The attempt to merge lp:~thumper/juju-core/api-juju-run into lp:juju-core failed. Below is the output from the failed tests. ok launchpad.net/juju-core/agent 1.312s ok launchpad.net/juju-core/agent/tools 0.237s ok launchpad.net/juju-core/bzr 7.102s ok launchpad.net/juju-core/cert 3.459s ok launchpad.net/juju-core/charm 0.586s ? launchpad.net/juju-core/charm/hooks [no test files] ok launchpad.net/juju-core/cloudinit 0.032s ok launchpad.net/juju-core/cloudinit/sshinit 1.071s ok launchpad.net/juju-core/cmd 0.233s ok launchpad.net/juju-core/cmd/charm-admin 0.794s ? 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 181.782s ok launchpad.net/juju-core/cmd/jujud 48.053s ok launchpad.net/juju-core/cmd/plugins/juju-metadata 2.767s ? launchpad.net/juju-core/cmd/plugins/juju-restore [no test files] ok launchpad.net/juju-core/constraints 0.028s ok launchpad.net/juju-core/container 0.036s ok launchpad.net/juju-core/container/factory 0.058s ok launchpad.net/juju-core/container/kvm 0.315s ok launchpad.net/juju-core/container/kvm/mock 0.043s ? launchpad.net/juju-core/container/kvm/testing [no test files] ok launchpad.net/juju-core/container/lxc 0.282s ? 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.307s ok launchpad.net/juju-core/environs 3.171s ok launchpad.net/juju-core/environs/bootstrap 4.483s ok launchpad.net/juju-core/environs/cloudinit 0.503s ok launchpad.net/juju-core/environs/config 1.076s ok launchpad.net/juju-core/environs/configstore 0.042s ok launchpad.net/juju-core/environs/filestorage 0.033s ok launchpad.net/juju-core/environs/httpstorage 0.995s ok launchpad.net/juju-core/environs/imagemetadata 0.513s ? launchpad.net/juju-core/environs/imagemetadata/testing [no test files] ok launchpad.net/juju-core/environs/instances 0.067s ok launchpad.net/juju-core/environs/jujutest 0.233s ok launchpad.net/juju-core/environs/manual 10.116s ok launchpad.net/juju-core/environs/simplestreams 0.379s ? launchpad.net/juju-core/environs/simplestreams/testing [no test files] ok launchpad.net/juju-core/environs/sshstorage 1.136s ok launchpad.net/juju-core/environs/storage 1.165s ok launchpad.net/juju-core/environs/sync 31.266s ok launchpad.net/juju-core/environs/testing 0.265s ok launchpad.net/juju-core/environs/tools 6.800s ? 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 16.194s ok launchpad.net/juju-core/juju/osenv 0.019s ? launchpad.net/juju-core/juju/testing [no test files] ok launchpad.net/juju-core/log 0.016s ok launchpad.net/juju-core/log/syslog 0.025s ok launchpad.net/juju-core/names 0.042s ? 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.418s ok launchpad.net/juju-core/provider/common 5.282s ok launchpad.net/juju-core/provider/dummy 22.797s ok launchpad.net/juju-core/provider/ec2 15.364s ok launchpad.net/juju-core/provider/ec2/httpstorage 0.210s ok launchpad.net/juju-core/provider/joyent 5.335s ok launchpad.net/juju-core/provider/local 2.419s ok launchpad.net/juju-core/provider/maas 24.060s ok launchpad.net/juju-core/provider/null 1.219s ok launchpad.net/juju-core/provider/openstack 25.617s ok launchpad.net/juju-core/replicaset 12.308s ok launchpad.net/juju-core/rpc 0.087s ok launchpad.net/juju-core/rpc/jsoncodec 0.043s ? launchpad.net/juju-core/rpc/rpcreflect [no test files] ok launchpad.net/juju-core/schema 0.039s ok launchpad.net/juju-core/state 84.150s ok launchpad.net/juju-core/state/api 1.937s ok launchpad.net/juju-core/state/api/agent 2.121s ? launchpad.net/juju-core/state/api/common [no test files] ok launchpad.net/juju-core/state/api/deployer 5.767s ok launchpad.net/juju-core/state/api/keymanager 3.506s ok launchpad.net/juju-core/state/api/keyupdater 2.736s ok launchpad.net/juju-core/state/api/logger 2.303s ok launchpad.net/juju-core/state/api/machiner 3.134s ok launchpad.net/juju-core/state/api/params 0.036s ok launchpad.net/juju-core/state/api/provisioner 10.266s ok launchpad.net/juju-core/state/api/uniter 26.560s ok launchpad.net/juju-core/state/api/upgrader 3.637s ok launchpad.net/juju-core/state/api/watcher 3.559s ok launchpad.net/juju-core/state/apiserver 7.306s ok launchpad.net/juju-core/state/apiserver/agent 3.115s ok launchpad.net/juju-core/state/apiserver/client 44.245s ok launchpad.net/juju-core/state/apiserver/common 1.480s ok launchpad.net/juju-core/state/apiserver/deployer 4.532s ok launchpad.net/juju-core/state/apiserver/keymanager 4.711s ? launchpad.net/juju-core/state/apiserver/keymanager/testing [no test files] ok launchpad.net/juju-core/state/apiserver/keyupdater 3.011s ok launchpad.net/juju-core/state/apiserver/logger 3.728s ok launchpad.net/juju-core/state/apiserver/machine 4.026s ok launchpad.net/juju-core/state/apiserver/provisioner 11.863s ? launchpad.net/juju-core/state/apiserver/testing [no test files] ok launchpad.net/juju-core/state/apiserver/uniter 18.501s ok launchpad.net/juju-core/state/apiserver/upgrader 5.555s ok launchpad.net/juju-core/state/multiwatcher 0.737s ok launchpad.net/juju-core/state/presence 6.453s ? launchpad.net/juju-core/state/statecmd [no test files] ? launchpad.net/juju-core/state/testing [no test files] ok launchpad.net/juju-core/state/watcher 6.070s ok launchpad.net/juju-core/store 19.490s ok launchpad.net/juju-core/testing 1.369s ok launchpad.net/juju-core/testing/checkers 0.033s 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.019s ok launchpad.net/juju-core/upstart 1.441s ok launchpad.net/juju-core/utils 2.522s ok launchpad.net/juju-core/utils/fslock 1.043s ok launchpad.net/juju-core/utils/parallel 0.958s ok launchpad.net/juju-core/utils/set 0.019s ok launchpad.net/juju-core/utils/ssh 1.768s ? launchpad.net/juju-core/utils/ssh/testing [no test files] ok launchpad.net/juju-core/utils/tailer 0.971s ok launchpad.net/juju-core/version 0.071s ok launchpad.net/juju-core/worker 6.662s ok launchpad.net/juju-core/worker/addressupdater 1.426s ok launchpad.net/juju-core/worker/authenticationworker 3.276s ok launchpad.net/juju-core/worker/cleaner 1.235s ok launchpad.net/juju-core/worker/deployer 3.766s ok launchpad.net/juju-core/worker/firewaller 10.752s ok launchpad.net/juju-core/worker/localstorage 0.048s ok launchpad.net/juju-core/worker/logger 1.516s ok launchpad.net/juju-core/worker/machiner 2.761s ok launchpad.net/juju-core/worker/minunitsworker 1.298s ---------------------------------------------------------------------- FAIL: provisioner_test.go:743: ProvisionerSuite.TestTurningOffSafeModeReapsUnknownInstances [LOG] 13.26830 DEBUG juju.environs.configstore Making /tmp/gocheck-4324745483838182873/43/home/ubuntu/.juju/environments [LOG] 13.36505 DEBUG juju.environs.tools reading v1.* tools [LOG] 13.36509 INFO juju environs/testing: uploading FAKE tools 1.17.1-precise-amd64 [LOG] 13.36597 DEBUG juju.environs.tools no architecture specified when finding tools, looking for any [LOG] 13.36599 DEBUG juju.environs.tools no series specified when finding tools, looking for any [LOG] 13.36606 DEBUG juju.environs.simplestreams fetchData failed for "http://127.0.0.1:40332/dummyenv/private/tools/streams/v1/index.sjson": file "tools/streams/v1/index.sjson" not found not found [LOG] 13.36608 DEBUG juju.environs.simplestreams cannot load index "http://127.0.0.1:40332/dummyenv/private/tools/streams/v1/index.sjson": invalid URL "http://127.0.0.1:40332/dummyenv/private/tools/streams/v1/index.sjson" not found [LOG] 13.36613 DEBUG juju.environs.simplestreams fetchData failed for "http://127.0.0.1:40332/dummyenv/private/tools/streams/v1/index.json": file "tools/streams/v1/index.json" not found not found [LOG] 13.36614 DEBUG juju.environs.simplestreams cannot load index "http://127.0.0.1:40332/dummyenv/private/tools/streams/v1/index.json": invalid URL "http://127.0.0.1:40332/dummyenv/private/tools/streams/v1/index.json" not found [LOG] 13.36639 INFO juju.environs.tools Writing tools/streams/v1/index.json [LOG] 13.36650 INFO juju.environs.tools Writing tools/streams/v1/com.ubuntu.juju:released:tools.json [LOG] 13.36664 INFO juju.environs.bootstrap bootstrapping environment "dummyenv" [LOG] 13.36667 DEBUG juju.environs.bootstrap looking for bootstrap tools: series="precise", arch=, version=1.17.1 [LOG] 13.36668 INFO juju.environs.tools reading tools with major.minor version 1.17 [LOG] 13.36670 INFO juju.environs.tools filtering tools by version: 1.17.1 [LOG] 13.36670 INFO juju.environs.tools filtering tools by series: precise [LOG] 13.36672 DEBUG juju.environs.tools no architecture specified when finding tools, looking for any [LOG] 13.36676 DEBUG juju.environs.simplestreams fetchData failed for "http://127.0.0.1:40332/dummyenv/private/tools/streams/v1/index.sjson": file "tools/streams/v1/index.sjson" not found not found [LOG] 13.36678 DEBUG juju.environs.simplestreams cannot load index "http://127.0.0.1:40332/dummyenv/private/tools/streams/v1/index.sjson": invalid URL "http://127.0.0.1:40332/dummyenv/private/tools/streams/v1/index.sjson" not found [LOG] 13.36688 DEBUG juju.environs.simplestreams fetchData failed for "http://127.0.0.1:40332/dummyenv/private/tools/streams/v1/mirrors.json": file "tools/streams/v1/mirrors.json" not found not found [LOG] 13.36690 DEBUG juju.environs.simplestreams no mirror index file found [LOG] 13.36694 DEBUG juju.environs.simplestreams no mirror information available for { }: mirror data for "com.ubuntu.juju:released:tools" not found [LOG] 13.36695 DEBUG juju.environs.simplestreams read metadata index at "http://127.0.0.1:40332/dummyenv/private/tools/streams/v1/index.json" [LOG] 13.36702 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 [{Sun, 12 Jan 2014 21:22:14 +0000 products:1.0 content-download [] streams/v1/com.ubuntu.juju:released:tools.json [com.ubuntu.juju:12.04:amd64]}] [LOG] 13.36704 DEBUG juju.environs.simplestreams finding products at path "streams/v1/com.ubuntu.juju:released:tools.json" [LOG] 13.36725 DEBUG juju.environs.simplestreams metadata: &{map[com.ubuntu.juju:12.04:amd64:{ 1.17.1 amd64 map[20140112:0xc20092e660]}] map[] Sun, 12 Jan 2014 21:22:14 +0000 products:1.0 com.ubuntu.juju:released:tools} [LOG] 13.36730 INFO juju.environs.bootstrap picked newest version: 1.17.1 [LOG] 13.36733 INFO juju.provider.dummy would pick tools from 1.17.1-precise-amd64 [LOG] 13.36772 INFO juju.state opening state; mongo addresses: ["localhost:56974"]; entity "" [LOG] 13.38792 INFO juju.state connection established [LOG] 13.43157 INFO juju.state initializing environment [LOG] 13.45358 INFO juju.state.apiserver listening on "127.0.0.1:39200" [LOG] 13.47153 INFO juju.state opening state; mongo addresses: ["localhost:56974"]; entity "" [LOG] 13.47435 INFO juju.state connection established [LOG] 13.49251 INFO juju juju: authorization error while connecting to state server; retrying [LOG] 13.49256 INFO juju.state opening state; mongo addresses: ["localhost:56974"]; entity "" [LOG] 13.49593 INFO juju.state connection established [LOG] 13.54160 INFO juju state/api: dialing "wss://127.0.0.1:39200/" [LOG] 13.54510 INFO juju state/api: connection established [LOG] 13.54530 DEBUG juju.state.apiserver <- [2D] {"RequestId":1,"Type":"Admin","Request":"Login","Params":{"AuthTag":"user-admin","Password":"dummy-secret","Nonce":""}} [LOG] 13.54564 DEBUG juju.state.apiserver -> [2D] user-admin 346.524us {"RequestId":1,"Response":{}} Admin[""].Login [LOG] 13.61719 INFO juju state/api: dialing "wss://127.0.0.1:39200/" [LOG] 13.62034 INFO juju state/api: connection established [LOG] 13.62050 DEBUG juju.state.apiserver <- [2E] {"RequestId":1,"Type":"Admin","Request":"Login","Params":{"AuthTag":"machine-0","Password":"8H5/mq93akobW26sJO8T6o/k","Nonce":"fake_nonce"}} [LOG] 13.63172 DEBUG juju.state.apiserver -> [2E] machine-0 11.181944ms {"RequestId":1,"Response":{}} Admin[""].Login API: login as "machine-0" successful [LOG] 13.65279 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":3,"Type":"Provisioner","Request":"WatchEnvironMachines","Params":{}} [LOG] 13.65348 DEBUG juju.state.apiserver -> [2E] machine-0 702.604us {"RequestId":3,"Response":{"StringsWatcherId":"2","Changes":["0"],"Error":null}} Provisioner[""].WatchEnvironMachines [LOG] 13.65371 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":4,"Type":"Provisioner","Request":"StateAddresses","Params":{}} [LOG] 13.65377 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":5,"Type":"StringsWatcher","Id":"2","Request":"Next","Params":{}} [LOG] 13.65506 DEBUG juju.state.apiserver -> [2E] machine-0 1.339808ms {"RequestId":4,"Response":{"Error":null,"Result":["0.1.2.3:1234"]}} Provisioner[""].StateAddresses [LOG] 13.65526 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":6,"Type":"Provisioner","Request":"APIAddresses","Params":{}} [LOG] 13.65658 DEBUG juju.state.apiserver -> [2E] machine-0 1.314622ms {"RequestId":6,"Response":{"Error":null,"Result":["0.1.2.3:4321"]}} Provisioner[""].APIAddresses [LOG] 13.65677 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":7,"Type":"Provisioner","Request":"CACert","Params":{}} [LOG] 13.65684 DEBUG juju.state.apiserver -> [2E] machine-0 49.638us {"RequestId":7,"Response":{"Result":"LS0tLS1CRUdJTiBDRVJUSUZJQ0FURS0tLS0tCk1JSUI0akNDQVk2Z0F3SUJBZ0lCQURBTEJna3Foa2lHOXcwQkFRVXdTakVOTUFzR0ExVUVDaE1FYW5WcWRURTUKTURjR0ExVUVBd3d3YW5WcWRTMW5aVzVsY21GMFpXUWdRMEVnWm05eUlHVnVkbWx5YjI1dFpXNTBJQ0pxZFdwMQpJSFJsYzNScGJtY2lNQjRYRFRFME1ERXhNakl4TVRZME9Wb1hEVEkwTURFeE1qSXhNakUwT1Zvd1NqRU5NQXNHCkExVUVDaE1FYW5WcWRURTVNRGNHQTFVRUF3d3dhblZxZFMxblpXNWxjbUYwWldRZ1EwRWdabTl5SUdWdWRtbHkKYjI1dFpXNTBJQ0pxZFdwMUlIUmxjM1JwYm1jaU1Gb3dDd1lKS29aSWh2Y05BUUVCQTBzQU1FZ0NRUUM5NWxUTwpDTVM0ZFRLWDJaYnc2ZmRnSDJjNEtWYzR0TUpkWVNDcTc1QWpqQnArYVBVcWtmUmcxbUllTVZnd0ZRNmJrbjBCCmxGbDBBaXN4SVc4QlBFdU5BZ01CQUFHall6QmhNQTRHQTFVZER3RUIvd1FFQXdJQXBEQVBCZ05WSFJNQkFmOEUKQlRBREFRSC9NQjBHQTFVZERnUVdCQlRSdFBYcHVrZ1NCSXJyZDNxLzVPYTNMOUJEYmpBZkJnTlZIU01FR0RBVwpnQlRSdFBYcHVrZ1NCSXJyZDNxLzVPYTNMOUJEYmpBTEJna3Foa2lHOXcwQkFRVURRUUNlZkV5dGlxRkptRmxRCnBKTU1UZUlGdjUrYTVuNkFPeUNTV3RLVnJwTnhsNVNiVUhza0pPRDdWTDh2eWdwVjRUUGIyMlNtdXpTV3ltMzcKaHRiZDg1OFkKLS0tLS1FTkQgQ0VSVElGSUNBVEUtLS0tLQo="}} Provisioner[""].CACert [LOG] 13.65710 INFO juju.provisioner Starting up provisioner task machine-0 [LOG] 13.65739 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":8,"Type":"Provisioner","Request":"Life","Params":{"Entities":[{"Tag":"machine-0"}]}} [LOG] 13.65769 DEBUG juju.state.apiserver -> [2E] machine-0 309.085us {"RequestId":8,"Response":{"Results":[{"Life":"alive","Error":null}]}} Provisioner[""].Life [LOG] 13.65811 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":9,"Type":"Provisioner","Request":"InstanceId","Params":{"Entities":[{"Tag":"machine-0"}]}} [LOG] 13.65840 DEBUG juju.state.apiserver -> [2E] machine-0 297.815us {"RequestId":9,"Response":{"Results":[{"Error":null,"Result":"i-fake"}]}} Provisioner[""].InstanceId [LOG] 13.65860 INFO juju.provisioner machine 0 already started as instance "i-fake" [LOG] 13.65871 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":10,"Type":"Provisioner","Request":"InstanceId","Params":{"Entities":[{"Tag":"machine-0"}]}} [LOG] 13.65899 DEBUG juju.state.apiserver -> [2E] machine-0 283.922us {"RequestId":10,"Response":{"Results":[{"Error":null,"Result":"i-fake"}]}} Provisioner[""].InstanceId [LOG] 13.65916 INFO juju.provisioner running in safe mode, unknown instances not stopped [] [LOG] 13.69180 DEBUG juju.state.apiserver -> [2E] machine-0 37.832182ms {"RequestId":5,"Response":{"StringsWatcherId":"","Changes":["1"],"Error":null}} StringsWatcher["2"].Next [LOG] 13.69280 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":11,"Type":"StringsWatcher","Id":"2","Request":"Next","Params":{}} [LOG] 13.69299 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":12,"Type":"Provisioner","Request":"Life","Params":{"Entities":[{"Tag":"machine-1"}]}} [LOG] 13.69446 DEBUG juju.state.apiserver -> [2E] machine-0 1.447247ms {"RequestId":12,"Response":{"Results":[{"Life":"alive","Error":null}]}} Provisioner[""].Life [LOG] 13.69506 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":13,"Type":"Provisioner","Request":"InstanceId","Params":{"Entities":[{"Tag":"machine-1"}]}} [LOG] 13.69708 DEBUG juju.state.apiserver -> [2E] machine-0 2.012145ms {"RequestId":13,"Response":{"Results":[{"Error":{"Message":"machine 1 is not provisioned","Code":"not provisioned"},"Result":""}]}} Provisioner[""].InstanceId [LOG] 13.69775 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":14,"Type":"Provisioner","Request":"Status","Params":{"Entities":[{"Tag":"machine-1"}]}} [LOG] 13.69926 DEBUG juju.state.apiserver -> [2E] machine-0 1.495957ms {"RequestId":14,"Response":{"Results":[{"Error":null,"Status":"pending","Info":""}]}} Provisioner[""].Status [LOG] 13.69958 INFO juju.provisioner found machine "1" pending provisioning [LOG] 13.69996 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":15,"Type":"Provisioner","Request":"InstanceId","Params":{"Entities":[{"Tag":"machine-0"}]}} [LOG] 13.70370 DEBUG juju.state.apiserver -> [2E] machine-0 3.701688ms {"RequestId":15,"Response":{"Results":[{"Error":null,"Result":"i-fake"}]}} Provisioner[""].InstanceId [LOG] 13.70446 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":16,"Type":"Provisioner","Request":"InstanceId","Params":{"Entities":[{"Tag":"machine-1"}]}} [LOG] 13.70583 DEBUG juju.state.apiserver -> [2E] machine-0 1.36458ms {"RequestId":16,"Response":{"Results":[{"Error":{"Message":"machine 1 is not provisioned","Code":"not provisioned"},"Result":""}]}} Provisioner[""].InstanceId [LOG] 13.70616 INFO juju.provisioner running in safe mode, unknown instances not stopped [] [LOG] 13.70646 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":17,"Type":"Provisioner","Request":"Constraints","Params":{"Entities":[{"Tag":"machine-1"}]}} [LOG] 13.70802 DEBUG juju.state.apiserver -> [2E] machine-0 1.475064ms {"RequestId":17,"Response":{"Results":[{"Error":null,"Constraints":{"arch":"amd64","cpu-cores":1,"mem":4096,"root-disk":8192}}]}} Provisioner[""].Constraints [LOG] 13.70874 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":18,"Type":"Provisioner","Request":"Series","Params":{"Entities":[{"Tag":"machine-1"}]}} [LOG] 13.70992 DEBUG juju.state.apiserver -> [2E] machine-0 1.158216ms {"RequestId":18,"Response":{"Results":[{"Error":null,"Result":"precise"}]}} Provisioner[""].Series [LOG] 13.71029 INFO juju.environs.tools reading tools with major.minor version 1.17 [LOG] 13.71034 INFO juju.environs.tools filtering tools by version: 1.17.1 [LOG] 13.71036 INFO juju.environs.tools filtering tools by series: precise [LOG] 13.71039 INFO juju.environs.tools filtering tools by architecture: amd64 [LOG] 13.71059 DEBUG juju.environs.simplestreams fetchData failed for "http://127.0.0.1:40332/dummyenv/private/tools/streams/v1/index.sjson": file "tools/streams/v1/index.sjson" not found not found [LOG] 13.71065 DEBUG juju.environs.simplestreams cannot load index "http://127.0.0.1:40332/dummyenv/private/tools/streams/v1/index.sjson": invalid URL "http://127.0.0.1:40332/dummyenv/private/tools/streams/v1/index.sjson" not found [LOG] 13.71089 DEBUG juju.environs.simplestreams fetchData failed for "http://127.0.0.1:40332/dummyenv/private/tools/streams/v1/mirrors.json": file "tools/streams/v1/mirrors.json" not found not found [LOG] 13.71094 DEBUG juju.environs.simplestreams no mirror index file found [LOG] 13.71100 DEBUG juju.environs.simplestreams no mirror information available for { }: mirror data for "com.ubuntu.juju:released:tools" not found [LOG] 13.71103 DEBUG juju.environs.simplestreams read metadata index at "http://127.0.0.1:40332/dummyenv/private/tools/streams/v1/index.json" [LOG] 13.71114 DEBUG juju.environs.simplestreams candidate matches for products ["com.ubuntu.juju:12.04:amd64"] are [{Sun, 12 Jan 2014 21:22:14 +0000 products:1.0 content-download [] streams/v1/com.ubuntu.juju:released:tools.json [com.ubuntu.juju:12.04:amd64]}] [LOG] 13.71118 DEBUG juju.environs.simplestreams finding products at path "streams/v1/com.ubuntu.juju:released:tools.json" [LOG] 13.71169 DEBUG juju.environs.simplestreams metadata: &{map[com.ubuntu.juju:12.04:amd64:{ 1.17.1 amd64 map[20140112:0xc200ce7f60]}] map[] Sun, 12 Jan 2014 21:22:14 +0000 products:1.0 com.ubuntu.juju:released:tools} [LOG] 13.71257 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":19,"Type":"Provisioner","Request":"SetPasswords","Params":{"Changes":[{"Tag":"machine-1","Password":"OKkdKbnrBZi0jufXp8CUdZTw"}]}} [LOG] 13.72474 INFO juju setting password for "machine-1" [LOG] 13.72488 DEBUG juju.state.apiserver -> [2E] machine-0 12.342095ms {"RequestId":19,"Response":{"Results":[{"Error":null}]}} Provisioner[""].SetPasswords [LOG] 13.72540 INFO juju.provider.dummy dummy startinstance, machine 1 [LOG] 13.72548 INFO juju.provider.dummy would pick tools from 1.17.1-precise-amd64 [LOG] 13.72651 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":20,"Type":"Provisioner","Request":"SetProvisioned","Params":{"Machines":[{"Tag":"machine-1","InstanceId":"dummyenv-0","Nonce":"machine-0:ec69d2ea-d2a1-4278-8cbc-1e5633034847","Characteristics":{"Arch":"amd64","Mem":4096,"RootDisk":8192,"CpuCores":1,"CpuPower":null,"Tags":null}}]}} machine 1 is still unprovisioned [LOG] 13.74112 DEBUG juju.state.apiserver -> [2E] machine-0 14.667909ms {"RequestId":20,"Response":{"Results":[{"Error":null}]}} Provisioner[""].SetProvisioned [LOG] 13.74140 INFO juju.provisioner started machine 1 as instance dummyenv-0 with hardware "arch=amd64 cpu-cores=1 mem=4096M root-disk=8192M" [LOG] 13.82836 DEBUG juju.state.apiserver -> [2E] machine-0 135.558385ms {"RequestId":11,"Response":{"StringsWatcherId":"","Changes":["2"],"Error":null}} StringsWatcher["2"].Next [LOG] 13.82873 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":21,"Type":"StringsWatcher","Id":"2","Request":"Next","Params":{}} [LOG] 13.82879 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":22,"Type":"Provisioner","Request":"Life","Params":{"Entities":[{"Tag":"machine-2"}]}} [LOG] 13.82922 DEBUG juju.state.apiserver -> [2E] machine-0 392.41us {"RequestId":22,"Response":{"Results":[{"Life":"alive","Error":null}]}} Provisioner[""].Life [LOG] 13.82942 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":23,"Type":"Provisioner","Request":"InstanceId","Params":{"Entities":[{"Tag":"machine-2"}]}} [LOG] 13.82996 DEBUG juju.state.apiserver -> [2E] machine-0 512.401us {"RequestId":23,"Response":{"Results":[{"Error":{"Message":"machine 2 is not provisioned","Code":"not provisioned"},"Result":""}]}} Provisioner[""].InstanceId [LOG] 13.83016 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":24,"Type":"Provisioner","Request":"Status","Params":{"Entities":[{"Tag":"machine-2"}]}} [LOG] 13.83071 DEBUG juju.state.apiserver -> [2E] machine-0 524.966us {"RequestId":24,"Response":{"Results":[{"Error":null,"Status":"pending","Info":""}]}} Provisioner[""].Status [LOG] 13.83083 INFO juju.provisioner found machine "2" pending provisioning [LOG] 13.83095 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":25,"Type":"Provisioner","Request":"InstanceId","Params":{"Entities":[{"Tag":"machine-0"}]}} [LOG] 13.83137 DEBUG juju.state.apiserver -> [2E] machine-0 390.316us {"RequestId":25,"Response":{"Results":[{"Error":null,"Result":"i-fake"}]}} Provisioner[""].InstanceId [LOG] 13.83171 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":26,"Type":"Provisioner","Request":"InstanceId","Params":{"Entities":[{"Tag":"machine-1"}]}} [LOG] 13.83227 DEBUG juju.state.apiserver -> [2E] machine-0 552.011us {"RequestId":26,"Response":{"Results":[{"Error":null,"Result":"dummyenv-0"}]}} Provisioner[""].InstanceId [LOG] 13.83266 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":27,"Type":"Provisioner","Request":"InstanceId","Params":{"Entities":[{"Tag":"machine-2"}]}} [LOG] 13.83335 DEBUG juju.state.apiserver -> [2E] machine-0 646.171us {"RequestId":27,"Response":{"Results":[{"Error":{"Message":"machine 2 is not provisioned","Code":"not provisioned"},"Result":""}]}} Provisioner[""].InstanceId [LOG] 13.83361 INFO juju.provisioner running in safe mode, unknown instances not stopped [] [LOG] 13.83383 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":28,"Type":"Provisioner","Request":"Constraints","Params":{"Entities":[{"Tag":"machine-2"}]}} [LOG] 13.83452 DEBUG juju.state.apiserver -> [2E] machine-0 713.485us {"RequestId":28,"Response":{"Results":[{"Error":null,"Constraints":{"arch":"amd64","cpu-cores":1,"mem":4096,"root-disk":8192}}]}} Provisioner[""].Constraints [LOG] 13.83486 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":29,"Type":"Provisioner","Request":"Series","Params":{"Entities":[{"Tag":"machine-2"}]}} [LOG] 13.83524 DEBUG juju.state.apiserver -> [2E] machine-0 401.498us {"RequestId":29,"Response":{"Results":[{"Error":null,"Result":"precise"}]}} Provisioner[""].Series [LOG] 13.83570 INFO juju.environs.tools reading tools with major.minor version 1.17 [LOG] 13.83574 INFO juju.environs.tools filtering tools by version: 1.17.1 [LOG] 13.83575 INFO juju.environs.tools filtering tools by series: precise [LOG] 13.83576 INFO juju.environs.tools filtering tools by architecture: amd64 [LOG] 13.83592 DEBUG juju.environs.simplestreams fetchData failed for "http://127.0.0.1:40332/dummyenv/private/tools/streams/v1/index.sjson": file "tools/streams/v1/index.sjson" not found not found [LOG] 13.83594 DEBUG juju.environs.simplestreams cannot load index "http://127.0.0.1:40332/dummyenv/private/tools/streams/v1/index.sjson": invalid URL "http://127.0.0.1:40332/dummyenv/private/tools/streams/v1/index.sjson" not found [LOG] 13.83610 DEBUG juju.environs.simplestreams fetchData failed for "http://127.0.0.1:40332/dummyenv/private/tools/streams/v1/mirrors.json": file "tools/streams/v1/mirrors.json" not found not found [LOG] 13.83611 DEBUG juju.environs.simplestreams no mirror index file found [LOG] 13.83614 DEBUG juju.environs.simplestreams no mirror information available for { }: mirror data for "com.ubuntu.juju:released:tools" not found [LOG] 13.83618 DEBUG juju.environs.simplestreams read metadata index at "http://127.0.0.1:40332/dummyenv/private/tools/streams/v1/index.json" [LOG] 13.83622 DEBUG juju.environs.simplestreams candidate matches for products ["com.ubuntu.juju:12.04:amd64"] are [{Sun, 12 Jan 2014 21:22:14 +0000 products:1.0 content-download [] streams/v1/com.ubuntu.juju:released:tools.json [com.ubuntu.juju:12.04:amd64]}] [LOG] 13.83626 DEBUG juju.environs.simplestreams finding products at path "streams/v1/com.ubuntu.juju:released:tools.json" [LOG] 13.83655 DEBUG juju.environs.simplestreams metadata: &{map[com.ubuntu.juju:12.04:amd64:{ 1.17.1 amd64 map[20140112:0xc2009bd780]}] map[] Sun, 12 Jan 2014 21:22:14 +0000 products:1.0 com.ubuntu.juju:released:tools} [LOG] 13.83685 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":30,"Type":"Provisioner","Request":"SetPasswords","Params":{"Changes":[{"Tag":"machine-2","Password":"D0a4CyiPPPXgADkBJEl4S6EO"}]}} [LOG] 13.84141 INFO juju setting password for "machine-2" [LOG] 13.84150 DEBUG juju.state.apiserver -> [2E] machine-0 4.622146ms {"RequestId":30,"Response":{"Results":[{"Error":null}]}} Provisioner[""].SetPasswords [LOG] 13.84176 INFO juju.provider.dummy dummy startinstance, machine 2 [LOG] 13.84180 INFO juju.provider.dummy would pick tools from 1.17.1-precise-amd64 [LOG] 13.84240 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":31,"Type":"Provisioner","Request":"SetProvisioned","Params":{"Machines":[{"Tag":"machine-2","InstanceId":"dummyenv-1","Nonce":"machine-0:9e13fd14-72d9-4c15-8aa3-1d9bca5f7e7e","Characteristics":{"Arch":"amd64","Mem":4096,"RootDisk":8192,"CpuCores":1,"CpuPower":null,"Tags":null}}]}} [LOG] 13.84852 DEBUG juju.state.apiserver -> [2E] machine-0 6.155143ms {"RequestId":31,"Response":{"Results":[{"Error":null}]}} Provisioner[""].SetProvisioned [LOG] 13.84879 INFO juju.provisioner started machine 2 as instance dummyenv-1 with hardware "arch=amd64 cpu-cores=1 mem=4096M root-disk=8192M" [LOG] 13.85856 DEBUG juju.state.apiserver -> [2E] machine-0 29.808691ms {"RequestId":21,"Response":{"StringsWatcherId":"","Changes":["2"],"Error":null}} StringsWatcher["2"].Next [LOG] 13.85898 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":32,"Type":"StringsWatcher","Id":"2","Request":"Next","Params":{}} [LOG] 13.85908 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":33,"Type":"Provisioner","Request":"Life","Params":{"Entities":[{"Tag":"machine-2"}]}} [LOG] 13.85990 DEBUG juju.state.apiserver -> [2E] machine-0 797.036us {"RequestId":33,"Response":{"Results":[{"Life":"dead","Error":null}]}} Provisioner[""].Life [LOG] 13.86018 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":34,"Type":"Provisioner","Request":"InstanceId","Params":{"Entities":[{"Tag":"machine-2"}]}} [LOG] 13.86083 DEBUG juju.state.apiserver -> [2E] machine-0 653.829us {"RequestId":34,"Response":{"Results":[{"Error":null,"Result":"dummyenv-1"}]}} Provisioner[""].InstanceId [LOG] 13.86111 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":35,"Type":"Provisioner","Request":"InstanceId","Params":{"Entities":[{"Tag":"machine-0"}]}} [LOG] 13.86177 DEBUG juju.state.apiserver -> [2E] machine-0 660.387us {"RequestId":35,"Response":{"Results":[{"Error":null,"Result":"i-fake"}]}} Provisioner[""].InstanceId [LOG] 13.86204 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":36,"Type":"Provisioner","Request":"InstanceId","Params":{"Entities":[{"Tag":"machine-1"}]}} [LOG] 13.86269 DEBUG juju.state.apiserver -> [2E] machine-0 647.147us {"RequestId":36,"Response":{"Results":[{"Error":null,"Result":"dummyenv-0"}]}} Provisioner[""].InstanceId [LOG] 13.86298 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":37,"Type":"Provisioner","Request":"InstanceId","Params":{"Entities":[{"Tag":"machine-2"}]}} [LOG] 13.86362 DEBUG juju.state.apiserver -> [2E] machine-0 640.341us {"RequestId":37,"Response":{"Results":[{"Error":null,"Result":"dummyenv-1"}]}} Provisioner[""].InstanceId [LOG] 13.86378 INFO juju.provisioner running in safe mode, unknown instances not stopped [] [LOG] 13.86379 INFO juju.provisioner stopping known instances [0xc2007bb850] [LOG] 13.86397 INFO juju.provisioner removing dead machine "2" [LOG] 13.86421 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] 13.90071 DEBUG juju.state.apiserver -> [2E] machine-0 41.69785ms {"RequestId":32,"Response":{"StringsWatcherId":"","Changes":["1"],"Error":null}} StringsWatcher["2"].Next [LOG] 13.90096 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":39,"Type":"StringsWatcher","Id":"2","Request":"Next","Params":{}} [LOG] 13.90602 DEBUG juju.state.apiserver -> [2E] machine-0 41.775691ms {"RequestId":38,"Response":{"Results":[{"Error":null}]}} Provisioner[""].Remove [LOG] 13.90638 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":40,"Type":"Provisioner","Request":"Life","Params":{"Entities":[{"Tag":"machine-1"}]}} [LOG] 13.90675 DEBUG juju.state.apiserver -> [2E] machine-0 366.452us {"RequestId":40,"Response":{"Results":[{"Life":"dead","Error":null}]}} Provisioner[""].Life [LOG] 13.90707 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":41,"Type":"Provisioner","Request":"InstanceId","Params":{"Entities":[{"Tag":"machine-1"}]}} [LOG] 13.90742 DEBUG juju.state.apiserver -> [2E] machine-0 406.051us {"RequestId":41,"Response":{"Results":[{"Error":null,"Result":"dummyenv-0"}]}} Provisioner[""].InstanceId [LOG] 13.90769 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":42,"Type":"Provisioner","Request":"InstanceId","Params":{"Entities":[{"Tag":"machine-0"}]}} [LOG] 13.90806 DEBUG juju.state.apiserver -> [2E] machine-0 366.92us {"RequestId":42,"Response":{"Results":[{"Error":null,"Result":"i-fake"}]}} Provisioner[""].InstanceId [LOG] 13.90833 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":43,"Type":"Provisioner","Request":"InstanceId","Params":{"Entities":[{"Tag":"machine-1"}]}} [LOG] 13.90867 DEBUG juju.state.apiserver -> [2E] machine-0 337.966us {"RequestId":43,"Response":{"Results":[{"Error":null,"Result":"dummyenv-0"}]}} Provisioner[""].InstanceId [LOG] 13.90880 INFO juju.provisioner running in safe mode, unknown instances not stopped [] [LOG] 13.90884 INFO juju.provisioner stopping known instances [0xc20073d150] [LOG] 13.90886 INFO juju.provisioner removing dead machine "1" [LOG] 13.90910 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":44,"Type":"Provisioner","Request":"Remove","Params":{"Entities":[{"Tag":"machine-1"}]}} machine 1 is still dead [LOG] 13.92556 DEBUG juju.state.apiserver -> [2E] machine-0 16.469904ms {"RequestId":44,"Response":{"Results":[{"Error":null}]}} Provisioner[""].Remove [LOG] 13.97374 INFO juju.provisioner safe mode changed to false [LOG] 13.97427 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":45,"Type":"Provisioner","Request":"InstanceId","Params":{"Entities":[{"Tag":"machine-0"}]}} [LOG] 13.97590 DEBUG juju.state.apiserver -> [2E] machine-0 1.591353ms {"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] 15.98443 INFO juju.provisioner Shutting down provisioner task machine-0 [LOG] 15.98480 DEBUG juju.state.apiserver <- [2E] machine-0 {"RequestId":46,"Type":"StringsWatcher","Id":"2","Request":"Stop","Params":{}} [LOG] 15.98492 DEBUG juju.state.apiserver -> [2E] machine-0 0 {"RequestId":39,"Error":"watcher has been stopped","ErrorCode":"stopped","Response":{}} StringsWatcher["2"].Next [LOG] 15.98504 DEBUG juju.state.apiserver -> [2E] machine-0 288.512us {"RequestId":46,"Response":{}} StringsWatcher["2"].Stop [LOG] 15.98586 INFO juju.provider.dummy reset environment [LOG] 15.99135 INFO juju Reset successfully reset admin password [LOG] 15.99709 INFO juju Reset successfully reset admin password [LOG] 16.00033 INFO juju Reset successfully reset admin password OOPS: 28 passed, 1 FAILED --- FAIL: Test (27.05 seconds) FAIL FAIL launchpad.net/juju-core/worker/provisioner 27.352s ok launchpad.net/juju-core/worker/resumer 1.547s ok launchpad.net/juju-core/worker/terminationworker 0.066s ok launchpad.net/juju-core/worker/uniter 163.581s ok launchpad.net/juju-core/worker/uniter/charm 3.549s ok launchpad.net/juju-core/worker/uniter/debug 1.131s ok launchpad.net/juju-core/worker/uniter/hook 0.016s ok launchpad.net/juju-core/worker/uniter/jujuc 0.455s ok launchpad.net/juju-core/worker/uniter/relation 1.411s ok launchpad.net/juju-core/worker/upgrader 3.570s 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.