[sync] Successful(?) sync exchanges no data

Sat May 3 17:31:40 UTC 2008

I am trying to sync synthesis standard version 3.0.2.18 with the
5/3/08 cvs version of Horde for contacts, calendar, tasks,and notes on
an Ubuntu 8.04 LTS (Hardy Heron) system.  I am using openldap for a
contacts backend. Synthesis is telling me that the sync is being
performed successfully, but it does not appear that any data is being
exchanged, and no data appears in any of the relevant Horde
applications.

I have been showing some PHP errors which do not otherwise appear to
impact performance. I have tried reinstalling the PHP5 package, but
this does not seem to make a difference.

Any suggestions would be much appreciated.

Thanks,

Bill Day

williamson.day at gmail.com

Recent output of /var/log/phperrors.log

[02-May-2008 23:40:17] PHP Warning:  PHP Startup: Unable to load
dynamic library '/usr/lib/php5/20060613+lfs/ps.so' -
/usr/lib/php5/20060613+lfs/ps.so: cannot open shared object file: No
such file or directory in Unknown on line 0
[03-May-2008 00:23:29] PHP Warning:  PHP Startup: Unable to load
dynamic library '/usr/lib/php5/20060613+lfs/imagick.so' -
libWand.so.9: cannot open shared object file: No such file or
directory in Unknown on line 0
[03-May-2008 00:23:29] PHP Warning:  PHP Startup: Unable to load
dynamic library '/usr/lib/php5/20060613+lfs/ming.so' -
/usr/lib/php5/20060613+lfs/ming.so: cannot open shared object file: No
such file or directory in Unknown on line 0
[03-May-2008 00:23:29] PHP Warning:  PHP Startup: Unable to load
dynamic library '/usr/lib/php5/20060613+lfs/ps.so' -
/usr/lib/php5/20060613+lfs/ps.so: cannot open shared object file: No
such file or directory in Unknown on line 0
[03-May-2008 00:30:42] PHP Warning:  PHP Startup: Unable to load
dynamic library '/usr/lib/php5/20060613+lfs/imagick.so' -
libWand.so.9: cannot open shared object file: No such file or
directory in Unknown on line 0
[03-May-2008 00:30:42] PHP Warning:  PHP Startup: Unable to load
dynamic library '/usr/lib/php5/20060613+lfs/ming.so' -
/usr/lib/php5/20060613+lfs/ming.so: cannot open shared object file: No
such file or directory in Unknown on line 0
[03-May-2008 00:30:43] PHP Warning:  PHP Startup: Unable to load
dynamic library '/usr/lib/php5/20060613+lfs/ps.so' -
/usr/lib/php5/20060613+lfs/ps.so: cannot open shared object file: No
such file or directory in Unknown on line 0

Output of /tmp/sync/log.txt

DEBUG: Backend of class SyncML_Backend_Horde created
DEBUG: Started at 2008-05-03 13:12:12. Packet logged in
/tmp/sync/client_80.wbxml
DEBUG: New session created: ebb81dd87034e7792d0a98f463a798bc
DEBUG: Checking authentication for user billday
DEBUG: Authenticated: yes; version: 1.1; message ID: 1; source URI:
PN70UBL5V0W8; target URI: https://192.168.1.57:443/horde/rpc.php;
user: billday; charset: UTF-8; wbxml: yes
ERR: Invalid database contacts
contacts. Try tasks, calendar, notes or contacts.
ERR: Invalid database calendar
event. Try tasks, calendar, notes or contacts.
DEBUG: SQL Query by SyncML_Backend_Horde::readSyncAnchors(): SELECT
syncml_clientanchor, syncml_serveranchor FROM horde_syncml_anchors
WHERE syncml_syncpartner = ? AND syncml_db = ? AND syncml_uid = ?,
values: PN70UBL5V0W8, tasks, billday
DEBUG: Previous sync found for database tasks; client timestamp:
20080503T170541Z
DEBUG: Anchor timestamps match, TwoWaySync possible. Syncing data
since 1970-08-21 05:55:03
DEBUG: Creating SyncML_Sync object for database tasks; sync type 200
DEBUG: SQL Query by SyncML_Backend_Horde::readSyncAnchors(): SELECT
syncml_clientanchor, syncml_serveranchor FROM horde_syncml_anchors
WHERE syncml_syncpartner = ? AND syncml_db = ? AND syncml_uid = ?,
values: PN70UBL5V0W8, notes, billday
DEBUG: Previous sync found for database notes; client timestamp:
20080503T170541Z
DEBUG: Anchor timestamps match, TwoWaySync possible. Syncing data
since 1970-08-21 05:55:03
DEBUG: Creating SyncML_Sync object for database notes; sync type 200
DEBUG: Received <Final> from client.
DEBUG: Handle <Final> for state Init
DEBUG: Handle <Final> for state Init
DEBUG: Sending <Final> to client
DEBUG: Return message completed
DEBUG: Finished at 2008-05-03 13:12:17. Packet logged in
/tmp/sync/server_80.wbxml
WARNING:Caught output: 
Notice: A non well formed numeric value encountered in
/usr/share/php/SyncML/Command/Alert.php on line 178 
 
