[horde] Activesync not working but seems fine in logs?
Michael J Rubinsky
mrubinsk at horde.org
Fri Jun 17 21:25:43 UTC 2011
Quoting info at pcbase.eu:
> Quoting Michael J Rubinsky <mrubinsk at horde.org>:
>
>> Quoting info at pcbase.eu:
>>
>>> Hi all,
>>>
>>> I configured horde for activesync usage, but then I try to sync
>>> email, calendar and contacts, it just fails according to my phone.
>>>
>>> This is the ActiveSync log:
>>>
>>> 2011-06-17T00:31:35+02:00 DEBUG:
>>> Horde_ActiveSync_Driver_Horde::getServerChanges(Calendar, 0,
>>> 1308263495, 0)
>>> 2011-06-17T00:31:35+02:00 DEBUG:
>>> [HTC942974c4d7bf04a7bd941aa283c5d] Found 0 message changes,
>>> checking for PIM initiated changes.
>>> 2011-06-17T00:31:35+02:00 DEBUG:
>>> [HTC942974c4d7bf04a7bd941aa283c5d] No PIM changes present,
>>> returning all messages.
>>> 2011-06-17T00:31:40+02:00 DEBUG:
>>> [HTC942974c4d7bf04a7bd941aa283c5d] Attempting to load PING state
>>> for: Contacts
>>> 2011-06-17T00:31:40+02:00 DEBUG:
>>> [HTC942974c4d7bf04a7bd941aa283c5d] Obtained lasst sync time for
>>> Contacts - 0
>>> 2011-06-17T00:31:40+02:00 DEBUG:
>>> [HTC942974c4d7bf04a7bd941aa283c5d] Initializing message diff
>>> engine for Contacts
>>> 2011-06-17T00:31:40+02:00 DEBUG:
>>> Horde_ActiveSync_Driver_Horde::getServerChanges(Contacts, 0,
>>> 1308263500, 0)
>>> 2011-06-17T00:31:40+02:00 DEBUG:
>>> [HTC942974c4d7bf04a7bd941aa283c5d] Found 0 message changes,
>>> checking for PIM initiated changes.
>>> 2011-06-17T00:31:40+02:00 DEBUG:
>>> [HTC942974c4d7bf04a7bd941aa283c5d] No PIM changes present,
>>> returning all messages.
>>> 2011-06-17T00:31:40+02:00 DEBUG:
>>> [HTC942974c4d7bf04a7bd941aa283c5d] Attempting to load PING state
>>> for: Calendar
>>> 2011-06-17T00:31:40+02:00 DEBUG:
>>> [HTC942974c4d7bf04a7bd941aa283c5d] Obtained lasst sync time for
>>> Calendar - 0
>>> 2011-06-17T00:31:40+02:00 DEBUG:
>>> [HTC942974c4d7bf04a7bd941aa283c5d] Initializing message diff
>>> engine for Calendar
>>> 2011-06-17T00:31:40+02:00 DEBUG:
>>> Horde_ActiveSync_Driver_Horde::getServerChanges(Calendar, 0,
>>> 1308263500, 0)
>>> 2011-06-17T00:31:40+02:00 DEBUG:
>>> [HTC942974c4d7bf04a7bd941aa283c5d] Found 0 message changes,
>>> checking for PIM initiated changes.
>>> 2011-06-17T00:31:40+02:00 DEBUG:
>>> [HTC942974c4d7bf04a7bd941aa283c5d] No PIM changes present,
>>> returning all messages.
>>> 2011-06-17T00:31:45+02:00 DEBUG:
>>> [HTC942974c4d7bf04a7bd941aa283c5d] Attempting to load PING state
>>> for: Contacts
>>> 2011-06-17T00:31:45+02:00 DEBUG:
>>> [HTC942974c4d7bf04a7bd941aa283c5d] Obtained lasst sync time for
>>> Contacts - 0
>>> 2011-06-17T00:31:45+02:00 DEBUG:
>>> [HTC942974c4d7bf04a7bd941aa283c5d] Initializing message diff
>>> engine for Contacts
>>> 2011-06-17T00:31:45+02:00 DEBUG:
>>> Horde_ActiveSync_Driver_Horde::getServerChanges(Contacts, 0,
>>> 1308263505, 0)
>>> 2011-06-17T00:31:45+02:00 DEBUG:
>>> [HTC942974c4d7bf04a7bd941aa283c5d] Found 0 message changes,
>>> checking for PIM initiated changes.
>>> 2011-06-17T00:31:45+02:00 DEBUG:
>>> [HTC942974c4d7bf04a7bd941aa283c5d] No PIM changes present,
>>> returning all messages.
>>> 2011-06-17T00:31:45+02:00 DEBUG:
>>> [HTC942974c4d7bf04a7bd941aa283c5d] Attempting to load PING state
>>> for: Calendar
>>> 2011-06-17T00:31:45+02:00 DEBUG:
>>> [HTC942974c4d7bf04a7bd941aa283c5d] Obtained lasst sync time for
>>> Calendar - 0
>>> 2011-06-17T00:31:45+02:00 DEBUG:
>>> [HTC942974c4d7bf04a7bd941aa283c5d] Initializing message diff
>>> engine for Calendar
>>> 2011-06-17T00:31:45+02:00 DEBUG:
>>> Horde_ActiveSync_Driver_Horde::getServerChanges(Calendar, 0,
>>> 1308263505, 0)
>>> 2011-06-17T00:31:45+02:00 DEBUG:
>>> [HTC942974c4d7bf04a7bd941aa283c5d] Found 0 message changes,
>>> checking for PIM initiated changes.
>>> 2011-06-17T00:31:45+02:00 DEBUG:
>>> [HTC942974c4d7bf04a7bd941aa283c5d] No PIM changes present,
>>> returning all messages.
>>> 2011-06-17T00:31:50+02:00 INFO: [HTC942974c4d7bf04a7bd941aa283c5d]
>>> Sending response for PING.
>>> 2011-06-17T00:31:50+02:00 DEBUG: O <Ping:Ping>
>>> 2011-06-17T00:31:50+02:00 DEBUG: O <Ping:Status>
>>> 2011-06-17T00:31:50+02:00 DEBUG: O 1
>>> 2011-06-17T00:31:50+02:00 DEBUG: O <Ping:Status/>
>>> 2011-06-17T00:31:50+02:00 DEBUG: O <Ping:Ping/>
>>>
>>>
>>>
>>> I thought that it might be a webserver problem, but that wasn't
>>> right, because it just returns a 200 status:
>>>
>>> [17/Jun/2011:19:08:38 +0200] "POST
>>> /Microsoft-Server-ActiveSync?Cmd=Sync&User=[MYUSER]&DeviceId=HTC942974c4d7bf04a7bd941aa283c5d&DeviceType=htcpyramid HTTP/1.1" 200 82 "-"
>>> "Android-EAS/4.0.2115272322.67241"
>>>
>>> When I try to access the webportal I can browse my mail, contacts
>>> and calandar fine, but AS just won't work. Any ideas why?
>>
>> What do you mean by "fails on my phone"? What, exactly, happens on
>> the phone?
>>
>> Can you reset the AS account on the device and on the server
>> (prefs->active ync devices), then capture the log from the
>> beginning? This log looks like it has already performed a
>> FOLDERSYNC operation, but the SYNC operation may have failed for
>> some reason (this log is from a PING request - which only happens
>> *after* an initial SYNC is performed).
>>
>> If you could also capture the traffic with wireshark or similar,
>> that would be helpful. If not, just the AS log should suffice.
>>
>> --
>> mike
>>
>> The Horde Project (www.horde.org)
>> mrubinsk at horde.org
>>
>> --
>> Horde mailing list
>> Frequently Asked Questions: http://horde.org/faq/
>> To unsubscribe, mail: horde-unsubscribe at lists.horde.org
>
> Thanks or the quick reply! This is what the log is saying:
>
> http://pastebin.com/q63QG7Wz
>
>
> With "fails on my phone" I mean that the default Android 2.3.3
> mailclient reports "Synchronization is experiencing problems." When
> I check the sync status on my phone, I see that Mail has an
> exclamation mark,
Horde's ActiveSync server does not support email. You should use your
phone's native email client. We only currently support contacts,
calendar and tasks. See http://wiki.horde.org/ActiveSync for more
information.
> Contacts and Calendar don't have the exclamation mark. When I check
> to see if the Contacts and the Calendar appointments are available
> in Horde, everything is still empty (My phone has the contacts and
> calendar items I want to have available in Horde).
ActiveSync does not move entries to the server that existed on the
device before the ActiveSync account was setup. Only entries that
originated from the ActiveSync server or entries that were added to
the ActiveSync acount on the device are synched. The reason is the
ActiveSync account did not exist on the device prior to this, so does
not contain any entries. The entries that happen to be on the device
before the account is setup are considered entries in the device's
local database - not part of the ActiveSync account.
--
mike
The Horde Project (www.horde.org)
mrubinsk at horde.org
More information about the horde
mailing list