View Issue Details

IDProjectCategoryView StatusLast Update
0004920SOGoActiveSyncpublic2020-01-10 14:32
Reporterdanbet Assigned To 
PrioritynormalSeveritytweakReproducibilityalways
Status newResolutionopen 
PlatformServer LinuxOSDebian 9 StretchOS Version9.11
Product Version4.1.1 
Summary0004920: Outlook does not show all emails in EAS sync mode
Description

Outlook doesn't load the mails as soon as I don't just want to synchronize the standard folders. Outlook shows the folder list, but never the text of an email.
I have enclosed the messages on the server, but they are not all, but only a few sections.
It doesn't matter whether Outlook from Office 365 or Outlook 2013, it is always the same behavior.
If I only synchronize the standard folders (inbox, sent), the emails are displayed, but then those from the other folders are missing.

Steps To Reproduce

In the settings, E-Mail, set "only synchronize standard folders (EAS)" to false

TagsNo tags attached.

Activities

danbet

danbet

2020-01-04 17:47

reporter  

SOGo-EAS.txt (7,751 bytes)   
Dec 30 07:41:15 sogod [1785]: <0x0x557469ef07a0[NGImap4Client]> Note: no key found for sorting, using 'DATE': (null)
Dec 30 07:41:15 sogod [1785]: <0x0x557468363be0[SOGoActiveSyncDispatcher]> EAS - sortedBySequence (49) - lastObject: 2821-10348
Dec 30 07:41:15 sogod [1785]: <0x0x557468363be0[SOGoActiveSyncDispatcher]> EAS - allCacheObjects (0) - lastObject: (null)
Dec 30 07:41:15 sogod [1785]: <0x0x557468363be0[SOGoActiveSyncDispatcher]> EAS - found in cache: 0  k = 0
Dec 30 07:41:16 sogod [1785]: <0x0x557469ef07a0[NGImap4Client]> Note: no key found for sorting, using 'DATE': (null)
Dec 30 07:41:16 sogod [1785]: <0x0x557468363be0[SOGoActiveSyncDispatcher]> EAS - sortedBySequence (33) - lastObject: 652-1313
Dec 30 07:41:16 sogod [1785]: <0x0x557468363be0[SOGoActiveSyncDispatcher]> EAS - allCacheObjects (0) - lastObject: (null)
Dec 30 07:41:16 sogod [1785]: <0x0x557468363be0[SOGoActiveSyncDispatcher]> EAS - found in cache: 0  k = 0
Dec 30 07:41:16 sogod [1785]: <0x0x557469ef07a0[NGImap4Client]> Note: no key found for sorting, using 'DATE': (null)
Dec 30 07:41:16 sogod [1785]: <0x0x557468363be0[SOGoActiveSyncDispatcher]> EAS - sortedBySequence (0) - lastObject: (null)
Dec 30 07:41:16 sogod [1785]: <0x0x557468363be0[SOGoActiveSyncDispatcher]> EAS - allCacheObjects (265) - lastObject: 273-457
Dec 30 07:41:16 sogod [1785]: <0x0x557468363be0[SOGoActiveSyncDispatcher]> EAS - found in cache: 0  k = 0
Dec 30 07:41:16 sogod [1785]: <0x0x557468363be0[SOGoActiveSyncDispatcher]> EAS - Dealing with cacheObject: 3-5
Dec 30 07:41:16 sogod [1785]: <0x0x557468363be0[SOGoActiveSyncDispatcher]> EAS - ADD!
Dec 30 07:41:16 sogod [1785]: <0x0x557468363be0[SOGoActiveSyncDispatcher]> EAS - first uid added 3
Dec 30 07:41:16 sogod [1785]: <0x0x557468363be0[SOGoActiveSyncDispatcher]> EAS - Dealing with cacheObject: 2-9
Dec 30 07:41:16 sogod [1785]: <0x0x557468363be0[SOGoActiveSyncDispatcher]> EAS - ADD!

