microservices-patterns / ftgo-application

Example code for the book Microservice patterns
Other
3.41k stars 1.32k forks source link

ComponentTest for Order Service fails and subsequently created orders are broken (created in APPROVAL_PENDING status) #71

Open asn25 opened 4 years ago

asn25 commented 4 years ago

Hi,

consider running of ComponentTest for Order Service - OrderServiceComponentTest (all services are up, running the test from IDE):

1) Run OrderServiceComponentTest

2) After that order creation process is broken. F.e., if I run EndToEndTest with only createOrder()-block in test-method, it will create order in APPROVAL_PENDING status and the test itself fails.

3) To restore normal order creation, full system restart is needed (docker-compose down & up).

====

ftgo-order-service_1 | 2020-01-01T12:04:50.649512809Z 2020-01-01 12:04:50.648 DEBUG [ftgo-order-service,,,] 1 --- [erServiceEvents] i.e.m.k.b.c.EventuateKafkaConsumer : Got orderServiceEvents 1 records ftgo-order-service_1 | 2020-01-01T12:04:50.650795193Z 2020-01-01 12:04:50.650 DEBUG [ftgo-order-service,,,] 1 --- [erServiceEvents] i.e.m.k.b.c.EventuateKafkaConsumer : processing record orderServiceEvents 0 {"payload":"{\"name\":\"Ajanta\",\"menu\":{\"menuItems\":[{\"id\":\"1\",\"nam e\":\"Chicken Vindaloo\",\"price\":\"12.34\"}]}}","headers":{"PARTITION_ID":"1","event-aggregate-type":"net.chrisrichardson.ftgo.restaurantservice.domain.Restaurant","DATE":"Wed, 1 Jan 2020 12:04:45 GMT","event-aggregate-id":"1","X-B3-SpanId":"efe31874ce01f8d7","event-type":"net.chrisrichardson.ftgo .restaurantservice.events.RestaurantCreated","DESTINATION":"net.chrisrichardson.ftgo.restaurantservice.domain.Restaurant","X-B3-Sampled":"1","X-B3-TraceId":"efe31874ce01f8d7","ID":"0000016f60fe72a7-14dae921faa80000"}} ftgo-order-service_1 | 2020-01-01T12:04:50.651632662Z 2020-01-01 12:04:50.651 DEBUG [ftgo-order-service,,,] 1 --- [erServiceEvents] i.e.m.k.b.c.EventuateKafkaConsumer : EventuateKafkaAggregateSubscriptions subscriber = orderServiceEvents, offset = 0, key = 1, value = {"payload":"{\"na me\":\"Ajanta\",\"menu\":{\"menuItems\":[{\"id\":\"1\",\"name\":\"Chicken Vindaloo\",\"price\":\"12.34\"}]}}","headers":{"PARTITION_ID":"1","event-aggregate-type":"net.chrisrichardson.ftgo.restaurantservice.domain.Restaurant","DATE":"Wed, 1 Jan 2020 12:04:45 GMT","event-aggregate-id":"1","X-B3-SpanI d":"efe31874ce01f8d7","event-type":"net.chrisrichardson.ftgo.restaurantservice.events.RestaurantCreated","DESTINATION":"net.chrisrichardson.ftgo.restaurantservice.domain.Restaurant","X-B3-Sampled":"1","X-B3-TraceId":"efe31874ce01f8d7","ID":"0000016f60fe72a7-14dae921faa80000"}} ftgo-order-service_1 | 2020-01-01T12:04:50.664245365Z 2020-01-01 12:04:50.663 DEBUG [ftgo-order-service,,,] 1 --- [erServiceEvents] i.e.m.k.b.c.EventuateKafkaConsumer : Processed orderServiceEvents 1 records ftgo-order-service_1 | 2020-01-01T12:04:50.701460064Z 2020-01-01 12:04:50.664 DEBUG [ftgo-order-service,,,] 1 --- [erServiceEvents] i.e.m.k.b.c.EventuateKafkaConsumer : To commit orderServiceEvents io.eventuate.messaging.kafka.basic.consumer.OffsetTracker@2248a26f[state={net.chrisrich ardson.ftgo.restaurantservice.domain.Restaurant-1=io.eventuate.messaging.kafka.basic.consumer.TopicPartitionOffsets@7b3fdaca[unprocessed=[0],processed=[]]}] ftgo-order-service_1 | 2020-01-01T12:04:50.861748379Z 2020-01-01 12:04:50.861 TRACE [ftgo-order-service,efe31874ce01f8d7,5ce83468dcb9abe9,true] 1 --- [pool-5-thread-1] i.e.t.c.c.DecoratedMessageHandlerFactory : Invoking handler orderServiceEvents 0000016f60fe72a7-14dae921faa80000 ftgo-order-service1 | 2020-01-01T12:04:51.000709484Z 2020-01-01 12:04:50.998 DEBUG [ftgo-order-service,efe31874ce01f8d7,5ce83468dcb9abe9,true] 1 --- [pool-5-thread-1] org.hibernate.SQL : select restaurant0.id as id1_20, restaurant0_.name as name2_20 from order_s ervicerestaurants restaurant0 where restaurant0_.id=? ftgo-order-service_1 | 2020-01-01T12:04:51.046888070Z 2020-01-01 12:04:51.046 TRACE [ftgo-order-service,efe31874ce01f8d7,5ce83468dcb9abe9,true] 1 --- [pool-5-thread-1] i.e.t.c.c.DecoratedMessageHandlerFactory : handled message orderServiceEvents 0000016f60fe72a7-14dae921faa80000 ftgo-order-service_1 | 2020-01-01T12:04:51.091850889Z 2020-01-01 12:04:51.091 DEBUG [ftgo-order-service,,,] 1 --- [pool-5-thread-1] org.hibernate.SQL : insert into order_service_restaurants (name, id) values (?, ?) ftgo-order-service_1 | 2020-01-01T12:04:51.112033279Z 2020-01-01 12:04:51.111 DEBUG [ftgo-order-service,,,] 1 --- [pool-5-thread-1] org.hibernate.SQL : insert into order_service_restaurant_menu_items (restaurant_id, id, name, amount) values (?, ?, ?, ?) ftgo-order-service_1 | 2020-01-01T12:04:51.137100360Z 2020-01-01 12:04:51.136 DEBUG [ftgo-order-service,,,] 1 --- [pool-5-thread-1] i.e.m.k.b.c.KafkaMessageProcessor : Adding processed record to queue orderServiceEvents 0 ftgo-order-service_1 | 2020-01-01T12:04:51.210928190Z 2020-01-01 12:04:51.210 DEBUG [ftgo-order-service,,,] 1 --- [erServiceEvents] i.e.m.k.b.c.EventuateKafkaConsumer : Committing offsets orderServiceEvents {net.chrisrichardson.ftgo.restaurantservice.domain.Restaurant-1=OffsetAndMetad ata{offset=1, metadata=''}} ftgo-order-service_1 | 2020-01-01T12:04:51.219510911Z 2020-01-01 12:04:51.219 DEBUG [ftgo-order-service,,,] 1 --- [erServiceEvents] i.e.m.k.b.c.EventuateKafkaConsumer : Committed offsets orderServiceEvents ftgo-order-service1 | 2020-01-01T12:04:53.924716020Z 2020-01-01 12:04:53.922 DEBUG [ftgo-order-service,571383ec71cf5e1b,571383ec71cf5e1b,true] 1 --- [nio-8080-exec-3] org.hibernate.SQL : select restaurant0.id as id1_20, restaurant0_.name as name2_20 from order_s ervicerestaurants restaurant0 where restaurant0_.id=? ftgo-order-service1 | 2020-01-01T12:04:54.008710556Z 2020-01-01 12:04:54.007 DEBUG [ftgo-order-service,571383ec71cf5e1b,571383ec71cf5e1b,true] 1 --- [nio-8080-exec-3] org.hibernate.SQL : select menuitems0.restaurant_id as restaura1_10, menuitems0_.id as id2_10, menuitems0_.name as name3_10, menuitems0_.amount as amount4_10 from order_service_restaurant_menuitems menuitems0 where menuitems0_.restaurant_id=? ftgo-order-service_1 | 2020-01-01T12:04:54.113915798Z 2020-01-01 12:04:54.113 DEBUG [ftgo-order-service,571383ec71cf5e1b,571383ec71cf5e1b,true] 1 --- [nio-8080-exec-3] org.hibernate.SQL : select next_val as id_val from hibernate_sequence for update ftgo-order-service_1 | 2020-01-01T12:04:54.118054321Z 2020-01-01 12:04:54.117 DEBUG [ftgo-order-service,571383ec71cf5e1b,571383ec71cf5e1b,true] 1 --- [nio-8080-exec-3] org.hibernate.SQL : update hibernate_sequence set next_val= ? where next_val=? ftgo-order-service_1 | 2020-01-01T12:04:54.182487835Z 2020-01-01 12:04:54.180 INFO [ftgo-order-service,571383ec71cf5e1b,571383ec71cf5e1b,true] 1 --- [nio-8080-exec-3] i.e.t.s.o.SagaInstanceRepositoryJdbc : Saving net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga 0 000016f60fe9323-0242ac13000b0000 ftgo-order-service_1 | 2020-01-01T12:04:54.196534343Z 2020-01-01 12:04:54.196 DEBUG [ftgo-order-service,571383ec71cf5e1b,571383ec71cf5e1b,true] 1 --- [nio-8080-exec-3] i.e.t.sagas.common.SagaLockManagerImpl : Saga net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga 000 0016f60fe9323-0242ac13000b0000 has locked net.chrisrichardson.ftgo.orderservice.domain.Order/1 ftgo-order-service_1 | 2020-01-01T12:04:54.218111644Z 2020-01-01 12:04:54.217 INFO [ftgo-order-service,571383ec71cf5e1b,571383ec71cf5e1b,true] 1 --- [nio-8080-exec-3] i.e.t.s.o.SagaInstanceRepositoryJdbc : Updating net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga 0000016f60fe9323-0242ac13000b0000 ftgo-order-service_1 | 2020-01-01T12:04:54.227249164Z 2020-01-01 12:04:54.226 DEBUG [ftgo-order-service,571383ec71cf5e1b,571383ec71cf5e1b,true] 1 --- [nio-8080-exec-3] org.hibernate.SQL : insert into orders (consumer_id, city, delivery_state, street1, street2, zip, de livery_time, amount, payment_token, restaurant_id, state, version, id) values (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?) ftgo-order-service_1 | 2020-01-01T12:04:54.236274853Z 2020-01-01 12:04:54.235 DEBUG [ftgo-order-service,571383ec71cf5e1b,571383ec71cf5e1b,true] 1 --- [nio-8080-exec-3] org.hibernate.SQL : insert into order_line_items (order_id, menu_item_id, name, price, quantity) val ues (?, ?, ?, ?, ?) ftgo-order-service_1 | 2020-01-01T12:04:54.508040043Z 2020-01-01 12:04:54.507 DEBUG [ftgo-order-service,,,] 1 --- [erSaga-consumer] i.e.m.k.b.c.EventuateKafkaConsumer : Got net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-consumer 1 records ftgo-order-service_1 | 2020-01-01T12:04:54.508109842Z 2020-01-01 12:04:54.507 DEBUG [ftgo-order-service,,,] 1 --- [erSaga-consumer] i.e.m.k.b.c.EventuateKafkaConsumer : processing record net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-consumer 0 {"payload":"{}" ,"headers":{"reply_outcome-type":"SUCCESS","commandreply_type":"net.chrisrichardson.ftgo.consumerservice.api.ValidateOrderByConsumer","commandreply_saga_type":"net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga","X-B3-ParentSpanId":"589a58f36d1050fd","reply_type":"io.eventuate.t ram.commands.common.Success","reply_to_message_id":"0000016f60fe9344-0242ac13000b0000","commandreply_saga_id":"0000016f60fe9323-0242ac13000b0000","DATE":"Wed, 1 Jan 2020 12:04:51 GMT","commandreplydestination":"consumerService","commandreply_reply_to":"net.chrisrichardson.ftgo.orderservice.sagas.c reateorder.CreateOrderSaga-reply","X-B3-SpanId":"06429063ce64af8d","DESTINATION":"net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-reply","X-B3-Sampled":"1","X-B3-TraceId":"571383ec71cf5e1b","ID":"0000016f60fe87b9-14dae921faa80000"}} ftgo-order-service_1 | 2020-01-01T12:04:54.508811277Z 2020-01-01 12:04:54.508 DEBUG [ftgo-order-service,,,] 1 --- [erSaga-consumer] i.e.m.k.b.c.EventuateKafkaConsumer : EventuateKafkaAggregateSubscriptions subscriber = net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrde rSaga-consumer, offset = 0, key = 0000016f60fe87b9-14dae921faa80000, value = {"payload":"{}","headers":{"reply_outcome-type":"SUCCESS","commandreply_type":"net.chrisrichardson.ftgo.consumerservice.api.ValidateOrderByConsumer","commandreply_saga_type":"net.chrisrichardson.ftgo.orderservice.sagas.crea teorder.CreateOrderSaga","X-B3-ParentSpanId":"589a58f36d1050fd","reply_type":"io.eventuate.tram.commands.common.Success","reply_to_message_id":"0000016f60fe9344-0242ac13000b0000","commandreply_saga_id":"0000016f60fe9323-0242ac13000b0000","DATE":"Wed, 1 Jan 2020 12:04:51 GMT","commandreplydestinati on":"consumerService","commandreply_reply_to":"net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-reply","X-B3-SpanId":"06429063ce64af8d","DESTINATION":"net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-reply","X-B3-Sampled":"1","X-B3-TraceId":"571383ec71cf 5e1b","ID":"0000016f60fe87b9-14dae921faa80000"}} ftgo-order-service_1 | 2020-01-01T12:04:54.509599712Z 2020-01-01 12:04:54.509 DEBUG [ftgo-order-service,,,] 1 --- [erSaga-consumer] i.e.m.k.b.c.EventuateKafkaConsumer : Processed net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-consumer 1 records ftgo-order-service_1 | 2020-01-01T12:04:54.510352190Z 2020-01-01 12:04:54.509 DEBUG [ftgo-order-service,,,] 1 --- [erSaga-consumer] i.e.m.k.b.c.EventuateKafkaConsumer : To commit net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-consumer io.eventuate.messaging.ka fka.basic.consumer.OffsetTracker@ce87c5a[state={net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-reply-0=io.eventuate.messaging.kafka.basic.consumer.TopicPartitionOffsets@1599fd9a[unprocessed=[0],processed=[]]}] ftgo-order-service_1 | 2020-01-01T12:04:54.518602560Z 2020-01-01 12:04:54.517 TRACE [ftgo-order-service,571383ec71cf5e1b,b27a1e201aaca0c8,true] 1 --- [pool-1-thread-1] i.e.t.c.c.DecoratedMessageHandlerFactory : Invoking handler net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateO rderSaga-consumer 0000016f60fe87b9-14dae921faa80000 ftgo-order-service_1 | 2020-01-01T12:04:54.527444665Z 2020-01-01 12:04:54.522 DEBUG [ftgo-order-service,571383ec71cf5e1b,b27a1e201aaca0c8,true] 1 --- [pool-1-thread-1] i.e.t.s.orchestration.SagaManagerImpl : handle message invoked io.eventuate.tram.messaging.common.MessageImpl@27e01452[p ayload={},headers={reply_outcome-type=SUCCESS, commandreply_type=net.chrisrichardson.ftgo.consumerservice.api.ValidateOrderByConsumer, commandreply_saga_type=net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga, reply_type=io.eventuate.tram.commands.common.Success, reply_to_messag e_id=0000016f60fe9344-0242ac13000b0000, commandreply_saga_id=0000016f60fe9323-0242ac13000b0000, DATE=Wed, 1 Jan 2020 12:04:51 GMT, commandreplydestination=consumerService, commandreply_reply_to=net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-reply, DESTINATION=net.chrisrich ardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-reply, ID=0000016f60fe87b9-14dae921faa80000, X-B3-TraceId=571383ec71cf5e1b, X-B3-SpanId=b27a1e201aaca0c8, X-B3-ParentSpanId=06429063ce64af8d, X-B3-Sampled=1}] ftgo-order-service_1 | 2020-01-01T12:04:54.537230367Z 2020-01-01 12:04:54.536 DEBUG [ftgo-order-service,571383ec71cf5e1b,b27a1e201aaca0c8,true] 1 --- [pool-1-thread-1] i.e.t.s.orchestration.SagaManagerImpl : Handle reply: io.eventuate.tram.messaging.common.MessageImpl@27e01452[payload={} ,headers={reply_outcome-type=SUCCESS, commandreply_type=net.chrisrichardson.ftgo.consumerservice.api.ValidateOrderByConsumer, commandreply_saga_type=net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga, reply_type=io.eventuate.tram.commands.common.Success, reply_to_message_id=0000 016f60fe9344-0242ac13000b0000, commandreply_saga_id=0000016f60fe9323-0242ac13000b0000, DATE=Wed, 1 Jan 2020 12:04:51 GMT, commandreplydestination=consumerService, commandreply_reply_to=net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-reply, DESTINATION=net.chrisrichardson.ft go.orderservice.sagas.createorder.CreateOrderSaga-reply, ID=0000016f60fe87b9-14dae921faa80000, X-B3-TraceId=571383ec71cf5e1b, X-B3-SpanId=b27a1e201aaca0c8, X-B3-ParentSpanId=06429063ce64af8d, X-B3-Sampled=1}] ftgo-order-service_1 | 2020-01-01T12:04:54.542337994Z 2020-01-01 12:04:54.537 INFO [ftgo-order-service,571383ec71cf5e1b,b27a1e201aaca0c8,true] 1 --- [pool-1-thread-1] i.e.t.s.o.SagaInstanceRepositoryJdbc : finding net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga 0000016f60fe9323-0242ac13000b0000 ftgo-order-service_1 | 2020-01-01T12:04:54.611420438Z 2020-01-01 12:04:54.610 INFO [ftgo-order-service,571383ec71cf5e1b,b27a1e201aaca0c8,true] 1 --- [pool-1-thread-1] i.e.t.s.orchestration.SagaManagerImpl : Current state={"currentlyExecuting":1,"compensating":false,"endState":false} ftgo-order-service_1 | 2020-01-01T12:04:54.638332732Z 2020-01-01 12:04:54.637 INFO [ftgo-order-service,571383ec71cf5e1b,b27a1e201aaca0c8,true] 1 --- [pool-1-thread-1] i.e.t.s.orchestration.SagaManagerImpl : Handled reply. Sending commands [io.eventuate.tram.commands.consumer.CommandWith Destination@c25baee[destinationChannel=kitchenService,resource=,command=net.chrisrichardson.ftgo.kitchenservice.api.CreateTicket@1643501c[orderId=1,ticketDetails=net.chrisrichardson.ftgo.kitchenservice.api.TicketDetails@647f51cd[lineItems=[net.chrisrichardson.ftgo.kitchenservice.api.TicketLine Item@2d0d885]],restaurantId=1]]] ftgo-order-service_1 | 2020-01-01T12:04:54.659623604Z 2020-01-01 12:04:54.659 INFO [ftgo-order-service,571383ec71cf5e1b,b27a1e201aaca0c8,true] 1 --- [pool-1-thread-1] i.e.t.s.o.SagaInstanceRepositoryJdbc : Updating net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga 0000016f60fe9323-0242ac13000b0000 ftgo-order-service_1 | 2020-01-01T12:04:54.662632119Z 2020-01-01 12:04:54.661 TRACE [ftgo-order-service,571383ec71cf5e1b,b27a1e201aaca0c8,true] 1 --- [pool-1-thread-1] i.e.t.c.c.DecoratedMessageHandlerFactory : handled message net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOr derSaga-consumer 0000016f60fe87b9-14dae921faa80000 ftgo-order-service_1 | 2020-01-01T12:04:54.680712863Z 2020-01-01 12:04:54.680 DEBUG [ftgo-order-service,,,] 1 --- [pool-1-thread-1] i.e.m.k.b.c.KafkaMessageProcessor : Adding processed record to queue net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-consumer 0 ftgo-order-service_1 | 2020-01-01T12:04:54.714331159Z 2020-01-01 12:04:54.713 DEBUG [ftgo-order-service,,,] 1 --- [erSaga-consumer] i.e.m.k.b.c.EventuateKafkaConsumer : Committing offsets net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-consumer {net.chrisrichar dson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-reply-0=OffsetAndMetadata{offset=1, metadata=''}} ftgo-order-service_1 | 2020-01-01T12:04:54.750000157Z 2020-01-01 12:04:54.746 DEBUG [ftgo-order-service,,,] 1 --- [erSaga-consumer] i.e.m.k.b.c.EventuateKafkaConsumer : Committed offsets net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-consumer ftgo-order-service_1 | 2020-01-01T12:04:54.801229180Z 2020-01-01 12:04:54.800 DEBUG [ftgo-order-service,,,] 1 --- [erSaga-consumer] i.e.m.k.b.c.EventuateKafkaConsumer : Got net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-consumer 1 records ftgo-order-service_1 | 2020-01-01T12:04:54.802626060Z 2020-01-01 12:04:54.801 DEBUG [ftgo-order-service,,,] 1 --- [erSaga-consumer] i.e.m.k.b.c.EventuateKafkaConsumer : processing record net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-consumer 0 {"payload":"{\" ticketId\":1}","headers":{"reply_outcome-type":"SUCCESS","commandreply_type":"net.chrisrichardson.ftgo.kitchenservice.api.CreateTicket","commandreply_saga_type":"net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga","X-B3-ParentSpanId":"6dea07981ad02773","reply_type":"net.chrisric hardson.ftgo.kitchenservice.api.CreateTicketReply","reply_to_message_id":"0000016f60fe94fe-0242ac13000b0000","commandreply_saga_id":"0000016f60fe9323-0242ac13000b0000","DATE":"Wed, 1 Jan 2020 12:04:51 GMT","commandreplydestination":"kitchenService","commandreply_reply_to":"net.chrisrichardson.ftgo .orderservice.sagas.createorder.CreateOrderSaga-reply","X-B3-SpanId":"786af267295f3ee1","DESTINATION":"net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-reply","X-B3-Sampled":"1","X-B3-TraceId":"571383ec71cf5e1b","ID":"0000016f60fe88d4-14dae921faa80000"}} ftgo-order-service_1 | 2020-01-01T12:04:54.805790784Z 2020-01-01 12:04:54.805 DEBUG [ftgo-order-service,,,] 1 --- [erSaga-consumer] i.e.m.k.b.c.EventuateKafkaConsumer : EventuateKafkaAggregateSubscriptions subscriber = net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrde rSaga-consumer, offset = 0, key = 0000016f60fe88d4-14dae921faa80000, value = {"payload":"{\"ticketId\":1}","headers":{"reply_outcome-type":"SUCCESS","commandreply_type":"net.chrisrichardson.ftgo.kitchenservice.api.CreateTicket","commandreply_saga_type":"net.chrisrichardson.ftgo.orderservice.sagas.cr eateorder.CreateOrderSaga","X-B3-ParentSpanId":"6dea07981ad02773","reply_type":"net.chrisrichardson.ftgo.kitchenservice.api.CreateTicketReply","reply_to_message_id":"0000016f60fe94fe-0242ac13000b0000","commandreply_saga_id":"0000016f60fe9323-0242ac13000b0000","DATE":"Wed, 1 Jan 2020 12:04:51 GMT","c ommandreplydestination":"kitchenService","commandreply_reply_to":"net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-reply","X-B3-SpanId":"786af267295f3ee1","DESTINATION":"net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-reply","X-B3-Sampled":"1","X-B3-T raceId":"571383ec71cf5e1b","ID":"0000016f60fe88d4-14dae921faa80000"}} ftgo-order-service_1 | 2020-01-01T12:04:54.807731078Z 2020-01-01 12:04:54.807 DEBUG [ftgo-order-service,,,] 1 --- [erSaga-consumer] i.e.m.k.b.c.EventuateKafkaConsumer : Processed net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-consumer 1 records ftgo-order-service_1 | 2020-01-01T12:04:54.812740701Z 2020-01-01 12:04:54.807 DEBUG [ftgo-order-service,,,] 1 --- [erSaga-consumer] i.e.m.k.b.c.EventuateKafkaConsumer : To commit net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-consumer io.eventuate.messaging.ka fka.basic.consumer.OffsetTracker@ce87c5a[state={net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-reply-0=io.eventuate.messaging.kafka.basic.consumer.TopicPartitionOffsets@1599fd9a[unprocessed=[],processed=[]], net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrder Saga-reply-1=io.eventuate.messaging.kafka.basic.consumer.TopicPartitionOffsets@1fece3c3[unprocessed=[0],processed=[]]}] ftgo-order-service_1 | 2020-01-01T12:04:54.821870440Z 2020-01-01 12:04:54.821 TRACE [ftgo-order-service,571383ec71cf5e1b,d69e05f3d80db0a9,true] 1 --- [pool-1-thread-1] i.e.t.c.c.DecoratedMessageHandlerFactory : Invoking handler net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateO rderSaga-consumer 0000016f60fe88d4-14dae921faa80000 ftgo-order-service_1 | 2020-01-01T12:04:54.838217041Z 2020-01-01 12:04:54.835 DEBUG [ftgo-order-service,571383ec71cf5e1b,d69e05f3d80db0a9,true] 1 --- [pool-1-thread-1] i.e.t.s.orchestration.SagaManagerImpl : handle message invoked io.eventuate.tram.messaging.common.MessageImpl@297c2bde[p ayload={"ticketId":1},headers={reply_outcome-type=SUCCESS, commandreply_type=net.chrisrichardson.ftgo.kitchenservice.api.CreateTicket, commandreply_saga_type=net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga, reply_type=net.chrisrichardson.ftgo.kitchenservice.api.CreateTicketRe ply, reply_to_message_id=0000016f60fe94fe-0242ac13000b0000, commandreply_saga_id=0000016f60fe9323-0242ac13000b0000, DATE=Wed, 1 Jan 2020 12:04:51 GMT, commandreplydestination=kitchenService, commandreply_reply_to=net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-reply, DESTIN ATION=net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-reply, ID=0000016f60fe88d4-14dae921faa80000, X-B3-TraceId=571383ec71cf5e1b, X-B3-SpanId=d69e05f3d80db0a9, X-B3-ParentSpanId=786af267295f3ee1, X-B3-Sampled=1}] ftgo-order-service_1 | 2020-01-01T12:04:54.840836612Z 2020-01-01 12:04:54.838 DEBUG [ftgo-order-service,571383ec71cf5e1b,d69e05f3d80db0a9,true] 1 --- [pool-1-thread-1] i.e.t.s.orchestration.SagaManagerImpl : Handle reply: io.eventuate.tram.messaging.common.MessageImpl@297c2bde[payload={" ticketId":1},headers={reply_outcome-type=SUCCESS, commandreply_type=net.chrisrichardson.ftgo.kitchenservice.api.CreateTicket, commandreply_saga_type=net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga, reply_type=net.chrisrichardson.ftgo.kitchenservice.api.CreateTicketReply, repl y_to_message_id=0000016f60fe94fe-0242ac13000b0000, commandreply_saga_id=0000016f60fe9323-0242ac13000b0000, DATE=Wed, 1 Jan 2020 12:04:51 GMT, commandreplydestination=kitchenService, commandreply_reply_to=net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-reply, DESTINATION=net .chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-reply, ID=0000016f60fe88d4-14dae921faa80000, X-B3-TraceId=571383ec71cf5e1b, X-B3-SpanId=d69e05f3d80db0a9, X-B3-ParentSpanId=786af267295f3ee1, X-B3-Sampled=1}] ftgo-order-service_1 | 2020-01-01T12:04:54.842584443Z 2020-01-01 12:04:54.841 INFO [ftgo-order-service,571383ec71cf5e1b,d69e05f3d80db0a9,true] 1 --- [pool-1-thread-1] i.e.t.s.o.SagaInstanceRepositoryJdbc : finding net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga 0000016f60fe9323-0242ac13000b0000 ftgo-order-service_1 | 2020-01-01T12:04:54.874653491Z 2020-01-01 12:04:54.869 INFO [ftgo-order-service,571383ec71cf5e1b,d69e05f3d80db0a9,true] 1 --- [pool-1-thread-1] i.e.t.s.orchestration.SagaManagerImpl : Current state={"currentlyExecuting":2,"compensating":false,"endState":false} ftgo-order-service_1 | 2020-01-01T12:04:54.886809347Z 2020-01-01 12:04:54.886 DEBUG [ftgo-order-service,571383ec71cf5e1b,d69e05f3d80db0a9,true] 1 --- [pool-1-thread-1] n.c.f.o.s.c.CreateOrderSagaState : getTicketId 1 ftgo-order-service_1 | 2020-01-01T12:04:54.925576295Z 2020-01-01 12:04:54.896 INFO [ftgo-order-service,571383ec71cf5e1b,d69e05f3d80db0a9,true] 1 --- [pool-1-thread-1] i.e.t.s.orchestration.SagaManagerImpl : Handled reply. Sending commands [io.eventuate.tram.commands.consumer.CommandWith Destination@5517cfa4[destinationChannel=accountingService,resource=,command=net.chrisrichardson.ftgo.accountservice.api.AuthorizeCommand@1d7d2c03]] ftgo-order-service_1 | 2020-01-01T12:04:54.987595108Z 2020-01-01 12:04:54.986 INFO [ftgo-order-service,571383ec71cf5e1b,d69e05f3d80db0a9,true] 1 --- [pool-1-thread-1] i.e.t.s.o.SagaInstanceRepositoryJdbc : Updating net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga 0000016f60fe9323-0242ac13000b0000 ftgo-order-service_1 | 2020-01-01T12:04:55.021395856Z 2020-01-01 12:04:55.013 TRACE [ftgo-order-service,571383ec71cf5e1b,d69e05f3d80db0a9,true] 1 --- [pool-1-thread-1] i.e.t.c.c.DecoratedMessageHandlerFactory : handled message net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOr derSaga-consumer 0000016f60fe88d4-14dae921faa80000 ftgo-order-service_1 | 2020-01-01T12:04:55.048742650Z 2020-01-01 12:04:55.043 DEBUG [ftgo-order-service,,,] 1 --- [pool-1-thread-1] i.e.m.k.b.c.KafkaMessageProcessor : Adding processed record to queue net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-consumer 0 ftgo-order-service_1 | 2020-01-01T12:04:55.143267619Z 2020-01-01 12:04:55.142 DEBUG [ftgo-order-service,,,] 1 --- [erSaga-consumer] i.e.m.k.b.c.EventuateKafkaConsumer : Committing offsets net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-consumer {net.chrisrichar dson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-reply-1=OffsetAndMetadata{offset=1, metadata=''}} ftgo-order-service_1 | 2020-01-01T12:04:55.152835328Z 2020-01-01 12:04:55.152 DEBUG [ftgo-order-service,,,] 1 --- [erSaga-consumer] i.e.m.k.b.c.EventuateKafkaConsumer : Committed offsets net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-consumer ftgo-order-service_1 | 2020-01-01T12:04:55.162473897Z 2020-01-01 12:04:55.160 DEBUG [ftgo-order-service,,,] 1 --- [erSaga-consumer] i.e.m.k.b.c.EventuateKafkaConsumer : Got net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-consumer 1 records ftgo-order-service_1 | 2020-01-01T12:04:55.163512806Z 2020-01-01 12:04:55.162 DEBUG [ftgo-order-service,,,] 1 --- [erSaga-consumer] i.e.m.k.b.c.EventuateKafkaConsumer : processing record net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-consumer 1 {"payload":"{}" ,"headers":{"reply_outcome-type":"SUCCESS","commandreply_type":"net.chrisrichardson.ftgo.accountservice.api.AuthorizeCommand","commandreply_saga_type":"net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga","X-B3-ParentSpanId":"f314ff599a25151f","reply_type":"io.eventuate.tram.comm ands.common.Success","reply_to_message_id":"0000016f60fe9628-0242ac13000b0000","commandreply_saga_id":"0000016f60fe9323-0242ac13000b0000","DATE":"Wed, 1 Jan 2020 12:04:51 GMT","commandreplydestination":"accountingService","commandreply_reply_to":"net.chrisrichardson.ftgo.orderservice.sagas.createo rder.CreateOrderSaga-reply","X-B3-SpanId":"a6ad9788926b70fa","DESTINATION":"net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-reply","X-B3-Sampled":"1","X-B3-TraceId":"571383ec71cf5e1b","ID":"0000016f60fe8a3e-14dae921faa80000"}} ftgo-order-service_1 | 2020-01-01T12:04:55.169581421Z 2020-01-01 12:04:55.163 DEBUG [ftgo-order-service,,,] 1 --- [erSaga-consumer] i.e.m.k.b.c.EventuateKafkaConsumer : EventuateKafkaAggregateSubscriptions subscriber = net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrde rSaga-consumer, offset = 1, key = 0000016f60fe8a3e-14dae921faa80000, value = {"payload":"{}","headers":{"reply_outcome-type":"SUCCESS","commandreply_type":"net.chrisrichardson.ftgo.accountservice.api.AuthorizeCommand","commandreply_saga_type":"net.chrisrichardson.ftgo.orderservice.sagas.createorder. CreateOrderSaga","X-B3-ParentSpanId":"f314ff599a25151f","reply_type":"io.eventuate.tram.commands.common.Success","reply_to_message_id":"0000016f60fe9628-0242ac13000b0000","commandreply_saga_id":"0000016f60fe9323-0242ac13000b0000","DATE":"Wed, 1 Jan 2020 12:04:51 GMT","commandreplydestination":"acc ountingService","commandreply_reply_to":"net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-reply","X-B3-SpanId":"a6ad9788926b70fa","DESTINATION":"net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-reply","X-B3-Sampled":"1","X-B3-TraceId":"571383ec71cf5e1b", "ID":"0000016f60fe8a3e-14dae921faa80000"}} ftgo-order-service_1 | 2020-01-01T12:04:55.170030747Z 2020-01-01 12:04:55.169 DEBUG [ftgo-order-service,,,] 1 --- [erSaga-consumer] i.e.m.k.b.c.EventuateKafkaConsumer : Processed net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-consumer 1 records ftgo-order-service_1 | 2020-01-01T12:04:55.170864961Z 2020-01-01 12:04:55.170 DEBUG [ftgo-order-service,,,] 1 --- [erSaga-consumer] i.e.m.k.b.c.EventuateKafkaConsumer : To commit net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-consumer io.eventuate.messaging.ka fka.basic.consumer.OffsetTracker@ce87c5a[state={net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-reply-0=io.eventuate.messaging.kafka.basic.consumer.TopicPartitionOffsets@1599fd9a[unprocessed=[1],processed=[]], net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrde rSaga-reply-1=io.eventuate.messaging.kafka.basic.consumer.TopicPartitionOffsets@1fece3c3[unprocessed=[],processed=[]]}] ftgo-order-service_1 | 2020-01-01T12:04:55.174011158Z 2020-01-01 12:04:55.173 TRACE [ftgo-order-service,571383ec71cf5e1b,4b8907f9f9ae3fc2,true] 1 --- [pool-1-thread-1] i.e.t.c.c.DecoratedMessageHandlerFactory : Invoking handler net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateO rderSaga-consumer 0000016f60fe8a3e-14dae921faa80000 ftgo-order-service_1 | 2020-01-01T12:04:55.179062472Z 2020-01-01 12:04:55.178 DEBUG [ftgo-order-service,571383ec71cf5e1b,4b8907f9f9ae3fc2,true] 1 --- [pool-1-thread-1] i.e.t.s.orchestration.SagaManagerImpl : handle message invoked io.eventuate.tram.messaging.common.MessageImpl@44a6f747[p ayload={},headers={reply_outcome-type=SUCCESS, commandreply_type=net.chrisrichardson.ftgo.accountservice.api.AuthorizeCommand, commandreply_saga_type=net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga, reply_type=io.eventuate.tram.commands.common.Success, reply_to_message_id=000 0016f60fe9628-0242ac13000b0000, commandreply_saga_id=0000016f60fe9323-0242ac13000b0000, DATE=Wed, 1 Jan 2020 12:04:51 GMT, commandreplydestination=accountingService, commandreply_reply_to=net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-reply, DESTINATION=net.chrisrichardson .ftgo.orderservice.sagas.createorder.CreateOrderSaga-reply, ID=0000016f60fe8a3e-14dae921faa80000, X-B3-TraceId=571383ec71cf5e1b, X-B3-SpanId=4b8907f9f9ae3fc2, X-B3-ParentSpanId=a6ad9788926b70fa, X-B3-Sampled=1}] ftgo-order-service_1 | 2020-01-01T12:04:55.180561939Z 2020-01-01 12:04:55.179 DEBUG [ftgo-order-service,571383ec71cf5e1b,4b8907f9f9ae3fc2,true] 1 --- [pool-1-thread-1] i.e.t.s.orchestration.SagaManagerImpl : Handle reply: io.eventuate.tram.messaging.common.MessageImpl@44a6f747[payload={} ,headers={reply_outcome-type=SUCCESS, commandreply_type=net.chrisrichardson.ftgo.accountservice.api.AuthorizeCommand, commandreply_saga_type=net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga, reply_type=io.eventuate.tram.commands.common.Success, reply_to_message_id=0000016f60fe 9628-0242ac13000b0000, commandreply_saga_id=0000016f60fe9323-0242ac13000b0000, DATE=Wed, 1 Jan 2020 12:04:51 GMT, commandreplydestination=accountingService, commandreply_reply_to=net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-reply, DESTINATION=net.chrisrichardson.ftgo.ord erservice.sagas.createorder.CreateOrderSaga-reply, ID=0000016f60fe8a3e-14dae921faa80000, X-B3-TraceId=571383ec71cf5e1b, X-B3-SpanId=4b8907f9f9ae3fc2, X-B3-ParentSpanId=a6ad9788926b70fa, X-B3-Sampled=1}] ftgo-order-service_1 | 2020-01-01T12:04:55.188888868Z 2020-01-01 12:04:55.180 INFO [ftgo-order-service,571383ec71cf5e1b,4b8907f9f9ae3fc2,true] 1 --- [pool-1-thread-1] i.e.t.s.o.SagaInstanceRepositoryJdbc : finding net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga 0000016f60fe9323-0242ac13000b0000 ftgo-order-service_1 | 2020-01-01T12:04:55.192991201Z 2020-01-01 12:04:55.192 INFO [ftgo-order-service,571383ec71cf5e1b,4b8907f9f9ae3fc2,true] 1 --- [pool-1-thread-1] i.e.t.s.orchestration.SagaManagerImpl : Current state={"currentlyExecuting":3,"compensating":false,"endState":false} ftgo-order-service_1 | 2020-01-01T12:04:55.206997397Z 2020-01-01 12:04:55.206 INFO [ftgo-order-service,571383ec71cf5e1b,4b8907f9f9ae3fc2,true] 1 --- [pool-1-thread-1] i.e.t.s.orchestration.SagaManagerImpl : Handled reply. Sending commands [io.eventuate.tram.commands.consumer.CommandWith Destination@3fa47d82[destinationChannel=kitchenService,resource=,command=net.chrisrichardson.ftgo.kitchenservice.api.ConfirmCreateTicket@4c4bb9fe]] ftgo-order-service_1 | 2020-01-01T12:04:55.229533992Z 2020-01-01 12:04:55.229 INFO [ftgo-order-service,571383ec71cf5e1b,4b8907f9f9ae3fc2,true] 1 --- [pool-1-thread-1] i.e.t.s.o.SagaInstanceRepositoryJdbc : Updating net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga 0000016f60fe9323-0242ac13000b0000 ftgo-order-service_1 | 2020-01-01T12:04:55.246853414Z 2020-01-01 12:04:55.246 TRACE [ftgo-order-service,571383ec71cf5e1b,4b8907f9f9ae3fc2,true] 1 --- [pool-1-thread-1] i.e.t.c.c.DecoratedMessageHandlerFactory : handled message net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOr derSaga-consumer 0000016f60fe8a3e-14dae921faa80000 ftgo-order-service_1 | 2020-01-01T12:04:55.264720770Z 2020-01-01 12:04:55.264 DEBUG [ftgo-order-service,,,] 1 --- [pool-1-thread-1] i.e.m.k.b.c.KafkaMessageProcessor : Adding processed record to queue net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-consumer 1 ftgo-order-service_1 | 2020-01-01T12:04:55.272048623Z 2020-01-01 12:04:55.271 DEBUG [ftgo-order-service,,,] 1 --- [erSaga-consumer] i.e.m.k.b.c.EventuateKafkaConsumer : Committing offsets net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-consumer {net.chrisrichar dson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-reply-0=OffsetAndMetadata{offset=2, metadata=''}} ftgo-order-service_1 | 2020-01-01T12:04:55.276696050Z 2020-01-01 12:04:55.275 DEBUG [ftgo-order-service,,,] 1 --- [erSaga-consumer] i.e.m.k.b.c.EventuateKafkaConsumer : Committed offsets net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-consumer ftgo-order-service_1 | 2020-01-01T12:04:55.310395835Z 2020-01-01 12:04:55.309 DEBUG [ftgo-order-service,,,] 1 --- [erSaga-consumer] i.e.m.k.b.c.EventuateKafkaConsumer : Got net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-consumer 1 records ftgo-order-service_1 | 2020-01-01T12:04:55.323863675Z 2020-01-01 12:04:55.310 DEBUG [ftgo-order-service,,,] 1 --- [erSaga-consumer] i.e.m.k.b.c.EventuateKafkaConsumer : processing record net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-consumer 1 {"payload":"{\" ticketId\":1}","headers":{"reply_outcome-type":"SUCCESS","commandreply_type":"net.chrisrichardson.ftgo.kitchenservice.api.CreateTicket","commandreply_saga_type":"net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga","X-B3-ParentSpanId":"56faa08a2668d806","reply_type":"net.chrisric hardson.ftgo.kitchenservice.api.CreateTicketReply","reply_to_message_id":"0000016f60fe94fe-0242ac13000b0000","commandreply_saga_id":"0000016f60fe9323-0242ac13000b0000","DATE":"Wed, 1 Jan 2020 12:04:55 GMT","commandreplydestination":"kitchenService","commandreply_reply_to":"net.chrisrichardson.ftgo .orderservice.sagas.createorder.CreateOrderSaga-reply","X-B3-SpanId":"f92d50afd7594f79","DESTINATION":"net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-reply","X-B3-Sampled":"1","X-B3-TraceId":"571383ec71cf5e1b","ID":"0000016f60fe96c7-0242ac13000c0000","saga-locked-target":"ne t.chrisrichardson.ftgo.kitchenservice.domain.Ticket/1"}} ftgo-order-service_1 | 2020-01-01T12:04:55.323961237Z 2020-01-01 12:04:55.318 DEBUG [ftgo-order-service,,,] 1 --- [erSaga-consumer] i.e.m.k.b.c.EventuateKafkaConsumer : EventuateKafkaAggregateSubscriptions subscriber = net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrde rSaga-consumer, offset = 1, key = 0000016f60fe96c7-0242ac13000c0000, value = {"payload":"{\"ticketId\":1}","headers":{"reply_outcome-type":"SUCCESS","commandreply_type":"net.chrisrichardson.ftgo.kitchenservice.api.CreateTicket","commandreply_saga_type":"net.chrisrichardson.ftgo.orderservice.sagas.cr eateorder.CreateOrderSaga","X-B3-ParentSpanId":"56faa08a2668d806","reply_type":"net.chrisrichardson.ftgo.kitchenservice.api.CreateTicketReply","reply_to_message_id":"0000016f60fe94fe-0242ac13000b0000","commandreply_saga_id":"0000016f60fe9323-0242ac13000b0000","DATE":"Wed, 1 Jan 2020 12:04:55 GMT","c ommandreplydestination":"kitchenService","commandreply_reply_to":"net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-reply","X-B3-SpanId":"f92d50afd7594f79","DESTINATION":"net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-reply","X-B3-Sampled":"1","X-B3-T raceId":"571383ec71cf5e1b","ID":"0000016f60fe96c7-0242ac13000c0000","saga-locked-target":"net.chrisrichardson.ftgo.kitchenservice.domain.Ticket/1"}} ftgo-order-service_1 | 2020-01-01T12:04:55.323990257Z 2020-01-01 12:04:55.318 DEBUG [ftgo-order-service,,,] 1 --- [erSaga-consumer] i.e.m.k.b.c.EventuateKafkaConsumer : Processed net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-consumer 1 records ftgo-order-service_1 | 2020-01-01T12:04:55.324005555Z 2020-01-01 12:04:55.318 DEBUG [ftgo-order-service,,,] 1 --- [erSaga-consumer] i.e.m.k.b.c.EventuateKafkaConsumer : To commit net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-consumer io.eventuate.messaging.ka fka.basic.consumer.OffsetTracker@ce87c5a[state={net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-reply-0=io.eventuate.messaging.kafka.basic.consumer.TopicPartitionOffsets@1599fd9a[unprocessed=[],processed=[]], net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrder Saga-reply-1=io.eventuate.messaging.kafka.basic.consumer.TopicPartitionOffsets@1fece3c3[unprocessed=[1],processed=[]]}] ftgo-order-service_1 | 2020-01-01T12:04:55.393858614Z 2020-01-01 12:04:55.345 TRACE [ftgo-order-service,571383ec71cf5e1b,da889d1e70951b1b,true] 1 --- [pool-1-thread-1] i.e.t.c.c.DecoratedMessageHandlerFactory : Invoking handler net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateO rderSaga-consumer 0000016f60fe96c7-0242ac13000c0000 ftgo-order-service_1 | 2020-01-01T12:04:55.425161324Z 2020-01-01 12:04:55.408 DEBUG [ftgo-order-service,571383ec71cf5e1b,da889d1e70951b1b,true] 1 --- [pool-1-thread-1] i.e.t.s.orchestration.SagaManagerImpl : handle message invoked io.eventuate.tram.messaging.common.MessageImpl@25ed4ab8[p ayload={"ticketId":1},headers={reply_outcome-type=SUCCESS, commandreply_type=net.chrisrichardson.ftgo.kitchenservice.api.CreateTicket, commandreply_saga_type=net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga, reply_type=net.chrisrichardson.ftgo.kitchenservice.api.CreateTicketRe ply, reply_to_message_id=0000016f60fe94fe-0242ac13000b0000, commandreply_saga_id=0000016f60fe9323-0242ac13000b0000, DATE=Wed, 1 Jan 2020 12:04:55 GMT, commandreplydestination=kitchenService, commandreply_reply_to=net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-reply, DESTIN ATION=net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-reply, ID=0000016f60fe96c7-0242ac13000c0000, saga-locked-target=net.chrisrichardson.ftgo.kitchenservice.domain.Ticket/1, X-B3-TraceId=571383ec71cf5e1b, X-B3-SpanId=da889d1e70951b1b, X-B3-ParentSpanId=f92d50afd7594f79, X-B3 -Sampled=1}] ftgo-order-service_1 | 2020-01-01T12:04:55.439280860Z 2020-01-01 12:04:55.434 DEBUG [ftgo-order-service,571383ec71cf5e1b,da889d1e70951b1b,true] 1 --- [pool-1-thread-1] i.e.t.s.orchestration.SagaManagerImpl : Handle reply: io.eventuate.tram.messaging.common.MessageImpl@25ed4ab8[payload={" ticketId":1},headers={reply_outcome-type=SUCCESS, commandreply_type=net.chrisrichardson.ftgo.kitchenservice.api.CreateTicket, commandreply_saga_type=net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga, reply_type=net.chrisrichardson.ftgo.kitchenservice.api.CreateTicketReply, repl y_to_message_id=0000016f60fe94fe-0242ac13000b0000, commandreply_saga_id=0000016f60fe9323-0242ac13000b0000, DATE=Wed, 1 Jan 2020 12:04:55 GMT, commandreplydestination=kitchenService, commandreply_reply_to=net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-reply, DESTINATION=net .chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-reply, ID=0000016f60fe96c7-0242ac13000c0000, saga-locked-target=net.chrisrichardson.ftgo.kitchenservice.domain.Ticket/1, X-B3-TraceId=571383ec71cf5e1b, X-B3-SpanId=da889d1e70951b1b, X-B3-ParentSpanId=f92d50afd7594f79, X-B3-Sampled= 1}] ftgo-order-service_1 | 2020-01-01T12:04:55.441900737Z 2020-01-01 12:04:55.441 INFO [ftgo-order-service,571383ec71cf5e1b,da889d1e70951b1b,true] 1 --- [pool-1-thread-1] i.e.t.s.o.SagaInstanceRepositoryJdbc : finding net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga 0000016f60fe9323-0242ac13000b0000 ftgo-order-service_1 | 2020-01-01T12:04:55.466342093Z 2020-01-01 12:04:55.465 DEBUG [ftgo-order-service,,,] 1 --- [erSaga-consumer] i.e.m.k.b.c.EventuateKafkaConsumer : Got net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-consumer 1 records ftgo-order-service_1 | 2020-01-01T12:04:55.472339804Z 2020-01-01 12:04:55.471 DEBUG [ftgo-order-service,,,] 1 --- [erSaga-consumer] i.e.m.k.b.c.EventuateKafkaConsumer : processing record net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-consumer 2 {"payload":"{}" ,"headers":{"reply_outcome-type":"SUCCESS","commandreply_type":"net.chrisrichardson.ftgo.kitchenservice.api.ConfirmCreateTicket","commandreply_saga_type":"net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga","X-B3-ParentSpanId":"cff9ac4e0d937abb","reply_type":"io.eventuate.tram.c ommands.common.Success","reply_to_message_id":"0000016f60fe9734-0242ac13000b0000","commandreply_saga_id":"0000016f60fe9323-0242ac13000b0000","DATE":"Wed, 1 Jan 2020 12:04:52 GMT","commandreplydestination":"kitchenService","commandreply_reply_to":"net.chrisrichardson.ftgo.orderservice.sagas.createo rder.CreateOrderSaga-reply","X-B3-SpanId":"b8c2424e38afc706","DESTINATION":"net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-reply","X-B3-Sampled":"1","X-B3-TraceId":"571383ec71cf5e1b","ID":"0000016f60fe8b5a-14dae921faa80000"}} ftgo-order-service_1 | 2020-01-01T12:04:55.482660652Z 2020-01-01 12:04:55.474 DEBUG [ftgo-order-service,,,] 1 --- [erSaga-consumer] i.e.m.k.b.c.EventuateKafkaConsumer : EventuateKafkaAggregateSubscriptions subscriber = net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrde rSaga-consumer, offset = 2, key = 0000016f60fe8b5a-14dae921faa80000, value = {"payload":"{}","headers":{"reply_outcome-type":"SUCCESS","commandreply_type":"net.chrisrichardson.ftgo.kitchenservice.api.ConfirmCreateTicket","commandreply_saga_type":"net.chrisrichardson.ftgo.orderservice.sagas.createord er.CreateOrderSaga","X-B3-ParentSpanId":"cff9ac4e0d937abb","reply_type":"io.eventuate.tram.commands.common.Success","reply_to_message_id":"0000016f60fe9734-0242ac13000b0000","commandreply_saga_id":"0000016f60fe9323-0242ac13000b0000","DATE":"Wed, 1 Jan 2020 12:04:52 GMT","commandreply__destination":" kitchenService","commandreply_reply_to":"net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-reply","X-B3-SpanId":"b8c2424e38afc706","DESTINATION":"net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-reply","X-B3-Sampled":"1","X-B3-TraceId":"571383ec71cf5e1b", "ID":"0000016f60fe8b5a-14dae921faa80000"}} ftgo-order-service_1 | 2020-01-01T12:04:55.482779695Z 2020-01-01 12:04:55.478 DEBUG [ftgo-order-service,,,] 1 --- [erSaga-consumer] i.e.m.k.b.c.EventuateKafkaConsumer : Processed net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-consumer 1 records ftgo-order-service_1 | 2020-01-01T12:04:55.482802762Z 2020-01-01 12:04:55.478 DEBUG [ftgo-order-service,,,] 1 --- [erSaga-consumer] i.e.m.k.b.c.EventuateKafkaConsumer : To commit net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-consumer io.eventuate.messaging.ka fka.basic.consumer.OffsetTracker@ce87c5a[state={net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-reply-0=io.eventuate.messaging.kafka.basic.consumer.TopicPartitionOffsets@1599fd9a[unprocessed=[],processed=[]], net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrder Saga-reply-1=io.eventuate.messaging.kafka.basic.consumer.TopicPartitionOffsets@1fece3c3[unprocessed=[1, 2],processed=[]]}] ftgo-order-service_1 | 2020-01-01T12:04:55.603265288Z 2020-01-01 12:04:55.602 INFO [ftgo-order-service,571383ec71cf5e1b,da889d1e70951b1b,true] 1 --- [pool-1-thread-1] i.e.t.s.orchestration.SagaManagerImpl : Current state={"currentlyExecuting":4,"compensating":false,"endState":false} ftgo-order-service_1 | 2020-01-01T12:04:55.607804840Z 2020-01-01 12:04:55.607 INFO [ftgo-order-service,571383ec71cf5e1b,da889d1e70951b1b,true] 1 --- [pool-1-thread-1] i.e.t.s.orchestration.SagaManagerImpl : Handled reply. Sending commands [io.eventuate.tram.commands.consumer.CommandWith Destination@14502752[destinationChannel=orderService,resource=,command=net.chrisrichardson.ftgo.orderservice.sagaparticipants.ApproveOrderCommand@5ba707ea]] ftgo-order-service1 | 2020-01-01T12:04:55.616919090Z 2020-01-01 12:04:55.616 DEBUG [ftgo-order-service,ab07217b2139aef1,ab07217b2139aef1,true] 1 --- [nio-8080-exec-4] org.hibernate.SQL : select order0.id as id1_30, order0_.consumer_id as consumer2_30, order0_.ci ty as city3_30, order0_.delivery_state as delivery4_30, order0_.street1 as street5_30, order0_.street2 as street6_30, order0_.zip as zip7_30, order0_.delivery_time as delivery8_30, order0_.amount as amount9_30, order0_.payment_token as payment10_30, order0_.restaurant_id as restaur11 _30, order0_.state as state12_30, order0_.version as version13_30 from orders order0 where order0.id=? ftgo-order-service_1 | 2020-01-01T12:04:55.644392009Z 2020-01-01 12:04:55.643 INFO [ftgo-order-service,571383ec71cf5e1b,da889d1e70951b1b,true] 1 --- [pool-1-thread-1] i.e.t.s.o.SagaInstanceRepositoryJdbc : Updating net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga 0000016f60fe9323-0242ac13000b0000 ftgo-order-service_1 | 2020-01-01T12:04:55.662562918Z 2020-01-01 12:04:55.662 TRACE [ftgo-order-service,571383ec71cf5e1b,da889d1e70951b1b,true] 1 --- [pool-1-thread-1] i.e.t.c.c.DecoratedMessageHandlerFactory : handled message net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOr derSaga-consumer 0000016f60fe96c7-0242ac13000c0000 ftgo-order-service1 | 2020-01-01T12:04:55.670991523Z 2020-01-01 12:04:55.667 DEBUG [ftgo-order-service,ab07217b2139aef1,ab07217b2139aef1,true] 1 --- [nio-8080-exec-4] org.hibernate.SQL : select lineitems0.order_id as order_id1_00, lineitems0_.menu_itemid as menu ite2_00, lineitems0_.name as name3_00, lineitems0_.price as price4_00, lineitems0_.quantity as quantity5_00 from order_lineitems lineitems0 where lineitems0_.order_id=? ftgo-order-service_1 | 2020-01-01T12:04:55.673082111Z 2020-01-01 12:04:55.672 DEBUG [ftgo-order-service,,,] 1 --- [pool-1-thread-1] i.e.m.k.b.c.KafkaMessageProcessor : Adding processed record to queue net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-consumer 1 ftgo-order-service_1 | 2020-01-01T12:04:55.703753200Z 2020-01-01 12:04:55.703 DEBUG [ftgo-order-service,,,] 1 --- [erSaga-consumer] i.e.m.k.b.c.EventuateKafkaConsumer : Committing offsets net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-consumer {net.chrisrichar dson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-reply-1=OffsetAndMetadata{offset=2, metadata=''}} ftgo-order-service_1 | 2020-01-01T12:04:55.709014033Z 2020-01-01 12:04:55.708 DEBUG [ftgo-order-service,,,] 1 --- [erSaga-consumer] i.e.m.k.b.c.EventuateKafkaConsumer : Committed offsets net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-consumer ftgo-order-service_1 | 2020-01-01T12:04:55.710564424Z 2020-01-01 12:04:55.685 DEBUG [ftgo-order-service,,,] 1 --- [er-orderService] i.e.m.k.b.c.EventuateKafkaConsumer : Got orderService 1 records ftgo-order-service_1 | 2020-01-01T12:04:55.720059784Z 2020-01-01 12:04:55.711 DEBUG [ftgo-order-service,,,] 1 --- [er-orderService] i.e.m.k.b.c.EventuateKafkaConsumer : processing record orderService 0 {"payload":"{\"orderId\":1}","headers":{"command_saga_id":"0000016f60fe9323-0242ac1 3000b0000","DATE":"Wed, 1 Jan 2020 12:04:55 GMT","command_type":"net.chrisrichardson.ftgo.orderservice.sagaparticipants.ApproveOrderCommand","X-B3-SpanId":"a3c7e871d648b351","command_reply_to":"net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-reply","DESTINATION":"orderService ","command_saga_type":"net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga","X-B3-ParentSpanId":"da889d1e70951b1b","X-B3-Sampled":"1","X-B3-TraceId":"571383ec71cf5e1b","commanddestination":"orderService","ID":"0000016f60fe98bc-0242ac13000b0000"}} ftgo-order-service_1 | 2020-01-01T12:04:55.728375846Z 2020-01-01 12:04:55.728 DEBUG [ftgo-order-service,,,] 1 --- [er-orderService] i.e.m.k.b.c.EventuateKafkaConsumer : EventuateKafkaAggregateSubscriptions subscriber = orderService, offset = 0, key = 0000016f60fe98bc-0242ac13000b0000, value = {"payload":"{\"orderId\":1}","headers":{"command_saga_id":"0000016f60fe9323-0242ac13000b0000","DATE":"Wed, 1 Jan 2020 12:04:55 GMT","command_type":"net.chrisrichardson.ftgo.orderservice.sagaparticipants.ApproveOrderCommand","X-B3-SpanId":"a3c7e871d648b351","command_reply_to":"net.chrisricha rdson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-reply","DESTINATION":"orderService","command_saga_type":"net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga","X-B3-ParentSpanId":"da889d1e70951b1b","X-B3-Sampled":"1","X-B3-TraceId":"571383ec71cf5e1b","commanddestinatio n":"orderService","ID":"0000016f60fe98bc-0242ac13000b0000"}} ftgo-order-service_1 | 2020-01-01T12:04:55.738973831Z 2020-01-01 12:04:55.738 DEBUG [ftgo-order-service,,,] 1 --- [er-orderService] i.e.m.k.b.c.EventuateKafkaConsumer : Processed orderService 1 records ftgo-order-service_1 | 2020-01-01T12:04:55.739742929Z 2020-01-01 12:04:55.738 DEBUG [ftgo-order-service,,,] 1 --- [er-orderService] i.e.m.k.b.c.EventuateKafkaConsumer : To commit orderService io.eventuate.messaging.kafka.basic.consumer.OffsetTracker@2841af58[state={orderService-1=io.e ventuate.messaging.kafka.basic.consumer.TopicPartitionOffsets@7fbeb1c[unprocessed=[0],processed=[]]}] ftgo-order-service_1 | 2020-01-01T12:04:55.761337063Z 2020-01-01 12:04:55.760 TRACE [ftgo-order-service,571383ec71cf5e1b,01a3f398574b3e7d,true] 1 --- [pool-1-thread-1] i.e.t.c.c.DecoratedMessageHandlerFactory : Invoking handler net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateO rderSaga-consumer 0000016f60fe8b5a-14dae921faa80000 ftgo-order-service_1 | 2020-01-01T12:04:55.761507960Z 2020-01-01 12:04:55.761 TRACE [ftgo-order-service,571383ec71cf5e1b,555cf6fce511002e,true] 1 --- [pool-4-thread-1] i.e.t.c.c.DecoratedMessageHandlerFactory : Invoking handler orderService 0000016f60fe98bc-0242ac13000b0000 ftgo-order-service_1 | 2020-01-01T12:04:55.765770306Z 2020-01-01 12:04:55.761 DEBUG [ftgo-order-service,571383ec71cf5e1b,01a3f398574b3e7d,true] 1 --- [pool-1-thread-1] i.e.t.s.orchestration.SagaManagerImpl : handle message invoked io.eventuate.tram.messaging.common.MessageImpl@754cf7eb[p ayload={},headers={reply_outcome-type=SUCCESS, commandreply_type=net.chrisrichardson.ftgo.kitchenservice.api.ConfirmCreateTicket, commandreply_saga_type=net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga, reply_type=io.eventuate.tram.commands.common.Success, reply_to_message_id= 0000016f60fe9734-0242ac13000b0000, commandreply_saga_id=0000016f60fe9323-0242ac13000b0000, DATE=Wed, 1 Jan 2020 12:04:52 GMT, commandreply__destination=kitchenService, commandreply_reply_to=net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-reply, DESTINATION=net.chrisrichardson .ftgo.orderservice.sagas.createorder.CreateOrderSaga-reply, ID=0000016f60fe8b5a-14dae921faa80000, X-B3-TraceId=571383ec71cf5e1b, X-B3-SpanId=01a3f398574b3e7d, X-B3-ParentSpanId=b8c2424e38afc706, X-B3-Sampled=1}] ftgo-order-service_1 | 2020-01-01T12:04:55.767964218Z 2020-01-01 12:04:55.765 DEBUG [ftgo-order-service,571383ec71cf5e1b,01a3f398574b3e7d,true] 1 --- [pool-1-thread-1] i.e.t.s.orchestration.SagaManagerImpl : Handle reply: io.eventuate.tram.messaging.common.MessageImpl@754cf7eb[payload={} ,headers={reply_outcome-type=SUCCESS, commandreply_type=net.chrisrichardson.ftgo.kitchenservice.api.ConfirmCreateTicket, commandreply_saga_type=net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga, reply_type=io.eventuate.tram.commands.common.Success, reply_to_message_id=0000016f6 0fe9734-0242ac13000b0000, commandreply_saga_id=0000016f60fe9323-0242ac13000b0000, DATE=Wed, 1 Jan 2020 12:04:52 GMT, commandreplydestination=kitchenService, commandreply_reply_to=net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-reply, DESTINATION=net.chrisrichardson.ftgo.ord erservice.sagas.createorder.CreateOrderSaga-reply, ID=0000016f60fe8b5a-14dae921faa80000, X-B3-TraceId=571383ec71cf5e1b, X-B3-SpanId=01a3f398574b3e7d, X-B3-ParentSpanId=b8c2424e38afc706, X-B3-Sampled=1}] ftgo-order-service_1 | 2020-01-01T12:04:55.770297014Z 2020-01-01 12:04:55.768 INFO [ftgo-order-service,571383ec71cf5e1b,01a3f398574b3e7d,true] 1 --- [pool-1-thread-1] i.e.t.s.o.SagaInstanceRepositoryJdbc : finding net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga 0000016f60fe9323-0242ac13000b0000 ftgo-order-service_1 | 2020-01-01T12:04:55.793291102Z 2020-01-01 12:04:55.792 TRACE [ftgo-order-service,571383ec71cf5e1b,555cf6fce511002e,true] 1 --- [pool-4-thread-1] i.e.t.s.p.SagaCommandDispatcher : Received message orderService io.eventuate.tram.messaging.common.MessageImpl@7c2 0a926[payload={"orderId":1},headers={command_saga_id=0000016f60fe9323-0242ac13000b0000, DATE=Wed, 1 Jan 2020 12:04:55 GMT, command_type=net.chrisrichardson.ftgo.orderservice.sagaparticipants.ApproveOrderCommand, command_reply_to=net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga -reply, DESTINATION=orderService, command_saga_type=net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga, commanddestination=orderService, ID=0000016f60fe98bc-0242ac13000b0000, X-B3-TraceId=571383ec71cf5e1b, X-B3-SpanId=555cf6fce511002e, X-B3-ParentSpanId=a3c7e871d648b351, X-B3- Sampled=1}] ftgo-order-service_1 | 2020-01-01T12:04:55.805169295Z 2020-01-01 12:04:55.801 INFO [ftgo-order-service,571383ec71cf5e1b,01a3f398574b3e7d,true] 1 --- [pool-1-thread-1] i.e.t.s.orchestration.SagaManagerImpl : Current state={"currentlyExecuting":5,"compensating":false,"endState":false} ftgo-order-service_1 | 2020-01-01T12:04:55.805236272Z 2020-01-01 12:04:55.804 INFO [ftgo-order-service,571383ec71cf5e1b,01a3f398574b3e7d,true] 1 --- [pool-1-thread-1] i.e.t.s.orchestration.SagaManagerImpl : Handled reply. Sending commands [] ftgo-order-service_1 | 2020-01-01T12:04:55.835647749Z 2020-01-01 12:04:55.834 INFO [ftgo-order-service,571383ec71cf5e1b,01a3f398574b3e7d,true] 1 --- [pool-1-thread-1] i.e.t.s.o.SagaInstanceRepositoryJdbc : Updating net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga 0000016f60fe9323-0242ac13000b0000 ftgo-order-service1 | 2020-01-01T12:04:55.853333763Z 2020-01-01 12:04:55.850 DEBUG [ftgo-order-service,571383ec71cf5e1b,555cf6fce511002e,true] 1 --- [pool-4-thread-1] org.hibernate.SQL : select order0.id as id1_30, order0_.consumer_id as consumer2_30, order0_.ci ty as city3_30, order0_.delivery_state as delivery4_30, order0_.street1 as street5_30, order0_.street2 as street6_30, order0_.zip as zip7_30, order0_.delivery_time as delivery8_30, order0_.amount as amount9_30, order0_.payment_token as payment10_30, order0_.restaurant_id as restaur11 _30, order0_.state as state12_30, order0_.version as version13_30 from orders order0 where order0.id=? ftgo-order-service_1 | 2020-01-01T12:04:55.950154954Z 2020-01-01 12:04:55.949 TRACE [ftgo-order-service,571383ec71cf5e1b,555cf6fce511002e,true] 1 --- [pool-4-thread-1] i.e.t.s.p.SagaCommandDispatcher : Generated replies orderService io.eventuate.tram.messaging.common.MessageImpl@7c 20a926[payload={"orderId":1},headers={command_saga_id=0000016f60fe9323-0242ac13000b0000, DATE=Wed, 1 Jan 2020 12:04:55 GMT, command_type=net.chrisrichardson.ftgo.orderservice.sagaparticipants.ApproveOrderCommand, command_reply_to=net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSag a-reply, DESTINATION=orderService, command_saga_type=net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga, command__destination=orderService, ID=0000016f60fe98bc-0242ac13000b0000, X-B3-TraceId=571383ec71cf5e1b, X-B3-SpanId=555cf6fce511002e, X-B3-ParentSpanId=a3c7e871d648b351, X-B3 -Sampled=1}] [io.eventuate.tram.messaging.common.MessageImpl@5de57486[payload={},headers={reply_outcome-type=SUCCESS, reply_type=io.eventuate.tram.commands.common.Success}]] ftgo-order-service_1 | 2020-01-01T12:04:55.998610984Z 2020-01-01 12:04:55.998 TRACE [ftgo-order-service,571383ec71cf5e1b,555cf6fce511002e,true] 1 --- [pool-4-thread-1] i.e.t.c.c.DecoratedMessageHandlerFactory : handled message orderService 0000016f60fe98bc-0242ac13000b0000 ftgo-order-service_1 | 2020-01-01T12:04:55.999637249Z 2020-01-01 12:04:55.999 DEBUG [ftgo-order-service,,,] 1 --- [pool-4-thread-1] org.hibernate.SQL : update orders set consumer_id=?, city=?, delivery_state=?, street1=?, street2=?, zip=?, delivery_time=?, amount=?, p ayment_token=?, restaurant_id=?, state=?, version=? where id=? and version=? ftgo-order-service_1 | 2020-01-01T12:04:56.028164804Z 2020-01-01 12:04:56.027 DEBUG [ftgo-order-service,,,] 1 --- [pool-4-thread-1] i.e.m.k.b.c.KafkaMessageProcessor : Adding processed record to queue orderService 0 ftgo-order-service_1 | 2020-01-01T12:04:56.056984043Z 2020-01-01 12:04:56.056 DEBUG [ftgo-order-service,,,] 1 --- [er-orderService] i.e.m.k.b.c.EventuateKafkaConsumer : Committing offsets orderService {orderService-1=OffsetAndMetadata{offset=1, metadata=''}} ftgo-order-service_1 | 2020-01-01T12:04:56.069442458Z 2020-01-01 12:04:56.068 INFO [ftgo-order-service,571383ec71cf5e1b,01a3f398574b3e7d,true] 1 --- [pool-1-thread-1] o.s.b.f.xml.XmlBeanDefinitionReader : Loading XML bean definitions from class path resource [org/springframework/jdbc/ support/sql-error-codes.xml] ftgo-order-service_1 | 2020-01-01T12:04:56.099497920Z 2020-01-01 12:04:56.098 DEBUG [ftgo-order-service,,,] 1 --- [er-orderService] i.e.m.k.b.c.EventuateKafkaConsumer : Committed offsets orderService ftgo-order-service_1 | 2020-01-01T12:04:56.269657032Z 2020-01-01 12:04:56.268 DEBUG [ftgo-order-service,,,] 1 --- [erSaga-consumer] i.e.m.k.b.c.EventuateKafkaConsumer : Got net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-consumer 1 records ftgo-order-service_1 | 2020-01-01T12:04:56.270715544Z 2020-01-01 12:04:56.269 DEBUG [ftgo-order-service,,,] 1 --- [erSaga-consumer] i.e.m.k.b.c.EventuateKafkaConsumer : processing record net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-consumer 2 {"payload":"{}" ,"headers":{"reply_outcome-type":"SUCCESS","commandreply_type":"net.chrisrichardson.ftgo.kitchenservice.api.ConfirmCreateTicket","commandreply_saga_type":"net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga","X-B3-ParentSpanId":"4592fff4a79e80d7","reply_type":"io.eventuate.tram.c ommands.common.Success","reply_to_message_id":"0000016f60fe9734-0242ac13000b0000","commandreply_saga_id":"0000016f60fe9323-0242ac13000b0000","DATE":"Wed, 1 Jan 2020 12:04:55 GMT","commandreplydestination":"kitchenService","commandreply_reply_to":"net.chrisrichardson.ftgo.orderservice.sagas.createo rder.CreateOrderSaga-reply","X-B3-SpanId":"00340a3717107135","DESTINATION":"net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-reply","X-B3-Sampled":"1","X-B3-TraceId":"571383ec71cf5e1b","ID":"0000016f60fe996d-0242ac13000c0000"}} ftgo-order-service_1 | 2020-01-01T12:04:56.270817266Z 2020-01-01 12:04:56.270 DEBUG [ftgo-order-service,,,] 1 --- [erSaga-consumer] i.e.m.k.b.c.EventuateKafkaConsumer : EventuateKafkaAggregateSubscriptions subscriber = net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrde rSaga-consumer, offset = 2, key = 0000016f60fe996d-0242ac13000c0000, value = {"payload":"{}","headers":{"reply_outcome-type":"SUCCESS","commandreply_type":"net.chrisrichardson.ftgo.kitchenservice.api.ConfirmCreateTicket","commandreply_saga_type":"net.chrisrichardson.ftgo.orderservice.sagas.createord er.CreateOrderSaga","X-B3-ParentSpanId":"4592fff4a79e80d7","reply_type":"io.eventuate.tram.commands.common.Success","reply_to_message_id":"0000016f60fe9734-0242ac13000b0000","commandreply_saga_id":"0000016f60fe9323-0242ac13000b0000","DATE":"Wed, 1 Jan 2020 12:04:55 GMT","commandreplydestination":" kitchenService","commandreply_reply_to":"net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-reply","X-B3-SpanId":"00340a3717107135","DESTINATION":"net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-reply","X-B3-Sampled":"1","X-B3-TraceId":"571383ec71cf5e1b", "ID":"0000016f60fe996d-0242ac13000c0000"}} ftgo-order-service_1 | 2020-01-01T12:04:56.288209049Z 2020-01-01 12:04:56.287 DEBUG [ftgo-order-service,,,] 1 --- [erSaga-consumer] i.e.m.k.b.c.EventuateKafkaConsumer : Processed net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-consumer 1 records ftgo-order-service_1 | 2020-01-01T12:04:56.301506913Z 2020-01-01 12:04:56.301 TRACE [ftgo-order-service,571383ec71cf5e1b,5fe90e8d4aa20856,true] 1 --- [pool-1-thread-2] i.e.t.c.c.DecoratedMessageHandlerFactory : Invoking handler net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateO rderSaga-consumer 0000016f60fe996d-0242ac13000c0000 ftgo-order-service_1 | 2020-01-01T12:04:56.302546150Z 2020-01-01 12:04:56.302 DEBUG [ftgo-order-service,571383ec71cf5e1b,5fe90e8d4aa20856,true] 1 --- [pool-1-thread-2] i.e.t.s.orchestration.SagaManagerImpl : handle message invoked io.eventuate.tram.messaging.common.MessageImpl@74ccab85[p ayload={},headers={reply_outcome-type=SUCCESS, commandreply_type=net.chrisrichardson.ftgo.kitchenservice.api.ConfirmCreateTicket, commandreply_saga_type=net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga, reply_type=io.eventuate.tram.commands.common.Success, reply_to_message_id= 0000016f60fe9734-0242ac13000b0000, commandreply_saga_id=0000016f60fe9323-0242ac13000b0000, DATE=Wed, 1 Jan 2020 12:04:55 GMT, commandreply__destination=kitchenService, commandreply_reply_to=net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-reply, DESTINATION=net.chrisrichardson .ftgo.orderservice.sagas.createorder.CreateOrderSaga-reply, ID=0000016f60fe996d-0242ac13000c0000, X-B3-TraceId=571383ec71cf5e1b, X-B3-SpanId=5fe90e8d4aa20856, X-B3-ParentSpanId=00340a3717107135, X-B3-Sampled=1}] ftgo-order-service_1 | 2020-01-01T12:04:56.302903410Z 2020-01-01 12:04:56.302 DEBUG [ftgo-order-service,571383ec71cf5e1b,5fe90e8d4aa20856,true] 1 --- [pool-1-thread-2] i.e.t.s.orchestration.SagaManagerImpl : Handle reply: io.eventuate.tram.messaging.common.MessageImpl@74ccab85[payload={} ,headers={reply_outcome-type=SUCCESS, commandreply_type=net.chrisrichardson.ftgo.kitchenservice.api.ConfirmCreateTicket, commandreply_saga_type=net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga, reply_type=io.eventuate.tram.commands.common.Success, reply_to_message_id=0000016f6 0fe9734-0242ac13000b0000, commandreply_saga_id=0000016f60fe9323-0242ac13000b0000, DATE=Wed, 1 Jan 2020 12:04:55 GMT, commandreplydestination=kitchenService, commandreply_reply_to=net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-reply, DESTINATION=net.chrisrichardson.ftgo.ord erservice.sagas.createorder.CreateOrderSaga-reply, ID=0000016f60fe996d-0242ac13000c0000, X-B3-TraceId=571383ec71cf5e1b, X-B3-SpanId=5fe90e8d4aa20856, X-B3-ParentSpanId=00340a3717107135, X-B3-Sampled=1}] ftgo-order-service_1 | 2020-01-01T12:04:56.304164300Z 2020-01-01 12:04:56.303 INFO [ftgo-order-service,571383ec71cf5e1b,5fe90e8d4aa20856,true] 1 --- [pool-1-thread-2] i.e.t.s.o.SagaInstanceRepositoryJdbc : finding net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga 0000016f60fe9323-0242ac13000b0000 ftgo-order-service_1 | 2020-01-01T12:04:56.307031937Z 2020-01-01 12:04:56.306 DEBUG [ftgo-order-service,,,] 1 --- [erSaga-consumer] i.e.m.k.b.c.EventuateKafkaConsumer : To commit net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-consumer io.eventuate.messaging.ka fka.basic.consumer.OffsetTracker@ce87c5a[state={net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-reply-0=io.eventuate.messaging.kafka.basic.consumer.TopicPartitionOffsets@1599fd9a[unprocessed=[2],processed=[]], net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrde rSaga-reply-1=io.eventuate.messaging.kafka.basic.consumer.TopicPartitionOffsets@1fece3c3[unprocessed=[2],processed=[]]}] ftgo-order-service_1 | 2020-01-01T12:04:56.381932402Z 2020-01-01 12:04:56.380 DEBUG [ftgo-order-service,,,] 1 --- [erSaga-consumer] i.e.m.k.b.c.EventuateKafkaConsumer : Got net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-consumer 1 records ftgo-order-service_1 | 2020-01-01T12:04:56.409026683Z 2020-01-01 12:04:56.408 DEBUG [ftgo-order-service,,,] 1 --- [erSaga-consumer] i.e.m.k.b.c.EventuateKafkaConsumer : processing record net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-consumer 3 {"payload":"{}" ,"headers":{"reply_outcome-type":"SUCCESS","commandreply_type":"net.chrisrichardson.ftgo.orderservice.sagaparticipants.ApproveOrderCommand","commandreply_saga_type":"net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga","X-B3-ParentSpanId":"555cf6fce511002e","reply_type":"io.event uate.tram.commands.common.Success","reply_to_message_id":"0000016f60fe98bc-0242ac13000b0000","commandreply_saga_id":"0000016f60fe9323-0242ac13000b0000","DATE":"Wed, 1 Jan 2020 12:04:55 GMT","commandreplydestination":"orderService","commandreply_reply_to":"net.chrisrichardson.ftgo.orderservice.saga s.createorder.CreateOrderSaga-reply","X-B3-SpanId":"2797728bcfed0b91","DESTINATION":"net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-reply","X-B3-Sampled":"1","X-B3-TraceId":"571383ec71cf5e1b","ID":"0000016f60fe9a1e-0242ac13000b0000"}} ftgo-order-service_1 | 2020-01-01T12:04:56.409338769Z 2020-01-01 12:04:56.409 DEBUG [ftgo-order-service,,,] 1 --- [erSaga-consumer] i.e.m.k.b.c.EventuateKafkaConsumer : EventuateKafkaAggregateSubscriptions subscriber = net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrde rSaga-consumer, offset = 3, key = 0000016f60fe9a1e-0242ac13000b0000, value = {"payload":"{}","headers":{"reply_outcome-type":"SUCCESS","commandreply_type":"net.chrisrichardson.ftgo.orderservice.sagaparticipants.ApproveOrderCommand","commandreply_saga_type":"net.chrisrichardson.ftgo.orderservice.saga s.createorder.CreateOrderSaga","X-B3-ParentSpanId":"555cf6fce511002e","reply_type":"io.eventuate.tram.commands.common.Success","reply_to_message_id":"0000016f60fe98bc-0242ac13000b0000","commandreply_saga_id":"0000016f60fe9323-0242ac13000b0000","DATE":"Wed, 1 Jan 2020 12:04:55 GMT","commandreply__des tination":"orderService","commandreply_reply_to":"net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-reply","X-B3-SpanId":"2797728bcfed0b91","DESTINATION":"net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-reply","X-B3-Sampled":"1","X-B3-TraceId":"571383ec7 1cf5e1b","ID":"0000016f60fe9a1e-0242ac13000b0000"}} ftgo-order-service_1 | 2020-01-01T12:04:56.409700927Z 2020-01-01 12:04:56.409 DEBUG [ftgo-order-service,,,] 1 --- [erSaga-consumer] i.e.m.k.b.c.EventuateKafkaConsumer : Processed net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-consumer 1 records ftgo-order-service_1 | 2020-01-01T12:04:56.410654298Z 2020-01-01 12:04:56.409 DEBUG [ftgo-order-service,,,] 1 --- [erSaga-consumer] i.e.m.k.b.c.EventuateKafkaConsumer : To commit net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-consumer io.eventuate.messaging.ka fka.basic.consumer.OffsetTracker@ce87c5a[state={net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-reply-0=io.eventuate.messaging.kafka.basic.consumer.TopicPartitionOffsets@1599fd9a[unprocessed=[2],processed=[]], net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrde rSaga-reply-1=io.eventuate.messaging.kafka.basic.consumer.TopicPartitionOffsets@1fece3c3[unprocessed=[2, 3],processed=[]]}] ftgo-order-service_1 | 2020-01-01T12:04:56.444341731Z 2020-01-01 12:04:56.440 INFO [ftgo-order-service,571383ec71cf5e1b,5fe90e8d4aa20856,true] 1 --- [pool-1-thread-2] i.e.t.s.orchestration.SagaManagerImpl : Current state={"currentlyExecuting":5,"compensating":false,"endState":false} ftgo-order-service_1 | 2020-01-01T12:04:56.444432458Z 2020-01-01 12:04:56.441 INFO [ftgo-order-service,571383ec71cf5e1b,5fe90e8d4aa20856,true] 1 --- [pool-1-thread-2] i.e.t.s.orchestration.SagaManagerImpl : Handled reply. Sending commands [] ftgo-order-service_1 | 2020-01-01T12:04:56.447360294Z 2020-01-01 12:04:56.446 INFO [ftgo-order-service,571383ec71cf5e1b,5fe90e8d4aa20856,true] 1 --- [pool-1-thread-2] i.e.t.s.o.SagaInstanceRepositoryJdbc : Updating net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga 0000016f60fe9323-0242ac13000b0000 ftgo-order-service1 | 2020-01-01T12:04:56.520370180Z 2020-01-01 12:04:56.520 DEBUG [ftgo-order-service,87c7ff006d3bb80e,87c7ff006d3bb80e,true] 1 --- [nio-8080-exec-5] org.hibernate.SQL : select order0.id as id1_30, order0_.consumer_id as consumer2_30, order0_.ci ty as city3_30, order0_.delivery_state as delivery4_30, order0_.street1 as street5_30, order0_.street2 as street6_30, order0_.zip as zip7_30, order0_.delivery_time as delivery8_30, order0_.amount as amount9_30, order0_.payment_token as payment10_30, order0_.restaurant_id as restaur11 _30, order0_.state as state12_30, order0_.version as version13_30 from orders order0 where order0.id=? ftgo-order-service1 | 2020-01-01T12:04:56.536861102Z 2020-01-01 12:04:56.536 DEBUG [ftgo-order-service,87c7ff006d3bb80e,87c7ff006d3bb80e,true] 1 --- [nio-8080-exec-5] org.hibernate.SQL : select lineitems0.order_id as order_id1_00, lineitems0_.menu_itemid as menu ite2_00, lineitems0_.name as name3_00, lineitems0_.price as price4_00, lineitems0_.quantity as quantity5_00 from order_lineitems lineitems0 where lineitems0_.order_id=? ftgo-order-service1 | 2020-01-01T12:04:56.744848458Z 2020-01-01 12:04:56.706 DEBUG [ftgo-order-service,6049136193c61eb1,6049136193c61eb1,true] 1 --- [nio-8080-exec-7] org.hibernate.SQL : select restaurant0.id as id1_20, restaurant0_.name as name2_20 from order_s ervicerestaurants restaurant0 where restaurant0_.id=? ftgo-order-service1 | 2020-01-01T12:04:56.754685633Z 2020-01-01 12:04:56.754 DEBUG [ftgo-order-service,6049136193c61eb1,6049136193c61eb1,true] 1 --- [nio-8080-exec-7] org.hibernate.SQL : select menuitems0.restaurant_id as restaura1_10, menuitems0_.id as id2_10, menuitems0_.name as name3_10, menuitems0_.amount as amount4_10 from order_service_restaurant_menuitems menuitems0 where menuitems0_.restaurant_id=? ftgo-order-service_1 | 2020-01-01T12:04:56.909710639Z 2020-01-01 12:04:56.909 DEBUG [ftgo-order-service,6049136193c61eb1,6049136193c61eb1,true] 1 --- [nio-8080-exec-7] org.hibernate.SQL : select next_val as id_val from hibernate_sequence for update ftgo-order-service_1 | 2020-01-01T12:04:56.911845389Z 2020-01-01 12:04:56.911 DEBUG [ftgo-order-service,6049136193c61eb1,6049136193c61eb1,true] 1 --- [nio-8080-exec-7] org.hibernate.SQL : update hibernate_sequence set next_val= ? where next_val=? ftgo-order-service_1 | 2020-01-01T12:04:56.923267294Z 2020-01-01 12:04:56.922 INFO [ftgo-order-service,6049136193c61eb1,6049136193c61eb1,true] 1 --- [nio-8080-exec-7] i.e.t.s.o.SagaInstanceRepositoryJdbc : Saving net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga 0 000016f60fe9dda-0242ac13000b0000 ftgo-order-service_1 | 2020-01-01T12:04:56.932809656Z 2020-01-01 12:04:56.932 DEBUG [ftgo-order-service,6049136193c61eb1,6049136193c61eb1,true] 1 --- [nio-8080-exec-7] i.e.t.sagas.common.SagaLockManagerImpl : Saga net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga 000 0016f60fe9dda-0242ac13000b0000 has locked net.chrisrichardson.ftgo.orderservice.domain.Order/2 ftgo-order-service_1 | 2020-01-01T12:04:56.947051882Z 2020-01-01 12:04:56.946 INFO [ftgo-order-service,6049136193c61eb1,6049136193c61eb1,true] 1 --- [nio-8080-exec-7] i.e.t.s.o.SagaInstanceRepositoryJdbc : Updating net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga 0000016f60fe9dda-0242ac13000b0000 ftgo-order-service_1 | 2020-01-01T12:04:56.950686985Z 2020-01-01 12:04:56.950 DEBUG [ftgo-order-service,6049136193c61eb1,6049136193c61eb1,true] 1 --- [nio-8080-exec-7] org.hibernate.SQL : insert into orders (consumer_id, city, delivery_state, street1, street2, zip, de livery_time, amount, payment_token, restaurant_id, state, version, id) values (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?) ftgo-order-service_1 | 2020-01-01T12:04:56.954849403Z 2020-01-01 12:04:56.954 DEBUG [ftgo-order-service,6049136193c61eb1,6049136193c61eb1,true] 1 --- [nio-8080-exec-7] org.hibernate.SQL : insert into order_line_items (order_id, menu_item_id, name, price, quantity) val ues (?, ?, ?, ?, ?) ftgo-order-service_1 | 2020-01-01T12:04:57.053415598Z 2020-01-01 12:04:57.052 DEBUG [ftgo-order-service,,,] 1 --- [erSaga-consumer] i.e.m.k.b.c.EventuateKafkaConsumer : Got net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-consumer 1 records ftgo-order-service_1 | 2020-01-01T12:04:57.053546312Z 2020-01-01 12:04:57.053 DEBUG [ftgo-order-service,,,] 1 --- [erSaga-consumer] i.e.m.k.b.c.EventuateKafkaConsumer : processing record net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-consumer 4 {"payload":"{}" ,"headers":{"reply_outcome-type":"SUCCESS","commandreply_type":"net.chrisrichardson.ftgo.consumerservice.api.ValidateOrderByConsumer","commandreply_saga_type":"net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga","X-B3-ParentSpanId":"480092d576bd3f0b","reply_type":"io.eventuate.t ram.commands.common.Success","reply_to_message_id":"0000016f60fe9de4-0242ac13000b0000","commandreply_saga_id":"0000016f60fe9dda-0242ac13000b0000","DATE":"Wed, 1 Jan 2020 12:04:53 GMT","commandreplydestination":"consumerService","commandreply_reply_to":"net.chrisrichardson.ftgo.orderservice.sagas.c reateorder.CreateOrderSaga-reply","X-B3-SpanId":"bb0b7f10509ce21e","DESTINATION":"net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-reply","X-B3-Sampled":"1","X-B3-TraceId":"6049136193c61eb1","ID":"0000016f60fe91b8-14dae921faa80000"}} ftgo-order-service_1 | 2020-01-01T12:04:57.053667241Z 2020-01-01 12:04:57.053 DEBUG [ftgo-order-service,,,] 1 --- [erSaga-consumer] i.e.m.k.b.c.EventuateKafkaConsumer : EventuateKafkaAggregateSubscriptions subscriber = net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrde rSaga-consumer, offset = 4, key = 0000016f60fe91b8-14dae921faa80000, value = {"payload":"{}","headers":{"reply_outcome-type":"SUCCESS","commandreply_type":"net.chrisrichardson.ftgo.consumerservice.api.ValidateOrderByConsumer","commandreply_saga_type":"net.chrisrichardson.ftgo.orderservice.sagas.crea teorder.CreateOrderSaga","X-B3-ParentSpanId":"480092d576bd3f0b","reply_type":"io.eventuate.tram.commands.common.Success","reply_to_message_id":"0000016f60fe9de4-0242ac13000b0000","commandreply_saga_id":"0000016f60fe9dda-0242ac13000b0000","DATE":"Wed, 1 Jan 2020 12:04:53 GMT","commandreplydestinati on":"consumerService","commandreply_reply_to":"net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-reply","X-B3-SpanId":"bb0b7f10509ce21e","DESTINATION":"net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-reply","X-B3-Sampled":"1","X-B3-TraceId":"6049136193c6 1eb1","ID":"0000016f60fe91b8-14dae921faa80000"}} ftgo-order-service_1 | 2020-01-01T12:04:57.053931666Z 2020-01-01 12:04:57.053 DEBUG [ftgo-order-service,,,] 1 --- [erSaga-consumer] i.e.m.k.b.c.EventuateKafkaConsumer : Processed net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-consumer 1 records ftgo-order-service_1 | 2020-01-01T12:04:57.055680756Z 2020-01-01 12:04:57.053 DEBUG [ftgo-order-service,,,] 1 --- [erSaga-consumer] i.e.m.k.b.c.EventuateKafkaConsumer : To commit net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-consumer io.eventuate.messaging.ka fka.basic.consumer.OffsetTracker@ce87c5a[state={net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-reply-0=io.eventuate.messaging.kafka.basic.consumer.TopicPartitionOffsets@1599fd9a[unprocessed=[2],processed=[]], net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrde rSaga-reply-1=io.eventuate.messaging.kafka.basic.consumer.TopicPartitionOffsets@1fece3c3[unprocessed=[2, 3, 4],processed=[]]}] ftgo-order-service_1 | 2020-01-01T12:04:57.076355352Z 2020-01-01 12:04:57.075 INFO [ftgo-order-service,571383ec71cf5e1b,01a3f398574b3e7d,true] 1 --- [pool-1-thread-1] o.s.jdbc.support.SQLErrorCodesFactory : SQLErrorCodes loaded: [DB2, Derby, H2, HDB, HSQL, Informix, MS-SQL, MySQL, Oracl e, PostgreSQL, Sybase] ftgo-order-service_1 | 2020-01-01T12:04:57.095613539Z 2020-01-01 12:04:57.090 TRACE [ftgo-order-service,571383ec71cf5e1b,01a3f398574b3e7d,true] 1 --- [pool-1-thread-1] i.e.t.c.c.DecoratedMessageHandlerFactory : handled message net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOr derSaga-consumer 0000016f60fe8b5a-14dae921faa80000 ftgo-order-service_1 | 2020-01-01T12:04:57.101950946Z 2020-01-01 12:04:57.101 DEBUG [ftgo-order-service,,,] 1 --- [pool-1-thread-1] i.e.m.k.b.c.KafkaMessageProcessor : Adding processed record to queue net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-consumer 2 ftgo-order-service_1 | 2020-01-01T12:04:57.121869241Z 2020-01-01 12:04:57.120 TRACE [ftgo-order-service,571383ec71cf5e1b,5dfbb3dddc4b13cb,true] 1 --- [pool-1-thread-1] i.e.t.c.c.DecoratedMessageHandlerFactory : Invoking handler net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateO rderSaga-consumer 0000016f60fe9a1e-0242ac13000b0000 ftgo-order-service_1 | 2020-01-01T12:04:57.121892510Z 2020-01-01 12:04:57.120 DEBUG [ftgo-order-service,571383ec71cf5e1b,5dfbb3dddc4b13cb,true] 1 --- [pool-1-thread-1] i.e.t.s.orchestration.SagaManagerImpl : handle message invoked io.eventuate.tram.messaging.common.MessageImpl@7262e9f[pa yload={},headers={reply_outcome-type=SUCCESS, commandreply_type=net.chrisrichardson.ftgo.orderservice.sagaparticipants.ApproveOrderCommand, commandreply_saga_type=net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga, reply_type=io.eventuate.tram.commands.common.Success, reply_to_m essage_id=0000016f60fe98bc-0242ac13000b0000, commandreply_saga_id=0000016f60fe9323-0242ac13000b0000, DATE=Wed, 1 Jan 2020 12:04:55 GMT, commandreply__destination=orderService, commandreply_reply_to=net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-reply, DESTINATION=net.chrisri chardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-reply, ID=0000016f60fe9a1e-0242ac13000b0000, X-B3-TraceId=571383ec71cf5e1b, X-B3-SpanId=5dfbb3dddc4b13cb, X-B3-ParentSpanId=2797728bcfed0b91, X-B3-Sampled=1}] ftgo-order-service_1 | 2020-01-01T12:04:57.131306102Z 2020-01-01 12:04:57.127 DEBUG [ftgo-order-service,571383ec71cf5e1b,5dfbb3dddc4b13cb,true] 1 --- [pool-1-thread-1] i.e.t.s.orchestration.SagaManagerImpl : Handle reply: io.eventuate.tram.messaging.common.MessageImpl@7262e9f[payload={}, headers={reply_outcome-type=SUCCESS, commandreply_type=net.chrisrichardson.ftgo.orderservice.sagaparticipants.ApproveOrderCommand, commandreply_saga_type=net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga, reply_type=io.eventuate.tram.commands.common.Success, reply_to_message_id =0000016f60fe98bc-0242ac13000b0000, commandreply_saga_id=0000016f60fe9323-0242ac13000b0000, DATE=Wed, 1 Jan 2020 12:04:55 GMT, commandreplydestination=orderService, commandreply_reply_to=net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-reply, DESTINATION=net.chrisrichardson. ftgo.orderservice.sagas.createorder.CreateOrderSaga-reply, ID=0000016f60fe9a1e-0242ac13000b0000, X-B3-TraceId=571383ec71cf5e1b, X-B3-SpanId=5dfbb3dddc4b13cb, X-B3-ParentSpanId=2797728bcfed0b91, X-B3-Sampled=1}] ftgo-order-service_1 | 2020-01-01T12:04:57.131351654Z 2020-01-01 12:04:57.127 INFO [ftgo-order-service,571383ec71cf5e1b,5dfbb3dddc4b13cb,true] 1 --- [pool-1-thread-1] i.e.t.s.o.SagaInstanceRepositoryJdbc : finding net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga 0000016f60fe9323-0242ac13000b0000 ftgo-order-service_1 | 2020-01-01T12:04:57.140293313Z 2020-01-01 12:04:57.139 TRACE [ftgo-order-service,571383ec71cf5e1b,5fe90e8d4aa20856,true] 1 --- [pool-1-thread-2] i.e.t.c.c.DecoratedMessageHandlerFactory : handled message net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOr derSaga-consumer 0000016f60fe996d-0242ac13000c0000 ftgo-order-service_1 | 2020-01-01T12:04:57.143824680Z 2020-01-01 12:04:57.142 INFO [ftgo-order-service,571383ec71cf5e1b,5dfbb3dddc4b13cb,true] 1 --- [pool-1-thread-1] i.e.t.s.orchestration.SagaManagerImpl : Current state={"currentlyExecuting":0,"compensating":false,"endState":true} ftgo-order-service_1 | 2020-01-01T12:04:57.144624755Z 2020-01-01 12:04:57.144 TRACE [ftgo-order-service,571383ec71cf5e1b,5dfbb3dddc4b13cb,true] 1 --- [pool-1-thread-1] i.e.t.c.c.DecoratedMessageHandlerFactory : Got exception net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrde rSaga-consumer 0000016f60fe9a1e-0242ac13000b0000 ftgo-order-service_1 | 2020-01-01T12:04:57.169052863Z 2020-01-01 12:04:57.165 DEBUG [ftgo-order-service,,,] 1 --- [erSaga-consumer] i.e.m.k.b.c.EventuateKafkaConsumer : Committing offsets net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-consumer {net.chrisrichar dson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-reply-1=OffsetAndMetadata{offset=3, metadata=''}} ftgo-order-service_1 | 2020-01-01T12:04:57.177613532Z 2020-01-01 12:04:57.174 DEBUG [ftgo-order-service,,,] 1 --- [pool-1-thread-2] i.e.m.k.b.c.KafkaMessageProcessor : Adding processed record to queue net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-consumer 2 ftgo-order-service_1 | 2020-01-01T12:04:57.186685909Z 2020-01-01 12:04:57.182 TRACE [ftgo-order-service,571383ec71cf5e1b,5dfbb3dddc4b13cb,true] 1 --- [pool-1-thread-1] i.e.t.c.c.DecoratedMessageHandlerFactory : Got exception ftgo-order-service_1 | 2020-01-01T12:04:57.186767113Z ftgo-order-service_1 | 2020-01-01T12:04:57.186786262Z java.util.NoSuchElementException: No value present ftgo-order-service_1 | 2020-01-01T12:04:57.186802504Z at java.util.Optional.get(Optional.java:135) ~[na:1.8.0_171] ftgo-order-service_1 | 2020-01-01T12:04:57.186816443Z at io.eventuate.tram.sagas.simpledsl.ParticipantInvocationStep.isSuccessfulReply(ParticipantInvocationStep.java:46) ~[eventuate-tram-sagas-simple-dsl-0.12.0.RC5.jar!/:na] ftgo-order-service_1 | 2020-01-01T12:04:57.186829185Z at io.eventuate.tram.sagas.simpledsl.SimpleSagaDefinition.handleReply(SimpleSagaDefinition.java:43) ~[eventuate-tram-sagas-simple-dsl-0.12.0.RC5.jar!/:na] ftgo-order-service_1 | 2020-01-01T12:04:57.186843158Z at io.eventuate.tram.sagas.orchestration.SagaManagerImpl.handleReply(SagaManagerImpl.java:197) ~[eventuate-tram-sagas-orchestration-0.12.0.RC5.jar!/:na] ftgo-order-service_1 | 2020-01-01T12:04:57.186857536Z at io.eventuate.tram.sagas.orchestration.SagaManagerImpl.handleMessage(SagaManagerImpl.java:167) ~[eventuate-tram-sagas-orchestration-0.12.0.RC5.jar!/:na] ftgo-order-service_1 | 2020-01-01T12:04:57.186894219Z at io.eventuate.tram.consumer.common.DecoratedMessageHandlerFactory.lambda$decorate$0(DecoratedMessageHandlerFactory.java:33) ~[eventuate-tram-consumer-common-0.22.0.RC5.jar!/:na] ftgo-order-service_1 | 2020-01-01T12:04:57.186910767Z at io.eventuate.tram.consumer.common.PrePostHandlerMessageHandlerDecorator.accept(PrePostHandlerMessageHandlerDecorator.java:26) ~[eventuate-tram-consumer-common-0.22.0.RC5.jar!/:na] ftgo-order-service_1 | 2020-01-01T12:04:57.186925637Z at io.eventuate.tram.consumer.common.PrePostHandlerMessageHandlerDecorator.accept(PrePostHandlerMessageHandlerDecorator.java:11) ~[eventuate-tram-consumer-common-0.22.0.RC5.jar!/:na] ftgo-order-service_1 | 2020-01-01T12:04:57.186940378Z at io.eventuate.tram.consumer.common.MessageHandlerDecoratorChainBuilder.lambda$buildChain$0(MessageHandlerDecoratorChainBuilder.java:38) ~[eventuate-tram-consumer-common-0.22.0.RC5.jar!/:na] ftgo-order-service_1 | 2020-01-01T12:04:57.186952693Z at io.eventuate.tram.consumer.common.DuplicateDetectingMessageHandlerDecorator.lambda$accept$0(DuplicateDetectingMessageHandlerDecorator.java:15) ~[eventuate-tram-consumer-common-0.22.0.RC5.jar!/:na] ftgo-order-service_1 | 2020-01-01T12:04:57.186980751Z at io.eventuate.tram.consumer.jdbc.SqlTableBasedDuplicateMessageDetector.lambda$doWithMessage$0(SqlTableBasedDuplicateMessageDetector.java:53) ~[eventuate-tram-consumer-jdbc-0.22.0.RC5.jar!/:na] ftgo-order-service_1 | 2020-01-01T12:04:57.187015438Z at org.springframework.transaction.support.TransactionTemplate.execute(TransactionTemplate.java:140) ~[spring-tx-5.0.7.RELEASE.jar!/:5.0.7.RELEASE] ftgo-order-service_1 | 2020-01-01T12:04:57.187033318Z at io.eventuate.tram.consumer.jdbc.SqlTableBasedDuplicateMessageDetector.doWithMessage(SqlTableBasedDuplicateMessageDetector.java:50) ~[eventuate-tram-consumer-jdbc-0.22.0.RC5.jar!/:na] ftgo-order-service_1 | 2020-01-01T12:04:57.187047895Z at io.eventuate.tram.consumer.common.DuplicateDetectingMessageHandlerDecorator.accept(DuplicateDetectingMessageHandlerDecorator.java:15) ~[eventuate-tram-consumer-common-0.22.0.RC5.jar!/:na] ftgo-order-service_1 | 2020-01-01T12:04:57.187061775Z at io.eventuate.tram.consumer.common.DuplicateDetectingMessageHandlerDecorator.accept(DuplicateDetectingMessageHandlerDecorator.java:5) ~[eventuate-tram-consumer-common-0.22.0.RC5.jar!/:na] ftgo-order-service_1 | 2020-01-01T12:04:57.187075679Z at io.eventuate.tram.consumer.common.MessageHandlerDecoratorChainBuilder.lambda$buildChain$0(MessageHandlerDecoratorChainBuilder.java:38) ~[eventuate-tram-consumer-common-0.22.0.RC5.jar!/:na] ftgo-order-service_1 | 2020-01-01T12:04:57.187088409Z at io.eventuate.tram.consumer.common.PrePostReceiveMessageHandlerDecorator.accept(PrePostReceiveMessageHandlerDecorator.java:25) ~[eventuate-tram-consumer-common-0.22.0.RC5.jar!/:na] ftgo-order-service_1 | 2020-01-01T12:04:57.187102378Z at io.eventuate.tram.consumer.common.PrePostReceiveMessageHandlerDecorator.accept(PrePostReceiveMessageHandlerDecorator.java:11) ~[eventuate-tram-consumer-common-0.22.0.RC5.jar!/:na] ftgo-order-service_1 | 2020-01-01T12:04:57.187115779Z at io.eventuate.tram.consumer.common.MessageHandlerDecoratorChainBuilder.lambda$buildChain$0(MessageHandlerDecoratorChainBuilder.java:38) ~[eventuate-tram-consumer-common-0.22.0.RC5.jar!/:na] ftgo-order-service_1 | 2020-01-01T12:04:57.187200995Z at io.eventuate.tram.consumer.common.MessageConsumerImpl.lambda$subscribe$0(MessageConsumerImpl.java:34) ~[eventuate-tram-consumer-common-0.22.0.RC5.jar!/:na] ftgo-order-service_1 | 2020-01-01T12:04:57.187220289Z at io.eventuate.tram.consumer.kafka.EventuateTramKafkaMessageConsumer.lambda$subscribe$0(EventuateTramKafkaMessageConsumer.java:24) ~[eventuate-tram-consumer-kafka-0.22.0.RC5.jar!/:na] ftgo-order-service_1 | 2020-01-01T12:04:57.187235277Z at io.eventuate.messaging.kafka.consumer.MessageConsumerKafkaImpl.handle(MessageConsumerKafkaImpl.java:60) ~[eventuate-messaging-kafka-consumer-0.3.0.RELEASE.jar!/:na] ftgo-order-service_1 | 2020-01-01T12:04:57.187250353Z at io.eventuate.messaging.kafka.consumer.MessageConsumerKafkaImpl.lambda$null$0(MessageConsumerKafkaImpl.java:40) ~[eventuate-messaging-kafka-consumer-0.3.0.RELEASE.jar!/:na] ftgo-order-service_1 | 2020-01-01T12:04:57.187264904Z at io.eventuate.messaging.kafka.consumer.SwimlaneDispatcher.processQueuedMessage(SwimlaneDispatcher.java:72) ~[eventuate-messaging-kafka-consumer-0.3.0.RELEASE.jar!/:na] ftgo-order-service_1 | 2020-01-01T12:04:57.187278454Z at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) ~[na:1.8.0_171] ftgo-order-service_1 | 2020-01-01T12:04:57.187290951Z at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) ~[na:1.8.0_171] ftgo-order-service_1 | 2020-01-01T12:04:57.187303575Z at java.lang.Thread.run(Thread.java:748) ~[na:1.8.0_171] ftgo-order-service_1 | 2020-01-01T12:04:57.187316393Z ftgo-order-service_1 | 2020-01-01T12:04:57.198724579Z 2020-01-01 12:04:57.197 ERROR [ftgo-order-service,,,] 1 --- [pool-1-thread-1] i.e.m.k.b.c.KafkaMessageProcessor : Got exception: ftgo-order-service_1 | 2020-01-01T12:04:57.198805520Z ftgo-order-service_1 | 2020-01-01T12:04:57.198823565Z java.util.NoSuchElementException: No value present ftgo-order-service_1 | 2020-01-01T12:04:57.198832917Z at java.util.Optional.get(Optional.java:135) ~[na:1.8.0_171] ftgo-order-service_1 | 2020-01-01T12:04:57.198841835Z at io.eventuate.tram.sagas.simpledsl.ParticipantInvocationStep.isSuccessfulReply(ParticipantInvocationStep.java:46) ~[eventuate-tram-sagas-simple-dsl-0.12.0.RC5.jar!/:na] ftgo-order-service_1 | 2020-01-01T12:04:57.198850888Z at io.eventuate.tram.sagas.simpledsl.SimpleSagaDefinition.handleReply(SimpleSagaDefinition.java:43) ~[eventuate-tram-sagas-simple-dsl-0.12.0.RC5.jar!/:na] ftgo-order-service_1 | 2020-01-01T12:04:57.198860046Z at io.eventuate.tram.sagas.orchestration.SagaManagerImpl.handleReply(SagaManagerImpl.java:197) ~[eventuate-tram-sagas-orchestration-0.12.0.RC5.jar!/:na] ftgo-order-service_1 | 2020-01-01T12:04:57.198872461Z at io.eventuate.tram.sagas.orchestration.SagaManagerImpl.handleMessage(SagaManagerImpl.java:167) ~[eventuate-tram-sagas-orchestration-0.12.0.RC5.jar!/:na] ftgo-order-service_1 | 2020-01-01T12:04:57.198903711Z at io.eventuate.tram.consumer.common.DecoratedMessageHandlerFactory.lambda$decorate$0(DecoratedMessageHandlerFactory.java:33) ~[eventuate-tram-consumer-common-0.22.0.RC5.jar!/:na] ftgo-order-service_1 | 2020-01-01T12:04:57.198918526Z at io.eventuate.tram.consumer.common.PrePostHandlerMessageHandlerDecorator.accept(PrePostHandlerMessageHandlerDecorator.java:26) ~[eventuate-tram-consumer-common-0.22.0.RC5.jar!/:na] ftgo-order-service_1 | 2020-01-01T12:04:57.198931886Z at io.eventuate.tram.consumer.common.PrePostHandlerMessageHandlerDecorator.accept(PrePostHandlerMessageHandlerDecorator.java:11) ~[eventuate-tram-consumer-common-0.22.0.RC5.jar!/:na] ftgo-order-service_1 | 2020-01-01T12:04:57.198978943Z at io.eventuate.tram.consumer.common.MessageHandlerDecoratorChainBuilder.lambda$buildChain$0(MessageHandlerDecoratorChainBuilder.java:38) ~[eventuate-tram-consumer-common-0.22.0.RC5.jar!/:na] ftgo-order-service_1 | 2020-01-01T12:04:57.199018988Z at io.eventuate.tram.consumer.common.DuplicateDetectingMessageHandlerDecorator.lambda$accept$0(DuplicateDetectingMessageHandlerDecorator.java:15) ~[eventuate-tram-consumer-common-0.22.0.RC5.jar!/:na] ftgo-order-service_1 | 2020-01-01T12:04:57.199037582Z at io.eventuate.tram.consumer.jdbc.SqlTableBasedDuplicateMessageDetector.lambda$doWithMessage$0(SqlTableBasedDuplicateMessageDetector.java:53) ~[eventuate-tram-consumer-jdbc-0.22.0.RC5.jar!/:na] ftgo-order-service_1 | 2020-01-01T12:04:57.199054285Z at org.springframework.transaction.support.TransactionTemplate.execute(TransactionTemplate.java:140) ~[spring-tx-5.0.7.RELEASE.jar!/:5.0.7.RELEASE] ftgo-order-service_1 | 2020-01-01T12:04:57.199068699Z at io.eventuate.tram.consumer.jdbc.SqlTableBasedDuplicateMessageDetector.doWithMessage(SqlTableBasedDuplicateMessageDetector.java:50) ~[eventuate-tram-consumer-jdbc-0.22.0.RC5.jar!/:na] ftgo-order-service_1 | 2020-01-01T12:04:57.199080792Z at io.eventuate.tram.consumer.common.DuplicateDetectingMessageHandlerDecorator.accept(DuplicateDetectingMessageHandlerDecorator.java:15) ~[eventuate-tram-consumer-common-0.22.0.RC5.jar!/:na] ftgo-order-service_1 | 2020-01-01T12:04:57.199092906Z at io.eventuate.tram.consumer.common.DuplicateDetectingMessageHandlerDecorator.accept(DuplicateDetectingMessageHandlerDecorator.java:5) ~[eventuate-tram-consumer-common-0.22.0.RC5.jar!/:na] ftgo-order-service_1 | 2020-01-01T12:04:57.199119355Z at io.eventuate.tram.consumer.common.MessageHandlerDecoratorChainBuilder.lambda$buildChain$0(MessageHandlerDecoratorChainBuilder.java:38) ~[eventuate-tram-consumer-common-0.22.0.RC5.jar!/:na] ftgo-order-service_1 | 2020-01-01T12:04:57.199133742Z at io.eventuate.tram.consumer.common.PrePostReceiveMessageHandlerDecorator.accept(PrePostReceiveMessageHandlerDecorator.java:25) ~[eventuate-tram-consumer-common-0.22.0.RC5.jar!/:na] ftgo-order-service_1 | 2020-01-01T12:04:57.199146109Z at io.eventuate.tram.consumer.common.PrePostReceiveMessageHandlerDecorator.accept(PrePostReceiveMessageHandlerDecorator.java:11) ~[eventuate-tram-consumer-common-0.22.0.RC5.jar!/:na] ftgo-order-service_1 | 2020-01-01T12:04:57.199159610Z at io.eventuate.tram.consumer.common.MessageHandlerDecoratorChainBuilder.lambda$buildChain$0(MessageHandlerDecoratorChainBuilder.java:38) ~[eventuate-tram-consumer-common-0.22.0.RC5.jar!/:na] ftgo-order-service_1 | 2020-01-01T12:04:57.199171657Z at io.eventuate.tram.consumer.common.MessageConsumerImpl.lambda$subscribe$0(MessageConsumerImpl.java:34) ~[eventuate-tram-consumer-common-0.22.0.RC5.jar!/:na] ftgo-order-service_1 | 2020-01-01T12:04:57.199184745Z at io.eventuate.tram.consumer.kafka.EventuateTramKafkaMessageConsumer.lambda$subscribe$0(EventuateTramKafkaMessageConsumer.java:24) ~[eventuate-tram-consumer-kafka-0.22.0.RC5.jar!/:na] ftgo-order-service_1 | 2020-01-01T12:04:57.199197989Z at io.eventuate.messaging.kafka.consumer.MessageConsumerKafkaImpl.handle(MessageConsumerKafkaImpl.java:60) [eventuate-messaging-kafka-consumer-0.3.0.RELEASE.jar!/:na] ftgo-order-service_1 | 2020-01-01T12:04:57.199213281Z at io.eventuate.messaging.kafka.consumer.MessageConsumerKafkaImpl.lambda$null$0(MessageConsumerKafkaImpl.java:40) [eventuate-messaging-kafka-consumer-0.3.0.RELEASE.jar!/:na] ftgo-order-service_1 | 2020-01-01T12:04:57.199227221Z at io.eventuate.messaging.kafka.consumer.SwimlaneDispatcher.processQueuedMessage(SwimlaneDispatcher.java:72) ~[eventuate-messaging-kafka-consumer-0.3.0.RELEASE.jar!/:na] ftgo-order-service_1 | 2020-01-01T12:04:57.199242033Z at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) ~[na:1.8.0_171] ftgo-order-service_1 | 2020-01-01T12:04:57.199256510Z at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) ~[na:1.8.0_171] ftgo-order-service_1 | 2020-01-01T12:04:57.199270062Z at java.lang.Thread.run(Thread.java:748) ~[na:1.8.0_171] ftgo-order-service_1 | 2020-01-01T12:04:57.199282064Z ftgo-order-service_1 | 2020-01-01T12:04:57.203190241Z 2020-01-01 12:04:57.202 ERROR [ftgo-order-service,,,] 1 --- [pool-1-thread-1] i.e.m.kafka.consumer.SwimlaneDispatcher : Exception handling message - terminating ftgo-order-service_1 | 2020-01-01T12:04:57.203229010Z ftgo-order-service_1 | 2020-01-01T12:04:57.203239526Z java.util.NoSuchElementException: No value present ftgo-order-service_1 | 2020-01-01T12:04:57.203248428Z at java.util.Optional.get(Optional.java:135) ~[na:1.8.0_171] ftgo-order-service_1 | 2020-01-01T12:04:57.203257491Z at io.eventuate.tram.sagas.simpledsl.ParticipantInvocationStep.isSuccessfulReply(ParticipantInvocationStep.java:46) ~[eventuate-tram-sagas-simple-dsl-0.12.0.RC5.jar!/:na] ftgo-order-service_1 | 2020-01-01T12:04:57.203266655Z at io.eventuate.tram.sagas.simpledsl.SimpleSagaDefinition.handleReply(SimpleSagaDefinition.java:43) ~[eventuate-tram-sagas-simple-dsl-0.12.0.RC5.jar!/:na] ftgo-order-service_1 | 2020-01-01T12:04:57.203276464Z at io.eventuate.tram.sagas.orchestration.SagaManagerImpl.handleReply(SagaManagerImpl.java:197) ~[eventuate-tram-sagas-orchestration-0.12.0.RC5.jar!/:na] ftgo-order-service_1 | 2020-01-01T12:04:57.203297838Z at io.eventuate.tram.sagas.orchestration.SagaManagerImpl.handleMessage(SagaManagerImpl.java:167) ~[eventuate-tram-sagas-orchestration-0.12.0.RC5.jar!/:na] ftgo-order-service_1 | 2020-01-01T12:04:57.203307625Z at io.eventuate.tram.consumer.common.DecoratedMessageHandlerFactory.lambda$decorate$0(DecoratedMessageHandlerFactory.java:33) ~[eventuate-tram-consumer-common-0.22.0.RC5.jar!/:na] ftgo-order-service_1 | 2020-01-01T12:04:57.203317062Z at io.eventuate.tram.consumer.common.PrePostHandlerMessageHandlerDecorator.accept(PrePostHandlerMessageHandlerDecorator.java:26) ~[eventuate-tram-consumer-common-0.22.0.RC5.jar!/:na] ftgo-order-service_1 | 2020-01-01T12:04:57.203326297Z at io.eventuate.tram.consumer.common.PrePostHandlerMessageHandlerDecorator.accept(PrePostHandlerMessageHandlerDecorator.java:11) ~[eventuate-tram-consumer-common-0.22.0.RC5.jar!/:na] ftgo-order-service_1 | 2020-01-01T12:04:57.203335597Z at io.eventuate.tram.consumer.common.MessageHandlerDecoratorChainBuilder.lambda$buildChain$0(MessageHandlerDecoratorChainBuilder.java:38) ~[eventuate-tram-consumer-common-0.22.0.RC5.jar!/:na] ftgo-order-service_1 | 2020-01-01T12:04:57.203344854Z at io.eventuate.tram.consumer.common.DuplicateDetectingMessageHandlerDecorator.lambda$accept$0(DuplicateDetectingMessageHandlerDecorator.java:15) ~[eventuate-tram-consumer-common-0.22.0.RC5.jar!/:na] ftgo-order-service_1 | 2020-01-01T12:04:57.203355585Z at io.eventuate.tram.consumer.jdbc.SqlTableBasedDuplicateMessageDetector.lambda$doWithMessage$0(SqlTableBasedDuplicateMessageDetector.java:53) ~[eventuate-tram-consumer-jdbc-0.22.0.RC5.jar!/:na] ftgo-order-service_1 | 2020-01-01T12:04:57.203365328Z at org.springframework.transaction.support.TransactionTemplate.execute(TransactionTemplate.java:140) ~[spring-tx-5.0.7.RELEASE.jar!/:5.0.7.RELEASE] ftgo-order-service_1 | 2020-01-01T12:04:57.203374479Z at io.eventuate.tram.consumer.jdbc.SqlTableBasedDuplicateMessageDetector.doWithMessage(SqlTableBasedDuplicateMessageDetector.java:50) ~[eventuate-tram-consumer-jdbc-0.22.0.RC5.jar!/:na] ftgo-order-service_1 | 2020-01-01T12:04:57.203383709Z at io.eventuate.tram.consumer.common.DuplicateDetectingMessageHandlerDecorator.accept(DuplicateDetectingMessageHandlerDecorator.java:15) ~[eventuate-tram-consumer-common-0.22.0.RC5.jar!/:na] ftgo-order-service_1 | 2020-01-01T12:04:57.203392988Z at io.eventuate.tram.consumer.common.DuplicateDetectingMessageHandlerDecorator.accept(DuplicateDetectingMessageHandlerDecorator.java:5) ~[eventuate-tram-consumer-common-0.22.0.RC5.jar!/:na] ftgo-order-service_1 | 2020-01-01T12:04:57.203402234Z at io.eventuate.tram.consumer.common.MessageHandlerDecoratorChainBuilder.lambda$buildChain$0(MessageHandlerDecoratorChainBuilder.java:38) ~[eventuate-tram-consumer-common-0.22.0.RC5.jar!/:na] ftgo-order-service_1 | 2020-01-01T12:04:57.203411433Z at io.eventuate.tram.consumer.common.PrePostReceiveMessageHandlerDecorator.accept(PrePostReceiveMessageHandlerDecorator.java:25) ~[eventuate-tram-consumer-common-0.22.0.RC5.jar!/:na] ftgo-order-service_1 | 2020-01-01T12:04:57.203420589Z at io.eventuate.tram.consumer.common.PrePostReceiveMessageHandlerDecorator.accept(PrePostReceiveMessageHandlerDecorator.java:11) ~[eventuate-tram-consumer-common-0.22.0.RC5.jar!/:na] ftgo-order-service_1 | 2020-01-01T12:04:57.203430311Z at io.eventuate.tram.consumer.common.MessageHandlerDecoratorChainBuilder.lambda$buildChain$0(MessageHandlerDecoratorChainBuilder.java:38) ~[eventuate-tram-consumer-common-0.22.0.RC5.jar!/:na] ftgo-order-service_1 | 2020-01-01T12:04:57.203442976Z at io.eventuate.tram.consumer.common.MessageConsumerImpl.lambda$subscribe$0(MessageConsumerImpl.java:34) ~[eventuate-tram-consumer-common-0.22.0.RC5.jar!/:na] ftgo-order-service_1 | 2020-01-01T12:04:57.203462898Z at io.eventuate.tram.consumer.kafka.EventuateTramKafkaMessageConsumer.lambda$subscribe$0(EventuateTramKafkaMessageConsumer.java:24) ~[eventuate-tram-consumer-kafka-0.22.0.RC5.jar!/:na] ftgo-order-service_1 | 2020-01-01T12:04:57.203478759Z at io.eventuate.messaging.kafka.consumer.MessageConsumerKafkaImpl.handle(MessageConsumerKafkaImpl.java:60) ~[eventuate-messaging-kafka-consumer-0.3.0.RELEASE.jar!/:na] ftgo-order-service_1 | 2020-01-01T12:04:57.203494822Z at io.eventuate.messaging.kafka.consumer.MessageConsumerKafkaImpl.lambda$null$0(MessageConsumerKafkaImpl.java:40) ~[eventuate-messaging-kafka-consumer-0.3.0.RELEASE.jar!/:na] ftgo-order-service_1 | 2020-01-01T12:04:57.203582301Z at io.eventuate.messaging.kafka.consumer.SwimlaneDispatcher.processQueuedMessage(SwimlaneDispatcher.java:72) ~[eventuate-messaging-kafka-consumer-0.3.0.RELEASE.jar!/:na] ftgo-order-service_1 | 2020-01-01T12:04:57.203600237Z at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) ~[na:1.8.0_171] ftgo-order-service_1 | 2020-01-01T12:04:57.203609590Z at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) ~[na:1.8.0_171] ftgo-order-service_1 | 2020-01-01T12:04:57.203618607Z at java.lang.Thread.run(Thread.java:748) ~[na:1.8.0_171] ftgo-order-service_1 | 2020-01-01T12:04:57.203627345Z ftgo-order-service_1 | 2020-01-01T12:04:57.249695792Z 2020-01-01 12:04:57.249 DEBUG [ftgo-order-service,,,] 1 --- [erSaga-consumer] i.e.m.k.b.c.EventuateKafkaConsumer : Committed offsets net.chrisrichardson.ftgo.orderservice.sagas.createorder.CreateOrderSaga-consumer ftgo-order-service1 | 2020-01-01T12:04:57.291872245Z 2020-01-01 12:04:57.290 DEBUG [ftgo-order-service,3de60b52a809ade7,3de60b52a809ade7,true] 1 --- [io-8080-exec-10] org.hibernate.SQL : select order0.id as id1_30, order0_.consumer_id as consumer2_30, order0_.ci ty as city3_30, order0_.delivery_state as delivery4_30, order0_.street1 as street5_30, order0_.street2 as street6_30, order0_.zip as zip7_30, order0_.delivery_time as delivery8_30, order0_.amount as amount9_30, order0_.payment_token as payment10_30, order0_.restaurant_id as restaur11 _30, order0_.state as state12_30, order0_.version as version13_30 from orders order0 where order0.id=? ftgo-order-service1 | 2020-01-01T12:04:57.305166936Z 2020-01-01 12:04:57.304 DEBUG [ftgo-order-service,3de60b52a809ade7,3de60b52a809ade7,true] 1 --- [io-8080-exec-10] org.hibernate.SQL : select lineitems0.order_id as order_id1_00, lineitems0_.menu_itemid as menu ite2_00, lineitems0_.name as name3_00, lineitems0_.price as price4_00, lineitems0_.quantity as quantity5_00 from order_lineitems lineitems0 where lineitems0_.order_id=? ftgo-order-service1 | 2020-01-01T12:04:57.872727441Z 2020-01-01 12:04:57.872 DEBUG [ftgo-order-service,9ce16b9f4ce99bc2,9ce16b9f4ce99bc2,true] 1 --- [nio-8080-exec-9] org.hibernate.SQL : select order0.id as id1_30, order0_.consumer_id as consumer2_30, order0_.ci ty as city3_30, order0_.delivery_state as delivery4_30, order0_.street1 as street5_30, order0_.street2 as street6_30, order0_.zip as zip7_30, order0_.delivery_time as delivery8_30, order0_.amount as amount9_30, order0_.payment_token as payment10_30, order0_.restaurant_id as restaur11 _30, order0_.state as state12_30, order0_.version as version13_30 from orders order0 where order0.id=? ftgo-order-service1 | 2020-01-01T12:04:57.878209001Z 2020-01-01 12:04:57.877 DEBUG [ftgo-order-service,9ce16b9f4ce99bc2,9ce16b9f4ce99bc2,true] 1 --- [nio-8080-exec-9] org.hibernate.SQL : select lineitems0.order_id as order_id1_00, lineitems0_.menu_itemid as menu ite2_00, lineitems0_.name as name3_00, lineitems0_.price as price4_00, lineitems0_.quantity as quantity5_00 from order_lineitems lineitems0 where lineitems0_.order_id=? ftgo-order-service1 | 2020-01-01T12:04:58.440744384Z 2020-01-01 12:04:58.440 DEBUG [ftgo-order-service,7d2b5e5f8cc507af,7d2b5e5f8cc507af,true] 1 --- [nio-8080-exec-1] org.hibernate.SQL : select order0.id as id1_30, order0_.consumer_id as consumer2_30, order0_.ci ty as city3_30, order0_.delivery_state as delivery4_30, order0_.street1 as street5_30, order0_.street2 as street6_30, order0_.zip as zip7_30, order0_.delivery_time as delivery8_30, order0_.amount as amount9_30, order0_.payment_token as payment10_30, order0_.restaurant_id as restaur11 _30, order0_.state as state12_30, order0_.version as version13_30 from orders order0 where order0.id=? ftgo-order-service1 | 2020-01-01T12:04:58.444762194Z 2020-01-01 12:04:58.444 DEBUG [ftgo-order-service,7d2b5e5f8cc507af,7d2b5e5f8cc507af,true] 1 --- [nio-8080-exec-1] org.hibernate.SQL : select lineitems0.order_id as order_id1_00, lineitems0_.menu_itemid as menu ite2_00, lineitems0_.name as name3_00, lineitems0_.price as price4_00, lineitems0_.quantity as quantity5_00 from order_lineitems lineitems0 where lineitems0_.order_id=? ftgo-order-service1 | 2020-01-01T12:04:59.028212300Z 2020-01-01 12:04:59.026 DEBUG [ftgo-order-service,635527ee0f29c373,635527ee0f29c373,true] 1 --- [nio-8080-exec-2] org.hibernate.SQL : select order0.id as id1_30, order0_.consumer_id as consumer2_30, order0_.ci ty as city3_30, order0_.delivery_state as delivery4_30, order0_.street1 as street5_30, order0_.street2 as street6_30, order0_.zip as zip7_30, order0_.delivery_time as delivery8_30, order0_.amount as amount9_30, order0_.payment_token as payment10_30, order0_.restaurant_id as restaur11 _30, order0_.state as state12_30, order0_.version as version13_30 from orders order0 where order0.id=? ftgo-order-service1 | 2020-01-01T12:04:59.052327578Z 2020-01-01 12:04:59.051 DEBUG [ftgo-order-service,635527ee0f29c373,635527ee0f29c373,true] 1 --- [nio-8080-exec-2] org.hibernate.SQL : select lineitems0.order_id as order_id1_00, lineitems0_.menu_itemid as menu ite2_00, lineitems0_.name as name3_00, lineitems0_.price as price4_00, lineitems0_.quantity as quantity5_00 from order_lineitems lineitems0 where lineitems0_.order_id=? ftgo-order-service1 | 2020-01-01T12:04:59.624916792Z 2020-01-01 12:04:59.624 DEBUG [ftgo-order-service,54be3e63d6a425a4,54be3e63d6a425a4,true] 1 --- [nio-8080-exec-3] org.hibernate.SQL : select order0.id as id1_30, order0_.consumer_id as consumer2_30, order0_.ci ty as city3_30, order0_.delivery_state as delivery4_30, order0_.street1 as street5_30, order0_.street2 as street6_30, order0_.zip as zip7_30, order0_.delivery_time as delivery8_30, order0_.amount as amount9_30, order0_.payment_token as payment10_30, order0_.restaurant_id as restaur11 _30, order0_.state as state12_30, order0_.version as version13_30 from orders order0 where order0.id=? ftgo-order-service1 | 2020-01-01T12:04:59.629189324Z 2020-01-01 12:04:59.628 DEBUG [ftgo-order-service,54be3e63d6a425a4,54be3e63d6a425a4,true] 1 --- [nio-8080-exec-3] org.hibernate.SQL : select lineitems0.order_id as order_id1_00, lineitems0_.menu_itemid as menu ite2_00, lineitems0_.name as name3_00, lineitems0_.price as price4_00, lineitems0_.quantity as quantity5_00 from order_lineitems lineitems0 where lineitems0_.order_id=? ftgo-order-service1 | 2020-01-01T12:05:00.183281251Z 2020-01-01 12:05:00.182 DEBUG [ftgo-order-service,3ba63af480d6c110,3ba63af480d6c110,true] 1 --- [nio-8080-exec-4] org.hibernate.SQL : select order0.id as id1_30, order0_.consumer_id as consumer2_30, order0_.ci ty as city3_30, order0_.delivery_state as delivery4_30, order0_.street1 as street5_30, order0_.street2 as street6_30, order0_.zip as zip7_30, order0_.delivery_time as delivery8_30, order0_.amount as amount9_30, order0_.payment_token as payment10_30, order0_.restaurant_id as restaur11 _30, order0_.state as state12_30, order0_.version as version13_30 from orders order0 where order0.id=? ftgo-order-service1 | 2020-01-01T12:05:00.187106458Z 2020-01-01 12:05:00.186 DEBUG [ftgo-order-service,3ba63af480d6c110,3ba63af480d6c110,true] 1 --- [nio-8080-exec-4] org.hibernate.SQL : select lineitems0.order_id as order_id1_00, lineitems0_.menu_itemid as menu ite2_00, lineitems0_.name as name3_00, lineitems0_.price as price4_00, lineitems0_.quantity as quantity5_00 from order_lineitems lineitems0 where lineitems0_.order_id=? ftgo-order-service1 | 2020-01-01T12:05:00.738243051Z 2020-01-01 12:05:00.737 DEBUG [ftgo-order-service,c979273db4ab0e33,c979273db4ab0e33,true] 1 --- [nio-8080-exec-5] org.hibernate.SQL : select order0.id as id1_30, order0_.consumer_id as consumer2_30, order0_.ci ty as city3_30, order0_.delivery_state as delivery4_30, order0_.street1 as street5_30, order0_.street2 as street6_30, order0_.zip as zip7_30, order0_.delivery_time as delivery8_30, order0_.amount as amount9_30, order0_.payment_token as payment10_30, order0_.restaurant_id as restaur11 _30, order0_.state as state12_30, order0_.version as version13_30 from orders order0 where order0.id=? ftgo-order-service1 | 2020-01-01T12:05:00.741265195Z 2020-01-01 12:05:00.740 DEBUG [ftgo-order-service,c979273db4ab0e33,c979273db4ab0e33,true] 1 --- [nio-8080-exec-5] org.hibernate.SQL : select lineitems0.order_id as order_id1_00, lineitems0_.menu_itemid as menu ite2_00, lineitems0_.name as name3_00, lineitems0_.price as price4_00, lineitems0_.quantity as quantity5_00 from order_lineitems lineitems0 where lineitems0_.order_id=? ftgo-order-service1 | 2020-01-01T12:05:01.291515081Z 2020-01-01 12:05:01.291 DEBUG [ftgo-order-service,f818d5b42c75724f,f818d5b42c75724f,true] 1 --- [nio-8080-exec-7] org.hibernate.SQL : select order0.id as id1_30, order0_.consumer_id as consumer2_30, order0_.ci ty as city3_30, order0_.delivery_state as delivery4_30, order0_.street1 as street5_30, order0_.street2 as street6_30, order0_.zip as zip7_30, order0_.delivery_time as delivery8_30, order0_.amount as amount9_30, order0_.payment_token as payment10_30, order0_.restaurant_id as restaur11 _30, order0_.state as state12_30, order0_.version as version13_30 from orders order0 where order0.id=? ftgo-order-service1 | 2020-01-01T12:05:01.298236971Z 2020-01-01 12:05:01.297 DEBUG [ftgo-order-service,f818d5b42c75724f,f818d5b42c75724f,true] 1 --- [nio-8080-exec-7] org.hibernate.SQL : select lineitems0.order_id as order_id1_00, lineitems0_.menu_itemid as menu ite2_00, lineitems0_.name as name3_00, lineitems0_.price as price4_00, lineitems0_.quantity as quantity5_00 from order_lineitems lineitems0 where lineitems0_.order_id=? ftgo-order-service1 | 2020-01-01T12:05:01.848311601Z 2020-01-01 12:05:01.847 DEBUG [ftgo-order-service,530a27b5c0bff4cb,530a27b5c0bff4cb,true] 1 --- [nio-8080-exec-8] org.hibernate.SQL : select order0.id as id1_30, order0_.consumer_id as consumer2_30, order0_.ci ty as city3_30, order0_.delivery_state as delivery4_30, order0_.street1 as street5_30, order0_.street2 as street6_30, order0_.zip as zip7_30, order0_.delivery_time as delivery8_30, order0_.amount as amount9_30, order0_.payment_token as payment10_30, order0_.restaurant_id as restaur11 _30, order0_.state as state12_30, order0_.version as version13_30 from orders order0 where order0.id=? ftgo-order-service1 | 2020-01-01T12:05:01.853157062Z 2020-01-01 12:05:01.852 DEBUG [ftgo-order-service,530a27b5c0bff4cb,530a27b5c0bff4cb,true] 1 --- [nio-8080-exec-8] org.hibernate.SQL : select lineitems0.order_id as order_id1_00, lineitems0_.menu_itemid as menu ite2_00, lineitems0_.name as name3_00, lineitems0_.price as price4_00, lineitems0_.quantity as quantity5_00 from order_lineitems lineitems0 where lineitems0_.order_id=? ftgo-order-service1 | 2020-01-01T12:05:02.406786376Z 2020-01-01 12:05:02.406 DEBUG [ftgo-order-service,acec85128513a62d,acec85128513a62d,true] 1 --- [nio-8080-exec-9] org.hibernate.SQL : select order0.id as id1_30, order0_.consumer_id as consumer2_30, order0_.ci ty as city3_30, order0_.delivery_state as delivery4_30, order0_.street1 as street5_30, order0_.street2 as street6_30, order0_.zip as zip7_30, order0_.delivery_time as delivery8_30, order0_.amount as amount9_30, order0_.payment_token as payment10_30, order0_.restaurant_id as restaur11 _30, order0_.state as state12_30, order0_.version as version13_30 from orders order0 where order0.id=? ftgo-order-service1 | 2020-01-01T12:05:02.420603088Z 2020-01-01 12:05:02.414 DEBUG [ftgo-order-service,acec85128513a62d,acec85128513a62d,true] 1 --- [nio-8080-exec-9] org.hibernate.SQL : select lineitems0.order_id as order_id1_00, lineitems0_.menu_itemid as menu ite2_00, lineitems0_.name as name3_00, lineitems0_.price as price4_00, lineitems0_.quantity as quantity5_00 from order_lineitems lineitems0 where lineitems0_.order_id=? ftgo-order-service1 | 2020-01-01T12:05:02.982098226Z 2020-01-01 12:05:02.981 DEBUG [ftgo-order-service,594de5f3edf3c0f5,594de5f3edf3c0f5,true] 1 --- [nio-8080-exec-1] org.hibernate.SQL : select order0.id as id1_30, order0_.consumer_id as consumer2_30, order0_.ci ty as city3_30, order0_.delivery_state as delivery4_30, order0_.street1 as street5_30, order0_.street2 as street6_30, order0_.zip as zip7_30, order0_.delivery_time as delivery8_30, order0_.amount as amount9_30, order0_.payment_token as payment10_30, order0_.restaurant_id as restaur11 _30, order0_.state as state12_30, order0_.version as version13_30 from orders order0 where order0.id=? ftgo-order-service1 | 2020-01-01T12:05:03.006009860Z 2020-01-01 12:05:03.005 DEBUG [ftgo-order-service,594de5f3edf3c0f5,594de5f3edf3c0f5,true] 1 --- [nio-8080-exec-1] org.hibernate.SQL : select lineitems0.order_id as order_id1_00, lineitems0_.menu_itemid as menu ite2_00, lineitems0_.name as name3_00, lineitems0_.price as price4_00, lineitems0_.quantity as quantity5_00 from order_lineitems lineitems0 where lineitems0_.order_id=? ftgo-order-service1 | 2020-01-01T12:05:03.582746767Z 2020-01-01 12:05:03.579 DEBUG [ftgo-order-service,5337713a17ce6121,5337713a17ce6121,true] 1 --- [nio-8080-exec-2] org.hibernate.SQL : select order0.id as id1_30, order0_.consumer_id as consumer2_30, order0_.ci ty as city3_30, order0_.delivery_state as delivery4_30, order0_.street1 as street5_30, order0_.street2 as street6_30, order0_.zip as zip7_30, order0_.delivery_time as delivery8_30, order0_.amount as amount9_30, order0_.payment_token as payment10_30, order0_.restaurant_id as restaur11 _30, order0_.state as state12_30, order0_.version as version13_30 from orders order0 where order0.id=? ftgo-order-service1 | 2020-01-01T12:05:03.584168383Z 2020-01-01 12:05:03.583 DEBUG [ftgo-order-service,5337713a17ce6121,5337713a17ce6121,true] 1 --- [nio-8080-exec-2] org.hibernate.SQL : select lineitems0.order_id as order_id1_00, lineitems0_.menu_itemid as menu ite2_00, lineitems0_.name as name3_00, lineitems0_.price as price4_00, lineitems0_.quantity as quantity5_00 from order_lineitems lineitems0 where lineitems0_.order_id=? ftgo-order-service1 | 2020-01-01T12:05:04.146516055Z 2020-01-01 12:05:04.146 DEBUG [ftgo-order-service,75a5abba7723773e,75a5abba7723773e,true] 1 --- [nio-8080-exec-3] org.hibernate.SQL : select order0.id as id1_30, order0_.consumer_id as consumer2_30, order0_.ci ty as city3_30, order0_.delivery_state as delivery4_30, order0_.street1 as street5_30, order0_.street2 as street6_30, order0_.zip as zip7_30, order0_.delivery_time as delivery8_30, order0_.amount as amount9_30, order0_.payment_token as payment10_30, order0_.restaurant_id as restaur11 _30, order0_.state as state12_30, order0_.version as version13_30 from orders order0 where order0.id=? ftgo-order-service1 | 2020-01-01T12:05:04.157335754Z 2020-01-01 12:05:04.156 DEBUG [ftgo-order-service,75a5abba7723773e,75a5abba7723773e,true] 1 --- [nio-8080-exec-3] org.hibernate.SQL : select lineitems0.order_id as order_id1_00, lineitems0_.menu_itemid as menu ite2_00, lineitems0_.name as name3_00, lineitems0_.price as price4_00, lineitems0_.quantity as quantity5_00 from order_lineitems lineitems0 where lineitems0_.order_id=? ftgo-order-service1 | 2020-01-01T12:05:04.710929917Z 2020-01-01 12:05:04.710 DEBUG [ftgo-order-service,38789eb02f8bbcc3,38789eb02f8bbcc3,true] 1 --- [nio-8080-exec-4] org.hibernate.SQL : select order0.id as id1_30, order0_.consumer_id as consumer2_30, order0_.ci ty as city3_30, order0_.delivery_state as delivery4_30, order0_.street1 as street5_30, order0_.street2 as street6_30, order0_.zip as zip7_30, order0_.delivery_time as delivery8_30, order0_.amount as amount9_30, order0_.payment_token as payment10_30, order0_.restaurant_id as restaur11 _30, order0_.state as state12_30, order0_.version as version13_30 from orders order0 where order0.id=? ftgo-order-service1 | 2020-01-01T12:05:04.714133632Z 2020-01-01 12:05:04.713 DEBUG [ftgo-order-service,38789eb02f8bbcc3,38789eb02f8bbcc3,true] 1 --- [nio-8080-exec-4] org.hibernate.SQL : select lineitems0.order_id as order_id1_00, lineitems0_.menu_itemid as menu ite2_00, lineitems0_.name as name3_00, lineitems0_.price as price4_00, lineitems0_.quantity as quantity5_00 from order_lineitems lineitems0 where lineitems0_.order_id=? ftgo-order-service1 | 2020-01-01T12:05:05.268694042Z 2020-01-01 12:05:05.268 DEBUG [ftgo-order-service,63547732328eb93a,63547732328eb93a,true] 1 --- [nio-8080-exec-5] org.hibernate.SQL : select order0.id as id1_30, order0_.consumer_id as consumer2_30, order0_.ci ty as city3_30, order0_.delivery_state as delivery4_30, order0_.street1 as street5_30, order0_.street2 as street6_30, order0_.zip as zip7_30, order0_.delivery_time as delivery8_30, order0_.amount as amount9_30, order0_.payment_token as payment10_30, order0_.restaurant_id as restaur11 _30, order0_.state as state12_30, order0_.version as version13_30 from orders order0 where order0.id=? ftgo-order-service1 | 2020-01-01T12:05:05.271916844Z 2020-01-01 12:05:05.271 DEBUG [ftgo-order-service,63547732328eb93a,63547732328eb93a,true] 1 --- [nio-8080-exec-5] org.hibernate.SQL : select lineitems0.order_id as order_id1_00, lineitems0_.menu_itemid as menu ite2_00, lineitems0_.name as name3_00, lineitems0_.price as price4_00, lineitems0_.quantity as quantity5_00 from order_lineitems lineitems0 where lineitems0_.order_id=? ftgo-order-service1 | 2020-01-01T12:05:05.825961587Z 2020-01-01 12:05:05.824 DEBUG [ftgo-order-service,b246537e28b2a514,b246537e28b2a514,true] 1 --- [nio-8080-exec-7] org.hibernate.SQL : select order0.id as id1_30, order0_.consumer_id as consumer2_30, order0_.ci ty as city3_30, order0_.delivery_state as delivery4_30, order0_.street1 as street5_30, order0_.street2 as street6_30, order0_.zip as zip7_30, order0_.delivery_time as delivery8_30, order0_.amount as amount9_30, order0_.payment_token as payment10_30, order0_.restaurant_id as restaur11 _30, order0_.state as state12_30, order0_.version as version13_30 from orders order0 where order0.id=? ftgo-order-service1 | 2020-01-01T12:05:05.832252167Z 2020-01-01 12:05:05.831 DEBUG [ftgo-order-service,b246537e28b2a514,b246537e28b2a514,true] 1 --- [nio-8080-exec-7] org.hibernate.SQL : select lineitems0.order_id as order_id1_00, lineitems0_.menu_itemid as menu ite2_00, lineitems0_.name as name3_00, lineitems0_.price as price4_00, lineitems0_.quantity as quantity5_00 from order_lineitems lineitems0 where lineitems0_.order_id=? ftgo-order-service1 | 2020-01-01T12:05:06.389655486Z 2020-01-01 12:05:06.389 DEBUG [ftgo-order-service,e45dac08b60f7f22,e45dac08b60f7f22,true] 1 --- [nio-8080-exec-8] org.hibernate.SQL : select order0.id as id1_30, order0_.consumer_id as consumer2_30, order0_.ci ty as city3_30, order0_.delivery_state as delivery4_30, order0_.street1 as street5_30, order0_.street2 as street6_30, order0_.zip as zip7_30, order0_.delivery_time as delivery8_30, order0_.amount as amount9_30, order0_.payment_token as payment10_30, order0_.restaurant_id as restaur11 _30, order0_.state as state12_30, order0_.version as version13_30 from orders order0 where order0.id=? ftgo-order-service1 | 2020-01-01T12:05:06.394123878Z 2020-01-01 12:05:06.393 DEBUG [ftgo-order-service,e45dac08b60f7f22,e45dac08b60f7f22,true] 1 --- [nio-8080-exec-8] org.hibernate.SQL : select lineitems0.order_id as order_id1_00, lineitems0_.menu_itemid as menu ite2_00, lineitems0_.name as name3_00, lineitems0_.price as price4_00, lineitems0_.quantity as quantity5_00 from order_lineitems lineitems0 where lineitems0_.order_id=? ftgo-order-service1 | 2020-01-01T12:05:06.945935748Z 2020-01-01 12:05:06.945 DEBUG [ftgo-order-service,d7ddcfb682042a5c,d7ddcfb682042a5c,true] 1 --- [nio-8080-exec-6] org.hibernate.SQL : select order0.id as id1_30, order0_.consumer_id as consumer2_30, order0_.ci ty as city3_30, order0_.delivery_state as delivery4_30, order0_.street1 as street5_30, order0_.street2 as street6_30, order0_.zip as zip7_30, order0_.delivery_time as delivery8_30, order0_.amount as amount9_30, order0_.payment_token as payment10_30, order0_.restaurant_id as restaur11 _30, order0_.state as state12_30, order0_.version as version13_30 from orders order0 where order0.id=? ftgo-order-service1 | 2020-01-01T12:05:06.950652771Z 2020-01-01 12:05:06.950 DEBUG [ftgo-order-service,d7ddcfb682042a5c,d7ddcfb682042a5c,true] 1 --- [nio-8080-exec-6] org.hibernate.SQL : select lineitems0.order_id as order_id1_00, lineitems0_.menu_itemid as menu ite2_00, lineitems0_.name as name3_00, lineitems0_.price as price4_00, lineitems0_.quantity as quantity5_00 from order_lineitems lineitems0 where lineitems0_.order_id=? ftgo-order-service1 | 2020-01-01T12:05:07.502600847Z 2020-01-01 12:05:07.502 DEBUG [ftgo-order-service,ce5c2f92ae7a51e9,ce5c2f92ae7a51e9,true] 1 --- [io-8080-exec-10] org.hibernate.SQL : select order0.id as id1_30, order0_.consumer_id as consumer2_30, order0_.ci ty as city3_30, order0_.delivery_state as delivery4_30, order0_.street1 as street5_30, order0_.street2 as street6_30, order0_.zip as zip7_30, order0_.delivery_time as delivery8_30, order0_.amount as amount9_30, order0_.payment_token as payment10_30, order0_.restaurant_id as restaur11 _30, order0_.state as state12_30, order0_.version as version13_30 from orders order0 where order0.id=? ftgo-order-service1 | 2020-01-01T12:05:07.506023587Z 2020-01-01 12:05:07.505 DEBUG [ftgo-order-service,ce5c2f92ae7a51e9,ce5c2f92ae7a51e9,true] 1 --- [io-8080-exec-10] org.hibernate.SQL : select lineitems0.order_id as order_id1_00, lineitems0_.menu_itemid as menu ite2_00, lineitems0_.name as name3_00, lineitems0_.price as price4_00, lineitems0_.quantity as quantity5_00 from order_lineitems lineitems0 where lineitems0_.order_id=? ftgo-order-service1 | 2020-01-01T12:05:08.052133045Z 2020-01-01 12:05:08.051 DEBUG [ftgo-order-service,0279cd7db2c17c80,0279cd7db2c17c80,true] 1 --- [nio-8080-exec-1] org.hibernate.SQL : select order0.id as id1_30, order0_.consumer_id as consumer2_30, order0_.ci ty as city3_30, order0_.delivery_state as delivery4_30, order0_.street1 as street5_30, order0_.street2 as street6_30, order0_.zip as zip7_30, order0_.delivery_time as delivery8_30, order0_.amount as amount9_30, order0_.payment_token as payment10_30, order0_.restaurant_id as restaur11 _30, order0_.state as state12_30, order0_.version as version13_30 from orders order0 where order0.id=? ftgo-order-service1 | 2020-01-01T12:05:08.055248772Z 2020-01-01 12:05:08.054 DEBUG [ftgo-order-service,0279cd7db2c17c80,0279cd7db2c17c80,true] 1 --- [nio-8080-exec-1] org.hibernate.SQL : select lineitems0.order_id as order_id1_00, lineitems0_.menu_itemid as menu ite2_00, lineitems0_.name as name3_00, lineitems0_.price as price4_00, lineitems0_.quantity as quantity5_00 from order_lineitems lineitems0 where lineitems0_.order_id=?