[sync] thunderbird lightning deleting an event leads to HttpSendRequest error 12152 in funambol
Dominique LALOT
dom.lalot at gmail.com
Thu Jan 29 17:09:57 UTC 2009
Andreas
I've been a little bit further.
My apache is segfaulting.. (Debian etch)
So I generate a core dump:
Core was generated by `/usr/sbin/apache2 -k start'.
Program terminated with signal 11, Segmentation fault.
#0 0xb7b83307 in memcpy () from /lib/tls/libc.so.6
*before showing backtrace, here is the log from funambol:*
2009-01-29 16:45:14 GMT +1:00 - # Mozilla PIM Plugin Log
16:45:14 GMT +1:00 [DEBUG] - Reading configuration settings...
16:45:14 GMT +1:00 [DEBUG] - Reading configuration settings from the
management tree
16:45:14 GMT +1:00 [DEBUG] - Creating SyncSources...
16:45:14 GMT +1:00 [DEBUG] - Checking for contacts sync...
16:45:14 GMT +1:00 [DEBUG] - The contacts SyncSource is not set to be synced
16:45:14 GMT +1:00 [DEBUG] - Checking for calendar sync...
16:45:14 GMT +1:00 [INFO] - ----------> Prepare Calendar Synchronization
<----------
16:45:14 GMT +1:00 [DEBUG] - Set listeners
16:45:14 GMT +1:00 [INFO] - ----------> Begin SyncML Synchronization
<----------
16:45:14 GMT +1:00 [DEBUG] - Synchronization URL:
https://hordetest.univmed.fr/horde/rpc.php
16:45:14 GMT +1:00 [INFO] - Preparing synchronization of calendar...
16:45:14 GMT +1:00 [DEBUG] - PropertyFile: it is not possible to read
the file: 'C:\Documents and Settings\User\Mes documents/calendar'
16:45:14 GMT +1:00 [DEBUG] - PropertyFile: try to see if a journal exists...
16:45:14 GMT +1:00 [DEBUG] - PropertyFile: there is no journal file:
'C:\Documents and Settings\User\Mes documents/calendar.jour'
16:45:14 GMT +1:00 [DEBUG] - Checking devinfo...
16:45:14 GMT +1:00 [DEBUG] - devinfo hash: XDwVds+fxC9vKxaiO5GreQ==
16:45:14 GMT +1:00 [DEBUG] - devinfo unchanged, no need to send
16:45:14 GMT +1:00 [DEBUG] - Initialization message:
16:45:14 GMT +1:00 [DEBUG] - User Agent = Funambol Mozilla Plugin v0.8
16:45:14 GMT +1:00 [DEBUG] - <?xml version="1.0" encoding="UTF-8"?>
<SyncML>
<SyncHdr><VerDTD>1.1</VerDTD>
<VerProto>SyncML/1.1</VerProto>
<SessionID>1233243914</SessionID>
<MsgID>1</MsgID>
<Target><LocURI>https://hordetest.univmed.fr/horde/rpc.php</LocURI>
</Target>
<Source><LocURI>fmz-ZqLHfQakX5AAZpKHqcPxDg==</LocURI>
</Source>
<Cred><Meta><Format xmlns="syncml:metinf">b64</Format>
<Type xmlns="syncml:metinf">syncml:auth-basic</Type>
</Meta>
<Data>xxx</Data>
</Cred>
<Meta><MaxMsgSize xmlns="syncml:metinf">250000</MaxMsgSize>
</Meta>
</SyncHdr>
<SyncBody><Alert><CmdID>1</CmdID>
<Data>200</Data>
<Item><Target><LocURI>event</LocURI>
</Target>
<Source><LocURI>calendar</LocURI>
</Source>
<Meta><Anchor xmlns="syncml:metinf"><Last>1233075790</Last>
<Next>1233243914</Next>
</Anchor>
</Meta>
</Item>
</Alert>
<Final/></SyncBody>
</SyncML>
16:45:14 GMT +1:00 [DEBUG] - Connecting to hordetest.univmed.fr:443
<http://hordetest.univmed.fr:443>
16:45:14 GMT +1:00 [DEBUG] - Requesting resource /horde/rpc.php
16:45:14 GMT +1:00 [DEBUG] - Message sent
16:45:14 GMT +1:00 [DEBUG] - Data sent succesfully to server. Server
responds OK
16:45:14 GMT +1:00 [DEBUG] - Reading response...
16:45:14 GMT +1:00 [DEBUG] - Content-length: 1536
16:45:14 GMT +1:00 [DEBUG] - Response read:
<?xml version="1.0" encoding="UTF-8"?><SyncML
xmlns="syncml:syncml1.1"><SyncHdr><VerDTD>1.1</VerDTD><VerProto>SyncML/1.1</VerProto><SessionID>1233243914</SessionID><MsgID>1</MsgID><Target><LocURI>fmz-ZqLHfQakX5AAZpKHqcPxDg==</LocURI><LocName>lalot</LocName></Target><Source><LocURI>https://hordetest.univmed.fr/horde/rpc.php</LocURI></Source><RespURI>https://hordetest.univmed.fr/horde/rpc.php</RespURI><Meta><MaxMsgSize
xmlns="syncml:metinf">1000000000</MaxMsgSize><MaxObjSize
xmlns="syncml:metinf">1000000000</MaxObjSize></Meta></SyncHdr><SyncBody><Status><CmdID>1</CmdID><MsgRef>1</MsgRef><CmdRef>0</CmdRef><Cmd>SyncHdr</Cmd><TargetRef>https://hordetest.univmed.fr/horde/rpc.php</TargetRef><SourceRef>fmz-ZqLHfQakX5AAZpKHqcPxDg==</SourceRef><Data>212</Data></Status><Status><CmdID>2</CmdID><MsgRef>1</MsgRef><CmdRef>1</CmdRef><Cmd>Alert</Cmd><TargetRef>event</TargetRef><SourceRef>calendar</SourceRef><Data>200</Data><Item><Data><Anchor
xmlns="syncml:metinf"><Last>1233075790</Last><Next>1233243914</Next></Anchor></Data></Item></Status><Alert><CmdID>3</CmdID><Data>200</Data><Item><Target><LocURI>calendar</LocURI></Target><Source><LocURI>event</LocURI></Source><Meta><Anchor
xmlns="syncml:metinf"><Last>1233075693</Last><Next>1233243810</Next></Anchor><MaxObjSize
xmlns="syncml:metinf">1000000000</MaxObjSize></Meta></Item></Alert><Get><CmdID>4</CmdID><Meta><Type
xmlns="syncml:metinf">application/vnd.syncml-devinf+xml</Type></Meta><Item><Target><LocURI>./devinf11</LocURI></Target></Item></Get><Final></Final></SyncBody></SyncML>
16:45:14 GMT +1:00 [DEBUG] - [Calendar] CalItemsCollector(): Start
16:45:14 GMT +1:00 [DEBUG] - Reading configuration settings from the
management tree
16:45:14 GMT +1:00 [DEBUG] - Reading configuration settings from the
management tree
16:45:14 GMT +1:00 [DEBUG] - Reading configuration settings from the
management tree
16:45:14 GMT +1:00 [DEBUG] - [Calendar] CalItemsCollector(): Searching
for calendar to sync...
16:45:14 GMT +1:00 [DEBUG] - [Calendar] CalItemsCollector(): Calendar
found: 'Mon agenda'
16:45:14 GMT +1:00 [DEBUG] - [Calendar] CalItemsCollector(): End
16:45:14 GMT +1:00 [DEBUG] - [Calendar] CollectModifiedItems(): Start
16:45:14 GMT +1:00 [DEBUG] - Reading configuration settings from the
management tree
16:45:14 GMT +1:00 [DEBUG] - Reading configuration settings from the
management tree
16:45:14 GMT +1:00 [DEBUG] - Reading configuration settings from the
management tree
16:45:14 GMT +1:00 [INFO] - [Calendar] Initializing modified items...
16:45:14 GMT +1:00 [DEBUG] - [Calendar] CollectModifiedItems(): Reading
db file...
16:45:14 GMT +1:00 [DEBUG] - [Calendar]
CalGetterListener::OnOperationComplete: Returned id
'a31ff09d-207f-4ede-a761-6d03a726e820'. Status code: 0
16:45:14 GMT +1:00 [DEBUG] - [Calendar] CollectModifiedItems(): Removed
item found
16:45:14 GMT +1:00 [INFO] - [Calendar] Initialization done
16:45:14 GMT +1:00 [INFO] - [Calendar] Detected 0 new items, 0 updated
items, 1 deleted items
16:45:14 GMT +1:00 [DEBUG] - [Calendar] CollectModifiedItems(): End
16:45:14 GMT +1:00 [DEBUG] - Modification message: estimated size 300,
allowed size 250000, real size 2854 / estimated size 300 = 951%
16:45:14 GMT +1:00 [DEBUG] - <?xml version="1.0" encoding="UTF-8"?>
<SyncML>
<SyncHdr><VerDTD>1.1</VerDTD>
<VerProto>SyncML/1.1</VerProto>
<SessionID>1233243914</SessionID>
<MsgID>2</MsgID>
<Target><LocURI>https://hordetest.univmed.fr/horde/rpc.php</LocURI>
</Target>
<Source><LocURI>fmz-ZqLHfQakX5AAZpKHqcPxDg==</LocURI>
</Source>
</SyncHdr>
<SyncBody><Status><CmdID>1</CmdID>
<MsgRef>1</MsgRef>
<CmdRef>0</CmdRef>
<Cmd>SyncHdr</Cmd>
<TargetRef>https://hordetest.univmed.fr/horde/rpc.php</TargetRef>
<SourceRef>fmz-ZqLHfQakX5AAZpKHqcPxDg==</SourceRef>
<Data>200</Data>
</Status>
<Status><CmdID>2</CmdID>
<MsgRef>1</MsgRef>
<CmdRef>3</CmdRef>
<Cmd>Alert</Cmd>
<TargetRef>event</TargetRef>
<SourceRef>calendar</SourceRef>
<Data>200</Data>
<Item><Data><Anchor xmlns="syncml:metinf"><Next>1233243810</Next>
</Anchor>
</Data>
</Item>
</Status>
<Status><CmdID>4</CmdID>
<MsgRef>1</MsgRef>
<CmdRef>4</CmdRef>
<Cmd>Get</Cmd>
<Data>200</Data>
</Status>
<Results><CmdID>3</CmdID>
<MsgRef>1</MsgRef>
<CmdRef>4</CmdRef>
<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>
<SwV>0.7</SwV>
<DevID>fmz-ZqLHfQakX5AAZpKHqcPxDg==</DevID>
<DevTyp>workstation</DevTyp>
<UTC/><DataStore><SourceRef>calendar</SourceRef>
<Rx-Pref><CTType>text/calendar</CTType>
<VerCT>2.0</VerCT>
</Rx-Pref>
<Tx-Pref><CTType>text/calendar</CTType>
<VerCT>2.0</VerCT>
</Tx-Pref>
<SyncCap><SyncType>2</SyncType>
<SyncType>1</SyncType>
<SyncType>6</SyncType>
<SyncType>4</SyncType>
</SyncCap>
</DataStore>
<DataStore><SourceRef>contact</SourceRef>
<Rx-Pref><CTType>text/x-vcard</CTType>
<VerCT>2.1</VerCT>
</Rx-Pref>
<Tx-Pref><CTType>text/x-vcard</CTType>
<VerCT>2.1</VerCT>
</Tx-Pref>
<SyncCap><SyncType>2</SyncType>
<SyncType>1</SyncType>
<SyncType>6</SyncType>
<SyncType>4</SyncType>
</SyncCap>
</DataStore>
<DataStore><SourceRef>event</SourceRef>
<Rx-Pref><CTType>text/calendar</CTType>
<VerCT>2.0</VerCT>
</Rx-Pref>
<Tx-Pref><CTType>text/calendar</CTType>
<VerCT>2.0</VerCT>
</Tx-Pref>
<SyncCap><SyncType>2</SyncType>
<SyncType>1</SyncType>
<SyncType>6</SyncType>
<SyncType>4</SyncType>
</SyncCap>
</DataStore>
<DataStore><SourceRef>task</SourceRef>
<Rx-Pref><CTType>text/calendar</CTType>
<VerCT>2.0</VerCT>
</Rx-Pref>
<Tx-Pref><CTType>text/calendar</CTType>
<VerCT>2.0</VerCT>
</Tx-Pref>
<SyncCap><SyncType>2</SyncType>
<SyncType>1</SyncType>
<SyncType>6</SyncType>
<SyncType>4</SyncType>
</SyncCap>
</DataStore>
</DevInf>
</Data>
</Item>
</Results>
<Sync><CmdID>5</CmdID>
<Target><LocURI>event</LocURI>
</Target>
<Source><LocURI>calendar</LocURI>
</Source>
<Delete><CmdID>6</CmdID>
<Meta><Type xmlns="syncml:metinf">text/calendar</Type>
</Meta>
<Item><Source><LocURI>a31ff09d-207f-4ede-a761-6d03a726e820</LocURI>
</Source>
</Item>
</Delete>
</Sync>
<Final/></SyncBody>
</SyncML>
16:45:14 GMT +1:00 [DEBUG] - Connecting to hordetest.univmed.fr:443
<http://hordetest.univmed.fr:443>
16:45:14 GMT +1:00 [DEBUG] - Requesting resource /horde/rpc.php
16:45:15 GMT +1:00 [DEBUG] - HttpSendRequest error 12152: Le serveur a
renvoyé une réponse non valide ou non reconnue
16:45:15 GMT +1:00 [INFO] - Network error writing data from client:
retry 1 time...
16:45:15 GMT +1:00 [DEBUG] - HttpSendRequest error 12019: Le handle
n'est pas dans l'état correct pour l'opération demandée
16:45:15 GMT +1:00 [DEBUG] - Network error: the request has timed out ->
exit.
16:45:15 GMT +1:00 [ERROR] - Error in syncing: Network error: the
request has timed out -> exit.
16:45:17 GMT +1:00 [DEBUG] - [Calendar] ~CalGetterListener(): opType=1
16:46:03 GMT +1:00 [DEBUG] -
===========================================================
================ SYNCHRONIZATION REPORT ===============
===========================================================
Last error code = 2007
Last error msg = Network error: the request has timed out -> exit.
----------|--------CLIENT---------|--------SERVER---------|
Source | NEW | MOD | DEL | NEW | MOD | DEL |
----------|-----------------------------------------------|
calendar| 0/ 0| 0/ 0| 0/ 0| 0/ 0| 0/ 0| 0/ 0|
----------|-----------------------------------------------|
contact| 0/ 0| 0/ 0| 0/ 0| 0/ 0| 0/ 0| 0/ 0|
----------|-----------------------------------------------|
calendar:
----------
Source State = 0
Last error code = 0
Last error msg = (null)
contact:
----------
Source State = 0
Last error code = 0
Last error msg = (null)
16:46:03 GMT +1:00 [INFO] - ----------> End SyncML Synchronization
<----------
16:46:03 GMT +1:00 [DEBUG] - Saving configuration settings...
16:46:03 GMT +1:00 [DEBUG] - Writing configuration settings to the
management tree
16:46:03 GMT +1:00 [DEBUG] - Unset listeners
17:16:44 GMT +1:00 [DEBUG] - Reading configuration settings from the
management tree
17:16:44 GMT +1:00 [DEBUG] - Reading configuration settings from the
management tree
17:16:46 GMT +1:00 [DEBUG] - Reading configuration settings from the
management tree
17:16:47 GMT +1:00 [DEBUG] - Reading configuration settings from the
management tree
17:16:47 GMT +1:00 [DEBUG] - Reading configuration settings from the
management tree
17:16:47 GMT +1:00 [DEBUG] - Writing configuration settings to the
management tree
17:16:52 GMT +1:00 [DEBUG] - Reading configuration settings from the
management tree
*and the backtrace*
#0 0xb7b83307 in memcpy () from /lib/tls/libc.so.6
No symbol table info available.
#1 0xb7449c37 in php_flock () from /usr/lib/apache2/modules/libphp5.so
No symbol table info available.
#2 0xb744aa7e in zif_user_sprintf () from
/usr/lib/apache2/modules/libphp5.so
No symbol table info available.
#3 0xb752860f in execute () from /usr/lib/apache2/modules/libphp5.so
No symbol table info available.
#4 0xb7518008 in execute () from /usr/lib/apache2/modules/libphp5.so
No symbol table info available.
#5 0xb7527ff1 in execute () from /usr/lib/apache2/modules/libphp5.so
No symbol table info available.
#6 0xb7518008 in execute () from /usr/lib/apache2/modules/libphp5.so
No symbol table info available.
#7 0xb74eec14 in zend_call_function () from
/usr/lib/apache2/modules/libphp5.so
No symbol table info available.
#8 0xb74efd5a in call_user_function_ex () from
/usr/lib/apache2/modules/libphp5.so
No symbol table info available.
#9 0xb743651e in zif_call_user_func_array () from
/usr/lib/apache2/modules/libphp5.so
No symbol table info available.
#10 0xb752860f in execute () from /usr/lib/apache2/modules/libphp5.so
....
No symbol table info available.
#26 0xb74eec14 in zend_call_function () from
/usr/lib/apache2/modules/libphp5.so
No symbol table info available.
#27 0xb749fc2f in zif_xml_parser_create () from
/usr/lib/apache2/modules/libphp5.so
No symbol table info available.
#28 0xb74a2b0a in _xml_endElementHandler () from
/usr/lib/apache2/modules/libphp5.so
No symbol table info available.
#29 0xb74a36a4 in php_XML_ParserCreate () from
/usr/lib/apache2/modules/libphp5.so
No symbol table info available.
#30 0xb7154c59 in xmlParseXMLDecl () from /usr/lib/libxml2.so.2
No symbol table info available.
#31 0xb71617d8 in xmlParseChunk () from /usr/lib/libxml2.so.2
No symbol table info available.
#32 0xb74a346a in php_XML_Parse () from /usr/lib/apache2/modules/libphp5.so
No symbol table info available.
#33 0xb74a24f1 in zif_xml_parse () from /usr/lib/apache2/modules/libphp5.so
No symbol table info available.
#34 0xb752860f in execute () from /usr/lib/apache2/modules/libphp5.so
No symbol table info available.
#35 0xb7518008 in execute () from /usr/lib/apache2/modules/libphp5.so
No symbol table info available.
#36 0xb7527ff1 in execute () from /usr/lib/apache2/modules/libphp5.so
No symbol table info available.
#37 0xb7518008 in execute () from /usr/lib/apache2/modules/libphp5.so
No symbol table info available.
#38 0xb7527ff1 in execute () from /usr/lib/apache2/modules/libphp5.so
No symbol table info available.
#39 0xb7518008 in execute () from /usr/lib/apache2/modules/libphp5.so
No symbol table info available.
#40 0xb74f91dc in zend_execute_scripts () from
/usr/lib/apache2/modules/libphp5.so
No symbol table info available.
#41 0xb74b4482 in php_execute_script () from
/usr/lib/apache2/modules/libphp5.so
No symbol table info available.
#42 0xb757cb33 in php_ap2_register_hook () from
/usr/lib/apache2/modules/libphp5.so
No symbol table info available.
#43 0x08074607 in ap_run_handler ()
No symbol table info available.
#44 0x080777b1 in ap_invoke_handler ()
No symbol table info available.
#45 0x08084898 in ap_process_request ()
No symbol table info available.
#46 0x08081b0e in ap_register_input_filter ()
No symbol table info available.
#47 0x0807b447 in ap_run_process_connection ()
No symbol table info available.
#48 0x080888bf in ap_graceful_stop_signalled ()
No symbol table info available.
#49 0x08088bfa in ap_graceful_stop_signalled ()
No symbol table info available.
#50 0x08088cba in ap_graceful_stop_signalled ()
No symbol table info available.
#51 0x08089706 in ap_mpm_run ()
No symbol table info available.
#52 0x0806224f in main ()
No symbol table info available.
2009/1/27 <lst_hoe02 at kwsoft.de <mailto:lst_hoe02 at kwsoft.de>>:
> Zitat von LALOT Dominique <dom.lalot at gmail.com
<mailto:dom.lalot at gmail.com>>:
> What is in the Funambol Client log??
> Try to set it to "DEBUG" and report what the Mozilla-Plugin log for this
> request. Do you use it on Windows BTW??
>
> Regards
>
> Andreas
>
Thanks
Dom <http://annuaire.univmed.fr/showuser?uid=lalot>
More information about the sync
mailing list