FAIL: filter_test.go:449: FilterSuite.TestConfigAndAddressEventsDiscarded
[LOG] 0:00.002 DEBUG juju.environs.configstore Made dir /mnt/tmp/check-5577006791947779410/22/home/ubuntu/.juju/environments
[LOG] 0:00.076 DEBUG juju.environs.configstore writing jenv file to /mnt/tmp/check-5577006791947779410/22/home/ubuntu/.juju/environments/dummyenv.jenv
[LOG] 0:00.076 DEBUG juju.environs.tools reading v1.* tools
[LOG] 0:00.076 INFO juju.environs.testing uploading FAKE tools 1.22-alpha1-trusty-amd64
[LOG] 0:00.080 INFO juju.environs.testing uploading FAKE tools 1.22-alpha1-precise-amd64
[LOG] 0:00.080 INFO juju.environs.testing uploading FAKE tools 1.22-alpha1-trusty-amd64
[LOG] 0:00.083 DEBUG juju.environs.tools no architecture specified when finding tools, looking for any
[LOG] 0:00.083 DEBUG juju.environs.tools no series specified when finding tools, looking for any
[LOG] 0:00.083 DEBUG juju.environs.simplestreams searching for metadata in datasource "existing metadata"
[LOG] 0:00.083 DEBUG juju.environs.simplestreams fetchData failed for "file:///mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index2.sjson": stat /mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index2.sjson: no such file or directory
[LOG] 0:00.083 DEBUG juju.environs.simplestreams streams/v1/index2.sjson not found, trying legacy index file
[LOG] 0:00.083 DEBUG juju.environs.simplestreams fetchData failed for "file:///mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index.sjson": stat /mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index.sjson: no such file or directory
[LOG] 0:00.083 DEBUG juju.environs.simplestreams cannot load index "file:///mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index.sjson": invalid URL "file:///mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index.sjson" not found
[LOG] 0:00.083 DEBUG juju.environs.simplestreams fetchData failed for "file:///mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index2.json": stat /mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index2.json: no such file or directory
[LOG] 0:00.083 DEBUG juju.environs.simplestreams streams/v1/index2.json not found, trying legacy index file
[LOG] 0:00.083 DEBUG juju.environs.simplestreams fetchData failed for "file:///mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index.json": stat /mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index.json: no such file or directory
[LOG] 0:00.083 DEBUG juju.environs.simplestreams cannot load index "file:///mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index.json": invalid URL "file:///mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index.json" not found
[LOG] 0:00.083 DEBUG juju.environs.tools no architecture specified when finding tools, looking for any
[LOG] 0:00.083 DEBUG juju.environs.tools no series specified when finding tools, looking for any
[LOG] 0:00.083 DEBUG juju.environs.simplestreams searching for metadata in datasource "existing metadata"
[LOG] 0:00.083 DEBUG juju.environs.simplestreams fetchData failed for "file:///mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index2.sjson": stat /mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index2.sjson: no such file or directory
[LOG] 0:00.083 DEBUG juju.environs.simplestreams streams/v1/index2.sjson not found, trying legacy index file
[LOG] 0:00.083 DEBUG juju.environs.simplestreams fetchData failed for "file:///mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index.sjson": stat /mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index.sjson: no such file or directory
[LOG] 0:00.083 DEBUG juju.environs.simplestreams cannot load index "file:///mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index.sjson": invalid URL "file:///mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index.sjson" not found
[LOG] 0:00.083 DEBUG juju.environs.simplestreams fetchData failed for "file:///mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index2.json": stat /mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index2.json: no such file or directory
[LOG] 0:00.083 DEBUG juju.environs.simplestreams streams/v1/index2.json not found, trying legacy index file
[LOG] 0:00.083 DEBUG juju.environs.simplestreams fetchData failed for "file:///mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index.json": stat /mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index.json: no such file or directory
[LOG] 0:00.083 DEBUG juju.environs.simplestreams cannot load index "file:///mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index.json": invalid URL "file:///mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index.json" not found
[LOG] 0:00.083 DEBUG juju.environs.tools no architecture specified when finding tools, looking for any
[LOG] 0:00.083 DEBUG juju.environs.tools no series specified when finding tools, looking for any
[LOG] 0:00.084 DEBUG juju.environs.simplestreams searching for metadata in datasource "existing metadata"
[LOG] 0:00.084 DEBUG juju.environs.simplestreams fetchData failed for "file:///mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index2.sjson": stat /mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index2.sjson: no such file or directory
[LOG] 0:00.084 DEBUG juju.environs.simplestreams streams/v1/index2.sjson not found, trying legacy index file
[LOG] 0:00.084 DEBUG juju.environs.simplestreams fetchData failed for "file:///mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index.sjson": stat /mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index.sjson: no such file or directory
[LOG] 0:00.084 DEBUG juju.environs.simplestreams cannot load index "file:///mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index.sjson": invalid URL "file:///mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index.sjson" not found
[LOG] 0:00.084 DEBUG juju.environs.simplestreams fetchData failed for "file:///mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index2.json": stat /mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index2.json: no such file or directory
[LOG] 0:00.084 DEBUG juju.environs.simplestreams streams/v1/index2.json not found, trying legacy index file
[LOG] 0:00.084 DEBUG juju.environs.simplestreams fetchData failed for "file:///mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index.json": stat /mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index.json: no such file or directory
[LOG] 0:00.084 DEBUG juju.environs.simplestreams cannot load index "file:///mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index.json": invalid URL "file:///mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index.json" not found
[LOG] 0:00.084 DEBUG juju.environs.tools no architecture specified when finding tools, looking for any
[LOG] 0:00.084 DEBUG juju.environs.tools no series specified when finding tools, looking for any
[LOG] 0:00.084 DEBUG juju.environs.simplestreams searching for metadata in datasource "existing metadata"
[LOG] 0:00.084 DEBUG juju.environs.simplestreams fetchData failed for "file:///mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index2.sjson": stat /mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index2.sjson: no such file or directory
[LOG] 0:00.084 DEBUG juju.environs.simplestreams streams/v1/index2.sjson not found, trying legacy index file
[LOG] 0:00.084 DEBUG juju.environs.simplestreams fetchData failed for "file:///mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index.sjson": stat /mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index.sjson: no such file or directory
[LOG] 0:00.084 DEBUG juju.environs.simplestreams cannot load index "file:///mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index.sjson": invalid URL "file:///mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index.sjson" not found
[LOG] 0:00.084 DEBUG juju.environs.simplestreams fetchData failed for "file:///mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index2.json": stat /mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index2.json: no such file or directory
[LOG] 0:00.084 DEBUG juju.environs.simplestreams streams/v1/index2.json not found, trying legacy index file
[LOG] 0:00.084 DEBUG juju.environs.simplestreams fetchData failed for "file:///mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index.json": stat /mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index.json: no such file or directory
[LOG] 0:00.084 DEBUG juju.environs.simplestreams cannot load index "file:///mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index.json": invalid URL "file:///mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index.json" not found
[LOG] 0:00.084 INFO juju.environs.tools Writing tools/streams/v1/index2.json
[LOG] 0:00.084 INFO juju.environs.tools Writing tools/streams/v1/com.ubuntu.juju:released:tools.json
[LOG] 0:00.084 DEBUG juju.environs.tools no architecture specified when finding tools, looking for any
[LOG] 0:00.084 DEBUG juju.environs.tools no series specified when finding tools, looking for any
[LOG] 0:00.085 DEBUG juju.environs.simplestreams searching for metadata in datasource "existing metadata"
[LOG] 0:00.085 DEBUG juju.environs.simplestreams fetchData failed for "file:///mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index2.sjson": stat /mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index2.sjson: no such file or directory
[LOG] 0:00.085 DEBUG juju.environs.simplestreams streams/v1/index2.sjson not found, trying legacy index file
[LOG] 0:00.085 DEBUG juju.environs.simplestreams fetchData failed for "file:///mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index.sjson": stat /mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index.sjson: no such file or directory
[LOG] 0:00.085 DEBUG juju.environs.simplestreams cannot load index "file:///mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index.sjson": invalid URL "file:///mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index.sjson" not found
[LOG] 0:00.085 DEBUG juju.environs.simplestreams fetchData failed for "file:///mnt/tmp/check-5577006791947779410/25/tools/streams/v1/mirrors.json": stat /mnt/tmp/check-5577006791947779410/25/tools/streams/v1/mirrors.json: no such file or directory
[LOG] 0:00.085 DEBUG juju.environs.simplestreams no mirror index file found
[LOG] 0:00.085 DEBUG juju.environs.simplestreams no mirror information available for { }: mirror data for "com.ubuntu.juju:released:tools" not found
[LOG] 0:00.085 DEBUG juju.environs.simplestreams read metadata index at "file:///mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index2.json"
[LOG] 0:00.085 DEBUG juju.environs.simplestreams candidate matches for products ["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: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: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: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: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: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: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: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: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: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: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: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 [{Mon, 10 Nov 2014 07:20:16 +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]}]
[LOG] 0:00.085 DEBUG juju.environs.simplestreams finding products at path "streams/v1/com.ubuntu.juju:released:tools.json"
[LOG] 0:00.085 DEBUG juju.environs.simplestreams metadata: &{map[com.ubuntu.juju:12.04:amd64:{ 1.22-alpha1 amd64 map[20141110:0xc210527d20]} com.ubuntu.juju:14.04:amd64:{ 1.22-alpha1 amd64 map[20141110:0xc210527e40]}] map[] Mon, 10 Nov 2014 07:20:16 +0000 products:1.0 com.ubuntu.juju:released:tools }
[LOG] 0:00.086 DEBUG juju.environs.tools no architecture specified when finding tools, looking for any
[LOG] 0:00.086 DEBUG juju.environs.tools no series specified when finding tools, looking for any
[LOG] 0:00.086 DEBUG juju.environs.simplestreams searching for metadata in datasource "existing metadata"
[LOG] 0:00.086 DEBUG juju.environs.simplestreams fetchData failed for "file:///mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index2.sjson": stat /mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index2.sjson: no such file or directory
[LOG] 0:00.086 DEBUG juju.environs.simplestreams streams/v1/index2.sjson not found, trying legacy index file
[LOG] 0:00.086 DEBUG juju.environs.simplestreams fetchData failed for "file:///mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index.sjson": stat /mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index.sjson: no such file or directory
[LOG] 0:00.086 DEBUG juju.environs.simplestreams cannot load index "file:///mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index.sjson": invalid URL "file:///mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index.sjson" not found
[LOG] 0:00.086 DEBUG juju.environs.simplestreams fetchData failed for "file:///mnt/tmp/check-5577006791947779410/25/tools/streams/v1/mirrors.json": stat /mnt/tmp/check-5577006791947779410/25/tools/streams/v1/mirrors.json: no such file or directory
[LOG] 0:00.086 DEBUG juju.environs.simplestreams no mirror index file found
[LOG] 0:00.086 DEBUG juju.environs.simplestreams no mirror information available for { }: mirror data for "com.ubuntu.juju:proposed:tools" not found
[LOG] 0:00.086 DEBUG juju.environs.simplestreams read metadata index at "file:///mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index2.json"
[LOG] 0:00.086 DEBUG juju.environs.simplestreams skipping index because of error getting latest metadata "file:///mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index2.json": index file missing "content-download" data not found
[LOG] 0:00.086 DEBUG juju.environs.tools no architecture specified when finding tools, looking for any
[LOG] 0:00.086 DEBUG juju.environs.tools no series specified when finding tools, looking for any
[LOG] 0:00.086 DEBUG juju.environs.simplestreams searching for metadata in datasource "existing metadata"
[LOG] 0:00.086 DEBUG juju.environs.simplestreams fetchData failed for "file:///mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index2.sjson": stat /mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index2.sjson: no such file or directory
[LOG] 0:00.086 DEBUG juju.environs.simplestreams streams/v1/index2.sjson not found, trying legacy index file
[LOG] 0:00.086 DEBUG juju.environs.simplestreams fetchData failed for "file:///mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index.sjson": stat /mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index.sjson: no such file or directory
[LOG] 0:00.086 DEBUG juju.environs.simplestreams cannot load index "file:///mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index.sjson": invalid URL "file:///mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index.sjson" not found
[LOG] 0:00.086 DEBUG juju.environs.simplestreams fetchData failed for "file:///mnt/tmp/check-5577006791947779410/25/tools/streams/v1/mirrors.json": stat /mnt/tmp/check-5577006791947779410/25/tools/streams/v1/mirrors.json: no such file or directory
[LOG] 0:00.086 DEBUG juju.environs.simplestreams no mirror index file found
[LOG] 0:00.086 DEBUG juju.environs.simplestreams no mirror information available for { }: mirror data for "com.ubuntu.juju:testing:tools" not found
[LOG] 0:00.086 DEBUG juju.environs.simplestreams read metadata index at "file:///mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index2.json"
[LOG] 0:00.087 DEBUG juju.environs.simplestreams skipping index because of error getting latest metadata "file:///mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index2.json": index file missing "content-download" data not found
[LOG] 0:00.087 DEBUG juju.environs.tools no architecture specified when finding tools, looking for any
[LOG] 0:00.087 DEBUG juju.environs.tools no series specified when finding tools, looking for any
[LOG] 0:00.087 DEBUG juju.environs.simplestreams searching for metadata in datasource "existing metadata"
[LOG] 0:00.087 DEBUG juju.environs.simplestreams fetchData failed for "file:///mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index2.sjson": stat /mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index2.sjson: no such file or directory
[LOG] 0:00.087 DEBUG juju.environs.simplestreams streams/v1/index2.sjson not found, trying legacy index file
[LOG] 0:00.087 DEBUG juju.environs.simplestreams fetchData failed for "file:///mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index.sjson": stat /mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index.sjson: no such file or directory
[LOG] 0:00.087 DEBUG juju.environs.simplestreams cannot load index "file:///mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index.sjson": invalid URL "file:///mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index.sjson" not found
[LOG] 0:00.087 DEBUG juju.environs.simplestreams fetchData failed for "file:///mnt/tmp/check-5577006791947779410/25/tools/streams/v1/mirrors.json": stat /mnt/tmp/check-5577006791947779410/25/tools/streams/v1/mirrors.json: no such file or directory
[LOG] 0:00.087 DEBUG juju.environs.simplestreams no mirror index file found
[LOG] 0:00.087 DEBUG juju.environs.simplestreams no mirror information available for { }: mirror data for "com.ubuntu.juju:devel:tools" not found
[LOG] 0:00.087 DEBUG juju.environs.simplestreams read metadata index at "file:///mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index2.json"
[LOG] 0:00.087 DEBUG juju.environs.simplestreams skipping index because of error getting latest metadata "file:///mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index2.json": index file missing "content-download" data not found
[LOG] 0:00.087 INFO juju.environs.tools Metadata for stream "released" unchanged
[LOG] 0:00.087 INFO juju.environs.tools Writing tools/streams/v1/index2.json
[LOG] 0:00.087 INFO juju.network setting prefer-ipv6 to true
[LOG] 0:00.088 DEBUG juju.environs.bootstrap environment "dummyenv" supports service/machine networks: true
[LOG] 0:00.088 DEBUG juju.environs.bootstrap network management by juju enabled: false
[LOG] 0:00.088 DEBUG juju.environs.bootstrap looking for bootstrap tools: version=1.22-alpha1
[LOG] 0:00.088 INFO juju.environs.tools reading tools with major.minor version 1.22
[LOG] 0:00.088 INFO juju.environs.tools filtering tools by version: 1.22-alpha1
[LOG] 0:00.088 DEBUG juju.environs.tools no architecture specified when finding tools, looking for any
[LOG] 0:00.088 DEBUG juju.environs.tools no series specified when finding tools, looking for any
[LOG] 0:00.088 DEBUG juju.environs.simplestreams searching for metadata in datasource "default simplestreams"
[LOG] 0:00.088 INFO juju.utils hostname SSL verification enabled
[LOG] 0:00.088 DEBUG juju.environs.simplestreams fetchData failed for "file:///mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index2.sjson": cannot find URL "file:///mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index2.sjson" not found
[LOG] 0:00.088 DEBUG juju.environs.simplestreams streams/v1/index2.sjson not found, trying legacy index file
[LOG] 0:00.088 INFO juju.utils hostname SSL verification enabled
[LOG] 0:00.088 DEBUG juju.environs.simplestreams fetchData failed for "file:///mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index.sjson": cannot find URL "file:///mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index.sjson" not found
[LOG] 0:00.088 DEBUG juju.environs.simplestreams cannot load index "file:///mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index.sjson": invalid URL "file:///mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index.sjson" not found
[LOG] 0:00.088 INFO juju.utils hostname SSL verification enabled
[LOG] 0:00.088 INFO juju.utils hostname SSL verification enabled
[LOG] 0:00.088 DEBUG juju.environs.simplestreams fetchData failed for "file:///mnt/tmp/check-5577006791947779410/25/tools/streams/v1/mirrors.json": cannot find URL "file:///mnt/tmp/check-5577006791947779410/25/tools/streams/v1/mirrors.json" not found
[LOG] 0:00.088 DEBUG juju.environs.simplestreams no mirror index file found
[LOG] 0:00.088 DEBUG juju.environs.simplestreams no mirror information available for { }: mirror data for "com.ubuntu.juju:released:tools" not found
[LOG] 0:00.088 DEBUG juju.environs.simplestreams read metadata index at "file:///mnt/tmp/check-5577006791947779410/25/tools/streams/v1/index2.json"
[LOG] 0:00.088 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: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: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: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: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: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: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: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: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: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: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:win2012:amd64" "com.ubuntu.juju:win2012:i386" "com.ubuntu.juju:win2012:armhf" "com.ubuntu.juju:win2012:arm64" "com.ubuntu.juju:win2012:ppc64el"] are [{Mon, 10 Nov 2014 07:20:16 +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]}]
[LOG] 0:00.089 DEBUG juju.environs.simplestreams finding products at path "streams/v1/com.ubuntu.juju:released:tools.json"
[LOG] 0:00.089 INFO juju.utils hostname SSL verification enabled
[LOG] 0:00.089 DEBUG juju.environs.simplestreams metadata: &{map[com.ubuntu.juju:12.04:amd64:{ 1.22-alpha1 amd64 map[20141110:0xc210298f00]} com.ubuntu.juju:14.04:amd64:{ 1.22-alpha1 amd64 map[20141110:0xc21038b060]}] map[] Mon, 10 Nov 2014 07:20:16 +0000 products:1.0 com.ubuntu.juju:released:tools }
[LOG] 0:00.121 INFO juju.network setting prefer-ipv6 to true
[LOG] 0:00.121 INFO juju.provider.dummy would pick tools from 1.22-alpha1-trusty-amd64
[LOG] 0:00.121 INFO juju.provider.dummy creating bootstrap instance
[LOG] 0:00.121 INFO juju.state opening state, mongo addresses: ["[::1]:54726" "localhost:54726"]; entity %!q(<nil>)
[LOG] 0:00.121 DEBUG juju.state dialing mongo
[LOG] 0:00.143 INFO juju.mongo dialled mongo successfully on address "127.0.0.1:54726"
[LOG] 0:00.143 INFO juju.mongo dialled mongo successfully on address "[::1]:54726"
[LOG] 0:00.144 DEBUG juju.state connection established
[LOG] 0:00.158 INFO juju.state initializing environment, owner: "dummy-admin@local"
[LOG] 0:00.158 INFO juju.state info: &mongo.MongoInfo{Info:mongo.Info{Addrs:[]string{"[::1]:54726", "localhost:54726"}, CACert:"-----BEGIN CERTIFICATE-----\nMIIB5DCCAZCgAwIBAgIBADALBgkqhkiG9w0BAQUwSjENMAsGA1UEChMEanVqdTE5\nMDcGA1UEAwwwanVqdS1nZW5lcmF0ZWQgQ0EgZm9yIGVudmlyb25tZW50ICJqdWp1\nIHRlc3RpbmciMB4XDTE0MTEwMzA3MjAxM1oXDTI0MTExMDA3MjAxM1owSjENMAsG\nA1UEChMEanVqdTE5MDcGA1UEAwwwanVqdS1nZW5lcmF0ZWQgQ0EgZm9yIGVudmly\nb25tZW50ICJqdWp1IHRlc3RpbmciMFwwDQYJKoZIhvcNAQEBBQADSwAwSAJBAKgr\nT/Fi2qVY5ue5IXh34cKDzCv3i/KeSpVAdaCXLtUWVM/PcVhhM5ZaPK0zr4ZaEJfn\npJxxG68PYAv1YLCerIECAwEAAaNjMGEwDgYDVR0PAQH/BAQDAgCkMA8GA1UdEwEB\n/wQFMAMBAf8wHQYDVR0OBBYEFE6i7Yl1n8J3wPweWAXWm9YPPeFWMB8GA1UdIwQY\nMBaAFE6i7Yl1n8J3wPweWAXWm9YPPeFWMAsGCSqGSIb3DQEBBQNBAHke8KR0yxIG\nV8BbCDtCGazrEHEETyOzhCYK4O2EFWlFSrdUnkaZOPjWqe/hT1zoCkMKQb9bxugm\nCiWkeR0eIhA=\n-----END CERTIFICATE-----\n"}, Tag:names.Tag(nil), Password:""}
[LOG] 0:00.203 DEBUG juju.provider.dummy setting password for "dummy-admin" to "dummy-secret"
[LOG] 0:00.207 INFO juju.apiserver listening on "[::]:59900"
[LOG] 0:00.218 INFO juju.environs.bootstrap newest version: 1.22-alpha1
[LOG] 0:00.218 INFO juju.environs.bootstrap picked bootstrap tools version: 1.22-alpha1
[LOG] 0:00.218 INFO juju.state opening state, mongo addresses: ["[::1]:54726" "localhost:54726"]; entity %!q(<nil>)
[LOG] 0:00.218 DEBUG juju.state dialing mongo
[LOG] 0:00.227 INFO juju.mongo dialled mongo successfully on address "127.0.0.1:54726"
[LOG] 0:00.228 DEBUG juju.state connection established
[LOG] 0:00.228 INFO juju.mongo dialled mongo successfully on address "[::1]:54726"
[LOG] 0:00.231 INFO juju.mongo dialled mongo successfully on address "127.0.0.1:54726"
[LOG] 0:00.263 DEBUG juju.environs StateServerInstances returned: [localhost]
[LOG] 0:00.263 INFO juju.api dialing "wss://localhost:59900/"
[LOG] 0:00.274 INFO juju.apiserver [A] API connection from 127.0.0.1:59323
[LOG] 0:00.274 INFO juju.api connection established to "wss://localhost:59900/"
[LOG] 0:00.274 DEBUG juju.apiserver <- [A] <unknown> {"RequestId":1,"Type":"Admin","Version":1,"Request":"Login","Params":{"auth-tag":"user-dummy-admin@local","credentials":"dummy-secret","nonce":""}}
[LOG] 0:00.277 DEBUG juju.apiserver hostPorts: []
[LOG] 0:00.278 DEBUG juju.apiserver -> [A] user-dummy-admin@local 3.863633ms {"RequestId":1,"Response":{"servers":[],"environ-tag":"environment-90168e4c-2f10-4e9c-83c2-feedfacee5a9","server-tag":"","user-info":{"display-name":"","identity":"user-dummy-admin@local"},"facades":[{"Name":"Actions","Versions":[0]},{"Name":"Agent","Versions":[0,1]},{"Name":"AllWatcher","Versions":[0]},{"Name":"Backups","Versions":[0]},{"Name":"CharmRevisionUpdater","Versions":[0]},{"Name":"Client","Versions":[0]},{"Name":"Deployer","Versions":[0]},{"Name":"Environment","Versions":[0]},{"Name":"Firewaller","Versions":[1]},{"Name":"HighAvailability","Versions":[1]},{"Name":"KeyManager","Versions":[0]},{"Name":"KeyUpdater","Versions":[0]},{"Name":"Logger","Versions":[0]},{"Name":"Machiner","Versions":[0]},{"Name":"MetricsManager","Versions":[0]},{"Name":"Networker","Versions":[0]},{"Name":"NotifyWatcher","Versions":[0]},{"Name":"Pinger","Versions":[0]},{"Name":"Provisioner","Versions":[0]},{"Name":"Reboot","Versions":[1]},{"Name":"RelationUnitsWatcher","Versions":[0]},{"Name":"Rsyslog","Versions":[0]},{"Name":"StringsWatcher","Versions":[0]},{"Name":"Uniter","Versions":[0,1]},{"Name":"Upgrader","Versions":[0]},{"Name":"UserManager","Versions":[0]}]}} Admin[""].Login
[LOG] 0:00.301 DEBUG juju.storage resource catalog entry created with id "47d6e7812099383e8266f590d37b7f5369ef43931aecc72a4b86674e7052f346a391648209b8e69ea5b649d2c0e86da0"
[LOG] 0:00.314 DEBUG juju.storage managed resource entry created with path "environs/90168e4c-2f10-4e9c-83c2-feedfacee5a9/charms/local:quantal/wordpress-3-1c63244965a3e1934da1a9713f0ea5620d13975a0f0b4c73aa259bc7f8083e0c" -> "47d6e7812099383e8266f590d37b7f5369ef43931aecc72a4b86674e7052f346a391648209b8e69ea5b649d2c0e86da0"
[LOG] 0:00.380 DEBUG juju.environs StateServerInstances returned: [localhost]
[LOG] 0:00.381 INFO juju.api dialing "wss://localhost:59900/environment/90168e4c-2f10-4e9c-83c2-feedfacee5a9/api"
[LOG] 0:00.388 INFO juju.apiserver [B] API connection from 127.0.0.1:59334
[LOG] 0:00.388 INFO juju.api connection established to "wss://localhost:59900/environment/90168e4c-2f10-4e9c-83c2-feedfacee5a9/api"
[LOG] 0:00.388 DEBUG juju.apiserver <- [B] <unknown> {"RequestId":1,"Type":"Admin","Version":1,"Request":"Login","Params":{"auth-tag":"unit-wordpress-0","credentials":"qWYBfrVNk4y6qPuU0dVpmeYd","nonce":""}}
[LOG] 0:00.395 DEBUG juju.apiserver hostPorts: [[localhost]]
[LOG] 0:00.395 DEBUG juju.apiserver -> [B] unit-wordpress-0 6.773608ms {"RequestId":1,"Response":{"servers":[[{"Value":"localhost","Type":"hostname","NetworkName":"","Scope":"","Port":59900}]],"environ-tag":"environment-90168e4c-2f10-4e9c-83c2-feedfacee5a9","server-tag":"","facades":[{"Name":"Actions","Versions":[0]},{"Name":"Agent","Versions":[0,1]},{"Name":"AllWatcher","Versions":[0]},{"Name":"Backups","Versions":[0]},{"Name":"CharmRevisionUpdater","Versions":[0]},{"Name":"Client","Versions":[0]},{"Name":"Deployer","Versions":[0]},{"Name":"Environment","Versions":[0]},{"Name":"Firewaller","Versions":[1]},{"Name":"HighAvailability","Versions":[1]},{"Name":"KeyManager","Versions":[0]},{"Name":"KeyUpdater","Versions":[0]},{"Name":"Logger","Versions":[0]},{"Name":"Machiner","Versions":[0]},{"Name":"MetricsManager","Versions":[0]},{"Name":"Networker","Versions":[0]},{"Name":"NotifyWatcher","Versions":[0]},{"Name":"Pinger","Versions":[0]},{"Name":"Provisioner","Versions":[0]},{"Name":"Reboot","Versions":[1]},{"Name":"RelationUnitsWatcher","Versions":[0]},{"Name":"Rsyslog","Versions":[0]},{"Name":"StringsWatcher","Versions":[0]},{"Name":"Uniter","Versions":[0,1]},{"Name":"Upgrader","Versions":[0]},{"Name":"UserManager","Versions":[0]}]}} Admin[""].Login
[LOG] 0:00.396 DEBUG juju.apiserver <- [B] unit-wordpress-0 {"RequestId":3,"Type":"Uniter","Version":1,"Request":"Life","Params":{"Entities":[{"Tag":"unit-wordpress-0"}]}}
[LOG] 0:00.398 DEBUG juju.apiserver -> [B] unit-wordpress-0 1.52399ms {"RequestId":3,"Response":{"Results":[{"Life":"alive","Error":null}]}} Uniter[""].Life
[LOG] 0:00.398 DEBUG juju.apiserver <- [B] unit-wordpress-0 {"RequestId":4,"Type":"Uniter","Version":1,"Request":"Life","Params":{"Entities":[{"Tag":"unit-wordpress-0"}]}}
[LOG] 0:00.399 DEBUG juju.apiserver -> [B] unit-wordpress-0 630.275us {"RequestId":4,"Response":{"Results":[{"Life":"alive","Error":null}]}} Uniter[""].Life
[LOG] 0:00.399 DEBUG juju.apiserver <- [B] unit-wordpress-0 {"RequestId":5,"Type":"Uniter","Version":1,"Request":"Resolved","Params":{"Entities":[{"Tag":"unit-wordpress-0"}]}}
[LOG] 0:00.399 DEBUG juju.apiserver -> [B] unit-wordpress-0 282.661us {"RequestId":5,"Response":{"Results":[{"Error":null,"Mode":""}]}} Uniter[""].Resolved
[LOG] 0:00.399 DEBUG juju.apiserver <- [B] unit-wordpress-0 {"RequestId":6,"Type":"Uniter","Version":1,"Request":"GetMeterStatus","Params":{"Entities":[{"Tag":"unit-wordpress-0"}]}}
[LOG] 0:00.400 DEBUG juju.apiserver -> [B] unit-wordpress-0 483.106us {"RequestId":6,"Response":{"Results":[{"Code":"NOT SET","Info":"","Error":null}]}} Uniter[""].GetMeterStatus
[LOG] 0:00.400 DEBUG juju.apiserver <- [B] unit-wordpress-0 {"RequestId":7,"Type":"Uniter","Version":1,"Request":"Life","Params":{"Entities":[{"Tag":"service-wordpress"}]}}
[LOG] 0:00.400 DEBUG juju.apiserver -> [B] unit-wordpress-0 499.758us {"RequestId":7,"Response":{"Results":[{"Life":"alive","Error":null}]}} Uniter[""].Life
[LOG] 0:00.401 DEBUG juju.apiserver <- [B] unit-wordpress-0 {"RequestId":8,"Type":"Uniter","Version":1,"Request":"Life","Params":{"Entities":[{"Tag":"service-wordpress"}]}}
[LOG] 0:00.401 DEBUG juju.apiserver -> [B] unit-wordpress-0 562.306us {"RequestId":8,"Response":{"Results":[{"Life":"alive","Error":null}]}} Uniter[""].Life
[LOG] 0:00.401 DEBUG juju.apiserver <- [B] unit-wordpress-0 {"RequestId":9,"Type":"Uniter","Version":1,"Request":"CharmURL","Params":{"Entities":[{"Tag":"service-wordpress"}]}}
[LOG] 0:00.402 DEBUG juju.apiserver -> [B] unit-wordpress-0 563.286us {"RequestId":9,"Response":{"Results":[{"Error":null,"Result":"local:quantal/wordpress-3","Ok":false}]}} Uniter[""].CharmURL
[LOG] 0:00.402 DEBUG juju.worker.uniter.filter charm check skipped, not yet installed.
[LOG] 0:00.402 DEBUG juju.apiserver <- [B] unit-wordpress-0 {"RequestId":10,"Type":"Uniter","Version":1,"Request":"Watch","Params":{"Entities":[{"Tag":"unit-wordpress-0"}]}}
[LOG] 0:00.403 DEBUG juju.apiserver -> [B] unit-wordpress-0 900.832us {"RequestId":10,"Response":{"Results":[{"NotifyWatcherId":"2","Error":null}]}} Uniter[""].Watch
[LOG] 0:00.403 DEBUG juju.apiserver <- [B] unit-wordpress-0 {"RequestId":11,"Type":"Uniter","Version":1,"Request":"Watch","Params":{"Entities":[{"Tag":"service-wordpress"}]}}
[LOG] 0:00.404 DEBUG juju.apiserver <- [B] unit-wordpress-0 {"RequestId":12,"Type":"NotifyWatcher","Id":"2","Request":"Next","Params":{}}
[LOG] 0:00.404 DEBUG juju.apiserver -> [B] unit-wordpress-0 797.051us {"RequestId":11,"Response":{"Results":[{"NotifyWatcherId":"3","Error":null}]}} Uniter[""].Watch
[LOG] 0:00.405 DEBUG juju.apiserver <- [B] unit-wordpress-0 {"RequestId":13,"Type":"Uniter","Version":1,"Request":"CharmURL","Params":{"Entities":[{"Tag":"unit-wordpress-0"}]}}
[LOG] 0:00.405 DEBUG juju.apiserver <- [B] unit-wordpress-0 {"RequestId":14,"Type":"NotifyWatcher","Id":"3","Request":"Next","Params":{}}
[LOG] 0:00.405 DEBUG juju.apiserver -> [B] unit-wordpress-0 568.721us {"RequestId":13,"Response":{"Results":[{"Error":null,"Result":"","Ok":false}]}} Uniter[""].CharmURL
[LOG] 0:00.405 DEBUG juju.apiserver <- [B] unit-wordpress-0 {"RequestId":15,"Type":"Uniter","Version":1,"Request":"WatchActions","Params":{"Entities":[{"Tag":"unit-wordpress-0"}]}}
[LOG] 0:00.406 DEBUG juju.apiserver -> [B] unit-wordpress-0 461.182us {"RequestId":15,"Response":{"Results":[{"StringsWatcherId":"4","Changes":null,"Error":null}]}} Uniter[""].WatchActions
[LOG] 0:00.406 DEBUG juju.apiserver <- [B] unit-wordpress-0 {"RequestId":16,"Type":"Uniter","Version":1,"Request":"WatchServiceRelations","Params":{"Entities":[{"Tag":"service-wordpress"}]}}
[LOG] 0:00.406 DEBUG juju.apiserver <- [B] unit-wordpress-0 {"RequestId":17,"Type":"StringsWatcher","Id":"4","Request":"Next","Params":{}}
[LOG] 0:00.407 DEBUG juju.apiserver -> [B] unit-wordpress-0 887.709us {"RequestId":16,"Response":{"Results":[{"StringsWatcherId":"5","Changes":[],"Error":null}]}} Uniter[""].WatchServiceRelations
[LOG] 0:00.407 DEBUG juju.apiserver <- [B] unit-wordpress-0 {"RequestId":18,"Type":"Uniter","Version":1,"Request":"WatchMeterStatus","Params":{"Entities":[{"Tag":"unit-wordpress-0"}]}}
[LOG] 0:00.407 DEBUG juju.apiserver <- [B] unit-wordpress-0 {"RequestId":19,"Type":"StringsWatcher","Id":"5","Request":"Next","Params":{}}
[LOG] 0:00.408 DEBUG juju.apiserver -> [B] unit-wordpress-0 611.217us {"RequestId":18,"Response":{"Results":[{"NotifyWatcherId":"6","Error":null}]}} Uniter[""].WatchMeterStatus
[LOG] 0:00.408 DEBUG juju.apiserver <- [B] unit-wordpress-0 {"RequestId":20,"Type":"Uniter","Version":1,"Request":"WatchUnitAddresses","Params":{"Entities":[{"Tag":"unit-wordpress-0"}]}}
[LOG] 0:00.408 DEBUG juju.apiserver <- [B] unit-wordpress-0 {"RequestId":21,"Type":"NotifyWatcher","Id":"6","Request":"Next","Params":{}}
[LOG] 0:00.409 DEBUG juju.apiserver -> [B] unit-wordpress-0 822.437us {"RequestId":20,"Response":{"Results":[{"NotifyWatcherId":"7","Error":null}]}} Uniter[""].WatchUnitAddresses
[LOG] 0:00.409 DEBUG juju.worker.uniter.filter got meter status change
[LOG] 0:00.409 DEBUG juju.apiserver <- [B] unit-wordpress-0 {"RequestId":22,"Type":"Uniter","Version":1,"Request":"GetMeterStatus","Params":{"Entities":[{"Tag":"unit-wordpress-0"}]}}
[LOG] 0:00.409 DEBUG juju.apiserver <- [B] unit-wordpress-0 {"RequestId":23,"Type":"NotifyWatcher","Id":"7","Request":"Next","Params":{}}
[LOG] 0:00.410 DEBUG juju.apiserver -> [B] unit-wordpress-0 609.22us {"RequestId":22,"Response":{"Results":[{"Code":"NOT SET","Info":"","Error":null}]}} Uniter[""].GetMeterStatus
[LOG] 0:00.410 DEBUG juju.worker.uniter.filter got 0 actions
[LOG] 0:00.410 DEBUG juju.worker.uniter.filter got service change
[LOG] 0:00.410 DEBUG juju.apiserver <- [B] unit-wordpress-0 {"RequestId":24,"Type":"Uniter","Version":1,"Request":"Life","Params":{"Entities":[{"Tag":"service-wordpress"}]}}
[LOG] 0:00.411 DEBUG juju.apiserver -> [B] unit-wordpress-0 502.39us {"RequestId":24,"Response":{"Results":[{"Life":"alive","Error":null}]}} Uniter[""].Life
[LOG] 0:00.411 DEBUG juju.apiserver <- [B] unit-wordpress-0 {"RequestId":25,"Type":"Uniter","Version":1,"Request":"CharmURL","Params":{"Entities":[{"Tag":"service-wordpress"}]}}
[LOG] 0:00.411 DEBUG juju.apiserver -> [B] unit-wordpress-0 497.519us {"RequestId":25,"Response":{"Results":[{"Error":null,"Result":"local:quantal/wordpress-3","Ok":false}]}} Uniter[""].CharmURL
[LOG] 0:00.411 DEBUG juju.worker.uniter.filter charm check skipped, not yet installed.
[LOG] 0:00.411 DEBUG juju.worker.uniter.filter got relations change
[LOG] 0:00.411 DEBUG juju.worker.uniter.filter got unit change
[LOG] 0:00.411 DEBUG juju.apiserver <- [B] unit-wordpress-0 {"RequestId":26,"Type":"Uniter","Version":1,"Request":"Life","Params":{"Entities":[{"Tag":"unit-wordpress-0"}]}}
[LOG] 0:00.412 DEBUG juju.apiserver -> [B] unit-wordpress-0 411.785us {"RequestId":26,"Response":{"Results":[{"Life":"alive","Error":null}]}} Uniter[""].Life
[LOG] 0:00.412 DEBUG juju.apiserver <- [B] unit-wordpress-0 {"RequestId":27,"Type":"Uniter","Version":1,"Request":"Resolved","Params":{"Entities":[{"Tag":"unit-wordpress-0"}]}}
[LOG] 0:00.412 DEBUG juju.apiserver -> [B] unit-wordpress-0 239.705us {"RequestId":27,"Response":{"Results":[{"Error":null,"Mode":""}]}} Uniter[""].Resolved
[LOG] 0:00.416 DEBUG juju.worker.uniter.filter changing charm to "local:quantal/wordpress-3"
[LOG] 0:00.416 DEBUG juju.apiserver <- [B] unit-wordpress-0 {"RequestId":28,"Type":"Uniter","Version":1,"Request":"SetCharmURL","Params":{"Entities":[{"Tag":"unit-wordpress-0","CharmURL":"local:quantal/wordpress-3"}]}}
[LOG] 0:00.419 INFO juju.mongo dialled mongo successfully on address "127.0.0.1:54726"
[LOG] 0:00.425 DEBUG juju.apiserver -> [B] unit-wordpress-0 8.791987ms {"RequestId":28,"Response":{"Results":[{"Error":null}]}} Uniter[""].SetCharmURL
[LOG] 0:00.425 DEBUG juju.apiserver <- [B] unit-wordpress-0 {"RequestId":29,"Type":"Uniter","Version":1,"Request":"WatchConfigSettings","Params":{"Entities":[{"Tag":"unit-wordpress-0"}]}}
[LOG] 0:00.426 DEBUG juju.apiserver -> [B] unit-wordpress-0 1.204427ms {"RequestId":29,"Response":{"Results":[{"NotifyWatcherId":"8","Error":null}]}} Uniter[""].WatchConfigSettings
[LOG] 0:00.427 DEBUG juju.apiserver -> [B] unit-wordpress-0 17.362597ms {"RequestId":23,"Response":{}} NotifyWatcher["7"].Next
[LOG] 0:00.427 DEBUG juju.apiserver <- [B] unit-wordpress-0 {"RequestId":30,"Type":"StringsWatcher","Id":"5","Request":"Stop","Params":{}}
[LOG] 0:00.427 DEBUG juju.apiserver <- [B] unit-wordpress-0 {"RequestId":31,"Type":"NotifyWatcher","Id":"8","Request":"Next","Params":{}}
[LOG] 0:00.427 DEBUG juju.apiserver -> [B] unit-wordpress-0 19.732714ms {"RequestId":19,"Error":"watcher has been stopped","ErrorCode":"stopped","Response":{}} StringsWatcher["5"].Next
[LOG] 0:00.427 DEBUG juju.apiserver -> [B] unit-wordpress-0 226.23us {"RequestId":30,"Response":{}} StringsWatcher["5"].Stop
[LOG] 0:00.427 DEBUG juju.apiserver <- [B] unit-wordpress-0 {"RequestId":32,"Type":"Uniter","Version":1,"Request":"WatchServiceRelations","Params":{"Entities":[{"Tag":"service-wordpress"}]}}
[LOG] 0:00.428 DEBUG juju.apiserver -> [B] unit-wordpress-0 795.868us {"RequestId":32,"Response":{"Results":[{"StringsWatcherId":"9","Changes":[],"Error":null}]}} Uniter[""].WatchServiceRelations
[LOG] 0:00.428 DEBUG juju.worker.uniter.filter no new charm event
[LOG] 0:00.428 DEBUG juju.worker.uniter.filter got config change
[LOG] 0:00.428 DEBUG juju.worker.uniter.filter preparing new config event
[LOG] 0:00.428 DEBUG juju.worker.uniter.filter discarded config event
[LOG] 0:00.428 DEBUG juju.worker.uniter.filter got relations change
[LOG] 0:00.428 DEBUG juju.worker.uniter.filter got address change
[LOG] 0:00.428 DEBUG juju.worker.uniter.filter preparing new config event
[LOG] 0:00.428 DEBUG juju.worker.uniter.filter sent config event
filter_test.go:469:
c.Fatalf("unexpected config event")
... Error: unexpected config event
[LOG] 0:00.432 DEBUG juju.apiserver <- [B] unit-wordpress-0 {"RequestId":33,"Type":"NotifyWatcher","Id":"7","Request":"Next","Params":{}}
[LOG] 0:00.432 DEBUG juju.apiserver <- [B] unit-wordpress-0 {"RequestId":34,"Type":"StringsWatcher","Id":"9","Request":"Next","Params":{}}
[LOG] 0:00.432 DEBUG juju.apiserver <- [B] unit-wordpress-0 {"RequestId":35,"Type":"NotifyWatcher","Id":"7","Request":"Stop","Params":{}}
[LOG] 0:00.432 DEBUG juju.apiserver -> [B] unit-wordpress-0 186.593us {"RequestId":33,"Error":"watcher has been stopped","ErrorCode":"stopped","Response":{}} NotifyWatcher["7"].Next
[LOG] 0:00.432 DEBUG juju.apiserver -> [B] unit-wordpress-0 142.4us {"RequestId":35,"Response":{}} NotifyWatcher["7"].Stop
[LOG] 0:00.432 DEBUG juju.apiserver <- [B] unit-wordpress-0 {"RequestId":36,"Type":"NotifyWatcher","Id":"6","Request":"Stop","Params":{}}
[LOG] 0:00.432 DEBUG juju.apiserver -> [B] unit-wordpress-0 24.225937ms {"RequestId":21,"Error":"watcher has been stopped","ErrorCode":"stopped","Response":{}} NotifyWatcher["6"].Next
[LOG] 0:00.432 DEBUG juju.apiserver -> [B] unit-wordpress-0 125.322us {"RequestId":36,"Response":{}} NotifyWatcher["6"].Stop
[LOG] 0:00.433 DEBUG juju.apiserver <- [B] unit-wordpress-0 {"RequestId":37,"Type":"StringsWatcher","Id":"4","Request":"Stop","Params":{}}
[LOG] 0:00.433 DEBUG juju.apiserver -> [B] unit-wordpress-0 26.573704ms {"RequestId":17,"Error":"watcher has been stopped","ErrorCode":"stopped","Response":{}} StringsWatcher["4"].Next
[LOG] 0:00.433 DEBUG juju.apiserver -> [B] unit-wordpress-0 115.676us {"RequestId":37,"Response":{}} StringsWatcher["4"].Stop
[LOG] 0:00.433 DEBUG juju.apiserver <- [B] unit-wordpress-0 {"RequestId":38,"Type":"NotifyWatcher","Id":"3","Request":"Stop","Params":{}}
[LOG] 0:00.433 DEBUG juju.apiserver -> [B] unit-wordpress-0 28.377924ms {"RequestId":14,"Error":"watcher has been stopped","ErrorCode":"stopped","Response":{}} NotifyWatcher["3"].Next
[LOG] 0:00.433 DEBUG juju.apiserver -> [B] unit-wordpress-0 114.591us {"RequestId":38,"Response":{}} NotifyWatcher["3"].Stop
[LOG] 0:00.433 DEBUG juju.apiserver <- [B] unit-wordpress-0 {"RequestId":39,"Type":"NotifyWatcher","Id":"2","Request":"Stop","Params":{}}
[LOG] 0:00.433 DEBUG juju.apiserver -> [B] unit-wordpress-0 29.786119ms {"RequestId":12,"Error":"watcher has been stopped","ErrorCode":"stopped","Response":{}} NotifyWatcher["2"].Next
[LOG] 0:00.433 DEBUG juju.apiserver -> [B] unit-wordpress-0 145.055us {"RequestId":39,"Response":{}} NotifyWatcher["2"].Stop
[LOG] 0:00.434 ERROR juju.worker.uniter.filter tomb: dying
[LOG] 0:00.434 ERROR juju.api.watcher error trying to stop watcher: connection is shut down
[LOG] 0:00.434 ERROR juju.api.watcher error trying to stop watcher: connection is shut down
[LOG] 0:00.434 INFO juju.provider.dummy reset environment
[LOG] 0:00.435 INFO juju.apiserver [A] user-dummy-admin@local API connection terminated after 160.995067ms
[LOG] 0:00.435 DEBUG juju.apiserver -> [B] unit-wordpress-0 8.495672ms {"RequestId":31,"Error":"watcher has been stopped","ErrorCode":"stopped","Response":{}} NotifyWatcher["8"].Next
[LOG] 0:00.435 DEBUG juju.apiserver -> [B] unit-wordpress-0 3.61203ms {"RequestId":34,"Error":"watcher has been stopped","ErrorCode":"stopped","Response":{}} StringsWatcher["9"].Next
[LOG] 0:00.436 ERROR juju.rpc error writing response: write tcp 127.0.0.1:59334: broken pipe
[LOG] 0:00.436 INFO juju.rpc error closing codec: write tcp 127.0.0.1:59334: broken pipe
[LOG] 0:00.436 INFO juju.apiserver [B] unit-wordpress-0 API connection terminated after 47.706563ms
[LOG] 0:00.439 INFO juju.testing reset successfully reset admin password
[LOG] 0:00.455 INFO juju.testing reset successfully reset admin password
[LOG] 0:00.457 INFO juju.testing reset successfully reset admin password
OOPS: 49 passed, 1 FAILED
--- FAIL: TestPackage (180.59 seconds)
FAIL
FAIL github.com/juju/juju/worker/uniter 180.759s
I believe both this and bug 1426394 have the same underlying cause. I'm working on a fix for both.