brooklyn-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From "Svetoslav Neykov (JIRA)" <j...@apache.org>
Subject [jira] [Created] (BROOKLYN-194) Syncrhonize access to entity.groups()
Date Tue, 17 Nov 2015 10:42:10 GMT
Svetoslav Neykov created BROOKLYN-194:
-----------------------------------------

             Summary: 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}
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}



--
This message was sent by Atlassian JIRA
(v6.3.4#6332)


Mime
View raw message