juju-core

sporadic test failure: FilterSuite.TestMeterStatusEvents

Bug #1436507 reported by Eric Snow on 2015-03-25

This bug affects 1 person

Affects		Status	Importance	Assigned to	Milestone
	juju-core	Invalid	Medium	Casey Marshall

Bug Description

In CI on vivid, 1.24:

See http://data.vapour.ws/juju-ci/products/version-2481/run-unit-tests-vivid-amd64/build-293/consoleText.

This appears so far to be sporadic (and probably new).

----------------------------------------------------------------------
FAIL: filter_test.go:580: FilterSuite.TestMeterStatusEvents

[LOG] 0:00.068 DEBUG juju.environs.configstore Made dir /tmp/check-5577006791947779410/37/home/ubuntu/.juju/environments
[LOG] 0:00.372 DEBUG juju.environs.configstore writing jenv file
[LOG] 0:00.373 DEBUG juju.environs.configstore writing jenv file to /tmp/check-5577006791947779410/37/home/ubuntu/.juju/environments/dummyenv.jenv
[LOG] 0:00.373 DEBUG juju.environs.tools reading v1.* tools
[LOG] 0:00.373 INFO juju.environs.testing uploading FAKE tools 1.24-alpha1-trusty-amd64
[LOG] 0:00.374 INFO juju.environs.testing uploading FAKE tools 1.24-alpha1-precise-amd64
[LOG] 0:00.376 INFO juju.environs.testing uploading FAKE tools 1.24-alpha1-vivid-amd64
[LOG] 0:00.378 DEBUG juju.environs.tools no architecture specified when finding tools, looking for any
[LOG] 0:00.378 DEBUG juju.environs.tools no series specified when finding tools, looking for any
[LOG] 0:00.378 DEBUG juju.environs.simplestreams searching for metadata in datasource "existing metadata"
[LOG] 0:00.378 DEBUG juju.environs.simplestreams fetchData failed for "file:///tmp/check-5577006791947779410/40/tools/streams/v1/index2.sjson": stat /tmp/check-5577006791947779410/40/tools/streams/v1/index2.sjson: no such file or directory
[LOG] 0:00.378 DEBUG juju.environs.simplestreams streams/v1/index2.sjson not found, trying legacy index file
[LOG] 0:00.378 DEBUG juju.environs.simplestreams fetchData failed for "file:///tmp/check-5577006791947779410/40/tools/streams/v1/index.sjson": stat /tmp/check-5577006791947779410/40/tools/streams/v1/index.sjson: no such file or directory
[LOG] 0:00.378 DEBUG juju.environs.simplestreams cannot load index "file:///tmp/check-5577006791947779410/40/tools/streams/v1/index.sjson": invalid URL "file:///tmp/check-5577006791947779410/40/tools/streams/v1/index.sjson" not found
[LOG] 0:00.378 DEBUG juju.environs.simplestreams fetchData failed for "file:///tmp/check-5577006791947779410/40/tools/streams/v1/index2.json": stat /tmp/check-5577006791947779410/40/tools/streams/v1/index2.json: no such file or directory
[LOG] 0:00.378 DEBUG juju.environs.simplestreams streams/v1/index2.json not found, trying legacy index file
[LOG] 0:00.378 DEBUG juju.environs.simplestreams fetchData failed for "file:///tmp/check-5577006791947779410/40/tools/streams/v1/index.json": stat /tmp/check-5577006791947779410/40/tools/streams/v1/index.json: no such file or directory
[LOG] 0:00.378 DEBUG juju.environs.simplestreams cannot load index "file:///tmp/check-5577006791947779410/40/tools/streams/v1/index.json": invalid URL "file:///tmp/check-5577006791947779410/40/tools/streams/v1/index.json" not found
[LOG] 0:00.378 DEBUG juju.environs.tools no architecture specified when finding tools, looking for any
[LOG] 0:00.378 DEBUG juju.environs.tools no series specified when finding tools, looking for any
[LOG] 0:00.378 DEBUG juju.environs.simplestreams searching for metadata in datasource "existing metadata"
[LOG] 0:00.378 DEBUG juju.environs.simplestreams fetchData failed for "file:///tmp/check-5577006791947779410/40/tools/streams/v1/index2.sjson": stat /tmp/check-5577006791947779410/40/tools/streams/v1/index2.sjson: no such file or directory
[LOG] 0:00.378 DEBUG juju.environs.simplestreams streams/v1/index2.sjson not found, trying legacy index file
[LOG] 0:00.378 DEBUG juju.environs.simplestreams fetchData failed for "file:///tmp/check-5577006791947779410/40/tools/streams/v1/index.sjson": stat /tmp/check-5577006791947779410/40/tools/streams/v1/index.sjson: no such file or directory
[LOG] 0:00.378 DEBUG juju.environs.simplestreams cannot load index "file:///tmp/check-5577006791947779410/40/tools/streams/v1/index.sjson": invalid URL "file:///tmp/check-5577006791947779410/40/tools/streams/v1/index.sjson" not found
[LOG] 0:00.378 DEBUG juju.environs.simplestreams fetchData failed for "file:///tmp/check-5577006791947779410/40/tools/streams/v1/index2.json": stat /tmp/check-5577006791947779410/40/tools/streams/v1/index2.json: no such file or directory
[LOG] 0:00.378 DEBUG juju.environs.simplestreams streams/v1/index2.json not found, trying legacy index file
[LOG] 0:00.378 DEBUG juju.environs.simplestreams fetchData failed for "file:///tmp/check-5577006791947779410/40/tools/streams/v1/index.json": stat /tmp/check-5577006791947779410/40/tools/streams/v1/index.json: no such file or directory
[LOG] 0:00.378 DEBUG juju.environs.simplestreams cannot load index "file:///tmp/check-5577006791947779410/40/tools/streams/v1/index.json": invalid URL "file:///tmp/check-5577006791947779410/40/tools/streams/v1/index.json" not found
[LOG] 0:00.378 DEBUG juju.environs.tools no architecture specified when finding tools, looking for any
[LOG] 0:00.378 DEBUG juju.environs.tools no series specified when finding tools, looking for any
[LOG] 0:00.378 DEBUG juju.environs.simplestreams searching for metadata in datasource "existing metadata"
[LOG] 0:00.378 DEBUG juju.environs.simplestreams fetchData failed for "file:///tmp/check-5577006791947779410/40/tools/streams/v1/index2.sjson": stat /tmp/check-5577006791947779410/40/tools/streams/v1/index2.sjson: no such file or directory
[LOG] 0:00.378 DEBUG juju.environs.simplestreams streams/v1/index2.sjson not found, trying legacy index file
[LOG] 0:00.378 DEBUG juju.environs.simplestreams fetchData failed for "file:///tmp/check-5577006791947779410/40/tools/streams/v1/index.sjson": stat /tmp/check-5577006791947779410/40/tools/streams/v1/index.sjson: no such file or directory
[LOG] 0:00.378 DEBUG juju.environs.simplestreams cannot load index "file:///tmp/check-5577006791947779410/40/tools/streams/v1/index.sjson": invalid URL "file:///tmp/check-5577006791947779410/40/tools/streams/v1/index.sjson" not found
[LOG] 0:00.378 DEBUG juju.environs.simplestreams fetchData failed for "file:///tmp/check-5577006791947779410/40/tools/streams/v1/index2.json": stat /tmp/check-5577006791947779410/40/tools/streams/v1/index2.json: no such file or directory
[LOG] 0:00.378 DEBUG juju.environs.simplestreams streams/v1/index2.json not found, trying legacy index file
[LOG] 0:00.378 DEBUG juju.environs.simplestreams fetchData failed for "file:///tmp/check-5577006791947779410/40/tools/streams/v1/index.json": stat /tmp/check-5577006791947779410/40/tools/streams/v1/index.json: no such file or directory
[LOG] 0:00.378 DEBUG juju.environs.simplestreams cannot load index "file:///tmp/check-5577006791947779410/40/tools/streams/v1/index.json": invalid URL "file:///tmp/check-5577006791947779410/40/tools/streams/v1/index.json" not found
[LOG] 0:00.378 DEBUG juju.environs.tools no architecture specified when finding tools, looking for any
[LOG] 0:00.378 DEBUG juju.environs.tools no series specified when finding tools, looking for any
[LOG] 0:00.379 DEBUG juju.environs.simplestreams searching for metadata in datasource "existing metadata"
[LOG] 0:00.379 DEBUG juju.environs.simplestreams fetchData failed for "file:///tmp/check-5577006791947779410/40/tools/streams/v1/index2.sjson": stat /tmp/check-5577006791947779410/40/tools/streams/v1/index2.sjson: no such file or directory
[LOG] 0:00.379 DEBUG juju.environs.simplestreams streams/v1/index2.sjson not found, trying legacy index file
[LOG] 0:00.379 DEBUG juju.environs.simplestreams fetchData failed for "file:///tmp/check-5577006791947779410/40/tools/streams/v1/index.sjson": stat /tmp/check-5577006791947779410/40/tools/streams/v1/index.sjson: no such file or directory
[LOG] 0:00.379 DEBUG juju.environs.simplestreams cannot load index "file:///tmp/check-5577006791947779410/40/tools/streams/v1/index.sjson": invalid URL "file:///tmp/check-5577006791947779410/40/tools/streams/v1/index.sjson" not found
[LOG] 0:00.379 DEBUG juju.environs.simplestreams fetchData failed for "file:///tmp/check-5577006791947779410/40/tools/streams/v1/index2.json": stat /tmp/check-5577006791947779410/40/tools/streams/v1/index2.json: no such file or directory
[LOG] 0:00.379 DEBUG juju.environs.simplestreams streams/v1/index2.json not found, trying legacy index file
[LOG] 0:00.379 DEBUG juju.environs.simplestreams fetchData failed for "file:///tmp/check-5577006791947779410/40/tools/streams/v1/index.json": stat /tmp/check-5577006791947779410/40/tools/streams/v1/index.json: no such file or directory
[LOG] 0:00.379 DEBUG juju.environs.simplestreams cannot load index "file:///tmp/check-5577006791947779410/40/tools/streams/v1/index.json": invalid URL "file:///tmp/check-5577006791947779410/40/tools/streams/v1/index.json" not found
[LOG] 0:00.379 INFO juju.environs.tools Writing tools/streams/v1/index2.json
[LOG] 0:00.379 INFO juju.environs.tools Writing tools/streams/v1/index.json
[LOG] 0:00.379 INFO juju.environs.tools Writing tools/streams/v1/com.ubuntu.juju-released-tools.json
[LOG] 0:00.379 DEBUG juju.environs.tools no architecture specified when finding tools, looking for any
[LOG] 0:00.379 DEBUG juju.environs.tools no series specified when finding tools, looking for any
[LOG] 0:00.379 DEBUG juju.environs.simplestreams searching for metadata in datasource "existing metadata"
[LOG] 0:00.379 DEBUG juju.environs.simplestreams fetchData failed for "file:///tmp/check-5577006791947779410/40/tools/streams/v1/index2.sjson": stat /tmp/check-5577006791947779410/40/tools/streams/v1/index2.sjson: no such file or directory
[LOG] 0:00.379 DEBUG juju.environs.simplestreams streams/v1/index2.sjson not found, trying legacy index file
[LOG] 0:00.379 DEBUG juju.environs.simplestreams fetchData failed for "file:///tmp/check-5577006791947779410/40/tools/streams/v1/index.sjson": stat /tmp/check-5577006791947779410/40/tools/streams/v1/index.sjson: no such file or directory
[LOG] 0:00.379 DEBUG juju.environs.simplestreams cannot load index "file:///tmp/check-5577006791947779410/40/tools/streams/v1/index.sjson": invalid URL "file:///tmp/check-5577006791947779410/40/tools/streams/v1/index.sjson" not found
[LOG] 0:00.380 DEBUG juju.environs.simplestreams fetchData failed for "file:///tmp/check-5577006791947779410/40/tools/streams/v1/mirrors.json": stat /tmp/check-5577006791947779410/40/tools/streams/v1/mirrors.json: no such file or directory
[LOG] 0:00.380 DEBUG juju.environs.simplestreams no mirror index file found
[LOG] 0:00.380 DEBUG juju.environs.simplestreams no mirror information available for { }: mirror data for "com.ubuntu.juju:released:tools" not found
[LOG] 0:00.380 DEBUG juju.environs.simplestreams read metadata index at "file:///tmp/check-5577006791947779410/40/tools/streams/v1/index2.json"
[LOG] 0:00.380 DEBUG juju.environs.simplestreams candidate matches for products ["com.ubuntu.juju:13.04:amd64" "com.ubuntu.juju:13.04:i386" "com.ubuntu.juju:13.04:armhf" "com.ubuntu.juju:13.04:arm64" "com.ubuntu.juju:13.04:ppc64el" "com.ubuntu.juju:13.10:amd64" "com.ubuntu.juju:13.10:i386" "com.ubuntu.juju:13.10:armhf" "com.ubuntu.juju:13.10:arm64" "com.ubuntu.juju:13.10:ppc64el" "com.ubuntu.juju:win2012hvr2:amd64" "com.ubuntu.juju:win2012hvr2:i386" "com.ubuntu.juju:win2012hvr2:armhf" "com.ubuntu.juju:win2012hvr2:arm64" "com.ubuntu.juju:win2012hvr2:ppc64el" "com.ubuntu.juju:17.04:amd64" "com.ubuntu.juju:17.04:i386" "com.ubuntu.juju:17.04:armhf" "com.ubuntu.juju:17.04:arm64" "com.ubuntu.juju:17.04:ppc64el" "com.ubuntu.juju:12.10:amd64" "com.ubuntu.juju:12.10:i386" "com.ubuntu.juju:12.10:armhf" "com.ubuntu.juju:12.10:arm64" "com.ubuntu.juju:12.10:ppc64el" "com.ubuntu.juju:win2012r2:amd64" "com.ubuntu.juju:win2012r2:i386" "com.ubuntu.juju:win2012r2:armhf" "com.ubuntu.juju:win2012r2:arm64" "com.ubuntu.juju:win2012r2:ppc64el" "com.ubuntu.juju:7:amd64" "com.ubuntu.juju:7:i386" "com.ubuntu.juju:7:armhf" "com.ubuntu.juju:7:arm64" "com.ubuntu.juju:7:ppc64el" "com.ubuntu.juju:win2012hv:amd64" "com.ubuntu.juju:win2012hv:i386" "com.ubuntu.juju:win2012hv:armhf" "com.ubuntu.juju:win2012hv:arm64" "com.ubuntu.juju:win2012hv:ppc64el" "com.ubuntu.juju:win2012:amd64" "com.ubuntu.juju:win2012:i386" "com.ubuntu.juju:win2012:armhf" "com.ubuntu.juju:win2012:arm64" "com.ubuntu.juju:win2012:ppc64el" "com.ubuntu.juju:win8:amd64" "com.ubuntu.juju:win8:i386" "com.ubuntu.juju:win8:armhf" "com.ubuntu.juju:win8:arm64" "com.ubuntu.juju:win8:ppc64el" "com.ubuntu.juju:win81:amd64" "com.ubuntu.juju:win81:i386" "com.ubuntu.juju:win81:armhf" "com.ubuntu.juju:win81:arm64" "com.ubuntu.juju:win81:ppc64el" "com.ubuntu.juju:12.04:amd64" "com.ubuntu.juju:12.04:i386" "com.ubuntu.juju:12.04:armhf" "com.ubuntu.juju:12.04:arm64" "com.ubuntu.juju:12.04:ppc64el" "com.ubuntu.juju:14.04:amd64" "com.ubuntu.juju:14.04:i386" "com.ubuntu.juju:14.04:armhf" "com.ubuntu.juju:14.04:arm64" "com.ubuntu.juju:14.04:ppc64el" "com.ubuntu.juju:14.10:amd64" "com.ubuntu.juju:14.10:i386" "com.ubuntu.juju:14.10:armhf" "com.ubuntu.juju:14.10:arm64" "com.ubuntu.juju:14.10:ppc64el" "com.ubuntu.juju:15.04:amd64" "com.ubuntu.juju:15.04:i386" "com.ubuntu.juju:15.04:armhf" "com.ubuntu.juju:15.04:arm64" "com.ubuntu.juju:15.04:ppc64el" "com.ubuntu.juju:win7:amd64" "com.ubuntu.juju:win7:i386" "com.ubuntu.juju:win7:armhf" "com.ubuntu.juju:win7:arm64" "com.ubuntu.juju:win7:ppc64el"] are [{Wed, 25 Mar 2015 01:51:40 +0000 products:1.0 content-download [] streams/v1/com.ubuntu.juju-released-tools.json [com.ubuntu.juju:12.04:amd64 com.ubuntu.juju:14.04:amd64 com.ubuntu.juju:15.04:amd64]}]
[LOG] 0:00.380 DEBUG juju.environs.simplestreams finding products at path "streams/v1/com.ubuntu.juju-released-tools.json"
[LOG] 0:00.380 DEBUG juju.environs.simplestreams metadata: &{map[com.ubuntu.juju:12.04:amd64:{ 1.24-alpha1 amd64 map[20150325:0xc20875f080]} com.ubuntu.juju:14.04:amd64:{ 1.24-alpha1 amd64 map[20150325:0xc20875f200]} com.ubuntu.juju:15.04:amd64:{ 1.24-alpha1 amd64 map[20150325:0xc20875f320]}] map[] Wed, 25 Mar 2015 01:51:40 +0000 products:1.0 com.ubuntu.juju:released:tools }
[LOG] 0:00.380 DEBUG juju.environs.tools no architecture specified when finding tools, looking for any
[LOG] 0:00.380 DEBUG juju.environs.tools no series specified when finding tools, looking for any
[LOG] 0:00.380 DEBUG juju.environs.simplestreams searching for metadata in datasource "existing metadata"
[LOG] 0:00.380 DEBUG juju.environs.simplestreams fetchData failed for "file:///tmp/check-5577006791947779410/40/tools/streams/v1/index2.sjson": stat /tmp/check-5577006791947779410/40/tools/streams/v1/index2.sjson: no such file or directory
[LOG] 0:00.380 DEBUG juju.environs.simplestreams streams/v1/index2.sjson not found, trying legacy index file
[LOG] 0:00.380 DEBUG juju.environs.simplestreams fetchData failed for "file:///tmp/check-5577006791947779410/40/tools/streams/v1/index.sjson": stat /tmp/check-5577006791947779410/40/tools/streams/v1/index.sjson: no such file or directory
[LOG] 0:00.380 DEBUG juju.environs.simplestreams cannot load index "file:///tmp/check-5577006791947779410/40/tools/streams/v1/index.sjson": invalid URL "file:///tmp/check-5577006791947779410/40/tools/streams/v1/index.sjson" not found
[LOG] 0:00.381 DEBUG juju.environs.simplestreams fetchData failed for "file:///tmp/check-5577006791947779410/40/tools/streams/v1/mirrors.json": stat /tmp/check-5577006791947779410/40/tools/streams/v1/mirrors.json: no such file or directory
[LOG] 0:00.381 DEBUG juju.environs.simplestreams no mirror index file found
[LOG] 0:00.381 DEBUG juju.environs.simplestreams no mirror information available for { }: mirror data for "com.ubuntu.juju:proposed:tools" not found
[LOG] 0:00.381 DEBUG juju.environs.simplestreams read metadata index at "file:///tmp/check-5577006791947779410/40/tools/streams/v1/index2.json"
[LOG] 0:00.381 DEBUG juju.environs.simplestreams skipping index because of error getting latest metadata "file:///tmp/check-5577006791947779410/40/tools/streams/v1/index2.json": index file missing "content-download" data not found
[LOG] 0:00.381 DEBUG juju.environs.tools no architecture specified when finding tools, looking for any
[LOG] 0:00.381 DEBUG juju.environs.tools no series specified when finding tools, looking for any
[LOG] 0:00.381 DEBUG juju.environs.simplestreams searching for metadata in datasource "existing metadata"
[LOG] 0:00.381 DEBUG juju.environs.simplestreams fetchData failed for "file:///tmp/check-5577006791947779410/40/tools/streams/v1/index2.sjson": stat /tmp/check-5577006791947779410/40/tools/streams/v1/index2.sjson: no such file or directory
[LOG] 0:00.381 DEBUG juju.environs.simplestreams streams/v1/index2.sjson not found, trying legacy index file
[LOG] 0:00.381 DEBUG juju.environs.simplestreams fetchData failed for "file:///tmp/check-5577006791947779410/40/tools/streams/v1/index.sjson": stat /tmp/check-5577006791947779410/40/tools/streams/v1/index.sjson: no such file or directory
[LOG] 0:00.381 DEBUG juju.environs.simplestreams cannot load index "file:///tmp/check-5577006791947779410/40/tools/streams/v1/index.sjson": invalid URL "file:///tmp/check-5577006791947779410/40/tools/streams/v1/index.sjson" not found
[LOG] 0:00.381 DEBUG juju.environs.simplestreams fetchData failed for "file:///tmp/check-5577006791947779410/40/tools/streams/v1/mirrors.json": stat /tmp/check-5577006791947779410/40/tools/streams/v1/mirrors.json: no such file or directory
[LOG] 0:00.381 DEBUG juju.environs.simplestreams no mirror index file found
[LOG] 0:00.381 DEBUG juju.environs.simplestreams no mirror information available for { }: mirror data for "com.ubuntu.juju:testing:tools" not found
[LOG] 0:00.381 DEBUG juju.environs.simplestreams read metadata index at "file:///tmp/check-5577006791947779410/40/tools/streams/v1/index2.json"
[LOG] 0:00.381 DEBUG juju.environs.simplestreams skipping index because of error getting latest metadata "file:///tmp/check-5577006791947779410/40/tools/streams/v1/index2.json": index file missing "content-download" data not found
[LOG] 0:00.381 DEBUG juju.environs.tools no architecture specified when finding tools, looking for any
[LOG] 0:00.381 DEBUG juju.environs.tools no series specified when finding tools, looking for any
[LOG] 0:00.381 DEBUG juju.environs.simplestreams searching for metadata in datasource "existing metadata"
[LOG] 0:00.381 DEBUG juju.environs.simplestreams fetchData failed for "file:///tmp/check-5577006791947779410/40/tools/streams/v1/index2.sjson": stat /tmp/check-5577006791947779410/40/tools/streams/v1/index2.sjson: no such file or directory
[LOG] 0:00.381 DEBUG juju.environs.simplestreams streams/v1/index2.sjson not found, trying legacy index file
[LOG] 0:00.381 DEBUG juju.environs.simplestreams fetchData failed for "file:///tmp/check-5577006791947779410/40/tools/streams/v1/index.sjson": stat /tmp/check-5577006791947779410/40/tools/streams/v1/index.sjson: no such file or directory
[LOG] 0:00.381 DEBUG juju.environs.simplestreams cannot load index "file:///tmp/check-5577006791947779410/40/tools/streams/v1/index.sjson": invalid URL "file:///tmp/check-5577006791947779410/40/tools/streams/v1/index.sjson" not found
[LOG] 0:00.381 DEBUG juju.environs.simplestreams fetchData failed for "file:///tmp/check-5577006791947779410/40/tools/streams/v1/mirrors.json": stat /tmp/check-5577006791947779410/40/tools/streams/v1/mirrors.json: no such file or directory
[LOG] 0:00.381 DEBUG juju.environs.simplestreams no mirror index file found
[LOG] 0:00.381 DEBUG juju.environs.simplestreams no mirror information available for { }: mirror data for "com.ubuntu.juju:devel:tools" not found
[LOG] 0:00.381 DEBUG juju.environs.simplestreams read metadata index at "file:///tmp/check-5577006791947779410/40/tools/streams/v1/index2.json"
[LOG] 0:00.381 DEBUG juju.environs.simplestreams skipping index because of error getting latest metadata "file:///tmp/check-5577006791947779410/40/tools/streams/v1/index2.json": index file missing "content-download" data not found
[LOG] 0:00.382 INFO juju.environs.tools Metadata for stream "released" unchanged
[LOG] 0:00.382 INFO juju.environs.tools Writing tools/streams/v1/index2.json
[LOG] 0:00.382 INFO juju.environs.tools Writing tools/streams/v1/index.json
[LOG] 0:00.382 INFO juju.network setting prefer-ipv6 to true
[LOG] 0:00.382 DEBUG juju.environs.bootstrap environment "dummyenv" supports service/machine networks: true
[LOG] 0:00.382 DEBUG juju.environs.bootstrap network management by juju enabled: true
[LOG] 0:00.382 DEBUG juju.environs.bootstrap looking for bootstrap tools: version=1.24-alpha1
[LOG] 0:00.382 INFO juju.environs.tools reading tools with major.minor version 1.24
[LOG] 0:00.382 INFO juju.environs.tools filtering tools by version: 1.24-alpha1
[LOG] 0:00.382 DEBUG juju.environs.tools no architecture specified when finding tools, looking for any
[LOG] 0:00.382 DEBUG juju.environs.tools no series specified when finding tools, looking for any
[LOG] 0:00.382 DEBUG juju.environs.simplestreams searching for metadata in datasource "default simplestreams"
[LOG] 0:00.382 DEBUG juju.environs.simplestreams fetchData failed for "file:///tmp/check-5577006791947779410/40/tools/streams/v1/index2.sjson": cannot find URL "file:///tmp/check-5577006791947779410/40/tools/streams/v1/index2.sjson" not found
[LOG] 0:00.382 DEBUG juju.environs.simplestreams streams/v1/index2.sjson not found, trying legacy index file
[LOG] 0:00.382 DEBUG juju.environs.simplestreams fetchData failed for "file:///tmp/check-5577006791947779410/40/tools/streams/v1/index.sjson": cannot find URL "file:///tmp/check-5577006791947779410/40/tools/streams/v1/index.sjson" not found
[LOG] 0:00.382 DEBUG juju.environs.simplestreams cannot load index "file:///tmp/check-5577006791947779410/40/tools/streams/v1/index.sjson": invalid URL "file:///tmp/check-5577006791947779410/40/tools/streams/v1/index.sjson" not found
[LOG] 0:00.383 DEBUG juju.environs.simplestreams fetchData failed for "file:///tmp/check-5577006791947779410/40/tools/streams/v1/mirrors.json": cannot find URL "file:///tmp/check-5577006791947779410/40/tools/streams/v1/mirrors.json" not found
[LOG] 0:00.383 DEBUG juju.environs.simplestreams no mirror index file found
[LOG] 0:00.383 DEBUG juju.environs.simplestreams no mirror information available for { }: mirror data for "com.ubuntu.juju:released:tools" not found
[LOG] 0:00.383 DEBUG juju.environs.simplestreams read metadata index at "file:///tmp/check-5577006791947779410/40/tools/streams/v1/index2.json"
[LOG] 0:00.383 DEBUG juju.environs.simplestreams candidate matches for products ["com.ubuntu.juju:13.10:amd64" "com.ubuntu.juju:13.10:i386" "com.ubuntu.juju:13.10:armhf" "com.ubuntu.juju:13.10:arm64" "com.ubuntu.juju:13.10:ppc64el" "com.ubuntu.juju:win2012hvr2:amd64" "com.ubuntu.juju:win2012hvr2:i386" "com.ubuntu.juju:win2012hvr2:armhf" "com.ubuntu.juju:win2012hvr2:arm64" "com.ubuntu.juju:win2012hvr2:ppc64el" "com.ubuntu.juju:13.04:amd64" "com.ubuntu.juju:13.04:i386" "com.ubuntu.juju:13.04:armhf" "com.ubuntu.juju:13.04:arm64" "com.ubuntu.juju:13.04:ppc64el" "com.ubuntu.juju:win2012r2:amd64" "com.ubuntu.juju:win2012r2:i386" "com.ubuntu.juju:win2012r2:armhf" "com.ubuntu.juju:win2012r2:arm64" "com.ubuntu.juju:win2012r2:ppc64el" "com.ubuntu.juju:7:amd64" "com.ubuntu.juju:7:i386" "com.ubuntu.juju:7:armhf" "com.ubuntu.juju:7:arm64" "com.ubuntu.juju:7:ppc64el" "com.ubuntu.juju:17.04:amd64" "com.ubuntu.juju:17.04:i386" "com.ubuntu.juju:17.04:armhf" "com.ubuntu.juju:17.04:arm64" "com.ubuntu.juju:17.04:ppc64el" "com.ubuntu.juju:12.10:amd64" "com.ubuntu.juju:12.10:i386" "com.ubuntu.juju:12.10:armhf" "com.ubuntu.juju:12.10:arm64" "com.ubuntu.juju:12.10:ppc64el" "com.ubuntu.juju:14.04:amd64" "com.ubuntu.juju:14.04:i386" "com.ubuntu.juju:14.04:armhf" "com.ubuntu.juju:14.04:arm64" "com.ubuntu.juju:14.04:ppc64el" "com.ubuntu.juju:14.10:amd64" "com.ubuntu.juju:14.10:i386" "com.ubuntu.juju:14.10:armhf" "com.ubuntu.juju:14.10:arm64" "com.ubuntu.juju:14.10:ppc64el" "com.ubuntu.juju:win2012hv:amd64" "com.ubuntu.juju:win2012hv:i386" "com.ubuntu.juju:win2012hv:armhf" "com.ubuntu.juju:win2012hv:arm64" "com.ubuntu.juju:win2012hv:ppc64el" "com.ubuntu.juju:win2012:amd64" "com.ubuntu.juju:win2012:i386" "com.ubuntu.juju:win2012:armhf" "com.ubuntu.juju:win2012:arm64" "com.ubuntu.juju:win2012:ppc64el" "com.ubuntu.juju:win8:amd64" "com.ubuntu.juju:win8:i386" "com.ubuntu.juju:win8:armhf" "com.ubuntu.juju:win8:arm64" "com.ubuntu.juju:win8:ppc64el" "com.ubuntu.juju:win81:amd64" "com.ubuntu.juju:win81:i386" "com.ubuntu.juju:win81:armhf" "com.ubuntu.juju:win81:arm64" "com.ubuntu.juju:win81:ppc64el" "com.ubuntu.juju:12.04:amd64" "com.ubuntu.juju:12.04:i386" "com.ubuntu.juju:12.04:armhf" "com.ubuntu.juju:12.04:arm64" "com.ubuntu.juju:12.04:ppc64el" "com.ubuntu.juju:win7:amd64" "com.ubuntu.juju:win7:i386" "com.ubuntu.juju:win7:armhf" "com.ubuntu.juju:win7:arm64" "com.ubuntu.juju:win7:ppc64el" "com.ubuntu.juju:15.04:amd64" "com.ubuntu.juju:15.04:i386" "com.ubuntu.juju:15.04:armhf" "com.ubuntu.juju:15.04:arm64" "com.ubuntu.juju:15.04:ppc64el"] are [{Wed, 25 Mar 2015 01:51:40 +0000 products:1.0 content-download [] streams/v1/com.ubuntu.juju-released-tools.json [com.ubuntu.juju:12.04:amd64 com.ubuntu.juju:14.04:amd64 com.ubuntu.juju:15.04:amd64]}]
[LOG] 0:00.383 DEBUG juju.environs.simplestreams finding products at path "streams/v1/com.ubuntu.juju-released-tools.json"
[LOG] 0:00.383 DEBUG juju.environs.simplestreams metadata: &{map[com.ubuntu.juju:12.04:amd64:{ 1.24-alpha1 amd64 map[20150325:0xc208184f00]} com.ubuntu.juju:14.04:amd64:{ 1.24-alpha1 amd64 map[20150325:0xc208185020]} com.ubuntu.juju:15.04:amd64:{ 1.24-alpha1 amd64 map[20150325:0xc208185140]}] map[] Wed, 25 Mar 2015 01:51:40 +0000 products:1.0 com.ubuntu.juju:released:tools }
[LOG] 0:00.674 INFO juju.network setting prefer-ipv6 to true
[LOG] 0:00.674 INFO juju.provider.dummy would pick tools from 1.24-alpha1-trusty-amd64
[LOG] 0:00.674 INFO juju.provider.dummy creating bootstrap instance
[LOG] 0:00.674 INFO juju.state opening state, mongo addresses: ["[::1]:53300" "localhost:53300"]; entity <nil>
[LOG] 0:00.674 DEBUG juju.state dialing mongo
[LOG] 0:00.692 INFO juju.mongo dialled mongo successfully on address "127.0.0.1:53300"
[LOG] 0:00.693 DEBUG juju.state connection established
[LOG] 0:00.694 INFO juju.mongo dialled mongo successfully on address "[::1]:53300"
[LOG] 0:00.777 INFO juju.state initializing environment, owner: "dummy-admin@local"
[LOG] 0:00.777 INFO juju.state info: &mongo.MongoInfo{Info:mongo.Info{Addrs:[]string{"[::1]:53300", "localhost:53300"}, CACert:"-----BEGIN CERTIFICATE-----\n...\n-----END CERTIFICATE-----\n"}, Tag:names.Tag(nil), Password:""}
[LOG] 0:00.777 INFO juju.state starting presence watcher
[LOG] 0:00.786 INFO juju.mongo dialled mongo successfully on address "127.0.0.1:53300"
[LOG] 0:00.973 DEBUG juju.provider.dummy setting password for "dummy-admin" to "dummy-secret"
[LOG] 0:00.976 INFO juju.apiserver listening on "[::]:58414"
[LOG] 0:00.998 INFO juju.environs.bootstrap newest version: 1.24-alpha1
[LOG] 0:00.998 INFO juju.environs.bootstrap picked bootstrap tools version: 1.24-alpha1
[LOG] 0:00.998 INFO juju.state opening state, mongo addresses: ["[::1]:53300" "localhost:53300"]; entity <nil>
[LOG] 0:00.998 DEBUG juju.state dialing mongo
[LOG] 0:01.069 INFO juju.mongo dialled mongo successfully on address "127.0.0.1:53300"
[LOG] 0:01.070 INFO juju.mongo dialled mongo successfully on address "[::1]:53300"
[LOG] 0:01.070 DEBUG juju.state connection established
[LOG] 0:01.297 DEBUG juju.environs StateServerInstances returned: [localhost]
[LOG] 0:01.297 INFO juju.api dialing "wss://localhost:58414/environment/deadbeef-0bad-400d-8000-4b1d0d06f00d/api"
[LOG] 0:01.371 INFO juju.apiserver [10] API connection from 127.0.0.1:36705
[LOG] 0:01.371 DEBUG juju.apiserver validate env uuid: state server environment - deadbeef-0bad-400d-8000-4b1d0d06f00d
[LOG] 0:01.371 INFO juju.api connection established to "wss://localhost:58414/environment/deadbeef-0bad-400d-8000-4b1d0d06f00d/api"
[LOG] 0:01.372 DEBUG juju.apiserver <- [10] <unknown> {"RequestId":1,"Type":"Admin","Version":2,"Request":"Login","Params":"'params redacted'"}
[LOG] 0:01.372 INFO juju.mongo dialled mongo successfully on address "127.0.0.1:53300"
[LOG] 0:01.376 DEBUG juju.apiserver hostPorts: []
[LOG] 0:01.376 DEBUG juju.apiserver -> [10] user-dummy-admin@local 4.509409ms {"RequestId":1,"Response":"'body redacted'"} Admin[""].Login
[LOG] 0:01.380 DEBUG juju.state setting API hostPorts: [[localhost:58414]]
[LOG] 0:01.381 DEBUG juju.environs.configstore writing jenv file
[LOG] 0:01.382 DEBUG juju.environs.configstore writing jenv file to /tmp/check-5577006791947779410/37/home/ubuntu/.juju/environments/dummyenv.jenv
jenv host ports: [][]network.HostPort{[]network.HostPort{localhost:58414}}
[LOG] 0:01.407 DEBUG juju.storage resource catalog entry created with id "62566af52e7c39909518ac08ae6d56d6ab9ee2f6eafa3aa383667358729c9adf7efa278bf40de13503a28b189064469e"
[LOG] 0:01.503 DEBUG juju.storage managed resource entry created with path "environs/deadbeef-0bad-400d-8000-4b1d0d06f00d/charms/local:quantal/wordpress-3-934186c5b32fc367151eee38a14185cab1117ebd3f0b7f24a6c358e2dc9fd4f9" -> "62566af52e7c39909518ac08ae6d56d6ab9ee2f6eafa3aa383667358729c9adf7efa278bf40de13503a28b189064469e"
[LOG] 0:01.868 DEBUG juju.environs StateServerInstances returned: [localhost]
[LOG] 0:01.869 INFO juju.api dialing "wss://localhost:58414/environment/deadbeef-0bad-400d-8000-4b1d0d06f00d/api"
[LOG] 0:01.877 INFO juju.apiserver [11] API connection from 127.0.0.1:36706
[LOG] 0:01.877 DEBUG juju.apiserver validate env uuid: state server environment - deadbeef-0bad-400d-8000-4b1d0d06f00d
[LOG] 0:01.877 INFO juju.api connection established to "wss://localhost:58414/environment/deadbeef-0bad-400d-8000-4b1d0d06f00d/api"
[LOG] 0:01.877 DEBUG juju.apiserver <- [11] <unknown> {"RequestId":1,"Type":"Admin","Version":2,"Request":"Login","Params":"'params redacted'"}
[LOG] 0:01.906 DEBUG juju.apiserver hostPorts: [[localhost:58414]]
[LOG] 0:01.906 DEBUG juju.apiserver -> [11] unit-wordpress-0 29.675094ms {"RequestId":1,"Response":"'body redacted'"} Admin[""].Login
[LOG] 0:01.907 DEBUG juju.apiserver <- [11] unit-wordpress-0 {"RequestId":3,"Type":"Uniter","Version":2,"Request":"Life","Params":"'params redacted'"}
[LOG] 0:01.969 DEBUG juju.apiserver -> [11] unit-wordpress-0 61.213117ms {"RequestId":3,"Response":"'body redacted'"} Uniter[""].Life
[LOG] 0:01.969 DEBUG juju.apiserver <- [11] unit-wordpress-0 {"RequestId":4,"Type":"Uniter","Version":2,"Request":"Life","Params":"'params redacted'"}
[LOG] 0:01.969 DEBUG juju.apiserver -> [11] unit-wordpress-0 560.233us {"RequestId":4,"Response":"'body redacted'"} Uniter[""].Life
[LOG] 0:01.970 DEBUG juju.apiserver <- [11] unit-wordpress-0 {"RequestId":5,"Type":"Uniter","Version":2,"Request":"Resolved","Params":"'params redacted'"}
[LOG] 0:01.970 DEBUG juju.apiserver -> [11] unit-wordpress-0 453.236us {"RequestId":5,"Response":"'body redacted'"} Uniter[""].Resolved
[LOG] 0:01.970 DEBUG juju.apiserver <- [11] unit-wordpress-0 {"RequestId":6,"Type":"Uniter","Version":2,"Request":"GetMeterStatus","Params":"'params redacted'"}
[LOG] 0:01.978 DEBUG juju.apiserver -> [11] unit-wordpress-0 7.606274ms {"RequestId":6,"Response":"'body redacted'"} Uniter[""].GetMeterStatus
[LOG] 0:01.978 DEBUG juju.apiserver <- [11] unit-wordpress-0 {"RequestId":7,"Type":"Uniter","Version":2,"Request":"Life","Params":"'params redacted'"}
[LOG] 0:01.979 DEBUG juju.apiserver -> [11] unit-wordpress-0 517.45us {"RequestId":7,"Response":"'body redacted'"} Uniter[""].Life
[LOG] 0:01.979 DEBUG juju.apiserver <- [11] unit-wordpress-0 {"RequestId":8,"Type":"Uniter","Version":2,"Request":"Life","Params":"'params redacted'"}
[LOG] 0:01.979 DEBUG juju.apiserver -> [11] unit-wordpress-0 471.068us {"RequestId":8,"Response":"'body redacted'"} Uniter[""].Life
[LOG] 0:01.979 DEBUG juju.apiserver <- [11] unit-wordpress-0 {"RequestId":9,"Type":"Uniter","Version":2,"Request":"CharmURL","Params":"'params redacted'"}
[LOG] 0:01.980 DEBUG juju.apiserver -> [11] unit-wordpress-0 446.593us {"RequestId":9,"Response":"'body redacted'"} Uniter[""].CharmURL
[LOG] 0:01.980 DEBUG juju.worker.uniter.filter charm check skipped, not yet installed.
[LOG] 0:01.980 DEBUG juju.apiserver <- [11] unit-wordpress-0 {"RequestId":10,"Type":"Uniter","Version":2,"Request":"Watch","Params":"'params redacted'"}
[LOG] 0:01.981 DEBUG juju.apiserver -> [11] unit-wordpress-0 703.405us {"RequestId":10,"Response":"'body redacted'"} Uniter[""].Watch
[LOG] 0:01.981 DEBUG juju.apiserver <- [11] unit-wordpress-0 {"RequestId":11,"Type":"Uniter","Version":2,"Request":"Watch","Params":"'params redacted'"}
[LOG] 0:01.981 DEBUG juju.apiserver <- [11] unit-wordpress-0 {"RequestId":12,"Type":"NotifyWatcher","Id":"2","Request":"Next","Params":"'params redacted'"}
[LOG] 0:01.982 DEBUG juju.apiserver -> [11] unit-wordpress-0 725.465us {"RequestId":11,"Response":"'body redacted'"} Uniter[""].Watch
[LOG] 0:01.982 DEBUG juju.apiserver <- [11] unit-wordpress-0 {"RequestId":13,"Type":"Uniter","Version":2,"Request":"CharmURL","Params":"'params redacted'"}
[LOG] 0:01.982 DEBUG juju.apiserver <- [11] unit-wordpress-0 {"RequestId":14,"Type":"NotifyWatcher","Id":"3","Request":"Next","Params":"'params redacted'"}
[LOG] 0:01.982 DEBUG juju.apiserver -> [11] unit-wordpress-0 534.119us {"RequestId":13,"Response":"'body redacted'"} Uniter[""].CharmURL
[LOG] 0:01.983 DEBUG juju.apiserver <- [11] unit-wordpress-0 {"RequestId":15,"Type":"Uniter","Version":2,"Request":"WatchActionNotifications","Params":"'params redacted'"}
[LOG] 0:01.990 DEBUG juju.apiserver -> [11] unit-wordpress-0 6.890903ms {"RequestId":15,"Response":"'body redacted'"} Uniter[""].WatchActionNotifications
[LOG] 0:01.990 DEBUG juju.apiserver <- [11] unit-wordpress-0 {"RequestId":16,"Type":"Uniter","Version":2,"Request":"WatchServiceRelations","Params":"'params redacted'"}
[LOG] 0:01.990 DEBUG juju.apiserver <- [11] unit-wordpress-0 {"RequestId":17,"Type":"StringsWatcher","Id":"4","Request":"Next","Params":"'params redacted'"}
[LOG] 0:01.991 DEBUG juju.apiserver -> [11] unit-wordpress-0 979.842us {"RequestId":16,"Response":"'body redacted'"} Uniter[""].WatchServiceRelations
[LOG] 0:01.991 DEBUG juju.apiserver <- [11] unit-wordpress-0 {"RequestId":18,"Type":"Uniter","Version":2,"Request":"WatchMeterStatus","Params":"'params redacted'"}
[LOG] 0:01.991 DEBUG juju.apiserver <- [11] unit-wordpress-0 {"RequestId":19,"Type":"StringsWatcher","Id":"5","Request":"Next","Params":"'params redacted'"}
[LOG] 0:01.992 DEBUG juju.apiserver -> [11] unit-wordpress-0 706.683us {"RequestId":18,"Response":"'body redacted'"} Uniter[""].WatchMeterStatus
[LOG] 0:01.992 DEBUG juju.apiserver <- [11] unit-wordpress-0 {"RequestId":20,"Type":"Uniter","Version":2,"Request":"WatchUnitAddresses","Params":"'params redacted'"}
[LOG] 0:01.992 DEBUG juju.apiserver <- [11] unit-wordpress-0 {"RequestId":21,"Type":"NotifyWatcher","Id":"6","Request":"Next","Params":"'params redacted'"}
[LOG] 0:01.993 DEBUG juju.apiserver -> [11] unit-wordpress-0 711.414us {"RequestId":20,"Response":"'body redacted'"} Uniter[""].WatchUnitAddresses
[LOG] 0:01.993 DEBUG juju.apiserver <- [11] unit-wordpress-0 {"RequestId":22,"Type":"Uniter","Version":2,"Request":"WatchUnitStorageAttachments","Params":"'params redacted'"}
[LOG] 0:01.993 DEBUG juju.apiserver <- [11] unit-wordpress-0 {"RequestId":23,"Type":"NotifyWatcher","Id":"7","Request":"Next","Params":"'params redacted'"}
[LOG] 0:01.993 DEBUG juju.apiserver -> [11] unit-wordpress-0 354.126us {"RequestId":22,"Response":"'body redacted'"} Uniter[""].WatchUnitStorageAttachments
[LOG] 0:01.993 DEBUG juju.worker.uniter.filter got unit change
[LOG] 0:01.993 DEBUG juju.apiserver <- [11] unit-wordpress-0 {"RequestId":24,"Type":"Uniter","Version":2,"Request":"Life","Params":"'params redacted'"}
[LOG] 0:01.993 DEBUG juju.apiserver <- [11] unit-wordpress-0 {"RequestId":25,"Type":"StringsWatcher","Id":"8","Request":"Next","Params":"'params redacted'"}
[LOG] 0:01.994 DEBUG juju.apiserver -> [11] unit-wordpress-0 485.561us {"RequestId":24,"Response":"'body redacted'"} Uniter[""].Life
[LOG] 0:01.994 DEBUG juju.apiserver <- [11] unit-wordpress-0 {"RequestId":26,"Type":"Uniter","Version":2,"Request":"Resolved","Params":"'params redacted'"}
[LOG] 0:01.994 DEBUG juju.apiserver -> [11] unit-wordpress-0 263.577us {"RequestId":26,"Response":"'body redacted'"} Uniter[""].Resolved
[LOG] 0:01.994 DEBUG juju.worker.uniter.filter got service change
[LOG] 0:01.995 DEBUG juju.apiserver <- [11] unit-wordpress-0 {"RequestId":27,"Type":"Uniter","Version":2,"Request":"Life","Params":"'params redacted'"}
[LOG] 0:01.995 DEBUG juju.apiserver -> [11] unit-wordpress-0 440.215us {"RequestId":27,"Response":"'body redacted'"} Uniter[""].Life
[LOG] 0:01.995 DEBUG juju.apiserver <- [11] unit-wordpress-0 {"RequestId":28,"Type":"Uniter","Version":2,"Request":"CharmURL","Params":"'params redacted'"}
[LOG] 0:01.995 DEBUG juju.apiserver -> [11] unit-wordpress-0 348.194us {"RequestId":28,"Response":"'body redacted'"} Uniter[""].CharmURL
[LOG] 0:01.996 DEBUG juju.worker.uniter.filter charm check skipped, not yet installed.
[LOG] 0:01.996 DEBUG juju.worker.uniter.filter got 0 actions
[LOG] 0:01.996 DEBUG juju.worker.uniter.filter got relations change
[LOG] 0:01.996 DEBUG juju.worker.uniter.filter got meter status change
[LOG] 0:01.996 DEBUG juju.apiserver <- [11] unit-wordpress-0 {"RequestId":29,"Type":"Uniter","Version":2,"Request":"GetMeterStatus","Params":"'params redacted'"}
[LOG] 0:01.996 DEBUG juju.apiserver -> [11] unit-wordpress-0 393.406us {"RequestId":29,"Response":"'body redacted'"} Uniter[""].GetMeterStatus
[LOG] 0:01.996 DEBUG juju.worker.uniter.filter got address change
[LOG] 0:01.996 DEBUG juju.worker.uniter.filter no config change seen yet, skipping config event
[LOG] 0:01.996 DEBUG juju.worker.uniter.filter got storage change
filter_test.go:591:
meterC.AssertOneReceive()
/home/ubuntu/juju-core_1.24-alpha1/src/github.com/juju/juju/testing/channel.go:36:
a.C.Fatalf("timed out waiting for channel message")
... Error: timed out waiting for channel message

