Closed Bug 700921 Opened 13 years ago Closed 12 years ago

Intermittent test_corrupt_keys.js | false == true | 0 == 1

Categories

(Firefox :: Sync, defect)

Product:
Firefox
Component:
Sync
Platform:
x86
Windows XP
Type:
defect
Priority:
Not set
Severity:
normal

Tracking

()

Status:
RESOLVED WORKSFORME

People

(Reporter: philor, Unassigned)

References

Details

(Keywords: intermittent-failure) 

Meet the new Beta, orange as the old Beta.

https://tbpl.mozilla.org/php/getParsedLog.php?id=7296262&tree=Mozilla-Beta
Rev3 WINNT 5.1 mozilla-beta debug test xpcshell on 2011-11-08 17:41:06 PST for push f8b25775b12b

TEST-INFO | c:\talos-slave\test\build\xpcshell\tests\services\sync\tests\unit\test_corrupt_keys.js | running test ...
TEST-UNEXPECTED-FAIL | c:\talos-slave\test\build\xpcshell\tests\services\sync\tests\unit\test_corrupt_keys.js | test failed (with xpcshell return code: 0), see following log:
...
1320805290580	Sync.Engine.Forms	DEBUG	First sync, uploading all items
WARNING: 1 sort operation has occurred for the SQL statement '0x5f546b8'.  See https://developer.mozilla.org/En/Storage/Warnings details.: file e:/builds/moz2_slave/m-beta-w32-dbg/build/storage/src/mozStoragePrivateHelpers.cpp, line 144
Updating collection crypto to 1320805290.8
1320805290614	Sync.Engine.Forms	INFO	0 outgoing items pre-reconciliation
1320805290621	Sync.Engine.Forms	INFO	Records: 0 applied, 0 failed to apply, 0 newly failed to apply, 0 reconciled.
1320805290628	Sync.Engine.History	INFO	0 outgoing items pre-reconciliation
1320805290670	Sync.Service	INFO	Bad HMAC event detected. Attempting recovery or signaling to other clients.
1320805290739	Sync.Resource	DEBUG	mesg: GET success 200 http://localhost:8080/1.1/johndoe/storage/crypto/keys
1320805290739	Sync.Resource	DEBUG	GET success 200 http://localhost:8080/1.1/johndoe/storage/crypto/keys
1320805290741	Sync.CollectionKeys	INFO	Updating collection keys...
1320805290745	Sync.CollectionKeys	INFO	Setting CollectionKeys contents. Our last modified: 1320805297.13, input modified: 1320805297.13.
1320805290745	Sync.BulkKeyBundle	INFO	BulkKeyBundle being created for [default]
1320805290746	Sync.CollectionKeys	INFO	Processing downloaded per-collection keys.
1320805290747	Sync.CollectionKeys	INFO	New keys are the same as our old keys! Bumped local modified time.
1320805290747	Sync.CollectionKeys	INFO	Collection keys updated.
1320805290750	Sync.Resource	DEBUG	PUT Length: 402
1320805290823	Sync.Resource	DEBUG	mesg: PUT success 200 http://localhost:8080/1.1/johndoe/storage/crypto/keys
1320805290823	Sync.Resource	DEBUG	PUT success 200 http://localhost:8080/1.1/johndoe/storage/crypto/keys
1320805290824	Sync.Service	INFO	Successfully re-uploaded keys. Continuing sync.
1320805290824	Sync.Engine.History	WARN	Error decrypting record: Record SHA256 HMAC mismatch: should be BDC668D5B86AA66FCB7A4A94F8EC6D33D366053E26E0EF6DB8C8E4C64E80E819, is bdc668d5b86aa66fcb7a4a94f8ec6d33d366053e26e0ef6db8c8e4c64e80e819 No traceback available
1320805290828	Sync.Engine.History	WARN	Error decrypting record: Record SHA256 HMAC mismatch: should be 6C9BAA41137D522565A6478A7F33D91834ABFCE870E2DC7DBABAE8EE03AA1FCC, is 6c9baa41137d522565a6478a7f33d91834abfce870e2dc7dbabae8ee03aa1fcc No traceback available
1320805290830	Sync.Engine.History	WARN	Error decrypting record: Record SHA256 HMAC mismatch: should be EDF04AC94261F0E3091D9C9FDB55BBCC4423A0BD1C255B2A8FAFC539573308E6, is edf04ac94261f0e3091d9c9fdb55bbcc4423a0bd1c255b2a8fafc539573308e6 No traceback available
1320805290832	Sync.Engine.History	WARN	Error decrypting record: Record SHA256 HMAC mismatch: should be 9F12886B75858E01C42759A03CE365C2CB12DF63ACC44C9442D22C81E9C9B539, is 9f12886b75858e01c42759a03ce365c2cb12df63acc44c9442d22c81e9c9b539 No traceback available
1320805290834	Sync.Engine.History	WARN	Error decrypting record: Record SHA256 HMAC mismatch: should be 33314AA2BAB1FA4D79C1FD8B3E4D55A7B7DA0B0101EB8D5A5C624821FF2A3211, is 33314aa2bab1fa4d79c1fd8b3e4d55a7b7da0b0101eb8d5a5c624821ff2a3211 No traceback available
1320805290836	Sync.Collection	DEBUG	mesg: GET success 200 http://localhost:8080/1.1/johndoe/storage/history?newer=1320805289.514&full=1
1320805290836	Sync.Collection	DEBUG	GET success 200 http://localhost:8080/1.1/johndoe/storage/history?newer=1320805289.514&full=1
1320805290837	Sync.Engine.History	DEBUG	Records that failed to apply: record-no--5,record-no--6,record-no--7,record-no--8,record-no--9
1320805290838	Sync.Engine.History	INFO	Records: 0 applied, 5 failed to apply, 5 newly failed to apply, 0 reconciled.
1320805290840	Sync.ErrorHandler	DEBUG	history failed to apply some records.
1320805290842	Sync.Engine.Passwords	DEBUG	First sync, uploading all items
1320805290843	Sync.Engine.Passwords	INFO	0 outgoing items pre-reconciliation
1320805290848	Sync.Engine.Passwords	INFO	Records: 0 applied, 0 failed to apply, 0 newly failed to apply, 0 reconciled.
1320805290851	Sync.Engine.Prefs	INFO	0 outgoing items pre-reconciliation
1320805290853	Sync.Engine.Prefs	INFO	Records: 0 applied, 0 failed to apply, 0 newly failed to apply, 0 reconciled.
1320805290856	Sync.Engine.Tabs	INFO	0 outgoing items pre-reconciliation
1320805290859	Sync.Engine.Tabs	INFO	Records: 0 applied, 0 failed to apply, 0 newly failed to apply, 0 reconciled.
1320805290860	Sync.Service	INFO	Sync completed at 2011-11-08 18:21:30 after 0.55 secs.
132080Keys now: JNFxLMl9GDJQ9vGSdJZUFVyyhWWG8Fk9xfTw79V6AwM=,y/BWAyIsdr1ESnxFSuM1BInxAl4Ukr6x4JjfitHI+fk=
Server keys have been updated, and we skipped over 5 more HMAC errors without adjusting history.

