Bug 1783829 - asb/tsb can not be installed for ocp 4.3
Summary: asb/tsb can not be installed for ocp 4.3
Keywords:
Status: CLOSED ERRATA
Alias: None
Product: OpenShift Container Platform
Classification: Red Hat
Component: Service Broker
Version: 4.3.0
Hardware: Unspecified
OS: Unspecified
high
high
Target Milestone: ---
: 4.3.0
Assignee: Jesus M. Rodriguez
QA Contact: Cuiping HUO
URL:
Whiteboard:
Depends On:
Blocks: 1776135
TreeView+ depends on / blocked
 
Reported: 2019-12-16 03:43 UTC by Cuiping HUO
Modified: 2020-01-23 11:19 UTC (History)
6 users (show)

Fixed In Version:
Doc Type: If docs needed, set a value
Doc Text:
Clone Of:
: 1785132 (view as bug list)
Environment:
Last Closed: 2020-01-23 11:19:25 UTC
Target Upstream Version:
Embargoed:


Attachments (Terms of Use)
a detailed log of asb/tsb install (213.70 KB, text/plain)
2019-12-16 03:43 UTC, Cuiping HUO
no flags Details
Updated formatting to make log easier to read. (111.24 KB, text/plain)
2019-12-16 03:57 UTC, Jesus M. Rodriguez
no flags Details


Links
System ID Private Priority Status Summary Last Updated
Red Hat Product Errata RHBA-2020:0062 0 None None None 2020-01-23 11:19:49 UTC

Description Cuiping HUO 2019-12-16 03:43:24 UTC
Created attachment 1645421 [details]
a detailed log of asb/tsb install

Description of problem:
asb/tsb can not be installed for ocp 4.3

Version-Release number of selected component (if applicable):
4.3.0-0.nightly-2019-12-13-180405

How reproducible:
Always

Steps to Reproduce:
1.installed asb operators
2.install asb
3.installed tsb operators
installed tsb operators

Actual results:
asb/tsb operators can be installed successfully, but asb/tsb install failed.

Expected results:
asb/tsb operators and asb/tsb can be installed successfully

