starting command: /usr/local/jdk1.7.0_17/bin/java -classpath dist/narayana-full-5.0.0.M4-SNAPSHOT/etc/:dist/narayana-full-5.0.0.M4-SNAPSHOT/lib/*:dist/narayana-full-5.0.0.M4-SNAPSHOT/lib/ext/*:dist/narayana-full-5.0.0.M4-SNAPSHOT/jacorb/lib/*:dist/narayana-full-5.0.0.M4-SNAPSHOT/jacorb/etc/:ext/fscontext.jar:ext/providerutil.jar:ext/jboss-profiler-jvmti.jar:ext/jboss-logging-spi.jar:tests/build/classes/:dbdrivers/selected_dbdriver/*:dbdrivers/DB2_v9.7/db2jcc.jar:dbdrivers/jConnect-6_0/classes/jconn3.jar:dbdrivers/mssql2005_sqljdbc_2.0/enu/sqljdbc4.jar:dbdrivers/mysql-connector-java-5.1.8-bin.jar:dbdrivers/oracle_10_2_0_4/ojdbc14.jar:dbdrivers/postgresql-8.3-605.jdbc4.jar:ext/netty.jar -Dqa.debug=true -Djava.naming.provider.url=file:///tmp -Djava.naming.factory.initial=com.sun.jndi.fscontext.RefFSContextFactory -Dperformanceprofilestore.dir=config/perf_profiles/ -Djdbcprofilestore.dir=config/jdbc_profiles -Dmemorytestprofilestore.dir=config/memory_profiles/ -Dots.server.bindname=value_1 -DCoordinatorEnvironmentBean.maintainHeuristics=NO -DRecoveryEnvironmentBean.recoveryBackoffPeriod=5 -DCoreEnvironmentBean.timeoutFactor=2 -DCoordinatorEnvironmentBean.defaultTimeout=240 -DCoordinatorEnvironmentBean.dynamic1PC=false -Demma.coverage.out.file=./testoutput/crashrecovery05_2/CrashRecovery05_2_Test028/client1-coverage.ec -DportOffsetId=4 -DObjectStoreBaseDir=/home/hudson/workspace/narayana/TESTS/QA_JTS_JACORB/jdk/jdk7.latest/label/linux/qa/testoutput/crashrecovery05_2/CrashRecovery05_2_Test028/client -DRecoveryEnvironmentBean.recoveryListener=true org.jboss.jbossts.qa.CrashRecovery05Clients2.Client03a value_3 value_4
2013-07-13 09:09:35,696 err: log4j:WARN No appenders could be found for logger (org.jboss.logging).
2013-07-13 09:09:35,696 err: log4j:WARN Please initialize the log4j system properly.
2013-07-13 09:09:35,778 err: SLF4J: Class path contains multiple SLF4J bindings.
2013-07-13 09:09:35,778 err: SLF4J: Found binding in [jar:file:/home/hudson/workspace/narayana/TESTS/QA_JTS_JACORB/jdk/jdk7.latest/label/linux/qa/dist/narayana-full-5.0.0.M4-SNAPSHOT/lib/ext/slf4j-simple.jar!/org/slf4j/impl/StaticLoggerBinder.class]
2013-07-13 09:09:35,779 err: SLF4J: Found binding in [jar:file:/home/hudson/workspace/narayana/TESTS/QA_JTS_JACORB/jdk/jdk7.latest/label/linux/qa/dist/narayana-full-5.0.0.M4-SNAPSHOT/jacorb/lib/slf4j-jdk14-1.5.6.jar!/org/slf4j/impl/StaticLoggerBinder.class]
2013-07-13 09:09:35,779 err: SLF4J: See http://www.slf4j.org/codes.html#multiple_bindings for an explanation.
2013-07-13 09:09:35,787 err: 5 [main] INFO jacorb.config - base configuration loaded from classpath orb.properties
2013-07-13 09:09:35,788 err: 5 [main] WARN jacorb.config - File e:/JacORB_CONFIG_FEATURE/etc/jacorb.properties for configuration jacorb not found
2013-07-13 09:09:35,794 err: 12 [main] INFO jacorb.config - configuration jacorb loaded from classpath
2013-07-13 09:09:35,827 err: 45 [main] INFO jacorb.orb.print_version -
2013-07-13 09:09:35,827 err:    ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
2013-07-13 09:09:35,827 err:    JacORB V 2.3.1 (JBoss patch01), www.jacorb.org
2013-07-13 09:09:35,827 err:    (C) The JacORB project 29-Jul-2009
2013-07-13 09:09:35,827 err:    ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
2013-07-13 09:09:35,969 err: 187 [main] INFO jacorb.orb - Property "jacorb.hashtable_class" is set to: java.util.Hashtable
2013-07-13 09:09:35,986 err: 204 [main] INFO jacorb.orb.interceptors - InterceptorManager started with 1 Server Interceptors, 1 Client Interceptors and 1 IOR Interceptors
2013-07-13 09:09:36,156 err: 374 [main] INFO jacorb.config - base configuration loaded from classpath orb.properties
2013-07-13 09:09:36,156 err: 374 [main] WARN jacorb.config - File e:/JacORB_CONFIG_FEATURE/etc/jacorb.properties for configuration jacorb not found
2013-07-13 09:09:36,157 err: 375 [main] INFO jacorb.config - configuration jacorb loaded from classpath
2013-07-13 09:09:36,158 err: 376 [main] INFO jacorb.orb.singleton - created ORBSingleton
2013-07-13 09:09:36,270 err: 488 [main] INFO jacorb.orb.giop - ClientConnectionManager: created new ClientGIOPConnection to 172.17.131.36:36476 (3ec35868)
2013-07-13 09:09:36,299 err: 517 [main] INFO jacorb.orb.iiop - Connected to 172.17.131.36:36476 from local port 43572
2013-07-13 09:09:36,378 err: 596 [main] INFO jacorb.orb.giop - ClientConnectionManager: created new ClientGIOPConnection to 172.17.131.36:40980 (62565cf8)
2013-07-13 09:09:36,384 err: 602 [main] INFO jacorb.orb.iiop - Connected to 172.17.131.36:40980 from local port 55512
2013-07-13 09:09:38,189 out: Gonna fail1
2013-07-13 09:09:38,190 out: Gonna fail2
2013-07-13 09:09:38,190 out: Gonna fail3
2013-07-13 09:09:38,190 out: Gonna fail4
2013-07-13 09:09:38,190 out: Using timeout delay factor of 2
2013-07-13 09:09:58,219 out: Gonna fail5: ResourceTraceNone
2013-07-13 09:09:58,219 out: Gonna fail6
2013-07-13 09:09:58,219 out: Failed
2013-07-13 09:09:58,220 err: 22438 [POADestructor] INFO jacorb.poa - POA RootPOA destroyed
2013-07-13 09:09:58,220 err: 22438 [main] INFO jacorb.orb - prepare ORB for shutdown...
2013-07-13 09:09:58,220 err: 22438 [main] INFO jacorb.orb - ORB going down...
2013-07-13 09:09:58,227 err: 22439 [main] INFO jacorb.orb.iiop - Client-side TCP transport to 172.17.131.36:36476 closed.
2013-07-13 09:09:58,227 err: 22439 [main] INFO jacorb.orb.iiop - Client-side TCP transport to 172.17.131.36:40980 closed.
2013-07-13 09:09:58,227 err: 22439 [main] INFO jacorb.orb - ORB shutdown complete
2013-07-13 09:09:58,227 err: 22445 [ClientMessageReceptor0] INFO jacorb.util.tpool - ConsumerTie exited
2013-07-13 09:09:58,227 err: 22445 [ClientMessageReceptor1] INFO jacorb.util.tpool - ConsumerTie exited
2013-07-13 09:09:58,227 err: 22445 [ServerSocketListener] INFO jacorb.orb.iiop - Listener exited