HORDE:







2014-03-14T22:07:07+01:00 INFO: [44210] Horde_Core_ActiveSync_Driver::authenticate() attempt for anon@anon.tld
2014-03-14T22:07:07+01:00 INFO: [44210] SYNC request received for user anon@anon.tld
2014-03-14T22:07:07+01:00 INFO: [44210] Device entry exists for ANDROID1325347202632, updating userAgent and version.
2014-03-14T22:07:07+01:00 INFO: [44210] Request being handled for device: ANDROID1325347202632, Supporting protocol version: 14.1, Using Horde_ActiveSync v2.13.1
2014-03-14T22:07:07+01:00 INFO: [44210] Handling SYNC command.
2014-03-14T22:07:07+01:00 INFO: [44210] Checking policykey for device: ANDROID1325347202632 user: anon@anon.tld
2014-03-14T22:07:07+01:00 INFO: [44210] Policykey: 0 verified.
2014-03-14T22:07:07+01:00 DEBUG: [44210] I  <Synchronize>
2014-03-14T22:07:07+01:00 DEBUG: [44210] I   <Folders>
2014-03-14T22:07:07+01:00 DEBUG: [44210] I    <Folder>
2014-03-14T22:07:07+01:00 DEBUG: [44210] I     <SyncKey>
2014-03-14T22:07:07+01:00 DEBUG: [44210] I       {53235d61-7a94-4399-88a1-9bb74e6f48ba}1
2014-03-14T22:07:07+01:00 DEBUG: [44210] I     </SyncKey>
2014-03-14T22:07:07+01:00 DEBUG: [44210] I     <FolderId>
2014-03-14T22:07:07+01:00 DEBUG: [44210] I       @Calendar@
2014-03-14T22:07:07+01:00 DEBUG: [44210] I     </FolderId>
2014-03-14T22:07:07+01:00 DEBUG: [44210] I     <DeletesAsMoves />
2014-03-14T22:07:07+01:00 DEBUG: [44210] I     <GetChanges />
2014-03-14T22:07:07+01:00 DEBUG: [44210] I     <WindowSize>
2014-03-14T22:07:07+01:00 DEBUG: [44210] I       20
2014-03-14T22:07:07+01:00 DEBUG: [44210] I     </WindowSize>
2014-03-14T22:07:07+01:00 DEBUG: [44210] I     <Options>
2014-03-14T22:07:07+01:00 DEBUG: [44210] I      <FilterType>
2014-03-14T22:07:07+01:00 DEBUG: [44210] I        4
2014-03-14T22:07:07+01:00 DEBUG: [44210] I      </FilterType>
2014-03-14T22:07:07+01:00 DEBUG: [44210] I      <AirSyncBase:BodyPreference>
2014-03-14T22:07:07+01:00 DEBUG: [44210] I       <AirSyncBase:Type>
2014-03-14T22:07:07+01:00 DEBUG: [44210] I         1
2014-03-14T22:07:07+01:00 DEBUG: [44210] I       </AirSyncBase:Type>
2014-03-14T22:07:07+01:00 DEBUG: [44210] I       <AirSyncBase:TruncationSize>
2014-03-14T22:07:07+01:00 DEBUG: [44210] I         200000
2014-03-14T22:07:07+01:00 DEBUG: [44210] I       </AirSyncBase:TruncationSize>
2014-03-14T22:07:07+01:00 DEBUG: [44210] I      </AirSyncBase:BodyPreference>
2014-03-14T22:07:07+01:00 DEBUG: [44210] I     </Options>
2014-03-14T22:07:07+01:00 DEBUG: [44210] I    </Folder>
2014-03-14T22:07:07+01:00 INFO: [44210] Obtaining collection class of Calendar for collection id @Calendar@
2014-03-14T22:07:07+01:00 INFO: [44210] Collection added to collection handler: collection: @Calendar@, synckey: {53235d61-7a94-4399-88a1-9bb74e6f48ba}1.
2014-03-14T22:07:07+01:00 DEBUG: [44210] I   </Folders>
2014-03-14T22:07:07+01:00 DEBUG: [44210] I  </Synchronize>
2014-03-14T22:07:07+01:00 INFO: [44210] Clearing all collection synckeys from the cache.
2014-03-14T22:07:07+01:00 INFO: Have syncable collections
2014-03-14T22:07:07+01:00 INFO: [44210] Replacing SYNC_CACHE entry for user anon@anon.tld and device ANDROID1325347202632: <serialized PHP array with private information>
2014-03-14T22:07:07+01:00 INFO: [44210] All synckeys confirmed. Continuing with SYNC
2014-03-14T22:07:07+01:00 DEBUG: [44210] O  <Synchronize>
2014-03-14T22:07:07+01:00 DEBUG: [44210] O   <Status>
2014-03-14T22:07:07+01:00 DEBUG: [44210] O    1
2014-03-14T22:07:07+01:00 DEBUG: [44210] O   </Status>
2014-03-14T22:07:07+01:00 INFO: [44210] Initializing state for collection: @Calendar@, synckey: {53235d61-7a94-4399-88a1-9bb74e6f48ba}1
2014-03-14T22:07:07+01:00 INFO: [44210] Loading state for synckey {53235d61-7a94-4399-88a1-9bb74e6f48ba}1
2014-03-14T22:07:07+01:00 INFO: [44210] Initializing message diff engine for @Calendar@ (@Calendar@)
2014-03-14T22:07:07+01:00 INFO: [44210] Using SYNCSTAMP 1461 for @Calendar@.
2014-03-14T22:07:07+01:00 INFO: [44210] Horde_Core_ActiveSync_Driver::getServerChanges(@Calendar@, 0, 1461, 1393621627, 0)
2014-03-14T22:07:07+01:00 INFO: [44210] Found 3 message changes in @Calendar@.
2014-03-14T22:07:07+01:00 INFO: [44210] No PIM changes present, returning all messages.
2014-03-14T22:07:07+01:00 INFO: [44210] Old SYNCKEY: {53235d61-7a94-4399-88a1-9bb74e6f48ba}1, New SYNCKEY: {53235d61-7a94-4399-88a1-9bb74e6f48ba}2
2014-03-14T22:07:07+01:00 DEBUG: [44210] O   <Folders>
2014-03-14T22:07:07+01:00 DEBUG: [44210] O    <Folder>
2014-03-14T22:07:07+01:00 DEBUG: [44210] O     <SyncKey>
2014-03-14T22:07:07+01:00 DEBUG: [44210] O      {53235d61-7a94-4399-88a1-9bb74e6f48ba}2
2014-03-14T22:07:07+01:00 DEBUG: [44210] O     </SyncKey>
2014-03-14T22:07:07+01:00 DEBUG: [44210] O     <FolderId>
2014-03-14T22:07:07+01:00 DEBUG: [44210] O      @Calendar@
2014-03-14T22:07:07+01:00 DEBUG: [44210] O     </FolderId>
2014-03-14T22:07:07+01:00 DEBUG: [44210] O     <Status>
2014-03-14T22:07:07+01:00 DEBUG: [44210] O      1
2014-03-14T22:07:07+01:00 DEBUG: [44210] O     </Status>
2014-03-14T22:07:07+01:00 INFO: [44210] Horde_Core_ActiveSync_Driver::getMessage(@Calendar@, 20130525100228.Q9N04N7InFClaWmpHdJ8Vg1@avocado.salatschuessel.net)




ANDROID:




[22:07:05] ExchangeService| Starting sync for Calendar
[22:07:05] ExchangeService| Starting thread for Calendar in account anon@anon.tld
[22:07:05] __eas[anon@anon.tld]| Reset, aborting Ping
[22:07:05] Calendar[anon@anon.tld]| SyncKey retrieved as {53235d61-7a94-4399-88a1-9bb74e6f48ba}1 from CalendarProvider
[22:07:05] Calendar[anon@anon.tld]| SyncKey retrieved as {53235d61-7a94-4399-88a1-9bb74e6f48ba}1 from CalendarProvider
[22:07:05] Calendar[anon@anon.tld]| sync, sending Calendar syncKey: {53235d61-7a94-4399-88a1-9bb74e6f48ba}1
[22:07:05] Calendar[anon@anon.tld]| SyncKey retrieved as {53235d61-7a94-4399-88a1-9bb74e6f48ba}1 from CalendarProvider
[22:07:06] CalendarParser| <Sync>
[22:07:06] CalendarParser| <Status>
[22:07:06] CalendarParser| Status: 1
[22:07:06] CalendarParser| </Status>
[22:07:06] CalendarParser| <Collections>
[22:07:06] CalendarParser| <Collection>
[22:07:06] CalendarParser| <SyncKey>
[22:07:06] Calendar[anon@anon.tld]| SyncKey retrieved as {53235d61-7a94-4399-88a1-9bb74e6f48ba}1 from CalendarProvider
[22:07:06] CalendarParser| SyncKey: {53235d61-7a94-4399-88a1-9bb74e6f48ba}2
[22:07:06] CalendarParser| </SyncKey>
[22:07:06] Calendar[anon@anon.tld]| Parsed key for Calendar: {53235d61-7a94-4399-88a1-9bb74e6f48ba}2
[22:07:06] CalendarParser| <CollectionId>
[22:07:06] CalendarParser| CollectionId: @Calendar@
[22:07:06] CalendarParser| </CollectionId>
[22:07:06] CalendarParser| <Status>
[22:07:06] CalendarParser| Status: 1
[22:07:06] CalendarParser| </Status>
[22:07:06] 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)
[22:07:06] Calendar[anon@anon.tld]| Sync finished
[22:07:06] Calendar[anon@anon.tld]| Sync ended due to an exception.
[22:07:06] Calendar[anon@anon.tld]| sync finished with reason: 0
[22:09:39] ExchangeService| !!! EAS ExchangeService, onStartCommand, startingUp = false, running = true
[22:09:39] ExchangeService| Reconciling accounts...
[22:09:44] ExchangeService| Starting sync for Calendar
[22:09:44] ExchangeService| Starting thread for Calendar in account anon@anon.tld
[22:09:44] __eas[anon@anon.tld]| Reset, aborting Ping
[22:09:44] Calendar[anon@anon.tld]| SyncKey retrieved as {53235d61-7a94-4399-88a1-9bb74e6f48ba}1 from CalendarProvider
[22:09:44] Calendar[anon@anon.tld]| SyncKey retrieved as {53235d61-7a94-4399-88a1-9bb74e6f48ba}1 from CalendarProvider
[22:09:44] Calendar[anon@anon.tld]| sync, sending Calendar syncKey: {53235d61-7a94-4399-88a1-9bb74e6f48ba}1
[22:09:44] Calendar[anon@anon.tld]| SyncKey retrieved as {53235d61-7a94-4399-88a1-9bb74e6f48ba}1 from CalendarProvider