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

Status: RESOLVED INCOMPLETE

(WebExtensions :: Untriaged, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: ncsoregi [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=264580543&repo=autoland
Full log: https://queue.taskcluster.net/v1/task/Wn_NHmDFSGmhoEDaiICFdw/runs/2/artifacts/public/logs/live_backing.log

---

[task 2019-09-02T14:24:23.275Z] 14:24:23 INFO - TEST-START | browser/components/extensions/test/browser/browser_ext_windows_remove.js
[task 2019-09-02T14:24:25.829Z] 14:24:25 INFO - GECKO(1127) | IPDL protocol error: Handler returned error code!
[task 2019-09-02T14:24:25.830Z] 14:24:25 INFO - GECKO(1127) | ###!!! [Parent][DispatchAsyncMessage] Error: PLayerTransaction::Msg_ReleaseLayer Processing error: message was deserialized, but the handler returned false (indicating failure)
[task 2019-09-02T14:24:25.830Z] 14:24:25 INFO - GECKO(1127) | IPDL protocol error: Handler returned error code!
[task 2019-09-02T14:24:25.830Z] 14:24:25 INFO - GECKO(1127) | ###!!! [Parent][DispatchAsyncMessage] Error: PLayerTransaction::Msg_ReleaseLayer Processing error: message was deserialized, but the handler returned false (indicating failure)
[task 2019-09-02T14:24:25.855Z] 14:24:25 INFO - GECKO(1127) | JavaScript error: resource://testing-common/PromiseTestUtils.jsm, line 112: uncaught exception: Object
[task 2019-09-02T14:24:25.856Z] 14:24:25 INFO - GECKO(1127) | MEMORY STAT | vsize 20976882MB | residentFast 3312MB
[task 2019-09-02T14:24:25.857Z] 14:24:25 INFO - TEST-OK | browser/components/extensions/test/browser/browser_ext_windows_remove.js | took 2587ms
[task 2019-09-02T14:24:25.940Z] 14:24:25 INFO - checking window state
[task 2019-09-02T14:24:26.054Z] 14:24:26 INFO - TEST-START | browser/components/extensions/test/browser/browser_ext_windows_size.js
[task 2019-09-02T14:30:44.138Z] 14:30:42 INFO - Buffered messages logged at 14:24:26
[task 2019-09-02T14:30:52.308Z] 14:30:50 INFO - Entering test bound testWindowCreate
[task 2019-09-02T14:30:56.682Z] 14:30:55 INFO - Extension loaded
[task 2019-09-02T14:31:03.286Z] 14:31:02 INFO - Console message: Warning: attempting to write 39398 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 2019-09-02T14:31:12.717Z] 14:31:10 INFO - Buffered messages finished
[task 2019-09-02T14:31:20.977Z] 14:31:18 ERROR - TEST-UNEXPECTED-TIMEOUT | browser/components/extensions/test/browser/browser_ext_windows_size.js | application timed out after 370 seconds with no output
[task 2019-09-02T14:31:28.617Z] 14:31:27 ERROR - Force-terminating active process(es).
[task 2019-09-02T14:31:37.824Z] 14:31:36 INFO - Determining child pids from psutil...
[task 2019-09-02T14:33:35.652Z] 14:33:34 INFO - [1262, 1294, 1314, 1202, 1187]
[task 2019-09-02T14:33:41.342Z] 14:33:40 INFO - ==> process 1127 launched child process 1151
[task 2019-09-02T14:33:46.744Z] 14:33:45 INFO - ==> process 1127 launched child process 1187
[task 2019-09-02T14:33:51.789Z] 14:33:50 INFO - ==> process 1127 launched child process 1202
[task 2019-09-02T14:33:55.331Z] 14:33:54 INFO - ==> process 1127 launched child process 1262
[task 2019-09-02T14:33:59.315Z] 14:33:57 INFO - ==> process 1127 launched child process 1294
[task 2019-09-02T14:34:04.545Z] 14:34:02 INFO - ==> process 1127 launched child process 1314
[task 2019-09-02T14:34:11.562Z] 14:34:09 INFO - ==> process 1127 launched child process 1386
[task 2019-09-02T14:34:22.477Z] 14:34:20 INFO - Found child pids: set([1314, 1187, 1386, 1294, 1202, 1262, 1151])
[task 2019-09-02T14:34:47.820Z] 14:34:47 INFO - Failed to get child procs
[task 2019-09-02T14:34:49.595Z] 14:34:48 INFO - Killing process: 1314
[task 2019-09-02T14:34:52.064Z] 14:34:51 INFO - TEST-INFO | started process screentopng
[task 2019-09-02T14:51:31.302Z] 14:51:31 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', '--total-chunks', '16', '--this-chunk', '16', '--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://queue.taskcluster.net/v1/task/IuRiBJHjSRadlZRgNVxusg/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-chunked_raw.log', '--log-errorsummary=/builds/worker/workspace/build/blobber_upload_dir/mochitest-browser-chrome-chunked_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 2019-09-02T14:51:31.316Z] 14:51:31 ERROR - timed out after 1000 seconds of no output
[task 2019-09-02T14:51:31.316Z] 14:51:31 ERROR - Return code: -15
[task 2019-09-02T14:51:31.316Z] 14:51:31 ERROR - No suite end message was emitted by this harness.
[task 2019-09-02T14:51:31.316Z] 14:51:31 INFO - TinderboxPrint: mochitest-mochitest-browser-chrome-chunked<br/>465/0/0
[task 2019-09-02T14:51:31.316Z] 14:51:31 ERROR - # TBPL FAILURE #
[task 2019-09-02T14:51:31.316Z] 14:51:31 WARNING - setting return code to 2
[task 2019-09-02T14:51:31.316Z] 14:51:31 ERROR - The mochitest suite: mochitest-browser-chrome-chunked ran with return status: FAILURE

Comment 1

[Intermittent Failures Robot]

1 failures in 4408 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1

Platform breakdown:

• linux64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1578258&startday=2019-09-02&endday=2019-09-08&tree=all

Comment 2

[Intermittent Failures Robot]

1 failures in 4637 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1

Platform breakdown:

• linux64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1578258&startday=2019-09-09&endday=2019-09-15&tree=all

Comment 3

[BugBot [:suhaib / :marco]]

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