Closed Bug 1498073 Opened 6 years ago Closed 5 years ago

Intermittent services/sync/tests/unit/test_bookmark_engine.js | - Should store original livemark A and tombstones for B and C on server - ["livemarkAAAA","livemarkBBBB","livemarkCCCC","menu","toolbar","unfiled"] deepEqual

Categories

(Firefox :: Sync, defect, P5)

defect

Tracking

()

RESOLVED WORKSFORME

People

(Reporter: intermittent-bug-filer, Assigned: lina)

References

Details

(Keywords: intermittent-failure, Whiteboard: [stockwell unknown])

Attachments

(1 file)

Filed by: apavel [at] mozilla.com https://treeherder.mozilla.org/logviewer.html#?job_id=204657468&repo=mozilla-inbound https://queue.taskcluster.net/v1/task/fj94zxSSSICFpnZY9Gm8LQ/runs/0/artifacts/public/logs/live_backing.log 00:31:41 INFO - TEST-START | services/sync/tests/unit/test_bookmark_engine.js 00:31:50 WARNING - TEST-UNEXPECTED-FAIL | services/sync/tests/unit/test_bookmark_engine.js | xpcshell return code: 0 00:31:50 INFO - TEST-INFO took 9313ms 00:31:50 INFO - >>>>>>> 00:31:50 INFO - PID 2744 | JavaScript strict warning: Z:/task_1539215136/build/tests/xpcshell/tests/services/sync/tests/unit/head_helpers.js -> resource://testing-common/sinon-2.3.2.js, line 8941: ReferenceError: reference to undefined property "iso-8859-8-i" 00:31:50 INFO - PID 2744 | JavaScript strict warning: resource://services-sync/policies.js, line 108: ReferenceError: reference to undefined property "_globalScore" 00:31:50 INFO - (xpcshell/head.js) | test MAIN run_test pending (1) 00:31:50 INFO - (xpcshell/head.js) | test run_next_test 0 pending (2) 00:31:50 INFO - (xpcshell/head.js) | test MAIN run_test finished (2) 00:31:50 INFO - running event loop 00:31:50 INFO - "CONSOLE_MESSAGE: (warn) [JavaScript Warning: "ReferenceError: reference to undefined property "iso-8859-8-i"" {file: "Z:/task_1539215136/build/tests/xpcshell/tests/services/sync/tests/unit/head_helpers.js -> resource://testing-common/sinon-2.3.2.js" line: 8941}]" 00:31:50 INFO - "CONSOLE_MESSAGE: (warn) [JavaScript Warning: "ReferenceError: reference to undefined property "_globalScore"" {file: "resource://services-sync/policies.js" line: 108}]" 00:31:50 INFO - services/sync/tests/unit/test_bookmark_engine.js | Starting head_setup 00:31:50 INFO - (xpcshell/head.js) | test head_setup pending (2) 00:31:50 INFO - (xpcshell/head.js) | test run_next_test 0 finished (2) 00:31:52 WARNING - TEST-UNEXPECTED-FAIL | services/sync/tests/unit/test_bookmark_engine.js | - Should store original livemark A and tombstones for B and C on server - ["livemarkAAAA","livemarkBBBB","livemarkCCCC","menu","toolbar","unfiled"] deepEqual ["livemarkAAAA","livemarkBBBB","livemarkCCCC","menu","mobile","toolbar","unfiled"] 00:31:52 INFO - Z:/task_1539215136/build/tests/xpcshell/tests/services/sync/tests/unit/test_bookmark_engine.js:test_livemarks:1365 00:31:52 INFO - exiting test 00:31:52 INFO - "CONSOLE_MESSAGE: (error) [JavaScript Error: "Exception trying to format object for log message: SyntaxError: JSON.parse: unexpected character at line 1 column 1 of the JSON data(resource://services-sync/resource.js:214:16) JS Stack trace: _processResponse/<@resource.js:214:16 00:31:52 INFO - get@XPCOMUtils.jsm:129:43 00:31:52 INFO - format@Log.jsm:607:14 00:31:52 INFO - formatText@Log.jsm:550:20 00:31:52 INFO - format@logging.js:25:9 00:31:52 INFO - append@Log.jsm:643:21 00:31:52 INFO - log@Log.jsm:365:7 00:31:52 INFO - debug@Log.jsm:385:5 00:31:52 INFO - flush@record.js:1065:7 00:31:52 INFO - async*_uploadOutgoing@engines.js:1771:13 00:31:52 INFO - async*_sync@engines.js:1839:15 00:31:52 INFO - async*_sync@bookmarks.js:396:13 00:31:52 INFO - async*WrappedNotify@util.js:179:27 00:31:52 INFO - async*sync@engines.js:895:12 00:31:52 INFO - async*sync_engine_and_validate_telem@head_helpers.js:439:13 00:31:52 INFO - async*test_livemarks@test_bookmark_engine.js:1363:11 00:31:52 INFO - async*add_bookmark_test/<@test_bookmark_engine.js:72:13 00:31:52 INFO - async*run_next_test/_run_next_test/<@Z:\\task_1539215136\\build\\tests\\xpcshell\\head.js:1441:22 00:31:52 INFO - async*_run_next_test@Z:\\task_1539215136\\build\\tests\\xpcshell\\head.js:1441:10 00:31:52 INFO - run@Z:\\task_1539215136\\build\\tests\\xpcshell\\head.js:692:9 00:31:52 INFO - _do_main@Z:\\task_1539215136\\build\\tests\\xpcshell\\head.js:219:3 00:31:52 INFO - _execute_test@Z:\\task_1539215136\\build\\tests\\xpcshell\\head.js:533:5 00:31:52 INFO - @-e:1:1" {file: "resource://gre/modules/Log.jsm" line: 22}] 00:31:52 INFO - dumpError@resource://gre/modules/Log.jsm:22:3 00:31:52 INFO - format@resource://gre/modules/Log.jsm:614:7 00:31:52 INFO - formatText@resource://gre/modules/Log.jsm:550:20 00:31:52 INFO - format@resource://testing-common/services/common/logging.js:25:9 00:31:52 INFO - append@resource://gre/modules/Log.jsm:643:21 00:31:52 INFO - log@resource://gre/modules/Log.jsm:365:7 00:31:52 INFO - debug@resource://gre/modules/Log.jsm:385:5 00:31:52 INFO - flush@resource://services-sync/record.js:1065:7 00:31:52 INFO - async*_uploadOutgoing@resource://services-sync/engines.js:1771:13 00:31:52 INFO - async*_sync@resource://services-sync/engines.js:1839:15 00:31:52 INFO - async*_sync@resource://services-sync/engines/bookmarks.js:396:13 00:31:52 INFO - async*WrappedNotify@resource://services-sync/util.js:179:27 00:31:52 INFO - async*sync@resource://services-sync/engines.js:895:12 00:31:52 INFO - async*sync_engine_and_validate_telem@Z:/task_1539215136/build/tests/xpcshell/tests/services/sync/tests/unit/head_helpers.js:439:13 00:31:52 INFO - async*test_livemarks@Z:/task_1539215136/build/tests/xpcshell/tests/services/sync/tests/unit/test_bookmark_engine.js:1363:11 00:31:52 INFO - async*add_bookmark_test/<@Z:/task_1539215136/build/tests/xpcshell/tests/services/sync/tests/unit/test_bookmark_engine.js:72:13 00:31:52 INFO - async*run_next_test/_run_next_test/<@Z:\\task_1539215136\\build\\tests\\xpcshell\\head.js:1441:22 00:31:52 INFO - async*_run_next_test@Z:\\task_1539215136\\build\\tests\\xpcshell\\head.js:1441:10 00:31:52 INFO - run@Z:\\task_1539215136\\build\\tests\\xpcshell\\head.js:692:9 00:31:52 INFO - _do_main@Z:\\task_1539215136\\build\\tests\\xpcshell\\head.js:219:3 00:31:52 INFO - _execute_test@Z:\\task_1539215136\\build\\tests\\xpcshell\\head.js:533:5 00:31:52 INFO - @-e:1:1 00:31:52 INFO - " 00:31:52 INFO - PID 2744 | 1539217910445 Sync.SyncScheduler TRACE Handling network:offline-status-changed 00:31:52 INFO - PID 2744 | 1539217910445 Sync.SyncScheduler TRACE Network offline status change: offline 00:31:52 INFO - PID 2744 | 1539217910446 Sync.SyncScheduler TRACE _checkSync returned "Network is offline". 00:31:52 INFO - PID 2744 | 1539217910446 Sync.SyncScheduler DEBUG Clearing sync triggers and the global score. 00:31:52 INFO - PID 2744 | 1539217910446 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: services.sync.nextSync 00:31:52 INFO - PID 2744 | JavaScript strict warning: resource://testing-common/httpd.js, line 800: ReferenceError: reference to undefined property "_stopCallback" 00:31:52 INFO - PID 2744 | !!! error running onStopped callback: TypeError: callback is not a function 00:31:52 INFO - "CONSOLE_MESSAGE: (warn) [JavaScript Warning: "ReferenceError: reference to undefined property "_stopCallback"" {file: "resource://testing-common/httpd.js" line: 800}]" 00:31:52 INFO - PID 2744 | 1539217910454 Sync.Telemetry TRACE observed profile-before-change null 00:31:52 INFO - PID 2744 | 1539217910455 Sync.Telemetry TRACE Not sending telemetry ping for self-hosted Sync user 00:31:52 INFO - PID 2744 | 1539217910463 Sync.Engine.Prefs.Tracker TRACE stop(). 00:31:52 INFO - PID 2744 | 1539217910465 Sqlite.Connection INFO places.sqlite#0: Closed 00:31:52 INFO - PID 2744 | 1539217910471 Sync.Engine.Bookmarks.Tracker TRACE stop(). 00:31:52 INFO - PID 2744 | 1539217910471 Sync.Engine.Bookmarks.Tracker DEBUG Not saving changedIDs. 00:31:52 INFO - Unexpected exception Error: The file c:\\users\\task_1539215136\\appdata\\local\\temp\\xpc-profile-jj5pwi\\weave\\changes\\bookmarks.json has already been finalized at resource://gre/modules/JSONFile.jsm:355 00:31:52 INFO - finalize@resource://gre/modules/JSONFile.jsm:355:13 00:31:52 INFO - async*finalize@resource://services-sync/engines.js:252:11 00:31:52 INFO - async*finalize@resource://services-sync/engines.js:2020:11 00:31:52 INFO - async*add_bookmark_test/<@Z:/task_1539215136/build/tests/xpcshell/tests/services/sync/tests/unit/test_bookmark_engine.js:74:13 00:31:52 INFO - async*run_next_test/_run_next_test/<@Z:\task_1539215136\build\tests\xpcshell\head.js:1441:22 00:31:52 INFO - async*_run_next_test@Z:\task_1539215136\build\tests\xpcshell\head.js:1441:10 00:31:52 INFO - run@Z:\task_1539215136\build\tests\xpcshell\head.js:692:9 00:31:52 INFO - _do_main@Z:\task_1539215136\build\tests\xpcshell\head.js:219:3 00:31:52 INFO - _execute_test@Z:\task_1539215136\build\tests\xpcshell\head.js:533:5 00:31:52 INFO - @-e:1:1 00:31:52 INFO - exiting test 00:31:52 INFO - PID 2744 | 1539217910474 Sqlite.Connection INFO places.sqlite#1: Closed 00:31:52 INFO - PID 2744 | 1539217910476 Sqlite.Connection INFO places.sqlite#16: Closed 00:31:52 INFO - PID 2744 | 1539217910476 Sqlite.Connection INFO places.sqlite#13: Closed 00:31:52 INFO - PID 2744 | 1539217910476 Sqlite.Connection INFO places.sqlite#15: Closed 00:31:52 INFO - PID 2744 | 1539217910477 Sqlite.Connection INFO places.sqlite#14: Closed 00:31:52 INFO - PID 2744 | 1539217910486 Sync.LogManager TRACE finished copy to: c:\\users\\task_1539215136\\appdata\\local\\temp\\xpc-profile-jj5pwi\\weave\\logs\\success-sync-1539217910422.txt 00:31:52 INFO - PID 2744 | 1539217910487 Sync.LogManager TRACE onCopyComplete: 1539217910487 00:31:52 INFO - <<<<<<< 00:31:52 INFO - TEST-START | services/sync/tests/unit/test_bookmark_engine.js 00:32:01 INFO - TEST-PASS | services/sync/tests/unit/test_bookmark_engine.js | took 9857ms 00:32:02 INFO - TEST-START | services/sync/tests/unit/test_bookmark_engine.js 00:32:11 WARNING - TEST-UNEXPECTED-FAIL | services/sync/tests/unit/test_bookmark_engine.js | xpcshell return code: 0 00:32:11 INFO - TEST-INFO took 9549ms 00:32:11 INFO - >>>>>>> 00:32:11 INFO - PID 3828 | JavaScript strict warning: Z:/task_1539215136/build/tests/xpcshell/tests/services/sync/tests/unit/head_helpers.js -> resource://testing-common/sinon-2.3.2.js, line 8941: ReferenceError: reference to undefined property "iso-8859-8-i" 00:32:11 INFO - PID 3828 | JavaScript strict warning: resource://services-sync/policies.js, line 108: ReferenceError: reference to undefined property "_globalScore" 00:32:11 INFO - (xpcshell/head.js) | test MAIN run_test pending (1) 00:32:11 INFO - (xpcshell/head.js) | test run_next_test 0 pending (2) 00:32:11 INFO - (xpcshell/head.js) | test MAIN run_test finished (2) 00:32:11 INFO - running event loop 00:32:11 INFO - "CONSOLE_MESSAGE: (warn) [JavaScript Warning: "ReferenceError: reference to undefined property "iso-8859-8-i"" {file: "Z:/task_1539215136/build/tests/xpcshell/tests/services/sync/tests/unit/head_helpers.js -> resource://testing-common/sinon-2.3.2.js" line: 8941}]" 00:32:11 INFO - "CONSOLE_MESSAGE: (warn) [JavaScript Warning: "ReferenceError: reference to undefined property "_globalScore"" {file: "resource://services-sync/policies.js" line: 108}]" 00:32:11 INFO - services/sync/tests/unit/test_bookmark_engine.js | Starting head_setup 00:32:11 INFO - (xpcshell/head.js) | test head_setup pending (2) 00:32:11 INFO - (xpcshell/head.js) | test run_next_test 0 finished (2)
Assignee: nobody → lina
Blocks: 1477671
Status: NEW → ASSIGNED
Keywords: leave-open
Pushed by kcambridge@mozilla.com: https://hg.mozilla.org/integration/mozilla-inbound/rev/1b960c462dcb Ensure each bookmark engine test cleans up. a=testonly
Summary: Intermittent services/sync/tests/unit/test_bookmark_engine.js | - Should store original livemark A and tombstones for B and C on server - ["livemarkAAAA","livemarkBBBB","livemarkCCCC","menu","toolbar","unfiled"] deepEqual → Intermittent Test-verify services/sync/tests/unit/test_bookmark_engine.js | - Should store original livemark A and tombstones for B and C on server - ["livemarkAAAA","livemarkBBBB","livemarkCCCC","menu","toolbar","unfiled"] deepEqual
Flags: needinfo?(mcastelluccio) → needinfo?(mak77)

Lina, any further idea, since you fixed one of the failures here already.

Flags: needinfo?(mak77) → needinfo?(lina)
Summary: Intermittent Test-verify services/sync/tests/unit/test_bookmark_engine.js | - Should store original livemark A and tombstones for B and C on server - ["livemarkAAAA","livemarkBBBB","livemarkCCCC","menu","toolbar","unfiled"] deepEqual → Intermittent services/sync/tests/unit/test_bookmark_engine.js | - Should store original livemark A and tombstones for B and C on server - ["livemarkAAAA","livemarkBBBB","livemarkCCCC","menu","toolbar","unfiled"] deepEqual
Pushed by kcambridge@mozilla.com: https://hg.mozilla.org/integration/autoland/rev/6359b0b75537 Use centisecond precision for modified times in Sync tests. a=testonly

We can uplift https://hg.mozilla.org/mozilla-central/rev/6359b0b75537 to Beta, but, since the merge is next week, I don't think it's that critical.

Status: ASSIGNED → RESOLVED
Closed: 5 years ago
Flags: needinfo?(lina)
Resolution: --- → WORKSFORME
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Created:
Updated:
Size: