Re: [Springframework-developer] Ready for 1.0 RC1

[Colin S. <col...@ex...>]

I am still debugging a $%$^%$% issue where Spring tries to close a=20
connection and JBoss says it is unknown.

2004-02-10 12:30:44,900 DEBUG [net.sf.hibernate.impl.SessionImpl]=20
executing flush
2004-02-10 12:30:44,900 DEBUG [net.sf.hibernate.impl.SessionImpl] post fl=
ush
2004-02-10 12:30:44,900 DEBUG=20
[org.springframework.transaction.jta.JtaTransactionManager] Triggering=20
beforeCompletion synchronization
2004-02-10 12:30:44,900 DEBUG=20
[org.springframework.transaction.support.TransactionSynchronizationManage=
r]=20
Removed value [org.springframework.orm.hibernate.SessionHolder@df42ce]=20
for key [net.sf.hibernate.impl.SessionFactoryImpl@1da6868] from thread=20
[TP-Processor2]
2004-02-10 12:30:44,900 DEBUG=20
[org.springframework.transaction.support.TransactionSynchronizationManage=
r]=20
Removed value=20
[org.springframework.jdbc.datasource.ConnectionHolder@52a665] for key=20
[org.jboss.resource.adapter.jdbc.WrapperDataSource@c39410] from thread=20
[TP-Processor2]
2004-02-10 12:30:44,900 INFO =20
[org.jboss.resource.connectionmanager.TxConnectionManager] throwable=20
from unregister connection
java.lang.IllegalStateException: Trying to return an unknown=20
connection2! org.jboss.resource.adapter.jdbc.WrappedConnection@1e94776
    at=20
org.jboss.resource.connectionmanager.CachedConnectionManager.unregisterCo=
nnection(CachedConnectionManager.java:330)
    at=20
org.jboss.resource.connectionmanager.TxConnectionManager$TxConnectionEven=
tListener.connectionClosed(TxConnectionManager.java:539)
    at=20
org.jboss.resource.adapter.jdbc.BaseWrapperManagedConnection.closeHandle(=
BaseWrapperManagedConnection.java:296)
    at=20
org.jboss.resource.adapter.jdbc.WrappedConnection.close(WrappedConnection=
.java:117)
    at=20
org.springframework.jdbc.datasource.DataSourceUtils.closeConnectionIfNece=
ssary(DataSourceUtils.java:162)
    at=20
org.springframework.jdbc.datasource.DataSourceUtils$ConnectionSynchroniza=
tion.beforeCompletion(DataSourceUtils.java:237)
    at=20
org.springframework.transaction.support.AbstractPlatformTransactionManage=
r.triggerBeforeCompletion(AbstractPlatformTransactionManager.java:417)
    at=20
org.springframework.transaction.support.AbstractPlatformTransactionManage=
r.commit(AbstractPlatformTransactionManager.java:298)
    at=20
org.springframework.transaction.interceptor.TransactionInterceptor.invoke=
(TransactionInterceptor.java:174)
    at=20
org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(Refl=
ectiveMethodInvocation.java:196)
    at=20
org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAop=
Proxy.java:135)
    at $Proxy142.createBuild(Unknown Source)
    at=20
com.whatever.coreserv.services.controller.application.ApplicationControll=
erBean.createBuild(ApplicationControllerBean.java:176)
    at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
    at=20
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java=
:39)
    at=20
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorI=
mpl.java:25)
    at java.lang.reflect.Method.invoke(Method.java:324)
    at=20
org.jboss.ejb.StatelessSessionContainer$ContainerInterceptor.invoke(State=
lessSessionContainer.java:683)
    at=20
org.jboss.resource.connectionmanager.CachedConnectionInterceptor.invoke(C=
achedConnectionInterceptor.java:185)
    at=20
org.jboss.ejb.plugins.StatelessSessionInstanceInterceptor.invoke(Stateles=
sSessionInstanceInterceptor.java:72)
    at=20
org.jboss.ejb.plugins.AbstractTxInterceptor.invokeNext(AbstractTxIntercep=
tor.java:84)
    at=20
org.jboss.ejb.plugins.TxInterceptorCMT.runWithTransactions(TxInterceptorC=
MT.java:267)
    at=20
org.jboss.ejb.plugins.TxInterceptorCMT.invoke(TxInterceptorCMT.java:128)
    at=20
org.jboss.ejb.plugins.SecurityInterceptor.invoke(SecurityInterceptor.java=
:118)
    at org.jboss.ejb.plugins.LogInterceptor.invoke(LogInterceptor.java:19=
1)
    at=20
org.jboss.ejb.plugins.ProxyFactoryFinderInterceptor.invoke(ProxyFactoryFi=
nderInterceptor.java:122)
    at=20
