[GroupDAV] Syncronizing Evolution and OG using Gnome Multisync and Funambol GroupDAV plugin
Samuli Seppänen
groupdav@opengroupware.org
Fri, 29 Sep 2006 18:19:07 +0200
Hello everybody!
I've been trying to sync Evolution with OpenGroupware.org using Gnome Multisync
that connects the Funambol server (via SyncML) and Evolution. I use the Funambol
GroupDAV connector provided by Matthew McBride. My main goal is to get a SyncML
phone synced with OpenGroupware.org using the Funambol DS server. I have tried
three different SyncML phones (Nokias) and Gnome MultiSync with SyncML plugin.
There are some problems that I don't seem to be able to solve. It is possible
that Gnome Multisync SyncML plugin does not work as it should, but I have also
tried the "SyncEvolution" plugin and it didn't work any better, either.
Funambol and Evolution are running locally. OpenGroupware.org is on a server on
the LAN.
First a summary:
- Multisync does not recognize any OGo events. It does recognize new evolution
events.
- Tomcat and GroupDAV connector get calendar files (*.ics) and events from OGo.
- Funambol logs do not show any calendar files OR events. On a previous sync
attempt I was able to get calendar files and events to funambol's logs
- SyncML phones can connect to the server via Internet but complain about the
Database, which I have configured to have the same name as GroupDAV connector's
URI (I haven't experimented with this thoroughly)
I hope that some of you could tell me if there's some obvious flaw in my
configuration. Here are (hopefully) the relevant pieces of information...
----
I believe that the the Funambol GroupDAV connector installed correctly, but here
are the settings just in case:
SyncML Source URI: apinja
Source Type: iCal (Events/Todo)
GrouDAV URL: http://172.20.1.12:80/zidestore/
Server Source: dav/%USER%/Calendar (I've also tried Overview)
Store Location: /opt/Funambol/ds-server/db/calendar/
Version: 1.1.1700 on 2006/08/28 ...
The user, device and principal are correctly set. The user I use has
administrator status.
----
My MULTISYNC settings are as follows:
Protocol: http
Server name: localhost:8080/funambol/ds
SyncML login: sasepp (the same as in Funambol AND OGo)
SyncML passwd: ********* (the same as in Funambol AND OGo)
MULTISYNC does not report any errors when syncing:
[evo2-sync] DEBUG: start: sync_connect
[evo2-sync] INFORMATION: Loading state from file
/home/falchion/.multisync/1/remotesettings
[evo2-sync] DEBUG: end: load_palm_state
[evo2-sync] DEBUG: end: sync_connect
[evo2-sync] INFORMATION: Done searching for changes. Found 3 changes
[evo2-sync] INFORMATION: Done searching for changes. Found 0 changes
If I add Calendar events to Evolution, Multisync WILL report them as shown above
("Found 3 changes"). If I create and event in OGo, Multisync DOES NOT find it
(Found 0 changes).
----
TCPDUMP shows that there's a lot of traffic between localhost (Funambol DS
server) and the OpenGroupware.org server. If I create an event into OGo and try
Syncronizing with multisync, tcpdump shows that this event is in fact sent from
the OGo server to the local computer's Funambol service.
16:26:44.009483 IP 172.20.1.12.www > lan133.tietoteema.fi.39525: P 1:1107(1106)
ack 190 win 6432
0x0000: 4500 047a c918 4000 3f06 14b0 ac14 010c E..z..@.?.......
0x0010: ac10 0085 0050 9a65 aec1 da84 230f 28a9 .....P.e....#.(.
0x0020: 5018 1920 9fef 0000 4854 5450 2f31 2e31 P.......HTTP/1.1
0x0030: 2032 3030 204f 4b0d 0a44 6174 653a 2046 .200.OK..Date:.F
0x0040: 7269 2c20 3239 2053 6570 2032 3030 3620 ri,.29.Sep.2006.
0x0050: 3134 3a33 373a 3130 2047 4d54 0d0a 5365 14:37:10.GMT..Se
0x0060: 7276 6572 3a20 4170 6163 6865 2f32 2e32 rver:.Apache/2.2
0x0070: 2e32 2028 4665 646f 7261 290d 0a65 7461 .2.(Fedora)..eta
0x0080: 673a 2031 3539 3734 303a 310d 0a63 6f6e g:.159740:1..con
0x0090: 7465 6e74 2d74 7970 653a 2074 6578 742f tent-type:.text/
0x00a0: 6361 6c65 6e64 6172 3b20 6368 6172 7365 calendar;.charse
0x00b0: 743d 7574 662d 380d 0a63 6f6e 7465 6e74 t=utf-8..content
0x00c0: 2d6c 656e 6774 683a 2039 3139 0d0a 436f -length:.919..Co
0x00d0: 6e6e 6563 7469 6f6e 3a20 636c 6f73 650d nnection:.close.
0x00e0: 0a0d 0a42 4547 494e 3a56 4341 4c45 4e44 ...BEGIN:VCALEND
0x00f0: 4152 0d0a 4d45 5448 4f44 3a52 4551 5545 AR..METHOD:REQUE
0x0100: 5354 0d0a 5052 4f44 4944 3a2d 2f2f 4f70 ST..PRODID:-//Op
0x0110: 656e 4772 6f75 7077 6172 652e 6f72 672f enGroupware.org/
0x0120: 5a69 6465 5374 6f72 6520 312e 332f 2f0d ZideStore.1.3//.
0x0130: 0a56 4552 5349 4f4e 3a32 2e30 0d0a 4245 .VERSION:2.0..BE
0x0140: 4749 4e3a 5645 5645 4e54 0d0a 4c4f 4341 GIN:VEVENT..LOCA
0x0150: 5449 4f4e 3a4e c3a4 6bc3 b66b 6573 6b75 TION:N..k..kesku
0x0160: 735c 2c20 4d79 6c6c 790d 0a53 5441 5455 s\,.Mylly..STATU
0x0170: 533a 434f 4e46 4952 4d45 440d 0a53 554d S:CONFIRMED..SUM
0x0180: 4d41 5259 3a4e c3a4 c3b6 6e74 6172 6b61 MARY:N....ntarka
0x0190: 7374 7573 202f 2072 696c 6c69 656e 206b stus./.rillien.k
0x01a0: 6f6b 6569 6c75 0d0a 4445 5343 5249 5054 okeilu..DESCRIPT
0x01b0: 494f 4e3a 4ec3 a4c3 b66e 7461 726b 6173 ION:N....ntarkas
0x01c0: 7475 7320 6a61 2072 696c 6c69 656e 206b tus.ja.rillien.k
0x01d0: 6f6b 6569 6c75 0d5c 6e0d 5c6e 0d0a 4454 okeilu.\n.\n..DT
0x01e0: 454e 443a 3230 3036 3039 3037 5431 3230 END:20060907T120
0x01f0: 3030 305a 0d0a 4454 5354 4152 543a 3230 000Z..DTSTART:20
0x0200: 3036 3039 3037 5431 3130 3030 305a 0d0a 060907T110000Z..
----
TOMCAT reports a lot to catalina.out, but here's what happens at the beginning:
Sep 29, 2006 4:26:42 PM com.funambol.transport.http.server.Sync4jServlet
showHeaders
FINEST: Http header:
Sep 29, 2006 4:26:42 PM com.funambol.transport.http.server.Sync4jServlet
showHeaders
FINEST: content-length: 4762
Sep 29, 2006 4:26:42 PM com.funambol.transport.http.server.Sync4jServlet
showHeaders
FINEST: content-type: application/vnd.syncml+xml
Sep 29, 2006 4:26:42 PM com.funambol.transport.http.server.Sync4jServlet
showHeaders
FINEST: accept: application/vnd.syncml+xml, application/vnd.syncml+wbxml
Sep 29, 2006 4:26:42 PM com.funambol.transport.http.server.Sync4jServlet
showHeaders
FINEST: host: localhost:8080
--- snip ---
TOMCAT can clearly see the Zidestore calendar (overview) for this user:
Sep 29, 2006 4:26:43 PM net.bionicmessage.groupdav.groupDAV$entityFinderHandler
characters
FINEST: We got a
href:http://172.20.1.12:80/zidestore/dav/sasepp/Overview/123500.ics
Sep 29, 2006 4:26:43 PM net.bionicmessage.groupdav.groupDAV$entityFinderHandler
characters
FINEST: We got an etag: 123500:1
Sep 29, 2006 4:26:43 PM net.bionicmessage.groupdav.groupDAV$entityFinderHandler
characters
FINEST: We got a
href:http://172.20.1.12:80/zidestore/dav/sasepp/Overview/123590.ics
Sep 29, 2006 4:26:43 PM net.bionicmessage.groupdav.groupDAV$entityFinderHandler
characters
FINEST: We got an etag: 123590:1
--- snip ---
The calendar events also reach TOMCAT:
BEGIN:VCALENDAR
METHOD:REQUEST
PRODID:-//OpenGroupware.org/ZideStore 1.3//
VERSION:2.0
BEGIN:VEVENT
LOCATION:Nowhere special
STATUS:CONFIRMED
SUMMARY:New test event
DTEND:20061003T100000Z
DTSTART:20061003T090000Z
TRANSP:OPAQUE
UID:skyrix://bc1s3vm2.tietoteema.fi/ogo.tietoteema.fi/184570
CREATED:20030710T120000Z
DTSTAMP:20060929T143711Z
X-MICROSOFT-CDO-IMPORTANCE:0
X-MICROSOFT-CDO-BUSYSTATUS:BUSY
X-MICROSOFT-CDO-INSTTYPE:0
X-MICROSOFT-CDO-ALLDAYEVENT:FALSE
ATTENDEE;CUTYPE="INDIVIDUAL";PARTSTAT="NEEDS-ACTION";ROLE="OPT-PARTICIPANT";RSVP="FALSE";CN="Samuli
Seppänen":MAILTO:samuli.seppanen@tietoteema.fi
ORGANIZER;CN="Samuli Seppänen":MAILTO:samuli.seppanen@tietoteema.fi
END:VEVENT
END:VCALENDAR
Also the GROUPDAV CONNECTOR'S log shows several events in it's logs, just like
TOMCAT's log.
-----
FUNAMBOL's DEVICE-SPECIFIC LOGS
(/opt/Funambol/ds-server/db/calendar/my_deviceid_sasepp/*) are not of any help.
----
For some reason FUNAMBOL'S LOG (funambol_ds.log) doesn't show any calendar file
(*.ics) or events, as show below:
[Sep 29, 2006 4:26:42 PM][funambol.transport.http] FINEST: Http header:
[Sep 29, 2006 4:26:42 PM][funambol.transport.http] FINEST: content-length: 4762
[Sep 29, 2006 4:26:42 PM][funambol.transport.http] FINEST: content-type:
application/vnd.syncml+xml
[Sep 29, 2006 4:26:42 PM][funambol.transport.http] FINEST: accept:
application/vnd.syncml+xml, application/vnd.syncml+wbxml
[Sep 29, 2006 4:26:42 PM][funambol.transport.http] FINEST: host: localhost:8080
--- snip ---
[Sep 29, 2006 4:26:43 PM][funambol.handler] FINEST: current state: STATE_START
[Sep 29, 2006 4:26:43 PM][funambol] FINEST: Username: sasepp
[Sep 29, 2006 4:26:43 PM] INFO: sasepp/my_deviceid logged in.
[Sep 29, 2006 4:26:43 PM][funambol.handler] FINEST: moving to state
STATE_PKG1_RECEIVING
[Sep 29, 2006 4:26:43 PM][funambol.handler] FINEST: Server auhenticated (code
200)
[Sep 29, 2006 4:26:43 PM][funambol.handler] FINEST: Processing the
initialization commands
[Sep 29, 2006 4:26:43 PM][funambol.engine] FINEST: Checking if the database
com.funambol.framework.database.Database@1184a4f[name=apinja,type=<null>,statusCode=200,target=3Dcom.funambol.framework.core.Target@1b6c184,source=3Dcom.funambol.framework.core.Source@15ad36d,anchor=3Dcom.funambol.framework.core.Anchor@1eeb372,principal=my_deviceid/sasepp]
is in the server database list.[Sep 29, 2006 4:26:43 PM][funambol.engine]
FINEST: Yes sir!
[Sep 29, 2006 4:26:43 PM][funambol.handler] FINEST: Requested databases:
[com.funambol.framework.database.Database@1184a4f[name=apinja,type=<null>,statusCode=508,target=3Dcom.funambol.framework.core.Target@1b6c184,source=3Dcom.funambol.framework.core.Source@15ad36d,anchor=3Dcom.funambol.framework.core.Anchor@1eeb372,principal=my_deviceid/sasepp]]
[Sep 29, 2006 4:26:43 PM][funambol.engine] FINEST: adding
com.funambol.framework.engine.source.MemorySyncSource@1c3432a - {name: apinja,
type: unknown, uri: apinja}
[Sep 29, 2006 4:26:43 PM][funambol.handler] FINEST: Checking if MaxMsgSize is
larger than the minimum size supported by the server (2500)
[Sep 29, 2006 4:26:43 PM][funambol.handler] FINEST: moving to state
STATE_PKG3_RECEIVING
[Sep 29, 2006 4:26:43 PM][funambol.server] FINEST: Use the request url to create
the RespURI
[Sep 29, 2006 4:26:43 PM][funambol.server] FINEST: Calling output pipeline
[Sep 29, 2006 4:26:43 PM][funambol.engine.pipeline] FINEST: Starting
postprocessing
[Sep 29, 2006 4:26:43 PM][funambol.engine.pipeline] FINEST: Returning message to
process: <?xml version="1.0" encoding="UTF-8"?
--- snip ---
[Sep 29, 2006 4:26:43 PM][funambol.handler] FINEST: current state:
STATE_PKG3_RECEIVING
[Sep 29, 2006 4:26:43 PM][funambol.handler] FINEST: Server auhenticated (code
200)
[Sep 29, 2006 4:26:43 PM][funambol.handler] FINEST: Processing the given
synchronization message
[Sep 29, 2006 4:26:43 PM][funambol.handler] FINEST: client sources:
[com.funambol.framework.engine.source.MemorySyncSource@1c3432a - {name: apinja,
type: unknown, uri: apinja}]
[Sep 29, 2006 4:26:43 PM][funambol.handler] FINEST: Checking if there are data
to add to previous data
[Sep 29, 2006 4:26:43 PM][funambol.handler] FINEST: Preparing
com.funambol.framework.engine.source.MemorySyncSource@1c3432a - {name: apinja,
type: unknown, uri: apinja} with [com.funambol.framework.core.Sync@b80f1c]
[Sep 29, 2006 4:26:43 PM] INFO: Starting synchronization ...
[Sep 29, 2006 4:26:43 PM][funambol.engine] FINEST: SyncSource state of 'apinja'
is CONFIGURED
[Sep 29, 2006 4:26:43 PM][funambol.engine.strategy] FINEST: Conflict resolution
not configured for 'apinja'. The default value is used
(CONFLICT_RESOLUTION_CLIENT_WINS)
[Sep 29, 2006 4:26:44 PM] INFO: Preparing slow synchronization of source
'apinja' for my_deviceid/sasepp ...
[Sep 29, 2006 4:26:44 PM] INFO: Last call
[Sep 29, 2006 4:26:44 PM][funambol.engine] FINEST: Create SyncItems with state
'N' checking the last anchor (null)
[Sep 29, 2006 4:26:44 PM][funambol.engine.strategy] FINEST: check: syncItemA:
null syncItemB: com.funambol.framework.engine.SyncItemImpl@1849daf[key= {
keyValue: skyrix://bc1s3vm2.tietoteema.fi/ogo.tietoteema.fi/184570 }
,parentKey=null,mappedKey=null,format=<null>,type=<null>,timestamp=<null>,content-lenght=content
null,state=N,properties={}]
[Sep 29, 2006 4:26:44 PM][funambol.engine.strategy] FINEST: operations:
[com.funambol.framework.engine.SyncOperationImpl@18c69a9[A=3Dcom.funambol.framework.engine.SyncItemImpl@16cefa8[key=
{ keyValue: NOT_EXISTING }
,parentKey=null,mappedKey=null,format=<null>,type=<null>,timestamp=<null>,content-lenght=content
null,state=X,properties={}],B=3Dcom.funambol.framework.engine.SyncItemImpl@1849daf[key=
{ keyValue: skyrix://bc1s3vm2.tietoteema.fi/ogo.tietoteema.fi/184570 }
,parentKey=null,mappedKey=null,format=<null>,type=<null>,timestamp=<null>,content-lenght=content
null,state=N,properties={}],operation=NEW,isAOperation=true,isBOperation=false,isDeleteForced=false]]
[Sep 29, 2006 4:26:44 PM] INFO: Preparation completed.
[Sep 29, 2006 4:26:44 PM] INFO: Synchronizing...
---
For some reason there are NO mentions on any calendar files or events. On a
previous sync attempt was -however - able to get the calendar files from
Zidestore. It is likely that I changed something somewhere :)
[Sep 29, 2006 4:22:23 PM][funambol] FINEST: We got a
href:http://172.20.1.12:80/zidestore/dav/sasepp/Overview/123500.ics
[Sep 29, 2006 4:22:23 PM][funambol] FINEST: We got an etag: 123500:1
[Sep 29, 2006 4:22:23 PM][funambol] FINEST: We got a
href:http://172.20.1.12:80/zidestore/dav/sasepp/Overview/123590.ics
[Sep 29, 2006 4:22:23 PM][funambol] FINEST: We got an etag: 123590:1
[Sep 29, 2006 4:22:23 PM][funambol] FINEST: We got a
href:http://172.20.1.12:80/zidestore/dav/sasepp/Overview/132310.ics
Can anyone shed some light on what's happening?
--
Samuli Seppänen
Lokalisoija
Tietoteema Oy
Artturinkatu 2
20200 Turku
GSM: +358 765 1248
telefax: +358 20 765 9071
email: samuli.seppanen@tietoteema.fi