Just a follow up:
I looked at the juju consolidated debug-log, and I see lots of messages from
the HOOKs logged. It seems that it fails during tools provisioning, and then
never moves on from there:
ip-172-31-27-140:2014-01-07 17:57:23 INFO juju.worker.uniter context.go:255
HOOK ldconfig deferred processing now taking place
ip-172-31-27-140:2014-01-07 17:57:26 INFO juju.worker.uniter uniter.go:356 ran
"config-changed" hook
ip-172-31-27-140:2014-01-07 17:57:26 INFO juju.worker.uniter uniter.go:363
committing "config-changed" hook
ip-172-31-27-140:2014-01-07 17:57:26 INFO juju.worker.uniter uniter.go:381
committed "config-changed" hook
ip-172-31-23-241:2014-01-07 17:58:41 INFO juju.worker.addressupdater
updater.go:226 machine "1" has new addresses:
[public:ec2-54-200-179-138.us-west-2.compute.amazonaws.com
local-cloud:ip-172-31-30-157.us-west-2.compute.internal public:54.200.179.138
local-cloud:172.31.30.157]
ip-172-31-23-241:2014-01-07 17:58:44 DEBUG juju.rpc.jsoncodec codec.go:172 ->
{"RequestId":23,"Response":{}}
ip-172-31-23-241:2014-01-07 17:58:44 DEBUG juju.rpc.jsoncodec codec.go:107 <-
{"RequestId":42,"Type":"NotifyWatcher","Id":"5","Request":"Next","Params":{}}
ip-172-31-23-241:2014-01-07 17:58:44 DEBUG juju.rpc.jsoncodec codec.go:107 <-
{"RequestId":43,"Type":"Machiner","Request":"Life","Params":{"Entities":[{"Tag":"machine-1"}]}}
ip-172-31-23-241:2014-01-07 17:58:44 DEBUG juju.rpc.jsoncodec codec.go:172 ->
{"RequestId":43,"Response":{"Results":[{"Life":"alive","Error":null}]}}
ip-172-31-23-241:2014-01-07 18:00:56 INFO juju.worker.addressupdater
updater.go:226 machine "2" has new addresses:
[public:ec2-54-200-128-44.us-west-2.compute.amazonaws.com
local-cloud:ip-172-31-27-140.us-west-2.compute.internal public:54.200.128.44
local-cloud:172.31.27.140]
ip-172-31-23-241:2014-01-07 18:00:59 DEBUG juju.rpc.jsoncodec codec.go:172 ->
{"RequestId":23,"Response":{}}
ip-172-31-23-241:2014-01-07 18:00:59 DEBUG juju.rpc.jsoncodec codec.go:107 <-
{"RequestId":43,"Type":"NotifyWatcher","Id":"5","Request":"Next","Params":{}}
ip-172-31-23-241:2014-01-07 18:00:59 DEBUG juju.rpc.jsoncodec codec.go:107 <-
{"RequestId":44,"Type":"Machiner","Request":"Life","Params":{"Entities":[{"Tag":"machine-2"}]}}
ip-172-31-23-241:2014-01-07 18:00:59 DEBUG juju.rpc.jsoncodec codec.go:172 ->
{"RequestId":44,"Response":{"Results":[{"Life":"alive","Error":null}]}}
ip-172-31-23-241:2014-01-07 18:01:06 INFO juju.worker.addressupdater
updater.go:226 machine "3" has new addresses:
[public:ec2-54-201-29-153.us-west-2.compute.amazonaws.com
local-cloud:ip-172-31-25-13.us-west-2.compute.internal public:54.201.29.153
local-cloud:172.31.25.13]
ip-172-31-23-241:2014-01-07 18:01:09 DEBUG juju.rpc.jsoncodec codec.go:172 ->
{"RequestId":22,"Response":{}}
ip-172-31-23-241:2014-01-07 18:01:09 DEBUG juju.rpc.jsoncodec codec.go:107 <-
{"RequestId":42,"Type":"NotifyWatcher","Id":"5","Request":"Next","Params":{}}
ip-172-31-23-241:2014-01-07 18:01:09 DEBUG juju.rpc.jsoncodec codec.go:107 <-
{"RequestId":43,"Type":"Machiner","Request":"Life","Params":{"Entities":[{"Tag":"machine-3"}]}}
ip-172-31-23-241:2014-01-07 18:01:09 DEBUG juju.rpc.jsoncodec codec.go:172 ->
{"RequestId":43,"Response":{"Results":[{"Life":"alive","Error":null}]}}
ip-172-31-30-157:2014-01-07 18:04:44 DEBUG juju.worker.uniter.filter
filter.go:322 got service change
ip-172-31-30-157:2014-01-07 18:04:44 DEBUG juju.worker.uniter.filter
filter.go:523 no new charm event
ip-172-31-23-241:2014-01-07 18:04:44 DEBUG juju.rpc.jsoncodec codec.go:172 ->
{"RequestId":30,"Response":{}}
ip-172-31-23-241:2014-01-07 18:04:44 DEBUG juju.rpc.jsoncodec codec.go:107 <-
{"RequestId":112,"Type":"NotifyWatcher","Id":"5","Request":"Next","Params":{}}
ip-172-31-23-241:2014-01-07 18:04:44 DEBUG juju.rpc.jsoncodec codec.go:107 <-
{"RequestId":113,"Type":"Uniter","Request":"Life","Params":{"Entities":[{"Tag":"service-jenkins"}]}}
ip-172-31-23-241:2014-01-07 18:04:44 DEBUG juju.rpc.jsoncodec codec.go:172 ->
{"RequestId":113,"Response":{"Results":[{"Life":"alive","Error":null}]}}
ip-172-31-23-241:2014-01-07 18:04:44 DEBUG juju.rpc.jsoncodec codec.go:107 <-
{"RequestId":114,"Type":"Uniter","Request":"CharmURL","Params":{"Entities":[{"Tag":"service-jenkins"}]}}
ip-172-31-23-241:2014-01-07 18:04:44 DEBUG juju.rpc.jsoncodec codec.go:172 ->
{"RequestId":114,"Response":{"Results":[{"Error":null,"Result":"cs:precise/jenkins-8","Ok":false}]}}
ip-172-31-23-241:2014-01-07 18:04:45 INFO juju.provider.ec2 ec2.go:793 opened
ports in security group juju-amazon-1: [8080/tcp]
ip-172-31-23-241:2014-01-07 18:04:45 INFO juju firewaller.go:463
worker/firewaller: opened ports [8080/tcp] on machine 1
ip-172-31-25-13:2014-01-08 10:15:42 DEBUG juju.worker.uniter.filter
filter.go:338 got relations change
ip-172-31-25-13:2014-01-08 10:15:42 DEBUG juju.worker.uniter.filter
filter.go:368 sent relations event
ip-172-31-25-13:2014-01-08 10:15:42 INFO juju.worker.uniter uniter.go:348
running "slave-relation-departed" hook
ip-172-31-25-13:2014-01-08 10:15:42 INFO juju.worker.uniter context.go:255 HOOK
stop: Unknown instance:
ip-172-31-25-13:2014-01-08 10:15:42 INFO juju.worker.uniter uniter.go:356 ran
"slave-relation-departed" hook
ip-172-31-25-13:2014-01-08 10:15:42 INFO juju.worker.uniter uniter.go:363
committing "relation-departed" hook
ip-172-31-25-13:2014-01-08 10:15:42 INFO juju.worker.uniter uniter.go:381
committed "relation-departed" hook
ip-172-31-25-13:2014-01-08 10:15:42 INFO juju.worker.uniter uniter.go:348
running "slave-relation-broken" hook
ip-172-31-25-13:2014-01-08 10:15:42 INFO juju.worker.uniter context.go:255 HOOK
stop: Unknown instance:
ip-172-31-25-13:2014-01-08 10:15:42 INFO juju.worker.uniter uniter.go:356 ran
"slave-relation-broken" hook
ip-172-31-25-13:2014-01-08 10:15:42 INFO juju.worker.uniter uniter.go:363
committing "relation-broken" hook
ip-172-31-25-13:2014-01-08 10:15:42 INFO juju.worker.uniter uniter.go:381
committed "relation-broken" hook
ip-172-31-27-140:2014-01-08 10:15:43 DEBUG juju.worker.uniter.filter
filter.go:338 got relations change
ip-172-31-27-140:2014-01-08 10:15:43 DEBUG juju.worker.uniter.filter
filter.go:368 sent relations event
ip-172-31-27-140:2014-01-08 10:15:43 INFO juju.worker.uniter uniter.go:348
running "slave-relation-departed" hook
ip-172-31-27-140:2014-01-08 10:15:43 INFO juju.worker.uniter context.go:255
HOOK stop: Unknown instance:
ip-172-31-27-140:2014-01-08 10:15:43 INFO juju.worker.uniter uniter.go:356 ran
"slave-relation-departed" hook
ip-172-31-27-140:2014-01-08 10:15:43 INFO juju.worker.uniter uniter.go:363
committing "relation-departed" hook
ip-172-31-27-140:2014-01-08 10:15:43 INFO juju.worker.uniter uniter.go:381
committed "relation-departed" hook
ip-172-31-27-140:2014-01-08 10:15:44 INFO juju.worker.uniter uniter.go:348
running "slave-relation-broken" hook
ip-172-31-27-140:2014-01-08 10:15:44 INFO juju.worker.uniter context.go:255
HOOK stop: Unknown instance:
ip-172-31-27-140:2014-01-08 10:15:44 INFO juju.worker.uniter uniter.go:356 ran
"slave-relation-broken" hook
ip-172-31-27-140:2014-01-08 10:15:44 INFO juju.worker.uniter uniter.go:363
committing "relation-broken" hook
ip-172-31-27-140:2014-01-08 10:15:44 INFO juju.worker.uniter uniter.go:381
committed "relation-broken” hook
(where I have removed all the pinging that seems to go on).
It looks as though the change of address notification comes in while the tool
set-up is still going on, and this breaks the linkages. But that is only a
guess.
I’m going to try again, waiting a long time between steps, and monitoring the
debug-log as I go.
On 8 Jan 2014, at 14:41, Steve Powell <[email protected]> wrote:
> Thanks for the hint. I find the debug-hooks documentation opaque. Some
> worked examples would be good.
> (I seem to recall something in a video I saw—I’ll go back and look for it).
>
> Where is the new window created? Presumably on the server I’m
> debugging—jenkins in my case. The one I saw ‘locally’ was an ssh session into
> that machine (it looks like). Of course, since I’m ssh’ing into the machine
> that runs the juju commands anyway, I’m running two steps away from it!
>
> I re-executed the {remove,add}-relation commands (in another shell on my juju
> host) and nothing happened in the window. What am I doing wrong?
>
> Steve Powell
> On 8 Jan 2014, at 13:03, Maarten Ectors <[email protected]> wrote:
--
Juju mailing list
[email protected]
Modify settings or unsubscribe at:
https://lists.ubuntu.com/mailman/listinfo/juju
