search

jobisoft / TbSync

[Thunderbird Add-On] Central user interface to manage cloud accounts and to synchronize their contact, task and calendar information with Thunderbird
https://github.com/jobisoft/TbSync/wiki/About:-TbSync
Mozilla Public License 2.0
807 stars 54 forks source link

Javascript Error (recRule.getComponent is not a function) #37

Closed ricleal closed 6 years ago

ricleal commented 6 years ago

Hi!

Great peace of software. I'm just reporting an issue I'm having synchronising with activesync.

The GUI shows: Calendar : Javascript Error: Check Debug, and the debug console the following:

recRule.getComponent is not a function  calendarsync.js:684
    eas.calendarsync.getWbxmlFromCalendarItem chrome://tbsync/content/provider/eas/calendarsync.js:684:33
    eas.calendarsync.sendLocalChanges< chrome://tbsync/content/provider/eas/calendarsync.js:222:62
    InterpretGeneratorResume self-hosted:1213:8
    next self-hosted:1120:9
    TaskImpl_run resource://gre/modules/Task.jsm:319:42
    bound TaskImpl_run self-hosted:957:17

I hope you can fix that! Let me know if you need further info. Thanks

jobisoft commented 6 years ago

Please retry with beta4 and report, if the error remains

ricleal commented 6 years ago

Thanks for the reply! I installed beta 4 but the problem persists (in a different line):

recRule.getComponent is not a function  sync.js:223
    eas.sync.getItemRecurrence chrome://tbsync/content/provider/eas/sync.js:223:33
    eas.sync.Calendar.getWbxmlFromThunderbirdItem chrome://tbsync/content/provider/eas/calendarsync.js:247:26
    eas.sync.sendLocalChanges< chrome://tbsync/content/provider/eas/sync.js:552:62
    InterpretGeneratorResume self-hosted:1213:8
    next self-hosted:1120:9
    TaskImpl_run resource://gre/modules/Task.jsm:319:42
    bound TaskImpl_run self-hosted:957:17
jobisoft commented 6 years ago

hm, that is odd. Can you send me a full debug log? I would like to look at the actual data being processed.

Debug log can be enabled at the help tab in the account manager.

What kind of system/environment is this?

ChrisJAllan commented 6 years ago

I think it's an RDATE rule. I've started on something to support it, but there doesn't seem to be any way to make such an event from within Thunderbird or Outlook so I'll have to get creative with the testing.

EDIT: It doesn't seem that EAS or Outlook supports those kinds on events. (I can't even import the iCal file to Outlook) Should we just ignore those or try to break them into separate events?

jobisoft commented 6 years ago

I actually do not know what you are talking about :-)

The error happens while trying to sync something from Thunderbird to the EAS server, right? @ricleal : How did you get the failing item into your Thunderbird calendar? @ChrisJAllan : What is RDATE? How do you get the impression, that this could be the reason?

I am officially lost.

ChrisJAllan commented 6 years ago

An RDATE is an exception that adds an extra occurence. I say that this is the issue because that type of recRule does not have a getComponent function and is not filtered out for special handling.

Related, there is also EXRULE, which defines a recurring exception, which isn't checked for or handled correctly. EAS doesn't support either of these rule types, so I was wondering if they should just be ignored.

jobisoft commented 6 years ago

@ChrisJAllan So if such events cannot be placed into the TB calendar during the initial sync, the user must have added this event by himself? Can you reproduce that?

If it is not supported by EAS at all, shouldn't we be able to catch those events by

if (recRule.getComponent) {...}

and simply ignore them?

@ricleal Does any of this sound familiar? Did you somehow added an event with exception to your calendar?

ChrisJAllan commented 6 years ago

The only way I found to recreate this was to manually modify an iCal file, import it, and copy the event into the TbSync calendar. PR #38 deals with them.

ricleal commented 6 years ago

I have no idea. Is there a way I could remove the calendar cache (not all Thunderbird cache) and re-sync everything?

jobisoft commented 6 years ago

No, please do not remove anything from your calendar, I will release a new beta shortly. But I have a question:

Your calendar contains a recurring event, which is using a recurrent format that is not supported by EAS. Probably RDATE which adds an "extra" occurence to a series. We ignore this extra occurence. If you ever delete your local thunderbird calendar and than sync the calendar fresh from your server to Thunderbird, that extra occurence will not show up anymore.

TbSync does not have a way to inform users about such things (except the debug log, which logs way to much for everyday usage).

What I could do, is sending you an E-mail notification (to be precise, your EAS server is sending it), that informs you about the situation. Would that be "too much"?

ricleal commented 6 years ago

That makes sense! I created a recurring event using the Thunderbird calendar... Sure send me an email: [removed]

jobisoft commented 6 years ago

No that is not what I mean. I am thinking about implementing an automatic notification email which is send by TbSync (via your EAS account) to inform you about such a situation. But that is not so important.

Much more interesting is your other remark. Can you recall what exactly you did with that calendar, after you initially synced it down from your server? Did you import anything from an iCal file or did you simply add an recurring event? What kind of recurrence?

ricleal commented 6 years ago

LOL! Monday I just simply add an recurring event: bi-weekly, Tuesday at 9AM EST (starting yesterday).

jobisoft commented 6 years ago

can you remove that recurring event and try to sync? does it work? can you than re-add it exactly as you did before and sync again? does it work?

ricleal commented 6 years ago

I had done that. Doesn't work. Here you have the full log (Creating and removing the recurring event):

** Wed Jan 24 2018 14:27:47 GMT-0500 (EST) **
[Init] : Please send this log to john.bieling@XXX.XX, if you have encountered an error.

** Wed Jan 24 2018 14:27:47 GMT-0500 (EST) **
[Thunderbird] : 52.5.0 on Linux

** Wed Jan 24 2018 14:27:47 GMT-0500 (EST) **
[PROVIDER] : eas::Exchange Active Sync

** Wed Jan 24 2018 14:27:47 GMT-0500 (EST) **
[PROVIDER] : dav::CalDAV/CardDAV (sabre/dav, ownCloud, Nextcloud)

** Wed Jan 24 2018 14:27:47 GMT-0500 (EST) **
[Active AddOn] : Default (52.5.0)

** Wed Jan 24 2018 14:27:47 GMT-0500 (EST) **
[Active AddOn] : English (GB) Language Pack (52.5.0)

** Wed Jan 24 2018 14:27:47 GMT-0500 (EST) **
[Active AddOn] : Messaging Menu and Unity Launcher integration (1.3.1)

** Wed Jan 24 2018 14:27:47 GMT-0500 (EST) **
[Active AddOn] : ExQuilla for Microsoft Exchange (52.3.1200)

** Wed Jan 24 2018 14:27:47 GMT-0500 (EST) **
[Active AddOn] : Lightning (5.4)

** Wed Jan 24 2018 14:27:47 GMT-0500 (EST) **
[Active AddOn] : Thunderbird Conversations (2.13.4)

** Wed Jan 24 2018 14:27:47 GMT-0500 (EST) **
[Active AddOn] : Markdown Here (2.13.2)

** Wed Jan 24 2018 14:27:47 GMT-0500 (EST) **
[Active AddOn] : TbSync (Provider for Exchange ActiveSync) (0.6.9.2-beta4)

** Wed Jan 24 2018 14:27:47 GMT-0500 (EST) **
[Active AddOn] : Shockwave Flash (28.0.0.137)

** Wed Jan 24 2018 14:27:48 GMT-0500 (EST) **
[JavaScript Error: "TypeError: tab is undefined" {file: "chrome://messenger/content/tabmail.xml" line: 1070}]
getBrowserForSelectedTab@chrome://messenger/content/tabmail.xml:1070:15
getBrowser@chrome://messenger/content/mailWindow.js:548:20
messagePaneOnResize@chrome://communicator/content/contentAreaClick.js:60:17
onresize@chrome://messenger/content/messenger.xul:1:8

** Wed Jan 24 2018 14:27:51 GMT-0500 (EST) **
[INIT] : DB

** Wed Jan 24 2018 14:27:53 GMT-0500 (EST) **
[setSyncState] : State: accountdone, Account: rhf@XXXX.XXX

** Wed Jan 24 2018 15:46:25 GMT-0500 (EST) **
[setSyncState] : State: syncing, Account: rhf@XXXX.XXX

** Wed Jan 24 2018 15:46:25 GMT-0500 (EST) **
[setSyncState] : State: requestingfolders, Account: rhf@XXXX.XXX

** Wed Jan 24 2018 15:46:25 GMT-0500 (EST) **
[Sending data <requestingfolders> for rhf@XXXX.XXX (WBXML)] : 
3 1 6a 0 0 7 56 52 3 32 0 1 1

** Wed Jan 24 2018 15:46:25 GMT-0500 (EST) **
[Sending data <requestingfolders> for rhf@XXXX.XXX (XML)] : 
<?xml version="1.0"?>
<FolderSync xmlns='FolderHierarchy'>
<SyncKey>2</SyncKey>
</FolderSync>

** Wed Jan 24 2018 15:46:25 GMT-0500 (EST) **
[sending] : POST https://activesync.XXXX.XXX/Microsoft-Server-ActiveSync?Cmd=FolderSync&User=rhf%40XXXX.XXX&DeviceType=Thunderbird&DeviceId=mztb10c6ce77f6b99007bfc3fd28fe6b

** Wed Jan 24 2018 15:46:25 GMT-0500 (EST) **
[PolicyKey used] : 929673007

** Wed Jan 24 2018 15:46:25 GMT-0500 (EST) **
[JavaScript Error: "activesync.XXXX.XXX : server does not support RFC 5746, see CVE-2009-3555"]

** Wed Jan 24 2018 15:46:25 GMT-0500 (EST) **
[Receiving data <requestingfolders> for rhf@XXXX.XXX (WBXML)] : 
3 1 6a 0 0 7 56 4c 3 31 0 1 52 3 32 0 1 4e 57 3 30 0 1 1 1

