[Obm] Plus de synchronisation suite mise à jour en 2.4.1.2

Vince tux45 at hotmail.fr
Mon Nov 19 17:01:12 CET 2012 

Thomas,

En fait, il y a des comptes qui ne se synchronisent pas, que ce soit 
avec un iphone ou un android. La synchro avec le connecteur fonctionne bien.

J'avais même un utilisateur qui avait énormément de doublons. J'ai du 
faire le ménage et supprimer plus de 1600 évènements redondants. Cela 
m'était déjà arrivé avec la migration en 2.4 et j'avais déjà fait du ménage.

Dans le log ci-dessous, à quoi correspond "col1" ???

Merci pour votre aide.

Vincent

2012-11-19 16:55:02.839:WARN::/opush/ActiveSyncServlet/
java.lang.IllegalStateException: col1 must not contain duplicate 
elements as evaluated by comparator
         at 
org.obm.push.utils.collection.Sets.checkCollectionNoDuplicateEntry(Sets.java:63) 

         at org.obm.push.utils.collection.Sets.difference(Sets.java:54)
         at 
org.obm.push.handler.ResponseWindowingService$WindowLogic.changesFromServer(ResponseWindowingService.java:127) 

         at 
org.obm.push.handler.ResponseWindowingService$WindowLogic.handleChangesOverflow(ResponseWindowingService.java:109) 

         at 
org.obm.push.handler.ResponseWindowingService$WindowLogic.window(ResponseWindowingService.java:80) 

         at 
org.obm.push.handler.ResponseWindowingService.windowDeletions(ResponseWindowingService.java:200) 

         at 
org.obm.push.handler.SyncHandler.doUpdates(SyncHandler.java:261)
         at 
org.obm.push.handler.SyncHandler.handleDataSync(SyncHandler.java:417)
         at 
org.obm.push.handler.SyncHandler.computeSyncState(SyncHandler.java:400)
         at org.obm.push.handler.SyncHandler.doTheJob(SyncHandler.java:384)
         at org.obm.push.handler.SyncHandler.process(SyncHandler.java:166)
         at 
org.obm.push.handler.WbxmlRequestHandler.process(WbxmlRequestHandler.java:125) 

         at 
org.obm.push.ActiveSyncServlet.processActiveSyncMethod(ActiveSyncServlet.java:235) 

         at 
org.obm.push.ActiveSyncServlet.service(ActiveSyncServlet.java:153)
         at 
org.obm.annotations.transactional.TransactionalInterceptor.managerTransaction(TransactionalInterceptor.java:88)
         at 
org.obm.annotations.transactional.TransactionalInterceptor.invoke(TransactionalInterceptor.java:74)
         at javax.servlet.http.HttpServlet.service(HttpServlet.java:717)
         at 
com.google.inject.servlet.ServletDefinition.doService(ServletDefinition.java:263)
         at 
com.google.inject.servlet.ServletDefinition.service(ServletDefinition.java:178)
         at 
com.google.inject.servlet.ManagedServletPipeline.service(ManagedServletPipeline.java:91)
         at 
com.google.inject.servlet.FilterChainInvocation.doFilter(FilterChainInvocation.java:62)
         at 
org.eclipse.jetty.continuation.ContinuationFilter.doFilter(ContinuationFilter.java:99)
         at 
com.google.inject.servlet.FilterDefinition.doFilter(FilterDefinition.java:163)
         at 
com.google.inject.servlet.FilterChainInvocation.doFilter(FilterChainInvocation.java:58)
         at 
com.google.inject.servlet.ManagedFilterPipeline.dispatch(ManagedFilterPipeline.java:118)
         at 
com.google.inject.servlet.GuiceFilter.doFilter(GuiceFilter.java:113)
         at 
org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1157)
         at 
org.mortbay.jetty.servlet.ServletHandler.handle(ServletHandler.java:388)
         at 
org.mortbay.jetty.security.SecurityHandler.handle(SecurityHandler.java:216)
         at 
org.mortbay.jetty.servlet.SessionHandler.handle(SessionHandler.java:182)
         at 
org.mortbay.jetty.handler.ContextHandler.handle(ContextHandler.java:765)
         at 
org.mortbay.jetty.webapp.WebAppContext.handle(WebAppContext.java:418)
         at 
org.mortbay.jetty.handler.ContextHandlerCollection.handle(ContextHandlerCollection.java:230)
         at 
org.mortbay.jetty.handler.HandlerCollection.handle(HandlerCollection.java:114)
         at 
