Uploaded image for project: 'Dev - Nexus Repo'
  1. Dev - Nexus Repo
  2. NEXUS-6588

session.touch() method invocation has failed. Unable to updatethe corresponding session's last access time based on the incoming request

    XMLWordPrintable

    Details

    • Type: Bug
    • Status: Closed
    • Priority: Minor
    • Resolution: Fixed
    • Affects Version/s: 2.8.1
    • Fix Version/s: 2.8.1, 3.0.0-m1
    • Component/s: UI
    • Labels:
      None
    • Environment:
      OSX, JDK 1.8.0
    • Sprint:
      Sprint 17

      Description

      Somehow my session cache is corrupted?

      Not sure how to reproduce this reliably yet.

      2014-05-15 16:38:59,281-0300 DEBUG [qtp102320898-102]  com.sonatype.nexus.licensing.ProfessionalApplicationStatusSource - Renewing system status
      2014-05-15 16:38:59,282-0300 DEBUG [qtp102320898-102]  org.apache.shiro.web.servlet.SimpleCookie - Found 'JSESSIONID' cookie value [d106a700-f8c5-4211-858d-d18aeb1aee24]
      2014-05-15 16:38:59,282-0300 DEBUG [qtp102320898-102]  net.sf.ehcache.store.disk.Segment - put added 0 on heap
      2014-05-15 16:38:59,282-0300 DEBUG [shiro-activeSessionCache.data] *SYSTEM net.sf.ehcache.store.disk.Segment - fault removed 0 from heap
      2014-05-15 16:38:59,282-0300 DEBUG [shiro-activeSessionCache.data] *SYSTEM net.sf.ehcache.store.disk.Segment - fault added 0 on disk
      2014-05-15 16:38:59,284-0300 ERROR [qtp102320898-102]  org.apache.shiro.web.servlet.AbstractShiroFilter - session.touch() method invocation has failed.  Unable to updatethe corresponding session's last access time based on the incoming request.
      org.apache.shiro.cache.CacheException: net.sf.ehcache.CacheException: java.io.EOFException
      	at org.apache.shiro.cache.ehcache.EhCache.put(EhCache.java:105) ~[shiro-ehcache-1.2.2.jar:1.2.2]
      	at org.apache.shiro.session.mgt.eis.CachingSessionDAO.cache(CachingSessionDAO.java:247) ~[shiro-core-1.2.2.jar:1.2.2]
      	at org.apache.shiro.session.mgt.eis.CachingSessionDAO.cache(CachingSessionDAO.java:235) ~[shiro-core-1.2.2.jar:1.2.2]
      	at org.apache.shiro.session.mgt.eis.CachingSessionDAO.update(CachingSessionDAO.java:280) ~[shiro-core-1.2.2.jar:1.2.2]
      	at org.apache.shiro.session.mgt.DefaultSessionManager.onChange(DefaultSessionManager.java:212) ~[shiro-core-1.2.2.jar:1.2.2]
      	at org.apache.shiro.session.mgt.AbstractNativeSessionManager.touch(AbstractNativeSessionManager.java:193) ~[shiro-core-1.2.2.jar:1.2.2]
      	at org.apache.shiro.session.mgt.DelegatingSession.touch(DelegatingSession.java:120) ~[shiro-core-1.2.2.jar:1.2.2]
      	at org.apache.shiro.session.ProxiedSession.touch(ProxiedSession.java:100) ~[shiro-core-1.2.2.jar:1.2.2]
      	at org.apache.shiro.web.servlet.AbstractShiroFilter.updateSessionLastAccessTime(AbstractShiroFilter.java:315) [shiro-web-1.2.2.jar:1.2.2]
      	at org.apache.shiro.web.servlet.AbstractShiroFilter$1.call(AbstractShiroFilter.java:364) [shiro-web-1.2.2.jar:1.2.2]
      	at org.apache.shiro.subject.support.SubjectCallable.doCall(SubjectCallable.java:90) [shiro-core-1.2.2.jar:1.2.2]
      	at org.apache.shiro.subject.support.SubjectCallable.call(SubjectCallable.java:83) [shiro-core-1.2.2.jar:1.2.2]
      	at org.apache.shiro.subject.support.DelegatingSubject.execute(DelegatingSubject.java:383) [shiro-core-1.2.2.jar:1.2.2]
      	at org.apache.shiro.web.servlet.AbstractShiroFilter.doFilterInternal(AbstractShiroFilter.java:362) [shiro-web-1.2.2.jar:1.2.2]
      	at org.apache.shiro.web.servlet.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:125) [shiro-web-1.2.2.jar:1.2.2]
      	at com.google.inject.servlet.FilterChainInvocation.doFilter(FilterChainInvocation.java:82) [guice-servlet-3.1.10.jar:3.1.10]
      	at com.google.inject.servlet.ManagedFilterPipeline.dispatch(ManagedFilterPipeline.java:120) [guice-servlet-3.1.10.jar:3.1.10]
      	at org.sonatype.nexus.web.internal.NexusGuiceFilter$MultiFilterChain.doFilter(NexusGuiceFilter.java:82) [nexus-core-2.8.1-SNAPSHOT.jar:2.8.1-SNAPSHOT]
      	at com.google.inject.servlet.FilterChainInvocation.doFilter(FilterChainInvocation.java:89) [guice-servlet-3.1.10.jar:3.1.10]
      	at com.sonatype.nexus.licensing.internal.LicensingRedirectFilter.doFilter(LicensingRedirectFilter.java:135) [nexus-licensing-plugin-2.8.1-SNAPSHOT/:na]
      	at com.google.inject.servlet.FilterChainInvocation.doFilter(FilterChainInvocation.java:82) [guice-servlet-3.1.10.jar:3.1.10]
      	at com.google.inject.servlet.ManagedFilterPipeline.dispatch(ManagedFilterPipeline.java:120) [guice-servlet-3.1.10.jar:3.1.10]
      	at org.sonatype.nexus.web.internal.NexusGuiceFilter$MultiFilterChain.doFilter(NexusGuiceFilter.java:82) [nexus-core-2.8.1-SNAPSHOT.jar:2.8.1-SNAPSHOT]
      	at com.google.inject.servlet.FilterChainInvocation.doFilter(FilterChainInvocation.java:89) [guice-servlet-3.1.10.jar:3.1.10]
      	at com.yammer.metrics.web.WebappMetricsFilter.doFilter(WebappMetricsFilter.java:76) [metrics-web-2.2.0.jar:na]
      	at com.google.inject.servlet.FilterChainInvocation.doFilter(FilterChainInvocation.java:82) [guice-servlet-3.1.10.jar:3.1.10]
      	at org.sonatype.nexus.web.internal.CommonHeadersFilter.doFilter(CommonHeadersFilter.java:67) [nexus-core-2.8.1-SNAPSHOT.jar:2.8.1-SNAPSHOT]
      	at com.google.inject.servlet.FilterChainInvocation.doFilter(FilterChainInvocation.java:82) [guice-servlet-3.1.10.jar:3.1.10]
      	at org.sonatype.nexus.web.internal.ErrorPageFilter.doFilter(ErrorPageFilter.java:71) [nexus-core-2.8.1-SNAPSHOT.jar:2.8.1-SNAPSHOT]
      	at com.google.inject.servlet.FilterChainInvocation.doFilter(FilterChainInvocation.java:82) [guice-servlet-3.1.10.jar:3.1.10]
      	at org.sonatype.nexus.web.internal.BaseUrlHolderFilter.doFilter(BaseUrlHolderFilter.java:70) [nexus-core-2.8.1-SNAPSHOT.jar:2.8.1-SNAPSHOT]
      	at com.google.inject.servlet.FilterChainInvocation.doFilter(FilterChainInvocation.java:82) [guice-servlet-3.1.10.jar:3.1.10]
      	at com.google.inject.servlet.ManagedFilterPipeline.dispatch(ManagedFilterPipeline.java:120) [guice-servlet-3.1.10.jar:3.1.10]
      	at org.sonatype.nexus.web.internal.NexusGuiceFilter$MultiFilterChain.doFilter(NexusGuiceFilter.java:82) [nexus-core-2.8.1-SNAPSHOT.jar:2.8.1-SNAPSHOT]
      	at org.sonatype.nexus.web.internal.NexusGuiceFilter$MultiFilterPipeline.dispatch(NexusGuiceFilter.java:56) [nexus-core-2.8.1-SNAPSHOT.jar:2.8.1-SNAPSHOT]
      	at com.google.inject.servlet.GuiceFilter$1.call(GuiceFilter.java:132) [guice-servlet-3.1.10.jar:3.1.10]
      	at com.google.inject.servlet.GuiceFilter$1.call(GuiceFilter.java:129) [guice-servlet-3.1.10.jar:3.1.10]
      	at com.google.inject.servlet.GuiceFilter$Context.call(GuiceFilter.java:206) [guice-servlet-3.1.10.jar:3.1.10]
      	at com.google.inject.servlet.GuiceFilter.doFilter(GuiceFilter.java:129) [guice-servlet-3.1.10.jar:3.1.10]
      	at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419) [jetty-servlet-8.1.11.v20130520.jar:8.1.11.v20130520]
      	at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:455) [jetty-servlet-8.1.11.v20130520.jar:8.1.11.v20130520]
      	at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:137) [jetty-server-8.1.11.v20130520.jar:8.1.11.v20130520]
      	at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:557) [jetty-security-8.1.11.v20130520.jar:8.1.11.v20130520]
      	at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:231) [jetty-server-8.1.11.v20130520.jar:8.1.11.v20130520]
      	at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1075) [jetty-server-8.1.11.v20130520.jar:8.1.11.v20130520]
      	at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:384) [jetty-servlet-8.1.11.v20130520.jar:8.1.11.v20130520]
      	at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:193) [jetty-server-8.1.11.v20130520.jar:8.1.11.v20130520]
      	at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1009) [jetty-server-8.1.11.v20130520.jar:8.1.11.v20130520]
      	at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:135) [jetty-server-8.1.11.v20130520.jar:8.1.11.v20130520]
      	at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:116) [jetty-server-8.1.11.v20130520.jar:8.1.11.v20130520]
      	at com.yammer.metrics.jetty.InstrumentedHandler.handle(InstrumentedHandler.java:200) [metrics-jetty-2.2.0.jar:na]
      	at org.eclipse.jetty.server.handler.HandlerCollection.handle(HandlerCollection.java:154) [jetty-server-8.1.11.v20130520.jar:8.1.11.v20130520]
      	at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:116) [jetty-server-8.1.11.v20130520.jar:8.1.11.v20130520]
      	at org.eclipse.jetty.server.Server.handle(Server.java:370) [jetty-server-8.1.11.v20130520.jar:8.1.11.v20130520]
      	at org.eclipse.jetty.server.AbstractHttpConnection.handleRequest(AbstractHttpConnection.java:489) [jetty-server-8.1.11.v20130520.jar:8.1.11.v20130520]
      	at org.eclipse.jetty.server.AbstractHttpConnection.headerComplete(AbstractHttpConnection.java:949) [jetty-server-8.1.11.v20130520.jar:8.1.11.v20130520]
      	at org.eclipse.jetty.server.AbstractHttpConnection$RequestHandler.headerComplete(AbstractHttpConnection.java:1011) [jetty-server-8.1.11.v20130520.jar:8.1.11.v20130520]
      	at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:644) [jetty-http-8.1.11.v20130520.jar:8.1.11.v20130520]
      	at org.eclipse.jetty.http.HttpParser.parseAvailable(HttpParser.java:235) [jetty-http-8.1.11.v20130520.jar:8.1.11.v20130520]
      	at org.eclipse.jetty.server.AsyncHttpConnection.handle(AsyncHttpConnection.java:82) [jetty-server-8.1.11.v20130520.jar:8.1.11.v20130520]
      	at org.eclipse.jetty.io.nio.SelectChannelEndPoint.handle(SelectChannelEndPoint.java:668) [jetty-io-8.1.11.v20130520.jar:8.1.11.v20130520]
      	at org.eclipse.jetty.io.nio.SelectChannelEndPoint$1.run(SelectChannelEndPoint.java:52) [jetty-io-8.1.11.v20130520.jar:8.1.11.v20130520]
      	at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:608) [jetty-util-8.1.11.v20130520.jar:8.1.11.v20130520]
      	at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:543) [jetty-util-8.1.11.v20130520.jar:8.1.11.v20130520]
      	at java.lang.Thread.run(Thread.java:744) [na:1.8.0]
      Caused by: net.sf.ehcache.CacheException: java.io.EOFException
      	at net.sf.ehcache.store.disk.DiskStorageFactory.retrieve(DiskStorageFactory.java:939) ~[ehcache-core-2.5.1.jar:na]
      	at net.sf.ehcache.store.disk.Segment.decode(Segment.java:167) ~[ehcache-core-2.5.1.jar:na]
      	at net.sf.ehcache.store.disk.Segment.put(Segment.java:444) ~[ehcache-core-2.5.1.jar:na]
      	at net.sf.ehcache.store.disk.DiskStore.put(DiskStore.java:477) ~[ehcache-core-2.5.1.jar:na]
      	at net.sf.ehcache.store.FrontEndCacheTier.put(FrontEndCacheTier.java:257) ~[ehcache-core-2.5.1.jar:na]
      	at net.sf.ehcache.Cache.putInternal(Cache.java:1489) ~[ehcache-core-2.5.1.jar:na]
      	at net.sf.ehcache.Cache.put(Cache.java:1417) ~[ehcache-core-2.5.1.jar:na]
      	at net.sf.ehcache.Cache.put(Cache.java:1382) ~[ehcache-core-2.5.1.jar:na]
      	at org.apache.shiro.cache.ehcache.EhCache.put(EhCache.java:102) ~[shiro-ehcache-1.2.2.jar:1.2.2]
      	... 64 common frames omitted
      Caused by: java.io.EOFException: null
      	at java.io.RandomAccessFile.readFully(RandomAccessFile.java:410) ~[na:1.8.0]
      	at java.io.RandomAccessFile.readFully(RandomAccessFile.java:388) ~[na:1.8.0]
      	at net.sf.ehcache.store.disk.DiskStorageFactory.read(DiskStorageFactory.java:372) ~[ehcache-core-2.5.1.jar:na]
      	at net.sf.ehcache.store.disk.DiskStorageFactory.retrieve(DiskStorageFactory.java:937) ~[ehcache-core-2.5.1.jar:na]
      	... 72 common frames omitted
      2014-05-15 16:38:59,285-0300 DEBUG [qtp102320898-102] admin org.sonatype.nexus.restlet1x.internal.RestletServlet - Processing: GET /nexus/service/local/status?perms=1&_dc=1400182739276 (http://spunge.local:9081/nexus/service/local/status)
      

        Attachments

          Issue Links

            Activity

              People

              Assignee:
              plynch Peter Lynch
              Reporter:
              plynch Peter Lynch
              Last Updated By:
              Peter Lynch
              Votes:
              0 Vote for this issue
              Watchers:
              3 Start watching this issue

                Dates

                Created:
                Updated:
                Resolved:
                Date of First Response:

                  tigCommentSecurity.panel-title