You are viewing a plain text version of this content. The canonical link for it is here.
Posted to commits@stratos.apache.org by ra...@apache.org on 2015/08/30 20:17:13 UTC
[20/50] [abbrv] stratos git commit: Fixing health stat publisher in
PCA, adding MT app integration test to PCA
http://git-wip-us.apache.org/repos/asf/stratos/blob/64368325/products/python-cartridge-agent/integration/cartridge-agent.log
----------------------------------------------------------------------
diff --git a/products/python-cartridge-agent/integration/cartridge-agent.log b/products/python-cartridge-agent/integration/cartridge-agent.log
new file mode 100644
index 0000000..75bf741
--- /dev/null
+++ b/products/python-cartridge-agent/integration/cartridge-agent.log
@@ -0,0 +1,3372 @@
+2015-08-28 16:51:34,169 [-] [main] INFO BrokerService Loaded the Bouncy Castle security provider.
+2015-08-28 16:51:34,176 [-] [main] INFO PythonAgentTestManager PCA integration properties: {distribution.name=apache-stratos-python-cartridge-agent-4.1.2, stratos.admin.password=admin, activemq.mqtt.bind.address=mqtt://localhost:1885, stratos.endpoint=http://localhost:9763, cep.port=7712, distribution.version=4.1.2, stratos.admin.username=admin, activemq.amqp.bind.address=tcp://localhost:61617}
+2015-08-28 16:51:34,178 [-] [main] INFO PythonAgentTestManager PCA integration properties: {distribution.name=apache-stratos-python-cartridge-agent-4.1.2, stratos.admin.password=admin, activemq.mqtt.bind.address=mqtt://localhost:1885, stratos.endpoint=http://localhost:9763, cep.port=7712, distribution.version=4.1.2, stratos.admin.username=admin, activemq.amqp.bind.address=tcp://localhost:61617}
+2015-08-28 16:51:34,179 [-] [main] INFO PythonAgentTestManager PCA integration properties: {distribution.name=apache-stratos-python-cartridge-agent-4.1.2, stratos.admin.password=admin, activemq.mqtt.bind.address=mqtt://localhost:1885, stratos.endpoint=http://localhost:9763, cep.port=7712, distribution.version=4.1.2, stratos.admin.username=admin, activemq.amqp.bind.address=tcp://localhost:61617}
+2015-08-28 16:51:34,470 [-] [JMX connector] INFO ManagementContext JMX consoles can connect to service:jmx:rmi:///jndi/rmi://localhost:1099/jmxrmi
+2015-08-28 16:51:34,547 [-] [main] INFO PListStoreImpl PListStore:[/home/akila/Documents/ASF/stratos-ravihansa3000.git/products/python-cartridge-agent/integration/target/test-classes/../d172cc3f-19ec-49a3-9d52-909804158deb/activemq-data/testBroker/tmp_storage] started
+2015-08-28 16:51:34,553 [-] [main] INFO BrokerService Using Persistence Adapter: KahaDBPersistenceAdapter[/home/akila/Documents/ASF/stratos-ravihansa3000.git/products/python-cartridge-agent/integration/target/test-classes/../d172cc3f-19ec-49a3-9d52-909804158deb/activemq-data/testBroker/KahaDB]
+2015-08-28 16:51:34,807 [-] [main] INFO BrokerService Apache ActiveMQ 5.10.0 (testBroker, ID:akila-wso2-lap-23868-1440760894669-0:1) is starting
+2015-08-28 16:51:34,816 [-] [main] INFO TransportServerThreadSupport Listening for connections at: tcp://localhost:61617
+2015-08-28 16:51:34,817 [-] [main] INFO TransportConnector Connector tcp://localhost:61617 started
+2015-08-28 16:51:34,818 [-] [main] INFO TransportServerThreadSupport Listening for connections at: mqtt://localhost:1885
+2015-08-28 16:51:34,818 [-] [main] INFO TransportConnector Connector mqtt://localhost:1885 started
+2015-08-28 16:51:34,819 [-] [main] INFO BrokerService Apache ActiveMQ 5.10.0 (testBroker, ID:akila-wso2-lap-23868-1440760894669-0:1) started
+2015-08-28 16:51:34,819 [-] [main] INFO BrokerService For help or more information please see: http://activemq.apache.org
+2015-08-28 16:51:34,819 [-] [main] WARN BrokerService Store limit is 102400 mb (current store usage is 0 mb). The data directory: /home/akila/Documents/ASF/stratos-ravihansa3000.git/products/python-cartridge-agent/integration/target/test-classes/../d172cc3f-19ec-49a3-9d52-909804158deb/activemq-data/testBroker/KahaDB only has 25094 mb of usable space - resetting to maximum available disk space: 25094 mb
+2015-08-28 16:51:34,819 [-] [main] ERROR BrokerService Temporary Store limit is 51200 mb, whilst the temporary data directory: /home/akila/Documents/ASF/stratos-ravihansa3000.git/products/python-cartridge-agent/integration/target/test-classes/../d172cc3f-19ec-49a3-9d52-909804158deb/activemq-data/testBroker/tmp_storage only has 25094 mb of usable space - resetting to maximum available 25094 mb.
+2015-08-28 16:51:34,854 [-] [main] INFO PythonAgentTestManager Broker service started!
+2015-08-28 16:51:34,857 [-] [main] INFO StratosThreadPool Thread pool created: [type] Executor Service [id] TEST_THREAD_POOL [size] 15
+2015-08-28 16:51:34,935 [-] [pool-2-thread-2] INFO TopologyEventMessageDelegator Topology event message delegator started
+2015-08-28 16:51:34,950 [-] [pool-2-thread-4] INFO InstanceStatusEventMessageDelegator Instance notifier event message delegator started
+2015-08-28 16:51:34,952 [-] [main] INFO PythonAgentTestManager Setting up python cartridge agent...
+2015-08-28 16:51:34,952 [-] [Thread-5] INFO PythonAgentTestManager Server socket started on port: 7712
+2015-08-28 16:51:35,003 [-] [main] INFO PythonAgentTestManager Changing extension scripts permissions
+2015-08-28 16:51:35,015 [-] [main] INFO PythonAgentTestManager Python cartridge agent setup completed
+2015-08-28 16:51:35,015 [-] [main] INFO PythonAgentTestManager Python agent working directory name: d172cc3f-19ec-49a3-9d52-909804158deb
+2015-08-28 16:51:35,015 [-] [main] INFO PythonAgentTestManager Starting python cartridge agent...
+2015-08-28 16:51:35,024 [-] [Thread-7] INFO PythonAgentTestManager Server socket started on port: 8080
+2015-08-28 16:51:35,026 [-] [main] ERROR PythonAgentTestManager Error while starting MB
+java.io.IOException: Failed to bind to server socket: tcp://localhost:61617 due to: java.net.BindException: Address already in use
+ at org.apache.activemq.util.IOExceptionSupport.create(IOExceptionSupport.java:33)
+ at org.apache.activemq.transport.tcp.TcpTransportServer.bind(TcpTransportServer.java:135)
+ at org.apache.activemq.transport.tcp.TcpTransportFactory.doBind(TcpTransportFactory.java:56)
+ at org.apache.activemq.transport.TransportFactorySupport.bind(TransportFactorySupport.java:40)
+ at org.apache.activemq.broker.BrokerService.createTransportConnector(BrokerService.java:2327)
+ at org.apache.activemq.broker.BrokerService.addConnector(BrokerService.java:316)
+ at org.apache.activemq.broker.BrokerService.addConnector(BrokerService.java:306)
+ at org.apache.stratos.python.cartridge.agent.test.PythonAgentTestManager.startBroker(PythonAgentTestManager.java:189)
+ at org.apache.stratos.python.cartridge.agent.test.PythonAgentTestManager.setup(PythonAgentTestManager.java:77)
+ at org.apache.stratos.python.cartridge.agent.test.ADCTest.setupADCTest(ADCTest.java:64)
+ at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
+ at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
+ at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
+ at java.lang.reflect.Method.invoke(Method.java:606)
+ at org.testng.internal.MethodInvocationHelper.invokeMethod(MethodInvocationHelper.java:80)
+ at org.testng.internal.Invoker.invokeConfigurationMethod(Invoker.java:525)
+ at org.testng.internal.Invoker.invokeConfigurations(Invoker.java:202)
+ at org.testng.internal.Invoker.invokeConfigurations(Invoker.java:130)
+ at org.testng.SuiteRunner.privateRun(SuiteRunner.java:277)
+ at org.testng.SuiteRunner.run(SuiteRunner.java:240)
+ at org.testng.SuiteRunnerWorker.runSuite(SuiteRunnerWorker.java:52)
+ at org.testng.SuiteRunnerWorker.run(SuiteRunnerWorker.java:86)
+ at org.testng.TestNG.runSuitesSequentially(TestNG.java:1158)
+ at org.testng.TestNG.runSuitesLocally(TestNG.java:1083)
+ at org.testng.TestNG.run(TestNG.java:999)
+ at org.apache.maven.surefire.testng.TestNGExecutor.run(TestNGExecutor.java:115)
+ at org.apache.maven.surefire.testng.TestNGDirectoryTestSuite.executeMulti(TestNGDirectoryTestSuite.java:205)
+ at org.apache.maven.surefire.testng.TestNGDirectoryTestSuite.execute(TestNGDirectoryTestSuite.java:108)
+ at org.apache.maven.surefire.testng.TestNGProvider.invoke(TestNGProvider.java:111)
+ at org.apache.maven.surefire.booter.ForkedBooter.invokeProviderInSameClassLoader(ForkedBooter.java:203)
+ at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:155)
+ at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:103)
+Caused by: java.net.BindException: Address already in use
+ at java.net.PlainSocketImpl.socketBind(Native Method)
+ at java.net.AbstractPlainSocketImpl.bind(AbstractPlainSocketImpl.java:376)
+ at java.net.ServerSocket.bind(ServerSocket.java:376)
+ at java.net.ServerSocket.<init>(ServerSocket.java:237)
+ at javax.net.DefaultServerSocketFactory.createServerSocket(ServerSocketFactory.java:231)
+ at org.apache.activemq.transport.tcp.TcpTransportServer.bind(TcpTransportServer.java:132)
+ ... 30 more
+2015-08-28 16:51:35,029 [-] [Thread-8] ERROR PythonAgentTestManager Could not start server socket: [port] 8080
+java.net.BindException: Address already in use
+ at java.net.PlainSocketImpl.socketBind(Native Method)
+ at java.net.AbstractPlainSocketImpl.bind(AbstractPlainSocketImpl.java:376)
+ at java.net.ServerSocket.bind(ServerSocket.java:376)
+ at java.net.ServerSocket.<init>(ServerSocket.java:237)
+ at java.net.ServerSocket.<init>(ServerSocket.java:128)
+ at org.apache.stratos.python.cartridge.agent.test.PythonAgentTestManager$4.run(PythonAgentTestManager.java:236)
+ at java.lang.Thread.run(Thread.java:745)
+2015-08-28 16:51:35,030 [-] [main] ERROR PythonAgentTestManager Error while starting MB
+java.io.IOException: Failed to bind to server socket: tcp://localhost:61617 due to: java.net.BindException: Address already in use
+ at org.apache.activemq.util.IOExceptionSupport.create(IOExceptionSupport.java:33)
+ at org.apache.activemq.transport.tcp.TcpTransportServer.bind(TcpTransportServer.java:135)
+ at org.apache.activemq.transport.tcp.TcpTransportFactory.doBind(TcpTransportFactory.java:56)
+ at org.apache.activemq.transport.TransportFactorySupport.bind(TransportFactorySupport.java:40)
+ at org.apache.activemq.broker.BrokerService.createTransportConnector(BrokerService.java:2327)
+ at org.apache.activemq.broker.BrokerService.addConnector(BrokerService.java:316)
+ at org.apache.activemq.broker.BrokerService.addConnector(BrokerService.java:306)
+ at org.apache.stratos.python.cartridge.agent.test.PythonAgentTestManager.startBroker(PythonAgentTestManager.java:189)
+ at org.apache.stratos.python.cartridge.agent.test.PythonAgentTestManager.setup(PythonAgentTestManager.java:77)
+ at org.apache.stratos.python.cartridge.agent.test.AgentStartupTest.setupAgentStartupTest(AgentStartupTest.java:57)
+ at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
+ at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
+ at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
+ at java.lang.reflect.Method.invoke(Method.java:606)
+ at org.testng.internal.MethodInvocationHelper.invokeMethod(MethodInvocationHelper.java:80)
+ at org.testng.internal.Invoker.invokeConfigurationMethod(Invoker.java:525)
+ at org.testng.internal.Invoker.invokeConfigurations(Invoker.java:202)
+ at org.testng.internal.Invoker.invokeConfigurations(Invoker.java:130)
+ at org.testng.SuiteRunner.privateRun(SuiteRunner.java:277)
+ at org.testng.SuiteRunner.run(SuiteRunner.java:240)
+ at org.testng.SuiteRunnerWorker.runSuite(SuiteRunnerWorker.java:52)
+ at org.testng.SuiteRunnerWorker.run(SuiteRunnerWorker.java:86)
+ at org.testng.TestNG.runSuitesSequentially(TestNG.java:1158)
+ at org.testng.TestNG.runSuitesLocally(TestNG.java:1083)
+ at org.testng.TestNG.run(TestNG.java:999)
+ at org.apache.maven.surefire.testng.TestNGExecutor.run(TestNGExecutor.java:115)
+ at org.apache.maven.surefire.testng.TestNGDirectoryTestSuite.executeMulti(TestNGDirectoryTestSuite.java:205)
+ at org.apache.maven.surefire.testng.TestNGDirectoryTestSuite.execute(TestNGDirectoryTestSuite.java:108)
+ at org.apache.maven.surefire.testng.TestNGProvider.invoke(TestNGProvider.java:111)
+ at org.apache.maven.surefire.booter.ForkedBooter.invokeProviderInSameClassLoader(ForkedBooter.java:203)
+ at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:155)
+ at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:103)
+Caused by: java.net.BindException: Address already in use
+ at java.net.PlainSocketImpl.socketBind(Native Method)
+ at java.net.AbstractPlainSocketImpl.bind(AbstractPlainSocketImpl.java:376)
+ at java.net.ServerSocket.bind(ServerSocket.java:376)
+ at java.net.ServerSocket.<init>(ServerSocket.java:237)
+ at javax.net.DefaultServerSocketFactory.createServerSocket(ServerSocketFactory.java:231)
+ at org.apache.activemq.transport.tcp.TcpTransportServer.bind(TcpTransportServer.java:132)
+ ... 30 more
+2015-08-28 16:51:35,032 [-] [Thread-9] ERROR PythonAgentTestManager Could not start server socket: [port] 8080
+java.net.BindException: Address already in use
+ at java.net.PlainSocketImpl.socketBind(Native Method)
+ at java.net.AbstractPlainSocketImpl.bind(AbstractPlainSocketImpl.java:376)
+ at java.net.ServerSocket.bind(ServerSocket.java:376)
+ at java.net.ServerSocket.<init>(ServerSocket.java:237)
+ at java.net.ServerSocket.<init>(ServerSocket.java:128)
+ at org.apache.stratos.python.cartridge.agent.test.PythonAgentTestManager$4.run(PythonAgentTestManager.java:236)
+ at java.lang.Thread.run(Thread.java:745)
+2015-08-28 16:51:35,349 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:35,269] DEBUG {config.py:read_conf_file} - Config file path : '/home/akila/Documents/ASF/stratos-ravihansa3000.git/products/python-cartridge-agent/integration/target/d172cc3f-19ec-49a3-9d52-909804158deb/apache-stratos-python-cartridge-agent-4.1.2/agent.conf'
+2015-08-28 16:51:35,349 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:35,270] DEBUG {config.py:read_payload_file} - Param file path : '/home/akila/Documents/ASF/stratos-ravihansa3000.git/products/python-cartridge-agent/integration/target/d172cc3f-19ec-49a3-9d52-909804158deb/apache-stratos-python-cartridge-agent-4.1.2/payload/launch-params'
+2015-08-28 16:51:35,350 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:35,270] DEBUG {config.py:read_property} - Reading payload parameter: APPLICATION_ID = application-3
+2015-08-28 16:51:35,350 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:35,270] DEBUG {config.py:read_property} - Reading payload parameter: SERVICE_NAME = tomcat-mt
+2015-08-28 16:51:35,350 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:35,270] DEBUG {config.py:read_property} - Reading payload parameter: CLUSTER_ID = tomcat.domain
+2015-08-28 16:51:35,350 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:35,270] DEBUG {config.py:read_property} - Reading payload parameter: PORTS = 8080
+2015-08-28 16:51:35,350 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:35,270] DEBUG {config.py:read_property} - Reading payload parameter: MULTITENANT = true
+2015-08-28 16:51:35,350 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:35,271] DEBUG {config.py:read_property} - Reading payload parameter: TENANT_ID = -1234
+2015-08-28 16:51:35,350 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:35,271] DEBUG {config.py:read_property} - Reading property: auto.commit = true
+2015-08-28 16:51:35,350 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:35,271] DEBUG {config.py:read_property} - Reading payload parameter: INTERNAL = false
+2015-08-28 16:51:35,350 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:35,271] DEBUG {config.py:read_property} - Reading property: artifact.update.interval = 15
+2015-08-28 16:51:35,350 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:35,271] DEBUG {config.py:read_property} - Reading payload parameter: CLUSTER_INSTANCE_ID = cluster-1-instance-1
+2015-08-28 16:51:35,350 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:35,271] DEBUG {config.py:read_property} - Reading payload parameter: MEMBER_ID = tomcat.member-1
+2015-08-28 16:51:35,350 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:35,271] DEBUG {config.py:read_property} - Reading payload parameter: NETWORK_PARTITION_ID = network-p1
+2015-08-28 16:51:35,350 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:35,271] DEBUG {config.py:read_property} - Reading payload parameter: PARTITION_ID = p1
+2015-08-28 16:51:35,350 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:35,271] DEBUG {config.py:read_property} - Reading payload parameter: APPLICATION_PATH = /tmp/pca-test-suite-2
+2015-08-28 16:51:35,350 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:35,271] DEBUG {config.py:read_property} - Reading payload parameter: REPO_URL = https://github.com/imesh/stratos-php-applications.git
+2015-08-28 16:51:35,351 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:35,271] DEBUG {config.py:read_property} - Reading payload parameter: CARTRIDGE_KEY = PUjpXCLujDhYr5A6
+2015-08-28 16:51:35,351 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:35,271] DEBUG {config.py:read_property} - Reading property: log.file.paths = /tmp/agent.screen-adc-mt-test.log
+2015-08-28 16:51:35,351 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:35,271] DEBUG {config.py:read_property} - Reading payload parameter: PERSISTENCE_MAPPING = None
+2015-08-28 16:51:35,351 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:35,271] DEBUG {config.py:read_property} - Reading property: auto.checkout = true
+2015-08-28 16:51:35,351 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:35,272] DEBUG {config.py:read_property} - Reading property: listen.address = localhost
+2015-08-28 16:51:35,351 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:35,272] DEBUG {config.py:read_property} - Reading payload parameter: LB_CLUSTER_ID = None
+2015-08-28 16:51:35,351 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:35,272] DEBUG {config.py:read_property} - Reading payload parameter: MIN_COUNT = 1
+2015-08-28 16:51:35,351 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:35,272] DEBUG {config.py:read_property} - Reading property: lb.private.ip =
+2015-08-28 16:51:35,351 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:35,272] DEBUG {config.py:read_property} - Reading property: lb.public.ip =
+2015-08-28 16:51:35,351 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:35,272] DEBUG {config.py:read_property} - Reading property: tenant.repository.path = /repository/tenants/
+2015-08-28 16:51:35,351 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:35,272] DEBUG {config.py:read_property} - Reading property: super.tenant.repository.path = /repository/deployment/server/
+2015-08-28 16:51:35,351 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:35,272] INFO {config.py:initialize_config} - Cartridge agent configuration initialized
+2015-08-28 16:51:35,351 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:35,272] DEBUG {config.py:initialize_config} - service-name: 'tomcat-mt'
+2015-08-28 16:51:35,351 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:35,272] DEBUG {config.py:initialize_config} - cluster-id: 'tomcat.domain'
+2015-08-28 16:51:35,352 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:35,272] DEBUG {config.py:initialize_config} - cluster-instance-id: 'cluster-1-instance-1'
+2015-08-28 16:51:35,352 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:35,272] DEBUG {config.py:initialize_config} - member-id: 'tomcat.member-1'
+2015-08-28 16:51:35,352 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:35,272] DEBUG {config.py:initialize_config} - network-partition-id: 'network-p1'
+2015-08-28 16:51:35,352 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:35,272] DEBUG {config.py:initialize_config} - partition-id: 'p1'
+2015-08-28 16:51:35,352 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:35,273] DEBUG {config.py:initialize_config} - cartridge-key: 'PUjpXCLujDhYr5A6'
+2015-08-28 16:51:35,352 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:35,273] DEBUG {config.py:initialize_config} - app-path: '/tmp/pca-test-suite-2'
+2015-08-28 16:51:35,352 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:35,273] DEBUG {config.py:initialize_config} - repo-url: 'https://github.com/imesh/stratos-php-applications.git'
+2015-08-28 16:51:35,352 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:35,273] DEBUG {config.py:initialize_config} - ports: "['8080']"
+2015-08-28 16:51:35,352 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:35,273] DEBUG {config.py:initialize_config} - lb-private-ip: None
+2015-08-28 16:51:35,352 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:35,273] DEBUG {config.py:initialize_config} - lb-public-ip: None
+2015-08-28 16:51:35,352 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:35,273] DEBUG {config.py:initialize_config} - dependant_cluster_id: None
+2015-08-28 16:51:35,352 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:35,273] DEBUG {config.py:initialize_config} - export_metadata_keys: None
+2015-08-28 16:51:35,352 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:35,273] DEBUG {config.py:initialize_config} - import_metadata_keys: None
+2015-08-28 16:51:35,352 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:35,273] DEBUG {config.py:initialize_config} - artifact.update.interval: '15'
+2015-08-28 16:51:35,352 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:35,273] DEBUG {config.py:initialize_config} - lvs-virtual-ip: None
+2015-08-28 16:51:35,353 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:35,273] DEBUG {config.py:initialize_config} - log_file_paths: ['/tmp/agent.screen-adc-mt-test.log']
+2015-08-28 16:51:35,353 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:35,273] INFO {config.py:initialize_config} - Initializing plugins
+2015-08-28 16:51:35,353 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:35,273] INFO {config.py:initialize_plugins} - Collecting and loading plugins
+2015-08-28 16:51:35,353 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:35,273] DEBUG {config.py:read_property} - Reading property: plugins.dir = /home/akila/Documents/ASF/stratos-ravihansa3000.git/products/python-cartridge-agent/integration/target/d172cc3f-19ec-49a3-9d52-909804158deb/apache-stratos-python-cartridge-agent-4.1.2/plugins
+2015-08-28 16:51:35,353 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:35,274] DEBUG {PluginFileLocator.py:locatePlugins} - PluginFileLocator walks (recursively) into directory: /home/akila/Documents/ASF/stratos-ravihansa3000.git/products/python-cartridge-agent/integration/target/d172cc3f-19ec-49a3-9d52-909804158deb/apache-stratos-python-cartridge-agent-4.1.2/plugins
+2015-08-28 16:51:35,353 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:35,274] DEBUG {PluginFileLocator.py:locatePlugins} - contracts.pyc is not a valid plugin for strategy info_ext
+2015-08-28 16:51:35,353 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:35,274] DEBUG {PluginFileLocator.py:locatePlugins} - __init__.pyc is not a valid plugin for strategy info_ext
+2015-08-28 16:51:35,353 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:35,274] DEBUG {PluginFileLocator.py:locatePlugins} - __init__.py is not a valid plugin for strategy info_ext
+2015-08-28 16:51:35,353 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:35,274] DEBUG {PluginFileLocator.py:locatePlugins} - contracts.py is not a valid plugin for strategy info_ext
+2015-08-28 16:51:35,353 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:35,274] DEBUG {PluginFileLocator.py:locatePlugins} - README.md is not a valid plugin for strategy info_ext
+2015-08-28 16:51:35,353 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:35,274] INFO {config.py:initialize_extensions} - Collecting and loading extensions
+2015-08-28 16:51:35,353 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:35,274] DEBUG {config.py:read_property} - Reading property: extensions.dir = /home/akila/Documents/ASF/stratos-ravihansa3000.git/products/python-cartridge-agent/integration/target/d172cc3f-19ec-49a3-9d52-909804158deb/apache-stratos-python-cartridge-agent-4.1.2/extensions/py
+2015-08-28 16:51:35,353 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:35,274] DEBUG {PluginFileLocator.py:locatePlugins} - PluginFileLocator walks (recursively) into directory: /home/akila/Documents/ASF/stratos-ravihansa3000.git/products/python-cartridge-agent/integration/target/d172cc3f-19ec-49a3-9d52-909804158deb/apache-stratos-python-cartridge-agent-4.1.2/extensions/py
+2015-08-28 16:51:35,353 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:35,274] DEBUG {PluginFileLocator.py:locatePlugins} - ExtensionExecutor.py is not a valid plugin for strategy info_ext
+2015-08-28 16:51:35,353 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:35,274] DEBUG {PluginFileLocator.py:locatePlugins} - PluginFileLocator found a candidate:
+2015-08-28 16:51:35,354 [-] [Thread-14] INFO PythonAgentTestManager /home/akila/Documents/ASF/stratos-ravihansa3000.git/products/python-cartridge-agent/integration/target/d172cc3f-19ec-49a3-9d52-909804158deb/apache-stratos-python-cartridge-agent-4.1.2/extensions/py/ExtensionExecutor.yapsy-plugin
+2015-08-28 16:51:35,354 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:35,275] DEBUG {PluginFileLocator.py:locatePlugins} - __init__.py is not a valid plugin for strategy info_ext
+2015-08-28 16:51:35,354 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:35,276] DEBUG {config.py:initialize_extensions} - Found extension executor [ExtensionExecutor] at [/home/akila/Documents/ASF/stratos-ravihansa3000.git/products/python-cartridge-agent/integration/target/d172cc3f-19ec-49a3-9d52-909804158deb/apache-stratos-python-cartridge-agent-4.1.2/extensions/py/ExtensionExecutor]
+2015-08-28 16:51:35,354 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:35,276] INFO {config.py:initialize_extensions} - Activated extension executor [ExtensionExecutor]
+2015-08-28 16:51:35,354 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:35,276] DEBUG {config.py:read_property} - Reading property: mb.ip = localhost
+2015-08-28 16:51:35,354 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:35,276] DEBUG {config.py:read_property} - Reading property: mb.port = 1885
+2015-08-28 16:51:35,354 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:35,276] DEBUG {agent.py:main} - Starting cartridge agent
+2015-08-28 16:51:35,354 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:35,277] INFO {agent.py:run} - Starting Cartridge Agent...
+2015-08-28 16:51:35,354 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:35,277] DEBUG {agent.py:register_topology_event_listeners} - Starting topology event message receiver thread
+2015-08-28 16:51:35,354 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:35,277] DEBUG {subscriber.py:register_handler} - Registered handler for event 'MemberActivatedEvent'
+2015-08-28 16:51:35,354 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:35,277] DEBUG {subscriber.py:register_handler} - Registered handler for event 'MemberTerminatedEvent'
+2015-08-28 16:51:35,354 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:35,277] DEBUG {subscriber.py:register_handler} - Registered handler for event 'MemberSuspendedEvent'
+2015-08-28 16:51:35,354 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:35,277] DEBUG {subscriber.py:register_handler} - Registered handler for event 'CompleteTopologyEvent'
+2015-08-28 16:51:35,354 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:35,277] DEBUG {subscriber.py:register_handler} - Registered handler for event 'MemberStartedEvent'
+2015-08-28 16:51:35,354 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:35,277] DEBUG {subscriber.py:register_handler} - Registered handler for event 'MemberCreatedEvent'
+2015-08-28 16:51:35,355 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:35,278] DEBUG {subscriber.py:register_handler} - Registered handler for event 'MemberInitializedEvent'
+2015-08-28 16:51:35,355 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:35,278] INFO {agent.py:register_topology_event_listeners} - Cartridge agent topology receiver thread started
+2015-08-28 16:51:35,355 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:35,279] DEBUG {subscriber.py:run} - Connecting to the message broker with address 'localhost':'1885'
+2015-08-28 16:51:35,355 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:35,317] DEBUG {subscriber.py:on_connect} - Connected to message broker.
+2015-08-28 16:51:35,355 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:35,317] DEBUG {subscriber.py:on_connect} - Subscribed to 'topology/#'
+2015-08-28 16:51:36,049 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:35,269] DEBUG {config.py:read_conf_file} - Config file path : '/home/akila/Documents/ASF/stratos-ravihansa3000.git/products/python-cartridge-agent/integration/target/d172cc3f-19ec-49a3-9d52-909804158deb/apache-stratos-python-cartridge-agent-4.1.2/agent.conf'
+2015-08-28 16:51:36,049 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:35,270] DEBUG {config.py:read_payload_file} - Param file path : '/home/akila/Documents/ASF/stratos-ravihansa3000.git/products/python-cartridge-agent/integration/target/d172cc3f-19ec-49a3-9d52-909804158deb/apache-stratos-python-cartridge-agent-4.1.2/payload/launch-params'
+2015-08-28 16:51:36,049 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:35,270] DEBUG {config.py:read_property} - Reading payload parameter: APPLICATION_ID = application-3
+2015-08-28 16:51:36,049 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:35,270] DEBUG {config.py:read_property} - Reading payload parameter: SERVICE_NAME = tomcat-mt
+2015-08-28 16:51:36,049 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:35,270] DEBUG {config.py:read_property} - Reading payload parameter: CLUSTER_ID = tomcat.domain
+2015-08-28 16:51:36,049 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:35,270] DEBUG {config.py:read_property} - Reading payload parameter: PORTS = 8080
+2015-08-28 16:51:36,049 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:35,270] DEBUG {config.py:read_property} - Reading payload parameter: MULTITENANT = true
+2015-08-28 16:51:36,049 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:35,271] DEBUG {config.py:read_property} - Reading payload parameter: TENANT_ID = -1234
+2015-08-28 16:51:36,050 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:35,271] DEBUG {config.py:read_property} - Reading property: auto.commit = true
+2015-08-28 16:51:36,050 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:35,271] DEBUG {config.py:read_property} - Reading payload parameter: INTERNAL = false
+2015-08-28 16:51:36,050 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:35,271] DEBUG {config.py:read_property} - Reading property: artifact.update.interval = 15
+2015-08-28 16:51:36,050 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:35,271] DEBUG {config.py:read_property} - Reading payload parameter: CLUSTER_INSTANCE_ID = cluster-1-instance-1
+2015-08-28 16:51:36,050 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:35,271] DEBUG {config.py:read_property} - Reading payload parameter: MEMBER_ID = tomcat.member-1
+2015-08-28 16:51:36,050 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:35,271] DEBUG {config.py:read_property} - Reading payload parameter: NETWORK_PARTITION_ID = network-p1
+2015-08-28 16:51:36,050 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:35,271] DEBUG {config.py:read_property} - Reading payload parameter: PARTITION_ID = p1
+2015-08-28 16:51:36,050 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:35,271] DEBUG {config.py:read_property} - Reading payload parameter: APPLICATION_PATH = /tmp/pca-test-suite-2
+2015-08-28 16:51:36,050 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:35,271] DEBUG {config.py:read_property} - Reading payload parameter: REPO_URL = https://github.com/imesh/stratos-php-applications.git
+2015-08-28 16:51:36,050 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:35,271] DEBUG {config.py:read_property} - Reading payload parameter: CARTRIDGE_KEY = PUjpXCLujDhYr5A6
+2015-08-28 16:51:36,050 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:35,271] DEBUG {config.py:read_property} - Reading property: log.file.paths = /tmp/agent.screen-adc-mt-test.log
+2015-08-28 16:51:36,050 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:35,271] DEBUG {config.py:read_property} - Reading payload parameter: PERSISTENCE_MAPPING = None
+2015-08-28 16:51:36,050 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:35,271] DEBUG {config.py:read_property} - Reading property: auto.checkout = true
+2015-08-28 16:51:36,050 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:35,272] DEBUG {config.py:read_property} - Reading property: listen.address = localhost
+2015-08-28 16:51:36,050 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:35,272] DEBUG {config.py:read_property} - Reading payload parameter: LB_CLUSTER_ID = None
+2015-08-28 16:51:36,051 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:35,272] DEBUG {config.py:read_property} - Reading payload parameter: MIN_COUNT = 1
+2015-08-28 16:51:36,051 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:35,272] DEBUG {config.py:read_property} - Reading property: lb.private.ip =
+2015-08-28 16:51:36,051 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:35,272] DEBUG {config.py:read_property} - Reading property: lb.public.ip =
+2015-08-28 16:51:36,051 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:35,272] DEBUG {config.py:read_property} - Reading property: tenant.repository.path = /repository/tenants/
+2015-08-28 16:51:36,051 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:35,272] DEBUG {config.py:read_property} - Reading property: super.tenant.repository.path = /repository/deployment/server/
+2015-08-28 16:51:36,051 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:35,272] INFO {config.py:initialize_config} - Cartridge agent configuration initialized
+2015-08-28 16:51:36,051 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:35,272] DEBUG {config.py:initialize_config} - service-name: 'tomcat-mt'
+2015-08-28 16:51:36,051 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:35,272] DEBUG {config.py:initialize_config} - cluster-id: 'tomcat.domain'
+2015-08-28 16:51:36,051 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:35,272] DEBUG {config.py:initialize_config} - cluster-instance-id: 'cluster-1-instance-1'
+2015-08-28 16:51:36,051 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:35,272] DEBUG {config.py:initialize_config} - member-id: 'tomcat.member-1'
+2015-08-28 16:51:36,051 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:35,272] DEBUG {config.py:initialize_config} - network-partition-id: 'network-p1'
+2015-08-28 16:51:36,051 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:35,272] DEBUG {config.py:initialize_config} - partition-id: 'p1'
+2015-08-28 16:51:36,051 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:35,273] DEBUG {config.py:initialize_config} - cartridge-key: 'PUjpXCLujDhYr5A6'
+2015-08-28 16:51:36,051 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:35,273] DEBUG {config.py:initialize_config} - app-path: '/tmp/pca-test-suite-2'
+2015-08-28 16:51:36,052 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:35,273] DEBUG {config.py:initialize_config} - repo-url: 'https://github.com/imesh/stratos-php-applications.git'
+2015-08-28 16:51:36,052 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:35,273] DEBUG {config.py:initialize_config} - ports: "['8080']"
+2015-08-28 16:51:36,052 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:35,273] DEBUG {config.py:initialize_config} - lb-private-ip: None
+2015-08-28 16:51:36,052 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:35,273] DEBUG {config.py:initialize_config} - lb-public-ip: None
+2015-08-28 16:51:36,052 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:35,273] DEBUG {config.py:initialize_config} - dependant_cluster_id: None
+2015-08-28 16:51:36,052 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:35,273] DEBUG {config.py:initialize_config} - export_metadata_keys: None
+2015-08-28 16:51:36,052 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:35,273] DEBUG {config.py:initialize_config} - import_metadata_keys: None
+2015-08-28 16:51:36,052 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:35,273] DEBUG {config.py:initialize_config} - artifact.update.interval: '15'
+2015-08-28 16:51:36,052 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:35,273] DEBUG {config.py:initialize_config} - lvs-virtual-ip: None
+2015-08-28 16:51:36,052 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:35,273] DEBUG {config.py:initialize_config} - log_file_paths: ['/tmp/agent.screen-adc-mt-test.log']
+2015-08-28 16:51:36,052 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:35,273] INFO {config.py:initialize_config} - Initializing plugins
+2015-08-28 16:51:36,052 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:35,273] INFO {config.py:initialize_plugins} - Collecting and loading plugins
+2015-08-28 16:51:36,052 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:35,273] DEBUG {config.py:read_property} - Reading property: plugins.dir = /home/akila/Documents/ASF/stratos-ravihansa3000.git/products/python-cartridge-agent/integration/target/d172cc3f-19ec-49a3-9d52-909804158deb/apache-stratos-python-cartridge-agent-4.1.2/plugins
+2015-08-28 16:51:36,053 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:35,274] DEBUG {PluginFileLocator.py:locatePlugins} - PluginFileLocator walks (recursively) into directory: /home/akila/Documents/ASF/stratos-ravihansa3000.git/products/python-cartridge-agent/integration/target/d172cc3f-19ec-49a3-9d52-909804158deb/apache-stratos-python-cartridge-agent-4.1.2/plugins
+2015-08-28 16:51:36,053 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:35,274] DEBUG {PluginFileLocator.py:locatePlugins} - contracts.pyc is not a valid plugin for strategy info_ext
+2015-08-28 16:51:36,053 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:35,274] DEBUG {PluginFileLocator.py:locatePlugins} - __init__.pyc is not a valid plugin for strategy info_ext
+2015-08-28 16:51:36,053 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:35,274] DEBUG {PluginFileLocator.py:locatePlugins} - __init__.py is not a valid plugin for strategy info_ext
+2015-08-28 16:51:36,053 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:35,274] DEBUG {PluginFileLocator.py:locatePlugins} - contracts.py is not a valid plugin for strategy info_ext
+2015-08-28 16:51:36,053 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:35,274] DEBUG {PluginFileLocator.py:locatePlugins} - README.md is not a valid plugin for strategy info_ext
+2015-08-28 16:51:36,053 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:35,274] INFO {config.py:initialize_extensions} - Collecting and loading extensions
+2015-08-28 16:51:36,053 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:35,274] DEBUG {config.py:read_property} - Reading property: extensions.dir = /home/akila/Documents/ASF/stratos-ravihansa3000.git/products/python-cartridge-agent/integration/target/d172cc3f-19ec-49a3-9d52-909804158deb/apache-stratos-python-cartridge-agent-4.1.2/extensions/py
+2015-08-28 16:51:36,053 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:35,274] DEBUG {PluginFileLocator.py:locatePlugins} - PluginFileLocator walks (recursively) into directory: /home/akila/Documents/ASF/stratos-ravihansa3000.git/products/python-cartridge-agent/integration/target/d172cc3f-19ec-49a3-9d52-909804158deb/apache-stratos-python-cartridge-agent-4.1.2/extensions/py
+2015-08-28 16:51:36,053 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:35,274] DEBUG {PluginFileLocator.py:locatePlugins} - ExtensionExecutor.py is not a valid plugin for strategy info_ext
+2015-08-28 16:51:36,053 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:35,274] DEBUG {PluginFileLocator.py:locatePlugins} - PluginFileLocator found a candidate:
+2015-08-28 16:51:36,054 [-] [Thread-15] INFO ADCMTAppTest /home/akila/Documents/ASF/stratos-ravihansa3000.git/products/python-cartridge-agent/integration/target/d172cc3f-19ec-49a3-9d52-909804158deb/apache-stratos-python-cartridge-agent-4.1.2/extensions/py/ExtensionExecutor.yapsy-plugin
+2015-08-28 16:51:36,054 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:35,275] DEBUG {PluginFileLocator.py:locatePlugins} - __init__.py is not a valid plugin for strategy info_ext
+2015-08-28 16:51:36,054 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:35,276] DEBUG {config.py:initialize_extensions} - Found extension executor [ExtensionExecutor] at [/home/akila/Documents/ASF/stratos-ravihansa3000.git/products/python-cartridge-agent/integration/target/d172cc3f-19ec-49a3-9d52-909804158deb/apache-stratos-python-cartridge-agent-4.1.2/extensions/py/ExtensionExecutor]
+2015-08-28 16:51:36,054 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:35,276] INFO {config.py:initialize_extensions} - Activated extension executor [ExtensionExecutor]
+2015-08-28 16:51:36,054 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:35,276] DEBUG {config.py:read_property} - Reading property: mb.ip = localhost
+2015-08-28 16:51:36,054 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:35,276] DEBUG {config.py:read_property} - Reading property: mb.port = 1885
+2015-08-28 16:51:36,054 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:35,276] DEBUG {agent.py:main} - Starting cartridge agent
+2015-08-28 16:51:36,054 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:35,277] INFO {agent.py:run} - Starting Cartridge Agent...
+2015-08-28 16:51:36,054 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:35,277] DEBUG {agent.py:register_topology_event_listeners} - Starting topology event message receiver thread
+2015-08-28 16:51:36,054 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:35,277] DEBUG {subscriber.py:register_handler} - Registered handler for event 'MemberActivatedEvent'
+2015-08-28 16:51:36,054 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:35,277] DEBUG {subscriber.py:register_handler} - Registered handler for event 'MemberTerminatedEvent'
+2015-08-28 16:51:36,054 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:35,277] DEBUG {subscriber.py:register_handler} - Registered handler for event 'MemberSuspendedEvent'
+2015-08-28 16:51:36,055 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:35,277] DEBUG {subscriber.py:register_handler} - Registered handler for event 'CompleteTopologyEvent'
+2015-08-28 16:51:36,055 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:35,277] DEBUG {subscriber.py:register_handler} - Registered handler for event 'MemberStartedEvent'
+2015-08-28 16:51:36,055 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:35,277] DEBUG {subscriber.py:register_handler} - Registered handler for event 'MemberCreatedEvent'
+2015-08-28 16:51:36,055 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:35,278] DEBUG {subscriber.py:register_handler} - Registered handler for event 'MemberInitializedEvent'
+2015-08-28 16:51:36,055 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:35,278] INFO {agent.py:register_topology_event_listeners} - Cartridge agent topology receiver thread started
+2015-08-28 16:51:36,055 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:35,279] DEBUG {subscriber.py:run} - Connecting to the message broker with address 'localhost':'1885'
+2015-08-28 16:51:36,055 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:35,317] DEBUG {subscriber.py:on_connect} - Connected to message broker.
+2015-08-28 16:51:36,360 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:36,279] DEBUG {agent.py:run} - LVS Virtual IP is not defined
+2015-08-28 16:51:36,360 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:36,279] INFO {agent.py:wait_for_complete_topology} - Waiting for complete topology event...
+2015-08-28 16:51:38,055 [-] [Thread-15] INFO ADCMTAppTest Publishing complete topology event...
+2015-08-28 16:51:38,208 [-] [Thread-15] INFO ADCMTAppTest Complete topology event published
+2015-08-28 16:51:38,209 [-] [Thread-15] INFO ADCMTAppTest Publishing member initialized event...
+2015-08-28 16:51:38,228 [-] [pool-2-thread-2] INFO CompleteTopologyMessageProcessor Topology initialized
+2015-08-28 16:51:38,246 [-] [Thread-15] INFO ADCMTAppTest Member initialized event published
+2015-08-28 16:51:38,246 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:35,317] DEBUG {subscriber.py:on_connect} - Subscribed to 'topology/#'
+2015-08-28 16:51:38,248 [-] [pool-2-thread-2] INFO MemberInitializedMessageProcessor Member initialized: [service] tomcat-mt [cluster] tomcat.domain [member] tomcat.member-1
+2015-08-28 16:51:38,267 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:38,212] DEBUG {subscriber.py:on_message} - Message received: topology/CompleteTopologyEvent:
+2015-08-28 16:51:38,267 [-] [Thread-14] INFO PythonAgentTestManager {"topology":{"clusterMap":{},"serviceMap":{"tomcat-mt":{"serviceName":"tomcat-mt","serviceType":"SingleTenant","clusterIdClusterMap":{"tomcat.domain":{"serviceName":"tomcat-mt","clusterId":"tomcat.domain","autoscalePolicyName":"autoscaling-policy-3","deploymentPolicyName":"deployment-policy-3","hostNames":[],"isLbCluster":false,"isKubernetesCluster":false,"memberMap":{"tomcat.member-1":{"serviceName":"tomcat-mt","clusterId":"tomcat.domain","memberId":"tomcat.member-1","clusterInstanceId":"cluster-1-instance-1","networkPartitionId":"network-partition-1","partitionId":"partition-1","initTime":1440760898061,"portMap":{},"defaultPublicIP":"20.0.0.1","defaultPrivateIP":"10.0.0.1","properties":{"prop1":"value1"},"memberStateManager":{"stateStack":["Created"],"identifier":"tomcat.member-1"},"loadBalancingIPType":"Private"}},"appId":"application-3","instanceIdToInstanceContextMap":{},"accessUrls":[],"kubernetesServices":[]
}},"portMap":{}}},"initialized":false}}
+2015-08-28 16:51:38,267 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:38,212] DEBUG {subscriber.py:run} - Executing handler for event 'CompleteTopologyEvent'
+2015-08-28 16:51:38,267 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:38,213] DEBUG {agent.py:on_complete_topology} - Complete topology event received
+2015-08-28 16:51:38,267 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:38,213] DEBUG {eventhandler.py:on_complete_topology_event} - Processing Complete topology event...
+2015-08-28 16:51:38,267 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:38,213] DEBUG {eventhandler.py:is_member_initialized_in_topology} - Found member: {memberId: tomcat.member-1, status: Created}
+2015-08-28 16:51:38,267 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:38,213] DEBUG {eventhandler.py:on_complete_topology_event} - Member initialized False
+2015-08-28 16:51:38,267 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:38,214] DEBUG {config.py:read_property} - Reading property: param.file.path = /home/akila/Documents/ASF/stratos-ravihansa3000.git/products/python-cartridge-agent/integration/target/d172cc3f-19ec-49a3-9d52-909804158deb/apache-stratos-python-cartridge-agent-4.1.2/payload/launch-params
+2015-08-28 16:51:38,267 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:38,214] DEBUG {eventhandler.py:execute_extension_for_event} - Executing extension for event [CompleteTopologyEvent]
+2015-08-28 16:51:38,267 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:38,214] DEBUG {ExtensionExecutor.py:run_plugin} - Running extension for CompleteTopologyEvent
+2015-08-28 16:51:38,267 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:38,215] DEBUG {ExtensionExecutor.py:execute_script} - Execute bash script :: /home/akila/Documents/ASF/stratos-ravihansa3000.git/products/python-cartridge-agent/integration/target/d172cc3f-19ec-49a3-9d52-909804158deb/apache-stratos-python-cartridge-agent-4.1.2/extensions/bash/CompleteTopologyEvent.sh
+2015-08-28 16:51:38,267 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:38,220] INFO {ExtensionExecutor.py:run_plugin} - CompleteTopologyEvent Extension executed. [output]: Fri Aug 28 16:51:38 IST 2015: Complete topology event shell extension executed
+2015-08-28 16:51:38,267 [-] [Thread-14] INFO PythonAgentTestManager
+2015-08-28 16:51:38,268 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:38,220] DEBUG {eventhandler.py:execute_plugins_for_event} - No plugins registered for event CompleteTopologyEvent
+2015-08-28 16:51:38,268 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:38,241] DEBUG {subscriber.py:on_message} - Message received: topology/MemberInitializedEvent:
+2015-08-28 16:51:38,268 [-] [Thread-14] INFO PythonAgentTestManager {"serviceName":"tomcat-mt","clusterId":"tomcat.domain","clusterInstanceId":"cluster-1-instance-1","networkPartitionId":"network-partition-1","partitionId":"partition-1","memberId":"tomcat.member-1"}
+2015-08-28 16:51:38,268 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:38,241] DEBUG {subscriber.py:run} - Executing handler for event 'MemberInitializedEvent'
+2015-08-28 16:51:38,268 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:38,241] DEBUG {agent.py:on_member_initialized} - Member initialized event received: '{"serviceName":"tomcat-mt","clusterId":"tomcat.domain","clusterInstanceId":"cluster-1-instance-1","networkPartitionId":"network-partition-1","partitionId":"partition-1","memberId":"tomcat.member-1"}'
+2015-08-28 16:51:38,268 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:38,241] DEBUG {eventhandler.py:on_member_initialized_event} - Processing Member initialized event...
+2015-08-28 16:51:38,268 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:38,241] DEBUG {eventhandler.py:on_member_initialized_event} - Member exists: True
+2015-08-28 16:51:38,268 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:38,242] DEBUG {config.py:read_property} - Reading property: param.file.path = /home/akila/Documents/ASF/stratos-ravihansa3000.git/products/python-cartridge-agent/integration/target/d172cc3f-19ec-49a3-9d52-909804158deb/apache-stratos-python-cartridge-agent-4.1.2/payload/launch-params
+2015-08-28 16:51:38,268 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:38,242] DEBUG {eventhandler.py:execute_extension_for_event} - Executing extension for event [MemberInitializedEvent]
+2015-08-28 16:51:38,268 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:38,242] DEBUG {ExtensionExecutor.py:run_plugin} - Running extension for MemberInitializedEvent
+2015-08-28 16:51:38,268 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:38,243] DEBUG {ExtensionExecutor.py:execute_script} - Execute bash script :: /home/akila/Documents/ASF/stratos-ravihansa3000.git/products/python-cartridge-agent/integration/target/d172cc3f-19ec-49a3-9d52-909804158deb/apache-stratos-python-cartridge-agent-4.1.2/extensions/bash/MemberInitializedEvent.sh
+2015-08-28 16:51:38,268 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:38,247] INFO {ExtensionExecutor.py:run_plugin} - MemberInitializedEvent Extension executed. [output]: Fri Aug 28 16:51:38 IST 2015: Member initialized event shell extension executed
+2015-08-28 16:51:38,268 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:38,248] DEBUG {eventhandler.py:execute_plugins_for_event} - No plugins registered for event MemberInitializedEvent
+2015-08-28 16:51:39,247 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:36,279] DEBUG {agent.py:run} - LVS Virtual IP is not defined
+2015-08-28 16:51:39,247 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:36,279] INFO {agent.py:wait_for_complete_topology} - Waiting for complete topology event...
+2015-08-28 16:51:39,247 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:38,212] DEBUG {subscriber.py:on_message} - Message received: topology/CompleteTopologyEvent:
+2015-08-28 16:51:39,248 [-] [Thread-15] INFO ADCMTAppTest {"topology":{"clusterMap":{},"serviceMap":{"tomcat-mt":{"serviceName":"tomcat-mt","serviceType":"SingleTenant","clusterIdClusterMap":{"tomcat.domain":{"serviceName":"tomcat-mt","clusterId":"tomcat.domain","autoscalePolicyName":"autoscaling-policy-3","deploymentPolicyName":"deployment-policy-3","hostNames":[],"isLbCluster":false,"isKubernetesCluster":false,"memberMap":{"tomcat.member-1":{"serviceName":"tomcat-mt","clusterId":"tomcat.domain","memberId":"tomcat.member-1","clusterInstanceId":"cluster-1-instance-1","networkPartitionId":"network-partition-1","partitionId":"partition-1","initTime":1440760898061,"portMap":{},"defaultPublicIP":"20.0.0.1","defaultPrivateIP":"10.0.0.1","properties":{"prop1":"value1"},"memberStateManager":{"stateStack":["Created"],"identifier":"tomcat.member-1"},"loadBalancingIPType":"Private"}},"appId":"application-3","instanceIdToInstanceContextMap":{},"accessUrls":[],"kubernetesServices":[]}},"portMa
p":{}}},"initialized":false}}
+2015-08-28 16:51:39,248 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:38,212] DEBUG {subscriber.py:run} - Executing handler for event 'CompleteTopologyEvent'
+2015-08-28 16:51:39,248 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:38,213] DEBUG {agent.py:on_complete_topology} - Complete topology event received
+2015-08-28 16:51:39,248 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:38,213] DEBUG {eventhandler.py:on_complete_topology_event} - Processing Complete topology event...
+2015-08-28 16:51:39,248 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:38,213] DEBUG {eventhandler.py:is_member_initialized_in_topology} - Found member: {memberId: tomcat.member-1, status: Created}
+2015-08-28 16:51:39,248 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:38,213] DEBUG {eventhandler.py:on_complete_topology_event} - Member initialized False
+2015-08-28 16:51:39,248 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:38,214] DEBUG {config.py:read_property} - Reading property: param.file.path = /home/akila/Documents/ASF/stratos-ravihansa3000.git/products/python-cartridge-agent/integration/target/d172cc3f-19ec-49a3-9d52-909804158deb/apache-stratos-python-cartridge-agent-4.1.2/payload/launch-params
+2015-08-28 16:51:39,248 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:38,214] DEBUG {eventhandler.py:execute_extension_for_event} - Executing extension for event [CompleteTopologyEvent]
+2015-08-28 16:51:39,248 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:38,214] DEBUG {ExtensionExecutor.py:run_plugin} - Running extension for CompleteTopologyEvent
+2015-08-28 16:51:39,248 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:38,215] DEBUG {ExtensionExecutor.py:execute_script} - Execute bash script :: /home/akila/Documents/ASF/stratos-ravihansa3000.git/products/python-cartridge-agent/integration/target/d172cc3f-19ec-49a3-9d52-909804158deb/apache-stratos-python-cartridge-agent-4.1.2/extensions/bash/CompleteTopologyEvent.sh
+2015-08-28 16:51:39,249 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:38,220] INFO {ExtensionExecutor.py:run_plugin} - CompleteTopologyEvent Extension executed. [output]: Fri Aug 28 16:51:38 IST 2015: Complete topology event shell extension executed
+2015-08-28 16:51:39,249 [-] [Thread-15] INFO ADCMTAppTest
+2015-08-28 16:51:39,249 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:38,220] DEBUG {eventhandler.py:execute_plugins_for_event} - No plugins registered for event CompleteTopologyEvent
+2015-08-28 16:51:39,249 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:38,241] DEBUG {subscriber.py:on_message} - Message received: topology/MemberInitializedEvent:
+2015-08-28 16:51:39,249 [-] [Thread-15] INFO ADCMTAppTest {"serviceName":"tomcat-mt","clusterId":"tomcat.domain","clusterInstanceId":"cluster-1-instance-1","networkPartitionId":"network-partition-1","partitionId":"partition-1","memberId":"tomcat.member-1"}
+2015-08-28 16:51:39,249 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:38,241] DEBUG {subscriber.py:run} - Executing handler for event 'MemberInitializedEvent'
+2015-08-28 16:51:39,249 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:38,241] DEBUG {agent.py:on_member_initialized} - Member initialized event received: '{"serviceName":"tomcat-mt","clusterId":"tomcat.domain","clusterInstanceId":"cluster-1-instance-1","networkPartitionId":"network-partition-1","partitionId":"partition-1","memberId":"tomcat.member-1"}'
+2015-08-28 16:51:39,249 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:38,241] DEBUG {eventhandler.py:on_member_initialized_event} - Processing Member initialized event...
+2015-08-28 16:51:39,249 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:38,241] DEBUG {eventhandler.py:on_member_initialized_event} - Member exists: True
+2015-08-28 16:51:39,249 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:38,242] DEBUG {config.py:read_property} - Reading property: param.file.path = /home/akila/Documents/ASF/stratos-ravihansa3000.git/products/python-cartridge-agent/integration/target/d172cc3f-19ec-49a3-9d52-909804158deb/apache-stratos-python-cartridge-agent-4.1.2/payload/launch-params
+2015-08-28 16:51:39,249 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:38,242] DEBUG {eventhandler.py:execute_extension_for_event} - Executing extension for event [MemberInitializedEvent]
+2015-08-28 16:51:39,249 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:38,242] DEBUG {ExtensionExecutor.py:run_plugin} - Running extension for MemberInitializedEvent
+2015-08-28 16:51:39,250 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:38,243] DEBUG {ExtensionExecutor.py:execute_script} - Execute bash script :: /home/akila/Documents/ASF/stratos-ravihansa3000.git/products/python-cartridge-agent/integration/target/d172cc3f-19ec-49a3-9d52-909804158deb/apache-stratos-python-cartridge-agent-4.1.2/extensions/bash/MemberInitializedEvent.sh
+2015-08-28 16:51:39,250 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:38,247] INFO {ExtensionExecutor.py:run_plugin} - MemberInitializedEvent Extension executed. [output]: Fri Aug 28 16:51:38 IST 2015: Member initialized event shell extension executed
+2015-08-28 16:51:39,250 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:38,248] DEBUG {eventhandler.py:execute_plugins_for_event} - No plugins registered for event MemberInitializedEvent
+2015-08-28 16:51:41,379 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:41,285] INFO {agent.py:wait_for_complete_topology} - Complete topology event received
+2015-08-28 16:51:41,380 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:41,285] DEBUG {agent.py:register_instance_topic_listeners} - Starting instance notifier event message receiver thread
+2015-08-28 16:51:41,380 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:41,285] DEBUG {subscriber.py:register_handler} - Registered handler for event 'ArtifactUpdatedEvent'
+2015-08-28 16:51:41,380 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:41,285] DEBUG {subscriber.py:register_handler} - Registered handler for event 'InstanceCleanupMemberEvent'
+2015-08-28 16:51:41,380 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:41,285] DEBUG {subscriber.py:register_handler} - Registered handler for event 'InstanceCleanupClusterEvent'
+2015-08-28 16:51:41,380 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:41,285] INFO {agent.py:register_instance_topic_listeners} - Instance notifier event message receiver thread started
+2015-08-28 16:51:41,380 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:41,286] DEBUG {subscriber.py:run} - Connecting to the message broker with address 'localhost':'1885'
+2015-08-28 16:51:41,380 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:41,292] DEBUG {subscriber.py:on_connect} - Connected to message broker.
+2015-08-28 16:51:41,380 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:41,292] DEBUG {subscriber.py:on_connect} - Subscribed to 'instance/#'
+2015-08-28 16:51:42,251 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:41,285] INFO {agent.py:wait_for_complete_topology} - Complete topology event received
+2015-08-28 16:51:42,251 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:41,285] DEBUG {agent.py:register_instance_topic_listeners} - Starting instance notifier event message receiver thread
+2015-08-28 16:51:42,251 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:41,285] DEBUG {subscriber.py:register_handler} - Registered handler for event 'ArtifactUpdatedEvent'
+2015-08-28 16:51:42,251 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:41,285] DEBUG {subscriber.py:register_handler} - Registered handler for event 'InstanceCleanupMemberEvent'
+2015-08-28 16:51:42,252 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:41,285] DEBUG {subscriber.py:register_handler} - Registered handler for event 'InstanceCleanupClusterEvent'
+2015-08-28 16:51:42,252 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:41,285] INFO {agent.py:register_instance_topic_listeners} - Instance notifier event message receiver thread started
+2015-08-28 16:51:42,252 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:41,286] DEBUG {subscriber.py:run} - Connecting to the message broker with address 'localhost':'1885'
+2015-08-28 16:51:42,252 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:41,292] DEBUG {subscriber.py:on_connect} - Connected to message broker.
+2015-08-28 16:51:42,252 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:41,292] DEBUG {subscriber.py:on_connect} - Subscribed to 'instance/#'
+2015-08-28 16:51:42,384 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:42,287] DEBUG {agent.py:register_tenant_event_listeners} - Starting tenant event message receiver thread
+2015-08-28 16:51:42,384 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:42,287] DEBUG {subscriber.py:register_handler} - Registered handler for event 'DomainMappingAddedEvent'
+2015-08-28 16:51:42,384 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:42,287] DEBUG {subscriber.py:register_handler} - Registered handler for event 'DomainsMappingRemovedEvent'
+2015-08-28 16:51:42,384 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:42,287] DEBUG {subscriber.py:register_handler} - Registered handler for event 'CompleteTenantEvent'
+2015-08-28 16:51:42,385 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:42,288] DEBUG {subscriber.py:register_handler} - Registered handler for event 'TenantSubscribedEvent'
+2015-08-28 16:51:42,385 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:42,288] INFO {agent.py:register_tenant_event_listeners} - Tenant event message receiver thread started
+2015-08-28 16:51:42,385 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:42,289] DEBUG {subscriber.py:run} - Connecting to the message broker with address 'localhost':'1885'
+2015-08-28 16:51:42,385 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:42,300] DEBUG {subscriber.py:on_connect} - Connected to message broker.
+2015-08-28 16:51:42,385 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:42,300] DEBUG {subscriber.py:on_connect} - Subscribed to 'tenant/#'
+2015-08-28 16:51:43,253 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:42,287] DEBUG {agent.py:register_tenant_event_listeners} - Starting tenant event message receiver thread
+2015-08-28 16:51:43,253 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:42,287] DEBUG {subscriber.py:register_handler} - Registered handler for event 'DomainMappingAddedEvent'
+2015-08-28 16:51:43,253 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:42,287] DEBUG {subscriber.py:register_handler} - Registered handler for event 'DomainsMappingRemovedEvent'
+2015-08-28 16:51:43,254 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:42,287] DEBUG {subscriber.py:register_handler} - Registered handler for event 'CompleteTenantEvent'
+2015-08-28 16:51:43,254 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:42,288] DEBUG {subscriber.py:register_handler} - Registered handler for event 'TenantSubscribedEvent'
+2015-08-28 16:51:43,254 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:42,288] INFO {agent.py:register_tenant_event_listeners} - Tenant event message receiver thread started
+2015-08-28 16:51:43,254 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:42,289] DEBUG {subscriber.py:run} - Connecting to the message broker with address 'localhost':'1885'
+2015-08-28 16:51:43,254 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:42,300] DEBUG {subscriber.py:on_connect} - Connected to message broker.
+2015-08-28 16:51:43,254 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:42,300] DEBUG {subscriber.py:on_connect} - Subscribed to 'tenant/#'
+2015-08-28 16:51:43,389 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:43,290] DEBUG {agent.py:register_application_signup_event_listeners} - Starting application signup event message receiver thread
+2015-08-28 16:51:43,389 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:43,290] DEBUG {subscriber.py:register_handler} - Registered handler for event 'ApplicationSignUpRemovedEvent'
+2015-08-28 16:51:43,390 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:43,291] INFO {agent.py:register_application_signup_event_listeners} - Application signup event message receiver thread started
+2015-08-28 16:51:43,390 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:43,291] DEBUG {subscriber.py:run} - Connecting to the message broker with address 'localhost':'1885'
+2015-08-28 16:51:43,390 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:43,298] DEBUG {subscriber.py:on_connect} - Connected to message broker.
+2015-08-28 16:51:43,390 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:43,298] DEBUG {subscriber.py:on_connect} - Subscribed to 'application/signup/#'
+2015-08-28 16:51:44,255 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:43,290] DEBUG {agent.py:register_application_signup_event_listeners} - Starting application signup event message receiver thread
+2015-08-28 16:51:44,255 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:43,290] DEBUG {subscriber.py:register_handler} - Registered handler for event 'ApplicationSignUpRemovedEvent'
+2015-08-28 16:51:44,255 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:43,291] INFO {agent.py:register_application_signup_event_listeners} - Application signup event message receiver thread started
+2015-08-28 16:51:44,255 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:43,291] DEBUG {subscriber.py:run} - Connecting to the message broker with address 'localhost':'1885'
+2015-08-28 16:51:44,255 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:43,298] DEBUG {subscriber.py:on_connect} - Connected to message broker.
+2015-08-28 16:51:44,255 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:43,298] DEBUG {subscriber.py:on_connect} - Subscribed to 'application/signup/#'
+2015-08-28 16:51:44,294 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:44,292] DEBUG {eventhandler.py:on_instance_started_event} - Processing instance started event...
+2015-08-28 16:51:44,294 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:44,292] DEBUG {config.py:read_property} - Reading property: param.file.path = /home/akila/Documents/ASF/stratos-ravihansa3000.git/products/python-cartridge-agent/integration/target/d172cc3f-19ec-49a3-9d52-909804158deb/apache-stratos-python-cartridge-agent-4.1.2/payload/launch-params
+2015-08-28 16:51:44,294 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:44,292] DEBUG {eventhandler.py:execute_extension_for_event} - Executing extension for event [InstanceStartedEvent]
+2015-08-28 16:51:44,295 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:44,293] DEBUG {ExtensionExecutor.py:run_plugin} - Running extension for InstanceStartedEvent
+2015-08-28 16:51:44,295 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:44,293] DEBUG {ExtensionExecutor.py:execute_script} - Execute bash script :: /home/akila/Documents/ASF/stratos-ravihansa3000.git/products/python-cartridge-agent/integration/target/d172cc3f-19ec-49a3-9d52-909804158deb/apache-stratos-python-cartridge-agent-4.1.2/extensions/bash/InstanceStartedEvent.sh
+2015-08-28 16:51:44,316 [-] [pool-2-thread-4] INFO PythonAgentTestManager Instance started event received
+2015-08-28 16:51:44,395 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:44,298] INFO {ExtensionExecutor.py:run_plugin} - InstanceStartedEvent Extension executed. [output]: Fri Aug 28 16:51:44 IST 2015: Instance started event shell extension executed
+2015-08-28 16:51:44,395 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:44,299] DEBUG {eventhandler.py:execute_plugins_for_event} - No plugins registered for event InstanceStartedEvent
+2015-08-28 16:51:44,395 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:44,299] INFO {publisher.py:publish_instance_started_event} - Publishing instance started event
+2015-08-28 16:51:44,395 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:44,299] DEBUG {config.py:read_property} - Reading property: mb.ip = localhost
+2015-08-28 16:51:44,395 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:44,299] DEBUG {config.py:read_property} - Reading property: mb.port = 1885
+2015-08-28 16:51:44,395 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:44,306] INFO {publisher.py:publish_instance_started_event} - Instance started event published
+2015-08-28 16:51:44,396 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:44,306] INFO {eventhandler.py:start_server_extension} - Processing start server extension...
+2015-08-28 16:51:44,396 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:44,306] DEBUG {eventhandler.py:is_member_initialized_in_topology} - Found member: {memberId: tomcat.member-1, status: Initialized}
+2015-08-28 16:51:44,396 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:44,306] DEBUG {config.py:read_property} - Reading property: param.file.path = /home/akila/Documents/ASF/stratos-ravihansa3000.git/products/python-cartridge-agent/integration/target/d172cc3f-19ec-49a3-9d52-909804158deb/apache-stratos-python-cartridge-agent-4.1.2/payload/launch-params
+2015-08-28 16:51:44,396 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:44,307] DEBUG {eventhandler.py:execute_extension_for_event} - Executing extension for event [StartServers]
+2015-08-28 16:51:44,396 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:44,307] DEBUG {ExtensionExecutor.py:run_plugin} - Running extension for StartServers
+2015-08-28 16:51:44,396 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:44,307] DEBUG {ExtensionExecutor.py:execute_script} - Execute bash script :: /home/akila/Documents/ASF/stratos-ravihansa3000.git/products/python-cartridge-agent/integration/target/d172cc3f-19ec-49a3-9d52-909804158deb/apache-stratos-python-cartridge-agent-4.1.2/extensions/bash/StartServers.sh
+2015-08-28 16:51:44,396 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:44,312] INFO {ExtensionExecutor.py:run_plugin} - StartServers Extension executed. [output]: Fri Aug 28 16:51:44 IST 2015: Start servers event shell extension executed
+2015-08-28 16:51:44,396 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:44,313] DEBUG {eventhandler.py:execute_plugins_for_event} - No plugins registered for event StartServers
+2015-08-28 16:51:44,396 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:44,313] INFO {agent.py:run} - Artifact repository found, waiting for artifact updated event to checkout artifacts: [repo_url] https://github.com/imesh/stratos-php-applications.git
+2015-08-28 16:51:44,396 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:44,313] DEBUG {config.py:read_property} - Reading property: enable.data.publisher = false
+2015-08-28 16:51:44,396 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:44,313] INFO {logpublisher.py:read_config} - Data Publisher disabled
+2015-08-28 16:51:44,396 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:44,315] DEBUG {subscriber.py:on_message} - Message received: instance/status/InstanceStartedEvent:
+2015-08-28 16:51:44,396 [-] [Thread-14] INFO PythonAgentTestManager {
+2015-08-28 16:51:44,396 [-] [Thread-14] INFO PythonAgentTestManager "applicationId": "application-3",
+2015-08-28 16:51:44,396 [-] [Thread-14] INFO PythonAgentTestManager "clusterId": "tomcat.domain",
+2015-08-28 16:51:44,397 [-] [Thread-14] INFO PythonAgentTestManager "clusterInstanceId": "cluster-1-instance-1",
+2015-08-28 16:51:44,397 [-] [Thread-14] INFO PythonAgentTestManager "instanceId": null,
+2015-08-28 16:51:44,397 [-] [Thread-14] INFO PythonAgentTestManager "memberId": "tomcat.member-1",
+2015-08-28 16:51:44,397 [-] [Thread-14] INFO PythonAgentTestManager "networkPartitionId": "network-p1",
+2015-08-28 16:51:44,397 [-] [Thread-14] INFO PythonAgentTestManager "partitionId": "p1",
+2015-08-28 16:51:44,397 [-] [Thread-14] INFO PythonAgentTestManager "serviceName": "tomcat-mt"
+2015-08-28 16:51:44,397 [-] [Thread-14] INFO PythonAgentTestManager }
+2015-08-28 16:51:44,397 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:44,315] DEBUG {subscriber.py:run} - Event handler not found for event : 'InstanceStartedEvent'
+2015-08-28 16:51:45,256 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:44,292] DEBUG {eventhandler.py:on_instance_started_event} - Processing instance started event...
+2015-08-28 16:51:45,256 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:44,292] DEBUG {config.py:read_property} - Reading property: param.file.path = /home/akila/Documents/ASF/stratos-ravihansa3000.git/products/python-cartridge-agent/integration/target/d172cc3f-19ec-49a3-9d52-909804158deb/apache-stratos-python-cartridge-agent-4.1.2/payload/launch-params
+2015-08-28 16:51:45,257 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:44,292] DEBUG {eventhandler.py:execute_extension_for_event} - Executing extension for event [InstanceStartedEvent]
+2015-08-28 16:51:45,257 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:44,293] DEBUG {ExtensionExecutor.py:run_plugin} - Running extension for InstanceStartedEvent
+2015-08-28 16:51:45,257 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:44,293] DEBUG {ExtensionExecutor.py:execute_script} - Execute bash script :: /home/akila/Documents/ASF/stratos-ravihansa3000.git/products/python-cartridge-agent/integration/target/d172cc3f-19ec-49a3-9d52-909804158deb/apache-stratos-python-cartridge-agent-4.1.2/extensions/bash/InstanceStartedEvent.sh
+2015-08-28 16:51:45,257 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:44,298] INFO {ExtensionExecutor.py:run_plugin} - InstanceStartedEvent Extension executed. [output]: Fri Aug 28 16:51:44 IST 2015: Instance started event shell extension executed
+2015-08-28 16:51:45,257 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:44,299] DEBUG {eventhandler.py:execute_plugins_for_event} - No plugins registered for event InstanceStartedEvent
+2015-08-28 16:51:45,258 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:44,299] INFO {publisher.py:publish_instance_started_event} - Publishing instance started event
+2015-08-28 16:51:45,258 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:44,299] DEBUG {config.py:read_property} - Reading property: mb.ip = localhost
+2015-08-28 16:51:45,258 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:44,299] DEBUG {config.py:read_property} - Reading property: mb.port = 1885
+2015-08-28 16:51:45,258 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:44,306] INFO {publisher.py:publish_instance_started_event} - Instance started event published
+2015-08-28 16:51:45,258 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:44,306] INFO {eventhandler.py:start_server_extension} - Processing start server extension...
+2015-08-28 16:51:45,259 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:44,306] DEBUG {eventhandler.py:is_member_initialized_in_topology} - Found member: {memberId: tomcat.member-1, status: Initialized}
+2015-08-28 16:51:45,259 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:44,306] DEBUG {config.py:read_property} - Reading property: param.file.path = /home/akila/Documents/ASF/stratos-ravihansa3000.git/products/python-cartridge-agent/integration/target/d172cc3f-19ec-49a3-9d52-909804158deb/apache-stratos-python-cartridge-agent-4.1.2/payload/launch-params
+2015-08-28 16:51:45,259 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:44,307] DEBUG {eventhandler.py:execute_extension_for_event} - Executing extension for event [StartServers]
+2015-08-28 16:51:45,259 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:44,307] DEBUG {ExtensionExecutor.py:run_plugin} - Running extension for StartServers
+2015-08-28 16:51:45,259 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:44,307] DEBUG {ExtensionExecutor.py:execute_script} - Execute bash script :: /home/akila/Documents/ASF/stratos-ravihansa3000.git/products/python-cartridge-agent/integration/target/d172cc3f-19ec-49a3-9d52-909804158deb/apache-stratos-python-cartridge-agent-4.1.2/extensions/bash/StartServers.sh
+2015-08-28 16:51:45,259 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:44,312] INFO {ExtensionExecutor.py:run_plugin} - StartServers Extension executed. [output]: Fri Aug 28 16:51:44 IST 2015: Start servers event shell extension executed
+2015-08-28 16:51:45,260 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:44,313] DEBUG {eventhandler.py:execute_plugins_for_event} - No plugins registered for event StartServers
+2015-08-28 16:51:45,286 [-] [Thread-15] INFO ADCMTAppTest Artifact updated event published
+2015-08-28 16:51:45,286 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:44,313] INFO {agent.py:run} - Artifact repository found, waiting for artifact updated event to checkout artifacts: [repo_url] https://github.com/imesh/stratos-php-applications.git
+2015-08-28 16:51:45,287 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:44,313] DEBUG {config.py:read_property} - Reading property: enable.data.publisher = false
+2015-08-28 16:51:45,287 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:44,313] INFO {logpublisher.py:read_config} - Data Publisher disabled
+2015-08-28 16:51:45,287 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:44,315] DEBUG {subscriber.py:on_message} - Message received: instance/status/InstanceStartedEvent:
+2015-08-28 16:51:45,287 [-] [Thread-15] INFO ADCMTAppTest {
+2015-08-28 16:51:45,287 [-] [Thread-15] INFO ADCMTAppTest "applicationId": "application-3",
+2015-08-28 16:51:45,287 [-] [Thread-15] INFO ADCMTAppTest "clusterId": "tomcat.domain",
+2015-08-28 16:51:45,287 [-] [Thread-15] INFO ADCMTAppTest "clusterInstanceId": "cluster-1-instance-1",
+2015-08-28 16:51:45,287 [-] [Thread-15] INFO ADCMTAppTest "instanceId": null,
+2015-08-28 16:51:45,287 [-] [Thread-15] INFO ADCMTAppTest "memberId": "tomcat.member-1",
+2015-08-28 16:51:45,287 [-] [Thread-15] INFO ADCMTAppTest "networkPartitionId": "network-p1",
+2015-08-28 16:51:45,287 [-] [Thread-15] INFO ADCMTAppTest "partitionId": "p1",
+2015-08-28 16:51:45,287 [-] [Thread-15] INFO ADCMTAppTest "serviceName": "tomcat-mt"
+2015-08-28 16:51:45,287 [-] [Thread-15] INFO ADCMTAppTest }
+2015-08-28 16:51:45,287 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:44,315] DEBUG {subscriber.py:run} - Event handler not found for event : 'InstanceStartedEvent'
+2015-08-28 16:51:45,306 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:45,284] DEBUG {subscriber.py:on_message} - Message received: instance/notifier/ArtifactUpdatedEvent:
+2015-08-28 16:51:45,306 [-] [Thread-14] INFO PythonAgentTestManager {"clusterId":"tomcat.domain","repoURL":"https://bitbucket.org/testapache2211/opentestrepo1.git","tenantId":"-1234","commitEnabled":false}
+2015-08-28 16:51:45,306 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:45,284] DEBUG {subscriber.py:run} - Executing handler for event 'ArtifactUpdatedEvent'
+2015-08-28 16:51:45,306 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:45,284] INFO {eventhandler.py:on_artifact_updated_event} - Processing Artifact update event: [tenant] -1234 [cluster] tomcat.domain [status] None
+2015-08-28 16:51:45,307 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:45,284] INFO {eventhandler.py:on_artifact_updated_event} - Executing git checkout
+2015-08-28 16:51:45,307 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:45,285] DEBUG {eventhandler.py:get_repo_path_for_tenant} - Repo path returned : '/tmp/pca-test-suite-2/repository/deployment/server/'
+2015-08-28 16:51:45,308 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:45,285] DEBUG {agentgithandler.py:checkout} - Cloning artifacts from https://bitbucket.org/testapache2211/opentestrepo1.git for the first time to /tmp/pca-test-suite-2/repository/deployment/server/
+2015-08-28 16:51:45,308 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:45,285] DEBUG {agentgithandler.py:checkout} - Executing git clone: [tenant-id] -1234 [repo-url] https://bitbucket.org/testapache2211/opentestrepo1.git, [repo path] /tmp/pca-test-suite-2/repository/deployment/server/
+2015-08-28 16:51:46,288 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:45,284] DEBUG {subscriber.py:on_message} - Message received: instance/notifier/ArtifactUpdatedEvent:
+2015-08-28 16:51:46,288 [-] [Thread-15] INFO ADCMTAppTest {"clusterId":"tomcat.domain","repoURL":"https://bitbucket.org/testapache2211/opentestrepo1.git","tenantId":"-1234","commitEnabled":false}
+2015-08-28 16:51:46,288 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:45,284] DEBUG {subscriber.py:run} - Executing handler for event 'ArtifactUpdatedEvent'
+2015-08-28 16:51:46,288 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:45,284] INFO {eventhandler.py:on_artifact_updated_event} - Processing Artifact update event: [tenant] -1234 [cluster] tomcat.domain [status] None
+2015-08-28 16:51:46,288 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:45,284] INFO {eventhandler.py:on_artifact_updated_event} - Executing git checkout
+2015-08-28 16:51:46,288 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:45,285] DEBUG {eventhandler.py:get_repo_path_for_tenant} - Repo path returned : '/tmp/pca-test-suite-2/repository/deployment/server/'
+2015-08-28 16:51:46,288 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:45,285] DEBUG {agentgithandler.py:checkout} - Cloning artifacts from https://bitbucket.org/testapache2211/opentestrepo1.git for the first time to /tmp/pca-test-suite-2/repository/deployment/server/
+2015-08-28 16:51:46,288 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:45,285] DEBUG {agentgithandler.py:checkout} - Executing git clone: [tenant-id] -1234 [repo-url] https://bitbucket.org/testapache2211/opentestrepo1.git, [repo path] /tmp/pca-test-suite-2/repository/deployment/server/
+2015-08-28 16:51:49,372 [-] [Thread-7] INFO PythonAgentTestManager Client connected to port 8080
+2015-08-28 16:51:49,421 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:49,360] INFO {agentgithandler.py:clone} - Git clone operation for tenant -1234 successful
+2015-08-28 16:51:49,421 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:49,360] DEBUG {agentgithandler.py:checkout} - Git clone executed: [tenant-id] -1234 [repo-url] https://bitbucket.org/testapache2211/opentestrepo1.git
+2015-08-28 16:51:49,421 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:49,360] DEBUG {config.py:read_property} - Reading property: param.file.path = /home/akila/Documents/ASF/stratos-ravihansa3000.git/products/python-cartridge-agent/integration/target/d172cc3f-19ec-49a3-9d52-909804158deb/apache-stratos-python-cartridge-agent-4.1.2/payload/launch-params
+2015-08-28 16:51:49,421 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:49,360] DEBUG {eventhandler.py:execute_extension_for_event} - Executing extension for event [ArtifactUpdatedEvent]
+2015-08-28 16:51:49,421 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:49,361] DEBUG {ExtensionExecutor.py:run_plugin} - Running extension for ArtifactUpdatedEvent
+2015-08-28 16:51:49,421 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:49,361] DEBUG {ExtensionExecutor.py:execute_script} - Execute bash script :: /home/akila/Documents/ASF/stratos-ravihansa3000.git/products/python-cartridge-agent/integration/target/d172cc3f-19ec-49a3-9d52-909804158deb/apache-stratos-python-cartridge-agent-4.1.2/extensions/bash/ArtifactUpdatedEvent.sh
+2015-08-28 16:51:49,422 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:49,370] INFO {ExtensionExecutor.py:run_plugin} - ArtifactUpdatedEvent Extension executed. [output]: Fri Aug 28 16:51:49 IST 2015: Artifacts updated event shell extension executed
+2015-08-28 16:51:49,422 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:49,371] DEBUG {eventhandler.py:execute_plugins_for_event} - No plugins registered for event ArtifactUpdatedEvent
+2015-08-28 16:51:49,422 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:49,371] DEBUG {config.py:read_property} - Reading property: port.check.timeout = 600000
+2015-08-28 16:51:49,422 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:49,372] DEBUG {cartridgeagentutils.py:wait_until_ports_active} - Port check timeout: 600000
+2015-08-28 16:51:49,422 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:49,372] INFO {cartridgeagentutils.py:wait_until_ports_active} - Waiting for ports to be active: [ip] 'localhost' [ports] ['8080']
+2015-08-28 16:51:49,422 [-] [Thread-14] INFO PythonAgentTestManager [2015-08-28 16:51:49,372] DEBUG {cartridgeagentutils.py:check_ports_active} - Port '8080' is active
+2015-08-28 16:51:50,289 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:49,360] INFO {agentgithandler.py:clone} - Git clone operation for tenant -1234 successful
+2015-08-28 16:51:50,290 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:49,360] DEBUG {agentgithandler.py:checkout} - Git clone executed: [tenant-id] -1234 [repo-url] https://bitbucket.org/testapache2211/opentestrepo1.git
+2015-08-28 16:51:50,290 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:49,360] DEBUG {config.py:read_property} - Reading property: param.file.path = /home/akila/Documents/ASF/stratos-ravihansa3000.git/products/python-cartridge-agent/integration/target/d172cc3f-19ec-49a3-9d52-909804158deb/apache-stratos-python-cartridge-agent-4.1.2/payload/launch-params
+2015-08-28 16:51:50,290 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:49,360] DEBUG {eventhandler.py:execute_extension_for_event} - Executing extension for event [ArtifactUpdatedEvent]
+2015-08-28 16:51:50,290 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:49,361] DEBUG {ExtensionExecutor.py:run_plugin} - Running extension for ArtifactUpdatedEvent
+2015-08-28 16:51:50,290 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:49,361] DEBUG {ExtensionExecutor.py:execute_script} - Execute bash script :: /home/akila/Documents/ASF/stratos-ravihansa3000.git/products/python-cartridge-agent/integration/target/d172cc3f-19ec-49a3-9d52-909804158deb/apache-stratos-python-cartridge-agent-4.1.2/extensions/bash/ArtifactUpdatedEvent.sh
+2015-08-28 16:51:50,290 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:49,370] INFO {ExtensionExecutor.py:run_plugin} - ArtifactUpdatedEvent Extension executed. [output]: Fri Aug 28 16:51:49 IST 2015: Artifacts updated event shell extension executed
+2015-08-28 16:51:50,290 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:49,371] DEBUG {eventhandler.py:execute_plugins_for_event} - No plugins registered for event ArtifactUpdatedEvent
+2015-08-28 16:51:50,290 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:49,371] DEBUG {config.py:read_property} - Reading property: port.check.timeout = 600000
+2015-08-28 16:51:50,290 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:49,372] DEBUG {cartridgeagentutils.py:wait_until_ports_active} - Port check timeout: 600000
+2015-08-28 16:51:50,290 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:49,372] INFO {cartridgeagentutils.py:wait_until_ports_active} - Waiting for ports to be active: [ip] 'localhost' [ports] ['8080']
+2015-08-28 16:51:50,290 [-] [Thread-15] INFO ADCMTAppTest [2015-08-28 16:51:49,372] DEBUG {cartridgeagentutils.py:check_ports_active} - P
<TRUNCATED>