The attempt to merge lp:~gz/juju-core/itsy_bitsy_change into lp:juju-core failed. Below is the output from the failed tests. ok launchpad.net/juju-core/agent 1.735s ok launchpad.net/juju-core/agent/tools 25.984s ok launchpad.net/juju-core/bzr 6.498s ok launchpad.net/juju-core/cert 3.083s ok launchpad.net/juju-core/charm 0.698s ? launchpad.net/juju-core/charm/hooks [no test files] ok launchpad.net/juju-core/cloudinit 0.016s ok launchpad.net/juju-core/cmd 0.205s ? launchpad.net/juju-core/cmd/builddb [no test files] ? 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 97.858s ---------------------------------------------------------------------- FAIL: machine_test.go:149: MachineSuite.TestDyingMachine [LOG] 18.92819 INFO juju environs/testing: uploading FAKE tools 1.13.0-precise-amd64 [LOG] 18.92852 INFO juju environs: reading tools with major version 1 [LOG] 18.92856 DEBUG juju.agent.tools reading v1.* tools [LOG] 18.92858 INFO juju environs: falling back to public bucket [LOG] 18.92859 DEBUG juju.agent.tools reading v1.* tools [LOG] 18.92868 DEBUG juju.agent.tools found 1.13.0-precise-amd64 [LOG] 18.92870 INFO juju environs: filtering tools by series: precise [LOG] 18.92872 INFO juju environs: filtering tools by version: 1.13.0 [LOG] 18.92875 INFO juju environs/dummy: would pick tools from 1.13.0-precise-amd64 [LOG] 18.95421 INFO juju state: opening state; mongo addresses: ["localhost:50043"]; entity "" [LOG] 18.96385 INFO juju state: connection established [LOG] 18.98333 INFO juju state: initializing environment [LOG] 19.03387 INFO juju state/api: listening on "127.0.0.1:56962" [LOG] 19.05479 INFO juju state: opening state; mongo addresses: ["localhost:50043"]; entity "" [LOG] 19.06326 INFO juju state: connection established [LOG] 19.06385 INFO juju juju: authorization error while connecting to state server; retrying [LOG] 19.06391 INFO juju state: opening state; mongo addresses: ["localhost:50043"]; entity "" [LOG] 19.06698 INFO juju state: connection established [LOG] 19.10453 INFO juju state/api: dialing "wss://127.0.0.1:56962/" [LOG] 19.10774 INFO juju rpc: discarding action method reflect.Method{Name:"apiRootForEntity", PkgPath:"launchpad.net/juju-core/state/apiserver", Type:(*reflect.rtype)(0xaf16a0), Func:reflect.Value{typ:(*reflect.rtype)(0xaf16a0), val:(unsafe.Pointer)(0xb20d60), flag:0x131}, Index:1} [LOG] 19.10782 INFO juju state/api: connection established [LOG] 19.10805 DEBUG juju rpc/jsoncodec: <- {"RequestId":1,"Type":"Admin","Request":"Login","Params":{"AuthTag":"user-admin","Password":"dummy-secret","Nonce":""}} [LOG] 19.10847 INFO juju rpc: discarding obtainer method reflect.Method{Name:"AuthClient", PkgPath:"", Type:(*reflect.rtype)(0xa15320), Func:reflect.Value{typ:(*reflect.rtype)(0xa15320), val:(unsafe.Pointer)(0xbd3720), flag:0x130}, Index:1} [LOG] 19.10850 INFO juju rpc: discarding obtainer method reflect.Method{Name:"AuthEnvironManager", PkgPath:"", Type:(*reflect.rtype)(0xa15320), Func:reflect.Value{typ:(*reflect.rtype)(0xa15320), val:(unsafe.Pointer)(0xbd3750), flag:0x130}, Index:2} [LOG] 19.10852 INFO juju rpc: discarding obtainer method reflect.Method{Name:"AuthMachineAgent", PkgPath:"", Type:(*reflect.rtype)(0xa15320), Func:reflect.Value{typ:(*reflect.rtype)(0xa15320), val:(unsafe.Pointer)(0xbd3780), flag:0x130}, Index:3} [LOG] 19.10854 INFO juju rpc: discarding obtainer method reflect.Method{Name:"AuthOwner", PkgPath:"", Type:(*reflect.rtype)(0xa733e0), Func:reflect.Value{typ:(*reflect.rtype)(0xa733e0), val:(unsafe.Pointer)(0xbd37b0), flag:0x130}, Index:4} [LOG] 19.10864 INFO juju rpc: discarding action method reflect.Method{Name:"getEnvironStateInfo", PkgPath:"launchpad.net/juju-core/state/apiserver/deployer", Type:(*reflect.rtype)(0xabe840), Func:reflect.Value{typ:(*reflect.rtype)(0xabe840), val:(unsafe.Pointer)(0xbba2c0), flag:0x131}, Index:7} [LOG] 19.10873 INFO juju rpc: discarding action method reflect.Method{Name:"removeEntity", PkgPath:"launchpad.net/juju-core/state/apiserver/common", Type:(*reflect.rtype)(0xa741a0), Func:reflect.Value{typ:(*reflect.rtype)(0xa741a0), val:(unsafe.Pointer)(0xbba2f0), flag:0x131}, Index:8} [LOG] 19.10877 INFO juju rpc: discarding action method reflect.Method{Name:"setPassword", PkgPath:"launchpad.net/juju-core/state/apiserver/common", Type:(*reflect.rtype)(0xabeb60), Func:reflect.Value{typ:(*reflect.rtype)(0xabeb60), val:(unsafe.Pointer)(0xbba320), flag:0x131}, Index:9} [LOG] 19.10879 INFO juju rpc: discarding obtainer method reflect.Method{Name:"GetAuthTag", PkgPath:"", Type:(*reflect.rtype)(0xa15420), Func:reflect.Value{typ:(*reflect.rtype)(0xa15420), val:(unsafe.Pointer)(0xbd3840), flag:0x130}, Index:7} [LOG] 19.10881 INFO juju rpc: discarding obtainer method reflect.Method{Name:"Kill", PkgPath:"", Type:(*reflect.rtype)(0x9e0d20), Func:reflect.Value{typ:(*reflect.rtype)(0x9e0d20), val:(unsafe.Pointer)(0xbd3870), flag:0x130}, Index:8} [LOG] 19.10886 INFO juju rpc: discarding action method reflect.Method{Name:"getMachine", PkgPath:"launchpad.net/juju-core/state/apiserver/machine", Type:(*reflect.rtype)(0xabeca0), Func:reflect.Value{typ:(*reflect.rtype)(0xabeca0), val:(unsafe.Pointer)(0xb74830), flag:0x131}, Index:2} [LOG] 19.10888 INFO juju rpc: discarding action method reflect.Method{Name:"setPassword", PkgPath:"launchpad.net/juju-core/state/apiserver/common", Type:(*reflect.rtype)(0xabed40), Func:reflect.Value{typ:(*reflect.rtype)(0xabed40), val:(unsafe.Pointer)(0xb74860), flag:0x131}, Index:3} [LOG] 19.10896 INFO juju rpc: discarding action method reflect.Method{Name:"oneAgentTools", PkgPath:"launchpad.net/juju-core/state/apiserver/upgrader", Type:(*reflect.rtype)(0xafe620), Func:reflect.Value{typ:(*reflect.rtype)(0xafe620), val:(unsafe.Pointer)(0xb874c0), flag:0x131}, Index:3} [LOG] 19.10898 INFO juju rpc: discarding action method reflect.Method{Name:"setOneAgentTools", PkgPath:"launchpad.net/juju-core/state/apiserver/upgrader", Type:(*reflect.rtype)(0xabf240), Func:reflect.Value{typ:(*reflect.rtype)(0xabf240), val:(unsafe.Pointer)(0xb874f0), flag:0x131}, Index:4} [LOG] 19.10901 INFO juju rpc: discarding obtainer method reflect.Method{Name:"requireAgent", PkgPath:"launchpad.net/juju-core/state/apiserver", Type:(*reflect.rtype)(0xa153a0), Func:reflect.Value{typ:(*reflect.rtype)(0xa153a0), val:(unsafe.Pointer)(0xbd39c0), flag:0x131}, Index:15} [LOG] 19.10904 INFO juju rpc: discarding obtainer method reflect.Method{Name:"requireClient", PkgPath:"launchpad.net/juju-core/state/apiserver", Type:(*reflect.rtype)(0xa153a0), Func:reflect.Value{typ:(*reflect.rtype)(0xa153a0), val:(unsafe.Pointer)(0xbd39f0), flag:0x131}, Index:16} [LOG] 19.10907 DEBUG juju rpc/jsoncodec: -> {"RequestId":1,"Response":{}} [LOG] 19.10936 INFO juju.container.lxc lxcObjectFactory replaced with mock lxc factory [LOG] 19.11042 DEBUG juju rpc/jsoncodec: <- {"RequestId":2,"Type":"Pinger","Request":"Ping","Params":{}} [LOG] 19.11049 DEBUG juju rpc/jsoncodec: -> {"RequestId":2,"Response":{}} [LOG] 19.15557 INFO juju machine agent machine-0 start [LOG] 19.16330 DEBUG juju.jujud.validation found lxc, not installing [LOG] 19.16332 INFO juju Starting StateWorker for machine-0 [LOG] 19.16335 INFO juju worker: start "state" [LOG] 19.16338 INFO juju state: opening state; mongo addresses: ["localhost:50043"]; entity "machine-0" [LOG] 19.16365 INFO juju worker: start "api" [LOG] 19.16383 INFO juju state/api: dialing "wss://127.0.0.1:56962/" [LOG] 19.17238 INFO juju state/api: connection established [LOG] 19.17252 INFO juju state: connection established [LOG] 19.17323 DEBUG juju rpc/jsoncodec: <- {"RequestId":1,"Type":"Admin","Request":"Login","Params":{"AuthTag":"machine-0","Password":"machine-password","Nonce":"user-admin:bootstrap"}} [LOG] 19.18201 DEBUG juju rpc/jsoncodec: -> {"RequestId":1,"Response":{}} [LOG] 19.18267 DEBUG juju rpc/jsoncodec: <- {"RequestId":2,"Type":"MachineAgent","Request":"GetMachines","Params":{"Entities":[{"Tag":"machine-0"}]}} [LOG] 19.18274 DEBUG juju rpc/jsoncodec: <- {"RequestId":3,"Type":"Pinger","Request":"Ping","Params":{}} [LOG] 19.18295 DEBUG juju rpc/jsoncodec: -> {"RequestId":3,"Response":{}} [LOG] 19.18319 DEBUG juju rpc/jsoncodec: -> {"RequestId":2,"Response":{"Machines":[{"Life":"dying","Jobs":["JobHostUnits"],"Error":null}]}} [LOG] 19.18346 INFO juju worker: start "upgrader" [LOG] 19.18380 INFO juju worker: start "lxc-provisioner" [LOG] 19.18412 INFO juju worker: start "machiner" [LOG] 19.19505 DEBUG juju rpc/jsoncodec: <- {"RequestId":4,"Type":"Deployer","Request":"CACert","Params":{}} [LOG] 19.19516 DEBUG juju rpc/jsoncodec: <- {"RequestId":5,"Type":"Machiner","Request":"Life","Params":{"Entities":[{"Tag":"machine-0"}]}} [LOG] 19.19534 DEBUG juju rpc/jsoncodec: -> {"RequestId":4,"Response":{"Result":"LS0tLS1CRUdJTiBDRVJUSUZJQ0FURS0tLS0tCk1JSUIzakNDQVlxZ0F3SUJBZ0lCQURBTEJna3Foa2lHOXcwQkFRVXdTREVOTUFzR0ExVUVDaE1FYW5WcWRURTMKTURVR0ExVUVBeE11YW5WcWRTMW5aVzVsY21GMFpXUWdRMEVnWm05eUlHVnVkbWx5YjI1dFpXNTBJR3AxYW5VZwpkR1Z6ZEdsdVp6QWVGdzB4TXpBM01qa3hOak0zTVRkYUZ3MHlNekEzTWpreE5qUXlNVGRhTUVneERUQUxCZ05WCkJBb1RCR3AxYW5VeE56QTFCZ05WQkFNVExtcDFhblV0WjJWdVpYSmhkR1ZrSUVOQklHWnZjaUJsYm5acGNtOXUKYldWdWRDQnFkV3AxSUhSbGMzUnBibWN3V2pBTEJna3Foa2lHOXcwQkFRRURTd0F3U0FKQkFMWTQwaElWaWZNRgpZRkxOeVlManJaeDZCQ3JMMkQra05mMm9yTHk5V3ZldmI1KzBURElWcUFCSDVMNnZvbXl4U1N2aXVpdm1hSmJJCjBJTk4xNlhDZ3JrQ0F3RUFBYU5qTUdFd0RnWURWUjBQQVFIL0JBUURBZ0NrTUE4R0ExVWRFd0VCL3dRRk1BTUIKQWY4d0hRWURWUjBPQkJZRUZMczZFeXRuejMrbldGYStGNDBCUXo2WEhqeXVNQjhHQTFVZEl3UVlNQmFBRkxzNgpFeXRuejMrbldGYStGNDBCUXo2WEhqeXVNQXNHQ1NxR1NJYjNEUUVCQlFOQkFGYkpGbnNmWEFVV1VQTC9ZVEpHCk56Z3pmcHBSNG90QlF5VExvYjlTSVRYeVhNYXhXU3FkMFdTcE8rdHpWWmo3Tm56SzNpY1J6MTNqM29oNVVnWGgKNVBBPQotLS0tLUVORCBDRVJUSUZJQ0FURS0tLS0tCg=="}} [LOG] 19.19814 DEBUG juju rpc/jsoncodec: -> {"RequestId":5,"Response":{"Results":[{"Life":"dying","Error":null}]}} [LOG] 19.19847 INFO juju.provisioner Starting up provisioner task 0 [LOG] 19.19849 INFO juju worker: start "deployer" [LOG] 19.19854 DEBUG juju rpc/jsoncodec: <- {"RequestId":6,"Type":"Deployer","Request":"WatchUnits","Params":{"Entities":[{"Tag":"machine-0"}]}} [LOG] 19.19903 DEBUG juju rpc/jsoncodec: <- {"RequestId":7,"Type":"Machiner","Request":"SetStatus","Params":{"Machines":[{"Tag":"machine-0","Status":"started","Info":""}]}} [LOG] 19.19924 DEBUG juju rpc/jsoncodec: -> {"RequestId":6,"Response":{"Results":[{"StringsWatcherId":"2","Changes":null,"Error":null}]}} [LOG] 19.19979 INFO juju.worker.deployer checking unit "tarmac/0" [LOG] 19.20017 DEBUG juju rpc/jsoncodec: <- {"RequestId":8,"Type":"Deployer","Request":"Life","Params":{"Entities":[{"Tag":"unit-tarmac-0"}]}} [LOG] 19.20024 DEBUG juju rpc/jsoncodec: <- {"RequestId":9,"Type":"StringsWatcher","Id":"2","Request":"Next","Params":{}} [LOG] 19.20256 DEBUG juju rpc/jsoncodec: -> {"RequestId":8,"Response":{"Results":[{"Life":"","Error":{"Message":"permission denied","Code":"unauthorized access"}}]}} [LOG] 19.20282 INFO juju.worker.deployer recalling unit "tarmac/0" [LOG] 19.20390 ERROR juju worker: fatal "deployer": remove /etc/init/jujud-machine-3:unit-tarmac-0.conf: permission denied [LOG] 19.20392 DEBUG juju worker: killing "machiner" [LOG] 19.20402 DEBUG juju rpc/jsoncodec: <- {"RequestId":10,"Type":"StringsWatcher","Id":"2","Request":"Stop","Params":{}} [LOG] 19.20418 DEBUG juju rpc/jsoncodec: -> {"RequestId":9,"Error":"watcher has been stopped","ErrorCode":"stopped","Response":{}} [LOG] 19.20434 DEBUG juju rpc/jsoncodec: -> {"RequestId":10,"Response":{}} [LOG] 19.21355 DEBUG juju rpc/jsoncodec: -> {"RequestId":7,"Response":{"Results":[{"Error":null}]}} [LOG] 19.21372 INFO juju.worker.machiner "machine-0" started [LOG] 19.21384 DEBUG juju rpc/jsoncodec: <- {"RequestId":11,"Type":"Machiner","Request":"Watch","Params":{"Entities":[{"Tag":"machine-0"}]}} [LOG] 19.21454 DEBUG juju rpc/jsoncodec: -> {"RequestId":11,"Response":{"Results":[{"NotifyWatcherId":"3","Error":null}]}} [LOG] 19.21489 ERROR juju state/api: error trying to stop watcher connection is shut down [LOG] 19.21492 ERROR juju worker: exited "api": remove /etc/init/jujud-machine-3:unit-tarmac-0.conf: permission denied [LOG] 19.21496 INFO juju worker: restarting "api" in 3s [LOG] 19.21507 DEBUG juju rpc/jsoncodec: <- {"RequestId":12,"Type":"NotifyWatcher","Id":"3","Request":"Stop","Params":{}} [LOG] 19.21515 DEBUG juju rpc/jsoncodec: <- {"RequestId":13,"Type":"NotifyWatcher","Id":"3","Request":"Next","Params":{}} [LOG] 19.21519 DEBUG juju rpc/jsoncodec: <- error: EOF (closing false) [LOG] 19.21528 DEBUG juju rpc/jsoncodec: -> {"RequestId":13,"Error":"watcher has been stopped","ErrorCode":"stopped","Response":{}} [LOG] 19.21660 DEBUG juju rpc/jsoncodec: -> {"RequestId":12,"Response":{}} [LOG] 19.21674 ERROR juju rpc: error writing response: write tcp 127.0.0.1:49070: broken pipe [LOG] 19.21676 INFO juju rpc: error closing codec: write tcp 127.0.0.1:49070: broken pipe [LOG] 22.21544 INFO juju worker: start "api" [LOG] 22.21613 INFO juju state/api: dialing "wss://127.0.0.1:56962/" [LOG] 22.22786 INFO juju state/api: connection established [LOG] 22.22817 DEBUG juju rpc/jsoncodec: <- {"RequestId":1,"Type":"Admin","Request":"Login","Params":{"AuthTag":"machine-0","Password":"machine-password","Nonce":"user-admin:bootstrap"}} [LOG] 22.23012 DEBUG juju rpc/jsoncodec: -> {"RequestId":1,"Response":{}} [LOG] 22.23035 DEBUG juju rpc/jsoncodec: <- {"RequestId":2,"Type":"MachineAgent","Request":"GetMachines","Params":{"Entities":[{"Tag":"machine-0"}]}} [LOG] 22.23042 DEBUG juju rpc/jsoncodec: <- {"RequestId":3,"Type":"Pinger","Request":"Ping","Params":{}} [LOG] 22.23068 DEBUG juju rpc/jsoncodec: -> {"RequestId":3,"Response":{}} [LOG] 22.23089 DEBUG juju rpc/jsoncodec: -> {"RequestId":2,"Response":{"Machines":[{"Life":"dying","Jobs":["JobHostUnits"],"Error":null}]}} [LOG] 22.23105 INFO juju worker: start "machiner" [LOG] 22.23113 DEBUG juju rpc/jsoncodec: <- {"RequestId":4,"Type":"Deployer","Request":"CACert","Params":{}} [LOG] 22.23118 DEBUG juju rpc/jsoncodec: <- {"RequestId":5,"Type":"Machiner","Request":"Life","Params":{"Entities":[{"Tag":"machine-0"}]}} [LOG] 22.23126 DEBUG juju rpc/jsoncodec: -> {"RequestId":4,"Response":{"Result":"LS0tLS1CRUdJTiBDRVJUSUZJQ0FURS0tLS0tCk1JSUIzakNDQVlxZ0F3SUJBZ0lCQURBTEJna3Foa2lHOXcwQkFRVXdTREVOTUFzR0ExVUVDaE1FYW5WcWRURTMKTURVR0ExVUVBeE11YW5WcWRTMW5aVzVsY21GMFpXUWdRMEVnWm05eUlHVnVkbWx5YjI1dFpXNTBJR3AxYW5VZwpkR1Z6ZEdsdVp6QWVGdzB4TXpBM01qa3hOak0zTVRkYUZ3MHlNekEzTWpreE5qUXlNVGRhTUVneERUQUxCZ05WCkJBb1RCR3AxYW5VeE56QTFCZ05WQkFNVExtcDFhblV0WjJWdVpYSmhkR1ZrSUVOQklHWnZjaUJsYm5acGNtOXUKYldWdWRDQnFkV3AxSUhSbGMzUnBibWN3V2pBTEJna3Foa2lHOXcwQkFRRURTd0F3U0FKQkFMWTQwaElWaWZNRgpZRkxOeVlManJaeDZCQ3JMMkQra05mMm9yTHk5V3ZldmI1KzBURElWcUFCSDVMNnZvbXl4U1N2aXVpdm1hSmJJCjBJTk4xNlhDZ3JrQ0F3RUFBYU5qTUdFd0RnWURWUjBQQVFIL0JBUURBZ0NrTUE4R0ExVWRFd0VCL3dRRk1BTUIKQWY4d0hRWURWUjBPQkJZRUZMczZFeXRuejMrbldGYStGNDBCUXo2WEhqeXVNQjhHQTFVZEl3UVlNQmFBRkxzNgpFeXRuejMrbldGYStGNDBCUXo2WEhqeXVNQXNHQ1NxR1NJYjNEUUVCQlFOQkFGYkpGbnNmWEFVV1VQTC9ZVEpHCk56Z3pmcHBSNG90QlF5VExvYjlTSVRYeVhNYXhXU3FkMFdTcE8rdHpWWmo3Tm56SzNpY1J6MTNqM29oNVVnWGgKNVBBPQotLS0tLUVORCBDRVJUSUZJQ0FURS0tLS0tCg=="}} [LOG] 22.23162 DEBUG juju rpc/jsoncodec: -> {"RequestId":5,"Response":{"Results":[{"Life":"dying","Error":null}]}} [LOG] 22.23196 INFO juju worker: start "deployer" [LOG] 22.23206 DEBUG juju rpc/jsoncodec: <- {"RequestId":6,"Type":"Machiner","Request":"SetStatus","Params":{"Machines":[{"Tag":"machine-0","Status":"started","Info":""}]}} [LOG] 22.23218 DEBUG juju rpc/jsoncodec: <- {"RequestId":7,"Type":"Deployer","Request":"WatchUnits","Params":{"Entities":[{"Tag":"machine-0"}]}} [LOG] 22.23410 DEBUG juju rpc/jsoncodec: -> {"RequestId":7,"Response":{"Results":[{"StringsWatcherId":"2","Changes":null,"Error":null}]}} [LOG] 22.23492 INFO juju.worker.deployer checking unit "tarmac/0" [LOG] 22.23602 DEBUG juju rpc/jsoncodec: <- {"RequestId":8,"Type":"Deployer","Request":"Life","Params":{"Entities":[{"Tag":"unit-tarmac-0"}]}} [LOG] 22.23612 DEBUG juju rpc/jsoncodec: <- {"RequestId":9,"Type":"StringsWatcher","Id":"2","Request":"Next","Params":{}} [LOG] 22.23739 DEBUG juju rpc/jsoncodec: -> {"RequestId":8,"Response":{"Results":[{"Life":"","Error":{"Message":"permission denied","Code":"unauthorized access"}}]}} [LOG] 22.23755 INFO juju.worker.deployer recalling unit "tarmac/0" [LOG] 22.23814 ERROR juju worker: fatal "deployer": remove /etc/init/jujud-machine-3:unit-tarmac-0.conf: permission denied [LOG] 22.23815 DEBUG juju worker: killing "machiner" [LOG] 22.23877 DEBUG juju rpc/jsoncodec: <- {"RequestId":10,"Type":"StringsWatcher","Id":"2","Request":"Stop","Params":{}} [LOG] 22.23886 DEBUG juju rpc/jsoncodec: -> {"RequestId":9,"Error":"watcher has been stopped","ErrorCode":"stopped","Response":{}} [LOG] 22.23891 DEBUG juju rpc/jsoncodec: -> {"RequestId":10,"Response":{}} [LOG] 22.25110 DEBUG juju rpc/jsoncodec: -> {"RequestId":6,"Response":{"Results":[{"Error":null}]}} [LOG] 22.25129 INFO juju.worker.machiner "machine-0" started [LOG] 22.25143 DEBUG juju rpc/jsoncodec: <- {"RequestId":11,"Type":"Machiner","Request":"Watch","Params":{"Entities":[{"Tag":"machine-0"}]}} [LOG] 22.25209 DEBUG juju rpc/jsoncodec: -> {"RequestId":11,"Response":{"Results":[{"NotifyWatcherId":"3","Error":null}]}} [LOG] 22.25299 ERROR juju state/api: error trying to stop watcher connection is shut down [LOG] 22.25302 ERROR juju worker: exited "api": remove /etc/init/jujud-machine-3:unit-tarmac-0.conf: permission denied [LOG] 22.25306 INFO juju worker: restarting "api" in 3s [LOG] 22.25316 DEBUG juju rpc/jsoncodec: <- {"RequestId":12,"Type":"NotifyWatcher","Id":"3","Request":"Stop","Params":{}} [LOG] 22.25324 DEBUG juju rpc/jsoncodec: <- error: EOF (closing false) [LOG] 22.25429 DEBUG juju rpc/jsoncodec: -> {"RequestId":12,"Response":{}} [LOG] 22.25441 INFO juju rpc: error closing codec: write tcp 127.0.0.1:49132: broken pipe [LOG] 25.25348 INFO juju worker: start "api" [LOG] 25.25421 INFO juju state/api: dialing "wss://127.0.0.1:56962/" [LOG] 25.26873 INFO juju state/api: connection established [LOG] 25.26886 DEBUG juju rpc/jsoncodec: <- {"RequestId":1,"Type":"Admin","Request":"Login","Params":{"AuthTag":"machine-0","Password":"machine-password","Nonce":"user-admin:bootstrap"}} [LOG] 25.29451 DEBUG juju rpc/jsoncodec: -> {"RequestId":1,"Response":{}} [LOG] 25.29475 DEBUG juju rpc/jsoncodec: <- {"RequestId":2,"Type":"MachineAgent","Request":"GetMachines","Params":{"Entities":[{"Tag":"machine-0"}]}} [LOG] 25.29481 DEBUG juju rpc/jsoncodec: <- {"RequestId":3,"Type":"Pinger","Request":"Ping","Params":{}} [LOG] 25.29501 DEBUG juju rpc/jsoncodec: -> {"RequestId":3,"Response":{}} [LOG] 25.29521 DEBUG juju rpc/jsoncodec: -> {"RequestId":2,"Response":{"Machines":[{"Life":"dying","Jobs":["JobHostUnits"],"Error":null}]}} [LOG] 25.29536 INFO juju worker: start "machiner" [LOG] 25.29549 DEBUG juju rpc/jsoncodec: <- {"RequestId":4,"Type":"Deployer","Request":"CACert","Params":{}} [LOG] 25.29554 DEBUG juju rpc/jsoncodec: <- {"RequestId":5,"Type":"Machiner","Request":"Life","Params":{"Entities":[{"Tag":"machine-0"}]}} [LOG] 25.29562 DEBUG juju rpc/jsoncodec: -> {"RequestId":4,"Response":{"Result":"LS0tLS1CRUdJTiBDRVJUSUZJQ0FURS0tLS0tCk1JSUIzakNDQVlxZ0F3SUJBZ0lCQURBTEJna3Foa2lHOXcwQkFRVXdTREVOTUFzR0ExVUVDaE1FYW5WcWRURTMKTURVR0ExVUVBeE11YW5WcWRTMW5aVzVsY21GMFpXUWdRMEVnWm05eUlHVnVkbWx5YjI1dFpXNTBJR3AxYW5VZwpkR1Z6ZEdsdVp6QWVGdzB4TXpBM01qa3hOak0zTVRkYUZ3MHlNekEzTWpreE5qUXlNVGRhTUVneERUQUxCZ05WCkJBb1RCR3AxYW5VeE56QTFCZ05WQkFNVExtcDFhblV0WjJWdVpYSmhkR1ZrSUVOQklHWnZjaUJsYm5acGNtOXUKYldWdWRDQnFkV3AxSUhSbGMzUnBibWN3V2pBTEJna3Foa2lHOXcwQkFRRURTd0F3U0FKQkFMWTQwaElWaWZNRgpZRkxOeVlManJaeDZCQ3JMMkQra05mMm9yTHk5V3ZldmI1KzBURElWcUFCSDVMNnZvbXl4U1N2aXVpdm1hSmJJCjBJTk4xNlhDZ3JrQ0F3RUFBYU5qTUdFd0RnWURWUjBQQVFIL0JBUURBZ0NrTUE4R0ExVWRFd0VCL3dRRk1BTUIKQWY4d0hRWURWUjBPQkJZRUZMczZFeXRuejMrbldGYStGNDBCUXo2WEhqeXVNQjhHQTFVZEl3UVlNQmFBRkxzNgpFeXRuejMrbldGYStGNDBCUXo2WEhqeXVNQXNHQ1NxR1NJYjNEUUVCQlFOQkFGYkpGbnNmWEFVV1VQTC9ZVEpHCk56Z3pmcHBSNG90QlF5VExvYjlTSVRYeVhNYXhXU3FkMFdTcE8rdHpWWmo3Tm56SzNpY1J6MTNqM29oNVVnWGgKNVBBPQotLS0tLUVORCBDRVJUSUZJQ0FURS0tLS0tCg=="}} [LOG] 25.29595 DEBUG juju rpc/jsoncodec: -> {"RequestId":5,"Response":{"Results":[{"Life":"dying","Error":null}]}} [LOG] 25.29625 INFO juju worker: start "deployer" [LOG] 25.29630 DEBUG juju rpc/jsoncodec: <- {"RequestId":6,"Type":"Machiner","Request":"SetStatus","Params":{"Machines":[{"Tag":"machine-0","Status":"started","Info":""}]}} [LOG] 25.29636 DEBUG juju rpc/jsoncodec: <- {"RequestId":7,"Type":"Deployer","Request":"WatchUnits","Params":{"Entities":[{"Tag":"machine-0"}]}} [LOG] 25.29859 DEBUG juju rpc/jsoncodec: -> {"RequestId":7,"Response":{"Results":[{"StringsWatcherId":"2","Changes":null,"Error":null}]}} [LOG] 25.29935 INFO juju.worker.deployer checking unit "tarmac/0" [LOG] 25.29959 DEBUG juju rpc/jsoncodec: <- {"RequestId":8,"Type":"Deployer","Request":"Life","Params":{"Entities":[{"Tag":"unit-tarmac-0"}]}} [LOG] 25.29967 DEBUG juju rpc/jsoncodec: <- {"RequestId":9,"Type":"StringsWatcher","Id":"2","Request":"Next","Params":{}} [LOG] 25.30148 DEBUG juju rpc/jsoncodec: -> {"RequestId":8,"Response":{"Results":[{"Life":"","Error":{"Message":"permission denied","Code":"unauthorized access"}}]}} [LOG] 25.30161 INFO juju.worker.deployer recalling unit "tarmac/0" [LOG] 25.30209 ERROR juju worker: fatal "deployer": remove /etc/init/jujud-machine-3:unit-tarmac-0.conf: permission denied [LOG] 25.30210 DEBUG juju worker: killing "machiner" [LOG] 25.30215 DEBUG juju rpc/jsoncodec: <- {"RequestId":10,"Type":"StringsWatcher","Id":"2","Request":"Stop","Params":{}} [LOG] 25.30223 DEBUG juju rpc/jsoncodec: -> {"RequestId":9,"Error":"watcher has been stopped","ErrorCode":"stopped","Response":{}} [LOG] 25.30228 DEBUG juju rpc/jsoncodec: -> {"RequestId":10,"Response":{}} [LOG] 25.30486 DEBUG juju rpc/jsoncodec: -> {"RequestId":6,"Response":{"Results":[{"Error":null}]}} [LOG] 25.30499 INFO juju.worker.machiner "machine-0" started [LOG] 25.30507 DEBUG juju rpc/jsoncodec: <- {"RequestId":11,"Type":"Machiner","Request":"Watch","Params":{"Entities":[{"Tag":"machine-0"}]}} [LOG] 25.30557 DEBUG juju rpc/jsoncodec: -> {"RequestId":11,"Response":{"Results":[{"NotifyWatcherId":"3","Error":null}]}} [LOG] 25.30580 ERROR juju state/api: error trying to stop watcher connection is shut down [LOG] 25.30582 ERROR juju worker: exited "api": remove /etc/init/jujud-machine-3:unit-tarmac-0.conf: permission denied [LOG] 25.30584 INFO juju worker: restarting "api" in 3s [LOG] 25.30589 DEBUG juju rpc/jsoncodec: <- {"RequestId":12,"Type":"NotifyWatcher","Id":"3","Request":"Stop","Params":{}} [LOG] 25.30594 DEBUG juju rpc/jsoncodec: <- {"RequestId":13,"Type":"NotifyWatcher","Id":"3","Request":"Next","Params":{}} [LOG] 25.30598 DEBUG juju rpc/jsoncodec: <- error: EOF (closing false) [LOG] 25.30603 DEBUG juju rpc/jsoncodec: -> {"RequestId":13,"Error":"watcher has been stopped","ErrorCode":"stopped","Response":{}} [LOG] 25.30675 DEBUG juju rpc/jsoncodec: -> {"RequestId":12,"Response":{}} [LOG] 25.30686 ERROR juju rpc: error writing response: write tcp 127.0.0.1:49187: broken pipe [LOG] 25.30690 INFO juju rpc: error closing codec: write tcp 127.0.0.1:49187: broken pipe machine_test.go:167: // TODO(rog) Fix this so it doesn't wait for so long. // https://bugs.launchpad.net/juju-core/+bug/1163983 c.Fatalf("timed out waiting for agent to terminate") ... Error: timed out waiting for agent to terminate [LOG] 25.43201 DEBUG juju worker: killing runner 0xc2005e2c60 [LOG] 25.43207 INFO juju worker: runner is dying [LOG] 25.43211 DEBUG juju worker: killing "state" [LOG] 25.43213 DEBUG juju worker: killing runner 0xc20037dba0 [LOG] 25.43216 DEBUG juju worker: killing "api" [LOG] 25.43218 DEBUG juju worker: killing runner 0xc20031ef60 [LOG] 25.43222 INFO juju worker: runner is dying [LOG] 25.43225 DEBUG juju worker: killing "upgrader" [LOG] 25.43228 DEBUG juju worker: killing "lxc-provisioner" [LOG] 25.43236 INFO juju.provisioner Shutting down provisioner task 0 [LOG] 25.43322 INFO juju.container.lxc lxcObjectFactory replaced with &{} [LOG] 25.43377 INFO juju environs/dummy: reset environment [LOG] 25.43393 DEBUG juju rpc/jsoncodec: <- error: read tcp 127.0.0.1:49067: use of closed network connection (closing true) [jujuc whatever] [remote] [/path/to/remote] [remote --help] [unknown] [remote --error borken] [remote --unknown] [remote unwanted] OOPS: 47 passed, 2 skipped, 1 FAILED --- FAIL: TestPackage (48.03 seconds) FAIL FAIL launchpad.net/juju-core/cmd/jujud 48.271s ok launchpad.net/juju-core/constraints 0.027s ok launchpad.net/juju-core/container/lxc 0.375s ? launchpad.net/juju-core/container/lxc/mock [no test files] ok launchpad.net/juju-core/downloader 5.321s ok launchpad.net/juju-core/environs 1.160s ? launchpad.net/juju-core/environs/all [no test files] ok launchpad.net/juju-core/environs/azure 2.524s ok launchpad.net/juju-core/environs/cloudinit 0.426s ok launchpad.net/juju-core/environs/config 0.712s ok launchpad.net/juju-core/environs/dummy 16.065s ok launchpad.net/juju-core/environs/ec2 173.733s ok launchpad.net/juju-core/environs/imagemetadata 0.249s ok launchpad.net/juju-core/environs/instances 0.222s ok launchpad.net/juju-core/environs/jujutest 0.239s ok launchpad.net/juju-core/environs/local 1.259s ? launchpad.net/juju-core/environs/local/storage [no test files] ok launchpad.net/juju-core/environs/localstorage 0.248s ok launchpad.net/juju-core/environs/maas 2.150s ok launchpad.net/juju-core/environs/openstack 7.341s ? launchpad.net/juju-core/environs/provider [no test files] ok launchpad.net/juju-core/environs/sync 0.257s ok launchpad.net/juju-core/environs/testing 0.015s ? launchpad.net/juju-core/errors [no test files] ok launchpad.net/juju-core/instance 0.021s ok launchpad.net/juju-core/juju 11.871s ? launchpad.net/juju-core/juju/testing [no test files] ok launchpad.net/juju-core/log 0.023s ok launchpad.net/juju-core/log/syslog 0.028s ok launchpad.net/juju-core/rpc 0.291s ok launchpad.net/juju-core/rpc/jsoncodec 0.285s ok launchpad.net/juju-core/schema 0.030s ok launchpad.net/juju-core/state 67.972s ? launchpad.net/juju-core/state/api [no test files] ? launchpad.net/juju-core/state/api/common [no test files] ok launchpad.net/juju-core/state/api/deployer 5.187s ok launchpad.net/juju-core/state/api/machineagent 1.510s ok launchpad.net/juju-core/state/api/machiner 2.471s ok launchpad.net/juju-core/state/api/params 0.035s ok launchpad.net/juju-core/state/api/upgrader 2.947s ok launchpad.net/juju-core/state/api/watcher 2.579s ok launchpad.net/juju-core/state/apiserver 2.454s ok launchpad.net/juju-core/state/apiserver/client 11.554s ok launchpad.net/juju-core/state/apiserver/common 0.041s ok launchpad.net/juju-core/state/apiserver/deployer 3.592s ok launchpad.net/juju-core/state/apiserver/machine 3.167s ? launchpad.net/juju-core/state/apiserver/testing [no test files] ok launchpad.net/juju-core/state/apiserver/upgrader 3.332s ok launchpad.net/juju-core/state/multiwatcher 0.896s ok launchpad.net/juju-core/state/presence 6.445s ok launchpad.net/juju-core/state/statecmd 6.178s ? launchpad.net/juju-core/state/testing [no test files] ok launchpad.net/juju-core/state/watcher 5.572s ok launchpad.net/juju-core/store 17.918s ok launchpad.net/juju-core/testing 1.334s ok launchpad.net/juju-core/testing/checkers 0.016s ok launchpad.net/juju-core/thirdparty/pbkdf2 0.132s ok launchpad.net/juju-core/upstart 0.113s ok launchpad.net/juju-core/utils 0.959s ok launchpad.net/juju-core/utils/fslock 0.987s ok launchpad.net/juju-core/utils/set 0.026s ok launchpad.net/juju-core/version 0.060s ok launchpad.net/juju-core/worker 1.378s ok launchpad.net/juju-core/worker/cleaner 1.078s ok launchpad.net/juju-core/worker/deployer 3.314s ok launchpad.net/juju-core/worker/firewaller 9.972s ok launchpad.net/juju-core/worker/machiner 2.297s ok launchpad.net/juju-core/worker/minunitsworker 1.158s ok launchpad.net/juju-core/worker/provisioner 16.368s ok launchpad.net/juju-core/worker/resumer 1.420s ok launchpad.net/juju-core/worker/uniter 110.969s ok launchpad.net/juju-core/worker/uniter/charm 2.375s ok launchpad.net/juju-core/worker/uniter/hook 0.014s ok launchpad.net/juju-core/worker/uniter/jujuc 0.485s ok launchpad.net/juju-core/worker/uniter/relation 1.302s ok launchpad.net/juju-core/worker/upgrader 2.172s warning: building out-of-date packages: 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.