Attachment 876376 Details for Bug 1078297 – client0_output.txt

client0_output.txt

client0_output.txt (text/plain), 11.22 KB, created by Ondrej Chaloupka on 2014-03-19 14:48:51 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Ondrej Chaloupka

Created: 2014-03-19 14:48:51 UTC

Size: 11.22 KB

patch

obsolete

>Task [client0 0]: starting command: starting command: java -classpath dist/jbossts-full-4.17.19.Final-SNAPSHOT/etc/:dist/jbossts-full-4.17.19.Final-SNAPSHOT/lib/*:dist/jbossts-full-4.17.19.Final-SNAPSHOT/lib/ext/*:dist/jbossts-full-4.17.19.Final-SNAPSHOT/jacorb/lib/*:dist/jbossts-full-4.17.19.Final-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=1 -DCoordinatorEnvironmentBean.defaultTimeout=120 -Demma.coverage.out.file=./testoutput/crashrecovery12/CrashRecovery12_Test02/client0-coverage.ec -DportOffsetId=2 -DObjectStoreBaseDir=/home/ochaloup/Transactions/narayana/qa/testoutput/crashrecovery12/CrashRecovery12_Test02/client0 -DRecoveryEnvironmentBean.recoveryListener=true -DObjectStoreEnvironmentBean.objectStoreType=com.arjuna.ats.internal.arjuna.objectstore.jdbc.JDBCStore -DObjectStoreEnvironmentBean.communicationStore.tablePrefix=Communication -DObjectStoreEnvironmentBean.communicationStore.objectStoreType=com.arjuna.ats.internal.arjuna.objectstore.jdbc.JDBCStore -DObjectStoreEnvironmentBean.stateStore.tablePrefix=stateStore -DObjectStoreEnvironmentBean.stateStore.objectStoreType=com.arjuna.ats.internal.arjuna.objectstore.jdbc.JDBCStore -DObjectStoreEnvironmentBean.tablePrefix=Action -DObjectStoreEnvironmentBean.jdbcAccess=com.arjuna.ats.internal.arjuna.objectstore.jdbc.accessors.DynamicDataSourceJDBCAccess;ClassName=oracle.jdbc.pool.OracleDataSource;DatabaseName=qaora12;User=dballo08;Password=dballo08;ServerName=dev151.mw.lab.eng.bos.redhat.com;PortNumber=1521;NetworkProtocol=tcp;DriverType=thin -DObjectStoreEnvironmentBean.stateStore.jdbcAccess=com.arjuna.ats.internal.arjuna.objectstore.jdbc.accessors.DynamicDataSourceJDBCAccess;ClassName=oracle.jdbc.pool.OracleDataSource;DatabaseName=qaora12;User=dballo08;Password=dballo08;ServerName=dev151.mw.lab.eng.bos.redhat.com;PortNumber=1521;NetworkProtocol=tcp;DriverType=thin -DObjectStoreEnvironmentBean.communicationStore.jdbcAccess=com.arjuna.ats.internal.arjuna.objectstore.jdbc.accessors.DynamicDataSourceJDBCAccess;ClassName=oracle.jdbc.pool.OracleDataSource;DatabaseName=qaora12;User=dballo08;Password=dballo08;ServerName=dev151.mw.lab.eng.bos.redhat.com;PortNumber=1521;NetworkProtocol=tcp;DriverType=thin org.jboss.jbossts.qa.CrashRecovery12Clients.Client01 prepare CR12_02.log 
>2014-03-19 14:24:12,045 err: log4j:WARN No appenders could be found for logger (org.jboss.logging).
>2014-03-19 14:24:12,046 err: log4j:WARN Please initialize the log4j system properly.
>2014-03-19 14:24:12,102 err: Mar 19, 2014 2:24:12 PM org.jacorb.config.JacORBConfiguration init
>2014-03-19 14:24:12,102 err: INFO: base configuration loaded from classpath orb.properties
>2014-03-19 14:24:12,103 err: Mar 19, 2014 2:24:12 PM org.jacorb.config.JacORBConfiguration init
>2014-03-19 14:24:12,103 err: WARNING: File e:/JacORB_CONFIG_FEATURE/etc/jacorb.properties for configuration jacorb not found
>2014-03-19 14:24:12,105 err: Mar 19, 2014 2:24:12 PM org.jacorb.config.JacORBConfiguration init
>2014-03-19 14:24:12,105 err: INFO: configuration jacorb loaded from classpath
>2014-03-19 14:24:12,112 err: 2014-03-19 14:24:12.112 FINE jacorb.interop.strict_check_on_tc_creation set to true
>2014-03-19 14:24:12,112 err: 2014-03-19 14:24:12.112 INFO 
>2014-03-19 14:24:12,112 err: 	~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
>2014-03-19 14:24:12,112 err: 	JacORB V 2.3.1 (JBoss patch01), www.jacorb.org
>2014-03-19 14:24:12,112 err: 	(C) The JacORB project 29-Jul-2009
>2014-03-19 14:24:12,112 err: 	~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
>2014-03-19 14:24:12,115 err: 2014-03-19 14:24:12.115 FINE RequestProcessorPoolFactory settings: thread_pool_min=5 thread_pool_max=20 thread_pool_shared=false
>2014-03-19 14:24:12,117 err: 2014-03-19 14:24:12.117 FINE added ORBInitializer: org.jacorb.orb.standardInterceptors.IORInterceptorInitializer
>2014-03-19 14:24:12,124 err: 2014-03-19 14:24:12.123 FINE added ORBInitializer: com.arjuna.ats.jts.orbspecific.jacorb.interceptors.interposition.InterpositionORBInitializerImpl
>2014-03-19 14:24:12,134 err: 2014-03-19 14:24:12.134 FINE defaulting to DefaultSocketFactory
>2014-03-19 14:24:12,140 err: 2014-03-19 14:24:12.139 FINE Maximum connection threads: 1000
>2014-03-19 14:24:12,140 err: 2014-03-19 14:24:12.140 FINE Maximum idle threads: 5
>2014-03-19 14:24:12,143 err: 2014-03-19 14:24:12.143 INFO Property "jacorb.hashtable_class" is set to: java.util.Hashtable
>2014-03-19 14:24:12,150 err: 2014-03-19 14:24:12.150 FINE TCS set to UTF8
>2014-03-19 14:24:12,154 err: 2014-03-19 14:24:12.154 INFO InterceptorManager started with 1 Server Interceptors, 1 Client Interceptors and 1 IOR Interceptors
>2014-03-19 14:24:12,184 err: 2014-03-19 14:24:12.184 FINE POA RootPOA ready
>2014-03-19 14:24:12,185 err: 2014-03-19 14:24:12.184 FINE Maximum connection threads: 1000
>2014-03-19 14:24:12,185 err: 2014-03-19 14:24:12.185 FINE Maximum idle threads: 5
>2014-03-19 14:24:12,187 err: 2014-03-19 14:24:12.186 FINE waiting for queue
>2014-03-19 14:24:12,196 err: 2014-03-19 14:24:12.196 FINE created org.omg.ETF.Factories: org.jacorb.orb.iiop.IIOPFactories
>2014-03-19 14:24:12,203 err: 2014-03-19 14:24:12.203 FINE created ServerSocketFactory: org.jacorb.orb.factory.DefaultServerSocketFactory
>2014-03-19 14:24:12,209 err: 2014-03-19 14:24:12.208 FINE Created socket listener on 0.0.0.0/0.0.0.0:49561
>2014-03-19 14:24:12,217 out: Ready
>2014-03-19 14:24:12,217 err: 2014-03-19 14:24:12.217 FINE reset a previous completion call
>2014-03-19 14:24:12,249 err: 2014-03-19 14:24:12.248 INFO oid: 
>2014-03-19 14:24:12,249 err: 00 18 17 20 28 43 18 15 01 02 07                            ... (C.....
>2014-03-19 14:24:12,249 err: object is activated
>2014-03-19 14:24:12,252 err: 2014-03-19 14:24:12.252 INFO Using server ID (8330747461) for transient POA
>2014-03-19 14:24:12,278 err: 2014-03-19 14:24:12.278 INFO base configuration loaded from classpath orb.properties
>2014-03-19 14:24:12,278 err: 2014-03-19 14:24:12.278 WARNING File e:/JacORB_CONFIG_FEATURE/etc/jacorb.properties for configuration jacorb not found
>2014-03-19 14:24:12,280 err: 2014-03-19 14:24:12.280 INFO configuration jacorb loaded from classpath
>2014-03-19 14:24:12,281 err: 2014-03-19 14:24:12.280 FINE jacorb.interop.strict_check_on_tc_creation set to true
>2014-03-19 14:24:12,281 err: 2014-03-19 14:24:12.281 INFO created ORBSingleton
>2014-03-19 14:24:19,555 err: 2014-03-19 14:24:19.554 FINE Located IOR:000000000000002B49444C3A61726A756E612E636F6D2F41726A756E614F54532F41726A756E61466163746F72793A312E30000000000001000000000000006C000102000000000A3132372E302E302E3100C19900000016383333303734373436312F00181720284318150102070000000000020000000000000008000000004A41430000000001000000200000000005010001000000010001000100010109000000020501000100010100 on local POA; assuming local.
>2014-03-19 14:24:19,565 err: 2014-03-19 14:24:19.565 INFO ClientConnectionManager: created new ClientGIOPConnection to 127.0.0.1:49561 (3d89aba6)
>2014-03-19 14:24:19,565 err: 2014-03-19 14:24:19.565 FINE [0/0] creating new thread
>2014-03-19 14:24:19,568 err: 2014-03-19 14:24:19.567 FINE [0/1] removed idle thread (job scheduled)
>2014-03-19 14:24:19,568 err: 2014-03-19 14:24:19.568 FINE ClientGIOPConnection to 127.0.0.1:49561 (3d89aba6): will wait until connected
>2014-03-19 14:24:22,080 err: Resource01(1, CR12_02.log)
>2014-03-19 14:24:22,082 err: setTransactionTimeout(seconds)
>2014-03-19 14:24:22,084 err: start(< 131072, 29, 36, 0000000000-1-112700100-55-648341-102124000349, 2929292929292929292928281562929302929-26-3511270-73153292929327829292929292929 >,0)
>2014-03-19 14:24:22,084 err: 2014-03-19 14:24:22.084 INFO oid: 
>2014-03-19 14:24:22,084 err: 01 18 17 20 28 43 18 15 01 02 07                            ... (C.....
>2014-03-19 14:24:22,085 err: object is activated
>2014-03-19 14:24:22,085 err: 2014-03-19 14:24:22.085 FINE Located IOR:000000000000002F49444C3A61726A756E612E636F6D2F41726A756E614F54532F4F545341627374726163745265636F72643A312E30000000000001000000000000006C000102000000000A3132372E302E302E3100C19900000016383333303734373436312F01181720284318150102070000000000020000000000000008000000004A41430000000001000000200000000005010001000000010001000100010109000000020501000100010100 on local POA; assuming local.
>2014-03-19 14:24:22,086 err: 2014-03-19 14:24:22.086 INFO ClientConnectionManager: found ClientGIOPConnection to 127.0.0.1:49561 (3d89aba6)
>2014-03-19 14:24:24,338 out: 2014-03-19 14:24:24,338 [main] WARN  com.arjuna.ats.jts - ARJUNA022079: RCManager could not find file in object store during setup.
>2014-03-19 14:24:24,341 out: 2014-03-19 14:24:24,340 [main] WARN  com.arjuna.ats.jts - ARJUNA022078: RCManager could not find file in object store.
>2014-03-19 14:24:24,344 out: 2014-03-19 14:24:24,342 [main] WARN  com.arjuna.ats.jts - ARJUNA022026: Creation of RecoveryCoordinator for 0:ffff7f000001:c9c0:53299a7c:3 threw exception
>2014-03-19 14:24:24,344 out: org.omg.CORBA.BAD_OPERATION: RecoveryCoordinator ARJUNA022027: not created!  vmcid: 0x0  minor code: 0  completed: No
>2014-03-19 14:24:24,344 out: 	at com.arjuna.ats.internal.jts.orbspecific.coordinator.ArjunaTransactionImple.register_resource(ArjunaTransactionImple.java:865)
>2014-03-19 14:24:24,344 out: 	at com.arjuna.ats.internal.jts.ControlWrapper.register_resource(ControlWrapper.java:354)
>2014-03-19 14:24:24,344 err: end(< 131072, 29, 36, 0000000000-1-112700100-55-648341-102124000349, 2929292929292929292928281562929302929-26-3511270-73153292929327829292929292929 >, 536870912)
>2014-03-19 14:24:24,344 out: 	at com.arjuna.ats.jts.extensions.AtomicTransaction.registerResource(AtomicTransaction.java:502)
>2014-03-19 14:24:24,345 out: 	at com.arjuna.ats.internal.jta.transaction.jts.TransactionImple.enlistResource(TransactionImple.java:729)
>2014-03-19 14:24:24,345 out: 	at com.arjuna.ats.internal.jta.transaction.jts.TransactionImple.enlistResource(TransactionImple.java:498)
>2014-03-19 14:24:24,345 err: rollback(< 131072, 29, 36, 0000000000-1-112700100-55-648341-102124000349, 2929292929292929292928281562929302929-26-3511270-73153292929327829292929292929 >)
>2014-03-19 14:24:24,345 out: 	at org.jboss.jbossts.qa.CrashRecovery12Clients.Client01.main(Client01.java:98)
>2014-03-19 14:24:24,346 err: 2014-03-19 14:24:24.346 FINE 
>2014-03-19 14:24:24,346 err: 01 18 17 20 28 43 18 15 01 02 07                            ... (C.....
>2014-03-19 14:24:24,346 err: all active processors for this object have finished
>2014-03-19 14:24:24,346 err: 2014-03-19 14:24:24.346 INFO oid: 
>2014-03-19 14:24:24,347 err: 01 18 17 20 28 43 18 15 01 02 07                            ... (C.....
>2014-03-19 14:24:24,347 err: object is deactivated
>2014-03-19 14:24:24,347 err: Resource02()
>2014-03-19 14:24:24,347 out: Passed

Actions: View

Attachments on bug 1078297: 876372 | 876376 | 876377 | 876379 | 876380 | 876382