org.jboss.ejb.StatelessSessionContainer.internalInvoke(StatelessSessionCo=
ntainer.java:331)
    at org.jboss.ejb.Container.invoke(Container.java:700)
    at sun.reflect.GeneratedMethodAccessor96.invoke(Unknown Source)
    at=20
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorI=
mpl.java:25)
    at java.lang.reflect.Method.invoke(Method.java:324)
    at=20
org.jboss.mx.capability.ReflectedMBeanDispatcher.invoke(ReflectedMBeanDis=
patcher.java:284)
    at org.jboss.mx.server.MBeanServerImpl.invoke(MBeanServerImpl.java:54=
6)
    at org.jboss.invocation.local.LocalInvoker.invoke(LocalInvoker.java:1=
01)
    at=20
org.jboss.invocation.InvokerInterceptor.invoke(InvokerInterceptor.java:90=
)
    at=20
org.jboss.proxy.TransactionInterceptor.invoke(TransactionInterceptor.java=
:46)
    at=20
org.jboss.proxy.SecurityInterceptor.invoke(SecurityInterceptor.java:45)
    at=20
org.jboss.proxy.ejb.StatelessSessionInterceptor.invoke(StatelessSessionIn=
terceptor.java:100)
    at org.jboss.proxy.ClientContainer.invoke(ClientContainer.java:85)
    at $Proxy127.createBuild(Unknown Source)
    ....
2004-02-10 12:30:45,291 DEBUG=20
[org.springframework.transaction.jta.JtaTransactionManager] Triggering=20
afterCompletion synchronization
2004-02-10 12:30:45,291 DEBUG [net.sf.hibernate.impl.SessionImpl]=20
transaction completion
2004-02-10 12:30:45,291 DEBUG=20
[org.springframework.orm.hibernate.SessionFactoryUtils] Closing=20
Hibernate session
2004-02-10 12:30:45,291 DEBUG [net.sf.hibernate.impl.SessionImpl]=20
closing session
2004-02-10 12:30:45,291 DEBUG [net.sf.hibernate.impl.SessionImpl]=20
disconnecting session
2004-02-10 12:30:45,291 DEBUG [net.sf.hibernate.impl.SessionImpl]=20
transaction completion
2004-02-10 12:30:45,291 DEBUG=20
[org.springframework.transaction.support.TransactionSynchronizationManage=
r]=20
Clearing transaction synchronization

My feeling is that this is a JBoss bug of some sort though. It was worse=20
in 3.2.2RC3, happeing in a number of places. When I moved to 3.2.3, it=20
only happened in one spot, among a bunch of code that is essentially=20
similar. My feeling is that it's some sort of race condition in the=20
JBoss transaction code. Certainly, if I look at the wrapped connection=20
and the actual connection, right before we call close() on it, it says=20
it is not closed. Then the close() call causes the error to show up in=20
the log...

Weird stuff...



j=FCrgen h=F6ller [werk3AT] wrote:

>Thanks for testing, Thomas. It's a pity that this approach just works fo=
r JDK 1.4; however, JDKs are easier to upgrade than databases. I've never=
 tried it on JDK 1.3, but I did try classes12 with JDK 1.4, and that did =
work (after I've changed our code to read the Oracle constants via reflec=
tion; the constant values differ between classes12 and ojdbc14!). I'm not=
 sure why JDK 1.3 causes a problem here.
>=20
>I'm finally gonna release RC1 tonight, so there's still a couple of hour=
s to go if you find any issues :-)
>=20
>Juergen
>=20
>
>________________________________
>
>Von: spr...@li... im Auftrag vo=
n tri...@tr...
>Gesendet: Di 10.02.2004 17:40
>An: spr...@li...
>Betreff: Re: [Springframework-developer] Ready for 1.0 RC1
>
>
>
>I tried the imagedb sample application for Oracle.  It works using JDK 1=
.4 and
>the odbcj14.jar jdbc driver for an 8i database.  It does not work at all=
 using
>JDK 1.3 and  classes12.zip/jar.  This means that we are limited to JDK 1=
.4 for
>this type functionality with Oracle for now.
>
>Just to clarify the Oracle drivers - there are no particular 8i or 9i jd=
bc
>drivers.  They are just labeled based on which database version they are
>distributed with.  They should be backwards compatible, and if they are =
not it
>is a bug.  (There is even a patch for the driver that came with 8i to fi=
x a
>problem connecting to a 9i database.)  The only driver availale for JDK =
1.4 is
>the ojdbc14.jar that comes with 9i.  Haven't checked out the 10g drivers=
 yet.
>
>I ran into a small problem with the html generated by the application.  =
The
>browser did not pick up the end of the <textarea> for the description.  =
I added
>a separate closing tag and comitted it to cvs.
>
>Thomas
> =20
>