[jira] [Created] (BROOKLYN-204) Unpredictable test: HotStandbyTest.testChangeMode

[Sam Corbett (JIRA)]

Sam Corbett created BROOKLYN-204:
------------------------------------

             Summary: Unpredictable test: HotStandbyTest.testChangeMode
                 Key: BROOKLYN-204
                 URL: https://issues.apache.org/jira/browse/BROOKLYN-204
             Project: Brooklyn
          Issue Type: Bug
            Reporter: Sam Corbett


Build output:
{code}
java.lang.AssertionError: expected [second-app] but found [defaultval]
        at 
org.apache.brooklyn.core.mgmt.ha.HotStandbyTest.testChangeMode(HotStandbyTest.java:537)
... Removed 33 stack frames
{code}

Log:
{code}
2015-12-14 15:59:22,642 INFO  o.a.b.t.s.LoggingVerboseReporter [main]: TESTNG 
INVOKING CONFIGURATION: "Surefire test" - @BeforeMethod 
org.apache.brooklyn.core.mgmt.ha.HotStandbyTest.setUp()
2015-12-14 15:59:22,642 INFO  o.a.b.t.s.LoggingVerboseReporter [main]: TESTNG 
PASSED CONFIGURATION: "Surefire test" - @BeforeMethod 
org.apache.brooklyn.core.mgmt.ha.HotStandbyTest.setUp() finished in 0 ms
2015-12-14 15:59:22,642 INFO  o.a.b.t.s.LoggingVerboseReporter [main]: TESTNG 
INVOKING: "Surefire test" - 
org.apache.brooklyn.core.mgmt.ha.HotStandbyTest.testChangeMode()
2015-12-14 15:59:22,642 DEBUG o.a.b.c.e.d.ReflectiveEntityDriverFactory [main]: 
Added driver mapping rule 
org.apache.brooklyn.core.entity.drivers.ReflectiveEntityDriverFactory$DriverInferenceForSshLocation@c93275f
2015-12-14 15:59:22,642 DEBUG o.a.b.c.e.d.ReflectiveEntityDriverFactory [main]: 
Added driver mapping rule 
org.apache.brooklyn.core.entity.drivers.ReflectiveEntityDriverFactory$DriverInferenceForPaasLocation@37711f1a
2015-12-14 15:59:22,642 DEBUG o.a.b.c.e.d.ReflectiveEntityDriverFactory [main]: 
Added driver mapping rule 
org.apache.brooklyn.core.entity.drivers.ReflectiveEntityDriverFactory$DriverInferenceForWinRmLocation@2182cd7c
2015-12-14 15:59:22,642 DEBUG o.a.b.c.m.r.RebindManagerImpl [main]: 
org.apache.brooklyn.core.mgmt.rebind.RebindManagerImpl@10939bc1[mgmt=null] 
initialized, settings: policies=true, enrichers=true, feeds=true, catalog=true
2015-12-14 15:59:22,642 DEBUG o.a.b.c.BrooklynFeatureEnablement [main]: Init 
feature enablement did nothing, as no settings in brooklyn properties
2015-12-14 15:59:22,642 DEBUG o.a.b.c.m.i.LocalManagementContext [main]: 
Created management context LocalManagementContext[D2gDJQKx-dIEUU7iP]
2015-12-14 15:59:22,643 DEBUG o.a.b.c.m.p.InMemoryObjectStore [main]: Using 
memory-based objectStore
2015-12-14 15:59:22,644 DEBUG 
o.a.b.c.m.h.ManagementPlaneSyncRecordPersisterToObjectStore [main]: 
ManagementPlaneMemento-persister will use store 
org.apache.brooklyn.core.mgmt.persist.ListeningObjectStore@d476a21
2015-12-14 15:59:22,645 INFO  o.a.b.c.mgmt.ha.HotStandbyTest [main]: Created 
node 0 dIEUU7iP
2015-12-14 15:59:22,645 DEBUG 
o.a.b.c.m.h.ManagementPlaneSyncRecordPersisterToObjectStore [main]: No 
master-memento deserialized from file 
StoreObjectAccessorLocking:org.apache.brooklyn.core.mgmt.persist.ListeningObjectStore$ListeningAccessor@c0ef9a8;
 ignoring and continuing (normal on startup, should cause an error later in 
live operation)
2015-12-14 15:59:22,645 DEBUG o.a.b.c.m.h.HighAvailabilityManagerImpl [main]: 
Healthy-master check result=false; masterId=null; masterMemento=<none>; 
ourMemento=<none>
2015-12-14 15:59:22,645 DEBUG 
o.a.b.c.m.h.ManagementPlaneSyncRecordPersisterToObjectStore [main]: 
Checkpointed delta of manager-memento in 0ms: 
org.apache.brooklyn.core.mgmt.ha.ManagementPlaneSyncRecordDeltaImpl[nodes: 
[BasicManagementNodeSyncRecord{nodeId=dIEUU7iP, status=STANDBY}]]
2015-12-14 15:59:22,645 DEBUG 
o.a.b.c.m.h.ManagementPlaneSyncRecordPersisterToObjectStore [main]: No 
master-memento deserialized from file 
StoreObjectAccessorLocking:org.apache.brooklyn.core.mgmt.persist.ListeningObjectStore$ListeningAccessor@c0ef9a8;
 ignoring and continuing (normal on startup, should cause an error later in 
live operation)
2015-12-14 15:59:22,646 DEBUG o.a.b.c.m.h.HighAvailabilityManagerImpl [main]: 
Detected master heartbeat timeout. Initiating a new master election. Master was 
null
2015-12-14 15:59:22,646 DEBUG o.a.b.c.m.h.BasicMasterChooser [main]: Choosing 
new master from {dIEUU7iP=BasicManagementNodeSyncRecord{nodeId=dIEUU7iP, 
status=STANDBY}}
2015-12-14 15:59:22,646 DEBUG o.a.b.c.m.h.HighAvailabilityManagerImpl [main]: 
Management node master-change required: 
newMaster=BasicManagementNodeSyncRecord{brooklynVersion=0.9.0-SNAPSHOT, 
nodeId=dIEUU7iP, status=STANDBY, priority=0, 
localTimestamp=1450108762645=2015-12-14 15:59:22.645, 
remoteTimestamp=1450108762645=2015-12-14 15:59:22.645}; oldMaster=null (no 
memento); plane=ManagementPlaneSyncRecordImpl{masterNodeId=null, 
nodes=[dIEUU7iP]}, 
self=BasicManagementNodeSyncRecord{brooklynVersion=0.9.0-SNAPSHOT, 
nodeId=dIEUU7iP, status=STANDBY, priority=0, 
localTimestamp=1450108762645=2015-12-14 15:59:22.645, 
remoteTimestamp=1450108762645=2015-12-14 15:59:22.645}; heartbeatTimeout=30s
2015-12-14 15:59:22,646 DEBUG 
o.a.b.c.m.h.ManagementPlaneSyncRecordPersisterToObjectStore [main]: 
Checkpointed delta of manager-memento in 0ms: 
org.apache.brooklyn.core.mgmt.ha.ManagementPlaneSyncRecordDeltaImpl[SET_MASTER: 
null->dIEUU7iP; nodes: [BasicManagementNodeSyncRecord{nodeId=dIEUU7iP, 
status=MASTER}]]
2015-12-14 15:59:22,646 DEBUG o.a.b.c.m.r.InitialFullRebindIteration 
[brooklyn-execmanager-dIEUU7iP-0]: Rebinding (MASTER) from in-memory (test) 
persistence store...
2015-12-14 15:59:22,647 DEBUG o.a.b.c.m.p.BrooklynMementoPersisterToObjectStore 
[brooklyn-execmanager-dIEUU7iP-0]: Loaded rebind lists; took 0ms: 0 entities, 0 
locations, 0 policies, 0 enrichers, 0 feeds, 0 catalog items; from in-memory 
(test) persistence store
2015-12-14 15:59:22,647 DEBUG o.a.b.c.m.p.BrooklynMementoPersisterToObjectStore 
[brooklyn-execmanager-dIEUU7iP-0]: Loaded rebind raw data; took 0ms; 0 
entities, 0 locations, 0 policies, 0 enrichers, 0 feeds, 0 catalog items, from 
in-memory (test) persistence store
2015-12-14 15:59:22,647 DEBUG o.a.b.c.m.p.BrooklynMementoPersisterToObjectStore 
[brooklyn-execmanager-dIEUU7iP-0]: Loaded rebind manifests; took 0ms: 0 
entities, 0 locations, 0 policies, 0 enrichers, 0 feeds, 0 catalog items; from 
in-memory (test) persistence store
2015-12-14 15:59:22,647 INFO  o.a.b.c.m.r.InitialFullRebindIteration 
[brooklyn-execmanager-dIEUU7iP-0]: Rebind check: no existing state; will 
persist new items to in-memory (test) persistence store
2015-12-14 15:59:22,647 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-0]: RebindManager instantiating catalog items: []
2015-12-14 15:59:22,647 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-0]: RebindManager reconstructing catalog items
2015-12-14 15:59:22,647 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-0]: RebindManager loading initial catalog 
locally because persisted state empty and the final official run has not yet 
been performed
2015-12-14 15:59:22,647 DEBUG o.a.b.c.c.i.CatalogInitialization 
[brooklyn-execmanager-dIEUU7iP-0]: Populating catalog for MASTER, 
needsInitial=true, needsAdditional=true, explicitItems=null; from 
RebindIteration.rebuildCatalog
2015-12-14 15:59:22,647 DEBUG o.a.b.c.c.i.BasicBrooklynCatalog 
[brooklyn-execmanager-dIEUU7iP-0]: Loading catalog for 
LocalManagementContext[D2gDJQKx-dIEUU7iP]
2015-12-14 15:59:22,647 DEBUG o.a.b.c.c.i.BasicBrooklynCatalog 
[brooklyn-execmanager-dIEUU7iP-0]: Loaded catalog for 
LocalManagementContext[D2gDJQKx-dIEUU7iP]: Loaded:CatalogDto{name=empty 
catalog, contentsDescription=empty catalog, expected to be reset later}(size 
0); search classpath is 
AggregateClassLoader[sun.misc.Launcher$AppClassLoader@29453f44, 
AggregateClassLoader[]]
2015-12-14 15:59:22,647 DEBUG o.a.b.c.c.i.CatalogInitialization 
[brooklyn-execmanager-dIEUU7iP-0]: Loading initial catalog from 
classpath://brooklyn/empty.catalog.bom
2015-12-14 15:59:22,647 DEBUG o.a.b.c.c.i.BasicBrooklynCatalog 
[brooklyn-execmanager-dIEUU7iP-0]: Adding manual catalog item to 
LocalManagementContext[D2gDJQKx-dIEUU7iP]: # Licensed to the Apache Software 
Foundation (ASF) under one
# or more contributor license agreements.  See the NOTICE file
# distributed with this work for additional information
# regarding copyright ownership.  The ASF licenses this file
# to you under the Apache License, Version 2.0 (the
# "License"); you may not use this file except in compliance
# with the License.  You may obtain a copy of the License at
#    
#   http://www.apache.org/licenses/LICENSE-2.0
#    
# Unless required by applicable law or agreed to in writing,
# software distributed under the License is distributed on an
# "AS IS" BASIS, WITHOUT WARRANTIES OR CONDITIONS OF ANY
# KIND, either express or implied.  See the License for the
# specific language governing permissions and limitations
# under the License.
#
brooklyn.catalog: {}
2015-12-14 15:59:22,648 DEBUG o.a.b.c.c.i.CatalogInitialization 
[brooklyn-execmanager-dIEUU7iP-0]: Loaded initial catalog from 
classpath://brooklyn/empty.catalog.bom: []
2015-12-14 15:59:22,648 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-0]: RebindManager instantiating locations: []
2015-12-14 15:59:22,648 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-0]: RebindManager instantiating entities: []
2015-12-14 15:59:22,648 DEBUG o.a.b.c.m.p.BrooklynMementoPersisterToObjectStore 
[brooklyn-execmanager-dIEUU7iP-0]: Loaded rebind mementos; took 0ms: 0 
entities, 0 locations, 0 policies, 0 enrichers, 0 feeds, 0 catalog items, from 
in-memory (test) persistence store
2015-12-14 15:59:22,648 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-0]: RebindManager instantiating policies: []
2015-12-14 15:59:22,648 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-0]: RebindManager instantiating enrichers: []
2015-12-14 15:59:22,648 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-0]: RebindManager instantiating feeds: []
2015-12-14 15:59:22,648 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-0]: RebindManager reconstructing locations
2015-12-14 15:59:22,648 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-0]: RebindManager reconstructing policies
2015-12-14 15:59:22,648 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-0]: RebindManager reconstructing enrichers
2015-12-14 15:59:22,648 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-0]: RebindManager reconstructing feeds
2015-12-14 15:59:22,648 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-0]: RebindManager reconstructing entities
2015-12-14 15:59:22,648 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-0]: RebindManager associating adjuncts to 
entities
2015-12-14 15:59:22,648 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-0]: RebindManager managing locations
2015-12-14 15:59:22,648 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-0]: RebindManager managing entities
2015-12-14 15:59:22,648 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-0]: RebindManager complete; apps: []
2015-12-14 15:59:22,648 DEBUG o.a.b.c.m.r.RebindManagerImpl [main]: Starting 
persistence 
(org.apache.brooklyn.core.mgmt.rebind.RebindManagerImpl@10939bc1[mgmt=dIEUU7iP]),
 mgmt dIEUU7iP
2015-12-14 15:59:22,648 INFO  o.a.b.c.m.h.HighAvailabilityManagerImpl [main]: 
Management node dIEUU7iP running as HA MASTER autodetected
2015-12-14 15:59:22,648 DEBUG o.a.b.c.m.r.PeriodicDeltaChangeListener 
[brooklyn-execmanager-dIEUU7iP-1]: Checkpointing delta of memento: updating 
entities=[], locations=[], policies=[], enrichers=[], catalog items=[]; 
removing entities=[], locations=[], policies=[], enrichers=[], catalog items=[]
2015-12-14 15:59:22,649 DEBUG o.a.b.c.m.h.HighAvailabilityManagerImpl [main]: 
Registering poll task for 
org.apache.brooklyn.core.mgmt.ha.HighAvailabilityManagerImpl@8add32f[node:dIEUU7iP;running=true],
 period 106751d 23h 47m 16s 854ms 775us 807ns
2015-12-14 15:59:22,659 DEBUG o.a.b.core.sensor.AttributeMap 
[brooklyn-execmanager-dIEUU7iP-0]: removing attribute service.state on 
Application[B8OCmlHf]
2015-12-14 15:59:22,659 DEBUG o.a.b.e.s.AbstractMultipleSensorAggregator 
[brooklyn-execmanager-dIEUU7iP-0]: 
ComputeServiceIndicatorsFromChildrenAndMembers{uniqueTag=service-lifecycle-indicators-from-children-and-members,
 running=true, entity=Application[B8OCmlHf], id=LrUDBFxe} subscribing to 
children of Application[B8OCmlHf]
2015-12-14 15:59:22,660 DEBUG o.a.b.c.e.l.ServiceStateLogic 
[brooklyn-execmanager-dIEUU7iP-0]: Ignoring 
ComputeServiceIndicatorsFromChildrenAndMembers{uniqueTag=service-lifecycle-indicators-from-children-and-members,
 running=true, entity=Application[B8OCmlHf], id=LrUDBFxe} onUpdated when entity 
is not in valid state (Application[B8OCmlHf])
2015-12-14 15:59:22,660 DEBUG o.a.b.c.entity.AbstractEntity 
[brooklyn-execmanager-dIEUU7iP-0]: Emitting sensor notification 
entity.sensor.added value Sensor: service.notUp.indicators (java.util.Map) on 
Application[B8OCmlHf]
2015-12-14 15:59:22,660 DEBUG o.a.b.c.m.i.LocalSubscriptionManager [main]: 
Creating subscription su6DhAnC for Application[B8OCmlHf] on 
Application[B8OCmlHf] Sensor: service.notUp.indicators (java.util.Map) in 
SubscriptionContext(4NiHo)
2015-12-14 15:59:22,660 DEBUG o.a.b.c.m.i.LocalSubscriptionManager [main]: 
Creating subscription ZaXptAtL for Application[B8OCmlHf] on 
Application[B8OCmlHf] Sensor: service.problems (java.util.Map) in 
SubscriptionContext(4NiHo)
2015-12-14 15:59:22,660 DEBUG o.a.b.c.m.i.LocalSubscriptionManager [main]: 
Creating subscription ZArEg0Nw for Application[B8OCmlHf] on 
Application[B8OCmlHf] Sensor: service.isUp (java.lang.Boolean) in 
SubscriptionContext(4NiHo)
2015-12-14 15:59:22,660 DEBUG o.a.b.c.m.i.LocalSubscriptionManager [main]: 
Creating subscription b9YHdECT for Application[B8OCmlHf] on 
Application[B8OCmlHf] Sensor: service.state.expected 
(org.apache.brooklyn.core.entity.lifecycle.Lifecycle$Transition) in 
SubscriptionContext(4NiHo)
2015-12-14 15:59:22,660 DEBUG o.a.b.c.m.i.LocalSubscriptionManager [main]: 
Creating subscription rwOTc1z1 for Application[B8OCmlHf] on null Sensor: 
service.isUp (java.lang.Boolean) in SubscriptionContext(4NiHo)
2015-12-14 15:59:22,660 DEBUG o.a.b.c.m.i.LocalSubscriptionManager [main]: 
Creating subscription l4WZsBHM for Application[B8OCmlHf] on null Sensor: 
service.state (org.apache.brooklyn.core.entity.lifecycle.Lifecycle) in 
SubscriptionContext(4NiHo)
2015-12-14 15:59:22,660 DEBUG o.a.b.c.m.i.LocalSubscriptionManager [main]: 
Creating subscription CVqms9Qv for Application[B8OCmlHf] on 
Application[B8OCmlHf] Sensor: entity.children.removed 
(org.apache.brooklyn.api.entity.Entity) in SubscriptionContext(4NiHo)
2015-12-14 15:59:22,660 DEBUG o.a.b.c.m.i.LocalSubscriptionManager [main]: 
Creating subscription nP6rP8GR for Application[B8OCmlHf] on 
Application[B8OCmlHf] Sensor: entity.children.added 
(org.apache.brooklyn.api.entity.Entity) in SubscriptionContext(4NiHo)
2015-12-14 15:59:22,660 DEBUG o.a.b.c.m.i.LocalEntityManager [main]: 
org.apache.brooklyn.core.mgmt.internal.LocalEntityManager@4eccb9f0 starting 
management of entity Application[B8OCmlHf]
2015-12-14 15:59:22,660 DEBUG o.a.b.core.sensor.AttributeMap 
[brooklyn-execmanager-dIEUU7iP-3]: removing attribute service.state on 
Application[B8OCmlHf]
2015-12-14 15:59:22,661 DEBUG o.a.b.c.l.BasicLocationRegistry 
[brooklyn-execmanager-dIEUU7iP-4]: Location resolvers are: 
{id=org.apache.brooklyn.core.location.DefinedLocationByIdResolver@38b22487, 
named=org.apache.brooklyn.core.location.NamedLocationResolver@3c8db749, 
brooklyn.catalog=org.apache.brooklyn.core.location.CatalogLocationResolver@798f2ebc,
 
portForwardManager=org.apache.brooklyn.core.location.access.PortForwardManagerLocationResolver@6183b1a0,
 byon=org.apache.brooklyn.location.byon.ByonLocationResolver@483c370b, 
single=org.apache.brooklyn.location.byon.SingleMachineLocationResolver@19daf6ba,
 host=org.apache.brooklyn.location.byon.HostLocationResolver@19a0d1f3, 
localhost=org.apache.brooklyn.location.localhost.LocalhostLocationResolver@65d26dd6,
 multi=org.apache.brooklyn.location.multi.MultiLocationResolver@4cff8a08}
2015-12-14 15:59:22,661 DEBUG o.a.b.c.l.BasicLocationRegistry 
[brooklyn-execmanager-dIEUU7iP-4]: Found 0 defined locations from properties 
(*.named.* syntax): []
2015-12-14 15:59:22,661 DEBUG o.a.b.c.l.BasicLocationRegistry 
[brooklyn-execmanager-dIEUU7iP-4]: Adding a defined location for localhost
2015-12-14 15:59:22,662 DEBUG o.a.b.c.m.i.EffectorUtils 
[brooklyn-execmanager-dIEUU7iP-4]: Invoking effector start on 
Application[B8OCmlHf]
2015-12-14 15:59:22,662 DEBUG o.a.b.c.entity.AbstractEntity 
[brooklyn-execmanager-dIEUU7iP-4]: Emitting sensor notification 
entity.sensor.added value Sensor: service.problems (java.util.Map) on 
Application[B8OCmlHf]
2015-12-14 15:59:22,662 DEBUG o.a.b.c.entity.AbstractEntity 
[brooklyn-execmanager-dIEUU7iP-0]: Emitting sensor notification 
entity.sensor.added value Sensor: service.state 
(org.apache.brooklyn.core.entity.lifecycle.Lifecycle) on Application[B8OCmlHf]
2015-12-14 15:59:22,662 DEBUG o.a.b.c.entity.AbstractEntity 
[brooklyn-execmanager-dIEUU7iP-4]: Emitting sensor notification 
entity.sensor.added value Sensor: service.state.expected 
(org.apache.brooklyn.core.entity.lifecycle.Lifecycle$Transition) on 
Application[B8OCmlHf]
2015-12-14 15:59:22,662 DEBUG o.a.b.c.m.i.LocalUsageManager 
[brooklyn-execmanager-dIEUU7iP-4]: Storing application lifecycle usage event: 
application Application[B8OCmlHf] in state starting
2015-12-14 15:59:22,662 DEBUG o.a.b.c.e.t.StartableMethods 
[brooklyn-execmanager-dIEUU7iP-4]: Starting entity Application[B8OCmlHf] at []
2015-12-14 15:59:22,662 DEBUG o.a.b.c.m.i.LocalUsageManager 
[brooklyn-execmanager-dIEUU7iP-4]: Storing application lifecycle usage event: 
application Application[B8OCmlHf] in state running
2015-12-14 15:59:22,662 DEBUG o.a.b.c.t.e.TestApplication 
[brooklyn-execmanager-dIEUU7iP-4]: Started application Application[B8OCmlHf]
2015-12-14 15:59:22,662 DEBUG o.a.b.c.entity.AbstractEntity [main]: Emitting 
sensor notification entity.sensor.added value Sensor: test.sequence 
(java.lang.Integer) on Application[B8OCmlHf]
2015-12-14 15:59:22,663 DEBUG o.a.b.c.m.r.PeriodicDeltaChangeListener [main]: 
Checkpointing delta of memento: updating entities=[Application[B8OCmlHf]], 
locations=[], policies=[], enrichers=[Transformer{uniqueTag=service.isUp if no 
service.notUp.indicators, running=true, entity=Application[B8OCmlHf], 
id=C8SMM18F}, 
ComputeServiceIndicatorsFromChildrenAndMembers{uniqueTag=service-lifecycle-indicators-from-children-and-members,
 running=true, entity=Application[B8OCmlHf], id=LrUDBFxe}], catalog items=[]; 
removing entities=[], locations=[], policies=[], enrichers=[], catalog items=[]
2015-12-14 15:59:22,665 DEBUG o.a.b.c.m.p.BrooklynMementoPersisterToObjectStore 
[main]: Checkpointed delta of memento in 2ms: updated 1 entities, 0 locations, 
0 policies, 3 enrichers, 0 catalog items; removed 0 entities, 0 locations, 0 
policies, 0 enrichers, 0 catalog items
2015-12-14 15:59:22,665 DEBUG o.a.b.c.e.d.ReflectiveEntityDriverFactory [main]: 
Added driver mapping rule 
org.apache.brooklyn.core.entity.drivers.ReflectiveEntityDriverFactory$DriverInferenceForSshLocation@49bc9588
2015-12-14 15:59:22,665 DEBUG o.a.b.c.e.d.ReflectiveEntityDriverFactory [main]: 
Added driver mapping rule 
org.apache.brooklyn.core.entity.drivers.ReflectiveEntityDriverFactory$DriverInferenceForPaasLocation@7b9c54c7
2015-12-14 15:59:22,665 DEBUG o.a.b.c.e.d.ReflectiveEntityDriverFactory [main]: 
Added driver mapping rule 
org.apache.brooklyn.core.entity.drivers.ReflectiveEntityDriverFactory$DriverInferenceForWinRmLocation@496de48b
2015-12-14 15:59:22,665 DEBUG o.a.b.c.m.r.RebindManagerImpl [main]: 
org.apache.brooklyn.core.mgmt.rebind.RebindManagerImpl@7005b6e[mgmt=null] 
initialized, settings: policies=true, enrichers=true, feeds=true, catalog=true
2015-12-14 15:59:22,666 DEBUG o.a.b.c.BrooklynFeatureEnablement [main]: Init 
feature enablement did nothing, as no settings in brooklyn properties
2015-12-14 15:59:22,666 DEBUG o.a.b.c.m.i.LocalManagementContext [main]: 
Created management context LocalManagementContext[L4lcSpwh-PRrsOqGI]
2015-12-14 15:59:22,666 DEBUG o.a.b.c.m.p.InMemoryObjectStore [main]: Using 
memory-based objectStore
2015-12-14 15:59:22,666 DEBUG 
o.a.b.c.m.h.ManagementPlaneSyncRecordPersisterToObjectStore [main]: 
ManagementPlaneMemento-persister will use store 
org.apache.brooklyn.core.mgmt.persist.ListeningObjectStore@15f12e7b
2015-12-14 15:59:22,667 INFO  o.a.b.c.mgmt.ha.HotStandbyTest [main]: Created 
node 1 PRrsOqGI
2015-12-14 15:59:22,668 DEBUG o.a.b.c.m.h.HighAvailabilityManagerImpl [main]: 
Healthy-master check result=true; masterId=dIEUU7iP; 
masterMemento=BasicManagementNodeSyncRecord{brooklynVersion=0.9.0-SNAPSHOT, 
nodeId=dIEUU7iP, status=MASTER, priority=0, 
localTimestamp=1450108762646=2015-12-14 15:59:22.646, 
remoteTimestamp=1450108762646=2015-12-14 15:59:22.646}; ourMemento=<none>
2015-12-14 15:59:22,668 INFO  o.a.b.c.m.h.HighAvailabilityManagerImpl [main]: 
Management node PRrsOqGI running as HA STANDBY (caller requested HOT_STANDBY, 
will attempt rebind for HOT_STANDBY next); master dIEUU7iP
2015-12-14 15:59:22,668 DEBUG 
o.a.b.c.m.h.ManagementPlaneSyncRecordPersisterToObjectStore [main]: 
Checkpointed delta of manager-memento in 0ms: 
org.apache.brooklyn.core.mgmt.ha.ManagementPlaneSyncRecordDeltaImpl[nodes: 
[BasicManagementNodeSyncRecord{nodeId=PRrsOqGI, status=INITIALIZING}]]
2015-12-14 15:59:22,668 DEBUG o.a.b.c.m.r.RebindManagerImpl [main]: Starting 
read-only rebinding 
(org.apache.brooklyn.core.mgmt.rebind.RebindManagerImpl@7005b6e[mgmt=PRrsOqGI]),
 mgmt PRrsOqGI
2015-12-14 15:59:22,668 DEBUG o.a.b.c.m.r.InitialFullRebindIteration 
[brooklyn-execmanager-PRrsOqGI-0]: Rebinding (HOT_STANDBY, iteration 1) from 
in-memory (test) persistence store...
2015-12-14 15:59:22,669 DEBUG o.a.b.c.m.p.BrooklynMementoPersisterToObjectStore 
[brooklyn-execmanager-PRrsOqGI-0]: Loaded rebind lists; took 0ms: 1 entities, 0 
locations, 0 policies, 3 enrichers, 0 feeds, 0 catalog items; from in-memory 
(test) persistence store
2015-12-14 15:59:22,670 DEBUG o.a.b.c.m.p.BrooklynMementoPersisterToObjectStore 
[brooklyn-execmanager-PRrsOqGI-0]: Loaded rebind raw data; took 1ms; 1 
entities, 0 locations, 0 policies, 3 enrichers, 0 feeds, 0 catalog items, from 
in-memory (test) persistence store
2015-12-14 15:59:22,677 DEBUG o.a.b.c.m.p.BrooklynMementoPersisterToObjectStore 
[brooklyn-execmanager-PRrsOqGI-0]: Loaded rebind manifests; took 6ms: 1 
entities, 0 locations, 0 policies, 3 enrichers, 0 feeds, 0 catalog items; from 
in-memory (test) persistence store
2015-12-14 15:59:22,677 INFO  o.a.b.c.m.r.InitialFullRebindIteration 
[brooklyn-execmanager-PRrsOqGI-0]: Rebinding from in-memory (test) persistence 
store for hot_standby PRrsOqGI...
2015-12-14 15:59:22,677 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-PRrsOqGI-0]: RebindManager instantiating catalog items: []
2015-12-14 15:59:22,677 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-PRrsOqGI-0]: RebindManager reconstructing catalog items
2015-12-14 15:59:22,677 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-PRrsOqGI-0]: RebindManager clearing local catalog and 
loading from persisted state
2015-12-14 15:59:22,677 DEBUG o.a.b.c.c.i.BasicBrooklynCatalog 
[brooklyn-execmanager-PRrsOqGI-0]: Loading catalog for 
LocalManagementContext[L4lcSpwh-PRrsOqGI]
2015-12-14 15:59:22,677 DEBUG o.a.b.c.c.i.BasicBrooklynCatalog 
[brooklyn-execmanager-PRrsOqGI-0]: Loaded catalog for 
LocalManagementContext[L4lcSpwh-PRrsOqGI]: Loaded:CatalogDto{name=empty 
catalog, contentsDescription=empty catalog, expected to be reset later}(size 
0); search classpath is 
AggregateClassLoader[sun.misc.Launcher$AppClassLoader@29453f44, 
AggregateClassLoader[]]
2015-12-14 15:59:22,677 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-PRrsOqGI-0]: RebindManager instantiating locations: []
2015-12-14 15:59:22,677 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-PRrsOqGI-0]: RebindManager instantiating entities: 
[B8OCmlHf]
2015-12-14 15:59:22,687 DEBUG o.a.b.c.m.p.BrooklynMementoPersisterToObjectStore 
[brooklyn-execmanager-PRrsOqGI-0]: Loaded rebind mementos; took 1ms: 1 
entities, 0 locations, 0 policies, 3 enrichers, 0 feeds, 0 catalog items, from 
in-memory (test) persistence store
2015-12-14 15:59:22,687 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-PRrsOqGI-0]: RebindManager instantiating policies: []
2015-12-14 15:59:22,687 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-PRrsOqGI-0]: RebindManager instantiating enrichers: 
[LrUDBFxe, C8SMM18F, wLvfLUO6]
2015-12-14 15:59:22,687 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-PRrsOqGI-0]: RebindManager instantiating enricher 
BasicEnricherMemento{type=org.apache.brooklyn.core.entity.lifecycle.ServiceStateLogic$ComputeServiceIndicatorsFromChildrenAndMembers,
 id=LrUDBFxe}
