You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@brooklyn.apache.org by "Alex Heneveld (JIRA)" <ji...@apache.org> on 2016/02/25 00:27:18 UTC
[jira] [Commented] (BROOKLYN-204) Unpredictable test:
HotStandbyTest.testChangeMode
[ https://issues.apache.org/jira/browse/BROOKLYN-204?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=15166321#comment-15166321 ]
Alex Heneveld commented on BROOKLYN-204:
----------------------------------------
possibly same issue as BROOKLYN-231
> 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)