[LOG] 0:11.978 DEBUG juju.apiserver <- [11] unit-wordpress-0 {"RequestId":30,"Type":"StringsWatcher","Id":"8","Request":"Stop","Params":"'params redacted'"}
[LOG] 0:11.978 DEBUG juju.apiserver -> [11] unit-wordpress-0 9.985043893s {"RequestId":25,"Error":"watcher has been stopped","ErrorCode":"stopped","Response":"'body redacted'"} StringsWatcher["8"].Next
[LOG] 0:11.979 DEBUG juju.apiserver -> [11] unit-wordpress-0 252.771us {"RequestId":30,"Response":"'body redacted'"} StringsWatcher["8"].Stop
[LOG] 0:11.979 DEBUG juju.apiserver <- [11] unit-wordpress-0 {"RequestId":31,"Type":"NotifyWatcher","Id":"7","Request":"Stop","Params":"'params redacted'"}
[LOG] 0:11.979 DEBUG juju.apiserver -> [11] unit-wordpress-0 9.986092387s {"RequestId":23,"Error":"watcher has been stopped","ErrorCode":"stopped","Response":"'body redacted'"} NotifyWatcher["7"].Next
[LOG] 0:11.979 DEBUG juju.apiserver -> [11] unit-wordpress-0 176.758us {"RequestId":31,"Response":"'body redacted'"} NotifyWatcher["7"].Stop
[LOG] 0:11.979 DEBUG juju.apiserver <- [11] unit-wordpress-0 {"RequestId":32,"Type":"NotifyWatcher","Id":"6","Request":"Stop","Params":"'params redacted'"}
[LOG] 0:11.979 DEBUG juju.apiserver -> [11] unit-wordpress-0 9.98750145s {"RequestId":21,"Error":"watcher has been stopped","ErrorCode":"stopped","Response":"'body redacted'"} NotifyWatcher["6"].Next
[LOG] 0:11.980 DEBUG juju.apiserver -> [11] unit-wordpress-0 179.189us {"RequestId":32,"Response":"'body redacted'"} NotifyWatcher["6"].Stop
[LOG] 0:11.980 DEBUG juju.apiserver <- [11] unit-wordpress-0 {"RequestId":33,"Type":"StringsWatcher","Id":"5","Request":"Stop","Params":"'params redacted'"}
[LOG] 0:11.980 DEBUG juju.apiserver -> [11] unit-wordpress-0 9.988810537s {"RequestId":19,"Error":"watcher has been stopped","ErrorCode":"stopped","Response":"'body redacted'"} StringsWatcher["5"].Next
[LOG] 0:11.980 DEBUG juju.apiserver -> [11] unit-wordpress-0 176.988us {"RequestId":33,"Response":"'body redacted'"} StringsWatcher["5"].Stop
[LOG] 0:11.980 DEBUG juju.apiserver <- [11] unit-wordpress-0 {"RequestId":34,"Type":"StringsWatcher","Id":"4","Request":"Stop","Params":"'params redacted'"}
[LOG] 0:11.980 DEBUG juju.apiserver -> [11] unit-wordpress-0 9.990430429s {"RequestId":17,"Error":"watcher has been stopped","ErrorCode":"stopped","Response":"'body redacted'"} StringsWatcher["4"].Next
[LOG] 0:11.980 DEBUG juju.apiserver -> [11] unit-wordpress-0 178.938us {"RequestId":34,"Response":"'body redacted'"} StringsWatcher["4"].Stop
[LOG] 0:11.981 DEBUG juju.apiserver <- [11] unit-wordpress-0 {"RequestId":35,"Type":"NotifyWatcher","Id":"3","Request":"Stop","Params":"'params redacted'"}
[LOG] 0:11.981 DEBUG juju.apiserver -> [11] unit-wordpress-0 9.99883956s {"RequestId":14,"Error":"watcher has been stopped","ErrorCode":"stopped","Response":"'body redacted'"} NotifyWatcher["3"].Next
[LOG] 0:11.981 DEBUG juju.apiserver -> [11] unit-wordpress-0 177.591us {"RequestId":35,"Response":"'body redacted'"} NotifyWatcher["3"].Stop
[LOG] 0:11.981 DEBUG juju.apiserver <- [11] unit-wordpress-0 {"RequestId":36,"Type":"NotifyWatcher","Id":"2","Request":"Stop","Params":"'params redacted'"}
[LOG] 0:11.981 DEBUG juju.apiserver -> [11] unit-wordpress-0 10.000368387s {"RequestId":12,"Error":"watcher has been stopped","ErrorCode":"stopped","Response":"'body redacted'"} NotifyWatcher["2"].Next
[LOG] 0:11.981 DEBUG juju.apiserver -> [11] unit-wordpress-0 290.576us {"RequestId":36,"Response":"'body redacted'"} NotifyWatcher["2"].Stop
[LOG] 0:11.981 ERROR juju.worker.uniter.filter tomb: dying
[LOG] 0:11.983 INFO juju.apiserver [10] user-dummy-admin@local API connection terminated after 10.611778205s
[LOG] 0:11.985 INFO juju.apiserver [11] unit-wordpress-0 API connection terminated after 10.108064092s
[LOG] 0:11.985 INFO juju.provider.dummy reset environment
[LOG] 0:11.996 INFO juju.testing reset successfully reset admin password
[LOG] 0:12.070 INFO juju.testing reset successfully reset admin password
[LOG] 0:12.072 INFO juju.testing reset successfully reset admin password
OOPS: 14 passed, 1 FAILED
--- FAIL: TestPackage (42.10 seconds)
FAIL
FAIL github.com/juju/juju/worker/uniter/filter 42.476s