org.mortbay.jetty.handler.HandlerWrapper.handle(HandlerWrapper.java:152)
         at org.mortbay.jetty.Server.handle(Server.java:326)
         at 
org.mortbay.jetty.HttpConnection.handleRequest(HttpConnection.java:542)
         at 
org.mortbay.jetty.HttpConnection$RequestHandler.content(HttpConnection.java:943)
         at org.mortbay.jetty.HttpParser.parseNext(HttpParser.java:756)
         at org.mortbay.jetty.HttpParser.parseAvailable(HttpParser.java:218)
         at org.mortbay.jetty.HttpConnection.handle(HttpConnection.java:404)
         at 
org.mortbay.io.nio.SelectChannelEndPoint.run(SelectChannelEndPoint.java:410)
         at 
org.mortbay.thread.QueuedThreadPool$PoolThread.run(QueuedThreadPool.java:582)
2012-11-19 16:55:02.920:WARN::/opush/ActiveSyncServlet/
java.lang.IllegalStateException: col1 must not contain duplicate 
elements as evaluated by comparator
         at 
org.obm.push.utils.collection.Sets.checkCollectionNoDuplicateEntry(Sets.java:63)
         at org.obm.push.utils.collection.Sets.difference(Sets.java:54)
         at 
org.obm.push.handler.ResponseWindowingService$WindowLogic.changesFromServer(ResponseWindowingService.java:127)
         at 
org.obm.push.handler.ResponseWindowingService$WindowLogic.handleChangesOverflow(ResponseWindowingService.java:109)
         at 
org.obm.push.handler.ResponseWindowingService$WindowLogic.window(ResponseWindowingService.java:80)
         at 
org.obm.push.handler.ResponseWindowingService.windowChanges(ResponseWindowingService.java:178)
         at org.obm.push.handler.SyncHandler.doUpdates(SyncHandler.java:258)
         at 
org.obm.push.handler.SyncHandler.handleDataSync(SyncHandler.java:417)
         at 
org.obm.push.handler.SyncHandler.computeSyncState(SyncHandler.java:400)
         at org.obm.push.handler.SyncHandler.doTheJob(SyncHandler.java:384)
         at org.obm.push.handler.SyncHandler.process(SyncHandler.java:166)
         at 
org.obm.push.handler.WbxmlRequestHandler.process(WbxmlRequestHandler.java:125)
         at 
org.obm.push.ActiveSyncServlet.processActiveSyncMethod(ActiveSyncServlet.java:235)
         at 
org.obm.push.ActiveSyncServlet.service(ActiveSyncServlet.java:153)
         at 
org.obm.annotations.transactional.TransactionalInterceptor.managerTransaction(TransactionalInterceptor.java:88)
         at 
org.obm.annotations.transactional.TransactionalInterceptor.invoke(TransactionalInterceptor.java:74)
         at javax.servlet.http.HttpServlet.service(HttpServlet.java:717)
         at 
com.google.inject.servlet.ServletDefinition.doService(ServletDefinition.java:263)
         at 
com.google.inject.servlet.ServletDefinition.service(ServletDefinition.java:178)
         at 
com.google.inject.servlet.ManagedServletPipeline.service(ManagedServletPipeline.java:91)
         at 
com.google.inject.servlet.FilterChainInvocation.doFilter(FilterChainInvocation.java:62)
         at 
org.eclipse.jetty.continuation.ContinuationFilter.doFilter(ContinuationFilter.java:99)
         at 
com.google.inject.servlet.FilterDefinition.doFilter(FilterDefinition.java:163)
         at 
com.google.inject.servlet.FilterChainInvocation.doFilter(FilterChainInvocation.java:58)
         at 
com.google.inject.servlet.ManagedFilterPipeline.dispatch(ManagedFilterPipeline.java:118)
         at 
com.google.inject.servlet.GuiceFilter.doFilter(GuiceFilter.java:113)
         at 
org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1157)
         at 
org.mortbay.jetty.servlet.ServletHandler.handle(ServletHandler.java:388)
         at 
org.mortbay.jetty.security.SecurityHandler.handle(SecurityHandler.java:216)
         at 
org.mortbay.jetty.servlet.SessionHandler.handle(SessionHandler.java:182)
         at 
org.mortbay.jetty.handler.ContextHandler.handle(ContextHandler.java:765)
         at 
org.mortbay.jetty.webapp.WebAppContext.handle(WebAppContext.java:418)
         at 
org.mortbay.jetty.handler.ContextHandlerCollection.handle(ContextHandlerCollection.java:230)
         at 