TEST-UNEXPECTED-FAIL | c:/talos-slave/test/build/xpcshell/tests/services/sync/tests/unit/test_corrupt_keys.js | false == true - See following stack:
JS frame :: c:\talos-slave\test\build\xpcshell\head.js :: do_throw :: line 453
JS frame :: c:\talos-slave\test\build\xpcshell\head.js :: _do_check_eq :: line 547
JS frame :: c:\talos-slave\test\build\xpcshell\head.js :: do_check_eq :: line 568
JS frame :: c:\talos-slave\test\build\xpcshell\head.js :: do_check_true :: line 582
JS frame :: c:/talos-slave/test/build/xpcshell/tests/services/sync/tests/unit/test_corrupt_keys.js :: test_locally_changed_keys :: line 221
JS frame :: c:\talos-slave\test\build\xpcshell\head.js :: _run_next_test :: line 873
JS frame :: c:\talos-slave\test\build\xpcshell\head.js :: <TOP_LEVEL> :: line 420

TEST-INFO | (xpcshell/head.js) | exiting test

TEST-UNEXPECTED-FAIL | c:\talos-slave\test\build\xpcshell\head.js | 2147500036 - See following stack:
JS frame :: c:\talos-slave\test\build\xpcshell\head.js :: do_throw :: line 453
JS frame :: c:\talos-slave\test\build\xpcshell\head.js :: _run_next_test :: line 876
JS frame :: c:\talos-slave\test\build\xpcshell\head.js :: <TOP_LEVEL> :: line 420
https://tbpl.mozilla.org/php/getParsedLog.php?id=7305425&tree=Mozilla-Inbound

