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-27 08:49
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
I0727 08:51:40.795007    2936 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]
I0727 08:51:40.849924    2936 openapi.go:461] [k8s.io/apimachinery/pkg/apis/meta/v1.LabelSelectorRequirement] Key string: tag patchMergeKey on type Builtin; only allowed on type Slice
I0727 08:51:40.849957    2936 openapi.go:461] [k8s.io/apimachinery/pkg/apis/meta/v1.LabelSelectorRequirement] Key string: tag patchStrategy on type Builtin; only allowed on type Slice
I0727 08:51:41.718769    2936 api_linter.go:44] Assembling file "./api_violations.txt"
+ diff -u ./api_violations.txt ./contrib/build/violation_exceptions.txt
... skipping 126 lines ...
I0727 08:55:22.346079       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Unbind request for ServiceBinding in-flight to Broker
I0727 08:55:22.346394       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:22.346053428 +0000 UTC m=+0.081631062
I0727 08:55:22.346853       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The binding was deleted successfully
I0727 08:55:22.346969       1 controller_binding.go:1534] ServiceBinding "test-ns/test-binding": Status updated
I0727 08:55:22.347029       1 controller_binding.go:1545] ServiceBinding "test-ns/test-binding": Cleared finalizer
I0727 08:55:22.365437       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The binding is being created asynchronously (testdescr)
I0727 08:55:22.371958       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Bind call failed: testdescr
I0727 08:55:22.372055       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Bind call failed: testdescr
W0727 08:55:22.378918       1 controller_binding.go:1095] ServiceBinding "test-ns/test-binding": Got invalid state in LastOperationResponse: "test invalid state"
I0727 08:55:22.385691       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The asynchronous Bind operation timed out and will not be retried
I0727 08:55:22.385852       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Stopping reconciliation retries because too much time has elapsed
I0727 08:55:22.385963       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Starting orphan mitigation
W0727 08:55:22.391275       1 controller_binding.go:1095] ServiceBinding "test-ns/test-binding": Got invalid state in LastOperationResponse: "test invalid state"
I0727 08:55:22.391372       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The asynchronous Bind operation timed out and will not be retried
I0727 08:55:22.391438       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Stopping reconciliation retries because too much time has elapsed
I0727 08:55:22.391540       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Starting orphan mitigation
I0727 08:55:22.396531       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Could not do a GET on binding resource: some error
I0727 08:55:22.396632       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Could not do a GET on binding resource: some error
I0727 08:55:22.396717       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Starting orphan mitigation
I0727 08:55:22.402640       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
I0727 08:55:22.402776       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
I0727 08:55:22.402865       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Starting orphan mitigation
I0727 08:55:22.408553       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Injected bind result
I0727 08:55:22.414284       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The binding was deleted successfully
I0727 08:55:22.414429       1 controller_binding.go:1534] ServiceBinding "test-ns/test-binding": Status updated
I0727 08:55:22.414517       1 controller_binding.go:1545] ServiceBinding "test-ns/test-binding": Cleared finalizer
I0727 08:55:22.420710       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The binding was deleted successfully
I0727 08:55:22.420875       1 controller_binding.go:1534] ServiceBinding "test-ns/test-binding": Status updated
I0727 08:55:22.420992       1 controller_binding.go:1545] ServiceBinding "test-ns/test-binding": Cleared finalizer
I0727 08:55:22.428066       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The binding is being deleted asynchronously (testdescr)
I0727 08:55:22.438443       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Unbind call failed: testdescr
W0727 08:55:22.444066       1 controller_binding.go:1095] ServiceBinding "test-ns/test-binding": Got invalid state in LastOperationResponse: "test invalid state"
I0727 08:55:22.450069       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The asynchronous Unbind operation timed out and will not be retried
I0727 08:55:22.450263       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Stopping reconciliation retries because too much time has elapsed
W0727 08:55:22.456633       1 controller_binding.go:1095] ServiceBinding "test-ns/test-binding": Got invalid state in LastOperationResponse: "test invalid state"
I0727 08:55:22.456742       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The asynchronous Unbind operation timed out and will not be retried
I0727 08:55:22.456887       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Stopping reconciliation retries because too much time has elapsed
I0727 08:55:22.464170       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Unbind call failed: testdescr
I0727 08:55:22.464387       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Stopping reconciliation retries because too much time has elapsed
I0727 08:55:22.470960       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Orphan mitigation was completed successfully
I0727 08:55:22.477635       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Orphan mitigation was completed successfully
I0727 08:55:22.485528       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The binding is being deleted asynchronously (testdescr)
I0727 08:55:22.498207       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Unbind call failed: testdescr
W0727 08:55:22.503526       1 controller_binding.go:1095] ServiceBinding "test-ns/test-binding": Got invalid state in LastOperationResponse: "test invalid state"
I0727 08:55:22.509008       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The asynchronous Unbind operation timed out and will not be retried
I0727 08:55:22.509189       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Orphan mitigation failed: Stopping reconciliation retries because too much time has elapsed
W0727 08:55:22.515317       1 controller_binding.go:1095] ServiceBinding "test-ns/test-binding": Got invalid state in LastOperationResponse: "test invalid state"
I0727 08:55:22.515431       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The asynchronous Unbind operation timed out and will not be retried
I0727 08:55:22.515556       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Orphan mitigation failed: Stopping reconciliation retries because too much time has elapsed
I0727 08:55:22.521948       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Unbind call failed: testdescr
I0727 08:55:22.522124       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Orphan mitigation failed: Stopping reconciliation retries because too much time has elapsed
I0727 08:55:22.528355       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Unbind request for ServiceBinding in-flight to Broker
I0727 08:55:22.528510       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:22.528334468 +0000 UTC m=+0.263912095
I0727 08:55:22.528971       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The binding is being deleted asynchronously
I0727 08:55:22.541051       1 controller_binding.go:698] ServiceBinding "test-binding": References a non-existent ServiceInstance "/nothere"
I0727 08:55:22.541209       1 controller_binding.go:712] ServiceBinding "test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:22.540900087 +0000 UTC m=+0.276477747
I0727 08:55:22.546995       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 ...
I0727 08:55:22.555053       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:22.554822878 +0000 UTC m=+0.290400522
W0727 08:55:22.561775       1 controller.go:585] ServiceInstance "test-ns/test-instance": References a non-existent ClusterServiceClass "nosuchclassid" - {ClusterServiceClassExternalName:"nothere"}
I0727 08:55:22.561897       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"}
I0727 08:55:22.561985       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:22.561875282 +0000 UTC m=+0.297452923
I0727 08:55:22.569494       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding request for ServiceBinding in-flight to Broker
I0727 08:55:22.569648       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:22.569479404 +0000 UTC m=+0.305057036
I0727 08:55:22.570154       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
I0727 08:55:22.576997       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding request for ServiceBinding in-flight to Broker
I0727 08:55:22.577186       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:22.576983139 +0000 UTC m=+0.312560766
I0727 08:55:22.578682       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Injected bind result
I0727 08:55:22.587992       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding request for ServiceBinding in-flight to Broker
I0727 08:55:22.588173       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:22.587979207 +0000 UTC m=+0.323556838
I0727 08:55:22.588670       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Injected bind result
... skipping 6 lines ...
I0727 08:55:22.607047       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
I0727 08:55:22.607217       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:22.607030841 +0000 UTC m=+0.342608479
I0727 08:55:22.607273       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
I0727 08:55:22.613254       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding cannot begin because referenced ServiceInstance "test-ns/test-instance" is not ready
I0727 08:55:22.613426       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:22.613238094 +0000 UTC m=+0.348815734
I0727 08:55:22.619193       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": 
I0727 08:55:22.619652       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Failed to get namespace "test-ns" during binding: No namespace
I0727 08:55:22.619843       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:22.61963158 +0000 UTC m=+0.355209237
I0727 08:55:22.626281       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Unbind request for ServiceBinding in-flight to Broker
I0727 08:55:22.626440       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:22.626265252 +0000 UTC m=+0.361842878
I0727 08:55:22.626942       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The binding was deleted successfully
I0727 08:55:22.627096       1 controller_binding.go:1534] ServiceBinding "test-ns/test-binding": Status updated
I0727 08:55:22.627169       1 controller_binding.go:1545] ServiceBinding "test-ns/test-binding": Cleared finalizer
... skipping 15 lines ...
I0727 08:55:22.650708       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Unbind request for ServiceBinding in-flight to Broker
I0727 08:55:22.651619       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The binding was deleted successfully
I0727 08:55:22.651870       1 controller_binding.go:1534] ServiceBinding "test-ns/test-binding": Status updated
I0727 08:55:22.651958       1 controller_binding.go:1545] ServiceBinding "test-ns/test-binding": Cleared finalizer
I0727 08:55:22.658588       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding request for ServiceBinding in-flight to Broker
I0727 08:55:22.658745       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:22.658574028 +0000 UTC m=+0.394151656
I0727 08:55:22.659482       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
I0727 08:55:22.666394       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding request for ServiceBinding in-flight to Broker
I0727 08:55:22.666587       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:22.66637578 +0000 UTC m=+0.401953411
I0727 08:55:22.667398       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>
I0727 08:55:22.667479       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>
I0727 08:55:22.681030       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding request for ServiceBinding in-flight to Broker
I0727 08:55:22.681232       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:22.681006785 +0000 UTC m=+0.416584416
I0727 08:55:22.681767       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
I0727 08:55:22.688853       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding request for ServiceBinding in-flight to Broker
I0727 08:55:22.689027       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:22.688838557 +0000 UTC m=+0.424416188
I0727 08:55:22.689570       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>
I0727 08:55:22.689695       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>
I0727 08:55:22.695879       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": 
I0727 08:55:22.696062       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:22.695846064 +0000 UTC m=+0.431423705
I0727 08:55:22.702370       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": 
I0727 08:55:22.708037       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": bar
I0727 08:55:22.714640       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": 
I0727 08:55:22.720131       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": 
I0727 08:55:22.728296       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": 
I0727 08:55:22.735105       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Unbind request for ServiceBinding in-flight to Broker
I0727 08:55:22.735289       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:22.735081748 +0000 UTC m=+0.470659375
I0727 08:55:22.735792       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
I0727 08:55:22.741445       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Unbind request for ServiceBinding in-flight to Broker
I0727 08:55:22.741609       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:22.741427688 +0000 UTC m=+0.477005394
I0727 08:55:22.742398       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>
W0727 08:55:22.743188       1 feature_gate.go:235] Setting GA feature gate OriginatingIdentity=false. It will be removed in a future release.
I0727 08:55:22.748574       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding request for ServiceBinding in-flight to Broker
I0727 08:55:22.748720       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:22.748556944 +0000 UTC m=+0.484134598
I0727 08:55:22.749242       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Injected bind result
W0727 08:55:22.749575       1 feature_gate.go:235] Setting GA feature gate OriginatingIdentity=true. It will be removed in a future release.
I0727 08:55:22.754515       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding request for ServiceBinding in-flight to Broker
... skipping 22 lines ...
I0727 08:55:22.782332       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The binding was deleted successfully
I0727 08:55:22.782450       1 controller_binding.go:1534] ServiceBinding "test-ns/test-binding": Status updated
I0727 08:55:22.782514       1 controller_binding.go:1545] ServiceBinding "test-ns/test-binding": Cleared finalizer
W0727 08:55:22.782876       1 feature_gate.go:235] Setting GA feature gate OriginatingIdentity=true. It will be removed in a future release.
I0727 08:55:22.790338       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Injected bind result
I0727 08:55:22.790520       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:22.790324305 +0000 UTC m=+0.525901970
I0727 08:55:22.796765       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
I0727 08:55:22.796942       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:22.796736878 +0000 UTC m=+0.532314521
I0727 08:55:22.797021       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Stopping reconciliation retries, too much time has elapsed
I0727 08:55:22.804148       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
I0727 08:55:22.804343       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:22.804133502 +0000 UTC m=+0.539711142
I0727 08:55:22.804416       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Stopping reconciliation retries, too much time has elapsed
I0727 08:55:22.804517       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Starting orphan mitigation
I0727 08:55:22.811823       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding request for ServiceBinding in-flight to Broker
I0727 08:55:22.812026       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:22.811804143 +0000 UTC m=+0.547381777
E0727 08:55:22.812132       1 controller_binding.go:751] ServiceBinding "test-ns/test-binding": Error updating status: update error
I0727 08:55:22.819588       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding request for ServiceBinding in-flight to Broker
I0727 08:55:22.819781       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:22.819568801 +0000 UTC m=+0.555146451
I0727 08:55:22.820917       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Injected bind result
I0727 08:55:22.828972       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding request for ServiceBinding in-flight to Broker
I0727 08:55:22.829161       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:22.828955422 +0000 UTC m=+0.564533052
I0727 08:55:22.829979       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
I0727 08:55:22.835817       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding request for ServiceBinding in-flight to Broker
I0727 08:55:22.835997       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:22.835800734 +0000 UTC m=+0.571378362
I0727 08:55:22.836726       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>
I0727 08:55:22.836842       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>
I0727 08:55:22.843442       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding request for ServiceBinding in-flight to Broker
I0727 08:55:22.843646       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:22.843427018 +0000 UTC m=+0.579004645
... skipping 21 lines ...
I0727 08:55:22.883519       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:22.883316021 +0000 UTC m=+0.618893657
I0727 08:55:22.884262       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>
I0727 08:55:22.884381       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>
I0727 08:55:22.884479       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Starting orphan mitigation
I0727 08:55:22.891720       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Orphan mitigation was completed successfully
I0727 08:55:22.891924       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:22.891689227 +0000 UTC m=+0.627266870
I0727 08:55:22.899238       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
I0727 08:55:22.899485       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Orphan mitigation failed: Stopping reconciliation retries, too much time has elapsed
I0727 08:55:22.907794       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Unbind request for ServiceBinding in-flight to Broker
I0727 08:55:22.908080       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:22.907760126 +0000 UTC m=+0.643337773
I0727 08:55:22.909114       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The binding was deleted successfully
I0727 08:55:22.909707       1 controller_binding.go:1534] ServiceBinding "test-ns/test-binding": Status updated
I0727 08:55:22.910665       1 controller_binding.go:1545] ServiceBinding "test-ns/test-binding": Cleared finalizer
I0727 08:55:22.916196       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Unbind request for ServiceBinding in-flight to Broker
... skipping 5 lines ...
I0727 08:55:22.923235       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:22.922973787 +0000 UTC m=+0.658551419
I0727 08:55:22.923773       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The binding is being created asynchronously
I0727 08:55:22.930084       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Unbind request for ServiceBinding in-flight to Broker
I0727 08:55:22.930311       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:22.930069264 +0000 UTC m=+0.665646891
I0727 08:55:22.930827       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The binding is being deleted asynchronously
I0727 08:55:22.950910       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The binding is being created asynchronously (testdescr)
I0727 08:55:22.957267       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Bind call failed: testdescr
I0727 08:55:22.957460       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Bind call failed: testdescr
W0727 08:55:22.963510       1 controller_binding.go:1095] ServiceBinding "test-ns/test-binding": Got invalid state in LastOperationResponse: "test invalid state"
I0727 08:55:22.970056       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The asynchronous Bind operation timed out and will not be retried
I0727 08:55:22.970261       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Stopping reconciliation retries because too much time has elapsed
I0727 08:55:22.970386       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Starting orphan mitigation
W0727 08:55:22.976012       1 controller_binding.go:1095] ServiceBinding "test-ns/test-binding": Got invalid state in LastOperationResponse: "test invalid state"
I0727 08:55:22.976462       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The asynchronous Bind operation timed out and will not be retried
I0727 08:55:22.977394       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Stopping reconciliation retries because too much time has elapsed
I0727 08:55:22.977541       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Starting orphan mitigation
I0727 08:55:22.983539       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Could not do a GET on binding resource: some error
I0727 08:55:22.983778       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Could not do a GET on binding resource: some error
I0727 08:55:22.983900       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Starting orphan mitigation
I0727 08:55:22.989279       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
I0727 08:55:22.989449       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
I0727 08:55:22.989577       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Starting orphan mitigation
I0727 08:55:22.996782       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Injected bind result
I0727 08:55:23.003045       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The binding was deleted successfully
I0727 08:55:23.003316       1 controller_binding.go:1534] ServiceBinding "test-ns/test-binding": Status updated
I0727 08:55:23.003423       1 controller_binding.go:1545] ServiceBinding "test-ns/test-binding": Cleared finalizer
I0727 08:55:23.009790       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The binding was deleted successfully
I0727 08:55:23.010073       1 controller_binding.go:1534] ServiceBinding "test-ns/test-binding": Status updated
I0727 08:55:23.010135       1 controller_binding.go:1545] ServiceBinding "test-ns/test-binding": Cleared finalizer
I0727 08:55:23.016397       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The binding is being deleted asynchronously (testdescr)
I0727 08:55:23.030518       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Unbind call failed: testdescr
W0727 08:55:23.038123       1 controller_binding.go:1095] ServiceBinding "test-ns/test-binding": Got invalid state in LastOperationResponse: "test invalid state"
I0727 08:55:23.046663       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The asynchronous Unbind operation timed out and will not be retried
I0727 08:55:23.046926       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Stopping reconciliation retries because too much time has elapsed
W0727 08:55:23.054027       1 controller_binding.go:1095] ServiceBinding "test-ns/test-binding": Got invalid state in LastOperationResponse: "test invalid state"
I0727 08:55:23.054226       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The asynchronous Unbind operation timed out and will not be retried
I0727 08:55:23.054362       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Stopping reconciliation retries because too much time has elapsed
I0727 08:55:23.061793       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Unbind call failed: testdescr
I0727 08:55:23.062656       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Stopping reconciliation retries because too much time has elapsed
I0727 08:55:23.069688       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Orphan mitigation was completed successfully
I0727 08:55:23.076583       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Orphan mitigation was completed successfully
I0727 08:55:23.083306       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The binding is being deleted asynchronously (testdescr)
I0727 08:55:23.098467       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Unbind call failed: testdescr
W0727 08:55:23.104998       1 controller_binding.go:1095] ServiceBinding "test-ns/test-binding": Got invalid state in LastOperationResponse: "test invalid state"
I0727 08:55:23.114102       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The asynchronous Unbind operation timed out and will not be retried
I0727 08:55:23.114384       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Orphan mitigation failed: Stopping reconciliation retries because too much time has elapsed
W0727 08:55:23.121882       1 controller_binding.go:1095] ServiceBinding "test-ns/test-binding": Got invalid state in LastOperationResponse: "test invalid state"
I0727 08:55:23.122049       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The asynchronous Unbind operation timed out and will not be retried
I0727 08:55:23.122281       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Orphan mitigation failed: Stopping reconciliation retries because too much time has elapsed
I0727 08:55:23.129892       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Unbind call failed: testdescr
I0727 08:55:23.130171       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Orphan mitigation failed: Stopping reconciliation retries because too much time has elapsed
I0727 08:55:23.242376       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServiceClasses
I0727 08:55:23.243051       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServicePlans
I0727 08:55:23.243962       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:23.243886674 +0000 UTC m=+0.979464344
I0727 08:55:23.251936       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 1 ClusterServiceClasses
I0727 08:55:23.252849       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServicePlans
I0727 08:55:23.253819       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:23.253758461 +0000 UTC m=+0.989336155
... skipping 6 lines ...
I0727 08:55:23.284717       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 1 ClusterServiceClasses
I0727 08:55:23.286011       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 1 ClusterServicePlans
I0727 08:55:23.287301       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:23.287256497 +0000 UTC m=+1.022834144
I0727 08:55:23.297040       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServiceClasses
I0727 08:55:23.298437       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServicePlans
E0727 08:55:23.298989       1 controller_clusterservicebroker.go:484] ClusterServiceBroker "test-clusterservicebroker": ClusterServiceClass (K8S: "cscguid" ExternalName: "test-clusterserviceclass") already exists for Broker "notTheSame"
W0727 08:55:23.299233       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"
I0727 08:55:23.299424       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:23.299365365 +0000 UTC m=+1.034943029
I0727 08:55:23.308002       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServiceClasses
I0727 08:55:23.309302       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServicePlans
E0727 08:55:23.309888       1 controller_clusterservicebroker.go:572] ClusterServiceBroker "test-clusterservicebroker": ClusterServicePlan (K8S: "cspguid" ExternalName: "test-clusterserviceplan") already exists for Broker "notTheSame"
W0727 08:55:23.310042       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"
I0727 08:55:23.310493       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:23.310172705 +0000 UTC m=+1.045750369
I0727 08:55:23.319307       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 1 ClusterServiceClasses
I0727 08:55:23.321213       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 1 ClusterServicePlans
I0727 08:55:23.321485       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:23.321439053 +0000 UTC m=+1.057016696
I0727 08:55:23.331431       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 1 ClusterServiceClasses
I0727 08:55:23.333442       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 1 ClusterServicePlans
I0727 08:55:23.333846       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:23.333743682 +0000 UTC m=+1.069321349
I0727 08:55:23.344148       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 1 ClusterServiceClasses
I0727 08:55:23.345786       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 1 ClusterServicePlans
I0727 08:55:23.346049       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:23.345977394 +0000 UTC m=+1.081555068
W0727 08:55:23.353266       1 controller_clusterservicebroker.go:184] ClusterServiceBroker "test-clusterservicebroker": Error getting broker catalog: ooops
I0727 08:55:23.353558       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:23.353470815 +0000 UTC m=+1.089048470
I0727 08:55:23.363467       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServiceClasses
I0727 08:55:23.364594       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServicePlans
I0727 08:55:23.364749       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:23.364665804 +0000 UTC m=+1.100243445
I0727 08:55:23.398077       1 controller_clusterservicebroker.go:135] ClusterServiceBroker "test-clusterservicebroker": Error getting broker auth credentials: auth secret didn't contain token
I0727 08:55:23.398525       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:23.398402301 +0000 UTC m=+1.133979998
I0727 08:55:23.409193       1 controller_clusterservicebroker.go:135] ClusterServiceBroker "test-clusterservicebroker": Error getting broker auth credentials: auth secret didn't contain token
I0727 08:55:23.409894       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:23.409573963 +0000 UTC m=+1.145151658
I0727 08:55:23.410076       1 controller_clusterservicebroker.go:135] ClusterServiceBroker "test-clusterservicebroker": Error getting broker auth credentials: auth secret didn't contain token
I0727 08:55:23.410885       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:23.410791498 +0000 UTC m=+1.146369208
I0727 08:55:23.411909       1 controller_clusterservicebroker.go:135] ClusterServiceBroker "test-clusterservicebroker": Error getting broker auth credentials: auth secret didn't contain token
I0727 08:55:23.412697       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:23.412613169 +0000 UTC m=+1.148190829
I0727 08:55:23.412938       1 controller_clusterservicebroker.go:135] ClusterServiceBroker "test-clusterservicebroker": Error getting broker auth credentials: auth secret didn't contain token
I0727 08:55:23.413395       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:23.413308441 +0000 UTC m=+1.148886100
I0727 08:55:23.415919       1 controller_clusterservicebroker.go:135] ClusterServiceBroker "test-clusterservicebroker": Error getting broker auth credentials: auth secret didn't contain token
I0727 08:55:23.416302       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:23.416206341 +0000 UTC m=+1.151783991
I0727 08:55:23.427041       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServiceClasses
I0727 08:55:23.428558       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServicePlans
E0727 08:55:23.428989       1 controller_clusterservicebroker.go:493] ClusterServiceBroker "test-clusterservicebroker": Error creating ClusterServiceClass (K8S: "cscguid" ExternalName: "test-clusterserviceclass"): error creating serviceclass
W0727 08:55:23.429218       1 controller_clusterservicebroker.go:267] ClusterServiceBroker "test-clusterservicebroker": Error reconciling ClusterServiceClass (K8S: "cscguid" ExternalName: "test-clusterserviceclass") (broker "test-clusterservicebroker"): error creating serviceclass
I0727 08:55:23.429449       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:23.429357179 +0000 UTC m=+1.164934839
I0727 08:55:23.439273       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServiceClasses
I0727 08:55:23.440592       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServicePlans
I0727 08:55:23.441419       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:23.441333304 +0000 UTC m=+1.176910983
W0727 08:55:23.451826       1 controller_clusterservicebroker.go:184] ClusterServiceBroker "test-clusterservicebroker": Error getting broker catalog: ooops
I0727 08:55:23.452117       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:23.452047438 +0000 UTC m=+1.187625078
I0727 08:55:23.452385       1 controller_clusterservicebroker.go:198] ClusterServiceBroker "test-clusterservicebroker": Stopping reconciliation retries because too much time has elapsed
I0727 08:55:23.452555       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Failed" to 2020-07-27 08:55:23.452467357 +0000 UTC m=+1.188045026
I0727 08:55:23.463376       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServiceClasses
I0727 08:55:23.464838       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServicePlans
I0727 08:55:23.465843       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:23.46567485 +0000 UTC m=+1.201252554
E0727 08:55:23.466003       1 controller_clusterservicebroker.go:688] ClusterServiceBroker "test-clusterservicebroker": Error updating ready condition: update error
I0727 08:55:23.476319       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:23.476248665 +0000 UTC m=+1.211826306
I0727 08:55:23.502294       1 controller_clusterservicebroker.go:661] ClusterServiceBroker "test-clusterservicebroker": Found status change for condition "Ready": "False" -> "True"; setting lastTransitionTime to 2020-07-27 08:55:23.502192474 +0000 UTC m=+1.237770115
I0727 08:55:23.519164       1 controller_clusterservicebroker.go:661] ClusterServiceBroker "test-clusterservicebroker": Found status change for condition "Ready": "True" -> "False"; setting lastTransitionTime to 2020-07-27 08:55:23.519036929 +0000 UTC m=+1.254614583
E0727 08:55:23.555535       1 controller_clusterservicebroker.go:616] ClusterServiceBroker "test-clusterservicebroker": Error updating ClusterServicePlan (K8S: "cspguid" ExternalName: "new-value"): oops
I0727 08:55:23.564096       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 1 ClusterServiceClasses
I0727 08:55:23.565079       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 1 ClusterServicePlans
I0727 08:55:23.566047       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:23.565983272 +0000 UTC m=+1.301560937
I0727 08:55:23.575342       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServiceClasses
I0727 08:55:23.576261       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServicePlans
I0727 08:55:23.577451       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:23.577387612 +0000 UTC m=+1.312965257
... skipping 30 lines ...
I0727 08:55:23.685629       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vkk75jg7s6m: Provision request for ServiceInstance in-flight to Broker
I0727 08:55:23.686935       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vkk75jg7s6m: The instance was provisioned successfully
I0727 08:55:23.696701       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vfjgdjrrjvm: Provision request for ServiceInstance in-flight to Broker
I0727 08:55:23.697649       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vfjgdjrrjvm: The instance is being provisioned asynchronously
I0727 08:55:23.707909       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance is being provisioned asynchronously (testdescr)
I0727 08:55:23.716409       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance was provisioned successfully
I0727 08:55:23.725038       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Provision call failed: (no description provided)
I0727 08:55:23.725306       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Provision call failed: (no description provided)
I0727 08:55:23.725455       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Provision call failed: (no description provided)
I0727 08:55:23.725622       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
I0727 08:55:23.734279       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Deprovision request for ServiceInstance in-flight to Broker
I0727 08:55:23.735180       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance was deprovisioned successfully
I0727 08:55:23.735422       1 controller_instance.go:2656] ServiceInstance "test-ns/test-instance": Cleared finalizer
I0727 08:55:23.744448       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Deprovision request for ServiceInstance in-flight to Broker
I0727 08:55:23.745282       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance is being deprovisioned asynchronously
I0727 08:55:23.756138       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance is being deprovisioned asynchronously (testdescr)
I0727 08:55:23.765379       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance is being deprovisioned asynchronously (testdescr)
I0727 08:55:23.775402       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance was deprovisioned successfully
I0727 08:55:23.775839       1 controller_instance.go:2656] ServiceInstance "test-ns/test-instance": Cleared finalizer
I0727 08:55:23.783797       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Deprovision call failed: (no description provided)
I0727 08:55:23.792252       1 controller_instance.go:1491] ServiceInstance "test-ns/test-instance": Found 1 ServiceClasses
I0727 08:55:23.796601       1 controller_instance.go:1612] ServiceInstance "test-ns/test-instance": Found 1 ServicePlans
I0727 08:55:23.814401       1 controller_instance.go:1417] ServiceInstance "test-instance": Found 0 ClusterServiceClasses
W0727 08:55:23.814760       1 controller_instance.go:1281] ServiceInstance "test-instance": References a non-existent ClusterServiceClass {ClusterServiceClassExternalName:"nothere"} or there is more than one (found: 0)
I0727 08:55:23.814952       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)
W0727 08:55:23.823041       1 controller_instance.go:1281] ServiceInstance "test-instance": References a non-existent ClusterServiceClass {ClusterServiceClassName:"nothereclass"}
... skipping 10 lines ...
I0727 08:55:23.886005       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vjvrxfmzc7p: Provision request for ServiceInstance in-flight to Broker
I0727 08:55:23.888467       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vjvrxfmzc7p: The instance was provisioned successfully
I0727 08:55:23.899349       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" v9x7fv5x2jm: Provision request for ServiceInstance in-flight to Broker
I0727 08:55:23.901554       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" v9x7fv5x2jm: The instance was provisioned successfully
I0727 08:55:23.912833       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vqlt9pq9s45: Provision request for ServiceInstance in-flight to Broker
I0727 08:55:23.915595       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vqlt9pq9s45: The instance was provisioned successfully
I0727 08:55:23.927410       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" v88p8xd2tdk: 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 {}
I0727 08:55:23.937143       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vz2hmqrqqdq: failed to prepare parameters nil: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0727 08:55:23.946305       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" v5cdhxx4vbm: failed to prepare parameters nil: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0727 08:55:23.955766       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" v95jwtvmpql: failed to prepare parameters nil: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0727 08:55:23.964890       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vzjlnb759q6: failed to prepare parameters nil: failed to unmarshal parameters as JSON object: invalid character 'b' looking for beginning of value
I0727 08:55:23.976230       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vxdb449lw47: Provision request for ServiceInstance in-flight to Broker
I0727 08:55:23.977419       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vxdb449lw47: The instance was provisioned successfully
I0727 08:55:23.990207       1 controller_instance.go:1417] ServiceInstance "test-ns/test-instance": Found 1 ClusterServiceClasses
I0727 08:55:23.995588       1 controller_instance.go:1552] ServiceInstance "test-ns/test-instance": Found 1 ClusterServicePlans
I0727 08:55:24.030742       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vxmsrtrxpd8: Provision request for ServiceInstance in-flight to Broker
I0727 08:55:24.053559       1 controller_instance.go:1552] ServiceInstance "test-ns/test-instance": Found 1 ClusterServicePlans
I0727 08:55:24.063190       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vrbr9k96mpg: Provision request for ServiceInstance in-flight to Broker
I0727 08:55:24.064196       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vrbr9k96mpg: The provision call failed and will be retried: Error communicating with broker for provisioning: fake creation failure
I0727 08:55:24.076470       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vnk9xghpvgv: Provision request for ServiceInstance in-flight to Broker
I0727 08:55:24.077565       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vnk9xghpvgv: Error provisioning ServiceInstance of ClusterServiceClass (K8S: "cscguid" ExternalName: "test-clusterserviceclass") at ClusterServiceBroker "test-clusterservicebroker": Status: 500; ErrorMessage: InternalServerError; Description: Something went wrong!; ResponseError: <nil>
I0727 08:55:24.077656       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vnk9xghpvgv: Error provisioning ServiceInstance of ClusterServiceClass (K8S: "cscguid" ExternalName: "test-clusterserviceclass") at ClusterServiceBroker "test-clusterservicebroker": Status: 500; ErrorMessage: InternalServerError; Description: Something went wrong!; ResponseError: <nil>
I0727 08:55:24.077867       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vnk9xghpvgv: The instance provision call failed with an ambiguous error; attempting to deprovision the instance in order to mitigate an orphaned resource
I0727 08:55:24.091597       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vwnkvc7lqnr: Provision request for ServiceInstance in-flight to Broker
I0727 08:55:24.092737       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vwnkvc7lqnr: 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>
I0727 08:55:24.092862       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vwnkvc7lqnr: 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>
I0727 08:55:24.105007       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vxckrnsxtpk: Provision request for ServiceInstance in-flight to Broker
I0727 08:55:24.106112       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vxckrnsxtpk: The instance was provisioned successfully
I0727 08:55:24.121925       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" v8rdbwrsdmw: ClusterServicePlan (K8S: "cspguid" ExternalName: "test-clusterserviceplan") has been deleted; cannot provision.
I0727 08:55:24.135115       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vmx2q4zgwvv: ClusterServiceClass (K8S: "cscguid" ExternalName: "test-clusterserviceclass") has been deleted; cannot provision.
I0727 08:55:24.148648       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" v8sf84k856f: Provision request for ServiceInstance in-flight to Broker
I0727 08:55:24.149574       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" v8sf84k856f: The instance was provisioned successfully
I0727 08:55:24.163196       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vnbsl9wcxv6: Provision request for ServiceInstance in-flight to Broker
I0727 08:55:24.164399       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vnbsl9wcxv6: The instance is being provisioned asynchronously
I0727 08:55:24.176896       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" v7tx4sbm7qp: Provision request for ServiceInstance in-flight to Broker
I0727 08:55:24.178501       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" v7tx4sbm7qp: The instance is being provisioned asynchronously
I0727 08:55:24.194993       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vdcc7j7npjq: Failed to get namespace "test-ns": No namespace
I0727 08:55:24.205547       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Deprovision request for ServiceInstance in-flight to Broker
I0727 08:55:24.206674       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance was deprovisioned successfully
I0727 08:55:24.206977       1 controller_instance.go:2656] ServiceInstance "test-ns/test-instance": Cleared finalizer
I0727 08:55:24.220601       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": All associated ServiceBindings must be removed before this ServiceInstance can be deleted
I0727 08:55:24.223596       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Deprovision request for ServiceInstance in-flight to Broker
I0727 08:55:24.224264       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance was deprovisioned successfully
... skipping 16 lines ...
I0727 08:55:24.382083       1 controller_instance.go:2656] ServiceInstance "test-ns/test-instance": Cleared finalizer
I0727 08:55:24.496985       1 controller_instance.go:2656] ServiceInstance "test-ns/test-instance" v1: Cleared finalizer
I0727 08:55:24.513021       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vpj6spkpj6q: Provision request for ServiceInstance in-flight to Broker
I0727 08:55:24.514286       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vpj6spkpj6q: The instance was provisioned successfully
I0727 08:55:24.529339       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance is being provisioned asynchronously (testdescr)
I0727 08:55:24.546195       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance was provisioned successfully
I0727 08:55:24.560993       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Provision call failed: (no description provided)
I0727 08:55:24.561410       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Provision call failed: (no description provided)
I0727 08:55:24.561650       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Provision call failed: (no description provided)
I0727 08:55:24.562026       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
I0727 08:55:24.582890       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance is being deprovisioned asynchronously (testdescr)
I0727 08:55:24.598600       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance is being deprovisioned asynchronously (testdescr)
I0727 08:55:24.619571       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance was deprovisioned successfully
I0727 08:55:24.620228       1 controller_instance.go:2656] ServiceInstance "test-ns/test-instance": Cleared finalizer
I0727 08:55:24.635216       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Deprovision call failed: (no description provided)
I0727 08:55:24.648860       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Deprovision call failed: (no description provided)
I0727 08:55:24.649574       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Stopping reconciliation retries because too much time has elapsed
I0727 08:55:24.663428       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance was deprovisioned successfully
I0727 08:55:24.665056       1 controller_instance.go:2656] ServiceInstance "test-ns/test-instance": Cleared finalizer
I0727 08:55:24.679712       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Error polling last operation: Status: 403; ErrorMessage: <nil>; Description: <nil>; ResponseError: <nil>
I0727 08:55:24.696145       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Error polling last operation: Status: 400; ErrorMessage: <nil>; Description: <nil>; ResponseError: <nil>
I0727 08:55:24.696645       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Error polling last operation: Status: 400; ErrorMessage: <nil>; Description: <nil>; ResponseError: <nil>
I0727 08:55:24.711734       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance was deprovisioned successfully
I0727 08:55:24.712450       1 controller_instance.go:2656] ServiceInstance "test-ns/test-instance": Cleared finalizer
I0727 08:55:24.727528       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vmwlfx6tg44: The instance was provisioned successfully
I0727 08:55:24.746126       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vxmbk59rzm2: Provision request for ServiceInstance in-flight to Broker
I0727 08:55:24.763454       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vsqmxvkzsj9: The provision call failed and will be retried: Error communicating with broker for provisioning: fake creation failure
I0727 08:55:24.763807       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vsqmxvkzsj9: Stopping reconciliation retries because too much time has elapsed
I0727 08:55:24.775173       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance was provisioned successfully
I0727 08:55:24.788157       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance is being provisioned asynchronously (testdescr)
I0727 08:55:24.788584       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Stopping reconciliation retries because too much time has elapsed
I0727 08:55:24.788822       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance is being provisioned asynchronously (testdescr)
I0727 08:55:24.789047       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
I0727 08:55:24.803399       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Provision request for ServiceInstance in-flight to Broker
E0727 08:55:24.803951       1 controller_instance.go:1985] ServiceInstance "test-ns/test-instance": Failed to update status: update error
I0727 08:55:24.805979       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Message
I0727 08:55:24.806538       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Message
I0727 08:55:24.807146       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": DifferentMessage
I0727 08:55:24.807433       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Message
I0727 08:55:24.807864       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Message
I0727 08:55:24.808140       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Message
... skipping 33 lines ...
W0727 08:55:25.013865       1 feature_gate.go:235] Setting GA feature gate OriginatingIdentity=true. It will be removed in a future release.
I0727 08:55:25.031247       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance is being provisioned asynchronously (testdescr)
W0727 08:55:25.032231       1 feature_gate.go:235] Setting GA feature gate OriginatingIdentity=true. It will be removed in a future release.
I0727 08:55:25.051573       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance is being provisioned asynchronously (testdescr)
W0727 08:55:25.053971       1 feature_gate.go:235] Setting GA feature gate OriginatingIdentity=true. It will be removed in a future release.
I0727 08:55:25.071143       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vc2lzldd76s: Provision request for ServiceInstance in-flight to Broker
I0727 08:55:25.073210       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vc2lzldd76s: Error provisioning ServiceInstance of ClusterServiceClass (K8S: "cscguid" ExternalName: "test-clusterserviceclass") at ClusterServiceBroker "test-clusterservicebroker": Status: 200; ErrorMessage: <nil>; Description: <nil>; ResponseError: <nil>
I0727 08:55:25.089650       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" v5c22mgrdp4: Provision request for ServiceInstance in-flight to Broker
I0727 08:55:25.091098       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" v5c22mgrdp4: Error provisioning ServiceInstance of ClusterServiceClass (K8S: "cscguid" ExternalName: "test-clusterserviceclass") at ClusterServiceBroker "test-clusterservicebroker": Status: 201; ErrorMessage: <nil>; Description: <nil>; ResponseError: <nil>
I0727 08:55:25.091285       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" v5c22mgrdp4: Error provisioning ServiceInstance of ClusterServiceClass (K8S: "cscguid" ExternalName: "test-clusterserviceclass") at ClusterServiceBroker "test-clusterservicebroker": Status: 201; ErrorMessage: <nil>; Description: <nil>; ResponseError: <nil>
I0727 08:55:25.091607       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" v5c22mgrdp4: The instance provision call failed with an ambiguous error; attempting to deprovision the instance in order to mitigate an orphaned resource
I0727 08:55:25.107441       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" v69kjxhgdl6: Provision request for ServiceInstance in-flight to Broker
I0727 08:55:25.108802       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" v69kjxhgdl6: Error provisioning ServiceInstance of ClusterServiceClass (K8S: "cscguid" ExternalName: "test-clusterserviceclass") at ClusterServiceBroker "test-clusterservicebroker": Status: 300; ErrorMessage: <nil>; Description: <nil>; ResponseError: <nil>
I0727 08:55:25.121683       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" v6hfrz86gfr: Provision request for ServiceInstance in-flight to Broker
I0727 08:55:25.123430       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" v6hfrz86gfr: Error provisioning ServiceInstance of ClusterServiceClass (K8S: "cscguid" ExternalName: "test-clusterserviceclass") at ClusterServiceBroker "test-clusterservicebroker": Status: 400; ErrorMessage: <nil>; Description: <nil>; ResponseError: <nil>
I0727 08:55:25.123546       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" v6hfrz86gfr: Error provisioning ServiceInstance of ClusterServiceClass (K8S: "cscguid" ExternalName: "test-clusterserviceclass") at ClusterServiceBroker "test-clusterservicebroker": Status: 400; ErrorMessage: <nil>; Description: <nil>; ResponseError: <nil>
I0727 08:55:25.143073       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" v75rgjtzg96: Provision request for ServiceInstance in-flight to Broker
I0727 08:55:25.144949       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" v75rgjtzg96: Error provisioning ServiceInstance of ClusterServiceClass (K8S: "cscguid" ExternalName: "test-clusterserviceclass") at ClusterServiceBroker "test-clusterservicebroker": Status: 408; ErrorMessage: <nil>; Description: <nil>; ResponseError: <nil>
I0727 08:55:25.162194       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" v4nz4btqhr9: Provision request for ServiceInstance in-flight to Broker
I0727 08:55:25.163974       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" v4nz4btqhr9: Error provisioning ServiceInstance of ClusterServiceClass (K8S: "cscguid" ExternalName: "test-clusterserviceclass") at ClusterServiceBroker "test-clusterservicebroker": Status: 400; ErrorMessage: <nil>; Description: <nil>; ResponseError: <nil>
I0727 08:55:25.164064       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" v4nz4btqhr9: Error provisioning ServiceInstance of ClusterServiceClass (K8S: "cscguid" ExternalName: "test-clusterserviceclass") at ClusterServiceBroker "test-clusterservicebroker": Status: 400; ErrorMessage: <nil>; Description: <nil>; ResponseError: <nil>
I0727 08:55:25.176139       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vbgjf89x28c: Provision request for ServiceInstance in-flight to Broker
I0727 08:55:25.179044       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vbgjf89x28c: Error provisioning ServiceInstance of ClusterServiceClass (K8S: "cscguid" ExternalName: "test-clusterserviceclass") at ClusterServiceBroker "test-clusterservicebroker": Status: 500; ErrorMessage: <nil>; Description: <nil>; ResponseError: <nil>
I0727 08:55:25.179368       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vbgjf89x28c: Error provisioning ServiceInstance of ClusterServiceClass (K8S: "cscguid" ExternalName: "test-clusterserviceclass") at ClusterServiceBroker "test-clusterservicebroker": Status: 500; ErrorMessage: <nil>; Description: <nil>; ResponseError: <nil>
I0727 08:55:25.179742       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vbgjf89x28c: The instance provision call failed with an ambiguous error; attempting to deprovision the instance in order to mitigate an orphaned resource
I0727 08:55:25.197593       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vsl6sd6f2gq: Provision request for ServiceInstance in-flight to Broker
I0727 08:55:25.200908       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vsl6sd6f2gq: Communication with the ClusterServiceBroker timed out; operation will be retried:  : timed out
I0727 08:55:25.201286       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vsl6sd6f2gq: Communication with the ClusterServiceBroker timed out; operation will be retried:  : timed out
I0727 08:55:25.201843       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vsl6sd6f2gq: The instance provision call failed with an ambiguous error; attempting to deprovision the instance in order to mitigate an orphaned resource
I0727 08:55:25.218988       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
I0727 08:55:25.220220       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Orphan mitigation was completed successfully
I0727 08:55:25.236182       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
I0727 08:55:25.237137       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance is being deprovisioned asynchronously
I0727 08:55:25.253051       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
I0727 08:55:25.254587       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>
I0727 08:55:25.267811       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
I0727 08:55:25.268990       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Orphan mitigation failed: Stopping reconciliation retries because too much time has elapsed
I0727 08:55:25.282928       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
I0727 08:55:25.284152       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Error deprovisioning, ClusterServiceClass (K8S: "cscguid" ExternalName: "test-clusterserviceclass") at ClusterServiceBroker "test-clusterservicebroker": other error
I0727 08:55:25.299516       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
I0727 08:55:25.300781       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Orphan mitigation failed: Stopping reconciliation retries because too much time has elapsed
I0727 08:55:25.320273       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
I0727 08:55:25.321257       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Orphan mitigation was completed successfully
I0727 08:55:25.335682       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
I0727 08:55:25.336516       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Orphan mitigation was completed successfully
I0727 08:55:25.352802       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
I0727 08:55:25.353603       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance is being deprovisioned asynchronously (description)
I0727 08:55:25.372596       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
I0727 08:55:25.374147       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Orphan mitigation failed: Stopping reconciliation retries because too much time has elapsed
I0727 08:55:25.388236       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
I0727 08:55:25.388891       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Orphan mitigation failed: Stopping reconciliation retries because too much time has elapsed
I0727 08:55:25.403825       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
I0727 08:55:25.404799       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Orphan mitigation failed: Stopping reconciliation retries because too much time has elapsed
I0727 08:55:25.418997       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
I0727 08:55:25.420227       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Orphan mitigation failed: Stopping reconciliation retries because too much time has elapsed
I0727 08:55:25.443999       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
W0727 08:55:25.448397       1 controller_instance.go:1173] ServiceInstance "test-ns/test-instance": Got invalid state in LastOperationResponse: "invalid state"
I0727 08:55:25.448965       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Orphan mitigation failed: Stopping reconciliation retries because too much time has elapsed
I0727 08:55:25.476087       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vsnmh99tggw: Provision request for ServiceInstance in-flight to Broker
I0727 08:55:25.478640       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vsnmh99tggw: The instance was provisioned successfully
I0727 08:55:25.498714       1 controller_instance.go:1417] ServiceInstance "test-ns/test-instance": Found 0 ClusterServiceClasses
W0727 08:55:25.499231       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)
I0727 08:55:25.499521       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)
I0727 08:55:25.517627       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Update request for ServiceInstance in-flight to Broker
... skipping 10 lines ...
I0727 08:55:25.608824       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance was updated successfully
I0727 08:55:25.626705       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Update request for ServiceInstance in-flight to Broker
I0727 08:55:25.628625       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance was updated successfully
I0727 08:55:25.644937       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Update request for ServiceInstance in-flight to Broker
I0727 08:55:25.647493       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance was updated successfully
I0727 08:55:25.661590       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Update request for ServiceInstance in-flight to Broker
I0727 08:55:25.663976       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
I0727 08:55:25.680222       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Update request for ServiceInstance in-flight to Broker
I0727 08:55:25.683465       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>
I0727 08:55:25.697299       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Update request for ServiceInstance in-flight to Broker
I0727 08:55:25.698850       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>
I0727 08:55:25.699012       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>
I0727 08:55:25.719271       1 controller_instance.go:1417] ServiceInstance "test-ns/test-instance": Found 1 ClusterServiceClasses
I0727 08:55:25.723360       1 controller_instance.go:1552] ServiceInstance "test-ns/test-instance": Found 1 ClusterServicePlans
I0727 08:55:25.745647       1 controller_instance.go:1552] ServiceInstance "test-ns/test-instance": Found 1 ClusterServicePlans
I0727 08:55:25.800830       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Update request for ServiceInstance in-flight to Broker
I0727 08:55:25.802286       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance is being updated asynchronously
I0727 08:55:25.826707       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance is being updated asynchronously (testdescr)
I0727 08:55:25.846242       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance was updated successfully
I0727 08:55:25.865472       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Update call failed: (no description provided)
I0727 08:55:25.865906       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Update call failed: (no description provided)
I0727 08:55:25.968538       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Deprovision request for ServiceInstance in-flight to Broker
I0727 08:55:25.970532       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance was deprovisioned successfully
I0727 08:55:25.971205       1 controller_instance.go:2656] ServiceInstance "test-ns/test-instance": Cleared finalizer
I0727 08:55:25.985153       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
I0727 08:55:25.986597       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Deprovision request for ServiceInstance in-flight to Broker
I0727 08:55:25.987980       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance was deprovisioned successfully
I0727 08:55:25.988407       1 controller_instance.go:2656] ServiceInstance "test-ns/test-instance": Cleared finalizer
I0727 08:55:26.007671       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Deprovision request for ServiceInstance in-flight to Broker
I0727 08:55:26.014483       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance was deprovisioned successfully
I0727 08:55:26.014955       1 controller_instance.go:2656] ServiceInstance "test-ns/test-instance": Cleared finalizer
I0727 08:55:26.043803       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
W0727 08:55:26.059685       1 controller_servicebroker.go:175] ServiceBroker "test-ns/test-servicebrokernot-predefined": Error getting broker catalog: Unexpected action
I0727 08:55:26.063687       1 controller_servicebroker.go:626] ServiceBroker "test-ns/test-servicebrokernot-predefined": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:26.063519159 +0000 UTC m=+3.799096858
I0727 08:55:26.085862       1 controller_servicebroker.go:728] ServiceBroker "test-ns/test-servicebroker": Found 1 ServiceClasses
I0727 08:55:26.090163       1 controller_servicebroker.go:745] ServiceBroker "test-ns/test-servicebroker": Found 1 ServicePlans
I0727 08:55:26.090805       1 controller_servicebroker.go:626] ServiceBroker "test-ns/test-servicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:26.090739617 +0000 UTC m=+3.826317303
I0727 08:55:26.142514       1 controller_servicebroker.go:728] ServiceBroker "test-ns/test-servicebroker": Found 1 ServiceClasses
I0727 08:55:26.145471       1 controller_servicebroker.go:745] ServiceBroker "test-ns/test-servicebroker": Found 1 ServicePlans
I0727 08:55:26.148367       1 controller_servicebroker.go:626] ServiceBroker "test-ns/test-servicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:26.148262233 +0000 UTC m=+3.883839879
I0727 08:55:26.174144       1 controller_servicebroker.go:728] ServiceBroker "test-ns/test-servicebroker": Found 1 ServiceClasses
I0727 08:55:26.177303       1 controller_servicebroker.go:745] ServiceBroker "test-ns/test-servicebroker": Found 1 ServicePlans
I0727 08:55:26.178711       1 controller_servicebroker.go:626] ServiceBroker "test-ns/test-servicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:26.178616446 +0000 UTC m=+3.914194112
E0727 08:55:26.361514       1 controller_servicebroker.go:590] ServiceBroker "test-ns/test-servicebroker": Error updating ServicePlan (K8S: "test-ns/spguid" ExternalName: "new-value"): oops
I0727 08:55:26.399373       1 controller_servicebroker.go:728] ServiceBroker "test-ns/test-servicebroker": Found 1 ServiceClasses
I0727 08:55:26.403748       1 controller_servicebroker.go:745] ServiceBroker "test-ns/test-servicebroker": Found 0 ServicePlans
I0727 08:55:26.405093       1 controller_servicebroker.go:626] ServiceBroker "test-ns/test-servicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:26.405030137 +0000 UTC m=+4.140607802
I0727 08:55:26.435846       1 controller_serviceclass.go:80] ServiceClass "test-ns/scguid": Processing
I0727 08:55:26.469929       1 controller_serviceclass.go:80] ServiceClass "test-ns/scguid": Processing
I0727 08:55:26.482102       1 controller_serviceclass.go:86] ServiceClass "test-ns/scguid": Removed from broker catalog; determining whether there are instances remaining
... skipping 117 lines ...
I0727 08:55:29.197915       1 controller_clusterserviceplan.go:76] ClusterServicePlan "76543" (ExternalName: "test-otherclusterserviceplan"): processing
I0727 08:55:29.226953       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0727 08:55:29.229687       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance was provisioned successfully
I0727 08:55:29.281876       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding request for ServiceBinding in-flight to Broker
I0727 08:55:29.282827       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:29.28184872 +0000 UTC m=+7.017426571
I0727 08:55:29.286791       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The binding is being created asynchronously
I0727 08:55:30.291176       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Bind call failed: (no description provided)
I0727 08:55:30.291924       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Bind call failed: (no description provided)
I0727 08:55:30.295206       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Unbind request for ServiceBinding in-flight to Broker
I0727 08:55:30.298326       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The binding was deleted successfully
I0727 08:55:30.299285       1 controller_binding.go:1534] ServiceBinding "test-ns/test-binding": Status updated
I0727 08:55:30.300228       1 controller_binding.go:1545] ServiceBinding "test-ns/test-binding": Cleared finalizer
I0727 08:55:30.330324       1 controller.go:279] Shutting down service-catalog controller
I0727 08:55:30.341852       1 controller.go:296] Shutdown service-catalog controller
... skipping 8 lines ...
I0727 08:55:30.546605       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0727 08:55:30.549272       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance was provisioned successfully
I0727 08:55:30.600632       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding request for ServiceBinding in-flight to Broker
I0727 08:55:30.601281       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:30.600614177 +0000 UTC m=+8.336191806
I0727 08:55:30.604623       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Injected bind result
I0727 08:55:30.646492       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Unbind request for ServiceBinding in-flight to Broker
I0727 08:55:30.652693       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>
I0727 08:55:30.656789       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The binding was deleted successfully
I0727 08:55:30.657972       1 controller_binding.go:1534] ServiceBinding "test-ns/test-binding": Status updated
I0727 08:55:30.658968       1 controller_binding.go:1545] ServiceBinding "test-ns/test-binding": Cleared finalizer
I0727 08:55:30.698393       1 controller.go:279] Shutting down service-catalog controller
I0727 08:55:30.712829       1 controller.go:296] Shutdown service-catalog controller
I0727 08:55:30.829453       1 controller.go:245] Starting service-catalog controller
... skipping 9 lines ...
I0727 08:55:30.949050       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding request for ServiceBinding in-flight to Broker
I0727 08:55:30.949710       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:30.949026558 +0000 UTC m=+8.684604189
I0727 08:55:30.953011       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The binding is being created asynchronously
I0727 08:55:31.958146       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Injected bind result
I0727 08:55:31.992942       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Unbind request for ServiceBinding in-flight to Broker
I0727 08:55:31.996719       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The binding is being deleted asynchronously
I0727 08:55:33.052719       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Unbind call failed: (no description provided)
I0727 08:55:33.064268       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The binding is being deleted asynchronously
I0727 08:55:33.074309       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The binding was deleted successfully
I0727 08:55:33.078009       1 controller_binding.go:1534] ServiceBinding "test-ns/test-binding": Status updated
I0727 08:55:33.078690       1 controller_binding.go:1545] ServiceBinding "test-ns/test-binding": Cleared finalizer
I0727 08:55:33.195683       1 controller.go:279] Shutting down service-catalog controller
I0727 08:55:33.228530       1 controller.go:296] Shutdown service-catalog controller
... skipping 6 lines ...
I0727 08:55:33.407527       1 controller_clusterserviceplan.go:76] ClusterServicePlan "nb34567" (ExternalName: "test-nonbindable-plan"): processing
I0727 08:55:33.411806       1 controller_clusterserviceplan.go:76] ClusterServicePlan "76543" (ExternalName: "test-otherclusterserviceplan"): processing
I0727 08:55:33.442023       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding cannot begin because referenced ServiceInstance "test-ns/service-instance" is not ready
I0727 08:55:33.444413       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:33.441932062 +0000 UTC m=+11.177509729
I0727 08:55:33.451000       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0727 08:55:33.452684       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding cannot begin because referenced ServiceInstance "test-ns/service-instance" is not ready
I0727 08:55:33.454038       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>
I0727 08:55:33.458071       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>
I0727 08:55:33.458981       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding cannot begin because referenced ServiceInstance "test-ns/service-instance" is not ready
I0727 08:55:33.459116       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
I0727 08:55:33.464037       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding cannot begin because referenced ServiceInstance "test-ns/service-instance" is not ready
I0727 08:55:33.468482       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": All associated ServiceBindings must be removed before this ServiceInstance can be deleted
I0727 08:55:33.468657       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding cannot begin because referenced ServiceInstance "test-ns/service-instance" is not ready
I0727 08:55:33.475287       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding cannot begin because referenced ServiceInstance "test-ns/service-instance" is not ready
I0727 08:55:33.475339       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": All associated ServiceBindings must be removed before this ServiceInstance can be deleted
I0727 08:55:33.481284       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding cannot begin because referenced ServiceInstance "test-ns/service-instance" is not ready
... skipping 13 lines ...
I0727 08:55:33.649198       1 controller_clusterserviceplan.go:76] ClusterServicePlan "34567" (ExternalName: "test-clusterserviceplan"): processing
I0727 08:55:33.655086       1 controller_clusterserviceplan.go:76] ClusterServicePlan "nb34567" (ExternalName: "test-nonbindable-plan"): processing
I0727 08:55:33.655517       1 controller_clusterserviceplan.go:76] ClusterServicePlan "76543" (ExternalName: "test-otherclusterserviceplan"): processing
I0727 08:55:33.692407       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0727 08:55:33.700262       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding cannot begin because referenced ServiceInstance "test-ns/service-instance" is not ready
I0727 08:55:33.701746       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:33.700210353 +0000 UTC m=+11.435788028
I0727 08:55:33.700455       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>
I0727 08:55:33.704655       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>
I0727 08:55:33.705315       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
I0727 08:55:33.706264       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding cannot begin because referenced ServiceInstance "test-ns/service-instance" is not ready
I0727 08:55:33.707937       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": All associated ServiceBindings must be removed before this ServiceInstance can be deleted
I0727 08:55:33.710057       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding cannot begin because referenced ServiceInstance "test-ns/service-instance" is not ready
I0727 08:55:33.711422       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": All associated ServiceBindings must be removed before this ServiceInstance can be deleted
I0727 08:55:33.719389       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding cannot begin because referenced ServiceInstance "test-ns/service-instance" is not ready
I0727 08:55:33.726741       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding cannot begin because referenced ServiceInstance "test-ns/service-instance" is not ready
... skipping 87 lines ...
I0727 08:55:35.314529       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:35.314398203 +0000 UTC m=+13.049975888
I0727 08:55:35.314770       1 controller_clusterserviceplan.go:76] ClusterServicePlan "34567" (ExternalName: "test-clusterserviceplan"): processing
I0727 08:55:35.316114       1 controller_clusterserviceplan.go:76] ClusterServicePlan "nb34567" (ExternalName: "test-nonbindable-plan"): processing
I0727 08:55:35.316520       1 controller_clusterserviceplan.go:76] ClusterServicePlan "76543" (ExternalName: "test-otherclusterserviceplan"): processing
I0727 08:55:35.361395       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0727 08:55:35.363411       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance was provisioned successfully
I0727 08:55:35.413637       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": failed to prepare parameters nil: secrets "secret-name" not found
I0727 08:55:35.414299       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:35.41358791 +0000 UTC m=+13.149165558
I0727 08:55:35.417066       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": failed to prepare parameters nil: secrets "secret-name" not found
I0727 08:55:35.420435       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": failed to prepare parameters nil: secrets "secret-name" not found
I0727 08:55:35.423147       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": failed to prepare parameters nil: secrets "secret-name" not found
I0727 08:55:35.426886       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": failed to prepare parameters nil: secrets "secret-name" not found
I0727 08:55:35.429624       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": failed to prepare parameters nil: secrets "secret-name" not found
I0727 08:55:35.432594       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": failed to prepare parameters nil: secrets "secret-name" not found
I0727 08:55:35.435606       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": failed to prepare parameters nil: secrets "secret-name" not found
I0727 08:55:35.438706       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": failed to prepare parameters nil: secrets "secret-name" not found
I0727 08:55:35.441831       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": failed to prepare parameters nil: secrets "secret-name" not found
I0727 08:55:35.444376       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": failed to prepare parameters nil: secrets "secret-name" not found
I0727 08:55:35.447173       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": failed to prepare parameters nil: secrets "secret-name" not found
I0727 08:55:35.449628       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": failed to prepare parameters nil: secrets "secret-name" not found
I0727 08:55:35.452383       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": failed to prepare parameters nil: secrets "secret-name" not found
I0727 08:55:35.454833       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": failed to prepare parameters nil: secrets "secret-name" not found
I0727 08:55:35.457208       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": failed to prepare parameters nil: secrets "secret-name" not found
I0727 08:55:35.459533       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": failed to prepare parameters nil: secrets "secret-name" not found
I0727 08:55:35.462016       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": failed to prepare parameters nil: secrets "secret-name" not found
I0727 08:55:35.463134       1 controller.go:279] Shutting down service-catalog controller
I0727 08:55:35.465271       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": failed to prepare parameters nil: secrets "secret-name" not found
I0727 08:55:35.472411       1 controller.go:296] Shutdown service-catalog controller
I0727 08:55:35.587024       1 controller.go:245] Starting service-catalog controller
I0727 08:55:35.591556       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServiceClasses
I0727 08:55:35.593701       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServicePlans
I0727 08:55:35.596283       1 controller_clusterserviceclass.go:75] ClusterServiceClass "clusterserviceclass-12345" (ExternalName: "test-clusterserviceclass"): processing
I0727 08:55:35.596620       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:35.596528955 +0000 UTC m=+13.332106621
I0727 08:55:35.598504       1 controller_clusterserviceplan.go:76] ClusterServicePlan "34567" (ExternalName: "test-clusterserviceplan"): processing
I0727 08:55:35.599739       1 controller_clusterserviceplan.go:76] ClusterServicePlan "nb34567" (ExternalName: "test-nonbindable-plan"): processing
I0727 08:55:35.600170       1 controller_clusterserviceplan.go:76] ClusterServicePlan "76543" (ExternalName: "test-otherclusterserviceplan"): processing
I0727 08:55:35.651293       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0727 08:55:35.653639       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance was provisioned successfully
I0727 08:55:35.704647       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
I0727 08:55:35.705162       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:35.704526397 +0000 UTC m=+13.440104064
I0727 08:55:35.708257       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
I0727 08:55:35.711781       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
I0727 08:55:35.715333       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
I0727 08:55:35.718251       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
I0727 08:55:35.721054       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
I0727 08:55:35.723859       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
I0727 08:55:35.726419       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
I0727 08:55:35.729278       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
I0727 08:55:35.732433       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
I0727 08:55:35.735535       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
I0727 08:55:35.737993       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
I0727 08:55:35.740637       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
I0727 08:55:35.743262       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
I0727 08:55:35.746416       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
I0727 08:55:35.749611       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
I0727 08:55:35.752864       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
I0727 08:55:35.755042       1 controller.go:279] Shutting down service-catalog controller
I0727 08:55:35.762741       1 controller.go:296] Shutdown service-catalog controller
I0727 08:55:35.871054       1 controller.go:245] Starting service-catalog controller
I0727 08:55:35.876805       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServiceClasses
I0727 08:55:35.878261       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServicePlans
I0727 08:55:35.881313       1 controller_clusterserviceclass.go:75] ClusterServiceClass "clusterserviceclass-12345" (ExternalName: "test-clusterserviceclass"): processing
I0727 08:55:35.882475       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:35.882321139 +0000 UTC m=+13.617898806
I0727 08:55:35.883970       1 controller_clusterserviceplan.go:76] ClusterServicePlan "34567" (ExternalName: "test-clusterserviceplan"): processing
I0727 08:55:35.886705       1 controller_clusterserviceplan.go:76] ClusterServicePlan "nb34567" (ExternalName: "test-nonbindable-plan"): processing
I0727 08:55:35.887123       1 controller_clusterserviceplan.go:76] ClusterServicePlan "76543" (ExternalName: "test-otherclusterserviceplan"): processing
I0727 08:55:35.932833       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0727 08:55:35.935428       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance was provisioned successfully
I0727 08:55:35.988878       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
I0727 08:55:35.989495       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:35.98876497 +0000 UTC m=+13.724342640
I0727 08:55:35.992578       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
I0727 08:55:35.996051       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
I0727 08:55:36.000737       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
I0727 08:55:36.004535       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
I0727 08:55:36.008315       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
I0727 08:55:36.012069       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
I0727 08:55:36.015537       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
I0727 08:55:36.018785       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
I0727 08:55:36.021506       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
I0727 08:55:36.024387       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
I0727 08:55:36.027412       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
I0727 08:55:36.029793       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
I0727 08:55:36.032242       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
I0727 08:55:36.037323       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
I0727 08:55:36.040010       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
I0727 08:55:36.042540       1 controller.go:279] Shutting down service-catalog controller
I0727 08:55:36.052848       1 controller.go:296] Shutdown service-catalog controller
I0727 08:55:36.151134       1 controller.go:245] Starting service-catalog controller
I0727 08:55:36.155533       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServiceClasses
I0727 08:55:36.157014       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServicePlans
I0727 08:55:36.159219       1 controller_clusterserviceclass.go:75] ClusterServiceClass "clusterserviceclass-12345" (ExternalName: "test-clusterserviceclass"): processing
I0727 08:55:36.161197       1 controller_clusterserviceplan.go:76] ClusterServicePlan "34567" (ExternalName: "test-clusterserviceplan"): processing
I0727 08:55:36.161224       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:36.161171836 +0000 UTC m=+13.896749499
I0727 08:55:36.164467       1 controller_clusterserviceplan.go:76] ClusterServicePlan "nb34567" (ExternalName: "test-nonbindable-plan"): processing
I0727 08:55:36.165655       1 controller_clusterserviceplan.go:76] ClusterServicePlan "76543" (ExternalName: "test-otherclusterserviceplan"): processing
I0727 08:55:36.214329       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0727 08:55:36.216937       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance was provisioned successfully
I0727 08:55:36.264404       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
I0727 08:55:36.264937       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:36.264336754 +0000 UTC m=+13.999914399
I0727 08:55:36.268126       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
I0727 08:55:36.271562       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
I0727 08:55:36.274471       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
I0727 08:55:36.276901       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
I0727 08:55:36.279663       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
I0727 08:55:36.282406       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
I0727 08:55:36.284966       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
I0727 08:55:36.287500       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
I0727 08:55:36.289630       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
I0727 08:55:36.292194       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
I0727 08:55:36.295346       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
I0727 08:55:36.297607       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
I0727 08:55:36.299777       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
I0727 08:55:36.302247       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
I0727 08:55:36.304484       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
I0727 08:55:36.306662       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
I0727 08:55:36.309004       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
I0727 08:55:36.312001       1 controller.go:279] Shutting down service-catalog controller
I0727 08:55:36.312784       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
I0727 08:55:36.317701       1 controller.go:296] Shutdown service-catalog controller
I0727 08:55:36.430135       1 controller.go:245] Starting service-catalog controller
I0727 08:55:36.434958       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServiceClasses
I0727 08:55:36.436642       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServicePlans
I0727 08:55:36.439765       1 controller_clusterserviceclass.go:75] ClusterServiceClass "clusterserviceclass-12345" (ExternalName: "test-clusterserviceclass"): processing
I0727 08:55:36.441001       1 controller_clusterserviceplan.go:76] ClusterServicePlan "34567" (ExternalName: "test-clusterserviceplan"): processing
... skipping 66 lines ...
I0727 08:55:37.646099       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:37.646003026 +0000 UTC m=+15.381580689
I0727 08:55:37.649468       1 controller_clusterserviceclass.go:75] ClusterServiceClass "clusterserviceclass-12345" (ExternalName: "test-clusterserviceclass"): processing
I0727 08:55:37.653101       1 controller_clusterserviceplan.go:76] ClusterServicePlan "34567" (ExternalName: "test-clusterserviceplan"): processing
I0727 08:55:37.655611       1 controller_clusterserviceplan.go:76] ClusterServicePlan "nb34567" (ExternalName: "test-nonbindable-plan"): processing
I0727 08:55:37.656757       1 controller_clusterserviceplan.go:76] ClusterServicePlan "76543" (ExternalName: "test-otherclusterserviceplan"): processing
I0727 08:55:37.668894       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0727 08:55:37.674151       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>
I0727 08:55:37.688045       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0727 08:55:37.700657       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>
I0727 08:55:38.680635       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance was provisioned successfully
I0727 08:55:38.708524       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance was provisioned successfully
I0727 08:55:38.714595       1 controller.go:279] Shutting down service-catalog controller
I0727 08:55:38.723619       1 controller.go:296] Shutdown service-catalog controller
I0727 08:55:38.734949       1 controller.go:279] Shutting down service-catalog controller
I0727 08:55:38.746124       1 controller.go:296] Shutdown service-catalog controller
... skipping 14 lines ...
I0727 08:55:38.941431       1 controller_clusterserviceplan.go:76] ClusterServicePlan "nb34567" (ExternalName: "test-nonbindable-plan"): processing
I0727 08:55:38.941973       1 controller_clusterserviceplan.go:76] ClusterServicePlan "76543" (ExternalName: "test-otherclusterserviceplan"): processing
I0727 08:55:38.945871       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0727 08:55:38.948487       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance is being provisioned asynchronously
I0727 08:55:38.967332       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0727 08:55:38.969787       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance is being provisioned asynchronously
I0727 08:55:39.952305       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision call failed: (no description provided)
I0727 08:55:39.953007       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision call failed: (no description provided)
I0727 08:55:39.953951       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision call failed: (no description provided)
I0727 08:55:39.954443       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
I0727 08:55:39.957840       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision request for ServiceInstance in-flight to Broker
I0727 08:55:39.958985       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance was deprovisioned successfully
I0727 08:55:39.959933       1 controller_instance.go:2656] ServiceInstance "test-ns/service-instance": Cleared finalizer
I0727 08:55:39.977599       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision call failed: (no description provided)
I0727 08:55:39.978692       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision call failed: (no description provided)
I0727 08:55:39.979417       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision call failed: (no description provided)
I0727 08:55:39.979856       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
I0727 08:55:39.982900       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision request for ServiceInstance in-flight to Broker
I0727 08:55:39.984286       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance was deprovisioned successfully
I0727 08:55:39.985963       1 controller_instance.go:2656] ServiceInstance "test-ns/service-instance": Cleared finalizer
I0727 08:55:39.990504       1 controller.go:279] Shutting down service-catalog controller
I0727 08:55:39.998271       1 controller.go:296] Shutdown service-catalog controller
I0727 08:55:40.023431       1 controller.go:279] Shutting down service-catalog controller
... skipping 38 lines ...
I0727 08:55:48.559871       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0727 08:55:48.561596       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance was provisioned successfully
I0727 08:55:48.602220       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Update request for ServiceInstance in-flight to Broker
I0727 08:55:48.604039       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance is being updated asynchronously
I0727 08:55:48.610944       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Update request for ServiceInstance in-flight to Broker
I0727 08:55:48.613172       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance is being updated asynchronously
I0727 08:55:49.608924       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Update call failed: (no description provided)
I0727 08:55:49.610116       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Update call failed: (no description provided)
I0727 08:55:49.613609       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision request for ServiceInstance in-flight to Broker
I0727 08:55:49.615141       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance was deprovisioned successfully
I0727 08:55:49.618805       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Update call failed: (no description provided)
I0727 08:55:49.619663       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Update call failed: (no description provided)
I0727 08:55:49.622306       1 controller_instance.go:2656] ServiceInstance "test-ns/service-instance": Cleared finalizer
I0727 08:55:49.623288       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision request for ServiceInstance in-flight to Broker
I0727 08:55:49.624982       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance was deprovisioned successfully
I0727 08:55:49.626588       1 controller_instance.go:2656] ServiceInstance "test-ns/service-instance": Cleared finalizer
I0727 08:55:49.655925       1 controller.go:279] Shutting down service-catalog controller
I0727 08:55:49.660724       1 controller.go:296] Shutdown service-catalog controller
... skipping 42 lines ...
I0727 08:55:50.201859       1 controller_clusterserviceplan.go:76] ClusterServicePlan "76543" (ExternalName: "test-otherclusterserviceplan"): processing
I0727 08:55:50.204659       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServiceClasses
I0727 08:55:50.208515       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServicePlans
I0727 08:55:50.212441       1 controller.go:245] Starting service-catalog controller
I0727 08:55:50.224926       1 controller_clusterserviceclass.go:75] ClusterServiceClass "clusterserviceclass-12345" (ExternalName: "test-clusterserviceclass"): processing
I0727 08:55:50.226896       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:50.226819247 +0000 UTC m=+27.962396911
I0727 08:55:50.230636       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
I0727 08:55:50.240886       1 controller_clusterserviceplan.go:76] ClusterServicePlan "34567" (ExternalName: "test-clusterserviceplan"): processing
I0727 08:55:50.241379       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
I0727 08:55:50.241636       1 controller_clusterserviceplan.go:76] ClusterServicePlan "nb34567" (ExternalName: "test-nonbindable-plan"): processing
I0727 08:55:50.247177       1 controller_clusterserviceplan.go:76] ClusterServicePlan "76543" (ExternalName: "test-otherclusterserviceplan"): processing
I0727 08:55:50.251515       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
I0727 08:55:50.253665       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServiceClasses
I0727 08:55:50.256536       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServicePlans
I0727 08:55:50.261134       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:50.261059797 +0000 UTC m=+27.996637466
I0727 08:55:50.269278       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
I0727 08:55:50.277913       1 controller_clusterserviceclass.go:75] ClusterServiceClass "clusterserviceclass-12345" (ExternalName: "test-clusterserviceclass"): processing
I0727 08:55:50.280886       1 controller_clusterserviceplan.go:76] ClusterServicePlan "34567" (ExternalName: "test-clusterserviceplan"): processing
I0727 08:55:50.281961       1 controller_clusterserviceplan.go:76] ClusterServicePlan "nb34567" (ExternalName: "test-nonbindable-plan"): processing
I0727 08:55:50.282674       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
I0727 08:55:50.286100       1 controller_clusterserviceplan.go:76] ClusterServicePlan "76543" (ExternalName: "test-otherclusterserviceplan"): processing
I0727 08:55:50.289163       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
I0727 08:55:50.296638       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
I0727 08:55:50.300363       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
I0727 08:55:50.303767       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
I0727 08:55:50.310923       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
I0727 08:55:50.315050       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
I0727 08:55:50.316359       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
I0727 08:55:50.322164       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
I0727 08:55:50.324812       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
I0727 08:55:50.329967       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
I0727 08:55:50.334051       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
I0727 08:55:50.342094       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
I0727 08:55:50.345187       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
I0727 08:55:50.346709       1 controller.go:245] Starting service-catalog controller
I0727 08:55:50.356413       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
I0727 08:55:50.361060       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
I0727 08:55:50.369503       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServiceClasses
I0727 08:55:50.372567       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServicePlans
I0727 08:55:50.376328       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
I0727 08:55:50.379516       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:50.379415915 +0000 UTC m=+28.114993579
I0727 08:55:50.353656       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
I0727 08:55:50.387910       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
I0727 08:55:50.389658       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
I0727 08:55:50.400099       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
I0727 08:55:50.391184       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
I0727 08:55:50.410183       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
I0727 08:55:50.379606       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
I0727 08:55:50.426493       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
I0727 08:55:50.429586       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
I0727 08:55:50.430843       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
I0727 08:55:50.438061       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
I0727 08:55:50.439404       1 controller_clusterserviceclass.go:75] ClusterServiceClass "clusterserviceclass-12345" (ExternalName: "test-clusterserviceclass"): processing
I0727 08:55:50.439448       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
I0727 08:55:50.445842       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
I0727 08:55:50.449056       1 controller_clusterserviceplan.go:76] ClusterServicePlan "34567" (ExternalName: "test-clusterserviceplan"): processing
I0727 08:55:50.450141       1 controller_clusterserviceplan.go:76] ClusterServicePlan "nb34567" (ExternalName: "test-nonbindable-plan"): processing
I0727 08:55:50.453482       1 controller_clusterserviceplan.go:76] ClusterServicePlan "76543" (ExternalName: "test-otherclusterserviceplan"): processing
I0727 08:55:50.454789       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
I0727 08:55:50.455656       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
I0727 08:55:50.464403       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
I0727 08:55:50.472837       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
I0727 08:55:50.466729       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
I0727 08:55:50.464661       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
I0727 08:55:50.490144       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
I0727 08:55:50.491139       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
I0727 08:55:50.499380       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
I0727 08:55:50.470393       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
I0727 08:55:50.509433       1 controller.go:279] Shutting down service-catalog controller
I0727 08:55:50.483161       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
I0727 08:55:50.519576       1 controller.go:279] Shutting down service-catalog controller
I0727 08:55:50.524324       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
I0727 08:55:50.532492       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
I0727 08:55:50.535800       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
I0727 08:55:50.539927       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
I0727 08:55:50.544385       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
I0727 08:55:50.548230       1 controller.go:296] Shutdown service-catalog controller
I0727 08:55:50.554655       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
I0727 08:55:50.557558       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
I0727 08:55:50.563796       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
I0727 08:55:50.571863       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
I0727 08:55:50.576461       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
I0727 08:55:50.580094       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
I0727 08:55:50.584661       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
I0727 08:55:50.589961       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
I0727 08:55:50.590765       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
I0727 08:55:50.597048       1 controller.go:296] Shutdown service-catalog controller
I0727 08:55:50.599592       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
I0727 08:55:50.601863       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
I0727 08:55:50.606811       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
I0727 08:55:50.610099       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
I0727 08:55:50.611090       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
I0727 08:55:50.617160       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
I0727 08:55:50.618275       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
I0727 08:55:50.626735       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
I0727 08:55:50.618289       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
I0727 08:55:50.633603       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
I0727 08:55:50.637770       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
I0727 08:55:50.638770       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
I0727 08:55:50.647961       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
I0727 08:55:50.652994       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
I0727 08:55:50.661768       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
I0727 08:55:50.667177       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
I0727 08:55:50.670791       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
I0727 08:55:50.673377       1 controller.go:279] Shutting down service-catalog controller
I0727 08:55:50.674949       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
I0727 08:55:50.683434       1 controller.go:279] Shutting down service-catalog controller
I0727 08:55:50.687625       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
I0727 08:55:50.698240       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
I0727 08:55:50.700223       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
I0727 08:55:50.703613       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
I0727 08:55:50.703735       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
I0727 08:55:50.707353       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
I0727 08:55:50.711103       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
I0727 08:55:50.713914       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
I0727 08:55:50.719967       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
I0727 08:55:50.721539       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
I0727 08:55:50.727520       1 controller.go:296] Shutdown service-catalog controller
I0727 08:55:50.703830       1 controller.go:296] Shutdown service-catalog controller
I0727 08:55:50.737505       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
I0727 08:55:50.738714       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
I0727 08:55:50.746580       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
I0727 08:55:50.749037       1 controller.go:279] Shutting down service-catalog controller
I0727 08:55:50.755097       1 controller.go:279] Shutting down service-catalog controller
I0727 08:55:50.756924       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
I0727 08:55:50.766357       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
I0727 08:55:50.746730       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
I0727 08:55:50.771300       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
I0727 08:55:50.769267       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
I0727 08:55:50.774470       1 controller.go:296] Shutdown service-catalog controller
I0727 08:55:50.778224       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
I0727 08:55:50.781932       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
I0727 08:55:50.785438       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
I0727 08:55:50.786950       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
I0727 08:55:50.791105       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
I0727 08:55:50.793233       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
I0727 08:55:50.800659       1 controller.go:296] Shutdown service-catalog controller
I0727 08:55:50.800957       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
I0727 08:55:50.807237       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
I0727 08:55:50.801236       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
I0727 08:55:50.819127       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
I0727 08:55:50.824357       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
I0727 08:55:50.830701       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
I0727 08:55:50.834330       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
I0727 08:55:50.842717       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
I0727 08:55:50.845168       1 controller.go:245] Starting service-catalog controller
I0727 08:55:50.846743       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
I0727 08:55:50.856230       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
I0727 08:55:50.862497       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
I0727 08:55:50.872886       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServiceClasses
I0727 08:55:50.875242       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
I0727 08:55:50.876273       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServicePlans
I0727 08:55:50.873059       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
I0727 08:55:50.881609       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:50.881481917 +0000 UTC m=+28.617059582
I0727 08:55:50.879186       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
I0727 08:55:50.892147       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
I0727 08:55:50.895027       1 controller_clusterserviceclass.go:75] ClusterServiceClass "clusterserviceclass-12345" (ExternalName: "test-clusterserviceclass"): processing
I0727 08:55:50.896240       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
I0727 08:55:50.901704       1 controller_clusterserviceplan.go:76] ClusterServicePlan "34567" (ExternalName: "test-clusterserviceplan"): processing
I0727 08:55:50.902947       1 controller_clusterserviceplan.go:76] ClusterServicePlan "nb34567" (ExternalName: "test-nonbindable-plan"): processing
I0727 08:55:50.904118       1 controller_clusterserviceplan.go:76] ClusterServicePlan "76543" (ExternalName: "test-otherclusterserviceplan"): processing
I0727 08:55:50.902012       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
I0727 08:55:50.913586       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
I0727 08:55:50.914275       1 controller.go:279] Shutting down service-catalog controller
I0727 08:55:50.922198       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
I0727 08:55:50.926683       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
I0727 08:55:50.930697       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
I0727 08:55:50.931811       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
I0727 08:55:50.942186       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
I0727 08:55:50.947191       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
I0727 08:55:50.955903       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
I0727 08:55:50.957430       1 controller.go:296] Shutdown service-catalog controller
I0727 08:55:50.961669       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
I0727 08:55:50.967912       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
I0727 08:55:50.972042       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
I0727 08:55:50.977021       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
I0727 08:55:50.979000       1 controller.go:279] Shutting down service-catalog controller
I0727 08:55:50.985683       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
I0727 08:55:50.991229       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
I0727 08:55:50.995713       1 controller.go:296] Shutdown service-catalog controller
I0727 08:55:51.001916       1 controller.go:279] Shutting down service-catalog controller
I0727 08:55:51.019352       1 controller.go:296] Shutdown service-catalog controller
I0727 08:55:51.262092       1 controller.go:245] Starting service-catalog controller
I0727 08:55:51.282495       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServiceClasses
I0727 08:55:51.284692       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServicePlans
... skipping 12 lines ...
I0727 08:55:51.380250       1 controller_clusterserviceplan.go:76] ClusterServicePlan "76543" (ExternalName: "test-otherclusterserviceplan"): processing
I0727 08:55:51.387878       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0727 08:55:51.388473       1 controller.go:245] Starting service-catalog controller
I0727 08:55:51.396179       1 controller.go:245] Starting service-catalog controller
I0727 08:55:51.406420       1 controller.go:245] Starting service-catalog controller
I0727 08:55:51.411824       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0727 08:55:51.420347       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Communication with the ClusterServiceBroker timed out; operation will be retried: Put https://fakebroker.com/v2/service_instances/instance_id: timeout error
I0727 08:55:51.422874       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Communication with the ClusterServiceBroker timed out; operation will be retried: Put https://fakebroker.com/v2/service_instances/instance_id: timeout error
I0727 08:55:51.423660       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
I0727 08:55:51.426206       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServiceClasses
I0727 08:55:51.435828       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServicePlans
I0727 08:55:51.426872       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Communication with the ClusterServiceBroker timed out; operation will be retried: Put https://fakebroker.com/v2/service_instances/instance_id: timeout error
I0727 08:55:51.442276       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Communication with the ClusterServiceBroker timed out; operation will be retried: Put https://fakebroker.com/v2/service_instances/instance_id: timeout error
I0727 08:55:51.443100       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
I0727 08:55:51.443433       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServiceClasses
I0727 08:55:51.475860       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServicePlans
I0727 08:55:51.480951       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:51.480823038 +0000 UTC m=+29.216400708
I0727 08:55:51.471858       1 controller_clusterserviceclass.go:75] ClusterServiceClass "clusterserviceclass-12345" (ExternalName: "test-clusterserviceclass"): processing
I0727 08:55:51.443692       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServiceClasses
I0727 08:55:51.494215       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServicePlans
... skipping 9 lines ...
I0727 08:55:51.546950       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServicePlans
I0727 08:55:51.559600       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:51.559421018 +0000 UTC m=+29.294998681
I0727 08:55:51.563506       1 controller_clusterserviceclass.go:75] ClusterServiceClass "clusterserviceclass-12345" (ExternalName: "test-clusterserviceclass"): processing
I0727 08:55:51.567663       1 controller_clusterserviceplan.go:76] ClusterServicePlan "34567" (ExternalName: "test-clusterserviceplan"): processing
I0727 08:55:51.568999       1 controller_clusterserviceplan.go:76] ClusterServicePlan "nb34567" (ExternalName: "test-nonbindable-plan"): processing
I0727 08:55:51.569513       1 controller_clusterserviceplan.go:76] ClusterServicePlan "76543" (ExternalName: "test-otherclusterserviceplan"): processing
I0727 08:55:51.548311       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:51.573839       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:51.549524       1 controller_clusterserviceplan.go:76] ClusterServicePlan "34567" (ExternalName: "test-clusterserviceplan"): processing
I0727 08:55:51.584110       1 controller_clusterserviceplan.go:76] ClusterServicePlan "nb34567" (ExternalName: "test-nonbindable-plan"): processing
I0727 08:55:51.584715       1 controller_clusterserviceplan.go:76] ClusterServicePlan "76543" (ExternalName: "test-otherclusterserviceplan"): processing
I0727 08:55:51.585180       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0727 08:55:51.589281       1 controller_clusterserviceclass.go:75] ClusterServiceClass "clusterserviceclass-12345" (ExternalName: "test-clusterserviceclass"): processing
I0727 08:55:51.591499       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Communication with the ClusterServiceBroker timed out; operation will be retried: Put https://fakebroker.com/v2/service_instances/instance_id: timeout error
I0727 08:55:51.595459       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Communication with the ClusterServiceBroker timed out; operation will be retried: Put https://fakebroker.com/v2/service_instances/instance_id: timeout error
I0727 08:55:51.596195       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
I0727 08:55:51.601031       1 controller_clusterserviceplan.go:76] ClusterServicePlan "34567" (ExternalName: "test-clusterserviceplan"): processing
I0727 08:55:51.602123       1 controller_clusterserviceplan.go:76] ClusterServicePlan "nb34567" (ExternalName: "test-nonbindable-plan"): processing
I0727 08:55:51.602542       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0727 08:55:51.606017       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:51.609339       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Communication with the ClusterServiceBroker timed out; operation will be retried: Put https://fakebroker.com/v2/service_instances/instance_id: timeout error
I0727 08:55:51.610963       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Communication with the ClusterServiceBroker timed out; operation will be retried: Put https://fakebroker.com/v2/service_instances/instance_id: timeout error
I0727 08:55:51.611619       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:51.551418       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServiceClasses
I0727 08:55:51.603139       1 controller_clusterserviceplan.go:76] ClusterServicePlan "76543" (ExternalName: "test-otherclusterserviceplan"): processing
I0727 08:55:51.611628       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
I0727 08:55:51.622448       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServicePlans
I0727 08:55:51.622958       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:51.628014       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:51.628956       1 controller.go:245] Starting service-catalog controller
I0727 08:55:51.647494       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:51.647421661 +0000 UTC m=+29.382999323
I0727 08:55:51.658777       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:51.666963       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:51.670574       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Orphan mitigation was completed successfully
I0727 08:55:51.680390       1 controller_clusterserviceclass.go:75] ClusterServiceClass "clusterserviceclass-12345" (ExternalName: "test-clusterserviceclass"): processing
I0727 08:55:51.689408       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServiceClasses
I0727 08:55:51.693339       1 controller_clusterserviceplan.go:76] ClusterServicePlan "34567" (ExternalName: "test-clusterserviceplan"): processing
I0727 08:55:51.701458       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0727 08:55:51.701460       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:51.713584       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:51.715653       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Orphan mitigation was completed successfully
I0727 08:55:51.726056       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServicePlans
I0727 08:55:51.729449       1 controller_clusterserviceplan.go:76] ClusterServicePlan "nb34567" (ExternalName: "test-nonbindable-plan"): processing
I0727 08:55:51.730860       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0727 08:55:51.732643       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Communication with the ClusterServiceBroker timed out; operation will be retried: Put https://fakebroker.com/v2/service_instances/instance_id: timeout error
I0727 08:55:51.738522       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:51.739985       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:51.747628       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Orphan mitigation was completed successfully
I0727 08:55:51.766002       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0727 08:55:51.770875       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:51.770704819 +0000 UTC m=+29.506282479
I0727 08:55:51.778994       1 controller_clusterserviceplan.go:76] ClusterServicePlan "76543" (ExternalName: "test-otherclusterserviceplan"): processing
I0727 08:55:51.782304       1 controller_clusterserviceclass.go:75] ClusterServiceClass "clusterserviceclass-12345" (ExternalName: "test-clusterserviceclass"): processing
I0727 08:55:51.784517       1 controller_clusterserviceplan.go:76] ClusterServicePlan "34567" (ExternalName: "test-clusterserviceplan"): processing
I0727 08:55:51.784708       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Communication with the ClusterServiceBroker timed out; operation will be retried: Put https://fakebroker.com/v2/service_instances/instance_id: timeout error
I0727 08:55:51.787578       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Communication with the ClusterServiceBroker timed out; operation will be retried: Put https://fakebroker.com/v2/service_instances/instance_id: timeout error
I0727 08:55:51.800368       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:51.808902       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:51.814913       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Orphan mitigation was completed successfully
I0727 08:55:51.821852       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Communication with the ClusterServiceBroker timed out; operation will be retried: Put https://fakebroker.com/v2/service_instances/instance_id: timeout error
I0727 08:55:51.843003       1 controller_clusterserviceplan.go:76] ClusterServicePlan "nb34567" (ExternalName: "test-nonbindable-plan"): processing
I0727 08:55:51.846606       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Communication with the ClusterServiceBroker timed out; operation will be retried: Put https://fakebroker.com/v2/service_instances/instance_id: timeout error
I0727 08:55:51.847695       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
I0727 08:55:51.853454       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Orphan mitigation was completed successfully
I0727 08:55:51.855974       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0727 08:55:51.860151       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Orphan mitigation was completed successfully
I0727 08:55:51.864635       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Communication with the ClusterServiceBroker timed out; operation will be retried: Put https://fakebroker.com/v2/service_instances/instance_id: timeout error
I0727 08:55:51.865445       1 controller_clusterserviceplan.go:76] ClusterServicePlan "76543" (ExternalName: "test-otherclusterserviceplan"): processing
I0727 08:55:51.867505       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
I0727 08:55:51.882017       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Orphan mitigation was completed successfully
I0727 08:55:51.885578       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Communication with the ClusterServiceBroker timed out; operation will be retried: Put https://fakebroker.com/v2/service_instances/instance_id: timeout error
I0727 08:55:51.888234       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:51.889561       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Orphan mitigation was completed successfully
I0727 08:55:51.892137       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
I0727 08:55:51.903748       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Communication with the ClusterServiceBroker timed out; operation will be retried: Put https://fakebroker.com/v2/service_instances/instance_id: timeout error
I0727 08:55:51.910204       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:51.921567       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
I0727 08:55:51.923620       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:51.931508       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:51.939347       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:51.940468       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:51.941382       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:51.948563       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:51.948972       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:51.950855       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:51.948835       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:51.958124       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:51.960850       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:51.961174       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Orphan mitigation was completed successfully
I0727 08:55:51.964429       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:51.969697       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:51.971050       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:51.972316       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:51.979473       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:51.979714       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:51.983463       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:51.996269       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:51.997748       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:51.999944       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Orphan mitigation was completed successfully
I0727 08:55:52.001143       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:52.009335       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Orphan mitigation was completed successfully
I0727 08:55:52.015293       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Orphan mitigation was completed successfully
I0727 08:55:52.437953       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0727 08:55:52.440545       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance was provisioned successfully
I0727 08:55:52.448908       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance was provisioned successfully
I0727 08:55:52.454648       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
... skipping 14 lines ...
I0727 08:55:52.712951       1 controller_clusterserviceplan.go:76] ClusterServicePlan "34567" (ExternalName: "test-clusterserviceplan"): processing
I0727 08:55:52.714328       1 controller_clusterserviceplan.go:76] ClusterServicePlan "nb34567" (ExternalName: "test-nonbindable-plan"): processing
I0727 08:55:52.717552       1 controller_clusterserviceplan.go:76] ClusterServicePlan "76543" (ExternalName: "test-otherclusterserviceplan"): processing
I0727 08:55:52.720309       1 controller_clusterserviceclass.go:75] ClusterServiceClass "clusterserviceclass-12345" (ExternalName: "test-clusterserviceclass"): processing
I0727 08:55:52.747960       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServiceClasses
I0727 08:55:52.752557       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0727 08:55:52.756485       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Communication with the ClusterServiceBroker timed out; operation will be retried: Put https://fakebroker.com/v2/service_instances/instance_id: timeout error
I0727 08:55:52.757065       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Communication with the ClusterServiceBroker timed out; operation will be retried: Put https://fakebroker.com/v2/service_instances/instance_id: timeout error
I0727 08:55:52.757839       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
I0727 08:55:52.758835       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServicePlans
I0727 08:55:52.763610       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:52.763533448 +0000 UTC m=+30.499111113
I0727 08:55:52.773122       1 controller_clusterserviceclass.go:75] ClusterServiceClass "clusterserviceclass-12345" (ExternalName: "test-clusterserviceclass"): processing
I0727 08:55:52.774490       1 controller_clusterserviceplan.go:76] ClusterServicePlan "34567" (ExternalName: "test-clusterserviceplan"): processing
I0727 08:55:52.778156       1 controller_clusterserviceplan.go:76] ClusterServicePlan "nb34567" (ExternalName: "test-nonbindable-plan"): processing
I0727 08:55:52.778787       1 controller_clusterserviceplan.go:76] ClusterServicePlan "76543" (ExternalName: "test-otherclusterserviceplan"): processing
I0727 08:55:52.802696       1 controller.go:245] Starting service-catalog controller
I0727 08:55:52.816414       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServiceClasses
I0727 08:55:52.821151       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServicePlans
I0727 08:55:52.833651       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0727 08:55:52.836113       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:55:52.836025631 +0000 UTC m=+30.571603313
I0727 08:55:52.839186       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Communication with the ClusterServiceBroker timed out; operation will be retried: Put https://fakebroker.com/v2/service_instances/instance_id: timeout error
I0727 08:55:52.840706       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Communication with the ClusterServiceBroker timed out; operation will be retried: Put https://fakebroker.com/v2/service_instances/instance_id: timeout error
I0727 08:55:52.840980       1 controller_clusterserviceplan.go:76] ClusterServicePlan "34567" (ExternalName: "test-clusterserviceplan"): processing
I0727 08:55:52.842049       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
I0727 08:55:52.842257       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:52.853203       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:52.834191       1 controller_clusterserviceclass.go:75] ClusterServiceClass "clusterserviceclass-12345" (ExternalName: "test-clusterserviceclass"): processing
I0727 08:55:52.855573       1 controller_clusterserviceplan.go:76] ClusterServicePlan "nb34567" (ExternalName: "test-nonbindable-plan"): processing
I0727 08:55:52.860250       1 controller_clusterserviceplan.go:76] ClusterServicePlan "76543" (ExternalName: "test-otherclusterserviceplan"): processing
I0727 08:55:52.866691       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:52.871437       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:52.879952       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0727 08:55:52.883370       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:52.883482       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Communication with the ClusterServiceBroker timed out; operation will be retried: Put https://fakebroker.com/v2/service_instances/instance_id: timeout error
I0727 08:55:52.885614       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Communication with the ClusterServiceBroker timed out; operation will be retried: Put https://fakebroker.com/v2/service_instances/instance_id: timeout error
I0727 08:55:52.886088       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
I0727 08:55:52.887615       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:52.883642       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:52.893573       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0727 08:55:52.895167       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:52.897389       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:52.898097       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance was provisioned successfully
I0727 08:55:52.904594       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:52.914010       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:52.915814       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:52.917448       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0727 08:55:52.919580       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:52.929903       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:52.932987       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:52.939263       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:52.940801       1 controller.go:279] Shutting down service-catalog controller
I0727 08:55:52.941613       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:52.946548       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Orphan mitigation was completed successfully
I0727 08:55:52.948371       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0727 08:55:52.950321       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:52.952061       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance was provisioned successfully
I0727 08:55:52.957423       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:52.962046       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0727 08:55:52.962634       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:52.965304       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance was provisioned successfully
I0727 08:55:52.920590       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance was provisioned successfully
I0727 08:55:52.968265       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:52.973118       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance was provisioned successfully
I0727 08:55:52.974215       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:52.978544       1 controller.go:279] Shutting down service-catalog controller
I0727 08:55:52.980969       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:52.986378       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:52.994354       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:52.996898       1 controller.go:296] Shutdown service-catalog controller
I0727 08:55:53.003425       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:53.007009       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:53.009146       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:53.009567       1 controller.go:279] Shutting down service-catalog controller
I0727 08:55:53.011718       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:53.016469       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:53.022918       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:53.023294       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:53.027931       1 controller.go:296] Shutdown service-catalog controller
I0727 08:55:53.031150       1 controller.go:296] Shutdown service-catalog controller
I0727 08:55:53.034934       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:53.036294       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:53.040453       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:53.044454       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:53.045679       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:53.050393       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:53.053372       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:53.055616       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:53.059230       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:53.060838       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:53.061145       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0727 08:55:53.064010       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance was provisioned successfully
I0727 08:55:53.064853       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:53.066782       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:53.072039       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:53.082020       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:53.093560       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:53.095932       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Orphan mitigation was completed successfully
I0727 08:55:53.097898       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:53.111815       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:53.119498       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:53.121008       1 controller.go:279] Shutting down service-catalog controller
I0727 08:55:53.124668       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:53.142639       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:53.145888       1 controller.go:279] Shutting down service-catalog controller
I0727 08:55:53.149160       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:53.157870       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:53.163033       1 controller.go:296] Shutdown service-catalog controller
I0727 08:55:53.169151       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:53.174114       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:53.178091       1 controller.go:296] Shutdown service-catalog controller
I0727 08:55:53.180670       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:53.185957       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:53.190239       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:53.195442       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:53.202676       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:53.207801       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:53.213679       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:53.218459       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:53.223393       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:53.229679       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:53.233164       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:53.239443       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:53.245328       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:53.251241       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:53.257265       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:53.261582       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:53.266037       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:53.271765       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:53.278062       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:53.290057       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:53.295507       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed; received error response from broker: Status: 500; ErrorMessage: temporary deprovision error; Description: <nil>; ResponseError: <nil>
I0727 08:55:53.319056       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Orphan mitigation was completed successfully
I0727 08:55:53.857875       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0727 08:55:53.860587       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance was provisioned successfully
I0727 08:55:53.881805       1 controller.go:279] Shutting down service-catalog controller
I0727 08:55:53.888603       1 controller.go:296] Shutdown service-catalog controller
I0727 08:55:53.955560       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
... skipping 51 lines ...
I0727 08:55:54.659979       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance was provisioned successfully
I0727 08:55:54.661646       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0727 08:55:54.664462       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance was provisioned successfully
I0727 08:55:54.665455       1 controller_clusterserviceplan.go:76] ClusterServicePlan "76543" (ExternalName: "test-otherclusterserviceplan"): processing
I0727 08:55:54.687927       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0727 08:55:54.688892       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Update request for ServiceInstance in-flight to Broker
I0727 08:55:54.691688       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The update call failed and will be retried: Error communicating with broker for updating: fake update error
I0727 08:55:54.694282       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Update request for ServiceInstance in-flight to Broker
I0727 08:55:54.696650       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The update call failed and will be retried: Error communicating with broker for updating: fake update error
I0727 08:55:54.691979       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance was provisioned successfully
I0727 08:55:54.702481       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance was provisioned successfully
I0727 08:55:54.703061       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Update request for ServiceInstance in-flight to Broker
I0727 08:55:54.705415       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The update call failed and will be retried: Error communicating with broker for updating: fake update error
I0727 08:55:54.719388       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Update request for ServiceInstance in-flight to Broker
I0727 08:55:54.722074       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The update call failed and will be retried: Error communicating with broker for updating: fake update error
I0727 08:55:54.743985       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Update request for ServiceInstance in-flight to Broker
I0727 08:55:54.746567       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The update call failed and will be retried: Error communicating with broker for updating: fake update error
I0727 08:55:55.699174       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The update call failed and will be retried: Error communicating with broker for updating: fake update error
I0727 08:55:55.706434       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The update call failed and will be retried: Error communicating with broker for updating: fake update error
I0727 08:55:55.712941       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The update call failed and will be retried: Error communicating with broker for updating: fake update error
I0727 08:55:55.729230       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The update call failed and will be retried: Error communicating with broker for updating: fake update error
I0727 08:55:55.753694       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The update call failed and will be retried: Error communicating with broker for updating: fake update error
I0727 08:55:57.705529       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance was updated successfully
I0727 08:55:57.713986       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance was updated successfully
I0727 08:55:57.721641       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance was updated successfully
I0727 08:55:57.737698       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance was updated successfully
I0727 08:55:57.742105       1 controller.go:279] Shutting down service-catalog controller
I0727 08:55:57.747680       1 controller.go:296] Shutdown service-catalog controller
... skipping 289 lines ...
I0727 08:55:59.048059       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance was updated successfully
I0727 08:55:59.049828       1 controller.go:279] Shutting down service-catalog controller
I0727 08:55:59.053192       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance was updated successfully
I0727 08:55:59.056630       1 controller.go:296] Shutdown service-catalog controller
I0727 08:55:59.096808       1 controller.go:279] Shutting down service-catalog controller
I0727 08:55:59.104875       1 controller.go:296] Shutdown service-catalog controller
--- FAIL: TestUpdateServiceInstanceUpdateParameters (0.01s)
    --- FAIL: TestUpdateServiceInstanceUpdateParameters/Add_secret_param (8.42s)
        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
