Mantis Bugtracker
  

Viewing Issue Advanced Details Jump to Notes ] View Simple ] Issue History ] Print ]
ID Category Severity Reproducibility Date Submitted Last Update
0000419 [Resin] minor always 10-10-05 00:00 12-08-05 07:37
Reporter arvoreen View Status public  
Assigned To
Priority high Resolution reopened Platform
Status closed   OS
Projection none   OS Version
ETA none Fixed in Version Product Version 3.0.14
  Product Build 3.0.14
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
Attached Files

- Relationships

- 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.
 

- Issue History
Date Modified Username Field Change
10-10-05 00:00 arvoreen New Issue
12-07-05 15:25 ferg Status acknowledged => closed
12-08-05 06:51 arvoreen Status closed => feedback
12-08-05 06:51 arvoreen Resolution open => reopened
12-08-05 06:51 arvoreen Note Added: 0000529
12-08-05 07:37 ferg Status feedback => closed
12-08-05 07:37 ferg Note Added: 0000530
02-12-06 21:52 keisuk-t Issue Monitored: keisuk-t


Mantis 1.0.0rc3[^]
Copyright © 2000 - 2005 Mantis Group
37 total queries executed.
32 unique queries executed.
Powered by Mantis Bugtracker