Exception (errorCode='AXONIQ-5001') with Axon Server 4.5.10

nabil · January 21, 2022, 1:57pm

For a POC, i use Axon Server version 4.5.6. My application works fine with this version. When i have decided to upgrade Axon Server to the last version 4.5.10, i encouter problems with Axon (Regression). The Queries are not dispatched and we have exceptions which are logged.

21/01/2022 08:48:04 15436 INFO Starting SupportApplication using Java 1.8.0_66 on PC-CHERQAOUI with PID 15436 (F:\WebDocument\DEV\Backend\codebase\services\support\target\classes started by admin in F:\WebDocument\DEV\Backend\codebase)
21/01/2022 08:48:04 15436 DEBUG Running with Spring Boot v2.6.2, Spring v5.3.14
21/01/2022 08:48:04 15436 INFO The following profiles are active: dev
21/01/2022 08:48:27 15436 INFO Started SupportApplication in 28.544 seconds (JVM running for 31.715)
21/01/2022 08:48:27 15436 INFO @@@ WebDocument version : 01.000.00
21/01/2022 08:48:27 15436 INFO — Loading WebDocument Configuration…
21/01/2022 08:48:27 15436 ERROR +++ org.axonframework.queryhandling.QueryExecutionException: com.ncrm.webdocument.commons.queries.WebDocumentConfigQuery
at org.axonframework.axonserver.connector.ErrorCode.lambda$static$15(ErrorCode.java:109)
at org.axonframework.axonserver.connector.ErrorCode.convert(ErrorCode.java:182)
at org.axonframework.axonserver.connector.query.GrpcBackedResponseMessage.(GrpcBackedResponseMessage.java:64)
at org.axonframework.axonserver.connector.query.QuerySerializer.deserializeResponse(QuerySerializer.java:176)
at org.axonframework.axonserver.connector.query.AxonServerQueryBus$ResponseProcessingTask.run(AxonServerQueryBus.java:725)
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: AxonServerRemoteQueryHandlingException{message=An exception was thrown by the remote message handling component: com.ncrm.webdocument.commons.queries.WebDocumentConfigQuery, errorCode=‘AXONIQ-5001’, location=‘12880@PC-CHERQAOUI’}
at org.axonframework.axonserver.connector.ErrorCode.lambda$static$15(ErrorCode.java:107)
… 9 more

Bert_Laverman · January 21, 2022, 2:05pm

Hey Nabil, welcome to our forums.

The logs you provide appear to be from the app that sends the query. Do you see anything in the Axon Server logs or in the app that contains the QueryHandler? The 5002 error is “just” the error showing the handling of the query ran into a problem.

Cheers,
Bert Laverman

nabil · January 21, 2022, 2:27pm

Hi Bert,

In Axon logs, any exception. The query handler that’s placed in another service is not called.

Axon Server Logs