I0727 08:56:06.594665       1 controller.go:279] Shutting down service-catalog controller
I0727 08:56:06.602136       1 controller.go:296] Shutdown service-catalog controller
I0727 08:56:06.712883       1 controller.go:245] Starting service-catalog controller
I0727 08:56:06.720322       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServiceClasses
... skipping 180 lines ...
I0727 08:56:12.889579       1 controller.go:245] Starting service-catalog controller
I0727 08:56:12.906122       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServiceClasses
I0727 08:56:12.908557       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServicePlans
I0727 08:56:12.911043       1 controller_clusterserviceclass.go:75] ClusterServiceClass "clusterserviceclass-12345" (ExternalName: "test-clusterserviceclass"): processing
I0727 08:56:12.912347       1 controller_clusterserviceclass.go:81] ClusterServiceClass "clusterserviceclass-12345" (ExternalName: "test-clusterserviceclass"): has been removed from broker catalog; determining whether there are instances remaining
I0727 08:56:12.914040       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-27 08:56:12.913964394 +0000 UTC m=+50.649542058
I0727 08:56:12.911136       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
I0727 08:56:12.874746       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0727 08:56:12.915046       1 controller_clusterserviceclass.go:87] Found 0 ServiceInstances
I0727 08:56:12.918214       1 controller_clusterserviceplan.go:76] ClusterServicePlan "34567" (ExternalName: ""): processing
I0727 08:56:12.920597       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance references a non-existent ClusterServicePlan "34567" - {ClusterServiceClassExternalName:"clusterserviceclass-12345", ClusterServicePlanExternalName:"34567"}
I0727 08:56:12.927055       1 controller_clusterserviceclass.go:75] ClusterServiceClass "clusterserviceclass-12345" (ExternalName: "test-clusterserviceclass"): processing
I0727 08:56:12.932510       1 controller_clusterserviceplan.go:76] ClusterServicePlan "34567" (ExternalName: "test-clusterserviceplan"): processing
... skipping 2 lines ...
I0727 08:56:12.939863       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance was provisioned successfully
I0727 08:56:12.940643       1 controller.go:279] Shutting down service-catalog controller
I0727 08:56:12.943107       1 controller_clusterserviceclass.go:75] ClusterServiceClass "clusterserviceclass-12345" (ExternalName: "test-clusterserviceclass"): processing
I0727 08:56:12.947304       1 controller_clusterserviceclass.go:93] ClusterServiceClass "clusterserviceclass-12345" (ExternalName: "test-clusterserviceclass"): has been removed from broker catalog and has zero instances remaining; deleting
I0727 08:56:12.951449       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0727 08:56:12.959322       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance references a non-existent ClusterServicePlan "34567" - {ClusterServiceClassExternalName:"clusterserviceclass-12345", ClusterServicePlanExternalName:"34567"}
I0727 08:56:12.966025       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
I0727 08:56:12.966577       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance references a non-existent broker "test-clusterservicebroker"
I0727 08:56:12.967439       1 controller_clusterserviceclass.go:75] ClusterServiceClass "clusterserviceclass-12345" (ExternalName: "test-clusterserviceclass"): processing
I0727 08:56:12.968762       1 controller_clusterserviceplan.go:76] ClusterServicePlan "nb34567" (ExternalName: "test-nonbindable-plan"): processing
I0727 08:56:12.969799       1 controller_clusterserviceplan.go:82] ClusterServicePlan "34567" (ExternalName: "test-clusterserviceplan"): has been removed from broker catalog; determining whether there are instances remaining
I0727 08:56:12.971099       1 controller_clusterserviceplan.go:76] ClusterServicePlan "nb34567" (ExternalName: "test-nonbindable-plan"): processing
I0727 08:56:12.971679       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 1 ClusterServiceClasses
I0727 08:56:12.972627       1 controller.go:279] Shutting down service-catalog controller
I0727 08:56:12.975603       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance was provisioned successfully
I0727 08:56:12.990155       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance was provisioned successfully
I0727 08:56:13.012300       1 controller.go:296] Shutdown service-catalog controller
I0727 08:56:13.022895       1 controller.go:279] Shutting down service-catalog controller
I0727 08:56:13.029208       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
I0727 08:56:13.031762       1 controller_clusterserviceplan.go:76] ClusterServicePlan "76543" (ExternalName: "test-otherclusterserviceplan"): processing
I0727 08:56:13.034764       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance references a non-existent broker "test-clusterservicebroker"
I0727 08:56:13.037679       1 controller_clusterserviceplan.go:88] Found 0 ServiceInstances
I0727 08:56:13.040900       1 controller_clusterserviceplan.go:76] ClusterServicePlan "76543" (ExternalName: "test-otherclusterserviceplan"): processing
I0727 08:56:13.044814       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServicePlans
I0727 08:56:13.054235       1 controller.go:279] Shutting down service-catalog controller
... skipping 16 lines ...
I0727 08:56:13.189701       1 controller.go:296] Shutdown service-catalog controller
I0727 08:56:13.196717       1 controller_clusterserviceclass.go:93] ClusterServiceClass "clusterserviceclass-12345" (ExternalName: "test-clusterserviceclass"): has been removed from broker catalog and has zero instances remaining; deleting
I0727 08:56:13.201412       1 controller.go:279] Shutting down service-catalog controller
I0727 08:56:13.221558       1 controller.go:296] Shutdown service-catalog controller
I0727 08:56:13.229069       1 controller.go:279] Shutting down service-catalog controller
I0727 08:56:13.263044       1 controller.go:296] Shutdown service-catalog controller
I0727 08:56:14.188748       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed: (no description provided)
I0727 08:56:14.193037       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance is being deprovisioned asynchronously
I0727 08:56:14.198765       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance was deprovisioned successfully
I0727 08:56:14.200610       1 controller_instance.go:2656] ServiceInstance "test-ns/service-instance": Cleared finalizer
I0727 08:56:14.215915       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 ...