Additional info:
$ oc get po -n openshift-ansible-service-broker
NAME                                                        READY   STATUS    RESTARTS   AGE
openshift-ansible-service-broker-operator-85779877f-2kxz9   1/1     Running   0          4m28s
$ oc logs -f openshift-ansible-service-broker-operator-85779877f-2kxz9 -n openshift-ansible-service-broker
{"level":"info","ts":1576464170.064453,"logger":"cmd","msg":"Go Version: go1.12.12"}
{"level":"info","ts":1576464170.0645478,"logger":"cmd","msg":"Go OS/Arch: linux/amd64"}
{"level":"info","ts":1576464170.064556,"logger":"cmd","msg":"Version of operator-sdk: v0.12.0+git"}
{"level":"info","ts":1576464170.0645752,"logger":"cmd","msg":"Watching namespace.","Namespace":"openshift-ansible-service-broker"}
{"level":"info","ts":1576464172.2723377,"logger":"controller-runtime.metrics","msg":"metrics server is starting to listen","addr":"0.0.0.0:8383"}
{"level":"info","ts":1576464172.2731643,"logger":"watches","msg":"Failed to parse %v from environment. Using default %v","WORKER_AUTOMATIONBROKER_OSB_OPENSHIFT_IO":1}
{"level":"info","ts":1576464172.2731888,"logger":"watches","msg":"Failed to parse %v from environment. Using default %v","ANSIBLE_VERBOSITY_AUTOMATIONBROKER_OSB_OPENSHIFT_IO":2}
{"level":"info","ts":1576464172.2732306,"logger":"ansible-controller","msg":"Watching resource","Options.Group":"osb.openshift.io","Options.Version":"v1","Options.Kind":"AutomationBroker"}
{"level":"info","ts":1576464172.274247,"logger":"controller-runtime.controller","msg":"Starting EventSource","controller":"automationbroker-controller","source":"kind source: osb.openshift.io/v1, Kind=AutomationBroker"}
{"level":"info","ts":1576464172.2744668,"logger":"leader","msg":"Trying to become the leader."}
{"level":"info","ts":1576464174.4988399,"logger":"leader","msg":"No pre-existing lock was found."}
{"level":"info","ts":1576464174.5121145,"logger":"leader","msg":"Became the leader."}
{"level":"info","ts":1576464179.0207112,"logger":"metrics","msg":"Metrics Service object updated","Service.Name":"openshift-ansible-service-broker-operator-metrics","Service.Namespace":"openshift-ansible-service-broker"}
{"level":"info","ts":1576464179.0222967,"logger":"proxy","msg":"Starting to serve","Address":"127.0.0.1:8888"}
{"level":"info","ts":1576464179.0224843,"logger":"controller-runtime.manager","msg":"starting metrics server","path":"/metrics"}
{"level":"info","ts":1576464179.1226544,"logger":"controller-runtime.controller","msg":"Starting Controller","controller":"automationbroker-controller"}
{"level":"info","ts":1576464179.2228234,"logger":"controller-runtime.controller","msg":"Starting workers","controller":"automationbroker-controller","worker count":1}
{"level":"error","ts":1576464179.6275408,"logger":"runner","msg":"Traceback (most recent call last):\n  File \"/usr/lib/python2.7/site-packages/ansible_runner/__main__.py\", line 329, in main\n    res = run(**run_options)\n  File \"/usr/lib/python2.7/site-packages/ansible_runner/interface.py\", line 162, in run\n    r.run()\n  File \"/usr/lib/python2.7/site-packages/ansible_runner/runner.py\", line 93, in run\n    self.status_callback('starting')\n  File \"/usr/lib/python2.7/site-packages/ansible_runner/runner.py\", line 84, in status_callback\n    ansible_runner.plugins[plugin].status_handler(self.config, status_data)\n  File \"/usr/lib/python2.7/site-packages/ansible_runner_http/events.py\", line 38, in status_handler\n    urlpath=plugin_config['runner_path'])\n  File \"/usr/lib/python2.7/site-packages/ansible_runner_http/events.py\", line 18, in send_request\n    return session.post(url_actual, headers=headers, json=(data))\n  File \"/usr/lib/python2.7/site-packages/requests/sessions.py\", line 529, in post\n    return self.request('POST', url, data=data, json=json, **kwargs)\n  File \"/usr/lib/python2.7/site-packages/requests/sessions.py\", line 486, in request\n    resp = self.send(prep, **send_kwargs)\n  File \"/usr/lib/python2.7/site-packages/requests/sessions.py\", line 598, in send\n    r = adapter.send(request, **kwargs)\n  File \"/usr/lib/python2.7/site-packages/requests/adapters.py\", line 370, in send\n    timeout=timeout\n  File \"/usr/lib/python2.7/site-packages/urllib3/connectionpool.py\", line 587, in urlopen\n    timeout_obj = self._get_timeout(timeout)\n  File \"/usr/lib/python2.7/site-packages/urllib3/connectionpool.py\", line 302, in _get_timeout\n    return Timeout.from_float(timeout)\n  File \"/usr/lib/python2.7/site-packages/urllib3/util/timeout.py\", line 154, in from_float\n    return Timeout(read=timeout, connect=timeout)\n  File \"/usr/lib/python2.7/site-packages/urllib3/util/timeout.py\", line 94, in __init__\n    self._connect = self._validate_timeout(connect, 'connect')\n  File \"/usr/lib/python2.7/site-packages/urllib3/util/timeout.py\", line 127, in _validate_timeout\n    \"int, float or None.\" % (name, value))\nValueError: Timeout value connect was Timeout(connect=None, read=None, total=None), but it must be an int, float or None.\n","job":"6129484611666145821","name":"ansible-service-broker","namespace":"openshift-ansible-service-broker","error":"exit status 1","stacktrace":"github.com/go-logr/zapr.(*zapLogger).Error\n\tsrc/github.com/operator-framework/operator-sdk/vendor/github.com/go-logr/zapr/zapr.go:128\ngithub.com/operator-framework/operator-sdk/pkg/ansible/runner.(*runner).Run.func1\n\tsrc/github.com/operator-framework/operator-sdk/pkg/ansible/runner/runner.go:199"}
{"level":"error","ts":1576464179.627876,"logger":"reconciler","msg":"Failed to get ansible-runner stdout","job":"6129484611666145821","name":"ansible-service-broker","namespace":"openshift-ansible-service-broker","error":"open /tmp/ansible-operator/runner/osb.openshift.io/v1/AutomationBroker/openshift-ansible-service-broker/ansible-service-broker/artifacts/6129484611666145821/stdout: no such file or directory","stacktrace":"github.com/go-logr/zapr.(*zapLogger).Error\n\tsrc/github.com/operator-framework/operator-sdk/vendor/github.com/go-logr/zapr/zapr.go:128\ngithub.com/operator-framework/operator-sdk/pkg/ansible/controller.(*AnsibleOperatorReconciler).Reconcile\n\tsrc/github.com/operator-framework/operator-sdk/pkg/ansible/controller/reconcile.go:183\nsigs.k8s.io/controller-runtime/pkg/internal/controller.(*Controller).reconcileHandler\n\tsrc/github.com/operator-framework/operator-sdk/vendor/sigs.k8s.io/controller-runtime/pkg/internal/controller/controller.go:216\nsigs.k8s.io/controller-runtime/pkg/internal/controller.(*Controller).processNextWorkItem\n\tsrc/github.com/operator-framework/operator-sdk/vendor/sigs.k8s.io/controller-runtime/pkg/internal/controller/controller.go:192\nsigs.k8s.io/controller-runtime/pkg/internal/controller.(*Controller).worker\n\tsrc/github.com/operator-framework/operator-sdk/vendor/sigs.k8s.io/controller-runtime/pkg/internal/controller/controller.go:171\nk8s.io/apimachinery/pkg/util/wait.JitterUntil.func1\n\tsrc/github.com/operator-framework/operator-sdk/vendor/k8s.io/apimachinery/pkg/util/wait/wait.go:152\nk8s.io/apimachinery/pkg/util/wait.JitterUntil\n\tsrc/github.com/operator-framework/operator-sdk/vendor/k8s.io/apimachinery/pkg/util/wait/wait.go:153\nk8s.io/apimachinery/pkg/util/wait.Until\n\tsrc/github.com/operator-framework/operator-sdk/vendor/k8s.io/apimachinery/pkg/util/wait/wait.go:88"}
{"level":"error","ts":1576464179.6280143,"logger":"controller-runtime.controller","msg":"Reconciler error","controller":"automationbroker-controller","request":"openshift-ansible-service-broker/ansible-service-broker","error":"open /tmp/ansible-operator/runner/osb.openshift.io/v1/AutomationBroker/openshift-ansible-service-broker/ansible-service-broker/artifacts/6129484611666145821/stdout: no such file or directory","stacktrace":"github.com/go-logr/zapr.(*zapLogger).Error\n\tsrc/github.com/operator-framework/operator-sdk/vendor/github.com/go-logr/zapr/zapr.go:128\nsigs.k8s.io/controller-runtime/pkg/internal/controller.(*Controller).reconcileHandler\n\tsrc/github.com/operator-framework/operator-sdk/vendor/sigs.k8s.io/controller-runtime/pkg/internal/controller/controller.go:218\nsigs.k8s.io/controller-runtime/pkg/internal/controller.(*Controller).processNextWorkItem\n\tsrc/github.com/operator-framework/operator-sdk/vendor/sigs.k8s.io/controller-runtime/pkg/internal/controller/controller.go:192\nsigs.k8s.io/controller-runtime/pkg/internal/controller.(*Controller).worker\n\tsrc/github.com/operator-framework/operator-sdk/vendor/sigs.k8s.io/controller-runtime/pkg/internal/controller/controller.go:171\nk8s.io/apimachinery/pkg/util/wait.JitterUntil.func1\n\tsrc/github.com/operator-framework/operator-sdk/vendor/k8s.io/apimachinery/pkg/util/wait/wait.go:152\nk8s.io/apimachinery/pkg/util/wait.JitterUntil\n\tsrc/github.com/operator-framework/operator-sdk/vendor/k8s.io/apimachinery/pkg/util/wait/wait.go:153\nk8s.io/apimachinery/pkg/util/wait.Until\n\tsrc/github.com/operator-framework/operator-sdk/vendor/k8s.io/apimachinery/pkg/util/wait/wait.go:88"}
{"level":"error","ts":1576464180.9534247,"logger":"runner","msg":"Traceback (most recent call last):\n  File \"/usr/lib/python2.7/site-packages/ansible_runner/__main__.py\", line 329, in main\n    res = run(**run_options)\n  File \"/usr/lib/python2.7/site-packages/ansible_runner/interface.py\", line 162, in run\n    r.run()\n  File \"/usr/lib/python2.7/site-packages/ansible_runner/runner.py\", line 93, in run\n    self.status_callback('starting')\n  File \"/usr/lib/python2.7/site-packages/ansible_runner/runner.py\", line 84, in status_callback\n    ansible_runner.plugins[plugin].status_handler(self.config, status_data)\n  File \"/usr/lib/python2.7/site-packages/ansible_runner_http/events.py\", line 38, in status_handler\n    urlpath=plugin_config['runner_path'])\n  File \"/usr/lib/python2.7/site-packages/ansible_runner_http/events.py\", line 18, in send_request\n    return session.post(url_actual, headers=headers, json=(data))\n  File \"/usr/lib/python2.7/site-packages/requests/sessions.py\", line 529, in post\n    return self.request('POST', url, data=data, json=json, **kwargs)\n  File \"/usr/lib/python2.7/site-packages/requests/sessions.py\", line 486, in request\n    resp = self.send(prep, **send_kwargs)\n  File \"/usr/lib/python2.7/site-packages/requests/sessions.py\", line 598, in send\n    r = adapter.send(request, **kwargs)\n  File \"/usr/lib/python2.7/site-packages/requests/adapters.py\", line 370, in send\n    timeout=timeout\n  File \"/usr/lib/python2.7/site-packages/urllib3/connectionpool.py\", line 587, in urlopen\n    timeout_obj = self._get_timeout(timeout)\n  File \"/usr/lib/python2.7/site-packages/urllib3/connectionpool.py\", line 302, in _get_timeout\n    return Timeout.from_float(timeout)\n  File \"/usr/lib/python2.7/site-packages/urllib3/util/timeout.py\", line 154, in from_float\n    return Timeout(read=timeout, connect=timeout)\n  File \"/usr/lib/python2.7/site-packages/urllib3/util/timeout.py\", line 94, in __init__\n    self._connect = self._validate_timeout(connect, 'connect')\n  File \"/usr/lib/python2.7/site-packages/urllib3/util/timeout.py\", line 127, in _validate_timeout\n    \"int, float or None.\" % (name, value))\nValueError: Timeout value connect was Timeout(connect=None, read=None, total=None), but it must be an int, float or None.\n","job":"4037200794235010051","name":"ansible-service-broker","namespace":"openshift-ansible-service-broker","error":"exit status 1","stacktrace":"github.com/go-logr/zapr.(*zapLogger).Error\n\tsrc/github.com/operator-framework/operator-sdk/vendor/github.com/go-logr/zapr/zapr.go:128\ngithub.com/operator-framework/operator-sdk/pkg/ansible/runner.(*runner).Run.func1\n\tsrc/github.com/operator-framework/operator-sdk/pkg/ansible/runner/runner.go:199"}

