You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@brooklyn.apache.org by "Svetoslav Neykov (JIRA)" <ji...@apache.org> on 2015/11/17 11:44:10 UTC
[jira] [Updated] (BROOKLYN-194) Syncrhonize access to
entity.groups()
[ https://issues.apache.org/jira/browse/BROOKLYN-194?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel ]
Svetoslav Neykov updated BROOKLYN-194:
--------------------------------------
Description:
{code}
Time elapsed: 30.779 sec <<< FAILURE! - in TestSuite
testStopOnChildUnmanaged(org.apache.brooklyn.entity.webapp.ControlledDynamicWebAppClusterTest) Time elapsed: 0.226 sec <<< FAILURE!
java.util.ConcurrentModificationException: null
at java.util.LinkedHashMap$LinkedHashIterator.nextEntry(LinkedHashMap.java:394)
at java.util.LinkedHashMap$KeyIterator.next(LinkedHashMap.java:405)
at java.util.AbstractCollection.toArray(AbstractCollection.java:141)
at com.google.common.collect.ImmutableList.copyOf(ImmutableList.java:258)
at org.apache.brooklyn.core.entity.AbstractEntity$BasicGroupSupport.asList(AbstractEntity.java:747)
at org.apache.brooklyn.core.entity.AbstractEntity$BasicGroupSupport.iterator(AbstractEntity.java:735)
at org.apache.brooklyn.core.mgmt.internal.LocalEntityManager.unmanageNonRecursive(LocalEntityManager.java:707)
at org.apache.brooklyn.core.mgmt.internal.LocalEntityManager.unmanage(LocalEntityManager.java:458)
at org.apache.brooklyn.core.mgmt.internal.LocalEntityManager.unmanage(LocalEntityManager.java:402)
at org.apache.brooklyn.core.mgmt.internal.LocalEntityManager.unmanage(LocalEntityManager.java:398)
at org.apache.brooklyn.core.entity.Entities.unmanage(Entities.java:1011)
at org.apache.brooklyn.entity.webapp.ControlledDynamicWebAppClusterTest.testStopOnChildUnmanaged(ControlledDynamicWebAppClusterTest.java:204)
{code}
was:
{code}
FailedКонзола с резултата
Skipping 3,579 KB.. Full Log
015-11-17 10:22:18,736 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.entity.java.JavaOptsTest.tearDown()
2015-11-17 10:22:18,739 INFO Stopping VanillaJavaAppImpl{id=T6gE9zFu} in [SshMachineLocation[SshMachineLocation:N4yT:localhost/127.0.0.1:22@N4yTlJC2]]
2015-11-17 10:22:18,747 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.entity.java.JavaOptsTest.tearDown() finished in 11 ms
2015-11-17 10:22:18,748 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.entity.java.JavaOptsTest.setUp()
2015-11-17 10:22:18,764 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.entity.java.JavaOptsTest.setUp() finished in 16 ms
2015-11-17 10:22:18,764 INFO TESTNG INVOKING: "Surefire test" - org.apache.brooklyn.entity.java.JavaOptsTest.testPassesJavaOptsObeyingMutualExclusions()
2015-11-17 10:22:18,786 INFO Starting TestingJavaOptsVanillaJavaAppImpl{id=slemckIV} on machine SshMachineLocation[SshMachineLocation:h144:localhost/127.0.0.1:22@h144KaL8]
2015-11-17 10:22:18,937 INFO TESTNG PASSED: "Surefire test" - org.apache.brooklyn.entity.java.JavaOptsTest.testPassesJavaOptsObeyingMutualExclusions() finished in 173 ms
2015-11-17 10:22:18,938 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.entity.java.JavaOptsTest.tearDown()
2015-11-17 10:22:18,940 INFO Stopping TestingJavaOptsVanillaJavaAppImpl{id=slemckIV} in [SshMachineLocation[SshMachineLocation:h144:localhost/127.0.0.1:22@h144KaL8]]
2015-11-17 10:22:18,949 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.entity.java.JavaOptsTest.tearDown() finished in 11 ms
2015-11-17 10:22:18,950 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.entity.java.JavaOptsTest.setUp()
2015-11-17 10:22:18,967 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.entity.java.JavaOptsTest.setUp() finished in 18 ms
2015-11-17 10:22:18,967 INFO TESTNG INVOKING: "Surefire test" - org.apache.brooklyn.entity.java.JavaOptsTest.testPassesJavaOptsOverridingDefaults()
2015-11-17 10:22:18,989 INFO Starting VanillaJavaAppImpl{id=Xh9BdIZP} on machine SshMachineLocation[SshMachineLocation:Ib10:localhost/127.0.0.1:22@Ib10mr0n]
2015-11-17 10:22:19,140 INFO TESTNG PASSED: "Surefire test" - org.apache.brooklyn.entity.java.JavaOptsTest.testPassesJavaOptsOverridingDefaults() finished in 173 ms
2015-11-17 10:22:19,140 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.entity.java.JavaOptsTest.tearDown()
2015-11-17 10:22:19,142 INFO Stopping VanillaJavaAppImpl{id=Xh9BdIZP} in [SshMachineLocation[SshMachineLocation:Ib10:localhost/127.0.0.1:22@Ib10mr0n]]
2015-11-17 10:22:19,151 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.entity.java.JavaOptsTest.tearDown() finished in 11 ms
2015-11-17 10:22:19,152 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.entity.java.JavaOptsTest.setUp()
2015-11-17 10:22:19,168 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.entity.java.JavaOptsTest.setUp() finished in 16 ms
2015-11-17 10:22:19,169 INFO TESTNG INVOKING: "Surefire test" - org.apache.brooklyn.entity.java.JavaOptsTest.testPassesJavaSysProps()
2015-11-17 10:22:19,194 INFO Starting VanillaJavaAppImpl{id=HY4H1Klq} on machine SshMachineLocation[SshMachineLocation:StA0:localhost/127.0.0.1:22@StA0OxPY]
2015-11-17 10:22:19,341 INFO TESTNG PASSED: "Surefire test" - org.apache.brooklyn.entity.java.JavaOptsTest.testPassesJavaSysProps() finished in 172 ms
2015-11-17 10:22:19,341 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.entity.java.JavaOptsTest.tearDown()
2015-11-17 10:22:19,343 INFO Stopping VanillaJavaAppImpl{id=HY4H1Klq} in [SshMachineLocation[SshMachineLocation:StA0:localhost/127.0.0.1:22@StA0OxPY]]
2015-11-17 10:22:19,350 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.entity.java.JavaOptsTest.tearDown() finished in 9 ms
2015-11-17 10:22:19,350 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.entity.java.JavaOptsTest.setUp()
2015-11-17 10:22:19,365 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.entity.java.JavaOptsTest.setUp() finished in 15 ms
2015-11-17 10:22:19,365 INFO TESTNG INVOKING: "Surefire test" - org.apache.brooklyn.entity.java.JavaOptsTest.testSecureJmxConfigFromDefault()
2015-11-17 10:22:19,388 INFO Starting TestingNoSensorsVanillaJavaAppImpl{id=xrPSsxpZ} on machine SshMachineLocation[SshMachineLocation:Xjy2:localhost/127.0.0.1:22@Xjy2vkss]
SUREFIRE-859: [GC 248889K->47839K(755712K), 0.0208430 secs]
2015-11-17 10:22:20,082 INFO TESTNG PASSED: "Surefire test" - org.apache.brooklyn.entity.java.JavaOptsTest.testSecureJmxConfigFromDefault() finished in 716 ms
2015-11-17 10:22:20,082 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.entity.java.JavaOptsTest.tearDown()
2015-11-17 10:22:20,085 INFO Stopping TestingNoSensorsVanillaJavaAppImpl{id=xrPSsxpZ} in [SshMachineLocation[SshMachineLocation:Xjy2:localhost/127.0.0.1:22@Xjy2vkss]]
2015-11-17 10:22:20,096 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.entity.java.JavaOptsTest.tearDown() finished in 14 ms
2015-11-17 10:22:20,097 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.entity.java.JavaOptsTest.setUp()
2015-11-17 10:22:20,113 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.entity.java.JavaOptsTest.setUp() finished in 16 ms
2015-11-17 10:22:20,113 INFO TESTNG INVOKING: "Surefire test" - org.apache.brooklyn.entity.java.JavaOptsTest.testSimpleLaunchesJavaProcess()
2015-11-17 10:22:20,138 INFO Starting VanillaJavaAppImpl{id=G38ec51V} on machine SshMachineLocation[SshMachineLocation:L0jR:localhost/127.0.0.1:22@L0jRaipG]
2015-11-17 10:22:20,287 INFO TESTNG PASSED: "Surefire test" - org.apache.brooklyn.entity.java.JavaOptsTest.testSimpleLaunchesJavaProcess() finished in 174 ms
2015-11-17 10:22:20,288 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.entity.java.JavaOptsTest.tearDown()
2015-11-17 10:22:20,290 INFO Stopping VanillaJavaAppImpl{id=G38ec51V} in [SshMachineLocation[SshMachineLocation:L0jR:localhost/127.0.0.1:22@L0jRaipG]]
2015-11-17 10:22:20,298 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.entity.java.JavaOptsTest.tearDown() finished in 11 ms
2015-11-17 10:22:20,335 INFO TESTNG
===============================================
Surefire test
Tests run: 138, Failures: 0, Skips: 0
===============================================
2015-11-17 10:22:20,336 INFO BrooklynLeakListener.onFinish attempting to terminate all extant ManagementContexts: name=Surefire test; includedGroups=[]; excludedGroups=[Integration, Acceptance, Live, Broken, WIP]; suiteName=Surefire suite; outDir=/home/jenkins/jenkins-slave/workspace/incubator-brooklyn-pull-requests/software/base/target/surefire-reports/Surefire suite: no org.apache.brooklyn.core.management.internal.LocalManagementContext available, so skipped
Tests run: 138, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 35.521 sec - in TestSuite
Results :
Tests run: 138, Failures: 0, Errors: 0, Skipped: 0
[JENKINS] Recording test results
[INFO]
[INFO] --- maven-jar-plugin:2.6:jar (default-jar) @ brooklyn-software-base ---
[INFO] Building jar: /home/jenkins/jenkins-slave/workspace/incubator-brooklyn-pull-requests/software/base/target/brooklyn-software-base-0.9.0-SNAPSHOT.jar
[INFO]
[INFO] --- maven-site-plugin:3.4:attach-descriptor (attach-descriptor) @ brooklyn-software-base ---
[INFO]
[INFO] --- maven-jar-plugin:2.6:test-jar (test-jar-creation) @ brooklyn-software-base ---
[INFO] Building jar: /home/jenkins/jenkins-slave/workspace/incubator-brooklyn-pull-requests/software/base/target/brooklyn-software-base-0.9.0-SNAPSHOT-tests.jar
[INFO]
[INFO] --- apache-rat-plugin:0.11:check (default) @ brooklyn-software-base ---
[INFO] 51 implicit excludes (use -debug for more details).
[INFO] Exclude: sandbox/**
[INFO] Exclude: release/**
[INFO] Exclude: **/nbactions.xml
[INFO] Exclude: **/nb-configuration.xml
[INFO] Exclude: **/.git/**
[INFO] Exclude: **/.gitignore
[INFO] Exclude: **/.repository/**
[INFO] Exclude: **/.idea/**
[INFO] Exclude: **/*.iml
[INFO] Exclude: **/.classpath/**
[INFO] Exclude: **/.project
[INFO] Exclude: **/.settings/**
[INFO] Exclude: **/*.log
[INFO] Exclude: **/brooklyn*.log.*
[INFO] Exclude: **/target/**
[INFO] Exclude: ignored/**
[INFO] Exclude: LICENSE.md
[INFO] Exclude: **/src/main/license/**
[INFO] Exclude: **/src/test/license/**
[INFO] Exclude: **/MANIFEST.MF
[INFO] Exclude: **/test-output/**
[INFO] Exclude: **/*.pem.pub
[INFO] Exclude: **/*.pem
[INFO] Exclude: **/*_rsa.pub
[INFO] Exclude: **/*_rsa
[INFO] Exclude: **/*.svg
[INFO] Exclude: **/*.crt
[INFO] Exclude: **/*.csr
[INFO] Exclude: **/*.key
[INFO] Exclude: **/*.key.org
[INFO] Exclude: **/*.psd
[INFO] Exclude: **/*.json
[INFO] Exclude: **/*.plxarc
[INFO] Exclude: **/src/test/resources/org/apache/brooklyn/entity/software/base/template_with_extra_substitutions.txt
[INFO] Exclude: **/src/main/resources/banner.txt
[INFO] Exclude: **/src/test/resources/ssl/certs/localhost/info.txt
[INFO] Exclude: **/src/main/history/dependencies.xml
[INFO] Exclude: **/sandbox/examples/src/main/scripts/amis.txt
[INFO] Exclude: docs/**
[INFO] 199 resources included (use -debug for more details)
[INFO] Rat check: Summary of files. Unapproved: 0 unknown: 0 generated: 0 approved: 199 licence.
[INFO]
[INFO] --- maven-source-plugin:2.4:jar-no-fork (attach-sources) @ brooklyn-software-base ---
[INFO] Building jar: /home/jenkins/jenkins-slave/workspace/incubator-brooklyn-pull-requests/software/base/target/brooklyn-software-base-0.9.0-SNAPSHOT-sources.jar
[INFO]
[INFO] --- maven-install-plugin:2.5.2:install (default-install) @ brooklyn-software-base ---
[INFO] Installing /home/jenkins/jenkins-slave/workspace/incubator-brooklyn-pull-requests/software/base/target/brooklyn-software-base-0.9.0-SNAPSHOT.jar to /home/jenkins/jenkins-slave/maven-repositories/1/org/apache/brooklyn/brooklyn-software-base/0.9.0-SNAPSHOT/brooklyn-software-base-0.9.0-SNAPSHOT.jar
[INFO] Installing /home/jenkins/jenkins-slave/workspace/incubator-brooklyn-pull-requests/software/base/pom.xml to /home/jenkins/jenkins-slave/maven-repositories/1/org/apache/brooklyn/brooklyn-software-base/0.9.0-SNAPSHOT/brooklyn-software-base-0.9.0-SNAPSHOT.pom
[INFO] Installing /home/jenkins/jenkins-slave/workspace/incubator-brooklyn-pull-requests/software/base/target/brooklyn-software-base-0.9.0-SNAPSHOT-tests.jar to /home/jenkins/jenkins-slave/maven-repositories/1/org/apache/brooklyn/brooklyn-software-base/0.9.0-SNAPSHOT/brooklyn-software-base-0.9.0-SNAPSHOT-tests.jar
[INFO] Installing /home/jenkins/jenkins-slave/workspace/incubator-brooklyn-pull-requests/software/base/target/brooklyn-software-base-0.9.0-SNAPSHOT-sources.jar to /home/jenkins/jenkins-slave/maven-repositories/1/org/apache/brooklyn/brooklyn-software-base/0.9.0-SNAPSHOT/brooklyn-software-base-0.9.0-SNAPSHOT-sources.jar
[JENKINS] Archiving disabled
[INFO]
[INFO] ------------------------------------------------------------------------
[INFO] Building Brooklyn Web App Software Entities 0.9.0-SNAPSHOT
[INFO] ------------------------------------------------------------------------
[INFO]
[INFO] --- maven-clean-plugin:2.6.1:clean (default-clean) @ brooklyn-software-webapp ---
[INFO] Deleting /home/jenkins/jenkins-slave/workspace/incubator-brooklyn-pull-requests/software/webapp/target
[INFO] Deleting /home/jenkins/jenkins-slave/workspace/incubator-brooklyn-pull-requests/software/webapp (includes = [brooklyn*.log, brooklyn*.log.*, stacktrace.log, test-output, prodDb.*], excludes = [])
[INFO]
[INFO] --- maven-replacer-plugin:1.4.1:replace (fix-eclipse-dot-classpath-mangling) @ brooklyn-software-webapp ---
[INFO] Ignoring missing file
[INFO] Replacement run on 0 file.
[INFO]
[INFO] --- buildnumber-maven-plugin:1.3:create (default) @ brooklyn-software-webapp ---
[INFO]
[INFO] --- maven-enforcer-plugin:1.4:enforce (brooklyn-build-req) @ brooklyn-software-webapp ---
[INFO]
[INFO] --- maven-remote-resources-plugin:1.5:process (default) @ brooklyn-software-webapp ---
[INFO]
[INFO] --- maven-resources-plugin:2.7:resources (default-resources) @ brooklyn-software-webapp ---
[INFO] Using 'UTF-8' encoding to copy filtered resources.
[INFO] Copying 15 resources
[INFO] Copying 4 resources
[INFO]
[INFO] --- maven-compiler-plugin:3.3:compile (default-compile) @ brooklyn-software-webapp ---
[INFO] Changes detected - recompiling the module!
[INFO] Compiling 66 source files to /home/jenkins/jenkins-slave/workspace/incubator-brooklyn-pull-requests/software/webapp/target/classes
[INFO] /home/jenkins/jenkins-slave/workspace/incubator-brooklyn-pull-requests/software/webapp/src/main/java/org/apache/brooklyn/entity/webapp/WebAppServiceMethods.java: Some input files use or override a deprecated API.
[INFO] /home/jenkins/jenkins-slave/workspace/incubator-brooklyn-pull-requests/software/webapp/src/main/java/org/apache/brooklyn/entity/webapp/WebAppServiceMethods.java: Recompile with -Xlint:deprecation for details.
[INFO]
[INFO] --- maven-checkstyle-plugin:2.13:check (verify-style) @ brooklyn-software-webapp ---
[INFO]
[INFO]
[INFO] --- buildnumber-maven-plugin:1.3:create (default) @ brooklyn-software-webapp ---
[INFO]
[INFO] --- maven-enforcer-plugin:1.4:enforce (brooklyn-build-req) @ brooklyn-software-webapp ---
[INFO]
[INFO] --- maven-remote-resources-plugin:1.5:process (default) @ brooklyn-software-webapp ---
[INFO]
[INFO] --- maven-resources-plugin:2.7:resources (default-resources) @ brooklyn-software-webapp ---
[INFO] Using 'UTF-8' encoding to copy filtered resources.
[INFO] Copying 15 resources
[INFO] Copying 4 resources
[INFO] Copying 4 resources
[INFO]
[INFO] --- maven-compiler-plugin:3.3:compile (default-compile) @ brooklyn-software-webapp ---
[INFO] Nothing to compile - all classes are up to date
[INFO]
[INFO] --- maven-checkstyle-plugin:2.13:check (verify-style) @ brooklyn-software-webapp ---
[INFO]
[WARNING] Failed to getClass for org.apache.felix.bundleplugin.ManifestPlugin
[INFO]
[INFO] --- maven-bundle-plugin:2.5.4:manifest (bundle-manifest) @ brooklyn-software-webapp ---
[WARNING] Manifest org.apache.brooklyn:brooklyn-software-webapp:jar:0.9.0-SNAPSHOT : Unused Export-Package instructions: [brooklyn.*]
[INFO]
[INFO] --- maven-resources-plugin:2.7:testResources (default-testResources) @ brooklyn-software-webapp ---
[INFO] Using 'UTF-8' encoding to copy filtered resources.
[INFO] Copying 7 resources
[INFO] Copying 4 resources
[INFO]
[INFO] --- maven-compiler-plugin:3.3:testCompile (default-testCompile) @ brooklyn-software-webapp ---
[INFO] Changes detected - recompiling the module!
[INFO] Compiling 58 source files to /home/jenkins/jenkins-slave/workspace/incubator-brooklyn-pull-requests/software/webapp/target/test-classes
[INFO] /home/jenkins/jenkins-slave/workspace/incubator-brooklyn-pull-requests/software/webapp/src/test/java/org/apache/brooklyn/entity/proxy/AbstractControllerTest.java: Some input files use or override a deprecated API.
[INFO] /home/jenkins/jenkins-slave/workspace/incubator-brooklyn-pull-requests/software/webapp/src/test/java/org/apache/brooklyn/entity/proxy/AbstractControllerTest.java: Recompile with -Xlint:deprecation for details.
[INFO] /home/jenkins/jenkins-slave/workspace/incubator-brooklyn-pull-requests/software/webapp/src/test/java/org/apache/brooklyn/entity/proxy/AbstractControllerTest.java: Some input files use unchecked or unsafe operations.
[INFO] /home/jenkins/jenkins-slave/workspace/incubator-brooklyn-pull-requests/software/webapp/src/test/java/org/apache/brooklyn/entity/proxy/AbstractControllerTest.java: Recompile with -Xlint:unchecked for details.
[INFO]
[INFO] --- maven-surefire-plugin:2.18.1:test (default-test) @ brooklyn-software-webapp ---
[INFO] Surefire report directory: /home/jenkins/jenkins-slave/workspace/incubator-brooklyn-pull-requests/software/webapp/target/surefire-reports
-------------------------------------------------------
T E S T S
-------------------------------------------------------
Running TestSuite
Configuring TestNG with: TestNG652Configurator
2015-11-17 10:22:28,843 INFO TESTNG RUNNING: Suite: "Surefire test" containing "38" Tests (config: null)
2015-11-17 10:22:28,848 INFO BrooklynLeakListener.onStart attempting to terminate all extant ManagementContexts: name=Surefire test; includedGroups=[]; excludedGroups=[Integration, Acceptance, Live, Broken, WIP]; suiteName=Surefire suite; outDir=/home/jenkins/jenkins-slave/workspace/incubator-brooklyn-pull-requests/software/webapp/target/surefire-reports/Surefire suite: no org.apache.brooklyn.core.management.internal.LocalManagementContext available, so skipped
2015-11-17 10:22:28,860 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.entity.proxy.AbstractControllerTest.setUp()
SUREFIRE-859: [GC 196608K->16191K(753664K), 0.0203690 secs]
2015-11-17 10:22:30,533 INFO Starting TrackingAbstractControllerImpl{id=v2Qk4Yeq}, obtaining a new location instance in FixedListMachineProvisioningLocation{id=ZsXqbC9r, name=FixedListMachineProvisioningLocation:ZsXq} with ports [22, 8000, 8443]
2015-11-17 10:22:30,538 INFO Starting TrackingAbstractControllerImpl{id=v2Qk4Yeq} on machine SshMachineLocation[SshMachineLocation:l8Qw:/1.1.1.1:22@l8QwU7l8]
2015-11-17 10:22:31,049 INFO Added policy ServerPoolMemberTrackerPolicy{name=Controller targets tracker, running=true} to TrackingAbstractControllerImpl{id=v2Qk4Yeq}
2015-11-17 10:22:31,050 INFO Resetting TrackingAbstractControllerImpl{id=v2Qk4Yeq}, server pool targets {}
2015-11-17 10:22:31,054 INFO Updating TrackingAbstractControllerImpl{id=v2Qk4Yeq}, server pool targets {}
2015-11-17 10:22:31,055 INFO test controller reconfigure, targets []
2015-11-17 10:22:31,103 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.entity.proxy.AbstractControllerTest.setUp() finished in 2244 ms
2015-11-17 10:22:31,103 INFO TESTNG INVOKING: "Surefire test" - org.apache.brooklyn.entity.proxy.AbstractControllerTest.testFailsIfSetHostAndPortAndHostnameOrPortNumberSensor()
2015-11-17 10:22:31,163 INFO Starting TrackingAbstractControllerImpl{id=MBLlJcrG}, obtaining a new location instance in FixedListMachineProvisioningLocation{id=ZsXqbC9r, name=FixedListMachineProvisioningLocation:ZsXq} with ports [22, 8000, 8443]
2015-11-17 10:22:31,165 INFO Starting TrackingAbstractControllerImpl{id=MBLlJcrG} on machine SshMachineLocation[SshMachineLocation:q6Ps:/1.1.1.2:22@q6PsvJS7]
2015-11-17 10:22:31,202 WARN Error invoking start at TrackingAbstractControllerImpl{id=MBLlJcrG}: java.lang.IllegalStateException: Must not set Sensor: member.sensor.hostandport (org.apache.brooklyn.api.sensor.AttributeSensor) and either of Sensor: member.sensor.hostname (org.apache.brooklyn.api.sensor.AttributeSensor) or Sensor: member.sensor.portNumber (org.apache.brooklyn.api.sensor.AttributeSensor)
2015-11-17 10:22:31,204 WARN Setting Application[HwiGpI8L] on-fire due to problems when expected running, up=true, problems: {service-lifecycle-indicators-from-children-and-members=Required entity not healthy: TrackingAbstractControllerImpl{id=MBLlJcrG}}
2015-11-17 10:22:31,269 INFO Starting TrackingAbstractControllerImpl{id=TIP7QHJk}, obtaining a new location instance in FixedListMachineProvisioningLocation{id=ZsXqbC9r, name=FixedListMachineProvisioningLocation:ZsXq} with ports [22, 8000, 8443]
2015-11-17 10:22:31,272 INFO Starting TrackingAbstractControllerImpl{id=TIP7QHJk} on machine SshMachineLocation[SshMachineLocation:erpC:/1.1.1.3:22@erpCgsM2]
2015-11-17 10:22:31,300 WARN Error invoking start at TrackingAbstractControllerImpl{id=TIP7QHJk}: java.lang.IllegalStateException: Must not set Sensor: member.sensor.hostandport (org.apache.brooklyn.api.sensor.AttributeSensor) and either of Sensor: member.sensor.hostname (org.apache.brooklyn.api.sensor.AttributeSensor) or Sensor: member.sensor.portNumber (org.apache.brooklyn.api.sensor.AttributeSensor)
2015-11-17 10:22:31,303 INFO TESTNG PASSED: "Surefire test" - org.apache.brooklyn.entity.proxy.AbstractControllerTest.testFailsIfSetHostAndPortAndHostnameOrPortNumberSensor() finished in 200 ms
2015-11-17 10:22:31,304 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.core.test.BrooklynAppUnitTestSupport.tearDown()
2015-11-17 10:22:31,317 INFO Stopping TrackingAbstractControllerImpl{id=v2Qk4Yeq} in [SshMachineLocation[SshMachineLocation:l8Qw:1.1.1.1/1.1.1.1:22@l8QwU7l8]]
2015-11-17 10:22:31,318 INFO Stopping TrackingAbstractControllerImpl{id=MBLlJcrG} in [SshMachineLocation[SshMachineLocation:q6Ps:1.1.1.2/1.1.1.2:22@q6PsvJS7]]
2015-11-17 10:22:31,321 INFO Stopping TrackingAbstractControllerImpl{id=TIP7QHJk} in [SshMachineLocation[SshMachineLocation:erpC:1.1.1.3/1.1.1.3:22@erpCgsM2]]
2015-11-17 10:22:31,364 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.core.test.BrooklynAppUnitTestSupport.tearDown() finished in 61 ms
2015-11-17 10:22:31,366 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.entity.proxy.AbstractControllerTest.setUp()
2015-11-17 10:22:31,532 INFO Starting TrackingAbstractControllerImpl{id=nogydsFw}, obtaining a new location instance in FixedListMachineProvisioningLocation{id=oT2GKZhs, name=FixedListMachineProvisioningLocation:oT2G} with ports [22, 8000, 8443]
2015-11-17 10:22:31,535 INFO Starting TrackingAbstractControllerImpl{id=nogydsFw} on machine SshMachineLocation[SshMachineLocation:AAxp:/1.1.1.1:22@AAxpO4pI]
2015-11-17 10:22:31,671 INFO Added policy ServerPoolMemberTrackerPolicy{name=Controller targets tracker, running=true} to TrackingAbstractControllerImpl{id=nogydsFw}
2015-11-17 10:22:31,671 INFO Resetting TrackingAbstractControllerImpl{id=nogydsFw}, server pool targets {}
2015-11-17 10:22:31,673 INFO Updating TrackingAbstractControllerImpl{id=nogydsFw}, server pool targets {}
2015-11-17 10:22:31,673 INFO test controller reconfigure, targets []
2015-11-17 10:22:31,680 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.entity.proxy.AbstractControllerTest.setUp() finished in 314 ms
2015-11-17 10:22:31,680 INFO TESTNG INVOKING: "Surefire test" - org.apache.brooklyn.entity.proxy.AbstractControllerTest.testMemberWithoutHostAndPortDoesNotLogErrorRepeatedly()
2015-11-17 10:22:31,730 INFO Starting TrackingAbstractControllerImpl{id=izIKnHBj}, obtaining a new location instance in FixedListMachineProvisioningLocation{id=oT2GKZhs, name=FixedListMachineProvisioningLocation:oT2G} with ports [22, 8000, 8443]
2015-11-17 10:22:31,732 INFO Starting TrackingAbstractControllerImpl{id=izIKnHBj} on machine SshMachineLocation[SshMachineLocation:Yykv:/1.1.1.2:22@YykvJyJL]
2015-11-17 10:22:31,853 INFO Added policy ServerPoolMemberTrackerPolicy{name=Controller targets tracker, running=true} to TrackingAbstractControllerImpl{id=izIKnHBj}
2015-11-17 10:22:31,854 INFO Resetting TrackingAbstractControllerImpl{id=izIKnHBj}, server pool targets {}
2015-11-17 10:22:31,855 INFO Updating TrackingAbstractControllerImpl{id=izIKnHBj}, server pool targets {}
2015-11-17 10:22:31,856 INFO test controller reconfigure, targets []
2015-11-17 10:22:31,893 ERROR Unable to construct hostname:port representation for TestEntityImpl{id=HXFz6CyQ} (null:null); skipping in TrackingAbstractControllerImpl{id=nogydsFw}
2015-11-17 10:22:31,895 ERROR Unable to construct hostname:port representation for TestEntityImpl{id=HXFz6CyQ} (null:null); skipping in TrackingAbstractControllerImpl{id=izIKnHBj}
2015-11-17 10:22:31,976 WARN SingleThreadedScheduler[subscription-delivery-entity-tgWOHVZS[DynamicClusterImpl{id=tgWOHVZS}]] is backing up, 50 tasks queued
2015-11-17 10:22:32,080 INFO TESTNG PASSED: "Surefire test" - org.apache.brooklyn.entity.proxy.AbstractControllerTest.testMemberWithoutHostAndPortDoesNotLogErrorRepeatedly() finished in 400 ms
2015-11-17 10:22:32,080 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.core.test.BrooklynAppUnitTestSupport.tearDown()
2015-11-17 10:22:32,089 INFO Stopping TrackingAbstractControllerImpl{id=nogydsFw} in [SshMachineLocation[SshMachineLocation:AAxp:1.1.1.1/1.1.1.1:22@AAxpO4pI]]
2015-11-17 10:22:32,090 INFO Stopping TrackingAbstractControllerImpl{id=izIKnHBj} in [SshMachineLocation[SshMachineLocation:Yykv:1.1.1.2/1.1.1.2:22@YykvJyJL]]
SUREFIRE-859: [GC 212799K->23516K(753664K), 0.0345870 secs]
2015-11-17 10:22:32,182 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.core.test.BrooklynAppUnitTestSupport.tearDown() finished in 102 ms
2015-11-17 10:22:32,184 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.entity.proxy.AbstractControllerTest.setUp()
2015-11-17 10:22:32,327 INFO Starting TrackingAbstractControllerImpl{id=y2rWraG7}, obtaining a new location instance in FixedListMachineProvisioningLocation{id=p30vD22g, name=FixedListMachineProvisioningLocation:p30v} with ports [22, 8000, 8443]
2015-11-17 10:22:32,329 INFO Starting TrackingAbstractControllerImpl{id=y2rWraG7} on machine SshMachineLocation[SshMachineLocation:dMDW:/1.1.1.1:22@dMDWfOOf]
2015-11-17 10:22:32,451 INFO Added policy ServerPoolMemberTrackerPolicy{name=Controller targets tracker, running=true} to TrackingAbstractControllerImpl{id=y2rWraG7}
2015-11-17 10:22:32,451 INFO Resetting TrackingAbstractControllerImpl{id=y2rWraG7}, server pool targets {}
2015-11-17 10:22:32,452 INFO Updating TrackingAbstractControllerImpl{id=y2rWraG7}, server pool targets {}
2015-11-17 10:22:32,452 INFO test controller reconfigure, targets []
2015-11-17 10:22:32,457 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.entity.proxy.AbstractControllerTest.setUp() finished in 273 ms
2015-11-17 10:22:32,457 INFO TESTNG INVOKING: "Surefire test" - org.apache.brooklyn.entity.proxy.AbstractControllerTest.testUpdateCalledWhenChildHostnameAndPortChanges()
2015-11-17 10:22:32,480 ERROR Unable to construct hostname:port representation for TestEntityImpl{id=UqRPmyjf} (null:null); skipping in TrackingAbstractControllerImpl{id=y2rWraG7}
2015-11-17 10:22:32,582 INFO Adding to TrackingAbstractControllerImpl{id=y2rWraG7}, new member TestEntityImpl{id=UqRPmyjf} with address mymachine:1234
2015-11-17 10:22:32,582 INFO Updating TrackingAbstractControllerImpl{id=y2rWraG7}, server pool targets {TestEntityImpl{id=UqRPmyjf}=mymachine:1234}
2015-11-17 10:22:32,583 INFO test controller reconfigure, targets [mymachine:1234]
2015-11-17 10:22:32,587 INFO Replacing in TrackingAbstractControllerImpl{id=y2rWraG7}, member TestEntityImpl{id=UqRPmyjf} with old address mymachine:1234, new address mymachine2:1234
2015-11-17 10:22:32,588 INFO Updating TrackingAbstractControllerImpl{id=y2rWraG7}, server pool targets {TestEntityImpl{id=UqRPmyjf}=mymachine2:1234}
2015-11-17 10:22:32,588 INFO test controller reconfigure, targets [mymachine2:1234]
2015-11-17 10:22:32,589 INFO Replacing in TrackingAbstractControllerImpl{id=y2rWraG7}, member TestEntityImpl{id=UqRPmyjf} with old address mymachine2:1234, new address mymachine2:1235
2015-11-17 10:22:32,590 INFO Updating TrackingAbstractControllerImpl{id=y2rWraG7}, server pool targets {TestEntityImpl{id=UqRPmyjf}=mymachine2:1235}
2015-11-17 10:22:32,590 INFO test controller reconfigure, targets [mymachine2:1235]
2015-11-17 10:22:32,593 ERROR Unable to construct hostname:port representation for TestEntityImpl{id=UqRPmyjf} (null:1235); skipping in TrackingAbstractControllerImpl{id=y2rWraG7}
2015-11-17 10:22:32,593 INFO Removing from TrackingAbstractControllerImpl{id=y2rWraG7}, member TestEntityImpl{id=UqRPmyjf} with old address mymachine2:1235, because inferred address is now null
2015-11-17 10:22:32,593 INFO Updating TrackingAbstractControllerImpl{id=y2rWraG7}, server pool targets {TestEntityImpl{id=UqRPmyjf}=null}
2015-11-17 10:22:32,594 INFO test controller reconfigure, targets []
2015-11-17 10:22:32,596 INFO TESTNG PASSED: "Surefire test" - org.apache.brooklyn.entity.proxy.AbstractControllerTest.testUpdateCalledWhenChildHostnameAndPortChanges() finished in 139 ms
2015-11-17 10:22:32,597 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.core.test.BrooklynAppUnitTestSupport.tearDown()
2015-11-17 10:22:32,603 INFO Stopping TrackingAbstractControllerImpl{id=y2rWraG7} in [SshMachineLocation[SshMachineLocation:dMDW:1.1.1.1/1.1.1.1:22@dMDWfOOf]]
2015-11-17 10:22:32,606 INFO Removing from TrackingAbstractControllerImpl{id=y2rWraG7}, member TestEntityImpl{id=UqRPmyjf} with address null
2015-11-17 10:22:32,607 INFO Updating TrackingAbstractControllerImpl{id=y2rWraG7}, server pool targets {}
2015-11-17 10:22:32,607 INFO test controller reconfigure, targets []
2015-11-17 10:22:32,624 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.core.test.BrooklynAppUnitTestSupport.tearDown() finished in 27 ms
2015-11-17 10:22:32,627 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.entity.proxy.AbstractControllerTest.setUp()
2015-11-17 10:22:32,752 INFO Starting TrackingAbstractControllerImpl{id=RmWlntMx}, obtaining a new location instance in FixedListMachineProvisioningLocation{id=glqZe9Tz, name=FixedListMachineProvisioningLocation:glqZ} with ports [22, 8000, 8443]
2015-11-17 10:22:32,754 INFO Starting TrackingAbstractControllerImpl{id=RmWlntMx} on machine SshMachineLocation[SshMachineLocation:S5Me:/1.1.1.1:22@S5MegUrx]
2015-11-17 10:22:32,879 INFO Added policy ServerPoolMemberTrackerPolicy{name=Controller targets tracker, running=true} to TrackingAbstractControllerImpl{id=RmWlntMx}
2015-11-17 10:22:32,879 INFO Resetting TrackingAbstractControllerImpl{id=RmWlntMx}, server pool targets {}
2015-11-17 10:22:32,880 INFO Updating TrackingAbstractControllerImpl{id=RmWlntMx}, server pool targets {}
2015-11-17 10:22:32,880 INFO test controller reconfigure, targets []
2015-11-17 10:22:32,884 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.entity.proxy.AbstractControllerTest.setUp() finished in 257 ms
2015-11-17 10:22:32,885 INFO TESTNG INVOKING: "Surefire test" - org.apache.brooklyn.entity.proxy.AbstractControllerTest.testUpdateCalledWithAddressesOfNewChildren()
2015-11-17 10:22:32,886 INFO Resize DynamicClusterImpl{id=XA5ZeEEy} from 0 to 1
2015-11-17 10:22:32,891 WARN Unsupported flags when configuring ClusteredEntity{id=PiWIOmWv}; storing: {cluster.member.id=0}
2015-11-17 10:22:32,903 WARN Deprecated use of Entities.manage(Entity), for unmanaged entity ClusteredEntity{id=PiWIOmWv}
2015-11-17 10:22:32,923 INFO Adding to TrackingAbstractControllerImpl{id=RmWlntMx}, new member ClusteredEntity{id=PiWIOmWv} with address 1.1.1.2:1234
2015-11-17 10:22:32,923 INFO Updating TrackingAbstractControllerImpl{id=RmWlntMx}, server pool targets {ClusteredEntity{id=PiWIOmWv}=1.1.1.2:1234}
2015-11-17 10:22:32,924 INFO test controller reconfigure, targets [1.1.1.2:1234]
2015-11-17 10:22:32,926 INFO Resize DynamicClusterImpl{id=XA5ZeEEy} from 1 to 2
2015-11-17 10:22:32,932 WARN Unsupported flags when configuring ClusteredEntity{id=B4NVUAN1}; storing: {cluster.member.id=1}
2015-11-17 10:22:32,934 WARN Deprecated use of Entities.manage(Entity), for unmanaged entity ClusteredEntity{id=B4NVUAN1}
2015-11-17 10:22:32,952 INFO Adding to TrackingAbstractControllerImpl{id=RmWlntMx}, new member ClusteredEntity{id=B4NVUAN1} with address 1.1.1.3:1234
2015-11-17 10:22:32,953 INFO Updating TrackingAbstractControllerImpl{id=RmWlntMx}, server pool targets {ClusteredEntity{id=PiWIOmWv}=1.1.1.2:1234, ClusteredEntity{id=B4NVUAN1}=1.1.1.3:1234}
2015-11-17 10:22:32,954 INFO test controller reconfigure, targets [1.1.1.2:1234, 1.1.1.3:1234]
2015-11-17 10:22:32,956 INFO Resize DynamicClusterImpl{id=XA5ZeEEy} from 2 to 0
2015-11-17 10:22:32,958 INFO Removing from TrackingAbstractControllerImpl{id=RmWlntMx}, member ClusteredEntity{id=B4NVUAN1} with address 1.1.1.3:1234
2015-11-17 10:22:32,958 INFO Updating TrackingAbstractControllerImpl{id=RmWlntMx}, server pool targets {ClusteredEntity{id=PiWIOmWv}=1.1.1.2:1234}
2015-11-17 10:22:32,958 INFO test controller reconfigure, targets [1.1.1.2:1234]
2015-11-17 10:22:32,960 INFO Removing from TrackingAbstractControllerImpl{id=RmWlntMx}, member ClusteredEntity{id=PiWIOmWv} with address 1.1.1.2:1234
2015-11-17 10:22:32,961 INFO Updating TrackingAbstractControllerImpl{id=RmWlntMx}, server pool targets {}
2015-11-17 10:22:32,961 INFO test controller reconfigure, targets []
2015-11-17 10:22:32,964 INFO TESTNG PASSED: "Surefire test" - org.apache.brooklyn.entity.proxy.AbstractControllerTest.testUpdateCalledWithAddressesOfNewChildren() finished in 79 ms
2015-11-17 10:22:32,964 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.core.test.BrooklynAppUnitTestSupport.tearDown()
2015-11-17 10:22:32,970 INFO Stopping TrackingAbstractControllerImpl{id=RmWlntMx} in [SshMachineLocation[SshMachineLocation:S5Me:1.1.1.1/1.1.1.1:22@S5MegUrx]]
2015-11-17 10:22:32,987 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.core.test.BrooklynAppUnitTestSupport.tearDown() finished in 23 ms
2015-11-17 10:22:32,988 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.entity.proxy.AbstractControllerTest.setUp()
2015-11-17 10:22:33,089 INFO Starting TrackingAbstractControllerImpl{id=spJXJh4y}, obtaining a new location instance in FixedListMachineProvisioningLocation{id=qi0jLLwD, name=FixedListMachineProvisioningLocation:qi0j} with ports [22, 8000, 8443]
2015-11-17 10:22:33,091 INFO Starting TrackingAbstractControllerImpl{id=spJXJh4y} on machine SshMachineLocation[SshMachineLocation:ZuH2:/1.1.1.1:22@ZuH2cnIx]
2015-11-17 10:22:33,212 INFO Added policy ServerPoolMemberTrackerPolicy{name=Controller targets tracker, running=true} to TrackingAbstractControllerImpl{id=spJXJh4y}
2015-11-17 10:22:33,212 INFO Resetting TrackingAbstractControllerImpl{id=spJXJh4y}, server pool targets {}
2015-11-17 10:22:33,213 INFO Updating TrackingAbstractControllerImpl{id=spJXJh4y}, server pool targets {}
2015-11-17 10:22:33,214 INFO test controller reconfigure, targets []
2015-11-17 10:22:33,217 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.entity.proxy.AbstractControllerTest.setUp() finished in 230 ms
2015-11-17 10:22:33,218 INFO TESTNG INVOKING: "Surefire test" - org.apache.brooklyn.entity.proxy.AbstractControllerTest.testUpdateCalledWithAddressesRemovedForServiceDownChildrenThatHaveClearedHostnamePort()
2015-11-17 10:22:33,219 INFO Resize DynamicClusterImpl{id=PPrhKnlb} from 0 to 2
2015-11-17 10:22:33,223 WARN Unsupported flags when configuring ClusteredEntity{id=aYlJ2NRs}; storing: {cluster.member.id=0}
2015-11-17 10:22:33,223 WARN Deprecated use of Entities.manage(Entity), for unmanaged entity ClusteredEntity{id=aYlJ2NRs}
2015-11-17 10:22:33,230 WARN Unsupported flags when configuring ClusteredEntity{id=hSjrgwar}; storing: {cluster.member.id=1}
2015-11-17 10:22:33,231 WARN Deprecated use of Entities.manage(Entity), for unmanaged entity ClusteredEntity{id=hSjrgwar}
2015-11-17 10:22:33,252 INFO Adding to TrackingAbstractControllerImpl{id=spJXJh4y}, new member ClusteredEntity{id=aYlJ2NRs} with address 1.1.1.2:1234
2015-11-17 10:22:33,253 INFO Updating TrackingAbstractControllerImpl{id=spJXJh4y}, server pool targets {ClusteredEntity{id=aYlJ2NRs}=1.1.1.2:1234}
2015-11-17 10:22:33,253 INFO test controller reconfigure, targets [1.1.1.2:1234]
2015-11-17 10:22:33,260 INFO Adding to TrackingAbstractControllerImpl{id=spJXJh4y}, new member ClusteredEntity{id=hSjrgwar} with address 1.1.1.3:1234
2015-11-17 10:22:33,261 INFO Updating TrackingAbstractControllerImpl{id=spJXJh4y}, server pool targets {ClusteredEntity{id=aYlJ2NRs}=1.1.1.2:1234, ClusteredEntity{id=hSjrgwar}=1.1.1.3:1234}
2015-11-17 10:22:33,261 INFO test controller reconfigure, targets [1.1.1.2:1234, 1.1.1.3:1234]
2015-11-17 10:22:33,263 INFO Removing from TrackingAbstractControllerImpl{id=spJXJh4y}, member ClusteredEntity{id=aYlJ2NRs} with address 1.1.1.2:1234
2015-11-17 10:22:33,264 INFO Updating TrackingAbstractControllerImpl{id=spJXJh4y}, server pool targets {ClusteredEntity{id=hSjrgwar}=1.1.1.3:1234}
2015-11-17 10:22:33,264 INFO test controller reconfigure, targets [1.1.1.3:1234]
2015-11-17 10:22:33,266 INFO Removing from TrackingAbstractControllerImpl{id=spJXJh4y}, member ClusteredEntity{id=hSjrgwar} with address 1.1.1.3:1234
2015-11-17 10:22:33,266 INFO Updating TrackingAbstractControllerImpl{id=spJXJh4y}, server pool targets {}
2015-11-17 10:22:33,266 INFO test controller reconfigure, targets []
2015-11-17 10:22:33,268 INFO TESTNG PASSED: "Surefire test" - org.apache.brooklyn.entity.proxy.AbstractControllerTest.testUpdateCalledWithAddressesRemovedForServiceDownChildrenThatHaveClearedHostnamePort() finished in 50 ms
2015-11-17 10:22:33,268 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.core.test.BrooklynAppUnitTestSupport.tearDown()
2015-11-17 10:22:33,272 INFO Stopping TrackingAbstractControllerImpl{id=spJXJh4y} in [SshMachineLocation[SshMachineLocation:ZuH2:1.1.1.1/1.1.1.1:22@ZuH2cnIx]]
2015-11-17 10:22:33,288 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.core.test.BrooklynAppUnitTestSupport.tearDown() finished in 20 ms
2015-11-17 10:22:33,289 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.entity.proxy.AbstractControllerTest.setUp()
2015-11-17 10:22:33,388 INFO Starting TrackingAbstractControllerImpl{id=wyNqHTBp}, obtaining a new location instance in FixedListMachineProvisioningLocation{id=JtoDkT3p, name=FixedListMachineProvisioningLocation:JtoD} with ports [22, 8000, 8443]
2015-11-17 10:22:33,389 INFO Starting TrackingAbstractControllerImpl{id=wyNqHTBp} on machine SshMachineLocation[SshMachineLocation:tKdD:/1.1.1.1:22@tKdDru4V]
2015-11-17 10:22:33,517 INFO Added policy ServerPoolMemberTrackerPolicy{name=Controller targets tracker, running=true} to TrackingAbstractControllerImpl{id=wyNqHTBp}
2015-11-17 10:22:33,517 INFO Resetting TrackingAbstractControllerImpl{id=wyNqHTBp}, server pool targets {}
2015-11-17 10:22:33,518 INFO Updating TrackingAbstractControllerImpl{id=wyNqHTBp}, server pool targets {}
2015-11-17 10:22:33,519 INFO test controller reconfigure, targets []
2015-11-17 10:22:33,524 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.entity.proxy.AbstractControllerTest.setUp() finished in 235 ms
2015-11-17 10:22:33,524 INFO TESTNG INVOKING: "Surefire test" - org.apache.brooklyn.entity.proxy.AbstractControllerTest.testUpdateCalledWithAddressesRemovedForStoppedChildren()
2015-11-17 10:22:33,525 INFO Resize DynamicClusterImpl{id=qBXKYLpw} from 0 to 2
2015-11-17 10:22:33,530 WARN Unsupported flags when configuring ClusteredEntity{id=s62HW8Qz}; storing: {cluster.member.id=0}
2015-11-17 10:22:33,531 WARN Deprecated use of Entities.manage(Entity), for unmanaged entity ClusteredEntity{id=s62HW8Qz}
2015-11-17 10:22:33,537 WARN Unsupported flags when configuring ClusteredEntity{id=ZytdOaWt}; storing: {cluster.member.id=1}
2015-11-17 10:22:33,538 WARN Deprecated use of Entities.manage(Entity), for unmanaged entity ClusteredEntity{id=ZytdOaWt}
2015-11-17 10:22:33,558 INFO Adding to TrackingAbstractControllerImpl{id=wyNqHTBp}, new member ClusteredEntity{id=s62HW8Qz} with address 1.1.1.2:1234
2015-11-17 10:22:33,559 INFO Updating TrackingAbstractControllerImpl{id=wyNqHTBp}, server pool targets {ClusteredEntity{id=s62HW8Qz}=1.1.1.2:1234}
2015-11-17 10:22:33,559 INFO test controller reconfigure, targets [1.1.1.2:1234]
2015-11-17 10:22:33,561 INFO Adding to TrackingAbstractControllerImpl{id=wyNqHTBp}, new member ClusteredEntity{id=ZytdOaWt} with address 1.1.1.3:1234
2015-11-17 10:22:33,561 INFO Updating TrackingAbstractControllerImpl{id=wyNqHTBp}, server pool targets {ClusteredEntity{id=s62HW8Qz}=1.1.1.2:1234, ClusteredEntity{id=ZytdOaWt}=1.1.1.3:1234}
2015-11-17 10:22:33,561 INFO test controller reconfigure, targets [1.1.1.2:1234, 1.1.1.3:1234]
2015-11-17 10:22:33,563 INFO Resize DynamicClusterImpl{id=qBXKYLpw} from 2 to 1
2015-11-17 10:22:33,564 INFO Removing from TrackingAbstractControllerImpl{id=wyNqHTBp}, member ClusteredEntity{id=ZytdOaWt} with address 1.1.1.3:1234
2015-11-17 10:22:33,565 INFO Updating TrackingAbstractControllerImpl{id=wyNqHTBp}, server pool targets {ClusteredEntity{id=s62HW8Qz}=1.1.1.2:1234}
2015-11-17 10:22:33,565 INFO test controller reconfigure, targets [1.1.1.2:1234]
2015-11-17 10:22:33,568 INFO TESTNG PASSED: "Surefire test" - org.apache.brooklyn.entity.proxy.AbstractControllerTest.testUpdateCalledWithAddressesRemovedForStoppedChildren() finished in 44 ms
2015-11-17 10:22:33,568 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.core.test.BrooklynAppUnitTestSupport.tearDown()
2015-11-17 10:22:33,573 INFO Stopping TrackingAbstractControllerImpl{id=wyNqHTBp} in [SshMachineLocation[SshMachineLocation:tKdD:1.1.1.1/1.1.1.1:22@tKdDru4V]]
2015-11-17 10:22:33,574 INFO Removing from TrackingAbstractControllerImpl{id=wyNqHTBp}, member ClusteredEntity{id=s62HW8Qz} with address 1.1.1.2:1234
2015-11-17 10:22:33,575 INFO Updating TrackingAbstractControllerImpl{id=wyNqHTBp}, server pool targets {}
2015-11-17 10:22:33,575 INFO test controller reconfigure, targets []
2015-11-17 10:22:33,590 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.core.test.BrooklynAppUnitTestSupport.tearDown() finished in 22 ms
2015-11-17 10:22:33,591 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.entity.proxy.AbstractControllerTest.setUp()
2015-11-17 10:22:33,689 INFO Starting TrackingAbstractControllerImpl{id=W0PvHORh}, obtaining a new location instance in FixedListMachineProvisioningLocation{id=w7ZSntk9, name=FixedListMachineProvisioningLocation:w7ZS} with ports [22, 8000, 8443]
2015-11-17 10:22:33,690 INFO Starting TrackingAbstractControllerImpl{id=W0PvHORh} on machine SshMachineLocation[SshMachineLocation:Egba:/1.1.1.1:22@EgbaHDRx]
2015-11-17 10:22:33,807 INFO Added policy ServerPoolMemberTrackerPolicy{name=Controller targets tracker, running=true} to TrackingAbstractControllerImpl{id=W0PvHORh}
2015-11-17 10:22:33,808 INFO Resetting TrackingAbstractControllerImpl{id=W0PvHORh}, server pool targets {}
2015-11-17 10:22:33,809 INFO Updating TrackingAbstractControllerImpl{id=W0PvHORh}, server pool targets {}
2015-11-17 10:22:33,809 INFO test controller reconfigure, targets []
2015-11-17 10:22:33,814 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.entity.proxy.AbstractControllerTest.setUp() finished in 223 ms
2015-11-17 10:22:33,814 INFO TESTNG INVOKING: "Surefire test" - org.apache.brooklyn.entity.proxy.AbstractControllerTest.testUsesHostAndPortSensor()
2015-11-17 10:22:33,843 INFO Starting TrackingAbstractControllerImpl{id=omAXbALI}, obtaining a new location instance in FixedListMachineProvisioningLocation{id=w7ZSntk9, name=FixedListMachineProvisioningLocation:w7ZS} with ports [22, 8000, 8443]
2015-11-17 10:22:33,844 INFO Starting TrackingAbstractControllerImpl{id=omAXbALI} on machine SshMachineLocation[SshMachineLocation:hLgJ:/1.1.1.2:22@hLgJ407O]
2015-11-17 10:22:33,968 INFO Added policy ServerPoolMemberTrackerPolicy{name=Controller targets tracker, running=true} to TrackingAbstractControllerImpl{id=omAXbALI}
2015-11-17 10:22:33,969 INFO Resetting TrackingAbstractControllerImpl{id=omAXbALI}, server pool targets {}
2015-11-17 10:22:33,970 INFO Updating TrackingAbstractControllerImpl{id=omAXbALI}, server pool targets {}
2015-11-17 10:22:33,970 INFO test controller reconfigure, targets []
2015-11-17 10:22:33,991 ERROR No host:port set for TestEntityImpl{id=NTNp1gL4} (using attribute Sensor: hostandport (java.lang.String)); skipping in TrackingAbstractControllerImpl{id=omAXbALI}
2015-11-17 10:22:33,991 ERROR Unable to construct hostname:port representation for TestEntityImpl{id=NTNp1gL4} (null:null); skipping in TrackingAbstractControllerImpl{id=W0PvHORh}
2015-11-17 10:22:34,091 INFO Adding to TrackingAbstractControllerImpl{id=omAXbALI}, new member TestEntityImpl{id=NTNp1gL4} with address mymachine:1234
2015-11-17 10:22:34,092 INFO Updating TrackingAbstractControllerImpl{id=omAXbALI}, server pool targets {TestEntityImpl{id=NTNp1gL4}=mymachine:1234}
2015-11-17 10:22:34,092 INFO test controller reconfigure, targets [mymachine:1234]
2015-11-17 10:22:34,093 INFO TESTNG PASSED: "Surefire test" - org.apache.brooklyn.entity.proxy.AbstractControllerTest.testUsesHostAndPortSensor() finished in 279 ms
2015-11-17 10:22:34,093 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.core.test.BrooklynAppUnitTestSupport.tearDown()
2015-11-17 10:22:34,097 INFO Stopping TrackingAbstractControllerImpl{id=W0PvHORh} in [SshMachineLocation[SshMachineLocation:Egba:1.1.1.1/1.1.1.1:22@EgbaHDRx]]
2015-11-17 10:22:34,098 INFO Stopping TrackingAbstractControllerImpl{id=omAXbALI} in [SshMachineLocation[SshMachineLocation:hLgJ:1.1.1.2/1.1.1.2:22@hLgJ407O]]
2015-11-17 10:22:34,100 INFO Removing from TrackingAbstractControllerImpl{id=omAXbALI}, member TestEntityImpl{id=NTNp1gL4} with address mymachine:1234
2015-11-17 10:22:34,100 INFO Updating TrackingAbstractControllerImpl{id=omAXbALI}, server pool targets {}
2015-11-17 10:22:34,100 INFO test controller reconfigure, targets []
2015-11-17 10:22:34,121 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.core.test.BrooklynAppUnitTestSupport.tearDown() finished in 28 ms
2015-11-17 10:22:34,121 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.entity.dns.AbstractGeoDnsServiceTest.setup()
SUREFIRE-859: [GC 220124K->23070K(753664K), 0.0308080 secs]
2015-11-17 10:22:34,982 WARN Deprecated use of unmanaged location (SshMachineLocation[West child:100.0.0.1/100.0.0.1:22@qGY8dbtV]); will be managed automatically now but not supported in future versions
2015-11-17 10:22:34,985 WARN Deprecated use of unmanaged location (SshMachineLocation[West child with location:100.0.0.1/100.0.0.1:22@HdkrYC7L]); will be managed automatically now but not supported in future versions
2015-11-17 10:22:34,988 WARN Deprecated use of unmanaged location (SshMachineLocation[East child:100.0.0.2/100.0.0.2:22@pt95wXfx]); will be managed automatically now but not supported in future versions
2015-11-17 10:22:34,991 WARN Deprecated use of unmanaged location (SshMachineLocation[East child with location:100.0.0.2/100.0.0.2:22@uvSbOyu2]); will be managed automatically now but not supported in future versions
2015-11-17 10:22:34,993 WARN Deprecated use of unmanaged location (SshMachineLocation[North child:10.0.0.1/10.0.0.1:22@sEuoQHAh]); will be managed automatically now but not supported in future versions
SUREFIRE-859: [GC 219678K->23320K(753664K), 0.0189350 secs]
SUREFIRE-859: [GC 219928K->48913K(753664K), 0.0453570 secs]
SUREFIRE-859: [GC 245521K->68421K(706560K), 0.0490050 secs]
SUREFIRE-859: [GC 217925K->80789K(730112K), 0.0305340 secs]
SUREFIRE-859: [GC 230293K->81085K(734208K), 0.0292560 secs]
SUREFIRE-859: [GC 237757K->84041K(733184K), 0.0562800 secs]
SUREFIRE-859: [GC 240713K->85575K(736768K), 0.0428430 secs]
2015-11-17 10:22:38,854 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.entity.dns.AbstractGeoDnsServiceTest.setup() finished in 4733 ms
2015-11-17 10:22:38,855 INFO TESTNG INVOKING: "Surefire test" - org.apache.brooklyn.entity.dns.AbstractGeoDnsServiceTest.testChildAddedLate()
2015-11-17 10:22:38,876 INFO Creating entity in fabric DynamicRegionsFabricImpl{id=bmHPnj9X} at SshMachineLocation[West child:100.0.0.1/100.0.0.1:22@qGY8dbtV]
2015-11-17 10:22:38,897 INFO Creating entity in fabric DynamicRegionsFabricImpl{id=bmHPnj9X} at SshMachineLocation[East child with location:100.0.0.2/100.0.0.2:22@uvSbOyu2]
SUREFIRE-859: [GC 247367K->78815K(735744K), 0.0219180 secs]
2015-11-17 10:22:38,900 INFO GeoDns TestService adding target host TestEntityImpl{id=yIh1rj9j} HostGeoInfo[West child: <address-ignored> at (0.0,-60.0)]
2015-11-17 10:22:38,929 INFO GeoDns TestService adding target host TestEntityImpl{id=yIh1rj9j} HostGeoInfo[West child: <address-ignored> at (0.0,-60.0)]
2015-11-17 10:22:38,946 INFO GeoDns TestService adding target host TestEntityImpl{id=yIh1rj9j} HostGeoInfo[West child: <address-ignored> at (0.0,-60.0)]
2015-11-17 10:22:38,947 INFO GeoDns TestService adding target host TestEntityImpl{id=aADbqED5} HostGeoInfo[East child with location: <address-ignored> at (0.0,60.0)]
2015-11-17 10:22:38,951 INFO GeoDns TestService adding target host TestEntityImpl{id=yIh1rj9j} HostGeoInfo[West child: <address-ignored> at (0.0,-60.0)]
2015-11-17 10:22:38,951 INFO GeoDns TestService adding target host TestEntityImpl{id=aADbqED5} HostGeoInfo[East child with location: <address-ignored> at (0.0,60.0)]
2015-11-17 10:22:38,959 INFO Creating entity in fabric DynamicRegionsFabricImpl{id=bmHPnj9X} at SshMachineLocation[North child:10.0.0.1/10.0.0.1:22@sEuoQHAh]
2015-11-17 10:22:38,978 INFO GeoDns TestService adding target host TestEntityImpl{id=yIh1rj9j} HostGeoInfo[West child: <address-ignored> at (0.0,-60.0)]
2015-11-17 10:22:38,978 INFO GeoDns TestService adding target host TestEntityImpl{id=aADbqED5} HostGeoInfo[East child with location: <address-ignored> at (0.0,60.0)]
2015-11-17 10:22:38,979 INFO GeoDns TestService adding target host TestEntityImpl{id=bUpbImlP} HostGeoInfo[North child: <address-ignored> at (60.0,0.0)]
2015-11-17 10:22:38,982 INFO GeoDns TestService adding target host TestEntityImpl{id=yIh1rj9j} HostGeoInfo[West child: <address-ignored> at (0.0,-60.0)]
2015-11-17 10:22:38,983 INFO GeoDns TestService adding target host TestEntityImpl{id=aADbqED5} HostGeoInfo[East child with location: <address-ignored> at (0.0,60.0)]
2015-11-17 10:22:38,983 INFO GeoDns TestService adding target host TestEntityImpl{id=bUpbImlP} HostGeoInfo[North child: <address-ignored> at (60.0,0.0)]
2015-11-17 10:22:38,984 INFO targets: {West child=HostGeoInfo[West child: <address-ignored> at (0.0,-60.0)], East child with location=HostGeoInfo[East child with location: <address-ignored> at (0.0,60.0)], North child=HostGeoInfo[North child: <address-ignored> at (60.0,0.0)]}
2015-11-17 10:22:38,984 INFO TESTNG PASSED: "Surefire test" - org.apache.brooklyn.entity.dns.AbstractGeoDnsServiceTest.testChildAddedLate() finished in 129 ms
2015-11-17 10:22:38,985 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.entity.dns.AbstractGeoDnsServiceTest.tearDown()
2015-11-17 10:22:38,998 INFO GeoDns TestService adding target host TestEntityImpl{id=yIh1rj9j} HostGeoInfo[West child: <address-ignored> at (0.0,-60.0)]
2015-11-17 10:22:38,999 INFO GeoDns TestService adding target host TestEntityImpl{id=aADbqED5} HostGeoInfo[East child with location: <address-ignored> at (0.0,60.0)]
2015-11-17 10:22:38,999 INFO GeoDns TestService adding target host TestEntityImpl{id=bUpbImlP} HostGeoInfo[North child: <address-ignored> at (60.0,0.0)]
2015-11-17 10:22:39,001 INFO GeoDns TestService adding target host TestEntityImpl{id=yIh1rj9j} HostGeoInfo[West child: <address-ignored> at (0.0,-60.0)]
2015-11-17 10:22:39,002 INFO GeoDns TestService adding target host TestEntityImpl{id=aADbqED5} HostGeoInfo[East child with location: <address-ignored> at (0.0,60.0)]
2015-11-17 10:22:39,003 INFO GeoDns TestService adding target host TestEntityImpl{id=bUpbImlP} HostGeoInfo[North child: <address-ignored> at (60.0,0.0)]
2015-11-17 10:22:39,005 INFO GeoDns TestService adding target host TestEntityImpl{id=yIh1rj9j} HostGeoInfo[West child: <address-ignored> at (0.0,-60.0)]
2015-11-17 10:22:39,005 INFO GeoDns TestService adding target host TestEntityImpl{id=aADbqED5} HostGeoInfo[East child with location: <address-ignored> at (0.0,60.0)]
2015-11-17 10:22:39,005 INFO GeoDns TestService adding target host TestEntityImpl{id=bUpbImlP} HostGeoInfo[North child: <address-ignored> at (60.0,0.0)]
2015-11-17 10:22:39,016 WARN Error calculating and setting aggregate for enricher Aggregator{uniqueTag=aggregator:fabric.size, running=true, entity=DynamicRegionsFabricImpl{id=bmHPnj9X}, id=SXOnVetd}
java.lang.IllegalStateException: Entity DynamicRegionsFabricImpl{id=bmHPnj9X} is no longer managed, when trying to publish Sensor: fabric.size (java.lang.Integer) 0
at org.apache.brooklyn.core.entity.AbstractEntity$BasicSensorSupport.emitInternal(AbstractEntity.java:1155) ~[brooklyn-core-0.9.0-SNAPSHOT.jar:0.9.0-SNAPSHOT]
2015-11-17 10:22:39,021 WARN Error calculating and setting aggregate for enricher Aggregator{uniqueTag=aggregator:fabric.size, running=true, entity=DynamicRegionsFabricImpl{id=bmHPnj9X}, id=SXOnVetd}
java.lang.IllegalStateException: Entity DynamicRegionsFabricImpl{id=bmHPnj9X} is no longer managed, when trying to publish Sensor: fabric.size (java.lang.Integer) 0
at org.apache.brooklyn.core.entity.AbstractEntity$BasicSensorSupport.emitInternal(AbstractEntity.java:1155) ~[brooklyn-core-0.9.0-SNAPSHOT.jar:0.9.0-SNAPSHOT]
2015-11-17 10:22:39,030 WARN Error calculating and setting aggregate for enricher Aggregator{uniqueTag=aggregator:fabric.size, running=true, entity=DynamicRegionsFabricImpl{id=bmHPnj9X}, id=SXOnVetd}
java.lang.IllegalStateException: Entity DynamicRegionsFabricImpl{id=bmHPnj9X} is no longer managed, when trying to publish Sensor: fabric.size (java.lang.Integer) 0
at org.apache.brooklyn.core.entity.AbstractEntity$BasicSensorSupport.emitInternal(AbstractEntity.java:1155) ~[brooklyn-core-0.9.0-SNAPSHOT.jar:0.9.0-SNAPSHOT]
2015-11-17 10:22:39,078 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.entity.dns.AbstractGeoDnsServiceTest.tearDown() finished in 94 ms
2015-11-17 10:22:39,079 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.entity.dns.AbstractGeoDnsServiceTest.setup()
SUREFIRE-859: [GC 240607K->69957K(733184K), 0.0089200 secs]
2015-11-17 10:22:39,526 WARN Deprecated use of unmanaged location (SshMachineLocation[West child:100.0.0.1/100.0.0.1:22@T0NhDFNi]); will be managed automatically now but not supported in future versions
2015-11-17 10:22:39,528 WARN Deprecated use of unmanaged location (SshMachineLocation[West child with location:100.0.0.1/100.0.0.1:22@UxpncodO]); will be managed automatically now but not supported in future versions
2015-11-17 10:22:39,531 WARN Deprecated use of unmanaged location (SshMachineLocation[East child:100.0.0.2/100.0.0.2:22@OUtYtbqN]); will be managed automatically now but not supported in future versions
2015-11-17 10:22:39,533 WARN Deprecated use of unmanaged location (SshMachineLocation[East child with location:100.0.0.2/100.0.0.2:22@GIZQ5wm9]); will be managed automatically now but not supported in future versions
2015-11-17 10:22:39,535 WARN Deprecated use of unmanaged location (SshMachineLocation[North child:10.0.0.1/10.0.0.1:22@TSMwMRDA]); will be managed automatically now but not supported in future versions
SUREFIRE-859: [GC 228677K->78890K(734720K), 0.0285190 secs]
SUREFIRE-859: [GC 237610K->92106K(737280K), 0.0213540 secs]
SUREFIRE-859: [GC 253386K->96248K(734720K), 0.0178330 secs]
SUREFIRE-859: [GC 257528K->95237K(741376K), 0.0213070 secs]
SUREFIRE-859: [GC 265221K->97156K(739328K), 0.0266710 secs]
SUREFIRE-859: [GC 267140K->98842K(745472K), 0.0188620 secs]
SUREFIRE-859: [GC 277018K->102210K(743424K), 0.0261850 secs]
2015-11-17 10:22:42,500 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.entity.dns.AbstractGeoDnsServiceTest.setup() finished in 3421 ms
2015-11-17 10:22:42,500 INFO TESTNG INVOKING: "Surefire test" - org.apache.brooklyn.entity.dns.AbstractGeoDnsServiceTest.testFiltersEntirelyPrivate()
2015-11-17 10:22:42,506 INFO Creating entity in fabric DynamicRegionsFabricImpl{id=NyHlwfe8} at SshMachineLocation[West child:100.0.0.1/100.0.0.1:22@T0NhDFNi]
2015-11-17 10:22:42,526 INFO Creating entity in fabric DynamicRegionsFabricImpl{id=NyHlwfe8} at SshMachineLocation[East child with location:100.0.0.2/100.0.0.2:22@GIZQ5wm9]
2015-11-17 10:22:42,549 INFO Creating entity in fabric DynamicRegionsFabricImpl{id=NyHlwfe8} at SshMachineLocation[North child:10.0.0.1/10.0.0.1:22@TSMwMRDA]
2015-11-17 10:22:42,576 INFO GeoDns adding TestEntityImpl{id=RXJtdqY5} at HostGeoInfo[West child: 100.0.0.1 at (0.0,-60.0)]
2015-11-17 10:22:42,577 INFO GeoDns adding TestEntityImpl{id=iqwJ5QJp} at HostGeoInfo[East child with location: 100.0.0.2 at (0.0,60.0)]
2015-11-17 10:22:42,577 WARN GeoDns ignoring TestEntityImpl{id=slragJU0} (private subnet detected for localhost)
2015-11-17 10:22:42,579 INFO TESTNG PASSED: "Surefire test" - org.apache.brooklyn.entity.dns.AbstractGeoDnsServiceTest.testFiltersEntirelyPrivate() finished in 79 ms
2015-11-17 10:22:42,579 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.entity.dns.AbstractGeoDnsServiceTest.tearDown()
2015-11-17 10:22:42,601 WARN When unsubscribing from all of DynamicRegionsFabricImpl{id=NyHlwfe8}, unsubscribe of Subscription[uikTlSQB;DynamicRegionsFabricImpl{id=NyHlwfe8}@RXJtdqY5:group.members.count] return false
2015-11-17 10:22:42,602 WARN Error calculating and setting aggregate for enricher Aggregator{uniqueTag=aggregator:fabric.size, running=true, entity=DynamicRegionsFabricImpl{id=NyHlwfe8}, id=kWjfkTch}
java.lang.IllegalStateException: Entity DynamicRegionsFabricImpl{id=NyHlwfe8} is no longer managed; subscription context not available
at org.apache.brooklyn.core.mgmt.internal.NonDeploymentManagementContext.getSubscriptionContext(NonDeploymentManagementContext.java:242) ~[brooklyn-core-0.9.0-SNAPSHOT.jar:0.9.0-SNAPSHOT]
2015-11-17 10:22:42,605 WARN Error calculating and setting aggregate for enricher Aggregator{uniqueTag=aggregator:fabric.size, running=true, entity=DynamicRegionsFabricImpl{id=NyHlwfe8}, id=kWjfkTch}
java.lang.IllegalStateException: Entity DynamicRegionsFabricImpl{id=NyHlwfe8} is no longer managed, when trying to publish Sensor: fabric.size (java.lang.Integer) 0
at org.apache.brooklyn.core.entity.AbstractEntity$BasicSensorSupport.emitInternal(AbstractEntity.java:1155) ~[brooklyn-core-0.9.0-SNAPSHOT.jar:0.9.0-SNAPSHOT]
2015-11-17 10:22:42,612 WARN Error calculating and setting aggregate for enricher Aggregator{uniqueTag=aggregator:fabric.size, running=true, entity=DynamicRegionsFabricImpl{id=NyHlwfe8}, id=kWjfkTch}
java.lang.IllegalStateException: Entity DynamicRegionsFabricImpl{id=NyHlwfe8} is no longer managed, when trying to publish Sensor: fabric.size (java.lang.Integer) 0
at org.apache.brooklyn.core.entity.AbstractEntity$BasicSensorSupport.emitInternal(AbstractEntity.java:1155) ~[brooklyn-core-0.9.0-SNAPSHOT.jar:0.9.0-SNAPSHOT]
2015-11-17 10:22:42,649 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.entity.dns.AbstractGeoDnsServiceTest.tearDown() finished in 69 ms
2015-11-17 10:22:42,649 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.entity.dns.AbstractGeoDnsServiceTest.setup()
SUREFIRE-859: [GC 280386K->86253K(744960K), 0.0098550 secs]
SUREFIRE-859: [GC 266477K->84450K(710144K), 0.0061760 secs]
2015-11-17 10:22:43,055 WARN Deprecated use of unmanaged location (SshMachineLocation[West child:100.0.0.1/100.0.0.1:22@QuzwMrIc]); will be managed automatically now but not supported in future versions
2015-11-17 10:22:43,058 WARN Deprecated use of unmanaged location (SshMachineLocation[West child with location:100.0.0.1/100.0.0.1:22@dE3DS1qV]); will be managed automatically now but not supported in future versions
2015-11-17 10:22:43,061 WARN Deprecated use of unmanaged location (SshMachineLocation[East child:100.0.0.2/100.0.0.2:22@tFCIh68u]); will be managed automatically now but not supported in future versions
2015-11-17 10:22:43,063 WARN Deprecated use of unmanaged location (SshMachineLocation[East child with location:100.0.0.2/100.0.0.2:22@NgvauuWv]); will be managed automatically now but not supported in future versions
2015-11-17 10:22:43,065 WARN Deprecated use of unmanaged location (SshMachineLocation[North child:10.0.0.1/10.0.0.1:22@kIX0C39D]); will be managed automatically now but not supported in future versions
SUREFIRE-859: [GC 264674K->104873K(745472K), 0.0188640 secs]
SUREFIRE-859: [GC 284073K->109147K(744448K), 0.0173720 secs]
SUREFIRE-859: [GC 288347K->110943K(746496K), 0.0172260 secs]
SUREFIRE-859: [GC 292703K->110848K(745984K), 0.0211190 secs]
SUREFIRE-859: [GC 292608K->112960K(748032K), 0.0192200 secs]
SUREFIRE-859: [GC 297792K->115589K(747520K), 0.0185590 secs]
2015-11-17 10:22:45,887 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.entity.dns.AbstractGeoDnsServiceTest.setup() finished in 3238 ms
2015-11-17 10:22:45,887 INFO TESTNG INVOKING: "Surefire test" - org.apache.brooklyn.entity.dns.AbstractGeoDnsServiceTest.testGeoInfoOnLocation()
2015-11-17 10:22:45,893 INFO Creating entity in fabric DynamicRegionsFabricImpl{id=wUK53IMe} at SshMachineLocation[West child with location:100.0.0.1/100.0.0.1:22@dE3DS1qV]
2015-11-17 10:22:45,914 INFO Creating entity in fabric DynamicRegionsFabricImpl{id=wUK53IMe} at SshMachineLocation[East child with location:100.0.0.2/100.0.0.2:22@NgvauuWv]
2015-11-17 10:22:45,914 INFO GeoDns TestService adding target host TestEntityImpl{id=ih1EhxxD} HostGeoInfo[West child with location: <address-ignored> at (0.0,-60.0)]
2015-11-17 10:22:45,920 INFO GeoDns TestService adding target host TestEntityImpl{id=ih1EhxxD} HostGeoInfo[West child with location: <address-ignored> at (0.0,-60.0)]
2015-11-17 10:22:45,933 INFO GeoDns TestService adding target host TestEntityImpl{id=ih1EhxxD} HostGeoInfo[West child with location: <address-ignored> at (0.0,-60.0)]
2015-11-17 10:22:45,933 INFO GeoDns TestService adding target host TestEntityImpl{id=RsG91XZI} HostGeoInfo[East child with location: <address-ignored> at (0.0,60.0)]
2015-11-17 10:22:45,937 INFO GeoDns TestService adding target host TestEntityImpl{id=ih1EhxxD} HostGeoInfo[West child with location: <address-ignored> at (0.0,-60.0)]
2015-11-17 10:22:45,938 INFO GeoDns TestService adding target host TestEntityImpl{id=RsG91XZI} HostGeoInfo[East child with location: <address-ignored> at (0.0,60.0)]
2015-11-17 10:22:45,941 INFO GeoDns TestService adding target host TestEntityImpl{id=ih1EhxxD} HostGeoInfo[West child with location: <address-ignored> at (0.0,-60.0)]
2015-11-17 10:22:45,942 INFO GeoDns TestService adding target host TestEntityImpl{id=RsG91XZI} HostGeoInfo[East child with location: <address-ignored> at (0.0,60.0)]
2015-11-17 10:22:45,942 INFO TESTNG PASSED: "Surefire test" - org.apache.brooklyn.entity.dns.AbstractGeoDnsServiceTest.testGeoInfoOnLocation() finished in 55 ms
2015-11-17 10:22:45,942 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.entity.dns.AbstractGeoDnsServiceTest.tearDown()
2015-11-17 10:22:45,943 INFO GeoDns TestService adding target host TestEntityImpl{id=ih1EhxxD} HostGeoInfo[West child with location: <address-ignored> at (0.0,-60.0)]
2015-11-17 10:22:45,944 INFO GeoDns TestService adding target host TestEntityImpl{id=RsG91XZI} HostGeoInfo[East child with location: <address-ignored> at (0.0,60.0)]
2015-11-17 10:22:45,945 INFO GeoDns TestService adding target host TestEntityImpl{id=ih1EhxxD} HostGeoInfo[West child with location: <address-ignored> at (0.0,-60.0)]
2015-11-17 10:22:45,946 INFO GeoDns TestService adding target host TestEntityImpl{id=RsG91XZI} HostGeoInfo[East child with location: <address-ignored> at (0.0,60.0)]
2015-11-17 10:22:45,947 INFO GeoDns TestService adding target host TestEntityImpl{id=ih1EhxxD} HostGeoInfo[West child with location: <address-ignored> at (0.0,-60.0)]
2015-11-17 10:22:45,948 INFO GeoDns TestService adding target host TestEntityImpl{id=RsG91XZI} HostGeoInfo[East child with location: <address-ignored> at (0.0,60.0)]
2015-11-17 10:22:45,950 INFO GeoDns TestService adding target host TestEntityImpl{id=ih1EhxxD} HostGeoInfo[West child with location: <address-ignored> at (0.0,-60.0)]
2015-11-17 10:22:45,951 INFO GeoDns TestService adding target host TestEntityImpl{id=RsG91XZI} HostGeoInfo[East child with location: <address-ignored> at (0.0,60.0)]
2015-11-17 10:22:45,953 INFO GeoDns TestService adding target host TestEntityImpl{id=ih1EhxxD} HostGeoInfo[West child with location: <address-ignored> at (0.0,-60.0)]
2015-11-17 10:22:45,953 INFO GeoDns TestService adding target host TestEntityImpl{id=RsG91XZI} HostGeoInfo[East child with location: <address-ignored> at (0.0,60.0)]
2015-11-17 10:22:45,962 WARN Error calculating and setting aggregate for enricher Aggregator{uniqueTag=aggregator:fabric.size, running=true, entity=DynamicRegionsFabricImpl{id=wUK53IMe}, id=hyKC8HFU}
java.lang.IllegalStateException: Entity DynamicRegionsFabricImpl{id=wUK53IMe} is no longer managed, when trying to publish Sensor: fabric.size (java.lang.Integer) 0
at org.apache.brooklyn.core.entity.AbstractEntity$BasicSensorSupport.emitInternal(AbstractEntity.java:1155) ~[brooklyn-core-0.9.0-SNAPSHOT.jar:0.9.0-SNAPSHOT]
2015-11-17 10:22:45,995 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.entity.dns.AbstractGeoDnsServiceTest.tearDown() finished in 53 ms
2015-11-17 10:22:45,996 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.entity.dns.AbstractGeoDnsServiceTest.setup()
SUREFIRE-859: [GC 300421K->99219K(746496K), 0.0116870 secs]
SUREFIRE-859: [GC 282192K->97644K(713728K), 0.0083640 secs]
2015-11-17 10:22:46,393 WARN Deprecated use of unmanaged location (SshMachineLocation[West child:100.0.0.1/100.0.0.1:22@PyPg58eR]); will be managed automatically now but not supported in future versions
2015-11-17 10:22:46,395 WARN Deprecated use of unmanaged location (SshMachineLocation[West child with location:100.0.0.1/100.0.0.1:22@z7QCpwR9]); will be managed automatically now but not supported in future versions
2015-11-17 10:22:46,397 WARN Deprecated use of unmanaged location (SshMachineLocation[East child:100.0.0.2/100.0.0.2:22@OPkgv9Ce]); will be managed automatically now but not supported in future versions
2015-11-17 10:22:46,399 WARN Deprecated use of unmanaged location (SshMachineLocation[East child with location:100.0.0.2/100.0.0.2:22@sygZ2Zjv]); will be managed automatically now but not supported in future versions
2015-11-17 10:22:46,401 WARN Deprecated use of unmanaged location (SshMachineLocation[North child:10.0.0.1/10.0.0.1:22@tQmCkj14]); will be managed automatically now but not supported in future versions
SUREFIRE-859: [GC 280940K->119591K(746496K), 0.0247470 secs]
SUREFIRE-859: [GC 301351K->123184K(745984K), 0.0196920 secs]
SUREFIRE-859: [GC 304944K->123519K(746496K), 0.0186390 secs]
SUREFIRE-859: [GC 305279K->123623K(745984K), 0.0332940 secs]
SUREFIRE-859: [GC 305383K->125899K(747520K), 0.0312010 secs]
SUREFIRE-859: [GC 309707K->127577K(747008K), 0.0175700 secs]
2015-11-17 10:22:49,252 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.entity.dns.AbstractGeoDnsServiceTest.setup() finished in 3256 ms
2015-11-17 10:22:49,253 INFO TESTNG INVOKING: "Surefire test" - org.apache.brooklyn.entity.dns.AbstractGeoDnsServiceTest.testGeoInfoOnParentLocation()
2015-11-17 10:22:49,259 INFO Creating entity in fabric DynamicRegionsFabricImpl{id=jgkWquaY} at SshMachineLocation[West child:100.0.0.1/100.0.0.1:22@PyPg58eR]
2015-11-17 10:22:49,276 INFO Creating entity in fabric DynamicRegionsFabricImpl{id=jgkWquaY} at SshMachineLocation[East child:100.0.0.2/100.0.0.2:22@OPkgv9Ce]
2015-11-17 10:22:49,278 INFO GeoDns TestService adding target host TestEntityImpl{id=iQA1K3f8} HostGeoInfo[West child: <address-ignored> at (0.0,-60.0)]
2015-11-17 10:22:49,282 INFO GeoDns TestService adding target host TestEntityImpl{id=iQA1K3f8} HostGeoInfo[West child: <address-ignored> at (0.0,-60.0)]
2015-11-17 10:22:49,293 INFO GeoDns TestService adding target host TestEntityImpl{id=iQA1K3f8} HostGeoInfo[West child: <address-ignored> at (0.0,-60.0)]
2015-11-17 10:22:49,293 INFO GeoDns TestService adding target host TestEntityImpl{id=LyZvdStp} HostGeoInfo[East child: <address-ignored> at (0.0,60.0)]
2015-11-17 10:22:49,296 INFO GeoDns TestService adding target host TestEntityImpl{id=iQA1K3f8} HostGeoInfo[West child: <address-ignored> at (0.0,-60.0)]
2015-11-17 10:22:49,296 INFO GeoDns TestService adding target host TestEntityImpl{id=LyZvdStp} HostGeoInfo[East child: <address-ignored> at (0.0,60.0)]
2015-11-17 10:22:49,299 INFO TESTNG PASSED: "Surefire test" - org.apache.brooklyn.entity.dns.AbstractGeoDnsServiceTest.testGeoInfoOnParentLocation() finished in 46 ms
2015-11-17 10:22:49,299 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.entity.dns.AbstractGeoDnsServiceTest.tearDown()
2015-11-17 10:22:49,308 INFO GeoDns TestService adding target host TestEntityImpl{id=iQA1K3f8} HostGeoInfo[West child: <address-ignored> at (0.0,-60.0)]
2015-11-17 10:22:49,308 INFO GeoDns TestService adding target host TestEntityImpl{id=LyZvdStp} HostGeoInfo[East child: <address-ignored> at (0.0,60.0)]
2015-11-17 10:22:49,311 INFO GeoDns TestService adding target host TestEntityImpl{id=iQA1K3f8} HostGeoInfo[West child: <address-ignored> at (0.0,-60.0)]
2015-11-17 10:22:49,311 INFO GeoDns TestService adding target host TestEntityImpl{id=LyZvdStp} HostGeoInfo[East child: <address-ignored> at (0.0,60.0)]
2015-11-17 10:22:49,318 WARN Error calculating and setting aggregate for enricher Aggregator{uniqueTag=aggregator:fabric.size, running=true, entity=DynamicRegionsFabricImpl{id=jgkWquaY}, id=xf3kxzxK}
java.lang.IllegalStateException: Entity DynamicRegionsFabricImpl{id=jgkWquaY} is no longer managed, when trying to publish Sensor: fabric.size (java.lang.Integer) 0
at org.apache.brooklyn.core.entity.AbstractEntity$BasicSensorSupport.emitInternal(AbstractEntity.java:1155) ~[brooklyn-core-0.9.0-SNAPSHOT.jar:0.9.0-SNAPSHOT]
2015-11-17 10:22:49,350 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.entity.dns.AbstractGeoDnsServiceTest.tearDown() finished in 51 ms
2015-11-17 10:22:49,351 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.entity.dns.AbstractGeoDnsServiceTest.setup()
SUREFIRE-859: [GC 311385K->108820K(745472K), 0.0130070 secs]
2015-11-17 10:22:49,734 WARN Deprecated use of unmanaged location (SshMachineLocation[West child:100.0.0.1/100.0.0.1:22@R6zmQ6eP]); will be managed automatically now but not supported in future versions
2015-11-17 10:22:49,736 WARN Deprecated use of unmanaged location (SshMachineLocation[West child with location:100.0.0.1/100.0.0.1:22@UkV2Tf9C]); will be managed automatically now but not supported in future versions
2015-11-17 10:22:49,738 WARN Deprecated use of unmanaged location (SshMachineLocation[East child:100.0.0.2/100.0.0.2:22@wrCZ64j9]); will be managed automatically now but not supported in future versions
2015-11-17 10:22:49,740 WARN Deprecated use of unmanaged location (SshMachineLocation[East child with location:100.0.0.2/100.0.0.2:22@MPqQ31Ql]); will be managed automatically now but not supported in future versions
2015-11-17 10:22:49,742 WARN Deprecated use of unmanaged location (SshMachineLocation[North child:10.0.0.1/10.0.0.1:22@YHtjfVxy]); will be managed automatically now but not supported in future versions
SUREFIRE-859: [GC 291092K->109537K(711680K), 0.0188850 secs]
SUREFIRE-859: [GC 291809K->133243K(745984K), 0.0200600 secs]
SUREFIRE-859: [GC 313979K->136387K(745472K), 0.0166070 secs]
SUREFIRE-859: [GC 317123K->135904K(745472K), 0.0185080 secs]
SUREFIRE-859: [GC 316128K->135875K(745472K), 0.0193010 secs]
SUREFIRE-859: [GC 316099K->138376K(746496K), 0.0201160 secs]
SUREFIRE-859: [GC 320648K->139920K(746496K), 0.0183270 secs]
2015-11-17 10:22:52,479 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.entity.dns.AbstractGeoDnsServiceTest.setup() finished in 3128 ms
2015-11-17 10:22:52,479 INFO TESTNG INVOKING: "Surefire test" - org.apache.brooklyn.entity.dns.AbstractGeoDnsServiceTest.testSubscribesToHostname()
2015-11-17 10:22:52,485 INFO Creating entity in fabric DynamicRegionsFabricImpl{id=hoyWxNTP} at SshMachineLocation[West child:100.0.0.1/100.0.0.1:22@R6zmQ6eP]
2015-11-17 10:22:52,504 INFO Creating entity in fabric DynamicRegionsFabricImpl{id=hoyWxNTP} at SshMachineLocation[East child with location:100.0.0.2/100.0.0.2:22@MPqQ31Ql]
2015-11-17 10:22:52,529 INFO GeoDns adding TestEntityImpl{id=kujBcxND} at HostGeoInfo[West child: 100.0.0.1 at (0.0,-60.0)]
2015-11-17 10:22:52,529 INFO GeoDns adding TestEntityImpl{id=TBiEHMa8} at HostGeoInfo[East child with location: 100.0.0.2 at (0.0,60.0)]
2015-11-17 10:22:52,531 INFO TESTNG PASSED: "Surefire test" - org.apache.brooklyn.entity.dns.AbstractGeoDnsServiceTest.testSubscribesToHostname() finished in 52 ms
2015-11-17 10:22:52,531 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.entity.dns.AbstractGeoDnsServiceTest.tearDown()
2015-11-17 10:22:52,589 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.entity.dns.AbstractGeoDnsServiceTest.tearDown() finished in 58 ms
2015-11-17 10:22:52,592 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.entity.webapp.DynamicWebAppFabricTest.setUp()
2015-11-17 10:22:52,611 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.entity.webapp.DynamicWebAppFabricTest.setUp() finished in 20 ms
2015-11-17 10:22:52,611 INFO TESTNG INVOKING: "Surefire test" - org.apache.brooklyn.entity.webapp.DynamicWebAppFabricTest.testRequestCountAggregation()
2015-11-17 10:22:52,677 INFO Creating entity in fabric DynamicWebAppFabricImpl{id=Xe5M6N28} at SimulatedLocation{id=ulIBP9kA, name=SimulatedLocation:ulIB}
2015-11-17 10:22:52,707 INFO Creating entity in fabric DynamicWebAppFabricImpl{id=Xe5M6N28} at SimulatedLocation{id=nHwPTp47, name=SimulatedLocation:nHwP}
2015-11-17 10:22:52,747 INFO TESTNG PASSED: "Surefire test" - org.apache.brooklyn.entity.webapp.DynamicWebAppFabricTest.testRequestCountAggregation() finished in 136 ms
2015-11-17 10:22:52,748 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.entity.webapp.DynamicWebAppFabricTest.tearDown()
SUREFIRE-859: [GC 322192K->118796K(743936K), 0.0142690 secs]
2015-11-17 10:22:52,776 WARN When unsubscribing from all of DynamicWebAppFabricImpl{id=Xe5M6N28}, unsubscribe of Subscription[z5dgm1SB;DynamicWebAppFabricImpl{id=Xe5M6N28}@IiPSPkL0:service.isUp] return false
2015-11-17 10:22:52,776 WARN When unsubscribing from all of DynamicWebAppFabricImpl{id=Xe5M6N28}, unsubscribe of Subscription[QAmXDTm0;DynamicWebAppFabricImpl{id=Xe5M6N28}@IiPSPkL0:service.state] return false
2015-11-17 10:22:52,779 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.entity.webapp.DynamicWebAppFabricTest.tearDown() finished in 31 ms
2015-11-17 10:22:52,779 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.entity.webapp.DynamicWebAppFabricTest.setUp()
2015-11-17 10:22:52,797 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.entity.webapp.DynamicWebAppFabricTest.setUp() finished in 18 ms
2015-11-17 10:22:52,797 INFO TESTNG INVOKING: "Surefire test" - org.apache.brooklyn.entity.webapp.DynamicWebAppFabricTest.testRequestCountAggregationOverClusters()
2015-11-17 10:22:52,839 INFO Creating entity in fabric DynamicWebAppFabricImpl{id=ewTCtRRQ} at SimulatedLocation{id=IP0KVDRO, name=SimulatedLocation:IP0K}
2015-11-17 10:22:52,881 INFO Creating entity in fabric DynamicWebAppFabricImpl{id=ewTCtRRQ} at SimulatedLocation{id=F5TYt6Cp, name=SimulatedLocation:F5TY}
2015-11-17 10:22:52,915 INFO Resize DynamicWebAppClusterImpl{id=P7MaGX4j} from 0 to 2
2015-11-17 10:22:52,938 INFO Resize DynamicWebAppClusterImpl{id=EAjoX9aX} from 0 to 2
2015-11-17 10:22:53,015 INFO TESTNG PASSED: "Surefire test" - org.apache.brooklyn.entity.webapp.DynamicWebAppFabricTest.testRequestCountAggregationOverClusters() finished in 218 ms
2015-11-17 10:22:53,016 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.entity.webapp.DynamicWebAppFabricTest.tearDown()
2015-11-17 10:22:53,042 WARN Error calculating and setting aggregate for enricher Aggregator{uniqueTag=aggregator:webapp.reqs.perSec.last, running=true, entity=DynamicWebAppFabricImpl{id=ewTCtRRQ}, id=dnzIk0Ct}
java.lang.IllegalStateException: Entity DynamicWebAppFabricImpl{id=ewTCtRRQ} is no longer managed; subscription context not available
at org.apache.brooklyn.core.mgmt.internal.NonDeploymentManagementContext.getSubscriptionContext(NonDeploymentManagementContext.java:242) ~[brooklyn-core-0.9.0-SNAPSHOT.jar:0.9.0-SNAPSHOT]
2015-11-17 10:22:53,044 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.entity.webapp.DynamicWebAppFabricTest.tearDown() finished in 28 ms
2015-11-17 10:22:53,045 WARN Error calculating and setting aggregate for enricher Aggregator{uniqueTag=aggregator:webapp.reqs.perSec.windowed, running=true, entity=DynamicWebAppFabricImpl{id=ewTCtRRQ}, id=XfMFSzc0}
java.lang.IllegalStateException: Entity DynamicWebAppFabricImpl{id=ewTCtRRQ} is no longer managed, when trying to publish Sensor: webapp.reqs.perSec.windowed (java.lang.Double) null
at org.apache.brooklyn.core.entity.AbstractEntity$BasicSensorSupport.emitInternal(AbstractEntity.java:1155) ~[brooklyn-core-0.9.0-SNAPSHOT.jar:0.9.0-SNAPSHOT]
2015-11-17 10:22:53,045 INFO TESTNG INVOKING: "Surefire test" - org.apache.brooklyn.entity.webapp.FilenameToWebContextMapperTest.testInferFromUrl()
2015-11-17 10:22:53,047 INFO TESTNG PASSED: "Surefire test" - org.apache.brooklyn.entity.webapp.FilenameToWebContextMapperTest.testInferFromUrl() finished in 2 ms
2015-11-17 10:22:53,047 INFO TESTNG INVOKING: "Surefire test" - org.apache.brooklyn.entity.webapp.FilenameToWebContextMapperTest.testOtherNames()
2015-11-17 10:22:53,047 INFO TESTNG PASSED: "Surefire test" - org.apache.brooklyn.entity.webapp.FilenameToWebContextMapperTest.testOtherNames() finished in 0 ms
2015-11-17 10:22:53,048 INFO TESTNG INVOKING: "Surefire test" - org.apache.brooklyn.entity.webapp.FilenameToWebContextMapperTest.testRootNames()
2015-11-17 10:22:53,048 INFO TESTNG PASSED: "Surefire test" - org.apache.brooklyn.entity.webapp.FilenameToWebContextMapperTest.testRootNames() finished in 0 ms
2015-11-17 10:22:53,048 INFO TESTNG INVOKING: "Surefire test" - org.apache.brooklyn.entity.dns.geoscaling.GeoscalingScriptGeneratorTest.testScriptGeneration()
2015-11-17 10:22:53,054 INFO TESTNG PASSED: "Surefire test" - org.apache.brooklyn.entity.dns.geoscaling.GeoscalingScriptGeneratorTest.testScriptGeneration() finished in 6 ms
2015-11-17 10:22:53,055 INFO TESTNG INVOKING: "Surefire test" - org.apache.brooklyn.entity.webapp.jboss.JBoss7PasswordHashingTest.testPasswordForApplicationRealm()
2015-11-17 10:22:53,078 INFO TESTNG PASSED: "Surefire test" - org.apache.brooklyn.entity.webapp.jboss.JBoss7PasswordHashingTest.testPasswordForApplicationRealm() finished in 23 ms
2015-11-17 10:22:53,079 INFO TESTNG INVOKING: "Surefire test" - org.apache.brooklyn.entity.webapp.jboss.JBoss7PasswordHashingTest.testPasswordForCustomRealm()
2015-11-17 10:22:53,079 INFO TESTNG PASSED: "Surefire test" - org.apache.brooklyn.entity.webapp.jboss.JBoss7PasswordHashingTest.testPasswordForCustomRealm() finished in 0 ms
2015-11-17 10:22:53,079 INFO TESTNG INVOKING: "Surefire test" - org.apache.brooklyn.entity.webapp.jboss.JBoss7PasswordHashingTest.testPasswordForManagementRealm()
2015-11-17 10:22:53,080 INFO TESTNG PASSED: "Surefire test" - org.apache.brooklyn.entity.webapp.jboss.JBoss7PasswordHashingTest.testPasswordForManagementRealm() finished in 1 ms
2015-11-17 10:22:53,081 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.entity.webapp.DynamicWebAppClusterTest.setUp()
2015-11-17 10:22:53,096 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.entity.webapp.DynamicWebAppClusterTest.setUp() finished in 15 ms
2015-11-17 10:22:53,097 INFO TESTNG INVOKING: "Surefire test" - org.apache.brooklyn.entity.webapp.DynamicWebAppClusterTest.testPropertiesToChildren()
2015-11-17 10:22:53,155 INFO Resize DynamicWebAppClusterImpl{id=b9MAKWG7} from 0 to 1
2015-11-17 10:22:53,166 WARN Unsupported flags when configuring TestJavaWebAppEntityImpl{id=wTKq0oLP}; storing: {a=1, b=2}
2015-11-17 10:22:53,196 INFO TESTNG PASSED: "Surefire test" - org.apache.brooklyn.entity.webapp.DynamicWebAppClusterTest.testPropertiesToChildren() finished in 99 ms
2015-11-17 10:22:53,197 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.entity.webapp.DynamicWebAppClusterTest.tearDown()
2015-11-17 10:22:53,209 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.entity.webapp.DynamicWebAppClusterTest.tearDown() finished in 12 ms
2015-11-17 10:22:53,210 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.entity.webapp.DynamicWebAppClusterTest.setUp()
2015-11-17 10:22:53,226 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.entity.webapp.DynamicWebAppClusterTest.setUp() finished in 17 ms
2015-11-17 10:22:53,226 INFO TESTNG INVOKING: "Surefire test" - org.apache.brooklyn.entity.webapp.DynamicWebAppClusterTest.testRequestCountAggregation()
2015-11-17 10:22:53,295 INFO Resize DynamicWebAppClusterImpl{id=BYtPnTx7} from 0 to 2
2015-11-17 10:22:53,358 INFO TESTNG PASSED: "Surefire test" - org.apache.brooklyn.entity.webapp.DynamicWebAppClusterTest.testRequestCountAggregation() finished in 132 ms
2015-11-17 10:22:53,359 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.entity.webapp.DynamicWebAppClusterTest.tearDown()
2015-11-17 10:22:53,377 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.entity.webapp.DynamicWebAppClusterTest.tearDown() finished in 18 ms
2015-11-17 10:22:53,378 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.entity.webapp.DynamicWebAppClusterTest.setUp()
2015-11-17 10:22:53,395 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.entity.webapp.DynamicWebAppClusterTest.setUp() finished in 17 ms
2015-11-17 10:22:53,395 INFO TESTNG INVOKING: "Surefire test" - org.apache.brooklyn.entity.webapp.DynamicWebAppClusterTest.testSetsServiceUpIfMemberIsUp()
2015-11-17 10:22:53,453 INFO Resize DynamicWebAppClusterImpl{id=MimI1L5W} from 0 to 1
2015-11-17 10:22:53,490 WARN Setting TestJavaWebAppEntityImpl{id=xKoMhIVw} on-fire due to problems when expected running, up=false, not-up-indicators: {}
2015-11-17 10:22:53,492 WARN Setting Application[SbBVJNPC] on-fire due to problems when expected running, up=false, not-up-indicators: {service-lifecycle-indicators-from-children-and-members=DynamicWebAppClusterImpl{id=MimI1L5W} is not up}
2015-11-17 10:22:53,492 WARN Setting DynamicWebAppClusterImpl{id=MimI1L5W} on-fire due to problems when expected running, up=false, problems: {service-lifecycle-indicators-from-children-and-members=Required entity not healthy: TestJavaWebAppEntityImpl{id=xKoMhIVw}}
2015-11-17 10:22:53,747 INFO Resize DynamicWebAppClusterImpl{id=MimI1L5W} from 1 to 2
2015-11-17 10:22:53,785 INFO TESTNG PASSED: "Surefire test" - org.apache.brooklyn.entity.webapp.DynamicWebAppClusterTest.testSetsServiceUpIfMemberIsUp() finished in 390 ms
2015-11-17 10:22:53,786 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.entity.webapp.DynamicWebAppClusterTest.tearDown()
2015-11-17 10:22:53,799 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.entity.webapp.DynamicWebAppClusterTest.tearDown() finished in 14 ms
2015-11-17 10:22:53,800 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.entity.webapp.DynamicWebAppClusterTest.setUp()
2015-11-17 10:22:53,819 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.entity.webapp.DynamicWebAppClusterTest.setUp() finished in 19 ms
2015-11-17 10:22:53,819 INFO TESTNG INVOKING: "Surefire test" - org.apache.brooklyn.entity.webapp.DynamicWebAppClusterTest.testTestJavaWebAppEntityStarts()
2015-11-17 10:22:53,841 INFO TESTNG PASSED: "Surefire test" - org.apache.brooklyn.entity.webapp.DynamicWebAppClusterTest.testTestJavaWebAppEntityStarts() finished in 22 ms
2015-11-17 10:22:53,842 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.entity.webapp.DynamicWebAppClusterTest.tearDown()
2015-11-17 10:22:53,849 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.entity.webapp.DynamicWebAppClusterTest.tearDown() finished in 8 ms
2015-11-17 10:22:53,850 INFO TESTNG INVOKING: "Surefire test" - org.apache.brooklyn.entity.proxy.ProxySslConfigTest.testFromMap()
2015-11-17 10:22:53,853 INFO TESTNG PASSED: "Surefire test" - org.apache.brooklyn.entity.proxy.ProxySslConfigTest.testFromMap() finished in 3 ms
2015-11-17 10:22:53,853 INFO TESTNG INVOKING: "Surefire test" - org.apache.brooklyn.entity.proxy.ProxySslConfigTest.testFromMapWithNullsAndDefaults()
2015-11-17 10:22:53,854 INFO TESTNG PASSED: "Surefire test" - org.apache.brooklyn.entity.proxy.ProxySslConfigTest.testFromMapWithNullsAndDefaults() finished in 1 ms
2015-11-17 10:22:53,854 INFO TESTNG INVOKING: "Surefire test" - org.apache.brooklyn.entity.webapp.HttpsSslConfigTest.testCoerce()
2015-11-17 10:22:53,855 INFO TESTNG PASSED: "Surefire test" - org.apache.brooklyn.entity.webapp.HttpsSslConfigTest.testCoerce() finished in 1 ms
2015-11-17 10:22:53,856 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.entity.proxy.UrlMappingTest.setup()
SUREFIRE-859: [GC 299020K->122995K(745472K), 0.0176440 secs]
2015-11-17 10:22:54,006 WARN Task Task[LSM.publish(DynamicWebAppClusterImpl{id=BYtPnTx7}.Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=QuarantineGroupImpl{id=t14wRW3x} @ 1447755773374);[subscription-delivery-entity-BYtPnTx7[DynamicWebAppClusterImpl{id=BYtPnTx7}]]:sXDeUE58] is being finalized before completion
2015-11-17 10:22:54,007 WARN Task Task[LSM.publish(DynamicWebAppClusterImpl{id=BYtPnTx7}.Sensor: service.state.expected (org.apache.brooklyn.core.entity.lifecycle.Lifecycle$Transition)=stopped @ 1447755773373 / Tue Nov 17 10:22:53 UTC 2015 @ 1447755773373);[subscription-delivery-entity-BYtPnTx7[DynamicWebAppClusterImpl{id=BYtPnTx7}]]:Z0sNleNK] is being finalized before completion
2015-11-17 10:22:54,007 WARN Task Task[LSM.publish(DynamicWebAppClusterImpl{id=BYtPnTx7}.Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=TestJavaWebAppEntityImpl{id=WoMPRPfo} @ 1447755773371);[subscription-delivery-entity-BYtPnTx7[DynamicWebAppClusterImpl{id=BYtPnTx7}]]:fFblgGEG] is being finalized before completion
2015-11-17 10:22:54,007 WARN Task Task[LSM.publish(DynamicWebAppClusterImpl{id=BYtPnTx7}.Sensor: service.notUp.indicators (java.util.Map)={service-not-up-indicators-from-children-and-members=No entities present} @ 1447755773371);[subscription-delivery-entity-BYtPnTx7[DynamicWebAppClusterImpl{id=BYtPnTx7}]]:EVsmPnOT] is being finalized before completion
2015-11-17 10:22:54,008 WARN Task Task[LSM.publish(DynamicWebAppClusterImpl{id=BYtPnTx7}.Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=TestJavaWebAppEntityImpl{id=dBYNtZCh} @ 1447755773371);[subscription-delivery-entity-BYtPnTx7[DynamicWebAppClusterImpl{id=BYtPnTx7}]]:wErrch4h] is being finalized before completion
2015-11-17 10:22:54,008 WARN Task Task[LSM.publish(TestJavaWebAppEntityImpl{id=WoMPRPfo}.Sensor: service.state (org.apache.brooklyn.core.entity.lifecycle.Lifecycle)=stopped @ 1447755773370);[subscription-delivery-entity-BYtPnTx7[DynamicWebAppClusterImpl{id=BYtPnTx7}]]:SBCmIiVp] is being finalized before completion
2015-11-17 10:22:54,008 WARN Task Task[LSM.publish(TestJavaWebAppEntityImpl{id=WoMPRPfo}.Sensor: service.state (org.apache.brooklyn.core.entity.lifecycle.Lifecycle)=stopped @ 1447755773370);[subscription-delivery-entity-BYtPnTx7[DynamicWebAppClusterImpl{id=BYtPnTx7}]]:mEAiYFmU] is being finalized before completion
2015-11-17 10:22:54,009 WARN Task Task[LSM.publish(TestJavaWebAppEntityImpl{id=WoMPRPfo}.Sensor: service.state (org.apache.brooklyn.core.entity.lifecycle.Lifecycle)=stopped @ 1447755773370);[subscription-delivery-entity-BYtPnTx7[DynamicWebAppClusterImpl{id=BYtPnTx7}]]:cWEwAJY3] is being finalized before completion
2015-11-17 10:22:54,009 WARN Task Task[LSM.publish(TestJavaWebAppEntityImpl{id=WoMPRPfo}.Sensor: service.isUp (java.lang.Boolean)=false @ 1447755773369);[subscription-delivery-entity-BYtPnTx7[DynamicWebAppClusterImpl{id=BYtPnTx7}]]:HM5qX7ID] is being finalized before completion
2015-11-17 10:22:54,009 WARN Task Task[LSM.publish(TestJavaWebAppEntityImpl{id=WoMPRPfo}.Sensor: service.isUp (java.lang.Boolean)=false @ 1447755773369);[subscription-delivery-entity-BYtPnTx7[DynamicWebAppClusterImpl{id=BYtPnTx7}]]:hqGAXBm1] is being finalized before completion
2015-11-17 10:22:54,010 WARN Task Task[LSM.publish(TestJavaWebAppEntityImpl{id=WoMPRPfo}.Sensor: service.isUp (java.lang.Boolean)=false @ 1447755773369);[subscription-delivery-entity-BYtPnTx7[DynamicWebAppClusterImpl{id=BYtPnTx7}]]:ntUM859T] is being finalized before completion
2015-11-17 10:22:54,010 WARN Task Task[LSM.publish(TestJavaWebAppEntityImpl{id=WoMPRPfo}.Sensor: service.state (org.apache.brooklyn.core.entity.lifecycle.Lifecycle)=stopping @ 1447755773368);[subscription-delivery-entity-BYtPnTx7[DynamicWebAppClusterImpl{id=BYtPnTx7}]]:I35oHCkP] is being finalized before completion
2015-11-17 10:22:54,010 WARN Task Task[LSM.publish(TestJavaWebAppEntityImpl{id=WoMPRPfo}.Sensor: service.state (org.apache.brooklyn.core.entity.lifecycle.Lifecycle)=stopping @ 1447755773368);[subscription-delivery-entity-BYtPnTx7[DynamicWebAppClusterImpl{id=BYtPnTx7}]]:DhutdMPC] is being finalized before completion
2015-11-17 10:22:54,016 WARN Task Task[LSM.publish(DynamicWebAppClusterImpl{id=b9MAKWG7}.Sensor: service.state.expected (org.apache.brooklyn.core.entity.lifecycle.Lifecycle$Transition)=stopped @ 1447755773205 / Tue Nov 17 10:22:53 UTC 2015 @ 1447755773205);[subscription-delivery-entity-b9MAKWG7[DynamicWebAppClusterImpl{id=b9MAKWG7}]]:LmumhrNj] is being finalized before completion
2015-11-17 10:22:54,016 WARN Task Task[LSM.publish(DynamicWebAppClusterImpl{id=b9MAKWG7}.Sensor: service.isUp (java.lang.Boolean)=false @ 1447755773206);[subscription-delivery-entity-b9MAKWG7[DynamicWebAppClusterImpl{id=b9MAKWG7}]]:OfJkOUu8] is being finalized before completion
2015-11-17 10:22:54,016 WARN Task Task[LSM.publish(DynamicWebAppClusterImpl{id=b9MAKWG7}.Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=QuarantineGroupImpl{id=bQd30HiG} @ 1447755773206);[subscription-delivery-entity-b9MAKWG7[DynamicWebAppClusterImpl{id=b9MAKWG7}]]:knqwLbZD] is being finalized before completion
2015-11-17 10:22:54,532 INFO Geo info lookup for penates.apache.org/67.195.81.186 returned: HostGeoInfo[Yahoo, Sunnyvale (US): penates.apache.org at (37.424900054932,-122.0074005127)]
2015-11-17 10:22:54,565 INFO Resize DynamicClusterImpl{id=fRB53i83} from 0 to 2
2015-11-17 10:22:54,569 WARN Unsupported flags when configuring StubAppServer{id=WGaMcanJ}; storing: {cluster.member.id=0}
2015-11-17 10:22:54,569 WARN Deprecated legacy compatibility, using factory (init will not be invoked): StubAppServer{id=WGaMcanJ}
2015-11-17 10:22:54,569 WARN Deprecated use of Entities.manage(Entity), for unmanaged entity StubAppServer{id=WGaMcanJ}
2015-11-17 10:22:54,573 WARN Unsupported flags when configuring StubAppServer{id=pN0gh3hL}; storing: {cluster.member.id=1}
2015-11-17 10:22:54,573 WARN Deprecated legacy compatibility, using factory (init will not be invoked): StubAppServer{id=pN0gh3hL}
2015-11-17 10:22:54,574 WARN Deprecated use of Entities.manage(Entity), for unmanaged entity StubAppServer{id=pN0gh3hL}
2015-11-17 10:22:54,625 INFO app's location managed: true
2015-11-17 10:22:54,625 INFO clusters's location managed: true
2015-11-17 10:22:54,626 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.entity.proxy.UrlMappingTest.setup() finished in 770 ms
2015-11-17 10:22:54,626 INFO TESTNG INVOKING: "Surefire test" - org.apache.brooklyn.entity.proxy.UrlMappingTest.testTargetMappingUpdatesAfterRebind()
2015-11-17 10:22:54,626 INFO starting testTargetMappingUpdatesAfterRebind
2015-11-17 10:22:54,657 INFO Rebinding app, using mementoDir /tmp/1447755773856-0; object store null
2015-11-17 10:22:54,799 INFO Rebinding from /tmp/1447755773856-0 for master fhSvpx61...
2015-11-17 10:22:54,840 WARN Deprecated rebind of entity without no-arg constructor; this may not be supported in future versions: id=WGaMcanJ; type=org.apache.brooklyn.entity.proxy.StubAppServer
2015-11-17 10:22:54,844 WARN Unsupported flags when configuring StubAppServer{id=WGaMcanJ}; storing: {id=WGaMcanJ}
2015-11-17 10:22:54,845 WARN Deprecated rebind of entity without no-arg constructor; this may not be supported in future versions: id=pN0gh3hL; type=org.apache.brooklyn.entity.proxy.StubAppServer
2015-11-17 10:22:54,846 WARN Unsupported flags when configuring StubAppServer{id=pN0gh3hL}; storing: {id=pN0gh3hL}
2015-11-17 10:22:54,940 INFO Rebind complete (MASTER) in 265ms: 1 app, 6 entities, 3 locations, 0 policies, 12 enrichers, 0 feeds, 0 catalog items
2015-11-17 10:22:54,942 INFO resizing DynamicClusterImpl{id=fRB53i83} - [QuarantineGroupImpl{id=DtILMDnj}, StubAppServer{id=WGaMcanJ}, StubAppServer{id=pN0gh3hL}]
2015-11-17 10:22:54,943 INFO Resize DynamicClusterImpl{id=fRB53i83} from 2 to 3
2015-11-17 10:22:54,945 WARN Unsupported flags when configuring StubAppServer{id=AVCXzAZl}; storing: {cluster.member.id=2}
2015-11-17 10:22:54,946 WARN Deprecated legacy compatibility, using factory (init will not be invoked): StubAppServer{id=AVCXzAZl}
2015-11-17 10:22:54,946 WARN Deprecated use of Entities.manage(Entity), for unmanaged entity StubAppServer{id=AVCXzAZl}
2015-11-17 10:22:54,966 INFO resized DynamicClusterImpl{id=fRB53i83} (3) - [QuarantineGroupImpl{id=DtILMDnj}, StubAppServer{id=WGaMcanJ}, StubAppServer{id=pN0gh3hL}, StubAppServer{id=AVCXzAZl}]
2015-11-17 10:22:54,966 INFO expecting [StubAppServer{id=WGaMcanJ}, StubAppServer{id=pN0gh3hL}, StubAppServer{id=AVCXzAZl}]
2015-11-17 10:22:54,966 INFO pretending one node down
2015-11-17 10:22:54,970 INFO unmanaging another node
2015-11-17 10:22:54,973 INFO success - testTargetMappingUpdatesAfterRebind
2015-11-17 10:22:54,973 INFO TESTNG PASSED: "Surefire test" - org.apache.brooklyn.entity.proxy.UrlMappingTest.testTargetMappingUpdatesAfterRebind() finished in 347 ms
2015-11-17 10:22:54,973 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.entity.proxy.UrlMappingTest.shutdown()
2015-11-17 10:22:54,996 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.entity.proxy.UrlMappingTest.shutdown() finished in 23 ms
2015-11-17 10:22:54,997 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.entity.webapp.ControlledDynamicWebAppClusterTest.setUp()
2015-11-17 10:22:55,024 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.entity.webapp.ControlledDynamicWebAppClusterTest.setUp() finished in 28 ms
2015-11-17 10:22:55,025 INFO TESTNG INVOKING: "Surefire test" - org.apache.brooklyn.entity.webapp.ControlledDynamicWebAppClusterTest.testMembersReflectChildClusterMembers()
2015-11-17 10:22:55,128 INFO Starting TrackingAbstractControllerImpl{id=DR0RGJCG} on machine SshMachineLocation[localhost:penates.apache.org/67.195.81.186:22@BpGX8II4]
2015-11-17 10:22:55,134 INFO Resize DynamicWebAppClusterImpl{id=PcmyDeqB} from 0 to 1
SUREFIRE-859: [GC 303219K->123136K(744448K), 0.0185280 secs]
2015-11-17 10:22:55,241 ERROR Unable to construct hostname:port representation for TestJavaWebAppEntityImpl{id=lcTcZ4Wh} (null:null); skipping in TrackingAbstractControllerImpl{id=DR0RGJCG}
2015-11-17 10:22:55,241 INFO Added policy ServerPoolMemberTrackerPolicy{name=Controller targets tracker, running=true} to TrackingAbstractControllerImpl{id=DR0RGJCG}
2015-11-17 10:22:55,241 ERROR Unable to construct hostname:port representation for TestJavaWebAppEntityImpl{id=lcTcZ4Wh} (null:null); skipping in TrackingAbstractControllerImpl{id=DR0RGJCG}
2015-11-17 10:22:55,241 INFO Resetting TrackingAbstractControllerImpl{id=DR0RGJCG}, server pool targets {TestJavaWebAppEntityImpl{id=lcTcZ4Wh}=null}
2015-11-17 10:22:55,242 INFO Updating TrackingAbstractControllerImpl{id=DR0RGJCG}, server pool targets {TestJavaWebAppEntityImpl{id=lcTcZ4Wh}=null}
2015-11-17 10:22:55,242 INFO test controller reconfigure, targets []
2015-11-17 10:22:55,246 INFO Updating TrackingAbstractControllerImpl{id=DR0RGJCG}, server pool targets {TestJavaWebAppEntityImpl{id=lcTcZ4Wh}=null}
2015-11-17 10:22:55,246 INFO test controller reconfigure, targets []
2015-11-17 10:22:55,258 INFO Resize DynamicWebAppClusterImpl{id=PcmyDeqB} from 1 to 2
2015-11-17 10:22:55,286 ERROR Unable to construct hostname:port representation for TestJavaWebAppEntityImpl{id=WuMh2zgx} (null:null); skipping in TrackingAbstractControllerImpl{id=DR0RGJCG}
2015-11-17 10:22:55,288 INFO Resize DynamicWebAppClusterImpl{id=PcmyDeqB} from 2 to 1
2015-11-17 10:22:55,295 INFO TESTNG PASSED: "Surefire test" - org.apache.brooklyn.entity.webapp.ControlledDynamicWebAppClusterTest.testMembersReflectChildClusterMembers() finished in 270 ms
2015-11-17 10:22:55,296 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.core.test.BrooklynAppUnitTestSupport.tearDown()
2015-11-17 10:22:55,300 INFO Stopping TrackingAbstractControllerImpl{id=DR0RGJCG} in [SshMachineLocation[localhost:penates.apache.org/67.195.81.186:22@BpGX8II4]]
2015-11-17 10:22:55,320 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.core.test.BrooklynAppUnitTestSupport.tearDown() finished in 24 ms
2015-11-17 10:22:55,321 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.entity.webapp.ControlledDynamicWebAppClusterTest.setUp()
2015-11-17 10:22:55,343 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.entity.webapp.ControlledDynamicWebAppClusterTest.setUp() finished in 22 ms
2015-11-17 10:22:55,343 INFO TESTNG INVOKING: "Surefire test" - org.apache.brooklyn.entity.webapp.ControlledDynamicWebAppClusterTest.testSetsInitialSize()
2015-11-17 10:22:55,422 INFO Starting TrackingAbstractControllerImpl{id=jn2YhSKy} on machine SshMachineLocation[localhost:penates.apache.org/67.195.81.186:22@CmvCoXxY]
2015-11-17 10:22:55,428 INFO Resize DynamicWebAppClusterImpl{id=uePEn759} from 0 to 2
2015-11-17 10:22:55,530 ERROR Unable to construct hostname:port representation for TestJavaWebAppEntityImpl{id=HPUEHH5D} (null:null); skipping in TrackingAbstractControllerImpl{id=jn2YhSKy}
2015-11-17 10:22:55,530 ERROR Unable to construct hostname:port representation for TestJavaWebAppEntityImpl{id=OLMdphhN} (null:null); skipping in TrackingAbstractControllerImpl{id=jn2YhSKy}
2015-11-17 10:22:55,531 INFO Added policy ServerPoolMemberTrackerPolicy{name=Controller targets tracker, running=true} to TrackingAbstractControllerImpl{id=jn2YhSKy}
2015-11-17 10:22:55,531 ERROR Unable to construct hostname:port representation for TestJavaWebAppEntityImpl{id=HPUEHH5D} (null:null); skipping in TrackingAbstractControllerImpl{id=jn2YhSKy}
2015-11-17 10:22:55,531 ERROR Unable to construct hostname:port representation for TestJavaWebAppEntityImpl{id=OLMdphhN} (null:null); skipping in TrackingAbstractControllerImpl{id=jn2YhSKy}
2015-11-17 10:22:55,531 INFO Resetting TrackingAbstractControllerImpl{id=jn2YhSKy}, server pool targets {TestJavaWebAppEntityImpl{id=HPUEHH5D}=null, TestJavaWebAppEntityImpl{id=OLMdphhN}=null}
2015-11-17 10:22:55,532 INFO Updating TrackingAbstractControllerImpl{id=jn2YhSKy}, server pool targets {TestJavaWebAppEntityImpl{id=HPUEHH5D}=null, TestJavaWebAppEntityImpl{id=OLMdphhN}=null}
2015-11-17 10:22:55,532 INFO test controller reconfigure, targets []
2015-11-17 10:22:55,536 INFO Updating TrackingAbstractControllerImpl{id=jn2YhSKy}, server pool targets {TestJavaWebAppEntityImpl{id=HPUEHH5D}=null, TestJavaWebAppEntityImpl{id=OLMdphhN}=null}
2015-11-17 10:22:55,536 INFO test controller reconfigure, targets []
2015-11-17 10:22:55,545 INFO TESTNG PASSED: "Surefire test" - org.apache.brooklyn.entity.webapp.ControlledDynamicWebAppClusterTest.testSetsInitialSize() finished in 202 ms
2015-11-17 10:22:55,545 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.core.test.BrooklynAppUnitTestSupport.tearDown()
2015-11-17 10:22:55,549 INFO Stopping TrackingAbstractControllerImpl{id=jn2YhSKy} in [SshMachineLocation[localhost:penates.apache.org/67.195.81.186:22@CmvCoXxY]]
2015-11-17 10:22:55,575 WARN Error processing subscriptions to LSM.publish(DynamicWebAppClusterImpl{id=uePEn759}.Sensor: webapp.reqs.total (java.lang.Integer)=null @ 1447755775570): java.lang.IllegalStateException: Entity ControlledDynamicWebAppClusterImpl{id=yAclwMHp} is no longer managed, when trying to publish Sensor: webapp.reqs.total (java.lang.Integer) null
java.lang.IllegalStateException: Entity ControlledDynamicWebAppClusterImpl{id=yAclwMHp} is no longer managed, when trying to publish Sensor: webapp.reqs.total (java.lang.Integer) null
at org.apache.brooklyn.core.entity.AbstractEntity$BasicSensorSupport.emitInternal(AbstractEntity.java:1155) ~[brooklyn-core-0.9.0-SNAPSHOT.jar:0.9.0-SNAPSHOT]
2015-11-17 10:22:55,576 WARN Error calculating and setting aggregate for enricher Aggregator{uniqueTag=aggregator:webapp.reqs.perSec.last.perNode, running=true, entity=DynamicWebAppClusterImpl{id=uePEn759}, id=cIn4U4gt}
java.lang.IllegalStateException: Entity DynamicWebAppClusterImpl{id=uePEn759} is no longer managed; subscription context not available
at org.apache.brooklyn.core.mgmt.internal.NonDeploymentManagementContext.getSubscriptionContext(NonDeploymentManagementContext.java:242) ~[brooklyn-core-0.9.0-SNAPSHOT.jar:0.9.0-SNAPSHOT]
2015-11-17 10:22:55,577 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.core.test.BrooklynAppUnitTestSupport.tearDown() finished in 32 ms
2015-11-17 10:22:55,579 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.entity.webapp.ControlledDynamicWebAppClusterTest.setUp()
2015-11-17 10:22:55,603 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.entity.webapp.ControlledDynamicWebAppClusterTest.setUp() finished in 25 ms
2015-11-17 10:22:55,604 INFO TESTNG INVOKING: "Surefire test" - org.apache.brooklyn.entity.webapp.ControlledDynamicWebAppClusterTest.testStopOnChildUnmanaged()
2015-11-17 10:22:55,692 INFO Starting TrackingAbstractControllerImpl{id=GI9lU46m} on machine SshMachineLocation[localhost:penates.apache.org/67.195.81.186:22@R8XOCIQu]
2015-11-17 10:22:55,698 INFO Resize DynamicWebAppClusterImpl{id=H06QGbrc} from 0 to 1
2015-11-17 10:22:55,801 ERROR Unable to construct hostname:port representation for TestJavaWebAppEntityImpl{id=YWtuwFQr} (null:null); skipping in TrackingAbstractControllerImpl{id=GI9lU46m}
2015-11-17 10:22:55,802 INFO Added policy ServerPoolMemberTrackerPolicy{name=Controller targets tracker, running=true} to TrackingAbstractControllerImpl{id=GI9lU46m}
2015-11-17 10:22:55,802 ERROR Unable to construct hostname:port representation for TestJavaWebAppEntityImpl{id=YWtuwFQr} (null:null); skipping in TrackingAbstractControllerImpl{id=GI9lU46m}
2015-11-17 10:22:55,802 INFO Resetting TrackingAbstractControllerImpl{id=GI9lU46m}, server pool targets {TestJavaWebAppEntityImpl{id=YWtuwFQr}=null}
2015-11-17 10:22:55,803 INFO Updating TrackingAbstractControllerImpl{id=GI9lU46m}, server pool targets {TestJavaWebAppEntityImpl{id=YWtuwFQr}=null}
2015-11-17 10:22:55,803 INFO test controller reconfigure, targets []
2015-11-17 10:22:55,806 INFO Updating TrackingAbstractControllerImpl{id=GI9lU46m}, server pool targets {TestJavaWebAppEntityImpl{id=YWtuwFQr}=null}
2015-11-17 10:22:55,806 INFO test controller reconfigure, targets []
2015-11-17 10:22:55,818 INFO Removing from TrackingAbstractControllerImpl{id=GI9lU46m}, member TestJavaWebAppEntityImpl{id=YWtuwFQr} with address null
2015-11-17 10:22:55,819 WARN Setting Application[FJR5jX0s] on-fire due to problems when expected running, up=false, not-up-indicators: {service-lifecycle-indicators-from-children-and-members=ControlledDynamicWebAppClusterImpl{id=PxUy4Lfz} is not up}
2015-11-17 10:22:55,819 INFO Updating TrackingAbstractControllerImpl{id=GI9lU46m}, server pool targets {}
2015-11-17 10:22:55,819 INFO test controller reconfigure, targets []
2015-11-17 10:22:55,821 INFO TESTNG FAILED: "Surefire test" - org.apache.brooklyn.entity.webapp.ControlledDynamicWebAppClusterTest.testStopOnChildUnmanaged() finished in 217 ms
java.util.ConcurrentModificationException
at java.util.LinkedHashMap$LinkedHashIterator.nextEntry(LinkedHashMap.java:394)
at java.util.LinkedHashMap$KeyIterator.next(LinkedHashMap.java:405)
at java.util.AbstractCollection.toArray(AbstractCollection.java:141)
at com.google.common.collect.ImmutableList.copyOf(ImmutableList.java:258)
at org.apache.brooklyn.core.entity.AbstractEntity$BasicGroupSupport.asList(AbstractEntity.java:747)
at org.apache.brooklyn.core.entity.AbstractEntity$BasicGroupSupport.iterator(AbstractEntity.java:735)
at org.apache.brooklyn.core.mgmt.internal.LocalEntityManager.unmanageNonRecursive(LocalEntityManager.java:707)
at org.apache.brooklyn.core.mgmt.internal.LocalEntityManager.unmanage(LocalEntityManager.java:458)
at org.apache.brooklyn.core.mgmt.internal.LocalEntityManager.unmanage(LocalEntityManager.java:402)
at org.apache.brooklyn.core.mgmt.internal.LocalEntityManager.unmanage(LocalEntityManager.java:398)
at org.apache.brooklyn.core.entity.Entities.unmanage(Entities.java:1011)
at org.apache.brooklyn.entity.webapp.ControlledDynamicWebAppClusterTest.testStopOnChildUnmanaged(ControlledDynamicWebAppClusterTest.java:204)
2015-11-17 10:22:55,821 WARN Setting ControlledDynamicWebAppClusterImpl{id=PxUy4Lfz} on-fire due to problems when expected running, up=false, not-up-indicators: {service-not-up-indicators-from-children-and-members=Not enough entities}
2015-11-17 10:22:55,827 WARN Setting DynamicWebAppClusterImpl{id=H06QGbrc} on-fire due to problems when expected running, up=false, not-up-indicators: {service-not-up-indicators-from-children-and-members=No entities present}
2015-11-17 10:22:55,829 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.core.test.BrooklynAppUnitTestSupport.tearDown()
2015-11-17 10:22:55,834 INFO Stopping TrackingAbstractControllerImpl{id=GI9lU46m} in [SshMachineLocation[localhost:penates.apache.org/67.195.81.186:22@R8XOCIQu]]
2015-11-17 10:22:55,847 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.core.test.BrooklynAppUnitTestSupport.tearDown() finished in 18 ms
2015-11-17 10:22:55,849 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.entity.webapp.ControlledDynamicWebAppClusterTest.setUp()
2015-11-17 10:22:55,873 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.entity.webapp.ControlledDynamicWebAppClusterTest.setUp() finished in 25 ms
2015-11-17 10:22:55,873 INFO TESTNG INVOKING: "Surefire test" - org.apache.brooklyn.entity.webapp.ControlledDynamicWebAppClusterTest.testTheTestJavaWebApp()
2015-11-17 10:22:55,903 INFO TESTNG PASSED: "Surefire test" - org.apache.brooklyn.entity.webapp.ControlledDynamicWebAppClusterTest.testTheTestJavaWebApp() finished in 30 ms
2015-11-17 10:22:55,903 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.core.test.BrooklynAppUnitTestSupport.tearDown()
2015-11-17 10:22:55,904 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.core.test.BrooklynAppUnitTestSupport.tearDown() finished in 1 ms
2015-11-17 10:22:55,904 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.entity.webapp.ControlledDynamicWebAppClusterTest.setUp()
2015-11-17 10:22:55,924 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.entity.webapp.ControlledDynamicWebAppClusterTest.setUp() finished in 19 ms
2015-11-17 10:22:55,924 INFO TESTNG INVOKING: "Surefire test" - org.apache.brooklyn.entity.webapp.ControlledDynamicWebAppClusterTest.testUsesCustomControlledGroup()
2015-11-17 10:22:56,032 INFO Starting TrackingAbstractControllerImpl{id=FbnJS7K0} on machine SshMachineLocation[localhost:penates.apache.org/67.195.81.186:22@TQzvDjXu]
2015-11-17 10:22:56,139 INFO Adding to TrackingAbstractControllerImpl{id=FbnJS7K0}, new member TestJavaWebAppEntityImpl{id=WQRQGHkM} with address myhostname:1234
2015-11-17 10:22:56,139 INFO Added policy ServerPoolMemberTrackerPolicy{name=Controller targets tracker, running=true} to TrackingAbstractControllerImpl{id=FbnJS7K0}
2015-11-17 10:22:56,139 INFO Resetting TrackingAbstractControllerImpl{id=FbnJS7K0}, server pool targets {TestJavaWebAppEntityImpl{id=WQRQGHkM}=myhostname:1234}
2015-11-17 10:22:56,140 INFO Updating TrackingAbstractControllerImpl{id=FbnJS7K0}, server pool targets {TestJavaWebAppEntityImpl{id=WQRQGHkM}=myhostname:1234}
2015-11-17 10:22:56,140 INFO test controller reconfigure, targets [myhostname:1234]
2015-11-17 10:22:56,250 WARN Service is not up when setting running on ControlledDynamicWebAppClusterImpl{id=Q9IWTPhH}; delayed 203ms but Sensor: service.isUp (java.lang.Boolean) did not recover from false; not-up-indicators={service-not-up-indicators-from-children-and-members=Not enough entities}
2015-11-17 10:22:56,250 WARN Setting ControlledDynamicWebAppClusterImpl{id=Q9IWTPhH} on-fire due to problems when expected running, up=false, not-up-indicators: {service-not-up-indicators-from-children-and-members=Not enough entities}
2015-11-17 10:22:56,250 WARN Setting ControlledDynamicWebAppClusterImpl{id=Q9IWTPhH} on-fire due to problems when expected running, up=false, not-up-indicators: {service-not-up-indicators-from-children-and-members=Not enough entities}
2015-11-17 10:22:56,254 WARN Setting Application[Z9RAcLns] on-fire due to problems when expected running, up=true, problems: {service-lifecycle-indicators-from-children-and-members=Required entity not healthy: ControlledDynamicWebAppClusterImpl{id=Q9IWTPhH}}
2015-11-17 10:22:56,254 WARN Setting Application[Z9RAcLns] on-fire due to problems when expected running, up=true, problems: {service-lifecycle-indicators-from-children-and-members=Required entity not healthy: ControlledDynamicWebAppClusterImpl{id=Q9IWTPhH}}
2015-11-17 10:22:56,255 INFO TESTNG PASSED: "Surefire test" - org.apache.brooklyn.entity.webapp.ControlledDynamicWebAppClusterTest.testUsesCustomControlledGroup() finished in 330 ms
2015-11-17 10:22:56,255 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.core.test.BrooklynAppUnitTestSupport.tearDown()
2015-11-17 10:22:56,258 INFO Stopping TrackingAbstractControllerImpl{id=FbnJS7K0} in [SshMachineLocation[localhost:penates.apache.org/67.195.81.186:22@TQzvDjXu]]
2015-11-17 10:22:56,261 INFO Removing from TrackingAbstractControllerImpl{id=FbnJS7K0}, member TestJavaWebAppEntityImpl{id=WQRQGHkM} with address myhostname:1234
2015-11-17 10:22:56,261 INFO Updating TrackingAbstractControllerImpl{id=FbnJS7K0}, server pool targets {}
2015-11-17 10:22:56,261 INFO test controller reconfigure, targets []
2015-11-17 10:22:56,272 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.core.test.BrooklynAppUnitTestSupport.tearDown() finished in 17 ms
2015-11-17 10:22:56,274 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.entity.webapp.ControlledDynamicWebAppClusterTest.setUp()
2015-11-17 10:22:56,293 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.entity.webapp.ControlledDynamicWebAppClusterTest.setUp() finished in 19 ms
2015-11-17 10:22:56,293 INFO TESTNG INVOKING: "Surefire test" - org.apache.brooklyn.entity.webapp.ControlledDynamicWebAppClusterTest.testUsesCustomController()
2015-11-17 10:22:56,365 INFO Starting TrackingAbstractControllerImpl{id=veheUqgA} on machine SshMachineLocation[localhost:penates.apache.org/67.195.81.186:22@wGUcDHVv]
2015-11-17 10:22:56,474 INFO Added policy ServerPoolMemberTrackerPolicy{name=Controller targets tracker, running=true} to TrackingAbstractControllerImpl{id=veheUqgA}
2015-11-17 10:22:56,475 INFO Resetting TrackingAbstractControllerImpl{id=veheUqgA}, server pool targets {}
2015-11-17 10:22:56,475 INFO Updating TrackingAbstractControllerImpl{id=veheUqgA}, server pool targets {}
2015-11-17 10:22:56,475 INFO test controller reconfigure, targets []
2015-11-17 10:22:56,581 WARN Service is not up when setting running on ControlledDynamicWebAppClusterImpl{id=p8KpFmie}; delayed 203ms but Sensor: service.isUp (java.lang.Boolean) did not recover from false; not-up-indicators={service-not-up-indicators-from-children-and-members=Not enough entities}
2015-11-17 10:22:56,581 WARN Setting ControlledDynamicWebAppClusterImpl{id=p8KpFmie} on-fire due to problems when expected running, up=false, not-up-indicators: {service-not-up-indicators-from-children-and-members=Not enough entities}
2015-11-17 10:22:56,581 WARN Setting ControlledDynamicWebAppClusterImpl{id=p8KpFmie} on-fire due to problems when expected running, up=false, not-up-indicators: {service-not-up-indicators-from-children-and-members=Not enough entities}
2015-11-17 10:22:56,584 WARN Setting Application[Z7MuChe1] on-fire due to problems when expected running, up=true, problems: {service-lifecycle-indicators-from-children-and-members=Required entity not healthy: ControlledDynamicWebAppClusterImpl{id=p8KpFmie}}
2015-11-17 10:22:56,585 WARN Setting Application[Z7MuChe1] on-fire due to problems when expected running, up=true, problems: {service-lifecycle-indicators-from-children-and-members=Required entity not healthy: ControlledDynamicWebAppClusterImpl{id=p8KpFmie}}
2015-11-17 10:22:56,589 INFO TESTNG PASSED: "Surefire test" - org.apache.brooklyn.entity.webapp.ControlledDynamicWebAppClusterTest.testUsesCustomController() finished in 296 ms
2015-11-17 10:22:56,590 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.core.test.BrooklynAppUnitTestSupport.tearDown()
2015-11-17 10:22:56,593 INFO Stopping TrackingAbstractControllerImpl{id=veheUqgA} in [SshMachineLocation[localhost:penates.apache.org/67.195.81.186:22@wGUcDHVv]]
2015-11-17 10:22:56,602 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.core.test.BrooklynAppUnitTestSupport.tearDown() finished in 12 ms
2015-11-17 10:22:56,604 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.entity.webapp.ControlledDynamicWebAppClusterTest.setUp()
2015-11-17 10:22:56,626 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.entity.webapp.ControlledDynamicWebAppClusterTest.setUp() finished in 22 ms
2015-11-17 10:22:56,627 INFO TESTNG INVOKING: "Surefire test" - org.apache.brooklyn.entity.webapp.ControlledDynamicWebAppClusterTest.testUsesCustomControllerSpec()
2015-11-17 10:22:56,704 INFO Starting TrackingAbstractControllerImpl{id=ttacRvTU} on machine SshMachineLocation[localhost:penates.apache.org/67.195.81.186:22@RMsQ8FEW]
2015-11-17 10:22:56,813 INFO Added policy ServerPoolMemberTrackerPolicy{name=Controller targets tracker, running=true} to TrackingAbstractControllerImpl{id=ttacRvTU}
2015-11-17 10:22:56,813 INFO Resetting TrackingAbstractControllerImpl{id=ttacRvTU}, server pool targets {}
2015-11-17 10:22:56,814 INFO Updating TrackingAbstractControllerImpl{id=ttacRvTU}, server pool targets {}
2015-11-17 10:22:56,814 INFO test controller reconfigure, targets []
2015-11-17 10:22:56,817 INFO Updating TrackingAbstractControllerImpl{id=ttacRvTU}, server pool targets {}
2015-11-17 10:22:56,817 INFO test controller reconfigure, targets []
2015-11-17 10:22:56,825 INFO Stopping TrackingAbstractControllerImpl{id=ttacRvTU} in [SshMachineLocation[localhost:penates.apache.org/67.195.81.186:22@RMsQ8FEW]]
2015-11-17 10:22:56,833 INFO TESTNG PASSED: "Surefire test" - org.apache.brooklyn.entity.webapp.ControlledDynamicWebAppClusterTest.testUsesCustomControllerSpec() finished in 207 ms
2015-11-17 10:22:56,833 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.core.test.BrooklynAppUnitTestSupport.tearDown()
2015-11-17 10:22:56,837 INFO Stopping TrackingAbstractControllerImpl{id=ttacRvTU} in []
2015-11-17 10:22:56,846 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.core.test.BrooklynAppUnitTestSupport.tearDown() finished in 13 ms
2015-11-17 10:22:56,847 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.entity.webapp.ControlledDynamicWebAppClusterTest.setUp()
2015-11-17 10:22:56,868 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.entity.webapp.ControlledDynamicWebAppClusterTest.setUp() finished in 21 ms
2015-11-17 10:22:56,868 INFO TESTNG INVOKING: "Surefire test" - org.apache.brooklyn.entity.webapp.ControlledDynamicWebAppClusterTest.testUsesCustomWebClusterSpec()
2015-11-17 10:22:56,949 INFO Starting TrackingAbstractControllerImpl{id=EX2OMEif} on machine SshMachineLocation[localhost:penates.apache.org/67.195.81.186:22@zNe2SdAA]
2015-11-17 10:22:57,057 INFO Added policy ServerPoolMemberTrackerPolicy{name=Controller targets tracker, running=true} to TrackingAbstractControllerImpl{id=EX2OMEif}
2015-11-17 10:22:57,057 INFO Resetting TrackingAbstractControllerImpl{id=EX2OMEif}, server pool targets {}
2015-11-17 10:22:57,058 INFO Updating TrackingAbstractControllerImpl{id=EX2OMEif}, server pool targets {}
2015-11-17 10:22:57,058 INFO test controller reconfigure, targets []
2015-11-17 10:22:57,061 INFO Updating TrackingAbstractControllerImpl{id=EX2OMEif}, server pool targets {}
2015-11-17 10:22:57,061 INFO test controller reconfigure, targets []
2015-11-17 10:22:57,066 INFO TESTNG PASSED: "Surefire test" - org.apache.brooklyn.entity.webapp.ControlledDynamicWebAppClusterTest.testUsesCustomWebClusterSpec() finished in 198 ms
2015-11-17 10:22:57,066 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.core.test.BrooklynAppUnitTestSupport.tearDown()
2015-11-17 10:22:57,070 INFO Stopping TrackingAbstractControllerImpl{id=EX2OMEif} in [SshMachineLocation[localhost:penates.apache.org/67.195.81.186:22@zNe2SdAA]]
2015-11-17 10:22:57,082 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.core.test.BrooklynAppUnitTestSupport.tearDown() finished in 16 ms
2015-11-17 10:22:57,083 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.entity.webapp.ElasticCustomLocationTest.setUp()
SUREFIRE-859: [GC 302336K->128374K(744960K), 0.0291130 secs]
2015-11-17 10:22:57,126 WARN Task Task[LSM.publish(TestJavaWebAppEntityImpl{id=HPUEHH5D}.Sensor: service.state (org.apache.brooklyn.core.entity.lifecycle.Lifecycle)=stopping @ 1447755775564);[subscription-delivery-entity-uePEn759[DynamicWebAppClusterImpl{id=uePEn759}]]:RGWklZWL] is being finalized before completion
2015-11-17 10:22:57,127 WARN Task Task[LSM.publish(ControlledDynamicWebAppClusterImpl{id=ulWKSIvI}.Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=TrackingAbstractControllerImpl{id=ttacRvTU} @ 1447755776842);[subscription-delivery-entity-ulWKSIvI[ControlledDynamicWebAppClusterImpl{id=ulWKSIvI}]]:DO10uDVW] is being finalized before completion
2015-11-17 10:22:57,127 WARN Task Task[LSM.publish(ControlledDynamicWebAppClusterImpl{id=ulWKSIvI}.Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=TrackingAbstractControllerImpl{id=ttacRvTU} @ 1447755776842);[subscription-delivery-entity-ulWKSIvI[ControlledDynamicWebAppClusterImpl{id=ulWKSIvI}]]:T7u185XJ] is being finalized before completion
2015-11-17 10:22:57,132 WARN Task Task[LSM.publish(TestJavaWebAppEntityImpl{id=HPUEHH5D}.Sensor: service.isUp (java.lang.Boolean)=false @ 1447755775565);[subscription-delivery-entity-uePEn759[DynamicWebAppClusterImpl{id=uePEn759}]]:ayvMitZb] is being finalized before completion
2015-11-17 10:22:57,132 WARN Task Task[LSM.publish(TestJavaWebAppEntityImpl{id=HPUEHH5D}.Sensor: service.isUp (java.lang.Boolean)=false @ 1447755775565);[subscription-delivery-entity-uePEn759[DynamicWebAppClusterImpl{id=uePEn759}]]:qqrMgczH] is being finalized before completion
2015-11-17 10:22:57,132 WARN Task Task[LSM.publish(TestJavaWebAppEntityImpl{id=HPUEHH5D}.Sensor: service.isUp (java.lang.Boolean)=false @ 1447755775565);[subscription-delivery-entity-uePEn759[DynamicWebAppClusterImpl{id=uePEn759}]]:gBvLNze5] is being finalized before completion
2015-11-17 10:22:57,133 WARN Task Task[LSM.publish(TestJavaWebAppEntityImpl{id=HPUEHH5D}.Sensor: service.state (org.apache.brooklyn.core.entity.lifecycle.Lifecycle)=stopped @ 1447755775567);[subscription-delivery-entity-uePEn759[DynamicWebAppClusterImpl{id=uePEn759}]]:zgLdShwg] is being finalized before completion
2015-11-17 10:22:57,133 WARN Task Task[LSM.publish(TestJavaWebAppEntityImpl{id=HPUEHH5D}.Sensor: service.state (org.apache.brooklyn.core.entity.lifecycle.Lifecycle)=stopped @ 1447755775567);[subscription-delivery-entity-uePEn759[DynamicWebAppClusterImpl{id=uePEn759}]]:rIERX5tF] is being finalized before completion
2015-11-17 10:22:57,133 WARN Task Task[LSM.publish(TestJavaWebAppEntityImpl{id=HPUEHH5D}.Sensor: service.state (org.apache.brooklyn.core.entity.lifecycle.Lifecycle)=stopped @ 1447755775567);[subscription-delivery-entity-uePEn759[DynamicWebAppClusterImpl{id=uePEn759}]]:ehfKKAsE] is being finalized before completion
2015-11-17 10:22:57,133 WARN Task Task[LSM.publish(DynamicWebAppClusterImpl{id=uePEn759}.Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=TestJavaWebAppEntityImpl{id=OLMdphhN} @ 1447755775568);[subscription-delivery-entity-uePEn759[DynamicWebAppClusterImpl{id=uePEn759}]]:TfcvW9k6] is being finalized before completion
2015-11-17 10:22:57,133 WARN Task Task[LSM.publish(DynamicWebAppClusterImpl{id=uePEn759}.Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=TestJavaWebAppEntityImpl{id=HPUEHH5D} @ 1447755775569);[subscription-delivery-entity-uePEn759[DynamicWebAppClusterImpl{id=uePEn759}]]:KbGqe2MX] is being finalized before completion
2015-11-17 10:22:57,133 WARN Task Task[LSM.publish(DynamicWebAppClusterImpl{id=uePEn759}.Sensor: service.notUp.indicators (java.util.Map)={service-not-up-indicators-from-children-and-members=No entities present} @ 1447755775569);[subscription-delivery-entity-uePEn759[DynamicWebAppClusterImpl{id=uePEn759}]]:Q09H971q] is being finalized before completion
2015-11-17 10:22:57,134 WARN Task Task[LSM.publish(DynamicWebAppClusterImpl{id=uePEn759}.Sensor: service.state.expected (org.apache.brooklyn.core.entity.lifecycle.Lifecycle$Transition)=stopped @ 1447755775570 / Tue Nov 17 10:22:55 UTC 2015 @ 1447755775570);[subscription-delivery-entity-uePEn759[DynamicWebAppClusterImpl{id=uePEn759}]]:N7QkdqWy] is being finalized before completion
2015-11-17 10:22:57,134 WARN Task Task[LSM.publish(DynamicWebAppClusterImpl{id=uePEn759}.Sensor: webapp.reqs.errors (java.lang.Integer)=null @ 1447755775570);[subscription-delivery-entity-yAclwMHp[ControlledDynamicWebAppClusterImpl{id=yAclwMHp}]]:pgEVm3Io] is being finalized before completion
2015-11-17 10:22:57,134 WARN Task Task[LSM.publish(DynamicWebAppClusterImpl{id=uePEn759}.Sensor: service.state.expected (org.apache.brooklyn.core.entity.lifecycle.Lifecycle$Transition)=stopped @ 1447755775570 / Tue Nov 17 10:22:55 UTC 2015 @ 1447755775570);[subscription-delivery-entity-yAclwMHp[ControlledDynamicWebAppClusterImpl{id=yAclwMHp}]]:VCQQDqPG] is being finalized before completion
2015-11-17 10:22:57,134 WARN Task Task[LSM.publish(DynamicWebAppClusterImpl{id=uePEn759}.Sensor: service.state (org.apache.brooklyn.core.entity.lifecycle.Lifecycle)=stopped @ 1447755775570);[subscription-delivery-entity-yAclwMHp[ControlledDynamicWebAppClusterImpl{id=yAclwMHp}]]:LiNLpybd] is being finalized before completion
2015-11-17 10:22:57,134 WARN Task Task[LSM.publish(DynamicWebAppClusterImpl{id=uePEn759}.Sensor: service.state (org.apache.brooklyn.core.entity.lifecycle.Lifecycle)=stopped @ 1447755775570);[subscription-delivery-entity-yAclwMHp[ControlledDynamicWebAppClusterImpl{id=yAclwMHp}]]:JAJtVo6w] is being finalized before completion
2015-11-17 10:22:57,135 WARN Task Task[LSM.publish(DynamicWebAppClusterImpl{id=uePEn759}.Sensor: webapp.reqs.perSec.last (java.lang.Double)=null @ 1447755775571);[subscription-delivery-entity-yAclwMHp[ControlledDynamicWebAppClusterImpl{id=yAclwMHp}]]:Umj9BuvE] is being finalized before completion
2015-11-17 10:22:57,135 WARN Task Task[LSM.publish(DynamicWebAppClusterImpl{id=uePEn759}.Sensor: service.state (org.apache.brooklyn.core.entity.lifecycle.Lifecycle)=stopped @ 1447755775570);[subscription-delivery-entity-yAclwMHp[ControlledDynamicWebAppClusterImpl{id=yAclwMHp}]]:waqU9PkS] is being finalized before completion
2015-11-17 10:22:57,135 WARN Task Task[LSM.publish(DynamicWebAppClusterImpl{id=uePEn759}.Sensor: webapp.reqs.perSec.last (java.lang.Double)=null @ 1447755775571);[subscription-delivery-entity-yAclwMHp[ControlledDynamicWebAppClusterImpl{id=yAclwMHp}]]:L2dl1ivs] is being finalized before completion
2015-11-17 10:22:57,135 WARN Task Task[LSM.publish(ControlledDynamicWebAppClusterImpl{id=yAclwMHp}.Sensor: service.state.expected (org.apache.brooklyn.core.entity.lifecycle.Lifecycle$Transition)=stopped @ 1447755775571 / Tue Nov 17 10:22:55 UTC 2015 @ 1447755775571);[subscription-delivery-entity-yAclwMHp[ControlledDynamicWebAppClusterImpl{id=yAclwMHp}]]:TnvVL8d4] is being finalized before completion
2015-11-17 10:22:57,135 WARN Task Task[LSM.publish(DynamicWebAppClusterImpl{id=uePEn759}.Sensor: webapp.reqs.perSec.windowed (java.lang.Double)=null @ 1447755775571);[subscription-delivery-entity-yAclwMHp[ControlledDynamicWebAppClusterImpl{id=yAclwMHp}]]:bLuBMZ5a] is being finalized before completion
2015-11-17 10:22:57,135 WARN Task Task[LSM.publish(DynamicWebAppClusterImpl{id=uePEn759}.Sensor: webapp.reqs.perSec.windowed (java.lang.Double)=null @ 1447755775571);[subscription-delivery-entity-yAclwMHp[ControlledDynamicWebAppClusterImpl{id=yAclwMHp}]]:fLEBAP6r] is being finalized before completion
2015-11-17 10:22:57,135 WARN Task Task[LSM.publish(DynamicWebAppClusterImpl{id=uePEn759}.Sensor: webapp.reqs.processingTime.total (java.lang.Integer)=null @ 1447755775572);[subscription-delivery-entity-yAclwMHp[ControlledDynamicWebAppClusterImpl{id=yAclwMHp}]]:AjJZSLeJ] is being finalized before completion
2015-11-17 10:22:57,135 WARN Task Task[LSM.publish(DynamicWebAppClusterImpl{id=uePEn759}.Sensor: webapp.reqs.processingTime.total (java.lang.Integer)=null @ 1447755775572);[subscription-delivery-entity-yAclwMHp[ControlledDynamicWebAppClusterImpl{id=yAclwMHp}]]:UqETSVRN] is being finalized before completion
2015-11-17 10:22:57,136 WARN Task Task[LSM.publish(ControlledDynamicWebAppClusterImpl{id=yAclwMHp}.Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=DynamicWebAppClusterImpl{id=uePEn759} @ 1447755775572);[subscription-delivery-entity-yAclwMHp[ControlledDynamicWebAppClusterImpl{id=yAclwMHp}]]:t5ISex1Q] is being finalized before completion
2015-11-17 10:22:57,136 WARN Task Task[LSM.publish(ControlledDynamicWebAppClusterImpl{id=yAclwMHp}.Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=DynamicWebAppClusterImpl{id=uePEn759} @ 1447755775572);[subscription-delivery-entity-yAclwMHp[ControlledDynamicWebAppClusterImpl{id=yAclwMHp}]]:PxatK7gy] is being finalized before completion
2015-11-17 10:22:57,136 WARN Task Task[LSM.publish(DynamicWebAppClusterImpl{id=uePEn759}.Sensor: webapp.reqs.processingTime.fraction.windowed (java.lang.Double)=null @ 1447755775573);[subscription-delivery-entity-yAclwMHp[ControlledDynamicWebAppClusterImpl{id=yAclwMHp}]]:NklLWOMj] is being finalized before completion
2015-11-17 10:22:57,136 WARN Task Task[LSM.publish(DynamicWebAppClusterImpl{id=uePEn759}.Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=QuarantineGroupImpl{id=KSOQkNGc} @ 1447755775573);[subscription-delivery-entity-uePEn759[DynamicWebAppClusterImpl{id=uePEn759}]]:ErLYCR2v] is being finalized before completion
2015-11-17 10:22:57,136 WARN Task Task[LSM.publish(DynamicWebAppClusterImpl{id=uePEn759}.Sensor: webapp.reqs.processingTime.fraction.windowed (java.lang.Double)=null @ 1447755775573);[subscription-delivery-entity-yAclwMHp[ControlledDynamicWebAppClusterImpl{id=yAclwMHp}]]:zgNtHU3S] is being finalized before completion
2015-11-17 10:22:57,136 WARN Task Task[LSM.publish(DynamicWebAppClusterImpl{id=uePEn759}.Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=QuarantineGroupImpl{id=KSOQkNGc} @ 1447755775573);[subscription-delivery-entity-yAclwMHp[ControlledDynamicWebAppClusterImpl{id=yAclwMHp}]]:p2c6KY2H] is being finalized before completion
2015-11-17 10:22:57,136 WARN Task Task[LSM.publish(ControlledDynamicWebAppClusterImpl{id=yAclwMHp}.Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=TestJavaWebAppEntityImpl{id=OLMdphhN} @ 1447755775573);[subscription-delivery-entity-yAclwMHp[ControlledDynamicWebAppClusterImpl{id=yAclwMHp}]]:klQRjQM3] is being finalized before completion
2015-11-17 10:22:57,137 WARN Task Task[LSM.publish(ControlledDynamicWebAppClusterImpl{id=yAclwMHp}.Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=TrackingAbstractControllerImpl{id=jn2YhSKy} @ 1447755775573);[subscription-delivery-entity-yAclwMHp[ControlledDynamicWebAppClusterImpl{id=yAclwMHp}]]:E5Q10G4o] is being finalized before completion
2015-11-17 10:22:57,137 WARN Task Task[LSM.publish(ControlledDynamicWebAppClusterImpl{id=yAclwMHp}.Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=TrackingAbstractControllerImpl{id=jn2YhSKy} @ 1447755775573);[subscription-delivery-entity-yAclwMHp[ControlledDynamicWebAppClusterImpl{id=yAclwMHp}]]:TPwoscI3] is being finalized before completion
2015-11-17 10:22:57,137 WARN Task Task[LSM.publish(ControlledDynamicWebAppClusterImpl{id=yAclwMHp}.Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=TestJavaWebAppEntityImpl{id=OLMdphhN} @ 1447755775573);[subscription-delivery-entity-yAclwMHp[ControlledDynamicWebAppClusterImpl{id=yAclwMHp}]]:sueGn48P] is being finalized before completion
2015-11-17 10:22:57,137 WARN Task Task[LSM.publish(DynamicWebAppClusterImpl{id=uePEn759}.Sensor: webapp.reqs.total.perNode (java.lang.Double)=null @ 1447755775573);[subscription-delivery-entity-yAclwMHp[ControlledDynamicWebAppClusterImpl{id=yAclwMHp}]]:Y2aSKh67] is being finalized before completion
2015-11-17 10:22:57,137 WARN Task Task[LSM.publish(DynamicWebAppClusterImpl{id=uePEn759}.Sensor: webapp.reqs.total.perNode (java.lang.Double)=null @ 1447755775573);[subscription-delivery-entity-yAclwMHp[ControlledDynamicWebAppClusterImpl{id=yAclwMHp}]]:JzlGni3z] is being finalized before completion
2015-11-17 10:22:57,137 WARN Task Task[LSM.publish(DynamicWebAppClusterImpl{id=uePEn759}.Sensor: webapp.reqs.errors.perNode (java.lang.Integer)=null @ 1447755775574);[subscription-delivery-entity-yAclwMHp[ControlledDynamicWebAppClusterImpl{id=yAclwMHp}]]:pLToFz0s] is being finalized before completion
2015-11-17 10:22:57,137 WARN Task Task[LSM.publish(DynamicWebAppClusterImpl{id=uePEn759}.Sensor: webapp.reqs.errors.perNode (java.lang.Integer)=null @ 1447755775574);[subscription-delivery-entity-yAclwMHp[ControlledDynamicWebAppClusterImpl{id=yAclwMHp}]]:JCtVQg4N] is being finalized before completion
2015-11-17 10:22:57,138 WARN Task Task[LSM.publish(ControlledDynamicWebAppClusterImpl{id=yAclwMHp}.Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=TestJavaWebAppEntityImpl{id=HPUEHH5D} @ 1447755775574);[subscription-delivery-entity-yAclwMHp[ControlledDynamicWebAppClusterImpl{id=yAclwMHp}]]:LBwtoM8x] is being finalized before completion
2015-11-17 10:22:57,138 WARN Task Task[LSM.publish(ControlledDynamicWebAppClusterImpl{id=yAclwMHp}.Sensor: entity.children.removed (org.apache.brooklyn.api.entity.Entity)=TestJavaWebAppEntityImpl{id=HPUEHH5D} @ 1447755775574);[subscription-delivery-entity-yAclwMHp[ControlledDynamicWebAppClusterImpl{id=yAclwMHp}]]:NuOYErPa] is being finalized before completion
2015-11-17 10:22:57,138 WARN Task Task[LSM.publish(DynamicWebAppClusterImpl{id=uePEn759}.Sensor: webapp.reqs.perSec.last.perNode (java.lang.Double)=null @ 1447755775574);[subscription-delivery-entity-yAclwMHp[ControlledDynamicWebAppClusterImpl{id=yAclwMHp}]]:u61TUMPp] is being finalized before completion
2015-11-17 10:22:57,139 WARN Task Task[LSM.publish(DynamicWebAppClusterImpl{id=uePEn759}.Sensor: group.members.removed (org.apache.brooklyn.api.entity.Entity)=TestJavaWebAppEntityImpl{id=HPUEHH5D} @ 1447755775561);[subscription-delivery-entity-uePEn759[DynamicWebAppClusterImpl{id=uePEn759}]]:FjwRdjWx] is being finalized before completion
2015-11-17 10:22:57,139 WARN Task Task[LSM.publish(DynamicWebAppClusterImpl{id=uePEn759}.Sensor: group.members.removed (org.apache.brooklyn.api.entity.Entity)=TestJavaWebAppEntityImpl{id=HPUEHH5D} @ 1447755775561);[subscription-delivery-entity-uePEn759[DynamicWebAppClusterImpl{id=uePEn759}]]:kbcbFsue] is being finalized before completion
2015-11-17 10:22:57,139 WARN Task Task[LSM.publish(DynamicWebAppClusterImpl{id=uePEn759}.Sensor: group.members.removed (org.apache.brooklyn.api.entity.Entity)=TestJavaWebAppEntityImpl{id=HPUEHH5D} @ 1447755775561);[subscription-delivery-entity-uePEn759[DynamicWebAppClusterImpl{id=uePEn759}]]:IyGgiYa3] is being finalized before completion
2015-11-17 10:22:57,140 WARN Task Task[LSM.publish(TestJavaWebAppEntityImpl{id=OLMdphhN}.Sensor: service.state (org.apache.brooklyn.core.entity.lifecycle.Lifecycle)=stopping @ 1447755775564);[subscription-delivery-entity-uePEn759[DynamicWebAppClusterImpl{id=uePEn759}]]:Cd97mZfX] is being finalized before completion
2015-11-17 10:22:57,140 WARN Task Task[LSM.publish(TestJavaWebAppEntityImpl{id=OLMdphhN}.Sensor: service.isUp (java.lang.Boolean)=false @ 1447755775564);[subscription-delivery-entity-uePEn759[DynamicWebAppClusterImpl{id=uePEn759}]]:Uu12McI6] is being finalized before completion
2015-11-17 10:22:57,140 WARN Task Task[LSM.publish(TestJavaWebAppEntityImpl{id=HPUEHH5D}.Sensor: service.state (org.apache.brooklyn.core.entity.lifecycle.Lifecycle)=stopping @ 1447755775564);[subscription-delivery-entity-uePEn759[DynamicWebAppClusterImpl{id=uePEn759}]]:VaGidnpS] is being finalized before completion
2015-11-17 10:22:57,140 WARN Task Task[LSM.publish(TestJavaWebAppEntityImpl{id=HPUEHH5D}.Sensor: service.state (org.apache.brooklyn.core.entity.lifecycle.Lifecycle)=stopping @ 1447755775564);[subscription-delivery-entity-uePEn759[DynamicWebAppClusterImpl{id=uePEn759}]]:jt4gY7NB] is being finalized before completion
2015-11-17 10:22:57,140 WARN Task Task[LSM.publish(TestJavaWebAppEntityImpl{id=OLMdphhN}.Sensor: service.state (org.apache.brooklyn.core.entity.lifecycle.Lifecycle)=stopped @ 1447755775565);[subscription-delivery-entity-uePEn759[DynamicWebAppClusterImpl{id=uePEn759}]]:NJWLu5ht] is being finalized before completion
SUREFIRE-859: [GC 307470K->128285K(746496K), 0.0090580 secs]
2015-11-17 10:22:57,606 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @BeforeMethod org.apache.brooklyn.entity.webapp.ElasticCustomLocationTest.setUp() finished in 523 ms
2015-11-17 10:22:57,606 INFO TESTNG INVOKING: "Surefire test" - org.apache.brooklyn.entity.webapp.ElasticCustomLocationTest.testElasticClusterCreatesTestEntity()
2015-11-17 10:22:57,611 WARN Deprecated legacy compatibility, using factory (init will not be invoked): MockWebService{id=AMJne3GH}
2015-11-17 10:22:57,611 WARN Deprecated use of Entities.manage(Entity), for unmanaged entity MockWebService{id=AMJne3GH}
2015-11-17 10:22:57,612 INFO TESTNG PASSED: "Surefire test" - org.apache.brooklyn.entity.webapp.ElasticCustomLocationTest.testElasticClusterCreatesTestEntity() finished in 6 ms
2015-11-17 10:22:57,613 INFO TESTNG INVOKING CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.entity.webapp.ElasticCustomLocationTest.tearDown()
2015-11-17 10:22:57,667 INFO TESTNG PASSED CONFIGURATION: "Surefire test" - @AfterMethod org.apache.brooklyn.entity.webapp.ElasticCustomLocationTest.tearDown() Tests run: 38, Failures: 1, Errors: 0, Skipped: 0, Time elapsed: 30.779 sec <<< FAILURE! - in TestSuite
testStopOnChildUnmanaged(org.apache.brooklyn.entity.webapp.ControlledDynamicWebAppClusterTest) Time elapsed: 0.226 sec <<< FAILURE!
java.util.ConcurrentModificationException: null
at java.util.LinkedHashMap$LinkedHashIterator.nextEntry(LinkedHashMap.java:394)
at java.util.LinkedHashMap$KeyIterator.next(LinkedHashMap.java:405)
at java.util.AbstractCollection.toArray(AbstractCollection.java:141)
at com.google.common.collect.ImmutableList.copyOf(ImmutableList.java:258)
at org.apache.brooklyn.core.entity.AbstractEntity$BasicGroupSupport.asList(AbstractEntity.java:747)
at org.apache.brooklyn.core.entity.AbstractEntity$BasicGroupSupport.iterator(AbstractEntity.java:735)
at org.apache.brooklyn.core.mgmt.internal.LocalEntityManager.unmanageNonRecursive(LocalEntityManager.java:707)
at org.apache.brooklyn.core.mgmt.internal.LocalEntityManager.unmanage(LocalEntityManager.java:458)
at org.apache.brooklyn.core.mgmt.internal.LocalEntityManager.unmanage(LocalEntityManager.java:402)
at org.apache.brooklyn.core.mgmt.internal.LocalEntityManager.unmanage(LocalEntityManager.java:398)
at org.apache.brooklyn.core.entity.Entities.unmanage(Entities.java:1011)
at org.apache.brooklyn.entity.webapp.ControlledDynamicWebAppClusterTest.testStopOnChildUnmanaged(ControlledDynamicWebAppClusterTest.java:204)
{code}
> Syncrhonize access to entity.groups()
> -------------------------------------
>
> Key: BROOKLYN-194
> URL: https://issues.apache.org/jira/browse/BROOKLYN-194
> Project: Brooklyn
> Issue Type: Bug
> Affects Versions: 0.9.0
> Reporter: Svetoslav Neykov
>
> {code}
> Time elapsed: 30.779 sec <<< FAILURE! - in TestSuite
> testStopOnChildUnmanaged(org.apache.brooklyn.entity.webapp.ControlledDynamicWebAppClusterTest) Time elapsed: 0.226 sec <<< FAILURE!
> java.util.ConcurrentModificationException: null
> at java.util.LinkedHashMap$LinkedHashIterator.nextEntry(LinkedHashMap.java:394)
> at java.util.LinkedHashMap$KeyIterator.next(LinkedHashMap.java:405)
> at java.util.AbstractCollection.toArray(AbstractCollection.java:141)
> at com.google.common.collect.ImmutableList.copyOf(ImmutableList.java:258)
> at org.apache.brooklyn.core.entity.AbstractEntity$BasicGroupSupport.asList(AbstractEntity.java:747)
> at org.apache.brooklyn.core.entity.AbstractEntity$BasicGroupSupport.iterator(AbstractEntity.java:735)
> at org.apache.brooklyn.core.mgmt.internal.LocalEntityManager.unmanageNonRecursive(LocalEntityManager.java:707)
> at org.apache.brooklyn.core.mgmt.internal.LocalEntityManager.unmanage(LocalEntityManager.java:458)
> at org.apache.brooklyn.core.mgmt.internal.LocalEntityManager.unmanage(LocalEntityManager.java:402)
> at org.apache.brooklyn.core.mgmt.internal.LocalEntityManager.unmanage(LocalEntityManager.java:398)
> at org.apache.brooklyn.core.entity.Entities.unmanage(Entities.java:1011)
> at org.apache.brooklyn.entity.webapp.ControlledDynamicWebAppClusterTest.testStopOnChildUnmanaged(ControlledDynamicWebAppClusterTest.java:204)
> {code}
--
This message was sent by Atlassian JIRA
(v6.3.4#6332)