Closed Bug 689979 Opened 14 years ago Closed 13 years ago

Sync encountered an error while connecting: Server incorrectly configured. Please try again.

Categories

(Firefox :: Sync, defect)

Product:
Firefox
Component:
Sync
Type:
defect
Priority:
Not set
Severity:
normal

Tracking

()

Status:
RESOLVED INCOMPLETE

People

(Reporter: toderu, Unassigned)

Details

Attachments

(4 files)

sync.jpg
12.13 KB, image/jpeg
Details
nightly trace log
25.85 KB, text/plain
Details
ff7 trace log
3.12 KB, text/plain
Details
sync issue in Firefox 7.0.1
750 bytes, text/plain
Details
Attached image sync.jpg —
User Agent: Mozilla/5.0 (Windows NT 6.1; WOW64; rv:7.0) Gecko/20100101 Firefox/7.0 Build ID: 20110922153450 Steps to reproduce: when starting firefox I get the error: Sync encountered an error while connecting: Server incorrectly configured. Please try again. Firefox version: 7 stable (upgraded from 7 beta). Sync server -> firefox server. account is same as bugzilla account. After pushing sync now from menu, it gives no error. Actual results: Sync encountered an error while connecting: Server incorrectly configured. Please try again. weave/log 1317227173115 Sync.Service INFO Loading Weave 1.10.0 1317227173128 Sync.Engine.Bookmarks DEBUG Engine initialized 1317227173130 Sync.Engine.Forms DEBUG Engine initialized 1317227173132 Sync.Engine.History DEBUG Engine initialized 1317227173133 Sync.Engine.Passwords DEBUG Engine initialized 1317227173134 Sync.Engine.Prefs DEBUG Engine initialized 1317227173135 Sync.Engine.Tabs DEBUG Engine initialized 1317227173136 Sync.Engine.Tabs DEBUG Resetting tabs last sync time 1317227173137 Sync.Service INFO Mozilla/5.0 (Windows NT 6.1; WOW64; rv:7.0) Gecko/20100101 Firefox/7.0 1317227173142 Sync.Service DEBUG Caching URLs under storage user base: https://phx-sync080.services.mozilla.com/1.1/7lp55uifggf3uyialzokhbmdfti5s6zw/ 1317227177148 Sync.Service INFO Starting sync at 2011-09-28 19:26:17 1317227177148 Sync.Service DEBUG In sync: should login. 1317227177150 Sync.Service INFO Logging in user 7lp55uifggf3uyialzokhbmdfti5s6zw 1317227180146 Sync.Resource WARN Caught unexpected exception Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIHttpChannel.responseStatus] Stack trace: _onComplete()@resource://services-sync/resource.js:327 < innerBind()@resource://services-sync/util.js:1159 < Channel_onStopRequest()@resource://services-sync/resource.js:553 in _onComplete. 1317227180146 Sync.Resource DEBUG Stack trace: _onComplete()@resource://services-sync/resource.js:327 < innerBind()@resource://services-sync/util.js:1159 < Channel_onStopRequest()@resource://services-sync/resource.js:553 1317227180146 Sync.Resource DEBUG Caught exception Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIHttpChannel.visitResponseHeaders] Stack trace: _onComplete()@resource://services-sync/resource.js:362 < innerBind()@resource://services-sync/util.js:1159 < Channel_onStopRequest()@resource://services-sync/resource.js:553 visiting headers in _onComplete. 1317227180146 Sync.Resource DEBUG Stack trace: _onComplete()@resource://services-sync/resource.js:362 < innerBind()@resource://services-sync/util.js:1159 < Channel_onStopRequest()@resource://services-sync/resource.js:553 1317227180156 Sync.SyncScheduler DEBUG Clearing sync triggers. Expected results: no error :)
Looks like Bug 672878. Could you confirm that you only saw this issue once, and that you can't reproduce it? Thanks!
Status: UNCONFIRMED → NEW
Ever confirmed: true
To reproduce the issue I have just to close and reopen firefox :) Every time I get the error. Just have a fresh one: 1317235311711 Sync.Service INFO Loading Weave 1.10.0 1317235311724 Sync.Engine.Bookmarks DEBUG Engine initialized 1317235311725 Sync.Engine.Forms DEBUG Engine initialized 1317235311726 Sync.Engine.History DEBUG Engine initialized 1317235311727 Sync.Engine.Passwords DEBUG Engine initialized 1317235311728 Sync.Engine.Prefs DEBUG Engine initialized 1317235311728 Sync.Engine.Tabs DEBUG Engine initialized 1317235311729 Sync.Engine.Tabs DEBUG Resetting tabs last sync time 1317235311729 Sync.Service INFO Mozilla/5.0 (Windows NT 6.1; WOW64; rv:7.0) Gecko/20100101 Firefox/7.0 1317235311732 Sync.Service DEBUG Caching URLs under storage user base: https://phx-sync080.services.mozilla.com/1.1/7lp55uifggf3uyialzokhbmdfti5s6zw/ 1317235315738 Sync.Service INFO Starting sync at 2011-09-28 21:41:55 1317235315738 Sync.Service DEBUG In sync: should login. 1317235315740 Sync.Service INFO Logging in user 7lp55uifggf3uyialzokhbmdfti5s6zw 1317235317726 Sync.Resource WARN Caught unexpected exception Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIHttpChannel.responseStatus] Stack trace: _onComplete()@resource://services-sync/resource.js:327 < innerBind()@resource://services-sync/util.js:1159 < Channel_onStopRequest()@resource://services-sync/resource.js:553 in _onComplete. 1317235317726 Sync.Resource DEBUG Stack trace: _onComplete()@resource://services-sync/resource.js:327 < innerBind()@resource://services-sync/util.js:1159 < Channel_onStopRequest()@resource://services-sync/resource.js:553 1317235317726 Sync.Resource DEBUG Caught exception Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIHttpChannel.visitResponseHeaders] Stack trace: _onComplete()@resource://services-sync/resource.js:362 < innerBind()@resource://services-sync/util.js:1159 < Channel_onStopRequest()@resource://services-sync/resource.js:553 visiting headers in _onComplete. 1317235317726 Sync.Resource DEBUG Stack trace: _onComplete()@resource://services-sync/resource.js:362 < innerBind()@resource://services-sync/util.js:1159 < Channel_onStopRequest()@resource://services-sync/resource.js:553 1317235317737 Sync.SyncScheduler DEBUG Clearing sync triggers.
(In reply to toderu from comment #2) > To reproduce the issue I have just to close and reopen firefox :) > Every time I get the error. Well, drat :) Would it be possible for you to back up your profile, download a Firefox nightly: http://nightly.mozilla.org/ and post the resulting log from startup? You can restore your old profile and go back to Firefox 7 afterwards, of course. I'm just interested to see if you can reproduce this issue on a current build. Thanks!
With nightly(9.0a1 (2011-09-27)) I get no new log in profile/weave/log (as I've noticed only on error it creates log) and also no error in gui. It seems in nightly the bug is solved.
(In reply to toderu from comment #4) > With nightly(9.0a1 (2011-09-27)) I get no new log in profile/weave/log (as > I've noticed only on error it creates log) and also no error in gui. > It seems in nightly the bug is solved. Could you try toggling the pref services.sync.log.appender.file.logOnSuccess to True, setting services.sync.log.logger.network.resources to "Trace" (with a capital "T"), restarting and attaching the success log that gets produced after the first sync? I'm interested to see if there's any output that might indicate the same issue is occurring. Once you're done you can undo those changes. Thanks for helping with this!
Log generated by nightly(9.0a1 (2011-09-27)) 1317242807778 Sync.Service INFO Loading Weave 1.12.0 1317242807790 Sync.Engine.Bookmarks DEBUG Engine initialized 1317242807791 Sync.Engine.Forms DEBUG Engine initialized 1317242807792 Sync.Engine.History DEBUG Engine initialized 1317242807792 Sync.Engine.Passwords DEBUG Engine initialized 1317242807793 Sync.Engine.Prefs DEBUG Engine initialized 1317242807793 Sync.Engine.Tabs DEBUG Engine initialized 1317242807793 Sync.Engine.Tabs DEBUG Resetting tabs last sync time 1317242807793 Sync.Service INFO Mozilla/5.0 (Windows NT 6.1; WOW64; rv:9.0a1) Gecko/20110927 Firefox/9.0a1 1317242807797 Sync.Service DEBUG Caching URLs under storage user base: https://phx-sync080.services.mozilla.com/1.1/7lp55uifggf3uyialzokhbmdfti5s6zw/ 1317242811822 Sync.Service DEBUG User-Agent: Firefox/9.0a1 FxSync/1.12.0.20110927030845. 1317242811822 Sync.Service INFO Starting sync at 2011-09-28 23:46:51 1317242811822 Sync.Service DEBUG In sync: should login. 1317242811823 Sync.Service INFO Logging in user 7lp55uifggf3uyialzokhbmdfti5s6zw 1317242811824 Sync.Resource TRACE In _doRequest. 1317242811921 Sync.Resource TRACE HTTP Header authorization: ***** (suppressed) 1317242813373 Sync.Resource TRACE onStartRequest called for channel [xpconnect wrapped nsIRequest]. 1317242813374 Sync.Resource TRACE onStartRequest: GET https://phx-sync080.services.mozilla.com/1.1/7lp55uifggf3uyialzokhbmdfti5s6zw/info/collections 1317242813374 Sync.Resource TRACE Channel for GET https://phx-sync080.services.mozilla.com/1.1/7lp55uifggf3uyialzokhbmdfti5s6zw/info/collections: isSuccessCode(0)? true 1317242813374 Sync.Resource TRACE Channel: flags = 640, URI = https://phx-sync080.services.mozilla.com/1.1/7lp55uifggf3uyialzokhbmdfti5s6zw/info/collections, HTTP success? true 1317242813374 Sync.Resource TRACE In _onComplete. Error is null. 1317242813374 Sync.Resource TRACE Channel: [xpconnect wrapped (nsISupports, nsIChannel, nsIRequest, nsIHttpChannel)] 1317242813374 Sync.Resource TRACE Action: GET 1317242813374 Sync.Resource TRACE Status: 200 1317242813374 Sync.Resource TRACE Success: true 1317242813374 Sync.Resource DEBUG mesg: GET success 200 https://phx-sync080.services.mozilla.com/1.1/7lp55uifggf3uyialzokhbmdfti5s6zw/info/collections 1317242813374 Sync.Resource DEBUG GET success 200 https://phx-sync080.services.mozilla.com/1.1/7lp55uifggf3uyialzokhbmdfti5s6zw/info/collections 1317242813374 Sync.Resource TRACE GET body: {"passwords": 1317242511.20, "tabs": 1317242733.28, "clients": 1317242670.11, "crypto": 1316445444.96, "forms": 1317242510.17, "meta": 1316445606.70, "bookmarks": 1317241907.69, "prefs": 1317241908.68, "history": 1317242671.25} 1317242813374 Sync.Resource TRACE Processing response headers. 1317242813374 Sync.Service DEBUG Fetching global metadata record 1317242813375 Sync.Resource TRACE In _doRequest. 1317242813375 Sync.Resource TRACE HTTP Header authorization: ***** (suppressed) 1317242813622 Sync.Resource TRACE onStartRequest called for channel [xpconnect wrapped nsIRequest]. 1317242813622 Sync.Resource TRACE onStartRequest: GET https://phx-sync080.services.mozilla.com/1.1/7lp55uifggf3uyialzokhbmdfti5s6zw/storage/meta/global 1317242813623 Sync.Resource TRACE Channel for GET https://phx-sync080.services.mozilla.com/1.1/7lp55uifggf3uyialzokhbmdfti5s6zw/storage/meta/global: isSuccessCode(0)? true 1317242813623 Sync.Resource TRACE Channel: flags = 640, URI = https://phx-sync080.services.mozilla.com/1.1/7lp55uifggf3uyialzokhbmdfti5s6zw/storage/meta/global, HTTP success? true 1317242813623 Sync.Resource TRACE In _onComplete. Error is null. 1317242813623 Sync.Resource TRACE Channel: [xpconnect wrapped (nsISupports, nsIChannel, nsIRequest, nsIHttpChannel)] 1317242813623 Sync.Resource TRACE Action: GET 1317242813623 Sync.Resource TRACE Status: 200 1317242813623 Sync.Resource TRACE Success: true 1317242813623 Sync.Resource DEBUG mesg: GET success 200 https://phx-sync080.services.mozilla.com/1.1/7lp55uifggf3uyialzokhbmdfti5s6zw/storage/meta/global 1317242813623 Sync.Resource DEBUG GET success 200 https://phx-sync080.services.mozilla.com/1.1/7lp55uifggf3uyialzokhbmdfti5s6zw/storage/meta/global 1317242813623 Sync.Resource TRACE GET body: {"username": "5344670", "id": "global", "modified": 1316445606.70, "payload": "{\"syncID\":\"ABy3mD1JmDul\",\"storageVersion\":5,\"engines\":{\"clients\":{\"version\":1,\"syncID\":\"AFZ4moCiofZa\"},\"bookmarks\":{\"version\":2,\"syncID\":\"0w4BZPJiy7DI\"},\"forms\":{\"version\":1,\"syncID\":\"gsvpInSDL7x-\"},\"history\":{\"version\":1,\"syncID\":\"s5tfSfF01S6s\"},\"passwords\":{\"version\":1,\"syncID\":\"X6WxgLPteN4T\"},\"prefs\":{\"version\":2,\"syncID\":\"yuLr5Jvp7Ofi\"},\"tabs\":{\"version\":1,\"syncID\":\"Qwj_TwFGHI66\"}}}"} 1317242813623 Sync.Resource TRACE Processing response headers. 1317242813623 Sync.Service DEBUG Weave Version: 1.12.0 Local Storage: 5 Remote Storage: 5 1317242813623 Sync.Service INFO Sync key is up-to-date: no need to upgrade. 1317242813623 Sync.Service DEBUG Fetching and verifying -- or generating -- symmetric keys. 1317242813623 Sync.Resource TRACE In _doRequest. 1317242813624 Sync.Resource TRACE HTTP Header authorization: ***** (suppressed) 1317242813816 Sync.Resource TRACE onStartRequest called for channel [xpconnect wrapped nsIRequest]. 1317242813816 Sync.Resource TRACE onStartRequest: GET https://phx-sync080.services.mozilla.com/1.1/7lp55uifggf3uyialzokhbmdfti5s6zw/info/collections 1317242813816 Sync.Resource TRACE Channel for GET https://phx-sync080.services.mozilla.com/1.1/7lp55uifggf3uyialzokhbmdfti5s6zw/info/collections: isSuccessCode(0)? true 1317242813816 Sync.Resource TRACE Channel: flags = 640, URI = https://phx-sync080.services.mozilla.com/1.1/7lp55uifggf3uyialzokhbmdfti5s6zw/info/collections, HTTP success? true 1317242813816 Sync.Resource TRACE In _onComplete. Error is null. 1317242813816 Sync.Resource TRACE Channel: [xpconnect wrapped (nsISupports, nsIChannel, nsIRequest, nsIHttpChannel)] 1317242813816 Sync.Resource TRACE Action: GET 1317242813816 Sync.Resource TRACE Status: 200 1317242813816 Sync.Resource TRACE Success: true 1317242813816 Sync.Resource DEBUG mesg: GET success 200 https://phx-sync080.services.mozilla.com/1.1/7lp55uifggf3uyialzokhbmdfti5s6zw/info/collections 1317242813816 Sync.Resource DEBUG GET success 200 https://phx-sync080.services.mozilla.com/1.1/7lp55uifggf3uyialzokhbmdfti5s6zw/info/collections 1317242813816 Sync.Resource TRACE GET body: {"passwords": 1317242511.20, "tabs": 1317242733.28, "clients": 1317242670.11, "crypto": 1316445444.96, "forms": 1317242510.17, "meta": 1316445606.70, "bookmarks": 1317241907.69, "prefs": 1317241908.68, "history": 1317242671.25} 1317242813816 Sync.Resource TRACE Processing response headers. 1317242813817 Sync.Service INFO Testing info/collections: {"passwords":1317242511.2,"tabs":1317242733.28,"clients":1317242670.11,"crypto":1316445444.96,"forms":1317242510.17,"meta":1316445606.7,"bookmarks":1317241907.69,"prefs":1317241908.68,"history":1317242671.25} 1317242813817 Sync.CollectionKeys INFO Testing for updateNeeded. Last modified: 0 1317242813817 Sync.Service INFO CollectionKeys reports that a key update is needed. 1317242813817 Sync.Resource TRACE In _doRequest. 1317242813817 Sync.Resource TRACE HTTP Header authorization: ***** (suppressed) 1317242814068 Sync.Resource TRACE onStartRequest called for channel [xpconnect wrapped nsIRequest]. 1317242814068 Sync.Resource TRACE onStartRequest: GET https://phx-sync080.services.mozilla.com/1.1/7lp55uifggf3uyialzokhbmdfti5s6zw/storage/crypto/keys 1317242814069 Sync.Resource TRACE Channel for GET https://phx-sync080.services.mozilla.com/1.1/7lp55uifggf3uyialzokhbmdfti5s6zw/storage/crypto/keys: isSuccessCode(0)? true 1317242814069 Sync.Resource TRACE Channel: flags = 640, URI = https://phx-sync080.services.mozilla.com/1.1/7lp55uifggf3uyialzokhbmdfti5s6zw/storage/crypto/keys, HTTP success? true 1317242814069 Sync.Resource TRACE In _onComplete. Error is null. 1317242814069 Sync.Resource TRACE Channel: [xpconnect wrapped (nsISupports, nsIChannel, nsIRequest, nsIHttpChannel)] 1317242814069 Sync.Resource TRACE Action: GET 1317242814069 Sync.Resource TRACE Status: 200 1317242814069 Sync.Resource TRACE Success: true 1317242814069 Sync.Resource DEBUG mesg: GET success 200 https://phx-sync080.services.mozilla.com/1.1/7lp55uifggf3uyialzokhbmdfti5s6zw/storage/crypto/keys 1317242814069 Sync.Resource DEBUG GET success 200 https://phx-sync080.services.mozilla.com/1.1/7lp55uifggf3uyialzokhbmdfti5s6zw/storage/crypto/keys 1317242814069 Sync.Resource TRACE GET body: {"payload": "{\"ciphertext\":\"0YT9MnwG5gC3QQZ4xc04k1mNDk77Wlo5oNddX8ZYgVux36gWh4UXDlHvferpC146GdqfHQNbKr0k+8fLm3VURRiUd3wPUowqbne6iBdcW0LZFyjy9qLzAJZtk6dsI/TbVap4C0YKrmuKE1hGm+vvFky+Zw8CBKvjAxfUVeE2EFUq6NKbA4sGeCLsc6X21UYmeU92OBZ4W3UBVfyURGN7Eg==\",\"IV\":\"vue+k3YNsW/DoEAQtqxJFg==\",\"hmac\":\"b168df5146c097ff26cecf70f82106a5accab0ae63b2645f8d74629c15756fc1\"}", "id": "keys", "modified": 1316445444.96} 1317242814069 Sync.Resource TRACE Processing response headers. 1317242814069 Sync.CollectionKeys INFO Updating collection keys... 1317242814071 Sync.CollectionKeys INFO Setting CollectionKeys contents. Our last modified: 0, input modified: 1316445444.96. 1317242814071 Sync.BulkKeyBundle INFO BulkKeyBundle being created for [default] 1317242814071 Sync.CollectionKeys INFO Processing downloaded per-collection keys. 1317242814071 Sync.CollectionKeys INFO Clearing CollectionKeys... 1317242814071 Sync.CollectionKeys INFO Saving downloaded keys. 1317242814071 Sync.CollectionKeys INFO Bumping last modified to 1316445444.96 1317242814071 Sync.CollectionKeys INFO Collection keys updated. 1317242814072 Sync.SyncScheduler DEBUG Clearing sync triggers. 1317242814073 Sync.Service INFO In sync(). 1317242814073 Sync.Resource TRACE In _doRequest. 1317242814073 Sync.Resource TRACE HTTP Header authorization: ***** (suppressed) 1317242814269 Sync.Resource TRACE onStartRequest called for channel [xpconnect wrapped nsIRequest]. 1317242814269 Sync.Resource TRACE onStartRequest: GET https://phx-sync080.services.mozilla.com/1.1/7lp55uifggf3uyialzokhbmdfti5s6zw/info/collections 1317242814269 Sync.Resource TRACE Channel for GET https://phx-sync080.services.mozilla.com/1.1/7lp55uifggf3uyialzokhbmdfti5s6zw/info/collections: isSuccessCode(0)? true 1317242814269 Sync.Resource TRACE Channel: flags = 640, URI = https://phx-sync080.services.mozilla.com/1.1/7lp55uifggf3uyialzokhbmdfti5s6zw/info/collections, HTTP success? true 1317242814269 Sync.Resource TRACE In _onComplete. Error is null. 1317242814269 Sync.Resource TRACE Channel: [xpconnect wrapped (nsISupports, nsIChannel, nsIRequest, nsIHttpChannel)] 1317242814269 Sync.Resource TRACE Action: GET 1317242814269 Sync.Resource TRACE Status: 200 1317242814269 Sync.Resource TRACE Success: true 1317242814269 Sync.Resource DEBUG mesg: GET success 200 https://phx-sync080.services.mozilla.com/1.1/7lp55uifggf3uyialzokhbmdfti5s6zw/info/collections 1317242814269 Sync.Resource DEBUG GET success 200 https://phx-sync080.services.mozilla.com/1.1/7lp55uifggf3uyialzokhbmdfti5s6zw/info/collections 1317242814269 Sync.Resource TRACE GET body: {"passwords": 1317242511.20, "tabs": 1317242733.28, "clients": 1317242670.11, "crypto": 1316445444.96, "forms": 1317242510.17, "meta": 1316445606.70, "bookmarks": 1317241907.69, "prefs": 1317241908.68, "history": 1317242671.25} 1317242814269 Sync.Resource TRACE Processing response headers. 1317242814270 Sync.Service DEBUG Fetching global metadata record 1317242814270 Sync.Service DEBUG Clearing cached meta record. metaModified is undefined, setting to 1316445606.7 1317242814270 Sync.Resource TRACE In _doRequest. 1317242814270 Sync.Resource TRACE HTTP Header authorization: ***** (suppressed) 1317242814466 Sync.Resource TRACE onStartRequest called for channel [xpconnect wrapped nsIRequest]. 1317242814466 Sync.Resource TRACE onStartRequest: GET https://phx-sync080.services.mozilla.com/1.1/7lp55uifggf3uyialzokhbmdfti5s6zw/storage/meta/global 1317242814466 Sync.Resource TRACE Channel for GET https://phx-sync080.services.mozilla.com/1.1/7lp55uifggf3uyialzokhbmdfti5s6zw/storage/meta/global: isSuccessCode(0)? true 1317242814466 Sync.Resource TRACE Channel: flags = 640, URI = https://phx-sync080.services.mozilla.com/1.1/7lp55uifggf3uyialzokhbmdfti5s6zw/storage/meta/global, HTTP success? true 1317242814466 Sync.Resource TRACE In _onComplete. Error is null. 1317242814466 Sync.Resource TRACE Channel: [xpconnect wrapped (nsISupports, nsIChannel, nsIRequest, nsIHttpChannel)] 1317242814466 Sync.Resource TRACE Action: GET 1317242814466 Sync.Resource TRACE Status: 200 1317242814466 Sync.Resource TRACE Success: true 1317242814466 Sync.Resource DEBUG mesg: GET success 200 https://phx-sync080.services.mozilla.com/1.1/7lp55uifggf3uyialzokhbmdfti5s6zw/storage/meta/global 1317242814466 Sync.Resource DEBUG GET success 200 https://phx-sync080.services.mozilla.com/1.1/7lp55uifggf3uyialzokhbmdfti5s6zw/storage/meta/global 1317242814466 Sync.Resource TRACE GET body: {"username": "5344670", "id": "global", "modified": 1316445606.70, "payload": "{\"syncID\":\"ABy3mD1JmDul\",\"storageVersion\":5,\"engines\":{\"clients\":{\"version\":1,\"syncID\":\"AFZ4moCiofZa\"},\"bookmarks\":{\"version\":2,\"syncID\":\"0w4BZPJiy7DI\"},\"forms\":{\"version\":1,\"syncID\":\"gsvpInSDL7x-\"},\"history\":{\"version\":1,\"syncID\":\"s5tfSfF01S6s\"},\"passwords\":{\"version\":1,\"syncID\":\"X6WxgLPteN4T\"},\"prefs\":{\"version\":2,\"syncID\":\"yuLr5Jvp7Ofi\"},\"tabs\":{\"version\":1,\"syncID\":\"Qwj_TwFGHI66\"}}}"} 1317242814466 Sync.Resource TRACE Processing response headers. 1317242814467 Sync.Service DEBUG Weave Version: 1.12.0 Local Storage: 5 Remote Storage: 5 1317242814467 Sync.Service INFO Sync key is up-to-date: no need to upgrade. 1317242814467 Sync.Service DEBUG Fetching and verifying -- or generating -- symmetric keys. 1317242814467 Sync.Service INFO Testing info/collections: {"passwords":1317242511.2,"tabs":1317242733.28,"clients":1317242670.11,"crypto":1316445444.96,"forms":1317242510.17,"meta":1316445606.7,"bookmarks":1317241907.69,"prefs":1317241908.68,"history":1317242671.25} 1317242814467 Sync.CollectionKeys INFO Testing for updateNeeded. Last modified: 1316445444.96 1317242814467 Sync.Service DEBUG Refreshing client list. 1317242814467 Sync.Engine.Clients DEBUG First sync, uploading all items 1317242814467 Sync.Engine.Clients INFO 1 outgoing items pre-reconciliation 1317242814468 Sync.Collection TRACE In _doRequest. 1317242814468 Sync.Collection TRACE HTTP Header accept: application/newlines 1317242814468 Sync.Collection TRACE HTTP Header authorization: ***** (suppressed) 1317242814673 Sync.Collection TRACE onStartRequest called for channel [xpconnect wrapped nsIRequest]. 1317242814673 Sync.Collection TRACE onStartRequest: GET https://phx-sync080.services.mozilla.com/1.1/7lp55uifggf3uyialzokhbmdfti5s6zw/storage/clients?full=1 1317242814675 Sync.Collection TRACE Channel for GET https://phx-sync080.services.mozilla.com/1.1/7lp55uifggf3uyialzokhbmdfti5s6zw/storage/clients?full=1: isSuccessCode(0)? true 1317242814675 Sync.Collection TRACE Channel: flags = 640, URI = https://phx-sync080.services.mozilla.com/1.1/7lp55uifggf3uyialzokhbmdfti5s6zw/storage/clients?full=1, HTTP success? true 1317242814675 Sync.Collection TRACE In _onComplete. Error is null. 1317242814675 Sync.Collection TRACE Channel: [xpconnect wrapped (nsISupports, nsIChannel, nsIRequest, nsIHttpChannel)] 1317242814675 Sync.Collection TRACE Action: GET 1317242814675 Sync.Collection TRACE Status: 200 1317242814675 Sync.Collection TRACE Success: true 1317242814675 Sync.Collection DEBUG mesg: GET success 200 https://phx-sync080.services.mozilla.com/1.1/7lp55uifggf3uyialzokhbmdfti5s6zw/storage/clients?full=1 1317242814675 Sync.Collection DEBUG GET success 200 https://phx-sync080.services.mozilla.com/1.1/7lp55uifggf3uyialzokhbmdfti5s6zw/storage/clients?full=1 1317242814675 Sync.Collection TRACE GET body: null 1317242814675 Sync.Collection TRACE Processing response headers. 1317242814675 Sync.Engine.Clients INFO Records: 3 applied, 0 failed to apply, 0 newly failed to apply, 0 reconciled. 1317242814677 Sync.Engine.Clients INFO Uploading all of 1 records 1317242814677 Sync.Collection TRACE In _doRequest. 1317242814677 Sync.Collection TRACE HTTP Header authorization: ***** (suppressed) 1317242814677 Sync.Collection DEBUG POST Length: 313 1317242814677 Sync.Collection TRACE POST Body: [{"payload":"{\"ciphertext\":\"M3tN23KqwxS7KZQH/j78S8buXRecIwnACfIW5WZbXfqA5ue/oVSVunH6wuDyh8EET79iAIlARFZEMreorzzzHnRH0+C9AMmP77dpHCWnPR6U5zFa7cobHwGgVqqxDXoS\",\"IV\":\"1UhdQDz58mqExwyEsFeENQ==\",\"hmac\":\"a127f8453a78e349222f76a828daf2cd809f355120730a9691e9b4cc316e7da1\"}","id":"E8sgoLAJd4M-","ttl":1814400}] 1317242815597 Sync.Collection TRACE onStartRequest called for channel [xpconnect wrapped nsIRequest]. 1317242815597 Sync.Collection TRACE onStartRequest: POST https://phx-sync080.services.mozilla.com/1.1/7lp55uifggf3uyialzokhbmdfti5s6zw/storage/clients 1317242815598 Sync.Collection TRACE Channel for POST https://phx-sync080.services.mozilla.com/1.1/7lp55uifggf3uyialzokhbmdfti5s6zw/storage/clients: isSuccessCode(0)? true 1317242815598 Sync.Collection TRACE Channel: flags = 640, URI = https://phx-sync080.services.mozilla.com/1.1/7lp55uifggf3uyialzokhbmdfti5s6zw/storage/clients, HTTP success? true 1317242815598 Sync.Collection TRACE In _onComplete. Error is null. 1317242815598 Sync.Collection TRACE Channel: [xpconnect wrapped (nsISupports, nsIChannel, nsIRequest, nsIHttpChannel, nsIUploadChannel)] 1317242815598 Sync.Collection TRACE Action: POST 1317242815598 Sync.Collection TRACE Status: 200 1317242815598 Sync.Collection TRACE Success: true 1317242815598 Sync.Collection DEBUG mesg: POST success 200 https://phx-sync080.services.mozilla.com/1.1/7lp55uifggf3uyialzokhbmdfti5s6zw/storage/clients 1317242815598 Sync.Collection DEBUG POST success 200 https://phx-sync080.services.mozilla.com/1.1/7lp55uifggf3uyialzokhbmdfti5s6zw/storage/clients 1317242815598 Sync.Collection TRACE POST body: {"failed": {}, "modified": 1317242814.35, "success": ["E8sgoLAJd4M-"]} 1317242815598 Sync.Collection TRACE Processing response headers. 1317242815598 Sync.SyncScheduler DEBUG Client count: 0 -> 3 1317242815598 Sync.Service INFO Updating enabled engines: 3 clients. 1317242815599 Sync.Engine.Bookmarks INFO 0 outgoing items pre-reconciliation 1317242815600 Sync.Engine.Bookmarks INFO Records: 0 applied, 0 failed to apply, 0 newly failed to apply, 0 reconciled. 1317242815601 Sync.Engine.Forms INFO 0 outgoing items pre-reconciliation 1317242815601 Sync.Engine.Forms INFO Records: 0 applied, 0 failed to apply, 0 newly failed to apply, 0 reconciled. 1317242815601 Sync.Engine.History INFO 1 outgoing items pre-reconciliation 1317242815602 Sync.Engine.History INFO Records: 0 applied, 0 failed to apply, 0 newly failed to apply, 0 reconciled. 1317242815607 Sync.Engine.History INFO Uploading all of 1 records 1317242815607 Sync.Collection TRACE In _doRequest. 1317242815607 Sync.Collection TRACE HTTP Header authorization: ***** (suppressed) 1317242815607 Sync.Collection DEBUG POST Length: 970 1317242815607 Sync.Collection TRACE POST Body: [{"payload":"{\"ciphertext\":\"5OoERM7vQZ0Jt9ReSiWrB0h9S8xG+gBLipyRsH5+aAGk6KLVKS3NTXevujoXZ2sP4DBGFuqd3bz2fYBHlvJCw0jMaww/Znx/MlrDxuIofQBNxmF876wS96k2DrQkYZy7hjQ3swZSIAnAStn7Jo6vd6S3ZBoUb1B9Dg1EDScgZnK4CfiPsJochFLySIJ6nuGRq5gh7Fj4FlOqVyL/WQ8fUkWfU+ltiH8nFu9jP2+HhdeQkLJRzjr4dxInFzvZaHtimO/F8yeKFYCqoBVFZ3hPYuMvs7oDFUDRLDT+HxsefNzaXexsqLBaAwITShzyxIMcAmHwXxSefldXLHM2bQA+ws2hCzTN5natLCh6o60isrqTmqp+m5j3zzE/b0P/AxEbUiUV043RUl7opRv+MWTUgq2VwbIvMVnsjIuctkobMpAgceC++crWevw42s9f8mHp/Jn+4hmaDnJhq/KAJ4uw3XfA4sg3C4o+G5jJPc+mi5RZXRs8hwX9Om81ATa4fvZBn/rOis/Tsp9BPqeqNzV1BJyV36Wv0X8XUGKdH/dkiIotxZoA07mbvP5t6A0zP8sEDCv9pKS+3mvfO2XG2FB1skgMZI31GH6xTZeBBRGkDrrHwe6stjUdgdz+lsSNBYwZvk5z1gPOwh0AML4NCUw4bD36o495yLC+OXf+V66+Tw4aK+ToXo5JHEhzMIcsj6hcN1nJxgCmJsgM5UUHwLf9bEdVnjxiRQwKhmS1Or55oFGvY9XVBc3Q0f5Ccv2uhIy1\",\"IV\":\"X9JfPv67xV0x5Uppawo2Kg==\",\"hmac\":\"329086e9c40e18e89c70c83d16d48cc6d7cfbf6bfcfccfe91ec4dbf799b8e0c9\"}","id":"A3h8SeJ6FYiU","sortindex":5908,"ttl":5184000}] 1317242815831 Sync.Collection TRACE onStartRequest called for channel [xpconnect wrapped nsIRequest]. 1317242815831 Sync.Collection TRACE onStartRequest: POST https://phx-sync080.services.mozilla.com/1.1/7lp55uifggf3uyialzokhbmdfti5s6zw/storage/history 1317242815831 Sync.Collection TRACE Channel for POST https://phx-sync080.services.mozilla.com/1.1/7lp55uifggf3uyialzokhbmdfti5s6zw/storage/history: isSuccessCode(0)? true 1317242815832 Sync.Collection TRACE Channel: flags = 640, URI = https://phx-sync080.services.mozilla.com/1.1/7lp55uifggf3uyialzokhbmdfti5s6zw/storage/history, HTTP success? true 1317242815832 Sync.Collection TRACE In _onComplete. Error is null. 1317242815832 Sync.Collection TRACE Channel: [xpconnect wrapped (nsISupports, nsIChannel, nsIRequest, nsIHttpChannel, nsIUploadChannel)] 1317242815832 Sync.Collection TRACE Action: POST 1317242815832 Sync.Collection TRACE Status: 200 1317242815832 Sync.Collection TRACE Success: true 1317242815832 Sync.Collection DEBUG mesg: POST success 200 https://phx-sync080.services.mozilla.com/1.1/7lp55uifggf3uyialzokhbmdfti5s6zw/storage/history 1317242815832 Sync.Collection DEBUG POST success 200 https://phx-sync080.services.mozilla.com/1.1/7lp55uifggf3uyialzokhbmdfti5s6zw/storage/history 1317242815832 Sync.Collection TRACE POST body: {"failed": {}, "modified": 1317242814.86, "success": ["A3h8SeJ6FYiU"]} 1317242815832 Sync.Collection TRACE Processing response headers. 1317242815832 Sync.Engine.Passwords INFO 0 outgoing items pre-reconciliation 1317242815833 Sync.Engine.Passwords INFO Records: 0 applied, 0 failed to apply, 0 newly failed to apply, 0 reconciled. 1317242815833 Sync.Engine.Prefs INFO 0 outgoing items pre-reconciliation 1317242815834 Sync.Engine.Prefs INFO Records: 0 applied, 0 failed to apply, 0 newly failed to apply, 0 reconciled. 1317242815834 Sync.Engine.Tabs DEBUG First sync, uploading all items 1317242815834 Sync.Engine.Tabs INFO 1 outgoing items pre-reconciliation 1317242815834 Sync.Collection TRACE In _doRequest. 1317242815835 Sync.Collection TRACE HTTP Header accept: application/newlines 1317242815835 Sync.Collection TRACE HTTP Header authorization: ***** (suppressed) 1317242816227 Sync.Collection TRACE onStartRequest called for channel [xpconnect wrapped nsIRequest]. 1317242816227 Sync.Collection TRACE onStartRequest: GET https://phx-sync080.services.mozilla.com/1.1/7lp55uifggf3uyialzokhbmdfti5s6zw/storage/tabs?full=1 1317242816228 Sync.Store.Tabs DEBUG Adding remote tabs from toderu's Firefox on desktop 1317242816231 Sync.Store.Tabs DEBUG Adding remote tabs from toderu's Firefox on Windows NT 6.1; WOW64 1317242816234 Sync.Collection TRACE Channel for GET https://phx-sync080.services.mozilla.com/1.1/7lp55uifggf3uyialzokhbmdfti5s6zw/storage/tabs?full=1: isSuccessCode(0)? true 1317242816234 Sync.Collection TRACE Channel: flags = 640, URI = https://phx-sync080.services.mozilla.com/1.1/7lp55uifggf3uyialzokhbmdfti5s6zw/storage/tabs?full=1, HTTP success? true 1317242816234 Sync.Collection TRACE In _onComplete. Error is null. 1317242816234 Sync.Collection TRACE Channel: [xpconnect wrapped (nsISupports, nsIChannel, nsIRequest, nsIHttpChannel)] 1317242816234 Sync.Collection TRACE Action: GET 1317242816234 Sync.Collection TRACE Status: 200 1317242816234 Sync.Collection TRACE Success: true 1317242816234 Sync.Collection DEBUG mesg: GET success 200 https://phx-sync080.services.mozilla.com/1.1/7lp55uifggf3uyialzokhbmdfti5s6zw/storage/tabs?full=1 1317242816234 Sync.Collection DEBUG GET success 200 https://phx-sync080.services.mozilla.com/1.1/7lp55uifggf3uyialzokhbmdfti5s6zw/storage/tabs?full=1 1317242816234 Sync.Collection TRACE GET body: null 1317242816234 Sync.Collection TRACE Processing response headers. 1317242816234 Sync.Engine.Tabs INFO Records: 3 applied, 0 failed to apply, 0 newly failed to apply, 0 reconciled. 1317242816236 Sync.Engine.Tabs INFO Uploading all of 1 records 1317242816236 Sync.Collection TRACE In _doRequest. 1317242816236 Sync.Collection TRACE HTTP Header authorization: ***** (suppressed) 1317242816237 Sync.Collection DEBUG POST Length: 676 1317242816237 Sync.Collection TRACE POST Body: [{"payload":"{\"ciphertext\":\"gJXYFHvoz8E+IK99If8VSVRRFah3pttJ0wBlktpAFT4pBknWxr42s5SblRV4N1f1Ez2NBqz8u2Ys+Cebf4q5zvH6aslaROSzhagvZqXkABtFQUnhvalwVIW1fQuv+ixrnf49VAF8thORwgUdDO/kcJ0Xig14kOLJMUzmiOQvsEC/Equ4b2+gZEoEycff0jIklFVAU9ETn/NDrYFUuiyX2wr4E4uw8ah39vfi65fVq9JwSLwU2hxufQwP2Ck01zL/rej6KH5vRb8XDaNDQfa/WFALuqtfv3F50rixKGE1wdxwAwKQFaCB5JK4/NpvrmUPXRQ/Mv3Rua7nMt6c6q1g/0GIB4XN44yAzb/Z5mux/xrhrurADCUFsNx9si2/M+iqYRB81FquvuhELDos9k+m1kXpHCiYvfOwDbuHkd8qzlifnPRwiBfN6tuLLhnnwVIMLRrTtPBYj0WcRmW5rYGJvyVYCBFB3GS7sIdeHHT6ouI=\",\"IV\":\"enNVkcpT/ypceKGSdxgZ9Q==\",\"hmac\":\"3bbb5a499ad57d5cab2d7536ef5d3b5570bfd22d8dbaa975a1f2fdeb5950e676\"}","id":"E8sgoLAJd4M-","ttl":604800}] 1317242816433 Sync.Collection TRACE onStartRequest called for channel [xpconnect wrapped nsIRequest]. 1317242816433 Sync.Collection TRACE onStartRequest: POST https://phx-sync080.services.mozilla.com/1.1/7lp55uifggf3uyialzokhbmdfti5s6zw/storage/tabs 1317242816433 Sync.Collection TRACE Channel for POST https://phx-sync080.services.mozilla.com/1.1/7lp55uifggf3uyialzokhbmdfti5s6zw/storage/tabs: isSuccessCode(0)? true 1317242816433 Sync.Collection TRACE Channel: flags = 640, URI = https://phx-sync080.services.mozilla.com/1.1/7lp55uifggf3uyialzokhbmdfti5s6zw/storage/tabs, HTTP success? true 1317242816433 Sync.Collection TRACE In _onComplete. Error is null. 1317242816433 Sync.Collection TRACE Channel: [xpconnect wrapped (nsISupports, nsIChannel, nsIRequest, nsIHttpChannel, nsIUploadChannel)] 1317242816433 Sync.Collection TRACE Action: POST 1317242816433 Sync.Collection TRACE Status: 200 1317242816433 Sync.Collection TRACE Success: true 1317242816433 Sync.Collection DEBUG mesg: POST success 200 https://phx-sync080.services.mozilla.com/1.1/7lp55uifggf3uyialzokhbmdfti5s6zw/storage/tabs 1317242816433 Sync.Collection DEBUG POST success 200 https://phx-sync080.services.mozilla.com/1.1/7lp55uifggf3uyialzokhbmdfti5s6zw/storage/tabs 1317242816433 Sync.Collection TRACE POST body: {"failed": {}, "modified": 1317242815.49, "success": ["E8sgoLAJd4M-"]} 1317242816433 Sync.Collection TRACE Processing response headers. 1317242816434 Sync.Service INFO Sync completed at 2011-09-28 23:46:56 after 2.36 secs.
Attached file nightly trace log —
sorry for previous c/p. the format gets wrong. attached
Attached file ff7 trace log —
(In reply to toderu from comment #8) > Created attachment 563199 [details] > ff7 trace log Thanks for these! So it really does appear that the issue doesn't occur in Nightly. I'll take a close look at the Fx7 log, see if I can determine what was going on.
@joanj -> does not like a firefox bug, just mozilla sync servers down: $ curl -k https://phx-sync340.services.mozilla.com/1.1/jjcmmgb/info/collections "server issue: database marked as down"
typo "does not look like" anyway, the I get same error since 03 October. additional error: "server issue: database is not healthy"
Looks like this was fixed.
Status: NEW → RESOLVED
Closed: 13 years ago
Resolution: --- → INCOMPLETE
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.

Attachment

General

Creator:
Created:
Updated:
Size: