Issue Details (XML | Word | Printable)

Key: GLASSFISH-9133
Type: Improvement Improvement
Status: Open Open
Priority: Major Major
Assignee: marina vatkina
Reporter: nachi_glassfish
Votes: 0
Watchers: 0
Operations

If you were logged in you would be able to see more operations.
glassfish

Manual Recover-Transactions after this server instance crash is not supported

Created: 14/Aug/09 02:57 PM   Updated: 29/Sep/10 11:27 AM
Component/s: jts
Affects Version/s: v2.1.1
Fix Version/s: future release

Time Tracking:
Not Specified

Environment:

Operating System: Solaris
Platform: Sun


Issuezilla Id: 9,133
Tags:
Participants: marina vatkina and nachi_glassfish


 Description  « Hide

I have a setup of DAS server connected to two databases (Java DB). I have
deployed an application which connects to both the databases to update tables in
a user transaction. I have used FailureInducer to induce a wait of 60 seconds in
my application during the commit phase. When the transaction waits in the commit
phase (ie, Prepared State), i bring down the appserver using "kill -9" unix
command. Then i start the application server with automatic-recovery configured
as false. But from the log i can observe that during the application server
start-up it is invoking RecoveryManager.recover method.

The transaction recovery does not happen after the application server is
started. Then i executed the command asadmin recover-transactions command. The
command output is given below

./asadmin recover-transactions server
Command recover-transactions executed successfully.

Though it says the command has executed successfully, the transaction recovery
does not happen. The tables are still locked in the database after the execution
of the command. When i do a select query on the table in any one of the two
databases, i am getting the following error message

Error code -1, SQL state 40XL1: A lock could not be obtained within the time
requested
Line 1, column 1

Execution finished after 0 s, 1 error(s) occurred.

I have provided the server log at Finest level for transaction as shown below

