Uploaded image for project: 'Nuxeo Platform'
  1. Nuxeo Platform
  2. NXP-25369

Fix hotreload ftest that generates error log

    XMLWordPrintable

    Details

    • Type: Bug
    • Status: Resolved
    • Priority: Minor
    • Resolution: Fixed
    • Affects Version/s: 10.2-SNAPSHOT
    • Fix Version/s: 10.3
    • Component/s: Tests

      Description

      During ITDevHotReloadTest hot reload can produce error in logs because we don't wait for audit flush before doing the hot reload.

      2018-07-11 07:56:19,850 ERROR [AuditLogWriterPool-00,in:22,inCheckpoint:21,out:0,lastRead:1531295778611,lastTimer:1531295778460,wm:200710000200777729,loop:93,record] [org.nuxeo.lib.stream.computation.log.ComputationRunner] AuditLogWriter: Exception in processLoop: thread waiting for the response was interrupted
      java.lang.RuntimeException: thread waiting for the response was interrupted
      	at org.elasticsearch.client.RestClient$SyncResponseListener.get(RestClient.java:687)
      	at org.elasticsearch.client.RestClient.performRequest(RestClient.java:235)
      	at org.elasticsearch.client.RestClient.performRequest(RestClient.java:198)
      	at org.elasticsearch.client.RestHighLevelClient.performRequest(RestHighLevelClient.java:522)
      	at org.elasticsearch.client.RestHighLevelClient.performRequestAndParseEntity(RestHighLevelClient.java:508)
      	at org.elasticsearch.client.RestHighLevelClient.index(RestHighLevelClient.java:348)
      	at org.nuxeo.elasticsearch.client.ESRestClient.index(ESRestClient.java:379)
      	at org.nuxeo.elasticsearch.seqgen.ESUIDSequencer.getNextLong(ESUIDSequencer.java:93)
      	at org.nuxeo.elasticsearch.audit.ESAuditBackend.addLogEntries(ESAuditBackend.java:483)
      	at org.nuxeo.ecm.platform.audit.impl.StreamAuditWriter$AuditLogWriterComputation.writeEntriesToAudit(StreamAuditWriter.java:127)
      	at org.nuxeo.ecm.platform.audit.impl.StreamAuditWriter$AuditLogWriterComputation.processTimer(StreamAuditWriter.java:96)
      	at org.nuxeo.lib.stream.computation.log.ComputationRunner.lambda$processTimer$2(ComputationRunner.java:246)
      	at java.util.LinkedHashMap.forEach(LinkedHashMap.java:676)
      	at org.nuxeo.lib.stream.computation.log.ComputationRunner.processTimer(ComputationRunner.java:244)
      	at org.nuxeo.lib.stream.computation.log.ComputationRunner.processLoop(ComputationRunner.java:193)
      	at org.nuxeo.lib.stream.computation.log.ComputationRunner.run(ComputationRunner.java:152)
      	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
      	at java.util.concurrent.FutureTask.run(FutureTask.java:266)
      	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
      	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
      	at java.lang.Thread.run(Thread.java:745)
      Caused by: java.lang.InterruptedException
      	at java.util.concurrent.locks.AbstractQueuedSynchronizer.doAcquireSharedNanos(AbstractQueuedSynchronizer.java:1039)
      	at java.util.concurrent.locks.AbstractQueuedSynchronizer.tryAcquireSharedNanos(AbstractQueuedSynchronizer.java:1328)
      	at java.util.concurrent.CountDownLatch.await(CountDownLatch.java:277)
      	at org.elasticsearch.client.RestClient$SyncResponseListener.get(RestClient.java:683)
      	... 20 more
      

      This audit error is normal when it cannot process the current records within one second.
      The test should wait for audit to be purged before doing the hot reload.

      Seen: https://qa.nuxeo.org/jenkins/job/TestAndPush/job/ondemand-testandpush-bdelbosc3/113/

        Attachments

          Issue Links

            Activity

              People

              • Votes:
                0 Vote for this issue
                Watchers:
                3 Start watching this issue

                Dates

                • Created:
                  Updated:
                  Resolved:

                  Time Tracking

                  Estimated:
                  Original Estimate - 0 minutes
                  0m
                  Remaining:
                  Remaining Estimate - 0 minutes
                  0m
                  Logged:
                  Time Spent - 6 hours
                  6h