2015-12-14 15:59:22,687 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-PRrsOqGI-0]: RebindManager instantiating enricher 
BasicEnricherMemento{type=org.apache.brooklyn.enricher.stock.Transformer, 
id=C8SMM18F}
2015-12-14 15:59:22,687 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-PRrsOqGI-0]: RebindManager instantiating enricher 
BasicEnricherMemento{type=org.apache.brooklyn.core.entity.lifecycle.ServiceStateLogic$ComputeServiceState,
 id=wLvfLUO6}
2015-12-14 15:59:22,687 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-PRrsOqGI-0]: RebindManager instantiating feeds: []
2015-12-14 15:59:22,687 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-PRrsOqGI-0]: RebindManager reconstructing locations
2015-12-14 15:59:22,687 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-PRrsOqGI-0]: RebindManager reconstructing policies
2015-12-14 15:59:22,687 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-PRrsOqGI-0]: RebindManager reconstructing enrichers
2015-12-14 15:59:22,687 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-PRrsOqGI-0]: RebindManager reconstructing enricher 
BasicEnricherMemento{type=org.apache.brooklyn.core.entity.lifecycle.ServiceStateLogic$ComputeServiceIndicatorsFromChildrenAndMembers,
 id=LrUDBFxe}
2015-12-14 15:59:22,687 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-PRrsOqGI-0]: RebindManager reconstructing enricher 
BasicEnricherMemento{type=org.apache.brooklyn.enricher.stock.Transformer, 
id=C8SMM18F}
2015-12-14 15:59:22,687 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-PRrsOqGI-0]: RebindManager reconstructing enricher 
BasicEnricherMemento{type=org.apache.brooklyn.core.entity.lifecycle.ServiceStateLogic$ComputeServiceState,
 id=wLvfLUO6}
2015-12-14 15:59:22,687 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-PRrsOqGI-0]: RebindManager reconstructing feeds
2015-12-14 15:59:22,687 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-PRrsOqGI-0]: RebindManager reconstructing entities
2015-12-14 15:59:22,688 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-PRrsOqGI-0]: RebindManager reconstructing entity 
BasicEntityMemento{type=org.apache.brooklyn.core.test.entity.TestApplicationImpl,
 id=B8OCmlHf}
2015-12-14 15:59:22,688 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-PRrsOqGI-0]: RebindManager associating adjuncts to 
entities
2015-12-14 15:59:22,688 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-PRrsOqGI-0]: RebindManager associating adjuncts to entity 
BasicEntityMemento{type=org.apache.brooklyn.core.test.entity.TestApplicationImpl,
 id=B8OCmlHf}
2015-12-14 15:59:22,695 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-PRrsOqGI-0]: RebindManager managing locations
2015-12-14 15:59:22,695 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-PRrsOqGI-0]: RebindManager managing entities
2015-12-14 15:59:22,696 INFO  o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-PRrsOqGI-0]: Rebind complete (HOT_STANDBY, iteration 1) 
in 27ms: 1 app, 1 entity, 0 locations, 0 policies, 3 enrichers, 0 feeds, 0 
catalog items
2015-12-14 15:59:22,696 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-PRrsOqGI-0]: RebindManager complete; apps: [B8OCmlHf]
2015-12-14 15:59:22,696 DEBUG 
o.a.b.c.m.h.ManagementPlaneSyncRecordPersisterToObjectStore [main]: 
Checkpointed delta of manager-memento in 0ms: 
org.apache.brooklyn.core.mgmt.ha.ManagementPlaneSyncRecordDeltaImpl[nodes: 
[BasicManagementNodeSyncRecord{nodeId=PRrsOqGI, status=HOT_STANDBY}]]
2015-12-14 15:59:22,696 INFO  o.a.b.c.m.h.HighAvailabilityManagerImpl [main]: 
Management node PRrsOqGI now running as HA HOT_STANDBY; 1 application loaded
2015-12-14 15:59:22,696 DEBUG o.a.b.c.m.h.HighAvailabilityManagerImpl [main]: 
Registering poll task for 
org.apache.brooklyn.core.mgmt.ha.HighAvailabilityManagerImpl@328c6f65[node:PRrsOqGI;running=true],
 period 106751d 23h 47m 16s 854ms 775us 807ns
2015-12-14 15:59:22,697 DEBUG o.a.b.c.m.r.InitialFullRebindIteration 
[brooklyn-execmanager-PRrsOqGI-1]: Rebinding (HOT_STANDBY, iteration 2) from 
in-memory (test) persistence store...
2015-12-14 15:59:22,697 DEBUG o.a.b.c.m.p.BrooklynMementoPersisterToObjectStore 
[brooklyn-execmanager-PRrsOqGI-1]: Loaded rebind lists; took 0ms: 1 entities, 0 
locations, 0 policies, 3 enrichers, 0 feeds, 0 catalog items; from in-memory 
(test) persistence store
2015-12-14 15:59:22,708 DEBUG o.a.b.c.m.h.HighAvailabilityManagerImpl 
[Finalizer]: Stopping 
org.apache.brooklyn.core.mgmt.ha.HighAvailabilityManagerImpl@13a0f230[node:FxluF6Vo;running=false]
2015-12-14 15:59:22,708 DEBUG o.a.b.c.m.r.RebindManagerImpl [Finalizer]: 
Stopping persistence 
(org.apache.brooklyn.core.mgmt.rebind.RebindManagerImpl@579c24c2[mgmt=FxluF6Vo]),
 mgmt FxluF6Vo
2015-12-14 15:59:22,708 DEBUG o.a.b.c.m.r.RebindManagerImpl [Finalizer]: 
Stopped rebind (persistence), mgmt FxluF6Vo
2015-12-14 15:59:22,708 DEBUG o.a.b.c.m.h.HighAvailabilityManagerImpl 
[Finalizer]: Stopping 
org.apache.brooklyn.core.mgmt.ha.HighAvailabilityManagerImpl@3c75fb73[node:null;running=false]
2015-12-14 15:59:22,708 DEBUG o.a.b.c.m.r.RebindManagerImpl [Finalizer]: 
Stopping persistence 
(org.apache.brooklyn.core.mgmt.rebind.RebindManagerImpl@78ad7d17[mgmt=ubZkEXh9]),
 mgmt ubZkEXh9
2015-12-14 15:59:22,708 DEBUG o.a.b.c.m.r.RebindManagerImpl [Finalizer]: 
Stopped rebind (persistence), mgmt ubZkEXh9
2015-12-14 15:59:22,708 DEBUG o.a.b.c.m.h.HighAvailabilityManagerImpl 
[Finalizer]: Stopping 
org.apache.brooklyn.core.mgmt.ha.HighAvailabilityManagerImpl@2673ba1f[node:null;running=false]
2015-12-14 15:59:22,708 DEBUG o.a.b.c.m.r.RebindManagerImpl [Finalizer]: 
Stopping persistence 
(org.apache.brooklyn.core.mgmt.rebind.RebindManagerImpl@1c0697c9[mgmt=A8tPTn5G]),
 mgmt A8tPTn5G
2015-12-14 15:59:22,708 DEBUG o.a.b.c.m.r.RebindManagerImpl [Finalizer]: 
Stopped rebind (persistence), mgmt A8tPTn5G
2015-12-14 15:59:22,708 DEBUG o.a.b.c.m.h.HighAvailabilityManagerImpl 
[Finalizer]: Stopping 
org.apache.brooklyn.core.mgmt.ha.HighAvailabilityManagerImpl@4743220d[node:null;running=false]
2015-12-14 15:59:22,708 DEBUG o.a.b.c.m.r.RebindManagerImpl [Finalizer]: 
Stopping persistence 
(org.apache.brooklyn.core.mgmt.rebind.RebindManagerImpl@347b27f3[mgmt=V6fsres4]),
 mgmt V6fsres4
2015-12-14 15:59:22,708 DEBUG o.a.b.c.m.r.RebindManagerImpl [Finalizer]: 
Stopped rebind (persistence), mgmt V6fsres4
2015-12-14 15:59:22,708 DEBUG o.a.b.c.m.h.HighAvailabilityManagerImpl 
[Finalizer]: Stopping 
org.apache.brooklyn.core.mgmt.ha.HighAvailabilityManagerImpl@45bf64f7[node:null;running=false]
2015-12-14 15:59:22,708 DEBUG o.a.b.c.m.r.RebindManagerImpl [Finalizer]: 
Stopping persistence 
(org.apache.brooklyn.core.mgmt.rebind.RebindManagerImpl@5ae50044[mgmt=xB8vpILw]),
 mgmt xB8vpILw
2015-12-14 15:59:22,708 DEBUG o.a.b.c.m.r.RebindManagerImpl [Finalizer]: 
Stopped rebind (persistence), mgmt xB8vpILw
2015-12-14 15:59:22,708 DEBUG o.a.b.c.m.h.HighAvailabilityManagerImpl 
[Finalizer]: Stopping 
org.apache.brooklyn.core.mgmt.ha.HighAvailabilityManagerImpl@1f1e4e04[node:null;running=false]
2015-12-14 15:59:22,708 DEBUG o.a.b.c.m.r.RebindManagerImpl [Finalizer]: 
Stopping persistence 
(org.apache.brooklyn.core.mgmt.rebind.RebindManagerImpl@231e5af[mgmt=gOVqrkwa]),
 mgmt gOVqrkwa
2015-12-14 15:59:22,708 DEBUG o.a.b.c.m.r.RebindManagerImpl [Finalizer]: 
Stopped rebind (persistence), mgmt gOVqrkwa
2015-12-14 15:59:22,708 DEBUG o.a.b.c.m.h.HighAvailabilityManagerImpl 
[Finalizer]: Stopping 
org.apache.brooklyn.core.mgmt.ha.HighAvailabilityManagerImpl@5c8b15f5[node:null;running=false]
2015-12-14 15:59:22,708 DEBUG o.a.b.c.m.r.RebindManagerImpl [Finalizer]: 
Stopping persistence 
(org.apache.brooklyn.core.mgmt.rebind.RebindManagerImpl@6e00a1ba[mgmt=xGXjcIav]),
 mgmt xGXjcIav
2015-12-14 15:59:22,708 DEBUG o.a.b.c.m.r.RebindManagerImpl [Finalizer]: 
Stopped rebind (persistence), mgmt xGXjcIav
2015-12-14 15:59:22,708 DEBUG o.a.b.c.m.h.HighAvailabilityManagerImpl 
[Finalizer]: Stopping 
org.apache.brooklyn.core.mgmt.ha.HighAvailabilityManagerImpl@ff1d29[node:null;running=false]
2015-12-14 15:59:22,708 DEBUG o.a.b.c.m.r.RebindManagerImpl [Finalizer]: 
Stopping persistence 
(org.apache.brooklyn.core.mgmt.rebind.RebindManagerImpl@446717fb[mgmt=w60p1vgO]),
 mgmt w60p1vgO
2015-12-14 15:59:22,708 DEBUG o.a.b.c.m.r.RebindManagerImpl [Finalizer]: 
Stopped rebind (persistence), mgmt w60p1vgO
2015-12-14 15:59:22,708 DEBUG o.a.b.c.m.h.HighAvailabilityManagerImpl 
[Finalizer]: Stopping 
org.apache.brooklyn.core.mgmt.ha.HighAvailabilityManagerImpl@3bf8feec[node:s1r2DdIF;running=false]
2015-12-14 15:59:22,708 DEBUG o.a.b.c.m.r.RebindManagerImpl [Finalizer]: 
Stopping persistence 
(org.apache.brooklyn.core.mgmt.rebind.RebindManagerImpl@5fc444d0[mgmt=s1r2DdIF]),
 mgmt s1r2DdIF
2015-12-14 15:59:22,708 DEBUG o.a.b.c.m.r.RebindManagerImpl [Finalizer]: 
Stopped rebind (persistence), mgmt s1r2DdIF
2015-12-14 15:59:22,708 DEBUG o.a.b.c.m.h.HighAvailabilityManagerImpl 
[Finalizer]: Stopping 
org.apache.brooklyn.core.mgmt.ha.HighAvailabilityManagerImpl@7b255b15[node:null;running=false]
2015-12-14 15:59:22,708 DEBUG o.a.b.c.m.r.RebindManagerImpl [Finalizer]: 
Stopping persistence 
(org.apache.brooklyn.core.mgmt.rebind.RebindManagerImpl@5cf4023d[mgmt=XcKMxFEw]),
 mgmt XcKMxFEw
2015-12-14 15:59:22,708 DEBUG o.a.b.c.m.r.RebindManagerImpl [Finalizer]: 
Stopped rebind (persistence), mgmt XcKMxFEw
2015-12-14 15:59:22,709 DEBUG o.a.b.c.m.h.HighAvailabilityManagerImpl 
[Finalizer]: Stopping 
org.apache.brooklyn.core.mgmt.ha.HighAvailabilityManagerImpl@230d0018[node:null;running=false]
2015-12-14 15:59:22,709 DEBUG o.a.b.c.m.r.RebindManagerImpl [Finalizer]: 
Stopping persistence 
(org.apache.brooklyn.core.mgmt.rebind.RebindManagerImpl@66f4fb48[mgmt=A5w7ozbb]),
 mgmt A5w7ozbb