[#|2009-08-14T14:54:05.271-0700|INFO|sun-appserver2.1|javax.enterprise.system.core|_ThreadID=16;_ThreadName=httpWorkerThread-4848-0;transactionapp;|CORE5022:
All ejb(s) of [transactionapp] were unloaded successfully!|#]

[#|2009-08-14T14:54:05.311-0700|INFO|sun-appserver2.1|javax.enterprise.system.tools.admin|_ThreadID=17;_ThreadName=httpWorkerThread-4848-1;/var/tmp/s1astempdomain1server416256446/transactionapp.ear;|ADM1064:The
upload file at [/var/tmp/s1astempdomain1server416256446/transactionapp.ear]
exists and will be overwritten.|#]

[#|2009-08-14T14:54:05.312-0700|INFO|sun-appserver2.1|javax.enterprise.system.tools.admin|_ThreadID=17;_ThreadName=httpWorkerThread-4848-1;/var/tmp/s1astempdomain1server416256446/transactionapp.ear;|ADM1006:Uploading
the file to:[/var/tmp/s1astempdomain1server416256446/transactionapp.ear]|#]

[#|2009-08-14T14:54:05.975-0700|INFO|sun-appserver2.1|javax.enterprise.system.tools.deployment|_ThreadID=20;_ThreadName=Thread-3484;|deployed
with moduleid = transactionapp|#]

[#|2009-08-14T14:54:06.180-0700|INFO|sun-appserver2.1|javax.enterprise.system.core.classloading|_ThreadID=16;_ThreadName=httpWorkerThread-4848-0;transactionapp;|LDR5010:
All ejb(s) of [transactionapp] loaded successfully!|#]

[#|2009-08-14T14:54:06.572-0700|INFO|sun-appserver2.1|javax.enterprise.system.stream.out|_ThreadID=21;_ThreadName=httpSSLWorkerThread-8080-1;|
Creating tables|#]

[#|2009-08-14T14:54:06.576-0700|INFO|sun-appserver2.1|javax.enterprise.system.stream.out|_ThreadID=21;_ThreadName=httpSSLWorkerThread-8080-1;|
tx866128|#]

[#|2009-08-14T14:54:06.577-0700|INFO|sun-appserver2.1|javax.enterprise.system.stream.out|_ThreadID=21;_ThreadName=httpSSLWorkerThread-8080-1;|
Executing create table using query :create table tx866128 (Account VARCHAR
(50),bal INTEGER )|#]

[#|2009-08-14T14:54:06.614-0700|INFO|sun-appserver2.1|javax.enterprise.system.stream.out|_ThreadID=21;_ThreadName=httpSSLWorkerThread-8080-1;|

  • Executed create Table |#]

[#|2009-08-14T14:54:06.619-0700|INFO|sun-appserver2.1|javax.enterprise.system.stream.out|_ThreadID=21;_ThreadName=httpSSLWorkerThread-8080-1;|
Executing create table using query :create table tx866128 (name VARCHAR (50),bal
INTEGER )|#]

[#|2009-08-14T14:54:06.677-0700|INFO|sun-appserver2.1|javax.enterprise.system.stream.out|_ThreadID=21;_ThreadName=httpSSLWorkerThread-8080-1;|

  • Executed create Table |#]

[#|2009-08-14T14:54:06.677-0700|INFO|sun-appserver2.1|javax.enterprise.system.stream.out|_ThreadID=21;_ThreadName=httpSSLWorkerThread-8080-1;|
Creating TxBean for Commit...|#]

[#|2009-08-14T14:54:06.711-0700|INFO|sun-appserver2.1|javax.enterprise.system.stream.out|_ThreadID=21;_ThreadName=httpSSLWorkerThread-8080-1;|
Getting the User Transaction|#]

[#|2009-08-14T14:54:06.712-0700|INFO|sun-appserver2.1|javax.enterprise.system.stream.out|_ThreadID=21;_ThreadName=httpSSLWorkerThread-8080-1;|
Beginning the Tx|#]

[#|2009-08-14T14:54:06.712-0700|INFO|sun-appserver2.1|javax.enterprise.system.stream.out|_ThreadID=21;_ThreadName=httpSSLWorkerThread-8080-1;|
tx begin...|#]

[#|2009-08-14T14:54:06.712-0700|INFO|sun-appserver2.1|javax.enterprise.system.stream.out|_ThreadID=21;_ThreadName=httpSSLWorkerThread-8080-1;|
Inserting the data|#]

[#|2009-08-14T14:54:06.712-0700|INFO|sun-appserver2.1|javax.enterprise.system.stream.out|_ThreadID=21;_ThreadName=httpSSLWorkerThread-8080-1;|
insert in TxBean|#]

[#|2009-08-14T14:54:06.712-0700|FINEST|sun-appserver2.1|javax.enterprise.system.core.transaction|_ThreadID=21;_ThreadName=httpSSLWorkerThread-8080-1;ClassName=CurrentImpl;MethodName=begin();_RequestID=bec4fc9e-9613-46ee-a65c-aa67c0ca4a41;|Before
invoking create() on TxFactory|#]

[#|2009-08-14T14:54:06.713-0700|FINE|sun-appserver2.1|javax.enterprise.system.core.transaction|_ThreadID=21;_ThreadName=httpSSLWorkerThread-8080-1;ClassName=TransactionFactoryImpl;MethodName=localCreate();_RequestID=bec4fc9e-9613-46ee-a65c-aa67c0ca4a41;|Control
object :com.sun.jts.CosTransactions.ControlImpl@1d344e7 corresponding to this
transaction has been createdGTID is :
0300000000DDBF1A676C617373666973682D7838362D312C7365727665722C5033373030|#]

[#|2009-08-14T14:54:06.713-0700|FINEST|sun-appserver2.1|javax.enterprise.system.core.transaction|_ThreadID=21;_ThreadName=httpSSLWorkerThread-8080-1;ClassName=CurrentImpl;MethodName=begin();_RequestID=bec4fc9e-9613-46ee-a65c-aa67c0ca4a41;|Before
invoking CurrentTransaction.setCurrent(control,true)|#]

[#|2009-08-14T14:54:06.713-0700|FINER|sun-appserver2.1|javax.enterprise.system.core.transaction|_ThreadID=21;_ThreadName=httpSSLWorkerThread-8080-1;ClassName=TopCoordinator;MethodName=register_synchronization();_RequestID=bec4fc9e-9613-46ee-a65c-aa67c0ca4a41;|SynchronizationImpl
:com.sun.jts.jta.SynchronizationImpl@150a3dc has been registeredwith
TopCoordinator :GTID is :
0300000000DDBF1A676C617373666973682D7838362D312C7365727665722C5033373030|#]

[#|2009-08-14T14:54:06.713-0700|FINEST|sun-appserver2.1|javax.enterprise.system.core.transaction|_ThreadID=21;_ThreadName=httpSSLWorkerThread-8080-1;ClassName=TopCoordinator;MethodName=register_resource();_RequestID=bec4fc9e-9613-46ee-a65c-aa67c0ca4a41;|OTSResource
OTSResource : XAResource com.sun.gjc.spi.XAResourceImpl@135ca6c XID {XID: formatID(4871251), gtrid_length(36), bqual_length(30), data(03000000 00DDBF1A 676C6173 73666973 682D7838 362D312C 73657276 65722C50 33373030 676C6173 73666973 682D7838 362D312C 73657276 65722C50 33373030 2C00)} has been registeredGTID
is:0300000000DDBF1A676C617373666973682D7838362D312C7365727665722C5033373030|#]

[#|2009-08-14T14:54:06.714-0700|INFO|sun-appserver2.1|javax.enterprise.system.stream.out|_ThreadID=21;_ThreadName=httpSSLWorkerThread-8080-1;|
Got DB Connection Successfully...|#]

[#|2009-08-14T14:54:06.715-0700|FINEST|sun-appserver2.1|javax.enterprise.system.core.transaction|_ThreadID=21;_ThreadName=httpSSLWorkerThread-8080-1;ClassName=TopCoordinator;MethodName=register_resource();_RequestID=bec4fc9e-9613-46ee-a65c-aa67c0ca4a41;|OTSResource
OTSResource : XAResource com.sun.gjc.spi.XAResourceImpl@4ee7c0 XID {XID: formatID(4871251), gtrid_length(36), bqual_length(30), data(03000000 00DDBF1A 676C6173 73666973 682D7838 362D312C 73657276 65722C50 33373030 676C6173 73666973 682D7838 362D312C 73657276 65722C50 33373030 2C01)} has been registeredGTID
is:0300000000DDBF1A676C617373666973682D7838362D312C7365727665722C5033373030|#]

[#|2009-08-14T14:54:06.719-0700|INFO|sun-appserver2.1|javax.enterprise.system.stream.out|_ThreadID=21;_ThreadName=httpSSLWorkerThread-8080-1;|
Got DB Connection Successfully...|#]

[#|2009-08-14T14:54:06.763-0700|INFO|sun-appserver2.1|javax.enterprise.system.stream.out|_ThreadID=21;_ThreadName=httpSSLWorkerThread-8080-1;|
Account added Successfully...|#]

[#|2009-08-14T14:54:06.763-0700|INFO|sun-appserver2.1|javax.enterprise.system.stream.out|_ThreadID=21;_ThreadName=httpSSLWorkerThread-8080-1;|
before committing the tx sleeping for 20 Secs...|#]

[#|2009-08-14T14:54:06.764-0700|FINE|sun-appserver2.1|javax.enterprise.system.core.transaction|_ThreadID=21;_ThreadName=httpSSLWorkerThread-8080-1;ClassName=com.sun.jts.pi.InterceptorImpl;MethodName=send_request;_RequestID=bec4fc9e-9613-46ee-a65c-aa67c0ca4a41;|
sending_request[42] : _is_a, ThreadName :
Thread[httpSSLWorkerThread-8080-1,10,Grizzly]|#]

[#|2009-08-14T14:54:06.765-0700|FINE|sun-appserver2.1|javax.enterprise.system.core.transaction|_ThreadID=21;_ThreadName=httpSSLWorkerThread-8080-1;ClassName=com.sun.jts.pi.InterceptorImpl;MethodName=receive_request_service_contexts;_RequestID=bec4fc9e-9613-46ee-a65c-aa67c0ca4a41;|
received_request[42] : _is_a, ThreadName :
Thread[httpSSLWorkerThread-8080-1,10,Grizzly]|#]

[#|2009-08-14T14:54:06.767-0700|FINE|sun-appserver2.1|javax.enterprise.system.core.transaction|_ThreadID=21;_ThreadName=httpSSLWorkerThread-8080-1;ClassName=com.sun.jts.pi.InterceptorImpl;MethodName=send_request;_RequestID=bec4fc9e-9613-46ee-a65c-aa67c0ca4a41;|
sending_request[43] : getGlobalTID, ThreadName :
Thread[httpSSLWorkerThread-8080-1,10,Grizzly]|#]

[#|2009-08-14T14:54:06.768-0700|FINE|sun-appserver2.1|javax.enterprise.system.core.transaction|_ThreadID=21;_ThreadName=httpSSLWorkerThread-8080-1;ClassName=com.sun.jts.pi.InterceptorImpl;MethodName=receive_request_service_contexts;_RequestID=bec4fc9e-9613-46ee-a65c-aa67c0ca4a41;|
received_request[43] : getGlobalTID, ThreadName :
Thread[httpSSLWorkerThread-8080-1,10,Grizzly]|#]

[#|2009-08-14T14:54:06.771-0700|INFO|sun-appserver2.1|javax.enterprise.system.stream.out|_ThreadID=21;_ThreadName=httpSSLWorkerThread-8080-1;|
before commit ...|#]

[#|2009-08-14T14:54:06.771-0700|FINEST|sun-appserver2.1|javax.enterprise.system.core.transaction|_ThreadID=21;_ThreadName=httpSSLWorkerThread-8080-1;ClassName=RegisterdSyncs;MethodName=distributeBefore();_RequestID=bec4fc9e-9613-46ee-a65c-aa67c0ca4a41;|Before
invoking before_completion() on synchronization object
com.sun.jts.jta.SynchronizationImpl@150a3dc|#]

[#|2009-08-14T14:54:06.775-0700|FINEST|sun-appserver2.1|javax.enterprise.system.core.transaction|_ThreadID=21;_ThreadName=httpSSLWorkerThread-8080-1;ClassName=RegisterdSyncs;MethodName=distributeBefore();_RequestID=bec4fc9e-9613-46ee-a65c-aa67c0ca4a41;|After
invoking before_completion() on synchronization object
com.sun.jts.jta.SynchronizationImpl@150a3dc|#]

[#|2009-08-14T14:54:06.775-0700|FINEST|sun-appserver2.1|javax.enterprise.system.core.transaction|_ThreadID=21;_ThreadName=httpSSLWorkerThread-8080-1;ClassName=TransactionState;MethodName=setState();_RequestID=bec4fc9e-9613-46ee-a65c-aa67c0ca4a41;|Acquiring
read lock on freeze : state PREPARING|#]

[#|2009-08-14T14:54:06.775-0700|FINEST|sun-appserver2.1|javax.enterprise.system.core.transaction|_ThreadID=21;_ThreadName=httpSSLWorkerThread-8080-1;ClassName=TransactionState;MethodName=setState();_RequestID=bec4fc9e-9613-46ee-a65c-aa67c0ca4a41;|Acquired
read lock on freeze : state PREPARING|#]

[#|2009-08-14T14:54:06.775-0700|WARNING|sun-appserver2.1|javax.enterprise.system.core.transaction|_ThreadID=21;_ThreadName=httpSSLWorkerThread-8080-1;0;_RequestID=bec4fc9e-9613-46ee-a65c-aa67c0ca4a41;|JTS5057:
FailPoint : [0]|#]

[#|2009-08-14T14:54:06.775-0700|WARNING|sun-appserver2.1|javax.enterprise.system.core.transaction|_ThreadID=21;_ThreadName=httpSSLWorkerThread-8080-1;_RequestID=bec4fc9e-9613-46ee-a65c-aa67c0ca4a41;|JTS5057:
FailPoint : [null]|#]

[#|2009-08-14T14:54:06.776-0700|FINER|sun-appserver2.1|javax.enterprise.system.core.transaction|_ThreadID=21;_ThreadName=httpSSLWorkerThread-8080-1;ClassName=RegisteredResources;MethodName=prepare();_RequestID=bec4fc9e-9613-46ee-a65c-aa67c0ca4a41;|Before
invoking prepare() on resource:OTSResource : XAResource
com.sun.gjc.spi.XAResourceImpl@135ca6c XID {XID: formatID(4871251), gtrid_length(36), bqual_length(30), data(03000000 00DDBF1A 676C6173 73666973 682D7838 362D312C 73657276 65722C50 33373030 676C6173 73666973 682D7838 362D312C 73657276 65722C50 33373030 2C00)}|#]

[#|2009-08-14T14:54:06.780-0700|FINER|sun-appserver2.1|javax.enterprise.system.core.transaction|_ThreadID=21;_ThreadName=httpSSLWorkerThread-8080-1;ClassName=RegisteredResources;MethodName=prepare();_RequestID=bec4fc9e-9613-46ee-a65c-aa67c0ca4a41;|After
invoking prepare() on resource:OTSResource : XAResource
com.sun.gjc.spi.XAResourceImpl@135ca6c XID {XID: formatID(4871251), gtrid_length(36), bqual_length(30), data(03000000 00DDBF1A 676C6173 73666973 682D7838 362D312C 73657276 65722C50 33373030 676C6173 73666973 682D7838 362D312C 73657276 65722C50 33373030 2C00)};This resource voted :
org.omg.CosTransactions.Vote@17750ef|#]

[#|2009-08-14T14:54:06.780-0700|FINER|sun-appserver2.1|javax.enterprise.system.core.transaction|_ThreadID=21;_ThreadName=httpSSLWorkerThread-8080-1;ClassName=RegisteredResources;MethodName=prepare();_RequestID=bec4fc9e-9613-46ee-a65c-aa67c0ca4a41;|Before
invoking prepare() on resource:OTSResource : XAResource
com.sun.gjc.spi.XAResourceImpl@4ee7c0 XID {XID: formatID(4871251), gtrid_length(36), bqual_length(30), data(03000000 00DDBF1A 676C6173 73666973 682D7838 362D312C 73657276 65722C50 33373030 676C6173 73666973 682D7838 362D312C 73657276 65722C50 33373030 2C01)}|#]

[#|2009-08-14T14:54:06.788-0700|FINER|sun-appserver2.1|javax.enterprise.system.core.transaction|_ThreadID=21;_ThreadName=httpSSLWorkerThread-8080-1;ClassName=RegisteredResources;MethodName=prepare();_RequestID=bec4fc9e-9613-46ee-a65c-aa67c0ca4a41;|After
invoking prepare() on resource:OTSResource : XAResource
com.sun.gjc.spi.XAResourceImpl@4ee7c0 XID {XID: formatID(4871251), gtrid_length(36), bqual_length(30), data(03000000 00DDBF1A 676C6173 73666973 682D7838 362D312C 73657276 65722C50 33373030 676C6173 73666973 682D7838 362D312C 73657276 65722C50 33373030 2C01)};This resource voted :
org.omg.CosTransactions.Vote@17750ef|#]

[#|2009-08-14T14:54:06.788-0700|FINEST|sun-appserver2.1|javax.enterprise.system.core.transaction|_ThreadID=21;_ThreadName=httpSSLWorkerThread-8080-1;ClassName=TransactionState;MethodName=setState();_RequestID=bec4fc9e-9613-46ee-a65c-aa67c0ca4a41;|Releasing
read lock on freeze : state Illegal state |#]

[#|2009-08-14T14:54:06.788-0700|FINEST|sun-appserver2.1|javax.enterprise.system.core.transaction|_ThreadID=21;_ThreadName=httpSSLWorkerThread-8080-1;ClassName=TransactionState;MethodName=setState();_RequestID=bec4fc9e-9613-46ee-a65c-aa67c0ca4a41;|Released
read lock on freeze|#]

[#|2009-08-14T14:54:06.788-0700|FINEST|sun-appserver2.1|javax.enterprise.system.core.transaction|_ThreadID=21;_ThreadName=httpSSLWorkerThread-8080-1;ClassName=TransactionState;MethodName=setState();_RequestID=bec4fc9e-9613-46ee-a65c-aa67c0ca4a41;|Released
read lock on freeze : state Illegal state |#]

[#|2009-08-14T14:54:06.789-0700|WARNING|sun-appserver2.1|javax.enterprise.system.core.transaction|_ThreadID=21;_ThreadName=httpSSLWorkerThread-8080-1;1;_RequestID=bec4fc9e-9613-46ee-a65c-aa67c0ca4a41;|JTS5057:
FailPoint : [1]|#]

[#|2009-08-14T14:54:06.797-0700|WARNING|sun-appserver2.1|javax.enterprise.system.core.transaction|_ThreadID=21;_ThreadName=httpSSLWorkerThread-8080-1;_RequestID=bec4fc9e-9613-46ee-a65c-aa67c0ca4a41;|JTS5057:
FailPoint : [null]|#]

[#|2009-08-14T14:54:06.797-0700|FINE|sun-appserver2.1|javax.enterprise.system.core.transaction|_ThreadID=21;_ThreadName=httpSSLWorkerThread-8080-1;ClassName=TopCoordinator;MethodName=commit();_RequestID=bec4fc9e-9613-46ee-a65c-aa67c0ca4a41;|Within
TopCoordinator.commit()GTID is
:0300000000DDBF1A676C617373666973682D7838362D312C7365727665722C5033373030|#]

[#|2009-08-14T14:54:06.797-0700|FINEST|sun-appserver2.1|javax.enterprise.system.core.transaction|_ThreadID=21;_ThreadName=httpSSLWorkerThread-8080-1;ClassName=TransactionState;MethodName=setState();_RequestID=bec4fc9e-9613-46ee-a65c-aa67c0ca4a41;|Acquiring
read lock on freeze : state COMMITTING|#]

[#|2009-08-14T14:54:06.797-0700|FINEST|sun-appserver2.1|javax.enterprise.system.core.transaction|_ThreadID=21;_ThreadName=httpSSLWorkerThread-8080-1;ClassName=TransactionState;MethodName=setState();_RequestID=bec4fc9e-9613-46ee-a65c-aa67c0ca4a41;|Acquired
read lock on freeze : state COMMITTING|#]

[#|2009-08-14T14:54:06.797-0700|WARNING|sun-appserver2.1|javax.enterprise.system.core.transaction|_ThreadID=21;_ThreadName=httpSSLWorkerThread-8080-1;2;_RequestID=bec4fc9e-9613-46ee-a65c-aa67c0ca4a41;|JTS5057:
FailPoint : [2]|#]

Aug 14, 2009 2:54:31 PM com.sun.enterprise.admin.servermgmt.launch.ASLauncher
buildCommand
INFO:
/usr/jdk/instances/jdk1.5.0/jre/../bin/java
-Dcom.sun.aas.instanceRoot=/export/nachi/V2/Appserver/glassfish/domains/domain1
-Dcom.sun.aas.ClassPathPrefix=
-Dcom.sun.aas.ClassPathSuffix=
-Dcom.sun.aas.ServerClassPath=
-Dcom.sun.aas.classloader.appserverChainJars.ee=
-Dcom.sun.aas.classloader.appserverChainJars=admin-cli.jar,admin-cli-ee.jar,j2ee-svc.jar
-Dcom.sun.aas.classloader.excludesList=admin-cli.jar,appserv-upgrade.jar,sun-appserv-ant.jar
-Dcom.sun.aas.classloader.optionalOverrideableChain.ee=
-Dcom.sun.aas.classloader.optionalOverrideableChain=webservices-rt.jar,webservices-tools.jar
-Dcom.sun.aas.classloader.serverClassPath.ee=/lib/hadbjdbc4.jar,/export/nachi/V2/Appserver/glassfish/lib/SUNWjdmk/5.1/lib/jdmkrt.jar,/lib/dbstate.jar,/lib/hadbm.jar,/lib/hadbmgt.jar,/opt/SUNWmfwk/lib/mfwk_instrum_tk.jar
-Dcom.sun.aas.classloader.serverClassPath=/export/nachi/V2/Appserver/glassfish/lib/install/applications/jmsra/imqjmsra.jar,/export/nachi/V2/Appserver/glassfish/imq/lib/jaxm-api.jar,/export/nachi/V2/Appserver/glassfish/imq/lib/fscontext.jar,/export/nachi/V2/Appserver/glassfish/imq/lib/imqbroker.jar,/export/nachi/V2/Appserver/glassfish/imq/lib/imqjmx.jar,/export/nachi/V2/Appserver/glassfish/lib/ant/lib/ant.jar,/export/nachi/V2/Appserver/glassfish/lib/SUNWjdmk/5.1/lib/jdmkrt.jar
-Dcom.sun.aas.classloader.sharedChainJars.ee=appserv-se.jar,appserv-ee.jar,jesmf-plugin.jar,/lib/dbstate.jar,/lib/hadbjdbc4.jar,jgroups-all.jar,/opt/SUNWmfwk/lib/mfwk_instrum_tk.jar
-Dcom.sun.aas.classloader.sharedChainJars=javaee.jar,/usr/jdk/instances/jdk1.5.0/jre/../lib/tools.jar,install/applications/jmsra/imqjmsra.jar,com-sun-commons-launcher.jar,com-sun-commons-logging.jar,/export/nachi/V2/Appserver/glassfish/imq/lib/jaxm-api.jar,/export/nachi/V2/Appserver/glassfish/imq/lib/fscontext.jar,/export/nachi/V2/Appserver/glassfish/imq/lib/imqbroker.jar,/export/nachi/V2/Appserver/glassfish/imq/lib/imqjmx.jar,/export/nachi/V2/Appserver/glassfish/imq/lib/imqxm.jar,webservices-rt.jar,webservices-tools.jar,mail.jar,appserv-jstl.jar,jmxremote_optional.jar,/export/nachi/V2/Appserver/glassfish/lib/SUNWjdmk/5.1/lib/jdmkrt.jar,activation.jar,appserv-rt.jar,appserv-admin.jar,appserv-cmp.jar,/export/nachi/V2/Appserver/glassfish/updatecenter/lib/updatecenter.jar,/export/nachi/V2/Appserver/glassfish/jbi/lib/jbi.jar,/export/nachi/V2/Appserver/glassfish/imq/lib/imqjmx.jar,/export/nachi/V2/Appserver/glassfish/lib/ant/lib/ant.jar,dbschema.jar
-Dcom.sun.aas.configName=server-config
-Dcom.sun.aas.configRoot=/export/nachi/V2/Appserver/glassfish/config
-Dcom.sun.aas.defaultLogFile=/export/nachi/V2/Appserver/glassfish/domains/domain1/logs/server.log
-Dcom.sun.aas.domainName=domain1
-Dcom.sun.aas.installRoot=/export/nachi/V2/Appserver/glassfish
-Dcom.sun.aas.instanceName=server
-Dcom.sun.aas.processLauncher=SE
-Dcom.sun.aas.promptForIdentity=true
-Dcom.sun.appserv.pluggable.features=com.sun.enterprise.ee.server.pluggable.EEPluggableFeatureImpl
-Dcom.sun.enterprise.config.config_environment_factory_class=com.sun.enterprise.config.serverbeans.AppserverConfigEnvironmentFactory
-Dcom.sun.enterprise.overrideablejavaxpackages=javax.help,javax.portlet
-Dcom.sun.enterprise.taglibs=appserv-jstl.jar,jsf-impl.jar
-Dcom.sun.enterprise.taglisteners=jsf-impl.jar
-Dcom.sun.updatecenter.home=/export/nachi/V2/Appserver/glassfish/updatecenter
-Ddomain.name=domain1
-Djava.endorsed.dirs=/export/nachi/V2/Appserver/glassfish/lib/endorsed
-Djava.ext.dirs=/usr/jdk/instances/jdk1.5.0/jre/../lib/ext:/usr/jdk/instances/jdk1.5.0/jre/../jre/lib/ext:/export/nachi/V2/Appserver/glassfish/domains/domain1/lib/ext:/export/nachi/V2/Appserver/glassfish/javadb/lib:/export/nachi/V2/Appserver/glassfish/lib/jdbcdrivers
-Djava.library.path=/export/nachi/V2/Appserver/glassfish/lib:/export/nachi/V2/Appserver/glassfish/lib:/export/nachi/V2/Appserver/glassfish/lib
-Djava.security.auth.login.config=/export/nachi/V2/Appserver/glassfish/domains/domain1/config/login.conf
-Djava.security.policy=/export/nachi/V2/Appserver/glassfish/domains/domain1/config/server.policy
-Djava.util.logging.manager=com.sun.enterprise.server.logging.ServerLogManager
-Djavax.management.builder.initial=com.sun.enterprise.ee.admin.AppServerMBeanServerBuilder
-Djavax.net.ssl.keyStore=/export/nachi/V2/Appserver/glassfish/domains/domain1/config/keystore.jks
-Djavax.net.ssl.trustStore=/export/nachi/V2/Appserver/glassfish/domains/domain1/config/cacerts.jks
-Djdbc.drivers=org.apache.derby.jdbc.ClientDriver
-Djmx.invoke.getters=true
-Dsun.rmi.dgc.client.gcInterval=3600000
-Dsun.rmi.dgc.server.gcInterval=3600000
-client
-XX:+UnlockDiagnosticVMOptions
-XX:MaxPermSize=192m
-Xmx512m
-XX:NewRatio=2
-XX:+LogVMOutput
-XX:LogFile=/export/nachi/V2/Appserver/glassfish/domains/domain1/logs/jvm.log
-cp
/export/nachi/V2/Appserver/glassfish/lib/jhall.jar:/export/nachi/V2/Appserver/glassfish/lib/appserv-launch.jar
com.sun.enterprise.server.PELaunch
start
[#|2009-08-14T14:54:33.527-0700|INFO|sun-appserver2.1|javax.enterprise.system.core|_ThreadID=10;_ThreadName=main;Java
HotSpot(TM) Client VM;1.5.0_12;Sun Microsystems Inc.;|CORE5076: Using [Java
HotSpot(TM) Client VM, Version 1.5.0_12] from [Sun Microsystems Inc.]|#]

[#|2009-08-14T14:54:33.593-0700|INFO|sun-appserver2.1|javax.enterprise.resource.jms|_ThreadID=11;_ThreadName=pool-1-thread-7;|Using
MQ RA for Broker lifecycle control|#]

[#|2009-08-14T14:54:33.635-0700|INFO|sun-appserver2.1|javax.enterprise.system.core.security|_ThreadID=12;_ThreadName=pool-1-thread-4;|SEC1002:
Security Manager is OFF.|#]

[#|2009-08-14T14:54:35.671-0700|INFO|sun-appserver2.1|javax.enterprise.system.core.security|_ThreadID=10;_ThreadName=main;com.sun.enterprise.security.provider.PolicyWrapper;|SEC1143:
Loading policy provider com.sun.enterprise.security.provider.PolicyWrapper.|#]

[#|2009-08-14T14:54:36.301-0700|INFO|sun-appserver2.1|javax.enterprise.system.container.web|_ThreadID=10;_ThreadName=main;server;|WEB0114:
SSO is disabled in virtual server [server]|#]

[#|2009-08-14T14:54:36.313-0700|INFO|sun-appserver2.1|javax.enterprise.system.container.web|ThreadID=10;_ThreadName=main;_asadmin;|WEB0114:
SSO is disabled in virtual server [__asadmin]|#]

[#|2009-08-14T14:54:36.318-0700|INFO|sun-appserver2.1|javax.enterprise.system.container.web|_ThreadID=10;_ThreadName=main;com.ericsson.ssa.config.ConvergedContextConfig;|REgistering
Custom ContextConfig|#]

[#|2009-08-14T14:54:36.318-0700|INFO|sun-appserver2.1|javax.enterprise.system.container.web|_ThreadID=10;_ThreadName=main;com.ericsson.ssa.config.ConvergedContextImpl;|REgistering
Custom Context|#]

[#|2009-08-14T14:54:36.724-0700|FINE|sun-appserver2.1|javax.enterprise.system.core.transaction|_ThreadID=10;_ThreadName=main;ClassName=Configuration;MethodName=setProperties();_RequestID=8c02f4b9-e82b-476c-b71e-4c6b77e3ef57;|
Properties set are :{ [ com.sun.jts.heuristicDirection->rollback ] [ com.sun.jts.logDirectory->/export/nachi/V2/Appserver/glassfish/domains/domain1/logs/server/tx ] [ com.sun.jts.commitRetry->600 ] [ com.sun.jts.keypointCount->65536 ] [ com.sun.jts.persistentServerId->3700 ] [ com.sun.jts.instancename->server ] }|#]

[#|2009-08-14T14:54:37.407-0700|FINE|sun-appserver2.1|javax.enterprise.system.core.transaction|_ThreadID=10;_ThreadName=main;ClassName=com.sun.jts.pi.InterceptorImpl;MethodName=<init>;_RequestID=8c02f4b9-e82b-476c-b71e-4c6b77e3ef57;|Transaction
INTEROP Mode: true|#]

[#|2009-08-14T14:54:38.463-0700|FINE|sun-appserver2.1|javax.enterprise.system.core.transaction|_ThreadID=10;_ThreadName=main;ClassName=Configuration;MethodName=setProperties();_RequestID=8c02f4b9-e82b-476c-b71e-4c6b77e3ef57;|
Properties set are :{ [ com.sun.jts.heuristicDirection->rollback ] [com.sun.jts.logDirectory->/export/nachi/V2/Appserver/glassfish/domains/domain1/logs/server/tx] [ com.sun.jts.commitRetry->600 ] [ com.sun.jts.keypointCount->65536 ] [com.sun.jts.persistentServerId->3700 ] [ com.sun.jts.instancename->server ] }] }|#]

[#|2009-08-14T14:54:38.464-0700|INFO|sun-appserver2.1|javax.enterprise.system.core.transaction|_ThreadID=10;_ThreadName=main;3700;|JTS5014:
Recoverable JTS instance, serverId = [3700]|#]

[#|2009-08-14T14:54:38.464-0700|FINE|sun-appserver2.1|javax.enterprise.system.core.transaction|_ThreadID=10;_ThreadName=main;ClassName=com.sun.jts.CosTransactions.Configuration;MethodName=getPropertyValue;_RequestID=8c02f4b9-e82b-476c-b71e-4c6b77e3ef57;|Property
:com.sun.jts.instancename has the value : server|#]

[#|2009-08-14T14:54:38.475-0700|FINE|sun-appserver2.1|javax.enterprise.system.core.transaction|_ThreadID=10;_ThreadName=main;ClassName=Configuration;MethodName=getDirectory();_RequestID=8c02f4b9-e82b-476c-b71e-4c6b77e3ef57;|Using
directory = /export/nachi/V2/Appserver/glassfish/domains/domain1/logs/server/tx
: provided in configuration|#]

[#|2009-08-14T14:54:38.479-0700|FINE|sun-appserver2.1|javax.enterprise.system.core.transaction|_ThreadID=10;_ThreadName=main;ClassName=Configuration;MethodName=setServerName();_RequestID=8c02f4b9-e82b-476c-b71e-4c6b77e3ef57;|
serverName = glassfish-x86-1,server,P3700; isRecoverable = true|#]

[#|2009-08-14T14:54:38.506-0700|FINE|sun-appserver2.1|javax.enterprise.system.core.transaction|_ThreadID=10;_ThreadName=main;ClassName=RecoveryManager;MethodName=initialise();_RequestID=8c02f4b9-e82b-476c-b71e-4c6b77e3ef57;|Before
starting ResyncThread |#]

[#|2009-08-14T14:54:38.507-0700|FINE|sun-appserver2.1|javax.enterprise.system.core.transaction|_ThreadID=13;_ThreadName=JTS
Resync
Thread;ClassName=ResyncThread;MethodName=run();_RequestID=c5c179bd-9f91-4f0f-869e-4dd45fb4e903;|Before
invoking RecoveryManager.recover()|#]

[#|2009-08-14T14:54:38.513-0700|FINE|sun-appserver2.1|javax.enterprise.system.core.transaction|_ThreadID=13;_ThreadName=JTS
Resync
Thread;ClassName=com.sun.jts.CosTransactions.Configuration;MethodName=getPropertyValue;_RequestID=c5c179bd-9f91-4f0f-869e-4dd45fb4e903;|Property
:com.sun.jts.keypointCount has the value : 65536|#]

[#|2009-08-14T14:54:38.515-0700|FINE|sun-appserver2.1|javax.enterprise.system.core.transaction|_ThreadID=13;_ThreadName=JTS
Resync
Thread;ClassName=Configuration;MethodName=getDirectory();_RequestID=c5c179bd-9f91-4f0f-869e-4dd45fb4e903;|Using
directory = /export/nachi/V2/Appserver/glassfish/domains/domain1/logs/server/tx
: provided in configuration|#]

[#|2009-08-14T14:54:38.549-0700|FINE|sun-appserver2.1|javax.enterprise.system.core.transaction|_ThreadID=13;_ThreadName=JTS
Resync
Thread;ClassName=RecoveryManager;MethodName=recover();_RequestID=c5c179bd-9f91-4f0f-869e-4dd45fb4e903;|Before
invoking proceedWithXARecovery()|#]

[#|2009-08-14T14:54:38.549-0700|FINE|sun-appserver2.1|javax.enterprise.system.core.transaction|_ThreadID=13;_ThreadName=JTS
Resync
Thread;ClassName=com.sun.jts.CosTransactions.Configuration;MethodName=getPropertyValue;_RequestID=c5c179bd-9f91-4f0f-869e-4dd45fb4e903;|Property
:com.sun.jts.ManualRecovery has the value : null|#]

[#|2009-08-14T14:54:38.550-0700|FINE|sun-appserver2.1|javax.enterprise.system.core.transaction|_ThreadID=13;_ThreadName=JTS
Resync
Thread;ClassName=RecoveryManager;MethodName=resync();_RequestID=c5c179bd-9f91-4f0f-869e-4dd45fb4e903;|Before
invoking commit on the reconstructed coordinatorGTID is:
0300000000DDBF1A676C617373666973682D7838362D312C7365727665722C5033373030|#]

[#|2009-08-14T14:54:38.550-0700|FINE|sun-appserver2.1|javax.enterprise.system.core.transaction|_ThreadID=13;_ThreadName=JTS
Resync
Thread;ClassName=TopCoordinator;MethodName=commit();_RequestID=c5c179bd-9f91-4f0f-869e-4dd45fb4e903;|Within
TopCoordinator.commit()GTID is
:0300000000DDBF1A676C617373666973682D7838362D312C7365727665722C5033373030|#]

[#|2009-08-14T14:54:38.551-0700|FINEST|sun-appserver2.1|javax.enterprise.system.core.transaction|_ThreadID=13;_ThreadName=JTS
Resync
Thread;ClassName=TransactionState;MethodName=setState();_RequestID=c5c179bd-9f91-4f0f-869e-4dd45fb4e903;|Acquiring
read lock on freeze : state COMMITTING|#]

[#|2009-08-14T14:54:38.551-0700|FINEST|sun-appserver2.1|javax.enterprise.system.core.transaction|_ThreadID=13;_ThreadName=JTS
Resync
Thread;ClassName=TransactionState;MethodName=setState();_RequestID=c5c179bd-9f91-4f0f-869e-4dd45fb4e903;|Acquired
read lock on freeze : state COMMITTING|#]

[#|2009-08-14T14:54:38.556-0700|FINEST|sun-appserver2.1|javax.enterprise.system.core.transaction|_ThreadID=13;_ThreadName=JTS
Resync
Thread;ClassName=TransactionState;MethodName=setState();_RequestID=c5c179bd-9f91-4f0f-869e-4dd45fb4e903;|Releasing
read lock on freeze : state Illegal state |#]

[#|2009-08-14T14:54:38.556-0700|FINEST|sun-appserver2.1|javax.enterprise.system.core.transaction|_ThreadID=13;_ThreadName=JTS
Resync
Thread;ClassName=TransactionState;MethodName=setState();_RequestID=c5c179bd-9f91-4f0f-869e-4dd45fb4e903;|Released
read lock on freeze|#]

[#|2009-08-14T14:54:38.556-0700|FINEST|sun-appserver2.1|javax.enterprise.system.core.transaction|_ThreadID=13;_ThreadName=JTS
Resync
Thread;ClassName=TransactionState;MethodName=setState();_RequestID=c5c179bd-9f91-4f0f-869e-4dd45fb4e903;|Released
read lock on freeze : state Illegal state |#]

[#|2009-08-14T14:54:38.566-0700|FINE|sun-appserver2.1|javax.enterprise.system.core.transaction|_ThreadID=13;_ThreadName=JTS
Resync
Thread;ClassName=RecoveryManager;MethodName=resync();_RequestID=c5c179bd-9f91-4f0f-869e-4dd45fb4e903;|Before
invoking commit on the reconstructed coordinatorGTID is:
0200000000DDBF1A676C617373666973682D7838362D312C7365727665722C5033373030|#]

[#|2009-08-14T14:54:38.566-0700|FINE|sun-appserver2.1|javax.enterprise.system.core.transaction|_ThreadID=13;_ThreadName=JTS
Resync
Thread;ClassName=TopCoordinator;MethodName=commit();_RequestID=c5c179bd-9f91-4f0f-869e-4dd45fb4e903;|Within
TopCoordinator.commit()GTID is
:0200000000DDBF1A676C617373666973682D7838362D312C7365727665722C5033373030|#]

[#|2009-08-14T14:54:38.567-0700|FINEST|sun-appserver2.1|javax.enterprise.system.core.transaction|_ThreadID=13;_ThreadName=JTS
Resync
Thread;ClassName=TransactionState;MethodName=setState();_RequestID=c5c179bd-9f91-4f0f-869e-4dd45fb4e903;|Acquiring
read lock on freeze : state COMMITTING|#]

[#|2009-08-14T14:54:38.567-0700|FINEST|sun-appserver2.1|javax.enterprise.system.core.transaction|_ThreadID=13;_ThreadName=JTS
Resync
Thread;ClassName=TransactionState;MethodName=setState();_RequestID=c5c179bd-9f91-4f0f-869e-4dd45fb4e903;|Acquired
read lock on freeze : state COMMITTING|#]

[#|2009-08-14T14:54:38.567-0700|FINEST|sun-appserver2.1|javax.enterprise.system.core.transaction|_ThreadID=13;_ThreadName=JTS
Resync
Thread;ClassName=TransactionState;MethodName=setState();_RequestID=c5c179bd-9f91-4f0f-869e-4dd45fb4e903;|Releasing
read lock on freeze : state Illegal state |#]

[#|2009-08-14T14:54:38.567-0700|FINEST|sun-appserver2.1|javax.enterprise.system.core.transaction|_ThreadID=13;_ThreadName=JTS
Resync
Thread;ClassName=TransactionState;MethodName=setState();_RequestID=c5c179bd-9f91-4f0f-869e-4dd45fb4e903;|Released
read lock on freeze|#]

[#|2009-08-14T14:54:38.567-0700|FINEST|sun-appserver2.1|javax.enterprise.system.core.transaction|_ThreadID=13;_ThreadName=JTS
Resync
Thread;ClassName=TransactionState;MethodName=setState();_RequestID=c5c179bd-9f91-4f0f-869e-4dd45fb4e903;|Released
read lock on freeze : state Illegal state |#]

[#|2009-08-14T14:54:38.821-0700|INFO|sun-appserver2.1|javax.enterprise.system.tools.admin|_ThreadID=10;_ThreadName=main;|ADM1079:
Initialization of AMX MBeans started|#]

[#|2009-08-14T14:54:39.090-0700|INFO|sun-appserver2.1|javax.enterprise.system.tools.admin|_ThreadID=14;_ThreadName=Thread-16;service:jmx:rmi:///jndi/rmi://glassfish-x86-1:8686/jmxrmi;|ADM1504:
Here is the JMXServiceURL for the Standard JMXConnectorServer:
[service:jmx:rmi:///jndi/rmi://glassfish-x86-1:8686/jmxrmi]. This is where the
remote administrative clients should connect using the standard JMX connectors|#]

[#|2009-08-14T14:54:39.090-0700|INFO|sun-appserver2.1|javax.enterprise.system.tools.admin|_ThreadID=14;_ThreadName=Thread-16;true;|ADM1506:
Status of Standard JMX Connector: Active = [true]|#]

[#|2009-08-14T14:54:39.762-0700|INFO|sun-appserver2.1|javax.enterprise.resource.resourceadapter|_ThreadID=10;_ThreadName=main;|JMS
Service Connection URL is :mq://glassfish-x86-1:7676/|#]

[#|2009-08-14T14:54:39.784-0700|INFO|sun-appserver2.1|javax.resourceadapter.mqjmsra.lifecycle|_ThreadID=10;_ThreadName=main;|MQJMSRA_RA1101:
SJSMQ JMS Resource Adapter starting...|#]

[#|2009-08-14T14:54:40.758-0700|INFO|sun-appserver2.1|javax.resourceadapter.mqjmsra.lifecycle|_ThreadID=10;_ThreadName=main;|MQJMSRA_EB1101:
EMBEDDED broker started with code =0|#]

[#|2009-08-14T14:54:40.760-0700|INFO|sun-appserver2.1|javax.resourceadapter.mqjmsra.lifecycle|_ThreadID=10;_ThreadName=main;|MQJMSRA_RA1101:
SJSMQ JMSRA Started:DIRECT|#]

[#|2009-08-14T14:54:41.929-0700|INFO|sun-appserver2.1|javax.enterprise.system.core.classloading|_ThreadID=10;_ThreadName=main;MEjbApp;|LDR5010:
All ejb(s) of [MEjbApp] loaded successfully!|#]

[#|2009-08-14T14:54:42.822-0700|INFO|sun-appserver2.1|javax.enterprise.system.container.ejb|ThreadID=10;_ThreadName=main;jdbc/_TimerPool;|EJB5109:EJB
Timer Service started successfully for datasource [jdbc/__TimerPool]|#]

[#|2009-08-14T14:54:42.822-0700|INFO|sun-appserver2.1|javax.enterprise.system.core.classloading|ThreadID=10;_ThreadName=main;_ejb_container_timer_app;|LDR5010:
All ejb(s) of [__ejb_container_timer_app] loaded successfully!|#]

[#|2009-08-14T14:54:43.127-0700|INFO|sun-appserver2.1|com.sun.jbi.framework|_ThreadID=15;_ThreadName=pool-1-thread-3;|JBIFW0010:
JBI framework ready to accept requests.|#]

[#|2009-08-14T14:54:43.251-0700|INFO|sun-appserver2.1|javax.enterprise.system.core.classloading|_ThreadID=10;_ThreadName=main;transactionapp;|LDR5010:
All ejb(s) of [transactionapp] loaded successfully!|#]

[#|2009-08-14T14:54:43.261-0700|INFO|sun-appserver2.1|javax.enterprise.system.container.web|_ThreadID=10;_ThreadName=main;|WEB0302:
Starting Sun-Java-System/Application-Server.|#]

[#|2009-08-14T14:54:43.585-0700|INFO|sun-appserver2.1|javax.enterprise.system.container.web|_ThreadID=10;_ThreadName=main;8080;|WEB0712:
Starting Sun-Java-System/Application-Server HTTP/1.1 on 8080|#]

[#|2009-08-14T14:54:43.651-0700|INFO|sun-appserver2.1|javax.enterprise.system.container.web|_ThreadID=10;_ThreadName=main;8181;|WEB0712:
Starting Sun-Java-System/Application-Server HTTP/1.1 on 8181|#]

[#|2009-08-14T14:54:43.663-0700|INFO|sun-appserver2.1|javax.enterprise.system.container.web|_ThreadID=10;_ThreadName=main;4848;|WEB0712:
Starting Sun-Java-System/Application-Server HTTP/1.1 on 4848|#]

[#|2009-08-14T14:54:44.200-0700|INFO|sun-appserver2.1|javax.enterprise.system.core.selfmanagement|_ThreadID=10;_ThreadName=main;|SMGT0007:
Self Management Rules service is enabled|#]

[#|2009-08-14T14:54:44.654-0700|WARNING|sun-appserver2.1|javax.enterprise.system.container.ejb|_ThreadID=10;_ThreadName=main;_RequestID=8c02f4b9-e82b-476c-b71e-4c6b77e3ef57;|EJBLifeCycle:
Automatic timer migration component not enabled for DAS instance|#]

[#|2009-08-14T14:54:44.674-0700|INFO|sun-appserver2.1|javax.enterprise.system.core|_ThreadID=10;_ThreadName=main;|Application
server startup complete.|#]

[#|2009-08-14T14:57:49.290-0700|WARNING|sun-appserver2.1|javax.enterprise.resource.resourceadapter|ThreadID=16;_ThreadName=httpWorkerThread-4848-0;_CallFlowPool;_RequestID=302b7b14-105d-429a-96bd-f952d6676e99;|RAR5005:Error
in accessing XA resource with JNDI name [__CallFlowPool] for recovery|#]

[#|2009-08-14T14:57:49.294-0700|WARNING|sun-appserver2.1|javax.enterprise.resource.resourceadapter|ThreadID=16;_ThreadName=httpWorkerThread-4848-0;_TimerPool;_RequestID=302b7b14-105d-429a-96bd-f952d6676e99;|RAR5005:Error
in accessing XA resource with JNDI name [__TimerPool] for recovery|#]

[#|2009-08-14T14:57:49.295-0700|INFO|sun-appserver2.1|javax.enterprise.resource.resourceadapter|_ThreadID=16;_ThreadName=httpWorkerThread-4848-0;|Recovery
of Inbound Transactions started.|#]

[#|2009-08-14T14:57:49.296-0700|FINE|sun-appserver2.1|javax.enterprise.system.core.transaction|_ThreadID=16;_ThreadName=httpWorkerThread-4848-0;ClassName=RecoveryManager;MethodName=getInDoubtXids();_RequestID=302b7b14-105d-429a-96bd-f952d6676e99;|Before
receiving inDoubtXids from xaresource = com.sun.gjc.spi.XAResourceImpl@1601539|#]

[#|2009-08-14T14:57:49.298-0700|FINE|sun-appserver2.1|javax.enterprise.system.core.transaction|_ThreadID=16;_ThreadName=httpWorkerThread-4848-0;ClassName=RecoveryManager;MethodName=getInDoubtXid();_RequestID=302b7b14-105d-429a-96bd-f952d6676e99;|InDoubtXids
returned from xaresource = com.sun.gjc.spi.XAResourceImpl@1601539are: Xid class
name is org.apache.derby.client.ClientXid Number of Xids are 4 [ {ClientXid: formatID(4871251), gtrid_length(36), bqual_length(30), data(03000000 9D753F1A 676C6173 73666973 682D7838 362D312C 73657276 65722C50 33373030 676C6173 73666973 682D7838 362D312C 73657276 65722C50 33373030 2C01)}

{ClientXid: formatID(4871251), gtrid_length(36), bqual_length(30), data(02000000 00DDBF1A 676C6173 73666973 682D7838 362D312C 73657276 65722C50 33373030 676C6173 73666973 682D7838 362D312C 73657276 65722C50 33373030 2C01)} {ClientXid: formatID(4871251), gtrid_length(36), bqual_length(30), data(04000000 0EEDF919 676C6173 73666973 682D7838 362D312C 73657276 65722C50 33373030 676C6173 73666973 682D7838 362D312C 73657276 65722C50 33373030 2C01)}

{ClientXid: formatID(4871251), gtrid_length(36), bqual_length(30), data(03000000 00DDBF1A 676C6173 73666973 682D7838 362D312C 73657276 65722C50 33373030 676C6173 73666973 682D7838 362D312C 73657276 65722C50 33373030 2C01)} ]|#]

[#|2009-08-14T14:57:49.299-0700|FINE|sun-appserver2.1|javax.enterprise.system.core.transaction|_ThreadID=16;_ThreadName=httpWorkerThread-4848-0;ClassName=RecoveryManager;MethodName=recoverIncompleteTx;_RequestID=302b7b14-105d-429a-96bd-f952d6676e99;|
This xid is UNIQUE {ClientXid: formatID(4871251), gtrid_length(36), bqual_length(30), data(03000000 9D753F1A 676C6173 73666973 682D7838 362D312C 73657276 65722C50 33373030 676C6173 73666973 682D7838 362D312C 73657276 65722C50 33373030 2C01)}|#]

[#|2009-08-14T14:57:49.305-0700|FINE|sun-appserver2.1|javax.enterprise.system.core.transaction|_ThreadID=16;_ThreadName=httpWorkerThread-4848-0;ClassName=RecoveryManager;MethodName=recoverIncompleteTx;_RequestID=302b7b14-105d-429a-96bd-f952d6676e99;|
This xid is UNIQUE {ClientXid: formatID(4871251), gtrid_length(36), bqual_length(30), data(02000000 00DDBF1A 676C6173 73666973 682D7838 362D312C 73657276 65722C50 33373030 676C6173 73666973 682D7838 362D312C 73657276 65722C50 33373030 2C01)}|#]

[#|2009-08-14T14:57:49.305-0700|FINE|sun-appserver2.1|javax.enterprise.system.core.transaction|_ThreadID=16;_ThreadName=httpWorkerThread-4848-0;ClassName=RecoveryManager;MethodName=recoverIncompleteTx;_RequestID=302b7b14-105d-429a-96bd-f952d6676e99;|
This xid is UNIQUE {ClientXid: formatID(4871251), gtrid_length(36), bqual_length(30), data(04000000 0EEDF919 676C6173 73666973 682D7838 362D312C 73657276 65722C50 33373030 676C6173 73666973 682D7838 362D312C 73657276 65722C50 33373030 2C01)}|#]

[#|2009-08-14T14:57:49.305-0700|FINE|sun-appserver2.1|javax.enterprise.system.core.transaction|_ThreadID=16;_ThreadName=httpWorkerThread-4848-0;ClassName=RecoveryManager;MethodName=recoverIncompleteTx;_RequestID=302b7b14-105d-429a-96bd-f952d6676e99;|
This xid is UNIQUE {ClientXid: formatID(4871251), gtrid_length(36), bqual_length(30), data(03000000 00DDBF1A 676C6173 73666973 682D7838 362D312C 73657276 65722C50 33373030 676C6173 73666973 682D7838 362D312C 73657276 65722C50 33373030 2C01)}|#]

[#|2009-08-14T14:57:49.306-0700|FINE|sun-appserver2.1|javax.enterprise.system.core.transaction|_ThreadID=16;_ThreadName=httpWorkerThread-4848-0;ClassName=RecoveryManager;MethodName=getInDoubtXids();_RequestID=302b7b14-105d-429a-96bd-f952d6676e99;|Before
receiving inDoubtXids from xaresource = com.sun.gjc.spi.XAResourceImpl@11d3f0b|#]

[#|2009-08-14T14:57:49.306-0700|FINE|sun-appserver2.1|javax.enterprise.system.core.transaction|_ThreadID=16;_ThreadName=httpWorkerThread-4848-0;ClassName=RecoveryManager;MethodName=getInDoubtXid();_RequestID=302b7b14-105d-429a-96bd-f952d6676e99;|InDoubtXids
returned from xaresource = com.sun.gjc.spi.XAResourceImpl@11d3f0bare: Xid class
name is org.apache.derby.client.ClientXid Number of Xids are 3 [ {ClientXid: formatID(4871251), gtrid_length(36), bqual_length(30), data(03000000 9D753F1A 676C6173 73666973 682D7838 362D312C 73657276 65722C50 33373030 676C6173 73666973 682D7838 362D312C 73657276 65722C50 33373030 2C00)}

{ClientXid: formatID(4871251), gtrid_length(36), bqual_length(30), data(04000000 0EEDF919 676C6173 73666973 682D7838 362D312C 73657276 65722C50 33373030 676C6173 73666973 682D7838 362D312C 73657276 65722C50 33373030 2C00)}

{ClientXid: formatID(4871251), gtrid_length(36), bqual_length(30), data(03000000 00DDBF1A 676C6173 73666973 682D7838 362D312C 73657276 65722C50 33373030 676C6173 73666973 682D7838 362D312C 73657276 65722C50 33373030 2C00)} ]|#]

[#|2009-08-14T14:57:49.306-0700|FINE|sun-appserver2.1|javax.enterprise.system.core.transaction|_ThreadID=16;_ThreadName=httpWorkerThread-4848-0;ClassName=RecoveryManager;MethodName=recoverIncompleteTx;_RequestID=302b7b14-105d-429a-96bd-f952d6676e99;|
This xid is UNIQUE {ClientXid: formatID(4871251), gtrid_length(36), bqual_length(30), data(03000000 9D753F1A 676C6173 73666973 682D7838 362D312C 73657276 65722C50 33373030 676C6173 73666973 682D7838 362D312C 73657276 65722C50 33373030 2C00)}|#]

[#|2009-08-14T14:57:49.307-0700|FINE|sun-appserver2.1|javax.enterprise.system.core.transaction|_ThreadID=16;_ThreadName=httpWorkerThread-4848-0;ClassName=RecoveryManager;MethodName=recoverIncompleteTx;_RequestID=302b7b14-105d-429a-96bd-f952d6676e99;|
This xid is UNIQUE {ClientXid: formatID(4871251), gtrid_length(36), bqual_length(30), data(04000000 0EEDF919 676C6173 73666973 682D7838 362D312C 73657276 65722C50 33373030 676C6173 73666973 682D7838 362D312C 73657276 65722C50 33373030 2C00)}|#]

[#|2009-08-14T14:57:49.307-0700|FINE|sun-appserver2.1|javax.enterprise.system.core.transaction|_ThreadID=16;_ThreadName=httpWorkerThread-4848-0;ClassName=RecoveryManager;MethodName=recoverIncompleteTx;_RequestID=302b7b14-105d-429a-96bd-f952d6676e99;|
This xid is UNIQUE {ClientXid: formatID(4871251), gtrid_length(36), bqual_length(30), data(03000000 00DDBF1A 676C6173 73666973 682D7838 362D312C 73657276 65722C50 33373030 676C6173 73666973 682D7838 362D312C 73657276 65722C50 33373030 2C00)}|#]



marina vatkina added a comment - 17/Aug/09 10:29 AM

Can it be the same problem as CR 6673688? The recovery skips resources that are
not up by the time the revovery is happening?


nachi_glassfish added a comment - 17/Aug/09 10:53 AM

This is a different scenario. In this case, the application server is brought
down and started again. The resources are always are up and running.


marina vatkina added a comment - 17/Aug/09 03:05 PM

It might still be an error in Derby with the lock handling


marina vatkina added a comment - 23/Oct/09 10:15 AM

not a blocker


marina vatkina added a comment - 17/Nov/09 10:08 AM

Unfortunately the scenario described in this bug - server crash and automatic
recovery not enabled - is not a supported option.

Manual recovery is supported only for a resource recovery on a live server, that
is when one of the resource manager dies and comes and you want to perform recovery.

I'll file a separate doc bug to document this limitation, but I'm changing this
issue to be an RFE for the next release


marina vatkina added a comment - 03/Jun/10 02:32 PM

The problem applies to non-delegated recovery only


kenaiadmin made changes - 26/Nov/10 12:06 AM
Field Original Value New Value
issue.field.bugzillaimportkey 9133 40737