Mantis - Resin
Viewing Issue Advanced Details
ID: Category: Severity: Reproducibility: Date Submitted: Last Update:
419 minor always 10-10-05 00:00 12-08-05 07:37
Reporter: arvoreen Platform:  
Assigned To: OS:  
Priority: high OS Version:  
Status: closed Product Version: 3.0.14  
Product Build: 3.0.14 Resolution: reopened  
Projection: none      
ETA: none Fixed in Version:  
Summary: 0000419: Transaction timeout problems with cluster and file sessions store
Description: RSN-465
I am seeing the following errors in my stdout.log file:


[2005/10/10 13:15:25.120] at java.lang.Thread.run(Thread.java:595)
[2005/10/10 13:15:27.630] Can't store session default__eon;a3Q2qlBTIc8eVrJHBB
[2005/10/10 13:15:28.640] Can't store session default__eon;aTHQw-4tYCNaIErEAB
[2005/10/10 13:15:31.150] Can't store session default__eon;akFD1QxAmZtfWc1xAB
[2005/10/10 13:15:34.669] Can't store session default__eon;a9IraxwdmP_-Gp6MAB
[2005/10/10 13:15:36.679] Can't store session default__eon;asVlQcmLL53_lHLHBB
[2005/10/10 13:15:39.209] Can't store session default__eon;arx4iJoKhwn95JjtAB
[2005/10/10 13:15:39.211] java.sql.SQLException: transaction timed out waiting for lock 30009
[2005/10/10 13:15:39.211] at com.caucho.db.store.Lock.queue(Lock.java:348)
[2005/10/10 13:15:39.211] at com.caucho.db.store.Lock.queue(Lock.java:315)
[2005/10/10 13:15:39.211] at com.caucho.db.store.Lock.lockRead(Lock.java:92)
[2005/10/10 13:15:39.211] at com.caucho.db.store.Transaction.lockAutoCommitRead(Transaction.java:222)
[2005/10/10 13:15:39.211] at com.caucho.db.sql.SelectQuery.execute(SelectQuery.java:160)
[2005/10/10 13:15:39.211] at com.caucho.db.jdbc.PreparedStatementImpl.execute(PreparedStatementImpl.java:339)
[2005/10/10 13:15:39.211] at com.caucho.db.jdbc.PreparedStatementImpl.executeQuery(PreparedStatementImpl.java:314)
[2005/10/10 13:15:39.211] at com.caucho.server.cluster.FileBacking.loadSelf(FileBacking.java:309)
[2005/10/10 13:15:39.211] at com.caucho.server.cluster.FileStore.load(FileStore.java:141)
[2005/10/10 13:15:39.211] at com.caucho.server.cluster.ClusterObject.load(ClusterObject.java:245)
[2005/10/10 13:15:39.211] at com.caucho.server.session.SessionImpl.load(SessionImpl.java:677)
[2005/10/10 13:15:39.211] at com.caucho.server.session.SessionManager.getSession(SessionManager.java:1035)
[2005/10/10 13:15:39.211] at com.caucho.server.connection.AbstractHttpRequest.createSession(AbstractHttpRequest.java:1364)
[2005/10/10 13:15:39.211] at com.caucho.server.connection.AbstractHttpRequest.getSession(AbstractHttpRequest.java:1174)
[2005/10/10 13:15:39.211] at _jsp._index__jsp._jspService(_index__jsp.java:18)
[2005/10/10 13:15:39.211] at com.caucho.jsp.JavaPage.service(JavaPage.java:60)
[2005/10/10 13:15:39.211] at com.caucho.jsp.Page.pageservice(Page.java:570)
[2005/10/10 13:15:39.211] at com.caucho.server.dispatch.PageFilterChain.doFilter(PageFilterChain.java:159)
[2005/10/10 13:15:39.211] at com.gblx.common.jsp.DBAccessFilter.doFilter(DBAccessFilter.java:198)
[2005/10/10 13:15:39.211] at com.caucho.server.dispatch.FilterFilterChain.doFilter(FilterFilterChain.java:70)
[2005/10/10 13:15:39.211] at com.gblx.common.jsp.LogPostRequests.doFilter(LogPostRequests.java:64)
[2005/10/10 13:15:39.211] at com.caucho.server.dispatch.FilterFilterChain.doFilter(FilterFilterChain.java:70)
[2005/10/10 13:15:39.211] at com.caucho.server.cache.CacheFilterChain.doFilter(CacheFilterChain.java:175)
[2005/10/10 13:15:39.211] at com.caucho.server.webapp.WebAppFilterChain.doFilter(WebAppFilterChain.java:163)
[2005/10/10 13:15:39.211] at com.caucho.server.dispatch.ServletInvocation.service(ServletInvocation.java:208)
[2005/10/10 13:15:39.211] at com.caucho.server.http.HttpRequest.handleRequest(HttpRequest.java:259)
[2005/10/10 13:15:39.211] at com.caucho.server.port.TcpConnection.run(TcpConnection.java:363)
[2005/10/10 13:15:39.211] at com.caucho.util.ThreadPool.runTasks(ThreadPool.java:490)
[2005/10/10 13:15:39.211] at com.caucho.util.ThreadPool.run(ThreadPool.java:423)
[2005/10/10 13:15:39.211] at java.lang.Thread.run(Thread.java:595)


These types of errors happen multiple times in the log file. Here are the relavant configuration sections with regard to sessions:
  <session-config>
          <session-timeout>480</session-timeout>
          <file-store>WEB-INF/sessions</file-store>
          <always-save-session/>
  </session-config>
