1826913 - Intermittent services/sync/tests/unit/test_bookmark_engine.js | single tracking bug

Status: RESOLVED INCOMPLETE

(Firefox :: Sync, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: smolnar [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=411649935&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/f2E-PxzpRZaZitUvtRj3Dg/runs/0/artifacts/public/logs/live_backing.log

---

INFO -  TEST-PASS | services/sync/tests/unit/test_bookmark_engine.js | test_buffer_hasDupe - [test_buffer_hasDupe : 870] 1 == 1
[task 2023-04-06T22:53:34.957Z] 22:53:34  WARNING -  TEST-UNEXPECTED-FAIL | services/sync/tests/unit/test_bookmark_engine.js | test_buffer_hasDupe - [test_buffer_hasDupe : 870] 1680821613658 <= 1680821613652
[task 2023-04-06T22:53:34.957Z] 22:53:34     INFO -  resource://services-sync/telemetry.sys.mjs:finish:870
[task 2023-04-06T22:53:34.957Z] 22:53:34     INFO -  resource://services-sync/telemetry.sys.mjs:maybeSubmitForInterval:1051
[task 2023-04-06T22:53:34.957Z] 22:53:34     INFO -  resource://services-sync/telemetry.sys.mjs:takeTelemetryRecord:1080
[task 2023-04-06T22:53:34.957Z] 22:53:34     INFO -  resource://services-sync/telemetry.sys.mjs:onSyncFinished:1065
[task 2023-04-06T22:53:34.957Z] 22:53:34     INFO -  resource://services-sync/telemetry.sys.mjs:observe:1152
[task 2023-04-06T22:53:34.957Z] 22:53:34     INFO -  resource://services-common/observers.sys.mjs:observe:131
[task 2023-04-06T22:53:34.957Z] 22:53:34     INFO -  resource://services-common/observers.sys.mjs:notify:83
[task 2023-04-06T22:53:34.957Z] 22:53:34     INFO -  C:/Users/task_168082015975705/build/tests/xpcshell/tests/services/sync/tests/unit/head_helpers.js:sync_engine_and_validate_telem:527
[task 2023-04-06T22:53:34.957Z] 22:53:34     INFO -  C:\Users\task_168082015975705\build\tests\xpcshell\head.js:_do_main:238
[task 2023-04-06T22:53:34.957Z] 22:53:34     INFO -  C:\Users\task_168082015975705\build\tests\xpcshell\head.js:_execute_test:585
[task 2023-04-06T22:53:34.957Z] 22:53:34     INFO -  -e:null:1
[task 2023-04-06T22:53:34.957Z] 22:53:34     INFO -  exiting test
[task 2023-04-06T22:53:34.957Z] 22:53:34     INFO -  PID 5716 | JavaScript error: C:\\Users\\task_168082015975705\\build\\tests\\xpcshell\\head.js, line 868: NS_ERROR_ABORT:
[task 2023-04-06T22:53:34.957Z] 22:53:34     INFO -  PID 5716 | 1680821613654	Sync.LogManager	DEBUG	Flushing file log
[task 2023-04-06T22:53:34.957Z] 22:53:34     INFO -  PID 5716 | 1680821613654	Sync.LogManager	TRACE	Beginning stream copy to error-sync-1680821613654.txt: 1680821613654
[task 2023-04-06T22:53:34.957Z] 22:53:34     INFO -  "CONSOLE_MESSAGE: (error) [JavaScript Error: "NS_ERROR_ABORT: " {file: "C:\\Users\\task_168082015975705\\build\\tests\\xpcshell\\head.js" line: 868}]
[task 2023-04-06T22:53:34.957Z] 22:53:34     INFO -  _abort_failed_test@C:\\Users\\task_168082015975705\\build\\tests\\xpcshell\\head.js:868:20
[task 2023-04-06T22:53:34.957Z] 22:53:34     INFO -  do_report_result@C:\\Users\\task_168082015975705\\build\\tests\\xpcshell\\head.js:977:5
[task 2023-04-06T22:53:34.957Z] 22:53:34     INFO -  Assert<@C:\\Users\\task_168082015975705\\build\\tests\\xpcshell\\head.js:71:21
[task 2023-04-06T22:53:34.957Z] 22:53:34     INFO -  Assert.prototype.report@resource://testing-common/Assert.sys.mjs:240:10
[task 2023-04-06T22:53:34.957Z] 22:53:34     INFO -  compareNumbers@resource://testing-common/Assert.sys.mjs:546:10
[task 2023-04-06T22:53:34.957Z] 22:53:34     INFO -  lessOrEqual@resource://testing-common/Assert.sys.mjs:616:18
[task 2023-04-06T22:53:34.957Z] 22:53:34     INFO -  assert_valid_ping/<@C:/Users/task_168082015975705/build/tests/xpcshell/tests/services/sync/tests/unit/head_helpers.js:314:18
[task 2023-04-06T22:53:34.957Z] 22:53:34     INFO -  assert_valid_ping@C:/Users/task_168082015975705/build/tests/xpcshell/tests/services/sync/tests/unit/head_helpers.js:313:18
[task 2023-04-06T22:53:34.957Z] 22:53:34     INFO -  assert_success_ping@C:/Users/task_168082015975705/build/tests/xpcshell/tests/services/sync/tests/unit/head_helpers.js:322:20
[task 2023-04-06T22:53:34.957Z] 22:53:34     INFO -  sync_engine_and_validate_telem/submitPromise</telem.submit@C:/Users/task_168082015975705/build/tests/xpcshell/tests/services/sync/tests/unit/head_helpers.js:497:28
[task 2023-04-06T22:53:34.957Z] 22:53:34     INFO -  finish@resource://services-sync/telemetry.sys.mjs:870:10
[task 2023-04-06T22:53:34.957Z] 22:53:34     INFO -  maybeSubmitForInterval@resource://services-sync/telemetry.sys.mjs:1051:12
[task 2023-04-06T22:53:34.957Z] 22:53:34     INFO -  takeTelemetryRecord@resource://services-sync/telemetry.sys.mjs:1080:10
[task 2023-04-06T22:53:34.957Z] 22:53:34     INFO -  onSyncFinished@resource://services-sync/telemetry.sys.mjs:1065:10
[task 2023-04-06T22:53:34.957Z] 22:53:34     INFO -  observe@resource://services-sync/telemetry.sys.mjs:1152:16
[task 2023-04-06T22:53:34.957Z] 22:53:34     INFO -  observe@resource://services-common/observers.sys.mjs:131:21
[task 2023-04-06T22:53:34.957Z] 22:53:34     INFO -  notify@resource://services-common/observers.sys.mjs:83:18
[task 2023-04-06T22:53:34.973Z] 22:53:34     INFO -  sync_engine_and_validate_telem@C:/Users/task_168082015975705/build/tests/xpcshell/tests/services/sync/tests/unit/head_helpers.js:527:15
[task 2023-04-06T22:53:34.973Z] 22:53:34     INFO -  _do_main@C:\\Users\\task_168082015975705\\build\\tests\\xpcshell\\head.js:238:6
 INFO -  _execute_test@C:\\Users\\task_168082015975705\\build\\tests\\xpcshell\\head.js:585:5
[task 2023-04-06T22:53:34.973Z] 22:53:34     INFO -  @-e:1:1
[task 2023-04-06T22:53:34.973Z] 22:53:34     INFO -  "
[task 2023-04-06T22:53:34.973Z] 22:53:34     INFO -  PID 5716 | 1680821613655	Sync.LogManager	TRACE	finished copy to: C:\\Users\\task_168082015975705\\AppData\\Local\\Temp\\xpc-profile-i64o1n75\\weave\\logs\\error-sync-1680821613654.txt
[task 2023-04-06T22:53:34.973Z] 22:53:34     INFO -  PID 5716 | 1680821613655	Sync.LogManager	TRACE	onCopyComplete: 1680821613655
[task 2023-04-06T22:53:34.973Z] 22:53:34     INFO -  PID 5716 | console.error: "Sync encountered an error - see about:sync-log for the log file."
[task 2023-04-06T22:53:34.973Z] 22:53:34     INFO -  PID 5716 | 1680821613655	Sync.SyncScheduler	TRACE	Handling network:offline-status-changed
[task 2023-04-06T22:53:34.973Z] 22:53:34     INFO -  PID 5716 | 1680821613655	Sync.SyncScheduler	TRACE	Network offline status change: offline
[task 2023-04-06T22:53:34.973Z] 22:53:34     INFO -  PID 5716 | 1680821613655	Sync.SyncScheduler	TRACE	_checkSync returned "Network is offline".
[task 2023-04-06T22:53:34.973Z] 22:53:34     INFO -  PID 5716 | 1680821613655	Sync.SyncScheduler	DEBUG	Clearing sync triggers and the global score.
[task 2023-04-06T22:53:34.973Z] 22:53:34     INFO -  PID 5716 | 1680821613655	Toolkit.Telemetry	TRACE	TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: services.sync.nextSync
[task 2023-04-06T22:53:34.973Z] 22:53:34     INFO -  PID 5716 | !!! error running onStopped callback: TypeError: callback is not a function
[task 2023-04-06T22:53:34.973Z] 22:53:34     INFO -  PID 5716 | 1680821613657	Sync.LogManager	DEBUG	Done deleting files.
[task 2023-04-06T22:53:34.973Z] 22:53:34     INFO -  PID 5716 | console.error: "Sync encountered an error - see about:sync-log for the log file."
[task 2023-04-06T22:53:34.973Z] 22:53:34     INFO -  PID 5716 | 1680821613676	Sync.SyncScheduler	TRACE	clients: score: 0
[task 2023-04-06T22:53:34.973Z] 22:53:34     INFO -  PID 5716 | 1680821613676	Sync.SyncScheduler	TRACE	prefs: score: 0
[task 2023-04-06T22:53:34.973Z] 22:53:34     INFO -  PID 5716 | 1680821613676	Sync.SyncScheduler	TRACE	passwords: score: 0
[task 2023-04-06T22:53:34.973Z] 22:53:34     INFO -  PID 5716 | 1680821613676	Sync.SyncScheduler	TRACE	tabs: score: 0
[task 2023-04-06T22:53:34.973Z] 22:53:34     INFO -  PID 5716 | 1680821613676	Sync.SyncScheduler	TRACE	addons: score: 0
[task 2023-04-06T22:53:34.973Z] 22:53:34     INFO -  PID 5716 | 1680821613676	Sync.SyncScheduler	TRACE	forms: score: 0
[task 2023-04-06T22:53:34.973Z] 22:53:34     INFO -  PID 5716 | 1680821613676	Sync.SyncScheduler	TRACE	history: score: 0
[task 2023-04-06T22:53:34.973Z] 22:53:34     INFO -  PID 5716 | 1680821613676	Sync.SyncScheduler	TRACE	extension-storage: score: 0
[task 2023-04-06T22:53:34.973Z] 22:53:34     INFO -  PID 5716 | 1680821613676	Sync.SyncScheduler	TRACE	Global score updated: 0
[task 2023-04-06T22:53:34.973Z] 22:53:34     INFO -  PID 5716 | 1680821613676	Sync.SyncScheduler	TRACE	_checkSync returned "Network is offline".
[task 2023-04-06T22:53:34.973Z] 22:53:34     INFO -  PID 5716 | 1680821613676	Sync.SyncScheduler	DEBUG	Clearing sync triggers and the global score.
[task 2023-04-06T22:53:34.973Z] 22:53:34     INFO -  PID 5716 | 1680821613696	Sync.Engine.Prefs.Tracker	TRACE	stop().
[task 2023-04-06T22:53:34.973Z] 22:53:34     INFO -  PID 5716 | 1680821613756	Toolkit.Telemetry	TRACE	TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: security.turn_off_all_security_so_that_viruses_can_take_over_this_computer
[task 2023-04-06T22:53:34.973Z] 22:53:34     INFO -  <<<<<<<
[task 2023-04-06T22:53:34.988Z] 22:53:34     INFO -  TEST-START | xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_startup_cache_telemetry.js

Comment 1

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• windows7-32-shippable-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1826913&startday=2023-04-03&endday=2023-04-09&tree=all

Comment 2

[BugBot [:suhaib / :marco/ :calixte]]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit BugBot documentation.

Comment 3

[Treeherder Bug Filer]

New failure instance: https://treeherder.mozilla.org/logviewer?job_id=416462399&repo=autoland

Comment 4

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• windows7-32-shippable-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1826913&startday=2023-05-15&endday=2023-05-21&tree=all

Comment 5

[BugBot [:suhaib / :marco/ :calixte]]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit BugBot documentation.

Comment 6

[Treeherder Bug Filer]

New failure instance: https://treeherder.mozilla.org/logviewer?job_id=434546536&repo=mozilla-beta

Comment 7

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-beta: 1

Platform and build breakdown:

• windows11-64-2009-qr: 1
  • debug: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1826913&startday=2023-10-30&endday=2023-11-05&tree=all

Comment 8

[BugBot [:suhaib / :marco/ :calixte]]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit BugBot documentation.

Comment 9

[Treeherder Bug Filer]

New failure instance: https://treeherder.mozilla.org/logviewer?job_id=437846754&repo=mozilla-beta

Comment 10

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-beta: 1

Platform and build breakdown:

• windows11-64-2009-qr: 1
  • debug: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1826913&startday=2023-11-27&endday=2023-12-03&tree=all

Comment 11

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 1

Platform and build breakdown:

• windows11-32-2009-shippable-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1826913&startday=2023-12-18&endday=2023-12-24&tree=all

Comment 12

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-esr115: 1

Platform and build breakdown:

• windows7-32-shippable-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1826913&startday=2024-01-01&endday=2024-01-07&tree=all

Comment 13

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 2

Platform and build breakdown:

• macosx1015-64-qr: 2
  • debug: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1826913&startday=2024-01-08&endday=2024-01-14&tree=all

Comment 14

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• windows11-32-2009-qr: 1
  • debug: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1826913&startday=2024-01-29&endday=2024-02-04&tree=all

Comment 15

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• windows10-64-2009-qr: 1
  • debug: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1826913&startday=2024-02-12&endday=2024-02-18&tree=all

Comment 16

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• macosx1015-64-qr: 1
  • debug: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1826913&startday=2024-02-19&endday=2024-02-25&tree=all

Comment 18

[BugBot [:suhaib / :marco/ :calixte]]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit BugBot documentation.

Comment 19

[Treeherder Bug Filer]

New failure instance: https://treeherder.mozilla.org/logviewer?job_id=458997411&repo=try

Comment 20

[Intermittent Failures Robot]

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

Repository breakdown:

• try: 1

Platform and build breakdown:

• windows11-64-2009-qr: 1
  • debug: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1826913&startday=2024-05-20&endday=2024-05-26&tree=all

Comment 21

[BugBot [:suhaib / :marco/ :calixte]]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit BugBot documentation.

Comment 22

[Intermittent Failures Robot]

1 failures were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 1

Table

	msix
windows11-x86_64/opt	1

For more details, see:

https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1826913&startday=2026-01-05&endday=2026-01-11&tree=all