Tags:

Curtis Hovey (sinzui) on 2015-03-25

Changed in juju-core:
status:	New → Triaged
importance:	Undecided → Medium
tags:	added: intermittent-failure

Casey Marshall (cmars) on 2015-03-25

Changed in juju-core:
assignee:	nobody → Casey Marshall (cmars)
status:	Triaged → In Progress

Revision history for this message

Casey Marshall (cmars) wrote on 2015-03-25:

I'm not able to reproduce the above failure with the same error. Intermittently, I'm getting cascading errors that follow this one:

```
filter_test.go:45:
s.wpcharm = s.AddTestingCharm(c, "wordpress")
/home/ubuntu/canonical/gocode/src/github.com/juju/juju/juju/testing/conn.go:553:
c.Assert(err, jc.ErrorIsNil)
... value *errors.errorString = &errors.errorString{s:"cannot put charm: cannot clean up after failed storage operation because: read tcp 127.0.0.1:390
92: i/o timeout: cannot add resource \"environs/deadbeef-0bad-400d-8000-4b1d0d06f00d/charms/local:quantal/wordpress-3-934186c5b32fc367151eee38a14185cab
1117ebd3f0b7f24a6c358e2dc9fd4f9\" to store at storage path \"02b3d48b-9ddf-4d23-8753-a5899809432d\": failed to flush data: read tcp 127.0.0.1:39092: i/
o timeout"} ("cannot put charm: cannot clean up after failed storage operation because: read tcp 127.0.0.1:39092: i/o timeout: cannot add resource \"en
virons/deadbeef-0bad-400d-8000-4b1d0d06f00d/charms/local:quantal/wordpress-3-934186c5b32fc367151eee38a14185cab1117ebd3f0b7f24a6c358e2dc9fd4f9\" to stor
e at storage path \"02b3d48b-9ddf-4d23-8753-a5899809432d\": failed to flush data: read tcp 127.0.0.1:39092: i/o timeout")
```

Revision history for this message

Casey Marshall (cmars) wrote on 2015-03-26:

At 0688e47a79a600e3d55e6b78c90d1f292c5a0512, cannot reproduce on a vivid amd64 KVM instance:

$ for i in {1..10}; do go test ./worker/uniter/filter; done
ok github.com/juju/juju/worker/uniter/filter 12.182s
ok github.com/juju/juju/worker/uniter/filter 11.970s
ok github.com/juju/juju/worker/uniter/filter 11.956s
ok github.com/juju/juju/worker/uniter/filter 12.060s
ok github.com/juju/juju/worker/uniter/filter 12.381s
ok github.com/juju/juju/worker/uniter/filter 12.660s
ok github.com/juju/juju/worker/uniter/filter 12.119s
ok github.com/juju/juju/worker/uniter/filter 12.245s
ok github.com/juju/juju/worker/uniter/filter 12.027s
ok github.com/juju/juju/worker/uniter/filter 12.485s

Changed in juju-core:
status:	In Progress → Invalid

Report a bug

This report contains Public information

Everyone can see this information.

You are

Subscribing...

Edit bug mail

Other bug subscribers

Remote bug watches

Bug watches keep track of this bug in other bug trackers.