15:24:58 FAIL: provisioninginfo_test.go:255: withoutControllerSuite.TestProvisioningInfoWithEndpointBindings
15:24:58
15:24:58 [LOG] 0:00.017 DEBUG juju.environs.tools reading v1.* agent binaries
15:24:58 [LOG] 0:00.017 INFO juju.environs.testing uploading FAKE tools 2.8.1-xenial-amd64
15:24:58 [LOG] 0:00.021 INFO juju.environs.testing uploading FAKE tools 2.8.1-bionic-amd64
15:24:58 [LOG] 0:00.022 INFO juju.environs.testing uploading FAKE tools 2.8.1-focal-amd64
15:24:58 [LOG] 0:00.023 INFO juju.environs.tools Writing tools/streams/v1/index2.json
15:24:58 [LOG] 0:00.024 INFO juju.environs.tools Writing tools/streams/v1/index.json
15:24:58 [LOG] 0:00.024 INFO juju.environs.tools Writing tools/streams/v1/com.ubuntu.juju-released-tools.json
15:24:58 [LOG] 0:00.142 DEBUG juju.environs.tools reading v1.* agent binaries
15:24:58 [LOG] 0:00.142 INFO juju.environs.testing uploading FAKE tools 2.8.1-xenial-amd64
15:24:58 [LOG] 0:00.143 INFO juju.environs.testing uploading FAKE tools 2.8.1-bionic-amd64
15:24:58 [LOG] 0:00.145 INFO juju.environs.testing uploading FAKE tools 2.8.1-focal-amd64
15:24:58 [LOG] 0:00.147 DEBUG juju.environs.simplestreams skipping index "file:///tmp/jj-G0Y/check-9098863181582159447/173/tools/streams/v1/index2.json" because of missing information: "content-download" data not found
15:24:58 [LOG] 0:00.148 DEBUG juju.environs.simplestreams skipping index "file:///tmp/jj-G0Y/check-9098863181582159447/173/tools/streams/v1/index2.json" because of missing information: "content-download" data not found
15:24:58 [LOG] 0:00.148 DEBUG juju.environs.simplestreams skipping index "file:///tmp/jj-G0Y/check-9098863181582159447/173/tools/streams/v1/index2.json" because of missing information: "content-download" data not found
15:24:58 [LOG] 0:00.148 INFO juju.environs.tools Writing tools/streams/v1/index2.json
15:24:58 [LOG] 0:00.149 INFO juju.environs.tools Writing tools/streams/v1/index.json
15:24:58 [LOG] 0:00.149 INFO juju.environs.tools Writing tools/streams/v1/com.ubuntu.juju-devel-tools.json
15:24:58 [LOG] 0:00.258 DEBUG juju.environs.bootstrap model "controller" supports application/machine networks: true
15:24:58 [LOG] 0:00.258 DEBUG juju.environs.bootstrap network management by juju enabled: true
15:24:58 [LOG] 0:00.258 INFO cmd Loading image metadata
15:24:58 [LOG] 0:00.258 INFO juju.environs.bootstrap looking for bootstrap agent binaries: version=2.8.1
15:24:58 [LOG] 0:00.258 DEBUG juju.environs.tools finding agent binaries in stream: "released"
15:24:58 [LOG] 0:00.258 DEBUG juju.environs.tools reading agent binaries with major.minor version 2.8
15:24:58 [LOG] 0:00.258 DEBUG juju.environs.tools filtering agent binaries by version: 2.8.1
15:24:58 [LOG] 0:00.258 DEBUG juju.environs.tools filtering agent binaries by series: bionic
15:24:58 [LOG] 0:00.258 DEBUG juju.environs.tools filtering agent binaries by architecture: amd64
15:24:58 [LOG] 0:00.260 INFO juju.environs.bootstrap found 1 packaged agent binaries
15:24:58 [LOG] 0:00.260 INFO cmd Starting new instance for initial controller
15:24:58 [LOG] 0:00.260 INFO juju.provider.dummy would pick agent binaries from 2.8.1-bionic-amd64
15:24:58 [LOG] 0:00.260 INFO juju.provider.dummy creating bootstrap instance
15:24:58 [LOG] 0:00.260 INFO juju.environs.bootstrap newest version: 2.8.1
15:24:58 [LOG] 0:00.260 INFO juju.environs.bootstrap picked bootstrap agent binary version: 2.8.1
15:24:58 [LOG] 0:00.282 DEBUG juju.cloudconfig.instancecfg Setting numa ctl preference to false
15:24:58 [LOG] 0:00.282 DEBUG juju.mongo dialled mongodb server at "127.0.0.1:40661"
15:24:58 [LOG] 0:00.299 INFO juju.state using client-side transactions
15:24:58 [LOG] 0:00.499 INFO juju.state controller settings not found, early stage initialization assumed
15:24:58 [LOG] 0:00.499 DEBUG juju.state mongodb initialised
15:24:58 [LOG] 0:00.500 INFO juju.state starting standard state workers
15:24:58 [LOG] 0:00.500 INFO juju.state creating cloud image metadata storage
15:24:58 [LOG] 0:00.500 INFO juju.state started state for model-deadbeef-0bad-400d-8000-4b1d0d06f00d successfully
15:24:58 [LOG] 0:00.500 DEBUG juju.mongo dialled mongodb server at "127.0.0.1:40661"
15:24:58 [LOG] 0:00.501 INFO juju.state initializing controller model deadbeef-0bad-400d-8000-4b1d0d06f00d
15:24:58 [LOG] 0:00.596 INFO juju.state creating logs collection for deadbeef-0bad-400d-8000-4b1d0d06f00d, capped at 1 MiB
15:24:58 [LOG] 0:00.695 DEBUG juju.provider.dummy setting password for "admin" to "dummy-secret"
15:24:58 [LOG] 0:00.707 DEBUG juju.mongo dialled mongodb server at "127.0.0.1:40661"
15:24:58 [LOG] 0:00.722 INFO juju.state allwatcher loaded for model "deadbeef-0bad-400d-8000-4b1d0d06f00d" in 15.47908ms
15:24:58 [LOG] 0:00.743 DEBUG juju.environs ControllerInstances returned: [localhost]
15:24:58 [LOG] 0:00.746 DEBUG juju.api looked up localhost -> [127.0.0.1]
15:24:58 [LOG] 0:00.758 DEBUG juju.apiserver [1] API connection from 127.0.0.1:42038
15:24:58 [LOG] 0:00.759 DEBUG juju.api successfully dialed "wss://localhost:37333/model/deadbeef-0bad-400d-8000-4b1d0d06f00d/api"
15:24:58 [LOG] 0:00.759 INFO juju.api connection established to "wss://localhost:37333/model/deadbeef-0bad-400d-8000-4b1d0d06f00d/api"
15:24:58 [LOG] 0:00.759 DEBUG juju.apiserver <- [1] {"request-id":1,"type":"Admin","version":3,"request":"Login","params":"'params redacted'"}
15:24:58 [LOG] 0:00.815 DEBUG juju.apiserver model login: user admin has "superuser" for controller; "admin" for model deadbeef-0bad-400d-8000-4b1d0d06f00d
15:24:58 [LOG] 0:00.815 DEBUG juju.apiserver model login: user-admin for deadbeef-0bad-400d-8000-4b1d0d06f00d
15:24:58 [LOG] 0:00.817 DEBUG juju.apiserver -> [1] 57.710852ms {"request-id":1,"response":"'body redacted'"} Admin[""].Login
15:24:58 [LOG] 0:00.819 DEBUG juju.state setting apiHostPorts: [[localhost:37333]]
15:24:58 [LOG] 0:00.820 DEBUG juju.state setting apiHostPortsForAgents: [[localhost:37333]]
15:24:58 [LOG] 0:00.848 INFO juju.state new machine "0" has preferred addresses: private "", public ""
15:24:58 [LOG] 0:00.917 DEBUG juju.mongo dialled mongodb server at "127.0.0.1:40661"
15:24:58 [LOG] 0:00.926 INFO juju.state new machine "1" has preferred addresses: private "", public ""
15:24:58 [LOG] 0:00.987 INFO juju.state new machine "2" has preferred addresses: private "", public ""
15:24:58 [LOG] 0:01.069 INFO juju.state new machine "3" has preferred addresses: private "", public ""
15:24:58 [LOG] 0:01.155 INFO juju.state new machine "4" has preferred addresses: private "", public ""
15:24:58 [LOG] 0:01.359 INFO juju.state new machine "5" has preferred addresses: private "", public ""
15:24:58 [LOG] 0:01.497 DEBUG juju.storage resource catalog entry created with id "9cae1c1aa6db0921f3dce9127e777a0d603ff3623128e305c1541c3bfa80b56f617f9006a4e980b83b37590e7406dc30"
15:24:58 [LOG] 0:01.527 DEBUG juju.storage managed resource entry created with path "buckets/deadbeef-0bad-400d-8000-4b1d0d06f00d/charms/local:quantal/wordpress-3-c0003044d4b99b5601facadd33c2b8d0d620c3284596e8bd6363d5a542dc5217" -> "9cae1c1aa6db0921f3dce9127e777a0d603ff3623128e305c1541c3bfa80b56f617f9006a4e980b83b37590e7406dc30"
15:24:58 [LOG] 0:01.587 DEBUG juju.state merged endpoint bindings modified: true, default: map[admin-api:0 cache:0 db:0 db-client:0 foo-bar:0 logging-dir:0 monitoring-port:0 url:0], current: map[], mergeWith: map[db:2 url:1], after: map[:0 admin-api:0 cache:0 db:2 db-client:0 foo-bar:0 logging-dir:0 monitoring-port:0 url:1]
15:24:58 [LOG] 0:01.680 DEBUG juju.state new application "wordpress" added to backing state
15:24:58 [LOG] 0:02.102 DEBUG juju.state.cloudimagemetadata searching for image metadata cloudimagemetadata.MetadataFilter{Region:"", Series:[]string{"quantal"}, Arches:[]string{"amd64", "i386", "armhf", "arm64", "ppc64el", "s390x"}, Stream:"daily", VirtType:"", RootStorageType:""}
15:24:58 [LOG] 0:02.103 DEBUG juju.apiserver.provisioner got from controller 0 metadata
15:24:58 [LOG] 0:02.103 DEBUG juju.environs obtained image datasource "default ubuntu cloud images"
15:24:58 [LOG] 0:02.103 DEBUG juju.apiserver.provisioner looking in data source default ubuntu cloud images
15:24:58 [LOG] 0:02.103 WARNING juju.apiserver.provisioner encountered invalid URL "http://cloud-images.ubuntu.com/daily/streams/v1/index.sjson" not found while getting published images metadata from default ubuntu cloud images
15:24:58 [LOG] 0:02.103 DEBUG juju.state.cloudimagemetadata searching for image metadata cloudimagemetadata.MetadataFilter{Region:"", Series:[]string{"quantal"}, Arches:[]string{"amd64", "i386", "armhf", "arm64", "ppc64el", "s390x"}, Stream:"daily", VirtType:"", RootStorageType:""}
15:24:58 [LOG] 0:02.104 DEBUG juju.apiserver.provisioner got from data sources 0 metadata
15:24:58 [LOG] 0:02.104 DEBUG juju.apiserver.provisioner available image metadata for provisioning: []
15:24:58 provisioninginfo_test.go:332:
15:24:58 c.Assert(result, jc.DeepEquals, expected)
15:24:58 ... obtained params.ProvisioningInfoResultsV10 = params.ProvisioningInfoResultsV10{Results:[]params.ProvisioningInfoResultV10{params.ProvisioningInfoResultV10{Result:(*params.ProvisioningInfoV10)(0xc000225e00), Error:<nil>}}}
15:24:58 ... expected params.ProvisioningInfoResultsV10 = params.ProvisioningInfoResultsV10{Results:[]params.ProvisioningInfoResultV10{params.ProvisioningInfoResultV10{Result:(*params.ProvisioningInfoV10)(0xc000170780), Error:<nil>}}}
15:24:58 ... mismatch at (*.Results[0].Result).ProvisioningNetworkTopology.SpaceSubnets["space2"][0]: unequal; obtained "subnet-2"; expected "subnet-1"
15:24:58
15:24:58 [LOG] 0:02.127 DEBUG juju.api RPC connection died
15:24:58 [LOG] 0:02.127 INFO juju.provider.dummy reset model
15:24:58 [LOG] 0:02.127 DEBUG juju.provider.dummy closing httpServer
15:24:58 [LOG] 0:02.127 DEBUG juju.provider.dummy stopping apiServer
15:24:58 [LOG] 0:02.127 DEBUG juju.rpc error closing codec: write tcp 127.0.0.1:37333->127.0.0.1:42038: write: broken pipe
15:24:58 [LOG] 0:02.127 DEBUG juju.apiserver [1] user-admin API connection terminated after 1.369159718s
15:24:58 [LOG] 0:02.136 DEBUG juju.provider.dummy stopping modelCache worker
15:24:58 [LOG] 0:02.137 DEBUG juju.provider.dummy stopping multiWatcherWorker worker
15:24:58 [LOG] 0:02.137 DEBUG juju.provider.dummy closing apiStatePool
15:24:58 [LOG] 0:02.138 DEBUG juju.state closed state without error
15:24:58 [LOG] 0:02.138 DEBUG juju.provider.dummy resetting MgoServer
15:24:58 [LOG] 0:02.147 INFO juju.testing reset successfully reset admin password
15:24:58 [LOG] 0:02.291 INFO juju.testing reset successfully reset admin password
15:24:58
15:24:58 ----------------------------------------------------------------------
Had this twice