On 18-03-12 03:01 PM, Ludovic Marcotte ([email protected]) wrote:
On 2018-03-12 2:34 PM, "Lucas Souza" ([email protected]) 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
--
[email protected]
https://inverse.ca/sogo/lists
Mar 09 10:36:46 sogod [31089]: |SOGo| starting method 'POST' on uri '/SOGo/Microsoft-Server-ActiveSync?Cmd=Sync&[email protected]&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 = '[email protected]';
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 = '[email protected]';
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 = '[email protected]';
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 = '[email protected]';
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&[email protected]&DeviceId=B0F13CDE5391495C880206B4B9928870&DeviceType=WindowsOutlook15 HTTP/1.0" 503 0/630 0.670 - - 0