org.mortbay.jetty.handler.HandlerCollection.handle(HandlerCollection.java:114)
         at 
org.mortbay.jetty.handler.HandlerWrapper.handle(HandlerWrapper.java:152)
         at org.mortbay.jetty.Server.handle(Server.java:326)
         at 
org.mortbay.jetty.HttpConnection.handleRequest(HttpConnection.java:542)
         at 
org.mortbay.jetty.HttpConnection$RequestHandler.content(HttpConnection.java:943)
         at org.mortbay.jetty.HttpParser.parseNext(HttpParser.java:756)
         at org.mortbay.jetty.HttpParser.parseAvailable(HttpParser.java:218)
         at org.mortbay.jetty.HttpConnection.handle(HttpConnection.java:404)
         at 
org.mortbay.io.nio.SelectChannelEndPoint.run(SelectChannelEndPoint.java:410)
         at 
org.mortbay.thread.QueuedThreadPool$PoolThread.run(QueuedThreadPool.java:582)
2012-11-19 16:55:03.266:WARN::/opush/ActiveSyncServlet/
java.lang.IllegalStateException: col1 must not contain duplicate 
elements as evaluated by comparator
         at 
org.obm.push.utils.collection.Sets.checkCollectionNoDuplicateEntry(Sets.java:63)
         at org.obm.push.utils.collection.Sets.difference(Sets.java:54)
         at 
org.obm.push.handler.ResponseWindowingService$WindowLogic.changesFromServer(ResponseWindowingService.java:127)
         at 
org.obm.push.handler.ResponseWindowingService$WindowLogic.handleChangesOverflow(ResponseWindowingService.java:109)
         at 
org.obm.push.handler.ResponseWindowingService$WindowLogic.window(ResponseWindowingService.java:80)
         at 
org.obm.push.handler.ResponseWindowingService.windowDeletions(ResponseWindowingService.java:200)
         at org.obm.push.handler.SyncHandler.doUpdates(SyncHandler.java:261)
         at 
org.obm.push.handler.SyncHandler.handleDataSync(SyncHandler.java:417)
         at 
org.obm.push.handler.SyncHandler.computeSyncState(SyncHandler.java:400)
         at org.obm.push.handler.SyncHandler.doTheJob(SyncHandler.java:384)
         at org.obm.push.handler.SyncHandler.process(SyncHandler.java:166)
         at 
org.obm.push.handler.WbxmlRequestHandler.process(WbxmlRequestHandler.java:125)
         at 
org.obm.push.ActiveSyncServlet.processActiveSyncMethod(ActiveSyncServlet.java:235)
         at 
org.obm.push.ActiveSyncServlet.service(ActiveSyncServlet.java:153)
         at 
org.obm.annotations.transactional.TransactionalInterceptor.managerTransaction(TransactionalInterceptor.java:88)
         at 
org.obm.annotations.transactional.TransactionalInterceptor.invoke(TransactionalInterceptor.java:74)
         at javax.servlet.http.HttpServlet.service(HttpServlet.java:717)
         at 
com.google.inject.servlet.ServletDefinition.doService(ServletDefinition.java:263)
         at 
com.google.inject.servlet.ServletDefinition.service(ServletDefinition.java:178)
         at 
com.google.inject.servlet.ManagedServletPipeline.service(ManagedServletPipeline.java:91)
         at 
com.google.inject.servlet.FilterChainInvocation.doFilter(FilterChainInvocation.java:62)
         at 
org.eclipse.jetty.continuation.ContinuationFilter.doFilter(ContinuationFilter.java:99)
         at 
com.google.inject.servlet.FilterDefinition.doFilter(FilterDefinition.java:163)
         at 
com.google.inject.servlet.FilterChainInvocation.doFilter(FilterChainInvocation.java:58)
         at 
com.google.inject.servlet.ManagedFilterPipeline.dispatch(ManagedFilterPipeline.java:118)
         at 
com.google.inject.servlet.GuiceFilter.doFilter(GuiceFilter.java:113)
         at 
org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1157)
         at 
org.mortbay.jetty.servlet.ServletHandler.handle(ServletHandler.java:388)
         at 
org.mortbay.jetty.security.SecurityHandler.handle(SecurityHandler.java:216)
         at 
org.mortbay.jetty.servlet.SessionHandler.handle(SessionHandler.java:182)
         at 
org.mortbay.jetty.handler.ContextHandler.handle(ContextHandler.java:765)
         at 
org.mortbay.jetty.webapp.WebAppContext.handle(WebAppContext.java:418)
         at 
