From: <tr...@eg...> - 2009-07-30 07:13:01
|
Ticket modified by Jörg Lehrke at 2009/07/30 09:12 Tracking System: Bugs Category: SyncML Version: Trunk Status: Open Resolution: None Completed: 0% Priority: 5 - medium Created By: camel camel Assigned to: Jörg Lehrke Summary: #2171 - SYNCING from EGW to Outlook (recover sync) -> Field problems ok, here i add the whole logs from funambol, EGW server logs, and pictures of the items from calendar and contact in contact there is 1 item in calendar there are 2 items i made pictures of the items in EGW, and - after sync - also in Outlook see attached: what i see: .) a lot of fileds are complete different and some fields will be deleted or are empty .) special chars (like quote, carr. return in note of a appointment are strange after syncing .) birthday apointments are not any more "full day appointment" (the test are done with apache2, postgresql8, EGW trunk +branch syncml-1.2 version 27451,funambol 8.03, outlook 2007) ---------------------- i will add logs and pictures later Comment by Jörg Lehrke at 2009/07/30 09:12: If you look at the vCalendar data sent from eGW, you will see it sent the dates in UTC (...Z). For eGW the usertime seems UTC+1h. If Funambol uses a timezone UTC+2h you get exactly the result you mention. With alarms in the past you may have an issue for recurring events. If you create such an event with startdate in the past you will not be able to set an alarm via GUI. The birthday handling in eGW is not based on events. eGW can display birthdays from contacts at the appropriate date. That way even you avoid to mess with timezone calculations. A reminder for these birthdays would be a feature request. Comment by camel camel at 2009/07/29 23:59: also funny: from EGW to OL (via funambol): the time is set to: 1920-05-04 23:00 - 1920-05-05 22:59 from EGW to THUNDERBIRD/LIGHTNING (via funambol): the time is set to: 1920-05-05 01:00 - 1920-05-06 01:00 the log in the comment before is from EGW to thunderbird/lightning Comment by camel camel at 2009/07/29 23:56: well, the problem is, that it is not syncing a fullday event from EGW to OL or thunderbird/lighning. see log: FUNAMBOL LOG: ============= 2009-07-29 23:49:29 GMT +1:00 - # Funambol Mozilla Sync Client Log 23:49:29 GMT +1:00 [DEBUG] - Creating SyncSources... 23:49:29 GMT +1:00 [DEBUG] - [contact] ContactSyncSource(): Contact folder: "abook.mab" 23:49:30 GMT +1:00 [DEBUG] - [contact] ContactSyncSource(): Contact folder found: "Persönliches Adressbuch" 23:49:30 GMT +1:00 [DEBUG] - [event] CalendarSyncSource(): Calendar folder: "moz-profile-calendar://" 23:49:30 GMT +1:00 [DEBUG] - [event] CalendarSyncSource(): Calendar found: 'Privat' 23:49:30 GMT +1:00 [DEBUG] - [task] CalendarSyncSource(): Calendar folder: "moz-profile-calendar://" 23:49:30 GMT +1:00 [DEBUG] - [task] CalendarSyncSource(): Calendar found: 'Privat' 23:49:30 GMT +1:00 [INFO] - Checking for contact sync... 23:49:30 GMT +1:00 [INFO] - The source contact isn't set to be synced 23:49:30 GMT +1:00 [INFO] - Checking for event sync... 23:49:30 GMT +1:00 [INFO] - Required sync mode: 'refresh from server' 23:49:30 GMT +1:00 [INFO] - A refresh sync from server has been requested, all the local items will be deleted !! 23:49:30 GMT +1:00 [INFO] - Checking for task sync... 23:49:30 GMT +1:00 [INFO] - The source task isn't set to be synced 23:49:30 GMT +1:00 [DEBUG] - Set the synchronization listeners 23:49:30 GMT +1:00 [INFO] - --------------------> Begin SyncML Synchronization <-------------------- 23:49:30 GMT +1:00 [DEBUG] - Synchronization URL: https://office.domain.com/rpc.php 23:49:30 GMT +1:00 [INFO] - Preparing synchronization of event... 23:49:30 GMT +1:00 [DEBUG] - Checking devinfo... 23:49:30 GMT +1:00 [DEBUG] - devinfo hash: 2UZ4puie+CJmYx23FKS/lA== 23:49:30 GMT +1:00 [DEBUG] - devinfo unchanged, no need to send 23:49:30 GMT +1:00 [DEBUG] - Initialization message: 23:49:30 GMT +1:00 [DEBUG] - User Agent = Funambol Mozilla Sync Client v0.9.1 23:49:30 GMT +1:00 [DEBUG] - <?xml version="1.0" encoding="UTF-8"?> 1.2 SyncML/1.2 1248904170 1 https://office.domain.com/rpc.php fmz-ciKOJ2vQAu0OjHxYFN8Cgg== b64 syncml:auth-basic ZXRzX21vYmlsOm9jYjExMQ== 250000 1 205 ./calendar event 1248904064 1248904170 23:49:30 GMT +1:00 [DEBUG] - Connecting to office.domain.com:443 23:49:30 GMT +1:00 [DEBUG] - Requesting resource /rpc.php 23:49:30 GMT +1:00 [DEBUG] - Message sent 23:49:30 GMT +1:00 [DEBUG] - Data sent succesfully to server. Server responds OK 23:49:30 GMT +1:00 [DEBUG] - Header: 23:49:30 GMT +1:00 [DEBUG] - Reading response... 23:49:30 GMT +1:00 [DEBUG] - Content-length: 1196 23:49:30 GMT +1:00 [DEBUG] - Response read: 1.2SyncML/1.212489041701fmz-ciKOJ2vQAu0OjHxYFN8Cgg==https://office.domain.com/rpc.phphttps://office.domain.com/rpc.php?syncml_sessionid=syncml-87e71d99e0238e6ae1156caa5b9961b2250000110SyncHdrhttps://office.domain.com/rpc.phpfmz-ciKOJ2vQAu0OjHxYFN8Cgg==212211Alert./calendarevent200124890417012489040643205event./calendar1248904172 23:49:30 GMT +1:00 [DEBUG] - [event] CalendarGetterListener::OnGetResult: Getting 1 calendar items. Status code: 0 23:49:30 GMT +1:00 [DEBUG] - [event] CalendarGetterListener::OnGetResult: Get item id: calendar-14667-0c2b53d7ff5e9330cdbab910d0f6316c 23:49:30 GMT +1:00 [DEBUG] - CalendarUtils::getVCalendarFromIcalComponent(): Start 23:49:30 GMT +1:00 [DEBUG] - CalendarUtils::getVCalendarFromIcalComponent(): get timezone info 23:49:30 GMT +1:00 [DEBUG] - CalendarUtils::getVCalendarFromIcalComponent(): parsing item 23:49:30 GMT +1:00 [DEBUG] - CalendarUtils::getVCalendarFromIcalComponent(): verify parsing 23:49:30 GMT +1:00 [DEBUG] - CalendarUtils::getVCalendarFromIcalComponent(): vCalendar parsed 23:49:30 GMT +1:00 [DEBUG] - CalendarUtils::getVCalendarFromIcalComponent(): End 23:49:30 GMT +1:00 [DEBUG] - [event] CalendarGetterListener::OnGetResult: Setting up a new SyncItem 23:49:30 GMT +1:00 [DEBUG] - [event] CalendarGetterListener::OnGetResult: End 23:49:30 GMT +1:00 [DEBUG] - [event] CalendarGetterListener::OnOperationComplete: Returned id '(null)'. Status code: 0 23:49:30 GMT +1:00 [DEBUG] - [event] removeItem(): Start 23:49:30 GMT +1:00 [DEBUG] - [event] CalendarGetterListener::OnGetResult: Getting 1 calendar items. Status code: 0 23:49:30 GMT +1:00 [DEBUG] - [event] CalendarGetterListener::OnGetResult: Get a sigle item 23:49:30 GMT +1:00 [DEBUG] - [event] CalendarGetterListener::OnGetResult: End 23:49:30 GMT +1:00 [DEBUG] - [event] CalendarGetterListener::OnOperationComplete: Returned id 'calendar-14667-0c2b53d7ff5e9330cdbab910d0f6316c'. Status code: 0 23:49:30 GMT +1:00 [DEBUG] - [] CalendarGetterListener::OnOperationComplete: Returned id 'calendar-14667-0c2b53d7ff5e9330cdbab910d0f6316c'. Status code: 0 23:49:30 GMT +1:00 [DEBUG] - [event] removeItem(): Item deleted key: 'calendar-14667-0c2b53d7ff5e9330cdbab910d0f6316c' 23:49:30 GMT +1:00 [DEBUG] - [event] removeItem(): End 23:49:30 GMT +1:00 [INFO] - [event] Successful Delete of item with key calendar-14667-0c2b53d7ff5e9330cdbab910d0f6316c - code 200 23:49:30 GMT +1:00 [DEBUG] - Removed all items for source event 23:49:30 GMT +1:00 [DEBUG] - Modification message: estimated size 0, allowed size 250000, real size 940 / estimated size 0 = 100% 23:49:30 GMT +1:00 [DEBUG] - <?xml version="1.0" encoding="UTF-8"?> 1.2 SyncML/1.2 1248904170 2 https://office.domain.com/rpc.php fmz-ciKOJ2vQAu0OjHxYFN8Cgg== 1 1 0 SyncHdr https://office.domain.com/rpc.php fmz-ciKOJ2vQAu0OjHxYFN8Cgg== 200 2 1 3 Alert ./calendar event 200 1248904172 3 ./calendar event 23:49:30 GMT +1:00 [DEBUG] - Connecting to office.domain.com:443 23:49:30 GMT +1:00 [DEBUG] - Requesting resource /rpc.php?syncml_sessionid=syncml-87e71d99e0238e6ae1156caa5b9961b2 23:49:31 GMT +1:00 [DEBUG] - Message sent 23:49:31 GMT +1:00 [DEBUG] - Data sent succesfully to server. Server responds OK 23:49:31 GMT +1:00 [DEBUG] - Header: HTTP/1.1 200 OK Date: Wed, 29 Jul 2009 21:49:33 GMT Server: Apache X-Powered-By: PHP/5.2.10-pl1-gentoo Cache-Control: no-store, no-cache, must-revalidate, post-check=0, pre-check=0 Pragma: no-cache Expires: Thu, 19 Nov 1981 08:52:00 GMT Content-length: 1697 Accept-Charset: UTF-8 Keep-Alive: timeout=15, max=100 Connection: Keep-Alive Content-Type: application/vnd.syncml+xml 23:49:31 GMT +1:00 [DEBUG] - Reading response... 23:49:31 GMT +1:00 [DEBUG] - Content-length: 1697 23:49:31 GMT +1:00 [DEBUG] - Response read: 1.2SyncML/1.212489041702fmz-ciKOJ2vQAu0OjHxYFN8Cgg==https://office.domain.com/rpc.phphttps://office.domain.com/rpc.php?syncml_sessionid=syncml-87e71d99e0238e6ae1156caa5b9961b2250000120SyncHdrhttps://office.domain.com/rpc.phpfmz-ciKOJ2vQAu0OjHxYFN8Cgg==200223Sync./calendarevent2003event./calendar04text/calendar496calendar-14667BEGIN:VCALENDAR VERSION:2.0 PRODID:-//eGroupWare//NONSGML eGroupWare Calendar 1.7.001//DE METHOD:PUBLISH BEGIN:VEVENT CLASS:PUBLIC SUMMARY:Manual Created - Birthday TEST DTSTART:19200504T230000Z DTEND:19200505T225959Z ORGANIZER;CN="Erwin-Mobil domain":MAILTO:use...@do... RRULE:FREQ=YEARLY PRIORITY:5 TRANSP:OPAQUE UID:calendar-14667-0c2b53d7ff5e9330cdbab910d0f6316c CREATED:20090729T213723Z LAST-MODIFIED:20090729T213712Z DTSTAMP:20090729T214933Z END:VEVENT END:VCALENDAR 23:49:31 GMT +1:00 [DEBUG] - [Calendar] getCalIItemBaseFromVCalendar(): Start 23:49:31 GMT +1:00 [DEBUG] - [Calendar] getCalIItemBaseFromVCalendar(): vCalendar parsed 23:49:31 GMT +1:00 [DEBUG] - [Calendar] getCalIItemBaseFromVCalendar(): Start setUnchanged() 23:49:31 GMT +1:00 [DEBUG] - [Calendar] getCalIItemBaseFromVCalendar(): End setUnchanged() 23:49:31 GMT +1:00 [DEBUG] - [Calendar] getCalIItemBaseFromVCalendar(): End 23:49:31 GMT +1:00 [DEBUG] - [] CalendarGetterListener::OnOperationComplete: Returned id 'calendar-14667-0c2b53d7ff5e9330cdbab910d0f6316c'. Status code: 0 23:49:31 GMT +1:00 [DEBUG] - [event] insertItem(): Item added key: 'calendar-14667-0c2b53d7ff5e9330cdbab910d0f6316c' 23:49:31 GMT +1:00 [DEBUG] - [event] insertItem(): End 23:49:31 GMT +1:00 [INFO] - [event] Successful Add of item with key calendar-14667-0c2b53d7ff5e9330cdbab910d0f6316c - code 200 23:49:31 GMT +1:00 [DEBUG] - Mapping 23:49:31 GMT +1:00 [DEBUG] - <?xml version="1.0" encoding="UTF-8"?> 1.2 SyncML/1.2 1248904170 3 https://office.domain.com/rpc.php fmz-ciKOJ2vQAu0OjHxYFN8Cgg== 3 2 0 SyncHdr https://office.domain.com/rpc.php fmz-ciKOJ2vQAu0OjHxYFN8Cgg== 200 1 2 3 Sync ./calendar event 200 2 2 4 Add 200 calendar-14667 4 ./calendar event calendar-14667 calendar-14667-0c2b53d7ff5e9330cdbab910d0f6316c 23:49:31 GMT +1:00 [DEBUG] - Connecting to office.domain.com:443 23:49:31 GMT +1:00 [DEBUG] - Requesting resource /rpc.php?syncml_sessionid=syncml-87e71d99e0238e6ae1156caa5b9961b2 23:49:32 GMT +1:00 [DEBUG] - Message sent 23:49:32 GMT +1:00 [DEBUG] - Data sent succesfully to server. Server responds OK 23:49:32 GMT +1:00 [DEBUG] - Header: HTTP/1.1 200 OK Date: Wed, 29 Jul 2009 21:49:34 GMT Server: Apache X-Powered-By: PHP/5.2.10-pl1-gentoo Cache-Control: no-store, no-cache, must-revalidate, post-check=0, pre-check=0 Pragma: no-cache Expires: Thu, 19 Nov 1981 08:52:00 GMT Content-length: 846 Accept-Charset: UTF-8 Keep-Alive: timeout=15, max=100 Connection: Keep-Alive Content-Type: application/vnd.syncml+xml 23:49:32 GMT +1:00 [DEBUG] - Reading response... 23:49:32 GMT +1:00 [DEBUG] - Content-length: 846 23:49:32 GMT +1:00 [DEBUG] - Response read: 1.2SyncML/1.212489041703fmz-ciKOJ2vQAu0OjHxYFN8Cgg==https://office.domain.com/rpc.phphttps://office.domain.com/rpc.php?syncml_sessionid=syncml-87e71d99e0238e6ae1156caa5b9961b2250000130SyncHdrhttps://office.domain.com/rpc.phpfmz-ciKOJ2vQAu0OjHxYFN8Cgg==200234Map./calendarevent200 23:49:32 GMT +1:00 [DEBUG] - [event] Saving cache 23:49:32 GMT +1:00 [DEBUG] - Committing source event (next: 1248904170) 23:49:32 GMT +1:00 [DEBUG] - ret: 0, lastErrorCode: 0, lastErrorMessage: 23:49:32 GMT +1:00 [INFO] - Sync successfully completed. 23:49:32 GMT +1:00 [DEBUG] - =========================================================== ================ SYNCHRONIZATION REPORT =============== =========================================================== Last error code = 0 Last error msg = ----------|--------CLIENT---------|--------SERVER---------| Source | NEW | MOD | DEL | NEW | MOD | DEL | ----------|-----------------------------------------------| contact| 0/ 0| 0/ 0| 0/ 0| 0/ 0| 0/ 0| 0/ 0| ----------|-----------------------------------------------| event| 1/ 1| 0/ 0| 0/ 0| 0/ 0| 0/ 0| 0/ 0| ----------|-----------------------------------------------| task| 0/ 0| 0/ 0| 0/ 0| 0/ 0| 0/ 0| 0/ 0| ----------|-----------------------------------------------| contact: ---------- Source State = 0 Last error code = 0 Last error msg = (null) event: ---------- Source State = 0 Last error code = 0 Last error msg = (null) Client Add: id 'calendar-14667-0c2b53d7ff5e9330cdbab910d0f6316c' status 200 task: ---------- Source State = 0 Last error code = 0 Last error msg = (null) 23:49:32 GMT +1:00 [INFO] - --------------------> End SyncML Synchronization <-------------------- 23:49:32 GMT +1:00 [DEBUG] - Saving configuration settings... 23:49:32 GMT +1:00 [DEBUG] - Saving configuration... 23:49:32 GMT +1:00 [DEBUG] - Writing configuration settings to the management tree 23:49:32 GMT +1:00 [DEBUG] - Configuration saved. 23:49:33 GMT +1:00 [DEBUG] - Unset listeners EGW-APACHE2 log: ================ ==> /var/log/NOT_SORTED_DEVLOG/notice.log <== Jul 29 23:49:32 s_d...@we... apache2: EGWSYNC: [] SyncML[syncml-87e71d99e0238e6ae1156caa5b9961b2]: starting new session for user_mobil [on line 174 of "/var/www/egroupware_TRUNK/htdocs/egroupware/phpgwapi/inc/horde/Horde/SyncML.php"] Jul 29 23:49:32 s_d...@we... apache2: EGWSYNC: [] SyncML[syncml-87e71d99e0238e6ae1156caa5b9961b2]: create new session state variable for fmz-ciKOJ2vQAu0OjHxYFN8Cgg== [on line 182 of "/var/www/egroupware_TRUNK/htdocs/egroupware/phpgwapi/inc/horde/Horde/SyncML.php"] Jul 29 23:49:32 s_d...@we... apache2: EGWSYNC: [] SyncML: Authentication not yet possible currently. Password not available [on line 296 of "/var/www/egroupware_TRUNK/htdocs/egroupware/phpgwapi/inc/horde/Horde/SyncML/State_egw.php"] Jul 29 23:49:32 s_d...@we... apache2: EGWSYNC: [] SyncML[syncml-87e71d99e0238e6ae1156caa5b9961b2]: package 1 +++++++++++++++++++++ started [on line 232 of "/var/www/egroupware_TRUNK/htdocs/egroupware/phpgwapi/inc/horde/Horde/SyncML.php"] Jul 29 23:49:32 s_d...@we... apache2: EGWSYNC: [] SyncML: Authentication not yet possible currently. Password not available [on line 296 of "/var/www/egroupware_TRUNK/htdocs/egroupware/phpgwapi/inc/horde/Horde/SyncML/State_egw.php"] Jul 29 23:49:32 s_d...@we... apache2: EGWSYNC: [] SyncML_EGW: Authentication of use...@of.../syncml-87e71d99e0238e6ae1156caa5b9961b2 succeded [on line 310 of "/var/www/egroupware_TRUNK/htdocs/egroupware/phpgwapi/inc/horde/Horde/SyncML/State_egw.php"] Jul 29 23:49:32 s_d...@we... apache2: EGWSYNC: [] SyncML[syncml-87e71d99e0238e6ae1156caa5b9961b2]: found action commands [on line 550 of "/var/www/egroupware_TRUNK/htdocs/egroupware/phpgwapi/inc/horde/Horde/SyncML.php"] Jul 29 23:49:32 s_d...@we... apache2: EGWSYNC: [] SyncML: Alert 205 [on line 175 of "/var/www/egroupware_TRUNK/htdocs/egroupware/phpgwapi/inc/horde/Horde/SyncML/Command/Alert.php"] Jul 29 23:49:32 s_d...@we... apache2: EGWSYNC: [] SyncML: state->removeAllUID(type=./calendar) [on line 337 of "/var/www/egroupware_TRUNK/htdocs/egroupware/phpgwapi/inc/horde/Horde/SyncML/State_egw.php"] Jul 29 23:49:32 s_d...@we... apache2: EGWSYNC: [] SyncML: Creating SyncML_Sync object for target ./calendar; sync type 205 [on line 306 of "/var/www/egroupware_TRUNK/htdocs/egroupware/phpgwapi/inc/horde/Horde/SyncML/Command/Alert.php"] Jul 29 23:49:32 s_d...@we... apache2: EGWSYNC: [] SyncML: new sync for alerttype 205 [on line 61 of "/var/www/egroupware_TRUNK/htdocs/egroupware/phpgwapi/inc/horde/Horde/SyncML/Sync.php"] Jul 29 23:49:32 s_d...@we... apache2: EGWSYNC: [] SyncML[syncml-87e71d99e0238e6ae1156caa5b9961b2]: syncStatus(server sync acknowledged) 0 [on line 690 of "/var/www/egroupware_TRUNK/htdocs/egroupware/phpgwapi/inc/horde/Horde/SyncML.php"] Jul 29 23:49:32 s_d...@we... apache2: EGWSYNC: [] SyncML[syncml-87e71d99e0238e6ae1156caa5b9961b2]: package ----------------------- done [on line 577 of "/var/www/egroupware_TRUNK/htdocs/egroupware/phpgwapi/inc/horde/Horde/SyncML.php"] Jul 29 23:49:32 s_d...@we... apache2: EGWSYNC: [] SyncML[syncml-87e71d99e0238e6ae1156caa5b9961b2]: syncStatus = 0, actionCommands = False [on line 606 of "/var/www/egroupware_TRUNK/htdocs/egroupware/phpgwapi/inc/horde/Horde/SyncML.php"] ==> /var/log/apache2/domain_SSL_access.log <== xx.xx.xx.xx - - [29/Jul/2009:23:49:32 +0200] "POST /rpc.php HTTP/1.1" 200 1196 "-" "Funambol Mozilla Sync Client v0.9.1" ==> /var/log/apache2/default_SSL_rewrite.log <== xx.xx.xx.xx - - [29/Jul/2009:23:49:32 +0200] [office.domain.com/sid#19aa2b0][rid#4e67628/initial] (1) pass through /rpc.php ==> /var/log/NOT_SORTED_DEVLOG/notice.log <== Jul 29 23:49:33 s_d...@we... apache2: EGWSYNC: [] SyncML[syncml-87e71d99e0238e6ae1156caa5b9961b2]: reusing existing session [on line 169 of "/var/www/egroupware_TRUNK/htdocs/egroupware/phpgwapi/inc/horde/Horde/SyncML.php"] Jul 29 23:49:33 s_d...@we... apache2: EGWSYNC: [] SyncML[syncml-87e71d99e0238e6ae1156caa5b9961b2]: session is authorized [on line 190 of "/var/www/egroupware_TRUNK/htdocs/egroupware/phpgwapi/inc/horde/Horde/SyncML.php"] Jul 29 23:49:33 s_d...@we... apache2: EGWSYNC: [] SyncML[syncml-87e71d99e0238e6ae1156caa5b9961b2]: package 2 +++++++++++++++++++++ started [on line 232 of "/var/www/egroupware_TRUNK/htdocs/egroupware/phpgwapi/inc/horde/Horde/SyncML.php"] Jul 29 23:49:33 s_d...@we... apache2: EGWSYNC: [] SyncML[syncml-87e71d99e0238e6ae1156caa5b9961b2]: found action commands [on line 550 of "/var/www/egroupware_TRUNK/htdocs/egroupware/phpgwapi/inc/horde/Horde/SyncML.php"] Jul 29 23:49:33 s_d...@we... apache2: EGWSYNC: [] SyncML[syncml-87e71d99e0238e6ae1156caa5b9961b2]: syncStatus(client sync started) 1 [on line 557 of "/var/www/egroupware_TRUNK/htdocs/egroupware/phpgwapi/inc/horde/Horde/SyncML.php"] Jul 29 23:49:33 s_d...@we... apache2: EGWSYNC: [] SyncML: $this->_targetURI = ./calendar [on line 75 of "/var/www/egroupware_TRUNK/htdocs/egroupware/phpgwapi/inc/horde/Horde/SyncML/Command/Sync.php"] Jul 29 23:49:33 s_d...@we... apache2: EGWSYNC: [] SyncML[syncml-87e71d99e0238e6ae1156caa5b9961b2]: syncStatus(server sync acknowledged) 3 [on line 690 of "/var/www/egroupware_TRUNK/htdocs/egroupware/phpgwapi/inc/horde/Horde/SyncML.php"] Jul 29 23:49:33 s_d...@we... apache2: EGWSYNC: [] SyncML[syncml-87e71d99e0238e6ae1156caa5b9961b2]: package ----------------------- done [on line 577 of "/var/www/egroupware_TRUNK/htdocs/egroupware/phpgwapi/inc/horde/Horde/SyncML.php"] Jul 29 23:49:33 s_d...@we... apache2: EGWSYNC: [] SyncML: starting sync to client [on line 129 of "/var/www/egroupware_TRUNK/htdocs/egroupware/phpgwapi/inc/horde/Horde/SyncML/Command/Sync.php"] Jul 29 23:49:33 s_d...@we... apache2: EGWSYNC: [] SyncML[syncml-87e71d99e0238e6ae1156caa5b9961b2]: sync alerttype 205 found for target ./calendar [on line 140 of "/var/www/egroupware_TRUNK/htdocs/egroupware/phpgwapi/inc/horde/Horde/SyncML/Command/Sync.php"] Jul 29 23:49:33 s_d...@we... apache2: EGWSYNC: [] SyncML: starting sync to client ./calendar [on line 149 of "/var/www/egroupware_TRUNK/htdocs/egroupware/phpgwapi/inc/horde/Horde/SyncML/Command/Sync.php"] Jul 29 23:49:33 s_d...@we... apache2: EGWSYNC: [] SyncML: reading added items from database for calendar [on line 155 of "/var/www/egroupware_TRUNK/htdocs/egroupware/phpgwapi/inc/horde/Horde/SyncML/Sync/RefreshFromServerSync.php"] Jul 29 23:49:33 s_d...@we... apache2: EGWSYNC: [egwcalendarsync] SymcML: egwcalendarsync listBy action: add timestamp: 1248904172 filter: [on line 146 of "/var/www/egroupware_TRUNK/htdocs/egroupware/syncml/calendar/lib/api.php"] Jul 29 23:49:33 s_d...@we... apache2: EGWSYNC: [egwcalendarsync] SymcML: egwcalendarsync getHistory('calendar', add, 1248904172)=Array Jul 29 23:49:33 s_d...@we... ( Jul 29 23:49:33 s_d...@we... ) Jul 29 23:49:33 s_d...@we... [on line 150 of "/var/www/egroupware_TRUNK/htdocs/egroupware/syncml/calendar/lib/api.php"] Jul 29 23:49:33 s_d...@we... apache2: EGWSYNC: [egwcalendarsync] SymcML: egwcalendarsync listBy startDate=728904173, endDate=1768904173 [on line 180 of "/var/www/egroupware_TRUNK/htdocs/egroupware/syncml/calendar/lib/api.php"] Jul 29 23:49:33 s_d...@we... apache2: EGWSYNC: [egwcalendarsync] SymcML: egwcalendarsync list filter: [on line 66 of "/var/www/egroupware_TRUNK/htdocs/egroupware/syncml/calendar/lib/api.php"] Jul 29 23:49:33 s_d...@we... apache2: EGWSYNC: [egwcalendarsync] SymcML: egwcalendarsync list startDate=728904173, endDate=1768904173 [on line 101 of "/var/www/egroupware_TRUNK/htdocs/egroupware/syncml/calendar/lib/api.php"] Jul 29 23:49:33 s_d...@we... apache2: EGWSYNC: [] SyncML: 1 added items found for ./calendar [on line 57 of "/var/www/egroupware_TRUNK/htdocs/egroupware/phpgwapi/inc/horde/Horde/SyncML/Sync/RefreshFromServerSync.php"] Jul 29 23:49:33 s_d...@we... apache2: EGWSYNC: [] SyncML: search LocID for use...@of...fmz-ciKOJ2vQAu0OjHxYFN8Cgg==./calendar / calendar-14667 [on line 251 of "/var/www/egroupware_TRUNK/htdocs/egroupware/phpgwapi/inc/horde/Horde/SyncML/State_egw.php"] Jul 29 23:49:33 s_d...@we... apache2: EGWSYNC: [egwcalendarsync] SymcML: egwcalendarsync export guid: 14667 (0) contenttype: Jul 29 23:49:33 s_d...@we... text/calendar [on line 474 of "/var/www/egroupware_TRUNK/htdocs/egroupware/syncml/calendar/lib/api.php"] Jul 29 23:49:33 s_d...@we... apache2: EGWSYNC: [egwcalendarsync] setSupportedFields(funambol, mozilla sync client) [on line 1259 of "/var/www/egroupware_TRUNK/htdocs/egroupware/calendar/inc/class.calendar_ical.inc.php"] Jul 29 23:49:33 s_d...@we... apache2: Unknown calendar SyncML client: manufacturer='funambol' product='mozilla sync client' Jul 29 23:49:33 s_d...@we... apache2: EGWSYNC: [egwcalendarsync] exportVCAL: Jul 29 23:49:33 s_d...@we... BEGIN:VCALENDAR Jul 29 23:49:33 s_d...@we... VERSION:2.0 Jul 29 23:49:33 s_d...@we... PRODID:-//eGroupWare//NONSGML eGroupWare Calendar 1.7.001//DE Jul 29 23:49:33 s_d...@we... METHOD:PUBLISH Jul 29 23:49:33 s_d...@we... BEGIN:VEVENT Jul 29 23:49:33 s_d...@we... CLASS:PUBLIC Jul 29 23:49:33 s_d...@we... SUMMARY:Manual Created - Birthday TEST Jul 29 23:49:33 s_d...@we... DTSTART:19200504T230000Z Jul 29 23:49:33 s_d...@we... DTEND:19200505T225959Z Jul 29 23:49:33 s_d...@we... ORGANIZER;CN="Erwin-Mobil domain":MAILTO:use...@do... Jul 29 23:49:33 s_d...@we... RRULE:FREQ=YEARLY Jul 29 23:49:33 s_d...@we... PRIORITY:5 Jul 29 23:49:33 s_d...@we... TRANSP:OPAQUE Jul 29 23:49:33 s_d...@we... UID:calendar-14667-0c2b53d7ff5e9330cdbab910d0f6316c Jul 29 23:49:33 s_d...@we... CREATED:20090729T213723Z Jul 29 23:49:33 s_d...@we... LAST-MODIFIED:20090729T213712Z Jul 29 23:49:33 s_d...@we... DTSTAMP:20090729 Jul 29 23:49:33 s_d...@we... apache2: EGWSYNC: [] SyncML: refresh add calendar-14667 to client Jul 29 23:49:33 s_d...@we... BEGIN:VCALENDAR Jul 29 23:49:33 s_d...@we... VERSION:2.0 Jul 29 23:49:33 s_d...@we... PRODID:-//eGroupWare//NONSGML eGroupWare Calendar 1.7.001//DE Jul 29 23:49:33 s_d...@we... METHOD:PUBLISH Jul 29 23:49:33 s_d...@we... BEGIN:VEVENT Jul 29 23:49:33 s_d...@we... CLASS:PUBLIC Jul 29 23:49:33 s_d...@we... SUMMARY:Manual Created - Birthday TEST Jul 29 23:49:33 s_d...@we... DTSTART:19200504T230000Z Jul 29 23:49:33 s_d...@we... DTEND:19200505T225959Z Jul 29 23:49:33 s_d...@we... ORGANIZER;CN="Erwin-Mobil domain":MAILTO:use...@do... Jul 29 23:49:33 s_d...@we... RRULE:FREQ=YEARLY Jul 29 23:49:33 s_d...@we... PRIORITY:5 Jul 29 23:49:33 s_d...@we... TRANSP:OPAQUE Jul 29 23:49:33 s_d...@we... UID:calendar-14667-0c2b53d7ff5e9330cdbab910d0f6316c Jul 29 23:49:33 s_d...@we... CREATED:20090729T213723Z Jul 29 23:49:33 s_d...@we... LAST-MODIFIED:20090729T213712Z Jul 29 23:49:33 s_d...@we... apache2: EGWSYNC: [] SyncML: Add: current = 1139, max = 250000 [on line 110 of "/var/www/egroupware_TRUNK/htdocs/egroupware/phpgwapi/inc/horde/Horde/SyncML/Command/Sync/ContentSyncElement.php"] Jul 29 23:49:33 s_d...@we... apache2: EGWSYNC: [] SyncML: All items handled for sync ./calendar [on line 137 of "/var/www/egroupware_TRUNK/htdocs/egroupware/phpgwapi/inc/horde/Horde/SyncML/Sync/RefreshFromServerSync.php"] Jul 29 23:49:33 s_d...@we... apache2: EGWSYNC: [] SyncML: state->removeExpiredUID(type=./calendar) [on line 376 of "/var/www/egroupware_TRUNK/htdocs/egroupware/phpgwapi/inc/horde/Horde/SyncML/State_egw.php"] Jul 29 23:49:33 s_d...@we... apache2: EGWSYNC: [] SyncML: syncStatus(syncToClient) = 5 [on line 204 of "/var/www/egroupware_TRUNK/htdocs/egroupware/phpgwapi/inc/horde/Horde/SyncML/Command/Sync.php"] Jul 29 23:49:33 s_d...@we... apache2: EGWSYNC: [] SyncML[syncml-87e71d99e0238e6ae1156caa5b9961b2]: syncStatus = 5, actionCommands = False [on line 606 of "/var/www/egroupware_TRUNK/htdocs/egroupware/phpgwapi/inc/horde/Horde/SyncML.php"] Jul 29 23:49:33 s_d...@we... apache2: EGWSYNC: [] SyncML[syncml-87e71d99e0238e6ae1156caa5b9961b2]: syncsyncml-87e71d99e0238e6ae1156caa5b9961b2 completed successfully! [on line 612 of "/var/www/egroupware_TRUNK/htdocs/egroupware/phpgwapi/inc/horde/Horde/SyncML.php"] Jul 29 23:49:33 s_d...@we... apache2: EGWSYNC: [] SyncML: write SYNCSummary for use...@of...fmz-ciKOJ2vQAu0OjHxYFN8Cgg== ./calendar serverts: 1248904172 clients: 1248904170 [on line 594 of "/var/www/egroupware_TRUNK/htdocs/egroupware/phpgwapi/inc/horde/Horde/SyncML/State_egw.php"] Jul 29 23:49:33 s_d...@we... apache2: EGWSYNC: [] SyncML[syncml-87e71d99e0238e6ae1156caa5b9961b2]: summary: Server-Add=1 [on line 622 of "/var/www/egroupware_TRUNK/htdocs/egroupware/phpgwapi/inc/horde/Horde/SyncML.php"] ==> /var/log/apache2/domain_SSL_access.log <== xx.xx.xx.xx - - [29/Jul/2009:23:49:33 +0200] "POST /rpc.php?syncml_sessionid=syncml-87e71d99e0238e6ae1156caa5b9961b2 HTTP/1.1" 200 1697 "-" "Funambol Mozilla Sync Client v0.9.1" ==> /var/log/apache2/default_SSL_rewrite.log <== xx.xx.xx.xx - - [29/Jul/2009:23:49:33 +0200] [office.domain.com/sid#19aa2b0][rid#7f6f0c01fd18/initial] (1) pass through /rpc.php xx.xx.xx.xx - - [29/Jul/2009:23:49:34 +0200] [office.domain.com/sid#19aa2b0][rid#2415d48/initial] (1) pass through /rpc.php ==> /var/log/NOT_SORTED_DEVLOG/notice.log <== Jul 29 23:49:34 s_d...@we... apache2: EGWSYNC: [] SyncML[syncml-87e71d99e0238e6ae1156caa5b9961b2]: reusing existing session [on line 169 of "/var/www/egroupware_TRUNK/htdocs/egroupware/phpgwapi/inc/horde/Horde/SyncML.php"] Jul 29 23:49:34 s_d...@we... apache2: EGWSYNC: [] SyncML[syncml-87e71d99e0238e6ae1156caa5b9961b2]: session is authorized [on line 190 of "/var/www/egroupware_TRUNK/htdocs/egroupware/phpgwapi/inc/horde/Horde/SyncML.php"] Jul 29 23:49:34 s_d...@we... apache2: EGWSYNC: [] SyncML[syncml-87e71d99e0238e6ae1156caa5b9961b2]: package 3 +++++++++++++++++++++ started [on line 232 of "/var/www/egroupware_TRUNK/htdocs/egroupware/phpgwapi/inc/horde/Horde/SyncML.php"] Jul 29 23:49:34 s_d...@we... apache2: EGWSYNC: [] SyncML: creating Map for source=calendar-14667-0c2b53d7ff5e9330cdbab910d0f6316c and target=calendar-14667 [on line 104 of "/var/www/egroupware_TRUNK/htdocs/egroupware/phpgwapi/inc/horde/Horde/SyncML/Command/Map.php"] Jul 29 23:49:34 s_d...@we... apache2: EGWSYNC: [] SyncML: setUID ./calendar, calendar-14667-0c2b53d7ff5e9330cdbab910d0f6316c, calendar-14667, 1248904172 [on line 466 of "/var/www/egroupware_TRUNK/htdocs/egroupware/phpgwapi/inc/horde/Horde/SyncML/State_egw.php"] Jul 29 23:49:34 s_d...@we... apache2: EGWSYNC: [] SyncML[syncml-87e71d99e0238e6ae1156caa5b9961b2]: syncStatus(server sync acknowledged) 6 [on line 690 of "/var/www/egroupware_TRUNK/htdocs/egroupware/phpgwapi/inc/horde/Horde/SyncML.php"] Jul 29 23:49:34 s_d...@we... apache2: EGWSYNC: [] SyncML[syncml-87e71d99e0238e6ae1156caa5b9961b2]: package ----------------------- done [on line 577 of "/var/www/egroupware_TRUNK/htdocs/egroupware/phpgwapi/inc/horde/Horde/SyncML.php"] Jul 29 23:49:34 s_d...@we... apache2: EGWSYNC: [] SyncML[syncml-87e71d99e0238e6ae1156caa5b9961b2]: syncStatus = 6, actionCommands = False [on line 606 of "/var/www/egroupware_TRUNK/htdocs/egroupware/phpgwapi/inc/horde/Horde/SyncML.php"] Jul 29 23:49:34 s_d...@we... apache2: EGWSYNC: [] SyncML[syncml-87e71d99e0238e6ae1156caa5b9961b2]: syncsyncml-87e71d99e0238e6ae1156caa5b9961b2 completed successfully! [on line 633 of "/var/www/egroupware_TRUNK/htdocs/egroupware/phpgwapi/inc/horde/Horde/SyncML.php"] Jul 29 23:49:34 s_d...@we... apache2: EGWSYNC: [] SyncML: write SYNCSummary for use...@of...fmz-ciKOJ2vQAu0OjHxYFN8Cgg== ./calendar serverts: 1248904172 clients: 1248904170 [on line 594 of "/var/www/egroupware_TRUNK/htdocs/egroupware/phpgwapi/inc/horde/Horde/SyncML/State_egw.php"] Jul 29 23:49:34 s_d...@we... apache2: EGWSYNC: [] SyncML[syncml-87e71d99e0238e6ae1156caa5b9961b2]: summary: Server-Add=1 [on line 643 of "/var/www/egroupware_TRUNK/htdocs/egroupware/phpgwapi/inc/horde/Horde/SyncML.php"] Jul 29 23:49:34 s_d...@we... apache2: EGWSYNC: [] SyncML[syncml-87e71d99e0238e6ae1156caa5b9961b2]: destroying sync session syncml-87e71d99e0238e6ae1156caa5b9961b2 [on line 645 of "/var/www/egroupware_TRUNK/htdocs/egroupware/phpgwapi/inc/horde/Horde/SyncML.php"] ==> /var/log/apache2/domain_SSL_access.log <== xx.xx.xx.xx - - [29/Jul/2009:23:49:34 +0200] "POST /rpc.php?syncml_sessionid=syncml-87e71d99e0238e6ae1156caa5b9961b2 HTTP/1.1" 200 846 "-" "Funambol Mozilla Sync Client v0.9.1" Comment by camel camel at 2009/07/29 23:02: with vcal i will do all tests once again with logs. what i see is also: tried to create a birthday event: start from 1960-05-05 fullday event - every year, and then i can't set a alarm: EGW told me: can't set alarm in past. well in past i don't want an alarm, but i want to get an alarm ex:12h before event happen again. not possible - ism there a trick ? Comment by Jörg Lehrke at 2009/07/29 22:54: Could not find any logs with vCalendar content. Please attach logs where both dictions of the failure are documented. What I need is the full content. Comment by camel camel at 2009/07/29 22:46: so, i tried the birthday events also with vcal. the same. in EGW they arte full day events. after syncing to OL, they are then from 01:00 to 01:00 and the field "full day is not selected ! see the attached logs and pictures Comment by camel camel at 2009/07/29 22:37: well, i try to find all differences on calendar and contacts. does this mean, that i should do my tests on calendar with vcal once again ?because EGW will only support in futire the vcal and not any more the sifcal ? and contacts are working now fine (excluded vcard parser for qutoe and the email handling.) addional i got an answer from funambol bug: 3 email line handling [ sync4j-Bugs-314077 ] Funambol Outlook Client v.8.0.4 -> email adress handling wrong http://forge.ow2.org/tracker/?func=detail&amp;atid=100096&amp;aid=314077&amp;group_id=96 >Comment By: Stefano Maffulli (smaffulli) Date: 2009-07-29 10:44 Message: Logged In: YES user_id=14287 This should be fixed in a synclet, probably it's not a client issue. @Jörg: maybe you can look into there and make a comment there also, maybe they do understand you better ... Comment by Jörg Lehrke at 2009/07/28 21:56: The SIF parser of eGW is not supporting newer features like and . Since Funambol will support vCalendar and SIF is a dead end, it is not worth spending time for enhancements. Calendar related issues are most probably timezone problems. The vCard encondig must be fixed by Funambol. I belive that eGW is almost RFC compliant in this area. Comment by camel camel at 2009/07/28 00:01: so, now i make more tests and i see, that the: 1.) sync from OL to EGW .) normal events -> 100% OK .) calendar items -> no alarm triggered and "Sunday" is marked in repeat tab ??? 2.) sync the same items back from EGW to OL .) normal events -> quote handling is strange ? .) birthday event -> birthday event is not any more a full day event. sometimes it change the start time to 00:00 - sometimes to 01:00 + the repeat is changed to weekly event ? see attached pictures: original event in OL: OL_Calendar_1Christian.png OL_Calendar_2Christian.png after sync to EGW: EGW_Calendar_1Christian - after Sync to EGW.png EGW_Calendar_2Christian - after Sync to EGW.png EGW_Sync OL to EGW-Server.txt after syncing back from EGW to OL: EGW_Calendar_1Christian - after Sync from EGW to OL back.png EGW_Calendar_2Christian - after Sync from EGW to OL back.png OL_Calendar_Lena - after Sync from EGW to OL back.png (quote support strange ?' = quote ?) EGW_Sync EGW to OL back.txt Comment by camel camel at 2009/07/24 05:52: to bug: "BUG_Calendar_1_Birthday". this does now works. also i see, that if when i'm syncing a birthday to EGW, then it is not count as 50 events.... it seems to be, that here are made a lot changes ? well, i will test more in detail, because on OL i have 1106 appointments. on EGW i have 750, do i miss "some" events ? i will test more in detail, so please don't wast your time, i will look into and let you know. thanks - cu camel Comment by camel camel at 2009/07/24 05:31: to bug: "BUG_Calendar_1_Birthday". i try to sync a full day event to calendar from OL to EGW, and this is working. so, please standby i will once again check why this appointment is wrong. Comment by camel camel at 2009/07/24 04:17: well, back to the problems from syncing .... i give now a new number to parts of it BUG_Calendar_1_Birthday -> time is wrong on birthday appointments ----------------------------------------------------- (see attached picture from Outlook -> "Calendar_1_Birthday.png") the problem is, that the item for birthday is sending wrong. in EGW the apppointment is from 00:00:00 to 23:59:59 but on syncing from EGW to OL (via funambol) is 1h wrong !!! see attached log ---------------- ==> /var/log/NOT_SORTED_DEVLOG/err.log <== Jul 24 02:05:44 s_d...@we... unparseable log message: "<?xml version="1.0" encoding="UTF-8"?>" Jul 24 02:05:44 s_d...@we... unparseable log message: "1.119621021T230000Z19621022T225959Z0100Geburtstag von Christian Reisner [on line 112 of "/var/www/egroupware_TRUNK/htdocs/egroupware/phpgwapi/inc/horde/Horde/SyncML/Sy" Comment by Jörg Lehrke at 2009/07/20 21:51: You can create a new GroupDAV calender in KOrganizer using the URL ...eGW/groupdav.php/calendar/ For KAddressBook the URL would be ...eGW/groupdav.php/addressbook/ This should work. Comment by camel camel at 2009/07/20 21:32: + kaddressbook Comment by camel camel at 2009/07/20 21:31: my friend is trying via groupdav. can you send me more infos how to connect via groupdav and "Kontact" . are there somewhere debug options which i can activate in EGW ? Comment by Jörg Lehrke at 2009/07/19 01:11: I'm using the Synthesis clients on my WM6 device and my iPhone. I would say this is the reference implementation of SyncML;-) On my desktop I'm using Linux and use the CalDAV interface for synchronization with eGW. I know that Funambol supports various platforms and I'm willing to do my best for inter-working with these clients. But I can not break the standard to support buggy clients. You have to contact the Funambol developers to fix them. Comment by camel camel at 2009/07/18 23:09: well, which client do you use ? i'm thinking, that funambol is the only one which have versions for pda and pc. isn't it ? can you tell me which sync client i can use for pda and pc where no problems are be ? Comment by Jörg Lehrke at 1970/01/01 01:33: Here is what I can see from the logs: 1. The contact vCard entry is RFC conform but Funambol does not like it. ENCODING=QUOTED-PRINTABLE in not understood properly, although it is used for the other direction (Funambol -> eGW). There is no way to fix this on eGW side. You have to complain at Funambol. The field matching for this client is now improved in the trunk version. 2. The calendar entry is exchanged in SIF format. eGW should import the event "whole day". I improved the import part within the trunk version. Linked entries: http://www.egroupware.org/egroupware/index.php?menuaction=tracker.tracker_ui.edit&tr_id=2171&no_popup=1 |