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=, 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] {"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] {"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.