Mantis Bugtracker
Main | My View | View Issues | Change Log | Docs
  

Viewing Issue Advanced Details [ Jump to Notes ] [ View Simple ] [ Issue History ] [ Print ]
ID Category Severity Reproducibility Date Submitted Last Update
0000850 [Resin] major random 01-10-06 06:51 01-24-06 14:45
Reporter flausa View Status public  
Assigned To ferg
Priority normal Resolution fixed Platform
Status closed   OS
Projection none   OS Version
ETA none Fixed in Version 3.0.18 Product Version 3.0.17
  Product Build
Summary 0000850: Server not responding
Description We are getting the following stack trace in our resin 3.0.17 log.

[2006-01-10 10:50:03.738] java.sql.SQLException: transaction timed out waiting for lock 30090

[2006-01-10 10:50:03.738] at com.caucho.db.store.Lock.queue(Lock.java:348)

[2006-01-10 10:50:03.738] at com.caucho.db.store.Lock.queue(Lock.java:315)

[2006-01-10 10:50:03.738] at com.caucho.db.store.Lock.lockRead(Lock.java:92)

[2006-01-10 10:50:03.738] at com.caucho.db.store.Transaction.lockAutoCommitRead(Transaction.java:223)

[2006-01-10 10:50:03.738] at com.caucho.db.sql.SelectQuery.execute(SelectQuery.java:159)

[2006-01-10 10:50:03.738] at com.caucho.db.jdbc.PreparedStatementImpl.execute(PreparedStatementImpl.java:339)

[2006-01-10 10:50:03.738] at com.caucho.db.jdbc.PreparedStatementImpl.executeQuery(PreparedStatementImpl.java:314)

[2006-01-10 10:50:03.738] at com.caucho.server.cluster.FileBacking.loadSelf(FileBacking.java:309)

[2006-01-10 10:50:03.738] at com.caucho.server.cluster.ClusterStore.load(ClusterStore.java:404)

[2006-01-10 10:50:03.738] at com.caucho.server.cluster.ClusterObject.load(ClusterObject.java:255)

[2006-01-10 10:50:03.738] at com.caucho.server.session.SessionImpl.load(SessionImpl.java:689)

[2006-01-10 10:50:03.738] at com.caucho.server.session.SessionManager.getSession(SessionManager.java:1048)

[2006-01-10 10:50:03.738] at com.caucho.server.connection.AbstractHttpRequest.createSession(AbstractHttpRequest.java:1364)

[2006-01-10 10:50:03.738] at com.caucho.server.connection.AbstractHttpRequest.getSession(AbstractHttpRequest.java:1174)

[2006-01-10 10:50:03.738] at _jsp._jsp._customer._login__jsp._jspService(_login__jsp.java:43)

[2006-01-10 10:50:03.738] at com.caucho.jsp.JavaPage.service(JavaPage.java:60)

[2006-01-10 10:50:03.738] at com.caucho.jsp.Page.pageservice(Page.java:570)

[2006-01-10 10:50:03.738] at com.caucho.server.dispatch.PageFilterChain.doFilter(PageFilterChain.java:159)

[2006-01-10 10:50:03.738] at com.caucho.server.webapp.WebAppFilterChain.doFilter(WebAppFilterChain.java:178)

[2006-01-10 10:50:03.738] at com.caucho.server.dispatch.ServletInvocation.service(ServletInvocation.java:229)

[2006-01-10 10:50:03.738] at com.caucho.server.hmux.HmuxRequest.handleRequest(HmuxRequest.java:419)

[2006-01-10 10:50:03.738] at com.caucho.server.port.TcpConnection.run(TcpConnection.java:388)

[2006-01-10 10:50:03.738] at com.caucho.util.ThreadPool.runTasks(ThreadPool.java:490)

[2006-01-10 10:50:03.738] at com.caucho.util.ThreadPool.run(ThreadPool.java:423)

[2006-01-10 10:50:03.738] at java.lang.Thread.run(Thread.java:534)

