============================    UPLOAD Mobile -> Horde    ============================


Android-log:

[8:45:48] ExchangeService| Starting sync for Calendar
[8:45:48] ExchangeService| Starting thread for Calendar in account anon@anon.tld
[8:45:48] __eas[anon@anon.tld]| Reset, aborting Ping
[8:45:48] Calendar[anon@anon.tld]| SyncKey retrieved as {532378ff-ff68-4eae-a65b-b5ce4e6f48ba}1 from CalendarProvider
[8:45:48] Calendar[anon@anon.tld]| SyncKey retrieved as {532378ff-ff68-4eae-a65b-b5ce4e6f48ba}1 from CalendarProvider
[8:45:48] Calendar[anon@anon.tld]| sync, sending Calendar syncKey: {532378ff-ff68-4eae-a65b-b5ce4e6f48ba}1
[8:45:48] Calendar[anon@anon.tld]| SyncKey retrieved as {532378ff-ff68-4eae-a65b-b5ce4e6f48ba}1 from CalendarProvider
[8:45:48] Calendar[anon@anon.tld]| Sending Calendar changes to the server
[8:45:48] Calendar[anon@anon.tld]| Creating new event with clientId: 66ca3699-3acc-44c0-8990-d1d459e97d95
[8:45:48] CalendarUtility| TZI string for Mitteleuropäische Zeit found in cache.
[8:45:50] Exception| Stack trace follows...
java.lang.ArrayIndexOutOfBoundsException: length=34; index=42
        at com.android.exchange.adapter.Parser.push(Parser.java:426)
        at com.android.exchange.adapter.Parser.getNext(Parser.java:519)
        at com.android.exchange.adapter.Parser.nextTag(Parser.java:308)
        at com.android.exchange.adapter.AbstractSyncParser.parse(AbstractSyncParser.java:126)
        at com.android.exchange.adapter.CalendarSyncAdapter.parse(CalendarSyncAdapter.java:240)
        at com.android.exchange.EasSyncService.sync(EasSyncService.java:2166)
        at com.android.exchange.EasSyncService.run(EasSyncService.java:2413)
        at java.lang.Thread.run(Thread.java:838)
[8:45:50] Calendar[anon@anon.tld]| Sync finished
[8:45:50] Calendar[anon@anon.tld]| Sync ended due to an exception.
[8:45:50] Calendar[anon@anon.tld]| sync finished with reason: 0



ActiveSync-log:

2014-03-24T08:45:47+01:00 INFO: [5950] Horde_Core_ActiveSync_Driver::authenticate() attempt for anon@anon.tld
2014-03-24T08:45:47+01:00 INFO: [5950] SYNC request received for user anon@anon.tld
2014-03-24T08:45:47+01:00 INFO: [5950] Device entry exists for ANDROID1325347202632, updating userAgent and version.
2014-03-24T08:45:47+01:00 INFO: [5950] Request being handled for device: ANDROID1325347202632, Supporting protocol version: 14.1, Using Horde_ActiveSync v2.13.2
2014-03-24T08:45:47+01:00 INFO: [5950] Handling SYNC command.
2014-03-24T08:45:47+01:00 INFO: [5950] Checking policykey for device: ANDROID1325347202632 user: anon@anon.tld
2014-03-24T08:45:47+01:00 INFO: [5950] Policykey: 0 verified.
2014-03-24T08:45:47+01:00 DEBUG: [5950] I  <Synchronize>
2014-03-24T08:45:47+01:00 DEBUG: [5950] I   <Folders>
2014-03-24T08:45:47+01:00 DEBUG: [5950] I    <Folder>
2014-03-24T08:45:47+01:00 DEBUG: [5950] I     <SyncKey>
2014-03-24T08:45:47+01:00 DEBUG: [5950] I       {532378ff-ff68-4eae-a65b-b5ce4e6f48ba}1
2014-03-24T08:45:47+01:00 DEBUG: [5950] I     </SyncKey>
2014-03-24T08:45:47+01:00 DEBUG: [5950] I     <FolderId>
2014-03-24T08:45:47+01:00 DEBUG: [5950] I       @Calendar@
2014-03-24T08:45:47+01:00 DEBUG: [5950] I     </FolderId>
2014-03-24T08:45:47+01:00 DEBUG: [5950] I     <DeletesAsMoves />
2014-03-24T08:45:47+01:00 DEBUG: [5950] I     <GetChanges />
2014-03-24T08:45:47+01:00 DEBUG: [5950] I     <WindowSize>
2014-03-24T08:45:47+01:00 DEBUG: [5950] I       20
2014-03-24T08:45:47+01:00 DEBUG: [5950] I     </WindowSize>
2014-03-24T08:45:47+01:00 DEBUG: [5950] I     <Options>
2014-03-24T08:45:47+01:00 DEBUG: [5950] I      <FilterType>
2014-03-24T08:45:47+01:00 DEBUG: [5950] I        4
2014-03-24T08:45:47+01:00 DEBUG: [5950] I      </FilterType>
2014-03-24T08:45:47+01:00 DEBUG: [5950] I      <AirSyncBase:BodyPreference>
2014-03-24T08:45:47+01:00 DEBUG: [5950] I       <AirSyncBase:Type>
2014-03-24T08:45:47+01:00 DEBUG: [5950] I         1
2014-03-24T08:45:47+01:00 DEBUG: [5950] I       </AirSyncBase:Type>
2014-03-24T08:45:47+01:00 DEBUG: [5950] I       <AirSyncBase:TruncationSize>
2014-03-24T08:45:47+01:00 DEBUG: [5950] I         200000
2014-03-24T08:45:47+01:00 DEBUG: [5950] I       </AirSyncBase:TruncationSize>
2014-03-24T08:45:47+01:00 DEBUG: [5950] I      </AirSyncBase:BodyPreference>
2014-03-24T08:45:47+01:00 DEBUG: [5950] I     </Options>
2014-03-24T08:45:47+01:00 DEBUG: [5950] I     <Commands>
2014-03-24T08:45:47+01:00 INFO: [5950] Obtaining collection class of Calendar for collection id @Calendar@
2014-03-24T08:45:47+01:00 INFO: [5950] Initializing state for collection: @Calendar@, synckey: {532378ff-ff68-4eae-a65b-b5ce4e6f48ba}1
2014-03-24T08:45:47+01:00 INFO: [5950] Loading state for synckey {532378ff-ff68-4eae-a65b-b5ce4e6f48ba}1
2014-03-24T08:45:47+01:00 DEBUG: [5950] I      <Add>
2014-03-24T08:45:47+01:00 DEBUG: [5950] I       <ClientEntryId>
2014-03-24T08:45:47+01:00 DEBUG: [5950] I         66ca3699-3acc-44c0-8990-d1d459e97d95
2014-03-24T08:45:47+01:00 DEBUG: [5950] I       </ClientEntryId>
2014-03-24T08:45:47+01:00 DEBUG: [5950] I       <Data>
2014-03-24T08:45:47+01:00 DEBUG: [5950] I        <POOMCAL:Timezone>
2014-03-24T08:45:47+01:00 DEBUG: [5950] I          [232 bytes of content]
2014-03-24T08:45:47+01:00 DEBUG: [5950] I        </POOMCAL:Timezone>
2014-03-24T08:45:47+01:00 DEBUG: [5950] I        <POOMCAL:AllDayEvent>
2014-03-24T08:45:47+01:00 DEBUG: [5950] I          0
2014-03-24T08:45:47+01:00 DEBUG: [5950] I        </POOMCAL:AllDayEvent>
2014-03-24T08:45:47+01:00 DEBUG: [5950] I        <POOMCAL:StartTime>
2014-03-24T08:45:47+01:00 DEBUG: [5950] I          20140325T050000Z
2014-03-24T08:45:47+01:00 DEBUG: [5950] I        </POOMCAL:StartTime>
2014-03-24T08:45:47+01:00 DEBUG: [5950] I        <POOMCAL:EndTime>
2014-03-24T08:45:47+01:00 DEBUG: [5950] I          20140325T060000Z
2014-03-24T08:45:47+01:00 DEBUG: [5950] I        </POOMCAL:EndTime>
2014-03-24T08:45:47+01:00 DEBUG: [5950] I        <POOMCAL:DtStamp>
2014-03-24T08:45:47+01:00 DEBUG: [5950] I          20140324T074548Z
2014-03-24T08:45:47+01:00 DEBUG: [5950] I        </POOMCAL:DtStamp>
2014-03-24T08:45:47+01:00 DEBUG: [5950] I        <POOMCAL:Subject>
2014-03-24T08:45:47+01:00 DEBUG: [5950] I          Test
2014-03-24T08:45:47+01:00 DEBUG: [5950] I        </POOMCAL:Subject>
2014-03-24T08:45:47+01:00 DEBUG: [5950] I        <AirSyncBase:Body>
2014-03-24T08:45:47+01:00 DEBUG: [5950] I         <AirSyncBase:Type>
2014-03-24T08:45:47+01:00 DEBUG: [5950] I           1
2014-03-24T08:45:47+01:00 DEBUG: [5950] I         </AirSyncBase:Type>
2014-03-24T08:45:47+01:00 DEBUG: [5950] I         <AirSyncBase:Data />
2014-03-24T08:45:47+01:00 DEBUG: [5950] I        </AirSyncBase:Body>
2014-03-24T08:45:47+01:00 DEBUG: [5950] I        <POOMCAL:OrganizerEmail>
2014-03-24T08:45:47+01:00 DEBUG: [5950] I          anon@anon.tld
2014-03-24T08:45:47+01:00 DEBUG: [5950] I        </POOMCAL:OrganizerEmail>
2014-03-24T08:45:47+01:00 DEBUG: [5950] I        <POOMCAL:Reminder>
2014-03-24T08:45:47+01:00 DEBUG: [5950] I          15
2014-03-24T08:45:47+01:00 DEBUG: [5950] I        </POOMCAL:Reminder>
2014-03-24T08:45:47+01:00 DEBUG: [5950] I        <POOMCAL:UID>
2014-03-24T08:45:47+01:00 DEBUG: [5950] I          66ca3699-3acc-44c0-8990-d1d459e97d95
2014-03-24T08:45:47+01:00 DEBUG: [5950] I        </POOMCAL:UID>
2014-03-24T08:45:47+01:00 DEBUG: [5950] I        <POOMCAL:BusyStatus>
2014-03-24T08:45:47+01:00 DEBUG: [5950] I          2
2014-03-24T08:45:47+01:00 DEBUG: [5950] I        </POOMCAL:BusyStatus>
2014-03-24T08:45:47+01:00 DEBUG: [5950] I        <POOMCAL:MeetingStatus>
2014-03-24T08:45:47+01:00 DEBUG: [5950] I          0
2014-03-24T08:45:47+01:00 DEBUG: [5950] I        </POOMCAL:MeetingStatus>
2014-03-24T08:45:47+01:00 DEBUG: [5950] I        <POOMCAL:Sensitivity>
2014-03-24T08:45:47+01:00 DEBUG: [5950] I          0
2014-03-24T08:45:47+01:00 DEBUG: [5950] I        </POOMCAL:Sensitivity>
2014-03-24T08:45:47+01:00 DEBUG: [5950] I       </Data>
2014-03-24T08:45:47+01:00 INFO: [5950] Horde_Core_ActiveSync_Driver::changeMessage(@Calendar@,  ...)