2015-12-14 15:59:22,709 DEBUG o.a.b.c.m.r.RebindManagerImpl [Finalizer]: 
Stopped rebind (persistence), mgmt A5w7ozbb
2015-12-14 15:59:22,709 DEBUG o.a.b.c.m.p.BrooklynMementoPersisterToObjectStore 
[brooklyn-execmanager-PRrsOqGI-1]: Loaded rebind raw data; took 12ms; 1 
entities, 0 locations, 0 policies, 3 enrichers, 0 feeds, 0 catalog items, from 
in-memory (test) persistence store
2015-12-14 15:59:22,718 DEBUG o.a.b.c.m.p.BrooklynMementoPersisterToObjectStore 
[brooklyn-execmanager-PRrsOqGI-1]: Loaded rebind manifests; took 8ms: 1 
entities, 0 locations, 0 policies, 3 enrichers, 0 feeds, 0 catalog items; from 
in-memory (test) persistence store
2015-12-14 15:59:22,718 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-PRrsOqGI-1]: RebindManager instantiating catalog items: []
2015-12-14 15:59:22,718 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-PRrsOqGI-1]: RebindManager reconstructing catalog items
2015-12-14 15:59:22,718 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-PRrsOqGI-1]: RebindManager clearing local catalog and 
loading from persisted state
2015-12-14 15:59:22,718 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-PRrsOqGI-1]: RebindManager instantiating locations: []
2015-12-14 15:59:22,718 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-PRrsOqGI-1]: RebindManager instantiating entities: 
[B8OCmlHf]
2015-12-14 15:59:22,720 DEBUG o.a.b.c.m.p.BrooklynMementoPersisterToObjectStore 
[brooklyn-execmanager-PRrsOqGI-1]: Loaded rebind mementos; took 0ms: 1 
entities, 0 locations, 0 policies, 3 enrichers, 0 feeds, 0 catalog items, from 
in-memory (test) persistence store
2015-12-14 15:59:22,720 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-PRrsOqGI-1]: RebindManager instantiating policies: []
2015-12-14 15:59:22,720 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-PRrsOqGI-1]: RebindManager instantiating enrichers: 
[LrUDBFxe, C8SMM18F, wLvfLUO6]
2015-12-14 15:59:22,720 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-PRrsOqGI-1]: RebindManager instantiating enricher 
BasicEnricherMemento{type=org.apache.brooklyn.core.entity.lifecycle.ServiceStateLogic$ComputeServiceIndicatorsFromChildrenAndMembers,
 id=LrUDBFxe}
2015-12-14 15:59:22,721 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-PRrsOqGI-1]: RebindManager instantiating enricher 
BasicEnricherMemento{type=org.apache.brooklyn.enricher.stock.Transformer, 
id=C8SMM18F}
2015-12-14 15:59:22,721 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-PRrsOqGI-1]: RebindManager instantiating enricher 
BasicEnricherMemento{type=org.apache.brooklyn.core.entity.lifecycle.ServiceStateLogic$ComputeServiceState,
 id=wLvfLUO6}
2015-12-14 15:59:22,721 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-PRrsOqGI-1]: RebindManager instantiating feeds: []
2015-12-14 15:59:22,721 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-PRrsOqGI-1]: RebindManager reconstructing locations
2015-12-14 15:59:22,721 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-PRrsOqGI-1]: RebindManager reconstructing policies
2015-12-14 15:59:22,721 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-PRrsOqGI-1]: RebindManager reconstructing enrichers
2015-12-14 15:59:22,721 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-PRrsOqGI-1]: RebindManager reconstructing enricher 
BasicEnricherMemento{type=org.apache.brooklyn.core.entity.lifecycle.ServiceStateLogic$ComputeServiceIndicatorsFromChildrenAndMembers,
 id=LrUDBFxe}
2015-12-14 15:59:22,721 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-PRrsOqGI-1]: RebindManager reconstructing enricher 
BasicEnricherMemento{type=org.apache.brooklyn.enricher.stock.Transformer, 
id=C8SMM18F}
2015-12-14 15:59:22,721 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-PRrsOqGI-1]: RebindManager reconstructing enricher 
BasicEnricherMemento{type=org.apache.brooklyn.core.entity.lifecycle.ServiceStateLogic$ComputeServiceState,
 id=wLvfLUO6}
2015-12-14 15:59:22,721 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-PRrsOqGI-1]: RebindManager reconstructing feeds
2015-12-14 15:59:22,721 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-PRrsOqGI-1]: RebindManager reconstructing entities
2015-12-14 15:59:22,721 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-PRrsOqGI-1]: RebindManager reconstructing entity 
BasicEntityMemento{type=org.apache.brooklyn.core.test.entity.TestApplicationImpl,
 id=B8OCmlHf}
2015-12-14 15:59:22,722 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-PRrsOqGI-1]: RebindManager associating adjuncts to 
entities
2015-12-14 15:59:22,722 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-PRrsOqGI-1]: RebindManager associating adjuncts to entity 
BasicEntityMemento{type=org.apache.brooklyn.core.test.entity.TestApplicationImpl,
 id=B8OCmlHf}
2015-12-14 15:59:22,722 DEBUG o.a.b.c.entity.AbstractEntity [main]: Emitting 
sensor notification entity.children.added value TestEntityImpl{id=F7J356SN} on 
Application[B8OCmlHf]
2015-12-14 15:59:22,722 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-PRrsOqGI-1]: RebindManager managing locations
2015-12-14 15:59:22,722 DEBUG o.a.b.e.s.AbstractMultipleSensorAggregator 
[brooklyn-execmanager-dIEUU7iP-3]: 
ComputeServiceIndicatorsFromChildrenAndMembers{uniqueTag=service-lifecycle-indicators-from-children-and-members,
 running=true, entity=Application[B8OCmlHf], id=LrUDBFxe} listening to 
TestEntityImpl{id=F7J356SN}
2015-12-14 15:59:22,722 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-PRrsOqGI-1]: RebindManager managing entities
2015-12-14 15:59:22,722 INFO  o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-PRrsOqGI-1]: Rebind complete (HOT_STANDBY, iteration 2) 
in 25ms: 1 app, 1 entity, 0 locations, 0 policies, 3 enrichers, 0 feeds, 0 
catalog items
2015-12-14 15:59:22,722 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-PRrsOqGI-1]: RebindManager complete; apps: [B8OCmlHf]
2015-12-14 15:59:22,723 DEBUG o.a.b.core.sensor.AttributeMap 
[brooklyn-execmanager-dIEUU7iP-4]: removing attribute service.state on 
TestEntityImpl{id=F7J356SN}
2015-12-14 15:59:22,723 DEBUG o.a.b.c.entity.AbstractEntity 
[brooklyn-execmanager-dIEUU7iP-4]: Emitting sensor notification 
entity.sensor.removed value Sensor: service.state 
(org.apache.brooklyn.core.entity.lifecycle.Lifecycle) on 
TestEntityImpl{id=F7J356SN}
2015-12-14 15:59:22,723 DEBUG o.a.b.c.m.i.LocalSubscriptionManager [main]: 
Creating subscription posRnYcS for TestEntityImpl{id=F7J356SN} on 
TestEntityImpl{id=F7J356SN} Sensor: service.notUp.indicators (java.util.Map) in 
SubscriptionContext(4NiHo)
2015-12-14 15:59:22,723 DEBUG o.a.b.c.m.i.LocalSubscriptionManager [main]: 
Creating subscription DwG5Cius for TestEntityImpl{id=F7J356SN} on 
TestEntityImpl{id=F7J356SN} Sensor: service.problems (java.util.Map) in 
SubscriptionContext(4NiHo)
2015-12-14 15:59:22,723 DEBUG o.a.b.c.m.i.LocalSubscriptionManager [main]: 
Creating subscription CvMqWqD0 for TestEntityImpl{id=F7J356SN} on 
TestEntityImpl{id=F7J356SN} Sensor: service.isUp (java.lang.Boolean) in 
SubscriptionContext(4NiHo)
2015-12-14 15:59:22,723 DEBUG o.a.b.c.m.i.LocalSubscriptionManager [main]: 
Creating subscription TAxhPC2f for TestEntityImpl{id=F7J356SN} on 
TestEntityImpl{id=F7J356SN} Sensor: service.state.expected 
(org.apache.brooklyn.core.entity.lifecycle.Lifecycle$Transition) in 
SubscriptionContext(4NiHo)
2015-12-14 15:59:22,723 DEBUG o.a.b.c.m.i.LocalEntityManager [main]: 
org.apache.brooklyn.core.mgmt.internal.LocalEntityManager@4eccb9f0 starting 
management of entity TestEntityImpl{id=F7J356SN}
2015-12-14 15:59:22,734 DEBUG o.a.b.core.sensor.AttributeMap 
[brooklyn-execmanager-dIEUU7iP-3]: removing attribute service.state on 
Application[NDFvjYIT]
2015-12-14 15:59:22,735 DEBUG o.a.b.e.s.AbstractMultipleSensorAggregator 
[brooklyn-execmanager-dIEUU7iP-3]: 
ComputeServiceIndicatorsFromChildrenAndMembers{uniqueTag=service-lifecycle-indicators-from-children-and-members,
 running=true, entity=Application[NDFvjYIT], id=ZPiRNGDD} subscribing to 
children of Application[NDFvjYIT]
2015-12-14 15:59:22,735 DEBUG o.a.b.c.e.l.ServiceStateLogic 
[brooklyn-execmanager-dIEUU7iP-3]: Ignoring 
ComputeServiceIndicatorsFromChildrenAndMembers{uniqueTag=service-lifecycle-indicators-from-children-and-members,
 running=true, entity=Application[NDFvjYIT], id=ZPiRNGDD} onUpdated when entity 
is not in valid state (Application[NDFvjYIT])
2015-12-14 15:59:22,735 DEBUG o.a.b.c.entity.AbstractEntity 
[brooklyn-execmanager-dIEUU7iP-3]: Emitting sensor notification 
entity.sensor.added value Sensor: service.notUp.indicators (java.util.Map) on 
Application[NDFvjYIT]
2015-12-14 15:59:22,735 DEBUG o.a.b.c.m.i.LocalSubscriptionManager [main]: 
Creating subscription ir2ivJsU for Application[NDFvjYIT] on 
Application[NDFvjYIT] Sensor: service.notUp.indicators (java.util.Map) in 
SubscriptionContext(4NiHo)
2015-12-14 15:59:22,735 DEBUG o.a.b.c.m.i.LocalSubscriptionManager [main]: 
Creating subscription py1Bgebn for Application[NDFvjYIT] on 
Application[NDFvjYIT] Sensor: service.problems (java.util.Map) in 
SubscriptionContext(4NiHo)
2015-12-14 15:59:22,735 DEBUG o.a.b.c.m.i.LocalSubscriptionManager [main]: 
Creating subscription idIQUNgz for Application[NDFvjYIT] on 
Application[NDFvjYIT] Sensor: service.isUp (java.lang.Boolean) in 
SubscriptionContext(4NiHo)
2015-12-14 15:59:22,735 DEBUG o.a.b.c.m.i.LocalSubscriptionManager [main]: 
Creating subscription A1jTr3iI for Application[NDFvjYIT] on 
Application[NDFvjYIT] Sensor: service.state.expected 
(org.apache.brooklyn.core.entity.lifecycle.Lifecycle$Transition) in 
SubscriptionContext(4NiHo)
2015-12-14 15:59:22,735 DEBUG o.a.b.c.m.i.LocalSubscriptionManager [main]: 
Creating subscription g3DwetFD for Application[NDFvjYIT] on null Sensor: 
service.isUp (java.lang.Boolean) in SubscriptionContext(4NiHo)
2015-12-14 15:59:22,735 DEBUG o.a.b.c.m.i.LocalSubscriptionManager [main]: 
Creating subscription IkFCEzpu for Application[NDFvjYIT] on null Sensor: 
service.state (org.apache.brooklyn.core.entity.lifecycle.Lifecycle) in 
SubscriptionContext(4NiHo)
2015-12-14 15:59:22,735 DEBUG o.a.b.c.m.i.LocalSubscriptionManager [main]: 
Creating subscription ZxGDZI0w for Application[NDFvjYIT] on 
Application[NDFvjYIT] Sensor: entity.children.removed 
(org.apache.brooklyn.api.entity.Entity) in SubscriptionContext(4NiHo)
2015-12-14 15:59:22,735 DEBUG o.a.b.c.m.i.LocalSubscriptionManager [main]: 
Creating subscription pAfJHa8J for Application[NDFvjYIT] on 
Application[NDFvjYIT] Sensor: entity.children.added 
(org.apache.brooklyn.api.entity.Entity) in SubscriptionContext(4NiHo)
2015-12-14 15:59:22,735 DEBUG o.a.b.c.m.i.LocalEntityManager [main]: 
org.apache.brooklyn.core.mgmt.internal.LocalEntityManager@4eccb9f0 starting 
management of entity Application[NDFvjYIT]
2015-12-14 15:59:22,736 DEBUG o.a.b.core.sensor.AttributeMap 
[brooklyn-execmanager-dIEUU7iP-1]: removing attribute service.state on 
Application[NDFvjYIT]
2015-12-14 15:59:22,737 DEBUG o.a.b.c.m.r.PeriodicDeltaChangeListener [main]: 
Checkpointing delta of memento: updating entities=[Application[B8OCmlHf], 
TestEntityImpl{id=F7J356SN}, Application[NDFvjYIT]], locations=[], policies=[], 
enrichers=[Transformer{uniqueTag=service.isUp if no service.notUp.indicators, 
running=true, entity=TestEntityImpl{id=F7J356SN}, id=hSJprWOu}, 
Transformer{uniqueTag=service.isUp if no service.notUp.indicators, 
running=true, entity=Application[NDFvjYIT], id=Y9ACR1MI}, 
ComputeServiceIndicatorsFromChildrenAndMembers{uniqueTag=service-lifecycle-indicators-from-children-and-members,
 running=true, entity=Application[NDFvjYIT], id=ZPiRNGDD}], catalog items=[]; 
removing entities=[], locations=[], policies=[], enrichers=[], catalog items=[]
2015-12-14 15:59:22,745 DEBUG o.a.b.c.m.p.BrooklynMementoPersisterToObjectStore 
[main]: Checkpointed delta of memento in 4ms: updated 3 entities, 0 locations, 
0 policies, 8 enrichers, 0 catalog items; removed 0 entities, 0 locations, 0 
policies, 0 enrichers, 0 catalog items
2015-12-14 15:59:22,745 DEBUG 
o.a.b.c.m.h.ManagementPlaneSyncRecordPersisterToObjectStore [main]: 
Checkpointed delta of manager-memento in 0ms: 
org.apache.brooklyn.core.mgmt.ha.ManagementPlaneSyncRecordDeltaImpl[nodes: 
[BasicManagementNodeSyncRecord{nodeId=PRrsOqGI, status=HOT_STANDBY}]]
2015-12-14 15:59:22,746 DEBUG o.a.b.c.m.h.HighAvailabilityManagerImpl [main]: 
Healthy-master check result=true; masterId=dIEUU7iP; 
masterMemento=BasicManagementNodeSyncRecord{brooklynVersion=0.9.0-SNAPSHOT, 
nodeId=dIEUU7iP, status=MASTER, priority=0, 
localTimestamp=1450108762646=2015-12-14 15:59:22.646, 
remoteTimestamp=1450108762646=2015-12-14 15:59:22.646}; 
ourMemento=BasicManagementNodeSyncRecord{brooklynVersion=0.9.0-SNAPSHOT, 
nodeId=dIEUU7iP, status=MASTER, priority=0, 
localTimestamp=1450108762646=2015-12-14 15:59:22.646, 
remoteTimestamp=1450108762646=2015-12-14 15:59:22.646}
2015-12-14 15:59:22,746 DEBUG o.a.b.c.m.r.RebindManagerImpl [main]: Stopping 
persistence 
(org.apache.brooklyn.core.mgmt.rebind.RebindManagerImpl@10939bc1[mgmt=dIEUU7iP]),
 mgmt dIEUU7iP
