Closed Bug 1411366 Opened 7 years ago Closed 6 years ago

Intermittent TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/element_retrieval/get_active_element.py | expected OK

Categories

(Remote Protocol :: Marionette, defect, P5)

Version 3
x86_64
Linux
defect

Tracking

(Not tracked)

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

References

(Depends on 1 open bug)

Details

(Keywords: intermittent-failure)

Filed by: archaeopteryx [at] coole-files.de

https://treeherder.mozilla.org/logviewer.html#?job_id=139244700&repo=autoland

https://queue.taskcluster.net/v1/task/RyhEx9krS5CeDs3FeJ351Q/runs/0/artifacts/public/logs/live_backing.log

[task 2017-10-24T17:42:53.136Z] 17:42:53     INFO - PID 1157 | 1508866973129	mozrunner::runner	INFO	Running command: "/usr/bin/firefox" "-marionette" "-profile" "/tmp/rust_mozprofile.q09LihpvHibs"
[task 2017-10-24T17:42:53.387Z] 17:42:53     INFO - PID 1157 | 1508866973376	addons.xpi	WARN	Error parsing extensions state: [Exception... "Component returned failure code: 0x80520012 (NS_ERROR_FILE_NOT_FOUND) [amIAddonManagerStartup.readStartupData]"  nsresult: "0x80520012 (NS_ERROR_FILE_NOT_FOUND)"  location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm :: loadExtensionState :: line 1554"  data: no] Stack trace: loadExtensionState()@resource://gre/modules/addons/XPIProvider.jsm:1554 < getInstallState()@resource://gre/modules/addons/XPIProvider.jsm:1589 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3109 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2188 < callProvider()@resource://gre/modules/AddonManager.jsm:269 < _startProvider()@resource://gre/modules/AddonManager.jsm:739 < startup()@resource://gre/modules/AddonManager.jsm:906 < startup()@resource://gre/modules/AddonManager.jsm:3090 < observe()@jar:file:///usr/lib/firefox/omni.ja!/components/addonManager.js:65
[task 2017-10-24T17:42:55.423Z] 17:42:55     INFO - PID 1157 | 1508866975416	Marionette	INFO	Enabled via --marionette
[task 2017-10-24T17:43:04.006Z] 17:43:04     INFO - PID 1157 | 1508866984002	Marionette	INFO	Listening on port 2828
[task 2017-10-24T17:43:04.131Z] 17:43:04     INFO - PID 1157 | 1508866984126	Marionette	DEBUG	Register listener.js for window 2147483649
[task 2017-10-24T17:43:04.171Z] 17:43:04     INFO - PID 1157 | 1508866984153	Marionette	DEBUG	Received DOM event "beforeunload" for "about:blank"
[task 2017-10-24T17:43:04.214Z] 17:43:04     INFO - PID 1157 | 1508866984208	Marionette	DEBUG	Received DOM event "beforeunload" for "about:blank"
[task 2017-10-24T17:43:04.215Z] 17:43:04     INFO - PID 1157 | 1508866984210	Marionette	DEBUG	Received DOM event "pagehide" for "about:blank"
[task 2017-10-24T17:43:04.215Z] 17:43:04     INFO - PID 1157 | 1508866984210	Marionette	DEBUG	Received DOM event "unload" for "about:blank"
[task 2017-10-24T17:43:04.251Z] 17:43:04     INFO - PID 1157 | 1508866984247	Marionette	DEBUG	Received DOM event "DOMContentLoaded" for "about:neterror?e=dnsNotFound&u=http%3A//web-platform.test%3A8000/webdriver/tests/support/inline.py%3Fdoc%3D%253Cbody%253E%253Cp%253EHello%252C+World%2521%253C%252Fp%253E%253C%252Fbody%253E%26content-type%3Dtext%252Fhtml%253Bcharset%253Dutf-8&c=UTF-8&f=regular&d=Firefox%20can%E2%80%99t%20find%20the%20server%20at%20web-platform.test."
[task 2017-10-24T17:43:05.456Z] 17:43:05     INFO - TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/element_retrieval/get_active_element.py | expected OK
jgraham: This looks like wptserve is failing to respond.
OS: Unspecified → Linux
Hardware: Unspecified → x86_64
https://wiki.mozilla.org/Bugmasters#Intermittent_Test_Failure_Cleanup
Status: NEW → RESOLVED
Closed: 7 years ago
Resolution: --- → INCOMPLETE
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
Status: REOPENED → RESOLVED
Closed: 7 years ago6 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
Status: REOPENED → RESOLVED
Closed: 6 years ago6 years ago
Resolution: --- → INCOMPLETE
Recent failure log: https://treeherder.mozilla.org/logviewer.html#?job_id=170466964&repo=autoland&lineNumber=35132


[task 2018-03-27T06:16:50.702Z] 06:16:50     INFO - PID 1860 |  => Process ID: 2174, Thread ID: 4147517184
[task 2018-03-27T06:16:50.718Z] 06:16:50     INFO - PID 1860 | 1522131410706	geckodriver::marionette	ERROR	Failed to stop browser process
[task 2018-03-27T06:16:50.722Z] 06:16:50     INFO - PID 1860 | 1522131410714	webdriver::server	DEBUG	<- 200 OK {"value": {}}
[task 2018-03-27T06:16:50.723Z] 06:16:50     INFO - STDOUT: tests/web-platform/tests/webdriver/tests/element_retrieval/get_active_element.py::test_handle_prompt_missing_value 
[task 2018-03-27T06:16:50.724Z] 06:16:50     INFO - PID 1860 | 1522131410717	webdriver::server	DEBUG	-> POST /session {"capabilities": {"alwaysMatch": {"moz:firefoxOptions": {"binary": "/builds/worker/workspace/build/application/firefox/firefox", "prefs": {"network.dns.localDomains": "web-platform.test,www.web-platform.test,www1.web-platform.test,www2.web-platform.test,xn--n8j6ds53lwwkrqhv28a.web-platform.test,xn--lve-6lad.web-platform.test"}}}}}
[task 2018-03-27T06:16:50.732Z] 06:16:50     INFO - PID 1860 | 1522131410723	mozrunner::runner	INFO	Running command: "/builds/worker/workspace/build/application/firefox/firefox" "-marionette" "-profile" "/tmp/rust_mozprofile.blTf7WleXvnn"
[task 2018-03-27T06:16:50.732Z] 06:16:50     INFO - PID 1860 | 1522131410726	geckodriver::marionette	DEBUG	Waiting 60s to connect to browser
[task 2018-03-27T06:16:52.555Z] 06:16:52     INFO - TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/element_retrieval/get_active_element.py | expected OK
[task 2018-03-27T06:16:52.555Z] 06:16:52     INFO - TEST-INFO took 80006ms
[task 2018-03-27T06:16:52.625Z] 06:16:52  WARNING - u'runner_teardown': ()
[task 2018-03-27T06:16:52.627Z] 06:16:52     INFO - Starting runner
[task 2018-03-27T06:16:52.667Z] 06:16:52     INFO - PID 2336 | 1522131412658	geckodriver	INFO	geckodriver 0.20.0 ( '849020c741de'  '2018-03-22 13:16 +0100')
[task 2018-03-27T06:16:52.668Z] 06:16:52     INFO - PID 2336 | 1522131412658	webdriver::httpapi	DEBUG	Creating routes
[task 2018-03-27T06:16:52.673Z] 06:16:52     INFO - PID 2336 | 1522131412665	geckodriver	INFO	Listening on 127.0.0.1:4444
[task 2018-03-27T06:16:53.163Z] 06:16:53     INFO - WebDriver HTTP server listening at http://127.0.0.1:4444/
[task 2018-03-27T06:16:53.165Z] 06:16:53     INFO - TEST-START | /webdriver/tests/element_send_keys/form_controls.py
[task 2018-03-27T06:16:53.478Z] 06:16:53     INFO - STDOUT: ============================= test session starts ==============================
[task 2018-03-27T06:16:53.480Z] 06:16:53     INFO - STDOUT: platform linux2 -- Python 2.7.12, pytest-unknown, py-1.5.2, pluggy-0.5.3.dev -- /builds/worker/workspace/build/venv/bin/python
[task 2018-03-27T06:16:53.480Z] 06:16:53     INFO - STDOUT: rootdir: /builds/worker/workspace/build, inifile:
[task 2018-03-27T06:16:53.480Z] 06:16:53     INFO - STDOUT: collecting ... 
[task 2018-03-27T06:16:53.520Z] 06:16:53     INFO - STDOUT: collected 8 items
[task 2018-03-27T06:16:53.521Z] 06:16:53     INFO - STDOUT: tests/web-platform/tests/webdriver/tests/element_send_keys/form_controls.py::test_input
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Component: web-platform-tests → geckodriver
There seem to be delays during startup of Firefox as caused by bug 1451621.
Depends on: 1451621
Moving bug to Remote Protocol::Marionette component per bug 1815831.
Component: geckodriver → Marionette
Product: Testing → Remote Protocol
You need to log in before you can comment on or make changes to this bug.