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-29

    root@mailrelay:~# zarafa-admin -V
    Product version: 7,2,4,29
    File version: 29

    root@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/Linux

    root@mailrelay:~# lsb_release -a
    No LSB modules are available.
    Distributor ID: Ubuntu
    Description: Ubuntu 14.04.5 LTS
    Release: 14.04
    Codename: trusty

    2017/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>
    

  • Kopano

    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…


  • Kopano

    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.


Log in to reply