Поскольку микрон нам так и не дал логов. Давайте взглянем на сферический лог в вакууме.
Не спрашивайте что он делает. Он не делает ничего. Это пустое приложение фреймворка Axon. В нем нет
бизнес-логики и вообще нет ничего. Это проба.
1.
2.
3.
4.
5.
6.
7.
8.
9.
10.
11.
12.
13.
14.
15.
16.
17.
18.
19.
20.
21.
22.
23.
24.
25.
26.
27.
28.
29.
30.
31.
32.
33.
34.
35.
36.
37.
38.
39.
40.
41.
42.
020-10-20 22:02:27.630 INFO 19326 --- [ main] DeferredRepositoryInitializationListener : Triggering deferred initialization of Spring Data repositories…
2020-10-20 22:02:27.631 INFO 19326 --- [ main] DeferredRepositoryInitializationListener : Spring Data repositories initialized!
2020-10-20 22:02:27.648 INFO 19326 --- [ main] m.s.axon.probeaxon.ProbeAxonApplication : Started ProbeAxonApplication in 4.399 seconds (JVM running for 4.877)
Hibernate: select tokenentry0_.segment as col_0_0_ from token_entry tokenentry0_ where tokenentry0_.processor_name=? order by tokenentry0_.segment ASC
Hibernate: select tokenentry0_.processor_name as processo1_2_0_, tokenentry0_.segment as segment2_2_0_, tokenentry0_.owner as owner3_2_0_, tokenentry0_.timestamp as timestam4_2_0_, tokenentry0_.token as token5_2_0_, tokenentry0_.token_type as token_ty6_2_0_ from token_entry tokenentry0_ where tokenentry0_.processor_name=? and tokenentry0_.segment=? for update
Hibernate: update token_entry set owner=?, timestamp=?, token=?, token_type=? where processor_name=? and segment=?
Hibernate: select tokenentry0_.segment as col_0_0_ from token_entry tokenentry0_ where tokenentry0_.processor_name=? order by tokenentry0_.segment ASC
2020-10-20 22:02:27.679 INFO 19326 --- [beaxon.query]-0] o.a.e.TrackingEventProcessor : Worker assigned to segment Segment[0/0] for processing
2020-10-20 22:02:27.685 INFO 19326 --- [beaxon.query]-0] o.a.e.TrackingEventProcessor : Using current Thread for last segment worker: TrackingSegmentWorker{processor=mayton.spring.axon.probeaxon.query, segment=Segment[0/0]}
Hibernate: select tokenentry0_.processor_name as processo1_2_0_, tokenentry0_.segment as segment2_2_0_, tokenentry0_.owner as owner3_2_0_, tokenentry0_.timestamp as timestam4_2_0_, tokenentry0_.token as token5_2_0_, tokenentry0_.token_type as token_ty6_2_0_ from token_entry tokenentry0_ where tokenentry0_.processor_name=? and tokenentry0_.segment=? for update
Hibernate: update token_entry set owner=?, timestamp=?, token=?, token_type=? where processor_name=? and segment=?
2020-10-20 22:02:27.689 INFO 19326 --- [beaxon.query]-0] o.a.e.TrackingEventProcessor : Fetched token: null for segment: Segment[0/0]
Hibernate: update token_entry set timestamp=? where processor_name=? and segment=? and owner=?
Hibernate: update token_entry set timestamp=? where processor_name=? and segment=? and owner=?
Hibernate: update token_entry set timestamp=? where processor_name=? and segment=? and owner=?
Hibernate: update token_entry set timestamp=? where processor_name=? and segment=? and owner=?
Hibernate: update token_entry set timestamp=? where processor_name=? and segment=? and owner=?
Hibernate: update token_entry set timestamp=? where processor_name=? and segment=? and owner=?
Hibernate: update token_entry set timestamp=? where processor_name=? and segment=? and owner=?
Hibernate: update token_entry set timestamp=? where processor_name=? and segment=? and owner=?
Hibernate: update token_entry set timestamp=? where processor_name=? and segment=? and owner=?
Hibernate: update token_entry set timestamp=? where processor_name=? and segment=? and owner=?
Hibernate: update token_entry set timestamp=? where processor_name=? and segment=? and owner=?
Hibernate: update token_entry set timestamp=? where processor_name=? and segment=? and owner=?
Hibernate: update token_entry set timestamp=? where processor_name=? and segment=? and owner=?
Hibernate: update token_entry set timestamp=? where processor_name=? and segment=? and owner=?
Hibernate: update token_entry set timestamp=? where processor_name=? and segment=? and owner=?
Hibernate: update token_entry set timestamp=? where processor_name=? and segment=? and owner=?
^C2020-10-20 22:02:44.452 INFO 19326 --- [nPool-worker-19] o.a.e.TrackingEventProcessor : Shutdown state set for Processor 'mayton.spring.axon.probeaxon.query'.
2020-10-20 22:02:44.452 INFO 19326 --- [nPool-worker-19] o.a.e.TrackingEventProcessor : Processor 'mayton.spring.axon.probeaxon.query' awaiting termination...
Hibernate: update token_entry set timestamp=? where processor_name=? and segment=? and owner=?
Hibernate: update token_entry set owner=null where owner=? and processor_name=? and segment=?
2020-10-20 22:02:44.932 INFO 19326 --- [beaxon.query]-0] o.a.e.TrackingEventProcessor : Released claim
2020-10-20 22:02:44.934 INFO 19326 --- [beaxon.query]-0] o.a.e.TrackingEventProcessor : Worker for segment Segment[0/0] stopped.
2020-10-20 22:02:44.967 INFO 19326 --- [extShutdownHook] i.a.a.c.query.impl.QueryChannelImpl : QueryChannel connected, 0 registrations resubscribed
2020-10-20 22:02:44.969 INFO 19326 --- [extShutdownHook] i.a.a.c.command.impl.CommandChannelImpl : Unregistered handler for command mayton.spring.axon.probeaxon.command.DeselectFoodCardCommand
2020-10-20 22:02:44.970 INFO 19326 --- [extShutdownHook] i.a.a.c.command.impl.CommandChannelImpl : Unregistered handler for command mayton.spring.axon.probeaxon.command.CreateFoodCardCommand
2020-10-20 22:02:44.971 INFO 19326 --- [extShutdownHook] i.a.a.c.command.impl.CommandChannelImpl : Unregistered handler for command mayton.spring.axon.probeaxon.command.SelectFoodCardCommand
2020-10-20 22:02:45.170 INFO 19326 --- [extShutdownHook] j.LocalContainerEntityManagerFactoryBean : Closing JPA EntityManagerFactory for persistence unit 'default'
2020-10-20 22:02:45.173 INFO 19326 --- [extShutdownHook] o.s.s.concurrent.ThreadPoolTaskExecutor : Shutting down ExecutorService 'applicationTaskExecutor'
2020-10-20 22:02:45.174 INFO 19326 --- [extShutdownHook] com.zaxxer.hikari.HikariDataSource : HikariPool-1 - Shutdown initiated...
2020-10-20 22:02:45.185 INFO 19326 --- [extShutdownHook] com.zaxxer.hikari.HikariDataSource : HikariPool-1 - Shutdown completed.
Что бросается в глаза?
1) Шумящие метки времени. Их надо убрать из обработки. Вот это.
2) Уровни. Что собирать? ERROR? Или WARN/INFO/DEBUG/TRACE? Я думаю надо собирать все но еще умножить количество
кластеров на уровни.
3) Есть проблема в разном формате сообщений. Тут видно что собщения от ORM отпечатались в другой логгер-аппендер
и поэтому имеют формат различный с основным логом.
Сразу от меня возникает предложение либо привести все эти несоотвествия к единому формату. Либо писать в отдельный
файловый appender (JSON)-подобного вида для упрощения задачи.
По поводу Log4j2/SLf4j/JUL/JCL я подниму отдельный топик. Я думаю что не я один бодался с зоопарком логгеров. И не я
один пытался унифицировать процесс старта и конфигурирования зоопарков.
