Jenkins and Jenkins-slave charms

Wed Jan 8 16:46:16 UTC 2014

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 <spowell at gopivotal.com> 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 <maarten.ectors at canonical.com> wrote:

-------------- next part --------------
An HTML attachment was scrubbed...
URL: <https://lists.ubuntu.com/archives/juju/attachments/20140108/0570321b/attachment-0001.html>