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>
