Uploaded image for project: 'Alfresco One Platform'
  1. Alfresco One Platform
  2. ACE-2836 5.0 R39 Pt2 Enterprise Regression Test
  3. ACE-2961

BM-0012: publicapi.addNodeTag, publicapi.getTags, publicapi.query actions are completely failed with RuntimeException: Session ID not recorded.

    Details

    • Type: Feature Bug
    • Status: Closed (View Workflow)
    • Priority: Critical
    • Resolution: Not a bug
    • Affects Version/s: None
    • Fix Version/s: None
    • Component/s: Benchmarking, REST API
    • Labels:
      None
    • Environment:
      BM Lab. Clusters are: 1.4, Servers: 3, Alfresco v5.0.0 (build-123)
    • Test In:
      Enterprise
    • Sprint:
      Rel 5.0 R39 Pt2 (WAT1 & PLAT1)

      Description

      Some actions failed with next errors according to results in MongoDB:

      publicapi.getTags

      /* 0 */
      {
        "_id" : ObjectId("5429783df3d5f8085ca5c5c0"),
        "_class" : "org.alfresco.bm.event.EventRecord",
        "serverId" : "356345-cld1_0000000357",
        "success" : false,
        "startTime" : NumberLong("1412003590984"),
        "startDelay" : NumberLong(0),
        "time" : NumberLong(310015),
        "event" : {
          "_id" : ObjectId("54297706f3d5f8085ca5c55c"),
          "name" : "publicapi.getTags",
          "sessionId" : "0bb773ca-2194-472c-bcee-58f22f7d2953",
          "scheduledTime" : NumberLong("1412003590984"),
          "lockOwner" : "356345-cld1_0000000357",
          "lockTime" : NumberLong("1412003590984"),
          "dataKey" : "6c635ea2-180d-48a6-8e03-ec75bf0d7380",
          "dataOwner" : "356345-cld1_0000000357",
          "lockId" : "869cddc6-143a-4c91-ac9e-40b96e004717"
        },
        "data" : "[2014-09-29T16:13:10.984+01:00] Event processing exception; no further events will be published. \r\njava.lang.RuntimeException: Session ID not recorded: 0bb773ca-2194-472c-bcee-58f22f7d2953\r\n\tat org.alfresco.bm.session.AbstractSessionService.getSessionDataNotNull(AbstractSessionService.java:72)\r\n\tat org.alfresco.bm.session.AbstractSessionService.getSessionData(AbstractSessionService.java:96)\r\n\tat org.alfresco.bm.event.selector.AbstractEventSelectorProcessor.getSessionData(AbstractEventSelectorProcessor.java:101)\r\n\tat org.alfresco.bm.event.selector.AbstractEventSelectorProcessor.processEvent(AbstractEventSelectorProcessor.java:134)\r\n\tat org.alfresco.bm.event.AbstractEventProcessor.processEvent(AbstractEventProcessor.java:266)\r\n\tat org.alfresco.bm.event.EventWork.run(EventWork.java:85)\r\n\tat java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source)\r\n\tat java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source)\r\n\tat java.lang.Thread.run(Unknown Source)\r\n",
        "chart" : true
      }
      

      publicapi.query

      /* 3 */
      {
        "_id" : ObjectId("542978423d03ef4cde52c3c4"),
        "_class" : "org.alfresco.bm.event.EventRecord",
        "serverId" : "356348-cld2_0000000356",
        "success" : false,
        "startTime" : NumberLong("1412003596025"),
        "startDelay" : NumberLong(188),
        "time" : NumberLong(310259),
        "event" : {
          "_id" : ObjectId("5429770b3d03ef4cde52c3b0"),
          "name" : "publicapi.query",
          "sessionId" : "9836c18f-5008-4137-bffc-52e8c66bf368",
          "scheduledTime" : NumberLong("1412003595837"),
          "lockOwner" : "356348-cld2_0000000356",
          "lockTime" : NumberLong("1412003596025"),
          "dataKey" : "4311ac3d-782e-46f2-9d88-ef70e4b678da",
          "dataOwner" : "356348-cld2_0000000356",
          "lockId" : "960286d7-8d0c-43ff-b3a7-9ba2d1439212"
        },
        "data" : "[2014-09-29T16:13:16.025+01:00] Event processing exception; no further events will be published. \r\njava.lang.RuntimeException: Session ID not recorded: 9836c18f-5008-4137-bffc-52e8c66bf368\r\n\tat org.alfresco.bm.session.AbstractSessionService.getSessionDataNotNull(AbstractSessionService.java:72)\r\n\tat org.alfresco.bm.session.AbstractSessionService.getSessionData(AbstractSessionService.java:96)\r\n\tat org.alfresco.bm.event.selector.AbstractEventSelectorProcessor.getSessionData(AbstractEventSelectorProcessor.java:101)\r\n\tat org.alfresco.bm.event.selector.AbstractEventSelectorProcessor.processEvent(AbstractEventSelectorProcessor.java:134)\r\n\tat org.alfresco.bm.event.AbstractEventProcessor.processEvent(AbstractEventProcessor.java:266)\r\n\tat org.alfresco.bm.event.EventWork.run(EventWork.java:85)\r\n\tat java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source)\r\n\tat java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source)\r\n\tat java.lang.Thread.run(Unknown Source)\r\n",
        "chart" : true
      }
      

      publicapi.addNodeTag

      /* 321 */
      {
        "_id" : ObjectId("5429ab293d03ef4cde52dc42"),
        "_class" : "org.alfresco.bm.event.EventRecord",
        "serverId" : "356348-cld2_0000000356",
        "success" : false,
        "startTime" : NumberLong("1412016627122"),
        "startDelay" : NumberLong(20202),
        "time" : NumberLong(310023),
        "event" : {
          "_id" : ObjectId("5429a9de3d03ef4cde52dbf5"),
          "name" : "publicapi.addNodeTag",
          "sessionId" : "544c116c-1f6e-4b1d-b490-952c91dcda26",
          "scheduledTime" : NumberLong("1412016606920"),
          "lockOwner" : "356348-cld2_0000000356",
          "lockTime" : NumberLong("1412016627122"),
          "dataKey" : "e630ad12-e6de-47b8-9c47-d84cc29fe0c8",
          "dataOwner" : "356348-cld2_0000000356",
          "lockId" : "f752328f-b3ec-4f7d-aae5-b1d0fd6f3a8a"
        },
        "data" : "Input CreateNodeTagRequest [tag=5499fba2-6c57-47e3-b942-f2b1a8d4fb44, node=DocumentNode [content=null, pwcId=null, nodeId=1bf8e47c-3a81-43a4-9b84-ee811f65b3e3, properties=null, parents=[], allowableActions=null, tags=null, comments=null, ratings=null]] generated an unexpected exception org.springframework.social.alfresco.connect.exception.AlfrescoException: 502\n response status \nProxy Error\n response body \n<!DOCTYPE HTML PUBLIC \"-//IETF//DTD HTML 2.0//EN\">\n<html><head>\n<title>502 Proxy Error</title>\n</head><body>\n<h1>Proxy Error</h1>\n<p>The proxy server received an invalid\r\nresponse from an upstream server.<br />\r\nThe proxy server could not handle the request <em><a href=\"/alfresco/api/-default-/public/alfresco/versions/1/nodes/1bf8e47c-3a81-43a4-9b84-ee811f65b3e3/tags/\">POST&nbsp;/alfresco/api/-default-/public/alfresco/versions/1/nodes/1bf8e47c-3a81-43a4-9b84-ee811f65b3e3/tags/</a></em>.<p>\nReason: <strong>Error reading from remote server</strong></p></p>\n<hr>\n<address>Apache/2.2.3 (Red Hat) Server at lb1 Port 80</address>\n</body></html>\n : null",
        "chart" : true
      }
      

      Next error was detected in the solr log during to this test:

      2014-09-29 16:22:29,392  ERROR [solr.servlet.SolrDispatchFilter] [catalina-alfresco120] null:ClientAbortException:  java.io.IOException
      	at org.apache.catalina.connector.OutputBuffer.doFlush(OutputBuffer.java:371)
      	at org.apache.catalina.connector.OutputBuffer.flush(OutputBuffer.java:333)
      	at org.apache.catalina.connector.CoyoteOutputStream.flush(CoyoteOutputStream.java:101)
      	at sun.nio.cs.StreamEncoder.implFlush(Unknown Source)
      	at sun.nio.cs.StreamEncoder.flush(Unknown Source)
      	at java.io.OutputStreamWriter.flush(Unknown Source)
      	at org.apache.solr.util.FastWriter.flush(FastWriter.java:137)
      	at org.apache.solr.servlet.SolrDispatchFilter.writeResponse(SolrDispatchFilter.java:766)
      	at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:426)
      	at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:207)
      	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:241)
      	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208)
      	at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:220)
      	at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:122)
      	at org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:501)
      	at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:170)
      	at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:98)
      	at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:116)
      	at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:408)
      	at org.apache.coyote.http11.AbstractHttp11Processor.process(AbstractHttp11Processor.java:1040)
      	at org.apache.coyote.AbstractProtocol$AbstractConnectionHandler.process(AbstractProtocol.java:607)
      	at org.apache.tomcat.util.net.AprEndpoint$SocketWithOptionsProcessor.run(AprEndpoint.java:2378)
      	at java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source)
      	at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source)
      	at java.lang.Thread.run(Unknown Source)
      Caused by: java.io.IOException
      	at org.apache.coyote.http11.InternalAprOutputBuffer.flushBuffer(InternalAprOutputBuffer.java:205)
      	at org.apache.coyote.http11.InternalAprOutputBuffer.flush(InternalAprOutputBuffer.java:109)
      	at org.apache.coyote.http11.AbstractHttp11Processor.action(AbstractHttp11Processor.java:799)
      	at org.apache.coyote.Response.action(Response.java:174)
      	at org.apache.catalina.connector.OutputBuffer.doFlush(OutputBuffer.java:366)
      	... 24 more
      
      Sep 29, 2014 4:38:10 PM org.apache.catalina.core.StandardWrapperValve invoke
      SEVERE: Servlet.service() for servlet [default] in context with path [/solr4] threw exception
      java.lang.IllegalStateException: Cannot call sendError() after the response has been committed
      	at org.apache.catalina.connector.ResponseFacade.sendError(ResponseFacade.java:450)
      	at org.apache.solr.servlet.SolrDispatchFilter.sendError(SolrDispatchFilter.java:821)
      	at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:434)
      	at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:207)
      	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:241)
      	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208)
      	at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:220)
      	at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:122)
      	at org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:501)
      	at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:170)
      	at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:98)
      	at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:116)
      	at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:408)
      	at org.apache.coyote.http11.AbstractHttp11Processor.process(AbstractHttp11Processor.java:1040)
      	at org.apache.coyote.AbstractProtocol$AbstractConnectionHandler.process(AbstractProtocol.java:607)
      	at org.apache.tomcat.util.net.AprEndpoint$SocketWithOptionsProcessor.run(AprEndpoint.java:2378)
      	at java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source)
      	at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source)
      	at java.lang.Thread.run(Unknown Source)
      

      "IllegalStateException: Cannot call sendError() after the response has been committed" is similar to MNT-10679 for 4.2.N version or
      I could be wrong but it looks like after test didn't find Session ID it drops the connection with the server.

      Please, see logs by path below and run results in attachment:

      ms1: /data/replicate/bm0012/logs/V500b123_solr4
      

        Attachments

          Issue Links

            Structure

              Activity

                People

                • Assignee:
                  closedissues Closed Issues
                  Reporter:
                  alfrescoqa Alfresco QA Team
                • Votes:
                  0 Vote for this issue
                  Watchers:
                  4 Start watching this issue

                  Dates

                  • Created:
                    Updated:
                    Resolved:

                    Structure Helper Panel