2015-12-14 15:59:22,746 DEBUG o.a.b.c.m.r.PeriodicDeltaChangeListener [main]: 
Checkpointing delta of memento: updating entities=[Application[NDFvjYIT]], 
locations=[], policies=[], enrichers=[], catalog items=[]; removing 
entities=[], locations=[], policies=[], enrichers=[], catalog items=[]
2015-12-14 15:59:22,747 DEBUG o.a.b.c.m.p.BrooklynMementoPersisterToObjectStore 
[main]: Checkpointed delta of memento in 0ms: updated 1 entities, 0 locations, 
0 policies, 3 enrichers, 0 catalog items; removed 0 entities, 0 locations, 0 
policies, 0 enrichers, 0 catalog items
2015-12-14 15:59:22,747 DEBUG o.a.b.util.core.task.BasicTask [main]: call from 
Thread[main,5,main], blocking until 
'Task[scheduled[periodic-persister]:MwaNZXPs]' finishes, ended with error: 
java.util.concurrent.CancellationException
2015-12-14 15:59:22,747 DEBUG o.a.b.util.core.task.BasicTask [main]: call from 
Thread[main,5,main], blocking until 
'Task[scheduled[periodic-persister]:MwaNZXPs]' finishes, ended with error: 
java.util.concurrent.CancellationException
2015-12-14 15:59:22,747 DEBUG o.a.b.c.m.r.RebindManagerImpl [main]: Stopped 
rebind (persistence), mgmt dIEUU7iP
2015-12-14 15:59:22,747 DEBUG o.a.b.c.m.i.LocalUsageManager [main]: Storing 
application lifecycle usage event: application Application[NDFvjYIT] in state 
destroyed
2015-12-14 15:59:22,748 DEBUG o.a.b.c.m.i.LocalEntityManager [main]: 
org.apache.brooklyn.core.mgmt.internal.LocalEntityManager@4eccb9f0 stopped 
management of entity Application[NDFvjYIT]
2015-12-14 15:59:22,748 DEBUG o.a.b.c.m.i.LocalUsageManager [main]: Storing 
application lifecycle usage event: application Application[B8OCmlHf] in state 
destroyed
2015-12-14 15:59:22,748 DEBUG o.a.b.c.m.i.LocalEntityManager [main]: 
org.apache.brooklyn.core.mgmt.internal.LocalEntityManager@4eccb9f0 stopped 
management of entity Application[B8OCmlHf]
2015-12-14 15:59:22,748 DEBUG o.a.b.c.entity.AbstractEntity [main]: Emitting 
sensor notification entity.children.removed value TestEntityImpl{id=F7J356SN} 
on Application[B8OCmlHf]
2015-12-14 15:59:22,748 DEBUG o.a.b.e.s.AbstractAggregator 
[brooklyn-execmanager-dIEUU7iP-3]: 
ComputeServiceIndicatorsFromChildrenAndMembers{uniqueTag=service-lifecycle-indicators-from-children-and-members,
 running=true, entity=Application[B8OCmlHf], id=LrUDBFxe} stopped listening to 
TestEntityImpl{id=F7J356SN}
2015-12-14 15:59:22,748 DEBUG o.a.b.c.m.i.LocalEntityManager [main]: 
org.apache.brooklyn.core.mgmt.internal.LocalEntityManager@4eccb9f0 stopped 
management of entity TestEntityImpl{id=F7J356SN}
2015-12-14 15:59:22,748 DEBUG o.a.b.c.e.l.ServiceStateLogic 
[brooklyn-execmanager-dIEUU7iP-3]: Ignoring 
ComputeServiceIndicatorsFromChildrenAndMembers{uniqueTag=service-lifecycle-indicators-from-children-and-members,
 running=true, entity=Application[B8OCmlHf], id=LrUDBFxe} onUpdated when entity 
is not in valid state (Application[B8OCmlHf])
2015-12-14 15:59:22,748 DEBUG o.a.b.c.c.i.BasicBrooklynCatalog [main]: 
Resetting 
org.apache.brooklyn.core.catalog.internal.BasicBrooklynCatalog@5e17cfd1 catalog 
to CatalogDto{contentsDescription=<reset-by-ha-status-change>}
2015-12-14 15:59:22,748 DEBUG o.a.b.c.c.internal.CatalogDo [main]: Loading 
catalog Loaded:CatalogDto{contentsDescription=<reset-by-ha-status-change>}(not 
yet loaded) into null
2015-12-14 15:59:22,748 DEBUG o.a.b.c.c.internal.CatalogDo [main]: Building 
cache for 
Loaded:CatalogDto{contentsDescription=<reset-by-ha-status-change>}(not yet 
loaded)
2015-12-14 15:59:22,748 DEBUG o.a.b.c.c.i.BasicBrooklynCatalog [main]: Reloaded 
catalog for 
org.apache.brooklyn.core.catalog.internal.BasicBrooklynCatalog@5e17cfd1, now 
switching
2015-12-14 15:59:22,749 DEBUG 
o.a.b.c.m.h.ManagementPlaneSyncRecordPersisterToObjectStore [main]: 
Checkpointed delta of manager-memento in 0ms: 
org.apache.brooklyn.core.mgmt.ha.ManagementPlaneSyncRecordDeltaImpl[CLEAR_MASTER:
 dIEUU7iP->null; nodes: [BasicManagementNodeSyncRecord{nodeId=dIEUU7iP, 
status=STANDBY}]]
2015-12-14 15:59:22,749 DEBUG o.a.b.c.m.r.RebindManagerImpl [main]: Starting 
read-only rebinding 
(org.apache.brooklyn.core.mgmt.rebind.RebindManagerImpl@10939bc1[mgmt=dIEUU7iP]),
 mgmt dIEUU7iP
2015-12-14 15:59:22,749 DEBUG o.a.b.c.m.r.InitialFullRebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: Rebinding (HOT_STANDBY, iteration 1) from 
in-memory (test) persistence store...
2015-12-14 15:59:22,749 DEBUG o.a.b.c.m.p.BrooklynMementoPersisterToObjectStore 
[brooklyn-execmanager-dIEUU7iP-1]: Loaded rebind lists; took 0ms: 3 entities, 0 
locations, 0 policies, 8 enrichers, 0 feeds, 0 catalog items; from in-memory 
(test) persistence store
2015-12-14 15:59:22,755 DEBUG o.a.b.c.m.p.BrooklynMementoPersisterToObjectStore 
[brooklyn-execmanager-dIEUU7iP-1]: Loaded rebind raw data; took 5ms; 3 
entities, 0 locations, 0 policies, 8 enrichers, 0 feeds, 0 catalog items, from 
in-memory (test) persistence store
2015-12-14 15:59:22,763 DEBUG o.a.b.c.m.p.BrooklynMementoPersisterToObjectStore 
[brooklyn-execmanager-dIEUU7iP-1]: Loaded rebind manifests; took 7ms: 3 
entities, 0 locations, 0 policies, 8 enrichers, 0 feeds, 0 catalog items; from 
in-memory (test) persistence store
2015-12-14 15:59:22,763 INFO  o.a.b.c.m.r.InitialFullRebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: Rebinding from in-memory (test) persistence 
store for hot_standby dIEUU7iP...
2015-12-14 15:59:22,763 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: RebindManager instantiating catalog items: []
2015-12-14 15:59:22,763 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: RebindManager reconstructing catalog items
2015-12-14 15:59:22,763 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: RebindManager clearing local catalog and 
loading from persisted state
2015-12-14 15:59:22,763 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: RebindManager instantiating locations: []
2015-12-14 15:59:22,763 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: RebindManager instantiating entities: 
[F7J356SN, NDFvjYIT, B8OCmlHf]
2015-12-14 15:59:22,795 DEBUG o.a.b.c.m.p.BrooklynMementoPersisterToObjectStore 
[brooklyn-execmanager-dIEUU7iP-1]: Loaded rebind mementos; took 1ms: 3 
entities, 0 locations, 0 policies, 8 enrichers, 0 feeds, 0 catalog items, from 
in-memory (test) persistence store
2015-12-14 15:59:22,795 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: RebindManager instantiating policies: []
2015-12-14 15:59:22,795 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: RebindManager instantiating enrichers: 
[FHkqwUAS, BjpvtEQb, ZPiRNGDD, LrUDBFxe, C8SMM18F, Y9ACR1MI, wLvfLUO6, hSJprWOu]
2015-12-14 15:59:22,795 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: RebindManager instantiating enricher 
BasicEnricherMemento{type=org.apache.brooklyn.core.entity.lifecycle.ServiceStateLogic$ComputeServiceState,
 id=FHkqwUAS}
2015-12-14 15:59:22,795 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: RebindManager instantiating enricher 
BasicEnricherMemento{type=org.apache.brooklyn.core.entity.lifecycle.ServiceStateLogic$ComputeServiceState,
 id=BjpvtEQb}
2015-12-14 15:59:22,795 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: RebindManager instantiating enricher 
BasicEnricherMemento{type=org.apache.brooklyn.core.entity.lifecycle.ServiceStateLogic$ComputeServiceIndicatorsFromChildrenAndMembers,
 id=ZPiRNGDD}
2015-12-14 15:59:22,796 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: RebindManager instantiating enricher 
BasicEnricherMemento{type=org.apache.brooklyn.core.entity.lifecycle.ServiceStateLogic$ComputeServiceIndicatorsFromChildrenAndMembers,
 id=LrUDBFxe}
2015-12-14 15:59:22,796 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: RebindManager instantiating enricher 
BasicEnricherMemento{type=org.apache.brooklyn.enricher.stock.Transformer, 
id=C8SMM18F}
2015-12-14 15:59:22,796 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: RebindManager instantiating enricher 
BasicEnricherMemento{type=org.apache.brooklyn.enricher.stock.Transformer, 
id=Y9ACR1MI}
2015-12-14 15:59:22,796 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: RebindManager instantiating enricher 
BasicEnricherMemento{type=org.apache.brooklyn.core.entity.lifecycle.ServiceStateLogic$ComputeServiceState,
 id=wLvfLUO6}
2015-12-14 15:59:22,796 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: RebindManager instantiating enricher 
BasicEnricherMemento{type=org.apache.brooklyn.enricher.stock.Transformer, 
id=hSJprWOu}
2015-12-14 15:59:22,796 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: RebindManager instantiating feeds: []
2015-12-14 15:59:22,797 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: RebindManager reconstructing locations
2015-12-14 15:59:22,797 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: RebindManager reconstructing policies
2015-12-14 15:59:22,797 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: RebindManager reconstructing enrichers
2015-12-14 15:59:22,797 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: RebindManager reconstructing enricher 
BasicEnricherMemento{type=org.apache.brooklyn.core.entity.lifecycle.ServiceStateLogic$ComputeServiceState,
 id=FHkqwUAS}
2015-12-14 15:59:22,797 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: RebindManager reconstructing enricher 
BasicEnricherMemento{type=org.apache.brooklyn.core.entity.lifecycle.ServiceStateLogic$ComputeServiceState,
 id=BjpvtEQb}
2015-12-14 15:59:22,797 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: RebindManager reconstructing enricher 
BasicEnricherMemento{type=org.apache.brooklyn.core.entity.lifecycle.ServiceStateLogic$ComputeServiceIndicatorsFromChildrenAndMembers,
 id=ZPiRNGDD}
2015-12-14 15:59:22,797 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: RebindManager reconstructing enricher 
BasicEnricherMemento{type=org.apache.brooklyn.core.entity.lifecycle.ServiceStateLogic$ComputeServiceIndicatorsFromChildrenAndMembers,
 id=LrUDBFxe}
2015-12-14 15:59:22,797 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: RebindManager reconstructing enricher 
BasicEnricherMemento{type=org.apache.brooklyn.enricher.stock.Transformer, 
id=C8SMM18F}
2015-12-14 15:59:22,797 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: RebindManager reconstructing enricher 
BasicEnricherMemento{type=org.apache.brooklyn.enricher.stock.Transformer, 
id=Y9ACR1MI}
2015-12-14 15:59:22,797 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: RebindManager reconstructing enricher 
BasicEnricherMemento{type=org.apache.brooklyn.core.entity.lifecycle.ServiceStateLogic$ComputeServiceState,
 id=wLvfLUO6}
2015-12-14 15:59:22,797 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: RebindManager reconstructing enricher 
BasicEnricherMemento{type=org.apache.brooklyn.enricher.stock.Transformer, 
id=hSJprWOu}
2015-12-14 15:59:22,797 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: RebindManager reconstructing feeds
2015-12-14 15:59:22,797 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: RebindManager reconstructing entities
2015-12-14 15:59:22,798 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: RebindManager reconstructing entity 
BasicEntityMemento{type=org.apache.brooklyn.core.test.entity.TestApplicationImpl,
 id=B8OCmlHf}
2015-12-14 15:59:22,798 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: RebindManager reconstructing entity 
BasicEntityMemento{type=org.apache.brooklyn.core.test.entity.TestEntityImpl, 
id=F7J356SN}
2015-12-14 15:59:22,798 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: RebindManager reconstructing entity 
BasicEntityMemento{type=org.apache.brooklyn.core.test.entity.TestApplicationImpl,
 id=NDFvjYIT}
2015-12-14 15:59:22,798 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: RebindManager associating adjuncts to 
entities
2015-12-14 15:59:22,798 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: RebindManager associating adjuncts to entity 
BasicEntityMemento{type=org.apache.brooklyn.core.test.entity.TestApplicationImpl,
 id=B8OCmlHf}
2015-12-14 15:59:22,798 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: RebindManager associating adjuncts to entity 
BasicEntityMemento{type=org.apache.brooklyn.core.test.entity.TestEntityImpl, 
id=F7J356SN}
2015-12-14 15:59:22,799 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: RebindManager associating adjuncts to entity 
BasicEntityMemento{type=org.apache.brooklyn.core.test.entity.TestApplicationImpl,
 id=NDFvjYIT}