============================    DOWNLOAD Horde -> Mobile   ============================




Android-log:

[8:51:15] ExchangeService| Starting sync for Calendar
[8:51:15] ExchangeService| Starting thread for Calendar in account anon@anon.tld
[8:51:15] __eas[anon@anon.tld]| Reset, aborting Ping
[8:51:15] Calendar[anon@anon.tld]| SyncKey retrieved as {532378ff-ff68-4eae-a65b-b5ce4e6f48ba}1 from CalendarProvider
[8:51:15] Calendar[anon@anon.tld]| SyncKey retrieved as {532378ff-ff68-4eae-a65b-b5ce4e6f48ba}1 from CalendarProvider
[8:51:15] Calendar[anon@anon.tld]| sync, sending Calendar syncKey: {532378ff-ff68-4eae-a65b-b5ce4e6f48ba}1
[8:51:15] Calendar[anon@anon.tld]| SyncKey retrieved as {532378ff-ff68-4eae-a65b-b5ce4e6f48ba}1 from CalendarProvider
[8:51:17] CalendarParser| <Sync>
[8:51:17] CalendarParser| <Status>
[8:51:17] CalendarParser| Status: 1
[8:51:17] CalendarParser| </Status>
[8:51:17] CalendarParser| <Collections>
[8:51:17] CalendarParser| <Collection>
[8:51:17] CalendarParser| <SyncKey>
[8:51:17] Calendar[anon@anon.tld]| SyncKey retrieved as {532378ff-ff68-4eae-a65b-b5ce4e6f48ba}1 from CalendarProvider
[8:51:17] CalendarParser| SyncKey: {532378ff-ff68-4eae-a65b-b5ce4e6f48ba}2
[8:51:17] CalendarParser| </SyncKey>
[8:51:17] Calendar[anon@anon.tld]| Parsed key for Calendar: {532378ff-ff68-4eae-a65b-b5ce4e6f48ba}2
[8:51:17] CalendarParser| <CollectionId>
[8:51:17] CalendarParser| CollectionId: @Calendar@
[8:51:17] CalendarParser| </CollectionId>
[8:51:17] CalendarParser| <Status>
[8:51:17] CalendarParser| Status: 1
[8:51:17] CalendarParser| </Status>
[8:51:17] Exception| Stack trace follows...
java.lang.ArrayIndexOutOfBoundsException: length=34; index=55
        at com.android.exchange.adapter.Parser.push(Parser.java:426)
        at com.android.exchange.adapter.Parser.getNext(Parser.java:519)
        at com.android.exchange.adapter.Parser.nextTag(Parser.java:308)
        at com.android.exchange.adapter.AbstractSyncParser.parse(AbstractSyncParser.java:134)
        at com.android.exchange.adapter.CalendarSyncAdapter.parse(CalendarSyncAdapter.java:240)
        at com.android.exchange.EasSyncService.sync(EasSyncService.java:2166)
        at com.android.exchange.EasSyncService.run(EasSyncService.java:2413)
        at java.lang.Thread.run(Thread.java:838)