Notice: A non well formed numeric value encountered in
/usr/share/php/SyncML/Command/Alert.php on line 178

DEBUG: Backend of class SyncML_Backend_Horde created
DEBUG: Started at 2008-05-03 13:12:18. Packet logged in
/tmp/sync/client_81.wbxml
DEBUG: Existing session continued: ebb81dd87034e7792d0a98f463a798bc
DEBUG: Authenticated: yes; version: 1.1; message ID: 2; source URI:
PN70UBL5V0W8; target URI: https://192.168.1.57:443/horde/rpc.php;
user: billday; charset: UTF-8; wbxml: yes
DEBUG: Created device class SyncML_Device_Synthesis
DEBUG: Created device class SyncML_Device_Synthesis
DEBUG: Received <Final> from client.
DEBUG: Handle <Final> for state Sync
DEBUG: Creating <Sync> output for server changes in database tasks
DEBUG: Created device class SyncML_Device_Synthesis
DEBUG: Compiling server changes from 2008-05-03 13:05:50 to 2008-05-03 13:12:17
DEBUG: Sending 0 server changes for client URI tasks
DEBUG: Handle <Final> for state Sync
DEBUG: Creating <Sync> output for server changes in database notes
DEBUG: Created device class SyncML_Device_Synthesis
DEBUG: Compiling server changes from 2008-05-03 13:05:50 to 2008-05-03 13:12:17
DEBUG: Sending 0 server changes for client URI notes
DEBUG: Sending <Final> to client
DEBUG: Return message completed
DEBUG: Finished at 2008-05-03 13:12:18. Packet logged in
/tmp/sync/server_81.wbxml

DEBUG: Backend of class SyncML_Backend_Horde created
DEBUG: Started at 2008-05-03 13:12:18. Packet logged in
/tmp/sync/client_82.wbxml
DEBUG: Existing session continued: ebb81dd87034e7792d0a98f463a798bc
DEBUG: Authenticated: yes; version: 1.1; message ID: 3; source URI:
PN70UBL5V0W8; target URI: https://192.168.1.57:443/horde/rpc.php;
user: billday; charset: UTF-8; wbxml: yes
DEBUG: Received <Final> from client.
DEBUG: Handle <Final> for state Completed
DEBUG: Handle <Final> for state Completed
DEBUG: Sending <Final> to client
DEBUG: SQL Query by SyncML_Backend_Horde::readSyncAnchors(): SELECT
syncml_clientanchor, syncml_serveranchor FROM horde_syncml_anchors
WHERE syncml_syncpartner = ? AND syncml_db = ? AND syncml_uid = ?,
values: PN70UBL5V0W8, tasks, billday
DEBUG: SQL Query by SyncML_Backend_Horde::writeSyncAnchors(): UPDATE
horde_syncml_anchors SET syncml_clientanchor = ?, syncml_serveranchor
= ? WHERE syncml_syncpartner = ? AND syncml_db = ? AND syncml_uid = ?,
values: 20080503T171204Z, 1209834737, PN70UBL5V0W8, tasks, billday
INFO: Successful sync of database tasks. Failures: 0; changes from
client (Add, Replace, Delete, AddReplaces): 0, 0, 0, 0; changes from
server (Add, Replace, Delete): 0, 0, 0
DEBUG: SQL Query by SyncML_Backend_Horde::readSyncAnchors(): SELECT
syncml_clientanchor, syncml_serveranchor FROM horde_syncml_anchors
WHERE syncml_syncpartner = ? AND syncml_db = ? AND syncml_uid = ?,
values: PN70UBL5V0W8, notes, billday
DEBUG: SQL Query by SyncML_Backend_Horde::writeSyncAnchors(): UPDATE
horde_syncml_anchors SET syncml_clientanchor = ?, syncml_serveranchor
= ? WHERE syncml_syncpartner = ? AND syncml_db = ? AND syncml_uid = ?,
values: 20080503T171204Z, 1209834737, PN70UBL5V0W8, notes, billday
INFO: Successful sync of database notes. Failures: 0; changes from
client (Add, Replace, Delete, AddReplaces): 0, 0, 0, 0; changes from
server (Add, Replace, Delete): 0, 0, 0
DEBUG: Finished at 2008-05-03 13:12:18. Packet logged in
/tmp/sync/server_82.wbxml

-- 
Bill Day
williamson.day at gmail.com
PGP Fingerprint: EE5D DE55 9EF1 E012 7417
A5F1 1D7D 0847 7785 1146