[sync] Problem With PocketPC Sync And Funambol

Deven Phillips, CISSP, CCNA dphillips at metalsales.us.com
Wed Mar 28 11:10:08 UTC 2007 

Hey gang...

I was hoping perhaps someone could help me with this. I have an HTC 
Windows Mobile Phone. I have installed the 6.0.5 version of the Funambol 
plugin, but I keep getting:

Sync not completed. See log for details.

I cannot make heads nor tails out of the log. I first started by using 
an HTTPS url for sync, and now I have switched to HTTP just to be sure 
that wasn/t the problem. I see nothing but the login in the horde log 
files. Can anyone make any sense of this funambol log?

Thanks In Advance!!!

Deven Phillips





Funambol Windows Mobile Plug-in Log - 2007-03-28 05:58:08 GMT -5:00
05:58:08 GMT -5:00 [DEBUG] - Reading configuration settings from the 
management tree
05:58:08 GMT -5:00 [INFO] - Synchronization URL: 
http://myserver.mydomain.com:80/hordecvs/rpc.php
05:58:08 GMT -5:00 [INFO] - Preparing synchronization of calendar...
05:58:09 GMT -5:00 [DEBUG] - devinfo: <DevInf 
xmlns="syncml:devinf"><VerDTD>1.1</VerDTD>
<DevID>fwm-35637800959178500</DevID>
<DevTyp>windowsmobile</DevTyp>
<SupportLargeObjs/><SupportNumberOfChanges/><DataStore><SourceRef>calendar</SourceRef>
<Rx-Pref><CTType>text/x-s4j-sife</CTType>
<VerCT></VerCT>
</Rx-Pref>
<Tx-Pref><CTType>text/x-s4j-sife</CTType>
<VerCT></VerCT>
</Tx-Pref>
<SyncCap><SyncType>2</SyncType>
<SyncType>1</SyncType>
<SyncType>6</SyncType>
</SyncCap>
</DataStore>
</DevInf>

05:58:09 GMT -5:00 [DEBUG] - devinfo hash: 0spcILjaOOYCGTRMrqmTvQ==
05:58:09 GMT -5:00 [DEBUG] - devinfo changed, retransmit
05:58:09 GMT -5:00 [DEBUG] - Initialization message:
05:58:09 GMT -5:00 [DEBUG] - <?xml version="1.0" encoding="UTF-8"?>
<SyncML>
<SyncHdr><VerDTD>1.1</VerDTD>
<VerProto>SyncML/1.1</VerProto>
<SessionID>1175079488</SessionID>
<MsgID>1</MsgID>
<Target><LocURI>http://myserver.mydomain.com:80/hordecvs/rpc.php</LocURI>
</Target>
<Source><LocURI>fwm-35637800959178500</LocURI>
</Source>
<Cred><Meta><Format xmlns="syncml:metinf">b64</Format>
<Type xmlns="syncml:metinf">syncml:auth-basic</Type>
</Meta>
<Data>REMOVEDREMOVED</Data>
</Cred>
<Meta><MaxMsgSize xmlns="syncml:metinf">16384</MaxMsgSize>
<MaxObjSize xmlns="syncml:metinf">2010000</MaxObjSize>
</Meta>
</SyncHdr>
<SyncBody><Alert><CmdID>1</CmdID>
<Data>204</Data>
<Item><Target><LocURI>calendar</LocURI>
</Target>
<Source><LocURI>calendar</LocURI>
</Source>
<Meta><Anchor xmlns="syncml:metinf"><Last>1</Last>
<Next>1175079489</Next>
</Anchor>
<MaxObjSize xmlns="syncml:metinf">2010000</MaxObjSize>
</Meta>
</Item>
</Alert>
<Put><CmdID>2</CmdID>
<Meta><Type xmlns="syncml:metinf">application/vnd.syncml-devinf+xml</Type>
</Meta>
<Item><Source><LocURI>./devinf11</LocURI>
</Source>
<Data><DevInf xmlns="syncml:devinf"><VerDTD>1.1</VerDTD>
<DevID>fwm-35637800959178500</DevID>
<DevTyp>windowsmobile</DevTyp>
<SupportLargeObjs/><SupportNumberOfChanges/><DataStore><SourceRef>calendar</SourceRef>
<Rx-Pref><CTType>text/x-s4j-sife</CTType>
<VerCT></VerCT>
</Rx-Pref>
<Tx-Pref><CTType>text/x-s4j-sife</CTType>
<VerCT></VerCT>
</Tx-Pref>
<SyncCap><SyncType>2</SyncType>
<SyncType>1</SyncType>
<SyncType>6</SyncType>
</SyncCap>
</DataStore>
</DevInf>
</Data>
</Item>
</Put>
<Final/></SyncBody>
</SyncML>
05:58:09 GMT -5:00 [INFO] - Establish connection: test internet 
connection status...
05:58:09 GMT -5:00 [DEBUG] - Not connected: try to connect...
05:58:09 GMT -5:00 [DEBUG] - Try to establish connection...
05:58:09 GMT -5:00 [DEBUG] - Start internet connection process...
05:58:09 GMT -5:00 [INFO] - Internet connection succesfully completed.
05:58:09 GMT -5:00 [DEBUG] - User Agent = PPC_USER_AGENT
05:58:09 GMT -5:00 [DEBUG] - Connecting to myserver.mydomain.com:80
05:58:09 GMT -5:00 [DEBUG] - Requesting resource /hordecvs/rpc.php
05:58:09 GMT -5:00 [DEBUG] - Content-Type: application/vnd.syncml+xml 
Content-Length: 1582 Accept-Encoding: deflate
05:58:09 GMT -5:00 [DEBUG] - Entering FunctionHttpSendRequest
05:58:10 GMT -5:00 [DEBUG] - FunctionHttpSendRequest: total byte sent: 1024
05:58:10 GMT -5:00 [DEBUG] - FunctionHttpSendRequest: total byte sent: 1582
05:58:10 GMT -5:00 [DEBUG] - Now HttpEndRequest is called
05:58:21 GMT -5:00 [DEBUG] - Exiting FunctionHttpSendRequest
05:58:21 GMT -5:00 [DEBUG] - Message sent
05:58:21 GMT -5:00 [DEBUG] - Data sent succesfully to server. Server 
responds OK
05:58:21 GMT -5:00 [DEBUG] - HEADER: 
????????????????????????????????????????

