The attempt to merge lp:~thumper/juju-core/really-unit-agent-proxy-settings into lp:juju-core failed. Below is the output from the failed tests. ok launchpad.net/juju-core/agent 0.842s ok launchpad.net/juju-core/agent/tools 0.279s ok launchpad.net/juju-core/bzr 7.425s ok launchpad.net/juju-core/cert 3.696s ok launchpad.net/juju-core/charm 0.623s ? 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.031s ok launchpad.net/juju-core/cloudinit/sshinit 1.133s ok launchpad.net/juju-core/cmd 0.232s ok launchpad.net/juju-core/cmd/charm-admin 0.868s ? 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 232.189s ---------------------------------------------------------------------- FAIL: machine_test.go:278: MachineSuite.TestManageEnviron [LOG] 48.23366 DEBUG juju.environs.configstore Making /tmp/gocheck-2970700287221458280/43/home/ubuntu/.juju/environments [LOG] 48.31803 DEBUG juju.environs.tools reading v1.* tools [LOG] 48.31805 INFO juju environs/testing: uploading FAKE tools 1.17.1-precise-amd64 [LOG] 48.31889 DEBUG juju.environs.tools no architecture specified when finding tools, looking for any [LOG] 48.31891 DEBUG juju.environs.tools no series specified when finding tools, looking for any [LOG] 48.31898 DEBUG juju.environs.simplestreams fetchData failed for "http://127.0.0.1:52216/dummyenv/private/tools/streams/v1/index.sjson": file "tools/streams/v1/index.sjson" not found not found [LOG] 48.31899 DEBUG juju.environs.simplestreams cannot load index "http://127.0.0.1:52216/dummyenv/private/tools/streams/v1/index.sjson": invalid URL "http://127.0.0.1:52216/dummyenv/private/tools/streams/v1/index.sjson" not found [LOG] 48.31907 DEBUG juju.environs.simplestreams fetchData failed for "http://127.0.0.1:52216/dummyenv/private/tools/streams/v1/index.json": file "tools/streams/v1/index.json" not found not found [LOG] 48.31911 DEBUG juju.environs.simplestreams cannot load index "http://127.0.0.1:52216/dummyenv/private/tools/streams/v1/index.json": invalid URL "http://127.0.0.1:52216/dummyenv/private/tools/streams/v1/index.json" not found [LOG] 48.31935 INFO juju.environs.tools Writing tools/streams/v1/index.json [LOG] 48.31941 INFO juju.environs.tools Writing tools/streams/v1/com.ubuntu.juju:released:tools.json [LOG] 48.31955 INFO juju.environs.bootstrap bootstrapping environment "dummyenv" [LOG] 48.31957 DEBUG juju.environs.bootstrap looking for bootstrap tools: series="precise", arch=, version=1.17.1 [LOG] 48.31959 INFO juju.environs.tools reading tools with major.minor version 1.17 [LOG] 48.31960 INFO juju.environs.tools filtering tools by version: 1.17.1 [LOG] 48.31961 INFO juju.environs.tools filtering tools by series: precise [LOG] 48.31966 DEBUG juju.environs.tools no architecture specified when finding tools, looking for any [LOG] 48.31966 DEBUG juju.environs.simplestreams fetchData failed for "http://127.0.0.1:52216/dummyenv/private/tools/streams/v1/index.sjson": file "tools/streams/v1/index.sjson" not found not found [LOG] 48.31968 DEBUG juju.environs.simplestreams cannot load index "http://127.0.0.1:52216/dummyenv/private/tools/streams/v1/index.sjson": invalid URL "http://127.0.0.1:52216/dummyenv/private/tools/streams/v1/index.sjson" not found [LOG] 48.31985 DEBUG juju.environs.simplestreams fetchData failed for "http://127.0.0.1:52216/dummyenv/private/tools/streams/v1/mirrors.json": file "tools/streams/v1/mirrors.json" not found not found [LOG] 48.31987 DEBUG juju.environs.simplestreams no mirror index file found [LOG] 48.31989 DEBUG juju.environs.simplestreams no mirror information available for { }: mirror data for "com.ubuntu.juju:released:tools" not found [LOG] 48.31991 DEBUG juju.environs.simplestreams read metadata index at "http://127.0.0.1:52216/dummyenv/private/tools/streams/v1/index.json" [LOG] 48.31996 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 [{Fri, 24 Jan 2014 01:17:49 +0000 products:1.0 content-download [] streams/v1/com.ubuntu.juju:released:tools.json [com.ubuntu.juju:12.04:amd64]}] [LOG] 48.31997 DEBUG juju.environs.simplestreams finding products at path "streams/v1/com.ubuntu.juju:released:tools.json" [LOG] 48.32017 DEBUG juju.environs.simplestreams metadata: &{map[com.ubuntu.juju:12.04:amd64:{ 1.17.1 amd64 map[20140124:0xc20080f780]}] map[] Fri, 24 Jan 2014 01:17:49 +0000 products:1.0 com.ubuntu.juju:released:tools} [LOG] 48.32023 INFO juju.environs.bootstrap picked newest version: 1.17.1 [LOG] 48.32026 INFO juju.provider.dummy would pick tools from 1.17.1-precise-amd64 [LOG] 48.32067 INFO juju.state opening state; mongo addresses: ["localhost:43289"]; entity "" [LOG] 48.32360 INFO juju.state connection established [LOG] 48.36482 INFO juju.state initializing environment [LOG] 48.38642 INFO juju.state.apiserver listening on "127.0.0.1:40061" [LOG] 48.41754 INFO juju.state opening state; mongo addresses: ["localhost:43289"]; entity "" [LOG] 48.44348 INFO juju.state connection established [LOG] 48.44528 INFO juju juju: authorization error while connecting to state server; retrying [LOG] 48.44541 INFO juju.state opening state; mongo addresses: ["localhost:43289"]; entity "" [LOG] 48.45282 INFO juju.state connection established [LOG] 48.49282 INFO juju state/api: dialing "wss://127.0.0.1:40061/" [LOG] 48.49579 INFO juju.state.apiserver [B] API connection from 127.0.0.1:53045 [LOG] 48.49588 INFO juju state/api: connection established [LOG] 48.49607 DEBUG juju.state.apiserver <- [B] {"RequestId":1,"Type":"Admin","Request":"Login","Params":{"AuthTag":"user-admin","Password":"dummy-secret","Nonce":""}} [LOG] 48.49648 DEBUG juju.state.apiserver -> [B] user-admin 410.621us {"RequestId":1,"Response":{}} Admin[""].Login [LOG] 48.49695 DEBUG juju.environs.tools reading v1.* tools [LOG] 48.49702 DEBUG juju.environs.tools found 1.17.1-precise-amd64 [LOG] 48.49706 INFO juju environs/testing: uploading FAKE tools 1.17.1-quantal-amd64 [LOG] 48.49792 DEBUG juju.environs.tools no architecture specified when finding tools, looking for any [LOG] 48.49793 DEBUG juju.environs.tools no series specified when finding tools, looking for any [LOG] 48.49799 DEBUG juju.environs.simplestreams fetchData failed for "http://127.0.0.1:52216/dummyenv/private/tools/streams/v1/index.sjson": file "tools/streams/v1/index.sjson" not found not found [LOG] 48.49801 DEBUG juju.environs.simplestreams cannot load index "http://127.0.0.1:52216/dummyenv/private/tools/streams/v1/index.sjson": invalid URL "http://127.0.0.1:52216/dummyenv/private/tools/streams/v1/index.sjson" not found [LOG] 48.49812 DEBUG juju.environs.simplestreams fetchData failed for "http://127.0.0.1:52216/dummyenv/private/tools/streams/v1/mirrors.json": file "tools/streams/v1/mirrors.json" not found not found [LOG] 48.49813 DEBUG juju.environs.simplestreams no mirror index file found [LOG] 48.49815 DEBUG juju.environs.simplestreams no mirror information available for { }: mirror data for "com.ubuntu.juju:released:tools" not found [LOG] 48.49816 DEBUG juju.environs.simplestreams read metadata index at "http://127.0.0.1:52216/dummyenv/private/tools/streams/v1/index.json" [LOG] 48.49825 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" "com.ubuntu.juju:12.10:amd64" "com.ubuntu.juju:12.10:i386" "com.ubuntu.juju:12.10:arm" "com.ubuntu.juju:13.04:amd64" "com.ubuntu.juju:13.04:i386" "com.ubuntu.juju:13.04:arm" "com.ubuntu.juju:13.10:amd64" "com.ubuntu.juju:13.10:i386" "com.ubuntu.juju:13.10:arm" "com.ubuntu.juju:14.04:amd64" "com.ubuntu.juju:14.04:i386" "com.ubuntu.juju:14.04:arm"] are [{Fri, 24 Jan 2014 01:17:49 +0000 products:1.0 content-download [] streams/v1/com.ubuntu.juju:released:tools.json [com.ubuntu.juju:12.04:amd64]}] [LOG] 48.49828 DEBUG juju.environs.simplestreams finding products at path "streams/v1/com.ubuntu.juju:released:tools.json" [LOG] 48.49847 DEBUG juju.environs.simplestreams metadata: &{map[com.ubuntu.juju:12.04:amd64:{ 1.17.1 amd64 map[20140124:0xc200906360]}] map[] Fri, 24 Jan 2014 01:17:49 +0000 products:1.0 com.ubuntu.juju:released:tools} [LOG] 48.49880 INFO juju.environs.tools Writing tools/streams/v1/index.json [LOG] 48.49881 INFO juju.environs.tools Writing tools/streams/v1/com.ubuntu.juju:released:tools.json [LOG] 48.57404 INFO juju environs/testing: uploading FAKE tools 1.17.1-precise-amd64 [LOG] 48.58826 INFO juju.cmd.jujud machine agent machine-0 start [LOG] 48.58841 DEBUG juju.agent Reading agent config, format: format 1.16 [LOG] 48.59464 INFO juju.cmd.jujud Starting StateWorker for machine-0 [LOG] 48.59485 INFO juju worker: start "state" [LOG] 48.59497 INFO juju.state opening state; mongo addresses: ["localhost:43289"]; entity "machine-0" [LOG] 48.59624 INFO juju worker: start "api" [LOG] 48.59645 INFO juju state/api: dialing "wss://localhost:51173/" [LOG] 48.59697 INFO juju worker: start "termination" [LOG] 48.61044 ERROR juju state/api: websocket.Dial wss://localhost:51173/: dial tcp 127.0.0.1:51173: connection refused [LOG] 48.62510 INFO juju writing charm to storage [1418 bytes] [LOG] 48.62526 INFO juju adding charm to state [LOG] 48.62635 ERROR juju worker: exited "api": websocket.Dial wss://localhost:51173/: dial tcp 127.0.0.1:51173: connection refused [LOG] 48.62639 INFO juju worker: restarting "api" in 50ms [LOG] 48.63062 INFO juju.state connection established [LOG] 48.64831 INFO juju worker: start "instancepoller" [LOG] 48.64844 INFO juju worker: start "apiserver" [LOG] 48.64859 INFO juju.state.apiserver listening on "[::]:51173" [LOG] 48.67037 INFO juju worker: start "cleaner" [LOG] 48.67078 INFO juju worker: start "resumer" [LOG] 48.67101 INFO juju worker: start "minunitsworker" [LOG] 48.67306 INFO juju.worker.instanceupdater instance poller received inital environment configuration [LOG] 48.67463 WARNING juju.worker.instanceupdater cannot get instance info for instance "ardbeg-0": no instances found [LOG] 48.69000 INFO juju worker: start "api" [LOG] 48.69032 INFO juju state/api: dialing "wss://localhost:51173/" [LOG] 48.69410 INFO juju.state.apiserver [C] API connection from 127.0.0.1:34618 [LOG] 48.69423 INFO juju state/api: connection established [LOG] 48.69456 DEBUG juju.state.apiserver <- [C] {"RequestId":1,"Type":"Admin","Request":"Login","Params":{"AuthTag":"machine-0","Password":"machine-password-1234567890","Nonce":"user-admin:bootstrap"}} [LOG] 48.69787 DEBUG juju.state.apiserver -> [C] machine-0 3.315973ms {"RequestId":1,"Response":{}} Admin[""].Login [LOG] 48.69800 DEBUG juju.agent writing configuration file [LOG] 48.69954 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":3,"Type":"Agent","Request":"GetEntities","Params":{"Entities":[{"Tag":"machine-0"}]}} [LOG] 48.70005 DEBUG juju.state.apiserver -> [C] machine-0 512.81us {"RequestId":3,"Response":{"Entities":[{"Life":"alive","Jobs":["JobManageEnviron","JobManageState"],"ContainerType":"","Error":null}]}} Agent[""].GetEntities [LOG] 48.70225 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":4,"Type":"Agent","Request":"SetPasswords","Params":{"Changes":[{"Tag":"machine-0","Password":"QISjPUWkAR9upWFrhPUc2U9T"}]}} [LOG] 48.70318 INFO juju setting mongo password for "machine-0" [LOG] 48.70598 INFO juju setting password for "machine-0" [LOG] 48.70603 DEBUG juju.state.apiserver -> [C] machine-0 3.808048ms {"RequestId":4,"Response":{"Results":[{"Error":null}]}} Agent[""].SetPasswords [LOG] 48.70617 INFO juju worker: start "machiner" [LOG] 48.70624 INFO juju worker: start "upgrader" [LOG] 48.70626 INFO juju worker: start "logger" [LOG] 48.70635 DEBUG juju.worker.logger initial log config: "=WARNING;juju=DEBUG;unit=DEBUG" [LOG] 48.70643 DEBUG juju.worker.logger logger setup [LOG] 48.70653 WARNING juju.cmd.jujud determining kvm support: exec: "kvm-ok": executable file not found in $PATH no kvm containers possible [LOG] 48.70658 INFO juju worker: start "authenticationworker" [LOG] 48.70685 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":5,"Type":"Machiner","Request":"Life","Params":{"Entities":[{"Tag":"machine-0"}]}} [LOG] 48.70700 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":6,"Type":"Upgrader","Request":"SetTools","Params":{"AgentTools":[{"Tag":"machine-0","Tools":{"Version":"1.17.1-precise-amd64"}}]}} [LOG] 48.70710 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":7,"Type":"Logger","Request":"LoggingConfig","Params":{"Entities":[{"Tag":"machine-0"}]}} [LOG] 48.70720 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":8,"Type":"Provisioner","Request":"Life","Params":{"Entities":[{"Tag":"machine-0"}]}} [LOG] 48.70728 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":9,"Type":"KeyUpdater","Request":"AuthorisedKeys","Params":{"Entities":[{"Tag":"machine-0"}]}} [LOG] 48.70840 DEBUG juju.state.apiserver -> [C] machine-0 1.60091ms {"RequestId":5,"Response":{"Results":[{"Life":"alive","Error":null}]}} Machiner[""].Life [LOG] 48.70914 DEBUG juju.state.apiserver -> [C] machine-0 2.050565ms {"RequestId":7,"Response":{"Results":[{"Error":null,"Result":"\u003croot\u003e=WARNING;juju=DEBUG;unit=DEBUG"}]}} Logger[""].LoggingConfig [LOG] 48.70930 DEBUG juju.state.apiserver -> [C] machine-0 2.122402ms {"RequestId":8,"Response":{"Results":[{"Life":"alive","Error":null}]}} Provisioner[""].Life [LOG] 48.71023 INFO juju.worker.machiner setting addresses for machine-0 to ["10.55.32.52" "fe80::f816:3eff:fef7:1c4e"] [LOG] 48.71067 DEBUG juju.state.apiserver -> [C] machine-0 3.401217ms {"RequestId":9,"Response":{"Results":[{"Error":null,"Result":["my-keys"]}]}} KeyUpdater[""].AuthorisedKeys [LOG] 48.71086 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":10,"Type":"Machiner","Request":"SetMachineAddresses","Params":{"MachineAddresses":[{"Tag":"machine-0","Addresses":[{"Value":"10.55.32.52","Type":"ipv4","NetworkName":"","NetworkScope":""},{"Value":"fe80::f816:3eff:fef7:1c4e","Type":"ipv6","NetworkName":"","NetworkScope":""}]}]}} [LOG] 48.71092 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":11,"Type":"Logger","Request":"WatchLoggingConfig","Params":{"Entities":[{"Tag":"machine-0"}]}} [LOG] 48.71099 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":12,"Type":"Provisioner","Request":"SetSupportedContainers","Params":{"Params":[{"MachineTag":"machine-0","ContainerTypes":["lxc"]}]}} [LOG] 48.71135 DEBUG juju.utils.ssh reading authorised keys file /tmp/gocheck-2970700287221458280/49/.ssh/authorized_keys [LOG] 48.71144 WARNING juju.utils.ssh invalid Juju ssh key my-keys: malformed line: "my-keys" [LOG] 48.71147 DEBUG juju.utils.ssh reading authorised keys file /tmp/gocheck-2970700287221458280/49/.ssh/authorized_keys [LOG] 48.71150 ERROR juju.worker.authenticationworker adding current Juju keys to ssh authorised keys: generating key fingerprint: malformed line: "my-keys" [LOG] 48.71228 DEBUG juju.state.apiserver -> [C] machine-0 1.35323ms {"RequestId":11,"Response":{"Results":[{"NotifyWatcherId":"2","Error":null}]}} Logger[""].WatchLoggingConfig [LOG] 48.71255 ERROR juju worker: exited "authenticationworker": adding current Juju keys to ssh authorised keys: generating key fingerprint: malformed line: "my-keys" [LOG] 48.71257 INFO juju worker: restarting "authenticationworker" in 50ms [LOG] 48.71306 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":14,"Type":"Logger","Request":"LoggingConfig","Params":{"Entities":[{"Tag":"machine-0"}]}} [LOG] 48.71311 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":15,"Type":"NotifyWatcher","Id":"2","Request":"Next","Params":{}} [LOG] 48.71399 DEBUG juju.state.apiserver -> [C] machine-0 935.588us {"RequestId":14,"Response":{"Results":[{"Error":null,"Result":"\u003croot\u003e=WARNING;juju=DEBUG;unit=DEBUG"}]}} Logger[""].LoggingConfig [LOG] 48.71602 DEBUG juju.state.apiserver -> [C] machine-0 9.095868ms {"RequestId":6,"Response":{"Results":[{"Error":null}]}} Upgrader[""].SetTools [LOG] 48.71628 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":16,"Type":"Upgrader","Request":"WatchAPIVersion","Params":{"Entities":[{"Tag":"machine-0"}]}} [LOG] 48.72350 DEBUG juju.state.apiserver -> [C] machine-0 7.206515ms {"RequestId":16,"Response":{"Results":[{"NotifyWatcherId":"3","Error":null}]}} Upgrader[""].WatchAPIVersion [LOG] 48.72380 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":17,"Type":"Upgrader","Request":"DesiredVersion","Params":{"Entities":[{"Tag":"machine-0"}]}} [LOG] 48.72389 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":18,"Type":"NotifyWatcher","Id":"3","Request":"Next","Params":{}} [LOG] 48.73808 DEBUG juju.state.apiserver -> [C] machine-0 14.048623ms {"RequestId":17,"Response":{"Results":[{"Version":"1.17.1","Error":null}]}} Upgrader[""].DesiredVersion [LOG] 48.74081 INFO juju.worker.upgrader desired tool version: 1.17.1 [LOG] 48.75098 DEBUG juju.state.apiserver -> [C] machine-0 40.172701ms {"RequestId":10,"Response":{"Results":[{"Error":null}]}} Machiner[""].SetMachineAddresses [LOG] 48.75134 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":19,"Type":"Machiner","Request":"SetStatus","Params":{"Entities":[{"Tag":"machine-0","Status":"started","Info":"","Data":null}]}} discarding unknown event dummy.OpPutFile{Env:"dummyenv", FileName:"local_3a_quantal_2f_dummy-1"} [LOG] 48.76108 DEBUG juju.state.apiserver -> [C] machine-0 50.071529ms {"RequestId":12,"Response":{"Results":[{"Error":null}]}} Provisioner[""].SetSupportedContainers [LOG] 48.76130 INFO juju worker: start "0-container-watcher" [LOG] 48.76133 INFO juju worker: start "environ-provisioner" [LOG] 48.76149 INFO juju worker: start "firewaller" [LOG] 48.76155 INFO juju worker: start "charm-revision-updater" [LOG] 48.76170 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":20,"Type":"Provisioner","Request":"WatchContainers","Params":{"Params":[{"MachineTag":"machine-0","ContainerType":""}]}} [LOG] 48.76175 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":21,"Type":"Provisioner","Request":"WatchForEnvironConfigChanges","Params":{}} [LOG] 48.76179 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":22,"Type":"Firewaller","Request":"WatchForEnvironConfigChanges","Params":{}} [LOG] 48.76184 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":23,"Type":"CharmRevisionUpdater","Request":"UpdateLatestRevisions","Params":{}} [LOG] 48.76293 INFO juju worker: start "authenticationworker" [LOG] 48.76314 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":24,"Type":"KeyUpdater","Request":"AuthorisedKeys","Params":{"Entities":[{"Tag":"machine-0"}]}} [LOG] 48.76365 DEBUG juju.state.apiserver -> [C] machine-0 1.889785ms {"RequestId":21,"Response":{"NotifyWatcherId":"4","Error":null}} Provisioner[""].WatchForEnvironConfigChanges [LOG] 48.76393 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":25,"Type":"Provisioner","Request":"EnvironConfig","Params":{}} [LOG] 48.76401 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":26,"Type":"NotifyWatcher","Id":"4","Request":"Next","Params":{}} [LOG] 48.76437 DEBUG juju.state.apiserver -> [C] machine-0 2.561485ms {"RequestId":22,"Response":{"NotifyWatcherId":"5","Error":null}} Firewaller[""].WatchForEnvironConfigChanges [LOG] 48.76469 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":27,"Type":"Firewaller","Request":"WatchEnvironMachines","Params":{}} [LOG] 48.76476 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":28,"Type":"NotifyWatcher","Id":"5","Request":"Next","Params":{}} [LOG] 48.76563 DEBUG juju.state.apiserver -> [C] machine-0 3.896305ms {"RequestId":20,"Response":{"Results":[{"StringsWatcherId":"6","Changes":[],"Error":null}]}} Provisioner[""].WatchContainers [LOG] 48.76590 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":29,"Type":"StringsWatcher","Id":"6","Request":"Next","Params":{}} [LOG] 48.76696 INFO juju.state.apiserver.charmrevisionupdater retrieving revision information for 0 charms [LOG] 48.76919 DEBUG juju.state.apiserver -> [C] machine-0 4.953892ms {"RequestId":25,"Response":{"Error":null,"Config":{"admin-secret":"","agent-version":"1.17.1","api-port":4321,"authorized-keys":"my-keys","broken":"","ca-cert":"-----BEGIN CERTIFICATE-----\nMIIB4jCCAY6gAwIBAgIBADALBgkqhkiG9w0BAQUwSjENMAsGA1UEChMEanVqdTE5\nMDcGA1UEAwwwanVqdS1nZW5lcmF0ZWQgQ0EgZm9yIGVudmlyb25tZW50ICJqdWp1\nIHRlc3RpbmciMB4XDTE0MDEyNDAxMTIzM1oXDTI0MDEyNDAxMTczM1owSjENMAsG\nA1UEChMEanVqdTE5MDcGA1UEAwwwanVqdS1nZW5lcmF0ZWQgQ0EgZm9yIGVudmly\nb25tZW50ICJqdWp1IHRlc3RpbmciMFowCwYJKoZIhvcNAQEBA0sAMEgCQQDNGtTQ\nGEsMOZwDIrfZxn7mvRjGxAU3J2WeUxR0nFVW0byV1cs9gmiffwUZ5EP2mu1mXmbP\nPXbAjp8V17gp4j3DAgMBAAGjYzBhMA4GA1UdDwEB/wQEAwIApDAPBgNVHRMBAf8E\nBTADAQH/MB0GA1UdDgQWBBS7La7KQKd7AwLDWpOJCOvEwJJKpTAfBgNVHSMEGDAW\ngBS7La7KQKd7AwLDWpOJCOvEwJJKpTALBgkqhkiG9w0BAQUDQQBZqCdF/HIb80Cw\nn+CVODOocoEWmmGojRCy7jpO30eQyD43J97YTyhdBmjgN/hqpsdF7sjUgtp3FyTt\nREYfJTH8\n-----END CERTIFICATE-----\n","ca-private-key":"","charm-store-auth":"","default-series":"precise","development":false,"firewall-mode":"instance","image-metadata-url":"","logging-config":"\u003croot\u003e=WARNING;juju=DEBUG;unit=DEBUG","name":"dummyenv","secret":"pork","ssl-hostname-verification":true,"state-id":"7","state-port":1234,"state-server":true,"syslog-port":2345,"tools-metadata-url":"","tools-url":"","type":"dummy"}}} Provisioner[""].EnvironConfig [LOG] 48.77149 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":30,"Type":"Provisioner","Request":"StateAddresses","Params":{}} [LOG] 48.77397 DEBUG juju.state.apiserver -> [C] machine-0 9.146917ms {"RequestId":27,"Response":{"StringsWatcherId":"7","Changes":["1","0"],"Error":null}} Firewaller[""].WatchEnvironMachines [LOG] 48.77463 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":31,"Type":"Firewaller","Request":"EnvironConfig","Params":{}} [LOG] 48.77473 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":32,"Type":"StringsWatcher","Id":"7","Request":"Next","Params":{}} [LOG] 48.77590 DEBUG juju.state.apiserver -> [C] machine-0 12.646322ms {"RequestId":24,"Response":{"Results":[{"Error":null,"Result":["my-keys"]}]}} KeyUpdater[""].AuthorisedKeys [LOG] 48.77721 DEBUG juju.state.apiserver -> [C] machine-0 2.26763ms {"RequestId":31,"Response":{"Error":null,"Config":{"admin-secret":"","agent-version":"1.17.1","api-port":4321,"authorized-keys":"my-keys","broken":"","ca-cert":"-----BEGIN CERTIFICATE-----\nMIIB4jCCAY6gAwIBAgIBADALBgkqhkiG9w0BAQUwSjENMAsGA1UEChMEanVqdTE5\nMDcGA1UEAwwwanVqdS1nZW5lcmF0ZWQgQ0EgZm9yIGVudmlyb25tZW50ICJqdWp1\nIHRlc3RpbmciMB4XDTE0MDEyNDAxMTIzM1oXDTI0MDEyNDAxMTczM1owSjENMAsG\nA1UEChMEanVqdTE5MDcGA1UEAwwwanVqdS1nZW5lcmF0ZWQgQ0EgZm9yIGVudmly\nb25tZW50ICJqdWp1IHRlc3RpbmciMFowCwYJKoZIhvcNAQEBA0sAMEgCQQDNGtTQ\nGEsMOZwDIrfZxn7mvRjGxAU3J2WeUxR0nFVW0byV1cs9gmiffwUZ5EP2mu1mXmbP\nPXbAjp8V17gp4j3DAgMBAAGjYzBhMA4GA1UdDwEB/wQEAwIApDAPBgNVHRMBAf8E\nBTADAQH/MB0GA1UdDgQWBBS7La7KQKd7AwLDWpOJCOvEwJJKpTAfBgNVHSMEGDAW\ngBS7La7KQKd7AwLDWpOJCOvEwJJKpTALBgkqhkiG9w0BAQUDQQBZqCdF/HIb80Cw\nn+CVODOocoEWmmGojRCy7jpO30eQyD43J97YTyhdBmjgN/hqpsdF7sjUgtp3FyTt\nREYfJTH8\n-----END CERTIFICATE-----\n","ca-private-key":"","charm-store-auth":"","default-series":"precise","development":false,"firewall-mode":"instance","image-metadata-url":"","logging-config":"\u003croot\u003e=WARNING;juju=DEBUG;unit=DEBUG","name":"dummyenv","secret":"pork","ssl-hostname-verification":true,"state-id":"7","state-port":1234,"state-server":true,"syslog-port":2345,"tools-metadata-url":"","tools-url":"","type":"dummy"}}} Firewaller[""].EnvironConfig [LOG] 48.77907 DEBUG juju.utils.ssh reading authorised keys file /tmp/gocheck-2970700287221458280/49/.ssh/authorized_keys [LOG] 48.77914 WARNING juju.utils.ssh invalid Juju ssh key my-keys: malformed line: "my-keys" [LOG] 48.77917 DEBUG juju.utils.ssh reading authorised keys file /tmp/gocheck-2970700287221458280/49/.ssh/authorized_keys [LOG] 48.77922 ERROR juju.worker.authenticationworker adding current Juju keys to ssh authorised keys: generating key fingerprint: malformed line: "my-keys" [LOG] 48.78154 DEBUG juju.state.apiserver -> [C] machine-0 10.05227ms {"RequestId":30,"Response":{"Error":null,"Result":["0.1.2.3:1234"]}} Provisioner[""].StateAddresses [LOG] 48.79738 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":34,"Type":"Firewaller","Request":"Life","Params":{"Entities":[{"Tag":"machine-1"}]}} [LOG] 48.83254 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":35,"Type":"Provisioner","Request":"APIAddresses","Params":{}} [LOG] 48.83281 DEBUG juju.state.apiserver -> [C] machine-0 49.335212ms {"RequestId":34,"Response":{"Results":[{"Life":"alive","Error":null}]}} Firewaller[""].Life [LOG] 48.83305 ERROR juju worker: exited "authenticationworker": adding current Juju keys to ssh authorised keys: generating key fingerprint: malformed line: "my-keys" [LOG] 48.83306 INFO juju worker: restarting "authenticationworker" in 50ms [LOG] 48.83327 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":36,"Type":"Firewaller","Request":"Life","Params":{"Entities":[{"Tag":"machine-1"}]}} [LOG] 48.83409 DEBUG juju.state.apiserver -> [C] machine-0 821.035us {"RequestId":36,"Response":{"Results":[{"Life":"alive","Error":null}]}} Firewaller[""].Life [LOG] 48.83429 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":37,"Type":"Firewaller","Request":"WatchUnits","Params":{"Entities":[{"Tag":"machine-1"}]}} [LOG] 48.83523 DEBUG juju.state.apiserver -> [C] machine-0 2.705129ms {"RequestId":35,"Response":{"Error":null,"Result":["0.1.2.3:4321"]}} Provisioner[""].APIAddresses [LOG] 48.83557 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":38,"Type":"Provisioner","Request":"CACert","Params":{}} [LOG] 48.83567 DEBUG juju.state.apiserver -> [C] machine-0 60.322us {"RequestId":38,"Response":{"Result":"LS0tLS1CRUdJTiBDRVJUSUZJQ0FURS0tLS0tCk1JSUI0akNDQVk2Z0F3SUJBZ0lCQURBTEJna3Foa2lHOXcwQkFRVXdTakVOTUFzR0ExVUVDaE1FYW5WcWRURTUKTURjR0ExVUVBd3d3YW5WcWRTMW5aVzVsY21GMFpXUWdRMEVnWm05eUlHVnVkbWx5YjI1dFpXNTBJQ0pxZFdwMQpJSFJsYzNScGJtY2lNQjRYRFRFME1ERXlOREF4TVRJek0xb1hEVEkwTURFeU5EQXhNVGN6TTFvd1NqRU5NQXNHCkExVUVDaE1FYW5WcWRURTVNRGNHQTFVRUF3d3dhblZxZFMxblpXNWxjbUYwWldRZ1EwRWdabTl5SUdWdWRtbHkKYjI1dFpXNTBJQ0pxZFdwMUlIUmxjM1JwYm1jaU1Gb3dDd1lKS29aSWh2Y05BUUVCQTBzQU1FZ0NRUUROR3RUUQpHRXNNT1p3RElyZlp4bjdtdlJqR3hBVTNKMldlVXhSMG5GVlcwYnlWMWNzOWdtaWZmd1VaNUVQMm11MW1YbWJQClBYYkFqcDhWMTdncDRqM0RBZ01CQUFHall6QmhNQTRHQTFVZER3RUIvd1FFQXdJQXBEQVBCZ05WSFJNQkFmOEUKQlRBREFRSC9NQjBHQTFVZERnUVdCQlM3TGE3S1FLZDdBd0xEV3BPSkNPdkV3SkpLcFRBZkJnTlZIU01FR0RBVwpnQlM3TGE3S1FLZDdBd0xEV3BPSkNPdkV3SkpLcFRBTEJna3Foa2lHOXcwQkFRVURRUUJacUNkRi9ISWI4MEN3Cm4rQ1ZPRE9vY29FV21tR29qUkN5N2pwTzMwZVF5RDQzSjk3WVR5aGRCbWpnTi9ocXBzZEY3c2pVZ3RwM0Z5VHQKUkVZZkpUSDgKLS0tLS1FTkQgQ0VSVElGSUNBVEUtLS0tLQo="}} Provisioner[""].CACert [LOG] 48.83599 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":39,"Type":"Provisioner","Request":"WatchEnvironMachines","Params":{}} [LOG] 48.83657 DEBUG juju.state.apiserver -> [C] machine-0 85.217143ms {"RequestId":19,"Response":{"Results":[{"Error":null}]}} Machiner[""].SetStatus [LOG] 48.83671 DEBUG juju.state.apiserver -> [C] machine-0 720.199us {"RequestId":39,"Response":{"StringsWatcherId":"8","Changes":["1","0"],"Error":null}} Provisioner[""].WatchEnvironMachines [LOG] 48.83687 INFO juju.worker.machiner "machine-0" started [LOG] 48.83694 INFO juju.provisioner Starting up provisioner task machine-0 [LOG] 48.83745 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":40,"Type":"Machiner","Request":"Watch","Params":{"Entities":[{"Tag":"machine-0"}]}} [LOG] 48.83751 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":41,"Type":"Provisioner","Request":"Life","Params":{"Entities":[{"Tag":"machine-1"}]}} [LOG] 48.83758 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":42,"Type":"StringsWatcher","Id":"8","Request":"Next","Params":{}} [LOG] 48.83829 DEBUG juju.state.apiserver -> [C] machine-0 769.163us {"RequestId":41,"Response":{"Results":[{"Life":"alive","Error":null}]}} Provisioner[""].Life [LOG] 48.83872 DEBUG juju.state.apiserver -> [C] machine-0 4.429417ms {"RequestId":37,"Response":{"Results":[{"StringsWatcherId":"9","Changes":["test-service/0"],"Error":null}]}} Firewaller[""].WatchUnits [LOG] 48.83879 DEBUG juju.state.apiserver -> [C] machine-0 1.355243ms {"RequestId":40,"Response":{"Results":[{"NotifyWatcherId":"10","Error":null}]}} Machiner[""].Watch [LOG] 48.83919 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":43,"Type":"Provisioner","Request":"Life","Params":{"Entities":[{"Tag":"machine-0"}]}} [LOG] 48.83925 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":44,"Type":"Firewaller","Request":"Life","Params":{"Entities":[{"Tag":"unit-test-service-0"}]}} [LOG] 48.83931 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":45,"Type":"Machiner","Request":"Life","Params":{"Entities":[{"Tag":"machine-0"}]}} [LOG] 48.83936 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":46,"Type":"StringsWatcher","Id":"9","Request":"Next","Params":{}} [LOG] 48.83941 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":47,"Type":"NotifyWatcher","Id":"10","Request":"Next","Params":{}} [LOG] 48.84012 DEBUG juju.state.apiserver -> [C] machine-0 922.324us {"RequestId":43,"Response":{"Results":[{"Life":"alive","Error":null}]}} Provisioner[""].Life [LOG] 48.84023 DEBUG juju.state.apiserver -> [C] machine-0 976.887us {"RequestId":44,"Response":{"Results":[{"Life":"alive","Error":null}]}} Firewaller[""].Life [LOG] 48.84037 DEBUG juju.state.apiserver -> [C] machine-0 1.064691ms {"RequestId":45,"Response":{"Results":[{"Life":"alive","Error":null}]}} Machiner[""].Life [LOG] 48.84059 DEBUG juju.state.apiserver -> [C] machine-0 78.745659ms {"RequestId":23,"Response":{"Error":null}} CharmRevisionUpdater[""].UpdateLatestRevisions [LOG] 48.84095 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":48,"Type":"Provisioner","Request":"InstanceId","Params":{"Entities":[{"Tag":"machine-1"}]}} [LOG] 48.84101 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":49,"Type":"Firewaller","Request":"GetAssignedMachine","Params":{"Entities":[{"Tag":"unit-test-service-0"}]}} [LOG] 48.84179 DEBUG juju.state.apiserver -> [C] machine-0 764.328us {"RequestId":49,"Response":{"Results":[{"Error":null,"Result":"machine-1"}]}} Firewaller[""].GetAssignedMachine [LOG] 48.84201 DEBUG juju.state.apiserver -> [C] machine-0 1.061512ms {"RequestId":48,"Response":{"Results":[{"Error":{"Message":"machine 1 is not provisioned","Code":"not provisioned"},"Result":""}]}} Provisioner[""].InstanceId [LOG] 48.84220 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":50,"Type":"Firewaller","Request":"Life","Params":{"Entities":[{"Tag":"service-test-service"}]}} [LOG] 48.84226 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":51,"Type":"Provisioner","Request":"Status","Params":{"Entities":[{"Tag":"machine-1"}]}} [LOG] 48.84276 DEBUG juju.state.apiserver -> [C] machine-0 559.681us {"RequestId":50,"Response":{"Results":[{"Life":"alive","Error":null}]}} Firewaller[""].Life [LOG] 48.84308 DEBUG juju.state.apiserver -> [C] machine-0 766.655us {"RequestId":51,"Response":{"Results":[{"Error":null,"Status":"pending","Info":""}]}} Provisioner[""].Status [LOG] 48.84329 INFO juju.provisioner found machine "1" pending provisioning [LOG] 48.84341 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":52,"Type":"Firewaller","Request":"OpenedPorts","Params":{"Entities":[{"Tag":"unit-test-service-0"}]}} [LOG] 48.84346 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":53,"Type":"Provisioner","Request":"InstanceId","Params":{"Entities":[{"Tag":"machine-0"}]}} [LOG] 48.84393 DEBUG juju.state.apiserver -> [C] machine-0 499.77us {"RequestId":52,"Response":{"Results":[{"Error":null,"Ports":[]}]}} Firewaller[""].OpenedPorts [LOG] 48.84408 DEBUG juju.state.apiserver -> [C] machine-0 623.365us {"RequestId":53,"Response":{"Results":[{"Error":null,"Result":"ardbeg-0"}]}} Provisioner[""].InstanceId [LOG] 48.84426 INFO juju.provisioner machine 0 already started as instance "ardbeg-0" [LOG] 48.84437 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":54,"Type":"Firewaller","Request":"GetExposed","Params":{"Entities":[{"Tag":"service-test-service"}]}} [LOG] 48.84443 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":55,"Type":"Provisioner","Request":"InstanceId","Params":{"Entities":[{"Tag":"machine-1"}]}} [LOG] 48.84490 DEBUG juju.state.apiserver -> [C] machine-0 502.332us {"RequestId":54,"Response":{"Results":[{"Error":null,"Result":true}]}} Firewaller[""].GetExposed [LOG] 48.84533 DEBUG juju.state.apiserver -> [C] machine-0 887.735us {"RequestId":55,"Response":{"Results":[{"Error":{"Message":"machine 1 is not provisioned","Code":"not provisioned"},"Result":""}]}} Provisioner[""].InstanceId [LOG] 48.84540 DEBUG juju worker/firewaller: started watching unit test-service/0 [LOG] 48.84542 DEBUG juju worker/firewaller: started watching "machine-1" [LOG] 48.84561 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":56,"Type":"Firewaller","Request":"Life","Params":{"Entities":[{"Tag":"machine-0"}]}} [LOG] 48.84567 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":57,"Type":"Firewaller","Request":"Watch","Params":{"Entities":[{"Tag":"service-test-service"}]}} [LOG] 48.84573 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":58,"Type":"Firewaller","Request":"Watch","Params":{"Entities":[{"Tag":"unit-test-service-0"}]}} [LOG] 48.84638 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":59,"Type":"Provisioner","Request":"InstanceId","Params":{"Entities":[{"Tag":"machine-0"}]}} [LOG] 48.84672 DEBUG juju.state.apiserver -> [C] machine-0 1.109571ms {"RequestId":56,"Response":{"Results":[{"Life":"alive","Error":null}]}} Firewaller[""].Life [LOG] 48.84700 DEBUG juju.state.apiserver -> [C] machine-0 631.908us {"RequestId":59,"Response":{"Results":[{"Error":null,"Result":"ardbeg-0"}]}} Provisioner[""].InstanceId [LOG] 48.84754 DEBUG juju.state.apiserver -> [C] machine-0 1.861438ms {"RequestId":57,"Response":{"Results":[{"NotifyWatcherId":"11","Error":null}]}} Firewaller[""].Watch [LOG] 48.84761 DEBUG juju.state.apiserver -> [C] machine-0 1.883255ms {"RequestId":58,"Response":{"Results":[{"NotifyWatcherId":"12","Error":null}]}} Firewaller[""].Watch [LOG] 48.84805 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":60,"Type":"Firewaller","Request":"Life","Params":{"Entities":[{"Tag":"machine-0"}]}} [LOG] 48.84813 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":61,"Type":"Provisioner","Request":"Constraints","Params":{"Entities":[{"Tag":"machine-1"}]}} [LOG] 48.84821 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":62,"Type":"Firewaller","Request":"Life","Params":{"Entities":[{"Tag":"service-test-service"}]}} [LOG] 48.84828 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":63,"Type":"Firewaller","Request":"Life","Params":{"Entities":[{"Tag":"unit-test-service-0"}]}} [LOG] 48.84834 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":64,"Type":"NotifyWatcher","Id":"11","Request":"Next","Params":{}} [LOG] 48.84840 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":65,"Type":"NotifyWatcher","Id":"12","Request":"Next","Params":{}} [LOG] 48.84929 DEBUG juju.state.apiserver -> [C] machine-0 1.243693ms {"RequestId":60,"Response":{"Results":[{"Life":"alive","Error":null}]}} Firewaller[""].Life [LOG] 48.84962 DEBUG juju.state.apiserver -> [C] machine-0 1.416611ms {"RequestId":62,"Response":{"Results":[{"Life":"alive","Error":null}]}} Firewaller[""].Life [LOG] 48.84971 DEBUG juju.state.apiserver -> [C] machine-0 1.444817ms {"RequestId":63,"Response":{"Results":[{"Life":"alive","Error":null}]}} Firewaller[""].Life [LOG] 48.84988 DEBUG juju.state.apiserver -> [C] machine-0 1.7003ms {"RequestId":61,"Response":{"Results":[{"Error":null,"Constraints":{}}]}} Provisioner[""].Constraints [LOG] 48.85026 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":66,"Type":"Firewaller","Request":"WatchUnits","Params":{"Entities":[{"Tag":"machine-0"}]}} [LOG] 48.85032 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":67,"Type":"Firewaller","Request":"GetExposed","Params":{"Entities":[{"Tag":"service-test-service"}]}} [LOG] 48.85037 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":68,"Type":"Firewaller","Request":"OpenedPorts","Params":{"Entities":[{"Tag":"unit-test-service-0"}]}} [LOG] 48.85043 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":69,"Type":"Provisioner","Request":"Series","Params":{"Entities":[{"Tag":"machine-1"}]}} [LOG] 48.85126 DEBUG juju.state.apiserver -> [C] machine-0 931.457us {"RequestId":67,"Response":{"Results":[{"Error":null,"Result":true}]}} Firewaller[""].GetExposed [LOG] 48.85137 DEBUG juju.state.apiserver -> [C] machine-0 995.778us {"RequestId":68,"Response":{"Results":[{"Error":null,"Ports":[]}]}} Firewaller[""].OpenedPorts [LOG] 48.85145 DEBUG juju.state.apiserver -> [C] machine-0 1.028493ms {"RequestId":69,"Response":{"Results":[{"Error":null,"Result":"quantal"}]}} Provisioner[""].Series [LOG] 48.85195 INFO juju.environs.tools reading tools with major.minor version 1.17 [LOG] 48.85196 INFO juju.environs.tools filtering tools by version: 1.17.1 [LOG] 48.85197 INFO juju.environs.tools filtering tools by series: quantal [LOG] 48.85200 DEBUG juju.environs.tools no architecture specified when finding tools, looking for any [LOG] 48.85206 DEBUG juju.environs.simplestreams fetchData failed for "http://127.0.0.1:52216/dummyenv/private/tools/streams/v1/index.sjson": file "tools/streams/v1/index.sjson" not found not found [LOG] 48.85208 DEBUG juju.environs.simplestreams cannot load index "http://127.0.0.1:52216/dummyenv/private/tools/streams/v1/index.sjson": invalid URL "http://127.0.0.1:52216/dummyenv/private/tools/streams/v1/index.sjson" not found [LOG] 48.85217 DEBUG juju.environs.simplestreams fetchData failed for "http://127.0.0.1:52216/dummyenv/private/tools/streams/v1/mirrors.json": file "tools/streams/v1/mirrors.json" not found not found [LOG] 48.85218 DEBUG juju.environs.simplestreams no mirror index file found [LOG] 48.85222 DEBUG juju.environs.simplestreams no mirror information available for { }: mirror data for "com.ubuntu.juju:released:tools" not found [LOG] 48.85223 DEBUG juju.environs.simplestreams read metadata index at "http://127.0.0.1:52216/dummyenv/private/tools/streams/v1/index.json" [LOG] 48.85229 DEBUG juju.environs.simplestreams candidate matches for products ["com.ubuntu.juju:12.10:amd64" "com.ubuntu.juju:12.10:i386" "com.ubuntu.juju:12.10:arm"] are [{Fri, 24 Jan 2014 01:17:49 +0000 products:1.0 content-download [] streams/v1/com.ubuntu.juju:released:tools.json [com.ubuntu.juju:12.04:amd64 com.ubuntu.juju:12.10:amd64]}] [LOG] 48.85231 DEBUG juju.environs.simplestreams finding products at path "streams/v1/com.ubuntu.juju:released:tools.json" [LOG] 48.85264 DEBUG juju.environs.simplestreams metadata: &{map[com.ubuntu.juju:12.04:amd64:{ 1.17.1 amd64 map[20140124:0xc20093e000]} com.ubuntu.juju:12.10:amd64:{ 1.17.1 amd64 map[20140124:0xc20093e180]}] map[] Fri, 24 Jan 2014 01:17:49 +0000 products:1.0 com.ubuntu.juju:released:tools} [LOG] 48.85285 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":70,"Type":"Provisioner","Request":"SetPasswords","Params":{"Changes":[{"Tag":"machine-1","Password":"8NfLouKrIJckZI99lWwJmOIO"}]}} [LOG] 48.85352 DEBUG juju.state.apiserver -> [C] machine-0 3.254589ms {"RequestId":66,"Response":{"Results":[{"StringsWatcherId":"13","Changes":null,"Error":null}]}} Firewaller[""].WatchUnits [LOG] 48.85365 DEBUG juju worker/firewaller: started watching "machine-0" [LOG] 48.85453 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":71,"Type":"Firewaller","Request":"Life","Params":{"Entities":[{"Tag":"machine-1"}]}} [LOG] 48.85459 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":72,"Type":"StringsWatcher","Id":"13","Request":"Next","Params":{}} [LOG] 48.85502 DEBUG juju.state.apiserver -> [C] machine-0 495.946us {"RequestId":71,"Response":{"Results":[{"Life":"alive","Error":null}]}} Firewaller[""].Life [LOG] 48.85600 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":73,"Type":"Firewaller","Request":"InstanceId","Params":{"Entities":[{"Tag":"machine-1"}]}} [LOG] 48.85702 DEBUG juju.state.apiserver -> [C] machine-0 1.015385ms {"RequestId":73,"Response":{"Results":[{"Error":{"Message":"machine 1 is not provisioned","Code":"not provisioned"},"Result":""}]}} Firewaller[""].InstanceId [LOG] 48.85772 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":74,"Type":"NotifyWatcher","Id":"5","Request":"Stop","Params":{}} [LOG] 48.85780 DEBUG juju.state.apiserver -> [C] machine-0 93.034577ms {"RequestId":28,"Error":"watcher has been stopped","ErrorCode":"stopped","Response":{}} NotifyWatcher["5"].Next [LOG] 48.85786 DEBUG juju.state.apiserver -> [C] machine-0 158.194us {"RequestId":74,"Response":{}} NotifyWatcher["5"].Stop [LOG] 48.85804 INFO juju setting password for "machine-1" [LOG] 48.85806 DEBUG juju.state.apiserver -> [C] machine-0 5.229711ms {"RequestId":70,"Response":{"Results":[{"Error":null}]}} Provisioner[""].SetPasswords [LOG] 48.85818 INFO juju.provider.dummy dummy startinstance, machine 1 [LOG] 48.85820 INFO juju.provider.dummy would pick tools from 1.17.1-quantal-amd64 waiting for unit "test-service/0" to be provisioned [LOG] 48.85860 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":75,"Type":"StringsWatcher","Id":"7","Request":"Stop","Params":{}} [LOG] 48.85871 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":76,"Type":"Provisioner","Request":"SetProvisioned","Params":{"Machines":[{"Tag":"machine-1","InstanceId":"dummyenv-0","Nonce":"machine-0:a3eb632c-fc11-4fe1-8106-6ebe39352760","Characteristics":{"Arch":"amd64","Mem":1024,"RootDisk":8192,"CpuCores":1,"CpuPower":null,"Tags":null}}]}} [LOG] 48.85894 DEBUG juju.state.apiserver -> [C] machine-0 84.195264ms {"RequestId":32,"Error":"watcher has been stopped","ErrorCode":"stopped","Response":{}} StringsWatcher["7"].Next [LOG] 48.85900 DEBUG juju.state.apiserver -> [C] machine-0 407.454us {"RequestId":75,"Response":{}} StringsWatcher["7"].Stop [LOG] 48.85997 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":77,"Type":"NotifyWatcher","Id":"12","Request":"Stop","Params":{}} [LOG] 48.86026 DEBUG juju.state.apiserver -> [C] machine-0 11.851024ms {"RequestId":65,"Error":"watcher has been stopped","ErrorCode":"stopped","Response":{}} NotifyWatcher["12"].Next [LOG] 48.86032 DEBUG juju.state.apiserver -> [C] machine-0 386.924us {"RequestId":77,"Response":{}} NotifyWatcher["12"].Stop [LOG] 48.86102 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":78,"Type":"NotifyWatcher","Id":"11","Request":"Stop","Params":{}} [LOG] 48.86135 DEBUG juju.state.apiserver -> [C] machine-0 12.991785ms {"RequestId":64,"Error":"watcher has been stopped","ErrorCode":"stopped","Response":{}} NotifyWatcher["11"].Next [LOG] 48.86141 DEBUG juju.state.apiserver -> [C] machine-0 409.426us {"RequestId":78,"Response":{}} NotifyWatcher["11"].Stop [LOG] 48.86213 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":79,"Type":"StringsWatcher","Id":"9","Request":"Stop","Params":{}} [LOG] 48.86221 DEBUG juju.state.apiserver -> [C] machine-0 22.837812ms {"RequestId":46,"Error":"watcher has been stopped","ErrorCode":"stopped","Response":{}} StringsWatcher["9"].Next [LOG] 48.86226 DEBUG juju.state.apiserver -> [C] machine-0 145.627us {"RequestId":79,"Response":{}} StringsWatcher["9"].Stop [LOG] 48.86315 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":80,"Type":"StringsWatcher","Id":"13","Request":"Stop","Params":{}} [LOG] 48.86321 DEBUG juju.state.apiserver -> [C] machine-0 8.621939ms {"RequestId":72,"Error":"watcher has been stopped","ErrorCode":"stopped","Response":{}} StringsWatcher["13"].Next [LOG] 48.86327 DEBUG juju.state.apiserver -> [C] machine-0 135.754us {"RequestId":80,"Response":{}} StringsWatcher["13"].Stop [LOG] 48.86340 ERROR juju worker: fatal "firewaller": machine 1 is not provisioned [LOG] 48.86341 DEBUG juju worker: killing "machiner" [LOG] 48.86342 DEBUG juju worker: killing "upgrader" [LOG] 48.86343 DEBUG juju worker: killing "logger" [LOG] 48.86344 DEBUG juju worker: killing "authenticationworker" [LOG] 48.86344 DEBUG juju worker: killing "0-container-watcher" [LOG] 48.86346 DEBUG juju worker: killing "environ-provisioner" [LOG] 48.86347 DEBUG juju worker: killing "charm-revision-updater" [LOG] 48.86438 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":81,"Type":"NotifyWatcher","Id":"10","Request":"Stop","Params":{}} [LOG] 48.86443 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":82,"Type":"NotifyWatcher","Id":"3","Request":"Stop","Params":{}} [LOG] 48.86451 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":83,"Type":"NotifyWatcher","Id":"2","Request":"Stop","Params":{}} [LOG] 48.86456 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":84,"Type":"StringsWatcher","Id":"6","Request":"Stop","Params":{}} [LOG] 48.86466 DEBUG juju.state.apiserver -> [C] machine-0 25.24723ms {"RequestId":47,"Error":"watcher has been stopped","ErrorCode":"stopped","Response":{}} NotifyWatcher["10"].Next [LOG] 48.86472 DEBUG juju.state.apiserver -> [C] machine-0 355.782us {"RequestId":81,"Response":{}} NotifyWatcher["10"].Stop [LOG] 48.86477 DEBUG juju.state.apiserver -> [C] machine-0 140.882751ms {"RequestId":18,"Error":"watcher has been stopped","ErrorCode":"stopped","Response":{}} NotifyWatcher["3"].Next [LOG] 48.86481 DEBUG juju.state.apiserver -> [C] machine-0 381.75us {"RequestId":82,"Response":{}} NotifyWatcher["3"].Stop [LOG] 48.86485 DEBUG juju.state.apiserver -> [C] machine-0 151.74105ms {"RequestId":15,"Error":"watcher has been stopped","ErrorCode":"stopped","Response":{}} NotifyWatcher["2"].Next [LOG] 48.86489 DEBUG juju.state.apiserver -> [C] machine-0 418.299us {"RequestId":83,"Response":{}} NotifyWatcher["2"].Stop [LOG] 48.86495 DEBUG juju.state.apiserver -> [C] machine-0 99.047863ms {"RequestId":29,"Error":"watcher has been stopped","ErrorCode":"stopped","Response":{}} StringsWatcher["6"].Next [LOG] 48.86499 DEBUG juju.state.apiserver -> [C] machine-0 430.846us {"RequestId":84,"Response":{}} StringsWatcher["6"].Stop [LOG] 48.86686 DEBUG juju.state.apiserver -> [C] machine-0 8.178152ms {"RequestId":76,"Response":{"Results":[{"Error":null}]}} Provisioner[""].SetProvisioned [LOG] 48.86699 INFO juju.provisioner started machine 1 as instance dummyenv-0 with hardware "arch=amd64 cpu-cores=1 mem=1024M root-disk=8192M" [LOG] 48.86701 INFO juju.provisioner Shutting down provisioner task machine-0 [LOG] 48.86711 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":85,"Type":"StringsWatcher","Id":"8","Request":"Stop","Params":{}} [LOG] 48.86717 DEBUG juju.state.apiserver -> [C] machine-0 29.592557ms {"RequestId":42,"Error":"watcher has been stopped","ErrorCode":"stopped","Response":{}} StringsWatcher["8"].Next [LOG] 48.86722 DEBUG juju.state.apiserver -> [C] machine-0 116.97us {"RequestId":85,"Response":{}} StringsWatcher["8"].Stop [LOG] 48.86743 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":86,"Type":"NotifyWatcher","Id":"4","Request":"Stop","Params":{}} [LOG] 48.86749 DEBUG juju.state.apiserver -> [C] machine-0 103.477966ms {"RequestId":26,"Error":"watcher has been stopped","ErrorCode":"stopped","Response":{}} NotifyWatcher["4"].Next [LOG] 48.86754 DEBUG juju.state.apiserver -> [C] machine-0 113.251us {"RequestId":86,"Response":{}} NotifyWatcher["4"].Stop [LOG] 48.88346 INFO juju worker: start "authenticationworker" [LOG] 48.88395 DEBUG juju worker: killing "authenticationworker" [LOG] 48.88425 DEBUG juju.state.apiserver <- [C] machine-0 {"RequestId":87,"Type":"KeyUpdater","Request":"AuthorisedKeys","Params":{"Entities":[{"Tag":"machine-0"}]}} [LOG] 48.89002 DEBUG juju.state.apiserver -> [C] machine-0 5.643211ms {"RequestId":87,"Response":{"Results":[{"Error":null,"Result":["my-keys"]}]}} KeyUpdater[""].AuthorisedKeys [LOG] 48.89091 DEBUG juju.utils.ssh reading authorised keys file /tmp/gocheck-2970700287221458280/49/.ssh/authorized_keys [LOG] 48.89112 WARNING juju.utils.ssh invalid Juju ssh key my-keys: malformed line: "my-keys" [LOG] 48.89135 DEBUG juju.utils.ssh reading authorised keys file /tmp/gocheck-2970700287221458280/49/.ssh/authorized_keys [LOG] 48.89148 ERROR juju.worker.authenticationworker adding current Juju keys to ssh authorised keys: generating key fingerprint: malformed line: "my-keys" [LOG] 48.89219 ERROR juju worker: exited "authenticationworker": adding current Juju keys to ssh authorised keys: generating key fingerprint: malformed line: "my-keys" [LOG] 48.89253 ERROR juju worker: fatal "api": machine 1 is not provisioned [LOG] 48.89256 DEBUG juju worker: killing "state" [LOG] 48.89261 DEBUG juju worker: killing runner 0xc200d91300 [LOG] 48.89264 DEBUG juju worker: killing "termination" [LOG] 48.89268 INFO juju worker: runner is dying [LOG] 48.89271 DEBUG juju worker: killing "instancepoller" [LOG] 48.89274 DEBUG juju worker: killing "apiserver" [LOG] 48.89277 DEBUG juju worker: killing "cleaner" [LOG] 48.89279 DEBUG juju worker: killing "resumer" [LOG] 48.89282 DEBUG juju worker: killing "minunitsworker" [LOG] 48.89605 INFO juju.state.apiserver [C] API connection terminated after 201.945827ms unit provisioned with instance dummyenv-0 machine_test.go:316: c.Check(opRecvTimeout(c, s.State, op, dummy.OpOpenPorts{}), gc.NotNil) machine_test.go:633: c.Fatalf("time out wating for operation") ... Error: time out wating for operation [LOG] 68.78868 DEBUG juju worker: killing runner 0xc2008aca80 [LOG] 68.79011 INFO juju.provider.dummy reset environment [LOG] 68.79030 INFO juju.state.apiserver [B] API connection terminated after 20.294543846s [LOG] 68.81303 INFO juju Reset successfully reset admin password [LOG] 68.82565 INFO juju Reset successfully reset admin password [LOG] 68.83088 INFO juju Reset successfully reset admin password [jujuc whatever] [remote] [/path/to/remote] [remote --help] [unknown] [remote --error borken] [remote --unknown] [remote unwanted] OOPS: 53 passed, 3 skipped, 1 FAILED --- FAIL: TestPackage (70.66 seconds) FAIL FAIL launchpad.net/juju-core/cmd/jujud 70.971s ok launchpad.net/juju-core/cmd/plugins/juju-metadata 11.981s ? launchpad.net/juju-core/cmd/plugins/juju-restore [no test files] ok launchpad.net/juju-core/constraints 0.027s ok launchpad.net/juju-core/container 0.059s ok launchpad.net/juju-core/container/factory 0.060s ok launchpad.net/juju-core/container/kvm 0.298s ok launchpad.net/juju-core/container/kvm/mock 0.050s ? launchpad.net/juju-core/container/kvm/testing [no test files] ok launchpad.net/juju-core/container/lxc 0.431s ? 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.268s ok launchpad.net/juju-core/environs 3.108s ok launchpad.net/juju-core/environs/bootstrap 4.467s ok launchpad.net/juju-core/environs/cloudinit 0.631s ok launchpad.net/juju-core/environs/config 2.428s ok launchpad.net/juju-core/environs/configstore 0.041s ok launchpad.net/juju-core/environs/filestorage 0.031s ok launchpad.net/juju-core/environs/httpstorage 0.846s ok launchpad.net/juju-core/environs/imagemetadata 0.544s ? launchpad.net/juju-core/environs/imagemetadata/testing [no test files] ok launchpad.net/juju-core/environs/instances 0.064s ok launchpad.net/juju-core/environs/jujutest 0.292s ok launchpad.net/juju-core/environs/manual 10.063s ok launchpad.net/juju-core/environs/simplestreams 0.340s ? launchpad.net/juju-core/environs/simplestreams/testing [no test files] ok launchpad.net/juju-core/environs/sshstorage 1.215s ok launchpad.net/juju-core/environs/storage 1.247s ok launchpad.net/juju-core/environs/sync 33.873s ok launchpad.net/juju-core/environs/testing 0.186s ok launchpad.net/juju-core/environs/tools 6.908s ? 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.024s ? launchpad.net/juju-core/instance/testing [no test files] ok launchpad.net/juju-core/juju 21.367s ok launchpad.net/juju-core/juju/osenv 0.020s ? launchpad.net/juju-core/juju/testing [no test files] ok launchpad.net/juju-core/log 0.015s ok launchpad.net/juju-core/log/syslog 0.019s ok launchpad.net/juju-core/names 0.027s ? 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.478s ok launchpad.net/juju-core/provider/common 5.586s ok launchpad.net/juju-core/provider/dummy 24.052s ok launchpad.net/juju-core/provider/ec2 20.339s ok launchpad.net/juju-core/provider/joyent 5.148s ok launchpad.net/juju-core/provider/local 5.960s ok launchpad.net/juju-core/provider/maas 21.875s ok launchpad.net/juju-core/provider/null 1.240s ok launchpad.net/juju-core/provider/openstack 33.875s ok launchpad.net/juju-core/replicaset 44.390s ok launchpad.net/juju-core/rpc 0.090s ok launchpad.net/juju-core/rpc/jsoncodec 0.044s ? launchpad.net/juju-core/rpc/rpcreflect [no test files] ok launchpad.net/juju-core/schema 0.041s ok launchpad.net/juju-core/state 87.181s ok launchpad.net/juju-core/state/api 1.794s ok launchpad.net/juju-core/state/api/agent 2.178s ? launchpad.net/juju-core/state/api/base [no test files] ok launchpad.net/juju-core/state/api/charmrevisionupdater 1.485s ? 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.129s ok launchpad.net/juju-core/state/api/firewaller 10.927s ok launchpad.net/juju-core/state/api/keymanager 3.409s ok launchpad.net/juju-core/state/api/keyupdater 2.682s ok launchpad.net/juju-core/state/api/logger 2.503s ok launchpad.net/juju-core/state/api/machiner 3.497s ok launchpad.net/juju-core/state/api/params 0.036s ok launchpad.net/juju-core/state/api/provisioner 10.035s ok launchpad.net/juju-core/state/api/uniter 27.927s ok launchpad.net/juju-core/state/api/upgrader 3.999s ok launchpad.net/juju-core/state/api/watcher 3.285s ok launchpad.net/juju-core/state/apiserver 7.708s ok launchpad.net/juju-core/state/apiserver/agent 3.047s ok launchpad.net/juju-core/state/apiserver/charmrevisionupdater 2.688s ? launchpad.net/juju-core/state/apiserver/charmrevisionupdater/testing [no test files] ok launchpad.net/juju-core/state/apiserver/client 45.456s ok launchpad.net/juju-core/state/apiserver/common 1.991s ? launchpad.net/juju-core/state/apiserver/common/testing [no test files] ok launchpad.net/juju-core/state/apiserver/deployer 4.736s ok launchpad.net/juju-core/state/apiserver/firewaller 6.262s ok launchpad.net/juju-core/state/apiserver/keymanager 4.207s ? launchpad.net/juju-core/state/apiserver/keymanager/testing [no test files] ok launchpad.net/juju-core/state/apiserver/keyupdater 3.195s ok launchpad.net/juju-core/state/apiserver/logger 3.475s ok launchpad.net/juju-core/state/apiserver/machine 2.804s ok launchpad.net/juju-core/state/apiserver/provisioner 12.257s ? launchpad.net/juju-core/state/apiserver/testing [no test files] ok launchpad.net/juju-core/state/apiserver/uniter 20.379s ok launchpad.net/juju-core/state/apiserver/upgrader 5.891s ok launchpad.net/juju-core/state/multiwatcher 0.755s ok launchpad.net/juju-core/state/presence 6.405s ? 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 5.704s ok launchpad.net/juju-core/store 19.640s ok launchpad.net/juju-core/testing 1.414s ok launchpad.net/juju-core/testing/checkers 0.026s ok launchpad.net/juju-core/testing/testbase 0.020s ok launchpad.net/juju-core/thirdparty/pbkdf2 0.143s ok launchpad.net/juju-core/tools 0.021s ok launchpad.net/juju-core/upstart 1.334s ok launchpad.net/juju-core/utils 2.451s ok launchpad.net/juju-core/utils/exec 0.039s ok launchpad.net/juju-core/utils/fslock 1.038s ok launchpad.net/juju-core/utils/parallel 0.911s ok launchpad.net/juju-core/utils/set 0.016s ok launchpad.net/juju-core/utils/ssh 23.558s ? launchpad.net/juju-core/utils/ssh/testing [no test files] ok launchpad.net/juju-core/utils/tailer 1.009s ok launchpad.net/juju-core/version 0.065s ok launchpad.net/juju-core/worker 6.624s ok launchpad.net/juju-core/worker/authenticationworker 3.189s ok launchpad.net/juju-core/worker/charmrevisionworker 2.020s ok launchpad.net/juju-core/worker/cleaner 1.288s ok launchpad.net/juju-core/worker/deployer 3.670s ok launchpad.net/juju-core/worker/firewaller 12.808s ok launchpad.net/juju-core/worker/instancepoller 2.572s ok launchpad.net/juju-core/worker/localstorage 0.095s ok launchpad.net/juju-core/worker/logger 1.665s ok launchpad.net/juju-core/worker/machiner 3.283s ok launchpad.net/juju-core/worker/minunitsworker 1.419s ok launchpad.net/juju-core/worker/provisioner 35.698s ok launchpad.net/juju-core/worker/resumer 1.561s ok launchpad.net/juju-core/worker/terminationworker 0.069s ok launchpad.net/juju-core/worker/uniter 177.651s ok launchpad.net/juju-core/worker/uniter/charm 3.346s ok launchpad.net/juju-core/worker/uniter/debug 0.198s ok launchpad.net/juju-core/worker/uniter/hook 0.024s ok launchpad.net/juju-core/worker/uniter/jujuc 0.459s ok launchpad.net/juju-core/worker/uniter/relation 1.347s ok launchpad.net/juju-core/worker/upgrader 3.705s 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.