[horde] ActiveSync email disabled. But horde connects to imap server?

Erling Preben Hansen erling at eph.dk
Wed May 7 20:41:24 UTC 2014


  Citat af Erling Preben Hansen <erling at eph.dk>:

> Citat af Michael J Rubinsky <mrubinsk at horde.org>:
>
>> Quoting Erling Preben Hansen <erling at eph.dk>:
>>
>>> Hey
>>>
>>> I have set up ActiveSync as this:
>>>
>>> $conf['activesync']['emailsync'] = false;
>>> $conf['activesync']['version'] = '14.1';
>>> $conf['activesync']['autodiscovery'] = 'full';
>>> $conf['activesync']['hosts']['imap']['host'] = 'eph.dk';
>>> $conf['activesync']['hosts']['imap']['port'] = 993;
>>> $conf['activesync']['hosts']['imap']['ssl'] = true;
>>> $conf['activesync']['hosts']['pop']['host'] = 'eph.dk';
>>> $conf['activesync']['hosts']['pop']['port'] = 995;
>>> $conf['activesync']['hosts']['pop']['ssl'] = true;
>>> $conf['activesync']['hosts']['smtp']['host'] = 'eph.dk';
>>> $conf['activesync']['hosts']['smtp']['port'] = 465;
>>> $conf['activesync']['hosts']['smtp']['popauth'] = false;
>>> $conf['activesync']['hosts']['smtp']['ssl'] = true;
>>> $conf['activesync']['outlookdiscovery'] = true;
>>> $conf['activesync']['logging']['type'] = 'horde';
>>> $conf['activesync']['ping']['heartbeatmin'] = 60;
>>> $conf['activesync']['ping']['heartbeatmax'] = 2700;
>>> $conf['activesync']['ping']['heartbeatdefault'] = 480;
>>> $conf['activesync']['ping']['deviceping'] = true;
>>> $conf['activesync']['ping']['waitinterval'] = 120;
>>> $conf['activesync']['enabled'] = true;
>>>
>>> I can see in my logs that Horde is opening and authenticating a
>>> connection
>>> to my imap server.
>>> This connection is open for 30 minutes and then closes and immediately
>>> opens again.
>>> Imap is only handling mails
>>>
>>> Horde authentication is done to sql server so there is no need to open
>>> Imap.
>>> Horde is handling Calendars Contacts Notes and Tasks.
>>>
>>> Is this normal behavior.......
>>> and why is it so.
>>
>> I cannot reproduce this. Also, I see that you have configured outlook
>> autodiscovery, which doesn't configure *ActiveSync* on Outlook, but
>> provides information to configure outlook for IMAP/POP etc... Is this
>> what you are using, or is this from a mobile device?
>>
>> --
>> mike
>> The Horde Project
>> http://www.horde.org
>>
https://www.facebook.com/hordeprojecthttps://www.twitter.com/hordeproject
>
> Hey
> ohh and by the way.
> I do know that these:
> $conf['activesync']['hosts']['imap']['host'] = 'eph.dk';
> $conf['activesync']['hosts']['imap']['port'] = 993;
> $conf['activesync']['hosts']['imap']['ssl'] = true;
> $conf['activesync']['hosts']['pop']['host'] = 'eph.dk';
> $conf['activesync']['hosts']['pop']['port'] = 995;
> $conf['activesync']['hosts']['pop']['ssl'] = true;
> $conf['activesync']['hosts']['smtp']['host'] = 'eph.dk';
> $conf['activesync']['hosts']['smtp']['port'] = 465;
> $conf['activesync']['hosts']['smtp']['popauth'] = false;
> $conf['activesync']['hosts']['smtp']['ssl'] = true;
> $conf['activesync']['outlookdiscovery'] = true;
>
> entries is for autodiscovery for outlook.
> I have some users that find it being a very nice feature.
>
> /erling
> --
> Horde mailing list
> Frequently Asked Questions: http://horde.org/faq/To unsubscribe, mail:
> horde-unsubscribe at lists.horde.org

Hey

I didn't notice that my first mail with logs was too big. So it didn't get
in to the list.

Ok here is some logging.

It seems it is every 10 min. that horde makes a imap logout and then login
again.
Horde is the only one to make login via imapd at localhost.
All others are forced to use imap-ssl on external ip.

Here are the logs.............

grep from mail.info

Time is GMT + 2

May  5 22:14:14 eph-03 imapd: LOGOUT, user=myuser at eph.dk,
ip=[::ffff:127.0.0.1], headers=0, body=0, rcvd=24, sent=229, time=829
May  5 22:14:16 eph-03 imapd: LOGIN, user=myuser at eph.dk,
ip=[::ffff:127.0.0.1], port=[34630], protocol=IMAP

May  5 22:31:06 eph-03 imapd: LOGOUT, user=myuser at eph.dk,
ip=[::ffff:127.0.0.1], headers=0, body=0, rcvd=24, sent=229, time=1010
May  5 22:31:08 eph-03 imapd: LOGIN, user=myuser at eph.dk,
ip=[::ffff:127.0.0.1], port=[34650], protocol=IMAP

May  5 22:47:58 eph-03 imapd: LOGOUT, user=myuser at eph.dk,
ip=[::ffff:127.0.0.1], headers=0, body=0, rcvd=24, sent=229, time=1010
May  5 22:47:59 eph-03 imapd: LOGIN, user=myuser at eph.dk,
ip=[::ffff:127.0.0.1], port=[34679], protocol=IMAP

horde.log
Time is GMT + 2

