Closed Bug 1699309 Opened 4 years ago Closed 4 years ago

Intermittent TEST-UNEXPECTED-TIMEOUT | browser/components/extensions/test/browser/browser_ext_contextMenus_commands.js | application timed out after 370 seconds with no output

Categories

(WebExtensions :: Untriaged, defect, P5)

Product:
WebExtensions
Component:
Untriaged
Type:
defect

Tracking

(Not tracked)

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

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

[task 2021-03-17T21:57:00.137Z] 21:57:00     INFO - TEST-START | browser/components/extensions/test/browser/browser_ext_contextMenus_commands.js
[task 2021-03-17T21:57:18.983Z] 21:57:18     INFO - GECKO(1561) | 1616018238694	addons.xpi	ERROR	System addon update list error Error: got node name: html, expected: updates
[task 2021-03-17T22:04:35.569Z] 22:04:34     INFO - Buffered messages logged at 21:57:00
[task 2021-03-17T22:04:38.606Z] 22:04:37     INFO - Entering test bound 
[task 2021-03-17T22:04:43.792Z] 22:04:43     INFO - Extension loaded
[task 2021-03-17T22:04:46.165Z] 22:04:45     INFO - Console message: Warning: attempting to write 9839 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 2021-03-17T22:04:51.215Z] 22:04:49     INFO - Console message: Warning: attempting to write 7242 bytes to preference browser.uiCustomization.state. 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 2021-03-17T22:04:53.472Z] 22:04:51     INFO - Buffered messages logged at 21:57:01
[task 2021-03-17T22:04:55.204Z] 22:04:53     INFO - Console message: [JavaScript Warning: "Password fields present on an insecure (http://) page. This is a security risk that allows user login credentials to be stolen." {file: "http://mochi.test:8888/browser/browser/components/extensions/test/browser/context.html?test=commands" line: 0 column: 0 source: "0"}]
[task 2021-03-17T22:04:57.361Z] 22:04:56     INFO - Buffered messages logged at 21:57:22
[task 2021-03-17T22:05:00.503Z] 22:04:59     INFO - Console message: [JavaScript Error: "1616018238694	addons.xpi	ERROR	System addon update list error Error: got node name: html, expected: updates" {file: "resource://gre/modules/Log.jsm" line: 723}]
[task 2021-03-17T22:05:02.589Z] 22:05:00     INFO - append@resource://gre/modules/Log.jsm:723:12
[task 2021-03-17T22:05:04.475Z] 22:05:01     INFO - log@resource://gre/modules/Log.jsm:379:16
[task 2021-03-17T22:05:05.960Z] 22:05:02     INFO - error@resource://gre/modules/Log.jsm:387:10
[task 2021-03-17T22:05:07.838Z] 22:05:03     INFO - updateSystemAddons/res<@resource://gre/modules/addons/XPIInstall.jsm:4016:25
[task 2021-03-17T22:05:08.822Z] 22:05:04     INFO - 
[task 2021-03-17T22:05:09.987Z] 22:05:05     INFO - Buffered messages logged at 21:57:27
[task 2021-03-17T22:05:11.562Z] 22:05:07     INFO - TEST-PASS | browser/components/extensions/test/browser/browser_ext_contextMenus_commands.js | exactly one menu item found - 
[task 2021-03-17T22:05:13.502Z] 22:05:10     INFO - Buffered messages logged at 21:58:16
[task 2021-03-17T22:05:16.483Z] 22:05:15     INFO - TEST-PASS | browser/components/extensions/test/browser/browser_ext_contextMenus_commands.js | _execute_sidebar_action worked - 
[task 2021-03-17T22:05:17.896Z] 22:05:16     INFO - Buffered messages logged at 21:58:21
[task 2021-03-17T22:05:19.554Z] 22:05:17     INFO - TEST-PASS | browser/components/extensions/test/browser/browser_ext_contextMenus_commands.js | exactly one menu item found - 
[task 2021-03-17T22:05:21.053Z] 22:05:18     INFO - Buffered messages finished
[task 2021-03-17T22:05:22.040Z] 22:05:19    ERROR - TEST-UNEXPECTED-TIMEOUT | browser/components/extensions/test/browser/browser_ext_contextMenus_commands.js | application timed out after 370 seconds with no output
[task 2021-03-17T22:05:24.038Z] 22:05:20    ERROR - Force-terminating active process(es).
[task 2021-03-17T22:05:25.506Z] 22:05:22     INFO - Determining child pids from psutil...
[task 2021-03-17T22:05:29.060Z] 22:05:28     INFO - [1626, 1708, 1650, 1742, 1774, 1816, 1843]
[task 2021-03-17T22:05:30.907Z] 22:05:29     INFO - ==> process 1561 launched child process 1576
[task 2021-03-17T22:05:32.996Z] 22:05:32     INFO - ==> process 1561 launched child process 1626
[task 2021-03-17T22:05:35.555Z] 22:05:34     INFO - ==> process 1561 launched child process 1650
[task 2021-03-17T22:05:38.689Z] 22:05:37     INFO - ==> process 1561 launched child process 1708
[task 2021-03-17T22:05:40.740Z] 22:05:40     INFO - ==> process 1561 launched child process 1742
[task 2021-03-17T22:05:42.682Z] 22:05:41     INFO - ==> process 1561 launched child process 1774
[task 2021-03-17T22:05:46.605Z] 22:05:45     INFO - ==> process 1561 launched child process 1816
[task 2021-03-17T22:05:48.195Z] 22:05:47     INFO - ==> process 1561 launched child process 1843
[task 2021-03-17T22:05:50.119Z] 22:05:48     INFO - Found child pids: set([1576, 1708, 1774, 1650, 1843, 1742, 1816, 1626])
[task 2021-03-17T22:05:53.686Z] 22:05:53     INFO - Failed to get child procs
[task 2021-03-17T22:05:55.626Z] 22:05:53     INFO - Killing process: 1576
[task 2021-03-17T22:05:58.506Z] 22:05:57     INFO - TEST-INFO | started process screentopng
[task 2021-03-17T22:22:37.988Z] 22:22:37     INFO - Automation Error: mozprocess timed out after 1000 seconds running ['/builds/worker/workspace/build/venv/bin/python', '-u', '/builds/worker/workspace/build/tests/mochitest/runtests.py', u'browser/components/extensions/test/browser/browser.ini', '--setpref=media.peerconnection.mtransport_process=false', '--setpref=network.process.enabled=false', '--appname=/builds/worker/workspace/build/application/firefox/firefox', '--utility-path=tests/bin', '--extra-profile-file=tests/bin/plugins', u'--symbols-path=https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/I_sI0Zh1TUOURJUdQRrPTA/artifacts/public/build/target.crashreporter-symbols.zip', '--certificate-path=tests/certs', '--setpref=webgl.force-enabled=true', '--quiet', '--log-raw=/builds/worker/workspace/build/blobber_upload_dir/mochitest-browser-chrome_raw.log', '--log-errorsummary=/builds/worker/workspace/build/blobber_upload_dir/mochitest-browser-chrome_errorsummary.log', '--use-test-media-devices', '--screenshot-on-fail', '--cleanup-crashes', '--marionette-startup-timeout=180', '--sandbox-read-whitelist=/builds/worker/workspace/build', '--log-raw=-', '--flavor=browser', '--chunk-by-runtime']
[task 2021-03-17T22:22:38.004Z] 22:22:37    ERROR - timed out after 1000 seconds of no output
[task 2021-03-17T22:22:38.004Z] 22:22:37    ERROR - Return code: -15
[task 2021-03-17T22:22:38.004Z] 22:22:37    ERROR - No suite end message was emitted by this harness.
[task 2021-03-17T22:22:38.004Z] 22:22:37     INFO - TinderboxPrint: mochitest-mochitest-browser-chrome<br/>51/0/0
[task 2021-03-17T22:22:38.004Z] 22:22:37    ERROR - # TBPL FAILURE #
[task 2021-03-17T22:22:38.004Z] 22:22:37  WARNING - setting return code to 2
[task 2021-03-17T22:22:38.004Z] 22:22:37    ERROR - The mochitest suite: mochitest-browser-chrome ran with return status: FAILURE
[task 2021-03-17T22:22:38.004Z] 22:22:37     INFO - Running post-action listener: _package_coverage_data
[task 2021-03-17T22:22:38.004Z] 22:22:37     INFO - Running post-action listener: _resource_record_post_action
[task 2021-03-17T22:22:38.004Z] 22:22:37     INFO - Running post-action listener: process_java_coverage_data
[task 2021-03-17T22:22:38.004Z] 22:22:37     INFO - [mozharness: 2021-03-17 22:22:37.961582Z] Finished run-tests step (success)```

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

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.