[MEMLEAK] Java memory total=536 MB free=117 MB used=419 MB Time: Tue Jan 10 10:50:03 CET 2006

[2006-01-10 10:50:03.741] java.sql.SQLException: transaction timed out waiting for lock 30090

[2006-01-10 10:50:03.741] at com.caucho.db.store.Lock.queue(Lock.java:348)

[2006-01-10 10:50:03.741] at com.caucho.db.store.Lock.queue(Lock.java:315)

[2006-01-10 10:50:03.741] at com.caucho.db.store.Lock.lockRead(Lock.java:92)

[2006-01-10 10:50:03.741] at com.caucho.db.store.Transaction.lockAutoCommitRead(Transaction.java:223)

[2006-01-10 10:50:03.741] at com.caucho.db.sql.SelectQuery.execute(SelectQuery.java:159)

[2006-01-10 10:50:03.741] at com.caucho.db.jdbc.PreparedStatementImpl.execute(PreparedStatementImpl.java:339)

[2006-01-10 10:50:03.741] at com.caucho.db.jdbc.PreparedStatementImpl.executeQuery(PreparedStatementImpl.java:314)

[2006-01-10 10:50:03.741] at com.caucho.server.cluster.FileBacking.loadSelf(FileBacking.java:309)

[2006-01-10 10:50:03.741] at com.caucho.server.cluster.ClusterStore.load(ClusterStore.java:404)

[2006-01-10 10:50:03.741] at com.caucho.server.cluster.ClusterObject.load(ClusterObject.java:255)

[2006-01-10 10:50:03.741] at com.caucho.server.session.SessionImpl.load(SessionImpl.java:689)

[2006-01-10 10:50:03.741] at com.caucho.server.session.SessionManager.getSession(SessionManager.java:1048)

[2006-01-10 10:50:03.741] at com.caucho.server.connection.AbstractHttpRequest.createSession(AbstractHttpRequest.java:1364)

[2006-01-10 10:50:03.741] at com.caucho.server.connection.AbstractHttpRequest.getSession(AbstractHttpRequest.java:1174)

[2006-01-10 10:50:03.741] at _jsp._jsp._customer._login__jsp._jspService(_login__jsp.java:43)

[2006-01-10 10:50:03.741] at com.caucho.jsp.JavaPage.service(JavaPage.java:60)

[2006-01-10 10:50:03.741] at com.caucho.jsp.Page.pageservice(Page.java:570)

[2006-01-10 10:50:03.741] at com.caucho.server.dispatch.PageFilterChain.doFilter(PageFilterChain.java:159)

[2006-01-10 10:50:03.741] at com.caucho.server.webapp.WebAppFilterChain.doFilter(WebAppFilterChain.java:178)

[2006-01-10 10:50:03.741] at com.caucho.server.dispatch.ServletInvocation.service(ServletInvocation.java:229)

[2006-01-10 10:50:03.741] at com.caucho.server.hmux.HmuxRequest.handleRequest(HmuxRequest.java:419)

[2006-01-10 10:50:03.741] at com.caucho.server.port.TcpConnection.run(TcpConnection.java:388)

[2006-01-10 10:50:03.741] at com.caucho.util.ThreadPool.runTasks(ThreadPool.java:490)

[2006-01-10 10:50:03.741] at com.caucho.util.ThreadPool.run(ThreadPool.java:423)

[2006-01-10 10:50:03.741] at java.lang.Thread.run(Thread.java:534)

[MEMLEAK] Java memory total=536 MB free=117 MB used=419 MB Time: Tue Jan 10 10:50:03 CET 2006

[MEMLEAK] Java memory total=536 MB free=117 MB used=419 MB Time: Tue Jan 10 10:50:03 CET 2006

[MEMLEAK] Java memory total=536 MB free=117 MB used=419 MB Time: Tue Jan 10 10:50:03 CET 2006

COOKIE VAL:63430532@2005-09-2612:55:29@d5425454e4e9b55b53b20f7edbb7ca83e31427f8

