Attachment 1480094 Details for Bug 1623331 – log showing working psql-apb: provision & bind

log showing working psql-apb: provision & bind

bz1623331.log (text/plain), 103.24 KB, created by Jesus M. Rodriguez on 2018-08-31 13:18:12 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Jesus M. Rodriguez

Created: 2018-08-31 13:18:12 UTC

Size: 103.24 KB

patch

obsolete

>Using config file mounted to /etc/automation-broker/config.yaml
>============================================================
>==           Creating Ansible Service Broker...           ==
>============================================================
>time="2018-08-30T20:12:32Z" level=info msg="Ansible Service Broker Version: 1.3.14"
>time="2018-08-30T20:12:32Z" level=info msg="Initializing clients..."
>time="2018-08-30T20:12:32Z" level=debug msg="Connecting to Cluster"
>time="2018-08-30T20:12:32Z" level=info msg="OpenShift version: v3.10.0+7fb1705-27"
>time="2018-08-30T20:12:32Z" level=debug msg="plugin for the network - "
>time="2018-08-30T20:12:32Z" level=debug msg="unable to retrieve the network plugin, defaulting to not joining networks - clusternetworks.network.openshift.io \"default\" not found"
>time="2018-08-30T20:12:32Z" level=info msg="Kubernetes version: v1.10.0+b81c8f8"
>time="2018-08-30T20:12:32Z" level=debug msg="Connecting Dao"
>time="2018-08-30T20:12:32Z" level=debug msg="Connecting Registry"
>time="2018-08-30T20:12:32Z" level=debug msg="Unable to get user from config"
>time="2018-08-30T20:12:32Z" level=debug msg="Unable to get pass from config"
>time="2018-08-30T20:12:32Z" level=debug msg="Unable to get token from config"
>time="2018-08-30T20:12:32Z" level=debug msg="Unable to get images from config"
>time="2018-08-30T20:12:32Z" level=debug msg="Unable to get namespaces from config"
>time="2018-08-30T20:12:32Z" level=debug msg="Unable to get fail_on_error from config"
>time="2018-08-30T20:12:32Z" level=debug msg="Unable to get auth_type from config"
>time="2018-08-30T20:12:32Z" level=debug msg="Unable to get auth_name from config"
>time="2018-08-30T20:12:32Z" level=debug msg="Unable to get runner from config"
>time="2018-08-30T20:12:32Z" level=debug msg="Unable to get skip_verify_tls from config"
>time="2018-08-30T20:12:32Z" level=info msg="Empty AuthType. Assuming credentials are defined in the config... "
>time="2018-08-30T20:12:32Z" level=info msg="== REGISTRY CX == "
>time="2018-08-30T20:12:32Z" level=info msg="Name: dh"
>time="2018-08-30T20:12:32Z" level=info msg="Type: dockerhub"
>time="2018-08-30T20:12:32Z" level=info msg="Url: https://registry.hub.docker.com"
>time="2018-08-30T20:12:32Z" level=debug msg="Creating filter for registry: dh"
>time="2018-08-30T20:12:32Z" level=debug msg="whitelist: [.*-apb$]"
>time="2018-08-30T20:12:32Z" level=debug msg="blacklist: [.*automation-broker-apb$]"
>time="2018-08-30T20:12:32Z" level=debug msg="Unable to get url from config"
>time="2018-08-30T20:12:32Z" level=debug msg="Unable to get user from config"
>time="2018-08-30T20:12:32Z" level=debug msg="Unable to get pass from config"
>time="2018-08-30T20:12:32Z" level=debug msg="Unable to get token from config"
>time="2018-08-30T20:12:32Z" level=debug msg="Unable to get org from config"
>time="2018-08-30T20:12:32Z" level=debug msg="Unable to get tag from config"
>time="2018-08-30T20:12:32Z" level=debug msg="Unable to get images from config"
>time="2018-08-30T20:12:32Z" level=debug msg="Unable to get fail_on_error from config"
>time="2018-08-30T20:12:32Z" level=debug msg="Unable to get black_list from config"
>time="2018-08-30T20:12:32Z" level=debug msg="Unable to get auth_type from config"
>time="2018-08-30T20:12:32Z" level=debug msg="Unable to get auth_name from config"
>time="2018-08-30T20:12:32Z" level=debug msg="Unable to get runner from config"
>time="2018-08-30T20:12:32Z" level=debug msg="Unable to get skip_verify_tls from config"
>time="2018-08-30T20:12:32Z" level=info msg="Empty AuthType. Assuming credentials are defined in the config... "
>time="2018-08-30T20:12:32Z" level=info msg="== REGISTRY CX == "
>time="2018-08-30T20:12:32Z" level=info msg="Name: localregistry"
>time="2018-08-30T20:12:32Z" level=info msg="Type: local_openshift"
>time="2018-08-30T20:12:32Z" level=info msg="Url: "
>time="2018-08-30T20:12:32Z" level=debug msg="Creating filter for registry: localregistry"
>time="2018-08-30T20:12:32Z" level=debug msg="whitelist: [.*]"
>time="2018-08-30T20:12:32Z" level=debug msg="blacklist: []"
>time="2018-08-30T20:12:32Z" level=debug msg="Initializing WorkEngine"
>time="2018-08-30T20:12:32Z" level=debug msg="Unable to get secrets from config"
>time="2018-08-30T20:12:32Z" level=debug msg="Creating AnsibleBroker"
>============================================================
>==           Starting Ansible Service Broker...           ==
>============================================================
>time="2018-08-30T20:12:32Z" level=info msg="Initiating Recovery Process"
>time="2018-08-30T20:12:32Z" level=info msg="Recovery complete"
>time="2018-08-30T20:12:32Z" level=info msg="recover called"
>time="2018-08-30T20:12:32Z" level=info msg="Broker configured to bootstrap on startup"
>time="2018-08-30T20:12:32Z" level=info msg="Attempting bootstrap..."
>time="2018-08-30T20:12:32Z" level=info msg="AnsibleBroker::Bootstrap"
>time="2018-08-30T20:12:32Z" level=debug msg="Dao::BatchGetSpecs"
>time="2018-08-30T20:12:32Z" level=debug msg="Dao::BatchGetBundleInstances"
>time="2018-08-30T20:12:32Z" level=debug msg="markedSpecs: map[]\n"
>time="2018-08-30T20:12:32Z" level=info msg="0 specs deleted"
>time="2018-08-30T20:12:32Z" level=debug msg="Dao::BatchGetSpecs"
>time="2018-08-30T20:12:32Z" level=debug msg="converting []*Spec to SpecManifest successful"
>time="2018-08-30T20:12:32Z" level=debug msg="DockerHubAdapter::GetImages"
>time="2018-08-30T20:12:32Z" level=debug msg="BundleSpecLabel: com.redhat.apb.spec"
>time="2018-08-30T20:12:32Z" level=debug msg="Loading image list for org: [ mhrivnak ]"
>time="2018-08-30T20:12:33Z" level=debug msg="Trying to load mhrivnak/pulp-k8s-mongodb"
>time="2018-08-30T20:12:33Z" level=debug msg="Trying to load mhrivnak/bind-apb"
>time="2018-08-30T20:12:33Z" level=debug msg="Trying to load mhrivnak/postgresql-apb"
>time="2018-08-30T20:12:33Z" level=debug msg="Trying to load mhrivnak/mediawiki-apb"
>time="2018-08-30T20:12:33Z" level=debug msg="Trying to load mhrivnak/redis-helm-apb"
>time="2018-08-30T20:12:33Z" level=debug msg="Trying to load mhrivnak/svccatalog-apb"
>time="2018-08-30T20:12:33Z" level=debug msg="Trying to load mhrivnak/rocketchat-apb"
>time="2018-08-30T20:12:33Z" level=debug msg="Trying to load mhrivnak/pulp-k8s-celery"
>time="2018-08-30T20:12:33Z" level=debug msg="Trying to load mhrivnak/pulp-k8s-qpid"
>time="2018-08-30T20:12:33Z" level=debug msg="Trying to load mhrivnak/origin-ansible-service-broker"
>time="2018-08-30T20:12:33Z" level=debug msg="Trying to load mhrivnak/pulp-k8s-httpd"
>time="2018-08-30T20:12:33Z" level=debug msg="Trying to load mhrivnak/pulp-k8s-base"
>time="2018-08-30T20:12:33Z" level=debug msg="Trying to load mhrivnak/bind"
>time="2018-08-30T20:12:33Z" level=debug msg="Trying to load mhrivnak/pulp-k8s-manage"
>time="2018-08-30T20:12:33Z" level=debug msg="Trying to load mhrivnak/staticreg"
>time="2018-08-30T20:12:33Z" level=debug msg="Trying to load mhrivnak/pulp"
>time="2018-08-30T20:12:33Z" level=debug msg="Trying to load mhrivnak/pulp-automation"
>time="2018-08-30T20:12:33Z" level=debug msg="Trying to load mhrivnak/vault"
>time="2018-08-30T20:12:33Z" level=debug msg="Trying to load mhrivnak/helm-ansible-base"
>time="2018-08-30T20:12:33Z" level=debug msg="Trying to load mhrivnak/helm-bundle-base"
>time="2018-08-30T20:12:33Z" level=debug msg="Filter applied against registry: dh"
>time="2018-08-30T20:12:33Z" level=debug msg="Bundles passing white/blacklist filter:"
>time="2018-08-30T20:12:33Z" level=debug msg="-> mhrivnak/rocketchat-apb"
>time="2018-08-30T20:12:33Z" level=debug msg="-> mhrivnak/postgresql-apb"
>time="2018-08-30T20:12:33Z" level=debug msg="-> mhrivnak/redis-helm-apb"
>time="2018-08-30T20:12:33Z" level=debug msg="-> mhrivnak/bind-apb"
>time="2018-08-30T20:12:33Z" level=debug msg="-> mhrivnak/mediawiki-apb"
>time="2018-08-30T20:12:33Z" level=debug msg="-> mhrivnak/svccatalog-apb"
>time="2018-08-30T20:12:33Z" level=info msg="Bundles filtered by white/blacklist filter:\n\t-> mhrivnak/helm-bundle-base\n\t-> mhrivnak/pulp-k8s-mongodb\n\t-> mhrivnak/pulp-k8s-celery\n\t-> mhrivnak/pulp-k8s-qpid\n\t-> mhrivnak/origin-ansible-service-broker\n\t-> mhrivnak/pulp-k8s-httpd\n\t-> mhrivnak/pulp-k8s-base\n\t-> mhrivnak/bind\n\t-> mhrivnak/pulp-k8s-manage\n\t-> mhrivnak/staticreg\n\t-> mhrivnak/pulp\n\t-> mhrivnak/pulp-automation\n\t-> mhrivnak/vault\n\t-> mhrivnak/helm-ansible-base\n"
>time="2018-08-30T20:12:34Z" level=debug msg="Successfully converted Image docker.io/mhrivnak/rocketchat-apb:latest into Spec"
>time="2018-08-30T20:12:34Z" level=info msg="adapter::configToSpec -> Image docker.io/mhrivnak/rocketchat-apb:latest runtime is 2"
>time="2018-08-30T20:12:35Z" level=debug msg="Successfully converted Image docker.io/mhrivnak/postgresql-apb:latest into Spec"
>time="2018-08-30T20:12:35Z" level=info msg="adapter::configToSpec -> Image docker.io/mhrivnak/postgresql-apb:latest runtime is 2"
>time="2018-08-30T20:12:35Z" level=debug msg="Successfully converted Image docker.io/mhrivnak/redis-helm-apb:latest into Spec"
>time="2018-08-30T20:12:35Z" level=info msg="adapter::configToSpec -> Image docker.io/mhrivnak/redis-helm-apb:latest runtime is 2"
>time="2018-08-30T20:12:36Z" level=debug msg="Successfully converted Image docker.io/mhrivnak/bind-apb:latest into Spec"
>time="2018-08-30T20:12:36Z" level=info msg="adapter::configToSpec -> Image docker.io/mhrivnak/bind-apb:latest runtime is 2"
>time="2018-08-30T20:12:36Z" level=debug msg="Successfully converted Image docker.io/mhrivnak/mediawiki-apb:latest into Spec"
>time="2018-08-30T20:12:36Z" level=info msg="adapter::configToSpec -> Image docker.io/mhrivnak/mediawiki-apb:latest runtime is 2"
>time="2018-08-30T20:12:36Z" level=debug msg="Successfully converted Image docker.io/mhrivnak/svccatalog-apb:latest into Spec"
>time="2018-08-30T20:12:36Z" level=info msg="adapter::configToSpec -> Image docker.io/mhrivnak/svccatalog-apb:latest runtime is 2"
>time="2018-08-30T20:12:36Z" level=info msg="Validating specs..."
>time="2018-08-30T20:12:36Z" level=debug msg="Spec [rocketchat-apb] version (1.0) not semver compatible"
>time="2018-08-30T20:12:36Z" level=debug msg="Spec [postgresql-apb] version (1.0) not semver compatible"
>time="2018-08-30T20:12:36Z" level=debug msg="Spec [mediawiki-apb] version (1.0) not semver compatible"
>time="2018-08-30T20:12:36Z" level=debug msg="Spec [redis-apb] version (1.0) not semver compatible"
>time="2018-08-30T20:12:36Z" level=debug msg="Spec [catalog-apb] version (1.0) not semver compatible"
>time="2018-08-30T20:12:36Z" level=debug msg="Spec [bind-apb] version (1.0) not semver compatible"
>time="2018-08-30T20:12:36Z" level=info msg="All specs passed validation!"
>time="2018-08-30T20:12:36Z" level=debug msg="LocalOpenShiftAdapter::GetImageNames"
>time="2018-08-30T20:12:36Z" level=debug msg="BundleSpecLabel: com.redhat.apb.spec"
>time="2018-08-30T20:12:36Z" level=debug msg="Filter applied against registry: localregistry"
>time="2018-08-30T20:12:36Z" level=debug msg="Bundles passing white/blacklist filter:"
>time="2018-08-30T20:12:36Z" level=debug msg="-> openshift/mariadb"
>time="2018-08-30T20:12:36Z" level=debug msg="-> openshift/nodejs"
>time="2018-08-30T20:12:36Z" level=debug msg="-> openshift/perl"
>time="2018-08-30T20:12:36Z" level=debug msg="-> openshift/postgresql"
>time="2018-08-30T20:12:36Z" level=debug msg="-> openshift/php"
>time="2018-08-30T20:12:36Z" level=debug msg="-> openshift/python"
>time="2018-08-30T20:12:36Z" level=debug msg="-> openshift/redis"
>time="2018-08-30T20:12:36Z" level=debug msg="-> openshift/httpd"
>time="2018-08-30T20:12:36Z" level=debug msg="-> openshift/jenkins"
>time="2018-08-30T20:12:36Z" level=debug msg="-> openshift/mongodb"
>time="2018-08-30T20:12:36Z" level=debug msg="-> openshift/mysql"
>time="2018-08-30T20:12:36Z" level=debug msg="-> openshift/nginx"
>time="2018-08-30T20:12:36Z" level=debug msg="-> openshift/ruby"
>time="2018-08-30T20:12:36Z" level=debug msg="LocalOpenShiftAdapter::FetchSpecs"
>time="2018-08-30T20:12:36Z" level=debug msg="No tag specified in config, assuming `latest`"
>time="2018-08-30T20:12:36Z" level=debug msg="LocalOpenShiftAdapter::LoadSpec"
>time="2018-08-30T20:12:36Z" level=info msg="No runtime label found. Set runtime=1. Will use 'exec' to gather bind credentials"
>time="2018-08-30T20:12:36Z" level=debug msg="LocalOpenShiftAdapter::LoadSpec"
>time="2018-08-30T20:12:36Z" level=info msg="No runtime label found. Set runtime=1. Will use 'exec' to gather bind credentials"
>time="2018-08-30T20:12:36Z" level=debug msg="LocalOpenShiftAdapter::LoadSpec"
>time="2018-08-30T20:12:36Z" level=info msg="No runtime label found. Set runtime=1. Will use 'exec' to gather bind credentials"
>time="2018-08-30T20:12:36Z" level=debug msg="LocalOpenShiftAdapter::LoadSpec"
>time="2018-08-30T20:12:36Z" level=info msg="No runtime label found. Set runtime=1. Will use 'exec' to gather bind credentials"
>time="2018-08-30T20:12:36Z" level=debug msg="LocalOpenShiftAdapter::LoadSpec"
>time="2018-08-30T20:12:36Z" level=info msg="No runtime label found. Set runtime=1. Will use 'exec' to gather bind credentials"
>time="2018-08-30T20:12:36Z" level=debug msg="LocalOpenShiftAdapter::LoadSpec"
>time="2018-08-30T20:12:36Z" level=info msg="No runtime label found. Set runtime=1. Will use 'exec' to gather bind credentials"
>time="2018-08-30T20:12:36Z" level=debug msg="LocalOpenShiftAdapter::LoadSpec"
>time="2018-08-30T20:12:36Z" level=info msg="No runtime label found. Set runtime=1. Will use 'exec' to gather bind credentials"
>time="2018-08-30T20:12:36Z" level=debug msg="LocalOpenShiftAdapter::LoadSpec"
>time="2018-08-30T20:12:36Z" level=info msg="No runtime label found. Set runtime=1. Will use 'exec' to gather bind credentials"
>time="2018-08-30T20:12:37Z" level=debug msg="LocalOpenShiftAdapter::LoadSpec"
>time="2018-08-30T20:12:37Z" level=info msg="No runtime label found. Set runtime=1. Will use 'exec' to gather bind credentials"
>time="2018-08-30T20:12:37Z" level=debug msg="LocalOpenShiftAdapter::LoadSpec"
>time="2018-08-30T20:12:37Z" level=info msg="No runtime label found. Set runtime=1. Will use 'exec' to gather bind credentials"
>time="2018-08-30T20:12:37Z" level=debug msg="LocalOpenShiftAdapter::LoadSpec"
>time="2018-08-30T20:12:37Z" level=info msg="No runtime label found. Set runtime=1. Will use 'exec' to gather bind credentials"
>time="2018-08-30T20:12:37Z" level=debug msg="LocalOpenShiftAdapter::LoadSpec"
>time="2018-08-30T20:12:37Z" level=info msg="No runtime label found. Set runtime=1. Will use 'exec' to gather bind credentials"
>time="2018-08-30T20:12:37Z" level=debug msg="LocalOpenShiftAdapter::LoadSpec"
>time="2018-08-30T20:12:37Z" level=info msg="No runtime label found. Set runtime=1. Will use 'exec' to gather bind credentials"
>time="2018-08-30T20:12:37Z" level=info msg="Validating specs..."
>time="2018-08-30T20:12:37Z" level=warning msg="Spec [  ] failed validation for the following reason: [ Spec [] failed version validation ]. It will not be made available."
>time="2018-08-30T20:12:37Z" level=warning msg="Spec [  ] failed validation for the following reason: [ Spec [] failed version validation ]. It will not be made available."
>time="2018-08-30T20:12:37Z" level=warning msg="Spec [  ] failed validation for the following reason: [ Spec [] failed version validation ]. It will not be made available."
>time="2018-08-30T20:12:37Z" level=warning msg="Spec [  ] failed validation for the following reason: [ Spec [] failed version validation ]. It will not be made available."
>time="2018-08-30T20:12:37Z" level=warning msg="Spec [  ] failed validation for the following reason: [ Spec [] failed version validation ]. It will not be made available."
>time="2018-08-30T20:12:37Z" level=warning msg="Spec [  ] failed validation for the following reason: [ Spec [] failed version validation ]. It will not be made available."
>time="2018-08-30T20:12:37Z" level=warning msg="Spec [  ] failed validation for the following reason: [ Spec [] failed version validation ]. It will not be made available."
>time="2018-08-30T20:12:37Z" level=warning msg="Spec [  ] failed validation for the following reason: [ Spec [] failed version validation ]. It will not be made available."
>time="2018-08-30T20:12:37Z" level=warning msg="Spec [  ] failed validation for the following reason: [ Spec [] failed version validation ]. It will not be made available."
>time="2018-08-30T20:12:37Z" level=warning msg="Spec [  ] failed validation for the following reason: [ Spec [] failed version validation ]. It will not be made available."
>time="2018-08-30T20:12:37Z" level=warning msg="Spec [  ] failed validation for the following reason: [ Spec [] failed version validation ]. It will not be made available."
>time="2018-08-30T20:12:37Z" level=warning msg="Spec [  ] failed validation for the following reason: [ Spec [] failed version validation ]. It will not be made available."
>time="2018-08-30T20:12:37Z" level=warning msg="Spec [  ] failed validation for the following reason: [ Spec [] failed version validation ]. It will not be made available."
>time="2018-08-30T20:12:37Z" level=warning msg="13 specs of 13 discovered specs failed validation from registry: openshift-registry"
>time="2018-08-30T20:12:37Z" level=debug msg="spec 'ba9c2d4db404ce97111bea80225de968|dh-rocketchat-apb' needs to be added"
>time="2018-08-30T20:12:37Z" level=debug msg="spec '1dda1477cace09730bd8ed7a6505607e|dh-postgresql-apb' needs to be added"
>time="2018-08-30T20:12:37Z" level=debug msg="spec 'f6c4486b7fb0cdac4b58e193607f7011|dh-mediawiki-apb' needs to be added"
>time="2018-08-30T20:12:37Z" level=debug msg="spec '4b12a667a4ffdcaf61a52c7f72c3fe67|dh-redis-apb' needs to be added"
>time="2018-08-30T20:12:37Z" level=debug msg="spec '2fc891259c84ffd32cb564adf55803f8|dh-catalog-apb' needs to be added"
>time="2018-08-30T20:12:37Z" level=debug msg="spec 'b0dec8711d205cc51d7633eb95c1ec04|dh-bind-apb' needs to be added"
>time="2018-08-30T20:12:37Z" level=info msg="add spec: ba9c2d4db404ce97111bea80225de968|dh-rocketchat-apb"
>time="2018-08-30T20:12:37Z" level=info msg="add spec: 1dda1477cace09730bd8ed7a6505607e|dh-postgresql-apb"
>time="2018-08-30T20:12:37Z" level=info msg="add spec: f6c4486b7fb0cdac4b58e193607f7011|dh-mediawiki-apb"
>time="2018-08-30T20:12:37Z" level=info msg="add spec: 4b12a667a4ffdcaf61a52c7f72c3fe67|dh-redis-apb"
>time="2018-08-30T20:12:37Z" level=info msg="add spec: 2fc891259c84ffd32cb564adf55803f8|dh-catalog-apb"
>time="2018-08-30T20:12:37Z" level=info msg="add spec: b0dec8711d205cc51d7633eb95c1ec04|dh-bind-apb"
>time="2018-08-30T20:12:37Z" level=info msg="Broker successfully bootstrapped on startup"
>time="2018-08-30T20:12:37Z" level=debug msg="RefreshInterval: 10m0s"
>time="2018-08-30T20:12:37Z" level=debug msg="calling NewSecureServingOptions"
>time="2018-08-30T20:12:38Z" level=debug msg="Creating k8s apiserver"
>time="2018-08-30T20:12:38Z" level=info msg="Listening on https://[::]:1338"
>time="2018-08-30T20:12:38Z" level=info msg="Ansible Service Broker Starting"
>time="2018-08-30T20:15:31Z" level=debug msg="Unable to find originating origin header"
>time="2018-08-30T20:15:31Z" level=info msg="Request: \"GET /osb/v2/catalog HTTP/1.1\\r\\nHost: broker.automation-broker.svc:1338\\r\\nAccept-Encoding: gzip\\r\\nUser-Agent: Go-http-client/1.1\\r\\nX-Broker-Api-Version: 2.13\\r\\n\\r\\n\""
>time="2018-08-30T20:15:31Z" level=info msg="AnsibleBroker::Catalog"
>time="2018-08-30T20:15:31Z" level=debug msg="Dao::BatchGetSpecs"
>time="2018-08-30T20:15:31Z" level=debug msg="Filtering secret parameters out of specs..."
>time="2018-08-30T20:15:31Z" level=debug msg="Filtering secrets from spec dh-postgresql-apb"
>time="2018-08-30T20:15:31Z" level=debug msg="Filtering secrets from spec dh-catalog-apb"
>time="2018-08-30T20:15:31Z" level=debug msg="Filtering secrets from spec dh-redis-apb"
>time="2018-08-30T20:15:31Z" level=debug msg="Filtering secrets from spec dh-bind-apb"
>time="2018-08-30T20:15:31Z" level=debug msg="Filtering secrets from spec dh-rocketchat-apb"
>time="2018-08-30T20:15:31Z" level=debug msg="Filtering secrets from spec dh-mediawiki-apb"
>172.17.0.10 - - [30/Aug/2018:20:15:31 +0000] "GET /osb/v2/catalog HTTP/1.1" 200 34451
>time="2018-08-30T20:15:33Z" level=debug msg="Unable to find originating origin header"
>time="2018-08-30T20:15:33Z" level=info msg="Request: \"GET /osb/v2/catalog HTTP/1.1\\r\\nHost: broker.automation-broker.svc:1338\\r\\nAccept-Encoding: gzip\\r\\nUser-Agent: Go-http-client/1.1\\r\\nX-Broker-Api-Version: 2.13\\r\\n\\r\\n\""
>time="2018-08-30T20:15:33Z" level=info msg="AnsibleBroker::Catalog"
>time="2018-08-30T20:15:33Z" level=debug msg="Dao::BatchGetSpecs"
>time="2018-08-30T20:15:33Z" level=debug msg="Filtering secret parameters out of specs..."
>time="2018-08-30T20:15:33Z" level=debug msg="Filtering secrets from spec dh-postgresql-apb"
>time="2018-08-30T20:15:33Z" level=debug msg="Filtering secrets from spec dh-catalog-apb"
>time="2018-08-30T20:15:33Z" level=debug msg="Filtering secrets from spec dh-redis-apb"
>time="2018-08-30T20:15:33Z" level=debug msg="Filtering secrets from spec dh-bind-apb"
>time="2018-08-30T20:15:33Z" level=debug msg="Filtering secrets from spec dh-rocketchat-apb"
>time="2018-08-30T20:15:33Z" level=debug msg="Filtering secrets from spec dh-mediawiki-apb"
>172.17.0.10 - - [30/Aug/2018:20:15:33 +0000] "GET /osb/v2/catalog HTTP/1.1" 200 34451
>time="2018-08-30T20:15:36Z" level=debug msg="Unable to find originating origin header"
>time="2018-08-30T20:15:36Z" level=info msg="Request: \"GET /osb/v2/catalog HTTP/1.1\\r\\nHost: broker.automation-broker.svc:1338\\r\\nAccept-Encoding: gzip\\r\\nUser-Agent: Go-http-client/1.1\\r\\nX-Broker-Api-Version: 2.13\\r\\n\\r\\n\""
>time="2018-08-30T20:15:36Z" level=info msg="AnsibleBroker::Catalog"
>time="2018-08-30T20:15:36Z" level=debug msg="Dao::BatchGetSpecs"
>time="2018-08-30T20:15:36Z" level=debug msg="Filtering secret parameters out of specs..."
>time="2018-08-30T20:15:36Z" level=debug msg="Filtering secrets from spec dh-postgresql-apb"
>time="2018-08-30T20:15:36Z" level=debug msg="Filtering secrets from spec dh-catalog-apb"
>time="2018-08-30T20:15:36Z" level=debug msg="Filtering secrets from spec dh-redis-apb"
>time="2018-08-30T20:15:36Z" level=debug msg="Filtering secrets from spec dh-bind-apb"
>time="2018-08-30T20:15:36Z" level=debug msg="Filtering secrets from spec dh-rocketchat-apb"
>time="2018-08-30T20:15:36Z" level=debug msg="Filtering secrets from spec dh-mediawiki-apb"
>172.17.0.10 - - [30/Aug/2018:20:15:36 +0000] "GET /osb/v2/catalog HTTP/1.1" 200 34451
>time="2018-08-30T20:15:39Z" level=debug msg="Unable to find originating origin header"
>time="2018-08-30T20:15:39Z" level=info msg="Request: \"GET /osb/v2/catalog HTTP/1.1\\r\\nHost: broker.automation-broker.svc:1338\\r\\nAccept-Encoding: gzip\\r\\nUser-Agent: Go-http-client/1.1\\r\\nX-Broker-Api-Version: 2.13\\r\\n\\r\\n\""
>time="2018-08-30T20:15:39Z" level=info msg="AnsibleBroker::Catalog"
>time="2018-08-30T20:15:39Z" level=debug msg="Dao::BatchGetSpecs"
>time="2018-08-30T20:15:39Z" level=debug msg="Filtering secret parameters out of specs..."
>time="2018-08-30T20:15:39Z" level=debug msg="Filtering secrets from spec dh-postgresql-apb"
>time="2018-08-30T20:15:39Z" level=debug msg="Filtering secrets from spec dh-catalog-apb"
>time="2018-08-30T20:15:39Z" level=debug msg="Filtering secrets from spec dh-redis-apb"
>time="2018-08-30T20:15:39Z" level=debug msg="Filtering secrets from spec dh-bind-apb"
>time="2018-08-30T20:15:39Z" level=debug msg="Filtering secrets from spec dh-rocketchat-apb"
>time="2018-08-30T20:15:39Z" level=debug msg="Filtering secrets from spec dh-mediawiki-apb"
>172.17.0.10 - - [30/Aug/2018:20:15:39 +0000] "GET /osb/v2/catalog HTTP/1.1" 200 34451
>time="2018-08-30T20:22:37Z" level=info msg="Broker configured to refresh specs every 10m0s seconds"
>time="2018-08-30T20:22:37Z" level=info msg="Attempting bootstrap at 2018-08-30 20:22:37.787126781 +0000 UTC"
>time="2018-08-30T20:22:37Z" level=info msg="AnsibleBroker::Bootstrap"
>time="2018-08-30T20:22:37Z" level=debug msg="Dao::BatchGetSpecs"
>time="2018-08-30T20:22:37Z" level=debug msg="Dao::BatchGetBundleInstances"
>time="2018-08-30T20:22:37Z" level=debug msg="markedSpecs: map[]\n"
>time="2018-08-30T20:22:37Z" level=info msg="0 specs deleted"
>time="2018-08-30T20:22:37Z" level=debug msg="Dao::BatchGetSpecs"
>time="2018-08-30T20:22:37Z" level=debug msg="converting []*Spec to SpecManifest successful"
>time="2018-08-30T20:22:37Z" level=debug msg="DockerHubAdapter::GetImages"
>time="2018-08-30T20:22:37Z" level=debug msg="BundleSpecLabel: com.redhat.apb.spec"
>time="2018-08-30T20:22:37Z" level=debug msg="Loading image list for org: [ mhrivnak ]"
>time="2018-08-30T20:22:38Z" level=debug msg="Trying to load mhrivnak/pulp-k8s-mongodb"
>time="2018-08-30T20:22:38Z" level=debug msg="Trying to load mhrivnak/bind-apb"
>time="2018-08-30T20:22:38Z" level=debug msg="Trying to load mhrivnak/postgresql-apb"
>time="2018-08-30T20:22:38Z" level=debug msg="Trying to load mhrivnak/mediawiki-apb"
>time="2018-08-30T20:22:38Z" level=debug msg="Trying to load mhrivnak/redis-helm-apb"
>time="2018-08-30T20:22:38Z" level=debug msg="Trying to load mhrivnak/svccatalog-apb"
>time="2018-08-30T20:22:38Z" level=debug msg="Trying to load mhrivnak/rocketchat-apb"
>time="2018-08-30T20:22:38Z" level=debug msg="Trying to load mhrivnak/pulp-k8s-celery"
>time="2018-08-30T20:22:38Z" level=debug msg="Trying to load mhrivnak/pulp-k8s-qpid"
>time="2018-08-30T20:22:38Z" level=debug msg="Trying to load mhrivnak/origin-ansible-service-broker"
>time="2018-08-30T20:22:38Z" level=debug msg="Trying to load mhrivnak/pulp-k8s-httpd"
>time="2018-08-30T20:22:38Z" level=debug msg="Trying to load mhrivnak/pulp-k8s-base"
>time="2018-08-30T20:22:38Z" level=debug msg="Trying to load mhrivnak/bind"
>time="2018-08-30T20:22:38Z" level=debug msg="Trying to load mhrivnak/pulp-k8s-manage"
>time="2018-08-30T20:22:38Z" level=debug msg="Trying to load mhrivnak/staticreg"
>time="2018-08-30T20:22:38Z" level=debug msg="Trying to load mhrivnak/pulp"
>time="2018-08-30T20:22:38Z" level=debug msg="Trying to load mhrivnak/pulp-automation"
>time="2018-08-30T20:22:38Z" level=debug msg="Trying to load mhrivnak/vault"
>time="2018-08-30T20:22:38Z" level=debug msg="Trying to load mhrivnak/helm-ansible-base"
>time="2018-08-30T20:22:38Z" level=debug msg="Trying to load mhrivnak/helm-bundle-base"
>time="2018-08-30T20:22:38Z" level=debug msg="Filter applied against registry: dh"
>time="2018-08-30T20:22:38Z" level=debug msg="Bundles passing white/blacklist filter:"
>time="2018-08-30T20:22:38Z" level=debug msg="-> mhrivnak/bind-apb"
>time="2018-08-30T20:22:38Z" level=debug msg="-> mhrivnak/rocketchat-apb"
>time="2018-08-30T20:22:38Z" level=debug msg="-> mhrivnak/postgresql-apb"
>time="2018-08-30T20:22:38Z" level=debug msg="-> mhrivnak/mediawiki-apb"
>time="2018-08-30T20:22:38Z" level=debug msg="-> mhrivnak/redis-helm-apb"
>time="2018-08-30T20:22:38Z" level=debug msg="-> mhrivnak/svccatalog-apb"
>time="2018-08-30T20:22:38Z" level=info msg="Bundles filtered by white/blacklist filter:\n\t-> mhrivnak/pulp-k8s-mongodb\n\t-> mhrivnak/pulp-k8s-celery\n\t-> mhrivnak/pulp-k8s-qpid\n\t-> mhrivnak/pulp-k8s-httpd\n\t-> mhrivnak/origin-ansible-service-broker\n\t-> mhrivnak/pulp-k8s-base\n\t-> mhrivnak/pulp-k8s-manage\n\t-> mhrivnak/bind\n\t-> mhrivnak/pulp\n\t-> mhrivnak/staticreg\n\t-> mhrivnak/pulp-automation\n\t-> mhrivnak/vault\n\t-> mhrivnak/helm-ansible-base\n\t-> mhrivnak/helm-bundle-base\n"
>time="2018-08-30T20:22:39Z" level=debug msg="Successfully converted Image docker.io/mhrivnak/bind-apb:latest into Spec"
>time="2018-08-30T20:22:39Z" level=info msg="adapter::configToSpec -> Image docker.io/mhrivnak/bind-apb:latest runtime is 2"
>time="2018-08-30T20:22:39Z" level=debug msg="Successfully converted Image docker.io/mhrivnak/rocketchat-apb:latest into Spec"
>time="2018-08-30T20:22:39Z" level=info msg="adapter::configToSpec -> Image docker.io/mhrivnak/rocketchat-apb:latest runtime is 2"
>time="2018-08-30T20:22:39Z" level=debug msg="Successfully converted Image docker.io/mhrivnak/postgresql-apb:latest into Spec"
>time="2018-08-30T20:22:39Z" level=info msg="adapter::configToSpec -> Image docker.io/mhrivnak/postgresql-apb:latest runtime is 2"
>time="2018-08-30T20:22:41Z" level=debug msg="Successfully converted Image docker.io/mhrivnak/mediawiki-apb:latest into Spec"
>time="2018-08-30T20:22:41Z" level=info msg="adapter::configToSpec -> Image docker.io/mhrivnak/mediawiki-apb:latest runtime is 2"
>time="2018-08-30T20:22:41Z" level=debug msg="Successfully converted Image docker.io/mhrivnak/redis-helm-apb:latest into Spec"
>time="2018-08-30T20:22:41Z" level=info msg="adapter::configToSpec -> Image docker.io/mhrivnak/redis-helm-apb:latest runtime is 2"
>time="2018-08-30T20:22:41Z" level=debug msg="Successfully converted Image docker.io/mhrivnak/svccatalog-apb:latest into Spec"
>time="2018-08-30T20:22:41Z" level=info msg="adapter::configToSpec -> Image docker.io/mhrivnak/svccatalog-apb:latest runtime is 2"
>time="2018-08-30T20:22:41Z" level=info msg="Validating specs..."
>time="2018-08-30T20:22:41Z" level=debug msg="Spec [bind-apb] version (1.0) not semver compatible"
>time="2018-08-30T20:22:41Z" level=debug msg="Spec [rocketchat-apb] version (1.0) not semver compatible"
>time="2018-08-30T20:22:41Z" level=debug msg="Spec [postgresql-apb] version (1.0) not semver compatible"
>time="2018-08-30T20:22:41Z" level=debug msg="Spec [mediawiki-apb] version (1.0) not semver compatible"
>time="2018-08-30T20:22:41Z" level=debug msg="Spec [redis-apb] version (1.0) not semver compatible"
>time="2018-08-30T20:22:41Z" level=debug msg="Spec [catalog-apb] version (1.0) not semver compatible"
>time="2018-08-30T20:22:41Z" level=info msg="All specs passed validation!"
>time="2018-08-30T20:22:41Z" level=debug msg="LocalOpenShiftAdapter::GetImageNames"
>time="2018-08-30T20:22:41Z" level=debug msg="BundleSpecLabel: com.redhat.apb.spec"
>time="2018-08-30T20:22:41Z" level=debug msg="Filter applied against registry: localregistry"
>time="2018-08-30T20:22:41Z" level=debug msg="Bundles passing white/blacklist filter:"
>time="2018-08-30T20:22:41Z" level=debug msg="-> openshift/postgresql"
>time="2018-08-30T20:22:41Z" level=debug msg="-> openshift/python"
>time="2018-08-30T20:22:41Z" level=debug msg="-> openshift/ruby"
>time="2018-08-30T20:22:41Z" level=debug msg="-> openshift/jenkins"
>time="2018-08-30T20:22:41Z" level=debug msg="-> openshift/mongodb"
>time="2018-08-30T20:22:41Z" level=debug msg="-> openshift/mysql"
>time="2018-08-30T20:22:41Z" level=debug msg="-> openshift/perl"
>time="2018-08-30T20:22:41Z" level=debug msg="-> openshift/php"
>time="2018-08-30T20:22:41Z" level=debug msg="-> openshift/httpd"
>time="2018-08-30T20:22:41Z" level=debug msg="-> openshift/mariadb"
>time="2018-08-30T20:22:41Z" level=debug msg="-> openshift/nginx"
>time="2018-08-30T20:22:41Z" level=debug msg="-> openshift/nodejs"
>time="2018-08-30T20:22:41Z" level=debug msg="-> openshift/redis"
>time="2018-08-30T20:22:41Z" level=debug msg="LocalOpenShiftAdapter::FetchSpecs"
>time="2018-08-30T20:22:41Z" level=debug msg="No tag specified in config, assuming `latest`"
>time="2018-08-30T20:22:41Z" level=debug msg="LocalOpenShiftAdapter::LoadSpec"
>time="2018-08-30T20:22:41Z" level=info msg="No runtime label found. Set runtime=1. Will use 'exec' to gather bind credentials"
>time="2018-08-30T20:22:41Z" level=debug msg="LocalOpenShiftAdapter::LoadSpec"
>time="2018-08-30T20:22:41Z" level=info msg="No runtime label found. Set runtime=1. Will use 'exec' to gather bind credentials"
>time="2018-08-30T20:22:41Z" level=debug msg="LocalOpenShiftAdapter::LoadSpec"
>time="2018-08-30T20:22:41Z" level=info msg="No runtime label found. Set runtime=1. Will use 'exec' to gather bind credentials"
>time="2018-08-30T20:22:41Z" level=debug msg="LocalOpenShiftAdapter::LoadSpec"
>time="2018-08-30T20:22:41Z" level=info msg="No runtime label found. Set runtime=1. Will use 'exec' to gather bind credentials"
>time="2018-08-30T20:22:41Z" level=debug msg="LocalOpenShiftAdapter::LoadSpec"
>time="2018-08-30T20:22:41Z" level=info msg="No runtime label found. Set runtime=1. Will use 'exec' to gather bind credentials"
>time="2018-08-30T20:22:41Z" level=debug msg="LocalOpenShiftAdapter::LoadSpec"
>time="2018-08-30T20:22:41Z" level=info msg="No runtime label found. Set runtime=1. Will use 'exec' to gather bind credentials"
>time="2018-08-30T20:22:41Z" level=debug msg="LocalOpenShiftAdapter::LoadSpec"
>time="2018-08-30T20:22:41Z" level=info msg="No runtime label found. Set runtime=1. Will use 'exec' to gather bind credentials"
>time="2018-08-30T20:22:41Z" level=debug msg="LocalOpenShiftAdapter::LoadSpec"
>time="2018-08-30T20:22:41Z" level=info msg="No runtime label found. Set runtime=1. Will use 'exec' to gather bind credentials"
>time="2018-08-30T20:22:41Z" level=debug msg="LocalOpenShiftAdapter::LoadSpec"
>time="2018-08-30T20:22:41Z" level=info msg="No runtime label found. Set runtime=1. Will use 'exec' to gather bind credentials"
>time="2018-08-30T20:22:41Z" level=debug msg="LocalOpenShiftAdapter::LoadSpec"
>time="2018-08-30T20:22:41Z" level=info msg="No runtime label found. Set runtime=1. Will use 'exec' to gather bind credentials"
>time="2018-08-30T20:22:41Z" level=debug msg="LocalOpenShiftAdapter::LoadSpec"
>time="2018-08-30T20:22:41Z" level=info msg="No runtime label found. Set runtime=1. Will use 'exec' to gather bind credentials"
>time="2018-08-30T20:22:41Z" level=debug msg="LocalOpenShiftAdapter::LoadSpec"
>time="2018-08-30T20:22:41Z" level=info msg="No runtime label found. Set runtime=1. Will use 'exec' to gather bind credentials"
>time="2018-08-30T20:22:42Z" level=debug msg="LocalOpenShiftAdapter::LoadSpec"
>time="2018-08-30T20:22:42Z" level=info msg="No runtime label found. Set runtime=1. Will use 'exec' to gather bind credentials"
>time="2018-08-30T20:22:42Z" level=info msg="Validating specs..."
>time="2018-08-30T20:22:42Z" level=warning msg="Spec [  ] failed validation for the following reason: [ Spec [] failed version validation ]. It will not be made available."
>time="2018-08-30T20:22:42Z" level=warning msg="Spec [  ] failed validation for the following reason: [ Spec [] failed version validation ]. It will not be made available."
>time="2018-08-30T20:22:42Z" level=warning msg="Spec [  ] failed validation for the following reason: [ Spec [] failed version validation ]. It will not be made available."
>time="2018-08-30T20:22:42Z" level=warning msg="Spec [  ] failed validation for the following reason: [ Spec [] failed version validation ]. It will not be made available."
>time="2018-08-30T20:22:42Z" level=warning msg="Spec [  ] failed validation for the following reason: [ Spec [] failed version validation ]. It will not be made available."
>time="2018-08-30T20:22:42Z" level=warning msg="Spec [  ] failed validation for the following reason: [ Spec [] failed version validation ]. It will not be made available."
>time="2018-08-30T20:22:42Z" level=warning msg="Spec [  ] failed validation for the following reason: [ Spec [] failed version validation ]. It will not be made available."
>time="2018-08-30T20:22:42Z" level=warning msg="Spec [  ] failed validation for the following reason: [ Spec [] failed version validation ]. It will not be made available."
>time="2018-08-30T20:22:42Z" level=warning msg="Spec [  ] failed validation for the following reason: [ Spec [] failed version validation ]. It will not be made available."
>time="2018-08-30T20:22:42Z" level=warning msg="Spec [  ] failed validation for the following reason: [ Spec [] failed version validation ]. It will not be made available."
>time="2018-08-30T20:22:42Z" level=warning msg="Spec [  ] failed validation for the following reason: [ Spec [] failed version validation ]. It will not be made available."
>time="2018-08-30T20:22:42Z" level=warning msg="Spec [  ] failed validation for the following reason: [ Spec [] failed version validation ]. It will not be made available."
>time="2018-08-30T20:22:42Z" level=warning msg="Spec [  ] failed validation for the following reason: [ Spec [] failed version validation ]. It will not be made available."
>time="2018-08-30T20:22:42Z" level=warning msg="13 specs of 13 discovered specs failed validation from registry: openshift-registry"
>time="2018-08-30T20:22:42Z" level=debug msg="spec 'b0dec8711d205cc51d7633eb95c1ec04|dh-bind-apb' needs to be updated"
>time="2018-08-30T20:22:42Z" level=debug msg="spec 'ba9c2d4db404ce97111bea80225de968|dh-rocketchat-apb' needs to be updated"
>time="2018-08-30T20:22:42Z" level=debug msg="spec '1dda1477cace09730bd8ed7a6505607e|dh-postgresql-apb' needs to be updated"
>time="2018-08-30T20:22:42Z" level=debug msg="spec 'f6c4486b7fb0cdac4b58e193607f7011|dh-mediawiki-apb' needs to be updated"
>time="2018-08-30T20:22:42Z" level=debug msg="spec '4b12a667a4ffdcaf61a52c7f72c3fe67|dh-redis-apb' needs to be updated"
>time="2018-08-30T20:22:42Z" level=debug msg="spec '2fc891259c84ffd32cb564adf55803f8|dh-catalog-apb' needs to be updated"
>time="2018-08-30T20:22:42Z" level=info msg="update spec: 1dda1477cace09730bd8ed7a6505607e|dh-postgresql-apb to crd"
>time="2018-08-30T20:22:42Z" level=info msg="update spec: f6c4486b7fb0cdac4b58e193607f7011|dh-mediawiki-apb to crd"
>time="2018-08-30T20:22:42Z" level=info msg="update spec: 4b12a667a4ffdcaf61a52c7f72c3fe67|dh-redis-apb to crd"
>time="2018-08-30T20:22:42Z" level=info msg="update spec: 2fc891259c84ffd32cb564adf55803f8|dh-catalog-apb to crd"
>time="2018-08-30T20:22:42Z" level=info msg="update spec: b0dec8711d205cc51d7633eb95c1ec04|dh-bind-apb to crd"
>time="2018-08-30T20:22:42Z" level=info msg="update spec: ba9c2d4db404ce97111bea80225de968|dh-rocketchat-apb to crd"
>time="2018-08-30T20:22:42Z" level=info msg="Broker successfully bootstrapped"
>time="2018-08-30T20:31:15Z" level=debug msg="Unable to find originating origin header"
>time="2018-08-30T20:31:15Z" level=info msg="Request: \"GET /osb/v2/catalog HTTP/1.1\\r\\nHost: broker.automation-broker.svc:1338\\r\\nAccept-Encoding: gzip\\r\\nUser-Agent: Go-http-client/1.1\\r\\nX-Broker-Api-Version: 2.13\\r\\n\\r\\n\""
>time="2018-08-30T20:31:15Z" level=info msg="AnsibleBroker::Catalog"
>time="2018-08-30T20:31:15Z" level=debug msg="Dao::BatchGetSpecs"
>time="2018-08-30T20:31:15Z" level=debug msg="Filtering secret parameters out of specs..."
>time="2018-08-30T20:31:15Z" level=debug msg="Filtering secrets from spec dh-postgresql-apb"
>time="2018-08-30T20:31:15Z" level=debug msg="Filtering secrets from spec dh-catalog-apb"
>time="2018-08-30T20:31:15Z" level=debug msg="Filtering secrets from spec dh-redis-apb"
>time="2018-08-30T20:31:15Z" level=debug msg="Filtering secrets from spec dh-bind-apb"
>time="2018-08-30T20:31:15Z" level=debug msg="Filtering secrets from spec dh-rocketchat-apb"
>time="2018-08-30T20:31:15Z" level=debug msg="Filtering secrets from spec dh-mediawiki-apb"
>172.17.0.10 - - [30/Aug/2018:20:31:15 +0000] "GET /osb/v2/catalog HTTP/1.1" 200 34451
>time="2018-08-30T20:32:37Z" level=info msg="Broker configured to refresh specs every 10m0s seconds"
>time="2018-08-30T20:32:37Z" level=info msg="Attempting bootstrap at 2018-08-30 20:32:37.78714619 +0000 UTC"
>time="2018-08-30T20:32:37Z" level=info msg="AnsibleBroker::Bootstrap"
>time="2018-08-30T20:32:37Z" level=debug msg="Dao::BatchGetSpecs"
>time="2018-08-30T20:32:37Z" level=debug msg="Dao::BatchGetBundleInstances"
>time="2018-08-30T20:32:37Z" level=debug msg="markedSpecs: map[]\n"
>time="2018-08-30T20:32:37Z" level=info msg="0 specs deleted"
>time="2018-08-30T20:32:37Z" level=debug msg="Dao::BatchGetSpecs"
>time="2018-08-30T20:32:37Z" level=debug msg="converting []*Spec to SpecManifest successful"
>time="2018-08-30T20:32:37Z" level=debug msg="DockerHubAdapter::GetImages"
>time="2018-08-30T20:32:37Z" level=debug msg="BundleSpecLabel: com.redhat.apb.spec"
>time="2018-08-30T20:32:37Z" level=debug msg="Loading image list for org: [ mhrivnak ]"
>time="2018-08-30T20:32:38Z" level=debug msg="Trying to load mhrivnak/pulp-k8s-mongodb"
>time="2018-08-30T20:32:38Z" level=debug msg="Trying to load mhrivnak/bind-apb"
>time="2018-08-30T20:32:38Z" level=debug msg="Trying to load mhrivnak/postgresql-apb"
>time="2018-08-30T20:32:38Z" level=debug msg="Trying to load mhrivnak/mediawiki-apb"
>time="2018-08-30T20:32:38Z" level=debug msg="Trying to load mhrivnak/redis-helm-apb"
>time="2018-08-30T20:32:38Z" level=debug msg="Trying to load mhrivnak/svccatalog-apb"
>time="2018-08-30T20:32:38Z" level=debug msg="Trying to load mhrivnak/rocketchat-apb"
>time="2018-08-30T20:32:38Z" level=debug msg="Trying to load mhrivnak/pulp-k8s-celery"
>time="2018-08-30T20:32:38Z" level=debug msg="Trying to load mhrivnak/pulp-k8s-qpid"
>time="2018-08-30T20:32:38Z" level=debug msg="Trying to load mhrivnak/origin-ansible-service-broker"
>time="2018-08-30T20:32:38Z" level=debug msg="Trying to load mhrivnak/pulp-k8s-httpd"
>time="2018-08-30T20:32:38Z" level=debug msg="Trying to load mhrivnak/pulp-k8s-base"
>time="2018-08-30T20:32:38Z" level=debug msg="Trying to load mhrivnak/bind"
>time="2018-08-30T20:32:38Z" level=debug msg="Trying to load mhrivnak/pulp-k8s-manage"
>time="2018-08-30T20:32:38Z" level=debug msg="Trying to load mhrivnak/staticreg"
>time="2018-08-30T20:32:38Z" level=debug msg="Trying to load mhrivnak/pulp"
>time="2018-08-30T20:32:38Z" level=debug msg="Trying to load mhrivnak/pulp-automation"
>time="2018-08-30T20:32:38Z" level=debug msg="Trying to load mhrivnak/vault"
>time="2018-08-30T20:32:38Z" level=debug msg="Trying to load mhrivnak/helm-ansible-base"
>time="2018-08-30T20:32:38Z" level=debug msg="Trying to load mhrivnak/helm-bundle-base"
>time="2018-08-30T20:32:38Z" level=debug msg="Filter applied against registry: dh"
>time="2018-08-30T20:32:38Z" level=debug msg="Bundles passing white/blacklist filter:"
>time="2018-08-30T20:32:38Z" level=debug msg="-> mhrivnak/rocketchat-apb"
>time="2018-08-30T20:32:38Z" level=debug msg="-> mhrivnak/mediawiki-apb"
>time="2018-08-30T20:32:38Z" level=debug msg="-> mhrivnak/redis-helm-apb"
>time="2018-08-30T20:32:38Z" level=debug msg="-> mhrivnak/svccatalog-apb"
>time="2018-08-30T20:32:38Z" level=debug msg="-> mhrivnak/bind-apb"
>time="2018-08-30T20:32:38Z" level=debug msg="-> mhrivnak/postgresql-apb"
>time="2018-08-30T20:32:38Z" level=info msg="Bundles filtered by white/blacklist filter:\n\t-> mhrivnak/helm-bundle-base\n\t-> mhrivnak/pulp-k8s-mongodb\n\t-> mhrivnak/pulp-k8s-httpd\n\t-> mhrivnak/pulp-k8s-base\n\t-> mhrivnak/bind\n\t-> mhrivnak/pulp-k8s-manage\n\t-> mhrivnak/origin-ansible-service-broker\n\t-> mhrivnak/staticreg\n\t-> mhrivnak/pulp\n\t-> mhrivnak/pulp-automation\n\t-> mhrivnak/vault\n\t-> mhrivnak/helm-ansible-base\n\t-> mhrivnak/pulp-k8s-celery\n\t-> mhrivnak/pulp-k8s-qpid\n"
>time="2018-08-30T20:32:39Z" level=debug msg="Successfully converted Image docker.io/mhrivnak/rocketchat-apb:latest into Spec"
>time="2018-08-30T20:32:39Z" level=info msg="adapter::configToSpec -> Image docker.io/mhrivnak/rocketchat-apb:latest runtime is 2"
>time="2018-08-30T20:32:40Z" level=debug msg="Successfully converted Image docker.io/mhrivnak/mediawiki-apb:latest into Spec"
>time="2018-08-30T20:32:40Z" level=info msg="adapter::configToSpec -> Image docker.io/mhrivnak/mediawiki-apb:latest runtime is 2"
>time="2018-08-30T20:32:40Z" level=debug msg="Successfully converted Image docker.io/mhrivnak/redis-helm-apb:latest into Spec"
>time="2018-08-30T20:32:40Z" level=info msg="adapter::configToSpec -> Image docker.io/mhrivnak/redis-helm-apb:latest runtime is 2"
>time="2018-08-30T20:32:40Z" level=debug msg="Successfully converted Image docker.io/mhrivnak/svccatalog-apb:latest into Spec"
>time="2018-08-30T20:32:40Z" level=info msg="adapter::configToSpec -> Image docker.io/mhrivnak/svccatalog-apb:latest runtime is 2"
>time="2018-08-30T20:32:41Z" level=debug msg="Successfully converted Image docker.io/mhrivnak/bind-apb:latest into Spec"
>time="2018-08-30T20:32:41Z" level=info msg="adapter::configToSpec -> Image docker.io/mhrivnak/bind-apb:latest runtime is 2"
>time="2018-08-30T20:32:41Z" level=debug msg="Successfully converted Image docker.io/mhrivnak/postgresql-apb:latest into Spec"
>time="2018-08-30T20:32:41Z" level=info msg="adapter::configToSpec -> Image docker.io/mhrivnak/postgresql-apb:latest runtime is 2"
>time="2018-08-30T20:32:41Z" level=info msg="Validating specs..."
>time="2018-08-30T20:32:41Z" level=debug msg="Spec [rocketchat-apb] version (1.0) not semver compatible"
>time="2018-08-30T20:32:41Z" level=debug msg="Spec [mediawiki-apb] version (1.0) not semver compatible"
>time="2018-08-30T20:32:41Z" level=debug msg="Spec [bind-apb] version (1.0) not semver compatible"
>time="2018-08-30T20:32:41Z" level=debug msg="Spec [catalog-apb] version (1.0) not semver compatible"
>time="2018-08-30T20:32:41Z" level=debug msg="Spec [redis-apb] version (1.0) not semver compatible"
>time="2018-08-30T20:32:41Z" level=debug msg="Spec [postgresql-apb] version (1.0) not semver compatible"
>time="2018-08-30T20:32:41Z" level=info msg="All specs passed validation!"
>time="2018-08-30T20:32:41Z" level=debug msg="LocalOpenShiftAdapter::GetImageNames"
>time="2018-08-30T20:32:41Z" level=debug msg="BundleSpecLabel: com.redhat.apb.spec"
>time="2018-08-30T20:32:41Z" level=debug msg="Filter applied against registry: localregistry"
>time="2018-08-30T20:32:41Z" level=debug msg="Bundles passing white/blacklist filter:"
>time="2018-08-30T20:32:41Z" level=debug msg="-> openshift/mongodb"
>time="2018-08-30T20:32:41Z" level=debug msg="-> openshift/php"
>time="2018-08-30T20:32:41Z" level=debug msg="-> openshift/python"
>time="2018-08-30T20:32:41Z" level=debug msg="-> openshift/redis"
>time="2018-08-30T20:32:41Z" level=debug msg="-> openshift/httpd"
>time="2018-08-30T20:32:41Z" level=debug msg="-> openshift/jenkins"
>time="2018-08-30T20:32:41Z" level=debug msg="-> openshift/mariadb"
>time="2018-08-30T20:32:41Z" level=debug msg="-> openshift/mysql"
>time="2018-08-30T20:32:41Z" level=debug msg="-> openshift/nginx"
>time="2018-08-30T20:32:41Z" level=debug msg="-> openshift/nodejs"
>time="2018-08-30T20:32:41Z" level=debug msg="-> openshift/perl"
>time="2018-08-30T20:32:41Z" level=debug msg="-> openshift/postgresql"
>time="2018-08-30T20:32:41Z" level=debug msg="-> openshift/ruby"
>time="2018-08-30T20:32:41Z" level=debug msg="LocalOpenShiftAdapter::FetchSpecs"
>time="2018-08-30T20:32:41Z" level=debug msg="No tag specified in config, assuming `latest`"
>time="2018-08-30T20:32:41Z" level=debug msg="LocalOpenShiftAdapter::LoadSpec"
>time="2018-08-30T20:32:41Z" level=info msg="No runtime label found. Set runtime=1. Will use 'exec' to gather bind credentials"
>time="2018-08-30T20:32:41Z" level=debug msg="LocalOpenShiftAdapter::LoadSpec"
>time="2018-08-30T20:32:41Z" level=info msg="No runtime label found. Set runtime=1. Will use 'exec' to gather bind credentials"
>time="2018-08-30T20:32:41Z" level=debug msg="LocalOpenShiftAdapter::LoadSpec"
>time="2018-08-30T20:32:41Z" level=info msg="No runtime label found. Set runtime=1. Will use 'exec' to gather bind credentials"
>time="2018-08-30T20:32:41Z" level=debug msg="LocalOpenShiftAdapter::LoadSpec"
>time="2018-08-30T20:32:41Z" level=info msg="No runtime label found. Set runtime=1. Will use 'exec' to gather bind credentials"
>time="2018-08-30T20:32:41Z" level=debug msg="LocalOpenShiftAdapter::LoadSpec"
>time="2018-08-30T20:32:41Z" level=info msg="No runtime label found. Set runtime=1. Will use 'exec' to gather bind credentials"
>time="2018-08-30T20:32:41Z" level=debug msg="LocalOpenShiftAdapter::LoadSpec"
>time="2018-08-30T20:32:41Z" level=info msg="No runtime label found. Set runtime=1. Will use 'exec' to gather bind credentials"
>time="2018-08-30T20:32:41Z" level=debug msg="LocalOpenShiftAdapter::LoadSpec"
>time="2018-08-30T20:32:41Z" level=info msg="No runtime label found. Set runtime=1. Will use 'exec' to gather bind credentials"
>time="2018-08-30T20:32:41Z" level=debug msg="LocalOpenShiftAdapter::LoadSpec"
>time="2018-08-30T20:32:41Z" level=info msg="No runtime label found. Set runtime=1. Will use 'exec' to gather bind credentials"
>time="2018-08-30T20:32:41Z" level=debug msg="LocalOpenShiftAdapter::LoadSpec"
>time="2018-08-30T20:32:41Z" level=info msg="No runtime label found. Set runtime=1. Will use 'exec' to gather bind credentials"
>time="2018-08-30T20:32:41Z" level=debug msg="LocalOpenShiftAdapter::LoadSpec"
>time="2018-08-30T20:32:41Z" level=info msg="No runtime label found. Set runtime=1. Will use 'exec' to gather bind credentials"
>time="2018-08-30T20:32:41Z" level=debug msg="LocalOpenShiftAdapter::LoadSpec"
>time="2018-08-30T20:32:41Z" level=info msg="No runtime label found. Set runtime=1. Will use 'exec' to gather bind credentials"
>time="2018-08-30T20:32:41Z" level=debug msg="LocalOpenShiftAdapter::LoadSpec"
>time="2018-08-30T20:32:41Z" level=info msg="No runtime label found. Set runtime=1. Will use 'exec' to gather bind credentials"
>time="2018-08-30T20:32:42Z" level=debug msg="LocalOpenShiftAdapter::LoadSpec"
>time="2018-08-30T20:32:42Z" level=info msg="No runtime label found. Set runtime=1. Will use 'exec' to gather bind credentials"
>time="2018-08-30T20:32:42Z" level=info msg="Validating specs..."
>time="2018-08-30T20:32:42Z" level=warning msg="Spec [  ] failed validation for the following reason: [ Spec [] failed version validation ]. It will not be made available."
>time="2018-08-30T20:32:42Z" level=warning msg="Spec [  ] failed validation for the following reason: [ Spec [] failed version validation ]. It will not be made available."
>time="2018-08-30T20:32:42Z" level=warning msg="Spec [  ] failed validation for the following reason: [ Spec [] failed version validation ]. It will not be made available."
>time="2018-08-30T20:32:42Z" level=warning msg="Spec [  ] failed validation for the following reason: [ Spec [] failed version validation ]. It will not be made available."
>time="2018-08-30T20:32:42Z" level=warning msg="Spec [  ] failed validation for the following reason: [ Spec [] failed version validation ]. It will not be made available."
>time="2018-08-30T20:32:42Z" level=warning msg="Spec [  ] failed validation for the following reason: [ Spec [] failed version validation ]. It will not be made available."
>time="2018-08-30T20:32:42Z" level=warning msg="Spec [  ] failed validation for the following reason: [ Spec [] failed version validation ]. It will not be made available."
>time="2018-08-30T20:32:42Z" level=warning msg="Spec [  ] failed validation for the following reason: [ Spec [] failed version validation ]. It will not be made available."
>time="2018-08-30T20:32:42Z" level=warning msg="Spec [  ] failed validation for the following reason: [ Spec [] failed version validation ]. It will not be made available."
>time="2018-08-30T20:32:42Z" level=warning msg="Spec [  ] failed validation for the following reason: [ Spec [] failed version validation ]. It will not be made available."
>time="2018-08-30T20:32:42Z" level=warning msg="Spec [  ] failed validation for the following reason: [ Spec [] failed version validation ]. It will not be made available."
>time="2018-08-30T20:32:42Z" level=warning msg="Spec [  ] failed validation for the following reason: [ Spec [] failed version validation ]. It will not be made available."
>time="2018-08-30T20:32:42Z" level=warning msg="Spec [  ] failed validation for the following reason: [ Spec [] failed version validation ]. It will not be made available."
>time="2018-08-30T20:32:42Z" level=warning msg="13 specs of 13 discovered specs failed validation from registry: openshift-registry"
>time="2018-08-30T20:32:42Z" level=debug msg="spec 'ba9c2d4db404ce97111bea80225de968|dh-rocketchat-apb' needs to be updated"
>time="2018-08-30T20:32:42Z" level=debug msg="spec 'f6c4486b7fb0cdac4b58e193607f7011|dh-mediawiki-apb' needs to be updated"
>time="2018-08-30T20:32:42Z" level=debug msg="spec 'b0dec8711d205cc51d7633eb95c1ec04|dh-bind-apb' needs to be updated"
>time="2018-08-30T20:32:42Z" level=debug msg="spec '2fc891259c84ffd32cb564adf55803f8|dh-catalog-apb' needs to be updated"
>time="2018-08-30T20:32:42Z" level=debug msg="spec '4b12a667a4ffdcaf61a52c7f72c3fe67|dh-redis-apb' needs to be updated"
>time="2018-08-30T20:32:42Z" level=debug msg="spec '1dda1477cace09730bd8ed7a6505607e|dh-postgresql-apb' needs to be updated"
>time="2018-08-30T20:32:42Z" level=info msg="update spec: 4b12a667a4ffdcaf61a52c7f72c3fe67|dh-redis-apb to crd"
>time="2018-08-30T20:32:42Z" level=info msg="update spec: 1dda1477cace09730bd8ed7a6505607e|dh-postgresql-apb to crd"
>time="2018-08-30T20:32:42Z" level=info msg="update spec: ba9c2d4db404ce97111bea80225de968|dh-rocketchat-apb to crd"
>time="2018-08-30T20:32:42Z" level=info msg="update spec: f6c4486b7fb0cdac4b58e193607f7011|dh-mediawiki-apb to crd"
>time="2018-08-30T20:32:42Z" level=info msg="update spec: b0dec8711d205cc51d7633eb95c1ec04|dh-bind-apb to crd"
>time="2018-08-30T20:32:42Z" level=info msg="update spec: 2fc891259c84ffd32cb564adf55803f8|dh-catalog-apb to crd"
>time="2018-08-30T20:32:42Z" level=info msg="Broker successfully bootstrapped"
>time="2018-08-30T20:42:37Z" level=info msg="Broker configured to refresh specs every 10m0s seconds"
>time="2018-08-30T20:42:37Z" level=info msg="Attempting bootstrap at 2018-08-30 20:42:37.787108602 +0000 UTC"
>time="2018-08-30T20:42:37Z" level=info msg="AnsibleBroker::Bootstrap"
>time="2018-08-30T20:42:37Z" level=debug msg="Dao::BatchGetSpecs"
>time="2018-08-30T20:42:37Z" level=debug msg="Dao::BatchGetBundleInstances"
>time="2018-08-30T20:42:37Z" level=debug msg="markedSpecs: map[]\n"
>time="2018-08-30T20:42:37Z" level=info msg="0 specs deleted"
>time="2018-08-30T20:42:37Z" level=debug msg="Dao::BatchGetSpecs"
>time="2018-08-30T20:42:37Z" level=debug msg="converting []*Spec to SpecManifest successful"
>time="2018-08-30T20:42:37Z" level=debug msg="DockerHubAdapter::GetImages"
>time="2018-08-30T20:42:37Z" level=debug msg="BundleSpecLabel: com.redhat.apb.spec"
>time="2018-08-30T20:42:37Z" level=debug msg="Loading image list for org: [ mhrivnak ]"
>time="2018-08-30T20:42:38Z" level=debug msg="Trying to load mhrivnak/pulp-k8s-mongodb"
>time="2018-08-30T20:42:38Z" level=debug msg="Trying to load mhrivnak/bind-apb"
>time="2018-08-30T20:42:38Z" level=debug msg="Trying to load mhrivnak/postgresql-apb"
>time="2018-08-30T20:42:38Z" level=debug msg="Trying to load mhrivnak/mediawiki-apb"
>time="2018-08-30T20:42:38Z" level=debug msg="Trying to load mhrivnak/redis-helm-apb"
>time="2018-08-30T20:42:38Z" level=debug msg="Trying to load mhrivnak/svccatalog-apb"
>time="2018-08-30T20:42:38Z" level=debug msg="Trying to load mhrivnak/rocketchat-apb"
>time="2018-08-30T20:42:38Z" level=debug msg="Trying to load mhrivnak/pulp-k8s-celery"
>time="2018-08-30T20:42:38Z" level=debug msg="Trying to load mhrivnak/pulp-k8s-qpid"
>time="2018-08-30T20:42:38Z" level=debug msg="Trying to load mhrivnak/origin-ansible-service-broker"
>time="2018-08-30T20:42:38Z" level=debug msg="Trying to load mhrivnak/pulp-k8s-httpd"
>time="2018-08-30T20:42:38Z" level=debug msg="Trying to load mhrivnak/pulp-k8s-base"
>time="2018-08-30T20:42:38Z" level=debug msg="Trying to load mhrivnak/bind"
>time="2018-08-30T20:42:38Z" level=debug msg="Trying to load mhrivnak/pulp-k8s-manage"
>time="2018-08-30T20:42:38Z" level=debug msg="Trying to load mhrivnak/staticreg"
>time="2018-08-30T20:42:38Z" level=debug msg="Trying to load mhrivnak/pulp"
>time="2018-08-30T20:42:38Z" level=debug msg="Trying to load mhrivnak/pulp-automation"
>time="2018-08-30T20:42:38Z" level=debug msg="Trying to load mhrivnak/vault"
>time="2018-08-30T20:42:38Z" level=debug msg="Trying to load mhrivnak/helm-ansible-base"
>time="2018-08-30T20:42:38Z" level=debug msg="Trying to load mhrivnak/helm-bundle-base"
>time="2018-08-30T20:42:38Z" level=debug msg="Filter applied against registry: dh"
>time="2018-08-30T20:42:38Z" level=debug msg="Bundles passing white/blacklist filter:"
>time="2018-08-30T20:42:38Z" level=debug msg="-> mhrivnak/postgresql-apb"
>time="2018-08-30T20:42:38Z" level=debug msg="-> mhrivnak/rocketchat-apb"
>time="2018-08-30T20:42:38Z" level=debug msg="-> mhrivnak/bind-apb"
>time="2018-08-30T20:42:38Z" level=debug msg="-> mhrivnak/mediawiki-apb"
>time="2018-08-30T20:42:38Z" level=debug msg="-> mhrivnak/redis-helm-apb"
>time="2018-08-30T20:42:38Z" level=debug msg="-> mhrivnak/svccatalog-apb"
>time="2018-08-30T20:42:38Z" level=info msg="Bundles filtered by white/blacklist filter:\n\t-> mhrivnak/pulp-k8s-mongodb\n\t-> mhrivnak/pulp-k8s-celery\n\t-> mhrivnak/pulp-k8s-qpid\n\t-> mhrivnak/origin-ansible-service-broker\n\t-> mhrivnak/pulp-k8s-httpd\n\t-> mhrivnak/pulp-k8s-base\n\t-> mhrivnak/bind\n\t-> mhrivnak/staticreg\n\t-> mhrivnak/pulp-k8s-manage\n\t-> mhrivnak/pulp\n\t-> mhrivnak/pulp-automation\n\t-> mhrivnak/vault\n\t-> mhrivnak/helm-ansible-base\n\t-> mhrivnak/helm-bundle-base\n"
>time="2018-08-30T20:42:39Z" level=debug msg="Successfully converted Image docker.io/mhrivnak/postgresql-apb:latest into Spec"
>time="2018-08-30T20:42:39Z" level=info msg="adapter::configToSpec -> Image docker.io/mhrivnak/postgresql-apb:latest runtime is 2"
>time="2018-08-30T20:42:39Z" level=debug msg="Successfully converted Image docker.io/mhrivnak/rocketchat-apb:latest into Spec"
>time="2018-08-30T20:42:39Z" level=info msg="adapter::configToSpec -> Image docker.io/mhrivnak/rocketchat-apb:latest runtime is 2"
>time="2018-08-30T20:42:40Z" level=debug msg="Successfully converted Image docker.io/mhrivnak/bind-apb:latest into Spec"
>time="2018-08-30T20:42:40Z" level=info msg="adapter::configToSpec -> Image docker.io/mhrivnak/bind-apb:latest runtime is 2"
>time="2018-08-30T20:42:40Z" level=debug msg="Successfully converted Image docker.io/mhrivnak/mediawiki-apb:latest into Spec"
>time="2018-08-30T20:42:40Z" level=info msg="adapter::configToSpec -> Image docker.io/mhrivnak/mediawiki-apb:latest runtime is 2"
>time="2018-08-30T20:42:40Z" level=debug msg="Successfully converted Image docker.io/mhrivnak/redis-helm-apb:latest into Spec"
>time="2018-08-30T20:42:40Z" level=info msg="adapter::configToSpec -> Image docker.io/mhrivnak/redis-helm-apb:latest runtime is 2"
>time="2018-08-30T20:42:41Z" level=debug msg="Successfully converted Image docker.io/mhrivnak/svccatalog-apb:latest into Spec"
>time="2018-08-30T20:42:41Z" level=info msg="adapter::configToSpec -> Image docker.io/mhrivnak/svccatalog-apb:latest runtime is 2"
>time="2018-08-30T20:42:41Z" level=info msg="Validating specs..."
>time="2018-08-30T20:42:41Z" level=debug msg="Spec [rocketchat-apb] version (1.0) not semver compatible"
>time="2018-08-30T20:42:41Z" level=debug msg="Spec [postgresql-apb] version (1.0) not semver compatible"
>time="2018-08-30T20:42:41Z" level=debug msg="Spec [bind-apb] version (1.0) not semver compatible"
>time="2018-08-30T20:42:41Z" level=debug msg="Spec [mediawiki-apb] version (1.0) not semver compatible"
>time="2018-08-30T20:42:41Z" level=debug msg="Spec [redis-apb] version (1.0) not semver compatible"
>time="2018-08-30T20:42:41Z" level=debug msg="Spec [catalog-apb] version (1.0) not semver compatible"
>time="2018-08-30T20:42:41Z" level=info msg="All specs passed validation!"
>time="2018-08-30T20:42:41Z" level=debug msg="LocalOpenShiftAdapter::GetImageNames"
>time="2018-08-30T20:42:41Z" level=debug msg="BundleSpecLabel: com.redhat.apb.spec"
>time="2018-08-30T20:42:41Z" level=debug msg="Filter applied against registry: localregistry"
>time="2018-08-30T20:42:41Z" level=debug msg="Bundles passing white/blacklist filter:"
>time="2018-08-30T20:42:41Z" level=debug msg="-> openshift/httpd"
>time="2018-08-30T20:42:41Z" level=debug msg="-> openshift/jenkins"
>time="2018-08-30T20:42:41Z" level=debug msg="-> openshift/mysql"
>time="2018-08-30T20:42:41Z" level=debug msg="-> openshift/nodejs"
>time="2018-08-30T20:42:41Z" level=debug msg="-> openshift/php"
>time="2018-08-30T20:42:41Z" level=debug msg="-> openshift/postgresql"
>time="2018-08-30T20:42:41Z" level=debug msg="-> openshift/python"
>time="2018-08-30T20:42:41Z" level=debug msg="-> openshift/ruby"
>time="2018-08-30T20:42:41Z" level=debug msg="-> openshift/mariadb"
>time="2018-08-30T20:42:41Z" level=debug msg="-> openshift/mongodb"
>time="2018-08-30T20:42:41Z" level=debug msg="-> openshift/nginx"
>time="2018-08-30T20:42:41Z" level=debug msg="-> openshift/perl"
>time="2018-08-30T20:42:41Z" level=debug msg="-> openshift/redis"
>time="2018-08-30T20:42:41Z" level=debug msg="LocalOpenShiftAdapter::FetchSpecs"
>time="2018-08-30T20:42:41Z" level=debug msg="No tag specified in config, assuming `latest`"
>time="2018-08-30T20:42:41Z" level=debug msg="LocalOpenShiftAdapter::LoadSpec"
>time="2018-08-30T20:42:41Z" level=info msg="No runtime label found. Set runtime=1. Will use 'exec' to gather bind credentials"
>time="2018-08-30T20:42:41Z" level=debug msg="LocalOpenShiftAdapter::LoadSpec"
>time="2018-08-30T20:42:41Z" level=info msg="No runtime label found. Set runtime=1. Will use 'exec' to gather bind credentials"
>time="2018-08-30T20:42:41Z" level=debug msg="LocalOpenShiftAdapter::LoadSpec"
>time="2018-08-30T20:42:41Z" level=info msg="No runtime label found. Set runtime=1. Will use 'exec' to gather bind credentials"
>time="2018-08-30T20:42:41Z" level=debug msg="LocalOpenShiftAdapter::LoadSpec"
>time="2018-08-30T20:42:41Z" level=info msg="No runtime label found. Set runtime=1. Will use 'exec' to gather bind credentials"
>time="2018-08-30T20:42:41Z" level=debug msg="LocalOpenShiftAdapter::LoadSpec"
>time="2018-08-30T20:42:41Z" level=info msg="No runtime label found. Set runtime=1. Will use 'exec' to gather bind credentials"
>time="2018-08-30T20:42:41Z" level=debug msg="LocalOpenShiftAdapter::LoadSpec"
>time="2018-08-30T20:42:41Z" level=info msg="No runtime label found. Set runtime=1. Will use 'exec' to gather bind credentials"
>time="2018-08-30T20:42:41Z" level=debug msg="LocalOpenShiftAdapter::LoadSpec"
>time="2018-08-30T20:42:41Z" level=info msg="No runtime label found. Set runtime=1. Will use 'exec' to gather bind credentials"
>time="2018-08-30T20:42:41Z" level=debug msg="LocalOpenShiftAdapter::LoadSpec"
>time="2018-08-30T20:42:41Z" level=info msg="No runtime label found. Set runtime=1. Will use 'exec' to gather bind credentials"
>time="2018-08-30T20:42:41Z" level=debug msg="LocalOpenShiftAdapter::LoadSpec"
>time="2018-08-30T20:42:41Z" level=info msg="No runtime label found. Set runtime=1. Will use 'exec' to gather bind credentials"
>time="2018-08-30T20:42:41Z" level=debug msg="LocalOpenShiftAdapter::LoadSpec"
>time="2018-08-30T20:42:41Z" level=info msg="No runtime label found. Set runtime=1. Will use 'exec' to gather bind credentials"
>time="2018-08-30T20:42:41Z" level=debug msg="LocalOpenShiftAdapter::LoadSpec"
>time="2018-08-30T20:42:41Z" level=info msg="No runtime label found. Set runtime=1. Will use 'exec' to gather bind credentials"
>time="2018-08-30T20:42:41Z" level=debug msg="LocalOpenShiftAdapter::LoadSpec"
>time="2018-08-30T20:42:41Z" level=info msg="No runtime label found. Set runtime=1. Will use 'exec' to gather bind credentials"
>time="2018-08-30T20:42:42Z" level=debug msg="LocalOpenShiftAdapter::LoadSpec"
>time="2018-08-30T20:42:42Z" level=info msg="No runtime label found. Set runtime=1. Will use 'exec' to gather bind credentials"
>time="2018-08-30T20:42:42Z" level=info msg="Validating specs..."
>time="2018-08-30T20:42:42Z" level=warning msg="Spec [  ] failed validation for the following reason: [ Spec [] failed version validation ]. It will not be made available."
>time="2018-08-30T20:42:42Z" level=warning msg="Spec [  ] failed validation for the following reason: [ Spec [] failed version validation ]. It will not be made available."
>time="2018-08-30T20:42:42Z" level=warning msg="Spec [  ] failed validation for the following reason: [ Spec [] failed version validation ]. It will not be made available."
>time="2018-08-30T20:42:42Z" level=warning msg="Spec [  ] failed validation for the following reason: [ Spec [] failed version validation ]. It will not be made available."
>time="2018-08-30T20:42:42Z" level=warning msg="Spec [  ] failed validation for the following reason: [ Spec [] failed version validation ]. It will not be made available."
>time="2018-08-30T20:42:42Z" level=warning msg="Spec [  ] failed validation for the following reason: [ Spec [] failed version validation ]. It will not be made available."
>time="2018-08-30T20:42:42Z" level=warning msg="Spec [  ] failed validation for the following reason: [ Spec [] failed version validation ]. It will not be made available."
>time="2018-08-30T20:42:42Z" level=warning msg="Spec [  ] failed validation for the following reason: [ Spec [] failed version validation ]. It will not be made available."
>time="2018-08-30T20:42:42Z" level=warning msg="Spec [  ] failed validation for the following reason: [ Spec [] failed version validation ]. It will not be made available."
>time="2018-08-30T20:42:42Z" level=warning msg="Spec [  ] failed validation for the following reason: [ Spec [] failed version validation ]. It will not be made available."
>time="2018-08-30T20:42:42Z" level=warning msg="Spec [  ] failed validation for the following reason: [ Spec [] failed version validation ]. It will not be made available."
>time="2018-08-30T20:42:42Z" level=warning msg="Spec [  ] failed validation for the following reason: [ Spec [] failed version validation ]. It will not be made available."
>time="2018-08-30T20:42:42Z" level=warning msg="Spec [  ] failed validation for the following reason: [ Spec [] failed version validation ]. It will not be made available."
>time="2018-08-30T20:42:42Z" level=warning msg="13 specs of 13 discovered specs failed validation from registry: openshift-registry"
>time="2018-08-30T20:42:42Z" level=debug msg="spec 'ba9c2d4db404ce97111bea80225de968|dh-rocketchat-apb' needs to be updated"
>time="2018-08-30T20:42:42Z" level=debug msg="spec '1dda1477cace09730bd8ed7a6505607e|dh-postgresql-apb' needs to be updated"
>time="2018-08-30T20:42:42Z" level=debug msg="spec 'b0dec8711d205cc51d7633eb95c1ec04|dh-bind-apb' needs to be updated"
>time="2018-08-30T20:42:42Z" level=debug msg="spec 'f6c4486b7fb0cdac4b58e193607f7011|dh-mediawiki-apb' needs to be updated"
>time="2018-08-30T20:42:42Z" level=debug msg="spec '4b12a667a4ffdcaf61a52c7f72c3fe67|dh-redis-apb' needs to be updated"
>time="2018-08-30T20:42:42Z" level=debug msg="spec '2fc891259c84ffd32cb564adf55803f8|dh-catalog-apb' needs to be updated"
>time="2018-08-30T20:42:42Z" level=info msg="update spec: f6c4486b7fb0cdac4b58e193607f7011|dh-mediawiki-apb to crd"
>time="2018-08-30T20:42:42Z" level=info msg="update spec: 4b12a667a4ffdcaf61a52c7f72c3fe67|dh-redis-apb to crd"
>time="2018-08-30T20:42:42Z" level=info msg="update spec: 2fc891259c84ffd32cb564adf55803f8|dh-catalog-apb to crd"
>time="2018-08-30T20:42:42Z" level=info msg="update spec: ba9c2d4db404ce97111bea80225de968|dh-rocketchat-apb to crd"
>time="2018-08-30T20:42:42Z" level=info msg="update spec: 1dda1477cace09730bd8ed7a6505607e|dh-postgresql-apb to crd"
>time="2018-08-30T20:42:42Z" level=info msg="update spec: b0dec8711d205cc51d7633eb95c1ec04|dh-bind-apb to crd"
>time="2018-08-30T20:42:42Z" level=info msg="Request: \"PUT /osb/v2/service_instances/3da5dced-ac95-11e8-9af4-0242ac11000b?accepts_incomplete=true HTTP/1.1\\r\\nHost: broker.automation-broker.svc:1338\\r\\nAccept-Encoding: gzip\\r\\nContent-Length: 448\\r\\nContent-Type: application/json\\r\\nUser-Agent: Go-http-client/1.1\\r\\nX-Broker-Api-Originating-Identity: kubernetes eyJ1c2VybmFtZSI6ImFkbWluIiwidWlkIjoiIiwiZ3JvdXBzIjpbInN5c3RlbTphdXRoZW50aWNhdGVkOm9hdXRoIiwic3lzdGVtOmF1dGhlbnRpY2F0ZWQiXSwiZXh0cmEiOnsic2NvcGVzLmF1dGhvcml6YXRpb24ub3BlbnNoaWZ0LmlvIjpbInVzZXI6ZnVsbCJdfX0=\\r\\nX-Broker-Api-Version: 2.13\\r\\n\\r\\n{\\\"service_id\\\":\\\"1dda1477cace09730bd8ed7a6505607e\\\",\\\"plan_id\\\":\\\"7f4a5e35e4af2beb70076e72fab0b7ff\\\",\\\"organization_guid\\\":\\\"fc5f90ca-ac94-11e8-9c73-c85b76145add\\\",\\\"space_guid\\\":\\\"fc5f90ca-ac94-11e8-9c73-c85b76145add\\\",\\\"parameters\\\":{\\\"postgresql_database\\\":\\\"admin\\\",\\\"postgresql_password\\\":\\\"password\\\",\\\"postgresql_user\\\":\\\"admin\\\",\\\"postgresql_version\\\":\\\"9.6\\\"},\\\"context\\\":{\\\"clusterid\\\":\\\"cb1c2980-ac83-11e8-8099-0242ac11000a\\\",\\\"namespace\\\":\\\"async-jian\\\",\\\"platform\\\":\\\"kubernetes\\\"}}\""
>time="2018-08-30T20:42:42Z" level=debug msg="Auto Escalate has been set to true, we are escalating permissions"
>time="2018-08-30T20:42:42Z" level=debug msg="get spec: 1dda1477cace09730bd8ed7a6505607e"
>time="2018-08-30T20:42:42Z" level=info msg="Broker successfully bootstrapped"
>time="2018-08-30T20:42:42Z" level=debug msg="Injecting PlanID as parameter: { _apb_plan_id: dev }"
>time="2018-08-30T20:42:42Z" level=debug msg="Injecting ServiceClassID as parameter: { _apb_service_class_id: 1dda1477cace09730bd8ed7a6505607e }"
>time="2018-08-30T20:42:42Z" level=debug msg="Injecting ServiceInstanceID as parameter: { _apb_service_instance_id: 3da5dced-ac95-11e8-9af4-0242ac11000b }"
>time="2018-08-30T20:42:42Z" level=debug msg="Injecting lastRequestingUserKey as parameter: { _apb_last_requesting_user: admin }"
>time="2018-08-30T20:42:42Z" level=debug msg="get service instance: 3da5dced-ac95-11e8-9af4-0242ac11000b"
>time="2018-08-30T20:42:42Z" level=debug msg="set service instance: 3da5dced-ac95-11e8-9af4-0242ac11000b"
>time="2018-08-30T20:42:43Z" level=info msg="ASYNC provisioning in progress"
>time="2018-08-30T20:42:43Z" level=debug msg="set job state for instance: 3da5dced-ac95-11e8-9af4-0242ac11000b token: e8ceb27a-a3f1-4108-b808-a4033f234ed8"
>172.17.0.10 - - [30/Aug/2018:20:42:42 +0000] "PUT /osb/v2/service_instances/3da5dced-ac95-11e8-9af4-0242ac11000b?accepts_incomplete=true HTTP/1.1" 202 58
>time="2018-08-30T20:42:43Z" level=info msg="============================================================"
>time="2018-08-30T20:42:43Z" level=info msg="                       PROVISIONING                         "
>time="2018-08-30T20:42:43Z" level=info msg="============================================================"
>time="2018-08-30T20:42:43Z" level=info msg="Spec.ID: 1dda1477cace09730bd8ed7a6505607e"
>time="2018-08-30T20:42:43Z" level=info msg="Spec.Name: dh-postgresql-apb"
>time="2018-08-30T20:42:43Z" level=info msg="Spec.Image: docker.io/mhrivnak/postgresql-apb:latest"
>time="2018-08-30T20:42:43Z" level=info msg="Spec.Description: SCL PostgreSQL apb implementation"
>time="2018-08-30T20:42:43Z" level=info msg="============================================================"
>time="2018-08-30T20:42:43Z" level=debug msg="executor::actionStarted"
>time="2018-08-30T20:42:43Z" level=debug msg="JobStateSubscriber Notify : msg state {e8ceb27a-a3f1-4108-b808-a4033f234ed8 in progress  provision  action started} "
>time="2018-08-30T20:42:43Z" level=debug msg="set job state for instance: 3da5dced-ac95-11e8-9af4-0242ac11000b token: e8ceb27a-a3f1-4108-b808-a4033f234ed8"
>time="2018-08-30T20:42:43Z" level=debug msg="Creating network policy for pod: bundle-7d2e7370-47eb-4740-865d-a289ea688acd to grant network access to ns: async-jian"
>time="2018-08-30T20:42:43Z" level=debug msg="Successfully created network policy for pod: bundle-7d2e7370-47eb-4740-865d-a289ea688acd to grant network access to ns: async-jian"
>time="2018-08-30T20:42:43Z" level=debug msg="Trying to create apb sandbox: [ bundle-7d2e7370-47eb-4740-865d-a289ea688acd ], with edit permissions in namespace dh-postgresql-apb-prov-s6zqf"
>time="2018-08-30T20:42:43Z" level=info msg="Creating RoleBinding bundle-7d2e7370-47eb-4740-865d-a289ea688acd"
>time="2018-08-30T20:42:43Z" level=info msg="Creating RoleBinding bundle-7d2e7370-47eb-4740-865d-a289ea688acd"
>time="2018-08-30T20:42:43Z" level=info msg="Successfully created apb sandbox: [ bundle-7d2e7370-47eb-4740-865d-a289ea688acd ], with edit permissions in namespace [ dh-postgresql-apb-prov-s6zqf ]"
>time="2018-08-30T20:42:43Z" level=debug msg="Running post create sandbox functions if defined."
>time="2018-08-30T20:42:43Z" level=debug msg="ExecutingApb:"
>time="2018-08-30T20:42:43Z" level=debug msg="name:[ dh-postgresql-apb ]"
>time="2018-08-30T20:42:43Z" level=debug msg="image:[ docker.io/mhrivnak/postgresql-apb:latest ]"
>time="2018-08-30T20:42:43Z" level=debug msg="action:[ provision ]"
>time="2018-08-30T20:42:43Z" level=debug msg="pullPolicy:[ IfNotPresent ]"
>time="2018-08-30T20:42:43Z" level=debug msg="role:[ edit ]"
>time="2018-08-30T20:42:43Z" level=debug msg="No proxy env vars found to be configured."
>client returned err  configmaps "3da5dced-ac95-11e8-9af4-0242ac11000b-state" not found
>time="2018-08-30T20:42:43Z" level=info msg="Creating pod \"bundle-7d2e7370-47eb-4740-865d-a289ea688acd\" in the dh-postgresql-apb-prov-s6zqf namespace"
>time="2018-08-30T20:42:44Z" level=debug msg="watching pod for serviceinstance &bundle.Spec{ID:\"1dda1477cace09730bd8ed7a6505607e\", Runtime:2, Version:\"1.0\", FQName:\"dh-postgresql-apb\", Image:\"docker.io/mhrivnak/postgresql-apb:latest\", Tags:[]string{\"database\", \"postgresql\"}, Bindable:true, Description:\"SCL PostgreSQL apb implementation\", Metadata:map[string]interface {}{\"documentationUrl\":\"https://www.postgresql.org/docs/\", \"longDescription\":\"An apb that deploys postgresql 9.4, 9.5, or 9.6.\", \"providerDisplayName\":\"Red Hat, Inc.\", \"console.openshift.io/iconClass\":\"icon-postgresql\", \"dependencies\":[]interface {}{\"registry.access.redhat.com/rhscl/postgresql-94-rhel7\", \"registry.access.redhat.com/rhscl/postgresql-95-rhel7\", \"registry.access.redhat.com/rhscl/postgresql-96-rhel7\"}, \"displayName\":\"PostgreSQL (APB)\"}, Async:\"optional\", Plans:[]bundle.Plan{bundle.Plan{ID:\"7f4a5e35e4af2beb70076e72fab0b7ff\", Name:\"dev\", Description:\"A single DB server with no storage\", Metadata:map[string]interface {}{\"cost\":\"$0.00\", \"displayName\":\"Development\", \"longDescription\":\"This plan provides a single non-HA PostgreSQL server without persistent storage\"}, Free:true, Bindable:false, Parameters:[]bundle.ParameterDescriptor{bundle.ParameterDescriptor{Name:\"postgresql_database\", Title:\"PostgreSQL Database Name\", Type:\"string\", Description:\"\", Default:\"admin\", DeprecatedMaxlength:0, MaxLength:0, MinLength:0, Pattern:\"^[a-zA-Z_][a-zA-Z0-9_]*$\", MultipleOf:0, Maximum:(*bundle.NilableNumber)(nil), ExclusiveMaximum:(*bundle.NilableNumber)(nil), Minimum:(*bundle.NilableNumber)(nil), ExclusiveMinimum:(*bundle.NilableNumber)(nil), Enum:[]string(nil), Required:true, Updatable:false, DisplayType:\"\", DisplayGroup:\"\", Dependencies:[]bundle.Dependency(nil)}, bundle.ParameterDescriptor{Name:\"postgresql_user\", Title:\"PostgreSQL User\", Type:\"string\", Description:\"\", Default:\"admin\", DeprecatedMaxlength:63, MaxLength:0, MinLength:0, Pattern:\"^[a-zA-Z_][a-zA-Z0-9_]*$\", MultipleOf:0, Maximum:(*bundle.NilableNumber)(nil), ExclusiveMaximum:(*bundle.NilableNumber)(nil), Minimum:(*bundle.NilableNumber)(nil), ExclusiveMinimum:(*bundle.NilableNumber)(nil), Enum:[]string(nil), Required:true, Updatable:false, DisplayType:\"\", DisplayGroup:\"\", Dependencies:[]bundle.Dependency(nil)}, bundle.ParameterDescriptor{Name:\"postgresql_password\", Title:\"PostgreSQL Password\", Type:\"string\", Description:\"\", Default:interface {}(nil), DeprecatedMaxlength:0, MaxLength:0, MinLength:0, Pattern:\"^[a-zA-Z0-9_~!@#$%^&*()-=<>,.?;:|]+$\", MultipleOf:0, Maximum:(*bundle.NilableNumber)(nil), ExclusiveMaximum:(*bundle.NilableNumber)(nil), Minimum:(*bundle.NilableNumber)(nil), ExclusiveMinimum:(*bundle.NilableNumber)(nil), Enum:[]string(nil), Required:true, Updatable:false, DisplayType:\"password\", DisplayGroup:\"\", Dependencies:[]bundle.Dependency(nil)}, bundle.ParameterDescriptor{Name:\"postgresql_version\", Title:\"PostgreSQL Version\", Type:\"enum\", Description:\"\", Default:\"9.6\", DeprecatedMaxlength:0, MaxLength:0, MinLength:0, Pattern:\"\", MultipleOf:0, Maximum:(*bundle.NilableNumber)(nil), ExclusiveMaximum:(*bundle.NilableNumber)(nil), Minimum:(*bundle.NilableNumber)(nil), ExclusiveMinimum:(*bundle.NilableNumber)(nil), Enum:[]string{\"9.6\", \"9.5\", \"9.4\"}, Required:true, Updatable:true, DisplayType:\"\", DisplayGroup:\"\", Dependencies:[]bundle.Dependency(nil)}}, BindParameters:[]bundle.ParameterDescriptor{}, UpdatesTo:[]string{\"prod\"}}, bundle.Plan{ID:\"ea4c99bb7d7d0d492ce55a8ac8c75373\", Name:\"prod\", Description:\"A single DB server with persistent storage\", Metadata:map[string]interface {}{\"cost\":\"$0.00\", \"displayName\":\"Production\", \"longDescription\":\"This plan provides a single non-HA PostgreSQL server with persistent storage\"}, Free:true, Bindable:false, Parameters:[]bundle.ParameterDescriptor{bundle.ParameterDescriptor{Name:\"postgresql_database\", Title:\"PostgreSQL Database Name\", Type:\"string\", Description:\"\", Default:\"admin\", DeprecatedMaxlength:0, MaxLength:0, MinLength:0, Pattern:\"^[a-zA-Z_][a-zA-Z0-9_]*$\", MultipleOf:0, Maximum:(*bundle.NilableNumber)(nil), ExclusiveMaximum:(*bundle.NilableNumber)(nil), Minimum:(*bundle.NilableNumber)(nil), ExclusiveMinimum:(*bundle.NilableNumber)(nil), Enum:[]string(nil), Required:true, Updatable:false, DisplayType:\"\", DisplayGroup:\"\", Dependencies:[]bundle.Dependency(nil)}, bundle.ParameterDescriptor{Name:\"postgresql_user\", Title:\"PostgreSQL User\", Type:\"string\", Description:\"\", Default:\"admin\", DeprecatedMaxlength:63, MaxLength:0, MinLength:0, Pattern:\"^[a-zA-Z_][a-zA-Z0-9_]*$\", MultipleOf:0, Maximum:(*bundle.NilableNumber)(nil), ExclusiveMaximum:(*bundle.NilableNumber)(nil), Minimum:(*bundle.NilableNumber)(nil), ExclusiveMinimum:(*bundle.NilableNumber)(nil), Enum:[]string(nil), Required:true, Updatable:false, DisplayType:\"\", DisplayGroup:\"\", Dependencies:[]bundle.Dependency(nil)}, bundle.ParameterDescriptor{Name:\"postgresql_password\", Title:\"PostgreSQL Password\", Type:\"string\", Description:\"\", Default:interface {}(nil), DeprecatedMaxlength:0, MaxLength:0, MinLength:0, Pattern:\"^[a-zA-Z0-9_~!@#$%^&*()-=<>,.?;:|]+$\", MultipleOf:0, Maximum:(*bundle.NilableNumber)(nil), ExclusiveMaximum:(*bundle.NilableNumber)(nil), Minimum:(*bundle.NilableNumber)(nil), ExclusiveMinimum:(*bundle.NilableNumber)(nil), Enum:[]string(nil), Required:true, Updatable:false, DisplayType:\"password\", DisplayGroup:\"\", Dependencies:[]bundle.Dependency(nil)}, bundle.ParameterDescriptor{Name:\"postgresql_version\", Title:\"PostgreSQL Version\", Type:\"enum\", Description:\"\", Default:\"9.6\", DeprecatedMaxlength:0, MaxLength:0, MinLength:0, Pattern:\"\", MultipleOf:0, Maximum:(*bundle.NilableNumber)(nil), ExclusiveMaximum:(*bundle.NilableNumber)(nil), Minimum:(*bundle.NilableNumber)(nil), ExclusiveMinimum:(*bundle.NilableNumber)(nil), Enum:[]string{\"9.6\", \"9.5\", \"9.4\"}, Required:true, Updatable:true, DisplayType:\"\", DisplayGroup:\"\", Dependencies:[]bundle.Dependency(nil)}}, BindParameters:[]bundle.ParameterDescriptor{}, UpdatesTo:[]string{\"dev\"}}}, Alpha:map[string]interface {}(nil), Delete:false}"
>time="2018-08-30T20:42:44Z" level=debug msg="Watching pod [ bundle-7d2e7370-47eb-4740-865d-a289ea688acd ] in namespace [ dh-postgresql-apb-prov-s6zqf ] for completion"
>time="2018-08-30T20:42:44Z" level=debug msg="pod [bundle-7d2e7370-47eb-4740-865d-a289ea688acd] in phase Pending"
>time="2018-08-30T20:42:44Z" level=debug msg="Pod [ bundle-7d2e7370-47eb-4740-865d-a289ea688acd ] Pending"
>time="2018-08-30T20:42:44Z" level=debug msg="pod [bundle-7d2e7370-47eb-4740-865d-a289ea688acd] in phase Pending"
>time="2018-08-30T20:42:44Z" level=debug msg="Pod [ bundle-7d2e7370-47eb-4740-865d-a289ea688acd ] Pending"
>time="2018-08-30T20:42:44Z" level=info msg="Request: \"GET /osb/v2/service_instances/3da5dced-ac95-11e8-9af4-0242ac11000b/last_operation?operation=e8ceb27a-a3f1-4108-b808-a4033f234ed8&plan_id=7f4a5e35e4af2beb70076e72fab0b7ff&service_id=1dda1477cace09730bd8ed7a6505607e HTTP/1.1\\r\\nHost: broker.automation-broker.svc:1338\\r\\nAccept-Encoding: gzip\\r\\nUser-Agent: Go-http-client/1.1\\r\\nX-Broker-Api-Originating-Identity: kubernetes eyJ1c2VybmFtZSI6ImFkbWluIiwidWlkIjoiIiwiZ3JvdXBzIjpbInN5c3RlbTphdXRoZW50aWNhdGVkOm9hdXRoIiwic3lzdGVtOmF1dGhlbnRpY2F0ZWQiXSwiZXh0cmEiOnsic2NvcGVzLmF1dGhvcml6YXRpb24ub3BlbnNoaWZ0LmlvIjpbInVzZXI6ZnVsbCJdfX0=\\r\\nX-Broker-Api-Version: 2.13\\r\\n\\r\\n\""
>time="2018-08-30T20:42:44Z" level=debug msg="service_id: 1dda1477cace09730bd8ed7a6505607e"
>time="2018-08-30T20:42:44Z" level=debug msg="plan_id: 7f4a5e35e4af2beb70076e72fab0b7ff"
>time="2018-08-30T20:42:44Z" level=debug msg="operation:  e8ceb27a-a3f1-4108-b808-a4033f234ed8"
>time="2018-08-30T20:42:44Z" level=debug msg="state: in progress"
>time="2018-08-30T20:42:44Z" level=debug msg="description: action started"
>172.17.0.10 - - [30/Aug/2018:20:42:44 +0000] "GET /osb/v2/service_instances/3da5dced-ac95-11e8-9af4-0242ac11000b/last_operation?operation=e8ceb27a-a3f1-4108-b808-a4033f234ed8&plan_id=7f4a5e35e4af2beb70076e72fab0b7ff&service_id=1dda1477cace09730bd8ed7a6505607e HTTP/1.1" 200 64
>time="2018-08-30T20:42:46Z" level=info msg="Request: \"GET /osb/v2/service_instances/3da5dced-ac95-11e8-9af4-0242ac11000b/last_operation?operation=e8ceb27a-a3f1-4108-b808-a4033f234ed8&plan_id=7f4a5e35e4af2beb70076e72fab0b7ff&service_id=1dda1477cace09730bd8ed7a6505607e HTTP/1.1\\r\\nHost: broker.automation-broker.svc:1338\\r\\nAccept-Encoding: gzip\\r\\nUser-Agent: Go-http-client/1.1\\r\\nX-Broker-Api-Originating-Identity: kubernetes eyJ1c2VybmFtZSI6ImFkbWluIiwidWlkIjoiIiwiZ3JvdXBzIjpbInN5c3RlbTphdXRoZW50aWNhdGVkOm9hdXRoIiwic3lzdGVtOmF1dGhlbnRpY2F0ZWQiXSwiZXh0cmEiOnsic2NvcGVzLmF1dGhvcml6YXRpb24ub3BlbnNoaWZ0LmlvIjpbInVzZXI6ZnVsbCJdfX0=\\r\\nX-Broker-Api-Version: 2.13\\r\\n\\r\\n\""
>time="2018-08-30T20:42:46Z" level=debug msg="service_id: 1dda1477cace09730bd8ed7a6505607e"
>time="2018-08-30T20:42:46Z" level=debug msg="plan_id: 7f4a5e35e4af2beb70076e72fab0b7ff"
>time="2018-08-30T20:42:46Z" level=debug msg="operation:  e8ceb27a-a3f1-4108-b808-a4033f234ed8"
>time="2018-08-30T20:42:46Z" level=debug msg="state: in progress"
>time="2018-08-30T20:42:46Z" level=debug msg="description: action started"
>172.17.0.10 - - [30/Aug/2018:20:42:46 +0000] "GET /osb/v2/service_instances/3da5dced-ac95-11e8-9af4-0242ac11000b/last_operation?operation=e8ceb27a-a3f1-4108-b808-a4033f234ed8&plan_id=7f4a5e35e4af2beb70076e72fab0b7ff&service_id=1dda1477cace09730bd8ed7a6505607e HTTP/1.1" 200 64
>time="2018-08-30T20:42:50Z" level=info msg="Request: \"GET /osb/v2/service_instances/3da5dced-ac95-11e8-9af4-0242ac11000b/last_operation?operation=e8ceb27a-a3f1-4108-b808-a4033f234ed8&plan_id=7f4a5e35e4af2beb70076e72fab0b7ff&service_id=1dda1477cace09730bd8ed7a6505607e HTTP/1.1\\r\\nHost: broker.automation-broker.svc:1338\\r\\nAccept-Encoding: gzip\\r\\nUser-Agent: Go-http-client/1.1\\r\\nX-Broker-Api-Originating-Identity: kubernetes eyJ1c2VybmFtZSI6ImFkbWluIiwidWlkIjoiIiwiZ3JvdXBzIjpbInN5c3RlbTphdXRoZW50aWNhdGVkOm9hdXRoIiwic3lzdGVtOmF1dGhlbnRpY2F0ZWQiXSwiZXh0cmEiOnsic2NvcGVzLmF1dGhvcml6YXRpb24ub3BlbnNoaWZ0LmlvIjpbInVzZXI6ZnVsbCJdfX0=\\r\\nX-Broker-Api-Version: 2.13\\r\\n\\r\\n\""
>time="2018-08-30T20:42:50Z" level=debug msg="service_id: 1dda1477cace09730bd8ed7a6505607e"
>time="2018-08-30T20:42:50Z" level=debug msg="plan_id: 7f4a5e35e4af2beb70076e72fab0b7ff"
>time="2018-08-30T20:42:50Z" level=debug msg="operation:  e8ceb27a-a3f1-4108-b808-a4033f234ed8"
>time="2018-08-30T20:42:50Z" level=debug msg="state: in progress"
>time="2018-08-30T20:42:50Z" level=debug msg="description: action started"
>172.17.0.10 - - [30/Aug/2018:20:42:50 +0000] "GET /osb/v2/service_instances/3da5dced-ac95-11e8-9af4-0242ac11000b/last_operation?operation=e8ceb27a-a3f1-4108-b808-a4033f234ed8&plan_id=7f4a5e35e4af2beb70076e72fab0b7ff&service_id=1dda1477cace09730bd8ed7a6505607e HTTP/1.1" 200 64
>time="2018-08-30T20:42:58Z" level=info msg="Request: \"GET /osb/v2/service_instances/3da5dced-ac95-11e8-9af4-0242ac11000b/last_operation?operation=e8ceb27a-a3f1-4108-b808-a4033f234ed8&plan_id=7f4a5e35e4af2beb70076e72fab0b7ff&service_id=1dda1477cace09730bd8ed7a6505607e HTTP/1.1\\r\\nHost: broker.automation-broker.svc:1338\\r\\nAccept-Encoding: gzip\\r\\nUser-Agent: Go-http-client/1.1\\r\\nX-Broker-Api-Originating-Identity: kubernetes eyJ1c2VybmFtZSI6ImFkbWluIiwidWlkIjoiIiwiZ3JvdXBzIjpbInN5c3RlbTphdXRoZW50aWNhdGVkOm9hdXRoIiwic3lzdGVtOmF1dGhlbnRpY2F0ZWQiXSwiZXh0cmEiOnsic2NvcGVzLmF1dGhvcml6YXRpb24ub3BlbnNoaWZ0LmlvIjpbInVzZXI6ZnVsbCJdfX0=\\r\\nX-Broker-Api-Version: 2.13\\r\\n\\r\\n\""
>time="2018-08-30T20:42:58Z" level=debug msg="service_id: 1dda1477cace09730bd8ed7a6505607e"
>time="2018-08-30T20:42:58Z" level=debug msg="plan_id: 7f4a5e35e4af2beb70076e72fab0b7ff"
>time="2018-08-30T20:42:58Z" level=debug msg="operation:  e8ceb27a-a3f1-4108-b808-a4033f234ed8"
>time="2018-08-30T20:42:58Z" level=debug msg="state: in progress"
>time="2018-08-30T20:42:58Z" level=debug msg="description: action started"
>172.17.0.10 - - [30/Aug/2018:20:42:58 +0000] "GET /osb/v2/service_instances/3da5dced-ac95-11e8-9af4-0242ac11000b/last_operation?operation=e8ceb27a-a3f1-4108-b808-a4033f234ed8&plan_id=7f4a5e35e4af2beb70076e72fab0b7ff&service_id=1dda1477cace09730bd8ed7a6505607e HTTP/1.1" 200 64
>time="2018-08-30T20:43:14Z" level=info msg="Request: \"GET /osb/v2/service_instances/3da5dced-ac95-11e8-9af4-0242ac11000b/last_operation?operation=e8ceb27a-a3f1-4108-b808-a4033f234ed8&plan_id=7f4a5e35e4af2beb70076e72fab0b7ff&service_id=1dda1477cace09730bd8ed7a6505607e HTTP/1.1\\r\\nHost: broker.automation-broker.svc:1338\\r\\nAccept-Encoding: gzip\\r\\nUser-Agent: Go-http-client/1.1\\r\\nX-Broker-Api-Originating-Identity: kubernetes eyJ1c2VybmFtZSI6ImFkbWluIiwidWlkIjoiIiwiZ3JvdXBzIjpbInN5c3RlbTphdXRoZW50aWNhdGVkOm9hdXRoIiwic3lzdGVtOmF1dGhlbnRpY2F0ZWQiXSwiZXh0cmEiOnsic2NvcGVzLmF1dGhvcml6YXRpb24ub3BlbnNoaWZ0LmlvIjpbInVzZXI6ZnVsbCJdfX0=\\r\\nX-Broker-Api-Version: 2.13\\r\\n\\r\\n\""
>time="2018-08-30T20:43:14Z" level=debug msg="service_id: 1dda1477cace09730bd8ed7a6505607e"
>time="2018-08-30T20:43:14Z" level=debug msg="plan_id: 7f4a5e35e4af2beb70076e72fab0b7ff"
>time="2018-08-30T20:43:14Z" level=debug msg="operation:  e8ceb27a-a3f1-4108-b808-a4033f234ed8"
>time="2018-08-30T20:43:14Z" level=debug msg="state: in progress"
>time="2018-08-30T20:43:14Z" level=debug msg="description: action started"
>172.17.0.10 - - [30/Aug/2018:20:43:14 +0000] "GET /osb/v2/service_instances/3da5dced-ac95-11e8-9af4-0242ac11000b/last_operation?operation=e8ceb27a-a3f1-4108-b808-a4033f234ed8&plan_id=7f4a5e35e4af2beb70076e72fab0b7ff&service_id=1dda1477cace09730bd8ed7a6505607e HTTP/1.1" 200 64
>time="2018-08-30T20:43:15Z" level=debug msg="pod [bundle-7d2e7370-47eb-4740-865d-a289ea688acd] in phase Running"
>time="2018-08-30T20:43:15Z" level=debug msg="Pod [ bundle-7d2e7370-47eb-4740-865d-a289ea688acd ] Running"
>time="2018-08-30T20:43:21Z" level=debug msg="pod [bundle-7d2e7370-47eb-4740-865d-a289ea688acd] in phase Succeeded"
>time="2018-08-30T20:43:21Z" level=debug msg="Pod [ bundle-7d2e7370-47eb-4740-865d-a289ea688acd ] completed"
>time="2018-08-30T20:43:21Z" level=debug msg="state: copying state from namespace dh-postgresql-apb-prov-s6zqf to ns automation-broker from name bundle-7d2e7370-47eb-4740-865d-a289ea688acd to name 3da5dced-ac95-11e8-9af4-0242ac11000b-state"
>time="2018-08-30T20:43:21Z" level=debug msg="no state configmap found to copy"
>time="2018-08-30T20:43:21Z" level=debug msg="Found secret with name bundle-7d2e7370-47eb-4740-865d-a289ea688acd\n"
>time="2018-08-30T20:43:21Z" level=info msg="Destroying APB sandbox..."
>time="2018-08-30T20:43:21Z" level=debug msg="Deleting namespace dh-postgresql-apb-prov-s6zqf"
>time="2018-08-30T20:43:21Z" level=debug msg="Deleting rolebinding bundle-7d2e7370-47eb-4740-865d-a289ea688acd, namespace dh-postgresql-apb-prov-s6zqf"
>time="2018-08-30T20:43:21Z" level=info msg="Successfully deleted rolebinding bundle-7d2e7370-47eb-4740-865d-a289ea688acd, namespace dh-postgresql-apb-prov-s6zqf"
>time="2018-08-30T20:43:21Z" level=debug msg="Deleting rolebinding bundle-7d2e7370-47eb-4740-865d-a289ea688acd, namespace async-jian"
>time="2018-08-30T20:43:21Z" level=info msg="Successfully deleted rolebinding bundle-7d2e7370-47eb-4740-865d-a289ea688acd, namespace async-jian"
>time="2018-08-30T20:43:21Z" level=debug msg="Deleting network policy for pod: bundle-7d2e7370-47eb-4740-865d-a289ea688acd to grant network access to ns: async-jian"
>time="2018-08-30T20:43:21Z" level=debug msg="Successfully deleted network policy for pod: bundle-7d2e7370-47eb-4740-865d-a289ea688acd to grant network access to ns: async-jian"
>time="2018-08-30T20:43:21Z" level=debug msg="Running post sandbox destroy hooks"
>time="2018-08-30T20:43:21Z" level=debug msg="executor::actionFinishedWithSuccess"
>time="2018-08-30T20:43:21Z" level=debug msg="closing channel for job e8ceb27a-a3f1-4108-b808-a4033f234ed8"
>time="2018-08-30T20:43:21Z" level=debug msg="JobStateSubscriber Notify : msg state {e8ceb27a-a3f1-4108-b808-a4033f234ed8 succeeded  provision  provision job completed} "
>time="2018-08-30T20:43:21Z" level=debug msg="set job state for instance: 3da5dced-ac95-11e8-9af4-0242ac11000b token: e8ceb27a-a3f1-4108-b808-a4033f234ed8"
>time="2018-08-30T20:43:21Z" level=debug msg="JobStateSubscriber handleSucceeded : msg state {e8ceb27a-a3f1-4108-b808-a4033f234ed8 succeeded  provision  provision job completed} "
>time="2018-08-30T20:43:46Z" level=info msg="Request: \"GET /osb/v2/service_instances/3da5dced-ac95-11e8-9af4-0242ac11000b/last_operation?operation=e8ceb27a-a3f1-4108-b808-a4033f234ed8&plan_id=7f4a5e35e4af2beb70076e72fab0b7ff&service_id=1dda1477cace09730bd8ed7a6505607e HTTP/1.1\\r\\nHost: broker.automation-broker.svc:1338\\r\\nAccept-Encoding: gzip\\r\\nUser-Agent: Go-http-client/1.1\\r\\nX-Broker-Api-Originating-Identity: kubernetes eyJ1c2VybmFtZSI6ImFkbWluIiwidWlkIjoiIiwiZ3JvdXBzIjpbInN5c3RlbTphdXRoZW50aWNhdGVkOm9hdXRoIiwic3lzdGVtOmF1dGhlbnRpY2F0ZWQiXSwiZXh0cmEiOnsic2NvcGVzLmF1dGhvcml6YXRpb24ub3BlbnNoaWZ0LmlvIjpbInVzZXI6ZnVsbCJdfX0=\\r\\nX-Broker-Api-Version: 2.13\\r\\n\\r\\n\""
>time="2018-08-30T20:43:46Z" level=debug msg="service_id: 1dda1477cace09730bd8ed7a6505607e"
>time="2018-08-30T20:43:46Z" level=debug msg="plan_id: 7f4a5e35e4af2beb70076e72fab0b7ff"
>time="2018-08-30T20:43:46Z" level=debug msg="operation:  e8ceb27a-a3f1-4108-b808-a4033f234ed8"
>time="2018-08-30T20:43:46Z" level=debug msg="state: succeeded"
>time="2018-08-30T20:43:46Z" level=debug msg="description: provision job completed"
>172.17.0.10 - - [30/Aug/2018:20:43:46 +0000] "GET /osb/v2/service_instances/3da5dced-ac95-11e8-9af4-0242ac11000b/last_operation?operation=e8ceb27a-a3f1-4108-b808-a4033f234ed8&plan_id=7f4a5e35e4af2beb70076e72fab0b7ff&service_id=1dda1477cace09730bd8ed7a6505607e HTTP/1.1" 200 71
>time="2018-08-30T20:43:56Z" level=info msg="Request: \"PUT /osb/v2/service_instances/3da5dced-ac95-11e8-9af4-0242ac11000b/service_bindings/5deb057e-ac95-11e8-9af4-0242ac11000b?accepts_incomplete=true HTTP/1.1\\r\\nHost: broker.automation-broker.svc:1338\\r\\nAccept-Encoding: gzip\\r\\nContent-Length: 162\\r\\nContent-Type: application/json\\r\\nUser-Agent: Go-http-client/1.1\\r\\nX-Broker-Api-Originating-Identity: kubernetes eyJ1c2VybmFtZSI6ImFkbWluIiwidWlkIjoiIiwiZ3JvdXBzIjpbInN5c3RlbTphdXRoZW50aWNhdGVkOm9hdXRoIiwic3lzdGVtOmF1dGhlbnRpY2F0ZWQiXSwiZXh0cmEiOnsic2NvcGVzLmF1dGhvcml6YXRpb24ub3BlbnNoaWZ0LmlvIjpbInVzZXI6ZnVsbCJdfX0=\\r\\nX-Broker-Api-Version: 2.13\\r\\n\\r\\n{\\\"service_id\\\":\\\"1dda1477cace09730bd8ed7a6505607e\\\",\\\"plan_id\\\":\\\"7f4a5e35e4af2beb70076e72fab0b7ff\\\",\\\"bind_resource\\\":{\\\"app_guid\\\":\\\"fc5f90ca-ac94-11e8-9c73-c85b76145add\\\"}}\""
>time="2018-08-30T20:43:56Z" level=debug msg="get service instance: 3da5dced-ac95-11e8-9af4-0242ac11000b"
>time="2018-08-30T20:43:56Z" level=debug msg="get spec: 1dda1477cace09730bd8ed7a6505607e"
>time="2018-08-30T20:43:56Z" level=debug msg="Auto Escalate has been set to true, we are escalating permissions"
>time="2018-08-30T20:43:56Z" level=debug msg="Injecting PlanID as parameter: { _apb_plan_id: dev }"
>time="2018-08-30T20:43:56Z" level=debug msg="Injecting ServiceClassID as parameter: { _apb_service_class_id: 1dda1477cace09730bd8ed7a6505607e }"
>time="2018-08-30T20:43:56Z" level=debug msg="Injecting ServiceInstanceID as parameter: { _apb_service_instance_id: 3da5dced-ac95-11e8-9af4-0242ac11000b }"
>time="2018-08-30T20:43:56Z" level=debug msg="Injecting lastRequestingUserKey as parameter: { _apb_last_requesting_user: admin }"
>time="2018-08-30T20:43:56Z" level=debug msg="Injecting ServiceBindingID as parameter: { _apb_service_binding_id: 5deb057e-ac95-11e8-9af4-0242ac11000b }"
>time="2018-08-30T20:43:56Z" level=debug msg="Found secret with name 3da5dced-ac95-11e8-9af4-0242ac11000b\n"
>time="2018-08-30T20:43:56Z" level=debug msg="get binding instance: 5deb057e-ac95-11e8-9af4-0242ac11000b"
>time="2018-08-30T20:43:56Z" level=debug msg="set binding instance: 5deb057e-ac95-11e8-9af4-0242ac11000b"
>time="2018-08-30T20:43:56Z" level=info msg="ASYNC binding in progress"
>time="2018-08-30T20:43:56Z" level=debug msg="set job state for instance: 5deb057e-ac95-11e8-9af4-0242ac11000b token: 0e32656c-8df8-4c46-8576-12ad3111443b"
>time="2018-08-30T20:43:56Z" level=debug msg="set binding instance: 5deb057e-ac95-11e8-9af4-0242ac11000b"
>time="2018-08-30T20:43:56Z" level=info msg="============================================================"
>time="2018-08-30T20:43:56Z" level=info msg="                       BINDING                              "
>time="2018-08-30T20:43:56Z" level=info msg="============================================================"
>time="2018-08-30T20:43:56Z" level=info msg="ServiceInstance.ID: 1dda1477cace09730bd8ed7a6505607e"
>time="2018-08-30T20:43:56Z" level=info msg="ServiceInstance.Name: dh-postgresql-apb"
>time="2018-08-30T20:43:56Z" level=info msg="ServiceInstance.Image: docker.io/mhrivnak/postgresql-apb:latest"
>time="2018-08-30T20:43:56Z" level=info msg="ServiceInstance.Description: SCL PostgreSQL apb implementation"
>time="2018-08-30T20:43:56Z" level=info msg="============================================================"
>time="2018-08-30T20:43:56Z" level=debug msg="executor::actionStarted"
>time="2018-08-30T20:43:56Z" level=debug msg="JobStateSubscriber Notify : msg state {0e32656c-8df8-4c46-8576-12ad3111443b in progress  bind  action started} "
>time="2018-08-30T20:43:56Z" level=debug msg="set job state for instance: 5deb057e-ac95-11e8-9af4-0242ac11000b token: 0e32656c-8df8-4c46-8576-12ad3111443b"
>time="2018-08-30T20:43:56Z" level=debug msg="Creating network policy for pod: bundle-d68e583d-ca79-440e-8213-453eb3428273 to grant network access to ns: async-jian"
>time="2018-08-30T20:43:56Z" level=debug msg="Successfully created network policy for pod: bundle-d68e583d-ca79-440e-8213-453eb3428273 to grant network access to ns: async-jian"
>time="2018-08-30T20:43:56Z" level=debug msg="Binding 5deb057e-ac95-11e8-9af4-0242ac11000b already exists, skipping update because of conflict."
>172.17.0.10 - - [30/Aug/2018:20:43:56 +0000] "PUT /osb/v2/service_instances/3da5dced-ac95-11e8-9af4-0242ac11000b/service_bindings/5deb057e-ac95-11e8-9af4-0242ac11000b?accepts_incomplete=true HTTP/1.1" 202 58
>time="2018-08-30T20:43:56Z" level=debug msg="Trying to create apb sandbox: [ bundle-d68e583d-ca79-440e-8213-453eb3428273 ], with edit permissions in namespace dh-postgresql-apb-bind-vjrwx"
>time="2018-08-30T20:43:56Z" level=info msg="Creating RoleBinding bundle-d68e583d-ca79-440e-8213-453eb3428273"
>time="2018-08-30T20:43:56Z" level=info msg="Creating RoleBinding bundle-d68e583d-ca79-440e-8213-453eb3428273"
>time="2018-08-30T20:43:56Z" level=info msg="Successfully created apb sandbox: [ bundle-d68e583d-ca79-440e-8213-453eb3428273 ], with edit permissions in namespace [ dh-postgresql-apb-bind-vjrwx ]"
>time="2018-08-30T20:43:56Z" level=debug msg="Running post create sandbox functions if defined."
>time="2018-08-30T20:43:56Z" level=debug msg="ExecutingApb:"
>time="2018-08-30T20:43:56Z" level=debug msg="name:[ dh-postgresql-apb ]"
>time="2018-08-30T20:43:56Z" level=debug msg="image:[ docker.io/mhrivnak/postgresql-apb:latest ]"
>time="2018-08-30T20:43:56Z" level=debug msg="action:[ bind ]"
>time="2018-08-30T20:43:56Z" level=debug msg="pullPolicy:[ IfNotPresent ]"
>time="2018-08-30T20:43:56Z" level=debug msg="role:[ edit ]"
>time="2018-08-30T20:43:56Z" level=debug msg="No proxy env vars found to be configured."
>client returned err  configmaps "3da5dced-ac95-11e8-9af4-0242ac11000b-state" not found
>time="2018-08-30T20:43:56Z" level=info msg="Creating pod \"bundle-d68e583d-ca79-440e-8213-453eb3428273\" in the dh-postgresql-apb-bind-vjrwx namespace"
>time="2018-08-30T20:43:56Z" level=debug msg="Watching pod [ bundle-d68e583d-ca79-440e-8213-453eb3428273 ] in namespace [ dh-postgresql-apb-bind-vjrwx ] for completion"
>time="2018-08-30T20:43:56Z" level=debug msg="pod [bundle-d68e583d-ca79-440e-8213-453eb3428273] in phase Pending"
>time="2018-08-30T20:43:56Z" level=debug msg="Pod [ bundle-d68e583d-ca79-440e-8213-453eb3428273 ] Pending"
>time="2018-08-30T20:43:56Z" level=debug msg="pod [bundle-d68e583d-ca79-440e-8213-453eb3428273] in phase Pending"
>time="2018-08-30T20:43:56Z" level=debug msg="Pod [ bundle-d68e583d-ca79-440e-8213-453eb3428273 ] Pending"
>time="2018-08-30T20:43:56Z" level=debug msg="pod [bundle-d68e583d-ca79-440e-8213-453eb3428273] in phase Pending"
>time="2018-08-30T20:43:56Z" level=debug msg="Pod [ bundle-d68e583d-ca79-440e-8213-453eb3428273 ] Pending"
>time="2018-08-30T20:43:57Z" level=info msg="Request: \"GET /osb/v2/service_instances/3da5dced-ac95-11e8-9af4-0242ac11000b/service_bindings/5deb057e-ac95-11e8-9af4-0242ac11000b/last_operation?operation=0e32656c-8df8-4c46-8576-12ad3111443b&plan_id=7f4a5e35e4af2beb70076e72fab0b7ff&service_id=1dda1477cace09730bd8ed7a6505607e HTTP/1.1\\r\\nHost: broker.automation-broker.svc:1338\\r\\nAccept-Encoding: gzip\\r\\nUser-Agent: Go-http-client/1.1\\r\\nX-Broker-Api-Originating-Identity: kubernetes eyJ1c2VybmFtZSI6ImFkbWluIiwidWlkIjoiIiwiZ3JvdXBzIjpbInN5c3RlbTphdXRoZW50aWNhdGVkOm9hdXRoIiwic3lzdGVtOmF1dGhlbnRpY2F0ZWQiXSwiZXh0cmEiOnsic2NvcGVzLmF1dGhvcml6YXRpb24ub3BlbnNoaWZ0LmlvIjpbInVzZXI6ZnVsbCJdfX0=\\r\\nX-Broker-Api-Version: 2.13\\r\\n\\r\\n\""
>time="2018-08-30T20:43:57Z" level=debug msg="get binding instance: 5deb057e-ac95-11e8-9af4-0242ac11000b"
>time="2018-08-30T20:43:57Z" level=debug msg="service_id: 1dda1477cace09730bd8ed7a6505607e"
>time="2018-08-30T20:43:57Z" level=debug msg="plan_id: 7f4a5e35e4af2beb70076e72fab0b7ff"
>time="2018-08-30T20:43:57Z" level=debug msg="operation:  0e32656c-8df8-4c46-8576-12ad3111443b"
>time="2018-08-30T20:43:57Z" level=debug msg="state: in progress"
>time="2018-08-30T20:43:57Z" level=debug msg="description: action started"
>172.17.0.10 - - [30/Aug/2018:20:43:57 +0000] "GET /osb/v2/service_instances/3da5dced-ac95-11e8-9af4-0242ac11000b/service_bindings/5deb057e-ac95-11e8-9af4-0242ac11000b/last_operation?operation=0e32656c-8df8-4c46-8576-12ad3111443b&plan_id=7f4a5e35e4af2beb70076e72fab0b7ff&service_id=1dda1477cace09730bd8ed7a6505607e HTTP/1.1" 200 64
>time="2018-08-30T20:43:58Z" level=debug msg="pod [bundle-d68e583d-ca79-440e-8213-453eb3428273] in phase Running"
>time="2018-08-30T20:43:58Z" level=debug msg="Pod [ bundle-d68e583d-ca79-440e-8213-453eb3428273 ] Running"
>time="2018-08-30T20:43:59Z" level=info msg="Request: \"GET /osb/v2/service_instances/3da5dced-ac95-11e8-9af4-0242ac11000b/service_bindings/5deb057e-ac95-11e8-9af4-0242ac11000b/last_operation?operation=0e32656c-8df8-4c46-8576-12ad3111443b&plan_id=7f4a5e35e4af2beb70076e72fab0b7ff&service_id=1dda1477cace09730bd8ed7a6505607e HTTP/1.1\\r\\nHost: broker.automation-broker.svc:1338\\r\\nAccept-Encoding: gzip\\r\\nUser-Agent: Go-http-client/1.1\\r\\nX-Broker-Api-Originating-Identity: kubernetes eyJ1c2VybmFtZSI6ImFkbWluIiwidWlkIjoiIiwiZ3JvdXBzIjpbInN5c3RlbTphdXRoZW50aWNhdGVkOm9hdXRoIiwic3lzdGVtOmF1dGhlbnRpY2F0ZWQiXSwiZXh0cmEiOnsic2NvcGVzLmF1dGhvcml6YXRpb24ub3BlbnNoaWZ0LmlvIjpbInVzZXI6ZnVsbCJdfX0=\\r\\nX-Broker-Api-Version: 2.13\\r\\n\\r\\n\""
>time="2018-08-30T20:43:59Z" level=debug msg="get binding instance: 5deb057e-ac95-11e8-9af4-0242ac11000b"
>time="2018-08-30T20:43:59Z" level=debug msg="service_id: 1dda1477cace09730bd8ed7a6505607e"
>time="2018-08-30T20:43:59Z" level=debug msg="plan_id: 7f4a5e35e4af2beb70076e72fab0b7ff"
>time="2018-08-30T20:43:59Z" level=debug msg="operation:  0e32656c-8df8-4c46-8576-12ad3111443b"
>time="2018-08-30T20:43:59Z" level=debug msg="state: in progress"
>time="2018-08-30T20:43:59Z" level=debug msg="description: action started"
>172.17.0.10 - - [30/Aug/2018:20:43:59 +0000] "GET /osb/v2/service_instances/3da5dced-ac95-11e8-9af4-0242ac11000b/service_bindings/5deb057e-ac95-11e8-9af4-0242ac11000b/last_operation?operation=0e32656c-8df8-4c46-8576-12ad3111443b&plan_id=7f4a5e35e4af2beb70076e72fab0b7ff&service_id=1dda1477cace09730bd8ed7a6505607e HTTP/1.1" 200 64
>time="2018-08-30T20:44:03Z" level=debug msg="pod [bundle-d68e583d-ca79-440e-8213-453eb3428273] in phase Succeeded"
>time="2018-08-30T20:44:03Z" level=debug msg="Pod [ bundle-d68e583d-ca79-440e-8213-453eb3428273 ] completed"
>time="2018-08-30T20:44:03Z" level=debug msg="state: copying state from namespace dh-postgresql-apb-bind-vjrwx to ns automation-broker from name bundle-d68e583d-ca79-440e-8213-453eb3428273 to name 3da5dced-ac95-11e8-9af4-0242ac11000b-state"
>time="2018-08-30T20:44:03Z" level=debug msg="no state configmap found to copy"
>time="2018-08-30T20:44:03Z" level=debug msg="Found secret with name bundle-d68e583d-ca79-440e-8213-453eb3428273\n"
>time="2018-08-30T20:44:03Z" level=debug msg="executor::actionFinishedWithSuccess"
>time="2018-08-30T20:44:03Z" level=info msg="Destroying APB sandbox..."
>time="2018-08-30T20:44:03Z" level=debug msg="closing channel for job 0e32656c-8df8-4c46-8576-12ad3111443b"
>time="2018-08-30T20:44:03Z" level=debug msg="JobStateSubscriber Notify : msg state {0e32656c-8df8-4c46-8576-12ad3111443b succeeded  bind  bind job completed} "
>time="2018-08-30T20:44:03Z" level=debug msg="set job state for instance: 5deb057e-ac95-11e8-9af4-0242ac11000b token: 0e32656c-8df8-4c46-8576-12ad3111443b"
>time="2018-08-30T20:44:03Z" level=debug msg="Deleting namespace dh-postgresql-apb-bind-vjrwx"
>time="2018-08-30T20:44:03Z" level=debug msg="JobStateSubscriber handleSucceeded : msg state {0e32656c-8df8-4c46-8576-12ad3111443b succeeded  bind  bind job completed} "
>time="2018-08-30T20:44:03Z" level=debug msg="Deleting rolebinding bundle-d68e583d-ca79-440e-8213-453eb3428273, namespace dh-postgresql-apb-bind-vjrwx"
>time="2018-08-30T20:44:03Z" level=info msg="Successfully deleted rolebinding bundle-d68e583d-ca79-440e-8213-453eb3428273, namespace dh-postgresql-apb-bind-vjrwx"
>time="2018-08-30T20:44:03Z" level=debug msg="Deleting rolebinding bundle-d68e583d-ca79-440e-8213-453eb3428273, namespace async-jian"
>time="2018-08-30T20:44:03Z" level=info msg="Successfully deleted rolebinding bundle-d68e583d-ca79-440e-8213-453eb3428273, namespace async-jian"
>time="2018-08-30T20:44:03Z" level=debug msg="Deleting network policy for pod: bundle-d68e583d-ca79-440e-8213-453eb3428273 to grant network access to ns: async-jian"
>time="2018-08-30T20:44:03Z" level=debug msg="Successfully deleted network policy for pod: bundle-d68e583d-ca79-440e-8213-453eb3428273 to grant network access to ns: async-jian"
>time="2018-08-30T20:44:03Z" level=debug msg="Running post sandbox destroy hooks"
>time="2018-08-30T20:44:03Z" level=info msg="Request: \"GET /osb/v2/service_instances/3da5dced-ac95-11e8-9af4-0242ac11000b/service_bindings/5deb057e-ac95-11e8-9af4-0242ac11000b/last_operation?operation=0e32656c-8df8-4c46-8576-12ad3111443b&plan_id=7f4a5e35e4af2beb70076e72fab0b7ff&service_id=1dda1477cace09730bd8ed7a6505607e HTTP/1.1\\r\\nHost: broker.automation-broker.svc:1338\\r\\nAccept-Encoding: gzip\\r\\nUser-Agent: Go-http-client/1.1\\r\\nX-Broker-Api-Originating-Identity: kubernetes eyJ1c2VybmFtZSI6ImFkbWluIiwidWlkIjoiIiwiZ3JvdXBzIjpbInN5c3RlbTphdXRoZW50aWNhdGVkOm9hdXRoIiwic3lzdGVtOmF1dGhlbnRpY2F0ZWQiXSwiZXh0cmEiOnsic2NvcGVzLmF1dGhvcml6YXRpb24ub3BlbnNoaWZ0LmlvIjpbInVzZXI6ZnVsbCJdfX0=\\r\\nX-Broker-Api-Version: 2.13\\r\\n\\r\\n\""
>time="2018-08-30T20:44:03Z" level=debug msg="get binding instance: 5deb057e-ac95-11e8-9af4-0242ac11000b"
>time="2018-08-30T20:44:03Z" level=debug msg="service_id: 1dda1477cace09730bd8ed7a6505607e"
>time="2018-08-30T20:44:03Z" level=debug msg="plan_id: 7f4a5e35e4af2beb70076e72fab0b7ff"
>time="2018-08-30T20:44:03Z" level=debug msg="operation:  0e32656c-8df8-4c46-8576-12ad3111443b"
>time="2018-08-30T20:44:03Z" level=debug msg="state: succeeded"
>time="2018-08-30T20:44:03Z" level=debug msg="description: bind job completed"
>172.17.0.10 - - [30/Aug/2018:20:44:03 +0000] "GET /osb/v2/service_instances/3da5dced-ac95-11e8-9af4-0242ac11000b/service_bindings/5deb057e-ac95-11e8-9af4-0242ac11000b/last_operation?operation=0e32656c-8df8-4c46-8576-12ad3111443b&plan_id=7f4a5e35e4af2beb70076e72fab0b7ff&service_id=1dda1477cace09730bd8ed7a6505607e HTTP/1.1" 200 66
>time="2018-08-30T20:44:03Z" level=debug msg="Unable to find originating origin header"
>time="2018-08-30T20:44:03Z" level=info msg="Request: \"GET /osb/v2/service_instances/3da5dced-ac95-11e8-9af4-0242ac11000b/service_bindings/5deb057e-ac95-11e8-9af4-0242ac11000b HTTP/1.1\\r\\nHost: broker.automation-broker.svc:1338\\r\\nAccept-Encoding: gzip\\r\\nUser-Agent: Go-http-client/1.1\\r\\nX-Broker-Api-Version: 2.13\\r\\n\\r\\n\""
>time="2018-08-30T20:44:03Z" level=debug msg="get service instance: 3da5dced-ac95-11e8-9af4-0242ac11000b"
>time="2018-08-30T20:44:03Z" level=debug msg="get spec: 1dda1477cace09730bd8ed7a6505607e"
>time="2018-08-30T20:44:03Z" level=debug msg="broker.GetBind: entered GetBind"
>time="2018-08-30T20:44:03Z" level=debug msg="Found secret with name 3da5dced-ac95-11e8-9af4-0242ac11000b\n"
>time="2018-08-30T20:44:03Z" level=debug msg="get binding instance: 5deb057e-ac95-11e8-9af4-0242ac11000b"
>time="2018-08-30T20:44:03Z" level=debug msg="Found secret with name 5deb057e-ac95-11e8-9af4-0242ac11000b\n"
>time="2018-08-30T20:44:03Z" level=debug msg="broker.GetBind: we got the bind credentials"
>time="2018-08-30T20:44:03Z" level=debug msg="bind creds: map[DB_HOST:postgresql DB_NAME:kvzbbpicvqbyhrj DB_PASSWORD:OQr2FSquUKYpPTAobWUY DB_PORT:5432 DB_TYPE:postgres DB_USER:kvzbbpicvqbyhrj]"
>time="2018-08-30T20:44:03Z" level=debug msg="handler: bind found"
>172.17.0.10 - - [30/Aug/2018:20:44:03 +0000] "GET /osb/v2/service_instances/3da5dced-ac95-11e8-9af4-0242ac11000b/service_bindings/5deb057e-ac95-11e8-9af4-0242ac11000b HTTP/1.1" 200 216

Actions: View

Attachments on bug 1623331: 1480094 | 1480132 | 1480133 | 1480891