Closed Bug 1728528 Opened 3 years ago Closed 3 years ago

Intermittent TEST-UNEXPECTED-TIMEOUT | devtools/server/tests/xpcshell/test_addon_debugging_connect.js | Test timed out

Categories

(DevTools :: General, defect, P5)

defect

Tracking

(Not tracked)

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

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


INFO -  TEST-START | devtools/server/tests/xpcshell/test_addon_debugging_connect.js
[task 2021-09-01T08:06:32.487Z] 08:06:32  WARNING -  TEST-UNEXPECTED-TIMEOUT | devtools/server/tests/xpcshell/test_addon_debugging_connect.js | Test timed out
[task 2021-09-01T08:06:32.488Z] 08:06:32     INFO -  TEST-INFO took 300094ms
[task 2021-09-01T08:06:32.488Z] 08:06:32     INFO -  >>>>>>>
[task 2021-09-01T08:06:32.488Z] 08:06:32     INFO -  PID 4712 | [Parent 4712, Main Thread] WARNING: Failed to get directory to cache.: file /builds/worker/checkouts/gecko/security/sandbox/win/src/sandboxbroker/sandboxBroker.cpp:119
[task 2021-09-01T08:06:32.489Z] 08:06:32     INFO -  PID 4712 | [Parent 4712, Main Thread] WARNING: Failed to get directory to cache.: file /builds/worker/checkouts/gecko/security/sandbox/win/src/sandboxbroker/sandboxBroker.cpp:119
[task 2021-09-01T08:06:32.489Z] 08:06:32     INFO -  PID 4712 | [Parent 4712, Main Thread] WARNING: Failed to get directory to cache.: file /builds/worker/checkouts/gecko/security/sandbox/win/src/sandboxbroker/sandboxBroker.cpp:119
[task 2021-09-01T08:06:32.489Z] 08:06:32     INFO -  PID 4712 | [Parent 4712, Main Thread] WARNING: Couldn't get the user appdata directory. Crash events may not be produced.: file /builds/worker/checkouts/gecko/toolkit/crashreporter/nsExceptionHandler.cpp:2940
[task 2021-09-01T08:06:32.490Z] 08:06:32     INFO -  (xpcshell/head.js) | test MAIN run_test pending (1)
[task 2021-09-01T08:06:32.490Z] 08:06:32     INFO -  (xpcshell/head.js) | test run_next_test 0 pending (2)
[task 2021-09-01T08:06:32.490Z] 08:06:32     INFO -  (xpcshell/head.js) | test MAIN run_test finished (2)
[task 2021-09-01T08:06:32.490Z] 08:06:32     INFO -  running event loop
[task 2021-09-01T08:06:32.491Z] 08:06:32     INFO -  devtools/server/tests/xpcshell/test_addon_debugging_connect.js | Starting test_webextension_addon_debugging_connect
[task 2021-09-01T08:06:32.491Z] 08:06:32     INFO -  (xpcshell/head.js) | test test_webextension_addon_debugging_connect pending (2)
[task 2021-09-01T08:06:32.491Z] 08:06:32     INFO -  PID 4712 | DBG-TEST: head_dbg.js observed a console message: 1630483292878	addons.manager	DEBUG	Application has been upgraded
[task 2021-09-01T08:06:32.491Z] 08:06:32     INFO -  "CONSOLE_MESSAGE: (info) 1630483292878	addons.manager	DEBUG	Application has been upgraded"
[task 2021-09-01T08:06:32.492Z] 08:06:32     INFO -  PID 4712 | DBG-TEST: head_dbg.js observed a console message: 1630483292878	addons.manager	DEBUG	Loaded provider scope for resource://gre/modules/addons/XPIProvider.jsm: ["XPIProvider", "XPIInternal"]
[task 2021-09-01T08:06:32.492Z] 08:06:32     INFO -  "CONSOLE_MESSAGE: (info) 1630483292878	addons.manager	DEBUG	Loaded provider scope for resource://gre/modules/addons/XPIProvider.jsm: ["XPIProvider", "XPIInternal"]"
[task 2021-09-01T08:06:32.492Z] 08:06:32     INFO -  PID 4712 | DBG-TEST: head_dbg.js observed a console message: 1630483292892	addons.manager	DEBUG	Loaded provider scope for resource://gre/modules/addons/GMPProvider.jsm
[task 2021-09-01T08:06:32.493Z] 08:06:32     INFO -  "CONSOLE_MESSAGE: (info) 1630483292892	addons.manager	DEBUG	Loaded provider scope for resource://gre/modules/addons/GMPProvider.jsm"
[task 2021-09-01T08:06:32.493Z] 08:06:32     INFO -  PID 4712 | DBG-TEST: head_dbg.js observed a console message: 1630483292892	addons.manager	DEBUG	Starting provider: XPIProvider
[task 2021-09-01T08:06:32.493Z] 08:06:32     INFO -  "CONSOLE_MESSAGE: (info) 1630483292892	addons.manager	DEBUG	Starting provider: XPIProvider"
[task 2021-09-01T08:06:32.494Z] 08:06:32     INFO -  PID 4712 | DBG-TEST: head_dbg.js observed a console message: 1630483292893	addons.xpi	DEBUG	startup
[task 2021-09-01T08:06:32.494Z] 08:06:32     INFO -  "CONSOLE_MESSAGE: (info) 1630483292893	addons.xpi	DEBUG	startup"
[task 2021-09-01T08:06:32.495Z] 08:06:32     INFO -  PID 4712 | DBG-TEST: head_dbg.js observed a console message: 1630483292894	addons.xpi	DEBUG	List of built-in add-ons is missing or invalid.: [Exception... "Component returned failure code: 0xc1f30001 (NS_ERROR_NOT_INITIALIZED) [nsIXPCComponents_Utils.readUTF8URI]"  nsresult: "0xc1f30001 (NS_ERROR_NOT_INITIALIZED)"  location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm :: startup :: line 2448"  data: no] Stack trace: startup()@resource://gre/modules/addons/XPIProvider.jsm:2448
[task 2021-09-01T08:06:32.495Z] 08:06:32     INFO -  PID 4712 | callProvider()@resource://gre/modules/AddonManager.jsm:222
[task 2021-09-01T08:06:32.495Z] 08:06:32     INFO -  PID 4712 | _startProvider()@resource://gre/modules/AddonManager.jsm:582
[task 2021-09-01T08:06:32.496Z] 08:06:32     INFO -  PID 4712 | startup()@resource://gre/modules/AddonManager.jsm:806
[task 2021-09-01T08:06:32.496Z] 08:06:32     INFO -  PID 4712 | startup()@resource://gre/modules/AddonManager.jsm:3499
[task 2021-09-01T08:06:32.496Z] 08:06:32     INFO -  PID 4712 | observe()@resource://gre/modules/addonManager.js:81
[task 2021-09-01T08:06:32.496Z] 08:06:32     INFO -  PID 4712 | promiseStartupManager()@resource://testing-common/AddonTestUtils.jsm:890
[task 2021-09-01T08:06:32.497Z] 08:06:32     INFO -  PID 4712 | test_webextension_addon_debugging_connect()@Z:/task_1630482044/build/tests/xpcshell/tests/devtools/server/tests/xpcshell/test_addon_debugging_connect.js:64
[task 2021-09-01T08:06:32.497Z] 08:06:32     INFO -  PID 4712 | _run_next_test/<()@Z:\task_1630482044\build\tests\xpcshell\head.js:1658
[task 2021-09-01T08:06:32.497Z] 08:06:32     INFO -  PID 4712 | _run_next_test()@Z:\task_1630482044\build\tests\xpcshell\head.js:1658
[task 2021-09-01T08:06:32.497Z] 08:06:32     INFO -  PID 4712 | run()@Z:\task_1630482044\build\tests\xpcshell\head.js:804
[task 2021-09-01T08:06:32.498Z] 08:06:32     INFO -  PID 4712 | _do_main()@Z:\task_1630482044\build\tests\xpcshell\head.js:240
INFO -  PID 4712 | _execute_test()@Z:\task_1630482044\build\tests\xpcshell\head.js:597
[task 2021-09-01T08:06:32.498Z] 08:06:32     INFO -  PID 4712 | -e:1
[task 2021-09-01T08:06:32.499Z] 08:06:32     INFO -  "CONSOLE_MESSAGE: (info) 1630483292894	addons.xpi	DEBUG	List of built-in add-ons is missing or invalid.: [Exception... "Component returned failure code: 0xc1f30001 (NS_ERROR_NOT_INITIALIZED) [nsIXPCComponents_Utils.readUTF8URI]"  nsresult: "0xc1f30001 (NS_ERROR_NOT_INITIALIZED)"  location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm :: startup :: line 2448"  data: no] Stack trace: startup()@resource://gre/modules/addons/XPIProvider.jsm:2448
[task 2021-09-01T08:06:32.499Z] 08:06:32     INFO -  callProvider()@resource://gre/modules/AddonManager.jsm:222
[task 2021-09-01T08:06:32.499Z] 08:06:32     INFO -  _startProvider()@resource://gre/modules/AddonManager.jsm:582
[task 2021-09-01T08:06:32.500Z] 08:06:32     INFO -  startup()@resource://gre/modules/AddonManager.jsm:806
[task 2021-09-01T08:06:32.500Z] 08:06:32     INFO -  startup()@resource://gre/modules/AddonManager.jsm:3499
[task 2021-09-01T08:06:32.500Z] 08:06:32     INFO -  observe()@resource://gre/modules/addonManager.js:81
[task 2021-09-01T08:06:32.500Z] 08:06:32     INFO -  promiseStartupManager()@resource://testing-common/AddonTestUtils.jsm:890
[task 2021-09-01T08:06:32.501Z] 08:06:32     INFO -  test_webextension_addon_debugging_connect()@Z:/task_1630482044/build/tests/xpcshell/tests/devtools/server/tests/xpcshell/test_addon_debugging_connect.js:64
[task 2021-09-01T08:06:32.501Z] 08:06:32     INFO -  _run_next_test/<()@Z:\\task_1630482044\\build\\tests\\xpcshell\\head.js:1658
[task 2021-09-01T08:06:32.501Z] 08:06:32     INFO -  _run_next_test()@Z:\\task_1630482044\\build\\tests\\xpcshell\\head.js:1658
[task 2021-09-01T08:06:32.501Z] 08:06:32     INFO -  run()@Z:\\task_1630482044\\build\\tests\\xpcshell\\head.js:804
[task 2021-09-01T08:06:32.502Z] 08:06:32     INFO -  _do_main()@Z:\\task_1630482044\\build\\tests\\xpcshell\\head.js:240
[task 2021-09-01T08:06:32.502Z] 08:06:32     INFO -  _execute_test()@Z:\\task_1630482044\\build\\tests\\xpcshell\\head.js:597
[task 2021-09-01T08:06:32.502Z] 08:06:32     INFO -  -e:1"
[task 2021-09-01T08:06:32.502Z] 08:06:32     INFO -  PID 4712 | DBG-TEST: head_dbg.js observed a console message: 1630483292905	addons.xpi	INFO	SystemAddonLocation directory is missing
[task 2021-09-01T08:06:32.503Z] 08:06:32     INFO -  "CONSOLE_MESSAGE: (info) 1630483292905	addons.xpi	INFO	SystemAddonLocation directory is missing"
[task 2021-09-01T08:06:32.503Z] 08:06:32     INFO -  PID 4712 | DBG-TEST: head_dbg.js observed a console message: 1630483292921	addons.xpi	INFO	Removing all system add-on upgrades.
[task 2021-09-01T08:06:32.503Z] 08:06:32     INFO -  "CONSOLE_MESSAGE: (info) 1630483292921	addons.xpi	INFO	Removing all system add-on upgrades."
<...>
 INFO -  PID 4712 | [Parent 4712, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:348
[task 2021-09-01T08:06:32.551Z] 08:06:32     INFO -  PID 4712 | DBG-TEST: head_dbg.js observed a console message: 1630483293752	addons.manager	DEBUG	Starting provider: GMPProvider
[task 2021-09-01T08:06:32.551Z] 08:06:32     INFO -  "CONSOLE_MESSAGE: (info) 1630483293752	addons.manager	DEBUG	Starting provider: GMPProvider"
[task 2021-09-01T08:06:32.551Z] 08:06:32     INFO -  PID 4712 | DBG-TEST: head_dbg.js observed a console message: 1630483293755	addons.manager	DEBUG	Registering shutdown blocker for GMPProvider
[task 2021-09-01T08:06:32.551Z] 08:06:32     INFO -  "CONSOLE_MESSAGE: (info) 1630483293755	addons.manager	DEBUG	Registering shutdown blocker for GMPProvider"
[task 2021-09-01T08:06:32.552Z] 08:06:32     INFO -  PID 4712 | DBG-TEST: head_dbg.js observed a console message: 1630483293756	addons.manager	DEBUG	Provider finished startup: GMPProvider
[task 2021-09-01T08:06:32.552Z] 08:06:32     INFO -  "CONSOLE_MESSAGE: (info) 1630483293756	addons.manager	DEBUG	Provider finished startup: GMPProvider"
[task 2021-09-01T08:06:32.552Z] 08:06:32     INFO -  TEST-PASS | devtools/server/tests/xpcshell/test_addon_debugging_connect.js | test_webextension_addon_debugging_connect - [test_webextension_addon_debugging_connect : 1] 3 == 3
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.