[horde] Wrong Birthday entry in Contacts ActiveSync & Andoird ICS 4.0.2
Daniel Basa
daniel at luli.de
Thu Mar 1 15:48:25 UTC 2012
Am 01.03.2012 16:39, schrieb Michael J Rubinsky:
>
> Quoting Daniel Basa <daniel at luli.de>:
>
>> Am 01.03.2012 15:57, schrieb Michael J Rubinsky:
>>>
>>> Quoting Daniel Basa <daniel at luli.de>:
>>>
>>>> Hi List!
>>>>
>>>> back again with an ActiveSync Android problem. This time I think that
>>>> it's not a problem with horde, but I would like to know if someone is
>>>> experiencing the same issue.
>>>>
>>>> Since January I sync my Contacts and my Calendars using Horde. The
>>>> first
>>>> two months I also had the google contact on my phone, but I did a whipe
>>>> today and resynced with horde.
>>>> Problem is, that all contact's birthday dates are wrong by one (or
>>>> two?)
>>>> days. They are correct in horde's addressbook and I checked the sync
>>>> log
>>>> and noticed birthdays of contacts in there were correct too.
>>>> Question is
>>>> why are the birthdays on my phone wrong?
>>>>
>>>> Anybody has the same issue? Perhaps a bug with google? Because of leap
>>>> year?
>>>
>>> Earlier Android "Contact" apps did not even support birthdays and would
>>> display them exactly as sent i.e., as the standard date/time string in
>>> UTC. The app in ICS, though, does have support for the birthday field
>>> and is working for on my Galaxy Nexus device.
>>>
>>> If you generate a sync log that includes the contact data being sent to
>>> the device, I can try to take a look and see if anything weird pops out.
>>> Also, does the timezone on the device match the timezone in your Horde
>>> account prefs? Just grasping at straws though since it shouldn't really
>>> matter; I don't see how tz can matter since tz data is not sent as part
>>> of the protocol for contact messages.
>>>
>>> Anyway, see http://wiki.horde.org/ActiveSync for more information on how
>>> to debug, provide log files etc...
>>
>> Timezone and date are the same on horde, the server and my device.
>>
>> What exactly do you need in my Sync-Log to be? I am syncing ~100
>> contacts and I don't want to publish their names, numbers addresses etc.
>> If I can cut out the part with the personal information then I can
>> provide the log. Otherwise it would be a bit problematic changing
>> numbers names addresses etc for 100 contacts. Another possibility would
>> be to crop all contacts execpt for one with a brithday.
>
> To be the *most* helpful, I would need the entire conversation between
> horde/device for a fresh contact sync - there is information exchanged
> during the *initial* contact sync that indicates supported/ghosted field
> names (the SUPPORTED responses if you are looking at the logs). However,
> for the actual contact data itself, it should be fine if you just
> include one with a birthday. It might also be helpful if you could
> provide the value of the birthday field, as it exists in the contacts
> backend that horde is using.
Here comes the log:
2012-03-01T15:52:45+01:00 INFO: Horde_ActiveSync_Driver_Horde::logon
attempt for: user at example.org
2012-03-01T15:52:46+01:00 DEBUG: [androidc1771218180] PING request
received for user user at example.org
2012-03-01T15:52:46+01:00 DEBUG: [androidc1771218180] loadDeviceInfo:
user at example.org
2012-03-01T15:52:46+01:00 INFO: Request being handled for device:
androidc1771218180 Supporting protocol version: 2.5
2012-03-01T15:52:46+01:00 INFO: [androidc1771218180] PING received at
timestamp: 1330613566.
2012-03-01T15:52:46+01:00 DEBUG: [androidc1771218180] loadDeviceInfo:
user at example.org
2012-03-01T15:52:46+01:00 DEBUG: I <Ping:Ping>
2012-03-01T15:52:46+01:00 DEBUG: I <Ping:HeartbeatInterval>
2012-03-01T15:52:46+01:00 DEBUG: I 470
2012-03-01T15:52:46+01:00 DEBUG: I </Ping:HeartbeatInterval>
2012-03-01T15:52:46+01:00 DEBUG: I <Ping:Folders>
2012-03-01T15:52:46+01:00 DEBUG: I <Ping:Folder>
2012-03-01T15:52:46+01:00 DEBUG: I <Ping:ServerEntryId>
2012-03-01T15:52:46+01:00 DEBUG: I Contacts
2012-03-01T15:52:46+01:00 DEBUG: I </Ping:ServerEntryId>
2012-03-01T15:52:46+01:00 DEBUG: I <Ping:FolderType>
2012-03-01T15:52:46+01:00 DEBUG: I Contacts
2012-03-01T15:52:46+01:00 DEBUG: I </Ping:FolderType>
2012-03-01T15:52:46+01:00 DEBUG: I </Ping:Folder>
2012-03-01T15:52:46+01:00 DEBUG: I <Ping:Folder>
2012-03-01T15:52:46+01:00 DEBUG: I <Ping:ServerEntryId>
2012-03-01T15:52:46+01:00 DEBUG: I Calendar
2012-03-01T15:52:46+01:00 DEBUG: I </Ping:ServerEntryId>
2012-03-01T15:52:46+01:00 DEBUG: I <Ping:FolderType>
2012-03-01T15:52:46+01:00 DEBUG: I Calendar
2012-03-01T15:52:46+01:00 DEBUG: I </Ping:FolderType>
2012-03-01T15:52:46+01:00 DEBUG: I </Ping:Folder>
2012-03-01T15:52:46+01:00 DEBUG: I </Ping:Folders>
2012-03-01T15:52:46+01:00 DEBUG: I </Ping:Ping>
2012-03-01T15:52:46+01:00 INFO: [androidc1771218180] Waiting for changes
(heartbeat interval: 470)
2012-03-01T15:52:46+01:00 DEBUG: [androidc1771218180] Attempting to load
PING state for: Contacts
2012-03-01T15:52:46+01:00 DEBUG: [androidc1771218180] Obtained last sync
time for Contacts - 1330595063
2012-03-01T15:52:46+01:00 DEBUG: [androidc1771218180] Initializing
message diff engine for Contacts
2012-03-01T15:52:46+01:00 DEBUG:
Horde_ActiveSync_Driver_Horde::getServerChanges(Contacts, 1330595063,
1330613566, 0)
2012-03-01T15:52:46+01:00 DEBUG: [androidc1771218180] Found 0 message
changes, checking for PIM initiated changes.
2012-03-01T15:52:46+01:00 DEBUG: [androidc1771218180] Attempting to load
PING state for: Calendar
2012-03-01T15:52:46+01:00 DEBUG: [androidc1771218180] Obtained last sync
time for Calendar - 1330591617
2012-03-01T15:52:46+01:00 DEBUG: [androidc1771218180] Initializing
message diff engine for Calendar
2012-03-01T15:52:46+01:00 DEBUG:
Horde_ActiveSync_Driver_Horde::getServerChanges(Calendar, 1330591617,
1330613566, 0)
2012-03-01T15:52:46+01:00 DEBUG: [androidc1771218180] Found 0 message
changes, checking for PIM initiated changes.
2012-03-01T15:52:51+01:00 DEBUG: [androidc1771218180] Attempting to load
PING state for: Contacts
2012-03-01T15:52:51+01:00 DEBUG: [androidc1771218180] Obtained last sync
time for Contacts - 1330595063
2012-03-01T15:52:51+01:00 DEBUG: [androidc1771218180] Initializing
message diff engine for Contacts
2012-03-01T15:52:51+01:00 DEBUG:
Horde_ActiveSync_Driver_Horde::getServerChanges(Contacts, 1330595063,
1330613571, 0)
2012-03-01T15:52:51+01:00 DEBUG: [androidc1771218180] Found 0 message
changes, checking for PIM initiated changes.
2012-03-01T15:52:51+01:00 DEBUG: [androidc1771218180] Attempting to load
PING state for: Calendar
2012-03-01T15:52:51+01:00 DEBUG: [androidc1771218180] Obtained last sync
time for Calendar - 1330591617
2012-03-01T15:52:51+01:00 DEBUG: [androidc1771218180] Initializing
message diff engine for Calendar
2012-03-01T15:52:51+01:00 DEBUG:
Horde_ActiveSync_Driver_Horde::getServerChanges(Calendar, 1330591617,
1330613571, 0)
2012-03-01T15:52:51+01:00 DEBUG: [androidc1771218180] Found 0 message
changes, checking for PIM initiated changes.
2012-03-01T15:52:56+01:00 DEBUG: [androidc1771218180] Attempting to load
PING state for: Contacts
2012-03-01T15:52:56+01:00 DEBUG: [androidc1771218180] Obtained last sync
time for Contacts - 1330595063
2012-03-01T15:52:56+01:00 DEBUG: [androidc1771218180] Initializing
message diff engine for Contacts
2012-03-01T15:52:56+01:00 DEBUG:
Horde_ActiveSync_Driver_Horde::getServerChanges(Contacts, 1330595063,
1330613576, 0)
2012-03-01T15:52:56+01:00 DEBUG: [androidc1771218180] Found 0 message
changes, checking for PIM initiated changes.
2012-03-01T15:52:56+01:00 DEBUG: [androidc1771218180] Attempting to load
PING state for: Calendar
2012-03-01T15:52:56+01:00 DEBUG: [androidc1771218180] Obtained last sync
time for Calendar - 1330591617
2012-03-01T15:52:56+01:00 DEBUG: [androidc1771218180] Initializing
message diff engine for Calendar
2012-03-01T15:52:56+01:00 DEBUG:
Horde_ActiveSync_Driver_Horde::getServerChanges(Calendar, 1330591617,
1330613576, 0)
2012-03-01T15:52:56+01:00 DEBUG: [androidc1771218180] Found 0 message
changes, checking for PIM initiated changes.
2012-03-01T15:53:01+01:00 DEBUG: [androidc1771218180] Attempting to load
PING state for: Contacts
2012-03-01T15:53:01+01:00 DEBUG: [androidc1771218180] Obtained last sync
time for Contacts - 1330595063
2012-03-01T15:53:01+01:00 DEBUG: [androidc1771218180] Initializing
message diff engine for Contacts
2012-03-01T15:53:01+01:00 DEBUG:
Horde_ActiveSync_Driver_Horde::getServerChanges(Contacts, 1330595063,
1330613581, 0)
2012-03-01T15:53:01+01:00 DEBUG: [androidc1771218180] Found 0 message
changes, checking for PIM initiated changes.
2012-03-01T15:53:01+01:00 DEBUG: [androidc1771218180] Attempting to load
PING state for: Calendar
2012-03-01T15:53:01+01:00 DEBUG: [androidc1771218180] Obtained last sync
time for Calendar - 1330591617
2012-03-01T15:53:01+01:00 DEBUG: [androidc1771218180] Initializing
message diff engine for Calendar
2012-03-01T15:53:01+01:00 DEBUG:
Horde_ActiveSync_Driver_Horde::getServerChanges(Calendar, 1330591617,
1330613581, 0)
2012-03-01T15:53:01+01:00 DEBUG: [androidc1771218180] Found 0 message
changes, checking for PIM initiated changes.
2012-03-01T15:53:06+01:00 DEBUG: [androidc1771218180] Attempting to load
PING state for: Contacts
2012-03-01T15:53:06+01:00 DEBUG: [androidc1771218180] Obtained last sync
time for Contacts - 1330595063
2012-03-01T15:53:06+01:00 DEBUG: [androidc1771218180] Initializing
message diff engine for Contacts
2012-03-01T15:53:06+01:00 DEBUG:
Horde_ActiveSync_Driver_Horde::getServerChanges(Contacts, 1330595063,
1330613586, 0)
2012-03-01T15:53:06+01:00 DEBUG: [androidc1771218180] Found 0 message
changes, checking for PIM initiated changes.
2012-03-01T15:53:06+01:00 DEBUG: [androidc1771218180] Attempting to load
PING state for: Calendar
2012-03-01T15:53:06+01:00 DEBUG: [androidc1771218180] Obtained last sync
time for Calendar - 1330591617
2012-03-01T15:53:06+01:00 DEBUG: [androidc1771218180] Initializing
message diff engine for Calendar
2012-03-01T15:53:06+01:00 DEBUG:
Horde_ActiveSync_Driver_Horde::getServerChanges(Calendar, 1330591617,
1330613586, 0)
2012-03-01T15:53:06+01:00 DEBUG: [androidc1771218180] Found 0 message
changes, checking for PIM initiated changes.
2012-03-01T15:53:11+01:00 DEBUG: [androidc1771218180] Attempting to load
PING state for: Contacts
2012-03-01T15:53:11+01:00 DEBUG: [androidc1771218180] Obtained last sync
time for Contacts - 1330595063
2012-03-01T15:53:11+01:00 DEBUG: [androidc1771218180] Initializing
message diff engine for Contacts
2012-03-01T15:53:11+01:00 DEBUG:
Horde_ActiveSync_Driver_Horde::getServerChanges(Contacts, 1330595063,
1330613591, 0)
2012-03-01T15:53:11+01:00 DEBUG: [androidc1771218180] Found 0 message
changes, checking for PIM initiated changes.
2012-03-01T15:53:11+01:00 DEBUG: [androidc1771218180] Attempting to load
PING state for: Calendar
2012-03-01T15:53:11+01:00 DEBUG: [androidc1771218180] Obtained last sync
time for Calendar - 1330591617
2012-03-01T15:53:11+01:00 DEBUG: [androidc1771218180] Initializing
message diff engine for Calendar
2012-03-01T15:53:11+01:00 DEBUG:
Horde_ActiveSync_Driver_Horde::getServerChanges(Calendar, 1330591617,
1330613591, 0)
2012-03-01T15:53:11+01:00 DEBUG: [androidc1771218180] Found 0 message
changes, checking for PIM initiated changes.
2012-03-01T15:53:15+01:00 INFO: Horde_ActiveSync_Driver_Horde::logon
attempt for: user at example.org
2012-03-01T15:53:16+01:00 DEBUG: [androidc1771218180] FOLDERSYNC request
received for user user at example.org
2012-03-01T15:53:16+01:00 DEBUG: [androidc1771218180] loadDeviceInfo:
user at example.org
2012-03-01T15:53:16+01:00 INFO: Request being handled for device:
androidc1771218180 Supporting protocol version: 2.5
2012-03-01T15:53:16+01:00 INFO: [Horde_ActiveSync::handleFolderSync]
Beginning FOLDERSYNC
2012-03-01T15:53:16+01:00 DEBUG: [androidc1771218180] Checking policykey
for device Key: 0 User: user at example.org
2012-03-01T15:53:16+01:00 DEBUG: [androidc1771218180] loadDeviceInfo:
user at example.org
2012-03-01T15:53:16+01:00 DEBUG: Policykey: 0 verified.
2012-03-01T15:53:16+01:00 DEBUG: I <FolderHierarchy:FolderSync>
2012-03-01T15:53:16+01:00 DEBUG: I <FolderHierarchy:SyncKey>
2012-03-01T15:53:16+01:00 DEBUG: I
{4f317f92-faa0-4442-b82a-7b75551fbb0c}1
2012-03-01T15:53:16+01:00 DEBUG: I </FolderHierarchy:SyncKey>
2012-03-01T15:53:16+01:00 DEBUG: [Horde_ActiveSync::handleFolderSync]
syncKey: {4f317f92-faa0-4442-b82a-7b75551fbb0c}1
2012-03-01T15:53:16+01:00 DEBUG: [androidc1771218180] Loading state for
synckey {4f317f92-faa0-4442-b82a-7b75551fbb0c}1
2012-03-01T15:53:16+01:00 DEBUG: [androidc1771218180] Loading FOLDERSYNC
state: Array
(
[0] => Array
(
[id] => Tasks
[mod] => Tasks
[parent] => 0
)
[1] => Array
(
[id] => Inbox
[mod] => Inbox
[parent] => 0
)
[2] => Array
(
[id] => Contacts
[mod] => Contacts
[parent] => 0
)
[3] => Array
(
[id] => Calendar
[mod] => Calendar
[parent] => 0
)
)
2012-03-01T15:53:16+01:00 DEBUG: I </FolderHierarchy:FolderSync>
2012-03-01T15:53:16+01:00 DEBUG: [Horde_ActiveSync::handleFolderSync]
Preparing to send changes to PIM
2012-03-01T15:53:16+01:00 DEBUG: [Horde_ActiveSync::handleFolderSync]
newSyncKey: {4f317f92-faa0-4442-b82a-7b75551fbb0c}2
2012-03-01T15:53:16+01:00 DEBUG: [androidc1771218180] Initializing
folder diff engine
2012-03-01T15:53:16+01:00 DEBUG: Horde::getFolderList()
2012-03-01T15:53:16+01:00 DEBUG: Horde::statFolder(Calendar)
2012-03-01T15:53:16+01:00 DEBUG: Horde::statFolder(Contacts)
2012-03-01T15:53:16+01:00 DEBUG: Horde::statFolder(Tasks)
2012-03-01T15:53:16+01:00 DEBUG: Horde::statFolder(Inbox)
2012-03-01T15:53:16+01:00 DEBUG: [androidc1771218180] Found 0 folder changes
2012-03-01T15:53:16+01:00 DEBUG: O <FolderHierarchy:FolderSync>
2012-03-01T15:53:16+01:00 DEBUG: O <FolderHierarchy:Status>
2012-03-01T15:53:16+01:00 DEBUG: O 1
2012-03-01T15:53:16+01:00 DEBUG: O <FolderHierarchy:Status/>
2012-03-01T15:53:16+01:00 DEBUG: O <FolderHierarchy:SyncKey>
2012-03-01T15:53:16+01:00 DEBUG: O
{4f317f92-faa0-4442-b82a-7b75551fbb0c}1
2012-03-01T15:53:16+01:00 DEBUG: O <FolderHierarchy:SyncKey/>
2012-03-01T15:53:16+01:00 DEBUG: O <FolderHierarchy:Changes>
2012-03-01T15:53:16+01:00 DEBUG: O <FolderHierarchy:Count>
2012-03-01T15:53:16+01:00 DEBUG: O 0
2012-03-01T15:53:16+01:00 DEBUG: O <FolderHierarchy:Count/>
2012-03-01T15:53:16+01:00 DEBUG: O <FolderHierarchy:Changes/>
2012-03-01T15:53:16+01:00 DEBUG: O <FolderHierarchy:FolderSync/>
2012-03-01T15:53:16+01:00 INFO: User user at example.org logged off
2012-03-01T15:53:16+01:00 DEBUG: [androidc1771218180] Attempting to load
PING state for: Contacts
2012-03-01T15:53:16+01:00 DEBUG: [androidc1771218180] Obtained last sync
time for Contacts - 1330595063
2012-03-01T15:53:16+01:00 DEBUG: [androidc1771218180] Initializing
message diff engine for Contacts
2012-03-01T15:53:16+01:00 DEBUG:
Horde_ActiveSync_Driver_Horde::getServerChanges(Contacts, 1330595063,
1330613596, 0)
2012-03-01T15:53:16+01:00 DEBUG: [androidc1771218180] Found 0 message
changes, checking for PIM initiated changes.
2012-03-01T15:53:16+01:00 DEBUG: [androidc1771218180] Attempting to load
PING state for: Calendar
2012-03-01T15:53:16+01:00 DEBUG: [androidc1771218180] Obtained last sync
time for Calendar - 1330591617
2012-03-01T15:53:16+01:00 DEBUG: [androidc1771218180] Initializing
message diff engine for Calendar
2012-03-01T15:53:16+01:00 DEBUG:
Horde_ActiveSync_Driver_Horde::getServerChanges(Calendar, 1330591617,
1330613596, 0)
2012-03-01T15:53:16+01:00 DEBUG: [androidc1771218180] Found 0 message
changes, checking for PIM initiated changes.
2012-03-01T15:53:17+01:00 INFO: Horde_ActiveSync_Driver_Horde::logon
attempt for: user at example.org
2012-03-01T15:53:17+01:00 DEBUG: [androidc1771218180] PING request
received for user user at example.org
2012-03-01T15:53:17+01:00 DEBUG: [androidc1771218180] loadDeviceInfo:
user at example.org
2012-03-01T15:53:17+01:00 INFO: Request being handled for device:
androidc1771218180 Supporting protocol version: 2.5
2012-03-01T15:53:17+01:00 INFO: [androidc1771218180] PING received at
timestamp: 1330613597.
2012-03-01T15:53:17+01:00 DEBUG: [androidc1771218180] loadDeviceInfo:
user at example.org
2012-03-01T15:53:17+01:00 DEBUG: I <Ping:Ping>
2012-03-01T15:53:17+01:00 DEBUG: I <Ping:HeartbeatInterval>
2012-03-01T15:53:17+01:00 DEBUG: I 470
2012-03-01T15:53:17+01:00 DEBUG: I </Ping:HeartbeatInterval>
2012-03-01T15:53:17+01:00 DEBUG: I <Ping:Folders>
2012-03-01T15:53:17+01:00 DEBUG: I <Ping:Folder>
2012-03-01T15:53:17+01:00 DEBUG: I <Ping:ServerEntryId>
2012-03-01T15:53:17+01:00 DEBUG: I Contacts
2012-03-01T15:53:17+01:00 DEBUG: I </Ping:ServerEntryId>
2012-03-01T15:53:17+01:00 DEBUG: I <Ping:FolderType>
2012-03-01T15:53:17+01:00 DEBUG: I Contacts
2012-03-01T15:53:17+01:00 DEBUG: I </Ping:FolderType>
2012-03-01T15:53:17+01:00 DEBUG: I </Ping:Folder>
2012-03-01T15:53:17+01:00 DEBUG: I <Ping:Folder>
2012-03-01T15:53:17+01:00 DEBUG: I <Ping:ServerEntryId>
2012-03-01T15:53:17+01:00 DEBUG: I Calendar
2012-03-01T15:53:17+01:00 DEBUG: I </Ping:ServerEntryId>
2012-03-01T15:53:17+01:00 DEBUG: I <Ping:FolderType>
2012-03-01T15:53:17+01:00 DEBUG: I Calendar
2012-03-01T15:53:17+01:00 DEBUG: I </Ping:FolderType>
2012-03-01T15:53:17+01:00 DEBUG: I </Ping:Folder>
2012-03-01T15:53:17+01:00 DEBUG: I </Ping:Folders>
2012-03-01T15:53:17+01:00 DEBUG: I </Ping:Ping>
2012-03-01T15:53:17+01:00 INFO: [androidc1771218180] Waiting for changes
(heartbeat interval: 470)
2012-03-01T15:53:17+01:00 DEBUG: [androidc1771218180] Attempting to load
PING state for: Contacts
2012-03-01T15:53:17+01:00 DEBUG: [androidc1771218180] Obtained last sync
time for Contacts - 1330595063
2012-03-01T15:53:17+01:00 DEBUG: [androidc1771218180] Initializing
message diff engine for Contacts
2012-03-01T15:53:17+01:00 DEBUG:
Horde_ActiveSync_Driver_Horde::getServerChanges(Contacts, 1330595063,
1330613597, 0)
2012-03-01T15:53:17+01:00 DEBUG: [androidc1771218180] Found 0 message
changes, checking for PIM initiated changes.
2012-03-01T15:53:17+01:00 DEBUG: [androidc1771218180] Attempting to load
PING state for: Calendar
2012-03-01T15:53:17+01:00 DEBUG: [androidc1771218180] Obtained last sync
time for Calendar - 1330591617
2012-03-01T15:53:17+01:00 DEBUG: [androidc1771218180] Initializing
message diff engine for Calendar
2012-03-01T15:53:17+01:00 DEBUG:
Horde_ActiveSync_Driver_Horde::getServerChanges(Calendar, 1330591617,
1330613597, 0)
2012-03-01T15:53:17+01:00 DEBUG: [androidc1771218180] Found 0 message
changes, checking for PIM initiated changes.
2012-03-01T15:53:21+01:00 DEBUG: [androidc1771218180] Attempting to load
PING state for: Contacts
2012-03-01T15:53:21+01:00 DEBUG: [androidc1771218180] Obtained last sync
time for Contacts - 1330595063
2012-03-01T15:53:21+01:00 DEBUG: [androidc1771218180] Initializing
message diff engine for Contacts
2012-03-01T15:53:21+01:00 DEBUG:
Horde_ActiveSync_Driver_Horde::getServerChanges(Contacts, 1330595063,
1330613601, 0)
2012-03-01T15:53:21+01:00 DEBUG: [androidc1771218180] Found 0 message
changes, checking for PIM initiated changes.
2012-03-01T15:53:21+01:00 DEBUG: [androidc1771218180] Attempting to load
PING state for: Calendar
2012-03-01T15:53:21+01:00 DEBUG: [androidc1771218180] Obtained last sync
time for Calendar - 1330591617
2012-03-01T15:53:21+01:00 DEBUG: [androidc1771218180] Initializing
message diff engine for Calendar
2012-03-01T15:53:21+01:00 DEBUG:
Horde_ActiveSync_Driver_Horde::getServerChanges(Calendar, 1330591617,
1330613601, 0)
2012-03-01T15:53:21+01:00 DEBUG: [androidc1771218180] Found 0 message
changes, checking for PIM initiated changes.
2012-03-01T15:53:22+01:00 DEBUG: [androidc1771218180] Attempting to load
PING state for: Contacts
2012-03-01T15:53:22+01:00 DEBUG: [androidc1771218180] Obtained last sync
time for Contacts - 1330595063
2012-03-01T15:53:22+01:00 DEBUG: [androidc1771218180] Initializing
message diff engine for Contacts
2012-03-01T15:53:22+01:00 DEBUG:
Horde_ActiveSync_Driver_Horde::getServerChanges(Contacts, 1330595063,
1330613602, 0)
2012-03-01T15:53:22+01:00 DEBUG: [androidc1771218180] Found 0 message
changes, checking for PIM initiated changes.
2012-03-01T15:53:22+01:00 DEBUG: [androidc1771218180] Attempting to load
PING state for: Calendar
2012-03-01T15:53:22+01:00 DEBUG: [androidc1771218180] Obtained last sync
time for Calendar - 1330591617
2012-03-01T15:53:22+01:00 DEBUG: [androidc1771218180] Initializing
message diff engine for Calendar
2012-03-01T15:53:22+01:00 DEBUG:
Horde_ActiveSync_Driver_Horde::getServerChanges(Calendar, 1330591617,
1330613602, 0)
2012-03-01T15:53:22+01:00 DEBUG: [androidc1771218180] Found 0 message
changes, checking for PIM initiated changes.
2012-03-01T15:53:26+01:00 DEBUG: [androidc1771218180] Attempting to load
PING state for: Contacts
2012-03-01T15:53:26+01:00 DEBUG: [androidc1771218180] Obtained last sync
time for Contacts - 1330595063
2012-03-01T15:53:26+01:00 DEBUG: [androidc1771218180] Initializing
message diff engine for Contacts
2012-03-01T15:53:26+01:00 DEBUG:
Horde_ActiveSync_Driver_Horde::getServerChanges(Contacts, 1330595063,
1330613606, 0)
2012-03-01T15:53:26+01:00 DEBUG: [androidc1771218180] Found 0 message
changes, checking for PIM initiated changes.
2012-03-01T15:53:26+01:00 DEBUG: [androidc1771218180] Attempting to load
PING state for: Calendar
2012-03-01T15:53:26+01:00 DEBUG: [androidc1771218180] Obtained last sync
time for Calendar - 1330591617
2012-03-01T15:53:26+01:00 DEBUG: [androidc1771218180] Initializing
message diff engine for Calendar
2012-03-01T15:53:26+01:00 DEBUG:
Horde_ActiveSync_Driver_Horde::getServerChanges(Calendar, 1330591617,
1330613606, 0)
2012-03-01T15:53:26+01:00 DEBUG: [androidc1771218180] Found 0 message
changes, checking for PIM initiated changes.
2012-03-01T15:53:27+01:00 DEBUG: [androidc1771218180] Attempting to load
PING state for: Contacts
2012-03-01T15:53:27+01:00 DEBUG: [androidc1771218180] Obtained last sync
time for Contacts - 1330595063
2012-03-01T15:53:27+01:00 DEBUG: [androidc1771218180] Initializing
message diff engine for Contacts
2012-03-01T15:53:27+01:00 DEBUG:
Horde_ActiveSync_Driver_Horde::getServerChanges(Contacts, 1330595063,
1330613607, 0)
2012-03-01T15:53:27+01:00 DEBUG: [androidc1771218180] Found 0 message
changes, checking for PIM initiated changes.
2012-03-01T15:53:27+01:00 DEBUG: [androidc1771218180] Attempting to load
PING state for: Calendar
2012-03-01T15:53:27+01:00 DEBUG: [androidc1771218180] Obtained last sync
time for Calendar - 1330591617
2012-03-01T15:53:27+01:00 DEBUG: [androidc1771218180] Initializing
message diff engine for Calendar
2012-03-01T15:53:27+01:00 DEBUG:
Horde_ActiveSync_Driver_Horde::getServerChanges(Calendar, 1330591617,
1330613607, 0)
2012-03-01T15:53:28+01:00 DEBUG: [androidc1771218180] Found 0 message
changes, checking for PIM initiated changes.
2012-03-01T15:53:31+01:00 DEBUG: [androidc1771218180] Attempting to load
PING state for: Contacts
2012-03-01T15:53:31+01:00 DEBUG: [androidc1771218180] Obtained last sync
time for Contacts - 1330595063
2012-03-01T15:53:31+01:00 DEBUG: [androidc1771218180] Initializing
message diff engine for Contacts
2012-03-01T15:53:31+01:00 DEBUG:
Horde_ActiveSync_Driver_Horde::getServerChanges(Contacts, 1330595063,
1330613611, 0)
2012-03-01T15:53:31+01:00 DEBUG: [androidc1771218180] Found 0 message
changes, checking for PIM initiated changes.
2012-03-01T15:53:31+01:00 DEBUG: [androidc1771218180] Attempting to load
PING state for: Calendar
2012-03-01T15:53:31+01:00 DEBUG: [androidc1771218180] Obtained last sync
time for Calendar - 1330591617
2012-03-01T15:53:31+01:00 DEBUG: [androidc1771218180] Initializing
message diff engine for Calendar
2012-03-01T15:53:31+01:00 DEBUG:
Horde_ActiveSync_Driver_Horde::getServerChanges(Calendar, 1330591617,
1330613611, 0)
2012-03-01T15:53:31+01:00 DEBUG: [androidc1771218180] Found 0 message
changes, checking for PIM initiated changes.
2012-03-01T15:53:33+01:00 DEBUG: [androidc1771218180] Attempting to load
PING state for: Contacts
2012-03-01T15:53:33+01:00 DEBUG: [androidc1771218180] Obtained last sync
time for Contacts - 1330595063
2012-03-01T15:53:33+01:00 DEBUG: [androidc1771218180] Initializing
message diff engine for Contacts
2012-03-01T15:53:33+01:00 DEBUG:
Horde_ActiveSync_Driver_Horde::getServerChanges(Contacts, 1330595063,
1330613613, 0)
2012-03-01T15:53:33+01:00 DEBUG: [androidc1771218180] Found 0 message
changes, checking for PIM initiated changes.
2012-03-01T15:53:33+01:00 DEBUG: [androidc1771218180] Attempting to load
PING state for: Calendar
2012-03-01T15:53:33+01:00 DEBUG: [androidc1771218180] Obtained last sync
time for Calendar - 1330591617
2012-03-01T15:53:33+01:00 DEBUG: [androidc1771218180] Initializing
message diff engine for Calendar
2012-03-01T15:53:33+01:00 DEBUG:
Horde_ActiveSync_Driver_Horde::getServerChanges(Calendar, 1330591617,
1330613613, 0)
2012-03-01T15:53:33+01:00 DEBUG: [androidc1771218180] Found 0 message
changes, checking for PIM initiated changes.
2012-03-01T15:53:33+01:00 DEBUG: [androidc1771218180] Attempting to load
PING state for: Contacts
2012-03-01T15:53:33+01:00 DEBUG: [androidc1771218180] Obtained last sync
time for Contacts - 1330595063
2012-03-01T15:53:33+01:00 DEBUG: [androidc1771218180] Initializing
message diff engine for Contacts
2012-03-01T15:53:33+01:00 DEBUG:
Horde_ActiveSync_Driver_Horde::getServerChanges(Contacts, 1330595063,
1330613613, 0)
2012-03-01T15:53:33+01:00 DEBUG: [androidc1771218180] Found 0 message
changes, checking for PIM initiated changes.
2012-03-01T15:53:33+01:00 DEBUG: [androidc1771218180] Attempting to load
PING state for: Calendar
2012-03-01T15:53:33+01:00 DEBUG: [androidc1771218180] Obtained last sync
time for Calendar - 1330591617
2012-03-01T15:53:33+01:00 DEBUG: [androidc1771218180] Initializing
message diff engine for Calendar
2012-03-01T15:53:33+01:00 DEBUG:
Horde_ActiveSync_Driver_Horde::getServerChanges(Calendar, 1330591617,
1330613613, 0)
2012-03-01T15:53:33+01:00 DEBUG: [androidc1771218180] Found 0 message
changes, checking for PIM initiated changes.
2012-03-01T15:53:38+01:00 DEBUG: [androidc1771218180] Attempting to load
PING state for: Contacts
2012-03-01T15:53:38+01:00 DEBUG: [androidc1771218180] Obtained last sync
time for Contacts - 1330595063
2012-03-01T15:53:38+01:00 DEBUG: [androidc1771218180] Initializing
message diff engine for Contacts
2012-03-01T15:53:38+01:00 DEBUG:
Horde_ActiveSync_Driver_Horde::getServerChanges(Contacts, 1330595063,
1330613618, 0)
2012-03-01T15:53:38+01:00 DEBUG: [androidc1771218180] Found 0 message
changes, checking for PIM initiated changes.
2012-03-01T15:53:38+01:00 DEBUG: [androidc1771218180] Attempting to load
PING state for: Calendar
2012-03-01T15:53:38+01:00 DEBUG: [androidc1771218180] Obtained last sync
time for Calendar - 1330591617
2012-03-01T15:53:38+01:00 DEBUG: [androidc1771218180] Initializing
message diff engine for Calendar
2012-03-01T15:53:38+01:00 DEBUG:
Horde_ActiveSync_Driver_Horde::getServerChanges(Calendar, 1330591617,
1330613618, 0)
2012-03-01T15:53:38+01:00 DEBUG: [androidc1771218180] Found 0 message
changes, checking for PIM initiated changes.
2012-03-01T15:53:38+01:00 DEBUG: [androidc1771218180] Attempting to load
PING state for: Contacts
2012-03-01T15:53:38+01:00 DEBUG: [androidc1771218180] Obtained last sync
time for Contacts - 1330595063
2012-03-01T15:53:38+01:00 DEBUG: [androidc1771218180] Initializing
message diff engine for Contacts
2012-03-01T15:53:38+01:00 DEBUG:
Horde_ActiveSync_Driver_Horde::getServerChanges(Contacts, 1330595063,
1330613618, 0)
2012-03-01T15:53:38+01:00 DEBUG: [androidc1771218180] Found 0 message
changes, checking for PIM initiated changes.
2012-03-01T15:53:38+01:00 DEBUG: [androidc1771218180] Attempting to load
PING state for: Calendar
2012-03-01T15:53:38+01:00 DEBUG: [androidc1771218180] Obtained last sync
time for Calendar - 1330591617
2012-03-01T15:53:38+01:00 DEBUG: [androidc1771218180] Initializing
message diff engine for Calendar
2012-03-01T15:53:38+01:00 DEBUG:
Horde_ActiveSync_Driver_Horde::getServerChanges(Calendar, 1330591617,
1330613618, 0)
2012-03-01T15:53:38+01:00 DEBUG: [androidc1771218180] Found 0 message
changes, checking for PIM initiated changes.
2012-03-01T15:53:43+01:00 DEBUG: [androidc1771218180] Attempting to load
PING state for: Contacts
2012-03-01T15:53:43+01:00 DEBUG: [androidc1771218180] Obtained last sync
time for Contacts - 1330595063
2012-03-01T15:53:43+01:00 DEBUG: [androidc1771218180] Initializing
message diff engine for Contacts
2012-03-01T15:53:43+01:00 DEBUG:
Horde_ActiveSync_Driver_Horde::getServerChanges(Contacts, 1330595063,
1330613623, 0)
2012-03-01T15:53:43+01:00 DEBUG: [androidc1771218180] Found 0 message
changes, checking for PIM initiated changes.
2012-03-01T15:53:43+01:00 DEBUG: [androidc1771218180] Attempting to load
PING state for: Calendar
2012-03-01T15:53:43+01:00 DEBUG: [androidc1771218180] Obtained last sync
time for Calendar - 1330591617
2012-03-01T15:53:43+01:00 DEBUG: [androidc1771218180] Initializing
message diff engine for Calendar
2012-03-01T15:53:43+01:00 DEBUG:
Horde_ActiveSync_Driver_Horde::getServerChanges(Calendar, 1330591617,
1330613623, 0)
2012-03-01T15:53:43+01:00 DEBUG: [androidc1771218180] Found 0 message
changes, checking for PIM initiated changes.
2012-03-01T15:53:45+01:00 INFO: Horde_ActiveSync_Driver_Horde::logon
attempt for: user at example.org
2012-03-01T15:53:45+01:00 DEBUG: [androidc1771218180] SYNC request
received for user user at example.org
2012-03-01T15:53:45+01:00 DEBUG: [androidc1771218180] loadDeviceInfo:
user at example.org
2012-03-01T15:53:45+01:00 INFO: Request being handled for device:
androidc1771218180 Supporting protocol version: 2.5
2012-03-01T15:53:45+01:00 INFO: [androidc1771218180] Handling SYNC command.
2012-03-01T15:53:45+01:00 DEBUG: [androidc1771218180] Checking policykey
for device Key: 0 User: user at example.org
2012-03-01T15:53:45+01:00 DEBUG: [androidc1771218180] loadDeviceInfo:
user at example.org
2012-03-01T15:53:45+01:00 DEBUG: Policykey: 0 verified.
2012-03-01T15:53:45+01:00 DEBUG: I <Synchronize>
2012-03-01T15:53:45+01:00 DEBUG: I <Folders>
2012-03-01T15:53:45+01:00 DEBUG: I <Folder>
2012-03-01T15:53:45+01:00 DEBUG: I <FolderType>
2012-03-01T15:53:45+01:00 DEBUG: I Contacts
2012-03-01T15:53:45+01:00 INFO: [androidc1771218180] Syncing folder
class: Contacts
2012-03-01T15:53:45+01:00 DEBUG: I </FolderType>
2012-03-01T15:53:45+01:00 DEBUG: I <SyncKey>
2012-03-01T15:53:45+01:00 DEBUG: I 0
2012-03-01T15:53:45+01:00 DEBUG: I </SyncKey>
2012-03-01T15:53:45+01:00 DEBUG: I <FolderId>
2012-03-01T15:53:45+01:00 DEBUG: I Contacts
2012-03-01T15:53:45+01:00 INFO: [androidc1771218180] Folder server id:
Contacts
2012-03-01T15:53:45+01:00 DEBUG: I </FolderId>
2012-03-01T15:53:45+01:00 DEBUG: I </Folder>
2012-03-01T15:53:45+01:00 DEBUG: [androidc1771218180] Resetting device
state.
2012-03-01T15:53:45+01:00 DEBUG: I </Folders>
2012-03-01T15:53:45+01:00 DEBUG: I </Synchronize>
2012-03-01T15:53:45+01:00 INFO: [androidc1771218180] Beginning SYNC
Response.
2012-03-01T15:53:45+01:00 DEBUG: Generating new synckey. Old synckey: 0
2012-03-01T15:53:45+01:00 DEBUG: New synckey generated:
{4f4f8d79-f500-4d32-b5ef-1de3551fbb0c}1
2012-03-01T15:53:45+01:00 DEBUG: O <Synchronize>
2012-03-01T15:53:45+01:00 DEBUG: O <Folders>
2012-03-01T15:53:45+01:00 DEBUG: O <Folder>
2012-03-01T15:53:45+01:00 DEBUG: O <FolderType>
2012-03-01T15:53:45+01:00 DEBUG: O Contacts
2012-03-01T15:53:45+01:00 DEBUG: O <FolderType/>
2012-03-01T15:53:45+01:00 DEBUG: O <SyncKey>
2012-03-01T15:53:45+01:00 DEBUG: O
{4f4f8d79-f500-4d32-b5ef-1de3551fbb0c}1
2012-03-01T15:53:45+01:00 DEBUG: O <SyncKey/>
2012-03-01T15:53:45+01:00 DEBUG: O <FolderId>
2012-03-01T15:53:45+01:00 DEBUG: O Contacts
2012-03-01T15:53:45+01:00 DEBUG: O <FolderId/>
2012-03-01T15:53:45+01:00 DEBUG: O <Status>
2012-03-01T15:53:45+01:00 DEBUG: O 1
2012-03-01T15:53:45+01:00 DEBUG: O <Status/>
2012-03-01T15:53:45+01:00 DEBUG: O <Folder/>
2012-03-01T15:53:45+01:00 DEBUG: [androidc1771218180] Saving state: Array
(
[0] => {4f4f8d79-f500-4d32-b5ef-1de3551fbb0c}1
[1] =>
[2] => androidc1771218180
[3] => 0
[4] => Contacts
[5] => user at example.org
)
2012-03-01T15:53:45+01:00 DEBUG: O <Folders/>
2012-03-01T15:53:45+01:00 DEBUG: O <Synchronize/>
2012-03-01T15:53:45+01:00 INFO: User user at example.org logged off
2012-03-01T15:53:46+01:00 INFO: Horde_ActiveSync_Driver_Horde::logon
attempt for: user at example.org
2012-03-01T15:53:47+01:00 DEBUG: [androidc1771218180] SYNC request
received for user user at example.org
2012-03-01T15:53:47+01:00 DEBUG: [androidc1771218180] loadDeviceInfo:
user at example.org
2012-03-01T15:53:47+01:00 INFO: Request being handled for device:
androidc1771218180 Supporting protocol version: 2.5
2012-03-01T15:53:47+01:00 INFO: [androidc1771218180] Handling SYNC command.
2012-03-01T15:53:47+01:00 DEBUG: [androidc1771218180] Checking policykey
for device Key: 0 User: user at example.org
2012-03-01T15:53:47+01:00 DEBUG: [androidc1771218180] loadDeviceInfo:
user at example.org
2012-03-01T15:53:47+01:00 DEBUG: Policykey: 0 verified.
2012-03-01T15:53:47+01:00 DEBUG: I <Synchronize>
2012-03-01T15:53:47+01:00 DEBUG: I <Folders>
2012-03-01T15:53:47+01:00 DEBUG: I <Folder>
2012-03-01T15:53:47+01:00 DEBUG: I <FolderType>
2012-03-01T15:53:47+01:00 DEBUG: I Contacts
2012-03-01T15:53:47+01:00 INFO: [androidc1771218180] Syncing folder
class: Contacts
2012-03-01T15:53:47+01:00 DEBUG: I </FolderType>
2012-03-01T15:53:47+01:00 DEBUG: I <SyncKey>
2012-03-01T15:53:47+01:00 DEBUG: I
{4f4f8d79-f500-4d32-b5ef-1de3551fbb0c}1
2012-03-01T15:53:47+01:00 DEBUG: I </SyncKey>
2012-03-01T15:53:47+01:00 DEBUG: I <FolderId>
2012-03-01T15:53:47+01:00 DEBUG: I Contacts
2012-03-01T15:53:47+01:00 INFO: [androidc1771218180] Folder server id:
Contacts
2012-03-01T15:53:47+01:00 DEBUG: I </FolderId>
2012-03-01T15:53:47+01:00 DEBUG: I <DeletesAsMoves/>
2012-03-01T15:53:47+01:00 DEBUG: I <GetChanges/>
2012-03-01T15:53:47+01:00 DEBUG: I <WindowSize>
2012-03-01T15:53:47+01:00 DEBUG: I 4
2012-03-01T15:53:47+01:00 DEBUG: I </WindowSize>
2012-03-01T15:53:47+01:00 DEBUG: I <Options>
2012-03-01T15:53:47+01:00 DEBUG: I <Truncation>
2012-03-01T15:53:47+01:00 DEBUG: I 7
2012-03-01T15:53:47+01:00 DEBUG: I </Truncation>
2012-03-01T15:53:47+01:00 DEBUG: I </Options>
2012-03-01T15:53:47+01:00 DEBUG: [androidc1771218180] Loading state for
synckey {4f4f8d79-f500-4d32-b5ef-1de3551fbb0c}1
2012-03-01T15:53:47+01:00 DEBUG: I </Folder>
2012-03-01T15:53:47+01:00 DEBUG: I </Folders>
2012-03-01T15:53:47+01:00 DEBUG: I </Synchronize>
2012-03-01T15:53:47+01:00 INFO: [androidc1771218180] Beginning SYNC
Response.
2012-03-01T15:53:47+01:00 DEBUG: [androidc1771218180] Initializing
message diff engine for Contacts
2012-03-01T15:53:47+01:00 DEBUG:
Horde_ActiveSync_Driver_Horde::getServerChanges(Contacts, 0, 1330613627, 0)
2012-03-01T15:53:47+01:00 DEBUG: [androidc1771218180] Found 107 message
changes, checking for PIM initiated changes.
2012-03-01T15:53:47+01:00 DEBUG: [androidc1771218180] No PIM changes
present, returning all messages.
... data blanked ...
2012-03-01T15:53:50+01:00 DEBUG: Generating new synckey. Old synckey:
{4f4f8d79-f500-4d32-b5ef-1de3551fbb0c}2
2012-03-01T15:53:50+01:00 DEBUG: New synckey generated:
{4f4f8d79-f500-4d32-b5ef-1de3551fbb0c}3
2012-03-01T15:53:50+01:00 DEBUG: O <Synchronize>
2012-03-01T15:53:50+01:00 DEBUG: O <Folders>
2012-03-01T15:53:50+01:00 DEBUG: O <Folder>
2012-03-01T15:53:50+01:00 DEBUG: O <FolderType>
2012-03-01T15:53:50+01:00 DEBUG: O Contacts
2012-03-01T15:53:50+01:00 DEBUG: O <FolderType/>
2012-03-01T15:53:50+01:00 DEBUG: O <SyncKey>
2012-03-01T15:53:50+01:00 DEBUG: O
{4f4f8d79-f500-4d32-b5ef-1de3551fbb0c}3
2012-03-01T15:53:50+01:00 DEBUG: O <SyncKey/>
2012-03-01T15:53:50+01:00 DEBUG: O <FolderId>
2012-03-01T15:53:50+01:00 DEBUG: O Contacts
2012-03-01T15:53:50+01:00 DEBUG: O <FolderId/>
2012-03-01T15:53:50+01:00 DEBUG: O <Status>
2012-03-01T15:53:50+01:00 DEBUG: O 1
2012-03-01T15:53:50+01:00 DEBUG: O <Status/>
2012-03-01T15:53:50+01:00 DEBUG: O <MoreAvailable/>
2012-03-01T15:53:50+01:00 DEBUG: Horde::getMessage(Contacts,
20120203063731.SAPZZYgRJpVPK3KblD_13HA at domain.example.org)
2012-03-01T15:53:50+01:00 DEBUG: O <Commands>
2012-03-01T15:53:50+01:00 DEBUG: O <Add>
2012-03-01T15:53:50+01:00 DEBUG: O <ServerEntryId>
2012-03-01T15:53:50+01:00 DEBUG: O
20120203063729.qCTRYogRJpVPK3KZD3eF3HA at domain.example.org
2012-03-01T15:53:50+01:00 DEBUG: O <ServerEntryId/>
2012-03-01T15:53:50+01:00 DEBUG: O <Data>
2012-03-01T15:53:50+01:00 DEBUG: O <POOMCONTACTS:Birthday>
2012-03-01T15:53:50+01:00 DEBUG: O 1954-09-21T23:00:00.000Z
(Entry in DB is: 1954-09-22)
2012-03-01T15:53:50+01:00 DEBUG: O <POOMCONTACTS:Birthday/>
2012-03-01T15:53:50+01:00 DEBUG: O <POOMCONTACTS:BodySize>
2012-03-01T15:53:50+01:00 DEBUG: O 0
2012-03-01T15:53:50+01:00 DEBUG: O <POOMCONTACTS:BodySize/>
2012-03-01T15:53:50+01:00 DEBUG: O <POOMCONTACTS:BodyTruncated>
2012-03-01T15:53:50+01:00 DEBUG: O 0
2012-03-01T15:53:50+01:00 DEBUG: O <POOMCONTACTS:BodyTruncated/>
2012-03-01T15:53:50+01:00 DEBUG: O <POOMCONTACTS:BusinessCity>
2012-03-01T15:53:50+01:00 DEBUG: O City
2012-03-01T15:53:50+01:00 DEBUG: O <POOMCONTACTS:BusinessCity/>
2012-03-01T15:53:50+01:00 DEBUG: O <POOMCONTACTS:BusinessPostalCode>
2012-03-01T15:53:50+01:00 DEBUG: O Postal code
2012-03-01T15:53:50+01:00 DEBUG: O <POOMCONTACTS:BusinessPostalCode/>
2012-03-01T15:53:50+01:00 DEBUG: O <POOMCONTACTS:BusinessStreet>
2012-03-01T15:53:50+01:00 DEBUG: O Street
2012-03-01T15:53:50+01:00 DEBUG: O <POOMCONTACTS:BusinessStreet/>
2012-03-01T15:53:50+01:00 DEBUG: O <POOMCONTACTS:Email1Address>
2012-03-01T15:53:50+01:00 DEBUG: O email
2012-03-01T15:53:50+01:00 DEBUG: O <POOMCONTACTS:Email1Address/>
2012-03-01T15:53:50+01:00 DEBUG: O <POOMCONTACTS:FileAs>
2012-03-01T15:53:50+01:00 DEBUG: O LastName, FirstName
2012-03-01T15:53:50+01:00 DEBUG: O <POOMCONTACTS:FileAs/>
2012-03-01T15:53:50+01:00 DEBUG: O <POOMCONTACTS:FirstName>
2012-03-01T15:53:50+01:00 DEBUG: O FirstName
2012-03-01T15:53:50+01:00 DEBUG: O <POOMCONTACTS:FirstName/>
2012-03-01T15:53:50+01:00 DEBUG: O <POOMCONTACTS:HomePhoneNumber>
2012-03-01T15:53:50+01:00 DEBUG: O +123456789
2012-03-01T15:53:50+01:00 DEBUG: O <POOMCONTACTS:HomePhoneNumber/>
2012-03-01T15:53:50+01:00 DEBUG: O <POOMCONTACTS:LastName>
2012-03-01T15:53:50+01:00 DEBUG: O LastName
2012-03-01T15:53:50+01:00 DEBUG: O <POOMCONTACTS:LastName/>
2012-03-01T15:53:50+01:00 DEBUG: O <POOMCONTACTS:MobilePhoneNumber>
2012-03-01T15:53:50+01:00 DEBUG: O +123456789
2012-03-01T15:53:50+01:00 DEBUG: O <POOMCONTACTS:MobilePhoneNumber/>
2012-03-01T15:53:50+01:00 DEBUG: O <Data/>
2012-03-01T15:53:50+01:00 DEBUG: O <Add/>
2012-03-01T15:53:50+01:00 DEBUG: Updating state during change
2012-03-01T15:53:50+01:00 INFO: [androidc1771218180] Exported maxItems
of messages: 4 - more available.
2012-03-01T15:53:50+01:00 DEBUG: O <Commands/>
2012-03-01T15:53:50+01:00 DEBUG: O <Folder/>
2012-03-01T15:53:50+01:00 DEBUG: [androidc1771218180] Saving state: Array
(
[0] => {4f4f8d79-f500-4d32-b5ef-1de3551fbb0c}3
[1] =>
a:99:{i:0;a:3:{s:2:"id";s:56:"20120203063727.XnEULYgRJpVPK3KXU6X13HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:1;a:3:{s:2:"id";s:56:"20120203063729.fhkCH4gRJpVPK3KZwzR13HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:2;a:3:{s:2:"id";s:56:"20120203063726.7uULXogRJpVPK3KWS6RV3HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:3;a:3:{s:2:"id";s:56:"20120203063724.OdfwfYgRJpVPK3KUWlm13HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:4;a:3:{s:2:"id";s:56:"20120203063725.8qUCF4gRJpVPK3KVtxT13HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:5;a:3:{s:2:"id";s:56:"20120203063728.E6SwFogRJpVPK3KYliiV3HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:6;a:3:{s:2:"id";s:56:"20120203063730.RpXAHogRJpVPK3KanIdF3HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:7;a:3:{s:2:"id";
s:56:"2
0120203063730.dNVVTYgRJpVPK3KasPoF3HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:8;a:3:{s:2:"id";s:56:"20120203063726.KkeSBogRJpVPK3KWayM13HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:9;a:3:{s:2:"id";s:56:"20120203063725.4diOZ4gRJpVPK3KVMkr13HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:10;a:3:{s:2:"id";s:56:"20120203063728.zJEUX4gRJpVPK3KY3N6l3HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:11;a:3:{s:2:"id";s:56:"20120203063724.z9SYCYgRJpVPK3KU88o13HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:12;a:3:{s:2:"id";s:56:"20120203063726.AWX8SIgRJpVPK3KW21Hl3HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:13;a:3:{s:2:"id";s:56:"20120203063724.QGsWaogRJpVPK3KU0ydF3HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:14;a:3:{s:2:"id";s:56:"20120203063731.K1QKW
YgRJpVP
K
3KbSld13HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:15;a:3:{s:2:"id";s:56:"20120203063724.7zR1MogRJpVPK3KUpCOl3HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:16;a:3:{s:2:"id";s:56:"20120301104423.OAFVaYgRJpVPT0T3GXgBFTA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:17;a:3:{s:2:"id";s:56:"20120203063727.BI0IEIgRJpVPK3KXhk8V3HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:18;a:3:{s:2:"id";s:56:"20120203063727.OHxbWYgRJpVPK3KXJMnV3HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:19;a:3:{s:2:"id";s:56:"20120203063724.Mj3-PogRJpVPK3KUr-rV3HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:20;a:3:{s:2:"id";s:56:"20120203063728.j3EsH4gRJpVPK3KYX_yV3HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:21;a:3:{s:2:"id";s:56:"20120203063730.evrdbYgRJpVPK3KaOuiV3HA at domain
.exampl
e
.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:22;a:3:{s:2:"id";s:56:"20120203063727.YBWseYgRJpVPK3KX5Fa13HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:23;a:3:{s:2:"id";s:56:"20120203063725.1p3QbIgRJpVPK3KVqrAl3HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:24;a:3:{s:2:"id";s:56:"20120301104422.kk36V4gRJpVPT0T2SYfhFTA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:25;a:3:{s:2:"id";s:56:"20120301104422.HYBLMYgRJpVPT0T2cVoRFTA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:26;a:3:{s:2:"id";s:56:"20120203063727.fHNfc4gRJpVPK3KXZHJF3HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:27;a:3:{s:2:"id";s:56:"20120203063728.olPEXogRJpVPK3KYUfMl3HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:28;a:3:{s:2:"id";s:56:"20120203063731.svutbogRJpVPK3Kbc-1V3HA at domain.example.org";s:4:"type";
s:6:"ch
a
nge";s:5:"flags";s:10:"NewMessage";}i:29;a:3:{s:2:"id";s:56:"20120203063727.oZdycYgRJpVPK3KXQe413HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:30;a:3:{s:2:"id";s:56:"20120203063730.2uhWSogRJpVPK3KaDTQ13HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:31;a:3:{s:2:"id";s:56:"20120203063727.7BlFY4gRJpVPK3KX0uJV3HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:32;a:3:{s:2:"id";s:56:"20120203063727.x23xN4gRJpVPK3KXtOu13HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:33;a:3:{s:2:"id";s:56:"20120203063726.3Obhe4gRJpVPK3KWE1VF3HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:34;a:3:{s:2:"id";s:56:"20120203063730.RO1DQogRJpVPK3KaxBLl3HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:35;a:3:{s:2:"id";s:56:"20120203063726.HRbSPIgRJpVPK3KWknoV3HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";
s:10:"N
e
wMessage";}i:36;a:3:{s:2:"id";s:56:"20120203063728.T4BCAogRJpVPK3KYo4AF3HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:37;a:3:{s:2:"id";s:56:"20120203063726.__9xeYgRJpVPK3KWyo-V3HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:38;a:3:{s:2:"id";s:56:"20120203063725.FyzwYIgRJpVPK3KVcpfV3HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:39;a:3:{s:2:"id";s:56:"20120203063727.DzhoNogRJpVPK3KXxOE13HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:40;a:3:{s:2:"id";s:56:"20120301104422.g8rCAYgRJpVPT0T25b6RFTA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:41;a:3:{s:2:"id";s:56:"20120203063728.LhQKGogRJpVPK3KYPVQl3HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:42;a:3:{s:2:"id";s:56:"20120203063730.IoQtZIgRJpVPK3KaTsyF3HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:43;a
:3:{s:2
:
"id";s:56:"20120203063729.pga9BogRJpVPK3KZiUi13HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:44;a:3:{s:2:"id";s:56:"20120203063727.n19EdYgRJpVPK3KXBQNl3HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:45;a:3:{s:2:"id";s:56:"20120203063724.QTsQQIgRJpVPK3KUczNl3HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:46;a:3:{s:2:"id";s:56:"20120203063725.imMWBIgRJpVPK3KViPV13HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:47;a:3:{s:2:"id";s:56:"20120203063730.62Fqe4gRJpVPK3KadPpl3HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:48;a:3:{s:2:"id";s:56:"20120203063729.Ysd2WogRJpVPK3KZQfHl3HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:49;a:3:{s:2:"id";s:56:"20120203063726.78DxdogRJpVPK3KWMNDF3HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:50;a:3:{s:2:"id";s:56:"201202
0306372
7
.xFdgOYgRJpVPK3KXNUCF3HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:51;a:3:{s:2:"id";s:56:"20120203063726.-B_2NIgRJpVPK3KWe5213HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:52;a:3:{s:2:"id";s:56:"20120203063725.WUi-O4gRJpVPK3KVSjol3HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:53;a:3:{s:2:"id";s:56:"20120203063731.igZfWIgRJpVPK3KbYrXl3HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:54;a:3:{s:2:"id";s:56:"20120203063725.Fn-nOYgRJpVPK3KVnwcF3HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:55;a:3:{s:2:"id";s:56:"20120301104422.xlqoLYgRJpVPT0T2mGohFTA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:56;a:3:{s:2:"id";s:56:"20120203063725.5JdEXYgRJpVPK3KVJ6kF3HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:57;a:3:{s:2:"id";s:56:"20120203063731.wm3jaIgRJpVPK3Kb
hTHF3HA
@
domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:58;a:3:{s:2:"id";s:56:"20120203063724.C8Q1UogRJpVPK3KUuiLV3HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:59;a:3:{s:2:"id";s:56:"20120203063728.oNtFeIgRJpVPK3KYFES13HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:60;a:3:{s:2:"id";s:56:"20120203063727.IPy9HIgRJpVPK3KXlxlV3HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:61;a:3:{s:2:"id";s:56:"20120203063727.1rjCP4gRJpVPK3KXFBV13HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:62;a:3:{s:2:"id";s:56:"20120203063726.iYfTSIgRJpVPK3KWoUDl3HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:63;a:3:{s:2:"id";s:56:"20120203063726.I0OjWIgRJpVPK3KWrWj13HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:64;a:3:{s:2:"id";s:56:"20120203063728.4_VQaIgRJpVPK3KYgjq13HA at domain.example.or
g";s:4:
"
type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:65;a:3:{s:2:"id";s:56:"20120203063726.H2qUX4gRJpVPK3KWPMXV3HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:66;a:3:{s:2:"id";s:56:"20120203063726.OLqrBIgRJpVPK3KW627F3HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:67;a:3:{s:2:"id";s:56:"20120203063728.te7eeIgRJpVPK3KYcRn13HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:68;a:3:{s:2:"id";s:56:"20120203063725.olTgfIgRJpVPK3KV1s9l3HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:69;a:3:{s:2:"id";s:56:"20120301104422.l6S6GIgRJpVPT0T2vrCRFTA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:70;a:3:{s:2:"id";s:56:"20120203063729.HllEHogRJpVPK3KZKalF3HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:71;a:3:{s:2:"id";s:56:"20120203063725.XAVYZ4gRJpVPK3KV4tOF3HA at domain.example.org";s:4:"type";s:6:"change
";s:5:"
f
lags";s:10:"NewMessage";}i:72;a:3:{s:2:"id";s:56:"20120203063729.Mzp3J4gRJpVPK3KZr5Hl3HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:73;a:3:{s:2:"id";s:56:"20120203063730.JM5YPIgRJpVPK3KaYor13HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:74;a:3:{s:2:"id";s:56:"20120203063725.OC41QogRJpVPK3KVG_yV3HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:75;a:3:{s:2:"id";s:56:"20120203063729.ACuOX4gRJpVPK3KZ1qwF3HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:76;a:3:{s:2:"id";s:56:"20120203063725.g5eSNYgRJpVPK3KVVa-F3HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:77;a:3:{s:2:"id";s:56:"20120203063726.Xc2xdogRJpVPK3KWWpHl3HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:78;a:3:{s:2:"id";s:56:"20120203063728.Xp1MaogRJpVPK3KY7dRV3HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMe
ssage";
}
i:79;a:3:{s:2:"id";s:56:"20120203063729.juAEE4gRJpVPK3KZ6cil3HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:80;a:3:{s:2:"id";s:56:"20120203063726.mN14BogRJpVPK3KWADEV3HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:81;a:3:{s:2:"id";s:56:"20120203063726.NEw3AogRJpVPK3KWvF-13HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:82;a:3:{s:2:"id";s:56:"20120203063730.dPBCDogRJpVPK3Ka2gOF3HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:83;a:3:{s:2:"id";s:56:"20120203063731.vesiZYgRJpVPK3KbDP5l3HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:84;a:3:{s:2:"id";s:56:"20120203063731.GuB7AYgRJpVPK3KbOS1F3HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:85;a:3:{s:2:"id";s:56:"20120203063730.gDdjIogRJpVPK3Ka759l3HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:86;a:3:{s:2:"id
";s:56:
"
20120203063728.U6I7OIgRJpVPK3KYtQ5l3HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:87;a:3:{s:2:"id";s:56:"20120203063728.OP7bNogRJpVPK3KYx7013HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:88;a:3:{s:2:"id";s:56:"20120203063729.C6odbogRJpVPK3KZm9NV3HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:89;a:3:{s:2:"id";s:56:"20120203063728.UzS8bIgRJpVPK3KYLHBV3HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:90;a:3:{s:2:"id";s:56:"20120203063725.GW7aF4gRJpVPK3KVZd0F3HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:91;a:3:{s:2:"id";s:56:"20120203063730.xSlAOIgRJpVPK3KaJ2J13HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:92;a:3:{s:2:"id";s:56:"20120203063730.xGq1WogRJpVPK3KaiMx13HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:93;a:3:{s:2:"id";s:56:"20120203063729.H0
LqS4gRJ
p
VPK3KZYZll3HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:94;a:3:{s:2:"id";s:56:"20120203063727.9ZbEaIgRJpVPK3KXdTgF3HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:95;a:3:{s:2:"id";s:56:"20120203063729.w_uxb4gRJpVPK3KZdRyF3HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:96;a:3:{s:2:"id";s:56:"20120203063728.nHC9QIgRJpVPK3KYAEhV3HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:97;a:3:{s:2:"id";s:56:"20120203063725.v14vUYgRJpVPK3KVxg413HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}i:98;a:3:{s:2:"id";s:56:"20120203063726.voamIogRJpVPK3KWIhP13HA at domain.example.org";s:4:"type";s:6:"change";s:5:"flags";s:10:"NewMessage";}}
[2] => androidc1771218180
[3] => 1330613630
[4] => Contacts
[5] => user at example.org
)
snip
More information about the horde
mailing list