05:58:21 GMT -5:00 [DEBUG] - Reading response...
05:58:21 GMT -5:00 [DEBUG] - Content-length: 571
05:58:21 GMT -5:00 [DEBUG] - In loop read bytes. Start
05:58:21 GMT -5:00 [DEBUG] - Entering WorkerFunctionInternetReadFile
05:58:21 GMT -5:00 [DEBUG] - Size: 571
05:58:21 GMT -5:00 [DEBUG] - Exiting WorkerFunctionInternetReadFile
05:58:21 GMT -5:00 [DEBUG] - In loop read bytes. End.
05:58:21 GMT -5:00 [DEBUG] - Response read
05:58:22 GMT -5:00 [DEBUG] - xœ¤TmoÚ0þ+(_'bšqÕ
uC¢]Uè¾»‰LySl(ì×ïlÇÉÂË:m|À—{ó=Ïݙ޲´·•LŠ|ê{=‘GEœä›©÷²ºï‡Þ-£Ëc=,zà›Ë©'á+K'ö 
 >ñ¬Ã׸bô»¨f«-Eµ¬uOU¡
fÓ g´JºRß>‡(2
ðh|†µZú 
7ÚH‘èŠW¡]ÑËóœ­ß²þ0ø8…ƒ1…ÆÕVíõÈ3Áâr›¤iRJc2*Š\ªe±«"ѤÜ*UNz¯¼,¥Ÿ 
ÅSÉS!ýô£"›„m‹*Ñ^¢ªŒür[¶7"—íâàŸ ðeòSœi“|í1‚Íð5ž&êÛë?D
‰‹©ýàb{!jº¡…OE|Iqµ“Œ~ÎbË¥4·Ïb]“«%í¡Ol\œ†Õ)Òu@ÿ©6¾f^‹WúØ:Ð x2 ÈH`«QuÑ
ÞGGºèîRQ©3làÈc^]©·5Ÿ–àЕ8W"«µô.€Š+í¤
.•®ËœôQT»cŠŒ‚"›ƒ5Øü
ˆãaØð`‰ÃA·ª“=jñ4ƒ|e=.xvGþ/â‹HGÁÒÿY„šš•î”ܼ?%ƒî”<íÜŒ´
÷Q,öP!&U—u2©_DÓž -ÀT»:–× Ân¥IÄ<ˆhŸÇ¾5÷íÕ ZÑ
ð‹ýý½Ô³;ªL}÷IÎSøpgûŒ û„³_
05:58:22 GMT -5:00 [ERROR] - Error processing SyncHdr Status
05:58:22 GMT -5:00 [DEBUG] - Modification message: estimated size 0, 
allowed size 16384, real size 705 / estimated size 0 = 100%
05:58:22 GMT -5:00 [DEBUG] - <?xml version="1.0" encoding="UTF-8"?>
<SyncML>
<SyncHdr><VerDTD>1.1</VerDTD>
<VerProto>SyncML/1.1</VerProto>
<SessionID>1175079488</SessionID>
<MsgID>2</MsgID>
<Target><LocURI>http://myserver.mydomain.com:80/hordecvs/rpc.php</LocURI>
</Target>
<Source><LocURI>fwm-35637800959178500</LocURI>
</Source>
</SyncHdr>
<SyncBody><Status><CmdID>1</CmdID>
<MsgRef>1</MsgRef>
<CmdRef>0</CmdRef>
<Cmd>SyncHdr</Cmd>
<TargetRef>http://myserver.mydomain.com:80/hordecvs/rpc.php</TargetRef>
<SourceRef>fwm-35637800959178500</SourceRef>
<Data>200</Data>
</Status>
<Sync><CmdID>2</CmdID>
<Target><LocURI>calendar</LocURI>
</Target>
<Source><LocURI>calendar</LocURI>
</Source>
</Sync>
<Final/></SyncBody>
</SyncML>
05:58:22 GMT -5:00 [DEBUG] - Connecting to myserver.mydomain.com:80
05:58:22 GMT -5:00 [DEBUG] - Requesting resource /hordecvs/rpc.php
05:58:22 GMT -5:00 [DEBUG] - Content-Type: application/vnd.syncml+xml 
Content-Length: 705 Accept-Encoding: deflate
05:58:22 GMT -5:00 [DEBUG] - Entering FunctionHttpSendRequest
05:58:22 GMT -5:00 [DEBUG] - FunctionHttpSendRequest: total byte sent: 705
05:58:22 GMT -5:00 [DEBUG] - Now HttpEndRequest is called
05:58:23 GMT -5:00 [DEBUG] - Exiting FunctionHttpSendRequest
05:58:23 GMT -5:00 [DEBUG] - Message sent
05:58:23 GMT -5:00 [DEBUG] - Data sent succesfully to server. Server 
responds OK
05:58:23 GMT -5:00 [DEBUG] - HEADER: 
????????????????????????????????????????*?
05:58:23 GMT -5:00 [DEBUG] - Reading response...
05:58:23 GMT -5:00 [DEBUG] - Content-length: 455
05:58:23 GMT -5:00 [DEBUG] - In loop read bytes. Start
05:58:23 GMT -5:00 [DEBUG] - Entering WorkerFunctionInternetReadFile
05:58:23 GMT -5:00 [DEBUG] - Size: 455
05:58:23 GMT -5:00 [DEBUG] - Exiting WorkerFunctionInternetReadFile
05:58:24 GMT -5:00 [DEBUG] - In loop read bytes. End.
05:58:24 GMT -5:00 [DEBUG] - Response read
05:58:24 GMT -5:00 [DEBUG] - xœœS]1ý+d^
s[Hé&JT’EÍÂúÞm
Œ™¦-ìâ¯÷v:è5ò@ÏÜ{ÎýlÙÝKYNÚº¼® MI2Е¬U^íÉãöý0Kî8Ûœ+¹¾ 
·r‹ÄáWYÌãASšDÂGe9ûªír»äheÐâ`ûbk_ó:g4²v!û
Ut:!ÓÙë,cÐ[ÙÚíñ1ˆ€m…ÝkÏÙ}-V|÷\Ç“7ãiFÈl2£ÓlBƒÖXŸD©¹2‡¼(rãWcbÐ…ÚÔG+õ%äÁ{3 
xÖO—–ڋ‰B»ôèRY—óŒÀ¡¶JË“kdj¦Ï]´5êð_¼`á›ü»þm€6¯v 
§¤ùaf£úüôíª1í4-Ç„pÙF
05:58:24 GMT -5:00 [ERROR] - Server Failure: server returned error code -1
05:58:24 GMT -5:00 [ERROR] - Error in syncing: Server Failure: server 
returned error code -1
05:58:24 GMT -5:00 [INFO] - Sync ended.

More information about the sync mailing list