Closed Bug 1750888 Opened 4 years ago Closed 3 years ago

Intermittent toolkit/components/extensions/test/xpcshell/test_ext_extension_startup_telemetry.js | test_telemetry - [test_telemetry : 63] Data recorded for second extension for histogram: WEBEXT_EXTENSION_STARTUP_MS. - false deepEqual true

Categories

(WebExtensions :: General, defect, P5)

Product:
WebExtensions
Component:
General
Type:
defect

Tracking

(Not tracked)

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

Filed by: imoraru [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=364573004&repo=mozilla-release
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/N1SuI-SbRrWWARpe8-l_AA/runs/0/artifacts/public/logs/live_backing.log

[task 2022-01-19T00:23:30.927Z] 00:23:30     INFO -  TEST-START | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_extension_startup_telemetry.js
[task 2022-01-19T00:23:31.079Z] 00:23:31  WARNING -  TEST-UNEXPECTED-FAIL | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_extension_startup_telemetry.js | xpcshell return code: 0
[task 2022-01-19T00:23:31.079Z] 00:23:31     INFO -  TEST-INFO took 151ms
[task 2022-01-19T00:23:31.079Z] 00:23:31     INFO -  >>>>>>>
[task 2022-01-19T00:23:31.079Z] 00:23:31     INFO -  (xpcshell/head.js) | test MAIN run_test pending (1)
[task 2022-01-19T00:23:31.079Z] 00:23:31     INFO -  (xpcshell/head.js) | test run_next_test 0 pending (2)
[task 2022-01-19T00:23:31.079Z] 00:23:31     INFO -  (xpcshell/head.js) | test MAIN run_test finished (2)
[task 2022-01-19T00:23:31.079Z] 00:23:31     INFO -  running event loop
[task 2022-01-19T00:23:31.079Z] 00:23:31     INFO -  xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_extension_startup_telemetry.js | Starting check_remote
[task 2022-01-19T00:23:31.079Z] 00:23:31     INFO -  (xpcshell/head.js) | test check_remote pending (2)
[task 2022-01-19T00:23:31.079Z] 00:23:31     INFO -  TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_extension_startup_telemetry.js | check_remote - [check_remote : 1] useRemoteWebExtensions matches - true == true
[task 2022-01-19T00:23:31.080Z] 00:23:31     INFO -  TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_extension_startup_telemetry.js | check_remote - [check_remote : 1] testing from extension process - false == false
[task 2022-01-19T00:23:31.080Z] 00:23:31     INFO -  (xpcshell/head.js) | test run_next_test 0 finished (2)
[task 2022-01-19T00:23:31.080Z] 00:23:31     INFO -  (xpcshell/head.js) | test run_next_test 1 pending (2)
[task 2022-01-19T00:23:31.080Z] 00:23:31     INFO -  (xpcshell/head.js) | test check_remote finished (2)
[task 2022-01-19T00:23:31.080Z] 00:23:31     INFO -  xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_extension_startup_telemetry.js | Starting test_telemetry
[task 2022-01-19T00:23:31.080Z] 00:23:31     INFO -  (xpcshell/head.js) | test test_telemetry pending (2)
[task 2022-01-19T00:23:31.080Z] 00:23:31     INFO -  "Extension attached"
[task 2022-01-19T00:23:31.080Z] 00:23:31     INFO -  "Extension attached"
[task 2022-01-19T00:23:31.080Z] 00:23:31     INFO -  TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_extension_startup_telemetry.js | test_telemetry - [test_telemetry : 31] No data recorded for histogram: WEBEXT_EXTENSION_STARTUP_MS. - 0 deepEqual 0
[task 2022-01-19T00:23:31.080Z] 00:23:31     INFO -  TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_extension_startup_telemetry.js | test_telemetry - [test_telemetry : 32] No data recorded for histogram: WEBEXT_EXTENSION_STARTUP_MS_BY_ADDONID. - 0 deepEqual 0
[task 2022-01-19T00:23:31.080Z] 00:23:31     INFO -  (xpcshell/head.js) | test run_next_test 1 finished (2)
[task 2022-01-19T00:23:31.080Z] 00:23:31     INFO -  TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_extension_startup_telemetry.js | test_telemetry - [test_telemetry : 36] Data recorded for first extension for histogram: WEBEXT_EXTENSION_STARTUP_MS. - true deepEqual true
[task 2022-01-19T00:23:31.081Z] 00:23:31     INFO -  TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_extension_startup_telemetry.js | test_telemetry - [test_telemetry : 42] Data recorded for first extension for histogram WEBEXT_EXTENSION_STARTUP_MS_BY_ADDONID - {"{564c37d0-6ad5-4981-860d-a120c77855ef}":true} deepEqual {"{564c37d0-6ad5-4981-860d-a120c77855ef}":true}
[task 2022-01-19T00:23:31.081Z] 00:23:31  WARNING -  TEST-UNEXPECTED-FAIL | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_extension_startup_telemetry.js | test_telemetry - [test_telemetry : 63] Data recorded for second extension for histogram: WEBEXT_EXTENSION_STARTUP_MS. - false deepEqual true
[task 2022-01-19T00:23:31.081Z] 00:23:31     INFO -  /opt/worker/tasks/task_1642551031/build/tests/xpcshell/tests/toolkit/components/extensions/test/xpcshell/test_ext_extension_startup_telemetry.js:test_telemetry:63
[task 2022-01-19T00:23:31.081Z] 00:23:31     INFO -  /opt/worker/tasks/task_1642551031/build/tests/xpcshell/head.js:_do_main:240
[task 2022-01-19T00:23:31.081Z] 00:23:31     INFO -  /opt/worker/tasks/task_1642551031/build/tests/xpcshell/head.js:_execute_test:605
[task 2022-01-19T00:23:31.081Z] 00:23:31     INFO -  -e:null:1
[task 2022-01-19T00:23:31.081Z] 00:23:31     INFO -  exiting test
[task 2022-01-19T00:23:31.081Z] 00:23:31     INFO -  Unexpected exception NS_ERROR_ABORT:
[task 2022-01-19T00:23:31.081Z] 00:23:31     INFO -  _abort_failed_test@/opt/worker/tasks/task_1642551031/build/tests/xpcshell/head.js:876:20
[task 2022-01-19T00:23:31.081Z] 00:23:31     INFO -  do_report_result@/opt/worker/tasks/task_1642551031/build/tests/xpcshell/head.js:977:5
[task 2022-01-19T00:23:31.081Z] 00:23:31     INFO -  Assert<@/opt/worker/tasks/task_1642551031/build/tests/xpcshell/head.js:75:21
[task 2022-01-19T00:23:31.081Z] 00:23:31     INFO -  proto.report@resource://testing-common/Assert.jsm:228:10
[task 2022-01-19T00:23:31.081Z] 00:23:31     INFO -  deepEqual@resource://testing-common/Assert.jsm:305:8
[task 2022-01-19T00:23:31.081Z] 00:23:31     INFO -  assertHistogramSnapshot@/opt/worker/tasks/task_1642551031/build/tests/xpcshell/tests/toolkit/components/extensions/test/xpcshell/head_telemetry.js:85:10
[task 2022-01-19T00:23:31.082Z] 00:23:31     INFO -  test_telemetry@/opt/worker/tasks/task_1642551031/build/tests/xpcshell/tests/toolkit/components/extensions/test/xpcshell/test_ext_extension_startup_telemetry.js:63:26
[task 2022-01-19T00:23:31.082Z] 00:23:31     INFO -  _do_main@/opt/worker/tasks/task_1642551031/build/tests/xpcshell/head.js:240:6
[task 2022-01-19T00:23:31.082Z] 00:23:31     INFO -  _execute_test@/opt/worker/tasks/task_1642551031/build/tests/xpcshell/head.js:605:5
[task 2022-01-19T00:23:31.082Z] 00:23:31     INFO -  @-e:1:1
[task 2022-01-19T00:23:31.082Z] 00:23:31     INFO -  exiting test
[task 2022-01-19T00:23:31.082Z] 00:23:31  WARNING -  TEST-UNEXPECTED-FAIL | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_extension_startup_telemetry.js | test_telemetry - [test_telemetry : 91] Extension left running at test shutdown - "running" == "unloaded"
[task 2022-01-19T00:23:31.082Z] 00:23:31     INFO -  resource://testing-common/ExtensionXPCShellUtils.jsm:ExtensionWrapper/<:91
[task 2022-01-19T00:23:31.082Z] 00:23:31     INFO -  /opt/worker/tasks/task_1642551031/build/tests/xpcshell/head.js:_execute_test/<:669
[task 2022-01-19T00:23:31.082Z] 00:23:31     INFO -  /opt/worker/tasks/task_1642551031/build/tests/xpcshell/head.js:_execute_test:678
[task 2022-01-19T00:23:31.082Z] 00:23:31     INFO -  -e:null:1
[task 2022-01-19T00:23:31.082Z] 00:23:31     INFO -  exiting test
[task 2022-01-19T00:23:31.082Z] 00:23:31     INFO -  NS_ERROR_ABORT:
[task 2022-01-19T00:23:31.082Z] 00:23:31     INFO -  _abort_failed_test@/opt/worker/tasks/task_1642551031/build/tests/xpcshell/head.js:876:20
[task 2022-01-19T00:23:31.082Z] 00:23:31     INFO -  do_report_result@/opt/worker/tasks/task_1642551031/build/tests/xpcshell/head.js:977:5
[task 2022-01-19T00:23:31.082Z] 00:23:31     INFO -  Assert<@/opt/worker/tasks/task_1642551031/build/tests/xpcshell/head.js:75:21
[task 2022-01-19T00:23:31.083Z] 00:23:31     INFO -  proto.report@resource://testing-common/Assert.jsm:228:10
[task 2022-01-19T00:23:31.083Z] 00:23:31     INFO -  equal@resource://testing-common/Assert.jsm:270:8
[task 2022-01-19T00:23:31.083Z] 00:23:31     INFO -  ExtensionWrapper/<@resource://testing-common/ExtensionXPCShellUtils.jsm:91:24
[task 2022-01-19T00:23:31.083Z] 00:23:31     INFO -  _execute_test/<@/opt/worker/tasks/task_1642551031/build/tests/xpcshell/head.js:669:28
[task 2022-01-19T00:23:31.083Z] 00:23:31     INFO -  _execute_test@/opt/worker/tasks/task_1642551031/build/tests/xpcshell/head.js:678:5
[task 2022-01-19T00:23:31.083Z] 00:23:31     INFO -  @-e:1:1
[task 2022-01-19T00:23:31.083Z] 00:23:31  WARNING -  TEST-UNEXPECTED-FAIL | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_extension_startup_telemetry.js | test_telemetry - [test_telemetry : 91] Extension left running at test shutdown - "running" == "unloaded"
[task 2022-01-19T00:23:31.083Z] 00:23:31     INFO -  resource://testing-common/ExtensionXPCShellUtils.jsm:ExtensionWrapper/<:91
[task 2022-01-19T00:23:31.083Z] 00:23:31     INFO -  /opt/worker/tasks/task_1642551031/build/tests/xpcshell/head.js:_execute_test/<:669
[task 2022-01-19T00:23:31.083Z] 00:23:31     INFO -  /opt/worker/tasks/task_1642551031/build/tests/xpcshell/head.js:_execute_test:678
[task 2022-01-19T00:23:31.083Z] 00:23:31     INFO -  -e:null:1
[task 2022-01-19T00:23:31.083Z] 00:23:31     INFO -  exiting test
[task 2022-01-19T00:23:31.083Z] 00:23:31     INFO -  NS_ERROR_ABORT:
[task 2022-01-19T00:23:31.083Z] 00:23:31     INFO -  _abort_failed_test@/opt/worker/tasks/task_1642551031/build/tests/xpcshell/head.js:876:20
[task 2022-01-19T00:23:31.083Z] 00:23:31     INFO -  do_report_result@/opt/worker/tasks/task_1642551031/build/tests/xpcshell/head.js:977:5
[task 2022-01-19T00:23:31.083Z] 00:23:31     INFO -  Assert<@/opt/worker/tasks/task_1642551031/build/tests/xpcshell/head.js:75:21
[task 2022-01-19T00:23:31.084Z] 00:23:31     INFO -  proto.report@resource://testing-common/Assert.jsm:228:10
[task 2022-01-19T00:23:31.084Z] 00:23:31     INFO -  equal@resource://testing-common/Assert.jsm:270:8
[task 2022-01-19T00:23:31.084Z] 00:23:31     INFO -  ExtensionWrapper/<@resource://testing-common/ExtensionXPCShellUtils.jsm:91:24
[task 2022-01-19T00:23:31.084Z] 00:23:31     INFO -  _execute_test/<@/opt/worker/tasks/task_1642551031/build/tests/xpcshell/head.js:669:28
[task 2022-01-19T00:23:31.084Z] 00:23:31     INFO -  _execute_test@/opt/worker/tasks/task_1642551031/build/tests/xpcshell/head.js:678:5
[task 2022-01-19T00:23:31.084Z] 00:23:31     INFO -  @-e:1:1
[task 2022-01-19T00:23:31.084Z] 00:23:31     INFO -  <<<<<<<
[task 2022-01-19T00:23:31.084Z] 00:23:31     INFO -  TEST-START | toolkit/components/processtools/tests/xpcshell/test_total_cpu_time.js

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

Status: NEW → RESOLVED
Closed: 3 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.