<html>
<head>
<style>
body {
font-family: Verdana, sans-serif;
font-size: 0.8em;
color:#484848;
}
h1, h2, h3 { font-family: "Trebuchet MS", Verdana, sans-serif; margin: 0px; }
h1 { font-size: 1.2em; }
h2, h3 { font-size: 1.1em; }
a, a:link, a:visited { color: #2A5685;}
a:hover, a:active { color: #c61a1a; }
a.wiki-anchor { display: none; }
hr {
width: 100%;
height: 1px;
background: #ccc;
border: 0;
}
.footer {
font-size: 0.8em;
font-style: italic;
}
</style>
</head>
<body>
<span class="header"></span>
Issue #392 has been reported by Vicente J. Ruiz Jurado.
<hr />
<h1><a href="http://redmine.ourproject.org/issues/392">Bug #392: Excepction with mysql connection pool and cron task</a></h1>
<ul>
<li>Author: Vicente J. Ruiz Jurado</li>
<li>Status: New</li>
<li>Priority: Normal</li>
<li>Assignee: </li>
<li>Category: Server side</li>
<li>Target version: </li>
<li>Resolution: </li>
</ul>
<p>2012-10-25 02:04:58 RackServletFilter [DEBUG] REQUEST: /ws/ContentService<br />2012-10-25 02:04:58 GWTServiceFilter [INFO] --------------------------------------------------------------------------------<br />2012-10-25 02:04:58 GWTServiceFilter [DEBUG] SERVICE: /ws/ContentService - ContentService<br />2012-10-25 02:04:58 AuthenticatedMethodInterceptor [INFO] ----- Starting method: getContentByWaveRef- userhash: 1n9sg2jn48eld8o2ul8sg7b1r -----<br />2012-10-25 02:04:58 AuthenticatedMethodInterceptor [INFO] Method: getContentByWaveRef<br />2012-10-25 02:04:58 AuthenticatedMethodInterceptor [INFO] Userhash received: 1n9sg2jn48eld8o2ul8sg7b1r<br />2012-10-25 02:04:58 LoggerMethodInterceptor [DEBUG] ContentRPC.getContentByWaveRef(1n9sg2jn48eld8o2ul8sg7b1r, kune.cc/w+75IIY14siqG, )<br />2012-10-25 02:04:58 LoggerMethodInterceptor [DEBUG] ContentRPC.getContentByWaveRef => StateDTO[null], time consumed: 3ms<br />2012-10-25 02:04:58 AuthenticatedMethodInterceptor [INFO] ----- Ending method: getContentByWaveRef- userhash: 1n9sg2jn48eld8o2ul8sg7b1r -----<br />2012-10-25 02:05:00 PendingNotificationImmediateJob [INFO] Immediate notifications cron job start, pendingNotifications: [0, 13, 5]<br />2012-10-25 02:05:00 PendingNotificationImmediateJob [INFO] Immediate notifications cron job end, pendingNotifications: [0, 13, 5]<br />2012-10-25 02:05:43 RackServletFilter [DEBUG] REQUEST: /ws/deferredjs/263731522B89AE30DF807A092AE86150/10.cache.js<br />2012-10-25 02:05:43 RackServletFilter [DEBUG] REQUEST: /ws/servlets/EntityLogoDownloadManager<br />2012-10-25 02:05:43 ServletServiceFilter [DEBUG] SERVICE: /ws/servlets/EntityLogoDownloadManager - EntityLogoDownloadManager<br />2012-10-25 02:05:43 RackServletFilter [DEBUG] REQUEST: /ws/servlets/EntityLogoDownloadManager<br />2012-10-25 02:05:43 ServletServiceFilter [DEBUG] SERVICE: /ws/servlets/EntityLogoDownloadManager - EntityLogoDownloadManager<br />2012-10-25 02:05:43 RackServletFilter [DEBUG] REQUEST: /ws/servlets/EntityLogoDownloadManager<br />2012-10-25 02:05:43 ServletServiceFilter [DEBUG] SERVICE: /ws/servlets/EntityLogoDownloadManager - EntityLogoDownloadManager<br />2012-10-25 02:05:43 RackServletFilter [DEBUG] REQUEST: /ws/servlets/EntityLogoDownloadManager<br />2012-10-25 02:05:43 ServletServiceFilter [DEBUG] SERVICE: /ws/servlets/EntityLogoDownloadManager - EntityLogoDownloadManager<br />2012-10-25 02:05:51 RackServletFilter [DEBUG] REQUEST: /ws/servlets/EntityLogoDownloadManager<br />2012-10-25 02:05:51 ServletServiceFilter [DEBUG] SERVICE: /ws/servlets/EntityLogoDownloadManager - EntityLogoDownloadManager<br />2012-10-25 02:06:00 PendingNotificationImmediateJob [INFO] Immediate notifications cron job start, pendingNotifications: [0, 13, 5]<br />2012-10-25 02:06:00 PendingNotificationImmediateJob [INFO] Immediate notifications cron job end, pendingNotifications: [0, 13, 5]<br />2012-10-25 02:07:00 PendingNotificationImmediateJob [INFO] Immediate notifications cron job start, pendingNotifications: [0, 13, 5]<br />2012-10-25 02:07:00 PendingNotificationImmediateJob [INFO] Immediate notifications cron job end, pendingNotifications: [0, 13, 5]<br />2012-10-25 02:08:00 PendingNotificationImmediateJob [INFO] Immediate notifications cron job start, pendingNotifications: [0, 13, 5]<br />2012-10-25 02:08:00 PendingNotificationImmediateJob [INFO] Immediate notifications cron job end, pendingNotifications: [0, 13, 5]<br />2012-10-25 02:09:00 PendingNotificationImmediateJob [INFO] Immediate notifications cron job start, pendingNotifications: [0, 13, 5]<br />2012-10-25 02:09:00 PendingNotificationImmediateJob [INFO] Immediate notifications cron job end, pendingNotifications: [0, 13, 5]<br />2012-10-25 02:09:00 nio [WARN] java.io.IOException: Broken pipe<br />2012-10-25 02:09:00 WaveEmailNotifier [INFO] '<a class="email" href="mailto:samer@kune.cc">samer@kune.cc</a>' update wave 'Emite'<br />2012-10-25 02:09:00 SqlExceptionHelper [WARN] SQL Error: 0, SQLState: 08003<br />2012-10-25 02:09:00 SqlExceptionHelper [ERROR] No operations allowed after connection closed.<br />2012-10-25 02:09:00 NewPooledConnection [WARN] [c3p0] A PooledConnection that has already signalled a Connection error is still in use!<br />2012-10-25 02:09:00 NewPooledConnection [WARN] [c3p0] Another error has occurred [ com.mysql.jdbc.exceptions.jdbc4.MySQLNonTransientConnectionException: No operations allowed after connection closed. ] which will not be reported to listeners!<br />com.mysql.jdbc.exceptions.jdbc4.MySQLNonTransientConnectionException: No operations allowed after connection closed.<br /> at sun.reflect.GeneratedConstructorAccessor164.newInstance(Unknown Source)<br /> at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)<br /> at java.lang.reflect.Constructor.newInstance(Constructor.java:532)<br /> at com.mysql.jdbc.Util.handleNewInstance(Util.java:409)<br /> at com.mysql.jdbc.Util.getInstance(Util.java:384)<br /> at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:1015)<br /> at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:989)<br /> at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:984)<br /> at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:929)<br /> at com.mysql.jdbc.ConnectionImpl.throwConnectionClosedException(ConnectionImpl.java:1193)<br /> at com.mysql.jdbc.ConnectionImpl.getMutex(ConnectionImpl.java:3035)<br /> at com.mysql.jdbc.ConnectionImpl.rollback(ConnectionImpl.java:4644)<br /> at com.mchange.v2.c3p0.impl.NewProxyConnection.rollback(NewProxyConnection.java:855)<br /> at org.hibernate.engine.transaction.internal.jdbc.JdbcTransaction.doRollback(JdbcTransaction.java:163)<br /> at org.hibernate.engine.transaction.spi.AbstractTransactionImpl.rollback(AbstractTransactionImpl.java:209)<br /> at org.hibernate.ejb.TransactionImpl.rollback(TransactionImpl.java:106)<br /> at com.google.inject.persist.jpa.KuneJpaLocalTxnInterceptor.rollbackIfNecessary(KuneJpaLocalTxnInterceptor.java:161)<br /> at com.google.inject.persist.jpa.KuneJpaLocalTxnInterceptor.invoke(KuneJpaLocalTxnInterceptor.java:77)<br /> at com.google.inject.internal.InterceptorStackCallback$InterceptedMethodInvocation.proceed(InterceptorStackCallback.java:72)<br /> at com.google.inject.internal.InterceptorStackCallback.intercept(InterceptorStackCallback.java:52)<br /> at cc.kune.core.server.notifier.PendingNotificationHourlyJob$$EnhancerByGuice$$701a4d54.execute(<generated>)<br /> at org.quartz.core.JobRunShell.run(JobRunShell.java:213)<br /> at org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:557)<br />2012-10-25 02:09:00 NewPooledConnection [WARN] [c3p0] A PooledConnection that has already signalled a Connection error is still in use!<br />2012-10-25 02:09:00 NewPooledConnection [WARN] [c3p0] Another error has occurred [ com.mysql.jdbc.exceptions.jdbc4.MySQLNonTransientConnectionException: No operations allowed after connection closed. ] which will not be reported to listeners!<br />com.mysql.jdbc.exceptions.jdbc4.MySQLNonTransientConnectionException: No operations allowed after connection closed.<br /> at sun.reflect.GeneratedConstructorAccessor164.newInstance(Unknown Source)<br /> at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)<br /> at java.lang.reflect.Constructor.newInstance(Constructor.java:532)<br /> at com.mysql.jdbc.Util.handleNewInstance(Util.java:409)<br /> at com.mysql.jdbc.Util.getInstance(Util.java:384)<br /> at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:1015)<br /> at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:989)<br /> at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:984)<br /> at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:929)<br /> at com.mysql.jdbc.ConnectionImpl.throwConnectionClosedException(ConnectionImpl.java:1193)<br /> at com.mysql.jdbc.ConnectionImpl.getMutex(ConnectionImpl.java:3035)<br /> at com.mysql.jdbc.ConnectionImpl.setAutoCommit(ConnectionImpl.java:4909)<br /> at com.mchange.v2.c3p0.impl.NewProxyConnection.setAutoCommit(NewProxyConnection.java:881)<br /> at org.hibernate.engine.transaction.internal.jdbc.JdbcTransaction.releaseManagedConnection(JdbcTransaction.java:127)<br /> at org.hibernate.engine.transaction.internal.jdbc.JdbcTransaction.doRollback(JdbcTransaction.java:170)<br /> at org.hibernate.engine.transaction.spi.AbstractTransactionImpl.rollback(AbstractTransactionImpl.java:209)<br /> at org.hibernate.ejb.TransactionImpl.rollback(TransactionImpl.java:106)<br /> at com.google.inject.persist.jpa.KuneJpaLocalTxnInterceptor.rollbackIfNecessary(KuneJpaLocalTxnInterceptor.java:161)<br /> at com.google.inject.persist.jpa.KuneJpaLocalTxnInterceptor.invoke(KuneJpaLocalTxnInterceptor.java:77)<br /> at com.google.inject.internal.InterceptorStackCallback$InterceptedMethodInvocation.proceed(InterceptorStackCallback.java:72)<br /> at com.google.inject.internal.InterceptorStackCallback.intercept(InterceptorStackCallback.java:52)<br /> at cc.kune.core.server.notifier.PendingNotificationHourlyJob$$EnhancerByGuice$$701a4d54.execute(<generated>)<br /> at org.quartz.core.JobRunShell.run(JobRunShell.java:213)<br /> at org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:557)<br />2012-10-25 02:09:00 JobRunShell [ERROR] Job groupdef.pendingnotifhourlyjob threw an unhandled Exception: <br />javax.persistence.PersistenceException: unexpected error when rollbacking<br /> at org.hibernate.ejb.TransactionImpl.rollback(TransactionImpl.java:109)<br /> at com.google.inject.persist.jpa.KuneJpaLocalTxnInterceptor.rollbackIfNecessary(KuneJpaLocalTxnInterceptor.java:161)<br /> at com.google.inject.persist.jpa.KuneJpaLocalTxnInterceptor.invoke(KuneJpaLocalTxnInterceptor.java:77)<br /> at org.quartz.core.JobRunShell.run(JobRunShell.java:213)<br /> at org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:557)<br />Caused by: org.hibernate.TransactionException: rollback failed<br /> at org.hibernate.engine.transaction.spi.AbstractTransactionImpl.rollback(AbstractTransactionImpl.java:215)<br /> at org.hibernate.ejb.TransactionImpl.rollback(TransactionImpl.java:106)<br /> ... 4 more<br />Caused by: org.hibernate.TransactionException: unable to rollback against JDBC connection<br /> at org.hibernate.engine.transaction.internal.jdbc.JdbcTransaction.doRollback(JdbcTransaction.java:167)<br /> at org.hibernate.engine.transaction.spi.AbstractTransactionImpl.rollback(AbstractTransactionImpl.java:209)<br /> ... 5 more<br />Caused by: com.mysql.jdbc.exceptions.jdbc4.MySQLNonTransientConnectionException: No operations allowed after connection closed.<br /> at sun.reflect.GeneratedConstructorAccessor164.newInstance(Unknown Source)<br /> at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)<br /> at java.lang.reflect.Constructor.newInstance(Constructor.java:532)<br /> at com.mysql.jdbc.Util.handleNewInstance(Util.java:409)<br /> at com.mysql.jdbc.Util.getInstance(Util.java:384)<br /> at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:1015)<br /> at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:989)<br /> at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:984)<br /> at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:929)<br /> at com.mysql.jdbc.ConnectionImpl.throwConnectionClosedException(ConnectionImpl.java:1193)<br /> at com.mysql.jdbc.ConnectionImpl.getMutex(ConnectionImpl.java:3035)<br /> at com.mysql.jdbc.ConnectionImpl.rollback(ConnectionImpl.java:4644)<br /> at com.mchange.v2.c3p0.impl.NewProxyConnection.rollback(NewProxyConnection.java:855)<br /> at org.hibernate.engine.transaction.internal.jdbc.JdbcTransaction.doRollback(JdbcTransaction.java:163)<br /> ... 6 more<br />2012-10-25 02:09:00 ErrorLogger [ERROR] Job (groupdef.pendingnotifhourlyjob threw an exception.<br />org.quartz.SchedulerException: Job threw an unhandled exception. [See nested exception: javax.persistence.PersistenceException: unexpected error when rollbacking]<br /> at org.quartz.core.JobRunShell.run(JobRunShell.java:224)<br /> at org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:557)<br />Caused by: javax.persistence.PersistenceException: unexpected error when rollbacking<br /> at org.hibernate.ejb.TransactionImpl.rollback(TransactionImpl.java:109)<br /> at com.google.inject.persist.jpa.KuneJpaLocalTxnInterceptor.rollbackIfNecessary(KuneJpaLocalTxnInterceptor.java:161)<br /> at com.google.inject.persist.jpa.KuneJpaLocalTxnInterceptor.invoke(KuneJpaLocalTxnInterceptor.java:77)<br /> at org.quartz.core.JobRunShell.run(JobRunShell.java:213)<br /> ... 1 more<br />Caused by: org.hibernate.TransactionException: rollback failed<br /> at org.hibernate.engine.transaction.spi.AbstractTransactionImpl.rollback(AbstractTransactionImpl.java:215)<br /> at org.hibernate.ejb.TransactionImpl.rollback(TransactionImpl.java:106)<br /> ... 4 more<br />Caused by: org.hibernate.TransactionException: unable to rollback against JDBC connection<br /> at org.hibernate.engine.transaction.internal.jdbc.JdbcTransaction.doRollback(JdbcTransaction.java:167)<br /> at org.hibernate.engine.transaction.spi.AbstractTransactionImpl.rollback(AbstractTransactionImpl.java:209)<br /> ... 5 more<br />Caused by: com.mysql.jdbc.exceptions.jdbc4.MySQLNonTransientConnectionException: No operations allowed after connection closed.<br /> at sun.reflect.GeneratedConstructorAccessor164.newInstance(Unknown Source)<br /> at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)<br /> at java.lang.reflect.Constructor.newInstance(Constructor.java:532)<br /> at com.mysql.jdbc.Util.handleNewInstance(Util.java:409)<br /> at com.mysql.jdbc.Util.getInstance(Util.java:384)<br /> at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:1015)<br /> at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:989)<br /> at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:984)<br /> at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:929)<br /> at com.mysql.jdbc.ConnectionImpl.throwConnectionClosedException(ConnectionImpl.java:1193)<br /> at com.mysql.jdbc.ConnectionImpl.getMutex(ConnectionImpl.java:3035)<br /> at com.mysql.jdbc.ConnectionImpl.rollback(ConnectionImpl.java:4644)<br /> at com.mchange.v2.c3p0.impl.NewProxyConnection.rollback(NewProxyConnection.java:855)<br /> at org.hibernate.engine.transaction.internal.jdbc.JdbcTransaction.doRollback(JdbcTransaction.java:163)<br /> ... 6 more</p>
<hr />
<span class="footer"><p>You have received this notification because you have either subscribed to it, or are involved in it.<br />To change your notification preferences, please click here: <a class="external" href="http://redmine.ourproject.org/my/account">http://redmine.ourproject.org/my/account</a></p></span>
</body>
</html>