2011-12-06T21:31:07+01:00 DEBUG: [HTCAnd3062b66b] Attempting to load PING state for: Contacts
2011-12-06T21:31:07+01:00 DEBUG: [ApplDNQGKADDDTD7] Attempting to load PING state for: Calendar
2011-12-06T21:31:07+01:00 DEBUG: [HTCAnd3062b66b] Obtained last sync time for Contacts - 1323196891
2011-12-06T21:31:07+01:00 DEBUG: [Appl6Q121QD8DZZ] Attempting to load PING state for: Calendar
2011-12-06T21:31:07+01:00 DEBUG: [ApplDNQGKADDDTD7] Obtained last sync time for Calendar - 1322994533
2011-12-06T21:31:07+01:00 DEBUG: [Appl6Q121QD8DZZ] Obtained last sync time for Calendar - 1322994476
2011-12-06T21:31:07+01:00 DEBUG: [HTCAnd3062b66b] Initializing message diff engine for Contacts
2011-12-06T21:31:07+01:00 DEBUG: Horde_ActiveSync_Driver_Horde::getServerChanges(Contacts, 1323196891, 1323203467, 0)
2011-12-06T21:31:07+01:00 DEBUG: [ApplDNQGKADDDTD7] Initializing message diff engine for Calendar
2011-12-06T21:31:07+01:00 DEBUG: Horde_ActiveSync_Driver_Horde::getServerChanges(Calendar, 1322994533, 1323203467, 0)
2011-12-06T21:31:07+01:00 DEBUG: [Appl6Q121QD8DZZ] Initializing message diff engine for Calendar
2011-12-06T21:31:07+01:00 DEBUG: Horde_ActiveSync_Driver_Horde::getServerChanges(Calendar, 1322994476, 1323203467, 0)
2011-12-06T21:31:07+01:00 DEBUG: [HTCAnd3062b66b] Found 0 message changes, checking for PIM initiated changes.
2011-12-06T21:31:07+01:00 DEBUG: [HTCAnd3062b66b] No PIM changes present, returning all messages.
2011-12-06T21:31:07+01:00 DEBUG: [HTCAnd3062b66b] Attempting to load PING state for: Calendar
2011-12-06T21:31:07+01:00 DEBUG: [ApplDNQGKADDDTD7] Found 0 message changes, checking for PIM initiated changes.
2011-12-06T21:31:07+01:00 DEBUG: [HTCAnd3062b66b] Obtained last sync time for Calendar - 1322998524
2011-12-06T21:31:07+01:00 DEBUG: [Appl6Q121QD8DZZ] Found 0 message changes, checking for PIM initiated changes.
2011-12-06T21:31:07+01:00 DEBUG: [ApplDNQGKADDDTD7] Attempting to load PING state for: Contacts
2011-12-06T21:31:07+01:00 DEBUG: [Appl6Q121QD8DZZ] No PIM changes present, returning all messages.
2011-12-06T21:31:07+01:00 DEBUG: [HTCAnd3062b66b] Initializing message diff engine for Calendar
2011-12-06T21:31:07+01:00 DEBUG: Horde_ActiveSync_Driver_Horde::getServerChanges(Calendar, 1322998524, 1323203467, 0)
2011-12-06T21:31:07+01:00 DEBUG: [Appl6Q121QD8DZZ] Attempting to load PING state for: Contacts
2011-12-06T21:31:07+01:00 DEBUG: [ApplDNQGKADDDTD7] Obtained last sync time for Contacts - 1322993939
2011-12-06T21:31:07+01:00 DEBUG: [Appl6Q121QD8DZZ] Obtained last sync time for Contacts - 1322992597
2011-12-06T21:31:07+01:00 DEBUG: [ApplDNQGKADDDTD7] Initializing message diff engine for Contacts
2011-12-06T21:31:07+01:00 DEBUG: Horde_ActiveSync_Driver_Horde::getServerChanges(Contacts, 1322993939, 1323203467, 0)
2011-12-06T21:31:07+01:00 DEBUG: [Appl6Q121QD8DZZ] Initializing message diff engine for Contacts
2011-12-06T21:31:07+01:00 DEBUG: Horde_ActiveSync_Driver_Horde::getServerChanges(Contacts, 1322992597, 1323203467, 0)
2011-12-06T21:31:07+01:00 DEBUG: [HTCAnd3062b66b] Found 0 message changes, checking for PIM initiated changes.
2011-12-06T21:31:07+01:00 DEBUG: [ApplDNQGKADDDTD7] Found 0 message changes, checking for PIM initiated changes.
2011-12-06T21:31:07+01:00 DEBUG: [HTCAnd3062b66b] No PIM changes present, returning all messages.
2011-12-06T21:31:07+01:00 DEBUG: [Appl6Q121QD8DZZ] Found 0 message changes, checking for PIM initiated changes.
2011-12-06T21:31:07+01:00 DEBUG: [Appl6Q121QD8DZZ] No PIM changes present, returning all messages.
2011-12-06T21:31:07+01:00 INFO: Horde_ActiveSync_Driver_Horde::logon attempt for: stephan@admin.nabira.de
2011-12-06T21:31:07+01:00 DEBUG: [HTCAnd3062b66b] SYNC request received for user stephan@admin.nabira.de
2011-12-06T21:31:07+01:00 DEBUG: [HTCAnd3062b66b] loadDeviceInfo: stephan@admin.nabira.de
2011-12-06T21:31:07+01:00 INFO: Request being handled for device: HTCAnd3062b66b Supporting protocol version: 2.5
2011-12-06T21:31:07+01:00 INFO: [HTCAnd3062b66b] Handling SYNC command.
2011-12-06T21:31:07+01:00 DEBUG: [HTCAnd3062b66b] Checking policykey for device  Key: 0 User: stephan@admin.nabira.de
2011-12-06T21:31:07+01:00 DEBUG: [HTCAnd3062b66b] loadDeviceInfo: stephan@admin.nabira.de
2011-12-06T21:31:07+01:00 DEBUG: Policykey:  verified.
2011-12-06T21:31:07+01:00 DEBUG: I  <Synchronize>
2011-12-06T21:31:07+01:00 DEBUG: I   <Folders>
2011-12-06T21:31:07+01:00 DEBUG: I    <Folder>
2011-12-06T21:31:07+01:00 DEBUG: I     <FolderType>
2011-12-06T21:31:07+01:00 DEBUG: I      Calendar
2011-12-06T21:31:07+01:00 INFO: [HTCAnd3062b66b] Syncing folder class: Calendar
2011-12-06T21:31:07+01:00 DEBUG: I     </FolderType>
2011-12-06T21:31:07+01:00 DEBUG: I     <SyncKey>
2011-12-06T21:31:07+01:00 DEBUG: I      {4eda5c64-5668-4c09-88bf-571d4e2ef992}3
2011-12-06T21:31:07+01:00 DEBUG: I     </SyncKey>
2011-12-06T21:31:07+01:00 DEBUG: I     <FolderId>
2011-12-06T21:31:07+01:00 DEBUG: I      Calendar
2011-12-06T21:31:07+01:00 INFO: [HTCAnd3062b66b] Folder server id: Calendar
2011-12-06T21:31:07+01:00 DEBUG: I     </FolderId>
2011-12-06T21:31:07+01:00 DEBUG: I     <DeletesAsMoves/>
2011-12-06T21:31:07+01:00 DEBUG: I     <GetChanges/>
2011-12-06T21:31:07+01:00 DEBUG: I     <WindowSize>
2011-12-06T21:31:07+01:00 DEBUG: I      100
2011-12-06T21:31:07+01:00 DEBUG: I     </WindowSize>
2011-12-06T21:31:07+01:00 DEBUG: I     <Options>
2011-12-06T21:31:07+01:00 DEBUG: I      <FilterType>
2011-12-06T21:31:07+01:00 DEBUG: I       4
2011-12-06T21:31:07+01:00 DEBUG: I      </FilterType>
2011-12-06T21:31:07+01:00 DEBUG: I      <Conflict>
2011-12-06T21:31:07+01:00 DEBUG: I       1
2011-12-06T21:31:07+01:00 DEBUG: I      </Conflict>
2011-12-06T21:31:07+01:00 DEBUG: I     </Options>
2011-12-06T21:31:07+01:00 DEBUG: [HTCAnd3062b66b] Loading state for synckey {4eda5c64-5668-4c09-88bf-571d4e2ef992}3
2011-12-06T21:31:07+01:00 DEBUG: I     <Commands>
2011-12-06T21:31:07+01:00 DEBUG: I      <Add>
2011-12-06T21:31:07+01:00 DEBUG: I       <ClientEntryId>
2011-12-06T21:31:07+01:00 DEBUG: I        8290
2011-12-06T21:31:07+01:00 DEBUG: I       </ClientEntryId>
2011-12-06T21:31:07+01:00 DEBUG: I       <Data>
2011-12-06T21:31:07+01:00 DEBUG: I        <POOMCAL:Timezone>
2011-12-06T21:31:07+01:00 DEBUG: I         [payload removed: 232 characters]
2011-12-06T21:31:07+01:00 DEBUG: I        </POOMCAL:Timezone>
2011-12-06T21:31:07+01:00 DEBUG: I        <POOMCAL:AllDayEvent>
2011-12-06T21:31:07+01:00 DEBUG: I         0
2011-12-06T21:31:07+01:00 DEBUG: I        </POOMCAL:AllDayEvent>
2011-12-06T21:31:07+01:00 DEBUG: I        <POOMCAL:BusyStatus>
2011-12-06T21:31:07+01:00 DEBUG: I         2
2011-12-06T21:31:07+01:00 DEBUG: I        </POOMCAL:BusyStatus>
2011-12-06T21:31:07+01:00 DEBUG: I        <POOMCAL:DtStamp>
2011-12-06T21:31:07+01:00 DEBUG: I         20111206T202659Z
2011-12-06T21:31:07+01:00 DEBUG: I        </POOMCAL:DtStamp>
2011-12-06T21:31:07+01:00 DEBUG: I        <POOMCAL:EndTime>
2011-12-06T21:31:07+01:00 DEBUG: I         20111206T120000Z
2011-12-06T21:31:07+01:00 DEBUG: I        </POOMCAL:EndTime>
2011-12-06T21:31:07+01:00 DEBUG: I        <POOMCAL:MeetingStatus>
2011-12-06T21:31:07+01:00 DEBUG: I         0
2011-12-06T21:31:07+01:00 DEBUG: I        </POOMCAL:MeetingStatus>
2011-12-06T21:31:07+01:00 DEBUG: I        <POOMCAL:Reminder>
2011-12-06T21:31:07+01:00 DEBUG: I         10
2011-12-06T21:31:07+01:00 DEBUG: I        </POOMCAL:Reminder>
2011-12-06T21:31:07+01:00 DEBUG: I        <POOMCAL:Sensitivity>
2011-12-06T21:31:07+01:00 DEBUG: I         0
2011-12-06T21:31:07+01:00 DEBUG: I        </POOMCAL:Sensitivity>
2011-12-06T21:31:07+01:00 DEBUG: I        <POOMCAL:Subject>
2011-12-06T21:31:07+01:00 DEBUG: I         Test
2011-12-06T21:31:07+01:00 DEBUG: I        </POOMCAL:Subject>
2011-12-06T21:31:07+01:00 DEBUG: I        <POOMCAL:StartTime>
2011-12-06T21:31:07+01:00 DEBUG: I         20111206T110000Z
2011-12-06T21:31:07+01:00 DEBUG: I        </POOMCAL:StartTime>
2011-12-06T21:31:07+01:00 DEBUG: I        <POOMCAL:UID>
2011-12-06T21:31:07+01:00 DEBUG: I         d1300fa6-bfbd-42dd-9498-6447c55740e7
2011-12-06T21:31:07+01:00 DEBUG: I        </POOMCAL:UID>
2011-12-06T21:31:07+01:00 DEBUG: I       </Data>
2011-12-06T21:31:07+01:00 DEBUG: Horde::changeMessage(Calendar, )
2011-12-06T21:31:08+01:00 DEBUG: ActiveSync_Driver_Horde::_smartStatMessage:Calendar:20111206213108.myY7fyyqjKtO3nuMJknjZ2A@www.nabira.de
2011-12-06T21:31:08+01:00 DEBUG: Updating state during change
2011-12-06T21:31:08+01:00 DEBUG: I      </Add>
2011-12-06T21:31:08+01:00 DEBUG: I     </Commands>
2011-12-06T21:31:08+01:00 DEBUG: [HTCAnd3062b66b] Processed 1 incoming changes
2011-12-06T21:31:08+01:00 DEBUG: I    </Folder>
2011-12-06T21:31:08+01:00 DEBUG: I   </Folders>
2011-12-06T21:31:08+01:00 DEBUG: I  </Synchronize>
2011-12-06T21:31:08+01:00 INFO: [HTCAnd3062b66b] Beginning SYNC Response.
2011-12-06T21:31:08+01:00 DEBUG: [HTCAnd3062b66b] Initializing message diff engine for Calendar
2011-12-06T21:31:08+01:00 DEBUG: Horde_ActiveSync_Driver_Horde::getServerChanges(Calendar, 1322998524, 1323203468, 1321993868)
2011-12-06T21:31:08+01:00 DEBUG: [HTCAnd3062b66b] Found 0 message changes, checking for PIM initiated changes.
2011-12-06T21:31:08+01:00 DEBUG: Generating new synckey. Old synckey: {4eda5c64-5668-4c09-88bf-571d4e2ef992}3
2011-12-06T21:31:08+01:00 DEBUG: New synckey generated: {4eda5c64-5668-4c09-88bf-571d4e2ef992}4
2011-12-06T21:31:08+01:00 DEBUG: O  <Synchronize>
2011-12-06T21:31:08+01:00 DEBUG: O   <Folders>
2011-12-06T21:31:08+01:00 DEBUG: O    <Folder>
2011-12-06T21:31:08+01:00 DEBUG: O     <FolderType>
2011-12-06T21:31:08+01:00 DEBUG: O      Calendar
2011-12-06T21:31:08+01:00 DEBUG: O     <FolderType/>
2011-12-06T21:31:08+01:00 DEBUG: O     <SyncKey>
2011-12-06T21:31:08+01:00 DEBUG: O      {4eda5c64-5668-4c09-88bf-571d4e2ef992}4
2011-12-06T21:31:08+01:00 DEBUG: O     <SyncKey/>
2011-12-06T21:31:08+01:00 DEBUG: O     <FolderId>
2011-12-06T21:31:08+01:00 DEBUG: O      Calendar
2011-12-06T21:31:08+01:00 DEBUG: O     <FolderId/>
2011-12-06T21:31:08+01:00 DEBUG: O     <Status>
2011-12-06T21:31:08+01:00 DEBUG: O      1
2011-12-06T21:31:08+01:00 DEBUG: O     <Status/>
2011-12-06T21:31:08+01:00 DEBUG: O     <Replies>
2011-12-06T21:31:08+01:00 DEBUG: O      <Add>
2011-12-06T21:31:08+01:00 DEBUG: O       <ClientEntryId>
2011-12-06T21:31:08+01:00 DEBUG: O        8290
2011-12-06T21:31:08+01:00 DEBUG: O       <ClientEntryId/>
2011-12-06T21:31:08+01:00 DEBUG: O       <ServerEntryId>
2011-12-06T21:31:08+01:00 DEBUG: O        20111206213108.myY7fyyqjKtO3nuMJknjZ2A@www.nabira.de
2011-12-06T21:31:08+01:00 DEBUG: O       <ServerEntryId/>
2011-12-06T21:31:08+01:00 DEBUG: O       <Status>
2011-12-06T21:31:08+01:00 DEBUG: O        1
2011-12-06T21:31:08+01:00 DEBUG: O       <Status/>
2011-12-06T21:31:08+01:00 DEBUG: O      <Add/>
2011-12-06T21:31:08+01:00 DEBUG: O     <Replies/>
2011-12-06T21:31:08+01:00 DEBUG: O    <Folder/>
2011-12-06T21:31:08+01:00 DEBUG: [HTCAnd3062b66b] Saving state: Array
2011-12-06T21:31:08+01:00 DEBUG: O   <Folders/>
2011-12-06T21:31:08+01:00 DEBUG: O  <Synchronize/>
2011-12-06T21:31:08+01:00 INFO: User stephan@admin.nabira.de logged off