2015-12-14 15:59:22,799 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: RebindManager managing locations
2015-12-14 15:59:22,799 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: RebindManager managing entities
2015-12-14 15:59:22,799 INFO  o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: Rebind complete (HOT_STANDBY, iteration 1) 
in 49ms: 2 apps, 3 entities, 0 locations, 0 policies, 8 enrichers, 0 feeds, 0 
catalog items
2015-12-14 15:59:22,799 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: RebindManager complete; apps: [NDFvjYIT, 
B8OCmlHf]
2015-12-14 15:59:22,800 DEBUG o.a.b.c.m.r.InitialFullRebindIteration 
[brooklyn-execmanager-dIEUU7iP-3]: Rebinding (HOT_STANDBY, iteration 2) from 
in-memory (test) persistence store...
2015-12-14 15:59:22,800 DEBUG o.a.b.c.m.p.BrooklynMementoPersisterToObjectStore 
[brooklyn-execmanager-dIEUU7iP-3]: Loaded rebind lists; took 0ms: 3 entities, 0 
locations, 0 policies, 8 enrichers, 0 feeds, 0 catalog items; from in-memory 
(test) persistence store
2015-12-14 15:59:22,800 DEBUG 
o.a.b.c.m.h.ManagementPlaneSyncRecordPersisterToObjectStore [main]: 
Checkpointed delta of manager-memento in 0ms: 
org.apache.brooklyn.core.mgmt.ha.ManagementPlaneSyncRecordDeltaImpl[nodes: 
[BasicManagementNodeSyncRecord{nodeId=dIEUU7iP, status=HOT_STANDBY}]]
2015-12-14 15:59:22,803 DEBUG o.a.b.c.m.h.HighAvailabilityManagerImpl [main]: 
Detected master heartbeat timeout. Initiating a new master election. Master was 
null
2015-12-14 15:59:22,803 DEBUG o.a.b.c.m.h.BasicMasterChooser [main]: Choosing 
new master from {dIEUU7iP=BasicManagementNodeSyncRecord{nodeId=dIEUU7iP, 
status=HOT_STANDBY}, PRrsOqGI=BasicManagementNodeSyncRecord{nodeId=PRrsOqGI, 
status=HOT_STANDBY}}
2015-12-14 15:59:22,803 DEBUG o.a.b.c.m.p.BrooklynMementoPersisterToObjectStore 
[brooklyn-execmanager-dIEUU7iP-3]: Loaded rebind raw data; took 3ms; 3 
entities, 0 locations, 0 policies, 8 enrichers, 0 feeds, 0 catalog items, from 
in-memory (test) persistence store
2015-12-14 15:59:22,803 DEBUG o.a.b.c.m.h.HighAvailabilityManagerImpl [main]: 
Management node master-change required: 
newMaster=BasicManagementNodeSyncRecord{brooklynVersion=0.9.0-SNAPSHOT, 
nodeId=PRrsOqGI, status=HOT_STANDBY, priority=1, 
localTimestamp=1450108762745=2015-12-14 15:59:22.745, 
remoteTimestamp=1450108762745=2015-12-14 15:59:22.745}; oldMaster= (no 
memento); plane=ManagementPlaneSyncRecordImpl{masterNodeId=, nodes=[dIEUU7iP, 
PRrsOqGI]}, self=BasicManagementNodeSyncRecord{brooklynVersion=0.9.0-SNAPSHOT, 
nodeId=dIEUU7iP, status=HOT_STANDBY, priority=0, 
localTimestamp=1450108762803=2015-12-14 15:59:22.803, 
remoteTimestamp=1450108762803=2015-12-14 15:59:22.803}; heartbeatTimeout=30s
2015-12-14 15:59:22,811 INFO  o.a.b.c.m.h.HighAvailabilityManagerImpl [main]: 
Management node dIEUU7iP running as HA HOT_STANDBY (explicitly requested); no 
master currently (subsequent election may repair)
2015-12-14 15:59:22,812 DEBUG o.a.b.c.m.p.BrooklynMementoPersisterToObjectStore 
[brooklyn-execmanager-dIEUU7iP-3]: Loaded rebind manifests; took 9ms: 3 
entities, 0 locations, 0 policies, 8 enrichers, 0 feeds, 0 catalog items; from 
in-memory (test) persistence store
2015-12-14 15:59:22,813 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-3]: RebindManager instantiating catalog items: []
2015-12-14 15:59:22,813 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-3]: RebindManager reconstructing catalog items
2015-12-14 15:59:22,813 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-3]: RebindManager clearing local catalog and 
loading from persisted state
2015-12-14 15:59:22,813 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-3]: RebindManager instantiating locations: []
2015-12-14 15:59:22,813 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-3]: RebindManager instantiating entities: 
[F7J356SN, NDFvjYIT, B8OCmlHf]
2015-12-14 15:59:22,812 DEBUG o.a.b.c.m.r.RebindManagerImpl [main]: Stopping 
read-only rebinding 
(org.apache.brooklyn.core.mgmt.rebind.RebindManagerImpl@10939bc1[mgmt=dIEUU7iP]),
 mgmt dIEUU7iP
2015-12-14 15:59:22,814 DEBUG o.a.b.util.core.task.BasicTask [main]: call from 
Thread[main,5,main], blocking until 'Task[rebind (periodic run:EUoFdX3f]' 
finishes, ended with error: java.util.concurrent.CancellationException
2015-12-14 15:59:22,817 DEBUG o.a.b.c.m.r.RebindManagerImpl 
[brooklyn-execmanager-dIEUU7iP-3]: Interrupted rebinding (re-interrupting): 
org.apache.brooklyn.util.exceptions.RuntimeInterruptedException: 
java.lang.InterruptedException
2015-12-14 15:59:22,827 DEBUG o.a.b.util.core.task.BasicTask [main]: call from 
Thread[main,5,main], blocking until 'Task[Periodic read-only rebind:zQZ6ZRwM]' 
finishes, ended with error: java.util.concurrent.CancellationException
2015-12-14 15:59:22,827 DEBUG o.a.b.util.core.task.BasicTask [main]: call from 
Thread[main,5,main], blocking until 'Task[rebind (periodic run:EUoFdX3f]' 
finishes, ended with error: java.util.concurrent.CancellationException
2015-12-14 15:59:22,827 DEBUG o.a.b.c.m.r.RebindManagerImpl [main]: Stopped 
read-only rebinding 
(org.apache.brooklyn.core.mgmt.rebind.RebindManagerImpl@10939bc1[mgmt=dIEUU7iP]),
 mgmt dIEUU7iP
2015-12-14 15:59:22,827 DEBUG o.a.b.c.m.r.RebindManagerImpl [main]: Starting 
read-only rebinding 
(org.apache.brooklyn.core.mgmt.rebind.RebindManagerImpl@10939bc1[mgmt=dIEUU7iP]),
 mgmt dIEUU7iP
2015-12-14 15:59:22,827 DEBUG o.a.b.c.m.r.InitialFullRebindIteration 
[brooklyn-execmanager-dIEUU7iP-3]: Rebinding (HOT_STANDBY, iteration 1) from 
in-memory (test) persistence store...
2015-12-14 15:59:22,827 DEBUG o.a.b.c.m.p.BrooklynMementoPersisterToObjectStore 
[brooklyn-execmanager-dIEUU7iP-3]: Loaded rebind lists; took 0ms: 3 entities, 0 
locations, 0 policies, 8 enrichers, 0 feeds, 0 catalog items; from in-memory 
(test) persistence store
2015-12-14 15:59:22,830 DEBUG o.a.b.c.m.p.BrooklynMementoPersisterToObjectStore 
[brooklyn-execmanager-dIEUU7iP-3]: Loaded rebind raw data; took 2ms; 3 
entities, 0 locations, 0 policies, 8 enrichers, 0 feeds, 0 catalog items, from 
in-memory (test) persistence store
2015-12-14 15:59:22,836 DEBUG o.a.b.c.m.p.BrooklynMementoPersisterToObjectStore 
[brooklyn-execmanager-dIEUU7iP-3]: Loaded rebind manifests; took 6ms: 3 
entities, 0 locations, 0 policies, 8 enrichers, 0 feeds, 0 catalog items; from 
in-memory (test) persistence store
2015-12-14 15:59:22,836 INFO  o.a.b.c.m.r.InitialFullRebindIteration 
[brooklyn-execmanager-dIEUU7iP-3]: Rebinding from in-memory (test) persistence 
store for hot_standby dIEUU7iP...
2015-12-14 15:59:22,836 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-3]: RebindManager instantiating catalog items: []
2015-12-14 15:59:22,836 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-3]: RebindManager reconstructing catalog items
2015-12-14 15:59:22,836 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-3]: RebindManager clearing local catalog and 
loading from persisted state
2015-12-14 15:59:22,836 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-3]: RebindManager instantiating locations: []
2015-12-14 15:59:22,836 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-3]: RebindManager instantiating entities: 
[F7J356SN, NDFvjYIT, B8OCmlHf]
2015-12-14 15:59:22,838 WARN  o.a.b.c.m.i.LocalEntityManager 
[brooklyn-execmanager-dIEUU7iP-3]: 
org.apache.brooklyn.core.mgmt.internal.LocalEntityManager@4eccb9f0 redundant 
call to pre-pre-manage entity TestEntityImpl{id=F7J356SN}; skipping
java.lang.Exception: source of duplicate pre-pre-manage of 
TestEntityImpl{id=F7J356SN}
        at 
org.apache.brooklyn.core.mgmt.internal.LocalEntityManager.prePreManage(LocalEntityManager.java:241)
 [classes/:na]
        at 
org.apache.brooklyn.core.mgmt.internal.LocalManagementContext.prePreManage(LocalManagementContext.java:213)
 [classes/:na]
        at 
org.apache.brooklyn.core.objs.proxy.InternalEntityFactory.constructEntityImpl(InternalEntityFactory.java:414)
 [classes/:na]
        at 
org.apache.brooklyn.core.objs.proxy.InternalEntityFactory.constructEntity(InternalEntityFactory.java:386)
 [classes/:na]
        at 
org.apache.brooklyn.core.mgmt.rebind.RebindIteration$BrooklynObjectInstantiator.newEntity(RebindIteration.java:883)
 [classes/:na]
        at 
org.apache.brooklyn.core.mgmt.rebind.RebindIteration.instantiateLocationsAndEntities(RebindIteration.java:453)
 [classes/:na]
        at 
org.apache.brooklyn.core.mgmt.rebind.RebindIteration.doRun(RebindIteration.java:239)
 [classes/:na]
        at 
org.apache.brooklyn.core.mgmt.rebind.InitialFullRebindIteration.doRun(InitialFullRebindIteration.java:69)
 [classes/:na]
        at 
org.apache.brooklyn.core.mgmt.rebind.RebindIteration.run(RebindIteration.java:265)
 [classes/:na]
        at 
org.apache.brooklyn.core.mgmt.rebind.RebindManagerImpl.rebindImpl(RebindManagerImpl.java:552)
 [classes/:na]
        at 
org.apache.brooklyn.core.mgmt.rebind.RebindManagerImpl$3.call(RebindManagerImpl.java:502)
 [classes/:na]
        at 
org.apache.brooklyn.core.mgmt.rebind.RebindManagerImpl$3.call(RebindManagerImpl.java:500)
 [classes/:na]
        at 
org.apache.brooklyn.util.core.task.BasicExecutionManager$SubmissionCallable.call(BasicExecutionManager.java:492)
 [classes/:na]
        at java.util.concurrent.FutureTask.run(FutureTask.java:266) 
[na:1.8.0_05]
        at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) 
[na:1.8.0_05]
        at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) 
[na:1.8.0_05]
        at java.lang.Thread.run(Thread.java:745) [na:1.8.0_05]
2015-12-14 15:59:22,840 WARN  o.a.b.c.m.i.LocalEntityManager 
[brooklyn-execmanager-dIEUU7iP-3]: 
org.apache.brooklyn.core.mgmt.internal.LocalEntityManager@4eccb9f0 redundant 
call to pre-pre-manage entity Application[NDFvjYIT]; skipping
java.lang.Exception: source of duplicate pre-pre-manage of Application[NDFvjYIT]
        at 
org.apache.brooklyn.core.mgmt.internal.LocalEntityManager.prePreManage(LocalEntityManager.java:241)
 [classes/:na]
        at 
org.apache.brooklyn.core.mgmt.internal.LocalManagementContext.prePreManage(LocalManagementContext.java:213)
 [classes/:na]
        at 
org.apache.brooklyn.core.objs.proxy.InternalEntityFactory.constructEntityImpl(InternalEntityFactory.java:414)
 [classes/:na]
        at 
org.apache.brooklyn.core.objs.proxy.InternalEntityFactory.constructEntity(InternalEntityFactory.java:386)
 [classes/:na]
        at 
org.apache.brooklyn.core.mgmt.rebind.RebindIteration$BrooklynObjectInstantiator.newEntity(RebindIteration.java:883)
 [classes/:na]
        at 
org.apache.brooklyn.core.mgmt.rebind.RebindIteration.instantiateLocationsAndEntities(RebindIteration.java:453)
 [classes/:na]
        at 
org.apache.brooklyn.core.mgmt.rebind.RebindIteration.doRun(RebindIteration.java:239)
 [classes/:na]
        at 
org.apache.brooklyn.core.mgmt.rebind.InitialFullRebindIteration.doRun(InitialFullRebindIteration.java:69)
 [classes/:na]
        at 
org.apache.brooklyn.core.mgmt.rebind.RebindIteration.run(RebindIteration.java:265)
 [classes/:na]
        at 
org.apache.brooklyn.core.mgmt.rebind.RebindManagerImpl.rebindImpl(RebindManagerImpl.java:552)
 [classes/:na]
        at 
org.apache.brooklyn.core.mgmt.rebind.RebindManagerImpl$3.call(RebindManagerImpl.java:502)
 [classes/:na]
        at 
org.apache.brooklyn.core.mgmt.rebind.RebindManagerImpl$3.call(RebindManagerImpl.java:500)
 [classes/:na]
        at 
org.apache.brooklyn.util.core.task.BasicExecutionManager$SubmissionCallable.call(BasicExecutionManager.java:492)
 [classes/:na]
        at java.util.concurrent.FutureTask.run(FutureTask.java:266) 
[na:1.8.0_05]
        at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) 
[na:1.8.0_05]
        at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) 
[na:1.8.0_05]
        at java.lang.Thread.run(Thread.java:745) [na:1.8.0_05]
2015-12-14 15:59:22,841 WARN  o.a.b.c.m.i.LocalEntityManager 
[brooklyn-execmanager-dIEUU7iP-3]: 
org.apache.brooklyn.core.mgmt.internal.LocalEntityManager@4eccb9f0 redundant 
call to pre-pre-manage entity Application[B8OCmlHf]; skipping
java.lang.Exception: source of duplicate pre-pre-manage of Application[B8OCmlHf]
        at 
org.apache.brooklyn.core.mgmt.internal.LocalEntityManager.prePreManage(LocalEntityManager.java:241)
 [classes/:na]
        at 
org.apache.brooklyn.core.mgmt.internal.LocalManagementContext.prePreManage(LocalManagementContext.java:213)
 [classes/:na]
        at 
org.apache.brooklyn.core.objs.proxy.InternalEntityFactory.constructEntityImpl(InternalEntityFactory.java:414)
 [classes/:na]
        at 
org.apache.brooklyn.core.objs.proxy.InternalEntityFactory.constructEntity(InternalEntityFactory.java:386)
 [classes/:na]
        at 
org.apache.brooklyn.core.mgmt.rebind.RebindIteration$BrooklynObjectInstantiator.newEntity(RebindIteration.java:883)
 [classes/:na]
        at 
org.apache.brooklyn.core.mgmt.rebind.RebindIteration.instantiateLocationsAndEntities(RebindIteration.java:453)
 [classes/:na]
        at 
org.apache.brooklyn.core.mgmt.rebind.RebindIteration.doRun(RebindIteration.java:239)
 [classes/:na]
        at 
org.apache.brooklyn.core.mgmt.rebind.InitialFullRebindIteration.doRun(InitialFullRebindIteration.java:69)
 [classes/:na]
        at 
org.apache.brooklyn.core.mgmt.rebind.RebindIteration.run(RebindIteration.java:265)
 [classes/:na]
        at 
org.apache.brooklyn.core.mgmt.rebind.RebindManagerImpl.rebindImpl(RebindManagerImpl.java:552)
 [classes/:na]
        at 
org.apache.brooklyn.core.mgmt.rebind.RebindManagerImpl$3.call(RebindManagerImpl.java:502)
 [classes/:na]
        at 
