juju-core

Merge lp:~gz/juju-core/unit_publicaddress_from_machine into lp:~go-bot/juju-core/trunk

unit_publicaddress_from_machine
Merge into trunk

Proposed by Martin Packman on 2013-08-01

Status:	Merged
Approved by:	Martin Packman on 2013-08-08
Approved revision:	no longer in the source branch.
Merged at revision:	1623
Proposed branch:	lp:~gz/juju-core/unit_publicaddress_from_machine
Merge into:	lp:~go-bot/juju-core/trunk
Prerequisite:	lp:~gz/juju-core/machine_doc_addresses
Diff against target:	89 lines (+42/-2) 3 files modified state/machine_test.go (+2/-1) state/unit.go (+18/-1) state/unit_test.go (+22/-0)
To merge this branch:	bzr merge lp:~gz/juju-core/unit_publicaddress_from_machine
Related bugs:	Link a bug report

Reviewer	Review Type	Date Requested	Status
Juju Engineering		2013-08-01	Pending
Review via email: mp+177983@code.launchpad.net

Commit message

state: Use machine addresses from unit

Make Unit.PublicAddress check for addresses an associated machine
by preference and select an appropriate one. This is the first
step towards removing PublicAddress and PrivateAddress from unit.

https://codereview.appspot.com/12218043/

R=dimitern, wallyworld

Description of the change

state: Use machine addresses from unit

Make Unit.PublicAddress check for addresses an associated machine
by preference and select an appropriate one. This is the first
step towards removing PublicAddress and PrivateAddress from unit.

https://codereview.appspot.com/12218043/

Revision history for this message

Martin Packman (gz) wrote on 2013-08-01:

Reviewers: mp+177983_code.launchpad.net,

Message:
Please take a look.

Description:
Attempt at using machine addresses from unit

Non-working attempt at getting Unit.PublicAddress to take the
value from the addresses stored on a related Machine where
available.

The test added fails as the machineDoc created from the unit
does not contain the addresses just assigned. Have they ever
reached mongo? Does the machine need constructing differently,
or the test need some added magic?

https://code.launchpad.net/~gz/juju-core/unit_publicaddress_from_machine/+merge/177983

Requires:
https://code.launchpad.net/~gz/juju-core/machine_doc_addresses/+merge/177978

(do not edit description out of merge proposal)

Please review this at https://codereview.appspot.com/12218043/

Affected files:
   [revision details]
   state/unit.go
   state/unit_test.go

Index: [revision details]
=== added file '[revision details]'
--- [revision details] 2012-01-01 00:00:00 +0000
+++ [revision details] 2012-01-01 00:00:00 +0000
@@ -0,0 +1,2 @@
+Old revision: <email address hidden>
+New revision: <email address hidden>

Index: state/unit.go
=== modified file 'state/unit.go'
--- state/unit.go 2013-07-31 14:19:29 +0000
+++ state/unit.go 2013-08-01 01:42:09 +0000
@@ -442,7 +442,16 @@

  // PublicAddress returns the public address of the unit and whether it is
valid.
  func (u *Unit) PublicAddress() (string, bool) {
- return u.doc.PublicAddress, u.doc.PublicAddress != ""
+ publicaddress := u.doc.PublicAddress
+ if u.doc.MachineId != "" {
+ m, err := u.st.Machine(u.doc.MachineId)
+ if err != nil {
+ // XXX(gz) Or treat as not having an address?
+ panic(err)
+ }
+ publicaddress = instance.SelectPublicAddress(m.Addresses())
+ }
+ return publicaddress, publicaddress != ""
  }

// PrivateAddress returns the private address of the unit and whether it
is valid.

Index: state/unit_test.go
=== modified file 'state/unit_test.go'
--- state/unit_test.go 2013-07-31 14:19:29 +0000
+++ state/unit_test.go 2013-08-01 01:42:09 +0000
@@ -169,6 +169,29 @@
c.Assert(err, ErrorMatches, `cannot set public address of
unit "wordpress/0": unit not found`)
}

+func (s *UnitSuite) TestGetPublicAddressFromMachine(c *C) {
+ machine, err := s.State.AddMachine("series", state.JobHostUnits)
+ c.Assert(err, IsNil)
+ err = s.unit.AssignToMachine(machine)
+ c.Assert(err, IsNil)
+
+ address, ok := s.unit.PublicAddress()
+ c.Check(address, Equals, "")
+ c.Assert(ok, Equals, false)
+
+ addresses := []instance.Address{
+ instance.NewAddress("127.0.0.1"),
+ instance.NewAddress("8.8.8.8"),
+ }
+ err = machine.SetAddresses(addresses)
+ c.Assert(err, IsNil)
+
+ // XXX(gz) Following fails, machine doc lacks addresses when read, why?
+ address, ok = s.unit.PublicAddress()
+ c.Check(address, Equals, "8.8.8.8")
+ c.Assert(ok, Equals, true)
+}
+
  func (s *UnitSuite) TestGetSetPrivateAddress(c *C) {
   _, ok := s.unit.PrivateAddress()
   c.Assert(ok, Equals, false)

Reviewers: mp+177983_code.launchpad.net,

Message:
Please take a look.

Description:
Attempt at using machine addresses from unit

Non-working attempt at getting Unit.PublicAddress to take the
value from the addresses stored on a related Machine where
available.

https://code.launchpad.net/~gz/juju-core/unit_publicaddress_from_machine/+merge/177983

Requires:
https://code.launchpad.net/~gz/juju-core/machine_doc_addresses/+merge/177978

(do not edit description out of merge proposal)

Please review this at https://codereview.appspot.com/12218043/

Affected files:
   [revision details]
   state/unit.go
   state/unit_test.go

Index: [revision details]
=== added file '[revision details]'
--- [revision details]	2012-01-01 00:00:00 +0000
+++ [revision details]	2012-01-01 00:00:00 +0000
@@ -0,0 +1,2 @@
+Old revision: martin.packman@canonical.com-20130801003953-xtf27u931lo2alkh
+New revision: martin.packman@canonical.com-20130801014209-5tp4h3p891i91yus

Index: state/unit.go
=== modified file 'state/unit.go'
--- state/unit.go	2013-07-31 14:19:29 +0000
+++ state/unit.go	2013-08-01 01:42:09 +0000
@@ -442,7 +442,16 @@

// PublicAddress returns the public address of the unit and whether it is  
valid.
  func (u *Unit) PublicAddress() (string, bool) {
-	return u.doc.PublicAddress, u.doc.PublicAddress != ""
+	publicaddress := u.doc.PublicAddress
+	if u.doc.MachineId != "" {
+		m, err := u.st.Machine(u.doc.MachineId)
+		if err != nil {
+			// XXX(gz) Or treat as not having an address?
+			panic(err)
+		}
+		publicaddress = instance.SelectPublicAddress(m.Addresses())
+	}
+	return publicaddress, publicaddress != ""
  }

// PrivateAddress returns the private address of the unit and whether it  
is valid.

Index: state/unit_test.go
=== modified file 'state/unit_test.go'
--- state/unit_test.go	2013-07-31 14:19:29 +0000
+++ state/unit_test.go	2013-08-01 01:42:09 +0000
@@ -169,6 +169,29 @@
  	c.Assert(err, ErrorMatches, `cannot set public address of  
unit "wordpress/0": unit not found`)
  }

