Closed Bug 1740803 Opened 3 years ago Closed 3 years ago

Intermittent TEST-UNEXPECTED-TIMEOUT | /html/webappapis/dynamic-markup-insertion/opening-the-input-stream/history.window.html | TestRunner hit external timeout (this may indicate a hang)

Categories

(Core :: DOM: Core & HTML, defect, P5)

Product:
Core
Component:
DOM: Core & HTML
Type:
defect

Tracking

()

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

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

[task 2021-11-11T20:51:23.373Z] 20:51:23     INFO - TEST-START | /largest-contentful-paint/first-letter-background.html
[task 2021-11-11T20:51:23.622Z] 20:51:23     INFO - Closing window 10637fe2-7e73-49c4-8471-eba973613e4b
[task 2021-11-11T20:51:30.284Z] 20:51:30     INFO - Got timeout in harness
[task 2021-11-11T20:51:30.748Z] 20:51:30     INFO - TEST-UNEXPECTED-TIMEOUT | /html/webappapis/dynamic-markup-insertion/opening-the-input-stream/history.window.html | TestRunner hit external timeout (this may indicate a hang)
[task 2021-11-11T20:51:30.751Z] 20:51:30     INFO - TEST-INFO took 25440ms
[task 2021-11-11T20:51:31.903Z] 20:51:31     INFO - .
[task 2021-11-11T20:51:31.903Z] 20:51:31     INFO - TEST-OK | /largest-contentful-paint/first-letter-background.html | took 8585ms
[task 2021-11-11T20:51:32.004Z] 20:51:31     INFO - TEST-START | /largest-contentful-paint/first-paint-equals-lcp-text.html
[task 2021-11-11T20:51:32.167Z] 20:51:32     INFO - Closing window 7499730f-651b-4c99-8438-1a1e765d3925
[task 2021-11-11T20:51:38.184Z] 20:51:38     INFO - .
[task 2021-11-11T20:51:38.184Z] 20:51:38     INFO - TEST-OK | /largest-contentful-paint/first-paint-equals-lcp-text.html | took 6247ms
[task 2021-11-11T20:51:38.198Z] 20:51:38     INFO - TEST-START | /largest-contentful-paint/idlharness.html
[task 2021-11-11T20:51:38.396Z] 20:51:38     INFO - Closing window f05012e2-c069-44bf-9c52-a5f1f432e86e
[task 2021-11-11T20:51:53.470Z] 20:51:53     INFO - 
[task 2021-11-11T20:51:53.470Z] 20:51:53     INFO - TEST-UNEXPECTED-TIMEOUT | /largest-contentful-paint/idlharness.html | idl_test setup - Test timed out
[task 2021-11-11T20:51:53.470Z] 20:51:53     INFO - TEST-INFO | expected FAIL
[task 2021-11-11T20:51:53.490Z] 20:51:53     INFO - TEST-UNEXPECTED-TIMEOUT | /largest-contentful-paint/idlharness.html | expected OK
[task 2021-11-11T20:51:53.495Z] 20:51:53     INFO - TEST-INFO took 15292ms
[task 2021-11-11T20:51:53.638Z] 20:51:53     INFO - PID 2536 | 1636663913587	Marionette	INFO	Stopped listening on port 63267
[task 2021-11-11T20:52:00.899Z] 20:52:00     INFO - PID 2536 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-11-11T20:52:02.163Z] 20:52:02     INFO - Browser exited with return code 0
[task 2021-11-11T20:52:02.186Z] 20:52:02     INFO - Closing logging queue
[task 2021-11-11T20:52:02.186Z] 20:52:02     INFO - queue closed
[task 2021-11-11T20:52:03.216Z] 20:52:03     INFO - Application command: Z:\task_163665931900537\build\application\firefox\firefox.exe -marionette about:blank --wait-for-browser -profile C:\Users\task_163665931900537\AppData\Local\Temp\tmpd__h5_09
[task 2021-11-11T20:52:03.368Z] 20:52:03     INFO - Starting runner
[task 2021-11-11T20:52:13.567Z] 20:52:13     INFO - PID 8608 | 1636663933534	Marionette	INFO	Marionette enabled
[task 2021-11-11T20:52:36.397Z] 20:52:36     INFO - PID 8608 | [Parent 5156, IPC I/O Parent] WARNING: file /builds/worker/checkouts/gecko/ipc/chromium/src/base/process_util_win.cc:167
[task 2021-11-11T20:52:45.090Z] 20:52:45     INFO - Browser exited with return code 572
[task 2021-11-11T20:52:51.612Z] 20:52:51     INFO - PID 8608 | console.warn: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open the file at C:\\Users\\task_163665931900537\\AppData\\Local\\Temp\\tmpd__h5_09\\search.json.mozlz4", (void 0)))
[task 2021-11-11T20:52:55.362Z] 20:52:55  WARNING - Forcibly terminating runner process
[task 2021-11-11T20:53:00.508Z] 20:53:00     INFO - Application command: Z:\task_163665931900537\build\application\firefox\firefox.exe -marionette about:blank --wait-for-browser -profile C:\Users\task_163665931900537\AppData\Local\Temp\tmpw_71dht_
[task 2021-11-11T20:53:00.835Z] 20:53:00     INFO - Starting runner
[task 2021-11-11T20:53:14.347Z] 20:53:14     INFO - Browser exited with return code 572
[task 2021-11-11T20:53:14.412Z] 20:53:14     INFO - PID 3844 | 1636663994384	Marionette	INFO	Marionette enabled
[task 2021-11-11T20:53:24.367Z] 20:53:24  WARNING - Forcibly terminating runner process
[task 2021-11-11T20:53:25.147Z] 20:53:25     INFO - Application command: Z:\task_163665931900537\build\application\firefox\firefox.exe -marionette about:blank --wait-for-browser -profile C:\Users\task_163665931900537\AppData\Local\Temp\tmpvmsyslyo
[task 2021-11-11T20:53:25.541Z] 20:53:25     INFO - Starting runner
[task 2021-11-11T20:53:32.987Z] 20:53:32     INFO - PID 3844 | JavaScript error: resource://gre/modules/XULStore.jsm, line 66: Error: Can't find profile directory.
[task 2021-11-11T20:53:39.140Z] 20:53:39     INFO - PID 6976 | 1636664019119	Marionette	INFO	Marionette enabled
[task 2021-11-11T20:53:47.357Z] 20:53:47     INFO - PID 3844 | console.warn: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open the file at C:\\Users\\task_163665931900537\\AppData\\Local\\Temp\\tmpw_71dht_\\search.json.mozlz4", (void 0)))
[task 2021-11-11T20:54:02.669Z] 20:54:02     INFO - PID 6976 | [Parent 7996, IPC I/O Parent] WARNING: file /builds/worker/checkouts/gecko/ipc/chromium/src/base/process_util_win.cc:167
[task 2021-11-11T20:54:05.691Z] 20:54:05     INFO - STDERR: Exception in thread 
[task 2021-11-11T20:54:05.771Z] 20:54:05     INFO - STDERR: Thread-1540
[task 2021-11-11T20:54:05.806Z] 20:54:05     INFO - STDERR: :
[task 2021-11-11T20:54:05.861Z] 20:54:05     INFO - STDERR: Traceback (most recent call last):
[task 2021-11-11T20:54:05.897Z] 20:54:05     INFO - STDERR:   File "c:\mozilla-build\python3\lib\threading.py", line 954, in _bootstrap_inner
[task 2021-11-11T20:54:05.927Z] 20:54:05     INFO - STDERR:     
[task 2021-11-11T20:54:05.965Z] 20:54:05     INFO - STDERR: self.run()
[task 2021-11-11T20:54:05.990Z] 20:54:05     INFO - STDERR:   File "c:\mozilla-build\python3\lib\threading.py", line 1266, in run
[task 2021-11-11T20:54:06.539Z] 20:54:06     INFO - STDERR:     
[task 2021-11-11T20:54:06.584Z] 20:54:06     INFO - STDERR: self.function(*self.args, **self.kwargs)
[task 2021-11-11T20:54:06.595Z] 20:54:06     INFO - STDERR:   File "Z:\task_163665931900537\build\tests\web-platform\tests\tools\wptrunner\wptrunner\testrunner.py", line 230, in init_timeout
[task 2021-11-11T20:54:06.632Z] 20:54:06     INFO - STDERR:     
[task 2021-11-11T20:54:06.643Z] 20:54:06     INFO - STDERR: self.send_message("init_failed")
[task 2021-11-11T20:54:06.761Z] 20:54:06     INFO - STDERR:   File "Z:\task_163665931900537\build\tests\web-platform\tests\tools\wptrunner\wptrunner\testrunner.py", line 224, in send_message
[task 2021-11-11T20:54:06.884Z] 20:54:06     INFO - STDERR:     
[task 2021-11-11T20:54:06.935Z] 20:54:06     INFO - STDERR: self.command_queue.put((command, args))
[task 2021-11-11T20:54:06.988Z] 20:54:06     INFO - STDERR:   File "c:\mozilla-build\python3\lib\multiprocessing\queues.py", line 88, in put
[task 2021-11-11T20:54:06.996Z] 20:54:06     INFO - STDERR:     
[task 2021-11-11T20:54:07.003Z] 20:54:07     INFO - STDERR: raise ValueError(f"Queue {self!r} is closed")
[task 2021-11-11T20:54:07.007Z] 20:54:07     INFO - STDERR: ValueError
[task 2021-11-11T20:54:07.013Z] 20:54:07     INFO - STDERR: : 
[task 2021-11-11T20:54:07.016Z] 20:54:07     INFO - STDERR: Queue <multiprocessing.queues.Queue object at 0x000001ADED6ECD90> is closed
[task 2021-11-11T20:54:14.123Z] 20:54:14     INFO - PID 6976 | console.warn: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open the file at C:\\Users\\task_163665931900537\\AppData\\Local\\Temp\\tmpvmsyslyo\\search.json.mozlz4", (void 0)))
[task 2021-11-11T20:54:15.854Z] 20:54:15     INFO - PID 3844 | 1636664055827	Marionette	INFO	Listening on port 63482
[task 2021-11-11T20:54:20.839Z] 20:54:20     INFO - TEST-START | /html/webappapis/dynamic-markup-insertion/opening-the-input-stream/ignore-opens-during-unload.window.html

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

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.