[
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)
