Skip to content

Instantly share code, notes, and snippets.

@st3fan

st3fan/gist:8619763

Created January 25, 2014 17:13
Show Gist options
  • Star 0 You must be signed in to star a gist
  • Fork 0 You must be signed in to fork a gist
  • Save st3fan/8619763 to your computer and use it in GitHub Desktop.
Save st3fan/8619763 to your computer and use it in GitHub Desktop.
Download ZIP
Raw
gistfile1.txt
1390664392449 Sync.Service INFO Loading Weave 1.31.0
1390664392452 Sync.Engine.Clients DEBUG Engine initialized
1390664392452 Sync.Engine.Clients DEBUG Resetting clients last sync time
1390664392464 Sync.Engine.Bookmarks DEBUG Engine initialized
1390664392468 Sync.Engine.Forms DEBUG Engine initialized
1390664392472 Sync.Engine.History DEBUG Engine initialized
1390664392477 Sync.Engine.Passwords DEBUG Engine initialized
1390664392480 Sync.Engine.Prefs DEBUG Engine initialized
1390664392484 Sync.Engine.Tabs DEBUG Engine initialized
1390664392484 Sync.Engine.Tabs TRACE Loading json from disk: toFetch/tabs
1390664392484 Sync.Engine.Tabs TRACE Loading json from disk: failed/tabs
1390664392485 Sync.Engine.Tabs DEBUG Resetting tabs last sync time
1390664392492 Sync.Engine.Addons DEBUG Engine initialized
1390664392493 Sync.Service INFO Mozilla/5.0 (Macintosh; Intel Mac OS X 10.9; rv:29.0) Gecko/20100101 Firefox/29.0
1390664392497 Sync.SyncScheduler TRACE Setting SyncScheduler policy values to defaults.
1390664392497 Sync.SyncScheduler DEBUG Clearing sync triggers and the global score.
1390664392499 Sync.Status DEBUG Status.login: error.login.reason.no_username => error.login.reason.no_username
1390664392499 Sync.Status DEBUG Status.service: service.client_not_configured => service.client_not_configured
1390664392499 Sync.Service DEBUG Caching URLs under storage user base: http://db1.oldsync.dev.lcip.org/1.1/null/
1390664392499 Sync.Status DEBUG Status.login: error.login.reason.no_username => error.login.reason.no_username
1390664392499 Sync.Status DEBUG Status.service: service.client_not_configured => service.client_not_configured
1390664392523 Sync.SyncScheduler TRACE Handling weave:service:ready
1390664392527 Sync.Status DEBUG Status.login: error.login.reason.no_username => error.login.reason.no_username
1390664392527 Sync.Status DEBUG Status.service: service.client_not_configured => service.client_not_configured
1390664392529 Sync.Status DEBUG Status.login: error.login.reason.no_username => error.login.reason.no_username
1390664392529 Sync.Status DEBUG Status.service: service.client_not_configured => service.client_not_configured
1390664392530 Sync.Status DEBUG Status.login: error.login.reason.no_username => error.login.reason.no_username
1390664392530 Sync.Status DEBUG Status.service: service.client_not_configured => service.client_not_configured
1390664392704 Sync.Status DEBUG Status.login: error.login.reason.no_username => error.login.reason.no_username
1390664392704 Sync.Status DEBUG Status.service: service.client_not_configured => service.client_not_configured
1390664399664 Sync.Status DEBUG Status.login: error.login.reason.no_username => error.login.reason.no_username
1390664399664 Sync.Status DEBUG Status.service: service.client_not_configured => service.client_not_configured
1390664405209 Sync.BrowserIDManager INFO Username changed. Removing stored credentials.
1390664435566 Sync.Status DEBUG Status.login: error.login.reason.no_username => error.login.reason.no_username
1390664435567 Sync.Status DEBUG Status.service: service.client_not_configured => service.client_not_configured
1390664477787 Sync.Status DEBUG Status.login: error.login.reason.no_username => error.login.reason.no_username
1390664477787 Sync.Status DEBUG Status.service: service.client_not_configured => service.client_not_configured
1390664478646 Sync.Status DEBUG Status.login: error.login.reason.no_username => error.login.reason.no_username
1390664478646 Sync.Status DEBUG Status.service: service.client_not_configured => service.client_not_configured
1390664487702 Sync.Status DEBUG Status.login: error.login.reason.no_username => error.login.reason.no_username
1390664487702 Sync.Status DEBUG Status.service: service.client_not_configured => service.client_not_configured
1390664526997 Sync.Status DEBUG Status.login: error.login.reason.no_username => error.login.reason.no_username
1390664526997 Sync.Status DEBUG Status.service: service.client_not_configured => service.client_not_configured
1390664528541 Sync.Status DEBUG Status.login: error.login.reason.no_username => error.login.reason.no_username
1390664528541 Sync.Status DEBUG Status.service: service.client_not_configured => service.client_not_configured
1390664530540 Sync.Status DEBUG Status.login: error.login.reason.no_username => error.login.reason.no_username
1390664530540 Sync.Status DEBUG Status.service: service.client_not_configured => service.client_not_configured
1390664549300 Sync.Status DEBUG Status.login: error.login.reason.no_username => error.login.reason.no_username
1390664549300 Sync.Status DEBUG Status.service: service.client_not_configured => service.client_not_configured
1390664601151 Sync.Status DEBUG Status.login: error.login.reason.no_username => error.login.reason.no_username
1390664601151 Sync.Status DEBUG Status.service: service.client_not_configured => service.client_not_configured
1390664629274 Sync.BrowserIDManager INFO Username changed. Removing stored credentials.
1390664629275 Sync.BrowserIDManager INFO Starting background fetch for key bundle.
1390664629276 Sync.BrowserIDManager INFO Fetching Sync token from: http://auth.oldsync.dev.lcip.org/1.0/sync/1.1
1390664630058 Sync.BrowserIDManager INFO Username changed. Removing stored credentials.
1390664630061 Sync.BulkKeyBundle INFO BulkKeyBundle being created for undefined
1390664630062 Sync.Service DEBUG Caching URLs under storage user base: http://db1.oldsync.dev.lcip.org/1.1/200/
1390664630062 Sync.BrowserIDManager INFO Background fetch for key bundle done - logging in
1390664630062 Sync.Service TRACE Event: weave:service:login:start
1390664630067 Sync.Status DEBUG Status.service: service.client_not_configured => success.status_ok
1390664630067 Sync.Status DEBUG Status.service: success.status_ok => success.status_ok
1390664630067 Sync.Service INFO Logging in user 200
1390664630067 Sync.Service DEBUG Caching URLs under storage user base: http://db1.oldsync.dev.lcip.org/1.1/200/
1390664630068 Sync.Resource TRACE In _doRequest.
1390664630071 Sync.Resource TRACE HTTP Header authorization: ***** (suppressed)
1390664630619 Sync.Resource TRACE onStartRequest called for channel [xpconnect wrapped (nsISupports, nsIChannel, nsIRequest, nsIHttpChannel)].
1390664630621 Sync.Resource TRACE onStartRequest: GET http://db1.oldsync.dev.lcip.org/1.1/200/info/collections
1390664630621 Sync.Resource TRACE Channel for GET http://db1.oldsync.dev.lcip.org/1.1/200/info/collections: isSuccessCode(0)? true
1390664630622 Sync.Resource TRACE Channel: flags = 17024, URI = http://db1.oldsync.dev.lcip.org/1.1/200/info/collections, HTTP success? true
1390664630622 Sync.Resource TRACE In _onComplete. Error is null.
1390664630627 Sync.Resource TRACE Channel: [xpconnect wrapped (nsISupports, nsIChannel, nsIRequest, nsIHttpChannel)]
1390664630628 Sync.Resource TRACE Action: GET
1390664630628 Sync.Resource TRACE Status: 200
1390664630628 Sync.Resource TRACE Success: true
1390664630628 Sync.Resource DEBUG mesg: GET success 200 http://db1.oldsync.dev.lcip.org/1.1/200/info/collections
1390664630628 Sync.Resource DEBUG GET success 200 http://db1.oldsync.dev.lcip.org/1.1/200/info/collections
1390664630628 Sync.Resource TRACE GET body: {"tabs": 1390663985.33, "clients": 1390662140.97, "crypto": 1390661839.57, "meta": 1390661862.23, "bookmarks": 1390663985.05, "prefs": 1390661861.54, "history": 1390663985.22}
1390664630628 Sync.Resource TRACE Processing response headers.
1390664630629 Sync.Service DEBUG Fetching global metadata record
1390664630629 Sync.Resource TRACE In _doRequest.
1390664630640 Sync.Resource TRACE HTTP Header authorization: ***** (suppressed)
1390664630793 Sync.Resource TRACE onStartRequest called for channel [xpconnect wrapped (nsISupports, nsIChannel, nsIRequest, nsIHttpChannel)].
1390664630793 Sync.Resource TRACE onStartRequest: GET http://db1.oldsync.dev.lcip.org/1.1/200/storage/meta/global
1390664630794 Sync.Resource TRACE Channel for GET http://db1.oldsync.dev.lcip.org/1.1/200/storage/meta/global: isSuccessCode(0)? true
1390664630794 Sync.Resource TRACE Channel: flags = 17024, URI = http://db1.oldsync.dev.lcip.org/1.1/200/storage/meta/global, HTTP success? true
1390664630794 Sync.Resource TRACE In _onComplete. Error is null.
1390664630794 Sync.Resource TRACE Channel: [xpconnect wrapped (nsISupports, nsIChannel, nsIRequest, nsIHttpChannel)]
1390664630794 Sync.Resource TRACE Action: GET
1390664630794 Sync.Resource TRACE Status: 200
1390664630794 Sync.Resource TRACE Success: true
1390664630794 Sync.Resource DEBUG mesg: GET success 200 http://db1.oldsync.dev.lcip.org/1.1/200/storage/meta/global
1390664630794 Sync.Resource DEBUG GET success 200 http://db1.oldsync.dev.lcip.org/1.1/200/storage/meta/global
1390664630794 Sync.Resource TRACE GET body: {"payload": "{\"syncID\":\"TgHiyQv6L77k\",\"storageVersion\":5,\"engines\":{\"clients\":{\"version\":1,\"syncID\":\"Ef1BQNjAPiFz\"},\"bookmarks\":{\"version\":2,\"syncID\":\"ZY6K0BVi4_cH\"},\"forms\":{\"version\":1,\"syncID\":\"6W7mhirIhS8W\"},\"history\":{\"version\":1,\"syncID\":\"J2S8KyetBPGk\"},\"passwords\":{\"version\":1,\"syncID\":\"fn4wrAzfOYiy\"},\"prefs\":{\"version\":2,\"syncID\":\"SAtOLBpAwILB\"},\"tabs\":{\"version\":1,\"syncID\":\"wkZgqW8UJxcc\"},\"addons\":{\"version\":1,\"syncID\":\"yi3GnlskbM7e\"}}}", "id": "global", "modified": 1390661862.23}
1390664630794 Sync.Resource TRACE Processing response headers.
1390664630795 Sync.Service DEBUG Weave Version: 1.31.0 Local Storage: 5 Remote Storage: 5
1390664630795 Sync.Service INFO Sync key is up-to-date: no need to upgrade.
1390664630795 Sync.Service DEBUG Fetching and verifying -- or generating -- symmetric keys.
1390664630795 Sync.Service TRACE In _fetchInfo: http://db1.oldsync.dev.lcip.org/1.1/200/info/collections
1390664630796 Sync.Resource TRACE In _doRequest.
1390664630798 Sync.Resource TRACE HTTP Header authorization: ***** (suppressed)
1390664630844 Sync.Resource TRACE onStartRequest called for channel [xpconnect wrapped (nsISupports, nsIChannel, nsIRequest, nsIHttpChannel)].
1390664630845 Sync.Resource TRACE onStartRequest: GET http://db1.oldsync.dev.lcip.org/1.1/200/info/collections
1390664630845 Sync.Resource TRACE Channel for GET http://db1.oldsync.dev.lcip.org/1.1/200/info/collections: isSuccessCode(0)? true
1390664630845 Sync.Resource TRACE Channel: flags = 17024, URI = http://db1.oldsync.dev.lcip.org/1.1/200/info/collections, HTTP success? true
1390664630845 Sync.Resource TRACE In _onComplete. Error is null.
1390664630845 Sync.Resource TRACE Channel: [xpconnect wrapped (nsISupports, nsIChannel, nsIRequest, nsIHttpChannel)]
1390664630845 Sync.Resource TRACE Action: GET
1390664630845 Sync.Resource TRACE Status: 200
1390664630845 Sync.Resource TRACE Success: true
1390664630846 Sync.Resource DEBUG mesg: GET success 200 http://db1.oldsync.dev.lcip.org/1.1/200/info/collections
1390664630846 Sync.Resource DEBUG GET success 200 http://db1.oldsync.dev.lcip.org/1.1/200/info/collections
1390664630846 Sync.Resource TRACE GET body: {"tabs": 1390663985.33, "clients": 1390662140.97, "crypto": 1390661839.57, "meta": 1390661862.23, "bookmarks": 1390663985.05, "prefs": 1390661861.54, "history": 1390663985.22}
1390664630846 Sync.Resource TRACE Processing response headers.
1390664630847 Sync.Service INFO Testing info/collections: {"tabs":1390663985.33,"clients":1390662140.97,"crypto":1390661839.57,"meta":1390661862.23,"bookmarks":1390663985.05,"prefs":1390661861.54,"history":1390663985.22}
1390664630847 Sync.CollectionKeyManager INFO Testing for updateNeeded. Last modified: 0
1390664630847 Sync.Service INFO collection keys reports that a key update is needed.
1390664630847 Sync.Resource TRACE In _doRequest.
1390664630850 Sync.Resource TRACE HTTP Header authorization: ***** (suppressed)
1390664630892 Sync.Resource TRACE onStartRequest called for channel [xpconnect wrapped (nsISupports, nsIChannel, nsIRequest, nsIHttpChannel)].
1390664630892 Sync.Resource TRACE onStartRequest: GET http://db1.oldsync.dev.lcip.org/1.1/200/storage/crypto/keys
1390664630893 Sync.Resource TRACE Channel for GET http://db1.oldsync.dev.lcip.org/1.1/200/storage/crypto/keys: isSuccessCode(0)? true
1390664630893 Sync.Resource TRACE Channel: flags = 17024, URI = http://db1.oldsync.dev.lcip.org/1.1/200/storage/crypto/keys, HTTP success? true
1390664630893 Sync.Resource TRACE In _onComplete. Error is null.
1390664630893 Sync.Resource TRACE Channel: [xpconnect wrapped (nsISupports, nsIChannel, nsIRequest, nsIHttpChannel)]
1390664630893 Sync.Resource TRACE Action: GET
1390664630893 Sync.Resource TRACE Status: 200
1390664630893 Sync.Resource TRACE Success: true
1390664630893 Sync.Resource DEBUG mesg: GET success 200 http://db1.oldsync.dev.lcip.org/1.1/200/storage/crypto/keys
1390664630893 Sync.Resource DEBUG GET success 200 http://db1.oldsync.dev.lcip.org/1.1/200/storage/crypto/keys
1390664630893 Sync.Resource TRACE GET body: {"payload": "{\"ciphertext\":\"2zFqg8FQ5Xw79L3XfcSBX7R9db65pAnT2Cr8NpraDLxvKLTegxx8UBnkRVq1Li6HB9E1khskqc5wvb3SdTot7qFjzNt2ZEXwmQOXBxeeobNx/FGIyHG6n7PKr8CuhYBccgMWDIdiR1riWgFPnMJcc8jIxrsIOp2iqEw+Yx4u5UG4mipXqgwQ5VishkgRJNzdDPD3VCJV23ERfWHqDDXPJw==\",\"IV\":\"i5Jqdu5naamYpbCHIbSDAQ==\",\"hmac\":\"c6d38de55345f1c8b1303ff21d6bf463d11e36b8861ee9729460d9f1950221c7\"}", "id": "keys", "modified": 1390661839.57}
1390664630893 Sync.Resource TRACE Processing response headers.
1390664630894 Sync.CollectionKeyManager INFO Updating collection keys...
1390664630896 Sync.CollectionKeyManager INFO Setting collection keys contents. Our last modified: 0, input modified: 1390661839.57.
1390664630896 Sync.BulkKeyBundle INFO BulkKeyBundle being created for [default]
1390664630896 Sync.CollectionKeyManager INFO Processing downloaded per-collection keys.
1390664630896 Sync.CollectionKeyManager INFO Clearing collection keys...
1390664630896 Sync.CollectionKeyManager INFO Saving downloaded keys.
1390664630897 Sync.CollectionKeyManager INFO Bumping last modified to 1390661839.57
1390664630897 Sync.CollectionKeyManager INFO Collection keys updated.
1390664630897 Sync.Status DEBUG Status.login: error.login.reason.no_username => success.login
1390664630897 Sync.Status DEBUG Status.service: success.status_ok => success.status_ok
1390664630897 Sync.Service TRACE Event: weave:service:login:finish
1390664630898 Sync.Status DEBUG Status.service: success.status_ok => success.status_ok
1390664630899 Sync.Status DEBUG Status.service: success.status_ok => success.status_ok
1390664630899 Sync.Status DEBUG Status.service: success.status_ok => success.status_ok
1390664656199 Sync.Status DEBUG Status.service: success.status_ok => success.status_ok
1390664658618 Sync.Status DEBUG Status.service: success.status_ok => success.status_ok
1390664659521 Sync.Status DEBUG Status.service: success.status_ok => success.status_ok
1390664660544 Sync.ErrorHandler DEBUG Beginning user-triggered sync.
1390664660545 Sync.Service DEBUG User-Agent: Firefox/29.0a1 FxSync/1.31.0.20140124171949.
1390664660545 Sync.Service INFO Starting sync at 2014-01-25 10:44:20
1390664660545 Sync.Service TRACE In sync: no need to login.
1390664660545 Sync.Service TRACE Event: weave:service:sync:start
1390664660546 Sync.SyncScheduler TRACE Handling weave:service:sync:start
1390664660546 Sync.SyncScheduler DEBUG Clearing sync triggers and the global score.
1390664660547 Sync.Status INFO Resetting Status.
1390664660547 Sync.Status DEBUG Status.service: success.status_ok => success.status_ok
1390664660547 Sync.Service TRACE In _fetchInfo: http://db1.oldsync.dev.lcip.org/1.1/200/info/collections
1390664660548 Sync.Resource TRACE In _doRequest.
1390664660554 Sync.Resource TRACE HTTP Header authorization: ***** (suppressed)
1390664660611 Sync.Resource TRACE onStartRequest called for channel [xpconnect wrapped (nsISupports, nsIChannel, nsIRequest, nsIHttpChannel)].
1390664660612 Sync.Resource TRACE onStartRequest: GET http://db1.oldsync.dev.lcip.org/1.1/200/info/collections
1390664660612 Sync.Resource TRACE Channel for GET http://db1.oldsync.dev.lcip.org/1.1/200/info/collections: isSuccessCode(0)? true
1390664660612 Sync.Resource TRACE Channel: flags = 17024, URI = http://db1.oldsync.dev.lcip.org/1.1/200/info/collections, HTTP success? true
1390664660612 Sync.Resource TRACE In _onComplete. Error is null.
1390664660612 Sync.Resource TRACE Channel: [xpconnect wrapped (nsISupports, nsIChannel, nsIRequest, nsIHttpChannel)]
1390664660612 Sync.Resource TRACE Action: GET
1390664660612 Sync.Resource TRACE Status: 200
1390664660612 Sync.Resource TRACE Success: true
1390664660613 Sync.Resource DEBUG mesg: GET success 200 http://db1.oldsync.dev.lcip.org/1.1/200/info/collections
1390664660613 Sync.Resource DEBUG GET success 200 http://db1.oldsync.dev.lcip.org/1.1/200/info/collections
1390664660613 Sync.Resource TRACE GET body: {"tabs": 1390663985.33, "clients": 1390662140.97, "crypto": 1390661839.57, "meta": 1390661862.23, "bookmarks": 1390663985.05, "prefs": 1390661861.54, "history": 1390663985.22}
1390664660613 Sync.Resource TRACE Processing response headers.
1390664660614 Sync.Service DEBUG Fetching global metadata record
1390664660614 Sync.Service DEBUG Clearing cached meta record. metaModified is undefined, setting to 1390661862.23
1390664660614 Sync.Resource TRACE In _doRequest.
1390664660617 Sync.Resource TRACE HTTP Header authorization: ***** (suppressed)
1390664660664 Sync.Resource TRACE onStartRequest called for channel [xpconnect wrapped (nsISupports, nsIChannel, nsIRequest, nsIHttpChannel)].
1390664660664 Sync.Resource TRACE onStartRequest: GET http://db1.oldsync.dev.lcip.org/1.1/200/storage/meta/global
1390664660665 Sync.Resource TRACE Channel for GET http://db1.oldsync.dev.lcip.org/1.1/200/storage/meta/global: isSuccessCode(0)? true
1390664660665 Sync.Resource TRACE Channel: flags = 17024, URI = http://db1.oldsync.dev.lcip.org/1.1/200/storage/meta/global, HTTP success? true
1390664660665 Sync.Resource TRACE In _onComplete. Error is null.
1390664660665 Sync.Resource TRACE Channel: [xpconnect wrapped (nsISupports, nsIChannel, nsIRequest, nsIHttpChannel)]
1390664660665 Sync.Resource TRACE Action: GET
1390664660665 Sync.Resource TRACE Status: 200
1390664660665 Sync.Resource TRACE Success: true
1390664660665 Sync.Resource DEBUG mesg: GET success 200 http://db1.oldsync.dev.lcip.org/1.1/200/storage/meta/global
1390664660665 Sync.Resource DEBUG GET success 200 http://db1.oldsync.dev.lcip.org/1.1/200/storage/meta/global
1390664660665 Sync.Resource TRACE GET body: {"payload": "{\"syncID\":\"TgHiyQv6L77k\",\"storageVersion\":5,\"engines\":{\"clients\":{\"version\":1,\"syncID\":\"Ef1BQNjAPiFz\"},\"bookmarks\":{\"version\":2,\"syncID\":\"ZY6K0BVi4_cH\"},\"forms\":{\"version\":1,\"syncID\":\"6W7mhirIhS8W\"},\"history\":{\"version\":1,\"syncID\":\"J2S8KyetBPGk\"},\"passwords\":{\"version\":1,\"syncID\":\"fn4wrAzfOYiy\"},\"prefs\":{\"version\":2,\"syncID\":\"SAtOLBpAwILB\"},\"tabs\":{\"version\":1,\"syncID\":\"wkZgqW8UJxcc\"},\"addons\":{\"version\":1,\"syncID\":\"yi3GnlskbM7e\"}}}", "id": "global", "modified": 1390661862.23}
1390664660665 Sync.Resource TRACE Processing response headers.
1390664660666 Sync.Service DEBUG Weave Version: 1.31.0 Local Storage: 5 Remote Storage: 5
1390664660666 Sync.Service INFO Sync key is up-to-date: no need to upgrade.
1390664660666 Sync.Service DEBUG Fetching and verifying -- or generating -- symmetric keys.
1390664660666 Sync.Service INFO Testing info/collections: {"tabs":1390663985.33,"clients":1390662140.97,"crypto":1390661839.57,"meta":1390661862.23,"bookmarks":1390663985.05,"prefs":1390661861.54,"history":1390663985.22}
1390664660666 Sync.CollectionKeyManager INFO Testing for updateNeeded. Last modified: 1390661839.57
1390664660666 Sync.Synchronizer DEBUG Refreshing client list.
1390664660667 Sync.Engine.Clients DEBUG First sync, uploading all items
1390664660668 Sync.Engine.Clients INFO 1 outgoing items pre-reconciliation
1390664660669 Sync.Collection TRACE In _doRequest.
1390664660671 Sync.Collection TRACE HTTP Header accept: application/newlines
1390664660671 Sync.Collection TRACE HTTP Header authorization: ***** (suppressed)
1390664660717 Sync.Collection TRACE onStartRequest called for channel [xpconnect wrapped (nsISupports, nsIChannel, nsIRequest, nsIHttpChannel)].
1390664660717 Sync.Collection TRACE onStartRequest: GET http://db1.oldsync.dev.lcip.org/1.1/200/storage/clients?full=1
1390664660720 Sync.Collection TRACE Channel for GET http://db1.oldsync.dev.lcip.org/1.1/200/storage/clients?full=1: isSuccessCode(0)? true
1390664660720 Sync.Collection TRACE Channel: flags = 17024, URI = http://db1.oldsync.dev.lcip.org/1.1/200/storage/clients?full=1, HTTP success? true
1390664660720 Sync.Collection TRACE In _onComplete. Error is null.
1390664660720 Sync.Collection TRACE Channel: [xpconnect wrapped (nsISupports, nsIChannel, nsIRequest, nsIHttpChannel)]
1390664660720 Sync.Collection TRACE Action: GET
1390664660720 Sync.Collection TRACE Status: 200
1390664660720 Sync.Collection TRACE Success: true
1390664660720 Sync.Collection DEBUG mesg: GET success 200 http://db1.oldsync.dev.lcip.org/1.1/200/storage/clients?full=1
1390664660720 Sync.Collection DEBUG GET success 200 http://db1.oldsync.dev.lcip.org/1.1/200/storage/clients?full=1
1390664660720 Sync.Collection TRACE GET body: null
1390664660721 Sync.Collection TRACE Processing response headers.
1390664660721 Sync.Engine.Clients INFO Records: 1 applied, 1 successfully, 0 failed to apply, 0 newly failed to apply, 0 reconciled.
1390664660722 Sync.SyncScheduler TRACE Handling weave:engine:sync:applied
1390664660722 Sync.SyncScheduler TRACE Engine clients successfully applied 1 items.
1390664660722 Sync.ErrorHandler TRACE Handling weave:engine:sync:applied
1390664660724 Sync.Engine.Clients INFO Uploading all of 1 records
1390664660725 Sync.Collection TRACE In _doRequest.
1390664660727 Sync.Collection TRACE HTTP Header authorization: ***** (suppressed)
1390664660727 Sync.Collection DEBUG POST Length: 357
1390664660727 Sync.Collection TRACE POST Body: [{"payload":"{\"ciphertext\":\"ABw3V8TfOcbMj/KjJ2P0qSa/fXzWfiloHcOGiruBF6plOSvBzCUeVEfKgsyWoFlOPFMVerFSsiZoJ0DDLsfB7/3zNlViwk54XquPG6RBDfs0y/HZRWz0Fm+QawAtuPbbL4pShiZLCV+AuYkQlJ+NnuZ7asJpb5Z/ayHPiP8CwWk=\",\"IV\":\"x4r+eGNJjUX398RmpK2GVg==\",\"hmac\":\"2f9d1322ed4a67405572480532f865524aa23d96e36f8c7eca730289c80e5c9c\"}","id":"6B3UsiWcYGV9","ttl":1814400}]
1390664660792 Sync.Collection TRACE onStartRequest called for channel [xpconnect wrapped (nsISupports, nsIChannel, nsIRequest, nsIHttpChannel, nsIUploadChannel)].
1390664660793 Sync.Collection TRACE onStartRequest: POST http://db1.oldsync.dev.lcip.org/1.1/200/storage/clients
1390664660793 Sync.Collection TRACE Channel for POST http://db1.oldsync.dev.lcip.org/1.1/200/storage/clients: isSuccessCode(0)? true
1390664660793 Sync.Collection TRACE Channel: flags = 17024, URI = http://db1.oldsync.dev.lcip.org/1.1/200/storage/clients, HTTP success? true
1390664660793 Sync.Collection TRACE In _onComplete. Error is null.
1390664660793 Sync.Collection TRACE Channel: [xpconnect wrapped (nsISupports, nsIChannel, nsIRequest, nsIHttpChannel, nsIUploadChannel)]
1390664660794 Sync.Collection TRACE Action: POST
1390664660794 Sync.Collection TRACE Status: 200
1390664660794 Sync.Collection TRACE Success: true
1390664660794 Sync.Collection DEBUG mesg: POST success 200 http://db1.oldsync.dev.lcip.org/1.1/200/storage/clients
1390664660794 Sync.Collection DEBUG POST success 200 http://db1.oldsync.dev.lcip.org/1.1/200/storage/clients
1390664660794 Sync.Collection TRACE POST body: {"failed": {}, "modified": 1390664660.29, "success": ["6B3UsiWcYGV9"]}
1390664660794 Sync.Collection TRACE Processing response headers.
1390664660795 Sync.SyncScheduler TRACE Handling weave:engine:sync:finish
1390664660795 Sync.Synchronizer INFO Updating enabled engines: 1 clients.
1390664660798 Sync.Engine.Bookmarks INFO 0 outgoing items pre-reconciliation
1390664660800 Sync.Engine.Bookmarks INFO Records: 0 applied, 0 successfully, 0 failed to apply, 0 newly failed to apply, 0 reconciled.
1390664660800 Sync.SyncScheduler TRACE Handling weave:engine:sync:applied
1390664660800 Sync.SyncScheduler TRACE Engine bookmarks successfully applied 0 items.
1390664660800 Sync.ErrorHandler TRACE Handling weave:engine:sync:applied
1390664660816 Sync.SyncScheduler TRACE Handling weave:engine:sync:finish
1390664660816 Sync.Engine.Forms DEBUG First sync, uploading all items
1390664660818 Sync.Engine.Forms INFO 0 outgoing items pre-reconciliation
1390664660819 Sync.Engine.Forms INFO Records: 0 applied, 0 successfully, 0 failed to apply, 0 newly failed to apply, 0 reconciled.
1390664660820 Sync.SyncScheduler TRACE Handling weave:engine:sync:applied
1390664660820 Sync.SyncScheduler TRACE Engine forms successfully applied 0 items.
1390664660820 Sync.ErrorHandler TRACE Handling weave:engine:sync:applied
1390664660820 Sync.SyncScheduler TRACE Handling weave:engine:sync:finish
1390664660821 Sync.Engine.History INFO 1 outgoing items pre-reconciliation
1390664660822 Sync.Engine.History INFO Records: 0 applied, 0 successfully, 0 failed to apply, 0 newly failed to apply, 0 reconciled.
1390664660822 Sync.SyncScheduler TRACE Handling weave:engine:sync:applied
1390664660823 Sync.SyncScheduler TRACE Engine history successfully applied 0 items.
1390664660823 Sync.ErrorHandler TRACE Handling weave:engine:sync:applied
1390664660827 Sync.Engine.History INFO Uploading all of 1 records
1390664660827 Sync.Collection TRACE In _doRequest.
1390664660829 Sync.Collection TRACE HTTP Header authorization: ***** (suppressed)
1390664660829 Sync.Collection DEBUG POST Length: 249
1390664660829 Sync.Collection TRACE POST Body: [{"payload":"{\"ciphertext\":\"nbyP4CBoEY/BimOedHCJWzwGpqP3ydPLUnwtSI/NShx5n9A1XXXdcq2bgZeROVOS\",\"IV\":\"63Rg5lT+FsWxwFQmdRwenA==\",\"hmac\":\"55770bdee172c3635f458485545fefe24411a3437c101e2082c25d5f0c789a62\"}","id":"FRLLUzMB2GaF","ttl":5184000}]
1390664660885 Sync.Collection TRACE onStartRequest called for channel [xpconnect wrapped (nsISupports, nsIChannel, nsIRequest, nsIHttpChannel, nsIUploadChannel)].
1390664660885 Sync.Collection TRACE onStartRequest: POST http://db1.oldsync.dev.lcip.org/1.1/200/storage/history
1390664660885 Sync.Collection TRACE Channel for POST http://db1.oldsync.dev.lcip.org/1.1/200/storage/history: isSuccessCode(0)? true
1390664660885 Sync.Collection TRACE Channel: flags = 17024, URI = http://db1.oldsync.dev.lcip.org/1.1/200/storage/history, HTTP success? true
1390664660886 Sync.Collection TRACE In _onComplete. Error is null.
1390664660886 Sync.Collection TRACE Channel: [xpconnect wrapped (nsISupports, nsIChannel, nsIRequest, nsIHttpChannel, nsIUploadChannel)]
1390664660886 Sync.Collection TRACE Action: POST
1390664660886 Sync.Collection TRACE Status: 200
1390664660886 Sync.Collection TRACE Success: true
1390664660886 Sync.Collection DEBUG mesg: POST success 200 http://db1.oldsync.dev.lcip.org/1.1/200/storage/history
1390664660886 Sync.Collection DEBUG POST success 200 http://db1.oldsync.dev.lcip.org/1.1/200/storage/history
1390664660886 Sync.Collection TRACE POST body: {"failed": {}, "modified": 1390664660.38, "success": ["FRLLUzMB2GaF"]}
1390664660886 Sync.Collection TRACE Processing response headers.
1390664660887 Sync.SyncScheduler TRACE Handling weave:engine:sync:finish
1390664660888 Sync.Engine.Passwords DEBUG First sync, uploading all items
1390664660891 Sync.Engine.Passwords INFO 0 outgoing items pre-reconciliation
1390664660893 Sync.Engine.Passwords INFO Records: 0 applied, 0 successfully, 0 failed to apply, 0 newly failed to apply, 0 reconciled.
1390664660894 Sync.SyncScheduler TRACE Handling weave:engine:sync:applied
1390664660894 Sync.SyncScheduler TRACE Engine passwords successfully applied 0 items.
1390664660894 Sync.ErrorHandler TRACE Handling weave:engine:sync:applied
1390664660894 Sync.SyncScheduler TRACE Handling weave:engine:sync:finish
1390664660895 Sync.Engine.Prefs INFO 0 outgoing items pre-reconciliation
1390664660896 Sync.Engine.Prefs INFO Records: 0 applied, 0 successfully, 0 failed to apply, 0 newly failed to apply, 0 reconciled.
1390664660896 Sync.SyncScheduler TRACE Handling weave:engine:sync:applied
1390664660897 Sync.SyncScheduler TRACE Engine prefs successfully applied 0 items.
1390664660897 Sync.ErrorHandler TRACE Handling weave:engine:sync:applied
1390664660897 Sync.SyncScheduler TRACE Handling weave:engine:sync:finish
1390664660897 Sync.Engine.Tabs TRACE Event: weave:engine:sync:start
1390664660898 Sync.Engine.Tabs DEBUG First sync, uploading all items
1390664660898 Sync.Engine.Tabs INFO 1 outgoing items pre-reconciliation
1390664660899 Sync.Engine.Tabs TRACE Downloading & applying server changes
1390664660900 Sync.Collection TRACE In _doRequest.
1390664660902 Sync.Collection TRACE HTTP Header accept: application/newlines
1390664660902 Sync.Collection TRACE HTTP Header authorization: ***** (suppressed)
1390664660948 Sync.Collection TRACE onStartRequest called for channel [xpconnect wrapped (nsISupports, nsIChannel, nsIRequest, nsIHttpChannel)].
1390664660948 Sync.Collection TRACE onStartRequest: GET http://db1.oldsync.dev.lcip.org/1.1/200/storage/tabs?full=1
1390664660953 Sync.Engine.Tabs TRACE Incoming: { id: 6B3UsiWcYGV9 index: 0 modified: 1390663985.33 ttl: 604800 payload: {"id":"6B3UsiWcYGV9","clientName":"stefan's Nightly on Baxial","tabs":[{"title":"Justin Bieber's people really wish he'd go to rehab: reports | Toronto Star","urlHistory":["http://www.thestar.com/entertainment/stargazing_blog/2014/01/justin_bieber_s_people_really_wish_he_d_go_to_rehab__reports.html"],"icon":"","lastUsed":1390663972},{"title":"Official Blog: Today’s outage for several Google services","urlHistory":["http://googleblog.blogspot.ca/2014/01/todays-outage-for-several-google.html"],"icon":"","lastUsed":1390662425},{"title":"Hacker News","urlHistory":["https://news.ycombinator.com/"],"icon":"","lastUsed":1390662413}]} collection: tabs }
1390664660953 Sync.Engine.Tabs TRACE Reconciling 6B3UsiWcYGV9. exists=true; modified=true; local age=1390664660.953; incoming age=675.1200001239777
1390664660962 Sync.Store.Tabs TRACE Created tabs 0 of 0
1390664660963 Sync.Engine.Tabs WARN DATA LOSS: Both local and remote changes to record: 6B3UsiWcYGV9
1390664660963 Sync.Store.Tabs TRACE Ignoring tab updates as local ones win
1390664660964 Sync.Collection TRACE Channel for GET http://db1.oldsync.dev.lcip.org/1.1/200/storage/tabs?full=1: isSuccessCode(0)? true
1390664660964 Sync.Collection TRACE Channel: flags = 17024, URI = http://db1.oldsync.dev.lcip.org/1.1/200/storage/tabs?full=1, HTTP success? true
1390664660964 Sync.Collection TRACE In _onComplete. Error is null.
1390664660964 Sync.Collection TRACE Channel: [xpconnect wrapped (nsISupports, nsIChannel, nsIRequest, nsIHttpChannel)]
1390664660965 Sync.Collection TRACE Action: GET
1390664660965 Sync.Collection TRACE Status: 200
1390664660965 Sync.Collection TRACE Success: true
1390664660965 Sync.Collection DEBUG mesg: GET success 200 http://db1.oldsync.dev.lcip.org/1.1/200/storage/tabs?full=1
1390664660965 Sync.Collection DEBUG GET success 200 http://db1.oldsync.dev.lcip.org/1.1/200/storage/tabs?full=1
1390664660965 Sync.Collection TRACE GET body: null
1390664660965 Sync.Collection TRACE Processing response headers.
1390664660966 Sync.Engine.Tabs INFO Records: 1 applied, 1 successfully, 0 failed to apply, 0 newly failed to apply, 0 reconciled.
1390664660966 Sync.SyncScheduler TRACE Handling weave:engine:sync:applied
1390664660966 Sync.SyncScheduler TRACE Engine tabs successfully applied 1 items.
1390664660966 Sync.ErrorHandler TRACE Handling weave:engine:sync:applied
1390664660966 Sync.Engine.Tabs TRACE Uploading local changes to server.
1390664660966 Sync.Engine.Tabs TRACE Preparing 1 outgoing records
1390664660969 Sync.Store.Tabs TRACE Created tabs 0 of 0
1390664660970 Sync.Engine.Tabs TRACE Outgoing: { id: 6B3UsiWcYGV9 index: 0 modified: undefined ttl: 604800 payload: {"id":"6B3UsiWcYGV9","clientName":"stefan's Nightly on Baxial","tabs":[]} collection: tabs }
1390664660971 Sync.Engine.Tabs INFO Uploading all of 1 records
1390664660971 Sync.Collection TRACE In _doRequest.
1390664660974 Sync.Collection TRACE HTTP Header authorization: ***** (suppressed)
1390664660974 Sync.Collection DEBUG POST Length: 292
1390664660974 Sync.Collection TRACE POST Body: [{"payload":"{\"ciphertext\":\"FxnGqKUaGPhtcta77e9t0b75iP4Mjo3cKF1VjM7Wt55cSFyCKLdUEj1YVKoa+vTmtgyAvPQa5Sq0cPAn0kG75fA9IQbuVRCNv0Nrv6wpfk0=\",\"IV\":\"fPut+x2pdIUPnndjXP/NaQ==\",\"hmac\":\"bfadfed5cc652fa8ec298a2f7768cd4ab7b8acb067a149556e7eea9fde5991ed\"}","id":"6B3UsiWcYGV9","ttl":604800}]
1390664661024 Sync.Collection TRACE onStartRequest called for channel [xpconnect wrapped (nsISupports, nsIChannel, nsIRequest, nsIHttpChannel, nsIUploadChannel)].
1390664661024 Sync.Collection TRACE onStartRequest: POST http://db1.oldsync.dev.lcip.org/1.1/200/storage/tabs
1390664661025 Sync.Collection TRACE Channel for POST http://db1.oldsync.dev.lcip.org/1.1/200/storage/tabs: isSuccessCode(0)? true
1390664661025 Sync.Collection TRACE Channel: flags = 17024, URI = http://db1.oldsync.dev.lcip.org/1.1/200/storage/tabs, HTTP success? true
1390664661025 Sync.Collection TRACE In _onComplete. Error is null.
1390664661025 Sync.Collection TRACE Channel: [xpconnect wrapped (nsISupports, nsIChannel, nsIRequest, nsIHttpChannel, nsIUploadChannel)]
1390664661025 Sync.Collection TRACE Action: POST
1390664661025 Sync.Collection TRACE Status: 200
1390664661025 Sync.Collection TRACE Success: true
1390664661025 Sync.Collection DEBUG mesg: POST success 200 http://db1.oldsync.dev.lcip.org/1.1/200/storage/tabs
1390664661025 Sync.Collection DEBUG POST success 200 http://db1.oldsync.dev.lcip.org/1.1/200/storage/tabs
1390664661025 Sync.Collection TRACE POST body: {"failed": {}, "modified": 1390664660.52, "success": ["6B3UsiWcYGV9"]}
1390664661026 Sync.Collection TRACE Processing response headers.
1390664661026 Sync.Engine.Tabs TRACE Finishing up sync
1390664661027 Sync.Engine.Tabs TRACE Event: weave:engine:sync:finish
1390664661027 Sync.SyncScheduler TRACE Handling weave:engine:sync:finish
1390664661027 Sync.Engine.Addons DEBUG Refreshing reconciler state
1390664661027 Sync.AddonsReconciler INFO Refreshing global state from AddonManager.
1390664661038 Sync.AddonsReconciler DEBUG Rectifying state for addon: {972ce4c6-7e08-4474-a285-3208198ce6fd}
1390664661038 Sync.AddonsReconciler DEBUG Rectifying state for addon: {3e9a3920-1b27-11da-8cd6-0800200c9a66}
1390664661038 Sync.AddonsReconciler DEBUG Adding change because add-on not present locally: {3e9a3920-1b27-11da-8cd6-0800200c9a66}
1390664661038 Sync.AddonsReconciler INFO Change recorded for {3e9a3920-1b27-11da-8cd6-0800200c9a66}
1390664661038 Sync.AddonsReconciler DEBUG Rectifying state for addon: {11b88c01-2bb2-4b2e-25cc-aefed565b0ee}
1390664661038 Sync.AddonsReconciler DEBUG Rectifying state for addon: {f0b3efbb-6ef1-0fed-35c7-f16b08b56f6b}
1390664661038 Sync.AddonsReconciler DEBUG Rectifying state for addon: {07b90314-1e79-c84a-4b47-fb7e1853be39}
1390664661039 Sync.AddonsReconciler INFO Saving reconciler state to file: addonsreconciler
1390664661041 Sync.Engine.Addons DEBUG First sync, uploading all items
1390664661042 Sync.Store.Addons DEBUG {972ce4c6-7e08-4474-a285-3208198ce6fd} not syncable: not installed in profile.
1390664661042 Sync.Store.Addons DEBUG {11b88c01-2bb2-4b2e-25cc-aefed565b0ee} not syncable: type not in whitelist: plugin
1390664661042 Sync.Store.Addons DEBUG {f0b3efbb-6ef1-0fed-35c7-f16b08b56f6b} not syncable: type not in whitelist: plugin
1390664661042 Sync.Store.Addons DEBUG {07b90314-1e79-c84a-4b47-fb7e1853be39} not syncable: type not in whitelist: plugin
1390664661043 Sync.Store.Addons DEBUG {3e9a3920-1b27-11da-8cd6-0800200c9a66} not syncable: is foreign install.
1390664661043 Sync.Engine.Addons INFO 0 outgoing items pre-reconciliation
1390664661044 Sync.Engine.Addons INFO Records: 0 applied, 0 successfully, 0 failed to apply, 0 newly failed to apply, 0 reconciled.
1390664661044 Sync.SyncScheduler TRACE Handling weave:engine:sync:applied
1390664661044 Sync.SyncScheduler TRACE Engine addons successfully applied 0 items.
1390664661044 Sync.ErrorHandler TRACE Handling weave:engine:sync:applied
1390664661045 Sync.SyncScheduler TRACE Handling weave:engine:sync:finish
1390664661045 Sync.Status DEBUG Status.sync: success.sync => success.sync
1390664661045 Sync.Status DEBUG Status.service: success.status_ok => success.status_ok
1390664661046 Sync.Synchronizer INFO Sync completed at 2014-01-25 10:44:21 after 0.50 secs.
1390664661046 Sync.Service TRACE Event: weave:service:sync:finish
1390664661046 Sync.SyncScheduler TRACE Handling weave:service:sync:finish
1390664661046 Sync.SyncScheduler TRACE Adjusting syncInterval to singleDeviceInterval.
1390664661046 Sync.SyncScheduler DEBUG Next sync in 86400000 ms.
1390664661047 Sync.ErrorHandler TRACE Handling weave:service:sync:finish
1390664661047 Sync.ErrorHandler TRACE Status.service is success.status_ok
1390664661047 Sync.ErrorHandler TRACE Clearing lastSyncReassigned.
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment