The attempt to merge lp:~jameinel/juju-core/api-set-creds-1199915 into lp:juju-core failed. Below is the output from the failed tests. ---------------------------------------------------------------------- PANIC: agent_test.go:0: openSuite.TearDownTest ... Panic: watcher iteration error: unauthorized db:juju ns:juju.txns.log lock type:0 client:127.0.0.1 (PC=0x41175F) /usr/lib/go/src/pkg/runtime/proc.c:1443 in panic /home/tarmac/trees/src/launchpad.net/juju-core/environs/dummy/environs.go:213 in environState.destroy /home/tarmac/trees/src/launchpad.net/juju-core/environs/dummy/environs.go:189 in Reset /home/tarmac/trees/src/launchpad.net/juju-core/juju/testing/conn.go:235 in JujuConnSuite.tearDownConn /home/tarmac/trees/src/launchpad.net/juju-core/juju/testing/conn.go:134 in JujuConnSuite.TearDownTest ---------------------------------------------------------------------- PANIC: agent_test.go:384: openSuite.TestOpenAPIFallbackPassword [LOG] 86.66806 INFO juju environs/testing: uploading FAKE tools 1.11.3-precise-amd64 [LOG] 86.66819 INFO juju environs: reading tools with major version 1 [LOG] 86.66821 DEBUG juju.environs.tools reading v1.* tools [LOG] 86.66823 INFO juju environs: falling back to public bucket [LOG] 86.66824 DEBUG juju.environs.tools reading v1.* tools [LOG] 86.66829 DEBUG juju.environs.tools found 1.11.3-precise-amd64 [LOG] 86.66831 INFO juju environs: filtering tools by series: precise [LOG] 86.66834 INFO juju environs: filtering tools by version: 1.11.3 [LOG] 86.66837 INFO juju environs/dummy: would pick tools from 1.11.3-precise-amd64 [LOG] 86.69442 INFO juju state: opening state; mongo addresses: ["localhost:38047"]; entity "" [LOG] 86.69843 INFO juju state: connection established [LOG] 91.48213 INFO juju state: initializing environment [LOG] 70.72201 INFO juju state/api: listening on "127.0.0.1:50666" [LOG] 70.75192 INFO juju state: opening state; mongo addresses: ["localhost:38047"]; entity "" [LOG] 70.75555 INFO juju state: connection established [LOG] 70.75608 INFO juju juju: authorization error while connecting to state server; retrying [LOG] 70.75614 INFO juju state: opening state; mongo addresses: ["localhost:38047"]; entity "" [LOG] 70.75931 INFO juju state: connection established [LOG] 70.81147 INFO juju state/api: dialing "wss://127.0.0.1:50666/" [LOG] 70.81616 INFO juju state/api: connection established [LOG] 70.81640 INFO juju rpc: discarding action method reflect.Method{Name:"apiRootForEntity", PkgPath:"launchpad.net/juju-core/state/apiserver", Type:(*reflect.commonType)(0x800ad8), Func:reflect.Value{typ:(*reflect.commonType)(0x800ad8), val:(unsafe.Pointer)(0x6b547b), flag:0x131}, Index:1} [LOG] 70.81651 DEBUG juju rpc/jsoncodec: <- {"RequestId":1,"Type":"Admin","Request":"Login","Params":{"AuthTag":"user-admin","Password":"dummy-secret"}} [LOG] 70.81700 INFO juju rpc: discarding obtainer method reflect.Method{Name:"AuthClient", PkgPath:"", Type:(*reflect.commonType)(0x746b50), Func:reflect.Value{typ:(*reflect.commonType)(0x746b50), val:(unsafe.Pointer)(0x6b68d1), flag:0x130}, Index:1} [LOG] 70.81703 INFO juju rpc: discarding obtainer method reflect.Method{Name:"AuthEnvironManager", PkgPath:"", Type:(*reflect.commonType)(0x746b50), Func:reflect.Value{typ:(*reflect.commonType)(0x746b50), val:(unsafe.Pointer)(0x6b688b), flag:0x130}, Index:2} [LOG] 70.81705 INFO juju rpc: discarding obtainer method reflect.Method{Name:"AuthMachineAgent", PkgPath:"", Type:(*reflect.commonType)(0x746b50), Func:reflect.Value{typ:(*reflect.commonType)(0x746b50), val:(unsafe.Pointer)(0x6b67ae), flag:0x130}, Index:3} [LOG] 70.81708 INFO juju rpc: discarding obtainer method reflect.Method{Name:"AuthOwner", PkgPath:"", Type:(*reflect.commonType)(0x7a3240), Func:reflect.Value{typ:(*reflect.commonType)(0x7a3240), val:(unsafe.Pointer)(0x6b67f5), flag:0x130}, Index:4} [LOG] 70.81716 INFO juju rpc: discarding obtainer method reflect.Method{Name:"GetAuthTag", PkgPath:"", Type:(*reflect.commonType)(0x746c40), Func:reflect.Value{typ:(*reflect.commonType)(0x746c40), val:(unsafe.Pointer)(0x6b691a), flag:0x130}, Index:6} [LOG] 70.81719 INFO juju rpc: discarding obtainer method reflect.Method{Name:"Kill", PkgPath:"", Type:(*reflect.commonType)(0x723928), Func:reflect.Value{typ:(*reflect.commonType)(0x723928), val:(unsafe.Pointer)(0x6b600e), flag:0x130}, Index:7} [LOG] 70.81723 INFO juju rpc: discarding action method reflect.Method{Name:"getMachine", PkgPath:"launchpad.net/juju-core/state/apiserver/machine", Type:(*reflect.commonType)(0x801688), Func:reflect.Value{typ:(*reflect.commonType)(0x801688), val:(unsafe.Pointer)(0x6fa2fe), flag:0x131}, Index:2} [LOG] 70.81725 INFO juju rpc: discarding action method reflect.Method{Name:"setPassword", PkgPath:"launchpad.net/juju-core/state/apiserver/common", Type:(*reflect.commonType)(0x801710), Func:reflect.Value{typ:(*reflect.commonType)(0x801710), val:(unsafe.Pointer)(0x6fb47b), flag:0x131}, Index:3} [LOG] 70.81732 INFO juju rpc: discarding action method reflect.Method{Name:"oneAgentTools", PkgPath:"launchpad.net/juju-core/state/apiserver/upgrader", Type:(*reflect.commonType)(0x846b60), Func:reflect.Value{typ:(*reflect.commonType)(0x846b60), val:(unsafe.Pointer)(0x6fbb04), flag:0x131}, Index:3} [LOG] 70.81735 INFO juju rpc: discarding obtainer method reflect.Method{Name:"requireAgent", PkgPath:"launchpad.net/juju-core/state/apiserver", Type:(*reflect.commonType)(0x746bc8), Func:reflect.Value{typ:(*reflect.commonType)(0x746bc8), val:(unsafe.Pointer)(0x6b603c), flag:0x131}, Index:14} [LOG] 70.81738 INFO juju rpc: discarding obtainer method reflect.Method{Name:"requireClient", PkgPath:"launchpad.net/juju-core/state/apiserver", Type:(*reflect.commonType)(0x746bc8), Func:reflect.Value{typ:(*reflect.commonType)(0x746bc8), val:(unsafe.Pointer)(0x6b60aa), flag:0x131}, Index:15} [LOG] 70.81741 DEBUG juju rpc/jsoncodec: -> {"RequestId":1,"Response":{}} [LOG] 70.81803 INFO juju state/api: dialing "wss://127.0.0.1:50666/" [LOG] 70.82176 DEBUG juju rpc/jsoncodec: <- {"RequestId":2,"Type":"Pinger","Request":"Ping","Params":{}} [LOG] 70.82189 DEBUG juju rpc/jsoncodec: -> {"RequestId":2,"Response":{}} [LOG] 70.82258 INFO juju state/api: connection established [LOG] 70.82276 DEBUG juju rpc/jsoncodec: <- {"RequestId":1,"Type":"Admin","Request":"Login","Params":{"AuthTag":"user-admin","Password":"not the right password"}} [LOG] 70.82313 DEBUG juju rpc/jsoncodec: -> {"RequestId":1,"Error":"invalid entity name or password","ErrorCode":"unauthorized access","Response":{}} [LOG] 70.82362 INFO juju state/api: dialing "wss://127.0.0.1:50666/" [LOG] 70.82382 DEBUG juju rpc/jsoncodec: <- error: EOF (closing false) [LOG] 70.83293 INFO juju state/api: connection established [LOG] 70.83313 DEBUG juju rpc/jsoncodec: <- {"RequestId":1,"Type":"Admin","Request":"Login","Params":{"AuthTag":"user-admin","Password":"dummy-secret"}} [LOG] 70.83363 DEBUG juju rpc/jsoncodec: -> {"RequestId":1,"Response":{}} [LOG] 70.83639 DEBUG juju rpc/jsoncodec: <- error: EOF (closing false) [LOG] 70.83647 INFO juju environs/dummy: reset environment [LOG] 70.83667 DEBUG juju rpc/jsoncodec: <- error: EOF (closing true) ... Panic: Fixture has panicked (see related PANIC) OOPS: 13 passed, 1 FIXTURE-PANICKED, 6 MISSED --- FAIL: Test (92.75 seconds) FAIL FAIL launchpad.net/juju-core/agent 93.008s ok launchpad.net/juju-core/bzr 159.614s ok launchpad.net/juju-core/cert 3.691s ok launchpad.net/juju-core/charm 5.946s ? launchpad.net/juju-core/charm/hooks [no test files] ok launchpad.net/juju-core/cloudinit 0.009s ok launchpad.net/juju-core/cmd 0.230s ? 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] *** Test killed: ran too long. FAIL launchpad.net/juju-core/cmd/juju 600.013s ---------------------------------------------------------------------- FAIL: machine_test.go:146: MachineSuite.TestDyingMachine [LOG] 82.64281 INFO juju environs/testing: uploading FAKE tools 1.11.3-precise-amd64 [LOG] 82.64293 INFO juju environs: reading tools with major version 1 [LOG] 82.64296 DEBUG juju.environs.tools reading v1.* tools [LOG] 82.64297 INFO juju environs: falling back to public bucket [LOG] 82.64299 DEBUG juju.environs.tools reading v1.* tools [LOG] 82.64310 DEBUG juju.environs.tools found 1.11.3-precise-amd64 [LOG] 82.64312 INFO juju environs: filtering tools by series: precise [LOG] 82.64315 INFO juju environs: filtering tools by version: 1.11.3 [LOG] 82.64318 INFO juju environs/dummy: would pick tools from 1.11.3-precise-amd64 [LOG] 82.66927 INFO juju state: opening state; mongo addresses: ["localhost:36224"]; entity "" [LOG] 82.67308 INFO juju state: connection established [LOG] 85.35382 INFO juju state: initializing environment [LOG] 87.07701 INFO juju state/api: listening on "127.0.0.1:40743" [LOG] 87.10388 INFO juju state: opening state; mongo addresses: ["localhost:36224"]; entity "" [LOG] 87.10758 INFO juju state: connection established [LOG] 87.10819 INFO juju juju: authorization error while connecting to state server; retrying [LOG] 87.10825 INFO juju state: opening state; mongo addresses: ["localhost:36224"]; entity "" [LOG] 87.11177 INFO juju state: connection established [LOG] 87.17023 INFO juju state/api: dialing "wss://127.0.0.1:40743/" [LOG] 87.17503 INFO juju state/api: connection established [LOG] 87.17528 INFO juju rpc: discarding action method reflect.Method{Name:"apiRootForEntity", PkgPath:"launchpad.net/juju-core/state/apiserver", Type:(*reflect.commonType)(0x9b06b8), Func:reflect.Value{typ:(*reflect.commonType)(0x9b06b8), val:(unsafe.Pointer)(0x55038b), flag:0x131}, Index:1} [LOG] 87.17538 DEBUG juju rpc/jsoncodec: <- {"RequestId":1,"Type":"Admin","Request":"Login","Params":{"AuthTag":"user-admin","Password":"dummy-secret"}} [LOG] 87.17588 INFO juju rpc: discarding obtainer method reflect.Method{Name:"AuthClient", PkgPath:"", Type:(*reflect.commonType)(0x8a4488), Func:reflect.Value{typ:(*reflect.commonType)(0x8a4488), val:(unsafe.Pointer)(0x5517e1), flag:0x130}, Index:1} [LOG] 87.17591 INFO juju rpc: discarding obtainer method reflect.Method{Name:"AuthEnvironManager", PkgPath:"", Type:(*reflect.commonType)(0x8a4488), Func:reflect.Value{typ:(*reflect.commonType)(0x8a4488), val:(unsafe.Pointer)(0x55179b), flag:0x130}, Index:2} [LOG] 87.17594 INFO juju rpc: discarding obtainer method reflect.Method{Name:"AuthMachineAgent", PkgPath:"", Type:(*reflect.commonType)(0x8a4488), Func:reflect.Value{typ:(*reflect.commonType)(0x8a4488), val:(unsafe.Pointer)(0x5516be), flag:0x130}, Index:3} [LOG] 87.17596 INFO juju rpc: discarding obtainer method reflect.Method{Name:"AuthOwner", PkgPath:"", Type:(*reflect.commonType)(0x924960), Func:reflect.Value{typ:(*reflect.commonType)(0x924960), val:(unsafe.Pointer)(0x551705), flag:0x130}, Index:4} [LOG] 87.17604 INFO juju rpc: discarding obtainer method reflect.Method{Name:"GetAuthTag", PkgPath:"", Type:(*reflect.commonType)(0x8a4578), Func:reflect.Value{typ:(*reflect.commonType)(0x8a4578), val:(unsafe.Pointer)(0x55182a), flag:0x130}, Index:6} [LOG] 87.17607 INFO juju rpc: discarding obtainer method reflect.Method{Name:"Kill", PkgPath:"", Type:(*reflect.commonType)(0x8720a8), Func:reflect.Value{typ:(*reflect.commonType)(0x8720a8), val:(unsafe.Pointer)(0x550f1e), flag:0x130}, Index:7} [LOG] 87.17611 INFO juju rpc: discarding action method reflect.Method{Name:"getMachine", PkgPath:"launchpad.net/juju-core/state/apiserver/machine", Type:(*reflect.commonType)(0x9b1268), Func:reflect.Value{typ:(*reflect.commonType)(0x9b1268), val:(unsafe.Pointer)(0x6da540), flag:0x131}, Index:2} [LOG] 87.17613 INFO juju rpc: discarding action method reflect.Method{Name:"setPassword", PkgPath:"launchpad.net/juju-core/state/apiserver/common", Type:(*reflect.commonType)(0x9b12f0), Func:reflect.Value{typ:(*reflect.commonType)(0x9b12f0), val:(unsafe.Pointer)(0x6db6bd), flag:0x131}, Index:3} [LOG] 87.17622 INFO juju rpc: discarding action method reflect.Method{Name:"oneAgentTools", PkgPath:"launchpad.net/juju-core/state/apiserver/upgrader", Type:(*reflect.commonType)(0xa15bf0), Func:reflect.Value{typ:(*reflect.commonType)(0xa15bf0), val:(unsafe.Pointer)(0x6dbd46), flag:0x131}, Index:3} [LOG] 87.17625 INFO juju rpc: discarding obtainer method reflect.Method{Name:"requireAgent", PkgPath:"launchpad.net/juju-core/state/apiserver", Type:(*reflect.commonType)(0x8a4500), Func:reflect.Value{typ:(*reflect.commonType)(0x8a4500), val:(unsafe.Pointer)(0x550f4c), flag:0x131}, Index:14} [LOG] 87.17627 INFO juju rpc: discarding obtainer method reflect.Method{Name:"requireClient", PkgPath:"launchpad.net/juju-core/state/apiserver", Type:(*reflect.commonType)(0x8a4500), Func:reflect.Value{typ:(*reflect.commonType)(0x8a4500), val:(unsafe.Pointer)(0x550fba), flag:0x131}, Index:15} [LOG] 87.17630 DEBUG juju rpc/jsoncodec: -> {"RequestId":1,"Response":{}} [LOG] 87.17671 INFO juju.container.lxc lxcObjectFactory replaced with mock lxc factory [LOG] 87.18374 DEBUG juju rpc/jsoncodec: <- {"RequestId":2,"Type":"Pinger","Request":"Ping","Params":{}} [LOG] 87.18389 DEBUG juju rpc/jsoncodec: -> {"RequestId":2,"Response":{}} [LOG] 99.57145 INFO juju machine agent machine-0 start [LOG] 99.57728 DEBUG juju.jujud.validation found lxc, not installing [LOG] 99.57729 INFO juju Starting StateWorker for machine-0 [LOG] 99.57737 INFO juju worker: start "state" [LOG] 99.57740 INFO juju state: opening state; mongo addresses: ["localhost:36224"]; entity "machine-0" [LOG] 99.57779 INFO juju worker: start "api" [LOG] 99.57808 INFO juju state/api: dialing "wss://127.0.0.1:40743/" [LOG] 99.58639 INFO juju state/api: connection established [LOG] 99.58658 DEBUG juju rpc/jsoncodec: <- {"RequestId":1,"Type":"Admin","Request":"Login","Params":{"AuthTag":"machine-0","Password":"machine-password"}} [LOG] 99.58704 DEBUG juju rpc/jsoncodec: -> {"RequestId":1,"Response":{}} [LOG] 99.58843 DEBUG juju rpc/jsoncodec: <- {"RequestId":2,"Type":"MachineAgent","Request":"GetMachines","Params":{"Entities":[{"Tag":"machine-0"}]}} [LOG] 99.58853 DEBUG juju rpc/jsoncodec: <- {"RequestId":3,"Type":"Pinger","Request":"Ping","Params":{}} [LOG] 99.58878 DEBUG juju rpc/jsoncodec: -> {"RequestId":3,"Response":{}} [LOG] 99.58903 DEBUG juju rpc/jsoncodec: -> {"RequestId":2,"Response":{"Machines":[{"Life":"dying","Jobs":["JobHostUnits"],"Error":null}]}} [LOG] 99.59049 INFO juju state: connection established [LOG] 99.61081 INFO juju worker: start "upgrader" [LOG] 99.61094 INFO juju worker: start "machiner" [LOG] 99.61096 INFO juju worker: start "lxc-provisioner" [LOG] 99.61113 INFO juju worker: start "deployer" [LOG] 99.61548 INFO juju.provisioner Starting up provisioner task 0 [LOG] 99.61802 DEBUG juju.worker.machiner agent for machine "0" is now alive [LOG] 99.62495 INFO juju.worker.machiner machine "0" started [LOG] 99.62546 DEBUG juju.worker.machiner machine "0" is now dying [LOG] 99.63339 INFO juju.worker.machiner machine "0" shutting down [LOG] 99.63346 ERROR juju worker: fatal "machiner": agent should be terminated [LOG] 99.63347 DEBUG juju worker: killing "lxc-provisioner" [LOG] 99.63349 DEBUG juju worker: killing "upgrader" [LOG] 99.63350 DEBUG juju worker: killing "deployer" [LOG] 99.63352 INFO juju.provisioner Shutting down provisioner task 0 [LOG] 5.55549 INFO juju.deployer checking unit "tarmac/0" [LOG] 5.55642 INFO juju.deployer recalling unit "tarmac/0" machine_test.go:164: // 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] 5.84849 DEBUG juju worker: killing runner 0xf8405c50c0 [LOG] 5.84857 INFO juju worker: runner is dying [LOG] 5.84862 DEBUG juju worker: killing "api" [LOG] 5.84865 DEBUG juju worker: killing runner 0xf8401d12a0 [LOG] 5.84869 DEBUG juju worker: killing "state" [LOG] 5.84871 DEBUG juju worker: killing runner 0xf8401a7000 [LOG] 5.84875 INFO juju worker: runner is dying [LOG] 5.84879 INFO juju worker: runner is dying [LOG] 5.84919 DEBUG juju rpc/jsoncodec: <- error: EOF (closing false) [LOG] 18.12213 ERROR juju worker: fatal "deployer": exec ["stop" "jujud-machine-1:unit-tarmac-0"]: exit status 1 (stop: Rejected send message, 1 matched rules; type="method_call", sender=":1.5387" (uid=1001 pid=25335 comm="stop jujud-machine-1:unit-tarmac-0 ") interface="com.ubuntu.Upstart0_6.Job" member="Stop" error name="(unset)" requested_reply="0" destination="com.ubuntu.Upstart" (uid=0 pid=1 comm="/sbin/init")) [LOG] 18.12563 ERROR juju worker: fatal "state": agent should be terminated [LOG] 18.12584 INFO juju.container.lxc lxcObjectFactory replaced with &{} [LOG] 18.12853 DEBUG juju rpc/jsoncodec: <- error: EOF (closing false) [LOG] 18.12872 INFO juju environs/dummy: reset environment [jujuc whatever] [remote] [/path/to/remote] [remote --help] [unknown] [remote --error borken] [remote --unknown] [remote unwanted] ---------------------------------------------------------------------- FAIL: unit_test.go:117: UnitSuite.TestRunStop [LOG] 1.15617 INFO juju environs/testing: uploading FAKE tools 1.11.3-precise-amd64 [LOG] 1.15626 INFO juju environs: reading tools with major version 1 [LOG] 1.15628 DEBUG juju.environs.tools reading v1.* tools [LOG] 1.15630 INFO juju environs: falling back to public bucket [LOG] 1.15632 DEBUG juju.environs.tools reading v1.* tools [LOG] 1.15636 DEBUG juju.environs.tools found 1.11.3-precise-amd64 [LOG] 1.15638 INFO juju environs: filtering tools by series: precise [LOG] 1.15641 INFO juju environs: filtering tools by version: 1.11.3 [LOG] 1.15644 INFO juju environs/dummy: would pick tools from 1.11.3-precise-amd64 [LOG] 1.19128 INFO juju state: opening state; mongo addresses: ["localhost:36224"]; entity "" [LOG] 1.20246 INFO juju state: connection established [LOG] 4.57913 INFO juju state: initializing environment [LOG] 4.60094 INFO juju state/api: listening on "127.0.0.1:52332" [LOG] 4.63203 INFO juju state: opening state; mongo addresses: ["localhost:36224"]; entity "" [LOG] 4.63548 INFO juju state: connection established [LOG] 4.63607 INFO juju juju: authorization error while connecting to state server; retrying [LOG] 4.63613 INFO juju state: opening state; mongo addresses: ["localhost:36224"]; entity "" [LOG] 4.63916 INFO juju state: connection established [LOG] 4.69453 INFO juju state/api: dialing "wss://127.0.0.1:52332/" [LOG] 4.69929 INFO juju state/api: connection established [LOG] 4.69945 DEBUG juju rpc/jsoncodec: <- {"RequestId":1,"Type":"Admin","Request":"Login","Params":{"AuthTag":"user-admin","Password":"dummy-secret"}} [LOG] 4.69990 DEBUG juju rpc/jsoncodec: -> {"RequestId":1,"Response":{}} [LOG] 4.70452 DEBUG juju rpc/jsoncodec: <- {"RequestId":2,"Type":"Pinger","Request":"Ping","Params":{}} [LOG] 4.70461 DEBUG juju rpc/jsoncodec: -> {"RequestId":2,"Response":{}} [LOG] 4.70738 INFO juju writing charm to storage [838 bytes] [LOG] 4.70746 INFO juju adding charm to state [LOG] 6.95703 INFO juju.jujud unit agent unit-wordpress-0 start [LOG] 6.95707 INFO juju worker: start "toplevel" [LOG] 6.95711 INFO juju state: opening state; mongo addresses: ["localhost:36224"]; entity "unit-wordpress-0" [LOG] 6.96044 INFO juju state: connection established [LOG] 6.98117 INFO juju worker: start "upgrader" [LOG] 6.98126 INFO juju worker: start "uniter" [LOG] 6.98364 INFO juju worker/uniter: unit "wordpress/0" started [LOG] 6.98836 INFO juju worker/uniter: ModeInit starting [LOG] 6.98838 INFO juju worker/uniter: updating unit addresses [LOG] 6.98867 DEBUG juju worker/uniter/filter: charm check skipped, not yet installed. [LOG] 6.99029 DEBUG juju worker/uniter/filter: got unit change [LOG] 6.99173 DEBUG juju worker/uniter/filter: got relations change [LOG] 6.99175 DEBUG juju worker/uniter/filter: got service change [LOG] 6.99269 DEBUG juju worker/uniter/filter: charm check skipped, not yet installed. [LOG] 6.99871 INFO juju reconciling relation state [LOG] 6.99884 DEBUG juju worker/uniter: ModeInit exiting [LOG] 6.99886 INFO juju worker/uniter: ModeContinue starting [LOG] 6.99887 INFO juju loading uniter state [LOG] 6.99889 INFO juju worker/uniter: charm is not deployed [LOG] 6.99892 DEBUG juju worker/uniter: ModeContinue exiting [LOG] 6.99893 INFO juju worker/uniter: ModeInstalling local:series/wordpress-3 starting [LOG] 6.99895 INFO juju worker/uniter: fetching charm "local:series/wordpress-3" [LOG] 6.99961 INFO juju worker/uniter/charm: downloading local:series/wordpress-3 from http://127.0.0.1:34942/dummyenv/private/local_3a_series_2f_wordpress-3 [LOG] 6.99997 INFO juju worker/uniter/charm: download complete [LOG] 7.00003 INFO juju worker/uniter/charm: download verified waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... waiting... unit_test.go:122: waitForUnitStarted(s.State, unit, c) unit_test.go:94: c.Fatalf("no activity detected") ... Error: no activity detected [LOG] 11.96152 DEBUG juju worker: killing runner 0xf840626f00 [LOG] 11.96168 INFO juju worker: runner is dying [LOG] 11.96178 DEBUG juju worker: killing "toplevel" [LOG] 11.96181 DEBUG juju worker: killing runner 0xf8402d0780 [LOG] 11.96195 INFO juju worker: runner is dying [LOG] 11.96198 DEBUG juju worker: killing "uniter" [LOG] 11.96209 DEBUG juju worker: killing "upgrader" [LOG] 11.97602 DEBUG juju worker/uniter/filter: got unit change [LOG] 44.08078 DEBUG juju worker/uniter/filter: changing charm to "local:series/wordpress-3" [LOG] 44.09229 DEBUG juju worker/uniter/filter: no new charm event [LOG] 44.09304 INFO juju worker/uniter: deploying charm "local:series/wordpress-3" [LOG] 44.09346 DEBUG juju worker/uniter/filter: got config change [LOG] 44.09347 DEBUG juju worker/uniter/filter: preparing new config event [LOG] 44.09348 DEBUG juju worker/uniter/filter: got relations change [LOG] 46.98001 DEBUG juju worker/uniter/filter: got unit change [LOG] 52.27859 INFO juju worker/uniter/charm: preparing new charm deployment [LOG] 64.70641 DEBUG juju rpc/jsoncodec: <- {"RequestId":3,"Type":"Pinger","Request":"Ping","Params":{}} [LOG] 64.70733 DEBUG juju rpc/jsoncodec: -> {"RequestId":3,"Response":{}} [LOG] 87.25277 INFO juju worker/uniter/charm: deploying charm [LOG] 87.26992 INFO juju worker/uniter/charm: charm deployment succeeded [LOG] 93.61827 INFO juju worker/uniter: charm "local:series/wordpress-3" is deployed [LOG] 4.02083 DEBUG juju worker/uniter: ModeInstalling local:series/wordpress-3 exiting [LOG] 4.02091 INFO juju worker/uniter: unit "wordpress/0" shutting down: tomb: dying [LOG] 4.02129 ERROR juju worker/uniter/filter: tomb: dying [LOG] 4.02862 DEBUG juju rpc/jsoncodec: <- error: EOF (closing false) [LOG] 4.02876 INFO juju environs/dummy: reset environment ---------------------------------------------------------------------- FAIL: upgradevalidation_test.go:113: UpgradeValidationMachineSuite.TestAgentEnsuresAPIInfo [LOG] 45.91773 INFO juju environs/testing: uploading FAKE tools 1.11.3-precise-amd64 [LOG] 45.91783 INFO juju environs: reading tools with major version 1 [LOG] 45.91785 DEBUG juju.environs.tools reading v1.* tools [LOG] 45.91787 INFO juju environs: falling back to public bucket [LOG] 45.91789 DEBUG juju.environs.tools reading v1.* tools [LOG] 45.91794 DEBUG juju.environs.tools found 1.11.3-precise-amd64 [LOG] 45.91796 INFO juju environs: filtering tools by series: precise [LOG] 45.91798 INFO juju environs: filtering tools by version: 1.11.3 [LOG] 45.91801 INFO juju environs/dummy: would pick tools from 1.11.3-precise-amd64 [LOG] 45.95105 INFO juju state: opening state; mongo addresses: ["localhost:36224"]; entity "" [LOG] 45.95458 INFO juju state: connection established [LOG] 48.37565 INFO juju state: initializing environment [LOG] 48.39629 INFO juju state/api: listening on "127.0.0.1:49159" [LOG] 48.41902 INFO juju state: opening state; mongo addresses: ["localhost:36224"]; entity "" [LOG] 48.44168 INFO juju state: connection established [LOG] 48.44223 INFO juju juju: authorization error while connecting to state server; retrying [LOG] 48.44229 INFO juju state: opening state; mongo addresses: ["localhost:36224"]; entity "" [LOG] 48.44547 INFO juju state: connection established [LOG] 48.49541 INFO juju state/api: dialing "wss://127.0.0.1:49159/" [LOG] 48.49992 INFO juju state/api: connection established [LOG] 48.50012 DEBUG juju rpc/jsoncodec: <- {"RequestId":1,"Type":"Admin","Request":"Login","Params":{"AuthTag":"user-admin","Password":"dummy-secret"}} [LOG] 48.50057 DEBUG juju rpc/jsoncodec: -> {"RequestId":1,"Response":{}} [LOG] 48.50099 INFO juju.container.lxc lxcObjectFactory replaced with mock lxc factory [LOG] 48.50694 DEBUG juju rpc/jsoncodec: <- {"RequestId":2,"Type":"Pinger","Request":"Ping","Params":{}} [LOG] 48.50705 DEBUG juju rpc/jsoncodec: -> {"RequestId":2,"Response":{}} [LOG] 49.96050 INFO juju machine agent machine-0 start [LOG] 49.96595 DEBUG juju.jujud.validation found lxc, not installing [LOG] 49.96597 INFO juju Starting StateWorker for machine-0 [LOG] 49.96600 INFO juju worker: start "state" [LOG] 49.96602 INFO juju state: opening state; mongo addresses: ["localhost:36224"]; entity "machine-0" [LOG] 49.96647 INFO juju worker: start "api" [LOG] 49.96677 INFO juju state/api: dialing "wss://127.0.0.1:49159/" upgradevalidation_test.go:134: c.Fatalf("state not opened") ... Error: state not opened [LOG] 51.37404 INFO juju.container.lxc lxcObjectFactory replaced with &{} [LOG] 51.37989 INFO juju environs/dummy: reset environment [LOG] 51.38007 DEBUG juju rpc/jsoncodec: <- error: read tcp 127.0.0.1:48485: use of closed network connection (closing true) [LOG] 51.38596 INFO juju state: connection established [LOG] 51.39106 INFO juju state/api: connection established [LOG] 51.39124 ERROR juju worker: exited "api": connection is shut down [LOG] 51.39126 INFO juju worker: restarting "api" in 3s [LOG] 54.39185 INFO juju worker: start "api" [LOG] 54.39283 INFO juju state/api: dialing "wss://127.0.0.1:49159/" [LOG] 54.39328 ERROR juju state/api: websocket.Dial wss://127.0.0.1:49159/: dial tcp 127.0.0.1:49159: connection refused [LOG] 54.39344 ERROR juju worker: exited "api": websocket.Dial wss://127.0.0.1:49159/: dial tcp 127.0.0.1:49159: connection refused [LOG] 54.39349 INFO juju worker: restarting "api" in 3s [LOG] 57.39394 INFO juju worker: start "api" [LOG] 57.39515 INFO juju state/api: dialing "wss://127.0.0.1:49159/" [LOG] 57.39556 ERROR juju state/api: websocket.Dial wss://127.0.0.1:49159/: dial tcp 127.0.0.1:49159: connection refused [LOG] 57.39565 ERROR juju worker: exited "api": websocket.Dial wss://127.0.0.1:49159/: dial tcp 127.0.0.1:49159: connection refused [LOG] 57.39570 INFO juju worker: restarting "api" in 3s [LOG] 58.37615 ERROR juju worker: fatal "state": agent should be terminated ---------------------------------------------------------------------- FAIL: upgradevalidation_test.go:142: UpgradeValidationMachineSuite.TestAgentEnsuresAPIInfoOnWorkers [LOG] 58.61435 INFO juju environs/testing: uploading FAKE tools 1.11.3-precise-amd64 [LOG] 58.61444 INFO juju environs: reading tools with major version 1 [LOG] 58.61446 DEBUG juju.environs.tools reading v1.* tools [LOG] 58.61447 INFO juju environs: falling back to public bucket [LOG] 58.61449 DEBUG juju.environs.tools reading v1.* tools [LOG] 58.61454 DEBUG juju.environs.tools found 1.11.3-precise-amd64 [LOG] 58.61456 INFO juju environs: filtering tools by series: precise [LOG] 58.61458 INFO juju environs: filtering tools by version: 1.11.3 [LOG] 58.61461 INFO juju environs/dummy: would pick tools from 1.11.3-precise-amd64 [LOG] 58.63718 INFO juju state: opening state; mongo addresses: ["localhost:36224"]; entity "" [LOG] 58.64059 INFO juju state: connection established [LOG] 58.66230 INFO juju state: initializing environment [LOG] 58.68285 INFO juju state/api: listening on "127.0.0.1:39515" [LOG] 58.71570 INFO juju state: opening state; mongo addresses: ["localhost:36224"]; entity "" [LOG] 58.71911 INFO juju state: connection established [LOG] 58.71962 INFO juju juju: authorization error while connecting to state server; retrying [LOG] 58.71968 INFO juju state: opening state; mongo addresses: ["localhost:36224"]; entity "" [LOG] 58.72270 INFO juju state: connection established [LOG] 58.78514 INFO juju state/api: dialing "wss://127.0.0.1:39515/" [LOG] 58.78967 INFO juju state/api: connection established [LOG] 58.78987 DEBUG juju rpc/jsoncodec: <- {"RequestId":1,"Type":"Admin","Request":"Login","Params":{"AuthTag":"user-admin","Password":"dummy-secret"}} [LOG] 58.79039 DEBUG juju rpc/jsoncodec: -> {"RequestId":1,"Response":{}} [LOG] 58.79075 INFO juju.container.lxc lxcObjectFactory replaced with mock lxc factory [LOG] 58.79104 DEBUG juju rpc/jsoncodec: <- {"RequestId":2,"Type":"Pinger","Request":"Ping","Params":{}} [LOG] 58.79115 DEBUG juju rpc/jsoncodec: -> {"RequestId":2,"Response":{}} [LOG] 60.39612 INFO juju worker: start "api" [LOG] 60.39713 INFO juju state/api: dialing "wss://127.0.0.1:49159/" [LOG] 60.39754 ERROR juju state/api: websocket.Dial wss://127.0.0.1:49159/: dial tcp 127.0.0.1:49159: connection refused [LOG] 60.39761 INFO juju worker: ignoring start request for "state" when dying [LOG] 60.39767 ERROR juju worker: exited "api": websocket.Dial wss://127.0.0.1:49159/: dial tcp 127.0.0.1:49159: connection refused [LOG] 60.48984 INFO juju machine agent machine-1 start [LOG] 60.49534 DEBUG juju.jujud.validation found lxc, not installing [LOG] 60.49537 INFO juju worker: start "api" [LOG] 60.49571 INFO juju state/api: dialing "wss://127.0.0.1:39515/" [LOG] 60.50020 INFO juju state/api: connection established [LOG] 60.50041 DEBUG juju rpc/jsoncodec: <- {"RequestId":1,"Type":"Admin","Request":"Login","Params":{"AuthTag":"machine-1","Password":""}} [LOG] 60.50102 DEBUG juju rpc/jsoncodec: -> {"RequestId":1,"Error":"invalid entity name or password","ErrorCode":"unauthorized access","Response":{}} [LOG] 60.50127 ERROR juju worker: exited "api": invalid entity name or password [LOG] 60.50129 INFO juju worker: start "state" [LOG] 60.50132 INFO juju state: opening state; mongo addresses: ["localhost:36224"]; entity "machine-1" [LOG] 60.50165 INFO juju worker: restarting "api" in 3s [LOG] 60.50224 DEBUG juju rpc/jsoncodec: <- error: EOF (closing false) [LOG] 60.50460 INFO juju state: connection established [LOG] 60.52500 INFO juju.jujud.validation agent.conf APIInfo password is not set. Setting to state password upgradevalidation_test.go:164: c.Fatalf("state not opened") ... Error: state not opened [LOG] 60.99876 INFO juju.container.lxc lxcObjectFactory replaced with &{} [LOG] 61.00038 INFO juju environs/dummy: reset environment [LOG] 61.00093 DEBUG juju rpc/jsoncodec: <- error: EOF (closing true) [LOG] 61.72312 INFO juju worker: start "upgrader" [LOG] 61.72336 INFO juju worker: start "machiner" [LOG] 61.72343 INFO juju worker: start "lxc-provisioner" [LOG] 61.72457 INFO juju worker: start "deployer" [LOG] 61.72701 ERROR juju worker: fatal "machiner": agent should be terminated [LOG] 61.72705 DEBUG juju worker: killing "upgrader" [LOG] 61.72709 DEBUG juju worker: killing "lxc-provisioner" [LOG] 61.72713 DEBUG juju worker: killing "deployer" [LOG] 61.72719 ERROR juju worker: fatal "deployer": machine 1 not found [LOG] 63.34966 ERROR juju worker: fatal "upgrader": cannot set agent tools for machine 1: not found or dead [LOG] 63.34986 ERROR juju worker: fatal "state": agent should be terminated [LOG] 63.50205 INFO juju worker: start "api" [LOG] 63.50316 INFO juju state/api: dialing "wss://127.0.0.1:39515/" [LOG] 63.50356 ERROR juju state/api: websocket.Dial wss://127.0.0.1:39515/: dial tcp 127.0.0.1:39515: connection refused [LOG] 63.50363 INFO juju worker: ignoring start request for "state" when dying [LOG] 63.50368 ERROR juju worker: exited "api": websocket.Dial wss://127.0.0.1:39515/: dial tcp 127.0.0.1:39515: connection refused *** Test killed: ran too long. FAIL launchpad.net/juju-core/cmd/jujud 600.019s ok launchpad.net/juju-core/constraints 0.014s ok launchpad.net/juju-core/container/lxc 0.345s ? launchpad.net/juju-core/container/lxc/mock [no test files] ok launchpad.net/juju-core/downloader 5.334s ok launchpad.net/juju-core/environs 4.512s ? launchpad.net/juju-core/environs/all [no test files] ok launchpad.net/juju-core/environs/azure 11.180s ok launchpad.net/juju-core/environs/cloudinit 0.538s ok launchpad.net/juju-core/environs/config 1.049s ok launchpad.net/juju-core/environs/dummy 98.417s ok launchpad.net/juju-core/environs/ec2 179.179s ok launchpad.net/juju-core/environs/imagemetadata 0.254s ok launchpad.net/juju-core/environs/instances 0.234s ok launchpad.net/juju-core/environs/jujutest 0.315s ok launchpad.net/juju-core/environs/local 1.767s ? launchpad.net/juju-core/environs/local/storage [no test files] ok launchpad.net/juju-core/environs/localstorage 0.279s ok launchpad.net/juju-core/environs/maas 8.837s ok launchpad.net/juju-core/environs/openstack 45.292s ? launchpad.net/juju-core/environs/provider [no test files] ok launchpad.net/juju-core/environs/testing 0.005s ok launchpad.net/juju-core/environs/tools 61.582s ? launchpad.net/juju-core/errors [no test files] ok launchpad.net/juju-core/instance 0.009s ok launchpad.net/juju-core/juju 112.258s ? launchpad.net/juju-core/juju/testing [no test files] ok launchpad.net/juju-core/log 0.008s ok launchpad.net/juju-core/log/syslog 8.606s ok launchpad.net/juju-core/rpc 0.315s ok launchpad.net/juju-core/rpc/jsoncodec 0.230s ok launchpad.net/juju-core/schema 0.011s *** Test killed: ran too long. FAIL launchpad.net/juju-core/state 600.014s ? 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/machineagent 5.560s ok launchpad.net/juju-core/state/api/machiner 27.031s ok launchpad.net/juju-core/state/api/params 0.022s ok launchpad.net/juju-core/state/api/upgrader 177.025s ok launchpad.net/juju-core/state/api/watcher 2.363s ok launchpad.net/juju-core/state/apiserver 11.807s ok launchpad.net/juju-core/state/apiserver/client 134.483s ok launchpad.net/juju-core/state/apiserver/common 0.020s ok launchpad.net/juju-core/state/apiserver/deployer 31.888s ok launchpad.net/juju-core/state/apiserver/machine 18.040s ? launchpad.net/juju-core/state/apiserver/testing [no test files] ok launchpad.net/juju-core/state/apiserver/upgrader 16.705s ok launchpad.net/juju-core/state/multiwatcher 1.184s ok launchpad.net/juju-core/state/presence 65.714s ok launchpad.net/juju-core/state/statecmd 151.040s ? launchpad.net/juju-core/state/testing [no test files] ok launchpad.net/juju-core/state/watcher 149.760s ok launchpad.net/juju-core/store 569.474s ok launchpad.net/juju-core/testing 11.106s ok launchpad.net/juju-core/testing/checkers 0.009s ok launchpad.net/juju-core/thirdparty/pbkdf2 0.226s ok launchpad.net/juju-core/upstart 16.202s ok launchpad.net/juju-core/utils 0.888s ok launchpad.net/juju-core/utils/fslock 1.171s ok launchpad.net/juju-core/utils/set 0.008s ok launchpad.net/juju-core/version 5.937s ok launchpad.net/juju-core/worker 11.708s ok launchpad.net/juju-core/worker/cleaner 10.734s ---------------------------------------------------------------------- FAIL: deployer_test.go:44: DeployerSuite.TestDeployRecallRemovePrincipals [LOG] 52.98720 INFO juju environs/testing: uploading FAKE tools 1.11.3-precise-amd64 [LOG] 52.98730 INFO juju environs: reading tools with major version 1 [LOG] 52.98732 DEBUG juju.environs.tools reading v1.* tools [LOG] 52.98734 INFO juju environs: falling back to public bucket [LOG] 52.98735 DEBUG juju.environs.tools reading v1.* tools [LOG] 52.98740 DEBUG juju.environs.tools found 1.11.3-precise-amd64 [LOG] 52.98743 INFO juju environs: filtering tools by series: precise [LOG] 52.98745 INFO juju environs: filtering tools by version: 1.11.3 [LOG] 52.98748 INFO juju environs/dummy: would pick tools from 1.11.3-precise-amd64 [LOG] 53.01599 INFO juju state: opening state; mongo addresses: ["localhost:43597"]; entity "" [LOG] 53.01971 INFO juju state: connection established [LOG] 55.02936 INFO juju state: initializing environment [LOG] 57.03721 INFO juju state/api: listening on "127.0.0.1:59955" [LOG] 57.06344 INFO juju state: opening state; mongo addresses: ["localhost:43597"]; entity "" [LOG] 57.06683 INFO juju state: connection established [LOG] 57.06734 INFO juju juju: authorization error while connecting to state server; retrying [LOG] 57.06739 INFO juju state: opening state; mongo addresses: ["localhost:43597"]; entity "" [LOG] 57.07047 INFO juju state: connection established [LOG] 57.12306 INFO juju state/api: dialing "wss://127.0.0.1:59955/" [LOG] 57.12757 INFO juju rpc: discarding action method reflect.Method{Name:"apiRootForEntity", PkgPath:"launchpad.net/juju-core/state/apiserver", Type:(*reflect.commonType)(0x800840), Func:reflect.Value{typ:(*reflect.commonType)(0x800840), val:(unsafe.Pointer)(0x6ab63c), flag:0x131}, Index:1} [LOG] 57.12767 INFO juju state/api: connection established [LOG] 57.12789 DEBUG juju rpc/jsoncodec: <- {"RequestId":1,"Type":"Admin","Request":"Login","Params":{"AuthTag":"user-admin","Password":"dummy-secret"}} [LOG] 57.12844 INFO juju rpc: discarding obtainer method reflect.Method{Name:"AuthClient", PkgPath:"", Type:(*reflect.commonType)(0x7468a8), Func:reflect.Value{typ:(*reflect.commonType)(0x7468a8), val:(unsafe.Pointer)(0x6aca92), flag:0x130}, Index:1} [LOG] 57.12847 INFO juju rpc: discarding obtainer method reflect.Method{Name:"AuthEnvironManager", PkgPath:"", Type:(*reflect.commonType)(0x7468a8), Func:reflect.Value{typ:(*reflect.commonType)(0x7468a8), val:(unsafe.Pointer)(0x6aca4c), flag:0x130}, Index:2} [LOG] 57.12850 INFO juju rpc: discarding obtainer method reflect.Method{Name:"AuthMachineAgent", PkgPath:"", Type:(*reflect.commonType)(0x7468a8), Func:reflect.Value{typ:(*reflect.commonType)(0x7468a8), val:(unsafe.Pointer)(0x6ac96f), flag:0x130}, Index:3} [LOG] 57.12854 INFO juju rpc: discarding obtainer method reflect.Method{Name:"AuthOwner", PkgPath:"", Type:(*reflect.commonType)(0x7a2af0), Func:reflect.Value{typ:(*reflect.commonType)(0x7a2af0), val:(unsafe.Pointer)(0x6ac9b6), flag:0x130}, Index:4} [LOG] 57.12861 INFO juju rpc: discarding obtainer method reflect.Method{Name:"GetAuthTag", PkgPath:"", Type:(*reflect.commonType)(0x746998), Func:reflect.Value{typ:(*reflect.commonType)(0x746998), val:(unsafe.Pointer)(0x6acadb), flag:0x130}, Index:6} [LOG] 57.12864 INFO juju rpc: discarding obtainer method reflect.Method{Name:"Kill", PkgPath:"", Type:(*reflect.commonType)(0x723ac8), Func:reflect.Value{typ:(*reflect.commonType)(0x723ac8), val:(unsafe.Pointer)(0x6ac1cf), flag:0x130}, Index:7} [LOG] 57.12868 INFO juju rpc: discarding action method reflect.Method{Name:"getMachine", PkgPath:"launchpad.net/juju-core/state/apiserver/machine", Type:(*reflect.commonType)(0x8013f0), Func:reflect.Value{typ:(*reflect.commonType)(0x8013f0), val:(unsafe.Pointer)(0x6fa65e), flag:0x131}, Index:2} [LOG] 57.12871 INFO juju rpc: discarding action method reflect.Method{Name:"setPassword", PkgPath:"launchpad.net/juju-core/state/apiserver/common", Type:(*reflect.commonType)(0x801478), Func:reflect.Value{typ:(*reflect.commonType)(0x801478), val:(unsafe.Pointer)(0x6fb7db), flag:0x131}, Index:3} [LOG] 57.12877 INFO juju rpc: discarding action method reflect.Method{Name:"oneAgentTools", PkgPath:"launchpad.net/juju-core/state/apiserver/upgrader", Type:(*reflect.commonType)(0x846838), Func:reflect.Value{typ:(*reflect.commonType)(0x846838), val:(unsafe.Pointer)(0x6fbe64), flag:0x131}, Index:3} [LOG] 57.12880 INFO juju rpc: discarding obtainer method reflect.Method{Name:"requireAgent", PkgPath:"launchpad.net/juju-core/state/apiserver", Type:(*reflect.commonType)(0x746920), Func:reflect.Value{typ:(*reflect.commonType)(0x746920), val:(unsafe.Pointer)(0x6ac1fd), flag:0x131}, Index:14} [LOG] 57.12882 INFO juju rpc: discarding obtainer method reflect.Method{Name:"requireClient", PkgPath:"launchpad.net/juju-core/state/apiserver", Type:(*reflect.commonType)(0x746920), Func:reflect.Value{typ:(*reflect.commonType)(0x746920), val:(unsafe.Pointer)(0x6ac26b), flag:0x131}, Index:15} [LOG] 57.12886 DEBUG juju rpc/jsoncodec: -> {"RequestId":1,"Response":{}} [LOG] 57.12932 DEBUG juju rpc/jsoncodec: <- {"RequestId":2,"Type":"Pinger","Request":"Ping","Params":{}} [LOG] 57.12942 DEBUG juju rpc/jsoncodec: -> {"RequestId":2,"Response":{}} [LOG] 57.16045 INFO juju writing charm to storage [838 bytes] [LOG] 57.16055 INFO juju adding charm to state [LOG] 57.20692 INFO juju.deployer checking unit "wordpress/0" [LOG] 57.20718 INFO juju.deployer deploying unit "wordpress/0" deployer_test.go:65: s.waitFor(c, isDeployed(ctx, u0.Name())) deployer_test.go:233: c.Fatalf("timeout") ... Error: timeout [LOG] 59.52719 WARNING juju.deployer installer: cannot restart syslog daemon: must be root [LOG] 59.55081 DEBUG juju rpc/jsoncodec: <- error: EOF (closing false) [LOG] 59.55100 INFO juju environs/dummy: reset environment ---------------------------------------------------------------------- FAIL: deployer_test.go:159: DeployerSuite.TestDeployRecallRemoveSubordinates [LOG] 59.63526 INFO juju environs/testing: uploading FAKE tools 1.11.3-precise-amd64 [LOG] 59.63535 INFO juju environs: reading tools with major version 1 [LOG] 59.63537 DEBUG juju.environs.tools reading v1.* tools [LOG] 59.63539 INFO juju environs: falling back to public bucket [LOG] 59.63540 DEBUG juju.environs.tools reading v1.* tools [LOG] 59.63545 DEBUG juju.environs.tools found 1.11.3-precise-amd64 [LOG] 59.63547 INFO juju environs: filtering tools by series: precise [LOG] 59.63549 INFO juju environs: filtering tools by version: 1.11.3 [LOG] 59.63552 INFO juju environs/dummy: would pick tools from 1.11.3-precise-amd64 [LOG] 59.66453 INFO juju state: opening state; mongo addresses: ["localhost:43597"]; entity "" [LOG] 59.66790 INFO juju state: connection established [LOG] 61.33665 INFO juju state: initializing environment [LOG] 61.35747 INFO juju state/api: listening on "127.0.0.1:53351" [LOG] 61.38556 INFO juju state: opening state; mongo addresses: ["localhost:43597"]; entity "" [LOG] 61.39001 INFO juju state: connection established [LOG] 61.39059 INFO juju juju: authorization error while connecting to state server; retrying [LOG] 61.39068 INFO juju state: opening state; mongo addresses: ["localhost:43597"]; entity "" [LOG] 61.39386 INFO juju state: connection established [LOG] 61.44875 INFO juju state/api: dialing "wss://127.0.0.1:53351/" [LOG] 61.45302 INFO juju state/api: connection established [LOG] 61.45320 DEBUG juju rpc/jsoncodec: <- {"RequestId":1,"Type":"Admin","Request":"Login","Params":{"AuthTag":"user-admin","Password":"dummy-secret"}} [LOG] 61.45363 DEBUG juju rpc/jsoncodec: -> {"RequestId":1,"Response":{}} [LOG] 61.45461 DEBUG juju rpc/jsoncodec: <- {"RequestId":2,"Type":"Pinger","Request":"Ping","Params":{}} [LOG] 61.45475 DEBUG juju rpc/jsoncodec: -> {"RequestId":2,"Response":{}} [LOG] 61.47901 INFO juju writing charm to storage [838 bytes] [LOG] 61.47909 INFO juju adding charm to state [LOG] 61.51594 INFO juju writing charm to storage [637 bytes] [LOG] 61.51602 INFO juju adding charm to state [LOG] 61.56113 INFO juju.deployer checking unit "wordpress/0" [LOG] 61.56159 INFO juju.deployer deploying unit "wordpress/0" [LOG] 63.15336 WARNING juju.deployer installer: cannot restart syslog daemon: must be root [LOG] 63.19831 INFO juju.deployer checking unit "subsvc0/0" [LOG] 63.19894 INFO juju.deployer deploying unit "subsvc0/0" [LOG] 63.20409 WARNING juju.deployer installer: cannot restart syslog daemon: must be root [LOG] 63.28535 INFO juju.deployer checking unit "subsvc1/0" [LOG] 63.28597 INFO juju.deployer deploying unit "subsvc1/0" [LOG] 63.29126 WARNING juju.deployer installer: cannot restart syslog daemon: must be root [LOG] 63.34998 INFO juju.deployer checking unit "subsvc1/0" [LOG] 63.35621 INFO juju.deployer checking unit "subsvc0/0" [LOG] 63.35687 INFO juju.deployer recalling unit "subsvc0/0" deployer_test.go:193: s.waitFor(c, isDeployed(ctx, u.Name(), sub1.Name())) deployer_test.go:233: c.Fatalf("timeout") ... Error: timeout [LOG] 63.97375 WARNING juju.deployer installer: cannot restart syslog daemon: must be root [LOG] 63.97389 INFO juju.deployer removing unit "subsvc0/0" [LOG] 63.99586 DEBUG juju rpc/jsoncodec: <- error: EOF (closing false) [LOG] 63.99599 INFO juju environs/dummy: reset environment ---------------------------------------------------------------------- FAIL: deployer_test.go:197: DeployerSuite.TestNonAliveSubordinates [LOG] 64.08050 INFO juju environs/testing: uploading FAKE tools 1.11.3-precise-amd64 [LOG] 64.08059 INFO juju environs: reading tools with major version 1 [LOG] 64.08061 DEBUG juju.environs.tools reading v1.* tools [LOG] 64.08063 INFO juju environs: falling back to public bucket [LOG] 64.08065 DEBUG juju.environs.tools reading v1.* tools [LOG] 64.08069 DEBUG juju.environs.tools found 1.11.3-precise-amd64 [LOG] 64.08071 INFO juju environs: filtering tools by series: precise [LOG] 64.08074 INFO juju environs: filtering tools by version: 1.11.3 [LOG] 64.08076 INFO juju environs/dummy: would pick tools from 1.11.3-precise-amd64 [LOG] 64.10520 INFO juju state: opening state; mongo addresses: ["localhost:43597"]; entity "" [LOG] 64.10873 INFO juju state: connection established [LOG] 65.03371 INFO juju state: initializing environment [LOG] 65.05364 INFO juju state/api: listening on "127.0.0.1:34862" [LOG] 65.08325 INFO juju state: opening state; mongo addresses: ["localhost:43597"]; entity "" [LOG] 65.08656 INFO juju state: connection established [LOG] 65.08709 INFO juju juju: authorization error while connecting to state server; retrying [LOG] 65.08715 INFO juju state: opening state; mongo addresses: ["localhost:43597"]; entity "" [LOG] 65.09028 INFO juju state: connection established [LOG] 65.14735 INFO juju state/api: dialing "wss://127.0.0.1:34862/" [LOG] 65.15184 INFO juju state/api: connection established [LOG] 65.15202 DEBUG juju rpc/jsoncodec: <- {"RequestId":1,"Type":"Admin","Request":"Login","Params":{"AuthTag":"user-admin","Password":"dummy-secret"}} [LOG] 65.15245 DEBUG juju rpc/jsoncodec: -> {"RequestId":1,"Response":{}} [LOG] 65.15336 DEBUG juju rpc/jsoncodec: <- {"RequestId":2,"Type":"Pinger","Request":"Ping","Params":{}} [LOG] 65.15349 DEBUG juju rpc/jsoncodec: -> {"RequestId":2,"Response":{}} [LOG] 65.17725 INFO juju writing charm to storage [838 bytes] [LOG] 65.17732 INFO juju adding charm to state [LOG] 65.21613 INFO juju writing charm to storage [637 bytes] [LOG] 65.21621 INFO juju adding charm to state [LOG] 65.31841 INFO juju.deployer checking unit "wordpress/0" [LOG] 65.31869 INFO juju.deployer deploying unit "wordpress/0" [LOG] 67.39650 WARNING juju.deployer installer: cannot restart syslog daemon: must be root deployer_test.go:220: s.waitFor(c, isRemoved(s.State, sub0.Name())) deployer_test.go:233: c.Fatalf("timeout") ... Error: timeout [LOG] 67.41955 INFO juju.deployer checking unit "subsvc0/0" [LOG] 67.42076 INFO juju.deployer removing unit "subsvc0/0" [LOG] 67.43599 INFO juju.deployer checking unit "subsvc1/0" [LOG] 67.43655 INFO juju.deployer removing unit "subsvc1/0" [LOG] 67.45489 DEBUG juju rpc/jsoncodec: <- error: EOF (closing false) [LOG] 67.45502 INFO juju environs/dummy: reset environment 2013-07-17 10:14:52 WARNING juju.deployer simple.go:130 installer: cannot restart syslog daemon: must be root 2013-07-17 10:14:52 WARNING juju.deployer simple.go:189 installer: cannot restart syslog daemon: must be root 2013-07-17 10:14:52 WARNING juju.deployer simple.go:130 installer: cannot restart syslog daemon: must be root 2013-07-17 10:14:52 WARNING juju.deployer simple.go:130 installer: cannot restart syslog daemon: must be root 2013-07-17 10:14:53 WARNING juju.deployer simple.go:189 installer: cannot restart syslog daemon: must be root 2013-07-17 10:14:53 WARNING juju.deployer simple.go:189 installer: cannot restart syslog daemon: must be root 2013-07-17 10:14:53 WARNING juju.deployer simple.go:189 installer: cannot restart syslog daemon: must be root 2013-07-17 10:14:53 WARNING juju.deployer simple.go:189 installer: cannot restart syslog daemon: must be root OOPS: 3 passed, 3 FAILED --- FAIL: TestPackage (37.95 seconds) FAIL FAIL launchpad.net/juju-core/worker/deployer 38.169s ok launchpad.net/juju-core/worker/firewaller 118.033s ok launchpad.net/juju-core/worker/machiner 53.210s ---------------------------------------------------------------------- FAIL: provisioner_test.go:328: ProvisionerSuite.TestProvisionerSetsErrorStatusWhenStartInstanceFailed [LOG] 21.72250 INFO juju environs/testing: uploading FAKE tools 1.11.3-precise-amd64 [LOG] 21.72257 INFO juju environs: reading tools with major version 1 [LOG] 21.72259 DEBUG juju.environs.tools reading v1.* tools [LOG] 21.72261 INFO juju environs: falling back to public bucket [LOG] 21.72264 DEBUG juju.environs.tools reading v1.* tools [LOG] 21.72267 DEBUG juju.environs.tools found 1.11.3-precise-amd64 [LOG] 21.72268 INFO juju environs: filtering tools by series: precise [LOG] 21.72271 INFO juju environs: filtering tools by version: 1.11.3 [LOG] 21.72273 INFO juju environs/dummy: would pick tools from 1.11.3-precise-amd64 [LOG] 21.75348 INFO juju state: opening state; mongo addresses: ["localhost:36812"]; entity "" [LOG] 21.75696 INFO juju state: connection established [LOG] 23.83452 INFO juju state: initializing environment [LOG] 23.85469 INFO juju state/api: listening on "127.0.0.1:39199" [LOG] 23.88614 INFO juju state: opening state; mongo addresses: ["localhost:36812"]; entity "" [LOG] 23.88961 INFO juju state: connection established [LOG] 23.89010 INFO juju juju: authorization error while connecting to state server; retrying [LOG] 23.89019 INFO juju state: opening state; mongo addresses: ["localhost:36812"]; entity "" [LOG] 23.89378 INFO juju state: connection established [LOG] 23.94820 INFO juju state/api: dialing "wss://127.0.0.1:39199/" [LOG] 23.95266 INFO juju state/api: connection established [LOG] 23.95285 DEBUG juju rpc/jsoncodec: <- {"RequestId":1,"Type":"Admin","Request":"Login","Params":{"AuthTag":"user-admin","Password":"dummy-secret"}} [LOG] 23.95330 DEBUG juju rpc/jsoncodec: -> {"RequestId":1,"Response":{}} [LOG] 23.96028 DEBUG juju rpc/jsoncodec: <- {"RequestId":2,"Type":"Pinger","Request":"Ping","Params":{}} [LOG] 23.96041 DEBUG juju rpc/jsoncodec: -> {"RequestId":2,"Response":{}} [LOG] 23.96499 INFO juju.provisioner Starting up provisioner task 0 [LOG] 23.99056 INFO juju.provisioner found machine "0" pending provisioning provisioner_test.go:341: c.Assert(status, Equals, params.StatusError) ... obtained params.Status = "pending" ... expected params.Status = "error" [LOG] 26.66127 INFO juju environs/dummy: dummy startinstance, machine 0 [LOG] 26.66134 ERROR juju.provisioner cannot start instance for machine "0": dummy.StartInstance is broken [LOG] 26.66684 INFO juju.provisioner Shutting down provisioner task 0 [LOG] 26.66738 INFO juju environs/dummy: reset environment [LOG] 26.66760 DEBUG juju rpc/jsoncodec: <- error: read tcp 127.0.0.1:59667: use of closed network connection (closing true) ---------------------------------------------------------------------- FAIL: provisioner_test.go:355: ProvisionerSuite.TestProvisioningDoesNotOccurForContainers [LOG] 37.77345 INFO juju environs/testing: uploading FAKE tools 1.11.3-precise-amd64 [LOG] 37.77352 INFO juju environs: reading tools with major version 1 [LOG] 37.77354 DEBUG juju.environs.tools reading v1.* tools [LOG] 37.77356 INFO juju environs: falling back to public bucket [LOG] 37.77358 DEBUG juju.environs.tools reading v1.* tools [LOG] 37.77361 DEBUG juju.environs.tools found 1.11.3-precise-amd64 [LOG] 37.77363 INFO juju environs: filtering tools by series: precise [LOG] 37.77365 INFO juju environs: filtering tools by version: 1.11.3 [LOG] 37.77367 INFO juju environs/dummy: would pick tools from 1.11.3-precise-amd64 [LOG] 37.80446 INFO juju state: opening state; mongo addresses: ["localhost:36812"]; entity "" [LOG] 37.80783 INFO juju state: connection established [LOG] 39.25750 INFO juju state: initializing environment [LOG] 39.27773 INFO juju state/api: listening on "127.0.0.1:51642" [LOG] 39.30551 INFO juju state: opening state; mongo addresses: ["localhost:36812"]; entity "" [LOG] 39.30899 INFO juju state: connection established [LOG] 39.30949 INFO juju juju: authorization error while connecting to state server; retrying [LOG] 39.30955 INFO juju state: opening state; mongo addresses: ["localhost:36812"]; entity "" [LOG] 39.31278 INFO juju state: connection established [LOG] 39.37048 INFO juju state/api: dialing "wss://127.0.0.1:51642/" [LOG] 39.37486 INFO juju state/api: connection established [LOG] 39.37502 DEBUG juju rpc/jsoncodec: <- {"RequestId":1,"Type":"Admin","Request":"Login","Params":{"AuthTag":"user-admin","Password":"dummy-secret"}} [LOG] 39.37547 DEBUG juju rpc/jsoncodec: -> {"RequestId":1,"Response":{}} [LOG] 39.37573 DEBUG juju rpc/jsoncodec: <- {"RequestId":2,"Type":"Pinger","Request":"Ping","Params":{}} [LOG] 39.37580 DEBUG juju rpc/jsoncodec: -> {"RequestId":2,"Response":{}} [LOG] 39.37900 INFO juju.provisioner Starting up provisioner task 0 [LOG] 39.40379 INFO juju.provisioner found machine "0" pending provisioning provisioner_test.go:362: inst := s.checkStartInstance(c, m) provisioner_test.go:166: c.Fatalf("provisioner did not start an instance") ... Error: provisioner did not start an instance [LOG] 43.00191 INFO juju environs/dummy: dummy startinstance, machine 0 [LOG] 43.00202 INFO juju environs: reading tools with major version 1 [LOG] 43.00208 DEBUG juju.environs.tools reading v1.* tools [LOG] 43.00213 INFO juju environs: falling back to public bucket [LOG] 43.00226 DEBUG juju.environs.tools reading v1.* tools [LOG] 43.00236 DEBUG juju.environs.tools found 1.11.3-precise-amd64 [LOG] 43.00245 INFO juju environs: filtering tools by version: 1.11.3 [LOG] 43.00248 INFO juju environs: filtering tools by series: precise [LOG] 43.00251 INFO juju environs: filtering tools by architecture: amd64 [LOG] 43.00259 INFO juju environs/dummy: would pick tools from 1.11.3-precise-amd64 [LOG] 43.01911 INFO juju.provisioner started machine 0 as instance dummyenv-0 with hardware "arch=amd64 cpu-cores=1 mem=4096M" [LOG] 43.01914 INFO juju.provisioner Shutting down provisioner task 0 [LOG] 43.01982 DEBUG juju rpc/jsoncodec: <- error: EOF (closing false) [LOG] 43.01990 INFO juju environs/dummy: reset environment ---------------------------------------------------------------------- FAIL: provisioner_test.go:441: ProvisionerSuite.TestProvisioningDoesNotProvisionTheSameMachineAfterRestart [LOG] 46.68518 INFO juju environs/testing: uploading FAKE tools 1.11.3-precise-amd64 [LOG] 46.68528 INFO juju environs: reading tools with major version 1 [LOG] 46.68530 DEBUG juju.environs.tools reading v1.* tools [LOG] 46.68532 INFO juju environs: falling back to public bucket [LOG] 46.68534 DEBUG juju.environs.tools reading v1.* tools [LOG] 46.68537 DEBUG juju.environs.tools found 1.11.3-precise-amd64 [LOG] 46.68539 INFO juju environs: filtering tools by series: precise [LOG] 46.68541 INFO juju environs: filtering tools by version: 1.11.3 [LOG] 46.68544 INFO juju environs/dummy: would pick tools from 1.11.3-precise-amd64 [LOG] 46.71831 INFO juju state: opening state; mongo addresses: ["localhost:36812"]; entity "" [LOG] 46.72194 INFO juju state: connection established [LOG] 51.27129 INFO juju state: initializing environment [LOG] 51.29291 INFO juju state/api: listening on "127.0.0.1:60765" [LOG] 51.32224 INFO juju state: opening state; mongo addresses: ["localhost:36812"]; entity "" [LOG] 51.32575 INFO juju state: connection established [LOG] 51.32632 INFO juju juju: authorization error while connecting to state server; retrying [LOG] 51.32638 INFO juju state: opening state; mongo addresses: ["localhost:36812"]; entity "" [LOG] 51.32955 INFO juju state: connection established [LOG] 51.38958 INFO juju state/api: dialing "wss://127.0.0.1:60765/" [LOG] 51.39425 INFO juju state/api: connection established [LOG] 51.39444 DEBUG juju rpc/jsoncodec: <- {"RequestId":1,"Type":"Admin","Request":"Login","Params":{"AuthTag":"user-admin","Password":"dummy-secret"}} [LOG] 51.39505 DEBUG juju rpc/jsoncodec: -> {"RequestId":1,"Response":{}} [LOG] 51.39906 INFO juju.provisioner Starting up provisioner task 0 [LOG] 51.40233 DEBUG juju rpc/jsoncodec: <- {"RequestId":2,"Type":"Pinger","Request":"Ping","Params":{}} [LOG] 51.40244 DEBUG juju rpc/jsoncodec: -> {"RequestId":2,"Response":{}} [LOG] 51.42927 INFO juju.provisioner found machine "0" pending provisioning provisioner_test.go:448: s.checkStartInstance(c, m) provisioner_test.go:166: c.Fatalf("provisioner did not start an instance") ... Error: provisioner did not start an instance [LOG] 55.37158 INFO juju environs/dummy: dummy startinstance, machine 0 [LOG] 55.37169 INFO juju environs: reading tools with major version 1 [LOG] 55.37175 DEBUG juju.environs.tools reading v1.* tools [LOG] 55.37180 INFO juju environs: falling back to public bucket [LOG] 55.37185 DEBUG juju.environs.tools reading v1.* tools [LOG] 55.37194 DEBUG juju.environs.tools found 1.11.3-precise-amd64 [LOG] 55.37203 INFO juju environs: filtering tools by version: 1.11.3 [LOG] 55.37206 INFO juju environs: filtering tools by series: precise [LOG] 55.37210 INFO juju environs: filtering tools by architecture: amd64 [LOG] 55.37217 INFO juju environs/dummy: would pick tools from 1.11.3-precise-amd64 [LOG] 55.38237 INFO juju.provisioner started machine 0 as instance dummyenv-0 with hardware "arch=amd64 cpu-cores=1 mem=4096M" [LOG] 55.38239 INFO juju.provisioner Shutting down provisioner task 0 [LOG] 55.38299 DEBUG juju rpc/jsoncodec: <- error: EOF (closing false) [LOG] 55.38308 INFO juju environs/dummy: reset environment ---------------------------------------------------------------------- FAIL: provisioner_test.go:420: ProvisionerSuite.TestProvisioningDoesOccurAfterInvalidEnvironmentPublished [LOG] 55.46875 INFO juju environs/testing: uploading FAKE tools 1.11.3-precise-amd64 [LOG] 55.46882 INFO juju environs: reading tools with major version 1 [LOG] 55.46884 DEBUG juju.environs.tools reading v1.* tools [LOG] 55.46886 INFO juju environs: falling back to public bucket [LOG] 55.46888 DEBUG juju.environs.tools reading v1.* tools [LOG] 55.46891 DEBUG juju.environs.tools found 1.11.3-precise-amd64 [LOG] 55.46893 INFO juju environs: filtering tools by series: precise [LOG] 55.46895 INFO juju environs: filtering tools by version: 1.11.3 [LOG] 55.46897 INFO juju environs/dummy: would pick tools from 1.11.3-precise-amd64 [LOG] 55.49605 INFO juju state: opening state; mongo addresses: ["localhost:36812"]; entity "" [LOG] 55.49968 INFO juju state: connection established [LOG] 57.36381 INFO juju state: initializing environment [LOG] 57.38441 INFO juju state/api: listening on "127.0.0.1:48540" [LOG] 57.41775 INFO juju state: opening state; mongo addresses: ["localhost:36812"]; entity "" [LOG] 57.42134 INFO juju state: connection established [LOG] 57.42185 INFO juju juju: authorization error while connecting to state server; retrying [LOG] 57.42192 INFO juju state: opening state; mongo addresses: ["localhost:36812"]; entity "" [LOG] 57.42519 INFO juju state: connection established [LOG] 57.48037 INFO juju state/api: dialing "wss://127.0.0.1:48540/" [LOG] 57.48488 INFO juju state/api: connection established [LOG] 57.48508 DEBUG juju rpc/jsoncodec: <- {"RequestId":1,"Type":"Admin","Request":"Login","Params":{"AuthTag":"user-admin","Password":"dummy-secret"}} [LOG] 57.48551 DEBUG juju rpc/jsoncodec: -> {"RequestId":1,"Response":{}} [LOG] 57.49429 INFO juju.provisioner Starting up provisioner task 0 [LOG] 57.49440 DEBUG juju rpc/jsoncodec: <- {"RequestId":2,"Type":"Pinger","Request":"Ping","Params":{}} [LOG] 57.49454 DEBUG juju rpc/jsoncodec: -> {"RequestId":2,"Response":{}} [LOG] 57.51994 INFO juju.provisioner found machine "0" pending provisioning provisioner_test.go:428: s.checkStartInstance(c, m) provisioner_test.go:166: c.Fatalf("provisioner did not start an instance") ... Error: provisioner did not start an instance [LOG] 59.69861 INFO juju environs/dummy: dummy startinstance, machine 0 [LOG] 59.69880 INFO juju environs: reading tools with major version 1 [LOG] 59.69886 DEBUG juju.environs.tools reading v1.* tools [LOG] 59.69892 INFO juju environs: falling back to public bucket [LOG] 59.69898 DEBUG juju.environs.tools reading v1.* tools [LOG] 59.69907 DEBUG juju.environs.tools found 1.11.3-precise-amd64 [LOG] 59.69916 INFO juju environs: filtering tools by version: 1.11.3 [LOG] 59.69919 INFO juju environs: filtering tools by series: precise [LOG] 59.69922 INFO juju environs: filtering tools by architecture: amd64 [LOG] 59.69930 INFO juju environs/dummy: would pick tools from 1.11.3-precise-amd64 [LOG] 59.72071 INFO juju.provisioner started machine 0 as instance dummyenv-0 with hardware "arch=amd64 cpu-cores=1 mem=4096M" [LOG] 59.72077 INFO juju.provisioner Shutting down provisioner task 0 [LOG] 59.72244 DEBUG juju rpc/jsoncodec: <- error: EOF (closing false) [LOG] 59.72271 INFO juju environs/dummy: reset environment ---------------------------------------------------------------------- FAIL: provisioner_test.go:526: ProvisionerSuite.TestProvisioningRecoversAfterInvalidEnvironmentPublished [LOG] 63.74847 INFO juju environs/testing: uploading FAKE tools 1.11.3-precise-amd64 [LOG] 63.74854 INFO juju environs: reading tools with major version 1 [LOG] 63.74857 DEBUG juju.environs.tools reading v1.* tools [LOG] 63.74858 INFO juju environs: falling back to public bucket [LOG] 63.74860 DEBUG juju.environs.tools reading v1.* tools [LOG] 63.74863 DEBUG juju.environs.tools found 1.11.3-precise-amd64 [LOG] 63.74865 INFO juju environs: filtering tools by series: precise [LOG] 63.74867 INFO juju environs: filtering tools by version: 1.11.3 [LOG] 63.74870 INFO juju environs/dummy: would pick tools from 1.11.3-precise-amd64 [LOG] 63.77623 INFO juju state: opening state; mongo addresses: ["localhost:36812"]; entity "" [LOG] 63.77967 INFO juju state: connection established [LOG] 65.90770 INFO juju state: initializing environment [LOG] 65.92768 INFO juju state/api: listening on "127.0.0.1:52595" [LOG] 65.96220 INFO juju state: opening state; mongo addresses: ["localhost:36812"]; entity "" [LOG] 65.96572 INFO juju state: connection established [LOG] 65.96626 INFO juju juju: authorization error while connecting to state server; retrying [LOG] 65.96632 INFO juju state: opening state; mongo addresses: ["localhost:36812"]; entity "" [LOG] 65.96931 INFO juju state: connection established [LOG] 66.02819 INFO juju state/api: dialing "wss://127.0.0.1:52595/" [LOG] 66.03244 INFO juju state/api: connection established [LOG] 66.03263 DEBUG juju rpc/jsoncodec: <- {"RequestId":1,"Type":"Admin","Request":"Login","Params":{"AuthTag":"user-admin","Password":"dummy-secret"}} [LOG] 66.03307 DEBUG juju rpc/jsoncodec: -> {"RequestId":1,"Response":{}} [LOG] 66.03436 DEBUG juju rpc/jsoncodec: <- {"RequestId":2,"Type":"Pinger","Request":"Ping","Params":{}} [LOG] 66.03444 DEBUG juju rpc/jsoncodec: -> {"RequestId":2,"Response":{}} [LOG] 66.03666 INFO juju.provisioner Starting up provisioner task 0 [LOG] 66.06045 INFO juju.provisioner found machine "0" pending provisioning provisioner_test.go:533: s.checkStartInstance(c, m) provisioner_test.go:166: c.Fatalf("provisioner did not start an instance") ... Error: provisioner did not start an instance [LOG] 82.01953 INFO juju environs/dummy: dummy startinstance, machine 0 [LOG] 82.01963 INFO juju environs: reading tools with major version 1 [LOG] 82.01968 DEBUG juju.environs.tools reading v1.* tools [LOG] 82.01973 INFO juju environs: falling back to public bucket [LOG] 82.01978 DEBUG juju.environs.tools reading v1.* tools [LOG] 82.01987 DEBUG juju.environs.tools found 1.11.3-precise-amd64 [LOG] 82.01996 INFO juju environs: filtering tools by version: 1.11.3 [LOG] 82.01999 INFO juju environs: filtering tools by series: precise [LOG] 82.02003 INFO juju environs: filtering tools by architecture: amd64 [LOG] 82.02010 INFO juju environs/dummy: would pick tools from 1.11.3-precise-amd64 [LOG] 82.03405 INFO juju.provisioner started machine 0 as instance dummyenv-0 with hardware "arch=amd64 cpu-cores=1 mem=4096M" [LOG] 82.03408 INFO juju.provisioner Shutting down provisioner task 0 [LOG] 82.03519 DEBUG juju rpc/jsoncodec: <- error: EOF (closing false) [LOG] 82.03527 INFO juju environs/dummy: reset environment ---------------------------------------------------------------------- FAIL: provisioner_test.go:465: ProvisionerSuite.TestProvisioningStopsInstances [LOG] 82.12026 INFO juju environs/testing: uploading FAKE tools 1.11.3-precise-amd64 [LOG] 82.12035 INFO juju environs: reading tools with major version 1 [LOG] 82.12037 DEBUG juju.environs.tools reading v1.* tools [LOG] 82.12038 INFO juju environs: falling back to public bucket [LOG] 82.12040 DEBUG juju.environs.tools reading v1.* tools [LOG] 82.12044 DEBUG juju.environs.tools found 1.11.3-precise-amd64 [LOG] 82.12046 INFO juju environs: filtering tools by series: precise [LOG] 82.12048 INFO juju environs: filtering tools by version: 1.11.3 [LOG] 82.12051 INFO juju environs/dummy: would pick tools from 1.11.3-precise-amd64 [LOG] 82.14828 INFO juju state: opening state; mongo addresses: ["localhost:36812"]; entity "" [LOG] 82.15197 INFO juju state: connection established [LOG] 84.30609 INFO juju state: initializing environment [LOG] 84.32686 INFO juju state/api: listening on "127.0.0.1:38671" [LOG] 84.35812 INFO juju state: opening state; mongo addresses: ["localhost:36812"]; entity "" [LOG] 84.36162 INFO juju state: connection established [LOG] 84.36211 INFO juju juju: authorization error while connecting to state server; retrying [LOG] 84.36220 INFO juju state: opening state; mongo addresses: ["localhost:36812"]; entity "" [LOG] 84.36516 INFO juju state: connection established [LOG] 84.41749 INFO juju state/api: dialing "wss://127.0.0.1:38671/" [LOG] 84.42195 INFO juju state/api: connection established [LOG] 84.42211 DEBUG juju rpc/jsoncodec: <- {"RequestId":1,"Type":"Admin","Request":"Login","Params":{"AuthTag":"user-admin","Password":"dummy-secret"}} [LOG] 84.42257 DEBUG juju rpc/jsoncodec: -> {"RequestId":1,"Response":{}} [LOG] 84.43121 INFO juju.provisioner Starting up provisioner task 0 [LOG] 84.43645 DEBUG juju rpc/jsoncodec: <- {"RequestId":2,"Type":"Pinger","Request":"Ping","Params":{}} [LOG] 84.43655 DEBUG juju rpc/jsoncodec: -> {"RequestId":2,"Response":{}} [LOG] 84.45600 INFO juju.provisioner found machine "0" pending provisioning provisioner_test.go:472: i0 := s.checkStartInstance(c, m0) provisioner_test.go:166: c.Fatalf("provisioner did not start an instance") ... Error: provisioner did not start an instance [LOG] 86.92446 INFO juju environs/dummy: dummy startinstance, machine 0 [LOG] 86.92460 INFO juju environs: reading tools with major version 1 [LOG] 86.92465 DEBUG juju.environs.tools reading v1.* tools [LOG] 86.92470 INFO juju environs: falling back to public bucket [LOG] 86.92476 DEBUG juju.environs.tools reading v1.* tools [LOG] 86.92486 DEBUG juju.environs.tools found 1.11.3-precise-amd64 [LOG] 86.92494 INFO juju environs: filtering tools by version: 1.11.3 [LOG] 86.92498 INFO juju environs: filtering tools by series: precise [LOG] 86.92501 INFO juju environs: filtering tools by architecture: amd64 [LOG] 86.92509 INFO juju environs/dummy: would pick tools from 1.11.3-precise-amd64 [LOG] 86.94007 INFO juju.provisioner started machine 0 as instance dummyenv-0 with hardware "arch=amd64 cpu-cores=1 mem=4096M" [LOG] 86.94009 INFO juju.provisioner Shutting down provisioner task 0 [LOG] 86.94067 DEBUG juju rpc/jsoncodec: <- error: EOF (closing false) [LOG] 86.94075 INFO juju environs/dummy: reset environment ---------------------------------------------------------------------- FAIL: provisioner_test.go:299: ProvisionerSuite.TestSimple [LOG] 87.02322 INFO juju environs/testing: uploading FAKE tools 1.11.3-precise-amd64 [LOG] 87.02329 INFO juju environs: reading tools with major version 1 [LOG] 87.02331 DEBUG juju.environs.tools reading v1.* tools [LOG] 87.02333 INFO juju environs: falling back to public bucket [LOG] 87.02335 DEBUG juju.environs.tools reading v1.* tools [LOG] 87.02338 DEBUG juju.environs.tools found 1.11.3-precise-amd64 [LOG] 87.02340 INFO juju environs: filtering tools by series: precise [LOG] 87.02342 INFO juju environs: filtering tools by version: 1.11.3 [LOG] 87.02344 INFO juju environs/dummy: would pick tools from 1.11.3-precise-amd64 [LOG] 87.05036 INFO juju state: opening state; mongo addresses: ["localhost:36812"]; entity "" [LOG] 87.05371 INFO juju state: connection established [LOG] 91.43296 INFO juju state: initializing environment [LOG] 91.45428 INFO juju state/api: listening on "127.0.0.1:35800" [LOG] 91.48735 INFO juju state: opening state; mongo addresses: ["localhost:36812"]; entity "" [LOG] 91.49089 INFO juju state: connection established [LOG] 91.49145 INFO juju juju: authorization error while connecting to state server; retrying [LOG] 91.49151 INFO juju state: opening state; mongo addresses: ["localhost:36812"]; entity "" [LOG] 91.49495 INFO juju state: connection established [LOG] 91.54962 INFO juju state/api: dialing "wss://127.0.0.1:35800/" [LOG] 91.55421 INFO juju state/api: connection established [LOG] 91.55441 DEBUG juju rpc/jsoncodec: <- {"RequestId":1,"Type":"Admin","Request":"Login","Params":{"AuthTag":"user-admin","Password":"dummy-secret"}} [LOG] 91.55490 DEBUG juju rpc/jsoncodec: -> {"RequestId":1,"Response":{}} [LOG] 91.55824 INFO juju.provisioner Starting up provisioner task 0 [LOG] 91.56291 DEBUG juju rpc/jsoncodec: <- {"RequestId":2,"Type":"Pinger","Request":"Ping","Params":{}} [LOG] 91.56304 DEBUG juju rpc/jsoncodec: -> {"RequestId":2,"Response":{}} [LOG] 91.58419 INFO juju.provisioner found machine "0" pending provisioning provisioner_test.go:306: instance := s.checkStartInstance(c, m) provisioner_test.go:166: c.Fatalf("provisioner did not start an instance") ... Error: provisioner did not start an instance [LOG] 28.64436 INFO juju environs/dummy: dummy startinstance, machine 0 [LOG] 28.64442 INFO juju environs: reading tools with major version 1 [LOG] 28.64445 DEBUG juju.environs.tools reading v1.* tools [LOG] 28.64448 INFO juju environs: falling back to public bucket [LOG] 28.64450 DEBUG juju.environs.tools reading v1.* tools [LOG] 28.64458 DEBUG juju.environs.tools found 1.11.3-precise-amd64 [LOG] 28.64463 INFO juju environs: filtering tools by version: 1.11.3 [LOG] 28.64465 INFO juju environs: filtering tools by series: precise [LOG] 28.64466 INFO juju environs: filtering tools by architecture: amd64 [LOG] 28.64470 INFO juju environs/dummy: would pick tools from 1.11.3-precise-amd64 [LOG] 28.65376 INFO juju.provisioner started machine 0 as instance dummyenv-0 with hardware "arch=amd64 cpu-cores=1 mem=4096M" [LOG] 28.65379 INFO juju.provisioner Shutting down provisioner task 0 [LOG] 28.65499 DEBUG juju rpc/jsoncodec: <- error: EOF (closing false) [LOG] 28.65508 INFO juju environs/dummy: reset environment OOPS: 11 passed, 7 FAILED --- FAIL: TestPackage (158.04 seconds) FAIL FAIL launchpad.net/juju-core/worker/provisioner 158.381s ---------------------------------------------------------------------- PANIC: resumer_test.go:0: ResumerSuite.TearDownTest ... Panic: watcher iteration error: unauthorized db:juju ns:juju.txns.log lock type:0 client:127.0.0.1 (PC=0x41175F) /usr/lib/go/src/pkg/runtime/proc.c:1443 in panic /home/tarmac/trees/src/launchpad.net/juju-core/environs/dummy/environs.go:213 in environState.destroy /home/tarmac/trees/src/launchpad.net/juju-core/environs/dummy/environs.go:189 in Reset /home/tarmac/trees/src/launchpad.net/juju-core/juju/testing/conn.go:235 in JujuConnSuite.tearDownConn /home/tarmac/trees/src/launchpad.net/juju-core/juju/testing/conn.go:134 in JujuConnSuite.TearDownTest ---------------------------------------------------------------------- PANIC: resumer_test.go:34: ResumerSuite.TestResumerCalls [LOG] 40.82637 INFO juju environs/testing: uploading FAKE tools 1.11.3-precise-amd64 [LOG] 40.82649 INFO juju environs: reading tools with major version 1 [LOG] 40.82650 DEBUG juju.environs.tools reading v1.* tools [LOG] 40.82652 INFO juju environs: falling back to public bucket [LOG] 40.82667 DEBUG juju.environs.tools reading v1.* tools [LOG] 40.82672 DEBUG juju.environs.tools found 1.11.3-precise-amd64 [LOG] 40.82674 INFO juju environs: filtering tools by series: precise [LOG] 40.82677 INFO juju environs: filtering tools by version: 1.11.3 [LOG] 40.82680 INFO juju environs/dummy: would pick tools from 1.11.3-precise-amd64 [LOG] 40.85280 INFO juju state: opening state; mongo addresses: ["localhost:50453"]; entity "" [LOG] 40.85643 INFO juju state: connection established [LOG] 44.23348 INFO juju state: initializing environment [LOG] 55.38845 INFO juju state/api: listening on "127.0.0.1:46453" [LOG] 55.41530 INFO juju state: opening state; mongo addresses: ["localhost:50453"]; entity "" [LOG] 55.41889 INFO juju state: connection established [LOG] 55.41942 INFO juju juju: authorization error while connecting to state server; retrying [LOG] 55.41948 INFO juju state: opening state; mongo addresses: ["localhost:50453"]; entity "" [LOG] 55.42260 INFO juju state: connection established [LOG] 55.47486 INFO juju state/api: dialing "wss://127.0.0.1:46453/" [LOG] 55.47938 INFO juju state/api: connection established [LOG] 55.47961 INFO juju rpc: discarding action method reflect.Method{Name:"apiRootForEntity", PkgPath:"launchpad.net/juju-core/state/apiserver", Type:(*reflect.commonType)(0x7eff20), Func:reflect.Value{typ:(*reflect.commonType)(0x7eff20), val:(unsafe.Pointer)(0x663b81), flag:0x131}, Index:1} [LOG] 55.47972 DEBUG juju rpc/jsoncodec: <- {"RequestId":1,"Type":"Admin","Request":"Login","Params":{"AuthTag":"user-admin","Password":"dummy-secret"}} [LOG] 55.48018 INFO juju rpc: discarding obtainer method reflect.Method{Name:"AuthClient", PkgPath:"", Type:(*reflect.commonType)(0x738030), Func:reflect.Value{typ:(*reflect.commonType)(0x738030), val:(unsafe.Pointer)(0x664fd7), flag:0x130}, Index:1} [LOG] 55.48021 INFO juju rpc: discarding obtainer method reflect.Method{Name:"AuthEnvironManager", PkgPath:"", Type:(*reflect.commonType)(0x738030), Func:reflect.Value{typ:(*reflect.commonType)(0x738030), val:(unsafe.Pointer)(0x664f91), flag:0x130}, Index:2} [LOG] 55.48023 INFO juju rpc: discarding obtainer method reflect.Method{Name:"AuthMachineAgent", PkgPath:"", Type:(*reflect.commonType)(0x738030), Func:reflect.Value{typ:(*reflect.commonType)(0x738030), val:(unsafe.Pointer)(0x664eb4), flag:0x130}, Index:3} [LOG] 55.48026 INFO juju rpc: discarding obtainer method reflect.Method{Name:"AuthOwner", PkgPath:"", Type:(*reflect.commonType)(0x7937e8), Func:reflect.Value{typ:(*reflect.commonType)(0x7937e8), val:(unsafe.Pointer)(0x664efb), flag:0x130}, Index:4} [LOG] 55.48033 INFO juju rpc: discarding obtainer method reflect.Method{Name:"GetAuthTag", PkgPath:"", Type:(*reflect.commonType)(0x738120), Func:reflect.Value{typ:(*reflect.commonType)(0x738120), val:(unsafe.Pointer)(0x665020), flag:0x130}, Index:6} [LOG] 55.48036 INFO juju rpc: discarding obtainer method reflect.Method{Name:"Kill", PkgPath:"", Type:(*reflect.commonType)(0x715590), Func:reflect.Value{typ:(*reflect.commonType)(0x715590), val:(unsafe.Pointer)(0x664714), flag:0x130}, Index:7} [LOG] 55.48040 INFO juju rpc: discarding action method reflect.Method{Name:"getMachine", PkgPath:"launchpad.net/juju-core/state/apiserver/machine", Type:(*reflect.commonType)(0x7f0ad0), Func:reflect.Value{typ:(*reflect.commonType)(0x7f0ad0), val:(unsafe.Pointer)(0x6ea93c), flag:0x131}, Index:2} [LOG] 55.48043 INFO juju rpc: discarding action method reflect.Method{Name:"setPassword", PkgPath:"launchpad.net/juju-core/state/apiserver/common", Type:(*reflect.commonType)(0x7f0b58), Func:reflect.Value{typ:(*reflect.commonType)(0x7f0b58), val:(unsafe.Pointer)(0x6ebab9), flag:0x131}, Index:3} [LOG] 55.48049 INFO juju rpc: discarding action method reflect.Method{Name:"oneAgentTools", PkgPath:"launchpad.net/juju-core/state/apiserver/upgrader", Type:(*reflect.commonType)(0x835688), Func:reflect.Value{typ:(*reflect.commonType)(0x835688), val:(unsafe.Pointer)(0x6ec142), flag:0x131}, Index:3} [LOG] 55.48052 INFO juju rpc: discarding obtainer method reflect.Method{Name:"requireAgent", PkgPath:"launchpad.net/juju-core/state/apiserver", Type:(*reflect.commonType)(0x7380a8), Func:reflect.Value{typ:(*reflect.commonType)(0x7380a8), val:(unsafe.Pointer)(0x664742), flag:0x131}, Index:14} [LOG] 55.48055 INFO juju rpc: discarding obtainer method reflect.Method{Name:"requireClient", PkgPath:"launchpad.net/juju-core/state/apiserver", Type:(*reflect.commonType)(0x7380a8), Func:reflect.Value{typ:(*reflect.commonType)(0x7380a8), val:(unsafe.Pointer)(0x6647b0), flag:0x131}, Index:15} [LOG] 55.48058 DEBUG juju rpc/jsoncodec: -> {"RequestId":1,"Response":{}} [LOG] 55.48086 DEBUG juju rpc/jsoncodec: <- {"RequestId":2,"Type":"Pinger","Request":"Ping","Params":{}} [LOG] 55.48097 DEBUG juju rpc/jsoncodec: -> {"RequestId":2,"Response":{}} [LOG] 55.58400 DEBUG juju rpc/jsoncodec: <- error: EOF (closing false) [LOG] 55.58423 INFO juju environs/dummy: reset environment ... Panic: Fixture has panicked (see related PANIC) OOPS: 0 passed, 1 FIXTURE-PANICKED, 2 MISSED --- FAIL: TestPackage (18.36 seconds) FAIL FAIL launchpad.net/juju-core/worker/resumer 18.626s ---------------------------------------------------------------------- PANIC: context.go:0: FilterSuite.TearDownTest ... Panic: watcher iteration error: unauthorized db:juju ns:juju.txns.log lock type:0 client:127.0.0.1 (PC=0x41175F) /usr/lib/go/src/pkg/runtime/proc.c:1443 in panic /home/tarmac/trees/src/launchpad.net/juju-core/environs/dummy/environs.go:213 in environState.destroy /home/tarmac/trees/src/launchpad.net/juju-core/environs/dummy/environs.go:189 in Reset /home/tarmac/trees/src/launchpad.net/juju-core/juju/testing/conn.go:235 in JujuConnSuite.tearDownConn /home/tarmac/trees/src/launchpad.net/juju-core/juju/testing/conn.go:134 in JujuConnSuite.TearDownTest ---------------------------------------------------------------------- PANIC: filter_test.go:351: FilterSuite.TestCharmErrorEvents [LOG] 84.20282 INFO juju environs/testing: uploading FAKE tools 1.11.3-precise-amd64 [LOG] 84.20296 INFO juju environs: reading tools with major version 1 [LOG] 84.20298 DEBUG juju.environs.tools reading v1.* tools [LOG] 84.20300 INFO juju environs: falling back to public bucket [LOG] 84.20301 DEBUG juju.environs.tools reading v1.* tools [LOG] 84.20306 DEBUG juju.environs.tools found 1.11.3-precise-amd64 [LOG] 84.20307 INFO juju environs: filtering tools by series: precise [LOG] 84.20313 INFO juju environs: filtering tools by version: 1.11.3 [LOG] 84.20316 INFO juju environs/dummy: would pick tools from 1.11.3-precise-amd64 [LOG] 84.22948 INFO juju state: opening state; mongo addresses: ["localhost:60594"]; entity "" [LOG] 84.23357 INFO juju state: connection established [LOG] 86.63084 INFO juju state: initializing environment [LOG] 99.08151 INFO juju state/api: listening on "127.0.0.1:52346" [LOG] 99.11143 INFO juju state: opening state; mongo addresses: ["localhost:60594"]; entity "" [LOG] 99.11498 INFO juju state: connection established [LOG] 99.11552 INFO juju juju: authorization error while connecting to state server; retrying [LOG] 99.11557 INFO juju state: opening state; mongo addresses: ["localhost:60594"]; entity "" [LOG] 99.11890 INFO juju state: connection established [LOG] 99.17444 INFO juju state/api: dialing "wss://127.0.0.1:52346/" [LOG] 99.17908 INFO juju state/api: connection established [LOG] 99.18322 INFO juju rpc: discarding action method reflect.Method{Name:"apiRootForEntity", PkgPath:"launchpad.net/juju-core/state/apiserver", Type:(*reflect.commonType)(0x87c128), Func:reflect.Value{typ:(*reflect.commonType)(0x87c128), val:(unsafe.Pointer)(0x6f99e4), flag:0x131}, Index:1} [LOG] 99.18340 DEBUG juju rpc/jsoncodec: <- {"RequestId":1,"Type":"Admin","Request":"Login","Params":{"AuthTag":"user-admin","Password":"dummy-secret"}} [LOG] 99.18396 INFO juju rpc: discarding obtainer method reflect.Method{Name:"AuthClient", PkgPath:"", Type:(*reflect.commonType)(0x7abfc0), Func:reflect.Value{typ:(*reflect.commonType)(0x7abfc0), val:(unsafe.Pointer)(0x6fae3a), flag:0x130}, Index:1} [LOG] 99.18399 INFO juju rpc: discarding obtainer method reflect.Method{Name:"AuthEnvironManager", PkgPath:"", Type:(*reflect.commonType)(0x7abfc0), Func:reflect.Value{typ:(*reflect.commonType)(0x7abfc0), val:(unsafe.Pointer)(0x6fadf4), flag:0x130}, Index:2} [LOG] 99.18402 INFO juju rpc: discarding obtainer method reflect.Method{Name:"AuthMachineAgent", PkgPath:"", Type:(*reflect.commonType)(0x7abfc0), Func:reflect.Value{typ:(*reflect.commonType)(0x7abfc0), val:(unsafe.Pointer)(0x6fad17), flag:0x130}, Index:3} [LOG] 99.18405 INFO juju rpc: discarding obtainer method reflect.Method{Name:"AuthOwner", PkgPath:"", Type:(*reflect.commonType)(0x811970), Func:reflect.Value{typ:(*reflect.commonType)(0x811970), val:(unsafe.Pointer)(0x6fad5e), flag:0x130}, Index:4} [LOG] 99.18411 INFO juju rpc: discarding obtainer method reflect.Method{Name:"GetAuthTag", PkgPath:"", Type:(*reflect.commonType)(0x7ac0b0), Func:reflect.Value{typ:(*reflect.commonType)(0x7ac0b0), val:(unsafe.Pointer)(0x6fae83), flag:0x130}, Index:6} [LOG] 99.18414 INFO juju rpc: discarding obtainer method reflect.Method{Name:"Kill", PkgPath:"", Type:(*reflect.commonType)(0x7858a8), Func:reflect.Value{typ:(*reflect.commonType)(0x7858a8), val:(unsafe.Pointer)(0x6fa577), flag:0x130}, Index:7} [LOG] 99.18418 INFO juju rpc: discarding action method reflect.Method{Name:"getMachine", PkgPath:"launchpad.net/juju-core/state/apiserver/machine", Type:(*reflect.commonType)(0x87ccd8), Func:reflect.Value{typ:(*reflect.commonType)(0x87ccd8), val:(unsafe.Pointer)(0x75766d), flag:0x131}, Index:2} [LOG] 99.18421 INFO juju rpc: discarding action method reflect.Method{Name:"setPassword", PkgPath:"launchpad.net/juju-core/state/apiserver/common", Type:(*reflect.commonType)(0x87cd60), Func:reflect.Value{typ:(*reflect.commonType)(0x87cd60), val:(unsafe.Pointer)(0x7587ea), flag:0x131}, Index:3} [LOG] 99.18427 INFO juju rpc: discarding action method reflect.Method{Name:"oneAgentTools", PkgPath:"launchpad.net/juju-core/state/apiserver/upgrader", Type:(*reflect.commonType)(0x8c97d0), Func:reflect.Value{typ:(*reflect.commonType)(0x8c97d0), val:(unsafe.Pointer)(0x758e73), flag:0x131}, Index:3} [LOG] 99.18430 INFO juju rpc: discarding obtainer method reflect.Method{Name:"requireAgent", PkgPath:"launchpad.net/juju-core/state/apiserver", Type:(*reflect.commonType)(0x7ac038), Func:reflect.Value{typ:(*reflect.commonType)(0x7ac038), val:(unsafe.Pointer)(0x6fa5a5), flag:0x131}, Index:14} [LOG] 99.18433 INFO juju rpc: discarding obtainer method reflect.Method{Name:"requireClient", PkgPath:"launchpad.net/juju-core/state/apiserver", Type:(*reflect.commonType)(0x7ac038), Func:reflect.Value{typ:(*reflect.commonType)(0x7ac038), val:(unsafe.Pointer)(0x6fa613), flag:0x131}, Index:15} [LOG] 99.18436 DEBUG juju rpc/jsoncodec: -> {"RequestId":1,"Response":{}} [LOG] 99.18472 DEBUG juju rpc/jsoncodec: <- {"RequestId":2,"Type":"Pinger","Request":"Ping","Params":{}} [LOG] 99.18480 DEBUG juju rpc/jsoncodec: -> {"RequestId":2,"Response":{}} [LOG] 99.19436 INFO juju writing charm to storage [838 bytes] [LOG] 99.19446 INFO juju adding charm to state [LOG] 99.24802 DEBUG juju worker/uniter/filter: charm check skipped, not yet installed. [LOG] 99.24808 DEBUG juju worker/uniter/filter: changing charm to "cs:missing/one-1" [LOG] 99.24965 DEBUG juju worker/uniter/filter: failed setting charm url "cs:missing/one-1": unknown charm url "cs:missing/one-1" [LOG] 99.24970 ERROR juju worker/uniter/filter: unknown charm url "cs:missing/one-1" [LOG] 99.30580 DEBUG juju worker/uniter/filter: charm check skipped, not yet installed. [LOG] 99.30593 DEBUG juju worker/uniter/filter: changing charm to [LOG] 99.30599 DEBUG juju worker/uniter/filter: failed setting charm url : cannot set nil charm url [LOG] 99.30722 ERROR juju worker/uniter/filter: cannot set nil charm url [LOG] 99.36166 DEBUG juju rpc/jsoncodec: <- error: EOF (closing false) [LOG] 99.36188 INFO juju environs/dummy: reset environment ... Panic: Fixture has panicked (see related PANIC) ---------------------------------------------------------------------- FAIL: context_test.go:0: RunHookSuite.SetUpTest [LOG] 99.42863 INFO juju environs: reading tools with major version 1 [LOG] 99.42866 DEBUG juju.environs.tools reading v1.* tools [LOG] 99.42868 INFO juju environs: falling back to public bucket [LOG] 99.42870 DEBUG juju.environs.tools reading v1.* tools [LOG] 99.42873 DEBUG juju.environs.tools found 1.11.3-precise-amd64 [LOG] 99.42875 INFO juju environs: filtering tools by series: precise [LOG] 99.42877 INFO juju environs: filtering tools by version: 1.11.3 [LOG] 99.42880 INFO juju environs/dummy: would pick tools from 1.11.3-precise-amd64 /home/tarmac/trees/src/launchpad.net/juju-core/juju/testing/conn.go:204: c.Assert(environs.Bootstrap(environ, constraints.Value{}), IsNil) ... value *errors.errorString = &errors.errorString{s:"environment is already bootstrapped"} ("environment is already bootstrapped") ---------------------------------------------------------------------- PANIC: context_test.go:0: RunHookSuite.TearDownTest ... Panic: runtime error: invalid memory address or nil pointer dereference (PC=0x41175F) /usr/lib/go/src/pkg/runtime/proc.c:1443 in panic /usr/lib/go/src/pkg/runtime/runtime.c:128 in panicstring /usr/lib/go/src/pkg/runtime/thread_linux.c:199 in sigpanic /home/tarmac/trees/src/launchpad.net/juju-core/state/state.go:1215 in State.SetAdminMongoPassword /home/tarmac/trees/src/launchpad.net/juju-core/juju/testing/conn.go:230 in JujuConnSuite.tearDownConn /home/tarmac/trees/src/launchpad.net/juju-core/juju/testing/conn.go:134 in JujuConnSuite.TearDownTest ---------------------------------------------------------------------- PANIC: context_test.go:200: RunHookSuite.TestRunHook ... Panic: Fixture has panicked (see related PANIC) ---------------------------------------------------------------------- FAIL: context_test.go:330: ContextRelationSuite.SetUpTest [LOG] 0.44809 INFO juju environs: reading tools with major version 1 [LOG] 0.44812 DEBUG juju.environs.tools reading v1.* tools [LOG] 0.44814 INFO juju environs: falling back to public bucket [LOG] 0.44815 DEBUG juju.environs.tools reading v1.* tools [LOG] 0.44818 DEBUG juju.environs.tools found 1.11.3-precise-amd64 [LOG] 0.44821 INFO juju environs: filtering tools by series: precise [LOG] 0.44823 INFO juju environs: filtering tools by version: 1.11.3 [LOG] 0.44826 INFO juju environs/dummy: would pick tools from 1.11.3-precise-amd64 context_test.go:331: s.JujuConnSuite.SetUpTest(c) /home/tarmac/trees/src/launchpad.net/juju-core/juju/testing/conn.go:204: c.Assert(environs.Bootstrap(environ, constraints.Value{}), IsNil) ... value *errors.errorString = &errors.errorString{s:"environment is already bootstrapped"} ("environment is already bootstrapped") ---------------------------------------------------------------------- PANIC: context_test.go:0: ContextRelationSuite.TearDownTest ... Panic: runtime error: invalid memory address or nil pointer dereference (PC=0x41175F) /usr/lib/go/src/pkg/runtime/proc.c:1443 in panic /usr/lib/go/src/pkg/runtime/runtime.c:128 in panicstring /usr/lib/go/src/pkg/runtime/thread_linux.c:199 in sigpanic /home/tarmac/trees/src/launchpad.net/juju-core/state/state.go:1215 in State.SetAdminMongoPassword /home/tarmac/trees/src/launchpad.net/juju-core/juju/testing/conn.go:230 in JujuConnSuite.tearDownConn /home/tarmac/trees/src/launchpad.net/juju-core/juju/testing/conn.go:134 in JujuConnSuite.TearDownTest ---------------------------------------------------------------------- PANIC: context_test.go:348: ContextRelationSuite.TestChangeMembers ... Panic: Fixture has panicked (see related PANIC) ---------------------------------------------------------------------- FAIL: context_test.go:0: InterfaceSuite.SetUpTest [LOG] 0.54902 INFO juju environs: reading tools with major version 1 [LOG] 0.54905 DEBUG juju.environs.tools reading v1.* tools [LOG] 0.54907 INFO juju environs: falling back to public bucket [LOG] 0.54909 DEBUG juju.environs.tools reading v1.* tools [LOG] 0.54912 DEBUG juju.environs.tools found 1.11.3-precise-amd64 [LOG] 0.54914 INFO juju environs: filtering tools by series: precise [LOG] 0.54917 INFO juju environs: filtering tools by version: 1.11.3 [LOG] 0.54920 INFO juju environs/dummy: would pick tools from 1.11.3-precise-amd64 /home/tarmac/trees/src/launchpad.net/juju-core/juju/testing/conn.go:204: c.Assert(environs.Bootstrap(environ, constraints.Value{}), IsNil) ... value *errors.errorString = &errors.errorString{s:"environment is already bootstrapped"} ("environment is already bootstrapped") ---------------------------------------------------------------------- PANIC: context_test.go:0: InterfaceSuite.TearDownTest ... Panic: runtime error: invalid memory address or nil pointer dereference (PC=0x41175F) /usr/lib/go/src/pkg/runtime/proc.c:1443 in panic /usr/lib/go/src/pkg/runtime/runtime.c:128 in panicstring /usr/lib/go/src/pkg/runtime/thread_linux.c:199 in sigpanic /home/tarmac/trees/src/launchpad.net/juju-core/state/state.go:1215 in State.SetAdminMongoPassword /home/tarmac/trees/src/launchpad.net/juju-core/juju/testing/conn.go:230 in JujuConnSuite.tearDownConn /home/tarmac/trees/src/launchpad.net/juju-core/juju/testing/conn.go:134 in JujuConnSuite.TearDownTest ---------------------------------------------------------------------- PANIC: context_test.go:573: InterfaceSuite.TestConfigCaching ... Panic: Fixture has panicked (see related PANIC) ---------------------------------------------------------------------- FAIL: relationer_test.go:35: RelationerSuite.SetUpTest [LOG] 0.64171 INFO juju environs: reading tools with major version 1 [LOG] 0.64174 DEBUG juju.environs.tools reading v1.* tools [LOG] 0.64176 INFO juju environs: falling back to public bucket [LOG] 0.64177 DEBUG juju.environs.tools reading v1.* tools [LOG] 0.64181 DEBUG juju.environs.tools found 1.11.3-precise-amd64 [LOG] 0.64183 INFO juju environs: filtering tools by series: precise [LOG] 0.64185 INFO juju environs: filtering tools by version: 1.11.3 [LOG] 0.64188 INFO juju environs/dummy: would pick tools from 1.11.3-precise-amd64 relationer_test.go:36: s.JujuConnSuite.SetUpTest(c) /home/tarmac/trees/src/launchpad.net/juju-core/juju/testing/conn.go:204: c.Assert(environs.Bootstrap(environ, constraints.Value{}), IsNil) ... value *errors.errorString = &errors.errorString{s:"environment is already bootstrapped"} ("environment is already bootstrapped") ---------------------------------------------------------------------- PANIC: context_test.go:0: RelationerSuite.TearDownTest ... Panic: runtime error: invalid memory address or nil pointer dereference (PC=0x41175F) /usr/lib/go/src/pkg/runtime/proc.c:1443 in panic /usr/lib/go/src/pkg/runtime/runtime.c:128 in panicstring /usr/lib/go/src/pkg/runtime/thread_linux.c:199 in sigpanic /home/tarmac/trees/src/launchpad.net/juju-core/state/state.go:1215 in State.SetAdminMongoPassword /home/tarmac/trees/src/launchpad.net/juju-core/juju/testing/conn.go:230 in JujuConnSuite.tearDownConn /home/tarmac/trees/src/launchpad.net/juju-core/juju/testing/conn.go:134 in JujuConnSuite.TearDownTest ---------------------------------------------------------------------- PANIC: relationer_test.go:62: RelationerSuite.TestEnterLeaveScope ... Panic: Fixture has panicked (see related PANIC) ---------------------------------------------------------------------- FAIL: context_test.go:0: RelationerImplicitSuite.SetUpTest [LOG] 0.73607 INFO juju environs: reading tools with major version 1 [LOG] 0.73610 DEBUG juju.environs.tools reading v1.* tools [LOG] 0.73612 INFO juju environs: falling back to public bucket [LOG] 0.73614 DEBUG juju.environs.tools reading v1.* tools [LOG] 0.73618 DEBUG juju.environs.tools found 1.11.3-precise-amd64 [LOG] 0.73620 INFO juju environs: filtering tools by series: precise [LOG] 0.73623 INFO juju environs: filtering tools by version: 1.11.3 [LOG] 0.73626 INFO juju environs/dummy: would pick tools from 1.11.3-precise-amd64 /home/tarmac/trees/src/launchpad.net/juju-core/juju/testing/conn.go:204: c.Assert(environs.Bootstrap(environ, constraints.Value{}), IsNil) ... value *errors.errorString = &errors.errorString{s:"environment is already bootstrapped"} ("environment is already bootstrapped") ---------------------------------------------------------------------- PANIC: context_test.go:0: RelationerImplicitSuite.TearDownTest ... Panic: runtime error: invalid memory address or nil pointer dereference (PC=0x41175F) /usr/lib/go/src/pkg/runtime/proc.c:1443 in panic /usr/lib/go/src/pkg/runtime/runtime.c:128 in panicstring /usr/lib/go/src/pkg/runtime/thread_linux.c:199 in sigpanic /home/tarmac/trees/src/launchpad.net/juju-core/state/state.go:1215 in State.SetAdminMongoPassword /home/tarmac/trees/src/launchpad.net/juju-core/juju/testing/conn.go:230 in JujuConnSuite.tearDownConn /home/tarmac/trees/src/launchpad.net/juju-core/juju/testing/conn.go:134 in JujuConnSuite.TearDownTest ---------------------------------------------------------------------- PANIC: relationer_test.go:391: RelationerImplicitSuite.TestImplicitRelationer ... Panic: Fixture has panicked (see related PANIC) ---------------------------------------------------------------------- FAIL: uniter_test.go:82: UniterSuite.SetUpTest [LOG] 52.44735 INFO juju environs: reading tools with major version 1 [LOG] 52.44738 DEBUG juju.environs.tools reading v1.* tools [LOG] 52.44740 INFO juju environs: falling back to public bucket [LOG] 52.44742 DEBUG juju.environs.tools reading v1.* tools [LOG] 52.44747 DEBUG juju.environs.tools found 1.11.3-precise-amd64 [LOG] 52.44750 INFO juju environs: filtering tools by series: precise [LOG] 52.44753 INFO juju environs: filtering tools by version: 1.11.3 [LOG] 52.44756 INFO juju environs/dummy: would pick tools from 1.11.3-precise-amd64 uniter_test.go:84: s.JujuConnSuite.SetUpTest(c) /home/tarmac/trees/src/launchpad.net/juju-core/juju/testing/conn.go:204: c.Assert(environs.Bootstrap(environ, constraints.Value{}), IsNil) ... value *errors.errorString = &errors.errorString{s:"environment is already bootstrapped"} ("environment is already bootstrapped") ---------------------------------------------------------------------- PANIC: uniter_test.go:88: UniterSuite.TearDownTest ... Panic: runtime error: invalid memory address or nil pointer dereference (PC=0x41175F) /usr/lib/go/src/pkg/runtime/proc.c:1443 in panic /usr/lib/go/src/pkg/runtime/runtime.c:128 in panicstring /usr/lib/go/src/pkg/runtime/thread_linux.c:199 in sigpanic /home/tarmac/trees/src/launchpad.net/juju-core/state/state.go:1215 in State.SetAdminMongoPassword /home/tarmac/trees/src/launchpad.net/juju-core/juju/testing/conn.go:230 in JujuConnSuite.tearDownConn /home/tarmac/trees/src/launchpad.net/juju-core/juju/testing/conn.go:134 in JujuConnSuite.TearDownTest uniter_test.go:91 in UniterSuite.TearDownTest ---------------------------------------------------------------------- PANIC: uniter_test.go:927: UniterSuite.TestSubordinateDying ... Panic: Fixture has panicked (see related PANIC) OOPS: 3 passed, 6 FAILED, 7 FIXTURE-PANICKED, 36 MISSED --- FAIL: TestPackage (170.38 seconds) FAIL FAIL launchpad.net/juju-core/worker/uniter 170.648s ok launchpad.net/juju-core/worker/uniter/charm 384.987s ok launchpad.net/juju-core/worker/uniter/hook 0.008s ok launchpad.net/juju-core/worker/uniter/jujuc 1.840s ok launchpad.net/juju-core/worker/uniter/relation 22.285s 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.