org.mortbay.jetty.handler.ContextHandlerCollection.handle(ContextHandlerCollection.java:230)
         at 
org.mortbay.jetty.handler.HandlerCollection.handle(HandlerCollection.java:114)
         at 
org.mortbay.jetty.handler.HandlerWrapper.handle(HandlerWrapper.java:152)
         at org.mortbay.jetty.Server.handle(Server.java:326)
         at 
org.mortbay.jetty.HttpConnection.handleRequest(HttpConnection.java:542)
         at 
org.mortbay.jetty.HttpConnection$RequestHandler.content(HttpConnection.java:943)
         at org.mortbay.jetty.HttpParser.parseNext(HttpParser.java:756)
         at org.mortbay.jetty.HttpParser.parseAvailable(HttpParser.java:218)
         at org.mortbay.jetty.HttpConnection.handle(HttpConnection.java:404)
         at 
org.mortbay.io.nio.SelectChannelEndPoint.run(SelectChannelEndPoint.java:410)
         at 
org.mortbay.thread.QueuedThreadPool$PoolThread.run(QueuedThreadPool.java:582)
2012-11-19 16:55:03.270:WARN::/opush/ActiveSyncServlet/
java.lang.IllegalStateException: col1 must not contain duplicate 
elements as evaluated by comparator
         at 
org.obm.push.utils.collection.Sets.checkCollectionNoDuplicateEntry(Sets.java:63)
         at org.obm.push.utils.collection.Sets.difference(Sets.java:54)
         at 
org.obm.push.handler.ResponseWindowingService$WindowLogic.changesFromServer(ResponseWindowingService.java:127)
         at 
org.obm.push.handler.ResponseWindowingService$WindowLogic.handleChangesOverflow(ResponseWindowingService.java:109)
         at 
org.obm.push.handler.ResponseWindowingService$WindowLogic.window(ResponseWindowingService.java:80)
         at 
org.obm.push.handler.ResponseWindowingService.windowChanges(ResponseWindowingService.java:178)
         at org.obm.push.handler.SyncHandler.doUpdates(SyncHandler.java:258)
         at 
org.obm.push.handler.SyncHandler.handleDataSync(SyncHandler.java:417)
         at 
org.obm.push.handler.SyncHandler.computeSyncState(SyncHandler.java:400)
         at org.obm.push.handler.SyncHandler.doTheJob(SyncHandler.java:384)
         at org.obm.push.handler.SyncHandler.process(SyncHandler.java:166)
         at 
org.obm.push.handler.WbxmlRequestHandler.process(WbxmlRequestHandler.java:125)
         at 
org.obm.push.ActiveSyncServlet.processActiveSyncMethod(ActiveSyncServlet.java:235)
         at 
org.obm.push.ActiveSyncServlet.service(ActiveSyncServlet.java:153)
         at 
org.obm.annotations.transactional.TransactionalInterceptor.managerTransaction(TransactionalInterceptor.java:88)
         at 
org.obm.annotations.transactional.TransactionalInterceptor.invoke(TransactionalInterceptor.java:74)
         at javax.servlet.http.HttpServlet.service(HttpServlet.java:717)
         at 
com.google.inject.servlet.ServletDefinition.doService(ServletDefinition.java:263)
         at 
com.google.inject.servlet.ServletDefinition.service(ServletDefinition.java:178)
         at 
com.google.inject.servlet.ManagedServletPipeline.service(ManagedServletPipeline.java:91)
         at 
com.google.inject.servlet.FilterChainInvocation.doFilter(FilterChainInvocation.java:62)
         at 
org.eclipse.jetty.continuation.ContinuationFilter.doFilter(ContinuationFilter.java:99)
         at 
com.google.inject.servlet.FilterDefinition.doFilter(FilterDefinition.java:163)
         at 
com.google.inject.servlet.FilterChainInvocation.doFilter(FilterChainInvocation.java:58)
         at 
com.google.inject.servlet.ManagedFilterPipeline.dispatch(ManagedFilterPipeline.java:118)
         at 
com.google.inject.servlet.GuiceFilter.doFilter(GuiceFilter.java:113)
         at 
org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1157)
         at 
org.mortbay.jetty.servlet.ServletHandler.handle(ServletHandler.java:388)
         at 
org.mortbay.jetty.security.SecurityHandler.handle(SecurityHandler.java:216)
         at 
org.mortbay.jetty.servlet.SessionHandler.handle(SessionHandler.java:182)
         at 
org.mortbay.jetty.handler.ContextHandler.handle(ContextHandler.java:765)
         at 
