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 1.224s ok launchpad.net/juju-core/agent/tools 0.278s ok launchpad.net/juju-core/bzr 7.319s ok launchpad.net/juju-core/cert 3.295s ok launchpad.net/juju-core/charm 0.635s ? 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.033s ok launchpad.net/juju-core/cloudinit/sshinit 1.205s ok launchpad.net/juju-core/cmd 0.250s ok launchpad.net/juju-core/cmd/charm-admin 0.836s ? 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 238.379s ---------------------------------------------------------------------- FAIL: machine_test.go:329: MachineSuite.TestManageEnvironRunsAddressUpdater [LOG] 24.63087 DEBUG juju.environs.configstore Making /tmp/gocheck-5486140987150761883/50/home/ubuntu/.juju/environments [LOG] 24.72452 DEBUG juju.environs.tools reading v1.* tools [LOG] 24.72458 INFO juju environs/testing: uploading FAKE tools 1.17.1-precise-amd64 [LOG] 24.73101 DEBUG juju.environs.tools no architecture specified when finding tools, looking for any [LOG] 24.73104 DEBUG juju.environs.tools no series specified when finding tools, looking for any [LOG] 24.73110 DEBUG juju.environs.simplestreams fetchData failed for "http://127.0.0.1:46087/dummyenv/private/tools/streams/v1/index.sjson": file "tools/streams/v1/index.sjson" not found not found [LOG] 24.73112 DEBUG juju.environs.simplestreams cannot load index "http://127.0.0.1:46087/dummyenv/private/tools/streams/v1/index.sjson": invalid URL "http://127.0.0.1:46087/dummyenv/private/tools/streams/v1/index.sjson" not found [LOG] 24.73117 DEBUG juju.environs.simplestreams fetchData failed for "http://127.0.0.1:46087/dummyenv/private/tools/streams/v1/index.json": file "tools/streams/v1/index.json" not found not found [LOG] 24.73119 DEBUG juju.environs.simplestreams cannot load index "http://127.0.0.1:46087/dummyenv/private/tools/streams/v1/index.json": invalid URL "http://127.0.0.1:46087/dummyenv/private/tools/streams/v1/index.json" not found [LOG] 24.73146 INFO juju.environs.tools Writing tools/streams/v1/index.json [LOG] 24.73151 INFO juju.environs.tools Writing tools/streams/v1/com.ubuntu.juju:released:tools.json [LOG] 24.73166 INFO juju.environs.bootstrap bootstrapping environment "dummyenv" [LOG] 24.73169 DEBUG juju.environs.bootstrap looking for bootstrap tools: series="precise", arch=, version=1.17.1 [LOG] 24.73170 INFO juju.environs.tools reading tools with major.minor version 1.17 [LOG] 24.73171 INFO juju.environs.tools filtering tools by version: 1.17.1 [LOG] 24.73172 INFO juju.environs.tools filtering tools by series: precise [LOG] 24.73174 DEBUG juju.environs.tools no architecture specified when finding tools, looking for any [LOG] 24.73178 DEBUG juju.environs.simplestreams fetchData failed for "http://127.0.0.1:46087/dummyenv/private/tools/streams/v1/index.sjson": file "tools/streams/v1/index.sjson" not found not found [LOG] 24.73180 DEBUG juju.environs.simplestreams cannot load index "http://127.0.0.1:46087/dummyenv/private/tools/streams/v1/index.sjson": invalid URL "http://127.0.0.1:46087/dummyenv/private/tools/streams/v1/index.sjson" not found [LOG] 24.73200 DEBUG juju.environs.simplestreams fetchData failed for "http://127.0.0.1:46087/dummyenv/private/tools/streams/v1/mirrors.json": file "tools/streams/v1/mirrors.json" not found not found [LOG] 24.73202 DEBUG juju.environs.simplestreams no mirror index file found [LOG] 24.73205 DEBUG juju.environs.simplestreams no mirror information available for { }: mirror data for "com.ubuntu.juju:released:tools" not found [LOG] 24.73207 DEBUG juju.environs.simplestreams read metadata index at "http://127.0.0.1:46087/dummyenv/private/tools/streams/v1/index.json" [LOG] 24.73214 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 [{Thu, 23 Jan 2014 22:04:05 +0000 products:1.0 content-download [] streams/v1/com.ubuntu.juju:released:tools.json [com.ubuntu.juju:12.04:amd64]}] [LOG] 24.73216 DEBUG juju.environs.simplestreams finding products at path "streams/v1/com.ubuntu.juju:released:tools.json" [LOG] 24.73250 DEBUG juju.environs.simplestreams metadata: &{map[com.ubuntu.juju:12.04:amd64:{ 1.17.1 amd64 map[20140123:0xc2002f7e40]}] map[] Thu, 23 Jan 2014 22:04:05 +0000 products:1.0 com.ubuntu.juju:released:tools} [LOG] 24.73259 INFO juju.environs.bootstrap picked newest version: 1.17.1 [LOG] 24.73263 INFO juju.provider.dummy would pick tools from 1.17.1-precise-amd64 [LOG] 24.73329 INFO juju.state opening state; mongo addresses: ["localhost:60162"]; entity "" [LOG] 24.73673 INFO juju.state connection established [LOG] 24.79280 INFO juju.state initializing environment [LOG] 24.81945 INFO juju.state.apiserver listening on "127.0.0.1:34486" [LOG] 24.84559 INFO juju.state opening state; mongo addresses: ["localhost:60162"]; entity "" [LOG] 24.86282 INFO juju.state connection established [LOG] 24.86511 INFO juju juju: authorization error while connecting to state server; retrying [LOG] 24.86538 INFO juju.state opening state; mongo addresses: ["localhost:60162"]; entity "" [LOG] 24.87047 INFO juju.state connection established [LOG] 24.92631 INFO juju state/api: dialing "wss://127.0.0.1:34486/" [LOG] 24.92940 INFO juju.state.apiserver [D] API connection from 127.0.0.1:57968 [LOG] 24.92953 INFO juju state/api: connection established [LOG] 24.92970 DEBUG juju.state.apiserver <- [D] {"RequestId":1,"Type":"Admin","Request":"Login","Params":{"AuthTag":"user-admin","Password":"dummy-secret","Nonce":""}} [LOG] 24.93012 DEBUG juju.state.apiserver -> [D] user-admin 423.814us {"RequestId":1,"Response":{}} Admin[""].Login [LOG] 24.93065 DEBUG juju.environs.tools reading v1.* tools [LOG] 24.93071 DEBUG juju.environs.tools found 1.17.1-precise-amd64 [LOG] 24.93075 INFO juju environs/testing: uploading FAKE tools 1.17.1-quantal-amd64 [LOG] 24.93170 DEBUG juju.environs.tools no architecture specified when finding tools, looking for any [LOG] 24.93172 DEBUG juju.environs.tools no series specified when finding tools, looking for any [LOG] 24.93178 DEBUG juju.environs.simplestreams fetchData failed for "http://127.0.0.1:46087/dummyenv/private/tools/streams/v1/index.sjson": file "tools/streams/v1/index.sjson" not found not found [LOG] 24.93181 DEBUG juju.environs.simplestreams cannot load index "http://127.0.0.1:46087/dummyenv/private/tools/streams/v1/index.sjson": invalid URL "http://127.0.0.1:46087/dummyenv/private/tools/streams/v1/index.sjson" not found [LOG] 24.93193 DEBUG juju.environs.simplestreams fetchData failed for "http://127.0.0.1:46087/dummyenv/private/tools/streams/v1/mirrors.json": file "tools/streams/v1/mirrors.json" not found not found [LOG] 24.93195 DEBUG juju.environs.simplestreams no mirror index file found [LOG] 24.93198 DEBUG juju.environs.simplestreams no mirror information available for { }: mirror data for "com.ubuntu.juju:released:tools" not found [LOG] 24.93199 DEBUG juju.environs.simplestreams read metadata index at "http://127.0.0.1:46087/dummyenv/private/tools/streams/v1/index.json" [LOG] 24.93208 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 [{Thu, 23 Jan 2014 22:04:05 +0000 products:1.0 content-download [] streams/v1/com.ubuntu.juju:released:tools.json [com.ubuntu.juju:12.04:amd64]}] [LOG] 24.93211 DEBUG juju.environs.simplestreams finding products at path "streams/v1/com.ubuntu.juju:released:tools.json" [LOG] 24.93236 DEBUG juju.environs.simplestreams metadata: &{map[com.ubuntu.juju:12.04:amd64:{ 1.17.1 amd64 map[20140123:0xc20065d060]}] map[] Thu, 23 Jan 2014 22:04:05 +0000 products:1.0 com.ubuntu.juju:released:tools} [LOG] 24.93269 INFO juju.environs.tools Writing tools/streams/v1/index.json [LOG] 24.93271 INFO juju.environs.tools Writing tools/streams/v1/com.ubuntu.juju:released:tools.json [LOG] 24.97028 INFO juju environs/testing: uploading FAKE tools 1.17.1-precise-amd64 [LOG] 25.00042 INFO juju.cmd.jujud machine agent machine-0 start [LOG] 25.00056 DEBUG juju.agent Reading agent config, format: format 1.16 [LOG] 25.00144 INFO juju.cmd.jujud Starting StateWorker for machine-0 [LOG] 25.00147 INFO juju worker: start "state" [LOG] 25.00152 INFO juju.state opening state; mongo addresses: ["localhost:60162"]; entity "machine-0" [LOG] 25.00188 INFO juju worker: start "api" [LOG] 25.00210 INFO juju state/api: dialing "wss://localhost:39698/" [LOG] 25.00255 INFO juju worker: start "termination" [LOG] 25.00802 INFO juju writing charm to storage [1418 bytes] [LOG] 25.00809 INFO juju adding charm to state [LOG] 25.01103 ERROR juju state/api: websocket.Dial wss://localhost:39698/: dial tcp 127.0.0.1:39698: connection refused [LOG] 25.01147 ERROR juju worker: exited "api": websocket.Dial wss://localhost:39698/: dial tcp 127.0.0.1:39698: connection refused [LOG] 25.01154 INFO juju worker: restarting "api" in 50ms [LOG] 25.01420 INFO juju.state connection established [LOG] 25.03314 INFO juju worker: start "addressupdater" [LOG] 25.03320 INFO juju worker: start "apiserver" [LOG] 25.03330 INFO juju.state.apiserver listening on "[::]:39698" [LOG] 25.07508 INFO juju worker: start "cleaner" [LOG] 25.07538 INFO juju worker: start "api" [LOG] 25.07561 INFO juju state/api: dialing "wss://localhost:39698/" [LOG] 25.07598 INFO juju worker: start "resumer" [LOG] 25.07607 INFO juju worker: start "minunitsworker" [LOG] 25.09247 INFO juju.state.apiserver [E] API connection from 127.0.0.1:32847 [LOG] 25.09278 INFO juju state/api: connection established [LOG] 25.09363 DEBUG juju.state.apiserver <- [E] {"RequestId":1,"Type":"Admin","Request":"Login","Params":{"AuthTag":"machine-0","Password":"machine-password-1234567890","Nonce":"user-admin:bootstrap"}} [LOG] 25.09839 INFO juju.worker.addressupdater address updater received inital environment configuration [LOG] 25.10973 WARNING juju.worker.addressupdater cannot get addresses for instance "ardbeg-0": no instances found [LOG] 25.11049 DEBUG juju.state.apiserver -> [E] machine-0 16.929062ms {"RequestId":1,"Response":{}} Admin[""].Login [LOG] 25.11064 DEBUG juju.agent writing configuration file [LOG] 25.11246 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":3,"Type":"Agent","Request":"GetEntities","Params":{"Entities":[{"Tag":"machine-0"}]}} [LOG] 25.11300 DEBUG juju.state.apiserver -> [E] machine-0 590.11us {"RequestId":3,"Response":{"Entities":[{"Life":"alive","Jobs":["JobManageEnviron","JobManageState"],"ContainerType":"","Error":null}]}} Agent[""].GetEntities [LOG] 25.11366 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":4,"Type":"Agent","Request":"SetPasswords","Params":{"Changes":[{"Tag":"machine-0","Password":"LDE460a46eGAbIlY8LdnOpjX"}]}} [LOG] 25.11453 INFO juju setting mongo password for "machine-0" [LOG] 25.11720 INFO juju setting password for "machine-0" [LOG] 25.11724 DEBUG juju.state.apiserver -> [E] machine-0 3.590028ms {"RequestId":4,"Response":{"Results":[{"Error":null}]}} Agent[""].SetPasswords [LOG] 25.11738 INFO juju worker: start "machiner" [LOG] 25.11745 INFO juju worker: start "upgrader" [LOG] 25.11747 INFO juju worker: start "logger" [LOG] 25.11756 DEBUG juju.worker.logger initial log config: "=WARNING;juju=DEBUG;unit=DEBUG" [LOG] 25.11764 DEBUG juju.worker.logger logger setup [LOG] 25.11774 WARNING juju.cmd.jujud determining kvm support: exec: "kvm-ok": executable file not found in $PATH no kvm containers possible [LOG] 25.11779 INFO juju worker: start "authenticationworker" [LOG] 25.11791 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":5,"Type":"Machiner","Request":"Life","Params":{"Entities":[{"Tag":"machine-0"}]}} [LOG] 25.11801 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":6,"Type":"Upgrader","Request":"SetTools","Params":{"AgentTools":[{"Tag":"machine-0","Tools":{"Version":"1.17.1-precise-amd64"}}]}} [LOG] 25.11807 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":7,"Type":"Logger","Request":"LoggingConfig","Params":{"Entities":[{"Tag":"machine-0"}]}} [LOG] 25.11812 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":8,"Type":"Provisioner","Request":"Life","Params":{"Entities":[{"Tag":"machine-0"}]}} [LOG] 25.11818 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":9,"Type":"KeyUpdater","Request":"AuthorisedKeys","Params":{"Entities":[{"Tag":"machine-0"}]}} [LOG] 25.11889 DEBUG juju.state.apiserver -> [E] machine-0 980.931us {"RequestId":5,"Response":{"Results":[{"Life":"alive","Error":null}]}} Machiner[""].Life [LOG] 25.11960 DEBUG juju.state.apiserver -> [E] machine-0 1.520505ms {"RequestId":7,"Response":{"Results":[{"Error":null,"Result":"\u003croot\u003e=WARNING;juju=DEBUG;unit=DEBUG"}]}} Logger[""].LoggingConfig [LOG] 25.11974 DEBUG juju.state.apiserver -> [E] machine-0 1.590944ms {"RequestId":8,"Response":{"Results":[{"Life":"alive","Error":null}]}} Provisioner[""].Life [LOG] 25.12061 INFO juju.worker.machiner setting addresses for machine-0 to ["10.55.32.52" "fe80::f816:3eff:fef7:1c4e"] [LOG] 25.12087 DEBUG juju.state.apiserver <- [E] 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] 25.12093 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":11,"Type":"Logger","Request":"WatchLoggingConfig","Params":{"Entities":[{"Tag":"machine-0"}]}} [LOG] 25.12099 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":12,"Type":"Provisioner","Request":"SetSupportedContainers","Params":{"Params":[{"MachineTag":"machine-0","ContainerTypes":["lxc"]}]}} [LOG] 25.12266 DEBUG juju.state.apiserver -> [E] machine-0 4.405657ms {"RequestId":9,"Response":{"Results":[{"Error":null,"Result":["my-keys"]}]}} KeyUpdater[""].AuthorisedKeys [LOG] 25.12328 DEBUG juju.state.apiserver -> [E] machine-0 2.32134ms {"RequestId":11,"Response":{"Results":[{"NotifyWatcherId":"2","Error":null}]}} Logger[""].WatchLoggingConfig [LOG] 25.12420 DEBUG juju.utils.ssh reading authorised keys file /tmp/gocheck-5486140987150761883/56/.ssh/authorized_keys [LOG] 25.12428 WARNING juju.utils.ssh invalid Juju ssh key my-keys: malformed line: "my-keys" [LOG] 25.12434 DEBUG juju.utils.ssh reading authorised keys file /tmp/gocheck-5486140987150761883/56/.ssh/authorized_keys [LOG] 25.12437 ERROR juju.worker.authenticationworker adding current Juju keys to ssh authorised keys: generating key fingerprint: malformed line: "my-keys" [LOG] 25.12490 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":14,"Type":"Logger","Request":"LoggingConfig","Params":{"Entities":[{"Tag":"machine-0"}]}} [LOG] 25.12510 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":15,"Type":"NotifyWatcher","Id":"2","Request":"Next","Params":{}} [LOG] 25.12694 ERROR juju worker: exited "authenticationworker": adding current Juju keys to ssh authorised keys: generating key fingerprint: malformed line: "my-keys" [LOG] 25.12714 INFO juju worker: restarting "authenticationworker" in 50ms [LOG] 25.12810 DEBUG juju.state.apiserver -> [E] machine-0 3.312036ms {"RequestId":14,"Response":{"Results":[{"Error":null,"Result":"\u003croot\u003e=WARNING;juju=DEBUG;unit=DEBUG"}]}} Logger[""].LoggingConfig [LOG] 25.14490 DEBUG juju.state.apiserver -> [E] machine-0 26.902274ms {"RequestId":6,"Response":{"Results":[{"Error":null}]}} Upgrader[""].SetTools [LOG] 25.14528 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":16,"Type":"Upgrader","Request":"WatchAPIVersion","Params":{"Entities":[{"Tag":"machine-0"}]}} [LOG] 25.16221 DEBUG juju.state.apiserver -> [E] machine-0 16.812092ms {"RequestId":16,"Response":{"Results":[{"NotifyWatcherId":"3","Error":null}]}} Upgrader[""].WatchAPIVersion [LOG] 25.16326 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":17,"Type":"Upgrader","Request":"DesiredVersion","Params":{"Entities":[{"Tag":"machine-0"}]}} [LOG] 25.16342 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":18,"Type":"NotifyWatcher","Id":"3","Request":"Next","Params":{}} [LOG] 25.17198 DEBUG juju.state.apiserver -> [E] machine-0 8.735457ms {"RequestId":17,"Response":{"Results":[{"Version":"1.17.1","Error":null}]}} Upgrader[""].DesiredVersion [LOG] 25.17222 INFO juju.worker.upgrader desired tool version: 1.17.1 [LOG] 25.17672 DEBUG juju.state.apiserver -> [E] machine-0 55.831467ms {"RequestId":10,"Response":{"Results":[{"Error":null}]}} Machiner[""].SetMachineAddresses [LOG] 25.17720 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":19,"Type":"Machiner","Request":"SetStatus","Params":{"Entities":[{"Tag":"machine-0","Status":"started","Info":"","Data":null}]}} [LOG] 25.17853 INFO juju worker: start "authenticationworker" [LOG] 25.17880 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":20,"Type":"KeyUpdater","Request":"AuthorisedKeys","Params":{"Entities":[{"Tag":"machine-0"}]}} [LOG] 25.18355 DEBUG juju.state.apiserver -> [E] machine-0 4.610779ms {"RequestId":20,"Response":{"Results":[{"Error":null,"Result":["my-keys"]}]}} KeyUpdater[""].AuthorisedKeys [LOG] 25.18451 DEBUG juju.utils.ssh reading authorised keys file /tmp/gocheck-5486140987150761883/56/.ssh/authorized_keys [LOG] 25.18456 WARNING juju.utils.ssh invalid Juju ssh key my-keys: malformed line: "my-keys" [LOG] 25.18462 DEBUG juju.utils.ssh reading authorised keys file /tmp/gocheck-5486140987150761883/56/.ssh/authorized_keys [LOG] 25.18465 ERROR juju.worker.authenticationworker adding current Juju keys to ssh authorised keys: generating key fingerprint: malformed line: "my-keys" [LOG] 25.18498 ERROR juju worker: exited "authenticationworker": adding current Juju keys to ssh authorised keys: generating key fingerprint: malformed line: "my-keys" [LOG] 25.18500 INFO juju worker: restarting "authenticationworker" in 50ms waiting for unit "test-service/0" to be provisioned [LOG] 25.19153 DEBUG juju.state.apiserver -> [E] machine-0 70.485703ms {"RequestId":12,"Response":{"Results":[{"Error":null}]}} Provisioner[""].SetSupportedContainers [LOG] 25.19177 INFO juju worker: start "0-container-watcher" [LOG] 25.19180 INFO juju worker: start "environ-provisioner" [LOG] 25.19207 INFO juju worker: start "firewaller" [LOG] 25.19226 INFO juju worker: start "charm-revision-updater" [LOG] 25.19272 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":22,"Type":"Provisioner","Request":"WatchContainers","Params":{"Params":[{"MachineTag":"machine-0","ContainerType":""}]}} [LOG] 25.19282 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":23,"Type":"Provisioner","Request":"WatchForEnvironConfigChanges","Params":{}} [LOG] 25.19290 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":24,"Type":"Firewaller","Request":"WatchForEnvironConfigChanges","Params":{}} [LOG] 25.19298 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":25,"Type":"CharmRevisionUpdater","Request":"UpdateLatestRevisions","Params":{}} [LOG] 25.19552 INFO juju.state.apiserver.charmrevisionupdater retrieving revision information for 0 charms [LOG] 25.19567 DEBUG juju.state.apiserver -> [E] machine-0 2.819553ms {"RequestId":23,"Response":{"NotifyWatcherId":"4","Error":null}} Provisioner[""].WatchForEnvironConfigChanges [LOG] 25.19610 DEBUG juju.state.apiserver -> [E] machine-0 3.395398ms {"RequestId":22,"Response":{"Results":[{"StringsWatcherId":"5","Changes":[],"Error":null}]}} Provisioner[""].WatchContainers [LOG] 25.19628 DEBUG juju.state.apiserver -> [E] machine-0 3.370357ms {"RequestId":24,"Response":{"NotifyWatcherId":"6","Error":null}} Firewaller[""].WatchForEnvironConfigChanges [LOG] 25.19692 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":26,"Type":"Firewaller","Request":"WatchEnvironMachines","Params":{}} [LOG] 25.19697 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":27,"Type":"Provisioner","Request":"EnvironConfig","Params":{}} [LOG] 25.19704 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":28,"Type":"NotifyWatcher","Id":"4","Request":"Next","Params":{}} [LOG] 25.19709 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":29,"Type":"StringsWatcher","Id":"5","Request":"Next","Params":{}} [LOG] 25.19714 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":30,"Type":"NotifyWatcher","Id":"6","Request":"Next","Params":{}} [LOG] 25.19920 DEBUG juju.state.apiserver -> [E] machine-0 2.068591ms {"RequestId":27,"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\nIHRlc3RpbmciMB4XDTE0MDEyMzIxNTgzOVoXDTI0MDEyMzIyMDMzOFowSjENMAsG\nA1UEChMEanVqdTE5MDcGA1UEAwwwanVqdS1nZW5lcmF0ZWQgQ0EgZm9yIGVudmly\nb25tZW50ICJqdWp1IHRlc3RpbmciMFowCwYJKoZIhvcNAQEBA0sAMEgCQQC2dn/H\n83gMo8vid18cABA6fKRmwnvcUa6C6C9ZV3SZL4GWrN9jifxCAkDsD2xn9VxhT5l2\nY57aa7wAqlX2FhbJAgMBAAGjYzBhMA4GA1UdDwEB/wQEAwIApDAPBgNVHRMBAf8E\nBTADAQH/MB0GA1UdDgQWBBR6Z/BxFsmOy6Cmcoy/D7WeCOOZ2jAfBgNVHSMEGDAW\ngBR6Z/BxFsmOy6Cmcoy/D7WeCOOZ2jALBgkqhkiG9w0BAQUDQQAXS16PTz+FXVZV\nDqsmRdxFKg1zH+YpWoPcQrTN3HlnvUxq1JUqUYGiV2iKFsB1q4C9pf9cxkDq5mTM\n/Is/sky3\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":"8","state-port":1234,"state-server":true,"syslog-port":2345,"tools-metadata-url":"","tools-url":"","type":"dummy"}}} Provisioner[""].EnvironConfig [LOG] 25.20067 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":31,"Type":"Provisioner","Request":"StateAddresses","Params":{}} [LOG] 25.20367 DEBUG juju.state.apiserver -> [E] machine-0 6.742051ms {"RequestId":26,"Response":{"StringsWatcherId":"7","Changes":["1","0"],"Error":null}} Firewaller[""].WatchEnvironMachines [LOG] 25.20398 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":32,"Type":"Firewaller","Request":"EnvironConfig","Params":{}} [LOG] 25.20403 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":33,"Type":"StringsWatcher","Id":"7","Request":"Next","Params":{}} [LOG] 25.20502 DEBUG juju.state.apiserver -> [E] machine-0 4.428925ms {"RequestId":31,"Response":{"Error":null,"Result":["0.1.2.3:1234"]}} Provisioner[""].StateAddresses [LOG] 25.20559 DEBUG juju.state.apiserver -> [E] machine-0 1.539278ms {"RequestId":32,"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\nIHRlc3RpbmciMB4XDTE0MDEyMzIxNTgzOVoXDTI0MDEyMzIyMDMzOFowSjENMAsG\nA1UEChMEanVqdTE5MDcGA1UEAwwwanVqdS1nZW5lcmF0ZWQgQ0EgZm9yIGVudmly\nb25tZW50ICJqdWp1IHRlc3RpbmciMFowCwYJKoZIhvcNAQEBA0sAMEgCQQC2dn/H\n83gMo8vid18cABA6fKRmwnvcUa6C6C9ZV3SZL4GWrN9jifxCAkDsD2xn9VxhT5l2\nY57aa7wAqlX2FhbJAgMBAAGjYzBhMA4GA1UdDwEB/wQEAwIApDAPBgNVHRMBAf8E\nBTADAQH/MB0GA1UdDgQWBBR6Z/BxFsmOy6Cmcoy/D7WeCOOZ2jAfBgNVHSMEGDAW\ngBR6Z/BxFsmOy6Cmcoy/D7WeCOOZ2jALBgkqhkiG9w0BAQUDQQAXS16PTz+FXVZV\nDqsmRdxFKg1zH+YpWoPcQrTN3HlnvUxq1JUqUYGiV2iKFsB1q4C9pf9cxkDq5mTM\n/Is/sky3\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":"8","state-port":1234,"state-server":true,"syslog-port":2345,"tools-metadata-url":"","tools-url":"","type":"dummy"}}} Firewaller[""].EnvironConfig [LOG] 25.20691 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":34,"Type":"Provisioner","Request":"APIAddresses","Params":{}} [LOG] 25.20698 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":35,"Type":"Firewaller","Request":"Life","Params":{"Entities":[{"Tag":"machine-1"}]}} [LOG] 25.20853 DEBUG juju.state.apiserver -> [E] machine-0 1.545779ms {"RequestId":35,"Response":{"Results":[{"Life":"alive","Error":null}]}} Firewaller[""].Life [LOG] 25.20956 DEBUG juju.state.apiserver -> [E] machine-0 2.646448ms {"RequestId":34,"Response":{"Error":null,"Result":["0.1.2.3:4321"]}} Provisioner[""].APIAddresses [LOG] 25.21018 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":36,"Type":"Firewaller","Request":"Life","Params":{"Entities":[{"Tag":"machine-1"}]}} [LOG] 25.21023 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":37,"Type":"Provisioner","Request":"CACert","Params":{}} [LOG] 25.21037 DEBUG juju.state.apiserver -> [E] machine-0 114.199us {"RequestId":37,"Response":{"Result":"LS0tLS1CRUdJTiBDRVJUSUZJQ0FURS0tLS0tCk1JSUI0akNDQVk2Z0F3SUJBZ0lCQURBTEJna3Foa2lHOXcwQkFRVXdTakVOTUFzR0ExVUVDaE1FYW5WcWRURTUKTURjR0ExVUVBd3d3YW5WcWRTMW5aVzVsY21GMFpXUWdRMEVnWm05eUlHVnVkbWx5YjI1dFpXNTBJQ0pxZFdwMQpJSFJsYzNScGJtY2lNQjRYRFRFME1ERXlNekl4TlRnek9Wb1hEVEkwTURFeU16SXlNRE16T0Zvd1NqRU5NQXNHCkExVUVDaE1FYW5WcWRURTVNRGNHQTFVRUF3d3dhblZxZFMxblpXNWxjbUYwWldRZ1EwRWdabTl5SUdWdWRtbHkKYjI1dFpXNTBJQ0pxZFdwMUlIUmxjM1JwYm1jaU1Gb3dDd1lKS29aSWh2Y05BUUVCQTBzQU1FZ0NRUUMyZG4vSAo4M2dNbzh2aWQxOGNBQkE2ZktSbXdudmNVYTZDNkM5WlYzU1pMNEdXck45amlmeENBa0RzRDJ4bjlWeGhUNWwyClk1N2FhN3dBcWxYMkZoYkpBZ01CQUFHall6QmhNQTRHQTFVZER3RUIvd1FFQXdJQXBEQVBCZ05WSFJNQkFmOEUKQlRBREFRSC9NQjBHQTFVZERnUVdCQlI2Wi9CeEZzbU95NkNtY295L0Q3V2VDT09aMmpBZkJnTlZIU01FR0RBVwpnQlI2Wi9CeEZzbU95NkNtY295L0Q3V2VDT09aMmpBTEJna3Foa2lHOXcwQkFRVURRUUFYUzE2UFR6K0ZYVlpWCkRxc21SZHhGS2cxekgrWXBXb1BjUXJUTjNIbG52VXhxMUpVcVVZR2lWMmlLRnNCMXE0QzlwZjljeGtEcTVtVE0KL0lzL3NreTMKLS0tLS1FTkQgQ0VSVElGSUNBVEUtLS0tLQo="}} Provisioner[""].CACert [LOG] 25.21069 DEBUG juju.state.apiserver -> [E] machine-0 17.69733ms {"RequestId":25,"Response":{"Error":null}} CharmRevisionUpdater[""].UpdateLatestRevisions [LOG] 25.21102 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":38,"Type":"Provisioner","Request":"WatchEnvironMachines","Params":{}} [LOG] 25.21163 DEBUG juju.state.apiserver -> [E] machine-0 34.42991ms {"RequestId":19,"Response":{"Results":[{"Error":null}]}} Machiner[""].SetStatus [LOG] 25.21183 DEBUG juju.state.apiserver -> [E] machine-0 1.643799ms {"RequestId":36,"Response":{"Results":[{"Life":"alive","Error":null}]}} Firewaller[""].Life [LOG] 25.21207 INFO juju.worker.machiner "machine-0" started [LOG] 25.21220 DEBUG juju.state.apiserver -> [E] machine-0 1.182544ms {"RequestId":38,"Response":{"StringsWatcherId":"8","Changes":["1","0"],"Error":null}} Provisioner[""].WatchEnvironMachines [LOG] 25.21231 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":39,"Type":"Machiner","Request":"Watch","Params":{"Entities":[{"Tag":"machine-0"}]}} [LOG] 25.21238 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":40,"Type":"Firewaller","Request":"WatchUnits","Params":{"Entities":[{"Tag":"machine-1"}]}} [LOG] 25.21268 INFO juju.provisioner Starting up provisioner task machine-0 [LOG] 25.21285 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":41,"Type":"Provisioner","Request":"Life","Params":{"Entities":[{"Tag":"machine-1"}]}} [LOG] 25.21290 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":42,"Type":"StringsWatcher","Id":"8","Request":"Next","Params":{}} [LOG] 25.21341 DEBUG juju.state.apiserver -> [E] machine-0 560.159us {"RequestId":41,"Response":{"Results":[{"Life":"alive","Error":null}]}} Provisioner[""].Life [LOG] 25.21356 DEBUG juju.state.apiserver -> [E] machine-0 1.260154ms {"RequestId":39,"Response":{"Results":[{"NotifyWatcherId":"9","Error":null}]}} Machiner[""].Watch [LOG] 25.21388 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":43,"Type":"Provisioner","Request":"Life","Params":{"Entities":[{"Tag":"machine-0"}]}} [LOG] 25.21394 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":44,"Type":"Machiner","Request":"Life","Params":{"Entities":[{"Tag":"machine-0"}]}} [LOG] 25.21399 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":45,"Type":"NotifyWatcher","Id":"9","Request":"Next","Params":{}} [LOG] 25.21477 DEBUG juju.state.apiserver -> [E] machine-0 881.848us {"RequestId":43,"Response":{"Results":[{"Life":"alive","Error":null}]}} Provisioner[""].Life [LOG] 25.21492 DEBUG juju.state.apiserver -> [E] machine-0 986.832us {"RequestId":44,"Response":{"Results":[{"Life":"alive","Error":null}]}} Machiner[""].Life [LOG] 25.21516 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":46,"Type":"Provisioner","Request":"InstanceId","Params":{"Entities":[{"Tag":"machine-1"}]}} [LOG] 25.21563 DEBUG juju.state.apiserver -> [E] machine-0 3.241601ms {"RequestId":40,"Response":{"Results":[{"StringsWatcherId":"10","Changes":["test-service/0"],"Error":null}]}} Firewaller[""].WatchUnits [LOG] 25.21596 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":47,"Type":"Firewaller","Request":"Life","Params":{"Entities":[{"Tag":"unit-test-service-0"}]}} [LOG] 25.21600 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":48,"Type":"StringsWatcher","Id":"10","Request":"Next","Params":{}} [LOG] 25.21638 DEBUG juju.state.apiserver -> [E] machine-0 1.212158ms {"RequestId":46,"Response":{"Results":[{"Error":{"Message":"machine 1 is not provisioned","Code":"not provisioned"},"Result":""}]}} Provisioner[""].InstanceId [LOG] 25.21653 DEBUG juju.state.apiserver -> [E] machine-0 587.944us {"RequestId":47,"Response":{"Results":[{"Life":"alive","Error":null}]}} Firewaller[""].Life [LOG] 25.21685 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":49,"Type":"Provisioner","Request":"Status","Params":{"Entities":[{"Tag":"machine-1"}]}} [LOG] 25.21691 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":50,"Type":"Firewaller","Request":"GetAssignedMachine","Params":{"Entities":[{"Tag":"unit-test-service-0"}]}} [LOG] 25.21769 DEBUG juju.state.apiserver -> [E] machine-0 737.836us {"RequestId":50,"Response":{"Results":[{"Error":null,"Result":"machine-1"}]}} Firewaller[""].GetAssignedMachine [LOG] 25.21794 DEBUG juju.state.apiserver -> [E] machine-0 1.067432ms {"RequestId":49,"Response":{"Results":[{"Error":null,"Status":"pending","Info":""}]}} Provisioner[""].Status [LOG] 25.21830 INFO juju.provisioner found machine "1" pending provisioning [LOG] 25.21849 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":51,"Type":"Firewaller","Request":"Life","Params":{"Entities":[{"Tag":"service-test-service"}]}} [LOG] 25.21859 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":52,"Type":"Provisioner","Request":"InstanceId","Params":{"Entities":[{"Tag":"machine-0"}]}} [LOG] 25.21931 DEBUG juju.state.apiserver -> [E] machine-0 815.704us {"RequestId":51,"Response":{"Results":[{"Life":"alive","Error":null}]}} Firewaller[""].Life [LOG] 25.21965 DEBUG juju.state.apiserver -> [E] machine-0 1.057458ms {"RequestId":52,"Response":{"Results":[{"Error":null,"Result":"ardbeg-0"}]}} Provisioner[""].InstanceId [LOG] 25.22002 INFO juju.provisioner machine 0 already started as instance "ardbeg-0" [LOG] 25.22022 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":53,"Type":"Firewaller","Request":"OpenedPorts","Params":{"Entities":[{"Tag":"unit-test-service-0"}]}} [LOG] 25.22032 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":54,"Type":"Provisioner","Request":"InstanceId","Params":{"Entities":[{"Tag":"machine-1"}]}} [LOG] 25.22114 DEBUG juju.state.apiserver -> [E] machine-0 890.621us {"RequestId":53,"Response":{"Results":[{"Error":null,"Ports":[]}]}} Firewaller[""].OpenedPorts [LOG] 25.22162 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":55,"Type":"Firewaller","Request":"GetExposed","Params":{"Entities":[{"Tag":"service-test-service"}]}} [LOG] 25.22232 DEBUG juju.state.apiserver -> [E] machine-0 1.988033ms {"RequestId":54,"Response":{"Results":[{"Error":{"Message":"machine 1 is not provisioned","Code":"not provisioned"},"Result":""}]}} Provisioner[""].InstanceId [LOG] 25.22254 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":56,"Type":"Provisioner","Request":"InstanceId","Params":{"Entities":[{"Tag":"machine-0"}]}} [LOG] 25.22281 DEBUG juju.state.apiserver -> [E] machine-0 1.190718ms {"RequestId":55,"Response":{"Results":[{"Error":null,"Result":false}]}} Firewaller[""].GetExposed [LOG] 25.22293 DEBUG juju worker/firewaller: started watching unit test-service/0 [LOG] 25.22294 DEBUG juju worker/firewaller: started watching "machine-1" [LOG] 25.22318 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":57,"Type":"Firewaller","Request":"Life","Params":{"Entities":[{"Tag":"machine-0"}]}} [LOG] 25.22323 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":58,"Type":"Firewaller","Request":"Watch","Params":{"Entities":[{"Tag":"service-test-service"}]}} [LOG] 25.22329 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":59,"Type":"Firewaller","Request":"Watch","Params":{"Entities":[{"Tag":"unit-test-service-0"}]}} [LOG] 25.22371 DEBUG juju.state.apiserver -> [E] machine-0 1.175148ms {"RequestId":56,"Response":{"Results":[{"Error":null,"Result":"ardbeg-0"}]}} Provisioner[""].InstanceId [LOG] 25.22400 DEBUG juju.state.apiserver -> [E] machine-0 829.121us {"RequestId":57,"Response":{"Results":[{"Life":"alive","Error":null}]}} Firewaller[""].Life [LOG] 25.22428 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":60,"Type":"Provisioner","Request":"Constraints","Params":{"Entities":[{"Tag":"machine-1"}]}} [LOG] 25.22434 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":61,"Type":"Firewaller","Request":"Life","Params":{"Entities":[{"Tag":"machine-0"}]}} [LOG] 25.22465 DEBUG juju.state.apiserver -> [E] machine-0 1.413269ms {"RequestId":58,"Response":{"Results":[{"NotifyWatcherId":"11","Error":null}]}} Firewaller[""].Watch [LOG] 25.22509 DEBUG juju.state.apiserver -> [E] machine-0 753.952us {"RequestId":61,"Response":{"Results":[{"Life":"alive","Error":null}]}} Firewaller[""].Life [LOG] 25.22521 DEBUG juju.state.apiserver -> [E] machine-0 1.882463ms {"RequestId":59,"Response":{"Results":[{"NotifyWatcherId":"12","Error":null}]}} Firewaller[""].Watch [LOG] 25.22550 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":62,"Type":"Firewaller","Request":"Life","Params":{"Entities":[{"Tag":"service-test-service"}]}} [LOG] 25.22555 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":63,"Type":"NotifyWatcher","Id":"11","Request":"Next","Params":{}} [LOG] 25.22561 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":64,"Type":"Firewaller","Request":"WatchUnits","Params":{"Entities":[{"Tag":"machine-0"}]}} [LOG] 25.22566 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":65,"Type":"Firewaller","Request":"Life","Params":{"Entities":[{"Tag":"unit-test-service-0"}]}} [LOG] 25.22571 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":66,"Type":"NotifyWatcher","Id":"12","Request":"Next","Params":{}} [LOG] 25.22607 DEBUG juju.state.apiserver -> [E] machine-0 1.790055ms {"RequestId":60,"Response":{"Results":[{"Error":null,"Constraints":{}}]}} Provisioner[""].Constraints [LOG] 25.22628 DEBUG juju.state.apiserver -> [E] machine-0 779.84us {"RequestId":62,"Response":{"Results":[{"Life":"alive","Error":null}]}} Firewaller[""].Life [LOG] 25.22648 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":67,"Type":"Provisioner","Request":"Series","Params":{"Entities":[{"Tag":"machine-1"}]}} [LOG] 25.22654 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":68,"Type":"Firewaller","Request":"GetExposed","Params":{"Entities":[{"Tag":"service-test-service"}]}} [LOG] 25.22670 DEBUG juju.state.apiserver -> [E] machine-0 1.044782ms {"RequestId":65,"Response":{"Results":[{"Life":"alive","Error":null}]}} Firewaller[""].Life [LOG] 25.22715 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":69,"Type":"Firewaller","Request":"OpenedPorts","Params":{"Entities":[{"Tag":"unit-test-service-0"}]}} [LOG] 25.22736 DEBUG juju.state.apiserver -> [E] machine-0 890.193us {"RequestId":67,"Response":{"Results":[{"Error":null,"Result":"quantal"}]}} Provisioner[""].Series [LOG] 25.22749 DEBUG juju.state.apiserver -> [E] machine-0 955.298us {"RequestId":68,"Response":{"Results":[{"Error":null,"Result":false}]}} Firewaller[""].GetExposed [LOG] 25.22771 INFO juju.environs.tools reading tools with major.minor version 1.17 [LOG] 25.22773 INFO juju.environs.tools filtering tools by version: 1.17.1 [LOG] 25.22774 INFO juju.environs.tools filtering tools by series: quantal [LOG] 25.22777 DEBUG juju.environs.tools no architecture specified when finding tools, looking for any [LOG] 25.22810 DEBUG juju.environs.simplestreams fetchData failed for "http://127.0.0.1:46087/dummyenv/private/tools/streams/v1/index.sjson": file "tools/streams/v1/index.sjson" not found not found [LOG] 25.22813 DEBUG juju.environs.simplestreams cannot load index "http://127.0.0.1:46087/dummyenv/private/tools/streams/v1/index.sjson": invalid URL "http://127.0.0.1:46087/dummyenv/private/tools/streams/v1/index.sjson" not found [LOG] 25.22822 DEBUG juju.environs.simplestreams fetchData failed for "http://127.0.0.1:46087/dummyenv/private/tools/streams/v1/mirrors.json": file "tools/streams/v1/mirrors.json" not found not found [LOG] 25.22823 DEBUG juju.environs.simplestreams no mirror index file found [LOG] 25.22826 DEBUG juju.environs.simplestreams no mirror information available for { }: mirror data for "com.ubuntu.juju:released:tools" not found [LOG] 25.22827 DEBUG juju.environs.simplestreams read metadata index at "http://127.0.0.1:46087/dummyenv/private/tools/streams/v1/index.json" [LOG] 25.22832 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 [{Thu, 23 Jan 2014 22:04:06 +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] 25.22833 DEBUG juju.environs.simplestreams finding products at path "streams/v1/com.ubuntu.juju:released:tools.json" [LOG] 25.22865 DEBUG juju.environs.simplestreams metadata: &{map[com.ubuntu.juju:12.04:amd64:{ 1.17.1 amd64 map[20140123:0xc200a269c0]} com.ubuntu.juju:12.10:amd64:{ 1.17.1 amd64 map[20140123:0xc200a26ae0]}] map[] Thu, 23 Jan 2014 22:04:06 +0000 products:1.0 com.ubuntu.juju:released:tools} [LOG] 25.22889 DEBUG juju.state.apiserver -> [E] machine-0 1.747234ms {"RequestId":69,"Response":{"Results":[{"Error":null,"Ports":[]}]}} Firewaller[""].OpenedPorts [LOG] 25.22904 DEBUG juju.state.apiserver -> [E] machine-0 3.429302ms {"RequestId":64,"Response":{"Results":[{"StringsWatcherId":"13","Changes":null,"Error":null}]}} Firewaller[""].WatchUnits [LOG] 25.23087 DEBUG juju worker/firewaller: started watching "machine-0" [LOG] 25.23140 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":70,"Type":"Provisioner","Request":"SetPasswords","Params":{"Changes":[{"Tag":"machine-1","Password":"zDpXpVEjbcU0+GHsW/CuUB+l"}]}} [LOG] 25.23159 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":71,"Type":"Firewaller","Request":"Life","Params":{"Entities":[{"Tag":"machine-1"}]}} [LOG] 25.23177 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":72,"Type":"StringsWatcher","Id":"13","Request":"Next","Params":{}} [LOG] 25.23395 DEBUG juju.state.apiserver -> [E] machine-0 2.257877ms {"RequestId":71,"Response":{"Results":[{"Life":"alive","Error":null}]}} Firewaller[""].Life [LOG] 25.23518 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":73,"Type":"Firewaller","Request":"InstanceId","Params":{"Entities":[{"Tag":"machine-1"}]}} [LOG] 25.23575 INFO juju worker: start "authenticationworker" [LOG] 25.23594 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":74,"Type":"KeyUpdater","Request":"AuthorisedKeys","Params":{"Entities":[{"Tag":"machine-0"}]}} [LOG] 25.23734 DEBUG juju.state.apiserver -> [E] machine-0 2.230259ms {"RequestId":73,"Response":{"Results":[{"Error":{"Message":"machine 1 is not provisioned","Code":"not provisioned"},"Result":""}]}} Firewaller[""].InstanceId [LOG] 25.23959 DEBUG juju.state.apiserver -> [E] machine-0 3.185357ms {"RequestId":74,"Response":{"Results":[{"Error":null,"Result":["my-keys"]}]}} KeyUpdater[""].AuthorisedKeys [LOG] 25.24345 DEBUG juju.utils.ssh reading authorised keys file /tmp/gocheck-5486140987150761883/56/.ssh/authorized_keys [LOG] 25.24351 WARNING juju.utils.ssh invalid Juju ssh key my-keys: malformed line: "my-keys" [LOG] 25.24355 DEBUG juju.utils.ssh reading authorised keys file /tmp/gocheck-5486140987150761883/56/.ssh/authorized_keys [LOG] 25.24358 ERROR juju.worker.authenticationworker adding current Juju keys to ssh authorised keys: generating key fingerprint: malformed line: "my-keys" [LOG] 25.24376 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":75,"Type":"NotifyWatcher","Id":"6","Request":"Stop","Params":{}} [LOG] 25.24393 DEBUG juju.state.apiserver -> [E] machine-0 46.78592ms {"RequestId":30,"Error":"watcher has been stopped","ErrorCode":"stopped","Response":{}} NotifyWatcher["6"].Next [LOG] 25.24398 DEBUG juju.state.apiserver -> [E] machine-0 232.217us {"RequestId":75,"Response":{}} NotifyWatcher["6"].Stop [LOG] 25.24414 ERROR juju worker: exited "authenticationworker": adding current Juju keys to ssh authorised keys: generating key fingerprint: malformed line: "my-keys" [LOG] 25.24416 INFO juju worker: restarting "authenticationworker" in 50ms [LOG] 25.24497 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":77,"Type":"StringsWatcher","Id":"7","Request":"Stop","Params":{}} [LOG] 25.24526 DEBUG juju.state.apiserver -> [E] machine-0 41.116237ms {"RequestId":33,"Error":"watcher has been stopped","ErrorCode":"stopped","Response":{}} StringsWatcher["7"].Next [LOG] 25.24544 DEBUG juju.state.apiserver -> [E] machine-0 531.872us {"RequestId":77,"Response":{}} StringsWatcher["7"].Stop [LOG] 25.24615 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":78,"Type":"NotifyWatcher","Id":"12","Request":"Stop","Params":{}} [LOG] 25.24630 DEBUG juju.state.apiserver -> [E] machine-0 20.554899ms {"RequestId":66,"Error":"watcher has been stopped","ErrorCode":"stopped","Response":{}} NotifyWatcher["12"].Next [LOG] 25.24645 DEBUG juju.state.apiserver -> [E] machine-0 318.925us {"RequestId":78,"Response":{}} NotifyWatcher["12"].Stop [LOG] 25.24695 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":79,"Type":"NotifyWatcher","Id":"11","Request":"Stop","Params":{}} [LOG] 25.24710 DEBUG juju.state.apiserver -> [E] machine-0 21.510244ms {"RequestId":63,"Error":"watcher has been stopped","ErrorCode":"stopped","Response":{}} NotifyWatcher["11"].Next [LOG] 25.24725 DEBUG juju.state.apiserver -> [E] machine-0 300.707us {"RequestId":79,"Response":{}} NotifyWatcher["11"].Stop [LOG] 25.24775 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":80,"Type":"StringsWatcher","Id":"10","Request":"Stop","Params":{}} [LOG] 25.24796 DEBUG juju.state.apiserver -> [E] machine-0 31.914057ms {"RequestId":48,"Error":"watcher has been stopped","ErrorCode":"stopped","Response":{}} StringsWatcher["10"].Next [LOG] 25.24810 DEBUG juju.state.apiserver -> [E] machine-0 367.035us {"RequestId":80,"Response":{}} StringsWatcher["10"].Stop [LOG] 25.24859 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":81,"Type":"StringsWatcher","Id":"13","Request":"Stop","Params":{}} [LOG] 25.24873 DEBUG juju.state.apiserver -> [E] machine-0 16.932043ms {"RequestId":72,"Error":"watcher has been stopped","ErrorCode":"stopped","Response":{}} StringsWatcher["13"].Next [LOG] 25.24888 DEBUG juju.state.apiserver -> [E] machine-0 291.277us {"RequestId":81,"Response":{}} StringsWatcher["13"].Stop [LOG] 25.25004 ERROR juju worker: fatal "firewaller": machine 1 is not provisioned [LOG] 25.25029 DEBUG juju worker: killing "machiner" [LOG] 25.25053 DEBUG juju worker: killing "upgrader" [LOG] 25.25076 DEBUG juju worker: killing "logger" [LOG] 25.25099 DEBUG juju worker: killing "authenticationworker" [LOG] 25.25120 DEBUG juju worker: killing "0-container-watcher" [LOG] 25.25143 DEBUG juju worker: killing "environ-provisioner" [LOG] 25.25167 DEBUG juju worker: killing "charm-revision-updater" [LOG] 25.25499 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":82,"Type":"NotifyWatcher","Id":"9","Request":"Stop","Params":{}} [LOG] 25.25602 DEBUG juju.state.apiserver -> [E] machine-0 41.599491ms {"RequestId":45,"Error":"watcher has been stopped","ErrorCode":"stopped","Response":{}} NotifyWatcher["9"].Next [LOG] 25.25700 DEBUG juju.state.apiserver -> [E] machine-0 2.048119ms {"RequestId":82,"Response":{}} NotifyWatcher["9"].Stop [LOG] 25.25821 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":83,"Type":"NotifyWatcher","Id":"3","Request":"Stop","Params":{}} [LOG] 25.25839 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":84,"Type":"NotifyWatcher","Id":"2","Request":"Stop","Params":{}} [LOG] 25.25853 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":85,"Type":"StringsWatcher","Id":"5","Request":"Stop","Params":{}} [LOG] 25.25879 DEBUG juju.state.apiserver -> [E] machine-0 625.418us {"RequestId":83,"Response":{}} NotifyWatcher["3"].Stop [LOG] 25.25887 DEBUG juju.state.apiserver -> [E] machine-0 505.939us {"RequestId":84,"Response":{}} NotifyWatcher["2"].Stop [LOG] 25.25893 DEBUG juju.state.apiserver -> [E] machine-0 419.648us {"RequestId":85,"Response":{}} StringsWatcher["5"].Stop [LOG] 25.25930 DEBUG juju.state.apiserver -> [E] machine-0 95.881492ms {"RequestId":18,"Error":"watcher has been stopped","ErrorCode":"stopped","Response":{}} NotifyWatcher["3"].Next [LOG] 25.25949 DEBUG juju.state.apiserver -> [E] machine-0 134.373066ms {"RequestId":15,"Error":"watcher has been stopped","ErrorCode":"stopped","Response":{}} NotifyWatcher["2"].Next [LOG] 25.25968 DEBUG juju.state.apiserver -> [E] machine-0 62.55893ms {"RequestId":29,"Error":"watcher has been stopped","ErrorCode":"stopped","Response":{}} StringsWatcher["5"].Next [LOG] 25.26633 INFO juju setting password for "machine-1" [LOG] 25.26644 DEBUG juju.state.apiserver -> [E] machine-0 35.114107ms {"RequestId":70,"Response":{"Results":[{"Error":null}]}} Provisioner[""].SetPasswords [LOG] 25.26789 INFO juju.provider.dummy dummy startinstance, machine 1 [LOG] 25.26798 INFO juju.provider.dummy would pick tools from 1.17.1-quantal-amd64 [LOG] 25.26856 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":86,"Type":"Provisioner","Request":"SetProvisioned","Params":{"Machines":[{"Tag":"machine-1","InstanceId":"dummyenv-0","Nonce":"machine-0:cc6fdad2-6980-400f-86e5-a9913a40cecb","Characteristics":{"Arch":"amd64","Mem":1024,"RootDisk":8192,"CpuCores":1,"CpuPower":null,"Tags":null}}]}} [LOG] 25.27974 DEBUG juju.state.apiserver -> [E] machine-0 11.293027ms {"RequestId":86,"Response":{"Results":[{"Error":null}]}} Provisioner[""].SetProvisioned [LOG] 25.27999 INFO juju.provisioner started machine 1 as instance dummyenv-0 with hardware "arch=amd64 cpu-cores=1 mem=1024M root-disk=8192M" [LOG] 25.28001 INFO juju.provisioner Shutting down provisioner task machine-0 [LOG] 25.28017 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":87,"Type":"StringsWatcher","Id":"8","Request":"Stop","Params":{}} [LOG] 25.28027 DEBUG juju.state.apiserver -> [E] machine-0 67.359161ms {"RequestId":42,"Error":"watcher has been stopped","ErrorCode":"stopped","Response":{}} StringsWatcher["8"].Next [LOG] 25.28034 DEBUG juju.state.apiserver -> [E] machine-0 185.669us {"RequestId":87,"Response":{}} StringsWatcher["8"].Stop [LOG] 25.28061 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":88,"Type":"NotifyWatcher","Id":"4","Request":"Stop","Params":{}} [LOG] 25.28069 DEBUG juju.state.apiserver -> [E] machine-0 83.632854ms {"RequestId":28,"Error":"watcher has been stopped","ErrorCode":"stopped","Response":{}} NotifyWatcher["4"].Next [LOG] 25.28077 DEBUG juju.state.apiserver -> [E] machine-0 168.981us {"RequestId":88,"Response":{}} NotifyWatcher["4"].Stop [LOG] 25.29458 INFO juju worker: start "authenticationworker" [LOG] 25.29500 DEBUG juju worker: killing "authenticationworker" [LOG] 25.29559 DEBUG juju.state.apiserver <- [E] machine-0 {"RequestId":89,"Type":"KeyUpdater","Request":"AuthorisedKeys","Params":{"Entities":[{"Tag":"machine-0"}]}} [LOG] 25.30246 DEBUG juju.state.apiserver -> [E] machine-0 6.872406ms {"RequestId":89,"Response":{"Results":[{"Error":null,"Result":["my-keys"]}]}} KeyUpdater[""].AuthorisedKeys [LOG] 25.30335 DEBUG juju.utils.ssh reading authorised keys file /tmp/gocheck-5486140987150761883/56/.ssh/authorized_keys [LOG] 25.30357 WARNING juju.utils.ssh invalid Juju ssh key my-keys: malformed line: "my-keys" [LOG] 25.30393 DEBUG juju.utils.ssh reading authorised keys file /tmp/gocheck-5486140987150761883/56/.ssh/authorized_keys [LOG] 25.30408 ERROR juju.worker.authenticationworker adding current Juju keys to ssh authorised keys: generating key fingerprint: malformed line: "my-keys" [LOG] 25.30530 ERROR juju worker: exited "authenticationworker": adding current Juju keys to ssh authorised keys: generating key fingerprint: malformed line: "my-keys" [LOG] 25.30817 INFO juju.state.apiserver [E] API connection terminated after 215.707264ms [LOG] 25.30858 ERROR juju worker: fatal "api": machine 1 is not provisioned [LOG] 25.30868 DEBUG juju worker: killing "state" [LOG] 25.30871 DEBUG juju worker: killing runner 0xc200c782a0 [LOG] 25.30881 DEBUG juju worker: killing "termination" [LOG] 25.30893 INFO juju worker: runner is dying [LOG] 25.30902 DEBUG juju worker: killing "addressupdater" [LOG] 25.30906 DEBUG juju worker: killing "apiserver" [LOG] 25.30914 DEBUG juju worker: killing "cleaner" [LOG] 25.30922 DEBUG juju worker: killing "resumer" [LOG] 25.30928 DEBUG juju worker: killing "minunitsworker" machine_test.go:342: c.Check(a.Run(nil), gc.IsNil) ... value *params.Error = ¶ms.Error{"not provisioned", "machine 1 is not provisioned"} ("machine 1 is not provisioned") unit provisioned with instance dummyenv-0 final machine addresses: []instance.Address(nil) machine_test.go:360: c.Fatalf("timed out waiting for machine to get address") ... Error: timed out waiting for machine to get address [LOG] 39.88158 DEBUG juju worker: killing runner 0xc200672720 [LOG] 39.88476 INFO juju.provider.dummy reset environment [LOG] 39.88527 INFO juju.state.apiserver [D] API connection terminated after 14.955866791s [LOG] 39.89250 INFO juju Reset successfully reset admin password [LOG] 39.89937 INFO juju Reset successfully reset admin password [LOG] 39.90265 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 (63.50 seconds) FAIL FAIL launchpad.net/juju-core/cmd/jujud 63.805s ok launchpad.net/juju-core/cmd/plugins/juju-metadata 12.723s ? 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.038s ok launchpad.net/juju-core/container/factory 0.053s ok launchpad.net/juju-core/container/kvm 0.258s ok launchpad.net/juju-core/container/kvm/mock 0.051s ? launchpad.net/juju-core/container/kvm/testing [no test files] ok launchpad.net/juju-core/container/lxc 0.310s ? 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.306s ok launchpad.net/juju-core/environs 3.116s ok launchpad.net/juju-core/environs/bootstrap 4.579s ok launchpad.net/juju-core/environs/cloudinit 0.628s ok launchpad.net/juju-core/environs/config 3.365s ok launchpad.net/juju-core/environs/configstore 0.040s ok launchpad.net/juju-core/environs/filestorage 0.033s ok launchpad.net/juju-core/environs/httpstorage 0.862s ok launchpad.net/juju-core/environs/imagemetadata 0.538s ? launchpad.net/juju-core/environs/imagemetadata/testing [no test files] ok launchpad.net/juju-core/environs/instances 0.059s ok launchpad.net/juju-core/environs/jujutest 0.247s ok launchpad.net/juju-core/environs/manual 8.382s ok launchpad.net/juju-core/environs/simplestreams 0.323s ? launchpad.net/juju-core/environs/simplestreams/testing [no test files] ok launchpad.net/juju-core/environs/sshstorage 1.288s ok launchpad.net/juju-core/environs/storage 1.096s ok launchpad.net/juju-core/environs/sync 33.638s ok launchpad.net/juju-core/environs/testing 0.204s ok launchpad.net/juju-core/environs/tools 6.878s ? 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 25.233s ok launchpad.net/juju-core/juju/osenv 0.021s ? launchpad.net/juju-core/juju/testing [no test files] ok launchpad.net/juju-core/log 0.016s ok launchpad.net/juju-core/log/syslog 0.023s ok launchpad.net/juju-core/names 0.028s ? 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.179s ok launchpad.net/juju-core/provider/common 7.525s ok launchpad.net/juju-core/provider/dummy 24.032s ok launchpad.net/juju-core/provider/ec2 20.623s ok launchpad.net/juju-core/provider/joyent 5.296s ok launchpad.net/juju-core/provider/local 2.383s ok launchpad.net/juju-core/provider/maas 23.406s ok launchpad.net/juju-core/provider/null 1.224s ok launchpad.net/juju-core/provider/openstack 33.552s ok launchpad.net/juju-core/replicaset 43.850s ok launchpad.net/juju-core/rpc 0.106s ok launchpad.net/juju-core/rpc/jsoncodec 0.033s ? launchpad.net/juju-core/rpc/rpcreflect [no test files] ok launchpad.net/juju-core/schema 0.038s ok launchpad.net/juju-core/state 86.780s ok launchpad.net/juju-core/state/api 2.075s ok launchpad.net/juju-core/state/api/agent 2.206s ? launchpad.net/juju-core/state/api/base [no test files] ok launchpad.net/juju-core/state/api/charmrevisionupdater 1.491s ? 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.653s ok launchpad.net/juju-core/state/api/firewaller 10.562s ok launchpad.net/juju-core/state/api/keymanager 3.650s ok launchpad.net/juju-core/state/api/keyupdater 2.686s ok launchpad.net/juju-core/state/api/logger 2.387s ok launchpad.net/juju-core/state/api/machiner 3.224s ok launchpad.net/juju-core/state/api/params 0.036s ok launchpad.net/juju-core/state/api/provisioner 9.727s ok launchpad.net/juju-core/state/api/uniter 27.343s ok launchpad.net/juju-core/state/api/upgrader 4.012s ok launchpad.net/juju-core/state/api/watcher 3.153s ok launchpad.net/juju-core/state/apiserver 7.980s ok launchpad.net/juju-core/state/apiserver/agent 3.383s ok launchpad.net/juju-core/state/apiserver/charmrevisionupdater 2.756s ? launchpad.net/juju-core/state/apiserver/charmrevisionupdater/testing [no test files] ok launchpad.net/juju-core/state/apiserver/client 46.363s ok launchpad.net/juju-core/state/apiserver/common 2.153s ? launchpad.net/juju-core/state/apiserver/common/testing [no test files] ok launchpad.net/juju-core/state/apiserver/deployer 4.853s ok launchpad.net/juju-core/state/apiserver/firewaller 6.291s ok launchpad.net/juju-core/state/apiserver/keymanager 4.614s ? launchpad.net/juju-core/state/apiserver/keymanager/testing [no test files] ok launchpad.net/juju-core/state/apiserver/keyupdater 3.086s ok launchpad.net/juju-core/state/apiserver/logger 3.681s ok launchpad.net/juju-core/state/apiserver/machine 3.080s ok launchpad.net/juju-core/state/apiserver/provisioner 12.312s ? launchpad.net/juju-core/state/apiserver/testing [no test files] ok launchpad.net/juju-core/state/apiserver/uniter 19.934s ok launchpad.net/juju-core/state/apiserver/upgrader 5.805s ok launchpad.net/juju-core/state/multiwatcher 0.777s ok launchpad.net/juju-core/state/presence 6.459s ? 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.918s ok launchpad.net/juju-core/store 19.776s ok launchpad.net/juju-core/testing 1.419s ok launchpad.net/juju-core/testing/checkers 0.025s ok launchpad.net/juju-core/testing/testbase 0.019s ok launchpad.net/juju-core/thirdparty/pbkdf2 0.134s ok launchpad.net/juju-core/tools 0.019s ok launchpad.net/juju-core/upstart 1.281s ok launchpad.net/juju-core/utils 2.412s ok launchpad.net/juju-core/utils/exec 0.045s ok launchpad.net/juju-core/utils/fslock 1.070s ok launchpad.net/juju-core/utils/parallel 0.933s ok launchpad.net/juju-core/utils/set 0.023s ok launchpad.net/juju-core/utils/ssh 23.475s ? launchpad.net/juju-core/utils/ssh/testing [no test files] ok launchpad.net/juju-core/utils/tailer 1.054s ok launchpad.net/juju-core/version 0.064s ok launchpad.net/juju-core/worker 6.595s ok launchpad.net/juju-core/worker/addressupdater 1.376s ok launchpad.net/juju-core/worker/authenticationworker 3.167s ok launchpad.net/juju-core/worker/charmrevisionworker 2.054s ok launchpad.net/juju-core/worker/cleaner 1.223s ok launchpad.net/juju-core/worker/deployer 3.747s ok launchpad.net/juju-core/worker/firewaller 12.574s ok launchpad.net/juju-core/worker/localstorage 0.055s ok launchpad.net/juju-core/worker/logger 1.630s ok launchpad.net/juju-core/worker/machiner 3.092s ok launchpad.net/juju-core/worker/minunitsworker 1.342s ok launchpad.net/juju-core/worker/provisioner 40.826s ok launchpad.net/juju-core/worker/resumer 1.548s ok launchpad.net/juju-core/worker/terminationworker 0.069s ok launchpad.net/juju-core/worker/uniter 172.509s ok launchpad.net/juju-core/worker/uniter/charm 3.936s ok launchpad.net/juju-core/worker/uniter/debug 1.127s ok launchpad.net/juju-core/worker/uniter/hook 0.016s ok launchpad.net/juju-core/worker/uniter/jujuc 0.482s ok launchpad.net/juju-core/worker/uniter/relation 1.385s ok launchpad.net/juju-core/worker/upgrader 3.808s 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.