brooklyn-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From "Sam Corbett (JIRA)" <j...@apache.org>
Subject [jira] [Created] (BROOKLYN-204) Unpredictable test: HotStandbyTest.testChangeMode
Date Mon, 14 Dec 2015 16:05:46 GMT
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)

Mime
View raw message