$ oc get po -n openshift-template-service-broker
NAME                                                         READY   STATUS    RESTARTS   AGE
openshift-template-service-broker-operator-5d6875794-rn8vq   1/1     Running   0          2m1s
$ oc project openshift-template-service-broker
Now using project "openshift-template-service-broker" on server "https://api.xiyuan-1216.qe.gcp.devcluster.openshift.com:6443".
[chuo@dhcp-140-51 .kube]$ oc logs -f openshift-template-service-broker-operator-5d6875794-rn8vq 
{"level":"info","ts":1576465825.7413166,"logger":"cmd","msg":"Go Version: go1.12.12"}
{"level":"info","ts":1576465825.7413588,"logger":"cmd","msg":"Go OS/Arch: linux/amd64"}
{"level":"info","ts":1576465825.7413635,"logger":"cmd","msg":"Version of operator-sdk: v0.12.0+git"}
{"level":"info","ts":1576465825.7413833,"logger":"cmd","msg":"Watching namespace.","Namespace":"openshift-template-service-broker"}
{"level":"info","ts":1576465827.9493294,"logger":"controller-runtime.metrics","msg":"metrics server is starting to listen","addr":"0.0.0.0:8383"}
{"level":"info","ts":1576465827.9500725,"logger":"watches","msg":"Failed to parse %v from environment. Using default %v","WORKER_TEMPLATESERVICEBROKER_OSB_OPENSHIFT_IO":1}
{"level":"info","ts":1576465827.9501,"logger":"watches","msg":"Failed to parse %v from environment. Using default %v","ANSIBLE_VERBOSITY_TEMPLATESERVICEBROKER_OSB_OPENSHIFT_IO":2}
{"level":"info","ts":1576465827.9501336,"logger":"ansible-controller","msg":"Watching resource","Options.Group":"osb.openshift.io","Options.Version":"v1","Options.Kind":"TemplateServiceBroker"}
{"level":"info","ts":1576465827.9503818,"logger":"controller-runtime.controller","msg":"Starting EventSource","controller":"templateservicebroker-controller","source":"kind source: osb.openshift.io/v1, Kind=TemplateServiceBroker"}
{"level":"info","ts":1576465827.9505699,"logger":"leader","msg":"Trying to become the leader."}
{"level":"info","ts":1576465830.182731,"logger":"leader","msg":"No pre-existing lock was found."}
{"level":"info","ts":1576465830.1922202,"logger":"leader","msg":"Became the leader."}
{"level":"info","ts":1576465834.637494,"logger":"metrics","msg":"Metrics Service object created","Service.Name":"openshift-template-service-broker-operator-metrics","Service.Namespace":"openshift-template-service-broker"}
{"level":"info","ts":1576465834.6386044,"logger":"proxy","msg":"Starting to serve","Address":"127.0.0.1:8888"}
{"level":"info","ts":1576465834.6387155,"logger":"controller-runtime.manager","msg":"starting metrics server","path":"/metrics"}
{"level":"info","ts":1576465834.742628,"logger":"controller-runtime.controller","msg":"Starting Controller","controller":"templateservicebroker-controller"}
{"level":"info","ts":1576465834.8428838,"logger":"controller-runtime.controller","msg":"Starting workers","controller":"templateservicebroker-controller","worker count":1}
{"level":"error","ts":1576465835.2734249,"logger":"runner","msg":"Traceback (most recent call last):\n  File \"/usr/lib/python2.7/site-packages/ansible_runner/__main__.py\", line 329, in main\n    res = run(**run_options)\n  File \"/usr/lib/python2.7/site-packages/ansible_runner/interface.py\", line 162, in run\n    r.run()\n  File \"/usr/lib/python2.7/site-packages/ansible_runner/runner.py\", line 93, in run\n    self.status_callback('starting')\n  File \"/usr/lib/python2.7/site-packages/ansible_runner/runner.py\", line 84, in status_callback\n    ansible_runner.plugins[plugin].status_handler(self.config, status_data)\n  File \"/usr/lib/python2.7/site-packages/ansible_runner_http/events.py\", line 38, in status_handler\n    urlpath=plugin_config['runner_path'])\n  File \"/usr/lib/python2.7/site-packages/ansible_runner_http/events.py\", line 18, in send_request\n    return session.post(url_actual, headers=headers, json=(data))\n  File \"/usr/lib/python2.7/site-packages/requests/sessions.py\", line 529, in post\n    return self.request('POST', url, data=data, json=json, **kwargs)\n  File \"/usr/lib/python2.7/site-packages/requests/sessions.py\", line 486, in request\n    resp = self.send(prep, **send_kwargs)\n  File \"/usr/lib/python2.7/site-packages/requests/sessions.py\", line 598, in send\n    r = adapter.send(request, **kwargs)\n  File \"/usr/lib/python2.7/site-packages/requests/adapters.py\", line 370, in send\n    timeout=timeout\n  File \"/usr/lib/python2.7/site-packages/urllib3/connectionpool.py\", line 587, in urlopen\n    timeout_obj = self._get_timeout(timeout)\n  File \"/usr/lib/python2.7/site-packages/urllib3/connectionpool.py\", line 302, in _get_timeout\n    return Timeout.from_float(timeout)\n  File \"/usr/lib/python2.7/site-packages/urllib3/util/timeout.py\", line 154, in from_float\n    return Timeout(read=timeout, connect=timeout)\n  File \"/usr/lib/python2.7/site-packages/urllib3/util/timeout.py\", line 94, in __init__\n    self._connect = self._validate_timeout(connect, 'connect')\n  File \"/usr/lib/python2.7/site-packages/urllib3/util/timeout.py\", line 127, in _validate_timeout\n    \"int, float or None.\" % (name, value))\nValueError: Timeout value connect was Timeout(connect=None, read=None, total=None), but it must be an int, float or None.\n","job":"6129484611666145821","name":"template-service-broker","namespace":"openshift-template-service-broker","error":"exit status 1","stacktrace":"github.com/go-logr/zapr.(*zapLogger).Error\n\tsrc/github.com/operator-framework/operator-sdk/vendor/github.com/go-logr/zapr/zapr.go:128\ngithub.com/operator-framework/operator-sdk/pkg/ansible/runner.(*runner).Run.func1\n\tsrc/github.com/operator-framework/operator-sdk/pkg/ansible/runner/runner.go:199"}
{"level":"error","ts":1576465835.2737632,"logger":"reconciler","msg":"Failed to get ansible-runner stdout","job":"6129484611666145821","name":"template-service-broker","namespace":"openshift-template-service-broker","error":"open /tmp/ansible-operator/runner/osb.openshift.io/v1/TemplateServiceBroker/openshift-template-service-broker/template-service-broker/artifacts/6129484611666145821/stdout: no such file or directory","stacktrace":"github.com/go-logr/zapr.(*zapLogger).Error\n\tsrc/github.com/operator-framework/operator-sdk/vendor/github.com/go-logr/zapr/zapr.go:128\ngithub.com/operator-framework/operator-sdk/pkg/ansible/controller.(*AnsibleOperatorReconciler).Reconcile\n\tsrc/github.com/operator-framework/operator-sdk/pkg/ansible/controller/reconcile.go:183\nsigs.k8s.io/controller-runtime/pkg/internal/controller.(*Controller).reconcileHandler\n\tsrc/github.com/operator-framework/operator-sdk/vendor/sigs.k8s.io/controller-runtime/pkg/internal/controller/controller.go:216\nsigs.k8s.io/controller-runtime/pkg/internal/controller.(*Controller).processNextWorkItem\n\tsrc/github.com/operator-framework/operator-sdk/vendor/sigs.k8s.io/controller-runtime/pkg/internal/controller/controller.go:192\nsigs.k8s.io/controller-runtime/pkg/internal/controller.(*Controller).worker\n\tsrc/github.com/operator-framework/operator-sdk/vendor/sigs.k8s.io/controller-runtime/pkg/internal/controller/controller.go:171\nk8s.io/apimachinery/pkg/util/wait.JitterUntil.func1\n\tsrc/github.com/operator-framework/operator-sdk/vendor/k8s.io/apimachinery/pkg/util/wait/wait.go:152\nk8s.io/apimachinery/pkg/util/wait.JitterUntil\n\tsrc/github.com/operator-framework/operator-sdk/vendor/k8s.io/apimachinery/pkg/util/wait/wait.go:153\nk8s.io/apimachinery/pkg/util/wait.Until\n\tsrc/github.com/operator-framework/operator-sdk/vendor/k8s.io/apimachinery/pkg/util/wait/wait.go:88"}
{"level":"error","ts":1576465835.2738986,"logger":"controller-runtime.controller","msg":"Reconciler error","controller":"templateservicebroker-controller","request":"openshift-template-service-broker/template-service-broker","error":"open /tmp/ansible-operator/runner/osb.openshift.io/v1/TemplateServiceBroker/openshift-template-service-broker/template-service-broker/artifacts/6129484611666145821/stdout: no such file or directory","stacktrace":"github.com/go-logr/zapr.(*zapLogger).Error\n\tsrc/github.com/operator-framework/operator-sdk/vendor/github.com/go-logr/zapr/zapr.go:128\nsigs.k8s.io/controller-runtime/pkg/internal/controller.(*Controller).reconcileHandler\n\tsrc/github.com/operator-framework/operator-sdk/vendor/sigs.k8s.io/controller-runtime/pkg/internal/controller/controller.go:218\nsigs.k8s.io/controller-runtime/pkg/internal/controller.(*Controller).processNextWorkItem\n\tsrc/github.com/operator-framework/operator-sdk/vendor/sigs.k8s.io/controller-runtime/pkg/internal/controller/controller.go:192\nsigs.k8s.io/controller-runtime/pkg/internal/controller.(*Controller).worker\n\tsrc/github.com/operator-framework/operator-sdk/vendor/sigs.k8s.io/controller-runtime/pkg/internal/controller/controller.go:171\nk8s.io/apimachinery/pkg/util/wait.JitterUntil.func1\n\tsrc/github.com/operator-framework/operator-sdk/vendor/k8s.io/apimachinery/pkg/util/wait/wait.go:152\nk8s.io/apimachinery/pkg/util/wait.JitterUntil\n\tsrc/github.com/operator-framework/operator-sdk/vendor/k8s.io/apimachinery/pkg/util/wait/wait.go:153\nk8s.io/apimachinery/pkg/util/wait.Until\n\tsrc/github.com/operator-framework/operator-sdk/vendor/k8s.io/apimachinery/pkg/util/wait/wait.go:88"}
{"level":"error","ts":1576465836.6054187,"logger":"runner","msg":"Traceback (most recent call last):\n  File \"/usr/lib/python2.7/site-packages/ansible_runner/__main__.py\", line 329, in main\n    res = run(**run_options)\n  File \"/usr/lib/python2.7/site-packages/ansible_runner/interface.py\", line 162, in run\n    r.run()\n  File \"/usr/lib/python2.7/site-packages/ansible_runner/runner.py\", line 93, in run\n    self.status_callback('starting')\n  File \"/usr/lib/python2.7/site-packages/ansible_runner/runner.py\", line 84, in status_callback\n    ansible_runner.plugins[plugin].status_handler(self.config, status_data)\n  File \"/usr/lib/python2.7/site-packages/ansible_runner_http/events.py\", line 38, in status_handler\n    urlpath=plugin_config['runner_path'])\n  File \"/usr/lib/python2.7/site-packages/ansible_runner_http/events.py\", line 18, in send_request\n    return session.post(url_actual, headers=headers, json=(data))\n  File \"/usr/lib/python2.7/site-packages/requests/sessions.py\", line 529, in post\n    return self.request('POST', url, data=data, json=json, **kwargs)\n  File \"/usr/lib/python2.7/site-packages/requests/sessions.py\", line 486, in request\n    resp = self.send(prep, **send_kwargs)\n  File \"/usr/lib/python2.7/site-packages/requests/sessions.py\", line 598, in send\n    r = adapter.send(request, **kwargs)\n  File \"/usr/lib/python2.7/site-packages/requests/adapters.py\", line 370, in send\n    timeout=timeout\n  File \"/usr/lib/python2.7/site-packages/urllib3/connectionpool.py\", line 587, in urlopen\n    timeout_obj = self._get_timeout(timeout)\n  File \"/usr/lib/python2.7/site-packages/urllib3/connectionpool.py\", line 302, in _get_timeout\n    return Timeout.from_float(timeout)\n  File \"/usr/lib/python2.7/site-packages/urllib3/util/timeout.py\", line 154, in from_float\n    return Timeout(read=timeout, connect=timeout)\n  File \"/usr/lib/python2.7/site-packages/urllib3/util/timeout.py\", line 94, in __init__\n    self._connect = self._validate_timeout(connect, 'connect')\n  File \"/usr/lib/python2.7/site-packages/urllib3/util/timeout.py\", line 127, in _validate_timeout\n    \"int, float or None.\" % (name, value))\nValueError: Timeout value connect was Timeout(connect=None, read=None, total=None), but it must be an int, float or None.\n","job":"4037200794235010051","name":"template-service-broker","namespace":"openshift-template-service-broker","error":"exit status 1","stacktrace":"github.com/go-logr/zapr.(*zapLogger).Error\n\tsrc/github.com/operator-framework/operator-sdk/vendor/github.com/go-logr/zapr/zapr.go:128\ngithub.com/operator-framework/operator-sdk/pkg/ansible/runner.(*runner).Run.func1\n\tsrc/github.com/operator-framework/operator-sdk/pkg/ansible/runner/runner.go:199"}

