Closed Bug 1411366 Opened 7 years ago Closed 7 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
Status: NEW → RESOLVED
Closed: 7 years ago
Resolution: --- → INCOMPLETE
Status: REOPENED → RESOLVED
Closed: 7 years ago7 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 7 years ago7 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.