Closed Bug 620901 Opened 14 years ago Closed 13 years ago

sync gives JS errors and fails, NS_ERROR_FAILURE

Categories

(Firefox :: Sync, defect)

x86
macOS
defect
Not set
normal

Tracking

()

VERIFIED FIXED

People

(Reporter: augustmiles, Unassigned)

References

Details

User-Agent:       Mozilla/5.0 (Macintosh; Intel Mac OS X 10.6; rv:2.0b9pre) Gecko/20101221 Firefox/4.0b9pre
Build Identifier: Mozilla/5.0 (Macintosh; Intel Mac OS X 10.6; rv:2.0b9pre) Gecko/20101221 Firefox/4.0b9pre

I am sync'ing between two macs, it has been failing for some weeks. Both machines
running nightlies.

Reproducible: Always

Steps to Reproduce:
1. Launch sync on office machine, from tools
2. wait 10 seconds, see "sync encountered and error while syncing: Unkonwn error"
3. Read Sync log (previously turning it on)
4. go back home, find that deleted bookmarks are still there
Actual Results:  
sync error

Expected Results:  
bookmarks should sync

sync log:
2010-12-22 10:16:59	Service.Engines      DEBUG	Engines are: ["bookmarks","forms","history","passwords","prefs","tabs"]
2010-12-22 10:16:59	Engine.Bookmarks     INFO	22 outgoing items pre-reconciliation
2010-12-22 10:16:59	Store.Bookmarks      DEBUG	Item -0-oSVJmIWjz already removed
2010-12-22 10:17:03	Store.Bookmarks      DEBUG	Item 0uV_F9fsrE-n already removed
2010-12-22 10:17:04	Store.Bookmarks      DEBUG	Item 4Ejfmhko8fwL already removed
2010-12-22 10:17:06	Store.Bookmarks      DEBUG	Item 9DkA5PI3Y2Fe already removed
2010-12-22 10:17:07	Store.Bookmarks      DEBUG	Item EH9CPoaBbOFU already removed
2010-12-22 10:17:09	Store.Bookmarks      DEBUG	Item H14Vszh6qwuJ already removed
2010-12-22 10:17:09	Store.Bookmarks      DEBUG	Item H1vjx0Y4M7Gr already removed
2010-12-22 10:17:09	Store.Bookmarks      DEBUG	Item I2xwVo9pgrgJ already removed
2010-12-22 10:17:09	Store.Bookmarks      DEBUG	Item JirpxYzsbUPl already removed
2010-12-22 10:17:10	Store.Bookmarks      DEBUG	Item MtyFLo_2PptU already removed
2010-12-22 10:17:11	Store.Bookmarks      DEBUG	Item PNP2e1dy2Cyn already removed
2010-12-22 10:17:11	Store.Bookmarks      DEBUG	Item PPOKUhA73bG9 already removed
2010-12-22 10:17:15	Store.Bookmarks      DEBUG	Item _vUYauEsKNvA already removed
2010-12-22 10:17:16	Store.Bookmarks      DEBUG	Item dFoMMmt_qq6A already removed
2010-12-22 10:17:17	Store.Bookmarks      DEBUG	Item gixTPYYDfRaK already removed
2010-12-22 10:17:19	Store.Bookmarks      DEBUG	Item omLUXOhu5taB already removed
2010-12-22 10:17:19	Store.Bookmarks      DEBUG	Item p4BVlLB1fMIM already removed
2010-12-22 10:17:21	Store.Bookmarks      DEBUG	Processing special node: toolbar
2010-12-22 10:17:21	Store.Bookmarks      DEBUG	Processing special node: unfiled
2010-12-22 10:17:21	Engine.Bookmarks     DEBUG	Total (ms): sync 22262, processIncoming 22220, syncStartup 1, createRecord 14877, getChangedIDs 0, isEqual 14978, reconcile 16534, syncCleanup 1
2010-12-22 10:17:21	Service.Main         DEBUG	bookmarks failed: NS_ERROR_FAILURE JS Stack trace: Res_get()@resource.js:376 < SyncEngine__processIncoming()@engines.js:511 < _processIncoming()@bookmarks.js:213 < ()@engines.js:203 < SyncEngine__sync()@engines.js:768 < wrappedSync(null)@util.js:168 < 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 < (3)@browser.js:5201
2010-12-22 10:17:21	Engine.Passwords     INFO	0 outgoing items pre-reconciliation
2010-12-22 10:17:21	Engine.Passwords     INFO	Records: 0 applied, 0 reconciled.
2010-12-22 10:17:21	Engine.Passwords     DEBUG	Total (ms): sync 1, processIncoming 0, uploadOutgoing 0, syncStartup 1, syncFinish 0, getChangedIDs 0, syncCleanup 0
2010-12-22 10:17:21	Engine.Prefs         INFO	0 outgoing items pre-reconciliation
2010-12-22 10:17:21	Engine.Prefs         INFO	Records: 0 applied, 0 reconciled.
2010-12-22 10:17:21	Engine.Prefs         DEBUG	Total (ms): sync 0, processIncoming 0, uploadOutgoing 0, syncStartup 0, syncFinish 0, getChangedIDs 0, syncCleanup 0
2010-12-22 10:17:21	Service.Main         DEBUG	Exception: Some engines did not sync correctly No traceback available
Component: General → Firefox Sync: Backend
Product: Firefox → Mozilla Services
QA Contact: general → sync-backend
Blocks: 621048
This seems to be an intermittent, incomprehensible problem inside the HTTP channel. Tracking in Bug 621048.
Status: UNCONFIRMED → NEW
Ever confirmed: true
I went back to firefox4b8, recent Imac, OSX 10.6.5