Comment 1 Jesus M. Rodriguez 2019-12-16 03:57:19 UTC
Created attachment 1645422 [details]
Updated formatting to make log easier to read.

Comment 2 Cuiping HUO 2019-12-16 05:45:01 UTC
tested on Azure, GCP, AWS(with FIPs on), all installation of asb/tsb are failed with the same error.

Comment 3 Cuiping HUO 2019-12-17 02:20:01 UTC
asb manifest last working successfully tag was 4.3.0-201912121917, tsb was 4.3.0-201912122317
this bug found for asb manifest with tag 4.3.0-201912132030, tsb with 4.3.0-201912130552

Comment 5 Jian Zhang 2019-12-17 08:48:21 UTC
> {"level":"info","ts":1576464179.2228234,"logger":"controller-runtime.controller","msg":"Starting workers","controller":"automationbroker-controller","worker count":1}
{"level":"error","ts":1576464179.6275408,"logger":"runner","msg":"Traceback (most recent call last):\n  File \"/usr/lib/python2.7/site-packages/ansible_runner/__main__.py\", line 329, in main\n    res = run(**run_options)\n  File \"/usr/lib/python2.7/site-packages/ansible_runner/interface.py\", line 162, in run\n    r.run()\n  File \"/usr/lib/python2.7/site-packages/ansible_runner/runner.py\", line 93, in run\n    self.status_callback('starting')\n  File \"/usr/lib/python2.7/site-packages/ansible_runner/runner.py\", line 84, in status_callback\n    ansible_runner.plugins[plugin].status_handler(self.config, status_data)\n  File \"/usr/lib/python2.7/site-packages/ansible_runner_http/events.py\", line 38, in status_handler\n    


Seems like something wrong in the base image: quay.io/operator-framework/ansible-operator:master
https://github.com/openshift/ansible-service-broker/blob/master/operator/build/Dockerfile#L1

mac:~ jianzhang$ oc image info  quay.io/operator-framework/ansible-operator:master
Name:        quay.io/operator-framework/ansible-operator:master
Digest:      sha256:7c53a71102a1fc049d60f1c0b0d7260d295dda6e2ce4979f1b4ede2223016e55
Media Type:  application/vnd.docker.distribution.manifest.v2+json
Created:     1d ago
Image Size:  140.8MB in 10 layers
Layers:      73.2MB  sha256:340ff6d7f58c908c438ce89bb845caee3649de828a81569317ed0fe169a97de2
             1.535kB sha256:0e8ea260d0262eac3725175d3d499ead6fd77cb1fa8272b3e665e8f64044fb89
             281B    sha256:d843bf874ec418f79f5167cd7be5d3d578a09b37eb6edbde7ad6e08c955fd542
             38.53MB sha256:b2403247c336e21683fd8d88678dc110a9934c19e65364978d5613855f0f39ca
             29MB    sha256:069fcf52f056ca4be70ec03419b6b1daa742d54584ed0c7cbdd841808b3c5b0e
             856B    sha256:1180d38e8daeabb97d1df7fbb78233ac268a1e0b5c4572f734ea21cb2f0bfc11
             11.57kB sha256:0ba3ecbf95f424f36b8917f63f52d679477b4436ba0145c480e1af42579beaad
             11.1kB  sha256:9bef0fa6f78cd5d93af35d0b4c0f286924eef0a2ea107cec6e99fd47abfbb76b
             9.464kB sha256:fb8dcb3732a4d3131bf0498c3d6cb8571b0072abf6b9740027127b5d013db103
             9.464kB sha256:2bc41d47dd50aa3e74ecddd520d0cb3af8087a0af55ef8bb0b02b337d3440c37
OS:          linux
Arch:        amd64
Entrypoint:  /tini -- /usr/local/bin/entrypoint
User:        1001
Environment: PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin
             container=oci
             OPERATOR=/usr/local/bin/ansible-operator
             USER_UID=1001
             USER_NAME=ansible-operator
             HOME=/opt/ansible
Labels:      architecture=x86_64
             authoritative-source-url=registry.access.redhat.com
             build-date=2019-10-29T16:44:53.794580
             com.redhat.build-host=cpt-1002.osbs.prod.upshift.rdu2.redhat.com
             com.redhat.component=ubi8-container
             com.redhat.license_terms=https://www.redhat.com/en/about/red-hat-end-user-license-agreements#UBI
             description=The Universal Base Image is designed and engineered to be the base layer for all of your containerized applications, middleware and utilities. This base image is freely redistributable, but Red Hat only supports Red Hat technologies through subscriptions for Red Hat products. This image is maintained by Red Hat and updated regularly.
             distribution-scope=public
             io.k8s.description=The Universal Base Image is designed and engineered to be the base layer for all of your containerized applications, middleware and utilities. This base image is freely redistributable, but Red Hat only supports Red Hat technologies through subscriptions for Red Hat products. This image is maintained by Red Hat and updated regularly.
             io.k8s.display-name=Red Hat Universal Base Image 8
             io.openshift.expose-services=
             io.openshift.tags=base rhel8
             maintainer=Red Hat, Inc.
             name=ubi8
             release=277
             summary=Provides the latest release of Red Hat Universal Base Image 8.
             url=https://access.redhat.com/containers/#/registry.access.redhat.com/ubi8/images/8.1-277
             vcs-ref=c42933bcdbf9f1c232e981a5e40de257c3534c8e
             vcs-type=git
             vendor=Red Hat, Inc.
             version=8.1

Comment 6 Jason Montleon 2019-12-17 17:40:58 UTC
I'm pretty sure is a bug with ose-ansible-operator.

I saw this last night after rebuilding our operator, and after pinning the build to an older tag it worked again:
http://pkgs.devel.redhat.com/cgit/containers/openshift-migration-operator/commit/?h=rhcam-1.0-rhel-7&id=2c0fc4e0631cc8b0fc5bd4188c466324898ce943

Comment 7 Jesus M. Rodriguez 2019-12-17 18:19:00 UTC
It seems that the updated python-requests rpm seems to cause the problem. The broken image has python-requests-2.6.0-8 which was built 2019-10-24. When we go back to using python-requests-2.6.0-7 things work.

http://pkgs.devel.redhat.com/cgit/rpms/python-requests/commit/?h=rhel-7.7&id=c2b7d2c6fae9ea48268a2931980b7274c1d8e538

Comment 8 Jesus M. Rodriguez 2019-12-17 20:00:03 UTC
ART is pinning an older version of python-requests (2.6.0-7) to fix the issue. 
http://pkgs.devel.redhat.com/cgit/containers/openshift-enterprise-apb-base/commit/Dockerfile?h=rhaos-4.3-rhel-7&id=414ec3ed5a400950d72bd56c56c5cf31c4623680

Comment 9 Jian Zhang 2019-12-18 07:42:05 UTC
Hi Jason and Jesus

Do you know the source code repo for this ose-ansible-operator? Thanks! I didn't find it in GitHub.

Comment 10 Jason Montleon 2019-12-18 13:26:18 UTC
I believe it's from https://github.com/operator-framework/operator-sdk

Comment 11 Jesus M. Rodriguez 2019-12-18 16:32:27 UTC
As Jason mentioned in comment #10, The ansible-operator source is a part of the operator-sdk repo specifically: https://github.com/operator-framework/operator-sdk/tree/master/pkg/ansible

The ansible-service-broker-operator that deploys the ansible service broker lives in the broker's repo: https://github.com/openshift/ansible-service-broker/tree/master/operator it is an implementation of the Ansible Operator.

The template-service-broker-operator that deploys the template service broker lives in its own repo: https://github.com/openshift/template-service-broker-operator/ it too is an implementation of the Ansible Operator.

Comment 12 Jesus M. Rodriguez 2019-12-18 16:34:37 UTC
Status: Downloaded newer image for registry-proxy.engineering.redhat.com/rh-osbs/openshift-apb-base:v4.3.0-201912171450
bash-4.2# rpm -q python-requests
python-requests-2.6.0-7.el7_7.noarch
bash-4.2# rpm -q python2-openshift
python2-openshift-0.8.11-1.el7.noarch
bash-4.2# 


Please rerun your tests with v4.3.0-201912171450 or newer.

Comment 13 Cuiping HUO 2019-12-19 02:37:18 UTC
Verified.
Both asb and tsb can be installed succefully. asb and tsb operators' pods do not have errors. 

asb manifest with tag 4.3.0-201912171717,tsb with 4.3.0-201912171717.

$ oc logs -f openshift-ansible-service-broker-operator-7f947f758f-lrxkk
{"level":"info","ts":1576721061.1930418,"logger":"cmd","msg":"Go Version: go1.12.12"}
{"level":"info","ts":1576721061.193085,"logger":"cmd","msg":"Go OS/Arch: linux/amd64"}
{"level":"info","ts":1576721061.1931002,"logger":"cmd","msg":"Version of operator-sdk: v0.12.0+git"}
{"level":"info","ts":1576721061.1931293,"logger":"cmd","msg":"Watching namespace.","Namespace":"openshift-ansible-service-broker"}
{"level":"info","ts":1576721063.404445,"logger":"controller-runtime.metrics","msg":"metrics server is starting to listen","addr":"0.0.0.0:8383"}
{"level":"info","ts":1576721063.4051988,"logger":"watches","msg":"Failed to parse %v from environment. Using default %v","WORKER_AUTOMATIONBROKER_OSB_OPENSHIFT_IO":1}
{"level":"info","ts":1576721063.4052334,"logger":"watches","msg":"Failed to parse %v from environment. Using default %v","ANSIBLE_VERBOSITY_AUTOMATIONBROKER_OSB_OPENSHIFT_IO":2}
{"level":"info","ts":1576721063.4052768,"logger":"ansible-controller","msg":"Watching resource","Options.Group":"osb.openshift.io","Options.Version":"v1","Options.Kind":"AutomationBroker"}
{"level":"info","ts":1576721063.4055076,"logger":"controller-runtime.controller","msg":"Starting EventSource","controller":"automationbroker-controller","source":"kind source: osb.openshift.io/v1, Kind=AutomationBroker"}
{"level":"info","ts":1576721063.4057496,"logger":"leader","msg":"Trying to become the leader."}
{"level":"info","ts":1576721065.6317785,"logger":"leader","msg":"Found existing lock","LockOwner":"openshift-ansible-service-broker-operator-6b847d4455-wstvz"}
{"level":"info","ts":1576721065.6477547,"logger":"leader","msg":"Not the leader. Waiting."}
{"level":"info","ts":1576721066.788262,"logger":"leader","msg":"Not the leader. Waiting."}
{"level":"info","ts":1576721069.1776547,"logger":"leader","msg":"Not the leader. Waiting."}
{"level":"info","ts":1576721073.7194026,"logger":"leader","msg":"Became the leader."}
{"level":"info","ts":1576721078.1961014,"logger":"metrics","msg":"Metrics Service object updated","Service.Name":"openshift-ansible-service-broker-operator-metrics","Service.Namespace":"openshift-ansible-service-broker"}
{"level":"info","ts":1576721078.1973548,"logger":"proxy","msg":"Starting to serve","Address":"127.0.0.1:8888"}
{"level":"info","ts":1576721078.1975448,"logger":"controller-runtime.manager","msg":"starting metrics server","path":"/metrics"}
{"level":"info","ts":1576721078.297675,"logger":"controller-runtime.controller","msg":"Starting Controller","controller":"automationbroker-controller"}
{"level":"info","ts":1576721078.3980548,"logger":"controller-runtime.controller","msg":"Starting workers","controller":"automationbroker-controller","worker count":1}
{"level":"info","ts":1576721086.0230038,"logger":"logging_event_handler","msg":"[playbook task]","name":"ansible-service-broker","namespace":"openshift-ansible-service-broker","gvk":"osb.openshift.io/v1, Kind=AutomationBroker","event_type":"playbook_on_task_start","job":"6334824724549167320","EventData.Name":"ansible-service-broker : Environment Validation"}
{"level":"info","ts":1576721086.0608552,"logger":"logging_event_handler","msg":"[playbook task]","name":"ansible-service-broker","namespace":"openshift-ansible-service-broker","gvk":"osb.openshift.io/v1, Kind=AutomationBroker","event_type":"playbook_on_task_start","job":"6334824724549167320","EventData.Name":"ansible-service-broker : Verify service catalog is installed"}
{"level":"info","ts":1576721086.0993896,"logger":"logging_event_handler","msg":"[playbook task]","name":"ansible-service-broker","namespace":"openshift-ansible-service-broker","gvk":"osb.openshift.io/v1, Kind=AutomationBroker","event_type":"playbook_on_task_start","job":"6334824724549167320","EventData.Name":"ansible-service-broker : Set broker admin cluster rolebinding state=present"}
{"level":"info","ts":1576721087.367299,"logger":"proxy","msg":"Injecting owner reference"}
{"level":"info","ts":1576721087.57146,"logger":"logging_event_handler","msg":"[playbook task]","name":"ansible-service-broker","namespace":"openshift-ansible-service-broker","gvk":"osb.openshift.io/v1, Kind=AutomationBroker","event_type":"playbook_on_task_start","job":"6334824724549167320","EventData.Name":"ansible-service-broker : fail"}
{"level":"info","ts":1576721087.6133673,"logger":"logging_event_handler","msg":"[playbook task]","name":"ansible-service-broker","namespace":"openshift-ansible-service-broker","gvk":"osb.openshift.io/v1, Kind=AutomationBroker","event_type":"playbook_on_task_start","job":"6334824724549167320","EventData.Name":"ansible-service-broker : fail"}
{"level":"info","ts":1576721087.6478806,"logger":"logging_event_handler","msg":"[playbook task]","name":"ansible-service-broker","namespace":"openshift-ansible-service-broker","gvk":"osb.openshift.io/v1, Kind=AutomationBroker","event_type":"playbook_on_task_start","job":"6334824724549167320","EventData.Name":"ansible-service-broker : Create directory for cert"}
{"level":"info","ts":1576721087.681968,"logger":"logging_event_handler","msg":"[playbook task]","name":"ansible-service-broker","namespace":"openshift-ansible-service-broker","gvk":"osb.openshift.io/v1, Kind=AutomationBroker","event_type":"playbook_on_task_start","job":"6334824724549167320","EventData.Name":"ansible-service-broker : Create cert"}
{"level":"info","ts":1576721087.7162833,"logger":"logging_event_handler","msg":"[playbook task]","name":"ansible-service-broker","namespace":"openshift-ansible-service-broker","gvk":"osb.openshift.io/v1, Kind=AutomationBroker","event_type":"playbook_on_task_start","job":"6334824724549167320","EventData.Name":"ansible-service-broker : Set tls secret state=present"}
{"level":"info","ts":1576721087.821631,"logger":"logging_event_handler","msg":"[playbook task]","name":"ansible-service-broker","namespace":"openshift-ansible-service-broker","gvk":"osb.openshift.io/v1, Kind=AutomationBroker","event_type":"playbook_on_task_start","job":"6334824724549167320","EventData.Name":"ansible-service-broker : Remove tls secret"}
{"level":"info","ts":1576721087.8653872,"logger":"logging_event_handler","msg":"[playbook task]","name":"ansible-service-broker","namespace":"openshift-ansible-service-broker","gvk":"osb.openshift.io/v1, Kind=AutomationBroker","event_type":"playbook_on_task_start","job":"6334824724549167320","EventData.Name":"ansible-service-broker : Set dashboard redirector state=present"}
{"level":"info","ts":1576721089.1150677,"logger":"proxy","msg":"Cache miss: route.openshift.io/v1, Kind=Route, openshift-ansible-service-broker/dr-1337"}
{"level":"info","ts":1576721089.1254578,"logger":"proxy","msg":"Injecting owner reference"}
{"level":"info","ts":1576721089.1257954,"logger":"proxy","msg":"Watching child resource","kind":"route.openshift.io/v1, Kind=Route","enqueue_kind":"osb.openshift.io/v1, Kind=AutomationBroker"}
{"level":"info","ts":1576721089.1258247,"logger":"controller-runtime.controller","msg":"Starting EventSource","controller":"automationbroker-controller","source":"kind source: route.openshift.io/v1, Kind=Route"}
{"level":"info","ts":1576721089.788264,"logger":"logging_event_handler","msg":"[playbook task]","name":"ansible-service-broker","namespace":"openshift-ansible-service-broker","gvk":"osb.openshift.io/v1, Kind=AutomationBroker","event_type":"playbook_on_task_start","job":"6334824724549167320","EventData.Name":"ansible-service-broker : Check if trusted CA configmap exists"}
{"level":"info","ts":1576721090.9779632,"logger":"proxy","msg":"Cache miss: /v1, Kind=ConfigMap, openshift-ansible-service-broker/asb-trusted-ca"}
{"level":"info","ts":1576721091.2015083,"logger":"logging_event_handler","msg":"[playbook task]","name":"ansible-service-broker","namespace":"openshift-ansible-service-broker","gvk":"osb.openshift.io/v1, Kind=AutomationBroker","event_type":"playbook_on_task_start","job":"6334824724549167320","EventData.Name":"ansible-service-broker : Create the trusted CA configmap"}
{"level":"info","ts":1576721092.1839094,"logger":"proxy","msg":"Cache miss: /v1, Kind=ConfigMap, openshift-ansible-service-broker/asb-trusted-ca"}
{"level":"info","ts":1576721092.1890283,"logger":"proxy","msg":"Injecting owner reference"}
{"level":"info","ts":1576721092.1893244,"logger":"proxy","msg":"Watching child resource","kind":"/v1, Kind=ConfigMap","enqueue_kind":"osb.openshift.io/v1, Kind=AutomationBroker"}
{"level":"info","ts":1576721092.1893575,"logger":"controller-runtime.controller","msg":"Starting EventSource","controller":"automationbroker-controller","source":"kind source: /v1, Kind=ConfigMap"}
{"level":"info","ts":1576721092.3350232,"logger":"logging_event_handler","msg":"[playbook task]","name":"ansible-service-broker","namespace":"openshift-ansible-service-broker","gvk":"osb.openshift.io/v1, Kind=AutomationBroker","event_type":"playbook_on_task_start","job":"6334824724549167320","EventData.Name":"ansible-service-broker : Get trusted CA configmap"}
{"level":"info","ts":1576721093.6100523,"logger":"logging_event_handler","msg":"[playbook task]","name":"ansible-service-broker","namespace":"openshift-ansible-service-broker","gvk":"osb.openshift.io/v1, Kind=AutomationBroker","event_type":"playbook_on_task_start","job":"6334824724549167320","EventData.Name":"ansible-service-broker : Create the duplicate bundle CM to be mounted"}
{"level":"info","ts":1576721094.5495918,"logger":"proxy","msg":"Cache miss: /v1, Kind=ConfigMap, openshift-ansible-service-broker/asb-trusted-ca-gd6g4k686h"}
{"level":"info","ts":1576721094.5571172,"logger":"proxy","msg":"Injecting owner reference"}
{"level":"info","ts":1576721094.8360026,"logger":"logging_event_handler","msg":"[playbook task]","name":"ansible-service-broker","namespace":"openshift-ansible-service-broker","gvk":"osb.openshift.io/v1, Kind=AutomationBroker","event_type":"playbook_on_task_start","job":"6334824724549167320","EventData.Name":"ansible-service-broker : Get the broker deployment"}
{"level":"info","ts":1576721095.7372296,"logger":"proxy","msg":"Cache miss: apps.openshift.io/v1, Kind=DeploymentConfig, openshift-ansible-service-broker/asb"}
{"level":"info","ts":1576721095.9163198,"logger":"logging_event_handler","msg":"[playbook task]","name":"ansible-service-broker","namespace":"openshift-ansible-service-broker","gvk":"osb.openshift.io/v1, Kind=AutomationBroker","event_type":"playbook_on_task_start","job":"6334824724549167320","EventData.Name":"ansible-service-broker : ConfigMap for Broker"}

$ oc logs -f openshift-template-service-broker-operator-77bc5595d6-ptczz
{"level":"info","ts":1576722179.1119323,"logger":"cmd","msg":"Go Version: go1.12.12"}
{"level":"info","ts":1576722179.1119728,"logger":"cmd","msg":"Go OS/Arch: linux/amd64"}
{"level":"info","ts":1576722179.1119819,"logger":"cmd","msg":"Version of operator-sdk: v0.12.0+git"}
{"level":"info","ts":1576722179.1120026,"logger":"cmd","msg":"Watching namespace.","Namespace":"openshift-template-service-broker"}
{"level":"info","ts":1576722181.325611,"logger":"controller-runtime.metrics","msg":"metrics server is starting to listen","addr":"0.0.0.0:8383"}
{"level":"info","ts":1576722181.3260546,"logger":"watches","msg":"Failed to parse %v from environment. Using default %v","WORKER_TEMPLATESERVICEBROKER_OSB_OPENSHIFT_IO":1}
{"level":"info","ts":1576722181.3260815,"logger":"watches","msg":"Failed to parse %v from environment. Using default %v","ANSIBLE_VERBOSITY_TEMPLATESERVICEBROKER_OSB_OPENSHIFT_IO":2}
{"level":"info","ts":1576722181.3261232,"logger":"ansible-controller","msg":"Watching resource","Options.Group":"osb.openshift.io","Options.Version":"v1","Options.Kind":"TemplateServiceBroker"}
{"level":"info","ts":1576722181.3263872,"logger":"controller-runtime.controller","msg":"Starting EventSource","controller":"templateservicebroker-controller","source":"kind source: osb.openshift.io/v1, Kind=TemplateServiceBroker"}
{"level":"info","ts":1576722181.3265355,"logger":"leader","msg":"Trying to become the leader."}
{"level":"info","ts":1576722183.5520158,"logger":"leader","msg":"No pre-existing lock was found."}
{"level":"info","ts":1576722183.5615258,"logger":"leader","msg":"Became the leader."}
{"level":"info","ts":1576722188.0618417,"logger":"metrics","msg":"Metrics Service object updated","Service.Name":"openshift-template-service-broker-operator-metrics","Service.Namespace":"openshift-template-service-broker"}
{"level":"info","ts":1576722188.0647452,"logger":"proxy","msg":"Starting to serve","Address":"127.0.0.1:8888"}
{"level":"info","ts":1576722188.0648947,"logger":"controller-runtime.manager","msg":"starting metrics server","path":"/metrics"}
{"level":"info","ts":1576722188.1649354,"logger":"controller-runtime.controller","msg":"Starting Controller","controller":"templateservicebroker-controller"}
{"level":"info","ts":1576722188.2651656,"logger":"controller-runtime.controller","msg":"Starting workers","controller":"templateservicebroker-controller","worker count":1}
{"level":"info","ts":1576722192.9303474,"logger":"logging_event_handler","msg":"[playbook task]","name":"template-service-broker","namespace":"openshift-template-service-broker","gvk":"osb.openshift.io/v1, Kind=TemplateServiceBroker","event_type":"playbook_on_task_start","job":"6129484611666145821","EventData.Name":"Validation"}
{"level":"info","ts":1576722192.974372,"logger":"logging_event_handler","msg":"[playbook task]","name":"template-service-broker","namespace":"openshift-template-service-broker","gvk":"osb.openshift.io/v1, Kind=TemplateServiceBroker","event_type":"playbook_on_task_start","job":"6129484611666145821","EventData.Name":"Run template-service-broker role"}
{"level":"info","ts":1576722193.5329936,"logger":"logging_event_handler","msg":"[playbook task]","name":"template-service-broker","namespace":"openshift-template-service-broker","gvk":"osb.openshift.io/v1, Kind=TemplateServiceBroker","event_type":"playbook_on_task_start","job":"6129484611666145821","EventData.Name":"template-service-broker : Set tsb objects state=present"}
{"level":"info","ts":1576722197.08075,"logger":"proxy","msg":"Watching child resource","kind":"/v1, Kind=ServiceAccount","enqueue_kind":"osb.openshift.io/v1, Kind=TemplateServiceBroker"}
{"level":"info","ts":1576722197.080812,"logger":"controller-runtime.controller","msg":"Starting EventSource","controller":"templateservicebroker-controller","source":"kind source: /v1, Kind=ServiceAccount"}
{"level":"info","ts":1576722200.3707473,"logger":"proxy","msg":"Watching child resource","kind":"apps.openshift.io/v1, Kind=DeploymentConfig","enqueue_kind":"osb.openshift.io/v1, Kind=TemplateServiceBroker"}
{"level":"info","ts":1576722200.3708098,"logger":"controller-runtime.controller","msg":"Starting EventSource","controller":"templateservicebroker-controller","source":"kind source: apps.openshift.io/v1, Kind=DeploymentConfig"}
{"level":"info","ts":1576722201.719896,"logger":"proxy","msg":"Watching child resource","kind":"/v1, Kind=ConfigMap","enqueue_kind":"osb.openshift.io/v1, Kind=TemplateServiceBroker"}
{"level":"info","ts":1576722201.7199576,"logger":"controller-runtime.controller","msg":"Starting EventSource","controller":"templateservicebroker-controller","source":"kind source: /v1, Kind=ConfigMap"}
{"level":"info","ts":1576722203.227627,"logger":"proxy","msg":"Watching child resource","kind":"/v1, Kind=Service","enqueue_kind":"osb.openshift.io/v1, Kind=TemplateServiceBroker"}
{"level":"info","ts":1576722203.2276764,"logger":"controller-runtime.controller","msg":"Starting EventSource","controller":"templateservicebroker-controller","source":"kind source: /v1, Kind=Service"}
{"level":"info","ts":1576722209.1284733,"logger":"proxy","msg":"Watching child resource","kind":"/v1, Kind=Secret","enqueue_kind":"osb.openshift.io/v1, Kind=TemplateServiceBroker"}
{"level":"info","ts":1576722209.1285336,"logger":"controller-runtime.controller","msg":"Starting EventSource","controller":"templateservicebroker-controller","source":"kind source: /v1, Kind=Secret"}
{"level":"info","ts":1576722210.317918,"logger":"proxy","msg":"Watching child resource","kind":"monitoring.coreos.com/v1, Kind=PrometheusRule","enqueue_kind":"osb.openshift.io/v1, Kind=TemplateServiceBroker"}
{"level":"info","ts":1576722210.3179746,"logger":"controller-runtime.controller","msg":"Starting EventSource","controller":"templateservicebroker-controller","source":"kind source: monitoring.coreos.com/v1, Kind=PrometheusRule"}
{"level":"info","ts":1576722210.4732764,"logger":"logging_event_handler","msg":"[playbook task]","name":"template-service-broker","namespace":"openshift-template-service-broker","gvk":"osb.openshift.io/v1, Kind=TemplateServiceBroker","event_type":"playbook_on_task_start","job":"6129484611666145821","EventData.Name":"template-service-broker : Set tsb config map state=present"}
{"level":"info","ts":1576722212.004077,"logger":"logging_event_handler","msg":"[playbook task]","name":"template-service-broker","namespace":"openshift-template-service-broker","gvk":"osb.openshift.io/v1, Kind=TemplateServiceBroker","event_type":"playbook_on_task_start","job":"6129484611666145821","EventData.Name":"template-service-broker : Set clusterservicebroker state=present"}
{"level":"info","ts":1576722214.089032,"logger":"runner","msg":"Ansible-runner exited successfully","job":"6129484611666145821","name":"template-service-broker","namespace":"openshift-template-service-broker"}

$ oc get csv -n openshift-ansible-service-broker
NAME                                               DISPLAY                                     VERSION              REPLACES                                           PHASE
openshiftansibleservicebroker.4.3.0-201912171717   OpenShift Ansible Service Broker Operator   4.3.0-201912171717   openshiftansibleservicebroker.4.3.0-201912171450   Succeeded

$ oc get po -n openshift-ansible-service-broker
NAME                                                         READY   STATUS    RESTARTS   AGE
asb-2-deploy                                                 1/1     Running   0          2m41s
asb-2-mjhlh                                                  1/2     Running   0          16s
openshift-ansible-service-broker-operator-7fb5fd646f-zg5lm   1/1     Running   0          5m39s

$ oc get csv -n openshift-template-service-broker
NAME                                                        DISPLAY                                      VERSION              REPLACES                                                    PHASE
openshifttemplateservicebrokeroperator.4.3.0-201912171717   OpenShift Template Service Broker Operator   4.3.0-201912171717   openshifttemplateservicebrokeroperator.4.3.0-201912171450   Succeeded
$ oc get po -n openshift-template-service-broker 
NAME                                                          READY   STATUS    RESTARTS   AGE
apiserver-2-deploy                                            1/1     Running   0          4m12s
apiserver-2-rl54b                                             0/1     Running   0          14s
openshift-template-service-broker-operator-77bc5595d6-ptczz   1/1     Running   0          4m49s

Comment 15 errata-xmlrpc 2020-01-23 11:19:25 UTC
Since the problem described in this bug report should be
resolved in a recent advisory, it has been closed with a
resolution of ERRATA.

For information on the advisory, and where to find the updated
files, follow the link below.

If the solution does not work for you, open a new bug report.

https://access.redhat.com/errata/RHBA-2020:0062


Note You need to log in before you can comment on or make changes to this bug.