Dec 30 07:45:27 sogod [1785]: <0x0x557468363be0[SOGoActiveSyncDispatcher]> EAS - Dealing with cacheObject: 175-282
Dec 30 07:45:27 sogod [1785]: <0x0x557468363be0[SOGoActiveSyncDispatcher]> EAS - ADD!
Dec 30 07:45:27 sogod [1785]: <0x0x557468363be0[SOGoActiveSyncDispatcher]> EAS - Dealing with cacheObject: 154-285
Dec 30 07:45:27 sogod [1785]: <0x0x557468363be0[SOGoActiveSyncDispatcher]> EAS - ADD!
Dec 30 07:45:27 sogod [1785]: <0x0x557468363be0[SOGoActiveSyncDispatcher]> EAS - Dealing with cacheObject: 177-286
Dec 30 07:45:27 sogod [1785]: <0x0x557468363be0[SOGoActiveSyncDispatcher]> EAS - ADD!
Dec 30 07:45:27 sogod [1785]: <0x0x557468363be0[SOGoActiveSyncDispatcher]> EAS - Dealing with cacheObject: 176-288
Dec 30 07:45:27 sogod [1785]: <0x0x557468363be0[SOGoActiveSyncDispatcher]> EAS - ADD!
Dec 30 07:45:27 sogod [1785]: <0x0x557468363be0[SOGoActiveSyncDispatcher]> EAS - Dealing with cacheObject: 179-289
Dec 30 07:45:27 sogod [1785]: <0x0x557468363be0[SOGoActiveSyncDispatcher]> EAS - ADD!
Dec 30 07:45:27 sogod [1785]: <0x0x557468363be0[SOGoActiveSyncDispatcher]> EAS - Dealing with cacheObject: 178-290
Dec 30 07:45:27 sogod [1785]: <0x0x557468363be0[SOGoActiveSyncDispatcher]> EAS - ADD!
Dec 30 07:45:27 sogod [1785]: <0x0x557468363be0[SOGoActiveSyncDispatcher]> EAS - Dealing with cacheObject: 180-292
Dec 30 07:45:27 sogod [1785]: <0x0x557468363be0[SOGoActiveSyncDispatcher]> EAS - ADD!
Dec 30 07:45:28 sogod [1785]: <0x0x557469ef07a0[NGImap4Client]> Note: no key found for sorting, using 'DATE': (null)
Dec 30 07:45:28 sogod [1785]: <0x0x557468363be0[SOGoActiveSyncDispatcher]> EAS - sortedBySequence (0) - lastObject: (null)
Dec 30 07:45:28 sogod [1785]: <0x0x557468363be0[SOGoActiveSyncDispatcher]> EAS - allCacheObjects (30) - lastObject: 31-56
Dec 30 07:45:28 sogod [1785]: <0x0x557468363be0[SOGoActiveSyncDispatcher]> EAS - found in cache: 0  k = 0
Dec 30 07:45:28 sogod [1785]: <0x0x557468363be0[SOGoActiveSyncDispatcher]> EAS - Dealing with cacheObject: 1-3
Dec 30 07:45:28 sogod [1785]: <0x0x557468363be0[SOGoActiveSyncDispatcher]> EAS - ADD!
Dec 30 07:45:28 sogod [1785]: <0x0x557468363be0[SOGoActiveSyncDispatcher]> EAS - first uid added 1
Dec 30 07:45:28 sogod [1785]: <0x0x557468363be0[SOGoActiveSyncDispatcher]> EAS - Dealing with cacheObject: 2-6
Dec 30 07:45:28 sogod [1785]: <0x0x557468363be0[SOGoActiveSyncDispatcher]> EAS - ADD!
Dec 30 07:45:28 sogod [1785]: <0x0x557468363be0[SOGoActiveSyncDispatcher]> EAS - Dealing with cacheObject: 3-6
Dec 30 07:45:28 sogod [1785]: <0x0x557468363be0[SOGoActiveSyncDispatcher]> EAS - ADD!
Dec 30 07:45:28 sogod [1785]: <0x0x557468363be0[SOGoActiveSyncDispatcher]> EAS - Dealing with cacheObject: 4-7
Dec 30 07:45:28 sogod [1785]: <0x0x557468363be0[SOGoActiveSyncDispatcher]> EAS - ADD!
Dec 30 07:45:28 sogod [1785]: <0x0x557468363be0[SOGoActiveSyncDispatcher]> EAS - Dealing with cacheObject: 5-7
Dec 30 07:45:28 sogod [1785]: <0x0x557468363be0[SOGoActiveSyncDispatcher]> EAS - ADD!
Dec 30 07:45:28 sogod [1785]: <0x0x557468363be0[SOGoActiveSyncDispatcher]> EAS - Dealing with cacheObject: 6-8
Dec 30 07:45:28 sogod [1785]: <0x0x557468363be0[SOGoActiveSyncDispatcher]> EAS - ADD!
Dec 30 07:45:29 sogod [1785]: <0x0x557468363be0[SOGoActiveSyncDispatcher]> EAS - Dealing with cacheObject: 7-9
Dec 30 07:45:29 sogod [1785]: <0x0x557468363be0[SOGoActiveSyncDispatcher]> EAS - ADD!
Dec 30 07:45:29 sogod [1785]: <0x0x557468363be0[SOGoActiveSyncDispatcher]> EAS - Dealing with cacheObject: 8-11
Dec 30 07:45:29 sogod [1785]: <0x0x557468363be0[SOGoActiveSyncDispatcher]> EAS - ADD!
Dec 30 07:45:29 sogod [1785]: <0x0x557468363be0[SOGoActiveSyncDispatcher]> EAS - Dealing with cacheObject: 9-12
Dec 30 07:45:29 sogod [1785]: <0x0x557468363be0[SOGoActiveSyncDispatcher]> EAS - ADD!
Dec 30 07:45:29 sogod [1785]: <0x0x557468363be0[SOGoActiveSyncDispatcher]> EAS - Dealing with cacheObject: 11-15
Dec 30 07:45:29 sogod [1785]: <0x0x557468363be0[SOGoActiveSyncDispatcher]> EAS - ADD!
Dec 30 07:45:29 sogod [1785]: <0x0x557468363be0[SOGoActiveSyncDispatcher]> EAS - Dealing with cacheObject: 12-16
Dec 30 07:45:29 sogod [1785]: <0x0x557468363be0[SOGoActiveSyncDispatcher]> EAS - ADD!
Dec 30 07:45:29 sogod [1785]: <0x0x557468363be0[SOGoActiveSyncDispatcher]> EAS - Dealing with cacheObject: 10-17
Dec 30 07:45:29 sogod [1785]: <0x0x557468363be0[SOGoActiveSyncDispatcher]> EAS - ADD!
Dec 30 07:45:29 sogod [1785]: <0x0x557468363be0[SOGoActiveSyncDispatcher]> EAS - Dealing with cacheObject: 13-18
Dec 30 07:45:29 sogod [1785]: <0x0x557468363be0[SOGoActiveSyncDispatcher]> EAS - ADD!
Dec 30 07:45:29 sogod [1785]: <0x0x557468363be0[SOGoActiveSyncDispatcher]> EAS - Dealing with cacheObject: 14-24
Dec 30 07:45:29 sogod [1785]: <0x0x557468363be0[SOGoActiveSyncDispatcher]> EAS - ADD!

Dec 30 07:48:58 sogod [1785]: <0x0x557468363be0[SOGoActiveSyncDispatcher]> EAS - Dealing with cacheObject: 295-1
Dec 30 07:48:58 sogod [1785]: <0x0x557468363be0[SOGoActiveSyncDispatcher]> EAS - ADD!
Dec 30 07:48:58 sogod [1785]: <0x0x55746925a990[NGMimeRFC822DateHeaderFieldParser]> WARNING: failed to parse RFC822 date field: 'Sat Feb 24 13:44:03 2007 +0200'
Dec 30 07:48:58 sogod [1785]: <0x0x557469263f80[NGMimeRFC822DateHeaderFieldParser]> WARNING: failed to parse RFC822 date field: 'Sat Feb 24 13:44:03 2007 +0200'
2019-12-30 07:48:58.608 sogod[1785:1785] _consume[1350]: error try to read over buffer len self->dataIdx 472 _cnt 1 byteLen 472
Dec 30 07:48:58 sogod [1785]: <0x0x557483c2bf20[NGMimeMessageParser]> WARNING(-[NGMimePartParser parseHeader]:713): 2 an error occured during body parsing (maybe end of stream)
Dec 30 07:48:58 sogod [1785]: <0x0x557468363be0[SOGoActiveSyncDispatcher]> EAS - Dealing with cacheObject: 296-1
Dec 30 07:48:58 sogod [1785]: <0x0x557468363be0[SOGoActiveSyncDispatcher]> EAS - ADD!
SOGo-EAS.txt (7,751 bytes)   
tfu

tfu

