Merge into trunk : lxc-trusty-autostart : Code : juju-core

Reviewer	Review Type	Date Requested	Status
Juju Engineering		2014-01-24	Pending
Review via email: mp+202974@code.launchpad.net

Revision history for this message

Jesse Meek (waigani) wrote on 2014-01-24:

#

Reviewers: mp+202974_code.launchpad.net,

Message:
Please take a look.

Description:
Auto start for lxc containers

Auto start for lxc containers should now work on Trusty. Symlink is
created if /etc/lxc/auto exists, otherwise auto start is set to true in
lxc config.

https://code.launchpad.net/~waigani/juju-core/lxc-trusty-autostart/+merge/202974

(do not edit description out of merge proposal)

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

Affected files (+48, -6 lines):
   A [revision details]
   M container/lxc/lxc.go
   M container/lxc/lxc_test.go
   A container/lxc/restart.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: tarmac-20140124005805-5wkfrpv3q7zaknxv
+New revision: <email address hidden>

Index: container/lxc/lxc.go
=== modified file 'container/lxc/lxc.go'
--- container/lxc/lxc.go 2013-12-04 02:37:05 +0000
+++ container/lxc/lxc.go 2014-01-24 01:19:25 +0000
@@ -109,12 +109,14 @@
    return nil, nil, err
   }
   logger.Tracef("lxc container created")
