Hi, I am running an ejb inside weblogic server 9.1 with Hibernate entity manager PersistenceUnitTransactionType.JTA and PersistenceContextType.TRANSACTION.
The short story is that I eventually get an illegal state exception trying to rollback a transaction that was already successfully committed. Here is what happens:
The AbstractEntityManagerImpl class attempts to join the current transaction in the postInit.
In AbstractEntityManagerImpl.joinTransaction(), the session is registered to the Tx:
- session.isOpen(); //register to the Tx | calls...
- checkTransactionSynchStatus() | calls...
- jdbcContext.registerSynchronizationIfPossible(); | calls...
- tx.registerSynchronization( new CacheSynchronization(owner, this, tx, null) );
This is out first call back
Later, still in AbstractEntityManagerImpl.joinTransaction() we register a 2nd callback:
log.trace( "Adding flush() and close() synchronization" );
joinableCMTTransaction.registerSynchronization(
new Synchronization() {
....
When the transaction completes, the afterCompletion of the container calls afterCompletion with status 3 (ie: committed) on our 2 registered callbacks:
- CacheSynchronization
- AbstractEntityManagerImpl$1
The first callback closes the Context in the finally block: ctx.managedClose();
This ends up closing the session.
Later our 2nd callback is called and the following code gets executed:
JoinableCMTTransaction joinable = (JoinableCMTTransaction) session.getTransaction();
Since the session is closed, getTransaction() throws an exception: throw new SessionException( "Session is closed!" );
This exception is handled in the afterCompletion and a PersistenceException is thrown: throwPersistenceException( e ); with an attempt first to mark the transaction as rollbacked:
public void throwPersistenceException(PersistenceException e) {
if ( ! ( e instanceof NoResultException || ( e instanceof NonUniqueResultException ) ) ) markAsRollback();
throw e;
}
In markAsRollback, transactionManager.setRollbackOnly(); will finally throw a java.lang.IllegalStateException: Transaction does not exist.
The problem seems to revolve around the ordering of the 2 synchronizations, or not testing if the session is closed before doing JoinableCMTTransaction joinable = (JoinableCMTTransaction) session.getTransaction(); in the afterCompletion.
Is it a bug?
Thanks for help,
Vincent
Hibernate version: hibernate-entitymanager-3.1.0.Beta8, hibernate-annotations-3.1.0.Beta10, hibernate-3.2.0cr2
Mapping documents:
@Entity
@Table(name = "Translatn")
public class Translation {
private Integer id;
private String into;
@Id
@GeneratedValue
public Integer getId() {
return id;
}
public void setId(Integer id) {
this.id = id;
}
@Column(name = "country_into")
public String getInto() {
return into;
}
public void setInto(String into) {
this.into = into;
}
@Override
public String toString() {
return "translation id=" + id + "/into=" + into;
}
}
Code between sessionFactory.openSession() and session.close(): inside business method of session bean:
EntityManager em = new CurrentEntityManagerImpl(sessionFactory, PersistenceUnitTransactionType.JTA);
Translation tl = new Translation();
tl.setInto("France");
em.persist(tl);
System.out.println(em.createQuery("select t from Translation t").getResultList());
Full stack trace of any exception that occurs: complete trace of server (stack at the bottom):
.
.
JAVA Memory arguments: -Xms256m -Xmx512m -XX:CompileThreshold=8000 -XX:PermSize=48m -XX:MaxPermSize=128m
.
WLS Start Mode=Development
.
CLASSPATH=C:\bea\WEBLOG~2\server\ext\jdbc\hsqldb-1.8.0.2.jar;E:\projects\BEADOM~1\dev91\ext\ejb-3.0-public-draft-20060327.jar;E:\projects\BEADOM~1\dev91\properties;C:\bea\patch_weblogic910\profiles\default\sys_manifest_classpath\weblogic_patch.jar;C:\bea\JDK150~1\lib\tools.jar;C:\bea\WEBLOG~2\server\lib\weblogic_sp.jar;C:\bea\WEBLOG~2\server\lib\weblogic.jar;C:\bea\WEBLOG~2\server\lib\webservices.jar;;C:\bea\WEBLOG~2\common\eval\pointbase\lib\pbclient51.jar;C:\bea\WEBLOG~2\server\lib\xqrl.jar;;C:\bea\WEBLOG~2\integration\lib\util.jar;
.
PATH=C:\bea\patch_weblogic910\profiles\default\native;C:\bea\WEBLOG~2\server\native\win\32;C:\bea\WEBLOG~2\server\bin;C:\bea\JDK150~1\jre\bin;C:\bea\JDK150~1\bin;C:\EXTUserAppl\Oracle\V9.2.0\bin;C:\WINDOWS\system32;C:\WINDOWS;C:\WINDOWS\System32\Wbem;C:\LOCLibrary\Bin;C:\Orbix\Orbix6\asp\6.1\bin;C:\Orbix\Orbix6\etc\bin;C:\Orbix\Orbix6\bin;C:\Program Files\Java\j2re1.4.2_03\bin\client;C:\IONA\Orbix_2.3c02\BIN;C:\Program Files\Schlumberger\Smart Cards and Terminals\Cyberflex Access Kits\v4\;C:\Program Files\Rational\ClearCase\bin;C:\Program Files\Rational\common;C:\Program Files\Oracle\jre\1.1.8
\bin;C:\EXTUSerAppl\Oracle\V9.2.0\jre\1.4.2\bin;C:\EXTUSerAppl\Oracle\V9.2.0\jre\1.4.2\bin\client;C:\EXTUserAppl\UltraEdit\;C:\soft\javalib\maven\1.0.2\bin;C:\soft\dev\xmlbeans-2.1.0\bin;C:\bea\WEBLOG~2\server\native\win\32\oci920_8
.
***************************************************
* To start WebLogic Server, use a username and *
* password assigned to an admin-level user. For *
* server administration, use the WebLogic Server *
* console at http:\\hostname:port\console *
***************************************************
starting weblogic with Java version:
Listening for transport dt_socket at address: 8453
java version "1.5.0_04"
Java(TM) 2 Runtime Environment, Standard Edition (build 1.5.0_04-b05)
Java HotSpot(TM) Client VM (build 1.5.0_04-b05, mixed mode)
Starting WLS with line:
C:\bea\JDK150~1\bin\java -client -Xdebug -Xnoagent -Xrunjdwp:transport=dt_socket,address=8453,server=y,suspend=n -Djava.compiler=NONE -Xms256m -Xmx512m -XX:CompileThreshold=8000 -XX:PermSize=48m -XX:MaxPermSize=128m -Dweblogic.wsee.verbose=* -Dweblogic.debug.DebugJTA2PC=true -Dweblogic.log.StdoutSeverity="Debug" -Xverify:none -ea -da:com.bea... -da:javelin... -da:weblogic... -ea:com.bea.wli... -ea:com.bea.broker... -ea:com.bea.sbconsole... -Dplatform.home=C:\bea\WEBLOG~2 -Dwls.home=C:\bea\WEBLOG~2\server -Dwli.home=C:\bea\WEBLOG~2\integration -Dweblogic.management.discover=true -Dweblogic.
ProductionModeEnabled= -Dwlw.iterativeDev= -Dwlw.testConsole= -Dwlw.logErrorsToConsole= -Dweblogic.ext.dirs=C:\bea\patch_weblogic910\profiles\default\sysext_manifest_classpath -Dweblogic.Name=AdminServer -Djava.security.policy=C:\bea\WEBLOG~2\server\lib\weblogic.policy weblogic.Server
Listening for transport dt_socket at address: 8453
<12 mai 2006 11.04. h CEST> <Info> <WebLogicServer> <BEA-000377> <Starting WebLogic Server with Java HotSpot(TM) Client VM Version 1.5.0_04-b05 from Sun Microsystems Inc.>
<12 mai 2006 11.04. h CEST> <Info> <Management> <BEA-141107> <Version: WebLogic Server 9.1 Mon Dec 12 19:26:33 EST 2005 689178 >
<12 mai 2006 11.04. h CEST> <Info> <WebLogicServer> <BEA-000215> <Loaded License : C:\bea\license.bea>
<12 mai 2006 11.04. h CEST> <Notice> <WebLogicServer> <BEA-000365> <Server state changed to STARTING>
<12 mai 2006 11.04. h CEST> <Info> <WorkManager> <BEA-002900> <Initializing self-tuning thread pool>
<12 mai 2006 11.04. h CEST> <Info> <WebLogicServer> <BEA-000214> <WebLogic Server "AdminServer" version:
WebLogic Server 9.1 Mon Dec 12 19:26:33 EST 2005 689178 (c) 1995, 1996, 1997, 1998 WebLogic, Inc.
(c) 1999, 2000, 2001 BEA Systems, Inc.>
<12 mai 2006 11.04. h CEST> <Info> <Log Management> <BEA-170017> <The log file E:\projects\beadomains\dev91\servers\AdminServer\logs\AdminServer.log will be rotated. Reopen the log file if tailing has stopped. This can happen on some platforms like Windows.>
<12 mai 2006 11.04. h CEST> <Info> <Log Management> <BEA-170018> <The log file has been rotated to E:\projects\beadomains\dev91\servers\AdminServer\logs\AdminServer.log00038. Log messages will continue to be logged in E:\projects\beadomains\dev91\servers\AdminServer\logs\AdminServer.log.>
<12 mai 2006 11.04. h CEST> <Notice> <Log Management> <BEA-170019> <The server log file E:\projects\beadomains\dev91\servers\AdminServer\logs\AdminServer.log is opened. All server side log events will be written to this file.>
<12 mai 2006 11.04. h CEST> <Info> <Log Management> <BEA-170023> <The Server Logging is initialized with Java Logging API implementation.>
<12 mai 2006 11.04. h CEST> <Info> <Diagnostics> <BEA-320001> <The ServerDebug service initialized successfully.>
<12 mai 2006 11.04. h CEST> <Info> <Server> <BEA-002622> <The protocol "t3" is now configured.>
<12 mai 2006 11.04. h CEST> <Info> <Server> <BEA-002622> <The protocol "t3s" is now configured.>
<12 mai 2006 11.04. h CEST> <Info> <Server> <BEA-002622> <The protocol "http" is now configured.>
<12 mai 2006 11.04. h CEST> <Info> <Server> <BEA-002622> <The protocol "https" is now configured.>
<12 mai 2006 11.04. h CEST> <Info> <Server> <BEA-002622> <The protocol "iiop" is now configured.>
<12 mai 2006 11.04. h CEST> <Info> <Server> <BEA-002622> <The protocol "iiops" is now configured.>
<12 mai 2006 11.04. h CEST> <Info> <Server> <BEA-002622> <The protocol "ldap" is now configured.>
<12 mai 2006 11.04. h CEST> <Info> <Server> <BEA-002622> <The protocol "ldaps" is now configured.>
<12 mai 2006 11.04. h CEST> <Info> <Server> <BEA-002622> <The protocol "admin" is now configured.>
<12 mai 2006 11.04. h CEST> <Info> <Server> <BEA-002624> <The administration protocol is "t3s" and is now configured.>
<12 mai 2006 11.04. h CEST> <Info> <RJVM> <BEA-000570> <Network Configuration for Channel "AdminServer"
Listen Address :7001
Public Address N/A
Http Enabled true
Tunneling Enabled false
Outbound Enabled false
Admin Traffic Enabled true>
<12 mai 2006 11.04. h CEST> <Debug> <RJVM> <BEA-000571> <Network Configuration Detail for Channel "AdminServer"
Channel Weight 50
Accept Backlog 50
Login Timeout 5000ms
Max Message Size 10000000
Message Timeout 60s
Idle Timeout 65s
Tunneling Timeout 40s
Tunneling Ping 45s>
<12 mai 2006 11.04. h CEST> <Info> <Server> <BEA-002609> <Channel Service initialized.>
<12 mai 2006 11.04. h CEST> <Info> <Socket> <BEA-000406> <NTSocketMuxer was built on Jan 13 2005 17:47:03
>
<12 mai 2006 11.04. h CEST> <Info> <Socket> <BEA-000436> <Allocating 3 reader threads.>
<12 mai 2006 11.04. h CEST> <Info> <Socket> <BEA-000446> <Native IO Enabled.>
<12 mai 2006 11.04. h CEST> <Info> <IIOP> <BEA-002014> <IIOP subsystem enabled.>
<12 mai 2006 11.04. h CEST> <Info> <Security> <BEA-090516> <The CredentialMapper provider has preexisting LDAP data.>
<12 mai 2006 11.04. h CEST> <Info> <Security> <BEA-090516> <The RoleMapper provider has preexisting LDAP data.>
<12 mai 2006 11.04. h CEST> <Info> <Security> <BEA-090516> <The Authorizer provider has preexisting LDAP data.>
<12 mai 2006 11.04. h CEST> <Info> <Security> <BEA-090516> <The Authenticator provider has preexisting LDAP data.>
<12 mai 2006 11.04. h CEST> <Info> <Security> <BEA-090093> <No pre-WLS 8.1 Keystore providers are configured for server AdminServer for security realm myrealm.>
<12 mai 2006 11.04. h CEST> <Notice> <Security> <BEA-090082> <Security initializing using security realm myrealm.>
<12 mai 2006 11.04. h CEST> <Info> <Server> <BEA-002622> <The protocol "[t3s, https, http, iiop, t3, ldap, ldaps, iiops, admin]" is now configured.>
<12 mai 2006 11.04. h CEST> <Info> <XML> <BEA-130036> <Initializing XMLRegistry.>
<12 mai 2006 11.04. h CEST> <Info> <messaging.interception> <BEA-400000> <Initializing message interception service>
<12 mai 2006 11.04. h CEST> <Info> <Store> <BEA-280050> <Persistent store "_WLS_AdminServer" opened: directory=".\servers\AdminServer\data\store\default" writePolicy="Direct-Write" blockSize=512 directIO=true driver="wlfileio2">
<12 mai 2006 11.04. h CEST> <Debug> <JTA2PC> <000000> <ServerTM.setAbandonTimeoutSeconds(sec=86400)>
<12 mai 2006 11.04. h CEST> <Debug> <JTA2PC> <000000> <ServerTM.setForgetHeuristics(true)>
<12 mai 2006 11.04. h CEST> <Debug> <JTA2PC> <000000> <ClientTM[AdminServer+10.23.130.96:7001+dev91+t3+].setAbandonTimeoutSeconds(sec=86400)>
<12 mai 2006 11.04. h CEST> <Debug> <JTA2PC> <000000> <setAbandonGraceTimeEndSec(1147425273)>
<12 mai 2006 11.04. h CEST> <Info> <JDBC> <BEA-001135> <Initializing the JDBC service.>
<12 mai 2006 11.04. h CEST> <Info> <JDBC> <BEA-001137> <Initialization complete.>
<12 mai 2006 11.04. h CEST> <Info> <JDBC> <BEA-001138> <Resuming the JDBC service.>
<12 mai 2006 11.04. h CEST> <Info> <JDBC> <BEA-001140> <Resume complete.>
<12 mai 2006 11.04. h CEST> <Info> <Connector> <BEA-190000> <Initializing J2EE Connector Service>
<12 mai 2006 11.04. h CEST> <Info> <Connector> <BEA-190001> <J2EE Connector Service initialized successfully>
<12 mai 2006 11.04. h CEST> <Info> <JMS> <BEA-040305> <JMS service is initialized and in standby mode.>
<12 mai 2006 11.04. h CEST> <Info> <JMS> <BEA-040090> <Deployed 8 default connection factories.>
<12 mai 2006 11.04. h CEST> <Info> <JMS> <BEA-040407> <Default connection factory "DefaultXAConnectionFactory1" with its JNDI name "weblogic.jms.XAConnectionFactory1" is started.>
<12 mai 2006 11.04. h CEST> <Info> <JMS> <BEA-040407> <Default connection factory "DefaultXAConnectionFactory" with its JNDI name "weblogic.jms.XAConnectionFactory" is started.>
<12 mai 2006 11.04. h CEST> <Info> <JMS> <BEA-040407> <Default connection factory "QueueConnectionFactory" with its JNDI name "javax.jms.QueueConnectionFactory" is started.>
<12 mai 2006 11.04. h CEST> <Info> <JMS> <BEA-040407> <Default connection factory "DefaultXAConnectionFactory2" with its JNDI name "weblogic.jms.XAConnectionFactory2" is started.>
<12 mai 2006 11.04. h CEST> <Info> <JMS> <BEA-040407> <Default connection factory "MessageDrivenBeanConnectionFactory" with its JNDI name "weblogic.jms.MessageDrivenBeanConnectionFactory" is started.>
<12 mai 2006 11.04. h CEST> <Info> <JMS> <BEA-040407> <Default connection factory "DefaultXAConnectionFactory0" with its JNDI name "weblogic.jms.XAConnectionFactory0" is started.>
<12 mai 2006 11.04. h CEST> <Info> <JMS> <BEA-040407> <Default connection factory "DefaultConnectionFactory" with its JNDI name "weblogic.jms.ConnectionFactory" is started.>
<12 mai 2006 11.04. h CEST> <Info> <JMS> <BEA-040407> <Default connection factory "TopicConnectionFactory" with its JNDI name "javax.jms.TopicConnectionFactory" is started.>
<12 mai 2006 11.04. h CEST> <Info> <JMS> <BEA-040306> <JMS service is active now.>
<12 mai 2006 11.04. h CEST> <Info> <SAFService> <BEA-281003> <SAF Service has been initialized.>
---------------------------------------------
| <WSEE> Webservice running in Verbose mode |
---------------------------------------------
<12 mai 2006 11.04. h CEST> <Info> <SAFService> <BEA-281002> <SAF Service has been started.>
<12 mai 2006 11.04. h CEST> <Info> <HTTP> <BEA-101128> <Initializing HTTP services.>
<12 mai 2006 11.04. h CEST> <Info> <HTTP> <BEA-101135> <AdminServer is the default Web server.>
<12 mai 2006 11.04. h CEST> <Info> <HTTP> <BEA-101052> <[HttpServer (defaultWebserver) name: AdminServer] Initialized>
<12 mai 2006 11.04. h CEST> <Info> <HTTP> <BEA-101129> <Initializing the Web application container.>
<12 mai 2006 11.04. h CEST> <Info> <WebService> <BEA-220031> <The server does not support reliable SOAP messaging.>
<12 mai 2006 11.04. h CEST> <Info> <WebService> <BEA-220027> <Web Service reliable agents are started on the server.>
<12 mai 2006 11.04. h CEST> <Info> <JMX> <BEA-149512> <JMX Connector Server started at service:jmx:iiop://10.23.130.96:7001/jndi/weblogic.management.mbeanservers.runtime .>
<12 mai 2006 11.04. h CEST> <Info> <JMX> <BEA-149512> <JMX Connector Server started at service:jmx:iiop://10.23.130.96:7001/jndi/weblogic.management.mbeanservers.edit .>
<12 mai 2006 11.04. h CEST> <Info> <Management> <BEA-141187> <Java system properties are defined as follows:
awt.toolkit = sun.awt.windows.WToolkit
file.encoding = Cp1252
file.encoding.pkg = sun.io
file.separator = \
java.awt.graphicsenv = sun.awt.Win32GraphicsEnvironment
java.awt.printerjob = sun.awt.windows.WPrinterJob
java.class.path = C:\bea\WEBLOG~2\server\ext\jdbc\hsqldb-1.8.0.2.jar;E:\projects\BEADOM~1\dev91\ext\ejb-3.0-public-draft-20060327.jar;E:\projects\BEADOM~1\dev91\properties;C:\bea\patch_weblogic910\profiles\default\sys_manifest_classpath\weblogic_patch.jar;C:\bea\JDK150~1\lib\tools.jar;C:\bea\WEBLOG~2\server\lib\weblogic_sp.jar;C:\bea\WEBLOG~2\server\lib\weblogic.jar;C:\bea\WEBLOG~2\server\lib\webservices.jar;;C:\bea\WEBLOG~2\common\eval\pointbase\lib\pbclient51.jar;C:\bea\WEBLOG~2\server\lib\xqrl.jar;;C:\bea\WEBLOG~2\integration\lib\util.jar;
java.class.version = 49.0
java.endorsed.dirs = C:\bea\JDK150~1\jre\lib\endorsed
java.ext.dirs = C:\bea\JDK150~1\jre\lib\ext
java.home = C:\bea\JDK150~1\jre
java.io.tmpdir = C:\DOCUME~1\Sevel\LOCALS~1\Temp\
java.library.path = C:\bea\JDK150~1\bin;.;C:\WINDOWS\system32;C:\WINDOWS;C:\bea\patch_weblogic910\profiles\default\native;C:\bea\WEBLOG~2\server\native\win\32;C:\bea\WEBLOG~2\server\bin;C:\bea\JDK150~1\jre\bin;C:\bea\JDK150~1\bin;C:\EXTUserAppl\Oracle\V9.2.0\bin;C:\WINDOWS\system32;C:\WINDOWS;C:\WINDOWS\System32\Wbem;C:\LOCLibrary\Bin;C:\Orbix\Orbix6\asp\6.1\bin;C:\Orbix\Orbix6\etc\bin;C:\Orbix\Orbix6\bin;C:\Program Files\Java\j2re1.4.2_03\bin\client;C:\IONA\Orbix_2.3c02\BIN;C:\Program Files\Schlumberger\Smart Cards and Terminals\Cyberflex Access Kits\v4\;C:\Program Files\Rational\ClearCase\bi
n;C:\Program Files\Rational\common;C:\Program Files\Oracle\jre\1.1.8\bin;C:\EXTUSerAppl\Oracle\V9.2.0\jre\1.4.2\bin;C:\EXTUSerAppl\Oracle\V9.2.0\jre\1.4.2\bin\client;C:\EXTUserAppl\UltraEdit\;C:\soft\javalib\maven\1.0.2\bin;C:\soft\dev\xmlbeans-2.1.0\bin;C:\bea\WEBLOG~2\server\native\win\32\oci920_8
java.naming.factory.initial = weblogic.jndi.WLInitialContextFactory
java.naming.factory.url.pkgs = weblogic.jndi.factories:weblogic.corba.j2ee.naming.url:weblogic.jndi.factories:weblogic.corba.j2ee.naming.url
java.protocol.handler.pkgs = weblogic.utils|weblogic.utils|weblogic.utils|weblogic.net
java.runtime.name = Java(TM) 2 Runtime Environment, Standard Edition
java.runtime.version = 1.5.0_04-b05
java.security.policy = C:\bea\WEBLOG~2\server\lib\weblogic.policy
java.specification.name = Java Platform API Specification
java.specification.vendor = Sun Microsystems Inc.
java.specification.version = 1.5
java.vendor = Sun Microsystems Inc.
java.vendor.url =
http://java.sun.com/
java.vendor.url.bug =
http://java.sun.com/cgi-bin/bugreport.cgi
java.version = 1.5.0_04
java.vm.info = mixed mode
java.vm.name = Java HotSpot(TM) Client VM
java.vm.specification.name = Java Virtual Machine Specification
java.vm.specification.vendor = Sun Microsystems Inc.
java.vm.specification.version = 1.0
java.vm.vendor = Sun Microsystems Inc.
java.vm.version = 1.5.0_04-b05
javax.rmi.CORBA.PortableRemoteObjectClass = weblogic.iiop.PortableRemoteObjectDelegateImpl
javax.rmi.CORBA.UtilClass = weblogic.iiop.UtilDelegateImpl
javax.xml.rpc.ServiceFactory = weblogic.webservice.core.rpc.ServiceFactoryImpl
javax.xml.soap.MessageFactory = weblogic.webservice.core.soap.MessageFactoryImpl
org.omg.CORBA.ORBClass = weblogic.corba.orb.ORB
org.omg.CORBA.ORBSingletonClass = weblogic.corba.orb.ORB
org.xml.sax.driver = weblogic.apache.xerces.parsers.SAXParser
org.xml.sax.parser = weblogic.xml.jaxp.RegistryParser
os.arch = x86
os.name = Windows XP
os.version = 5.1
path.separator = ;
platform.home = C:\bea\WEBLOG~2
sun.arch.data.model = 32
sun.boot.class.path = C:\bea\JDK150~1\jre\lib\rt.jar;C:\bea\JDK150~1\jre\lib\i18n.jar;C:\bea\JDK150~1\jre\lib\sunrsasign.jar;C:\bea\JDK150~1\jre\lib\jsse.jar;C:\bea\JDK150~1\jre\lib\jce.jar;C:\bea\JDK150~1\jre\lib\charsets.jar;C:\bea\JDK150~1\jre\classes
sun.boot.library.path = C:\bea\JDK150~1\jre\bin
sun.cpu.endian = little
sun.desktop = windows
sun.io.unicode.encoding = UnicodeLittle
sun.jnu.encoding = Cp1252
sun.management.compiler = HotSpot Client Compiler
sun.os.patch.level = Service Pack 2
user.country = CH
user.dir = E:\projects\beadomains\dev91
user.home = \\Merlin\Sevel$
user.language = fr
user.name = Sevel
user.timezone = Europe/Berlin
vde.home = .\servers\AdminServer\data\ldap
weblogic.Name = AdminServer
weblogic.classloader.preprocessor = weblogic.diagnostics.instrumentation.DiagnosticClassPreProcessor
weblogic.debug.DebugJTA2PC = true
weblogic.ext.dirs = C:\bea\patch_weblogic910\profiles\default\sysext_manifest_classpath
weblogic.log.StdoutSeverity = Debug
weblogic.management.discover = true
weblogic.wsee.verbose = *
wli.home = C:\bea\WEBLOG~2\integration
wls.home = C:\bea\WEBLOG~2\server
>
<12 mai 2006 11.04. h CEST> <Notice> <WebLogicServer> <BEA-000365> <Server state changed to STANDBY>
<12 mai 2006 11.04. h CEST> <Notice> <WebLogicServer> <BEA-000365> <Server state changed to STARTING>
<12 mai 2006 11.04. h CEST> <Info> <Deployer> <BEA-149209> <Resuming.>
<12 mai 2006 11.04. h CEST> <Info> <J2EE> <BEA-160151> <Registered library Extension-Name: bea_wls_async_response (EJB).>
<12 mai 2006 11.04. h CEST> <Info> <EJB> <BEA-010008> <EJB Deploying file: bea_wls_remote_deployer.jar>
<WSEE>C:\bea\WEBLOG~2\server\lib\bea_wls_remote_deployer.jar#webservices.xml bean is null<WsEJBDeployListener.doprepare:127>
<WSEE>C:\bea\WEBLOG~2\server\lib\bea_wls_remote_deployer.jar#weblogic-webservices.xml bean is null<WsEJBDeployListener.doprepare:133>
<12 mai 2006 11.04. h CEST> <Info> <J2EE> <BEA-160192> <While upgrading weblogic.xml, encountered "debug" param "jsp-param". This param is unknown and will be removed>
<12 mai 2006 11.04. h CEST> <Info> <J2EE> <BEA-160192> <While upgrading weblogic.xml, encountered "debug" param "jsp-param". This param is unknown and will be removed>
<12 mai 2006 11.04. h CEST> <Info> <EJB> <BEA-010008> <EJB Deploying file: mejb.jar>
<WSEE>C:\bea\WEBLOG~2\server\lib\mejb.jar#webservices.xml bean is null<WsEJBDeployListener.doprepare:127>
<WSEE>C:\bea\WEBLOG~2\server\lib\mejb.jar#weblogic-webservices.xml bean is null<WsEJBDeployListener.doprepare:133>
<12 mai 2006 11.04. h CEST> <Info> <JDBC> <BEA-001177> <Creating Connection Pool named DummyDS, URL = jdbc:hsqldb:E:\workspaces\hibernate\db, Properties = .>
<12 mai 2006 11.04. h CEST> <Info> <Common> <BEA-000626> <Free resources in pool "DummyDS" will be tested every "120" seconds.>
<12 mai 2006 11.04. h CEST> <Info> <JDBC> <BEA-001516> <Connection Pool "DummyDS" connected to Database: "HSQL Database Engine", Version: "1.8.0".>
<12 mai 2006 11.04. h CEST> <Info> <JDBC> <BEA-001517> <Connection Pool "DummyDS" using Driver: "HSQL Database Engine Driver", Version: "1.8.0".>
<12 mai 2006 11.04. h CEST> <Warning> <JDBC> <BEA-001110> <No test table set up for pool "DummyDS". Connections will not be tested.>
<12 mai 2006 11.04. h CEST> <Info> <Common> <BEA-000628> <Created "1" resources for pool "DummyDS", out of which "1" are available and "0" are unavailable.>
<12 mai 2006 11.04. h CEST> <Info> <JDBC> <BEA-001124> <Created Connection Pool named DummyDS.>
<12 mai 2006 11.04. h CEST> <Info> <JDBC> <BEA-001174> <Creating Data Source named DummyDS, JNDI Name = jdbc/DummyDS.>
<12 mai 2006 11.04. h CEST> <Info> <JDBC> <BEA-001512> <Data Source DummyDS has been successfully created.>
<12 mai 2006 11.04. h CEST> <Info> <SAFService> <BEA-281007> <SAF Agent "SAFAgent-0@AdminServer" has been successfully prepared.>
<12 mai 2006 11.04. h CEST> <Info> <JMS> <BEA-040321> <JMSServer "SAFAgent-0@AdminServer" is resuming.>
<12 mai 2006 11.04. h CEST> <Info> <Messaging> <BEA-282003> <The messaging kernel SAFAgent-0@AdminServer will use up to 177'580'714 bytes of memory before paging begins>
<12 mai 2006 11.04. h CEST> <Info> <Store> <BEA-280050> <Persistent store "SAFAgent-0@AdminServer" opened: directory=".\servers\AdminServer\tmp" writePolicy="Non-Durable" blockSize=512 directIO=false driver="wlfileio2">
<12 mai 2006 11.04. h CEST> <Info> <Messaging> <BEA-282001> <The messaging kernel SAFAgent-0@AdminServer is beginning recovery of its persistent state>
<12 mai 2006 11.04. h CEST> <Info> <Messaging> <BEA-282002> <The messaging kernel SAFAgent-0@AdminServer has recovered 0 persistent messages>
<12 mai 2006 11.04. h CEST> <Info> <SAFService> <BEA-281008> <SAF Agent "SAFAgent-0@AdminServer" has been successfully activated.>
<12 mai 2006 11.04. h CEST> <Info> <JMS> <BEA-040321> <JMSServer "JMSServer-0" is resuming.>
<12 mai 2006 11.04. h CEST> <Info> <Messaging> <BEA-282003> <The messaging kernel JMSServer-0 will use up to 177'580'714 bytes of memory before paging begins>
<12 mai 2006 11.04. h CEST> <Info> <Store> <BEA-280050> <Persistent store "JMSServer-0" opened: directory=".\servers\AdminServer\tmp" writePolicy="Non-Durable" blockSize=512 directIO=false driver="wlfileio2">
<12 mai 2006 11.04. h CEST> <Info> <Messaging> <BEA-282001> <The messaging kernel JMSServer-0 is beginning recovery of its persistent state>
<12 mai 2006 11.04. h CEST> <Info> <Messaging> <BEA-282002> <The messaging kernel JMSServer-0 has recovered 0 persistent messages>
<12 mai 2006 11.04. h CEST> <Info> <JMS> <BEA-040108> <User connection factory "SystemModule-0!ConnectionFactory-0" is started.>
<12 mai 2006 11.04. h CEST> <Info> <JMS> <BEA-040108> <User connection factory "SystemModule-0!WebServiceConnectionFactory" is started.>
<12 mai 2006 11.04. h CEST> <Info> <JMS> <BEA-040404> <Foreign JMS Server "ForeignServer-0 BOA" is started.>
<12 mai 2006 11.04. h CEST> <Info> <Deployer> <BEA-149059> <Module test2Services-V01.01.00.jar of application test2App-V01.01.00 is transitioning from STATE_NEW to STATE_PREPARED on server AdminServer.>
<12 mai 2006 11.04. h CEST> <Info> <EJB> <BEA-010008> <EJB Deploying file: test2Services-V01.01.00.jar>
<WSEE>E:\projects\beadomains\dev91\.\servers\AdminServer\tmp\_WL_user\test2App-V01.01.00\krf4h5\test2Services-V01.01.00.jar#webservices.xml bean is null<WsEJBDeployListener.doprepare:127>
<WSEE>E:\projects\beadomains\dev91\.\servers\AdminServer\tmp\_WL_user\test2App-V01.01.00\krf4h5\test2Services-V01.01.00.jar#weblogic-webservices.xml bean is null<WsEJBDeployListener.doprepare:133>
<12 mai 2006 11.04. h CEST> <Info> <Deployer> <BEA-149060> <Module test2Services-V01.01.00.jar of application test2App-V01.01.00 successfully transitioned from STATE_NEW to STATE_PREPARED on server AdminServer.>
<12 mai 2006 11.04. h CEST> <Info> <Deployer> <BEA-149059> <Module test2Web of application test2App-V01.01.00 is transitioning from STATE_NEW to STATE_PREPARED on server AdminServer.>
<12 mai 2006 11.04. h CEST> <Info> <Deployer> <BEA-149060> <Module test2Web of application test2App-V01.01.00 successfully transitioned from STATE_NEW to STATE_PREPARED on server AdminServer.>
<12 mai 2006 11.04. h CEST> <Info> <EJB> <BEA-010009> <EJB Deployed EJB with JNDI name weblogic.remote.Deployer.>
<12 mai 2006 11.04. h CEST> <Info> <EJB> <BEA-010009> <EJB Deployed EJB with JNDI name ejb.mgmt.MEJB.>
<12 mai 2006 11.04. h CEST> <Info> <Deployer> <BEA-149059> <Module test2Services-V01.01.00.jar of application test2App-V01.01.00 is transitioning from STATE_PREPARED to STATE_ADMIN on server AdminServer.>
<12 mai 2006 11.04. h CEST> <Info> <EJB> <BEA-010009> <EJB Deployed EJB with JNDI name ejb/session/DummyServicesRemoteHome.>
<12 mai 2006 11.04. h CEST> <Info> <Deployer> <BEA-149060> <Module test2Services-V01.01.00.jar of application test2App-V01.01.00 successfully transitioned from STATE_PREPARED to STATE_ADMIN on server AdminServer.>
<12 mai 2006 11.04. h CEST> <Info> <Deployer> <BEA-149059> <Module test2Web of application test2App-V01.01.00 is transitioning from STATE_PREPARED to STATE_ADMIN on server AdminServer.>
<12 mai 2006 11.04. h CEST> <Info> <Deployer> <BEA-149060> <Module test2Web of application test2App-V01.01.00 successfully transitioned from STATE_PREPARED to STATE_ADMIN on server AdminServer.>
initializing log4j from file:/E:/projects/beadomains/dev91/properties/dummy_log4j.properties
file:/E:/projects/beadomains/dev91/properties/dummy_log4j.properties
log4j: Parsing for [root] with value=[WARN, stdout].
log4j: Level token is [WARN].
log4j: Category root set to WARN
log4j: Parsing appender named "stdout".
log4j: Parsing layout options for "stdout".
log4j: Setting property [conversionPattern] to [%d %-5p %c - %m%n].
log4j: End of parsing for "stdout".
log4j: Parsed "stdout" options.
log4j: Parsing for [javax.persistence] with value=[DEBUG].
log4j: Level token is [DEBUG].
log4j: Category javax.persistence set to DEBUG
log4j: Handling log4j.additivity.javax.persistence=[null]
log4j: Parsing for [org.hibernate] with value=[DEBUG].
log4j: Level token is [DEBUG].
log4j: Category org.hibernate set to DEBUG
log4j: Handling log4j.additivity.org.hibernate=[null]
log4j: Parsing for [com.lodh] with value=[DEBUG].
log4j: Level token is [DEBUG].
log4j: Category com.lodh set to DEBUG
log4j: Handling log4j.additivity.com.lodh=[null]
log4j: Finished configuring.
2006-05-12 11:04:38,768 DEBUG com.lodh.dummy.DummyListener - log4j initialized
2006-05-12 11:04:38,784 DEBUG com.lodh.util.properties.search.ClasspathSearcher - START: Search properties in EAR (or jar for standalone application) with pattern 'com/lodh/.*properties'
2006-05-12 11:04:38,815 DEBUG com.lodh.util.properties.search.ResourceSearcher - Found fileName = E:\projects\beadomains\dev91\.\servers\AdminServer\tmp\_WL_user\test2App-V01.01.00\krf4h5/com/lodh/properties/application.properties
2006-05-12 11:04:38,955 DEBUG com.lodh.util.properties.search.ResourceSearcher - Found fileName = E:\projects\beadomains\dev91\servers\AdminServer\tmp\_WL_user\test2App-V01.01.00\krf4h5/com/lodh/properties/application.properties
2006-05-12 11:04:39,034 DEBUG com.lodh.util.properties.search.ClasspathSearcher - END: Search properties in EAR. found : 2 element(s) in CP
2006-05-12 11:04:39,049 INFO com.lodh.util.properties.LodhProperties - *** Reading static properties from file 'com/lodh/properties/application.properties'
2006-05-12 11:04:39,065 INFO com.lodh.util.properties.LodhProperties - *** Reading static properties from file 'com/lodh/properties/application.properties'
2006-05-12 11:04:39,065 DEBUG com.lodh.util.properties.search.ClasspathSearcher - START: Search properties in EAR (or jar for standalone application) with pattern 'META-INF/application.xml'
2006-05-12 11:04:39,205 DEBUG com.lodh.util.properties.search.ResourceSearcher - Found fileName = E:\projects\beadomains\dev91\.\servers\AdminServer\tmp\_WL_user\test2App-V01.01.00\krf4h5/META-INF/application.xml
2006-05-12 11:04:39,315 DEBUG com.lodh.util.properties.search.ResourceSearcher - Found fileName = E:\projects\beadomains\dev91\servers\AdminServer\tmp\_WL_user\test2App-V01.01.00\krf4h5/META-INF/application.xml
2006-05-12 11:04:39,315 DEBUG com.lodh.util.properties.search.ClasspathSearcher - END: Search properties in EAR. found : 2 element(s) in CP
2006-05-12 11:04:39,315 INFO com.lodh.util.properties.PropertiesManager - This propertiesManager is located in an EAR.
2006-05-12 11:04:39,315 INFO com.lodh.util.properties.PropertiesManager - This propertiesManager is located in an EAR.
2006-05-12 11:04:39,315 DEBUG com.lodh.util.properties.search.ClasspathSearcher - START: Search properties in EAR (or jar for standalone application) with pattern 'WEB-INF/web.xml'
2006-05-12 11:04:39,330 DEBUG com.lodh.util.properties.search.ClasspathSearcher - END: Search properties in EAR. found : 0 element(s) in CP
2006-05-12 11:04:39,330 DEBUG com.lodh.util.properties.PropertiesManager - 2 static properties files added
2006-05-12 11:04:39,330 DEBUG com.lodh.util.properties.search.ClasspathSearcher - START: Search properties in global CP with pattern 'test2/.*properties'
2006-05-12 11:04:39,330 DEBUG com.lodh.util.properties.search.ClasspathSearcher - END: Search properties in global CP. found : 0 element(s) in CP
2006-05-12 11:04:39,330 DEBUG com.lodh.util.properties.PropertiesManager - 0 dynamic properties files added
2006-05-12 11:04:39,362 INFO com.lodh.commons.naming.NamingManager - Trying to lookup JNDI 'weblogic.management.home.localhome' on server 'null' with principal 'null'
2006-05-12 11:04:41,862 INFO com.lodh.util.mbean.MbeanManager - Mbean 'com.lodh.util.mbean.DynamicPropertiesManagement' with objectName 'com.lodh.jee:Name=DynamicPropertiesManagement,Type=Config,App=test2' has been registered.
2006-05-12 11:04:41,862 INFO com.lodh.util.properties.PropertiesListener - Application has started
<12 mai 2006 11.04. h CEST> <Info> <Diagnostics> <BEA-320000> <The Diagnostics subsystem is initializing on Server AdminServer.>
<12 mai 2006 11.04. h CEST> <Info> <Log Management> <BEA-170017> <The log file E:\projects\beadomains\dev91\servers\AdminServer\logs\dev91.log will be rotated. Reopen the log file if tailing has stopped. This can happen on some platforms like Windows.>
<12 mai 2006 11.04. h CEST> <Info> <Log Management> <BEA-170018> <The log file has been rotated to E:\projects\beadomains\dev91\servers\AdminServer\logs\dev91.log00036. Log messages will continue to be logged in E:\projects\beadomains\dev91\servers\AdminServer\logs\dev91.log.>
<12 mai 2006 11.04. h CEST> <Info> <Log Management> <BEA-170025> <Initialized Domain Logging. Domain log events will be written to .\servers\AdminServer\logs/dev91.log.>
<12 mai 2006 11.04. h CEST> <Notice> <Log Management> <BEA-170027> <The server initialized the domain log broadcaster successfully. Log messages will now be broadcasted to the domain log.>
<12 mai 2006 11.04. h CEST> <Info> <Store> <BEA-280050> <Persistent store "WLS_DIAGNOSTICS" opened: directory="E:\projects\beadomains\dev91\.\servers\AdminServer\data\store\diagnostics" writePolicy="Disabled" blockSize=512 directIO=false driver="wlfileio2">
<12 mai 2006 11.04. h CEST> <Info> <netuix> <BEA-423101> <[] Initializing the NetUIx container>
<12 mai 2006 11.04. h CEST> <Info> <Diagnostics> <BEA-320077> <Initialized the Diagnostic Accessor Service.>
<12 mai 2006 11.04. h CEST> <Info> <JMX> <BEA-149512> <JMX Connector Server started at service:jmx:iiop://10.23.130.96:7001/jndi/weblogic.management.mbeanservers.domainruntime .>
<12 mai 2006 11.04. h CEST> <Notice> <WebLogicServer> <BEA-000365> <Server state changed to ADMIN>
<12 mai 2006 11.04. h CEST> <Notice> <WebLogicServer> <BEA-000365> <Server state changed to RESUMING>
<12 mai 2006 11.04. h CEST> <Info> <Deployer> <BEA-149059> <Module test2Services-V01.01.00.jar of application test2App-V01.01.00 is transitioning from STATE_ADMIN to STATE_ACTIVE on server AdminServer.>
<12 mai 2006 11.04. h CEST> <Info> <Deployer> <BEA-149060> <Module test2Services-V01.01.00.jar of application test2App-V01.01.00 successfully transitioned from STATE_ADMIN to STATE_ACTIVE on server AdminServer.>
<12 mai 2006 11.04. h CEST> <Info> <Deployer> <BEA-149059> <Module test2Web of application test2App-V01.01.00 is transitioning from STATE_ADMIN to STATE_ACTIVE on server AdminServer.>
<12 mai 2006 11.04. h CEST> <Info> <Deployer> <BEA-149060> <Module test2Web of application test2App-V01.01.00 successfully transitioned from STATE_ADMIN to STATE_ACTIVE on server AdminServer.>
<12 mai 2006 11.04. h CEST> <Info> <Management> <BEA-141052> <The auto deployment poller has started.>
<12 mai 2006 11.04. h CEST> <Info> <JMS> <BEA-040010> <JMSServer "JMSServer-0" configured no session pools.>
<12 mai 2006 11.04. h CEST> <Info> <JMS> <BEA-040109> <JMSServer "JMSServer-0" is started.>
<12 mai 2006 11.04. h CEST> <Info> <JMS> <BEA-040109> <JMSServer "SAFAgent-0@AdminServer" is started.>
<12 mai 2006 11.04. h CEST> <Info> <Server> <BEA-002610> <Dynamic Listener Service initialized.>
<12 mai 2006 11.04. h CEST> <Notice> <Server> <BEA-002613> <Channel "Default[1]" is now listening on 127.0.0.1:7001 for protocols iiop, t3, ldap, http.>
<12 mai 2006 11.04. h CEST> <Notice> <Server> <BEA-002613> <Channel "Default" is now listening on 10.23.130.96:7001 for protocols iiop, t3, ldap, http.>
<12 mai 2006 11.04. h CEST> <Notice> <WebLogicServer> <BEA-000331> <Started WebLogic Admin Server "AdminServer" for domain "dev91" running in Development Mode>
<12 mai 2006 11.04. h CEST> <Notice> <WebLogicServer> <BEA-000365> <Server state changed to RUNNING>
<12 mai 2006 11.04. h CEST> <Notice> <WebLogicServer> <BEA-000360> <Server started in RUNNING mode>
<12 mai 2006 11.04. h CEST> <Debug> <JTA2PC> <000000> <Xid=BEA1-0000F64804BE(30219854),Status=Active,numRepliesOwedMe=0,numRepliesOwedOthers=0,seconds since begin=0,seconds left=-1147424693) wakeUpAfterSeconds(300)>
<12 mai 2006 11.04. h CEST> <Debug> <JTA2PC> <000000> <BEA1-0000F64804BE: null: init(t/o=300,ttl=300)>
<12 mai 2006 11.04. h CEST> <Debug> <JTA2PC> <000000> <BEA1-0000F64804BE: null: setProperty: weblogic.transaction.name=[EJB com.lodh.dummy.ejb.session.DummyServicesBean.sayHello()]>
<12 mai 2006 11.04. h CEST> <Debug> <JTA2PC> <000000> <BEA1-0000F64804BE: null: setName: [EJB com.lodh.dummy.ejb.session.DummyServicesBean.sayHello()]>
<12 mai 2006 11.04. h CEST> <Debug> <JTA2PC> <000000> <BEA1-0000F64804BE: [EJB com.lodh.dummy.ejb.session.DummyServicesBean.sayHello()]: registerSync:weblogic.ejb.container.internal.TxManager$TxListener@e54538 15025464>
<12 mai 2006 11.04. h CEST> <Debug> <JTA2PC> <000000> <BEA1-0000F64804BE: [EJB com.lodh.dummy.ejb.session.DummyServicesBean.sayHello()]: setCoordinatorURL =>ServerCoordinatorDescriptor=(CoordinatorURL=AdminServer+10.23.130.96:7001+dev91+t3+, XAResources={WLStore_dev91__WLS_AdminServer},NonXAResources={})>
<12 mai 2006 11.04. h CEST> <Debug> <JTA2PC> <000000> <BEA1-0000F64804BE82B8D686: [EJB com.lodh.dummy.ejb.session.DummyServicesBean.sayHello()]: Before ServerSCInfo.addSync, Number of Sync:0>
<12 mai 2006 11.04. h CEST> <Debug> <JTA2PC> <000000> <BEA1-0000F64804BE82B8D686: [EJB com.lodh.dummy.ejb.session.DummyServicesBean.sayHello()]: After ServerSCInfo.addSync, Number of Sync:1>
2006-05-12 11:04:53,815 DEBUG com.lodh.dummy.ejb.session.DummyServicesBean - creating factory
2006-05-12 11:04:53,862 INFO org.hibernate.ejb.Version - Hibernate EntityManager 3.1.0.Beta8b
2006-05-12 11:04:53,877 INFO org.hibernate.cfg.annotations.Version - Hibernate Annotations 3.1.0.Beta10b
2006-05-12 11:04:53,908 INFO org.hibernate.cfg.Environment - Hibernate 3.2 cr2
2006-05-12 11:04:53,908 INFO org.hibernate.cfg.Environment - hibernate.properties not found
2006-05-12 11:04:53,924 INFO org.hibernate.cfg.Environment - Bytecode provider name : cglib
2006-05-12 11:04:53,924 INFO org.hibernate.cfg.Environment - using JDK 1.4 java.sql.Timestamp handling
2006-05-12 11:04:54,127 DEBUG org.hibernate.ejb.Ejb3Configuration - Trying to find persistence unit: sample
2006-05-12 11:04:54,127 DEBUG org.hibernate.ejb.Ejb3Configuration - Analyse of persistence.xml: zip:E:/projects/beadomains/dev91/./servers/AdminServer/tmp/_WL_user/test2App-V01.01.00/krf4h5/test2Services-V01.01.00.jar!/META-INF/persistence.xml
2006-05-12 11:04:54,174 DEBUG org.hibernate.util.DTDEntityResolver - trying to resolve system-id [http://java.sun.com/xml/ns/persistence/persistence_1_0.xsd]
2006-05-12 11:04:54,174 DEBUG org.hibernate.cfg.EJB3DTDEntityResolver - recognized EJB3 ORM namespace; attempting to resolve on classpath under org/hibernate/ejb
2006-05-12 11:04:54,190 DEBUG org.hibernate.cfg.EJB3DTDEntityResolver - located [http://java.sun.com/xml/ns/persistence/persistence_1_0.xsd] in classpath
2006-05-12 11:04:54,221 DEBUG org.hibernate.ejb.packaging.PersistenceXmlLoader - Persistent Unit name from persistence.xml: sample
2006-05-12 11:04:54,221 DEBUG org.hibernate.ejb.Ejb3Configuration - Detect class: true; detect hbm: true
2006-05-12 11:04:54,252 DEBUG org.hibernate.ejb.Ejb3Configuration - Archive to be processed by hibernate Entity Manager implementation found
2006-05-12 11:04:54,252 DEBUG org.hibernate.ejb.packaging.JarVisitor - Searching mapped entities in jar/par: zip:E:/projects/beadomains/dev91/./servers/AdminServer/tmp/_WL_user/test2App-V01.01.00/krf4h5/test2Services-V01.01.00.jar
2006-05-12 11:04:54,252 DEBUG org.hibernate.ejb.Ejb3Configuration - Persistence unit name: sample
2006-05-12 11:04:54,252 DEBUG org.hibernate.ejb.Ejb3Configuration - emname:sample metadata: sample
2006-05-12 11:04:54,252 WARN org.hibernate.ejb.packaging.InputStreamZippedJarVisitor - Unable to find file (ignored): zip:E:/projects/beadomains/dev91/./servers/AdminServer/tmp/_WL_user/test2App-V01.01.00/krf4h5/test2Services-V01.01.00.jar
java.io.FileNotFoundException: zip:E:/projects/beadomains/dev91/./servers/AdminServer/tmp/_WL_user/test2App-V01.01.00/krf4h5/test2Services-V01.01.00.jar
at weblogic.utils.zip.ZipURLConnection.connect(Handler.java:78)
at weblogic.utils.zip.ZipURLConnection.getInputStream(Handler.java:53)
at java.net.URL.openStream(URL.java:1007)
at org.hibernate.ejb.packaging.InputStreamZippedJarVisitor.doProcessElements(InputStreamZippedJarVisitor.java:33)
at org.hibernate.ejb.packaging.JarVisitor.getMatchingEntries(JarVisitor.java:208)
at org.hibernate.ejb.Ejb3Configuration.addMetadataFromVisitor(Ejb3Configuration.java:201)
at org.hibernate.ejb.Ejb3Configuration.createEntityManagerFactory(Ejb3Configuration.java:183)
at org.hibernate.ejb.HibernatePersistence.createEntityManagerFactory(HibernatePersistence.java:114)
at com.lodh.dummy.ejb.session.DummyServicesBean.<clinit>(DummyServicesBean.java:41)
at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:39)
at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:27)
at java.lang.reflect.Constructor.newInstance(Constructor.java:494)
at java.lang.Class.newInstance0(Class.java:350)
at java.lang.Class.newInstance(Class.java:303)
at weblogic.ejb.container.manager.BaseEJBManager.allocateBean(BaseEJBManager.java:196)
at weblogic.ejb.container.manager.StatelessManager.createBean(StatelessManager.java:292)
at weblogic.ejb.container.pool.StatelessSessionPool.createBean(StatelessSessionPool.java:168)
at weblogic.ejb.container.pool.StatelessSessionPool.getBean(StatelessSessionPool.java:104)
at weblogic.ejb.container.manager.StatelessManager.preInvoke(StatelessManager.java:143)
at weblogic.ejb.container.internal.BaseEJBObject.preInvoke(BaseEJBObject.java:213)
at weblogic.ejb.container.internal.StatelessEJBObject.preInvoke(StatelessEJBObject.java:64)
at com.lodh.dummy.ejb.session.DummyServices_e0s58w_EOImpl.sayHello(DummyServices_e0s58w_EOImpl.java:41)
at com.lodh.dummy.ejb.session.DummyServices_e0s58w_EOImpl_WLSkel.invoke(Unknown Source)
at weblogic.rmi.internal.BasicServerRef.invoke(BasicServerRef.java:517)
at weblogic.rmi.cluster.ClusterableServerRef.invoke(ClusterableServerRef.java:224)
at weblogic.rmi.internal.BasicServerRef$1.run(BasicServerRef.java:407)
at weblogic.security.acl.internal.AuthenticatedSubject.doAs(AuthenticatedSubject.java:363)
at weblogic.security.service.SecurityManager.runAs(SecurityManager.java:147)
at weblogic.rmi.internal.BasicServerRef.handleRequest(BasicServerRef.java:403)
at weblogic.rmi.internal.BasicServerRef.access$300(BasicServerRef.java:56)
at weblogic.rmi.internal.BasicServerRef$BasicExecuteRequest.run(BasicServerRef.java:934)
at weblogic.work.ExecuteThread.execute(ExecuteThread.java:207)
at weblogic.work.ExecuteThread.run(ExecuteThread.java:179)
2006-05-12 11:04:54,362 DEBUG org.hibernate.ejb.Ejb3Configuration - Creating Factory: sample
2006-05-12 11:04:54,440 DEBUG org.hibernate.cfg.AnnotationConfiguration - Execute first pass mapping processing
2006-05-12 11:04:54,471 DEBUG org.hibernate.cfg.AnnotationConfiguration - Process hbm files
2006-05-12 11:04:54,471 DEBUG org.hibernate.cfg.AnnotationConfiguration - Process annotated classes
2006-05-12 11:04:54,502 INFO org.hibernate.cfg.AnnotationBinder - Binding entity from annotated class: com.lodh.project.ejb.entity.Translation
2006-05-12 11:04:54,549 DEBUG org.hibernate.cfg.Ejb3Column - Binding column DTYPE unique false
2006-05-12 11:04:54,596 DEBUG org.hibernate.cfg.annotations.EntityBinder - Import with entity name=Translation
2006-05-12 11:04:54,612 INFO org.hibernate.cfg.annotations.EntityBinder - Bind entity com.lodh.project.ejb.entity.Translation on table Translatn
2006-05-12 11:04:54,627 DEBUG org.hibernate.cfg.AnnotationBinder - Processing com.lodh.project.ejb.entity.Translation property annotation
2006-05-12 11:04:54,690 DEBUG org.hibernate.cfg.AnnotationBinder - Processing annotations of com.lodh.project.ejb.entity.Translation.id
2006-05-12 11:04:54,690 DEBUG org.hibernate.cfg.Ejb3Column - Binding column id unique false
2006-05-12 11:04:54,690 DEBUG org.hibernate.cfg.AnnotationBinder - id is an id
2006-05-12 11:04:54,705 DEBUG org.hibernate.cfg.annotations.SimpleValueBinder - building SimpleValue for id
2006-05-12 11:04:54,721 DEBUG org.hibernate.cfg.annotations.PropertyBinder - Building property id
2006-05-12 11:04:54,721 DEBUG org.hibernate.cfg.annotations.PropertyBinder - Cascading id with null
2006-05-12 11:04:54,721 DEBUG org.hibernate.cfg.AnnotationBinder - Bind @Id on id
2006-05-12 11:04:54,721 DEBUG org.hibernate.cfg.AnnotationBinder - Processing annotations of com.lodh.project.ejb.entity.Translation.into
2006-05-12 11:04:54,721 DEBUG org.hibernate.cfg.Ejb3Column - Binding column country_into unique false
2006-05-12 11:04:54,721 DEBUG org.hibernate.cfg.annotations.PropertyBinder - binding property into with lazy=false
2006-05-12 11:04:54,721 DEBUG org.hibernate.cfg.annotations.SimpleValueBinder - building SimpleValue for into
2006-05-12 11:04:54,721 DEBUG org.hibernate.cfg.annotations.PropertyBinder - Building property into
2006-05-12 11:04:54,721 DEBUG org.hibernate.cfg.annotations.PropertyBinder - Cascading into with null
2006-05-12 11:04:54,737 DEBUG org.hibernate.cfg.AnnotationConfiguration - processing manytoone fk mappings
2006-05-12 11:04:54,752 DEBUG org.hibernate.cfg.Configuration - processing extends queue
2006-05-12 11:04:54,752 DEBUG org.hibernate.cfg.Configuration - processing collection mappings
2006-05-12 11:04:54,752 DEBUG org.hibernate.cfg.Configuration - processing native query and ResultSetMapping mappings
2006-05-12 11:04:54,752 DEBUG org.hibernate.cfg.Configuration - processing association property references
2006-05-12 11:04:54,752 DEBUG org.hibernate.cfg.Configuration - processing foreign key constraints
2006-05-12 11:04:54,768 DEBUG org.hibernate.validator.ClassValidator - ResourceBundle ValidatorMessages not found in thread context classloader
2006-05-12 11:04:54,768 DEBUG org.hibernate.validator.ClassValidator - ResourceBundle ValidatorMessages not found in Validator classloader. Delegate to org.hibernate.validator.resources.DefaultValidatorMessages
2006-05-12 11:04:54,815 DEBUG org.hibernate.cfg.Configuration - Preparing to build session factory with filters : {}
2006-05-12 11:04:54,815 DEBUG org.hibernate.cfg.AnnotationConfiguration - Execute first pass mapping processing
2006-05-12 11:04:54,815 DEBUG org.hibernate.cfg.AnnotationConfiguration - Process hbm files
2006-05-12 11:04:54,815 DEBUG org.hibernate.cfg.AnnotationConfiguration - Process annotated classes
2006-05-12 11:04:54,815 DEBUG org.hibernate.cfg.AnnotationConfiguration - processing manytoone fk mappings
2006-05-12 11:04:54,815 DEBUG org.hibernate.cfg.Configuration - processing extends queue
2006-05-12 11:04:54,815 DEBUG org.hibernate.cfg.Configuration - processing collection mappings
2006-05-12 11:04:54,815 DEBUG org.hibernate.cfg.Configuration - processing native query and ResultSetMapping mappings
2006-05-12 11:04:54,815 DEBUG org.hibernate.cfg.Configuration - processing association property references
2006-05-12 11:04:54,815 DEBUG org.hibernate.cfg.Configuration - processing foreign key constraints
2006-05-12 11:04:54,815 DEBUG org.hibernate.validator.ClassValidator - ResourceBundle ValidatorMessages not found in thread context classloader
2006-05-12 11:04:54,815 DEBUG org.hibernate.validator.ClassValidator - ResourceBundle ValidatorMessages not found in Validator classloader. Delegate to org.hibernate.validator.resources.DefaultValidatorMessages
2006-05-12 11:04:55,002 INFO org.hibernate.util.NamingHelper - JNDI InitialContext properties:{}
2006-05-12 11:04:55,002 INFO org.hibernate.connection.DatasourceConnectionProvider - Using datasource: jdbc/DummyDS
2006-05-12 11:04:55,127 DEBUG org.hibernate.cfg.SettingsFactory - could not get database version from JDBC metadata
2006-05-12 11:04:55,127 INFO org.hibernate.cfg.SettingsFactory - RDBMS: HSQL Database Engine, version: 1.8.0
2006-05-12 11:04:55,127 INFO org.hibernate.cfg.SettingsFactory - JDBC driver: HSQL Database Engine Driver, version: 1.8.0
2006-05-12 11:04:55,174 INFO org.hibernate.dialect.Dialect - Using dialect: org.hibernate.dialect.HSQLDialect
2006-05-12 11:04:55,190 INFO org.hibernate.transaction.TransactionFactoryFactory - Transaction strategy: org.hibernate.ejb.transaction.JoinableCMTTransactionFactory
2006-05-12 11:04:55,190 INFO org.hibernate.transaction.TransactionManagerLookupFactory - instantiating TransactionManagerLookup: org.hibernate.transaction.WeblogicTransactionManagerLookup
2006-05-12 11:04:55,190 INFO org.hibernate.transaction.TransactionManagerLookupFactory - instantiated TransactionManagerLookup
2006-05-12 11:04:55,190 INFO org.hibernate.cfg.SettingsFactory - Automatic flush during beforeCompletion(): disabled
2006-05-12 11:04:55,205 INFO org.hibernate.cfg.SettingsFactory - Automatic session close at end of transaction: disabled
2006-05-12 11:04:55,205 INFO org.hibernate.cfg.SettingsFactory - JDBC batch size: 15
2006-05-12 11:04:55,205 INFO org.hibernate.cfg.SettingsFactory - JDBC batch updates for versioned data: disabled
2006-05-12 11:04:55,205 INFO org.hibernate.cfg.SettingsFactory - Scrollable result sets: enabled
2006-05-12 11:04:55,205 DEBUG org.hibernate.cfg.SettingsFactory - Wrap result sets: disabled
2006-05-12 11:04:55,205 INFO org.hibernate.cfg.SettingsFactory - JDBC3 getGeneratedKeys(): disabled
2006-05-12 11:04:55,205 INFO org.hibernate.cfg.SettingsFactory - Connection release mode: auto
2006-05-12 11:04:55,205 INFO org.hibernate.cfg.SettingsFactory - Maximum outer join fetch depth: 3
2006-05-12 11:04:55,205 INFO org.hibernate.cfg.SettingsFactory - Default batch fetch size: 1
2006-05-12 11:04:55,205 INFO org.hibernate.cfg.SettingsFactory - Generate SQL with comments: disabled
2006-05-12 11:04:55,205 INFO org.hibernate.cfg.SettingsFactory - Order SQL updates by primary key: disabled
2006-05-12 11:04:55,205 INFO org.hibernate.cfg.SettingsFactory - Query translator: org.hibernate.hql.ast.ASTQueryTranslatorFactory
2006-05-12 11:04:55,205 INFO org.hibernate.hql.ast.ASTQueryTranslatorFactory - Using ASTQueryTranslatorFactory
2006-05-12 11:04:55,221 INFO org.hibernate.cfg.SettingsFactory - Query language substitutions: {}
2006-05-12 11:04:55,221 INFO org.hibernate.cfg.SettingsFactory - Second-level cache: enabled
2006-05-12 11:04:55,221 INFO org.hibernate.cfg.SettingsFactory - Query cache: disabled
2006-05-12 11:04:55,237 INFO org.hibernate.cfg.SettingsFactory - Cache provider: org.hibernate.cache.OSCacheProvider
2006-05-12 11:04:55,237 ERROR com.opensymphony.oscache.base.Config - Error reading /oscache.properties in CacheAdministrator.loadProps() java.lang.NullPointerException
2006-05-12 11:04:55,237 ERROR com.opensymphony.oscache.base.Config - Ensure the /oscache.properties file is readable and in your classpath.
2006-05-12 11:04:55,237 INFO org.hibernate.cfg.SettingsFactory - Optimize cache for minimal puts: disabled
2006-05-12 11:04:55,237 INFO org.hibernate.cfg.SettingsFactory - Structured second-level cache entries: disabled
2006-05-12 11:04:55,237 DEBUG org.hibernate.exception.SQLExceptionConverterFactory - Using dialect defined converter
2006-05-12 11:04:55,252 INFO org.hibernate.cfg.SettingsFactory - Statistics: disabled
2006-05-12 11:04:55,252 INFO org.hibernate.cfg.SettingsFactory - Deleted entity synthetic identifier rollback: disabled
2006-05-12 11:04:55,252 INFO org.hibernate.cfg.SettingsFactory - Default entity-mode: pojo
2006-05-12 11:04:55,283 DEBUG org.hibernate.validator.ClassValidator - ResourceBundle ValidatorMessages not found in thread context classloader
2006-05-12 11:04:55,283 DEBUG org.hibernate.validator.ClassValidator - ResourceBundle ValidatorMessages not found in Validator classloader. Delegate to org.hibernate.validator.resources.DefaultValidatorMessages
2006-05-12 11:04:55,315 INFO org.hibernate.impl.SessionFactoryImpl - building session factory
2006-05-12 11:04:55,315 DEBUG org.hibernate.impl.SessionFactoryImpl - Session factory constructed with filter configurations : {}
2006-05-12 11:04:55,315 DEBUG org.hibernate.impl.SessionFactoryImpl - instantiating session factory with properties: {java.vendor=Sun Microsystems Inc., wli.home=C:\bea\WEBLOG~2\integration, org.xml.sax.parser=weblogic.xml.jaxp.RegistryParser, sun.management.compiler=HotSpot Client Compiler, hibernate.ejb.discard_pc_on_close=false, hibernate.transaction.flush_before_completion=false, os.name=Windows XP, sun.boot.class.path=C:\bea\JDK150~1\jre\lib\rt.jar;C:\bea\JDK150~1\jre\lib\i18n.jar;C:\bea\JDK150~1\jre\lib\sunrsasign.jar;C:\bea\JDK150~1\jre\lib\jsse.jar;C:\bea\JDK150~1\jre\lib\jce.jar;C:\be
a\JDK150~1\jre\lib\charsets.jar;C:\bea\JDK150~1\jre\classes, weblogic.wsee.verbose=*, sun.desktop=windows, java.vm.specification.vendor=Sun Microsystems Inc., java.runtime.version=1.5.0_04-b05, hibernate.connection.autocommit=true, weblogic.Name=AdminServer, hibernate.cache.provider_class=org.hibernate.cache.OSCacheProvider, user.name=Sevel, hibernate.connection.release_mode=auto, user.language=fr, java.naming.factory.initial=weblogic.jndi.WLInitialContextFactory, sun.boot.library.path=C:\bea\JDK150~1\jre\bin, wlw.testConsole=, wlw.iterativeDev=, java.version=1.5.0_04, user.timezone=Europe/Ber
lin, sun.arch.data.model=32, javax.rmi.CORBA.UtilClass=weblogic.iiop.UtilDelegateImpl, weblogic.debug.DebugJTA2PC=true, java.endorsed.dirs=C:\bea\JDK150~1\jre\lib\endorsed, vde.home=.\servers\AdminServer\data\ldap, sun.cpu.isalist=, sun.jnu.encoding=Cp1252, file.encoding.pkg=sun.io, wlw.logErrorsToConsole=, file.separator=\, java.specification.name=Java Platform API Specification, java.class.version=49.0, user.country=CH, java.home=C:\bea\JDK150~1\jre, platform.home=C:\bea\WEBLOG~2, java.vm.info=mixed mode, os.version=5.1, hibernate.transaction.factory_class=org.hibernate.ejb.transaction.Joina
bleCMTTransactionFactory, hibernate.connection.datasource=jdbc/DummyDS, org.omg.CORBA.ORBSingletonClass=weblogic.corba.orb.ORB, path.separator=;, java.vm.version=1.5.0_04-b05, hibernate.max_fetch_depth=3, user.variant=, java.protocol.handler.pkgs=weblogic.utils|weblogic.utils|weblogic.utils|weblogic.net, java.awt.printerjob=sun.awt.windows.WPrinterJob, java.security.policy=C:\bea\WEBLOG~2\server\lib\weblogic.policy, sun.io.unicode.encoding=UnicodeLittle, awt.toolkit=sun.awt.windows.WToolkit, java.naming.factory.url.pkgs=weblogic.jndi.factories:weblogic.corba.j2ee.naming.url:weblogic.jndi.facto
ries:weblogic.corba.j2ee.naming.url, user.home=\\Merlin\Sevel$, wls.home=C:\bea\WEBLOG~2\server, weblogic.log.StdoutSeverity=Debug, java.specification.vendor=Sun Microsystems Inc., org.xml.sax.driver=weblogic.apache.xerces.parsers.SAXParser, hibernate.hbm2ddl.auto=create-drop, java.library.path=C:\bea\JDK150~1\bin;.;C:\WINDOWS\system32;C:\WINDOWS;C:\bea\patch_weblogic910\profiles\default\native;C:\bea\WEBLOG~2\server\native\win\32;C:\bea\WEBLOG~2\server\bin;C:\bea\JDK150~1\jre\bin;C:\bea\JDK150~1\bin;C:\EXTUserAppl\Oracle\V9.2.0\bin;C:\WINDOWS\system32;C:\WINDOWS;C:\WINDOWS\System32\Wbem;C:\LO
CLibrary\Bin;C:\Orbix\Orbix6\asp\6.1\bin;C:\Orbix\Orbix6\etc\bin;C:\Orbix\Orbix6\bin;C:\Program Files\Java\j2re1.4.2_03\bin\client;C:\IONA\Orbix_2.3c02\BIN;C:\Program Files\Schlumberger\Smart Cards and Terminals\Cyberflex Access Kits\v4\;C:\Program Files\Rational\ClearCase\bin;C:\Program Files\Rational\common;C:\Program Files\Oracle\jre\1.1.8\bin;C:\EXTUSerAppl\Oracle\V9.2.0\jre\1.4.2\bin;C:\EXTUSerAppl\Oracle\V9.2.0\jre\1.4.2\bin\client;C:\EXTUserAppl\UltraEdit\;C:\soft\javalib\maven\1.0.2\bin;C:\soft\dev\xmlbeans-2.1.0\bin;C:\bea\WEBLOG~2\server\native\win\32\oci920_8, java.vendor.url=http:/
/java.sun.com/, java.vm.vendor=Sun Microsystems Inc., java.runtime.name=Java(TM) 2 Runtime Environment, Standard Edition, java.class.path=C:\bea\WEBLOG~2\server\ext\jdbc\hsqldb-1.8.0.2.jar;E:\projects\BEADOM~1\dev91\ext\ejb-3.0-public-draft-20060327.jar;E:\projects\BEADOM~1\dev91\properties;C:\bea\patch_weblogic910\profiles\default\sys_manifest_classpath\weblogic_patch.jar;C:\bea\JDK150~1\lib\tools.jar;C:\bea\WEBLOG~2\server\lib\weblogic_sp.jar;C:\bea\WEBLOG~2\server\lib\weblogic.jar;C:\bea\WEBLOG~2\server\lib\webservices.jar;;C:\bea\WEBLOG~2\common\eval\pointbase\lib\pbclient51.jar;C:\bea\WEB
LOG~2\server\lib\xqrl.jar;;C:\bea\WEBLOG~2\integration\lib\util.jar;, hibernate.bytecode.use_reflection_optimizer=false, java.vm.specification.name=Java Virtual Machine Specification, java.vm.specification.version=1.0, javax.rmi.CORBA.PortableRemoteObjectClass=weblogic.iiop.PortableRemoteObjectDelegateImpl, sun.cpu.endian=little, sun.os.patch.level=Service Pack 2, java.io.tmpdir=C:\DOCUME~1\Sevel\LOCALS~1\Temp\, java.vendor.url.bug=http://java.sun.com/cgi-bin/bugreport.cgi, java.awt.graphicsenv=sun.awt.Win32GraphicsEnvironment, os.arch=x86, java.ext.dirs=C:\bea\JDK150~1\jre\lib\ext, user.dir=E
:\projects\beadomains\dev91, line.separator=
, weblogic.ext.dirs=C:\bea\patch_weblogic910\profiles\default\sysext_manifest_classpath, java.vm.name=Java HotSpot(TM) Client VM, javax.xml.soap.MessageFactory=weblogic.webservice.core.soap.MessageFactoryImpl, weblogic.management.discover=true, org.omg.CORBA.ORBClass=weblogic.corba.orb.ORB, file.encoding=Cp1252, hibernate.use_identifier_rollback=false, javax.xml.rpc.ServiceFactory=weblogic.webservice.core.rpc.ServiceFactoryImpl, weblogic.classloader.preprocessor=weblogic.diagnostics.instrumentation.DiagnosticClassPreProcessor, hibernate.transaction.manager_lookup_class=org.hibernate.transactio
n.WeblogicTransactionManagerLookup, weblogic.ProductionModeEnabled=, java.specification.version=1.5}
2006-05-12 11:04:55,924 DEBUG org.hibernate.persister.entity.AbstractEntityPersister - Static SQL for entity: com.lodh.project.ejb.entity.Translation
2006-05-12 11:04:55,924 DEBUG org.hibernate.persister.entity.AbstractEntityPersister - Version select: select id from Translatn where id =?
2006-05-12 11:04:55,924 DEBUG org.hibernate.persister.entity.AbstractEntityPersister - Snapshot select: select translatio_.id, translatio_.country_into as country2_0_ from Translatn translatio_ where translatio_.id=?
2006-05-12 11:04:55,924 DEBUG org.hibernate.persister.entity.AbstractEntityPersister - Insert 0: insert into Translatn (country_into, id) values (?, ?)
2006-05-12 11:04:55,924 DEBUG org.hibernate.persister.entity.AbstractEntityPersister - Update 0: update Translatn set country_into=? where id=?
2006-05-12 11:04:55,924 DEBUG org.hibernate.persister.entity.AbstractEntityPersister - Delete 0: delete from Translatn where id=?
2006-05-12 11:04:55,924 DEBUG org.hibernate.persister.entity.AbstractEntityPersister - Identity insert: insert into Translatn (id, country_into) values (null, ?)
2006-05-12 11:04:56,408 DEBUG org.hibernate.loader.entity.EntityLoader - Static select for entity com.lodh.project.ejb.entity.Translation: select translatio0_.id as id0_0_, translatio0_.country_into as country2_0_0_ from Translatn translatio0_ where translatio0_.id=?
2006-05-12 11:04:56,408 DEBUG org.hibernate.loader.entity.EntityLoader - Static select for entity com.lodh.project.ejb.entity.Translation: select translatio0_.id as id0_0_, translatio0_.country_into as country2_0_0_ from Translatn translatio0_ where translatio0_.id=?
2006-05-12 11:04:56,408 DEBUG org.hibernate.loader.entity.EntityLoader - Static select for entity com.lodh.project.ejb.entity.Translation: select translatio0_.id as id0_0_, translatio0_.country_into as country2_0_0_ from Translatn translatio0_ where translatio0_.id=?
2006-05-12 11:04:56,408