Outlook does not sync any more
-
Hi,
Outlook 2016 does not sync anymore out of nothing. Wireshark caputes no packets sent by Outlook. Any hints?
Kopano Addin: 1.3.156
Outlook: 16.0.0.7870
Z-Push: 2.3.5+4
PHP-MAPI: 7.2.4-29root@mailrelay:~# zarafa-admin -V
Product version: 7,2,4,29
File version: 29root@mailrelay:~# uname -a
Linux mailrelay 3.19.0-25-generic #26~14.04.1-Ubuntu SMP Fri Jul 24 21:16:20 UTC 2015 x86_64 x86_64 x86_64 GNU/Linuxroot@mailrelay:~# lsb_release -a
No LSB modules are available.
Distributor ID: Ubuntu
Description: Ubuntu 14.04.5 LTS
Release: 14.04
Codename: trusty2017/04/28 06:25:05.227 (1,VSTA_Main): Trace: ThisAddIn: OutlookUI created 2017/04/28 06:25:05.382 (1,VSTA_Main): Info: ThisAddIn: Starting version 1.3.156: 343705345b7aef675e2e0a1aa37d011171b6cf2f @ 05.04.2017 12:11:19. Outlook version: 16.0.0.7870. Options: '' 2017/04/28 06:25:05.382 (1,VSTA_Main): Trace: NLogLogger: Level initialized 2017/04/28 06:25:05.403 (1,VSTA_Main): Info: FeatureReplyFlags: Starting feature: '' 2017/04/28 06:25:05.407 (1,VSTA_Main): Trace: FeatureReplyFlags: Started feature 2017/04/28 06:25:05.407 (1,VSTA_Main): Info: FeatureOutOfOffice: Starting feature: '' 2017/04/28 06:25:05.407 (1,VSTA_Main): Debug: OutOfOffice: Acacia.UI.Outlook.RibbonToggleButton: Registered 2017/04/28 06:25:05.407 (1,VSTA_Main): Trace: FeatureOutOfOffice: Started feature 2017/04/28 06:25:05.407 (1,VSTA_Main): Info: FeatureSharedFolders: Starting feature: '' 2017/04/28 06:25:05.407 (1,VSTA_Main): Debug: SharedFolders: Acacia.UI.Outlook.RibbonButton: Registered 2017/04/28 06:25:05.407 (1,VSTA_Main): Debug: SharedFolders: Acacia.UI.Outlook.MenuItem`1[Acacia.Stubs.IFolder]: Registered 2017/04/28 06:25:05.407 (1,VSTA_Main): Trace: FeatureSharedFolders: Started feature 2017/04/28 06:25:05.407 (1,VSTA_Main): Info: FeatureWebApp: Starting feature: '' 2017/04/28 06:25:05.407 (1,VSTA_Main): Debug: WebApp: Acacia.UI.Outlook.RibbonButton: Registered 2017/04/28 06:25:05.407 (1,VSTA_Main): Trace: FeatureWebApp: Started feature 2017/04/28 06:25:05.407 (1,VSTA_Main): Info: FeatureFreeBusy: Starting feature: '' 2017/04/28 06:25:05.427 (1,VSTA_Main): Trace: FeatureFreeBusy: Started feature 2017/04/28 06:25:05.427 (1,VSTA_Main): Info: FeatureGAB: Starting feature: '' 2017/04/28 06:25:05.427 (1,VSTA_Main): Trace: FeatureGAB: Started feature 2017/04/28 06:25:05.427 (1,VSTA_Main): Info: FeatureNotes: Starting feature: '' 2017/04/28 06:25:05.427 (1,VSTA_Main): Trace: FeatureNotes: Started feature 2017/04/28 06:25:05.427 (1,VSTA_Main): Info: FeatureSecondaryContacts: Starting feature: '' 2017/04/28 06:25:05.427 (1,VSTA_Main): Trace: FeatureSecondaryContacts: Started feature 2017/04/28 06:25:05.427 (1,VSTA_Main): Info: FeatureSendAs: Starting feature: '' 2017/04/28 06:25:05.427 (1,VSTA_Main): Trace: FeatureSendAs: Started feature 2017/04/28 06:25:05.427 (1,VSTA_Main): Info: FeatureSignatures: Starting feature: '' 2017/04/28 06:25:05.427 (1,VSTA_Main): Trace: FeatureSignatures: Started feature 2017/04/28 06:25:05.437 (1,VSTA_Main): Info: FeatureDebugSupport: Starting feature: 'Dialog' 2017/04/28 06:25:05.437 (1,VSTA_Main): Debug: DebugSupport: Acacia.UI.Outlook.RibbonButton: Registered 2017/04/28 06:25:05.437 (1,VSTA_Main): Debug: DebugSupport: Acacia.UI.Outlook.RibbonButton: Registered 2017/04/28 06:25:05.437 (1,VSTA_Main): Debug: DebugSupport: Acacia.UI.Outlook.RibbonButton: Registered 2017/04/28 06:25:05.437 (1,VSTA_Main): Trace: FeatureDebugSupport: Started feature 2017/04/28 06:25:05.469 (1,VSTA_Main): Debug: ThisAddIn: Startup done 2017/04/28 06:25:05.872 (6,): Trace: StoresWrapper: New store: firstname.lastname@company.de 2017/04/28 06:25:05.883 (6,): Trace: StoresWrapper: Account registered: firstname lastname -> 0000000038A1BB1005E5101AA1BB08002B2A56C200007073747072782E646C6C0000000000000000E92FEB759650448683B87DE522AA4948000043003A005C00550073006500720073005C006D006E0069006500770069006E0064005C0041007000700044006100740061005C004C006F00630061006C005C004D006900630072006F0073006F00660074005C004F00750074006C006F006F006B005C006D00690063006800610065006C002E006E0069006500770069006E00640040006D0065007400610067006D00620068002E006400650020002D00200054006500730074002E006F00730074000000 2017/04/28 06:25:05.883 (6,): Trace: StoresWrapper: New account store: firstname.lastname@company.de: firstname.lastname@company.de 2017/04/28 06:25:05.883 (6,): Trace: ZPushAccounts: Checking account: firstname.lastname@company.de 2017/04/28 06:25:05.883 (6,): Trace: ZPushAccounts: ZPush account: firstname.lastname@company.de 2017/04/28 06:25:05.889 (6,): Debug: WebApp: Starting kdiscover: company.de 2017/04/28 06:25:06.428 (6,): Debug: WebApp: Finished kdiscover: company.de: 2017/04/28 06:25:06.428 (6,): Info: GAB: Account discovered: firstname lastname 2017/04/28 06:25:06.505 (6,): Trace: ZPushFolder: Watching folder: firstname.lastname@company.de: 00000000EBA9A36B41584F41BC7ACB0F9F41B1E90100D9539C2261A6BB45B9DAB62C7081B3C101002000FFFF0000 2017/04/28 06:25:07.568 (6,): Trace: ZPushWatcher: Folder discovered: firstname.lastname@company.de 2017/04/28 06:25:07.581 (6,): Debug: ZPushWatcher: Folder event: firstname.lastname@company.de, ReplyFlags:MailItem, True 2017/04/28 06:25:07.956 (6,): Trace: StoresWrapper: New store: Outlook-Datendatei 2017/04/28 06:25:07.969 (6,): Trace: StoresWrapper: Not an account store: Outlook-Datendatei 2017/04/28 06:25:07.969 (6,): Debug: GAB: Accounts scanned 2017/04/28 06:25:07.969 (6,): Debug: ZPushLocalStore: Opening store with prefix C:\Users\mlastname\AppData\Local\Kopano\Kopano OL Extension\KopanoLocalFolders 2017/04/28 06:25:07.985 (6,): Info: ZPushLocalStore: Opening existing store: C:\Users\mlastname\AppData\Local\Kopano\Kopano OL Extension\KopanoLocalFolders.pst 2017/04/28 06:25:08.038 (6,): Debug: GAB: CheckGABRemoved 2017/04/28 06:25:08.044 (6,): Debug: SharedFolders: Starting sync for account firstname.lastname@company.de 2017/04/28 06:25:08.101 (6,): Trace: ZPushConnection: Request: <?xml version="1.0" encoding="utf-8"?> <soap:Envelope xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance" xmlns:xsd="http://www.w3.org/2001/XMLSchema" xmlns:soap="http://schemas.xmlsoap.org/soap/envelope/" xmlns:soap-enc="http://schemas.xmlsoap.org/soap/encoding/" xmlns:ns2="http://xml.apache.org/xml-soap" xmlns="mail.company.de" > <soap:Body> <AdditionalFolderList> <devid xsi:type="xsd:string">d2fcb2678fbf48429115b0b668cb3953</devid> </AdditionalFolderList> </soap:Body> </soap:Envelope> 2017/04/28 06:29:03.530 (6,): Error: SharedFolders: Exception in task SharedFolders: System.AggregateException: One or more errors occurred. ---> System.Threading.Tasks.TaskCanceledException: A task was canceled. --- End of inner exception stack trace --- at System.Threading.Tasks.Task.ThrowIfExceptional(Boolean includeTaskCanceledExceptions) at System.Threading.Tasks.Task`1.GetResultCore(Boolean waitCompletionNotification) at System.Threading.Tasks.Task`1.get_Result() at Acacia.ZPush.Connect.ZPushConnection.Execute(String url, RequestEncoder request) at Acacia.ZPush.Connect.ZPushWebService.Execute[ResponseType](SoapRequest`1 request) at Acacia.ZPush.API.SharedFolders.SharedFoldersAPI.GetCurrentShares(Nullable`1 cancel) at Acacia.Features.SharedFolders.SharedFoldersManager.GetCurrentShares(Nullable`1 cancel) at Acacia.Features.SharedFolders.FeatureSharedFolders.AdditionalFolders_Sync(ZPushConnection connection) at Acacia.ZPush.ZPushSync.SyncTask.<>c__DisplayClass5_0.<GetInstance>b__0() at Acacia.Utils.AcaciaTask.Execute() ---> (Inner Exception #0) System.Threading.Tasks.TaskCanceledException: A task was canceled.<--- 2017/04/28 06:29:03.592 (6,): Trace: Request: Sending request: mail.company.de -> <?xml version="1.0" encoding="utf-16"?><Settings><Oof><Get><BodyType> TEXT </BodyType></Get></Oof></Settings> 2017/04/28 06:29:03.592 (6,): Trace: Request: Sending request: mail.company.de -> receiveflags,sendflags,oof,ooftime,sharedfolders,webapp,freebusy,gab,notes,secondarycontacts,sendas,signatures,debugsupport: <?xml version="1.0" encoding="utf-16"?><Settings><Oof><Get><BodyType> TEXT </BodyType></Get></Oof></Settings> 2017/04/28 06:32:14.136 (1,VSTA_Main): Trace: ThisAddIn: OutlookUI created 2017/04/28 06:32:14.286 (1,VSTA_Main): Info: ThisAddIn: Starting version 1.3.156: 343705345b7aef675e2e0a1aa37d011171b6cf2f @ 05.04.2017 12:11:19. Outlook version: 16.0.0.7870. Options: '' 2017/04/28 06:32:14.286 (1,VSTA_Main): Trace: NLogLogger: Level initialized 2017/04/28 06:32:14.307 (1,VSTA_Main): Info: FeatureReplyFlags: Starting feature: '' 2017/04/28 06:32:14.307 (1,VSTA_Main): Trace: FeatureReplyFlags: Started feature 2017/04/28 06:32:14.307 (1,VSTA_Main): Info: FeatureOutOfOffice: Starting feature: '' 2017/04/28 06:32:14.314 (1,VSTA_Main): Debug: OutOfOffice: Acacia.UI.Outlook.RibbonToggleButton: Registered 2017/04/28 06:32:14.314 (1,VSTA_Main): Trace: FeatureOutOfOffice: Started feature 2017/04/28 06:32:14.314 (1,VSTA_Main): Info: FeatureSharedFolders: Starting feature: '' 2017/04/28 06:32:14.314 (1,VSTA_Main): Debug: SharedFolders: Acacia.UI.Outlook.RibbonButton: Registered 2017/04/28 06:32:14.314 (1,VSTA_Main): Debug: SharedFolders: Acacia.UI.Outlook.MenuItem`1[Acacia.Stubs.IFolder]: Registered 2017/04/28 06:32:14.314 (1,VSTA_Main): Trace: FeatureSharedFolders: Started feature 2017/04/28 06:32:14.314 (1,VSTA_Main): Info: FeatureWebApp: Starting feature: '' 2017/04/28 06:32:14.314 (1,VSTA_Main): Debug: WebApp: Acacia.UI.Outlook.RibbonButton: Registered 2017/04/28 06:32:14.314 (1,VSTA_Main): Trace: FeatureWebApp: Started feature 2017/04/28 06:32:14.314 (1,VSTA_Main): Info: FeatureFreeBusy: Starting feature: '' 2017/04/28 06:32:14.333 (1,VSTA_Main): Trace: FeatureFreeBusy: Started feature 2017/04/28 06:32:14.333 (1,VSTA_Main): Info: FeatureGAB: Starting feature: '' 2017/04/28 06:32:14.333 (1,VSTA_Main): Trace: FeatureGAB: Started feature 2017/04/28 06:32:14.333 (1,VSTA_Main): Info: FeatureNotes: Starting feature: '' 2017/04/28 06:32:14.333 (1,VSTA_Main): Trace: FeatureNotes: Started feature 2017/04/28 06:32:14.333 (1,VSTA_Main): Info: FeatureSecondaryContacts: Starting feature: '' 2017/04/28 06:32:14.333 (1,VSTA_Main): Trace: FeatureSecondaryContacts: Started feature 2017/04/28 06:32:14.333 (1,VSTA_Main): Info: FeatureSendAs: Starting feature: '' 2017/04/28 06:32:14.333 (1,VSTA_Main): Trace: FeatureSendAs: Started feature 2017/04/28 06:32:14.333 (1,VSTA_Main): Info: FeatureSignatures: Starting feature: '' 2017/04/28 06:32:14.333 (1,VSTA_Main): Trace: FeatureSignatures: Started feature 2017/04/28 06:32:14.333 (1,VSTA_Main): Info: FeatureDebugSupport: Starting feature: 'Dialog' 2017/04/28 06:32:14.333 (1,VSTA_Main): Debug: DebugSupport: Acacia.UI.Outlook.RibbonButton: Registered 2017/04/28 06:32:14.333 (1,VSTA_Main): Debug: DebugSupport: Acacia.UI.Outlook.RibbonButton: Registered 2017/04/28 06:32:14.333 (1,VSTA_Main): Debug: DebugSupport: Acacia.UI.Outlook.RibbonButton: Registered 2017/04/28 06:32:14.333 (1,VSTA_Main): Trace: FeatureDebugSupport: Started feature 2017/04/28 06:32:14.373 (1,VSTA_Main): Debug: ThisAddIn: Startup done 2017/04/28 06:32:14.748 (5,): Trace: StoresWrapper: New store: firstname.lastname@company.de 2017/04/28 06:32:14.759 (5,): Trace: StoresWrapper: Account registered: firstname lastname -> 0000000038A1BB1005E5101AA1BB08002B2A56C200007073747072782E646C6C0000000000000000E92FEB759650448683B87DE522AA4948000043003A005C00550073006500720073005C006D006E0069006500770069006E0064005C0041007000700044006100740061005C004C006F00630061006C005C004D006900630072006F0073006F00660074005C004F00750074006C006F006F006B005C006D00690063006800610065006C002E006E0069006500770069006E00640040006D0065007400610067006D00620068002E006400650020002D00200054006500730074002E006F00730074000000 2017/04/28 06:32:14.759 (5,): Trace: StoresWrapper: New account store: firstname.lastname@company.de: firstname.lastname@company.de 2017/04/28 06:32:14.759 (5,): Trace: ZPushAccounts: Checking account: firstname.lastname@company.de 2017/04/28 06:32:14.759 (5,): Trace: ZPushAccounts: ZPush account: firstname.lastname@company.de 2017/04/28 06:32:14.759 (5,): Debug: WebApp: Starting kdiscover: company.de 2017/04/28 06:32:15.271 (5,): Debug: WebApp: Finished kdiscover: company.de: 2017/04/28 06:32:15.271 (5,): Info: GAB: Account discovered: firstname lastname 2017/04/28 06:32:15.292 (5,): Trace: ZPushFolder: Watching folder: firstname.lastname@company.de: 00000000EBA9A36B41584F41BC7ACB0F9F41B1E90100D9539C2261A6BB45B9DAB62C7081B3C101002000FFFF0000 2017/04/28 06:32:16.008 (5,): Trace: ZPushWatcher: Folder discovered: firstname.lastname@company.de 2017/04/28 06:32:16.126 (5,): Debug: ZPushWatcher: Folder event: firstname.lastname@company.de, ReplyFlags:MailItem, True 2017/04/28 06:32:16.273 (5,): Trace: StoresWrapper: New store: Outlook-Datendatei 2017/04/28 06:32:16.283 (5,): Trace: StoresWrapper: Not an account store: Outlook-Datendatei 2017/04/28 06:32:16.283 (5,): Debug: GAB: Accounts scanned 2017/04/28 06:32:16.283 (5,): Debug: ZPushLocalStore: Opening store with prefix C:\Users\mlastname\AppData\Local\Kopano\Kopano OL Extension\KopanoLocalFolders 2017/04/28 06:32:16.283 (5,): Info: ZPushLocalStore: Opening existing store: C:\Users\mlastname\AppData\Local\Kopano\Kopano OL Extension\KopanoLocalFolders.pst 2017/04/28 06:32:16.335 (5,): Debug: GAB: CheckGABRemoved 2017/04/28 06:32:16.335 (5,): Debug: SharedFolders: Starting sync for account firstname.lastname@company.de 2017/04/28 06:32:16.401 (5,): Trace: ZPushConnection: Request: <?xml version="1.0" encoding="utf-8"?> <soap:Envelope xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance" xmlns:xsd="http://www.w3.org/2001/XMLSchema" xmlns:soap="http://schemas.xmlsoap.org/soap/envelope/" xmlns:soap-enc="http://schemas.xmlsoap.org/soap/encoding/" xmlns:ns2="http://xml.apache.org/xml-soap" xmlns="mail.company.de" > <soap:Body> <AdditionalFolderList> <devid xsi:type="xsd:string">d2fcb2678fbf48429115b0b668cb3953</devid> </AdditionalFolderList> </soap:Body> </soap:Envelope> 2017/04/28 06:36:16.502 (5,): Error: SharedFolders: Exception in task SharedFolders: System.AggregateException: One or more errors occurred. ---> System.Threading.Tasks.TaskCanceledException: A task was canceled. --- End of inner exception stack trace --- at System.Threading.Tasks.Task.ThrowIfExceptional(Boolean includeTaskCanceledExceptions) at System.Threading.Tasks.Task`1.GetResultCore(Boolean waitCompletionNotification) at System.Threading.Tasks.Task`1.get_Result() at Acacia.ZPush.Connect.ZPushConnection.Execute(String url, RequestEncoder request) at Acacia.ZPush.Connect.ZPushWebService.Execute[ResponseType](SoapRequest`1 request) at Acacia.ZPush.API.SharedFolders.SharedFoldersAPI.GetCurrentShares(Nullable`1 cancel) at Acacia.Features.SharedFolders.SharedFoldersManager.GetCurrentShares(Nullable`1 cancel) at Acacia.Features.SharedFolders.FeatureSharedFolders.AdditionalFolders_Sync(ZPushConnection connection) at Acacia.ZPush.ZPushSync.SyncTask.<>c__DisplayClass5_0.<GetInstance>b__0() at Acacia.Utils.AcaciaTask.Execute() ---> (Inner Exception #0) System.Threading.Tasks.TaskCanceledException: A task was canceled.<--- 2017/04/28 06:36:16.576 (5,): Trace: Request: Sending request: mail.company.de -> <?xml version="1.0" encoding="utf-16"?><Settings><Oof><Get><BodyType> TEXT </BodyType></Get></Oof></Settings> 2017/04/28 06:36:16.576 (5,): Trace: Request: Sending request: mail.company.de -> receiveflags,sendflags,oof,ooftime,sharedfolders,webapp,freebusy,gab,notes,secondarycontacts,sendas,signatures,debugsupport: <?xml version="1.0" encoding="utf-16"?><Settings><Oof><Get><BodyType> TEXT </BodyType></Get></Oof></Settings>
-
Well, if there is no traffic from Outlook, then it’s hard to say.
I guess you tried restarting OL and/or rebooting the PC? -
Of course. :)
We waited ~2h and now it is in sync again…wired…
-
Perhaps the sync went into loop detection, this can delay the sync substantially.
But then still, there should have been some movement in the log.