juju-core

Sporadic test failure w/ bot inside Uniter: FilterSuite.TestUnitRemoval

Bug #1236471 reported by Nate Finch
6
This bug affects 1 person
Affects Status Importance Assigned to Milestone
juju-core
Fix Released
High
Tim Penhey
juju-core 1.23-beta1
1.25
Fix Released
Undecided
Unassigned

Bug Description

----------------------------------------------------------------------
FAIL: filter_test.go:97: FilterSuite.TestUnitRemoval

[LOG] 67.35777 DEBUG juju.environs.configstore Making /tmp/gocheck-5577006791947779410/7/home/ubuntu/.juju/environments
[LOG] 67.45901 INFO juju environs/testing: uploading FAKE tools 1.15.1-precise-amd64
[LOG] 67.45993 DEBUG juju.environs.tools no architecture specified when finding tools, looking for any
[LOG] 67.45994 DEBUG juju.environs.tools no series specified when finding tools, looking for any
[LOG] 67.46005 DEBUG juju.environs.simplestreams fetchData failed for "http://127.0.0.1:34038/dummyenv/private/tools/streams/v1/index.sjson": file "tools/streams/v1/index.sjson" not found not found
[LOG] 67.46009 DEBUG juju.environs.simplestreams cannot load index "http://127.0.0.1:34038/dummyenv/private/tools/streams/v1/index.sjson": invalid URL "http://127.0.0.1:34038/dummyenv/private/tools/streams/v1/index.sjson" not found
[LOG] 67.46012 DEBUG juju.environs.simplestreams fetchData failed for "http://127.0.0.1:34038/dummyenv/private/tools/streams/v1/index.json": file "tools/streams/v1/index.json" not found not found
[LOG] 67.46015 DEBUG juju.environs.simplestreams cannot load index "http://127.0.0.1:34038/dummyenv/private/tools/streams/v1/index.json": invalid URL "http://127.0.0.1:34038/dummyenv/private/tools/streams/v1/index.json" not found
[LOG] 67.46033 INFO juju.environs.tools Writing tools/streams/v1/index.json
[LOG] 67.46042 INFO juju.environs.tools Writing tools/streams/v1/com.ubuntu.juju:released:tools.json
[LOG] 67.46046 INFO juju.environs.boostrap bootstrapping environment "dummyenv"
[LOG] 67.46049 INFO juju.environs.tools reading tools with major.minor version 1.15
[LOG] 67.46051 INFO juju.environs.tools filtering tools by version: 1.15.1
[LOG] 67.46052 INFO juju.environs.tools filtering tools by series: precise
[LOG] 67.46055 DEBUG juju.environs.tools no architecture specified when finding tools, looking for any
[LOG] 67.46063 DEBUG juju.environs.simplestreams fetchData failed for "http://127.0.0.1:34038/dummyenv/private/tools/streams/v1/index.sjson": file "tools/streams/v1/index.sjson" not found not found
[LOG] 67.46065 DEBUG juju.environs.simplestreams cannot load index "http://127.0.0.1:34038/dummyenv/private/tools/streams/v1/index.sjson": invalid URL "http://127.0.0.1:34038/dummyenv/private/tools/streams/v1/index.sjson" not found
[LOG] 67.46080 DEBUG juju.environs.simplestreams no mirror information available for {DataType:content-download MirrorContentId:com.ubuntu.juju:released:tools FilterFunc:0x691970 ValueTemplate:{Release: Version: Arch: Size:0 Path: FullPath: FileType: SHA256:}}: mirror data for "com.ubuntu.juju:released:tools" not found
[LOG] 67.46081 DEBUG juju.environs.simplestreams read metadata index at "http://127.0.0.1:34038/dummyenv/private/tools/streams/v1/index.json"
[LOG] 67.46088 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:arm"] are [{Mon, 07 Oct 2013 16:38:08 +0000 products:1.0 content-download [] streams/v1/com.ubuntu.juju:released:tools.json [com.ubuntu.juju:12.04:amd64]}]
[LOG] 67.46090 DEBUG juju.environs.simplestreams finding products at path "streams/v1/com.ubuntu.juju:released:tools.json"
[LOG] 67.46111 INFO juju.provider.dummy would pick tools from 1.15.1-precise-amd64
[LOG] 67.50150 INFO juju.state opening state; mongo addresses: ["localhost:48243"]; entity ""
[LOG] 67.50401 INFO juju.state connection established
[LOG] 67.53703 INFO juju.state initializing environment
[LOG] 67.55811 INFO juju state/api: listening on "127.0.0.1:35509"
[LOG] 67.57568 INFO juju.state opening state; mongo addresses: ["localhost:48243"]; entity ""
[LOG] 67.57846 INFO juju.state connection established
[LOG] 67.57906 INFO juju juju: authorization error while connecting to state server; retrying
[LOG] 67.57911 INFO juju.state opening state; mongo addresses: ["localhost:48243"]; entity ""
[LOG] 67.58193 INFO juju.state connection established
[LOG] 67.65183 INFO juju state/api: dialing "wss://127.0.0.1:35509/"
[LOG] 67.66232 INFO juju state/api: connection established
[LOG] 67.66244 DEBUG juju.rpc.jsoncodec <- {"RequestId":1,"Type":"Admin","Request":"Login","Params":{"AuthTag":"user-admin","Password":"dummy-secret","Nonce":""}}
[LOG] 67.66291 DEBUG juju.rpc.jsoncodec -> {"RequestId":1,"Response":{}}
[LOG] 67.66786 DEBUG juju.rpc.jsoncodec <- {"RequestId":2,"Type":"Pinger","Request":"Ping","Params":{}}
[LOG] 67.66792 DEBUG juju.rpc.jsoncodec -> {"RequestId":2,"Response":{}}
[LOG] 67.67006 INFO juju writing charm to storage [853 bytes]
[LOG] 67.67012 INFO juju adding charm to state
[LOG] 67.71799 INFO juju state/api: dialing "wss://127.0.0.1:35509/"
[LOG] 67.72085 INFO juju state/api: connection established
[LOG] 67.72096 DEBUG juju.rpc.jsoncodec <- {"RequestId":1,"Type":"Admin","Request":"Login","Params":{"AuthTag":"unit-wordpress-0","Password":"password","Nonce":""}}
[LOG] 67.75390 DEBUG juju.rpc.jsoncodec -> {"RequestId":1,"Response":{}}
[LOG] 67.75501 DEBUG juju.rpc.jsoncodec <- {"RequestId":2,"Type":"Pinger","Request":"Ping","Params":{}}
[LOG] 67.75523 DEBUG juju.rpc.jsoncodec <- {"RequestId":3,"Type":"Uniter","Request":"Life","Params":{"Entities":[{"Tag":"unit-wordpress-0"}]}}
[LOG] 67.75539 DEBUG juju.rpc.jsoncodec -> {"RequestId":2,"Response":{}}
[LOG] 67.75671 DEBUG juju.rpc.jsoncodec -> {"RequestId":3,"Response":{"Results":[{"Life":"alive","Error":null}]}}
[LOG] 67.75730 DEBUG juju.rpc.jsoncodec <- {"RequestId":4,"Type":"Uniter","Request":"Life","Params":{"Entities":[{"Tag":"unit-wordpress-0"}]}}
[LOG] 67.75962 DEBUG juju.rpc.jsoncodec -> {"RequestId":4,"Response":{"Results":[{"Life":"alive","Error":null}]}}
[LOG] 67.75982 DEBUG juju.rpc.jsoncodec <- {"RequestId":5,"Type":"Uniter","Request":"Resolved","Params":{"Entities":[{"Tag":"unit-wordpress-0"}]}}
[LOG] 67.76102 DEBUG juju.rpc.jsoncodec -> {"RequestId":5,"Response":{"Results":[{"Error":null,"Mode":""}]}}
[LOG] 67.76141 DEBUG juju.rpc.jsoncodec <- {"RequestId":6,"Type":"Uniter","Request":"Life","Params":{"Entities":[{"Tag":"service-wordpress"}]}}
[LOG] 67.76198 DEBUG juju.rpc.jsoncodec -> {"RequestId":6,"Response":{"Results":[{"Life":"alive","Error":null}]}}
[LOG] 67.76243 DEBUG juju.rpc.jsoncodec <- {"RequestId":7,"Type":"Uniter","Request":"Life","Params":{"Entities":[{"Tag":"service-wordpress"}]}}
[LOG] 67.76312 DEBUG juju.rpc.jsoncodec -> {"RequestId":7,"Response":{"Results":[{"Life":"alive","Error":null}]}}
[LOG] 67.76356 DEBUG juju.rpc.jsoncodec <- {"RequestId":8,"Type":"Uniter","Request":"CharmURL","Params":{"Entities":[{"Tag":"service-wordpress"}]}}
[LOG] 67.76498 DEBUG juju.rpc.jsoncodec -> {"RequestId":8,"Response":{"Results":[{"Error":null,"Result":"local:quantal/wordpress-3","Ok":false}]}}
[LOG] 67.76512 DEBUG juju.worker.uniter.filter charm check skipped, not yet installed.
[LOG] 67.76520 DEBUG juju.rpc.jsoncodec <- {"RequestId":9,"Type":"Uniter","Request":"Watch","Params":{"Entities":[{"Tag":"unit-wordpress-0"}]}}
[LOG] 67.77123 DEBUG juju.rpc.jsoncodec -> {"RequestId":9,"Response":{"Results":[{"NotifyWatcherId":"2","Error":null}]}}
[LOG] 67.77143 DEBUG juju.rpc.jsoncodec <- {"RequestId":10,"Type":"Uniter","Request":"Watch","Params":{"Entities":[{"Tag":"service-wordpress"}]}}
[LOG] 67.77149 DEBUG juju.rpc.jsoncodec <- {"RequestId":11,"Type":"NotifyWatcher","Id":"2","Request":"Next","Params":{}}
[LOG] 67.77264 DEBUG juju.rpc.jsoncodec -> {"RequestId":10,"Response":{"Results":[{"NotifyWatcherId":"3","Error":null}]}}
[LOG] 67.77286 DEBUG juju.rpc.jsoncodec <- {"RequestId":12,"Type":"Uniter","Request":"CharmURL","Params":{"Entities":[{"Tag":"unit-wordpress-0"}]}}
[LOG] 67.77291 DEBUG juju.rpc.jsoncodec <- {"RequestId":13,"Type":"NotifyWatcher","Id":"3","Request":"Next","Params":{}}
[LOG] 67.77366 DEBUG juju.rpc.jsoncodec -> {"RequestId":12,"Response":{"Results":[{"Error":{"Message":"unit \"wordpress/0\" not found","Code":"not found"},"Result":"","Ok":false}]}}
[LOG] 67.77378 ERROR juju.worker.uniter.filter unit charm: unit "wordpress/0" not found
[LOG] 67.77385 DEBUG juju.rpc.jsoncodec <- {"RequestId":14,"Type":"NotifyWatcher","Id":"3","Request":"Stop","Params":{}}
[LOG] 67.77392 DEBUG juju.rpc.jsoncodec -> {"RequestId":13,"Error":"watcher has been stopped","ErrorCode":"stopped","Response":{}}
[LOG] 67.77395 DEBUG juju.rpc.jsoncodec -> {"RequestId":14,"Response":{}}
[LOG] 67.77411 DEBUG juju.rpc.jsoncodec <- {"RequestId":15,"Type":"NotifyWatcher","Id":"2","Request":"Stop","Params":{}}
[LOG] 67.77416 DEBUG juju.rpc.jsoncodec -> {"RequestId":11,"Error":"watcher has been stopped","ErrorCode":"stopped","Response":{}}
[LOG] 67.77421 DEBUG juju.rpc.jsoncodec -> {"RequestId":15,"Response":{}}
[LOG] 67.77430 ERROR juju.worker.uniter.filter unit "wordpress/0" not found
filter_test.go:105:
    s.assertAgentTerminates(c, f)