org.apache.brooklyn.core.mgmt.rebind.RebindManagerImpl$3.call(RebindManagerImpl.java:500)
 [classes/:na]
        at 
org.apache.brooklyn.util.core.task.BasicExecutionManager$SubmissionCallable.call(BasicExecutionManager.java:492)
 [classes/:na]
        at java.util.concurrent.FutureTask.run(FutureTask.java:266) 
[na:1.8.0_05]
        at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) 
[na:1.8.0_05]
        at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) 
[na:1.8.0_05]
        at java.lang.Thread.run(Thread.java:745) [na:1.8.0_05]
2015-12-14 15:59:22,842 DEBUG o.a.b.c.m.p.BrooklynMementoPersisterToObjectStore 
[brooklyn-execmanager-dIEUU7iP-3]: Loaded rebind mementos; took 1ms: 3 
entities, 0 locations, 0 policies, 8 enrichers, 0 feeds, 0 catalog items, from 
in-memory (test) persistence store
2015-12-14 15:59:22,842 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-3]: RebindManager instantiating policies: []
2015-12-14 15:59:22,842 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-3]: RebindManager instantiating enrichers: 
[FHkqwUAS, BjpvtEQb, ZPiRNGDD, LrUDBFxe, C8SMM18F, Y9ACR1MI, wLvfLUO6, hSJprWOu]
2015-12-14 15:59:22,842 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-3]: RebindManager instantiating enricher 
BasicEnricherMemento{type=org.apache.brooklyn.core.entity.lifecycle.ServiceStateLogic$ComputeServiceState,
 id=FHkqwUAS}
2015-12-14 15:59:22,843 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-3]: RebindManager instantiating enricher 
BasicEnricherMemento{type=org.apache.brooklyn.core.entity.lifecycle.ServiceStateLogic$ComputeServiceState,
 id=BjpvtEQb}
2015-12-14 15:59:22,843 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-3]: RebindManager instantiating enricher 
BasicEnricherMemento{type=org.apache.brooklyn.core.entity.lifecycle.ServiceStateLogic$ComputeServiceIndicatorsFromChildrenAndMembers,
 id=ZPiRNGDD}
2015-12-14 15:59:22,843 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-3]: RebindManager instantiating enricher 
BasicEnricherMemento{type=org.apache.brooklyn.core.entity.lifecycle.ServiceStateLogic$ComputeServiceIndicatorsFromChildrenAndMembers,
 id=LrUDBFxe}
2015-12-14 15:59:22,844 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-3]: RebindManager instantiating enricher 
BasicEnricherMemento{type=org.apache.brooklyn.enricher.stock.Transformer, 
id=C8SMM18F}
2015-12-14 15:59:22,844 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-3]: RebindManager instantiating enricher 
BasicEnricherMemento{type=org.apache.brooklyn.enricher.stock.Transformer, 
id=Y9ACR1MI}
2015-12-14 15:59:22,844 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-3]: RebindManager instantiating enricher 
BasicEnricherMemento{type=org.apache.brooklyn.core.entity.lifecycle.ServiceStateLogic$ComputeServiceState,
 id=wLvfLUO6}
2015-12-14 15:59:22,844 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-3]: RebindManager instantiating enricher 
BasicEnricherMemento{type=org.apache.brooklyn.enricher.stock.Transformer, 
id=hSJprWOu}
2015-12-14 15:59:22,844 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-3]: RebindManager instantiating feeds: []
2015-12-14 15:59:22,844 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-3]: RebindManager reconstructing locations
2015-12-14 15:59:22,844 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-3]: RebindManager reconstructing policies
2015-12-14 15:59:22,844 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-3]: RebindManager reconstructing enrichers
2015-12-14 15:59:22,844 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-3]: RebindManager reconstructing enricher 
BasicEnricherMemento{type=org.apache.brooklyn.core.entity.lifecycle.ServiceStateLogic$ComputeServiceState,
 id=FHkqwUAS}
2015-12-14 15:59:22,844 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-3]: RebindManager reconstructing enricher 
BasicEnricherMemento{type=org.apache.brooklyn.core.entity.lifecycle.ServiceStateLogic$ComputeServiceState,
 id=BjpvtEQb}
2015-12-14 15:59:22,844 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-3]: RebindManager reconstructing enricher 
BasicEnricherMemento{type=org.apache.brooklyn.core.entity.lifecycle.ServiceStateLogic$ComputeServiceIndicatorsFromChildrenAndMembers,
 id=ZPiRNGDD}
2015-12-14 15:59:22,844 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-3]: RebindManager reconstructing enricher 
BasicEnricherMemento{type=org.apache.brooklyn.core.entity.lifecycle.ServiceStateLogic$ComputeServiceIndicatorsFromChildrenAndMembers,
 id=LrUDBFxe}
2015-12-14 15:59:22,845 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-3]: RebindManager reconstructing enricher 
BasicEnricherMemento{type=org.apache.brooklyn.enricher.stock.Transformer, 
id=C8SMM18F}
2015-12-14 15:59:22,845 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-3]: RebindManager reconstructing enricher 
BasicEnricherMemento{type=org.apache.brooklyn.enricher.stock.Transformer, 
id=Y9ACR1MI}
2015-12-14 15:59:22,845 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-3]: RebindManager reconstructing enricher 
BasicEnricherMemento{type=org.apache.brooklyn.core.entity.lifecycle.ServiceStateLogic$ComputeServiceState,
 id=wLvfLUO6}
2015-12-14 15:59:22,845 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-3]: RebindManager reconstructing enricher 
BasicEnricherMemento{type=org.apache.brooklyn.enricher.stock.Transformer, 
id=hSJprWOu}
2015-12-14 15:59:22,845 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-3]: RebindManager reconstructing feeds
2015-12-14 15:59:22,845 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-3]: RebindManager reconstructing entities
2015-12-14 15:59:22,845 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-3]: RebindManager reconstructing entity 
BasicEntityMemento{type=org.apache.brooklyn.core.test.entity.TestApplicationImpl,
 id=B8OCmlHf}
2015-12-14 15:59:22,845 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-3]: RebindManager reconstructing entity 
BasicEntityMemento{type=org.apache.brooklyn.core.test.entity.TestEntityImpl, 
id=F7J356SN}
2015-12-14 15:59:22,845 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-3]: RebindManager reconstructing entity 
BasicEntityMemento{type=org.apache.brooklyn.core.test.entity.TestApplicationImpl,
 id=NDFvjYIT}
2015-12-14 15:59:22,845 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-3]: RebindManager associating adjuncts to 
entities
2015-12-14 15:59:22,845 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-3]: RebindManager associating adjuncts to entity 
BasicEntityMemento{type=org.apache.brooklyn.core.test.entity.TestApplicationImpl,
 id=B8OCmlHf}
2015-12-14 15:59:22,846 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-3]: RebindManager associating adjuncts to entity 
BasicEntityMemento{type=org.apache.brooklyn.core.test.entity.TestEntityImpl, 
id=F7J356SN}
2015-12-14 15:59:22,846 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-3]: RebindManager associating adjuncts to entity 
BasicEntityMemento{type=org.apache.brooklyn.core.test.entity.TestApplicationImpl,
 id=NDFvjYIT}
2015-12-14 15:59:22,846 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-3]: RebindManager managing locations
2015-12-14 15:59:22,846 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-3]: RebindManager managing entities
2015-12-14 15:59:22,846 INFO  o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-3]: Rebind complete (HOT_STANDBY, iteration 1) 
in 18ms: 2 apps, 3 entities, 0 locations, 0 policies, 8 enrichers, 0 feeds, 0 
catalog items
2015-12-14 15:59:22,846 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-3]: RebindManager complete; apps: [NDFvjYIT, 
B8OCmlHf]
2015-12-14 15:59:22,846 DEBUG o.a.b.c.m.h.HighAvailabilityManagerImpl [main]: 
Registering poll task for 
org.apache.brooklyn.core.mgmt.ha.HighAvailabilityManagerImpl@8add32f[node:dIEUU7iP;running=true],
 period 106751d 23h 47m 16s 854ms 775us 807ns
2015-12-14 15:59:22,846 DEBUG o.a.b.c.m.r.InitialFullRebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: Rebinding (HOT_STANDBY, iteration 2) from 
in-memory (test) persistence store...
2015-12-14 15:59:22,846 DEBUG o.a.b.c.m.p.BrooklynMementoPersisterToObjectStore 
[brooklyn-execmanager-dIEUU7iP-1]: Loaded rebind lists; took 0ms: 3 entities, 0 
locations, 0 policies, 8 enrichers, 0 feeds, 0 catalog items; from in-memory 
(test) persistence store
2015-12-14 15:59:22,849 DEBUG o.a.b.c.m.p.BrooklynMementoPersisterToObjectStore 
[brooklyn-execmanager-dIEUU7iP-1]: Loaded rebind raw data; took 2ms; 3 
entities, 0 locations, 0 policies, 8 enrichers, 0 feeds, 0 catalog items, from 
in-memory (test) persistence store
2015-12-14 15:59:22,857 DEBUG o.a.b.c.m.p.BrooklynMementoPersisterToObjectStore 
[brooklyn-execmanager-dIEUU7iP-1]: Loaded rebind manifests; took 7ms: 3 
entities, 0 locations, 0 policies, 8 enrichers, 0 feeds, 0 catalog items; from 
in-memory (test) persistence store
2015-12-14 15:59:22,857 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: RebindManager instantiating catalog items: []
2015-12-14 15:59:22,857 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: RebindManager reconstructing catalog items
2015-12-14 15:59:22,857 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: RebindManager clearing local catalog and 
loading from persisted state
2015-12-14 15:59:22,857 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: RebindManager instantiating locations: []
2015-12-14 15:59:22,857 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: RebindManager instantiating entities: 
[F7J356SN, NDFvjYIT, B8OCmlHf]
2015-12-14 15:59:22,857 INFO  o.a.b.t.s.LoggingVerboseReporter [main]: TESTNG 
FAILED: "Surefire test" - 
org.apache.brooklyn.core.mgmt.ha.HotStandbyTest.testChangeMode() finished in 
204 ms
java.lang.AssertionError: expected [second-app] but found [defaultval]
        at 
org.apache.brooklyn.core.mgmt.ha.HotStandbyTest.testChangeMode(HotStandbyTest.java:537)
2015-12-14 15:59:22,859 WARN  o.a.b.c.m.i.LocalEntityManager 
[brooklyn-execmanager-dIEUU7iP-1]: 
org.apache.brooklyn.core.mgmt.internal.LocalEntityManager@4eccb9f0 redundant 
call to pre-pre-manage entity TestEntityImpl{id=F7J356SN}; skipping
java.lang.Exception: source of duplicate pre-pre-manage of 
TestEntityImpl{id=F7J356SN}
        at 
org.apache.brooklyn.core.mgmt.internal.LocalEntityManager.prePreManage(LocalEntityManager.java:241)
 [classes/:na]
        at 
org.apache.brooklyn.core.mgmt.internal.LocalManagementContext.prePreManage(LocalManagementContext.java:213)
 [classes/:na]
        at 
org.apache.brooklyn.core.objs.proxy.InternalEntityFactory.constructEntityImpl(InternalEntityFactory.java:414)
 [classes/:na]
        at 
org.apache.brooklyn.core.objs.proxy.InternalEntityFactory.constructEntity(InternalEntityFactory.java:386)
 [classes/:na]
        at 
org.apache.brooklyn.core.mgmt.rebind.RebindIteration$BrooklynObjectInstantiator.newEntity(RebindIteration.java:883)
 [classes/:na]
        at 
org.apache.brooklyn.core.mgmt.rebind.RebindIteration.instantiateLocationsAndEntities(RebindIteration.java:453)
 [classes/:na]
        at 
org.apache.brooklyn.core.mgmt.rebind.RebindIteration.doRun(RebindIteration.java:239)
 [classes/:na]
        at 
org.apache.brooklyn.core.mgmt.rebind.InitialFullRebindIteration.doRun(InitialFullRebindIteration.java:69)
 [classes/:na]
        at 
org.apache.brooklyn.core.mgmt.rebind.RebindIteration.run(RebindIteration.java:265)
 [classes/:na]
        at 
org.apache.brooklyn.core.mgmt.rebind.RebindManagerImpl.rebindImpl(RebindManagerImpl.java:552)
 [classes/:na]
        at 
org.apache.brooklyn.core.mgmt.rebind.RebindManagerImpl.rebind(RebindManagerImpl.java:510)
 [classes/:na]
        at 
org.apache.brooklyn.core.mgmt.rebind.RebindManagerImpl$1$1.call(RebindManagerImpl.java:313)
 [classes/:na]
        at 
org.apache.brooklyn.core.mgmt.rebind.RebindManagerImpl$1$1.call(RebindManagerImpl.java:310)
 [classes/:na]
        at 
org.apache.brooklyn.util.core.task.BasicExecutionManager$ScheduledTaskCallable$1.call(BasicExecutionManager.java:409)
 [classes/:na]
        at 
org.apache.brooklyn.util.core.task.BasicExecutionManager$SubmissionCallable.call(BasicExecutionManager.java:492)
 [classes/:na]
        at java.util.concurrent.FutureTask.run(FutureTask.java:266) 
[na:1.8.0_05]
        at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) 
[na:1.8.0_05]
        at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) 
[na:1.8.0_05]
        at java.lang.Thread.run(Thread.java:745) [na:1.8.0_05]
2015-12-14 15:59:22,865 DEBUG o.a.b.c.m.p.BrooklynMementoPersisterToObjectStore 
[brooklyn-execmanager-dIEUU7iP-1]: Loaded rebind mementos; took 3ms: 3 
entities, 0 locations, 0 policies, 8 enrichers, 0 feeds, 0 catalog items, from 
in-memory (test) persistence store
2015-12-14 15:59:22,865 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: RebindManager instantiating policies: []
2015-12-14 15:59:22,865 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: RebindManager instantiating enrichers: 
[FHkqwUAS, BjpvtEQb, ZPiRNGDD, LrUDBFxe, C8SMM18F, Y9ACR1MI, wLvfLUO6, hSJprWOu]
2015-12-14 15:59:22,865 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: RebindManager instantiating enricher 
BasicEnricherMemento{type=org.apache.brooklyn.core.entity.lifecycle.ServiceStateLogic$ComputeServiceState,
 id=FHkqwUAS}
2015-12-14 15:59:22,865 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: RebindManager instantiating enricher 
BasicEnricherMemento{type=org.apache.brooklyn.core.entity.lifecycle.ServiceStateLogic$ComputeServiceState,
 id=BjpvtEQb}
2015-12-14 15:59:22,865 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: RebindManager instantiating enricher 
BasicEnricherMemento{type=org.apache.brooklyn.core.entity.lifecycle.ServiceStateLogic$ComputeServiceIndicatorsFromChildrenAndMembers,
 id=ZPiRNGDD}
2015-12-14 15:59:22,866 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: RebindManager instantiating enricher 
BasicEnricherMemento{type=org.apache.brooklyn.core.entity.lifecycle.ServiceStateLogic$ComputeServiceIndicatorsFromChildrenAndMembers,
 id=LrUDBFxe}