+func (s *UnitSuite) TestGetPublicAddressFromMachine(c *C) {
+	machine, err := s.State.AddMachine("series", state.JobHostUnits)
+	c.Assert(err, IsNil)
+	err = s.unit.AssignToMachine(machine)
+	c.Assert(err, IsNil)
+
+	address, ok := s.unit.PublicAddress()
+	c.Check(address, Equals, "")
+	c.Assert(ok, Equals, false)
+
+	addresses := []instance.Address{
+		instance.NewAddress("127.0.0.1"),
+		instance.NewAddress("8.8.8.8"),
+	}
+	err = machine.SetAddresses(addresses)
+	c.Assert(err, IsNil)
+
+	// XXX(gz) Following fails, machine doc lacks addresses when read, why?
+	address, ok = s.unit.PublicAddress()
+	c.Check(address, Equals, "8.8.8.8")
+	c.Assert(ok, Equals, true)
+}
+
  func (s *UnitSuite) TestGetSetPrivateAddress(c *C) {
  	_, ok := s.unit.PrivateAddress()
  	c.Assert(ok, Equals, false)

Revision history for this message

Martin Packman (gz) wrote on 2013-08-01:

Please take a look.

https://codereview.appspot.com/12218043/

Revision history for this message

Martin Packman (gz) wrote on 2013-08-01:

Please take a look.

https://codereview.appspot.com/12218043/

Revision history for this message

Dimiter Naydenov (dimitern) wrote on 2013-08-01:

LGTM modulo the below

https://codereview.appspot.com/12218043/diff/11001/state/unit.go
File state/unit.go (right):

https://codereview.appspot.com/12218043/diff/11001/state/unit.go#newcode445
state/unit.go:445: publicaddress := u.doc.PublicAddress
publicAddress please;

Also do we need doc.PublicAddress anymore, now that we're using the
machine one?

https://codereview.appspot.com/12218043/diff/11001/state/unit.go#newcode449
state/unit.go:449: // XXX(gz) Or treat as not having an address?
I think just logging it and returning false should be enough here, no
need to panic.

https://codereview.appspot.com/12218043/diff/11001/state/unit_test.go
File state/unit_test.go (right):

https://codereview.appspot.com/12218043/diff/11001/state/unit_test.go#newcode191
state/unit_test.go:191: c.Assert(ok, Equals, true)
How about another test when the unit is not assigned to a machine?

https://codereview.appspot.com/12218043/

Revision history for this message

Martin Packman (gz) wrote on 2013-08-01:

https://codereview.appspot.com/12218043/diff/11001/state/unit.go
File state/unit.go (right):

https://codereview.appspot.com/12218043/diff/11001/state/unit.go#newcode445
state/unit.go:445: publicaddress := u.doc.PublicAddress
On 2013/08/01 14:30:00, dimitern wrote:
> publicAddress please;

Okay.

> Also do we need doc.PublicAddress anymore, now that we're using the
machine one?

Yes, though potentially with some adjustments. We may need, for
backwards compat reasons, to care about charms manually setting a public
address on a unit, in which case we'd have to pay attention to that.
Most of that is for a follow up branch though.

https://codereview.appspot.com/12218043/diff/11001/state/unit.go#newcode449
state/unit.go:449: // XXX(gz) Or treat as not having an address?
On 2013/08/01 14:30:00, dimitern wrote:
> I think just logging it and returning false should be enough here, no
need to
> panic.

Okay.

https://codereview.appspot.com/12218043/diff/11001/state/unit_test.go
File state/unit_test.go (right):

https://codereview.appspot.com/12218043/diff/11001/state/unit_test.go#newcode191
state/unit_test.go:191: c.Assert(ok, Equals, true)
On 2013/08/01 14:30:00, dimitern wrote:
> How about another test when the unit is not assigned to a machine?

The existing test above should cover that case fine I think. As
mentioned, there may be other funny scenarios we need to care about
later though.

https://codereview.appspot.com/12218043/

Revision history for this message

Martin Packman (gz) wrote on 2013-08-01:

Please take a look.

https://codereview.appspot.com/12218043/

Revision history for this message

Ian Booth (wallyworld) wrote on 2013-08-01:

LGTM. Perhaps with another test like Dimiter suggested.
Plus the err in the log message.

https://codereview.appspot.com/12218043/diff/18001/state/unit.go
File state/unit.go (right):

https://codereview.appspot.com/12218043/diff/18001/state/unit.go#newcode454
state/unit.go:454: unitLogger.Errorf("unit %v misses machine id %v", u,
id)
please include err in the logged message

https://codereview.appspot.com/12218043/diff/18001/state/unit.go#newcode455
state/unit.go:455: return "", false
did we really want to return "" here? why not u.doc.PublicAddress?

https://codereview.appspot.com/12218043/

Revision history for this message

Tim Penhey (thumper) wrote on 2013-08-02:

https://codereview.appspot.com/12218043/diff/18001/state/unit.go
File state/unit.go (right):

https://codereview.appspot.com/12218043/diff/18001/state/unit.go#newcode457
state/unit.go:457: publicAddress =
instance.SelectPublicAddress(m.Addresses())
Is there likely to be a case where u.doc.PublicAddress is valid, but
m.Addresses() is empty? Because if there is, we'd be returning "" here
instead of u.doc.PublicAddress

https://codereview.appspot.com/12218043/

Revision history for this message

Dimiter Naydenov (dimitern) wrote on 2013-08-02:

https://codereview.appspot.com/12218043/diff/18001/state/unit.go
File state/unit.go (right):

https://codereview.appspot.com/12218043/diff/18001/state/unit.go#newcode29
state/unit.go:29: var unitLogger = loggo.GetLogger("juju.state.unit")
I think this should be "juju.state" and please move it in state/state.go
perhaps?

https://codereview.appspot.com/12218043/

Revision history for this message

Dimiter Naydenov (dimitern) wrote on 2013-08-02:

https://codereview.appspot.com/12218043/diff/18001/state/unit.go
File state/unit.go (right):

https://codereview.appspot.com/12218043/

Revision history for this message

Go Bot (go-bot) wrote on 2013-08-07:

The prerequisite https://code.launchpad.net/~gz/juju-core/machine_doc_addresses has not yet been merged into https://code.launchpad.net/~go-bot/juju-core/trunk.

Revision history for this message

Go Bot (go-bot) wrote on 2013-08-07:

Download full text (146.2 KiB)

The attempt to merge lp:~gz/juju-core/unit_publicaddress_from_machine into lp:juju-core failed. Below is the output from the failed tests.

ok launchpad.net/juju-core/agent 2.467s
ok launchpad.net/juju-core/agent/tools 25.304s
ok launchpad.net/juju-core/bzr 6.765s
ok launchpad.net/juju-core/cert 1.887s
ok launchpad.net/juju-core/charm 0.972s
? launchpad.net/juju-core/charm/hooks [no test files]
ok launchpad.net/juju-core/cloudinit 0.021s
ok launchpad.net/juju-core/cmd 0.223s
? launchpad.net/juju-core/cmd/builddb [no test files]
? launchpad.net/juju-core/cmd/charmd [no test files]
? launchpad.net/juju-core/cmd/charmload [no test files]
listing available tools
found 4 tools
found 4 recent tools (version 1.0.0)
listing target bucket
found 3 tools in target; 4 tools to be copied
copying 1.0.0-defaultseries-amd64 from http://127.0.0.1:56898/tools/juju-1.0.0-defaultseries-amd64.tgz
copying tools/juju-1.0.0-defaultseries-amd64.tgz
downloaded tools/juju-1.0.0-defaultseries-amd64.tgz (0kB), uploading
download 0kB, uploading
copying 1.0.0-precise-amd64 from http://127.0.0.1:56898/tools/juju-1.0.0-precise-amd64.tgz
copying tools/juju-1.0.0-precise-amd64.tgz
downloaded tools/juju-1.0.0-precise-amd64.tgz (0kB), uploading
download 0kB, uploading
copying 1.0.0-quantal-amd64 from http://127.0.0.1:56898/tools/juju-1.0.0-quantal-amd64.tgz
copying tools/juju-1.0.0-quantal-amd64.tgz
downloaded tools/juju-1.0.0-quantal-amd64.tgz (0kB), uploading
download 0kB, uploading
copying 1.0.0-quantal-i386 from http://127.0.0.1:56898/tools/juju-1.0.0-quantal-i386.tgz
copying tools/juju-1.0.0-quantal-i386.tgz
downloaded tools/juju-1.0.0-quantal-i386.tgz (0kB), uploading
download 0kB, uploading
copied 4 tools

----------------------------------------------------------------------
FAIL: debughooks_test.go:69: DebugHooksSuite.TestDebugHooksCommand

The attempt to merge lp:~gz/juju-core/unit_publicaddress_from_machine into lp:juju-core failed. Below is the output from the failed tests.

ok  	launchpad.net/juju-core/agent	2.467s
ok  	launchpad.net/juju-core/agent/tools	25.304s
ok  	launchpad.net/juju-core/bzr	6.765s
ok  	launchpad.net/juju-core/cert	1.887s
ok  	launchpad.net/juju-core/charm	0.972s
?   	launchpad.net/juju-core/charm/hooks	[no test files]
ok  	launchpad.net/juju-core/cloudinit	0.021s
ok  	launchpad.net/juju-core/cmd	0.223s
?   	launchpad.net/juju-core/cmd/builddb	[no test files]
?   	launchpad.net/juju-core/cmd/charmd	[no test files]
?   	launchpad.net/juju-core/cmd/charmload	[no test files]
listing available tools
found 4 tools
found 4 recent tools (version 1.0.0)
listing target bucket
found 3 tools in target; 4 tools to be copied
copying 1.0.0-defaultseries-amd64 from http://127.0.0.1:56898/tools/juju-1.0.0-defaultseries-amd64.tgz
copying tools/juju-1.0.0-defaultseries-amd64.tgz
downloaded tools/juju-1.0.0-defaultseries-amd64.tgz (0kB), uploading
download 0kB, uploading
copying 1.0.0-precise-amd64 from http://127.0.0.1:56898/tools/juju-1.0.0-precise-amd64.tgz
copying tools/juju-1.0.0-precise-amd64.tgz
downloaded tools/juju-1.0.0-precise-amd64.tgz (0kB), uploading
download 0kB, uploading
copying 1.0.0-quantal-amd64 from http://127.0.0.1:56898/tools/juju-1.0.0-quantal-amd64.tgz
copying tools/juju-1.0.0-quantal-amd64.tgz
downloaded tools/juju-1.0.0-quantal-amd64.tgz (0kB), uploading
download 0kB, uploading
copying 1.0.0-quantal-i386 from http://127.0.0.1:56898/tools/juju-1.0.0-quantal-i386.tgz
copying tools/juju-1.0.0-quantal-i386.tgz
downloaded tools/juju-1.0.0-quantal-i386.tgz (0kB), uploading
download 0kB, uploading
copied 4 tools

----------------------------------------------------------------------
FAIL: debughooks_test.go:69: DebugHooksSuite.TestDebugHooksCommand

[LOG] 22.39726 INFO juju environs/testing: uploading FAKE tools 1.13.1-precise-amd64
[LOG] 22.39735 INFO juju environs: reading tools with major version 1
[LOG] 22.39736 DEBUG juju.agent.tools reading v1.* tools
[LOG] 22.39738 INFO juju environs: falling back to public bucket
[LOG] 22.39739 DEBUG juju.agent.tools reading v1.* tools
[LOG] 22.39742 DEBUG juju.agent.tools found 1.13.1-precise-amd64
[LOG] 22.39744 INFO juju environs: filtering tools by series: precise
[LOG] 22.39746 INFO juju environs: filtering tools by version: 1.13.1
[LOG] 22.39748 INFO juju environs/dummy: would pick tools from 1.13.1-precise-amd64
[LOG] 22.43057 INFO juju state: opening state; mongo addresses: ["localhost:55024"]; entity ""
[LOG] 22.43381 INFO juju state: connection established
[LOG] 22.46165 INFO juju state: initializing environment
[LOG] 22.48270 INFO juju state/api: listening on "127.0.0.1:55142"
[LOG] 22.49972 INFO juju state: opening state; mongo addresses: ["localhost:55024"]; entity ""
[LOG] 22.50562 INFO juju state: connection established
[LOG] 22.50624 INFO juju juju: authorization error while connecting to state server; retrying
[LOG] 22.50628 INFO juju state: opening state; mongo addresses: ["localhost:55024"]; entity ""
[LOG] 22.50984 INFO juju state: connection established
[LOG] 22.54675 INFO juju state/api: dialing "wss://127.0.0.1:55142/"
[LOG] 22.54974 INFO juju state/api: connection established
[LOG] 22.54987 DEBUG juju rpc/jsoncodec: <- {"RequestId":1,"Type":"Admin","Request":"Login","Params":{"AuthTag":"user-admin","Password":"dummy-secret","Nonce":""}}
[LOG] 22.55080 DEBUG juju rpc/jsoncodec: -> {"RequestId":1,"Response":{}}
[LOG] 22.55188 DEBUG juju rpc/jsoncodec: <- {"RequestId":2,"Type":"Pinger","Request":"Ping","Params":{}}
[LOG] 22.55203 DEBUG juju rpc/jsoncodec: -> {"RequestId":2,"Response":{}}
[LOG] 22.57527 INFO juju environs/dummy: dummy startinstance, machine 0
[LOG] 22.57533 INFO juju environs: reading tools with major version 1
[LOG] 22.57534 DEBUG juju.agent.tools reading v1.* tools
[LOG] 22.57535 INFO juju environs: falling back to public bucket
[LOG] 22.57536 DEBUG juju.agent.tools reading v1.* tools
[LOG] 22.57539 DEBUG juju.agent.tools found 1.13.1-precise-amd64
[LOG] 22.57543 INFO juju environs: filtering tools by version: 1.13.1
[LOG] 22.57544 INFO juju environs: filtering tools by series: precise
[LOG] 22.57547 INFO juju environs/dummy: would pick tools from 1.13.1-precise-amd64
[LOG] 22.61218 INFO juju environs/dummy: dummy startinstance, machine 1
[LOG] 22.61222 INFO juju environs: reading tools with major version 1
[LOG] 22.61224 DEBUG juju.agent.tools reading v1.* tools
[LOG] 22.61225 INFO juju environs: falling back to public bucket
[LOG] 22.61226 DEBUG juju.agent.tools reading v1.* tools
[LOG] 22.61229 DEBUG juju.agent.tools found 1.13.1-precise-amd64
[LOG] 22.61231 INFO juju environs: filtering tools by version: 1.13.1
[LOG] 22.61232 INFO juju environs: filtering tools by series: precise
[LOG] 22.61234 INFO juju environs/dummy: would pick tools from 1.13.1-precise-amd64
[LOG] 22.63747 INFO juju environs/dummy: dummy startinstance, machine 2
[LOG] 22.63751 INFO juju environs: reading tools with major version 1
[LOG] 22.63752 DEBUG juju.agent.tools reading v1.* tools
[LOG] 22.63754 INFO juju environs: falling back to public bucket
[LOG] 22.63755 DEBUG juju.agent.tools reading v1.* tools
[LOG] 22.63757 DEBUG juju.agent.tools found 1.13.1-precise-amd64
[LOG] 22.63760 INFO juju environs: filtering tools by version: 1.13.1
[LOG] 22.63761 INFO juju environs: filtering tools by series: precise
[LOG] 22.63763 INFO juju environs/dummy: would pick tools from 1.13.1-precise-amd64
[LOG] 22.65996 INFO juju writing charm to storage [1418 bytes]
[LOG] 22.66002 INFO juju adding charm to state
test 0: 
	[mysql/0]

[LOG] 22.80556 INFO juju state: opening state; mongo addresses: ["localhost:55024"]; entity ""
[LOG] 22.82405 INFO juju state: connection established
[LOG] 22.85919 INFO juju looking up address for unit "mysql/0"...
debughooks_test.go:85:
    c.Check(code, Equals, t.code)
... obtained int = 1
... expected int = 0

debughooks_test.go:86:
    c.Check(ctx.Stderr.(*bytes.Buffer).String(), Matches, t.stderr)
... value string = "error: unit \"mysql/0\" has no public address\n"
... regex string = ""

debughooks_test.go:87:
    c.Check(ctx.Stdout.(*bytes.Buffer).String(), Matches, t.result)
... value string = ""
... regex string = "-l ubuntu -t -o StrictHostKeyChecking no -o PasswordAuthentication no dummyenv-0\\.dns -- sudo /bin/bash -c 'F=\\$\$mktemp\$; echo IyEvYmluL2Jhc2gKKAojIExvY2sgdGhlIGp1anUtPHVuaXQ\\+LWRlYnVnIGxvY2tmaWxlLgpmbG9jayAtbiA4IHx8IChlY2hvICJGYWlsZWQgdG8gYWNxdWlyZSAvdG1wL2p1anUtdW5pdC1teXNxbC0wLWRlYnVnLWhvb2tzOiB1bml0IGlzIGFscmVhZHkgYmVpbmcgZGVidWdnZWQiIDI\\+JjE7IGV4aXQgMSkKKAojIENsb3NlIHRoZSBpbmhlcml0ZWQgbG9jayBGRCwgb3IgdG11eCB3aWxsIGtlZXAgaXQgb3Blbi4KZXhlYyA4PiYtCgojIFdyaXRlIG91dCB0aGUgZGVidWctaG9va3MgYXJncy4KZWNobyAiZTMwSyIgfCBiYXNlNjQgLWQgPiAvdG1wL2p1anUtdW5pdC1teXNxbC0wLWRlYnVnLWhvb2tzCgojIExvY2sgdGhlIGp1anUtPHVuaXQ\\+LWRlYnVnLWV4aXQgbG9ja2ZpbGUuCmZsb2NrIC1uIDkgfHwgZXhpdCAxCgojIFdhaXQgZm9yIHRtdXggdG8gYmUgaW5zdGFsbGVkLgp3aGlsZSBbICEgLWYgL3Vzci9iaW4vdG11eCBdOyBkbwogICAgc2xlZXAgMQpkb25lCgppZiBbICEgLWYgfi8udG11eC5jb25mIF07IHRoZW4KICAgICAgICBpZiBbIC1mIC91c3Ivc2hhcmUvYnlvYnUvcHJvZmlsZXMvdG11eCBdOyB0aGVuCiAgICAgICAgICAgICAgICAjIFVzZSBieW9idS90bXV4IHByb2ZpbGUgZm9yIGZhbWlsaWFyIGtleWJpbmRpbmdzIGFuZCBicmFuZGluZwogICAgICAgICAgICAgICAgZWNobyAic291cmNlLWZpbGUgL3Vzci9zaGFyZS9ieW9idS9wcm9maWxlcy90bXV4IiA\\+IH4vLnRtdXguY29uZgogICAgICAgIGVsc2UKICAgICAgICAgICAgICAgICMgT3RoZXJ3aXNlLCB1c2UgdGhlIGxlZ2FjeSBqdWp1L3RtdXggY29uZmlndXJhdGlvbgogICAgICAgICAgICAgICAgY2F0ID4gfi8udG11eC5jb25mIDw8RU5ECiAgICAgICAgICAgICAgICAKIyBTdGF0dXMgYmFyCnNldC1vcHRpb24gLWcgc3RhdHVzLWJnIGJsYWNrCnNldC1vcHRpb24gLWcgc3RhdHVzLWZnIHdoaXRlCgpzZXQtd2luZG93LW9wdGlvbiAtZyB3aW5kb3ctc3RhdHVzLWN1cnJlbnQtYmcgcmVkCnNldC13aW5kb3ctb3B0aW9uIC1nIHdpbmRvdy1zdGF0dXMtY3VycmVudC1hdHRyIGJyaWdodAoKc2V0LW9wdGlvbiAtZyBzdGF0dXMtcmlnaHQgJycKCiMgUGFuZXMKc2V0LW9wdGlvbiAtZyBwYW5lLWJvcmRlci1mZyB3aGl0ZQpzZXQtb3B0aW9uIC1nIHBhbmUtYWN0aXZlLWJvcmRlci1mZyB3aGl0ZQoKIyBNb25pdG9yIGFjdGl2aXR5IG9uIHdpbmRvd3MKc2V0LXdpbmRvdy1vcHRpb24gLWcgbW9uaXRvci1hY3Rpdml0eSBvbgoKIyBTY3JlZW4gYmluZGluZ3MsIHNpbmNlIHBlb3BsZSBhcmUgbW9yZSBmYW1pbGlhciB3aXRoIHRoYXQuCnNldC1vcHRpb24gLWcgcHJlZml4IEMtYQpiaW5kIEMtYSBsYXN0LXdpbmRvdwpiaW5kIGEgc2VuZC1rZXkgQy1hCgpiaW5kIHwgc3BsaXQtd2luZG93IC1oCmJpbmQgLSBzcGxpdC13aW5kb3cgLXYKCiMgRml4IENUUkwtUEdVUC9QR0RPV04gZm9yIHZpbQpzZXQtd2luZG93LW9wdGlvbiAtZyB4dGVybS1rZXlzIG9uCgojIFByZXZlbnQgRVNDIGtleSBmcm9tIGFkZGluZyBkZWxheSBhbmQgYnJlYWtpbmcgVmltJ3MgRVNDID4gYXJyb3cga2V5CnNldC1vcHRpb24gLXMgZXNjYXBlLXRpbWUgMAoKRU5ECiAgICAgICAgZmkKZmkKCigKICAgICMgQ2xvc2UgdGhlIGluaGVyaXRlZCBsb2NrIEZELCBvciB0bXV4IHdpbGwga2VlcCBpdCBvcGVuLgogICAgZXhlYyA5PiYtCiAgICBleGVjIHRtdXggbmV3LXNlc3Npb24gLXMgbXlzcWwvMAopCikgOT4vdG1wL2p1anUtdW5pdC1teXNxbC0wLWRlYnVnLWhvb2tzLWV4aXQKKSA4Pi90bXAvanVqdS11bml0LW15c3FsLTAtZGVidWctaG9va3MKZXhpdCAkPwo= \\| base64 -d > \\$F; \\. \\$F'\n"

test 1: 
	[mongodb/1]

[LOG] 22.90460 INFO juju state: opening state; mongo addresses: ["localhost:55024"]; entity ""
[LOG] 22.93144 INFO juju state: connection established
[LOG] 22.96109 INFO juju looking up address for unit "mongodb/1"...
debughooks_test.go:85:
    c.Check(code, Equals, t.code)
... obtained int = 1
... expected int = 0

debughooks_test.go:86:
    c.Check(ctx.Stderr.(*bytes.Buffer).String(), Matches, t.stderr)
... value string = "error: unit \"mongodb/1\" has no public address\n"
... regex string = ""

debughooks_test.go:87:
    c.Check(ctx.Stdout.(*bytes.Buffer).String(), Matches, t.result)
... value string = ""
... regex string = "-l ubuntu -t -o StrictHostKeyChecking no -o PasswordAuthentication no dummyenv-2\\.dns -- sudo /bin/bash -c 'F=\\$\$mktemp\$; echo IyEvYmluL2Jhc2gKKAojIExvY2sgdGhlIGp1anUtPHVuaXQ\\+LWRlYnVnIGxvY2tmaWxlLgpmbG9jayAtbiA4IHx8IChlY2hvICJGYWlsZWQgdG8gYWNxdWlyZSAvdG1wL2p1anUtdW5pdC1tb25nb2RiLTEtZGVidWctaG9va3M6IHVuaXQgaXMgYWxyZWFkeSBiZWluZyBkZWJ1Z2dlZCIgMj4mMTsgZXhpdCAxKQooCiMgQ2xvc2UgdGhlIGluaGVyaXRlZCBsb2NrIEZELCBvciB0bXV4IHdpbGwga2VlcCBpdCBvcGVuLgpleGVjIDg\\+Ji0KCiMgV3JpdGUgb3V0IHRoZSBkZWJ1Zy1ob29rcyBhcmdzLgplY2hvICJlMzBLIiB8IGJhc2U2NCAtZCA\\+IC90bXAvanVqdS11bml0LW1vbmdvZGItMS1kZWJ1Zy1ob29rcwoKIyBMb2NrIHRoZSBqdWp1LTx1bml0Pi1kZWJ1Zy1leGl0IGxvY2tmaWxlLgpmbG9jayAtbiA5IHx8IGV4aXQgMQoKIyBXYWl0IGZvciB0bXV4IHRvIGJlIGluc3RhbGxlZC4Kd2hpbGUgWyAhIC1mIC91c3IvYmluL3RtdXggXTsgZG8KICAgIHNsZWVwIDEKZG9uZQoKaWYgWyAhIC1mIH4vLnRtdXguY29uZiBdOyB0aGVuCiAgICAgICAgaWYgWyAtZiAvdXNyL3NoYXJlL2J5b2J1L3Byb2ZpbGVzL3RtdXggXTsgdGhlbgogICAgICAgICAgICAgICAgIyBVc2UgYnlvYnUvdG11eCBwcm9maWxlIGZvciBmYW1pbGlhciBrZXliaW5kaW5ncyBhbmQgYnJhbmRpbmcKICAgICAgICAgICAgICAgIGVjaG8gInNvdXJjZS1maWxlIC91c3Ivc2hhcmUvYnlvYnUvcHJvZmlsZXMvdG11eCIgPiB\\+Ly50bXV4LmNvbmYKICAgICAgICBlbHNlCiAgICAgICAgICAgICAgICAjIE90aGVyd2lzZSwgdXNlIHRoZSBsZWdhY3kganVqdS90bXV4IGNvbmZpZ3VyYXRpb24KICAgICAgICAgICAgICAgIGNhdCA\\+IH4vLnRtdXguY29uZiA8PEVORAogICAgICAgICAgICAgICAgCiMgU3RhdHVzIGJhcgpzZXQtb3B0aW9uIC1nIHN0YXR1cy1iZyBibGFjawpzZXQtb3B0aW9uIC1nIHN0YXR1cy1mZyB3aGl0ZQoKc2V0LXdpbmRvdy1vcHRpb24gLWcgd2luZG93LXN0YXR1cy1jdXJyZW50LWJnIHJlZApzZXQtd2luZG93LW9wdGlvbiAtZyB3aW5kb3ctc3RhdHVzLWN1cnJlbnQtYXR0ciBicmlnaHQKCnNldC1vcHRpb24gLWcgc3RhdHVzLXJpZ2h0ICcnCgojIFBhbmVzCnNldC1vcHRpb24gLWcgcGFuZS1ib3JkZXItZmcgd2hpdGUKc2V0LW9wdGlvbiAtZyBwYW5lLWFjdGl2ZS1ib3JkZXItZmcgd2hpdGUKCiMgTW9uaXRvciBhY3Rpdml0eSBvbiB3aW5kb3dzCnNldC13aW5kb3ctb3B0aW9uIC1nIG1vbml0b3ItYWN0aXZpdHkgb24KCiMgU2NyZWVuIGJpbmRpbmdzLCBzaW5jZSBwZW9wbGUgYXJlIG1vcmUgZmFtaWxpYXIgd2l0aCB0aGF0LgpzZXQtb3B0aW9uIC1nIHByZWZpeCBDLWEKYmluZCBDLWEgbGFzdC13aW5kb3cKYmluZCBhIHNlbmQta2V5IEMtYQoKYmluZCB8IHNwbGl0LXdpbmRvdyAtaApiaW5kIC0gc3BsaXQtd2luZG93IC12CgojIEZpeCBDVFJMLVBHVVAvUEdET1dOIGZvciB2aW0Kc2V0LXdpbmRvdy1vcHRpb24gLWcgeHRlcm0ta2V5cyBvbgoKIyBQcmV2ZW50IEVTQyBrZXkgZnJvbSBhZGRpbmcgZGVsYXkgYW5kIGJyZWFraW5nIFZpbSdzIEVTQyA\\+IGFycm93IGtleQpzZXQtb3B0aW9uIC1zIGVzY2FwZS10aW1lIDAKCkVORAogICAgICAgIGZpCmZpCgooCiAgICAjIENsb3NlIHRoZSBpbmhlcml0ZWQgbG9jayBGRCwgb3IgdG11eCB3aWxsIGtlZXAgaXQgb3Blbi4KICAgIGV4ZWMgOT4mLQogICAgZXhlYyB0bXV4IG5ldy1zZXNzaW9uIC1zIG1vbmdvZGIvMQopCikgOT4vdG1wL2p1anUtdW5pdC1tb25nb2RiLTEtZGVidWctaG9va3MtZXhpdAopIDg\\+L3RtcC9qdWp1LXVuaXQtbW9uZ29kYi0xLWRlYnVnLWhvb2tzCmV4aXQgJD8K \\| base64 -d > \\$F; \\. \\$F'\n"

test 2: "*" is a valid hook name: it means hook everything
	[mysql/0 *]

[LOG] 23.03033 INFO juju state: opening state; mongo addresses: ["localhost:55024"]; entity ""
[LOG] 23.03303 INFO juju state: connection established
[LOG] 23.07605 INFO juju looking up address for unit "mysql/0"...
debughooks_test.go:85:
    c.Check(code, Equals, t.code)
... obtained int = 1
... expected int = 0

debughooks_test.go:86:
    c.Check(ctx.Stderr.(*bytes.Buffer).String(), Matches, t.stderr)
... value string = "error: unit \"mysql/0\" has no public address\n"
... regex string = ""

debughooks_test.go:87:
    c.Check(ctx.Stdout.(*bytes.Buffer).String(), Matches, t.result)
... value string = ""
... regex string = ".*\n"

test 3: "*" mixed with named hooks is equivalent to "*"
	[mysql/0 * relation-get]

[LOG] 23.11697 INFO juju state: opening state; mongo addresses: ["localhost:55024"]; entity ""
[LOG] 23.11988 INFO juju state: connection established
[LOG] 23.17010 INFO juju looking up address for unit "mysql/0"...
debughooks_test.go:85:
    c.Check(code, Equals, t.code)
... obtained int = 1
... expected int = 0

debughooks_test.go:86:
    c.Check(ctx.Stderr.(*bytes.Buffer).String(), Matches, t.stderr)
... value string = "error: unit \"mysql/0\" has no public address\n"
... regex string = ""

debughooks_test.go:87:
    c.Check(ctx.Stdout.(*bytes.Buffer).String(), Matches, t.result)
... value string = ""
... regex string = ".*\n"

test 4: multiple named hooks may be specified
	[mysql/0 start stop]

[LOG] 23.23862 INFO juju state: opening state; mongo addresses: ["localhost:55024"]; entity ""
[LOG] 23.25997 INFO juju state: connection established
[LOG] 23.30115 INFO juju looking up address for unit "mysql/0"...
debughooks_test.go:85:
    c.Check(code, Equals, t.code)
... obtained int = 1
... expected int = 0

debughooks_test.go:86:
    c.Check(ctx.Stderr.(*bytes.Buffer).String(), Matches, t.stderr)
... value string = "error: unit \"mysql/0\" has no public address\n"
... regex string = ""

debughooks_test.go:87:
    c.Check(ctx.Stdout.(*bytes.Buffer).String(), Matches, t.result)
... value string = ""
... regex string = ".*\n"

test 5: relation hooks have the relation name prefixed
	[mysql/0 juju-info-relation-joined]

[LOG] 23.34134 INFO juju state: opening state; mongo addresses: ["localhost:55024"]; entity ""
[LOG] 23.35907 INFO juju state: connection established
[LOG] 23.38920 INFO juju looking up address for unit "mysql/0"...
debughooks_test.go:85:
    c.Check(code, Equals, t.code)
... obtained int = 1
... expected int = 0

debughooks_test.go:86:
    c.Check(ctx.Stderr.(*bytes.Buffer).String(), Matches, t.stderr)
... value string = "error: unit \"mysql/0\" has no public address\n"
... regex string = ""

debughooks_test.go:87:
    c.Check(ctx.Stdout.(*bytes.Buffer).String(), Matches, t.result)
... value string = ""
... regex string = ".*\n"

test 6: invalid unit syntax
	[mysql]

[LOG] 23.44279 INFO juju state: opening state; mongo addresses: ["localhost:55024"]; entity ""
[LOG] 23.46693 INFO juju state: connection established
test 7: invalid unit
	[nonexistent/123]

[LOG] 23.57288 INFO juju state: opening state; mongo addresses: ["localhost:55024"]; entity ""
[LOG] 23.57592 INFO juju state: connection established
test 8: invalid hook
	[mysql/0 invalid-hook]

[LOG] 23.67820 INFO juju state: opening state; mongo addresses: ["localhost:55024"]; entity ""
[LOG] 23.69884 INFO juju state: connection established
[LOG] 23.74064 INFO juju environs/dummy: reset environment
[LOG] 23.74091 DEBUG juju rpc/jsoncodec: <- error: read tcp 127.0.0.1:34378: use of closed network connection (closing true)

----------------------------------------------------------------------
FAIL: scp_test.go:48: SCPSuite.TestSCPCommand

[LOG] 69.29986 INFO juju environs/testing: uploading FAKE tools 1.13.1-precise-amd64
[LOG] 69.29992 INFO juju environs: reading tools with major version 1
[LOG] 69.29994 DEBUG juju.agent.tools reading v1.* tools
[LOG] 69.29995 INFO juju environs: falling back to public bucket
[LOG] 69.29996 DEBUG juju.agent.tools reading v1.* tools
[LOG] 69.29999 DEBUG juju.agent.tools found 1.13.1-precise-amd64
[LOG] 69.30000 INFO juju environs: filtering tools by series: precise
[LOG] 69.30003 INFO juju environs: filtering tools by version: 1.13.1
[LOG] 69.30005 INFO juju environs/dummy: would pick tools from 1.13.1-precise-amd64
[LOG] 69.31708 INFO juju state: opening state; mongo addresses: ["localhost:55024"]; entity ""
[LOG] 69.32003 INFO juju state: connection established
[LOG] 69.35478 INFO juju state: initializing environment
[LOG] 69.37711 INFO juju state/api: listening on "127.0.0.1:56278"
[LOG] 69.39391 INFO juju state: opening state; mongo addresses: ["localhost:55024"]; entity ""
[LOG] 69.39892 INFO juju state: connection established
[LOG] 69.41638 INFO juju juju: authorization error while connecting to state server; retrying
[LOG] 69.41650 INFO juju state: opening state; mongo addresses: ["localhost:55024"]; entity ""
[LOG] 69.42429 INFO juju state: connection established
[LOG] 69.47785 INFO juju state/api: dialing "wss://127.0.0.1:56278/"
[LOG] 69.48612 INFO juju state/api: connection established
[LOG] 69.48628 DEBUG juju rpc/jsoncodec: <- {"RequestId":1,"Type":"Admin","Request":"Login","Params":{"AuthTag":"user-admin","Password":"dummy-secret","Nonce":""}}
[LOG] 69.48666 DEBUG juju rpc/jsoncodec: -> {"RequestId":1,"Response":{}}
[LOG] 69.48803 DEBUG juju rpc/jsoncodec: <- {"RequestId":2,"Type":"Pinger","Request":"Ping","Params":{}}
[LOG] 69.48810 DEBUG juju rpc/jsoncodec: -> {"RequestId":2,"Response":{}}
[LOG] 69.49849 INFO juju environs/dummy: dummy startinstance, machine 0
[LOG] 69.49853 INFO juju environs: reading tools with major version 1
[LOG] 69.49855 DEBUG juju.agent.tools reading v1.* tools
[LOG] 69.49856 INFO juju environs: falling back to public bucket
[LOG] 69.49857 DEBUG juju.agent.tools reading v1.* tools
[LOG] 69.49860 DEBUG juju.agent.tools found 1.13.1-precise-amd64
[LOG] 69.49862 INFO juju environs: filtering tools by version: 1.13.1
[LOG] 69.49863 INFO juju environs: filtering tools by series: precise
[LOG] 69.49865 INFO juju environs/dummy: would pick tools from 1.13.1-precise-amd64
[LOG] 69.51319 INFO juju environs/dummy: dummy startinstance, machine 1
[LOG] 69.51323 INFO juju environs: reading tools with major version 1
[LOG] 69.51324 DEBUG juju.agent.tools reading v1.* tools
[LOG] 69.51325 INFO juju environs: falling back to public bucket
[LOG] 69.51326 DEBUG juju.agent.tools reading v1.* tools
[LOG] 69.51328 DEBUG juju.agent.tools found 1.13.1-precise-amd64
[LOG] 69.51330 INFO juju environs: filtering tools by version: 1.13.1
[LOG] 69.51331 INFO juju environs: filtering tools by series: precise
[LOG] 69.51333 INFO juju environs/dummy: would pick tools from 1.13.1-precise-amd64
[LOG] 69.57261 INFO juju environs/dummy: dummy startinstance, machine 2
[LOG] 69.57269 INFO juju environs: reading tools with major version 1
[LOG] 69.57271 DEBUG juju.agent.tools reading v1.* tools
[LOG] 69.57272 INFO juju environs: falling back to public bucket
[LOG] 69.57273 DEBUG juju.agent.tools reading v1.* tools
[LOG] 69.57276 DEBUG juju.agent.tools found 1.13.1-precise-amd64
[LOG] 69.57278 INFO juju environs: filtering tools by version: 1.13.1
[LOG] 69.57279 INFO juju environs: filtering tools by series: precise
[LOG] 69.57282 INFO juju environs/dummy: would pick tools from 1.13.1-precise-amd64
testing juju scp [0:foo .]
[LOG] 69.71780 INFO juju state: opening state; mongo addresses: ["localhost:55024"]; entity ""
[LOG] 69.72096 INFO juju state: connection established
[LOG] 69.76683 INFO juju looking up address for machine 0...
testing juju scp [foo 0:]
[LOG] 69.82403 INFO juju state: opening state; mongo addresses: ["localhost:55024"]; entity ""
[LOG] 69.85114 INFO juju state: connection established
[LOG] 69.88027 INFO juju looking up address for machine 0...
testing juju scp [0:foo mysql/0:/foo]
[LOG] 69.98078 INFO juju state: opening state; mongo addresses: ["localhost:55024"]; entity ""
[LOG] 70.00005 INFO juju state: connection established
[LOG] 70.04188 INFO juju looking up address for machine 0...
[LOG] 70.04250 INFO juju looking up address for unit ""...
scp_test.go:71:
    c.Check(code, Equals, 0)
... obtained int = 1
... expected int = 0

scp_test.go:72:
    c.Check(ctx.Stderr.(*bytes.Buffer).String(), Equals, "")
... obtained string = "error: unit \"mysql/0\" has no public address\n"
... expected string = ""

scp_test.go:73:
    c.Check(ctx.Stdout.(*bytes.Buffer).String(), Equals, t.result)
... obtained string = ""
... expected string = "-o StrictHostKeyChecking no -o PasswordAuthentication no ubuntu@dummyenv-0.dns:foo ubuntu@dummyenv-0.dns:/foo\n"

testing juju scp [a b mysql/0]
[LOG] 70.08218 INFO juju state: opening state; mongo addresses: ["localhost:55024"]; entity ""
[LOG] 70.10318 INFO juju state: connection established
testing juju scp [mongodb/1:foo mongodb/0:]
[LOG] 70.18601 INFO juju state: opening state; mongo addresses: ["localhost:55024"]; entity ""
[LOG] 70.20381 INFO juju state: connection established
[LOG] 70.23992 INFO juju looking up address for unit ""...
scp_test.go:71:
    c.Check(code, Equals, 0)
... obtained int = 1
... expected int = 0

scp_test.go:72:
    c.Check(ctx.Stderr.(*bytes.Buffer).String(), Equals, "")
... obtained string = "error: unit \"mongodb/1\" has no public address\n"
... expected string = ""

[LOG] 70.26959 INFO juju environs/dummy: reset environment
[LOG] 70.26988 DEBUG juju rpc/jsoncodec: <- error: read tcp 127.0.0.1:56320: use of closed network connection (closing true)

----------------------------------------------------------------------
FAIL: ssh_test.go:92: SSHSuite.TestSSHCommand

[LOG] 70.36205 INFO juju environs/testing: uploading FAKE tools 1.13.1-precise-amd64
[LOG] 70.36215 INFO juju environs: reading tools with major version 1
[LOG] 70.36217 DEBUG juju.agent.tools reading v1.* tools
[LOG] 70.36219 INFO juju environs: falling back to public bucket
[LOG] 70.36220 DEBUG juju.agent.tools reading v1.* tools
[LOG] 70.36224 DEBUG juju.agent.tools found 1.13.1-precise-amd64
[LOG] 70.36226 INFO juju environs: filtering tools by series: precise
[LOG] 70.36229 INFO juju environs: filtering tools by version: 1.13.1
[LOG] 70.36231 INFO juju environs/dummy: would pick tools from 1.13.1-precise-amd64
[LOG] 70.37930 INFO juju state: opening state; mongo addresses: ["localhost:55024"]; entity ""
[LOG] 70.39913 INFO juju state: connection established
[LOG] 70.42483 INFO juju state: initializing environment
[LOG] 70.44568 INFO juju state/api: listening on "127.0.0.1:47768"
[LOG] 70.46332 INFO juju state: opening state; mongo addresses: ["localhost:55024"]; entity ""
[LOG] 70.46933 INFO juju state: connection established
[LOG] 70.48688 INFO juju juju: authorization error while connecting to state server; retrying
[LOG] 70.48734 INFO juju state: opening state; mongo addresses: ["localhost:55024"]; entity ""
[LOG] 70.49701 INFO juju state: connection established
[LOG] 70.52843 INFO juju state/api: dialing "wss://127.0.0.1:47768/"
[LOG] 70.53149 INFO juju state/api: connection established
[LOG] 70.53165 DEBUG juju rpc/jsoncodec: <- {"RequestId":1,"Type":"Admin","Request":"Login","Params":{"AuthTag":"user-admin","Password":"dummy-secret","Nonce":""}}
[LOG] 70.53203 DEBUG juju rpc/jsoncodec: -> {"RequestId":1,"Response":{}}
[LOG] 70.53343 DEBUG juju rpc/jsoncodec: <- {"RequestId":2,"Type":"Pinger","Request":"Ping","Params":{}}
[LOG] 70.53349 DEBUG juju rpc/jsoncodec: -> {"RequestId":2,"Response":{}}
[LOG] 70.54397 INFO juju environs/dummy: dummy startinstance, machine 0
[LOG] 70.54401 INFO juju environs: reading tools with major version 1
[LOG] 70.54402 DEBUG juju.agent.tools reading v1.* tools
[LOG] 70.54403 INFO juju environs: falling back to public bucket
[LOG] 70.54404 DEBUG juju.agent.tools reading v1.* tools
[LOG] 70.54407 DEBUG juju.agent.tools found 1.13.1-precise-amd64
[LOG] 70.54409 INFO juju environs: filtering tools by version: 1.13.1
[LOG] 70.54412 INFO juju environs: filtering tools by series: precise
[LOG] 70.54415 INFO juju environs/dummy: would pick tools from 1.13.1-precise-amd64
[LOG] 70.55925 INFO juju environs/dummy: dummy startinstance, machine 1
[LOG] 70.55928 INFO juju environs: reading tools with major version 1
[LOG] 70.55929 DEBUG juju.agent.tools reading v1.* tools
[LOG] 70.55930 INFO juju environs: falling back to public bucket
[LOG] 70.55931 DEBUG juju.agent.tools reading v1.* tools
[LOG] 70.55933 DEBUG juju.agent.tools found 1.13.1-precise-amd64
[LOG] 70.55935 INFO juju environs: filtering tools by version: 1.13.1
[LOG] 70.55936 INFO juju environs: filtering tools by series: precise
[LOG] 70.55938 INFO juju environs/dummy: would pick tools from 1.13.1-precise-amd64
[LOG] 70.57417 INFO juju environs/dummy: dummy startinstance, machine 2
[LOG] 70.57420 INFO juju environs: reading tools with major version 1
[LOG] 70.57421 DEBUG juju.agent.tools reading v1.* tools
[LOG] 70.57422 INFO juju environs: falling back to public bucket
[LOG] 70.57423 DEBUG juju.agent.tools reading v1.* tools
[LOG] 70.57425 DEBUG juju.agent.tools found 1.13.1-precise-amd64
[LOG] 70.57427 INFO juju environs: filtering tools by version: 1.13.1
[LOG] 70.57428 INFO juju environs: filtering tools by series: precise
[LOG] 70.57430 INFO juju environs/dummy: would pick tools from 1.13.1-precise-amd64
testing juju ssh [0]
[LOG] 70.72115 INFO juju state: opening state; mongo addresses: ["localhost:55024"]; entity ""
[LOG] 70.72726 INFO juju state: connection established
[LOG] 70.75336 INFO juju looking up address for machine 0...
testing juju ssh [0 uname -a]
[LOG] 70.81085 INFO juju state: opening state; mongo addresses: ["localhost:55024"]; entity ""
[LOG] 70.81851 INFO juju state: connection established
[LOG] 70.86264 INFO juju looking up address for machine 0...
testing juju ssh [0 -- uname -a]
[LOG] 70.91851 INFO juju state: opening state; mongo addresses: ["localhost:55024"]; entity ""
[LOG] 70.92186 INFO juju state: connection established
[LOG] 70.96947 INFO juju looking up address for machine 0...
testing juju ssh [mysql/0]
[LOG] 71.06405 INFO juju state: opening state; mongo addresses: ["localhost:55024"]; entity ""
[LOG] 71.08612 INFO juju state: connection established
[LOG] 71.12549 INFO juju looking up address for unit "mysql/0"...
ssh_test.go:115:
    c.Check(code, Equals, 0)
... obtained int = 1
... expected int = 0

ssh_test.go:116:
    c.Check(ctx.Stderr.(*bytes.Buffer).String(), Equals, "")
... obtained string = "error: unit \"mysql/0\" has no public address\n"
... expected string = ""

ssh_test.go:117:
    c.Check(ctx.Stdout.(*bytes.Buffer).String(), Equals, t.result)
... obtained string = ""
... expected string = "-l ubuntu -t -o StrictHostKeyChecking no -o PasswordAuthentication no dummyenv-0.dns\n"

testing juju ssh [mongodb/1]
[LOG] 71.17698 INFO juju state: opening state; mongo addresses: ["localhost:55024"]; entity ""
[LOG] 71.20298 INFO juju state: connection established
[LOG] 71.23257 INFO juju looking up address for unit "mongodb/1"...
ssh_test.go:115:
    c.Check(code, Equals, 0)
... obtained int = 1
... expected int = 0

ssh_test.go:116:
    c.Check(ctx.Stderr.(*bytes.Buffer).String(), Equals, "")
... obtained string = "error: unit \"mongodb/1\" has no public address\n"
... expected string = ""

[LOG] 71.25414 INFO juju environs/dummy: reset environment
[LOG] 71.25440 DEBUG juju rpc/jsoncodec: <- error: read tcp 127.0.0.1:34218: use of closed network connection (closing true)
OOPS: 146 passed, 3 FAILED
--- FAIL: TestPackage (101.74 seconds)
FAIL
FAIL	launchpad.net/juju-core/cmd/juju	101.990s
ok  	launchpad.net/juju-core/cmd/jujud	51.581s
ok  	launchpad.net/juju-core/cmd/plugins/juju-metadata	0.822s
ok  	launchpad.net/juju-core/constraints	0.034s
ok  	launchpad.net/juju-core/container/lxc	0.365s
?   	launchpad.net/juju-core/container/lxc/mock	[no test files]
ok  	launchpad.net/juju-core/downloader	5.371s
ok  	launchpad.net/juju-core/environs	1.168s
?   	launchpad.net/juju-core/environs/all	[no test files]
ok  	launchpad.net/juju-core/environs/azure	3.848s
ok  	launchpad.net/juju-core/environs/cloudinit	0.484s
ok  	launchpad.net/juju-core/environs/config	0.706s
ok  	launchpad.net/juju-core/environs/dummy	15.966s
ok  	launchpad.net/juju-core/environs/ec2	181.917s
ok  	launchpad.net/juju-core/environs/imagemetadata	0.238s
ok  	launchpad.net/juju-core/environs/instances	0.206s
ok  	launchpad.net/juju-core/environs/jujutest	0.294s
ok  	launchpad.net/juju-core/environs/local	1.237s
?   	launchpad.net/juju-core/environs/local/storage	[no test files]
ok  	launchpad.net/juju-core/environs/localstorage	0.226s
ok  	launchpad.net/juju-core/environs/maas	2.103s
ok  	launchpad.net/juju-core/environs/openstack	3.915s
?   	launchpad.net/juju-core/environs/provider	[no test files]
ok  	launchpad.net/juju-core/environs/sync	0.292s
ok  	launchpad.net/juju-core/environs/testing	0.010s
?   	launchpad.net/juju-core/errors	[no test files]
ok  	launchpad.net/juju-core/instance	0.023s
ok  	launchpad.net/juju-core/juju	11.815s
ok  	launchpad.net/juju-core/juju/osenv	0.202s
?   	launchpad.net/juju-core/juju/testing	[no test files]
ok  	launchpad.net/juju-core/log	0.020s
ok  	launchpad.net/juju-core/log/syslog	0.039s
ok  	launchpad.net/juju-core/names	0.027s
ok  	launchpad.net/juju-core/rpc	0.247s
ok  	launchpad.net/juju-core/rpc/jsoncodec	0.253s
ok  	launchpad.net/juju-core/schema	0.026s
ok  	launchpad.net/juju-core/state	68.646s
?   	launchpad.net/juju-core/state/api	[no test files]
ok  	launchpad.net/juju-core/state/api/agent	1.789s
?   	launchpad.net/juju-core/state/api/common	[no test files]
ok  	launchpad.net/juju-core/state/api/deployer	5.305s
ok  	launchpad.net/juju-core/state/api/machiner	2.637s
ok  	launchpad.net/juju-core/state/api/params	0.038s
ok  	launchpad.net/juju-core/state/api/upgrader	2.898s
ok  	launchpad.net/juju-core/state/api/watcher	2.761s
ok  	launchpad.net/juju-core/state/apiserver	2.739s
ok  	launchpad.net/juju-core/state/apiserver/agent	2.192s
ok  	launchpad.net/juju-core/state/apiserver/client	11.567s
ok  	launchpad.net/juju-core/state/apiserver/common	0.053s
ok  	launchpad.net/juju-core/state/apiserver/deployer	3.747s
ok  	launchpad.net/juju-core/state/apiserver/machine	3.305s
?   	launchpad.net/juju-core/state/apiserver/testing	[no test files]
ok  	launchpad.net/juju-core/state/apiserver/upgrader	3.500s
ok  	launchpad.net/juju-core/state/multiwatcher	0.890s
ok  	launchpad.net/juju-core/state/presence	6.556s
ok  	launchpad.net/juju-core/state/statecmd	6.177s
?   	launchpad.net/juju-core/state/testing	[no test files]
ok  	launchpad.net/juju-core/state/watcher	5.215s
ok  	launchpad.net/juju-core/store	17.959s
ok  	launchpad.net/juju-core/testing	1.351s
ok  	launchpad.net/juju-core/testing/checkers	0.016s
ok  	launchpad.net/juju-core/thirdparty/pbkdf2	0.132s
ok  	launchpad.net/juju-core/upstart	0.115s
ok  	launchpad.net/juju-core/utils	0.947s
ok  	launchpad.net/juju-core/utils/fslock	0.900s
ok  	launchpad.net/juju-core/utils/set	0.020s
ok  	launchpad.net/juju-core/version	0.061s
ok  	launchpad.net/juju-core/worker	1.449s
ok  	launchpad.net/juju-core/worker/cleaner	1.141s
ok  	launchpad.net/juju-core/worker/deployer	3.138s
ok  	launchpad.net/juju-core/worker/firewaller	10.349s
ok  	launchpad.net/juju-core/worker/machiner	2.262s
ok  	launchpad.net/juju-core/worker/minunitsworker	1.309s
ok  	launchpad.net/juju-core/worker/provisioner	16.492s
ok  	launchpad.net/juju-core/worker/resumer	1.322s

----------------------------------------------------------------------
FAIL: uniter_test.go:258: UniterSuite.TestUniterBootstrap

[LOG] 92.32063 INFO juju environs/testing: uploading FAKE tools 1.13.1-precise-amd64
[LOG] 92.32075 INFO juju environs: reading tools with major version 1
[LOG] 92.32077 DEBUG juju.agent.tools reading v1.* tools
[LOG] 92.32078 INFO juju environs: falling back to public bucket
[LOG] 92.32079 DEBUG juju.agent.tools reading v1.* tools
[LOG] 92.32083 DEBUG juju.agent.tools found 1.13.1-precise-amd64
[LOG] 92.32085 INFO juju environs: filtering tools by series: precise
[LOG] 92.32087 INFO juju environs: filtering tools by version: 1.13.1
[LOG] 92.32089 INFO juju environs/dummy: would pick tools from 1.13.1-precise-amd64
[LOG] 92.33825 INFO juju state: opening state; mongo addresses: ["localhost:34764"]; entity ""
[LOG] 92.34112 INFO juju state: connection established
[LOG] 92.35959 INFO juju state: initializing environment
[LOG] 92.39232 INFO juju state/api: listening on "127.0.0.1:37022"
[LOG] 92.41110 INFO juju state: opening state; mongo addresses: ["localhost:34764"]; entity ""
[LOG] 92.41461 INFO juju state: connection established
[LOG] 92.43332 INFO juju juju: authorization error while connecting to state server; retrying
[LOG] 92.43373 INFO juju state: opening state; mongo addresses: ["localhost:34764"]; entity ""
[LOG] 92.44184 INFO juju state: connection established
[LOG] 92.49772 INFO juju state/api: dialing "wss://127.0.0.1:37022/"
[LOG] 92.50284 INFO juju state/api: connection established
[LOG] 92.50300 DEBUG juju rpc/jsoncodec: <- {"RequestId":1,"Type":"Admin","Request":"Login","Params":{"AuthTag":"user-admin","Password":"dummy-secret","Nonce":""}}
[LOG] 92.50365 DEBUG juju rpc/jsoncodec: -> {"RequestId":1,"Response":{}}

test 0: insane deployment

[LOG] 92.50487 DEBUG juju rpc/jsoncodec: <- {"RequestId":2,"Type":"Pinger","Request":"Ping","Params":{}}
[LOG] 92.50512 DEBUG juju rpc/jsoncodec: -> {"RequestId":2,"Response":{}}
step 0
uniter_test.createCharm{revision:0, badHooks:[]string(nil), customize:(func(*gocheck.C, *uniter_test.context, string))(nil)}
uniter_test.addCharm{dir:(*charm.Dir)(0xc2001dfdb0), curl:(*charm.URL)(0xc200721dc0)}
step 1
uniter_test.serveCharm{}
step 2
uniter_test.writeFile{path:"charm", mode:0x1a4}
step 3
uniter_test.createUniter{}
uniter_test.createServiceAndUnit{}
uniter_test.startUniter{}
uniter_test.waitAddresses{}
[LOG] 92.63541 INFO juju worker/uniter: unit "u/0" started
[LOG] 92.65661 INFO juju worker/uniter: ModeInit starting
[LOG] 92.65674 INFO juju worker/uniter: updating unit addresses
[LOG] 92.65723 DEBUG juju worker/uniter/filter: charm check skipped, not yet installed.
[LOG] 92.65843 DEBUG juju worker/uniter/filter: got unit change
[LOG] 92.65928 DEBUG juju worker/uniter/filter: got service change
[LOG] 92.65997 DEBUG juju worker/uniter/filter: charm check skipped, not yet installed.
[LOG] 92.65999 DEBUG juju worker/uniter/filter: got relations change
[LOG] 92.66499 INFO juju reconciling relation state
[LOG] 92.66509 DEBUG juju worker/uniter: ModeInit exiting
[LOG] 92.66510 INFO juju worker/uniter: ModeContinue starting
[LOG] 92.66511 INFO juju loading uniter state
[LOG] 92.66513 INFO juju worker/uniter: charm is not deployed
[LOG] 92.66514 DEBUG juju worker/uniter: ModeContinue exiting
[LOG] 92.66517 INFO juju worker/uniter: ModeInstalling cs:series/wordpress-0 starting
[LOG] 92.66518 INFO juju worker/uniter: fetching charm "cs:series/wordpress-0"
[LOG] 92.66568 INFO juju worker/uniter/charm: downloading cs:series/wordpress-0 from http://localhost:40085/charms/wordpress/0
[LOG] 92.66617 INFO juju worker/uniter/charm: download complete
[LOG] 92.66629 INFO juju worker/uniter/charm: download verified
[LOG] 92.72765 DEBUG juju worker/uniter/filter: changing charm to "cs:series/wordpress-0"
[LOG] 92.75659 DEBUG juju worker/uniter/filter: no new charm event
[LOG] 92.75773 INFO juju worker/uniter: deploying charm "cs:series/wordpress-0"
[LOG] 92.75848 DEBUG juju worker/uniter/filter: got config change
[LOG] 92.75850 DEBUG juju worker/uniter/filter: preparing new config event
[LOG] 92.75853 DEBUG juju worker/uniter/filter: got relations change
[LOG] 92.76144 ERROR juju worker/uniter/charm: charm deployment failed: "/tmp/gocheck-6175742077372812453/0/agents/unit-u-0/charm" is not a directory
[LOG] 92.76157 DEBUG juju worker/uniter: ModeInstalling cs:series/wordpress-0 exiting
[LOG] 92.76165 INFO juju worker/uniter: unit "u/0" shutting down: ModeInstalling cs:series/wordpress-0: charm deployment failed: "/tmp/gocheck-6175742077372812453/0/agents/unit-u-0/charm" is not a directory
[LOG] 92.76205 ERROR juju worker/uniter/filter: tomb: dying
uniter_test.go:259:
    s.runUniterTests(c, bootstrapTests)
uniter_test.go:1092:
    c.Fatalf("timed out waiting for unit addresses")
... Error: timed out waiting for unit addresses

uniter_test.go:259:
    s.runUniterTests(c, bootstrapTests)
uniter_test.go:143:
    c.Assert(err, IsNil)
... value *errors.errorString = &errors.errorString{s:"ModeInstalling cs:series/wordpress-0: charm deployment failed: \"/tmp/gocheck-6175742077372812453/0/agents/unit-u-0/charm\" is not a directory"} ("ModeInstalling cs:series/wordpress-0: charm deployment failed: \"/tmp/gocheck-6175742077372812453/0/agents/unit-u-0/charm\" is not a directory")

[LOG] 2.68908 INFO juju environs/dummy: reset environment
[LOG] 2.69013 DEBUG juju rpc/jsoncodec: <- error: read tcp 127.0.0.1:58358: use of closed network connection (closing true)
[LOG] 2.75359 INFO juju environs/testing: uploading FAKE tools 1.13.1-precise-amd64
[LOG] 2.75369 INFO juju environs: reading tools with major version 1
[LOG] 2.75371 DEBUG juju.agent.tools reading v1.* tools
[LOG] 2.75372 INFO juju environs: falling back to public bucket
[LOG] 2.75373 DEBUG juju.agent.tools reading v1.* tools
[LOG] 2.75376 DEBUG juju.agent.tools found 1.13.1-precise-amd64
[LOG] 2.75378 INFO juju environs: filtering tools by series: precise
[LOG] 2.75380 INFO juju environs: filtering tools by version: 1.13.1
[LOG] 2.75381 INFO juju environs/dummy: would pick tools from 1.13.1-precise-amd64
[LOG] 2.77146 INFO juju state: opening state; mongo addresses: ["localhost:34764"]; entity ""
[LOG] 2.78521 INFO juju state: connection established
[LOG] 2.83325 INFO juju state: initializing environment
[LOG] 2.86769 INFO juju state/api: listening on "127.0.0.1:58699"
[LOG] 2.88948 INFO juju state: opening state; mongo addresses: ["localhost:34764"]; entity ""
[LOG] 2.89772 INFO juju state: connection established
[LOG] 2.89848 INFO juju juju: authorization error while connecting to state server; retrying
[LOG] 2.89855 INFO juju state: opening state; mongo addresses: ["localhost:34764"]; entity ""
[LOG] 2.90179 INFO juju state: connection established
[LOG] 2.96321 INFO juju state/api: dialing "wss://127.0.0.1:58699/"
[LOG] 2.96630 INFO juju state/api: connection established
[LOG] 2.96644 DEBUG juju rpc/jsoncodec: <- {"RequestId":1,"Type":"Admin","Request":"Login","Params":{"AuthTag":"user-admin","Password":"dummy-secret","Nonce":""}}
[LOG] 2.96699 DEBUG juju rpc/jsoncodec: -> {"RequestId":1,"Response":{}}
[LOG] 2.97174 DEBUG juju rpc/jsoncodec: <- {"RequestId":2,"Type":"Pinger","Request":"Ping","Params":{}}
[LOG] 2.97241 DEBUG juju rpc/jsoncodec: -> {"RequestId":2,"Response":{}}
[LOG] 2.97318 INFO juju environs/dummy: reset environment
[LOG] 2.97342 DEBUG juju rpc/jsoncodec: <- error: read tcp 127.0.0.1:55342: use of closed network connection (closing true)

----------------------------------------------------------------------
FAIL: uniter_test.go:429: UniterSuite.TestUniterConfigChangedHook

[LOG] 3.02654 INFO juju environs/testing: uploading FAKE tools 1.13.1-precise-amd64
[LOG] 3.02665 INFO juju environs: reading tools with major version 1
[LOG] 3.02667 DEBUG juju.agent.tools reading v1.* tools
[LOG] 3.02668 INFO juju environs: falling back to public bucket
[LOG] 3.02669 DEBUG juju.agent.tools reading v1.* tools
[LOG] 3.02672 DEBUG juju.agent.tools found 1.13.1-precise-amd64
[LOG] 3.02674 INFO juju environs: filtering tools by series: precise
[LOG] 3.02675 INFO juju environs: filtering tools by version: 1.13.1
[LOG] 3.02677 INFO juju environs/dummy: would pick tools from 1.13.1-precise-amd64
[LOG] 3.04405 INFO juju state: opening state; mongo addresses: ["localhost:34764"]; entity ""
[LOG] 3.04684 INFO juju state: connection established
[LOG] 3.07924 INFO juju state: initializing environment
[LOG] 3.10014 INFO juju state/api: listening on "127.0.0.1:33117"
[LOG] 3.12571 INFO juju state: opening state; mongo addresses: ["localhost:34764"]; entity ""
[LOG] 3.12849 INFO juju state: connection established
[LOG] 3.14281 INFO juju juju: authorization error while connecting to state server; retrying
[LOG] 3.14289 INFO juju state: opening state; mongo addresses: ["localhost:34764"]; entity ""
[LOG] 3.14804 INFO juju state: connection established
[LOG] 3.18437 INFO juju state/api: dialing "wss://127.0.0.1:33117/"
[LOG] 3.19306 INFO juju state/api: connection established
[LOG] 3.19320 DEBUG juju rpc/jsoncodec: <- {"RequestId":1,"Type":"Admin","Request":"Login","Params":{"AuthTag":"user-admin","Password":"dummy-secret","Nonce":""}}
[LOG] 3.19354 DEBUG juju rpc/jsoncodec: -> {"RequestId":1,"Response":{}}

test 0: config-changed hook fail and resolve

step 0
uniter_test.startupError{badHook:"config-changed"}
uniter_test.createCharm{revision:0, badHooks:[]string{"config-changed"}, customize:(func(*gocheck.C, *uniter_test.context, string))(nil)}
uniter_test.addCharm{dir:(*charm.Dir)(0xc20033b930), curl:(*charm.URL)(0xc200be1780)}
[LOG] 3.21422 DEBUG juju rpc/jsoncodec: <- {"RequestId":2,"Type":"Pinger","Request":"Ping","Params":{}}
[LOG] 3.24354 DEBUG juju rpc/jsoncodec: -> {"RequestId":2,"Response":{}}
uniter_test.serveCharm{}
uniter_test.createUniter{}
uniter_test.createServiceAndUnit{}
uniter_test.startUniter{}
uniter_test.waitAddresses{}
[LOG] 3.31269 INFO juju worker/uniter: unit "u/0" started
[LOG] 3.33146 INFO juju worker/uniter: ModeInit starting
[LOG] 3.33150 INFO juju worker/uniter: updating unit addresses
[LOG] 3.33248 DEBUG juju worker/uniter/filter: charm check skipped, not yet installed.
[LOG] 3.33605 DEBUG juju worker/uniter/filter: got unit change
[LOG] 3.33766 DEBUG juju worker/uniter/filter: got service change
[LOG] 3.33837 DEBUG juju worker/uniter/filter: charm check skipped, not yet installed.
[LOG] 3.33839 DEBUG juju worker/uniter/filter: got relations change
[LOG] 3.34344 INFO juju reconciling relation state
[LOG] 3.34354 DEBUG juju worker/uniter: ModeInit exiting
[LOG] 3.34355 INFO juju worker/uniter: ModeContinue starting
[LOG] 3.34356 INFO juju loading uniter state
[LOG] 3.34358 INFO juju worker/uniter: charm is not deployed
[LOG] 3.34359 DEBUG juju worker/uniter: ModeContinue exiting
[LOG] 3.34360 INFO juju worker/uniter: ModeInstalling cs:series/wordpress-0 starting
[LOG] 3.34362 INFO juju worker/uniter: fetching charm "cs:series/wordpress-0"
[LOG] 3.34412 INFO juju worker/uniter/charm: downloading cs:series/wordpress-0 from http://localhost:40085/charms/wordpress/0
[LOG] 3.34463 INFO juju worker/uniter/charm: download complete
[LOG] 3.34477 INFO juju worker/uniter/charm: download verified
[LOG] 3.37890 DEBUG juju worker/uniter/filter: changing charm to "cs:series/wordpress-0"
[LOG] 3.38642 DEBUG juju worker/uniter/filter: no new charm event
[LOG] 3.38686 INFO juju worker/uniter: deploying charm "cs:series/wordpress-0"
[LOG] 3.38726 DEBUG juju worker/uniter/filter: got config change
[LOG] 3.38729 DEBUG juju worker/uniter/filter: preparing new config event
[LOG] 3.38730 DEBUG juju worker/uniter/filter: got relations change
[LOG] 3.38989 INFO juju worker/uniter/charm: preparing new charm deployment
[LOG] 3.45980 INFO juju worker/uniter/charm: deploying charm
[LOG] 3.46265 INFO juju worker/uniter/charm: charm deployment succeeded
[LOG] 3.46561 INFO juju worker/uniter: charm "cs:series/wordpress-0" is deployed
[LOG] 3.46782 DEBUG juju worker/uniter: ModeInstalling cs:series/wordpress-0 exiting
[LOG] 3.46787 INFO juju worker/uniter: ModeContinue starting
[LOG] 3.46789 INFO juju worker/uniter: found queued "install" hook
[LOG] 3.47276 INFO juju worker/uniter: running "install" hook
[LOG] 3.54948 INFO juju worker/uniter/jujuc: running hook tool "juju-log" ["66c8aa4b-7801-4807-8b2a-e4260bacf51f" "install"]
[LOG] 3.54954 DEBUG juju worker/uniter/jujuc: hook context id "u/0:install:601246710097769432"; dir "/tmp/gocheck-6175742077372812453/0/agents/unit-u-0/charm"
[LOG] 3.54958 INFO juju u/0: 66c8aa4b-7801-4807-8b2a-e4260bacf51f install
[LOG] 3.55543 INFO juju worker/uniter: ran "install" hook
[LOG] 3.55546 INFO juju worker/uniter: committing "install" hook
[LOG] 3.57189 INFO juju worker/uniter: committed "install" hook
[LOG] 3.57246 DEBUG juju worker/uniter: ModeContinue exiting
[LOG] 3.57252 INFO juju worker/uniter: ModeContinue starting
[LOG] 3.57260 INFO juju worker/uniter: continuing after "install" hook
[LOG] 3.57261 DEBUG juju worker/uniter: ModeContinue exiting
[LOG] 3.57262 INFO juju worker/uniter: ModeConfigChanged starting
[LOG] 3.57953 DEBUG juju worker/uniter/filter: discarded config event
[LOG] 3.58285 INFO juju worker/uniter: running "config-changed" hook
[LOG] 3.67400 INFO juju worker/uniter/jujuc: running hook tool "juju-log" ["66c8aa4b-7801-4807-8b2a-e4260bacf51f" "fail-config-changed"]
[LOG] 3.67406 DEBUG juju worker/uniter/jujuc: hook context id "u/0:config-changed:4756596409946127991"; dir "/tmp/gocheck-6175742077372812453/0/agents/unit-u-0/charm"
[LOG] 3.67411 INFO juju u/0: 66c8aa4b-7801-4807-8b2a-e4260bacf51f fail-config-changed
[LOG] 3.67638 ERROR juju worker/uniter: hook failed: exit status 1
[LOG] 3.67688 DEBUG juju worker/uniter: ModeConfigChanged exiting
[LOG] 3.67691 INFO juju worker/uniter: ModeHookError starting
[LOG] 3.68335 DEBUG juju worker/uniter/filter: want resolved event
[LOG] 3.68338 DEBUG juju worker/uniter/filter: want forced upgrade true
[LOG] 3.68340 DEBUG juju worker/uniter/filter: no new charm event
[LOG] 8.16666 DEBUG juju worker/uniter/filter: got unit change
uniter_test.go:430:
    s.runUniterTests(c, configChangedHookTests)
uniter_test.go:1092:
    c.Fatalf("timed out waiting for unit addresses")
... Error: timed out waiting for unit addresses

[LOG] 13.35117 DEBUG juju worker/uniter: ModeHookError exiting
[LOG] 13.35120 INFO juju worker/uniter: unit "u/0" shutting down: tomb: dying
[LOG] 13.35141 ERROR juju worker/uniter/filter: tomb: dying
[LOG] 13.35275 INFO juju environs/dummy: reset environment
[LOG] 13.35301 DEBUG juju rpc/jsoncodec: <- error: read tcp 127.0.0.1:44608: use of closed network connection (closing true)
[LOG] 13.42572 INFO juju environs/testing: uploading FAKE tools 1.13.1-precise-amd64
[LOG] 13.42589 INFO juju environs: reading tools with major version 1
[LOG] 13.42590 DEBUG juju.agent.tools reading v1.* tools
[LOG] 13.42591 INFO juju environs: falling back to public bucket
[LOG] 13.42592 DEBUG juju.agent.tools reading v1.* tools
[LOG] 13.42596 DEBUG juju.agent.tools found 1.13.1-precise-amd64
[LOG] 13.42598 INFO juju environs: filtering tools by series: precise
[LOG] 13.42599 INFO juju environs: filtering tools by version: 1.13.1
[LOG] 13.42601 INFO juju environs/dummy: would pick tools from 1.13.1-precise-amd64
[LOG] 13.44336 INFO juju state: opening state; mongo addresses: ["localhost:34764"]; entity ""
[LOG] 13.47035 INFO juju state: connection established
[LOG] 13.50222 INFO juju state: initializing environment
[LOG] 13.52292 INFO juju state/api: listening on "127.0.0.1:44748"
[LOG] 13.55212 INFO juju state: opening state; mongo addresses: ["localhost:34764"]; entity ""
[LOG] 13.55751 INFO juju state: connection established
[LOG] 13.57421 INFO juju juju: authorization error while connecting to state server; retrying
[LOG] 13.57497 INFO juju state: opening state; mongo addresses: ["localhost:34764"]; entity ""
[LOG] 13.58380 INFO juju state: connection established
[LOG] 13.61583 INFO juju state/api: dialing "wss://127.0.0.1:44748/"
[LOG] 13.61867 INFO juju state/api: connection established
[LOG] 13.61880 DEBUG juju rpc/jsoncodec: <- {"RequestId":1,"Type":"Admin","Request":"Login","Params":{"AuthTag":"user-admin","Password":"dummy-secret","Nonce":""}}
[LOG] 13.61927 DEBUG juju rpc/jsoncodec: -> {"RequestId":1,"Response":{}}
[LOG] 13.62606 INFO juju environs/dummy: reset environment
[LOG] 13.62620 DEBUG juju rpc/jsoncodec: <- error: read tcp 127.0.0.1:45071: use of closed network connection (closing true)

----------------------------------------------------------------------
FAIL: uniter_test.go:517: UniterSuite.TestUniterDyingReaction

[LOG] 13.69769 INFO juju environs/testing: uploading FAKE tools 1.13.1-precise-amd64
[LOG] 13.69776 INFO juju environs: reading tools with major version 1
[LOG] 13.69777 DEBUG juju.agent.tools reading v1.* tools
[LOG] 13.69778 INFO juju environs: falling back to public bucket
[LOG] 13.69780 DEBUG juju.agent.tools reading v1.* tools
[LOG] 13.69782 DEBUG juju.agent.tools found 1.13.1-precise-amd64
[LOG] 13.69784 INFO juju environs: filtering tools by series: precise
[LOG] 13.69785 INFO juju environs: filtering tools by version: 1.13.1
[LOG] 13.69787 INFO juju environs/dummy: would pick tools from 1.13.1-precise-amd64
[LOG] 13.71486 INFO juju state: opening state; mongo addresses: ["localhost:34764"]; entity ""
[LOG] 13.73855 INFO juju state: connection established
[LOG] 13.76779 INFO juju state: initializing environment
[LOG] 13.79107 INFO juju state/api: listening on "127.0.0.1:34986"
[LOG] 13.80807 INFO juju state: opening state; mongo addresses: ["localhost:34764"]; entity ""
[LOG] 13.82916 INFO juju state: connection established
[LOG] 13.83016 INFO juju juju: authorization error while connecting to state server; retrying
[LOG] 13.83025 INFO juju state: opening state; mongo addresses: ["localhost:34764"]; entity ""
[LOG] 13.83535 INFO juju state: connection established
[LOG] 13.89235 INFO juju state/api: dialing "wss://127.0.0.1:34986/"
[LOG] 13.89847 INFO juju state/api: connection established
[LOG] 13.89865 DEBUG juju rpc/jsoncodec: <- {"RequestId":1,"Type":"Admin","Request":"Login","Params":{"AuthTag":"user-admin","Password":"dummy-secret","Nonce":""}}
[LOG] 13.89899 DEBUG juju rpc/jsoncodec: -> {"RequestId":1,"Response":{}}

test 0: steady state service dying

[LOG] 13.89935 DEBUG juju rpc/jsoncodec: <- {"RequestId":2,"Type":"Pinger","Request":"Ping","Params":{}}
[LOG] 13.89941 DEBUG juju rpc/jsoncodec: -> {"RequestId":2,"Response":{}}
step 0
uniter_test.quickStart{}
uniter_test.createCharm{revision:0, badHooks:[]string(nil), customize:(func(*gocheck.C, *uniter_test.context, string))(nil)}
uniter_test.addCharm{dir:(*charm.Dir)(0xc200b482a0), curl:(*charm.URL)(0xc200685cd0)}
uniter_test.serveCharm{}
uniter_test.createUniter{}
uniter_test.createServiceAndUnit{}
uniter_test.startUniter{}
uniter_test.waitAddresses{}
[LOG] 14.02772 INFO juju worker/uniter: unit "u/0" started
[LOG] 14.03892 INFO juju worker/uniter: ModeInit starting
[LOG] 14.03894 INFO juju worker/uniter: updating unit addresses
[LOG] 14.03918 DEBUG juju worker/uniter/filter: charm check skipped, not yet installed.
[LOG] 14.04030 DEBUG juju worker/uniter/filter: got unit change
[LOG] 14.04132 DEBUG juju worker/uniter/filter: got relations change
[LOG] 14.04134 DEBUG juju worker/uniter/filter: got service change
[LOG] 14.04207 DEBUG juju worker/uniter/filter: charm check skipped, not yet installed.
[LOG] 14.04747 INFO juju reconciling relation state
[LOG] 14.04756 DEBUG juju worker/uniter: ModeInit exiting
[LOG] 14.04757 INFO juju worker/uniter: ModeContinue starting
[LOG] 14.04758 INFO juju loading uniter state
[LOG] 14.04762 INFO juju worker/uniter: charm is not deployed
[LOG] 14.04764 DEBUG juju worker/uniter: ModeContinue exiting
[LOG] 14.04768 INFO juju worker/uniter: ModeInstalling cs:series/wordpress-0 starting
[LOG] 14.04770 INFO juju worker/uniter: fetching charm "cs:series/wordpress-0"
[LOG] 14.04826 INFO juju worker/uniter/charm: downloading cs:series/wordpress-0 from http://localhost:40085/charms/wordpress/0
[LOG] 14.04884 INFO juju worker/uniter/charm: download complete
[LOG] 14.04905 INFO juju worker/uniter/charm: download verified
[LOG] 14.11924 DEBUG juju worker/uniter/filter: changing charm to "cs:series/wordpress-0"
[LOG] 14.13752 DEBUG juju worker/uniter/filter: no new charm event
[LOG] 14.13807 INFO juju worker/uniter: deploying charm "cs:series/wordpress-0"
[LOG] 14.13881 DEBUG juju worker/uniter/filter: got config change
[LOG] 14.13889 DEBUG juju worker/uniter/filter: preparing new config event
[LOG] 14.13891 DEBUG juju worker/uniter/filter: got relations change
[LOG] 14.14153 INFO juju worker/uniter/charm: preparing new charm deployment
[LOG] 14.29853 INFO juju worker/uniter/charm: deploying charm
[LOG] 14.30315 INFO juju worker/uniter/charm: charm deployment succeeded
[LOG] 14.30652 INFO juju worker/uniter: charm "cs:series/wordpress-0" is deployed
[LOG] 14.30973 DEBUG juju worker/uniter: ModeInstalling cs:series/wordpress-0 exiting
[LOG] 14.30982 INFO juju worker/uniter: ModeContinue starting
[LOG] 14.30987 INFO juju worker/uniter: found queued "install" hook
[LOG] 14.31711 INFO juju worker/uniter: running "install" hook
[LOG] 14.43684 INFO juju worker/uniter/jujuc: running hook tool "juju-log" ["a4e2cfe6-ba96-44d3-8b0c-19466a97518b" "install"]
[LOG] 14.43710 DEBUG juju worker/uniter/jujuc: hook context id "u/0:install:2177792784801172668"; dir "/tmp/gocheck-6175742077372812453/0/agents/unit-u-0/charm"
[LOG] 14.43728 INFO juju u/0: a4e2cfe6-ba96-44d3-8b0c-19466a97518b install
[LOG] 14.44564 INFO juju worker/uniter: ran "install" hook
[LOG] 14.44589 INFO juju worker/uniter: committing "install" hook
[LOG] 14.47748 INFO juju worker/uniter: committed "install" hook
[LOG] 14.47829 DEBUG juju worker/uniter: ModeContinue exiting
[LOG] 14.47835 INFO juju worker/uniter: ModeContinue starting
[LOG] 14.47838 INFO juju worker/uniter: continuing after "install" hook
[LOG] 14.47839 DEBUG juju worker/uniter: ModeContinue exiting
[LOG] 14.47843 INFO juju worker/uniter: ModeConfigChanged starting
[LOG] 14.51079 DEBUG juju worker/uniter/filter: discarded config event
[LOG] 14.51425 INFO juju worker/uniter: running "config-changed" hook
[LOG] 14.61462 INFO juju worker/uniter/jujuc: running hook tool "juju-log" ["a4e2cfe6-ba96-44d3-8b0c-19466a97518b" "config-changed"]
[LOG] 14.61481 DEBUG juju worker/uniter/jujuc: hook context id "u/0:config-changed:7189892485631597015"; dir "/tmp/gocheck-6175742077372812453/0/agents/unit-u-0/charm"
[LOG] 14.61505 INFO juju u/0: a4e2cfe6-ba96-44d3-8b0c-19466a97518b config-changed
[LOG] 14.62052 INFO juju worker/uniter: ran "config-changed" hook
[LOG] 14.62055 INFO juju worker/uniter: committing "config-changed" hook
[LOG] 14.65027 INFO juju worker/uniter: committed "config-changed" hook
[LOG] 14.65082 DEBUG juju worker/uniter: ModeConfigChanged exiting
[LOG] 14.65085 INFO juju worker/uniter: ModeContinue starting
[LOG] 14.65086 INFO juju worker/uniter: continuing after "config-changed" hook
[LOG] 14.65087 DEBUG juju worker/uniter: ModeContinue exiting
[LOG] 14.65088 INFO juju worker/uniter: ModeStarting starting
[LOG] 14.65592 INFO juju worker/uniter: running "start" hook
[LOG] 14.76316 INFO juju worker/uniter/jujuc: running hook tool "juju-log" ["a4e2cfe6-ba96-44d3-8b0c-19466a97518b" "start"]
[LOG] 14.76320 DEBUG juju worker/uniter/jujuc: hook context id "u/0:start:8690697648720404033"; dir "/tmp/gocheck-6175742077372812453/0/agents/unit-u-0/charm"
[LOG] 14.76324 INFO juju u/0: a4e2cfe6-ba96-44d3-8b0c-19466a97518b start
[LOG] 14.77188 INFO juju worker/uniter: ran "start" hook
[LOG] 14.77209 INFO juju worker/uniter: committing "start" hook
[LOG] 14.82312 INFO juju worker/uniter: committed "start" hook
[LOG] 14.82369 DEBUG juju worker/uniter: ModeStarting exiting
[LOG] 14.82374 INFO juju worker/uniter: ModeContinue starting
[LOG] 14.82375 INFO juju worker/uniter: continuing after "start" hook
[LOG] 14.82376 DEBUG juju worker/uniter: ModeContinue exiting
[LOG] 14.82377 INFO juju worker/uniter: ModeAbide starting
[LOG] 14.82974 DEBUG juju worker/uniter/filter: want forced upgrade false
[LOG] 14.82976 DEBUG juju worker/uniter/filter: no new charm event
[LOG] 18.85486 DEBUG juju worker/uniter/filter: got unit change
uniter_test.go:518:
    s.runUniterTests(c, dyingReactionTests)
uniter_test.go:1092:
    c.Fatalf("timed out waiting for unit addresses")
... Error: timed out waiting for unit addresses

[LOG] 24.05408 DEBUG juju worker/uniter: ModeAbide exiting
[LOG] 24.05410 INFO juju worker/uniter: unit "u/0" shutting down: tomb: dying
[LOG] 24.05425 ERROR juju worker/uniter/filter: tomb: dying
[LOG] 24.05522 INFO juju environs/dummy: reset environment
[LOG] 24.05548 DEBUG juju rpc/jsoncodec: <- error: read tcp 127.0.0.1:54750: use of closed network connection (closing true)
[LOG] 24.15245 INFO juju environs/testing: uploading FAKE tools 1.13.1-precise-amd64
[LOG] 24.15255 INFO juju environs: reading tools with major version 1
[LOG] 24.15257 DEBUG juju.agent.tools reading v1.* tools
[LOG] 24.15258 INFO juju environs: falling back to public bucket
[LOG] 24.15259 DEBUG juju.agent.tools reading v1.* tools
[LOG] 24.15262 DEBUG juju.agent.tools found 1.13.1-precise-amd64
[LOG] 24.15265 INFO juju environs: filtering tools by series: precise
[LOG] 24.15267 INFO juju environs: filtering tools by version: 1.13.1
[LOG] 24.15269 INFO juju environs/dummy: would pick tools from 1.13.1-precise-amd64
[LOG] 24.16979 INFO juju state: opening state; mongo addresses: ["localhost:34764"]; entity ""
[LOG] 24.19196 INFO juju state: connection established
[LOG] 24.23271 INFO juju state: initializing environment
[LOG] 24.25331 INFO juju state/api: listening on "127.0.0.1:43414"
[LOG] 24.27577 INFO juju state: opening state; mongo addresses: ["localhost:34764"]; entity ""
[LOG] 24.29622 INFO juju state: connection established
[LOG] 24.29822 INFO juju juju: authorization error while connecting to state server; retrying
[LOG] 24.29834 INFO juju state: opening state; mongo addresses: ["localhost:34764"]; entity ""
[LOG] 24.30605 INFO juju state: connection established
[LOG] 24.36287 INFO juju state/api: dialing "wss://127.0.0.1:43414/"
[LOG] 24.37830 INFO juju state/api: connection established
[LOG] 24.37848 DEBUG juju rpc/jsoncodec: <- {"RequestId":1,"Type":"Admin","Request":"Login","Params":{"AuthTag":"user-admin","Password":"dummy-secret","Nonce":""}}
[LOG] 24.37920 DEBUG juju rpc/jsoncodec: -> {"RequestId":1,"Response":{}}
[LOG] 24.39878 DEBUG juju rpc/jsoncodec: <- {"RequestId":2,"Type":"Pinger","Request":"Ping","Params":{}}
[LOG] 24.39885 DEBUG juju rpc/jsoncodec: -> {"RequestId":2,"Response":{}}
[LOG] 24.39942 INFO juju environs/dummy: reset environment
[LOG] 24.39957 DEBUG juju rpc/jsoncodec: <- error: read tcp 127.0.0.1:53955: use of closed network connection (closing true)

----------------------------------------------------------------------
FAIL: uniter_test.go:683: UniterSuite.TestUniterErrorStateUpgrade

[LOG] 24.47642 INFO juju environs/testing: uploading FAKE tools 1.13.1-precise-amd64
[LOG] 24.47660 INFO juju environs: reading tools with major version 1
[LOG] 24.47662 DEBUG juju.agent.tools reading v1.* tools
[LOG] 24.47663 INFO juju environs: falling back to public bucket
[LOG] 24.47666 DEBUG juju.agent.tools reading v1.* tools
[LOG] 24.47669 DEBUG juju.agent.tools found 1.13.1-precise-amd64
[LOG] 24.47671 INFO juju environs: filtering tools by series: precise
[LOG] 24.47672 INFO juju environs: filtering tools by version: 1.13.1
[LOG] 24.47674 INFO juju environs/dummy: would pick tools from 1.13.1-precise-amd64
[LOG] 24.49375 INFO juju state: opening state; mongo addresses: ["localhost:34764"]; entity ""
[LOG] 24.51966 INFO juju state: connection established
[LOG] 24.55403 INFO juju state: initializing environment
[LOG] 24.58276 INFO juju state/api: listening on "127.0.0.1:41361"
[LOG] 24.60630 INFO juju state: opening state; mongo addresses: ["localhost:34764"]; entity ""
[LOG] 24.61298 INFO juju state: connection established
[LOG] 24.61356 INFO juju juju: authorization error while connecting to state server; retrying
[LOG] 24.61361 INFO juju state: opening state; mongo addresses: ["localhost:34764"]; entity ""
[LOG] 24.61575 INFO juju state: connection established
[LOG] 24.66685 INFO juju state/api: dialing "wss://127.0.0.1:41361/"
[LOG] 24.67382 INFO juju state/api: connection established
[LOG] 24.67393 DEBUG juju rpc/jsoncodec: <- {"RequestId":1,"Type":"Admin","Request":"Login","Params":{"AuthTag":"user-admin","Password":"dummy-secret","Nonce":""}}
[LOG] 24.67427 DEBUG juju rpc/jsoncodec: -> {"RequestId":1,"Response":{}}

test 0: error state unforced upgrade (ignored until started state)

step 0
uniter_test.startupError{badHook:"start"}
uniter_test.createCharm{revision:0, badHooks:[]string{"start"}, customize:(func(*gocheck.C, *uniter_test.context, string))(nil)}
uniter_test.addCharm{dir:(*charm.Dir)(0xc200565c00), curl:(*charm.URL)(0xc200bd9d20)}
[LOG] 24.69527 DEBUG juju rpc/jsoncodec: <- {"RequestId":2,"Type":"Pinger","Request":"Ping","Params":{}}
[LOG] 24.72835 DEBUG juju rpc/jsoncodec: -> {"RequestId":2,"Response":{}}
uniter_test.serveCharm{}
uniter_test.createUniter{}
uniter_test.createServiceAndUnit{}
uniter_test.startUniter{}
uniter_test.waitAddresses{}
[LOG] 24.81783 INFO juju worker/uniter: unit "u/0" started
[LOG] 24.83745 INFO juju worker/uniter: ModeInit starting
[LOG] 24.83749 INFO juju worker/uniter: updating unit addresses
[LOG] 24.83839 DEBUG juju worker/uniter/filter: charm check skipped, not yet installed.
[LOG] 24.84113 DEBUG juju worker/uniter/filter: got unit change
[LOG] 24.84205 DEBUG juju worker/uniter/filter: got service change
[LOG] 24.84274 DEBUG juju worker/uniter/filter: charm check skipped, not yet installed.
[LOG] 24.84276 DEBUG juju worker/uniter/filter: got relations change
[LOG] 24.85048 INFO juju reconciling relation state
[LOG] 24.85069 DEBUG juju worker/uniter: ModeInit exiting
[LOG] 24.85077 INFO juju worker/uniter: ModeContinue starting
[LOG] 24.85079 INFO juju loading uniter state
[LOG] 24.85084 INFO juju worker/uniter: charm is not deployed
[LOG] 24.85087 DEBUG juju worker/uniter: ModeContinue exiting
[LOG] 24.85089 INFO juju worker/uniter: ModeInstalling cs:series/wordpress-0 starting
[LOG] 24.85092 INFO juju worker/uniter: fetching charm "cs:series/wordpress-0"
[LOG] 24.85181 INFO juju worker/uniter/charm: downloading cs:series/wordpress-0 from http://localhost:40085/charms/wordpress/0
[LOG] 24.85311 INFO juju worker/uniter/charm: download complete
[LOG] 24.85326 INFO juju worker/uniter/charm: download verified
[LOG] 24.90223 DEBUG juju worker/uniter/filter: changing charm to "cs:series/wordpress-0"
[LOG] 24.90858 DEBUG juju worker/uniter/filter: no new charm event
[LOG] 24.90904 INFO juju worker/uniter: deploying charm "cs:series/wordpress-0"
[LOG] 24.91197 INFO juju worker/uniter/charm: preparing new charm deployment
[LOG] 24.92532 DEBUG juju worker/uniter/filter: got config change
[LOG] 24.92543 DEBUG juju worker/uniter/filter: preparing new config event
[LOG] 24.92551 DEBUG juju worker/uniter/filter: got relations change
[LOG] 25.07774 INFO juju worker/uniter/charm: deploying charm
[LOG] 25.08327 INFO juju worker/uniter/charm: charm deployment succeeded
[LOG] 25.08639 INFO juju worker/uniter: charm "cs:series/wordpress-0" is deployed
[LOG] 25.08876 DEBUG juju worker/uniter: ModeInstalling cs:series/wordpress-0 exiting
[LOG] 25.08884 INFO juju worker/uniter: ModeContinue starting
[LOG] 25.08887 INFO juju worker/uniter: found queued "install" hook
[LOG] 25.09379 INFO juju worker/uniter: running "install" hook
[LOG] 25.22241 INFO juju worker/uniter/jujuc: running hook tool "juju-log" ["4342b813-a75c-491b-82ac-81d4ec09d4d0" "install"]
[LOG] 25.22267 DEBUG juju worker/uniter/jujuc: hook context id "u/0:install:7746258953770796086"; dir "/tmp/gocheck-6175742077372812453/0/agents/unit-u-0/charm"
[LOG] 25.22287 INFO juju u/0: 4342b813-a75c-491b-82ac-81d4ec09d4d0 install
[LOG] 25.23322 INFO juju worker/uniter: ran "install" hook
[LOG] 25.23329 INFO juju worker/uniter: committing "install" hook
[LOG] 25.26803 INFO juju worker/uniter: committed "install" hook
[LOG] 25.26853 DEBUG juju worker/uniter: ModeContinue exiting
[LOG] 25.26856 INFO juju worker/uniter: ModeContinue starting
[LOG] 25.26857 INFO juju worker/uniter: continuing after "install" hook
[LOG] 25.26858 DEBUG juju worker/uniter: ModeContinue exiting
[LOG] 25.26859 INFO juju worker/uniter: ModeConfigChanged starting
[LOG] 25.30042 DEBUG juju worker/uniter/filter: discarded config event
[LOG] 25.30548 INFO juju worker/uniter: running "config-changed" hook
[LOG] 25.42688 INFO juju worker/uniter/jujuc: running hook tool "juju-log" ["4342b813-a75c-491b-82ac-81d4ec09d4d0" "config-changed"]
[LOG] 25.42705 DEBUG juju worker/uniter/jujuc: hook context id "u/0:config-changed:7379696677785732984"; dir "/tmp/gocheck-6175742077372812453/0/agents/unit-u-0/charm"
[LOG] 25.42713 INFO juju u/0: 4342b813-a75c-491b-82ac-81d4ec09d4d0 config-changed
[LOG] 25.43650 INFO juju worker/uniter: ran "config-changed" hook
[LOG] 25.43679 INFO juju worker/uniter: committing "config-changed" hook
[LOG] 25.48385 INFO juju worker/uniter: committed "config-changed" hook
[LOG] 25.48540 DEBUG juju worker/uniter: ModeConfigChanged exiting
[LOG] 25.48547 INFO juju worker/uniter: ModeContinue starting
[LOG] 25.48560 INFO juju worker/uniter: continuing after "config-changed" hook
[LOG] 25.48564 DEBUG juju worker/uniter: ModeContinue exiting
[LOG] 25.48567 INFO juju worker/uniter: ModeStarting starting
[LOG] 25.49409 INFO juju worker/uniter: running "start" hook
[LOG] 25.62381 INFO juju worker/uniter/jujuc: running hook tool "juju-log" ["4342b813-a75c-491b-82ac-81d4ec09d4d0" "fail-start"]
[LOG] 25.62385 DEBUG juju worker/uniter/jujuc: hook context id "u/0:start:540830960343391975"; dir "/tmp/gocheck-6175742077372812453/0/agents/unit-u-0/charm"
[LOG] 25.62388 INFO juju u/0: 4342b813-a75c-491b-82ac-81d4ec09d4d0 fail-start
[LOG] 25.62889 ERROR juju worker/uniter: hook failed: exit status 1
[LOG] 25.63038 DEBUG juju worker/uniter: ModeStarting exiting
[LOG] 25.63056 INFO juju worker/uniter: ModeHookError starting
[LOG] 25.65192 DEBUG juju worker/uniter/filter: want resolved event
[LOG] 25.65199 DEBUG juju worker/uniter/filter: want forced upgrade true
[LOG] 25.65202 DEBUG juju worker/uniter/filter: no new charm event
[LOG] 29.63388 DEBUG juju worker/uniter/filter: got unit change
uniter_test.go:684:
    s.runUniterTests(c, errorUpgradeTests)
uniter_test.go:1092:
    c.Fatalf("timed out waiting for unit addresses")
... Error: timed out waiting for unit addresses

[LOG] 34.85830 DEBUG juju worker/uniter: ModeHookError exiting
[LOG] 34.85832 INFO juju worker/uniter: unit "u/0" shutting down: tomb: dying
[LOG] 34.85850 ERROR juju worker/uniter/filter: tomb: dying
[LOG] 34.85975 INFO juju environs/dummy: reset environment
[LOG] 34.85999 DEBUG juju rpc/jsoncodec: <- error: read tcp 127.0.0.1:34080: use of closed network connection (closing true)
[LOG] 34.91618 INFO juju environs/testing: uploading FAKE tools 1.13.1-precise-amd64
[LOG] 34.91627 INFO juju environs: reading tools with major version 1
[LOG] 34.91628 DEBUG juju.agent.tools reading v1.* tools
[LOG] 34.91629 INFO juju environs: falling back to public bucket
[LOG] 34.91630 DEBUG juju.agent.tools reading v1.* tools
[LOG] 34.91634 DEBUG juju.agent.tools found 1.13.1-precise-amd64
[LOG] 34.91638 INFO juju environs: filtering tools by series: precise
[LOG] 34.91640 INFO juju environs: filtering tools by version: 1.13.1
[LOG] 34.91641 INFO juju environs/dummy: would pick tools from 1.13.1-precise-amd64
[LOG] 34.93366 INFO juju state: opening state; mongo addresses: ["localhost:34764"]; entity ""
[LOG] 34.95512 INFO juju state: connection established
[LOG] 34.99433 INFO juju state: initializing environment
[LOG] 35.01493 INFO juju state/api: listening on "127.0.0.1:38094"
[LOG] 35.04384 INFO juju state: opening state; mongo addresses: ["localhost:34764"]; entity ""
[LOG] 35.05111 INFO juju state: connection established
[LOG] 35.05250 INFO juju juju: authorization error while connecting to state server; retrying
[LOG] 35.05262 INFO juju state: opening state; mongo addresses: ["localhost:34764"]; entity ""
[LOG] 35.05996 INFO juju state: connection established
[LOG] 35.10325 INFO juju state/api: dialing "wss://127.0.0.1:38094/"
[LOG] 35.10625 INFO juju state/api: connection established
[LOG] 35.10637 DEBUG juju rpc/jsoncodec: <- {"RequestId":1,"Type":"Admin","Request":"Login","Params":{"AuthTag":"user-admin","Password":"dummy-secret","Nonce":""}}
[LOG] 35.10706 DEBUG juju rpc/jsoncodec: -> {"RequestId":1,"Response":{}}
[LOG] 35.11409 INFO juju environs/dummy: reset environment
[LOG] 35.11421 DEBUG juju rpc/jsoncodec: <- error: read tcp 127.0.0.1:56597: use of closed network connection (closing true)

----------------------------------------------------------------------
FAIL: uniter_test.go:466: UniterSuite.TestUniterHookSynchronisation

[LOG] 35.20085 INFO juju environs/testing: uploading FAKE tools 1.13.1-precise-amd64
[LOG] 35.20091 INFO juju environs: reading tools with major version 1
[LOG] 35.20096 DEBUG juju.agent.tools reading v1.* tools
[LOG] 35.20097 INFO juju environs: falling back to public bucket
[LOG] 35.20098 DEBUG juju.agent.tools reading v1.* tools
[LOG] 35.20101 DEBUG juju.agent.tools found 1.13.1-precise-amd64
[LOG] 35.20102 INFO juju environs: filtering tools by series: precise
[LOG] 35.20104 INFO juju environs: filtering tools by version: 1.13.1
[LOG] 35.20106 INFO juju environs/dummy: would pick tools from 1.13.1-precise-amd64
[LOG] 35.22482 INFO juju state: opening state; mongo addresses: ["localhost:34764"]; entity ""
[LOG] 35.22859 INFO juju state: connection established
[LOG] 35.27304 INFO juju state: initializing environment
[LOG] 35.29966 INFO juju state/api: listening on "127.0.0.1:50710"
[LOG] 35.31662 INFO juju state: opening state; mongo addresses: ["localhost:34764"]; entity ""
[LOG] 35.32022 INFO juju state: connection established
[LOG] 35.33843 INFO juju juju: authorization error while connecting to state server; retrying
[LOG] 35.33851 INFO juju state: opening state; mongo addresses: ["localhost:34764"]; entity ""
[LOG] 35.34518 INFO juju state: connection established
[LOG] 35.37948 INFO juju state/api: dialing "wss://127.0.0.1:50710/"
[LOG] 35.38319 INFO juju state/api: connection established
[LOG] 35.38337 DEBUG juju rpc/jsoncodec: <- {"RequestId":1,"Type":"Admin","Request":"Login","Params":{"AuthTag":"user-admin","Password":"dummy-secret","Nonce":""}}
[LOG] 35.38387 DEBUG juju rpc/jsoncodec: -> {"RequestId":1,"Response":{}}

test 0: verify config change hook not run while lock held

[LOG] 35.38440 DEBUG juju rpc/jsoncodec: <- {"RequestId":2,"Type":"Pinger","Request":"Ping","Params":{}}
[LOG] 35.38450 DEBUG juju rpc/jsoncodec: -> {"RequestId":2,"Response":{}}
step 0
uniter_test.quickStart{}
uniter_test.createCharm{revision:0, badHooks:[]string(nil), customize:(func(*gocheck.C, *uniter_test.context, string))(nil)}
uniter_test.addCharm{dir:(*charm.Dir)(0xc200bcf630), curl:(*charm.URL)(0xc2009e1e60)}
uniter_test.serveCharm{}
uniter_test.createUniter{}
uniter_test.createServiceAndUnit{}
uniter_test.startUniter{}
uniter_test.waitAddresses{}
[LOG] 35.50577 INFO juju worker/uniter: unit "u/0" started
[LOG] 35.51632 INFO juju worker/uniter: ModeInit starting
[LOG] 35.51634 INFO juju worker/uniter: updating unit addresses
[LOG] 35.51663 DEBUG juju worker/uniter/filter: charm check skipped, not yet installed.
[LOG] 35.51794 DEBUG juju worker/uniter/filter: got unit change
[LOG] 35.51895 DEBUG juju worker/uniter/filter: got relations change
[LOG] 35.51897 DEBUG juju worker/uniter/filter: got service change
[LOG] 35.51974 DEBUG juju worker/uniter/filter: charm check skipped, not yet installed.
[LOG] 35.52467 INFO juju reconciling relation state
[LOG] 35.52476 DEBUG juju worker/uniter: ModeInit exiting
[LOG] 35.52478 INFO juju worker/uniter: ModeContinue starting
[LOG] 35.52478 INFO juju loading uniter state
[LOG] 35.52481 INFO juju worker/uniter: charm is not deployed
[LOG] 35.52482 DEBUG juju worker/uniter: ModeContinue exiting
[LOG] 35.52483 INFO juju worker/uniter: ModeInstalling cs:series/wordpress-0 starting
[LOG] 35.52484 INFO juju worker/uniter: fetching charm "cs:series/wordpress-0"
[LOG] 35.52534 INFO juju worker/uniter/charm: downloading cs:series/wordpress-0 from http://localhost:40085/charms/wordpress/0
[LOG] 35.52585 INFO juju worker/uniter/charm: download complete
[LOG] 35.52596 INFO juju worker/uniter/charm: download verified
[LOG] 35.57302 DEBUG juju worker/uniter/filter: changing charm to "cs:series/wordpress-0"
[LOG] 35.58771 DEBUG juju worker/uniter/filter: no new charm event
[LOG] 35.58859 INFO juju worker/uniter: deploying charm "cs:series/wordpress-0"
[LOG] 35.58979 DEBUG juju worker/uniter/filter: got config change
[LOG] 35.58981 DEBUG juju worker/uniter/filter: preparing new config event
[LOG] 35.58988 DEBUG juju worker/uniter/filter: got relations change
[LOG] 35.59165 INFO juju worker/uniter/charm: preparing new charm deployment
[LOG] 35.76107 INFO juju worker/uniter/charm: deploying charm
[LOG] 35.76369 INFO juju worker/uniter/charm: charm deployment succeeded
[LOG] 35.76715 INFO juju worker/uniter: charm "cs:series/wordpress-0" is deployed
[LOG] 35.77008 DEBUG juju worker/uniter: ModeInstalling cs:series/wordpress-0 exiting
[LOG] 35.77021 INFO juju worker/uniter: ModeContinue starting
[LOG] 35.77025 INFO juju worker/uniter: found queued "install" hook
[LOG] 35.77880 INFO juju worker/uniter: running "install" hook
[LOG] 35.89049 INFO juju worker/uniter/jujuc: running hook tool "juju-log" ["a330a262-8023-40b5-8f25-4a7756a1f27d" "install"]
[LOG] 35.89066 DEBUG juju worker/uniter/jujuc: hook context id "u/0:install:17560332210786587"; dir "/tmp/gocheck-6175742077372812453/0/agents/unit-u-0/charm"
[LOG] 35.89095 INFO juju u/0: a330a262-8023-40b5-8f25-4a7756a1f27d install
[LOG] 35.90659 INFO juju worker/uniter: ran "install" hook
[LOG] 35.90675 INFO juju worker/uniter: committing "install" hook
[LOG] 35.94317 INFO juju worker/uniter: committed "install" hook
[LOG] 35.94364 DEBUG juju worker/uniter: ModeContinue exiting
[LOG] 35.94366 INFO juju worker/uniter: ModeContinue starting
[LOG] 35.94368 INFO juju worker/uniter: continuing after "install" hook
[LOG] 35.94368 DEBUG juju worker/uniter: ModeContinue exiting
[LOG] 35.94369 INFO juju worker/uniter: ModeConfigChanged starting
[LOG] 35.97283 DEBUG juju worker/uniter/filter: discarded config event
[LOG] 35.97806 INFO juju worker/uniter: running "config-changed" hook
[LOG] 36.09875 INFO juju worker/uniter/jujuc: running hook tool "juju-log" ["a330a262-8023-40b5-8f25-4a7756a1f27d" "config-changed"]
[LOG] 36.09884 DEBUG juju worker/uniter/jujuc: hook context id "u/0:config-changed:1246144891468761826"; dir "/tmp/gocheck-6175742077372812453/0/agents/unit-u-0/charm"
[LOG] 36.09903 INFO juju u/0: a330a262-8023-40b5-8f25-4a7756a1f27d config-changed
[LOG] 36.10677 INFO juju worker/uniter: ran "config-changed" hook
[LOG] 36.10683 INFO juju worker/uniter: committing "config-changed" hook
[LOG] 36.14132 INFO juju worker/uniter: committed "config-changed" hook
[LOG] 36.14221 DEBUG juju worker/uniter: ModeConfigChanged exiting
[LOG] 36.14226 INFO juju worker/uniter: ModeContinue starting
[LOG] 36.14231 INFO juju worker/uniter: continuing after "config-changed" hook
[LOG] 36.14233 DEBUG juju worker/uniter: ModeContinue exiting
[LOG] 36.14237 INFO juju worker/uniter: ModeStarting starting
[LOG] 36.15445 INFO juju worker/uniter: running "start" hook
[LOG] 36.27064 INFO juju worker/uniter/jujuc: running hook tool "juju-log" ["a330a262-8023-40b5-8f25-4a7756a1f27d" "start"]
[LOG] 36.27075 DEBUG juju worker/uniter/jujuc: hook context id "u/0:start:755775599385557633"; dir "/tmp/gocheck-6175742077372812453/0/agents/unit-u-0/charm"
[LOG] 36.27089 INFO juju u/0: a330a262-8023-40b5-8f25-4a7756a1f27d start
[LOG] 36.28653 INFO juju worker/uniter: ran "start" hook
[LOG] 36.28659 INFO juju worker/uniter: committing "start" hook
[LOG] 36.32254 INFO juju worker/uniter: committed "start" hook
[LOG] 36.32304 DEBUG juju worker/uniter: ModeStarting exiting
[LOG] 36.32307 INFO juju worker/uniter: ModeContinue starting
[LOG] 36.32308 INFO juju worker/uniter: continuing after "start" hook
[LOG] 36.32309 DEBUG juju worker/uniter: ModeContinue exiting
[LOG] 36.32310 INFO juju worker/uniter: ModeAbide starting
[LOG] 36.35067 DEBUG juju worker/uniter/filter: want forced upgrade false
[LOG] 36.35069 DEBUG juju worker/uniter/filter: no new charm event
[LOG] 40.36220 DEBUG juju worker/uniter/filter: got unit change
uniter_test.go:467:
    s.runUniterTests(c, hookSynchronizationTests)
uniter_test.go:1092:
    c.Fatalf("timed out waiting for unit addresses")
... Error: timed out waiting for unit addresses

[LOG] 45.53012 DEBUG juju worker/uniter: ModeAbide exiting
[LOG] 45.53015 INFO juju worker/uniter: unit "u/0" shutting down: tomb: dying
[LOG] 45.53028 ERROR juju worker/uniter/filter: tomb: dying
[LOG] 45.53108 INFO juju environs/dummy: reset environment
[LOG] 45.53132 DEBUG juju rpc/jsoncodec: <- error: read tcp 127.0.0.1:45253: use of closed network connection (closing true)
[LOG] 45.61451 INFO juju environs/testing: uploading FAKE tools 1.13.1-precise-amd64
[LOG] 45.61460 INFO juju environs: reading tools with major version 1
[LOG] 45.61462 DEBUG juju.agent.tools reading v1.* tools
[LOG] 45.61463 INFO juju environs: falling back to public bucket
[LOG] 45.61463 DEBUG juju.agent.tools reading v1.* tools
[LOG] 45.61467 DEBUG juju.agent.tools found 1.13.1-precise-amd64
[LOG] 45.61469 INFO juju environs: filtering tools by series: precise
[LOG] 45.61470 INFO juju environs: filtering tools by version: 1.13.1
[LOG] 45.61472 INFO juju environs/dummy: would pick tools from 1.13.1-precise-amd64
[LOG] 45.63176 INFO juju state: opening state; mongo addresses: ["localhost:34764"]; entity ""
[LOG] 45.65594 INFO juju state: connection established
[LOG] 45.67862 INFO juju state: initializing environment
[LOG] 45.70059 INFO juju state/api: listening on "127.0.0.1:57827"
[LOG] 45.72129 INFO juju state: opening state; mongo addresses: ["localhost:34764"]; entity ""
[LOG] 45.72658 INFO juju state: connection established
[LOG] 45.73984 INFO juju juju: authorization error while connecting to state server; retrying
[LOG] 45.73996 INFO juju state: opening state; mongo addresses: ["localhost:34764"]; entity ""
[LOG] 45.74719 INFO juju state: connection established
[LOG] 45.78441 INFO juju state/api: dialing "wss://127.0.0.1:57827/"
[LOG] 45.78734 INFO juju state/api: connection established
[LOG] 45.78750 DEBUG juju rpc/jsoncodec: <- {"RequestId":1,"Type":"Admin","Request":"Login","Params":{"AuthTag":"user-admin","Password":"dummy-secret","Nonce":""}}
[LOG] 45.78795 DEBUG juju rpc/jsoncodec: -> {"RequestId":1,"Response":{}}
[LOG] 45.81309 DEBUG juju rpc/jsoncodec: <- {"RequestId":2,"Type":"Pinger","Request":"Ping","Params":{}}
[LOG] 45.81341 DEBUG juju rpc/jsoncodec: -> {"RequestId":2,"Response":{}}
[LOG] 45.81393 INFO juju environs/dummy: reset environment
[LOG] 45.81422 DEBUG juju rpc/jsoncodec: <- error: read tcp 127.0.0.1:42346: use of closed network connection (closing true)

----------------------------------------------------------------------
FAIL: uniter_test.go:295: UniterSuite.TestUniterInstallHook

[LOG] 45.88356 INFO juju environs/testing: uploading FAKE tools 1.13.1-precise-amd64
[LOG] 45.88362 INFO juju environs: reading tools with major version 1
[LOG] 45.88364 DEBUG juju.agent.tools reading v1.* tools
[LOG] 45.88365 INFO juju environs: falling back to public bucket
[LOG] 45.88366 DEBUG juju.agent.tools reading v1.* tools
[LOG] 45.88369 DEBUG juju.agent.tools found 1.13.1-precise-amd64
[LOG] 45.88371 INFO juju environs: filtering tools by series: precise
[LOG] 45.88374 INFO juju environs: filtering tools by version: 1.13.1
[LOG] 45.88376 INFO juju environs/dummy: would pick tools from 1.13.1-precise-amd64
[LOG] 45.90076 INFO juju state: opening state; mongo addresses: ["localhost:34764"]; entity ""
[LOG] 45.91862 INFO juju state: connection established
[LOG] 45.95069 INFO juju state: initializing environment
[LOG] 45.97504 INFO juju state/api: listening on "127.0.0.1:36971"
[LOG] 45.99939 INFO juju state: opening state; mongo addresses: ["localhost:34764"]; entity ""
[LOG] 46.00622 INFO juju state: connection established
[LOG] 46.00769 INFO juju juju: authorization error while connecting to state server; retrying
[LOG] 46.00773 INFO juju state: opening state; mongo addresses: ["localhost:34764"]; entity ""
[LOG] 46.01402 INFO juju state: connection established
[LOG] 46.06918 INFO juju state/api: dialing "wss://127.0.0.1:36971/"
[LOG] 46.07195 INFO juju state/api: connection established
[LOG] 46.07217 DEBUG juju rpc/jsoncodec: <- {"RequestId":1,"Type":"Admin","Request":"Login","Params":{"AuthTag":"user-admin","Password":"dummy-secret","Nonce":""}}
[LOG] 46.07261 DEBUG juju rpc/jsoncodec: -> {"RequestId":1,"Response":{}}

test 0: install hook fail and resolve

[LOG] 46.07332 DEBUG juju rpc/jsoncodec: <- {"RequestId":2,"Type":"Pinger","Request":"Ping","Params":{}}
[LOG] 46.07346 DEBUG juju rpc/jsoncodec: -> {"RequestId":2,"Response":{}}
step 0
uniter_test.startupError{badHook:"install"}
uniter_test.createCharm{revision:0, badHooks:[]string{"install"}, customize:(func(*gocheck.C, *uniter_test.context, string))(nil)}
uniter_test.addCharm{dir:(*charm.Dir)(0xc2002ea930), curl:(*charm.URL)(0xc200bd9730)}
uniter_test.serveCharm{}
uniter_test.createUniter{}
uniter_test.createServiceAndUnit{}
uniter_test.startUniter{}
uniter_test.waitAddresses{}
[LOG] 46.22647 INFO juju worker/uniter: unit "u/0" started
[LOG] 46.24029 INFO juju worker/uniter: ModeInit starting
[LOG] 46.24033 INFO juju worker/uniter: updating unit addresses
[LOG] 46.24129 DEBUG juju worker/uniter/filter: charm check skipped, not yet installed.
[LOG] 46.24542 DEBUG juju worker/uniter/filter: got unit change
[LOG] 46.24670 DEBUG juju worker/uniter/filter: got relations change
[LOG] 46.24674 DEBUG juju worker/uniter/filter: got service change
[LOG] 46.24873 DEBUG juju worker/uniter/filter: charm check skipped, not yet installed.
[LOG] 46.25597 INFO juju reconciling relation state
[LOG] 46.25615 DEBUG juju worker/uniter: ModeInit exiting
[LOG] 46.25619 INFO juju worker/uniter: ModeContinue starting
[LOG] 46.25621 INFO juju loading uniter state
[LOG] 46.25627 INFO juju worker/uniter: charm is not deployed
[LOG] 46.25631 DEBUG juju worker/uniter: ModeContinue exiting
[LOG] 46.25634 INFO juju worker/uniter: ModeInstalling cs:series/wordpress-0 starting
[LOG] 46.25641 INFO juju worker/uniter: fetching charm "cs:series/wordpress-0"
[LOG] 46.25790 INFO juju worker/uniter/charm: downloading cs:series/wordpress-0 from http://localhost:40085/charms/wordpress/0
[LOG] 46.25915 INFO juju worker/uniter/charm: download complete
[LOG] 46.25944 INFO juju worker/uniter/charm: download verified
[LOG] 46.32927 DEBUG juju worker/uniter/filter: changing charm to "cs:series/wordpress-0"
[LOG] 46.34120 DEBUG juju worker/uniter/filter: no new charm event
[LOG] 46.34173 INFO juju worker/uniter: deploying charm "cs:series/wordpress-0"
[LOG] 46.34223 DEBUG juju worker/uniter/filter: got config change
[LOG] 46.34224 DEBUG juju worker/uniter/filter: preparing new config event
[LOG] 46.34225 DEBUG juju worker/uniter/filter: got relations change
[LOG] 46.34461 INFO juju worker/uniter/charm: preparing new charm deployment
[LOG] 46.52802 INFO juju worker/uniter/charm: deploying charm
[LOG] 46.53541 INFO juju worker/uniter/charm: charm deployment succeeded
[LOG] 46.53983 INFO juju worker/uniter: charm "cs:series/wordpress-0" is deployed
[LOG] 46.54216 DEBUG juju worker/uniter: ModeInstalling cs:series/wordpress-0 exiting
[LOG] 46.54218 INFO juju worker/uniter: ModeContinue starting
[LOG] 46.54221 INFO juju worker/uniter: found queued "install" hook
[LOG] 46.54612 INFO juju worker/uniter: running "install" hook
[LOG] 46.66979 INFO juju worker/uniter/jujuc: running hook tool "juju-log" ["909a57fb-3c6d-4ca7-83f6-5e50189381a9" "fail-install"]
[LOG] 46.66989 DEBUG juju worker/uniter/jujuc: hook context id "u/0:install:434020472318578047"; dir "/tmp/gocheck-6175742077372812453/0/agents/unit-u-0/charm"
[LOG] 46.66997 INFO juju u/0: 909a57fb-3c6d-4ca7-83f6-5e50189381a9 fail-install
[LOG] 46.67589 ERROR juju worker/uniter: hook failed: exit status 1
[LOG] 46.67701 DEBUG juju worker/uniter: ModeContinue exiting
[LOG] 46.67706 INFO juju worker/uniter: ModeContinue starting
[LOG] 46.67710 INFO juju worker/uniter: awaiting error resolution for "install" hook
[LOG] 46.67712 DEBUG juju worker/uniter: ModeContinue exiting
[LOG] 46.67714 INFO juju worker/uniter: ModeHookError starting
[LOG] 46.69619 DEBUG juju worker/uniter/filter: want resolved event
[LOG] 46.69632 DEBUG juju worker/uniter/filter: want forced upgrade true
[LOG] 46.69641 DEBUG juju worker/uniter/filter: no new charm event
[LOG] 51.03304 DEBUG juju worker/uniter/filter: got unit change
uniter_test.go:296:
    s.runUniterTests(c, installHookTests)
uniter_test.go:1092:
    c.Fatalf("timed out waiting for unit addresses")
... Error: timed out waiting for unit addresses

[LOG] 56.26666 DEBUG juju worker/uniter: ModeHookError exiting
[LOG] 56.26668 INFO juju worker/uniter: unit "u/0" shutting down: tomb: dying
[LOG] 56.26686 ERROR juju worker/uniter/filter: tomb: dying
[LOG] 56.26829 INFO juju environs/dummy: reset environment
[LOG] 56.26852 DEBUG juju rpc/jsoncodec: <- error: read tcp 127.0.0.1:38000: use of closed network connection (closing true)
[LOG] 56.32367 INFO juju environs/testing: uploading FAKE tools 1.13.1-precise-amd64
[LOG] 56.32384 INFO juju environs: reading tools with major version 1
[LOG] 56.32385 DEBUG juju.agent.tools reading v1.* tools
[LOG] 56.32386 INFO juju environs: falling back to public bucket
[LOG] 56.32387 DEBUG juju.agent.tools reading v1.* tools
[LOG] 56.32390 DEBUG juju.agent.tools found 1.13.1-precise-amd64
[LOG] 56.32391 INFO juju environs: filtering tools by series: precise
[LOG] 56.32393 INFO juju environs: filtering tools by version: 1.13.1
[LOG] 56.32394 INFO juju environs/dummy: would pick tools from 1.13.1-precise-amd64
[LOG] 56.34114 INFO juju state: opening state; mongo addresses: ["localhost:34764"]; entity ""
[LOG] 56.36171 INFO juju state: connection established
[LOG] 56.39117 INFO juju state: initializing environment
[LOG] 56.41180 INFO juju state/api: listening on "127.0.0.1:40709"
[LOG] 56.42962 INFO juju state: opening state; mongo addresses: ["localhost:34764"]; entity ""
[LOG] 56.44667 INFO juju state: connection established
[LOG] 56.44821 INFO juju juju: authorization error while connecting to state server; retrying
[LOG] 56.44832 INFO juju state: opening state; mongo addresses: ["localhost:34764"]; entity ""
[LOG] 56.46918 INFO juju state: connection established
[LOG] 56.50355 INFO juju state/api: dialing "wss://127.0.0.1:40709/"
[LOG] 56.50647 INFO juju state/api: connection established
[LOG] 56.50659 DEBUG juju rpc/jsoncodec: <- {"RequestId":1,"Type":"Admin","Request":"Login","Params":{"AuthTag":"user-admin","Password":"dummy-secret","Nonce":""}}
[LOG] 56.50693 DEBUG juju rpc/jsoncodec: -> {"RequestId":1,"Response":{}}
[LOG] 56.51372 INFO juju environs/dummy: reset environment
[LOG] 56.51387 DEBUG juju rpc/jsoncodec: <- error: read tcp 127.0.0.1:52538: use of closed network connection (closing true)

----------------------------------------------------------------------
FAIL: uniter_test.go:361: UniterSuite.TestUniterMultipleErrors

[LOG] 56.58223 INFO juju environs/testing: uploading FAKE tools 1.13.1-precise-amd64
[LOG] 56.58229 INFO juju environs: reading tools with major version 1
[LOG] 56.58231 DEBUG juju.agent.tools reading v1.* tools
[LOG] 56.58232 INFO juju environs: falling back to public bucket
[LOG] 56.58233 DEBUG juju.agent.tools reading v1.* tools
[LOG] 56.58236 DEBUG juju.agent.tools found 1.13.1-precise-amd64
[LOG] 56.58237 INFO juju environs: filtering tools by series: precise
[LOG] 56.58239 INFO juju environs: filtering tools by version: 1.13.1
[LOG] 56.58241 INFO juju environs/dummy: would pick tools from 1.13.1-precise-amd64
[LOG] 56.60530 INFO juju state: opening state; mongo addresses: ["localhost:34764"]; entity ""
[LOG] 56.61170 INFO juju state: connection established
[LOG] 56.63621 INFO juju state: initializing environment
[LOG] 56.65965 INFO juju state/api: listening on "127.0.0.1:44867"
[LOG] 56.67656 INFO juju state: opening state; mongo addresses: ["localhost:34764"]; entity ""
[LOG] 56.68022 INFO juju state: connection established
[LOG] 56.69787 INFO juju juju: authorization error while connecting to state server; retrying
[LOG] 56.69791 INFO juju state: opening state; mongo addresses: ["localhost:34764"]; entity ""
[LOG] 56.70437 INFO juju state: connection established
[LOG] 56.74213 INFO juju state/api: dialing "wss://127.0.0.1:44867/"
[LOG] 56.74508 INFO juju state/api: connection established
[LOG] 56.74518 DEBUG juju rpc/jsoncodec: <- {"RequestId":1,"Type":"Admin","Request":"Login","Params":{"AuthTag":"user-admin","Password":"dummy-secret","Nonce":""}}
[LOG] 56.74550 DEBUG juju rpc/jsoncodec: -> {"RequestId":1,"Response":{}}

test 0: start hook fail and resolve

step 0
uniter_test.startupError{badHook:"start"}
uniter_test.createCharm{revision:0, badHooks:[]string{"start"}, customize:(func(*gocheck.C, *uniter_test.context, string))(nil)}
uniter_test.addCharm{dir:(*charm.Dir)(0xc200b8b960), curl:(*charm.URL)(0xc2007bc230)}
[LOG] 56.77453 DEBUG juju rpc/jsoncodec: <- {"RequestId":2,"Type":"Pinger","Request":"Ping","Params":{}}
[LOG] 56.80503 DEBUG juju rpc/jsoncodec: -> {"RequestId":2,"Response":{}}
uniter_test.serveCharm{}
uniter_test.createUniter{}
uniter_test.createServiceAndUnit{}
uniter_test.startUniter{}
uniter_test.waitAddresses{}
[LOG] 56.88482 INFO juju worker/uniter: unit "u/0" started
[LOG] 56.90572 INFO juju worker/uniter: ModeInit starting
[LOG] 56.90577 INFO juju worker/uniter: updating unit addresses
[LOG] 56.90603 DEBUG juju worker/uniter/filter: charm check skipped, not yet installed.
[LOG] 56.90983 DEBUG juju worker/uniter/filter: got unit change
[LOG] 56.91219 DEBUG juju worker/uniter/filter: got relations change
[LOG] 56.91224 DEBUG juju worker/uniter/filter: got service change
[LOG] 56.91408 DEBUG juju worker/uniter/filter: charm check skipped, not yet installed.
[LOG] 56.92079 INFO juju reconciling relation state
[LOG] 56.92091 DEBUG juju worker/uniter: ModeInit exiting
[LOG] 56.92092 INFO juju worker/uniter: ModeContinue starting
[LOG] 56.92095 INFO juju loading uniter state
[LOG] 56.92098 INFO juju worker/uniter: charm is not deployed
[LOG] 56.92099 DEBUG juju worker/uniter: ModeContinue exiting
[LOG] 56.92104 INFO juju worker/uniter: ModeInstalling cs:series/wordpress-0 starting
[LOG] 56.92106 INFO juju worker/uniter: fetching charm "cs:series/wordpress-0"
[LOG] 56.92161 INFO juju worker/uniter/charm: downloading cs:series/wordpress-0 from http://localhost:40085/charms/wordpress/0
[LOG] 56.92231 INFO juju worker/uniter/charm: download complete
[LOG] 56.92244 INFO juju worker/uniter/charm: download verified
[LOG] 56.98025 DEBUG juju worker/uniter/filter: changing charm to "cs:series/wordpress-0"
[LOG] 57.00790 DEBUG juju worker/uniter/filter: no new charm event
[LOG] 57.00884 INFO juju worker/uniter: deploying charm "cs:series/wordpress-0"
[LOG] 57.00989 DEBUG juju worker/uniter/filter: got config change
[LOG] 57.00992 DEBUG juju worker/uniter/filter: preparing new config event
[LOG] 57.01068 DEBUG juju worker/uniter/filter: got relations change
[LOG] 57.01326 INFO juju worker/uniter/charm: preparing new charm deployment
[LOG] 57.18365 INFO juju worker/uniter/charm: deploying charm
[LOG] 57.18840 INFO juju worker/uniter/charm: charm deployment succeeded
[LOG] 57.19307 INFO juju worker/uniter: charm "cs:series/wordpress-0" is deployed
[LOG] 57.19655 DEBUG juju worker/uniter: ModeInstalling cs:series/wordpress-0 exiting
[LOG] 57.19678 INFO juju worker/uniter: ModeContinue starting
[LOG] 57.19686 INFO juju worker/uniter: found queued "install" hook
[LOG] 57.20461 INFO juju worker/uniter: running "install" hook
[LOG] 57.31617 INFO juju worker/uniter/jujuc: running hook tool "juju-log" ["d1bdfe2c-609c-4969-8a00-15e3d86cbe1c" "install"]
[LOG] 57.31652 DEBUG juju worker/uniter/jujuc: hook context id "u/0:install:1936306270525527524"; dir "/tmp/gocheck-6175742077372812453/0/agents/unit-u-0/charm"
[LOG] 57.31699 INFO juju u/0: d1bdfe2c-609c-4969-8a00-15e3d86cbe1c install
[LOG] 57.32575 INFO juju worker/uniter: ran "install" hook
[LOG] 57.32580 INFO juju worker/uniter: committing "install" hook
[LOG] 57.36344 INFO juju worker/uniter: committed "install" hook
[LOG] 57.36393 DEBUG juju worker/uniter: ModeContinue exiting
[LOG] 57.36396 INFO juju worker/uniter: ModeContinue starting
[LOG] 57.36397 INFO juju worker/uniter: continuing after "install" hook
[LOG] 57.36398 DEBUG juju worker/uniter: ModeContinue exiting
[LOG] 57.36399 INFO juju worker/uniter: ModeConfigChanged starting
[LOG] 57.39754 DEBUG juju worker/uniter/filter: discarded config event
[LOG] 57.40115 INFO juju worker/uniter: running "config-changed" hook
[LOG] 57.51008 INFO juju worker/uniter/jujuc: running hook tool "juju-log" ["d1bdfe2c-609c-4969-8a00-15e3d86cbe1c" "config-changed"]
[LOG] 57.51019 DEBUG juju worker/uniter/jujuc: hook context id "u/0:config-changed:7144197542778799667"; dir "/tmp/gocheck-6175742077372812453/0/agents/unit-u-0/charm"
[LOG] 57.51026 INFO juju u/0: d1bdfe2c-609c-4969-8a00-15e3d86cbe1c config-changed
[LOG] 57.51692 INFO juju worker/uniter: ran "config-changed" hook
[LOG] 57.51701 INFO juju worker/uniter: committing "config-changed" hook
[LOG] 57.54888 INFO juju worker/uniter: committed "config-changed" hook
[LOG] 57.55021 DEBUG juju worker/uniter: ModeConfigChanged exiting
[LOG] 57.55029 INFO juju worker/uniter: ModeContinue starting
[LOG] 57.55033 INFO juju worker/uniter: continuing after "config-changed" hook
[LOG] 57.55035 DEBUG juju worker/uniter: ModeContinue exiting
[LOG] 57.55037 INFO juju worker/uniter: ModeStarting starting
[LOG] 57.55761 INFO juju worker/uniter: running "start" hook
[LOG] 57.66911 INFO juju worker/uniter/jujuc: running hook tool "juju-log" ["d1bdfe2c-609c-4969-8a00-15e3d86cbe1c" "fail-start"]
[LOG] 57.66926 DEBUG juju worker/uniter/jujuc: hook context id "u/0:start:3098756002400989177"; dir "/tmp/gocheck-6175742077372812453/0/agents/unit-u-0/charm"
[LOG] 57.66936 INFO juju u/0: d1bdfe2c-609c-4969-8a00-15e3d86cbe1c fail-start
[LOG] 57.67394 ERROR juju worker/uniter: hook failed: exit status 1
[LOG] 57.69235 DEBUG juju worker/uniter: ModeStarting exiting
[LOG] 57.69238 INFO juju worker/uniter: ModeHookError starting
[LOG] 57.69965 DEBUG juju worker/uniter/filter: want resolved event
[LOG] 57.69967 DEBUG juju worker/uniter/filter: want forced upgrade true
[LOG] 57.69968 DEBUG juju worker/uniter/filter: no new charm event
[LOG] 61.72343 DEBUG juju worker/uniter/filter: got unit change
uniter_test.go:362:
    s.runUniterTests(c, startHookTests)
uniter_test.go:1092:
    c.Fatalf("timed out waiting for unit addresses")
... Error: timed out waiting for unit addresses

[LOG] 66.90533 DEBUG juju worker/uniter: ModeHookError exiting
[LOG] 66.90536 INFO juju worker/uniter: unit "u/0" shutting down: tomb: dying
[LOG] 66.90551 ERROR juju worker/uniter/filter: tomb: dying
[LOG] 66.90682 INFO juju environs/dummy: reset environment
[LOG] 66.90707 DEBUG juju rpc/jsoncodec: <- error: read tcp 127.0.0.1:37043: use of closed network connection (closing true)
[LOG] 66.98627 INFO juju environs/testing: uploading FAKE tools 1.13.1-precise-amd64
[LOG] 66.98635 INFO juju environs: reading tools with major version 1
[LOG] 66.98637 DEBUG juju.agent.tools reading v1.* tools
[LOG] 66.98638 INFO juju environs: falling back to public bucket
[LOG] 66.98639 DEBUG juju.agent.tools reading v1.* tools
[LOG] 66.98643 DEBUG juju.agent.tools found 1.13.1-precise-amd64
[LOG] 66.98645 INFO juju environs: filtering tools by series: precise
[LOG] 66.98646 INFO juju environs: filtering tools by version: 1.13.1
[LOG] 66.98648 INFO juju environs/dummy: would pick tools from 1.13.1-precise-amd64
[LOG] 67.00348 INFO juju state: opening state; mongo addresses: ["localhost:34764"]; entity ""
[LOG] 67.01983 INFO juju state: connection established
[LOG] 67.04474 INFO juju state: initializing environment
[LOG] 67.06492 INFO juju state/api: listening on "127.0.0.1:52142"
[LOG] 67.08197 INFO juju state: opening state; mongo addresses: ["localhost:34764"]; entity ""
[LOG] 67.08574 INFO juju state: connection established
[LOG] 67.10355 INFO juju juju: authorization error while connecting to state server; retrying
[LOG] 67.10367 INFO juju state: opening state; mongo addresses: ["localhost:34764"]; entity ""
[LOG] 67.11073 INFO juju state: connection established
[LOG] 67.16068 INFO juju state/api: dialing "wss://127.0.0.1:52142/"
[LOG] 67.16952 INFO juju state/api: connection established
[LOG] 67.16983 DEBUG juju rpc/jsoncodec: <- {"RequestId":1,"Type":"Admin","Request":"Login","Params":{"AuthTag":"user-admin","Password":"dummy-secret","Nonce":""}}
[LOG] 67.17069 DEBUG juju rpc/jsoncodec: -> {"RequestId":1,"Response":{}}
[LOG] 67.18143 INFO juju environs/dummy: reset environment
[LOG] 67.18157 DEBUG juju rpc/jsoncodec: <- error: read tcp 127.0.0.1:51917: use of closed network connection (closing true)

----------------------------------------------------------------------
FAIL: uniter_test.go:871: UniterSuite.TestUniterRelations

[LOG] 67.25678 INFO juju environs/testing: uploading FAKE tools 1.13.1-precise-amd64
[LOG] 67.25687 INFO juju environs: reading tools with major version 1
[LOG] 67.25689 DEBUG juju.agent.tools reading v1.* tools
[LOG] 67.25690 INFO juju environs: falling back to public bucket
[LOG] 67.25692 DEBUG juju.agent.tools reading v1.* tools
[LOG] 67.25701 DEBUG juju.agent.tools found 1.13.1-precise-amd64
[LOG] 67.25703 INFO juju environs: filtering tools by series: precise
[LOG] 67.25705 INFO juju environs: filtering tools by version: 1.13.1
[LOG] 67.25707 INFO juju environs/dummy: would pick tools from 1.13.1-precise-amd64
[LOG] 67.27397 INFO juju state: opening state; mongo addresses: ["localhost:34764"]; entity ""
[LOG] 67.29424 INFO juju state: connection established
[LOG] 67.32635 INFO juju state: initializing environment
[LOG] 67.37394 INFO juju state/api: listening on "127.0.0.1:40173"
[LOG] 67.39260 INFO juju state: opening state; mongo addresses: ["localhost:34764"]; entity ""
[LOG] 67.39524 INFO juju state: connection established
[LOG] 67.40966 INFO juju juju: authorization error while connecting to state server; retrying
[LOG] 67.40971 INFO juju state: opening state; mongo addresses: ["localhost:34764"]; entity ""
[LOG] 67.41237 INFO juju state: connection established
[LOG] 67.46638 INFO juju state/api: dialing "wss://127.0.0.1:40173/"
[LOG] 67.47028 INFO juju state/api: connection established
[LOG] 67.47047 DEBUG juju rpc/jsoncodec: <- {"RequestId":1,"Type":"Admin","Request":"Login","Params":{"AuthTag":"user-admin","Password":"dummy-secret","Nonce":""}}
[LOG] 67.47082 DEBUG juju rpc/jsoncodec: -> {"RequestId":1,"Response":{}}

test 0: simple joined/changed/departed

[LOG] 67.47106 DEBUG juju rpc/jsoncodec: <- {"RequestId":2,"Type":"Pinger","Request":"Ping","Params":{}}
[LOG] 67.47110 DEBUG juju rpc/jsoncodec: -> {"RequestId":2,"Response":{}}
step 0
uniter_test.quickStartRelation{}
uniter_test.quickStart{}
uniter_test.createCharm{revision:0, badHooks:[]string(nil), customize:(func(*gocheck.C, *uniter_test.context, string))(nil)}
uniter_test.addCharm{dir:(*charm.Dir)(0xc2001b7810), curl:(*charm.URL)(0xc2007d30f0)}
uniter_test.serveCharm{}
uniter_test.createUniter{}
uniter_test.createServiceAndUnit{}
uniter_test.startUniter{}
uniter_test.waitAddresses{}
[LOG] 67.61622 INFO juju worker/uniter: unit "u/0" started
[LOG] 67.62943 INFO juju worker/uniter: ModeInit starting
[LOG] 67.62946 INFO juju worker/uniter: updating unit addresses
[LOG] 67.63014 DEBUG juju worker/uniter/filter: charm check skipped, not yet installed.
[LOG] 67.63250 DEBUG juju worker/uniter/filter: got unit change
[LOG] 67.63382 DEBUG juju worker/uniter/filter: got relations change
[LOG] 67.63383 DEBUG juju worker/uniter/filter: got service change
[LOG] 67.63496 DEBUG juju worker/uniter/filter: charm check skipped, not yet installed.
[LOG] 67.64603 INFO juju reconciling relation state
[LOG] 67.64614 DEBUG juju worker/uniter: ModeInit exiting
[LOG] 67.64616 INFO juju worker/uniter: ModeContinue starting
[LOG] 67.64616 INFO juju loading uniter state
[LOG] 67.64619 INFO juju worker/uniter: charm is not deployed
[LOG] 67.64620 DEBUG juju worker/uniter: ModeContinue exiting
[LOG] 67.64625 INFO juju worker/uniter: ModeInstalling cs:series/wordpress-0 starting
[LOG] 67.64626 INFO juju worker/uniter: fetching charm "cs:series/wordpress-0"
[LOG] 67.64676 INFO juju worker/uniter/charm: downloading cs:series/wordpress-0 from http://localhost:40085/charms/wordpress/0
[LOG] 67.64747 INFO juju worker/uniter/charm: download complete
[LOG] 67.64761 INFO juju worker/uniter/charm: download verified
[LOG] 67.69686 DEBUG juju worker/uniter/filter: changing charm to "cs:series/wordpress-0"
[LOG] 67.70520 DEBUG juju worker/uniter/filter: no new charm event
[LOG] 67.70576 INFO juju worker/uniter: deploying charm "cs:series/wordpress-0"
[LOG] 67.70626 DEBUG juju worker/uniter/filter: got config change
[LOG] 67.70628 DEBUG juju worker/uniter/filter: preparing new config event
[LOG] 67.70630 DEBUG juju worker/uniter/filter: got relations change
[LOG] 67.70970 INFO juju worker/uniter/charm: preparing new charm deployment
[LOG] 67.90527 INFO juju worker/uniter/charm: deploying charm
[LOG] 67.91132 INFO juju worker/uniter/charm: charm deployment succeeded
[LOG] 67.91524 INFO juju worker/uniter: charm "cs:series/wordpress-0" is deployed
[LOG] 67.91817 DEBUG juju worker/uniter: ModeInstalling cs:series/wordpress-0 exiting
[LOG] 67.91827 INFO juju worker/uniter: ModeContinue starting
[LOG] 67.91830 INFO juju worker/uniter: found queued "install" hook
[LOG] 67.92594 INFO juju worker/uniter: running "install" hook
[LOG] 68.04018 INFO juju worker/uniter/jujuc: running hook tool "juju-log" ["7799e131-b51e-4619-85c8-aedefe6a3c44" "install"]
[LOG] 68.04029 DEBUG juju worker/uniter/jujuc: hook context id "u/0:install:7613694542555931115"; dir "/tmp/gocheck-6175742077372812453/0/agents/unit-u-0/charm"
[LOG] 68.04040 INFO juju u/0: 7799e131-b51e-4619-85c8-aedefe6a3c44 install
[LOG] 68.04934 INFO juju worker/uniter: ran "install" hook
[LOG] 68.04937 INFO juju worker/uniter: committing "install" hook
[LOG] 68.07916 INFO juju worker/uniter: committed "install" hook
[LOG] 68.08144 DEBUG juju worker/uniter: ModeContinue exiting
[LOG] 68.08156 INFO juju worker/uniter: ModeContinue starting
[LOG] 68.08160 INFO juju worker/uniter: continuing after "install" hook
[LOG] 68.08162 DEBUG juju worker/uniter: ModeContinue exiting
[LOG] 68.08179 INFO juju worker/uniter: ModeConfigChanged starting
[LOG] 68.09664 DEBUG juju worker/uniter/filter: discarded config event
[LOG] 68.10285 INFO juju worker/uniter: running "config-changed" hook
[LOG] 68.21061 INFO juju worker/uniter/jujuc: running hook tool "juju-log" ["7799e131-b51e-4619-85c8-aedefe6a3c44" "config-changed"]
[LOG] 68.21080 DEBUG juju worker/uniter/jujuc: hook context id "u/0:config-changed:6577456096178271837"; dir "/tmp/gocheck-6175742077372812453/0/agents/unit-u-0/charm"
[LOG] 68.21097 INFO juju u/0: 7799e131-b51e-4619-85c8-aedefe6a3c44 config-changed
[LOG] 68.21879 INFO juju worker/uniter: ran "config-changed" hook
[LOG] 68.21881 INFO juju worker/uniter: committing "config-changed" hook
[LOG] 68.25770 INFO juju worker/uniter: committed "config-changed" hook
[LOG] 68.26008 DEBUG juju worker/uniter: ModeConfigChanged exiting
[LOG] 68.26030 INFO juju worker/uniter: ModeContinue starting
[LOG] 68.26034 INFO juju worker/uniter: continuing after "config-changed" hook
[LOG] 68.26036 DEBUG juju worker/uniter: ModeContinue exiting
[LOG] 68.26050 INFO juju worker/uniter: ModeStarting starting
[LOG] 68.27070 INFO juju worker/uniter: running "start" hook
[LOG] 68.38053 INFO juju worker/uniter/jujuc: running hook tool "juju-log" ["7799e131-b51e-4619-85c8-aedefe6a3c44" "start"]
[LOG] 68.38078 DEBUG juju worker/uniter/jujuc: hook context id "u/0:start:4170898519850741918"; dir "/tmp/gocheck-6175742077372812453/0/agents/unit-u-0/charm"
[LOG] 68.38209 INFO juju u/0: 7799e131-b51e-4619-85c8-aedefe6a3c44 start
[LOG] 68.39140 INFO juju worker/uniter: ran "start" hook
[LOG] 68.39142 INFO juju worker/uniter: committing "start" hook
[LOG] 68.42210 INFO juju worker/uniter: committed "start" hook
[LOG] 68.42302 DEBUG juju worker/uniter: ModeStarting exiting
[LOG] 68.42307 INFO juju worker/uniter: ModeContinue starting
[LOG] 68.42309 INFO juju worker/uniter: continuing after "start" hook
[LOG] 68.42311 DEBUG juju worker/uniter: ModeContinue exiting
[LOG] 68.42312 INFO juju worker/uniter: ModeAbide starting
[LOG] 68.45134 DEBUG juju worker/uniter/filter: want forced upgrade false
[LOG] 68.45136 DEBUG juju worker/uniter/filter: no new charm event
[LOG] 72.43074 DEBUG juju worker/uniter/filter: got unit change
uniter_test.go:872:
    s.runUniterTests(c, relationsTests)
uniter_test.go:1092:
    c.Fatalf("timed out waiting for unit addresses")
... Error: timed out waiting for unit addresses

[LOG] 77.63965 DEBUG juju worker/uniter: ModeAbide exiting
[LOG] 77.63968 INFO juju worker/uniter: unit "u/0" shutting down: tomb: dying
[LOG] 77.63982 ERROR juju worker/uniter/filter: tomb: dying
[LOG] 77.64054 INFO juju environs/dummy: reset environment
[LOG] 77.64082 DEBUG juju rpc/jsoncodec: <- error: read tcp 127.0.0.1:53310: use of closed network connection (closing true)
[LOG] 77.72226 INFO juju environs/testing: uploading FAKE tools 1.13.1-precise-amd64
[LOG] 77.72239 INFO juju environs: reading tools with major version 1
[LOG] 77.72241 DEBUG juju.agent.tools reading v1.* tools
[LOG] 77.72242 INFO juju environs: falling back to public bucket
[LOG] 77.72243 DEBUG juju.agent.tools reading v1.* tools
[LOG] 77.72253 DEBUG juju.agent.tools found 1.13.1-precise-amd64
[LOG] 77.72255 INFO juju environs: filtering tools by series: precise
[LOG] 77.72256 INFO juju environs: filtering tools by version: 1.13.1
[LOG] 77.72258 INFO juju environs/dummy: would pick tools from 1.13.1-precise-amd64
[LOG] 77.73954 INFO juju state: opening state; mongo addresses: ["localhost:34764"]; entity ""
[LOG] 77.74254 INFO juju state: connection established
[LOG] 77.77784 INFO juju state: initializing environment
[LOG] 77.79924 INFO juju state/api: listening on "127.0.0.1:43036"
[LOG] 77.81710 INFO juju state: opening state; mongo addresses: ["localhost:34764"]; entity ""
[LOG] 77.82073 INFO juju state: connection established
[LOG] 77.83769 INFO juju juju: authorization error while connecting to state server; retrying
[LOG] 77.83773 INFO juju state: opening state; mongo addresses: ["localhost:34764"]; entity ""
[LOG] 77.84449 INFO juju state: connection established
[LOG] 77.87539 INFO juju state/api: dialing "wss://127.0.0.1:43036/"
[LOG] 77.87835 INFO juju state/api: connection established
[LOG] 77.87847 DEBUG juju rpc/jsoncodec: <- {"RequestId":1,"Type":"Admin","Request":"Login","Params":{"AuthTag":"user-admin","Password":"dummy-secret","Nonce":""}}
[LOG] 77.87884 DEBUG juju rpc/jsoncodec: -> {"RequestId":1,"Response":{}}
[LOG] 77.89252 INFO juju environs/dummy: reset environment
[LOG] 77.89273 DEBUG juju rpc/jsoncodec: <- error: read tcp 127.0.0.1:42074: use of closed network connection (closing true)

----------------------------------------------------------------------
FAIL: uniter_test.go:334: UniterSuite.TestUniterStartHook

[LOG] 77.97385 INFO juju environs/testing: uploading FAKE tools 1.13.1-precise-amd64
[LOG] 77.97392 INFO juju environs: reading tools with major version 1
[LOG] 77.97393 DEBUG juju.agent.tools reading v1.* tools
[LOG] 77.97394 INFO juju environs: falling back to public bucket
[LOG] 77.97395 DEBUG juju.agent.tools reading v1.* tools
[LOG] 77.97398 DEBUG juju.agent.tools found 1.13.1-precise-amd64
[LOG] 77.97399 INFO juju environs: filtering tools by series: precise
[LOG] 77.97401 INFO juju environs: filtering tools by version: 1.13.1
[LOG] 77.97403 INFO juju environs/dummy: would pick tools from 1.13.1-precise-amd64
[LOG] 77.99108 INFO juju state: opening state; mongo addresses: ["localhost:34764"]; entity ""
[LOG] 77.99628 INFO juju state: connection established
[LOG] 78.03060 INFO juju state: initializing environment
[LOG] 78.05133 INFO juju state/api: listening on "127.0.0.1:45637"
[LOG] 78.06826 INFO juju state: opening state; mongo addresses: ["localhost:34764"]; entity ""
[LOG] 78.09168 INFO juju state: connection established
[LOG] 78.09275 INFO juju juju: authorization error while connecting to state server; retrying
[LOG] 78.09282 INFO juju state: opening state; mongo addresses: ["localhost:34764"]; entity ""
[LOG] 78.09688 INFO juju state: connection established
[LOG] 78.12928 INFO juju state/api: dialing "wss://127.0.0.1:45637/"
[LOG] 78.13221 INFO juju state/api: connection established
[LOG] 78.13232 DEBUG juju rpc/jsoncodec: <- {"RequestId":1,"Type":"Admin","Request":"Login","Params":{"AuthTag":"user-admin","Password":"dummy-secret","Nonce":""}}
[LOG] 78.13308 DEBUG juju rpc/jsoncodec: -> {"RequestId":1,"Response":{}}

test 0: start hook fail and resolve

step 0
uniter_test.startupError{badHook:"start"}
uniter_test.createCharm{revision:0, badHooks:[]string{"start"}, customize:(func(*gocheck.C, *uniter_test.context, string))(nil)}
uniter_test.addCharm{dir:(*charm.Dir)(0xc20049abd0), curl:(*charm.URL)(0xc200a82320)}
[LOG] 78.16704 DEBUG juju rpc/jsoncodec: <- {"RequestId":2,"Type":"Pinger","Request":"Ping","Params":{}}
[LOG] 78.19997 DEBUG juju rpc/jsoncodec: -> {"RequestId":2,"Response":{}}
uniter_test.serveCharm{}
uniter_test.createUniter{}
uniter_test.createServiceAndUnit{}
uniter_test.startUniter{}
uniter_test.waitAddresses{}
[LOG] 78.27963 INFO juju worker/uniter: unit "u/0" started
[LOG] 78.28924 INFO juju worker/uniter: ModeInit starting
[LOG] 78.28928 INFO juju worker/uniter: updating unit addresses
[LOG] 78.28968 DEBUG juju worker/uniter/filter: charm check skipped, not yet installed.
[LOG] 78.29166 DEBUG juju worker/uniter/filter: got unit change
[LOG] 78.29202 DEBUG juju worker/uniter/filter: got relations change
[LOG] 78.29203 DEBUG juju worker/uniter/filter: got service change
[LOG] 78.29369 DEBUG juju worker/uniter/filter: charm check skipped, not yet installed.
[LOG] 78.30313 INFO juju reconciling relation state
[LOG] 78.30322 DEBUG juju worker/uniter: ModeInit exiting
[LOG] 78.30323 INFO juju worker/uniter: ModeContinue starting
[LOG] 78.30324 INFO juju loading uniter state
[LOG] 78.30326 INFO juju worker/uniter: charm is not deployed
[LOG] 78.30328 DEBUG juju worker/uniter: ModeContinue exiting
[LOG] 78.30328 INFO juju worker/uniter: ModeInstalling cs:series/wordpress-0 starting
[LOG] 78.30330 INFO juju worker/uniter: fetching charm "cs:series/wordpress-0"
[LOG] 78.30379 INFO juju worker/uniter/charm: downloading cs:series/wordpress-0 from http://localhost:40085/charms/wordpress/0
[LOG] 78.30431 INFO juju worker/uniter/charm: download complete
[LOG] 78.30442 INFO juju worker/uniter/charm: download verified
[LOG] 78.37343 DEBUG juju worker/uniter/filter: changing charm to "cs:series/wordpress-0"
[LOG] 78.37980 DEBUG juju worker/uniter/filter: no new charm event
[LOG] 78.38020 INFO juju worker/uniter: deploying charm "cs:series/wordpress-0"
[LOG] 78.38425 INFO juju worker/uniter/charm: preparing new charm deployment
[LOG] 78.39590 DEBUG juju worker/uniter/filter: got config change
[LOG] 78.39603 DEBUG juju worker/uniter/filter: preparing new config event
[LOG] 78.39610 DEBUG juju worker/uniter/filter: got relations change
[LOG] 78.57369 INFO juju worker/uniter/charm: deploying charm
[LOG] 78.57796 INFO juju worker/uniter/charm: charm deployment succeeded
[LOG] 78.58124 INFO juju worker/uniter: charm "cs:series/wordpress-0" is deployed
[LOG] 78.58677 DEBUG juju worker/uniter: ModeInstalling cs:series/wordpress-0 exiting
[LOG] 78.58680 INFO juju worker/uniter: ModeContinue starting
[LOG] 78.58684 INFO juju worker/uniter: found queued "install" hook
[LOG] 78.61964 INFO juju worker/uniter: running "install" hook
[LOG] 78.74993 INFO juju worker/uniter/jujuc: running hook tool "juju-log" ["131c6e59-c625-4660-806a-94c2e924628f" "install"]
[LOG] 78.75018 DEBUG juju worker/uniter/jujuc: hook context id "u/0:install:9108875021955881423"; dir "/tmp/gocheck-6175742077372812453/0/agents/unit-u-0/charm"
[LOG] 78.75061 INFO juju u/0: 131c6e59-c625-4660-806a-94c2e924628f install
[LOG] 78.76089 INFO juju worker/uniter: ran "install" hook
[LOG] 78.76102 INFO juju worker/uniter: committing "install" hook
[LOG] 78.79583 INFO juju worker/uniter: committed "install" hook
[LOG] 78.79732 DEBUG juju worker/uniter: ModeContinue exiting
[LOG] 78.79744 INFO juju worker/uniter: ModeContinue starting
[LOG] 78.79748 INFO juju worker/uniter: continuing after "install" hook
[LOG] 78.79750 DEBUG juju worker/uniter: ModeContinue exiting
[LOG] 78.79753 INFO juju worker/uniter: ModeConfigChanged starting
[LOG] 78.81211 DEBUG juju worker/uniter/filter: discarded config event
[LOG] 78.81788 INFO juju worker/uniter: running "config-changed" hook
[LOG] 78.93934 INFO juju worker/uniter/jujuc: running hook tool "juju-log" ["131c6e59-c625-4660-806a-94c2e924628f" "config-changed"]
[LOG] 78.93959 DEBUG juju worker/uniter/jujuc: hook context id "u/0:config-changed:1117121234938525493"; dir "/tmp/gocheck-6175742077372812453/0/agents/unit-u-0/charm"
[LOG] 78.93967 INFO juju u/0: 131c6e59-c625-4660-806a-94c2e924628f config-changed
[LOG] 78.95075 INFO juju worker/uniter: ran "config-changed" hook
[LOG] 78.95085 INFO juju worker/uniter: committing "config-changed" hook
[LOG] 78.99413 INFO juju worker/uniter: committed "config-changed" hook
[LOG] 78.99476 DEBUG juju worker/uniter: ModeConfigChanged exiting
[LOG] 78.99479 INFO juju worker/uniter: ModeContinue starting
[LOG] 78.99485 INFO juju worker/uniter: continuing after "config-changed" hook
[LOG] 78.99486 DEBUG juju worker/uniter: ModeContinue exiting
[LOG] 78.99487 INFO juju worker/uniter: ModeStarting starting
[LOG] 79.01141 INFO juju worker/uniter: running "start" hook
[LOG] 79.12717 INFO juju worker/uniter/jujuc: running hook tool "juju-log" ["131c6e59-c625-4660-806a-94c2e924628f" "fail-start"]
[LOG] 79.12722 DEBUG juju worker/uniter/jujuc: hook context id "u/0:start:2081334910574398524"; dir "/tmp/gocheck-6175742077372812453/0/agents/unit-u-0/charm"
[LOG] 79.12725 INFO juju u/0: 131c6e59-c625-4660-806a-94c2e924628f fail-start
[LOG] 79.13320 ERROR juju worker/uniter: hook failed: exit status 1
[LOG] 79.13466 DEBUG juju worker/uniter: ModeStarting exiting
[LOG] 79.13489 INFO juju worker/uniter: ModeHookError starting
[LOG] 79.14770 DEBUG juju worker/uniter/filter: want resolved event
[LOG] 79.14781 DEBUG juju worker/uniter/filter: want forced upgrade true
[LOG] 79.14785 DEBUG juju worker/uniter/filter: no new charm event
[LOG] 83.11728 DEBUG juju worker/uniter/filter: got unit change
uniter_test.go:335:
    s.runUniterTests(c, startHookTests)
uniter_test.go:1092:
    c.Fatalf("timed out waiting for unit addresses")
... Error: timed out waiting for unit addresses

[LOG] 88.29876 DEBUG juju worker/uniter: ModeHookError exiting
[LOG] 88.29879 INFO juju worker/uniter: unit "u/0" shutting down: tomb: dying
[LOG] 88.29892 ERROR juju worker/uniter/filter: tomb: dying
[LOG] 88.30023 INFO juju environs/dummy: reset environment
[LOG] 88.30047 DEBUG juju rpc/jsoncodec: <- error: read tcp 127.0.0.1:49443: use of closed network connection (closing true)
[LOG] 88.36418 INFO juju environs/testing: uploading FAKE tools 1.13.1-precise-amd64
[LOG] 88.36434 INFO juju environs: reading tools with major version 1
[LOG] 88.36436 DEBUG juju.agent.tools reading v1.* tools
[LOG] 88.36437 INFO juju environs: falling back to public bucket
[LOG] 88.36438 DEBUG juju.agent.tools reading v1.* tools
[LOG] 88.36442 DEBUG juju.agent.tools found 1.13.1-precise-amd64
[LOG] 88.36444 INFO juju environs: filtering tools by series: precise
[LOG] 88.36445 INFO juju environs: filtering tools by version: 1.13.1
[LOG] 88.36447 INFO juju environs/dummy: would pick tools from 1.13.1-precise-amd64
[LOG] 88.40286 INFO juju state: opening state; mongo addresses: ["localhost:34764"]; entity ""
[LOG] 88.42643 INFO juju state: connection established
[LOG] 88.45308 INFO juju state: initializing environment
[LOG] 88.48152 INFO juju state/api: listening on "127.0.0.1:34360"
[LOG] 88.50085 INFO juju state: opening state; mongo addresses: ["localhost:34764"]; entity ""
[LOG] 88.51705 INFO juju state: connection established
[LOG] 88.51864 INFO juju juju: authorization error while connecting to state server; retrying
[LOG] 88.51875 INFO juju state: opening state; mongo addresses: ["localhost:34764"]; entity ""
[LOG] 88.52598 INFO juju state: connection established
[LOG] 88.56583 INFO juju state/api: dialing "wss://127.0.0.1:34360/"
[LOG] 88.56878 INFO juju state/api: connection established
[LOG] 88.56891 DEBUG juju rpc/jsoncodec: <- {"RequestId":1,"Type":"Admin","Request":"Login","Params":{"AuthTag":"user-admin","Password":"dummy-secret","Nonce":""}}
[LOG] 88.56928 DEBUG juju rpc/jsoncodec: -> {"RequestId":1,"Response":{}}
[LOG] 88.57620 INFO juju environs/dummy: reset environment
[LOG] 88.57633 DEBUG juju rpc/jsoncodec: <- error: read tcp 127.0.0.1:35978: use of closed network connection (closing true)

----------------------------------------------------------------------
FAIL: uniter_test.go:624: UniterSuite.TestUniterSteadyStateUpgrade

[LOG] 89.63325 INFO juju environs/testing: uploading FAKE tools 1.13.1-precise-amd64
[LOG] 89.63333 INFO juju environs: reading tools with major version 1
[LOG] 89.63334 DEBUG juju.agent.tools reading v1.* tools
[LOG] 89.63335 INFO juju environs: falling back to public bucket
[LOG] 89.63337 DEBUG juju.agent.tools reading v1.* tools
[LOG] 89.63340 DEBUG juju.agent.tools found 1.13.1-precise-amd64
[LOG] 89.63341 INFO juju environs: filtering tools by series: precise
[LOG] 89.63343 INFO juju environs: filtering tools by version: 1.13.1
[LOG] 89.63345 INFO juju environs/dummy: would pick tools from 1.13.1-precise-amd64
[LOG] 89.65052 INFO juju state: opening state; mongo addresses: ["localhost:34764"]; entity ""
[LOG] 89.67050 INFO juju state: connection established
[LOG] 89.69118 INFO juju state: initializing environment
[LOG] 89.71229 INFO juju state/api: listening on "127.0.0.1:41546"
[LOG] 89.73627 INFO juju state: opening state; mongo addresses: ["localhost:34764"]; entity ""
[LOG] 89.75681 INFO juju state: connection established
[LOG] 89.75837 INFO juju juju: authorization error while connecting to state server; retrying
[LOG] 89.75850 INFO juju state: opening state; mongo addresses: ["localhost:34764"]; entity ""
[LOG] 89.76654 INFO juju state: connection established
[LOG] 89.79511 INFO juju state/api: dialing "wss://127.0.0.1:41546/"
[LOG] 89.79823 INFO juju state/api: connection established
[LOG] 89.79837 DEBUG juju rpc/jsoncodec: <- {"RequestId":1,"Type":"Admin","Request":"Login","Params":{"AuthTag":"user-admin","Password":"dummy-secret","Nonce":""}}
[LOG] 89.79875 DEBUG juju rpc/jsoncodec: -> {"RequestId":1,"Response":{}}

test 0: steady state upgrade

step 0
uniter_test.quickStart{}
uniter_test.createCharm{revision:0, badHooks:[]string(nil), customize:(func(*gocheck.C, *uniter_test.context, string))(nil)}
uniter_test.addCharm{dir:(*charm.Dir)(0xc200284b40), curl:(*charm.URL)(0xc200bd9fa0)}
[LOG] 89.82536 DEBUG juju rpc/jsoncodec: <- {"RequestId":2,"Type":"Pinger","Request":"Ping","Params":{}}
[LOG] 89.86568 DEBUG juju rpc/jsoncodec: -> {"RequestId":2,"Response":{}}
uniter_test.serveCharm{}
uniter_test.createUniter{}
uniter_test.createServiceAndUnit{}
uniter_test.startUniter{}
uniter_test.waitAddresses{}
[LOG] 89.97092 INFO juju worker/uniter: unit "u/0" started
[LOG] 89.98011 INFO juju worker/uniter: ModeInit starting
[LOG] 89.98013 INFO juju worker/uniter: updating unit addresses
[LOG] 89.98041 DEBUG juju worker/uniter/filter: charm check skipped, not yet installed.
[LOG] 89.98176 DEBUG juju worker/uniter/filter: got unit change
[LOG] 89.98275 DEBUG juju worker/uniter/filter: got service change
[LOG] 89.98356 DEBUG juju worker/uniter/filter: charm check skipped, not yet installed.
[LOG] 89.98358 DEBUG juju worker/uniter/filter: got relations change
[LOG] 89.98907 INFO juju reconciling relation state
[LOG] 89.98916 DEBUG juju worker/uniter: ModeInit exiting
[LOG] 89.98918 INFO juju worker/uniter: ModeContinue starting
[LOG] 89.98920 INFO juju loading uniter state
[LOG] 89.98922 INFO juju worker/uniter: charm is not deployed
[LOG] 89.98924 DEBUG juju worker/uniter: ModeContinue exiting
[LOG] 89.98925 INFO juju worker/uniter: ModeInstalling cs:series/wordpress-0 starting
[LOG] 89.98926 INFO juju worker/uniter: fetching charm "cs:series/wordpress-0"
[LOG] 89.98975 INFO juju worker/uniter/charm: downloading cs:series/wordpress-0 from http://localhost:40085/charms/wordpress/0
[LOG] 89.99032 INFO juju worker/uniter/charm: download complete
[LOG] 89.99043 INFO juju worker/uniter/charm: download verified
[LOG] 90.05419 DEBUG juju worker/uniter/filter: changing charm to "cs:series/wordpress-0"
[LOG] 90.08414 DEBUG juju worker/uniter/filter: no new charm event
[LOG] 90.08644 INFO juju worker/uniter: deploying charm "cs:series/wordpress-0"
[LOG] 90.08788 DEBUG juju worker/uniter/filter: got config change
[LOG] 90.08795 DEBUG juju worker/uniter/filter: preparing new config event
[LOG] 90.08803 DEBUG juju worker/uniter/filter: got relations change
[LOG] 90.09128 INFO juju worker/uniter/charm: preparing new charm deployment
[LOG] 90.27394 INFO juju worker/uniter/charm: deploying charm
[LOG] 90.28153 INFO juju worker/uniter/charm: charm deployment succeeded
[LOG] 90.28513 INFO juju worker/uniter: charm "cs:series/wordpress-0" is deployed
[LOG] 90.28782 DEBUG juju worker/uniter: ModeInstalling cs:series/wordpress-0 exiting
[LOG] 90.28786 INFO juju worker/uniter: ModeContinue starting
[LOG] 90.28789 INFO juju worker/uniter: found queued "install" hook
[LOG] 90.29513 INFO juju worker/uniter: running "install" hook
[LOG] 90.40768 INFO juju worker/uniter/jujuc: running hook tool "juju-log" ["4a3ef7d6-ed80-4997-8486-b0c20f38a26d" "install"]
[LOG] 90.40786 DEBUG juju worker/uniter/jujuc: hook context id "u/0:install:5399275964461904777"; dir "/tmp/gocheck-6175742077372812453/0/agents/unit-u-0/charm"
[LOG] 90.40794 INFO juju u/0: 4a3ef7d6-ed80-4997-8486-b0c20f38a26d install
[LOG] 90.41724 INFO juju worker/uniter: ran "install" hook
[LOG] 90.41746 INFO juju worker/uniter: committing "install" hook
[LOG] 90.45983 INFO juju worker/uniter: committed "install" hook
[LOG] 90.46124 DEBUG juju worker/uniter: ModeContinue exiting
[LOG] 90.46131 INFO juju worker/uniter: ModeContinue starting
[LOG] 90.46134 INFO juju worker/uniter: continuing after "install" hook
[LOG] 90.46137 DEBUG juju worker/uniter: ModeContinue exiting
[LOG] 90.46139 INFO juju worker/uniter: ModeConfigChanged starting
[LOG] 90.49287 DEBUG juju worker/uniter/filter: discarded config event
[LOG] 90.49716 INFO juju worker/uniter: running "config-changed" hook
[LOG] 90.58226 INFO juju worker/uniter/jujuc: running hook tool "juju-log" ["4a3ef7d6-ed80-4997-8486-b0c20f38a26d" "config-changed"]
[LOG] 90.58233 DEBUG juju worker/uniter/jujuc: hook context id "u/0:config-changed:7994604818268466630"; dir "/tmp/gocheck-6175742077372812453/0/agents/unit-u-0/charm"
[LOG] 90.58238 INFO juju u/0: 4a3ef7d6-ed80-4997-8486-b0c20f38a26d config-changed
[LOG] 90.58889 INFO juju worker/uniter: ran "config-changed" hook
[LOG] 90.58891 INFO juju worker/uniter: committing "config-changed" hook
[LOG] 90.61211 INFO juju worker/uniter: committed "config-changed" hook
[LOG] 90.61317 DEBUG juju worker/uniter: ModeConfigChanged exiting
[LOG] 90.61333 INFO juju worker/uniter: ModeContinue starting
[LOG] 90.61355 INFO juju worker/uniter: continuing after "config-changed" hook
[LOG] 90.61358 DEBUG juju worker/uniter: ModeContinue exiting
[LOG] 90.61361 INFO juju worker/uniter: ModeStarting starting
[LOG] 90.62401 INFO juju worker/uniter: running "start" hook
[LOG] 90.74435 INFO juju worker/uniter/jujuc: running hook tool "juju-log" ["4a3ef7d6-ed80-4997-8486-b0c20f38a26d" "start"]
[LOG] 90.74446 DEBUG juju worker/uniter/jujuc: hook context id "u/0:start:5919297103683014847"; dir "/tmp/gocheck-6175742077372812453/0/agents/unit-u-0/charm"
[LOG] 90.74455 INFO juju u/0: 4a3ef7d6-ed80-4997-8486-b0c20f38a26d start
[LOG] 90.75339 INFO juju worker/uniter: ran "start" hook
[LOG] 90.75355 INFO juju worker/uniter: committing "start" hook
[LOG] 90.79637 INFO juju worker/uniter: committed "start" hook
[LOG] 90.79683 DEBUG juju worker/uniter: ModeStarting exiting
[LOG] 90.79686 INFO juju worker/uniter: ModeContinue starting
[LOG] 90.79687 INFO juju worker/uniter: continuing after "start" hook
[LOG] 90.79690 DEBUG juju worker/uniter: ModeContinue exiting
[LOG] 90.79691 INFO juju worker/uniter: ModeAbide starting
[LOG] 90.81757 DEBUG juju worker/uniter/filter: want forced upgrade false
[LOG] 90.81759 DEBUG juju worker/uniter/filter: no new charm event
[LOG] 94.78615 DEBUG juju worker/uniter/filter: got unit change
uniter_test.go:625:
    s.runUniterTests(c, steadyUpgradeTests)
uniter_test.go:1092:
    c.Fatalf("timed out waiting for unit addresses")
... Error: timed out waiting for unit addresses

[LOG] 99.99521 DEBUG juju worker/uniter: ModeAbide exiting
[LOG] 99.99524 INFO juju worker/uniter: unit "u/0" shutting down: tomb: dying
[LOG] 99.99537 ERROR juju worker/uniter/filter: tomb: dying
[LOG] 99.99685 INFO juju environs/dummy: reset environment
[LOG] 99.99711 DEBUG juju rpc/jsoncodec: <- error: read tcp 127.0.0.1:55896: use of closed network connection (closing true)
[LOG] 0.08319 INFO juju environs/testing: uploading FAKE tools 1.13.1-precise-amd64
[LOG] 0.08327 INFO juju environs: reading tools with major version 1
[LOG] 0.08328 DEBUG juju.agent.tools reading v1.* tools
[LOG] 0.08329 INFO juju environs: falling back to public bucket
[LOG] 0.08330 DEBUG juju.agent.tools reading v1.* tools
[LOG] 0.08334 DEBUG juju.agent.tools found 1.13.1-precise-amd64
[LOG] 0.08335 INFO juju environs: filtering tools by series: precise
[LOG] 0.08337 INFO juju environs: filtering tools by version: 1.13.1
[LOG] 0.08339 INFO juju environs/dummy: would pick tools from 1.13.1-precise-amd64
[LOG] 0.11432 INFO juju state: opening state; mongo addresses: ["localhost:34764"]; entity ""
[LOG] 0.11668 INFO juju state: connection established
[LOG] 0.14356 INFO juju state: initializing environment
[LOG] 0.16472 INFO juju state/api: listening on "127.0.0.1:37529"
[LOG] 0.18545 INFO juju state: opening state; mongo addresses: ["localhost:34764"]; entity ""
[LOG] 0.18885 INFO juju state: connection established
[LOG] 0.20294 INFO juju juju: authorization error while connecting to state server; retrying
[LOG] 0.20298 INFO juju state: opening state; mongo addresses: ["localhost:34764"]; entity ""
[LOG] 0.20712 INFO juju state: connection established
[LOG] 0.26758 INFO juju state/api: dialing "wss://127.0.0.1:37529/"
[LOG] 0.27255 INFO juju state/api: connection established
[LOG] 0.27288 DEBUG juju rpc/jsoncodec: <- {"RequestId":1,"Type":"Admin","Request":"Login","Params":{"AuthTag":"user-admin","Password":"dummy-secret","Nonce":""}}
[LOG] 0.27330 DEBUG juju rpc/jsoncodec: -> {"RequestId":1,"Response":{}}
[LOG] 0.27391 DEBUG juju rpc/jsoncodec: <- {"RequestId":2,"Type":"Pinger","Request":"Ping","Params":{}}
[LOG] 0.27421 DEBUG juju rpc/jsoncodec: -> {"RequestId":2,"Response":{}}
[LOG] 0.28774 INFO juju environs/dummy: reset environment
[LOG] 0.28793 DEBUG juju rpc/jsoncodec: <- error: read tcp 127.0.0.1:47983: use of closed network connection (closing true)

----------------------------------------------------------------------
FAIL: uniter_test.go:903: UniterSuite.TestUniterSubordinates

[LOG] 0.35219 INFO juju environs/testing: uploading FAKE tools 1.13.1-precise-amd64
[LOG] 0.35225 INFO juju environs: reading tools with major version 1
[LOG] 0.35227 DEBUG juju.agent.tools reading v1.* tools
[LOG] 0.35228 INFO juju environs: falling back to public bucket
[LOG] 0.35229 DEBUG juju.agent.tools reading v1.* tools
[LOG] 0.35232 DEBUG juju.agent.tools found 1.13.1-precise-amd64
[LOG] 0.35233 INFO juju environs: filtering tools by series: precise
[LOG] 0.35235 INFO juju environs: filtering tools by version: 1.13.1
[LOG] 0.35237 INFO juju environs/dummy: would pick tools from 1.13.1-precise-amd64
[LOG] 0.36929 INFO juju state: opening state; mongo addresses: ["localhost:34764"]; entity ""
[LOG] 0.38807 INFO juju state: connection established
[LOG] 0.41945 INFO juju state: initializing environment
[LOG] 0.44015 INFO juju state/api: listening on "127.0.0.1:54230"
[LOG] 0.45729 INFO juju state: opening state; mongo addresses: ["localhost:34764"]; entity ""
[LOG] 0.47185 INFO juju state: connection established
[LOG] 0.47257 INFO juju juju: authorization error while connecting to state server; retrying
[LOG] 0.47262 INFO juju state: opening state; mongo addresses: ["localhost:34764"]; entity ""
[LOG] 0.48029 INFO juju state: connection established
[LOG] 0.51790 INFO juju state/api: dialing "wss://127.0.0.1:54230/"
[LOG] 0.52132 INFO juju state/api: connection established
[LOG] 0.52154 DEBUG juju rpc/jsoncodec: <- {"RequestId":1,"Type":"Admin","Request":"Login","Params":{"AuthTag":"user-admin","Password":"dummy-secret","Nonce":""}}
[LOG] 0.52201 DEBUG juju rpc/jsoncodec: -> {"RequestId":1,"Response":{}}

test 0: unit becomes dying while subordinates exist

[LOG] 0.52223 DEBUG juju rpc/jsoncodec: <- {"RequestId":2,"Type":"Pinger","Request":"Ping","Params":{}}
[LOG] 0.52227 DEBUG juju rpc/jsoncodec: -> {"RequestId":2,"Response":{}}
step 0
uniter_test.quickStart{}
uniter_test.createCharm{revision:0, badHooks:[]string(nil), customize:(func(*gocheck.C, *uniter_test.context, string))(nil)}
uniter_test.addCharm{dir:(*charm.Dir)(0xc20099c6f0), curl:(*charm.URL)(0xc200cf7780)}
uniter_test.serveCharm{}
uniter_test.createUniter{}
uniter_test.createServiceAndUnit{}
uniter_test.startUniter{}
uniter_test.waitAddresses{}
[LOG] 0.65412 INFO juju worker/uniter: unit "u/0" started
[LOG] 0.66686 INFO juju worker/uniter: ModeInit starting
[LOG] 0.66688 INFO juju worker/uniter: updating unit addresses
[LOG] 0.66719 DEBUG juju worker/uniter/filter: charm check skipped, not yet installed.
[LOG] 0.66850 DEBUG juju worker/uniter/filter: got unit change
[LOG] 0.66949 DEBUG juju worker/uniter/filter: got service change
[LOG] 0.67014 DEBUG juju worker/uniter/filter: charm check skipped, not yet installed.
[LOG] 0.67015 DEBUG juju worker/uniter/filter: got relations change
[LOG] 0.67499 INFO juju reconciling relation state
[LOG] 0.67508 DEBUG juju worker/uniter: ModeInit exiting
[LOG] 0.67509 INFO juju worker/uniter: ModeContinue starting
[LOG] 0.67510 INFO juju loading uniter state
[LOG] 0.67512 INFO juju worker/uniter: charm is not deployed
[LOG] 0.67514 DEBUG juju worker/uniter: ModeContinue exiting
[LOG] 0.67515 INFO juju worker/uniter: ModeInstalling cs:series/wordpress-0 starting
[LOG] 0.67518 INFO juju worker/uniter: fetching charm "cs:series/wordpress-0"
[LOG] 0.67568 INFO juju worker/uniter/charm: downloading cs:series/wordpress-0 from http://localhost:40085/charms/wordpress/0
[LOG] 0.67619 INFO juju worker/uniter/charm: download complete
[LOG] 0.67631 INFO juju worker/uniter/charm: download verified
[LOG] 0.74531 DEBUG juju worker/uniter/filter: changing charm to "cs:series/wordpress-0"
[LOG] 0.76949 DEBUG juju worker/uniter/filter: no new charm event
[LOG] 0.77127 INFO juju worker/uniter: deploying charm "cs:series/wordpress-0"
[LOG] 0.77223 DEBUG juju worker/uniter/filter: got config change
[LOG] 0.77230 DEBUG juju worker/uniter/filter: preparing new config event
[LOG] 0.77237 DEBUG juju worker/uniter/filter: got relations change
[LOG] 0.77547 INFO juju worker/uniter/charm: preparing new charm deployment
[LOG] 0.94630 INFO juju worker/uniter/charm: deploying charm
[LOG] 0.95157 INFO juju worker/uniter/charm: charm deployment succeeded
[LOG] 0.95551 INFO juju worker/uniter: charm "cs:series/wordpress-0" is deployed
[LOG] 0.95906 DEBUG juju worker/uniter: ModeInstalling cs:series/wordpress-0 exiting
[LOG] 0.95915 INFO juju worker/uniter: ModeContinue starting
[LOG] 0.95918 INFO juju worker/uniter: found queued "install" hook
[LOG] 0.96593 INFO juju worker/uniter: running "install" hook
[LOG] 1.09044 INFO juju worker/uniter/jujuc: running hook tool "juju-log" ["15b858e4-a221-42ce-84e2-6ca6e67622e3" "install"]
[LOG] 1.09068 DEBUG juju worker/uniter/jujuc: hook context id "u/0:install:1823992389557351320"; dir "/tmp/gocheck-6175742077372812453/0/agents/unit-u-0/charm"
[LOG] 1.09076 INFO juju u/0: 15b858e4-a221-42ce-84e2-6ca6e67622e3 install
[LOG] 1.10000 INFO juju worker/uniter: ran "install" hook
[LOG] 1.10005 INFO juju worker/uniter: committing "install" hook
[LOG] 1.14158 INFO juju worker/uniter: committed "install" hook
[LOG] 1.14201 DEBUG juju worker/uniter: ModeContinue exiting
[LOG] 1.14203 INFO juju worker/uniter: ModeContinue starting
[LOG] 1.14205 INFO juju worker/uniter: continuing after "install" hook
[LOG] 1.14205 DEBUG juju worker/uniter: ModeContinue exiting
[LOG] 1.14206 INFO juju worker/uniter: ModeConfigChanged starting
[LOG] 1.14719 DEBUG juju worker/uniter/filter: discarded config event
[LOG] 1.15059 INFO juju worker/uniter: running "config-changed" hook
[LOG] 1.25880 INFO juju worker/uniter/jujuc: running hook tool "juju-log" ["15b858e4-a221-42ce-84e2-6ca6e67622e3" "config-changed"]
[LOG] 1.25897 DEBUG juju worker/uniter/jujuc: hook context id "u/0:config-changed:6781612629018235807"; dir "/tmp/gocheck-6175742077372812453/0/agents/unit-u-0/charm"
[LOG] 1.25905 INFO juju u/0: 15b858e4-a221-42ce-84e2-6ca6e67622e3 config-changed
[LOG] 1.26881 INFO juju worker/uniter: ran "config-changed" hook
[LOG] 1.26888 INFO juju worker/uniter: committing "config-changed" hook
[LOG] 1.30841 INFO juju worker/uniter: committed "config-changed" hook
[LOG] 1.30885 DEBUG juju worker/uniter: ModeConfigChanged exiting
[LOG] 1.30888 INFO juju worker/uniter: ModeContinue starting
[LOG] 1.30889 INFO juju worker/uniter: continuing after "config-changed" hook
[LOG] 1.30890 DEBUG juju worker/uniter: ModeContinue exiting
[LOG] 1.30891 INFO juju worker/uniter: ModeStarting starting
[LOG] 1.31641 INFO juju worker/uniter: running "start" hook
[LOG] 1.42916 INFO juju worker/uniter/jujuc: running hook tool "juju-log" ["15b858e4-a221-42ce-84e2-6ca6e67622e3" "start"]
[LOG] 1.42920 DEBUG juju worker/uniter/jujuc: hook context id "u/0:start:4991515370728313268"; dir "/tmp/gocheck-6175742077372812453/0/agents/unit-u-0/charm"
[LOG] 1.42927 INFO juju u/0: 15b858e4-a221-42ce-84e2-6ca6e67622e3 start
[LOG] 1.43488 INFO juju worker/uniter: ran "start" hook
[LOG] 1.43490 INFO juju worker/uniter: committing "start" hook
[LOG] 1.46187 INFO juju worker/uniter: committed "start" hook
[LOG] 1.46277 DEBUG juju worker/uniter: ModeStarting exiting
[LOG] 1.46282 INFO juju worker/uniter: ModeContinue starting
[LOG] 1.46284 INFO juju worker/uniter: continuing after "start" hook
[LOG] 1.46286 DEBUG juju worker/uniter: ModeContinue exiting
[LOG] 1.46287 INFO juju worker/uniter: ModeAbide starting
[LOG] 1.49379 DEBUG juju worker/uniter/filter: want forced upgrade false
[LOG] 1.49391 DEBUG juju worker/uniter/filter: no new charm event
[LOG] 5.49884 DEBUG juju worker/uniter/filter: got unit change
uniter_test.go:904:
    s.runUniterTests(c, subordinatesTests)
uniter_test.go:1092:
    c.Fatalf("timed out waiting for unit addresses")
... Error: timed out waiting for unit addresses

[LOG] 10.67773 DEBUG juju worker/uniter: ModeAbide exiting
[LOG] 10.67775 INFO juju worker/uniter: unit "u/0" shutting down: tomb: dying
[LOG] 10.67790 ERROR juju worker/uniter/filter: tomb: dying
[LOG] 10.67879 INFO juju environs/dummy: reset environment
[LOG] 10.67903 DEBUG juju rpc/jsoncodec: <- error: read tcp 127.0.0.1:48490: use of closed network connection (closing true)
[LOG] 10.76594 INFO juju environs/testing: uploading FAKE tools 1.13.1-precise-amd64
[LOG] 10.76604 INFO juju environs: reading tools with major version 1
[LOG] 10.76607 DEBUG juju.agent.tools reading v1.* tools
[LOG] 10.76608 INFO juju environs: falling back to public bucket
[LOG] 10.76608 DEBUG juju.agent.tools reading v1.* tools
[LOG] 10.76613 DEBUG juju.agent.tools found 1.13.1-precise-amd64
[LOG] 10.76614 INFO juju environs: filtering tools by series: precise
[LOG] 10.76616 INFO juju environs: filtering tools by version: 1.13.1
[LOG] 10.76618 INFO juju environs/dummy: would pick tools from 1.13.1-precise-amd64
[LOG] 10.80221 INFO juju state: opening state; mongo addresses: ["localhost:34764"]; entity ""
[LOG] 10.82380 INFO juju state: connection established
[LOG] 10.85778 INFO juju state: initializing environment
[LOG] 10.87841 INFO juju state/api: listening on "127.0.0.1:58504"
[LOG] 10.90013 INFO juju state: opening state; mongo addresses: ["localhost:34764"]; entity ""
[LOG] 10.90385 INFO juju state: connection established
[LOG] 10.91945 INFO juju juju: authorization error while connecting to state server; retrying
[LOG] 10.91953 INFO juju state: opening state; mongo addresses: ["localhost:34764"]; entity ""
[LOG] 10.92303 INFO juju state: connection established
[LOG] 10.95659 INFO juju state/api: dialing "wss://127.0.0.1:58504/"
[LOG] 10.95957 INFO juju state/api: connection established
[LOG] 10.95973 DEBUG juju rpc/jsoncodec: <- {"RequestId":1,"Type":"Admin","Request":"Login","Params":{"AuthTag":"user-admin","Password":"dummy-secret","Nonce":""}}
[LOG] 10.96010 DEBUG juju rpc/jsoncodec: -> {"RequestId":1,"Response":{}}
[LOG] 10.96730 INFO juju environs/dummy: reset environment
[LOG] 10.96749 DEBUG juju rpc/jsoncodec: <- error: read tcp 127.0.0.1:57136: use of closed network connection (closing true)

----------------------------------------------------------------------
FAIL: uniter_test.go:804: UniterSuite.TestUniterUpgradeConflicts

[LOG] 11.05197 INFO juju environs/testing: uploading FAKE tools 1.13.1-precise-amd64
[LOG] 11.05204 INFO juju environs: reading tools with major version 1
[LOG] 11.05206 DEBUG juju.agent.tools reading v1.* tools
[LOG] 11.05207 INFO juju environs: falling back to public bucket
[LOG] 11.05208 DEBUG juju.agent.tools reading v1.* tools
[LOG] 11.05211 DEBUG juju.agent.tools found 1.13.1-precise-amd64
[LOG] 11.05212 INFO juju environs: filtering tools by series: precise
[LOG] 11.05214 INFO juju environs: filtering tools by version: 1.13.1
[LOG] 11.05216 INFO juju environs/dummy: would pick tools from 1.13.1-precise-amd64
[LOG] 11.06922 INFO juju state: opening state; mongo addresses: ["localhost:34764"]; entity ""
[LOG] 11.07369 INFO juju state: connection established
[LOG] 11.11331 INFO juju state: initializing environment
[LOG] 11.13418 INFO juju state/api: listening on "127.0.0.1:46174"
[LOG] 11.15219 INFO juju state: opening state; mongo addresses: ["localhost:34764"]; entity ""
[LOG] 11.15594 INFO juju state: connection established
[LOG] 11.17566 INFO juju juju: authorization error while connecting to state server; retrying
[LOG] 11.17582 INFO juju state: opening state; mongo addresses: ["localhost:34764"]; entity ""
[LOG] 11.18344 INFO juju state: connection established
[LOG] 11.23195 INFO juju state/api: dialing "wss://127.0.0.1:46174/"
[LOG] 11.23490 INFO juju state/api: connection established
[LOG] 11.23517 DEBUG juju rpc/jsoncodec: <- {"RequestId":1,"Type":"Admin","Request":"Login","Params":{"AuthTag":"user-admin","Password":"dummy-secret","Nonce":""}}
[LOG] 11.23560 DEBUG juju rpc/jsoncodec: -> {"RequestId":1,"Response":{}}

test 0: upgrade: conflicting files

[LOG] 11.23593 DEBUG juju rpc/jsoncodec: <- {"RequestId":2,"Type":"Pinger","Request":"Ping","Params":{}}
[LOG] 11.23600 DEBUG juju rpc/jsoncodec: -> {"RequestId":2,"Response":{}}
step 0
uniter_test.startUpgradeError{}
uniter_test.createCharm{revision:0, badHooks:[]string(nil), customize:(func(*gocheck.C, *uniter_test.context, string))(0x4510d0)}
uniter_test.addCharm{dir:(*charm.Dir)(0xc2005e0090), curl:(*charm.URL)(0xc200721280)}
uniter_test.serveCharm{}
uniter_test.createUniter{}
uniter_test.createServiceAndUnit{}
uniter_test.startUniter{}
uniter_test.waitAddresses{}
[LOG] 11.37126 INFO juju worker/uniter: unit "u/0" started
[LOG] 11.38298 INFO juju worker/uniter: ModeInit starting
[LOG] 11.38300 INFO juju worker/uniter: updating unit addresses
[LOG] 11.38328 DEBUG juju worker/uniter/filter: charm check skipped, not yet installed.
[LOG] 11.38491 DEBUG juju worker/uniter/filter: got unit change
[LOG] 11.38591 DEBUG juju worker/uniter/filter: got relations change
[LOG] 11.38593 DEBUG juju worker/uniter/filter: got service change
[LOG] 11.38675 DEBUG juju worker/uniter/filter: charm check skipped, not yet installed.
[LOG] 11.39233 INFO juju reconciling relation state
[LOG] 11.39243 DEBUG juju worker/uniter: ModeInit exiting
[LOG] 11.39244 INFO juju worker/uniter: ModeContinue starting
[LOG] 11.39245 INFO juju loading uniter state
[LOG] 11.39256 INFO juju worker/uniter: charm is not deployed
[LOG] 11.39259 DEBUG juju worker/uniter: ModeContinue exiting
[LOG] 11.39260 INFO juju worker/uniter: ModeInstalling cs:series/wordpress-0 starting
[LOG] 11.39262 INFO juju worker/uniter: fetching charm "cs:series/wordpress-0"
[LOG] 11.39344 INFO juju worker/uniter/charm: downloading cs:series/wordpress-0 from http://localhost:40085/charms/wordpress/0
[LOG] 11.39441 INFO juju worker/uniter/charm: download complete
[LOG] 11.39462 INFO juju worker/uniter/charm: download verified
[LOG] 11.46154 DEBUG juju worker/uniter/filter: changing charm to "cs:series/wordpress-0"
[LOG] 11.47982 DEBUG juju worker/uniter/filter: no new charm event
[LOG] 11.48041 INFO juju worker/uniter: deploying charm "cs:series/wordpress-0"
[LOG] 11.48182 DEBUG juju worker/uniter/filter: got config change
[LOG] 11.48188 DEBUG juju worker/uniter/filter: preparing new config event
[LOG] 11.48190 DEBUG juju worker/uniter/filter: got relations change
[LOG] 11.49168 INFO juju worker/uniter/charm: preparing new charm deployment
[LOG] 11.65352 INFO juju worker/uniter/charm: deploying charm
[LOG] 11.65704 INFO juju worker/uniter/charm: charm deployment succeeded
[LOG] 11.66095 INFO juju worker/uniter: charm "cs:series/wordpress-0" is deployed
[LOG] 11.66409 DEBUG juju worker/uniter: ModeInstalling cs:series/wordpress-0 exiting
[LOG] 11.66417 INFO juju worker/uniter: ModeContinue starting
[LOG] 11.66420 INFO juju worker/uniter: found queued "install" hook
[LOG] 11.67027 INFO juju worker/uniter: running "install" hook
[LOG] 11.76503 INFO juju worker/uniter/jujuc: running hook tool "juju-log" ["f45fd730-a065-4c84-8581-24bb7035904d" "install"]
[LOG] 11.76508 DEBUG juju worker/uniter/jujuc: hook context id "u/0:install:3319833975759976444"; dir "/tmp/gocheck-6175742077372812453/0/agents/unit-u-0/charm"
[LOG] 11.76511 INFO juju u/0: f45fd730-a065-4c84-8581-24bb7035904d install
[LOG] 11.77402 INFO juju worker/uniter: ran "install" hook
[LOG] 11.77408 INFO juju worker/uniter: committing "install" hook
[LOG] 11.80565 INFO juju worker/uniter: committed "install" hook
[LOG] 11.80646 DEBUG juju worker/uniter: ModeContinue exiting
[LOG] 11.80651 INFO juju worker/uniter: ModeContinue starting
[LOG] 11.80653 INFO juju worker/uniter: continuing after "install" hook
[LOG] 11.80655 DEBUG juju worker/uniter: ModeContinue exiting
[LOG] 11.80659 INFO juju worker/uniter: ModeConfigChanged starting
[LOG] 11.83217 DEBUG juju worker/uniter/filter: discarded config event
[LOG] 11.83713 INFO juju worker/uniter: running "config-changed" hook
[LOG] 11.95385 INFO juju worker/uniter/jujuc: running hook tool "juju-log" ["f45fd730-a065-4c84-8581-24bb7035904d" "config-changed"]
[LOG] 11.95408 DEBUG juju worker/uniter/jujuc: hook context id "u/0:config-changed:877839925617399552"; dir "/tmp/gocheck-6175742077372812453/0/agents/unit-u-0/charm"
[LOG] 11.95416 INFO juju u/0: f45fd730-a065-4c84-8581-24bb7035904d config-changed
[LOG] 11.96243 INFO juju worker/uniter: ran "config-changed" hook
[LOG] 11.96247 INFO juju worker/uniter: committing "config-changed" hook
[LOG] 11.99773 INFO juju worker/uniter: committed "config-changed" hook
[LOG] 11.99877 DEBUG juju worker/uniter: ModeConfigChanged exiting
[LOG] 11.99884 INFO juju worker/uniter: ModeContinue starting
[LOG] 11.99893 INFO juju worker/uniter: continuing after "config-changed" hook
[LOG] 11.99895 DEBUG juju worker/uniter: ModeContinue exiting
[LOG] 11.99901 INFO juju worker/uniter: ModeStarting starting
[LOG] 12.00518 INFO juju worker/uniter: running "start" hook
[LOG] 12.13067 INFO juju worker/uniter/jujuc: running hook tool "juju-log" ["f45fd730-a065-4c84-8581-24bb7035904d" "start"]
[LOG] 12.13078 DEBUG juju worker/uniter/jujuc: hook context id "u/0:start:3459078748636535806"; dir "/tmp/gocheck-6175742077372812453/0/agents/unit-u-0/charm"
[LOG] 12.13090 INFO juju u/0: f45fd730-a065-4c84-8581-24bb7035904d start
[LOG] 12.14197 INFO juju worker/uniter: ran "start" hook
[LOG] 12.14203 INFO juju worker/uniter: committing "start" hook
[LOG] 12.17561 INFO juju worker/uniter: committed "start" hook
[LOG] 12.17647 DEBUG juju worker/uniter: ModeStarting exiting
[LOG] 12.17652 INFO juju worker/uniter: ModeContinue starting
[LOG] 12.17657 INFO juju worker/uniter: continuing after "start" hook
[LOG] 12.17660 DEBUG juju worker/uniter: ModeContinue exiting
[LOG] 12.17661 INFO juju worker/uniter: ModeAbide starting
[LOG] 12.19950 DEBUG juju worker/uniter/filter: want forced upgrade false
[LOG] 12.19954 DEBUG juju worker/uniter/filter: no new charm event
[LOG] 16.22045 DEBUG juju worker/uniter/filter: got unit change
uniter_test.go:805:
    s.runUniterTests(c, upgradeConflictsTests)
uniter_test.go:1092:
    c.Fatalf("timed out waiting for unit addresses")
... Error: timed out waiting for unit addresses

[LOG] 21.39534 DEBUG juju worker/uniter: ModeAbide exiting
[LOG] 21.39537 INFO juju worker/uniter: unit "u/0" shutting down: tomb: dying
[LOG] 21.39551 ERROR juju worker/uniter/filter: tomb: dying
[LOG] 21.39641 INFO juju environs/dummy: reset environment
[LOG] 21.39668 DEBUG juju rpc/jsoncodec: <- error: read tcp 127.0.0.1:49150: use of closed network connection (closing true)
[LOG] 21.47412 INFO juju environs/testing: uploading FAKE tools 1.13.1-precise-amd64
[LOG] 21.47431 INFO juju environs: reading tools with major version 1
[LOG] 21.47433 DEBUG juju.agent.tools reading v1.* tools
[LOG] 21.47434 INFO juju environs: falling back to public bucket
[LOG] 21.47435 DEBUG juju.agent.tools reading v1.* tools
[LOG] 21.47449 DEBUG juju.agent.tools found 1.13.1-precise-amd64
[LOG] 21.47451 INFO juju environs: filtering tools by series: precise
[LOG] 21.47452 INFO juju environs: filtering tools by version: 1.13.1
[LOG] 21.47454 INFO juju environs/dummy: would pick tools from 1.13.1-precise-amd64
[LOG] 21.49150 INFO juju state: opening state; mongo addresses: ["localhost:34764"]; entity ""
[LOG] 21.49461 INFO juju state: connection established
[LOG] 21.53009 INFO juju state: initializing environment
[LOG] 21.56242 INFO juju state/api: listening on "127.0.0.1:49770"
[LOG] 21.57923 INFO juju state: opening state; mongo addresses: ["localhost:34764"]; entity ""
[LOG] 21.58329 INFO juju state: connection established
[LOG] 21.58471 INFO juju juju: authorization error while connecting to state server; retrying
[LOG] 21.58484 INFO juju state: opening state; mongo addresses: ["localhost:34764"]; entity ""
[LOG] 21.59366 INFO juju state: connection established
[LOG] 21.64761 INFO juju state/api: dialing "wss://127.0.0.1:49770/"
[LOG] 21.65658 INFO juju state/api: connection established
[LOG] 21.65703 DEBUG juju rpc/jsoncodec: <- {"RequestId":1,"Type":"Admin","Request":"Login","Params":{"AuthTag":"user-admin","Password":"dummy-secret","Nonce":""}}
[LOG] 21.65771 DEBUG juju rpc/jsoncodec: -> {"RequestId":1,"Response":{}}
[LOG] 21.67083 DEBUG juju rpc/jsoncodec: <- {"RequestId":2,"Type":"Pinger","Request":"Ping","Params":{}}
[LOG] 21.67094 DEBUG juju rpc/jsoncodec: -> {"RequestId":2,"Response":{}}
[LOG] 21.67186 INFO juju environs/dummy: reset environment
[LOG] 21.67199 DEBUG juju rpc/jsoncodec: <- error: read tcp 127.0.0.1:44092: use of closed network connection (closing true)
OOPS: 27 passed, 12 FAILED
--- FAIL: TestPackage (146.59 seconds)
FAIL
FAIL	launchpad.net/juju-core/worker/uniter	146.824s
ok  	launchpad.net/juju-core/worker/uniter/charm	2.265s
ok  	launchpad.net/juju-core/worker/uniter/debug	2.128s
ok  	launchpad.net/juju-core/worker/uniter/hook	0.024s
ok  	launchpad.net/juju-core/worker/uniter/jujuc	0.463s
ok  	launchpad.net/juju-core/worker/uniter/relation	1.367s
ok  	launchpad.net/juju-core/worker/upgrader	2.625s

warning: building out-of-date packages:
	launchpad.net/goamz/ec2/ec2test
	launchpad.net/goamz/s3/s3test
	launchpad.net/goose/testservices/hook
	launchpad.net/goose/testservices/identityservice
	launchpad.net/goose/testservices
	launchpad.net/goose/testservices/novaservice
	launchpad.net/goose/testservices/swiftservice
	launchpad.net/goose/testservices/openstackservice
installing these packages with 'go test -i ./...' will speed future tests.

Preview Diff

[H/L] Next/Prev Comment, [J/K] Next/Prev File, [N/P] Next/Prev Hunk

Subscribers

People subscribed via source and target branches

to all changes:

Aaron Bentley

Dave Cheney

Dimiter Naydenov

Eric Snow

John A Meinel

Martin Packman

Nate Finch

Raphaël Badin

Tim Penhey

to status/vote changes:

rowez

 === modified file 'state/machine_test.go'
 --- state/machine_test.go	2013-08-01 15:09:01 +0000
 +++ state/machine_test.go	2013-08-08 16:45:26 +0000
@@ -946,6 +946,7 @@
+ 	}
  	err = machine.SetAddresses(addresses)
  	c.Assert(err, IsNil)
