juju-core

unit test failures: storeManagerStateSuite.TestStateWatcher

Bug #1438274 reported by Eric Snow
20
This bug affects 2 people
Affects Status Importance Assigned to Milestone
juju-core
Fix Released
Medium
Unassigned

Bug Description

(see http://reports.vapour.ws/releases/issue/56faa6d5749a566da5b0d8ab)

These two are likely closely related. They have shown up intermittently.

----------------------------------------------------------------------
FAIL: megawatcher_internal_test.go:1561: storeManagerStateSuite.TestStateWatcher

[LOG] 0:00.002 INFO juju.state opening state, mongo addresses: ["localhost:45660"]; entity <nil>
[LOG] 0:00.002 DEBUG juju.state dialing mongo
[LOG] 0:00.004 INFO juju.mongo dialled mongo successfully on address "127.0.0.1:45660"
[LOG] 0:00.004 DEBUG juju.state connection established
[LOG] 0:00.097 INFO juju.mongo dialled mongo successfully on address "127.0.0.1:45660"
[LOG] 0:00.097 INFO juju.state initializing environment, owner: "test-admin@local"
[LOG] 0:00.097 INFO juju.state info: &mongo.MongoInfo{Info:mongo.Info{Addrs:[]string{"localhost:45660"}, CACert:"..."}, Tag:names.Tag(nil), Password:""}
[LOG] 0:00.097 INFO juju.state starting presence watcher
[LOG] 0:00.099 INFO juju.mongo dialled mongo successfully on address "127.0.0.1:45660"
[LOG] 0:00.119 INFO juju.state opening state, mongo addresses: ["localhost:45660"]; entity <nil>
[LOG] 0:00.119 DEBUG juju.state dialing mongo
[LOG] 0:00.124 INFO juju.mongo dialled mongo successfully on address "127.0.0.1:45660"
[LOG] 0:00.125 DEBUG juju.state connection established
[LOG] 0:00.178 INFO juju.mongo dialled mongo successfully on address "127.0.0.1:45660"
[LOG] 0:00.179 INFO juju.mongo dialled mongo successfully on address "127.0.0.1:45660"
[LOG] 0:00.609 INFO juju.mongo dialled mongo successfully on address "127.0.0.1:45660"
megawatcher_internal_test.go:1678:
    checkDeltasEqual(c, deltas, []multiwatcher.Delta{{
        Entity: &multiwatcher.MachineInfo{
            Id: "0",
            InstanceId: "i-0",
            Status: multiwatcher.Status("pending"),
            Life: multiwatcher.Life("alive"),
            Series: "trusty",
            Jobs: []multiwatcher.MachineJob{JobManageEnviron.ToParams()},
            Addresses: []network.Address{},
            HardwareCharacteristics: hc,
            HasVote: false,
            WantsVote: true,
        },
    }, {
        Removed: true,
        Entity: &multiwatcher.MachineInfo{
            Id: "1",
            Status: multiwatcher.Status("pending"),
            Life: multiwatcher.Life("alive"),
            Series: "saucy",
            Jobs: []multiwatcher.MachineJob{JobHostUnits.ToParams()},
            Addresses: []network.Address{},
        },
    }, {
        Entity: &multiwatcher.MachineInfo{
            Id: "2",
            Status: multiwatcher.Status("pending"),
            Life: multiwatcher.Life("alive"),
            Series: "quantal",
            Jobs: []multiwatcher.MachineJob{JobHostUnits.ToParams()},
            Addresses: []network.Address{},
            HasVote: false,
            WantsVote: false,
        },
    }, {
        Entity: &multiwatcher.ServiceInfo{
            Name: "wordpress",
            CharmURL: "local:quantal/quantal-wordpress-3",
            OwnerTag: s.owner.String(),
            Life: "alive",
            Config: make(map[string]interface{}),
        },
    }, {
        Entity: &multiwatcher.UnitInfo{
            Name: "wordpress/0",
            Service: "wordpress",
            Series: "quantal",
            MachineId: "2",
            Status: "allocating",
        },
    }})
megawatcher_internal_test.go:1906:
    // Deltas are returned in arbitrary order, so we compare them as maps.
    c.Check(deltaMap(d0), jc.DeepEquals, deltaMap(d1))
... obtained map[interface {}]multiwatcher.EntityInfo = map[interface {}]multiwatcher.EntityInfo{multiwatcher.EntityId{Kind:"service", Id:"wordpress"}:(*multiwatcher.ServiceInfo)(0xc2081b3600), multiwatcher.EntityId{Kind:"machine", Id:"0"}:(*multiwatcher.MachineInfo)(0xc20810f790), multiwatcher.EntityId{Kind:"machine", Id:"1"}:multiwatcher.EntityInfo(nil)}
... expected map[interface {}]multiwatcher.EntityInfo = map[interface {}]multiwatcher.EntityInfo{multiwatcher.EntityId{Kind:"service", Id:"wordpress"}:(*multiwatcher.ServiceInfo)(0xc2081b2000), multiwatcher.EntityId{Kind:"unit", Id:"wordpress/0"}:(*multiwatcher.UnitInfo)(0xc2080f2270), multiwatcher.EntityId{Kind:"machine", Id:"0"}:(*multiwatcher.MachineInfo)(0xc2080f2000), multiwatcher.EntityId{Kind:"machine", Id:"1"}:multiwatcher.EntityInfo(nil), multiwatcher.EntityId{Kind:"machine", Id:"2"}:(*multiwatcher.MachineInfo)(0xc2080f21a0)}
... mismatch at top level: length mismatch, 3 vs 5; obtained map[interface {}]multiwatcher.EntityInfo{multiwatcher.EntityId{Kind:"machine", Id:"0"}:(*multiwatcher.MachineInfo)(0xc20810f790), multiwatcher.EntityId{Kind:"machine", Id:"1"}:multiwatcher.EntityInfo(nil), multiwatcher.EntityId{Kind:"service", Id:"wordpress"}:(*multiwatcher.ServiceInfo)(0xc2081b3600)}; expected map[interface {}]multiwatcher.EntityInfo{multiwatcher.EntityId{Kind:"unit", Id:"wordpress/0"}:(*multiwatcher.UnitInfo)(0xc2080f2270), multiwatcher.EntityId{Kind:"machine", Id:"0"}:(*multiwatcher.MachineInfo)(0xc2080f2000), multiwatcher.EntityId{Kind:"machine", Id:"1"}:multiwatcher.EntityInfo(nil), multiwatcher.EntityId{Kind:"machine", Id:"2"}:(*multiwatcher.MachineInfo)(0xc2080f21a0), multiwatcher.EntityId{Kind:"service", Id:"wordpress"}:(*multiwatcher.ServiceInfo)(0xc2081b2000)}

[LOG] 0:00.709 INFO juju.testing reset successfully reset admin password

----------------------------------------------------------------------
FAIL: megawatcher_internal_test.go:1681: storeManagerStateSuite.TestStateWatcherTwoEnvironments

[LOG] 0:00.002 INFO juju.state opening state, mongo addresses: ["localhost:45660"]; entity <nil>
[LOG] 0:00.002 DEBUG juju.state dialing mongo
[LOG] 0:00.004 INFO juju.mongo dialled mongo successfully on address "127.0.0.1:45660"
[LOG] 0:00.004 DEBUG juju.state connection established
[LOG] 0:00.095 INFO juju.mongo dialled mongo successfully on address "127.0.0.1:45660"
[LOG] 0:00.095 INFO juju.state initializing environment, owner: "test-admin@local"
[LOG] 0:00.096 INFO juju.state info: &mongo.MongoInfo{Info:mongo.Info{Addrs:[]string{"localhost:45660"}, CACert:"..."}, Tag:names.Tag(nil), Password:""}
[LOG] 0:00.096 INFO juju.state starting presence watcher
[LOG] 0:00.104 INFO juju.mongo dialled mongo successfully on address "127.0.0.1:45660"
[LOG] 0:00.119 INFO juju.state opening state, mongo addresses: ["localhost:45660"]; entity <nil>
[LOG] 0:00.119 DEBUG juju.state dialing mongo
[LOG] 0:00.166 INFO juju.mongo dialled mongo successfully on address "127.0.0.1:45660"
[LOG] 0:00.167 DEBUG juju.state connection established
[LOG] 0:00.178 INFO juju.mongo dialled mongo successfully on address "127.0.0.1:45660"
[LOG] 0:00.181 INFO juju.mongo dialled mongo successfully on address "127.0.0.1:45660"
Test 0: machines
Making changes to environment fb22afd4-6994-4b5e-8e02-47a8911c30d5
[LOG] 0:00.191 TRACE juju.state.watcher got request: watcher.reqWatch{key:watcher.watchKey{c:"openedPorts", id:?reflect.Value?}, info:watcher.watchInfo{ch:(chan<- watcher.Change)(0xc2083334a0), revno:0, filter:(func(interface {}) bool)(0x7cc6d0)}}
[LOG] 0:00.191 TRACE juju.state.watcher got request: watcher.reqWatch{key:watcher.watchKey{c:"machines", id:?reflect.Value?}, info:watcher.watchInfo{ch:(chan<- watcher.Change)(0xc2083334a0), revno:0, filter:(func(interface {}) bool)(0x7cc6d0)}}
[LOG] 0:00.191 TRACE juju.state.watcher got request: watcher.reqWatch{key:watcher.watchKey{c:"statuses", id:?reflect.Value?}, info:watcher.watchInfo{ch:(chan<- watcher.Change)(0xc208333620), revno:0, filter:(func(interface {}) bool)(0x7cc6d0)}}
[LOG] 0:00.191 TRACE juju.state.watcher got request: watcher.reqWatch{key:watcher.watchKey{c:"settings", id:?reflect.Value?}, info:watcher.watchInfo{ch:(chan<- watcher.Change)(0xc208333620), revno:0, filter:(func(interface {}) bool)(0x7cc6d0)}}
[LOG] 0:00.191 TRACE juju.state.watcher got request: watcher.reqWatch{key:watcher.watchKey{c:"relations", id:?reflect.Value?}, info:watcher.watchInfo{ch:(chan<- watcher.Change)(0xc2083334a0), revno:0, filter:(func(interface {}) bool)(0x7cc6d0)}}
[LOG] 0:00.191 TRACE juju.state.watcher got request: watcher.reqWatch{key:watcher.watchKey{c:"annotations", id:?reflect.Value?}, info:watcher.watchInfo{ch:(chan<- watcher.Change)(0xc2083334a0), revno:0, filter:(func(interface {}) bool)(0x7cc6d0)}}
[LOG] 0:00.191 TRACE juju.state.watcher got request: watcher.reqWatch{key:watcher.watchKey{c:"openedPorts", id:?reflect.Value?}, info:watcher.watchInfo{ch:(chan<- watcher.Change)(0xc208333620), revno:0, filter:(func(interface {}) bool)(0x7cc6d0)}}
[LOG] 0:00.191 TRACE juju.state.watcher got request: watcher.reqWatch{key:watcher.watchKey{c:"services", id:?reflect.Value?}, info:watcher.watchInfo{ch:(chan<- watcher.Change)(0xc208333620), revno:0, filter:(func(interface {}) bool)(0x7cc6d0)}}
[LOG] 0:00.192 TRACE juju.state.watcher got request: watcher.reqWatch{key:watcher.watchKey{c:"blocks", id:?reflect.Value?}, info:watcher.watchInfo{ch:(chan<- watcher.Change)(0xc2083334a0), revno:0, filter:(func(interface {}) bool)(0x7cc6d0)}}
[LOG] 0:00.192 TRACE juju.state.watcher got request: watcher.reqWatch{key:watcher.watchKey{c:"statuses", id:?reflect.Value?}, info:watcher.watchInfo{ch:(chan<- watcher.Change)(0xc2083334a0), revno:0, filter:(func(interface {}) bool)(0x7cc6d0)}}
[LOG] 0:00.192 TRACE juju.state.watcher got request: watcher.reqWatch{key:watcher.watchKey{c:"actions", id:?reflect.Value?}, info:watcher.watchInfo{ch:(chan<- watcher.Change)(0xc208333620), revno:0, filter:(func(interface {}) bool)(0x7cc6d0)}}
[LOG] 0:00.192 TRACE juju.state.watcher got request: watcher.reqWatch{key:watcher.watchKey{c:"relations", id:?reflect.Value?}, info:watcher.watchInfo{ch:(chan<- watcher.Change)(0xc208333620), revno:0, filter:(func(interface {}) bool)(0x7cc6d0)}}
[LOG] 0:00.192 TRACE juju.state.watcher got request: watcher.reqWatch{key:watcher.watchKey{c:"constraints", id:?reflect.Value?}, info:watcher.watchInfo{ch:(chan<- watcher.Change)(0xc2083334a0), revno:0, filter:(func(interface {}) bool)(0x7cc6d0)}}
[LOG] 0:00.192 TRACE juju.state.watcher got request: watcher.reqWatch{key:watcher.watchKey{c:"settings", id:?reflect.Value?}, info:watcher.watchInfo{ch:(chan<- watcher.Change)(0xc2083334a0), revno:0, filter:(func(interface {}) bool)(0x7cc6d0)}}
[LOG] 0:00.192 TRACE juju.state.watcher got request: watcher.reqWatch{key:watcher.watchKey{c:"annotations", id:?reflect.Value?}, info:watcher.watchInfo{ch:(chan<- watcher.Change)(0xc208333620), revno:0, filter:(func(interface {}) bool)(0x7cc6d0)}}
[LOG] 0:00.192 TRACE juju.state.watcher got request: watcher.reqWatch{key:watcher.watchKey{c:"blocks", id:?reflect.Value?}, info:watcher.watchInfo{ch:(chan<- watcher.Change)(0xc208333620), revno:0, filter:(func(interface {}) bool)(0x7cc6d0)}}
[LOG] 0:00.192 TRACE juju.state.watcher got request: watcher.reqWatch{key:watcher.watchKey{c:"units", id:?reflect.Value?}, info:watcher.watchInfo{ch:(chan<- watcher.Change)(0xc2083334a0), revno:0, filter:(func(interface {}) bool)(0x7cc6d0)}}
[LOG] 0:00.192 TRACE juju.state.watcher got request: watcher.reqWatch{key:watcher.watchKey{c:"services", id:?reflect.Value?}, info:watcher.watchInfo{ch:(chan<- watcher.Change)(0xc2083334a0), revno:0, filter:(func(interface {}) bool)(0x7cc6d0)}}
[LOG] 0:00.192 TRACE juju.state.watcher got request: watcher.reqWatch{key:watcher.watchKey{c:"machines", id:?reflect.Value?}, info:watcher.watchInfo{ch:(chan<- watcher.Change)(0xc208333620), revno:0, filter:(func(interface {}) bool)(0x7cc6d0)}}
[LOG] 0:00.192 TRACE juju.state.watcher got request: watcher.reqWatch{key:watcher.watchKey{c:"units", id:?reflect.Value?}, info:watcher.watchInfo{ch:(chan<- watcher.Change)(0xc208333620), revno:0, filter:(func(interface {}) bool)(0x7cc6d0)}}
[LOG] 0:00.192 TRACE juju.state.watcher got request: watcher.reqWatch{key:watcher.watchKey{c:"actions", id:?reflect.Value?}, info:watcher.watchInfo{ch:(chan<- watcher.Change)(0xc2083334a0), revno:0, filter:(func(interface {}) bool)(0x7cc6d0)}}
[LOG] 0:00.192 TRACE juju.state.watcher got request: watcher.reqWatch{key:watcher.watchKey{c:"constraints", id:?reflect.Value?}, info:watcher.watchInfo{ch:(chan<- watcher.Change)(0xc208333620), revno:0, filter:(func(interface {}) bool)(0x7cc6d0)}}
[LOG] 0:00.267 TRACE juju.state.watcher got request: watcher.reqSync{}
[LOG] 0:00.267 TRACE juju.state.watcher got changelog document: bson.D{bson.DocElem{Name:"_id", Value:"U\x15\xff\xbe\x805\x8fsD\x00\x01i"}, bson.DocElem{Name:"constraints", Value:bson.D{bson.DocElem{Name:"d", Value:[]interface {}{"fb22afd4-6994-4b5e-8e02-47a8911c30d5:m#0"}}, bson.DocElem{Name:"r", Value:[]interface {}{2}}}}, bson.DocElem{Name:"statuses", Value:bson.D{bson.DocElem{Name:"d", Value:[]interface {}{"fb22afd4-6994-4b5e-8e02-47a8911c30d5:m#0"}}, bson.DocElem{Name:"r", Value:[]interface {}{2}}}}, bson.DocElem{Name:"requestednetworks", Value:bson.D{bson.DocElem{Name:"d", Value:[]interface {}{"fb22afd4-6994-4b5e-8e02-47a8911c30d5:m#0"}}, bson.DocElem{Name:"r", Value:[]interface {}{2}}}}, bson.DocElem{Name:"blockdevices", Value:bson.D{bson.DocElem{Name:"d", Value:[]interface {}{"fb22afd4-6994-4b5e-8e02-47a8911c30d5:0"}}, bson.DocElem{Name:"r", Value:[]interface {}{2}}}}, bson.DocElem{Name:"containerRefs", Value:bson.D{bson.DocElem{Name:"d", Value:[]interface {}{"fb22afd4-6994-4b5e-8e02-47a8911c30d5:0"}}, bson.DocElem{Name:"r", Value:[]interface {}{2}}}}, bson.DocElem{Name:"machines", Value:bson.D{bson.DocElem{Name:"d", Value:[]interface {}{"fb22afd4-6994-4b5e-8e02-47a8911c30d5:0"}}, bson.DocElem{Name:"r", Value:[]interface {}{2}}}}}
[LOG] 0:00.268 TRACE juju.state.watcher got changelog document: bson.D{bson.DocElem{Name:"_id", Value:"U\x15\xff\xbe\x805\x8fsD\x00\x01h"}, bson.DocElem{Name:"constraints", Value:bson.D{bson.DocElem{Name:"d", Value:[]interface {}{"f22cb0d8-3c91-47e4-8169-1eea898446f6:e"}}, bson.DocElem{Name:"r", Value:[]interface {}{2}}}}, bson.DocElem{Name:"settings", Value:bson.D{bson.DocElem{Name:"d", Value:[]interface {}{"f22cb0d8-3c91-47e4-8169-1eea898446f6:e"}}, bson.DocElem{Name:"r", Value:[]interface {}{2}}}}, bson.DocElem{Name:"environments", Value:bson.D{bson.DocElem{Name:"d", Value:[]interface {}{"f22cb0d8-3c91-47e4-8169-1eea898446f6"}}, bson.DocElem{Name:"r", Value:[]interface {}{2}}}}, bson.DocElem{Name:"userenvname", Value:bson.D{bson.DocElem{Name:"d", Value:[]interface {}{"test-admin@local:newtestenv"}}, bson.DocElem{Name:"r", Value:[]interface {}{2}}}}, bson.DocElem{Name:"envusers", Value:bson.D{bson.DocElem{Name:"d", Value:[]interface {}{"f22cb0d8-3c91-47e4-8169-1eea898446f6:test-admin@local"}}, bson.DocElem{Name:"r", Value:[]interface {}{2}}}}}
[LOG] 0:00.268 TRACE juju.state.watcher got changelog document: bson.D{bson.DocElem{Name:"_id", Value:"U\x15\xff\xbe\x805\x8fsD\x00\x01g"}, bson.DocElem{Name:"constraints", Value:bson.D{bson.DocElem{Name:"d", Value:[]interface {}{"fb22afd4-6994-4b5e-8e02-47a8911c30d5:e"}}, bson.DocElem{Name:"r", Value:[]interface {}{2}}}}, bson.DocElem{Name:"settings", Value:bson.D{bson.DocElem{Name:"d", Value:[]interface {}{"fb22afd4-6994-4b5e-8e02-47a8911c30d5:e"}}, bson.DocElem{Name:"r", Value:[]interface {}{2}}}}, bson.DocElem{Name:"environments", Value:bson.D{bson.DocElem{Name:"d", Value:[]interface {}{"fb22afd4-6994-4b5e-8e02-47a8911c30d5"}}, bson.DocElem{Name:"r", Value:[]interface {}{2}}}}, bson.DocElem{Name:"userenvname", Value:bson.D{bson.DocElem{Name:"d", Value:[]interface {}{"test-admin@local:testenv"}}, bson.DocElem{Name:"r", Value:[]interface {}{2}}}}, bson.DocElem{Name:"envusers", Value:bson.D{bson.DocElem{Name:"d", Value:[]interface {}{"fb22afd4-6994-4b5e-8e02-47a8911c30d5:test-admin@local"}}, bson.DocElem{Name:"r", Value:[]interface {}{2}}}}, bson.DocElem{Name:"users", Value:bson.D{bson.DocElem{Name:"d", Value:[]interface {}{"test-admin"}}, bson.DocElem{Name:"r", Value:[]interface {}{2}}}}, bson.DocElem{Name:"stateServers", Value:bson.D{bson.DocElem{Name:"d", Value:[]interface {}{"e", "apiHostPorts", "stateServingInfo"}}, bson.DocElem{Name:"r", Value:[]interface {}{2, 2, 2}}}}}
[LOG] 0:00.319 TRACE juju.state.watcher got request: watcher.reqSync{}
[LOG] 0:00.465 TRACE juju.state.watcher got request: watcher.reqSync{}
[LOG] 0:00.465 TRACE juju.state.watcher got changelog document: bson.D{bson.DocElem{Name:"_id", Value:"U\x15\xff\xbe\x805\x8fsD\x00\x01i"}, bson.DocElem{Name:"constraints", Value:bson.D{bson.DocElem{Name:"d", Value:[]interface {}{"fb22afd4-6994-4b5e-8e02-47a8911c30d5:m#0"}}, bson.DocElem{Name:"r", Value:[]interface {}{2}}}}, bson.DocElem{Name:"statuses", Value:bson.D{bson.DocElem{Name:"d", Value:[]interface {}{"fb22afd4-6994-4b5e-8e02-47a8911c30d5:m#0"}}, bson.DocElem{Name:"r", Value:[]interface {}{2}}}}, bson.DocElem{Name:"requestednetworks", Value:bson.D{bson.DocElem{Name:"d", Value:[]interface {}{"fb22afd4-6994-4b5e-8e02-47a8911c30d5:m#0"}}, bson.DocElem{Name:"r", Value:[]interface {}{2}}}}, bson.DocElem{Name:"blockdevices", Value:bson.D{bson.DocElem{Name:"d", Value:[]interface {}{"fb22afd4-6994-4b5e-8e02-47a8911c30d5:0"}}, bson.DocElem{Name:"r", Value:[]interface {}{2}}}}, bson.DocElem{Name:"containerRefs", Value:bson.D{bson.DocElem{Name:"d", Value:[]interface {}{"fb22afd4-6994-4b5e-8e02-47a8911c30d5:0"}}, bson.DocElem{Name:"r", Value:[]interface {}{2}}}}, bson.DocElem{Name:"machines", Value:bson.D{bson.DocElem{Name:"d", Value:[]interface {}{"fb22afd4-6994-4b5e-8e02-47a8911c30d5:0"}}, bson.DocElem{Name:"r", Value:[]interface {}{2}}}}}
[LOG] 0:00.466 TRACE juju.state.watcher got changelog document: bson.D{bson.DocElem{Name:"_id", Value:"U\x15\xff\xbe\x805\x8fsD\x00\x01h"}, bson.DocElem{Name:"constraints", Value:bson.D{bson.DocElem{Name:"d", Value:[]interface {}{"f22cb0d8-3c91-47e4-8169-1eea898446f6:e"}}, bson.DocElem{Name:"r", Value:[]interface {}{2}}}}, bson.DocElem{Name:"settings", Value:bson.D{bson.DocElem{Name:"d", Value:[]interface {}{"f22cb0d8-3c91-47e4-8169-1eea898446f6:e"}}, bson.DocElem{Name:"r", Value:[]interface {}{2}}}}, bson.DocElem{Name:"environments", Value:bson.D{bson.DocElem{Name:"d", Value:[]interface {}{"f22cb0d8-3c91-47e4-8169-1eea898446f6"}}, bson.DocElem{Name:"r", Value:[]interface {}{2}}}}, bson.DocElem{Name:"userenvname", Value:bson.D{bson.DocElem{Name:"d", Value:[]interface {}{"test-admin@local:newtestenv"}}, bson.DocElem{Name:"r", Value:[]interface {}{2}}}}, bson.DocElem{Name:"envusers", Value:bson.D{bson.DocElem{Name:"d", Value:[]interface {}{"f22cb0d8-3c91-47e4-8169-1eea898446f6:test-admin@local"}}, bson.DocElem{Name:"r", Value:[]interface {}{2}}}}}
Making changes to environment f22cb0d8-3c91-47e4-8169-1eea898446f6
[LOG] 0:00.536 TRACE juju.state.watcher got request: watcher.reqSync{}
[LOG] 0:00.537 TRACE juju.state.watcher got changelog document: bson.D{bson.DocElem{Name:"_id", Value:"U\x15\xff\xbf\x805\x8fsD\x00\x01j"}, bson.DocElem{Name:"constraints", Value:bson.D{bson.DocElem{Name:"d", Value:[]interface {}{"f22cb0d8-3c91-47e4-8169-1eea898446f6:m#0"}}, bson.DocElem{Name:"r", Value:[]interface {}{2}}}}, bson.DocElem{Name:"statuses", Value:bson.D{bson.DocElem{Name:"d", Value:[]interface {}{"f22cb0d8-3c91-47e4-8169-1eea898446f6:m#0"}}, bson.DocElem{Name:"r", Value:[]interface {}{2}}}}, bson.DocElem{Name:"requestednetworks", Value:bson.D{bson.DocElem{Name:"d", Value:[]interface {}{"f22cb0d8-3c91-47e4-8169-1eea898446f6:m#0"}}, bson.DocElem{Name:"r", Value:[]interface {}{2}}}}, bson.DocElem{Name:"blockdevices", Value:bson.D{bson.DocElem{Name:"d", Value:[]interface {}{"f22cb0d8-3c91-47e4-8169-1eea898446f6:0"}}, bson.DocElem{Name:"r", Value:[]interface {}{2}}}}, bson.DocElem{Name:"containerRefs", Value:bson.D{bson.DocElem{Name:"d", Value:[]interface {}{"f22cb0d8-3c91-47e4-8169-1eea898446f6:0"}}, bson.DocElem{Name:"r", Value:[]interface {}{2}}}}, bson.DocElem{Name:"machines", Value:bson.D{bson.DocElem{Name:"d", Value:[]interface {}{"f22cb0d8-3c91-47e4-8169-1eea898446f6:0"}}, bson.DocElem{Name:"r", Value:[]interface {}{2}}}}}
[LOG] 0:00.566 INFO juju.mongo dialled mongo successfully on address "127.0.0.1:45660"
[LOG] 0:00.617 TRACE juju.state.watcher got request: watcher.reqSync{}
[LOG] 0:00.668 TRACE juju.state.watcher got request: watcher.reqSync{}
[LOG] 0:00.669 TRACE juju.state.watcher got changelog document: bson.D{bson.DocElem{Name:"_id", Value:"U\x15\xff\xbf\x805\x8fsD\x00\x01j"}, bson.DocElem{Name:"constraints", Value:bson.D{bson.DocElem{Name:"d", Value:[]interface {}{"f22cb0d8-3c91-47e4-8169-1eea898446f6:m#0"}}, bson.DocElem{Name:"r", Value:[]interface {}{2}}}}, bson.DocElem{Name:"statuses", Value:bson.D{bson.DocElem{Name:"d", Value:[]interface {}{"f22cb0d8-3c91-47e4-8169-1eea898446f6:m#0"}}, bson.DocElem{Name:"r", Value:[]interface {}{2}}}}, bson.DocElem{Name:"requestednetworks", Value:bson.D{bson.DocElem{Name:"d", Value:[]interface {}{"f22cb0d8-3c91-47e4-8169-1eea898446f6:m#0"}}, bson.DocElem{Name:"r", Value:[]interface {}{2}}}}, bson.DocElem{Name:"blockdevices", Value:bson.D{bson.DocElem{Name:"d", Value:[]interface {}{"f22cb0d8-3c91-47e4-8169-1eea898446f6:0"}}, bson.DocElem{Name:"r", Value:[]interface {}{2}}}}, bson.DocElem{Name:"containerRefs", Value:bson.D{bson.DocElem{Name:"d", Value:[]interface {}{"f22cb0d8-3c91-47e4-8169-1eea898446f6:0"}}, bson.DocElem{Name:"r", Value:[]interface {}{2}}}}, bson.DocElem{Name:"machines", Value:bson.D{bson.DocElem{Name:"d", Value:[]interface {}{"f22cb0d8-3c91-47e4-8169-1eea898446f6:0"}}, bson.DocElem{Name:"r", Value:[]interface {}{2}}}}}
[LOG] 0:00.767 INFO juju.testing reset successfully reset admin password
[LOG] 0:00.772 INFO juju.state opening state, mongo addresses: ["localhost:45660"]; entity <nil>
[LOG] 0:00.772 DEBUG juju.state dialing mongo
[LOG] 0:00.773 INFO juju.mongo dialled mongo successfully on address "127.0.0.1:45660"
[LOG] 0:00.774 DEBUG juju.state connection established
[LOG] 0:00.802 INFO juju.mongo dialled mongo successfully on address "127.0.0.1:45660"
[LOG] 0:00.802 INFO juju.state initializing environment, owner: "test-admin@local"
[LOG] 0:00.803 INFO juju.state info: &mongo.MongoInfo{Info:mongo.Info{Addrs:[]string{"localhost:45660"}, CACert:"..."}, Tag:names.Tag(nil), Password:""}
[LOG] 0:00.803 INFO juju.state starting presence watcher
[LOG] 0:00.866 INFO juju.mongo dialled mongo successfully on address "127.0.0.1:45660"
[LOG] 0:00.882 INFO juju.state opening state, mongo addresses: ["localhost:45660"]; entity <nil>
[LOG] 0:00.882 DEBUG juju.state dialing mongo
[LOG] 0:00.887 INFO juju.mongo dialled mongo successfully on address "127.0.0.1:45660"
[LOG] 0:00.888 DEBUG juju.state connection established
[LOG] 0:00.895 INFO juju.mongo dialled mongo successfully on address "127.0.0.1:45660"
[LOG] 0:00.899 INFO juju.mongo dialled mongo successfully on address "127.0.0.1:45660"
Test 1: services
Making changes to environment f2a638bb-839f-479a-87ac-b414c9f2e12b
[LOG] 0:01.003 INFO juju.mongo dialled mongo successfully on address "127.0.0.1:45660"
Making changes to environment 29dd32b7-4210-4363-8db1-fe1f01960b43
[LOG] 0:01.266 INFO juju.mongo dialled mongo successfully on address "127.0.0.1:45660"
[LOG] 0:01.467 INFO juju.testing reset successfully reset admin password
[LOG] 0:01.472 INFO juju.state opening state, mongo addresses: ["localhost:45660"]; entity <nil>
[LOG] 0:01.472 DEBUG juju.state dialing mongo
[LOG] 0:01.473 INFO juju.mongo dialled mongo successfully on address "127.0.0.1:45660"
[LOG] 0:01.474 DEBUG juju.state connection established
[LOG] 0:01.503 INFO juju.mongo dialled mongo successfully on address "127.0.0.1:45660"
[LOG] 0:01.504 INFO juju.state initializing environment, owner: "test-admin@local"
[LOG] 0:01.504 INFO juju.state info: &mongo.MongoInfo{Info:mongo.Info{Addrs:[]string{"localhost:45660"}, CACert:"..."}, Tag:names.Tag(nil), Password:""}
[LOG] 0:01.504 INFO juju.state starting presence watcher
[LOG] 0:01.567 INFO juju.mongo dialled mongo successfully on address "127.0.0.1:45660"
[LOG] 0:01.581 INFO juju.state opening state, mongo addresses: ["localhost:45660"]; entity <nil>
[LOG] 0:01.581 DEBUG juju.state dialing mongo
[LOG] 0:01.583 INFO juju.mongo dialled mongo successfully on address "127.0.0.1:45660"
[LOG] 0:01.583 DEBUG juju.state connection established
[LOG] 0:01.592 INFO juju.mongo dialled mongo successfully on address "127.0.0.1:45660"
[LOG] 0:01.595 INFO juju.mongo dialled mongo successfully on address "127.0.0.1:45660"
Test 2: units
Making changes to environment 73a53ba9-4caf-4855-842a-a7fd84a08817
[LOG] 0:01.966 INFO juju.mongo dialled mongo successfully on address "127.0.0.1:45660"
[LOG] 0:01.968 ERROR juju.state.unit unit wordpress/0 cannot get assigned machine: unit "wordpress/0" is not assigned to a machine
[LOG] 0:01.968 ERROR juju.state.unit unit wordpress/0 cannot get assigned machine: unit "wordpress/0" is not assigned to a machine
Making changes to environment 34264e16-f003-451c-8abc-cbf03697cc50
[LOG] 0:02.166 INFO juju.mongo dialled mongo successfully on address "127.0.0.1:45660"
[LOG] 0:02.339 ERROR juju.state.unit unit wordpress/0 cannot get assigned machine: unit "wordpress/0" is not assigned to a machine
[LOG] 0:02.339 ERROR juju.state.unit unit wordpress/0 cannot get assigned machine: unit "wordpress/0" is not assigned to a machine
[LOG] 0:02.497 INFO juju.testing reset successfully reset admin password
[LOG] 0:02.502 INFO juju.state opening state, mongo addresses: ["localhost:45660"]; entity <nil>
[LOG] 0:02.502 DEBUG juju.state dialing mongo
[LOG] 0:02.507 INFO juju.mongo dialled mongo successfully on address "127.0.0.1:45660"
[LOG] 0:02.508 DEBUG juju.state connection established
[LOG] 0:02.597 INFO juju.mongo dialled mongo successfully on address "127.0.0.1:45660"
[LOG] 0:02.598 INFO juju.state initializing environment, owner: "test-admin@local"
[LOG] 0:02.598 INFO juju.state info: &mongo.MongoInfo{Info:mongo.Info{Addrs:[]string{"localhost:45660"}, CACert:"..."}, Tag:names.Tag(nil), Password:""}
[LOG] 0:02.598 INFO juju.state starting presence watcher
[LOG] 0:02.606 INFO juju.mongo dialled mongo successfully on address "127.0.0.1:45660"
[LOG] 0:02.678 INFO juju.state opening state, mongo addresses: ["localhost:45660"]; entity <nil>
[LOG] 0:02.678 DEBUG juju.state dialing mongo
[LOG] 0:02.684 INFO juju.mongo dialled mongo successfully on address "127.0.0.1:45660"
[LOG] 0:02.691 DEBUG juju.state connection established
[LOG] 0:02.700 INFO juju.mongo dialled mongo successfully on address "127.0.0.1:45660"
[LOG] 0:02.707 INFO juju.mongo dialled mongo successfully on address "127.0.0.1:45660"
Test 3: relations
Making changes to environment 3aa28e5f-4749-4ea7-8f9e-f0d21d5cc330
megawatcher_internal_test.go:1819:
    func() {
        checkIsolationForEnv := func(st *State, w, otherW *testWatcher) {
            c.Logf("Making changes to environment %s", st.EnvironUUID())

            if test.setUpState != nil {
                test.setUpState(st)
                // Consume events from setup.
                w.AssertChanges()
                w.AssertNoChange()
                otherW.AssertNoChange()
            }

            test.triggerEvent(st)
            // Check event was isolated to the correct watcher.
            w.AssertChanges()
            w.AssertNoChange()
            otherW.AssertNoChange()
        }

        w1 := newTestWatcher(s.state, c)
        defer w1.Stop()
        w2 := newTestWatcher(s.OtherState, c)
        defer w2.Stop()

        checkIsolationForEnv(s.state, w1, w2)
        checkIsolationForEnv(s.OtherState, w2, w1)
    }()
megawatcher_internal_test.go:1884:
    tw.c.Assert(len(tw.All()), jc.GreaterThan, 0)
... obtained int = 0
... expected int = 0

[LOG] 0:02.882 INFO juju.mongo dialled mongo successfully on address "127.0.0.1:45660"
[LOG] 0:02.887 INFO juju.testing reset successfully reset admin password
2015-03-28 01:15:45 INFO juju.testing mgo.go:514 reset successfully reset admin password
2015-03-28 01:17:16 INFO juju.testing mgo.go:514 reset successfully reset admin password
2015-03-28 01:18:05 INFO juju.testing mgo.go:514 reset successfully reset admin password
OOPS: 910 passed, 2 FAILED
--- FAIL: TestPackage (506.48 seconds)
FAIL
FAIL github.com/juju/juju/state 506.879s

See original description
Tags: ci
Aaron Bentley (abentley)
tags: added: intermittent-failure test-failure
Changed in juju-core:
status: New → Triaged
importance: Undecided → High
John George (jog)
tags: added: unit-tests
Curtis Hovey (sinzui)
Changed in juju-core:
milestone: 1.24-alpha1 → 1.24.0
Curtis Hovey (sinzui)
Changed in juju-core:
milestone: 1.24.0 → 1.25.0
Curtis Hovey (sinzui)
no longer affects: juju-core/1.24
Curtis Hovey (sinzui)
Changed in juju-core:
milestone: 1.25.0 → none
importance: High → Medium
Aaron Bentley (abentley)
description: updated
Revision history for this message
Anastasia (anastasia-macmood) wrote :

Has not been seen in master since June 2016.

Changed in juju-core:
status: Triaged → Incomplete
tags: removed: intermittent-failure test-failure unit-tests
Curtis Hovey (sinzui)
Changed in juju-core:
status: Incomplete → Fix Released
To post a comment you must log in.
This report contains Public information  
Everyone can see this information.

Duplicates of this bug

Subscribing...

Other bug subscribers

Remote bug watches

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