[ https://issues.apache.org/jira/browse/BROOKLYN-518?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=16053203#comment-16053203 ]
Svetoslav Neykov commented on BROOKLYN-518: ------------------------------------------- There's also a (failed) attempt for a fix in related area of the code - https://github.com/apache/brooklyn-server/pull/641. > newEnricherForServiceStateFromProblemsAndUp fails to update to RUNNING > ---------------------------------------------------------------------- > > Key: BROOKLYN-518 > URL: https://issues.apache.org/jira/browse/BROOKLYN-518 > Project: Brooklyn > Issue Type: Bug > Reporter: Svetoslav Neykov > > I saw the problem in the integration tests build at \[1]. It's an infrequent > failure - first time I notice it, but looks like a real problem. > Exception: > {noformat} > failed succeeds-eventually, 21 attempts, 3000ms elapsed: AssertionError: > entity=Application[tcp7p0x7]; attribute=Sensor: service.state > (org.apache.brooklyn.core.entity.lifecycle.Lifecycle) expected [running] but > found [on-fire] > Stacktrace > java.lang.AssertionError: failed succeeds-eventually, 21 attempts, > 3000ms elapsed: AssertionError: entity=Application[tcp7p0x7]; > attribute=Sensor: service.state > (org.apache.brooklyn.core.entity.lifecycle.Lifecycle) expected [running] but > found [on-fire] > at > org.apache.brooklyn.test.Asserts.succeedsEventually(Asserts.java:1012) > at org.apache.brooklyn.test.Asserts.succeedsEventually(Asserts.java:895) > at > org.apache.brooklyn.core.entity.EntityAsserts.assertAttributeEqualsEventually(EntityAsserts.java:71) > at > org.apache.brooklyn.core.entity.lifecycle.ServiceStateLogicTest.assertAttributeEqualsEventually(ServiceStateLogicTest.java:338) > at > org.apache.brooklyn.core.entity.lifecycle.ServiceStateLogicTest.testManuallySettingIndicatorsOnApplications(ServiceStateLogicTest.java:269) > at > org.apache.brooklyn.core.entity.lifecycle.ServiceStateLogicTest.testManuallySettingIndicatorsOnApplicationsManyTimes(ServiceStateLogicTest.java:162) > ... > Caused by: java.lang.AssertionError: entity=Application[tcp7p0x7]; > attribute=Sensor: service.state > (org.apache.brooklyn.core.entity.lifecycle.Lifecycle) expected [running] but > found [on-fire] > at org.apache.brooklyn.test.Asserts.fail(Asserts.java:758) > at org.apache.brooklyn.test.Asserts.failNotEquals(Asserts.java:148) > at org.apache.brooklyn.test.Asserts.assertEquals(Asserts.java:470) > at > org.apache.brooklyn.core.entity.EntityAsserts.assertAttributeEquals(EntityAsserts.java:58) > at > org.apache.brooklyn.core.entity.EntityAsserts$1.run(EntityAsserts.java:74) > at > org.apache.brooklyn.test.Asserts$RunnableAdapter.call(Asserts.java:1366) > at org.apache.brooklyn.test.Asserts.succeedsEventually(Asserts.java:973) > ... 34 more > {noformat} > Interesting bit from the log: > {noformat} > 2017-06-17 01:24:25,551 WARN > o.a.b.c.e.l.ServiceStateLogic$ComputeServiceState [main]: Setting > TestEntityImpl{id=hplgmxsx06} on-fire due to problems when expected running, > up=false, not-up-indicators: {} > 2017-06-17 01:24:28,552 INFO o.apache.brooklyn.test.Asserts [main]: > succeedsEventually exceeded max attempts or timeout - 21 attempts lasting > 3000 ms, for > RunnableAdapter(org.apache.brooklyn.core.entity.EntityAsserts$1@6402ddfc) > {noformat} > Note that just before the failure {{SERVICE_UP}} was confirmed to be true > (second line fails): > {noformat} > assertAttributeEqualsEventually(app, Attributes.SERVICE_UP, true); > assertAttributeEqualsEventually(app, Attributes.SERVICE_STATE_ACTUAL, > Lifecycle.RUNNING); > {noformat} > --- > Failing test run: > {noformat} > 2017-06-17 01:24:25,000 INFO o.a.b.t.s.LoggingVerboseReporter [main]: TESTNG > INVOKING: "Surefire test" - > org.apache.brooklyn.core.entity.lifecycle.ServiceStateLogicTest.testManuallySettingIndicatorsOnApplicationsManyTimes() > success: 100% > 2017-06-17 01:24:25,002 DEBUG o.a.b.c.entity.AbstractEntity [main]: Emitting > sensor notification entity.sensor.added value Sensor: > service.notUp.indicators (java.util.Map) on TestEntityImpl{id=xnvxswt1vg} > 2017-06-17 01:24:25,095 DEBUG o.a.b.c.entity.AbstractEntity [main]: Emitting > sensor notification entity.sensor.added value Sensor: service.state.expected > (org.apache.brooklyn.core.entity.lifecycle.Lifecycle$Transition) on > TestEntityImpl{id=xnvxswt1vg} > 2017-06-17 01:24:25,095 WARN > o.a.b.c.e.l.ServiceStateLogic$ComputeServiceState > [brooklyn-execmanager-nYK4CxsC-1]: Setting TestEntityImpl{id=xnvxswt1vg} > on-fire due to problems when expected running, up=false, not-up-indicators: > {test-indicator-1=We're also pretending to block service up} > 2017-06-17 01:24:25,095 WARN > o.a.b.c.e.l.ServiceStateLogic$ComputeServiceState > [brooklyn-execmanager-nYK4CxsC-1]: Setting Application[tcp7p0x7] on-fire due > to problems when expected null / up=true: > {service-lifecycle-indicators-from-children-and-members=Required entity not > healthy: TestEntityImpl{id=xnvxswt1vg}} > 2017-06-17 01:24:25,189 WARN > o.a.b.c.e.l.ServiceStateLogic$ComputeServiceState > [brooklyn-execmanager-nYK4CxsC-3]: Setting Application[tcp7p0x7] on-fire due > to problems when expected null / up=true: > {service-lifecycle-indicators-from-children-and-members=Not enough entities > running to be quorate} > 2017-06-17 01:24:25,192 DEBUG o.a.b.c.entity.AbstractEntity [main]: Emitting > sensor notification entity.children.removed value > TestEntityImpl{id=xnvxswt1vg} on Application[tcp7p0x7] > 2017-06-17 01:24:25,192 DEBUG o.a.b.e.s.AbstractAggregator > [brooklyn-execmanager-nYK4CxsC-2]: > ComputeServiceIndicatorsFromChildrenAndMembers{uniqueTag=service-lifecycle-indicators-from-children-and-members, > running=true, entity=Application[tcp7p0x7], id=jf7azd1k8q} stopped listening > to TestEntityImpl{id=xnvxswt1vg} > 2017-06-17 01:24:25,192 DEBUG o.a.b.c.m.i.LocalEntityManager [main]: > org.apache.brooklyn.core.mgmt.internal.LocalEntityManager@69fb63c5 stopped > management of entity TestEntityImpl{id=xnvxswt1vg} > 2017-06-17 01:24:25,192 WARN > o.a.b.c.e.l.ServiceStateLogic$ComputeServiceState > [brooklyn-execmanager-nYK4CxsC-3]: Setting Application[tcp7p0x7] on-fire due > to problems when expected null / up=true: > {service-lifecycle-indicators-from-children-and-members=Not enough entities > running to be quorate} > 2017-06-17 01:24:25,225 DEBUG o.a.b.c.l.BasicLocationRegistry > [brooklyn-execmanager-nYK4CxsC-3]: Location resolvers are: > {id=org.apache.brooklyn.core.location.DefinedLocationByIdResolver@787648d9, > named=org.apache.brooklyn.core.location.NamedLocationResolver@49d1a803, > brooklyn.catalog=org.apache.brooklyn.core.location.CatalogLocationResolver@75da0403, > > portForwardManager=org.apache.brooklyn.core.location.access.PortForwardManagerLocationResolver@588b9749, > byon=org.apache.brooklyn.location.byon.ByonLocationResolver@12713c01, > single=org.apache.brooklyn.location.byon.SingleMachineLocationResolver@56a41ffc, > host=org.apache.brooklyn.location.byon.HostLocationResolver@737bf337, > localhost=org.apache.brooklyn.location.localhost.LocalhostLocationResolver@77ce13ce, > multi=org.apache.brooklyn.location.multi.MultiLocationResolver@6cc4a802} > 2017-06-17 01:24:25,225 DEBUG o.a.b.c.l.BasicLocationRegistry > [brooklyn-execmanager-nYK4CxsC-3]: Found 0 defined locations from properties > (*.named.* syntax): [] > 2017-06-17 01:24:25,225 DEBUG o.a.b.c.c.i.CatalogInitialization > [brooklyn-execmanager-nYK4CxsC-3]: Populating catalog unofficially > (org.apache.brooklyn.core.catalog.internal.BasicBrooklynCatalog@1ae90199) > 2017-06-17 01:24:25,225 DEBUG o.a.b.c.c.i.CatalogInitialization > [brooklyn-execmanager-nYK4CxsC-3]: Loading initial catalog from > classpath://brooklyn/empty.catalog.bom > 2017-06-17 01:24:25,226 DEBUG o.a.b.c.c.i.BasicBrooklynCatalog > [brooklyn-execmanager-nYK4CxsC-3]: Forcing catalog load on access of catalog > items > 2017-06-17 01:24:25,226 DEBUG o.a.b.c.c.i.BasicBrooklynCatalog > [brooklyn-execmanager-nYK4CxsC-3]: Loading catalog for > LocalManagementContext[?-nYK4CxsC] > 2017-06-17 01:24:25,226 DEBUG o.a.b.c.c.internal.CatalogDo > [brooklyn-execmanager-nYK4CxsC-3]: Loading catalog > Loaded:CatalogDto{name=empty catalog, contentsDescription=empty catalog, > expected to be reset later}(not yet loaded) into null > 2017-06-17 01:24:25,226 DEBUG o.a.b.c.c.internal.CatalogDo > [brooklyn-execmanager-nYK4CxsC-3]: Building cache for > Loaded:CatalogDto{name=empty catalog, contentsDescription=empty catalog, > expected to be reset later}(not yet loaded) > 2017-06-17 01:24:25,226 DEBUG o.a.b.c.c.i.BasicBrooklynCatalog > [brooklyn-execmanager-nYK4CxsC-3]: Loaded catalog for > LocalManagementContext[?-nYK4CxsC]: Loaded:CatalogDto{name=empty catalog, > contentsDescription=empty catalog, expected to be reset later}(size 0); > search classpath is > AggregateClassLoader[sun.misc.Launcher$AppClassLoader@330bedb4, > AggregateClassLoader[]] > 2017-06-17 01:24:25,226 DEBUG o.a.b.c.c.i.BasicBrooklynCatalog > [brooklyn-execmanager-nYK4CxsC-3]: Resetting > org.apache.brooklyn.core.catalog.internal.BasicBrooklynCatalog@1ae90199 > catalog to CatalogDto{contentsDescription=explicit-catalog-reset} > 2017-06-17 01:24:25,226 DEBUG o.a.b.c.c.internal.CatalogDo > [brooklyn-execmanager-nYK4CxsC-3]: Loading catalog > Loaded:CatalogDto{contentsDescription=explicit-catalog-reset}(not yet loaded) > into null > 2017-06-17 01:24:25,226 DEBUG o.a.b.c.c.internal.CatalogDo > [brooklyn-execmanager-nYK4CxsC-3]: Building cache for > Loaded:CatalogDto{contentsDescription=explicit-catalog-reset}(not yet loaded) > 2017-06-17 01:24:25,226 DEBUG o.a.b.c.c.i.BasicBrooklynCatalog > [brooklyn-execmanager-nYK4CxsC-3]: Reloaded catalog for > org.apache.brooklyn.core.catalog.internal.BasicBrooklynCatalog@1ae90199, now > switching > 2017-06-17 01:24:25,226 DEBUG o.a.b.c.c.i.BasicBrooklynCatalog > [brooklyn-execmanager-nYK4CxsC-3]: Adding manual catalog item to > LocalManagementContext[?-nYK4CxsC]: # 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: {} > 2017-06-17 01:24:25,226 INFO o.a.b.c.c.i.BasicBrooklynCatalog > [brooklyn-execmanager-nYK4CxsC-3]: No Camp-YAML parser registered for parsing > catalog item DSL; skipping DSL-parsing > 2017-06-17 01:24:25,226 DEBUG o.a.b.c.c.i.CatalogInitialization > [brooklyn-execmanager-nYK4CxsC-3]: Loaded initial catalog from > classpath://brooklyn/empty.catalog.bom: [] > 2017-06-17 01:24:25,226 DEBUG o.a.b.c.m.i.EffectorUtils > [brooklyn-execmanager-nYK4CxsC-3]: Invoking effector start on > Application[tcp7p0x7] > 2017-06-17 01:24:25,227 DEBUG o.a.b.c.entity.AbstractEntity > [brooklyn-execmanager-nYK4CxsC-3]: Emitting sensor notification > entity.sensor.added value Sensor: service.state.expected > (org.apache.brooklyn.core.entity.lifecycle.Lifecycle$Transition) on > Application[tcp7p0x7] > 2017-06-17 01:24:25,227 DEBUG o.a.b.c.m.i.LocalUsageManager > [brooklyn-execmanager-nYK4CxsC-3]: Storing application lifecycle usage event: > application Application[tcp7p0x7] in state starting > 2017-06-17 01:24:25,227 DEBUG o.a.b.c.e.t.StartableMethods > [brooklyn-execmanager-nYK4CxsC-3]: Starting entity Application[tcp7p0x7] at [] > 2017-06-17 01:24:25,228 DEBUG o.a.b.util.repeat.Repeater > [brooklyn-execmanager-nYK4CxsC-3]: Repeater: unsatisfied during iteration 1 > (200ms remaining) > 2017-06-17 01:24:25,429 DEBUG o.a.b.util.repeat.Repeater > [brooklyn-execmanager-nYK4CxsC-3]: Repeater: condition not satisfied, with > 201.0ms elapsed (limit 200ms) > 2017-06-17 01:24:25,429 WARN o.a.b.c.e.l.ServiceStateLogic > [brooklyn-execmanager-nYK4CxsC-3]: Service is not up when setting running on > Application[tcp7p0x7]; delayed 201ms but Sensor: service.isUp > (java.lang.Boolean) did not recover from false; > not-up-indicators={service-lifecycle-indicators-from-children-and-members=No > entities present} > 2017-06-17 01:24:25,429 WARN > o.a.b.c.e.l.ServiceStateLogic$ComputeServiceState > [brooklyn-execmanager-nYK4CxsC-3]: Setting Application[tcp7p0x7] on-fire due > to problems when expected running, up=false, problems: > {service-lifecycle-indicators-from-children-and-members=Not enough entities > running to be quorate} > 2017-06-17 01:24:25,429 WARN > o.a.b.c.e.l.ServiceStateLogic$ComputeServiceState > [brooklyn-execmanager-nYK4CxsC-0]: Setting Application[tcp7p0x7] on-fire due > to problems when expected running, up=false, problems: > {service-lifecycle-indicators-from-children-and-members=Not enough entities > running to be quorate} > 2017-06-17 01:24:25,429 DEBUG o.a.b.c.m.i.LocalUsageManager > [brooklyn-execmanager-nYK4CxsC-3]: Storing application lifecycle usage event: > application Application[tcp7p0x7] in state running > 2017-06-17 01:24:25,429 DEBUG o.a.b.c.t.e.TestApplication > [brooklyn-execmanager-nYK4CxsC-3]: Started application Application[tcp7p0x7] > 2017-06-17 01:24:25,483 DEBUG o.a.b.c.entity.AbstractEntity [main]: Emitting > sensor notification entity.children.added value TestEntityImpl{id=hplgmxsx06} > on Application[tcp7p0x7] > 2017-06-17 01:24:25,484 DEBUG o.a.b.e.s.AbstractMultipleSensorAggregator > [brooklyn-execmanager-nYK4CxsC-2]: > ComputeServiceIndicatorsFromChildrenAndMembers{uniqueTag=service-lifecycle-indicators-from-children-and-members, > running=true, entity=Application[tcp7p0x7], id=jf7azd1k8q} listening to > TestEntityImpl{id=hplgmxsx06} > 2017-06-17 01:24:25,486 DEBUG o.a.b.c.m.i.LocalSubscriptionManager [main]: > Creating subscription kVMH5B9k for TestEntityImpl{id=hplgmxsx06} on > TestEntityImpl{id=hplgmxsx06} Sensor: service.notUp.indicators > (java.util.Map) in SubscriptionContext(AA+3C) > 2017-06-17 01:24:25,486 DEBUG o.a.b.c.m.i.LocalSubscriptionManager [main]: > Creating subscription k6lGpM9f for TestEntityImpl{id=hplgmxsx06} on > TestEntityImpl{id=hplgmxsx06} Sensor: service.problems (java.util.Map) in > SubscriptionContext(AA+3C) > 2017-06-17 01:24:25,486 DEBUG o.a.b.c.m.i.LocalSubscriptionManager [main]: > Creating subscription EKjjp0n0 for TestEntityImpl{id=hplgmxsx06} on > TestEntityImpl{id=hplgmxsx06} Sensor: service.isUp (java.lang.Boolean) in > SubscriptionContext(AA+3C) > 2017-06-17 01:24:25,486 DEBUG o.a.b.c.m.i.LocalSubscriptionManager [main]: > Creating subscription YrSJUi9h for TestEntityImpl{id=hplgmxsx06} on > TestEntityImpl{id=hplgmxsx06} Sensor: service.state.expected > (org.apache.brooklyn.core.entity.lifecycle.Lifecycle$Transition) in > SubscriptionContext(AA+3C) > 2017-06-17 01:24:25,486 DEBUG o.a.b.c.m.i.LocalEntityManager [main]: > org.apache.brooklyn.core.mgmt.internal.LocalEntityManager@69fb63c5 starting > management of entity TestEntityImpl{id=hplgmxsx06} > 2017-06-17 01:24:25,550 DEBUG o.a.b.c.entity.AbstractEntity [main]: Emitting > sensor notification entity.sensor.added value Sensor: > service.notUp.indicators (java.util.Map) on TestEntityImpl{id=hplgmxsx06} > 2017-06-17 01:24:25,550 DEBUG o.a.b.c.entity.AbstractEntity [main]: Emitting > sensor notification entity.sensor.added value Sensor: service.state.expected > (org.apache.brooklyn.core.entity.lifecycle.Lifecycle$Transition) on > TestEntityImpl{id=hplgmxsx06} > 2017-06-17 01:24:25,551 WARN > o.a.b.c.e.l.ServiceStateLogic$ComputeServiceState [main]: Setting > TestEntityImpl{id=hplgmxsx06} on-fire due to problems when expected running, > up=false, not-up-indicators: {} > 2017-06-17 01:24:28,552 INFO o.apache.brooklyn.test.Asserts [main]: > succeedsEventually exceeded max attempts or timeout - 21 attempts lasting > 3000 ms, for > RunnableAdapter(org.apache.brooklyn.core.entity.EntityAsserts$1@6402ddfc) > 2017-06-17 01:24:28,552 INFO o.apache.brooklyn.test.Asserts [main]: failed > succeeds-eventually, 21 attempts, 3000ms elapsed (rethrowing): > java.lang.AssertionError: entity=Application[tcp7p0x7]; attribute=Sensor: > service.state (org.apache.brooklyn.core.entity.lifecycle.Lifecycle) expected > [running] but found [on-fire] > 2017-06-17 01:24:28,553 WARN o.a.b.c.e.l.ServiceStateLogicTest [main]: > Expected Application[tcp7p0x7] eventually to have Sensor: service.state > (org.apache.brooklyn.core.entity.lifecycle.Lifecycle) = running; instead: > 2017-06-17 01:24:28,560 INFO o.a.b.t.s.LoggingVerboseReporter [main]: TESTNG > FAILED: "Surefire test" - > org.apache.brooklyn.core.entity.lifecycle.ServiceStateLogicTest.testManuallySettingIndicatorsOnApplicationsManyTimes() > finished in 3556 ms (16 of 100) > java.lang.AssertionError: failed succeeds-eventually, 21 attempts, 3000ms > elapsed: AssertionError: entity=Application[tcp7p0x7]; attribute=Sensor: > service.state (org.apache.brooklyn.core.entity.lifecycle.Lifecycle) expected > [running] but found [on-fire] > at > org.apache.brooklyn.test.Asserts.succeedsEventually(Asserts.java:1012) > at org.apache.brooklyn.test.Asserts.succeedsEventually(Asserts.java:895) > at > org.apache.brooklyn.core.entity.EntityAsserts.assertAttributeEqualsEventually(EntityAsserts.java:71) > at > org.apache.brooklyn.core.entity.lifecycle.ServiceStateLogicTest.assertAttributeEqualsEventually(ServiceStateLogicTest.java:338) > at > org.apache.brooklyn.core.entity.lifecycle.ServiceStateLogicTest.testManuallySettingIndicatorsOnApplications(ServiceStateLogicTest.java:269) > at > org.apache.brooklyn.core.entity.lifecycle.ServiceStateLogicTest.testManuallySettingIndicatorsOnApplicationsManyTimes(ServiceStateLogicTest.java:162) > Caused by: java.lang.AssertionError: entity=Application[tcp7p0x7]; > attribute=Sensor: service.state > (org.apache.brooklyn.core.entity.lifecycle.Lifecycle) expected [running] but > found [on-fire] > at org.apache.brooklyn.test.Asserts.fail(Asserts.java:758) > at org.apache.brooklyn.test.Asserts.failNotEquals(Asserts.java:148) > at org.apache.brooklyn.test.Asserts.assertEquals(Asserts.java:470) > at > org.apache.brooklyn.core.entity.EntityAsserts.assertAttributeEquals(EntityAsserts.java:58) > at > org.apache.brooklyn.core.entity.EntityAsserts$1.run(EntityAsserts.java:74) > at > org.apache.brooklyn.test.Asserts$RunnableAdapter.call(Asserts.java:1366) > at org.apache.brooklyn.test.Asserts.succeedsEventually(Asserts.java:973) > ... 34 more > {noformat} > Successful test run: > {noformat} > 2017-06-17 01:24:24,404 INFO o.a.b.t.s.LoggingVerboseReporter [main]: TESTNG > INVOKING: "Surefire test" - > org.apache.brooklyn.core.entity.lifecycle.ServiceStateLogicTest.testManuallySettingIndicatorsOnApplicationsManyTimes() > success: 100% > 2017-06-17 01:24:24,406 DEBUG o.a.b.c.entity.AbstractEntity [main]: Emitting > sensor notification entity.sensor.added value Sensor: > service.notUp.indicators (java.util.Map) on TestEntityImpl{id=wgtl9250zd} > 2017-06-17 01:24:24,497 DEBUG o.a.b.c.entity.AbstractEntity [main]: Emitting > sensor notification entity.sensor.added value Sensor: service.state.expected > (org.apache.brooklyn.core.entity.lifecycle.Lifecycle$Transition) on > TestEntityImpl{id=wgtl9250zd} > 2017-06-17 01:24:24,497 WARN > o.a.b.c.e.l.ServiceStateLogic$ComputeServiceState > [brooklyn-execmanager-gD8L8lGG-0]: Setting TestEntityImpl{id=wgtl9250zd} > on-fire due to problems when expected running, up=false, not-up-indicators: > {test-indicator-1=We're also pretending to block service up} > 2017-06-17 01:24:24,498 WARN > o.a.b.c.e.l.ServiceStateLogic$ComputeServiceState > [brooklyn-execmanager-gD8L8lGG-0]: Setting Application[h21cegjd] on-fire due > to problems when expected null / up=true: > {service-lifecycle-indicators-from-children-and-members=Required entity not > healthy: TestEntityImpl{id=wgtl9250zd}} > 2017-06-17 01:24:24,592 WARN > o.a.b.c.e.l.ServiceStateLogic$ComputeServiceState > [brooklyn-execmanager-gD8L8lGG-0]: Setting Application[h21cegjd] on-fire due > to problems when expected null / up=true: > {service-lifecycle-indicators-from-children-and-members=Not enough entities > running to be quorate} > 2017-06-17 01:24:24,594 DEBUG o.a.b.c.entity.AbstractEntity [main]: Emitting > sensor notification entity.children.removed value > TestEntityImpl{id=wgtl9250zd} on Application[h21cegjd] > 2017-06-17 01:24:24,594 DEBUG o.a.b.e.s.AbstractAggregator > [brooklyn-execmanager-gD8L8lGG-3]: > ComputeServiceIndicatorsFromChildrenAndMembers{uniqueTag=service-lifecycle-indicators-from-children-and-members, > running=true, entity=Application[h21cegjd], id=kg73ier5l4} stopped listening > to TestEntityImpl{id=wgtl9250zd} > 2017-06-17 01:24:24,594 DEBUG o.a.b.c.m.i.LocalEntityManager [main]: > org.apache.brooklyn.core.mgmt.internal.LocalEntityManager@1adc30fe stopped > management of entity TestEntityImpl{id=wgtl9250zd} > 2017-06-17 01:24:24,594 WARN > o.a.b.c.e.l.ServiceStateLogic$ComputeServiceState > [brooklyn-execmanager-gD8L8lGG-0]: Setting Application[h21cegjd] on-fire due > to problems when expected null / up=true: > {service-lifecycle-indicators-from-children-and-members=Not enough entities > running to be quorate} > 2017-06-17 01:24:24,628 DEBUG o.a.b.c.l.BasicLocationRegistry > [brooklyn-execmanager-gD8L8lGG-0]: Location resolvers are: > {id=org.apache.brooklyn.core.location.DefinedLocationByIdResolver@3055c8c3, > named=org.apache.brooklyn.core.location.NamedLocationResolver@3f881fd0, > brooklyn.catalog=org.apache.brooklyn.core.location.CatalogLocationResolver@2048a127, > > portForwardManager=org.apache.brooklyn.core.location.access.PortForwardManagerLocationResolver@29996c13, > byon=org.apache.brooklyn.location.byon.ByonLocationResolver@3714aa33, > single=org.apache.brooklyn.location.byon.SingleMachineLocationResolver@481d559e, > host=org.apache.brooklyn.location.byon.HostLocationResolver@2d399423, > localhost=org.apache.brooklyn.location.localhost.LocalhostLocationResolver@4f228c63, > multi=org.apache.brooklyn.location.multi.MultiLocationResolver@5d75f286} > 2017-06-17 01:24:24,628 DEBUG o.a.b.c.l.BasicLocationRegistry > [brooklyn-execmanager-gD8L8lGG-0]: Found 0 defined locations from properties > (*.named.* syntax): [] > 2017-06-17 01:24:24,628 DEBUG o.a.b.c.c.i.CatalogInitialization > [brooklyn-execmanager-gD8L8lGG-0]: Populating catalog unofficially > (org.apache.brooklyn.core.catalog.internal.BasicBrooklynCatalog@7fe6ed13) > 2017-06-17 01:24:24,628 DEBUG o.a.b.c.c.i.CatalogInitialization > [brooklyn-execmanager-gD8L8lGG-0]: Loading initial catalog from > classpath://brooklyn/empty.catalog.bom > 2017-06-17 01:24:24,628 DEBUG o.a.b.c.c.i.BasicBrooklynCatalog > [brooklyn-execmanager-gD8L8lGG-0]: Forcing catalog load on access of catalog > items > 2017-06-17 01:24:24,628 DEBUG o.a.b.c.c.i.BasicBrooklynCatalog > [brooklyn-execmanager-gD8L8lGG-0]: Loading catalog for > LocalManagementContext[?-gD8L8lGG] > 2017-06-17 01:24:24,628 DEBUG o.a.b.c.c.internal.CatalogDo > [brooklyn-execmanager-gD8L8lGG-0]: Loading catalog > Loaded:CatalogDto{name=empty catalog, contentsDescription=empty catalog, > expected to be reset later}(not yet loaded) into null > 2017-06-17 01:24:24,628 DEBUG o.a.b.c.c.internal.CatalogDo > [brooklyn-execmanager-gD8L8lGG-0]: Building cache for > Loaded:CatalogDto{name=empty catalog, contentsDescription=empty catalog, > expected to be reset later}(not yet loaded) > 2017-06-17 01:24:24,628 DEBUG o.a.b.c.c.i.BasicBrooklynCatalog > [brooklyn-execmanager-gD8L8lGG-0]: Loaded catalog for > LocalManagementContext[?-gD8L8lGG]: Loaded:CatalogDto{name=empty catalog, > contentsDescription=empty catalog, expected to be reset later}(size 0); > search classpath is > AggregateClassLoader[sun.misc.Launcher$AppClassLoader@330bedb4, > AggregateClassLoader[]] > 2017-06-17 01:24:24,628 DEBUG o.a.b.c.c.i.BasicBrooklynCatalog > [brooklyn-execmanager-gD8L8lGG-0]: Resetting > org.apache.brooklyn.core.catalog.internal.BasicBrooklynCatalog@7fe6ed13 > catalog to CatalogDto{contentsDescription=explicit-catalog-reset} > 2017-06-17 01:24:24,628 DEBUG o.a.b.c.c.internal.CatalogDo > [brooklyn-execmanager-gD8L8lGG-0]: Loading catalog > Loaded:CatalogDto{contentsDescription=explicit-catalog-reset}(not yet loaded) > into null > 2017-06-17 01:24:24,628 DEBUG o.a.b.c.c.internal.CatalogDo > [brooklyn-execmanager-gD8L8lGG-0]: Building cache for > Loaded:CatalogDto{contentsDescription=explicit-catalog-reset}(not yet loaded) > 2017-06-17 01:24:24,628 DEBUG o.a.b.c.c.i.BasicBrooklynCatalog > [brooklyn-execmanager-gD8L8lGG-0]: Reloaded catalog for > org.apache.brooklyn.core.catalog.internal.BasicBrooklynCatalog@7fe6ed13, now > switching > 2017-06-17 01:24:24,629 DEBUG o.a.b.c.c.i.BasicBrooklynCatalog > [brooklyn-execmanager-gD8L8lGG-0]: Adding manual catalog item to > LocalManagementContext[?-gD8L8lGG]: # 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: {} > 2017-06-17 01:24:24,629 INFO o.a.b.c.c.i.BasicBrooklynCatalog > [brooklyn-execmanager-gD8L8lGG-0]: No Camp-YAML parser registered for parsing > catalog item DSL; skipping DSL-parsing > 2017-06-17 01:24:24,629 DEBUG o.a.b.c.c.i.CatalogInitialization > [brooklyn-execmanager-gD8L8lGG-0]: Loaded initial catalog from > classpath://brooklyn/empty.catalog.bom: [] > 2017-06-17 01:24:24,629 DEBUG o.a.b.c.m.i.EffectorUtils > [brooklyn-execmanager-gD8L8lGG-0]: Invoking effector start on > Application[h21cegjd] > 2017-06-17 01:24:24,629 DEBUG o.a.b.c.entity.AbstractEntity > [brooklyn-execmanager-gD8L8lGG-0]: Emitting sensor notification > entity.sensor.added value Sensor: service.state.expected > (org.apache.brooklyn.core.entity.lifecycle.Lifecycle$Transition) on > Application[h21cegjd] > 2017-06-17 01:24:24,629 DEBUG o.a.b.c.m.i.LocalUsageManager > [brooklyn-execmanager-gD8L8lGG-0]: Storing application lifecycle usage event: > application Application[h21cegjd] in state starting > 2017-06-17 01:24:24,629 DEBUG o.a.b.c.e.t.StartableMethods > [brooklyn-execmanager-gD8L8lGG-0]: Starting entity Application[h21cegjd] at [] > 2017-06-17 01:24:24,630 DEBUG o.a.b.util.repeat.Repeater > [brooklyn-execmanager-gD8L8lGG-0]: Repeater: unsatisfied during iteration 1 > (200ms remaining) > 2017-06-17 01:24:24,831 DEBUG o.a.b.util.repeat.Repeater > [brooklyn-execmanager-gD8L8lGG-0]: Repeater: condition not satisfied, with > 201.0ms elapsed (limit 200ms) > 2017-06-17 01:24:24,831 WARN o.a.b.c.e.l.ServiceStateLogic > [brooklyn-execmanager-gD8L8lGG-0]: Service is not up when setting running on > Application[h21cegjd]; delayed 201ms but Sensor: service.isUp > (java.lang.Boolean) did not recover from false; > not-up-indicators={service-lifecycle-indicators-from-children-and-members=No > entities present} > 2017-06-17 01:24:24,831 WARN > o.a.b.c.e.l.ServiceStateLogic$ComputeServiceState > [brooklyn-execmanager-gD8L8lGG-0]: Setting Application[h21cegjd] on-fire due > to problems when expected running, up=false, problems: > {service-lifecycle-indicators-from-children-and-members=Not enough entities > running to be quorate} > 2017-06-17 01:24:24,831 DEBUG o.a.b.c.m.i.LocalUsageManager > [brooklyn-execmanager-gD8L8lGG-0]: Storing application lifecycle usage event: > application Application[h21cegjd] in state running > 2017-06-17 01:24:24,831 DEBUG o.a.b.c.t.e.TestApplication > [brooklyn-execmanager-gD8L8lGG-0]: Started application Application[h21cegjd] > 2017-06-17 01:24:24,875 DEBUG o.a.b.c.entity.AbstractEntity [main]: Emitting > sensor notification entity.children.added value TestEntityImpl{id=ui0mvrmewx} > on Application[h21cegjd] > 2017-06-17 01:24:24,875 DEBUG o.a.b.e.s.AbstractMultipleSensorAggregator > [brooklyn-execmanager-gD8L8lGG-0]: > ComputeServiceIndicatorsFromChildrenAndMembers{uniqueTag=service-lifecycle-indicators-from-children-and-members, > running=true, entity=Application[h21cegjd], id=kg73ier5l4} listening to > TestEntityImpl{id=ui0mvrmewx} > 2017-06-17 01:24:24,876 DEBUG o.a.b.c.m.i.LocalSubscriptionManager [main]: > Creating subscription Pzf6nLmd for TestEntityImpl{id=ui0mvrmewx} on > TestEntityImpl{id=ui0mvrmewx} Sensor: service.notUp.indicators > (java.util.Map) in SubscriptionContext(XblPX) > 2017-06-17 01:24:24,876 DEBUG o.a.b.c.m.i.LocalSubscriptionManager [main]: > Creating subscription OIdUSX3x for TestEntityImpl{id=ui0mvrmewx} on > TestEntityImpl{id=ui0mvrmewx} Sensor: service.problems (java.util.Map) in > SubscriptionContext(XblPX) > 2017-06-17 01:24:24,876 DEBUG o.a.b.c.m.i.LocalSubscriptionManager [main]: > Creating subscription J6MyNHl9 for TestEntityImpl{id=ui0mvrmewx} on > TestEntityImpl{id=ui0mvrmewx} Sensor: service.isUp (java.lang.Boolean) in > SubscriptionContext(XblPX) > 2017-06-17 01:24:24,876 DEBUG o.a.b.c.m.i.LocalSubscriptionManager [main]: > Creating subscription HdrOSlAa for TestEntityImpl{id=ui0mvrmewx} on > TestEntityImpl{id=ui0mvrmewx} Sensor: service.state.expected > (org.apache.brooklyn.core.entity.lifecycle.Lifecycle$Transition) in > SubscriptionContext(XblPX) > 2017-06-17 01:24:24,876 DEBUG o.a.b.c.m.i.LocalEntityManager [main]: > org.apache.brooklyn.core.mgmt.internal.LocalEntityManager@1adc30fe starting > management of entity TestEntityImpl{id=ui0mvrmewx} > 2017-06-17 01:24:24,939 DEBUG o.a.b.c.entity.AbstractEntity [main]: Emitting > sensor notification entity.sensor.added value Sensor: > service.notUp.indicators (java.util.Map) on TestEntityImpl{id=ui0mvrmewx} > 2017-06-17 01:24:24,940 DEBUG o.a.b.c.entity.AbstractEntity [main]: Emitting > sensor notification entity.sensor.added value Sensor: service.state.expected > (org.apache.brooklyn.core.entity.lifecycle.Lifecycle$Transition) on > TestEntityImpl{id=ui0mvrmewx} > 2017-06-17 01:24:24,941 INFO o.a.b.t.s.LoggingVerboseReporter [main]: TESTNG > PASSED: "Surefire test" - > org.apache.brooklyn.core.entity.lifecycle.ServiceStateLogicTest.testManuallySettingIndicatorsOnApplicationsManyTimes() > finished in 537 ms (15 of 100) > {noformat} > \[1] > https://builds.apache.org/view/B/view/Brooklyn/job/brooklyn-integration-tests/76/ -- This message was sent by Atlassian JIRA (v6.4.14#64029)