1) On first run it deleted all my bookmarks (all 5000)
2) I then sync'ed again
3) Similar problems so unlike comment in bug 621048 it
is older than than the b9 branch

2010-12-23 10:41:43	Engine.Bookmarks     DEBUG	Total (ms): sync 49482, processIncoming 49463, syncStartup 0, createRecord 27775, getChangedIDs 0, isEqual 27905, reconcile 36379, syncCleanup 1
2010-12-23 10:41:43	Service.Main         DEBUG	bookmarks failed: NS_ERROR_FAILURE JS Stack trace: 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 < 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 < (30)@browser.js:5122
2010-12-23 10:41:43	Engine.Passwords     INFO	0 outgoing items pre-reconciliation
2010-12-23 10:41:43	Engine.Passwords     INFO	Records: 0 applied, 0 reconciled.
2010-12-23 10:41:43	Engine.Passwords     DEBUG	Total (ms): sync 1, processIncoming 0, uploadOutgoing 0, syncStartup 1, syncFinish 0, getChangedIDs 0, syncCleanup 0
2010-12-23 10:41:43	Engine.Prefs         INFO	0 outgoing items pre-reconciliation
2010-12-23 10:41:43	Engine.Prefs         INFO	Records: 0 applied, 0 reconciled.
2010-12-23 10:41:43	Engine.Prefs         DEBUG	Total (ms): sync 1, processIncoming 1, uploadOutgoing 0, syncStartup 0, syncFinish 0, getChangedIDs 0, syncCleanup 0
2010-12-23 10:41:43	Service.Main         DEBUG	Exception: Some engines did not sync correctly No traceback available
AM, it looks like you can reproduce this. I believe the problem is within the bookmarks engine. Can you please set the bookmarks engine logging (services.sync.log.logger.engine.bookmarks) to Trace, restart, and sync again? If the error pops up again, please post the log. Thanks!
Some Partial results: The log gets truncated, so I can't get the whole thing
in one go.
Is there an option to keep more lines in the log?

1) Launch sync
2) visit about:sync-log after a few seconds
3) Copy some of what I see 
4) If I wait too long I only see the same errors that I reported above

First try
---------------------


2011-01-03 11:22:27	Engine.Bookmarks     TRACE	Mapped: Unsorted Bookmarks,bhttp://www.nytimes.com/slideshow/2009/08/20/technology/personaltech/20090820-pogue-slideshow_2.html:No Flash? No Problem. - The New York Times > Personal Tech > Slide Show > Slide 2 of 13,8O8-BZeEd6fv,false
2011-01-03 11:22:27	Engine.Bookmarks     TRACE	Mapped: Unsorted Bookmarks,bhttp://nikonrumors.com/:Nikon Rumors,6zH_26uspqCQ,false
2011-01-03 11:22:27	Engine.Bookmarks     TRACE	Mapped: Unsorted Bookmarks,fmusic,VsgTfvjpYzJ6,false
2011-01-03 11:22:27	Engine.Bookmarks     TRACE	Mapped: Unsorted Bookmarks,fbacteria,G7O2F88TTZht,true
2011-01-03 11:22:27	Engine.Bookmarks     TRACE	Mapped: Unsorted Bookmarks,fhamaker,80UZP-VEEtze,true
2011-01-03 11:22:27	Engine.Bookmarks     TRACE	Mapped: Unsorted Bookmarks,fgigli,L8U2WPpDpzEe,true
2011-01-03 11:22:27	Engine.Bookmarks     TRACE	Mapped: Unsorted Bookmarks,flupu chatelet,4yzsxrj3EClz,true
2011-01-03 

------------------------------
Second try
------------------------------

 index: 0
  modified: 1292953626.64
  payload: DELETED
  collection: bookmarks }
2011-01-03 11:27:36	Engine.Bookmarks     TRACE	Reconcile step 1: Check for conflicts
2011-01-03 11:27:36	Engine.Bookmarks     TRACE	Reconcile step 2: Check for updates
2011-01-03 11:27:36	Engine.Bookmarks     TRACE	Reconcile step 2.5: Don't dupe deletes
2011-01-03 11:27:36	Store.Bookmarks      DEBUG	Item -0-oSVJmIWjz already removed
2011-01-03 11:27:36	Engine.Bookmarks     TRACE	Incoming: { id: -0ow6OViPT5S
  index: 1000000
  modified: 1292915618.5
  payload: {"id":"-0ow6OViPT5S","type":"folder","parentName":"Unsorted Bookmarks","title":"boundary conditions","children":[],"parentid":"unfiled","hasDupe":true}
  collection: bookmarks }
2011-01-03 11:27:36	Engine.Bookmarks     TRACE	Reconcile step 1: Check for conflicts
-------------------------------------
A better trace:

----------------------------------
  index: 140
  modified: 1292915701.26
  payload: {"id":"4qCS3qkxeOmG","type":"bookmark","title":"Living in Santa Barbara","parentName":"Santa Barbara","bmkUri":"http://www.esm.ucsb.edu/admissions/livingsb.htm","tags":[],"keyword":null,"loadInSidebar":false,"parentid":"AzX5_o7rTg3Z"}
  collection: bookmarks }
2011-01-03 11:47:25	Engine.Bookmarks     TRACE	Reconcile step 1: Check for conflicts
2011-01-03 11:47:25	Engine.Bookmarks     TRACE	Finding mapping: Santa Barbara, fLiving in Santa Barbara
2011-01-03 11:47:25	Engine.Bookmarks     TRACE	Mapped dupe: 4qCS3qkxeOmG
2011-01-03 11:47:25	Engine.Bookmarks     TRACE	Local record: { id: 4qCS3qkxeOmG
  index: 1000000
  modified: undefined
  payload: {"id":"4qCS3qkxeOmG","type":"folder","parentName":"Santa Barbara","title":"Living in Santa Barbara","children":["4qCS3qkxeOmG"],"parentid":"AzX5_o7rTg3Z"}
  collection: bookmarks }
