View Issue Details

Related ChangesetsJump to NotesJump to History
IDProjectCategoryView StatusDate SubmittedLast Update
0003460SOGoActiveSyncpublic2016-01-27 08:182016-09-23 16:49
Reporterstefan21 Assigned Toludovic  
PrioritynormalSeveritymajorReproducibilityalways
Status resolvedResolutionfixed 
Platform[Server] LinuxOSRHEL/CentOSOS Version6
Summary0003460: Mobile NOKIA E7-00 (OS Symbian ANNA and BELLE) not able to sync
Description

I'm not able to sync a Nokia E7-00 mobile with sogo. The phone is running on symbian os and provides a native ms exchange support which does work on any ms exchange server and also z-push. It was (still is) the "so-called" Nokia Flagship of business-mobiles.

Steps To Reproduce

Just set up an exchange-account on the phone with the usual credentials. It won't sync with sogo. It does sync with the same credentials to an ms exchange-server or a zarafa-server with z-push.

Additional Information

Through http-logs and sogo-logs the connect of the phone to the server can be seen. But there's no sync. I can provide logs or additional information. Just tell me what you need.

TagsNo tags attached.

Activities

tfu

tfu

2016-01-27 09:55

reporter   ~0009338

Set SOGoEASDebugEnabled = YES in sogo.conf when creating the account and upload sogo.log.
stefan21

stefan21

2016-01-27 10:32

reporter  

sogo.log (37,255 bytes)   
stefan21

stefan21

2016-01-27 10:33

reporter  

access.log (38,788 bytes)   
tfu

tfu

2016-01-27 11:37

reporter   ~0009339

The Logfile looks good. It just looks like as if there are no mails to sync (Filter is set to 7 Days):