[8:51:17] Calendar[anon@anon.tld]| Sync finished
[8:51:17] Calendar[anon@anon.tld]| Sync ended due to an exception.
[8:51:17] Calendar[anon@anon.tld]| sync finished with reason: 0



ActiveSync-log:

2014-03-24T08:51:17+01:00 INFO: [5952] Horde_Core_ActiveSync_Driver::authenticate() attempt for anon@anon.tld
2014-03-24T08:51:17+01:00 INFO: [5952] SYNC request received for user anon@anon.tld
2014-03-24T08:51:17+01:00 INFO: [5952] Device entry exists for ANDROID1325347202632, updating userAgent and version.
2014-03-24T08:51:17+01:00 INFO: [5952] Request being handled for device: ANDROID1325347202632, Supporting protocol version: 14.1, Using Horde_ActiveSync v2.13.2
2014-03-24T08:51:17+01:00 INFO: [5952] Handling SYNC command.
2014-03-24T08:51:17+01:00 INFO: [5952] Checking policykey for device: ANDROID1325347202632 user: anon@anon.tld
2014-03-24T08:51:17+01:00 INFO: [5952] Policykey: 0 verified.
2014-03-24T08:51:17+01:00 DEBUG: [5952] I  <Synchronize>
2014-03-24T08:51:17+01:00 DEBUG: [5952] I   <Folders>
2014-03-24T08:51:17+01:00 DEBUG: [5952] I    <Folder>
2014-03-24T08:51:17+01:00 DEBUG: [5952] I     <SyncKey>
2014-03-24T08:51:17+01:00 DEBUG: [5952] I       {532378ff-ff68-4eae-a65b-b5ce4e6f48ba}1
2014-03-24T08:51:17+01:00 DEBUG: [5952] I     </SyncKey>
2014-03-24T08:51:17+01:00 DEBUG: [5952] I     <FolderId>
2014-03-24T08:51:17+01:00 DEBUG: [5952] I       @Calendar@
2014-03-24T08:51:17+01:00 DEBUG: [5952] I     </FolderId>
2014-03-24T08:51:17+01:00 DEBUG: [5952] I     <DeletesAsMoves />
2014-03-24T08:51:17+01:00 DEBUG: [5952] I     <GetChanges />
2014-03-24T08:51:17+01:00 DEBUG: [5952] I     <WindowSize>
2014-03-24T08:51:17+01:00 DEBUG: [5952] I       20
2014-03-24T08:51:17+01:00 DEBUG: [5952] I     </WindowSize>
2014-03-24T08:51:17+01:00 DEBUG: [5952] I     <Options>
2014-03-24T08:51:17+01:00 DEBUG: [5952] I      <FilterType>
2014-03-24T08:51:17+01:00 DEBUG: [5952] I        4
2014-03-24T08:51:17+01:00 DEBUG: [5952] I      </FilterType>
2014-03-24T08:51:17+01:00 DEBUG: [5952] I      <AirSyncBase:BodyPreference>
2014-03-24T08:51:17+01:00 DEBUG: [5952] I       <AirSyncBase:Type>
2014-03-24T08:51:17+01:00 DEBUG: [5952] I         1
2014-03-24T08:51:17+01:00 DEBUG: [5952] I       </AirSyncBase:Type>
2014-03-24T08:51:17+01:00 DEBUG: [5952] I       <AirSyncBase:TruncationSize>
2014-03-24T08:51:17+01:00 DEBUG: [5952] I         200000
2014-03-24T08:51:17+01:00 DEBUG: [5952] I       </AirSyncBase:TruncationSize>
2014-03-24T08:51:17+01:00 DEBUG: [5952] I      </AirSyncBase:BodyPreference>
2014-03-24T08:51:17+01:00 DEBUG: [5952] I     </Options>
2014-03-24T08:51:17+01:00 DEBUG: [5952] I    </Folder>
2014-03-24T08:51:17+01:00 INFO: [5952] Obtaining collection class of Calendar for collection id @Calendar@
2014-03-24T08:51:17+01:00 INFO: [5952] Collection added to collection handler: collection: @Calendar@, synckey: {532378ff-ff68-4eae-a65b-b5ce4e6f48ba}1.
2014-03-24T08:51:17+01:00 DEBUG: [5952] I   </Folders>
2014-03-24T08:51:17+01:00 DEBUG: [5952] I  </Synchronize>
2014-03-24T08:51:17+01:00 INFO: [5952] Clearing all collection synckeys from the cache.
2014-03-24T08:51:17+01:00 INFO: Have syncable collections
2014-03-24T08:51:17+01:00 INFO: [5952] Replacing SYNC_CACHE entry for user anon@anon.tld and device ANDROID1325347202632: <php-serialized-array>
2014-03-24T08:51:17+01:00 INFO: [5952] All synckeys confirmed. Continuing with SYNC
2014-03-24T08:51:17+01:00 DEBUG: [5952] O  <Synchronize>
2014-03-24T08:51:17+01:00 DEBUG: [5952] O   <Status>
2014-03-24T08:51:17+01:00 DEBUG: [5952] O    1
2014-03-24T08:51:17+01:00 DEBUG: [5952] O   </Status>
2014-03-24T08:51:17+01:00 INFO: [5952] Initializing state for collection: @Calendar@, synckey: {532378ff-ff68-4eae-a65b-b5ce4e6f48ba}1
2014-03-24T08:51:17+01:00 INFO: [5952] Loading state for synckey {532378ff-ff68-4eae-a65b-b5ce4e6f48ba}1
2014-03-24T08:51:17+01:00 INFO: [5952] Initializing message diff engine for @Calendar@ (@Calendar@)
2014-03-24T08:51:17+01:00 INFO: [5952] Using SYNCSTAMP 1492 for @Calendar@.
2014-03-24T08:51:17+01:00 INFO: [5952] Horde_Core_ActiveSync_Driver::getServerChanges(@Calendar@, 0, 1492, 1394437877, 0)
2014-03-24T08:51:17+01:00 INFO: [5952] Found 2 message changes in @Calendar@.
2014-03-24T08:51:17+01:00 INFO: [5952] No PIM changes present, returning all messages.
2014-03-24T08:51:17+01:00 INFO: [5952] Old SYNCKEY: {532378ff-ff68-4eae-a65b-b5ce4e6f48ba}1, New SYNCKEY: {532378ff-ff68-4eae-a65b-b5ce4e6f48ba}2
2014-03-24T08:51:17+01:00 DEBUG: [5952] O   <Folders>
2014-03-24T08:51:17+01:00 DEBUG: [5952] O    <Folder>
2014-03-24T08:51:17+01:00 DEBUG: [5952] O     <SyncKey>
2014-03-24T08:51:17+01:00 DEBUG: [5952] O      {532378ff-ff68-4eae-a65b-b5ce4e6f48ba}2
2014-03-24T08:51:17+01:00 DEBUG: [5952] O     </SyncKey>
2014-03-24T08:51:17+01:00 DEBUG: [5952] O     <FolderId>
2014-03-24T08:51:17+01:00 DEBUG: [5952] O      @Calendar@
2014-03-24T08:51:17+01:00 DEBUG: [5952] O     </FolderId>
2014-03-24T08:51:17+01:00 DEBUG: [5952] O     <Status>
2014-03-24T08:51:17+01:00 DEBUG: [5952] O      1
2014-03-24T08:51:17+01:00 DEBUG: [5952] O     </Status>
2014-03-24T08:51:17+01:00 INFO: [5952] Horde_Core_ActiveSync_Driver::getMessage(@Calendar@, 20130525100228.Q9N04N7InFClaWmpHdJ8Vg1@host.tld)