** Wed Jan 24 2018 15:46:25 GMT-0500 (EST) **
[Receiving data <requestingfolders> for rhf@XXXX.XXX (XML)] : 
<?xml version="1.0"?>
<FolderSync xmlns='FolderHierarchy'>
<Status>1</Status>
<SyncKey>2</SyncKey>
<Changes>
<Count>0</Count>
</Changes>
</FolderSync>

** Wed Jan 24 2018 15:46:25 GMT-0500 (EST) **
[Extracted XML data] : 
FolderSync => 
 Status = [1]
 SyncKey = [2]
 Changes => 
  Count = [0]
 Changes <= 
FolderSync <= 

** Wed Jan 24 2018 15:46:25 GMT-0500 (EST) **
[setSyncState] : State: preparing, Account: rhf@XXXX.XXX, Folder: Calendar

** Wed Jan 24 2018 15:46:25 GMT-0500 (EST) **
[checkCalender] : 1.74

** Wed Jan 24 2018 15:46:25 GMT-0500 (EST) **
[setSyncState] : State: requestingchanges, Account: rhf@XXXX.XXX, Folder: Calendar

** Wed Jan 24 2018 15:46:25 GMT-0500 (EST) **
[Sending data <requestingchanges> for rhf@XXXX.XXX (Calendar) (WBXML)] : 
3 1 6a 0 45 5c 4f 4b 3 32 30 32 37 39 38 37 33 33 38 0 1 52 3 37 34 0 1 5e 3 31 0 1 53 3 31 0 1 55 3 31 30 30 0 1 57 0 11 45 46 3 31 0 1 1 0 0 1 1 1 1

** Wed Jan 24 2018 15:46:25 GMT-0500 (EST) **
[Sending data <requestingchanges> for rhf@XXXX.XXX (Calendar) (XML)] : 
<?xml version="1.0"?>
<Sync xmlns='AirSync'>
<Collections>
<Collection>
<SyncKey>2027987338</SyncKey>
<CollectionId>74</CollectionId>
<DeletesAsMoves>1</DeletesAsMoves>
<GetChanges>1</GetChanges>
<WindowSize>100</WindowSize>
<Options>
<BodyPreference xmlns='AirSyncBase'>
<Type xmlns='AirSyncBase'>1</Type>
</BodyPreference>
</Options>
</Collection>
</Collections>
</Sync>

** Wed Jan 24 2018 15:46:25 GMT-0500 (EST) **
[sending] : POST https://activesync.XXXX.XXX/Microsoft-Server-ActiveSync?Cmd=Sync&User=rhf%40XXXX.XXX&DeviceType=Thunderbird&DeviceId=mztb10c6ce77f6b99007bfc3fd28fe6b

** Wed Jan 24 2018 15:46:25 GMT-0500 (EST) **
[PolicyKey used] : 929673007

** Wed Jan 24 2018 15:46:25 GMT-0500 (EST) **
[Receiving data <requestingchanges> for rhf@XXXX.XXX (Calendar) (WBXML)] : 

** Wed Jan 24 2018 15:46:25 GMT-0500 (EST) **
[Receiving data <requestingchanges> for rhf@XXXX.XXX (Calendar) (XML)] : 

** Wed Jan 24 2018 15:46:25 GMT-0500 (EST) **
[setSyncState] : State: recievingchanges, Account: rhf@XXXX.XXX, Folder: Calendar

** Wed Jan 24 2018 15:46:25 GMT-0500 (EST) **
[setSyncState] : State: sendingchanges, Account: rhf@XXXX.XXX, Folder: Calendar

** Wed Jan 24 2018 15:46:25 GMT-0500 (EST) **
[finishFolderSync(rhf@XXXX.XXX.Calendar)] : Javascript error! Check debug log for details.

** Wed Jan 24 2018 15:46:25 GMT-0500 (EST) **
[setSyncState] : State: done, Account: rhf@XXXX.XXX

** Wed Jan 24 2018 15:46:25 GMT-0500 (EST) **
[finishAccountSync(rhf@XXXX.XXX)] : Javascript error! Check debug log for details.

** Wed Jan 24 2018 15:46:25 GMT-0500 (EST) **
[setSyncState] : State: accountdone, Account: rhf@XXXX.XXX

** Wed Jan 24 2018 15:46:25 GMT-0500 (EST) **
[JavaScript Error: "recRule.getComponent is not a function" {file: "chrome://tbsync/content/provider/eas/sync.js" line: 223}]
eas.sync.getItemRecurrence@chrome://tbsync/content/provider/eas/sync.js:223:33
eas.sync.Calendar.getWbxmlFromThunderbirdItem@chrome://tbsync/content/provider/eas/calendarsync.js:247:26
eas.sync.sendLocalChanges<@chrome://tbsync/content/provider/eas/sync.js:552:62
TaskImpl_run@resource://gre/modules/Task.jsm:319:42

** Wed Jan 24 2018 15:47:30 GMT-0500 (EST) **
[JavaScript Error: "TypeError: getBrowser(...) is null" {file: "chrome://messenger/content/mailWindow.js" line: 62}]
onCopyOrDragStart@chrome://messenger/content/mailWindow.js:62:19

** Wed Jan 24 2018 15:47:32 GMT-0500 (EST) **
[INIT] : DB

** Wed Jan 24 2018 15:47:35 GMT-0500 (EST) **
[setSyncState] : State: accountdone, Account: rhf@XXXX.XXX

** Wed Jan 24 2018 15:50:07 GMT-0500 (EST) **
[JavaScript Error: "2018-01-24 15:50:07 gloda.datastore ERROR   got error in _asyncTrackerListener.handleError(): 19: constraint failed
" {file: "resource:///modules/gloda/log4moz.js" line: 691}]
CApp_append@resource:///modules/gloda/log4moz.js:691:7
Logger_log@resource:///modules/gloda/log4moz.js:380:7
Logger_error@resource:///modules/gloda/log4moz.js:388:5
GlodaDatastore._asyncTrackerListener.handleError@resource:///modules/gloda/datastore.js:1803:9

** Wed Jan 24 2018 15:54:31 GMT-0500 (EST) **
[JavaScript Error: "2018-01-24 15:54:31 gloda.collection    ERROR   caught exception from listener in onItemsModified: resource://conversations/modules/conversation.js:1047: TypeError: markReadButton is null
" {file: "resource:///modules/gloda/log4moz.js" line: 691}]
CApp_append@resource:///modules/gloda/log4moz.js:691:7
Logger_log@resource:///modules/gloda/log4moz.js:380:7
Logger_error@resource:///modules/gloda/log4moz.js:388:5
gloda_coll_onItemsModified@resource:///modules/gloda/collection.js:584:9
gloda_colm_itemsModified@resource:///modules/gloda/collection.js:382:9
gloda_ds_updateMessageLocations@resource:///modules/gloda/datastore.js:2706:7
gloda_indexer_msgsMoveCopyCompleted@resource:///modules/gloda/index_msg.js:2418:15
_finishCopy@jar:file:///home/rhf/.thunderbird/en7zwitk.default/extensions/exquilla@mesquilla.com.xpi!/components/ewsMsgFolderComponent.js:3449:7
EwsMsgFolder.prototype._promiseCopyEwsMessages<@jar:file:///home/rhf/.thunderbird/en7zwitk.default/extensions/exquilla@mesquilla.com.xpi!/components/ewsMsgFolderComponent.js:3433:7
TaskImpl_run@resource://gre/modules/Task.jsm:319:42

** Wed Jan 24 2018 16:19:34 GMT-0500 (EST) **
[setSyncState] : State: syncing, Account: rhf@XXXX.XXX

** Wed Jan 24 2018 16:19:35 GMT-0500 (EST) **
[setSyncState] : State: requestingfolders, Account: rhf@XXXX.XXX

** Wed Jan 24 2018 16:19:35 GMT-0500 (EST) **
[Sending data <requestingfolders> for rhf@XXXX.XXX (WBXML)] : 
3 1 6a 0 0 7 56 52 3 32 0 1 1

** Wed Jan 24 2018 16:19:35 GMT-0500 (EST) **
[Sending data <requestingfolders> for rhf@XXXX.XXX (XML)] : 
<?xml version="1.0"?>
<FolderSync xmlns='FolderHierarchy'>
<SyncKey>2</SyncKey>
</FolderSync>

** Wed Jan 24 2018 16:19:35 GMT-0500 (EST) **
[sending] : POST https://activesync.XXXX.XXX/Microsoft-Server-ActiveSync?Cmd=FolderSync&User=rhf%40XXXX.XXX&DeviceType=Thunderbird&DeviceId=mztb10c6ce77f6b99007bfc3fd28fe6b

** Wed Jan 24 2018 16:19:35 GMT-0500 (EST) **
[PolicyKey used] : 929673007

** Wed Jan 24 2018 16:19:35 GMT-0500 (EST) **
[JavaScript Error: "activesync.XXXX.XXX : server does not support RFC 5746, see CVE-2009-3555"]

** Wed Jan 24 2018 16:19:35 GMT-0500 (EST) **
[Receiving data <requestingfolders> for rhf@XXXX.XXX (WBXML)] : 
3 1 6a 0 0 7 56 4c 3 31 0 1 52 3 32 0 1 4e 57 3 30 0 1 1 1

** Wed Jan 24 2018 16:19:35 GMT-0500 (EST) **
[Receiving data <requestingfolders> for rhf@XXXX.XXX (XML)] : 
<?xml version="1.0"?>
<FolderSync xmlns='FolderHierarchy'>
<Status>1</Status>
<SyncKey>2</SyncKey>
<Changes>
<Count>0</Count>
</Changes>
</FolderSync>

** Wed Jan 24 2018 16:19:35 GMT-0500 (EST) **
[Extracted XML data] : 
FolderSync => 
 Status = [1]
 SyncKey = [2]
 Changes => 
  Count = [0]
 Changes <= 
FolderSync <= 

** Wed Jan 24 2018 16:19:35 GMT-0500 (EST) **
[setSyncState] : State: preparing, Account: rhf@XXXX.XXX, Folder: Calendar

** Wed Jan 24 2018 16:19:35 GMT-0500 (EST) **
[checkCalender] : 1.74

** Wed Jan 24 2018 16:19:35 GMT-0500 (EST) **
[setSyncState] : State: requestingchanges, Account: rhf@XXXX.XXX, Folder: Calendar

** Wed Jan 24 2018 16:19:35 GMT-0500 (EST) **
[Sending data <requestingchanges> for rhf@XXXX.XXX (Calendar) (WBXML)] : 
3 1 6a 0 45 5c 4f 4b 3 32 30 32 37 39 38 37 33 33 38 0 1 52 3 37 34 0 1 5e 3 31 0 1 53 3 31 0 1 55 3 31 30 30 0 1 57 0 11 45 46 3 31 0 1 1 0 0 1 1 1 1

** Wed Jan 24 2018 16:19:35 GMT-0500 (EST) **
[Sending data <requestingchanges> for rhf@XXXX.XXX (Calendar) (XML)] : 
<?xml version="1.0"?>
<Sync xmlns='AirSync'>
<Collections>
<Collection>
<SyncKey>2027987338</SyncKey>
<CollectionId>74</CollectionId>
<DeletesAsMoves>1</DeletesAsMoves>
<GetChanges>1</GetChanges>
<WindowSize>100</WindowSize>
<Options>
<BodyPreference xmlns='AirSyncBase'>
<Type xmlns='AirSyncBase'>1</Type>
</BodyPreference>
</Options>
</Collection>
</Collections>
</Sync>

** Wed Jan 24 2018 16:19:35 GMT-0500 (EST) **
[sending] : POST https://activesync.XXXX.XXX/Microsoft-Server-ActiveSync?Cmd=Sync&User=rhf%40XXXX.XXX&DeviceType=Thunderbird&DeviceId=mztb10c6ce77f6b99007bfc3fd28fe6b

** Wed Jan 24 2018 16:19:35 GMT-0500 (EST) **
[PolicyKey used] : 929673007

** Wed Jan 24 2018 16:19:35 GMT-0500 (EST) **
[Receiving data <requestingchanges> for rhf@XXXX.XXX (Calendar) (WBXML)] : 

** Wed Jan 24 2018 16:19:35 GMT-0500 (EST) **
[Receiving data <requestingchanges> for rhf@XXXX.XXX (Calendar) (XML)] : 

** Wed Jan 24 2018 16:19:35 GMT-0500 (EST) **
[setSyncState] : State: recievingchanges, Account: rhf@XXXX.XXX, Folder: Calendar

** Wed Jan 24 2018 16:19:35 GMT-0500 (EST) **
[setSyncState] : State: sendingchanges, Account: rhf@XXXX.XXX, Folder: Calendar

** Wed Jan 24 2018 16:19:35 GMT-0500 (EST) **
[finishFolderSync(rhf@XXXX.XXX.Calendar)] : Javascript error! Check debug log for details.

** Wed Jan 24 2018 16:19:35 GMT-0500 (EST) **
[setSyncState] : State: done, Account: rhf@XXXX.XXX

** Wed Jan 24 2018 16:19:35 GMT-0500 (EST) **
[finishAccountSync(rhf@XXXX.XXX)] : Javascript error! Check debug log for details.

** Wed Jan 24 2018 16:19:35 GMT-0500 (EST) **
[setSyncState] : State: accountdone, Account: rhf@XXXX.XXX

** Wed Jan 24 2018 16:19:35 GMT-0500 (EST) **
[JavaScript Error: "recRule.getComponent is not a function" {file: "chrome://tbsync/content/provider/eas/sync.js" line: 223}]
eas.sync.getItemRecurrence@chrome://tbsync/content/provider/eas/sync.js:223:33
eas.sync.Calendar.getWbxmlFromThunderbirdItem@chrome://tbsync/content/provider/eas/calendarsync.js:247:26
eas.sync.sendLocalChanges<@chrome://tbsync/content/provider/eas/sync.js:552:62
TaskImpl_run@resource://gre/modules/Task.jsm:319:42

** Wed Jan 24 2018 16:19:58 GMT-0500 (EST) **
[JavaScript Error: "NS_ERROR_NOT_AVAILABLE: Cannot call openModalWindow on a hidden window" {file: "resource://gre/components/nsPrompter.js" line: 351}]

** Wed Jan 24 2018 16:19:58 GMT-0500 (EST) **
[JavaScript Error: "NS_ERROR_NOT_AVAILABLE: Cannot call openModalWindow on a hidden window" {file: "resource://gre/components/nsPrompter.js" line: 351}]

** Wed Jan 24 2018 16:19:58 GMT-0500 (EST) **
[JavaScript Error: "NS_ERROR_NOT_AVAILABLE: Cannot call openModalWindow on a hidden window" {file: "resource://gre/components/nsPrompter.js" line: 351}]
openModalWindow@resource://gre/components/nsPrompter.js:351:19
ModalPrompter.prototype.openPrompt@resource://gre/components/nsPrompter.js:579:9
ModalPrompter.prototype.confirmEx@resource://gre/components/nsPrompter.js:720:9
Prompter.prototype.confirmEx@resource://gre/components/nsPrompter.js:79:16
calItipEmailTransport.prototype._sendXpcomMail@file:///home/rhf/.thunderbird/en7zwitk.default/extensions/%7Be2fda1a4-762b-4020-b5ad-a41df1933103%7D/components/calItipEmailTransport.js:248:21
calItipEmailTransport.prototype.sendItems@file:///home/rhf/.thunderbird/en7zwitk.default/extensions/%7Be2fda1a4-762b-4020-b5ad-a41df1933103%7D/components/calItipEmailTransport.js:54:24
sendMessage/_sendItem@resource://calendar/modules/calItipUtils.jsm:1147:16
sendMessage@resource://calendar/modules/calItipUtils.jsm:1170:16
cal.itip.checkAndSend@resource://calendar/modules/calItipUtils.jsm:751:21
InvitationsManager.prototype.processJobQueue/operationListener.prototype.onOperationComplete@chrome://calendar/content/calendar-invitations-manager.js:280:21
cal.ProviderBase.prototype.notifyPureOperationComplete@resource://calendar/modules/calProviderUtils.jsm:632:17
cal.ProviderBase.prototype.notifyOperationComplete@resource://calendar/modules/calProviderUtils.jsm:640:9
calStorageCalendar.prototype.doModifyItem@file:///home/rhf/.thunderbird/en7zwitk.default/extensions/%7Be2fda1a4-762b-4020-b5ad-a41df1933103%7D/components/calStorageCalendar.js:567:9
calStorageCalendar.prototype.modifyItem/offlineJournalFlagListener.onOperationComplete@file:///home/rhf/.thunderbird/en7zwitk.default/extensions/%7Be2fda1a4-762b-4020-b5ad-a41df1933103%7D/components/calStorageCalendar.js:477:17
calStorageCalendar.prototype.getItemOfflineFlag/listener.handleCompletion@file:///home/rhf/.thunderbird/en7zwitk.default/extensions/%7Be2fda1a4-762b-4020-b5ad-a41df1933103%7D/components/calStorageCalendar.js:959:21
openModalWindow@resource://gre/components/nsPrompter.js:364:5
ModalPrompter.prototype.openPrompt@resource://gre/components/nsPrompter.js:579:9
ModalPrompter.prototype.confirmEx@resource://gre/components/nsPrompter.js:720:9
Prompter.prototype.confirmEx@resource://gre/components/nsPrompter.js:79:16
calItipEmailTransport.prototype._sendXpcomMail@file:///home/rhf/.thunderbird/en7zwitk.default/extensions/%7Be2fda1a4-762b-4020-b5ad-a41df1933103%7D/components/calItipEmailTransport.js:248:21
calItipEmailTransport.prototype.sendItems@file:///home/rhf/.thunderbird/en7zwitk.default/extensions/%7Be2fda1a4-762b-4020-b5ad-a41df1933103%7D/components/calItipEmailTransport.js:54:24
sendMessage/_sendItem@resource://calendar/modules/calItipUtils.jsm:1147:16
sendMessage@resource://calendar/modules/calItipUtils.jsm:1170:16
cal.itip.checkAndSend@resource://calendar/modules/calItipUtils.jsm:751:21
InvitationsManager.prototype.processJobQueue/operationListener.prototype.onOperationComplete@chrome://calendar/content/calendar-invitations-manager.js:280:21
cal.ProviderBase.prototype.notifyPureOperationComplete@resource://calendar/modules/calProviderUtils.jsm:632:17
cal.ProviderBase.prototype.notifyOperationComplete@resource://calendar/modules/calProviderUtils.jsm:640:9
calStorageCalendar.prototype.doModifyItem@file:///home/rhf/.thunderbird/en7zwitk.default/extensions/%7Be2fda1a4-762b-4020-b5ad-a41df1933103%7D/components/calStorageCalendar.js:567:9
calStorageCalendar.prototype.modifyItem/offlineJournalFlagListener.onOperationComplete@file:///home/rhf/.thunderbird/en7zwitk.default/extensions/%7Be2fda1a4-762b-4020-b5ad-a41df1933103%7D/components/calStorageCalendar.js:477:17
calStorageCalendar.prototype.getItemOfflineFlag/listener.handleCompletion@file:///home/rhf/.thunderbird/en7zwitk.default/extensions/%7Be2fda1a4-762b-4020-b5ad-a41df1933103%7D/components/calStorageCalendar.js:959:21

** Wed Jan 24 2018 16:19:58 GMT-0500 (EST) **
[JavaScript Error: "[Exception... "Cannot call openModalWindow on a hidden window"  nsresult: "0x80040111 (NS_ERROR_NOT_AVAILABLE)"  location: "JS frame :: resource://gre/components/nsPrompter.js :: openModalWindow :: line 351"  data: no]" {file: "resource://calendar/modules/calProviderUtils.jsm" line: 634}]

** Wed Jan 24 2018 16:20:11 GMT-0500 (EST) **
[setSyncState] : State: syncing, Account: rhf@XXXX.XXX

** Wed Jan 24 2018 16:20:11 GMT-0500 (EST) **
[setSyncState] : State: requestingfolders, Account: rhf@XXXX.XXX

** Wed Jan 24 2018 16:20:11 GMT-0500 (EST) **
[Sending data <requestingfolders> for rhf@XXXX.XXX (WBXML)] : 
3 1 6a 0 0 7 56 52 3 32 0 1 1

** Wed Jan 24 2018 16:20:11 GMT-0500 (EST) **
[Sending data <requestingfolders> for rhf@XXXX.XXX (XML)] : 
<?xml version="1.0"?>
<FolderSync xmlns='FolderHierarchy'>
<SyncKey>2</SyncKey>
</FolderSync>

** Wed Jan 24 2018 16:20:11 GMT-0500 (EST) **
[sending] : POST https://activesync.XXXX.XXX/Microsoft-Server-ActiveSync?Cmd=FolderSync&User=rhf%40XXXX.XXX&DeviceType=Thunderbird&DeviceId=mztb10c6ce77f6b99007bfc3fd28fe6b

** Wed Jan 24 2018 16:20:11 GMT-0500 (EST) **
[PolicyKey used] : 929673007

** Wed Jan 24 2018 16:20:11 GMT-0500 (EST) **
[Receiving data <requestingfolders> for rhf@XXXX.XXX (WBXML)] : 
3 1 6a 0 0 7 56 4c 3 31 0 1 52 3 32 0 1 4e 57 3 30 0 1 1 1

** Wed Jan 24 2018 16:20:11 GMT-0500 (EST) **
[Receiving data <requestingfolders> for rhf@XXXX.XXX (XML)] : 
<?xml version="1.0"?>
<FolderSync xmlns='FolderHierarchy'>
<Status>1</Status>
<SyncKey>2</SyncKey>
<Changes>
<Count>0</Count>
</Changes>
</FolderSync>

** Wed Jan 24 2018 16:20:11 GMT-0500 (EST) **
[Extracted XML data] : 
FolderSync => 
 Status = [1]
 SyncKey = [2]
 Changes => 
  Count = [0]
 Changes <= 
FolderSync <= 

** Wed Jan 24 2018 16:20:11 GMT-0500 (EST) **
[setSyncState] : State: preparing, Account: rhf@XXXX.XXX, Folder: Calendar

** Wed Jan 24 2018 16:20:11 GMT-0500 (EST) **
[checkCalender] : 1.74

** Wed Jan 24 2018 16:20:11 GMT-0500 (EST) **
[setSyncState] : State: requestingchanges, Account: rhf@XXXX.XXX, Folder: Calendar

** Wed Jan 24 2018 16:20:11 GMT-0500 (EST) **
[Sending data <requestingchanges> for rhf@XXXX.XXX (Calendar) (WBXML)] : 
3 1 6a 0 45 5c 4f 4b 3 32 30 32 37 39 38 37 33 33 38 0 1 52 3 37 34 0 1 5e 3 31 0 1 53 3 31 0 1 55 3 31 30 30 0 1 57 0 11 45 46 3 31 0 1 1 0 0 1 1 1 1

** Wed Jan 24 2018 16:20:11 GMT-0500 (EST) **
[Sending data <requestingchanges> for rhf@XXXX.XXX (Calendar) (XML)] : 
<?xml version="1.0"?>
<Sync xmlns='AirSync'>
<Collections>
<Collection>
<SyncKey>2027987338</SyncKey>
<CollectionId>74</CollectionId>
<DeletesAsMoves>1</DeletesAsMoves>
<GetChanges>1</GetChanges>
<WindowSize>100</WindowSize>
<Options>
<BodyPreference xmlns='AirSyncBase'>
<Type xmlns='AirSyncBase'>1</Type>
</BodyPreference>
</Options>
</Collection>
</Collections>
</Sync>

** Wed Jan 24 2018 16:20:11 GMT-0500 (EST) **
[sending] : POST https://activesync.XXXX.XXX/Microsoft-Server-ActiveSync?Cmd=Sync&User=rhf%40XXXX.XXX&DeviceType=Thunderbird&DeviceId=mztb10c6ce77f6b99007bfc3fd28fe6b

** Wed Jan 24 2018 16:20:11 GMT-0500 (EST) **
[PolicyKey used] : 929673007

** Wed Jan 24 2018 16:20:11 GMT-0500 (EST) **
[Receiving data <requestingchanges> for rhf@XXXX.XXX (Calendar) (WBXML)] : 

** Wed Jan 24 2018 16:20:11 GMT-0500 (EST) **
[Receiving data <requestingchanges> for rhf@XXXX.XXX (Calendar) (XML)] : 

** Wed Jan 24 2018 16:20:11 GMT-0500 (EST) **
[setSyncState] : State: recievingchanges, Account: rhf@XXXX.XXX, Folder: Calendar

** Wed Jan 24 2018 16:20:11 GMT-0500 (EST) **
[setSyncState] : State: sendingchanges, Account: rhf@XXXX.XXX, Folder: Calendar

** Wed Jan 24 2018 16:20:11 GMT-0500 (EST) **
[finishFolderSync(rhf@XXXX.XXX.Calendar)] : Javascript error! Check debug log for details.

** Wed Jan 24 2018 16:20:11 GMT-0500 (EST) **
[setSyncState] : State: done, Account: rhf@XXXX.XXX

** Wed Jan 24 2018 16:20:11 GMT-0500 (EST) **
[finishAccountSync(rhf@XXXX.XXX)] : Javascript error! Check debug log for details.

** Wed Jan 24 2018 16:20:11 GMT-0500 (EST) **
[setSyncState] : State: accountdone, Account: rhf@XXXX.XXX

** Wed Jan 24 2018 16:20:12 GMT-0500 (EST) **
[JavaScript Error: "recRule.getComponent is not a function" {file: "chrome://tbsync/content/provider/eas/sync.js" line: 223}]
eas.sync.getItemRecurrence@chrome://tbsync/content/provider/eas/sync.js:223:33
eas.sync.Calendar.getWbxmlFromThunderbirdItem@chrome://tbsync/content/provider/eas/calendarsync.js:247:26
eas.sync.sendLocalChanges<@chrome://tbsync/content/provider/eas/sync.js:552:62
TaskImpl_run@resource://gre/modules/Task.jsm:319:42

** Wed Jan 24 2018 16:21:43 GMT-0500 (EST) **
[JavaScript Error: "2018-01-24 16:21:43.600 exquilla.native ERROR   machine error: taskCopyItems responseCode:taskCopyItems aMessageText: <javascript error: 2147942487
 line: undefined 
JS frame :: resource://exquilla/ewsUtils.jsm :: se :: line 85 
JS frame :: resource://exquilla/EwsNativeMachine.jsm :: taskCopyItems :: line 518 
JS frame :: resource://gre/modules/Task.jsm :: TaskImpl_run :: line 336 
>" {file: "resource://gre/modules/Log.jsm" line: 753}]
App_append@resource://gre/modules/Log.jsm:753:9
Logger.prototype.log@resource://gre/modules/Log.jsm:389:7
Logger.prototype.error@resource://gre/modules/Log.jsm:397:5
_machineErrorResponse@resource://exquilla/EwsNativeMachine.jsm:1982:5
taskCopyItems@resource://exquilla/EwsNativeMachine.jsm:518:18
TaskImpl_run@resource://gre/modules/Task.jsm:336:41

** Wed Jan 24 2018 16:26:44 GMT-0500 (EST) **
[setSyncState] : State: syncing, Account: rhf@XXXX.XXX

** Wed Jan 24 2018 16:26:44 GMT-0500 (EST) **
[setSyncState] : State: requestingfolders, Account: rhf@XXXX.XXX

** Wed Jan 24 2018 16:26:44 GMT-0500 (EST) **
[Sending data <requestingfolders> for rhf@XXXX.XXX (WBXML)] : 
3 1 6a 0 0 7 56 52 3 32 0 1 1

** Wed Jan 24 2018 16:26:44 GMT-0500 (EST) **
[Sending data <requestingfolders> for rhf@XXXX.XXX (XML)] : 
<?xml version="1.0"?>
<FolderSync xmlns='FolderHierarchy'>
<SyncKey>2</SyncKey>
</FolderSync>

** Wed Jan 24 2018 16:26:44 GMT-0500 (EST) **
[sending] : POST https://activesync.XXXX.XXX/Microsoft-Server-ActiveSync?Cmd=FolderSync&User=rhf%40XXXX.XXX&DeviceType=Thunderbird&DeviceId=mztb10c6ce77f6b99007bfc3fd28fe6b

** Wed Jan 24 2018 16:26:44 GMT-0500 (EST) **
[PolicyKey used] : 929673007

** Wed Jan 24 2018 16:26:44 GMT-0500 (EST) **
[JavaScript Error: "activesync.XXXX.XXX : server does not support RFC 5746, see CVE-2009-3555"]

** Wed Jan 24 2018 16:26:44 GMT-0500 (EST) **
[Receiving data <requestingfolders> for rhf@XXXX.XXX (WBXML)] : 
3 1 6a 0 0 7 56 4c 3 31 0 1 52 3 32 0 1 4e 57 3 30 0 1 1 1

** Wed Jan 24 2018 16:26:44 GMT-0500 (EST) **
[Receiving data <requestingfolders> for rhf@XXXX.XXX (XML)] : 
<?xml version="1.0"?>
<FolderSync xmlns='FolderHierarchy'>
<Status>1</Status>
<SyncKey>2</SyncKey>
<Changes>
<Count>0</Count>
</Changes>
</FolderSync>

** Wed Jan 24 2018 16:26:44 GMT-0500 (EST) **
[Extracted XML data] : 
FolderSync => 
 Status = [1]
 SyncKey = [2]
 Changes => 
  Count = [0]
 Changes <= 
FolderSync <= 

** Wed Jan 24 2018 16:26:44 GMT-0500 (EST) **
[setSyncState] : State: preparing, Account: rhf@XXXX.XXX, Folder: Calendar

** Wed Jan 24 2018 16:26:44 GMT-0500 (EST) **
[checkCalender] : 1.74

** Wed Jan 24 2018 16:26:44 GMT-0500 (EST) **
[setSyncState] : State: requestingchanges, Account: rhf@XXXX.XXX, Folder: Calendar

** Wed Jan 24 2018 16:26:44 GMT-0500 (EST) **
[Sending data <requestingchanges> for rhf@XXXX.XXX (Calendar) (WBXML)] : 
3 1 6a 0 45 5c 4f 4b 3 32 30 32 37 39 38 37 33 33 38 0 1 52 3 37 34 0 1 5e 3 31 0 1 53 3 31 0 1 55 3 31 30 30 0 1 57 0 11 45 46 3 31 0 1 1 0 0 1 1 1 1

** Wed Jan 24 2018 16:26:44 GMT-0500 (EST) **
[Sending data <requestingchanges> for rhf@XXXX.XXX (Calendar) (XML)] : 
<?xml version="1.0"?>
<Sync xmlns='AirSync'>
<Collections>
<Collection>
<SyncKey>2027987338</SyncKey>
<CollectionId>74</CollectionId>
<DeletesAsMoves>1</DeletesAsMoves>
<GetChanges>1</GetChanges>
<WindowSize>100</WindowSize>
<Options>
<BodyPreference xmlns='AirSyncBase'>
<Type xmlns='AirSyncBase'>1</Type>
</BodyPreference>
</Options>
</Collection>
</Collections>
</Sync>

** Wed Jan 24 2018 16:26:44 GMT-0500 (EST) **
[sending] : POST https://activesync.XXXX.XXX/Microsoft-Server-ActiveSync?Cmd=Sync&User=rhf%40XXXX.XXX&DeviceType=Thunderbird&DeviceId=mztb10c6ce77f6b99007bfc3fd28fe6b

** Wed Jan 24 2018 16:26:44 GMT-0500 (EST) **
[PolicyKey used] : 929673007

** Wed Jan 24 2018 16:26:44 GMT-0500 (EST) **
[Receiving data <requestingchanges> for rhf@XXXX.XXX (Calendar) (WBXML)] : 

** Wed Jan 24 2018 16:26:44 GMT-0500 (EST) **
[Receiving data <requestingchanges> for rhf@XXXX.XXX (Calendar) (XML)] : 

** Wed Jan 24 2018 16:26:44 GMT-0500 (EST) **
[setSyncState] : State: recievingchanges, Account: rhf@XXXX.XXX, Folder: Calendar

** Wed Jan 24 2018 16:26:44 GMT-0500 (EST) **
[setSyncState] : State: sendingchanges, Account: rhf@XXXX.XXX, Folder: Calendar

** Wed Jan 24 2018 16:26:44 GMT-0500 (EST) **
[finishFolderSync(rhf@XXXX.XXX.Calendar)] : Javascript error! Check debug log for details.

** Wed Jan 24 2018 16:26:44 GMT-0500 (EST) **
[setSyncState] : State: done, Account: rhf@XXXX.XXX

** Wed Jan 24 2018 16:26:44 GMT-0500 (EST) **
[finishAccountSync(rhf@XXXX.XXX)] : Javascript error! Check debug log for details.

** Wed Jan 24 2018 16:26:44 GMT-0500 (EST) **
[setSyncState] : State: accountdone, Account: rhf@XXXX.XXX

** Wed Jan 24 2018 16:26:44 GMT-0500 (EST) **
[JavaScript Error: "recRule.getComponent is not a function" {file: "chrome://tbsync/content/provider/eas/sync.js" line: 223}]
eas.sync.getItemRecurrence@chrome://tbsync/content/provider/eas/sync.js:223:33
eas.sync.Calendar.getWbxmlFromThunderbirdItem@chrome://tbsync/content/provider/eas/calendarsync.js:247:26
eas.sync.sendLocalChanges<@chrome://tbsync/content/provider/eas/sync.js:552:62
TaskImpl_run@resource://gre/modules/Task.jsm:319:42
jobisoft commented 6 years ago

Please try again with beta7.

ricleal commented 6 years ago

It fails with a different message..

image

Here's the log (removed attendees and text):

** Wed Jan 24 2018 17:08:43 GMT-0500 (EST) **
[Init] : Please send this log to john.bieling@gmx.de, if you have encountered an error.

** Wed Jan 24 2018 17:08:43 GMT-0500 (EST) **
[Thunderbird] : 52.5.0 on Linux

** Wed Jan 24 2018 17:08:43 GMT-0500 (EST) **
[PROVIDER] : eas::Exchange Active Sync

** Wed Jan 24 2018 17:08:43 GMT-0500 (EST) **
[PROVIDER] : dav::CalDAV/CardDAV (sabre/dav, ownCloud, Nextcloud)

** Wed Jan 24 2018 17:08:43 GMT-0500 (EST) **
[Active AddOn] : Default (52.5.0)

** Wed Jan 24 2018 17:08:43 GMT-0500 (EST) **
[Active AddOn] : English (GB) Language Pack (52.5.0)

** Wed Jan 24 2018 17:08:43 GMT-0500 (EST) **
[Active AddOn] : Messaging Menu and Unity Launcher integration (1.3.1)

** Wed Jan 24 2018 17:08:43 GMT-0500 (EST) **
[Active AddOn] : ExQuilla for Microsoft Exchange (52.3.1200)

** Wed Jan 24 2018 17:08:43 GMT-0500 (EST) **
[Active AddOn] : Lightning (5.4)

** Wed Jan 24 2018 17:08:43 GMT-0500 (EST) **
[Active AddOn] : Thunderbird Conversations (2.13.4)

** Wed Jan 24 2018 17:08:43 GMT-0500 (EST) **
[Active AddOn] : Markdown Here (2.13.2)

** Wed Jan 24 2018 17:08:43 GMT-0500 (EST) **
[Active AddOn] : TbSync (Provider for Exchange ActiveSync) (0.6.9.2-beta7)

** Wed Jan 24 2018 17:08:43 GMT-0500 (EST) **
[Active AddOn] : Shockwave Flash (28.0.0.137)

[....]

** Wed Jan 24 2018 17:08:58 GMT-0500 (EST) **
[setSyncState] : State: prepare.request.localchanges, Account: rhf@XXXX.XXX, Folder: Calendar

** Wed Jan 24 2018 17:08:58 GMT-0500 (EST) **
[Ignoring rule] : RDATE;VALUE=DATE-TIME:20180118T200000Z

** Wed Jan 24 2018 17:08:58 GMT-0500 (EST) **
[setSyncState] : State: send.request.localchanges, Account: rhf@XXXX.XXX, Folder: Calendar

** Wed Jan 24 2018 17:08:58 GMT-0500 (EST) **
[Sending data <send.request.localchanges> for rhf@XXXX.XXX (Calendar) (WBXML)] : 

(**************)

** Wed Jan 24 2018 17:08:58 GMT-0500 (EST) **
[Sending data <send.request.localchanges> for rhf@XXXX.XXX (Calendar) (XML)] : 
<?xml version="1.0"?>
<Sync xmlns='AirSync'>
<Collections>
<Collection>
<SyncKey>2027987338</SyncKey>
<CollectionId>74</CollectionId>
<Commands>
<Add>
<ClientId>040000008200E00074C5B7101A82E0080000000030A4DCBCB557D301000000000000000010000000F7F0AEF2E6892E42B4201E3E1171E65B</ClientId>
<ApplicationData>
<UID xmlns='Calendar'>040000008200E00074C5B7101A82E0080000000030A4DCBCB557D301000000000000000010000000F7F0AEF2E6892E42B4201E3E1171E65B</UID>
<TimeZone xmlns='Calendar'>LAEAACgARwBNAFQALQAwADUAOgAwADAAKQAgAEEAbQBlAHIAaQBjAGEALwBOAGUAdwBfAFkAbwByAGsAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAACgARwBNAFQALQAwADUAOgAwADAAKQAgAEEAbQBlAHIAaQBjAGEALwBOAGUAdwBfAFkAbwByAGsAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA==</TimeZone>
<StartTime xmlns='Calendar'>20180118T200000Z</StartTime>
<EndTime xmlns='Calendar'>20180118T200000Z</EndTime>
<DtStamp xmlns='Calendar'>20180117T163310Z</DtStamp>
<Subject xmlns='Calendar'/>
<Location xmlns='Calendar'/>
<Categories xmlns='Calendar'/>
<Attendees xmlns='Calendar'/>
<Exceptions xmlns='Calendar'>
<Exception xmlns='Calendar'>
<ExceptionStartTime xmlns='Calendar'>20180118T200000Z</ExceptionStartTime>
<StartTime xmlns='Calendar'>20180118T200000Z</StartTime>
<EndTime xmlns='Calendar'>20180118T210000Z</EndTime>
<DtStamp xmlns='Calendar'>20180117T163343Z</DtStamp>
<Subject xmlns='Calendar'>Monthly Meeting for the ECS initiative</Subject>
<Location xmlns='Calendar'>RES: CR 8600 RM C464 (Polycom)</Location>

<Body xmlns='AirSyncBase'>
<Type xmlns='AirSyncBase'>1</Type>
<EstimatedDataSize xmlns='AirSyncBase'>130</EstimatedDataSize>
<Data xmlns='AirSyncBase'>Hi all:
The next meeting for the ECS initiative is scheduled for tomorrow from 3  4 PM at the SNS in room 464.

Regards,
Adam

</Data>
</Body>
<BusyStatus xmlns='Calendar'>2</BusyStatus>
<Sensitivity xmlns='Calendar'>0</Sensitivity>
<AllDayEvent xmlns='Calendar'>0</AllDayEvent>
<MeetingStatus xmlns='Calendar'>3</MeetingStatus>
</Exception>
</Exceptions>
<Body xmlns='AirSyncBase'>
<Type xmlns='AirSyncBase'>1</Type>
<EstimatedDataSize xmlns='AirSyncBase'>0</EstimatedDataSize>
<Data xmlns='AirSyncBase'/>
</Body>
<BusyStatus xmlns='Calendar'>1</BusyStatus>
<Sensitivity xmlns='Calendar'>1</Sensitivity>
<AllDayEvent xmlns='Calendar'>0</AllDayEvent>
<MeetingStatus xmlns='Calendar'>1</MeetingStatus>
</ApplicationData>
</Add>

[...]

** Wed Jan 24 2018 17:08:58 GMT-0500 (EST) **
[sending] : POST https://activesync.XXXX.XXX/Microsoft-Server-ActiveSync?Cmd=Sync&User=rhf%40XXXX.XXX&DeviceType=Thunderbird&DeviceId=mztb10c6ce77f6b99007bfc3fd28fe6b

** Wed Jan 24 2018 17:08:58 GMT-0500 (EST) **
[PolicyKey used] : 929673007

** Wed Jan 24 2018 17:08:58 GMT-0500 (EST) **
[Receiving data <send.request.localchanges> for rhf@XXXX.XXX (Calendar) (WBXML)] : 
3 1 6a 0 45 4e 3 34 0 1 1

** Wed Jan 24 2018 17:08:58 GMT-0500 (EST) **
[Receiving data <send.request.localchanges> for rhf@XXXX.XXX (Calendar) (XML)] : 
<?xml version="1.0"?>
<Sync xmlns='AirSync'>
<Status>4</Status>
</Sync>

** Wed Jan 24 2018 17:08:58 GMT-0500 (EST) **
[setSyncState] : State: eval.response.localchanges, Account: rhf@XXXX.XXX, Folder: Calendar

** Wed Jan 24 2018 17:08:58 GMT-0500 (EST) **
[Extracted XML data] : 
Sync => 
 Status = [4]
Sync <= 

** Wed Jan 24 2018 17:08:58 GMT-0500 (EST) **
[wbxml status check] : Sync: Sync.Status = 4

** Wed Jan 24 2018 17:08:58 GMT-0500 (EST) **
[wbxml status] : Server reports unhandled status <Sync.Status = 4>. Aborting Sync.

** Wed Jan 24 2018 17:08:58 GMT-0500 (EST) **
[finishFolderSync(rhf@XXXX.XXX.Calendar)] : Sync failed. Server responds with status <Sync.Status = 4>.

** Wed Jan 24 2018 17:08:58 GMT-0500 (EST) **
[setSyncState] : State: done, Account: rhf@XXXX.XXX

** Wed Jan 24 2018 17:08:58 GMT-0500 (EST) **
[finishAccountSync(rhf@XXXX.XXX)] : Sync failed. Server responds with status <Sync.Status = 4>.

** Wed Jan 24 2018 17:08:58 GMT-0500 (EST) **
[setSyncState] : State: accountdone, Account: rhf@XXXX.XXX
jobisoft commented 6 years ago

Why is he trying to add tons of new items to the server, if you just changed one? Can you please start from scratch? Disconnect account and reconnect. And than make a change to the calendar and sync.

ChrisJAllan commented 6 years ago

The event on 2018-01-18 (Monthly Meeting for the ECS initiative) is the issue. It doesn't have a recurrence rule, only a date (which is ignored), and then an exception for that event, which fails as there is no recurrence to modify. I have no idea how an event ended up this mangled or how to deal with it. Was that one created in Thunderbird or somewhere else?

ricleal commented 6 years ago

It works now :) Disconnected the account and reconnected it again. I didn't do it before because I thought that I would lost the cache (12h synchronising all the stuff...). No more duplicated stuff... Thanks! Looks like it's up and running.

jobisoft commented 6 years ago

@ricleal Can you re-add the event with the recurrence we are talking about? What happens if you try to sync after that?

ChrisJAllan commented 6 years ago

@ricleal Does that event actually occur monthly? There doesn't seem to be any monthly rule on it. It seems the best solution would be to delete and re-create that event.

ricleal commented 6 years ago

If I create a bi-weekly event I have the same problem :(


** Wed Jan 24 2018 17:41:26 GMT-0500 (EST) **
[wbxml status check] : Sync: Sync.Collections.Collection.Responses.Add[0].Status = 6

** Wed Jan 24 2018 17:41:26 GMT-0500 (EST) **
[wbxml status] : Server reports unhandled status <Sync.Collections.Collection.Responses.Add[0].Status = 6>. Aborting Sync.

** Wed Jan 24 2018 17:41:26 GMT-0500 (EST) **
[finishFolderSync(ORNL.Calendar)] : Sync failed. Server responds with status <Sync.Collections.Collection.Responses.Add[0].Status = 6>.

** Wed Jan 24 2018 17:41:26 GMT-0500 (EST) **
[setSyncState] : State: done, Account: ORNL

** Wed Jan 24 2018 17:41:26 GMT-0500 (EST) **
[finishAccountSync(ORNL)] : Sync failed. Server responds with status <Sync.Collections.Collection.Responses.Add[0].Status = 6>.

** Wed Jan 24 2018 17:41:26 GMT-0500 (EST) **
[setSyncState] : State: accountdone, Account: XXX
jobisoft commented 6 years ago

full log please

ricleal commented 6 years ago

Monthly is OK!

ricleal commented 6 years ago

Don't ask me why, it works now with bi-weekly, weekly, etc...

jobisoft commented 6 years ago

no, that is a bug in tbSync. After he failed to sync the "bad" event, he forgets about it and next time you sync, he does not include it. But it has not yet been synced

Please start from scratch again, add a bi-weekly meeting. Restart TB (to clear the log) and sync and send the full log of the error.

ChrisJAllan commented 6 years ago

Make sure it actually shows up on the remote server before saying "it works."

ricleal commented 6 years ago

I can't repeat the error. everything is OK. The remote calendar is updated after the synchronisation for any type of recurring events. I will update this issue if the problem arises again.

jobisoft commented 6 years ago

I am not able to reproduce the bad event stated in the log, starting from a be-weekly recurrence pattern. This is so strange...

jobisoft commented 6 years ago

Please update to beta1, it contains a few more sanity check.

jobisoft commented 6 years ago

Can this issue be considered as solved?

ricleal commented 6 years ago

I have had no further issues. From my side yes, you can close the issue. Thanks again for everything!

ricleal commented 6 years ago

Sorry to open this again.. The error came back with a new event... Here the debug:

** Thu Feb 01 2018 16:58:29 GMT-0500 (EST) **
[Init] : Please send this log to john.bieling@gmx.de, if you have encountered an error.

** Thu Feb 01 2018 16:58:29 GMT-0500 (EST) **
[Thunderbird] : 52.6.0 on Linux

** Thu Feb 01 2018 16:58:29 GMT-0500 (EST) **
[PROVIDER] : eas::Exchange Active Sync

** Thu Feb 01 2018 16:58:29 GMT-0500 (EST) **
[PROVIDER] : dav::CalDAV/CardDAV (sabre/dav, ownCloud, Nextcloud)

** Thu Feb 01 2018 16:58:29 GMT-0500 (EST) **
[Active AddOn] : ExQuilla for Microsoft Exchange (52.3.1200)

** Thu Feb 01 2018 16:58:29 GMT-0500 (EST) **
[Active AddOn] : Lightning (5.4)

** Thu Feb 01 2018 16:58:29 GMT-0500 (EST) **
[Active AddOn] : Thunderbird Conversations (2.13.4)

** Thu Feb 01 2018 16:58:29 GMT-0500 (EST) **
[Active AddOn] : Markdown Here (2.13.2)

** Thu Feb 01 2018 16:58:29 GMT-0500 (EST) **
[Active AddOn] : TbSync (Provider for Exchange ActiveSync) (0.7.3)

** Thu Feb 01 2018 16:58:29 GMT-0500 (EST) **
[Active AddOn] : Default (52.6.0)

** Thu Feb 01 2018 16:58:29 GMT-0500 (EST) **
[Active AddOn] : English (GB) Language Pack (52.6.0)

** Thu Feb 01 2018 16:58:29 GMT-0500 (EST) **
[Active AddOn] : Messaging Menu and Unity Launcher integration (1.3.1)

** Thu Feb 01 2018 16:58:29 GMT-0500 (EST) **
[Active AddOn] : Shockwave Flash (28.0.0.137)

** Thu Feb 01 2018 16:58:32 GMT-0500 (EST) **
[JavaScript Error: "TypeError: tab is undefined" {file: "chrome://messenger/content/tabmail.xml" line: 1070}]
getBrowserForSelectedTab@chrome://messenger/content/tabmail.xml:1070:15
getBrowser@chrome://messenger/content/mailWindow.js:548:20
messagePaneOnResize@chrome://communicator/content/contentAreaClick.js:60:17
onresize@chrome://messenger/content/messenger.xul:1:8

** Thu Feb 01 2018 16:58:35 GMT-0500 (EST) **
[TbSync init] : start

** Thu Feb 01 2018 16:58:35 GMT-0500 (EST) **
[INIT] : DB

** Thu Feb 01 2018 16:58:37 GMT-0500 (EST) **
[setSyncState] : State: accountdone, Account: xxxx

** Thu Feb 01 2018 16:58:37 GMT-0500 (EST) **
[TbSync init] : done

** Thu Feb 01 2018 16:58:56 GMT-0500 (EST) **
[calendarObserver::onPropertyChanged] : <calendar-main-default> changed from <true> to <false>

** Thu Feb 01 2018 16:58:56 GMT-0500 (EST) **
[calendarObserver::onPropertyChanged] : <calendar-main-default> changed from <null> to <true>

** Thu Feb 01 2018 16:58:58 GMT-0500 (EST) **
[calendarObserver::onPropertyChanged] : <calendar-main-default> changed from <true> to <false>

** Thu Feb 01 2018 16:58:58 GMT-0500 (EST) **
[calendarObserver::onPropertyChanged] : <calendar-main-default> changed from <null> to <true>

** Thu Feb 01 2018 16:59:10 GMT-0500 (EST) **
[calendarObserver::onPropertyChanged] : <imip.identity.key> changed from <> to <id2>

** Thu Feb 01 2018 16:59:10 GMT-0500 (EST) **
[calendarObserver::onPropertyChanged] : <auto-enabled> changed from <true> to <false>

** Thu Feb 01 2018 16:59:19 GMT-0500 (EST) **
[JavaScript Error: "uncaught exception: 2147500033" {file: "jar:file:///home/xxx/.thunderbird/en7zwitk.default/extensions/exquilla@mesquilla.com.xpi!/components/ewsProtocolComponent.js" line: 788}]

** Thu Feb 01 2018 16:59:19 GMT-0500 (EST) **
[JavaScript Error: "uncaught exception: 2147500033" {file: "jar:file:///home/xxx/.thunderbird/en7zwitk.default/extensions/exquilla@mesquilla.com.xpi!/components/ewsProtocolComponent.js" line: 788}]

** Thu Feb 01 2018 17:00:48 GMT-0500 (EST) **
[setSyncState] : State: syncing, Account: xxxx

** Thu Feb 01 2018 17:00:48 GMT-0500 (EST) **
[setSyncState] : State: prepare.request.folders, Account: xxxx

** Thu Feb 01 2018 17:00:48 GMT-0500 (EST) **
[setSyncState] : State: send.request.folders, Account: xxxx

** Thu Feb 01 2018 17:00:48 GMT-0500 (EST) **
[Sending data <send.request.folders> for xxxx (WBXML)] : 
3 1 6a 0 0 7 56 52 3 31 0 1 1

** Thu Feb 01 2018 17:00:48 GMT-0500 (EST) **
[Sending data <send.request.folders> for xxxx (XML)] : 
<?xml version="1.0"?>
<FolderSync xmlns='FolderHierarchy'>
<SyncKey>1</SyncKey>
</FolderSync>

** Thu Feb 01 2018 17:00:48 GMT-0500 (EST) **
[Sending (EAS v14.0)] : POST https://activesync.xxxx.gov/Microsoft-Server-ActiveSync?Cmd=FolderSync&User=xxx%40xxxx.gov&DeviceType=Thunderbird&DeviceId=mztba276ee3b44ccc04b854f907959f2

** Thu Feb 01 2018 17:00:48 GMT-0500 (EST) **
[JavaScript Error: "activesync.xxxx.gov : server does not support RFC 5746, see CVE-2009-3555"]

** Thu Feb 01 2018 17:00:48 GMT-0500 (EST) **
[Receiving data <send.request.folders> for xxxx (WBXML)] : 
3 1 6a 0 0 7 56 4c 3 31 0 1 52 3 31 0 1 4e 57 3 30 0 1 1 1

** Thu Feb 01 2018 17:00:48 GMT-0500 (EST) **
[Receiving data <send.request.folders> for xxxx (XML)] : 
<?xml version="1.0"?>
<FolderSync xmlns='FolderHierarchy'>
<Status>1</Status>
<SyncKey>1</SyncKey>
<Changes>
<Count>0</Count>
</Changes>
</FolderSync>

** Thu Feb 01 2018 17:00:48 GMT-0500 (EST) **
[setSyncState] : State: eval.response.folders, Account: xxxx

** Thu Feb 01 2018 17:00:48 GMT-0500 (EST) **
[Extracted XML data] : 
FolderSync => 
 Status = [1]
 SyncKey = [1]
 Changes => 
  Count = [0]
 Changes <= 
FolderSync <= 

** Thu Feb 01 2018 17:00:48 GMT-0500 (EST) **
[setSyncState] : State: preparing, Account: xxxx, Folder: Calendar

** Thu Feb 01 2018 17:00:48 GMT-0500 (EST) **
[checkCalender] : 2.77

** Thu Feb 01 2018 17:00:48 GMT-0500 (EST) **
[setSyncState] : State: prepare.request.estimate, Account: xxxx, Folder: Calendar

** Thu Feb 01 2018 17:00:48 GMT-0500 (EST) **
[setSyncState] : State: send.request.estimate, Account: xxxx, Folder: Calendar

** Thu Feb 01 2018 17:00:48 GMT-0500 (EST) **
[Sending data <send.request.estimate> for xxxx (Calendar) (WBXML)] : 
3 1 6a 0 0 6 45 47 48 0 0 4b 3 32 37 32 36 36 34 39 37 32 0 1 0 6 4a 3 37 37 0 1 0 0 57 50 3 43 61 6c 65 6e 64 61 72 0 1 58 3 37 0 1 1 0 6 1 1 1

** Thu Feb 01 2018 17:00:48 GMT-0500 (EST) **
[Sending data <send.request.estimate> for xxxx (Calendar) (XML)] : 
<?xml version="1.0"?>
<GetItemEstimate xmlns='GetItemEstimate'>
<Collections>
<Collection>
<SyncKey xmlns='AirSync'>272664972</SyncKey>
<CollectionId>77</CollectionId>
<Options xmlns='AirSync'>
<Class xmlns='AirSync'>Calendar</Class>
<FilterType xmlns='AirSync'>7</FilterType>
</Options>
</Collection>
</Collections>
</GetItemEstimate>

** Thu Feb 01 2018 17:00:48 GMT-0500 (EST) **
[Sending (EAS v14.0)] : POST https://activesync.xxxx.gov/Microsoft-Server-ActiveSync?Cmd=GetItemEstimate&User=xxx%40xxxx.gov&DeviceType=Thunderbird&DeviceId=mztba276ee3b44ccc04b854f907959f2

** Thu Feb 01 2018 17:00:49 GMT-0500 (EST) **
[Receiving data <send.request.estimate> for xxxx (Calendar) (WBXML)] : 
3 1 6a 0 0 6 45 4d 4e 3 31 0 1 48 4a 3 37 37 0 1 4c 3 30 0 1 1 1 1

** Thu Feb 01 2018 17:00:49 GMT-0500 (EST) **
[Receiving data <send.request.estimate> for xxxx (Calendar) (XML)] : 
<?xml version="1.0"?>
<GetItemEstimate xmlns='GetItemEstimate'>
<Response>
<Status>1</Status>
<Collection>
<CollectionId>77</CollectionId>
<Estimate>0</Estimate>
</Collection>
</Response>
</GetItemEstimate>

** Thu Feb 01 2018 17:00:49 GMT-0500 (EST) **
[setSyncState] : State: eval.response.estimate, Account: xxxx, Folder: Calendar

** Thu Feb 01 2018 17:00:49 GMT-0500 (EST) **
[Extracted XML data] : 
GetItemEstimate => 
 Response => 
  Status = [1]
  Collection => 
   CollectionId = [77]
   Estimate = [0]
  Collection <= 
 Response <= 
GetItemEstimate <= 

** Thu Feb 01 2018 17:00:49 GMT-0500 (EST) **
[setSyncState] : State: prepare.request.remotechanges, Account: xxxx, Folder: Calendar

** Thu Feb 01 2018 17:00:49 GMT-0500 (EST) **
[setSyncState] : State: send.request.remotechanges, Account: xxxx, Folder: Calendar

** Thu Feb 01 2018 17:00:49 GMT-0500 (EST) **
[Sending data <send.request.remotechanges> for xxxx (Calendar) (WBXML)] : 
3 1 6a 0 45 5c 4f 4b 3 32 37 32 36 36 34 39 37 32 0 1 52 3 37 37 0 1 5e 3 31 0 1 53 3 31 0 1 55 3 35 30 0 1 57 58 3 37 0 1 0 11 45 46 3 31 0 1 1 0 0 1 1 1 1

** Thu Feb 01 2018 17:00:49 GMT-0500 (EST) **
[Sending data <send.request.remotechanges> for xxxx (Calendar) (XML)] : 
<?xml version="1.0"?>
<Sync xmlns='AirSync'>
<Collections>
<Collection>
<SyncKey>272664972</SyncKey>
<CollectionId>77</CollectionId>
<DeletesAsMoves>1</DeletesAsMoves>
<GetChanges>1</GetChanges>
<WindowSize>50</WindowSize>
<Options>
<FilterType>7</FilterType>
<BodyPreference xmlns='AirSyncBase'>
<Type xmlns='AirSyncBase'>1</Type>
</BodyPreference>
</Options>
</Collection>
</Collections>
</Sync>

** Thu Feb 01 2018 17:00:49 GMT-0500 (EST) **
[Sending (EAS v14.0)] : POST https://activesync.xxxx.gov/Microsoft-Server-ActiveSync?Cmd=Sync&User=xxx%40xxxx.gov&DeviceType=Thunderbird&DeviceId=mztba276ee3b44ccc04b854f907959f2

** Thu Feb 01 2018 17:00:49 GMT-0500 (EST) **
[Receiving data <send.request.remotechanges> for xxxx (Calendar) (WBXML)] : 

** Thu Feb 01 2018 17:00:49 GMT-0500 (EST) **
[Receiving data <send.request.remotechanges> for xxxx (Calendar) (XML)] : 

** Thu Feb 01 2018 17:00:49 GMT-0500 (EST) **
[setSyncState] : State: eval.response.remotechanges, Account: xxxx, Folder: Calendar

** Thu Feb 01 2018 17:00:49 GMT-0500 (EST) **
[setSyncState] : State: prepare.request.localchanges, Account: xxxx, Folder: Calendar

** Thu Feb 01 2018 17:00:49 GMT-0500 (EST) **
[setSyncState] : State: send.request.localchanges, Account: xxxx, Folder: Calendar

** Thu Feb 01 2018 17:00:49 GMT-0500 (EST) **
[Sending data <send.request.localchanges> for xxxx (Calendar) (WBXML)] : 
3 1 6a 0 45 5c 4f 4b 3 32 37 32 36 36 34 39 37 32 0 1 52 3 37 37 0 1 56 47 4c 3 30 34 30 30 30 30 30 30 38 32 30 30 45 0 1 64 3 31 30 0 1 58 3 33 0 1 0 0 1 1 1 1 1 1

** Thu Feb 01 2018 17:00:49 GMT-0500 (EST) **
[Sending data <send.request.localchanges> for xxxx (Calendar) (XML)] : 
<?xml version="1.0"?>
<Sync xmlns='AirSync'>
<Collections>
<Collection>
<SyncKey>272664972</SyncKey>
<CollectionId>77</CollectionId>
<Commands>
<Add>
<ClientId>040000008200E00074C5B7101A82E00800000000F0B1A952789BD3010000000000000000100000001CFCB99C99B653459AD7A37F03F29C61</ClientId>
<ApplicationData>
<UID xmlns='Calendar'>040000008200E00074C5B7101A82E00800000000F0B1A952789BD3010000000000000000100000001CFCB99C99B653459AD7A37F03F29C61</UID>
<TimeZone xmlns='Calendar'>LAEAACgARwBNAFQALQAwADUAOgAwADAAKQAgAEEAbQBlAHIAaQBjAGEALwBOAGUAdwBfAFkAbwByAGsAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAACgARwBNAFQALQAwADUAOgAwADAAKQAgAEEAbQBlAHIAaQBjAGEALwBOAGUAdwBfAFkAbwByAGsAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA==</TimeZone>
<StartTime xmlns='Calendar'>20180207T140000Z</StartTime>
<EndTime xmlns='Calendar'>20180207T150000Z</EndTime>
<DtStamp xmlns='Calendar'>20180201T215922Z</DtStamp>
<Subject xmlns='Calendar'>NScD Data Assessment--Neutron Data Sciences Staff and Peter Parker</Subject>
<Location xmlns='Calendar'>8600, C152</Location>
<Categories xmlns='Calendar'/>
<OrganizerName xmlns='Calendar'>Sawyer, Titonia K.</OrganizerName>
<OrganizerEmail xmlns='Calendar'>sawyertk@xxxx.gov</OrganizerEmail>
<Attendees xmlns='Calendar'>

</Attendees>
<Body xmlns='AirSyncBase'>
<Type xmlns='AirSyncBase'>1</Type>
<EstimatedDataSize xmlns='AirSyncBase'>1</EstimatedDataSize>
<Data xmlns='AirSyncBase'>
</Data>
</Body>
<BusyStatus xmlns='Calendar'>2</BusyStatus>
<Sensitivity xmlns='Calendar'>0</Sensitivity>
<AllDayEvent xmlns='Calendar'>0</AllDayEvent>
<Reminder xmlns='Calendar'>10</Reminder>
<MeetingStatus xmlns='Calendar'>3</MeetingStatus>
</ApplicationData>
</Add>
<Add>
<ClientId>040000008200E00074C5B7101A82E00800000000F0E85E9E779BD30100000000000000001000000089ABF6F6A3CC4E4CB3FD2D24F8EC2478</ClientId>
<ApplicationData>
<UID xmlns='Calendar'>040000008200E00074C5B7101A82E00800000000F0E85E9E779BD30100000000000000001000000089ABF6F6A3CC4E4CB3FD2D24F8EC2478</UID>
<TimeZone xmlns='Calendar'>LAEAACgARwBNAFQALQAwADUAOgAwADAAKQAgAEEAbQBlAHIAaQBjAGEALwBOAGUAdwBfAFkAbwByAGsAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAACgARwBNAFQALQAwADUAOgAwADAAKQAgAEEAbQBlAHIAaQBjAGEALwBOAGUAdwBfAFkAbwByAGsAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA==</TimeZone>
<StartTime xmlns='Calendar'>20180206T230000Z</StartTime>
<EndTime xmlns='Calendar'>20180207T010000Z</EndTime>
<DtStamp xmlns='Calendar'>20180201T215946Z</DtStamp>
<Subject xmlns='Calendar'>FW: NScD Data Assessment Poster Session</Subject>
<Location xmlns='Calendar'>8600, Lobby</Location>
<Categories xmlns='Calendar'/>
<OrganizerName xmlns='Calendar'>S.</OrganizerName>
<OrganizerEmail xmlns='Calendar'>sawyertk@xxxx.gov</OrganizerEmail>
<Attendees xmlns='Calendar'>
<Attendee xmlns='Calendar'>
<Email xmlns='Calendar'>parkerpg@xxxx.gov</Email>
<Name xmlns='Calendar'>.</Name>
<AttendeeType xmlns='Calendar'>1</AttendeeType>
</Attendee>
<Attendee xmlns='Calendar'>
<Email xmlns='Calendar'>ferrazlealrm@xxxx.gov</Email>
<Name xmlns='Calendar'></Name>
<AttendeeType xmlns='Calendar'>1</AttendeeType>
</Attendee>
</Attendees>
<Body xmlns='AirSyncBase'>
<Type xmlns='AirSyncBase'>1</Type>
<EstimatedDataSize xmlns='AirSyncBase'>2346</EstimatedDataSize>
<Data xmlns='AirSyncBase'>
[id:image001.png@01D3442D.9C93B7E0]

From: "Sawyer, Titonia K." <sawyertk@xxxx.gov>
Date: Thursday, February 1, 2018 at 4:13 PM
To: 
Subject: NScD Data Assessment Poster Session

From: 
Sent: Monday, January 29, 2018 10:16 AM

</Data>
</Body>
<BusyStatus xmlns='Calendar'>2</BusyStatus>
<Sensitivity xmlns='Calendar'>0</Sensitivity>
<AllDayEvent xmlns='Calendar'>0</AllDayEvent>
<Reminder xmlns='Calendar'>10</Reminder>
<MeetingStatus xmlns='Calendar'>3</MeetingStatus>
</ApplicationData>
</Add>
</Commands>
</Collection>
</Collections>
</Sync>

** Thu Feb 01 2018 17:00:49 GMT-0500 (EST) **
[Sending (EAS v14.0)] : POST https://activesync.xxxx.gov/Microsoft-Server-ActiveSync?Cmd=Sync&User=xxx%40xxxx.gov&DeviceType=Thunderbird&DeviceId=mztba276ee3b44ccc04b854f907959f2

** Thu Feb 01 2018 17:00:49 GMT-0500 (EST) **
[Receiving data <send.request.localchanges> for xxxx (Calendar) (WBXML)] : 
3 1 6a 0 45 5c 4f 4b 3 32 37 32 36 36 34 39 37 32 0 1 52 3 37 37 0 1 4e 3 31 0 1 46 47 4c 3 30 34 30 30 30 30 30 30 38 32 30 30 45 30 30 30 37 34 43 35 42 37 31 30 31 41 38 32 45 30 30 38 30 30 30 30 30 30 30 30 46 30 42 31 41 39 35 32 37 38 39 42 44 33 30 31 30 30 30 30 30 30 30 30 30 30 30 30 30 30 30 30 31 30 30 30 30 30 30 30 31 43 46 43 42 39 39 43 39 39 42 36 35 33 34 35 39 41 44 37 41 33 37 46 30 33 46 32 39 43 36 31 0 1 4e 3 36 0 1 1 47 4c 3 30 34 30 30 30 30 30 30 38 32 30 30 45 30 30 30 37 34 43 35 42 37 31 30 31 41 38 32 45 30 30 38 30 30 30 30 30 30 30 30 46 30 45 38 35 45 39 45 37 37 39 42 44 33 30 31 30 30 30 30 30 30 30 30 30 30 30 30 30 30 30 30 31 30 30 30 30 30 30 30 38 39 41 42 46 36 46 36 41 33 43 43 34 45 34 43 42 33 46 44 32 44 32 34 46 38 45 43 32 34 37 38 0 1 4e 3 36 0 1 1 1 1 1 1

** Thu Feb 01 2018 17:00:49 GMT-0500 (EST) **
[Receiving data <send.request.localchanges> for xxxx (Calendar) (XML)] : 
<?xml version="1.0"?>
<Sync xmlns='AirSync'>
<Collections>
<Collection>
<SyncKey>272664972</SyncKey>
<CollectionId>77</CollectionId>
<Status>1</Status>
<Responses>
<Add>
<ClientId>040000008200E00074C5B7101A82E00800000000F0B1A952789BD3010000000000000000100000001CFCB99C99B653459AD7A37F03F29C61</ClientId>
<Status>6</Status>
</Add>
<Add>
<ClientId>040000008200E00074C5B7101A82E00800000000F0E85E9E779BD30100000000000000001000000089ABF6F6A3CC4E4CB3FD2D24F8EC2478</ClientId>
<Status>6</Status>
</Add>
</Responses>
</Collection>
</Collections>
</Sync>

** Thu Feb 01 2018 17:00:49 GMT-0500 (EST) **
[setSyncState] : State: eval.response.localchanges, Account: xxxx, Folder: Calendar

** Thu Feb 01 2018 17:00:49 GMT-0500 (EST) **
[Extracted XML data] : 
Sync => 
 Collections => 
  Collection => 
   SyncKey = [272664972]
   CollectionId = [77]
   Status = [1]
   Responses => 
    Add => 
     0 => 
      ClientId = [040000008200E00074C5B7101A82E00800000000F0B1A952789BD3010000000000000000100000001CFCB99C99B653459AD7A37F03F29C61]
      Status = [6]
     0 <= 
     1 => 
      ClientId = [040000008200E00074C5B7101A82E00800000000F0E85E9E779BD30100000000000000001000000089ABF6F6A3CC4E4CB3FD2D24F8EC2478]
      Status = [6]
     1 <= 
    Add <= 
   Responses <= 
  Collection <= 
 Collections <= 
Sync <= 

** Thu Feb 01 2018 17:00:49 GMT-0500 (EST) **
[wbxml status check] : Sync: Sync.Collections.Collection.Responses.Add[0].Status = 6

** Thu Feb 01 2018 17:00:49 GMT-0500 (EST) **
[wbxml status] : Server reports unhandled status <Sync.Collections.Collection.Responses.Add[0].Status = 6>. Aborting Sync.

** Thu Feb 01 2018 17:00:49 GMT-0500 (EST) **
[finishFolderSync(xxxx.Calendar)] : Sync failed. Server responds with status <Sync.Collections.Collection.Responses.Add[0].Status = 6>.

** Thu Feb 01 2018 17:00:49 GMT-0500 (EST) **
[setSyncState] : State: done, Account: xxxx

** Thu Feb 01 2018 17:00:49 GMT-0500 (EST) **
[finishAccountSync(xxxx)] : Sync failed. Server responds with status <Sync.Collections.Collection.Responses.Add[0].Status = 6>.

** Thu Feb 01 2018 17:00:49 GMT-0500 (EST) **
[setSyncState] : State: accountdone, Account: xxxx
jobisoft commented 6 years ago

That is a different error, I do not see any reference to recRule.getComponent. Please open a new issue for this. You do not need to include the log again