org.mortbay.jetty.webapp.WebAppContext.handle(WebAppContext.java:418)
         at 
org.mortbay.jetty.handler.ContextHandlerCollection.handle(ContextHandlerCollection.java:230)
         at 
org.mortbay.jetty.handler.HandlerCollection.handle(HandlerCollection.java:114)
         at 
org.mortbay.jetty.handler.HandlerWrapper.handle(HandlerWrapper.java:152)
         at org.mortbay.jetty.Server.handle(Server.java:326)
         at 
org.mortbay.jetty.HttpConnection.handleRequest(HttpConnection.java:542)
         at 
org.mortbay.jetty.HttpConnection$RequestHandler.content(HttpConnection.java:943)
         at org.mortbay.jetty.HttpParser.parseNext(HttpParser.java:756)
         at org.mortbay.jetty.HttpParser.parseAvailable(HttpParser.java:218)
         at org.mortbay.jetty.HttpConnection.handle(HttpConnection.java:404)
         at 
org.mortbay.io.nio.SelectChannelEndPoint.run(SelectChannelEndPoint.java:410)
         at 
org.mortbay.thread.QueuedThreadPool$PoolThread.run(QueuedThreadPool.java:582)
15:55:03.336 [bitronix-scheduler] DEBUG bitronix.tm.timer.TaskScheduler 
- running a RecoveryTask scheduled for Mon Nov 19 15:55:03 GMT 2012
15:55:03.336 [bitronix-scheduler] DEBUG bitronix.tm.timer.RecoveryTask - 
running recovery
15:55:03.337 [bitronix-scheduler] DEBUG bitronix.tm.timer.RecoveryTask - 
rescheduling recovery for Mon Nov 19 15:56:03 GMT 2012
15:55:03.337 [bitronix-recovery-thread] DEBUG 
b.tm.BitronixTransactionManager - oldest in-flight transaction's 
timestamp: -9223372036854775808
15:55:03.337 [bitronix-scheduler] DEBUG bitronix.tm.timer.TaskScheduler 
- scheduling recovery task for Mon Nov 19 15:56:03 GMT 2012
15:55:03.337 [bitronix-scheduler] DEBUG bitronix.tm.timer.TaskScheduler 
- removing task by bitronix.tm.recovery.Recoverer at 16381a53
15:55:03.337 [bitronix-recovery-thread] DEBUG 
b.tm.journal.TransactionLogCursor - end of transaction log file reached 
at 21
15:55:03.337 [bitronix-scheduler] DEBUG bitronix.tm.timer.TaskScheduler 
- cancelled a RecoveryTask scheduled for Mon Nov 19 15:55:03 GMT 2012, 
total task(s) still queued: 1
15:55:03.337 [bitronix-recovery-thread] DEBUG 
bitronix.tm.journal.DiskJournal - collected dangling records of a 
TransactionLogAppender on obm-locator-btm1.tlog, committing: 0, 
committed: 0, delta: 0
15:55:03.337 [bitronix-scheduler] DEBUG bitronix.tm.timer.TaskScheduler 
- scheduled a RecoveryTask scheduled for Mon Nov 19 15:56:03 GMT 2012, 
total task(s) queued: 2
15:55:03.337 [bitronix-recovery-thread] DEBUG 
bitronix.tm.recovery.Recoverer - performing recovery on pgsql
15:55:03.337 [bitronix-scheduler] DEBUG bitronix.tm.timer.TaskScheduler 
- successfully ran a RecoveryTask scheduled for Mon Nov 19 15:55:03 GMT 2012
15:55:03.337 [bitronix-recovery-thread] DEBUG 
bitronix.tm.recovery.Recoverer - running recovery on a PoolingDataSource 
containing an XAPool of resource pgsql with 1 connection(s) (1 still 
available)
15:55:03.337 [bitronix-scheduler] DEBUG bitronix.tm.timer.TaskScheduler 
- total task(s) still queued: 2
15:55:03.337 [bitronix-recovery-thread] DEBUG 
bitronix.tm.resource.common.XAPool - getting a IN_POOL connection from 
an XAPool of resource pgsql with 1 connection(s) (1 still available)
15:55:03.337 [bitronix-recovery-thread] DEBUG 
bitronix.tm.resource.common.XAPool - waiting for IN_POOL connections 
count to be > 0, currently is 1
15:55:03.337 [bitronix-recovery-thread] DEBUG 
bitronix.tm.resource.common.XAPool - found IN_POOL connection a 
JdbcPooledConnection from datasource pgsql in state IN_POOL with usage 
count 0 wrapping org.postgresql.xa.PGXAConnection at 549ad840 from an 
XAPool of resource pgsql with 1 connection(s) (1 still available)
15:55:03.337 [bitronix-recovery-thread] DEBUG 
b.t.r.jdbc.JdbcPooledConnection - getting connection handle from a 
JdbcPooledConnection from datasource pgsql in state IN_POOL with usage 
count 0 wrapping org.postgresql.xa.PGXAConnection at 549ad840
15:55:03.337 [bitronix-recovery-thread] DEBUG 
b.t.r.c.AbstractXAStatefulHolder - notifying 2 
stateChangeEventListener(s) about state changing from IN_POOL to 
ACCESSIBLE in a JdbcPooledConnection from datasource pgsql in state 
IN_POOL with usage count 1 wrapping 
org.postgresql.xa.PGXAConnection at 549ad840
15:55:03.337 [bitronix-recovery-thread] DEBUG 
b.t.r.c.AbstractXAStatefulHolder - state changing from IN_POOL to 
ACCESSIBLE in a JdbcPooledConnection from datasource pgsql in state 
IN_POOL with usage count 1 wrapping 
org.postgresql.xa.PGXAConnection at 549ad840
15:55:03.338 [bitronix-recovery-thread] DEBUG 
b.t.r.c.AbstractXAStatefulHolder - notifying 2 
stateChangeEventListener(s) about state changed from IN_POOL to 
ACCESSIBLE in a JdbcPooledConnection from datasource pgsql in state 
ACCESSIBLE with usage count 1 wrapping 
org.postgresql.xa.PGXAConnection at 549ad840
15:55:03.338 [bitronix-recovery-thread] DEBUG 
b.t.r.jdbc.JdbcPooledConnection - connection 
org.postgresql.xa.PGXAConnection at 549ad840 was in state IN_POOL, testing it
15:55:03.338 [bitronix-recovery-thread] DEBUG 
b.t.r.jdbc.JdbcPooledConnection - testing with query 'SELECT 666' 
connection of a JdbcPooledConnection from datasource pgsql in state 
ACCESSIBLE with usage count 1 wrapping 
org.postgresql.xa.PGXAConnection at 549ad840
15:55:03.338 [bitronix-recovery-thread] DEBUG 
b.t.r.jdbc.JdbcPooledConnection - testQuery successfully tested 
connection of a JdbcPooledConnection from datasource pgsql in state 
ACCESSIBLE with usage count 1 wrapping 
org.postgresql.xa.PGXAConnection at 549ad840
15:55:03.338 [bitronix-recovery-thread] DEBUG 
b.t.r.jdbc.JdbcPooledConnection - got connection handle from a 
JdbcPooledConnection from datasource pgsql in state ACCESSIBLE with 
usage count 1 wrapping org.postgresql.xa.PGXAConnection at 549ad840
15:55:03.339 [bitronix-recovery-thread] DEBUG 
bitronix.tm.recovery.RecoveryHelper - recovering with STARTRSCAN
15:55:03.339 [bitronix-recovery-thread] DEBUG 
bitronix.tm.recovery.RecoveryHelper - STARTRSCAN recovered 0 xid(s) on 
an XAResourceHolderState with uniqueName=pgsql 
XAResource=org.postgresql.xa.PGXAConnection at 549ad840 with XID null
15:55:03.339 [bitronix-recovery-thread] DEBUG 
bitronix.tm.recovery.RecoveryHelper - recovering with ENDRSCAN
15:55:03.339 [bitronix-recovery-thread] DEBUG 
bitronix.tm.recovery.RecoveryHelper - ENDRSCAN recovered 0 xid(s) on an 
XAResourceHolderState with uniqueName=pgsql 
XAResource=org.postgresql.xa.PGXAConnection at 549ad840 with XID null
15:55:03.339 [bitronix-recovery-thread] DEBUG 
b.tm.resource.jdbc.PoolingDataSource - recovery xa resource is being 
closed: bitronix.tm.resource.common.RecoveryXAResourceHolder at 9115340
15:55:03.339 [bitronix-recovery-thread] DEBUG 
b.t.r.jdbc.JdbcConnectionHandle - closing a JdbcConnectionHandle of a 
JdbcPooledConnection from datasource pgsql in state ACCESSIBLE with 
usage count 1 wrapping org.postgresql.xa.PGXAConnection at 549ad840 on 
Pooled connection wrapping physical connection 
org.postgresql.jdbc4.Jdbc4Connection at 1639d811
15:55:03.339 [bitronix-recovery-thread] DEBUG 
b.t.r.jdbc.JdbcPooledConnection - releasing to pool a 
JdbcPooledConnection from datasource pgsql in state ACCESSIBLE with 
usage count 1 wrapping org.postgresql.xa.PGXAConnection at 549ad840
15:55:03.340 [bitronix-recovery-thread] DEBUG 
b.t.r.c.TransactionContextHelper - delisting a JdbcPooledConnection from 
datasource pgsql in state ACCESSIBLE with usage count 0 wrapping 
org.postgresql.xa.PGXAConnection at 549ad840 from null
15:55:03.340 [bitronix-recovery-thread] DEBUG 
b.t.r.c.TransactionContextHelper - resource is not in enlisting global 
transaction context: a JdbcPooledConnection from datasource pgsql in 
state ACCESSIBLE with usage count 0 wrapping 
org.postgresql.xa.PGXAConnection at 549ad840
15:55:03.340 [bitronix-recovery-thread] DEBUG 
b.t.r.c.TransactionContextHelper - requeuing a JdbcPooledConnection from 
datasource pgsql in state ACCESSIBLE with usage count 0 wrapping 
org.postgresql.xa.PGXAConnection at 549ad840 from null
15:55:03.340 [bitronix-recovery-thread] DEBUG 
b.t.r.c.TransactionContextHelper - resource is not in enlisting global 
transaction context: a JdbcPooledConnection from datasource pgsql in 
state ACCESSIBLE with usage count 0 wrapping 
org.postgresql.xa.PGXAConnection at 549ad840
15:55:03.340 [bitronix-recovery-thread] DEBUG 
b.t.r.c.TransactionContextHelper - looking in in-flight transactions for 
XAResourceHolderState of a JdbcPooledConnection from datasource pgsql in 
state ACCESSIBLE with usage count 0 wrapping 
org.postgresql.xa.PGXAConnection at 549ad840
15:55:03.340 [bitronix-recovery-thread] DEBUG 
b.t.r.c.AbstractXAResourceHolder - resource not enlisted in any 
transaction: a JdbcPooledConnection from datasource pgsql in state 
ACCESSIBLE with usage count 0 wrapping 
org.postgresql.xa.PGXAConnection at 549ad840
15:55:03.340 [bitronix-recovery-thread] DEBUG 
b.t.r.c.TransactionContextHelper - resource not in enlisting global 
transaction context, immediately releasing to pool a 
JdbcPooledConnection from datasource pgsql in state ACCESSIBLE with 
usage count 0 wrapping org.postgresql.xa.PGXAConnection at 549ad840
15:55:03.340 [bitronix-recovery-thread] DEBUG 
b.t.r.c.AbstractXAStatefulHolder - notifying 2 
stateChangeEventListener(s) about state changing from ACCESSIBLE to 
IN_POOL in a JdbcPooledConnection from datasource pgsql in state 
ACCESSIBLE with usage count 0 wrapping 
org.postgresql.xa.PGXAConnection at 549ad840
15:55:03.340 [bitronix-recovery-thread] DEBUG 
b.t.r.jdbc.JdbcPooledConnection - closing 0 dangling uncached statement(s)
15:55:03.340 [bitronix-recovery-thread] DEBUG 
b.t.r.jdbc.JdbcPooledConnection - clearing warnings of Pooled connection 
wrapping physical connection org.postgresql.jdbc4.Jdbc4Connection at 1639d811
15:55:03.340 [bitronix-recovery-thread] DEBUG 
b.t.r.c.AbstractXAStatefulHolder - state changing from ACCESSIBLE to 
IN_POOL in a JdbcPooledConnection from datasource pgsql in state 
ACCESSIBLE with usage count 0 wrapping 
org.postgresql.xa.PGXAConnection at 549ad840
15:55:03.340 [bitronix-recovery-thread] DEBUG 
b.t.r.c.AbstractXAStatefulHolder - notifying 2 
stateChangeEventListener(s) about state changed from ACCESSIBLE to 
IN_POOL in a JdbcPooledConnection from datasource pgsql in state IN_POOL 
with usage count 0 wrapping org.postgresql.xa.PGXAConnection at 549ad840
15:55:03.340 [bitronix-recovery-thread] DEBUG 
b.t.r.jdbc.JdbcPooledConnection - requeued JDBC connection of a 
PoolingDataSource containing an XAPool of resource pgsql with 1 
connection(s) (1 still available)
15:55:03.340 [bitronix-recovery-thread] DEBUG 
bitronix.tm.resource.common.XAPool - a connection's state changed to 
IN_POOL, notifying a thread eventually waiting for a connection
15:55:03.341 [bitronix-recovery-thread] DEBUG 
b.t.r.jdbc.JdbcPooledConnection - released to pool a 
JdbcPooledConnection from datasource pgsql in state IN_POOL with usage 
count 0 wrapping org.postgresql.xa.PGXAConnection at 549ad840
15:55:03.341 [bitronix-recovery-thread] DEBUG 
bitronix.tm.recovery.Recoverer - recovered 0 XID(s) from resource pgsql
15:55:03.341 [bitronix-recovery-thread] DEBUG 
bitronix.tm.recovery.Recoverer - found 0 dangling record(s) in journal
15:55:03.341 [bitronix-recovery-thread] DEBUG 
bitronix.tm.recovery.Recoverer - committed 0 dangling transaction(s)
15:55:03.341 [bitronix-recovery-thread] DEBUG 
bitronix.tm.recovery.Recoverer - rolling back aborted branch(es)
15:55:03.341 [bitronix-recovery-thread] DEBUG 
bitronix.tm.recovery.Recoverer - checking 0 branch(es) on pgsql for rollback
15:55:03.341 [bitronix-recovery-thread] DEBUG 
bitronix.tm.recovery.Recoverer - checked 0 branch(es) on pgsql for rollback
15:55:03.341 [bitronix-recovery-thread] DEBUG 
bitronix.tm.recovery.Recoverer - rolled back 0 aborted branch(es)
15:55:03.341 [bitronix-recovery-thread] DEBUG 
bitronix.tm.recovery.Recoverer - recovery committed 0 dangling 
transaction(s) and rolled back 0 aborted transaction(s) on 1 resource(s) 
[pgsql] (restricted to serverId 'obm-locator')
15:55:03.837 [bitronix-scheduler] DEBUG bitronix.tm.timer.TaskScheduler 
- running a PoolShrinkingTask scheduled for Mon Nov 19 15:55:03 GMT 2012 
on an XAPool of resource pgsql with 1 connection(s) (1 still available)
15:55:03.837 [bitronix-scheduler] DEBUG 
bitronix.tm.resource.common.XAPool - shrinking an XAPool of resource 
pgsql with 1 connection(s) (1 still available)
15:55:03.838 [bitronix-scheduler] DEBUG 
bitronix.tm.resource.common.XAPool - checking if connection can be 
closed: a JdbcPooledConnection from datasource pgsql in state IN_POOL 
with usage count 0 wrapping org.postgresql.xa.PGXAConnection at 549ad840 - 
closing time: 1353340563340, now time: 1353340503837
15:55:03.838 [bitronix-scheduler] DEBUG 
bitronix.tm.resource.common.XAPool - closed 0 idle connection(s)
15:55:03.838 [bitronix-scheduler] DEBUG 
bitronix.tm.resource.common.XAPool - shrunk an XAPool of resource pgsql 
with 1 connection(s) (1 still available)
15:55:03.838 [bitronix-scheduler] DEBUG bitronix.tm.timer.TaskScheduler 
- scheduling pool shrinking task on an XAPool of resource pgsql with 1 
connection(s) (1 still available) for Mon Nov 19 15:56:03 GMT 2012
15:55:03.838 [bitronix-scheduler] DEBUG bitronix.tm.timer.TaskScheduler 
- removing task by an XAPool of resource pgsql with 1 connection(s) (1 
still available)
15:55:03.838 [bitronix-scheduler] DEBUG bitronix.tm.timer.TaskScheduler 
- cancelled a PoolShrinkingTask scheduled for Mon Nov 19 15:55:03 GMT 
2012 on an XAPool of resource pgsql with 1 connection(s) (1 still 
available), total task(s) still queued: 1
15:55:03.838 [bitronix-scheduler] DEBUG bitronix.tm.timer.TaskScheduler 
- scheduled a PoolShrinkingTask scheduled for Mon Nov 19 15:56:03 GMT 
2012 on an XAPool of resource pgsql with 1 connection(s) (1 still 
available), total task(s) queued: 2
15:55:03.838 [bitronix-scheduler] DEBUG bitronix.tm.timer.TaskScheduler 
- successfully ran a PoolShrinkingTask scheduled for Mon Nov 19 15:55:03 
GMT 2012 on an XAPool of resource pgsql with 1 connection(s) (1 still 
available)
15:55:03.838 [bitronix-scheduler] DEBUG bitronix.tm.timer.TaskScheduler 
- total task(s) still queued: 2

More information about the Obm mailing list