[testng] [Parser] Running: [testng] /root/.hudson/jobs/sherry-module/workspace/gf-ha-qe/build/testng.xml [testng] [testng] ***** /root/.hudson/jobs/sherry-module/workspace/gf-ha-qe/results/iiop/com.sun.dft.glassfish.iiop.failover.ejb.IIOPFailoverEJBMultipleFO [testng] ***** iiop [testng] Dec 31, 2011 9:08:49 AM com.sun.dft.config.FrameworkLogger setLogLevels [testng] INFO: No Framework Log Levels set [testng] Dec 31, 2011 9:08:49 AM com.sun.dft.config.TopologyManager getFTPServerHost [testng] INFO: Property ftp.server.host set to - jed-asqe-24.us.oracle.com [testng] Dec 31, 2011 9:08:49 AM com.sun.dft.ftp.api.FTPServiceFactory getLocalFTPService [testng] INFO: Property ftp.property.files.location set to - /root/.hudson/jobs/sherry-module/workspace/gf-ha-qe/config/ftp [testng] Dec 31, 2011 9:08:49 AM com.sun.dft.ftp.api.FTPServiceFactory getLocalFTPService [testng] INFO: ftp.server.host - [testng] Dec 31, 2011 9:08:49 AM com.sun.dft.ftp.impl.serverCore [testng] INFO: FTP server started on port : 8576 [testng] FTP Server Started at: 0.0.0.0 [testng] Dec 31, 2011 9:08:51 AM com.sun.dft.proxy.impl.AgentServiceImpl [testng] INFO: Creating Agent Service with following props: name - agent3 rmi-host: jed-asqe-26.us.oracle.com rmi-port:3000 ftp-host: jed-asqe-24.us.oracle.com ftp-port: 8576 ftp-user: dftuser ftp-password: dftpassword [testng] Dec 31, 2011 9:08:51 AM com.sun.dft.proxy.impl.AgentServiceImpl [testng] INFO: No Agent Log Levels were set [testng] Dec 31, 2011 9:08:51 AM com.sun.dft.proxy.impl.AgentServiceImpl [testng] INFO: Creating Agent Service with following props: name - agent2 rmi-host: jed-asqe-25.us.oracle.com rmi-port:3000 ftp-host: jed-asqe-24.us.oracle.com ftp-port: 8576 ftp-user: dftuser ftp-password: dftpassword [testng] Dec 31, 2011 9:08:51 AM com.sun.dft.proxy.impl.AgentServiceImpl [testng] INFO: No Agent Log Levels were set [testng] Dec 31, 2011 9:08:51 AM com.sun.dft.proxy.impl.AgentServiceImpl [testng] INFO: Creating Agent Service with following props: name - agent1 rmi-host: jed-asqe-24.us.oracle.com rmi-port:3000 ftp-host: jed-asqe-24.us.oracle.com ftp-port: 8576 ftp-user: dftuser ftp-password: dftpassword [testng] Dec 31, 2011 9:08:51 AM com.sun.dft.proxy.impl.AgentServiceImpl [testng] INFO: No Agent Log Levels were set [testng] Dec 31, 2011 9:08:51 AM com.sun.dft.glassfish.config.resources.impl.DASImpl createAndDownloadPasswordFile [testng] INFO: Writing to password file - {AS_ADMIN_PASSWORD=, AS_ADMIN_MASTERPASSWORD=, imq.imqcmd.password=admin} [testng] Dec 31, 2011 9:08:51 AM com.sun.dft.glassfish.config.resources.impl.DASImpl createAndDownloadPasswordFile [testng] INFO: Copying password file asadmin-password-file to remote location. [testng] Dec 31, 2011 9:08:52 AM com.sun.dft.glassfish.config.ResourceManager createDASandClusters [testng] INFO: Created Resource Das - [machineAgent: [MachineAgent - name:agent1], domainName: st-domain, dasPort: 4848, httpPort: 8080, httpsPort: 8181, [testng] , user: adminpassword: adminadmin, masterPassword: changeit] [testng] Dec 31, 2011 9:08:52 AM com.sun.dft.glassfish.config.ResourceManager createClusters [testng] INFO: Created Cluster - com.sun.dft.glassfish.config.resources.impl.ClusterImpl@8b8524a [testng] Dec 31, 2011 9:08:52 AM com.sun.dft.glassfish.config.ResourceManager createNodesAndSetInstanceCounts [testng] INFO: Created Nodes - [NodeImpl:[agent1. Installlocation : /space/gf-ha/glassfish3. Instances count map : {st-cluster=2}], NodeImpl:[agent2. Installlocation : /space/gf-ha/glassfish3. Instances count map : {st-cluster=4}], NodeImpl:[agent3. Installlocation : /space/gf-ha/glassfish3. Instances count map : {st-cluster=4}]] [testng] Dec 31, 2011 9:08:52 AM com.sun.dft.glassfish.config.ResourceManager createServerInstances [testng] INFO: Created Server Instance - com.sun.dft.glassfish.config.resources.impl.ServerInstanceImpl@8b8850c for cluster - st-cluster [testng] Dec 31, 2011 9:08:52 AM com.sun.dft.glassfish.config.ResourceManager createServerInstances [testng] INFO: Created Server Instance - com.sun.dft.glassfish.config.resources.impl.ServerInstanceImpl@8b89137 for cluster - st-cluster [testng] Dec 31, 2011 9:08:52 AM com.sun.dft.glassfish.config.ResourceManager createServerInstances [testng] INFO: Created Server Instance - com.sun.dft.glassfish.config.resources.impl.ServerInstanceImpl@8b89e57 for cluster - st-cluster [testng] Dec 31, 2011 9:08:52 AM com.sun.dft.glassfish.config.ResourceManager createServerInstances [testng] INFO: Created Server Instance - com.sun.dft.glassfish.config.resources.impl.ServerInstanceImpl@8b8ad0c for cluster - st-cluster [testng] Dec 31, 2011 9:08:52 AM com.sun.dft.glassfish.config.ResourceManager createServerInstances [testng] INFO: Created Server Instance - com.sun.dft.glassfish.config.resources.impl.ServerInstanceImpl@8b8bccb for cluster - st-cluster [testng] Dec 31, 2011 9:08:52 AM com.sun.dft.glassfish.config.ResourceManager createServerInstances [testng] INFO: Created Server Instance - com.sun.dft.glassfish.config.resources.impl.ServerInstanceImpl@8b8ce67 for cluster - st-cluster [testng] Dec 31, 2011 9:08:52 AM com.sun.dft.glassfish.config.ResourceManager createServerInstances [testng] INFO: Created Server Instance - com.sun.dft.glassfish.config.resources.impl.ServerInstanceImpl@8c4e23c for cluster - st-cluster [testng] Dec 31, 2011 9:08:52 AM com.sun.dft.glassfish.config.ResourceManager createServerInstances [testng] INFO: Created Server Instance - com.sun.dft.glassfish.config.resources.impl.ServerInstanceImpl@8c4f60d for cluster - st-cluster [testng] Dec 31, 2011 9:08:52 AM com.sun.dft.glassfish.config.ResourceManager createServerInstances [testng] INFO: Created Server Instance - com.sun.dft.glassfish.config.resources.impl.ServerInstanceImpl@8c3eb62 for cluster - st-cluster [testng] Dec 31, 2011 9:08:52 AM com.sun.dft.glassfish.config.ResourceManager createServerInstances [testng] INFO: Created Server Instance - com.sun.dft.glassfish.config.resources.impl.ServerInstanceImpl@8c402ad for cluster - st-cluster [testng] Dec 31, 2011 9:08:52 AM com.sun.dft.glassfish.config.ResourceManager createWebserverAndLBConfig [testng] INFO: Found webserver element in the configuration, now creating webserver resource [testng] Dec 31, 2011 9:08:52 AM com.sun.dft.glassfish.config.resources.api.WebserverFactory createWebserver [testng] INFO: Creating sjsws [testng] Dec 31, 2011 9:08:52 AM com.sun.dft.glassfish.config.ResourceManager createWebserverAndLBConfig [testng] INFO: Created Webserver Resource: com.sun.dft.glassfish.config.resources.impl.SJSWS@8c46935 [testng] Dec 31, 2011 9:08:52 AM com.sun.dft.glassfish.config.ResourceManager createWebserverAndLBConfig [testng] INFO: Created LB Config Resource: com.sun.dft.glassfish.config.resources.impl.LBConfigImpl@8c47321 [testng] Dec 31, 2011 9:08:52 AM com.sun.dft.glassfish.ipc.impl.CallbackServiceImpl [testng] INFO: Registering CallbackServer [testng] Dec 31, 2011 9:08:52 AM com.sun.dft.proxy.impl.AgentServiceImpl resetAgentLog [testng] INFO: Resetting agent log file - /space/gf-ha/tools/dft-agent-runtime/felix/agent.log on agent: agent3 [testng] Dec 31, 2011 9:08:52 AM com.sun.dft.proxy.impl.AgentServiceImpl resetAgentLog [testng] INFO: Resetting agent log file - /space/gf-ha/tools/dft-agent-runtime/felix/agent.log on agent: agent1 [testng] Dec 31, 2011 9:08:52 AM com.sun.dft.proxy.impl.AgentServiceImpl resetAgentLog [testng] INFO: Resetting agent log file - /space/gf-ha/tools/dft-agent-runtime/felix/agent.log on agent: agent2 [testng] Dec 31, 2011 9:08:53 AM com.sun.dft.glassfish.config.resources.impl.DASImpl deployAndRetrieveClientJar [testng] INFO: DASImpl: Deploying iiop/MultiEJBApp.ear [testng] Dec 31, 2011 9:08:53 AM com.sun.dft.glassfish.config.resources.impl.DASImpl invokeCommandLineService [testng] INFO: Executing following command at Agent: agent1, Host: jed-asqe-24.us.oracle.com [testng] /space/gf-ha/glassfish3/bin/asadmin --user admin deploy --retrieve /space/gf-ha/agent-repository//appclient --availabilityenabled=true --target st-cluster --force=true /space/gf-ha/agent-repository/iiop/MultiEJBApp.ear [testng] Dec 31, 2011 9:08:59 AM com.sun.dft.glassfish.utils.Utility logCommandOutput [testng] INFO: Command Executed at agent machine agent1: /space/gf-ha/glassfish3/bin/asadmin --user admin deploy --retrieve /space/gf-ha/agent-repository//appclient --availabilityenabled=true --target st-cluster --force=true /space/gf-ha/agent-repository/iiop/MultiEJBApp.ear [testng] Output : Application deployed with name MultiEJBApp. [testng] Command deploy executed successfully. [testng] [testng] Deployed MultiEJBApp [testng] Dec 31, 2011 9:08:59 AM com.sun.dft.glassfish.config.BaseTestCase checkPoint [testng] INFO: Deployed MultiEJBApp [testng] com.sun.dft.glassfish.iiop.failover.ejb.IIOPFailoverEJBMultipleFO/test1 [testng] reportDir = /root/.hudson/jobs/sherry-module/workspace/gf-ha-qe/results/iiop/com.sun.dft.glassfish.iiop.failover.ejb.IIOPFailoverEJBMultipleFO/test1 [testng] Dec 31, 2011 9:08:59 AM com.sun.dft.glassfish.report.ReportUtils marshall [testng] INFO: Marshalling report xml file - /root/.hudson/jobs/sherry-module/workspace/gf-ha-qe/results/iiop/com.sun.dft.glassfish.iiop.failover.ejb.IIOPFailoverEJBMultipleFO/xml/report.xml [testng] Running IIOPFailoverEJBMultipleFO from AppClient [testng] Dec 31, 2011 9:08:59 AM com.sun.dft.glassfish.config.BaseTestCase checkPoint [testng] INFO: Running IIOPFailoverEJBMultipleFO from AppClient [testng] Dec 31, 2011 9:08:59 AM com.sun.dft.glassfish.config.resources.impl.DASImpl invokeCommandLineService [testng] INFO: Executing following command at Agent: agent1, Host: jed-asqe-24.us.oracle.com [testng] /space/gf-ha/glassfish3/bin/asadmin --user admin get instance101.system-property.IIOP_LISTENER_PORT.value [testng] Dec 31, 2011 9:09:00 AM com.sun.dft.glassfish.utils.Utility logCommandOutput [testng] INFO: Command Executed at agent machine agent1: /space/gf-ha/glassfish3/bin/asadmin --user admin get instance101.system-property.IIOP_LISTENER_PORT.value [testng] Output : instance101.system-property.IIOP_LISTENER_PORT.value=23700 [testng] Command get executed successfully. [testng] [testng] Dec 31, 2011 9:09:00 AM com.sun.dft.glassfish.config.resources.impl.DASImpl invokeCommandLineService [testng] INFO: Executing following command at Agent: agent1, Host: jed-asqe-24.us.oracle.com [testng] /space/gf-ha/glassfish3/bin/asadmin --user admin get instance102.system-property.IIOP_LISTENER_PORT.value [testng] Dec 31, 2011 9:09:01 AM com.sun.dft.glassfish.utils.Utility logCommandOutput [testng] INFO: Command Executed at agent machine agent1: /space/gf-ha/glassfish3/bin/asadmin --user admin get instance102.system-property.IIOP_LISTENER_PORT.value [testng] Output : instance102.system-property.IIOP_LISTENER_PORT.value=23700 [testng] Command get executed successfully. [testng] [testng] Dec 31, 2011 9:09:01 AM com.sun.dft.glassfish.config.resources.impl.DASImpl invokeCommandLineService [testng] INFO: Executing following command at Agent: agent1, Host: jed-asqe-24.us.oracle.com [testng] /space/gf-ha/glassfish3/bin/asadmin --user admin get instance103.system-property.IIOP_LISTENER_PORT.value [testng] Dec 31, 2011 9:09:03 AM com.sun.dft.glassfish.utils.Utility logCommandOutput [testng] INFO: Command Executed at agent machine agent1: /space/gf-ha/glassfish3/bin/asadmin --user admin get instance103.system-property.IIOP_LISTENER_PORT.value [testng] Output : instance103.system-property.IIOP_LISTENER_PORT.value=23700 [testng] Command get executed successfully. [testng] [testng] Dec 31, 2011 9:09:03 AM com.sun.dft.glassfish.config.resources.impl.DASImpl invokeCommandLineService [testng] INFO: Executing following command at Agent: agent1, Host: jed-asqe-24.us.oracle.com [testng] /space/gf-ha/glassfish3/bin/asadmin --user admin get instance104.system-property.IIOP_LISTENER_PORT.value [testng] Dec 31, 2011 9:09:04 AM com.sun.dft.glassfish.utils.Utility logCommandOutput [testng] INFO: Command Executed at agent machine agent1: /space/gf-ha/glassfish3/bin/asadmin --user admin get instance104.system-property.IIOP_LISTENER_PORT.value [testng] Output : instance104.system-property.IIOP_LISTENER_PORT.value=23701 [testng] Command get executed successfully. [testng] [testng] Dec 31, 2011 9:09:04 AM com.sun.dft.glassfish.config.resources.impl.DASImpl invokeCommandLineService [testng] INFO: Executing following command at Agent: agent1, Host: jed-asqe-24.us.oracle.com [testng] /space/gf-ha/glassfish3/bin/asadmin --user admin get instance105.system-property.IIOP_LISTENER_PORT.value [testng] Dec 31, 2011 9:09:05 AM com.sun.dft.glassfish.utils.Utility logCommandOutput [testng] INFO: Command Executed at agent machine agent1: /space/gf-ha/glassfish3/bin/asadmin --user admin get instance105.system-property.IIOP_LISTENER_PORT.value [testng] Output : instance105.system-property.IIOP_LISTENER_PORT.value=23701 [testng] Command get executed successfully. [testng] [testng] Dec 31, 2011 9:09:05 AM com.sun.dft.glassfish.config.resources.impl.DASImpl invokeCommandLineService [testng] INFO: Executing following command at Agent: agent1, Host: jed-asqe-24.us.oracle.com [testng] /space/gf-ha/glassfish3/bin/asadmin --user admin get instance106.system-property.IIOP_LISTENER_PORT.value [testng] Dec 31, 2011 9:09:07 AM com.sun.dft.glassfish.utils.Utility logCommandOutput [testng] INFO: Command Executed at agent machine agent1: /space/gf-ha/glassfish3/bin/asadmin --user admin get instance106.system-property.IIOP_LISTENER_PORT.value [testng] Output : instance106.system-property.IIOP_LISTENER_PORT.value=23701 [testng] Command get executed successfully. [testng] [testng] Dec 31, 2011 9:09:07 AM com.sun.dft.glassfish.config.resources.impl.DASImpl invokeCommandLineService [testng] INFO: Executing following command at Agent: agent1, Host: jed-asqe-24.us.oracle.com [testng] /space/gf-ha/glassfish3/bin/asadmin --user admin get instance107.system-property.IIOP_LISTENER_PORT.value [testng] Dec 31, 2011 9:09:08 AM com.sun.dft.glassfish.utils.Utility logCommandOutput [testng] INFO: Command Executed at agent machine agent1: /space/gf-ha/glassfish3/bin/asadmin --user admin get instance107.system-property.IIOP_LISTENER_PORT.value [testng] Output : instance107.system-property.IIOP_LISTENER_PORT.value=23702 [testng] Command get executed successfully. [testng] [testng] Dec 31, 2011 9:09:08 AM com.sun.dft.glassfish.config.resources.impl.DASImpl invokeCommandLineService [testng] INFO: Executing following command at Agent: agent1, Host: jed-asqe-24.us.oracle.com [testng] /space/gf-ha/glassfish3/bin/asadmin --user admin get instance108.system-property.IIOP_LISTENER_PORT.value [testng] Dec 31, 2011 9:09:09 AM com.sun.dft.glassfish.utils.Utility logCommandOutput [testng] INFO: Command Executed at agent machine agent1: /space/gf-ha/glassfish3/bin/asadmin --user admin get instance108.system-property.IIOP_LISTENER_PORT.value [testng] Output : instance108.system-property.IIOP_LISTENER_PORT.value=23702 [testng] Command get executed successfully. [testng] [testng] Dec 31, 2011 9:09:09 AM com.sun.dft.glassfish.config.resources.impl.DASImpl invokeCommandLineService [testng] INFO: Executing following command at Agent: agent1, Host: jed-asqe-24.us.oracle.com [testng] /space/gf-ha/glassfish3/bin/asadmin --user admin get instance109.system-property.IIOP_LISTENER_PORT.value [testng] Dec 31, 2011 9:09:10 AM com.sun.dft.glassfish.utils.Utility logCommandOutput [testng] INFO: Command Executed at agent machine agent1: /space/gf-ha/glassfish3/bin/asadmin --user admin get instance109.system-property.IIOP_LISTENER_PORT.value [testng] Output : instance109.system-property.IIOP_LISTENER_PORT.value=23703 [testng] Command get executed successfully. [testng] [testng] Dec 31, 2011 9:09:10 AM com.sun.dft.glassfish.config.resources.impl.DASImpl invokeCommandLineService [testng] INFO: Executing following command at Agent: agent1, Host: jed-asqe-24.us.oracle.com [testng] /space/gf-ha/glassfish3/bin/asadmin --user admin get instance110.system-property.IIOP_LISTENER_PORT.value [testng] Dec 31, 2011 9:09:12 AM com.sun.dft.glassfish.utils.Utility logCommandOutput [testng] INFO: Command Executed at agent machine agent1: /space/gf-ha/glassfish3/bin/asadmin --user admin get instance110.system-property.IIOP_LISTENER_PORT.value [testng] Output : instance110.system-property.IIOP_LISTENER_PORT.value=23703 [testng] Command get executed successfully. [testng] [testng] Dec 31, 2011 9:09:12 AM com.sun.dft.glassfish.helpers.IIOPHelper getIIOPEndpoints [testng] INFO: IIOP endpoints = jed-asqe-24.us.oracle.com:23700,jed-asqe-25.us.oracle.com:23700,jed-asqe-26.us.oracle.com:23700,jed-asqe-24.us.oracle.com:23701,jed-asqe-25.us.oracle.com:23701,jed-asqe-26.us.oracle.com:23701,jed-asqe-25.us.oracle.com:23702,jed-asqe-26.us.oracle.com:23702,jed-asqe-25.us.oracle.com:23703,jed-asqe-26.us.oracle.com:23703 [testng] Dec 31, 2011 9:09:12 AM com.sun.dft.glassfish.config.resources.impl.DASImpl invokeCommandLineService [testng] INFO: Executing following command at Agent: agent1, Host: jed-asqe-24.us.oracle.com [testng] /space/gf-ha/glassfish3/bin/asadmin --user admin get instance101.system-property.IIOP_LISTENER_PORT.value [testng] Dec 31, 2011 9:09:13 AM com.sun.dft.glassfish.utils.Utility logCommandOutput [testng] INFO: Command Executed at agent machine agent1: /space/gf-ha/glassfish3/bin/asadmin --user admin get instance101.system-property.IIOP_LISTENER_PORT.value [testng] Output : instance101.system-property.IIOP_LISTENER_PORT.value=23700 [testng] Command get executed successfully. [testng] [testng] Dec 31, 2011 9:09:13 AM com.sun.dft.glassfish.config.resources.impl.DASImpl invokeCommandLineService [testng] INFO: Executing following command at Agent: agent1, Host: jed-asqe-24.us.oracle.com [testng] /space/gf-ha/glassfish3/bin/asadmin --user admin get instance102.system-property.IIOP_LISTENER_PORT.value [testng] Dec 31, 2011 9:09:15 AM com.sun.dft.glassfish.utils.Utility logCommandOutput [testng] INFO: Command Executed at agent machine agent1: /space/gf-ha/glassfish3/bin/asadmin --user admin get instance102.system-property.IIOP_LISTENER_PORT.value [testng] Output : instance102.system-property.IIOP_LISTENER_PORT.value=23700 [testng] Command get executed successfully. [testng] [testng] Dec 31, 2011 9:09:23 AM com.sun.dft.glassfish.ipc.impl.CallbackServiceImpl findInstanceWithMessageInLog [testng] INFO: Instance with message [SFSB1Bean.sayHello] in log : instance104 [testng] Dec 31, 2011 9:09:33 AM com.sun.dft.glassfish.ipc.impl.CallbackServiceImpl killInstance [testng] INFO: Killing instance instance104 [testng] Dec 31, 2011 9:09:33 AM com.sun.dft.glassfish.utils.TestUtils sleep [testng] INFO: Sleep for 1 seconds. Delay before kill [testng] Dec 31, 2011 9:09:35 AM com.sun.dft.glassfish.helpers.FailureInjectionService killInstanceWithNA [testng] INFO: Result of killing instance104: Linux [testng] [testng] Dec 31, 2011 9:09:46 AM com.sun.dft.glassfish.ipc.impl.CallbackServiceImpl findInstanceWithMessageInLog [testng] INFO: Instance with message [SFSB1Bean.getName] in log : instance106 [testng] Dec 31, 2011 9:09:46 AM com.sun.dft.glassfish.ipc.impl.CallbackServiceImpl killInstance [testng] INFO: Killing instance instance106 [testng] Dec 31, 2011 9:09:46 AM com.sun.dft.glassfish.utils.TestUtils sleep [testng] INFO: Sleep for 1 seconds. Delay before kill [testng] Dec 31, 2011 9:09:48 AM com.sun.dft.glassfish.helpers.FailureInjectionService killInstanceWithNA [testng] INFO: Result of killing instance106: Linux [testng] [testng] Dec 31, 2011 9:09:48 AM com.sun.dft.glassfish.ipc.impl.CallbackServiceImpl getInstancesInCluster [testng] INFO: getting Instances of the cluster [testng] Dec 31, 2011 9:09:48 AM com.sun.dft.glassfish.ipc.impl.CallbackServiceImpl findInInstanceServerLog [testng] INFO: Instance with message [sayHello()] appeared [0] times in log in the instance instance101 [testng] Dec 31, 2011 9:09:48 AM com.sun.dft.glassfish.ipc.impl.CallbackServiceImpl findInInstanceServerLog [testng] INFO: Instance with message [getName()] appeared [0] times in log in the instance instance101 [testng] Dec 31, 2011 9:09:48 AM com.sun.dft.glassfish.ipc.impl.CallbackServiceImpl findInInstanceServerLog [testng] INFO: Instance with message [sayHello()] appeared [0] times in log in the instance instance102 [testng] Dec 31, 2011 9:09:48 AM com.sun.dft.glassfish.ipc.impl.CallbackServiceImpl findInInstanceServerLog [testng] INFO: Instance with message [getName()] appeared [0] times in log in the instance instance102 [testng] Dec 31, 2011 9:09:48 AM com.sun.dft.glassfish.ipc.impl.CallbackServiceImpl findInInstanceServerLog [testng] INFO: Instance with message [sayHello()] appeared [0] times in log in the instance instance103 [testng] Dec 31, 2011 9:09:48 AM com.sun.dft.glassfish.ipc.impl.CallbackServiceImpl findInInstanceServerLog [testng] INFO: Instance with message [getName()] appeared [0] times in log in the instance instance103 [testng] Dec 31, 2011 9:09:48 AM com.sun.dft.glassfish.ipc.impl.CallbackServiceImpl findInInstanceServerLog [testng] INFO: Instance with message [sayHello()] appeared [10] times in log in the instance instance104 [testng] Dec 31, 2011 9:09:48 AM com.sun.dft.glassfish.ipc.impl.CallbackServiceImpl findInInstanceServerLog [testng] INFO: Instance with message [getName()] appeared [0] times in log in the instance instance104 [testng] Dec 31, 2011 9:09:48 AM com.sun.dft.glassfish.ipc.impl.CallbackServiceImpl findInInstanceServerLog [testng] INFO: Instance with message [sayHello()] appeared [0] times in log in the instance instance105 [testng] Dec 31, 2011 9:09:48 AM com.sun.dft.glassfish.ipc.impl.CallbackServiceImpl findInInstanceServerLog [testng] INFO: Instance with message [getName()] appeared [0] times in log in the instance instance105 [testng] Dec 31, 2011 9:09:48 AM com.sun.dft.glassfish.ipc.impl.CallbackServiceImpl findInInstanceServerLog [testng] INFO: Instance with message [sayHello()] appeared [0] times in log in the instance instance106 [testng] Dec 31, 2011 9:09:48 AM com.sun.dft.glassfish.ipc.impl.CallbackServiceImpl findInInstanceServerLog [testng] INFO: Instance with message [getName()] appeared [2] times in log in the instance instance106 [testng] Dec 31, 2011 9:09:48 AM com.sun.dft.glassfish.ipc.impl.CallbackServiceImpl findInInstanceServerLog [testng] INFO: Instance with message [sayHello()] appeared [0] times in log in the instance instance107 [testng] Dec 31, 2011 9:09:48 AM com.sun.dft.glassfish.ipc.impl.CallbackServiceImpl findInInstanceServerLog [testng] INFO: Instance with message [getName()] appeared [0] times in log in the instance instance107 [testng] Dec 31, 2011 9:09:48 AM com.sun.dft.glassfish.ipc.impl.CallbackServiceImpl findInInstanceServerLog [testng] INFO: Instance with message [sayHello()] appeared [0] times in log in the instance instance108 [testng] Dec 31, 2011 9:09:48 AM com.sun.dft.glassfish.ipc.impl.CallbackServiceImpl findInInstanceServerLog [testng] INFO: Instance with message [getName()] appeared [0] times in log in the instance instance108 [testng] Dec 31, 2011 9:09:48 AM com.sun.dft.glassfish.ipc.impl.CallbackServiceImpl findInInstanceServerLog [testng] INFO: Instance with message [sayHello()] appeared [0] times in log in the instance instance109 [testng] Dec 31, 2011 9:09:48 AM com.sun.dft.glassfish.ipc.impl.CallbackServiceImpl findInInstanceServerLog [testng] INFO: Instance with message [getName()] appeared [0] times in log in the instance instance109 [testng] Dec 31, 2011 9:09:48 AM com.sun.dft.glassfish.ipc.impl.CallbackServiceImpl findInInstanceServerLog [testng] INFO: Instance with message [sayHello()] appeared [0] times in log in the instance instance110 [testng] Dec 31, 2011 9:09:48 AM com.sun.dft.glassfish.ipc.impl.CallbackServiceImpl findInInstanceServerLog [testng] INFO: Instance with message [getName()] appeared [0] times in log in the instance instance110 [testng] Dec 31, 2011 9:09:48 AM com.sun.dft.glassfish.utils.Utility logCommandOutput [testng] INFO: Command Executed at agent machine agent1: /space/gf-ha/glassfish3/glassfish/bin/appclient -Dcom.sun.appserv.iiop.endpoints=jed-asqe-24.us.oracle.com:23700,jed-asqe-25.us.oracle.com:23700,jed-asqe-26.us.oracle.com:23700,jed-asqe-24.us.oracle.com:23701,jed-asqe-25.us.oracle.com:23701,jed-asqe-26.us.oracle.com:23701,jed-asqe-25.us.oracle.com:23702,jed-asqe-26.us.oracle.com:23702,jed-asqe-25.us.oracle.com:23703,jed-asqe-26.us.oracle.com:23703 -classpath /space/gf-ha/agent-repository//appclient/MultiEJBAppClient/MultiEJBApp-app-clientClient.jar -xml /space/gf-ha/agent-repository//appclient/sun-acc.xml com.sun.appserver.ee.tests.client.Client MultipleFO [testng] Output : Created ic [testng] Called runTest [testng] SFSB1 done [testng] SFSB2 done [testng] SFSB3 done [testng] SFSB4 done [testng] SFSB5 done [testng] SLSB1 done [testng] SLSB2 done [testng] SLSB3 done [testng] SLSB4 done [testng] SLSB5 done [testng] Called runtest2 [testng] Receive the name SFSB1 [testng] Receive the name SFSB2 [testng] Instance Name: instance101 [testng] Instance instance101 handled 0 requests [testng] Instance Name: instance102 [testng] Instance instance102 handled 0 requests [testng] Instance Name: instance103 [testng] Instance instance103 handled 0 requests [testng] Instance Name: instance104 [testng] Instance instance104 handled 10 requests [testng] Instance Name: instance105 [testng] Instance instance105 handled 0 requests [testng] Instance Name: instance106 [testng] Instance instance106 handled 0 requests [testng] Instance Name: instance107 [testng] Instance instance107 handled 0 requests [testng] Instance Name: instance108 [testng] Instance instance108 handled 0 requests [testng] Instance Name: instance109 [testng] Instance instance109 handled 0 requests [testng] Instance Name: instance110 [testng] Instance instance110 handled 0 requests [testng] FAIL [testng] [testng] Dec 31, 2011 9:09:48 AM com.sun.dft.glassfish.utils.Utility logCommandOutput [testng] SEVERE: Dec 31, 2011 5:09:18 PM org.glassfish.enterprise.iiop.impl.GlassFishORBManager getCorbalocURL [testng] INFO: list[i] ==> jed-asqe-24.us.oracle.com:23700 [testng] Dec 31, 2011 5:09:18 PM org.glassfish.enterprise.iiop.impl.GlassFishORBManager getCorbalocURL [testng] INFO: list[i] ==> jed-asqe-25.us.oracle.com:23700 [testng] Dec 31, 2011 5:09:18 PM org.glassfish.enterprise.iiop.impl.GlassFishORBManager getCorbalocURL [testng] INFO: list[i] ==> jed-asqe-26.us.oracle.com:23700 [testng] Dec 31, 2011 5:09:18 PM org.glassfish.enterprise.iiop.impl.GlassFishORBManager getCorbalocURL [testng] INFO: list[i] ==> jed-asqe-24.us.oracle.com:23701 [testng] Dec 31, 2011 5:09:18 PM org.glassfish.enterprise.iiop.impl.GlassFishORBManager getCorbalocURL [testng] INFO: list[i] ==> jed-asqe-25.us.oracle.com:23701 [testng] Dec 31, 2011 5:09:18 PM org.glassfish.enterprise.iiop.impl.GlassFishORBManager getCorbalocURL [testng] INFO: list[i] ==> jed-asqe-26.us.oracle.com:23701 [testng] Dec 31, 2011 5:09:18 PM org.glassfish.enterprise.iiop.impl.GlassFishORBManager getCorbalocURL [testng] INFO: list[i] ==> jed-asqe-25.us.oracle.com:23702 [testng] Dec 31, 2011 5:09:18 PM org.glassfish.enterprise.iiop.impl.GlassFishORBManager getCorbalocURL [testng] INFO: list[i] ==> jed-asqe-26.us.oracle.com:23702 [testng] Dec 31, 2011 5:09:18 PM org.glassfish.enterprise.iiop.impl.GlassFishORBManager getCorbalocURL [testng] INFO: list[i] ==> jed-asqe-25.us.oracle.com:23703 [testng] Dec 31, 2011 5:09:18 PM org.glassfish.enterprise.iiop.impl.GlassFishORBManager getCorbalocURL [testng] INFO: list[i] ==> jed-asqe-26.us.oracle.com:23703 [testng] Dec 31, 2011 5:09:18 PM org.glassfish.enterprise.iiop.impl.GlassFishORBManager getCorbalocURL [testng] INFO: corbaloc url ==> iiop:1.2@jed-asqe-24.us.oracle.com:23700,iiop:1.2@jed-asqe-25.us.oracle.com:23700,iiop:1.2@jed-asqe-26.us.oracle.com:23700,iiop:1.2@jed-asqe-24.us.oracle.com:23701,iiop:1.2@jed-asqe-25.us.oracle.com:23701,iiop:1.2@jed-asqe-26.us.oracle.com:23701,iiop:1.2@jed-asqe-25.us.oracle.com:23702,iiop:1.2@jed-asqe-26.us.oracle.com:23702,iiop:1.2@jed-asqe-25.us.oracle.com:23703,iiop:1.2@jed-asqe-26.us.oracle.com:23703 [testng] Unknown command memleakserver [testng] Dec 31, 2011 5:09:23 PM com.sun.dft.glassfish.ipc.api.CallbackClient findInstanceWithMessageInLog [testng] INFO: Invoking CallbackServer.findInstanceWithMessageInLog [testng] Dec 31, 2011 5:09:23 PM com.sun.dft.glassfish.ipc.api.CallbackClient getCallbackService [testng] INFO: Resolving CallbackServer [testng] Dec 31, 2011 5:09:23 PM com.sun.dft.glassfish.ipc.api.CallbackClient getCallbackService [testng] INFO: Resolved CallbackServer [testng] Dec 31, 2011 5:09:33 PM com.sun.dft.glassfish.ipc.api.CallbackClient killInstance [testng] INFO: Invoking CallbackServer.killInstance on instance instance104 [testng] Dec 31, 2011 5:09:46 PM com.sun.dft.glassfish.ipc.api.CallbackClient findInstanceWithMessageInLog [testng] INFO: Invoking CallbackServer.findInstanceWithMessageInLog [testng] Dec 31, 2011 5:09:46 PM com.sun.dft.glassfish.ipc.api.CallbackClient killInstance [testng] INFO: Invoking CallbackServer.killInstance on instance instance106 [testng] javax.ejb.EJBException: java.rmi.MarshalException: CORBA COMM_FAILURE 1330446344 Maybe; nested exception is: [testng] org.omg.CORBA.COMM_FAILURE: FINE: IOP00410008: Connection abort vmcid: OMG minor code: 8 completed: Maybe [testng] at com.sun.appserver.ee.tests.ejb.stateful._SFSB3Remote_Wrapper.getName(com/sun/appserver/ee/tests/ejb/stateful/_SFSB3Remote_Wrapper.java) [testng] at com.sun.appserver.ee.tests.client.Client.runTest2(Unknown Source) [testng] at com.sun.appserver.ee.tests.client.Client.runMultipleFOTest(Unknown Source) [testng] at com.sun.appserver.ee.tests.client.Client.main(Unknown Source) [testng] Caused by: java.rmi.MarshalException: CORBA COMM_FAILURE 1330446344 Maybe; nested exception is: [testng] org.omg.CORBA.COMM_FAILURE: FINE: IOP00410008: Connection abort vmcid: OMG minor code: 8 completed: Maybe [testng] at com.sun.corba.ee.impl.javax.rmi.CORBA.Util.mapSystemException(Util.java:259) [testng] at com.sun.corba.ee.impl.presentation.rmi.StubInvocationHandlerImpl.privateInvoke(StubInvocationHandlerImpl.java:213) [testng] at com.sun.corba.ee.impl.presentation.rmi.StubInvocationHandlerImpl.invoke(StubInvocationHandlerImpl.java:152) [testng] at com.sun.corba.ee.impl.presentation.rmi.codegen.CodegenStubBase.invoke(CodegenStubBase.java:227) [testng] at com.sun.appserver.ee.tests.ejb.stateful.__SFSB3Remote_Remote_DynamicStub.getName(com/sun/appserver/ee/tests/ejb/stateful/__SFSB3Remote_Remote_DynamicStub.java) [testng] ... 4 more [testng] Caused by: org.omg.CORBA.COMM_FAILURE: FINE: IOP00410008: Connection abort vmcid: OMG minor code: 8 completed: Maybe [testng] at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method) [testng] at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:39) [testng] at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:27) [testng] at java.lang.reflect.Constructor.newInstance(Constructor.java:513) [testng] at com.sun.corba.ee.spi.orbutil.logex.corba.CorbaExtension.makeException(CorbaExtension.java:248) [testng] at com.sun.corba.ee.spi.orbutil.logex.corba.CorbaExtension.makeException(CorbaExtension.java:95) [testng] at com.sun.corba.ee.spi.orbutil.logex.WrapperGenerator.handleFullLogging(WrapperGenerator.java:387) [testng] at com.sun.corba.ee.spi.orbutil.logex.WrapperGenerator.access$400(WrapperGenerator.java:107) [testng] at com.sun.corba.ee.spi.orbutil.logex.WrapperGenerator$2.invoke(WrapperGenerator.java:511) [testng] at com.sun.corba.ee.spi.orbutil.proxy.CompositeInvocationHandlerImpl.invoke(CompositeInvocationHandlerImpl.java:99) [testng] at $Proxy32.connectionAbort(Unknown Source) [testng] at com.sun.corba.ee.impl.transport.SocketOrChannelConnectionImpl.doOptimizedReadStrategy(SocketOrChannelConnectionImpl.java:1537) [testng] at com.sun.corba.ee.impl.transport.SocketOrChannelConnectionImpl.doWork(SocketOrChannelConnectionImpl.java:1084) [testng] at com.sun.corba.ee.impl.orbutil.threadpool.ThreadPoolImpl$WorkerThread.performWork(ThreadPoolImpl.java:497) [testng] at com.sun.corba.ee.impl.orbutil.threadpool.ThreadPoolImpl$WorkerThread.run(ThreadPoolImpl.java:540) [testng] Caused by: org.omg.CORBA.COMM_FAILURE: FINE: IOP00410030: Exception in a blocking read on connection SocketOrChannelConnectionImpl[ java.nio.channels.SocketChannel[connected local=/10.133.185.54:44947 remote=jed-asqe-26.us.oracle.com/10.133.185.55:23701] ESTABLISHED true true] with a temporary selector vmcid: OMG minor code: 30 completed: No [testng] at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method) [testng] at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:39) [testng] at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:27) [testng] at java.lang.reflect.Constructor.newInstance(Constructor.java:513) [testng] at com.sun.corba.ee.spi.orbutil.logex.corba.CorbaExtension.makeException(CorbaExtension.java:248) [testng] at com.sun.corba.ee.spi.orbutil.logex.corba.CorbaExtension.makeException(CorbaExtension.java:95) [testng] at com.sun.corba.ee.spi.orbutil.logex.WrapperGenerator.handleFullLogging(WrapperGenerator.java:387) [testng] at com.sun.corba.ee.spi.orbutil.logex.WrapperGenerator.access$400(WrapperGenerator.java:107) [testng] at com.sun.corba.ee.spi.orbutil.logex.WrapperGenerator$2.invoke(WrapperGenerator.java:511) [testng] at com.sun.corba.ee.spi.orbutil.proxy.CompositeInvocationHandlerImpl.invoke(CompositeInvocationHandlerImpl.java:99) [testng] at $Proxy32.exceptionBlockingReadWithTemporarySelector(Unknown Source) [testng] at com.sun.corba.ee.impl.transport.SocketOrChannelConnectionImpl.blockingRead(SocketOrChannelConnectionImpl.java:1604) [testng] at com.sun.corba.ee.impl.transport.SocketOrChannelConnectionImpl.doOptimizedReadStrategy(SocketOrChannelConnectionImpl.java:1501) [testng] ... 3 more [testng] Caused by: java.io.IOException: Connection reset by peer [testng] at sun.nio.ch.SocketDispatcher.read1(Native Method) [testng] at sun.nio.ch.SocketDispatcher.read(SocketDispatcher.java:46) [testng] at sun.nio.ch.IOUtil.read(IOUtil.java:255) [testng] at sun.nio.ch.SocketChannelImpl.read(SocketChannelImpl.java:243) [testng] at com.sun.corba.ee.impl.transport.SocketOrChannelConnectionImpl.blockingRead(SocketOrChannelConnectionImpl.java:1567) [testng] ... 4 more [testng] Dec 31, 2011 5:09:48 PM com.sun.dft.glassfish.ipc.api.CallbackClient getInstancesInCluster [testng] INFO: Invoking CallbackServer.getInstancesInCluster [testng] Dec 31, 2011 5:09:48 PM com.sun.dft.glassfish.ipc.api.CallbackClient findInInstanceServerLog [testng] INFO: Invoking CallbackServer.findInstanceWithMessageInLog [testng] Dec 31, 2011 5:09:48 PM com.sun.dft.glassfish.ipc.api.CallbackClient findInInstanceServerLog [testng] INFO: Invoking CallbackServer.findInstanceWithMessageInLog [testng] Dec 31, 2011 5:09:48 PM com.sun.dft.glassfish.ipc.api.CallbackClient findInInstanceServerLog [testng] INFO: Invoking CallbackServer.findInstanceWithMessageInLog [testng] Dec 31, 2011 5:09:48 PM com.sun.dft.glassfish.ipc.api.CallbackClient findInInstanceServerLog [testng] INFO: Invoking CallbackServer.findInstanceWithMessageInLog [testng] Dec 31, 2011 5:09:48 PM com.sun.dft.glassfish.ipc.api.CallbackClient findInInstanceServerLog [testng] INFO: Invoking CallbackServer.findInstanceWithMessageInLog [testng] Dec 31, 2011 5:09:48 PM com.sun.dft.glassfish.ipc.api.CallbackClient findInInstanceServerLog [testng] INFO: Invoking CallbackServer.findInstanceWithMessageInLog [testng] Dec 31, 2011 5:09:48 PM com.sun.dft.glassfish.ipc.api.CallbackClient findInInstanceServerLog [testng] INFO: Invoking CallbackServer.findInstanceWithMessageInLog [testng] Dec 31, 2011 5:09:48 PM com.sun.dft.glassfish.ipc.api.CallbackClient findInInstanceServerLog [testng] INFO: Invoking CallbackServer.findInstanceWithMessageInLog [testng] Dec 31, 2011 5:09:48 PM com.sun.dft.glassfish.ipc.api.CallbackClient findInInstanceServerLog [testng] INFO: Invoking CallbackServer.findInstanceWithMessageInLog [testng] Dec 31, 2011 5:09:48 PM com.sun.dft.glassfish.ipc.api.CallbackClient findInInstanceServerLog [testng] INFO: Invoking CallbackServer.findInstanceWithMessageInLog [testng] Dec 31, 2011 5:09:48 PM com.sun.dft.glassfish.ipc.api.CallbackClient findInInstanceServerLog [testng] INFO: Invoking CallbackServer.findInstanceWithMessageInLog [testng] Dec 31, 2011 5:09:48 PM com.sun.dft.glassfish.ipc.api.CallbackClient findInInstanceServerLog [testng] INFO: Invoking CallbackServer.findInstanceWithMessageInLog [testng] Dec 31, 2011 5:09:48 PM com.sun.dft.glassfish.ipc.api.CallbackClient findInInstanceServerLog [testng] INFO: Invoking CallbackServer.findInstanceWithMessageInLog [testng] Dec 31, 2011 5:09:48 PM com.sun.dft.glassfish.ipc.api.CallbackClient findInInstanceServerLog [testng] INFO: Invoking CallbackServer.findInstanceWithMessageInLog [testng] Dec 31, 2011 5:09:48 PM com.sun.dft.glassfish.ipc.api.CallbackClient findInInstanceServerLog [testng] INFO: Invoking CallbackServer.findInstanceWithMessageInLog [testng] Dec 31, 2011 5:09:48 PM com.sun.dft.glassfish.ipc.api.CallbackClient findInInstanceServerLog [testng] INFO: Invoking CallbackServer.findInstanceWithMessageInLog [testng] Dec 31, 2011 5:09:48 PM com.sun.dft.glassfish.ipc.api.CallbackClient findInInstanceServerLog [testng] INFO: Invoking CallbackServer.findInstanceWithMessageInLog [testng] Dec 31, 2011 5:09:48 PM com.sun.dft.glassfish.ipc.api.CallbackClient findInInstanceServerLog [testng] INFO: Invoking CallbackServer.findInstanceWithMessageInLog [testng] Dec 31, 2011 5:09:48 PM com.sun.dft.glassfish.ipc.api.CallbackClient findInInstanceServerLog [testng] INFO: Invoking CallbackServer.findInstanceWithMessageInLog [testng] Dec 31, 2011 5:09:48 PM com.sun.dft.glassfish.ipc.api.CallbackClient findInInstanceServerLog [testng] INFO: Invoking CallbackServer.findInstanceWithMessageInLog [testng] [testng] Dec 31, 2011 9:09:48 AM com.sun.dft.glassfish.config.resources.impl.DASImpl invokeCommandLineService [testng] INFO: Executing following command at Agent: agent1, Host: jed-asqe-24.us.oracle.com [testng] /space/gf-ha/glassfish3/bin/asadmin --user admin undeploy --target st-cluster MultiEJBApp [testng] Dec 31, 2011 9:09:50 AM com.sun.dft.glassfish.utils.Utility logCommandOutput [testng] INFO: Command Executed at agent machine agent1: /space/gf-ha/glassfish3/bin/asadmin --user admin undeploy --target st-cluster MultiEJBApp [testng] Output : WARNING: Instance instance104 seems to be offline; command disable was not replicated to that instance [testng] WARNING: Instance instance106 seems to be offline; command disable was not replicated to that instance [testng] WARNING: Instance instance104 seems to be offline; command undeploy was not replicated to that instance [testng] WARNING: Instance instance106 seems to be offline; command undeploy was not replicated to that instance [testng] Command undeploy completed with warnings. [testng] [testng] Dec 31, 2011 9:09:50 AM com.sun.dft.glassfish.config.resources.impl.DASImpl invokeCommandLineService [testng] INFO: Executing following command at Agent: agent1, Host: jed-asqe-24.us.oracle.com [testng] /space/gf-ha/glassfish3/bin/asadmin --user admin start-cluster st-cluster [testng] Dec 31, 2011 9:10:12 AM com.sun.dft.glassfish.utils.Utility logCommandOutput [testng] INFO: Command Executed at agent machine agent1: /space/gf-ha/glassfish3/bin/asadmin --user admin start-cluster st-cluster [testng] Output : Command start-cluster executed successfully. [testng] [testng] Dec 31, 2011 9:10:12 AM com.sun.dft.glassfish.report.ReportUtils unmarshall [testng] INFO: Unmarshalling report xml file - /root/.hudson/jobs/sherry-module/workspace/gf-ha-qe/results/iiop/com.sun.dft.glassfish.iiop.failover.ejb.IIOPFailoverEJBMultipleFO/xml/report.xml [testng] Dec 31, 2011 9:10:12 AM com.sun.dft.glassfish.report.ReportUtils marshall [testng] INFO: Marshalling report xml file - /root/.hudson/jobs/sherry-module/workspace/gf-ha-qe/results/iiop/com.sun.dft.glassfish.iiop.failover.ejb.IIOPFailoverEJBMultipleFO/xml/report.xml [testng] Dec 31, 2011 9:10:12 AM com.sun.dft.glassfish.helpers.LogsCollector collectAllLogs [testng] INFO: Report directory : /root/.hudson/jobs/sherry-module/workspace/gf-ha-qe/results/iiop/com.sun.dft.glassfish.iiop.failover.ejb.IIOPFailoverEJBMultipleFO/test1 [testng] Dec 31, 2011 9:10:12 AM com.sun.dft.glassfish.helpers.LogsCollector collectAllLogs [testng] INFO: Report directory : /root/.hudson/jobs/sherry-module/workspace/gf-ha-qe/results/iiop/com.sun.dft.glassfish.iiop.failover.ejb.IIOPFailoverEJBMultipleFO/test1/logs [testng] Dec 31, 2011 9:10:13 AM com.sun.dft.glassfish.helpers.LogsCollector collectResourceLogs [testng] INFO: Waiting for Log collection threads to complete [testng] Dec 31, 2011 9:10:14 AM com.sun.dft.glassfish.helpers.LogsCollector collectResourceLogs [testng] INFO: Waiting for Log collection threads to complete [testng] Dec 31, 2011 9:10:15 AM com.sun.dft.glassfish.helpers.LogsCollector collectResourceLogs [testng] INFO: Waiting for Log collection threads to complete [testng] Dec 31, 2011 9:10:15 AM com.sun.dft.proxy.impl.AgentServiceImpl uploadAgentLog [testng] INFO: Uploading remote file - /space/gf-ha/tools/dft-agent-runtime/felix/agent.log to local destination - logs/agent3 [testng] Dec 31, 2011 9:10:16 AM com.sun.dft.osgi.file_utility_service_impl.FileUtilityServiceImpl copyFile [testng] INFO: Copy file /space/gf-ha/controller-repository/logs/agent3/agent.log to file /root/.hudson/jobs/sherry-module/workspace/gf-ha-qe/results/iiop/com.sun.dft.glassfish.iiop.failover.ejb.IIOPFailoverEJBMultipleFO/test1/logs/agent3/agent.log [testng] Dec 31, 2011 9:10:16 AM com.sun.dft.proxy.impl.AgentServiceImpl uploadAgentLog [testng] INFO: Uploading remote file - /space/gf-ha/tools/dft-agent-runtime/felix/agent.log to local destination - logs/agent1 [testng] Dec 31, 2011 9:10:17 AM com.sun.dft.osgi.file_utility_service_impl.FileUtilityServiceImpl copyFile [testng] INFO: Copy file /space/gf-ha/controller-repository/logs/agent1/agent.log to file /root/.hudson/jobs/sherry-module/workspace/gf-ha-qe/results/iiop/com.sun.dft.glassfish.iiop.failover.ejb.IIOPFailoverEJBMultipleFO/test1/logs/agent1/agent.log [testng] Dec 31, 2011 9:10:17 AM com.sun.dft.proxy.impl.AgentServiceImpl uploadAgentLog [testng] INFO: Uploading remote file - /space/gf-ha/tools/dft-agent-runtime/felix/agent.log to local destination - logs/agent2 [testng] Dec 31, 2011 9:10:17 AM com.sun.dft.osgi.file_utility_service_impl.FileUtilityServiceImpl copyFile [testng] INFO: Copy file /space/gf-ha/controller-repository/logs/agent2/agent.log to file /root/.hudson/jobs/sherry-module/workspace/gf-ha-qe/results/iiop/com.sun.dft.glassfish.iiop.failover.ejb.IIOPFailoverEJBMultipleFO/test1/logs/agent2/agent.log [testng] Dec 31, 2011 9:10:17 AM com.sun.dft.osgi.file_utility_service_impl.FileUtilityServiceImpl copyFile [testng] INFO: Copy file /root/.hudson/jobs/sherry-module/workspace/gf-ha-qe/functional/iiop/framework.log to file /root/.hudson/jobs/sherry-module/workspace/gf-ha-qe/results/iiop/com.sun.dft.glassfish.iiop.failover.ejb.IIOPFailoverEJBMultipleFO/test1/logs/framework.log [testng] [testng] =============================================== [testng] iiop [testng] Total tests run: 1, Failures: 1, Skips: 0 [testng] =============================================== [testng] [testng] [org.testng.internal.PoolService] Shutting down poolservice org.testng.internal.PoolService@8c9b771 terminated:false