2011-01-03 11:47:25	Engine.Bookmarks     TRACE	Local record is different
2011-01-03 11:47:25	Engine.Bookmarks     TRACE	Record age vs local age: 1135937.8299999237,1294051645.638
2011-01-03 11:47:25	Store.Bookmarks      TRACE	Updating 4qCS3qkxeOmG (11747)
2011-01-03 11:47:25	Engine.Bookmarks     TRACE	Event: weave:engine:sync:error
2011-01-03 11:47:25	Engine.Bookmarks     DEBUG	Total (ms): sync 9637, processIncoming 6637, syncStartup 2982, createRecord 4799, findDupe 0, isEqual 4851, reconcile 4910, syncCleanup 5
2011-01-03 11:47:25	Service.Main         DEBUG	bookmarks failed: NS_ERROR_ILLEGAL_VALUE JS Stack trace: Res_get()@resource.js:376 < SyncEngine__processIncoming()@engines.js:511 < _processIncoming()@bookmarks.js:213 < ()@engines.js:203 < SyncEngine__sync()@engines.js:768 < wrappedSync(null)@util.js:168 < 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 < (1)@browser.js:5214
2011-01-03 11:47:25	Engine.Forms         INFO	0 outgoing items pre-reconciliation
2011-01-03 11:47:25	Engine.Forms         INFO	Records: 0 applied, 0 reconciled.
2011-01-03 11:47:25	Engine.Forms         DEBUG	Total (ms): sync 1, processIncoming 0, uploadOutgoing 0, syncStartup 1, syncFinish 0, getChangedIDs 0, syncCleanup 0
2011-01-03 11:47:25	Engine.History       DEBUG	First sync, uploading all items
2011-01-03 11:47:26	Engine.History       INFO	1991 outgoing items pre-reconciliation
2011-01-03 11:47:31	Engine.History       DEBUG	Total (ms): sync 5976, processIncoming 5443, syncStartup 522, findDupe 14, createRecord 757, isEqual 781, deleteId 1, handleDupe 4, reconcile 878, syncCleanup 10
2011-01-03 11:47:31	Service.Main         DEBUG	history failed: NS_ERROR_FAILURE JS Stack trace: Res_get()@resource.js:376 < SyncEngine__processIncoming()@engines.js:511 < ()@engines.js:203 < SyncEngine__sync()@engines.js:768 < wrappedSync(null)@util.js:168 < 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 < (1)@browser.js:5214
2011-01-03 11:47:31	Engine.Passwords     INFO	0 outgoing items pre-reconciliation
2011-01-03 11:47:31	Engine.Passwords     INFO	Records: 0 applied, 0 reconciled.
2011-01-03 11:47:31	Engine.Passwords     DEBUG	Total (ms): sync 1, processIncoming 1, uploadOutgoing 0, syncStartup 0, syncFinish 0, getChangedIDs 0, syncCleanup 0
2011-01-03 11:47:31	Engine.Prefs         INFO	0 outgoing items pre-reconciliation
2011-01-03 11:47:31	Engine.Prefs         INFO	Records: 0 applied, 0 reconciled.
2011-01-03 11:47:31	Engine.Prefs         DEBUG	Total (ms): sync 0, processIncoming 0, uploadOutgoing 0, syncStartup 0, syncFinish 0, getChangedIDs 0, syncCleanup 0
2011-01-03 11:47:31	Service.Main         DEBUG	Exception: Some engines did not sync correctly No traceback available
AM, are you still seeing this with 4.0b10 or recent nightlies?
I just tried on b10


2011-01-27 18:41:27	Service.Main         DEBUG	bookmarks failed: NS_ERROR_FAILURE JS Stack trace: Res_get()@resource.js:434 < SyncEngine__processIncoming()@engines.js:678 < _processIncoming()@bookmarks.js:320 < ()@engines.js:382 < SyncEngine__sync()@engines.js:935 < wrappedSync(null)@util.js:179 < batchedSync()@util.js:185 < ()@engines.js:382 < WrappedNotify()@util.js:158 < Engine_sync()@engines.js:392 < WeaveSvc__syncEngine([object Object])@service.js:1866 < ()@service.js:1752 < WrappedNotify()@util.js:158 < WrappedLock()@util.js:126 < _lockedSync()@service.js:1657 < ()@service.js:1648 < WrappedCatch()@util.js:100 < sync()@service.js:1629 < (1)@browser.js:5201
AM, more log context would be helpful.

If your logs are rolling over, try setting the pref that ends

  ...debugLog.rotate = false

in about:config. That should let you get a full log.
2011-01-27 18:41:26	Engine.Bookmarks     TRACE	Local record is different
2011-01-27 18:41:26	Engine.Bookmarks     TRACE	Record age vs local age: 3234490.390000105,1296150086.878
2011-01-27 18:41:26	Store.Bookmarks      TRACE	Updating zSLKt9H1Fvht (19292)
2011-01-27 18:41:26	Store.Bookmarks      DEBUG	Reparenting orphans  to 19292
2011-01-27 18:41:26	Engine.Bookmarks     TRACE	Incoming: { id: z_hPqAAEk6RN
  index: 140
  modified: 1292915590.26
  ttl: undefined
  payload: {"id":"z_hPqAAEk6RN","type":"bookmark","title":"COMSOL : COMSOL - Multiphysics Modeling","parentName":"Unsorted Bookmarks","bmkUri":"http://www.comsol.com/","tags":[],"keyword":null,"loadInSidebar":false,"parentid":"unfiled"}
  collection: bookmarks }
