On 18-03-12 03:01 PM, Ludovic Marcotte (lmarco...@inverse.ca) wrote:

On 2018-03-12 2:34 PM, "Lucas Souza" (lucas.so...@mav.com.br) wrote:

This is happening when the mailbox server is down or unavailable for any reason and SOGo ActiveSync is up.
You mean when the IMAP server is down?

If so, I guess we might be missing some error handling.

I am also trying to track down the same issue with Outlook 2016 re-syncing the entire mailbox.  In at least one case, shortly before the re-sync, SOGo replied with a 501 because it could not reach the IMAP server.  In another case, there did not seem to be any IMAP issues, but there was a SOGo error that resulted in a 503 response to the client.  I am attaching the section of the SOGo logs that resulted in the 503 error.  Additionally, in this case there were two concurrent requests from the same Outlook client, a Ping and a Sync.  The Sync failed.  I am not sure if concurrent requests are allowed, so I thought I would mention it.  I have packet dumps as well if needed.

Greg
--
users@sogo.nu
https://inverse.ca/sogo/lists
Mar 09 10:36:46 sogod [31089]: |SOGo| starting method 'POST' on uri '/SOGo/Microsoft-Server-ActiveSync?Cmd=Sync&User=u...@domain.com&DeviceId=B0F13CDE5391495C880206B4B9928870&DeviceType=WindowsOutlook15'
Mar 09 10:36:46 sogod [31089]: <0x0x55dbe6d1d140[SOGoActiveSyncDispatcher]> EAS - request for device B0F13CDE5391495C880206B4B9928870: <?xml version="1.0"?>
2018-03-09 10:36:46.067 sogod[31089] <MySQL4Channel[0x0x55dbe6860eb0] connection=0x0x55dbe6375790> SQL: SELECT * FROM sogo_cache_folder WHERE c_path = '/B0F13CDE5391495C880206B4B9928870' AND c_uid = 'u...@domain.com';
2018-03-09 10:36:46.102 sogod[31089] <MySQL4Channel[0x0x55dbe6860eb0] connection=0x0x55dbe6375790>   query has results, entering fetch-mode.
2018-03-09 10:36:46.103 sogod[31089] <MySQL4Channel[0x0x55dbe6860eb0] connection=0x0x55dbe6375790> SQL: UPDATE sogo_cache_folder  SET c_lastmodified = 1520609806, c_deleted = 0,      c_version = 52785, c_content = 'NQQAABBTeW5jUmVxdWVzdCttYWlsLzQyNzc0NTI1YzJmYjJlNWE3NDc5MDAwMDI3MTgyMDhk
2018-03-09 10:36:46.130 sogod[31089] <MySQL4Channel[0x0x55dbe6860eb0] connection=0x0x55dbe6375790>   query has no results.
Mar 09 10:36:46 sogod [31089]: <0x0x55dbe8c10360[NGImap4Client]> TLS started successfully.
2018-03-09 10:36:46.622 sogod[31089] <MySQL4Channel[0x0x55dbe6860eb0] connection=0x0x55dbe6375790> SQL: SELECT * FROM sogo_cache_folder WHERE c_path = '/B0F13CDE5391495C880206B4B9928870+folder70ccc319e9bed459106d00002718208d' AND c_uid = 'u...@domain.com';
2018-03-09 10:36:46.661 sogod[31089] <MySQL4Channel[0x0x55dbe6860eb0] connection=0x0x55dbe6375790>   query has results, entering fetch-mode.
Mar 09 10:36:46 sogod [31089]: <0x0x55dbe6d1d140[SOGoActiveSyncDispatcher]> EAS - processSyncCollection: no folderMetadata found: folderINBOX
2018-03-09 10:36:46.661 sogod[31089] <MySQL4Channel[0x0x55dbe6860eb0] connection=0x0x55dbe6375790> SQL: SELECT * FROM sogo_cache_folder WHERE c_path = '/B0F13CDE5391495C880206B4B9928870' AND c_uid = 'u...@domain.com';
2018-03-09 10:36:46.678 sogod[31089] <MySQL4Channel[0x0x55dbe6860eb0] connection=0x0x55dbe6375790>   query has results, entering fetch-mode.
2018-03-09 10:36:46.679 sogod[31089] <MySQL4Channel[0x0x55dbe6860eb0] connection=0x0x55dbe6375790> SQL: SELECT * FROM sogo_cache_folder WHERE c_path = '/B0F13CDE5391495C880206B4B9928870' AND c_uid = 'u...@domain.com';
2018-03-09 10:36:46.719 sogod[31089] <MySQL4Channel[0x0x55dbe6860eb0] connection=0x0x55dbe6375790>   query has results, entering fetch-mode.
Mar 09 10:36:46 sogod [31089]: <0x0x55dbe6d1d140[SOGoActiveSyncDispatcher]> EAS - Discard response {FolderSyncKey = "activesync01_dlcgroup_ca_77c8_20519158_3"; PingCachedFolders = ("mail%2F70ccc319e9bed459106d00002718208d", "vevent%2Fpersonal", "vcard%2Fpersonal", "mail%2F69d06038ac36d659365100002718208d", "vtodo%2Fpersonal", "mail%2F2d38473a72da2e5abf4800002718208d", "mail%2F6fdc441bab07875ab91a00002718208d"); PingHeartbeatInterval = 250; PingRequest = 30662; SyncRequest = 1520609665; "SyncRequest+mail/2d38473a72da2e5abf4800002718208d" = 30664; "SyncRequest+mail/42774525c2fb2e5a747900002718208d" = 4473; "SyncRequest+mail/68d06038ac36d659365100002718208d" = 23939; "SyncRequest+mail/69d06038ac36d659365100002718208d" = 30664; "SyncRequest+mail/6ad06038ac36d659365100002718208d" = 23939; "SyncRequest+mail/6fdc441bab07875ab91a00002718208d" = 30664; "SyncRequest+mail/70ccc319e9bed459106d00002718208d" = 30664; "SyncRequest+mail/861f511d8f25285a117e00002718208d" = 19437; "SyncRequest+mail/Shared" = 13887; "SyncRequest+vcard/personal" = 30664; "SyncRequest+vevent/personal" = 30664; "SyncRequest+vtodo/personal" = 30664; }
Mar 09 10:36:46 sogod [31089]: |SOGo| request took 0.666614 seconds to execute
Mar 09 10:36:46 sogod [31089]: 70.50.139.19 "POST /SOGo/Microsoft-Server-ActiveSync?Cmd=Sync&User=u...@domain.com&DeviceId=B0F13CDE5391495C880206B4B9928870&DeviceType=WindowsOutlook15 HTTP/1.0" 503 0/630 0.670 - - 0

Reply via email to