- // Now symlink the config file into the restart directory.
- containerConfigFile := filepath.Join(LxcContainerDir, name, "config")
- if err := os.Symlink(containerConfigFile, restartSymlink(name)); err !=
nil {
- return nil, nil, err
+ // Now symlink the config file into the restart directory, if it exists
+ if restartDirExists() {
+ containerConfigFile := filepath.Join(LxcContainerDir, name, "config")
+ if err := os.Symlink(containerConfigFile, restartSymlink(name)); err !=
nil {
+ return nil, nil, err
+ }
+ logger.Tracef("auto-restart link created")
   }
- logger.Tracef("auto-restart link created")

   // Start the lxc container with the appropriate settings for grabbing the
   // console output and a log file.
@@ -198,7 +200,12 @@
  `

func networkConfigTemplate(networkType, networkLink string) string {
- return fmt.Sprintf(networkTemplate, networkType, networkLink)
+ networkConfig := fmt.Sprintf(networkTemplate, networkType, networkLink)
+ if !restartDirExists() {
+ networkConfig += "lxc.start.auto = 1\n"
+ logger.Tracef("Setting auto start to true in lxc config.")
+ }
+ return networkConfig
}

func generateNetworkConfig(network *container.NetworkConfig) string {

Index: container/lxc/lxc_test.go
=== modified file 'container/lxc/lxc_test.go'
--- container/lxc/lxc_test.go 2013-12-16 07:20:01 +0000
+++ container/lxc/lxc_test.go 2014-01-24 01:19:25 +0000
@@ -186,6 +186,25 @@
c.Assert(autostartLink, jc.IsSymlink)
}

+func (s *LxcSuite) TestStartContainerNoRestartDir(c *gc.C) {
+ err := os.Remove(s.RestartDir)
+ c.Assert(err, gc.IsNil)
+
+ manager := lxc.NewContainerManager(container.ManagerConfig{})
+ instance := containertesting.StartContainer(c, manager, "1/lxc/0")
+ autostartLink := lxc.RestartSymlink(string(instance.Id()))
+
+ config := lxc.NetworkConfigTemplate("foo", "bar")
+ expected := `
+lxc.network.type = foo
+lxc.network.link = bar
+lxc.network.flags = up
+lxc.start.auto = 1
+`
+ c.Assert(config, gc.Equals, expected)
+ c.Assert(autostartLink, jc.Does...

Reviewers: mp+202974_code.launchpad.net,

Message:
Please take a look.

Description:
Auto start for lxc containers

Auto start for lxc containers should now work on Trusty. Symlink is
created if /etc/lxc/auto exists, otherwise auto start is set to true in
lxc config.

https://code.launchpad.net/~waigani/juju-core/lxc-trusty-autostart/+merge/202974

(do not edit description out of merge proposal)

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

Affected files (+48, -6 lines):
   A [revision details]
   M container/lxc/lxc.go
   M container/lxc/lxc_test.go
   A container/lxc/restart.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: tarmac-20140124005805-5wkfrpv3q7zaknxv
+New revision: jesse.meek@canonical.com-20140124012753-qofd0e6qgcwp4etq

Index: container/lxc/lxc.go
=== modified file 'container/lxc/lxc.go'
--- container/lxc/lxc.go	2013-12-04 02:37:05 +0000
+++ container/lxc/lxc.go	2014-01-24 01:19:25 +0000
@@ -109,12 +109,14 @@
  		return nil, nil, err
  	}
  	logger.Tracef("lxc container created")
-	// Now symlink the config file into the restart directory.
-	containerConfigFile := filepath.Join(LxcContainerDir, name, "config")
-	if err := os.Symlink(containerConfigFile, restartSymlink(name)); err !=  
nil {
-		return nil, nil, err
+	// Now symlink the config file into the restart directory, if it exists
+	if restartDirExists() {
+		containerConfigFile := filepath.Join(LxcContainerDir, name, "config")
+		if err := os.Symlink(containerConfigFile, restartSymlink(name)); err !=  
nil {
+			return nil, nil, err
+		}
+		logger.Tracef("auto-restart link created")
  	}
-	logger.Tracef("auto-restart link created")

// Start the lxc container with the appropriate settings for grabbing the
  	// console output and a log file.
@@ -198,7 +200,12 @@
  `

func networkConfigTemplate(networkType, networkLink string) string {
-	return fmt.Sprintf(networkTemplate, networkType, networkLink)
+	networkConfig := fmt.Sprintf(networkTemplate, networkType, networkLink)
+	if !restartDirExists() {
+		networkConfig += "lxc.start.auto = 1\n"
+		logger.Tracef("Setting auto start to true in lxc config.")
+	}
+	return networkConfig
  }

func generateNetworkConfig(network *container.NetworkConfig) string {

Index: container/lxc/lxc_test.go
=== modified file 'container/lxc/lxc_test.go'
--- container/lxc/lxc_test.go	2013-12-16 07:20:01 +0000
+++ container/lxc/lxc_test.go	2014-01-24 01:19:25 +0000
@@ -186,6 +186,25 @@
  	c.Assert(autostartLink, jc.IsSymlink)
  }

+func (s *LxcSuite) TestStartContainerNoRestartDir(c *gc.C) {
+	err := os.Remove(s.RestartDir)
+	c.Assert(err, gc.IsNil)
+
+	manager := lxc.NewContainerManager(container.ManagerConfig{})
+	instance := containertesting.StartContainer(c, manager, "1/lxc/0")
+	autostartLink := lxc.RestartSymlink(string(instance.Id()))
+
+	config := lxc.NetworkConfigTemplate("foo", "bar")
+	expected := `
+lxc.network.type = foo
+lxc.network.link = bar
+lxc.network.flags = up
+lxc.start.auto = 1
+`
+	c.Assert(config, gc.Equals, expected)
+	c.Assert(autostartLink, jc.DoesNotExist)
+}
+
  func (s *LxcSuite) TestStopContainerRemovesAutostartLink(c *gc.C) {
  	manager := lxc.NewContainerManager(container.ManagerConfig{})
  	instance := containertesting.StartContainer(c, manager, "1/lxc/0")

Index: container/lxc/restart.go
=== added file 'container/lxc/restart.go'
--- container/lxc/restart.go	1970-01-01 00:00:00 +0000
+++ container/lxc/restart.go	2014-01-24 01:19:25 +0000
@@ -0,0 +1,14 @@
+package lxc
+
+import (
+	"os"
+)
+
+func restartDirExists() bool {
+	if _, err := os.Stat(LxcRestartDir); err != nil {
+		if os.IsNotExist(err) {
+			return false
+		}
+	}
+	return true
+}

Revision history for this message

Andrew Wilkins (axwalk) wrote on 2014-01-24:

#

LGTM with comment embellishment

https://codereview.appspot.com/56370043/diff/1/container/lxc/lxc.go
File container/lxc/lxc.go (right):

https://codereview.appspot.com/56370043/diff/1/container/lxc/lxc.go#newcode112
container/lxc/lxc.go:112: // Now symlink the config file into the
restart directory, if it exists
I think it'd be good to add a comment as to when it does/doesn't exist.
i.e. that this is for backwards-compatibility, and the directory no
longer exists from trusty.

https://codereview.appspot.com/56370043/

Revision history for this message

William Reade (fwereade) wrote on 2014-01-24:

#

Nice, LGTM, please remember to link the bug to your branch:
https://bugs.launchpad.net/juju-core/+bug/1271941

https://codereview.appspot.com/56370043/

Revision history for this message

William Reade (fwereade) wrote on 2014-01-24:

#

On 2014/01/24 14:52:20, fwereade wrote:
> Nice, LGTM, please remember to link the bug to your branch:
> https://bugs.launchpad.net/juju-core/+bug/1271941

Except; wait. Do we know what happens on a machine that has a restart
dir from an earlier LXC version, but currently does not? I think it
might be better to depend on an explicit LXC version than on this
heuristic...

https://codereview.appspot.com/56370043/

Revision history for this message

Jesse Meek (waigani) wrote on 2014-01-26:

#

On 2014/01/24 14:53:50, fwereade wrote:
> On 2014/01/24 14:52:20, fwereade wrote:
> > Nice, LGTM, please remember to link the bug to your branch:
> > https://bugs.launchpad.net/juju-core/+bug/1271941

> Except; wait. Do we know what happens on a machine that has a restart
dir from
> an earlier LXC version, but currently does not? I think it might be
better to
> depend on an explicit LXC version than on this heuristic...

Good point. Any idea from what version LXC stopped using the restart
directory? Should we just use the version currently used in Trusty?

https://codereview.appspot.com/56370043/

Revision history for this message

Tim Penhey (thumper) wrote on 2014-01-26:

#

On 2014/01/24 14:53:50, fwereade wrote:
> On 2014/01/24 14:52:20, fwereade wrote:
> > Nice, LGTM, please remember to link the bug to your branch:
> > https://bugs.launchpad.net/juju-core/+bug/1271941

> Except; wait. Do we know what happens on a machine that has a restart
dir from
> an earlier LXC version, but currently does not? I think it might be
better to
> depend on an explicit LXC version than on this heuristic...

I talked with stgraber and hallyn about this, and they both said that
the
best approach is to use the /etc/lxc/auto directory if it is there.

As lxc gets upgraded, it updates all of the containers currently
auto-starting
using the directory and then removes the directory.

There will be no /etc/lxc/auto directory if it is not used by lxc.

https://codereview.appspot.com/56370043/

Revision history for this message

Tim Penhey (thumper) wrote on 2014-01-26:

#

https://codereview.appspot.com/56370043/diff/1/container/lxc/lxc.go
File container/lxc/lxc.go (right):

https://codereview.appspot.com/56370043/diff/1/container/lxc/lxc.go#newcode112
container/lxc/lxc.go:112: // Now symlink the config file into the
restart directory, if it exists
On 2014/01/24 02:19:17, axw wrote:
> I think it'd be good to add a comment as to when it does/doesn't
exist. i.e.
> that this is for backwards-compatibility, and the directory no longer
exists
> from trusty.

I agree that there should be more of a comment here to explain
intent.

https://codereview.appspot.com/56370043/

Revision history for this message

Jesse Meek (waigani) wrote on 2014-01-26:

#

https://codereview.appspot.com/56370043/diff/1/container/lxc/lxc.go
File container/lxc/lxc.go (right):

https://codereview.appspot.com/56370043/diff/1/container/lxc/lxc.go#newcode112
container/lxc/lxc.go:112: // Now symlink the config file into the
restart directory, if it exists
On 2014/01/24 02:19:17, axw wrote:
> I think it'd be good to add a comment as to when it does/doesn't
exist. i.e.
> that this is for backwards-compatibility, and the directory no longer
exists
> from trusty.

Done.

https://codereview.appspot.com/56370043/

Revision history for this message

Go Bot (go-bot) wrote on 2014-01-26:

#

No approved revision specified.

Revision history for this message

Go Bot (go-bot) wrote on 2014-01-27:

#

Download full text (37.5 KiB)

The attempt to merge lp:~waigani/juju-core/lxc-trusty-autostart into lp:juju-core failed. Below is the output from the failed tests.

ok launchpad.net/juju-core/agent 0.817s
ok launchpad.net/juju-core/agent/tools 0.277s
ok launchpad.net/juju-core/bzr 7.531s
ok launchpad.net/juju-core/cert 3.643s
ok launchpad.net/juju-core/charm 0.583s
? launchpad.net/juju-core/charm/hooks [no test files]
? launchpad.net/juju-core/charm/testing [no test files]
ok launchpad.net/juju-core/cloudinit 0.032s
ok launchpad.net/juju-core/cloudinit/sshinit 1.112s
ok launchpad.net/juju-core/cmd 0.217s
ok launchpad.net/juju-core/cmd/charm-admin 0.843s
? launchpad.net/juju-core/cmd/charmd [no test files]
? launchpad.net/juju-core/cmd/charmload [no test files]
ok launchpad.net/juju-core/cmd/juju 236.908s
ok launchpad.net/juju-core/cmd/jujud 60.625s
ok launchpad.net/juju-core/cmd/plugins/juju-metadata 12.271s
? launchpad.net/juju-core/cmd/plugins/juju-restore [no test files]
ok launchpad.net/juju-core/constraints 0.029s
ok launchpad.net/juju-core/container 0.064s
ok launchpad.net/juju-core/container/factory 0.101s
ok launchpad.net/juju-core/container/kvm 0.289s
ok launchpad.net/juju-core/container/kvm/mock 0.039s
? launchpad.net/juju-core/container/kvm/testing [no test files]

----------------------------------------------------------------------
FAIL: lxc_test.go:251: NetworkSuite.TestNetworkConfigTemplate

lxc_test.go:258:
c.Assert(config, gc.Equals, expected)
... obtained string = "" +
... "\n" +
... "lxc.network.type = foo\n" +
... "lxc.network.link = bar\n" +
... "lxc.network.flags = up\n" +
... "lxc.start.auto = 1\n"
... expected string = "" +
... "\n" +
... "lxc.network.type = foo\n" +
... "lxc.network.link = bar\n" +
... "lxc.network.flags = up\n"

OOPS: 10 passed, 1 FAILED
--- FAIL: Test (0.08 seconds)
FAIL
FAIL launchpad.net/juju-core/container/lxc 0.351s
? launchpad.net/juju-core/container/lxc/mock [no test files]
? launchpad.net/juju-core/container/lxc/testing [no test files]
? launchpad.net/juju-core/container/testing [no test files]
ok launchpad.net/juju-core/downloader 5.253s
ok launchpad.net/juju-core/environs 3.185s
ok launchpad.net/juju-core/environs/bootstrap 4.531s
ok launchpad.net/juju-core/environs/cloudinit 0.661s
ok launchpad.net/juju-core/environs/config 3.678s
ok launchpad.net/juju-core/environs/configstore 0.041s
ok launchpad.net/juju-core/environs/filestorage 0.033s
ok launchpad.net/juju-core/environs/httpstorage 0.978s
ok launchpad.net/juju-core/environs/imagemetadata 0.491s
? launchpad.net/juju-core/environs/imagemetadata/testing [no test files]
ok launchpad.net/juju-core/environs/instances 0.058s
ok launchpad.net/juju-core/environs/jujutest 0.322s
ok launchpad.net/juju-core/environs/manual 12.310s
ok launchpad.net/juju-core/environs/simplestreams 0.327s
? launchpad.net/juju-core/environs/simplestreams/testing [no test files]
ok launchpad.net/juju-core/environs/sshstorage 1.274s
ok launchpad.net/juju-core/environs/storage 1.181s
ok launchpad.net/juju-core/environs/sync 34.084s
ok launchpad.net/juju-core/en...

The attempt to merge lp:~waigani/juju-core/lxc-trusty-autostart into lp:juju-core failed. Below is the output from the failed tests.

ok  	launchpad.net/juju-core/agent	0.817s
ok  	launchpad.net/juju-core/agent/tools	0.277s
ok  	launchpad.net/juju-core/bzr	7.531s
ok  	launchpad.net/juju-core/cert	3.643s
ok  	launchpad.net/juju-core/charm	0.583s
?   	launchpad.net/juju-core/charm/hooks	[no test files]
?   	launchpad.net/juju-core/charm/testing	[no test files]
ok  	launchpad.net/juju-core/cloudinit	0.032s
ok  	launchpad.net/juju-core/cloudinit/sshinit	1.112s
ok  	launchpad.net/juju-core/cmd	0.217s
ok  	launchpad.net/juju-core/cmd/charm-admin	0.843s
?   	launchpad.net/juju-core/cmd/charmd	[no test files]
?   	launchpad.net/juju-core/cmd/charmload	[no test files]
ok  	launchpad.net/juju-core/cmd/juju	236.908s
ok  	launchpad.net/juju-core/cmd/jujud	60.625s
ok  	launchpad.net/juju-core/cmd/plugins/juju-metadata	12.271s
?   	launchpad.net/juju-core/cmd/plugins/juju-restore	[no test files]
ok  	launchpad.net/juju-core/constraints	0.029s
ok  	launchpad.net/juju-core/container	0.064s
ok  	launchpad.net/juju-core/container/factory	0.101s
ok  	launchpad.net/juju-core/container/kvm	0.289s
ok  	launchpad.net/juju-core/container/kvm/mock	0.039s
?   	launchpad.net/juju-core/container/kvm/testing	[no test files]

----------------------------------------------------------------------
FAIL: lxc_test.go:251: NetworkSuite.TestNetworkConfigTemplate

lxc_test.go:258:
    c.Assert(config, gc.Equals, expected)
... obtained string = "" +
...     "\n" +
...     "lxc.network.type = foo\n" +
...     "lxc.network.link = bar\n" +
...     "lxc.network.flags = up\n" +
...     "lxc.start.auto = 1\n"
... expected string = "" +
...     "\n" +
...     "lxc.network.type = foo\n" +
...     "lxc.network.link = bar\n" +
...     "lxc.network.flags = up\n"

OOPS: 10 passed, 1 FAILED
--- FAIL: Test (0.08 seconds)
FAIL
FAIL	launchpad.net/juju-core/container/lxc	0.351s
?   	launchpad.net/juju-core/container/lxc/mock	[no test files]
?   	launchpad.net/juju-core/container/lxc/testing	[no test files]
?   	launchpad.net/juju-core/container/testing	[no test files]
ok  	launchpad.net/juju-core/downloader	5.253s
ok  	launchpad.net/juju-core/environs	3.185s
ok  	launchpad.net/juju-core/environs/bootstrap	4.531s
ok  	launchpad.net/juju-core/environs/cloudinit	0.661s
ok  	launchpad.net/juju-core/environs/config	3.678s
ok  	launchpad.net/juju-core/environs/configstore	0.041s
ok  	launchpad.net/juju-core/environs/filestorage	0.033s
ok  	launchpad.net/juju-core/environs/httpstorage	0.978s
ok  	launchpad.net/juju-core/environs/imagemetadata	0.491s
?   	launchpad.net/juju-core/environs/imagemetadata/testing	[no test files]
ok  	launchpad.net/juju-core/environs/instances	0.058s
ok  	launchpad.net/juju-core/environs/jujutest	0.322s
ok  	launchpad.net/juju-core/environs/manual	12.310s
ok  	launchpad.net/juju-core/environs/simplestreams	0.327s
?   	launchpad.net/juju-core/environs/simplestreams/testing	[no test files]
ok  	launchpad.net/juju-core/environs/sshstorage	1.274s
ok  	launchpad.net/juju-core/environs/storage	1.181s
ok  	launchpad.net/juju-core/environs/sync	34.084s
ok  	launchpad.net/juju-core/environs/testing	0.212s
ok  	launchpad.net/juju-core/environs/tools	7.006s
?   	launchpad.net/juju-core/environs/tools/testing	[no test files]
ok  	launchpad.net/juju-core/errors	0.016s
ok  	launchpad.net/juju-core/instance	0.025s
?   	launchpad.net/juju-core/instance/testing	[no test files]
ok  	launchpad.net/juju-core/juju	22.108s
ok  	launchpad.net/juju-core/juju/osenv	0.021s
?   	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.023s
ok  	launchpad.net/juju-core/names	0.040s
?   	launchpad.net/juju-core/provider	[no test files]
?   	launchpad.net/juju-core/provider/all	[no test files]
ok  	launchpad.net/juju-core/provider/azure	6.672s
ok  	launchpad.net/juju-core/provider/common	9.887s
ok  	launchpad.net/juju-core/provider/dummy	24.702s
ok  	launchpad.net/juju-core/provider/ec2	18.938s
ok  	launchpad.net/juju-core/provider/joyent	5.285s
ok  	launchpad.net/juju-core/provider/local	4.946s
ok  	launchpad.net/juju-core/provider/maas	19.255s
ok  	launchpad.net/juju-core/provider/null	1.189s
ok  	launchpad.net/juju-core/provider/openstack	39.978s
ok  	launchpad.net/juju-core/replicaset	44.027s
ok  	launchpad.net/juju-core/rpc	0.117s
ok  	launchpad.net/juju-core/rpc/jsoncodec	0.038s
?   	launchpad.net/juju-core/rpc/rpcreflect	[no test files]
ok  	launchpad.net/juju-core/schema	0.046s
ok  	launchpad.net/juju-core/state	90.250s
ok  	launchpad.net/juju-core/state/api	2.119s
ok  	launchpad.net/juju-core/state/api/agent	2.101s
?   	launchpad.net/juju-core/state/api/base	[no test files]
ok  	launchpad.net/juju-core/state/api/charmrevisionupdater	1.385s
?   	launchpad.net/juju-core/state/api/common	[no test files]
?   	launchpad.net/juju-core/state/api/common/testing	[no test files]
ok  	launchpad.net/juju-core/state/api/deployer	6.247s
ok  	launchpad.net/juju-core/state/api/firewaller	11.043s
ok  	launchpad.net/juju-core/state/api/keymanager	3.645s
ok  	launchpad.net/juju-core/state/api/keyupdater	2.800s
ok  	launchpad.net/juju-core/state/api/logger	2.424s
ok  	launchpad.net/juju-core/state/api/machiner	3.782s
ok  	launchpad.net/juju-core/state/api/params	0.036s
ok  	launchpad.net/juju-core/state/api/provisioner	10.108s
ok  	launchpad.net/juju-core/state/api/uniter	29.175s
ok  	launchpad.net/juju-core/state/api/upgrader	3.865s
ok  	launchpad.net/juju-core/state/api/watcher	3.451s
ok  	launchpad.net/juju-core/state/apiserver	8.060s
ok  	launchpad.net/juju-core/state/apiserver/agent	3.100s
ok  	launchpad.net/juju-core/state/apiserver/charmrevisionupdater	2.703s
?   	launchpad.net/juju-core/state/apiserver/charmrevisionupdater/testing	[no test files]
ok  	launchpad.net/juju-core/state/apiserver/client	44.687s
ok  	launchpad.net/juju-core/state/apiserver/common	2.208s
?   	launchpad.net/juju-core/state/apiserver/common/testing	[no test files]
ok  	launchpad.net/juju-core/state/apiserver/deployer	4.768s
ok  	launchpad.net/juju-core/state/apiserver/firewaller	6.156s
ok  	launchpad.net/juju-core/state/apiserver/keymanager	4.345s
?   	launchpad.net/juju-core/state/apiserver/keymanager/testing	[no test files]
ok  	launchpad.net/juju-core/state/apiserver/keyupdater	3.223s
ok  	launchpad.net/juju-core/state/apiserver/logger	3.689s
ok  	launchpad.net/juju-core/state/apiserver/machine	2.983s
ok  	launchpad.net/juju-core/state/apiserver/provisioner	12.371s
?   	launchpad.net/juju-core/state/apiserver/testing	[no test files]
ok  	launchpad.net/juju-core/state/apiserver/uniter	20.422s
ok  	launchpad.net/juju-core/state/apiserver/upgrader	6.369s
ok  	launchpad.net/juju-core/state/multiwatcher	0.766s
ok  	launchpad.net/juju-core/state/presence	6.537s
ok  	launchpad.net/juju-core/state/statecmd	2.239s
?   	launchpad.net/juju-core/state/testing	[no test files]
ok  	launchpad.net/juju-core/state/watcher	6.137s
ok  	launchpad.net/juju-core/store	19.830s
ok  	launchpad.net/juju-core/testing	1.424s
ok  	launchpad.net/juju-core/testing/checkers	0.051s
ok  	launchpad.net/juju-core/testing/testbase	0.020s
ok  	launchpad.net/juju-core/thirdparty/pbkdf2	0.126s
ok  	launchpad.net/juju-core/tools	0.020s
ok  	launchpad.net/juju-core/upstart	1.460s
ok  	launchpad.net/juju-core/utils	2.532s
ok  	launchpad.net/juju-core/utils/exec	0.038s
ok  	launchpad.net/juju-core/utils/fslock	1.479s
ok  	launchpad.net/juju-core/utils/parallel	0.915s
ok  	launchpad.net/juju-core/utils/set	0.018s
ok  	launchpad.net/juju-core/utils/ssh	20.914s
?   	launchpad.net/juju-core/utils/ssh/testing	[no test files]
ok  	launchpad.net/juju-core/utils/tailer	1.037s
ok  	launchpad.net/juju-core/version	0.070s
ok  	launchpad.net/juju-core/worker	6.686s
ok  	launchpad.net/juju-core/worker/authenticationworker	3.415s
ok  	launchpad.net/juju-core/worker/charmrevisionworker	2.330s
ok  	launchpad.net/juju-core/worker/cleaner	1.451s
ok  	launchpad.net/juju-core/worker/deployer	3.876s
ok  	launchpad.net/juju-core/worker/firewaller	13.095s
ok  	launchpad.net/juju-core/worker/instancepoller	2.381s
ok  	launchpad.net/juju-core/worker/localstorage	0.053s
ok  	launchpad.net/juju-core/worker/logger	1.802s
ok  	launchpad.net/juju-core/worker/machiner	3.422s
ok  	launchpad.net/juju-core/worker/minunitsworker	1.349s

----------------------------------------------------------------------
FAIL: provisioner_test.go:743: ProvisionerSuite.TestTurningOffSafeModeReapsUnknownInstances

[LOG] 66.77568 DEBUG juju.environs.configstore Making /tmp/gocheck-1976235410884491574/28/home/ubuntu/.juju/environments
[LOG] 66.87813 DEBUG juju.environs.tools reading v1.* tools
[LOG] 66.87818 INFO juju environs/testing: uploading FAKE tools 1.17.1-precise-amd64
[LOG] 66.87909 DEBUG juju.environs.tools no architecture specified when finding tools, looking for any
[LOG] 66.87911 DEBUG juju.environs.tools no series specified when finding tools, looking for any
[LOG] 66.87918 DEBUG juju.environs.simplestreams fetchData failed for "http://127.0.0.1:34823/dummyenv/private/tools/streams/v1/index.sjson": file "tools/streams/v1/index.sjson" not found not found
[LOG] 66.87920 DEBUG juju.environs.simplestreams cannot load index "http://127.0.0.1:34823/dummyenv/private/tools/streams/v1/index.sjson": invalid URL "http://127.0.0.1:34823/dummyenv/private/tools/streams/v1/index.sjson" not found
[LOG] 66.87924 DEBUG juju.environs.simplestreams fetchData failed for "http://127.0.0.1:34823/dummyenv/private/tools/streams/v1/index.json": file "tools/streams/v1/index.json" not found not found
[LOG] 66.87927 DEBUG juju.environs.simplestreams cannot load index "http://127.0.0.1:34823/dummyenv/private/tools/streams/v1/index.json": invalid URL "http://127.0.0.1:34823/dummyenv/private/tools/streams/v1/index.json" not found
[LOG] 66.87959 INFO juju.environs.tools Writing tools/streams/v1/index.json
[LOG] 66.87972 INFO juju.environs.tools Writing tools/streams/v1/com.ubuntu.juju:released:tools.json
[LOG] 66.87987 INFO juju.environs.bootstrap bootstrapping environment "dummyenv"
[LOG] 66.87990 DEBUG juju.environs.bootstrap looking for bootstrap tools: series="precise", arch=<nil>, version=1.17.1
[LOG] 66.87992 INFO juju.environs.tools reading tools with major.minor version 1.17
[LOG] 66.87993 INFO juju.environs.tools filtering tools by version: 1.17.1
[LOG] 66.87994 INFO juju.environs.tools filtering tools by series: precise
[LOG] 66.87997 DEBUG juju.environs.tools no architecture specified when finding tools, looking for any
[LOG] 66.88001 DEBUG juju.environs.simplestreams fetchData failed for "http://127.0.0.1:34823/dummyenv/private/tools/streams/v1/index.sjson": file "tools/streams/v1/index.sjson" not found not found
[LOG] 66.88003 DEBUG juju.environs.simplestreams cannot load index "http://127.0.0.1:34823/dummyenv/private/tools/streams/v1/index.sjson": invalid URL "http://127.0.0.1:34823/dummyenv/private/tools/streams/v1/index.sjson" not found
[LOG] 66.88013 DEBUG juju.environs.simplestreams fetchData failed for "http://127.0.0.1:34823/dummyenv/private/tools/streams/v1/mirrors.json": file "tools/streams/v1/mirrors.json" not found not found
[LOG] 66.88015 DEBUG juju.environs.simplestreams no mirror index file found
[LOG] 66.88017 DEBUG juju.environs.simplestreams no mirror information available for { }: mirror data for "com.ubuntu.juju:released:tools" not found
[LOG] 66.88019 DEBUG juju.environs.simplestreams read metadata index at "http://127.0.0.1:34823/dummyenv/private/tools/streams/v1/index.json"
[LOG] 66.88024 DEBUG juju.environs.simplestreams candidate matches for products ["com.ubuntu.juju:12.04:amd64" "com.ubuntu.juju:12.04:i386" "com.ubuntu.juju:12.04:arm"] are [{Mon, 27 Jan 2014 02:06:28 +0000 products:1.0 content-download  [] streams/v1/com.ubuntu.juju:released:tools.json [com.ubuntu.juju:12.04:amd64]}]
[LOG] 66.88025 DEBUG juju.environs.simplestreams finding products at path "streams/v1/com.ubuntu.juju:released:tools.json"
[LOG] 66.88047 DEBUG juju.environs.simplestreams metadata: &{map[com.ubuntu.juju:12.04:amd64:{ 1.17.1 amd64   map[20140127:0xc200872960]}] map[] Mon, 27 Jan 2014 02:06:28 +0000 products:1.0 com.ubuntu.juju:released:tools}
[LOG] 66.88052 INFO juju.environs.bootstrap picked newest version: 1.17.1
[LOG] 66.88055 INFO juju.provider.dummy would pick tools from 1.17.1-precise-amd64
[LOG] 66.88099 INFO juju.state opening state; mongo addresses: ["localhost:60450"]; entity ""
[LOG] 66.90219 INFO juju.state connection established
[LOG] 66.93149 INFO juju.state adding state server info to legacy environment
[LOG] 66.93220 INFO juju.state found existing state servers []
[LOG] 66.94837 INFO juju.state initializing environment
[LOG] 66.97002 INFO juju.state.apiserver listening on "127.0.0.1:40536"
[LOG] 66.98871 INFO juju.state opening state; mongo addresses: ["localhost:60450"]; entity ""
[LOG] 67.00467 INFO juju.state connection established
[LOG] 67.00571 INFO juju juju: authorization error while connecting to state server; retrying
[LOG] 67.00576 INFO juju.state opening state; mongo addresses: ["localhost:60450"]; entity ""
[LOG] 67.00906 INFO juju.state connection established
[LOG] 67.01263 INFO juju.state adding state server info to legacy environment
[LOG] 67.01289 INFO juju.state found existing state servers []
[LOG] 67.05390 INFO juju state/api: dialing "wss://127.0.0.1:40536/"
[LOG] 67.05715 INFO juju.state.apiserver [2D] API connection from 127.0.0.1:49199
[LOG] 67.05731 INFO juju state/api: connection established
[LOG] 67.05749 DEBUG juju.state.apiserver <- [2D] <unknown> {"RequestId":1,"Type":"Admin","Request":"Login","Params":{"AuthTag":"user-admin","Password":"dummy-secret","Nonce":""}}
[LOG] 67.05790 DEBUG juju.state.apiserver -> [2D] user-admin 419.159us {"RequestId":1,"Response":{}} Admin[""].Login
[LOG] 67.13205 INFO juju state/api: dialing "wss://127.0.0.1:40536/"
[LOG] 67.13609 INFO juju.state.apiserver [2E] API connection from 127.0.0.1:49200
[LOG] 67.13624 INFO juju state/api: connection established
[LOG] 67.13646 DEBUG juju.state.apiserver <- [2E] <unknown> {"RequestId":1,"Type":"Admin","Request":"Login","Params":{"AuthTag":"machine-0","Password":"23IRiPRiashtJEG646g8EN4l","Nonce":"fake_nonce"}}
[LOG] 67.16735 DEBUG juju.state.apiserver -> [2E] machine-0 30.801097ms {"RequestId":1,"Response":{}} Admin[""].Login
API: login as "machine-0" successful
[LOG] 67.16860 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":3,"Type":"Provisioner","Request":"WatchEnvironMachines","Params":{}}
[LOG] 67.17065 DEBUG juju.state.apiserver -> [2E] machine-0 2.013232ms {"RequestId":3,"Response":{"StringsWatcherId":"2","Changes":["0"],"Error":null}} Provisioner[""].WatchEnvironMachines
[LOG] 67.17260 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":4,"Type":"Provisioner","Request":"StateAddresses","Params":{}}
[LOG] 67.17316 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":5,"Type":"StringsWatcher","Id":"2","Request":"Next","Params":{}}
[LOG] 67.17680 DEBUG juju.state.apiserver -> [2E] machine-0 4.427871ms {"RequestId":4,"Response":{"Error":null,"Result":["0.1.2.3:1234"]}} Provisioner[""].StateAddresses
[LOG] 67.17708 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":6,"Type":"Provisioner","Request":"APIAddresses","Params":{}}
[LOG] 67.17831 DEBUG juju.state.apiserver -> [2E] machine-0 1.214071ms {"RequestId":6,"Response":{"Error":null,"Result":["0.1.2.3:4321"]}} Provisioner[""].APIAddresses
[LOG] 67.17859 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":7,"Type":"Provisioner","Request":"CACert","Params":{}}
[LOG] 67.17871 DEBUG juju.state.apiserver -> [2E] machine-0 72.61us {"RequestId":7,"Response":{"Result":"LS0tLS1CRUdJTiBDRVJUSUZJQ0FURS0tLS0tCk1JSUI0akNDQVk2Z0F3SUJBZ0lCQURBTEJna3Foa2lHOXcwQkFRVXdTakVOTUFzR0ExVUVDaE1FYW5WcWRURTUKTURjR0ExVUVBd3d3YW5WcWRTMW5aVzVsY21GMFpXUWdRMEVnWm05eUlHVnVkbWx5YjI1dFpXNTBJQ0pxZFdwMQpJSFJsYzNScGJtY2lNQjRYRFRFME1ERXlOekF5TURBME4xb1hEVEkwTURFeU56QXlNRFUwTjFvd1NqRU5NQXNHCkExVUVDaE1FYW5WcWRURTVNRGNHQTFVRUF3d3dhblZxZFMxblpXNWxjbUYwWldRZ1EwRWdabTl5SUdWdWRtbHkKYjI1dFpXNTBJQ0pxZFdwMUlIUmxjM1JwYm1jaU1Gb3dDd1lKS29aSWh2Y05BUUVCQTBzQU1FZ0NRUUM0R2R3Mwo1QUFlaEJrRmM5RWcyQXF4VndRdnZUK1NZSGFvMVQ4MWQyQlRSc1hVM0xRYndXVzYzeFhlbGV5YlRleVlWMWg3ClIxaFBqbjc2UEU2QnFJRmJBZ01CQUFHall6QmhNQTRHQTFVZER3RUIvd1FFQXdJQXBEQVBCZ05WSFJNQkFmOEUKQlRBREFRSC9NQjBHQTFVZERnUVdCQlJheW44aExhbXhBVmhPWjFNZVNtdnRFUFZuQXpBZkJnTlZIU01FR0RBVwpnQlJheW44aExhbXhBVmhPWjFNZVNtdnRFUFZuQXpBTEJna3Foa2lHOXcwQkFRVURRUUEvSjdQM3NBVlZROFBTCkE4MjRuc1ZNTkpTWFdFZWdoZWVtSTNDaW1ZVWtROFVXRU5Ic0lSamcwZzJOWUY4L1ZKa1QrSFE5V2U4Q3kwN2MKZjMrc0pidXAKLS0tLS1FTkQgQ0VSVElGSUNBVEUtLS0tLQo="}} Provisioner[""].CACert
[LOG] 67.17902 INFO juju.provisioner Starting up provisioner task machine-0
[LOG] 67.17964 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":8,"Type":"Provisioner","Request":"Life","Params":{"Entities":[{"Tag":"machine-0"}]}}
[LOG] 67.18044 DEBUG juju.state.apiserver -> [2E] machine-0 812.394us {"RequestId":8,"Response":{"Results":[{"Life":"alive","Error":null}]}} Provisioner[""].Life
[LOG] 67.18170 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":9,"Type":"Provisioner","Request":"InstanceId","Params":{"Entities":[{"Tag":"machine-0"}]}}
[LOG] 67.18256 DEBUG juju.state.apiserver -> [2E] machine-0 895.402us {"RequestId":9,"Response":{"Results":[{"Error":null,"Result":"i-fake"}]}} Provisioner[""].InstanceId
[LOG] 67.18300 INFO juju.provisioner machine 0 already started as instance "i-fake"
[LOG] 67.18340 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":10,"Type":"Provisioner","Request":"InstanceId","Params":{"Entities":[{"Tag":"machine-0"}]}}
[LOG] 67.18416 DEBUG juju.state.apiserver -> [2E] machine-0 765.631us {"RequestId":10,"Response":{"Results":[{"Error":null,"Result":"i-fake"}]}} Provisioner[""].InstanceId
[LOG] 67.18469 INFO juju.provisioner running in safe mode, unknown instances not stopped []
[LOG] 67.21068 DEBUG juju.state.apiserver -> [2E] machine-0 37.641718ms {"RequestId":5,"Response":{"StringsWatcherId":"","Changes":["1"],"Error":null}} StringsWatcher["2"].Next
[LOG] 67.21150 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":11,"Type":"StringsWatcher","Id":"2","Request":"Next","Params":{}}
[LOG] 67.21168 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":12,"Type":"Provisioner","Request":"Life","Params":{"Entities":[{"Tag":"machine-1"}]}}
[LOG] 67.21295 DEBUG juju.state.apiserver -> [2E] machine-0 1.232532ms {"RequestId":12,"Response":{"Results":[{"Life":"alive","Error":null}]}} Provisioner[""].Life
[LOG] 67.21364 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":13,"Type":"Provisioner","Request":"InstanceId","Params":{"Entities":[{"Tag":"machine-1"}]}}
[LOG] 67.21565 DEBUG juju.state.apiserver -> [2E] machine-0 1.864688ms {"RequestId":13,"Response":{"Results":[{"Error":{"Message":"machine 1 is not provisioned","Code":"not provisioned"},"Result":""}]}} Provisioner[""].InstanceId
[LOG] 67.21707 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":14,"Type":"Provisioner","Request":"Status","Params":{"Entities":[{"Tag":"machine-1"}]}}
[LOG] 67.21958 DEBUG juju.state.apiserver -> [2E] machine-0 2.403046ms {"RequestId":14,"Response":{"Results":[{"Error":null,"Status":"pending","Info":""}]}} Provisioner[""].Status
[LOG] 67.22042 INFO juju.provisioner found machine "1" pending provisioning
[LOG] 67.22112 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":15,"Type":"Provisioner","Request":"InstanceId","Params":{"Entities":[{"Tag":"machine-0"}]}}
[LOG] 67.22274 DEBUG juju.state.apiserver -> [2E] machine-0 1.654076ms {"RequestId":15,"Response":{"Results":[{"Error":null,"Result":"i-fake"}]}} Provisioner[""].InstanceId
[LOG] 67.22402 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":16,"Type":"Provisioner","Request":"InstanceId","Params":{"Entities":[{"Tag":"machine-1"}]}}
[LOG] 67.22462 DEBUG juju.state.apiserver -> [2E] machine-0 601.752us {"RequestId":16,"Response":{"Results":[{"Error":{"Message":"machine 1 is not provisioned","Code":"not provisioned"},"Result":""}]}} Provisioner[""].InstanceId
[LOG] 67.22479 INFO juju.provisioner running in safe mode, unknown instances not stopped []
[LOG] 67.22493 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":17,"Type":"Provisioner","Request":"Constraints","Params":{"Entities":[{"Tag":"machine-1"}]}}
[LOG] 67.22558 DEBUG juju.state.apiserver -> [2E] machine-0 603.504us {"RequestId":17,"Response":{"Results":[{"Error":null,"Constraints":{"arch":"amd64","cpu-cores":1,"mem":4096,"root-disk":8192}}]}} Provisioner[""].Constraints
[LOG] 67.22589 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":18,"Type":"Provisioner","Request":"Series","Params":{"Entities":[{"Tag":"machine-1"}]}}
[LOG] 67.22629 DEBUG juju.state.apiserver -> [2E] machine-0 389.11us {"RequestId":18,"Response":{"Results":[{"Error":null,"Result":"precise"}]}} Provisioner[""].Series
[LOG] 67.22647 INFO juju.environs.tools reading tools with major.minor version 1.17
[LOG] 67.22648 INFO juju.environs.tools filtering tools by version: 1.17.1
[LOG] 67.22649 INFO juju.environs.tools filtering tools by series: precise
[LOG] 67.22650 INFO juju.environs.tools filtering tools by architecture: amd64
[LOG] 67.22663 DEBUG juju.environs.simplestreams fetchData failed for "http://127.0.0.1:34823/dummyenv/private/tools/streams/v1/index.sjson": file "tools/streams/v1/index.sjson" not found not found
[LOG] 67.22666 DEBUG juju.environs.simplestreams cannot load index "http://127.0.0.1:34823/dummyenv/private/tools/streams/v1/index.sjson": invalid URL "http://127.0.0.1:34823/dummyenv/private/tools/streams/v1/index.sjson" not found
[LOG] 67.22680 DEBUG juju.environs.simplestreams fetchData failed for "http://127.0.0.1:34823/dummyenv/private/tools/streams/v1/mirrors.json": file "tools/streams/v1/mirrors.json" not found not found
[LOG] 67.22681 DEBUG juju.environs.simplestreams no mirror index file found
[LOG] 67.22684 DEBUG juju.environs.simplestreams no mirror information available for { }: mirror data for "com.ubuntu.juju:released:tools" not found
[LOG] 67.22685 DEBUG juju.environs.simplestreams read metadata index at "http://127.0.0.1:34823/dummyenv/private/tools/streams/v1/index.json"
[LOG] 67.22692 DEBUG juju.environs.simplestreams candidate matches for products ["com.ubuntu.juju:12.04:amd64"] are [{Mon, 27 Jan 2014 02:06:28 +0000 products:1.0 content-download  [] streams/v1/com.ubuntu.juju:released:tools.json [com.ubuntu.juju:12.04:amd64]}]
[LOG] 67.22694 DEBUG juju.environs.simplestreams finding products at path "streams/v1/com.ubuntu.juju:released:tools.json"
[LOG] 67.22727 DEBUG juju.environs.simplestreams metadata: &{map[com.ubuntu.juju:12.04:amd64:{ 1.17.1 amd64   map[20140127:0xc2009ae000]}] map[] Mon, 27 Jan 2014 02:06:28 +0000 products:1.0 com.ubuntu.juju:released:tools}
[LOG] 67.22755 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":19,"Type":"Provisioner","Request":"SetPasswords","Params":{"Changes":[{"Tag":"machine-1","Password":"ryjdqJYnOIvl6k4zYrvjfLTP"}]}}
[LOG] 67.23236 INFO juju setting password for "machine-1"
[LOG] 67.23242 DEBUG juju.state.apiserver -> [2E] machine-0 4.872145ms {"RequestId":19,"Response":{"Results":[{"Error":null}]}} Provisioner[""].SetPasswords
[LOG] 67.23260 INFO juju.provider.dummy dummy startinstance, machine 1
[LOG] 67.23264 INFO juju.provider.dummy would pick tools from 1.17.1-precise-amd64
[LOG] 67.23293 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":20,"Type":"Provisioner","Request":"SetProvisioned","Params":{"Machines":[{"Tag":"machine-1","InstanceId":"dummyenv-0","Nonce":"machine-0:f8103a17-3702-4429-802e-4a84c512d96c","Characteristics":{"Arch":"amd64","Mem":4096,"RootDisk":8192,"CpuCores":1,"CpuPower":null,"Tags":null}}]}}
machine 1 is still unprovisioned
[LOG] 67.24090 DEBUG juju.state.apiserver -> [2E] machine-0 7.983916ms {"RequestId":20,"Response":{"Results":[{"Error":null}]}} Provisioner[""].SetProvisioned
[LOG] 67.24110 INFO juju.provisioner started machine 1 as instance dummyenv-0 with hardware "arch=amd64 cpu-cores=1 mem=4096M root-disk=8192M"
[LOG] 67.33280 DEBUG juju.state.apiserver -> [2E] machine-0 121.212855ms {"RequestId":11,"Response":{"StringsWatcherId":"","Changes":["2"],"Error":null}} StringsWatcher["2"].Next
[LOG] 67.33375 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":21,"Type":"StringsWatcher","Id":"2","Request":"Next","Params":{}}
[LOG] 67.33396 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":22,"Type":"Provisioner","Request":"Life","Params":{"Entities":[{"Tag":"machine-2"}]}}
[LOG] 67.33498 DEBUG juju.state.apiserver -> [2E] machine-0 1.001393ms {"RequestId":22,"Response":{"Results":[{"Life":"alive","Error":null}]}} Provisioner[""].Life
[LOG] 67.33553 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":23,"Type":"Provisioner","Request":"InstanceId","Params":{"Entities":[{"Tag":"machine-2"}]}}
[LOG] 67.33760 DEBUG juju.state.apiserver -> [2E] machine-0 1.91728ms {"RequestId":23,"Response":{"Results":[{"Error":{"Message":"machine 2 is not provisioned","Code":"not provisioned"},"Result":""}]}} Provisioner[""].InstanceId
[LOG] 67.33891 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":24,"Type":"Provisioner","Request":"Status","Params":{"Entities":[{"Tag":"machine-2"}]}}
[LOG] 67.34143 DEBUG juju.state.apiserver -> [2E] machine-0 2.464908ms {"RequestId":24,"Response":{"Results":[{"Error":null,"Status":"pending","Info":""}]}} Provisioner[""].Status
[LOG] 67.34225 INFO juju.provisioner found machine "2" pending provisioning
[LOG] 67.34296 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":25,"Type":"Provisioner","Request":"InstanceId","Params":{"Entities":[{"Tag":"machine-0"}]}}
[LOG] 67.34414 DEBUG juju.state.apiserver -> [2E] machine-0 1.330202ms {"RequestId":25,"Response":{"Results":[{"Error":null,"Result":"i-fake"}]}} Provisioner[""].InstanceId
[LOG] 67.34444 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":26,"Type":"Provisioner","Request":"InstanceId","Params":{"Entities":[{"Tag":"machine-1"}]}}
[LOG] 67.34480 DEBUG juju.state.apiserver -> [2E] machine-0 354.418us {"RequestId":26,"Response":{"Results":[{"Error":null,"Result":"dummyenv-0"}]}} Provisioner[""].InstanceId
[LOG] 67.34510 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":27,"Type":"Provisioner","Request":"InstanceId","Params":{"Entities":[{"Tag":"machine-2"}]}}
[LOG] 67.34586 DEBUG juju.state.apiserver -> [2E] machine-0 767.989us {"RequestId":27,"Response":{"Results":[{"Error":{"Message":"machine 2 is not provisioned","Code":"not provisioned"},"Result":""}]}} Provisioner[""].InstanceId
[LOG] 67.34604 INFO juju.provisioner running in safe mode, unknown instances not stopped []
[LOG] 67.34861 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":28,"Type":"Provisioner","Request":"Constraints","Params":{"Entities":[{"Tag":"machine-2"}]}}
[LOG] 67.34948 DEBUG juju.state.apiserver -> [2E] machine-0 971.273us {"RequestId":28,"Response":{"Results":[{"Error":null,"Constraints":{"arch":"amd64","cpu-cores":1,"mem":4096,"root-disk":8192}}]}} Provisioner[""].Constraints
[LOG] 67.34983 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":29,"Type":"Provisioner","Request":"Series","Params":{"Entities":[{"Tag":"machine-2"}]}}
[LOG] 67.35018 DEBUG juju.state.apiserver -> [2E] machine-0 343.204us {"RequestId":29,"Response":{"Results":[{"Error":null,"Result":"precise"}]}} Provisioner[""].Series
[LOG] 67.35037 INFO juju.environs.tools reading tools with major.minor version 1.17
[LOG] 67.35041 INFO juju.environs.tools filtering tools by version: 1.17.1
[LOG] 67.35041 INFO juju.environs.tools filtering tools by series: precise
[LOG] 67.35042 INFO juju.environs.tools filtering tools by architecture: amd64
[LOG] 67.35056 DEBUG juju.environs.simplestreams fetchData failed for "http://127.0.0.1:34823/dummyenv/private/tools/streams/v1/index.sjson": file "tools/streams/v1/index.sjson" not found not found
[LOG] 67.35059 DEBUG juju.environs.simplestreams cannot load index "http://127.0.0.1:34823/dummyenv/private/tools/streams/v1/index.sjson": invalid URL "http://127.0.0.1:34823/dummyenv/private/tools/streams/v1/index.sjson" not found
[LOG] 67.35070 DEBUG juju.environs.simplestreams fetchData failed for "http://127.0.0.1:34823/dummyenv/private/tools/streams/v1/mirrors.json": file "tools/streams/v1/mirrors.json" not found not found
[LOG] 67.35074 DEBUG juju.environs.simplestreams no mirror index file found
[LOG] 67.35077 DEBUG juju.environs.simplestreams no mirror information available for { }: mirror data for "com.ubuntu.juju:released:tools" not found
[LOG] 67.35078 DEBUG juju.environs.simplestreams read metadata index at "http://127.0.0.1:34823/dummyenv/private/tools/streams/v1/index.json"
[LOG] 67.35085 DEBUG juju.environs.simplestreams candidate matches for products ["com.ubuntu.juju:12.04:amd64"] are [{Mon, 27 Jan 2014 02:06:28 +0000 products:1.0 content-download  [] streams/v1/com.ubuntu.juju:released:tools.json [com.ubuntu.juju:12.04:amd64]}]
[LOG] 67.35086 DEBUG juju.environs.simplestreams finding products at path "streams/v1/com.ubuntu.juju:released:tools.json"
[LOG] 67.35118 DEBUG juju.environs.simplestreams metadata: &{map[com.ubuntu.juju:12.04:amd64:{ 1.17.1 amd64   map[20140127:0xc2008351e0]}] map[] Mon, 27 Jan 2014 02:06:28 +0000 products:1.0 com.ubuntu.juju:released:tools}
[LOG] 67.35146 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":30,"Type":"Provisioner","Request":"SetPasswords","Params":{"Changes":[{"Tag":"machine-2","Password":"GV8brcWHZ0dvKh0hl2e0jIgH"}]}}
[LOG] 67.35563 INFO juju setting password for "machine-2"
[LOG] 67.35571 DEBUG juju.state.apiserver -> [2E] machine-0 4.25022ms {"RequestId":30,"Response":{"Results":[{"Error":null}]}} Provisioner[""].SetPasswords
[LOG] 67.35600 INFO juju.provider.dummy dummy startinstance, machine 2
[LOG] 67.35606 INFO juju.provider.dummy would pick tools from 1.17.1-precise-amd64
[LOG] 67.35666 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":31,"Type":"Provisioner","Request":"SetProvisioned","Params":{"Machines":[{"Tag":"machine-2","InstanceId":"dummyenv-1","Nonce":"machine-0:f6e4c9fb-f708-4aae-86f0-843dfc385cff","Characteristics":{"Arch":"amd64","Mem":4096,"RootDisk":8192,"CpuCores":1,"CpuPower":null,"Tags":null}}]}}
[LOG] 67.36249 DEBUG juju.state.apiserver -> [2E] machine-0 5.85045ms {"RequestId":31,"Response":{"Results":[{"Error":null}]}} Provisioner[""].SetProvisioned
[LOG] 67.36277 INFO juju.provisioner started machine 2 as instance dummyenv-1 with hardware "arch=amd64 cpu-cores=1 mem=4096M root-disk=8192M"
[LOG] 67.37450 DEBUG juju.state.apiserver -> [2E] machine-0 40.798443ms {"RequestId":21,"Response":{"StringsWatcherId":"","Changes":["2"],"Error":null}} StringsWatcher["2"].Next
[LOG] 67.37484 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":32,"Type":"StringsWatcher","Id":"2","Request":"Next","Params":{}}
[LOG] 67.37491 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":33,"Type":"Provisioner","Request":"Life","Params":{"Entities":[{"Tag":"machine-2"}]}}
[LOG] 67.37564 DEBUG juju.state.apiserver -> [2E] machine-0 716.332us {"RequestId":33,"Response":{"Results":[{"Life":"dead","Error":null}]}} Provisioner[""].Life
[LOG] 67.37591 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":34,"Type":"Provisioner","Request":"InstanceId","Params":{"Entities":[{"Tag":"machine-2"}]}}
[LOG] 67.37664 DEBUG juju.state.apiserver -> [2E] machine-0 715.092us {"RequestId":34,"Response":{"Results":[{"Error":null,"Result":"dummyenv-1"}]}} Provisioner[""].InstanceId
[LOG] 67.37692 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":35,"Type":"Provisioner","Request":"InstanceId","Params":{"Entities":[{"Tag":"machine-0"}]}}
[LOG] 67.37750 DEBUG juju.state.apiserver -> [2E] machine-0 579.385us {"RequestId":35,"Response":{"Results":[{"Error":null,"Result":"i-fake"}]}} Provisioner[""].InstanceId
[LOG] 67.37780 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":36,"Type":"Provisioner","Request":"InstanceId","Params":{"Entities":[{"Tag":"machine-1"}]}}
[LOG] 67.37839 DEBUG juju.state.apiserver -> [2E] machine-0 610.358us {"RequestId":36,"Response":{"Results":[{"Error":null,"Result":"dummyenv-0"}]}} Provisioner[""].InstanceId
[LOG] 67.37872 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":37,"Type":"Provisioner","Request":"InstanceId","Params":{"Entities":[{"Tag":"machine-2"}]}}
[LOG] 67.37932 DEBUG juju.state.apiserver -> [2E] machine-0 651.803us {"RequestId":37,"Response":{"Results":[{"Error":null,"Result":"dummyenv-1"}]}} Provisioner[""].InstanceId
[LOG] 67.37948 INFO juju.provisioner running in safe mode, unknown instances not stopped []
[LOG] 67.37950 INFO juju.provisioner stopping known instances [0xc2009deb80]
[LOG] 67.37955 INFO juju.provisioner removing dead machine "2"
[LOG] 67.37968 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":38,"Type":"Provisioner","Request":"Remove","Params":{"Entities":[{"Tag":"machine-2"}]}}
provisioner_test.go:763:
    // with safe mode on, only one of the machines is stopped.
    s.checkStopSomeInstances(c, []instance.Instance{i0}, []instance.Instance{i1})
provisioner_test.go:232:
    c.Errorf("provisioner unexpectedly stopped instance %s", instId)
... Error: provisioner unexpectedly stopped instance dummyenv-1

[LOG] 67.41311 DEBUG juju.state.apiserver -> [2E] machine-0 38.225998ms {"RequestId":32,"Response":{"StringsWatcherId":"","Changes":["1"],"Error":null}} StringsWatcher["2"].Next
[LOG] 67.41341 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":39,"Type":"StringsWatcher","Id":"2","Request":"Next","Params":{}}
[LOG] 67.42269 DEBUG juju.state.apiserver -> [2E] machine-0 42.940162ms {"RequestId":38,"Response":{"Results":[{"Error":null}]}} Provisioner[""].Remove
[LOG] 67.42312 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":40,"Type":"Provisioner","Request":"Life","Params":{"Entities":[{"Tag":"machine-1"}]}}
[LOG] 67.42357 DEBUG juju.state.apiserver -> [2E] machine-0 426.751us {"RequestId":40,"Response":{"Results":[{"Life":"dead","Error":null}]}} Provisioner[""].Life
[LOG] 67.42387 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":41,"Type":"Provisioner","Request":"InstanceId","Params":{"Entities":[{"Tag":"machine-1"}]}}
[LOG] 67.42424 DEBUG juju.state.apiserver -> [2E] machine-0 401.816us {"RequestId":41,"Response":{"Results":[{"Error":null,"Result":"dummyenv-0"}]}} Provisioner[""].InstanceId
[LOG] 67.42451 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":42,"Type":"Provisioner","Request":"InstanceId","Params":{"Entities":[{"Tag":"machine-0"}]}}
[LOG] 67.42491 DEBUG juju.state.apiserver -> [2E] machine-0 366.546us {"RequestId":42,"Response":{"Results":[{"Error":null,"Result":"i-fake"}]}} Provisioner[""].InstanceId
[LOG] 67.42519 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":43,"Type":"Provisioner","Request":"InstanceId","Params":{"Entities":[{"Tag":"machine-1"}]}}
[LOG] 67.42562 DEBUG juju.state.apiserver -> [2E] machine-0 437.932us {"RequestId":43,"Response":{"Results":[{"Error":null,"Result":"dummyenv-0"}]}} Provisioner[""].InstanceId
[LOG] 67.42578 INFO juju.provisioner running in safe mode, unknown instances not stopped []
[LOG] 67.42580 INFO juju.provisioner stopping known instances [0xc200388800]
[LOG] 67.42583 INFO juju.provisioner removing dead machine "1"
[LOG] 67.42627 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":44,"Type":"Provisioner","Request":"Remove","Params":{"Entities":[{"Tag":"machine-1"}]}}
machine 1 is still dead
[LOG] 67.46473 DEBUG juju.state.apiserver -> [2E] machine-0 38.421898ms {"RequestId":44,"Response":{"Results":[{"Error":null}]}} Provisioner[""].Remove
[LOG] 67.49042 INFO juju.provisioner safe mode changed to false
[LOG] 67.49143 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":45,"Type":"Provisioner","Request":"InstanceId","Params":{"Entities":[{"Tag":"machine-0"}]}}
[LOG] 67.49369 DEBUG juju.state.apiserver -> [2E] machine-0 2.277713ms {"RequestId":45,"Response":{"Results":[{"Error":null,"Result":"i-fake"}]}} Provisioner[""].InstanceId
provisioner_test.go:768:
    s.checkStopInstances(c, i1)
provisioner_test.go:240:
    c.Fatalf("provisioner did not stop an instance")
... Error: provisioner did not stop an instance

[LOG] 69.50940 INFO juju.provisioner Shutting down provisioner task machine-0
[LOG] 69.50969 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":46,"Type":"StringsWatcher","Id":"2","Request":"Stop","Params":{}}
[LOG] 69.50980 DEBUG juju.state.apiserver -> [2E] machine-0 2.096384324s {"RequestId":39,"Error":"watcher has been stopped","ErrorCode":"stopped","Response":{}} StringsWatcher["2"].Next
[LOG] 69.50988 DEBUG juju.state.apiserver -> [2E] machine-0 212.912us {"RequestId":46,"Response":{}} StringsWatcher["2"].Stop
[LOG] 69.51134 INFO juju.provider.dummy reset environment
[LOG] 69.51186 INFO juju.state.apiserver [2D] API connection terminated after 2.454702823s
[LOG] 69.51249 INFO juju.state.apiserver [2E] API connection terminated after 2.376433297s
[LOG] 69.52889 INFO juju Reset successfully reset admin password
[LOG] 69.53760 INFO juju Reset successfully reset admin password
[LOG] 69.54642 INFO juju Reset successfully reset admin password
OOPS: 28 passed, 1 FAILED
--- FAIL: Test (42.51 seconds)
FAIL
FAIL	launchpad.net/juju-core/worker/provisioner	42.838s
ok  	launchpad.net/juju-core/worker/resumer	1.626s
ok  	launchpad.net/juju-core/worker/terminationworker	0.091s
ok  	launchpad.net/juju-core/worker/uniter	186.327s
ok  	launchpad.net/juju-core/worker/uniter/charm	5.101s
ok  	launchpad.net/juju-core/worker/uniter/debug	1.110s
ok  	launchpad.net/juju-core/worker/uniter/hook	0.021s
ok  	launchpad.net/juju-core/worker/uniter/jujuc	0.491s
ok  	launchpad.net/juju-core/worker/uniter/relation	1.384s
ok  	launchpad.net/juju-core/worker/upgrader	3.689s

warning: building out-of-date packages:
	launchpad.net/lpad
	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.

Revision history for this message

Jesse Meek (waigani) wrote on 2014-01-27:

#

Download full text (40.3 KiB)

All tests pass for me. What am I missing? Here are my steps:

$ cd ~/go/src/launchpad.net/juju-core/container/lxc
$ bzr switch lxc-trusty-autostart
$ go test
OK: 11 passed
PASS
ok launchpad.net/juju-core/container/lxc 0.149s
$ go test -gocheck.f TestNetworkConfigTemplate
OK: 1 passed
PASS
ok launchpad.net/juju-core/container/lxc 0.140s

On Mon, Jan 27, 2014 at 3:09 PM, Go Bot <email address hidden> wrote:

> The attempt to merge lp:~waigani/juju-core/lxc-trusty-autostart into
> lp:juju-core failed. Below is the output from the failed tests.
>
> ok launchpad.net/juju-core/agent 0.817s
> ok launchpad.net/juju-core/agent/tools 0.277s
> ok launchpad.net/juju-core/bzr 7.531s
> ok launchpad.net/juju-core/cert 3.643s
> ok launchpad.net/juju-core/charm 0.583s
> ? launchpad.net/juju-core/charm/hooks [no test files]
> ? launchpad.net/juju-core/charm/testing [no test files]
> ok launchpad.net/juju-core/cloudinit 0.032s
> ok launchpad.net/juju-core/cloudinit/sshinit 1.112s
> ok launchpad.net/juju-core/cmd 0.217s
> ok launchpad.net/juju-core/cmd/charm-admin 0.843s
> ? launchpad.net/juju-core/cmd/charmd [no test files]
> ? launchpad.net/juju-core/cmd/charmload [no test files]
> ok launchpad.net/juju-core/cmd/juju 236.908s
> ok launchpad.net/juju-core/cmd/jujud 60.625s
> ok launchpad.net/juju-core/cmd/plugins/juju-metadata 12.271s
> ? launchpad.net/juju-core/cmd/plugins/juju-restore [no test
> files]
> ok launchpad.net/juju-core/constraints 0.029s
> ok launchpad.net/juju-core/container 0.064s
> ok launchpad.net/juju-core/container/factory 0.101s
> ok launchpad.net/juju-core/container/kvm 0.289s
> ok launchpad.net/juju-core/container/kvm/mock 0.039s
> ? launchpad.net/juju-core/container/kvm/testing [no test files]
>
> ----------------------------------------------------------------------
> FAIL: lxc_test.go:251: NetworkSuite.TestNetworkConfigTemplate
>
> lxc_test.go:258:
> c.Assert(config, gc.Equals, expected)
> ... obtained string = "" +
> ... "\n" +
> ... "lxc.network.type = foo\n" +
> ... "lxc.network.link = bar\n" +
> ... "lxc.network.flags = up\n" +
> ... "lxc.start.auto = 1\n"
> ... expected string = "" +
> ... "\n" +
> ... "lxc.network.type = foo\n" +
> ... "lxc.network.link = bar\n" +
> ... "lxc.network.flags = up\n"
>
> OOPS: 10 passed, 1 FAILED
> --- FAIL: Test (0.08 seconds)
> FAIL
> FAIL launchpad.net/juju-core/container/lxc 0.351s
> ? launchpad.net/juju-core/container/lxc/mock [no test files]
> ? launchpad.net/juju-core/container/lxc/testing [no test files]
> ? launchpad.net/juju-core/container/testing [no test files]
> ok launchpad.net/juju-core/downloader 5.253s
> ok launchpad.net/juju-core/environs 3.185s
> ok launchpad.net/juju-core/environs/bootstrap 4.531s
> ok launchpad.net/juju-core/environs/cloudinit 0.661s
> ok launchpad.net/juju-core/environs/config 3.678s
> ok launchpad.net/juju-core...

All tests pass for me. What am I missing? Here are my steps:

$ cd ~/go/src/launchpad.net/juju-core/container/lxc
$ bzr switch lxc-trusty-autostart
$ go test
OK: 11 passed
PASS
ok   launchpad.net/juju-core/container/lxc 0.149s
$ go test -gocheck.f TestNetworkConfigTemplate
OK: 1 passed
PASS
ok   launchpad.net/juju-core/container/lxc 0.140s

On Mon, Jan 27, 2014 at 3:09 PM, Go Bot <goose-bot@arbash-meinel.com> wrote:

> The attempt to merge lp:~waigani/juju-core/lxc-trusty-autostart into
> lp:juju-core failed. Below is the output from the failed tests.
>
> ok      launchpad.net/juju-core/agent   0.817s
> ok      launchpad.net/juju-core/agent/tools     0.277s
> ok      launchpad.net/juju-core/bzr     7.531s
> ok      launchpad.net/juju-core/cert    3.643s
> ok      launchpad.net/juju-core/charm   0.583s
> ?       launchpad.net/juju-core/charm/hooks     [no test files]
> ?       launchpad.net/juju-core/charm/testing   [no test files]
> ok      launchpad.net/juju-core/cloudinit       0.032s
> ok      launchpad.net/juju-core/cloudinit/sshinit       1.112s
> ok      launchpad.net/juju-core/cmd     0.217s
> ok      launchpad.net/juju-core/cmd/charm-admin 0.843s
> ?       launchpad.net/juju-core/cmd/charmd      [no test files]
> ?       launchpad.net/juju-core/cmd/charmload   [no test files]
> ok      launchpad.net/juju-core/cmd/juju        236.908s
> ok      launchpad.net/juju-core/cmd/jujud       60.625s
> ok      launchpad.net/juju-core/cmd/plugins/juju-metadata       12.271s
> ?       launchpad.net/juju-core/cmd/plugins/juju-restore        [no test
> files]
> ok      launchpad.net/juju-core/constraints     0.029s
> ok      launchpad.net/juju-core/container       0.064s
> ok      launchpad.net/juju-core/container/factory       0.101s
> ok      launchpad.net/juju-core/container/kvm   0.289s
> ok      launchpad.net/juju-core/container/kvm/mock      0.039s
> ?       launchpad.net/juju-core/container/kvm/testing   [no test files]
>
> ----------------------------------------------------------------------
> FAIL: lxc_test.go:251: NetworkSuite.TestNetworkConfigTemplate
>
> lxc_test.go:258:
>     c.Assert(config, gc.Equals, expected)
> ... obtained string = "" +
> ...     "\n" +
> ...     "lxc.network.type = foo\n" +
> ...     "lxc.network.link = bar\n" +
> ...     "lxc.network.flags = up\n" +
> ...     "lxc.start.auto = 1\n"
> ... expected string = "" +
> ...     "\n" +
> ...     "lxc.network.type = foo\n" +
> ...     "lxc.network.link = bar\n" +
> ...     "lxc.network.flags = up\n"
>
> OOPS: 10 passed, 1 FAILED
> --- FAIL: Test (0.08 seconds)
> FAIL
> FAIL    launchpad.net/juju-core/container/lxc   0.351s
> ?       launchpad.net/juju-core/container/lxc/mock      [no test files]
> ?       launchpad.net/juju-core/container/lxc/testing   [no test files]
> ?       launchpad.net/juju-core/container/testing       [no test files]
> ok      launchpad.net/juju-core/downloader      5.253s
> ok      launchpad.net/juju-core/environs        3.185s
> ok      launchpad.net/juju-core/environs/bootstrap      4.531s
> ok      launchpad.net/juju-core/environs/cloudinit      0.661s
> ok      launchpad.net/juju-core/environs/config 3.678s
> ok      launchpad.net/juju-core/environs/configstore    0.041s
> ok      launchpad.net/juju-core/environs/filestorage    0.033s
> ok      launchpad.net/juju-core/environs/httpstorage    0.978s
> ok      launchpad.net/juju-core/environs/imagemetadata  0.491s
> ?       launchpad.net/juju-core/environs/imagemetadata/testing  [no test
> files]
> ok      launchpad.net/juju-core/environs/instances      0.058s
> ok      launchpad.net/juju-core/environs/jujutest       0.322s
> ok      launchpad.net/juju-core/environs/manual 12.310s
> ok      launchpad.net/juju-core/environs/simplestreams  0.327s
> ?       launchpad.net/juju-core/environs/simplestreams/testing  [no test
> files]
> ok      launchpad.net/juju-core/environs/sshstorage     1.274s
> ok      launchpad.net/juju-core/environs/storage        1.181s
> ok      launchpad.net/juju-core/environs/sync   34.084s
> ok      launchpad.net/juju-core/environs/testing        0.212s
> ok      launchpad.net/juju-core/environs/tools  7.006s
> ?       launchpad.net/juju-core/environs/tools/testing  [no test files]
> ok      launchpad.net/juju-core/errors  0.016s
> ok      launchpad.net/juju-core/instance        0.025s
> ?       launchpad.net/juju-core/instance/testing        [no test files]
> ok      launchpad.net/juju-core/juju    22.108s
> ok      launchpad.net/juju-core/juju/osenv      0.021s
> ?       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.023s
> ok      launchpad.net/juju-core/names   0.040s
> ?       launchpad.net/juju-core/provider        [no test files]
> ?       launchpad.net/juju-core/provider/all    [no test files]
> ok      launchpad.net/juju-core/provider/azure  6.672s
> ok      launchpad.net/juju-core/provider/common 9.887s
> ok      launchpad.net/juju-core/provider/dummy  24.702s
> ok      launchpad.net/juju-core/provider/ec2    18.938s
> ok      launchpad.net/juju-core/provider/joyent 5.285s
> ok      launchpad.net/juju-core/provider/local  4.946s
> ok      launchpad.net/juju-core/provider/maas   19.255s
> ok      launchpad.net/juju-core/provider/null   1.189s
> ok      launchpad.net/juju-core/provider/openstack      39.978s
> ok      launchpad.net/juju-core/replicaset      44.027s
> ok      launchpad.net/juju-core/rpc     0.117s
> ok      launchpad.net/juju-core/rpc/jsoncodec   0.038s
> ?       launchpad.net/juju-core/rpc/rpcreflect  [no test files]
> ok      launchpad.net/juju-core/schema  0.046s
> ok      launchpad.net/juju-core/state   90.250s
> ok      launchpad.net/juju-core/state/api       2.119s
> ok      launchpad.net/juju-core/state/api/agent 2.101s
> ?       launchpad.net/juju-core/state/api/base  [no test files]
> ok      launchpad.net/juju-core/state/api/charmrevisionupdater  1.385s
> ?       launchpad.net/juju-core/state/api/common        [no test files]
> ?       launchpad.net/juju-core/state/api/common/testing        [no test
> files]
> ok      launchpad.net/juju-core/state/api/deployer      6.247s
> ok      launchpad.net/juju-core/state/api/firewaller    11.043s
> ok      launchpad.net/juju-core/state/api/keymanager    3.645s
> ok      launchpad.net/juju-core/state/api/keyupdater    2.800s
> ok      launchpad.net/juju-core/state/api/logger        2.424s
> ok      launchpad.net/juju-core/state/api/machiner      3.782s
> ok      launchpad.net/juju-core/state/api/params        0.036s
> ok      launchpad.net/juju-core/state/api/provisioner   10.108s
> ok      launchpad.net/juju-core/state/api/uniter        29.175s
> ok      launchpad.net/juju-core/state/api/upgrader      3.865s
> ok      launchpad.net/juju-core/state/api/watcher       3.451s
> ok      launchpad.net/juju-core/state/apiserver 8.060s
> ok      launchpad.net/juju-core/state/apiserver/agent   3.100s
> ok      launchpad.net/juju-core/state/apiserver/charmrevisionupdater
>  2.703s
> ?
> launchpad.net/juju-core/state/apiserver/charmrevisionupdater/testing
>  [no test files]
> ok      launchpad.net/juju-core/state/apiserver/client  44.687s
> ok      launchpad.net/juju-core/state/apiserver/common  2.208s
> ?       launchpad.net/juju-core/state/apiserver/common/testing  [no test
> files]
> ok      launchpad.net/juju-core/state/apiserver/deployer        4.768s
> ok      launchpad.net/juju-core/state/apiserver/firewaller      6.156s
> ok      launchpad.net/juju-core/state/apiserver/keymanager      4.345s
> ?       launchpad.net/juju-core/state/apiserver/keymanager/testing
>  [no test files]
> ok      launchpad.net/juju-core/state/apiserver/keyupdater      3.223s
> ok      launchpad.net/juju-core/state/apiserver/logger  3.689s
> ok      launchpad.net/juju-core/state/apiserver/machine 2.983s
> ok      launchpad.net/juju-core/state/apiserver/provisioner     12.371s
> ?       launchpad.net/juju-core/state/apiserver/testing [no test files]
> ok      launchpad.net/juju-core/state/apiserver/uniter  20.422s
> ok      launchpad.net/juju-core/state/apiserver/upgrader        6.369s
> ok      launchpad.net/juju-core/state/multiwatcher      0.766s
> ok      launchpad.net/juju-core/state/presence  6.537s
> ok      launchpad.net/juju-core/state/statecmd  2.239s
> ?       launchpad.net/juju-core/state/testing   [no test files]
> ok      launchpad.net/juju-core/state/watcher   6.137s
> ok      launchpad.net/juju-core/store   19.830s
> ok      launchpad.net/juju-core/testing 1.424s
> ok      launchpad.net/juju-core/testing/checkers        0.051s
> ok      launchpad.net/juju-core/testing/testbase        0.020s
> ok      launchpad.net/juju-core/thirdparty/pbkdf2       0.126s
> ok      launchpad.net/juju-core/tools   0.020s
> ok      launchpad.net/juju-core/upstart 1.460s
> ok      launchpad.net/juju-core/utils   2.532s
> ok      launchpad.net/juju-core/utils/exec      0.038s
> ok      launchpad.net/juju-core/utils/fslock    1.479s
> ok      launchpad.net/juju-core/utils/parallel  0.915s
> ok      launchpad.net/juju-core/utils/set       0.018s
> ok      launchpad.net/juju-core/utils/ssh       20.914s
> ?       launchpad.net/juju-core/utils/ssh/testing       [no test files]
> ok      launchpad.net/juju-core/utils/tailer    1.037s
> ok      launchpad.net/juju-core/version 0.070s
> ok      launchpad.net/juju-core/worker  6.686s
> ok      launchpad.net/juju-core/worker/authenticationworker     3.415s
> ok      launchpad.net/juju-core/worker/charmrevisionworker      2.330s
> ok      launchpad.net/juju-core/worker/cleaner  1.451s
> ok      launchpad.net/juju-core/worker/deployer 3.876s
> ok      launchpad.net/juju-core/worker/firewaller       13.095s
> ok      launchpad.net/juju-core/worker/instancepoller   2.381s
> ok      launchpad.net/juju-core/worker/localstorage     0.053s
> ok      launchpad.net/juju-core/worker/logger   1.802s
> ok      launchpad.net/juju-core/worker/machiner 3.422s
> ok      launchpad.net/juju-core/worker/minunitsworker   1.349s
>
> ----------------------------------------------------------------------
> FAIL: provisioner_test.go:743:
> ProvisionerSuite.TestTurningOffSafeModeReapsUnknownInstances
>
> [LOG] 66.77568 DEBUG juju.environs.configstore Making
> /tmp/gocheck-1976235410884491574/28/home/ubuntu/.juju/environments
> [LOG] 66.87813 DEBUG juju.environs.tools reading v1.* tools
> [LOG] 66.87818 INFO juju environs/testing: uploading FAKE tools
> 1.17.1-precise-amd64
> [LOG] 66.87909 DEBUG juju.environs.tools no architecture specified when
> finding tools, looking for any
> [LOG] 66.87911 DEBUG juju.environs.tools no series specified when finding
> tools, looking for any
> [LOG] 66.87918 DEBUG juju.environs.simplestreams fetchData failed for "
> http://127.0.0.1:34823/dummyenv/private/tools/streams/v1/index.sjson":
> file "tools/streams/v1/index.sjson" not found not found
> [LOG] 66.87920 DEBUG juju.environs.simplestreams cannot load index "
> http://127.0.0.1:34823/dummyenv/private/tools/streams/v1/index.sjson":
> invalid URL "
> http://127.0.0.1:34823/dummyenv/private/tools/streams/v1/index.sjson" not
> found
> [LOG] 66.87924 DEBUG juju.environs.simplestreams fetchData failed for "
> http://127.0.0.1:34823/dummyenv/private/tools/streams/v1/index.json":
> file "tools/streams/v1/index.json" not found not found
> [LOG] 66.87927 DEBUG juju.environs.simplestreams cannot load index "
> http://127.0.0.1:34823/dummyenv/private/tools/streams/v1/index.json":
> invalid URL "
> http://127.0.0.1:34823/dummyenv/private/tools/streams/v1/index.json" not
> found
> [LOG] 66.87959 INFO juju.environs.tools Writing tools/streams/v1/index.json
> [LOG] 66.87972 INFO juju.environs.tools Writing
> tools/streams/v1/com.ubuntu.juju:released:tools.json
> [LOG] 66.87987 INFO juju.environs.bootstrap bootstrapping environment
> "dummyenv"
> [LOG] 66.87990 DEBUG juju.environs.bootstrap looking for bootstrap tools:
> series="precise", arch=<nil>, version=1.17.1
> [LOG] 66.87992 INFO juju.environs.tools reading tools with major.minor
> version 1.17
> [LOG] 66.87993 INFO juju.environs.tools filtering tools by version: 1.17.1
> [LOG] 66.87994 INFO juju.environs.tools filtering tools by series: precise
> [LOG] 66.87997 DEBUG juju.environs.tools no architecture specified when
> finding tools, looking for any
> [LOG] 66.88001 DEBUG juju.environs.simplestreams fetchData failed for "
> http://127.0.0.1:34823/dummyenv/private/tools/streams/v1/index.sjson":
> file "tools/streams/v1/index.sjson" not found not found
> [LOG] 66.88003 DEBUG juju.environs.simplestreams cannot load index "
> http://127.0.0.1:34823/dummyenv/private/tools/streams/v1/index.sjson":
> invalid URL "
> http://127.0.0.1:34823/dummyenv/private/tools/streams/v1/index.sjson" not
> found
> [LOG] 66.88013 DEBUG juju.environs.simplestreams fetchData failed for "
> http://127.0.0.1:34823/dummyenv/private/tools/streams/v1/mirrors.json":
> file "tools/streams/v1/mirrors.json" not found not found
> [LOG] 66.88015 DEBUG juju.environs.simplestreams no mirror index file found
> [LOG] 66.88017 DEBUG juju.environs.simplestreams no mirror information
> available for { }: mirror data for "com.ubuntu.juju:released:tools" not
> found
> [LOG] 66.88019 DEBUG juju.environs.simplestreams read metadata index at "
> http://127.0.0.1:34823/dummyenv/private/tools/streams/v1/index.json"
> [LOG] 66.88024 DEBUG juju.environs.simplestreams candidate matches for
> products ["com.ubuntu.juju:12.04:amd64" "com.ubuntu.juju:12.04:i386"
> "com.ubuntu.juju:12.04:arm"] are [{Mon, 27 Jan 2014 02:06:28 +0000
> products:1.0 content-download  []
> streams/v1/com.ubuntu.juju:released:tools.json
> [com.ubuntu.juju:12.04:amd64]}]
> [LOG] 66.88025 DEBUG juju.environs.simplestreams finding products at path
> "streams/v1/com.ubuntu.juju:released:tools.json"
> [LOG] 66.88047 DEBUG juju.environs.simplestreams metadata:
> &{map[com.ubuntu.juju:12.04:amd64:{ 1.17.1 amd64
> map[20140127:0xc200872960]}] map[] Mon, 27 Jan 2014 02:06:28 +0000
> products:1.0 com.ubuntu.juju:released:tools}
> [LOG] 66.88052 INFO juju.environs.bootstrap picked newest version: 1.17.1
> [LOG] 66.88055 INFO juju.provider.dummy would pick tools from
> 1.17.1-precise-amd64
> [LOG] 66.88099 INFO juju.state opening state; mongo addresses:
> ["localhost:60450"]; entity ""
> [LOG] 66.90219 INFO juju.state connection established
> [LOG] 66.93149 INFO juju.state adding state server info to legacy
> environment
> [LOG] 66.93220 INFO juju.state found existing state servers []
> [LOG] 66.94837 INFO juju.state initializing environment
> [LOG] 66.97002 INFO juju.state.apiserver listening on "127.0.0.1:40536"
> [LOG] 66.98871 INFO juju.state opening state; mongo addresses:
> ["localhost:60450"]; entity ""
> [LOG] 67.00467 INFO juju.state connection established
> [LOG] 67.00571 INFO juju juju: authorization error while connecting to
> state server; retrying
> [LOG] 67.00576 INFO juju.state opening state; mongo addresses:
> ["localhost:60450"]; entity ""
> [LOG] 67.00906 INFO juju.state connection established
> [LOG] 67.01263 INFO juju.state adding state server info to legacy
> environment
> [LOG] 67.01289 INFO juju.state found existing state servers []
> [LOG] 67.05390 INFO juju state/api: dialing "wss://127.0.0.1:40536/"
> [LOG] 67.05715 INFO juju.state.apiserver [2D] API connection from
> 127.0.0.1:49199
> [LOG] 67.05731 INFO juju state/api: connection established
> [LOG] 67.05749 DEBUG juju.state.apiserver <- [2D] <unknown>
> {"RequestId":1,"Type":"Admin","Request":"Login","Params":{"AuthTag":"user-admin","Password":"dummy-secret","Nonce":""}}
> [LOG] 67.05790 DEBUG juju.state.apiserver -> [2D] user-admin 419.159us
> {"RequestId":1,"Response":{}} Admin[""].Login
> [LOG] 67.13205 INFO juju state/api: dialing "wss://127.0.0.1:40536/"
> [LOG] 67.13609 INFO juju.state.apiserver [2E] API connection from
> 127.0.0.1:49200
> [LOG] 67.13624 INFO juju state/api: connection established
> [LOG] 67.13646 DEBUG juju.state.apiserver <- [2E] <unknown>
> {"RequestId":1,"Type":"Admin","Request":"Login","Params":{"AuthTag":"machine-0","Password":"23IRiPRiashtJEG646g8EN4l","Nonce":"fake_nonce"}}
> [LOG] 67.16735 DEBUG juju.state.apiserver -> [2E] machine-0 30.801097ms
> {"RequestId":1,"Response":{}} Admin[""].Login
> API: login as "machine-0" successful
> [LOG] 67.16860 DEBUG juju.state.apiserver <- [2E] machine-0
> {"RequestId":3,"Type":"Provisioner","Request":"WatchEnvironMachines","Params":{}}
> [LOG] 67.17065 DEBUG juju.state.apiserver -> [2E] machine-0 2.013232ms
> {"RequestId":3,"Response":{"StringsWatcherId":"2","Changes":["0"],"Error":null}}
> Provisioner[""].WatchEnvironMachines
> [LOG] 67.17260 DEBUG juju.state.apiserver <- [2E] machine-0
> {"RequestId":4,"Type":"Provisioner","Request":"StateAddresses","Params":{}}
> [LOG] 67.17316 DEBUG juju.state.apiserver <- [2E] machine-0
> {"RequestId":5,"Type":"StringsWatcher","Id":"2","Request":"Next","Params":{}}
> [LOG] 67.17680 DEBUG juju.state.apiserver -> [2E] machine-0 4.427871ms
> {"RequestId":4,"Response":{"Error":null,"Result":["0.1.2.3:1234"]}}
> Provisioner[""].StateAddresses
> [LOG] 67.17708 DEBUG juju.state.apiserver <- [2E] machine-0
> {"RequestId":6,"Type":"Provisioner","Request":"APIAddresses","Params":{}}
> [LOG] 67.17831 DEBUG juju.state.apiserver -> [2E] machine-0 1.214071ms
> {"RequestId":6,"Response":{"Error":null,"Result":["0.1.2.3:4321"]}}
> Provisioner[""].APIAddresses
> [LOG] 67.17859 DEBUG juju.state.apiserver <- [2E] machine-0
> {"RequestId":7,"Type":"Provisioner","Request":"CACert","Params":{}}
> [LOG] 67.17871 DEBUG juju.state.apiserver -> [2E] machine-0 72.61us
> {"RequestId":7,"Response":{"Result":"LS0tLS1CRUdJTiBDRVJUSUZJQ0FURS0tLS0tCk1JSUI0akNDQVk2Z0F3SUJBZ0lCQURBTEJna3Foa2lHOXcwQkFRVXdTakVOTUFzR0ExVUVDaE1FYW5WcWRURTUKTURjR0ExVUVBd3d3YW5WcWRTMW5aVzVsY21GMFpXUWdRMEVnWm05eUlHVnVkbWx5YjI1dFpXNTBJQ0pxZFdwMQpJSFJsYzNScGJtY2lNQjRYRFRFME1ERXlOekF5TURBME4xb1hEVEkwTURFeU56QXlNRFUwTjFvd1NqRU5NQXNHCkExVUVDaE1FYW5WcWRURTVNRGNHQTFVRUF3d3dhblZxZFMxblpXNWxjbUYwWldRZ1EwRWdabTl5SUdWdWRtbHkKYjI1dFpXNTBJQ0pxZFdwMUlIUmxjM1JwYm1jaU1Gb3dDd1lKS29aSWh2Y05BUUVCQTBzQU1FZ0NRUUM0R2R3Mwo1QUFlaEJrRmM5RWcyQXF4VndRdnZUK1NZSGFvMVQ4MWQyQlRSc1hVM0xRYndXVzYzeFhlbGV5YlRleVlWMWg3ClIxaFBqbjc2UEU2QnFJRmJBZ01CQUFHall6QmhNQTRHQTFVZER3RUIvd1FFQXdJQXBEQVBCZ05WSFJNQkFmOEUKQlRBREFRSC9NQjBHQTFVZERnUVdCQlJheW44aExhbXhBVmhPWjFNZVNtdnRFUFZuQXpBZkJnTlZIU01FR0RBVwpnQlJheW44aExhbXhBVmhPWjFNZVNtdnRFUFZuQXpBTEJna3Foa2lHOXcwQkFRVURRUUEvSjdQM3NBVlZROFBTCkE4MjRuc1ZNTkpTWFdFZWdoZWVtSTNDaW1ZVWtROFVXRU5Ic0lSamcwZzJOWUY4L1ZKa1QrSFE5V2U4Q3kwN2MKZjMrc0pidXAKLS0tLS1FTkQgQ0VSVElGSUNBVEUtLS0tLQo="}}
> Provisioner[""].CACert
> [LOG] 67.17902 INFO juju.provisioner Starting up provisioner task machine-0
> [LOG] 67.17964 DEBUG juju.state.apiserver <- [2E] machine-0
> {"RequestId":8,"Type":"Provisioner","Request":"Life","Params":{"Entities":[{"Tag":"machine-0"}]}}
> [LOG] 67.18044 DEBUG juju.state.apiserver -> [2E] machine-0 812.394us
> {"RequestId":8,"Response":{"Results":[{"Life":"alive","Error":null}]}}
> Provisioner[""].Life
> [LOG] 67.18170 DEBUG juju.state.apiserver <- [2E] machine-0
> {"RequestId":9,"Type":"Provisioner","Request":"InstanceId","Params":{"Entities":[{"Tag":"machine-0"}]}}
> [LOG] 67.18256 DEBUG juju.state.apiserver -> [2E] machine-0 895.402us
> {"RequestId":9,"Response":{"Results":[{"Error":null,"Result":"i-fake"}]}}
> Provisioner[""].InstanceId
> [LOG] 67.18300 INFO juju.provisioner machine 0 already started as instance
> "i-fake"
> [LOG] 67.18340 DEBUG juju.state.apiserver <- [2E] machine-0
> {"RequestId":10,"Type":"Provisioner","Request":"InstanceId","Params":{"Entities":[{"Tag":"machine-0"}]}}
> [LOG] 67.18416 DEBUG juju.state.apiserver -> [2E] machine-0 765.631us
> {"RequestId":10,"Response":{"Results":[{"Error":null,"Result":"i-fake"}]}}
> Provisioner[""].InstanceId
> [LOG] 67.18469 INFO juju.provisioner running in safe mode, unknown
> instances not stopped []
> [LOG] 67.21068 DEBUG juju.state.apiserver -> [2E] machine-0 37.641718ms
> {"RequestId":5,"Response":{"StringsWatcherId":"","Changes":["1"],"Error":null}}
> StringsWatcher["2"].Next
> [LOG] 67.21150 DEBUG juju.state.apiserver <- [2E] machine-0
> {"RequestId":11,"Type":"StringsWatcher","Id":"2","Request":"Next","Params":{}}
> [LOG] 67.21168 DEBUG juju.state.apiserver <- [2E] machine-0
> {"RequestId":12,"Type":"Provisioner","Request":"Life","Params":{"Entities":[{"Tag":"machine-1"}]}}
> [LOG] 67.21295 DEBUG juju.state.apiserver -> [2E] machine-0 1.232532ms
> {"RequestId":12,"Response":{"Results":[{"Life":"alive","Error":null}]}}
> Provisioner[""].Life
> [LOG] 67.21364 DEBUG juju.state.apiserver <- [2E] machine-0
> {"RequestId":13,"Type":"Provisioner","Request":"InstanceId","Params":{"Entities":[{"Tag":"machine-1"}]}}
> [LOG] 67.21565 DEBUG juju.state.apiserver -> [2E] machine-0 1.864688ms
> {"RequestId":13,"Response":{"Results":[{"Error":{"Message":"machine 1 is
> not provisioned","Code":"not provisioned"},"Result":""}]}}
> Provisioner[""].InstanceId
> [LOG] 67.21707 DEBUG juju.state.apiserver <- [2E] machine-0
> {"RequestId":14,"Type":"Provisioner","Request":"Status","Params":{"Entities":[{"Tag":"machine-1"}]}}
> [LOG] 67.21958 DEBUG juju.state.apiserver -> [2E] machine-0 2.403046ms
> {"RequestId":14,"Response":{"Results":[{"Error":null,"Status":"pending","Info":""}]}}
> Provisioner[""].Status
> [LOG] 67.22042 INFO juju.provisioner found machine "1" pending provisioning
> [LOG] 67.22112 DEBUG juju.state.apiserver <- [2E] machine-0
> {"RequestId":15,"Type":"Provisioner","Request":"InstanceId","Params":{"Entities":[{"Tag":"machine-0"}]}}
> [LOG] 67.22274 DEBUG juju.state.apiserver -> [2E] machine-0 1.654076ms
> {"RequestId":15,"Response":{"Results":[{"Error":null,"Result":"i-fake"}]}}
> Provisioner[""].InstanceId
> [LOG] 67.22402 DEBUG juju.state.apiserver <- [2E] machine-0
> {"RequestId":16,"Type":"Provisioner","Request":"InstanceId","Params":{"Entities":[{"Tag":"machine-1"}]}}
> [LOG] 67.22462 DEBUG juju.state.apiserver -> [2E] machine-0 601.752us
> {"RequestId":16,"Response":{"Results":[{"Error":{"Message":"machine 1 is
> not provisioned","Code":"not provisioned"},"Result":""}]}}
> Provisioner[""].InstanceId
> [LOG] 67.22479 INFO juju.provisioner running in safe mode, unknown
> instances not stopped []
> [LOG] 67.22493 DEBUG juju.state.apiserver <- [2E] machine-0
> {"RequestId":17,"Type":"Provisioner","Request":"Constraints","Params":{"Entities":[{"Tag":"machine-1"}]}}
> [LOG] 67.22558 DEBUG juju.state.apiserver -> [2E] machine-0 603.504us
> {"RequestId":17,"Response":{"Results":[{"Error":null,"Constraints":{"arch":"amd64","cpu-cores":1,"mem":4096,"root-disk":8192}}]}}
> Provisioner[""].Constraints
> [LOG] 67.22589 DEBUG juju.state.apiserver <- [2E] machine-0
> {"RequestId":18,"Type":"Provisioner","Request":"Series","Params":{"Entities":[{"Tag":"machine-1"}]}}
> [LOG] 67.22629 DEBUG juju.state.apiserver -> [2E] machine-0 389.11us
> {"RequestId":18,"Response":{"Results":[{"Error":null,"Result":"precise"}]}}
> Provisioner[""].Series
> [LOG] 67.22647 INFO juju.environs.tools reading tools with major.minor
> version 1.17
> [LOG] 67.22648 INFO juju.environs.tools filtering tools by version: 1.17.1
> [LOG] 67.22649 INFO juju.environs.tools filtering tools by series: precise
> [LOG] 67.22650 INFO juju.environs.tools filtering tools by architecture:
> amd64
> [LOG] 67.22663 DEBUG juju.environs.simplestreams fetchData failed for "
> http://127.0.0.1:34823/dummyenv/private/tools/streams/v1/index.sjson":
> file "tools/streams/v1/index.sjson" not found not found
> [LOG] 67.22666 DEBUG juju.environs.simplestreams cannot load index "
> http://127.0.0.1:34823/dummyenv/private/tools/streams/v1/index.sjson":
> invalid URL "
> http://127.0.0.1:34823/dummyenv/private/tools/streams/v1/index.sjson" not
> found
> [LOG] 67.22680 DEBUG juju.environs.simplestreams fetchData failed for "
> http://127.0.0.1:34823/dummyenv/private/tools/streams/v1/mirrors.json":
> file "tools/streams/v1/mirrors.json" not found not found
> [LOG] 67.22681 DEBUG juju.environs.simplestreams no mirror index file found
> [LOG] 67.22684 DEBUG juju.environs.simplestreams no mirror information
> available for { }: mirror data for "com.ubuntu.juju:released:tools" not
> found
> [LOG] 67.22685 DEBUG juju.environs.simplestreams read metadata index at "
> http://127.0.0.1:34823/dummyenv/private/tools/streams/v1/index.json"
> [LOG] 67.22692 DEBUG juju.environs.simplestreams candidate matches for
> products ["com.ubuntu.juju:12.04:amd64"] are [{Mon, 27 Jan 2014 02:06:28
> +0000 products:1.0 content-download  []
> streams/v1/com.ubuntu.juju:released:tools.json
> [com.ubuntu.juju:12.04:amd64]}]
> [LOG] 67.22694 DEBUG juju.environs.simplestreams finding products at path
> "streams/v1/com.ubuntu.juju:released:tools.json"
> [LOG] 67.22727 DEBUG juju.environs.simplestreams metadata:
> &{map[com.ubuntu.juju:12.04:amd64:{ 1.17.1 amd64
> map[20140127:0xc2009ae000]}] map[] Mon, 27 Jan 2014 02:06:28 +0000
> products:1.0 com.ubuntu.juju:released:tools}
> [LOG] 67.22755 DEBUG juju.state.apiserver <- [2E] machine-0
> {"RequestId":19,"Type":"Provisioner","Request":"SetPasswords","Params":{"Changes":[{"Tag":"machine-1","Password":"ryjdqJYnOIvl6k4zYrvjfLTP"}]}}
> [LOG] 67.23236 INFO juju setting password for "machine-1"
> [LOG] 67.23242 DEBUG juju.state.apiserver -> [2E] machine-0 4.872145ms
> {"RequestId":19,"Response":{"Results":[{"Error":null}]}}
> Provisioner[""].SetPasswords
> [LOG] 67.23260 INFO juju.provider.dummy dummy startinstance, machine 1
> [LOG] 67.23264 INFO juju.provider.dummy would pick tools from
> 1.17.1-precise-amd64
> [LOG] 67.23293 DEBUG juju.state.apiserver <- [2E] machine-0
> {"RequestId":20,"Type":"Provisioner","Request":"SetProvisioned","Params":{"Machines":[{"Tag":"machine-1","InstanceId":"dummyenv-0","Nonce":"machine-0:f8103a17-3702-4429-802e-4a84c512d96c","Characteristics":{"Arch":"amd64","Mem":4096,"RootDisk":8192,"CpuCores":1,"CpuPower":null,"Tags":null}}]}}
> machine 1 is still unprovisioned
> [LOG] 67.24090 DEBUG juju.state.apiserver -> [2E] machine-0 7.983916ms
> {"RequestId":20,"Response":{"Results":[{"Error":null}]}}
> Provisioner[""].SetProvisioned
> [LOG] 67.24110 INFO juju.provisioner started machine 1 as instance
> dummyenv-0 with hardware "arch=amd64 cpu-cores=1 mem=4096M root-disk=8192M"
> [LOG] 67.33280 DEBUG juju.state.apiserver -> [2E] machine-0 121.212855ms
> {"RequestId":11,"Response":{"StringsWatcherId":"","Changes":["2"],"Error":null}}
> StringsWatcher["2"].Next
> [LOG] 67.33375 DEBUG juju.state.apiserver <- [2E] machine-0
> {"RequestId":21,"Type":"StringsWatcher","Id":"2","Request":"Next","Params":{}}
> [LOG] 67.33396 DEBUG juju.state.apiserver <- [2E] machine-0
> {"RequestId":22,"Type":"Provisioner","Request":"Life","Params":{"Entities":[{"Tag":"machine-2"}]}}
> [LOG] 67.33498 DEBUG juju.state.apiserver -> [2E] machine-0 1.001393ms
> {"RequestId":22,"Response":{"Results":[{"Life":"alive","Error":null}]}}
> Provisioner[""].Life
> [LOG] 67.33553 DEBUG juju.state.apiserver <- [2E] machine-0
> {"RequestId":23,"Type":"Provisioner","Request":"InstanceId","Params":{"Entities":[{"Tag":"machine-2"}]}}
> [LOG] 67.33760 DEBUG juju.state.apiserver -> [2E] machine-0 1.91728ms
> {"RequestId":23,"Response":{"Results":[{"Error":{"Message":"machine 2 is
> not provisioned","Code":"not provisioned"},"Result":""}]}}
> Provisioner[""].InstanceId
> [LOG] 67.33891 DEBUG juju.state.apiserver <- [2E] machine-0
> {"RequestId":24,"Type":"Provisioner","Request":"Status","Params":{"Entities":[{"Tag":"machine-2"}]}}
> [LOG] 67.34143 DEBUG juju.state.apiserver -> [2E] machine-0 2.464908ms
> {"RequestId":24,"Response":{"Results":[{"Error":null,"Status":"pending","Info":""}]}}
> Provisioner[""].Status
> [LOG] 67.34225 INFO juju.provisioner found machine "2" pending provisioning
> [LOG] 67.34296 DEBUG juju.state.apiserver <- [2E] machine-0
> {"RequestId":25,"Type":"Provisioner","Request":"InstanceId","Params":{"Entities":[{"Tag":"machine-0"}]}}
> [LOG] 67.34414 DEBUG juju.state.apiserver -> [2E] machine-0 1.330202ms
> {"RequestId":25,"Response":{"Results":[{"Error":null,"Result":"i-fake"}]}}
> Provisioner[""].InstanceId
> [LOG] 67.34444 DEBUG juju.state.apiserver <- [2E] machine-0
> {"RequestId":26,"Type":"Provisioner","Request":"InstanceId","Params":{"Entities":[{"Tag":"machine-1"}]}}
> [LOG] 67.34480 DEBUG juju.state.apiserver -> [2E] machine-0 354.418us
> {"RequestId":26,"Response":{"Results":[{"Error":null,"Result":"dummyenv-0"}]}}
> Provisioner[""].InstanceId
> [LOG] 67.34510 DEBUG juju.state.apiserver <- [2E] machine-0
> {"RequestId":27,"Type":"Provisioner","Request":"InstanceId","Params":{"Entities":[{"Tag":"machine-2"}]}}
> [LOG] 67.34586 DEBUG juju.state.apiserver -> [2E] machine-0 767.989us
> {"RequestId":27,"Response":{"Results":[{"Error":{"Message":"machine 2 is
> not provisioned","Code":"not provisioned"},"Result":""}]}}
> Provisioner[""].InstanceId
> [LOG] 67.34604 INFO juju.provisioner running in safe mode, unknown
> instances not stopped []
> [LOG] 67.34861 DEBUG juju.state.apiserver <- [2E] machine-0
> {"RequestId":28,"Type":"Provisioner","Request":"Constraints","Params":{"Entities":[{"Tag":"machine-2"}]}}
> [LOG] 67.34948 DEBUG juju.state.apiserver -> [2E] machine-0 971.273us
> {"RequestId":28,"Response":{"Results":[{"Error":null,"Constraints":{"arch":"amd64","cpu-cores":1,"mem":4096,"root-disk":8192}}]}}
> Provisioner[""].Constraints
> [LOG] 67.34983 DEBUG juju.state.apiserver <- [2E] machine-0
> {"RequestId":29,"Type":"Provisioner","Request":"Series","Params":{"Entities":[{"Tag":"machine-2"}]}}
> [LOG] 67.35018 DEBUG juju.state.apiserver -> [2E] machine-0 343.204us
> {"RequestId":29,"Response":{"Results":[{"Error":null,"Result":"precise"}]}}
> Provisioner[""].Series
> [LOG] 67.35037 INFO juju.environs.tools reading tools with major.minor
> version 1.17
> [LOG] 67.35041 INFO juju.environs.tools filtering tools by version: 1.17.1
> [LOG] 67.35041 INFO juju.environs.tools filtering tools by series: precise
> [LOG] 67.35042 INFO juju.environs.tools filtering tools by architecture:
> amd64
> [LOG] 67.35056 DEBUG juju.environs.simplestreams fetchData failed for "
> http://127.0.0.1:34823/dummyenv/private/tools/streams/v1/index.sjson":
> file "tools/streams/v1/index.sjson" not found not found
> [LOG] 67.35059 DEBUG juju.environs.simplestreams cannot load index "
> http://127.0.0.1:34823/dummyenv/private/tools/streams/v1/index.sjson":
> invalid URL "
> http://127.0.0.1:34823/dummyenv/private/tools/streams/v1/index.sjson" not
> found
> [LOG] 67.35070 DEBUG juju.environs.simplestreams fetchData failed for "
> http://127.0.0.1:34823/dummyenv/private/tools/streams/v1/mirrors.json":
> file "tools/streams/v1/mirrors.json" not found not found
> [LOG] 67.35074 DEBUG juju.environs.simplestreams no mirror index file found
> [LOG] 67.35077 DEBUG juju.environs.simplestreams no mirror information
> available for { }: mirror data for "com.ubuntu.juju:released:tools" not
> found
> [LOG] 67.35078 DEBUG juju.environs.simplestreams read metadata index at "
> http://127.0.0.1:34823/dummyenv/private/tools/streams/v1/index.json"
> [LOG] 67.35085 DEBUG juju.environs.simplestreams candidate matches for
> products ["com.ubuntu.juju:12.04:amd64"] are [{Mon, 27 Jan 2014 02:06:28
> +0000 products:1.0 content-download  []
> streams/v1/com.ubuntu.juju:released:tools.json
> [com.ubuntu.juju:12.04:amd64]}]
> [LOG] 67.35086 DEBUG juju.environs.simplestreams finding products at path
> "streams/v1/com.ubuntu.juju:released:tools.json"
> [LOG] 67.35118 DEBUG juju.environs.simplestreams metadata:
> &{map[com.ubuntu.juju:12.04:amd64:{ 1.17.1 amd64
> map[20140127:0xc2008351e0]}] map[] Mon, 27 Jan 2014 02:06:28 +0000
> products:1.0 com.ubuntu.juju:released:tools}
> [LOG] 67.35146 DEBUG juju.state.apiserver <- [2E] machine-0
> {"RequestId":30,"Type":"Provisioner","Request":"SetPasswords","Params":{"Changes":[{"Tag":"machine-2","Password":"GV8brcWHZ0dvKh0hl2e0jIgH"}]}}
> [LOG] 67.35563 INFO juju setting password for "machine-2"
> [LOG] 67.35571 DEBUG juju.state.apiserver -> [2E] machine-0 4.25022ms
> {"RequestId":30,"Response":{"Results":[{"Error":null}]}}
> Provisioner[""].SetPasswords
> [LOG] 67.35600 INFO juju.provider.dummy dummy startinstance, machine 2
> [LOG] 67.35606 INFO juju.provider.dummy would pick tools from
> 1.17.1-precise-amd64
> [LOG] 67.35666 DEBUG juju.state.apiserver <- [2E] machine-0
> {"RequestId":31,"Type":"Provisioner","Request":"SetProvisioned","Params":{"Machines":[{"Tag":"machine-2","InstanceId":"dummyenv-1","Nonce":"machine-0:f6e4c9fb-f708-4aae-86f0-843dfc385cff","Characteristics":{"Arch":"amd64","Mem":4096,"RootDisk":8192,"CpuCores":1,"CpuPower":null,"Tags":null}}]}}
> [LOG] 67.36249 DEBUG juju.state.apiserver -> [2E] machine-0 5.85045ms
> {"RequestId":31,"Response":{"Results":[{"Error":null}]}}
> Provisioner[""].SetProvisioned
> [LOG] 67.36277 INFO juju.provisioner started machine 2 as instance
> dummyenv-1 with hardware "arch=amd64 cpu-cores=1 mem=4096M root-disk=8192M"
> [LOG] 67.37450 DEBUG juju.state.apiserver -> [2E] machine-0 40.798443ms
> {"RequestId":21,"Response":{"StringsWatcherId":"","Changes":["2"],"Error":null}}
> StringsWatcher["2"].Next
> [LOG] 67.37484 DEBUG juju.state.apiserver <- [2E] machine-0
> {"RequestId":32,"Type":"StringsWatcher","Id":"2","Request":"Next","Params":{}}
> [LOG] 67.37491 DEBUG juju.state.apiserver <- [2E] machine-0
> {"RequestId":33,"Type":"Provisioner","Request":"Life","Params":{"Entities":[{"Tag":"machine-2"}]}}
> [LOG] 67.37564 DEBUG juju.state.apiserver -> [2E] machine-0 716.332us
> {"RequestId":33,"Response":{"Results":[{"Life":"dead","Error":null}]}}
> Provisioner[""].Life
> [LOG] 67.37591 DEBUG juju.state.apiserver <- [2E] machine-0
> {"RequestId":34,"Type":"Provisioner","Request":"InstanceId","Params":{"Entities":[{"Tag":"machine-2"}]}}
> [LOG] 67.37664 DEBUG juju.state.apiserver -> [2E] machine-0 715.092us
> {"RequestId":34,"Response":{"Results":[{"Error":null,"Result":"dummyenv-1"}]}}
> Provisioner[""].InstanceId
> [LOG] 67.37692 DEBUG juju.state.apiserver <- [2E] machine-0
> {"RequestId":35,"Type":"Provisioner","Request":"InstanceId","Params":{"Entities":[{"Tag":"machine-0"}]}}
> [LOG] 67.37750 DEBUG juju.state.apiserver -> [2E] machine-0 579.385us
> {"RequestId":35,"Response":{"Results":[{"Error":null,"Result":"i-fake"}]}}
> Provisioner[""].InstanceId
> [LOG] 67.37780 DEBUG juju.state.apiserver <- [2E] machine-0
> {"RequestId":36,"Type":"Provisioner","Request":"InstanceId","Params":{"Entities":[{"Tag":"machine-1"}]}}
> [LOG] 67.37839 DEBUG juju.state.apiserver -> [2E] machine-0 610.358us
> {"RequestId":36,"Response":{"Results":[{"Error":null,"Result":"dummyenv-0"}]}}
> Provisioner[""].InstanceId
> [LOG] 67.37872 DEBUG juju.state.apiserver <- [2E] machine-0
> {"RequestId":37,"Type":"Provisioner","Request":"InstanceId","Params":{"Entities":[{"Tag":"machine-2"}]}}
> [LOG] 67.37932 DEBUG juju.state.apiserver -> [2E] machine-0 651.803us
> {"RequestId":37,"Response":{"Results":[{"Error":null,"Result":"dummyenv-1"}]}}
> Provisioner[""].InstanceId
> [LOG] 67.37948 INFO juju.provisioner running in safe mode, unknown
> instances not stopped []
> [LOG] 67.37950 INFO juju.provisioner stopping known instances
> [0xc2009deb80]
> [LOG] 67.37955 INFO juju.provisioner removing dead machine "2"
> [LOG] 67.37968 DEBUG juju.state.apiserver <- [2E] machine-0
> {"RequestId":38,"Type":"Provisioner","Request":"Remove","Params":{"Entities":[{"Tag":"machine-2"}]}}
> provisioner_test.go:763:
>     // with safe mode on, only one of the machines is stopped.
>     s.checkStopSomeInstances(c, []instance.Instance{i0},
> []instance.Instance{i1})
> provisioner_test.go:232:
>     c.Errorf("provisioner unexpectedly stopped instance %s", instId)
> ... Error: provisioner unexpectedly stopped instance dummyenv-1
>
> [LOG] 67.41311 DEBUG juju.state.apiserver -> [2E] machine-0 38.225998ms
> {"RequestId":32,"Response":{"StringsWatcherId":"","Changes":["1"],"Error":null}}
> StringsWatcher["2"].Next
> [LOG] 67.41341 DEBUG juju.state.apiserver <- [2E] machine-0
> {"RequestId":39,"Type":"StringsWatcher","Id":"2","Request":"Next","Params":{}}
> [LOG] 67.42269 DEBUG juju.state.apiserver -> [2E] machine-0 42.940162ms
> {"RequestId":38,"Response":{"Results":[{"Error":null}]}}
> Provisioner[""].Remove
> [LOG] 67.42312 DEBUG juju.state.apiserver <- [2E] machine-0
> {"RequestId":40,"Type":"Provisioner","Request":"Life","Params":{"Entities":[{"Tag":"machine-1"}]}}
> [LOG] 67.42357 DEBUG juju.state.apiserver -> [2E] machine-0 426.751us
> {"RequestId":40,"Response":{"Results":[{"Life":"dead","Error":null}]}}
> Provisioner[""].Life
> [LOG] 67.42387 DEBUG juju.state.apiserver <- [2E] machine-0
> {"RequestId":41,"Type":"Provisioner","Request":"InstanceId","Params":{"Entities":[{"Tag":"machine-1"}]}}
> [LOG] 67.42424 DEBUG juju.state.apiserver -> [2E] machine-0 401.816us
> {"RequestId":41,"Response":{"Results":[{"Error":null,"Result":"dummyenv-0"}]}}
> Provisioner[""].InstanceId
> [LOG] 67.42451 DEBUG juju.state.apiserver <- [2E] machine-0
> {"RequestId":42,"Type":"Provisioner","Request":"InstanceId","Params":{"Entities":[{"Tag":"machine-0"}]}}
> [LOG] 67.42491 DEBUG juju.state.apiserver -> [2E] machine-0 366.546us
> {"RequestId":42,"Response":{"Results":[{"Error":null,"Result":"i-fake"}]}}
> Provisioner[""].InstanceId
> [LOG] 67.42519 DEBUG juju.state.apiserver <- [2E] machine-0
> {"RequestId":43,"Type":"Provisioner","Request":"InstanceId","Params":{"Entities":[{"Tag":"machine-1"}]}}
> [LOG] 67.42562 DEBUG juju.state.apiserver -> [2E] machine-0 437.932us
> {"RequestId":43,"Response":{"Results":[{"Error":null,"Result":"dummyenv-0"}]}}
> Provisioner[""].InstanceId
> [LOG] 67.42578 INFO juju.provisioner running in safe mode, unknown
> instances not stopped []
> [LOG] 67.42580 INFO juju.provisioner stopping known instances
> [0xc200388800]
> [LOG] 67.42583 INFO juju.provisioner removing dead machine "1"
> [LOG] 67.42627 DEBUG juju.state.apiserver <- [2E] machine-0
> {"RequestId":44,"Type":"Provisioner","Request":"Remove","Params":{"Entities":[{"Tag":"machine-1"}]}}
> machine 1 is still dead
> [LOG] 67.46473 DEBUG juju.state.apiserver -> [2E] machine-0 38.421898ms
> {"RequestId":44,"Response":{"Results":[{"Error":null}]}}
> Provisioner[""].Remove
> [LOG] 67.49042 INFO juju.provisioner safe mode changed to false
> [LOG] 67.49143 DEBUG juju.state.apiserver <- [2E] machine-0
> {"RequestId":45,"Type":"Provisioner","Request":"InstanceId","Params":{"Entities":[{"Tag":"machine-0"}]}}
> [LOG] 67.49369 DEBUG juju.state.apiserver -> [2E] machine-0 2.277713ms
> {"RequestId":45,"Response":{"Results":[{"Error":null,"Result":"i-fake"}]}}
> Provisioner[""].InstanceId
> provisioner_test.go:768:
>     s.checkStopInstances(c, i1)
> provisioner_test.go:240:
>     c.Fatalf("provisioner did not stop an instance")
> ... Error: provisioner did not stop an instance
>
> [LOG] 69.50940 INFO juju.provisioner Shutting down provisioner task
> machine-0
> [LOG] 69.50969 DEBUG juju.state.apiserver <- [2E] machine-0
> {"RequestId":46,"Type":"StringsWatcher","Id":"2","Request":"Stop","Params":{}}
> [LOG] 69.50980 DEBUG juju.state.apiserver -> [2E] machine-0 2.096384324s
> {"RequestId":39,"Error":"watcher has been
> stopped","ErrorCode":"stopped","Response":{}} StringsWatcher["2"].Next
> [LOG] 69.50988 DEBUG juju.state.apiserver -> [2E] machine-0 212.912us
> {"RequestId":46,"Response":{}} StringsWatcher["2"].Stop
> [LOG] 69.51134 INFO juju.provider.dummy reset environment
> [LOG] 69.51186 INFO juju.state.apiserver [2D] API connection terminated
> after 2.454702823s
> [LOG] 69.51249 INFO juju.state.apiserver [2E] API connection terminated
> after 2.376433297s
> [LOG] 69.52889 INFO juju Reset successfully reset admin password
> [LOG] 69.53760 INFO juju Reset successfully reset admin password
> [LOG] 69.54642 INFO juju Reset successfully reset admin password
> OOPS: 28 passed, 1 FAILED
> --- FAIL: Test (42.51 seconds)
> FAIL
> FAIL    launchpad.net/juju-core/worker/provisioner      42.838s
> ok      launchpad.net/juju-core/worker/resumer  1.626s
> ok      launchpad.net/juju-core/worker/terminationworker        0.091s
> ok      launchpad.net/juju-core/worker/uniter   186.327s
> ok      launchpad.net/juju-core/worker/uniter/charm     5.101s
> ok      launchpad.net/juju-core/worker/uniter/debug     1.110s
> ok      launchpad.net/juju-core/worker/uniter/hook      0.021s
> ok      launchpad.net/juju-core/worker/uniter/jujuc     0.491s
> ok      launchpad.net/juju-core/worker/uniter/relation  1.384s
> ok      launchpad.net/juju-core/worker/upgrader 3.689s
>
> warning: building out-of-date packages:
>         launchpad.net/lpad
>         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.
>
>
> --
>
> https://code.launchpad.net/~waigani/juju-core/lxc-trusty-autostart/+merge/202974
> You are the owner of lp:~waigani/juju-core/lxc-trusty-autostart.
>

Revision history for this message

Dimiter Naydenov (dimitern) wrote on 2014-01-27:

#

Download full text (41.5 KiB)

-----BEGIN PGP SIGNED MESSAGE-----
Hash: SHA1

On 27.01.2014 04:27, Jesse Meek wrote:
> All tests pass for me. What am I missing? Here are my steps:

The bot is running precise, not trusty, which might imply you've
written the tests assuming they should be run on trusty?

Dimiter
>
> $ cd ~/go/src/launchpad.net/juju-core/container/lxc $ bzr switch
> lxc-trusty-autostart $ go test OK: 11 passed PASS ok
> launchpad.net/juju-core/container/lxc 0.149s $ go test -gocheck.f
> TestNetworkConfigTemplate OK: 1 passed PASS ok
> launchpad.net/juju-core/container/lxc 0.140s
>
>
>
> On Mon, Jan 27, 2014 at 3:09 PM, Go Bot
> <email address hidden> wrote:
>
>> The attempt to merge lp:~waigani/juju-core/lxc-trusty-autostart
>> into lp:juju-core failed. Below is the output from the failed
>> tests.
>>
>> ok launchpad.net/juju-core/agent 0.817s ok
>> launchpad.net/juju-core/agent/tools 0.277s ok
>> launchpad.net/juju-core/bzr 7.531s ok
>> launchpad.net/juju-core/cert 3.643s ok
>> launchpad.net/juju-core/charm 0.583s ?
>> launchpad.net/juju-core/charm/hooks [no test files] ?
>> launchpad.net/juju-core/charm/testing [no test files] ok
>> launchpad.net/juju-core/cloudinit 0.032s ok
>> launchpad.net/juju-core/cloudinit/sshinit 1.112s ok
>> launchpad.net/juju-core/cmd 0.217s ok
>> launchpad.net/juju-core/cmd/charm-admin 0.843s ?
>> launchpad.net/juju-core/cmd/charmd [no test files] ?
>> launchpad.net/juju-core/cmd/charmload [no test files] ok
>> launchpad.net/juju-core/cmd/juju 236.908s ok
>> launchpad.net/juju-core/cmd/jujud 60.625s ok
>> launchpad.net/juju-core/cmd/plugins/juju-metadata 12.271s ?
>> launchpad.net/juju-core/cmd/plugins/juju-restore [no test
>> files] ok launchpad.net/juju-core/constraints 0.029s ok
>> launchpad.net/juju-core/container 0.064s ok
>> launchpad.net/juju-core/container/factory 0.101s ok
>> launchpad.net/juju-core/container/kvm 0.289s ok
>> launchpad.net/juju-core/container/kvm/mock 0.039s ?
>> launchpad.net/juju-core/container/kvm/testing [no test files]
>>
>> ----------------------------------------------------------------------
>>
>>
FAIL: lxc_test.go:251: NetworkSuite.TestNetworkConfigTemplate
>>
>> lxc_test.go:258: c.Assert(config, gc.Equals, expected) ...
>> obtained string = "" + ... "\n" + ... "lxc.network.type =
>> foo\n" + ... "lxc.network.link = bar\n" + ...
>> "lxc.network.flags = up\n" + ... "lxc.start.auto = 1\n" ...
>> expected string = "" + ... "\n" + ... "lxc.network.type =
>> foo\n" + ... "lxc.network.link = bar\n" + ...
>> "lxc.network.flags = up\n"
>>
>> OOPS: 10 passed, 1 FAILED --- FAIL: Test (0.08 seconds) FAIL FAIL
>> launchpad.net/juju-core/container/lxc 0.351s ?
>> launchpad.net/juju-core/container/lxc/mock [no test files] ?
>> launchpad.net/juju-core/container/lxc/testing [no test files] ?
>> launchpad.net/juju-core/container/testing [no test files]
>> ok launchpad.net/juju-core/downloader 5.253s ok
>> launchpad.net/juju-core/environs 3.185s ok
>> launchpad.net/juju-core/environs/bootstrap 4.531s ok
>> launchpad.net/juju-cor...

-----BEGIN PGP SIGNED MESSAGE-----
Hash: SHA1

On 27.01.2014 04:27, Jesse Meek wrote:
> All tests pass for me. What am I missing? Here are my steps:

The bot is running precise, not trusty, which might imply you've
written the tests assuming they should be run on trusty?

Dimiter
> 
> $ cd ~/go/src/launchpad.net/juju-core/container/lxc $ bzr switch
> lxc-trusty-autostart $ go test OK: 11 passed PASS ok
> launchpad.net/juju-core/container/lxc 0.149s $ go test -gocheck.f
> TestNetworkConfigTemplate OK: 1 passed PASS ok
> launchpad.net/juju-core/container/lxc 0.140s
> 
> 
> 
> On Mon, Jan 27, 2014 at 3:09 PM, Go Bot
> <goose-bot@arbash-meinel.com> wrote:
> 
>> The attempt to merge lp:~waigani/juju-core/lxc-trusty-autostart
>> into lp:juju-core failed. Below is the output from the failed
>> tests.
>> 
>> ok      launchpad.net/juju-core/agent   0.817s ok
>> launchpad.net/juju-core/agent/tools     0.277s ok
>> launchpad.net/juju-core/bzr     7.531s ok
>> launchpad.net/juju-core/cert    3.643s ok
>> launchpad.net/juju-core/charm   0.583s ?
>> launchpad.net/juju-core/charm/hooks     [no test files] ?
>> launchpad.net/juju-core/charm/testing   [no test files] ok
>> launchpad.net/juju-core/cloudinit       0.032s ok
>> launchpad.net/juju-core/cloudinit/sshinit       1.112s ok
>> launchpad.net/juju-core/cmd     0.217s ok
>> launchpad.net/juju-core/cmd/charm-admin 0.843s ?
>> launchpad.net/juju-core/cmd/charmd      [no test files] ?
>> launchpad.net/juju-core/cmd/charmload   [no test files] ok
>> launchpad.net/juju-core/cmd/juju        236.908s ok
>> launchpad.net/juju-core/cmd/jujud       60.625s ok
>> launchpad.net/juju-core/cmd/plugins/juju-metadata       12.271s ?
>> launchpad.net/juju-core/cmd/plugins/juju-restore        [no test 
>> files] ok      launchpad.net/juju-core/constraints     0.029s ok
>> launchpad.net/juju-core/container       0.064s ok
>> launchpad.net/juju-core/container/factory       0.101s ok
>> launchpad.net/juju-core/container/kvm   0.289s ok
>> launchpad.net/juju-core/container/kvm/mock      0.039s ?
>> launchpad.net/juju-core/container/kvm/testing   [no test files]
>> 
>> ----------------------------------------------------------------------
>>
>> 
FAIL: lxc_test.go:251: NetworkSuite.TestNetworkConfigTemplate
>> 
>> lxc_test.go:258: c.Assert(config, gc.Equals, expected) ...
>> obtained string = "" + ...     "\n" + ...     "lxc.network.type =
>> foo\n" + ...     "lxc.network.link = bar\n" + ...
>> "lxc.network.flags = up\n" + ...     "lxc.start.auto = 1\n" ...
>> expected string = "" + ...     "\n" + ...     "lxc.network.type =
>> foo\n" + ...     "lxc.network.link = bar\n" + ...
>> "lxc.network.flags = up\n"
>> 
>> OOPS: 10 passed, 1 FAILED --- FAIL: Test (0.08 seconds) FAIL FAIL
>> launchpad.net/juju-core/container/lxc   0.351s ?
>> launchpad.net/juju-core/container/lxc/mock      [no test files] ?
>> launchpad.net/juju-core/container/lxc/testing   [no test files] ?
>> launchpad.net/juju-core/container/testing       [no test files] 
>> ok      launchpad.net/juju-core/downloader      5.253s ok
>> launchpad.net/juju-core/environs        3.185s ok
>> launchpad.net/juju-core/environs/bootstrap      4.531s ok
>> launchpad.net/juju-core/environs/cloudinit      0.661s ok
>> launchpad.net/juju-core/environs/config 3.678s ok
>> launchpad.net/juju-core/environs/configstore    0.041s ok
>> launchpad.net/juju-core/environs/filestorage    0.033s ok
>> launchpad.net/juju-core/environs/httpstorage    0.978s ok
>> launchpad.net/juju-core/environs/imagemetadata  0.491s ?
>> launchpad.net/juju-core/environs/imagemetadata/testing  [no test 
>> files] ok      launchpad.net/juju-core/environs/instances
>> 0.058s ok      launchpad.net/juju-core/environs/jujutest
>> 0.322s ok      launchpad.net/juju-core/environs/manual 12.310s ok
>> launchpad.net/juju-core/environs/simplestreams  0.327s ?
>> launchpad.net/juju-core/environs/simplestreams/testing  [no test 
>> files] ok      launchpad.net/juju-core/environs/sshstorage
>> 1.274s ok      launchpad.net/juju-core/environs/storage
>> 1.181s ok      launchpad.net/juju-core/environs/sync   34.084s ok
>> launchpad.net/juju-core/environs/testing        0.212s ok
>> launchpad.net/juju-core/environs/tools  7.006s ?
>> launchpad.net/juju-core/environs/tools/testing  [no test files] 
>> ok      launchpad.net/juju-core/errors  0.016s ok
>> launchpad.net/juju-core/instance        0.025s ?
>> launchpad.net/juju-core/instance/testing        [no test files] 
>> ok      launchpad.net/juju-core/juju    22.108s ok
>> launchpad.net/juju-core/juju/osenv      0.021s ?
>> 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.023s ok
>> launchpad.net/juju-core/names   0.040s ?
>> launchpad.net/juju-core/provider        [no test files] ?
>> launchpad.net/juju-core/provider/all    [no test files] ok
>> launchpad.net/juju-core/provider/azure  6.672s ok
>> launchpad.net/juju-core/provider/common 9.887s ok
>> launchpad.net/juju-core/provider/dummy  24.702s ok
>> launchpad.net/juju-core/provider/ec2    18.938s ok
>> launchpad.net/juju-core/provider/joyent 5.285s ok
>> launchpad.net/juju-core/provider/local  4.946s ok
>> launchpad.net/juju-core/provider/maas   19.255s ok
>> launchpad.net/juju-core/provider/null   1.189s ok
>> launchpad.net/juju-core/provider/openstack      39.978s ok
>> launchpad.net/juju-core/replicaset      44.027s ok
>> launchpad.net/juju-core/rpc     0.117s ok
>> launchpad.net/juju-core/rpc/jsoncodec   0.038s ?
>> launchpad.net/juju-core/rpc/rpcreflect  [no test files] ok
>> launchpad.net/juju-core/schema  0.046s ok
>> launchpad.net/juju-core/state   90.250s ok
>> launchpad.net/juju-core/state/api       2.119s ok
>> launchpad.net/juju-core/state/api/agent 2.101s ?
>> launchpad.net/juju-core/state/api/base  [no test files] ok
>> launchpad.net/juju-core/state/api/charmrevisionupdater  1.385s ?
>> launchpad.net/juju-core/state/api/common        [no test files] ?
>> launchpad.net/juju-core/state/api/common/testing        [no test 
>> files] ok      launchpad.net/juju-core/state/api/deployer
>> 6.247s ok      launchpad.net/juju-core/state/api/firewaller
>> 11.043s ok      launchpad.net/juju-core/state/api/keymanager
>> 3.645s ok      launchpad.net/juju-core/state/api/keyupdater
>> 2.800s ok      launchpad.net/juju-core/state/api/logger
>> 2.424s ok      launchpad.net/juju-core/state/api/machiner
>> 3.782s ok      launchpad.net/juju-core/state/api/params
>> 0.036s ok      launchpad.net/juju-core/state/api/provisioner
>> 10.108s ok      launchpad.net/juju-core/state/api/uniter
>> 29.175s ok      launchpad.net/juju-core/state/api/upgrader
>> 3.865s ok      launchpad.net/juju-core/state/api/watcher
>> 3.451s ok      launchpad.net/juju-core/state/apiserver 8.060s ok
>> launchpad.net/juju-core/state/apiserver/agent   3.100s ok
>> launchpad.net/juju-core/state/apiserver/charmrevisionupdater 
>> 2.703s ? 
>> launchpad.net/juju-core/state/apiserver/charmrevisionupdater/testing
>>
>> 
[no test files]
>> ok      launchpad.net/juju-core/state/apiserver/client  44.687s 
>> ok      launchpad.net/juju-core/state/apiserver/common  2.208s ?
>> launchpad.net/juju-core/state/apiserver/common/testing  [no test 
>> files] ok      launchpad.net/juju-core/state/apiserver/deployer
>> 4.768s ok      launchpad.net/juju-core/state/apiserver/firewaller
>> 6.156s ok      launchpad.net/juju-core/state/apiserver/keymanager
>> 4.345s ?
>> launchpad.net/juju-core/state/apiserver/keymanager/testing [no
>> test files] ok
>> launchpad.net/juju-core/state/apiserver/keyupdater      3.223s ok
>> launchpad.net/juju-core/state/apiserver/logger  3.689s ok
>> launchpad.net/juju-core/state/apiserver/machine 2.983s ok
>> launchpad.net/juju-core/state/apiserver/provisioner     12.371s ?
>> launchpad.net/juju-core/state/apiserver/testing [no test files] 
>> ok      launchpad.net/juju-core/state/apiserver/uniter  20.422s 
>> ok      launchpad.net/juju-core/state/apiserver/upgrader
>> 6.369s ok      launchpad.net/juju-core/state/multiwatcher
>> 0.766s ok      launchpad.net/juju-core/state/presence  6.537s ok
>> launchpad.net/juju-core/state/statecmd  2.239s ?
>> launchpad.net/juju-core/state/testing   [no test files] ok
>> launchpad.net/juju-core/state/watcher   6.137s ok
>> launchpad.net/juju-core/store   19.830s ok
>> launchpad.net/juju-core/testing 1.424s ok
>> launchpad.net/juju-core/testing/checkers        0.051s ok
>> launchpad.net/juju-core/testing/testbase        0.020s ok
>> launchpad.net/juju-core/thirdparty/pbkdf2       0.126s ok
>> launchpad.net/juju-core/tools   0.020s ok
>> launchpad.net/juju-core/upstart 1.460s ok
>> launchpad.net/juju-core/utils   2.532s ok
>> launchpad.net/juju-core/utils/exec      0.038s ok
>> launchpad.net/juju-core/utils/fslock    1.479s ok
>> launchpad.net/juju-core/utils/parallel  0.915s ok
>> launchpad.net/juju-core/utils/set       0.018s ok
>> launchpad.net/juju-core/utils/ssh       20.914s ?
>> launchpad.net/juju-core/utils/ssh/testing       [no test files] 
>> ok      launchpad.net/juju-core/utils/tailer    1.037s ok
>> launchpad.net/juju-core/version 0.070s ok
>> launchpad.net/juju-core/worker  6.686s ok
>> launchpad.net/juju-core/worker/authenticationworker     3.415s ok
>> launchpad.net/juju-core/worker/charmrevisionworker      2.330s ok
>> launchpad.net/juju-core/worker/cleaner  1.451s ok
>> launchpad.net/juju-core/worker/deployer 3.876s ok
>> launchpad.net/juju-core/worker/firewaller       13.095s ok
>> launchpad.net/juju-core/worker/instancepoller   2.381s ok
>> launchpad.net/juju-core/worker/localstorage     0.053s ok
>> launchpad.net/juju-core/worker/logger   1.802s ok
>> launchpad.net/juju-core/worker/machiner 3.422s ok
>> launchpad.net/juju-core/worker/minunitsworker   1.349s
>> 
>> ----------------------------------------------------------------------
>>
>> 
FAIL: provisioner_test.go:743:
>> ProvisionerSuite.TestTurningOffSafeModeReapsUnknownInstances
>> 
>> [LOG] 66.77568 DEBUG juju.environs.configstore Making 
>> /tmp/gocheck-1976235410884491574/28/home/ubuntu/.juju/environments
>>
>> 
[LOG] 66.87813 DEBUG juju.environs.tools reading v1.* tools
>> [LOG] 66.87818 INFO juju environs/testing: uploading FAKE tools 
>> 1.17.1-precise-amd64 [LOG] 66.87909 DEBUG juju.environs.tools no
>> architecture specified when finding tools, looking for any [LOG]
>> 66.87911 DEBUG juju.environs.tools no series specified when
>> finding tools, looking for any [LOG] 66.87918 DEBUG
>> juju.environs.simplestreams fetchData failed for " 
>> http://127.0.0.1:34823/dummyenv/private/tools/streams/v1/index.sjson":
>>
>> 
file "tools/streams/v1/index.sjson" not found not found
>> [LOG] 66.87920 DEBUG juju.environs.simplestreams cannot load
>> index " 
>> http://127.0.0.1:34823/dummyenv/private/tools/streams/v1/index.sjson":
>>
>> 
invalid URL "
>> http://127.0.0.1:34823/dummyenv/private/tools/streams/v1/index.sjson"
>> not found [LOG] 66.87924 DEBUG juju.environs.simplestreams
>> fetchData failed for " 
>> http://127.0.0.1:34823/dummyenv/private/tools/streams/v1/index.json":
>>
>> 
file "tools/streams/v1/index.json" not found not found
>> [LOG] 66.87927 DEBUG juju.environs.simplestreams cannot load
>> index " 
>> http://127.0.0.1:34823/dummyenv/private/tools/streams/v1/index.json":
>>
>> 
invalid URL "
>> http://127.0.0.1:34823/dummyenv/private/tools/streams/v1/index.json"
>> not found [LOG] 66.87959 INFO juju.environs.tools Writing
>> tools/streams/v1/index.json [LOG] 66.87972 INFO
>> juju.environs.tools Writing 
>> tools/streams/v1/com.ubuntu.juju:released:tools.json [LOG]
>> 66.87987 INFO juju.environs.bootstrap bootstrapping environment 
>> "dummyenv" [LOG] 66.87990 DEBUG juju.environs.bootstrap looking
>> for bootstrap tools: series="precise", arch=<nil>,
>> version=1.17.1 [LOG] 66.87992 INFO juju.environs.tools reading
>> tools with major.minor version 1.17 [LOG] 66.87993 INFO
>> juju.environs.tools filtering tools by version: 1.17.1 [LOG]
>> 66.87994 INFO juju.environs.tools filtering tools by series:
>> precise [LOG] 66.87997 DEBUG juju.environs.tools no architecture
>> specified when finding tools, looking for any [LOG] 66.88001
>> DEBUG juju.environs.simplestreams fetchData failed for " 
>> http://127.0.0.1:34823/dummyenv/private/tools/streams/v1/index.sjson":
>>
>> 
file "tools/streams/v1/index.sjson" not found not found
>> [LOG] 66.88003 DEBUG juju.environs.simplestreams cannot load
>> index " 
>> http://127.0.0.1:34823/dummyenv/private/tools/streams/v1/index.sjson":
>>
>> 
invalid URL "
>> http://127.0.0.1:34823/dummyenv/private/tools/streams/v1/index.sjson"
>> not found [LOG] 66.88013 DEBUG juju.environs.simplestreams
>> fetchData failed for " 
>> http://127.0.0.1:34823/dummyenv/private/tools/streams/v1/mirrors.json":
>>
>> 
file "tools/streams/v1/mirrors.json" not found not found
>> [LOG] 66.88015 DEBUG juju.environs.simplestreams no mirror index
>> file found [LOG] 66.88017 DEBUG juju.environs.simplestreams no
>> mirror information available for { }: mirror data for
>> "com.ubuntu.juju:released:tools" not found [LOG] 66.88019 DEBUG
>> juju.environs.simplestreams read metadata index at " 
>> http://127.0.0.1:34823/dummyenv/private/tools/streams/v1/index.json"
>>
>> 
[LOG] 66.88024 DEBUG juju.environs.simplestreams candidate matches for
>> products ["com.ubuntu.juju:12.04:amd64"
>> "com.ubuntu.juju:12.04:i386" "com.ubuntu.juju:12.04:arm"] are
>> [{Mon, 27 Jan 2014 02:06:28 +0000 products:1.0 content-download
>> [] streams/v1/com.ubuntu.juju:released:tools.json 
>> [com.ubuntu.juju:12.04:amd64]}] [LOG] 66.88025 DEBUG
>> juju.environs.simplestreams finding products at path 
>> "streams/v1/com.ubuntu.juju:released:tools.json" [LOG] 66.88047
>> DEBUG juju.environs.simplestreams metadata: 
>> &{map[com.ubuntu.juju:12.04:amd64:{ 1.17.1 amd64 
>> map[20140127:0xc200872960]}] map[] Mon, 27 Jan 2014 02:06:28
>> +0000 products:1.0 com.ubuntu.juju:released:tools} [LOG] 66.88052
>> INFO juju.environs.bootstrap picked newest version: 1.17.1 [LOG]
>> 66.88055 INFO juju.provider.dummy would pick tools from 
>> 1.17.1-precise-amd64 [LOG] 66.88099 INFO juju.state opening
>> state; mongo addresses: ["localhost:60450"]; entity "" [LOG]
>> 66.90219 INFO juju.state connection established [LOG] 66.93149
>> INFO juju.state adding state server info to legacy environment 
>> [LOG] 66.93220 INFO juju.state found existing state servers [] 
>> [LOG] 66.94837 INFO juju.state initializing environment [LOG]
>> 66.97002 INFO juju.state.apiserver listening on
>> "127.0.0.1:40536" [LOG] 66.98871 INFO juju.state opening state;
>> mongo addresses: ["localhost:60450"]; entity "" [LOG] 67.00467
>> INFO juju.state connection established [LOG] 67.00571 INFO juju
>> juju: authorization error while connecting to state server;
>> retrying [LOG] 67.00576 INFO juju.state opening state; mongo
>> addresses: ["localhost:60450"]; entity "" [LOG] 67.00906 INFO
>> juju.state connection established [LOG] 67.01263 INFO juju.state
>> adding state server info to legacy environment [LOG] 67.01289
>> INFO juju.state found existing state servers [] [LOG] 67.05390
>> INFO juju state/api: dialing "wss://127.0.0.1:40536/" [LOG]
>> 67.05715 INFO juju.state.apiserver [2D] API connection from 
>> 127.0.0.1:49199 [LOG] 67.05731 INFO juju state/api: connection
>> established [LOG] 67.05749 DEBUG juju.state.apiserver <- [2D]
>> <unknown> 
>> {"RequestId":1,"Type":"Admin","Request":"Login","Params":{"AuthTag":"user-admin","Password":"dummy-secret","Nonce":""}}
>>
>> 
[LOG] 67.05790 DEBUG juju.state.apiserver -> [2D] user-admin 419.159us
>> {"RequestId":1,"Response":{}} Admin[""].Login [LOG] 67.13205 INFO
>> juju state/api: dialing "wss://127.0.0.1:40536/" [LOG] 67.13609
>> INFO juju.state.apiserver [2E] API connection from 
>> 127.0.0.1:49200 [LOG] 67.13624 INFO juju state/api: connection
>> established [LOG] 67.13646 DEBUG juju.state.apiserver <- [2E]
>> <unknown> 
>> {"RequestId":1,"Type":"Admin","Request":"Login","Params":{"AuthTag":"machine-0","Password":"23IRiPRiashtJEG646g8EN4l","Nonce":"fake_nonce"}}
>>
>> 
[LOG] 67.16735 DEBUG juju.state.apiserver -> [2E] machine-0 30.801097ms
>> {"RequestId":1,"Response":{}} Admin[""].Login API: login as
>> "machine-0" successful [LOG] 67.16860 DEBUG juju.state.apiserver
>> <- [2E] machine-0 
>> {"RequestId":3,"Type":"Provisioner","Request":"WatchEnvironMachines","Params":{}}
>>
>> 
[LOG] 67.17065 DEBUG juju.state.apiserver -> [2E] machine-0 2.013232ms
>> {"RequestId":3,"Response":{"StringsWatcherId":"2","Changes":["0"],"Error":null}}
>>
>> 
Provisioner[""].WatchEnvironMachines
>> [LOG] 67.17260 DEBUG juju.state.apiserver <- [2E] machine-0 
>> {"RequestId":4,"Type":"Provisioner","Request":"StateAddresses","Params":{}}
>>
>> 
[LOG] 67.17316 DEBUG juju.state.apiserver <- [2E] machine-0
>> {"RequestId":5,"Type":"StringsWatcher","Id":"2","Request":"Next","Params":{}}
>>
>> 
[LOG] 67.17680 DEBUG juju.state.apiserver -> [2E] machine-0 4.427871ms
>> {"RequestId":4,"Response":{"Error":null,"Result":["0.1.2.3:1234"]}}
>>
>> 
Provisioner[""].StateAddresses
>> [LOG] 67.17708 DEBUG juju.state.apiserver <- [2E] machine-0 
>> {"RequestId":6,"Type":"Provisioner","Request":"APIAddresses","Params":{}}
>>
>> 
[LOG] 67.17831 DEBUG juju.state.apiserver -> [2E] machine-0 1.214071ms
>> {"RequestId":6,"Response":{"Error":null,"Result":["0.1.2.3:4321"]}}
>>
>> 
Provisioner[""].APIAddresses
>> [LOG] 67.17859 DEBUG juju.state.apiserver <- [2E] machine-0 
>> {"RequestId":7,"Type":"Provisioner","Request":"CACert","Params":{}}
>>
>> 
[LOG] 67.17871 DEBUG juju.state.apiserver -> [2E] machine-0 72.61us
>> {"RequestId":7,"Response":{"Result":"LS0tLS1CRUdJTiBDRVJUSUZJQ0FURS0tLS0tCk1JSUI0akNDQVk2Z0F3SUJBZ0lCQURBTEJna3Foa2lHOXcwQkFRVXdTakVOTUFzR0ExVUVDaE1FYW5WcWRURTUKTURjR0ExVUVBd3d3YW5WcWRTMW5aVzVsY21GMFpXUWdRMEVnWm05eUlHVnVkbWx5YjI1dFpXNTBJQ0pxZFdwMQpJSFJsYzNScGJtY2lNQjRYRFRFME1ERXlOekF5TURBME4xb1hEVEkwTURFeU56QXlNRFUwTjFvd1NqRU5NQXNHCkExVUVDaE1FYW5WcWRURTVNRGNHQTFVRUF3d3dhblZxZFMxblpXNWxjbUYwWldRZ1EwRWdabTl5SUdWdWRtbHkKYjI1dFpXNTBJQ0pxZFdwMUlIUmxjM1JwYm1jaU1Gb3dDd1lKS29aSWh2Y05BUUVCQTBzQU1FZ0NRUUM0R2R3Mwo1QUFlaEJrRmM5RWcyQXF4VndRdnZUK1NZSGFvMVQ4MWQyQlRSc1hVM0xRYndXVzYzeFhlbGV5YlRleVlWMWg3ClIxaFBqbjc2UEU2QnFJRmJBZ01CQUFHall6QmhNQTRHQTFVZER3RUIvd1FFQXdJQXBEQVBCZ05WSFJNQkFmOEUKQlRBREFRSC9NQjBHQTFVZERnUVdCQlJheW44aExhbXhBVmhPWjFNZVNtdnRFUFZuQXpBZkJnTlZIU01FR0RBVwpnQlJheW44aExhbXhBVmhPWjFNZVNtdnRFUFZuQXpBTEJna3Foa2lHOXcwQkFRVURRUUEvSjdQM3NBVlZROFBTCkE4MjRuc1ZNTkpTWFdFZWdoZWVtSTNDaW1ZVWtROFVXRU5Ic0lSamcwZzJOWUY4L1ZKa1QrSFE5V2U4Q3kwN2MKZjMrc0pidXAKLS0tLS1FTkQgQ0VSVElGSUNBVEUtLS0tLQo="}}
>>
>> 
Provisioner[""].CACert
>> [LOG] 67.17902 INFO juju.provisioner Starting up provisioner task
>> machine-0 [LOG] 67.17964 DEBUG juju.state.apiserver <- [2E]
>> machine-0 
>> {"RequestId":8,"Type":"Provisioner","Request":"Life","Params":{"Entities":[{"Tag":"machine-0"}]}}
>>
>> 
[LOG] 67.18044 DEBUG juju.state.apiserver -> [2E] machine-0 812.394us
>> {"RequestId":8,"Response":{"Results":[{"Life":"alive","Error":null}]}}
>>
>> 
Provisioner[""].Life
>> [LOG] 67.18170 DEBUG juju.state.apiserver <- [2E] machine-0 
>> {"RequestId":9,"Type":"Provisioner","Request":"InstanceId","Params":{"Entities":[{"Tag":"machine-0"}]}}
>>
>> 
[LOG] 67.18256 DEBUG juju.state.apiserver -> [2E] machine-0 895.402us
>> {"RequestId":9,"Response":{"Results":[{"Error":null,"Result":"i-fake"}]}}
>>
>> 
Provisioner[""].InstanceId
>> [LOG] 67.18300 INFO juju.provisioner machine 0 already started as
>> instance "i-fake" [LOG] 67.18340 DEBUG juju.state.apiserver <-
>> [2E] machine-0 
>> {"RequestId":10,"Type":"Provisioner","Request":"InstanceId","Params":{"Entities":[{"Tag":"machine-0"}]}}
>>
>> 
[LOG] 67.18416 DEBUG juju.state.apiserver -> [2E] machine-0 765.631us
>> {"RequestId":10,"Response":{"Results":[{"Error":null,"Result":"i-fake"}]}}
>>
>> 
Provisioner[""].InstanceId
>> [LOG] 67.18469 INFO juju.provisioner running in safe mode,
>> unknown instances not stopped [] [LOG] 67.21068 DEBUG
>> juju.state.apiserver -> [2E] machine-0 37.641718ms 
>> {"RequestId":5,"Response":{"StringsWatcherId":"","Changes":["1"],"Error":null}}
>>
>> 
StringsWatcher["2"].Next
>> [LOG] 67.21150 DEBUG juju.state.apiserver <- [2E] machine-0 
>> {"RequestId":11,"Type":"StringsWatcher","Id":"2","Request":"Next","Params":{}}
>>
>> 
[LOG] 67.21168 DEBUG juju.state.apiserver <- [2E] machine-0
>> {"RequestId":12,"Type":"Provisioner","Request":"Life","Params":{"Entities":[{"Tag":"machine-1"}]}}
>>
>> 
[LOG] 67.21295 DEBUG juju.state.apiserver -> [2E] machine-0 1.232532ms
>> {"RequestId":12,"Response":{"Results":[{"Life":"alive","Error":null}]}}
>>
>> 
Provisioner[""].Life
>> [LOG] 67.21364 DEBUG juju.state.apiserver <- [2E] machine-0 
>> {"RequestId":13,"Type":"Provisioner","Request":"InstanceId","Params":{"Entities":[{"Tag":"machine-1"}]}}
>>
>> 
[LOG] 67.21565 DEBUG juju.state.apiserver -> [2E] machine-0 1.864688ms
>> {"RequestId":13,"Response":{"Results":[{"Error":{"Message":"machine
>> 1 is not provisioned","Code":"not provisioned"},"Result":""}]}} 
>> Provisioner[""].InstanceId [LOG] 67.21707 DEBUG
>> juju.state.apiserver <- [2E] machine-0 
>> {"RequestId":14,"Type":"Provisioner","Request":"Status","Params":{"Entities":[{"Tag":"machine-1"}]}}
>>
>> 
[LOG] 67.21958 DEBUG juju.state.apiserver -> [2E] machine-0 2.403046ms
>> {"RequestId":14,"Response":{"Results":[{"Error":null,"Status":"pending","Info":""}]}}
>>
>> 
Provisioner[""].Status
>> [LOG] 67.22042 INFO juju.provisioner found machine "1" pending
>> provisioning [LOG] 67.22112 DEBUG juju.state.apiserver <- [2E]
>> machine-0 
>> {"RequestId":15,"Type":"Provisioner","Request":"InstanceId","Params":{"Entities":[{"Tag":"machine-0"}]}}
>>
>> 
[LOG] 67.22274 DEBUG juju.state.apiserver -> [2E] machine-0 1.654076ms
>> {"RequestId":15,"Response":{"Results":[{"Error":null,"Result":"i-fake"}]}}
>>
>> 
Provisioner[""].InstanceId
>> [LOG] 67.22402 DEBUG juju.state.apiserver <- [2E] machine-0 
>> {"RequestId":16,"Type":"Provisioner","Request":"InstanceId","Params":{"Entities":[{"Tag":"machine-1"}]}}
>>
>> 
[LOG] 67.22462 DEBUG juju.state.apiserver -> [2E] machine-0 601.752us
>> {"RequestId":16,"Response":{"Results":[{"Error":{"Message":"machine
>> 1 is not provisioned","Code":"not provisioned"},"Result":""}]}} 
>> Provisioner[""].InstanceId [LOG] 67.22479 INFO juju.provisioner
>> running in safe mode, unknown instances not stopped [] [LOG]
>> 67.22493 DEBUG juju.state.apiserver <- [2E] machine-0 
>> {"RequestId":17,"Type":"Provisioner","Request":"Constraints","Params":{"Entities":[{"Tag":"machine-1"}]}}
>>
>> 
[LOG] 67.22558 DEBUG juju.state.apiserver -> [2E] machine-0 603.504us
>> {"RequestId":17,"Response":{"Results":[{"Error":null,"Constraints":{"arch":"amd64","cpu-cores":1,"mem":4096,"root-disk":8192}}]}}
>>
>> 
Provisioner[""].Constraints
>> [LOG] 67.22589 DEBUG juju.state.apiserver <- [2E] machine-0 
>> {"RequestId":18,"Type":"Provisioner","Request":"Series","Params":{"Entities":[{"Tag":"machine-1"}]}}
>>
>> 
[LOG] 67.22629 DEBUG juju.state.apiserver -> [2E] machine-0 389.11us
>> {"RequestId":18,"Response":{"Results":[{"Error":null,"Result":"precise"}]}}
>>
>> 
Provisioner[""].Series
>> [LOG] 67.22647 INFO juju.environs.tools reading tools with
>> major.minor version 1.17 [LOG] 67.22648 INFO juju.environs.tools
>> filtering tools by version: 1.17.1 [LOG] 67.22649 INFO
>> juju.environs.tools filtering tools by series: precise [LOG]
>> 67.22650 INFO juju.environs.tools filtering tools by
>> architecture: amd64 [LOG] 67.22663 DEBUG
>> juju.environs.simplestreams fetchData failed for " 
>> http://127.0.0.1:34823/dummyenv/private/tools/streams/v1/index.sjson":
>>
>> 
file "tools/streams/v1/index.sjson" not found not found
>> [LOG] 67.22666 DEBUG juju.environs.simplestreams cannot load
>> index " 
>> http://127.0.0.1:34823/dummyenv/private/tools/streams/v1/index.sjson":
>>
>> 
invalid URL "
>> http://127.0.0.1:34823/dummyenv/private/tools/streams/v1/index.sjson"
>> not found [LOG] 67.22680 DEBUG juju.environs.simplestreams
>> fetchData failed for " 
>> http://127.0.0.1:34823/dummyenv/private/tools/streams/v1/mirrors.json":
>>
>> 
file "tools/streams/v1/mirrors.json" not found not found
>> [LOG] 67.22681 DEBUG juju.environs.simplestreams no mirror index
>> file found [LOG] 67.22684 DEBUG juju.environs.simplestreams no
>> mirror information available for { }: mirror data for
>> "com.ubuntu.juju:released:tools" not found [LOG] 67.22685 DEBUG
>> juju.environs.simplestreams read metadata index at " 
>> http://127.0.0.1:34823/dummyenv/private/tools/streams/v1/index.json"
>>
>> 
[LOG] 67.22692 DEBUG juju.environs.simplestreams candidate matches for
>> products ["com.ubuntu.juju:12.04:amd64"] are [{Mon, 27 Jan 2014
>> 02:06:28 +0000 products:1.0 content-download  [] 
>> streams/v1/com.ubuntu.juju:released:tools.json 
>> [com.ubuntu.juju:12.04:amd64]}] [LOG] 67.22694 DEBUG
>> juju.environs.simplestreams finding products at path 
>> "streams/v1/com.ubuntu.juju:released:tools.json" [LOG] 67.22727
>> DEBUG juju.environs.simplestreams metadata: 
>> &{map[com.ubuntu.juju:12.04:amd64:{ 1.17.1 amd64 
>> map[20140127:0xc2009ae000]}] map[] Mon, 27 Jan 2014 02:06:28
>> +0000 products:1.0 com.ubuntu.juju:released:tools} [LOG] 67.22755
>> DEBUG juju.state.apiserver <- [2E] machine-0 
>> {"RequestId":19,"Type":"Provisioner","Request":"SetPasswords","Params":{"Changes":[{"Tag":"machine-1","Password":"ryjdqJYnOIvl6k4zYrvjfLTP"}]}}
>>
>> 
[LOG] 67.23236 INFO juju setting password for "machine-1"
>> [LOG] 67.23242 DEBUG juju.state.apiserver -> [2E] machine-0
>> 4.872145ms 
>> {"RequestId":19,"Response":{"Results":[{"Error":null}]}} 
>> Provisioner[""].SetPasswords [LOG] 67.23260 INFO
>> juju.provider.dummy dummy startinstance, machine 1 [LOG] 67.23264
>> INFO juju.provider.dummy would pick tools from 
>> 1.17.1-precise-amd64 [LOG] 67.23293 DEBUG juju.state.apiserver <-
>> [2E] machine-0 
>> {"RequestId":20,"Type":"Provisioner","Request":"SetProvisioned","Params":{"Machines":[{"Tag":"machine-1","InstanceId":"dummyenv-0","Nonce":"machine-0:f8103a17-3702-4429-802e-4a84c512d96c","Characteristics":{"Arch":"amd64","Mem":4096,"RootDisk":8192,"CpuCores":1,"CpuPower":null,"Tags":null}}]}}
>>
>> 
machine 1 is still unprovisioned
>> [LOG] 67.24090 DEBUG juju.state.apiserver -> [2E] machine-0
>> 7.983916ms 
>> {"RequestId":20,"Response":{"Results":[{"Error":null}]}} 
>> Provisioner[""].SetProvisioned [LOG] 67.24110 INFO
>> juju.provisioner started machine 1 as instance dummyenv-0 with
>> hardware "arch=amd64 cpu-cores=1 mem=4096M root-disk=8192M" [LOG]
>> 67.33280 DEBUG juju.state.apiserver -> [2E] machine-0
>> 121.212855ms 
>> {"RequestId":11,"Response":{"StringsWatcherId":"","Changes":["2"],"Error":null}}
>>
>> 
StringsWatcher["2"].Next
>> [LOG] 67.33375 DEBUG juju.state.apiserver <- [2E] machine-0 
>> {"RequestId":21,"Type":"StringsWatcher","Id":"2","Request":"Next","Params":{}}
>>
>> 
[LOG] 67.33396 DEBUG juju.state.apiserver <- [2E] machine-0
>> {"RequestId":22,"Type":"Provisioner","Request":"Life","Params":{"Entities":[{"Tag":"machine-2"}]}}
>>
>> 
[LOG] 67.33498 DEBUG juju.state.apiserver -> [2E] machine-0 1.001393ms
>> {"RequestId":22,"Response":{"Results":[{"Life":"alive","Error":null}]}}
>>
>> 
Provisioner[""].Life
>> [LOG] 67.33553 DEBUG juju.state.apiserver <- [2E] machine-0 
>> {"RequestId":23,"Type":"Provisioner","Request":"InstanceId","Params":{"Entities":[{"Tag":"machine-2"}]}}
>>
>> 
[LOG] 67.33760 DEBUG juju.state.apiserver -> [2E] machine-0 1.91728ms
>> {"RequestId":23,"Response":{"Results":[{"Error":{"Message":"machine
>> 2 is not provisioned","Code":"not provisioned"},"Result":""}]}} 
>> Provisioner[""].InstanceId [LOG] 67.33891 DEBUG
>> juju.state.apiserver <- [2E] machine-0 
>> {"RequestId":24,"Type":"Provisioner","Request":"Status","Params":{"Entities":[{"Tag":"machine-2"}]}}
>>
>> 
[LOG] 67.34143 DEBUG juju.state.apiserver -> [2E] machine-0 2.464908ms
>> {"RequestId":24,"Response":{"Results":[{"Error":null,"Status":"pending","Info":""}]}}
>>
>> 
Provisioner[""].Status
>> [LOG] 67.34225 INFO juju.provisioner found machine "2" pending
>> provisioning [LOG] 67.34296 DEBUG juju.state.apiserver <- [2E]
>> machine-0 
>> {"RequestId":25,"Type":"Provisioner","Request":"InstanceId","Params":{"Entities":[{"Tag":"machine-0"}]}}
>>
>> 
[LOG] 67.34414 DEBUG juju.state.apiserver -> [2E] machine-0 1.330202ms
>> {"RequestId":25,"Response":{"Results":[{"Error":null,"Result":"i-fake"}]}}
>>
>> 
Provisioner[""].InstanceId
>> [LOG] 67.34444 DEBUG juju.state.apiserver <- [2E] machine-0 
>> {"RequestId":26,"Type":"Provisioner","Request":"InstanceId","Params":{"Entities":[{"Tag":"machine-1"}]}}
>>
>> 
[LOG] 67.34480 DEBUG juju.state.apiserver -> [2E] machine-0 354.418us
>> {"RequestId":26,"Response":{"Results":[{"Error":null,"Result":"dummyenv-0"}]}}
>>
>> 
Provisioner[""].InstanceId
>> [LOG] 67.34510 DEBUG juju.state.apiserver <- [2E] machine-0 
>> {"RequestId":27,"Type":"Provisioner","Request":"InstanceId","Params":{"Entities":[{"Tag":"machine-2"}]}}
>>
>> 
[LOG] 67.34586 DEBUG juju.state.apiserver -> [2E] machine-0 767.989us
>> {"RequestId":27,"Response":{"Results":[{"Error":{"Message":"machine
>> 2 is not provisioned","Code":"not provisioned"},"Result":""}]}} 
>> Provisioner[""].InstanceId [LOG] 67.34604 INFO juju.provisioner
>> running in safe mode, unknown instances not stopped [] [LOG]
>> 67.34861 DEBUG juju.state.apiserver <- [2E] machine-0 
>> {"RequestId":28,"Type":"Provisioner","Request":"Constraints","Params":{"Entities":[{"Tag":"machine-2"}]}}
>>
>> 
[LOG] 67.34948 DEBUG juju.state.apiserver -> [2E] machine-0 971.273us
>> {"RequestId":28,"Response":{"Results":[{"Error":null,"Constraints":{"arch":"amd64","cpu-cores":1,"mem":4096,"root-disk":8192}}]}}
>>
>> 
Provisioner[""].Constraints
>> [LOG] 67.34983 DEBUG juju.state.apiserver <- [2E] machine-0 
>> {"RequestId":29,"Type":"Provisioner","Request":"Series","Params":{"Entities":[{"Tag":"machine-2"}]}}
>>
>> 
[LOG] 67.35018 DEBUG juju.state.apiserver -> [2E] machine-0 343.204us
>> {"RequestId":29,"Response":{"Results":[{"Error":null,"Result":"precise"}]}}
>>
>> 
Provisioner[""].Series
>> [LOG] 67.35037 INFO juju.environs.tools reading tools with
>> major.minor version 1.17 [LOG] 67.35041 INFO juju.environs.tools
>> filtering tools by version: 1.17.1 [LOG] 67.35041 INFO
>> juju.environs.tools filtering tools by series: precise [LOG]
>> 67.35042 INFO juju.environs.tools filtering tools by
>> architecture: amd64 [LOG] 67.35056 DEBUG
>> juju.environs.simplestreams fetchData failed for " 
>> http://127.0.0.1:34823/dummyenv/private/tools/streams/v1/index.sjson":
>>
>> 
file "tools/streams/v1/index.sjson" not found not found
>> [LOG] 67.35059 DEBUG juju.environs.simplestreams cannot load
>> index " 
>> http://127.0.0.1:34823/dummyenv/private/tools/streams/v1/index.sjson":
>>
>> 
invalid URL "
>> http://127.0.0.1:34823/dummyenv/private/tools/streams/v1/index.sjson"
>> not found [LOG] 67.35070 DEBUG juju.environs.simplestreams
>> fetchData failed for " 
>> http://127.0.0.1:34823/dummyenv/private/tools/streams/v1/mirrors.json":
>>
>> 
file "tools/streams/v1/mirrors.json" not found not found
>> [LOG] 67.35074 DEBUG juju.environs.simplestreams no mirror index
>> file found [LOG] 67.35077 DEBUG juju.environs.simplestreams no
>> mirror information available for { }: mirror data for
>> "com.ubuntu.juju:released:tools" not found [LOG] 67.35078 DEBUG
>> juju.environs.simplestreams read metadata index at " 
>> http://127.0.0.1:34823/dummyenv/private/tools/streams/v1/index.json"
>>
>> 
[LOG] 67.35085 DEBUG juju.environs.simplestreams candidate matches for
>> products ["com.ubuntu.juju:12.04:amd64"] are [{Mon, 27 Jan 2014
>> 02:06:28 +0000 products:1.0 content-download  [] 
>> streams/v1/com.ubuntu.juju:released:tools.json 
>> [com.ubuntu.juju:12.04:amd64]}] [LOG] 67.35086 DEBUG
>> juju.environs.simplestreams finding products at path 
>> "streams/v1/com.ubuntu.juju:released:tools.json" [LOG] 67.35118
>> DEBUG juju.environs.simplestreams metadata: 
>> &{map[com.ubuntu.juju:12.04:amd64:{ 1.17.1 amd64 
>> map[20140127:0xc2008351e0]}] map[] Mon, 27 Jan 2014 02:06:28
>> +0000 products:1.0 com.ubuntu.juju:released:tools} [LOG] 67.35146
>> DEBUG juju.state.apiserver <- [2E] machine-0 
>> {"RequestId":30,"Type":"Provisioner","Request":"SetPasswords","Params":{"Changes":[{"Tag":"machine-2","Password":"GV8brcWHZ0dvKh0hl2e0jIgH"}]}}
>>
>> 
[LOG] 67.35563 INFO juju setting password for "machine-2"
>> [LOG] 67.35571 DEBUG juju.state.apiserver -> [2E] machine-0
>> 4.25022ms 
>> {"RequestId":30,"Response":{"Results":[{"Error":null}]}} 
>> Provisioner[""].SetPasswords [LOG] 67.35600 INFO
>> juju.provider.dummy dummy startinstance, machine 2 [LOG] 67.35606
>> INFO juju.provider.dummy would pick tools from 
>> 1.17.1-precise-amd64 [LOG] 67.35666 DEBUG juju.state.apiserver <-
>> [2E] machine-0 
>> {"RequestId":31,"Type":"Provisioner","Request":"SetProvisioned","Params":{"Machines":[{"Tag":"machine-2","InstanceId":"dummyenv-1","Nonce":"machine-0:f6e4c9fb-f708-4aae-86f0-843dfc385cff","Characteristics":{"Arch":"amd64","Mem":4096,"RootDisk":8192,"CpuCores":1,"CpuPower":null,"Tags":null}}]}}
>>
>> 
[LOG] 67.36249 DEBUG juju.state.apiserver -> [2E] machine-0 5.85045ms
>> {"RequestId":31,"Response":{"Results":[{"Error":null}]}} 
>> Provisioner[""].SetProvisioned [LOG] 67.36277 INFO
>> juju.provisioner started machine 2 as instance dummyenv-1 with
>> hardware "arch=amd64 cpu-cores=1 mem=4096M root-disk=8192M" [LOG]
>> 67.37450 DEBUG juju.state.apiserver -> [2E] machine-0
>> 40.798443ms 
>> {"RequestId":21,"Response":{"StringsWatcherId":"","Changes":["2"],"Error":null}}
>>
>> 
StringsWatcher["2"].Next
>> [LOG] 67.37484 DEBUG juju.state.apiserver <- [2E] machine-0 
>> {"RequestId":32,"Type":"StringsWatcher","Id":"2","Request":"Next","Params":{}}
>>
>> 
[LOG] 67.37491 DEBUG juju.state.apiserver <- [2E] machine-0
>> {"RequestId":33,"Type":"Provisioner","Request":"Life","Params":{"Entities":[{"Tag":"machine-2"}]}}
>>
>> 
[LOG] 67.37564 DEBUG juju.state.apiserver -> [2E] machine-0 716.332us
>> {"RequestId":33,"Response":{"Results":[{"Life":"dead","Error":null}]}}
>>
>> 
Provisioner[""].Life
>> [LOG] 67.37591 DEBUG juju.state.apiserver <- [2E] machine-0 
>> {"RequestId":34,"Type":"Provisioner","Request":"InstanceId","Params":{"Entities":[{"Tag":"machine-2"}]}}
>>
>> 
[LOG] 67.37664 DEBUG juju.state.apiserver -> [2E] machine-0 715.092us
>> {"RequestId":34,"Response":{"Results":[{"Error":null,"Result":"dummyenv-1"}]}}
>>
>> 
Provisioner[""].InstanceId
>> [LOG] 67.37692 DEBUG juju.state.apiserver <- [2E] machine-0 
>> {"RequestId":35,"Type":"Provisioner","Request":"InstanceId","Params":{"Entities":[{"Tag":"machine-0"}]}}
>>
>> 
[LOG] 67.37750 DEBUG juju.state.apiserver -> [2E] machine-0 579.385us
>> {"RequestId":35,"Response":{"Results":[{"Error":null,"Result":"i-fake"}]}}
>>
>> 
Provisioner[""].InstanceId
>> [LOG] 67.37780 DEBUG juju.state.apiserver <- [2E] machine-0 
>> {"RequestId":36,"Type":"Provisioner","Request":"InstanceId","Params":{"Entities":[{"Tag":"machine-1"}]}}
>>
>> 
[LOG] 67.37839 DEBUG juju.state.apiserver -> [2E] machine-0 610.358us
>> {"RequestId":36,"Response":{"Results":[{"Error":null,"Result":"dummyenv-0"}]}}
>>
>> 
Provisioner[""].InstanceId
>> [LOG] 67.37872 DEBUG juju.state.apiserver <- [2E] machine-0 
>> {"RequestId":37,"Type":"Provisioner","Request":"InstanceId","Params":{"Entities":[{"Tag":"machine-2"}]}}
>>
>> 
[LOG] 67.37932 DEBUG juju.state.apiserver -> [2E] machine-0 651.803us
>> {"RequestId":37,"Response":{"Results":[{"Error":null,"Result":"dummyenv-1"}]}}
>>
>> 
Provisioner[""].InstanceId
>> [LOG] 67.37948 INFO juju.provisioner running in safe mode,
>> unknown instances not stopped [] [LOG] 67.37950 INFO
>> juju.provisioner stopping known instances [0xc2009deb80] [LOG]
>> 67.37955 INFO juju.provisioner removing dead machine "2" [LOG]
>> 67.37968 DEBUG juju.state.apiserver <- [2E] machine-0 
>> {"RequestId":38,"Type":"Provisioner","Request":"Remove","Params":{"Entities":[{"Tag":"machine-2"}]}}
>>
>> 
provisioner_test.go:763:
>> // with safe mode on, only one of the machines is stopped. 
>> s.checkStopSomeInstances(c, []instance.Instance{i0}, 
>> []instance.Instance{i1}) provisioner_test.go:232: 
>> c.Errorf("provisioner unexpectedly stopped instance %s", instId) 
>> ... Error: provisioner unexpectedly stopped instance dummyenv-1
>> 
>> [LOG] 67.41311 DEBUG juju.state.apiserver -> [2E] machine-0
>> 38.225998ms 
>> {"RequestId":32,"Response":{"StringsWatcherId":"","Changes":["1"],"Error":null}}
>>
>> 
StringsWatcher["2"].Next
>> [LOG] 67.41341 DEBUG juju.state.apiserver <- [2E] machine-0 
>> {"RequestId":39,"Type":"StringsWatcher","Id":"2","Request":"Next","Params":{}}
>>
>> 
[LOG] 67.42269 DEBUG juju.state.apiserver -> [2E] machine-0 42.940162ms
>> {"RequestId":38,"Response":{"Results":[{"Error":null}]}} 
>> Provisioner[""].Remove [LOG] 67.42312 DEBUG juju.state.apiserver
>> <- [2E] machine-0 
>> {"RequestId":40,"Type":"Provisioner","Request":"Life","Params":{"Entities":[{"Tag":"machine-1"}]}}
>>
>> 
[LOG] 67.42357 DEBUG juju.state.apiserver -> [2E] machine-0 426.751us
>> {"RequestId":40,"Response":{"Results":[{"Life":"dead","Error":null}]}}
>>
>> 
Provisioner[""].Life
>> [LOG] 67.42387 DEBUG juju.state.apiserver <- [2E] machine-0 
>> {"RequestId":41,"Type":"Provisioner","Request":"InstanceId","Params":{"Entities":[{"Tag":"machine-1"}]}}
>>
>> 
[LOG] 67.42424 DEBUG juju.state.apiserver -> [2E] machine-0 401.816us
>> {"RequestId":41,"Response":{"Results":[{"Error":null,"Result":"dummyenv-0"}]}}
>>
>> 
Provisioner[""].InstanceId
>> [LOG] 67.42451 DEBUG juju.state.apiserver <- [2E] machine-0 
>> {"RequestId":42,"Type":"Provisioner","Request":"InstanceId","Params":{"Entities":[{"Tag":"machine-0"}]}}
>>
>> 
[LOG] 67.42491 DEBUG juju.state.apiserver -> [2E] machine-0 366.546us
>> {"RequestId":42,"Response":{"Results":[{"Error":null,"Result":"i-fake"}]}}
>>
>> 
Provisioner[""].InstanceId
>> [LOG] 67.42519 DEBUG juju.state.apiserver <- [2E] machine-0 
>> {"RequestId":43,"Type":"Provisioner","Request":"InstanceId","Params":{"Entities":[{"Tag":"machine-1"}]}}
>>
>> 
[LOG] 67.42562 DEBUG juju.state.apiserver -> [2E] machine-0 437.932us
>> {"RequestId":43,"Response":{"Results":[{"Error":null,"Result":"dummyenv-0"}]}}
>>
>> 
Provisioner[""].InstanceId
>> [LOG] 67.42578 INFO juju.provisioner running in safe mode,
>> unknown instances not stopped [] [LOG] 67.42580 INFO
>> juju.provisioner stopping known instances [0xc200388800] [LOG]
>> 67.42583 INFO juju.provisioner removing dead machine "1" [LOG]
>> 67.42627 DEBUG juju.state.apiserver <- [2E] machine-0 
>> {"RequestId":44,"Type":"Provisioner","Request":"Remove","Params":{"Entities":[{"Tag":"machine-1"}]}}
>>
>> 
machine 1 is still dead
>> [LOG] 67.46473 DEBUG juju.state.apiserver -> [2E] machine-0
>> 38.421898ms 
>> {"RequestId":44,"Response":{"Results":[{"Error":null}]}} 
>> Provisioner[""].Remove [LOG] 67.49042 INFO juju.provisioner safe
>> mode changed to false [LOG] 67.49143 DEBUG juju.state.apiserver
>> <- [2E] machine-0 
>> {"RequestId":45,"Type":"Provisioner","Request":"InstanceId","Params":{"Entities":[{"Tag":"machine-0"}]}}
>>
>> 
[LOG] 67.49369 DEBUG juju.state.apiserver -> [2E] machine-0 2.277713ms
>> {"RequestId":45,"Response":{"Results":[{"Error":null,"Result":"i-fake"}]}}
>>
>> 
Provisioner[""].InstanceId
>> provisioner_test.go:768: s.checkStopInstances(c, i1) 
>> provisioner_test.go:240: c.Fatalf("provisioner did not stop an
>> instance") ... Error: provisioner did not stop an instance
>> 
>> [LOG] 69.50940 INFO juju.provisioner Shutting down provisioner
>> task machine-0 [LOG] 69.50969 DEBUG juju.state.apiserver <- [2E]
>> machine-0 
>> {"RequestId":46,"Type":"StringsWatcher","Id":"2","Request":"Stop","Params":{}}
>>
>> 
[LOG] 69.50980 DEBUG juju.state.apiserver -> [2E] machine-0 2.096384324s
>> {"RequestId":39,"Error":"watcher has been 
>> stopped","ErrorCode":"stopped","Response":{}}
>> StringsWatcher["2"].Next [LOG] 69.50988 DEBUG
>> juju.state.apiserver -> [2E] machine-0 212.912us 
>> {"RequestId":46,"Response":{}} StringsWatcher["2"].Stop [LOG]
>> 69.51134 INFO juju.provider.dummy reset environment [LOG]
>> 69.51186 INFO juju.state.apiserver [2D] API connection
>> terminated after 2.454702823s [LOG] 69.51249 INFO
>> juju.state.apiserver [2E] API connection terminated after
>> 2.376433297s [LOG] 69.52889 INFO juju Reset successfully reset
>> admin password [LOG] 69.53760 INFO juju Reset successfully reset
>> admin password [LOG] 69.54642 INFO juju Reset successfully reset
>> admin password OOPS: 28 passed, 1 FAILED --- FAIL: Test (42.51
>> seconds) FAIL FAIL    launchpad.net/juju-core/worker/provisioner
>> 42.838s ok      launchpad.net/juju-core/worker/resumer  1.626s ok
>> launchpad.net/juju-core/worker/terminationworker        0.091s ok
>> launchpad.net/juju-core/worker/uniter   186.327s ok
>> launchpad.net/juju-core/worker/uniter/charm     5.101s ok
>> launchpad.net/juju-core/worker/uniter/debug     1.110s ok
>> launchpad.net/juju-core/worker/uniter/hook      0.021s ok
>> launchpad.net/juju-core/worker/uniter/jujuc     0.491s ok
>> launchpad.net/juju-core/worker/uniter/relation  1.384s ok
>> launchpad.net/juju-core/worker/upgrader 3.689s
>> 
>> warning: building out-of-date packages: launchpad.net/lpad 
>> 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.
>> 
>> 
>> --
>> 
>> https://code.launchpad.net/~waigani/juju-core/lxc-trusty-autostart/+merge/202974
>>
>> 
You are the owner of lp:~waigani/juju-core/lxc-trusty-autostart.
>> 
>

-----BEGIN PGP SIGNATURE-----
Version: GnuPG v1.4.14 (GNU/Linux)
Comment: Using GnuPG with Thunderbird - http://www.enigmail.net/

iQEcBAEBAgAGBQJS5cSNAAoJENzxV2TbLzHwGucIAMLN3gKrT1V1LhH+OmjkkxLz
KIq+xjYi+APDnqOHuTkwe5aJeWmKCoFZwi4vym6Hg9QmJzcp3zDa5nsCN3H/t1Qk
qYSnL/jiq64IeJnSQ7Wg13cQHYZGQENYueNM8R/gw40DLP3Rie3LHVo2HWFhaMCB
RBAP44GoLNbZms0TLidjTzoUJFSU67I2qNmLfHc8rzXeki4JxbOPhZ6qsmlVt0fV
gYJeYnDLKKChNcd3K/gyvUw/v6z8fEjTSWgJD9txwgK0GXW0EfzjYZCxgR+F+Zlw
hFYZEBe5VDyaPXnJSf++9AovGoPrp6+3nxbHs/JYf2317iTjknu5lD3vOBMMm20=
=LVj6
-----END PGP SIGNATURE-----

Revision history for this message

Tim Penhey (thumper) wrote on 2014-01-27:

#

-----BEGIN PGP SIGNED MESSAGE-----
Hash: SHA1

On 27/01/14 15:30, Dimiter Naydenov wrote:
> On 27.01.2014 04:27, Jesse Meek wrote:
>> All tests pass for me. What am I missing? Here are my steps:
>
> The bot is running precise, not trusty, which might imply you've
> written the tests assuming they should be run on trusty?
>
> Dimiter

No... I think it is just a test isolation thing.

I'll work with Jesse on it.

-----BEGIN PGP SIGNATURE-----
Version: GnuPG v1.4.14 (GNU/Linux)
Comment: Using GnuPG with Thunderbird - http://www.enigmail.net/

iEYEARECAAYFAlLlxSkACgkQd1fvI4G7WRBbHACgmvyGTZKD65NI3AS6xHx8kgqZ
kAIAnAyomeR/4zbn4R6hLz6Tt7RMS0Sy
=/ZOW
-----END PGP SIGNATURE-----

Revision history for this message

Tim Penhey (thumper) wrote on 2014-01-27:

#

The test failed because the bot doesn't have lxc installed, and thus the lxc.RemoveDir doesn't exist.

The NetworkSuite doesn't contain the lxc suite, so there is no isolation of the RemoveDir.

In the past, the entire lxc.conf file was just networking. With the addition of the auto start, we now have to have better isolation with this test.

There are a few options, but the one that I think fits best, is to parse the conf template results and just get the results that start with 'lxc.network' as that is what the test cares about.

So, something like

obtained = []string{}
for _, value := range strings.Split(config, "\n")
  if strings.HasPrefix(value, "lxc.network") {
    obtained := append(obtained, value)
  }
}
expected := []string{
  "lxc.network.type = foo",
  "lxc.network.link = bar",
  "lxc.network.flags = up",
}
c.Assert(obtained, gc.DeepEquals, expected)

Revision history for this message

William Reade (fwereade) wrote on 2014-01-27:

#

LGTM in light of the explanation, thanks.

https://codereview.appspot.com/56370043/

juju-core

Merge lp:~waigani/juju-core/lxc-trusty-autostart into lp:~go-bot/juju-core/trunk

Commit message

Description of the change

Preview Diff

Subscribers

 === modified file 'container/lxc/lxc.go'
 --- container/lxc/lxc.go	2013-12-04 02:37:05 +0000
 +++ container/lxc/lxc.go	2014-01-27 07:18:13 +0000
@@ -109,12 +109,17 @@
  		return nil, nil, err
+ 	}
  	logger.Tracef("lxc container created")
--	// Now symlink the config file into the restart directory.
--	containerConfigFile := filepath.Join(LxcContainerDir, name, "config")
--	if err := os.Symlink(containerConfigFile, restartSymlink(name)); err != nil {
--		return nil, nil, err
++	// Now symlink the config file into the restart directory, if it exists.
++	// This is for backwards compatiblity. From Trusty onwards, the auto start
++	// option should be set in the LXC config file, this is done in the networkConfigTemplate
++	// function below.
++	if useRestartDir() {
++		containerConfigFile := filepath.Join(LxcContainerDir, name, "config")
++		if err := os.Symlink(containerConfigFile, restartSymlink(name)); err != nil {
++			return nil, nil, err
++		}
++		logger.Tracef("auto-restart link created")
+ 	}
--	logger.Tracef("auto-restart link created")
  	// Start the lxc container with the appropriate settings for grabbing the
  	// console output and a log file.
@@ -198,7 +203,12 @@
+ `
  func networkConfigTemplate(networkType, networkLink string) string {
--	return fmt.Sprintf(networkTemplate, networkType, networkLink)
++	networkConfig := fmt.Sprintf(networkTemplate, networkType, networkLink)
++	if !useRestartDir() {
++		networkConfig += "lxc.start.auto = 1\n"
++		logger.Tracef("Setting auto start to true in lxc config.")
++	}
++	return networkConfig
+ }
  func generateNetworkConfig(network *container.NetworkConfig) string {
 === modified file 'container/lxc/lxc_test.go'
 --- container/lxc/lxc_test.go	2013-12-16 07:20:01 +0000
 +++ container/lxc/lxc_test.go	2014-01-27 07:18:13 +0000
@@ -8,6 +8,7 @@
  	"io/ioutil"
  	"os"
  	"path/filepath"
++	"strings"
  	stdtesting "testing"
  	gc "launchpad.net/gocheck"
@@ -186,6 +187,25 @@
  	c.Assert(autostartLink, jc.IsSymlink)
+ }
++func (s *LxcSuite) TestStartContainerNoRestartDir(c *gc.C) {
++	err := os.Remove(s.RestartDir)
++	c.Assert(err, gc.IsNil)
++
++	manager := lxc.NewContainerManager(container.ManagerConfig{})
++	instance := containertesting.StartContainer(c, manager, "1/lxc/0")
++	autostartLink := lxc.RestartSymlink(string(instance.Id()))
++
++	config := lxc.NetworkConfigTemplate("foo", "bar")
++	expected := `
++lxc.network.type = foo
++lxc.network.link = bar
++lxc.network.flags = up
++lxc.start.auto = 1
++`
++	c.Assert(config, gc.Equals, expected)
++	c.Assert(autostartLink, jc.DoesNotExist)
++}
++
  func (s *LxcSuite) TestStopContainerRemovesAutostartLink(c *gc.C) {
  	manager := lxc.NewContainerManager(container.ManagerConfig{})
  	instance := containertesting.StartContainer(c, manager, "1/lxc/0")
@@ -231,10 +251,20 @@
  func (*NetworkSuite) TestNetworkConfigTemplate(c *gc.C) {
  	config := lxc.NetworkConfigTemplate("foo", "bar")
--	expected := `
--lxc.network.type = foo
--lxc.network.link = bar
--lxc.network.flags = up
--`
--	c.Assert(config, gc.Equals, expected)
++	//In the past, the entire lxc.conf file was just networking. With the addition
++	//of the auto start, we now have to have better isolate this test. As such, we
++	//parse the conf template results and just get the results that start with
++	//'lxc.network' as that is what the test cares about.
++	obtained := []string{}
++	for _, value := range strings.Split(config, "\n") {
++		if strings.HasPrefix(value, "lxc.network") {
++			obtained = append(obtained, value)
++		}
++	}
++	expected := []string{
++		"lxc.network.type = foo",
++		"lxc.network.link = bar",
++		"lxc.network.flags = up",
++	}
++	c.Assert(obtained, gc.DeepEquals, expected)
+ }
 === added file 'container/lxc/restart.go'
 --- container/lxc/restart.go	1970-01-01 00:00:00 +0000
 +++ container/lxc/restart.go	2014-01-27 07:18:13 +0000
@@ -0,0 +1,16 @@
++package lxc
++
++import (
++	"os"
++)
++
++//If restart dir exists, return true. Otherwise, return false.
++//TODO Should this test LXC version, not existence of restart dir?
++func useRestartDir() bool {
++	if _, err := os.Stat(LxcRestartDir); err != nil {
++		if os.IsNotExist(err) {
++			return false
++		}
++	}
++	return true
++}