Hibernate 3, JBoss 4.0.2, Oracle 10

Hi, I'm a newbie, trying to get Hibernate working. I am deploying a HAR file, accessing Hibernate in a stateless session bean, trying to use CMT to manage the transaction (my transaction is defined as 'requires').

I inserted 2 rows into a table with JDBC. I can use a Hibernate query to retrieve the rows just fine. When I session.save() a new object, nothing appears in the table, although I don't get an exception. I am guessing (perhaps wrongly) that the container is not commiting the transaction. And I am further perplexed that I don't see any SQL being generated (the setting is set and I do see the SQL for the select).

Here is code for the save, mapping document, debug level log output,
and the hibernate mBean definition. Below that I have included the log output for the query which shows the query to be working. (The difference in the query code and the insert is only changing the commented line from the session.createQuery() to the session.save()).

Please help


Session session = null;
Transaction trans = null;
List l=null;
try
{
InitialContext ic = new InitialContext();
SessionFactory factory = (SessionFactory) ic.lookup
("java:/hibernate/SessionFactory");
session = factory.openSession();
trans = session.beginTransaction();
session.save(ledger);
//l = session.createQuery("from Ledger").list();

trans.commit();
session.close();

return "";
}
catch (Throwable t)
{
throw new SystemException("exception saveing Ledger: "+t.getMessage(),t,"createLedger()");
}