filter_test.go:120:
    c.Assert(f.Wait(), gc.Equals, worker.ErrTerminateAgent)
... obtained *params.Error = &params.Error{"not found", "unit \"wordpress/0\" not found"} ("unit \"wordpress/0\" not found")
... expected *errors.errorString = &errors.errorString{s:"agent should be terminated"} ("agent should be terminated")

[LOG] 67.78462 INFO juju.provider.dummy reset environment
[LOG] 67.78502 DEBUG juju.rpc.jsoncodec <- error: read tcp 127.0.0.1:36518: use of closed network connection (closing true)
[LOG] 67.78619 DEBUG juju.rpc.jsoncodec <- error: read tcp 127.0.0.1:36519: use of closed network connection (closing true)
[LOG] 67.79193 INFO juju MgoReset successfully reset admin password
[LOG] 67.79725 INFO juju MgoReset successfully reset admin password
[LOG] 67.80019 INFO juju MgoReset successfully reset admin password
OOPS: 39 passed, 1 FAILED
--- FAIL: TestPackage (167.95 seconds)
FAIL
FAIL launchpad.net/juju-core/worker/uniter 168.210s

Tags: test-failure
Curtis Hovey (sinzui)
Changed in juju-core:
status: New → Triaged
importance: Undecided → High
tags: added: test-failure
Canonical Juju QA Bot (juju-qa-bot)
Changed in juju-core:
importance: High → Medium
Revision history for this message
Curtis Hovey (sinzui) wrote :

This bug has returned and is being seen in utopic amd64 testing

Changed in juju-core:
milestone: none → 1.21-beta3
milestone: 1.21-beta3 → 1.22
importance: Medium → High
Curtis Hovey (sinzui)
Changed in juju-core:
milestone: 1.22 → 1.23
Tim Penhey (thumper)
Changed in juju-core:
assignee: nobody → Tim Penhey (thumper)
status: Triaged → In Progress
Tim Penhey (thumper)
Changed in juju-core:
status: In Progress → Fix Committed
status: Fix Committed → Fix Released
Curtis Hovey (sinzui)
Changed in juju-core:
milestone: 1.23 → 1.23-beta1
Revision history for this message
Aaron Bentley (abentley) wrote :

Still seems to affect at least 1.24: http://reports.vapour.ws/releases/2663/job/run-unit-tests-utopic-amd64/attempt/2164

Curtis Hovey (sinzui)
no longer affects: juju-core/1.24
Curtis Hovey (sinzui)
no longer affects: juju-core/1.25
To post a comment you must log in.
This report contains Public information  
Everyone can see this information.
Subscribing...

Other bug subscribers

Remote bug watches

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