Closed Bug 1743178 Opened 4 years ago Closed 4 years ago

Intermittent toolkit/modules/tests/xpcshell/test_firstStartup.js | xpcshell return code: 0 | test_timeout - [test_timeout : 271] A promise chain failed to handle a rejection: Data is not ready. - stack: get data@resource://gre/modules/JSONFile.jsm:186:13

Categories

(Toolkit :: General, defect, P5)

defect

Tracking

()

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

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


[task 2021-11-26T17:51:12.113Z] 17:51:12     INFO -  TEST-START | toolkit/modules/tests/xpcshell/test_firstStartup.js
[task 2021-11-26T17:51:29.860Z] 17:51:29  WARNING -  TEST-UNEXPECTED-FAIL | toolkit/modules/tests/xpcshell/test_firstStartup.js | xpcshell return code: 0
[task 2021-11-26T17:51:29.860Z] 17:51:29     INFO -  TEST-INFO took 17747ms
[task 2021-11-26T17:51:29.873Z] 17:51:29     INFO -  >>>>>>>
[task 2021-11-26T17:51:29.873Z] 17:51:29     INFO -  (xpcshell/head.js) | test MAIN run_test pending (1)
[task 2021-11-26T17:51:29.873Z] 17:51:29     INFO -  (xpcshell/head.js) | test run_next_test 0 pending (2)
[task 2021-11-26T17:51:29.873Z] 17:51:29     INFO -  (xpcshell/head.js) | test MAIN run_test finished (2)
[task 2021-11-26T17:51:29.874Z] 17:51:29     INFO -  running event loop
[task 2021-11-26T17:51:29.874Z] 17:51:29     INFO -  toolkit/modules/tests/xpcshell/test_firstStartup.js | Starting test_success
[task 2021-11-26T17:51:29.874Z] 17:51:29     INFO -  (xpcshell/head.js) | test test_success pending (2)
[task 2021-11-26T17:51:29.874Z] 17:51:29     INFO -  "CONSOLE_MESSAGE: (error) [JavaScript Error: "NetworkError: Network request failed" {file: "resource://services-settings/Utils.jsm" line: 131}]
[task 2021-11-26T17:51:29.875Z] 17:51:29     INFO -  fetch/</request.onerror@resource://services-settings/Utils.jsm:131:26
[task 2021-11-26T17:51:29.875Z] 17:51:29     INFO -  init@resource://gre/modules/FirstStartup.jsm:67:19
[task 2021-11-26T17:51:29.875Z] 17:51:29     INFO -  test_success@Z:/task_163794621319069/build/tests/xpcshell/tests/toolkit/modules/tests/xpcshell/test_firstStartup.js:17:16
[task 2021-11-26T17:51:29.875Z] 17:51:29     INFO -  _run_next_test/<@Z:\\task_163794621319069\\build\\tests\\xpcshell\\head.js:1674:22
[task 2021-11-26T17:51:29.876Z] 17:51:29     INFO -  _run_next_test@Z:\\task_163794621319069\\build\\tests\\xpcshell\\head.js:1674:38
[task 2021-11-26T17:51:29.876Z] 17:51:29     INFO -  run@Z:\\task_163794621319069\\build\\tests\\xpcshell\\head.js:820:9
[task 2021-11-26T17:51:29.876Z] 17:51:29     INFO -  _do_main@Z:\\task_163794621319069\\build\\tests\\xpcshell\\head.js:240:6
[task 2021-11-26T17:51:29.876Z] 17:51:29     INFO -  _execute_test@Z:\\task_163794621319069\\build\\tests\\xpcshell\\head.js:605:5
[task 2021-11-26T17:51:29.877Z] 17:51:29     INFO -  @-e:1:1
[task 2021-11-26T17:51:29.877Z] 17:51:29     INFO -  "
[task 2021-11-26T17:51:29.877Z] 17:51:29     INFO -  "CONSOLE_MESSAGE: (error) [JavaScript Error: "NetworkError: Network request failed" {file: "resource://services-settings/Utils.jsm" line: 131}]
[task 2021-11-26T17:51:29.877Z] 17:51:29     INFO -  fetch/</request.onerror@resource://services-settings/Utils.jsm:131:26
[task 2021-11-26T17:51:29.877Z] 17:51:29     INFO -  init@resource://gre/modules/FirstStartup.jsm:67:19
[task 2021-11-26T17:51:29.878Z] 17:51:29     INFO -  test_success@Z:/task_163794621319069/build/tests/xpcshell/tests/toolkit/modules/tests/xpcshell/test_firstStartup.js:17:16
[task 2021-11-26T17:51:29.878Z] 17:51:29     INFO -  _run_next_test/<@Z:\\task_163794621319069\\build\\tests\\xpcshell\\head.js:1674:22
[task 2021-11-26T17:51:29.878Z] 17:51:29     INFO -  _run_next_test@Z:\\task_163794621319069\\build\\tests\\xpcshell\\head.js:1674:38
[task 2021-11-26T17:51:29.879Z] 17:51:29     INFO -  run@Z:\\task_163794621319069\\build\\tests\\xpcshell\\head.js:820:9
[task 2021-11-26T17:51:29.879Z] 17:51:29     INFO -  _do_main@Z:\\task_163794621319069\\build\\tests\\xpcshell\\head.js:240:6
[task 2021-11-26T17:51:29.879Z] 17:51:29     INFO -  _execute_test@Z:\\task_163794621319069\\build\\tests\\xpcshell\\head.js:605:5
[task 2021-11-26T17:51:29.879Z] 17:51:29     INFO -  @-e:1:1
[task 2021-11-26T17:51:29.879Z] 17:51:29     INFO -  "
[task 2021-11-26T17:51:29.880Z] 17:51:29     INFO -  "CONSOLE_MESSAGE: (error) [JavaScript Error: "NetworkError: Network request failed" {file: "resource://services-settings/Utils.jsm" line: 131}]
[task 2021-11-26T17:51:29.880Z] 17:51:29     INFO -  fetch/</request.onerror@resource://services-settings/Utils.jsm:131:26
[task 2021-11-26T17:51:29.880Z] 17:51:29     INFO -  init@resource://gre/modules/FirstStartup.jsm:67:19
[task 2021-11-26T17:51:29.881Z] 17:51:29     INFO -  test_success@Z:/task_163794621319069/build/tests/xpcshell/tests/toolkit/modules/tests/xpcshell/test_firstStartup.js:17:16
[task 2021-11-26T17:51:29.881Z] 17:51:29     INFO -  _run_next_test/<@Z:\\task_163794621319069\\build\\tests\\xpcshell\\head.js:1674:22
[task 2021-11-26T17:51:29.881Z] 17:51:29     INFO -  _run_next_test@Z:\\task_163794621319069\\build\\tests\\xpcshell\\head.js:1674:38
[task 2021-11-26T17:51:29.881Z] 17:51:29     INFO -  run@Z:\\task_163794621319069\\build\\tests\\xpcshell\\head.js:820:9
[task 2021-11-26T17:51:29.882Z] 17:51:29     INFO -  _do_main@Z:\\task_163794621319069\\build\\tests\\xpcshell\\head.js:240:6
[task 2021-11-26T17:51:29.882Z] 17:51:29     INFO -  _execute_test@Z:\\task_163794621319069\\build\\tests\\xpcshell\\head.js:605:5
[task 2021-11-26T17:51:29.882Z] 17:51:29     INFO -  @-e:1:1
[task 2021-11-26T17:51:29.882Z] 17:51:29     INFO -  "
[task 2021-11-26T17:51:29.882Z] 17:51:29     INFO -  "CONSOLE_MESSAGE: (warn) [JavaScript Warning: "telemetry.state_file_read_errors - Unknown scalar."]"
[task 2021-11-26T17:51:29.883Z] 17:51:29     INFO -  "CONSOLE_MESSAGE: (warn) [JavaScript Warning: "telemetry.generated_new_client_id - Unknown scalar."]"
[task 2021-11-26T17:51:29.883Z] 17:51:29     INFO -  TEST-PASS | toolkit/modules/tests/xpcshell/test_firstStartup.js | test_success - [test_success : 19] 3 == 3
[task 2021-11-26T17:51:29.883Z] 17:51:29     INFO -  (xpcshell/head.js) | test run_next_test 0 finished (2)
[task 2021-11-26T17:51:29.883Z] 17:51:29     INFO -  (xpcshell/head.js) | test run_next_test 1 pending (2)
[task 2021-11-26T17:51:29.884Z] 17:51:29     INFO -  (xpcshell/head.js) | test test_success finished (2)
[task 2021-11-26T17:51:29.884Z] 17:51:29     INFO -  toolkit/modules/tests/xpcshell/test_firstStartup.js | Starting test_timeout
[task 2021-11-26T17:51:29.884Z] 17:51:29     INFO -  (xpcshell/head.js) | test test_timeout pending (2)
[task 2021-11-26T17:51:29.884Z] 17:51:29     INFO -  TEST-PASS | toolkit/modules/tests/xpcshell/test_firstStartup.js | test_timeout - [test_timeout : 31] 2 == 2
[task 2021-11-26T17:51:29.885Z] 17:51:29     INFO -  (xpcshell/head.js) | test run_next_test 1 finished (2)
[task 2021-11-26T17:51:29.885Z] 17:51:29     INFO -  (xpcshell/head.js) | test run_next_test 2 pending (2)
[task 2021-11-26T17:51:29.885Z] 17:51:29     INFO -  (xpcshell/head.js) | test test_timeout finished (2)
[task 2021-11-26T17:51:29.885Z] 17:51:29     INFO -  (xpcshell/head.js) | test run_next_test 2 finished (1)
[task 2021-11-26T17:51:29.885Z] 17:51:29     INFO -  exiting test
[task 2021-11-26T17:51:29.886Z] 17:51:29     INFO -  PID 9460 | JavaScript error: resource://gre/modules/JSONFile.jsm, line 186: Error: Data is not ready.
[task 2021-11-26T17:51:29.886Z] 17:51:29     INFO -  "CONSOLE_MESSAGE: (error) [JavaScript Error: "Error: Data is not ready." {file: "resource://gre/modules/JSONFile.jsm" line: 186}]"
[task 2021-11-26T17:51:29.886Z] 17:51:29  WARNING -  TEST-UNEXPECTED-FAIL | toolkit/modules/tests/xpcshell/test_firstStartup.js | test_timeout - [test_timeout : 271] A promise chain failed to handle a rejection: Data is not ready. - stack: get data@resource://gre/modules/JSONFile.jsm:186:13
[task 2021-11-26T17:51:29.887Z] 17:51:29     INFO -  recordOriginalValues@resource://normandy/lib/PreferenceExperiments.jsm:198:30
[task 2021-11-26T17:51:29.887Z] 17:51:29     INFO -  observe@resource://gre/modules/AsyncShutdown.jsm:575:16
[task 2021-11-26T17:51:29.887Z] 17:51:29     INFO -  _execute_test@Z:\\task_163794621319069\\build\\tests\\xpcshell\\head.js:712:23
[task 2021-11-26T17:51:29.887Z] 17:51:29     INFO -  @-e:1:1
[task 2021-11-26T17:51:29.888Z] 17:51:29     INFO -  Rejection date: Fri Nov 26 2021 17:51:29 GMT+0000 (Greenwich Mean Time) - false == true
[task 2021-11-26T17:51:29.888Z] 17:51:29     INFO -  resource://testing-common/PromiseTestUtils.jsm:assertNoUncaughtRejections:271
[task 2021-11-26T17:51:29.888Z] 17:51:29     INFO -  Z:\task_163794621319069\build\tests\xpcshell\head.js:_execute_test:724
[task 2021-11-26T17:51:29.888Z] 17:51:29     INFO -  -e:null:1
[task 2021-11-26T17:51:29.888Z] 17:51:29     INFO -  exiting test
[task 2021-11-26T17:51:29.889Z] 17:51:29     INFO -  PID 9460 | JavaScript error: Z:\task_163794621319069\build\tests\xpcshell\head.js, line 876: NS_ERROR_ABORT:
[task 2021-11-26T17:51:29.889Z] 17:51:29     INFO -  <<<<<<<
[task 2021-11-26T17:51:29.891Z] 17:51:29     INFO -  INFO | Result summary:
[task 2021-11-26T17:51:29.891Z] 17:51:29     INFO -  INFO | Passed: 1946
[task 2021-11-26T17:51:29.891Z] 17:51:29  WARNING -  INFO | Failed: 1
[task 2021-11-26T17:51:29.891Z] 17:51:29  WARNING -  One or more unittests failed.
[task 2021-11-26T17:51:29.891Z] 17:51:29     INFO -  INFO | Todo: 0
[task 2021-11-26T17:51:29.892Z] 17:51:29     INFO -  INFO | Retried: 4
[task 2021-11-26T17:51:29.892Z] 17:51:29     INFO -  SUITE-END | took 791s
Status: NEW → RESOLVED
Closed: 4 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.