[<hibernate-mapping>

<class name="com.blackdog.gl.domain.Ledger" table="gl_Ledger">
<id name="ledgerId" column="LEDGER_ID" />
<property name="umbrellaCoId" column="UMBRELLA_CO_ID" />
<property name="companyId" column="COMPANY_ID" />
<property name="basis" column="BASIS" />
<property name="seg1" column="ACCT_SEG_1" />
<property name="seg2" column="ACCT_SEG_2" />
<property name="seg3" column="ACCT_SEG_3" />
<property name="seg4" column="ACCT_SEG_4" />
<property name="seg5" column="ACCT_SEG_5" />
<property name="seg6" column="ACCT_SEG_6" />
<property name="seg7" column="ACCT_SEG_7" />
<property name="seg8" column="ACCT_SEG_8" />
<property name="seg9" column="ACCT_SEG_9" />
<property name="seg10" column="ACCT_SEG_10" />
<property name="module" column="MODULE" />
<property name="transId" column="TRANS_ID" />
<property name="description" column="DESCRIPTION" />
<property name="amount" column="AMOUNT" />
<property name="status" column="STATUS" />
<property name="ledgerDate" column="LEDGER_DATE" />
<property name="reversing" column="REVERSING" />
<property name="reversePeriod" column="REV_PERIOD" />
<property name="dateCreated" column="DATE_CREATED" />
<property name="createdBy" column="CREATED_BY" />
<property name="dateModified" column="DATE_MODIFIED" />
<property name="modifiedBy" column="MODIFIED_BY" />
</class>
</hibernate-mapping>


<server>
<mbean code="org.jboss.hibernate.jmx.Hibernate" name="jboss.har:service=Hibernate">
<attribute name="DatasourceName">java:/jdbc/OracleDS</attribute>
<attribute name="Dialect">org.hibernate.dialect.HSQLDialect</attribute>
<attribute "SessionFactoryName">java:/hibernate/SessionFactory</attribute>
<attribute "CacheProviderClass">org.hibernate.cache.HashtableCacheProvider</attribute>
<attribute name="ShowSqlEnabled">True</attribute>
<attribute name="TransactionStrategy">net.sf.hibernate.transaction.JTATransactionFactory</attribute>
<attribute name="TransactionManagerLookupStrategy">net.sf.hibernate.transaction.JBossTransactionManagerLookup</attribute>
</mbean>
</server>


the not working insert(save) log:
14:32:51,390 DEBUG [SessionFactoryObjectFactory] JNDI lookup: hibernate/SessionFactory
14:32:51,390 DEBUG [SessionFactoryObjectFactory] lookup: uid=4028a0c405b119060105b1190e790000
14:32:51,515 DEBUG [JDBCContext] successfully registered Synchronization
14:32:51,515 DEBUG [SessionImpl] opened session at timestamp: 4603731850813440
14:33:00,843 DEBUG [JTATransaction] begin
14:33:00,843 DEBUG [JTATransaction] Looking for UserTransaction under: UserTransaction
14:33:00,859 DEBUG [JTATransaction] Obtained UserTransaction
14:33:03,296 DEBUG [DefaultSaveOrUpdateEventListener] saving transient instance
14:33:03,312 DEBUG [AbstractSaveEventListener] generated identifier: 0, using strategy: org.hibernate.id.Assigned
14:33:03,328 DEBUG [AbstractSaveEventListener] saving [com.blackdog.gl.domain.Ledger#0]
14:33:15,390 DEBUG [JTATransaction] commit
14:33:18,250 DEBUG [SessionImpl] closing session
14:33:22,468 DEBUG [CacheSynchronization] transaction before completion callback
14:33:22,468 DEBUG [CacheSynchronization] automatically flushing session
14:33:22,468 DEBUG [SessionImpl] automatically flushing session
14:33:22,468 DEBUG [JDBCContext] before transaction completion
14:33:22,468 DEBUG [SessionImpl] before transaction completion
14:33:22,468 DEBUG [CacheSynchronization] transaction after completion callback, status: 3
14:33:22,468 DEBUG [JDBCContext] after transaction completion
14:33:22,468 DEBUG [SessionImpl] after transaction completion
14:33:22,468 DEBUG [StatelessSessionContainer] Useless invocation of remove() for stateless session bean
14:33:55,812 DEBUG [JDBCContext] running Session.finalize()
14:35:18,656 DEBUG [IdleRemover] run: IdleRemover notifying pools, interval: 450000
14:42:48,656 DEBUG [IdleRemover] run: IdleRemover notifying pools, interval: 450000
14:50:18,656 DEBUG [IdleRemover] run: IdleRemover notifying pools, interval: 450000



The working query log:


11:43:48,109 DEBUG [SessionFactoryObjectFactory] JNDI lookup: hibernate/SessionFactory
11:43:48,109 DEBUG [SessionFactoryObjectFactory] lookup: uid=4028a0c405b081bf0105b081c7ba0000
11:43:48,359 DEBUG [JDBCContext] successfully registered Synchronization
11:43:48,359 DEBUG [SessionImpl] opened session at timestamp: 4603690304000000
11:44:02,406 DEBUG [JTATransaction] begin
11:44:02,406 DEBUG [JTATransaction] Looking for UserTransaction under: UserTransaction
11:44:02,421 DEBUG [JTATransaction] Obtained UserTransaction
11:44:06,328 DEBUG [SessionImpl] find: from Ledger
11:44:06,343 DEBUG [QueryParameters] named parameters: {}
11:44:06,906 DEBUG [QueryTranslatorImpl] parse() - HQL: from com.blackdog.gl.domain.Ledger
11:44:07,000 DEBUG [AST] --- HQL AST ---
\-[QUERY] 'query'
\-[SELECT_FROM] 'SELECT_FROM'
\-[FROM] 'from'
\-[RANGE] 'RANGE'
\-[DOT] '.'
+-[DOT] '.'
| +-[DOT] '.'
| | +-[DOT] '.'
| | | +-[IDENT] 'com'
| | | \-[IDENT] 'blackdog'
| | \-[IDENT] 'gl'
| \-[IDENT] 'domain'
\-[IDENT] 'Ledger'

11:44:07,031 DEBUG [ErrorCounter] throwQueryException() : no errors
11:44:07,250 DEBUG [HqlSqlBaseWalker] query() << begin, level = 1
11:44:07,390 DEBUG [FromElement] FromClause{level=1} : com.blackdog.gl.domain.Ledger (no alias) -> ledger0_
11:44:07,390 DEBUG [HqlSqlBaseWalker] query() : finishing up , level = 1
11:44:07,390 DEBUG [HqlSqlWalker] processQuery() : ( SELECT ( FromClause{level=1} gl_Ledger ledger0_ ) )
11:44:07,484 DEBUG [HqlSqlWalker] Derived SELECT clause created.
11:44:07,515 DEBUG [JoinProcessor] Using FROM fragment [gl_Ledger ledger0_]
11:44:07,531 DEBUG [HqlSqlBaseWalker] query() >> end, level = 1
11:44:07,562 DEBUG [AST] --- SQL AST ---
\-[SELECT] QueryNode: 'SELECT' querySpaces (gl_Ledger)
+-[SELECT_CLAUSE] SelectClause: '{derived select clause}'
| +-[SELECT_EXPR] SelectExpressionImpl: 'ledger0_.LEDGER_ID as LEDGER1_' {FromElement{explicit,not a collection join,not a fetch join,fetch non-lazy properties,classAlias=null,role=null,tableName=gl_Ledger,tableAlias=ledger0_,colums={,className=com.blackdog.gl.domain.Ledger}}}
| \-[SQL_TOKEN] SqlFragment: 'ledger0_.UMBRELLA_CO_ID as UMBRELLA2_0_, ledger0_.COMPANY_ID as COMPANY3_0_, ledger0_.BASIS as BASIS0_, ledger0_.ACCT_SEG_1 as ACCT5_0_, ledger0_.ACCT_SEG_2 as ACCT6_0_, ledger0_.ACCT_SEG_3 as ACCT7_0_, ledger0_.ACCT_SEG_4 as ACCT8_0_, ledger0_.ACCT_SEG_5 as ACCT9_0_, ledger0_.ACCT_SEG_6 as ACCT10_0_, ledger0_.ACCT_SEG_7 as ACCT11_0_, ledger0_.ACCT_SEG_8 as ACCT12_0_, ledger0_.ACCT_SEG_9 as ACCT13_0_, ledger0_.ACCT_SEG_10 as ACCT14_0_, ledger0_.MODULE as MODULE0_, ledger0_.TRANS_ID as TRANS16_0_, ledger0_.DESCRIPTION as DESCRIP17_0_, ledger0_.AMOUNT as AMOUNT0_, ledger0_.STATUS as STATUS0_, ledger0_.LEDGER_DATE as LEDGER20_0_, ledger0_.REVERSING as REVERSING0_, ledger0_.REV_PERIOD as REV22_0_, ledger0_.DATE_CREATED as DATE23_0_, ledger0_.CREATED_BY as CREATED24_0_, ledger0_.DATE_MODIFIED as DATE25_0_, ledger0_.MODIFIED_BY as MODIFIED26_0_'
\-[FROM] FromClause: 'from' FromClause{level=1, fromElementCounter=1, fromElements=1, fromElementByClassAlias=[], fromElementByTableAlias=[ledger0_], fromElementsByPath=[], collectionJoinFromElementsByPath=[], impliedElements=[]}
\-[FROM_FRAGMENT] FromElement: 'gl_Ledger ledger0_' FromElement{explicit,not a collection join,not a fetch join,fetch non-lazy properties,classAlias=null,role=null,tableName=gl_Ledger,tableAlias=ledger0_,colums={,className=com.blackdog.gl.domain.Ledger}}

11:44:07,578 DEBUG [ErrorCounter] throwQueryException() : no errors
11:44:07,625 DEBUG [QueryTranslatorImpl] HQL: from com.blackdog.gl.domain.Ledger
11:44:07,625 DEBUG [QueryTranslatorImpl] SQL: select ledger0_.LEDGER_ID as LEDGER1_, ledger0_.UMBRELLA_CO_ID as UMBRELLA2_0_, ledger0_.COMPANY_ID as COMPANY3_0_, ledger0_.BASIS as BASIS0_, ledger0_.ACCT_SEG_1 as ACCT5_0_, ledger0_.ACCT_SEG_2 as ACCT6_0_, ledger0_.ACCT_SEG_3 as ACCT7_0_, ledger0_.ACCT_SEG_4 as ACCT8_0_, ledger0_.ACCT_SEG_5 as ACCT9_0_, ledger0_.ACCT_SEG_6 as ACCT10_0_, ledger0_.ACCT_SEG_7 as ACCT11_0_, ledger0_.ACCT_SEG_8 as ACCT12_0_, ledger0_.ACCT_SEG_9 as ACCT13_0_, ledger0_.ACCT_SEG_10 as ACCT14_0_, ledger0_.MODULE as MODULE0_, ledger0_.TRANS_ID as TRANS16_0_, ledger0_.DESCRIPTION as DESCRIP17_0_, ledger0_.AMOUNT as AMOUNT0_, ledger0_.STATUS as STATUS0_, ledger0_.LEDGER_DATE as LEDGER20_0_, ledger0_.REVERSING as REVERSING0_, ledger0_.REV_PERIOD as REV22_0_, ledger0_.DATE_CREATED as DATE23_0_, ledger0_.CREATED_BY as CREATED24_0_, ledger0_.DATE_MODIFIED as DATE25_0_, ledger0_.MODIFIED_BY as MODIFIED26_0_ from gl_Ledger ledger0_
11:44:07,640 DEBUG [ErrorCounter] throwQueryException() : no errors
11:44:07,671 DEBUG [AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
11:44:07,671 DEBUG [AbstractBatcher] opening JDBC connection
11:44:07,671 DEBUG [SQL] select ledger0_.LEDGER_ID as LEDGER1_, ledger0_.UMBRELLA_CO_ID as UMBRELLA2_0_, ledger0_.COMPANY_ID as COMPANY3_0_, ledger0_.BASIS as BASIS0_, ledger0_.ACCT_SEG_1 as ACCT5_0_, ledger0_.ACCT_SEG_2 as ACCT6_0_, ledger0_.ACCT_SEG_3 as ACCT7_0_, ledger0_.ACCT_SEG_4 as ACCT8_0_, ledger0_.ACCT_SEG_5 as ACCT9_0_, ledger0_.ACCT_SEG_6 as ACCT10_0_, ledger0_.ACCT_SEG_7 as ACCT11_0_, ledger0_.ACCT_SEG_8 as ACCT12_0_, ledger0_.ACCT_SEG_9 as ACCT13_0_, ledger0_.ACCT_SEG_10 as ACCT14_0_, ledger0_.MODULE as MODULE0_, ledger0_.TRANS_ID as TRANS16_0_, ledger0_.DESCRIPTION as DESCRIP17_0_, ledger0_.AMOUNT as AMOUNT0_, ledger0_.STATUS as STATUS0_, ledger0_.LEDGER_DATE as LEDGER20_0_, ledger0_.REVERSING as REVERSING0_, ledger0_.REV_PERIOD as REV22_0_, ledger0_.DATE_CREATED as DATE23_0_, ledger0_.CREATED_BY as CREATED24_0_, ledger0_.DATE_MODIFIED as DATE25_0_, ledger0_.MODIFIED_BY as MODIFIED26_0_ from gl_Ledger ledger0_
11:44:07,734 DEBUG [AbstractBatcher] preparing statement
11:44:09,500 DEBUG [AbstractBatcher] about to open ResultSet (open ResultSets: 0, globally: 0)
11:44:09,515 DEBUG [Loader] processing result set
11:44:09,515 DEBUG [Loader] result set row: 0
11:44:09,515 DEBUG [IntegerType] returning '1' as column: LEDGER1_
11:44:09,562 DEBUG [Loader] result row: EntityKey[com.blackdog.gl.domain.Ledger#1]
11:44:09,578 DEBUG [Loader] Initializing object from ResultSet: [com.blackdog.gl.domain.Ledger#1]
11:44:09,625 DEBUG [BasicEntityPersister] Hydrating entity: [com.blackdog.gl.domain.Ledger#1]
11:44:09,625 DEBUG [IntegerType] returning '1' as column: UMBRELLA2_0_
11:44:09,625 DEBUG [IntegerType] returning '1' as column: COMPANY3_0_
11:44:09,625 DEBUG [StringType] returning 'C' as column: BASIS0_
11:44:09,640 DEBUG [StringType] returning '1000' as column: ACCT5_0_
11:44:09,640 DEBUG [StringType] returning '0200' as column: ACCT6_0_
11:44:09,640 DEBUG [StringType] returning '3' as column: ACCT7_0_
11:44:09,640 DEBUG [StringType] returning '4' as column: ACCT8_0_
11:44:09,640 DEBUG [StringType] returning '5' as column: ACCT9_0_
11:44:09,640 DEBUG [StringType] returning '6' as column: ACCT10_0_
11:44:09,640 DEBUG [StringType] returning '7' as column: ACCT11_0_
11:44:09,640 DEBUG [StringType] returning '8' as column: ACCT12_0_
11:44:09,656 DEBUG [StringType] returning '9' as column: ACCT13_0_
11:44:09,656 DEBUG [StringType] returning '10' as column: ACCT14_0_
11:44:09,656 DEBUG [StringType] returning 'GL' as column: MODULE0_
11:44:09,656 DEBUG [IntegerType] returning '12345' as column: TRANS16_0_
11:44:09,656 DEBUG [StringType] returning 'PAY GARDENER' as column: DESCRIP17_0_
11:44:09,656 DEBUG [BigDecimalType] returning '999.99' as column: AMOUNT0_
11:44:09,656 DEBUG [StringType] returning 'A' as column: STATUS0_
11:44:09,671 DEBUG [TimestampType] returning '2005-01-04 00:00:00' as column: LEDGER20_0_
11:44:09,671 DEBUG [StringType] returning 'N' as column: REVERSING0_
11:44:09,671 DEBUG [IntegerType] returning '3' as column: REV22_0_
11:44:09,671 DEBUG [TimestampType] returning '2005-01-04 00:00:00' as column: DATE23_0_
11:44:09,671 DEBUG [IntegerType] returning '1' as column: CREATED24_0_
11:44:09,671 DEBUG [TimestampType] returning '2005-01-04 00:00:00' as column: DATE25_0_
11:44:09,671 DEBUG [IntegerType] returning '1' as column: MODIFIED26_0_
11:44:09,687 DEBUG [Loader] result set row: 1
11:44:09,687 DEBUG [IntegerType] returning '2' as column: LEDGER1_
11:44:09,687 DEBUG [Loader] result row: EntityKey[com.blackdog.gl.domain.Ledger#2]
11:44:09,703 DEBUG [Loader] Initializing object from ResultSet: [com.blackdog.gl.domain.Ledger#2]
11:44:09,703 DEBUG [BasicEntityPersister] Hydrating entity: [com.blackdog.gl.domain.Ledger#2]
11:44:09,703 DEBUG [IntegerType] returning '2' as column: UMBRELLA2_0_
11:44:09,703 DEBUG [IntegerType] returning '2' as column: COMPANY3_0_
11:44:09,703 DEBUG [StringType] returning 'C' as column: BASIS0_
11:44:09,703 DEBUG [StringType] returning '1000' as column: ACCT5_0_
11:44:09,703 DEBUG [StringType] returning '0200' as column: ACCT6_0_
11:44:09,718 DEBUG [StringType] returning '3' as column: ACCT7_0_
11:44:09,718 DEBUG [StringType] returning '4' as column: ACCT8_0_
11:44:09,718 DEBUG [StringType] returning '5' as column: ACCT9_0_
11:44:09,718 DEBUG [StringType] returning '6' as column: ACCT10_0_
11:44:09,718 DEBUG [StringType] returning '7' as column: ACCT11_0_
11:44:09,718 DEBUG [StringType] returning '8' as column: ACCT12_0_
11:44:09,718 DEBUG [StringType] returning '9' as column: ACCT13_0_
11:44:09,718 DEBUG [StringType] returning '10' as column: ACCT14_0_
11:44:09,718 DEBUG [StringType] returning 'GL' as column: MODULE0_
11:44:09,718 DEBUG [IntegerType] returning '12345' as column: TRANS16_0_
11:44:09,718 DEBUG [StringType] returning 'PAY GARDENER' as column: DESCRIP17_0_
11:44:09,734 DEBUG [BigDecimalType] returning '999.99' as column: AMOUNT0_
11:44:09,734 DEBUG [StringType] returning 'A' as column: STATUS0_
11:44:09,734 DEBUG [TimestampType] returning '2005-01-04 00:00:00' as column: LEDGER20_0_
11:44:09,734 DEBUG [StringType] returning 'N' as column: REVERSING0_
11:44:09,734 DEBUG [IntegerType] returning '3' as column: REV22_0_
11:44:09,734 DEBUG [TimestampType] returning '2005-01-04 00:00:00' as column: DATE23_0_
11:44:09,734 DEBUG [IntegerType] returning '1' as column: CREATED24_0_
11:44:09,734 DEBUG [TimestampType] returning '2005-01-04 00:00:00' as column: DATE25_0_
11:44:09,750 DEBUG [IntegerType] returning '1' as column: MODIFIED26_0_
11:44:09,750 DEBUG [Loader] done processing result set (2 rows)
11:44:09,750 DEBUG [AbstractBatcher] about to close ResultSet (open ResultSets: 1, globally: 1)
11:44:09,750 DEBUG [AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1)
11:44:09,750 DEBUG [AbstractBatcher] closing statement
11:44:09,750 DEBUG [AbstractBatcher] closing JDBC connection (open PreparedStatements: 0, globally: 0) (open ResultSets: 0, globally: 0)
11:44:09,796 DEBUG [Loader] total objects hydrated: 2
11:44:09,796 DEBUG [TwoPhaseLoad] resolving associations for [com.blackdog.gl.domain.Ledger#1]
11:44:09,828 DEBUG [TwoPhaseLoad] done materializing entity [com.blackdog.gl.domain.Ledger#1]
11:44:09,828 DEBUG [TwoPhaseLoad] resolving associations for [com.blackdog.gl.domain.Ledger#2]
11:44:09,828 DEBUG [TwoPhaseLoad] done materializing entity [com.blackdog.gl.domain.Ledger#2]
11:44:09,843 DEBUG [PersistenceContext] initializing non-lazy collections
11:44:27,578 DEBUG [JTATransaction] commit
11:44:29,890 DEBUG [SessionImpl] closing session
11:45:35,953 DEBUG [CacheSynchronization] transaction before completion callback
11:45:35,953 DEBUG [CacheSynchronization] automatically flushing session
11:45:35,953 DEBUG [SessionImpl] automatically flushing session
11:45:35,953 DEBUG [JDBCContext] before transaction completion
11:45:35,953 DEBUG [SessionImpl] before transaction completion
11:45:35,953 DEBUG [CacheSynchronization] transaction after completion callback, status: 3
11:45:35,953 DEBUG [JDBCContext] after transaction completion
11:45:35,953 DEBUG [SessionImpl] after transaction completion
11:45:35,953 DEBUG [StatelessSessionContainer] Useless invocation of remove() for stateless session bean
11:45:39,468 DEBUG [JDBCContext] running Session.finalize()
11:50:04,515 DEBUG [IdleRemover] run: IdleRemover notifying pools, interval: 450000

Let me just add that I misspoke whne I said "nothing is in the table" after the insert. The original 2 rows are, just not the new row I'm trying to insert. I did turn off the Hibernate option to recreate the table.

After another day of trying to figure this out, I've come to the conclusion I'm not understanding the MBean configuration necessary to configure CMT with HIbernate. For instance, I now believe that the attributes:

<attribute name="TransactionStrategy">net.sf.hibernate.transaction.JTATransactionFactory</attribute>
<attribute name="TransactionManagerLookupStrategy">net.sf.hibernate.transaction.JBossTransactionManagerLookup</attribute>
</mbean>

don't belong in the name="jboss.har:service=Hibernate" MBean, but in the service=HibernateFactory MBean. (I get this from
Hibernate in Action p 56-57.

I'm still not sure if I need a Hibernate-Service and a HibernateFactory service, or both and what other MBeans (e.g. JCA?) I need.

Can someone describe, or point me to the clearest description of, which MBeans I have to define/configure to make CMT work? (my transactions are local to one Oracle database).

Tnx.

You are using 2 year old information to do this, from HiA. Why don't you use the reference manuals of the current Hibernate and JBoss releases?

I am using them, too. I've also read Getting Started with JBOss 4.0.2 It was actually the JBoss AS Guide 4.0.2 doc in the chapter on Hibernate (page 432) that suggested using the Hibernate in Action for more information.

I know, being new, I'm pretty ignorant on this. I'm finding the doc on the needed JMX MBean configurations for tying HIbernate I/O operations to JTA hard to locate, both in the Hibernate and JBoss doc.

If you can point me to where in the current docs I can find how to do implement CMT with Hibernate, it would be very helpful.

For a start, you are using Hibernate2 option names for a Hibernate3 configuration.

Well, that is a start. Thanks.

1. With Oracle, it is better to use Oracle dialect.
2. I currently use hibernate POJOs in session beans. I do not use a transaction object. I use CMP, let the container take care of transaction and close the session.

Artemis, I only vaguely understand what you're suggesting. Is there a place in either the Hibernate and/or JBoss manuals you can point me to to explain what you mean?

<b> to anyone else who can help</b>

I changed the Hibernate session MBean to a JCA Hibernate factory MBean (as per pg 41 of the Hibernate 3 manual. Now I can't find Hibernate Session when I do the lookup. The server log says it bound it, but I don't see it in the JNDI view. There is also an error in the log:
<b>
--- MBeans waiting for other MBeans ---
ObjectName: jboss.jca:service=HibernateFactory
State: CONFIGURED
I Depend On:
jboss.jca:service=RARDeployer
jboss.jca:service=LocalTxCM,name=jdbc/OracleDS

--- MBEANS THAT ARE THE ROOT CAUSE OF THE PROBLEM ---
ObjectName: jboss.jca:service=LocalTxCM,name=jdbc/OracleDS
State: NOTYETINSTALLED
Depends On Me:
jboss.jca:service=HibernateFactory
</b>


Here is my new Session Factory MBean definition, the jndi view and most of the server log:
<b>the Hibernate Factory MBean</b>

server>
<mbean code="org.hibernate.jmx.HibernateService" name="jboss.jca:service=HibernateFactory">
<depends>jboss.jca:service=RARDeployer</depends>
<depends>jboss.jca:service=LocalTxCM,name=jdbc/OracleDS</depends>
<attribute name="JndiName">java:/hibernate/SessionFactory</attribute>
<attribute name="Datasource">java:/jdbc/OracleDS</attribute>
<attribute name="Dialect">org.hibernate.dialect.HSQLDialect</attribute>
<attribute name="TransactionStrategy">org.hibernate.transaction.JTATransactionFactory</attribute>
<attribute name="TransactionManagerLookupStrategy">org.hibernate.transaction.JBossTransactionManagerLookup</attribute>
<attribute name="FlushBeforeCompletionEnabled">true</attribute>
<attribute name="AutoCloseSessionEnabled">true</attribute>
<attribute name="MaximumFetchDepth">3</attribute>
<attribute name="SecondLevelCacheEnabled">false</attribute>
<attribute name="ShowSqlEnabled">True</attribute>
<attribute name="MapResources">ledger.hbm.xml</attribute>
</mbean>
</server>




<b>the JNDI View</b>
java: Namespace
+- XAConnectionFactory (class: org.jboss.mq.SpyXAConnectionFactory)
+- DefaultDS (class: javax.sql.DataSource)
+- SecurityProxyFactory (class: org.jboss.security.SubjectSecurityProxyFactory)
+- DefaultJMSProvider (class: org.jboss.jms.jndi.JNDIProviderAdapter)
+- comp (class: javax.naming.Context)
+- jdbc (class: org.jnp.interfaces.NamingContext)
| +- OracleDS (class: javax.sql.DataSource)
+- JmsXA (class: org.jboss.resource.adapter.jms.JmsConnectionFactoryImpl)
+- ConnectionFactory (class: org.jboss.mq.SpyConnectionFactory)
+- jaas (class: javax.naming.Context)
| +- JmsXARealm (class: org.jboss.security.plugins.SecurityDomainContext)
| +- jbossmq (class: org.jboss.security.plugins.SecurityDomainContext)
| +- HsqlDbRealm (class: org.jboss.security.plugins.SecurityDomainContext)
+- timedCacheFactory (class: javax.naming.Context)
Failed to lookup: timedCacheFactory, errmsg=org.jboss.util.TimedCachePolicy
+- TransactionPropagationContextExporter (class: org.jboss.tm.TransactionPropagationContextFactory)
+- StdJMSPool (class: org.jboss.jms.asf.StdServerSessionPoolFactory)
+- Mail (class: javax.mail.Session)
+- TransactionPropagationContextImporter (class: org.jboss.tm.TransactionPropagationContextImporter)
+- TransactionManager (class: org.jboss.tm.TxManager)

Global JNDI Namespace
+- XAConnectionFactory (class: org.jboss.mq.SpyXAConnectionFactory)
+- UIL2ConnectionFactory[link -> ConnectionFactory] (class: javax.naming.LinkRef)
+- UserTransactionSessionFactory (proxy: $Proxy11 implements interface org.jboss.tm.usertx.interfaces.UserTransactionSessionFactory)
+- HTTPConnectionFactory (class: org.jboss.mq.SpyConnectionFactory)
+- console (class: org.jnp.interfaces.NamingContext)
| +- PluginManager (proxy: $Proxy37 implements interface org.jboss.console.manager.PluginManagerMBean)
+- UIL2XAConnectionFactory[link -> XAConnectionFactory] (class: javax.naming.LinkRef)
+- UUIDKeyGeneratorFactory (class: org.jboss.ejb.plugins.keygenerator.uuid.UUIDKeyGeneratorFactory)
+- HTTPXAConnectionFactory (class: org.jboss.mq.SpyXAConnectionFactory)
+- topic (class: org.jnp.interfaces.NamingContext)
| +- testDurableTopic (class: org.jboss.mq.SpyTopic)
| +- testTopic (class: org.jboss.mq.SpyTopic)
| +- securedTopic (class: org.jboss.mq.SpyTopic)
+- queue (class: org.jnp.interfaces.NamingContext)
| +- A (class: org.jboss.mq.SpyQueue)
| +- testQueue (class: org.jboss.mq.SpyQueue)
| +- ex (class: org.jboss.mq.SpyQueue)
| +- DLQ (class: org.jboss.mq.SpyQueue)
| +- D (class: org.jboss.mq.SpyQueue)
| +- C (class: org.jboss.mq.SpyQueue)
| +- B (class: org.jboss.mq.SpyQueue)
+- ConnectionFactory (class: org.jboss.mq.SpyConnectionFactory)
+- UserTransaction (class: org.jboss.tm.usertx.client.ClientUserTransaction)
+- ejb (class: org.jnp.interfaces.NamingContext)
| +- LedgerManager (proxy: $Proxy53 implements interface com.blackdog.gl.interfaces.LedgerManagerHome,interface javax.ejb.Handle)
| +- LedgerManagerLocal (proxy: $Proxy50 implements interface com.blackdog.gl.interfaces.LedgerManagerLocalHome)
+- jmx (class: org.jnp.interfaces.NamingContext)
| +- invoker (class: org.jnp.interfaces.NamingContext)
| | +- RMIAdaptor (proxy: $Proxy36 implements interface org.jboss.jmx.adaptor.rmi.RMIAdaptor,interface org.jboss.jmx.adaptor.rmi.RMIAdaptorExt)
| +- rmi (class: org.jnp.interfaces.NamingContext)
| | +- RMIAdaptor[link -> jmx/invoker/RMIAdaptor] (class: javax.naming.LinkRef)
+- HiLoKeyGeneratorFactory (class: org.jboss.ejb.plugins.keygenerator.hilo.HiLoKeyGeneratorFactory)
+- UILXAConnectionFactory[link -> XAConnectionFactory] (class: javax.naming.LinkRef)
+- UILConnectionFactory[link -> ConnectionFactory] (class: javax.naming.LinkRef)

<b>the server log</b>
<server>
<mbean code="org.jboss.resource.connectionmanager.TxConnectionManager" display-name="ConnectionManager for DataSource /jdbc/OracleDS" name="jboss.jca:service=LocalTxCM,name=/jdbc/OracleDS"><attribute name="TrackConnectionByTx">true</attribute><attribute name="LocalTransactions">true</attribute><depends optional-attribute-name="ManagedConnectionPool"><mbean code="org.jboss.resource.connectionmanager.JBossManagedConnectionPool" display-name="Connection Pool for DataSource /jdbc/OracleDS" name="jboss.jca:service=ManagedConnectionPool,name=/jdbc/OracleDS"><depends optional-attribute-name="ManagedConnectionFactoryName"><mbean code="org.jboss.resource.connectionmanager.RARDeployment" display-name="ManagedConnectionFactory for DataSource /jdbc/OracleDS" name="jboss.jca:service=ManagedConnectionFactory,name=/jdbc/OracleDS"><depends optional-attribute-name="OldRarDeployment">jboss.jca:service=RARDeployment,name='jboss-local-jdbc.rar'</depends><attribute name="RARName"></attribute><attribute name="ConnectionDefinition">javax.sql.DataSource</attribute><attribute name="ManagedConnectionFactoryProperties"><properties><config-property name="ConnectionURL" type="java.lang.String">jdbc:oracle:thin:@joan-dt.blackdogsoft.net:1521:blackdog</config-property><config-property name="DriverClass" type="java.lang.String">oracle.jdbc.driver.OracleDriver</config-property><config-property name="UserName" type="java.lang.String">developement</config-property><config-property name="Password" type="java.lang.String">admin!234</config-property><config-property name="ExceptionSorterClassName" type="java.lang.String">org.jboss.resource.adapter.jdbc.vendor.OracleExceptionSorter</config-property></properties></attribute></mbean></depends><attribute name="MinSize">0</attribute><attribute name="MaxSize">20</attribute><attribute name="BlockingTimeoutMillis">30000</attribute><attribute name="IdleTimeoutMinutes">15</attribute><attribute name="Criteria">ByNothing</attribute></mbean></depends><attribute name="JndiName">/jdbc/OracleDS</attribute><depends optional-attribute-name="CachedConnectionManager">jboss.jca:service=CachedConnectionManager</depends><depends optional-attribute-name="TransactionManagerService">jboss:service=TransactionManager</depends></mbean><mbean code="org.jboss.resource.adapter.jdbc.remote.WrapperDataSourceService" display-name="Binding for DataSource /jdbc/OracleDS" name="jboss.jca:service=DataSourceBinding,name=/jdbc/OracleDS"><attribute name="JndiName">/jdbc/OracleDS</attribute><attribute name="UseJavaContext">true</attribute><depends optional-attribute-name="ConnectionManager">jboss.jca:service=LocalTxCM,name=/jdbc/OracleDS</depends><depends optional-attribute-name="JMXInvokerName">jboss:service=invoker,type=jrmp</depends></mbean><mbean code="org.jboss.ejb.plugins.cmp.jdbc.metadata.DataSourceMetaData" name="jboss.jdbc:service=metadata,datasource=/jdbc/OracleDS"><depends optional-attribute-name="MetadataLibrary">jboss.jdbc:service=metadata</depends><attribute name="TypeMapping">Oracle9i</attribute></mbean>

</server>
15:11:09,044 DEBUG [SARDeployer] Using existing deployment.document
15:11:09,044 DEBUG [SARDeployer] about to copy 0 local directories
15:11:09,044 DEBUG [SARDeployer] looking for nested deployments in : file:/C:/jboss/jboss-4.0.2/server/default/deploy/oracle-ds.xml
15:11:09,044 DEBUG [DeploymentInfo] createLoaderRepository from config: LoaderRepositoryConfig(repositoryName: JMImplementation:service=LoaderRepository,name=Default, repositoryClassName: null, configParserClassName: null, repositoryConfig: null)
15:11:09,044 DEBUG [UnifiedClassLoader] New jmx UCL with url file:/C:/jboss/jboss-4.0.2/server/default/tmp/deploy/tmp16871oracle-ds.xml
15:11:09,044 DEBUG [RepositoryClassLoader] setRepository, repository=org.jboss.mx.loading.UnifiedLoaderRepository3@aeea66, cl=org.jboss.mx.loading.UnifiedClassLoader3@13ef605{ url=file:/C:/jboss/jboss-4.0.2/server/default/tmp/deploy/tmp16871oracle-ds.xml ,addedOrder=0}
15:11:09,044 DEBUG [RepositoryClassLoader] setRepository, repository=org.jboss.mx.loading.UnifiedLoaderRepository3@aeea66, cl=org.jboss.mx.loading.UnifiedClassLoader3@13ef605{ url=file:/C:/jboss/jboss-4.0.2/server/default/tmp/deploy/tmp16871oracle-ds.xml ,addedOrder=0}
15:11:09,044 DEBUG [UnifiedLoaderRepository3] Adding org.jboss.mx.loading.UnifiedClassLoader3@13ef605{ url=file:/C:/jboss/jboss-4.0.2/server/default/tmp/deploy/tmp16871oracle-ds.xml ,addedOrder=0}
15:11:09,044 DEBUG [MainDeployer] found 0 subpackages of file:/C:/jboss/jboss-4.0.2/server/default/deploy/oracle-ds.xml
15:11:09,044 DEBUG [MainDeployer] Watching new file: file:/C:/jboss/jboss-4.0.2/server/default/deploy/oracle-ds.xml
15:11:09,044 DEBUG [MainDeployer] create step for deployment file:/C:/jboss/jboss-4.0.2/server/default/deploy/oracle-ds.xml
15:11:09,059 DEBUG [SARDeployer] Deploying SAR, create step: url file:/C:/jboss/jboss-4.0.2/server/default/deploy/oracle-ds.xml
15:11:09,059 DEBUG [SARDeployer] Registering service UCL=jmx.loading:UCL=13ef605
15:11:09,059 DEBUG [ServiceCreator] About to create bean: jboss.jca:service=LocalTxCM,name=/jdbc/OracleDS with code: org.jboss.resource.connectionmanager.TxConnectionManager
15:11:09,075 DEBUG [ServiceCreator] Created bean: jboss.jca:service=LocalTxCM,name=/jdbc/OracleDS
15:11:09,075 DEBUG [ServiceConfigurator] TrackConnectionByTx set to true in jboss.jca:service=LocalTxCM,name=/jdbc/OracleDS
15:11:09,075 DEBUG [ServiceConfigurator] LocalTransactions set to true in jboss.jca:service=LocalTxCM,name=/jdbc/OracleDS
15:11:09,075 DEBUG [ServiceCreator] About to create bean: jboss.jca:service=ManagedConnectionPool,name=/jdbc/OracleDS with code: org.jboss.resource.connectionmanager.JBossManagedConnectionPool
15:11:09,106 DEBUG [ServiceCreator] Created bean: jboss.jca:service=ManagedConnectionPool,name=/jdbc/OracleDS
15:11:09,106 DEBUG [ServiceCreator] About to create bean: jboss.jca:service=ManagedConnectionFactory,name=/jdbc/OracleDS with code: org.jboss.resource.connectionmanager.RARDeployment
15:11:09,247 DEBUG [ServiceCreator] Created bean: jboss.jca:service=ManagedConnectionFactory,name=/jdbc/OracleDS
15:11:09,247 DEBUG [ServiceController] recording that jboss.jca:service=ManagedConnectionFactory,name=/jdbc/OracleDS depends on jboss.jca:service=RARDeployment,name='jboss-local-jdbc.rar'
15:11:09,247 DEBUG [ServiceConfigurator] considering OldRarDeployment with object name jboss.jca:service=RARDeployment,name='jboss-local-jdbc.rar'
15:11:09,278 DEBUG [ServiceConfigurator] ConnectionDefinition set to javax.sql.DataSource in jboss.jca:service=ManagedConnectionFactory,name=/jdbc/OracleDS
15:11:09,294 DEBUG [ServiceConfigurator] ManagedConnectionFactoryProperties set to [properties: null] in jboss.jca:service=ManagedConnectionFactory,name=/jdbc/OracleDS
15:11:09,294 DEBUG [ServiceController] recording that jboss.jca:service=ManagedConnectionPool,name=/jdbc/OracleDS depends on jboss.jca:service=ManagedConnectionFactory,name=/jdbc/OracleDS
15:11:09,294 DEBUG [ServiceConfigurator] considering ManagedConnectionFactoryName with object name jboss.jca:service=ManagedConnectionFactory,name=/jdbc/OracleDS
15:11:09,294 DEBUG [ServiceConfigurator] MinSize set to 0 in jboss.jca:service=ManagedConnectionPool,name=/jdbc/OracleDS
15:11:09,294 DEBUG [ServiceConfigurator] MaxSize set to 20 in jboss.jca:service=ManagedConnectionPool,name=/jdbc/OracleDS
15:11:09,294 DEBUG [ServiceConfigurator] BlockingTimeoutMillis set to 30000 in jboss.jca:service=ManagedConnectionPool,name=/jdbc/OracleDS
15:11:09,294 DEBUG [ServiceConfigurator] IdleTimeoutMinutes set to 15 in jboss.jca:service=ManagedConnectionPool,name=/jdbc/OracleDS
15:11:09,309 DEBUG [ServiceConfigurator] Criteria set to ByNothing in jboss.jca:service=ManagedConnectionPool,name=/jdbc/OracleDS
15:11:09,309 DEBUG [ServiceController] recording that jboss.jca:service=LocalTxCM,name=/jdbc/OracleDS depends on jboss.jca:service=ManagedConnectionPool,name=/jdbc/OracleDS
15:11:09,309 DEBUG [ServiceConfigurator] considering ManagedConnectionPool with object name jboss.jca:service=ManagedConnectionPool,name=/jdbc/OracleDS
15:11:09,309 DEBUG [ServiceConfigurator] JndiName set to /jdbc/OracleDS in jboss.jca:service=LocalTxCM,name=/jdbc/OracleDS
15:11:09,309 DEBUG [ServiceController] recording that jboss.jca:service=LocalTxCM,name=/jdbc/OracleDS depends on jboss.jca:service=CachedConnectionManager
15:11:09,309 DEBUG [ServiceConfigurator] considering CachedConnectionManager with object name jboss.jca:service=CachedConnectionManager
15:11:09,309 DEBUG [ServiceController] recording that jboss.jca:service=LocalTxCM,name=/jdbc/OracleDS depends on jboss:service=TransactionManager
15:11:09,309 DEBUG [ServiceConfigurator] considering TransactionManagerService with object name jboss:service=TransactionManager
15:11:09,309 DEBUG [ServiceCreator] About to create bean: jboss.jca:service=DataSourceBinding,name=/jdbc/OracleDS with code: org.jboss.resource.adapter.jdbc.remote.WrapperDataSourceService
15:11:09,325 DEBUG [ServiceCreator] Created bean: jboss.jca:service=DataSourceBinding,name=/jdbc/OracleDS
15:11:09,325 DEBUG [ServiceConfigurator] JndiName set to /jdbc/OracleDS in jboss.jca:service=DataSourceBinding,name=/jdbc/OracleDS
15:11:09,325 DEBUG [ServiceConfigurator] UseJavaContext set to true in jboss.jca:service=DataSourceBinding,name=/jdbc/OracleDS
15:11:09,325 DEBUG [ServiceController] recording that jboss.jca:service=DataSourceBinding,name=/jdbc/OracleDS depends on jboss.jca:service=LocalTxCM,name=/jdbc/OracleDS
15:11:09,325 DEBUG [ServiceConfigurator] considering ConnectionManager with object name jboss.jca:service=LocalTxCM,name=/jdbc/OracleDS
15:11:09,325 DEBUG [ServiceController] recording that jboss.jca:service=DataSourceBinding,name=/jdbc/OracleDS depends on jboss:service=invoker,type=jrmp
15:11:09,325 DEBUG [ServiceConfigurator] considering JMXInvokerName with object name jboss:service=invoker,type=jrmp
15:11:09,325 DEBUG [ServiceCreator] About to create bean: jboss.jdbc:service=metadata,datasource=/jdbc/OracleDS with code: org.jboss.ejb.plugins.cmp.jdbc.metadata.DataSourceMetaData
15:11:09,356 DEBUG [ServiceCreator] Created bean: jboss.jdbc:service=metadata,datasource=/jdbc/OracleDS
15:11:09,356 DEBUG [ServiceController] recording that jboss.jdbc:service=metadata,datasource=/jdbc/OracleDS depends on jboss.jdbc:service=metadata
15:11:09,356 DEBUG [ServiceConfigurator] considering MetadataLibrary with object name jboss.jdbc:service=metadata
15:11:09,356 DEBUG [ServiceConfigurator] TypeMapping set to Oracle9i in jboss.jdbc:service=metadata,datasource=/jdbc/OracleDS
15:11:09,372 DEBUG [ServiceController] Creating service jboss.jca:service=LocalTxCM,name=/jdbc/OracleDS
15:11:09,372 DEBUG [ServiceController] waiting in create of jboss.jca:service=LocalTxCM,name=/jdbc/OracleDS waiting on jboss.jca:service=ManagedConnectionPool,name=/jdbc/OracleDS
15:11:09,372 DEBUG [ServiceController] Creating service jboss.jca:service=ManagedConnectionPool,name=/jdbc/OracleDS
15:11:09,372 DEBUG [ServiceController] waiting in create of jboss.jca:service=ManagedConnectionPool,name=/jdbc/OracleDS waiting on jboss.jca:service=ManagedConnectionFactory,name=/jdbc/OracleDS
15:11:09,372 DEBUG [ServiceController] Creating service jboss.jca:service=ManagedConnectionFactory,name=/jdbc/OracleDS
15:11:09,372 DEBUG [RARDeployment] Creating jboss.jca:service=ManagedConnectionFactory,name=/jdbc/OracleDS
15:11:09,372 DEBUG [RARDeployment] Created jboss.jca:service=ManagedConnectionFactory,name=/jdbc/OracleDS
15:11:09,372 DEBUG [ServiceController] Creating dependent components for: jboss.jca:service=ManagedConnectionFactory,name=/jdbc/OracleDS dependents are: [ObjectName: jboss.jca:service=ManagedConnectionPool,name=/jdbc/OracleDS
State: CONFIGURED
I Depend On:
jboss.jca:service=ManagedConnectionFactory,name=/jdbc/OracleDS
Depends On Me:
jboss.jca:service=LocalTxCM,name=/jdbc/OracleDS
]
15:11:09,372 DEBUG [ServiceController] Creating service jboss.jca:service=ManagedConnectionPool,name=/jdbc/OracleDS
15:11:09,372 DEBUG [JBossManagedConnectionPool] Creating jboss.jca:service=ManagedConnectionPool,name=/jdbc/OracleDS
15:11:09,372 DEBUG [JBossManagedConnectionPool] Created jboss.jca:service=ManagedConnectionPool,name=/jdbc/OracleDS
15:11:09,372 DEBUG [ServiceController] Creating dependent components for: jboss.jca:service=ManagedConnectionPool,name=/jdbc/OracleDS dependents are: [ObjectName: jboss.jca:service=LocalTxCM,name=/jdbc/OracleDS
State: CONFIGURED
I Depend On:
jboss.jca:service=ManagedConnectionPool,name=/jdbc/OracleDS
jboss.jca:service=CachedConnectionManager
jboss:service=TransactionManager
Depends On Me:
jboss.jca:service=DataSourceBinding,name=/jdbc/OracleDS
]
15:11:09,372 DEBUG [ServiceController] Creating service jboss.jca:service=LocalTxCM,name=/jdbc/OracleDS
15:11:09,372 DEBUG [TxConnectionManager] Creating jboss.jca:service=LocalTxCM,name=/jdbc/OracleDS
15:11:09,372 DEBUG [TxConnectionManager] Created jboss.jca:service=LocalTxCM,name=/jdbc/OracleDS
15:11:09,372 DEBUG [ServiceController] Creating dependent components for: jboss.jca:service=LocalTxCM,name=/jdbc/OracleDS dependents are: [ObjectName: jboss.jca:service=DataSourceBinding,name=/jdbc/OracleDS
State: CONFIGURED
I Depend On:
jboss.jca:service=LocalTxCM,name=/jdbc/OracleDS
jboss:service=invoker,type=jrmp
]
15:11:09,372 DEBUG [ServiceController] Creating service jboss.jca:service=DataSourceBinding,name=/jdbc/OracleDS
15:11:09,372 DEBUG [WrapperDataSourceService] Creating jboss.jca:service=DataSourceBinding,name=/jdbc/OracleDS
15:11:09,372 DEBUG [WrapperDataSourceService] Created jboss.jca:service=DataSourceBinding,name=/jdbc/OracleDS
15:11:09,372 DEBUG [ServiceController] Creating dependent components for: jboss.jca:service=DataSourceBinding,name=/jdbc/OracleDS dependents are: []
15:11:09,372 DEBUG [ServiceController] Creating service jboss.jca:service=DataSourceBinding,name=/jdbc/OracleDS
15:11:09,372 DEBUG [ServiceController] Ignoring create request for service: jboss.jca:service=DataSourceBinding,name=/jdbc/OracleDS
15:11:09,372 DEBUG [ServiceController] Creating service jboss.jdbc:service=metadata,datasource=/jdbc/OracleDS
15:11:09,372 DEBUG [DataSourceMetaData] Creating jboss.jdbc:service=metadata,datasource=/jdbc/OracleDS
15:11:09,372 DEBUG [DataSourceMetaData] Created jboss.jdbc:service=metadata,datasource=/jdbc/OracleDS
15:11:09,372 DEBUG [ServiceController] Creating dependent components for: jboss.jdbc:service=metadata,datasource=/jdbc/OracleDS dependents are: []
15:11:09,372 DEBUG [MainDeployer] Done with create step of deploying oracle-ds.xml
15:11:09,372 DEBUG [MainDeployer] Begin deployment start file:/C:/jboss/jboss-4.0.2/server/default/deploy/oracle-ds.xml
15:11:09,372 DEBUG [SARDeployer] Deploying SAR, start step: url file:/C:/jboss/jboss-4.0.2/server/default/deploy/oracle-ds.xml
15:11:09,372 DEBUG [ServiceController] starting service jboss.jca:service=LocalTxCM,name=/jdbc/OracleDS
15:11:09,372 DEBUG [ServiceController] waiting in start jboss.jca:service=LocalTxCM,name=/jdbc/OracleDS on jboss.jca:service=ManagedConnectionPool,name=/jdbc/OracleDS
15:11:09,372 DEBUG [ServiceController] starting service jboss.jca:service=ManagedConnectionPool,name=/jdbc/OracleDS
15:11:09,372 DEBUG [ServiceController] waiting in start jboss.jca:service=ManagedConnectionPool,name=/jdbc/OracleDS on jboss.jca:service=ManagedConnectionFactory,name=/jdbc/OracleDS
15:11:09,372 DEBUG [ServiceController] starting service jboss.jca:service=ManagedConnectionFactory,name=/jdbc/OracleDS
15:11:09,372 DEBUG [RARDeployment] Starting jboss.jca:service=ManagedConnectionFactory,name=/jdbc/OracleDS
15:11:09,387 DEBUG [RARDeployment] Not setting config property 'NewConnectionSQL'
15:11:09,387 DEBUG [RARDeployment] Not setting config property 'ExceptionSorterClassName'
15:11:09,387 DEBUG [RARDeployment] Not setting config property 'SharePreparedStatements'
15:11:09,387 DEBUG [RARDeployment] Not setting config property 'TrackStatements'
15:11:09,387 DEBUG [RARDeployment] Not setting config property 'ConnectionURL'
15:11:09,387 DEBUG [RARDeployment] Not setting config property 'Password'
15:11:09,387 DEBUG [RARDeployment] Not setting config property 'TransactionIsolation'
15:11:09,387 DEBUG [RARDeployment] Not setting config property 'CheckValidConnectionSQL'
15:11:09,387 DEBUG [RARDeployment] Not setting config property 'ValidConnectionCheckerClassName'
15:11:09,387 DEBUG [RARDeployment] Not setting config property 'PreparedStatementCacheSize'
15:11:09,387 DEBUG [RARDeployment] Not setting config property 'ConnectionProperties'
15:11:09,387 DEBUG [RARDeployment] Not setting config property 'UserName'
15:11:09,387 DEBUG [RARDeployment] Not setting config property 'DriverClass'
15:11:09,387 DEBUG [RARDeployment] setting property: ConnectionURL to value jdbc:oracle:thin:@joan-dt.blackdogsoft.net:1521:blackdog
15:11:09,387 DEBUG [RARDeployment] set property ConnectionURL to value jdbc:oracle:thin:@joan-dt.blackdogsoft.net:1521:blackdog
15:11:09,403 DEBUG [RARDeployment] setting property: DriverClass to value oracle.jdbc.driver.OracleDriver
15:11:09,403 DEBUG [RARDeployment] set property DriverClass to value oracle.jdbc.driver.OracleDriver
15:11:09,403 DEBUG [RARDeployment] setting property: UserName to value developement
15:11:09,403 DEBUG [RARDeployment] set property UserName to value developement
15:11:09,403 DEBUG [RARDeployment] setting property: Password to value admin!234
15:11:09,403 DEBUG [RARDeployment] set property Password to value admin!234
15:11:09,403 DEBUG [RARDeployment] setting property: ExceptionSorterClassName to value org.jboss.resource.adapter.jdbc.vendor.OracleExceptionSorter
15:11:09,403 DEBUG [RARDeployment] set property ExceptionSorterClassName to value org.jboss.resource.adapter.jdbc.vendor.OracleExceptionSorter
15:11:09,419 DEBUG [RARDeployment] Started jboss.jca:service=ManagedConnectionFactory,name=/jdbc/OracleDS
15:11:09,419 DEBUG [ServiceController] Starting dependent components for: jboss.jca:service=ManagedConnectionFactory,name=/jdbc/OracleDS dependent components: [ObjectName: jboss.jca:service=ManagedConnectionPool,name=/jdbc/OracleDS
State: CREATED
I Depend On:
jboss.jca:service=ManagedConnectionFactory,name=/jdbc/OracleDS
Depends On Me:
jboss.jca:service=LocalTxCM,name=/jdbc/OracleDS
]
15:11:09,419 DEBUG [ServiceController] starting service jboss.jca:service=ManagedConnectionPool,name=/jdbc/OracleDS
15:11:09,419 DEBUG [JBossManagedConnectionPool] Starting jboss.jca:service=ManagedConnectionPool,name=/jdbc/OracleDS
15:11:09,419 DEBUG [JBossManagedConnectionPool] Started jboss.jca:service=ManagedConnectionPool,name=/jdbc/OracleDS
15:11:09,419 DEBUG [ServiceController] Starting dependent components for: jboss.jca:service=ManagedConnectionPool,name=/jdbc/OracleDS dependent components: [ObjectName: jboss.jca:service=LocalTxCM,name=/jdbc/OracleDS
State: CREATED
I Depend On:
jboss.jca:service=ManagedConnectionPool,name=/jdbc/OracleDS
jboss.jca:service=CachedConnectionManager
jboss:service=TransactionManager
Depends On Me:
jboss.jca:service=DataSourceBinding,name=/jdbc/OracleDS
]
15:11:09,419 DEBUG [ServiceController] starting service jboss.jca:service=LocalTxCM,name=/jdbc/OracleDS
15:11:09,419 DEBUG [TxConnectionManager] Starting jboss.jca:service=LocalTxCM,name=/jdbc/OracleDS
15:11:09,419 DEBUG [TxConnectionManager] Started jboss.jca:service=LocalTxCM,name=/jdbc/OracleDS
15:11:09,419 DEBUG [ServiceController] Starting dependent components for: jboss.jca:service=LocalTxCM,name=/jdbc/OracleDS dependent components: [ObjectName: jboss.jca:service=DataSourceBinding,name=/jdbc/OracleDS
State: CREATED
I Depend On:
jboss.jca:service=LocalTxCM,name=/jdbc/OracleDS
jboss:service=invoker,type=jrmp
]
15:11:09,419 DEBUG [ServiceController] starting service jboss.jca:service=DataSourceBinding,name=/jdbc/OracleDS
15:11:09,419 DEBUG [WrapperDataSourceService] Starting jboss.jca:service=DataSourceBinding,name=/jdbc/OracleDS
15:11:09,419 DEBUG [WrapperDataSourceService] Using delegate: org.jboss.invocation.jrmp.interfaces.JRMPInvokerProxy@3f1179 for invoker=jboss:service=invoker,type=jrmp
15:11:09,419 DEBUG [WrapperDataSourceService] Created proxy for invoker=jboss:service=invoker,type=jrmp, targetName=jboss.jca:service=DataSourceBinding,name=/jdbc/OracleDS, nameHash=1406704999
15:11:09,544 DEBUG [WrapperDataSourceService] Binding object 'org.jboss.resource.adapter.jdbc.WrapperDataSource@7196b2' into JNDI at 'java:/jdbc/OracleDS'
15:11:09,544 INFO [WrapperDataSourceService] Bound connection factory for resource adapter for ConnectionManager 'jboss.jca:service=DataSourceBinding,name=/jdbc/OracleDS to JNDI name 'java:/jdbc/OracleDS'
15:11:09,544 DEBUG [WrapperDataSourceService] Started jboss.jca:service=DataSourceBinding,name=/jdbc/OracleDS
15:11:09,544 DEBUG [ServiceController] Starting dependent components for: jboss.jca:service=DataSourceBinding,name=/jdbc/OracleDS dependent components: []
15:11:09,559 DEBUG [ServiceController] starting service jboss.jca:service=DataSourceBinding,name=/jdbc/OracleDS
15:11:09,559 DEBUG [ServiceController] Ignoring start request for service: jboss.jca:service=DataSourceBinding,name=/jdbc/OracleDS
15:11:09,559 DEBUG [ServiceController] starting service jboss.jdbc:service=metadata,datasource=/jdbc/OracleDS
15:11:09,559 DEBUG [DataSourceMetaData] Starting jboss.jdbc:service=metadata,datasource=/jdbc/OracleDS
15:11:09,559 DEBUG [DataSourceMetaData] Started jboss.jdbc:service=metadata,datasource=/jdbc/OracleDS
15:11:09,559 DEBUG [ServiceController] Starting dependent components for: jboss.jdbc:service=metadata,datasource=/jdbc/OracleDS dependent components: []
15:11:09,622 DEBUG [MainDeployer] End deployment start on package: oracle-ds.xml
15:11:09,622 DEBUG [MainDeployer] Deployed package: file:/C:/jboss/jboss-4.0.2/server/default/deploy/oracle-ds.xml
15:11:09,622 DEBUG [URLDeploymentScanner] Watch URL for: file:/C:/jboss/jboss-4.0.2/server/default/deploy/oracle-ds.xml -> file:/C:/jboss/jboss-4.0.2/server/default/deploy/oracle-ds.xml
15:11:09,637 DEBUG [MainDeployer] Starting deployment of package: file:/C:/jboss/jboss-4.0.2/server/default/deploy/All.jar
15:11:09,637 DEBUG [MainDeployer] Starting deployment (init step) of package at: file:/C:/jboss/jboss-4.0.2/server/default/deploy/All.jar
15:11:09,637 DEBUG [MainDeployer] Copying file:/C:/jboss/jboss-4.0.2/server/default/deploy/All.jar -> C:\jboss\jboss-4.0.2\server\default\tmp\deploy\tmp16872All.jar
15:11:09,653 DEBUG [JARDeployer] No META-INF or WEB-INF resource found, assuming it if for us
15:11:09,653 DEBUG [MainDeployer] using deployer org.jboss.deployment.JARDeployer@ea5461
15:11:09,653 DEBUG [JARDeployer] looking for nested deployments in : file:/C:/jboss/jboss-4.0.2/server/default/deploy/All.jar
15:11:09,669 DEBUG [DeploymentInfo] createLoaderRepository from config: LoaderRepositoryConfig(repositoryName: JMImplementation:service=LoaderRepository,name=Default, repositoryClassName: null, configParserClassName: null, repositoryConfig: null)
15:11:09,669 DEBUG [UnifiedClassLoader] New jmx UCL with url file:/C:/jboss/jboss-4.0.2/server/default/tmp/deploy/tmp16872All.jar
15:11:09,669 DEBUG [RepositoryClassLoader] setRepository, repository=org.jboss.mx.loading.UnifiedLoaderRepository3@aeea66, cl=org.jboss.mx.loading.UnifiedClassLoader3@1c3d029{ url=file:/C:/jboss/jboss-4.0.2/server/default/tmp/deploy/tmp16872All.jar ,addedOrder=0}
15:11:09,669 DEBUG [RepositoryClassLoader] setRepository, repository=org.jboss.mx.loading.UnifiedLoaderRepository3@aeea66, cl=org.jboss.mx.loading.UnifiedClassLoader3@1c3d029{ url=file:/C:/jboss/jboss-4.0.2/server/default/tmp/deploy/tmp16872All.jar ,addedOrder=0}
15:11:09,715 DEBUG [UnifiedLoaderRepository3] Adding org.jboss.mx.loading.UnifiedClassLoader3@1c3d029{ url=file:/C:/jboss/jboss-4.0.2/server/default/tmp/deploy/tmp16872All.jar ,addedOrder=0}
15:11:09,715 DEBUG [ClassLoaderUtils] Multiple class loaders found for pkg:
15:11:09,715 DEBUG [ClassLoaderUtils] Multiple class loaders found for pkg: com.blackdog.gl.domain
15:11:09,731 DEBUG [MainDeployer] found 0 subpackages of file:/C:/jboss/jboss-4.0.2/server/default/deploy/All.jar
15:11:09,731 DEBUG [MainDeployer] Watching new file: file:/C:/jboss/jboss-4.0.2/server/default/deploy/All.jar
15:11:09,731 DEBUG [MainDeployer] create step for deployment file:/C:/jboss/jboss-4.0.2/server/default/deploy/All.jar
15:11:09,731 DEBUG [MainDeployer] Done with create step of deploying All.jar
15:11:09,731 DEBUG [MainDeployer] Begin deployment start file:/C:/jboss/jboss-4.0.2/server/default/deploy/All.jar
15:11:09,731 DEBUG [MainDeployer] End deployment start on package: All.jar
15:11:09,731 DEBUG [MainDeployer] Deployed package: file:/C:/jboss/jboss-4.0.2/server/default/deploy/All.jar
15:11:09,731 DEBUG [URLDeploymentScanner] Watch URL for: file:/C:/jboss/jboss-4.0.2/server/default/deploy/All.jar -> file:/C:/jboss/jboss-4.0.2/server/default/deploy/All.jar
15:11:09,731 DEBUG [MainDeployer] Starting deployment of package: file:/C:/jboss/jboss-4.0.2/server/default/deploy/Resource.jar
15:11:09,731 DEBUG [MainDeployer] Starting deployment (init step) of package at: file:/C:/jboss/jboss-4.0.2/server/default/deploy/Resource.jar
15:11:09,731 DEBUG [MainDeployer] Copying file:/C:/jboss/jboss-4.0.2/server/default/deploy/Resource.jar -> C:\jboss\jboss-4.0.2\server\default\tmp\deploy\tmp16873Resource.jar
15:11:09,778 DEBUG [JARDeployer] No META-INF or WEB-INF resource found, assuming it if for us
15:11:09,778 DEBUG [MainDeployer] using deployer org.jboss.deployment.JARDeployer@ea5461
15:11:09,778 DEBUG [JARDeployer] looking for nested deployments in : file:/C:/jboss/jboss-4.0.2/server/default/deploy/Resource.jar
15:11:09,794 DEBUG [DeploymentInfo] createLoaderRepository from config: LoaderRepositoryConfig(repositoryName: JMImplementation:service=LoaderRepository,name=Default, repositoryClassName: null, configParserClassName: null, repositoryConfig: null)
15:11:09,794 DEBUG [UnifiedClassLoader] New jmx UCL with url file:/C:/jboss/jboss-4.0.2/server/default/tmp/deploy/tmp16873Resource.jar
15:11:09,794 DEBUG [RepositoryClassLoader] setRepository, repository=org.jboss.mx.loading.UnifiedLoaderRepository3@aeea66, cl=org.jboss.mx.loading.UnifiedClassLoader3@974e4b{ url=file:/C:/jboss/jboss-4.0.2/server/default/tmp/deploy/tmp16873Resource.jar ,addedOrder=0}
15:11:09,794 DEBUG [RepositoryClassLoader] setRepository, repository=org.jboss.mx.loading.UnifiedLoaderRepository3@aeea66, cl=org.jboss.mx.loading.UnifiedClassLoader3@974e4b{ url=file:/C:/jboss/jboss-4.0.2/server/default/tmp/deploy/tmp16873Resource.jar ,addedOrder=0}
15:11:09,794 DEBUG [UnifiedLoaderRepository3] Adding org.jboss.mx.loading.UnifiedClassLoader3@974e4b{ url=file:/C:/jboss/jboss-4.0.2/server/default/tmp/deploy/tmp16873Resource.jar ,addedOrder=0}
15:11:09,794 DEBUG [ClassLoaderUtils] Multiple class loaders found for pkg: com.blackdog.gl.configbean
15:11:09,794 DEBUG [ClassLoaderUtils] Multiple class loaders found for pkg: com.blackdog.gl.resources
15:11:09,794 DEBUG [ClassLoaderUtils] Multiple class loaders found for pkg:
15:11:09,794 DEBUG [MainDeployer] found 0 subpackages of file:/C:/jboss/jboss-4.0.2/server/default/deploy/Resource.jar
15:11:09,794 DEBUG [MainDeployer] Watching new file: file:/C:/jboss/jboss-4.0.2/server/default/deploy/Resource.jar
15:11:09,794 DEBUG [MainDeployer] create step for deployment file:/C:/jboss/jboss-4.0.2/server/default/deploy/Resource.jar
15:11:09,794 DEBUG [MainDeployer] Done with create step of deploying Resource.jar
15:11:09,794 DEBUG [MainDeployer] Begin deployment start file:/C:/jboss/jboss-4.0.2/server/default/deploy/Resource.jar
15:11:09,794 DEBUG [MainDeployer] End deployment start on package: Resource.jar
15:11:09,794 DEBUG [MainDeployer] Deployed package: file:/C:/jboss/jboss-4.0.2/server/default/deploy/Resource.jar
15:11:09,794 DEBUG [URLDeploymentScanner] Watch URL for: file:/C:/jboss/jboss-4.0.2/server/default/deploy/Resource.jar -> file:/C:/jboss/jboss-4.0.2/server/default/deploy/Resource.jar
15:11:09,794 DEBUG [MainDeployer] Starting deployment of package: file:/C:/jboss/jboss-4.0.2/server/default/deploy/jmx-console.war/
15:11:09,794 DEBUG [MainDeployer] Starting deployment (init step) of package at: file:/C:/jboss/jboss-4.0.2/server/default/deploy/jmx-console.war/
15:11:09,794 DEBUG [MainDeployer] using deployer org.jboss.web.tomcat.tc5.Tomcat5@ef7d74
15:11:09,794 DEBUG [Tomcat5] Begin init
15:11:09,794 DEBUG [Tomcat5] webContext: null
15:11:09,794 DEBUG [Tomcat5] warURL: file:/C:/jboss/jboss-4.0.2/server/default/deploy/jmx-console.war/
15:11:09,872 DEBUG [ServiceDeployer] handleNotification: org.jboss.deployment.SubDeployer.init,jmx-console.war
15:11:09,872 DEBUG [Tomcat5] End init
15:11:09,872 DEBUG [DeploymentInfo] createLoaderRepository from config: LoaderRepositoryConfig(repositoryName: JMImplementation:service=LoaderRepository,name=Default, repositoryClassName: null, configParserClassName: null, repositoryConfig: null)
15:11:09,872 DEBUG [UnifiedClassLoader] New jmx UCL with url file:/C:/jboss/jboss-4.0.2/server/default/deploy/jmx-console.war/
15:11:09,872 DEBUG [RepositoryClassLoader] setRepository, repository=org.jboss.mx.loading.UnifiedLoaderRepository3@aeea66, cl=org.jboss.mx.loading.UnifiedClassLoader3@1955dd4{ url=file:/C:/jboss/jboss-4.0.2/server/default/deploy/jmx-console.war/ ,addedOrder=0}
15:11:09,872 DEBUG [RepositoryClassLoader] setRepository, repository=org.jboss.mx.loading.UnifiedLoaderRepository3@aeea66, cl=org.jboss.mx.loading.UnifiedClassLoader3@1955dd4{ url=file:/C:/jboss/jboss-4.0.2/server/default/deploy/jmx-console.war/ ,addedOrder=0}
15:11:09,872 DEBUG [UnifiedLoaderRepository3] Adding org.jboss.mx.loading.UnifiedClassLoader3@1955dd4{ url=file:/C:/jboss/jboss-4.0.2/server/default/deploy/jmx-console.war/ ,addedOrder=0}
15:11:09,872 DEBUG [MainDeployer] found 0 subpackages of file:/C:/jboss/jboss-4.0.2/server/default/deploy/jmx-console.war/
15:11:09,872 DEBUG [MainDeployer] Watching new file: file:/C:/jboss/jboss-4.0.2/server/default/deploy/jmx-console.war/
15:11:09,872 DEBUG [MainDeployer] create step for deployment file:/C:/jboss/jboss-4.0.2/server/default/deploy/jmx-console.war/
15:11:09,872 DEBUG [ServiceController] Creating service jboss.web.deployment:war=jmx-console.war,id=-696842805
15:11:09,872 DEBUG [ServiceController] adding depends in ServiceController.register: []
15:11:09,872 DEBUG [WebModule] Creating jboss.web.deployment:war=jmx-console.war,id=-696842805
15:11:09,872 DEBUG [WebModule] Created jboss.web.deployment:war=jmx-console.war,id=-696842805
15:11:09,872 DEBUG [ServiceController] Creating dependent components for: jboss.web.deployment:war=jmx-console.war,id=-696842805 dependents are: []
15:11:09,872 DEBUG [ServiceDeployer] handleNotification: org.jboss.deployment.SubDeployer.create,jmx-console.war
15:11:09,872 DEBUG [MainDeployer] Done with create step of deploying jmx-console.war
15:11:09,872 DEBUG [MainDeployer] Begin deployment start file:/C:/jboss/jboss-4.0.2/server/default/deploy/jmx-console.war/
15:11:09,872 DEBUG [ServiceController] starting service jboss.web.deployment:war=jmx-console.war,id=-696842805
15:11:09,872 DEBUG [WebModule] Starting jboss.web.deployment:war=jmx-console.war,id=-696842805
15:11:09,887 DEBUG [TomcatDeployer] webContext: null
15:11:09,887 DEBUG [TomcatDeployer] warURL: file:/C:/jboss/jboss-4.0.2/server/default/deploy/jmx-console.war/
15:11:09,887 DEBUG [TomcatDeployer] webAppParser: org.jboss.web.AbstractWebDeployer$DescriptorParser@14322ba
15:11:09,887 DEBUG [WebPermissionMapping] Qualified url patterns: {/=PatternInfo[pattern=/,type=3,isOverriden=false,qualifiers=[]]}
15:11:09,887 INFO [TomcatDeployer] deploy, ctxPath=/jmx-console, warUrl=file:/C:/jboss/jboss-4.0.2/server/default/deploy/jmx-console.war/
15:11:09,950 DEBUG [TomcatDeployer] AbstractWebContainer.parseWebAppDescriptors, Begin
15:11:09,950 DEBUG [TomcatDeployer] Creating ENC using ClassLoader: java.net.FactoryURLClassLoader@9d04dc
15:11:09,950 DEBUG [TomcatDeployer] ..org.jboss.mx.loading.UnifiedClassLoader3@1955dd4{ url=file:/C:/jboss/jboss-4.0.2/server/default/deploy/jmx-console.war/ ,addedOrder=41}
15:11:09,950 DEBUG [TomcatDeployer] ..org.jboss.system.server.NoAnnotationURLClassLoader@704baa
15:11:09,950 DEBUG [TomcatDeployer] ..sun.misc.Launcher$AppClassLoader@a39137
15:11:09,950 DEBUG [TomcatDeployer] ..sun.misc.Launcher$ExtClassLoader@92e78c
15:11:09,950 DEBUG [TomcatDeployer] Unable to retrieve orbjavax.management.InstanceNotFoundException: jboss:service=CorbaORB is not registered.
15:11:09,950 DEBUG [TomcatDeployer] Linked java:comp/UserTransaction to JNDI name: UserTransaction
15:11:09,950 DEBUG [TomcatDeployer] addEnvEntries
15:11:09,950 DEBUG [TomcatDeployer] linkResourceEnvRefs
15:11:09,950 DEBUG [TomcatDeployer] linkResourceRefs
15:11:09,950 DEBUG [TomcatDeployer] linkMessageDestinationRefs
15:11:09,950 DEBUG [TomcatDeployer] linkEjbRefs
15:11:09,950 DEBUG [TomcatDeployer] linkEjbLocalRefs
15:11:09,950 DEBUG [TomcatDeployer] linkServiceRefs
15:11:09,950 DEBUG [TomcatDeployer] linkSecurityDomain
15:11:09,950 DEBUG [TomcatDeployer] No security-domain given, using default: java:/jaas/other
15:11:09,950 DEBUG [TomcatDeployer] Linking security/securityMgr to JNDI name: java:/jaas/other
15:11:09,965 DEBUG [TomcatDeployer] AbstractWebContainer.parseWebAppDescriptors, End
15:11:10,044 DEBUG [TomcatDeployer] Using session cookies default setting
15:11:10,200 DEBUG [ReplyHeaderFilter] Adding header name: X-Powered-By='Servlet 2.4; JBoss-4.0.2 (build: CVSTag=JBoss_4_0_2 date=200505022023)/Tomcat-5.5'
15:11:10,372 DEBUG [TomcatDeployer] Initialized: {WebApplication: /C:/jboss/jboss-4.0.2/server/default/deploy/jmx-console.war/, URL: file:/C:/jboss/jboss-4.0.2/server/default/deploy/jmx-console.war/, classLoader: java.net.FactoryURLClassLoader@9d04dc:10290396} jboss.web:j2eeType=WebModule,name=//localhost/jmx-console,J2EEApplication=none,J2EEServer=none
15:11:10,372 DEBUG [WebModule] Started jboss.web.deployment:war=jmx-console.war,id=-696842805
15:11:10,372 DEBUG [ServiceController] Starting dependent components for: jboss.web.deployment:war=jmx-console.war,id=-696842805 dependent components: []
15:11:10,372 DEBUG [ServiceDeployer] handleNotification: org.jboss.deployment.SubDeployer.start,jmx-console.war
15:11:10,528 DEBUG [MainDeployer] End deployment start on package: jmx-console.war
15:11:10,528 DEBUG [MainDeployer] Deployed package: file:/C:/jboss/jboss-4.0.2/server/default/deploy/jmx-console.war/
15:11:10,544 DEBUG [URLDeploymentScanner] Watch URL for: file:/C:/jboss/jboss-4.0.2/server/default/deploy/jmx-console.war/ -> file:/C:/jboss/jboss-4.0.2/server/default/deploy/jmx-console.war/WEB-INF/web.xml
15:11:10,544 DEBUG [MainDeployer] Starting deployment of package: file:/C:/jboss/jboss-4.0.2/server/default/deploy/GlApp.ear
15:11:10,544 DEBUG [MainDeployer] Starting deployment (init step) of package at: file:/C:/jboss/jboss-4.0.2/server/default/deploy/GlApp.ear
15:11:10,544 DEBUG [MainDeployer] Copying file:/C:/jboss/jboss-4.0.2/server/default/deploy/GlApp.ear -> C:\jboss\jboss-4.0.2\server\default\tmp\deploy\tmp16874GlApp.ear
15:11:10,544 DEBUG [MainDeployer] using deployer org.jboss.deployment.EARDeployer@d61301
15:11:10,544 INFO [EARDeployer] Init J2EE application: file:/C:/jboss/jboss-4.0.2/server/default/deploy/GlApp.ear
15:11:10,606 DEBUG [EARDeployer] Extracted non-deployable content: META-INF/MANIFEST.MF
15:11:10,622 DEBUG [EARDeployer] Extracted non-deployable content: META-INF/application.xml
15:11:10,622 DEBUG [EARDeployer] Extracted deployable content: GLEJB.jar
15:11:10,637 DEBUG [EARDeployer] Extracted deployable content: GLWeb.war
15:11:10,637 DEBUG [EARDeployer] Extracted non-deployable content: META-INF/jboss-app.xml
15:11:10,653 DEBUG [EARDeployer] Extracted deployable content: gl.har
15:11:10,653 DEBUG [EARDeployer] Deployment Info: org.jboss.deployment.DeploymentInfo@ee09ec8f { url=file:/C:/jboss/jboss-4.0.2/server/default/tmp/deploy/tmp16874GlApp.ear-contents/GLEJB.jar }
deployer: null
status: null
state: CONSTRUCTED
watch: file:/C:/jboss/jboss-4.0.2/server/default/tmp/deploy/tmp16874GlApp.ear-contents/GLEJB.jar
altDD: null
lastDeployed: 0
lastModified: 0
mbeans:
, isDirectory: false
15:11:10,653 DEBUG [EARDeployer] Deployment Info: org.jboss.deployment.DeploymentInfo@d61010d3 { url=file:/C:/jboss/jboss-4.0.2/server/default/tmp/deploy/tmp16874GlApp.ear-contents/GLWeb.war }
deployer: null
status: null
state: CONSTRUCTED
watch: file:/C:/jboss/jboss-4.0.2/server/default/tmp/deploy/tmp16874GlApp.ear-contents/GLWeb.war
altDD: null
lastDeployed: 0
lastModified: 0
mbeans:
, isDirectory: false
15:11:10,669 DEBUG [EARDeployer] Deployment Info: org.jboss.deployment.DeploymentInfo@fc84673c { url=file:/C:/jboss/jboss-4.0.2/server/default/tmp/deploy/tmp16874GlApp.ear-contents/gl.har }
deployer: null
status: null
state: CONSTRUCTED
watch: file:/C:/jboss/jboss-4.0.2/server/default/tmp/deploy/tmp16874GlApp.ear-contents/gl.har
altDD: null
lastDeployed: 0
lastModified: 0
mbeans:
, isDirectory: false
15:11:10,700 DEBUG [DeploymentInfo] createLoaderRepository from config: LoaderRepositoryConfig(repositoryName: JMImplementation:service=LoaderRepository,name=Default, repositoryClassName: null, configParserClassName: null, repositoryConfig: null)
15:11:10,700 DEBUG [UnifiedClassLoader] New jmx UCL with url file:/C:/jboss/jboss-4.0.2/server/default/tmp/deploy/tmp16874GlApp.ear
15:11:10,700 DEBUG [RepositoryClassLoader] setRepository, repository=org.jboss.mx.loading.UnifiedLoaderRepository3@aeea66, cl=org.jboss.mx.loading.UnifiedClassLoader3@c5dbb{ url=file:/C:/jboss/jboss-4.0.2/server/default/tmp/deploy/tmp16874GlApp.ear ,addedOrder=0}
15:11:10,700 DEBUG [RepositoryClassLoader] setRepository, repository=org.jboss.mx.loading.UnifiedLoaderRepository3@aeea66, cl=org.jboss.mx.loading.UnifiedClassLoader3@c5dbb{ url=file:/C:/jboss/jboss-4.0.2/server/default/tmp/deploy/tmp16874GlApp.ear ,addedOrder=0}
15:11:10,700 DEBUG [UnifiedLoaderRepository3] Adding org.jboss.mx.loading.UnifiedClassLoader3@c5dbb{ url=file:/C:/jboss/jboss-4.0.2/server/default/tmp/deploy/tmp16874GlApp.ear ,addedOrder=0}
15:11:10,700 DEBUG [ClassLoaderUtils] Multiple class loaders found for pkg:
15:11:10,700 DEBUG [MainDeployer] found 3 subpackages of file:/C:/jboss/jboss-4.0.2/server/default/deploy/GlApp.ear
15:11:10,700 DEBUG [MainDeployer] Starting deployment (init step) of package at: file:/C:/jboss/jboss-4.0.2/server/default/tmp/deploy/tmp16874GlApp.ear-contents/gl.har
15:11:10,715 DEBUG [HARDeployer] accept> url=file:/C:/jboss/jboss-4.0.2/server/default/tmp/deploy/tmp16874GlApp.ear-contents/gl.har, accepted=true
15:11:10,715 DEBUG [MainDeployer] using deployer org.jboss.hibernate.har.HARDeployer@19b6225
15:11:10,715 DEBUG [HARDeployer] Deploying HAR; init; org.jboss.deployment.DeploymentInfo@fc84673c { url=file:/C:/jboss/jboss-4.0.2/server/default/tmp/deploy/tmp16874GlApp.ear-contents/gl.har }
deployer: org.jboss.hibernate.har.HARDeployer@19b6225
status: null
state: INIT_DEPLOYER
watch: file:/C:/jboss/jboss-4.0.2/server/default/tmp/deploy/tmp16874GlApp.ear-contents/gl.har
altDD: null
lastDeployed: 0
lastModified: 0
mbeans:

15:11:10,715 DEBUG [HARDeployer] looking for nested deployments in : file:/C:/jboss/jboss-4.0.2/server/default/tmp/deploy/tmp16874GlApp.ear-contents/gl.har
15:11:10,715 DEBUG [HARDeployer] Checking deployability of [name=META-INF/MANIFEST.MF, url=file:/C:/jboss/jboss-4.0.2/server/default/tmp/deploy/tmp16874GlApp.ear-contents/gl.har!/META-INF/MANIFEST.MF]
15:11:10,715 DEBUG [HARDeployer] Checking deployability of [name=com/blackdog/gl/domain/SecurityEntity.class, url=file:/C:/jboss/jboss-4.0.2/server/default/tmp/deploy/tmp16874GlApp.ear-contents/gl.har!/com/blackdog/gl/domain/SecurityEntity.class]
15:11:10,715 DEBUG [HARDeployer] Checking deployability of [name=com/blackdog/gl/domain/Ledger.class, url=file:/C:/jboss/jboss-4.0.2/server/default/tmp/deploy/tmp16874GlApp.ear-contents/gl.har!/com/blackdog/gl/domain/Ledger.class]
15:11:10,747 DEBUG [HARDeployer] Checking deployability of [name=com/blackdog/gl/domain/Company.class, url=file:/C:/jboss/jboss-4.0.2/server/default/tmp/deploy/tmp16874GlApp.ear-contents/gl.har!/com/blackdog/gl/domain/Company.class]
15:11:10,747 DEBUG [HARDeployer] Checking deployability of [name=com/blackdog/gl/domain/base/Address.class, url=file:/C:/jboss/jboss-4.0.2/server/default/tmp/deploy/tmp16874GlApp.ear-contents/gl.har!/com/blackdog/gl/domain/base/Address.class]
15:11:10,747 DEBUG [HARDeployer] Checking deployability of [name=com/blackdog/gl/domain/base/DomainObject.class, url=file:/C:/jboss/jboss-4.0.2/server/default/tmp/deploy/tmp16874GlApp.ear-contents/gl.har!/com/blackdog/gl/domain/base/DomainObject.class]
15:11:10,747 DEBUG [HARDeployer] Checking deployability of [name=com/blackdog/gl/domain/base/Person.class, url=file:/C:/jboss/jboss-4.0.2/server/default/tmp/deploy/tmp16874GlApp.ear-contents/gl.har!/com/blackdog/gl/domain/base/Person.class]
15:11:10,747 DEBUG [HARDeployer] Checking deployability of [name=com/blackdog/gl/domain/hibernate/Ledger.hbm.xml, url=file:/C:/jboss/jboss-4.0.2/server/default/tmp/deploy/tmp16874GlApp.ear-contents/gl.har!/com/blackdog/gl/domain/hibernate/Ledger.hbm.xml]
15:11:10,747 DEBUG [HARDeployer] Checking deployability of [name=com/blackdog/gl/domain/key/LedgerKey.class, url=file:/C:/jboss/jboss-4.0.2/server/default/tmp/deploy/tmp16874GlApp.ear-contents/gl.har!/com/blackdog/gl/domain/key/LedgerKey.class]
15:11:10,747 DEBUG [HARDeployer] Checking deployability of [name=com/blackdog/gl/domain/key/CompanyKey.class, url=file:/C:/jboss/jboss-4.0.2/server/default/tmp/deploy/tmp16874GlApp.ear-contents/gl.har!/com/blackdog/gl/domain/key/CompanyKey.class]
15:11:10,747 DEBUG [HARDeployer] Checking deployability of [name=com/blackdog/gl/domain/key/SecurityEntityKey.class, url=file:/C:/jboss/jboss-4.0.2/server/default/tmp/deploy/tmp16874GlApp.ear-contents/gl.har!/com/blackdog/gl/domain/key/SecurityEntityKey.class]
15:11:10,747 DEBUG [HARDeployer] Checking deployability of [name=.classpath, url=file:/C:/jboss/jboss-4.0.2/server/default/tmp/deploy/tmp16874GlApp.ear-contents/gl.har!/.classpath]
15:11:10,747 DEBUG [HARDeployer] Checking deployability of [name=.project, url=file:/C:/jboss/jboss-4.0.2/server/default/tmp/deploy/tmp16874GlApp.ear-contents/gl.har!/.project]
15:11:10,747 DEBUG [HARDeployer] Checking deployability of [name=.settings/org.eclipse.jdt.core.prefs, url=file:/C:/jboss/jboss-4.0.2/server/default/tmp/deploy/tmp16874GlApp.ear-contents/gl.har!/.settings/org.eclipse.jdt.core.prefs]
15:11:10,747 DEBUG [HARDeployer] Checking deployability of [name=META-INF/hibernate-service.xml, url=file:/C:/jboss/jboss-4.0.2/server/default/tmp/deploy/tmp16874GlApp.ear-contents/gl.har!/META-INF/hibernate-service.xml]
15:11:10,747 DEBUG [HARDeployer] nested deployment: file:/C:/jboss/jboss-4.0.2/server/default/tmp/deploy/tmp16874GlApp.ear-contents/gl.har-contents/META-INF/hibernate-service.xml
15:11:10,747 DEBUG [ClassLoaderUtils] Multiple class loaders found for pkg: com.blackdog.gl.domain
15:11:10,747 DEBUG [ClassLoaderUtils] Multiple class loaders found for pkg: com.blackdog.gl.domain.base
15:11:10,747 DEBUG [ClassLoaderUtils] Multiple class loaders found for pkg: com.blackdog.gl.domain.key
15:11:10,747 DEBUG [ClassLoaderUtils] Multiple class loaders found for pkg: .settings
15:11:10,747 DEBUG [RepositoryClassLoader] Added url: file:/C:/jboss/jboss-4.0.2/server/default/tmp/deploy/tmp16874GlApp.ear-contents/gl.har, to ucl: org.jboss.mx.loading.UnifiedClassLoader3@c5dbb{ url=file:/C:/jboss/jboss-4.0.2/server/default/tmp/deploy/tmp16874GlApp.ear ,addedOrder=42}
15:11:10,747 DEBUG [MainDeployer] found 1 subpackages of file:/C:/jboss/jboss-4.0.2/server/default/tmp/deploy/tmp16874GlApp.ear-contents/gl.har
15:11:10,747 DEBUG [MainDeployer] Starting deployment (init step) of package at: file:/C:/jboss/jboss-4.0.2/server/default/tmp/deploy/tmp16874GlApp.ear-contents/gl.har-contents/META-INF/hibernate-service.xml
15:11:10,747 DEBUG [MainDeployer] using deployer org.jboss.deployment.SARDeployer@15b0e2c
15:11:10,747 DEBUG [SARDeployer] about to copy 0 local directories
15:11:10,747 DEBUG [SARDeployer] looking for nested deployments in : file:/C:/jboss/jboss-4.0.2/server/default/tmp/deploy/tmp16874GlApp.ear-contents/gl.har-contents/META-INF/hibernate-service.xml
15:11:10,747 DEBUG [RepositoryClassLoader] Added url: file:/C:/jboss/jboss-4.0.2/server/default/tmp/deploy/tmp16874GlApp.ear-contents/gl.har-contents/META-INF/hibernate-service.xml, to ucl: org.jboss.mx.loading.UnifiedClassLoader3@c5dbb{ url=file:/C:/jboss/jboss-4.0.2/server/default/tmp/deploy/tmp16874GlApp.ear ,addedOrder=42}
15:11:10,747 DEBUG [MainDeployer] found 0 subpackages of file:/C:/jboss/jboss-4.0.2/server/default/tmp/deploy/tmp16874GlApp.ear-contents/gl.har-contents/META-INF/hibernate-service.xml
15:11:10,747 DEBUG [MainDeployer] Starting deployment (init step) of package at: file:/C:/jboss/jboss-4.0.2/server/default/tmp/deploy/tmp16874GlApp.ear-contents/GLEJB.jar
15:11:10,747 DEBUG [MainDeployer] using deployer org.jboss.ejb.EJBDeployer@3e1d25
15:11:10,747 DEBUG [EJBDeployer] init, GLEJB.jar
15:11:10,856 DEBUG [EJBDeployer] looking for nested deployments in : file:/C:/jboss/jboss-4.0.2/server/default/tmp/deploy/tmp16874GlApp.ear-contents/GLEJB.jar
15:11:10,872 DEBUG [ServiceDeployer] handleNotification: org.jboss.deployment.SubDeployer.init,GLEJB.jar
15:11:10,872 DEBUG [RepositoryClassLoader] Added url: file:/C:/jboss/jboss-4.0.2/server/default/tmp/deploy/tmp16874GlApp.ear-contents/GLEJB.jar, to ucl: org.jboss.mx.loading.UnifiedClassLoader3@c5dbb{ url=file:/C:/jboss/jboss-4.0.2/server/default/tmp/deploy/tmp16874GlApp.ear ,addedOrder=42}
15:11:10,872 DEBUG [MainDeployer] found 0 subpackages of file:/C:/jboss/jboss-4.0.2/server/default/tmp/deploy/tmp16874GlApp.ear-contents/GLEJB.jar
15:11:10,872 DEBUG [MainDeployer] Starting deployment (init step) of package at: file:/C:/jboss/jboss-4.0.2/server/default/tmp/deploy/tmp16874GlApp.ear-contents/GLWeb.war
15:11:10,872 DEBUG [MainDeployer] using deployer org.jboss.web.tomcat.tc5.Tomcat5@ef7d74
15:11:10,872 DEBUG [Tomcat5] Begin init
15:11:10,872 DEBUG [Tomcat5] Unpacking war to: C:\jboss\jboss-4.0.2\server\default\tmp\deploy\tmp16874GlApp.ear-contents\GLWeb-exp.war
15:11:11,012 DEBUG [Tomcat5] Replaced war with unpacked contents
15:11:11,012 DEBUG [Tomcat5] webContext: /gl
15:11:11,012 DEBUG [Tomcat5] warURL: file:/C:/jboss/jboss-4.0.2/server/default/tmp/deploy/tmp16874GlApp.ear-contents/GLWeb-exp.war/
15:11:11,090 DEBUG [ServiceDeployer] handleNotification: org.jboss.deployment.SubDeployer.init,GLWeb.war
15:11:11,090 DEBUG [Tomcat5] End init
15:11:11,090 DEBUG [RepositoryClassLoader] Added url: file:/C:/jboss/jboss-4.0.2/server/default/tmp/deploy/tmp16874GlApp.ear-contents/GLWeb-exp.war/, to ucl: org.jboss.mx.loading.UnifiedClassLoader3@c5dbb{ url=file:/C:/jboss/jboss-4.0.2/server/default/tmp/deploy/tmp16874GlApp.ear ,addedOrder=42}
15:11:11,090 DEBUG [MainDeployer] found 0 subpackages of file:/C:/jboss/jboss-4.0.2/server/default/tmp/deploy/tmp16874GlApp.ear-contents/GLWeb.war
15:11:11,090 DEBUG [MainDeployer] Watching new file: file:/C:/jboss/jboss-4.0.2/server/default/deploy/GlApp.ear
15:11:11,090 DEBUG [MainDeployer] create step for deployment file:/C:/jboss/jboss-4.0.2/server/default/deploy/GlApp.ear
15:11:11,090 DEBUG [MainDeployer] create step for deployment file:/C:/jboss/jboss-4.0.2/server/default/tmp/deploy/tmp16874GlApp.ear-contents/gl.har
15:11:11,090 DEBUG [MainDeployer] create step for deployment file:/C:/jboss/jboss-4.0.2/server/default/tmp/deploy/tmp16874GlApp.ear-contents/gl.har-contents/META-INF/hibernate-service.xml
15:11:11,090 DEBUG [SARDeployer] Deploying SAR, create step: url file:/C:/jboss/jboss-4.0.2/server/default/tmp/deploy/tmp16874GlApp.ear-contents/gl.har-contents/META-INF/hibernate-service.xml
15:11:11,090 DEBUG [SARDeployer] Registering service UCL=jmx.loading:UCL=c5dbb
15:11:11,106 DEBUG [ServiceCreator] About to create bean: jboss.jca:service=HibernateFactory with code: org.hibernate.jmx.HibernateService
15:11:11,247 DEBUG [ServiceCreator] Created bean: jboss.jca:service=HibernateFactory
15:11:11,247 DEBUG [ServiceController] recording that jboss.jca:service=HibernateFactory depends on jboss.jca:service=RARDeployer
15:11:11,247 DEBUG [ServiceConfigurator] considering <anonymous> with object name jboss.jca:service=RARDeployer
15:11:11,247 DEBUG [ServiceController] recording that jboss.jca:service=HibernateFactory depends on jboss.jca:service=LocalTxCM,name=jdbc/OracleDS
15:11:11,247 DEBUG [ServiceConfigurator] considering <anonymous> with object name jboss.jca:service=LocalTxCM,name=jdbc/OracleDS
15:11:11,247 DEBUG [ServiceConfigurator] JndiName set to java:/hibernate/SessionFactory in jboss.jca:service=HibernateFactory
15:11:11,247 DEBUG [ServiceConfigurator] Datasource set to java:/jdbc/OracleDS in jboss.jca:service=HibernateFactory
15:11:11,262 DEBUG [ServiceConfigurator] Dialect set to org.hibernate.dialect.HSQLDialect in jboss.jca:service=HibernateFactory
15:11:11,262 DEBUG [ServiceConfigurator] TransactionStrategy set to org.hibernate.transaction.JTATransactionFactory in jboss.jca:service=HibernateFactory
15:11:11,278 DEBUG [ServiceConfigurator] TransactionManagerLookupStrategy set to org.hibernate.transaction.JBossTransactionManagerLookup in jboss.jca:service=HibernateFactory
15:11:11,278 DEBUG [ServiceConfigurator] FlushBeforeCompletionEnabled set to true in jboss.jca:service=HibernateFactory
15:11:11,294 DEBUG [ServiceConfigurator] AutoCloseSessionEnabled set to true in jboss.jca:service=HibernateFactory
15:11:11,294 DEBUG [ServiceConfigurator] MaximumFetchDepth set to 3 in jboss.jca:service=HibernateFactory
15:11:11,294 DEBUG [ServiceConfigurator] SecondLevelCacheEnabled set to false in jboss.jca:service=HibernateFactory
15:11:11,309 DEBUG [ServiceConfigurator] ShowSqlEnabled set to True in jboss.jca:service=HibernateFactory
15:11:11,309 DEBUG [ServiceConfigurator] MapResources set to ledger.hbm.xml in jboss.jca:service=HibernateFactory
15:11:11,309 DEBUG [ServiceController] Creating service jboss.jca:service=HibernateFactory
15:11:11,309 DEBUG [ServiceController] waiting in create of jboss.jca:service=HibernateFactory waiting on jboss.jca:service=LocalTxCM,name=jdbc/OracleDS
15:11:11,309 DEBUG [MainDeployer] Done with create step of deploying hibernate-service.xml
15:11:11,309 DEBUG [HARDeployer] Deploying HAR; create; org.jboss.deployment.DeploymentInfo@fc84673c { url=file:/C:/jboss/jboss-4.0.2/server/default/tmp/deploy/tmp16874GlApp.ear-contents/gl.har }
deployer: org.jboss.hibernate.har.HARDeployer@19b6225
status: null
state: CREATE_DEPLOYER
watch: file:/C:/jboss/jboss-4.0.2/server/default/tmp/deploy/tmp16874GlApp.ear-contents/gl.har
altDD: null
lastDeployed: 1124219470747
lastModified: 1124219470637
mbeans:

15:11:11,309 DEBUG [HARDeployer] Checking sub-deployment [file:/C:/jboss/jboss-4.0.2/server/default/tmp/deploy/tmp16874GlApp.ear-contents/gl.har-contents/META-INF/hibernate-service.xml] for descriptor-name
15:11:11,309 DEBUG [HARDeployer] Attempting to locate HibernateMBean in sub-deployment [file:/C:/jboss/jboss-4.0.2/server/default/tmp/deploy/tmp16874GlApp.ear-contents/gl.har-contents/META-INF/hibernate-service.xml]
15:11:11,309 DEBUG [HARDeployer] Testing [jboss.jca:service=HibernateFactory] as HibernateMBean
15:11:11,325 DEBUG [MainDeployer] Done with create step of deploying gl.har
15:11:11,325 DEBUG [MainDeployer] create step for deployment file:/C:/jboss/jboss-4.0.2/server/default/tmp/deploy/tmp16874GlApp.ear-contents/GLEJB.jar
15:11:11,325 DEBUG [EJBDeployer] create, GLEJB.jar
15:11:11,622 DEBUG [EJBDeployer] Verifying file:/C:/jboss/jboss-4.0.2/server/default/tmp/deploy/tmp16874GlApp.ear-contents/GLEJB.jar
15:11:11,809 DEBUG [verifier] Bean checked: LedgerManager: Verified.
15:11:11,919 DEBUG [EJBDeployer] Deploying: file:/C:/jboss/jboss-4.0.2/server/default/tmp/deploy/tmp16874GlApp.ear-contents/GLEJB.jar
15:11:11,919 DEBUG [ServiceController] Creating service jboss.j2ee:service=EjbModule,module=GLEJB.jar
15:11:11,919 DEBUG [EjbModule] Creating jboss.j2ee:service=EjbModule,module=GLEJB.jar
15:11:11,934 DEBUG [EjbModule] createService, begin
15:11:11,934 INFO [EjbModule] Deploying LedgerManager
15:11:11,997 DEBUG [EjbModule] Creating WebClassLoader of class org.jboss.web.WebClassLoader
15:11:12,153 DEBUG [EjbModule] creating binding for ejb/LedgerManager:stateless-rmi-invoker
15:11:12,247 DEBUG [ServiceController] Creating service jboss.j2ee:jndiName=ejb/LedgerManager,service=EJB
15:11:12,247 DEBUG [ServiceController] adding depends in ServiceController.register: []
15:11:12,247 DEBUG [StatelessSessionContainer] Creating jboss.j2ee:jndiName=ejb/LedgerManager,service=EJB
15:11:12,356 DEBUG [StatelessSessionContainer] Mapped createLedger HASH -1480948368to public java.lang.String com.blackdog.gl.ejb.LedgerManagerBean.createLedger(com.blackdog.gl.domain.Ledger) throws com.blackdog.common.exception.BaseException
15:11:12,356 DEBUG [StatelessSessionContainer] Mapped getLedger HASH -903554902to public com.blackdog.gl.domain.Ledger com.blackdog.gl.ejb.LedgerManagerBean.getLedger(com.blackdog.gl.domain.key.LedgerKey) throws com.blackdog.common.exception.BaseException
15:11:12,356 DEBUG [StatelessSessionContainer] Mapped Container method remove HASH -1842617161
15:11:12,356 DEBUG [StatelessSessionContainer] Mapped Container method getHandle HASH 1182305581
15:11:12,356 DEBUG [StatelessSessionContainer] Mapped Container method getEJBHome HASH -993218923
15:11:12,356 DEBUG [StatelessSessionContainer] Mapped Container method getPrimaryKey HASH -131865408
15:11:12,356 DEBUG [StatelessSessionContainer] Mapped Container method isIdentical HASH 285457048
15:11:12,356 DEBUG [StatelessSessionContainer] Mapped createLedger HASH 671726323to public java.lang.String com.blackdog.gl.ejb.LedgerManagerBean.createLedger(com.blackdog.gl.domain.Ledger) throws com.blackdog.common.exception.BaseException
15:11:12,356 DEBUG [StatelessSessionContainer] Mapped getLedger HASH 1167583017to public com.blackdog.gl.domain.Ledger com.blackdog.gl.ejb.LedgerManagerBean.getLedger(com.blackdog.gl.domain.key.LedgerKey) throws com.blackdog.common.exception.BaseException
15:11:12,356 DEBUG [StatelessSessionContainer] Mapped Container method remove HASH 2137066130
15:11:12,356 DEBUG [StatelessSessionContainer] Mapped Container method getEJBLocalHome HASH 696357781
15:11:12,356 DEBUG [StatelessSessionContainer] Mapped Container method getPrimaryKey HASH 359205605
15:11:12,356 DEBUG [StatelessSessionContainer] Mapped Container method isIdentical HASH -62303043
15:11:12,356 DEBUG [StatelessSessionContainer] Mapping create
15:11:12,356 DEBUG [StatelessSessionContainer] Mapping remove
15:11:12,356 DEBUG [StatelessSessionContainer] Mapping remove
15:11:12,356 DEBUG [StatelessSessionContainer] Mapping getEJBMetaData
15:11:12,356 DEBUG [StatelessSessionContainer] Mapping getHomeHandle
15:11:12,356 DEBUG [StatelessSessionContainer] Mapping create
15:11:12,356 DEBUG [StatelessSessionContainer] Mapping remove
15:11:12,450 DEBUG [ProxyFactory] Proxy Factory for ejb/LedgerManager initialized
15:11:12,497 DEBUG [ServiceController] Creating service jboss.j2ee:service=EJB,plugin=pool,jndiName=ejb/LedgerManager
15:11:12,497 DEBUG [StatelessSessionInstancePool] Creating jboss.j2ee:service=EJB,plugin=pool,jndiName=ejb/LedgerManager
15:11:12,497 DEBUG [StatelessSessionInstancePool] Created jboss.j2ee:service=EJB,plugin=pool,jndiName=ejb/LedgerManager
15:11:12,497 DEBUG [ServiceController] Creating dependent components for: jboss.j2ee:service=EJB,plugin=pool,jndiName=ejb/LedgerManager dependents are: []
15:11:12,497 DEBUG [StatelessSessionContainer] Created jboss.j2ee:jndiName=ejb/LedgerManager,service=EJB
15:11:12,497 DEBUG [ServiceController] Creating dependent components for: jboss.j2ee:jndiName=ejb/LedgerManager,service=EJB dependents are: []
15:11:12,497 DEBUG [EjbModule] Bound jmxName=jboss.j2ee:jndiName=ejb/LedgerManager,service=EJB, hash=2061480045into Registry
15:11:12,497 DEBUG [EjbModule] Created jboss.j2ee:service=EjbModule,module=GLEJB.jar
15:11:12,512 DEBUG [ServiceController] Creating dependent components for: jboss.j2ee:service=EjbModule,module=GLEJB.jar dependents are: []
15:11:12,512 DEBUG [ServiceDeployer] handleNotification: org.jboss.deployment.SubDeployer.create,GLEJB.jar
15:11:12,512 DEBUG [MainDeployer] Done with create step of deploying GLEJB.jar
15:11:12,512 DEBUG [MainDeployer] create step for deployment file:/C:/jboss/jboss-4.0.2/server/default/tmp/deploy/tmp16874GlApp.ear-contents/GLWeb.war
15:11:12,528 DEBUG [ServiceController] Creating service jboss.web.deployment:war=GLWeb.war,id=-703590189
15:11:12,528 DEBUG [ServiceController] adding depends in ServiceController.register: []
15:11:12,528 DEBUG [WebModule] Creating jboss.web.deployment:war=GLWeb.war,id=-703590189
15:11:12,528 DEBUG [WebModule] Created jboss.web.deployment:war=GLWeb.war,id=-703590189
15:11:12,528 DEBUG [ServiceController] Creating dependent components for: jboss.web.deployment:war=GLWeb.war,id=-703590189 dependents are: []
15:11:12,528 DEBUG [ServiceDeployer] handleNotification: org.jboss.deployment.SubDeployer.create,GLWeb.war
15:11:12,528 DEBUG [MainDeployer] Done with create step of deploying GLWeb.war
15:11:12,528 DEBUG [ServiceController] Creating service jboss.j2ee:service=EARDeployment,url='GlApp.ear'
15:11:12,528 DEBUG [EARDeployment] Creating jboss.j2ee:service=EARDeployment,url='GlApp.ear'
15:11:12,528 DEBUG [EARDeployment] Created jboss.j2ee:service=EARDeployment,url='GlApp.ear'
15:11:12,528 DEBUG [ServiceController] Creating dependent components for: jboss.j2ee:service=EARDeployment,url='GlApp.ear' dependents are: []
15:11:12,528 DEBUG [MainDeployer] Done with create step of deploying GlApp.ear
15:11:12,528 DEBUG [MainDeployer] Begin deployment start file:/C:/jboss/jboss-4.0.2/server/default/deploy/GlApp.ear
15:11:12,528 DEBUG [MainDeployer] Begin deployment start file:/C:/jboss/jboss

Did you ever find an answer to this? I am seeing the exact same thing and it is driving me nuts! Any help would be appreciated, thank you...

Nick