[PID:23384:054 2018.09.14 08:04:55.777 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30093916 [PID:23384:017 2018.09.14 08:04:55.784 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:025 2018.09.14 08:04:56.782 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30093937 [PID:23384:017 2018.09.14 08:04:56.788 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:050 2018.09.14 08:04:57.789 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30093985 [PID:23384:017 2018.09.14 08:04:57.795 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:036 2018.09.14 08:04:58.790 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30094011 [PID:23384:017 2018.09.14 08:04:58.797 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:067 2018.09.14 08:04:59.795 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30094021 [PID:23384:017 2018.09.14 08:04:59.805 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:057 2018.09.14 08:05:00.800 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30094044 [PID:23384:017 2018.09.14 08:05:00.811 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:029 2018.09.14 08:05:01.805 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30094059 [PID:23384:017 2018.09.14 08:05:01.813 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:048 2018.09.14 08:05:03.814 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30094070 [PID:23384:017 2018.09.14 08:05:03.818 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:065 2018.09.14 08:05:06.832 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30094105 [PID:23384:017 2018.09.14 08:05:06.839 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:054 2018.09.14 08:05:07.841 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30094122 [PID:23384:025 2018.09.14 08:05:08.279 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #1]: ReadStreamEventsBackward - 1108ms. Q: 0/12. [PID:23384:017 2018.09.14 08:05:08.294 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:023 2018.09.14 08:05:08.810 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #1]: ReadStreamEventsBackward - 505ms. Q: 0/24. [PID:23384:028 2018.09.14 08:05:09.530 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #3]: ReadStreamEventsBackward - 392ms. Q: 0/9. [PID:23384:009 2018.09.14 08:05:09.850 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30094148 [PID:23384:017 2018.09.14 08:05:09.856 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:029 2018.09.14 08:05:10.109 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #3]: ReadStreamEventsBackward - 534ms. Q: 0/20. [PID:23384:009 2018.09.14 08:05:10.856 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30094174 [PID:23384:017 2018.09.14 08:05:10.862 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:027 2018.09.14 08:05:11.510 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #3]: ReadStreamEventsBackward - 1200ms. Q: 0/9. [PID:23384:059 2018.09.14 08:05:11.863 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30094185 [PID:23384:017 2018.09.14 08:05:11.906 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:067 2018.09.14 08:05:13.882 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #1]: ReadStreamEventsBackward - 1892ms. Q: 1/15. [PID:23384:056 2018.09.14 08:05:14.708 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #4]: ReadStreamEventsBackward - 734ms. Q: 0/44. [PID:23384:045 2018.09.14 08:05:14.827 TRACE CoreProjectionCheckp] Writing checkpoint for BySession at $ce-Message: 30094330 with expected version number 7523 [PID:23384:045 2018.09.14 08:05:14.837 TRACE CoreProjectionCheckp] Checkpoint has been written for projection BySession at sequence number 7524 (current) [PID:23384:007 2018.09.14 08:05:14.877 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30094199 [PID:23384:017 2018.09.14 08:05:14.883 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:056 2018.09.14 08:05:16.492 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #4]: ReadStreamEventsBackward - 1572ms. Q: 21/30. [PID:23384:033 2018.09.14 08:05:17.302 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #3]: ReadStreamEventsBackward - 805ms. Q: 0/18. [PID:23384:032 2018.09.14 08:05:17.890 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30094228 [PID:23384:035 2018.09.14 08:05:18.083 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #1]: ReadStreamEventsBackward - 399ms. Q: 0/10. [PID:23384:017 2018.09.14 08:05:18.098 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:064 2018.09.14 08:05:18.504 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #4]: ReadStreamEventsBackward - 414ms. Q: 0/11. [PID:23384:036 2018.09.14 08:05:18.898 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30094238 [PID:23384:017 2018.09.14 08:05:18.905 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:064 2018.09.14 08:05:19.901 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #4]: ReadStreamEventsBackward - 1372ms. Q: 5/15. [PID:23384:066 2018.09.14 08:05:21.259 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #1]: ReadStreamEventsBackward - 1324ms. Q: 1/25. [PID:23384:056 2018.09.14 08:05:21.866 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #4]: ReadStreamEventsBackward - 480ms. Q: 0/21. [PID:23384:055 2018.09.14 08:05:21.914 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30094259 [PID:23384:017 2018.09.14 08:05:21.925 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:037 2018.09.14 08:05:23.471 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #4]: ReadStreamEventsBackward - 1488ms. Q: 0/32. [PID:23384:017 2018.09.14 08:05:23.849 TRACE InMemoryBus ] SLOW BUS MSG [MainBus]: CommitReplicated - 51ms. Handler: RequestManagementService. [PID:23384:017 2018.09.14 08:05:23.849 TRACE QueuedHandlerAutoRes] SLOW QUEUE MSG [MainQueue]: CommitReplicated - 52ms. Q: 5/7. [PID:23384:044 2018.09.14 08:05:23.849 TRACE InMemoryBus ] SLOW BUS MSG [bus]: WriteEventsCompleted - 51ms. Handler: RequestResponseDispatcher`2. [PID:23384:044 2018.09.14 08:05:23.849 TRACE QueuedHandlerAutoRes] SLOW QUEUE MSG [Projection Core #1]: WriteEventsCompleted - 52ms. Q: 1/6. ... [PID:23384:006 2018.09.14 08:05:38.018 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30094407 [PID:23384:017 2018.09.14 08:05:38.038 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:059 2018.09.14 08:05:38.894 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #2]: ReadStreamEventsBackward - 743ms. Q: 0/10. [PID:23384:025 2018.09.14 08:05:39.020 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30094432 [PID:23384:022 2018.09.14 08:05:41.202 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #1]: ReadStreamEventsBackward - 2301ms. Q: 0/16. [PID:23384:044 2018.09.14 08:05:41.595 INFO CoreProjectionCheckp] Failed to write events to stream $ce-Tracking. Error: CommitTimeout [PID:23384:043 2018.09.14 08:05:41.595 INFO CoreProjectionCheckp] Failed to write events to stream Date-20180914-1005. Error: CommitTimeout [PID:23384:044 2018.09.14 08:05:41.595 INFO CoreProjectionCheckp] Failed to write events to stream $ce-Message. Error: CommitTimeout [PID:23384:017 2018.09.14 08:05:41.595 DEBUG PersistentSubscripti] Error writing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: CommitTimeout [PID:23384:060 2018.09.14 08:05:42.031 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30094442 [PID:23384:008 2018.09.14 08:05:42.603 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #1]: ReadStreamEventsBackward - 1360ms. Q: 0/16. [PID:23384:017 2018.09.14 08:05:42.631 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:044 2018.09.14 08:05:42.646 TRACE CoreProjectionCheckp] Writing checkpoint for $by_category at C:153350165517/P:153350165517 with expected version number 25453 [PID:23384:044 2018.09.14 08:05:42.702 TRACE CoreProjectionCheckp] Checkpoint has been written for projection $by_category at sequence number 25454 (current) [PID:23384:043 2018.09.14 08:05:42.850 TRACE InMemoryBus ] SLOW BUS MSG [bus]: UnwrapEnvelopeMessage - 54ms. Handler: UnwrapEnvelopeHandler. [PID:23384:043 2018.09.14 08:05:42.850 TRACE QueuedHandlerAutoRes] SLOW QUEUE MSG [Projection Core #0]: UnwrapEnvelopeMessage - 55ms. Q: 0/172. [PID:23384:055 2018.09.14 08:05:43.969 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #3]: ReadStreamEventsBackward - 819ms. Q: 0/10. [PID:23384:035 2018.09.14 08:05:44.038 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30094459 [PID:23384:017 2018.09.14 08:05:44.047 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:065 2018.09.14 08:05:44.256 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #2]: ReadStreamEventsBackward - 282ms. Q: 0/5. [PID:23384:009 2018.09.14 08:05:44.801 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #4]: ReadStreamEventsBackward - 245ms. Q: 0/15. [PID:23384:034 2018.09.14 08:05:45.040 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30094491 [PID:23384:013 2018.09.14 08:05:45.737 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #4]: ReadStreamEventsBackward - 203ms. Q: 0/0. [PID:23384:033 2018.09.14 08:05:46.016 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #1]: ReadStreamEventsBackward - 1020ms. Q: 0/12. [PID:23384:017 2018.09.14 08:05:46.034 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:032 2018.09.14 08:05:46.531 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #2]: ReadStreamEventsBackward - 510ms. Q: 0/6. [PID:23384:059 2018.09.14 08:05:47.043 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30094508 [PID:23384:017 2018.09.14 08:05:47.050 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:062 2018.09.14 08:05:47.274 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #3]: ReadStreamEventsBackward - 412ms. Q: 0/8. [PID:23384:021 2018.09.14 08:05:48.868 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #1]: ReadStreamEventsBackward - 1586ms. Q: 0/13. [PID:23384:045 2018.09.14 08:05:48.986 TRACE InMemoryBus ] SLOW BUS MSG [bus]: UnwrapEnvelopeMessage - 76ms. Handler: UnwrapEnvelopeHandler. [PID:23384:045 2018.09.14 08:05:48.986 TRACE QueuedHandlerAutoRes] SLOW QUEUE MSG [Projection Core #2]: UnwrapEnvelopeMessage - 76ms. Q: 0/7. [PID:23384:068 2018.09.14 08:05:49.057 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30094519 [PID:23384:017 2018.09.14 08:05:49.124 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:022 2018.09.14 08:05:49.918 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #2]: ReadStreamEventsBackward - 834ms. Q: 0/43. [PID:23384:046 2018.09.14 08:05:51.095 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30094533 [PID:23384:017 2018.09.14 08:05:51.103 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:008 2018.09.14 08:05:52.459 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #4]: ReadStreamEventsBackward - 2158ms. Q: 0/26. [PID:23384:045 2018.09.14 08:05:52.670 INFO CoreProjectionCheckp] Failed to write events to stream PeerA-XXXX. Error: CommitTimeout [PID:23384:044 2018.09.14 08:05:52.670 INFO CoreProjectionCheckp] Failed to write events to stream $ce-MessageTracking. Error: CommitTimeout [PID:23384:044 2018.09.14 08:05:52.670 INFO CoreProjectionCheckp] Failed to write events to stream $$MessageTracking-XXXX. Error: CommitTimeout [PID:23384:044 2018.09.14 08:05:52.670 INFO CoreProjectionCheckp] Failed to write events to stream $ce-PeerB. Error: CommitTimeout [PID:23384:044 2018.09.14 08:05:52.671 INFO CoreProjectionCheckp] Failed to write events to stream $ce-PeerC. Error: CommitTimeout [PID:23384:052 2018.09.14 08:05:53.111 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30094546 [PID:23384:017 2018.09.14 08:05:53.118 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:032 2018.09.14 08:05:53.919 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #2]: ReadStreamEventsBackward - 1365ms. Q: 0/32. [PID:23384:061 2018.09.14 08:05:55.148 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30094592 [PID:23384:017 2018.09.14 08:05:55.156 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:063 2018.09.14 08:05:56.378 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #3]: ReadStreamEventsBackward - 1410ms. Q: 0/17. [PID:23384:044 2018.09.14 08:05:56.636 TRACE InMemoryBus ] SLOW BUS MSG [bus]: ReadStreamEventsBackwardCompleted - 54ms. Handler: RequestResponseDispatcher`2. [PID:23384:044 2018.09.14 08:05:56.636 TRACE QueuedHandlerAutoRes] SLOW QUEUE MSG [Projection Core #1]: ReadStreamEventsBackwardCompleted - 54ms. Q: 0/4. [PID:23384:045 2018.09.14 08:05:56.636 TRACE InMemoryBus ] SLOW BUS MSG [bus]: UnwrapEnvelopeMessage - 55ms. Handler: UnwrapEnvelopeHandler. [PID:23384:045 2018.09.14 08:05:56.637 TRACE QueuedHandlerAutoRes] SLOW QUEUE MSG [Projection Core #2]: UnwrapEnvelopeMessage - 56ms. Q: 0/47. ... [PID:23384:062 2018.09.14 08:06:38.629 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #4]: ReadStreamEventsBackward - 304ms. Q: 0/9. [PID:23384:044 2018.09.14 08:06:38.920 INFO CoreProjectionCheckp] Failed to write events to stream $ce-Message. Error: CommitTimeout [PID:23384:057 2018.09.14 08:06:39.356 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30095114 [PID:23384:017 2018.09.14 08:06:39.363 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:009 2018.09.14 08:06:40.139 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #4]: ReadStreamEventsBackward - 1309ms. Q: 0/11. [PID:23384:043 2018.09.14 08:06:40.329 TRACE InMemoryBus ] SLOW BUS MSG [bus]: UnwrapEnvelopeMessage - 52ms. Handler: UnwrapEnvelopeHandler. [PID:23384:043 2018.09.14 08:06:40.329 TRACE QueuedHandlerAutoRes] SLOW QUEUE MSG [Projection Core #0]: UnwrapEnvelopeMessage - 53ms. Q: 0/153. [PID:23384:028 2018.09.14 08:06:41.359 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30095131 [PID:23384:017 2018.09.14 08:06:41.366 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:068 2018.09.14 08:06:41.457 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #3]: ReadStreamEventsBackward - 603ms. Q: 0/16. [PID:23384:045 2018.09.14 08:06:41.610 TRACE InMemoryBus ] SLOW BUS MSG [bus]: WriteEventsCompleted - 61ms. Handler: RequestResponseDispatcher`2. [PID:23384:045 2018.09.14 08:06:41.610 TRACE QueuedHandlerAutoRes] SLOW QUEUE MSG [Projection Core #2]: WriteEventsCompleted - 61ms. Q: 0/1. [PID:23384:014 2018.09.14 08:06:41.610 TRACE InMemoryBus ] SLOW BUS MSG [PersistentSubscriptionsBus]: EventCommitted - 61ms. Handler: PersistentSubscriptionService. [PID:23384:022 2018.09.14 08:06:41.610 TRACE InMemoryBus ] SLOW BUS MSG [SubscriptionsBus]: EventCommitted - 60ms. Handler: SubscriptionsService. [PID:23384:044 2018.09.14 08:06:41.842 TRACE CoreProjectionCheckp] Writing checkpoint for Stats at $ce-Message: 30096304; $ce-Session: 1701370; $ce-Queue: 11917; with expected version number 8100 [PID:23384:044 2018.09.14 08:06:41.849 TRACE CoreProjectionCheckp] Checkpoint has been written for projection Stats at sequence number 8101 (current) [PID:23384:014 2018.09.14 08:06:42.364 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30095182 [PID:23384:017 2018.09.14 08:06:42.398 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:050 2018.09.14 08:06:42.626 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #2]: ReadStreamEventsBackward - 591ms. Q: 0/9. [PID:23384:022 2018.09.14 08:06:43.439 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #3]: ReadStreamEventsBackward - 807ms. Q: 0/9. [PID:23384:037 2018.09.14 08:06:44.499 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #4]: ReadStreamEventsBackward - 1055ms. Q: 1/44. [PID:23384:050 2018.09.14 08:06:45.277 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #1]: ReadStreamEventsBackward - 214ms. Q: 5/14. [PID:23384:068 2018.09.14 08:06:45.377 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30095217 [PID:23384:017 2018.09.14 08:06:45.384 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint ... [PID:23384:048 2018.09.14 08:07:13.905 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #2]: ReadStreamEventsBackward - 349ms. Q: 0/10. [PID:23384:055 2018.09.14 08:07:15.431 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #4]: ReadStreamEventsBackward - 1511ms. Q: 0/15. [PID:23384:064 2018.09.14 08:07:17.859 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #1]: ReadStreamEventsBackward - 2422ms. Q: 0/21. [PID:23384:045 2018.09.14 08:07:18.333 INFO CoreProjectionCheckp] Failed to write events to stream PeerA-XXXX. Error: CommitTimeout [PID:23384:044 2018.09.14 08:07:18.333 INFO CoreProjectionCheckp] Failed to write events to stream $ce-PeerB. Error: CommitTimeout [PID:23384:044 2018.09.14 08:07:18.333 INFO CoreProjectionCheckp] Failed to write events to stream $ce-Message. Error: CommitTimeout [PID:23384:044 2018.09.14 08:07:18.333 INFO CoreProjectionCheckp] Failed to write events to stream $ce-Tracking. Error: CommitTimeout [PID:23384:031 2018.09.14 08:07:18.586 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30095693 [PID:23384:049 2018.09.14 08:07:19.400 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #1]: ReadStreamEventsBackward - 1254ms. Q: 0/15. [PID:23384:017 2018.09.14 08:07:19.418 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint ... [PID:23384:007 2018.09.14 08:07:36.073 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #1]: ReadStreamEventsBackward - 1281ms. Q: 2/13. [PID:23384:017 2018.09.14 08:07:36.088 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:033 2018.09.14 08:07:36.613 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #1]: ReadStreamEventsBackward - 226ms. Q: 0/8. [PID:23384:051 2018.09.14 08:07:36.723 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30095818 [PID:23384:033 2018.09.14 08:07:38.248 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #1]: ReadStreamEventsBackward - 1625ms. Q: 2/10. [PID:23384:017 2018.09.14 08:07:38.263 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:033 2018.09.14 08:07:40.352 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #1]: ReadStreamEventsBackward - 2096ms. Q: 0/16. [PID:23384:045 2018.09.14 08:07:40.475 INFO CoreProjectionCheckp] Failed to write events to stream PeerA-XXXX. Error: CommitTimeout [PID:23384:044 2018.09.14 08:07:40.475 INFO CoreProjectionCheckp] Failed to write events to stream $ce-Message. Error: CommitTimeout [PID:23384:031 2018.09.14 08:07:42.419 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #4]: ReadStreamEventsBackward - 2057ms. Q: 0/18. [PID:23384:044 2018.09.14 08:07:42.487 INFO CoreProjectionCheckp] Failed to write events to stream $ce-PeerB. Error: CommitTimeout [PID:23384:044 2018.09.14 08:07:42.488 INFO CoreProjectionCheckp] Failed to write events to stream $$Tracking-XXXX. Error: CommitTimeout [PID:23384:044 2018.09.14 08:07:42.488 INFO CoreProjectionCheckp] Failed to write events to stream MessageTracking-XXXX. Error: CommitTimeout [PID:23384:044 2018.09.14 08:07:42.488 INFO CoreProjectionCheckp] Failed to write events to stream $ce-MessageTracking. Error: CommitTimeout [PID:23384:045 2018.09.14 08:07:42.490 INFO CoreProjectionCheckp] Failed to write events to stream PeerC-XXXX. Error: CommitTimeout [PID:23384:056 2018.09.14 08:07:42.941 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #3]: ReadStreamEventsBackward - 505ms. Q: 0/51. [PID:23384:024 2018.09.14 08:07:43.750 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30095837 [PID:23384:017 2018.09.14 08:07:43.756 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:035 2018.09.14 08:07:44.307 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #2]: ReadStreamEventsBackward - 1348ms. Q: 0/33. [PID:23384:011 2018.09.14 08:07:45.506 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #3]: ReadStreamEventsBackward - 1181ms. Q: 0/25. [PID:23384:043 2018.09.14 08:07:45.750 TRACE CoreProjectionCheckp] Writing checkpoint for ByDate at C:153357340454/P:153357340454 with expected version number 20028 ... [PID:23384:036 2018.09.14 08:08:13.614 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #2]: ReadStreamEventsBackward - 674ms. Q: 0/10. [PID:23384:067 2018.09.14 08:08:13.968 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30096153 [PID:23384:017 2018.09.14 08:08:13.973 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:028 2018.09.14 08:08:15.403 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #3]: ReadStreamEventsBackward - 1655ms. Q: 6/25. [PID:23384:026 2018.09.14 08:08:15.874 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #1]: ReadStreamEventsBackward - 453ms. Q: 0/14. [PID:23384:039 2018.09.14 08:08:17.229 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #4]: ReadStreamEventsBackward - 1345ms. Q: 0/18. [PID:23384:065 2018.09.14 08:08:19.279 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #3]: ReadStreamEventsBackward - 2035ms. Q: 0/48. [PID:23384:045 2018.09.14 08:08:19.727 INFO CoreProjectionCheckp] Failed to write events to stream PeerA-XXXX. Error: CommitTimeout [PID:23384:044 2018.09.14 08:08:19.727 INFO CoreProjectionCheckp] Failed to write events to stream $projections-Stats-order. Error: CommitTimeout [PID:23384:045 2018.09.14 08:08:19.727 INFO CoreProjectionCheckp] Failed to write events to stream PeerA-XXXX. Error: CommitTimeout [PID:23384:044 2018.09.14 08:08:19.727 INFO CoreProjectionCheckp] Failed to write events to stream $ce-Date. Error: CommitTimeout [PID:23384:045 2018.09.14 08:08:19.727 INFO CoreProjectionCheckp] Failed to write events to stream PeerB-XXXX. Error: CommitTimeout [PID:23384:044 2018.09.14 08:08:19.727 INFO CoreProjectionCheckp] Failed to write events to stream $$MessageTracking-XXXX. Error: CommitTimeout [PID:23384:045 2018.09.14 08:08:19.727 INFO CoreProjectionCheckp] Failed to write events to stream PeerB-XXXX. Error: CommitTimeout [PID:23384:044 2018.09.14 08:08:19.727 INFO CoreProjectionCheckp] Failed to write events to stream $$Tracking-XXXX. Error: CommitTimeout [PID:23384:045 2018.09.14 08:08:19.727 INFO CoreProjectionCheckp] Failed to write events to stream PeerB-XXXX. Error: CommitTimeout [PID:23384:044 2018.09.14 08:08:19.727 INFO CoreProjectionCheckp] Failed to write events to stream $$MessageTracking-XXXX. Error: CommitTimeout [PID:23384:045 2018.09.14 08:08:19.727 INFO CoreProjectionCheckp] Failed to write events to stream PeerA-XXXX. Error: CommitTimeout [PID:23384:045 2018.09.14 08:08:19.728 INFO CoreProjectionCheckp] Failed to write events to stream PeerA-XXXX. Error: CommitTimeout [PID:23384:032 2018.09.14 08:08:19.990 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30096176 [PID:23384:017 2018.09.14 08:08:19.996 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:053 2018.09.14 08:08:20.078 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #2]: ReadStreamEventsBackward - 367ms. Q: 0/12. [PID:23384:026 2018.09.14 08:08:20.996 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30096226 [PID:23384:017 2018.09.14 08:08:21.003 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:034 2018.09.14 08:08:21.718 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #2]: ReadStreamEventsBackward - 1092ms. Q: 0/15. [PID:23384:030 2018.09.14 08:08:22.451 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #4]: ReadStreamEventsBackward - 440ms. Q: 0/20. [PID:23384:023 2018.09.14 08:08:22.719 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #2]: ReadStreamEventsBackward - 261ms. Q: 0/15. ... [PID:23384:017 2018.09.14 08:08:43.161 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:053 2018.09.14 08:08:44.055 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #3]: ReadStreamEventsBackward - 770ms. Q: 0/11. [PID:23384:044 2018.09.14 08:08:46.159 INFO CoreProjectionCheckp] Failed to write events to stream $ce-Alias. Error: CommitTimeout [PID:23384:054 2018.09.14 08:08:46.224 TRACE InMemoryBus ] SLOW BUS MSG [Worker #1 Bus]: HttpSend - 589ms. Handler: HttpSendService. [PID:23384:054 2018.09.14 08:08:46.224 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [Worker #1]: HttpSend - 589ms. Q: 0/1. [PID:23384:011 2018.09.14 08:08:46.244 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #2]: ReadStreamEventsBackward - 2182ms. Q: 0/13. [PID:23384:021 2018.09.14 08:08:46.855 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #1]: ReadStreamEventsBackward - 246ms. Q: 0/11. [PID:23384:021 2018.09.14 08:08:47.094 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #1]: ReadStreamEventsBackward - 214ms. Q: 5/12. [PID:23384:021 2018.09.14 08:08:47.179 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30096520 [PID:23384:022 2018.09.14 08:08:47.587 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #1]: ReadStreamEventsBackward - 427ms. Q: 1/10. [PID:23384:017 2018.09.14 08:08:47.606 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:063 2018.09.14 08:08:48.806 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #2]: ReadStreamEventsBackward - 1141ms. Q: 8/12. [PID:23384:058 2018.09.14 08:08:49.189 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30096533 [PID:23384:017 2018.09.14 08:08:49.198 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:056 2018.09.14 08:08:49.546 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #3]: ReadStreamEventsBackward - 609ms. Q: 0/18. [PID:23384:031 2018.09.14 08:08:49.919 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #1]: ReadStreamEventsBackward - 318ms. Q: 0/12. [PID:23384:050 2018.09.14 08:08:50.198 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30096554 [PID:23384:017 2018.09.14 08:08:50.206 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:055 2018.09.14 08:08:51.258 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #2]: ReadStreamEventsBackward - 1333ms. Q: 0/14. [PID:23384:034 2018.09.14 08:08:52.380 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #1]: ReadStreamEventsBackward - 1084ms. Q: 0/13. [PID:23384:058 2018.09.14 08:08:52.967 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #4]: ReadStreamEventsBackward - 438ms. Q: 1/18. [PID:23384:037 2018.09.14 08:08:53.207 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30096575 [PID:23384:017 2018.09.14 08:08:53.215 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:031 2018.09.14 08:08:54.300 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #2]: ReadStreamEventsBackward - 1328ms. Q: 0/22. [PID:23384:011 2018.09.14 08:08:55.137 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #4]: ReadStreamEventsBackward - 754ms. Q: 0/14. [PID:23384:011 2018.09.14 08:08:56.428 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #4]: ReadStreamEventsBackward - 1179ms. Q: 17/29. [PID:23384:033 2018.09.14 08:08:56.860 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #3]: ReadStreamEventsBackward - 417ms. Q: 2/22. [PID:23384:045 2018.09.14 08:08:57.070 TRACE InMemoryBus ] SLOW BUS MSG [bus]: CoreTick - 54ms. Handler: ProjectionCoreService. [PID:23384:044 2018.09.14 08:08:57.070 TRACE InMemoryBus ] SLOW BUS MSG [bus]: CoreTick - 54ms. Handler: ProjectionCoreService. [PID:23384:045 2018.09.14 08:08:57.070 TRACE QueuedHandlerAutoRes] SLOW QUEUE MSG [Projection Core #2]: CoreTick - 55ms. Q: 3/8. [PID:23384:044 2018.09.14 08:08:57.070 TRACE QueuedHandlerAutoRes] SLOW QUEUE MSG [Projection Core #1]: CoreTick - 55ms. Q: 2/4. [PID:23384:021 2018.09.14 08:08:57.232 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30096599 [PID:23384:064 2018.09.14 08:08:58.982 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #1]: ReadStreamEventsBackward - 1969ms. Q: 4/22. [PID:23384:017 2018.09.14 08:08:59.028 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:044 2018.09.14 08:08:59.235 INFO CoreProjectionCheckp] Failed to write events to stream $$Tracking-XXXX. Error: CommitTimeout [PID:23384:045 2018.09.14 08:08:59.235 INFO CoreProjectionCheckp] Failed to write events to stream PeerB-XXXX. Error: CommitTimeout [PID:23384:044 2018.09.14 08:08:59.235 INFO CoreProjectionCheckp] Failed to write events to stream $ce-Message. Error: CommitTimeout [PID:23384:044 2018.09.14 08:08:59.235 INFO CoreProjectionCheckp] Failed to write events to stream $ce-PeerB. Error: CommitTimeout [PID:23384:044 2018.09.14 08:08:59.235 INFO CoreProjectionCheckp] Failed to write events to stream $ce-Tracking. Error: CommitTimeout [PID:23384:044 2018.09.14 08:08:59.235 INFO CoreProjectionCheckp] Failed to write events to stream $$MessageTracking-XXXX. Error: CommitTimeout [PID:23384:006 2018.09.14 08:08:59.245 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30096612 [PID:23384:017 2018.09.14 08:08:59.253 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:008 2018.09.14 08:09:00.378 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #3]: ReadStreamEventsBackward - 1220ms. Q: 0/10. [PID:23384:064 2018.09.14 08:09:01.074 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #1]: ReadStreamEventsBackward - 686ms. Q: 0/38. [PID:23384:046 2018.09.14 08:09:01.256 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30096625 [PID:23384:017 2018.09.14 08:09:01.277 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:013 2018.09.14 08:09:03.479 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #1]: ReadStreamEventsBackward - 2080ms. Q: 0/18. [PID:23384:044 2018.09.14 08:09:04.258 INFO CoreProjectionCheckp] Failed to write events to stream $ce-Tracking. Error: CommitTimeout [PID:23384:043 2018.09.14 08:09:04.258 INFO CoreProjectionCheckp] Failed to write events to stream Date-20180914-1009. Error: CommitTimeout [PID:23384:044 2018.09.14 08:09:04.258 INFO CoreProjectionCheckp] Failed to write events to stream $$MessageTracking-XXXX. Error: CommitTimeout [PID:23384:044 2018.09.14 08:09:04.258 INFO CoreProjectionCheckp] Failed to write events to stream $ce-PeerB. Error: CommitTimeout [PID:23384:044 2018.09.14 08:09:04.258 INFO CoreProjectionCheckp] Failed to write events to stream $ce-Message. Error: CommitTimeout [PID:23384:063 2018.09.14 08:09:04.279 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30096637 [PID:23384:017 2018.09.14 08:09:04.284 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:033 2018.09.14 08:09:04.623 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #4]: ReadStreamEventsBackward - 1072ms. Q: 4/11. [PID:23384:048 2018.09.14 08:09:05.283 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30096672 [PID:23384:066 2018.09.14 08:09:05.882 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #1]: ReadStreamEventsBackward - 1130ms. Q: 0/15. [PID:23384:017 2018.09.14 08:09:05.897 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:044 2018.09.14 08:09:06.234 TRACE InMemoryBus ] SLOW BUS MSG [bus]: UnwrapEnvelopeMessage - 57ms. Handler: UnwrapEnvelopeHandler. [PID:23384:044 2018.09.14 08:09:06.234 TRACE QueuedHandlerAutoRes] SLOW QUEUE MSG [Projection Core #1]: UnwrapEnvelopeMessage - 57ms. Q: 0/216. [PID:23384:013 2018.09.14 08:09:06.445 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #3]: ReadStreamEventsBackward - 367ms. Q: 0/30. [PID:23384:056 2018.09.14 08:09:06.824 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #2]: ReadStreamEventsBackward - 370ms. Q: 0/12. [PID:23384:007 2018.09.14 08:09:07.062 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #3]: ReadStreamEventsBackward - 233ms. Q: 0/7. [PID:23384:011 2018.09.14 08:09:07.293 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30096693 [PID:23384:017 2018.09.14 08:09:07.299 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:063 2018.09.14 08:09:08.743 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #2]: ReadStreamEventsBackward - 1659ms. Q: 0/15. [PID:23384:043 2018.09.14 08:09:09.255 TRACE CoreProjectionCheckp] Writing checkpoint for ByDate at C:153362028929/P:153362028929 with expected version number 20029 [PID:23384:031 2018.09.14 08:09:10.354 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #4]: ReadStreamEventsBackward - 1397ms. Q: 0/37. [PID:23384:043 2018.09.14 08:09:10.549 TRACE CoreProjectionCheckp] Checkpoint has been written for projection ByDate at sequence number 20030 (current) [PID:23384:026 2018.09.14 08:09:12.305 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #3]: ReadStreamEventsBackward - 1932ms. Q: 0/24. [PID:23384:037 2018.09.14 08:09:12.319 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30096703 [PID:23384:017 2018.09.14 08:09:12.327 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:034 2018.09.14 08:09:13.323 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30096728 [PID:23384:017 2018.09.14 08:09:13.330 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:030 2018.09.14 08:09:15.234 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #4]: ReadStreamEventsBackward - 2097ms. Q: 0/11. [PID:23384:044 2018.09.14 08:09:15.330 INFO CoreProjectionCheckp] Failed to write events to stream $ce-MessageTracking. Error: CommitTimeout [PID:23384:030 2018.09.14 08:09:15.954 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #4]: ReadStreamEventsBackward - 660ms. Q: 4/27. [PID:23384:043 2018.09.14 08:09:16.079 TRACE InMemoryBus ] SLOW BUS MSG [bus]: UnwrapEnvelopeMessage - 81ms. Handler: UnwrapEnvelopeHandler. [PID:23384:043 2018.09.14 08:09:16.079 TRACE QueuedHandlerAutoRes] SLOW QUEUE MSG [Projection Core #0]: UnwrapEnvelopeMessage - 81ms. Q: 0/151. [PID:23384:026 2018.09.14 08:09:16.338 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30096739 [PID:23384:017 2018.09.14 08:09:16.343 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:030 2018.09.14 08:09:16.927 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #4]: ReadStreamEventsBackward - 648ms. Q: 17/23. [PID:23384:012 2018.09.14 08:09:17.243 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #1]: ReadStreamEventsBackward - 302ms. Q: 0/13. [PID:23384:031 2018.09.14 08:09:17.930 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #2]: ReadStreamEventsBackward - 682ms. Q: 0/14. [PID:23384:040 2018.09.14 08:09:18.342 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #3]: ReadStreamEventsBackward - 402ms. Q: 0/22. [PID:23384:040 2018.09.14 08:09:18.635 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #3]: ReadStreamEventsBackward - 232ms. Q: 11/18. [PID:23384:038 2018.09.14 08:09:19.348 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30096776 [PID:23384:017 2018.09.14 08:09:19.353 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:053 2018.09.14 08:09:19.896 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #2]: ReadStreamEventsBackward - 743ms. Q: 0/12. [PID:23384:054 2018.09.14 08:09:20.280 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #1]: ReadStreamEventsBackward - 363ms. Q: 0/10. [PID:23384:060 2018.09.14 08:09:20.352 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30096789 [PID:23384:017 2018.09.14 08:09:20.359 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:007 2018.09.14 08:09:21.368 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #3]: ReadStreamEventsBackward - 1082ms. Q: 0/10. [PID:23384:049 2018.09.14 08:09:22.495 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #4]: ReadStreamEventsBackward - 1112ms. Q: 0/19. [PID:23384:056 2018.09.14 08:09:23.357 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30096811 [PID:23384:017 2018.09.14 08:09:23.364 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:031 2018.09.14 08:09:24.167 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #3]: ReadStreamEventsBackward - 1290ms. Q: 0/10. [PID:23384:040 2018.09.14 08:09:25.660 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #2]: ReadStreamEventsBackward - 1315ms. Q: 0/15. [PID:23384:058 2018.09.14 08:09:26.372 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30096827 [PID:23384:017 2018.09.14 08:09:26.379 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:012 2018.09.14 08:09:26.832 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #4]: ReadStreamEventsBackward - 921ms. Q: 10/18. [PID:23384:012 2018.09.14 08:09:28.205 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #2]: ReadStreamEventsBackward - 1338ms. Q: 0/14. [PID:23384:012 2018.09.14 08:09:29.864 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #2]: ReadStreamEventsBackward - 1626ms. Q: 4/22. [PID:23384:043 2018.09.14 08:09:30.053 TRACE InMemoryBus ] SLOW BUS MSG [bus]: UnwrapEnvelopeMessage - 54ms. Handler: UnwrapEnvelopeHandler. [PID:23384:043 2018.09.14 08:09:30.053 TRACE QueuedHandlerAutoRes] SLOW QUEUE MSG [Projection Core #0]: UnwrapEnvelopeMessage - 54ms. Q: 0/71. [PID:23384:068 2018.09.14 08:09:30.401 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30096852 [PID:23384:017 2018.09.14 08:09:30.410 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:025 2018.09.14 08:09:31.409 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30096888 [PID:23384:017 2018.09.14 08:09:31.417 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:020 2018.09.14 08:09:32.119 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #3]: ReadStreamEventsBackward - 1344ms. Q: 0/11. [PID:23384:008 2018.09.14 08:09:33.089 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #4]: ReadStreamEventsBackward - 942ms. Q: 0/12. [PID:23384:053 2018.09.14 08:09:33.426 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30096898 [PID:23384:060 2018.09.14 08:09:34.483 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #1]: ReadStreamEventsBackward - 1347ms. Q: 0/17. [PID:23384:044 2018.09.14 08:09:34.512 TRACE CoreProjectionCheckp] Writing checkpoint for $by_category at C:153363330758/P:153363330758 with expected version number 25458 [PID:23384:017 2018.09.14 08:09:34.514 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:044 2018.09.14 08:09:34.528 TRACE CoreProjectionCheckp] Checkpoint has been written for projection $by_category at sequence number 25459 (current) [PID:23384:060 2018.09.14 08:09:34.932 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #1]: ReadStreamEventsBackward - 440ms. Q: 0/14. [PID:23384:017 2018.09.14 08:09:35.078 TRACE InMemoryBus ] SLOW BUS MSG [MainBus]: CommitReplicated - 50ms. Handler: RequestManagementService. [PID:23384:017 2018.09.14 08:09:35.079 TRACE QueuedHandlerAutoRes] SLOW QUEUE MSG [MainQueue]: CommitReplicated - 51ms. Q: 2/97. [PID:23384:044 2018.09.14 08:09:35.081 TRACE CoreProjectionCheckp] Writing checkpoint for Stats at $ce-Message: 30100213; $ce-Session: 1701448; $ce-Queue: 11930; with expected version number 8101 [PID:23384:044 2018.09.14 08:09:35.246 TRACE CoreProjectionCheckp] Checkpoint has been written for projection Stats at sequence number 8102 (current) [PID:23384:061 2018.09.14 08:09:35.340 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #2]: ReadStreamEventsBackward - 351ms. Q: 0/34. [PID:23384:036 2018.09.14 08:09:35.437 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30096910 [PID:23384:017 2018.09.14 08:09:35.445 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:038 2018.09.14 08:09:36.845 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #3]: ReadStreamEventsBackward - 1499ms. Q: 0/15. [PID:23384:057 2018.09.14 08:09:37.837 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #2]: ReadStreamEventsBackward - 981ms. Q: 0/9. [PID:23384:051 2018.09.14 08:09:40.144 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #3]: ReadStreamEventsBackward - 2292ms. Q: 0/33. [PID:23384:045 2018.09.14 08:09:40.460 INFO CoreProjectionCheckp] Failed to write events to stream PeerA-XXXX. Error: CommitTimeout [PID:23384:044 2018.09.14 08:09:40.460 INFO CoreProjectionCheckp] Failed to write events to stream $$MessageTracking-XXXX. Error: CommitTimeout [PID:23384:045 2018.09.14 08:09:40.460 INFO CoreProjectionCheckp] Failed to write events to stream PeerA-XXXX. Error: CommitTimeout [PID:23384:044 2018.09.14 08:09:40.460 INFO CoreProjectionCheckp] Failed to write events to stream $$MessageTracking-XXXX. Error: CommitTimeout [PID:23384:045 2018.09.14 08:09:40.460 INFO CoreProjectionCheckp] Failed to write events to stream PeerA-XXXX. Error: CommitTimeout [PID:23384:044 2018.09.14 08:09:40.460 INFO CoreProjectionCheckp] Failed to write events to stream $$MessageTracking-XXXX. Error: CommitTimeout [PID:23384:045 2018.09.14 08:09:40.460 INFO CoreProjectionCheckp] Failed to write events to stream PeerB-XXXX. Error: CommitTimeout [PID:23384:044 2018.09.14 08:09:40.460 INFO CoreProjectionCheckp] Failed to write events to stream $$Tracking-XXXX. Error: CommitTimeout [PID:23384:044 2018.09.14 08:09:40.460 INFO CoreProjectionCheckp] Failed to write events to stream $projections-Stats-order. Error: CommitTimeout [PID:23384:044 2018.09.14 08:09:40.460 INFO CoreProjectionCheckp] Failed to write events to stream $ce-Date. Error: CommitTimeout [PID:23384:051 2018.09.14 08:09:40.583 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #3]: ReadStreamEventsBackward - 292ms. Q: 7/16. [PID:23384:056 2018.09.14 08:09:41.023 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #2]: ReadStreamEventsBackward - 434ms. Q: 0/19. [PID:23384:009 2018.09.14 08:09:41.269 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #3]: ReadStreamEventsBackward - 241ms. Q: 0/8. [PID:23384:028 2018.09.14 08:09:41.468 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30096927 [PID:23384:029 2018.09.14 08:09:41.820 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #1]: ReadStreamEventsBackward - 545ms. Q: 0/12. [PID:23384:017 2018.09.14 08:09:41.980 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:054 2018.09.14 08:09:43.307 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #4]: ReadStreamEventsBackward - 1482ms. Q: 0/17. [PID:23384:054 2018.09.14 08:09:43.791 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #4]: ReadStreamEventsBackward - 436ms. Q: 8/22. [PID:23384:065 2018.09.14 08:09:44.054 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #2]: ReadStreamEventsBackward - 227ms. Q: 0/18. [PID:23384:039 2018.09.14 08:09:44.291 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #3]: ReadStreamEventsBackward - 232ms. Q: 0/12. [PID:23384:008 2018.09.14 08:09:44.485 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30096965 [PID:23384:017 2018.09.14 08:09:44.499 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:024 2018.09.14 08:09:44.860 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #1]: ReadStreamEventsBackward - 248ms. Q: 0/10. [PID:23384:012 2018.09.14 08:09:45.502 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30097081 [PID:23384:067 2018.09.14 08:09:45.598 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #4]: ReadStreamEventsBackward - 253ms. Q: 0/11. [PID:23384:063 2018.09.14 08:09:45.659 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #1]: ReadStreamEventsBackward - 287ms. Q: 0/15. [PID:23384:017 2018.09.14 08:09:45.673 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:058 2018.09.14 08:09:46.507 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30097097 [PID:23384:061 2018.09.14 08:09:47.539 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #1]: ReadStreamEventsBackward - 1869ms. Q: 0/16. [PID:23384:017 2018.09.14 08:09:47.571 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:059 2018.09.14 08:09:47.974 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #3]: ReadStreamEventsBackward - 418ms. Q: 0/11. [PID:23384:049 2018.09.14 08:09:48.514 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30097149 [PID:23384:017 2018.09.14 08:09:48.523 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:009 2018.09.14 08:09:49.523 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30097179 [PID:23384:017 2018.09.14 08:09:49.530 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:025 2018.09.14 08:09:49.821 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #2]: ReadStreamEventsBackward - 1133ms. Q: 0/12. [PID:23384:025 2018.09.14 08:09:51.064 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #2]: ReadStreamEventsBackward - 1235ms. Q: 2/12. [PID:23384:033 2018.09.14 08:09:51.529 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30097192 [PID:23384:053 2018.09.14 08:09:52.309 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #1]: ReadStreamEventsBackward - 978ms. Q: 12/18. [PID:23384:017 2018.09.14 08:09:52.372 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:029 2018.09.14 08:09:52.556 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30097204 [PID:23384:017 2018.09.14 08:09:52.577 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:055 2018.09.14 08:09:53.563 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30097214 [PID:23384:017 2018.09.14 08:09:53.569 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:006 2018.09.14 08:09:53.878 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #2]: ReadStreamEventsBackward - 1321ms. Q: 2/16. [PID:23384:065 2018.09.14 08:09:54.474 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #3]: ReadStreamEventsBackward - 375ms. Q: 0/15. [PID:23384:030 2018.09.14 08:09:54.566 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30097225 [PID:23384:017 2018.09.14 08:09:54.571 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:053 2018.09.14 08:09:56.242 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #2]: ReadStreamEventsBackward - 1760ms. Q: 0/13. [PID:23384:053 2018.09.14 08:09:58.440 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #2]: ReadStreamEventsBackward - 2056ms. Q: 13/21. [PID:23384:044 2018.09.14 08:09:58.609 INFO CoreProjectionCheckp] Failed to write events to stream Tracking-XXXX. Error: CommitTimeout [PID:23384:044 2018.09.14 08:09:58.609 INFO CoreProjectionCheckp] Failed to write events to stream $ce-PeerA. Error: CommitTimeout [PID:23384:044 2018.09.14 08:09:58.609 INFO CoreProjectionCheckp] Failed to write events to stream $$Tracking-761210369. Error: CommitTimeout [PID:23384:044 2018.09.14 08:09:58.609 INFO CoreProjectionCheckp] Failed to write events to stream $ce-Alias. Error: CommitTimeout [PID:23384:045 2018.09.14 08:09:58.611 INFO CoreProjectionCheckp] Failed to write events to stream PeerC-XXXX. Error: CommitTimeout [PID:23384:045 2018.09.14 08:09:58.611 INFO CoreProjectionCheckp] Failed to write events to stream PeerD-XXXX. Error: CommitTimeout [PID:23384:045 2018.09.14 08:09:58.611 INFO CoreProjectionCheckp] Failed to write events to stream PeerB-XXXX. Error: CommitTimeout [PID:23384:046 2018.09.14 08:09:59.575 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #4]: ReadStreamEventsBackward - 1065ms. Q: 5/31. [PID:23384:053 2018.09.14 08:09:59.618 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30097236 [PID:23384:017 2018.09.14 08:09:59.624 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:063 2018.09.14 08:10:00.230 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #2]: ReadStreamEventsBackward - 648ms. Q: 0/41. [PID:23384:020 2018.09.14 08:10:02.221 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #1]: ReadStreamEventsBackward - 1923ms. Q: 0/19. [PID:23384:044 2018.09.14 08:10:02.644 INFO CoreProjectionCheckp] Failed to write events to stream $$Tracking-XXXX. Error: CommitTimeout [PID:23384:027 2018.09.14 08:10:02.645 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30097253 [PID:23384:017 2018.09.14 08:10:02.682 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:059 2018.09.14 08:10:03.647 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30097289 [PID:23384:017 2018.09.14 08:10:03.657 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:038 2018.09.14 08:10:04.696 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #3]: ReadStreamEventsBackward - 1656ms. Q: 0/10. [PID:23384:056 2018.09.14 08:10:05.501 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #3]: ReadStreamEventsBackward - 722ms. Q: 0/20. [PID:23384:057 2018.09.14 08:10:05.652 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30097304 [PID:23384:017 2018.09.14 08:10:05.658 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:038 2018.09.14 08:10:07.000 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #2]: ReadStreamEventsBackward - 1469ms. Q: 0/10. [PID:23384:051 2018.09.14 08:10:07.498 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #1]: ReadStreamEventsBackward - 490ms. Q: 0/23. [PID:23384:063 2018.09.14 08:10:09.473 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #4]: ReadStreamEventsBackward - 1794ms. Q: 0/34. [PID:23384:051 2018.09.14 08:10:09.676 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30097324 [PID:23384:017 2018.09.14 08:10:09.684 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:057 2018.09.14 08:10:10.750 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #4]: ReadStreamEventsBackward - 1142ms. Q: 0/18. [PID:23384:057 2018.09.14 08:10:11.229 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #4]: ReadStreamEventsBackward - 388ms. Q: 13/23. [PID:23384:053 2018.09.14 08:10:13.288 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #2]: ReadStreamEventsBackward - 2054ms. Q: 0/13. [PID:23384:044 2018.09.14 08:10:13.695 INFO CoreProjectionCheckp] Failed to write events to stream $$Tracking-XXXX. Error: CommitTimeout [PID:23384:028 2018.09.14 08:10:13.695 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30097355 [PID:23384:017 2018.09.14 08:10:13.702 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:049 2018.09.14 08:10:15.650 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #3]: ReadStreamEventsBackward - 2170ms. Q: 0/10. [PID:23384:044 2018.09.14 08:10:15.708 INFO CoreProjectionCheckp] Failed to write events to stream $projections-Stats-order. Error: CommitTimeout [PID:23384:044 2018.09.14 08:10:17.715 INFO CoreProjectionCheckp] Failed to write events to stream $ce-Tracking. Error: CommitTimeout [PID:23384:044 2018.09.14 08:10:17.715 INFO CoreProjectionCheckp] Failed to write events to stream $ce-Message. Error: CommitTimeout [PID:23384:056 2018.09.14 08:10:18.183 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #1]: ReadStreamEventsBackward - 2515ms. Q: 0/7. [PID:23384:027 2018.09.14 08:10:18.716 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30097370 [PID:23384:017 2018.09.14 08:10:18.730 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:063 2018.09.14 08:10:19.076 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #4]: ReadStreamEventsBackward - 792ms. Q: 0/9. [PID:23384:040 2018.09.14 08:10:19.718 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30097397 [PID:23384:017 2018.09.14 08:10:19.725 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:056 2018.09.14 08:10:20.548 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #3]: ReadStreamEventsBackward - 1173ms. Q: 0/10. [PID:23384:059 2018.09.14 08:10:20.969 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #2]: ReadStreamEventsBackward - 410ms. Q: 0/14. [PID:23384:063 2018.09.14 08:10:22.597 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #4]: ReadStreamEventsBackward - 1616ms. Q: 0/13. [PID:23384:063 2018.09.14 08:10:22.864 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #4]: ReadStreamEventsBackward - 254ms. Q: 0/13. [PID:23384:063 2018.09.14 08:10:23.534 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #4]: ReadStreamEventsBackward - 606ms. Q: 8/13. [PID:23384:052 2018.09.14 08:10:23.749 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30097414 [PID:23384:017 2018.09.14 08:10:23.756 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:014 2018.09.14 08:10:23.893 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #2]: ReadStreamEventsBackward - 354ms. Q: 0/9. [PID:23384:058 2018.09.14 08:10:24.160 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #4]: ReadStreamEventsBackward - 261ms. Q: 0/9. [PID:23384:055 2018.09.14 08:10:25.677 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #2]: ReadStreamEventsBackward - 1512ms. Q: 0/8. [PID:23384:025 2018.09.14 08:10:25.765 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30097429 [PID:23384:017 2018.09.14 08:10:25.770 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:013 2018.09.14 08:10:26.407 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #4]: ReadStreamEventsBackward - 713ms. Q: 0/10. [PID:23384:033 2018.09.14 08:10:26.769 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30097448 [PID:23384:006 2018.09.14 08:10:28.237 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #1]: ReadStreamEventsBackward - 1728ms. Q: 0/14. [PID:23384:017 2018.09.14 08:10:28.254 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:032 2018.09.14 08:10:28.775 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30097458 [PID:23384:036 2018.09.14 08:10:29.802 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #1]: ReadStreamEventsBackward - 1553ms. Q: 0/10. [PID:23384:017 2018.09.14 08:10:29.816 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:065 2018.09.14 08:10:30.203 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #1]: ReadStreamEventsBackward - 379ms. Q: 0/17. [PID:23384:036 2018.09.14 08:10:30.784 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30097475 [PID:23384:017 2018.09.14 08:10:30.792 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:047 2018.09.14 08:10:31.779 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #2]: ReadStreamEventsBackward - 1572ms. Q: 0/9. [PID:23384:022 2018.09.14 08:10:33.195 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #1]: ReadStreamEventsBackward - 1409ms. Q: 0/13. [PID:23384:051 2018.09.14 08:10:33.436 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #2]: ReadStreamEventsBackward - 228ms. Q: 0/10. [PID:23384:065 2018.09.14 08:10:33.795 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30097486 [PID:23384:017 2018.09.14 08:10:33.803 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:009 2018.09.14 08:10:33.823 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #4]: ReadStreamEventsBackward - 383ms. Q: 0/14. [PID:23384:032 2018.09.14 08:10:34.883 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #2]: ReadStreamEventsBackward - 990ms. Q: 0/8. [PID:23384:052 2018.09.14 08:10:35.296 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #1]: ReadStreamEventsBackward - 309ms. Q: 5/17. [PID:23384:061 2018.09.14 08:10:35.652 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #4]: ReadStreamEventsBackward - 342ms. Q: 0/10. [PID:23384:009 2018.09.14 08:10:35.802 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30097527 [PID:23384:017 2018.09.14 08:10:35.813 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:068 2018.09.14 08:10:36.574 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #3]: ReadStreamEventsBackward - 776ms. Q: 0/10. [PID:23384:061 2018.09.14 08:10:36.812 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30097539 [PID:23384:017 2018.09.14 08:10:36.819 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:043 2018.09.14 08:10:38.817 INFO CoreProjectionCheckp] Failed to write events to stream Date-20180914-1010. Error: CommitTimeout [PID:23384:044 2018.09.14 08:10:38.817 INFO CoreProjectionCheckp] Failed to write events to stream $ce-Date. Error: CommitTimeout [PID:23384:044 2018.09.14 08:10:38.817 INFO CoreProjectionCheckp] Failed to write events to stream $projections-Stats-order. Error: CommitTimeout [PID:23384:038 2018.09.14 08:10:39.822 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #3]: ReadStreamEventsBackward - 3101ms. Q: 0/28. [PID:23384:038 2018.09.14 08:10:40.756 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #3]: ReadStreamEventsBackward - 899ms. Q: 12/29. [PID:23384:034 2018.09.14 08:10:40.836 TRACE InMemoryBus ] SLOW BUS MSG [SubscriptionsBus]: EventCommitted - 60ms. Handler: SubscriptionsService. [PID:23384:014 2018.09.14 08:10:40.836 TRACE InMemoryBus ] SLOW BUS MSG [PersistentSubscriptionsBus]: EventCommitted - 60ms. Handler: PersistentSubscriptionService. [PID:23384:044 2018.09.14 08:10:40.836 TRACE InMemoryBus ] SLOW BUS MSG [bus]: WriteEventsCompleted - 61ms. Handler: RequestResponseDispatcher`2. [PID:23384:044 2018.09.14 08:10:40.836 TRACE QueuedHandlerAutoRes] SLOW QUEUE MSG [Projection Core #1]: WriteEventsCompleted - 62ms. Q: 7/14. [PID:23384:044 2018.09.14 08:10:41.075 TRACE CoreProjectionCheckp] Writing checkpoint for Tracking at $ce-Message: 30101353 with expected version number 2204 [PID:23384:044 2018.09.14 08:10:41.113 TRACE CoreProjectionCheckp] Checkpoint has been written for projection Tracking at sequence number 2205 (current) [PID:23384:058 2018.09.14 08:10:41.181 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #2]: ReadStreamEventsBackward - 219ms. Q: 0/13. [PID:23384:058 2018.09.14 08:10:41.515 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #2]: ReadStreamEventsBackward - 237ms. Q: 8/14. [PID:23384:044 2018.09.14 08:10:41.549 TRACE CoreProjectionCheckp] Writing checkpoint for $by_category at C:153366027724/P:153366027724 with expected version number 25459 [PID:23384:044 2018.09.14 08:10:41.565 TRACE CoreProjectionCheckp] Checkpoint has been written for projection $by_category at sequence number 25460 (current) [PID:23384:052 2018.09.14 08:10:41.845 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30097562 [PID:23384:017 2018.09.14 08:10:41.851 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:020 2018.09.14 08:10:43.013 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #2]: ReadStreamEventsBackward - 1452ms. Q: 0/11. [PID:23384:061 2018.09.14 08:10:43.851 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30097577 [PID:23384:017 2018.09.14 08:10:43.858 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:057 2018.09.14 08:10:44.463 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #2]: ReadStreamEventsBackward - 1224ms. Q: 0/11. [PID:23384:066 2018.09.14 08:10:45.697 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #4]: ReadStreamEventsBackward - 270ms. Q: 0/0. [PID:23384:038 2018.09.14 08:10:46.633 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #3]: ReadStreamEventsBackward - 2027ms. Q: 0/24. [PID:23384:045 2018.09.14 08:10:46.866 INFO CoreProjectionCheckp] Failed to write events to stream PeerA-XXXX. Error: CommitTimeout [PID:23384:044 2018.09.14 08:10:46.866 INFO CoreProjectionCheckp] Failed to write events to stream $ce-Date. Error: CommitTimeout [PID:23384:043 2018.09.14 08:10:46.866 INFO CoreProjectionCheckp] Failed to write events to stream Date-20180914-1010. Error: CommitTimeout [PID:23384:044 2018.09.14 08:10:46.867 INFO CoreProjectionCheckp] Failed to write events to stream $projections-Stats-order. Error: CommitTimeout [PID:23384:061 2018.09.14 08:10:46.867 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30097588 [PID:23384:045 2018.09.14 08:10:46.867 INFO CoreProjectionCheckp] Failed to write events to stream PeerB-XXXX. Error: CommitTimeout [PID:23384:044 2018.09.14 08:10:46.867 INFO CoreProjectionCheckp] Failed to write events to stream $$Tracking-XXXX. Error: CommitTimeout [PID:23384:045 2018.09.14 08:10:46.867 INFO CoreProjectionCheckp] Failed to write events to stream PeerA-XXXX. Error: CommitTimeout [PID:23384:045 2018.09.14 08:10:46.867 INFO CoreProjectionCheckp] Failed to write events to stream PeerB-XXXX. Error: CommitTimeout [PID:23384:045 2018.09.14 08:10:46.867 INFO CoreProjectionCheckp] Failed to write events to stream PeerA-XXXX. Error: CommitTimeout [PID:23384:045 2018.09.14 08:10:46.867 INFO CoreProjectionCheckp] Failed to write events to stream PeerB-XXXX. Error: CommitTimeout [PID:23384:063 2018.09.14 08:10:47.993 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #1]: ReadStreamEventsBackward - 1309ms. Q: 0/9. [PID:23384:017 2018.09.14 08:10:48.061 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:049 2018.09.14 08:10:50.491 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #3]: ReadStreamEventsBackward - 2490ms. Q: 0/19. [PID:23384:044 2018.09.14 08:10:50.882 INFO CoreProjectionCheckp] Failed to write events to stream $ce-Date. Error: CommitTimeout [PID:23384:045 2018.09.14 08:10:50.882 INFO CoreProjectionCheckp] Failed to write events to stream PeerA-XXXX. Error: CommitTimeout [PID:23384:043 2018.09.14 08:10:50.882 INFO CoreProjectionCheckp] Failed to write events to stream Date-20180914-1010. Error: CommitTimeout [PID:23384:044 2018.09.14 08:10:50.882 INFO CoreProjectionCheckp] Failed to write events to stream $projections-Stats-order. Error: CommitTimeout [PID:23384:045 2018.09.14 08:10:50.882 INFO CoreProjectionCheckp] Failed to write events to stream PeerB-36012. Error: CommitTimeout [PID:23384:044 2018.09.14 08:10:50.882 INFO CoreProjectionCheckp] Failed to write events to stream $$Tracking-XXXX. Error: CommitTimeout [PID:23384:045 2018.09.14 08:10:50.882 INFO CoreProjectionCheckp] Failed to write events to stream PeerA-XXXX. Error: CommitTimeout [PID:23384:044 2018.09.14 08:10:50.882 INFO CoreProjectionCheckp] Failed to write events to stream $$Tracking-XXXX. Error: CommitTimeout [PID:23384:045 2018.09.14 08:10:50.882 INFO CoreProjectionCheckp] Failed to write events to stream PeerA-XXXX. Error: CommitTimeout [PID:23384:031 2018.09.14 08:10:51.950 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #4]: ReadStreamEventsBackward - 1446ms. Q: 0/23. [PID:23384:011 2018.09.14 08:10:52.697 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #2]: ReadStreamEventsBackward - 238ms. Q: 0/13. [PID:23384:020 2018.09.14 08:10:52.902 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30097643 [PID:23384:017 2018.09.14 08:10:52.908 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:009 2018.09.14 08:10:53.906 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30097660 [PID:23384:037 2018.09.14 08:10:54.441 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #1]: ReadStreamEventsBackward - 1420ms. Q: 0/10. [PID:23384:017 2018.09.14 08:10:54.468 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:048 2018.09.14 08:10:55.107 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #1]: ReadStreamEventsBackward - 648ms. Q: 0/9. [PID:23384:056 2018.09.14 08:10:55.916 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30097670 [PID:23384:017 2018.09.14 08:10:55.922 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:033 2018.09.14 08:10:56.961 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #2]: ReadStreamEventsBackward - 1846ms. Q: 0/10. [PID:23384:007 2018.09.14 08:10:57.495 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #3]: ReadStreamEventsBackward - 367ms. Q: 0/17. [PID:23384:050 2018.09.14 08:10:57.926 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30097685 [PID:23384:059 2018.09.14 08:10:57.970 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #1]: ReadStreamEventsBackward - 466ms. Q: 0/13. [PID:23384:017 2018.09.14 08:10:57.989 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:066 2018.09.14 08:10:59.284 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #1]: ReadStreamEventsBackward - 1286ms. Q: 0/11. [PID:23384:044 2018.09.14 08:10:59.610 TRACE InMemoryBus ] SLOW BUS MSG [bus]: WriteEventsCompleted - 48ms. Handler: RequestResponseDispatcher`2. [PID:23384:044 2018.09.14 08:10:59.611 TRACE QueuedHandlerAutoRes] SLOW QUEUE MSG [Projection Core #1]: WriteEventsCompleted - 49ms. Q: 0/1. [PID:23384:057 2018.09.14 08:11:00.218 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #3]: ReadStreamEventsBackward - 922ms. Q: 0/11. ... [PID:23384:023 2018.09.14 08:12:46.652 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #4]: ReadStreamEventsBackward - 1028ms. Q: 0/11. [PID:23384:025 2018.09.14 08:12:47.055 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #4]: ReadStreamEventsBackward - 303ms. Q: 0/13. [PID:23384:063 2018.09.14 08:12:47.658 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #2]: ReadStreamEventsBackward - 497ms. Q: 0/10. [PID:23384:027 2018.09.14 08:12:47.682 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30098818 [PID:23384:017 2018.09.14 08:12:47.692 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:063 2018.09.14 08:12:48.124 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #2]: ReadStreamEventsBackward - 439ms. Q: 3/8. [PID:23384:047 2018.09.14 08:12:48.689 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30098836 [PID:23384:017 2018.09.14 08:12:48.695 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:007 2018.09.14 08:12:49.847 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #3]: ReadStreamEventsBackward - 1543ms. Q: 0/9. [PID:23384:025 2018.09.14 08:12:51.268 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #2]: ReadStreamEventsBackward - 1408ms. Q: 1/8. [PID:23384:052 2018.09.14 08:12:53.391 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #1]: ReadStreamEventsBackward - 2110ms. Q: 0/13. [PID:23384:044 2018.09.14 08:12:53.682 INFO CoreProjectionCheckp] Failed to write events to stream $ce-Message. Error: CommitTimeout [PID:23384:043 2018.09.14 08:12:53.682 INFO CoreProjectionCheckp] Failed to write events to stream Date-20180914-1012. Error: CommitTimeout [PID:23384:044 2018.09.14 08:12:53.683 INFO CoreProjectionCheckp] Failed to write events to stream Tracking-XXXX. Error: CommitTimeout [PID:23384:044 2018.09.14 08:12:53.683 INFO CoreProjectionCheckp] Failed to write events to stream Tracking-XXXX. Error: CommitTimeout [PID:23384:060 2018.09.14 08:12:53.713 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30098846 [PID:23384:017 2018.09.14 08:12:53.719 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:057 2018.09.14 08:12:55.278 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #4]: ReadStreamEventsBackward - 1868ms. Q: 0/11. [PID:23384:012 2018.09.14 08:12:57.479 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #1]: ReadStreamEventsBackward - 2195ms. Q: 0/30. [PID:23384:044 2018.09.14 08:12:57.694 INFO CoreProjectionCheckp] Failed to write events to stream $ce-Tracking. Error: CommitTimeout [PID:23384:045 2018.09.14 08:12:57.694 INFO CoreProjectionCheckp] Failed to write events to stream PeerB-XXXX. Error: CommitTimeout [PID:23384:044 2018.09.14 08:12:57.695 INFO CoreProjectionCheckp] Failed to write events to stream $ce-Message. Error: CommitTimeout [PID:23384:045 2018.09.14 08:12:57.695 INFO CoreProjectionCheckp] Failed to write events to stream PeerA-XXXX. Error: CommitTimeout [PID:23384:043 2018.09.14 08:12:57.694 INFO CoreProjectionCheckp] Failed to write events to stream Date20180914-1012. Error: CommitTimeout [PID:23384:044 2018.09.14 08:12:57.695 INFO CoreProjectionCheckp] Failed to write events to stream $$Tracking-XXXX. Error: CommitTimeout [PID:23384:044 2018.09.14 08:12:57.695 INFO CoreProjectionCheckp] Failed to write events to stream $$MessageTracking-XXXX. Error: CommitTimeout [PID:23384:044 2018.09.14 08:12:57.695 INFO CoreProjectionCheckp] Failed to write events to stream $$MessageTracking-XXXX. Error: CommitTimeout [PID:23384:044 2018.09.14 08:12:57.695 INFO CoreProjectionCheckp] Failed to write events to stream $ce-PeerB. Error: CommitTimeout [PID:23384:044 2018.09.14 08:12:57.695 INFO CoreProjectionCheckp] Failed to write events to stream $$MessageTracking-XXXX. Error: CommitTimeout [PID:23384:044 2018.09.14 08:12:57.695 INFO CoreProjectionCheckp] Failed to write events to stream $$Tracking-XXXX. Error: CommitTimeout [PID:23384:061 2018.09.14 08:12:57.725 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30098875 [PID:23384:017 2018.09.14 08:12:57.731 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:060 2018.09.14 08:12:58.148 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #2]: ReadStreamEventsBackward - 530ms. Q: 0/5. [PID:23384:054 2018.09.14 08:12:58.745 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30098885 [PID:23384:017 2018.09.14 08:12:58.752 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:064 2018.09.14 08:12:59.754 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #2]: ReadStreamEventsBackward - 1488ms. Q: 0/6. [PID:23384:044 2018.09.14 08:12:59.916 TRACE InMemoryBus ] SLOW BUS MSG [bus]: CoreTick - 112ms. Handler: ProjectionCoreService. [PID:23384:044 2018.09.14 08:12:59.916 TRACE QueuedHandlerAutoRes] SLOW QUEUE MSG [Projection Core #1]: CoreTick - 112ms. Q: 21/42. [PID:23384:057 2018.09.14 08:13:02.056 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #1]: ReadStreamEventsBackward - 2296ms. Q: 0/37. [PID:23384:044 2018.09.14 08:13:02.726 INFO CoreProjectionCheckp] Failed to write events to stream $$MessageTracking-XXXX. Error: CommitTimeout [PID:23384:043 2018.09.14 08:13:02.726 INFO CoreProjectionCheckp] Failed to write events to stream Date20180914-1012. Error: CommitTimeout [PID:23384:045 2018.09.14 08:13:02.726 INFO CoreProjectionCheckp] Failed to write events to stream PeerC-XXXX. Error: CommitTimeout [PID:23384:044 2018.09.14 08:13:02.726 INFO CoreProjectionCheckp] Failed to write events to stream $$Tracking-XXXX. Error: CommitTimeout [PID:23384:045 2018.09.14 08:13:02.726 INFO CoreProjectionCheckp] Failed to write events to stream PeerA-XXXX. Error: CommitTimeout [PID:23384:044 2018.09.14 08:13:02.726 INFO CoreProjectionCheckp] Failed to write events to stream $$MessageTracking-XXXX. Error: CommitTimeout [PID:23384:045 2018.09.14 08:13:02.726 INFO CoreProjectionCheckp] Failed to write events to stream PeerC-XXXX. Error: CommitTimeout [PID:23384:044 2018.09.14 08:13:02.727 INFO CoreProjectionCheckp] Failed to write events to stream $ce-Message. Error: CommitTimeout [PID:23384:044 2018.09.14 08:13:02.727 INFO CoreProjectionCheckp] Failed to write events to stream $$MessageTracking-XXXX. Error: CommitTimeout [PID:23384:044 2018.09.14 08:13:02.727 INFO CoreProjectionCheckp] Failed to write events to stream $$MessageTracking-XXXX. Error: CommitTimeout [PID:23384:044 2018.09.14 08:13:02.727 INFO CoreProjectionCheckp] Failed to write events to stream $ce-Tracking. Error: CommitTimeout [PID:23384:044 2018.09.14 08:13:02.727 INFO CoreProjectionCheckp] Failed to write events to stream $$Tracking-XXXX. Error: CommitTimeout [PID:23384:044 2018.09.14 08:13:02.727 INFO CoreProjectionCheckp] Failed to write events to stream $ce-PeerB. Error: CommitTimeout [PID:23384:044 2018.09.14 08:13:02.727 INFO CoreProjectionCheckp] Failed to write events to stream $$MessageTracking-XXXX. Error: CommitTimeout [PID:23384:044 2018.09.14 08:13:02.727 INFO CoreProjectionCheckp] Failed to write events to stream $$Tracking-XXXX. Error: CommitTimeout [PID:23384:044 2018.09.14 08:13:02.728 INFO CoreProjectionCheckp] Failed to write events to stream $$MessageTracking-XXXX. Error: CommitTimeout [PID:23384:020 2018.09.14 08:13:02.767 DEBUG PersistentSubscripti] Publishing checkpoint for $persistentsubscription-$ce-Message::PostOffice-checkpoint: 30098923 [PID:23384:017 2018.09.14 08:13:02.774 DEBUG PersistentSubscripti] Checkpoint write successful for $persistentsubscription-$ce-Message::PostOffice-checkpoint [PID:23384:006 2018.09.14 08:13:04.765 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #4]: ReadStreamEventsBackward - 2205ms. Q: 0/11. [PID:23384:026 2018.09.14 08:13:06.913 TRACE QueuedHandlerThreadP] SLOW QUEUE MSG [StorageReaderQueue #3]: ReadStreamEventsBackward - 2142ms. Q: 0/29. ...