Steps To Reproduce:
Additional Information: Solaris 9
Relationships
Attached Files:

Notes
(0000466)
sam   
10-10-05 00:00   
(rep by K Fetterman)

[18:26:21.479] java.sql.SQLException: transaction timed out waiting for
lock 1020
[18:26:21.479] at com.caucho.db.store.Lock.queue(Lock.java:348)
[18:26:21.479] at com.caucho.db.store.Lock.queue(Lock.java:315)
[18:26:21.479] at
com.caucho.db.store.Lock.lockReadAndUpgrade(Lock.java:190)
[18:26:21.479] at
com.caucho.db.store.Transaction.lockAutoCommitWrite(Transaction.java:240)
[18:26:21.479] at
com.caucho.db.sql.UpdateQuery.execute(UpdateQuery.java:106)

and

[02:04:07.890] Can't store session default;aaOhVCTnmiK4OvGNuB
[02:04:09.417] Can't store session default;aExvxLlqRB8eunHNuB
[02:04:09.417] Can't store session default;a4WG8jzTMN74i9HNuB

Since a JSP file is referenced in the stack trace, I looked
at the Java source file at line 22. The Java source statement is
below:

[18:26:21.479] at _jsp._meeting__jsp._jspService(_greeting__jsp.java:22)

javax.servlet.http.HttpSession session = request.getSession(true);
(0000467)
keithdfetterman   
10-10-05 00:00   
I want to add another example of the problem. The code that was executed at

[2005-10-15 22:55:12.655] at com.marinersupply.bean.CustomerBean.logout(CustomerBean.java:406)

was:

        session.invalidate();

This is the Exception/stacktrace that was the result when the invalidate() method failed:

[2005-10-15 22:55:12.655] java.sql.SQLException: transaction timed out waiting for lock 3060
[2005-10-15 22:55:12.655] at com.caucho.db.store.Lock.queue(Lock.java:348)
[2005-10-15 22:55:12.655] at com.caucho.db.store.Lock.queue(Lock.java:315)
[2005-10-15 22:55:12.655] at com.caucho.db.store.Lock.lockReadAndUpgrade(Lock.java:190)
[2005-10-15 22:55:12.655] at com.caucho.db.store.Transaction.lockAutoCommitWrite(Transaction.java:240)
[2005-10-15 22:55:12.655] at com.caucho.db.sql.DeleteQuery.execute(DeleteQuery.java:82)
[2005-10-15 22:55:12.655] at com.caucho.db.jdbc.PreparedStatementImpl.execute(PreparedStatementImpl.java:349)
[2005-10-15 22:55:12.655] at com.caucho.db.jdbc.PreparedStatementImpl.executeUpdate(PreparedStatementImpl.java:325)
[2005-10-15 22:55:12.655] at com.caucho.server.cluster.FileBacking.remove(FileBacking.java:375)
[2005-10-15 22:55:12.655] at com.caucho.server.cluster.FileStore.remove(FileStore.java:189)
[2005-10-15 22:55:12.655] at com.caucho.server.cluster.ClusterObject.remove(ClusterObject.java:421)
[2005-10-15 22:55:12.655] at com.caucho.server.session.SessionImpl.invalidateImpl(SessionImpl.java:590)
[2005-10-15 22:55:12.655] at com.caucho.server.session.SessionImpl.invalidate(SessionImpl.java:545)
[2005-10-15 22:55:12.655] at com.caucho.server.session.SessionImpl.invalidate(SessionImpl.java:506)
[2005-10-15 22:55:12.655] at com.marinersupply.bean.CustomerBean.logout(CustomerBean.java:406)
[2005-10-15 22:55:12.655] at com.marinersupply.servlet.actions.NewCommercialCustomer.doAction(NewCommercialCustomer.java:62)
[2005-10-15 22:55:12.655] at com.marinersupply.servlet.Control.service(Control.java:164)
[2005-10-15 22:55:12.655] at javax.servlet.http.HttpServlet.service(HttpServlet.java:92)
[2005-10-15 22:55:12.655] at com.caucho.server.dispatch.ServletFilterChain.doFilter(ServletFilterChain.java:99)
[2005-10-15 22:55:12.655] at com.caucho.server.cache.CacheFilterChain.doFilter(CacheFilterChain.java:175)
[2005-10-15 22:55:12.655] at com.caucho.server.webapp.WebAppFilterChain.doFilter(WebAppFilterChain.java:163)
[2005-10-15 22:55:12.655] at com.caucho.server.dispatch.ServletInvocation.service(ServletInvocation.java:208)
[2005-10-15 22:55:12.655] at com.caucho.server.hmux.HmuxRequest.handleRequest(HmuxRequest.java:396)
[2005-10-15 22:55:12.655] at com.caucho.server.port.TcpConnection.run(TcpConnection.java:363)
[2005-10-15 22:55:12.655] at com.caucho.util.ThreadPool.runTasks(ThreadPool.java:490)
[2005-10-15 22:55:12.655] at com.caucho.util.ThreadPool.run(ThreadPool.java:423)
[2005-10-15 22:55:12.655] at java.lang.Thread.run(Thread.java:534)
(0000529)
arvoreen   
12-08-05 06:51   
Why was this issue closed with no resolution or comments?
(0000530)
ferg   
12-08-05 07:37   
Fixed in 3.0.15. If a related problem occurs, please file it against 3.0.15, not 3.0.14.