2020-01-05 08:08

reporter   ~0014017

This might be caused by a broken mail in one of the on-standard folders.
When you press the refresh button in OL you should get something like "... EAS - request for device ..." and "... EAS - response for device ..." in sogo.log.

  • check for any exception between these messages
danbet

danbet

2020-01-06 12:32

reporter   ~0014019

With refresh button do you mean? F5?
After a long time, Outlook ist disconnected. Then I cannot do a refresh, it does not happens. I have to close and reopen OL.
In the SOGo-Log I find "request for device" and "response for device", the messages between this are the ones that I have uploade in the file SOGo-EAS.txt. I canot detect the item that made a problem.

tfu

tfu

2020-01-06 13:22

reporter   ~0014020

With refresh I mean: Send/Receive (F9) .
If you post a sogo.log including messages before AND INCLUDING the "... response for device ..." causing the "Outlook disconnect" I can try find the problem.
You can also try to debug with SOGoMaximumSyncWindowSize=1. With this parameter only 1 message will be processed per request which might help to identify the problem message.

danbet

danbet

2020-01-06 16:28

reporter   ~0014026

I have tried with SOGoMaximumSyncWindowSize=1. Now from time to time I have an error like this:
|SOGo| terminating app, vMem size limit (384 MB) has been reached (currently 477 MB)

After a while it goes a step forward. It has a very long time and has not finished yet. But I can see more an more emails in OL.

tfu

tfu

2020-01-07 16:21

reporter   ~0014031

How is the status of the sync?

danbet

danbet

2020-01-08 10:13

reporter   ~0014032

Monday evening I had to end my trials. Today I have started again. At the moment OL syncs since three hours and has not finished.

danbet

danbet

2020-01-08 14:56

reporter   ~0014034

I am not sure that OL has finished the sync. On the sogo.log I see always messages from EAS. I have made sample checks of my emails, there are all here, but I does not have controlled each of them, they are too many.
The addressbook is also synced, but now the calendar is not. Before I set SOGoMaximumSyncWindowSize=1 the calendar was synced.

tfu

tfu

2020-01-08 16:59

reporter   ~0014035

If we can assume that all emails are synced now then it is most properly not a borken mail which is causing the problem.
Let's try this: remove SOGoMaximumSyncWindowSize and set SOGoMaximumSyncResponseSize=10240

danbet

danbet

2020-01-09 06:53

reporter   ~0014036

The calendar was synced over night.
I have done this changes in sogo.conf. Now if I start OL all things seems to be there and nothing happens in sogo.log. Shoud I reset the device witch sogo-tool and try it again?

danbet

danbet

2020-01-09 07:16

reporter   ~0014037

With sogo-tool manage-eas listdevices shows me several devices, How can I find the right device?

danbet

danbet

2020-01-09 10:26

reporter   ~0014041

It seems to be OK now. I will try tomorrow on another device.

danbet

danbet

2020-01-10 13:57

reporter   ~0014047

On the other device the problem is also solved now. The sync runs for about one hour. All emails, calendar entries and addressbook entries seems to be here. Thank you very much.
What does SOGoMaximumSyncResponseSize=10240 make in detail?
I am in vacation until January, 20th.

tfu

tfu

2020-01-10 14:32

reporter   ~0014048

Details about the parameter can be found in Installation Guide: https://sogo.nu/files/docs/SOGoInstallationGuide.html

Issue History

Date Modified Username Field Change
2020-01-04 17:47 danbet New Issue
2020-01-04 17:47 danbet File Added: SOGo-EAS.txt
2020-01-05 08:08 tfu Note Added: 0014017
2020-01-06 12:32 danbet Note Added: 0014019
2020-01-06 13:22 tfu Note Added: 0014020
2020-01-06 16:28 danbet Note Added: 0014026
2020-01-07 16:21 tfu Note Added: 0014031
2020-01-08 10:13 danbet Note Added: 0014032
2020-01-08 14:56 danbet Note Added: 0014034
2020-01-08 16:59 tfu Note Added: 0014035
2020-01-09 06:53 danbet Note Added: 0014036
2020-01-09 07:16 danbet Note Added: 0014037
2020-01-09 10:26 danbet Note Added: 0014041
2020-01-10 13:57 danbet Note Added: 0014047
2020-01-10 14:32 tfu Note Added: 0014048