Unit test failure: ContainerSetupSuite.TestContainerProvisionerStarted
Affects | Status | Importance | Assigned to | Milestone | |
---|---|---|---|---|---|
Canonical Juju |
Fix Released
|
Low
|
Joseph Phillips |
Bug Description
As seen here:
http://
FAIL: container_
[LOG] 0:00.003 DEBUG juju.environs.tools reading v1.* agent binaries
[LOG] 0:00.003 INFO juju.environs.
[LOG] 0:00.008 INFO juju.environs.
[LOG] 0:00.008 INFO juju.environs.
[LOG] 0:00.008 INFO juju.environs.
[LOG] 0:00.010 INFO juju.environs.tools Writing tools/streams/
[LOG] 0:00.010 INFO juju.environs.tools Writing tools/streams/
[LOG] 0:00.010 INFO juju.environs.tools Writing tools/streams/
[LOG] 0:00.019 DEBUG juju.environs.tools reading v1.* agent binaries
[LOG] 0:00.019 INFO juju.environs.
[LOG] 0:00.020 INFO juju.environs.
[LOG] 0:00.020 INFO juju.environs.
[LOG] 0:00.021 INFO juju.environs.
[LOG] 0:00.022 DEBUG juju.environs.
[LOG] 0:00.022 DEBUG juju.environs.
[LOG] 0:00.022 DEBUG juju.environs.
[LOG] 0:00.023 INFO juju.environs.tools Writing tools/streams/
[LOG] 0:00.023 INFO juju.environs.tools Writing tools/streams/
[LOG] 0:00.023 INFO juju.environs.tools Writing tools/streams/
[LOG] 0:00.034 DEBUG juju.environs.
[LOG] 0:00.034 DEBUG juju.environs.
[LOG] 0:00.034 INFO cmd Loading image metadata
[LOG] 0:00.034 INFO juju.environs.
[LOG] 0:00.034 DEBUG juju.environs.tools finding agent binaries in stream: "devel, proposed, released"
[LOG] 0:00.034 DEBUG juju.environs.tools reading agent binaries with major.minor version 2.5
[LOG] 0:00.034 DEBUG juju.environs.tools filtering agent binaries by version: 2.5-beta1
[LOG] 0:00.034 DEBUG juju.environs.tools filtering agent binaries by architecture: amd64
[LOG] 0:00.036 DEBUG juju.environs.
[LOG] 0:00.037 INFO juju.environs.
[LOG] 0:00.037 INFO cmd Starting new instance for initial controller
[LOG] 0:00.037 INFO juju.provider.dummy would pick agent binaries from 2.5-beta1-
[LOG] 0:00.037 INFO juju.provider.dummy creating bootstrap instance
[LOG] 0:00.037 INFO juju.environs.
[LOG] 0:00.037 INFO juju.environs.
[LOG] 0:00.057 DEBUG juju.cloudconfi
[LOG] 0:00.058 DEBUG juju.mongo dialled mongodb server at "127.0.0.1:43266"
[LOG] 0:00.087 DEBUG juju.state mongodb initialised
[LOG] 0:00.087 INFO juju.state running state anonymously; using unique store id
[LOG] 0:00.087 INFO juju.state starting standard state workers
[LOG] 0:00.087 DEBUG juju.mongo dialled mongodb server at "127.0.0.1:43266"
[LOG] 0:00.087 INFO juju.state creating cloud image metadata storage
[LOG] 0:00.087 INFO juju.state started state for model-deadbeef-
[LOG] 0:00.087 DEBUG juju.state.presence using $bit operations with Mongo 3.2.15
[LOG] 0:00.087 DEBUG juju.mongo dialled mongodb server at "127.0.0.1:43266"
[LOG] 0:00.087 DEBUG juju.mongo dialled mongodb server at "127.0.0.1:43266"
[LOG] 0:00.087 DEBUG juju.mongo dialled mongodb server at "127.0.0.1:43266"
[LOG] 0:00.091 INFO juju.state initializing controller model deadbeef-
[LOG] 0:00.202 DEBUG juju.provider.dummy setting password for "admin" to "dummy-secret"
[LOG] 0:00.210 WARNING juju.apiserver.
[LOG] 0:00.216 DEBUG juju.mongo dialled mongodb server at "127.0.0.1:43266"
[LOG] 0:00.237 DEBUG juju.mongo dialled mongodb server at "127.0.0.1:43266"
[LOG] 0:00.252 INFO juju.state starting standard state workers
[LOG] 0:00.252 INFO juju.state creating cloud image metadata storage
[LOG] 0:00.252 INFO juju.state started state for model-deadbeef-
[LOG] 0:00.254 DEBUG juju.state.presence using $bit operations with Mongo 3.2.15
[LOG] 0:00.254 DEBUG juju.mongo dialled mongodb server at "127.0.0.1:43266"
[LOG] 0:00.254 DEBUG juju.mongo dialled mongodb server at "127.0.0.1:43266"
[LOG] 0:00.254 DEBUG juju.mongo dialled mongodb server at "127.0.0.1:43266"
[LOG] 0:00.255 DEBUG juju.mongo dialled mongodb server at "127.0.0.1:43266"
[LOG] 0:00.285 DEBUG juju.environs ControllerInstances returned: [localhost]
[LOG] 0:00.285 DEBUG juju.api looked up localhost -> [127.0.0.1]
[LOG] 0:00.295 DEBUG juju.apiserver [1] API connection from 127.0.0.1:53792
[LOG] 0:00.295 DEBUG juju.api successfully dialed "wss://
[LOG] 0:00.295 INFO juju.api connection established to "wss://
[LOG] 0:00.295 DEBUG juju.apiserver <- [1] {"request-
[LOG] 0:00.300 DEBUG juju.apiserver model login: user admin has "superuser" for controller; "admin" for model deadbeef-
[LOG] 0:00.300 DEBUG juju.apiserver model login: user-admin for deadbeef-
[LOG] 0:00.301 DEBUG juju.apiserver -> [1] 5.411459ms {"request-
[LOG] 0:00.302 DEBUG juju.state setting apiHostPorts: [[localhost:42279]]
[LOG] 0:00.302 DEBUG juju.state setting apiHostPortsFor
[LOG] 0:00.315 DEBUG juju.state.
[LOG] 0:00.324 INFO juju.state new machine "0" has preferred addresses: private "localhost", public "localhost"
[LOG] 0:00.357 DEBUG juju.environs ControllerInstances returned: [localhost]
[LOG] 0:00.358 DEBUG juju.api looked up localhost -> [127.0.0.1]
[LOG] 0:00.367 DEBUG juju.apiserver [2] API connection from 127.0.0.1:53828
[LOG] 0:00.367 DEBUG juju.api successfully dialed "wss://
[LOG] 0:00.367 INFO juju.api connection established to "wss://
[LOG] 0:00.367 DEBUG juju.apiserver <- [2] {"request-
[LOG] 0:01.232 INFO juju.apiserver.
[LOG] 0:01.232 DEBUG juju.apiserver model login: machine-0 for deadbeef-
[LOG] 0:01.233 DEBUG juju.apiserver -> [2] 865.35284ms {"request-
API: login as "machine-0" successful
[LOG] 0:01.234 DEBUG juju.environs ControllerInstances returned: [localhost]
[LOG] 0:01.234 DEBUG juju.apiserver <- [2] machine-0 {"request-
[LOG] 0:01.245 INFO juju.state new machine "1" has preferred addresses: private "", public ""
[LOG] 0:01.262 DEBUG juju.apiserver -> [2] machine-0 27.393335ms {"request-
[LOG] 0:01.262 DEBUG juju.apiserver <- [2] machine-0 {"request-
[LOG] 0:01.262 DEBUG juju.apiserver <- [2] machine-0 {"request-
[LOG] 0:01.263 DEBUG juju.network selected "localhost" as controller address, using scope ""
[LOG] 0:01.263 DEBUG juju.apiserver -> [2] machine-0 1.11932ms {"request-
[LOG] 0:01.263 DEBUG juju.apiserver <- [2] machine-0 {"request-
[LOG] 0:01.266 DEBUG juju.apiserver -> [2] machine-0 2.988701ms {"request-
[LOG] 0:01.267 DEBUG juju.apiserver <- [2] machine-0 {"request-
[LOG] 0:01.267 DEBUG juju.apiserver -> [2] machine-0 329.226µs {"request-
[LOG] 0:01.267 DEBUG juju.apiserver <- [2] machine-0 {"request-
[LOG] 0:01.267 DEBUG juju.apiserver -> [2] machine-0 50.881µs {"request-
[LOG] 0:01.267 DEBUG juju.apiserver <- [2] machine-0 {"request-
[LOG] 0:01.268 DEBUG juju.apiserver -> [2] machine-0 650.247µs {"request-
[LOG] 0:01.268 DEBUG juju.apiserver <- [2] machine-0 {"request-
[LOG] 0:01.268 DEBUG juju.apiserver -> [2] machine-0 76.844µs {"request-
[LOG] 0:01.268 DEBUG juju.apiserver <- [2] machine-0 {"request-
[LOG] 0:01.269 DEBUG juju.apiserver <- [2] machine-0 {"request-
[LOG] 0:01.269 DEBUG juju.apiserver <- [2] machine-0 {"request-
[LOG] 0:01.269 DEBUG juju.apiserver -> [2] machine-0 789.413µs {"request-
[LOG] 0:01.270 DEBUG juju.apiserver <- [2] machine-0 {"request-
[LOG] 0:01.270 DEBUG juju.apiserver -> [2] machine-0 297.206µs {"request-
[LOG] 0:01.270 DEBUG juju.apiserver <- [2] machine-0 {"request-
[LOG] 0:01.271 DEBUG juju.apiserver <- [2] machine-0 {"request-
[LOG] 0:01.271 DEBUG juju.apiserver -> [2] machine-0 694.136µs {"request-
[LOG] 0:01.286 DEBUG juju.apiserver -> [2] machine-0 14.966707ms {"request-
[LOG] 0:01.286 DEBUG juju.apiserver <- [2] machine-0 {"request-
[LOG] 0:01.286 DEBUG juju.apiserver -> [2] machine-0 569.984µs {"request-
[LOG] 0:01.287 DEBUG juju.apiserver <- [2] machine-0 {"request-
[LOG] 0:01.287 DEBUG juju.apiserver -> [2] machine-0 540.073µs {"request-
[LOG] 0:01.287 DEBUG juju.apiserver <- [2] machine-0 {"request-
[LOG] 0:01.291 DEBUG juju.apiserver -> [2] machine-0 3.613105ms {"request-
[LOG] 0:01.291 INFO juju.provisioner found machine pending provisioning id:1, details:1
[LOG] 0:01.291 DEBUG juju.apiserver <- [2] machine-0 {"request-
[LOG] 0:01.292 DEBUG juju.apiserver -> [2] machine-0 747.794µs {"request-
[LOG] 0:01.292 INFO juju.provisioner machine 0 already started as instance "localhost"
[LOG] 0:01.292 DEBUG juju.apiserver <- [2] machine-0 {"request-
[LOG] 0:01.293 DEBUG juju.apiserver -> [2] machine-0 559.959µs {"request-
[LOG] 0:01.293 DEBUG juju.apiserver <- [2] machine-0 {"request-
[LOG] 0:01.293 DEBUG juju.apiserver -> [2] machine-0 443.243µs {"request-
[LOG] 0:01.294 INFO juju.provisioner provisioner-
[LOG] 0:01.294 DEBUG juju.apiserver <- [2] machine-0 {"request-
[LOG] 0:01.294 DEBUG juju.apiserver -> [2] machine-0 283.716µs {"request-
[LOG] 0:01.294 DEBUG juju.apiserver <- [2] machine-0 {"request-
[LOG] 0:01.295 DEBUG juju.apiserver -> [2] machine-0 576.68µs {"request-
[LOG] 0:01.295 DEBUG juju.apiserver <- [2] machine-0 {"request-
[LOG] 0:01.306 DEBUG juju.state.
[LOG] 0:01.306 DEBUG juju.apiserver.
[LOG] 0:01.306 DEBUG juju.state.
[LOG] 0:01.306 DEBUG juju.apiserver.
[LOG] 0:01.306 DEBUG juju.apiserver.
[LOG] 0:01.307 DEBUG juju.apiserver -> [2] machine-0 11.389199ms {"request-
[LOG] 0:01.307 DEBUG juju.apiserver <- [2] machine-0 {"request-
[LOG] 0:01.310 INFO juju.apiserver.
[LOG] 0:01.310 DEBUG juju.apiserver -> [2] machine-0 2.781088ms {"request-
[LOG] 0:01.310 DEBUG juju.apiserver <- [2] machine-0 {"request-
[LOG] 0:01.315 DEBUG juju.environs.tools finding agent binaries in stream: "devel, proposed, released"
[LOG] 0:01.315 DEBUG juju.environs.tools reading agent binaries with major version -1
[LOG] 0:01.315 DEBUG juju.environs.tools filtering agent binaries by version: 2.5-beta1
[LOG] 0:01.315 DEBUG juju.environs.tools filtering agent binaries by series: bionic
[LOG] 0:01.315 DEBUG juju.environs.tools filtering agent binaries by architecture: amd64
[LOG] 0:01.316 DEBUG juju.environs.
[LOG] 0:01.317 DEBUG juju.apiserver -> [2] machine-0 7.229839ms {"request-
[LOG] 0:01.318 DEBUG juju.apiserver <- [2] machine-0 {"request-
[LOG] 0:01.318 DEBUG juju.apiserver.
[LOG] 0:01.321 DEBUG juju.apiserver -> [2] machine-0 3.344949ms {"request-
[LOG] 0:01.321 INFO juju.provisioner trying machine 1 StartInstance in availability zone zone1
[LOG] 0:01.321 INFO juju.provider.dummy dummy startinstance, machine 1
[LOG] 0:01.321 INFO juju.provider.dummy would pick agent binaries from 2.5-beta1-
[LOG] 0:01.321 DEBUG juju.provider.dummy StartInstance addresses: [controller-0.dns local-machine:
[LOG] 0:01.321 DEBUG juju.apiserver <- [2] machine-0 {"request-
[LOG] 0:01.322 DEBUG juju.state no device addresses to set
[LOG] 0:01.332 DEBUG juju.apiserver -> [2] machine-0 10.513793ms {"request-
[LOG] 0:01.332 INFO juju.provisioner started machine 1 as instance controller-0 with hardware "arch=amd64 cores=1 mem=4096M root-disk=8192M availability-
[LOG] 0:01.333 DEBUG juju.apiserver <- [2] machine-0 {"request-
[LOG] 0:01.333 DEBUG juju.apiserver -> [2] machine-0 402.309µs {"request-
[LOG] 0:01.333 DEBUG juju.apiserver <- [2] machine-0 {"request-
[LOG] 0:01.339 DEBUG juju.apiserver -> [2] machine-0 5.296024ms {"request-
[LOG] 0:01.339 DEBUG juju.environs ControllerInstances returned: [localhost]
[LOG] 0:01.340 DEBUG juju.apiserver <- [2] machine-0 {"request-
[LOG] 0:01.341 DEBUG juju.apiserver -> [2] machine-0 1.203751ms {"request-
[LOG] 0:01.341 DEBUG juju.apiserver <- [2] machine-0 {"request-
[LOG] 0:01.343 INFO juju.state new machine "1/kvm/0" has preferred addresses: private "", public ""
[LOG] 0:01.507 DEBUG juju.apiserver -> [2] machine-0 238.71208ms {"request-
[LOG] 0:01.507 DEBUG juju.apiserver <- [2] machine-0 {"request-
[LOG] 0:01.507 DEBUG juju.apiserver <- [2] machine-0 {"request-
[LOG] 0:01.508 DEBUG juju.apiserver -> [2] machine-0 784.355µs {"request-
[LOG] 0:01.508 DEBUG juju.apiserver <- [2] machine-0 {"request-
[LOG] 0:01.509 DEBUG juju.apiserver -> [2] machine-0 479.791µs {"request-
[LOG] 0:01.509 DEBUG juju.apiserver <- [2] machine-0 {"request-
[LOG] 0:01.510 DEBUG juju.apiserver -> [2] machine-0 485.545µs {"request-
[LOG] 0:01.510 DEBUG juju.apiserver <- [2] machine-0 {"request-
[LOG] 0:01.510 DEBUG juju.apiserver -> [2] machine-0 436.334µs {"request-
[LOG] 0:01.510 DEBUG juju.apiserver <- [2] machine-0 {"request-
[LOG] 0:01.511 DEBUG juju.apiserver -> [2] machine-0 449.17µs {"request-
[LOG] 0:01.511 INFO juju.provisioner provisioner-
[LOG] 0:01.511 INFO juju.provisioner stopping known instances [0xc4206ae580]
[LOG] 0:01.511 INFO juju.provisioner removing dead machine "1"
[LOG] 0:01.511 DEBUG juju.apiserver <- [2] machine-0 {"request-
[LOG] 0:01.519 DEBUG juju.apiserver -> [2] machine-0 7.347934ms {"request-
container_
s.assertCon
container_
// the container worker should have created the provisioner
c.Assert(
... obtained bool = false
[LOG] 0:01.535 INFO juju.provisioner Shutting down provisioner task machine-0
[LOG] 0:01.535 DEBUG juju.apiserver <- [2] machine-0 {"request-
[LOG] 0:01.535 DEBUG juju.apiserver <- [2] machine-0 {"request-
[LOG] 0:01.535 DEBUG juju.apiserver <- [2] machine-0 {"request-
[LOG] 0:01.535 DEBUG juju.apiserver -> [2] machine-0 193.361µs {"request-
[LOG] 0:01.535 DEBUG juju.apiserver -> [2] machine-0 266.68619ms {"request-
[LOG] 0:01.535 DEBUG juju.apiserver -> [2] machine-0 124.721µs {"request-
[LOG] 0:01.535 DEBUG juju.apiserver -> [2] machine-0 98.492µs {"request-
[LOG] 0:01.535 DEBUG juju.apiserver -> [2] machine-0 273.283538ms {"request-
[LOG] 0:01.536 DEBUG juju.apiserver -> [2] machine-0 28.197787ms {"request-
[LOG] 0:01.536 DEBUG juju.api RPC connection died
[LOG] 0:01.536 DEBUG juju.api RPC connection died
[LOG] 0:01.536 DEBUG juju.apiserver -> [2] machine-0 194.835694ms {"request-
[LOG] 0:01.536 DEBUG juju.rpc error closing codec: write tcp 127.0.0.
[LOG] 0:01.536 INFO juju.apiserver.
[LOG] 0:01.536 DEBUG juju.state closed state without error
[LOG] 0:01.536 INFO juju.provider.dummy reset model
[LOG] 0:01.536 DEBUG juju.apiserver [2] machine-0 API connection terminated after 1.169604529s
[LOG] 0:01.536 DEBUG juju.provider.dummy closing httpServer
[LOG] 0:01.536 DEBUG juju.provider.dummy stopping apiServer
[LOG] 0:01.536 DEBUG juju.rpc error closing codec: write tcp 127.0.0.
[LOG] 0:01.536 DEBUG juju.apiserver [1] user-admin API connection terminated after 1.241766487s
[LOG] 0:01.543 DEBUG juju.provider.dummy closing apiStatePool
[LOG] 0:01.544 DEBUG juju.state closed state without error
[LOG] 0:01.544 DEBUG juju.provider.dummy resetting MgoServer
[LOG] 0:01.545 INFO juju.testing reset successfully reset admin password
[LOG] 0:01.547 INFO juju.testing reset successfully reset admin password
Changed in juju: | |
status: | Triaged → Incomplete |
Changed in juju: | |
status: | Fix Committed → Fix Released |
You can get (un)lucky and replicate this locally.