2011-01-27 18:41:26	Engine.Bookmarks     TRACE	Reconcile step 1: Check for conflicts
2011-01-27 18:41:26	Engine.Bookmarks     TRACE	Finding mapping: Unsorted Bookmarks, bhttp://www.comsol.com/:COMSOL : COMSOL - Multiphysics Modeling
2011-01-27 18:41:26	Engine.Bookmarks     TRACE	Mapped dupe: z_hPqAAEk6RN
2011-01-27 18:41:26	Engine.Bookmarks     TRACE	Local record: { id: z_hPqAAEk6RN
  index: 140
  modified: undefined
  ttl: undefined
  payload: {"id":"z_hPqAAEk6RN","type":"bookmark","title":"COMSOL : COMSOL - Multiphysics Modeling","parentName":"Unsorted Bookmarks","bmkUri":"http://www.comsol.com/","tags":[],"keyword":null,"description":null,"loadInSidebar":false,"parentid":"unfiled"}
  collection: bookmarks }
2011-01-27 18:41:26	Engine.Bookmarks     TRACE	Local record is different
2011-01-27 18:41:26	Engine.Bookmarks     TRACE	Record age vs local age: 3234490.390000105,1296150086.884
2011-01-27 18:41:26	Store.Bookmarks      TRACE	Updating z_hPqAAEk6RN (7679)
2011-01-27 18:41:26	Collection           WARN	Got exception calling onProgress handler during fetch of https://phx-sync185.services.mozilla.com/1.0/augustm/storage/bookmarks?full=1
2011-01-27 18:41:26	Collection           DEBUG	cipher finalize failed Stack trace: resource://services-crypto/WeaveCrypto.js:560 < resource://services-crypto/WeaveCrypto.js:505 < decrypt()@resource://services-sync/record.js:242 < PlacesItem_decrypt()@resource://services-sync/engines/bookmarks.js:73 < resource://services-sync/engines.js:647 < innerBind()@resource://services-sync/util.js:1398 < resource://services-sync/record.js:801 < Channel_onDataAvail()@resource://services-sync/resource.js:522
2011-01-27 18:41:26	Tracker.Bookmarks    TRACE	Adding changed ID: menu,0
2011-01-27 18:41:26	Tracker.Bookmarks    TRACE	Adding changed ID: toolbar,0
2011-01-27 18:41:26	Tracker.Bookmarks    TRACE	Adding changed ID: ktZK02OhuDh3,0
2011-01-27 18:41:26	Tracker.Bookmarks    TRACE	Adding changed ID: 6_QVNqe1WiMO,0


....hundreds of lines  which are similar

