1498073 - 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

Reporter

Description

•

6 years ago

treeherder

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)

Lina Butler [:lina]

Assignee

Updated

•

6 years ago

Assignee: nobody → lina

Blocks: 1477671

Status: NEW → ASSIGNED

Comment hidden (Intermittent Failures Robot)

9 failures in 2740 pushes (0.003 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:
* autoland: 3
* mozilla-inbound: 4
* mozilla-central: 2

Platform breakdown:
* windows7-32: 5
* windows10-64: 4

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1498073&startday=2018-10-08&endday=2018-10-14&tree=all

Comment hidden (Intermittent Failures Robot)

2 failures in 3007 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:
* try: 1
* mozilla-beta: 1

Platform breakdown:
* windows7-32-devedition: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1498073&startday=2018-11-05&endday=2018-11-11&tree=all

Comment hidden (Intermittent Failures Robot)

2 failures in 2917 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:
* mozilla-beta: 1
* try: 1

Platform breakdown:
* windows7-32-nightly: 1
* windows10-64-devedition: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1498073&startday=2018-11-12&endday=2018-11-18&tree=all

Comment hidden (Intermittent Failures Robot)

1 failures in 2387 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:
* mozilla-beta: 1

Platform breakdown:
* windows10-64-nightly: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1498073&startday=2018-11-19&endday=2018-11-25&tree=all

Comment hidden (Intermittent Failures Robot)

1 failures in 2808 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:
* mozilla-beta: 1

Platform breakdown:
* windows7-32-devedition: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1498073&startday=2018-11-26&endday=2018-12-02&tree=all

Comment hidden (Intermittent Failures Robot)

2 failures in 1862 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:
* try: 1
* mozilla-release: 1

Platform breakdown:
* windows10-64-nightly: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1498073&startday=2018-12-03&endday=2018-12-09&tree=all

Lina Butler [:lina]

Assignee

Updated

•

5 years ago

Keywords: leave-open

Pulsebot

Comment 7

•

5 years ago

Pushed by kcambridge@mozilla.com:
https://hg.mozilla.org/integration/mozilla-inbound/rev/1b960c462dcb
Ensure each bookmark engine test cleans up. a=testonly

Noemi Erli[:noemi_erli]

Comment 8

•

5 years ago

bugherder

https://hg.mozilla.org/mozilla-central/rev/1b960c462dcb

Comment hidden (Intermittent Failures Robot)

14 failures in 2485 pushes (0.006 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:
* try: 2
* autoland: 3
* mozilla-inbound: 3
* mozilla-central: 6

Platform breakdown:
* windows7-32-nightly: 1
* windows10-64-devedition: 2
* windows10-64: 6
* windows10-64-nightly: 1
* windows7-32: 4

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1498073&startday=2018-12-10&endday=2018-12-16&tree=all

Comment hidden (Intermittent Failures Robot)

8 failures in 1668 pushes (0.005 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:
* mozilla-inbound: 3
* mozilla-beta: 1
* mozilla-central: 4

Platform breakdown:
* windows7-32-devedition: 1
* windows10-64: 4
* windows7-32: 3

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1498073&startday=2018-12-31&endday=2019-01-06&tree=all

Cristina Coroiu [:ccoroiu]

Updated

•

5 years ago

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

Andreea Pavel [:apavel]

Comment 11

•

5 years ago

This appeared when Bug 1477671 landed: https://hg.mozilla.org/integration/mozilla-inbound/rev/224c0fc59fc12565320b507bfdb7b8f80a07d12d#l4.2

Failure log: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=204657468&repo=mozilla-inbound&lineNumber=1215

Marco: can you take a look?

Flags: needinfo?(mcastelluccio)

Whiteboard: [stockwell needswork:owner]

Marco Castelluccio [:marco]

Updated

•

5 years ago

Flags: needinfo?(mcastelluccio) → needinfo?(mak77)

Comment hidden (Intermittent Failures Robot)

Marco Bonardo [:mak]

Comment 13

•

5 years ago

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

Flags: needinfo?(mak77) → needinfo?(lina)

Comment hidden (Intermittent Failures Robot)

Natalia Csoregi [:nataliaCs]

Updated

•

5 years ago

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

Comment hidden (Intermittent Failures Robot)

Oana Pop-Rus

Comment 26

•

5 years ago

Recent failures occurs more often and seems to be from bug: https://bugzilla.mozilla.org/show_bug.cgi?id=1552621. For the moment it is tier 2.

https://treeherder.mozilla.org/#/jobs?repo=mozilla-central&resultStatus=superseded%2Ctestfailed%2Cbusted%2Cexception%2Crunnable&revision=31cec3975dd0988d798cb63711477e9745339064&searchStr=tv&selectedJob=250127301

Comment hidden (Intermittent Failures Robot)

Lina Butler [:lina]

Assignee

Comment 32

•

5 years ago

Attached file Bug 1498073 - Use centisecond precision for modified times in Sync tests. a=testonly — Details

Lina Butler [:lina]

Assignee

Comment 33

•

5 years ago

https://treeherder.mozilla.org/#/jobs?repo=try&revision=2abbaab40a765e88f4e404f6b030b384415938d2

Pulsebot

Comment 34

•

5 years ago

Pushed by kcambridge@mozilla.com:
https://hg.mozilla.org/integration/autoland/rev/6359b0b75537
Use centisecond precision for modified times in Sync tests. a=testonly

Razvan Maries

Comment 35

•

5 years ago

bugherder

https://hg.mozilla.org/mozilla-central/rev/6359b0b75537

Comment hidden (Intermittent Failures Robot)

Lina Butler [:lina]

Assignee

Comment 39

•

5 years ago

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

BugBot [:suhaib / :marco/ :calixte]

Updated

•

5 years ago

Keywords: leave-open

Comment hidden (Intermittent Failures Robot)