Skip to content

Instantly share code, notes, and snippets.

@davidx
Created January 7, 2016 22:42
Show Gist options
  • Star 0 You must be signed in to star a gist
  • Fork 0 You must be signed in to fork a gist
  • Save davidx/1a21649a5e637b7b2a6a to your computer and use it in GitHub Desktop.
Save davidx/1a21649a5e637b7b2a6a to your computer and use it in GitHub Desktop.
HaPolicyRebindTest.setUp()
2016-01-07 13:33:58,378 INFO Test class org.apache.brooklyn.policy.ha.HaPolicyRebindTest persisting to /tmp/HaPolicyRebindTest-qIZu
2016-01-07 13:33:58,386 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.policy.ha.HaPolicyRebindTest.setUp() finished in 21 ms
2016-01-07 13:33:58,386 INFO TESTNG INVOKING: "Surefire test" - org.apache.brooklyn.policy.ha.HaPolicyRebindTest.testServiceReplacerWorksAfterRebind()
2016-01-07 13:33:58,412 INFO Resize DynamicClusterImpl{id=v2klXbzT} from 0 to 3
2016-01-07 13:33:58,664 WARN Service is not up when setting running on Application[JkKKjWTp]; delayed 208ms but Sensor: service.isUp (java.lang.Boolean) did not recover from null; not-up-indicators={}
2016-01-07 13:33:58,866 WARN Service is not up when setting running on Application[JkKKjWTp]; delayed 202ms but Sensor: service.isUp (java.lang.Boolean) did not recover from null; not-up-indicators={}
2016-01-07 13:33:58,871 INFO Rebinding app, using mementoDir /tmp/HaPolicyRebindTest-qIZu; object store null
2016-01-07 13:33:58,889 INFO Rebinding from /tmp/HaPolicyRebindTest-qIZu for master Xzmi9RQF...
2016-01-07 13:33:58,948 INFO Rebind complete (MASTER) in 76ms: 1 app, 7 entities, 1 location, 1 policy, 13 enrichers, 0 feeds, 0 catalog items
2016-01-07 13:33:58,949 WARN ServiceReplacer notified; dispatching job for DynamicClusterImpl{id=v2klXbzT} (FailureDescriptor{component=TestEntityImpl{id=WwT9cE4C}, description=simulate failure})
2016-01-07 13:33:58,949 WARN ServiceReplacer acting on failure detected at TestEntityImpl{id=WwT9cE4C} (FailureDescriptor{component=TestEntityImpl{id=WwT9cE4C}, description=simulate failure}, child of DynamicClusterImpl{id=v2klXbzT})
2016-01-07 13:33:58,950 INFO In DynamicClusterImpl{id=v2klXbzT}, replacing member WwT9cE4C (TestEntityImpl{id=WwT9cE4C})
2016-01-07 13:33:58,969 INFO TESTNG PASSED: "Surefire test" - org.apache.brooklyn.policy.ha.HaPolicyRebindTest.testServiceReplacerWorksAfterRebind() finished in 583 ms
2016-01-07 13:33:58,969 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.core.mgmt.rebind.RebindTestFixture.tearDown()
2016-01-07 13:33:58,986 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.core.mgmt.rebind.RebindTestFixture.tearDown() finished in 17 ms
2016-01-07 13:33:58,986 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.policy.ha.HaPolicyRebindTest.setUp()
2016-01-07 13:33:58,998 INFO Test class org.apache.brooklyn.policy.ha.HaPolicyRebindTest persisting to /tmp/HaPolicyRebindTest-qLsD
2016-01-07 13:33:59,006 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.policy.ha.HaPolicyRebindTest.setUp() finished in 20 ms
2016-01-07 13:33:59,006 INFO TESTNG INVOKING: "Surefire test" - org.apache.brooklyn.policy.ha.HaPolicyRebindTest.testServiceRestarterWorksAfterRebind()
2016-01-07 13:33:59,011 INFO Rebinding app, using mementoDir /tmp/HaPolicyRebindTest-qLsD; object store null
2016-01-07 13:33:59,018 INFO Rebinding from /tmp/HaPolicyRebindTest-qLsD for master Finr0v13...
2016-01-07 13:33:59,040 INFO Rebind complete (MASTER) in 28ms: 1 app, 2 entities, 0 locations, 1 policy, 2 enrichers, 0 feeds, 0 catalog items
2016-01-07 13:33:59,041 INFO ServiceRestarter notified; dispatching job for TestEntityImpl{id=FcB0a08x} (FailureDescriptor{component=TestEntityImpl{id=FcB0a08x}, description=simulate failure})
2016-01-07 13:33:59,041 WARN ServiceRestarter acting on failure detected at TestEntityImpl{id=FcB0a08x} (FailureDescriptor{component=TestEntityImpl{id=FcB0a08x}, description=simulate failure})
2016-01-07 13:33:59,042 INFO TESTNG PASSED: "Surefire test" - org.apache.brooklyn.policy.ha.HaPolicyRebindTest.testServiceRestarterWorksAfterRebind() finished in 36 ms
2016-01-07 13:33:59,042 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.core.mgmt.rebind.RebindTestFixture.tearDown()
2016-01-07 13:33:59,049 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.core.mgmt.rebind.RebindTestFixture.tearDown() finished in 7 ms
2016-01-07 13:33:59,049 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.policy.loadbalancing.AbstractLoadBalancingPolicyTest.before()
2016-01-07 13:33:59,076 WARN Deprecated use of unmanaged location (SimulatedLocation{id=vLaRgy6U, name=loc}); will be managed automatically now but not supported in future versions
2016-01-07 13:33:59,078 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.policy.loadbalancing.AbstractLoadBalancingPolicyTest.before() finished in 29 ms
2016-01-07 13:33:59,078 INFO TESTNG INVOKING: "Surefire test" - org.apache.brooklyn.policy.loadbalancing.LoadBalancingPolicySoakTest.testLoadBalancingManyItemsQuickTest()
2016-01-07 13:33:59,085 INFO org.apache.brooklyn.policy.loadbalancing.LoadBalancingPolicy$2@56c3890b emitted COLD (suggesting 0): {pool.current.size=1, pool.current.workrate=0.0, pool.low.threshold=200.0, pool.high.threshold=300.0}
SUREFIRE-859: [GC (Metadata GC Threshold) 204527K->50248K(756224K), 0.0132428 secs]
SUREFIRE-859: [Full GC (Metadata GC Threshold) 50248K->29684K(756224K), 0.1225820 secs]
2016-01-07 13:33:59,257 INFO org.apache.brooklyn.policy.loadbalancing.LoadBalancingPolicy$2@14559ac6 emitted COLD (suggesting 1): {pool.current.size=5, pool.current.workrate=5.0, pool.low.threshold=1000.0, pool.high.threshold=1500.0}
2016-01-07 13:33:59,415 INFO LoadBalancingPolicySoakTest: cycle 1
2016-01-07 13:33:59,422 WARN Error invoking move at MockItem[item-9]: java.lang.reflect.UndeclaredThrowableException: Container lock MockContainer[container-2]; it is not running
2016-01-07 13:33:59,425 ERROR Error rebalancing
org.apache.brooklyn.util.exceptions.PropagatedRuntimeException:
at org.apache.brooklyn.util.exceptions.Exceptions.propagate(Exceptions.java:107) ~[brooklyn-utils-common-0.9.0-SNAPSHOT.jar:0.9.0-SNAPSHOT]
Caused by: java.util.concurrent.ExecutionException: org.apache.brooklyn.util.exceptions.PropagatedRuntimeException: Error invoking move at MockItem[item-9]: java.lang.reflect.UndeclaredThrowableException: Container lock MockContainer[container-2]; it is not running
at java.util.concurrent.FutureTask.report(FutureTask.java:122) [na:1.8.0_31]
Caused by: org.apache.brooklyn.util.exceptions.PropagatedRuntimeException: Error invoking move at MockItem[item-9]
at org.apache.brooklyn.core.mgmt.internal.EffectorUtils.handleEffectorException(EffectorUtils.java:270) ~[brooklyn-core-0.9.0-SNAPSHOT.jar:0.9.0-SNAPSHOT]
Caused by: java.util.concurrent.ExecutionException: java.lang.reflect.UndeclaredThrowableException
at org.apache.brooklyn.core.mgmt.internal.AbstractManagementContext.invokeEffectorMethodSync(AbstractManagementContext.java:337) ~[brooklyn-core-0.9.0-SNAPSHOT.jar:0.9.0-SNAPSHOT]
Caused by: java.lang.reflect.UndeclaredThrowableException: null
at com.sun.proxy.$Proxy1111.lock(Unknown Source) ~[na:na]
Caused by: java.lang.reflect.InvocationTargetException: null
at sun.reflect.GeneratedMethodAccessor45.invoke(Unknown Source) ~[na:na]
Caused by: java.lang.IllegalStateException: Container lock MockContainer[container-2]; it is not running
at org.apache.brooklyn.policy.loadbalancing.MockContainerEntityImpl.lock(MockContainerEntityImpl.java:68) ~[test-classes/:na]
2016-01-07 13:33:59,442 INFO TESTNG PASSED: "Surefire test" - org.apache.brooklyn.policy.loadbalancing.LoadBalancingPolicySoakTest.testLoadBalancingManyItemsQuickTest() finished in 364 ms
2016-01-07 13:33:59,442 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.policy.loadbalancing.AbstractLoadBalancingPolicyTest.after()
2016-01-07 13:33:59,451 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.policy.loadbalancing.AbstractLoadBalancingPolicyTest.after() finished in 9 ms
2016-01-07 13:33:59,451 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.policy.loadbalancing.AbstractLoadBalancingPolicyTest.before()
2016-01-07 13:33:59,480 WARN Deprecated use of unmanaged location (SimulatedLocation{id=ZcDHvogp, name=loc}); will be managed automatically now but not supported in future versions
2016-01-07 13:33:59,481 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.policy.loadbalancing.AbstractLoadBalancingPolicyTest.before() finished in 30 ms
2016-01-07 13:33:59,481 INFO TESTNG INVOKING: "Surefire test" - org.apache.brooklyn.policy.loadbalancing.LoadBalancingPolicySoakTest.testLoadBalancingQuickTest()
2016-01-07 13:33:59,490 INFO org.apache.brooklyn.policy.loadbalancing.LoadBalancingPolicy$2@34f22d44 emitted COLD (suggesting 0): {pool.current.size=1, pool.current.workrate=0.0, pool.low.threshold=200.0, pool.high.threshold=300.0}
2016-01-07 13:33:59,525 INFO org.apache.brooklyn.policy.loadbalancing.LoadBalancingPolicy$2@1998e0a3 emitted COLD (suggesting 1): {pool.current.size=5, pool.current.workrate=5.0, pool.low.threshold=1000.0, pool.high.threshold=1500.0}
2016-01-07 13:33:59,547 INFO LoadBalancingPolicySoakTest: cycle 1
2016-01-07 13:33:59,558 INFO org.apache.brooklyn.policy.loadbalancing.LoadBalancingPolicy$2@4afa4cd2 emitted HOT (suggesting 5): {pool.current.size=4, pool.current.workrate=1423.0, pool.low.threshold=800.0, pool.high.threshold=1200.0}
2016-01-07 13:33:59,566 INFO TESTNG PASSED: "Surefire test" - org.apache.brooklyn.policy.loadbalancing.LoadBalancingPolicySoakTest.testLoadBalancingQuickTest() finished in 85 ms
2016-01-07 13:33:59,567 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.policy.loadbalancing.AbstractLoadBalancingPolicyTest.after()
2016-01-07 13:33:59,572 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.policy.loadbalancing.AbstractLoadBalancingPolicyTest.after() finished in 6 ms
2016-01-07 13:33:59,572 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.policy.loadbalancing.AbstractLoadBalancingPolicyTest.before()
2016-01-07 13:33:59,600 WARN Deprecated use of unmanaged location (SimulatedLocation{id=qLb6HKXM, name=loc}); will be managed automatically now but not supported in future versions
2016-01-07 13:33:59,601 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.policy.loadbalancing.AbstractLoadBalancingPolicyTest.before() finished in 29 ms
2016-01-07 13:33:59,601 INFO TESTNG INVOKING: "Surefire test" - org.apache.brooklyn.policy.loadbalancing.LoadBalancingPolicyTest.testAddContainerWhenCold()
2016-01-07 13:33:59,610 INFO org.apache.brooklyn.policy.loadbalancing.LoadBalancingPolicy$2@36e614e8 emitted COLD (suggesting 0): {pool.current.size=1, pool.current.workrate=0.0, pool.low.threshold=10.0, pool.high.threshold=50.0}
2016-01-07 13:33:59,625 INFO org.apache.brooklyn.policy.loadbalancing.LoadBalancingPolicy$2@77db1ed3 emitted COLD (suggesting 1): {pool.current.size=2, pool.current.workrate=10.0, pool.low.threshold=20.0, pool.high.threshold=100.0}
2016-01-07 13:33:59,776 INFO TESTNG PASSED: "Surefire test" - org.apache.brooklyn.policy.loadbalancing.LoadBalancingPolicyTest.testAddContainerWhenCold() finished in 175 ms
2016-01-07 13:33:59,777 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.policy.loadbalancing.AbstractLoadBalancingPolicyTest.after()
2016-01-07 13:33:59,888 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.policy.loadbalancing.AbstractLoadBalancingPolicyTest.after() finished in 111 ms
2016-01-07 13:33:59,889 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.policy.loadbalancing.AbstractLoadBalancingPolicyTest.before()
2016-01-07 13:33:59,914 WARN Deprecated use of unmanaged location (SimulatedLocation{id=wXdQiWJo, name=loc}); will be managed automatically now but not supported in future versions
2016-01-07 13:33:59,915 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.policy.loadbalancing.AbstractLoadBalancingPolicyTest.before() finished in 27 ms
2016-01-07 13:33:59,915 INFO TESTNG INVOKING: "Surefire test" - org.apache.brooklyn.policy.loadbalancing.LoadBalancingPolicyTest.testAddContainerWhenHot()
2016-01-07 13:33:59,921 INFO org.apache.brooklyn.policy.loadbalancing.LoadBalancingPolicy$2@59eacb48 emitted COLD (suggesting 0): {pool.current.size=1, pool.current.workrate=0.0, pool.low.threshold=10.0, pool.high.threshold=30.0}
2016-01-07 13:33:59,934 INFO org.apache.brooklyn.policy.loadbalancing.LoadBalancingPolicy$2@18d7c173 emitted COLD (suggesting 1): {pool.current.size=2, pool.current.workrate=10.0, pool.low.threshold=20.0, pool.high.threshold=60.0}
2016-01-07 13:33:59,966 INFO org.apache.brooklyn.policy.loadbalancing.LoadBalancingPolicy$2@7f008e7b emitted HOT (suggesting 3): {pool.current.size=2, pool.current.workrate=70.0, pool.low.threshold=20.0, pool.high.threshold=60.0}
2016-01-07 13:34:00,086 INFO TESTNG PASSED: "Surefire test" - org.apache.brooklyn.policy.loadbalancing.LoadBalancingPolicyTest.testAddContainerWhenHot() finished in 171 ms
2016-01-07 13:34:00,086 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.policy.loadbalancing.AbstractLoadBalancingPolicyTest.after()
2016-01-07 13:34:00,193 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.policy.loadbalancing.AbstractLoadBalancingPolicyTest.after() finished in 107 ms
2016-01-07 13:34:00,193 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.policy.loadbalancing.AbstractLoadBalancingPolicyTest.before()
2016-01-07 13:34:00,218 WARN Deprecated use of unmanaged location (SimulatedLocation{id=OEIEYDgf, name=loc}); will be managed automatically now but not supported in future versions
2016-01-07 13:34:00,219 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.policy.loadbalancing.AbstractLoadBalancingPolicyTest.before() finished in 26 ms
2016-01-07 13:34:00,219 INFO TESTNG INVOKING: "Surefire test" - org.apache.brooklyn.policy.loadbalancing.LoadBalancingPolicyTest.testAddItem()
2016-01-07 13:34:00,226 INFO org.apache.brooklyn.policy.loadbalancing.LoadBalancingPolicy$2@6d749e38 emitted COLD (suggesting 0): {pool.current.size=1, pool.current.workrate=0.0, pool.low.threshold=10.0, pool.high.threshold=50.0}
2016-01-07 13:34:00,240 INFO org.apache.brooklyn.policy.loadbalancing.LoadBalancingPolicy$2@5463cf28 emitted COLD (suggesting 1): {pool.current.size=2, pool.current.workrate=10.0, pool.low.threshold=20.0, pool.high.threshold=100.0}
SUREFIRE-859: [GC (Allocation Failure) 245748K->35796K(763392K), 0.0074064 secs]
2016-01-07 13:34:00,253 WARN Task Task[LSM.publish(Application[pQCfs222].Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=MockContainer[C] @ 1452202440190);[subscription-delivery-entity-pQCfs222[Application[pQCfs222]]]:Y05TTDNY] is being finalized before completion
2016-01-07 13:34:00,253 WARN Task Task[LSM.publish(Application[pQCfs222].Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=MockItem[8] @ 1452202440190);[subscription-delivery-entity-pQCfs222[Application[pQCfs222]]]:vsHyJC29] is being finalized before completion
2016-01-07 13:34:00,253 WARN Task Task[LSM.publish(Application[pQCfs222].Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=MockItem[7] @ 1452202440190);[subscription-delivery-entity-pQCfs222[Application[pQCfs222]]]:nJQ02vnA] is being finalized before completion
2016-01-07 13:34:00,253 WARN Task Task[LSM.publish(Application[pQCfs222].Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=MockItem[6] @ 1452202440190);[subscription-delivery-entity-pQCfs222[Application[pQCfs222]]]:d08MzQ7k] is being finalized before completion
2016-01-07 13:34:00,253 WARN Task Task[LSM.publish(Application[pQCfs222].Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=MockItem[5] @ 1452202440190);[subscription-delivery-entity-pQCfs222[Application[pQCfs222]]]:dP6D7MQP] is being finalized before completion
2016-01-07 13:34:00,253 WARN Task Task[LSM.publish(Application[pQCfs222].Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=MockItem[4] @ 1452202440190);[subscription-delivery-entity-pQCfs222[Application[pQCfs222]]]:qKNMgQtH] is being finalized before completion
2016-01-07 13:34:00,253 WARN Task Task[LSM.publish(Application[pQCfs222].Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=MockItem[3] @ 1452202440190);[subscription-delivery-entity-pQCfs222[Application[pQCfs222]]]:YDCpshnK] is being finalized before completion
2016-01-07 13:34:00,253 WARN Task Task[LSM.publish(Application[pQCfs222].Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=MockItem[2] @ 1452202440189);[subscription-delivery-entity-pQCfs222[Application[pQCfs222]]]:EGyu6qSi] is being finalized before completion
2016-01-07 13:34:00,253 WARN Task Task[LSM.publish(Application[pQCfs222].Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=MockItem[1] @ 1452202440189);[subscription-delivery-entity-pQCfs222[Application[pQCfs222]]]:F0UdpaWr] is being finalized before completion
2016-01-07 13:34:00,254 WARN Task Task[LSM.publish(Application[pQCfs222].Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=MockContainer[B] @ 1452202440189);[subscription-delivery-entity-pQCfs222[Application[pQCfs222]]]:C9VjNNvS] is being finalized before completion
2016-01-07 13:34:00,254 WARN Task Task[LSM.publish(Application[pQCfs222].Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=MockContainer[A] @ 1452202440189);[subscription-delivery-entity-pQCfs222[Application[pQCfs222]]]:YDwtarYc] is being finalized before completion
2016-01-07 13:34:00,254 WARN Task Task[LSM.publish(Application[pQCfs222].Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=BalanceableWorkerPoolImpl{id=fVx7Jxyh} @ 1452202440189);[subscription-delivery-entity-pQCfs222[Application[pQCfs222]]]:e9GVpHIZ] is being finalized before completion
2016-01-07 13:34:00,254 WARN Task Task[LSM.publish(Application[pQCfs222].Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=DynamicGroupImpl{id=b0FqArSh} @ 1452202440189);[subscription-delivery-entity-pQCfs222[Application[pQCfs222]]]:n8KVxL9h] is being finalized before completion
2016-01-07 13:34:00,254 WARN Task Task[LSM.publish(Application[pQCfs222].Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=DynamicGroupImpl{id=Ndq7kxaN} @ 1452202440189);[subscription-delivery-entity-pQCfs222[Application[pQCfs222]]]:Q5466GQF] is being finalized before completion
2016-01-07 13:34:00,254 WARN Task Task[LSM.publish(Application[pQCfs222].Sensor: service.state.expected (org.apache.brooklyn.core.entity.lifecycle.Lifecycle$Transition)=stopped @ 1452202440189 / Thu Jan 07 13:34:00 PST 2016 @ 1452202440189);[subscription-delivery-entity-pQCfs222[Application[pQCfs222]]]:OHZdvlEx] is being finalized before completion
2016-01-07 13:34:00,281 INFO TESTNG PASSED: "Surefire test" - org.apache.brooklyn.policy.loadbalancing.LoadBalancingPolicyTest.testAddItem() finished in 62 ms
2016-01-07 13:34:00,281 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.policy.loadbalancing.AbstractLoadBalancingPolicyTest.after()
2016-01-07 13:34:00,285 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.policy.loadbalancing.AbstractLoadBalancingPolicyTest.after() finished in 4 ms
2016-01-07 13:34:00,285 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.policy.loadbalancing.AbstractLoadBalancingPolicyTest.before()
2016-01-07 13:34:00,312 WARN Deprecated use of unmanaged location (SimulatedLocation{id=R3cWmXu6, name=loc}); will be managed automatically now but not supported in future versions
2016-01-07 13:34:00,313 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.policy.loadbalancing.AbstractLoadBalancingPolicyTest.before() finished in 28 ms
2016-01-07 13:34:00,313 INFO TESTNG INVOKING: "Surefire test" - org.apache.brooklyn.policy.loadbalancing.LoadBalancingPolicyTest.testLockedItemsContributeToOverloadedMeasurements()
2016-01-07 13:34:00,320 INFO org.apache.brooklyn.policy.loadbalancing.LoadBalancingPolicy$2@28162af0 emitted COLD (suggesting 0): {pool.current.size=1, pool.current.workrate=0.0, pool.low.threshold=10.0, pool.high.threshold=50.0}
2016-01-07 13:34:00,346 INFO TESTNG PASSED: "Surefire test" - org.apache.brooklyn.policy.loadbalancing.LoadBalancingPolicyTest.testLockedItemsContributeToOverloadedMeasurements() finished in 33 ms
2016-01-07 13:34:00,346 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.policy.loadbalancing.AbstractLoadBalancingPolicyTest.after()
2016-01-07 13:34:00,349 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.policy.loadbalancing.AbstractLoadBalancingPolicyTest.after() finished in 3 ms
2016-01-07 13:34:00,349 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.policy.loadbalancing.AbstractLoadBalancingPolicyTest.before()
2016-01-07 13:34:00,376 WARN Deprecated use of unmanaged location (SimulatedLocation{id=truBX6kL, name=loc}); will be managed automatically now but not supported in future versions
2016-01-07 13:34:00,377 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.policy.loadbalancing.AbstractLoadBalancingPolicyTest.before() finished in 28 ms
2016-01-07 13:34:00,377 INFO TESTNG INVOKING: "Surefire test" - org.apache.brooklyn.policy.loadbalancing.LoadBalancingPolicyTest.testLockedItemsNotMoved()
2016-01-07 13:34:00,384 INFO org.apache.brooklyn.policy.loadbalancing.LoadBalancingPolicy$2@2a4eea6c emitted COLD (suggesting 0): {pool.current.size=1, pool.current.workrate=0.0, pool.low.threshold=10.0, pool.high.threshold=50.0}
2016-01-07 13:34:00,660 INFO TESTNG PASSED: "Surefire test" - org.apache.brooklyn.policy.loadbalancing.LoadBalancingPolicyTest.testLockedItemsNotMoved() finished in 283 ms
2016-01-07 13:34:00,660 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.policy.loadbalancing.AbstractLoadBalancingPolicyTest.after()
2016-01-07 13:34:00,665 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.policy.loadbalancing.AbstractLoadBalancingPolicyTest.after() finished in 5 ms
2016-01-07 13:34:00,665 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.policy.loadbalancing.AbstractLoadBalancingPolicyTest.before()
2016-01-07 13:34:00,692 WARN Deprecated use of unmanaged location (SimulatedLocation{id=ofvYYQsz, name=loc}); will be managed automatically now but not supported in future versions
2016-01-07 13:34:00,693 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.policy.loadbalancing.AbstractLoadBalancingPolicyTest.before() finished in 28 ms
2016-01-07 13:34:00,693 INFO TESTNG INVOKING: "Surefire test" - org.apache.brooklyn.policy.loadbalancing.LoadBalancingPolicyTest.testModelIncludesItemsAndContainersStartedBeforePolicyCreated()
2016-01-07 13:34:00,706 INFO TESTNG PASSED: "Surefire test" - org.apache.brooklyn.policy.loadbalancing.LoadBalancingPolicyTest.testModelIncludesItemsAndContainersStartedBeforePolicyCreated() finished in 13 ms
2016-01-07 13:34:00,706 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.policy.loadbalancing.AbstractLoadBalancingPolicyTest.after()
2016-01-07 13:34:00,708 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.policy.loadbalancing.AbstractLoadBalancingPolicyTest.after() finished in 2 ms
2016-01-07 13:34:00,708 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.policy.loadbalancing.AbstractLoadBalancingPolicyTest.before()
2016-01-07 13:34:00,733 WARN Deprecated use of unmanaged location (SimulatedLocation{id=V8jl59y9, name=loc}); will be managed automatically now but not supported in future versions
2016-01-07 13:34:00,734 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.policy.loadbalancing.AbstractLoadBalancingPolicyTest.before() finished in 26 ms
2016-01-07 13:34:00,734 INFO TESTNG INVOKING: "Surefire test" - org.apache.brooklyn.policy.loadbalancing.LoadBalancingPolicyTest.testMultiMoveBalancing()
2016-01-07 13:34:00,740 INFO org.apache.brooklyn.policy.loadbalancing.LoadBalancingPolicy$2@6cd987f7 emitted COLD (suggesting 0): {pool.current.size=1, pool.current.workrate=0.0, pool.low.threshold=20.0, pool.high.threshold=50.0}
2016-01-07 13:34:00,753 INFO org.apache.brooklyn.policy.loadbalancing.LoadBalancingPolicy$2@2de48dcc emitted COLD (suggesting 1): {pool.current.size=2, pool.current.workrate=10.0, pool.low.threshold=40.0, pool.high.threshold=100.0}
2016-01-07 13:34:00,765 INFO org.apache.brooklyn.policy.loadbalancing.LoadBalancingPolicy$2@4dcb7283 emitted COLD (suggesting 2): {pool.current.size=2, pool.current.workrate=30.0, pool.low.threshold=40.0, pool.high.threshold=100.0}
2016-01-07 13:34:00,803 INFO TESTNG PASSED: "Surefire test" - org.apache.brooklyn.policy.loadbalancing.LoadBalancingPolicyTest.testMultiMoveBalancing() finished in 69 ms
2016-01-07 13:34:00,803 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.policy.loadbalancing.AbstractLoadBalancingPolicyTest.after()
2016-01-07 13:34:00,806 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.policy.loadbalancing.AbstractLoadBalancingPolicyTest.after() finished in 3 ms
2016-01-07 13:34:00,806 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.policy.loadbalancing.AbstractLoadBalancingPolicyTest.before()
2016-01-07 13:34:00,831 WARN Deprecated use of unmanaged location (SimulatedLocation{id=ykEBFYcl, name=loc}); will be managed automatically now but not supported in future versions
2016-01-07 13:34:00,833 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.policy.loadbalancing.AbstractLoadBalancingPolicyTest.before() finished in 27 ms
2016-01-07 13:34:00,833 INFO TESTNG INVOKING: "Surefire test" - org.apache.brooklyn.policy.loadbalancing.LoadBalancingPolicyTest.testNoopWhenAlreadyBalanced()
2016-01-07 13:34:00,840 INFO org.apache.brooklyn.policy.loadbalancing.LoadBalancingPolicy$2@5a09e998 emitted COLD (suggesting 0): {pool.current.size=1, pool.current.workrate=0.0, pool.low.threshold=20.0, pool.high.threshold=80.0}
2016-01-07 13:34:00,851 INFO org.apache.brooklyn.policy.loadbalancing.LoadBalancingPolicy$2@1b34e3a3 emitted COLD (suggesting 1): {pool.current.size=2, pool.current.workrate=10.0, pool.low.threshold=40.0, pool.high.threshold=160.0}
2016-01-07 13:34:00,868 INFO TESTNG PASSED: "Surefire test" - org.apache.brooklyn.policy.loadbalancing.LoadBalancingPolicyTest.testNoopWhenAlreadyBalanced() finished in 35 ms
2016-01-07 13:34:00,868 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.policy.loadbalancing.AbstractLoadBalancingPolicyTest.after()
2016-01-07 13:34:00,871 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.policy.loadbalancing.AbstractLoadBalancingPolicyTest.after() finished in 3 ms
2016-01-07 13:34:00,872 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.policy.loadbalancing.AbstractLoadBalancingPolicyTest.before()
2016-01-07 13:34:00,897 WARN Deprecated use of unmanaged location (SimulatedLocation{id=lyBH5Bki, name=loc}); will be managed automatically now but not supported in future versions
2016-01-07 13:34:00,898 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.policy.loadbalancing.AbstractLoadBalancingPolicyTest.before() finished in 26 ms
2016-01-07 13:34:00,898 INFO TESTNG INVOKING: "Surefire test" - org.apache.brooklyn.policy.loadbalancing.LoadBalancingPolicyTest.testNoopWhenWithinThresholds()
2016-01-07 13:34:00,906 INFO org.apache.brooklyn.policy.loadbalancing.LoadBalancingPolicy$2@59f9253d emitted COLD (suggesting 0): {pool.current.size=1, pool.current.workrate=0.0, pool.low.threshold=10.0, pool.high.threshold=100.0}
2016-01-07 13:34:00,921 INFO org.apache.brooklyn.policy.loadbalancing.LoadBalancingPolicy$2@18c42f06 emitted COLD (suggesting 1): {pool.current.size=2, pool.current.workrate=10.0, pool.low.threshold=30.0, pool.high.threshold=160.0}
2016-01-07 13:34:00,926 INFO org.apache.brooklyn.policy.loadbalancing.LoadBalancingPolicy$2@39d68e25 emitted COLD (suggesting 2): {pool.current.size=2, pool.current.workrate=20.0, pool.low.threshold=30.0, pool.high.threshold=160.0}
2016-01-07 13:34:00,937 INFO TESTNG PASSED: "Surefire test" - org.apache.brooklyn.policy.loadbalancing.LoadBalancingPolicyTest.testNoopWhenWithinThresholds() finished in 39 ms
2016-01-07 13:34:00,937 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.policy.loadbalancing.AbstractLoadBalancingPolicyTest.after()
2016-01-07 13:34:00,941 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.policy.loadbalancing.AbstractLoadBalancingPolicyTest.after() finished in 4 ms
2016-01-07 13:34:00,941 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.policy.loadbalancing.AbstractLoadBalancingPolicyTest.before()
2016-01-07 13:34:00,970 WARN Deprecated use of unmanaged location (SimulatedLocation{id=o1YxmHxV, name=loc}); will be managed automatically now but not supported in future versions
2016-01-07 13:34:00,971 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.policy.loadbalancing.AbstractLoadBalancingPolicyTest.before() finished in 30 ms
2016-01-07 13:34:00,971 INFO TESTNG INVOKING: "Surefire test" - org.apache.brooklyn.policy.loadbalancing.LoadBalancingPolicyTest.testOverloadedLockedItemsPreventMoreWorkEnteringContainer()
2016-01-07 13:34:00,979 INFO org.apache.brooklyn.policy.loadbalancing.LoadBalancingPolicy$2@5c37f86a emitted COLD (suggesting 0): {pool.current.size=1, pool.current.workrate=0.0, pool.low.threshold=10.0, pool.high.threshold=50.0}
2016-01-07 13:34:01,004 INFO org.apache.brooklyn.policy.loadbalancing.LoadBalancingPolicy$2@3777f9db emitted HOT (suggesting 3): {pool.current.size=2, pool.current.workrate=110.0, pool.low.threshold=20.0, pool.high.threshold=100.0}
2016-01-07 13:34:01,266 INFO TESTNG PASSED: "Surefire test" - org.apache.brooklyn.policy.loadbalancing.LoadBalancingPolicyTest.testOverloadedLockedItemsPreventMoreWorkEnteringContainer() finished in 295 ms
2016-01-07 13:34:01,266 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.policy.loadbalancing.AbstractLoadBalancingPolicyTest.after()
2016-01-07 13:34:01,271 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.policy.loadbalancing.AbstractLoadBalancingPolicyTest.after() finished in 5 ms
2016-01-07 13:34:01,272 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.policy.loadbalancing.AbstractLoadBalancingPolicyTest.before()
2016-01-07 13:34:01,298 WARN Deprecated use of unmanaged location (SimulatedLocation{id=uj9vBbGs, name=loc}); will be managed automatically now but not supported in future versions
2016-01-07 13:34:01,299 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.policy.loadbalancing.AbstractLoadBalancingPolicyTest.before() finished in 27 ms
2016-01-07 13:34:01,299 INFO TESTNG INVOKING: "Surefire test" - org.apache.brooklyn.policy.loadbalancing.LoadBalancingPolicyTest.testPolicyUpdatesModel()
2016-01-07 13:34:01,307 INFO org.apache.brooklyn.policy.loadbalancing.LoadBalancingPolicy$2@3c1cef7f emitted COLD (suggesting 0): {pool.current.size=1, pool.current.workrate=0.0, pool.low.threshold=10.0, pool.high.threshold=20.0}
2016-01-07 13:34:01,319 INFO org.apache.brooklyn.policy.loadbalancing.LoadBalancingPolicy$2@7d21e544 emitted COLD (suggesting 2): {pool.current.size=2, pool.current.workrate=12.0, pool.low.threshold=21.0, pool.high.threshold=41.0}
2016-01-07 13:34:01,325 INFO TESTNG PASSED: "Surefire test" - org.apache.brooklyn.policy.loadbalancing.LoadBalancingPolicyTest.testPolicyUpdatesModel() finished in 26 ms
2016-01-07 13:34:01,325 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.policy.loadbalancing.AbstractLoadBalancingPolicyTest.after()
2016-01-07 13:34:01,329 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.policy.loadbalancing.AbstractLoadBalancingPolicyTest.after() finished in 4 ms
2016-01-07 13:34:01,329 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.policy.loadbalancing.AbstractLoadBalancingPolicyTest.before()
2016-01-07 13:34:01,355 WARN Deprecated use of unmanaged location (SimulatedLocation{id=GKbzlF6A, name=loc}); will be managed automatically now but not supported in future versions
2016-01-07 13:34:01,356 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.policy.loadbalancing.AbstractLoadBalancingPolicyTest.before() finished in 27 ms
2016-01-07 13:34:01,356 INFO TESTNG INVOKING: "Surefire test" - org.apache.brooklyn.policy.loadbalancing.LoadBalancingPolicyTest.testRebalanceWhenWorkratesChange()
2016-01-07 13:34:01,363 INFO org.apache.brooklyn.policy.loadbalancing.LoadBalancingPolicy$2@12289f12 emitted COLD (suggesting 0): {pool.current.size=1, pool.current.workrate=0.0, pool.low.threshold=10.0, pool.high.threshold=50.0}
SUREFIRE-859: [GC (Allocation Failure) 251348K->38188K(762880K), 0.0134029 secs]
2016-01-07 13:34:01,382 WARN Task Task[LSM.publish(Application[Lhqlw5nH].Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=MockItem[3] @ 1452202440804);[subscription-delivery-entity-Lhqlw5nH[Application[Lhqlw5nH]]]:K2itDZiI] is being finalized before completion
2016-01-07 13:34:01,382 WARN Task Task[LSM.publish(Application[Lhqlw5nH].Sensor: service.state.expected (org.apache.brooklyn.core.entity.lifecycle.Lifecycle$Transition)=stopped @ 1452202440804 / Thu Jan 07 13:34:00 PST 2016 @ 1452202440804);[subscription-delivery-entity-Lhqlw5nH[Application[Lhqlw5nH]]]:wnP74ZlT] is being finalized before completion
2016-01-07 13:34:01,382 WARN Task Task[LSM.publish(Application[Lhqlw5nH].Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=DynamicGroupImpl{id=hNtQUBav} @ 1452202440804);[subscription-delivery-entity-Lhqlw5nH[Application[Lhqlw5nH]]]:KgCppzaa] is being finalized before completion
2016-01-07 13:34:01,382 WARN Task Task[LSM.publish(Application[Lhqlw5nH].Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=DynamicGroupImpl{id=ouKhR6nu} @ 1452202440804);[subscription-delivery-entity-Lhqlw5nH[Application[Lhqlw5nH]]]:caHOc9PK] is being finalized before completion
2016-01-07 13:34:01,383 WARN Task Task[LSM.publish(Application[Lhqlw5nH].Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=BalanceableWorkerPoolImpl{id=y1ilLbuF} @ 1452202440804);[subscription-delivery-entity-Lhqlw5nH[Application[Lhqlw5nH]]]:QMgfFOYI] is being finalized before completion
2016-01-07 13:34:01,383 WARN Task Task[LSM.publish(Application[Lhqlw5nH].Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=MockContainer[A] @ 1452202440804);[subscription-delivery-entity-Lhqlw5nH[Application[Lhqlw5nH]]]:e9ryMAwU] is being finalized before completion
2016-01-07 13:34:01,383 WARN Task Task[LSM.publish(Application[Lhqlw5nH].Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=MockContainer[B] @ 1452202440804);[subscription-delivery-entity-Lhqlw5nH[Application[Lhqlw5nH]]]:Xrxiuo07] is being finalized before completion
2016-01-07 13:34:01,383 WARN Task Task[LSM.publish(Application[Lhqlw5nH].Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=MockItem[1] @ 1452202440804);[subscription-delivery-entity-Lhqlw5nH[Application[Lhqlw5nH]]]:llHjv8hz] is being finalized before completion
2016-01-07 13:34:01,383 WARN Task Task[LSM.publish(Application[Lhqlw5nH].Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=MockItem[2] @ 1452202440804);[subscription-delivery-entity-Lhqlw5nH[Application[Lhqlw5nH]]]:lAidAqR8] is being finalized before completion
2016-01-07 13:34:01,383 WARN Task Task[LSM.publish(Application[Lhqlw5nH].Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=MockItem[4] @ 1452202440804);[subscription-delivery-entity-Lhqlw5nH[Application[Lhqlw5nH]]]:YqDVYEde] is being finalized before completion
2016-01-07 13:34:01,383 WARN Task Task[LSM.publish(Application[Lhqlw5nH].Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=MockItem[5] @ 1452202440805);[subscription-delivery-entity-Lhqlw5nH[Application[Lhqlw5nH]]]:vfgeAlU6] is being finalized before completion
2016-01-07 13:34:01,383 WARN Task Task[LSM.publish(Application[Lhqlw5nH].Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=MockItem[6] @ 1452202440805);[subscription-delivery-entity-Lhqlw5nH[Application[Lhqlw5nH]]]:kp49dOIn] is being finalized before completion
2016-01-07 13:34:01,383 WARN Task Task[LSM.publish(Application[Lhqlw5nH].Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=MockItem[7] @ 1452202440805);[subscription-delivery-entity-Lhqlw5nH[Application[Lhqlw5nH]]]:TyHFuLll] is being finalized before completion
2016-01-07 13:34:01,383 WARN Task Task[LSM.publish(Application[Lhqlw5nH].Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=MockItem[8] @ 1452202440805);[subscription-delivery-entity-Lhqlw5nH[Application[Lhqlw5nH]]]:LRr7JgSj] is being finalized before completion
2016-01-07 13:34:01,383 WARN Task Task[LSM.publish(Application[Lhqlw5nH].Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=MockItem[9] @ 1452202440805);[subscription-delivery-entity-Lhqlw5nH[Application[Lhqlw5nH]]]:TrO3ngZw] is being finalized before completion
2016-01-07 13:34:01,383 WARN Task Task[LSM.publish(Application[Lhqlw5nH].Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=MockItem[10] @ 1452202440805);[subscription-delivery-entity-Lhqlw5nH[Application[Lhqlw5nH]]]:CHoT5qpu] is being finalized before completion
2016-01-07 13:34:01,383 WARN Task Task[LSM.publish(Application[xEwZE6xV].Sensor: service.state.expected (org.apache.brooklyn.core.entity.lifecycle.Lifecycle$Transition)=stopped @ 1452202440663 / Thu Jan 07 13:34:00 PST 2016 @ 1452202440663);[subscription-delivery-entity-xEwZE6xV[Application[xEwZE6xV]]]:TZtolrOV] is being finalized before completion
2016-01-07 13:34:01,383 WARN Task Task[LSM.publish(Application[xEwZE6xV].Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=DynamicGroupImpl{id=TpaIYu9H} @ 1452202440663);[subscription-delivery-entity-xEwZE6xV[Application[xEwZE6xV]]]:cVJd7ZZ2] is being finalized before completion
2016-01-07 13:34:01,384 WARN Task Task[LSM.publish(Application[xEwZE6xV].Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=DynamicGroupImpl{id=Yy6J0Nyc} @ 1452202440664);[subscription-delivery-entity-xEwZE6xV[Application[xEwZE6xV]]]:cClOwLDt] is being finalized before completion
2016-01-07 13:34:01,384 WARN Task Task[LSM.publish(Application[xEwZE6xV].Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=BalanceableWorkerPoolImpl{id=tflA01RG} @ 1452202440664);[subscription-delivery-entity-xEwZE6xV[Application[xEwZE6xV]]]:IYAxPVip] is being finalized before completion
2016-01-07 13:34:01,384 WARN Task Task[LSM.publish(Application[xEwZE6xV].Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=MockContainer[A] @ 1452202440664);[subscription-delivery-entity-xEwZE6xV[Application[xEwZE6xV]]]:MfZHoML3] is being finalized before completion
2016-01-07 13:34:01,384 WARN Task Task[LSM.publish(Application[xEwZE6xV].Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=MockContainer[B] @ 1452202440664);[subscription-delivery-entity-xEwZE6xV[Application[xEwZE6xV]]]:rojLXsv5] is being finalized before completion
2016-01-07 13:34:01,384 WARN Task Task[LSM.publish(Application[xEwZE6xV].Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=MockItem[1] @ 1452202440664);[subscription-delivery-entity-xEwZE6xV[Application[xEwZE6xV]]]:D7TMqYJn] is being finalized before completion
2016-01-07 13:34:01,384 WARN Task Task[LSM.publish(Application[xEwZE6xV].Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=MockItem[2] @ 1452202440664);[subscription-delivery-entity-xEwZE6xV[Application[xEwZE6xV]]]:n5KaXiqO] is being finalized before completion
2016-01-07 13:34:01,384 WARN Task Task[LSM.publish(Application[eDVNUpTr].Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=MockItem[6] @ 1452202440283);[subscription-delivery-entity-eDVNUpTr[Application[eDVNUpTr]]]:GM3Vmw39] is being finalized before completion
2016-01-07 13:34:01,384 WARN Task Task[LSM.publish(Application[eDVNUpTr].Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=MockItem[7] @ 1452202440283);[subscription-delivery-entity-eDVNUpTr[Application[eDVNUpTr]]]:tg9fMlcd] is being finalized before completion
2016-01-07 13:34:01,384 WARN Task Task[LSM.publish(Application[JkC6yN2H].Sensor: service.state.expected (org.apache.brooklyn.core.entity.lifecycle.Lifecycle$Transition)=stopped @ 1452202440347 / Thu Jan 07 13:34:00 PST 2016 @ 1452202440348);[subscription-delivery-entity-JkC6yN2H[Application[JkC6yN2H]]]:ph7Ot2fQ] is being finalized before completion
2016-01-07 13:34:01,384 WARN Task Task[LSM.publish(Application[JkC6yN2H].Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=DynamicGroupImpl{id=Ui7b8mug} @ 1452202440348);[subscription-delivery-entity-JkC6yN2H[Application[JkC6yN2H]]]:co7tIJyD] is being finalized before completion
2016-01-07 13:34:01,384 WARN Task Task[LSM.publish(Application[JkC6yN2H].Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=DynamicGroupImpl{id=sEHLTVDJ} @ 1452202440348);[subscription-delivery-entity-JkC6yN2H[Application[JkC6yN2H]]]:DnyuZI6h] is being finalized before completion
2016-01-07 13:34:01,384 WARN Task Task[LSM.publish(Application[JkC6yN2H].Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=BalanceableWorkerPoolImpl{id=xjg23kMc} @ 1452202440348);[subscription-delivery-entity-JkC6yN2H[Application[JkC6yN2H]]]:YAsMQobT] is being finalized before completion
2016-01-07 13:34:01,384 WARN Task Task[LSM.publish(Application[JkC6yN2H].Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=MockContainer[A] @ 1452202440348);[subscription-delivery-entity-JkC6yN2H[Application[JkC6yN2H]]]:QnSIV75K] is being finalized before completion
2016-01-07 13:34:01,384 WARN Task Task[LSM.publish(Application[JkC6yN2H].Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=MockContainer[B] @ 1452202440348);[subscription-delivery-entity-JkC6yN2H[Application[JkC6yN2H]]]:S5icSW0P] is being finalized before completion
2016-01-07 13:34:01,384 WARN Task Task[LSM.publish(Application[JkC6yN2H].Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=MockItem[1] @ 1452202440348);[subscription-delivery-entity-JkC6yN2H[Application[JkC6yN2H]]]:t3RjiSvB] is being finalized before completion
2016-01-07 13:34:01,384 WARN Task Task[LSM.publish(Application[JkC6yN2H].Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=MockItem[2] @ 1452202440348);[subscription-delivery-entity-JkC6yN2H[Application[JkC6yN2H]]]:F5V5GzsP] is being finalized before completion
2016-01-07 13:34:01,384 WARN Task Task[LSM.publish(Application[JkC6yN2H].Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=MockItem[3] @ 1452202440348);[subscription-delivery-entity-JkC6yN2H[Application[JkC6yN2H]]]:eWTWlOer] is being finalized before completion
2016-01-07 13:34:01,384 WARN Task Task[LSM.publish(Application[eDVNUpTr].Sensor: service.state.expected (org.apache.brooklyn.core.entity.lifecycle.Lifecycle$Transition)=stopped @ 1452202440283 / Thu Jan 07 13:34:00 PST 2016 @ 1452202440283);[subscription-delivery-entity-eDVNUpTr[Application[eDVNUpTr]]]:p1D9mJcZ] is being finalized before completion
2016-01-07 13:34:01,384 WARN Task Task[LSM.publish(Application[eDVNUpTr].Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=DynamicGroupImpl{id=hY6tbVgi} @ 1452202440283);[subscription-delivery-entity-eDVNUpTr[Application[eDVNUpTr]]]:UUM8HNIr] is being finalized before completion
2016-01-07 13:34:01,384 WARN Task Task[LSM.publish(Application[eDVNUpTr].Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=DynamicGroupImpl{id=sOqdGp9Q} @ 1452202440283);[subscription-delivery-entity-eDVNUpTr[Application[eDVNUpTr]]]:Njkzvwg6] is being finalized before completion
2016-01-07 13:34:01,384 WARN Task Task[LSM.publish(Application[eDVNUpTr].Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=BalanceableWorkerPoolImpl{id=CuBrpew3} @ 1452202440283);[subscription-delivery-entity-eDVNUpTr[Application[eDVNUpTr]]]:VDtUm7Qz] is being finalized before completion
2016-01-07 13:34:01,384 WARN Task Task[LSM.publish(Application[eDVNUpTr].Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=MockContainer[A] @ 1452202440283);[subscription-delivery-entity-eDVNUpTr[Application[eDVNUpTr]]]:oBecqaeS] is being finalized before completion
2016-01-07 13:34:01,384 WARN Task Task[LSM.publish(Application[eDVNUpTr].Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=MockContainer[B] @ 1452202440283);[subscription-delivery-entity-eDVNUpTr[Application[eDVNUpTr]]]:NIzvE99G] is being finalized before completion
2016-01-07 13:34:01,384 WARN Task Task[LSM.publish(Application[eDVNUpTr].Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=MockItem[1] @ 1452202440283);[subscription-delivery-entity-eDVNUpTr[Application[eDVNUpTr]]]:v8rkbXG8] is being finalized before completion
2016-01-07 13:34:01,384 WARN Task Task[LSM.publish(Application[eDVNUpTr].Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=MockItem[2] @ 1452202440283);[subscription-delivery-entity-eDVNUpTr[Application[eDVNUpTr]]]:cWYCNUoa] is being finalized before completion
2016-01-07 13:34:01,384 WARN Task Task[LSM.publish(Application[eDVNUpTr].Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=MockItem[3] @ 1452202440283);[subscription-delivery-entity-eDVNUpTr[Application[eDVNUpTr]]]:ZLCsrH1C] is being finalized before completion
2016-01-07 13:34:01,384 WARN Task Task[LSM.publish(Application[eDVNUpTr].Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=MockItem[4] @ 1452202440283);[subscription-delivery-entity-eDVNUpTr[Application[eDVNUpTr]]]:evDxb3c1] is being finalized before completion
2016-01-07 13:34:01,384 WARN Task Task[LSM.publish(Application[eDVNUpTr].Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=MockItem[5] @ 1452202440283);[subscription-delivery-entity-eDVNUpTr[Application[eDVNUpTr]]]:nLmZ38PI] is being finalized before completion
2016-01-07 13:34:01,385 WARN Task Task[LSM.publish(Application[igKyZhRI].Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=MockItem[4] @ 1452202440870);[subscription-delivery-entity-igKyZhRI[Application[igKyZhRI]]]:Ei2EofY0] is being finalized before completion
2016-01-07 13:34:01,385 WARN Task Task[LSM.publish(Application[igKyZhRI].Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=MockItem[3] @ 1452202440870);[subscription-delivery-entity-igKyZhRI[Application[igKyZhRI]]]:zt8fX5iH] is being finalized before completion
2016-01-07 13:34:01,385 WARN Task Task[LSM.publish(Application[igKyZhRI].Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=MockItem[2] @ 1452202440870);[subscription-delivery-entity-igKyZhRI[Application[igKyZhRI]]]:Nfxr0VVO] is being finalized before completion
2016-01-07 13:34:01,385 WARN Task Task[LSM.publish(Application[igKyZhRI].Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=MockItem[1] @ 1452202440870);[subscription-delivery-entity-igKyZhRI[Application[igKyZhRI]]]:ert5lY2M] is being finalized before completion
2016-01-07 13:34:01,385 WARN Task Task[LSM.publish(Application[igKyZhRI].Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=MockContainer[B] @ 1452202440870);[subscription-delivery-entity-igKyZhRI[Application[igKyZhRI]]]:UZGm2her] is being finalized before completion
2016-01-07 13:34:01,385 WARN Task Task[LSM.publish(Application[igKyZhRI].Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=MockContainer[A] @ 1452202440870);[subscription-delivery-entity-igKyZhRI[Application[igKyZhRI]]]:p28HO56A] is being finalized before completion
2016-01-07 13:34:01,385 WARN Task Task[LSM.publish(Application[igKyZhRI].Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=BalanceableWorkerPoolImpl{id=A4rV4DEF} @ 1452202440870);[subscription-delivery-entity-igKyZhRI[Application[igKyZhRI]]]:UrkH7gHe] is being finalized before completion
2016-01-07 13:34:01,385 WARN Task Task[LSM.publish(Application[igKyZhRI].Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=DynamicGroupImpl{id=Wx6A6hp5} @ 1452202440870);[subscription-delivery-entity-igKyZhRI[Application[igKyZhRI]]]:ZSQi19fv] is being finalized before completion
2016-01-07 13:34:01,385 WARN Task Task[LSM.publish(Application[igKyZhRI].Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=DynamicGroupImpl{id=OF1Q3PuQ} @ 1452202440870);[subscription-delivery-entity-igKyZhRI[Application[igKyZhRI]]]:XJEYhncl] is being finalized before completion
2016-01-07 13:34:01,385 WARN Task Task[LSM.publish(Application[igKyZhRI].Sensor: service.state.expected (org.apache.brooklyn.core.entity.lifecycle.Lifecycle$Transition)=stopped @ 1452202440870 / Thu Jan 07 13:34:00 PST 2016 @ 1452202440870);[subscription-delivery-entity-igKyZhRI[Application[igKyZhRI]]]:N5F7ysqB] is being finalized before completion
2016-01-07 13:34:01,385 WARN Task Task[LSM.publish(Application[ntZ0qbHO].Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=MockItem[2] @ 1452202441327);[subscription-delivery-entity-ntZ0qbHO[Application[ntZ0qbHO]]]:x5GUhoLO] is being finalized before completion
2016-01-07 13:34:01,385 WARN Task Task[LSM.publish(Application[ntZ0qbHO].Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=MockItem[1] @ 1452202441327);[subscription-delivery-entity-ntZ0qbHO[Application[ntZ0qbHO]]]:vaZ6TCiN] is being finalized before completion
2016-01-07 13:34:01,385 WARN Task Task[LSM.publish(Application[ntZ0qbHO].Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=MockContainer[B] @ 1452202441327);[subscription-delivery-entity-ntZ0qbHO[Application[ntZ0qbHO]]]:ar9ilSYp] is being finalized before completion
2016-01-07 13:34:01,385 WARN Task Task[LSM.publish(Application[ntZ0qbHO].Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=MockContainer[A] @ 1452202441327);[subscription-delivery-entity-ntZ0qbHO[Application[ntZ0qbHO]]]:Cyy1QbcN] is being finalized before completion
2016-01-07 13:34:01,385 WARN Task Task[LSM.publish(Application[ntZ0qbHO].Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=BalanceableWorkerPoolImpl{id=gnvBZA9L} @ 1452202441327);[subscription-delivery-entity-ntZ0qbHO[Application[ntZ0qbHO]]]:z2lSvnoN] is being finalized before completion
2016-01-07 13:34:01,385 WARN Task Task[LSM.publish(Application[ntZ0qbHO].Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=DynamicGroupImpl{id=kghMrcaS} @ 1452202441327);[subscription-delivery-entity-ntZ0qbHO[Application[ntZ0qbHO]]]:jKC4H6hX] is being finalized before completion
2016-01-07 13:34:01,385 WARN Task Task[LSM.publish(Application[ntZ0qbHO].Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=DynamicGroupImpl{id=UPdNLNcJ} @ 1452202441327);[subscription-delivery-entity-ntZ0qbHO[Application[ntZ0qbHO]]]:Y4HCYZuu] is being finalized before completion
2016-01-07 13:34:01,385 WARN Task Task[LSM.publish(Application[ntZ0qbHO].Sensor: service.state.expected (org.apache.brooklyn.core.entity.lifecycle.Lifecycle$Transition)=stopped @ 1452202441327 / Thu Jan 07 13:34:01 PST 2016 @ 1452202441327);[subscription-delivery-entity-ntZ0qbHO[Application[ntZ0qbHO]]]:QVybNJbu] is being finalized before completion
2016-01-07 13:34:01,386 WARN Task Task[LSM.publish(Application[lMC1WVwi].Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=MockItem[3] @ 1452202441270);[subscription-delivery-entity-lMC1WVwi[Application[lMC1WVwi]]]:s5ibvLbT] is being finalized before completion
2016-01-07 13:34:01,386 WARN Task Task[LSM.publish(Application[lMC1WVwi].Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=MockItem[2] @ 1452202441270);[subscription-delivery-entity-lMC1WVwi[Application[lMC1WVwi]]]:vv42K3ec] is being finalized before completion
2016-01-07 13:34:01,386 WARN Task Task[LSM.publish(Application[lMC1WVwi].Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=MockItem[1] @ 1452202441270);[subscription-delivery-entity-lMC1WVwi[Application[lMC1WVwi]]]:lmx2aKMK] is being finalized before completion
2016-01-07 13:34:01,386 WARN Task Task[LSM.publish(Application[lMC1WVwi].Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=MockContainer[B] @ 1452202441270);[subscription-delivery-entity-lMC1WVwi[Application[lMC1WVwi]]]:pBftB8ug] is being finalized before completion
2016-01-07 13:34:01,386 WARN Task Task[LSM.publish(Application[lMC1WVwi].Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=MockContainer[A] @ 1452202441270);[subscription-delivery-entity-lMC1WVwi[Application[lMC1WVwi]]]:ulfp5rN0] is being finalized before completion
2016-01-07 13:34:01,386 WARN Task Task[LSM.publish(Application[lMC1WVwi].Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=BalanceableWorkerPoolImpl{id=eoAuynep} @ 1452202441269);[subscription-delivery-entity-lMC1WVwi[Application[lMC1WVwi]]]:G81gJJ3i] is being finalized before completion
2016-01-07 13:34:01,386 WARN Task Task[LSM.publish(Application[lMC1WVwi].Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=DynamicGroupImpl{id=Pj10oUgq} @ 1452202441269);[subscription-delivery-entity-lMC1WVwi[Application[lMC1WVwi]]]:zDcX88Q3] is being finalized before completion
2016-01-07 13:34:01,386 WARN Task Task[LSM.publish(Application[lMC1WVwi].Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=DynamicGroupImpl{id=nq9dLpjp} @ 1452202441269);[subscription-delivery-entity-lMC1WVwi[Application[lMC1WVwi]]]:mUzonFqP] is being finalized before completion
2016-01-07 13:34:01,386 WARN Task Task[LSM.publish(Application[lMC1WVwi].Sensor: service.state.expected (org.apache.brooklyn.core.entity.lifecycle.Lifecycle$Transition)=stopped @ 1452202441269 / Thu Jan 07 13:34:01 PST 2016 @ 1452202441269);[subscription-delivery-entity-lMC1WVwi[Application[lMC1WVwi]]]:qswUmGxi] is being finalized before completion
2016-01-07 13:34:01,396 INFO TESTNG PASSED: "Surefire test" - org.apache.brooklyn.policy.loadbalancing.LoadBalancingPolicyTest.testRebalanceWhenWorkratesChange() finished in 40 ms
2016-01-07 13:34:01,396 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.policy.loadbalancing.AbstractLoadBalancingPolicyTest.after()
2016-01-07 13:34:01,399 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.policy.loadbalancing.AbstractLoadBalancingPolicyTest.after() finished in 3 ms
2016-01-07 13:34:01,399 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.policy.loadbalancing.AbstractLoadBalancingPolicyTest.before()
2016-01-07 13:34:01,425 WARN Deprecated use of unmanaged location (SimulatedLocation{id=Uyz8NRzv, name=loc}); will be managed automatically now but not supported in future versions
2016-01-07 13:34:01,426 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.policy.loadbalancing.AbstractLoadBalancingPolicyTest.before() finished in 27 ms
2016-01-07 13:34:01,426 INFO TESTNG INVOKING: "Surefire test" - org.apache.brooklyn.policy.loadbalancing.LoadBalancingPolicyTest.testRebalancesAfterManualMove()
2016-01-07 13:34:01,434 INFO org.apache.brooklyn.policy.loadbalancing.LoadBalancingPolicy$2@213be8d5 emitted COLD (suggesting 0): {pool.current.size=1, pool.current.workrate=0.0, pool.low.threshold=10.0, pool.high.threshold=50.0}
2016-01-07 13:34:01,467 INFO TESTNG PASSED: "Surefire test" - org.apache.brooklyn.policy.loadbalancing.LoadBalancingPolicyTest.testRebalancesAfterManualMove() finished in 41 ms
2016-01-07 13:34:01,467 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.policy.loadbalancing.AbstractLoadBalancingPolicyTest.after()
2016-01-07 13:34:01,471 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.policy.loadbalancing.AbstractLoadBalancingPolicyTest.after() finished in 4 ms
2016-01-07 13:34:01,472 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.policy.loadbalancing.AbstractLoadBalancingPolicyTest.before()
2016-01-07 13:34:01,499 WARN Deprecated use of unmanaged location (SimulatedLocation{id=aK0y1Jgj, name=loc}); will be managed automatically now but not supported in future versions
2016-01-07 13:34:01,500 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.policy.loadbalancing.AbstractLoadBalancingPolicyTest.before() finished in 28 ms
2016-01-07 13:34:01,500 INFO TESTNG INVOKING: "Surefire test" - org.apache.brooklyn.policy.loadbalancing.LoadBalancingPolicyTest.testRemoveItemCausesRebalancing()
2016-01-07 13:34:01,509 INFO org.apache.brooklyn.policy.loadbalancing.LoadBalancingPolicy$2@2be1609 emitted COLD (suggesting 0): {pool.current.size=1, pool.current.workrate=0.0, pool.low.threshold=10.0, pool.high.threshold=30.0}
2016-01-07 13:34:01,534 INFO TESTNG PASSED: "Surefire test" - org.apache.brooklyn.policy.loadbalancing.LoadBalancingPolicyTest.testRemoveItemCausesRebalancing() finished in 34 ms
2016-01-07 13:34:01,535 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.policy.loadbalancing.AbstractLoadBalancingPolicyTest.after()
2016-01-07 13:34:01,540 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.policy.loadbalancing.AbstractLoadBalancingPolicyTest.after() finished in 5 ms
2016-01-07 13:34:01,540 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.policy.loadbalancing.AbstractLoadBalancingPolicyTest.before()
2016-01-07 13:34:01,567 WARN Deprecated use of unmanaged location (SimulatedLocation{id=oZUqlN5C, name=loc}); will be managed automatically now but not supported in future versions
2016-01-07 13:34:01,568 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.policy.loadbalancing.AbstractLoadBalancingPolicyTest.before() finished in 28 ms
2016-01-07 13:34:01,568 INFO TESTNG INVOKING: "Surefire test" - org.apache.brooklyn.policy.loadbalancing.LoadBalancingPolicyTest.testSimpleBalancing()
2016-01-07 13:34:01,576 INFO org.apache.brooklyn.policy.loadbalancing.LoadBalancingPolicy$2@58810205 emitted COLD (suggesting 0): {pool.current.size=1, pool.current.workrate=0.0, pool.low.threshold=10.0, pool.high.threshold=25.0}
2016-01-07 13:34:01,589 INFO org.apache.brooklyn.policy.loadbalancing.LoadBalancingPolicy$2@782e76ba emitted COLD (suggesting 1): {pool.current.size=2, pool.current.workrate=10.0, pool.low.threshold=30.0, pool.high.threshold=85.0}
2016-01-07 13:34:01,595 INFO org.apache.brooklyn.policy.loadbalancing.LoadBalancingPolicy$2@97f2a5 emitted COLD (suggesting 2): {pool.current.size=2, pool.current.workrate=20.0, pool.low.threshold=30.0, pool.high.threshold=85.0}
2016-01-07 13:34:01,610 INFO TESTNG PASSED: "Surefire test" - org.apache.brooklyn.policy.loadbalancing.LoadBalancingPolicyTest.testSimpleBalancing() finished in 42 ms
2016-01-07 13:34:01,610 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.policy.loadbalancing.AbstractLoadBalancingPolicyTest.after()
2016-01-07 13:34:01,613 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.policy.loadbalancing.AbstractLoadBalancingPolicyTest.after() finished in 3 ms
2016-01-07 13:34:01,613 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.policy.loadbalancing.AbstractLoadBalancingPolicyTest.before()
2016-01-07 13:34:01,642 WARN Deprecated use of unmanaged location (SimulatedLocation{id=VE0dn9Ew, name=loc}); will be managed automatically now but not supported in future versions
2016-01-07 13:34:01,643 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.policy.loadbalancing.AbstractLoadBalancingPolicyTest.before() finished in 30 ms
2016-01-07 13:34:01,643 INFO TESTNG INVOKING: "Surefire test" - org.apache.brooklyn.policy.loadbalancing.LoadBalancingPolicyTest.testSimpleBalancing2()
2016-01-07 13:34:01,650 INFO org.apache.brooklyn.policy.loadbalancing.LoadBalancingPolicy$2@7a6085dc emitted COLD (suggesting 0): {pool.current.size=1, pool.current.workrate=0.0, pool.low.threshold=20.0, pool.high.threshold=40.0}
2016-01-07 13:34:01,685 INFO TESTNG PASSED: "Surefire test" - org.apache.brooklyn.policy.loadbalancing.LoadBalancingPolicyTest.testSimpleBalancing2() finished in 42 ms
2016-01-07 13:34:01,685 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.policy.loadbalancing.AbstractLoadBalancingPolicyTest.after()
2016-01-07 13:34:01,690 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.policy.loadbalancing.AbstractLoadBalancingPolicyTest.after() finished in 5 ms
2016-01-07 13:34:01,717 INFO TESTNG
===============================================
Surefire test
Tests run: 231, Failures: 3, Skips: 0
===============================================
2016-01-07 13:34:01,717 INFO BrooklynLeakListener.onFinish attempting to terminate all extant ManagementContexts: name=Surefire test; includedGroups=[]; excludedGroups=[Integration, Acceptance, Live, WIP, Broken]; suiteName=Surefire suite; outDir=/Users/davidx/code/incubator-brooklyn/brooklyn-server/policy/target/surefire-reports/Surefire suite: no org.apache.brooklyn.core.management.internal.LocalManagementContext available, so skipped
Tests run: 231, Failures: 3, Errors: 0, Skipped: 0, Time elapsed: 125.248 sec <<< FAILURE! - in TestSuite
testNotifiedOfFailure(org.apache.brooklyn.policy.ha.ConnectionFailureDetectorTest) Time elapsed: 30.008 sec <<< FAILURE!
java.lang.AssertionError: No matching Sensor: ha.connectionFailed (org.apache.brooklyn.policy.ha.HASensors$FailureDescriptor) event found; events=[]
at org.testng.Assert.fail(Assert.java:94)
at org.apache.brooklyn.policy.ha.ConnectionFailureDetectorTest.assertHasEvent(ConnectionFailureDetectorTest.java:279)
at org.apache.brooklyn.policy.ha.ConnectionFailureDetectorTest.access$100(ConnectionFailureDetectorTest.java:53)
testNotifiedOfRecovery(org.apache.brooklyn.policy.ha.ConnectionFailureDetectorTest) Time elapsed: 30.006 sec <<< FAILURE!
java.lang.AssertionError: No matching Sensor: ha.connectionFailed (org.apache.brooklyn.policy.ha.HASensors$FailureDescriptor) event found; events=[]
at org.testng.Assert.fail(Assert.java:94)
at org.apache.brooklyn.policy.ha.ConnectionFailureDetectorTest.assertHasEvent(ConnectionFailureDetectorTest.java:279)
at org.apache.brooklyn.policy.ha.ConnectionFailureDetectorTest.access$100(ConnectionFailureDetectorTest.java:53)
testReportsFailureWhenAlreadyDownOnRegisteringPolicy(org.apache.brooklyn.policy.ha.ConnectionFailureDetectorTest) Time elapsed: 30.005 sec <<< FAILURE!
java.lang.AssertionError: No matching Sensor: ha.connectionFailed (org.apache.brooklyn.policy.ha.HASensors$FailureDescriptor) event found; events=[]
at org.testng.Assert.fail(Assert.java:94)
at org.apache.brooklyn.policy.ha.ConnectionFailureDetectorTest.assertHasEvent(ConnectionFailureDetectorTest.java:279)
at org.apache.brooklyn.policy.ha.ConnectionFailureDetectorTest.access$100(ConnectionFailureDetectorTest.java:53)
Results :
Failed tests:
ConnectionFailureDetectorTest.testNotifiedOfFailure:138->assertHasEventEventually:283->access$100:53->assertHasEvent:279 No matching Sensor: ha.connectionFailed (org.apache.brooklyn.policy.ha.HASensors$FailureDescriptor) event found; events=[]
ConnectionFailureDetectorTest.testNotifiedOfRecovery:148->assertHasEventEventually:283->access$100:53->assertHasEvent:279 No matching Sensor: ha.connectionFailed (org.apache.brooklyn.policy.ha.HASensors$FailureDescriptor) event found; events=[]
ConnectionFailureDetectorTest.testReportsFailureWhenAlreadyDownOnRegisteringPolicy:163->assertHasEventEventually:283->access$100:53->assertHasEvent:279 No matching Sensor: ha.connectionFailed (org.apache.brooklyn.policy.ha.HASensors$FailureDescriptor) event found; events=[]
Tests run: 231, Failures: 3, Errors: 0, Skipped: 0
[INFO] ------------------------------------------------------------------------
[INFO] Reactor Summary:
[INFO]
[INFO] Brooklyn REST JavaScript Web GUI ................... SUCCESS [ 12.486 s]
[INFO] Brooklyn Server Root ............................... SUCCESS [ 0.115 s]
[INFO] Brooklyn Parent Project ............................ SUCCESS [ 1.445 s]
[INFO] Brooklyn Test Support Utilities .................... SUCCESS [ 2.234 s]
[INFO] Brooklyn Logback Includable Configuration .......... SUCCESS [ 0.317 s]
[INFO] Brooklyn Common Utilities .......................... SUCCESS [ 8.722 s]
[INFO] Brooklyn API ....................................... SUCCESS [ 0.868 s]
[INFO] CAMP Server Parent Project ......................... SUCCESS [ 0.101 s]
[INFO] CAMP Base .......................................... SUCCESS [ 1.533 s]
[INFO] Brooklyn Test Support .............................. SUCCESS [ 0.801 s]
[INFO] Brooklyn REST Swagger Apidoc Utilities ............. SUCCESS [ 3.614 s]
[INFO] Brooklyn Logback Configuration ..................... SUCCESS [ 0.178 s]
[INFO] CAMP Server ........................................ SUCCESS [ 3.254 s]
[INFO] Brooklyn OSGi Utils ................................ SUCCESS [ 1.112 s]
[INFO] Brooklyn Felix Runtime ............................. SUCCESS [ 1.556 s]
[INFO] Brooklyn Groovy Utilities .......................... SUCCESS [ 0.437 s]
[INFO] Brooklyn Core ...................................... SUCCESS [02:53 min]
[INFO] Brooklyn Policies .................................. FAILURE [02:07 min]
[INFO] Brooklyn WinRM Software Entities ................... SKIPPED
[INFO] Brooklyn Secure JMXMP Agent ........................ SKIPPED
[INFO] Brooklyn JMX RMI Agent ............................. SKIPPED
[INFO] Brooklyn Jclouds Location Targets .................. SKIPPED
[INFO] Brooklyn Software Base ............................. SKIPPED
[INFO] Brooklyn CAMP REST API ............................. SKIPPED
[INFO] Brooklyn Hazelcast Storage ......................... SKIPPED
[INFO] Brooklyn REST API .................................. SKIPPED
[INFO] Brooklyn REST Server ............................... SKIPPED
[INFO] Brooklyn Launcher .................................. SKIPPED
[INFO] Brooklyn Command Line Interface .................... SKIPPED
[INFO] Brooklyn REST Client ............................... SKIPPED
[INFO] Brooklyn Test Framework ............................ SKIPPED
[INFO] Brooklyn Karaf ..................................... SKIPPED
[INFO] Brooklyn Karaf Features ............................ SKIPPED
[INFO] Brooklyn Karaf Distro .............................. SKIPPED
[INFO] Brooklyn Karaf Shell Commands ...................... SKIPPED
[INFO] Brooklyn Karaf pax-exam itest ...................... SKIPPED
[INFO] Brooklyn Library Root .............................. SKIPPED
[INFO] Brooklyn Network Software Entities ................. SKIPPED
[INFO] Brooklyn OSGi Software Entities .................... SKIPPED
[INFO] Brooklyn Database Software Entities ................ SKIPPED
[INFO] Brooklyn Web App Software Entities ................. SKIPPED
[INFO] Brooklyn Messaging Software Entities ............... SKIPPED
[INFO] Brooklyn NoSQL Data Store Software Entities ........ SKIPPED
[INFO] Brooklyn Monitoring Software Entities .............. SKIPPED
[INFO] Brooklyn QA ........................................ SKIPPED
[INFO] Brooklyn Examples Aggregator Project ............... SKIPPED
[INFO] Brooklyn Examples Aggregator Project - Webapps ..... SKIPPED
[INFO] hello-world-webapp Maven Webapp .................... SKIPPED
[INFO] hello-world-sql-webapp Maven Webapp ................ SKIPPED
[INFO] Brooklyn Simple Web Cluster Example ................ SKIPPED
[INFO] Brooklyn Global Web Fabric Example ................. SKIPPED
[INFO] Brooklyn Simple Messaging Publish-Subscribe Example SKIPPED
[INFO] Brooklyn NoSQL Cluster Examples .................... SKIPPED
[INFO] Brooklyn Downstream Project Parent ................. SKIPPED
[INFO] Brooklyn Dist Root ................................. SKIPPED
[INFO] Brooklyn All Things ................................ SKIPPED
[INFO] Brooklyn Distribution .............................. SKIPPED
[INFO] Brooklyn Quick-Start Project Archetype ............. SKIPPED
[INFO] Brooklyn Root ...................................... SKIPPED
[INFO] ------------------------------------------------------------------------
[INFO] BUILD FAILURE
[INFO] ------------------------------------------------------------------------
[INFO] Total time: 05:40 min
[INFO] Finished at: 2016-01-07T13:34:02-08:00
[INFO] Final Memory: 126M/1521M
[INFO] ------------------------------------------------------------------------
[ERROR] Failed to execute goal org.apache.maven.plugins:maven-surefire-plugin:2.18.1:test (default-test) on project brooklyn-policy: There are test failures.
[ERROR]
[ERROR] Please refer to /Users/davidx/code/incubator-brooklyn/brooklyn-server/policy/target/surefire-reports for the individual test results.
[ERROR] -> [Help 1]
org.apache.maven.lifecycle.LifecycleExecutionException: Failed to execute goal org.apache.maven.plugins:maven-surefire-plugin:2.18.1:test (default-test) on project brooklyn-policy: There are test failures.
Please refer to /Users/davidx/code/incubator-brooklyn/brooklyn-server/policy/target/surefire-reports for the individual test results.
at org.apache.maven.lifecycle.internal.MojoExecutor.execute(MojoExecutor.java:212)
at org.apache.maven.lifecycle.internal.MojoExecutor.execute(MojoExecutor.java:153)
at org.apache.maven.lifecycle.internal.MojoExecutor.execute(MojoExecutor.java:145)
at org.apache.maven.lifecycle.internal.LifecycleModuleBuilder.buildProject(LifecycleModuleBuilder.java:116)
at org.apache.maven.lifecycle.internal.LifecycleModuleBuilder.buildProject(LifecycleModuleBuilder.java:80)
at org.apache.maven.lifecycle.internal.builder.singlethreaded.SingleThreadedBuilder.build(SingleThreadedBuilder.java:51)
at org.apache.maven.lifecycle.internal.LifecycleStarter.execute(LifecycleStarter.java:120)
at org.apache.maven.DefaultMaven.doExecute(DefaultMaven.java:355)
at org.apache.maven.DefaultMaven.execute(DefaultMaven.java:155)
at org.apache.maven.cli.MavenCli.execute(MavenCli.java:584)
at org.apache.maven.cli.MavenCli.doMain(MavenCli.java:216)
at org.apache.maven.cli.MavenCli.main(MavenCli.java:160)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:483)
at org.codehaus.plexus.classworlds.launcher.Launcher.launchEnhanced(Launcher.java:289)
at org.codehaus.plexus.classworlds.launcher.Launcher.launch(Launcher.java:229)
at org.codehaus.plexus.classworlds.launcher.Launcher.mainWithExitCode(Launcher.java:415)
at org.codehaus.plexus.classworlds.launcher.Launcher.main(Launcher.java:356)
Caused by: org.apache.maven.plugin.MojoFailureException: There are test failures.
Please refer to /Users/davidx/code/incubator-brooklyn/brooklyn-server/policy/target/surefire-reports for the individual test results.
at org.apache.maven.plugin.surefire.SurefireHelper.reportExecution(SurefireHelper.java:82)
at org.apache.maven.plugin.surefire.SurefirePlugin.handleSummary(SurefirePlugin.java:254)
at org.apache.maven.plugin.surefire.AbstractSurefireMojo.executeAfterPreconditionsChecked(AbstractSurefireMojo.java:854)
at org.apache.maven.plugin.surefire.AbstractSurefireMojo.execute(AbstractSurefireMojo.java:722)
at org.apache.maven.plugin.DefaultBuildPluginManager.executeMojo(DefaultBuildPluginManager.java:132)
at org.apache.maven.lifecycle.internal.MojoExecutor.execute(MojoExecutor.java:208)
... 19 more
[ERROR]
[ERROR]
[ERROR] For more information about the errors and possible solutions, please read the following articles:
[ERROR] [Help 1] http://cwiki.apache.org/confluence/display/MAVEN/MojoFailureException
[ERROR]
[ERROR] After correcting the problems, you can resume the build with the command
[ERROR] mvn <goals> -rf :brooklyn-policy
Davids-MBP:incubator-brooklyn davidx$
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment