Sync: Error while syncing - Unknown Error
Firefox 3.6.13 is on 2 XP computers, in home and in office. Both have Sync 1.6.1 with the same settings and same key. One works ok, but another fails to update every time. Tried with all add-ons but Sync disabled, did not help. Un/reinstalled Sync, did not help. Follows log file.
2011-01-01 14:09:12 Service.Main INFO Loading Weave 1.6.1
2011-01-01 14:09:12 Engine.Bookmarks DEBUG Engine initialized
2011-01-01 14:09:12 Engine.Forms DEBUG Engine initialized
2011-01-01 14:09:12 Engine.History DEBUG Engine initialized
2011-01-01 14:09:12 Engine.Passwords DEBUG Engine initialized
2011-01-01 14:09:12 Engine.Prefs DEBUG Engine initialized
2011-01-01 14:09:12 Engine.Tabs DEBUG Engine initialized
2011-01-01 14:09:12 Engine.Tabs DEBUG Resetting tabs last sync time
2011-01-01 14:09:12 Service.Main INFO Mozilla/5.0 (Windows; U; Windows NT 5.1; en-US; rv:1.9.2.13) Gecko/20101203 Firefox/3.6.13
2011-01-01 14:09:12 Service.Main DEBUG Caching URLs under storage user base: https://phx-sync068.services.mozilla.com/1.0/vrapp/
2011-01-01 14:09:19 Service.Main INFO Logging in user vrapp
2011-01-01 14:09:19 Net.Resource DEBUG GET success 200 https://phx-sync068.services.mozilla.com/1.0/vrapp/info/collections
2011-01-01 14:09:19 Service.Main DEBUG Fetching global metadata record
2011-01-01 14:09:19 Net.Resource DEBUG GET success 200 https://phx-sync068.services.mozilla.com/1.0/vrapp/storage/meta/global
2011-01-01 14:09:19 Service.Main DEBUG Weave Version: 1.6.1 Local Storage: 5 Remote Storage: 5
2011-01-01 14:09:19 Service.Main INFO Sync key is up-to-date: no need to upgrade.
2011-01-01 14:09:19 Service.Main DEBUG Fetching and verifying -- or generating -- symmetric keys.
2011-01-01 14:09:20 Net.Resource DEBUG GET success 200 https://phx-sync068.services.mozilla.com/1.0/vrapp/info/collections
2011-01-01 14:09:20 Service.Main INFO Testing info/collections: {"clients":1293912456.2,"crypto":1293123687.96,"forms":1293912456.72,"history":1293912457.28,"keys":1291348530.74,"meta":1293123923.44,"bookmarks":1293907362.65,"prefs":1293907615.76,"tabs":1293912459.01,"passwords":1293908857.35}
2011-01-01 14:09:20 CollectionKeys INFO Testing for updateNeeded. Last modified: 0
2011-01-01 14:09:20 Service.Main INFO CollectionKeys reports that a key update is needed.
2011-01-01 14:09:20 Net.Resource DEBUG GET success 200 https://phx-sync068.services.mozilla.com/1.0/vrapp/storage/crypto/keys
2011-01-01 14:09:20 CollectionKeys INFO Updating collection keys...
2011-01-01 14:09:20 CollectionKeys INFO Setting CollectionKeys contents. Our last modified: 0, input modified: 1293123687.96.
2011-01-01 14:09:20 BulkKeyBundle INFO BulkKeyBundle being created for [default]
2011-01-01 14:09:20 CollectionKeys INFO Processing downloaded per-collection keys.
2011-01-01 14:09:20 CollectionKeys INFO Clearing CollectionKeys...
2011-01-01 14:09:20 CollectionKeys INFO Saving downloaded keys.
2011-01-01 14:09:20 CollectionKeys INFO Bumping last modified to 1293123687.96
2011-01-01 14:09:20 CollectionKeys INFO Collection keys updated.
2011-01-01 14:09:23 Service.Main INFO In sync().
2011-01-01 14:09:23 Net.Resource DEBUG GET success 200 https://phx-sync068.services.mozilla.com/1.0/vrapp/info/collections
2011-01-01 14:09:23 Service.Main DEBUG Fetching global metadata record
2011-01-01 14:09:23 Service.Main DEBUG Clearing cached meta record. metaModified is undefined, setting to 1293123923.44
2011-01-01 14:09:23 Net.Resource DEBUG GET success 200 https://phx-sync068.services.mozilla.com/1.0/vrapp/storage/meta/global
2011-01-01 14:09:23 Service.Main DEBUG Weave Version: 1.6.1 Local Storage: 5 Remote Storage: 5
2011-01-01 14:09:23 Service.Main INFO Sync key is up-to-date: no need to upgrade.
2011-01-01 14:09:23 Service.Main DEBUG Fetching and verifying -- or generating -- symmetric keys.
2011-01-01 14:09:23 Service.Main INFO Testing info/collections: {"clients":1293912456.2,"crypto":1293123687.96,"forms":1293912456.72,"history":1293912457.28,"keys":1291348530.74,"meta":1293123923.44,"bookmarks":1293907362.65,"prefs":1293907615.76,"tabs":1293912459.01,"passwords":1293908857.35}
2011-01-01 14:09:23 CollectionKeys INFO Testing for updateNeeded. Last modified: 1293123687.96
2011-01-01 14:09:23 Engine.Clients DEBUG First sync, uploading all items
2011-01-01 14:09:23 Engine.Clients INFO 1 outgoing items pre-reconciliation
2011-01-01 14:09:23 Collection DEBUG GET success 200 https://phx-sync068.services.mozilla.com/1.0/vrapp/storage/clients?full=1
2011-01-01 14:09:23 Engine.Clients INFO Records: 2 applied, 0 reconciled.
2011-01-01 14:09:23 Engine.Clients INFO Uploading all of 1 records
2011-01-01 14:09:23 Collection DEBUG POST Length: 277
2011-01-01 14:09:23 Collection DEBUG POST success 200 https://phx-sync068.services.mozilla.com/1.0/vrapp/storage/clients
2011-01-01 14:09:23 Engine.Clients DEBUG Total (ms): sync 209, processIncoming 91, uploadOutgoing 117, syncStartup 1, syncFinish 0, reconcile 0, createRecord 0, syncCleanup 0
2011-01-01 14:09:23 Service.Main INFO Updating enabled engines: 2 clients.
2011-01-01 14:09:23 Service.Engines DEBUG Could not get engine: clients
2011-01-01 14:09:23 Engine.Bookmarks INFO 0 outgoing items pre-reconciliation
2011-01-01 14:09:23 Engine.Bookmarks INFO Records: 0 applied, 0 reconciled.
2011-01-01 14:09:23 Engine.Bookmarks DEBUG Total (ms): sync 4, processIncoming 1, uploadOutgoing 0, syncStartup 0, syncFinish 1, getChangedIDs 0, syncCleanup 0
2011-01-01 14:09:23 Engine.Forms INFO 0 outgoing items pre-reconciliation
2011-01-01 14:09:23 Engine.Forms INFO Records: 0 applied, 0 reconciled.
2011-01-01 14:09:23 Engine.Forms DEBUG Total (ms): sync 0, processIncoming 0, uploadOutgoing 0, syncStartup 0, syncFinish 0, getChangedIDs 0, syncCleanup 0
2011-01-01 14:09:23 Engine.History INFO 2 outgoing items pre-reconciliation
2011-01-01 14:09:23 Engine.History INFO Records: 0 applied, 0 reconciled.
2011-01-01 14:09:23 Engine.History INFO Uploading all of 2 records
2011-01-01 14:09:23 Collection DEBUG POST Length: 2087
2011-01-01 14:09:24 Collection DEBUG POST success 200 https://phx-sync068.services.mozilla.com/1.0/vrapp/storage/history
2011-01-01 14:09:24 Engine.History DEBUG Total (ms): sync 164, processIncoming 0, uploadOutgoing 163, syncStartup 1, syncFinish 0, getChangedIDs 0, createRecord 30, syncCleanup 0
2011-01-01 14:09:24 Engine.Passwords DEBUG First sync, uploading all items
2011-01-01 14:09:24 Engine.Passwords INFO 407 outgoing items pre-reconciliation
2011-01-01 14:09:25 Store.Passwords DEBUG Adding login for sync.xmarks.com
2011-01-01 14:09:25 Engine.Passwords DEBUG Total (ms): sync 1327, processIncoming 916, syncStartup 410, findDupe 1, createRecord 189, isEqual 191, reconcile 198, syncCleanup 1
2011-01-01 14:09:25 Service.Main DEBUG passwords failed: NS_ERROR_XPC_JS_THREW_STRING JS Stack trace: Channel_onStopRequest([object XPCWrappedNative_NoHelper],null,2153185310)@resource.js:438 < Res__request(...)@resource.js:357 < Res_get()@resource.js:376 < SyncEngine__processIncoming()@engines.js:511 < ()@engines.js:203 < SyncEngine__sync()@engines.js:768 < ()@engines.js:203 < WrappedNotify()@util.js:147 < Engine_sync()@engines.js:213 < WeaveSvc__syncEngine([object Object])@service.js:1738 < ()@service.js:1624 < WrappedNotify()@util.js:147 < WrappedLock()@util.js:119 < WrappedCatch()@util.js:97 < sync()@service.js:1529 < (5)@sync.js:380
2011-01-01 14:09:25 Engine.Prefs INFO 0 outgoing items pre-reconciliation
2011-01-01 14:09:25 Engine.Prefs INFO Records: 0 applied, 0 reconciled.
2011-01-01 14:09:25 Engine.Prefs DEBUG Total (ms): sync 1, processIncoming 0, uploadOutgoing 0, syncStartup 1, syncFinish 0, getChangedIDs 0, syncCleanup 0
2011-01-01 14:09:25 Engine.Tabs DEBUG First sync, uploading all items
2011-01-01 14:09:25 Engine.Tabs INFO 1 outgoing items pre-reconciliation
2011-01-01 14:09:25 Store.Tabs DEBUG Adding remote tabs from vrapp's Firefox on DEVELOPER-VR
2011-01-01 14:09:25 Collection DEBUG GET success 200 https://phx-sync068.services.mozilla.com/1.0/vrapp/storage/tabs?full=1
2011-01-01 14:09:25 Engine.Tabs INFO Records: 1 applied, 1 reconciled.
2011-01-01 14:09:25 Engine.Tabs INFO Uploading all of 1 records
2011-01-01 14:09:25 Collection DEBUG POST Length: 425
2011-01-01 14:09:25 Collection DEBUG POST success 200 https://phx-sync068.services.mozilla.com/1.0/vrapp/storage/tabs
2011-01-01 14:09:25 Engine.Tabs DEBUG Total (ms): sync 410, processIncoming 301, uploadOutgoing 108, syncStartup 1, syncFinish 0, createRecord 32, findDupe 0, isEqual 25, reconcile 26, syncCleanup 0
2011-01-01 14:09:25 Service.Main DEBUG Exception: Some engines did not sync correctly No traceback available
Modified by vrapp
All Replies (7)
I am seeing the exact same symptoms as described above - two XP systems with FireFox 3.6.13 and Sync 1.6.1, but only one system will sync . Below is the info from about:sync-log from the broken machine.
Thanks…
~~~~~~~~~~~~~~~~~~~~~~~
2011-01-01 22:44:28 Service.Main INFO In sync(). 2011-01-01 22:44:28 Net.Resource DEBUG GET success 200 https://phx-sync098.services.mozilla.com/1.0/<user>/info/collections 2011-01-01 22:44:28 Service.Main DEBUG Fetching global metadata record 2011-01-01 22:44:28 Service.Main DEBUG Clearing cached meta record. metaModified is undefined, setting to 1293906137.5 2011-01-01 22:44:28 Net.Resource DEBUG GET success 200 https://phx-sync098.services.mozilla.com/1.0/<user>/storage/meta/global 2011-01-01 22:44:28 Service.Main DEBUG Weave Version: 1.6.1 Local Storage: 5 Remote Storage: 5 2011-01-01 22:44:28 Service.Main INFO Sync key is up-to-date: no need to upgrade. 2011-01-01 22:44:28 Service.Main DEBUG Fetching and verifying -- or generating -- symmetric keys. 2011-01-01 22:44:28 Service.Main INFO Testing info/collections: {"clients":1293925144.17,"crypto":1293906118.87,"keys":1291339841.4,"meta":1293906137.5,"bookmarks":1293906136.52,"prefs":1293925173.55} 2011-01-01 22:44:28 CollectionKeys INFO Testing for updateNeeded. Last modified: 1293906118.87 2011-01-01 22:44:28 Engine.Clients DEBUG First sync, uploading all items 2011-01-01 22:44:28 Engine.Clients INFO 1 outgoing items pre-reconciliation 2011-01-01 22:44:28 Collection DEBUG GET success 200 https://phx-sync098.services.mozilla.com/1.0/<user>/storage/clients?full=1 2011-01-01 22:44:28 Engine.Clients INFO Records: 2 applied, 0 reconciled. 2011-01-01 22:44:28 Engine.Clients INFO Uploading all of 1 records 2011-01-01 22:44:28 Collection DEBUG POST Length: 277 2011-01-01 22:44:28 Collection DEBUG POST success 200 https://phx-sync098.services.mozilla.com/1.0/<user>/storage/clients 2011-01-01 22:44:28 Engine.Clients DEBUG Total (ms): sync 145, processIncoming 60, uploadOutgoing 84, syncStartup 0, syncFinish 0, reconcile 0, createRecord 0, syncCleanup 0 2011-01-01 22:44:28 Service.Main INFO Updating enabled engines: 2 clients. 2011-01-01 22:44:28 Service.Engines DEBUG Could not get engine: clients 2011-01-01 22:44:28 Engine.Bookmarks DEBUG First sync, uploading all items 2011-01-01 22:44:28 Engine.Bookmarks INFO 1283 outgoing items pre-reconciliation 2011-01-01 22:44:30 Engine.Bookmarks DEBUG Total (ms): sync 1402, processIncoming 514, syncStartup 797, createRecord 403, isEqual 403, reconcile 403, syncCleanup 21 2011-01-01 22:44:30 Service.Main DEBUG bookmarks failed: NS_ERROR_MALFORMED_URI JS Stack trace: Channel_onStopRequest([object XPCWrappedNative_NoHelper],null,2152398858)@resource.js:438 < Res__request(...)@resource.js:357 < Res_get()@resource.js:376 < SyncEngine__processIncoming()@engines.js:511 < _processIncoming()@bookmarks.js:212 < ()@engines.js:203 < SyncEngine__sync()@engines.js:768 < wrappedSync(null)@util.js:168 < runInBatchMode([object Object],null)@:0 < batchedSync()@util.js:174 < ()@engines.js:203 < WrappedNotify()@util.js:147 < Engine_sync()@engines.js:213 < WeaveSvc__syncEngine([object Object])@service.js:1738 < ()@service.js:1624 < WrappedNotify()@util.js:147 < WrappedLock()@util.js:119 < WrappedCatch()@util.js:97 < sync()@service.js:1529 < (5)@sync.js:380 2011-01-01 22:44:30 Engine.Prefs INFO 0 outgoing items pre-reconciliation 2011-01-01 22:44:30 Engine.Prefs INFO Records: 0 applied, 0 reconciled. 2011-01-01 22:44:30 Engine.Prefs DEBUG Total (ms): sync 3, processIncoming 1, uploadOutgoing 0, syncStartup 1, syncFinish 0, getChangedIDs 0, syncCleanup 0 2011-01-01 22:44:30 Service.Main DEBUG Exception: Some engines did not sync correctly No traceback available
Update: I was able to resolve this problem by the following steps:
1. open Sync Preferences and uncheck all checkboxes but one 2. perform update. It performed successfully. 3. open Preferences and check another checkbox in addition
Repeat steps 2-3 until all checkboxes are checked. In the end, I had them all checked like in the beginning, and it updated successfully, no more error.
Thank you, vrapp!!! Your fix worked for me. From what I can tell, I think the history engine was the hang-up... I had 5000 entries to sync and it took quite a while, relatively, compared to a usual sync that only partially worked. (Before, I'd get bookmarks synced and other things, but got an error and the process was over quickly... this took some time to sync up the histories.)
Ah, it appears I have spoken too quickly. It did work, but now I'm having the same issue again. It wasn't the history section, though... it's a password sync fail. Perhaps it's intermittent?
I just thought it was the history section due to the time it took to upload, but it appears when you unsync and re-sync history, it does a full upload regardless of whether it was properly synced before or not.
Here's the error in the log:
2011-01-05 04:14:05 Service.Main DEBUG passwords failed: NS_ERROR_XPC_JS_THREW_STRING JS Stack trace: Res_get()@resource.js:376 < SyncEngine__processIncoming()@engines.js:511 < ()@engines.js:203 < SyncEngine__sync()@engines.js:768 < ()@engines.js:203 < WrappedNotify()@util.js:147 < Engine_sync()@engines.js:213 < WeaveSvc__syncEngine([object Object])@service.js:1738 < ()@service.js:1624 < WrappedNotify()@util.js:147 < WrappedLock()@util.js:119 < WrappedCatch()@util.js:97 < sync()@service.js:1529 < (4)@browser.js:5122
Yes, same here. It looks like showing the success after disabling/reenabling is yet another bug. It never actually synchronized the passwords.
I suggest you join the bug report thread at https://bugzilla.mozilla.org/show_bug.cgi?id=614984 and add your vote for importance. So far it wasn't addressed, but maybe more votes will make it.
RESOLVED (for me at least :)) )
After update of Firefox Sync it set's up a default "Computer name", which happened to be same on all of my three computers, something alike: <user name> on Windows NT5.1
Therefore there was a conflict as the server doesn't recognize computer as unique entity.
As soon as I gave unique name to my computers (Tools --> Sync ---> Preferences ---> Computer Name) sync started to work as a charm :)))
Hope this will help to you to...
Kind regards, Nenad
nenad -- tried changing the name to no avail. My names were different to begin with, though.
vrapp -- excellent suggestion. I've voted on the bug and submitted the relevant portion of the log.