Chris,
Using you handy command to dump the addressbook, I attempted a new
one-way sync using the following command with standard group setup.
msynctool --sync EvoBarry --filter-objtype note --filter-objtype todo
--filter-objtype event --conflict i
The process hung on exactly the same record as i referenced in the
previous thread, but with OSYNC_TRACE enabled (which I noted was
conveniently mentioned on the man page) I was able to identify the
record where the pause/freeze occurs.
As you noted, my issue does not appear to be a specific record I can
identify. I removed the last record in the output, only to have it
pause on what was before the second last record.. I continued to remove
records, only to have it pause/jam up sooner. (i.e. as I removed
records, no additional records were written to the BBerry, which I found
strange)
As no additional records were written, I assume it is unlikely to be a
time_out type of issue.
Additionally I removed 5 other records that were generating
"xmlEscapeEntities : char out of range" to rule them out, although they
have been around before during successful syncs.
I have full TRACE logs with sensitivity turned on (7MB compressed) if
they would be useful, though I found no errors other then some notes
"too long for buffer" and a couple of ERRORS related to the filters
"Unable to find sink with name "todo" "
So now I am stuck with no way to sync my Evo to my empty Berry, looking
for advice on next steps.
At the point of the freeze, as shown below, the memory consumption is
not expanding (as I may have suggested previously), but the CPU begins
to move up to 100% over time.
i...@n8-laptop:~$ date && ps aux | grep osplugin | grep -v grep
Fri Nov 27 08:46:03 EST 2009
imac 5892 3.0 0.7 292296 22304 pts/1 Sl+ 08:40 0:09
/usr/lib/opensync/osplugin /home/imac/.opensync-0.22/group2 1
imac 5901 17.1 0.2 160056 5908 pts/1 Rl+ 08:40 0:55
/usr/lib/opensync/osplugin /home/imac/.opensync-0.22/group2 2
i...@n8-laptop:~$ date && ps aux | grep osplugin | grep -v grep
Fri Nov 27 08:46:32 EST 2009
imac 5892 3.0 0.7 292296 22304 pts/1 Sl+ 08:40 0:10
/usr/lib/opensync/osplugin /home/imac/.opensync-0.22/group2 1
imac 5901 21.9 0.2 160056 5908 pts/1 Rl+ 08:40 1:15
/usr/lib/opensync/osplugin /home/imac/.opensync-0.22/group2 2
i...@n8-laptop:~$ date && ps aux | grep osplugin | grep -v grep
Fri Nov 27 08:47:02 EST 2009
imac 5892 2.9 0.7 292296 22304 pts/1 Rl+ 08:40 0:11
/usr/lib/opensync/osplugin /home/imac/.opensync-0.22/group2 1
imac 5901 28.1 0.2 160056 5908 pts/1 Rl+ 08:40 1:45
/usr/lib/opensync/osplugin /home/imac/.opensync-0.22/group2 2
i...@n8-laptop:~$ date && ps aux | grep osplugin | grep -v grep
Fri Nov 27 08:47:21 EST 2009
imac 5892 2.9 0.7 292296 22304 pts/1 Rl+ 08:40 0:11
/usr/lib/opensync/osplugin /home/imac/.opensync-0.22/group2 1
imac 5901 31.4 0.2 160056 5908 pts/1 Rl+ 08:40 2:03
/usr/lib/opensync/osplugin /home/imac/.opensync-0.22/group2 2
i...@n8-laptop:~$ date && ps aux | grep osplugin | grep -v grep
Fri Nov 27 09:01:49 EST 2009
imac 5892 2.8 0.7 292296 22304 pts/1 Rl+ 08:40 0:36
/usr/lib/opensync/osplugin /home/imac/.opensync-0.22/group2 1
imac 5901 78.3 0.2 160056 5908 pts/1 Rl+ 08:40 16:27
/usr/lib/opensync/osplugin /home/imac/.opensync-0.22/group2 2
i...@n8-laptop:~$
End of the traces mentioned above do not have anything that jumps out,
tail'd below.
i...@n8-laptop:/tmp/barry$ tail *
==> Thread140012965181168-14820.log <==
[1259339980.889318]
barry_sync: attr value:
[1259339980.889403]
<<<<<<< barry_sync: vBase::GetAttr
[1259339980.889444]
>>>>>>> barry_sync: vBase::GetAttrObj
[1259339980.889486]
barry_sync: getting attr: CATEGORIES
[1259339980.889529]
<<<<<<< barry_sync: vBase::GetAttrObj
[1259339980.889579]
<<<<<<< barry_sync: vCard::ToBarry
[1259339980.889688] <<<<<<<
barry_sync: VCardConverter::ParseData
[1259339980.889774]
barry_sync: adding record
[1259339980.889876] >>>>>>>
barry_sync: VCardConverter::builder operator()
[1259339980.889960] <<<<<<<
barry_sync: VCardConverter::builder operator()
==> Thread140157115729648-14813.log <==
[1259339821.346620] >>>>>>>
osync_context_report_success(0x22b7ae0)
[1259339821.346673] >>>>>>>
message_callback(0x2299670, 0x22b6920, (nil))
[1259339821.346730] [CLI]
FULL DEBUG: Member is replying with message 0x22c1820 to message
0x2e7ddb0:"1259339821345949--127976996" with no error
[1259339821.346779] >>>>>>>
osync_queue_send_message(0x229a7d0, (nil), 0x22c1820, (nil))
[1259339821.346831] <<<<<<<
osync_queue_send_message
[1259339821.346874] <<<<<<<
message_callback
[1259339821.346917] <<<<<<<
osync_context_report_success
[1259339821.346960] <<<<<<<
osync_member_committed_all
[1259339821.347002] <<<<<<< message_handler
[1259339821.347046] <<<<<<< _incoming_dispatch: Done dispatching
==> Thread140599517157648-14812.log <==
[1259339980.902932] >>>>>>>
osync_change_get_objformat(0x7fdfda9c76c0)
[1259339980.902955] <<<<<<<
osync_change_get_objformat: 0x1b21f40
[1259339980.903075] query executed: UPDATE
tbl_changes SET uid='pas-id-4AC3823200000007', objtype='contact',
format='vcard30', memberid='2', mappingid='1003' WHERE id=2654
[1259339980.959992] <<<<<<< osync_db_save_change
[1259339980.960077] >>>>>>>
osengine_mappingentry_decider(0x1b27a00, 0x7fdfda9c7290)
[1259339980.960104]
ENG(RUN1,STOP1,SENT1,READ1,MAP1,CHK1,MUL1,SYNC0,COMMITTED1)
[1259339980.960132]
ENT(DATA1,DRY0,MAP1,INFO1,SYNC1,DEL0)
[1259339980.960159] <<<<<<< osengine_mappingentry_decider:
Waste
[1259339980.960183] <<<<<<< _commit_change_reply_receiver
[1259339980.960213] <<<<<<< _incoming_dispatch: Done dispatching
==> Thread140599769052912-14812.log <==
[1259339728.339794] 0x1b63cc0 handler to
0x1b26f50
[1259339728.339879] >>>>>>>
osync_queue_send_message_with_timeout(0x1b49a00, 0x1b63cc0, (nil))
[1259339728.339964] >>>>>>>
osync_queue_send_message(0x1b49a00, 0x1b27470, 0x1b63cc0, (nil))
[1259339728.340018] <<<<<<<
osync_queue_send_message
[1259339728.340102] <<<<<<<
osync_queue_send_message_with_timeout: (null)
[1259339728.340187] <<<<<<< osync_client_connect
[1259339728.340271] <<<<<<< osengine_client_decider
[1259339728.340355] >>>>>>>
osync_queue_send_message(0x1b50c40, (nil), 0x1b65360, 0x7fff72d243a8)
[1259339728.340440] <<<<<<< osync_queue_send_message
[1259339728.340486] <<<<<<< osengine_synchronize
cheers,
Ian
On Thu, 2009-11-26 at 21:41 +0000,
barry-devel-requ...@lists.sourceforge.net wrote:
I would enable opensync debug tracing (set the OSYNC_TRACE environment
> variable to the name of an empty directory) and then while it's
> in the busy loop, grep the trace logs. The data of the record should
> be in the logs.
--
Ian B. MacDonald <ian.macdon...@n8id.com>
Director of Product Management
N8 Identity Inc.
W:(416)800-2209
M:(416)988-0856
------------------------------------------------------------------------------
Let Crystal Reports handle the reporting - Free Crystal Reports 2008 30-Day
trial. Simplify your report design, integration and deployment - and focus on
what you do best, core application coding. Discover what's new with
Crystal Reports now. http://p.sf.net/sfu/bobj-july
_______________________________________________
Barry-devel mailing list
Barry-devel@lists.sourceforge.net
https://lists.sourceforge.net/lists/listinfo/barry-devel