2022-01-21 14:27:40.627 INFO 16672 — [main] io.axoniq.axonserver.AxonServer : Starting AxonServer using Java 1.8.0_66 on PC-CHERQAOUI with PID 16672 (R:\WebDocument\Server\Lib\axonserver.jar started by PC-CHERQAOUI$ in R:\WebDocument\Server)
2022-01-21 14:27:40.633 INFO 16672 — [main] io.axoniq.axonserver.AxonServer : No active profile set, falling back to default profiles: default
2022-01-21 14:27:44.645 INFO 16672 — [main] o.s.b.w.embedded.tomcat.TomcatWebServer : Tomcat initialized with port(s): 27900 (https)
2022-01-21 14:27:44.801 INFO 16672 — [main] i.a.a.c.MessagingPlatformConfiguration : Configuring domain from hostname property: hostname=webdocumentserver, domain=ncr-maroc.com
2022-01-21 14:27:44.809 INFO 16672 — [main] A.i.a.a.c.MessagingPlatformConfiguration : Configuration initialized with SSL ENABLED and access control ENABLED.
2022-01-21 14:27:47.404 INFO 16672 — [main] io.axoniq.axonserver.AxonServer : Axon Server version 4.5.10
2022-01-21 14:27:48.096 INFO 16672 — [main] i.a.a.localstorage.LocalEventStore : default: initializing Workers [1082865087]
2022-01-21 14:27:48.108 INFO 16672 — [main] i.a.a.l.file.PrimaryEventStore : Opening file R:\WebDocument\eventstore\data\default\00000000000000000000.events
2022-01-21 14:27:48.115 INFO 16672 — [main] i.a.axonserver.config.FileSystemMonitor : Monitoring file store ‘default-event’ at path ‘R:\WebDocument\eventstore\data\default’
2022-01-21 14:27:48.128 INFO 16672 — [main] i.a.a.l.file.PrimaryEventStore : Opening file R:\WebDocument\eventstore\data\default\00000000000000000000.snapshots
2022-01-21 14:27:48.129 INFO 16672 — [main] i.a.axonserver.config.FileSystemMonitor : Monitoring file store ‘default-snapshot’ at path ‘R:\WebDocument\eventstore\data\default’
2022-01-21 14:27:48.133 INFO 16672 — [main] i.a.a.localstorage.LocalEventStore : Workers[1082865087] for context default has been initialized.
2022-01-21 14:27:48.353 INFO 16672 — [main] i.a.a.c.AxonServerStandardConfiguration : Creating SE EventSchedulerService
2022-01-21 14:27:50.773 INFO 16672 — [main] i.a.axonserver.plugin.OsgiController : System packages io.axoniq.axonserver.plugin;version=“4.5.0”,io.axoniq.axonserver.plugin.hook;uses:=“io.axoniq.axonserver.grpc.event,io.axoniq.axonserver.plugin”;version=“4.5.0”,io.axoniq.axonserver.plugin.interceptor;uses:=“io.axoniq.axonserver.grpc.command,io.axoniq.axonserver.grpc.event,io.axoniq.axonserver.grpc.query,io.axoniq.axonserver.plugin”;version=“4.5.0”,io.axoniq.axonserver.grpc;uses:=“com.google.protobuf”;version=“4.5.0”,io.axoniq.axonserver.grpc.command;uses:=“com.google.protobuf,io.axoniq.axonserver.grpc”;version=“4.5.0”,io.axoniq.axonserver.grpc.control;uses:=“com.google.protobuf,io.axoniq.axonserver.grpc”;version=“4.5.0”,io.axoniq.axonserver.grpc.event;uses:=“com.google.protobuf,io.axoniq.axonserver.grpc”;version=“4.5.0”,io.axoniq.axonserver.grpc.query;uses:=“com.google.protobuf,io.axoniq.axonserver.grpc”;version=“4.5.0”,com.google.protobuf;version=“3.12.0”,com.google.protobuf.compiler;uses:=“com.google.protobuf”;version=“4.5.0”,org.osgi.service.log;version=“1.3”;uses:=“org.osgi.framework”
2022-01-21 14:27:53.568 INFO 16672 — [main] io.axoniq.axonserver.grpc.Gateway : Axon Server Gateway started on port: 27901 - SSL enabled
2022-01-21 14:27:53.659 INFO 16672 — [main] o.s.b.w.embedded.tomcat.TomcatWebServer : Tomcat started on port(s): 27900 (https) with context path ‘’
2022-01-21 14:27:54.343 INFO 16672 — [main] io.axoniq.axonserver.AxonServer : Started AxonServer in 14.505 seconds (JVM running for 15.096)
2022-01-21 14:28:27.813 INFO 16672 — [grpc-executor-1] i.a.a.grpc.DefaultClientIdRegistry : Platform stream connected: 22124@PC-CHERQAOUI [stream id → 22124@PC-CHERQAOUI.44967654-4d51-4ae3-bf8b-8eec6c333400]
2022-01-21 14:28:27.815 INFO 16672 — [grpc-executor-1] i.a.a.logging.TopologyEventsLogger : Application connected: administration-service, clientId = 22124@PC-CHERQAOUI, clientStreamId = 22124@PC-CHERQAOUI.44967654-4d51-4ae3-bf8b-8eec6c333400, context = default
2022-01-21 14:29:02.153 INFO 16672 — [grpc-executor-4] i.a.a.grpc.DefaultClientIdRegistry : Platform stream connected: 5172@PC-CHERQAOUI [stream id → 5172@PC-CHERQAOUI.c2dac646-fe4e-4705-8e75-681cedca57d1]
2022-01-21 14:29:02.154 INFO 16672 — [grpc-executor-4] i.a.a.logging.TopologyEventsLogger : Application connected: support-service, clientId = 5172@PC-CHERQAOUI, clientStreamId = 5172@PC-CHERQAOUI.c2dac646-fe4e-4705-8e75-681cedca57d1, context = default
2022-01-21 14:29:04.065 INFO 16672 — [grpc-executor-1] i.a.a.grpc.DefaultClientIdRegistry : Platform stream disconnected: 5172@PC-CHERQAOUI [stream id → 5172@PC-CHERQAOUI.c2dac646-fe4e-4705-8e75-681cedca57d1]
2022-01-21 14:29:04.066 INFO 16672 — [grpc-executor-1] i.a.a.logging.TopologyEventsLogger : Application disconnected: support-service, clientId = 5172@PC-CHERQAOUI.c2dac646-fe4e-4705-8e75-681cedca57d1, context = default: Platform connection completed by client