2011-01-27 18:41:27	Tracker.Bookmarks    TRACE	Adding changed ID: zSLKt9H1Fvht,0
2011-01-27 18:41:27	Tracker.Bookmarks    TRACE	Adding changed ID: NVMoARoh8D-x,0
2011-01-27 18:41:27	Tracker.Bookmarks    TRACE	Adding changed ID: R97FsqhqY70C,0
2011-01-27 18:41:27	Engine.Bookmarks     TRACE	Event: weave:engine:sync:error
2011-01-27 18:41:27	Engine.Bookmarks     DEBUG	Total (ms): sync 9768, processIncoming 6323, syncStartup 3188, createRecord 5484, isEqual 5488, reconcile 5560, syncCleanup 245
2011-01-27 18:41:27	Service.Main         DEBUG	bookmarks failed: NS_ERROR_FAILURE JS Stack trace: Res_get()@resource.js:434 < SyncEngine__processIncoming()@engines.js:678 < _processIncoming()@bookmarks.js:320 < ()@engines.js:382 < SyncEngine__sync()@engines.js:935 < wrappedSync(null)@util.js:179 < batchedSync()@util.js:185 < ()@engines.js:382 < WrappedNotify()@util.js:158 < Engine_sync()@engines.js:392 < WeaveSvc__syncEngine([object Object])@service.js:1866 < ()@service.js:1752 < WrappedNotify()@util.js:158 < WrappedLock()@util.js:126 < _lockedSync()@service.js:1657 < ()@service.js:1648 < WrappedCatch()@util.js:100 < sync()@service.js:1629 < (1)@browser.js:5201
2011-01-27 18:41:27	Engine.Forms         INFO	0 outgoing items pre-reconciliation
2011-01-27 18:41:27	Engine.Forms         INFO	Records: 0 applied, 0 reconciled.
2011-01-27 18:41:27	Engine.Forms         DEBUG	Total (ms): sync 1, processIncoming 1, uploadOutgoing 0, syncStartup 0, syncFinish 0, getChangedIDs 0, syncCleanup 0
2011-01-27 18:41:27	Engine.History       INFO	1 outgoing items pre-reconciliation
2011-01-27 18:41:27	Engine.History       INFO	Records: 0 applied, 0 reconciled.
2011-01-27 18:41:27	Engine.History       INFO	Uploading all of 1 records
2011-01-27 18:41:27	Collection           DEBUG	POST Length: 802
2011-01-27 18:41:28	Tracker.Bookmarks    TRACE	Saving json to disk: weave/changes/bookmarks.json
2011-01-27 18:41:28	Collection           DEBUG	POST success 200 https://phx-sync185.services.mozilla.com/1.0/augustm/storage/history
2011-01-27 18:41:28	Engine.History       DEBUG	Total (ms): sync 1225, processIncoming 0, uploadOutgoing 1225, syncStartup 0, syncFinish 0, getChangedIDs 0, createRecord 23, syncCleanup 0
2011-01-27 18:41:28	Engine.Passwords     INFO	0 outgoing items pre-reconciliation
2011-01-27 18:41:28	Engine.Passwords     INFO	Records: 0 applied, 0 reconciled.
2011-01-27 18:41:28	Engine.Passwords     DEBUG	Total (ms): sync 0, processIncoming 0, uploadOutgoing 0, syncStartup 0, syncFinish 0, getChangedIDs 0, syncCleanup 0
2011-01-27 18:41:28	Engine.Prefs         INFO	0 outgoing items pre-reconciliation
2011-01-27 18:41:28	Engine.Prefs         INFO	Records: 0 applied, 0 reconciled.
2011-01-27 18:41:28	Engine.Prefs         DEBUG	Total (ms): sync 1, processIncoming 0, uploadOutgoing 0, syncStartup 1, syncFinish 0, getChangedIDs 0, syncCleanup 0
2011-01-27 18:41:28	Service.Main         CONFIG	Starting backoff, next sync at:Thu Jan 27 2011 22:42:54 GMT+0100 (CET)
2011-01-27 18:41:28	Service.Main         DEBUG	Exception: Some engines did not sync correctly No traceback available
Here's the real failure:

2011-01-27 18:41:26    Collection           WARN    Got exception calling
onProgress handler during fetch of
https://phx-sync185.services.mozilla.com/1.0/augustm/storage/bookmarks?full=1
2011-01-27 18:41:26    Collection           DEBUG    cipher finalize failed
Stack trace: resource://services-crypto/WeaveCrypto.js:560 <
resource://services-crypto/WeaveCrypto.js:505 <
decrypt()@resource://services-sync/record.js:242 <
PlacesItem_decrypt()@resource://services-sync/engines/bookmarks.js:73 <
resource://services-sync/engines.js:647 <
innerBind()@resource://services-sync/util.js:1398 <
resource://services-sync/record.js:801 <
Channel_onDataAvail()@resource://services-sync/resource.js:522

I believe the root cause of this is a GC bug in our WeaveCrypto code: Bug 617650. 

This resulted in a bad record being uploaded to the server. That's fixed, but it can't correct old records. We don't deal with it very well on download: Bug 622762.

AM, you can do one of these things:

* Wait for a release with Bug 622762, which will skip over this record and allow sync to complete, albeit still surfacing the error.

* Take a more heavy-handed approach: once Bug 626796 lands on m-c, make a bookmarks backup on the working machine, restore it, then sync. That ought to wipe the server for bookmarks only.

* Delete that record from the server by using Reset Sync > Replace other machines on one of your computers. This has the potential for wiping out your other profile changes, of course.

* Find out which bookmark is failing, and edit it on the working machine; on next sync it'll fix the record on the server.
Depends on: 622762
Resolving this since the root cause, bug 617650, has been fixed.
Status: NEW → RESOLVED
Closed: 13 years ago
Resolution: --- → FIXED
verified with nightly minefield builds of 20110218
Status: RESOLVED → VERIFIED
Component: Firefox Sync: Backend → Sync
Product: Cloud Services → Firefox
You need to log in before you can comment on or make changes to this bug.