--	machine.Refresh()
++	err = machine.Refresh()
++	c.Assert(err, IsNil)
  	c.Assert(machine.Addresses(), DeepEquals, addresses)
+ }
 === modified file 'state/unit.go'
 --- state/unit.go	2013-08-08 08:12:29 +0000
 +++ state/unit.go	2013-08-08 16:45:26 +0000
@@ -13,6 +13,8 @@
  	"labix.org/v2/mgo/bson"
  	"labix.org/v2/mgo/txn"
++	"launchpad.net/loggo"
++
  	"launchpad.net/juju-core/agent/tools"
  	"launchpad.net/juju-core/charm"
  	"launchpad.net/juju-core/constraints"
@@ -24,6 +26,8 @@
  	"launchpad.net/juju-core/utils"
+ )
++var unitLogger = loggo.GetLogger("juju.state.unit")
++
  // AssignmentPolicy controls what machine a unit will be assigned to.
  type AssignmentPolicy string
@@ -450,7 +454,20 @@
  // PublicAddress returns the public address of the unit and whether it is valid.
  func (u *Unit) PublicAddress() (string, bool) {
--	return u.doc.PublicAddress, u.doc.PublicAddress != ""
++	publicAddress := u.doc.PublicAddress
++	id := u.doc.MachineId
++	if id != "" {
++		m, err := u.st.Machine(id)
++		if err != nil {
++			unitLogger.Errorf("unit %v misses machine id %v", u, id)
++			return "", false
++		}
++		addresses := m.Addresses()
++		if len(addresses) > 0 {
++			publicAddress = instance.SelectPublicAddress(addresses)
++		}
++	}
++	return publicAddress, publicAddress != ""
+ }
  // PrivateAddress returns the private address of the unit and whether it is valid.
 === modified file 'state/unit_test.go'
 --- state/unit_test.go	2013-08-06 04:03:58 +0000
 +++ state/unit_test.go	2013-08-08 16:45:26 +0000
@@ -169,6 +169,28 @@
  	c.Assert(err, ErrorMatches, `cannot set public address of unit "wordpress/0": unit not found`)
+ }
++func (s *UnitSuite) TestGetPublicAddressFromMachine(c *C) {
++	machine, err := s.State.AddMachine("series", state.JobHostUnits)
++	c.Assert(err, IsNil)
++	err = s.unit.AssignToMachine(machine)
++	c.Assert(err, IsNil)
++
++	address, ok := s.unit.PublicAddress()
++	c.Check(address, Equals, "")
++	c.Assert(ok, Equals, false)
++
++	addresses := []instance.Address{
++		instance.NewAddress("127.0.0.1"),
++		instance.NewAddress("8.8.8.8"),
++	}
++	err = machine.SetAddresses(addresses)
++	c.Assert(err, IsNil)
++
++	address, ok = s.unit.PublicAddress()
++	c.Check(address, Equals, "8.8.8.8")
++	c.Assert(ok, Equals, true)
++}
++
  func (s *UnitSuite) TestGetSetPrivateAddress(c *C) {
  	_, ok := s.unit.PrivateAddress()
  	c.Assert(ok, Equals, false)