Closed Bug 1678086 Opened 5 years ago Closed 5 years ago

Intermittent TEST-UNEXPECTED-TIMEOUT | http://mochi.test:8888/tests/toolkit/components/extensions/test/mochitest/test_ext_listener_proxies.html | application timed out after 370 seconds with no output

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: apavel [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=322192914&repo=mozilla-central
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/CuZqLuhuSKWf9lDCVe9fRw/runs/0/artifacts/public/logs/live_backing.log

[task 2020-11-18T16:50:57.080Z] 16:50:57     INFO - TEST-START | http://mochi.test:8888/tests/toolkit/components/extensions/test/mochitest/test_ext_listener_proxies.html
[task 2020-11-18T16:50:57.161Z] 16:50:57     INFO - GECKO(12656) | [Child 14330, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3359
[task 2020-11-18T16:50:57.197Z] 16:50:57     INFO - GECKO(12656) | [Child 14330, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4336
[task 2020-11-18T16:50:57.374Z] 16:50:57     INFO - GECKO(12656) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmp3kBHNi.mozrunner/runtests_leaks_tab_pid14431.log
[task 2020-11-18T16:50:57.375Z] 16:50:57     INFO - GECKO(12656) | [14431, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:251
[task 2020-11-18T16:50:57.471Z] 16:50:57     INFO - GECKO(12656) | [Child 14431, Main Thread] WARNING: could not set real-time limit in CubebUtils::InitLibrary: file /builds/worker/checkouts/gecko/dom/media/CubebUtils.cpp:629
[task 2020-11-18T16:50:57.587Z] 16:50:57     INFO - GECKO(12656) | Console message: Warning: attempting to write 5963 bytes to preference extensions.webextensions.uuids. This is bad for general performance and memory usage. Such an amount of data should rather be written to an external file. This preference will not be sent to any content processes.
[task 2020-11-18T16:50:57.648Z] 16:50:57     INFO - GECKO(12656) | [Parent 12656, Main Thread] WARNING: Need BrowserChild to get the nativeWindow from!: file /builds/worker/checkouts/gecko/widget/PuppetWidget.cpp:1040
[task 2020-11-18T16:50:57.648Z] 16:50:57     INFO - GECKO(12656) | [Child 12775, Main Thread] WARNING: Fallback to BasicLayerManager: file /builds/worker/checkouts/gecko/dom/ipc/BrowserChild.cpp:2715

[task 2020-11-18T16:56:15.207Z] 16:56:15     INFO - GECKO(12656) | Console message: [JavaScript Error: "SecurityError: Permission denied to access property "wrappedJSObject" on cross-origin object" {file: "http://mochi.xorigin-test:8888/tests/SimpleTest/TestRunner.js" line: 162}]
[task 2020-11-18T16:56:15.207Z] 16:56:15     INFO - GECKO(12656) | TestRunner._checkForHangs@http://mochi.xorigin-test:8888/tests/SimpleTest/TestRunner.js:162:10
[task 2020-11-18T16:57:36.546Z] 16:57:36     INFO - GECKO(12656) | 1605718656539	addons.xpi	ERROR	System addon update list error Error: got node name: html, expected: updates
[task 2020-11-18T16:57:36.547Z] 16:57:36     INFO - GECKO(12656) | Console message: [JavaScript Error: "1605718656539	addons.xpi	ERROR	System addon update list error Error: got node name: html, expected: updates" {file: "resource://gre/modules/Log.jsm" line: 723}]
[task 2020-11-18T16:57:36.548Z] 16:57:36     INFO - GECKO(12656) | append@resource://gre/modules/Log.jsm:723:12
[task 2020-11-18T16:57:36.548Z] 16:57:36     INFO - GECKO(12656) | log@resource://gre/modules/Log.jsm:379:16
[task 2020-11-18T16:57:36.549Z] 16:57:36     INFO - GECKO(12656) | error@resource://gre/modules/Log.jsm:387:10
[task 2020-11-18T16:57:36.549Z] 16:57:36     INFO - GECKO(12656) | updateSystemAddons/res<@resource://gre/modules/addons/XPIInstall.jsm:4015:25
[task 2020-11-18T16:59:09.941Z] 16:59:09     INFO - GECKO(12656) | [Parent 12656, Main Thread] WARNING: 'NS_FAILED(aRv)', file /builds/worker/checkouts/gecko/netwerk/ipc/NeckoParent.cpp:914
[task 2020-11-18T16:59:09.941Z] 16:59:09     INFO - GECKO(12656) | [Parent 12656, Main Thread] WARNING: 'NS_FAILED(aRv)', file /builds/worker/checkouts/gecko/netwerk/ipc/NeckoParent.cpp:914
[task 2020-11-18T16:59:09.949Z] 16:59:09     INFO - GECKO(12656) | [Child 12830, Main Thread] WARNING: NS_ENSURE_TRUE(mRequest) failed: file /builds/worker/checkouts/gecko/netwerk/base/nsBaseChannel.cpp:928
[task 2020-11-18T16:59:09.949Z] 16:59:09     INFO - GECKO(12656) | [Child 12830, Main Thread] WARNING: NS_ENSURE_TRUE(mRequest) failed: file /builds/worker/checkouts/gecko/netwerk/base/nsBaseChannel.cpp:928
[task 2020-11-18T17:05:19.969Z] 17:05:19     INFO - Buffered messages logged at 16:50:57
[task 2020-11-18T17:05:19.970Z] 17:05:19     INFO - add_task | Entering test test_listener_proxies
[task 2020-11-18T17:05:19.970Z] 17:05:19     INFO - Extension loaded
[task 2020-11-18T17:05:19.971Z] 17:05:19     INFO - Buffered messages finished
[task 2020-11-18T17:05:19.972Z] 17:05:19    ERROR - TEST-UNEXPECTED-TIMEOUT | http://mochi.test:8888/tests/toolkit/components/extensions/test/mochitest/test_ext_listener_proxies.html | application timed out after 370 seconds with no output
[task 2020-11-18T17:05:19.972Z] 17:05:19    ERROR - Force-terminating active process(es).
[task 2020-11-18T17:05:19.973Z] 17:05:19     INFO - Determining child pids from psutil...
[task 2020-11-18T17:05:20.001Z] 17:05:20     INFO - [14356, 14377, 14404, 14068, 12755, 12830, 12856, 14431, 12865, 12898, 12923, 12960, 12998, 13022, 13051, 13081, 13110, 13142, 12775, 13172, 13202, 13806, 13227, 13261, 13288, 13317, 13345, 13374, 13496, 13403, 13435, 13464, 14293, 13524, 13573, 13608, 13640, 13671, 13697, 13550, 13723, 13749, 13777, 13907, 13828, 13853, 13881, 13933, 13959, 14017, 14042, 14094, 14126, 14156, 14185, 14215, 14240, 14266, 13993, 14330]
[task 2020-11-18T17:05:20.001Z] 17:05:20     INFO - ==> process 12656 launched child process 12680
[task 2020-11-18T17:05:20.002Z] 17:05:20     INFO - ==> process 12656 launched child process 12755
[task 2020-11-18T17:05:20.002Z] 17:05:20     INFO - ==> process 12656 launched child process 12775
[task 2020-11-18T17:05:20.004Z] 17:05:20     INFO - ==> process 12656 launched child process 12830
[task 2020-11-18T17:05:20.004Z] 17:05:20     INFO - ==> process 12656 launched child process 12856
[task 2020-11-18T17:05:20.004Z] 17:05:20     INFO - ==> process 12656 launched child process 12865
[task 2020-11-18T17:05:20.004Z] 17:05:20     INFO - ==> process 12656 launched child process 12898
[task 2020-11-18T17:05:20.006Z] 17:05:20     INFO - ==> process 12656 launched child process 12923
[task 2020-11-18T17:05:20.006Z] 17:05:20     INFO - ==> process 12656 launched child process 12960
[task 2020-11-18T17:05:20.006Z] 17:05:20     INFO - ==> process 12656 launched child process 12998
[task 2020-11-18T17:05:20.006Z] 17:05:20     INFO - ==> process 12656 launched child process 13022
[task 2020-11-18T17:05:20.006Z] 17:05:20     INFO - ==> process 12656 launched child process 13051
[task 2020-11-18T17:05:20.006Z] 17:05:20     INFO - ==> process 12656 launched child process 13081
[task 2020-11-18T17:05:20.006Z] 17:05:20     INFO - ==> process 12656 launched child process 13110
[task 2020-11-18T17:05:20.006Z] 17:05:20     INFO - ==> process 12656 launched child process 13142
[task 2020-11-18T17:05:20.006Z] 17:05:20     INFO - ==> process 12656 launched child process 13172
[task 2020-11-18T17:05:20.006Z] 17:05:20     INFO - ==> process 12656 launched child process 13202
[task 2020-11-18T17:05:20.006Z] 17:05:20     INFO - ==> process 12656 launched child process 13227
[task 2020-11-18T17:05:20.006Z] 17:05:20     INFO - ==> process 12656 launched child process 13261
[task 2020-11-18T17:05:20.006Z] 17:05:20     INFO - ==> process 12656 launched child process 13288

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

Status: NEW → RESOLVED
Closed: 5 years ago
Resolution: --- → INCOMPLETE

Recent failure log: https://treeherder.mozilla.org/logviewer?job_id=327231290&repo=mozilla-central&lineNumber=14972

Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---

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

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