my-ip - eph.dk\\myuser at eph.dk [05/May/2014:22:14:15 +0200] "POST
/Microsoft-Server-ActiveSync?Cmd=Ping&User=eph.dk%5Cmyuser%40eph.dk&DeviceId=androidc1233653448&DeviceType=Android
HTTP/1.1" 200 13 "-"

my-ip - eph.dk\\myuser at eph.dk [05/May/2014:22:31:07 +0200] "POST
/Microsoft-Server-ActiveSync?Cmd=FolderSync&User=eph.dk%5Cmyuser%40eph.dk&DeviceId=androidc1233653448&DeviceType=Android
HTTP/1.1" 200 63 "-"
my-ip - eph.dk\\myuser at eph.dk [05/May/2014:22:31:07 +0200] "POST
/Microsoft-Server-ActiveSync?Cmd=Ping&User=eph.dk%5Cmyuser%40eph.dk&DeviceId=androidc1233653448&DeviceType=Android
HTTP/1.1" 200 13 "-"

my-ip - eph.dk\\myuser at eph.dk [05/May/2014:22:47:59 +0200] "POST
/Microsoft-Server-ActiveSync?Cmd=Ping&User=eph.dk%5Cmyuser%40eph.dk&DeviceId=androidc1233653448&DeviceType=Android
HTTP/1.1" 200 13 "-"

Device ActiveSync log
Time is GMT / GMT+2