2015-12-14 15:59:22,866 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: RebindManager instantiating enricher 
BasicEnricherMemento{type=org.apache.brooklyn.enricher.stock.Transformer, 
id=C8SMM18F}
2015-12-14 15:59:22,866 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: RebindManager instantiating enricher 
BasicEnricherMemento{type=org.apache.brooklyn.enricher.stock.Transformer, 
id=Y9ACR1MI}
2015-12-14 15:59:22,866 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: RebindManager instantiating enricher 
BasicEnricherMemento{type=org.apache.brooklyn.core.entity.lifecycle.ServiceStateLogic$ComputeServiceState,
 id=wLvfLUO6}
2015-12-14 15:59:22,866 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: RebindManager instantiating enricher 
BasicEnricherMemento{type=org.apache.brooklyn.enricher.stock.Transformer, 
id=hSJprWOu}
2015-12-14 15:59:22,866 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: RebindManager instantiating feeds: []
2015-12-14 15:59:22,866 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: RebindManager reconstructing locations
2015-12-14 15:59:22,866 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: RebindManager reconstructing policies
2015-12-14 15:59:22,866 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: RebindManager reconstructing enrichers
2015-12-14 15:59:22,866 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: RebindManager reconstructing enricher 
BasicEnricherMemento{type=org.apache.brooklyn.core.entity.lifecycle.ServiceStateLogic$ComputeServiceState,
 id=FHkqwUAS}
2015-12-14 15:59:22,867 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: RebindManager reconstructing enricher 
BasicEnricherMemento{type=org.apache.brooklyn.core.entity.lifecycle.ServiceStateLogic$ComputeServiceState,
 id=BjpvtEQb}
2015-12-14 15:59:22,867 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: RebindManager reconstructing enricher 
BasicEnricherMemento{type=org.apache.brooklyn.core.entity.lifecycle.ServiceStateLogic$ComputeServiceIndicatorsFromChildrenAndMembers,
 id=ZPiRNGDD}
2015-12-14 15:59:22,867 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: RebindManager reconstructing enricher 
BasicEnricherMemento{type=org.apache.brooklyn.core.entity.lifecycle.ServiceStateLogic$ComputeServiceIndicatorsFromChildrenAndMembers,
 id=LrUDBFxe}
2015-12-14 15:59:22,867 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: RebindManager reconstructing enricher 
BasicEnricherMemento{type=org.apache.brooklyn.enricher.stock.Transformer, 
id=C8SMM18F}
2015-12-14 15:59:22,867 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: RebindManager reconstructing enricher 
BasicEnricherMemento{type=org.apache.brooklyn.enricher.stock.Transformer, 
id=Y9ACR1MI}
2015-12-14 15:59:22,867 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: RebindManager reconstructing enricher 
BasicEnricherMemento{type=org.apache.brooklyn.core.entity.lifecycle.ServiceStateLogic$ComputeServiceState,
 id=wLvfLUO6}
2015-12-14 15:59:22,867 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: RebindManager reconstructing enricher 
BasicEnricherMemento{type=org.apache.brooklyn.enricher.stock.Transformer, 
id=hSJprWOu}
2015-12-14 15:59:22,867 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: RebindManager reconstructing feeds
2015-12-14 15:59:22,867 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: RebindManager reconstructing entities
2015-12-14 15:59:22,868 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: RebindManager reconstructing entity 
BasicEntityMemento{type=org.apache.brooklyn.core.test.entity.TestApplicationImpl,
 id=B8OCmlHf}
2015-12-14 15:59:22,868 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: RebindManager reconstructing entity 
BasicEntityMemento{type=org.apache.brooklyn.core.test.entity.TestEntityImpl, 
id=F7J356SN}
2015-12-14 15:59:22,868 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: RebindManager reconstructing entity 
BasicEntityMemento{type=org.apache.brooklyn.core.test.entity.TestApplicationImpl,
 id=NDFvjYIT}
2015-12-14 15:59:22,868 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: RebindManager associating adjuncts to 
entities
2015-12-14 15:59:22,868 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: RebindManager associating adjuncts to entity 
BasicEntityMemento{type=org.apache.brooklyn.core.test.entity.TestApplicationImpl,
 id=B8OCmlHf}
2015-12-14 15:59:22,868 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: RebindManager associating adjuncts to entity 
BasicEntityMemento{type=org.apache.brooklyn.core.test.entity.TestEntityImpl, 
id=F7J356SN}
2015-12-14 15:59:22,868 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: RebindManager associating adjuncts to entity 
BasicEntityMemento{type=org.apache.brooklyn.core.test.entity.TestApplicationImpl,
 id=NDFvjYIT}
2015-12-14 15:59:22,868 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: RebindManager managing locations
2015-12-14 15:59:22,868 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: RebindManager managing entities
2015-12-14 15:59:22,869 INFO  o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: Rebind complete (HOT_STANDBY, iteration 2) 
in 22ms: 2 apps, 3 entities, 0 locations, 0 policies, 8 enrichers, 0 feeds, 0 
catalog items
2015-12-14 15:59:22,869 DEBUG o.a.b.c.m.r.RebindIteration 
[brooklyn-execmanager-dIEUU7iP-1]: RebindManager complete; apps: [NDFvjYIT, 
B8OCmlHf]
2015-12-14 15:59:22,870 INFO  o.a.b.t.s.LoggingVerboseReporter [main]: TESTNG 
INVOKING CONFIGURATION: "Surefire test" - @AfterMethod 
org.apache.brooklyn.core.mgmt.ha.HotStandbyTest.tearDown()
2015-12-14 15:59:22,870 DEBUG o.a.b.c.m.h.HighAvailabilityManagerImpl [main]: 
Stopping 
org.apache.brooklyn.core.mgmt.ha.HighAvailabilityManagerImpl@8add32f[node:dIEUU7iP;running=true]
2015-12-14 15:59:22,870 DEBUG o.a.b.c.m.r.RebindManagerImpl [main]: Stopping 
read-only rebinding 
(org.apache.brooklyn.core.mgmt.rebind.RebindManagerImpl@10939bc1[mgmt=dIEUU7iP]),
 mgmt dIEUU7iP
2015-12-14 15:59:22,870 DEBUG o.a.b.util.core.task.BasicTask [main]: call from 
Thread[main,5,main], blocking until 'Task[Periodic read-only rebind:NbkFPWVK]' 
finishes, ended with error: java.util.concurrent.CancellationException
2015-12-14 15:59:22,870 DEBUG o.a.b.c.m.r.RebindManagerImpl [main]: Stopped 
read-only rebinding 
(org.apache.brooklyn.core.mgmt.rebind.RebindManagerImpl@10939bc1[mgmt=dIEUU7iP]),
 mgmt dIEUU7iP
2015-12-14 15:59:22,870 DEBUG o.a.b.c.m.i.LocalEntityManager [main]: No 
relations being updated on unmanage of read only Application[NDFvjYIT]
2015-12-14 15:59:22,870 DEBUG o.a.b.c.m.i.LocalEntityManager [main]: 
org.apache.brooklyn.core.mgmt.internal.LocalEntityManager@4eccb9f0 stopped 
management of entity Application[NDFvjYIT]
2015-12-14 15:59:22,871 DEBUG o.a.b.c.m.i.LocalEntityManager [main]: No 
relations being updated on unmanage of read only Application[B8OCmlHf]
2015-12-14 15:59:22,871 DEBUG o.a.b.c.m.i.LocalEntityManager [main]: 
org.apache.brooklyn.core.mgmt.internal.LocalEntityManager@4eccb9f0 stopped 
management of entity Application[B8OCmlHf]
2015-12-14 15:59:22,871 DEBUG o.a.b.c.m.i.LocalEntityManager [main]: No 
relations being updated on unmanage of read only TestEntityImpl{id=F7J356SN}
2015-12-14 15:59:22,871 DEBUG o.a.b.c.m.i.LocalEntityManager [main]: 
org.apache.brooklyn.core.mgmt.internal.LocalEntityManager@4eccb9f0 stopped 
management of entity TestEntityImpl{id=F7J356SN}
2015-12-14 15:59:22,871 DEBUG o.a.b.c.c.i.BasicBrooklynCatalog [main]: 
Resetting 
org.apache.brooklyn.core.catalog.internal.BasicBrooklynCatalog@5e17cfd1 catalog 
to CatalogDto{contentsDescription=<reset-by-ha-status-change>}
2015-12-14 15:59:22,871 DEBUG o.a.b.c.c.internal.CatalogDo [main]: Loading 
catalog Loaded:CatalogDto{contentsDescription=<reset-by-ha-status-change>}(not 
yet loaded) into null
2015-12-14 15:59:22,871 DEBUG o.a.b.c.c.internal.CatalogDo [main]: Building 
cache for 
Loaded:CatalogDto{contentsDescription=<reset-by-ha-status-change>}(not yet 
loaded)
2015-12-14 15:59:22,871 DEBUG o.a.b.c.c.i.BasicBrooklynCatalog [main]: Reloaded 
catalog for 
org.apache.brooklyn.core.catalog.internal.BasicBrooklynCatalog@5e17cfd1, now 
switching
2015-12-14 15:59:22,871 DEBUG o.a.b.c.m.r.RebindManagerImpl [main]: Stopping 
persistence 
(org.apache.brooklyn.core.mgmt.rebind.RebindManagerImpl@10939bc1[mgmt=dIEUU7iP]),
 mgmt dIEUU7iP
2015-12-14 15:59:22,871 DEBUG o.a.b.c.m.r.RebindManagerImpl [main]: Stopped 
rebind (persistence), mgmt dIEUU7iP
2015-12-14 15:59:22,872 DEBUG o.a.b.core.entity.Entities [main]: destroying all 
apps in LocalManagementContext[D2gDJQKx-dIEUU7iP]: []
2015-12-14 15:59:22,872 DEBUG o.a.b.c.m.h.HighAvailabilityManagerImpl [main]: 
Stopping 
org.apache.brooklyn.core.mgmt.ha.HighAvailabilityManagerImpl@8add32f[node:dIEUU7iP;running=false]
2015-12-14 15:59:22,872 DEBUG o.a.b.c.m.r.RebindManagerImpl [main]: Stopping 
persistence 
(org.apache.brooklyn.core.mgmt.rebind.RebindManagerImpl@10939bc1[mgmt=dIEUU7iP]),
 mgmt dIEUU7iP
2015-12-14 15:59:22,872 DEBUG o.a.b.c.m.r.RebindManagerImpl [main]: Stopped 
rebind (persistence), mgmt dIEUU7iP
2015-12-14 15:59:22,874 DEBUG o.a.b.c.m.h.HighAvailabilityManagerImpl [main]: 
Stopping 
org.apache.brooklyn.core.mgmt.ha.HighAvailabilityManagerImpl@328c6f65[node:PRrsOqGI;running=true]
2015-12-14 15:59:22,874 DEBUG o.a.b.c.m.r.RebindManagerImpl [main]: Stopping 
read-only rebinding 
(org.apache.brooklyn.core.mgmt.rebind.RebindManagerImpl@7005b6e[mgmt=PRrsOqGI]),
 mgmt PRrsOqGI
2015-12-14 15:59:22,874 DEBUG o.a.b.util.core.task.BasicTask [main]: call from 
Thread[main,5,main], blocking until 'Task[Periodic read-only rebind:pati4wIV]' 
finishes, ended with error: java.util.concurrent.CancellationException
2015-12-14 15:59:22,874 DEBUG o.a.b.c.m.r.RebindManagerImpl [main]: Stopped 
read-only rebinding 
(org.apache.brooklyn.core.mgmt.rebind.RebindManagerImpl@7005b6e[mgmt=PRrsOqGI]),
 mgmt PRrsOqGI
2015-12-14 15:59:22,875 DEBUG o.a.b.c.m.i.LocalEntityManager [main]: No 
relations being updated on unmanage of read only Application[B8OCmlHf]
2015-12-14 15:59:22,875 DEBUG o.a.b.c.m.i.LocalEntityManager [main]: 
org.apache.brooklyn.core.mgmt.internal.LocalEntityManager@65289e33 stopped 
management of entity Application[B8OCmlHf]
2015-12-14 15:59:22,875 DEBUG o.a.b.c.c.i.BasicBrooklynCatalog [main]: 
Resetting 
org.apache.brooklyn.core.catalog.internal.BasicBrooklynCatalog@2c85b002 catalog 
to CatalogDto{contentsDescription=<reset-by-ha-status-change>}
2015-12-14 15:59:22,875 DEBUG o.a.b.c.c.internal.CatalogDo [main]: Loading 
catalog Loaded:CatalogDto{contentsDescription=<reset-by-ha-status-change>}(not 
yet loaded) into null
2015-12-14 15:59:22,875 DEBUG o.a.b.c.c.internal.CatalogDo [main]: Building 
cache for 
Loaded:CatalogDto{contentsDescription=<reset-by-ha-status-change>}(not yet 
loaded)
2015-12-14 15:59:22,875 DEBUG o.a.b.c.c.i.BasicBrooklynCatalog [main]: Reloaded 
catalog for 
org.apache.brooklyn.core.catalog.internal.BasicBrooklynCatalog@2c85b002, now 
switching
2015-12-14 15:59:22,875 DEBUG 
o.a.b.c.m.h.ManagementPlaneSyncRecordPersisterToObjectStore [main]: 
Checkpointed delta of manager-memento in 0ms: 
org.apache.brooklyn.core.mgmt.ha.ManagementPlaneSyncRecordDeltaImpl[nodes: 
[BasicManagementNodeSyncRecord{nodeId=PRrsOqGI, status=TERMINATED}]]
2015-12-14 15:59:22,875 DEBUG o.a.b.c.m.r.RebindManagerImpl [main]: Stopping 
persistence 
(org.apache.brooklyn.core.mgmt.rebind.RebindManagerImpl@7005b6e[mgmt=PRrsOqGI]),
 mgmt PRrsOqGI
2015-12-14 15:59:22,875 DEBUG o.a.b.c.m.r.RebindManagerImpl [main]: Stopped 
rebind (persistence), mgmt PRrsOqGI
2015-12-14 15:59:22,877 DEBUG o.a.b.core.entity.Entities [main]: destroying all 
apps in LocalManagementContext[L4lcSpwh-PRrsOqGI]: []
2015-12-14 15:59:22,877 DEBUG o.a.b.c.m.h.HighAvailabilityManagerImpl [main]: 
Stopping 
org.apache.brooklyn.core.mgmt.ha.HighAvailabilityManagerImpl@328c6f65[node:PRrsOqGI;running=false]
2015-12-14 15:59:22,877 DEBUG o.a.b.c.m.r.RebindManagerImpl [main]: Stopping 
persistence 
(org.apache.brooklyn.core.mgmt.rebind.RebindManagerImpl@7005b6e[mgmt=PRrsOqGI]),
 mgmt PRrsOqGI
2015-12-14 15:59:22,877 DEBUG o.a.b.c.m.r.RebindManagerImpl [main]: Stopped 
rebind (persistence), mgmt PRrsOqGI
2015-12-14 15:59:22,877 INFO  o.a.b.t.s.LoggingVerboseReporter [main]: TESTNG 
PASSED CONFIGURATION: "Surefire test" - @AfterMethod 
org.apache.brooklyn.core.mgmt.ha.HotStandbyTest.tearDown() finished in 7 ms
{code}




--
This message was sent by Atlassian JIRA
(v6.3.4#6332)