Jan 27 11:20:54 sogod [2490]: <0x0xb894bf5c[SOGoActiveSyncDispatcher]> EAS - sortedBySequence (0) - lastObject: (null)
Jan 27 11:20:54 sogod [2490]: <0x0xb894bf5c[SOGoActiveSyncDispatcher]> EAS - allCacheObjects (0) - lastObject: (null)
Jan 27 11:20:54 sogod [2490]: <0x0xb894bf5c[SOGoActiveSyncDispatcher]> EAS - found in cache: 0 k = 0
Jan 27 11:20:54 sogod [2490]: <0x0xb894bf5c[SOGoActiveSyncDispatcher]> Change detected during Sync, we push the content.
Jan 27 11:20:54 sogod [2490]: <0x0xb894bf5c[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: <?xml version="1.0"?>
<!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/&quot;>
<Sync xmlns="AirSync:">
<Collections>
<Collection>
<Class>Email</Class>
<SyncKey>3-17</SyncKey>
<CollectionId>mail%2F1ef7512fd5206c54bb0b0000b79f221a</CollectionId>
<Status>1</Status>
</Collection>
</Collections>
</Sync>

Same for calendar: The logfile looks good but it seems that nothing has found to sync (Filter is set to 2 Weeks).
stefan21

stefan21

2016-01-27 13:58

reporter   ~0009340

I sent an email from the admin-account via horde to the test-user. The email has been synced and displayed on the mobile. Sending an email from the mobile to the admin, does not work. I didn't set up an external email-server in the box - not quite sure if this is the reason?

Syncing events is working in both directions.

Syncing contacts does not work at all - in no direction.

As I want to migrate from zarafa I imported all contacts and events in sogo. They're all displayed in the web-gui of sogo. Nothing is synced to the mobile.
tfu

tfu

2016-01-27 14:24

reporter   ~0009341

Can you send an email to the admin from the webui?
Are changes to email synced from mobile to the server (flag/unflag read)?
Check whether you can delete a mail from the mobile.
stefan21

stefan21

2016-01-27 16:34

reporter   ~0009342

Yes, I can send an email to the admin.
Yes, email status changes are synced.
Yes, deleting an email on the mobile is synced back to the server. On the server the email is also deleted.
tfu

tfu

2016-01-27 17:07

reporter   ~0009343

When you filed the bug you said that your mobile doesn't sync, but from what I see above most of the things are working now. Have you changed anything to come to that state?

You say that sending an email from sogo's webui works, then sending a mail via EAS should also work. Check sogo.log, search for SendMail.
stefan21

stefan21

2016-01-27 17:31

reporter   ~0009344

Sogo has been updated to 2.3.5-1.centos6.i686
On the mobile I changed the sync-duration to all emails. No expression "SendMail" in the sogo.log.

I disagree if you say most things are working now. I would rather say most things are still not working.

  • contacts aren't synced at all. Either new ones in both directions nor the imported ones back to the mobile.
  • only new events are synced, all imported events aren't synced.
tfu

tfu

2016-01-27 17:56

reporter   ~0009345

Try to recreate the account on your mobile and select to sync all mails and all events while creating the account.
What do you see in sogo.log when sending a mail via eas?
stefan21

stefan21

2016-01-28 09:22

reporter   ~0009353

Did as requested. At the initial sync I'm not able to configure/tune the mailbox on the phone. Did the sync with email, contacts and calendar.

Email and contacts have been synced. Calendar is still empty.

There are a warnings and errors in the log while starting the gui of sogo:

Jan 28 09:59:02 sogod [1963]: [ERROR] <0x0xb4f78b14[WOWatchDog]> No child available to handle incoming request!
Jan 28 09:59:02 sogod [1990]: 192.168.10.181 "POST /SOGo/so/test/Calendar/alarmslist?browserTime=1453967942 HTTP/1.1" 200 57/0 0.006 - - 0
Jan 28 09:59:02 sogod [1989]: 192.168.10.181 "POST /SOGo/so/test/Contacts/personal/canAccessContent HTTP/1.1" 204 0/0 0.031 - - 128KJan 28 09:59:02 sogod [1990]: 192.168.10.181 "POST /SOGo/so/test/Contacts/users/canAccessContent HTTP/1.1" 204 0/0 0.010 - - 4K

Jan 28 09:59:02 sogod [1986]: <0x0xb4ebb414[SOGoCache]> Cache cleanup interval set every 1800.000000 seconds
Jan 28 09:59:02 sogod [1986]: <0x0xb4ebb414[SOGoCache]> Using host(s) '127.0.0.1' as server(s)
Jan 28 09:59:02 sogod [1986]: 192.168.10.181 "POST /SOGo/so/test/Contacts/groups/canAccessContent HTTP/1.1" 204 0/0 0.069 - - 856K
Jan 28 09:59:06 sogod [1986]: [WARN] <0x0xb48cb044[SOGoWebDAVAclManager]> entry '{DAV:}write' already exists in DAV permissions table
Jan 28 09:59:06 sogod [1986]: [WARN] <0x0xb48cb044[SOGoWebDAVAclManager]> entry '{DAV:}write-properties' already exists in DAV permissions table
Jan 28 09:59:06 sogod [1986]: [WARN] <0x0xb48cb044[SOGoWebDAVAclManager]> entry '{DAV:}write-content' already exists in DAV permissions table
Jan 28 09:59:06 sogod [1986]: 192.168.10.181 "GET /SOGo/so/test/Calendar HTTP/1.1" 302 0/0 0.010 - - 96K
Jan 28 09:59:06 sogod [1986]: 192.168.10.181 "GET /SOGo/so/test/Calendar/view HTTP/1.1" 200 14398/0 0.234 54103 73% 3M
Jan 28 09:59:07 sogod [1986]: 192.168.10.181 "POST /SOGo/so/test/Calendar/personal/canAccessContent HTTP/1.1" 204 0/0 0.005 - - 12K
Jan 28 09:59:07 sogod [1989]: [WARN] <0x0xb48fed34[SOGoWebDAVAclManager]> entry '{DAV:}write' already exists in DAV permissions tableJan 28 09:59:07 sogod [1963]: [ERROR] <0x0xb4f78b14[WOWatchDog]> No child available to handle incoming request!

Jan 28 09:59:07 sogod [1989]: [WARN] <0x0xb48fed34[SOGoWebDAVAclManager]> entry '{DAV:}write-properties' already exists in DAV permissions table
Jan 28 09:59:07 sogod [1989]: [WARN] <0x0xb48fed34[SOGoWebDAVAclManager]> entry '{DAV:}write-content' already exists in DAV permissions table
Jan 28 09:59:07 sogod [1990]: 192.168.10.181 "POST /SOGo/so/test/Calendar/taskslist?show-completed=0&asc=true&sort=end&filterpopup=view_today HTTP/1.1" 200 2/0 0.018 - - 40K
Jan 28 09:59:07 sogod [1986]: 192.168.10.181 "POST /SOGo/so/test/Calendar/alarmslist?browserTime=1453967946 HTTP/1.1" 200 57/0 0.024 - - 12K
Jan 28 09:59:07 sogod [1963]: [ERROR] <0x0xb4f78b14[WOWatchDog]> No child available to handle incoming request!
Jan 28 09:59:07 sogod [1990]: 192.168.10.181 "POST /SOGo/so/test/Calendar/activeTasks HTTP/1.1" 200 15/0 0.013 - - 4K

Some additional information of an event:

&lt;Add>
 &lt;ServerId>ab182abc313e48b58e4fa83998d1f95@ThunderBirthDay.ics&lt;/ServerId>
 &lt;ApplicationData>
  &lt;AllDayEvent xmlns=&quot;Calendar:&quot;>1&lt;/AllDayEvent>
  &lt;DTStamp xmlns=&quot;Calendar:&quot;>20151005T153340Z&lt;/DTStamp>
  &lt;StartTime xmlns=&quot;Calendar:&quot;>19620301T230000Z&lt;/StartTime>
  &lt;EndTime xmlns=&quot;Calendar:&quot;>19620302T230000Z&lt;/EndTime>
  &lt;TimeZone xmlns=&quot;Calendar:&quot;>xP///wAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAoAAAAFAAMAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAMAAAAFAAIAAAAAAAAAxP///w==&lt;/TimeZone>
  &lt;MeetingStatus xmlns=&quot;Calendar:&quot;>0&lt;/MeetingStatus>
  &lt;Subject xmlns=&quot;Calendar:&quot;>Weimer, Jürgen&lt;/Subject>
  &lt;UID xmlns=&quot;Calendar:&quot;>ab182abc313e48b58e4fa83998d1f95@ThunderBirthDay&lt;/UID>
  &lt;Sensitivity xmlns=&quot;Calendar:&quot;>2&lt;/Sensitivity>
  &lt;Recurrence xmlns=&quot;Calendar:&quot;>
   &lt;Recurrence_DayOfMonth>2&lt;/Recurrence_DayOfMonth>
   &lt;Recurrence_MonthOfYear>3&lt;/Recurrence_MonthOfYear>
   &lt;Recurrence_Type>5&lt;/Recurrence_Type>
  &lt;/Recurrence>
  &lt;NativeBodyType xmlns=&quot;AirSyncBase:&quot;>1&lt;/NativeBodyType>
 &lt;/ApplicationData>
&lt;/Add>

Syncing seems to be started only with a few events. There are at least > 100 to be synced.

I'll re-create the account on the mobile once more. Will then start to sync step-by-step. First email, then contacts at last events. I'll report the result.
stefan21

stefan21

2016-01-28 09:51

reporter   ~0009354

Initial calendar sync, events are configured to all:

Jan 28 10:37:13 sogod [1990]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=test&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Sync HTTP/1.1" 200 0/99 0.400 - - 0
Jan 28 10:37:14 sogod [1990]: <0x0xb48d733c[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: <?xml version="1.0"?>
<!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/&quot;>
<Sync xmlns="AirSync:">
<Collections>
<Collection>
<SyncKey>0</SyncKey>
<CollectionId>vevent%2Fpersonal</CollectionId>
</Collection>
</Collections>
</Sync>

Jan 28 10:37:14 sogod [1990]: <0x0xb48d733c[SOGoActiveSyncDispatcher]> Change detected during Sync, we push the content.
Jan 28 10:37:14 sogod [1990]: <0x0xb48d733c[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: <?xml version="1.0"?>
<!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/&quot;>
<Sync xmlns="AirSync:">
<Collections>
<Collection>
<Class>Calendar</Class>
<SyncKey>-1</SyncKey>
<CollectionId>vevent%2Fpersonal</CollectionId>
<Status>1</Status>
<MoreAvailable/>
</Collection>
</Collections>
</Sync>

Jan 28 10:37:14 sogod [1990]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=test&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Sync HTTP/1.1" 200 55/36 0.013 - - 0
Jan 28 10:37:14 sogod [1990]: <0x0xb464f97c[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: <?xml version="1.0"?>
<!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/&quot;>
<Sync xmlns="AirSync:">
<Collections>
<Collection>
<SyncKey>-1</SyncKey>
<CollectionId>vevent%2Fpersonal</CollectionId>
<DeletesAsMoves/>
<GetChanges/>
<WindowSize>5</WindowSize>
<Options>
<FilterType>0</FilterType>
<BodyPreference xmlns="AirSyncBase:">
<Type>1</Type>
<TruncationSize>2048</TruncationSize>
</BodyPreference>
<Conflict>1</Conflict>
</Options>
</Collection>
</Collections>
</Sync>

Jan 28 10:37:14 sogod [1990]: <0x0xb464f97c[SOGoActiveSyncDispatcher]> Change detected during Sync, we push the content.
Jan 28 10:37:14 sogod [1990]: <0x0xb464f97c[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: <?xml version="1.0"?>
<!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/&quot;>
<Sync xmlns="AirSync:">
<Collections>
<Collection>
<Class>Calendar</Class>
<SyncKey>1453898687</SyncKey>
<CollectionId>vevent%2Fpersonal</CollectionId>
<Status>1</Status>
<MoreAvailable/>
<Commands>
<Add>
<ServerId>7BE-56A8BB00-1-5A9B4E00</ServerId>
<ApplicationData>
<AllDayEvent xmlns="Calendar:">0</AllDayEvent>
<DTStamp xmlns="Calendar:">20160127T124136Z</DTStamp>
<StartTime xmlns="Calendar:">20160128T100000Z</StartTime>
<EndTime xmlns="Calendar:">20160128T110000Z</EndTime>
<TimeZone xmlns="Calendar:">xP///wAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAoAAAAFAAMAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAMAAAAFAAIAAAAAAAAAxP///w==</TimeZone>
<MeetingStatus xmlns="Calendar:">0</MeetingStatus>
<Subject xmlns="Calendar:">das ist ein test</Subject>
<Location xmlns="Calendar:">ort</Location>
<UID xmlns="Calendar:">7BE-56A8BB00-1-5A9B4E00</UID>
<Sensitivity xmlns="Calendar:">0</Sensitivity>
<Reminder xmlns="Calendar:">15</Reminder>
<NativeBodyType xmlns="AirSyncBase:">1</NativeBodyType>
</ApplicationData>
</Add>
<Add>
<ServerId>040000008200E00074C5B7101A82E0080000000080F034A615FED001000000000000000001000000E9FD132FBBC441898ADA4BACBF1F2223.ics</ServerId>
<ApplicationData>
<AllDayEvent xmlns="Calendar:">1</AllDayEvent>
<DTStamp xmlns="Calendar:">20151003T195653Z</DTStamp>
<StartTime xmlns="Calendar:">20110609T220000Z</StartTime>
<EndTime xmlns="Calendar:">20110610T220000Z</EndTime>
<TimeZone xmlns="Calendar:">xP///wAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAoAAAAFAAMAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAMAAAAFAAIAAAAAAAAAxP///w==</TimeZone>
<Organizer_Email xmlns="Calendar:">mailto:</Organizer_Email>
<MeetingStatus xmlns="Calendar:">0</MeetingStatus>
<Subject xmlns="Calendar:">Messebesuch Solartech</Subject>
<Location xmlns="Calendar:">München</Location>
<UID xmlns="Calendar:">040000008200E00074C5B7101A82E0080000000080F034A615FED001000000000000000001000000E9FD132FBBC441898ADA4BACBF1F2223</UID>
<Sensitivity xmlns="Calendar:">0</Sensitivity>
<NativeBodyType xmlns="AirSyncBase:">1</NativeBodyType>
</ApplicationData>
</Add>
<Add>
<ServerId>040000008200E00074C5B7101A82E0080000000080F034A615FED001000000000000000001000000CB25042FD27C4A799A8DF3A1D3B41E36.ics</ServerId>
<ApplicationData>
<AllDayEvent xmlns="Calendar:">1</AllDayEvent>
<DTStamp xmlns="Calendar:">20151003T195653Z</DTStamp>
<StartTime xmlns="Calendar:">20120429T220000Z</StartTime>
<EndTime xmlns="Calendar:">20120510T220000Z</EndTime>
<TimeZone xmlns="Calendar:">xP///wAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAoAAAAFAAMAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAMAAAAFAAIAAAAAAAAAxP///w==</TimeZone>
<Organizer_Email xmlns="Calendar:">mailto:</Organizer_Email>
<MeetingStatus xmlns="Calendar:">0</MeetingStatus>
<Subject xmlns="Calendar:">Urlaub</Subject>
<UID xmlns="Calendar:">040000008200E00074C5B7101A82E0080000000080F034A615FED001000000000000000001000000CB25042FD27C4A799A8DF3A1D3B41E36</UID>
<Sensitivity xmlns="Calendar:">2</Sensitivity>
<NativeBodyType xmlns="AirSyncBase:">1</NativeBodyType>
</ApplicationData>
</Add>
</Commands>
</Collection>
</Collections>
</Sync>

Jan 28 10:37:14 sogod [1990]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=test&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Sync HTTP/1.1" 200 1669/75 0.103 - - 0

There's only one event synced. The one I created yesterday for test purpose.

Enabling the sync of contacts is not working at all. Here's the log after enabling on the mobile:

Jan 28 10:48:55 sogod [1990]: <0x0xb47d1994[GCSFolder]> ERROR(-[GCSFolder writeContent:fromComponent:container:toName:baseVersion:]): cannot insert content : <MySQL4Exception: 0xb457e644> NAME:ExecutionFailed REASON:Duplicate entry '4561732055696420546167205B265E25242340215D3034303030303030383230' for key 'PRIMARY'
Jan 28 10:48:55 sogod [1990]: [ERROR] <0xB47D34B4[SOGoAppointmentObject]:4561732055696420546167205B265E25242340215D30343030303030303832303045303030373443354237313031413832453030383030303030303030383046303334413631354645443030313030303030303030303030303030303030313030303030304539464431333246424243343431383938414441344241434246314632323233.ics> write failed: <MySQL4Exception: 0xb457e644> NAME:ExecutionFailed REASON:Duplicate entry '4561732055696420546167205B265E25242340215D3034303030303030383230' for key 'PRIMARY'
Jan 28 10:48:56 sogod [1990]: <0x0xb47b7584[SOGoActiveSyncDispatcher]> Change detected during Sync, we push the content.
Jan 28 10:48:56 sogod [1990]: <0x0xb47b7584[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: <?xml version="1.0"?>
<!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/&quot;>
<Sync xmlns="AirSync:">
<Collections>
<Collection>
<Class>Calendar</Class>
<SyncKey>1453898687</SyncKey>
<CollectionId>vevent%2Fpersonal</CollectionId>
<Status>1</Status>
<MoreAvailable/>
<Responses>
<Add>
<ClientId>1</ClientId>
<ServerId>4561732055696420546167205B265E25242340215D30343030303030303832303045303030373443354237313031413832453030383030303030303030383046303334413631354645443030313030303030303030303030303030303030313030303030304539464431333246424243343431383938414441344241434246314632323233</ServerId>
<Status>1</Status>
</Add>
</Responses>
<Commands>
<Add>
<ServerId>7BE-56A8BB00-1-5A9B4E00</ServerId>
<ApplicationData>
<AllDayEvent xmlns="Calendar:">0</AllDayEvent>
<DTStamp xmlns="Calendar:">20160127T124136Z</DTStamp>
<StartTime xmlns="Calendar:">20160128T100000Z</StartTime>
<EndTime xmlns="Calendar:">20160128T110000Z</EndTime>
<TimeZone xmlns="Calendar:">xP///wAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAoAAAAFAAMAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAMAAAAFAAIAAAAAAAAAxP///w==</TimeZone>
<MeetingStatus xmlns="Calendar:">0</MeetingStatus>
<Subject xmlns="Calendar:">das ist ein test</Subject>
<Location xmlns="Calendar:">ort</Location>
<UID xmlns="Calendar:">7BE-56A8BB00-1-5A9B4E00</UID>
<Sensitivity xmlns="Calendar:">0</Sensitivity>
<Reminder xmlns="Calendar:">15</Reminder>
<NativeBodyType xmlns="AirSyncBase:">1</NativeBodyType>
</ApplicationData>
</Add>
<Add>
<ServerId>040000008200E00074C5B7101A82E0080000000080F034A615FED001000000000000000001000000E9FD132FBBC441898ADA4BACBF1F2223.ics</ServerId>
<ApplicationData>
<AllDayEvent xmlns="Calendar:">1</AllDayEvent>
<DTStamp xmlns="Calendar:">20151003T195653Z</DTStamp>
<StartTime xmlns="Calendar:">20110609T220000Z</StartTime>
<EndTime xmlns="Calendar:">20110610T220000Z</EndTime>
<TimeZone xmlns="Calendar:">xP///wAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAoAAAAFAAMAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAMAAAAFAAIAAAAAAAAAxP///w==</TimeZone>
<Organizer_Email xmlns="Calendar:">mailto:</Organizer_Email>
<MeetingStatus xmlns="Calendar:">0</MeetingStatus>
<Subject xmlns="Calendar:">Messebesuch Solartech</Subject>
<Location xmlns="Calendar:">München</Location>
<UID xmlns="Calendar:">040000008200E00074C5B7101A82E0080000000080F034A615FED001000000000000000001000000E9FD132FBBC441898ADA4BACBF1F2223</UID>
<Sensitivity xmlns="Calendar:">0</Sensitivity>
<NativeBodyType xmlns="AirSyncBase:">1</NativeBodyType>
</ApplicationData>
</Add>
<Add>
<ServerId>040000008200E00074C5B7101A82E0080000000080F034A615FED001000000000000000001000000CB25042FD27C4A799A8DF3A1D3B41E36.ics</ServerId>
<ApplicationData>
<AllDayEvent xmlns="Calendar:">1</AllDayEvent>
<DTStamp xmlns="Calendar:">20151003T195653Z</DTStamp>
<StartTime xmlns="Calendar:">20120429T220000Z</StartTime>
<EndTime xmlns="Calendar:">20120510T220000Z</EndTime>
<TimeZone xmlns="Calendar:">xP///wAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAoAAAAFAAMAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAMAAAAFAAIAAAAAAAAAxP///w==</TimeZone>
<Organizer_Email xmlns="Calendar:">mailto:</Organizer_Email>
<MeetingStatus xmlns="Calendar:">0</MeetingStatus>
<Subject xmlns="Calendar:">Urlaub</Subject>
<UID xmlns="Calendar:">040000008200E00074C5B7101A82E0080000000080F034A615FED001000000000000000001000000CB25042FD27C4A799A8DF3A1D3B41E36</UID>
<Sensitivity xmlns="Calendar:">2</Sensitivity>
<NativeBodyType xmlns="AirSyncBase:">1</NativeBodyType>
</ApplicationData>
</Add>
</Commands>
</Collection>
</Collections>
</Sync>

Jan 28 10:48:56 sogod [1990]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=test&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Sync HTTP/1.1" 200 1953/729 0.114 - - 0

No contacts have been synced.

Seems all syncing has to be enabled while doing the initial sync?
stefan21

stefan21

2016-01-28 09:53

reporter   ~0009355

Error:

Jan 28 10:48:55 sogod [1990]: <0x0xb47d1994[GCSFolder]> ERROR(-[GCSFolder writeContent:fromComponent:container:toName:baseVersion:]): cannot insert content : <MySQL4Exception: 0xb457e644> NAME:ExecutionFailed REASON:Duplicate entry '4561732055696420546167205B265E25242340215D3034303030303030383230' for key 'PRIMARY'
Jan 28 10:48:55 sogod [1990]: [ERROR] <0xB47D34B4[SOGoAppointmentObject]:4561732055696420546167205B265E25242340215D30343030303030303832303045303030373443354237313031413832453030383030303030303030383046303334413631354645443030313030303030303030303030303030303030313030303030304539464431333246424243343431383938414441344241434246314632323233.ics> write failed: <MySQL4Exception: 0xb457e644> NAME:ExecutionFailed REASON:Duplicate entry '4561732055696420546167205B265E25242340215D3034303030303030383230' for key 'PRIMARY'

Don't know if this the reason causing the sync stop.
tfu

tfu

2016-01-28 10:24

reporter   ~0009356

To get rid of the mesasage 'No child available to handle incoming request!' you have to increase WOWorkersCount.

/etc/sogo/sogo.conf
WOWorkersCount =
/etc/default/sogo
PREFORK=20
restart sogo and check:
ps auxwww | grep -i sogo
stefan21

stefan21

2016-01-28 10:38

reporter   ~0009357

Last edited: 2016-01-28 10:40

Did some changes:

// For active sync
WOWorkersCount = 5;
SOGoMaximumPingInterval = 3540;
SOGoMaximumSyncInterval = 3540;
SOGoInternalSyncInterval = 30;

and:

The amount of processes that should be spawned (Default: 3)

PREFORK=10

Here's the log:

[root@mond sogo]# tail -f /var/log/sogo/sogo.log
Jan 28 11:27:39 sogod [4039]: <0x0xb7a8b4d4[WOWatchDog]> child spawned with pid 4040
Jan 28 11:27:39 sogod [4039]: <0x0xb7a8b4d4[WOWatchDog]> child spawned with pid 4041
Jan 28 11:27:39 sogod [4039]: <0x0xb7a8b4d4[WOWatchDog]> child spawned with pid 4042
Jan 28 11:27:39 sogod [4039]: <0x0xb7a8b4d4[WOWatchDog]> child spawned with pid 4043
Jan 28 11:27:39 sogod [4039]: <0x0xb7a8b4d4[WOWatchDog]> child spawned with pid 4044
Jan 28 11:27:39 sogod [4039]: <0x0xb7a8b4d4[WOWatchDog]> child spawned with pid 4045
Jan 28 11:27:40 sogod [4039]: <0x0xb7a8b4d4[WOWatchDog]> child spawned with pid 4046
Jan 28 11:27:40 sogod [4039]: <0x0xb7a8b4d4[WOWatchDog]> child spawned with pid 4047
Jan 28 11:27:40 sogod [4039]: <0x0xb7a8b4d4[WOWatchDog]> child spawned with pid 4048
Jan 28 11:27:40 sogod [4039]: <0x0xb7a8b4d4[WOWatchDog]> child spawned with pid 4049
Jan 28 11:35:29 sogod [4048]: <0x0xb7a555cc[SOGoCache]> Cache cleanup interval set every 1800.000000 seconds
Jan 28 11:35:29 sogod [4048]: <0x0xb7a555cc[SOGoCache]> Using host(s) '127.0.0.1' as server(s)
Jan 28 11:35:29 sogod [4048]: <0x0xb7be4b84[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: <?xml version="1.0"?>
<!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/&quot;>
<FolderSync xmlns="FolderHierarchy:">
<SyncKey>mond_ivbhome_local_e65_1c5a215b_0</SyncKey>
</FolderSync>

Jan 28 11:35:30 sogod [4048]: [WARN] <0x0xb7d45a1c[SOGoWebDAVAclManager]> entry '{DAV:}write' already exists in DAV permissions table
Jan 28 11:35:30 sogod [4048]: [WARN] <0x0xb7d45a1c[SOGoWebDAVAclManager]> entry '{DAV:}write-properties' already exists in DAV permissions table
Jan 28 11:35:30 sogod [4048]: [WARN] <0x0xb7d45a1c[SOGoWebDAVAclManager]> entry '{DAV:}write-content' already exists in DAV permissions table
Jan 28 11:35:30 sogod [4048]: <0x0xb7be4b84[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: <?xml version="1.0"?>
<!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/&quot;>
<FolderSync xmlns="FolderHierarchy:">
<Status>1</Status>
<SyncKey>mond_ivbhome_local_e65_1c5a215b_0</SyncKey>
<Changes>
<Count>0</Count>
</Changes>
</FolderSync>

Jan 28 11:35:30 sogod [4048]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=test&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=FolderSync HTTP/1.1" 200 57/45 0.616 - - 3M
Jan 28 11:35:30 sogod [4048]: <0x0xb7c52c6c[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: <?xml version="1.0"?>
<!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/&quot;>
<GetItemEstimate xmlns="GetItemEstimate:">
<Collections>
<Collection>
<CollectionId>mail%2F1ef7512fd5206c54bb0b0000b79f221a</CollectionId>
<FilterType xmlns="AirSync:">3</FilterType>
<SyncKey xmlns="AirSync:">4-25</SyncKey>
</Collection>
<Collection>
<CollectionId>vevent%2Fpersonal</CollectionId>
<FilterType xmlns="AirSync:">4</FilterType>
<SyncKey xmlns="AirSync:">1453898707</SyncKey>
</Collection>
<Collection>
<CollectionId>vcard%2Fpersonal</CollectionId>
<FilterType xmlns="AirSync:">0</FilterType>
<SyncKey xmlns="AirSync:">1453899477</SyncKey>
</Collection>
</Collections>
</GetItemEstimate>

Jan 28 11:35:30 sogod [4048]: <0x0xb7bfae3c[NGImap4Client]> Note: no key found for sorting, using 'DATE': (null)
Jan 28 11:35:30 sogod [4048]: <0x0xb7c52c6c[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: <?xml version="1.0"?>
<!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/&quot;>
<GetItemEstimate xmlns="GetItemEstimate:">
<Response>
<Status>1</Status>
<Collection>
<Class>Email</Class>
<CollectionId>mail%2F1ef7512fd5206c54bb0b0000b79f221a</CollectionId>
<Estimate>0</Estimate>
</Collection>
</Response>
<Response>
<Status>1</Status>
<Collection>
<Class>Calendar</Class>
<CollectionId>vevent%2Fpersonal</CollectionId>
<Estimate>270</Estimate>
</Collection>
</Response>
<Response>
<Status>1</Status>
<Collection>
<Class>Contacts</Class>
<CollectionId>vcard%2Fpersonal</CollectionId>
<Estimate>263</Estimate>
</Collection>
</Response>
</GetItemEstimate>

Jan 28 11:35:30 sogod [4048]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=test&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=GetItemEstimate HTTP/1.1" 200 171/161 0.549 - - 160K
Jan 28 11:35:31 sogod [4048]: <0x0xb7bfa4d4[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: <?xml version="1.0"?>
<!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/&quot;>
<Sync xmlns="AirSync:">
<Collections>
<Collection>
<SyncKey>1453898707</SyncKey>
<CollectionId>vevent%2Fpersonal</CollectionId>
<DeletesAsMoves/>
<GetChanges/>
<WindowSize>5</WindowSize>
<Options>
<FilterType>4</FilterType>
<BodyPreference xmlns="AirSyncBase:">
<Type>1</Type>
<TruncationSize>2048</TruncationSize>
</BodyPreference>
<Conflict>1</Conflict>
</Options>
<Commands>
<Add>
<ClientId>1</ClientId>
<ApplicationData>
<TimeZone xmlns="Calendar:">xP///wAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAoAAAAFAAEAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAMAAAAFAAEAAAAAAAAAxP///w==</TimeZone>
<DTStamp xmlns="Calendar:">20160128T103526Z</DTStamp>
<StartTime xmlns="Calendar:">20160202T070000Z</StartTime>
<Subject xmlns="Calendar:">Test</Subject>
<UID xmlns="Calendar:">4561732055696420546167205B265E25242340215D32366434616665662D373135322D333630632D623632372D636135343433356361646462</UID>
<EndTime xmlns="Calendar:">20160202T080000Z</EndTime>
<Body xmlns="AirSyncBase:">
<Type>1</Type>
<Data/>
</Body>
<Sensitivity xmlns="Calendar:">2</Sensitivity>
<BusyStatus xmlns="Calendar:">2</BusyStatus>
<Reminder xmlns="Calendar:">15</Reminder>
</ApplicationData>
</Add>
</Commands>
</Collection>
</Collections>
</Sync>

Jan 28 11:35:31 sogod [4048]: <0x0xb7bfa4d4[SOGoActiveSyncDispatcher]> Change detected during Sync, we push the content.
Jan 28 11:35:31 sogod [4048]: <0x0xb7bfa4d4[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: <?xml version="1.0"?>
<!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/&quot;>
<Sync xmlns="AirSync:">
<Collections>
<Collection>
<Class>Calendar</Class>
<SyncKey>1453898707</SyncKey>
<CollectionId>vevent%2Fpersonal</CollectionId>
<Status>1</Status>
<MoreAvailable/>
<Responses>
<Add>
<ClientId>1</ClientId>
<ServerId>4561732055696420546167205B265E25242340215D32366434616665662D373135322D333630632D623632372D636135343433356361646462</ServerId>
<Status>1</Status>
</Add>
</Responses>
<Commands>
<Add>
<ServerId>94ca368efd54dfbe545964502c6d3f8@ThunderBirthDay.ics</ServerId>
<ApplicationData>
<AllDayEvent xmlns="Calendar:">1</AllDayEvent>
<DTStamp xmlns="Calendar:">20151005T153340Z</DTStamp>
<StartTime xmlns="Calendar:">19630313T230000Z</StartTime>
<EndTime xmlns="Calendar:">19630314T230000Z</EndTime>
<TimeZone xmlns="Calendar:">xP///wAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAoAAAAFAAMAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAMAAAAFAAIAAAAAAAAAxP///w==</TimeZone>
<MeetingStatus xmlns="Calendar:">0</MeetingStatus>
<Subject xmlns="Calendar:">Reichenberger, Thomas</Subject>
<UID xmlns="Calendar:">94ca368efd54dfbe545964502c6d3f8@ThunderBirthDay</UID>
<Sensitivity xmlns="Calendar:">2</Sensitivity>
<Recurrence xmlns="Calendar:">
<Recurrence_DayOfMonth>14</Recurrence_DayOfMonth>
<Recurrence_MonthOfYear>3</Recurrence_MonthOfYear>
<Recurrence_Type>5</Recurrence_Type>
</Recurrence>
<NativeBodyType xmlns="AirSyncBase:">1</NativeBodyType>
</ApplicationData>
</Add>
<Add>
<ServerId>a997fa5fbf823dfc5dfb92eb032e92@ThunderBirthDay.ics</ServerId>
<ApplicationData>
<AllDayEvent xmlns="Calendar:">1</AllDayEvent>
<DTStamp xmlns="Calendar:">20151005T153340Z</DTStamp>
<StartTime xmlns="Calendar:">19390325T230000Z</StartTime>
<EndTime xmlns="Calendar:">19390326T230000Z</EndTime>
<TimeZone xmlns="Calendar:">xP///wAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAoAAAAFAAMAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAMAAAAFAAIAAAAAAAAAxP///w==</TimeZone>
<MeetingStatus xmlns="Calendar:">0</MeetingStatus>
<Subject xmlns="Calendar:">Kammerer, Bernd</Subject>
<UID xmlns="Calendar:">a997fa5fbf823dfc5dfb92eb032e92@ThunderBirthDay</UID>
<Sensitivity xmlns="Calendar:">2</Sensitivity>
<Recurrence xmlns="Calendar:">
<Recurrence_DayOfMonth>26</Recurrence_DayOfMonth>
<Recurrence_MonthOfYear>3</Recurrence_MonthOfYear>
<Recurrence_Type>5</Recurrence_Type>
</Recurrence>
<NativeBodyType xmlns="AirSyncBase:">1</NativeBodyType>
</ApplicationData>
</Add>
<Add>
<ServerId>dd8c604a7acf166c3569db9193cbe39f@ThunderBirthDay.ics</ServerId>
<ApplicationData>
<AllDayEvent xmlns="Calendar:">1</AllDayEvent>
<DTStamp xmlns="Calendar:">20151005T153340Z</DTStamp>
<StartTime xmlns="Calendar:">19980329T220000Z</StartTime>
<EndTime xmlns="Calendar:">19980330T220000Z</EndTime>
<TimeZone xmlns="Calendar:">xP///wAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAoAAAAFAAMAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAMAAAAFAAIAAAAAAAAAxP///w==</TimeZone>
<MeetingStatus xmlns="Calendar:">0</MeetingStatus>
<Subject xmlns="Calendar:">Just, Vanessa</Subject>
<UID xmlns="Calendar:">dd8c604a7acf166c3569db9193cbe39f@ThunderBirthDay</UID>
<Sensitivity xmlns="Calendar:">2</Sensitivity>
<Recurrence xmlns="Calendar:">
<Recurrence_DayOfMonth>30</Recurrence_DayOfMonth>
<Recurrence_MonthOfYear>3</Recurrence_MonthOfYear>
<Recurrence_Type>5</Recurrence_Type>
</Recurrence>
<NativeBodyType xmlns="AirSyncBase:">1</NativeBodyType>
</ApplicationData>
</Add>
</Commands>
</Collection>
</Collections>
</Sync>

Jan 28 11:35:31 sogod [4048]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=test&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Sync HTTP/1.1" 200 1607/546 0.148 - - 2M
Jan 28 11:35:34 sogod [4048]: <0x0xb7d513a4[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: <?xml version="1.0"?>
<!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/&quot;>
<FolderSync xmlns="FolderHierarchy:">
<SyncKey>mond_ivbhome_local_e65_1c5a215b_0</SyncKey>
</FolderSync>

Jan 28 11:35:34 sogod [4048]: <0x0xb7d513a4[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: <?xml version="1.0"?>
<!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/&quot;>
<FolderSync xmlns="FolderHierarchy:">
<Status>1</Status>
<SyncKey>mond_ivbhome_local_e65_1c5a215b_0</SyncKey>
<Changes>
<Count>0</Count>
</Changes>
</FolderSync>

Jan 28 11:35:34 sogod [4048]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=test&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=FolderSync HTTP/1.1" 200 57/45 0.319 - - 0
Jan 28 11:35:35 sogod [4048]: <0x0xb7c30dbc[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: <?xml version="1.0"?>
<!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/&quot;>
<Sync xmlns="AirSync:">
<Collections>
<Collection>
<SyncKey>4-25</SyncKey>
<CollectionId>mail%2F1ef7512fd5206c54bb0b0000b79f221a</CollectionId>
<DeletesAsMoves/>
<GetChanges/>
<WindowSize>5</WindowSize>
<Options>
<FilterType>3</FilterType>
<BodyPreference xmlns="AirSyncBase:">
<Type>2</Type>
<TruncationSize>2048</TruncationSize>
</BodyPreference>
<Conflict>1</Conflict>
</Options>
</Collection>
</Collections>
</Sync>

Jan 28 11:35:35 sogod [4048]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=test&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Sync HTTP/1.1" 200 0/99 0.462 - - 0
Jan 28 11:35:35 sogod [4048]: <0x0xb7c5a134[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: <?xml version="1.0"?>
<!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/&quot;>
<Sync xmlns="AirSync:">
<Collections>
<Collection>
<SyncKey>1453898707</SyncKey>
<CollectionId>vevent%2Fpersonal</CollectionId>
<DeletesAsMoves/>
<GetChanges/>
<WindowSize>5</WindowSize>
<Options>
<FilterType>4</FilterType>
<BodyPreference xmlns="AirSyncBase:">
<Type>1</Type>
<TruncationSize>2048</TruncationSize>
</BodyPreference>
<Conflict>1</Conflict>
</Options>
<Commands>
<Add>
<ClientId>1</ClientId>
<ApplicationData>
<TimeZone xmlns="Calendar:">xP///wAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAoAAAAFAAEAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAMAAAAFAAEAAAAAAAAAxP///w==</TimeZone>
<DTStamp xmlns="Calendar:">20160128T103530Z</DTStamp>
<StartTime xmlns="Calendar:">20160202T070000Z</StartTime>
<Subject xmlns="Calendar:">Test</Subject>
<UID xmlns="Calendar:">4561732055696420546167205B265E25242340215D32366434616665662D373135322D333630632D623632372D636135343433356361646462</UID>
<EndTime xmlns="Calendar:">20160202T080000Z</EndTime>
<Body xmlns="AirSyncBase:">
<Type>1</Type>
<Data/>
</Body>
<Sensitivity xmlns="Calendar:">2</Sensitivity>
<BusyStatus xmlns="Calendar:">2</BusyStatus>
<Reminder xmlns="Calendar:">15</Reminder>
</ApplicationData>
</Add>
</Commands>
</Collection>
</Collections>
</Sync>

Jan 28 11:35:35 sogod [4048]: <0x0xb7c5a134[SOGoActiveSyncDispatcher]> Change detected during Sync, we push the content.
Jan 28 11:35:35 sogod [4048]: <0x0xb7c5a134[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: <?xml version="1.0"?>
<!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/&quot;>
<Sync xmlns="AirSync:">
<Collections>
<Collection>
<Class>Calendar</Class>
<SyncKey>1453898707</SyncKey>
<CollectionId>vevent%2Fpersonal</CollectionId>
<Status>1</Status>
<MoreAvailable/>
<Responses>
<Add>
<ClientId>1</ClientId>
<ServerId>4561732055696420546167205B265E25242340215D32366434616665662D373135322D333630632D623632372D636135343433356361646462</ServerId>
<Status>1</Status>
</Add>
</Responses>
<Commands>
<Add>
<ServerId>bf473cfe864f205921030d1bc19e67@ThunderBirthDay.ics</ServerId>
<ApplicationData>
<AllDayEvent xmlns="Calendar:">1</AllDayEvent>
<DTStamp xmlns="Calendar:">20151005T153340Z</DTStamp>
<StartTime xmlns="Calendar:">19680331T230000Z</StartTime>
<EndTime xmlns="Calendar:">19680401T230000Z</EndTime>
<TimeZone xmlns="Calendar:">xP///wAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAoAAAAFAAMAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAMAAAAFAAIAAAAAAAAAxP///w==</TimeZone>
<MeetingStatus xmlns="Calendar:">0</MeetingStatus>
<Subject xmlns="Calendar:">Buck, Andreas</Subject>
<UID xmlns="Calendar:">bf473cfe864f205921030d1bc19e67@ThunderBirthDay</UID>
<Sensitivity xmlns="Calendar:">2</Sensitivity>
<Recurrence xmlns="Calendar:">
<Recurrence_DayOfMonth>1</Recurrence_DayOfMonth>
<Recurrence_MonthOfYear>4</Recurrence_MonthOfYear>
<Recurrence_Type>5</Recurrence_Type>
</Recurrence>
<NativeBodyType xmlns="AirSyncBase:">1</NativeBodyType>
</ApplicationData>
</Add>
<Add>
<ServerId>79575cca5cac9a6824bb30b9137656a3@ThunderBirthDay.ics</ServerId>
<ApplicationData>
<AllDayEvent xmlns="Calendar:">1</AllDayEvent>
<DTStamp xmlns="Calendar:">20151005T153340Z</DTStamp>
<StartTime xmlns="Calendar:">19280331T230000Z</StartTime>
<EndTime xmlns="Calendar:">19280401T230000Z</EndTime>
<TimeZone xmlns="Calendar:">xP///wAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAoAAAAFAAMAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAMAAAAFAAIAAAAAAAAAxP///w==</TimeZone>
<MeetingStatus xmlns="Calendar:">0</MeetingStatus>
<Subject xmlns="Calendar:">Kraft, Walter</Subject>
<UID xmlns="Calendar:">79575cca5cac9a6824bb30b9137656a3@ThunderBirthDay</UID>
<Sensitivity xmlns="Calendar:">2</Sensitivity>
<Recurrence xmlns="Calendar:">
<Recurrence_DayOfMonth>1</Recurrence_DayOfMonth>
<Recurrence_MonthOfYear>4</Recurrence_MonthOfYear>
<Recurrence_Type>5</Recurrence_Type>
</Recurrence>
<NativeBodyType xmlns="AirSyncBase:">1</NativeBodyType>
</ApplicationData>
</Add>
<Add>
<ServerId>80d44f26bc20db9853a39662ce694c0@ThunderBirthDay.ics</ServerId>
<ApplicationData>
<AllDayEvent xmlns="Calendar:">1</AllDayEvent>
<DTStamp xmlns="Calendar:">20151005T153340Z</DTStamp>
<StartTime xmlns="Calendar:">19680409T230000Z</StartTime>
<EndTime xmlns="Calendar:">19680410T230000Z</EndTime>
<TimeZone xmlns="Calendar:">xP///wAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAoAAAAFAAMAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAMAAAAFAAIAAAAAAAAAxP///w==</TimeZone>
<MeetingStatus xmlns="Calendar:">0</MeetingStatus>
<Subject xmlns="Calendar:">di Parma, Lucia</Subject>
<UID xmlns="Calendar:">80d44f26bc20db9853a39662ce694c0@ThunderBirthDay</UID>
<Sensitivity xmlns="Calendar:">2</Sensitivity>
<Recurrence xmlns="Calendar:">
<Recurrence_DayOfMonth>10</Recurrence_DayOfMonth>
<Recurrence_MonthOfYear>4</Recurrence_MonthOfYear>
<Recurrence_Type>5</Recurrence_Type>
</Recurrence>
<NativeBodyType xmlns="AirSyncBase:">1</NativeBodyType>
</ApplicationData>
</Add>
</Commands>
</Collection>
</Collections>
</Sync>

Jan 28 11:35:35 sogod [4048]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=test&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=Sync HTTP/1.1" 200 1596/546 0.075 - - 124K

Email from the mobile to admin-account has not been sent.

Imported events aren't synced.
stefan21

stefan21

2016-01-28 11:21

reporter   ~0009358

Deleting a contact on the mobile does not sync back to the server.

Creating a contact in sogo-gui causes a warning:

Jan 28 12:00:30 sogod [4045]: 192.168.10.181 "GET /SOGo/so/test/Contacts/personal/newcontact HTTP/1.1" 302 0/0 0.030 - - 16K
Jan 28 12:00:30 sogod [4045]: 192.168.10.181 "GET /SOGo/so/test/Contacts/personal//FCD-56A9F500-5-5875D600.vcf/editAsContact HTTP/1.1" 200 5709/0 0.020 21981 74% 28K
Jan 28 12:00:58 sogod [4045]: <0x0xb7d377c4[WOTextField]> -[WOInput takeValuesFromRequest:inContext:]: form value is not settable: <0x0xb7e30eec[WOValueAssociation]: value="">
Jan 28 12:00:58 sogod [4045]: [WARN] UIxDatePicker Could not parse dateString: '0000-00-00'
Jan 28 12:00:58 sogod [4045]: 192.168.10.181 "POST /SOGo/so/test/Contacts/personal/FCD-56A9F500-5-5875D600.vcf/saveAsContact HTTP/1.1" 200 658/758 0.014 - - 4K
Jan 28 12:00:58 sogod [4045]: 192.168.10.181 "POST /SOGo/so/test/Contacts/personal/view?noframe=1&search=name_or_address&value=.&sort=c_cn&asc=true HTTP/1.1" 200 8323/0 0.139 46193 81% 132K
Jan 28 12:00:58 sogod [4046]: 192.168.10.181 "POST /SOGo/so/test/Contacts/personal/FCD-56A9F500-5-5875D600.vcf/view?noframe=1 HTTP/1.1" 200 723/0 0.142 - - 1M

The new contact is synced to the mobile.

It seems that any time a new sync-process from the mobile to sogo has been initiated, sogo tries to sync events - with no luck.

Jan 28 12:13:43 sogod [4049]: <0x0xb7d38404[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: <?xml version="1.0"?>
<!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/&quot;>
<FolderSync xmlns="FolderHierarchy:">
<SyncKey>mond_ivbhome_local_fcd_1c5a2b3f_0</SyncKey>
</FolderSync>

Jan 28 12:13:43 sogod [4049]: <0x0xb7d38404[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: <?xml version="1.0"?>
<!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/&quot;>
<FolderSync xmlns="FolderHierarchy:">
<Status>1</Status>
<SyncKey>mond_ivbhome_local_fcd_1c5a2b3f_0</SyncKey>
<Changes>
<Count>0</Count>
</Changes>
</FolderSync>

Jan 28 12:13:43 sogod [4049]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=test&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=FolderSync HTTP/1.1" 200 57/45 0.366 - - 720K
Jan 28 12:13:43 sogod [4049]: <0x0xb7e11e0c[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: <?xml version="1.0"?>
<!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/&quot;>
<GetItemEstimate xmlns="GetItemEstimate:">
<Collections>
<Collection>
<CollectionId>mail%2F1ef7512fd5206c54bb0b0000b79f221a</CollectionId>
<FilterType xmlns="AirSync:">3</FilterType>
<SyncKey xmlns="AirSync:">4-25</SyncKey>
</Collection>
<Collection>
<CollectionId>vevent%2Fpersonal</CollectionId>
<FilterType xmlns="AirSync:">4</FilterType>
<SyncKey xmlns="AirSync:">1453898707</SyncKey>
</Collection>
<Collection>
<CollectionId>vcard%2Fpersonal</CollectionId>
<FilterType xmlns="AirSync:">0</FilterType>
<SyncKey xmlns="AirSync:">1453899477</SyncKey>
</Collection>
</Collections>
</GetItemEstimate>

Jan 28 12:13:44 sogod [4049]: <0x0xb7da27ac[NGImap4Client]> Note: no key found for sorting, using 'DATE': (null)
Jan 28 12:13:44 sogod [4049]: <0x0xb7e11e0c[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: <?xml version="1.0"?>
<!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/&quot;>
<GetItemEstimate xmlns="GetItemEstimate:">
<Response>
<Status>1</Status>
<Collection>
<Class>Email</Class>
<CollectionId>mail%2F1ef7512fd5206c54bb0b0000b79f221a</CollectionId>
<Estimate>0</Estimate>
</Collection>
</Response>
<Response>
<Status>1</Status>
<Collection>
<Class>Calendar</Class>
<CollectionId>vevent%2Fpersonal</CollectionId>
<Estimate>270</Estimate>
</Collection>
</Response>
<Response>
<Status>1</Status>
<Collection>
<Class>Contacts</Class>
<CollectionId>vcard%2Fpersonal</CollectionId>
<Estimate>263</Estimate>
</Collection>
</Response>
</GetItemEstimate>

Jan 28 12:13:44 sogod [4049]: 37.209.102.40 "POST /SOGo/Microsoft-Server-ActiveSync?User=test&DeviceId=IMEI357909041390858&DeviceType=NokiaEmail&Cmd=GetItemEstimate HTTP/1.1" 200 171/161 0.614 - - 60K
Jan 28 12:13:44 sogod [4049]: <0x0xb7c0a294[SOGoActiveSyncDispatcher]> EAS - request for device IMEI357909041390858: <?xml version="1.0"?>
<!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/&quot;>
<Sync xmlns="AirSync:">
<Collections>
<Collection>
<SyncKey>1453898707</SyncKey>
<CollectionId>vevent%2Fpersonal</CollectionId>
<DeletesAsMoves/>
<GetChanges/>
<WindowSize>5</WindowSize>
<Options>
<FilterType>4</FilterType>
<BodyPreference xmlns="AirSyncBase:">
<Type>1</Type>
<TruncationSize>2048</TruncationSize>
</BodyPreference>
<Conflict>1</Conflict>
</Options>
<Commands>
<Add>
<ClientId>1</ClientId>
<ApplicationData>
<TimeZone xmlns="Calendar:">xP///wAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAoAAAAFAAEAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAMAAAAFAAEAAAAAAAAAxP///w==</TimeZone>
<DTStamp xmlns="Calendar:">20160128T111343Z</DTStamp>
<StartTime xmlns="Calendar:">20160202T070000Z</StartTime>
<Subject xmlns="Calendar:">Test</Subject>
<UID xmlns="Calendar:">4561732055696420546167205B265E25242340215D32366434616665662D373135322D333630632D623632372D636135343433356361646462</UID>
<EndTime xmlns="Calendar:">20160202T080000Z</EndTime>
<Body xmlns="AirSyncBase:">
<Type>1</Type>
<Data/>
</Body>
<Sensitivity xmlns="Calendar:">2</Sensitivity>
<BusyStatus xmlns="Calendar:">2</BusyStatus>
<Reminder xmlns="Calendar:">15</Reminder>
</ApplicationData>
</Add>
</Commands>
</Collection>
</Collections>
</Sync>

Jan 28 12:13:44 sogod [4049]: <0x0xb7c0a294[SOGoActiveSyncDispatcher]> Change detected during Sync, we push the content.
Jan 28 12:13:44 sogod [4049]: <0x0xb7c0a294[SOGoActiveSyncDispatcher]> EAS - response for device IMEI357909041390858: <?xml version="1.0"?>
<!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/&quot;>
<Sync xmlns="AirSync:">
<Collections>
<Collection>
<Class>Calendar</Class>
<SyncKey>1453898707</SyncKey>
<CollectionId>vevent%2Fpersonal</CollectionId>
<Status>1</Status>
<MoreAvailable/>
<Responses>
<Add>
<ClientId>1</ClientId>
<ServerId>4561732055696420546167205B265E25242340215D32366434616665662D373135322D333630632D623632372D636135343433356361646462</ServerId>
<Status>1</Status>
</Add>
</Responses>
<Commands>
<Add>
<ServerId>ab182abc313e48b58e4fa83998d1f95@ThunderBirthDay.ics</ServerId>
<ApplicationData>
<AllDayEvent xmlns="Calendar:">1</AllDayEvent>
<DTStamp xmlns="Calendar:">20151005T153340Z</DTStamp>
<StartTime xmlns="Calendar:">19620301T230000Z</StartTime>
<EndTime xmlns="Calendar:">19620302T230000Z</EndTime>
<TimeZone xmlns="Calendar:">xP///wAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAoAAAAFAAMAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAMAAAAFAAIAAAAAAAAAxP///w==</TimeZone>
<MeetingStatus xmlns="Calendar:">0</MeetingStatus>
<Subject xmlns="Calendar:">Weimer, Jürgen</Subject>
<UID xmlns="Calendar:">ab182abc313e48b58e4fa83998d1f95@ThunderBirthDay</UID>
<Sensitivity xmlns="Calendar:">2</Sensitivity>
<Recurrence xmlns="Calendar:">
<Recurrence_DayOfMonth>2</Recurrence_DayOfMonth>
<Recurrence_MonthOfYear>3</Recurrence_MonthOfYear>
<Recurrence_Type>5</Recurrence_Type>
</Recurrence>
<NativeBodyType xmlns="AirSyncBase:">1</NativeBodyType>
</ApplicationData>
</Add>
<Add>
<ServerId>94ca368efd54dfbe545964502c6d3f8@ThunderBirthDay.ics</ServerId>
<ApplicationData>
<AllDayEvent xmlns="Calendar:">1</AllDayEvent>
<DTStamp xmlns="Calendar:">20151005T153340Z</DTStamp>
<StartTime xmlns="Calendar:">19630313T230000Z</StartTime>
<EndTime xmlns="Calendar:">19630314T230000Z</EndTime>
<TimeZone xmlns="Calendar:">xP///wAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAoAAAAFAAMAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAMAAAAFAAIAAAAAAAAAxP///w==</TimeZone>
<MeetingStatus xmlns="Calendar:">0</MeetingStatus>
<Subject xmlns="Calendar:">Reichenberger, Thomas</Subject>
<UID xmlns="Calendar:">94ca368efd54dfbe545964502c6d3f8@ThunderBirthDay</UID>
<Sensitivity xmlns="Calendar:">2</Sensitivity>
<Recurrence xmlns="Calendar:">
<Recurrence_DayOfMonth>14</Recurrence_DayOfMonth>
<Recurrence_MonthOfYear>3</Recurrence_MonthOfYear>
<Recurrence_Type>5</Recurrence_Type>
</Recurrence>
<NativeBodyType xmlns="AirSyncBase:">1</NativeBodyType>
</ApplicationData>
</Add>
<Add>
<ServerId>a997fa5fbf823dfc5dfb92eb032e92@ThunderBirthDay.ics</ServerId>
<ApplicationData>
<AllDayEvent xmlns="Calendar:">1</AllDayEvent>
<DTStamp xmlns="Calendar:">20151005T153340Z</DTStamp>
<StartTime xmlns="Calendar:">19390325T230000Z</StartTime>
<EndTime xmlns="Calendar:">19390326T230000Z</EndTime>
<TimeZone xmlns="Calendar:">xP///wAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAoAAAAFAAMAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAMAAAAFAAIAAAAAAAAAxP///w==</TimeZone>
<MeetingStatus xmlns="Calendar:">0</MeetingStatus>
<Subject xmlns="Calendar:">Kammerer, Bernd</Subject>
<UID xmlns="Calendar:">a997fa5fbf823dfc5dfb92eb032e92@ThunderBirthDay</UID>
<Sensitivity xmlns="Calendar:">2</Sensitivity>
<Recurrence xmlns="Calendar:">
<Recurrence_DayOfMonth>26</Recurrence_DayOfMonth>
<Recurrence_MonthOfYear>3</Recurrence_MonthOfYear>
<Recurrence_Type>5</Recurrence_Type>
</Recurrence>
<NativeBodyType xmlns="AirSyncBase:">1</NativeBodyType>
</ApplicationData>
</Add>
</Commands>
</Collection>
</Collections>
</Sync>

This is OT but changing configuration (as user also as admin) in the sogo-gui causes an error:

Jan 28 12:09:25 sogod [4046]: 192.168.10.181 "POST /SOGo/so/test/preferences HTTP/1.1" 200 17/2069 0.448 - - 0
Jan 28 12:09:38 sogod [4046]: 192.168.10.181 "GET /SOGo/so/test/preferences HTTP/1.1" 200 18266/0 0.037 79931 77% 0
Jan 28 12:09:46 sogod [4046]: <0x0xb7ecd67c[SOGoSieveManager]> WARNING: Could not delete Sieve script - continuing...: {RawResponse = "{ok = 0; }"; result = 0; }
tfu

tfu

2016-01-28 11:39

reporter   ~0009359

I suggest you start with and new sogo user.
1.) Create a view contacts / Events / eMails and try to sync via EAS.
2.) Change a contact and an event on server do a sync.
3.) Update (if synced) a contact and an event on the mobile and do a sync.
4.) Add a new contact and an event on the mobile and do a sync.
5.) Send a mail and do a sync.

Upload the sogo.log from each test.
stefan21

stefan21

2016-01-28 12:30

reporter   ~0009360

Last edited: 2016-01-29 10:47

I'll do - it'll take some time...

Here are the results:

With a new created user the initial sync from sogo to the mobile works with

  • email,
  • contacts
  • events haven't been synced

From mobile to sogo

  • sending email,
  • new events aren't synced
  • new contacts aren't synced

Creating new contacts or new events in sogo are not synced to the mobile. New emails are synced.

I attach the whole log.
stefan21

stefan21

2016-01-29 10:47

reporter  

20160129-sogo.log (302,639 bytes)
tfu

tfu

2016-01-29 15:19

reporter   ~0009367

I found some kind of loop in the log where the phone sends over and over again the same event. I seems it doesn't like the response sent by sogo:
<?xml version="1.0"?>
<!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/&quot;>
<Sync xmlns="AirSync:">
<Collections>
<Collection>
<Class>Calendar</Class>
<SyncKey>1454060918</SyncKey>
<CollectionId>vevent%2Fpersonal</CollectionId>
<Status>1</Status>
<Responses>
<Add>
<ClientId>1</ClientId>
<ServerId>4561732055696420546167205B265E25242340215D39653466366633372D653339642D333530372D393133392D383332656136623163383566</ServerId>
<Status>1</Status>
</Add>
</Responses>
</Collection>
</Collections>
</Sync>

I could be that the E7 doesn't like the long serverId, but I cannot test because I don't have this phone model.

To rule this out and to test other stuff I suggest you delete any events in sogo which have been created via the E7 and re-test.

I didn't see any sign of a contact send by the mobile in the log, but this could be cause by problems with created events.

Check whether you can sync changes on events which were sync from the server and to add a contact on the client. If the contect gets created on the server try updates on both sides.
stefan21

stefan21

2016-01-29 19:00

reporter   ~0009368

Last edited: 2016-01-29 19:25

Deleted the exchange account on the mobile.

Deleted any events in sogo.
Now in sogo there's one contact, one email in the inbox and two emails in the sent folder.

Setup the mobile again with the credentials needed to sync with an exchange server.

Inital sync from sogo to the mobile went fine.

  • emails are all synced
  • contact is synced

Created a contact on the mobile. Does not sync back while tickled as "private". Has been synced to sogo while changed to "public".

Edited the contact in sogo which came from the mobile. Works flawlessly.
Edited the contact on the mobile which came from sogo. BTW this contact is tickled as "private". Is working anyway.

Created a test event on the mobile. The event is synced to sogo.
Editing the event (changed time) in sogo. Worked in sogo. But the event vanished on the mobile.

Created a second test event on the mobile. While syncing both events appeared on the mobile. In sogo is only the first event visible.
After logging out an logging in again in sogo, both events are in sogo.

Created an event in sogo. This event is not synced to the mobile.

I attach the log.
stefan21

stefan21

2016-01-29 19:01

reporter  

201601291957-sogo.log (135,402 bytes)   
tfu

tfu

2016-01-29 19:50

reporter   ~0009369

The private/public flag for contacts seems to be a feature of the E7 and which allows to sync a contact or not.
Obviously sync problem started again when you created an event on the mobile.
Cleanup all events, recreate the account on your phone and test gain:

Mail (Send, Change read-lag, delete)
Contact (sync, change on server, change on client, add on client, change)
Event (sync, change on server, change on client, DON'T ADD AN EVENT ON CLIENT)

This should help to confirm whether creating events on the mobile is causing all problems.
stefan21

stefan21

2016-01-30 10:57

reporter   ~0009373

Did as requested.

  • email is beeing synced in any direction. Works flawlessly.

  • deleting email is synced back to sogo. deleted email folder is not beeing synced to the mobile.

  • contacts from sogo to the mobile are synced. Some details are missing i.e. private website, and a few others also. Obviously there are different mappings.

  • contacts form the mobile to sogo are synced. The mappings have to be figured out.

  • added an event in sogo. No syncing to the mobile.

  • changed the event (time) in sogo. No syncing to the mobile.

  • changed the event (public to private) in sogo. No syncing to the mobile.

The log is attached.

Well, if it does help, I can send you a phone for test purpose.
stefan21

stefan21

2016-01-30 11:00

reporter  

20160130-sogo.log (219,443 bytes)   
stefan21

stefan21

2016-01-30 11:13

reporter   ~0009374

Last edited: 2016-01-30 11:22

There's a new situation to report:

The mobile displays in default mode two calendars. Private/personal and the mail for exchange calendar.
While tickling the private/personal calendar off, the event, which was created in sogo, appeared. I don't had to alter or change this,
while syncing via z-push to zarafa. All events just appeared on the mobile.

  • edited the event in sogo. Changed back to public. Worked.
  • edited the event in sogo. Changed time. Worked.

No event added on the client yet.
stefan21

stefan21

2016-01-30 15:58

reporter   ~0009375

Playing around with creating events on the mobile:

  1. created an event on the mobile, sent the event via email as an attachment (.vcs) to the admin. Saved and renamed to *.ics. Imported the event in sogo. This is the raw-text:

BEGIN:VCALENDAR
VERSION:1.0
BEGIN:VEVENT
UID:edf5d755-3ac6-3a30-90f6-5bba563980d6
SUMMARY:From mobile
DTSTART:20160208T070000Z
DTEND:20160208T080000Z
X-EPOCAGENDAENTRYTYPE:APPOINTMENT
CLASS:PUBLIC
LOCATION:test location
X-SYMBIAN-DTSTAMP:20160130T150647Z
SEQUENCE:1
X-METHOD:NONE
AALARM;TYPE=X-EPOCSOUND:20160208T064500Z;;;
LAST-MODIFIED:20160130T150647Z
PRIORITY:5
X-SYMBIAN-LUID:9
TRANSP:0
X-USERDATA-INT:0
END:VEVENT
END:VCALENDAR

Have a look at the UID.

Now, created a second event on the mobile, synced OTA to sogo. Here's the raw-text, when opening in sogo:

BEGIN:VCALENDAR
PRODID:-//Inverse inc./SOGo 2.3.5//EN
VERSION:2.0
BEGIN:VTIMEZONE
TZID:Europe/Berlin
X-LIC-LOCATION:Europe/Berlin
BEGIN:DAYLIGHT
TZOFFSETFROM:+0100
TZOFFSETTO:+0200
TZNAME:CEST
DTSTART:19700329T020000
RRULE:FREQ=YEARLY;BYMONTH=3;BYDAY=-1SU
END:DAYLIGHT
BEGIN:STANDARD
TZOFFSETFROM:+0200
TZOFFSETTO:+0100
TZNAME:CET
DTSTART:19701025T030000
RRULE:FREQ=YEARLY;BYMONTH=10;BYDAY=-1SU
END:STANDARD
END:VTIMEZONE
BEGIN:VEVENT
UID:4561732055696420546167205B265E25242340215D31363438613839322D363730332D3
36437362D623161352D613132336462373437653764
SUMMARY:Event now ota
DTSTART;TZID=Europe/Berlin:20160131T080000
CLASS:PUBLIC
DTEND;TZID=Europe/Berlin:20160131T090000
END:VEVENT
END:VCALENDAR

Have a look at the UID.

Created an event in sogo:

BEGIN:VCALENDAR
PRODID:-//Inverse inc./SOGo 2.3.5//EN
VERSION:2.0
BEGIN:VTIMEZONE
TZID:Europe/Berlin
X-LIC-LOCATION:Europe/Berlin
BEGIN:DAYLIGHT
TZOFFSETFROM:+0100
TZOFFSETTO:+0200
TZNAME:CEST
DTSTART:19700329T020000
RRULE:FREQ=YEARLY;BYMONTH=3;BYDAY=-1SU
END:DAYLIGHT
BEGIN:STANDARD
TZOFFSETFROM:+0200
TZOFFSETTO:+0100
TZNAME:CET
DTSTART:19701025T030000
RRULE:FREQ=YEARLY;BYMONTH=10;BYDAY=-1SU
END:STANDARD
END:VTIMEZONE
BEGIN:VEVENT
UID:B57-56ACDB00-5-4E6C6700
SUMMARY:from sogo
CLASS:PUBLIC
CREATED:20160130T154629Z
DTSTAMP:20160130T154629Z
LAST-MODIFIED:20160130T154629Z
BEGIN:VALARM
TRIGGER;VALUE=DURATION:-PT15M
ACTION:DISPLAY
END:VALARM
DTSTART;TZID=Europe/Berlin:20160130T190000
DTEND;TZID=Europe/Berlin:20160130T200000
TRANSP:OPAQUE
END:VEVENT
END:VCALENDAR

Have a look at the UID. Event is not synced back to the mobile.

It seems, syncing events is the reason for the problems.

I attach the last log.
stefan21

stefan21

2016-01-30 15:59

reporter  

201601301655-sogo.log (100,464 bytes)   
stefan21

stefan21

2016-01-30 20:02

reporter   ~0009376

Here's another attachment. This calendar.ics is exported from zarafa.
stefan21

stefan21

2016-01-30 20:02

reporter  

Calendar.ics (2,341 bytes)   
tfu

tfu

2016-02-01 20:20

reporter   ~0009393

Are you in the position to test a patch? Your e-mail address?
stefan21

stefan21

2016-02-01 21:40

reporter   ~0009397

Last edited: 2016-02-02 11:40

Well, I build a VM based on the smeserver 9.1 and 2.3.5-1.centos6.i686. I have a mobile to play around. (We bought identical mobiles for the company.) At least I have time.

With your help I can test anything you want. Just tell me what to do.
stefan21

stefan21

2016-02-02 08:14

reporter   ~0009399

tfu,

are you able to read notes marked as private?
tfu

tfu

2016-02-02 10:13

reporter   ~0009402

no - tfu@aon.at
ludovic

ludovic

2016-03-23 15:25

reporter   ~0009823

Is this bug still relevant?
tfu

tfu

2016-03-23 17:24

reporter   ~0009825

Yes, I sent you a patch some time ago, together with the foldermerge page.
stefan21

stefan21

2016-03-24 06:55

reporter   ~0009832

Yes, I'd still like to see it fixed.
stefan21

stefan21

2016-04-02 08:29

reporter   ~0009917

@ludovic

I don't want to bother - any chance to get the fix implemented? Would be nice to hear something from you. Even more nicer - I could start to migrate from zarafa to sogo...
stefan21

stefan21

2016-04-20 03:50

reporter   ~0009991

as time goes by... Any chance to get this bug fixed?
ludovic

ludovic

2016-04-20 12:29

reporter   ~0009993

Patches are being analyzed / integrated. They will be part of the next release.
stefan21

stefan21

2016-05-09 06:36

reporter   ~0010076

Already integrated?
ludovic

ludovic

2016-05-10 13:22

reporter   ~0010095

We will push the patch after we release 2.3.11 and 3.1.

Related Changesets

sogo: master 440acec1

2016-07-21 14:06

ludovic


Details Diff		 Added EAS folder merge support and fixed long GUID issue (fixes 0003460) Affected Issues
0003460
mod - ActiveSync/SOGoActiveSyncDispatcher+Sync.m Diff File
mod - ActiveSync/SOGoActiveSyncDispatcher.m Diff File
mod - Tools/SOGoToolManageEAS.m Diff File

sogo: v2 16356aaf

2016-07-21 14:06

ludovic


Details Diff		 Added EAS folder merge support and fixed long GUID issue (fixes 0003460)

Conflicts:

ActiveSync/SOGoActiveSyncDispatcher+Sync.m		 Affected Issues
0003460
mod - ActiveSync/SOGoActiveSyncDispatcher+Sync.m Diff File
mod - ActiveSync/SOGoActiveSyncDispatcher.m Diff File
mod - Tools/SOGoToolManageEAS.m Diff File

Issue History

Date Modified Username Field Change
2016-01-27 08:18 stefan21 New Issue
2016-01-27 09:55 tfu Note Added: 0009338
2016-01-27 10:32 stefan21 File Added: sogo.log
2016-01-27 10:33 stefan21 File Added: access.log
2016-01-27 11:37 tfu Note Added: 0009339
2016-01-27 13:58 stefan21 Note Added: 0009340
2016-01-27 14:24 tfu Note Added: 0009341
2016-01-27 16:34 stefan21 Note Added: 0009342
2016-01-27 17:07 tfu Note Added: 0009343
2016-01-27 17:31 stefan21 Note Added: 0009344
2016-01-27 17:56 tfu Note Added: 0009345
2016-01-28 09:22 stefan21 Note Added: 0009353
2016-01-28 09:51 stefan21 Note Added: 0009354
2016-01-28 09:53 stefan21 Note Added: 0009355
2016-01-28 10:24 tfu Note Added: 0009356
2016-01-28 10:38 stefan21 Note Added: 0009357
2016-01-28 10:40 stefan21 Note Edited: 0009357
2016-01-28 11:21 stefan21 Note Added: 0009358
2016-01-28 11:39 tfu Note Added: 0009359
2016-01-28 12:30 stefan21 Note Added: 0009360
2016-01-29 10:47 stefan21 Note Edited: 0009360
2016-01-29 10:47 stefan21 File Added: 20160129-sogo.log
2016-01-29 15:19 tfu Note Added: 0009367
2016-01-29 19:00 stefan21 Note Added: 0009368
2016-01-29 19:01 stefan21 File Added: 201601291957-sogo.log
2016-01-29 19:25 stefan21 Note Edited: 0009368
2016-01-29 19:25 stefan21 Note Edited: 0009368
2016-01-29 19:25 stefan21 Note Edited: 0009368
2016-01-29 19:50 tfu Note Added: 0009369
2016-01-30 10:57 stefan21 Note Added: 0009373
2016-01-30 11:00 stefan21 File Added: 20160130-sogo.log
2016-01-30 11:13 stefan21 Note Added: 0009374
2016-01-30 11:21 stefan21 Note Edited: 0009374
2016-01-30 11:22 stefan21 Note Edited: 0009374
2016-01-30 15:58 stefan21 Note Added: 0009375
2016-01-30 15:59 stefan21 File Added: 201601301655-sogo.log
2016-01-30 20:02 stefan21 Note Added: 0009376
2016-01-30 20:02 stefan21 File Added: Calendar.ics
2016-02-01 20:20 tfu Note Added: 0009393
2016-02-01 21:40 stefan21 Note Added: 0009397
2016-02-01 21:40 stefan21 Note View State: 0009397: private
2016-02-02 08:14 stefan21 Note Added: 0009399
2016-02-02 10:13 tfu Note Added: 0009402
2016-02-02 11:40 stefan21 Note Edited: 0009397
2016-02-02 11:40 stefan21 Note View State: 0009397: public
2016-03-23 15:25 ludovic Note Added: 0009823
2016-03-23 17:24 tfu Note Added: 0009825
2016-03-24 06:55 stefan21 Note Added: 0009832
2016-04-02 08:29 stefan21 Note Added: 0009917
2016-04-20 03:50 stefan21 Note Added: 0009991
2016-04-20 12:29 ludovic Note Added: 0009993
2016-05-09 06:36 stefan21 Note Added: 0010076
2016-05-10 13:22 ludovic Note Added: 0010095
2016-07-21 18:10 ludovic Changeset attached => sogo master 440acec1
2016-07-21 18:10 ludovic Assigned To => ludovic
2016-07-21 18:10 ludovic Resolution open => fixed
2016-07-21 18:11 ludovic Changeset attached => sogo v2 16356aaf
2016-09-23 16:49 ludovic Status new => resolved