2014-05-05T20:14:16+00:00 INFO: [19463]
Horde_Core_ActiveSync_Driver::authenticate() attempt for myuser at eph.dk
2014-05-05T20:14:16+00:00 INFO: [19463] PING request received for user
myuser at eph.dk
2014-05-05T20:14:16+00:00 INFO: [19463] Device entry exists for
ANDROIDC1233653448, updating userAgent and version.
2014-05-05T20:14:16+00:00 INFO: [19463] Request being handled for device:
ANDROIDC1233653448, Supporting protocol version: 12.0, Using
Horde_ActiveSync v2.14.0
2014-05-05T20:14:16+00:00 INFO: [19463] Handling PING command received at
timestamp: 1399320856.
2014-05-05T20:14:16+00:00 DEBUG: [19463] I  <Ping:Ping>
2014-05-05T20:14:16+00:00 DEBUG: [19463] I   <Ping:HeartbeatInterval>
2014-05-05T20:14:16+00:00 DEBUG: [19463] I     1010
2014-05-05T20:14:16+00:00 DEBUG: [19463] I   </Ping:HeartbeatInterval>
2014-05-05T20:14:16+00:00 DEBUG: [19463] I   <Ping:Folders>
2014-05-05T20:14:16+00:00 DEBUG: [19463] I    <Ping:Folder>
2014-05-05T20:14:16+00:00 DEBUG: [19463] I     <Ping:ServerEntryId>
2014-05-05T20:14:16+00:00 DEBUG: [19463] I       @Contacts@
2014-05-05T20:14:16+00:00 DEBUG: [19463] I     </Ping:ServerEntryId>
2014-05-05T20:14:16+00:00 DEBUG: [19463] I     <Ping:FolderType>
2014-05-05T20:14:16+00:00 DEBUG: [19463] I       Contacts
2014-05-05T20:14:16+00:00 DEBUG: [19463] I     </Ping:FolderType>
2014-05-05T20:14:16+00:00 DEBUG: [19463] I    </Ping:Folder>
2014-05-05T20:14:16+00:00 INFO: [19463] Obtained synckey for collection
@Contacts@ from cache: {5363f94c-b6f4-4bae-82dd-2950c0a87b68}19
2014-05-05T20:14:16+00:00 INFO: [19463] Collection added to collection
handler: collection: @Contacts@, synckey:
{5363f94c-b6f4-4bae-82dd-2950c0a87b68}19.
2014-05-05T20:14:16+00:00 DEBUG: [19463] I    <Ping:Folder>
2014-05-05T20:14:16+00:00 DEBUG: [19463] I     <Ping:ServerEntryId>
2014-05-05T20:14:16+00:00 DEBUG: [19463] I       @Calendar@
2014-05-05T20:14:16+00:00 DEBUG: [19463] I     </Ping:ServerEntryId>
2014-05-05T20:14:16+00:00 DEBUG: [19463] I     <Ping:FolderType>
2014-05-05T20:14:16+00:00 DEBUG: [19463] I       Calendar
2014-05-05T20:14:16+00:00 DEBUG: [19463] I     </Ping:FolderType>
2014-05-05T20:14:16+00:00 DEBUG: [19463] I    </Ping:Folder>
2014-05-05T20:14:16+00:00 INFO: [19463] Obtained synckey for collection
@Calendar@ from cache: {5363f891-47e8-4577-9aae-2916c0a87b68}10
2014-05-05T20:14:16+00:00 INFO: [19463] Collection added to collection
handler: collection: @Calendar@, synckey:
{5363f891-47e8-4577-9aae-2916c0a87b68}10.
2014-05-05T20:14:16+00:00 DEBUG: [19463] I   </Ping:Folders>
2014-05-05T20:14:16+00:00 INFO: Setting collection @Calendar@ (@Calendar@)
PINGABLE.
2014-05-05T20:14:16+00:00 INFO: UNSETTING collection INBOX (INBOX) PINGABLE
flag.
2014-05-05T20:14:16+00:00 INFO: Setting collection @Contacts@ (@Contacts@)
PINGABLE.
2014-05-05T20:14:16+00:00 DEBUG: [19463] I  </Ping:Ping>
2014-05-05T20:14:16+00:00 INFO: Waiting for changes for 1010 seconds
2014-05-05T20:14:16+00:00 INFO: [19463] Replacing SYNC_CACHE entry for user
myuser at eph.dk and device ANDROIDC1233653448:
a:11:{s:18:"confirmed_synckeys";a:0:{}s:17:"lasthbsyncstarted";i:1399320856;s:17:"lastsyncendnormal";b:0;s:9:"timestamp";s:10:"1399320856";s:4:"wait";b:0;s:10:"hbinterval";s:4:"1010";s:7:"folders";a:7:{s:5:"Trash";a:2:{s:5:"class";s:5:"Email";s:8:"serverid";s:5:"Trash";}s:4:"Sent";a:2:{s:5:"class";s:5:"Email";s:8:"serverid";s:4:"Sent";}s:5:"INBOX";a:2:{s:5:"class";s:5:"Email";s:8:"serverid";s:5:"INBOX";}s:7:"@Tasks@";a:2:{s:5:"class";s:5:"Tasks";s:8:"serverid";s:7:"@Tasks@";}s:7:"@Notes@";a:2:{s:5:"class";s:5:"Notes";s:8:"serverid";s:7:"@Notes@";}s:10:"@Contacts@";a:2:{s:5:"class";s:8:"Contacts";s:8:"serverid";s:10:"@Contacts@";}s:10:"@Calendar@";a:2:{s:5:"class";s:8:"Calendar";s:8:"serverid";s:10:"@Calendar@";}}s:9:"hierarchy";s:39:"{5363f86a-2fac-453a-bc44-28c1c0a87b68}1";s:11:"collections";a:3:{s:10:"@Calendar@";a:12:{s:5:"class";s:8:"Calendar";s:10:"windowsize";s:1:"4";s:10:"truncation"
;i:0;s:11:"mimesupport";i:0;s:14:"mimetruncation";i:8;s:8:"conflict";i:1;s:9:"bodyprefs";a:1:{i:1;a:2:{s:4:"type";s:1:"1";s:14:"truncationsize";s:6:"200000";}}s:8:"serverid";s:10:"@Calendar@";s:11:"lastsynckey";s:40:"{5363f891-47e8-4577-9aae-2916c0a87b68}10";s:14:"deletesasmoves";b:1;s:10:"filtertype";s:1:"4";s:8:"pingable";b:1;}s:5:"INBOX";a:12:{s:5:"class";s:5:"Email";s:10:"windowsize";s:1:"5";s:10:"truncation";i:0;s:11:"mimesupport";i:0;s:14:"mimetruncation";i:8;s:8:"conflict";i:1;s:9:"bodyprefs";a:1:{i:2;a:2:{s:4:"type";s:1:"2";s:14:"truncationsize";s:6:"200000";}}s:8:"serverid";s:5:"INBOX";s:14:"deletesasmoves";b:1;s:10:"filtertype";s:1:"1";s:11:"lastsynckey";s:39:"{5363f94c-7240-4886-a74a-28c1c0a87b68}2";s:8:"pingable";b:0;}s:10:"@Contacts@";a:12:{s:5:"class";s:8:"Contacts";s:10:"windowsize";s:1:"4";s:14:"deletesasmoves";b:1;s:10:"filtertype";s:1:"0";s:10:"truncation";i:0;s:11:"mimesupport";i:0;s:14:"mimetruncation";i:8;s:8:"conflict";i:1;s:9:"bodyprefs";a:1:{i:1;a:2:{s:4:"type
";s:1:"1";s:14:"
truncationsize";s:6:"200000";}}s:8:"serverid";s:10:"@Contacts@";s:11:"lastsynckey";s:40:"{5363f94c-b6f4-4bae-82dd-2950c0a87b68}19";s:8:"pingable";b:1;}}s:13:"pingheartbeat";b:0;s:14:"synckeycounter";a:0:{}}
2014-05-05T20:14:16+00:00 INFO: [19463] Initializing state for collection:
@Contacts@, synckey: {5363f94c-b6f4-4bae-82dd-2950c0a87b68}19
2014-05-05T20:14:16+00:00 INFO: [19463] Loading state for synckey
{5363f94c-b6f4-4bae-82dd-2950c0a87b68}19
2014-05-05T20:14:16+00:00 INFO: [19463] Initializing message diff engine
for @Contacts@ (@Contacts@)
2014-05-05T20:14:16+00:00 INFO: [19463] Using SYNCSTAMP 4041 for
@Contacts at .
2014-05-05T20:14:16+00:00 INFO: [19463]
Horde_Core_ActiveSync_Driver::getServerChanges(@Contacts@, 4041, 4041, 0,
1)
2014-05-05T20:14:16+00:00 INFO: [19463] Fetching changes for contacts using
MODSEQ.
2014-05-05T20:14:16+00:00 INFO: [19463] Found 0 message changes in
@Contacts at .
2014-05-05T20:14:16+00:00 INFO: [19463] Initializing state for collection:
@Calendar@, synckey: {5363f891-47e8-4577-9aae-2916c0a87b68}10
2014-05-05T20:14:16+00:00 INFO: [19463] Loading state for synckey
{5363f891-47e8-4577-9aae-2916c0a87b68}10
2014-05-05T20:14:16+00:00 INFO: [19463] Initializing message diff engine
for @Calendar@ (@Calendar@)
2014-05-05T22:14:16+02:00 INFO: [19463] Using SYNCSTAMP 4048 for
@Calendar at .
2014-05-05T22:14:16+02:00 INFO: [19463]
Horde_Core_ActiveSync_Driver::getServerChanges(@Calendar@, 4048, 4048,
1398111256, 1)
2014-05-05T22:14:16+02:00 INFO: [19463] Fetching changes for calendar using
MODSEQ.
2014-05-05T22:14:16+02:00 INFO: [19463] Found 0 message changes in
@Calendar at .
2014-05-05T22:14:16+02:00 INFO: [19463] Sleeping for 120 seconds.
2014-05-05T22:16:16+02:00 INFO: [19463] SyncCache collections refreshed.
2014-05-05T22:16:16+02:00 INFO: [19463] Refreshing @Contacts@ from the
cache.
2014-05-05T22:16:16+02:00 INFO: [19463] Refreshing @Calendar@ from the
cache.
2014-05-05T22:16:16+02:00 INFO: [19463] Initializing state for collection:
@Contacts@, synckey: {5363f94c-b6f4-4bae-82dd-2950c0a87b68}19
2014-05-05T22:16:16+02:00 INFO: [19463] Loading state for synckey
{5363f94c-b6f4-4bae-82dd-2950c0a87b68}19
2014-05-05T22:16:16+02:00 INFO: [19463] Initializing message diff engine
for @Contacts@ (@Contacts@)
2014-05-05T22:16:16+02:00 INFO: [19463] Using SYNCSTAMP 4041 for
@Contacts at .
2014-05-05T22:16:16+02:00 INFO: [19463]
Horde_Core_ActiveSync_Driver::getServerChanges(@Contacts@, 4041, 4041, 0,
1)
2014-05-05T22:16:16+02:00 INFO: [19463] Fetching changes for contacts using
MODSEQ.
2014-05-05T22:16:16+02:00 INFO: [19463] Found 0 message changes in
@Contacts at .
2014-05-05T22:16:16+02:00 INFO: [19463] Initializing state for collection:
@Calendar@, synckey: {5363f891-47e8-4577-9aae-2916c0a87b68}10
2014-05-05T22:16:16+02:00 INFO: [19463] Loading state for synckey
{5363f891-47e8-4577-9aae-2916c0a87b68}10
2014-05-05T22:16:16+02:00 INFO: [19463] Initializing message diff engine
for @Calendar@ (@Calendar@)
2014-05-05T22:16:16+02:00 INFO: [19463] Using SYNCSTAMP 4048 for
@Calendar at .
2014-05-05T22:16:16+02:00 INFO: [19463]
Horde_Core_ActiveSync_Driver::getServerChanges(@Calendar@, 4048, 4048,
1398111376, 1)
2014-05-05T22:16:16+02:00 INFO: [19463] Fetching changes for calendar using
MODSEQ.
2014-05-05T22:16:16+02:00 INFO: [19463] Found 0 message changes in
@Calendar at .
2014-05-05T22:16:16+02:00 INFO: [19463] Sleeping for 120 seconds.
2014-05-05T22:18:16+02:00 INFO: [19463] SyncCache collections refreshed.
2014-05-05T22:18:16+02:00 INFO: [19463] Refreshing @Contacts@ from the
cache.
2014-05-05T22:18:16+02:00 INFO: [19463] Refreshing @Calendar@ from the
cache.
2014-05-05T22:18:16+02:00 INFO: [19463] Initializing state for collection:
@Contacts@, synckey: {5363f94c-b6f4-4bae-82dd-2950c0a87b68}19
2014-05-05T22:18:16+02:00 INFO: [19463] Loading state for synckey
{5363f94c-b6f4-4bae-82dd-2950c0a87b68}19
2014-05-05T22:18:16+02:00 INFO: [19463] Initializing message diff engine
for @Contacts@ (@Contacts@)
2014-05-05T22:18:16+02:00 INFO: [19463] Using SYNCSTAMP 4041 for
@Contacts at .
2014-05-05T22:18:16+02:00 INFO: [19463]
Horde_Core_ActiveSync_Driver::getServerChanges(@Contacts@, 4041, 4041, 0,
1)
2014-05-05T22:18:16+02:00 INFO: [19463] Fetching changes for contacts using
MODSEQ.
2014-05-05T22:18:16+02:00 INFO: [19463] Found 0 message changes in
@Contacts at .
2014-05-05T22:18:16+02:00 INFO: [19463] Initializing state for collection:
@Calendar@, synckey: {5363f891-47e8-4577-9aae-2916c0a87b68}10
2014-05-05T22:18:16+02:00 INFO: [19463] Loading state for synckey
{5363f891-47e8-4577-9aae-2916c0a87b68}10
2014-05-05T22:18:16+02:00 INFO: [19463] Initializing message diff engine
for @Calendar@ (@Calendar@)
2014-05-05T22:18:16+02:00 INFO: [19463] Using SYNCSTAMP 4048 for
@Calendar at .
2014-05-05T22:18:16+02:00 INFO: [19463]
Horde_Core_ActiveSync_Driver::getServerChanges(@Calendar@, 4048, 4048,
1398111496, 1)
2014-05-05T22:18:16+02:00 INFO: [19463] Fetching changes for calendar using
MODSEQ.
2014-05-05T22:18:16+02:00 INFO: [19463] Found 0 message changes in
@Calendar at .
2014-05-05T22:18:16+02:00 INFO: [19463] Sleeping for 120 seconds.
2014-05-05T22:20:16+02:00 INFO: [19463] SyncCache collections refreshed.
2014-05-05T22:20:16+02:00 INFO: [19463] Refreshing @Contacts@ from the
cache.
2014-05-05T22:20:16+02:00 INFO: [19463] Refreshing @Calendar@ from the
cache.
2014-05-05T22:20:16+02:00 INFO: [19463] Initializing state for collection:
@Contacts@, synckey: {5363f94c-b6f4-4bae-82dd-2950c0a87b68}19
2014-05-05T22:20:16+02:00 INFO: [19463] Loading state for synckey
{5363f94c-b6f4-4bae-82dd-2950c0a87b68}19
2014-05-05T22:20:16+02:00 INFO: [19463] Initializing message diff engine
for @Contacts@ (@Contacts@)
2014-05-05T22:20:16+02:00 INFO: [19463] Using SYNCSTAMP 4041 for
@Contacts at .
2014-05-05T22:20:16+02:00 INFO: [19463]
Horde_Core_ActiveSync_Driver::getServerChanges(@Contacts@, 4041, 4041, 0,
1)
2014-05-05T22:20:16+02:00 INFO: [19463] Fetching changes for contacts using
MODSEQ.
2014-05-05T22:20:16+02:00 INFO: [19463] Found 0 message changes in
@Contacts at .
2014-05-05T22:20:16+02:00 INFO: [19463] Initializing state for collection:
@Calendar@, synckey: {5363f891-47e8-4577-9aae-2916c0a87b68}10
2014-05-05T22:20:16+02:00 INFO: [19463] Loading state for synckey
{5363f891-47e8-4577-9aae-2916c0a87b68}10
2014-05-05T22:20:16+02:00 INFO: [19463] Initializing message diff engine
for @Calendar@ (@Calendar@)
2014-05-05T22:20:16+02:00 INFO: [19463] Using SYNCSTAMP 4048 for
@Calendar at .
2014-05-05T22:20:16+02:00 INFO: [19463]
Horde_Core_ActiveSync_Driver::getServerChanges(@Calendar@, 4048, 4048,
1398111616, 1)
2014-05-05T22:20:16+02:00 INFO: [19463] Fetching changes for calendar using
MODSEQ.
2014-05-05T22:20:16+02:00 INFO: [19463] Found 0 message changes in
@Calendar at .
2014-05-05T22:20:16+02:00 INFO: [19463] Sleeping for 120 seconds.
2014-05-05T22:22:16+02:00 INFO: [19463] SyncCache collections refreshed.
2014-05-05T22:22:16+02:00 INFO: [19463] Refreshing @Contacts@ from the
cache.
2014-05-05T22:22:16+02:00 INFO: [19463] Refreshing @Calendar@ from the
cache.
2014-05-05T22:22:16+02:00 INFO: [19463] Initializing state for collection:
@Contacts@, synckey: {5363f94c-b6f4-4bae-82dd-2950c0a87b68}19
2014-05-05T22:22:16+02:00 INFO: [19463] Loading state for synckey
{5363f94c-b6f4-4bae-82dd-2950c0a87b68}19
2014-05-05T22:22:16+02:00 INFO: [19463] Initializing message diff engine
for @Contacts@ (@Contacts@)
2014-05-05T22:22:16+02:00 INFO: [19463] Using SYNCSTAMP 4041 for
@Contacts at .
2014-05-05T22:22:16+02:00 INFO: [19463]
Horde_Core_ActiveSync_Driver::getServerChanges(@Contacts@, 4041, 4041, 0,
1)
2014-05-05T22:22:16+02:00 INFO: [19463] Fetching changes for contacts using
MODSEQ.
2014-05-05T22:22:16+02:00 INFO: [19463] Found 0 message changes in
@Contacts at .
2014-05-05T22:22:16+02:00 INFO: [19463] Initializing state for collection:
@Calendar@, synckey: {5363f891-47e8-4577-9aae-2916c0a87b68}10
2014-05-05T22:22:16+02:00 INFO: [19463] Loading state for synckey
{5363f891-47e8-4577-9aae-2916c0a87b68}10
2014-05-05T22:22:16+02:00 INFO: [19463] Initializing message diff engine
for @Calendar@ (@Calendar@)
2014-05-05T22:22:16+02:00 INFO: [19463] Using SYNCSTAMP 4048 for
@Calendar at .
2014-05-05T22:22:16+02:00 INFO: [19463]
Horde_Core_ActiveSync_Driver::getServerChanges(@Calendar@, 4048, 4048,
1398111736, 1)
2014-05-05T22:22:16+02:00 INFO: [19463] Fetching changes for calendar using
MODSEQ.
2014-05-05T22:22:16+02:00 INFO: [19463] Found 0 message changes in
@Calendar at .
2014-05-05T22:22:16+02:00 INFO: [19463] Sleeping for 120 seconds.
2014-05-05T22:24:16+02:00 INFO: [19463] SyncCache collections refreshed.
2014-05-05T22:24:16+02:00 INFO: [19463] Refreshing @Contacts@ from the
cache.
2014-05-05T22:24:16+02:00 INFO: [19463] Refreshing @Calendar@ from the
cache.
2014-05-05T22:24:16+02:00 INFO: [19463] Initializing state for collection:
@Contacts@, synckey: {5363f94c-b6f4-4bae-82dd-2950c0a87b68}19
2014-05-05T22:24:16+02:00 INFO: [19463] Loading state for synckey
{5363f94c-b6f4-4bae-82dd-2950c0a87b68}19
2014-05-05T22:24:16+02:00 INFO: [19463] Initializing message diff engine
for @Contacts@ (@Contacts@)
2014-05-05T22:24:16+02:00 INFO: [19463] Using SYNCSTAMP 4041 for
@Contacts at .
2014-05-05T22:24:16+02:00 INFO: [19463]
Horde_Core_ActiveSync_Driver::getServerChanges(@Contacts@, 4041, 4041, 0,
1)
2014-05-05T22:24:16+02:00 INFO: [19463] Fetching changes for contacts using
MODSEQ.
2014-05-05T22:24:16+02:00 INFO: [19463] Found 0 message changes in
@Contacts at .
2014-05-05T22:24:16+02:00 INFO: [19463] Initializing state for collection:
@Calendar@, synckey: {5363f891-47e8-4577-9aae-2916c0a87b68}10
2014-05-05T22:24:16+02:00 INFO: [19463] Loading state for synckey
{5363f891-47e8-4577-9aae-2916c0a87b68}10
2014-05-05T22:24:16+02:00 INFO: [19463] Initializing message diff engine
for @Calendar@ (@Calendar@)
2014-05-05T22:24:16+02:00 INFO: [19463] Using SYNCSTAMP 4048 for
@Calendar at .
2014-05-05T22:24:16+02:00 INFO: [19463]
Horde_Core_ActiveSync_Driver::getServerChanges(@Calendar@, 4048, 4048,
1398111856, 1)
2014-05-05T22:24:16+02:00 INFO: [19463] Fetching changes for calendar using
MODSEQ.
2014-05-05T22:24:16+02:00 INFO: [19463] Found 0 message changes in
@Calendar at .
2014-05-05T22:24:16+02:00 INFO: [19463] Sleeping for 120 seconds.
2014-05-05T22:26:16+02:00 INFO: [19463] SyncCache collections refreshed.
2014-05-05T22:26:16+02:00 INFO: [19463] Refreshing @Contacts@ from the
cache.
2014-05-05T22:26:16+02:00 INFO: [19463] Refreshing @Calendar@ from the
cache.
2014-05-05T22:26:16+02:00 INFO: [19463] Initializing state for collection:
@Contacts@, synckey: {5363f94c-b6f4-4bae-82dd-2950c0a87b68}19
2014-05-05T22:26:16+02:00 INFO: [19463] Loading state for synckey
{5363f94c-b6f4-4bae-82dd-2950c0a87b68}19
2014-05-05T22:26:16+02:00 INFO: [19463] Initializing message diff engine
for @Contacts@ (@Contacts@)
2014-05-05T22:26:16+02:00 INFO: [19463] Using SYNCSTAMP 4041 for
@Contacts at .
2014-05-05T22:26:16+02:00 INFO: [19463]
Horde_Core_ActiveSync_Driver::getServerChanges(@Contacts@, 4041, 4041, 0,
1)
2014-05-05T22:26:16+02:00 INFO: [19463] Fetching changes for contacts using
MODSEQ.
2014-05-05T22:26:16+02:00 INFO: [19463] Found 0 message changes in
@Contacts at .
2014-05-05T22:26:16+02:00 INFO: [19463] Initializing state for collection:
@Calendar@, synckey: {5363f891-47e8-4577-9aae-2916c0a87b68}10
2014-05-05T22:26:16+02:00 INFO: [19463] Loading state for synckey
{5363f891-47e8-4577-9aae-2916c0a87b68}10
2014-05-05T22:26:16+02:00 INFO: [19463] Initializing message diff engine
for @Calendar@ (@Calendar@)
2014-05-05T22:26:16+02:00 INFO: [19463] Using SYNCSTAMP 4048 for
@Calendar at .
2014-05-05T22:26:16+02:00 INFO: [19463]
Horde_Core_ActiveSync_Driver::getServerChanges(@Calendar@, 4048, 4048,
1398111976, 1)
2014-05-05T22:26:16+02:00 INFO: [19463] Fetching changes for calendar using
MODSEQ.
2014-05-05T22:26:16+02:00 INFO: [19463] Found 0 message changes in
@Calendar at .
2014-05-05T22:26:16+02:00 INFO: [19463] Sleeping for 120 seconds.
2014-05-05T22:28:16+02:00 INFO: [19463] SyncCache collections refreshed.
2014-05-05T22:28:16+02:00 INFO: [19463] Refreshing @Contacts@ from the
cache.
2014-05-05T22:28:16+02:00 INFO: [19463] Refreshing @Calendar@ from the
cache.
2014-05-05T22:28:16+02:00 INFO: [19463] Initializing state for collection:
@Contacts@, synckey: {5363f94c-b6f4-4bae-82dd-2950c0a87b68}19
2014-05-05T22:28:16+02:00 INFO: [19463] Loading state for synckey
{5363f94c-b6f4-4bae-82dd-2950c0a87b68}19
2014-05-05T22:28:16+02:00 INFO: [19463] Initializing message diff engine
for @Contacts@ (@Contacts@)
2014-05-05T22:28:16+02:00 INFO: [19463] Using SYNCSTAMP 4041 for
@Contacts at .
2014-05-05T22:28:16+02:00 INFO: [19463]
Horde_Core_ActiveSync_Driver::getServerChanges(@Contacts@, 4041, 4041, 0,
1)
2014-05-05T22:28:16+02:00 INFO: [19463] Fetching changes for contacts using
MODSEQ.
2014-05-05T22:28:16+02:00 INFO: [19463] Found 0 message changes in
@Contacts at .
2014-05-05T22:28:16+02:00 INFO: [19463] Initializing state for collection:
@Calendar@, synckey: {5363f891-47e8-4577-9aae-2916c0a87b68}10
2014-05-05T22:28:16+02:00 INFO: [19463] Loading state for synckey
{5363f891-47e8-4577-9aae-2916c0a87b68}10
2014-05-05T22:28:16+02:00 INFO: [19463] Initializing message diff engine
for @Calendar@ (@Calendar@)
2014-05-05T22:28:16+02:00 INFO: [19463] Using SYNCSTAMP 4048 for
@Calendar at .
2014-05-05T22:28:16+02:00 INFO: [19463]
Horde_Core_ActiveSync_Driver::getServerChanges(@Calendar@, 4048, 4048,
1398112096, 1)
2014-05-05T22:28:16+02:00 INFO: [19463] Fetching changes for calendar using
MODSEQ.
2014-05-05T22:28:16+02:00 INFO: [19463] Found 0 message changes in
@Calendar at .
2014-05-05T22:28:16+02:00 INFO: [19463] Sleeping for 120 seconds.
2014-05-05T22:30:16+02:00 INFO: [19463] SyncCache collections refreshed.
2014-05-05T22:30:16+02:00 INFO: [19463] Refreshing @Contacts@ from the
cache.
2014-05-05T22:30:16+02:00 INFO: [19463] Refreshing @Calendar@ from the
cache.
2014-05-05T22:30:16+02:00 INFO: [19463] Initializing state for collection:
@Contacts@, synckey: {5363f94c-b6f4-4bae-82dd-2950c0a87b68}19
2014-05-05T22:30:16+02:00 INFO: [19463] Loading state for synckey
{5363f94c-b6f4-4bae-82dd-2950c0a87b68}19
2014-05-05T22:30:16+02:00 INFO: [19463] Initializing message diff engine
for @Contacts@ (@Contacts@)
2014-05-05T22:30:16+02:00 INFO: [19463] Using SYNCSTAMP 4041 for
@Contacts at .
2014-05-05T22:30:16+02:00 INFO: [19463]
Horde_Core_ActiveSync_Driver::getServerChanges(@Contacts@, 4041, 4041, 0,
1)
2014-05-05T22:30:16+02:00 INFO: [19463] Fetching changes for contacts using
MODSEQ.
2014-05-05T22:30:16+02:00 INFO: [19463] Found 0 message changes in
@Contacts at .
2014-05-05T22:30:16+02:00 INFO: [19463] Initializing state for collection:
@Calendar@, synckey: {5363f891-47e8-4577-9aae-2916c0a87b68}10
2014-05-05T22:30:16+02:00 INFO: [19463] Loading state for synckey
{5363f891-47e8-4577-9aae-2916c0a87b68}10
2014-05-05T22:30:16+02:00 INFO: [19463] Initializing message diff engine
for @Calendar@ (@Calendar@)
2014-05-05T22:30:16+02:00 INFO: [19463] Using SYNCSTAMP 4048 for
@Calendar at .
2014-05-05T22:30:16+02:00 INFO: [19463]
Horde_Core_ActiveSync_Driver::getServerChanges(@Calendar@, 4048, 4048,
1398112216, 1)
2014-05-05T22:30:16+02:00 INFO: [19463] Fetching changes for calendar using
MODSEQ.
2014-05-05T22:30:16+02:00 INFO: [19463] Found 0 message changes in
@Calendar at .
2014-05-05T22:30:16+02:00 INFO: [19463] Sleeping for 50 seconds.
2014-05-05T22:31:06+02:00 INFO: [19463] SyncCache collections refreshed.
2014-05-05T22:31:06+02:00 INFO: [19463] Refreshing @Contacts@ from the
cache.
2014-05-05T22:31:06+02:00 INFO: [19463] Refreshing @Calendar@ from the
cache.
2014-05-05T22:31:06+02:00 INFO: [19463] Looping Sync complete:
DataAvailable: , DataImported:
2014-05-05T22:31:06+02:00 DEBUG: [19463] O  <Ping:Ping>
2014-05-05T22:31:06+02:00 DEBUG: [19463] O   <Ping:Status>
2014-05-05T22:31:06+02:00 DEBUG: [19463] O    1
2014-05-05T22:31:06+02:00 DEBUG: [19463] O   </Ping:Status>
2014-05-05T22:31:06+02:00 DEBUG: [19463] O  </Ping:Ping>
2014-05-05T22:31:06+02:00 INFO: [19463] Maximum memory usage for ActiveSync
request: 8756332 bytes.

2014-05-05T20:31:07+00:00 INFO: [18271]
Horde_Core_ActiveSync_Driver::authenticate() attempt for myuser at eph.dk
2014-05-05T20:31:07+00:00 INFO: [18271] FOLDERSYNC request received for
user myuser at eph.dk
2014-05-05T20:31:07+00:00 INFO: [18271] Device entry exists for
ANDROIDC1233653448, updating userAgent and version.
2014-05-05T20:31:07+00:00 INFO: [18271] Request being handled for device:
ANDROIDC1233653448, Supporting protocol version: 12.0, Using
Horde_ActiveSync v2.14.0
2014-05-05T20:31:07+00:00 INFO: [18271] Handling FOLDERSYNC command.
2014-05-05T20:31:07+00:00 INFO: [18271] Checking policykey for device:
ANDROIDC1233653448 user: myuser at eph.dk
2014-05-05T20:31:07+00:00 INFO: [18271] Allowing ANDROIDC1233653448 to
connect since PROVISIONING_LOOSE is true and is either non-provisionable or
has broken provisioning.
2014-05-05T20:31:07+00:00 INFO: [18271] Stored key: 0
2014-05-05T20:31:07+00:00 INFO: [18271] Policykey:  verified.
2014-05-05T20:31:07+00:00 DEBUG: [18271] I  <FolderHierarchy:FolderSync>
2014-05-05T20:31:07+00:00 DEBUG: [18271] I   <FolderHierarchy:SyncKey>
2014-05-05T20:31:07+00:00 DEBUG: [18271] I    
{5363f86a-2fac-453a-bc44-28c1c0a87b68}1
2014-05-05T20:31:07+00:00 DEBUG: [18271] I   </FolderHierarchy:SyncKey>
2014-05-05T20:31:07+00:00 INFO: [18271] Loading state for synckey
{5363f86a-2fac-453a-bc44-28c1c0a87b68}1
2014-05-05T20:31:07+00:00 INFO: [18271] Loading FOLDERSYNC state containing
7 folders.
2014-05-05T20:31:07+00:00 DEBUG: [18271] I  </FolderHierarchy:FolderSync>
2014-05-05T20:31:07+00:00 INFO: [18271] Initializing folder diff engine
2014-05-05T20:31:07+00:00 INFO: [18271]
Horde_Core_ActiveSync_Driver::getFolderList()
2014-05-05T20:31:07+00:00 INFO: [18271] No folder changes found.
2014-05-05T20:31:07+00:00 DEBUG: [18271] O  <FolderHierarchy:FolderSync>
2014-05-05T20:31:07+00:00 DEBUG: [18271] O   <FolderHierarchy:Status>
2014-05-05T20:31:07+00:00 DEBUG: [18271] O    1
2014-05-05T20:31:07+00:00 DEBUG: [18271] O   </FolderHierarchy:Status>
2014-05-05T20:31:07+00:00 DEBUG: [18271] O   <FolderHierarchy:SyncKey>
2014-05-05T20:31:07+00:00 DEBUG: [18271] O   
{5363f86a-2fac-453a-bc44-28c1c0a87b68}1
2014-05-05T20:31:07+00:00 DEBUG: [18271] O   </FolderHierarchy:SyncKey>
2014-05-05T20:31:07+00:00 DEBUG: [18271] O   <FolderHierarchy:Changes>
2014-05-05T20:31:07+00:00 DEBUG: [18271] O    <FolderHierarchy:Count>
2014-05-05T20:31:07+00:00 DEBUG: [18271] O     0
2014-05-05T20:31:07+00:00 DEBUG: [18271] O    </FolderHierarchy:Count>
2014-05-05T20:31:07+00:00 DEBUG: [18271] O   </FolderHierarchy:Changes>
2014-05-05T20:31:07+00:00 DEBUG: [18271] O  </FolderHierarchy:FolderSync>
2014-05-05T20:31:07+00:00 INFO: [18271] Replacing SYNC_CACHE entry for user
myuser at eph.dk and device ANDROIDC1233653448:
a:11:{s:18:"confirmed_synckeys";a:0:{}s:17:"lasthbsyncstarted";i:1399320856;s:17:"lastsyncendnormal";b:0;s:9:"timestamp";s:10:"1399321867";s:4:"wait";b:0;s:10:"hbinterval";s:4:"1010";s:7:"folders";a:7:{s:5:"Trash";a:2:{s:5:"class";s:5:"Email";s:8:"serverid";s:5:"Trash";}s:4:"Sent";a:2:{s:5:"class";s:5:"Email";s:8:"serverid";s:4:"Sent";}s:5:"INBOX";a:2:{s:5:"class";s:5:"Email";s:8:"serverid";s:5:"INBOX";}s:7:"@Tasks@";a:2:{s:5:"class";s:5:"Tasks";s:8:"serverid";s:7:"@Tasks@";}s:7:"@Notes@";a:2:{s:5:"class";s:5:"Notes";s:8:"serverid";s:7:"@Notes@";}s:10:"@Contacts@";a:2:{s:5:"class";s:8:"Contacts";s:8:"serverid";s:10:"@Contacts@";}s:10:"@Calendar@";a:2:{s:5:"class";s:8:"Calendar";s:8:"serverid";s:10:"@Calendar@";}}s:9:"hierarchy";s:39:"{5363f86a-2fac-453a-bc44-28c1c0a87b68}1";s:11:"collections";a:3:{s:10:"@Calendar@";a:12:{s:5:"class";s:8:"Calendar";s:10:"windowsize";s:1:"4";s:10:"truncation"
;i:0;s:11:"mimesupport";i:0;s:14:"mimetruncation";i:8;s:8:"conflict";i:1;s:9:"bodyprefs";a:1:{i:1;a:2:{s:4:"type";s:1:"1";s:14:"truncationsize";s:6:"200000";}}s:8:"serverid";s:10:"@Calendar@";s:11:"lastsynckey";s:40:"{5363f891-47e8-4577-9aae-2916c0a87b68}10";s:14:"deletesasmoves";b:1;s:10:"filtertype";s:1:"4";s:8:"pingable";b:1;}s:5:"INBOX";a:12:{s:5:"class";s:5:"Email";s:10:"windowsize";s:1:"5";s:10:"truncation";i:0;s:11:"mimesupport";i:0;s:14:"mimetruncation";i:8;s:8:"conflict";i:1;s:9:"bodyprefs";a:1:{i:2;a:2:{s:4:"type";s:1:"2";s:14:"truncationsize";s:6:"200000";}}s:8:"serverid";s:5:"INBOX";s:14:"deletesasmoves";b:1;s:10:"filtertype";s:1:"1";s:11:"lastsynckey";s:39:"{5363f94c-7240-4886-a74a-28c1c0a87b68}2";s:8:"pingable";b:0;}s:10:"@Contacts@";a:12:{s:5:"class";s:8:"Contacts";s:10:"windowsize";s:1:"4";s:14:"deletesasmoves";b:1;s:10:"filtertype";s:1:"0";s:10:"truncation";i:0;s:11:"mimesupport";i:0;s:14:"mimetruncation";i:8;s:8:"conflict";i:1;s:9:"bodyprefs";a:1:{i:1;a:2:{s:4:"type
";s:1:"1";s:14:"
truncationsize";s:6:"200000";}}s:8:"serverid";s:10:"@Contacts@";s:11:"lastsynckey";s:40:"{5363f94c-b6f4-4bae-82dd-2950c0a87b68}19";s:8:"pingable";b:1;}}s:13:"pingheartbeat";b:0;s:14:"synckeycounter";a:0:{}}
2014-05-05T20:31:07+00:00 INFO: [18271] Maximum memory usage for ActiveSync
request: 5125312 bytes.


More information about the horde mailing list