Uploaded image for project: 'mod-pubsub'
  1. mod-pubsub
  2. MODPUBSUB-147

Plenty of pub-sub errors on bug fest for latest hours

    XMLWordPrintable

Details

    • Bug
    • Status: Closed (View Workflow)
    • P1
    • Resolution: Won't Do
    • None
    • None
    • Honeysuckle Bugfest

    • Folijet Sprint 108
    • 3
    • Folijet
    • R1 2021
    • Lehigh, MO State, University of Chicago

    Description

      Following is observed in pub-sub log file:

      2020-12-08T18:49:40.680Z 18:49:40.680 [vert.x-eventloop-thread-0] INFO  LogUtil              [2837209150eqId] 127.0.0.1:41080 POST /pubsub/publish  HTTP_1_1 400 79 0 tid=fs09000000 Bad Request 
      2020-12-08T18:50:01.979Z 18:50:01.978 [vert.x-eventloop-thread-0] ERROR PublisherServiceImpl [2837230448eqId] mod-circulation-19.2.8 is not registered as PUBLISHER for event type LOG_RECORD
      2020-12-08T18:50:01.979Z 18:50:01.978 [vert.x-eventloop-thread-0] ERROR ExceptionHelper      [2837230448eqId] mod-circulation-19.2.8 is not registered as PUBLISHER for event type LOG_RECORD
      

      Mod-circulation logs:

      2020-12-08T18:42:22.704Z 18:42:22 ERROR SubPublishingService Failed to publish event. ID: 1fad30dd-aff4-4602-9854-2a29b21b3c75, type: LOG_RECORD, payload: {"logEventType":"LOAN","payload":{"userId":"8eea2f6a-cc48-4710-8e31-fd23f1887c73","itemBarcode":"35960422","itemId":"e03626df-9efa-4710-a686-d03225426b79","instanceId":"9627f91e-e3ea-4fd2-bd08-a5323fa837fa","holdingsRecordId":"f2b4d95c-d59b-418f-83c1-f5156901755e","action":"Age to lost","date":"2020-12-08T18:42:21.542Z","servicePointId":"130d8bff-bdbd-4dc5-a4ac-6d970f4918ff","description":"Due date: 2020-12-08T18:42:18.620Z","loanId":"696e0a16-8254-4e77-8152-040452c593db"}}
      2020-12-08T18:42:22.704Z java.util.concurrent.CompletionException: org.folio.util.pubsub.exceptions.EventSendingException: Error during publishing Event Message in PubSub. Status code: 400 . Status message: Bad Request 
      2020-12-08T18:42:22.704Z 	at java.util.concurrent.CompletableFuture.encodeThrowable(CompletableFuture.java:331) ~[?:?]
      2020-12-08T18:42:22.704Z 	at java.util.concurrent.CompletableFuture.completeThrowable(CompletableFuture.java:346) ~[?:?]
      2020-12-08T18:42:22.704Z 	at java.util.concurrent.CompletableFuture.uniWhenComplete(CompletableFuture.java:870) ~[?:?]
      2020-12-08T18:42:22.704Z 	at java.util.concurrent.CompletableFuture$UniWhenComplete.tryFire(CompletableFuture.java:837) ~[?:?]
      2020-12-08T18:42:22.704Z 	at java.util.concurrent.CompletableFuture.postComplete(CompletableFuture.java:506) ~[?:?]
      2020-12-08T18:42:22.704Z 	at java.util.concurrent.CompletableFuture.completeExceptionally(CompletableFuture.java:2088) ~[?:?]
      2020-12-08T18:42:22.704Z 	at org.folio.util.pubsub.PubSubClientUtils.lambda$sendEventMessage$0(PubSubClientUtils.java:65) ~[ms.jar:?]
      2020-12-08T18:42:22.704Z 	at io.vertx.core.http.impl.HttpClientRequestImpl.handleResponse(HttpClientRequestImpl.java:390) ~[ms.jar:?]
      2020-12-08T18:42:22.704Z 	at io.vertx.core.http.impl.HttpClientRequestBase.checkHandleResponse(HttpClientRequestBase.java:167) ~[ms.jar:?]
      2020-12-08T18:42:22.704Z 	at io.vertx.core.http.impl.HttpClientRequestBase.handleResponse(HttpClientRequestBase.java:148) ~[ms.jar:?]
      2020-12-08T18:42:22.704Z 	at io.vertx.core.http.impl.Http1xClientConnection.handleResponseBegin(Http1xClientConnection.java:623) ~[ms.jar:?]
      2020-12-08T18:42:22.704Z 	at io.vertx.core.http.impl.Http1xClientConnection.handleHttpMessage(Http1xClientConnection.java:593) ~[ms.jar:?]
      2020-12-08T18:42:22.704Z 	at io.vertx.core.http.impl.Http1xClientConnection.handleMessage(Http1xClientConnection.java:575) ~[ms.jar:?]
      2020-12-08T18:42:22.704Z 	at io.vertx.core.impl.ContextImpl.executeTask(ContextImpl.java:366) ~[ms.jar:?]
      2020-12-08T18:42:22.704Z 	at io.vertx.core.impl.WorkerContext.lambda$wrapTask$0(WorkerContext.java:35) ~[ms.jar:?]
      2020-12-08T18:42:22.704Z 	at io.vertx.core.impl.TaskQueue.run(TaskQueue.java:76) ~[ms.jar:?]
      2020-12-08T18:42:22.704Z 	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) [?:?]
      2020-12-08T18:42:22.704Z 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) [?:?]
      2020-12-08T18:42:22.704Z 	at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30) [ms.jar:?]
      2020-12-08T18:42:22.704Z 	at java.lang.Thread.run(Thread.java:834) [?:?]
      2020-12-08T18:42:22.704Z Caused by: org.folio.util.pubsub.exceptions.EventSendingException: Error during publishing Event Message in PubSub. Status code: 400 . Status message: Bad Request 
      2020-12-08T18:42:22.704Z 	at org.folio.util.pubsub.PubSubClientUtils.lambda$sendEventMessage$0(PubSubClientUtils.java:63) ~[ms.jar:?]
      2020-12-08T18:42:22.704Z 	... 13 more
      

      Interesting parties: marcjohnson, hollyolepm, cboerema, Charniak, FSE hosting team.

      TestRail: Results

        Attachments

          Issue Links

            Activity

              People

                OleksiiKuzminov Oleksii Kuzminov
                bohdan-suprun Bohdan Suprun
                Votes:
                0 Vote for this issue
                Watchers:
                7 Start watching this issue

                Dates

                  Created:
                  Updated:
                  Resolved:

                  TestRail: Runs

                    TestRail: Cases