Skip to content

Instantly share code, notes, and snippets.

@luksa

luksa/service-catalog-controller-double-provision-request.log

Created May 26, 2018 12:57
Show Gist options
  • Save luksa/424719839efbf7d76e4aa79d771c81a5 to your computer and use it in GitHub Desktop.
Save luksa/424719839efbf7d76e4aa79d771c81a5 to your computer and use it in GitHub Desktop.
Download ZIP
Raw
service-catalog-controller-double-provision-request.log
I0526 08:47:11.153362 17338 round_trippers.go:405] GET https://localhost:6443/api/v1/namespaces/default/configmaps/cluster-info 200 OK in 1 milliseconds
I0526 08:47:11.663177 17338 controller_instance.go:102] ===========================================
I0526 08:47:11.663188 17338 controller_instance.go:103] Adding ServiceInstance 'foo21/ups-instance-with-parametersfrom' to queue
I0526 08:47:11.663194 17338 controller_instance.go:104] ===========================================
I0526 08:47:11.663209 17338 controller.go:357] -------------------------------------------------
I0526 08:47:11.663237 17338 controller_instance.go:1112] ServiceInstance "foo21/ups-instance-with-parametersfrom": Removing condition "Failed"
I0526 08:47:11.663246 17338 controller_instance.go:962] ServiceInstance "foo21/ups-instance-with-parametersfrom": looking up a ClusterServiceClass from spec.externalName: "user-provided-service"
I0526 08:47:11.665472 17338 round_trippers.go:405] GET https://localhost:6443/apis/servicecatalog.k8s.io/v1beta1/clusterserviceclasses?fieldSelector=spec.externalName%3Duser-provided-service 200 OK in 2 milliseconds
I0526 08:47:11.665603 17338 controller_instance.go:972] ServiceInstance "foo21/ups-instance-with-parametersfrom": resolved {ClassExternalName:"user-provided-service"} to K8S ClusterServiceClass "4f6e6cf6-ffdd-425f-a2c7-3c9258ad2468"
I0526 08:47:11.756254 17338 round_trippers.go:405] GET https://localhost:6443/apis/servicecatalog.k8s.io/v1beta1/clusterserviceplans?fieldSelector=spec.externalName%3Ddefault%2Cspec.clusterServiceClassRef.name%3D4f6e6cf6-ffdd-425f-a2c7-3c9258ad2468%2Cspec.clusterServiceBrokerName%3Dups-broker 200 OK in 90 milliseconds
I0526 08:47:11.756527 17338 controller_instance.go:1049] ServiceInstance "foo21/ups-instance-with-parametersfrom": resolved {ClassExternalName:"user-provided-service", PlanExternalName:"default"} to ClusterServicePlan (K8S: "86064792-7ea2-467b-af93-ac9694d96d52")
I0526 08:47:11.756554 17338 controller_instance.go:1205] ServiceInstance "foo21/ups-instance-with-parametersfrom": Updating references (resourceVersion: 122, generation: 1, observed: 1)
I0526 08:47:11.762955 17338 round_trippers.go:405] PUT https://localhost:6443/apis/servicecatalog.k8s.io/v1beta1/namespaces/foo21/serviceinstances/ups-instance-with-parametersfrom/reference 200 OK in 6 milliseconds
I0526 08:47:11.763208 17338 controller.go:361] Reconciler function finished without error
I0526 08:47:11.763225 17338 controller.go:365] -------------------------------------------------
I0526 08:47:11.863809 17338 controller_instance.go:119] ===========================================
I0526 08:47:11.863846 17338 controller_instance.go:120] Adding ServiceInstance 'foo21/ups-instance-with-parametersfrom' to queue
I0526 08:47:11.863866 17338 controller_instance.go:121] ServiceInstance updated. New resourceVersion: 123, generation: 1, observed: 0
I0526 08:47:11.863885 17338 controller_instance.go:122] ===========================================
I0526 08:47:11.863930 17338 controller.go:357] -------------------------------------------------
I0526 08:47:11.864001 17338 controller_instance.go:1112] ServiceInstance "foo21/ups-instance-with-parametersfrom": Removing condition "Failed"
I0526 08:47:11.864031 17338 controller_instance.go:370] ServiceInstance "foo21/ups-instance-with-parametersfrom": Processing adding event
I0526 08:47:11.864071 17338 controller.go:461] ServiceInstance "foo21/ups-instance-with-parametersfrom": Creating client for ClusterServiceBroker ups-broker, URL: http://ups-broker-ups-broker.ups-broker.svc.cluster.local
I0526 08:47:11.867025 17338 round_trippers.go:405] GET https://localhost:6443/api/v1/namespaces/foo21 200 OK in 2 milliseconds
I0526 08:47:11.870242 17338 round_trippers.go:405] GET https://localhost:6443/api/v1/namespaces/foo21/secrets/instance-parameters 404 Not Found in 2 milliseconds
I0526 08:47:11.870374 17338 controller_instance.go:1155] ServiceInstance "foo21/ups-instance-with-parametersfrom": failed to prepare parameters nil: secrets "instance-parameters" not found
I0526 08:47:11.870389 17338 controller_instance.go:1156] ServiceInstance "foo21/ups-instance-with-parametersfrom": Setting condition "Ready" to False
I0526 08:47:11.870407 17338 controller_instance.go:1169] ServiceInstance "foo21/ups-instance-with-parametersfrom": Setting lastTransitionTime, condition "Ready" to 2018-05-26 08:47:11.870359749 +0200 CEST m=+1366.709768611
I0526 08:47:11.870445 17338 controller_instance.go:1230] ServiceInstance "foo21/ups-instance-with-parametersfrom": Updating status (resourceVersion: 123, generation: 1, observed: 1)
I0526 08:47:11.874859 17338 round_trippers.go:405] PUT https://localhost:6443/apis/servicecatalog.k8s.io/v1beta1/namespaces/foo21/serviceinstances/ups-instance-with-parametersfrom/status 200 OK in 4 milliseconds
I0526 08:47:11.875056 17338 controller.go:370] Error syncing ServiceInstance foo21/ups-instance-with-parametersfrom (retry: 0/15): failed to prepare parameters nil: secrets "instance-parameters" not found
I0526 08:47:11.875074 17338 controller.go:372] -------------------------------------------------
I0526 08:47:11.875122 17338 event.go:218] Event(v1.ObjectReference{Kind:"ServiceInstance", Namespace:"foo21", Name:"ups-instance-with-parametersfrom", UID:"9df12c32-60b0-11e8-88db-0242ac110007", APIVersion:"servicecatalog.k8s.io", ResourceVersion:"123", FieldPath:""}): type: 'Warning' reason: 'ErrorWithParameters' failed to prepare parameters nil: secrets "instance-parameters" not found
I0526 08:47:11.877633 17338 round_trippers.go:405] POST https://localhost:6443/api/v1/namespaces/foo21/events 201 Created in 2 milliseconds
I0526 08:47:11.880176 17338 controller.go:357] -------------------------------------------------
I0526 08:47:11.880228 17338 controller_instance.go:1112] ServiceInstance "foo21/ups-instance-with-parametersfrom": Removing condition "Failed"
I0526 08:47:11.880240 17338 controller_instance.go:370] ServiceInstance "foo21/ups-instance-with-parametersfrom": Processing adding event
I0526 08:47:11.880259 17338 controller.go:461] ServiceInstance "foo21/ups-instance-with-parametersfrom": Creating client for ClusterServiceBroker ups-broker, URL: http://ups-broker-ups-broker.ups-broker.svc.cluster.local
I0526 08:47:11.881466 17338 round_trippers.go:405] GET https://localhost:6443/api/v1/namespaces/foo21 200 OK in 1 milliseconds
I0526 08:47:11.882462 17338 round_trippers.go:405] GET https://localhost:6443/api/v1/namespaces/foo21/secrets/instance-parameters 404 Not Found in 0 milliseconds
I0526 08:47:11.882538 17338 controller_instance.go:1155] ServiceInstance "foo21/ups-instance-with-parametersfrom": failed to prepare parameters nil: secrets "instance-parameters" not found
I0526 08:47:11.882549 17338 controller_instance.go:1156] ServiceInstance "foo21/ups-instance-with-parametersfrom": Setting condition "Ready" to False
I0526 08:47:11.882562 17338 controller_instance.go:1169] ServiceInstance "foo21/ups-instance-with-parametersfrom": Setting lastTransitionTime, condition "Ready" to 2018-05-26 08:47:11.882528464 +0200 CEST m=+1366.721937317
I0526 08:47:11.882570 17338 controller_instance.go:1230] ServiceInstance "foo21/ups-instance-with-parametersfrom": Updating status (resourceVersion: 123, generation: 1, observed: 1)
I0526 08:47:11.885176 17338 round_trippers.go:405] PUT https://localhost:6443/apis/servicecatalog.k8s.io/v1beta1/namespaces/foo21/serviceinstances/ups-instance-with-parametersfrom/status 409 Conflict in 2 milliseconds
I0526 08:47:11.885282 17338 controller_instance.go:1236] ServiceInstance "foo21/ups-instance-with-parametersfrom": Couldn't update status because the resource was modified by someone else
I0526 08:47:11.887333 17338 round_trippers.go:405] GET https://localhost:6443/apis/servicecatalog.k8s.io/v1beta1/namespaces/foo21/serviceinstances/ups-instance-with-parametersfrom 200 OK in 1 milliseconds
I0526 08:47:11.987681 17338 controller_instance.go:1230] ServiceInstance "foo21/ups-instance-with-parametersfrom": Updating status (resourceVersion: 123, generation: 1, observed: 1)
I0526 08:47:11.993938 17338 round_trippers.go:405] PUT https://localhost:6443/apis/servicecatalog.k8s.io/v1beta1/namespaces/foo21/serviceinstances/ups-instance-with-parametersfrom/status 200 OK in 5 milliseconds
I0526 08:47:11.994287 17338 controller.go:370] Error syncing ServiceInstance foo21/ups-instance-with-parametersfrom (retry: 1/15): failed to prepare parameters nil: secrets "instance-parameters" not found
I0526 08:47:11.994327 17338 controller.go:372] -------------------------------------------------
I0526 08:47:11.994370 17338 event.go:218] Event(v1.ObjectReference{Kind:"ServiceInstance", Namespace:"foo21", Name:"ups-instance-with-parametersfrom", UID:"9df12c32-60b0-11e8-88db-0242ac110007", APIVersion:"servicecatalog.k8s.io", ResourceVersion:"123", FieldPath:""}): type: 'Warning' reason: 'ErrorWithParameters' failed to prepare parameters nil: secrets "instance-parameters" not found
I0526 08:47:11.998839 17338 round_trippers.go:405] PATCH https://localhost:6443/api/v1/namespaces/foo21/events/ups-instance-with-parametersfrom.15321eee293e9cb3 200 OK in 3 milliseconds
I0526 08:47:12.004496 17338 controller.go:357] -------------------------------------------------
I0526 08:47:12.004551 17338 controller_instance.go:1112] ServiceInstance "foo21/ups-instance-with-parametersfrom": Removing condition "Failed"
I0526 08:47:12.004566 17338 controller_instance.go:370] ServiceInstance "foo21/ups-instance-with-parametersfrom": Processing adding event
I0526 08:47:12.004586 17338 controller.go:461] ServiceInstance "foo21/ups-instance-with-parametersfrom": Creating client for ClusterServiceBroker ups-broker, URL: http://ups-broker-ups-broker.ups-broker.svc.cluster.local
I0526 08:47:12.006727 17338 round_trippers.go:405] GET https://localhost:6443/api/v1/namespaces/foo21 200 OK in 2 milliseconds
I0526 08:47:12.009217 17338 round_trippers.go:405] GET https://localhost:6443/api/v1/namespaces/foo21/secrets/instance-parameters 404 Not Found in 2 milliseconds
I0526 08:47:12.009377 17338 controller_instance.go:1155] ServiceInstance "foo21/ups-instance-with-parametersfrom": failed to prepare parameters nil: secrets "instance-parameters" not found
I0526 08:47:12.009402 17338 controller_instance.go:1156] ServiceInstance "foo21/ups-instance-with-parametersfrom": Setting condition "Ready" to False
I0526 08:47:12.009457 17338 controller_instance.go:1169] ServiceInstance "foo21/ups-instance-with-parametersfrom": Setting lastTransitionTime, condition "Ready" to 2018-05-26 08:47:12.009357139 +0200 CEST m=+1366.848766034
I0526 08:47:12.009477 17338 controller_instance.go:1230] ServiceInstance "foo21/ups-instance-with-parametersfrom": Updating status (resourceVersion: 123, generation: 1, observed: 1)
I0526 08:47:12.014589 17338 round_trippers.go:405] PUT https://localhost:6443/apis/servicecatalog.k8s.io/v1beta1/namespaces/foo21/serviceinstances/ups-instance-with-parametersfrom/status 409 Conflict in 4 milliseconds
I0526 08:47:12.014781 17338 controller_instance.go:1236] ServiceInstance "foo21/ups-instance-with-parametersfrom": Couldn't update status because the resource was modified by someone else
I0526 08:47:12.018566 17338 round_trippers.go:405] GET https://localhost:6443/apis/servicecatalog.k8s.io/v1beta1/namespaces/foo21/serviceinstances/ups-instance-with-parametersfrom 200 OK in 3 milliseconds
I0526 08:47:12.064091 17338 controller_instance.go:119] ===========================================
I0526 08:47:12.064130 17338 controller_instance.go:120] Adding ServiceInstance 'foo21/ups-instance-with-parametersfrom' to queue
I0526 08:47:12.064152 17338 controller_instance.go:121] ServiceInstance updated. New resourceVersion: 124, generation: 1, observed: 1
I0526 08:47:12.064172 17338 controller_instance.go:122] ===========================================
I0526 08:47:12.118942 17338 controller_instance.go:1230] ServiceInstance "foo21/ups-instance-with-parametersfrom": Updating status (resourceVersion: 123, generation: 1, observed: 1)
I0526 08:47:12.123532 17338 round_trippers.go:405] PUT https://localhost:6443/apis/servicecatalog.k8s.io/v1beta1/namespaces/foo21/serviceinstances/ups-instance-with-parametersfrom/status 200 OK in 4 milliseconds
I0526 08:47:12.123726 17338 controller.go:370] Error syncing ServiceInstance foo21/ups-instance-with-parametersfrom (retry: 2/15): failed to prepare parameters nil: secrets "instance-parameters" not found
I0526 08:47:12.123745 17338 controller.go:372] -------------------------------------------------
I0526 08:47:12.123760 17338 controller.go:357] -------------------------------------------------
I0526 08:47:12.123785 17338 controller_instance.go:370] ServiceInstance "foo21/ups-instance-with-parametersfrom": Processing adding event
I0526 08:47:12.123800 17338 controller.go:461] ServiceInstance "foo21/ups-instance-with-parametersfrom": Creating client for ClusterServiceBroker ups-broker, URL: http://ups-broker-ups-broker.ups-broker.svc.cluster.local
I0526 08:47:12.123793 17338 event.go:218] Event(v1.ObjectReference{Kind:"ServiceInstance", Namespace:"foo21", Name:"ups-instance-with-parametersfrom", UID:"9df12c32-60b0-11e8-88db-0242ac110007", APIVersion:"servicecatalog.k8s.io", ResourceVersion:"123", FieldPath:""}): type: 'Warning' reason: 'ErrorWithParameters' failed to prepare parameters nil: secrets "instance-parameters" not found
I0526 08:47:12.125450 17338 round_trippers.go:405] GET https://localhost:6443/api/v1/namespaces/foo21 200 OK in 1 milliseconds
I0526 08:47:12.126737 17338 round_trippers.go:405] PATCH https://localhost:6443/api/v1/namespaces/foo21/events/ups-instance-with-parametersfrom.15321eee293e9cb3 200 OK in 2 milliseconds
I0526 08:47:12.126836 17338 round_trippers.go:405] GET https://localhost:6443/api/v1/namespaces/foo21/secrets/instance-parameters 404 Not Found in 1 milliseconds
I0526 08:47:12.126941 17338 controller_instance.go:1155] ServiceInstance "foo21/ups-instance-with-parametersfrom": failed to prepare parameters nil: secrets "instance-parameters" not found
I0526 08:47:12.126956 17338 controller_instance.go:1156] ServiceInstance "foo21/ups-instance-with-parametersfrom": Setting condition "Ready" to False
I0526 08:47:12.126967 17338 controller_instance.go:1230] ServiceInstance "foo21/ups-instance-with-parametersfrom": Updating status (resourceVersion: 124, generation: 1, observed: 1)
I0526 08:47:12.131871 17338 round_trippers.go:405] PUT https://localhost:6443/apis/servicecatalog.k8s.io/v1beta1/namespaces/foo21/serviceinstances/ups-instance-with-parametersfrom/status 409 Conflict in 4 milliseconds
I0526 08:47:12.132022 17338 controller_instance.go:1236] ServiceInstance "foo21/ups-instance-with-parametersfrom": Couldn't update status because the resource was modified by someone else
I0526 08:47:12.135411 17338 round_trippers.go:405] GET https://localhost:6443/apis/servicecatalog.k8s.io/v1beta1/namespaces/foo21/serviceinstances/ups-instance-with-parametersfrom 200 OK in 3 milliseconds
I0526 08:47:12.235899 17338 controller_instance.go:1230] ServiceInstance "foo21/ups-instance-with-parametersfrom": Updating status (resourceVersion: 124, generation: 1, observed: 1)
I0526 08:47:12.263499 17338 controller_instance.go:119] ===========================================
I0526 08:47:12.263520 17338 controller_instance.go:120] Adding ServiceInstance 'foo21/ups-instance-with-parametersfrom' to queue
I0526 08:47:12.263530 17338 controller_instance.go:121] ServiceInstance updated. New resourceVersion: 125, generation: 1, observed: 1
I0526 08:47:12.263542 17338 controller_instance.go:122] ===========================================
I0526 08:47:12.270968 17338 round_trippers.go:405] PUT https://localhost:6443/apis/servicecatalog.k8s.io/v1beta1/namespaces/foo21/serviceinstances/ups-instance-with-parametersfrom/status 200 OK in 7 milliseconds
I0526 08:47:12.271235 17338 controller.go:370] Error syncing ServiceInstance foo21/ups-instance-with-parametersfrom (retry: 3/15): failed to prepare parameters nil: secrets "instance-parameters" not found
I0526 08:47:12.271261 17338 controller.go:372] -------------------------------------------------
I0526 08:47:12.271275 17338 controller.go:357] -------------------------------------------------
I0526 08:47:12.271317 17338 controller_instance.go:370] ServiceInstance "foo21/ups-instance-with-parametersfrom": Processing adding event
I0526 08:47:12.271310 17338 event.go:218] Event(v1.ObjectReference{Kind:"ServiceInstance", Namespace:"foo21", Name:"ups-instance-with-parametersfrom", UID:"9df12c32-60b0-11e8-88db-0242ac110007", APIVersion:"servicecatalog.k8s.io", ResourceVersion:"124", FieldPath:""}): type: 'Warning' reason: 'ErrorWithParameters' failed to prepare parameters nil: secrets "instance-parameters" not found
I0526 08:47:12.271344 17338 controller.go:461] ServiceInstance "foo21/ups-instance-with-parametersfrom": Creating client for ClusterServiceBroker ups-broker, URL: http://ups-broker-ups-broker.ups-broker.svc.cluster.local
I0526 08:47:12.273170 17338 round_trippers.go:405] GET https://localhost:6443/api/v1/namespaces/foo21 200 OK in 1 milliseconds
I0526 08:47:12.274270 17338 round_trippers.go:405] GET https://localhost:6443/api/v1/namespaces/foo21/secrets/instance-parameters 404 Not Found in 0 milliseconds
I0526 08:47:12.274283 17338 round_trippers.go:405] PATCH https://localhost:6443/api/v1/namespaces/foo21/events/ups-instance-with-parametersfrom.15321eee293e9cb3 200 OK in 2 milliseconds
I0526 08:47:12.274362 17338 controller_instance.go:1155] ServiceInstance "foo21/ups-instance-with-parametersfrom": failed to prepare parameters nil: secrets "instance-parameters" not found
I0526 08:47:12.274375 17338 controller_instance.go:1156] ServiceInstance "foo21/ups-instance-with-parametersfrom": Setting condition "Ready" to False
I0526 08:47:12.274387 17338 controller_instance.go:1230] ServiceInstance "foo21/ups-instance-with-parametersfrom": Updating status (resourceVersion: 125, generation: 1, observed: 1)
I0526 08:47:12.463489 17338 request.go:481] Throttling request took 188.94991ms, request: PUT:https://localhost:6443/apis/servicecatalog.k8s.io/v1beta1/namespaces/foo21/serviceinstances/ups-instance-with-parametersfrom/status
I0526 08:47:12.463795 17338 controller_instance.go:119] ===========================================
I0526 08:47:12.463822 17338 controller_instance.go:120] Adding ServiceInstance 'foo21/ups-instance-with-parametersfrom' to queue
I0526 08:47:12.463841 17338 controller_instance.go:121] ServiceInstance updated. New resourceVersion: 126, generation: 1, observed: 1
I0526 08:47:12.463860 17338 controller_instance.go:122] ===========================================
I0526 08:47:12.553647 17338 round_trippers.go:405] PUT https://localhost:6443/apis/servicecatalog.k8s.io/v1beta1/namespaces/foo21/serviceinstances/ups-instance-with-parametersfrom/status 409 Conflict in 90 milliseconds
I0526 08:47:12.553758 17338 controller_instance.go:1236] ServiceInstance "foo21/ups-instance-with-parametersfrom": Couldn't update status because the resource was modified by someone else
I0526 08:47:12.663389 17338 request.go:481] Throttling request took 109.576136ms, request: GET:https://localhost:6443/apis/servicecatalog.k8s.io/v1beta1/namespaces/foo21/serviceinstances/ups-instance-with-parametersfrom
I0526 08:47:12.666240 17338 round_trippers.go:405] GET https://localhost:6443/apis/servicecatalog.k8s.io/v1beta1/namespaces/foo21/serviceinstances/ups-instance-with-parametersfrom 200 OK in 2 milliseconds
I0526 08:47:12.766564 17338 controller_instance.go:1230] ServiceInstance "foo21/ups-instance-with-parametersfrom": Updating status (resourceVersion: 125, generation: 1, observed: 1)
I0526 08:47:12.863434 17338 request.go:481] Throttling request took 96.689899ms, request: PUT:https://localhost:6443/apis/servicecatalog.k8s.io/v1beta1/namespaces/foo21/serviceinstances/ups-instance-with-parametersfrom/status
I0526 08:47:12.956842 17338 round_trippers.go:405] PUT https://localhost:6443/apis/servicecatalog.k8s.io/v1beta1/namespaces/foo21/serviceinstances/ups-instance-with-parametersfrom/status 200 OK in 93 milliseconds
I0526 08:47:12.957171 17338 controller.go:370] Error syncing ServiceInstance foo21/ups-instance-with-parametersfrom (retry: 4/15): failed to prepare parameters nil: secrets "instance-parameters" not found
I0526 08:47:12.957210 17338 controller.go:372] -------------------------------------------------
I0526 08:47:12.957233 17338 controller.go:357] -------------------------------------------------
I0526 08:47:12.957281 17338 controller_instance.go:370] ServiceInstance "foo21/ups-instance-with-parametersfrom": Processing adding event
I0526 08:47:12.957256 17338 event.go:218] Event(v1.ObjectReference{Kind:"ServiceInstance", Namespace:"foo21", Name:"ups-instance-with-parametersfrom", UID:"9df12c32-60b0-11e8-88db-0242ac110007", APIVersion:"servicecatalog.k8s.io", ResourceVersion:"125", FieldPath:""}): type: 'Warning' reason: 'ErrorWithParameters' failed to prepare parameters nil: secrets "instance-parameters" not found
I0526 08:47:12.957311 17338 controller.go:461] ServiceInstance "foo21/ups-instance-with-parametersfrom": Creating client for ClusterServiceBroker ups-broker, URL: http://ups-broker-ups-broker.ups-broker.svc.cluster.local
I0526 08:47:12.959670 17338 round_trippers.go:405] GET https://localhost:6443/api/v1/namespaces/foo21 200 OK in 2 milliseconds
I0526 08:47:12.961409 17338 round_trippers.go:405] GET https://localhost:6443/api/v1/namespaces/foo21/secrets/instance-parameters 200 OK in 1 milliseconds
I0526 08:47:12.961479 17338 round_trippers.go:405] PATCH https://localhost:6443/api/v1/namespaces/foo21/events/ups-instance-with-parametersfrom.15321eee293e9cb3 200 OK in 3 milliseconds
I0526 08:47:12.961586 17338 controller_instance.go:1155] ServiceInstance "foo21/ups-instance-with-parametersfrom": Provision request for ServiceInstance in-flight to Broker
I0526 08:47:12.961603 17338 controller_instance.go:1156] ServiceInstance "foo21/ups-instance-with-parametersfrom": Setting condition "Ready" to False
I0526 08:47:12.961618 17338 controller_instance.go:1230] ServiceInstance "foo21/ups-instance-with-parametersfrom": Updating status (resourceVersion: 126, generation: 1, observed: 1)
I0526 08:47:13.063523 17338 request.go:481] Throttling request took 101.746101ms, request: PUT:https://localhost:6443/apis/servicecatalog.k8s.io/v1beta1/namespaces/foo21/serviceinstances/ups-instance-with-parametersfrom/status
I0526 08:47:13.063962 17338 controller_instance.go:119] ===========================================
I0526 08:47:13.063996 17338 controller_instance.go:120] Adding ServiceInstance 'foo21/ups-instance-with-parametersfrom' to queue
I0526 08:47:13.064018 17338 controller_instance.go:121] ServiceInstance updated. New resourceVersion: 127, generation: 1, observed: 1
I0526 08:47:13.064039 17338 controller_instance.go:122] ===========================================
I0526 08:47:13.070745 17338 round_trippers.go:405] PUT https://localhost:6443/apis/servicecatalog.k8s.io/v1beta1/namespaces/foo21/serviceinstances/ups-instance-with-parametersfrom/status 409 Conflict in 7 milliseconds
I0526 08:47:13.071014 17338 controller_instance.go:1236] ServiceInstance "foo21/ups-instance-with-parametersfrom": Couldn't update status because the resource was modified by someone else
I0526 08:47:13.263535 17338 request.go:481] Throttling request took 192.397065ms, request: GET:https://localhost:6443/apis/servicecatalog.k8s.io/v1beta1/namespaces/foo21/serviceinstances/ups-instance-with-parametersfrom
I0526 08:47:13.354356 17338 round_trippers.go:405] GET https://localhost:6443/apis/servicecatalog.k8s.io/v1beta1/namespaces/foo21/serviceinstances/ups-instance-with-parametersfrom 200 OK in 90 milliseconds
I0526 08:47:13.454885 17338 controller_instance.go:1230] ServiceInstance "foo21/ups-instance-with-parametersfrom": Updating status (resourceVersion: 126, generation: 1, observed: 1)
I0526 08:47:13.656924 17338 round_trippers.go:405] PUT https://localhost:6443/apis/servicecatalog.k8s.io/v1beta1/namespaces/foo21/serviceinstances/ups-instance-with-parametersfrom/status 200 OK in 193 milliseconds
I0526 08:47:13.657059 17338 controller.go:361] Reconciler function finished without error
I0526 08:47:13.657066 17338 controller.go:365] -------------------------------------------------
I0526 08:47:13.657074 17338 controller.go:357] -------------------------------------------------
I0526 08:47:13.657092 17338 controller_instance.go:370] ServiceInstance "foo21/ups-instance-with-parametersfrom": Processing adding event
I0526 08:47:13.657102 17338 controller.go:461] ServiceInstance "foo21/ups-instance-with-parametersfrom": Creating client for ClusterServiceBroker ups-broker, URL: http://ups-broker-ups-broker.ups-broker.svc.cluster.local
I0526 08:47:13.658411 17338 round_trippers.go:405] GET https://localhost:6443/api/v1/namespaces/foo21 200 OK in 1 milliseconds
I0526 08:47:13.659676 17338 round_trippers.go:405] GET https://localhost:6443/api/v1/namespaces/foo21/secrets/instance-parameters 200 OK in 1 milliseconds
I0526 08:47:13.659802 17338 controller_instance.go:1155] ServiceInstance "foo21/ups-instance-with-parametersfrom": Provision request for ServiceInstance in-flight to Broker
I0526 08:47:13.659812 17338 controller_instance.go:1156] ServiceInstance "foo21/ups-instance-with-parametersfrom": Setting condition "Ready" to False
I0526 08:47:13.659823 17338 controller_instance.go:1230] ServiceInstance "foo21/ups-instance-with-parametersfrom": Updating status (resourceVersion: 127, generation: 1, observed: 1)
I0526 08:47:13.954574 17338 round_trippers.go:405] PUT https://localhost:6443/apis/servicecatalog.k8s.io/v1beta1/namespaces/foo21/serviceinstances/ups-instance-with-parametersfrom/status 409 Conflict in 291 milliseconds
I0526 08:47:13.954745 17338 controller_instance.go:1236] ServiceInstance "foo21/ups-instance-with-parametersfrom": Couldn't update status because the resource was modified by someone else
I0526 08:47:13.958666 17338 round_trippers.go:405] GET https://localhost:6443/apis/servicecatalog.k8s.io/v1beta1/namespaces/foo21/serviceinstances/ups-instance-with-parametersfrom 200 OK in 3 milliseconds
I0526 08:47:14.059117 17338 controller_instance.go:1230] ServiceInstance "foo21/ups-instance-with-parametersfrom": Updating status (resourceVersion: 127, generation: 1, observed: 1)
I0526 08:47:14.063652 17338 controller_instance.go:119] ===========================================
I0526 08:47:14.063680 17338 controller_instance.go:120] Adding ServiceInstance 'foo21/ups-instance-with-parametersfrom' to queue
I0526 08:47:14.063696 17338 controller_instance.go:121] ServiceInstance updated. New resourceVersion: 128, generation: 1, observed: 1
I0526 08:47:14.063708 17338 controller_instance.go:122] ===========================================
I0526 08:47:14.071619 17338 round_trippers.go:405] PUT https://localhost:6443/apis/servicecatalog.k8s.io/v1beta1/namespaces/foo21/serviceinstances/ups-instance-with-parametersfrom/status 200 OK in 8 milliseconds
I0526 08:47:14.071762 17338 controller.go:361] Reconciler function finished without error
I0526 08:47:14.071769 17338 controller.go:365] -------------------------------------------------
I0526 08:47:14.071781 17338 controller.go:357] -------------------------------------------------
I0526 08:47:14.071804 17338 controller_instance.go:370] ServiceInstance "foo21/ups-instance-with-parametersfrom": Processing adding event
I0526 08:47:14.071817 17338 controller.go:461] ServiceInstance "foo21/ups-instance-with-parametersfrom": Creating client for ClusterServiceBroker ups-broker, URL: http://ups-broker-ups-broker.ups-broker.svc.cluster.local
I0526 08:47:14.072983 17338 round_trippers.go:405] GET https://localhost:6443/api/v1/namespaces/foo21 200 OK in 1 milliseconds
I0526 08:47:14.074084 17338 round_trippers.go:405] GET https://localhost:6443/api/v1/namespaces/foo21/secrets/instance-parameters 200 OK in 1 milliseconds
I0526 08:47:14.074200 17338 controller_instance.go:399] ServiceInstance "foo21/ups-instance-with-parametersfrom": Provisioning a new ServiceInstance of ClusterServiceClass (K8S: "4f6e6cf6-ffdd-425f-a2c7-3c9258ad2468" ExternalName: "user-provided-service") at ClusterServiceBroker "ups-broker"
I0526 08:47:14.075076 17338 controller_instance.go:1155] ServiceInstance "foo21/ups-instance-with-parametersfrom": The instance was provisioned successfully
I0526 08:47:14.075096 17338 controller_instance.go:1156] ServiceInstance "foo21/ups-instance-with-parametersfrom": Setting condition "Ready" to True
I0526 08:47:14.075117 17338 controller_instance.go:1181] ServiceInstance "foo21/ups-instance-with-parametersfrom": Found status change, condition "Ready": "False" -> "True"; setting lastTransitionTime to 2018-05-26 08:47:14.075059867 +0200 CEST m=+1368.914468772
I0526 08:47:14.075130 17338 controller_instance.go:1230] ServiceInstance "foo21/ups-instance-with-parametersfrom": Updating status (resourceVersion: 128, generation: 1, observed: 1)
I0526 08:47:14.263346 17338 controller_instance.go:119] ===========================================
I0526 08:47:14.263363 17338 controller_instance.go:120] Adding ServiceInstance 'foo21/ups-instance-with-parametersfrom' to queue
I0526 08:47:14.263370 17338 controller_instance.go:121] ServiceInstance updated. New resourceVersion: 129, generation: 1, observed: 1
I0526 08:47:14.263371 17338 request.go:481] Throttling request took 188.14507ms, request: PUT:https://localhost:6443/apis/servicecatalog.k8s.io/v1beta1/namespaces/foo21/serviceinstances/ups-instance-with-parametersfrom/status
I0526 08:47:14.263378 17338 controller_instance.go:122] ===========================================
I0526 08:47:14.265534 17338 round_trippers.go:405] PUT https://localhost:6443/apis/servicecatalog.k8s.io/v1beta1/namespaces/foo21/serviceinstances/ups-instance-with-parametersfrom/status 409 Conflict in 2 milliseconds
I0526 08:47:14.265617 17338 controller_instance.go:1236] ServiceInstance "foo21/ups-instance-with-parametersfrom": Couldn't update status because the resource was modified by someone else
I0526 08:47:14.463511 17338 request.go:481] Throttling request took 197.82502ms, request: GET:https://localhost:6443/apis/servicecatalog.k8s.io/v1beta1/namespaces/foo21/serviceinstances/ups-instance-with-parametersfrom
I0526 08:47:14.468802 17338 round_trippers.go:405] GET https://localhost:6443/apis/servicecatalog.k8s.io/v1beta1/namespaces/foo21/serviceinstances/ups-instance-with-parametersfrom 200 OK in 5 milliseconds
I0526 08:47:14.569372 17338 controller_instance.go:1230] ServiceInstance "foo21/ups-instance-with-parametersfrom": Updating status (resourceVersion: 128, generation: 1, observed: 1)
I0526 08:47:14.663402 17338 request.go:481] Throttling request took 93.811572ms, request: PUT:https://localhost:6443/apis/servicecatalog.k8s.io/v1beta1/namespaces/foo21/serviceinstances/ups-instance-with-parametersfrom/status
I0526 08:47:14.666592 17338 round_trippers.go:405] PUT https://localhost:6443/apis/servicecatalog.k8s.io/v1beta1/namespaces/foo21/serviceinstances/ups-instance-with-parametersfrom/status 200 OK in 3 milliseconds
I0526 08:47:14.666770 17338 controller.go:361] Reconciler function finished without error
I0526 08:47:14.666777 17338 controller.go:365] -------------------------------------------------
I0526 08:47:14.666786 17338 controller.go:357] -------------------------------------------------
I0526 08:47:14.666807 17338 controller_instance.go:370] ServiceInstance "foo21/ups-instance-with-parametersfrom": Processing adding event
I0526 08:47:14.666823 17338 controller.go:461] ServiceInstance "foo21/ups-instance-with-parametersfrom": Creating client for ClusterServiceBroker ups-broker, URL: http://ups-broker-ups-broker.ups-broker.svc.cluster.local
I0526 08:47:14.666886 17338 event.go:218] Event(v1.ObjectReference{Kind:"ServiceInstance", Namespace:"foo21", Name:"ups-instance-with-parametersfrom", UID:"9df12c32-60b0-11e8-88db-0242ac110007", APIVersion:"servicecatalog.k8s.io", ResourceVersion:"128", FieldPath:""}): type: 'Normal' reason: 'ProvisionedSuccessfully' The instance was provisioned successfully
I0526 08:47:14.668034 17338 round_trippers.go:405] GET https://localhost:6443/api/v1/namespaces/foo21 200 OK in 1 milliseconds
I0526 08:47:14.668686 17338 round_trippers.go:405] POST https://localhost:6443/api/v1/namespaces/foo21/events 201 Created in 1 milliseconds
I0526 08:47:14.669168 17338 round_trippers.go:405] GET https://localhost:6443/api/v1/namespaces/foo21/secrets/instance-parameters 200 OK in 1 milliseconds
I0526 08:47:14.669277 17338 controller_instance.go:399] ServiceInstance "foo21/ups-instance-with-parametersfrom": Provisioning a new ServiceInstance of ClusterServiceClass (K8S: "4f6e6cf6-ffdd-425f-a2c7-3c9258ad2468" ExternalName: "user-provided-service") at ClusterServiceBroker "ups-broker"
I0526 08:47:14.670107 17338 controller_instance.go:1155] ServiceInstance "foo21/ups-instance-with-parametersfrom": The instance was provisioned successfully
I0526 08:47:14.670126 17338 controller_instance.go:1156] ServiceInstance "foo21/ups-instance-with-parametersfrom": Setting condition "Ready" to True
I0526 08:47:14.670145 17338 controller_instance.go:1181] ServiceInstance "foo21/ups-instance-with-parametersfrom": Found status change, condition "Ready": "False" -> "True"; setting lastTransitionTime to 2018-05-26 08:47:14.670091413 +0200 CEST m=+1369.509500274
I0526 08:47:14.670161 17338 controller_instance.go:1230] ServiceInstance "foo21/ups-instance-with-parametersfrom": Updating status (resourceVersion: 129, generation: 1, observed: 1)
I0526 08:47:14.863471 17338 request.go:481] Throttling request took 193.157369ms, request: PUT:https://localhost:6443/apis/servicecatalog.k8s.io/v1beta1/namespaces/foo21/serviceinstances/ups-instance-with-parametersfrom/status
I0526 08:47:14.863890 17338 controller_instance.go:119] ===========================================
I0526 08:47:14.863919 17338 controller_instance.go:120] Adding ServiceInstance 'foo21/ups-instance-with-parametersfrom' to queue
I0526 08:47:14.863935 17338 controller_instance.go:121] ServiceInstance updated. New resourceVersion: 130, generation: 1, observed: 1
I0526 08:47:14.863952 17338 controller_instance.go:122] ===========================================
I0526 08:47:14.954684 17338 round_trippers.go:405] PUT https://localhost:6443/apis/servicecatalog.k8s.io/v1beta1/namespaces/foo21/serviceinstances/ups-instance-with-parametersfrom/status 409 Conflict in 91 milliseconds
I0526 08:47:14.954842 17338 controller_instance.go:1236] ServiceInstance "foo21/ups-instance-with-parametersfrom": Couldn't update status because the resource was modified by someone else
I0526 08:47:15.063490 17338 request.go:481] Throttling request took 108.559717ms, request: GET:https://localhost:6443/apis/servicecatalog.k8s.io/v1beta1/namespaces/foo21/serviceinstances/ups-instance-with-parametersfrom
I0526 08:47:15.153520 17338 round_trippers.go:405] GET https://localhost:6443/apis/servicecatalog.k8s.io/v1beta1/namespaces/foo21/serviceinstances/ups-instance-with-parametersfrom 200 OK in 89 milliseconds
I0526 08:47:15.253979 17338 controller_instance.go:1230] ServiceInstance "foo21/ups-instance-with-parametersfrom": Updating status (resourceVersion: 129, generation: 1, observed: 1)
I0526 08:47:15.354580 17338 round_trippers.go:405] PUT https://localhost:6443/apis/servicecatalog.k8s.io/v1beta1/namespaces/foo21/serviceinstances/ups-instance-with-parametersfrom/status 200 OK in 91 milliseconds
I0526 08:47:15.355005 17338 controller.go:361] Reconciler function finished without error
I0526 08:47:15.355026 17338 controller.go:365] -------------------------------------------------
I0526 08:47:15.355051 17338 controller.go:357] -------------------------------------------------
I0526 08:47:15.355099 17338 controller_instance.go:460] ServiceInstance "foo21/ups-instance-with-parametersfrom": Not processing event because status showed there is no work to do
I0526 08:47:15.355112 17338 controller.go:361] Reconciler function finished without error
I0526 08:47:15.355123 17338 controller.go:365] -------------------------------------------------
I0526 08:47:15.355147 17338 event.go:218] Event(v1.ObjectReference{Kind:"ServiceInstance", Namespace:"foo21", Name:"ups-instance-with-parametersfrom", UID:"9df12c32-60b0-11e8-88db-0242ac110007", APIVersion:"servicecatalog.k8s.io", ResourceVersion:"129", FieldPath:""}): type: 'Normal' reason: 'ProvisionedSuccessfully' The instance was provisioned successfully
I0526 08:47:15.360785 17338 round_trippers.go:405] PATCH https://localhost:6443/api/v1/namespaces/foo21/events/ups-instance-with-parametersfrom.15321eeecfa4efe8 200 OK in 5 milliseconds
I0526 08:47:26.156591 17338 round_trippers.go:405] GET https://localhost:6443/api/v1/namespaces/default/configmaps/cluster-info 200 OK in 2 milliseconds
I0526 08:47:41.158240 17338 round_trippers.go:405] GET https://localhost:6443/api/v1/namespaces/default/configmaps/cluster-info 200 OK in 1 milliseconds
I0526 08:47:45.567583 17338 reflector.go:286] github.com/kubernetes-incubator/service-catalog/pkg/client/informers_generated/externalversions/factory.go:75: forcing resync
I0526 08:47:45.567643 17338 controller.go:357] -------------------------------------------------
I0526 08:47:45.567685 17338 controller_clusterservicebroker.go:173] ClusterServiceBroker "ups-broker": Processing
I0526 08:47:45.567695 17338 controller.go:361] Reconciler function finished without error
I0526 08:47:45.567701 17338 controller.go:365] -------------------------------------------------
I0526 08:47:54.753141 17338 reflector.go:428] github.com/kubernetes-incubator/service-catalog/pkg/client/informers_generated/externalversions/factory.go:75: Watch close - *v1beta1.ClusterServiceBroker total 1 items received
I0526 08:47:54.956804 17338 round_trippers.go:405] GET https://localhost:6443/apis/servicecatalog.k8s.io/v1beta1/clusterservicebrokers?resourceVersion=95&timeoutSeconds=387&watch=true 200 OK in 203 milliseconds
I0526 08:47:56.161259 17338 round_trippers.go:405] GET https://localhost:6443/api/v1/namespaces/default/configmaps/cluster-info 200 OK in 2 milliseconds
I0526 08:48:11.164762 17338 round_trippers.go:405] GET https://localhost:6443/api/v1/namespaces/default/configmaps/cluster-info 200 OK in 3 milliseconds
I0526 08:48:26.167589 17338 round_trippers.go:405] GET https://localhost:6443/api/v1/namespaces/default/configmaps/cluster-info 200 OK in 2 milliseconds
I0526 08:48:41.169821 17338 round_trippers.go:405] GET https://localhost:6443/api/v1/namespaces/default/configmaps/cluster-info 200 OK in 1 milliseconds
I0526 08:48:56.172455 17338 round_trippers.go:405] GET https://localhost:6443/api/v1/namespaces/default/configmaps/cluster-info 200 OK in 2 milliseconds
I0526 08:49:08.586334 17338 reflector.go:286] github.com/kubernetes-incubator/service-catalog/pkg/client/informers_generated/externalversions/factory.go:75: forcing resync
I0526 08:49:08.586539 17338 controller.go:357] -------------------------------------------------
I0526 08:49:08.586549 17338 controller.go:357] -------------------------------------------------
I0526 08:49:08.586576 17338 controller.go:357] -------------------------------------------------
I0526 08:49:08.586572 17338 controller_clusterserviceclass.go:72] ClusterServiceClass "4f6e6cf6-ffdd-425f-a2c7-3c9258ad2468" (ExternalName: "user-provided-service"): processing
I0526 08:49:08.586582 17338 controller_clusterserviceclass.go:72] ClusterServiceClass "5f6e6cf6-ffdd-425f-a2c7-3c9258ad2468" (ExternalName: "user-provided-service-single-plan"): processing
I0526 08:49:08.586598 17338 controller_clusterserviceclass.go:72] ClusterServiceClass "8a6229d4-239e-4790-ba1f-8367004d0473" (ExternalName: "user-provided-service-with-schemas"): processing
I0526 08:49:08.586612 17338 controller.go:361] Reconciler function finished without error
I0526 08:49:08.586613 17338 controller.go:361] Reconciler function finished without error
I0526 08:49:08.586635 17338 controller.go:361] Reconciler function finished without error
I0526 08:49:08.586639 17338 controller.go:365] -------------------------------------------------
I0526 08:49:08.586647 17338 controller.go:365] -------------------------------------------------
I0526 08:49:08.586652 17338 controller.go:365] -------------------------------------------------
I0526 08:49:11.175327 17338 round_trippers.go:405] GET https://localhost:6443/api/v1/namespaces/default/configmaps/cluster-info 200 OK in 2 milliseconds
I0526 08:49:25.754115 17338 reflector.go:428] github.com/kubernetes-incubator/service-catalog/pkg/client/informers_generated/externalversions/factory.go:75: Watch close - *v1beta1.ClusterServiceClass total 0 items received
I0526 08:49:25.953298 17338 round_trippers.go:405] GET https://localhost:6443/apis/servicecatalog.k8s.io/v1beta1/clusterserviceclasses?resourceVersion=92&timeoutSeconds=389&watch=true 200 OK in 199 milliseconds
W0526 08:49:25.953389 17338 reflector.go:341] github.com/kubernetes-incubator/service-catalog/pkg/client/informers_generated/externalversions/factory.go:75: watch of *v1beta1.ClusterServiceClass ended with: very short watch: github.com/kubernetes-incubator/service-catalog/pkg/client/informers_generated/externalversions/factory.go:75: Unexpected watch close - watch lasted less than a second and no items received
I0526 08:49:26.177889 17338 round_trippers.go:405] GET https://localhost:6443/api/v1/namespaces/default/configmaps/cluster-info 200 OK in 2 milliseconds
I0526 08:49:26.953560 17338 reflector.go:240] Listing and watching *v1beta1.ClusterServiceClass from github.com/kubernetes-incubator/service-catalog/pkg/client/informers_generated/externalversions/factory.go:75
I0526 08:49:27.055010 17338 round_trippers.go:405] GET https://localhost:6443/apis/servicecatalog.k8s.io/v1beta1/clusterserviceclasses?limit=500&resourceVersion=0 200 OK in 101 milliseconds
I0526 08:49:27.055573 17338 controller.go:357] -------------------------------------------------
I0526 08:49:27.055616 17338 controller_clusterserviceclass.go:72] ClusterServiceClass "4f6e6cf6-ffdd-425f-a2c7-3c9258ad2468" (ExternalName: "user-provided-service"): processing
I0526 08:49:27.055644 17338 controller.go:357] -------------------------------------------------
I0526 08:49:27.055677 17338 controller_clusterserviceclass.go:72] ClusterServiceClass "8a6229d4-239e-4790-ba1f-8367004d0473" (ExternalName: "user-provided-service-with-schemas"): processing
I0526 08:49:27.055706 17338 controller.go:361] Reconciler function finished without error
I0526 08:49:27.055651 17338 controller.go:361] Reconciler function finished without error
I0526 08:49:27.055635 17338 controller.go:357] -------------------------------------------------
I0526 08:49:27.055756 17338 controller.go:365] -------------------------------------------------
I0526 08:49:27.055734 17338 controller.go:365] -------------------------------------------------
I0526 08:49:27.055762 17338 controller_clusterserviceclass.go:72] ClusterServiceClass "5f6e6cf6-ffdd-425f-a2c7-3c9258ad2468" (ExternalName: "user-provided-service-single-plan"): processing
I0526 08:49:27.055796 17338 controller.go:361] Reconciler function finished without error
I0526 08:49:27.055815 17338 controller.go:365] -------------------------------------------------
I0526 08:49:27.259992 17338 round_trippers.go:405] GET https://localhost:6443/apis/servicecatalog.k8s.io/v1beta1/clusterserviceclasses?resourceVersion=130&timeoutSeconds=525&watch=true 200 OK in 204 milliseconds
I0526 08:49:41.179727 17338 round_trippers.go:405] GET https://localhost:6443/api/v1/namespaces/default/configmaps/cluster-info 200 OK in 1 milliseconds
I0526 08:49:56.181990 17338 round_trippers.go:405] GET https://localhost:6443/api/v1/namespaces/default/configmaps/cluster-info 200 OK in 1 milliseconds
I0526 08:49:58.663122 17338 reflector.go:428] github.com/kubernetes-incubator/service-catalog/pkg/client/informers_generated/externalversions/factory.go:75: Watch close - *v1beta1.ServiceBinding total 0 items received
I0526 08:49:58.758759 17338 round_trippers.go:405] GET https://localhost:6443/apis/servicecatalog.k8s.io/v1beta1/servicebindings?resourceVersion=92&timeoutSeconds=361&watch=true 200 OK in 95 milliseconds
W0526 08:49:58.758815 17338 reflector.go:341] github.com/kubernetes-incubator/service-catalog/pkg/client/informers_generated/externalversions/factory.go:75: watch of *v1beta1.ServiceBinding ended with: very short watch: github.com/kubernetes-incubator/service-catalog/pkg/client/informers_generated/externalversions/factory.go:75: Unexpected watch close - watch lasted less than a second and no items received
I0526 08:49:59.659234 17338 reflector.go:286] github.com/kubernetes-incubator/service-catalog/pkg/client/informers_generated/externalversions/factory.go:75: forcing resync
I0526 08:49:59.659345 17338 controller_instance.go:119] ===========================================
I0526 08:49:59.659356 17338 controller_instance.go:120] Adding ServiceInstance 'foo12/ups-instance-with-parametersfrom' to queue
I0526 08:49:59.659364 17338 controller_instance.go:121] ServiceInstance updated. New resourceVersion: 79, generation: 1, observed: 1
I0526 08:49:59.659375 17338 controller_instance.go:122] ===========================================
I0526 08:49:59.659389 17338 controller_instance.go:119] ===========================================
I0526 08:49:59.659396 17338 controller_instance.go:120] Adding ServiceInstance 'foo19/ups-instance-with-parametersfrom' to queue
I0526 08:49:59.659398 17338 controller.go:357] -------------------------------------------------
I0526 08:49:59.659403 17338 controller_instance.go:121] ServiceInstance updated. New resourceVersion: 116, generation: 1, observed: 1
I0526 08:49:59.659411 17338 controller_instance.go:122] ===========================================
I0526 08:49:59.659434 17338 controller.go:357] -------------------------------------------------
I0526 08:49:59.659454 17338 controller_instance.go:460] ServiceInstance "foo12/ups-instance-with-parametersfrom": Not processing event because status showed there is no work to do
I0526 08:49:59.659465 17338 controller.go:361] Reconciler function finished without error
I0526 08:49:59.659475 17338 controller.go:365] -------------------------------------------------
I0526 08:49:59.659457 17338 controller_instance.go:460] ServiceInstance "foo19/ups-instance-with-parametersfrom": Not processing event because status showed there is no work to do
I0526 08:49:59.659490 17338 controller.go:361] Reconciler function finished without error
I0526 08:49:59.659494 17338 controller_instance.go:119] ===========================================
I0526 08:49:59.659508 17338 controller_instance.go:120] Adding ServiceInstance 'foo20/ups-instance-with-parametersfrom' to queue
I0526 08:49:59.659516 17338 controller_instance.go:121] ServiceInstance updated. New resourceVersion: 121, generation: 1, observed: 1
I0526 08:49:59.659524 17338 controller_instance.go:122] ===========================================
I0526 08:49:59.659534 17338 controller_instance.go:119] ===========================================
I0526 08:49:59.659538 17338 controller_instance.go:120] Adding ServiceInstance 'foo21/ups-instance-with-parametersfrom' to queue
I0526 08:49:59.659543 17338 controller_instance.go:121] ServiceInstance updated. New resourceVersion: 130, generation: 1, observed: 1
I0526 08:49:59.659547 17338 controller_instance.go:122] ===========================================
I0526 08:49:59.659557 17338 controller.go:357] -------------------------------------------------
I0526 08:49:59.659497 17338 controller.go:365] -------------------------------------------------
I0526 08:49:59.659574 17338 controller.go:357] -------------------------------------------------
I0526 08:49:59.659605 17338 controller_instance.go:460] ServiceInstance "foo21/ups-instance-with-parametersfrom": Not processing event because status showed there is no work to do
I0526 08:49:59.659612 17338 controller.go:361] Reconciler function finished without error
I0526 08:49:59.659618 17338 controller.go:365] -------------------------------------------------
I0526 08:49:59.659569 17338 controller_instance.go:119] ===========================================
I0526 08:49:59.659627 17338 controller_instance.go:120] Adding ServiceInstance 'foo15/ups-instance-with-parametersfrom' to queue
I0526 08:49:59.659634 17338 controller_instance.go:121] ServiceInstance updated. New resourceVersion: 90, generation: 1, observed: 1
I0526 08:49:59.659642 17338 controller_instance.go:122] ===========================================
I0526 08:49:59.659653 17338 controller_instance.go:119] ===========================================
I0526 08:49:59.659660 17338 controller_instance.go:120] Adding ServiceInstance 'foo18/ups-instance-with-parametersfrom' to queue
I0526 08:49:59.659664 17338 controller_instance.go:121] ServiceInstance updated. New resourceVersion: 111, generation: 1, observed: 1
I0526 08:49:59.659669 17338 controller_instance.go:122] ===========================================
I0526 08:49:59.659676 17338 controller.go:357] -------------------------------------------------
I0526 08:49:59.659689 17338 controller_instance.go:460] ServiceInstance "foo15/ups-instance-with-parametersfrom": Not processing event because status showed there is no work to do
I0526 08:49:59.659693 17338 controller.go:361] Reconciler function finished without error
I0526 08:49:59.659700 17338 controller.go:365] -------------------------------------------------
I0526 08:49:59.659575 17338 controller_instance.go:460] ServiceInstance "foo20/ups-instance-with-parametersfrom": Not processing event because status showed there is no work to do
I0526 08:49:59.659722 17338 controller_instance.go:119] ===========================================
I0526 08:49:59.659709 17338 controller.go:357] -------------------------------------------------
I0526 08:49:59.659730 17338 controller_instance.go:120] Adding ServiceInstance 'foo1/ups-instance-with-parametersfrom' to queue
I0526 08:49:59.659737 17338 controller_instance.go:121] ServiceInstance updated. New resourceVersion: 15, generation: 1, observed: 1
I0526 08:49:59.659746 17338 controller_instance.go:122] ===========================================
I0526 08:49:59.659753 17338 controller_instance.go:460] ServiceInstance "foo18/ups-instance-with-parametersfrom": Not processing event because status showed there is no work to do
I0526 08:49:59.659756 17338 controller_instance.go:119] ===========================================
I0526 08:49:59.659762 17338 controller.go:361] Reconciler function finished without error
I0526 08:49:59.659764 17338 controller_instance.go:120] Adding ServiceInstance 'foo10/ups-instance-with-parametersfrom' to queue
I0526 08:49:59.659771 17338 controller.go:365] -------------------------------------------------
I0526 08:49:59.659772 17338 controller_instance.go:121] ServiceInstance updated. New resourceVersion: 67, generation: 1, observed: 1
I0526 08:49:59.659722 17338 controller.go:361] Reconciler function finished without error
I0526 08:49:59.659780 17338 controller_instance.go:122] ===========================================
I0526 08:49:59.659782 17338 controller.go:357] -------------------------------------------------
I0526 08:49:59.659787 17338 controller.go:365] -------------------------------------------------
I0526 08:49:59.659792 17338 controller.go:357] -------------------------------------------------
I0526 08:49:59.659801 17338 controller_instance.go:460] ServiceInstance "foo1/ups-instance-with-parametersfrom": Not processing event because status showed there is no work to do
I0526 08:49:59.659811 17338 controller_instance.go:460] ServiceInstance "foo10/ups-instance-with-parametersfrom": Not processing event because status showed there is no work to do
I0526 08:49:59.659819 17338 controller.go:361] Reconciler function finished without error
I0526 08:49:59.659826 17338 controller.go:365] -------------------------------------------------
I0526 08:49:59.659812 17338 controller.go:361] Reconciler function finished without error
I0526 08:49:59.659835 17338 controller.go:365] -------------------------------------------------
I0526 08:49:59.659801 17338 controller_instance.go:119] ===========================================
I0526 08:49:59.659842 17338 controller_instance.go:120] Adding ServiceInstance 'foo13/ups-instance-with-parametersfrom' to queue
I0526 08:49:59.659846 17338 controller_instance.go:121] ServiceInstance updated. New resourceVersion: 84, generation: 1, observed: 1
I0526 08:49:59.659851 17338 controller_instance.go:122] ===========================================
I0526 08:49:59.659859 17338 controller_instance.go:119] ===========================================
I0526 08:49:59.659863 17338 controller_instance.go:120] Adding ServiceInstance 'foo6/ups-instance-with-parametersfrom' to queue
I0526 08:49:59.659867 17338 controller_instance.go:121] ServiceInstance updated. New resourceVersion: 49, generation: 1, observed: 1
I0526 08:49:59.659870 17338 controller.go:357] -------------------------------------------------
I0526 08:49:59.659874 17338 controller_instance.go:122] ===========================================
I0526 08:49:59.659884 17338 controller.go:357] -------------------------------------------------
I0526 08:49:59.659887 17338 controller_instance.go:460] ServiceInstance "foo13/ups-instance-with-parametersfrom": Not processing event because status showed there is no work to do
I0526 08:49:59.659892 17338 controller.go:361] Reconciler function finished without error
I0526 08:49:59.659896 17338 controller_instance.go:460] ServiceInstance "foo6/ups-instance-with-parametersfrom": Not processing event because status showed there is no work to do
I0526 08:49:59.659897 17338 controller.go:365] -------------------------------------------------
I0526 08:49:59.659902 17338 controller.go:361] Reconciler function finished without error
I0526 08:49:59.659907 17338 controller.go:365] -------------------------------------------------
I0526 08:49:59.659907 17338 controller_instance.go:119] ===========================================
I0526 08:49:59.659912 17338 controller_instance.go:120] Adding ServiceInstance 'foo7/ups-instance-with-parametersfrom' to queue
I0526 08:49:59.659917 17338 controller_instance.go:121] ServiceInstance updated. New resourceVersion: 54, generation: 1, observed: 1
I0526 08:49:59.659922 17338 controller_instance.go:122] ===========================================
I0526 08:49:59.659928 17338 controller_instance.go:119] ===========================================
I0526 08:49:59.659932 17338 controller_instance.go:120] Adding ServiceInstance 'foo17/ups-instance-with-parametersfrom' to queue
I0526 08:49:59.659936 17338 controller_instance.go:121] ServiceInstance updated. New resourceVersion: 105, generation: 1, observed: 1
I0526 08:49:59.659940 17338 controller_instance.go:122] ===========================================
I0526 08:49:59.659947 17338 controller.go:357] -------------------------------------------------
I0526 08:49:59.659947 17338 controller.go:357] -------------------------------------------------
I0526 08:49:59.659959 17338 controller_instance.go:460] ServiceInstance "foo17/ups-instance-with-parametersfrom": Not processing event because status showed there is no work to do
I0526 08:49:59.659964 17338 controller.go:361] Reconciler function finished without error
I0526 08:49:59.659965 17338 controller_instance.go:460] ServiceInstance "foo7/ups-instance-with-parametersfrom": Not processing event because status showed there is no work to do
I0526 08:49:59.659968 17338 controller.go:365] -------------------------------------------------
I0526 08:49:59.659970 17338 controller.go:361] Reconciler function finished without error
I0526 08:49:59.659975 17338 controller.go:365] -------------------------------------------------
I0526 08:49:59.659976 17338 controller_instance.go:119] ===========================================
I0526 08:49:59.659981 17338 controller_instance.go:120] Adding ServiceInstance 'foo9/ups-instance-with-parametersfrom' to queue
I0526 08:49:59.659985 17338 controller_instance.go:121] ServiceInstance updated. New resourceVersion: 63, generation: 1, observed: 1
I0526 08:49:59.659990 17338 controller_instance.go:122] ===========================================
I0526 08:49:59.659996 17338 controller_instance.go:119] ===========================================
I0526 08:49:59.660000 17338 controller_instance.go:120] Adding ServiceInstance 'foo16/ups-instance-with-parametersfrom' to queue
I0526 08:49:59.660008 17338 controller_instance.go:121] ServiceInstance updated. New resourceVersion: 100, generation: 1, observed: 1
I0526 08:49:59.660012 17338 controller_instance.go:122] ===========================================
I0526 08:49:59.660016 17338 controller.go:357] -------------------------------------------------
I0526 08:49:59.660023 17338 controller_instance.go:119] ===========================================
I0526 08:49:59.660028 17338 controller_instance.go:120] Adding ServiceInstance 'foo11/ups-instance-with-parametersfrom' to queue
I0526 08:49:59.660032 17338 controller_instance.go:121] ServiceInstance updated. New resourceVersion: 74, generation: 1, observed: 1
I0526 08:49:59.660038 17338 controller_instance.go:122] ===========================================
I0526 08:49:59.660043 17338 controller_instance.go:119] ===========================================
I0526 08:49:59.660047 17338 controller_instance.go:120] Adding ServiceInstance 'foo2/ups-instance-with-parametersfrom' to queue
I0526 08:49:59.660051 17338 controller_instance.go:121] ServiceInstance updated. New resourceVersion: 30, generation: 1, observed: 1
I0526 08:49:59.660056 17338 controller_instance.go:122] ===========================================
I0526 08:49:59.660062 17338 controller.go:357] -------------------------------------------------
I0526 08:49:59.660073 17338 controller_instance.go:460] ServiceInstance "foo11/ups-instance-with-parametersfrom": Not processing event because status showed there is no work to do
I0526 08:49:59.660076 17338 controller.go:357] -------------------------------------------------
I0526 08:49:59.660085 17338 controller_instance.go:119] ===========================================
I0526 08:49:59.660089 17338 controller_instance.go:120] Adding ServiceInstance 'foo3/ups-instance-with-parametersfrom' to queue
I0526 08:49:59.660094 17338 controller_instance.go:121] ServiceInstance updated. New resourceVersion: 35, generation: 1, observed: 1
I0526 08:49:59.660096 17338 controller_instance.go:460] ServiceInstance "foo2/ups-instance-with-parametersfrom": Not processing event because status showed there is no work to do
I0526 08:49:59.660098 17338 controller_instance.go:122] ===========================================
I0526 08:49:59.660033 17338 controller_instance.go:460] ServiceInstance "foo9/ups-instance-with-parametersfrom": Not processing event because status showed there is no work to do
I0526 08:49:59.660104 17338 controller.go:361] Reconciler function finished without error
I0526 08:49:59.660111 17338 controller.go:357] -------------------------------------------------
I0526 08:49:59.660018 17338 controller.go:357] -------------------------------------------------
I0526 08:49:59.660078 17338 controller.go:361] Reconciler function finished without error
I0526 08:49:59.660131 17338 controller.go:365] -------------------------------------------------
I0526 08:49:59.660132 17338 controller_instance.go:460] ServiceInstance "foo16/ups-instance-with-parametersfrom": Not processing event because status showed there is no work to do
I0526 08:49:59.660111 17338 controller.go:361] Reconciler function finished without error
I0526 08:49:59.660139 17338 controller.go:361] Reconciler function finished without error
I0526 08:49:59.660142 17338 controller.go:365] -------------------------------------------------
I0526 08:49:59.660144 17338 controller.go:365] -------------------------------------------------
I0526 08:49:59.660106 17338 controller_instance.go:119] ===========================================
I0526 08:49:59.660151 17338 controller_instance.go:120] Adding ServiceInstance 'foo4/ups-instance-with-parametersfrom' to queue
I0526 08:49:59.660156 17338 controller_instance.go:121] ServiceInstance updated. New resourceVersion: 44, generation: 1, observed: 1
I0526 08:49:59.660161 17338 controller_instance.go:122] ===========================================
I0526 08:49:59.660173 17338 controller_instance.go:119] ===========================================
I0526 08:49:59.660178 17338 controller_instance.go:120] Adding ServiceInstance 'foo8/ups-instance-with-parametersfrom' to queue
I0526 08:49:59.660113 17338 controller.go:365] -------------------------------------------------
I0526 08:49:59.660185 17338 controller_instance.go:121] ServiceInstance updated. New resourceVersion: 59, generation: 1, observed: 1
I0526 08:49:59.660122 17338 controller_instance.go:460] ServiceInstance "foo3/ups-instance-with-parametersfrom": Not processing event because status showed there is no work to do
I0526 08:49:59.660190 17338 controller.go:357] -------------------------------------------------
I0526 08:49:59.660194 17338 controller_instance.go:122] ===========================================
I0526 08:49:59.660196 17338 controller.go:361] Reconciler function finished without error
I0526 08:49:59.660202 17338 controller.go:365] -------------------------------------------------
I0526 08:49:59.660203 17338 controller.go:357] -------------------------------------------------
I0526 08:49:59.660224 17338 controller_instance.go:460] ServiceInstance "foo8/ups-instance-with-parametersfrom": Not processing event because status showed there is no work to do
I0526 08:49:59.660230 17338 controller.go:361] Reconciler function finished without error
I0526 08:49:59.660262 17338 controller.go:365] -------------------------------------------------
I0526 08:49:59.660204 17338 controller_instance.go:460] ServiceInstance "foo4/ups-instance-with-parametersfrom": Not processing event because status showed there is no work to do
I0526 08:49:59.660275 17338 controller.go:361] Reconciler function finished without error
I0526 08:49:59.660282 17338 controller.go:365] -------------------------------------------------
I0526 08:49:59.758964 17338 reflector.go:240] Listing and watching *v1beta1.ServiceBinding from github.com/kubernetes-incubator/service-catalog/pkg/client/informers_generated/externalversions/factory.go:75
I0526 08:49:59.762515 17338 round_trippers.go:405] GET https://localhost:6443/apis/servicecatalog.k8s.io/v1beta1/servicebindings?limit=500&resourceVersion=0 200 OK in 3 milliseconds
I0526 08:49:59.965606 17338 round_trippers.go:405] GET https://localhost:6443/apis/servicecatalog.k8s.io/v1beta1/servicebindings?resourceVersion=130&timeoutSeconds=559&watch=true 200 OK in 202 milliseconds
I0526 08:50:11.184637 17338 round_trippers.go:405] GET https://localhost:6443/api/v1/namespaces/default/configmaps/cluster-info 200 OK in 2 milliseconds
I0526 08:50:26.186988 17338 round_trippers.go:405] GET https://localhost:6443/api/v1/namespaces/default/configmaps/cluster-info 200 OK in 1 milliseconds
I0526 08:50:41.188668 17338 round_trippers.go:405] GET https://localhost:6443/api/v1/namespaces/default/configmaps/cluster-info 200 OK in 1 milliseconds
I0526 08:50:56.190713 17338 round_trippers.go:405] GET https://localhost:6443/api/v1/namespaces/default/configmaps/cluster-info 200 OK in 1 milliseconds
I0526 08:51:11.192398 17338 round_trippers.go:405] GET https://localhost:6443/api/v1/namespaces/default/configmaps/cluster-info 200 OK in 1 milliseconds
I0526 08:51:18.954562 17338 reflector.go:286] github.com/kubernetes-incubator/service-catalog/pkg/client/informers_generated/externalversions/factory.go:75: forcing resync
I0526 08:51:18.954753 17338 controller.go:357] -------------------------------------------------
I0526 08:51:18.954772 17338 controller_clusterserviceplan.go:73] ClusterServicePlan "4dbcd97c-c9d2-4c6b-9503-4401a789b558" (ExternalName: "default"): processing
I0526 08:51:18.954789 17338 controller.go:361] Reconciler function finished without error
I0526 08:51:18.954801 17338 controller.go:365] -------------------------------------------------
I0526 08:51:18.954805 17338 controller.go:357] -------------------------------------------------
I0526 08:51:18.954848 17338 controller_clusterserviceplan.go:73] ClusterServicePlan "86064792-7ea2-467b-af93-ac9694d96d52" (ExternalName: "default"): processing
I0526 08:51:18.954868 17338 controller.go:361] Reconciler function finished without error
I0526 08:51:18.954864 17338 controller.go:357] -------------------------------------------------
I0526 08:51:18.954872 17338 controller.go:357] -------------------------------------------------
I0526 08:51:18.954893 17338 controller_clusterserviceplan.go:73] ClusterServicePlan "96064792-7ea2-467b-af93-ac9694d96d52" (ExternalName: "default"): processing
I0526 08:51:18.954911 17338 controller.go:361] Reconciler function finished without error
I0526 08:51:18.954910 17338 controller_clusterserviceplan.go:73] ClusterServicePlan "cc0d7529-18e8-416d-8946-6f7456acd589" (ExternalName: "premium"): processing
I0526 08:51:18.954922 17338 controller.go:365] -------------------------------------------------
I0526 08:51:18.954927 17338 controller.go:361] Reconciler function finished without error
I0526 08:51:18.954937 17338 controller.go:365] -------------------------------------------------
I0526 08:51:18.954883 17338 controller.go:365] -------------------------------------------------
I0526 08:51:26.194054 17338 round_trippers.go:405] GET https://localhost:6443/api/v1/namespaces/default/configmaps/cluster-info 200 OK in 1 milliseconds
I0526 08:51:41.197137 17338 round_trippers.go:405] GET https://localhost:6443/api/v1/namespaces/default/configmaps/cluster-info 200 OK in 2 milliseconds
I0526 08:51:56.200220 17338 round_trippers.go:405] GET https://localhost:6443/api/v1/namespaces/default/configmaps/cluster-info 200 OK in 2 milliseconds
I0526 08:52:11.204708 17338 round_trippers.go:405] GET https://localhost:6443/api/v1/namespaces/default/configmaps/cluster-info 200 OK in 3 milliseconds
I0526 08:52:26.206504 17338 round_trippers.go:405] GET https://localhost:6443/api/v1/namespaces/default/configmaps/cluster-info 200 OK in 1 milliseconds
I0526 08:52:41.210567 17338 round_trippers.go:405] GET https://localhost:6443/api/v1/namespaces/default/configmaps/cluster-info 200 OK in 3 milliseconds
I0526 08:52:45.567820 17338 reflector.go:286] github.com/kubernetes-incubator/service-catalog/pkg/client/informers_generated/externalversions/factory.go:75: forcing resync
I0526 08:52:45.567930 17338 controller.go:357] -------------------------------------------------
I0526 08:52:45.568012 17338 controller_clusterservicebroker.go:173] ClusterServiceBroker "ups-broker": Processing
I0526 08:52:45.568036 17338 controller.go:361] Reconciler function finished without error
I0526 08:52:45.568049 17338 controller.go:365] -------------------------------------------------
I0526 08:52:56.214519 17338 round_trippers.go:405] GET https://localhost:6443/api/v1/namespaces/default/configmaps/cluster-info 200 OK in 3 milliseconds
I0526 08:53:11.216291 17338 round_trippers.go:405] GET https://localhost:6443/api/v1/namespaces/default/configmaps/cluster-info 200 OK in 1 milliseconds
I0526 08:53:26.218184 17338 round_trippers.go:405] GET https://localhost:6443/api/v1/namespaces/default/configmaps/cluster-info 200 OK in 1 milliseconds
I0526 08:53:41.220173 17338 round_trippers.go:405] GET https://localhost:6443/api/v1/namespaces/default/configmaps/cluster-info 200 OK in 1 milliseconds
I0526 08:53:56.221972 17338 round_trippers.go:405] GET https://localhost:6443/api/v1/namespaces/default/configmaps/cluster-info 200 OK in 1 milliseconds
I0526 08:54:09.957251 17338 reflector.go:428] github.com/kubernetes-incubator/service-catalog/pkg/client/informers_generated/externalversions/factory.go:75: Watch close - *v1beta1.ClusterServicePlan total 0 items received
I0526 08:54:10.254382 17338 round_trippers.go:405] GET https://localhost:6443/apis/servicecatalog.k8s.io/v1beta1/clusterserviceplans?resourceVersion=106&timeoutSeconds=509&watch=true 200 OK in 296 milliseconds
I0526 08:54:11.226111 17338 round_trippers.go:405] GET https://localhost:6443/api/v1/namespaces/default/configmaps/cluster-info 200 OK in 3 milliseconds
I0526 08:54:21.756201 17338 reflector.go:428] github.com/kubernetes-incubator/service-catalog/pkg/client/informers_generated/externalversions/factory.go:75: Watch close - *v1beta1.ClusterServiceBroker total 0 items received
I0526 08:54:21.856062 17338 round_trippers.go:405] GET https://localhost:6443/apis/servicecatalog.k8s.io/v1beta1/clusterservicebrokers?resourceVersion=95&timeoutSeconds=457&watch=true 200 OK in 99 milliseconds
W0526 08:54:21.856124 17338 reflector.go:341] github.com/kubernetes-incubator/service-catalog/pkg/client/informers_generated/externalversions/factory.go:75: watch of *v1beta1.ClusterServiceBroker ended with: very short watch: github.com/kubernetes-incubator/service-catalog/pkg/client/informers_generated/externalversions/factory.go:75: Unexpected watch close - watch lasted less than a second and no items received
I0526 08:54:22.856327 17338 reflector.go:240] Listing and watching *v1beta1.ClusterServiceBroker from github.com/kubernetes-incubator/service-catalog/pkg/client/informers_generated/externalversions/factory.go:75
I0526 08:54:22.953518 17338 round_trippers.go:405] GET https://localhost:6443/apis/servicecatalog.k8s.io/v1beta1/clusterservicebrokers?limit=500&resourceVersion=0 200 OK in 97 milliseconds
I0526 08:54:22.953746 17338 controller.go:357] -------------------------------------------------
I0526 08:54:22.953788 17338 controller_clusterservicebroker.go:173] ClusterServiceBroker "ups-broker": Processing
I0526 08:54:22.953797 17338 controller.go:361] Reconciler function finished without error
I0526 08:54:22.953801 17338 controller.go:365] -------------------------------------------------
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment