0005247: session database starts rapidly grow in size after exception

Viewing Issue Advanced Details [ Jump to Notes ]

[ View Simple ] [ Issue History ] [ Print ]

Category

Severity

Reproducibility

Date Submitted

Last Update

0005247

[Resin]

minor

always

10-17-12 15:26

10-25-12 12:27

Reporter

alex

View Status

public

Assigned To

ferg

Priority

normal

Resolution

fixed

Platform

Status

closed

Projection

none

OS Version

ETA

none

Fixed in Version

4.0.30

Product Version

3.1.12

Product Build

Summary

0005247: session database starts rapidly grow in size after exception

Description

rep by : Julius Gnanasigamani
268347.559: [GC [PSYoungGen: 311640K->60422K(332224K)] 2842323K->2591651K(2892224K), 0.0549600 secs] [Times: user=0.37 sys=0.00, real=0.06 secs]
[2012-10-16 18:04:46.487] [WARNING] ClusterObject: java.lang.IllegalStateException: block at 7e00fe0000000000 is invalid for file /local/apps/www/app/cluster/srun_appserver43.db (length 1b5a0000)
[2012-10-16 18:04:46.487] at com.caucho.db.store.Store.__AW_readBlock(Store.java:1699)
[2012-10-16 18:04:46.487] at com.caucho.db.store.Store.readBlock(Store.java)
[2012-10-16 18:04:46.487] at com.caucho.db.store.Block.read(Block.java:157)
[2012-10-16 18:04:46.487] at com.caucho.db.store.Store.readBlock(Store.java:475)
[2012-10-16 18:04:46.487] at com.caucho.db.store.Transaction.readBlock(Transaction.java:372)
[2012-10-16 18:04:46.487] at com.caucho.db.store.Store.writeBlockLong(Store.java:1278)
[2012-10-16 18:04:46.487] at com.caucho.db.store.Inode.writeBlockAddr(Inode.java:1118)
[2012-10-16 18:04:46.487] at com.caucho.db.store.Inode.appendBlock(Inode.java:496)
[2012-10-16 18:04:46.487] at com.caucho.db.store.Inode.append(Inode.java:388)
[2012-10-16 18:04:46.487] at com.caucho.db.store.BlobOutputStream.flushBlock(BlobOutputStream.java:188)
[2012-10-16 18:04:46.487] at com.caucho.db.store.BlobOutputStream.close(BlobOutputStream.java:159)
[2012-10-16 18:04:46.487] at com.caucho.db.table.BlobColumn.setStream(BlobColumn.java:182)
[2012-10-16 18:04:46.487] at com.caucho.db.table.BlobColumn.set(BlobColumn.java:267)
[2012-10-16 18:04:46.487] at com.caucho.db.sql.UpdateQuery.__AW_execute(UpdateQuery.java:110)
[2012-10-16 18:04:46.487] at com.caucho.db.sql.UpdateQuery.execute(UpdateQuery.java)
[2012-10-16 18:04:46.487] at com.caucho.db.jdbc.PreparedStatementImpl.__AW_execute(PreparedStatementImpl.java:345)
[2012-10-16 18:04:46.487] at com.caucho.db.jdbc.PreparedStatementImpl.execute(PreparedStatementImpl.java)
[2012-10-16 18:04:46.487] at com.caucho.db.jdbc.PreparedStatementImpl.__AW_executeUpdate(PreparedStatementImpl.java:313)
[2012-10-16 18:04:46.487] at com.caucho.db.jdbc.PreparedStatementImpl.executeUpdate(PreparedStatementImpl.java)
[2012-10-16 18:04:46.487] at com.caucho.server.cluster.FileBacking.storeSelfUpdate(FileBacking.java:524)
[2012-10-16 18:04:46.487] at com.caucho.server.cluster.FileBacking.storeSelf(FileBacking.java:474)
[2012-10-16 18:04:46.487] at com.caucho.server.cluster.ClusterStore.__AW_store(ClusterStore.java:642)
[2012-10-16 18:04:46.487] at com.caucho.server.cluster.ClusterStore.store(ClusterStore.java)
[2012-10-16 18:04:46.487] at com.caucho.server.cluster.ClusterObject.__AW_store(ClusterObject.java:436)
[2012-10-16 18:04:46.487] at com.caucho.server.cluster.ClusterObject.store(ClusterObject.java)
[2012-10-16 18:04:46.487] at com.caucho.server.session.SessionImpl.save(SessionImpl.java:952)
[2012-10-16 18:04:46.487] at com.caucho.server.connection.AbstractHttpRequest.saveSession(AbstractHttpRequest.java:2519)
[2012-10-16 18:04:46.487] at com.caucho.server.connection.AbstractHttpResponse.__AW_sendRedirect(AbstractHttpResponse.java:659)
[2012-10-16 18:04:46.487] at com.caucho.server.connection.AbstractHttpResponse.sendRedirect(AbstractHttpResponse.java)
[2012-10-16 18:04:46.487] at com.ihg.commons.interceptor.CountryLanguageInterceptor.checkAndRedirect(CountryLanguageInterceptor.java:55)
[2012-10-16 18:04:46.487] at com.ihg.commons.interceptor.CountryLanguageInterceptor.handleUSENURL(CountryLanguageInterceptor.java:150)
[2012-10-16 18:04:46.487] at com.ihg.commons.interceptor.CountryLanguageInterceptor.handleCountryLanguage(CountryLanguageInterceptor.java:96)
[2012-10-16 18:04:46.487] at com.ihg.commons.interceptor.CountryLanguageInterceptor.__AW_preHandle(CountryLanguageInterceptor.java:188)
[2012-10-16 18:04:46.487] at com.ihg.commons.interceptor.CountryLanguageInterceptor.preHandle(CountryLanguageInterceptor.java)
[2012-10-16 18:04:46.487] at org.springframework.web.servlet.DispatcherServlet.doDispatch(DispatcherServlet.java:781)
[2012-10-16 18:04:46.487] at org.springframework.web.servlet.DispatcherServlet.doService(DispatcherServlet.java:719)
[2012-10-16 18:04:46.487] at org.springframework.web.servlet.FrameworkServlet.processRequest(FrameworkServlet.java:669)
[2012-10-16 18:04:46.487] at org.springframework.web.servlet.FrameworkServlet.doPost(FrameworkServlet.java:585)
[2012-10-16 18:04:46.487] at javax.servlet.http.HttpServlet.service(HttpServlet.java:157)
[2012-10-16 18:04:46.487] at javax.servlet.http.HttpServlet.__AW_service(HttpServlet.java:95)
[2012-10-16 18:04:46.487] at javax.servlet.http.HttpServlet.service(HttpServlet.java)
[2012-10-16 18:04:46.487] at com.caucho.server.dispatch.ServletFilterChain.doFilter(ServletFilterChain.java:103)
[2012-10-16 18:04:46.487] at com.ihg.pcr2.cdm.CDCFilter.doFilter(CDCFilter.java:173)
[2012-10-16 18:04:46.487] at com.caucho.server.dispatch.FilterFilterChain.doFilter(FilterFilterChain.java:87)
[2012-10-16 18:04:46.487] at com.ihg.dec.apps.webframework.servlet.info.ServerInfoFilter.doFilter(ServerInfoFilter.java:61)
[2012-10-16 18:04:46.487] at com.caucho.server.dispatch.FilterFilterChain.doFilter(FilterFilterChain.java:87)
[2012-10-16 18:04:46.487] at com.ihg.multibrand.sso.cm.SSOFilter.doFilter(SSOFilter.java:93)
[2012-10-16 18:04:46.487] at com.caucho.server.dispatch.FilterFilterChain.doFilter(FilterFilterChain.java:87)
[2012-10-16 18:04:46.487] at com.ihg.dec.apps.hi.servlet.filter.IE9CompatibilityFilter.doFilter(IE9CompatibilityFilter.java:91)
[2012-10-16 18:04:46.487] at com.caucho.server.dispatch.FilterFilterChain.doFilter(FilterFilterChain.java:87)
[2012-10-16 18:04:46.487] at com.ihg.dec.apps.hi.servlet.filter.CacheFilter.doFilter(CacheFilter.java:61)
[2012-10-16 18:04:46.487] at com.caucho.server.dispatch.FilterFilterChain.doFilter(FilterFilterChain.java:87)
[2012-10-16 18:04:46.487] at org.springframework.web.filter.RequestContextFilter.doFilterInternal(RequestContextFilter.java:83)
[2012-10-16 18:04:46.487] at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:76)
[2012-10-16 18:04:46.487] at com.caucho.server.dispatch.FilterFilterChain.doFilter(FilterFilterChain.java:87)
[2012-10-16 18:04:46.487] at com.ihg.dec.apps.hi.servlet.filter.CrawlServlet.doFilter(CrawlServlet.java:87)
[2012-10-16 18:04:46.487] at com.caucho.server.dispatch.FilterFilterChain.doFilter(FilterFilterChain.java:87)
[2012-10-16 18:04:46.487] at com.ihg.server.dao.endeavor.EndeavorContextFilter.__AW_doFilter(EndeavorContextFilter.java:140)
[2012-10-16 18:04:46.487] at com.ihg.server.dao.endeavor.EndeavorContextFilter.doFilter(EndeavorContextFilter.java)
[2012-10-16 18:04:46.487] at com.caucho.server.dispatch.FilterFilterChain.doFilter(FilterFilterChain.java:87)
[2012-10-16 18:04:46.487] at com.caucho.server.cache.CacheFilterChain.__AW_doFilter(CacheFilterChain.java:162)
[2012-10-16 18:04:46.487] at com.caucho.server.cache.CacheFilterChain.doFilter(CacheFilterChain.java)
[2012-10-16 18:04:46.487] at com.caucho.server.webapp.WebAppFilterChain.__AW_doFilter(WebAppFilterChain.java:187)
[2012-10-16 18:04:46.487] at com.caucho.server.webapp.WebAppFilterChain.doFilter(WebAppFilterChain.java)
[2012-10-16 18:04:46.487] at com.caucho.server.dispatch.ServletInvocation.service(ServletInvocation.java:265)
[2012-10-16 18:04:46.487] at com.caucho.server.hmux.HmuxRequest.__AW_handleRequest(HmuxRequest.java:436)
[2012-10-16 18:04:46.487] at com.caucho.server.hmux.HmuxRequest.handleRequest(HmuxRequest.java)
[2012-10-16 18:04:46.487] at com.caucho.server.port.TcpConnection.__AW_run(TcpConnection.java:682)
[2012-10-16 18:04:46.487] at com.caucho.server.port.TcpConnection.run(TcpConnection.java)
[2012-10-16 18:04:46.487] at com.caucho.util.ThreadPool$Item.runTasks(ThreadPool.java:743)
[2012-10-16 18:04:46.487] at com.caucho.util.ThreadPool$Item.run(ThreadPool.java:662)
[2012-10-16 18:04:46.487] at java.lang.Thread.run(Thread.java:619)
[2012-10-16 18:04:46.487]

Steps To Reproduce

Additional Information

Please see email " Re: Cluster Store file size" to totalcare for more log data.

Attached Files

Relationships

Notes
(0006074) ferg 10-25-12 12:27	This is fixed in 4.0.x. The fix cannot be patched into the 3.1.x tree.

Issue History
Date Modified	Username	Field	Change
10-17-12 15:26	alex	New Issue
10-25-12 12:27	ferg	Note Added: 0006074
10-25-12 12:27	ferg	Assigned To	=> ferg
10-25-12 12:27	ferg	Status	new => closed
10-25-12 12:27	ferg	Resolution	open => fixed
10-25-12 12:27	ferg	Fixed in Version	=> 4.0.30

Mantis 1.0.0rc3[^]

29 total queries executed.
26 unique queries executed.