1320845651496	Sync.Collection	DEBUG	POST Length: 420
1320845651503	Sync.Test.Server	DEBUG	SyncServer: Handling request: POST /1.1/johndoe/storage/tabs
1320845651504	Sync.Test.Server	DEBUG	Creating WBO "F9EfGw0FmtSM" on the fly.
1320845651511	Sync.Collection	DEBUG	mesg: POST success 200 http://localhost:8080/1.1/johndoe/storage/tabs
1320845651511	Sync.Collection	DEBUG	POST success 200 http://localhost:8080/1.1/johndoe/storage/tabs
1320845651523	Sync.Resource	DEBUG	PUT Length: 496
1320845651531	Sync.Test.Server	DEBUG	SyncServer: Handling request: PUT /1.1/johndoe/storage/meta/global
1320845651538	Sync.Resource	DEBUG	mesg: PUT success 200 http://localhost:8080/1.1/johndoe/storage/meta/global
1320845651538	Sync.Resource	DEBUG	PUT success 200 http://localhost:8080/1.1/johndoe/storage/meta/global
1320845651548	Sync.Status	DEBUG	Status.sync: success.sync => success.sync
1320845651548	Sync.Status	DEBUG	Status.service: success.status_ok => success.status_ok
1320845651548	Sync.Service	INFO	Sync completed at 2011-11-09 05:34:11 after 0.60 secs.
1320845651549	Sync.SyncScheduler	DEBUG	Next sync in 86400000 ms.

TEST-UNEXPECTED-FAIL | /Users/cltbld/talos-slave/test/build/xpcshell/tests/services/sync/tests/unit/test_corrupt_keys.js | 0 == 1 - See following stack:
JS frame :: /Users/cltbld/talos-slave/test/build/xpcshell/head.js :: do_throw :: line 453
JS frame :: /Users/cltbld/talos-slave/test/build/xpcshell/head.js :: _do_check_eq :: line 547
JS frame :: /Users/cltbld/talos-slave/test/build/xpcshell/head.js :: do_check_eq :: line 568
JS frame :: /Users/cltbld/talos-slave/test/build/xpcshell/tests/services/sync/tests/unit/test_corrupt_keys.js :: test_locally_changed_keys :: line 144
JS frame :: /Users/cltbld/talos-slave/test/build/xpcshell/head.js :: _run_next_test :: line 873
JS frame :: /Users/cltbld/talos-slave/test/build/xpcshell/head.js :: <TOP_LEVEL> :: line 420
Summary: Intermittent test_corrupt_keys.js | false == true → Intermittent test_corrupt_keys.js | false == true | 0 == 1
Intermittent orange hasn't manifested in a while. It may have magically fixed itself.
Status: NEW → RESOLVED
Closed: 12 years ago
Resolution: --- → WORKSFORME
Whiteboard: [orange]
Component: Firefox Sync: Crypto → Sync
Product: Cloud Services → Firefox
You need to log in before you can comment on or make changes to this bug.