Build: #2 was successful
Job: Default Job was successful
Build log
The build generated 3,605 lines of output. The output is too long and has been truncated to the last 1,000 lines. Download or view full build log.
| 17-Jun-2025 22:21:16 | INFO 2025-06-17 22:21:16,501 [openmrs-db-reconnect-watchdog] c.z.h.HikariDataSource:123 HikariPool-4 - Start completed. |
| 17-Jun-2025 22:21:16 | INFO 2025-06-17 22:21:16,502 [openmrs-db-reconnect-watchdog] l.database:37 Set default schema name to PUBLIC |
| 17-Jun-2025 22:21:16 | INFO 2025-06-17 22:21:16,514 [openmrs-db-reconnect-watchdog] l.lockservice:37 Successfully acquired change log lock |
| 17-Jun-2025 22:21:16 | INFO 2025-06-17 22:21:16,598 [openmrs-db-reconnect-watchdog] l.changelog:37 Reading from PUBLIC.LIQUIBASECHANGELOG |
| 17-Jun-2025 22:21:16 | INFO 2025-06-17 22:21:16,601 [openmrs-db-reconnect-watchdog] l.command:37 Using deploymentId: 0198876599 |
| 17-Jun-2025 22:21:16 | Running Changeset: liquibase-watcher.xml::20200205-1001::wluyima |
| 17-Jun-2025 22:21:16 | INFO 2025-06-17 22:21:16,630 [openmrs-db-reconnect-watchdog] l.changelog:37 Marking ChangeSet: "liquibase-watcher.xml::20200205-1001::wluyima" as ran despite precondition failure due to onFail='MARK_RAN': |
| 17-Jun-2025 22:21:16 | liquibase-watcher.xml : Not precondition failed |
| 17-Jun-2025 22:21:16 | |
| 17-Jun-2025 22:21:16 | Running Changeset: liquibase-watcher.xml::20200205-1002::wluyima |
| 17-Jun-2025 22:21:16 | INFO 2025-06-17 22:21:16,639 [openmrs-db-reconnect-watchdog] l.changelog:37 Marking ChangeSet: "liquibase-watcher.xml::20200205-1002::wluyima" as ran despite precondition failure due to onFail='MARK_RAN': |
| 17-Jun-2025 22:21:16 | liquibase-watcher.xml : Not precondition failed |
| 17-Jun-2025 22:21:16 | |
| 17-Jun-2025 22:21:16 | Running Changeset: liquibase-watcher.xml::20200205-1003::wluyima |
| 17-Jun-2025 22:21:16 | INFO 2025-06-17 22:21:16,651 [openmrs-db-reconnect-watchdog] l.changelog:37 Marking ChangeSet: "liquibase-watcher.xml::20200205-1003::wluyima" as ran despite precondition failure due to onFail='MARK_RAN': |
| 17-Jun-2025 22:21:16 | liquibase-watcher.xml : Not precondition failed |
| 17-Jun-2025 22:21:16 | |
| 17-Jun-2025 22:21:16 | Running Changeset: liquibase-watcher.xml::20210827-1200::wluyima |
| 17-Jun-2025 22:21:16 | INFO 2025-06-17 22:21:16,655 [openmrs-db-reconnect-watchdog] l.changelog:37 Marking ChangeSet: "liquibase-watcher.xml::20210827-1200::wluyima" as ran despite precondition failure due to onFail='MARK_RAN': |
| 17-Jun-2025 22:21:16 | liquibase-watcher.xml : Not precondition failed |
| 17-Jun-2025 22:21:16 | |
| 17-Jun-2025 22:21:16 | INFO 2025-06-17 22:21:16,656 [openmrs-db-reconnect-watchdog] l.util:37 UPDATE SUMMARY |
| 17-Jun-2025 22:21:16 | INFO 2025-06-17 22:21:16,656 [openmrs-db-reconnect-watchdog] l.util:37 Run: 4 |
| 17-Jun-2025 22:21:16 | INFO 2025-06-17 22:21:16,656 [openmrs-db-reconnect-watchdog] l.util:37 Previously run: 0 |
| 17-Jun-2025 22:21:16 | INFO 2025-06-17 22:21:16,656 [openmrs-db-reconnect-watchdog] l.util:37 Filtered out: 1 |
| 17-Jun-2025 22:21:16 | INFO 2025-06-17 22:21:16,656 [openmrs-db-reconnect-watchdog] l.util:37 ------------------------------- |
| 17-Jun-2025 22:21:16 | INFO 2025-06-17 22:21:16,657 [openmrs-db-reconnect-watchdog] l.util:37 Total change sets: 5 |
| 17-Jun-2025 22:21:16 | |
| 17-Jun-2025 22:21:16 | INFO 2025-06-17 22:21:16,657 [openmrs-db-reconnect-watchdog] l.util:37 |
| 17-Jun-2025 22:21:16 | FILTERED CHANGE SETS SUMMARY |
| 17-Jun-2025 22:21:16 | |
| 17-Jun-2025 22:21:16 | INFO 2025-06-17 22:21:16,657 [openmrs-db-reconnect-watchdog] l.util:37 DBMS mismatch: 1 |
| 17-Jun-2025 22:21:16 | |
| 17-Jun-2025 22:21:16 | UPDATE SUMMARY |
| 17-Jun-2025 22:21:16 | Run: 4 |
| 17-Jun-2025 22:21:16 | Previously run: 0 |
| 17-Jun-2025 22:21:16 | Filtered out: 1 |
| 17-Jun-2025 22:21:16 | ------------------------------- |
| 17-Jun-2025 22:21:16 | Total change sets: 5 |
| 17-Jun-2025 22:21:16 | |
| 17-Jun-2025 22:21:16 | |
| 17-Jun-2025 22:21:16 | FILTERED CHANGE SETS SUMMARY |
| 17-Jun-2025 22:21:16 | |
| 17-Jun-2025 22:21:16 | DBMS mismatch: 1 |
| 17-Jun-2025 22:21:16 | |
| 17-Jun-2025 22:21:16 | INFO 2025-06-17 22:21:16,657 [openmrs-db-reconnect-watchdog] l.util:37 'liquibase-watcher.xml::20210827-1100::wluyima' : mismatched DBMS value of 'mysql' |
| 17-Jun-2025 22:21:16 | INFO 2025-06-17 22:21:16,658 [openmrs-db-reconnect-watchdog] l.util:37 Update summary generated |
| 17-Jun-2025 22:21:16 | INFO 2025-06-17 22:21:16,658 [openmrs-db-reconnect-watchdog] l.command:37 Update command completed successfully. |
| 17-Jun-2025 22:21:16 | Liquibase: Update has been successful. |
| 17-Jun-2025 22:21:16 | INFO 2025-06-17 22:21:16,667 [openmrs-db-reconnect-watchdog] l.lockservice:37 Successfully released change log lock |
| 17-Jun-2025 22:21:16 | INFO 2025-06-17 22:21:16,668 [openmrs-db-reconnect-watchdog] l.lockservice:37 Successfully released change log lock |
| 17-Jun-2025 22:21:16 | INFO 2025-06-17 22:21:16,669 [openmrs-db-reconnect-watchdog] l.command:37 Command execution complete |
| 17-Jun-2025 22:21:16 | INFO 2025-06-17 22:21:16,730 [openmrs-db-reconnect-watchdog] o.h.j.i.u.LogHelper:31 HHH000204: Processing PersistenceUnitInfo [name: openmrs] |
| 17-Jun-2025 22:21:16 | INFO 2025-06-17 22:21:16,741 [openmrs-db-reconnect-watchdog] o.h.d.Dialect:170 HHH000400: Using dialect: org.hibernate.dialect.MySQL5Dialect |
| 17-Jun-2025 22:21:16 | INFO 2025-06-17 22:21:16,851 [openmrs-db-reconnect-watchdog] o.h.e.t.j.p.i.JtaPlatformInitiator:52 HHH000490: Using JtaPlatform implementation: [org.hibernate.engine.transaction.jta.platform.internal.NoJtaPlatform] |
| 17-Jun-2025 22:21:16 | INFO 2025-06-17 22:21:16,852 [openmrs-db-reconnect-watchdog] o.s.o.j.LocalContainerEntityManagerFactoryBean:416 Initialized JPA EntityManagerFactory for persistence unit 'openmrs' |
| 17-Jun-2025 22:21:16 | INFO 2025-06-17 22:21:16,859 [openmrs-db-reconnect-watchdog] o.h.j.i.u.LogHelper:31 HHH000204: Processing PersistenceUnitInfo [name: mngt] |
| 17-Jun-2025 22:21:16 | INFO 2025-06-17 22:21:16,894 [openmrs-db-reconnect-watchdog] o.h.d.Dialect:170 HHH000400: Using dialect: org.hibernate.dialect.H2Dialect |
| 17-Jun-2025 22:21:17 | INFO 2025-06-17 22:21:17,026 [openmrs-db-reconnect-watchdog] o.h.e.t.j.p.i.JtaPlatformInitiator:52 HHH000490: Using JtaPlatform implementation: [org.hibernate.engine.transaction.jta.platform.internal.NoJtaPlatform] |
| 17-Jun-2025 22:21:17 | INFO 2025-06-17 22:21:17,030 [openmrs-db-reconnect-watchdog] o.s.o.j.LocalContainerEntityManagerFactoryBean:416 Initialized JPA EntityManagerFactory for persistence unit 'mngt' |
| 17-Jun-2025 22:21:17 | INFO 2025-06-17 22:21:17,032 [openmrs-db-reconnect-watchdog] o.o.e.m.w.AuditableEventFilter:37 Auditable tables to filter -> [] |
| 17-Jun-2025 22:21:17 | INFO 2025-06-17 22:21:17,035 [openmrs-db-reconnect-watchdog] o.o.e.m.w.DebeziumMessageProcessor:32 Event Filters -> [org.openmrs.eip.mysql.watcher.AuditableEventFilter@30be6a05] |
| 17-Jun-2025 22:21:17 | INFO 2025-06-17 22:21:17,147 [openmrs-db-reconnect-watchdog] o.a.c.m.DefaultConfigurationConfigurer:423 Adding custom EventNotifier with id: shutdownListener and implementation: org.openmrs.eip.mysql.watcher.ShutdownListener@73624529 |
| 17-Jun-2025 22:21:17 | INFO 2025-06-17 22:21:17,821 [openmrs-db-reconnect-watchdog] o.a.c.s.b.SpringBootRoutesCollector:120 Loading additional Camel XML routes from: classpath:camel/db-event-processor.xml |
| 17-Jun-2025 22:21:17 | INFO 2025-06-17 22:21:17,995 [openmrs-db-reconnect-watchdog] o.a.c.s.b.SpringBootRoutesCollector:146 Loading additional Camel XML rests from: classpath:camel-rest/*.xml |
| 17-Jun-2025 22:21:18 | INFO 2025-06-17 22:21:18,021 [openmrs-db-reconnect-watchdog] o.a.c.m.JmxManagementStrategy:115 JMX is enabled |
| 17-Jun-2025 22:21:18 | INFO 2025-06-17 22:21:18,320 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:2635 Apache Camel 3.3.0 (CamelContext: camel-2) is starting |
| 17-Jun-2025 22:21:18 | INFO 2025-06-17 22:21:18,361 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:2778 StreamCaching is not in use. If using streams then its recommended to enable stream caching. See more details at http://camel.apache.org/stream-caching.html |
| 17-Jun-2025 22:21:18 | INFO 2025-06-17 22:21:18,398 [openmrs-db-reconnect-watchdog] o.a.c.c.j.JpaComponent:153 Using EntityManagerFactory configured: org.springframework.orm.jpa.LocalContainerEntityManagerFactoryBean@e417628 |
| 17-Jun-2025 22:21:18 | INFO 2025-06-17 22:21:18,399 [openmrs-db-reconnect-watchdog] o.a.c.c.j.JpaComponent:169 Using TransactionManager configured on this component: org.springframework.orm.jpa.JpaTransactionManager@272cec71 |
| 17-Jun-2025 22:21:18 | INFO 2025-06-17 22:21:18,511 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3423 Route: db-event-processor started and consuming from: direct://db-event-processor |
| 17-Jun-2025 22:21:18 | INFO 2025-06-17 22:21:18,531 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:2687 Total 1 routes, of which 1 are started |
| 17-Jun-2025 22:21:18 | INFO 2025-06-17 22:21:18,531 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:2692 Apache Camel 3.3.0 (CamelContext: camel-2) started in 0.210 seconds |
| 17-Jun-2025 22:21:18 | INFO 2025-06-17 22:21:18,535 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DbEventProcessorRouteTest:61 Started DbEventProcessorRouteTest in 4.592 seconds (JVM running for 65.57) |
| 17-Jun-2025 22:21:18 | INFO 2025-06-17 22:21:18,610 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3423 Route: test-error-handler started and consuming from: direct://test-error-handler |
| 17-Jun-2025 22:21:18 | DEBUG 2025-06-17 22:21:18,628 [openmrs-db-reconnect-watchdog] db-event-processor:152 Processing db event: |
| 17-Jun-2025 22:21:18 | DEBUG 2025-06-17 22:21:18,628 [openmrs-db-reconnect-watchdog] o.o.e.m.w.IdentifierSettingProcessor:32 Received: Event {tableName=orders, primaryKeyId=106, identifier=order_uuid, operation=c, snapshot=false} |
| 17-Jun-2025 22:21:18 | DEBUG 2025-06-17 22:21:18,629 [openmrs-db-reconnect-watchdog] o.o.e.m.w.IdentifierSettingProcessor:37 Event object already has the identifier set |
| 17-Jun-2025 22:21:18 | DEBUG 2025-06-17 22:21:18,652 [openmrs-db-reconnect-watchdog] db-event-processor:152 All DB Event destinations -> mock:event-listener |
| 17-Jun-2025 22:21:18 | DEBUG 2025-06-17 22:21:18,704 [openmrs-db-reconnect-watchdog] db-event-processor:152 Previous order id -> 105 |
| 17-Jun-2025 22:21:18 | INFO 2025-06-17 22:21:18,704 [openmrs-db-reconnect-watchdog] db-event-processor:158 Has previous entity with id -> 105, checking if previous order has no retry items |
| 17-Jun-2025 22:21:18 | INFO 2025-06-17 22:21:18,723 [openmrs-db-reconnect-watchdog] db-event-processor:158 Moving order event to the failure queue because its previous order has 1 event(s) in the retry queue for destination: mock:event-listener |
| 17-Jun-2025 22:21:18 | DEBUG 2025-06-17 22:21:18,724 [openmrs-db-reconnect-watchdog] db-event-processor:152 Done processing db event |
| 17-Jun-2025 22:21:18 | INFO 2025-06-17 22:21:18,725 [openmrs-db-reconnect-watchdog] o.a.c.c.m.MockEndpoint:390 Asserting: mock://event-listener is satisfied |
| 17-Jun-2025 22:21:18 | INFO 2025-06-17 22:21:18,725 [openmrs-db-reconnect-watchdog] o.o.e.BaseCamelTest:90 Log event satisfied -> [INFO] Moving order event to the failure queue because its previous order has 1 event(s) in the retry queue for destination: mock:event-listener |
| 17-Jun-2025 22:21:18 | INFO 2025-06-17 22:21:18,725 [openmrs-db-reconnect-watchdog] o.o.e.BaseCamelTest:90 Log event satisfied -> [DEBUG] Done processing db event |
| 17-Jun-2025 22:21:18 | DEBUG 2025-06-17 22:21:18,725 [openmrs-db-reconnect-watchdog] o.o.e.DeleteDataTestExecutionListener:44 Deleting all data from management DB tables... |
| 17-Jun-2025 22:21:18 | DEBUG 2025-06-17 22:21:18,729 [openmrs-db-reconnect-watchdog] o.o.e.DeleteDataTestExecutionListener:51 Deleting all data from OpenMRS DB tables... |
| 17-Jun-2025 22:21:18 | INFO 2025-06-17 22:21:18,802 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DbEventProcessorRouteTest:53 ******************************************************************************** |
| 17-Jun-2025 22:21:18 | INFO 2025-06-17 22:21:18,802 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DbEventProcessorRouteTest:54 Testing done: shouldNotProcessAnOrderThatHasAPreviousTestOrderIfThePreviousTestOrderIsInTheErrorQueueForTheDestination(org.openmrs.eip.mysql.watcher.route.DbEventProcessorRouteTest) |
| 17-Jun-2025 22:21:18 | INFO 2025-06-17 22:21:18,802 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DbEventProcessorRouteTest:55 Took: 0.234 seconds (234 millis) |
| 17-Jun-2025 22:21:18 | INFO 2025-06-17 22:21:18,803 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DbEventProcessorRouteTest:56 ******************************************************************************** |
| 17-Jun-2025 22:21:18 | INFO 2025-06-17 22:21:18,828 [openmrs-db-reconnect-watchdog] o.a.c.i.e.DefaultShutdownStrategy:197 Starting to graceful shutdown 1 routes (timeout 45 seconds) |
| 17-Jun-2025 22:21:18 | INFO 2025-06-17 22:21:18,831 [Camel (camel-2) thread #2 - ShutdownTask] o.a.c.i.e.DefaultShutdownStrategy:703 Route: test-error-handler shutdown complete, was consuming from: direct://test-error-handler |
| 17-Jun-2025 22:21:18 | INFO 2025-06-17 22:21:18,832 [openmrs-db-reconnect-watchdog] o.a.c.i.e.DefaultShutdownStrategy:262 Graceful shutdown of 1 routes completed in 0 seconds |
| 17-Jun-2025 22:21:18 | INFO 2025-06-17 22:21:18,832 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3167 Route: test-error-handler is stopped, was consuming from: direct://test-error-handler |
| 17-Jun-2025 22:21:18 | INFO 2025-06-17 22:21:18,833 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3167 Route: test-error-handler is shutdown and removed, was consuming from: direct://test-error-handler |
| 17-Jun-2025 22:21:18 | INFO 2025-06-17 22:21:18,842 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3423 Route: test-error-handler started and consuming from: direct://test-error-handler |
| 17-Jun-2025 22:21:18 | DEBUG 2025-06-17 22:21:18,870 [openmrs-db-reconnect-watchdog] db-event-processor:152 Processing db event: |
| 17-Jun-2025 22:21:18 | DEBUG 2025-06-17 22:21:18,871 [openmrs-db-reconnect-watchdog] o.o.e.m.w.IdentifierSettingProcessor:32 Received: Event {tableName=orders, primaryKeyId=2, identifier=order_uuid, operation=c, snapshot=false} |
| 17-Jun-2025 22:21:18 | DEBUG 2025-06-17 22:21:18,871 [openmrs-db-reconnect-watchdog] o.o.e.m.w.IdentifierSettingProcessor:37 Event object already has the identifier set |
| 17-Jun-2025 22:21:18 | DEBUG 2025-06-17 22:21:18,873 [openmrs-db-reconnect-watchdog] db-event-processor:152 All DB Event destinations -> mock:event-listener |
| 17-Jun-2025 22:21:18 | DEBUG 2025-06-17 22:21:18,875 [openmrs-db-reconnect-watchdog] db-event-processor:152 Publishing to destination: mock:event-listener |
| 17-Jun-2025 22:21:18 | INFO 2025-06-17 22:21:18,877 [openmrs-db-reconnect-watchdog] db-event-processor:158 Removing from the error queue an item with id: 5 |
| 17-Jun-2025 22:21:18 | DEBUG 2025-06-17 22:21:18,896 [openmrs-db-reconnect-watchdog] db-event-processor:152 Successfully removed from the error queue an item with id: 5 |
| 17-Jun-2025 22:21:18 | DEBUG 2025-06-17 22:21:18,896 [openmrs-db-reconnect-watchdog] db-event-processor:152 Done processing db event |
| 17-Jun-2025 22:21:18 | INFO 2025-06-17 22:21:18,896 [openmrs-db-reconnect-watchdog] o.a.c.c.m.MockEndpoint:390 Asserting: mock://event-listener is satisfied |
| 17-Jun-2025 22:21:18 | INFO 2025-06-17 22:21:18,896 [openmrs-db-reconnect-watchdog] o.o.e.BaseCamelTest:90 Log event satisfied -> [DEBUG] Publishing to destination: mock:event-listener |
| 17-Jun-2025 22:21:18 | INFO 2025-06-17 22:21:18,897 [openmrs-db-reconnect-watchdog] o.o.e.BaseCamelTest:90 Log event satisfied -> [DEBUG] Done processing db event |
| 17-Jun-2025 22:21:18 | DEBUG 2025-06-17 22:21:18,897 [openmrs-db-reconnect-watchdog] o.o.e.DeleteDataTestExecutionListener:44 Deleting all data from management DB tables... |
| 17-Jun-2025 22:21:18 | DEBUG 2025-06-17 22:21:18,900 [openmrs-db-reconnect-watchdog] o.o.e.DeleteDataTestExecutionListener:51 Deleting all data from OpenMRS DB tables... |
| 17-Jun-2025 22:21:18 | INFO 2025-06-17 22:21:18,950 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DbEventProcessorRouteTest:53 ******************************************************************************** |
| 17-Jun-2025 22:21:18 | INFO 2025-06-17 22:21:18,950 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DbEventProcessorRouteTest:54 Testing done: shouldProcessARetryItemForAnOrderThatHasAPreviousOrderIfThePreviousOrderIsInTheErrorQueueForTheDestination(org.openmrs.eip.mysql.watcher.route.DbEventProcessorRouteTest) |
| 17-Jun-2025 22:21:18 | INFO 2025-06-17 22:21:18,950 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DbEventProcessorRouteTest:55 Took: 0.143 seconds (143 millis) |
| 17-Jun-2025 22:21:18 | INFO 2025-06-17 22:21:18,950 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DbEventProcessorRouteTest:56 ******************************************************************************** |
| 17-Jun-2025 22:21:18 | INFO 2025-06-17 22:21:18,972 [openmrs-db-reconnect-watchdog] o.a.c.i.e.DefaultShutdownStrategy:197 Starting to graceful shutdown 1 routes (timeout 45 seconds) |
| 17-Jun-2025 22:21:18 | INFO 2025-06-17 22:21:18,973 [Camel (camel-2) thread #2 - ShutdownTask] o.a.c.i.e.DefaultShutdownStrategy:703 Route: test-error-handler shutdown complete, was consuming from: direct://test-error-handler |
| 17-Jun-2025 22:21:18 | INFO 2025-06-17 22:21:18,974 [openmrs-db-reconnect-watchdog] o.a.c.i.e.DefaultShutdownStrategy:262 Graceful shutdown of 1 routes completed in 0 seconds |
| 17-Jun-2025 22:21:18 | INFO 2025-06-17 22:21:18,974 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3167 Route: test-error-handler is stopped, was consuming from: direct://test-error-handler |
| 17-Jun-2025 22:21:18 | INFO 2025-06-17 22:21:18,975 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3167 Route: test-error-handler is shutdown and removed, was consuming from: direct://test-error-handler |
| 17-Jun-2025 22:21:18 | INFO 2025-06-17 22:21:18,980 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3423 Route: test-error-handler started and consuming from: direct://test-error-handler |
| 17-Jun-2025 22:21:19 | DEBUG 2025-06-17 22:21:19,002 [openmrs-db-reconnect-watchdog] db-event-processor:152 Processing db event: |
| 17-Jun-2025 22:21:19 | DEBUG 2025-06-17 22:21:19,006 [openmrs-db-reconnect-watchdog] o.o.e.m.w.IdentifierSettingProcessor:32 Received: Event {tableName=orders, primaryKeyId=114, identifier=order_uuid, operation=c, snapshot=false} |
| 17-Jun-2025 22:21:19 | DEBUG 2025-06-17 22:21:19,006 [openmrs-db-reconnect-watchdog] o.o.e.m.w.IdentifierSettingProcessor:37 Event object already has the identifier set |
| 17-Jun-2025 22:21:19 | DEBUG 2025-06-17 22:21:19,009 [openmrs-db-reconnect-watchdog] db-event-processor:152 All DB Event destinations -> mock:event-listener |
| 17-Jun-2025 22:21:19 | DEBUG 2025-06-17 22:21:19,015 [openmrs-db-reconnect-watchdog] db-event-processor:152 Previous order id -> 113 |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,017 [openmrs-db-reconnect-watchdog] db-event-processor:158 Has previous entity with id -> 113, checking if previous order has no retry items |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,026 [openmrs-db-reconnect-watchdog] db-event-processor:158 Moving order event to the failure queue because its previous order has 1 event(s) in the retry queue for destination: mock:event-listener |
| 17-Jun-2025 22:21:19 | DEBUG 2025-06-17 22:21:19,027 [openmrs-db-reconnect-watchdog] db-event-processor:152 Done processing db event |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,035 [openmrs-db-reconnect-watchdog] o.a.c.c.m.MockEndpoint:390 Asserting: mock://event-listener is satisfied |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,035 [openmrs-db-reconnect-watchdog] o.o.e.BaseCamelTest:90 Log event satisfied -> [INFO] Moving order event to the failure queue because its previous order has 1 event(s) in the retry queue for destination: mock:event-listener |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,035 [openmrs-db-reconnect-watchdog] o.o.e.BaseCamelTest:90 Log event satisfied -> [DEBUG] Done processing db event |
| 17-Jun-2025 22:21:19 | DEBUG 2025-06-17 22:21:19,035 [openmrs-db-reconnect-watchdog] o.o.e.DeleteDataTestExecutionListener:44 Deleting all data from management DB tables... |
| 17-Jun-2025 22:21:19 | DEBUG 2025-06-17 22:21:19,036 [openmrs-db-reconnect-watchdog] o.o.e.DeleteDataTestExecutionListener:51 Deleting all data from OpenMRS DB tables... |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,071 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DbEventProcessorRouteTest:53 ******************************************************************************** |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,071 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DbEventProcessorRouteTest:54 Testing done: shouldNotProcessAnOrderThatHasAPreviousReferralOrderIfThePreviousReferralOrderIsInTheErrorQueueForTheDestination(org.openmrs.eip.mysql.watcher.route.DbEventProcessorRouteTest) |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,072 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DbEventProcessorRouteTest:55 Took: 0.120 seconds (120 millis) |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,072 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DbEventProcessorRouteTest:56 ******************************************************************************** |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,099 [openmrs-db-reconnect-watchdog] o.a.c.i.e.DefaultShutdownStrategy:197 Starting to graceful shutdown 1 routes (timeout 45 seconds) |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,099 [Camel (camel-2) thread #2 - ShutdownTask] o.a.c.i.e.DefaultShutdownStrategy:703 Route: test-error-handler shutdown complete, was consuming from: direct://test-error-handler |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,099 [openmrs-db-reconnect-watchdog] o.a.c.i.e.DefaultShutdownStrategy:262 Graceful shutdown of 1 routes completed in 0 seconds |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,100 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3167 Route: test-error-handler is stopped, was consuming from: direct://test-error-handler |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,100 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3167 Route: test-error-handler is shutdown and removed, was consuming from: direct://test-error-handler |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,117 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3423 Route: test-error-handler started and consuming from: direct://test-error-handler |
| 17-Jun-2025 22:21:19 | DEBUG 2025-06-17 22:21:19,124 [openmrs-db-reconnect-watchdog] db-event-processor:152 Processing db event: |
| 17-Jun-2025 22:21:19 | DEBUG 2025-06-17 22:21:19,124 [openmrs-db-reconnect-watchdog] o.o.e.m.w.IdentifierSettingProcessor:32 Received: Event {tableName=orders, primaryKeyId=2, identifier=order_uuid, operation=c, snapshot=false} |
| 17-Jun-2025 22:21:19 | DEBUG 2025-06-17 22:21:19,124 [openmrs-db-reconnect-watchdog] o.o.e.m.w.IdentifierSettingProcessor:37 Event object already has the identifier set |
| 17-Jun-2025 22:21:19 | DEBUG 2025-06-17 22:21:19,125 [openmrs-db-reconnect-watchdog] db-event-processor:152 All DB Event destinations -> mock:event-listener |
| 17-Jun-2025 22:21:19 | DEBUG 2025-06-17 22:21:19,130 [openmrs-db-reconnect-watchdog] db-event-processor:152 Previous order id -> 1 |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,130 [openmrs-db-reconnect-watchdog] db-event-processor:158 Has previous entity with id -> 1, checking if previous order has no retry items |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,147 [openmrs-db-reconnect-watchdog] db-event-processor:158 Moving order event to the failure queue because its previous order has 1 event(s) in the retry queue for destination: mock:event-listener |
| 17-Jun-2025 22:21:19 | DEBUG 2025-06-17 22:21:19,148 [openmrs-db-reconnect-watchdog] db-event-processor:152 Done processing db event |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,148 [openmrs-db-reconnect-watchdog] o.a.c.c.m.MockEndpoint:390 Asserting: mock://event-listener is satisfied |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,148 [openmrs-db-reconnect-watchdog] o.o.e.BaseCamelTest:90 Log event satisfied -> [INFO] Moving order event to the failure queue because its previous order has 1 event(s) in the retry queue for destination: mock:event-listener |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,148 [openmrs-db-reconnect-watchdog] o.o.e.BaseCamelTest:90 Log event satisfied -> [DEBUG] Done processing db event |
| 17-Jun-2025 22:21:19 | DEBUG 2025-06-17 22:21:19,149 [openmrs-db-reconnect-watchdog] o.o.e.DeleteDataTestExecutionListener:44 Deleting all data from management DB tables... |
| 17-Jun-2025 22:21:19 | DEBUG 2025-06-17 22:21:19,150 [openmrs-db-reconnect-watchdog] o.o.e.DeleteDataTestExecutionListener:51 Deleting all data from OpenMRS DB tables... |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,185 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DbEventProcessorRouteTest:53 ******************************************************************************** |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,185 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DbEventProcessorRouteTest:54 Testing done: shouldNotProcessAnOrderThatHasAPreviousOrderIfThePreviousOrderIsInTheErrorQueueForTheDestination(org.openmrs.eip.mysql.watcher.route.DbEventProcessorRouteTest) |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,185 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DbEventProcessorRouteTest:55 Took: 0.112 seconds (112 millis) |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,185 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DbEventProcessorRouteTest:56 ******************************************************************************** |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,205 [openmrs-db-reconnect-watchdog] o.a.c.i.e.DefaultShutdownStrategy:197 Starting to graceful shutdown 1 routes (timeout 45 seconds) |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,205 [Camel (camel-2) thread #2 - ShutdownTask] o.a.c.i.e.DefaultShutdownStrategy:703 Route: test-error-handler shutdown complete, was consuming from: direct://test-error-handler |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,206 [openmrs-db-reconnect-watchdog] o.a.c.i.e.DefaultShutdownStrategy:262 Graceful shutdown of 1 routes completed in 0 seconds |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,207 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3167 Route: test-error-handler is stopped, was consuming from: direct://test-error-handler |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,208 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3167 Route: test-error-handler is shutdown and removed, was consuming from: direct://test-error-handler |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,216 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3423 Route: test-error-handler started and consuming from: direct://test-error-handler |
| 17-Jun-2025 22:21:19 | DEBUG 2025-06-17 22:21:19,230 [openmrs-db-reconnect-watchdog] db-event-processor:152 Processing db event: |
| 17-Jun-2025 22:21:19 | DEBUG 2025-06-17 22:21:19,231 [openmrs-db-reconnect-watchdog] o.o.e.m.w.IdentifierSettingProcessor:32 Received: Event {tableName=referral_order, primaryKeyId=112, identifier=order_uuid, operation=c, snapshot=false} |
| 17-Jun-2025 22:21:19 | DEBUG 2025-06-17 22:21:19,231 [openmrs-db-reconnect-watchdog] o.o.e.m.w.IdentifierSettingProcessor:37 Event object already has the identifier set |
| 17-Jun-2025 22:21:19 | DEBUG 2025-06-17 22:21:19,239 [openmrs-db-reconnect-watchdog] db-event-processor:152 All DB Event destinations -> mock:event-listener |
| 17-Jun-2025 22:21:19 | DEBUG 2025-06-17 22:21:19,247 [openmrs-db-reconnect-watchdog] db-event-processor:152 Previous order id -> 111 |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,247 [openmrs-db-reconnect-watchdog] db-event-processor:158 Has previous entity with id -> 111, checking if previous order has no retry items |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,265 [openmrs-db-reconnect-watchdog] db-event-processor:158 Moving order event to the failure queue because its previous order has 1 event(s) in the retry queue for destination: mock:event-listener |
| 17-Jun-2025 22:21:19 | DEBUG 2025-06-17 22:21:19,270 [openmrs-db-reconnect-watchdog] db-event-processor:152 Done processing db event |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,270 [openmrs-db-reconnect-watchdog] o.a.c.c.m.MockEndpoint:390 Asserting: mock://event-listener is satisfied |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,270 [openmrs-db-reconnect-watchdog] o.o.e.BaseCamelTest:90 Log event satisfied -> [INFO] Moving order event to the failure queue because its previous order has 1 event(s) in the retry queue for destination: mock:event-listener |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,270 [openmrs-db-reconnect-watchdog] o.o.e.BaseCamelTest:90 Log event satisfied -> [DEBUG] Done processing db event |
| 17-Jun-2025 22:21:19 | DEBUG 2025-06-17 22:21:19,270 [openmrs-db-reconnect-watchdog] o.o.e.DeleteDataTestExecutionListener:44 Deleting all data from management DB tables... |
| 17-Jun-2025 22:21:19 | DEBUG 2025-06-17 22:21:19,279 [openmrs-db-reconnect-watchdog] o.o.e.DeleteDataTestExecutionListener:51 Deleting all data from OpenMRS DB tables... |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,317 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DbEventProcessorRouteTest:53 ******************************************************************************** |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,318 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DbEventProcessorRouteTest:54 Testing done: shouldNotProcessAReferralOrderThatHasAPreviousOrderIfThePreviousOrderIsInTheErrorQueueForTheDestination(org.openmrs.eip.mysql.watcher.route.DbEventProcessorRouteTest) |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,318 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DbEventProcessorRouteTest:55 Took: 0.131 seconds (131 millis) |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,318 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DbEventProcessorRouteTest:56 ******************************************************************************** |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,335 [openmrs-db-reconnect-watchdog] o.a.c.i.e.DefaultShutdownStrategy:197 Starting to graceful shutdown 1 routes (timeout 45 seconds) |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,336 [Camel (camel-2) thread #2 - ShutdownTask] o.a.c.i.e.DefaultShutdownStrategy:703 Route: test-error-handler shutdown complete, was consuming from: direct://test-error-handler |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,336 [openmrs-db-reconnect-watchdog] o.a.c.i.e.DefaultShutdownStrategy:262 Graceful shutdown of 1 routes completed in 0 seconds |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,337 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3167 Route: test-error-handler is stopped, was consuming from: direct://test-error-handler |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,337 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3167 Route: test-error-handler is shutdown and removed, was consuming from: direct://test-error-handler |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,343 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3423 Route: test-error-handler started and consuming from: direct://test-error-handler |
| 17-Jun-2025 22:21:19 | DEBUG 2025-06-17 22:21:19,348 [openmrs-db-reconnect-watchdog] db-event-processor:152 Processing db event: |
| 17-Jun-2025 22:21:19 | DEBUG 2025-06-17 22:21:19,348 [openmrs-db-reconnect-watchdog] o.o.e.m.w.IdentifierSettingProcessor:32 Received: Event {tableName=test_order, primaryKeyId=106, identifier=order_uuid, operation=c, snapshot=false} |
| 17-Jun-2025 22:21:19 | DEBUG 2025-06-17 22:21:19,348 [openmrs-db-reconnect-watchdog] o.o.e.m.w.IdentifierSettingProcessor:37 Event object already has the identifier set |
| 17-Jun-2025 22:21:19 | DEBUG 2025-06-17 22:21:19,352 [openmrs-db-reconnect-watchdog] db-event-processor:152 All DB Event destinations -> mock:event-listener |
| 17-Jun-2025 22:21:19 | DEBUG 2025-06-17 22:21:19,367 [openmrs-db-reconnect-watchdog] db-event-processor:152 Previous order id -> 105 |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,367 [openmrs-db-reconnect-watchdog] db-event-processor:158 Has previous entity with id -> 105, checking if previous order has no retry items |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,376 [openmrs-db-reconnect-watchdog] db-event-processor:158 Moving order event to the failure queue because its previous order has 1 event(s) in the retry queue for destination: mock:event-listener |
| 17-Jun-2025 22:21:19 | DEBUG 2025-06-17 22:21:19,383 [openmrs-db-reconnect-watchdog] db-event-processor:152 Done processing db event |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,383 [openmrs-db-reconnect-watchdog] o.a.c.c.m.MockEndpoint:390 Asserting: mock://event-listener is satisfied |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,383 [openmrs-db-reconnect-watchdog] o.o.e.BaseCamelTest:90 Log event satisfied -> [INFO] Moving order event to the failure queue because its previous order has 1 event(s) in the retry queue for destination: mock:event-listener |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,384 [openmrs-db-reconnect-watchdog] o.o.e.BaseCamelTest:90 Log event satisfied -> [DEBUG] Done processing db event |
| 17-Jun-2025 22:21:19 | DEBUG 2025-06-17 22:21:19,384 [openmrs-db-reconnect-watchdog] o.o.e.DeleteDataTestExecutionListener:44 Deleting all data from management DB tables... |
| 17-Jun-2025 22:21:19 | DEBUG 2025-06-17 22:21:19,386 [openmrs-db-reconnect-watchdog] o.o.e.DeleteDataTestExecutionListener:51 Deleting all data from OpenMRS DB tables... |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,426 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DbEventProcessorRouteTest:53 ******************************************************************************** |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,426 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DbEventProcessorRouteTest:54 Testing done: shouldNotProcessATestOrderThatHasAPreviousTestOrderIfThePreviousTestOrderIsInTheErrorQueueForTheDestination(org.openmrs.eip.mysql.watcher.route.DbEventProcessorRouteTest) |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,426 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DbEventProcessorRouteTest:55 Took: 0.107 seconds (107 millis) |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,427 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DbEventProcessorRouteTest:56 ******************************************************************************** |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,450 [openmrs-db-reconnect-watchdog] o.a.c.i.e.DefaultShutdownStrategy:197 Starting to graceful shutdown 1 routes (timeout 45 seconds) |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,450 [Camel (camel-2) thread #2 - ShutdownTask] o.a.c.i.e.DefaultShutdownStrategy:703 Route: test-error-handler shutdown complete, was consuming from: direct://test-error-handler |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,450 [openmrs-db-reconnect-watchdog] o.a.c.i.e.DefaultShutdownStrategy:262 Graceful shutdown of 1 routes completed in 0 seconds |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,451 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3167 Route: test-error-handler is stopped, was consuming from: direct://test-error-handler |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,451 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3167 Route: test-error-handler is shutdown and removed, was consuming from: direct://test-error-handler |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,455 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3423 Route: test-error-handler started and consuming from: direct://test-error-handler |
| 17-Jun-2025 22:21:19 | DEBUG 2025-06-17 22:21:19,467 [openmrs-db-reconnect-watchdog] db-event-processor:152 Processing db event: |
| 17-Jun-2025 22:21:19 | DEBUG 2025-06-17 22:21:19,467 [openmrs-db-reconnect-watchdog] o.o.e.m.w.IdentifierSettingProcessor:32 Received: Event {tableName=referral_order, primaryKeyId=114, identifier=order_uuid, operation=c, snapshot=false} |
| 17-Jun-2025 22:21:19 | DEBUG 2025-06-17 22:21:19,467 [openmrs-db-reconnect-watchdog] o.o.e.m.w.IdentifierSettingProcessor:37 Event object already has the identifier set |
| 17-Jun-2025 22:21:19 | DEBUG 2025-06-17 22:21:19,468 [openmrs-db-reconnect-watchdog] db-event-processor:152 All DB Event destinations -> mock:event-listener |
| 17-Jun-2025 22:21:19 | DEBUG 2025-06-17 22:21:19,472 [openmrs-db-reconnect-watchdog] db-event-processor:152 Previous order id -> 113 |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,472 [openmrs-db-reconnect-watchdog] db-event-processor:158 Has previous entity with id -> 113, checking if previous order has no retry items |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,476 [openmrs-db-reconnect-watchdog] db-event-processor:158 Moving order event to the failure queue because its previous order has 1 event(s) in the retry queue for destination: mock:event-listener |
| 17-Jun-2025 22:21:19 | DEBUG 2025-06-17 22:21:19,477 [openmrs-db-reconnect-watchdog] db-event-processor:152 Done processing db event |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,477 [openmrs-db-reconnect-watchdog] o.a.c.c.m.MockEndpoint:390 Asserting: mock://event-listener is satisfied |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,478 [openmrs-db-reconnect-watchdog] o.o.e.BaseCamelTest:90 Log event satisfied -> [INFO] Moving order event to the failure queue because its previous order has 1 event(s) in the retry queue for destination: mock:event-listener |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,478 [openmrs-db-reconnect-watchdog] o.o.e.BaseCamelTest:90 Log event satisfied -> [DEBUG] Done processing db event |
| 17-Jun-2025 22:21:19 | DEBUG 2025-06-17 22:21:19,478 [openmrs-db-reconnect-watchdog] o.o.e.DeleteDataTestExecutionListener:44 Deleting all data from management DB tables... |
| 17-Jun-2025 22:21:19 | DEBUG 2025-06-17 22:21:19,479 [openmrs-db-reconnect-watchdog] o.o.e.DeleteDataTestExecutionListener:51 Deleting all data from OpenMRS DB tables... |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,515 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DbEventProcessorRouteTest:53 ******************************************************************************** |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,515 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DbEventProcessorRouteTest:54 Testing done: shouldNotProcessAReferralOrderThatHasAPreviousReferralOrderIfThePreviousReferralOrderIsInTheErrorQueueForTheDestination(org.openmrs.eip.mysql.watcher.route.DbEventProcessorRouteTest) |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,515 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DbEventProcessorRouteTest:55 Took: 0.087 seconds (87 millis) |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,516 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DbEventProcessorRouteTest:56 ******************************************************************************** |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,537 [openmrs-db-reconnect-watchdog] o.a.c.i.e.DefaultShutdownStrategy:197 Starting to graceful shutdown 1 routes (timeout 45 seconds) |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,538 [Camel (camel-2) thread #2 - ShutdownTask] o.a.c.i.e.DefaultShutdownStrategy:703 Route: test-error-handler shutdown complete, was consuming from: direct://test-error-handler |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,542 [openmrs-db-reconnect-watchdog] o.a.c.i.e.DefaultShutdownStrategy:262 Graceful shutdown of 1 routes completed in 0 seconds |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,543 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3167 Route: test-error-handler is stopped, was consuming from: direct://test-error-handler |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,543 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3167 Route: test-error-handler is shutdown and removed, was consuming from: direct://test-error-handler |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,547 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3423 Route: test-error-handler started and consuming from: direct://test-error-handler |
| 17-Jun-2025 22:21:19 | DEBUG 2025-06-17 22:21:19,555 [openmrs-db-reconnect-watchdog] db-event-processor:152 Processing db event: |
| 17-Jun-2025 22:21:19 | DEBUG 2025-06-17 22:21:19,559 [openmrs-db-reconnect-watchdog] o.o.e.m.w.IdentifierSettingProcessor:32 Received: Event {tableName=drug_order, primaryKeyId=108, identifier=order_uuid, operation=c, snapshot=false} |
| 17-Jun-2025 22:21:19 | DEBUG 2025-06-17 22:21:19,559 [openmrs-db-reconnect-watchdog] o.o.e.m.w.IdentifierSettingProcessor:37 Event object already has the identifier set |
| 17-Jun-2025 22:21:19 | DEBUG 2025-06-17 22:21:19,560 [openmrs-db-reconnect-watchdog] db-event-processor:152 All DB Event destinations -> mock:event-listener |
| 17-Jun-2025 22:21:19 | DEBUG 2025-06-17 22:21:19,580 [openmrs-db-reconnect-watchdog] db-event-processor:152 Previous order id -> 107 |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,580 [openmrs-db-reconnect-watchdog] db-event-processor:158 Has previous entity with id -> 107, checking if previous order has no retry items |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,593 [openmrs-db-reconnect-watchdog] db-event-processor:158 Moving order event to the failure queue because its previous order has 1 event(s) in the retry queue for destination: mock:event-listener |
| 17-Jun-2025 22:21:19 | DEBUG 2025-06-17 22:21:19,595 [openmrs-db-reconnect-watchdog] db-event-processor:152 Done processing db event |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,595 [openmrs-db-reconnect-watchdog] o.a.c.c.m.MockEndpoint:390 Asserting: mock://event-listener is satisfied |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,595 [openmrs-db-reconnect-watchdog] o.o.e.BaseCamelTest:90 Log event satisfied -> [INFO] Moving order event to the failure queue because its previous order has 1 event(s) in the retry queue for destination: mock:event-listener |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,595 [openmrs-db-reconnect-watchdog] o.o.e.BaseCamelTest:90 Log event satisfied -> [DEBUG] Done processing db event |
| 17-Jun-2025 22:21:19 | DEBUG 2025-06-17 22:21:19,596 [openmrs-db-reconnect-watchdog] o.o.e.DeleteDataTestExecutionListener:44 Deleting all data from management DB tables... |
| 17-Jun-2025 22:21:19 | DEBUG 2025-06-17 22:21:19,597 [openmrs-db-reconnect-watchdog] o.o.e.DeleteDataTestExecutionListener:51 Deleting all data from OpenMRS DB tables... |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,639 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DbEventProcessorRouteTest:53 ******************************************************************************** |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,639 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DbEventProcessorRouteTest:54 Testing done: shouldNotProcessADrugOrderThatHasAPreviousDrugOrderIfThePreviousDrugOrderIsInTheErrorQueueForTheDestination(org.openmrs.eip.mysql.watcher.route.DbEventProcessorRouteTest) |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,640 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DbEventProcessorRouteTest:55 Took: 0.123 seconds (123 millis) |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,640 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DbEventProcessorRouteTest:56 ******************************************************************************** |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,658 [openmrs-db-reconnect-watchdog] o.a.c.i.e.DefaultShutdownStrategy:197 Starting to graceful shutdown 1 routes (timeout 45 seconds) |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,658 [Camel (camel-2) thread #2 - ShutdownTask] o.a.c.i.e.DefaultShutdownStrategy:703 Route: test-error-handler shutdown complete, was consuming from: direct://test-error-handler |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,658 [openmrs-db-reconnect-watchdog] o.a.c.i.e.DefaultShutdownStrategy:262 Graceful shutdown of 1 routes completed in 0 seconds |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,659 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3167 Route: test-error-handler is stopped, was consuming from: direct://test-error-handler |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,659 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3167 Route: test-error-handler is shutdown and removed, was consuming from: direct://test-error-handler |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,664 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3423 Route: test-error-handler started and consuming from: direct://test-error-handler |
| 17-Jun-2025 22:21:19 | DEBUG 2025-06-17 22:21:19,669 [openmrs-db-reconnect-watchdog] db-event-processor:152 Processing db event: |
| 17-Jun-2025 22:21:19 | DEBUG 2025-06-17 22:21:19,669 [openmrs-db-reconnect-watchdog] o.o.e.m.w.IdentifierSettingProcessor:32 Received: Event {tableName=orders, primaryKeyId=2, identifier=order_uuid, operation=d, snapshot=false} |
| 17-Jun-2025 22:21:19 | DEBUG 2025-06-17 22:21:19,669 [openmrs-db-reconnect-watchdog] o.o.e.m.w.IdentifierSettingProcessor:37 Event object already has the identifier set |
| 17-Jun-2025 22:21:19 | DEBUG 2025-06-17 22:21:19,670 [openmrs-db-reconnect-watchdog] db-event-processor:152 All DB Event destinations -> mock:event-listener |
| 17-Jun-2025 22:21:19 | DEBUG 2025-06-17 22:21:19,673 [openmrs-db-reconnect-watchdog] db-event-processor:152 Previous order id -> 1 |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,673 [openmrs-db-reconnect-watchdog] db-event-processor:158 Has previous entity with id -> 1, checking if previous order has no retry items |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,676 [openmrs-db-reconnect-watchdog] db-event-processor:158 Moving order event to the failure queue because its previous order has 1 event(s) in the retry queue for destination: mock:event-listener |
| 17-Jun-2025 22:21:19 | DEBUG 2025-06-17 22:21:19,676 [openmrs-db-reconnect-watchdog] db-event-processor:152 Done processing db event |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,676 [openmrs-db-reconnect-watchdog] o.a.c.c.m.MockEndpoint:390 Asserting: mock://event-listener is satisfied |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,677 [openmrs-db-reconnect-watchdog] o.o.e.BaseCamelTest:90 Log event satisfied -> [INFO] Moving order event to the failure queue because its previous order has 1 event(s) in the retry queue for destination: mock:event-listener |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,677 [openmrs-db-reconnect-watchdog] o.o.e.BaseCamelTest:90 Log event satisfied -> [DEBUG] Done processing db event |
| 17-Jun-2025 22:21:19 | DEBUG 2025-06-17 22:21:19,677 [openmrs-db-reconnect-watchdog] o.o.e.DeleteDataTestExecutionListener:44 Deleting all data from management DB tables... |
| 17-Jun-2025 22:21:19 | DEBUG 2025-06-17 22:21:19,684 [openmrs-db-reconnect-watchdog] o.o.e.DeleteDataTestExecutionListener:51 Deleting all data from OpenMRS DB tables... |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,731 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DbEventProcessorRouteTest:53 ******************************************************************************** |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,731 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DbEventProcessorRouteTest:54 Testing done: shouldNotProcessADeletedOrderThatHasAPreviousOrderIfThePreviousOrderIsInTheErrorQueueForTheDestination(org.openmrs.eip.mysql.watcher.route.DbEventProcessorRouteTest) |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,731 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DbEventProcessorRouteTest:55 Took: 0.091 seconds (91 millis) |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,731 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DbEventProcessorRouteTest:56 ******************************************************************************** |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,758 [openmrs-db-reconnect-watchdog] o.a.c.i.e.DefaultShutdownStrategy:197 Starting to graceful shutdown 1 routes (timeout 45 seconds) |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,760 [Camel (camel-2) thread #2 - ShutdownTask] o.a.c.i.e.DefaultShutdownStrategy:703 Route: test-error-handler shutdown complete, was consuming from: direct://test-error-handler |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,760 [openmrs-db-reconnect-watchdog] o.a.c.i.e.DefaultShutdownStrategy:262 Graceful shutdown of 1 routes completed in 0 seconds |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,761 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3167 Route: test-error-handler is stopped, was consuming from: direct://test-error-handler |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,766 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3167 Route: test-error-handler is shutdown and removed, was consuming from: direct://test-error-handler |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,772 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3423 Route: test-error-handler started and consuming from: direct://test-error-handler |
| 17-Jun-2025 22:21:19 | DEBUG 2025-06-17 22:21:19,780 [openmrs-db-reconnect-watchdog] db-event-processor:152 Processing db event: |
| 17-Jun-2025 22:21:19 | DEBUG 2025-06-17 22:21:19,781 [openmrs-db-reconnect-watchdog] o.o.e.m.w.IdentifierSettingProcessor:32 Received: Event {tableName=orders, primaryKeyId=2, identifier=order_uuid, operation=c, snapshot=false} |
| 17-Jun-2025 22:21:19 | DEBUG 2025-06-17 22:21:19,781 [openmrs-db-reconnect-watchdog] o.o.e.m.w.IdentifierSettingProcessor:37 Event object already has the identifier set |
| 17-Jun-2025 22:21:19 | DEBUG 2025-06-17 22:21:19,782 [openmrs-db-reconnect-watchdog] db-event-processor:152 All DB Event destinations -> mock:event-listener |
| 17-Jun-2025 22:21:19 | DEBUG 2025-06-17 22:21:19,787 [openmrs-db-reconnect-watchdog] db-event-processor:152 Publishing to destination: mock:event-listener |
| 17-Jun-2025 22:21:19 | DEBUG 2025-06-17 22:21:19,787 [openmrs-db-reconnect-watchdog] db-event-processor:152 Done processing db event |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,787 [openmrs-db-reconnect-watchdog] o.a.c.c.m.MockEndpoint:390 Asserting: mock://event-listener is satisfied |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,787 [openmrs-db-reconnect-watchdog] o.o.e.BaseCamelTest:90 Log event satisfied -> [DEBUG] Publishing to destination: mock:event-listener |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,787 [openmrs-db-reconnect-watchdog] o.o.e.BaseCamelTest:90 Log event satisfied -> [DEBUG] Done processing db event |
| 17-Jun-2025 22:21:19 | DEBUG 2025-06-17 22:21:19,788 [openmrs-db-reconnect-watchdog] o.o.e.DeleteDataTestExecutionListener:44 Deleting all data from management DB tables... |
| 17-Jun-2025 22:21:19 | DEBUG 2025-06-17 22:21:19,791 [openmrs-db-reconnect-watchdog] o.o.e.DeleteDataTestExecutionListener:51 Deleting all data from OpenMRS DB tables... |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,826 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DbEventProcessorRouteTest:53 ******************************************************************************** |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,826 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DbEventProcessorRouteTest:54 Testing done: shouldProcessAnOrderThatHasAPreviousOrderIfThePreviousOrderIsInTheErrorQueueForTheDestinationButTheCheckIsDisabled(org.openmrs.eip.mysql.watcher.route.DbEventProcessorRouteTest) |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,826 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DbEventProcessorRouteTest:55 Took: 0.091 seconds (91 millis) |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,826 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DbEventProcessorRouteTest:56 ******************************************************************************** |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,843 [openmrs-db-reconnect-watchdog] o.a.c.i.e.DefaultShutdownStrategy:197 Starting to graceful shutdown 1 routes (timeout 45 seconds) |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,843 [Camel (camel-2) thread #2 - ShutdownTask] o.a.c.i.e.DefaultShutdownStrategy:703 Route: test-error-handler shutdown complete, was consuming from: direct://test-error-handler |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,843 [openmrs-db-reconnect-watchdog] o.a.c.i.e.DefaultShutdownStrategy:262 Graceful shutdown of 1 routes completed in 0 seconds |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,844 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3167 Route: test-error-handler is stopped, was consuming from: direct://test-error-handler |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,844 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3167 Route: test-error-handler is shutdown and removed, was consuming from: direct://test-error-handler |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,847 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3423 Route: test-error-handler started and consuming from: direct://test-error-handler |
| 17-Jun-2025 22:21:19 | DEBUG 2025-06-17 22:21:19,858 [openmrs-db-reconnect-watchdog] db-event-processor:152 Processing db event: |
| 17-Jun-2025 22:21:19 | DEBUG 2025-06-17 22:21:19,858 [openmrs-db-reconnect-watchdog] o.o.e.m.w.IdentifierSettingProcessor:32 Received: Event {tableName=drug_order, primaryKeyId=104, identifier=order_uuid, operation=c, snapshot=false} |
| 17-Jun-2025 22:21:19 | DEBUG 2025-06-17 22:21:19,858 [openmrs-db-reconnect-watchdog] o.o.e.m.w.IdentifierSettingProcessor:37 Event object already has the identifier set |
| 17-Jun-2025 22:21:19 | DEBUG 2025-06-17 22:21:19,859 [openmrs-db-reconnect-watchdog] db-event-processor:152 All DB Event destinations -> mock:event-listener |
| 17-Jun-2025 22:21:19 | DEBUG 2025-06-17 22:21:19,869 [openmrs-db-reconnect-watchdog] db-event-processor:152 Previous order id -> 103 |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,869 [openmrs-db-reconnect-watchdog] db-event-processor:158 Has previous entity with id -> 103, checking if previous order has no retry items |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,874 [openmrs-db-reconnect-watchdog] db-event-processor:158 Moving order event to the failure queue because its previous order has 1 event(s) in the retry queue for destination: mock:event-listener |
| 17-Jun-2025 22:21:19 | DEBUG 2025-06-17 22:21:19,879 [openmrs-db-reconnect-watchdog] db-event-processor:152 Done processing db event |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,879 [openmrs-db-reconnect-watchdog] o.a.c.c.m.MockEndpoint:390 Asserting: mock://event-listener is satisfied |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,879 [openmrs-db-reconnect-watchdog] o.o.e.BaseCamelTest:90 Log event satisfied -> [INFO] Moving order event to the failure queue because its previous order has 1 event(s) in the retry queue for destination: mock:event-listener |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,879 [openmrs-db-reconnect-watchdog] o.o.e.BaseCamelTest:90 Log event satisfied -> [DEBUG] Done processing db event |
| 17-Jun-2025 22:21:19 | DEBUG 2025-06-17 22:21:19,880 [openmrs-db-reconnect-watchdog] o.o.e.DeleteDataTestExecutionListener:44 Deleting all data from management DB tables... |
| 17-Jun-2025 22:21:19 | DEBUG 2025-06-17 22:21:19,884 [openmrs-db-reconnect-watchdog] o.o.e.DeleteDataTestExecutionListener:51 Deleting all data from OpenMRS DB tables... |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,921 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DbEventProcessorRouteTest:53 ******************************************************************************** |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,921 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DbEventProcessorRouteTest:54 Testing done: shouldNotProcessADrugOrderThatHasAPreviousOrderIfThePreviousOrderIsInTheErrorQueueForTheDestination(org.openmrs.eip.mysql.watcher.route.DbEventProcessorRouteTest) |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,921 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DbEventProcessorRouteTest:55 Took: 0.094 seconds (94 millis) |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,921 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DbEventProcessorRouteTest:56 ******************************************************************************** |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,943 [openmrs-db-reconnect-watchdog] o.a.c.i.e.DefaultShutdownStrategy:197 Starting to graceful shutdown 1 routes (timeout 45 seconds) |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,945 [Camel (camel-2) thread #2 - ShutdownTask] o.a.c.i.e.DefaultShutdownStrategy:703 Route: test-error-handler shutdown complete, was consuming from: direct://test-error-handler |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,945 [openmrs-db-reconnect-watchdog] o.a.c.i.e.DefaultShutdownStrategy:262 Graceful shutdown of 1 routes completed in 0 seconds |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,945 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3167 Route: test-error-handler is stopped, was consuming from: direct://test-error-handler |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,946 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3167 Route: test-error-handler is shutdown and removed, was consuming from: direct://test-error-handler |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,949 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3423 Route: test-error-handler started and consuming from: direct://test-error-handler |
| 17-Jun-2025 22:21:19 | DEBUG 2025-06-17 22:21:19,958 [openmrs-db-reconnect-watchdog] db-event-processor:152 Processing db event: |
| 17-Jun-2025 22:21:19 | DEBUG 2025-06-17 22:21:19,958 [openmrs-db-reconnect-watchdog] o.o.e.m.w.IdentifierSettingProcessor:32 Received: Event {tableName=orders, primaryKeyId=108, identifier=order_uuid, operation=c, snapshot=false} |
| 17-Jun-2025 22:21:19 | DEBUG 2025-06-17 22:21:19,958 [openmrs-db-reconnect-watchdog] o.o.e.m.w.IdentifierSettingProcessor:37 Event object already has the identifier set |
| 17-Jun-2025 22:21:19 | DEBUG 2025-06-17 22:21:19,959 [openmrs-db-reconnect-watchdog] db-event-processor:152 All DB Event destinations -> mock:event-listener |
| 17-Jun-2025 22:21:19 | DEBUG 2025-06-17 22:21:19,970 [openmrs-db-reconnect-watchdog] db-event-processor:152 Previous order id -> 107 |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,970 [openmrs-db-reconnect-watchdog] db-event-processor:158 Has previous entity with id -> 107, checking if previous order has no retry items |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,978 [openmrs-db-reconnect-watchdog] db-event-processor:158 Moving order event to the failure queue because its previous order has 1 event(s) in the retry queue for destination: mock:event-listener |
| 17-Jun-2025 22:21:19 | DEBUG 2025-06-17 22:21:19,984 [openmrs-db-reconnect-watchdog] db-event-processor:152 Done processing db event |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,984 [openmrs-db-reconnect-watchdog] o.a.c.c.m.MockEndpoint:390 Asserting: mock://event-listener is satisfied |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,984 [openmrs-db-reconnect-watchdog] o.o.e.BaseCamelTest:90 Log event satisfied -> [INFO] Moving order event to the failure queue because its previous order has 1 event(s) in the retry queue for destination: mock:event-listener |
| 17-Jun-2025 22:21:19 | INFO 2025-06-17 22:21:19,984 [openmrs-db-reconnect-watchdog] o.o.e.BaseCamelTest:90 Log event satisfied -> [DEBUG] Done processing db event |
| 17-Jun-2025 22:21:19 | DEBUG 2025-06-17 22:21:19,985 [openmrs-db-reconnect-watchdog] o.o.e.DeleteDataTestExecutionListener:44 Deleting all data from management DB tables... |
| 17-Jun-2025 22:21:19 | DEBUG 2025-06-17 22:21:19,992 [openmrs-db-reconnect-watchdog] o.o.e.DeleteDataTestExecutionListener:51 Deleting all data from OpenMRS DB tables... |
| 17-Jun-2025 22:21:20 | INFO 2025-06-17 22:21:20,027 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DbEventProcessorRouteTest:53 ******************************************************************************** |
| 17-Jun-2025 22:21:20 | INFO 2025-06-17 22:21:20,027 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DbEventProcessorRouteTest:54 Testing done: shouldNotProcessAnOrderThatHasAPreviousDrugOrderIfThePreviousDrugOrderIsInTheErrorQueueForTheDestination(org.openmrs.eip.mysql.watcher.route.DbEventProcessorRouteTest) |
| 17-Jun-2025 22:21:20 | INFO 2025-06-17 22:21:20,027 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DbEventProcessorRouteTest:55 Took: 0.104 seconds (104 millis) |
| 17-Jun-2025 22:21:20 | INFO 2025-06-17 22:21:20,027 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DbEventProcessorRouteTest:56 ******************************************************************************** |
| 17-Jun-2025 22:21:20 | INFO 2025-06-17 22:21:20,062 [openmrs-db-reconnect-watchdog] o.a.c.i.e.DefaultShutdownStrategy:197 Starting to graceful shutdown 1 routes (timeout 45 seconds) |
| 17-Jun-2025 22:21:20 | INFO 2025-06-17 22:21:20,064 [Camel (camel-2) thread #2 - ShutdownTask] o.a.c.i.e.DefaultShutdownStrategy:703 Route: test-error-handler shutdown complete, was consuming from: direct://test-error-handler |
| 17-Jun-2025 22:21:20 | INFO 2025-06-17 22:21:20,065 [openmrs-db-reconnect-watchdog] o.a.c.i.e.DefaultShutdownStrategy:262 Graceful shutdown of 1 routes completed in 0 seconds |
| 17-Jun-2025 22:21:20 | INFO 2025-06-17 22:21:20,065 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3167 Route: test-error-handler is stopped, was consuming from: direct://test-error-handler |
| 17-Jun-2025 22:21:20 | INFO 2025-06-17 22:21:20,065 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3167 Route: test-error-handler is shutdown and removed, was consuming from: direct://test-error-handler |
| 17-Jun-2025 22:21:20 | INFO 2025-06-17 22:21:20,070 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3423 Route: test-error-handler started and consuming from: direct://test-error-handler |
| 17-Jun-2025 22:21:20 | DEBUG 2025-06-17 22:21:20,082 [openmrs-db-reconnect-watchdog] db-event-processor:152 Processing db event: |
| 17-Jun-2025 22:21:20 | DEBUG 2025-06-17 22:21:20,082 [openmrs-db-reconnect-watchdog] o.o.e.m.w.IdentifierSettingProcessor:32 Received: Event {tableName=orders, primaryKeyId=110, identifier=order_uuid, operation=c, snapshot=false} |
| 17-Jun-2025 22:21:20 | DEBUG 2025-06-17 22:21:20,082 [openmrs-db-reconnect-watchdog] o.o.e.m.w.IdentifierSettingProcessor:37 Event object already has the identifier set |
| 17-Jun-2025 22:21:20 | DEBUG 2025-06-17 22:21:20,087 [openmrs-db-reconnect-watchdog] db-event-processor:152 All DB Event destinations -> mock:event-listener |
| 17-Jun-2025 22:21:20 | DEBUG 2025-06-17 22:21:20,099 [openmrs-db-reconnect-watchdog] db-event-processor:152 Previous order id -> 109 |
| 17-Jun-2025 22:21:20 | INFO 2025-06-17 22:21:20,099 [openmrs-db-reconnect-watchdog] db-event-processor:158 Has previous entity with id -> 109, checking if previous order has no retry items |
| 17-Jun-2025 22:21:20 | DEBUG 2025-06-17 22:21:20,113 [openmrs-db-reconnect-watchdog] db-event-processor:152 Publishing to destination: mock:event-listener |
| 17-Jun-2025 22:21:20 | DEBUG 2025-06-17 22:21:20,113 [openmrs-db-reconnect-watchdog] db-event-processor:152 Done processing db event |
| 17-Jun-2025 22:21:20 | INFO 2025-06-17 22:21:20,113 [openmrs-db-reconnect-watchdog] o.a.c.c.m.MockEndpoint:390 Asserting: mock://event-listener is satisfied |
| 17-Jun-2025 22:21:20 | INFO 2025-06-17 22:21:20,113 [openmrs-db-reconnect-watchdog] o.o.e.BaseCamelTest:90 Log event satisfied -> [DEBUG] Publishing to destination: mock:event-listener |
| 17-Jun-2025 22:21:20 | INFO 2025-06-17 22:21:20,113 [openmrs-db-reconnect-watchdog] o.o.e.BaseCamelTest:90 Log event satisfied -> [DEBUG] Done processing db event |
| 17-Jun-2025 22:21:20 | DEBUG 2025-06-17 22:21:20,114 [openmrs-db-reconnect-watchdog] o.o.e.DeleteDataTestExecutionListener:44 Deleting all data from management DB tables... |
| 17-Jun-2025 22:21:20 | DEBUG 2025-06-17 22:21:20,115 [openmrs-db-reconnect-watchdog] o.o.e.DeleteDataTestExecutionListener:51 Deleting all data from OpenMRS DB tables... |
| 17-Jun-2025 22:21:20 | INFO 2025-06-17 22:21:20,156 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DbEventProcessorRouteTest:53 ******************************************************************************** |
| 17-Jun-2025 22:21:20 | INFO 2025-06-17 22:21:20,157 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DbEventProcessorRouteTest:54 Testing done: shouldProcessAnOrderThatHasAPreviousOrderIfThePreviousOrderIsInTheErrorQueueForAnotherDestination(org.openmrs.eip.mysql.watcher.route.DbEventProcessorRouteTest) |
| 17-Jun-2025 22:21:20 | INFO 2025-06-17 22:21:20,157 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DbEventProcessorRouteTest:55 Took: 0.128 seconds (128 millis) |
| 17-Jun-2025 22:21:20 | INFO 2025-06-17 22:21:20,157 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DbEventProcessorRouteTest:56 ******************************************************************************** |
| 17-Jun-2025 22:21:20 | INFO 2025-06-17 22:21:20,174 [openmrs-db-reconnect-watchdog] o.a.c.i.e.DefaultShutdownStrategy:197 Starting to graceful shutdown 1 routes (timeout 45 seconds) |
| 17-Jun-2025 22:21:20 | INFO 2025-06-17 22:21:20,174 [Camel (camel-2) thread #2 - ShutdownTask] o.a.c.i.e.DefaultShutdownStrategy:703 Route: test-error-handler shutdown complete, was consuming from: direct://test-error-handler |
| 17-Jun-2025 22:21:20 | INFO 2025-06-17 22:21:20,174 [openmrs-db-reconnect-watchdog] o.a.c.i.e.DefaultShutdownStrategy:262 Graceful shutdown of 1 routes completed in 0 seconds |
| 17-Jun-2025 22:21:20 | INFO 2025-06-17 22:21:20,174 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3167 Route: test-error-handler is stopped, was consuming from: direct://test-error-handler |
| 17-Jun-2025 22:21:20 | INFO 2025-06-17 22:21:20,175 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3167 Route: test-error-handler is shutdown and removed, was consuming from: direct://test-error-handler |
| 17-Jun-2025 22:21:20 | INFO 2025-06-17 22:21:20,178 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3423 Route: test-error-handler started and consuming from: direct://test-error-handler |
| 17-Jun-2025 22:21:20 | DEBUG 2025-06-17 22:21:20,183 [openmrs-db-reconnect-watchdog] db-event-processor:152 Processing db event: |
| 17-Jun-2025 22:21:20 | DEBUG 2025-06-17 22:21:20,183 [openmrs-db-reconnect-watchdog] o.o.e.m.w.IdentifierSettingProcessor:32 Received: Event {tableName=test_order, primaryKeyId=102, identifier=order_uuid, operation=c, snapshot=false} |
| 17-Jun-2025 22:21:20 | DEBUG 2025-06-17 22:21:20,183 [openmrs-db-reconnect-watchdog] o.o.e.m.w.IdentifierSettingProcessor:37 Event object already has the identifier set |
| 17-Jun-2025 22:21:20 | DEBUG 2025-06-17 22:21:20,184 [openmrs-db-reconnect-watchdog] db-event-processor:152 All DB Event destinations -> mock:event-listener |
| 17-Jun-2025 22:21:20 | DEBUG 2025-06-17 22:21:20,186 [openmrs-db-reconnect-watchdog] db-event-processor:152 Previous order id -> 101 |
| 17-Jun-2025 22:21:20 | INFO 2025-06-17 22:21:20,186 [openmrs-db-reconnect-watchdog] db-event-processor:158 Has previous entity with id -> 101, checking if previous order has no retry items |
| 17-Jun-2025 22:21:20 | INFO 2025-06-17 22:21:20,193 [openmrs-db-reconnect-watchdog] db-event-processor:158 Moving order event to the failure queue because its previous order has 1 event(s) in the retry queue for destination: mock:event-listener |
| 17-Jun-2025 22:21:20 | DEBUG 2025-06-17 22:21:20,198 [openmrs-db-reconnect-watchdog] db-event-processor:152 Done processing db event |
| 17-Jun-2025 22:21:20 | INFO 2025-06-17 22:21:20,199 [openmrs-db-reconnect-watchdog] o.a.c.c.m.MockEndpoint:390 Asserting: mock://event-listener is satisfied |
| 17-Jun-2025 22:21:20 | INFO 2025-06-17 22:21:20,199 [openmrs-db-reconnect-watchdog] o.o.e.BaseCamelTest:90 Log event satisfied -> [INFO] Moving order event to the failure queue because its previous order has 1 event(s) in the retry queue for destination: mock:event-listener |
| 17-Jun-2025 22:21:20 | INFO 2025-06-17 22:21:20,199 [openmrs-db-reconnect-watchdog] o.o.e.BaseCamelTest:90 Log event satisfied -> [DEBUG] Done processing db event |
| 17-Jun-2025 22:21:20 | DEBUG 2025-06-17 22:21:20,199 [openmrs-db-reconnect-watchdog] o.o.e.DeleteDataTestExecutionListener:44 Deleting all data from management DB tables... |
| 17-Jun-2025 22:21:20 | DEBUG 2025-06-17 22:21:20,200 [openmrs-db-reconnect-watchdog] o.o.e.DeleteDataTestExecutionListener:51 Deleting all data from OpenMRS DB tables... |
| 17-Jun-2025 22:21:20 | INFO 2025-06-17 22:21:20,232 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DbEventProcessorRouteTest:53 ******************************************************************************** |
| 17-Jun-2025 22:21:20 | INFO 2025-06-17 22:21:20,232 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DbEventProcessorRouteTest:54 Testing done: shouldNotProcessATestOrderThatHasAPreviousOrderIfThePreviousOrderIsInTheErrorQueueForTheDestination(org.openmrs.eip.mysql.watcher.route.DbEventProcessorRouteTest) |
| 17-Jun-2025 22:21:20 | INFO 2025-06-17 22:21:20,232 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DbEventProcessorRouteTest:55 Took: 0.074 seconds (74 millis) |
| 17-Jun-2025 22:21:20 | INFO 2025-06-17 22:21:20,232 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DbEventProcessorRouteTest:56 ******************************************************************************** |
| 17-Jun-2025 22:21:20 | INFO 2025-06-17 22:21:20,735 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:2828 Apache Camel 3.3.0 (CamelContext: camel-2) is shutting down |
| 17-Jun-2025 22:21:20 | INFO 2025-06-17 22:21:20,736 [openmrs-db-reconnect-watchdog] o.o.e.Utils:77 Received application shutting down event |
| 17-Jun-2025 22:21:20 | INFO 2025-06-17 22:21:20,736 [openmrs-db-reconnect-watchdog] o.a.c.i.e.DefaultShutdownStrategy:197 Starting to graceful shutdown 2 routes (timeout 45 seconds) |
| 17-Jun-2025 22:21:20 | INFO 2025-06-17 22:21:20,736 [Camel (camel-2) thread #2 - ShutdownTask] o.a.c.i.e.DefaultShutdownStrategy:703 Route: test-error-handler shutdown complete, was consuming from: direct://test-error-handler |
| 17-Jun-2025 22:21:20 | INFO 2025-06-17 22:21:20,747 [Camel (camel-2) thread #2 - ShutdownTask] o.a.c.i.e.DefaultShutdownStrategy:703 Route: db-event-processor shutdown complete, was consuming from: direct://db-event-processor |
| 17-Jun-2025 22:21:20 | INFO 2025-06-17 22:21:20,748 [openmrs-db-reconnect-watchdog] o.a.c.i.e.DefaultShutdownStrategy:262 Graceful shutdown of 2 routes completed in 0 seconds |
| 17-Jun-2025 22:21:20 | INFO 2025-06-17 22:21:20,751 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:2946 Apache Camel 3.3.0 (CamelContext: camel-2) uptime 2.430 seconds |
| 17-Jun-2025 22:21:20 | INFO 2025-06-17 22:21:20,751 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:2947 Apache Camel 3.3.0 (CamelContext: camel-2) is shutdown in 0.016 seconds |
| 17-Jun-2025 22:21:20 | INFO 2025-06-17 22:21:20,752 [openmrs-db-reconnect-watchdog] o.s.o.j.LocalContainerEntityManagerFactoryBean:598 Closing JPA EntityManagerFactory for persistence unit 'mngt' |
| 17-Jun-2025 22:21:20 | INFO 2025-06-17 22:21:20,754 [openmrs-db-reconnect-watchdog] o.s.o.j.LocalContainerEntityManagerFactoryBean:598 Closing JPA EntityManagerFactory for persistence unit 'openmrs' |
| 17-Jun-2025 22:21:20 | INFO 2025-06-17 22:21:20,754 [openmrs-db-reconnect-watchdog] c.z.h.HikariDataSource:350 HikariPool-4 - Shutdown initiated... |
| 17-Jun-2025 22:21:20 | INFO 2025-06-17 22:21:20,786 [openmrs-db-reconnect-watchdog] c.z.h.HikariDataSource:352 HikariPool-4 - Shutdown completed. |
| 17-Jun-2025 22:21:20 | INFO 2025-06-17 22:21:20,787 [openmrs-db-reconnect-watchdog] c.z.h.HikariDataSource:350 HikariPool-3 - Shutdown initiated... |
| 17-Jun-2025 22:21:20 | INFO 2025-06-17 22:21:20,815 [openmrs-db-reconnect-watchdog] c.z.h.HikariDataSource:352 HikariPool-3 - Shutdown completed. |
| 17-Jun-2025 22:21:20 | [INFO] Tests run: 14, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 18.426 s - in org.openmrs.eip.mysql.watcher.route.DbEventProcessorRouteTest |
| 17-Jun-2025 22:21:20 | [INFO] Running org.openmrs.eip.mysql.watcher.route.DbEventListenerRouteTest |
| 17-Jun-2025 22:21:20 | [WARNING] Tests run: 1, Failures: 0, Errors: 0, Skipped: 1, Time elapsed: 0 s - in org.openmrs.eip.mysql.watcher.route.DbEventListenerRouteTest |
| 17-Jun-2025 22:21:20 | [INFO] Running org.openmrs.eip.mysql.watcher.route.DebeziumRouteTest |
| 17-Jun-2025 22:21:20 | INFO 2025-06-17 22:21:20,819 [openmrs-db-reconnect-watchdog] o.s.b.t.c.SpringBootTestContextBootstrapper:308 Neither @ContextConfiguration nor @ContextHierarchy found for test class [org.openmrs.eip.mysql.watcher.route.DebeziumRouteTest], using SpringBootContextLoader |
| 17-Jun-2025 22:21:20 | INFO 2025-06-17 22:21:20,820 [openmrs-db-reconnect-watchdog] o.s.t.c.s.AbstractContextLoader:264 Could not detect default resource locations for test class [org.openmrs.eip.mysql.watcher.route.DebeziumRouteTest]: no resource found for suffixes {-context.xml, Context.groovy}. |
| 17-Jun-2025 22:21:20 | INFO 2025-06-17 22:21:20,832 [openmrs-db-reconnect-watchdog] o.s.b.t.c.SpringBootTestContextBootstrapper:177 Using TestExecutionListeners: [org.springframework.test.context.support.DirtiesContextBeforeModesTestExecutionListener@616d9a6d, org.springframework.boot.test.mock.mockito.MockitoTestExecutionListener@16263fb2, org.springframework.test.context.support.DependencyInjectionTestExecutionListener@52b4da7c, org.springframework.test.context.support.DirtiesContextTestExecutionListener@4620e669, org.springframework.boot.test.mock.mockito.ResetMocksTestExecutionListener@4b93a232, org.openmrs.eip.DeleteDataTestExecutionListener@6c6fdf5d, org.springframework.test.context.jdbc.SqlScriptsTestExecutionListener@187c8f96] |
| 17-Jun-2025 22:21:20 | INFO 2025-06-17 22:21:20,851 [testcontainers-lifecycle-0] ?.7.31]:357 Creating container for image: mysql:5.7.31 |
| 17-Jun-2025 22:21:21 | INFO 2025-06-17 22:21:21,060 [testcontainers-lifecycle-0] ?.7.31]:418 Starting container with ID: 4903011dbcccf34c9f0bd52f4721d3926ad2867d43a1027a8a7ac0f228d04be6 |
| 17-Jun-2025 22:21:21 | INFO 2025-06-17 22:21:21,552 [testcontainers-lifecycle-0] ?.7.31]:422 Container mysql:5.7.31 is starting: 4903011dbcccf34c9f0bd52f4721d3926ad2867d43a1027a8a7ac0f228d04be6 |
| 17-Jun-2025 22:21:21 | INFO 2025-06-17 22:21:21,556 [testcontainers-lifecycle-0] ?.7.31]:135 Waiting for database connection to become available at jdbc:mysql://localhost:32773/openmrs using query 'SELECT 1' |
| 17-Jun-2025 22:21:33 | INFO 2025-06-17 22:21:33,030 [testcontainers-lifecycle-0] ?.7.31]:167 Container is started (JDBC URL: jdbc:mysql://localhost:32773/openmrs) |
| 17-Jun-2025 22:21:33 | INFO 2025-06-17 22:21:33,031 [testcontainers-lifecycle-0] ?.7.31]:475 Container mysql:5.7.31 started in PT12.18S |
| 17-Jun-2025 22:21:33 | |
| 17-Jun-2025 22:21:33 | . ____ _ __ _ _ |
| 17-Jun-2025 22:21:33 | /\\ / ___'_ __ _ _(_)_ __ __ _ \ \ \ \ |
| 17-Jun-2025 22:21:33 | ( ( )\___ | '_ | '_| | '_ \/ _` | \ \ \ \ |
| 17-Jun-2025 22:21:33 | \\/ ___)| |_)| | | | | || (_| | ) ) ) ) |
| 17-Jun-2025 22:21:33 | ' |____| .__|_| |_|_| |_\__, | / / / / |
| 17-Jun-2025 22:21:33 | =========|_|==============|___/=/_/_/_/ |
| 17-Jun-2025 22:21:33 | :: Spring Boot :: (v2.3.0.RELEASE) |
| 17-Jun-2025 22:21:33 | |
| 17-Jun-2025 22:21:33 | INFO 2025-06-17 22:21:33,088 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DebeziumRouteTest:55 Starting DebeziumRouteTest on xindi with PID 808714 (started by bamboo-agent in /home/bamboo-agent/bamboo-agent/xml-data/build-dir/EIP-EIP0-JOB1/openmrs-watcher) |
| 17-Jun-2025 22:21:33 | DEBUG 2025-06-17 22:21:33,088 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DebeziumRouteTest:56 Running with Spring Boot v2.3.0.RELEASE, Spring v5.2.6.RELEASE |
| 17-Jun-2025 22:21:33 | INFO 2025-06-17 22:21:33,088 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DebeziumRouteTest:651 No active profile set, falling back to default profiles: default |
| 17-Jun-2025 22:21:33 | INFO 2025-06-17 22:21:33,608 [openmrs-db-reconnect-watchdog] o.s.d.r.c.RepositoryConfigurationDelegate:127 Bootstrapping Spring Data JPA repositories in DEFAULT mode. |
| 17-Jun-2025 22:21:33 | INFO 2025-06-17 22:21:33,609 [openmrs-db-reconnect-watchdog] o.s.d.r.c.RepositoryConfigurationDelegate:187 Finished Spring Data repository scanning in 0ms. Found 0 JPA repository interfaces. |
| 17-Jun-2025 22:21:33 | INFO 2025-06-17 22:21:33,725 [openmrs-db-reconnect-watchdog] o.s.c.s.PostProcessorRegistrationDelegate$BeanPostProcessorChecker:335 Bean 'org.apache.camel.spring.boot.CamelAutoConfiguration' of type [org.apache.camel.spring.boot.CamelAutoConfiguration] is not eligible for getting processed by all BeanPostProcessors (for example: not eligible for auto-proxying) |
| 17-Jun-2025 22:21:33 | INFO 2025-06-17 22:21:33,795 [openmrs-db-reconnect-watchdog] o.s.c.s.PostProcessorRegistrationDelegate$BeanPostProcessorChecker:335 Bean 'org.openmrs.eip.TestDBConfig' of type [org.openmrs.eip.TestDBConfig] is not eligible for getting processed by all BeanPostProcessors (for example: not eligible for auto-proxying) |
| 17-Jun-2025 22:21:33 | INFO 2025-06-17 22:21:33,881 [openmrs-db-reconnect-watchdog] c.z.h.HikariDataSource:110 HikariPool-5 - Starting... |
| 17-Jun-2025 22:21:33 | INFO 2025-06-17 22:21:33,889 [openmrs-db-reconnect-watchdog] c.z.h.HikariDataSource:123 HikariPool-5 - Start completed. |
| 17-Jun-2025 22:21:33 | INFO 2025-06-17 22:21:33,890 [openmrs-db-reconnect-watchdog] l.database:37 Set default schema name to PUBLIC |
| 17-Jun-2025 22:21:33 | INFO 2025-06-17 22:21:33,909 [openmrs-db-reconnect-watchdog] l.lockservice:37 Successfully acquired change log lock |
| 17-Jun-2025 22:21:33 | INFO 2025-06-17 22:21:33,986 [openmrs-db-reconnect-watchdog] l.changelog:37 Reading from PUBLIC.LIQUIBASECHANGELOG |
| 17-Jun-2025 22:21:33 | INFO 2025-06-17 22:21:33,989 [openmrs-db-reconnect-watchdog] l.command:37 Using deploymentId: 0198893987 |
| 17-Jun-2025 22:21:33 | Running Changeset: liquibase-watcher.xml::20200205-1001::wluyima |
| 17-Jun-2025 22:21:34 | INFO 2025-06-17 22:21:33,999 [openmrs-db-reconnect-watchdog] l.changelog:37 Marking ChangeSet: "liquibase-watcher.xml::20200205-1001::wluyima" as ran despite precondition failure due to onFail='MARK_RAN': |
| 17-Jun-2025 22:21:34 | liquibase-watcher.xml : Not precondition failed |
| 17-Jun-2025 22:21:34 | |
| 17-Jun-2025 22:21:34 | Running Changeset: liquibase-watcher.xml::20200205-1002::wluyima |
| 17-Jun-2025 22:21:34 | INFO 2025-06-17 22:21:34,018 [openmrs-db-reconnect-watchdog] l.changelog:37 Marking ChangeSet: "liquibase-watcher.xml::20200205-1002::wluyima" as ran despite precondition failure due to onFail='MARK_RAN': |
| 17-Jun-2025 22:21:34 | liquibase-watcher.xml : Not precondition failed |
| 17-Jun-2025 22:21:34 | |
| 17-Jun-2025 22:21:34 | Running Changeset: liquibase-watcher.xml::20200205-1003::wluyima |
| 17-Jun-2025 22:21:34 | INFO 2025-06-17 22:21:34,021 [openmrs-db-reconnect-watchdog] l.changelog:37 Marking ChangeSet: "liquibase-watcher.xml::20200205-1003::wluyima" as ran despite precondition failure due to onFail='MARK_RAN': |
| 17-Jun-2025 22:21:34 | liquibase-watcher.xml : Not precondition failed |
| 17-Jun-2025 22:21:34 | |
| 17-Jun-2025 22:21:34 | Running Changeset: liquibase-watcher.xml::20210827-1200::wluyima |
| 17-Jun-2025 22:21:34 | INFO 2025-06-17 22:21:34,025 [openmrs-db-reconnect-watchdog] l.changelog:37 Marking ChangeSet: "liquibase-watcher.xml::20210827-1200::wluyima" as ran despite precondition failure due to onFail='MARK_RAN': |
| 17-Jun-2025 22:21:34 | liquibase-watcher.xml : Not precondition failed |
| 17-Jun-2025 22:21:34 | |
| 17-Jun-2025 22:21:34 | INFO 2025-06-17 22:21:34,026 [openmrs-db-reconnect-watchdog] l.util:37 UPDATE SUMMARY |
| 17-Jun-2025 22:21:34 | INFO 2025-06-17 22:21:34,026 [openmrs-db-reconnect-watchdog] l.util:37 Run: 4 |
| 17-Jun-2025 22:21:34 | INFO 2025-06-17 22:21:34,026 [openmrs-db-reconnect-watchdog] l.util:37 Previously run: 0 |
| 17-Jun-2025 22:21:34 | INFO 2025-06-17 22:21:34,026 [openmrs-db-reconnect-watchdog] l.util:37 Filtered out: 1 |
| 17-Jun-2025 22:21:34 | INFO 2025-06-17 22:21:34,027 [openmrs-db-reconnect-watchdog] l.util:37 ------------------------------- |
| 17-Jun-2025 22:21:34 | INFO 2025-06-17 22:21:34,027 [openmrs-db-reconnect-watchdog] l.util:37 Total change sets: 5 |
| 17-Jun-2025 22:21:34 | |
| 17-Jun-2025 22:21:34 | INFO 2025-06-17 22:21:34,027 [openmrs-db-reconnect-watchdog] l.util:37 |
| 17-Jun-2025 22:21:34 | FILTERED CHANGE SETS SUMMARY |
| 17-Jun-2025 22:21:34 | |
| 17-Jun-2025 22:21:34 | INFO 2025-06-17 22:21:34,027 [openmrs-db-reconnect-watchdog] l.util:37 DBMS mismatch: 1 |
| 17-Jun-2025 22:21:34 | |
| 17-Jun-2025 22:21:34 | UPDATE SUMMARY |
| 17-Jun-2025 22:21:34 | Run: 4 |
| 17-Jun-2025 22:21:34 | Previously run: 0 |
| 17-Jun-2025 22:21:34 | Filtered out: 1 |
| 17-Jun-2025 22:21:34 | ------------------------------- |
| 17-Jun-2025 22:21:34 | Total change sets: 5 |
| 17-Jun-2025 22:21:34 | |
| 17-Jun-2025 22:21:34 | |
| 17-Jun-2025 22:21:34 | FILTERED CHANGE SETS SUMMARY |
| 17-Jun-2025 22:21:34 | |
| 17-Jun-2025 22:21:34 | DBMS mismatch: 1 |
| 17-Jun-2025 22:21:34 | |
| 17-Jun-2025 22:21:34 | INFO 2025-06-17 22:21:34,027 [openmrs-db-reconnect-watchdog] l.util:37 'liquibase-watcher.xml::20210827-1100::wluyima' : mismatched DBMS value of 'mysql' |
| 17-Jun-2025 22:21:34 | INFO 2025-06-17 22:21:34,027 [openmrs-db-reconnect-watchdog] l.util:37 Update summary generated |
| 17-Jun-2025 22:21:34 | INFO 2025-06-17 22:21:34,028 [openmrs-db-reconnect-watchdog] l.command:37 Update command completed successfully. |
| 17-Jun-2025 22:21:34 | Liquibase: Update has been successful. |
| 17-Jun-2025 22:21:34 | INFO 2025-06-17 22:21:34,029 [openmrs-db-reconnect-watchdog] l.lockservice:37 Successfully released change log lock |
| 17-Jun-2025 22:21:34 | INFO 2025-06-17 22:21:34,030 [openmrs-db-reconnect-watchdog] l.lockservice:37 Successfully released change log lock |
| 17-Jun-2025 22:21:34 | INFO 2025-06-17 22:21:34,030 [openmrs-db-reconnect-watchdog] l.command:37 Command execution complete |
| 17-Jun-2025 22:21:34 | INFO 2025-06-17 22:21:34,057 [openmrs-db-reconnect-watchdog] o.h.j.i.u.LogHelper:31 HHH000204: Processing PersistenceUnitInfo [name: mngt] |
| 17-Jun-2025 22:21:34 | INFO 2025-06-17 22:21:34,080 [openmrs-db-reconnect-watchdog] o.h.d.Dialect:170 HHH000400: Using dialect: org.hibernate.dialect.H2Dialect |
| 17-Jun-2025 22:21:34 | INFO 2025-06-17 22:21:34,184 [openmrs-db-reconnect-watchdog] o.h.e.t.j.p.i.JtaPlatformInitiator:52 HHH000490: Using JtaPlatform implementation: [org.hibernate.engine.transaction.jta.platform.internal.NoJtaPlatform] |
| 17-Jun-2025 22:21:34 | INFO 2025-06-17 22:21:34,185 [openmrs-db-reconnect-watchdog] o.s.o.j.LocalContainerEntityManagerFactoryBean:416 Initialized JPA EntityManagerFactory for persistence unit 'mngt' |
| 17-Jun-2025 22:21:34 | INFO 2025-06-17 22:21:34,188 [openmrs-db-reconnect-watchdog] o.o.e.m.w.AuditableEventFilter:37 Auditable tables to filter -> [] |
| 17-Jun-2025 22:21:34 | INFO 2025-06-17 22:21:34,190 [openmrs-db-reconnect-watchdog] o.o.e.m.w.DebeziumMessageProcessor:32 Event Filters -> [org.openmrs.eip.mysql.watcher.AuditableEventFilter@4377b35b] |
| 17-Jun-2025 22:21:34 | INFO 2025-06-17 22:21:34,311 [openmrs-db-reconnect-watchdog] o.a.c.m.DefaultConfigurationConfigurer:423 Adding custom EventNotifier with id: shutdownListener and implementation: org.openmrs.eip.mysql.watcher.ShutdownListener@656dc20f |
| 17-Jun-2025 22:21:34 | INFO 2025-06-17 22:21:34,973 [openmrs-db-reconnect-watchdog] o.a.c.m.JmxManagementStrategy:115 JMX is enabled |
| 17-Jun-2025 22:21:34 | INFO 2025-06-17 22:21:34,974 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:2635 Apache Camel 3.3.0 (CamelContext: camel-3) is starting |
| 17-Jun-2025 22:21:34 | INFO 2025-06-17 22:21:34,980 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:2778 StreamCaching is not in use. If using streams then its recommended to enable stream caching. See more details at http://camel.apache.org/stream-caching.html |
| 17-Jun-2025 22:21:34 | INFO 2025-06-17 22:21:34,981 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:2687 Total 0 routes, of which 0 are started |
| 17-Jun-2025 22:21:34 | INFO 2025-06-17 22:21:34,981 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:2692 Apache Camel 3.3.0 (CamelContext: camel-3) started in 0.007 seconds |
| 17-Jun-2025 22:21:34 | INFO 2025-06-17 22:21:34,982 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DebeziumRouteTest:61 Started DebeziumRouteTest in 1.948 seconds (JVM running for 82.017) |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,178 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3423 Route: test-error-handler started and consuming from: direct://test-error-handler |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,193 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DebeziumRoute:41 Starting debezium... |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,193 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DebeziumRoute:45 Setting debezium route error handler to: watcherErrorHandler |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,239 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3423 Route: debezium-route started and consuming from: direct://debezium-route |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,248 [openmrs-db-reconnect-watchdog] o.a.c.i.e.DefaultShutdownStrategy:197 Starting to graceful shutdown 1 routes (timeout 45 seconds) |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,249 [Camel (camel-3) thread #3 - ShutdownTask] o.a.c.i.e.DefaultShutdownStrategy:703 Route: debezium-route shutdown complete, was consuming from: direct://debezium-route |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,250 [openmrs-db-reconnect-watchdog] o.a.c.i.e.DefaultShutdownStrategy:262 Graceful shutdown of 1 routes completed in 0 seconds |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,250 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3167 Route: debezium-route is stopped, was consuming from: direct://debezium-route |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,251 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3167 Route: debezium-route is shutdown and removed, was consuming from: direct://debezium-route |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,252 [openmrs-db-reconnect-watchdog] o.a.c.b.AdviceWithTasks:207 AdviceWith (ProcessDefinition) : [process[ref:debezium-msg-processor]] --> replace [To[mock://debezium-msg-processor]] |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,252 [openmrs-db-reconnect-watchdog] o.a.c.b.AdviceWithTasks:207 AdviceWith (ProcessDefinition) : [process[ref:id-setting-event-processor]] --> replace [To[mock://id-setting-event-processor]] |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,296 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3423 Route: debezium-route started and consuming from: direct://debezium-route |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,304 [openmrs-db-reconnect-watchdog] o.a.c.c.m.MockEndpoint:390 Asserting: mock://debezium-msg-processor is satisfied |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,304 [openmrs-db-reconnect-watchdog] o.a.c.c.m.MockEndpoint:390 Asserting: mock://id-setting-event-processor is satisfied |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,304 [openmrs-db-reconnect-watchdog] o.a.c.c.m.MockEndpoint:390 Asserting: mock://debezium-event-listener is satisfied |
| 17-Jun-2025 22:21:35 | DEBUG 2025-06-17 22:21:35,305 [openmrs-db-reconnect-watchdog] o.o.e.DeleteDataTestExecutionListener:44 Deleting all data from management DB tables... |
| 17-Jun-2025 22:21:35 | DEBUG 2025-06-17 22:21:35,315 [openmrs-db-reconnect-watchdog] o.o.e.DeleteDataTestExecutionListener:51 Deleting all data from OpenMRS DB tables... |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,315 [openmrs-db-reconnect-watchdog] c.z.h.HikariDataSource:110 HikariPool-6 - Starting... |
| 17-Jun-2025 22:21:35 | WARN 2025-06-17 22:21:35,315 [openmrs-db-reconnect-watchdog] c.z.h.u.DriverDataSource:70 Registered driver with driverClassName=com.mysql.jdbc.Driver was not found, trying direct instantiation. |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,339 [openmrs-db-reconnect-watchdog] c.z.h.HikariDataSource:123 HikariPool-6 - Start completed. |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,347 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DebeziumRouteTest:53 ******************************************************************************** |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,347 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DebeziumRouteTest:54 Testing done: shouldProcessTheEventIfSkipIsSetToFalse(org.openmrs.eip.mysql.watcher.route.DebeziumRouteTest) |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,347 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DebeziumRouteTest:55 Took: 0.331 seconds (331 millis) |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,347 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DebeziumRouteTest:56 ******************************************************************************** |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,352 [openmrs-db-reconnect-watchdog] o.a.c.i.e.DefaultShutdownStrategy:197 Starting to graceful shutdown 1 routes (timeout 45 seconds) |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,353 [Camel (camel-3) thread #3 - ShutdownTask] o.a.c.i.e.DefaultShutdownStrategy:703 Route: test-error-handler shutdown complete, was consuming from: direct://test-error-handler |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,354 [openmrs-db-reconnect-watchdog] o.a.c.i.e.DefaultShutdownStrategy:262 Graceful shutdown of 1 routes completed in 0 seconds |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,354 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3167 Route: test-error-handler is stopped, was consuming from: direct://test-error-handler |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,355 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3167 Route: test-error-handler is shutdown and removed, was consuming from: direct://test-error-handler |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,363 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3423 Route: test-error-handler started and consuming from: direct://test-error-handler |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,364 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DebeziumRoute:41 Starting debezium... |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,364 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DebeziumRoute:45 Setting debezium route error handler to: watcherErrorHandler |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,364 [openmrs-db-reconnect-watchdog] o.a.c.i.e.DefaultShutdownStrategy:197 Starting to graceful shutdown 1 routes (timeout 45 seconds) |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,364 [Camel (camel-3) thread #3 - ShutdownTask] o.a.c.i.e.DefaultShutdownStrategy:703 Route: debezium-route shutdown complete, was consuming from: direct://debezium-route |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,366 [openmrs-db-reconnect-watchdog] o.a.c.i.e.DefaultShutdownStrategy:262 Graceful shutdown of 1 routes completed in 0 seconds |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,366 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3167 Route: debezium-route is stopped, was consuming from: direct://debezium-route |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,372 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3167 Route: debezium-route is shutdown and removed, was consuming from: direct://debezium-route |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,404 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3423 Route: debezium-route started and consuming from: direct://debezium-route |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,404 [openmrs-db-reconnect-watchdog] o.a.c.i.e.DefaultShutdownStrategy:197 Starting to graceful shutdown 1 routes (timeout 45 seconds) |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,405 [Camel (camel-3) thread #3 - ShutdownTask] o.a.c.i.e.DefaultShutdownStrategy:703 Route: debezium-route shutdown complete, was consuming from: direct://debezium-route |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,405 [openmrs-db-reconnect-watchdog] o.a.c.i.e.DefaultShutdownStrategy:262 Graceful shutdown of 1 routes completed in 0 seconds |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,407 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3167 Route: debezium-route is stopped, was consuming from: direct://debezium-route |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,409 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3167 Route: debezium-route is shutdown and removed, was consuming from: direct://debezium-route |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,409 [openmrs-db-reconnect-watchdog] o.a.c.b.AdviceWithTasks:207 AdviceWith (ProcessDefinition) : [process[ref:debezium-msg-processor]] --> replace [To[mock://debezium-msg-processor]] |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,409 [openmrs-db-reconnect-watchdog] o.a.c.b.AdviceWithTasks:207 AdviceWith (ProcessDefinition) : [process[ref:id-setting-event-processor]] --> replace [To[mock://id-setting-event-processor]] |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,439 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3423 Route: debezium-route started and consuming from: direct://debezium-route |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,440 [openmrs-db-reconnect-watchdog] o.a.c.c.m.MockEndpoint:390 Asserting: mock://debezium-msg-processor is satisfied |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,445 [openmrs-db-reconnect-watchdog] o.a.c.c.m.MockEndpoint:390 Asserting: mock://id-setting-event-processor is satisfied |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,445 [openmrs-db-reconnect-watchdog] o.a.c.c.m.MockEndpoint:390 Asserting: mock://debezium-event-listener is satisfied |
| 17-Jun-2025 22:21:35 | DEBUG 2025-06-17 22:21:35,445 [openmrs-db-reconnect-watchdog] o.o.e.DeleteDataTestExecutionListener:44 Deleting all data from management DB tables... |
| 17-Jun-2025 22:21:35 | DEBUG 2025-06-17 22:21:35,455 [openmrs-db-reconnect-watchdog] o.o.e.DeleteDataTestExecutionListener:51 Deleting all data from OpenMRS DB tables... |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,461 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DebeziumRouteTest:53 ******************************************************************************** |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,466 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DebeziumRouteTest:54 Testing done: shouldNotProcessTheEventIfMarkedForSkipping(org.openmrs.eip.mysql.watcher.route.DebeziumRouteTest) |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,467 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DebeziumRouteTest:55 Took: 0.113 seconds (113 millis) |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,467 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DebeziumRouteTest:56 ******************************************************************************** |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,481 [openmrs-db-reconnect-watchdog] o.a.c.i.e.DefaultShutdownStrategy:197 Starting to graceful shutdown 1 routes (timeout 45 seconds) |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,482 [Camel (camel-3) thread #3 - ShutdownTask] o.a.c.i.e.DefaultShutdownStrategy:703 Route: test-error-handler shutdown complete, was consuming from: direct://test-error-handler |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,482 [openmrs-db-reconnect-watchdog] o.a.c.i.e.DefaultShutdownStrategy:262 Graceful shutdown of 1 routes completed in 0 seconds |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,483 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3167 Route: test-error-handler is stopped, was consuming from: direct://test-error-handler |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,485 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3167 Route: test-error-handler is shutdown and removed, was consuming from: direct://test-error-handler |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,489 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3423 Route: test-error-handler started and consuming from: direct://test-error-handler |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,495 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DebeziumRoute:41 Starting debezium... |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,495 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DebeziumRoute:45 Setting debezium route error handler to: watcherErrorHandler |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,495 [openmrs-db-reconnect-watchdog] o.a.c.i.e.DefaultShutdownStrategy:197 Starting to graceful shutdown 1 routes (timeout 45 seconds) |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,495 [Camel (camel-3) thread #3 - ShutdownTask] o.a.c.i.e.DefaultShutdownStrategy:703 Route: debezium-route shutdown complete, was consuming from: direct://debezium-route |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,496 [openmrs-db-reconnect-watchdog] o.a.c.i.e.DefaultShutdownStrategy:262 Graceful shutdown of 1 routes completed in 0 seconds |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,499 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3167 Route: debezium-route is stopped, was consuming from: direct://debezium-route |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,501 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3167 Route: debezium-route is shutdown and removed, was consuming from: direct://debezium-route |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,542 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3423 Route: debezium-route started and consuming from: direct://debezium-route |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,542 [openmrs-db-reconnect-watchdog] o.a.c.i.e.DefaultShutdownStrategy:197 Starting to graceful shutdown 1 routes (timeout 45 seconds) |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,542 [Camel (camel-3) thread #3 - ShutdownTask] o.a.c.i.e.DefaultShutdownStrategy:703 Route: debezium-route shutdown complete, was consuming from: direct://debezium-route |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,542 [openmrs-db-reconnect-watchdog] o.a.c.i.e.DefaultShutdownStrategy:262 Graceful shutdown of 1 routes completed in 0 seconds |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,545 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3167 Route: debezium-route is stopped, was consuming from: direct://debezium-route |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,547 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3167 Route: debezium-route is shutdown and removed, was consuming from: direct://debezium-route |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,547 [openmrs-db-reconnect-watchdog] o.a.c.b.AdviceWithTasks:207 AdviceWith (ProcessDefinition) : [process[ref:debezium-msg-processor]] --> replace [To[mock://debezium-msg-processor]] |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,547 [openmrs-db-reconnect-watchdog] o.a.c.b.AdviceWithTasks:207 AdviceWith (ProcessDefinition) : [process[ref:id-setting-event-processor]] --> replace [To[mock://id-setting-event-processor]] |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,588 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3423 Route: debezium-route started and consuming from: direct://debezium-route |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,588 [openmrs-db-reconnect-watchdog] o.a.c.c.m.MockEndpoint:390 Asserting: mock://debezium-msg-processor is satisfied |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,588 [openmrs-db-reconnect-watchdog] o.a.c.c.m.MockEndpoint:390 Asserting: mock://id-setting-event-processor is satisfied |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,588 [openmrs-db-reconnect-watchdog] o.a.c.c.m.MockEndpoint:390 Asserting: mock://debezium-event-listener is satisfied |
| 17-Jun-2025 22:21:35 | DEBUG 2025-06-17 22:21:35,589 [openmrs-db-reconnect-watchdog] o.o.e.DeleteDataTestExecutionListener:44 Deleting all data from management DB tables... |
| 17-Jun-2025 22:21:35 | DEBUG 2025-06-17 22:21:35,592 [openmrs-db-reconnect-watchdog] o.o.e.DeleteDataTestExecutionListener:51 Deleting all data from OpenMRS DB tables... |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,594 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DebeziumRouteTest:53 ******************************************************************************** |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,594 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DebeziumRouteTest:54 Testing done: shouldProcessTheEventIfSkipIsSetToNull(org.openmrs.eip.mysql.watcher.route.DebeziumRouteTest) |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,594 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DebeziumRouteTest:55 Took: 0.125 seconds (125 millis) |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,594 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DebeziumRouteTest:56 ******************************************************************************** |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,599 [openmrs-db-reconnect-watchdog] o.a.c.i.e.DefaultShutdownStrategy:197 Starting to graceful shutdown 1 routes (timeout 45 seconds) |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,599 [Camel (camel-3) thread #3 - ShutdownTask] o.a.c.i.e.DefaultShutdownStrategy:703 Route: test-error-handler shutdown complete, was consuming from: direct://test-error-handler |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,600 [openmrs-db-reconnect-watchdog] o.a.c.i.e.DefaultShutdownStrategy:262 Graceful shutdown of 1 routes completed in 0 seconds |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,600 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3167 Route: test-error-handler is stopped, was consuming from: direct://test-error-handler |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,600 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3167 Route: test-error-handler is shutdown and removed, was consuming from: direct://test-error-handler |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,604 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3423 Route: test-error-handler started and consuming from: direct://test-error-handler |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,605 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DebeziumRoute:41 Starting debezium... |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,605 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DebeziumRoute:45 Setting debezium route error handler to: watcherErrorHandler |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,605 [openmrs-db-reconnect-watchdog] o.a.c.i.e.DefaultShutdownStrategy:197 Starting to graceful shutdown 1 routes (timeout 45 seconds) |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,605 [Camel (camel-3) thread #3 - ShutdownTask] o.a.c.i.e.DefaultShutdownStrategy:703 Route: debezium-route shutdown complete, was consuming from: direct://debezium-route |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,606 [openmrs-db-reconnect-watchdog] o.a.c.i.e.DefaultShutdownStrategy:262 Graceful shutdown of 1 routes completed in 0 seconds |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,607 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3167 Route: debezium-route is stopped, was consuming from: direct://debezium-route |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,607 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3167 Route: debezium-route is shutdown and removed, was consuming from: direct://debezium-route |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,630 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3423 Route: debezium-route started and consuming from: direct://debezium-route |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,630 [openmrs-db-reconnect-watchdog] o.a.c.i.e.DefaultShutdownStrategy:197 Starting to graceful shutdown 1 routes (timeout 45 seconds) |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,631 [Camel (camel-3) thread #3 - ShutdownTask] o.a.c.i.e.DefaultShutdownStrategy:703 Route: debezium-route shutdown complete, was consuming from: direct://debezium-route |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,631 [openmrs-db-reconnect-watchdog] o.a.c.i.e.DefaultShutdownStrategy:262 Graceful shutdown of 1 routes completed in 0 seconds |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,631 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3167 Route: debezium-route is stopped, was consuming from: direct://debezium-route |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,632 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3167 Route: debezium-route is shutdown and removed, was consuming from: direct://debezium-route |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,632 [openmrs-db-reconnect-watchdog] o.a.c.b.AdviceWithTasks:207 AdviceWith (ProcessDefinition) : [process[ref:debezium-msg-processor]] --> replace [To[mock://debezium-msg-processor]] |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,632 [openmrs-db-reconnect-watchdog] o.a.c.b.AdviceWithTasks:207 AdviceWith (ProcessDefinition) : [process[ref:id-setting-event-processor]] --> replace [To[mock://id-setting-event-processor]] |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,651 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3423 Route: debezium-route started and consuming from: direct://debezium-route |
| 17-Jun-2025 22:21:35 | DEBUG 2025-06-17 22:21:35,654 [openmrs-db-reconnect-watchdog] o.o.e.m.w.CustomFileOffsetBackingStore:21 Disabled saving of offsets |
| 17-Jun-2025 22:21:35 | DEBUG 2025-06-17 22:21:35,655 [openmrs-db-reconnect-watchdog] debezium-route:152 Deferring DB event because an error was encountered while processing a previous one |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,655 [openmrs-db-reconnect-watchdog] o.a.c.c.m.MockEndpoint:390 Asserting: mock://debezium-msg-processor is satisfied |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,656 [openmrs-db-reconnect-watchdog] o.a.c.c.m.MockEndpoint:390 Asserting: mock://id-setting-event-processor is satisfied |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,656 [openmrs-db-reconnect-watchdog] o.a.c.c.m.MockEndpoint:390 Asserting: mock://debezium-event-listener is satisfied |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,656 [openmrs-db-reconnect-watchdog] o.o.e.BaseCamelTest:90 Log event satisfied -> [DEBUG] Deferring DB event because an error was encountered while processing a previous one |
| 17-Jun-2025 22:21:35 | DEBUG 2025-06-17 22:21:35,656 [openmrs-db-reconnect-watchdog] o.o.e.DeleteDataTestExecutionListener:44 Deleting all data from management DB tables... |
| 17-Jun-2025 22:21:35 | DEBUG 2025-06-17 22:21:35,666 [openmrs-db-reconnect-watchdog] o.o.e.DeleteDataTestExecutionListener:51 Deleting all data from OpenMRS DB tables... |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,672 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DebeziumRouteTest:53 ******************************************************************************** |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,672 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DebeziumRouteTest:54 Testing done: shouldNotProcessTheEventIfTheOffsetBackingStoreStoreIsDisabled(org.openmrs.eip.mysql.watcher.route.DebeziumRouteTest) |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,673 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DebeziumRouteTest:55 Took: 0.076 seconds (76 millis) |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,673 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DebeziumRouteTest:56 ******************************************************************************** |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,678 [openmrs-db-reconnect-watchdog] o.a.c.i.e.DefaultShutdownStrategy:197 Starting to graceful shutdown 1 routes (timeout 45 seconds) |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,678 [Camel (camel-3) thread #3 - ShutdownTask] o.a.c.i.e.DefaultShutdownStrategy:703 Route: test-error-handler shutdown complete, was consuming from: direct://test-error-handler |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,678 [openmrs-db-reconnect-watchdog] o.a.c.i.e.DefaultShutdownStrategy:262 Graceful shutdown of 1 routes completed in 0 seconds |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,679 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3167 Route: test-error-handler is stopped, was consuming from: direct://test-error-handler |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,679 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3167 Route: test-error-handler is shutdown and removed, was consuming from: direct://test-error-handler |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,682 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3423 Route: test-error-handler started and consuming from: direct://test-error-handler |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,691 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DebeziumRoute:41 Starting debezium... |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,691 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DebeziumRoute:45 Setting debezium route error handler to: watcherErrorHandler |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,691 [openmrs-db-reconnect-watchdog] o.a.c.i.e.DefaultShutdownStrategy:197 Starting to graceful shutdown 1 routes (timeout 45 seconds) |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,692 [Camel (camel-3) thread #3 - ShutdownTask] o.a.c.i.e.DefaultShutdownStrategy:703 Route: debezium-route shutdown complete, was consuming from: direct://debezium-route |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,692 [openmrs-db-reconnect-watchdog] o.a.c.i.e.DefaultShutdownStrategy:262 Graceful shutdown of 1 routes completed in 0 seconds |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,692 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3167 Route: debezium-route is stopped, was consuming from: direct://debezium-route |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,693 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3167 Route: debezium-route is shutdown and removed, was consuming from: direct://debezium-route |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,725 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3423 Route: debezium-route started and consuming from: direct://debezium-route |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,725 [openmrs-db-reconnect-watchdog] o.a.c.i.e.DefaultShutdownStrategy:197 Starting to graceful shutdown 1 routes (timeout 45 seconds) |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,726 [Camel (camel-3) thread #3 - ShutdownTask] o.a.c.i.e.DefaultShutdownStrategy:703 Route: debezium-route shutdown complete, was consuming from: direct://debezium-route |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,734 [openmrs-db-reconnect-watchdog] o.a.c.i.e.DefaultShutdownStrategy:262 Graceful shutdown of 1 routes completed in 0 seconds |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,735 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3167 Route: debezium-route is stopped, was consuming from: direct://debezium-route |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,735 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3167 Route: debezium-route is shutdown and removed, was consuming from: direct://debezium-route |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,735 [openmrs-db-reconnect-watchdog] o.a.c.b.AdviceWithTasks:207 AdviceWith (ProcessDefinition) : [process[ref:debezium-msg-processor]] --> replace [To[mock://debezium-msg-processor]] |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,736 [openmrs-db-reconnect-watchdog] o.a.c.b.AdviceWithTasks:207 AdviceWith (ProcessDefinition) : [process[ref:id-setting-event-processor]] --> replace [To[mock://id-setting-event-processor]] |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,757 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:3423 Route: debezium-route started and consuming from: direct://debezium-route |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,758 [openmrs-db-reconnect-watchdog] o.a.c.c.m.MockEndpoint:390 Asserting: mock://debezium-msg-processor is satisfied |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,758 [openmrs-db-reconnect-watchdog] o.a.c.c.m.MockEndpoint:390 Asserting: mock://id-setting-event-processor is satisfied |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,758 [openmrs-db-reconnect-watchdog] o.a.c.c.m.MockEndpoint:390 Asserting: mock://debezium-event-listener is satisfied |
| 17-Jun-2025 22:21:35 | DEBUG 2025-06-17 22:21:35,758 [openmrs-db-reconnect-watchdog] o.o.e.DeleteDataTestExecutionListener:44 Deleting all data from management DB tables... |
| 17-Jun-2025 22:21:35 | DEBUG 2025-06-17 22:21:35,759 [openmrs-db-reconnect-watchdog] o.o.e.DeleteDataTestExecutionListener:51 Deleting all data from OpenMRS DB tables... |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,761 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DebeziumRouteTest:53 ******************************************************************************** |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,762 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DebeziumRouteTest:54 Testing done: shouldProcessTheEvent(org.openmrs.eip.mysql.watcher.route.DebeziumRouteTest) |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,762 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DebeziumRouteTest:55 Took: 0.087 seconds (87 millis) |
| 17-Jun-2025 22:21:35 | INFO 2025-06-17 22:21:35,762 [openmrs-db-reconnect-watchdog] o.o.e.m.w.r.DebeziumRouteTest:56 ******************************************************************************** |
| 17-Jun-2025 22:21:36 | INFO 2025-06-17 22:21:36,240 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:2828 Apache Camel 3.3.0 (CamelContext: camel-3) is shutting down |
| 17-Jun-2025 22:21:36 | INFO 2025-06-17 22:21:36,241 [openmrs-db-reconnect-watchdog] o.o.e.Utils:77 Received application shutting down event |
| 17-Jun-2025 22:21:36 | INFO 2025-06-17 22:21:36,241 [openmrs-db-reconnect-watchdog] o.a.c.i.e.DefaultShutdownStrategy:197 Starting to graceful shutdown 2 routes (timeout 45 seconds) |
| 17-Jun-2025 22:21:36 | INFO 2025-06-17 22:21:36,242 [Camel (camel-3) thread #3 - ShutdownTask] o.a.c.i.e.DefaultShutdownStrategy:703 Route: debezium-route shutdown complete, was consuming from: direct://debezium-route |
| 17-Jun-2025 22:21:36 | INFO 2025-06-17 22:21:36,242 [Camel (camel-3) thread #3 - ShutdownTask] o.a.c.i.e.DefaultShutdownStrategy:703 Route: test-error-handler shutdown complete, was consuming from: direct://test-error-handler |
| 17-Jun-2025 22:21:36 | INFO 2025-06-17 22:21:36,242 [openmrs-db-reconnect-watchdog] o.a.c.i.e.DefaultShutdownStrategy:262 Graceful shutdown of 2 routes completed in 0 seconds |
| 17-Jun-2025 22:21:36 | INFO 2025-06-17 22:21:36,245 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:2946 Apache Camel 3.3.0 (CamelContext: camel-3) uptime 1.271 seconds |
| 17-Jun-2025 22:21:36 | INFO 2025-06-17 22:21:36,245 [openmrs-db-reconnect-watchdog] o.a.c.i.e.AbstractCamelContext:2947 Apache Camel 3.3.0 (CamelContext: camel-3) is shutdown in 0.005 seconds |
| 17-Jun-2025 22:21:36 | INFO 2025-06-17 22:21:36,255 [openmrs-db-reconnect-watchdog] o.s.o.j.LocalContainerEntityManagerFactoryBean:598 Closing JPA EntityManagerFactory for persistence unit 'mngt' |
| 17-Jun-2025 22:21:36 | INFO 2025-06-17 22:21:36,255 [openmrs-db-reconnect-watchdog] c.z.h.HikariDataSource:350 HikariPool-5 - Shutdown initiated... |
| 17-Jun-2025 22:21:36 | INFO 2025-06-17 22:21:36,262 [openmrs-db-reconnect-watchdog] c.z.h.HikariDataSource:352 HikariPool-5 - Shutdown completed. |
| 17-Jun-2025 22:21:36 | INFO 2025-06-17 22:21:36,263 [openmrs-db-reconnect-watchdog] c.z.h.HikariDataSource:350 HikariPool-6 - Shutdown initiated... |
| 17-Jun-2025 22:21:36 | INFO 2025-06-17 22:21:36,277 [openmrs-db-reconnect-watchdog] c.z.h.HikariDataSource:352 HikariPool-6 - Shutdown completed. |
| 17-Jun-2025 22:21:36 | [INFO] Tests run: 5, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 15.393 s - in org.openmrs.eip.mysql.watcher.route.DebeziumRouteTest |
| 17-Jun-2025 22:21:36 | [INFO] Running org.openmrs.eip.mysql.watcher.OpenmrsDbReconnectHandlerTest |
| 17-Jun-2025 22:21:38 | ERROR 2025-06-17 22:21:38,799 [openmrs-db-reconnect-handler] o.o.e.m.w.OpenmrsDbReconnectHandler:40 Failed to resume debezium route |
| 17-Jun-2025 22:21:38 | org.openmrs.eip.EIPException: test |
| 17-Jun-2025 22:21:38 | at org.openmrs.eip.mysql.watcher.OpenmrsDbReconnectHandler.run(OpenmrsDbReconnectHandler.java:37) |
| 17-Jun-2025 22:21:38 | at org.openmrs.eip.mysql.watcher.OpenmrsDbReconnectHandlerTest.run_shouldFailIfTheDebeziumRouteCannotBeResumed(OpenmrsDbReconnectHandlerTest.java:53) |
| 17-Jun-2025 22:21:38 | at org.junit.internal.runners.TestMethod.invoke(TestMethod.java:68) |
| 17-Jun-2025 22:21:38 | at org.junit.internal.runners.MethodRoadie$2.run(MethodRoadie.java:89) |
| 17-Jun-2025 22:21:38 | at org.junit.internal.runners.MethodRoadie.runBeforesThenTestThenAfters(MethodRoadie.java:97) |
| 17-Jun-2025 22:21:38 | at org.junit.internal.runners.MethodRoadie.runTest(MethodRoadie.java:87) |
| 17-Jun-2025 22:21:38 | at org.junit.internal.runners.MethodRoadie.run(MethodRoadie.java:50) |
| 17-Jun-2025 22:21:38 | at org.junit.internal.runners.ClassRoadie.runUnprotected(ClassRoadie.java:34) |
| 17-Jun-2025 22:21:38 | at org.junit.internal.runners.ClassRoadie.runProtected(ClassRoadie.java:44) |
| 17-Jun-2025 22:21:38 | at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:365) |
| 17-Jun-2025 22:21:38 | at org.apache.maven.surefire.junit4.JUnit4Provider.executeWithRerun(JUnit4Provider.java:273) |
| 17-Jun-2025 22:21:38 | at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:238) |
| 17-Jun-2025 22:21:38 | at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:159) |
| 17-Jun-2025 22:21:38 | at org.apache.maven.surefire.booter.ForkedBooter.invokeProviderInSameClassLoader(ForkedBooter.java:384) |
| 17-Jun-2025 22:21:38 | at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:345) |
| 17-Jun-2025 22:21:38 | at org.apache.maven.surefire.booter.ForkedBooter.execute(ForkedBooter.java:126) |
| 17-Jun-2025 22:21:38 | at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:418) |
| 17-Jun-2025 22:21:38 | [INFO] Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 2.516 s - in org.openmrs.eip.mysql.watcher.OpenmrsDbReconnectHandlerTest |
| 17-Jun-2025 22:21:38 | [INFO] Running org.openmrs.eip.mysql.watcher.AuditableFieldsEventFilterTest |
| 17-Jun-2025 22:21:38 | INFO 2025-06-17 22:21:38,839 [openmrs-db-reconnect-handler] o.o.e.m.w.AuditableEventFilter:37 Auditable tables to filter -> [person] |
| 17-Jun-2025 22:21:38 | INFO 2025-06-17 22:21:38,839 [openmrs-db-reconnect-handler] o.o.e.m.w.AuditableEventFilter:37 Auditable tables to filter -> [person] |
| 17-Jun-2025 22:21:38 | INFO 2025-06-17 22:21:38,840 [openmrs-db-reconnect-handler] o.o.e.m.w.AuditableEventFilter:37 Auditable tables to filter -> [person] |
| 17-Jun-2025 22:21:38 | INFO 2025-06-17 22:21:38,850 [openmrs-db-reconnect-handler] o.o.e.m.w.AuditableEventFilter:37 Auditable tables to filter -> [person] |
| 17-Jun-2025 22:21:38 | INFO 2025-06-17 22:21:38,851 [openmrs-db-reconnect-handler] o.o.e.m.w.AuditableEventFilter:37 Auditable tables to filter -> [person] |
| 17-Jun-2025 22:21:38 | INFO 2025-06-17 22:21:38,851 [openmrs-db-reconnect-handler] o.o.e.m.w.AuditableEventFilter:37 Auditable tables to filter -> [person] |
| 17-Jun-2025 22:21:38 | INFO 2025-06-17 22:21:38,851 [openmrs-db-reconnect-handler] o.o.e.m.w.AuditableEventFilter:37 Auditable tables to filter -> [person] |
| 17-Jun-2025 22:21:38 | INFO 2025-06-17 22:21:38,851 [openmrs-db-reconnect-handler] o.o.e.m.w.AuditableEventFilter:37 Auditable tables to filter -> [person] |
| 17-Jun-2025 22:21:38 | INFO 2025-06-17 22:21:38,851 [openmrs-db-reconnect-handler] o.o.e.m.w.AuditableEventFilter:37 Auditable tables to filter -> [person] |
| 17-Jun-2025 22:21:38 | INFO 2025-06-17 22:21:38,852 [openmrs-db-reconnect-handler] o.o.e.m.w.AuditableEventFilter:37 Auditable tables to filter -> [person] |
| 17-Jun-2025 22:21:38 | INFO 2025-06-17 22:21:38,852 [openmrs-db-reconnect-handler] o.o.e.m.w.AuditableEventFilter:37 Auditable tables to filter -> [person] |
| 17-Jun-2025 22:21:38 | INFO 2025-06-17 22:21:38,852 [openmrs-db-reconnect-handler] o.o.e.m.w.AuditableEventFilter:37 Auditable tables to filter -> [person] |
| 17-Jun-2025 22:21:38 | INFO 2025-06-17 22:21:38,852 [openmrs-db-reconnect-handler] o.o.e.m.w.AuditableEventFilter:37 Auditable tables to filter -> [person] |
| 17-Jun-2025 22:21:38 | INFO 2025-06-17 22:21:38,852 [openmrs-db-reconnect-handler] o.o.e.m.w.AuditableEventFilter:37 Auditable tables to filter -> [] |
| 17-Jun-2025 22:21:38 | INFO 2025-06-17 22:21:38,852 [openmrs-db-reconnect-handler] o.o.e.m.w.AuditableEventFilter:37 Auditable tables to filter -> [person] |
| 17-Jun-2025 22:21:38 | INFO 2025-06-17 22:21:38,853 [openmrs-db-reconnect-handler] o.o.e.m.w.AuditableEventFilter:37 Auditable tables to filter -> [person] |
| 17-Jun-2025 22:21:38 | INFO 2025-06-17 22:21:38,853 [openmrs-db-reconnect-handler] o.o.e.m.w.AuditableEventFilter:37 Auditable tables to filter -> [person] |
| 17-Jun-2025 22:21:38 | INFO 2025-06-17 22:21:38,853 [openmrs-db-reconnect-handler] o.o.e.m.w.AuditableEventFilter:37 Auditable tables to filter -> [visit, encounter] |
| 17-Jun-2025 22:21:38 | INFO 2025-06-17 22:21:38,854 [openmrs-db-reconnect-handler] o.o.e.m.w.AuditableEventFilter:37 Auditable tables to filter -> [person] |
| 17-Jun-2025 22:21:38 | INFO 2025-06-17 22:21:38,854 [openmrs-db-reconnect-handler] o.o.e.m.w.AuditableEventFilter:37 Auditable tables to filter -> [person] |
| 17-Jun-2025 22:21:38 | [INFO] Tests run: 18, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.025 s - in org.openmrs.eip.mysql.watcher.AuditableFieldsEventFilterTest |
| 17-Jun-2025 22:21:38 | [INFO] Running org.openmrs.eip.mysql.watcher.FailureTolerantMySqlConnectorTest |
| 17-Jun-2025 22:21:40 | ERROR 2025-06-17 22:21:40,222 [openmrs-db-reconnect-handler] o.o.e.m.w.FailureTolerantMySqlConnector:56 Failed to suspend debezium route |
| 17-Jun-2025 22:21:40 | org.openmrs.eip.EIPException: test |
| 17-Jun-2025 22:21:40 | at org.openmrs.eip.mysql.watcher.FailureTolerantMySqlConnector.stop(FailureTolerantMySqlConnector.java:53) |
| 17-Jun-2025 22:21:40 | at org.openmrs.eip.mysql.watcher.FailureTolerantMySqlConnectorTest.stop_shouldFailIfTheDebeziumRouteCannotBeSuspended(FailureTolerantMySqlConnectorTest.java:99) |
| 17-Jun-2025 22:21:40 | at org.junit.internal.runners.TestMethod.invoke(TestMethod.java:68) |
| 17-Jun-2025 22:21:40 | at org.junit.internal.runners.MethodRoadie$2.run(MethodRoadie.java:89) |
| 17-Jun-2025 22:21:40 | at org.junit.internal.runners.MethodRoadie.runBeforesThenTestThenAfters(MethodRoadie.java:97) |
| 17-Jun-2025 22:21:40 | at org.junit.internal.runners.MethodRoadie.runTest(MethodRoadie.java:87) |
| 17-Jun-2025 22:21:40 | at org.junit.internal.runners.MethodRoadie.run(MethodRoadie.java:50) |
| 17-Jun-2025 22:21:40 | at org.junit.internal.runners.ClassRoadie.runUnprotected(ClassRoadie.java:34) |
| 17-Jun-2025 22:21:40 | at org.junit.internal.runners.ClassRoadie.runProtected(ClassRoadie.java:44) |
| 17-Jun-2025 22:21:40 | at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:365) |
| 17-Jun-2025 22:21:40 | at org.apache.maven.surefire.junit4.JUnit4Provider.executeWithRerun(JUnit4Provider.java:273) |
| 17-Jun-2025 22:21:40 | at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:238) |
| 17-Jun-2025 22:21:40 | at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:159) |
| 17-Jun-2025 22:21:40 | at org.apache.maven.surefire.booter.ForkedBooter.invokeProviderInSameClassLoader(ForkedBooter.java:384) |
| 17-Jun-2025 22:21:40 | at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:345) |
| 17-Jun-2025 22:21:40 | at org.apache.maven.surefire.booter.ForkedBooter.execute(ForkedBooter.java:126) |
| 17-Jun-2025 22:21:40 | at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:418) |
| 17-Jun-2025 22:21:40 | ERROR 2025-06-17 22:21:40,229 [openmrs-db-reconnect-handler] o.o.e.m.w.FailureTolerantMySqlConnector:75 Failed to start OpenMRS DB disconnect watchdog |
| 17-Jun-2025 22:21:40 | org.openmrs.eip.EIPException: test |
| 17-Jun-2025 22:21:40 | at org.openmrs.eip.mysql.watcher.FailureTolerantMySqlConnector.stop(FailureTolerantMySqlConnector.java:69) |
| 17-Jun-2025 22:21:40 | at org.openmrs.eip.mysql.watcher.FailureTolerantMySqlConnectorTest.stop_shouldFailIfTheWatchDogCannotBeStarted(FailureTolerantMySqlConnectorTest.java:111) |
| 17-Jun-2025 22:21:40 | at org.junit.internal.runners.TestMethod.invoke(TestMethod.java:68) |
| 17-Jun-2025 22:21:40 | at org.junit.internal.runners.MethodRoadie$2.run(MethodRoadie.java:89) |
| 17-Jun-2025 22:21:40 | at org.junit.internal.runners.MethodRoadie.runBeforesThenTestThenAfters(MethodRoadie.java:97) |
| 17-Jun-2025 22:21:40 | at org.junit.internal.runners.MethodRoadie.runTest(MethodRoadie.java:87) |
| 17-Jun-2025 22:21:40 | at org.junit.internal.runners.MethodRoadie.run(MethodRoadie.java:50) |
| 17-Jun-2025 22:21:40 | at org.junit.internal.runners.ClassRoadie.runUnprotected(ClassRoadie.java:34) |
| 17-Jun-2025 22:21:40 | at org.junit.internal.runners.ClassRoadie.runProtected(ClassRoadie.java:44) |
| 17-Jun-2025 22:21:40 | at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:365) |
| 17-Jun-2025 22:21:40 | at org.apache.maven.surefire.junit4.JUnit4Provider.executeWithRerun(JUnit4Provider.java:273) |
| 17-Jun-2025 22:21:40 | at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:238) |
| 17-Jun-2025 22:21:40 | at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:159) |
| 17-Jun-2025 22:21:40 | at org.apache.maven.surefire.booter.ForkedBooter.invokeProviderInSameClassLoader(ForkedBooter.java:384) |
| 17-Jun-2025 22:21:40 | at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:345) |
| 17-Jun-2025 22:21:40 | at org.apache.maven.surefire.booter.ForkedBooter.execute(ForkedBooter.java:126) |
| 17-Jun-2025 22:21:40 | at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:418) |
| 17-Jun-2025 22:21:40 | [INFO] Tests run: 7, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 1.364 s - in org.openmrs.eip.mysql.watcher.FailureTolerantMySqlConnectorTest |
| 17-Jun-2025 22:21:40 | [INFO] Running org.openmrs.eip.mysql.watcher.DebeziumMessageProcessorTest |
| 17-Jun-2025 22:21:40 | INFO 2025-06-17 22:21:40,246 [openmrs-db-reconnect-handler] o.o.e.m.w.DebeziumMessageProcessor:32 Event Filters -> [] |
| 17-Jun-2025 22:21:40 | INFO 2025-06-17 22:21:40,257 [openmrs-db-reconnect-handler] o.o.e.m.w.DebeziumMessageProcessor:100 Processing change event: Event {tableName=visit, primaryKeyId=2, identifier=null, operation=c, snapshot=true}, Source Metadata: {table=visit, snapshot=} |
| 17-Jun-2025 22:21:40 | INFO 2025-06-17 22:21:40,258 [openmrs-db-reconnect-handler] o.o.e.m.w.DebeziumMessageProcessor:32 Event Filters -> [] |
| 17-Jun-2025 22:21:40 | INFO 2025-06-17 22:21:40,259 [openmrs-db-reconnect-handler] o.o.e.m.w.DebeziumMessageProcessor:100 Processing change event: Event {tableName=visit, primaryKeyId=2, identifier=null, operation=c, snapshot=false}, Source Metadata: {table=visit, snapshot=false} |
| 17-Jun-2025 22:21:40 | INFO 2025-06-17 22:21:40,260 [openmrs-db-reconnect-handler] o.o.e.m.w.DebeziumMessageProcessor:32 Event Filters -> [] |
| 17-Jun-2025 22:21:40 | INFO 2025-06-17 22:21:40,270 [openmrs-db-reconnect-handler] o.o.e.m.w.DebeziumMessageProcessor:32 Event Filters -> [Mock for EventFilter, hashCode: 243714469, Mock for EventFilter, hashCode: 208910844, Mock for EventFilter, hashCode: 1169826236] |
| 17-Jun-2025 22:21:40 | INFO 2025-06-17 22:21:40,271 [openmrs-db-reconnect-handler] o.o.e.m.w.DebeziumMessageProcessor:32 Event Filters -> [] |
| 17-Jun-2025 22:21:40 | INFO 2025-06-17 22:21:40,273 [openmrs-db-reconnect-handler] o.o.e.m.w.DebeziumMessageProcessor:100 Processing change event: Event {tableName=visit, primaryKeyId=2, identifier=null, operation=c, snapshot=true}, Source Metadata: {table=visit} |
| 17-Jun-2025 22:21:40 | INFO 2025-06-17 22:21:40,273 [openmrs-db-reconnect-handler] o.o.e.m.w.DebeziumMessageProcessor:32 Event Filters -> [] |
| 17-Jun-2025 22:21:40 | INFO 2025-06-17 22:21:40,275 [openmrs-db-reconnect-handler] o.o.e.m.w.DebeziumMessageProcessor:100 Processing change event: Event {tableName=visit, primaryKeyId=2, identifier=null, operation=c, snapshot=false}, Source Metadata: {table=visit, snapshot=false} |
| 17-Jun-2025 22:21:40 | INFO 2025-06-17 22:21:40,275 [openmrs-db-reconnect-handler] o.o.e.m.w.DebeziumMessageProcessor:32 Event Filters -> [] |
| 17-Jun-2025 22:21:40 | INFO 2025-06-17 22:21:40,277 [openmrs-db-reconnect-handler] o.o.e.m.w.DebeziumMessageProcessor:32 Event Filters -> [Mock for EventFilter, hashCode: 46019500, Mock for EventFilter, hashCode: 1943597593] |
| 17-Jun-2025 22:21:40 | INFO 2025-06-17 22:21:40,277 [openmrs-db-reconnect-handler] o.o.e.m.w.DebeziumMessageProcessor:32 Event Filters -> [] |
| 17-Jun-2025 22:21:40 | INFO 2025-06-17 22:21:40,281 [openmrs-db-reconnect-handler] o.o.e.m.w.DebeziumMessageProcessor:32 Event Filters -> [] |
| 17-Jun-2025 22:21:40 | INFO 2025-06-17 22:21:40,282 [openmrs-db-reconnect-handler] o.o.e.m.w.DebeziumMessageProcessor:100 Processing change event: Event {tableName=visit, primaryKeyId=2, identifier=null, operation=c, snapshot=true}, Source Metadata: {table=visit, snapshot=true} |
| 17-Jun-2025 22:21:40 | INFO 2025-06-17 22:21:40,282 [openmrs-db-reconnect-handler] o.o.e.m.w.DebeziumMessageProcessor:32 Event Filters -> [] |
| 17-Jun-2025 22:21:40 | INFO 2025-06-17 22:21:40,284 [openmrs-db-reconnect-handler] o.o.e.m.w.DebeziumMessageProcessor:32 Event Filters -> [Mock for EventFilter, hashCode: 442067065, Mock for EventFilter, hashCode: 904822216, Mock for EventFilter, hashCode: 54377811] |
| 17-Jun-2025 22:21:40 | INFO 2025-06-17 22:21:40,285 [openmrs-db-reconnect-handler] o.o.e.m.w.DebeziumMessageProcessor:32 Event Filters -> [] |
| 17-Jun-2025 22:21:40 | INFO 2025-06-17 22:21:40,287 [openmrs-db-reconnect-handler] o.o.e.m.w.DebeziumMessageProcessor:32 Event Filters -> [Mock for EventFilter, hashCode: 950532002, Mock for EventFilter, hashCode: 667562048] |
| 17-Jun-2025 22:21:40 | INFO 2025-06-17 22:21:40,287 [openmrs-db-reconnect-handler] o.o.e.m.w.DebeziumMessageProcessor:100 Processing change event: Event {tableName=visit, primaryKeyId=1, identifier=null, operation=c, snapshot=false}, Source Metadata: {table=visit, snapshot=false} |
| 17-Jun-2025 22:21:40 | [WARNING] Tests run: 14, Failures: 0, Errors: 0, Skipped: 4, Time elapsed: 0.041 s - in org.openmrs.eip.mysql.watcher.DebeziumMessageProcessorTest |
| 17-Jun-2025 22:21:40 | [INFO] |
| 17-Jun-2025 22:21:40 | [INFO] Results: |
| 17-Jun-2025 22:21:40 | [INFO] |
| 17-Jun-2025 22:21:40 | [WARNING] Tests run: 73, Failures: 0, Errors: 0, Skipped: 5 |
| 17-Jun-2025 22:21:40 | [INFO] |
| 17-Jun-2025 22:21:40 | [INFO] |
| 17-Jun-2025 22:21:40 | [INFO] --- maven-jar-plugin:3.2.0:jar (default-jar) @ openmrs-watcher --- |
| 17-Jun-2025 22:21:40 | [INFO] Building jar: /home/bamboo-agent/bamboo-agent/xml-data/build-dir/EIP-EIP0-JOB1/openmrs-watcher/target/openmrs-watcher-3.3.0-SNAPSHOT.jar |
| 17-Jun-2025 22:21:40 | [INFO] |
| 17-Jun-2025 22:21:40 | [INFO] --- maven-jar-plugin:3.2.0:test-jar (default) @ openmrs-watcher --- |
| 17-Jun-2025 22:21:40 | [INFO] Building jar: /home/bamboo-agent/bamboo-agent/xml-data/build-dir/EIP-EIP0-JOB1/openmrs-watcher/target/openmrs-watcher-3.3.0-SNAPSHOT-tests.jar |
| 17-Jun-2025 22:21:40 | [INFO] |
| 17-Jun-2025 22:21:40 | [INFO] --- maven-install-plugin:2.4:install (default-install) @ openmrs-watcher --- |
| 17-Jun-2025 22:21:40 | [INFO] Installing /home/bamboo-agent/bamboo-agent/xml-data/build-dir/EIP-EIP0-JOB1/openmrs-watcher/target/openmrs-watcher-3.3.0-SNAPSHOT.jar to /home/bamboo-agent/.m2/repository/org/openmrs/eip/openmrs-watcher/3.3.0-SNAPSHOT/openmrs-watcher-3.3.0-SNAPSHOT.jar |
| 17-Jun-2025 22:21:40 | [INFO] Installing /home/bamboo-agent/bamboo-agent/xml-data/build-dir/EIP-EIP0-JOB1/openmrs-watcher/pom.xml to /home/bamboo-agent/.m2/repository/org/openmrs/eip/openmrs-watcher/3.3.0-SNAPSHOT/openmrs-watcher-3.3.0-SNAPSHOT.pom |
| 17-Jun-2025 22:21:40 | [INFO] Installing /home/bamboo-agent/bamboo-agent/xml-data/build-dir/EIP-EIP0-JOB1/openmrs-watcher/target/openmrs-watcher-3.3.0-SNAPSHOT-tests.jar to /home/bamboo-agent/.m2/repository/org/openmrs/eip/openmrs-watcher/3.3.0-SNAPSHOT/openmrs-watcher-3.3.0-SNAPSHOT-tests.jar |
| 17-Jun-2025 22:21:40 | [INFO] |
| 17-Jun-2025 22:21:40 | [INFO] --- maven-deploy-plugin:2.7:deploy (default-deploy) @ openmrs-watcher --- |
| 17-Jun-2025 22:21:40 | [INFO] Downloading from openmrs-repo-snapshots: https://openmrs.jfrog.io/openmrs/snapshots/org/openmrs/eip/openmrs-watcher/3.3.0-SNAPSHOT/maven-metadata.xml |
| 17-Jun-2025 22:21:41 | [INFO] Downloaded from openmrs-repo-snapshots: https://openmrs.jfrog.io/openmrs/snapshots/org/openmrs/eip/openmrs-watcher/3.3.0-SNAPSHOT/maven-metadata.xml (992 B at 1.6 kB/s) |
| 17-Jun-2025 22:21:41 | [INFO] Uploading to openmrs-repo-snapshots: https://openmrs.jfrog.io/openmrs/snapshots/org/openmrs/eip/openmrs-watcher/3.3.0-SNAPSHOT/openmrs-watcher-3.3.0-20250617.221742-5.jar |
| 17-Jun-2025 22:21:42 | [INFO] Uploaded to openmrs-repo-snapshots: https://openmrs.jfrog.io/openmrs/snapshots/org/openmrs/eip/openmrs-watcher/3.3.0-SNAPSHOT/openmrs-watcher-3.3.0-20250617.221742-5.jar (48 kB at 40 kB/s) |
| 17-Jun-2025 22:21:42 | [INFO] Uploading to openmrs-repo-snapshots: https://openmrs.jfrog.io/openmrs/snapshots/org/openmrs/eip/openmrs-watcher/3.3.0-SNAPSHOT/openmrs-watcher-3.3.0-20250617.221742-5.pom |
| 17-Jun-2025 22:21:43 | [INFO] Uploaded to openmrs-repo-snapshots: https://openmrs.jfrog.io/openmrs/snapshots/org/openmrs/eip/openmrs-watcher/3.3.0-SNAPSHOT/openmrs-watcher-3.3.0-20250617.221742-5.pom (1.9 kB at 1.9 kB/s) |
| 17-Jun-2025 22:21:43 | [INFO] Downloading from openmrs-repo-snapshots: https://openmrs.jfrog.io/openmrs/snapshots/org/openmrs/eip/openmrs-watcher/maven-metadata.xml |
| 17-Jun-2025 22:21:44 | [INFO] Downloaded from openmrs-repo-snapshots: https://openmrs.jfrog.io/openmrs/snapshots/org/openmrs/eip/openmrs-watcher/maven-metadata.xml (739 B at 3.2 kB/s) |
| 17-Jun-2025 22:21:44 | [INFO] Uploading to openmrs-repo-snapshots: https://openmrs.jfrog.io/openmrs/snapshots/org/openmrs/eip/openmrs-watcher/3.3.0-SNAPSHOT/maven-metadata.xml |
| 17-Jun-2025 22:21:44 | [INFO] Uploaded to openmrs-repo-snapshots: https://openmrs.jfrog.io/openmrs/snapshots/org/openmrs/eip/openmrs-watcher/3.3.0-SNAPSHOT/maven-metadata.xml (992 B at 1.6 kB/s) |
| 17-Jun-2025 22:21:44 | [INFO] Uploading to openmrs-repo-snapshots: https://openmrs.jfrog.io/openmrs/snapshots/org/openmrs/eip/openmrs-watcher/maven-metadata.xml |
| 17-Jun-2025 22:21:45 | [INFO] Uploaded to openmrs-repo-snapshots: https://openmrs.jfrog.io/openmrs/snapshots/org/openmrs/eip/openmrs-watcher/maven-metadata.xml (682 B at 845 B/s) |
| 17-Jun-2025 22:21:45 | [INFO] Uploading to openmrs-repo-snapshots: https://openmrs.jfrog.io/openmrs/snapshots/org/openmrs/eip/openmrs-watcher/3.3.0-SNAPSHOT/openmrs-watcher-3.3.0-20250617.221742-5-tests.jar |
| 17-Jun-2025 22:21:46 | [INFO] Uploaded to openmrs-repo-snapshots: https://openmrs.jfrog.io/openmrs/snapshots/org/openmrs/eip/openmrs-watcher/3.3.0-SNAPSHOT/openmrs-watcher-3.3.0-20250617.221742-5-tests.jar (41 kB at 43 kB/s) |
| 17-Jun-2025 22:21:46 | [INFO] Uploading to openmrs-repo-snapshots: https://openmrs.jfrog.io/openmrs/snapshots/org/openmrs/eip/openmrs-watcher/3.3.0-SNAPSHOT/maven-metadata.xml |
| 17-Jun-2025 22:21:47 | [INFO] Uploaded to openmrs-repo-snapshots: https://openmrs.jfrog.io/openmrs/snapshots/org/openmrs/eip/openmrs-watcher/3.3.0-SNAPSHOT/maven-metadata.xml (992 B at 1.6 kB/s) |
| 17-Jun-2025 22:21:47 | [INFO] |
| 17-Jun-2025 22:21:47 | [INFO] --------------------< org.openmrs.eip:commons-web >--------------------- |
| 17-Jun-2025 22:21:47 | [INFO] Building OpenMRS EIP Commons Web 3.3.0-SNAPSHOT [4/5] |
| 17-Jun-2025 22:21:47 | [INFO] --------------------------------[ jar ]--------------------------------- |
| 17-Jun-2025 22:21:47 | [INFO] Downloading from openmrs-repo: https://mavenrepo.openmrs.org/nexus/content/repositories/public/org/springframework/boot/spring-boot-starter-web/2.3.0.RELEASE/spring-boot-starter-web-2.3.0.RELEASE.pom |
| 17-Jun-2025 22:21:47 | [INFO] Downloaded from openmrs-repo: https://mavenrepo.openmrs.org/nexus/content/repositories/public/org/springframework/boot/spring-boot-starter-web/2.3.0.RELEASE/spring-boot-starter-web-2.3.0.RELEASE.pom (3.3 kB at 11 kB/s) |
| 17-Jun-2025 22:21:47 | [INFO] Downloading from openmrs-repo: https://mavenrepo.openmrs.org/nexus/content/repositories/public/org/springframework/boot/spring-boot-starter-json/2.3.0.RELEASE/spring-boot-starter-json-2.3.0.RELEASE.pom |
| 17-Jun-2025 22:21:47 | [INFO] Downloaded from openmrs-repo: https://mavenrepo.openmrs.org/nexus/content/repositories/public/org/springframework/boot/spring-boot-starter-json/2.3.0.RELEASE/spring-boot-starter-json-2.3.0.RELEASE.pom (3.3 kB at 13 kB/s) |
| 17-Jun-2025 22:21:47 | [INFO] Downloading from openmrs-repo: https://mavenrepo.openmrs.org/nexus/content/repositories/public/org/springframework/spring-web/5.2.6.RELEASE/spring-web-5.2.6.RELEASE.pom |
| 17-Jun-2025 22:21:47 | [INFO] Downloaded from openmrs-repo: https://mavenrepo.openmrs.org/nexus/content/repositories/public/org/springframework/spring-web/5.2.6.RELEASE/spring-web-5.2.6.RELEASE.pom (1.9 kB at 6.6 kB/s) |
| 17-Jun-2025 22:21:47 | [INFO] Downloading from openmrs-repo: https://mavenrepo.openmrs.org/nexus/content/repositories/public/com/fasterxml/jackson/datatype/jackson-datatype-jdk8/2.11.0/jackson-datatype-jdk8-2.11.0.pom |
| 17-Jun-2025 22:21:48 | [INFO] Downloaded from openmrs-repo: https://mavenrepo.openmrs.org/nexus/content/repositories/public/com/fasterxml/jackson/datatype/jackson-datatype-jdk8/2.11.0/jackson-datatype-jdk8-2.11.0.pom (2.2 kB at 8.0 kB/s) |
| 17-Jun-2025 22:21:48 | [INFO] Downloading from openmrs-repo: https://mavenrepo.openmrs.org/nexus/content/repositories/public/com/fasterxml/jackson/module/jackson-module-parameter-names/2.11.0/jackson-module-parameter-names-2.11.0.pom |
| 17-Jun-2025 22:21:48 | [INFO] Downloaded from openmrs-repo: https://mavenrepo.openmrs.org/nexus/content/repositories/public/com/fasterxml/jackson/module/jackson-module-parameter-names/2.11.0/jackson-module-parameter-names-2.11.0.pom (4.0 kB at 15 kB/s) |
| 17-Jun-2025 22:21:48 | [INFO] Downloading from openmrs-repo: https://mavenrepo.openmrs.org/nexus/content/repositories/public/org/springframework/spring-webmvc/5.2.6.RELEASE/spring-webmvc-5.2.6.RELEASE.pom |
| 17-Jun-2025 22:21:48 | [INFO] Downloaded from openmrs-repo: https://mavenrepo.openmrs.org/nexus/content/repositories/public/org/springframework/spring-webmvc/5.2.6.RELEASE/spring-webmvc-5.2.6.RELEASE.pom (2.6 kB at 10 kB/s) |
| 17-Jun-2025 22:21:48 | [INFO] Downloading from openmrs-repo: https://mavenrepo.openmrs.org/nexus/content/repositories/public/org/springframework/boot/spring-boot-starter-web/2.3.0.RELEASE/spring-boot-starter-web-2.3.0.RELEASE.jar |
| 17-Jun-2025 22:21:48 | [INFO] Downloading from openmrs-repo: https://mavenrepo.openmrs.org/nexus/content/repositories/public/org/springframework/boot/spring-boot-starter-json/2.3.0.RELEASE/spring-boot-starter-json-2.3.0.RELEASE.jar |
| 17-Jun-2025 22:21:48 | [INFO] Downloading from openmrs-repo: https://mavenrepo.openmrs.org/nexus/content/repositories/public/com/fasterxml/jackson/datatype/jackson-datatype-jdk8/2.11.0/jackson-datatype-jdk8-2.11.0.jar |
| 17-Jun-2025 22:21:48 | [INFO] Downloading from openmrs-repo: https://mavenrepo.openmrs.org/nexus/content/repositories/public/com/fasterxml/jackson/module/jackson-module-parameter-names/2.11.0/jackson-module-parameter-names-2.11.0.jar |
| 17-Jun-2025 22:21:48 | [INFO] Downloading from openmrs-repo: https://mavenrepo.openmrs.org/nexus/content/repositories/public/org/springframework/spring-web/5.2.6.RELEASE/spring-web-5.2.6.RELEASE.jar |
| 17-Jun-2025 22:21:49 | [INFO] Downloaded from openmrs-repo: https://mavenrepo.openmrs.org/nexus/content/repositories/public/org/springframework/boot/spring-boot-starter-web/2.3.0.RELEASE/spring-boot-starter-web-2.3.0.RELEASE.jar (4.8 kB at 15 kB/s) |
| 17-Jun-2025 22:21:49 | [INFO] Downloading from openmrs-repo: https://mavenrepo.openmrs.org/nexus/content/repositories/public/org/springframework/spring-webmvc/5.2.6.RELEASE/spring-webmvc-5.2.6.RELEASE.jar |
| 17-Jun-2025 22:21:49 | [INFO] Downloaded from openmrs-repo: https://mavenrepo.openmrs.org/nexus/content/repositories/public/com/fasterxml/jackson/datatype/jackson-datatype-jdk8/2.11.0/jackson-datatype-jdk8-2.11.0.jar (34 kB at 60 kB/s) |
| 17-Jun-2025 22:21:49 | [INFO] Downloaded from openmrs-repo: https://mavenrepo.openmrs.org/nexus/content/repositories/public/org/springframework/boot/spring-boot-starter-json/2.3.0.RELEASE/spring-boot-starter-json-2.3.0.RELEASE.jar (4.7 kB at 8.1 kB/s) |
| 17-Jun-2025 22:21:49 | [INFO] Downloaded from openmrs-repo: https://mavenrepo.openmrs.org/nexus/content/repositories/public/com/fasterxml/jackson/module/jackson-module-parameter-names/2.11.0/jackson-module-parameter-names-2.11.0.jar (9.3 kB at 15 kB/s) |
| 17-Jun-2025 22:21:50 | [INFO] Downloaded from openmrs-repo: https://mavenrepo.openmrs.org/nexus/content/repositories/public/org/springframework/spring-web/5.2.6.RELEASE/spring-web-5.2.6.RELEASE.jar (1.4 MB at 879 kB/s) |
| 17-Jun-2025 22:21:50 | [INFO] Downloaded from openmrs-repo: https://mavenrepo.openmrs.org/nexus/content/repositories/public/org/springframework/spring-webmvc/5.2.6.RELEASE/spring-webmvc-5.2.6.RELEASE.jar (955 kB at 495 kB/s) |
| 17-Jun-2025 22:21:50 | [INFO] |
| 17-Jun-2025 22:21:50 | [INFO] --- maven-clean-plugin:2.5:clean (default-clean) @ commons-web --- |
| 17-Jun-2025 22:21:50 | [INFO] |
| 17-Jun-2025 22:21:50 | [INFO] --- maven-resources-plugin:3.1.0:resources (default-resources) @ commons-web --- |
| 17-Jun-2025 22:21:50 | [INFO] Using 'UTF-8' encoding to copy filtered resources. |
| 17-Jun-2025 22:21:50 | [INFO] Copying 1 resource |
| 17-Jun-2025 22:21:50 | [INFO] |
| 17-Jun-2025 22:21:50 | [INFO] --- maven-compiler-plugin:3.8.0:compile (default-compile) @ commons-web --- |
| 17-Jun-2025 22:21:50 | [INFO] Changes detected - recompiling the module! |
| 17-Jun-2025 22:21:50 | [INFO] Compiling 1 source file to /home/bamboo-agent/bamboo-agent/xml-data/build-dir/EIP-EIP0-JOB1/commons-web/target/classes |
| 17-Jun-2025 22:21:50 | [INFO] |
| 17-Jun-2025 22:21:50 | [INFO] --- maven-resources-plugin:3.1.0:testResources (default-testResources) @ commons-web --- |
| 17-Jun-2025 22:21:50 | [INFO] Using 'UTF-8' encoding to copy filtered resources. |
| 17-Jun-2025 22:21:50 | [INFO] skip non existing resourceDirectory /home/bamboo-agent/bamboo-agent/xml-data/build-dir/EIP-EIP0-JOB1/commons-web/src/test/resources |
| 17-Jun-2025 22:21:50 | [INFO] |
| 17-Jun-2025 22:21:50 | [INFO] --- maven-compiler-plugin:3.8.0:testCompile (default-testCompile) @ commons-web --- |
| 17-Jun-2025 22:21:50 | [INFO] No sources to compile |
| 17-Jun-2025 22:21:50 | [INFO] |
| 17-Jun-2025 22:21:50 | [INFO] --- maven-surefire-plugin:2.22.1:test (default-test) @ commons-web --- |
| 17-Jun-2025 22:21:50 | [INFO] No tests to run. |
| 17-Jun-2025 22:21:50 | [INFO] |
| 17-Jun-2025 22:21:50 | [INFO] --- maven-jar-plugin:3.2.0:jar (default-jar) @ commons-web --- |
| 17-Jun-2025 22:21:50 | [INFO] Building jar: /home/bamboo-agent/bamboo-agent/xml-data/build-dir/EIP-EIP0-JOB1/commons-web/target/commons-web-3.3.0-SNAPSHOT.jar |
| 17-Jun-2025 22:21:50 | [INFO] |
| 17-Jun-2025 22:21:50 | [INFO] --- maven-jar-plugin:3.2.0:test-jar (default) @ commons-web --- |
| 17-Jun-2025 22:21:50 | [WARNING] JAR will be empty - no content was marked for inclusion! |
| 17-Jun-2025 22:21:50 | [INFO] Building jar: /home/bamboo-agent/bamboo-agent/xml-data/build-dir/EIP-EIP0-JOB1/commons-web/target/commons-web-3.3.0-SNAPSHOT-tests.jar |
| 17-Jun-2025 22:21:51 | [INFO] |
| 17-Jun-2025 22:21:51 | [INFO] --- maven-install-plugin:2.4:install (default-install) @ commons-web --- |
| 17-Jun-2025 22:21:51 | [INFO] Installing /home/bamboo-agent/bamboo-agent/xml-data/build-dir/EIP-EIP0-JOB1/commons-web/target/commons-web-3.3.0-SNAPSHOT.jar to /home/bamboo-agent/.m2/repository/org/openmrs/eip/commons-web/3.3.0-SNAPSHOT/commons-web-3.3.0-SNAPSHOT.jar |
| 17-Jun-2025 22:21:51 | [INFO] Installing /home/bamboo-agent/bamboo-agent/xml-data/build-dir/EIP-EIP0-JOB1/commons-web/pom.xml to /home/bamboo-agent/.m2/repository/org/openmrs/eip/commons-web/3.3.0-SNAPSHOT/commons-web-3.3.0-SNAPSHOT.pom |
| 17-Jun-2025 22:21:51 | [INFO] Installing /home/bamboo-agent/bamboo-agent/xml-data/build-dir/EIP-EIP0-JOB1/commons-web/target/commons-web-3.3.0-SNAPSHOT-tests.jar to /home/bamboo-agent/.m2/repository/org/openmrs/eip/commons-web/3.3.0-SNAPSHOT/commons-web-3.3.0-SNAPSHOT-tests.jar |
| 17-Jun-2025 22:21:51 | [INFO] |
| 17-Jun-2025 22:21:51 | [INFO] --- maven-deploy-plugin:2.7:deploy (default-deploy) @ commons-web --- |
| 17-Jun-2025 22:21:51 | [INFO] Downloading from openmrs-repo-snapshots: https://openmrs.jfrog.io/openmrs/snapshots/org/openmrs/eip/commons-web/3.3.0-SNAPSHOT/maven-metadata.xml |
| 17-Jun-2025 22:21:51 | [INFO] Downloaded from openmrs-repo-snapshots: https://openmrs.jfrog.io/openmrs/snapshots/org/openmrs/eip/commons-web/3.3.0-SNAPSHOT/maven-metadata.xml (988 B at 4.0 kB/s) |
| 17-Jun-2025 22:21:51 | [INFO] Uploading to openmrs-repo-snapshots: https://openmrs.jfrog.io/openmrs/snapshots/org/openmrs/eip/commons-web/3.3.0-SNAPSHOT/commons-web-3.3.0-20250617.221742-5.jar |
| 17-Jun-2025 22:21:52 | [INFO] Uploaded to openmrs-repo-snapshots: https://openmrs.jfrog.io/openmrs/snapshots/org/openmrs/eip/commons-web/3.3.0-SNAPSHOT/commons-web-3.3.0-20250617.221742-5.jar (2.7 kB at 3.0 kB/s) |
| 17-Jun-2025 22:21:52 | [INFO] Uploading to openmrs-repo-snapshots: https://openmrs.jfrog.io/openmrs/snapshots/org/openmrs/eip/commons-web/3.3.0-SNAPSHOT/commons-web-3.3.0-20250617.221742-5.pom |
| 17-Jun-2025 22:21:53 | [INFO] Uploaded to openmrs-repo-snapshots: https://openmrs.jfrog.io/openmrs/snapshots/org/openmrs/eip/commons-web/3.3.0-SNAPSHOT/commons-web-3.3.0-20250617.221742-5.pom (1.2 kB at 1.4 kB/s) |
| 17-Jun-2025 22:21:53 | [INFO] Downloading from openmrs-repo-snapshots: https://openmrs.jfrog.io/openmrs/snapshots/org/openmrs/eip/commons-web/maven-metadata.xml |
| 17-Jun-2025 22:21:53 | [INFO] Downloaded from openmrs-repo-snapshots: https://openmrs.jfrog.io/openmrs/snapshots/org/openmrs/eip/commons-web/maven-metadata.xml (619 B at 2.6 kB/s) |
| 17-Jun-2025 22:21:53 | [INFO] Uploading to openmrs-repo-snapshots: https://openmrs.jfrog.io/openmrs/snapshots/org/openmrs/eip/commons-web/3.3.0-SNAPSHOT/maven-metadata.xml |
| 17-Jun-2025 22:21:53 | [INFO] Uploaded to openmrs-repo-snapshots: https://openmrs.jfrog.io/openmrs/snapshots/org/openmrs/eip/commons-web/3.3.0-SNAPSHOT/maven-metadata.xml (988 B at 1.6 kB/s) |
| 17-Jun-2025 22:21:53 | [INFO] Uploading to openmrs-repo-snapshots: https://openmrs.jfrog.io/openmrs/snapshots/org/openmrs/eip/commons-web/maven-metadata.xml |
| 17-Jun-2025 22:21:54 | [INFO] Uploaded to openmrs-repo-snapshots: https://openmrs.jfrog.io/openmrs/snapshots/org/openmrs/eip/commons-web/maven-metadata.xml (562 B at 643 B/s) |
| 17-Jun-2025 22:21:54 | [INFO] Uploading to openmrs-repo-snapshots: https://openmrs.jfrog.io/openmrs/snapshots/org/openmrs/eip/commons-web/3.3.0-SNAPSHOT/commons-web-3.3.0-20250617.221742-5-tests.jar |
| 17-Jun-2025 22:21:55 | [INFO] Uploaded to openmrs-repo-snapshots: https://openmrs.jfrog.io/openmrs/snapshots/org/openmrs/eip/commons-web/3.3.0-SNAPSHOT/commons-web-3.3.0-20250617.221742-5-tests.jar (1.6 kB at 1.8 kB/s) |
| 17-Jun-2025 22:21:55 | [INFO] Uploading to openmrs-repo-snapshots: https://openmrs.jfrog.io/openmrs/snapshots/org/openmrs/eip/commons-web/3.3.0-SNAPSHOT/maven-metadata.xml |
| 17-Jun-2025 22:21:56 | [INFO] Uploaded to openmrs-repo-snapshots: https://openmrs.jfrog.io/openmrs/snapshots/org/openmrs/eip/commons-web/3.3.0-SNAPSHOT/maven-metadata.xml (988 B at 1.6 kB/s) |
| 17-Jun-2025 22:21:56 | [INFO] |
| 17-Jun-2025 22:21:56 | [INFO] --------------------< org.openmrs.eip:example-app >--------------------- |
| 17-Jun-2025 22:21:56 | [INFO] Building OpenMRS EIP Example App 3.3.0-SNAPSHOT [5/5] |
| 17-Jun-2025 22:21:56 | [INFO] --------------------------------[ jar ]--------------------------------- |
| 17-Jun-2025 22:21:56 | [INFO] Downloading from openmrs-repo: https://mavenrepo.openmrs.org/nexus/content/repositories/public/org/apache/maven/plugins/maven-deploy-plugin/2.8.2/maven-deploy-plugin-2.8.2.pom |
| 17-Jun-2025 22:21:56 | [INFO] Downloaded from openmrs-repo: https://mavenrepo.openmrs.org/nexus/content/repositories/public/org/apache/maven/plugins/maven-deploy-plugin/2.8.2/maven-deploy-plugin-2.8.2.pom (7.1 kB at 25 kB/s) |
| 17-Jun-2025 22:21:56 | [INFO] Downloading from openmrs-repo: https://mavenrepo.openmrs.org/nexus/content/repositories/public/org/apache/maven/plugins/maven-deploy-plugin/2.8.2/maven-deploy-plugin-2.8.2.jar |
| 17-Jun-2025 22:21:56 | [INFO] Downloaded from openmrs-repo: https://mavenrepo.openmrs.org/nexus/content/repositories/public/org/apache/maven/plugins/maven-deploy-plugin/2.8.2/maven-deploy-plugin-2.8.2.jar (34 kB at 107 kB/s) |
| 17-Jun-2025 22:21:56 | [INFO] Downloading from openmrs-repo: https://mavenrepo.openmrs.org/nexus/content/repositories/public/commons-codec/commons-codec/1.11/commons-codec-1.11.pom |
| 17-Jun-2025 22:21:57 | [INFO] Downloaded from openmrs-repo: https://mavenrepo.openmrs.org/nexus/content/repositories/public/commons-codec/commons-codec/1.11/commons-codec-1.11.pom (14 kB at 65 kB/s) |
| 17-Jun-2025 22:21:57 | [INFO] Downloading from openmrs-repo: https://mavenrepo.openmrs.org/nexus/content/repositories/public/org/apache/logging/log4j/log4j-api/2.12.1/log4j-api-2.12.1.pom |
| 17-Jun-2025 22:21:57 | [INFO] Downloaded from openmrs-repo: https://mavenrepo.openmrs.org/nexus/content/repositories/public/org/apache/logging/log4j/log4j-api/2.12.1/log4j-api-2.12.1.pom (13 kB at 43 kB/s) |
| 17-Jun-2025 22:21:57 | [INFO] Downloading from openmrs-repo: https://mavenrepo.openmrs.org/nexus/content/repositories/public/org/yaml/snakeyaml/1.25/snakeyaml-1.25.pom |
| 17-Jun-2025 22:21:57 | [INFO] Downloaded from openmrs-repo: https://mavenrepo.openmrs.org/nexus/content/repositories/public/org/yaml/snakeyaml/1.25/snakeyaml-1.25.pom (38 kB at 104 kB/s) |
| 17-Jun-2025 22:21:57 | [INFO] Downloading from openmrs-repo: https://mavenrepo.openmrs.org/nexus/content/repositories/public/org/apache/xbean/xbean-spring/4.16/xbean-spring-4.16.pom |
| 17-Jun-2025 22:21:58 | [INFO] Downloaded from openmrs-repo: https://mavenrepo.openmrs.org/nexus/content/repositories/public/org/apache/xbean/xbean-spring/4.16/xbean-spring-4.16.pom (6.0 kB at 22 kB/s) |
| 17-Jun-2025 22:21:58 | [INFO] Downloading from openmrs-repo: https://mavenrepo.openmrs.org/nexus/content/repositories/public/org/apache/xbean/xbean/4.16/xbean-4.16.pom |
| 17-Jun-2025 22:21:58 | [INFO] Downloaded from openmrs-repo: https://mavenrepo.openmrs.org/nexus/content/repositories/public/org/apache/xbean/xbean/4.16/xbean-4.16.pom (19 kB at 80 kB/s) |
| 17-Jun-2025 22:21:58 | [INFO] Downloading from openmrs-repo: https://mavenrepo.openmrs.org/nexus/content/repositories/public/org/apache/kafka/kafka-clients/2.4.0/kafka-clients-2.4.0.pom |
| 17-Jun-2025 22:21:58 | [INFO] Downloaded from openmrs-repo: https://mavenrepo.openmrs.org/nexus/content/repositories/public/org/apache/kafka/kafka-clients/2.4.0/kafka-clients-2.4.0.pom (2.7 kB at 11 kB/s) |
| 17-Jun-2025 22:21:58 | [INFO] Downloading from openmrs-repo: https://mavenrepo.openmrs.org/nexus/content/repositories/public/com/github/luben/zstd-jni/1.4.3-1/zstd-jni-1.4.3-1.pom |
| 17-Jun-2025 22:21:58 | [INFO] Downloaded from openmrs-repo: https://mavenrepo.openmrs.org/nexus/content/repositories/public/com/github/luben/zstd-jni/1.4.3-1/zstd-jni-1.4.3-1.pom (1.9 kB at 7.0 kB/s) |
| 17-Jun-2025 22:21:58 | [INFO] Downloading from openmrs-repo: https://mavenrepo.openmrs.org/nexus/content/repositories/public/org/lz4/lz4-java/1.6.0/lz4-java-1.6.0.pom |
| 17-Jun-2025 22:21:59 | [INFO] Downloaded from openmrs-repo: https://mavenrepo.openmrs.org/nexus/content/repositories/public/org/lz4/lz4-java/1.6.0/lz4-java-1.6.0.pom (2.0 kB at 8.3 kB/s) |
| 17-Jun-2025 22:21:59 | [INFO] Downloading from openmrs-repo: https://mavenrepo.openmrs.org/nexus/content/repositories/public/com/fasterxml/jackson/jaxrs/jackson-jaxrs-json-provider/2.10.0/jackson-jaxrs-json-provider-2.10.0.pom |
| 17-Jun-2025 22:21:59 | [INFO] Downloaded from openmrs-repo: https://mavenrepo.openmrs.org/nexus/content/repositories/public/com/fasterxml/jackson/jaxrs/jackson-jaxrs-json-provider/2.10.0/jackson-jaxrs-json-provider-2.10.0.pom (4.0 kB at 14 kB/s) |
| 17-Jun-2025 22:21:59 | [INFO] Downloading from openmrs-repo: https://mavenrepo.openmrs.org/nexus/content/repositories/public/com/fasterxml/jackson/jaxrs/jackson-jaxrs-providers/2.10.0/jackson-jaxrs-providers-2.10.0.pom |
| 17-Jun-2025 22:21:59 | [INFO] Downloaded from openmrs-repo: https://mavenrepo.openmrs.org/nexus/content/repositories/public/com/fasterxml/jackson/jaxrs/jackson-jaxrs-providers/2.10.0/jackson-jaxrs-providers-2.10.0.pom (4.6 kB at 12 kB/s) |
| 17-Jun-2025 22:21:59 | [INFO] Downloading from openmrs-repo: https://mavenrepo.openmrs.org/nexus/content/repositories/public/com/fasterxml/jackson/jaxrs/jackson-jaxrs-base/2.10.0/jackson-jaxrs-base-2.10.0.pom |
| 17-Jun-2025 22:22:00 | [INFO] Downloaded from openmrs-repo: https://mavenrepo.openmrs.org/nexus/content/repositories/public/com/fasterxml/jackson/jaxrs/jackson-jaxrs-base/2.10.0/jackson-jaxrs-base-2.10.0.pom (1.7 kB at 6.7 kB/s) |
| 17-Jun-2025 22:22:00 | [INFO] Downloading from openmrs-repo: https://mavenrepo.openmrs.org/nexus/content/repositories/public/com/fasterxml/jackson/module/jackson-module-jaxb-annotations/2.10.0/jackson-module-jaxb-annotations-2.10.0.pom |
| 17-Jun-2025 22:22:00 | [INFO] Downloaded from openmrs-repo: https://mavenrepo.openmrs.org/nexus/content/repositories/public/com/fasterxml/jackson/module/jackson-module-jaxb-annotations/2.10.0/jackson-module-jaxb-annotations-2.10.0.pom (3.5 kB at 12 kB/s) |
| 17-Jun-2025 22:22:00 | [INFO] Downloading from openmrs-repo: https://mavenrepo.openmrs.org/nexus/content/repositories/public/com/fasterxml/jackson/module/jackson-modules-base/2.10.0/jackson-modules-base-2.10.0.pom |
| 17-Jun-2025 22:22:00 | [INFO] Downloaded from openmrs-repo: https://mavenrepo.openmrs.org/nexus/content/repositories/public/com/fasterxml/jackson/module/jackson-modules-base/2.10.0/jackson-modules-base-2.10.0.pom (3.2 kB at 6.9 kB/s) |
| 17-Jun-2025 22:22:00 | [INFO] Downloading from openmrs-repo: https://mavenrepo.openmrs.org/nexus/content/repositories/public/org/apache/xbean/xbean-spring/4.16/xbean-spring-4.16.jar |
| 17-Jun-2025 22:22:00 | [INFO] Downloading from openmrs-repo: https://mavenrepo.openmrs.org/nexus/content/repositories/public/org/lz4/lz4-java/1.6.0/lz4-java-1.6.0.jar |
| 17-Jun-2025 22:22:00 | [INFO] Downloading from openmrs-repo: https://mavenrepo.openmrs.org/nexus/content/repositories/public/com/github/luben/zstd-jni/1.4.3-1/zstd-jni-1.4.3-1.jar |
| 17-Jun-2025 22:22:00 | [INFO] Downloading from openmrs-repo: https://mavenrepo.openmrs.org/nexus/content/repositories/public/org/apache/kafka/kafka-clients/2.4.0/kafka-clients-2.4.0.jar |
| 17-Jun-2025 22:22:00 | [INFO] Downloading from openmrs-repo: https://mavenrepo.openmrs.org/nexus/content/repositories/public/com/fasterxml/jackson/jaxrs/jackson-jaxrs-json-provider/2.10.0/jackson-jaxrs-json-provider-2.10.0.jar |
| 17-Jun-2025 22:22:01 | [INFO] Downloaded from openmrs-repo: https://mavenrepo.openmrs.org/nexus/content/repositories/public/com/fasterxml/jackson/jaxrs/jackson-jaxrs-json-provider/2.10.0/jackson-jaxrs-json-provider-2.10.0.jar (16 kB at 40 kB/s) |
| 17-Jun-2025 22:22:01 | [INFO] Downloading from openmrs-repo: https://mavenrepo.openmrs.org/nexus/content/repositories/public/com/fasterxml/jackson/jaxrs/jackson-jaxrs-base/2.10.0/jackson-jaxrs-base-2.10.0.jar |
| 17-Jun-2025 22:22:01 | [INFO] Downloaded from openmrs-repo: https://mavenrepo.openmrs.org/nexus/content/repositories/public/org/apache/xbean/xbean-spring/4.16/xbean-spring-4.16.jar (131 kB at 209 kB/s) |
| 17-Jun-2025 22:22:01 | [INFO] Downloading from openmrs-repo: https://mavenrepo.openmrs.org/nexus/content/repositories/public/com/fasterxml/jackson/module/jackson-module-jaxb-annotations/2.10.0/jackson-module-jaxb-annotations-2.10.0.jar |
| 17-Jun-2025 22:22:01 | [INFO] Downloaded from openmrs-repo: https://mavenrepo.openmrs.org/nexus/content/repositories/public/com/fasterxml/jackson/jaxrs/jackson-jaxrs-base/2.10.0/jackson-jaxrs-base-2.10.0.jar (33 kB at 42 kB/s) |
| 17-Jun-2025 22:22:01 | [INFO] Downloading from openmrs-repo: https://mavenrepo.openmrs.org/nexus/content/repositories/public/com/fasterxml/jackson/core/jackson-databind/2.11.0/jackson-databind-2.11.0.jar |
| 17-Jun-2025 22:22:01 | [INFO] Downloaded from openmrs-repo: https://mavenrepo.openmrs.org/nexus/content/repositories/public/com/fasterxml/jackson/module/jackson-module-jaxb-annotations/2.10.0/jackson-module-jaxb-annotations-2.10.0.jar (35 kB at 39 kB/s) |
| 17-Jun-2025 22:22:01 | [INFO] Downloading from openmrs-repo: https://mavenrepo.openmrs.org/nexus/content/repositories/public/com/fasterxml/jackson/core/jackson-annotations/2.11.0/jackson-annotations-2.11.0.jar |
| 17-Jun-2025 22:22:02 | [INFO] Downloaded from openmrs-repo: https://mavenrepo.openmrs.org/nexus/content/repositories/public/org/lz4/lz4-java/1.6.0/lz4-java-1.6.0.jar (640 kB at 525 kB/s) |
| 17-Jun-2025 22:22:02 | [INFO] Downloading from openmrs-repo: https://mavenrepo.openmrs.org/nexus/content/repositories/public/com/fasterxml/jackson/core/jackson-core/2.11.0/jackson-core-2.11.0.jar |
| 17-Jun-2025 22:22:02 | [INFO] Downloaded from openmrs-repo: https://mavenrepo.openmrs.org/nexus/content/repositories/public/com/fasterxml/jackson/core/jackson-annotations/2.11.0/jackson-annotations-2.11.0.jar (68 kB at 54 kB/s) |
| 17-Jun-2025 22:22:02 | [INFO] Downloaded from openmrs-repo: https://mavenrepo.openmrs.org/nexus/content/repositories/public/org/apache/kafka/kafka-clients/2.4.0/kafka-clients-2.4.0.jar (3.3 MB at 2.3 MB/s) |
| 17-Jun-2025 22:22:02 | [INFO] Downloaded from openmrs-repo: https://mavenrepo.openmrs.org/nexus/content/repositories/public/com/fasterxml/jackson/core/jackson-core/2.11.0/jackson-core-2.11.0.jar (352 kB at 213 kB/s) |
| 17-Jun-2025 22:22:02 | [INFO] Downloaded from openmrs-repo: https://mavenrepo.openmrs.org/nexus/content/repositories/public/com/fasterxml/jackson/core/jackson-databind/2.11.0/jackson-databind-2.11.0.jar (1.4 MB at 703 kB/s) |
| 17-Jun-2025 22:22:02 | [INFO] Downloaded from openmrs-repo: https://mavenrepo.openmrs.org/nexus/content/repositories/public/com/github/luben/zstd-jni/1.4.3-1/zstd-jni-1.4.3-1.jar (4.1 MB at 2.0 MB/s) |
| 17-Jun-2025 22:22:02 | [INFO] |
| 17-Jun-2025 22:22:02 | [INFO] --- maven-clean-plugin:2.5:clean (default-clean) @ example-app --- |
| 17-Jun-2025 22:22:02 | [INFO] |
| 17-Jun-2025 22:22:02 | [INFO] --- maven-resources-plugin:2.6:resources (default-resources) @ example-app --- |
| 17-Jun-2025 22:22:02 | [WARNING] Using platform encoding (UTF-8 actually) to copy filtered resources, i.e. build is platform dependent! |
| 17-Jun-2025 22:22:02 | [INFO] Copying 2 resources |
| 17-Jun-2025 22:22:02 | [INFO] |
| 17-Jun-2025 22:22:02 | [INFO] --- maven-compiler-plugin:3.8.0:compile (default-compile) @ example-app --- |
| 17-Jun-2025 22:22:02 | [INFO] Changes detected - recompiling the module! |
| 17-Jun-2025 22:22:02 | [WARNING] File encoding has not been set, using platform encoding UTF-8, i.e. build is platform dependent! |
| 17-Jun-2025 22:22:02 | [INFO] Compiling 1 source file to /home/bamboo-agent/bamboo-agent/xml-data/build-dir/EIP-EIP0-JOB1/example-app/target/classes |
| 17-Jun-2025 22:22:03 | [INFO] |
| 17-Jun-2025 22:22:03 | [INFO] --- maven-resources-plugin:2.6:testResources (default-testResources) @ example-app --- |
| 17-Jun-2025 22:22:03 | [WARNING] Using platform encoding (UTF-8 actually) to copy filtered resources, i.e. build is platform dependent! |
| 17-Jun-2025 22:22:03 | [INFO] skip non existing resourceDirectory /home/bamboo-agent/bamboo-agent/xml-data/build-dir/EIP-EIP0-JOB1/example-app/src/test/resources |
| 17-Jun-2025 22:22:03 | [INFO] |
| 17-Jun-2025 22:22:03 | [INFO] --- maven-compiler-plugin:3.8.0:testCompile (default-testCompile) @ example-app --- |
| 17-Jun-2025 22:22:03 | [INFO] No sources to compile |
| 17-Jun-2025 22:22:03 | [INFO] |
| 17-Jun-2025 22:22:03 | [INFO] --- maven-surefire-plugin:2.12.4:test (default-test) @ example-app --- |
| 17-Jun-2025 22:22:03 | [INFO] No tests to run. |
| 17-Jun-2025 22:22:03 | [INFO] |
| 17-Jun-2025 22:22:03 | [INFO] --- maven-jar-plugin:2.4:jar (default-jar) @ example-app --- |
| 17-Jun-2025 22:22:03 | [INFO] Building jar: /home/bamboo-agent/bamboo-agent/xml-data/build-dir/EIP-EIP0-JOB1/example-app/target/example-app-3.3.0-SNAPSHOT.jar |
| 17-Jun-2025 22:22:03 | [INFO] |
| 17-Jun-2025 22:22:03 | [INFO] --- maven-install-plugin:2.4:install (default-install) @ example-app --- |
| 17-Jun-2025 22:22:03 | [INFO] Installing /home/bamboo-agent/bamboo-agent/xml-data/build-dir/EIP-EIP0-JOB1/example-app/target/example-app-3.3.0-SNAPSHOT.jar to /home/bamboo-agent/.m2/repository/org/openmrs/eip/example-app/3.3.0-SNAPSHOT/example-app-3.3.0-SNAPSHOT.jar |
| 17-Jun-2025 22:22:03 | [INFO] Installing /home/bamboo-agent/bamboo-agent/xml-data/build-dir/EIP-EIP0-JOB1/example-app/pom.xml to /home/bamboo-agent/.m2/repository/org/openmrs/eip/example-app/3.3.0-SNAPSHOT/example-app-3.3.0-SNAPSHOT.pom |
| 17-Jun-2025 22:22:03 | [INFO] |
| 17-Jun-2025 22:22:03 | [INFO] --- maven-deploy-plugin:2.8.2:deploy (default-deploy) @ example-app --- |
| 17-Jun-2025 22:22:03 | [INFO] Skipping artifact deployment |
| 17-Jun-2025 22:22:03 | [INFO] ------------------------------------------------------------------------ |
| 17-Jun-2025 22:22:03 | [INFO] Reactor Summary for OpenMRS EIP 3.3.0-SNAPSHOT: |
| 17-Jun-2025 22:22:03 | [INFO] |
| 17-Jun-2025 22:22:03 | [INFO] OpenMRS EIP ........................................ SUCCESS [ 52.500 s] |
| 17-Jun-2025 22:22:03 | [INFO] OpenMRS EIP Commons ................................ SUCCESS [01:02 min] |
| 17-Jun-2025 22:22:03 | [INFO] OpenMRS EIP Watcher ................................ SUCCESS [02:06 min] |
| 17-Jun-2025 22:22:03 | [INFO] OpenMRS EIP Commons Web ............................ SUCCESS [ 9.204 s] |
| 17-Jun-2025 22:22:03 | [INFO] OpenMRS EIP Example App ............................ SUCCESS [ 7.048 s] |
| 17-Jun-2025 22:22:03 | [INFO] ------------------------------------------------------------------------ |
| 17-Jun-2025 22:22:03 | [INFO] BUILD SUCCESS |
| 17-Jun-2025 22:22:03 | [INFO] ------------------------------------------------------------------------ |
| 17-Jun-2025 22:22:03 | [INFO] Total time: 04:21 min |
| 17-Jun-2025 22:22:03 | [INFO] Finished at: 2025-06-17T22:22:03Z |
| 17-Jun-2025 22:22:03 | [INFO] ------------------------------------------------------------------------ |
| 17-Jun-2025 22:22:03 | Parsing test results under /home/bamboo-agent/bamboo-agent/xml-data/build-dir/EIP-EIP0-JOB1... |
| 17-Jun-2025 22:22:03 | Finished task 'Deploy' with result: Success |
| 17-Jun-2025 22:22:03 | Running post build plugin 'Docker Container Cleanup' |
| 17-Jun-2025 22:22:03 | Running post build plugin 'NCover Results Collector' |
| 17-Jun-2025 22:22:03 | Running post build plugin 'Build Results Label Collector' |
| 17-Jun-2025 22:22:03 | Running post build plugin 'Clover Results Collector' |
| 17-Jun-2025 22:22:03 | Running post build plugin 'npm Cache Cleanup' |
| 17-Jun-2025 22:22:03 | Running post build plugin 'Artifact Copier' |
| 17-Jun-2025 22:22:03 | Finalising the build... |
| 17-Jun-2025 22:22:03 | Stopping timer. |
| 17-Jun-2025 22:22:03 | Build EIP-EIP0-JOB1-2 completed. |
| 17-Jun-2025 22:22:03 | Running on server: post build plugin 'Build Hanging Detection Configuration' |
| 17-Jun-2025 22:22:03 | Running on server: post build plugin 'NCover Results Collector' |
| 17-Jun-2025 22:22:03 | Running on server: post build plugin 'Build Labeller' |
| 17-Jun-2025 22:22:03 | Running on server: post build plugin 'Clover Delta Calculator' |
| 17-Jun-2025 22:22:03 | Running on server: post build plugin 'Maven Dependencies Postprocessor' |
| 17-Jun-2025 22:22:03 | All post build plugins have finished |
| 17-Jun-2025 22:22:03 | Generating build results summary... |
| 17-Jun-2025 22:22:03 | Saving build results to disk... |
| 17-Jun-2025 22:22:03 | Store variable context... |
| 17-Jun-2025 22:22:03 | Finished building EIP-EIP0-JOB1-2. |