Attachment 1480133 Details for Bug 1623331 – provision and 3 async binds

provision and 3 async binds

binding.log (text/plain), 88.73 KB, created by Jesus M. Rodriguez on 2018-08-31 18:23:50 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Jesus M. Rodriguez

Created: 2018-08-31 18:23:50 UTC

Size: 88.73 KB

patch

obsolete

>Using config file mounted to /etc/automation-broker/config.yaml
>============================================================
>==           Creating Ansible Service Broker...           ==
>============================================================
>time="2018-08-31T18:09:10Z" level=info msg="Ansible Service Broker Version: 1.3.15"
>time="2018-08-31T18:09:10Z" level=info msg="Initializing clients..."
>time="2018-08-31T18:09:10Z" level=debug msg="Connecting to Cluster"
>time="2018-08-31T18:09:10Z" level=info msg="OpenShift version: v3.10.0+8e19bad-29"
>time="2018-08-31T18:09:10Z" level=debug msg="plugin for the network - "
>time="2018-08-31T18:09:10Z" level=debug msg="unable to retrieve the network plugin, defaulting to not joining networks - clusternetworks.network.openshift.io \"default\" not found"
>time="2018-08-31T18:09:10Z" level=info msg="Kubernetes version: v1.10.0+b81c8f8"
>time="2018-08-31T18:09:10Z" level=debug msg="Connecting Dao"
>time="2018-08-31T18:09:10Z" level=debug msg="Connecting Registry"
>time="2018-08-31T18:09:10Z" level=debug msg="Unable to get user from config"
>time="2018-08-31T18:09:10Z" level=debug msg="Unable to get pass from config"
>time="2018-08-31T18:09:10Z" level=debug msg="Unable to get token from config"
>time="2018-08-31T18:09:10Z" level=debug msg="Unable to get images from config"
>time="2018-08-31T18:09:10Z" level=debug msg="Unable to get namespaces from config"
>time="2018-08-31T18:09:10Z" level=debug msg="Unable to get fail_on_error from config"
>time="2018-08-31T18:09:10Z" level=debug msg="Unable to get auth_type from config"
>time="2018-08-31T18:09:10Z" level=debug msg="Unable to get auth_name from config"
>time="2018-08-31T18:09:10Z" level=debug msg="Unable to get runner from config"
>time="2018-08-31T18:09:10Z" level=debug msg="Unable to get skip_verify_tls from config"
>time="2018-08-31T18:09:10Z" level=info msg="Empty AuthType. Assuming credentials are defined in the config... "
>time="2018-08-31T18:09:10Z" level=info msg="== REGISTRY CX == "
>time="2018-08-31T18:09:10Z" level=info msg="Name: dh"
>time="2018-08-31T18:09:10Z" level=info msg="Type: dockerhub"
>time="2018-08-31T18:09:10Z" level=info msg="Url: https://registry.hub.docker.com"
>time="2018-08-31T18:09:10Z" level=debug msg="Creating filter for registry: dh"
>time="2018-08-31T18:09:10Z" level=debug msg="whitelist: [.*-apb$]"
>time="2018-08-31T18:09:10Z" level=debug msg="blacklist: [.*automation-broker-apb$]"
>time="2018-08-31T18:09:10Z" level=debug msg="Unable to get url from config"
>time="2018-08-31T18:09:10Z" level=debug msg="Unable to get user from config"
>time="2018-08-31T18:09:10Z" level=debug msg="Unable to get pass from config"
>time="2018-08-31T18:09:10Z" level=debug msg="Unable to get token from config"
>time="2018-08-31T18:09:10Z" level=debug msg="Unable to get org from config"
>time="2018-08-31T18:09:10Z" level=debug msg="Unable to get tag from config"
>time="2018-08-31T18:09:10Z" level=debug msg="Unable to get images from config"
>time="2018-08-31T18:09:10Z" level=debug msg="Unable to get fail_on_error from config"
>time="2018-08-31T18:09:10Z" level=debug msg="Unable to get black_list from config"
>time="2018-08-31T18:09:10Z" level=debug msg="Unable to get auth_type from config"
>time="2018-08-31T18:09:10Z" level=debug msg="Unable to get auth_name from config"
>time="2018-08-31T18:09:10Z" level=debug msg="Unable to get runner from config"
>time="2018-08-31T18:09:10Z" level=debug msg="Unable to get skip_verify_tls from config"
>time="2018-08-31T18:09:10Z" level=info msg="Empty AuthType. Assuming credentials are defined in the config... "
>time="2018-08-31T18:09:10Z" level=info msg="== REGISTRY CX == "
>time="2018-08-31T18:09:10Z" level=info msg="Name: localregistry"
>time="2018-08-31T18:09:10Z" level=info msg="Type: local_openshift"
>time="2018-08-31T18:09:10Z" level=info msg="Url: "
>time="2018-08-31T18:09:10Z" level=debug msg="Creating filter for registry: localregistry"
>time="2018-08-31T18:09:10Z" level=debug msg="whitelist: [.*]"
>time="2018-08-31T18:09:10Z" level=debug msg="blacklist: []"
>time="2018-08-31T18:09:10Z" level=debug msg="Initializing WorkEngine"
>time="2018-08-31T18:09:10Z" level=debug msg="Unable to get secrets from config"
>time="2018-08-31T18:09:10Z" level=debug msg="Creating AnsibleBroker"
>============================================================
>==           Starting Ansible Service Broker...           ==
>============================================================
>time="2018-08-31T18:09:10Z" level=info msg="Initiating Recovery Process"
>time="2018-08-31T18:09:10Z" level=info msg="Recovery complete"
>time="2018-08-31T18:09:10Z" level=info msg="recover called"
>time="2018-08-31T18:09:10Z" level=info msg="Broker configured to bootstrap on startup"
>time="2018-08-31T18:09:10Z" level=info msg="Attempting bootstrap..."
>time="2018-08-31T18:09:10Z" level=info msg="AnsibleBroker::Bootstrap"
>time="2018-08-31T18:09:10Z" level=debug msg="Dao::BatchGetSpecs"
>time="2018-08-31T18:09:10Z" level=debug msg="Dao::BatchGetBundleInstances"
>time="2018-08-31T18:09:10Z" level=debug msg="markedSpecs: map[]\n"
>time="2018-08-31T18:09:10Z" level=info msg="0 specs deleted"
>time="2018-08-31T18:09:10Z" level=debug msg="Dao::BatchGetSpecs"
>time="2018-08-31T18:09:10Z" level=debug msg="converting []*Spec to SpecManifest successful"
>time="2018-08-31T18:09:10Z" level=debug msg="DockerHubAdapter::GetImages"
>time="2018-08-31T18:09:10Z" level=debug msg="BundleSpecLabel: com.redhat.apb.spec"
>time="2018-08-31T18:09:10Z" level=debug msg="Loading image list for org: [ mhrivnak ]"
>time="2018-08-31T18:09:11Z" level=debug msg="Trying to load mhrivnak/pulp-k8s-mongodb"
>time="2018-08-31T18:09:11Z" level=debug msg="Trying to load mhrivnak/bind-apb"
>time="2018-08-31T18:09:11Z" level=debug msg="Trying to load mhrivnak/postgresql-apb"
>time="2018-08-31T18:09:11Z" level=debug msg="Trying to load mhrivnak/mediawiki-apb"
>time="2018-08-31T18:09:11Z" level=debug msg="Trying to load mhrivnak/redis-helm-apb"
>time="2018-08-31T18:09:11Z" level=debug msg="Trying to load mhrivnak/svccatalog-apb"
>time="2018-08-31T18:09:11Z" level=debug msg="Trying to load mhrivnak/rocketchat-apb"
>time="2018-08-31T18:09:11Z" level=debug msg="Trying to load mhrivnak/pulp-k8s-celery"
>time="2018-08-31T18:09:11Z" level=debug msg="Trying to load mhrivnak/pulp-k8s-qpid"
>time="2018-08-31T18:09:11Z" level=debug msg="Trying to load mhrivnak/origin-ansible-service-broker"
>time="2018-08-31T18:09:11Z" level=debug msg="Trying to load mhrivnak/pulp-k8s-httpd"
>time="2018-08-31T18:09:11Z" level=debug msg="Trying to load mhrivnak/pulp-k8s-base"
>time="2018-08-31T18:09:11Z" level=debug msg="Trying to load mhrivnak/bind"
>time="2018-08-31T18:09:11Z" level=debug msg="Trying to load mhrivnak/pulp-k8s-manage"
>time="2018-08-31T18:09:11Z" level=debug msg="Trying to load mhrivnak/staticreg"
>time="2018-08-31T18:09:11Z" level=debug msg="Trying to load mhrivnak/pulp"
>time="2018-08-31T18:09:11Z" level=debug msg="Trying to load mhrivnak/pulp-automation"
>time="2018-08-31T18:09:11Z" level=debug msg="Trying to load mhrivnak/vault"
>time="2018-08-31T18:09:11Z" level=debug msg="Trying to load mhrivnak/helm-ansible-base"
>time="2018-08-31T18:09:11Z" level=debug msg="Trying to load mhrivnak/helm-bundle-base"
>time="2018-08-31T18:09:11Z" level=debug msg="Filter applied against registry: dh"
>time="2018-08-31T18:09:11Z" level=debug msg="Bundles passing white/blacklist filter:"
>time="2018-08-31T18:09:11Z" level=debug msg="-> mhrivnak/postgresql-apb"
>time="2018-08-31T18:09:11Z" level=debug msg="-> mhrivnak/redis-helm-apb"
>time="2018-08-31T18:09:11Z" level=debug msg="-> mhrivnak/bind-apb"
>time="2018-08-31T18:09:11Z" level=debug msg="-> mhrivnak/rocketchat-apb"
>time="2018-08-31T18:09:11Z" level=debug msg="-> mhrivnak/svccatalog-apb"
>time="2018-08-31T18:09:11Z" level=debug msg="-> mhrivnak/mediawiki-apb"
>time="2018-08-31T18:09:11Z" level=info msg="Bundles filtered by white/blacklist filter:\n\t-> mhrivnak/origin-ansible-service-broker\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/pulp-k8s-base\n\t-> mhrivnak/bind\n\t-> mhrivnak/pulp-k8s-manage\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-31T18:09:12Z" level=debug msg="Successfully converted Image docker.io/mhrivnak/postgresql-apb:latest into Spec"
>time="2018-08-31T18:09:12Z" level=info msg="adapter::configToSpec -> Image docker.io/mhrivnak/postgresql-apb:latest runtime is 2"
>time="2018-08-31T18:09:12Z" level=debug msg="Successfully converted Image docker.io/mhrivnak/redis-helm-apb:latest into Spec"
>time="2018-08-31T18:09:12Z" level=info msg="adapter::configToSpec -> Image docker.io/mhrivnak/redis-helm-apb:latest runtime is 2"
>time="2018-08-31T18:09:12Z" level=debug msg="Successfully converted Image docker.io/mhrivnak/bind-apb:latest into Spec"
>time="2018-08-31T18:09:12Z" level=info msg="adapter::configToSpec -> Image docker.io/mhrivnak/bind-apb:latest runtime is 2"
>time="2018-08-31T18:09:13Z" level=debug msg="Successfully converted Image docker.io/mhrivnak/rocketchat-apb:latest into Spec"
>time="2018-08-31T18:09:13Z" level=info msg="adapter::configToSpec -> Image docker.io/mhrivnak/rocketchat-apb:latest runtime is 2"
>time="2018-08-31T18:09:13Z" level=debug msg="Successfully converted Image docker.io/mhrivnak/svccatalog-apb:latest into Spec"
>time="2018-08-31T18:09:13Z" level=info msg="adapter::configToSpec -> Image docker.io/mhrivnak/svccatalog-apb:latest runtime is 2"
>time="2018-08-31T18:09:13Z" level=debug msg="Successfully converted Image docker.io/mhrivnak/mediawiki-apb:latest into Spec"
>time="2018-08-31T18:09:13Z" level=info msg="adapter::configToSpec -> Image docker.io/mhrivnak/mediawiki-apb:latest runtime is 2"
>time="2018-08-31T18:09:13Z" level=info msg="Validating specs..."
>time="2018-08-31T18:09:13Z" level=debug msg="Spec [bind-apb] version (1.0) not semver compatible"
>time="2018-08-31T18:09:13Z" level=debug msg="Spec [rocketchat-apb] version (1.0) not semver compatible"
>time="2018-08-31T18:09:13Z" level=debug msg="Spec [redis-apb] version (1.0) not semver compatible"
>time="2018-08-31T18:09:13Z" level=debug msg="Spec [postgresql-apb] version (1.0) not semver compatible"
>time="2018-08-31T18:09:13Z" level=debug msg="Spec [mediawiki-apb] version (1.0) not semver compatible"
>time="2018-08-31T18:09:13Z" level=debug msg="Spec [catalog-apb] version (1.0) not semver compatible"
>time="2018-08-31T18:09:13Z" level=info msg="All specs passed validation!"
>time="2018-08-31T18:09:13Z" level=debug msg="LocalOpenShiftAdapter::GetImageNames"
>time="2018-08-31T18:09:13Z" level=debug msg="BundleSpecLabel: com.redhat.apb.spec"
>time="2018-08-31T18:09:13Z" level=debug msg="Filter applied against registry: localregistry"
>time="2018-08-31T18:09:13Z" level=debug msg="Bundles passing white/blacklist filter:"
>time="2018-08-31T18:09:13Z" level=debug msg="-> openshift/httpd"
>time="2018-08-31T18:09:13Z" level=debug msg="-> openshift/jenkins"
>time="2018-08-31T18:09:13Z" level=debug msg="-> openshift/mariadb"
>time="2018-08-31T18:09:13Z" level=debug msg="-> openshift/mysql"
>time="2018-08-31T18:09:13Z" level=debug msg="-> openshift/nginx"
>time="2018-08-31T18:09:13Z" level=debug msg="-> openshift/postgresql"
>time="2018-08-31T18:09:13Z" level=debug msg="-> openshift/python"
>time="2018-08-31T18:09:13Z" level=debug msg="-> openshift/ruby"
>time="2018-08-31T18:09:13Z" level=debug msg="-> openshift/mongodb"
>time="2018-08-31T18:09:13Z" level=debug msg="-> openshift/nodejs"
>time="2018-08-31T18:09:13Z" level=debug msg="-> openshift/perl"
>time="2018-08-31T18:09:13Z" level=debug msg="-> openshift/php"
>time="2018-08-31T18:09:13Z" level=debug msg="-> openshift/redis"
>time="2018-08-31T18:09:13Z" level=debug msg="LocalOpenShiftAdapter::FetchSpecs"
>time="2018-08-31T18:09:13Z" level=debug msg="No tag specified in config, assuming `latest`"
>time="2018-08-31T18:09:13Z" level=debug msg="LocalOpenShiftAdapter::LoadSpec"
>time="2018-08-31T18:09:13Z" level=info msg="No runtime label found. Set runtime=1. Will use 'exec' to gather bind credentials"
>time="2018-08-31T18:09:13Z" level=debug msg="LocalOpenShiftAdapter::LoadSpec"
>time="2018-08-31T18:09:13Z" level=info msg="No runtime label found. Set runtime=1. Will use 'exec' to gather bind credentials"
>time="2018-08-31T18:09:13Z" level=debug msg="LocalOpenShiftAdapter::LoadSpec"
>time="2018-08-31T18:09:13Z" level=info msg="No runtime label found. Set runtime=1. Will use 'exec' to gather bind credentials"
>time="2018-08-31T18:09:13Z" level=debug msg="LocalOpenShiftAdapter::LoadSpec"
>time="2018-08-31T18:09:13Z" level=info msg="No runtime label found. Set runtime=1. Will use 'exec' to gather bind credentials"
>time="2018-08-31T18:09:13Z" level=debug msg="LocalOpenShiftAdapter::LoadSpec"
>time="2018-08-31T18:09:13Z" level=info msg="No runtime label found. Set runtime=1. Will use 'exec' to gather bind credentials"
>time="2018-08-31T18:09:13Z" level=debug msg="LocalOpenShiftAdapter::LoadSpec"
>time="2018-08-31T18:09:13Z" level=info msg="No runtime label found. Set runtime=1. Will use 'exec' to gather bind credentials"
>time="2018-08-31T18:09:13Z" level=debug msg="LocalOpenShiftAdapter::LoadSpec"
>time="2018-08-31T18:09:13Z" level=info msg="No runtime label found. Set runtime=1. Will use 'exec' to gather bind credentials"
>time="2018-08-31T18:09:14Z" level=debug msg="LocalOpenShiftAdapter::LoadSpec"
>time="2018-08-31T18:09:14Z" level=info msg="No runtime label found. Set runtime=1. Will use 'exec' to gather bind credentials"
>time="2018-08-31T18:09:14Z" level=debug msg="LocalOpenShiftAdapter::LoadSpec"
>time="2018-08-31T18:09:14Z" level=info msg="No runtime label found. Set runtime=1. Will use 'exec' to gather bind credentials"
>time="2018-08-31T18:09:14Z" level=debug msg="LocalOpenShiftAdapter::LoadSpec"
>time="2018-08-31T18:09:14Z" level=info msg="No runtime label found. Set runtime=1. Will use 'exec' to gather bind credentials"
>time="2018-08-31T18:09:14Z" level=debug msg="LocalOpenShiftAdapter::LoadSpec"
>time="2018-08-31T18:09:14Z" level=info msg="No runtime label found. Set runtime=1. Will use 'exec' to gather bind credentials"
>time="2018-08-31T18:09:14Z" level=debug msg="LocalOpenShiftAdapter::LoadSpec"
>time="2018-08-31T18:09:14Z" level=info msg="No runtime label found. Set runtime=1. Will use 'exec' to gather bind credentials"
>time="2018-08-31T18:09:14Z" level=debug msg="LocalOpenShiftAdapter::LoadSpec"
>time="2018-08-31T18:09:14Z" level=info msg="No runtime label found. Set runtime=1. Will use 'exec' to gather bind credentials"
>time="2018-08-31T18:09:14Z" level=info msg="Validating specs..."
>time="2018-08-31T18:09:14Z" level=warning msg="Spec [  ] failed validation for the following reason: [ Spec [] failed version validation ]. It will not be made available."
>time="2018-08-31T18:09:14Z" level=warning msg="Spec [  ] failed validation for the following reason: [ Spec [] failed version validation ]. It will not be made available."
>time="2018-08-31T18:09:14Z" level=warning msg="Spec [  ] failed validation for the following reason: [ Spec [] failed version validation ]. It will not be made available."
>time="2018-08-31T18:09:14Z" level=warning msg="Spec [  ] failed validation for the following reason: [ Spec [] failed version validation ]. It will not be made available."
>time="2018-08-31T18:09:14Z" level=warning msg="Spec [  ] failed validation for the following reason: [ Spec [] failed version validation ]. It will not be made available."
>time="2018-08-31T18:09:14Z" level=warning msg="Spec [  ] failed validation for the following reason: [ Spec [] failed version validation ]. It will not be made available."
>time="2018-08-31T18:09:14Z" level=warning msg="Spec [  ] failed validation for the following reason: [ Spec [] failed version validation ]. It will not be made available."
>time="2018-08-31T18:09:14Z" level=warning msg="Spec [  ] failed validation for the following reason: [ Spec [] failed version validation ]. It will not be made available."
>time="2018-08-31T18:09:14Z" level=warning msg="Spec [  ] failed validation for the following reason: [ Spec [] failed version validation ]. It will not be made available."
>time="2018-08-31T18:09:14Z" level=warning msg="Spec [  ] failed validation for the following reason: [ Spec [] failed version validation ]. It will not be made available."
>time="2018-08-31T18:09:14Z" level=warning msg="Spec [  ] failed validation for the following reason: [ Spec [] failed version validation ]. It will not be made available."
>time="2018-08-31T18:09:14Z" level=warning msg="Spec [  ] failed validation for the following reason: [ Spec [] failed version validation ]. It will not be made available."
>time="2018-08-31T18:09:14Z" level=warning msg="Spec [  ] failed validation for the following reason: [ Spec [] failed version validation ]. It will not be made available."
>time="2018-08-31T18:09:14Z" level=warning msg="13 specs of 13 discovered specs failed validation from registry: openshift-registry"
>time="2018-08-31T18:09:14Z" level=debug msg="spec 'b0dec8711d205cc51d7633eb95c1ec04|dh-bind-apb' needs to be updated"
>time="2018-08-31T18:09:14Z" level=debug msg="spec 'ba9c2d4db404ce97111bea80225de968|dh-rocketchat-apb' needs to be updated"
>time="2018-08-31T18:09:14Z" level=debug msg="spec '4b12a667a4ffdcaf61a52c7f72c3fe67|dh-redis-apb' needs to be updated"
>time="2018-08-31T18:09:14Z" level=debug msg="spec '1dda1477cace09730bd8ed7a6505607e|dh-postgresql-apb' needs to be updated"
>time="2018-08-31T18:09:14Z" level=debug msg="spec 'f6c4486b7fb0cdac4b58e193607f7011|dh-mediawiki-apb' needs to be updated"
>time="2018-08-31T18:09:14Z" level=debug msg="spec '2fc891259c84ffd32cb564adf55803f8|dh-catalog-apb' needs to be updated"
>time="2018-08-31T18:09:14Z" level=info msg="update spec: f6c4486b7fb0cdac4b58e193607f7011|dh-mediawiki-apb to crd"
>time="2018-08-31T18:09:14Z" level=info msg="update spec: 2fc891259c84ffd32cb564adf55803f8|dh-catalog-apb to crd"
>time="2018-08-31T18:09:14Z" level=info msg="update spec: b0dec8711d205cc51d7633eb95c1ec04|dh-bind-apb to crd"
>time="2018-08-31T18:09:14Z" level=info msg="update spec: ba9c2d4db404ce97111bea80225de968|dh-rocketchat-apb to crd"
>time="2018-08-31T18:09:14Z" level=info msg="update spec: 4b12a667a4ffdcaf61a52c7f72c3fe67|dh-redis-apb to crd"
>time="2018-08-31T18:09:14Z" level=info msg="update spec: 1dda1477cace09730bd8ed7a6505607e|dh-postgresql-apb to crd"
>time="2018-08-31T18:09:14Z" level=info msg="Broker successfully bootstrapped on startup"
>time="2018-08-31T18:09:14Z" level=debug msg="RefreshInterval: 10m0s"
>time="2018-08-31T18:09:14Z" level=debug msg="calling NewSecureServingOptions"
>time="2018-08-31T18:09:15Z" level=debug msg="Creating k8s apiserver"
>time="2018-08-31T18:09:15Z" level=info msg="Listening on https://[::]:1338"
>time="2018-08-31T18:09:15Z" level=info msg="Ansible Service Broker Starting"
>time="2018-08-31T18:12:29Z" level=info msg="Request: \"PUT /osb/v2/service_instances/6bc15ef6-ad49-11e8-9470-0242ac11000b?accepts_incomplete=true HTTP/1.1\\r\\nHost: broker.automation-broker.svc:1338\\r\\nAccept-Encoding: gzip\\r\\nContent-Length: 450\\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\\\":\\\"5e839961-ad49-11e8-8f23-c85b76145add\\\",\\\"space_guid\\\":\\\"5e839961-ad49-11e8-8f23-c85b76145add\\\",\\\"parameters\\\":{\\\"postgresql_database\\\":\\\"admin\\\",\\\"postgresql_password\\\":\\\"password\\\",\\\"postgresql_user\\\":\\\"admin\\\",\\\"postgresql_version\\\":\\\"9.6\\\"},\\\"context\\\":{\\\"clusterid\\\":\\\"4ca515b5-ad46-11e8-8a4c-0242ac110008\\\",\\\"namespace\\\":\\\"blog-project\\\",\\\"platform\\\":\\\"kubernetes\\\"}}\""
>time="2018-08-31T18:12:29Z" level=debug msg="Auto Escalate has been set to true, we are escalating permissions"
>time="2018-08-31T18:12:29Z" level=debug msg="get spec: 1dda1477cace09730bd8ed7a6505607e"
>time="2018-08-31T18:12:29Z" level=debug msg="Injecting PlanID as parameter: { _apb_plan_id: dev }"
>time="2018-08-31T18:12:29Z" level=debug msg="Injecting ServiceClassID as parameter: { _apb_service_class_id: 1dda1477cace09730bd8ed7a6505607e }"
>time="2018-08-31T18:12:29Z" level=debug msg="Injecting ServiceInstanceID as parameter: { _apb_service_instance_id: 6bc15ef6-ad49-11e8-9470-0242ac11000b }"
>time="2018-08-31T18:12:29Z" level=debug msg="Injecting lastRequestingUserKey as parameter: { _apb_last_requesting_user: admin }"
>time="2018-08-31T18:12:29Z" level=debug msg="get service instance: 6bc15ef6-ad49-11e8-9470-0242ac11000b"
>time="2018-08-31T18:12:29Z" level=debug msg="set service instance: 6bc15ef6-ad49-11e8-9470-0242ac11000b"
>time="2018-08-31T18:12:29Z" level=info msg="ASYNC provisioning in progress"
>time="2018-08-31T18:12:29Z" level=debug msg="set job state for instance: 6bc15ef6-ad49-11e8-9470-0242ac11000b token: fe802111-7f52-4bc3-83f2-4d55f78c782d"
>172.17.0.8 - - [31/Aug/2018:18:12:29 +0000] "PUT /osb/v2/service_instances/6bc15ef6-ad49-11e8-9470-0242ac11000b?accepts_incomplete=true HTTP/1.1" 202 58
>time="2018-08-31T18:12:29Z" level=info msg="============================================================"
>time="2018-08-31T18:12:29Z" level=info msg="                       PROVISIONING                         "
>time="2018-08-31T18:12:29Z" level=info msg="============================================================"
>time="2018-08-31T18:12:29Z" level=info msg="Spec.ID: 1dda1477cace09730bd8ed7a6505607e"
>time="2018-08-31T18:12:29Z" level=info msg="Spec.Name: dh-postgresql-apb"
>time="2018-08-31T18:12:29Z" level=info msg="Spec.Image: docker.io/mhrivnak/postgresql-apb:latest"
>time="2018-08-31T18:12:29Z" level=info msg="Spec.Description: SCL PostgreSQL apb implementation"
>time="2018-08-31T18:12:29Z" level=info msg="============================================================"
>time="2018-08-31T18:12:29Z" level=debug msg="executor::actionStarted"
>time="2018-08-31T18:12:29Z" level=debug msg="JobStateSubscriber Notify : msg state {fe802111-7f52-4bc3-83f2-4d55f78c782d in progress  provision  action started} "
>time="2018-08-31T18:12:29Z" level=debug msg="set job state for instance: 6bc15ef6-ad49-11e8-9470-0242ac11000b token: fe802111-7f52-4bc3-83f2-4d55f78c782d"
>time="2018-08-31T18:12:29Z" level=debug msg="Creating network policy for pod: bundle-9f3e87de-491d-4fff-af2e-7710b7503f1e to grant network access to ns: blog-project"
>time="2018-08-31T18:12:29Z" level=debug msg="Successfully created network policy for pod: bundle-9f3e87de-491d-4fff-af2e-7710b7503f1e to grant network access to ns: blog-project"
>time="2018-08-31T18:12:29Z" level=debug msg="Trying to create apb sandbox: [ bundle-9f3e87de-491d-4fff-af2e-7710b7503f1e ], with edit permissions in namespace dh-postgresql-apb-prov-vcjv4"
>time="2018-08-31T18:12:29Z" level=info msg="Creating RoleBinding bundle-9f3e87de-491d-4fff-af2e-7710b7503f1e"
>time="2018-08-31T18:12:29Z" level=info msg="Creating RoleBinding bundle-9f3e87de-491d-4fff-af2e-7710b7503f1e"
>time="2018-08-31T18:12:29Z" level=info msg="Successfully created apb sandbox: [ bundle-9f3e87de-491d-4fff-af2e-7710b7503f1e ], with edit permissions in namespace [ dh-postgresql-apb-prov-vcjv4 ]"
>time="2018-08-31T18:12:29Z" level=debug msg="Running post create sandbox functions if defined."
>time="2018-08-31T18:12:29Z" level=debug msg="ExecutingApb:"
>time="2018-08-31T18:12:29Z" level=debug msg="name:[ dh-postgresql-apb ]"
>time="2018-08-31T18:12:29Z" level=debug msg="image:[ docker.io/mhrivnak/postgresql-apb:latest ]"
>time="2018-08-31T18:12:29Z" level=debug msg="action:[ provision ]"
>time="2018-08-31T18:12:29Z" level=debug msg="pullPolicy:[ IfNotPresent ]"
>time="2018-08-31T18:12:29Z" level=debug msg="role:[ edit ]"
>client returned err  configmaps "6bc15ef6-ad49-11e8-9470-0242ac11000b-state" not found
>time="2018-08-31T18:12:29Z" level=info msg="Proxy configuration present. Applying to APB before execution:"
>time="2018-08-31T18:12:29Z" level=info msg="HTTP_PROXY=\"\""
>time="2018-08-31T18:12:29Z" level=info msg="HTTPS_PROXY=\"\""
>time="2018-08-31T18:12:29Z" level=info msg="NO_PROXY=\"\""
>time="2018-08-31T18:12:29Z" level=info msg="Creating pod \"bundle-9f3e87de-491d-4fff-af2e-7710b7503f1e\" in the dh-postgresql-apb-prov-vcjv4 namespace"
>time="2018-08-31T18:12:29Z" 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 {}{\"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)\", \"documentationUrl\":\"https://www.postgresql.org/docs/\"}, Async:\"optional\", Plans:[]bundle.Plan{bundle.Plan{ID:\"7f4a5e35e4af2beb70076e72fab0b7ff\", Name:\"dev\", Description:\"A single DB server with no storage\", Metadata:map[string]interface {}{\"longDescription\":\"This plan provides a single non-HA PostgreSQL server without persistent storage\", \"cost\":\"$0.00\", \"displayName\":\"Development\"}, 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-31T18:12:29Z" level=debug msg="Watching pod [ bundle-9f3e87de-491d-4fff-af2e-7710b7503f1e ] in namespace [ dh-postgresql-apb-prov-vcjv4 ] for completion"
>time="2018-08-31T18:12:29Z" level=debug msg="pod [bundle-9f3e87de-491d-4fff-af2e-7710b7503f1e] in phase Pending"
>time="2018-08-31T18:12:29Z" level=debug msg="Pod [ bundle-9f3e87de-491d-4fff-af2e-7710b7503f1e ] Pending"
>time="2018-08-31T18:12:29Z" level=debug msg="pod [bundle-9f3e87de-491d-4fff-af2e-7710b7503f1e] in phase Pending"
>time="2018-08-31T18:12:29Z" level=debug msg="Pod [ bundle-9f3e87de-491d-4fff-af2e-7710b7503f1e ] Pending"
>time="2018-08-31T18:12:30Z" level=info msg="Request: \"GET /osb/v2/service_instances/6bc15ef6-ad49-11e8-9470-0242ac11000b/last_operation?operation=fe802111-7f52-4bc3-83f2-4d55f78c782d&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-31T18:12:30Z" level=debug msg="service_id: 1dda1477cace09730bd8ed7a6505607e"
>time="2018-08-31T18:12:30Z" level=debug msg="plan_id: 7f4a5e35e4af2beb70076e72fab0b7ff"
>time="2018-08-31T18:12:30Z" level=debug msg="operation:  fe802111-7f52-4bc3-83f2-4d55f78c782d"
>time="2018-08-31T18:12:30Z" level=debug msg="state: in progress"
>time="2018-08-31T18:12:30Z" level=debug msg="description: action started"
>172.17.0.8 - - [31/Aug/2018:18:12:30 +0000] "GET /osb/v2/service_instances/6bc15ef6-ad49-11e8-9470-0242ac11000b/last_operation?operation=fe802111-7f52-4bc3-83f2-4d55f78c782d&plan_id=7f4a5e35e4af2beb70076e72fab0b7ff&service_id=1dda1477cace09730bd8ed7a6505607e HTTP/1.1" 200 64
>time="2018-08-31T18:12:31Z" level=debug msg="pod [bundle-9f3e87de-491d-4fff-af2e-7710b7503f1e] in phase Pending"
>time="2018-08-31T18:12:31Z" level=debug msg="Pod [ bundle-9f3e87de-491d-4fff-af2e-7710b7503f1e ] Pending"
>time="2018-08-31T18:12:32Z" level=info msg="Request: \"GET /osb/v2/service_instances/6bc15ef6-ad49-11e8-9470-0242ac11000b/last_operation?operation=fe802111-7f52-4bc3-83f2-4d55f78c782d&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-31T18:12:32Z" level=debug msg="service_id: 1dda1477cace09730bd8ed7a6505607e"
>time="2018-08-31T18:12:32Z" level=debug msg="plan_id: 7f4a5e35e4af2beb70076e72fab0b7ff"
>time="2018-08-31T18:12:32Z" level=debug msg="operation:  fe802111-7f52-4bc3-83f2-4d55f78c782d"
>time="2018-08-31T18:12:32Z" level=debug msg="state: in progress"
>time="2018-08-31T18:12:32Z" level=debug msg="description: action started"
>172.17.0.8 - - [31/Aug/2018:18:12:32 +0000] "GET /osb/v2/service_instances/6bc15ef6-ad49-11e8-9470-0242ac11000b/last_operation?operation=fe802111-7f52-4bc3-83f2-4d55f78c782d&plan_id=7f4a5e35e4af2beb70076e72fab0b7ff&service_id=1dda1477cace09730bd8ed7a6505607e HTTP/1.1" 200 64
>time="2018-08-31T18:12:36Z" level=info msg="Request: \"GET /osb/v2/service_instances/6bc15ef6-ad49-11e8-9470-0242ac11000b/last_operation?operation=fe802111-7f52-4bc3-83f2-4d55f78c782d&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-31T18:12:36Z" level=debug msg="service_id: 1dda1477cace09730bd8ed7a6505607e"
>time="2018-08-31T18:12:36Z" level=debug msg="plan_id: 7f4a5e35e4af2beb70076e72fab0b7ff"
>time="2018-08-31T18:12:36Z" level=debug msg="operation:  fe802111-7f52-4bc3-83f2-4d55f78c782d"
>time="2018-08-31T18:12:36Z" level=debug msg="state: in progress"
>time="2018-08-31T18:12:36Z" level=debug msg="description: action started"
>172.17.0.8 - - [31/Aug/2018:18:12:36 +0000] "GET /osb/v2/service_instances/6bc15ef6-ad49-11e8-9470-0242ac11000b/last_operation?operation=fe802111-7f52-4bc3-83f2-4d55f78c782d&plan_id=7f4a5e35e4af2beb70076e72fab0b7ff&service_id=1dda1477cace09730bd8ed7a6505607e HTTP/1.1" 200 64
>time="2018-08-31T18:12:42Z" level=debug msg="pod [bundle-9f3e87de-491d-4fff-af2e-7710b7503f1e] in phase Pending"
>time="2018-08-31T18:12:42Z" level=debug msg="Pod [ bundle-9f3e87de-491d-4fff-af2e-7710b7503f1e ] Pending"
>time="2018-08-31T18:12:44Z" level=info msg="Request: \"GET /osb/v2/service_instances/6bc15ef6-ad49-11e8-9470-0242ac11000b/last_operation?operation=fe802111-7f52-4bc3-83f2-4d55f78c782d&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-31T18:12:44Z" level=debug msg="service_id: 1dda1477cace09730bd8ed7a6505607e"
>time="2018-08-31T18:12:44Z" level=debug msg="plan_id: 7f4a5e35e4af2beb70076e72fab0b7ff"
>time="2018-08-31T18:12:44Z" level=debug msg="operation:  fe802111-7f52-4bc3-83f2-4d55f78c782d"
>time="2018-08-31T18:12:44Z" level=debug msg="state: in progress"
>time="2018-08-31T18:12:44Z" level=debug msg="description: action started"
>172.17.0.8 - - [31/Aug/2018:18:12:44 +0000] "GET /osb/v2/service_instances/6bc15ef6-ad49-11e8-9470-0242ac11000b/last_operation?operation=fe802111-7f52-4bc3-83f2-4d55f78c782d&plan_id=7f4a5e35e4af2beb70076e72fab0b7ff&service_id=1dda1477cace09730bd8ed7a6505607e HTTP/1.1" 200 64
>time="2018-08-31T18:13:00Z" level=info msg="Request: \"GET /osb/v2/service_instances/6bc15ef6-ad49-11e8-9470-0242ac11000b/last_operation?operation=fe802111-7f52-4bc3-83f2-4d55f78c782d&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-31T18:13:00Z" level=debug msg="service_id: 1dda1477cace09730bd8ed7a6505607e"
>time="2018-08-31T18:13:00Z" level=debug msg="plan_id: 7f4a5e35e4af2beb70076e72fab0b7ff"
>time="2018-08-31T18:13:00Z" level=debug msg="operation:  fe802111-7f52-4bc3-83f2-4d55f78c782d"
>time="2018-08-31T18:13:00Z" level=debug msg="state: in progress"
>time="2018-08-31T18:13:00Z" level=debug msg="description: action started"
>172.17.0.8 - - [31/Aug/2018:18:13:00 +0000] "GET /osb/v2/service_instances/6bc15ef6-ad49-11e8-9470-0242ac11000b/last_operation?operation=fe802111-7f52-4bc3-83f2-4d55f78c782d&plan_id=7f4a5e35e4af2beb70076e72fab0b7ff&service_id=1dda1477cace09730bd8ed7a6505607e HTTP/1.1" 200 64
>time="2018-08-31T18:13:24Z" level=debug msg="pod [bundle-9f3e87de-491d-4fff-af2e-7710b7503f1e] in phase Running"
>time="2018-08-31T18:13:24Z" level=debug msg="Pod [ bundle-9f3e87de-491d-4fff-af2e-7710b7503f1e ] Running"
>time="2018-08-31T18:13:31Z" level=debug msg="pod [bundle-9f3e87de-491d-4fff-af2e-7710b7503f1e] in phase Succeeded"
>time="2018-08-31T18:13:31Z" level=debug msg="Pod [ bundle-9f3e87de-491d-4fff-af2e-7710b7503f1e ] completed"
>time="2018-08-31T18:13:31Z" level=debug msg="state: copying state from namespace dh-postgresql-apb-prov-vcjv4 to ns automation-broker from name bundle-9f3e87de-491d-4fff-af2e-7710b7503f1e to name 6bc15ef6-ad49-11e8-9470-0242ac11000b-state"
>time="2018-08-31T18:13:31Z" level=debug msg="no state configmap found to copy"
>time="2018-08-31T18:13:31Z" level=debug msg="Found secret with name bundle-9f3e87de-491d-4fff-af2e-7710b7503f1e\n"
>time="2018-08-31T18:13:31Z" level=info msg="Destroying APB sandbox..."
>time="2018-08-31T18:13:31Z" level=debug msg="Deleting namespace dh-postgresql-apb-prov-vcjv4"
>time="2018-08-31T18:13:31Z" level=debug msg="Deleting rolebinding bundle-9f3e87de-491d-4fff-af2e-7710b7503f1e, namespace dh-postgresql-apb-prov-vcjv4"
>time="2018-08-31T18:13:31Z" level=info msg="Successfully deleted rolebinding bundle-9f3e87de-491d-4fff-af2e-7710b7503f1e, namespace dh-postgresql-apb-prov-vcjv4"
>time="2018-08-31T18:13:31Z" level=debug msg="Deleting rolebinding bundle-9f3e87de-491d-4fff-af2e-7710b7503f1e, namespace blog-project"
>time="2018-08-31T18:13:31Z" level=info msg="Successfully deleted rolebinding bundle-9f3e87de-491d-4fff-af2e-7710b7503f1e, namespace blog-project"
>time="2018-08-31T18:13:31Z" level=debug msg="Deleting network policy for pod: bundle-9f3e87de-491d-4fff-af2e-7710b7503f1e to grant network access to ns: blog-project"
>time="2018-08-31T18:13:31Z" level=debug msg="Successfully deleted network policy for pod: bundle-9f3e87de-491d-4fff-af2e-7710b7503f1e to grant network access to ns: blog-project"
>time="2018-08-31T18:13:31Z" level=debug msg="Running post sandbox destroy hooks"
>time="2018-08-31T18:13:31Z" level=debug msg="executor::actionFinishedWithSuccess"
>time="2018-08-31T18:13:31Z" level=debug msg="closing channel for job fe802111-7f52-4bc3-83f2-4d55f78c782d"
>time="2018-08-31T18:13:31Z" level=debug msg="JobStateSubscriber Notify : msg state {fe802111-7f52-4bc3-83f2-4d55f78c782d succeeded  provision  provision job completed} "
>time="2018-08-31T18:13:31Z" level=debug msg="set job state for instance: 6bc15ef6-ad49-11e8-9470-0242ac11000b token: fe802111-7f52-4bc3-83f2-4d55f78c782d"
>time="2018-08-31T18:13:31Z" level=debug msg="JobStateSubscriber handleSucceeded : msg state {fe802111-7f52-4bc3-83f2-4d55f78c782d succeeded  provision  provision job completed} "
>time="2018-08-31T18:13:37Z" level=info msg="Request: \"GET /osb/v2/service_instances/6bc15ef6-ad49-11e8-9470-0242ac11000b/last_operation?operation=fe802111-7f52-4bc3-83f2-4d55f78c782d&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-31T18:13:37Z" level=debug msg="service_id: 1dda1477cace09730bd8ed7a6505607e"
>time="2018-08-31T18:13:37Z" level=debug msg="plan_id: 7f4a5e35e4af2beb70076e72fab0b7ff"
>time="2018-08-31T18:13:37Z" level=debug msg="operation:  fe802111-7f52-4bc3-83f2-4d55f78c782d"
>time="2018-08-31T18:13:37Z" level=debug msg="state: succeeded"
>time="2018-08-31T18:13:37Z" level=debug msg="description: provision job completed"
>172.17.0.8 - - [31/Aug/2018:18:13:37 +0000] "GET /osb/v2/service_instances/6bc15ef6-ad49-11e8-9470-0242ac11000b/last_operation?operation=fe802111-7f52-4bc3-83f2-4d55f78c782d&plan_id=7f4a5e35e4af2beb70076e72fab0b7ff&service_id=1dda1477cace09730bd8ed7a6505607e HTTP/1.1" 200 71
>time="2018-08-31T18:16:05Z" level=info msg="Request: \"PUT /osb/v2/service_instances/6bc15ef6-ad49-11e8-9470-0242ac11000b/service_bindings/eca9b104-ad49-11e8-9470-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\\\":\\\"5e839961-ad49-11e8-8f23-c85b76145add\\\"}}\""
>time="2018-08-31T18:16:05Z" level=debug msg="get service instance: 6bc15ef6-ad49-11e8-9470-0242ac11000b"
>time="2018-08-31T18:16:05Z" level=debug msg="get spec: 1dda1477cace09730bd8ed7a6505607e"
>time="2018-08-31T18:16:05Z" level=debug msg="Auto Escalate has been set to true, we are escalating permissions"
>time="2018-08-31T18:16:05Z" level=debug msg="Injecting PlanID as parameter: { _apb_plan_id: dev }"
>time="2018-08-31T18:16:05Z" level=debug msg="Injecting ServiceClassID as parameter: { _apb_service_class_id: 1dda1477cace09730bd8ed7a6505607e }"
>time="2018-08-31T18:16:05Z" level=debug msg="Injecting ServiceInstanceID as parameter: { _apb_service_instance_id: 6bc15ef6-ad49-11e8-9470-0242ac11000b }"
>time="2018-08-31T18:16:05Z" level=debug msg="Injecting lastRequestingUserKey as parameter: { _apb_last_requesting_user: admin }"
>time="2018-08-31T18:16:05Z" level=debug msg="Injecting ServiceBindingID as parameter: { _apb_service_binding_id: eca9b104-ad49-11e8-9470-0242ac11000b }"
>time="2018-08-31T18:16:05Z" level=debug msg="Found secret with name 6bc15ef6-ad49-11e8-9470-0242ac11000b\n"
>time="2018-08-31T18:16:05Z" level=debug msg="get binding instance: eca9b104-ad49-11e8-9470-0242ac11000b"
>time="2018-08-31T18:16:05Z" level=debug msg="set binding instance: eca9b104-ad49-11e8-9470-0242ac11000b"
>time="2018-08-31T18:16:05Z" level=info msg="ASYNC binding in progress"
>time="2018-08-31T18:16:05Z" level=debug msg="set job state for instance: eca9b104-ad49-11e8-9470-0242ac11000b token: 56877cb1-40bb-45c2-886e-586a36a2c17f"
>time="2018-08-31T18:16:05Z" level=debug msg="set binding instance: eca9b104-ad49-11e8-9470-0242ac11000b"
>time="2018-08-31T18:16:05Z" level=info msg="============================================================"
>time="2018-08-31T18:16:05Z" level=info msg="                       BINDING                              "
>time="2018-08-31T18:16:05Z" level=info msg="============================================================"
>time="2018-08-31T18:16:05Z" level=info msg="ServiceInstance.ID: 1dda1477cace09730bd8ed7a6505607e"
>time="2018-08-31T18:16:05Z" level=info msg="ServiceInstance.Name: dh-postgresql-apb"
>time="2018-08-31T18:16:05Z" level=info msg="ServiceInstance.Image: docker.io/mhrivnak/postgresql-apb:latest"
>time="2018-08-31T18:16:05Z" level=info msg="ServiceInstance.Description: SCL PostgreSQL apb implementation"
>time="2018-08-31T18:16:05Z" level=info msg="============================================================"
>time="2018-08-31T18:16:05Z" level=debug msg="executor::actionStarted"
>time="2018-08-31T18:16:05Z" level=debug msg="JobStateSubscriber Notify : msg state {56877cb1-40bb-45c2-886e-586a36a2c17f in progress  bind  action started} "
>time="2018-08-31T18:16:05Z" level=debug msg="set job state for instance: eca9b104-ad49-11e8-9470-0242ac11000b token: 56877cb1-40bb-45c2-886e-586a36a2c17f"
>time="2018-08-31T18:16:05Z" level=debug msg="Creating network policy for pod: bundle-22048b8a-3fe3-4a8e-8079-45f3a9f8bc2f to grant network access to ns: blog-project"
>time="2018-08-31T18:16:05Z" level=debug msg="Successfully created network policy for pod: bundle-22048b8a-3fe3-4a8e-8079-45f3a9f8bc2f to grant network access to ns: blog-project"
>time="2018-08-31T18:16:05Z" level=debug msg="Trying to create apb sandbox: [ bundle-22048b8a-3fe3-4a8e-8079-45f3a9f8bc2f ], with edit permissions in namespace dh-postgresql-apb-bind-7b5r4"
>time="2018-08-31T18:16:05Z" level=info msg="Creating RoleBinding bundle-22048b8a-3fe3-4a8e-8079-45f3a9f8bc2f"
>time="2018-08-31T18:16:05Z" level=debug msg="Binding eca9b104-ad49-11e8-9470-0242ac11000b already exists, skipping update because of conflict."
>172.17.0.8 - - [31/Aug/2018:18:16:05 +0000] "PUT /osb/v2/service_instances/6bc15ef6-ad49-11e8-9470-0242ac11000b/service_bindings/eca9b104-ad49-11e8-9470-0242ac11000b?accepts_incomplete=true HTTP/1.1" 202 58
>time="2018-08-31T18:16:05Z" level=info msg="Creating RoleBinding bundle-22048b8a-3fe3-4a8e-8079-45f3a9f8bc2f"
>time="2018-08-31T18:16:05Z" level=info msg="Successfully created apb sandbox: [ bundle-22048b8a-3fe3-4a8e-8079-45f3a9f8bc2f ], with edit permissions in namespace [ dh-postgresql-apb-bind-7b5r4 ]"
>time="2018-08-31T18:16:05Z" level=debug msg="Running post create sandbox functions if defined."
>time="2018-08-31T18:16:05Z" level=debug msg="ExecutingApb:"
>time="2018-08-31T18:16:05Z" level=debug msg="name:[ dh-postgresql-apb ]"
>time="2018-08-31T18:16:05Z" level=debug msg="image:[ docker.io/mhrivnak/postgresql-apb:latest ]"
>time="2018-08-31T18:16:05Z" level=debug msg="action:[ bind ]"
>time="2018-08-31T18:16:05Z" level=debug msg="pullPolicy:[ IfNotPresent ]"
>time="2018-08-31T18:16:05Z" level=debug msg="role:[ edit ]"
>client returned err  configmaps "6bc15ef6-ad49-11e8-9470-0242ac11000b-state" not found
>time="2018-08-31T18:16:05Z" level=info msg="Proxy configuration present. Applying to APB before execution:"
>time="2018-08-31T18:16:05Z" level=info msg="HTTP_PROXY=\"\""
>time="2018-08-31T18:16:05Z" level=info msg="HTTPS_PROXY=\"\""
>time="2018-08-31T18:16:05Z" level=info msg="NO_PROXY=\"\""
>time="2018-08-31T18:16:05Z" level=info msg="Creating pod \"bundle-22048b8a-3fe3-4a8e-8079-45f3a9f8bc2f\" in the dh-postgresql-apb-bind-7b5r4 namespace"
>time="2018-08-31T18:16:05Z" level=debug msg="Watching pod [ bundle-22048b8a-3fe3-4a8e-8079-45f3a9f8bc2f ] in namespace [ dh-postgresql-apb-bind-7b5r4 ] for completion"
>time="2018-08-31T18:16:05Z" level=debug msg="pod [bundle-22048b8a-3fe3-4a8e-8079-45f3a9f8bc2f] in phase Pending"
>time="2018-08-31T18:16:05Z" level=debug msg="Pod [ bundle-22048b8a-3fe3-4a8e-8079-45f3a9f8bc2f ] Pending"
>time="2018-08-31T18:16:05Z" level=debug msg="pod [bundle-22048b8a-3fe3-4a8e-8079-45f3a9f8bc2f] in phase Pending"
>time="2018-08-31T18:16:05Z" level=debug msg="Pod [ bundle-22048b8a-3fe3-4a8e-8079-45f3a9f8bc2f ] Pending"
>time="2018-08-31T18:16:05Z" level=debug msg="pod [bundle-22048b8a-3fe3-4a8e-8079-45f3a9f8bc2f] in phase Pending"
>time="2018-08-31T18:16:05Z" level=debug msg="Pod [ bundle-22048b8a-3fe3-4a8e-8079-45f3a9f8bc2f ] Pending"
>time="2018-08-31T18:16:06Z" level=info msg="Request: \"GET /osb/v2/service_instances/6bc15ef6-ad49-11e8-9470-0242ac11000b/service_bindings/eca9b104-ad49-11e8-9470-0242ac11000b/last_operation?operation=56877cb1-40bb-45c2-886e-586a36a2c17f&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-31T18:16:06Z" level=debug msg="get binding instance: eca9b104-ad49-11e8-9470-0242ac11000b"
>time="2018-08-31T18:16:06Z" level=debug msg="service_id: 1dda1477cace09730bd8ed7a6505607e"
>time="2018-08-31T18:16:06Z" level=debug msg="plan_id: 7f4a5e35e4af2beb70076e72fab0b7ff"
>time="2018-08-31T18:16:06Z" level=debug msg="operation:  56877cb1-40bb-45c2-886e-586a36a2c17f"
>time="2018-08-31T18:16:06Z" level=debug msg="state: in progress"
>time="2018-08-31T18:16:06Z" level=debug msg="description: action started"
>172.17.0.8 - - [31/Aug/2018:18:16:06 +0000] "GET /osb/v2/service_instances/6bc15ef6-ad49-11e8-9470-0242ac11000b/service_bindings/eca9b104-ad49-11e8-9470-0242ac11000b/last_operation?operation=56877cb1-40bb-45c2-886e-586a36a2c17f&plan_id=7f4a5e35e4af2beb70076e72fab0b7ff&service_id=1dda1477cace09730bd8ed7a6505607e HTTP/1.1" 200 64
>time="2018-08-31T18:16:06Z" level=debug msg="pod [bundle-22048b8a-3fe3-4a8e-8079-45f3a9f8bc2f] in phase Running"
>time="2018-08-31T18:16:06Z" level=debug msg="Pod [ bundle-22048b8a-3fe3-4a8e-8079-45f3a9f8bc2f ] Running"
>time="2018-08-31T18:16:08Z" level=info msg="Request: \"GET /osb/v2/service_instances/6bc15ef6-ad49-11e8-9470-0242ac11000b/service_bindings/eca9b104-ad49-11e8-9470-0242ac11000b/last_operation?operation=56877cb1-40bb-45c2-886e-586a36a2c17f&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-31T18:16:08Z" level=debug msg="get binding instance: eca9b104-ad49-11e8-9470-0242ac11000b"
>time="2018-08-31T18:16:08Z" level=debug msg="service_id: 1dda1477cace09730bd8ed7a6505607e"
>time="2018-08-31T18:16:08Z" level=debug msg="plan_id: 7f4a5e35e4af2beb70076e72fab0b7ff"
>time="2018-08-31T18:16:08Z" level=debug msg="operation:  56877cb1-40bb-45c2-886e-586a36a2c17f"
>time="2018-08-31T18:16:08Z" level=debug msg="state: in progress"
>time="2018-08-31T18:16:08Z" level=debug msg="description: action started"
>172.17.0.8 - - [31/Aug/2018:18:16:08 +0000] "GET /osb/v2/service_instances/6bc15ef6-ad49-11e8-9470-0242ac11000b/service_bindings/eca9b104-ad49-11e8-9470-0242ac11000b/last_operation?operation=56877cb1-40bb-45c2-886e-586a36a2c17f&plan_id=7f4a5e35e4af2beb70076e72fab0b7ff&service_id=1dda1477cace09730bd8ed7a6505607e HTTP/1.1" 200 64
>time="2018-08-31T18:16:12Z" level=debug msg="pod [bundle-22048b8a-3fe3-4a8e-8079-45f3a9f8bc2f] in phase Succeeded"
>time="2018-08-31T18:16:12Z" level=debug msg="Pod [ bundle-22048b8a-3fe3-4a8e-8079-45f3a9f8bc2f ] completed"
>time="2018-08-31T18:16:12Z" level=debug msg="state: copying state from namespace dh-postgresql-apb-bind-7b5r4 to ns automation-broker from name bundle-22048b8a-3fe3-4a8e-8079-45f3a9f8bc2f to name 6bc15ef6-ad49-11e8-9470-0242ac11000b-state"
>time="2018-08-31T18:16:12Z" level=debug msg="no state configmap found to copy"
>time="2018-08-31T18:16:12Z" level=debug msg="Found secret with name bundle-22048b8a-3fe3-4a8e-8079-45f3a9f8bc2f\n"
>time="2018-08-31T18:16:12Z" level=debug msg="executor::actionFinishedWithSuccess"
>time="2018-08-31T18:16:12Z" level=info msg="Destroying APB sandbox..."
>time="2018-08-31T18:16:12Z" level=debug msg="closing channel for job 56877cb1-40bb-45c2-886e-586a36a2c17f"
>time="2018-08-31T18:16:12Z" level=debug msg="JobStateSubscriber Notify : msg state {56877cb1-40bb-45c2-886e-586a36a2c17f succeeded  bind  bind job completed} "
>time="2018-08-31T18:16:12Z" level=debug msg="set job state for instance: eca9b104-ad49-11e8-9470-0242ac11000b token: 56877cb1-40bb-45c2-886e-586a36a2c17f"
>time="2018-08-31T18:16:12Z" level=debug msg="Deleting namespace dh-postgresql-apb-bind-7b5r4"
>time="2018-08-31T18:16:12Z" level=debug msg="Deleting rolebinding bundle-22048b8a-3fe3-4a8e-8079-45f3a9f8bc2f, namespace dh-postgresql-apb-bind-7b5r4"
>time="2018-08-31T18:16:12Z" level=debug msg="JobStateSubscriber handleSucceeded : msg state {56877cb1-40bb-45c2-886e-586a36a2c17f succeeded  bind  bind job completed} "
>time="2018-08-31T18:16:12Z" level=info msg="Successfully deleted rolebinding bundle-22048b8a-3fe3-4a8e-8079-45f3a9f8bc2f, namespace dh-postgresql-apb-bind-7b5r4"
>time="2018-08-31T18:16:12Z" level=debug msg="Deleting rolebinding bundle-22048b8a-3fe3-4a8e-8079-45f3a9f8bc2f, namespace blog-project"
>time="2018-08-31T18:16:12Z" level=info msg="Successfully deleted rolebinding bundle-22048b8a-3fe3-4a8e-8079-45f3a9f8bc2f, namespace blog-project"
>time="2018-08-31T18:16:12Z" level=debug msg="Deleting network policy for pod: bundle-22048b8a-3fe3-4a8e-8079-45f3a9f8bc2f to grant network access to ns: blog-project"
>time="2018-08-31T18:16:12Z" level=debug msg="Successfully deleted network policy for pod: bundle-22048b8a-3fe3-4a8e-8079-45f3a9f8bc2f to grant network access to ns: blog-project"
>time="2018-08-31T18:16:12Z" level=debug msg="Running post sandbox destroy hooks"
>time="2018-08-31T18:16:12Z" level=info msg="Request: \"GET /osb/v2/service_instances/6bc15ef6-ad49-11e8-9470-0242ac11000b/service_bindings/eca9b104-ad49-11e8-9470-0242ac11000b/last_operation?operation=56877cb1-40bb-45c2-886e-586a36a2c17f&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-31T18:16:12Z" level=debug msg="get binding instance: eca9b104-ad49-11e8-9470-0242ac11000b"
>time="2018-08-31T18:16:12Z" level=debug msg="service_id: 1dda1477cace09730bd8ed7a6505607e"
>time="2018-08-31T18:16:12Z" level=debug msg="plan_id: 7f4a5e35e4af2beb70076e72fab0b7ff"
>time="2018-08-31T18:16:12Z" level=debug msg="operation:  56877cb1-40bb-45c2-886e-586a36a2c17f"
>time="2018-08-31T18:16:12Z" level=debug msg="state: succeeded"
>time="2018-08-31T18:16:12Z" level=debug msg="description: bind job completed"
>172.17.0.8 - - [31/Aug/2018:18:16:12 +0000] "GET /osb/v2/service_instances/6bc15ef6-ad49-11e8-9470-0242ac11000b/service_bindings/eca9b104-ad49-11e8-9470-0242ac11000b/last_operation?operation=56877cb1-40bb-45c2-886e-586a36a2c17f&plan_id=7f4a5e35e4af2beb70076e72fab0b7ff&service_id=1dda1477cace09730bd8ed7a6505607e HTTP/1.1" 200 66
>time="2018-08-31T18:16:12Z" level=debug msg="Unable to find originating origin header"
>time="2018-08-31T18:16:12Z" level=info msg="Request: \"GET /osb/v2/service_instances/6bc15ef6-ad49-11e8-9470-0242ac11000b/service_bindings/eca9b104-ad49-11e8-9470-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-31T18:16:12Z" level=debug msg="get service instance: 6bc15ef6-ad49-11e8-9470-0242ac11000b"
>time="2018-08-31T18:16:12Z" level=debug msg="get spec: 1dda1477cace09730bd8ed7a6505607e"
>time="2018-08-31T18:16:12Z" level=debug msg="broker.GetBind: entered GetBind"
>time="2018-08-31T18:16:12Z" level=debug msg="Found secret with name 6bc15ef6-ad49-11e8-9470-0242ac11000b\n"
>time="2018-08-31T18:16:12Z" level=debug msg="get binding instance: eca9b104-ad49-11e8-9470-0242ac11000b"
>time="2018-08-31T18:16:12Z" level=debug msg="Found secret with name eca9b104-ad49-11e8-9470-0242ac11000b\n"
>time="2018-08-31T18:16:12Z" level=debug msg="broker.GetBind: we got the bind credentials"
>time="2018-08-31T18:16:12Z" level=debug msg="bind creds: map[DB_PORT:5432 DB_TYPE:postgres DB_USER:rjzquzbycvyjtuz DB_HOST:postgresql DB_NAME:rjzquzbycvyjtuz DB_PASSWORD:ESVfEe3w0rUEzKmVBgnE]"
>time="2018-08-31T18:16:12Z" level=debug msg="handler: bind found"
>172.17.0.8 - - [31/Aug/2018:18:16:12 +0000] "GET /osb/v2/service_instances/6bc15ef6-ad49-11e8-9470-0242ac11000b/service_bindings/eca9b104-ad49-11e8-9470-0242ac11000b HTTP/1.1" 200 216
>time="2018-08-31T18:17:03Z" level=info msg="Request: \"PUT /osb/v2/service_instances/6bc15ef6-ad49-11e8-9470-0242ac11000b/service_bindings/0f451da0-ad4a-11e8-9470-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\\\":\\\"5e839961-ad49-11e8-8f23-c85b76145add\\\"}}\""
>time="2018-08-31T18:17:03Z" level=debug msg="get service instance: 6bc15ef6-ad49-11e8-9470-0242ac11000b"
>time="2018-08-31T18:17:03Z" level=debug msg="get spec: 1dda1477cace09730bd8ed7a6505607e"
>time="2018-08-31T18:17:03Z" level=debug msg="Auto Escalate has been set to true, we are escalating permissions"
>time="2018-08-31T18:17:03Z" level=debug msg="Injecting PlanID as parameter: { _apb_plan_id: dev }"
>time="2018-08-31T18:17:03Z" level=debug msg="Injecting ServiceClassID as parameter: { _apb_service_class_id: 1dda1477cace09730bd8ed7a6505607e }"
>time="2018-08-31T18:17:03Z" level=debug msg="Injecting ServiceInstanceID as parameter: { _apb_service_instance_id: 6bc15ef6-ad49-11e8-9470-0242ac11000b }"
>time="2018-08-31T18:17:03Z" level=debug msg="Injecting lastRequestingUserKey as parameter: { _apb_last_requesting_user: admin }"
>time="2018-08-31T18:17:03Z" level=debug msg="Injecting ServiceBindingID as parameter: { _apb_service_binding_id: 0f451da0-ad4a-11e8-9470-0242ac11000b }"
>time="2018-08-31T18:17:03Z" level=debug msg="Found secret with name 6bc15ef6-ad49-11e8-9470-0242ac11000b\n"
>time="2018-08-31T18:17:03Z" level=debug msg="get binding instance: 0f451da0-ad4a-11e8-9470-0242ac11000b"
>time="2018-08-31T18:17:03Z" level=debug msg="set binding instance: 0f451da0-ad4a-11e8-9470-0242ac11000b"
>time="2018-08-31T18:17:03Z" level=info msg="ASYNC binding in progress"
>time="2018-08-31T18:17:03Z" level=debug msg="set job state for instance: 0f451da0-ad4a-11e8-9470-0242ac11000b token: 4a0c09e9-22fd-45c2-8f52-fb883ec7e5a7"
>time="2018-08-31T18:17:03Z" level=debug msg="set binding instance: 0f451da0-ad4a-11e8-9470-0242ac11000b"
>time="2018-08-31T18:17:03Z" level=info msg="============================================================"
>time="2018-08-31T18:17:03Z" level=info msg="                       BINDING                              "
>time="2018-08-31T18:17:03Z" level=info msg="============================================================"
>time="2018-08-31T18:17:03Z" level=info msg="ServiceInstance.ID: 1dda1477cace09730bd8ed7a6505607e"
>time="2018-08-31T18:17:03Z" level=info msg="ServiceInstance.Name: dh-postgresql-apb"
>time="2018-08-31T18:17:03Z" level=info msg="ServiceInstance.Image: docker.io/mhrivnak/postgresql-apb:latest"
>time="2018-08-31T18:17:03Z" level=info msg="ServiceInstance.Description: SCL PostgreSQL apb implementation"
>time="2018-08-31T18:17:03Z" level=info msg="============================================================"
>time="2018-08-31T18:17:03Z" level=debug msg="executor::actionStarted"
>time="2018-08-31T18:17:03Z" level=debug msg="JobStateSubscriber Notify : msg state {4a0c09e9-22fd-45c2-8f52-fb883ec7e5a7 in progress  bind  action started} "
>time="2018-08-31T18:17:03Z" level=debug msg="set job state for instance: 0f451da0-ad4a-11e8-9470-0242ac11000b token: 4a0c09e9-22fd-45c2-8f52-fb883ec7e5a7"
>time="2018-08-31T18:17:03Z" level=debug msg="Creating network policy for pod: bundle-616f03f3-6f1f-40d3-b228-81935f7bfa91 to grant network access to ns: blog-project"
>time="2018-08-31T18:17:03Z" level=debug msg="Successfully created network policy for pod: bundle-616f03f3-6f1f-40d3-b228-81935f7bfa91 to grant network access to ns: blog-project"
>time="2018-08-31T18:17:03Z" level=debug msg="Trying to create apb sandbox: [ bundle-616f03f3-6f1f-40d3-b228-81935f7bfa91 ], with edit permissions in namespace dh-postgresql-apb-bind-28hgd"
>time="2018-08-31T18:17:03Z" level=info msg="Creating RoleBinding bundle-616f03f3-6f1f-40d3-b228-81935f7bfa91"
>time="2018-08-31T18:17:03Z" level=debug msg="Binding 0f451da0-ad4a-11e8-9470-0242ac11000b already exists, skipping update because of conflict."
>172.17.0.8 - - [31/Aug/2018:18:17:03 +0000] "PUT /osb/v2/service_instances/6bc15ef6-ad49-11e8-9470-0242ac11000b/service_bindings/0f451da0-ad4a-11e8-9470-0242ac11000b?accepts_incomplete=true HTTP/1.1" 202 58
>time="2018-08-31T18:17:03Z" level=info msg="Creating RoleBinding bundle-616f03f3-6f1f-40d3-b228-81935f7bfa91"
>time="2018-08-31T18:17:03Z" level=info msg="Successfully created apb sandbox: [ bundle-616f03f3-6f1f-40d3-b228-81935f7bfa91 ], with edit permissions in namespace [ dh-postgresql-apb-bind-28hgd ]"
>time="2018-08-31T18:17:03Z" level=debug msg="Running post create sandbox functions if defined."
>time="2018-08-31T18:17:03Z" level=debug msg="ExecutingApb:"
>time="2018-08-31T18:17:03Z" level=debug msg="name:[ dh-postgresql-apb ]"
>time="2018-08-31T18:17:03Z" level=debug msg="image:[ docker.io/mhrivnak/postgresql-apb:latest ]"
>time="2018-08-31T18:17:03Z" level=debug msg="action:[ bind ]"
>time="2018-08-31T18:17:03Z" level=debug msg="pullPolicy:[ IfNotPresent ]"
>time="2018-08-31T18:17:03Z" level=debug msg="role:[ edit ]"
>client returned err  configmaps "6bc15ef6-ad49-11e8-9470-0242ac11000b-state" not found
>time="2018-08-31T18:17:03Z" level=info msg="Proxy configuration present. Applying to APB before execution:"
>time="2018-08-31T18:17:03Z" level=info msg="HTTP_PROXY=\"\""
>time="2018-08-31T18:17:03Z" level=info msg="HTTPS_PROXY=\"\""
>time="2018-08-31T18:17:03Z" level=info msg="NO_PROXY=\"\""
>time="2018-08-31T18:17:03Z" level=info msg="Creating pod \"bundle-616f03f3-6f1f-40d3-b228-81935f7bfa91\" in the dh-postgresql-apb-bind-28hgd namespace"
>time="2018-08-31T18:17:03Z" level=debug msg="Watching pod [ bundle-616f03f3-6f1f-40d3-b228-81935f7bfa91 ] in namespace [ dh-postgresql-apb-bind-28hgd ] for completion"
>time="2018-08-31T18:17:03Z" level=debug msg="pod [bundle-616f03f3-6f1f-40d3-b228-81935f7bfa91] in phase Pending"
>time="2018-08-31T18:17:03Z" level=debug msg="Pod [ bundle-616f03f3-6f1f-40d3-b228-81935f7bfa91 ] Pending"
>time="2018-08-31T18:17:03Z" level=debug msg="pod [bundle-616f03f3-6f1f-40d3-b228-81935f7bfa91] in phase Pending"
>time="2018-08-31T18:17:03Z" level=debug msg="Pod [ bundle-616f03f3-6f1f-40d3-b228-81935f7bfa91 ] Pending"
>time="2018-08-31T18:17:03Z" level=debug msg="pod [bundle-616f03f3-6f1f-40d3-b228-81935f7bfa91] in phase Pending"
>time="2018-08-31T18:17:03Z" level=debug msg="Pod [ bundle-616f03f3-6f1f-40d3-b228-81935f7bfa91 ] Pending"
>time="2018-08-31T18:17:04Z" level=info msg="Request: \"GET /osb/v2/service_instances/6bc15ef6-ad49-11e8-9470-0242ac11000b/service_bindings/0f451da0-ad4a-11e8-9470-0242ac11000b/last_operation?operation=4a0c09e9-22fd-45c2-8f52-fb883ec7e5a7&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-31T18:17:04Z" level=debug msg="get binding instance: 0f451da0-ad4a-11e8-9470-0242ac11000b"
>time="2018-08-31T18:17:04Z" level=debug msg="service_id: 1dda1477cace09730bd8ed7a6505607e"
>time="2018-08-31T18:17:04Z" level=debug msg="plan_id: 7f4a5e35e4af2beb70076e72fab0b7ff"
>time="2018-08-31T18:17:04Z" level=debug msg="operation:  4a0c09e9-22fd-45c2-8f52-fb883ec7e5a7"
>time="2018-08-31T18:17:04Z" level=debug msg="state: in progress"
>time="2018-08-31T18:17:04Z" level=debug msg="description: action started"
>172.17.0.8 - - [31/Aug/2018:18:17:04 +0000] "GET /osb/v2/service_instances/6bc15ef6-ad49-11e8-9470-0242ac11000b/service_bindings/0f451da0-ad4a-11e8-9470-0242ac11000b/last_operation?operation=4a0c09e9-22fd-45c2-8f52-fb883ec7e5a7&plan_id=7f4a5e35e4af2beb70076e72fab0b7ff&service_id=1dda1477cace09730bd8ed7a6505607e HTTP/1.1" 200 64
>time="2018-08-31T18:17:05Z" level=debug msg="pod [bundle-616f03f3-6f1f-40d3-b228-81935f7bfa91] in phase Running"
>time="2018-08-31T18:17:05Z" level=debug msg="Pod [ bundle-616f03f3-6f1f-40d3-b228-81935f7bfa91 ] Running"
>time="2018-08-31T18:17:06Z" level=info msg="Request: \"GET /osb/v2/service_instances/6bc15ef6-ad49-11e8-9470-0242ac11000b/service_bindings/0f451da0-ad4a-11e8-9470-0242ac11000b/last_operation?operation=4a0c09e9-22fd-45c2-8f52-fb883ec7e5a7&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-31T18:17:06Z" level=debug msg="get binding instance: 0f451da0-ad4a-11e8-9470-0242ac11000b"
>time="2018-08-31T18:17:06Z" level=debug msg="service_id: 1dda1477cace09730bd8ed7a6505607e"
>time="2018-08-31T18:17:06Z" level=debug msg="plan_id: 7f4a5e35e4af2beb70076e72fab0b7ff"
>time="2018-08-31T18:17:06Z" level=debug msg="operation:  4a0c09e9-22fd-45c2-8f52-fb883ec7e5a7"
>time="2018-08-31T18:17:06Z" level=debug msg="state: in progress"
>time="2018-08-31T18:17:06Z" level=debug msg="description: action started"
>172.17.0.8 - - [31/Aug/2018:18:17:06 +0000] "GET /osb/v2/service_instances/6bc15ef6-ad49-11e8-9470-0242ac11000b/service_bindings/0f451da0-ad4a-11e8-9470-0242ac11000b/last_operation?operation=4a0c09e9-22fd-45c2-8f52-fb883ec7e5a7&plan_id=7f4a5e35e4af2beb70076e72fab0b7ff&service_id=1dda1477cace09730bd8ed7a6505607e HTTP/1.1" 200 64
>time="2018-08-31T18:17:09Z" level=debug msg="pod [bundle-616f03f3-6f1f-40d3-b228-81935f7bfa91] in phase Succeeded"
>time="2018-08-31T18:17:09Z" level=debug msg="Pod [ bundle-616f03f3-6f1f-40d3-b228-81935f7bfa91 ] completed"
>time="2018-08-31T18:17:09Z" level=debug msg="state: copying state from namespace dh-postgresql-apb-bind-28hgd to ns automation-broker from name bundle-616f03f3-6f1f-40d3-b228-81935f7bfa91 to name 6bc15ef6-ad49-11e8-9470-0242ac11000b-state"
>time="2018-08-31T18:17:09Z" level=debug msg="no state configmap found to copy"
>time="2018-08-31T18:17:09Z" level=debug msg="Found secret with name bundle-616f03f3-6f1f-40d3-b228-81935f7bfa91\n"
>time="2018-08-31T18:17:09Z" level=debug msg="executor::actionFinishedWithSuccess"
>time="2018-08-31T18:17:09Z" level=info msg="Destroying APB sandbox..."
>time="2018-08-31T18:17:09Z" level=debug msg="closing channel for job 4a0c09e9-22fd-45c2-8f52-fb883ec7e5a7"
>time="2018-08-31T18:17:09Z" level=debug msg="JobStateSubscriber Notify : msg state {4a0c09e9-22fd-45c2-8f52-fb883ec7e5a7 succeeded  bind  bind job completed} "
>time="2018-08-31T18:17:09Z" level=debug msg="set job state for instance: 0f451da0-ad4a-11e8-9470-0242ac11000b token: 4a0c09e9-22fd-45c2-8f52-fb883ec7e5a7"
>time="2018-08-31T18:17:09Z" level=debug msg="Deleting namespace dh-postgresql-apb-bind-28hgd"
>time="2018-08-31T18:17:09Z" level=debug msg="Deleting rolebinding bundle-616f03f3-6f1f-40d3-b228-81935f7bfa91, namespace dh-postgresql-apb-bind-28hgd"
>time="2018-08-31T18:17:09Z" level=debug msg="JobStateSubscriber handleSucceeded : msg state {4a0c09e9-22fd-45c2-8f52-fb883ec7e5a7 succeeded  bind  bind job completed} "
>time="2018-08-31T18:17:09Z" level=info msg="Successfully deleted rolebinding bundle-616f03f3-6f1f-40d3-b228-81935f7bfa91, namespace dh-postgresql-apb-bind-28hgd"
>time="2018-08-31T18:17:09Z" level=debug msg="Deleting rolebinding bundle-616f03f3-6f1f-40d3-b228-81935f7bfa91, namespace blog-project"
>time="2018-08-31T18:17:09Z" level=info msg="Successfully deleted rolebinding bundle-616f03f3-6f1f-40d3-b228-81935f7bfa91, namespace blog-project"
>time="2018-08-31T18:17:09Z" level=debug msg="Deleting network policy for pod: bundle-616f03f3-6f1f-40d3-b228-81935f7bfa91 to grant network access to ns: blog-project"
>time="2018-08-31T18:17:09Z" level=debug msg="Successfully deleted network policy for pod: bundle-616f03f3-6f1f-40d3-b228-81935f7bfa91 to grant network access to ns: blog-project"
>time="2018-08-31T18:17:09Z" level=debug msg="Running post sandbox destroy hooks"
>time="2018-08-31T18:17:10Z" level=info msg="Request: \"GET /osb/v2/service_instances/6bc15ef6-ad49-11e8-9470-0242ac11000b/service_bindings/0f451da0-ad4a-11e8-9470-0242ac11000b/last_operation?operation=4a0c09e9-22fd-45c2-8f52-fb883ec7e5a7&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-31T18:17:10Z" level=debug msg="get binding instance: 0f451da0-ad4a-11e8-9470-0242ac11000b"
>time="2018-08-31T18:17:10Z" level=debug msg="service_id: 1dda1477cace09730bd8ed7a6505607e"
>time="2018-08-31T18:17:10Z" level=debug msg="plan_id: 7f4a5e35e4af2beb70076e72fab0b7ff"
>time="2018-08-31T18:17:10Z" level=debug msg="operation:  4a0c09e9-22fd-45c2-8f52-fb883ec7e5a7"
>time="2018-08-31T18:17:10Z" level=debug msg="state: succeeded"
>time="2018-08-31T18:17:10Z" level=debug msg="description: bind job completed"
>172.17.0.8 - - [31/Aug/2018:18:17:10 +0000] "GET /osb/v2/service_instances/6bc15ef6-ad49-11e8-9470-0242ac11000b/service_bindings/0f451da0-ad4a-11e8-9470-0242ac11000b/last_operation?operation=4a0c09e9-22fd-45c2-8f52-fb883ec7e5a7&plan_id=7f4a5e35e4af2beb70076e72fab0b7ff&service_id=1dda1477cace09730bd8ed7a6505607e HTTP/1.1" 200 66
>time="2018-08-31T18:17:10Z" level=debug msg="Unable to find originating origin header"
>time="2018-08-31T18:17:10Z" level=info msg="Request: \"GET /osb/v2/service_instances/6bc15ef6-ad49-11e8-9470-0242ac11000b/service_bindings/0f451da0-ad4a-11e8-9470-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-31T18:17:10Z" level=debug msg="get service instance: 6bc15ef6-ad49-11e8-9470-0242ac11000b"
>time="2018-08-31T18:17:10Z" level=debug msg="get spec: 1dda1477cace09730bd8ed7a6505607e"
>time="2018-08-31T18:17:10Z" level=debug msg="broker.GetBind: entered GetBind"
>time="2018-08-31T18:17:10Z" level=debug msg="Found secret with name 6bc15ef6-ad49-11e8-9470-0242ac11000b\n"
>time="2018-08-31T18:17:10Z" level=debug msg="get binding instance: 0f451da0-ad4a-11e8-9470-0242ac11000b"
>time="2018-08-31T18:17:10Z" level=debug msg="Found secret with name 0f451da0-ad4a-11e8-9470-0242ac11000b\n"
>time="2018-08-31T18:17:10Z" level=debug msg="broker.GetBind: we got the bind credentials"
>time="2018-08-31T18:17:10Z" level=debug msg="bind creds: map[DB_HOST:postgresql DB_NAME:daaihgiyzwtrlnt DB_PASSWORD:nN8FwLtkEyqX2n6s02VK DB_PORT:5432 DB_TYPE:postgres DB_USER:daaihgiyzwtrlnt]"
>time="2018-08-31T18:17:10Z" level=debug msg="handler: bind found"
>172.17.0.8 - - [31/Aug/2018:18:17:10 +0000] "GET /osb/v2/service_instances/6bc15ef6-ad49-11e8-9470-0242ac11000b/service_bindings/0f451da0-ad4a-11e8-9470-0242ac11000b HTTP/1.1" 200 216
>time="2018-08-31T18:17:12Z" level=info msg="Request: \"PUT /osb/v2/service_instances/6bc15ef6-ad49-11e8-9470-0242ac11000b/service_bindings/14eed73f-ad4a-11e8-9470-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\\\":\\\"5e839961-ad49-11e8-8f23-c85b76145add\\\"}}\""
>time="2018-08-31T18:17:12Z" level=debug msg="get service instance: 6bc15ef6-ad49-11e8-9470-0242ac11000b"
>time="2018-08-31T18:17:12Z" level=debug msg="get spec: 1dda1477cace09730bd8ed7a6505607e"
>time="2018-08-31T18:17:12Z" level=debug msg="Auto Escalate has been set to true, we are escalating permissions"
>time="2018-08-31T18:17:12Z" level=debug msg="Injecting PlanID as parameter: { _apb_plan_id: dev }"
>time="2018-08-31T18:17:12Z" level=debug msg="Injecting ServiceClassID as parameter: { _apb_service_class_id: 1dda1477cace09730bd8ed7a6505607e }"
>time="2018-08-31T18:17:12Z" level=debug msg="Injecting ServiceInstanceID as parameter: { _apb_service_instance_id: 6bc15ef6-ad49-11e8-9470-0242ac11000b }"
>time="2018-08-31T18:17:12Z" level=debug msg="Injecting lastRequestingUserKey as parameter: { _apb_last_requesting_user: admin }"
>time="2018-08-31T18:17:12Z" level=debug msg="Injecting ServiceBindingID as parameter: { _apb_service_binding_id: 14eed73f-ad4a-11e8-9470-0242ac11000b }"
>time="2018-08-31T18:17:12Z" level=debug msg="Found secret with name 6bc15ef6-ad49-11e8-9470-0242ac11000b\n"
>time="2018-08-31T18:17:12Z" level=debug msg="get binding instance: 14eed73f-ad4a-11e8-9470-0242ac11000b"
>time="2018-08-31T18:17:12Z" level=debug msg="set binding instance: 14eed73f-ad4a-11e8-9470-0242ac11000b"
>time="2018-08-31T18:17:13Z" level=info msg="ASYNC binding in progress"
>time="2018-08-31T18:17:13Z" level=debug msg="set job state for instance: 14eed73f-ad4a-11e8-9470-0242ac11000b token: 5a095008-a58d-4913-9db9-fa6b64e3e65e"
>time="2018-08-31T18:17:13Z" level=debug msg="set binding instance: 14eed73f-ad4a-11e8-9470-0242ac11000b"
>time="2018-08-31T18:17:13Z" level=info msg="============================================================"
>time="2018-08-31T18:17:13Z" level=info msg="                       BINDING                              "
>time="2018-08-31T18:17:13Z" level=info msg="============================================================"
>time="2018-08-31T18:17:13Z" level=info msg="ServiceInstance.ID: 1dda1477cace09730bd8ed7a6505607e"
>time="2018-08-31T18:17:13Z" level=info msg="ServiceInstance.Name: dh-postgresql-apb"
>time="2018-08-31T18:17:13Z" level=info msg="ServiceInstance.Image: docker.io/mhrivnak/postgresql-apb:latest"
>time="2018-08-31T18:17:13Z" level=info msg="ServiceInstance.Description: SCL PostgreSQL apb implementation"
>time="2018-08-31T18:17:13Z" level=info msg="============================================================"
>time="2018-08-31T18:17:13Z" level=debug msg="executor::actionStarted"
>time="2018-08-31T18:17:13Z" level=debug msg="JobStateSubscriber Notify : msg state {5a095008-a58d-4913-9db9-fa6b64e3e65e in progress  bind  action started} "
>time="2018-08-31T18:17:13Z" level=debug msg="set job state for instance: 14eed73f-ad4a-11e8-9470-0242ac11000b token: 5a095008-a58d-4913-9db9-fa6b64e3e65e"
>time="2018-08-31T18:17:13Z" level=debug msg="Creating network policy for pod: bundle-4c74fda7-e86b-4da9-86f5-909a13c2a395 to grant network access to ns: blog-project"
>time="2018-08-31T18:17:13Z" level=debug msg="Binding 14eed73f-ad4a-11e8-9470-0242ac11000b already exists, skipping update because of conflict."
>172.17.0.8 - - [31/Aug/2018:18:17:12 +0000] "PUT /osb/v2/service_instances/6bc15ef6-ad49-11e8-9470-0242ac11000b/service_bindings/14eed73f-ad4a-11e8-9470-0242ac11000b?accepts_incomplete=true HTTP/1.1" 202 58
>time="2018-08-31T18:17:13Z" level=debug msg="Successfully created network policy for pod: bundle-4c74fda7-e86b-4da9-86f5-909a13c2a395 to grant network access to ns: blog-project"
>time="2018-08-31T18:17:13Z" level=debug msg="Trying to create apb sandbox: [ bundle-4c74fda7-e86b-4da9-86f5-909a13c2a395 ], with edit permissions in namespace dh-postgresql-apb-bind-fwwqg"
>time="2018-08-31T18:17:13Z" level=info msg="Creating RoleBinding bundle-4c74fda7-e86b-4da9-86f5-909a13c2a395"
>time="2018-08-31T18:17:13Z" level=info msg="Creating RoleBinding bundle-4c74fda7-e86b-4da9-86f5-909a13c2a395"
>time="2018-08-31T18:17:13Z" level=info msg="Successfully created apb sandbox: [ bundle-4c74fda7-e86b-4da9-86f5-909a13c2a395 ], with edit permissions in namespace [ dh-postgresql-apb-bind-fwwqg ]"
>time="2018-08-31T18:17:13Z" level=debug msg="Running post create sandbox functions if defined."
>time="2018-08-31T18:17:13Z" level=debug msg="ExecutingApb:"
>time="2018-08-31T18:17:13Z" level=debug msg="name:[ dh-postgresql-apb ]"
>time="2018-08-31T18:17:13Z" level=debug msg="image:[ docker.io/mhrivnak/postgresql-apb:latest ]"
>time="2018-08-31T18:17:13Z" level=debug msg="action:[ bind ]"
>time="2018-08-31T18:17:13Z" level=debug msg="pullPolicy:[ IfNotPresent ]"
>time="2018-08-31T18:17:13Z" level=debug msg="role:[ edit ]"
>client returned err  configmaps "6bc15ef6-ad49-11e8-9470-0242ac11000b-state" not found
>time="2018-08-31T18:17:13Z" level=info msg="Proxy configuration present. Applying to APB before execution:"
>time="2018-08-31T18:17:13Z" level=info msg="HTTP_PROXY=\"\""
>time="2018-08-31T18:17:13Z" level=info msg="HTTPS_PROXY=\"\""
>time="2018-08-31T18:17:13Z" level=info msg="NO_PROXY=\"\""
>time="2018-08-31T18:17:13Z" level=info msg="Creating pod \"bundle-4c74fda7-e86b-4da9-86f5-909a13c2a395\" in the dh-postgresql-apb-bind-fwwqg namespace"
>time="2018-08-31T18:17:13Z" level=debug msg="Watching pod [ bundle-4c74fda7-e86b-4da9-86f5-909a13c2a395 ] in namespace [ dh-postgresql-apb-bind-fwwqg ] for completion"
>time="2018-08-31T18:17:13Z" level=debug msg="pod [bundle-4c74fda7-e86b-4da9-86f5-909a13c2a395] in phase Pending"
>time="2018-08-31T18:17:13Z" level=debug msg="Pod [ bundle-4c74fda7-e86b-4da9-86f5-909a13c2a395 ] Pending"
>time="2018-08-31T18:17:13Z" level=debug msg="pod [bundle-4c74fda7-e86b-4da9-86f5-909a13c2a395] in phase Pending"
>time="2018-08-31T18:17:13Z" level=debug msg="Pod [ bundle-4c74fda7-e86b-4da9-86f5-909a13c2a395 ] Pending"
>time="2018-08-31T18:17:13Z" level=debug msg="pod [bundle-4c74fda7-e86b-4da9-86f5-909a13c2a395] in phase Pending"
>time="2018-08-31T18:17:13Z" level=debug msg="Pod [ bundle-4c74fda7-e86b-4da9-86f5-909a13c2a395 ] Pending"
>time="2018-08-31T18:17:14Z" level=info msg="Request: \"GET /osb/v2/service_instances/6bc15ef6-ad49-11e8-9470-0242ac11000b/service_bindings/14eed73f-ad4a-11e8-9470-0242ac11000b/last_operation?operation=5a095008-a58d-4913-9db9-fa6b64e3e65e&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-31T18:17:14Z" level=debug msg="get binding instance: 14eed73f-ad4a-11e8-9470-0242ac11000b"
>time="2018-08-31T18:17:14Z" level=debug msg="service_id: 1dda1477cace09730bd8ed7a6505607e"
>time="2018-08-31T18:17:14Z" level=debug msg="plan_id: 7f4a5e35e4af2beb70076e72fab0b7ff"
>time="2018-08-31T18:17:14Z" level=debug msg="operation:  5a095008-a58d-4913-9db9-fa6b64e3e65e"
>time="2018-08-31T18:17:14Z" level=debug msg="state: in progress"
>time="2018-08-31T18:17:14Z" level=debug msg="description: action started"
>172.17.0.8 - - [31/Aug/2018:18:17:14 +0000] "GET /osb/v2/service_instances/6bc15ef6-ad49-11e8-9470-0242ac11000b/service_bindings/14eed73f-ad4a-11e8-9470-0242ac11000b/last_operation?operation=5a095008-a58d-4913-9db9-fa6b64e3e65e&plan_id=7f4a5e35e4af2beb70076e72fab0b7ff&service_id=1dda1477cace09730bd8ed7a6505607e HTTP/1.1" 200 64
>time="2018-08-31T18:17:14Z" level=debug msg="pod [bundle-4c74fda7-e86b-4da9-86f5-909a13c2a395] in phase Running"
>time="2018-08-31T18:17:14Z" level=debug msg="Pod [ bundle-4c74fda7-e86b-4da9-86f5-909a13c2a395 ] Running"
>time="2018-08-31T18:17:16Z" level=info msg="Request: \"GET /osb/v2/service_instances/6bc15ef6-ad49-11e8-9470-0242ac11000b/service_bindings/14eed73f-ad4a-11e8-9470-0242ac11000b/last_operation?operation=5a095008-a58d-4913-9db9-fa6b64e3e65e&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-31T18:17:16Z" level=debug msg="get binding instance: 14eed73f-ad4a-11e8-9470-0242ac11000b"
>time="2018-08-31T18:17:16Z" level=debug msg="service_id: 1dda1477cace09730bd8ed7a6505607e"
>time="2018-08-31T18:17:16Z" level=debug msg="plan_id: 7f4a5e35e4af2beb70076e72fab0b7ff"
>time="2018-08-31T18:17:16Z" level=debug msg="operation:  5a095008-a58d-4913-9db9-fa6b64e3e65e"
>time="2018-08-31T18:17:16Z" level=debug msg="state: in progress"
>time="2018-08-31T18:17:16Z" level=debug msg="description: action started"
>172.17.0.8 - - [31/Aug/2018:18:17:16 +0000] "GET /osb/v2/service_instances/6bc15ef6-ad49-11e8-9470-0242ac11000b/service_bindings/14eed73f-ad4a-11e8-9470-0242ac11000b/last_operation?operation=5a095008-a58d-4913-9db9-fa6b64e3e65e&plan_id=7f4a5e35e4af2beb70076e72fab0b7ff&service_id=1dda1477cace09730bd8ed7a6505607e HTTP/1.1" 200 64
>time="2018-08-31T18:17:19Z" level=debug msg="pod [bundle-4c74fda7-e86b-4da9-86f5-909a13c2a395] in phase Succeeded"
>time="2018-08-31T18:17:19Z" level=debug msg="Pod [ bundle-4c74fda7-e86b-4da9-86f5-909a13c2a395 ] completed"
>time="2018-08-31T18:17:19Z" level=debug msg="state: copying state from namespace dh-postgresql-apb-bind-fwwqg to ns automation-broker from name bundle-4c74fda7-e86b-4da9-86f5-909a13c2a395 to name 6bc15ef6-ad49-11e8-9470-0242ac11000b-state"
>time="2018-08-31T18:17:19Z" level=debug msg="no state configmap found to copy"
>time="2018-08-31T18:17:19Z" level=debug msg="Found secret with name bundle-4c74fda7-e86b-4da9-86f5-909a13c2a395\n"
>time="2018-08-31T18:17:19Z" level=debug msg="executor::actionFinishedWithSuccess"
>time="2018-08-31T18:17:19Z" level=info msg="Destroying APB sandbox..."
>time="2018-08-31T18:17:19Z" level=debug msg="closing channel for job 5a095008-a58d-4913-9db9-fa6b64e3e65e"
>time="2018-08-31T18:17:19Z" level=debug msg="JobStateSubscriber Notify : msg state {5a095008-a58d-4913-9db9-fa6b64e3e65e succeeded  bind  bind job completed} "
>time="2018-08-31T18:17:19Z" level=debug msg="set job state for instance: 14eed73f-ad4a-11e8-9470-0242ac11000b token: 5a095008-a58d-4913-9db9-fa6b64e3e65e"
>time="2018-08-31T18:17:19Z" level=debug msg="Deleting namespace dh-postgresql-apb-bind-fwwqg"
>time="2018-08-31T18:17:19Z" level=debug msg="JobStateSubscriber handleSucceeded : msg state {5a095008-a58d-4913-9db9-fa6b64e3e65e succeeded  bind  bind job completed} "
>time="2018-08-31T18:17:19Z" level=debug msg="Deleting rolebinding bundle-4c74fda7-e86b-4da9-86f5-909a13c2a395, namespace dh-postgresql-apb-bind-fwwqg"
>time="2018-08-31T18:17:19Z" level=info msg="Successfully deleted rolebinding bundle-4c74fda7-e86b-4da9-86f5-909a13c2a395, namespace dh-postgresql-apb-bind-fwwqg"
>time="2018-08-31T18:17:19Z" level=debug msg="Deleting rolebinding bundle-4c74fda7-e86b-4da9-86f5-909a13c2a395, namespace blog-project"
>time="2018-08-31T18:17:19Z" level=info msg="Successfully deleted rolebinding bundle-4c74fda7-e86b-4da9-86f5-909a13c2a395, namespace blog-project"
>time="2018-08-31T18:17:19Z" level=debug msg="Deleting network policy for pod: bundle-4c74fda7-e86b-4da9-86f5-909a13c2a395 to grant network access to ns: blog-project"
>time="2018-08-31T18:17:19Z" level=debug msg="Successfully deleted network policy for pod: bundle-4c74fda7-e86b-4da9-86f5-909a13c2a395 to grant network access to ns: blog-project"
>time="2018-08-31T18:17:19Z" level=debug msg="Running post sandbox destroy hooks"
>time="2018-08-31T18:17:20Z" level=info msg="Request: \"GET /osb/v2/service_instances/6bc15ef6-ad49-11e8-9470-0242ac11000b/service_bindings/14eed73f-ad4a-11e8-9470-0242ac11000b/last_operation?operation=5a095008-a58d-4913-9db9-fa6b64e3e65e&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-31T18:17:20Z" level=debug msg="get binding instance: 14eed73f-ad4a-11e8-9470-0242ac11000b"
>time="2018-08-31T18:17:20Z" level=debug msg="service_id: 1dda1477cace09730bd8ed7a6505607e"
>time="2018-08-31T18:17:20Z" level=debug msg="plan_id: 7f4a5e35e4af2beb70076e72fab0b7ff"
>time="2018-08-31T18:17:20Z" level=debug msg="operation:  5a095008-a58d-4913-9db9-fa6b64e3e65e"
>time="2018-08-31T18:17:20Z" level=debug msg="state: succeeded"
>time="2018-08-31T18:17:20Z" level=debug msg="description: bind job completed"
>172.17.0.8 - - [31/Aug/2018:18:17:20 +0000] "GET /osb/v2/service_instances/6bc15ef6-ad49-11e8-9470-0242ac11000b/service_bindings/14eed73f-ad4a-11e8-9470-0242ac11000b/last_operation?operation=5a095008-a58d-4913-9db9-fa6b64e3e65e&plan_id=7f4a5e35e4af2beb70076e72fab0b7ff&service_id=1dda1477cace09730bd8ed7a6505607e HTTP/1.1" 200 66
>time="2018-08-31T18:17:20Z" level=debug msg="Unable to find originating origin header"
>time="2018-08-31T18:17:20Z" level=info msg="Request: \"GET /osb/v2/service_instances/6bc15ef6-ad49-11e8-9470-0242ac11000b/service_bindings/14eed73f-ad4a-11e8-9470-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-31T18:17:20Z" level=debug msg="get service instance: 6bc15ef6-ad49-11e8-9470-0242ac11000b"
>time="2018-08-31T18:17:20Z" level=debug msg="get spec: 1dda1477cace09730bd8ed7a6505607e"
>time="2018-08-31T18:17:20Z" level=debug msg="broker.GetBind: entered GetBind"
>time="2018-08-31T18:17:20Z" level=debug msg="Found secret with name 6bc15ef6-ad49-11e8-9470-0242ac11000b\n"
>time="2018-08-31T18:17:20Z" level=debug msg="get binding instance: 14eed73f-ad4a-11e8-9470-0242ac11000b"
>time="2018-08-31T18:17:20Z" level=debug msg="Found secret with name 14eed73f-ad4a-11e8-9470-0242ac11000b\n"
>time="2018-08-31T18:17:20Z" level=debug msg="broker.GetBind: we got the bind credentials"
>time="2018-08-31T18:17:20Z" level=debug msg="bind creds: map[DB_HOST:postgresql DB_NAME:ckohliudezvaevw DB_PASSWORD:VWwDN63anPqFtWSu5evS DB_PORT:5432 DB_TYPE:postgres DB_USER:ckohliudezvaevw]"
>time="2018-08-31T18:17:20Z" level=debug msg="handler: bind found"
>172.17.0.8 - - [31/Aug/2018:18:17:20 +0000] "GET /osb/v2/service_instances/6bc15ef6-ad49-11e8-9470-0242ac11000b/service_bindings/14eed73f-ad4a-11e8-9470-0242ac11000b HTTP/1.1" 200 216

Actions: View

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