COOKIE VAL:63430532@2005-09-2612:55:29@d5425454e4e9b55b53b20f7edbb7ca83e31427f8

COOKIE VAL:63430532@2005-09-2612:55:29@d5425454e4e9b55b53b20f7edbb7ca83e31427f8

COOKIE VAL:63430532@2005-09-2612:55:29@d5425454e4e9b55b53b20f7edbb7ca83e31427f8

[MEMLEAK] Java memory total=536 MB free=113 MB used=423 MB Time: Tue Jan 10 10:50:24 CET 2006

[2006-01-10 10:50:33.668] Can't store session default;bvP-YS9GClMd6fbUZI

[2006-01-10 10:50:44.727] java.sql.SQLException: transaction timed out waiting for lock 10710

[2006-01-10 10:50:44.727] at com.caucho.db.store.Lock.queue(Lock.java:348)

[2006-01-10 10:50:44.727] at com.caucho.db.store.Lock.queue(Lock.java:315)

[2006-01-10 10:50:44.727] at com.caucho.db.store.Lock.lockReadAndUpgrade(Lock.java:190)

[2006-01-10 10:50:44.727] at com.caucho.db.store.Transaction.lockAutoCommitWrite(Transaction.java:241)

[2006-01-10 10:50:44.727] at com.caucho.db.sql.DeleteQuery.execute(DeleteQuery.java:82)

[2006-01-10 10:50:44.727] at com.caucho.db.jdbc.PreparedStatementImpl.execute(PreparedStatementImpl.java:349)

[2006-01-10 10:50:44.727] at com.caucho.db.jdbc.PreparedStatementImpl.executeUpdate(PreparedStatementImpl.java:325)

[2006-01-10 10:50:44.727] at com.caucho.server.cluster.FileBacking.remove(FileBacking.java:375)

[2006-01-10 10:50:44.727] at com.caucho.server.cluster.ClusterStore.removeSelf(ClusterStore.java:894)

[2006-01-10 10:50:44.727] at com.caucho.server.cluster.ClusterStore.remove(ClusterStore.java:860)

[2006-01-10 10:50:44.727] at com.caucho.server.cluster.ClusterObject.remove(ClusterObject.java:431)

[2006-01-10 10:50:44.727] at com.caucho.server.session.SessionImpl.invalidateImpl(SessionImpl.java:602)

[2006-01-10 10:50:44.727] at com.caucho.server.session.SessionImpl.invalidate(SessionImpl.java:546)

This looks like the following issue that was resolved in 3.0.15, http://bugs.caucho.com/view.php?id=419 [^]

The server simply stops responding and the only solution is to restart the server. We are using the following configuration:

Linux RH ES 3.0
Oracle 9i
Apache 2.0

Our config file looks like this:

    <!-- the srun port, read by both JVM and plugin -->
    <cluster>
      <client-live-time>30s</client-live-time>
      <srun id="a" host='xxxxxx' port='6802' index='1'/>
      <srun id="b" host='xxxxxx' port='6803' index='2'/>
    </cluster>

 <persistent-store type="cluster">
    <init path="$server-root/ne/WEB-INF/sessions"/>
  </persistent-store>

      <session-config>
        <use-persistent-store>true</use-persistent-store>
        <session-max>2600</session-max>
         <session-timeout>180</session-timeout>
        <reuse-session-id>false</reuse-session-id>
      </session-config>
Steps To Reproduce
Additional Information
Attached Files

- Relationships

- Notes
(0000769)
ferg
01-24-06 14:45

 timing/locking issues in session store
 

- Issue History
Date Modified Username Field Change
01-10-06 06:51 flausa New Issue
01-10-06 06:57 flausa Issue Monitored: flausa
01-10-06 06:58 flausa Issue End Monitor: flausa
01-24-06 14:45 ferg Note Added: 0000769
01-24-06 14:45 ferg Assigned To  => ferg
01-24-06 14:45 ferg Status new => closed
01-24-06 14:45 ferg Resolution open => fixed
01-24-06 14:45 ferg Fixed in Version  => 3.0.18

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