0005247: session database starts rapidly grow in size after exception

Viewing Issue Simple Details [ Jump to Notes ]

[ View Advanced ] [ 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

Status

closed

Product Version

3.1.12

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]

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.