Closed Bug 1489472 Opened 6 years ago Closed 4 years ago

Intermittent TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/new_session/platform_name.py | expected OK

Categories

(Remote Protocol :: Marionette, defect, P5)

Version 3
defect

Tracking

(Not tracked)

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: intermittent-failure)

Filed by: rgurzau [at] mozilla.com

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

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

04:03:31     INFO - TEST-START | /webdriver/tests/new_session/platform_name.py
04:03:31     INFO - STDOUT: ============================= test session starts ==============================
04:03:31     INFO - STDOUT: platform darwin -- Python 2.7.10, pytest-unknown, py-1.5.2, pluggy-0.5.3.dev -- /Users/cltbld/tasks/task_1536317376/build/venv/bin/python
04:03:31     INFO - STDOUT: rootdir: /Users/cltbld/tasks/task_1536317376/build, inifile:
04:03:31     INFO - STDOUT: collecting ... 
04:03:31     INFO - STDOUT: collected 1 item
04:03:31     INFO - STDOUT: tests/web-platform/tests/webdriver/tests/new_session/platform_name.py::test_corresponds_to_local_system 
04:03:31     INFO - PID 2161 | 1536318211473	webdriver::server	DEBUG	-> POST /session {"capabilities": {"alwaysMatch": {"moz:firefoxOptions": {"binary": "/Users/cltbld/tasks/task_1536317376/build/application/Firefox Nightly.app/Contents/MacOS/firefox", "prefs": {"network.dns.localDomains": "xn--lve-6lad.not-web-platform.test,xn--lve-6lad.web-platform.test,xn--n8j6ds53lwwkrqhv28a.not-web-platform.test,www1.web-platform.test,www2.web-platform.test,not-web-platform.test,web-platform.test,www2.not-web-platform.test,www1.not-web-platform.test,www.not-web-platform.test,www.web-platform.test,xn--n8j6ds53lwwkrqhv28a.web-platform.test"}}}}}
04:03:31     INFO - PID 2161 | 1536318211476	mozrunner::runner	INFO	Running command: "/Users/cltbld/tasks/task_1536317376/build/application/Firefox Nightly.app/Contents/MacOS/firefox" "-marionette" "-foreground" "-no-remote" "-profile" "/var/folders/l8/1ps7cm7x6692vgdwmp2kskzw00000x/T/rust_mozprofile.Td7b0HvK7PNm"
04:03:31     INFO - PID 2161 | 1536318211477	geckodriver::marionette	DEBUG	Waiting 60s to connect to browser on 127.0.0.1:2828
04:03:32     INFO - PID 2161 | 1536318212120	Marionette	DEBUG	Received observer notification profile-after-change
04:03:32     INFO - PID 2161 | 1536318212166	Marionette	DEBUG	Received observer notification command-line-startup
04:03:32     INFO - PID 2161 | 1536318212167	Marionette	DEBUG	Received observer notification nsPref:changed
04:03:32     INFO - PID 2161 | 1536318212167	Marionette	DEBUG	Init aborted (running=false, enabled=true, finalUIStartup=false)
04:03:32     INFO - PID 2161 | 1536318212303	Marionette	DEBUG	Received observer notification toplevel-window-ready
04:03:33     INFO - PID 2161 | 1536318213247	Marionette	DEBUG	Received observer notification sessionstore-windows-restored
04:03:33     INFO - PID 2161 | 1536318213247	Marionette	DEBUG	Waiting for delayed startup...
04:03:33     INFO - PID 2161 | JavaScript error: resource://activity-stream/lib/TopStoriesFeed.jsm, line 99: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
04:03:33     INFO - PID 2161 | 1536318213651	Marionette	DEBUG	Waiting for startup tests...
04:03:33     INFO - PID 2161 | 1536318213652	Marionette	DEBUG	Setting recommended pref security.turn_off_all_security_so_that_viruses_can_take_over_this_computer to true
04:03:33     INFO - PID 2161 | 1536318213652	Marionette	DEBUG	Setting recommended pref apz.content_response_timeout to 60000
04:03:33     INFO - PID 2161 | 1536318213653	Marionette	DEBUG	Setting recommended pref browser.download.panel.shown to true
04:03:33     INFO - PID 2161 | 1536318213654	Marionette	DEBUG	Setting recommended pref browser.pagethumbnails.capturing_disabled to true
04:03:33     INFO - PID 2161 | 1536318213655	Marionette	DEBUG	Setting recommended pref browser.search.update to false
04:03:33     INFO - PID 2161 | 1536318213656	Marionette	DEBUG	Setting recommended pref toolkit.cosmeticAnimations.enabled to false
04:03:33     INFO - PID 2161 | 1536318213658	Marionette	DEBUG	Setting recommended pref browser.tabs.disableBackgroundZombification to false
04:03:33     INFO - PID 2161 | 1536318213658	Marionette	DEBUG	Setting recommended pref browser.tabs.warnOnCloseOtherTabs to false
04:03:33     INFO - PID 2161 | 1536318213658	Marionette	DEBUG	Setting recommended pref browser.tabs.warnOnOpen to false
04:03:33     INFO - PID 2161 | 1536318213659	Marionette	DEBUG	Setting recommended pref browser.usedOnWindows10.introURL to
04:03:33     INFO - PID 2161 | 1536318213659	Marionette	DEBUG	Setting recommended pref browser.urlbar.suggest.searches to false
04:03:33     INFO - PID 2161 | 1536318213661	Marionette	DEBUG	Setting recommended pref datareporting.policy.dataSubmissionPolicyAccepted to false
04:03:33     INFO - PID 2161 | 1536318213662	Marionette	DEBUG	Setting recommended pref dom.disable_beforeunload to true
04:03:33     INFO - PID 2161 | 1536318213662	Marionette	DEBUG	Setting recommended pref dom.disable_open_during_load to false
04:03:33     INFO - PID 2161 | 1536318213663	Marionette	DEBUG	Setting recommended pref dom.file.createInChild to true
04:03:33     INFO - PID 2161 | 1536318213663	Marionette	DEBUG	Setting recommended pref dom.max_chrome_script_run_time to 0
04:03:33     INFO - PID 2161 | 1536318213663	Marionette	DEBUG	Setting recommended pref dom.max_script_run_time to 0
04:03:33     INFO - PID 2161 | 1536318213666	Marionette	DEBUG	Setting recommended pref extensions.getAddons.cache.enabled to false
04:03:33     INFO - PID 2161 | 1536318213667	Marionette	DEBUG	Setting recommended pref extensions.webservice.discoverURL to http://%(server)s/dummy/discoveryURL
04:03:33     INFO - PID 2161 | 1536318213667	Marionette	DEBUG	Setting recommended pref network.http.prompt-temp-redirect to false
04:03:33     INFO - PID 2161 | 1536318213667	Marionette	DEBUG	Setting recommended pref network.http.speculative-parallel-limit to 0
04:03:33     INFO - PID 2161 | 1536318213668	Marionette	DEBUG	Setting recommended pref security.fileuri.strict_origin_policy to false
04:03:33     INFO - PID 2161 | 1536318213669	Marionette	DEBUG	Setting recommended pref security.notification_enable_delay to 0
04:03:33     INFO - PID 2161 | 1536318213669	Marionette	DEBUG	Setting recommended pref signon.autofillForms to false
04:03:33     INFO - PID 2161 | 1536318213670	Marionette	DEBUG	Setting recommended pref signon.rememberSignons to false
04:03:33     INFO - PID 2161 | 1536318213743	Marionette	INFO	Listening on port 2828
04:03:33     INFO - PID 2161 | 1536318213743	Marionette	DEBUG	Remote service is active
04:03:33     INFO - PID 2161 | 1536318213796	geckodriver::marionette	DEBUG	Connection established on 127.0.0.1:2828. Waiting for Marionette handshake
04:03:33     INFO - PID 2161 | 1536318213797	Marionette	DEBUG	Accepted connection 0 from 127.0.0.1:53136
04:03:33     INFO - PID 2161 | 1536318213798	geckodriver::marionette	DEBUG	Connected to Marionette
04:03:33     INFO - PID 2161 | 1536318213799	Marionette	TRACE	0 -> [0,1,"WebDriver:NewSession",{}]
04:03:33     INFO - PID 2161 | 1536318213862	Marionette	DEBUG	[4294967297] Frame script loaded
04:03:33     INFO - PID 2161 | 1536318213869	Marionette	DEBUG	[4294967297] Frame script registered
04:03:33     INFO - PID 2161 | 1536318213882	Marionette	TRACE	0 <- [1,1,null,{"sessionId":"f97c2568-25b6-2f41-9b90-e718fe7d7958","capabilities":{"browserName":"firefox","browserVersion":"64.0a ... 2vgdwmp2kskzw00000x/T/rust_mozprofile.Td7b0HvK7PNm","moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true}}]
04:03:33     INFO - STDOUT: PASSED
04:03:33     INFO - PID 2161 | 1536318213886	webdriver::server	DEBUG	<- 200 OK {"value":{"sessionId":"f97c2568-25b6-2f41-9b90-e718fe7d7958","capabilities":{"acceptInsecureCerts":false,"browserName":"firefox","browserVersion":"64.0a1","moz:accessibilityChecks":false,"moz:geckodriverVersion":"0.21.0","moz:headless":false,"moz:processID":2364,"moz:profile":"/var/folders/l8/1ps7cm7x6692vgdwmp2kskzw00000x/T/rust_mozprofile.Td7b0HvK7PNm","moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true,"pageLoadStrategy":"normal","platformName":"mac","platformVersion":"14.5.0","rotatable":false,"setWindowRect":true,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"unhandledPromptBehavior":"dismiss and notify"}}}
04:03:33     INFO - PID 2161 | 1536318213889	webdriver::server	DEBUG	-> DELETE /session/f97c2568-25b6-2f41-9b90-e718fe7d7958
04:03:33     INFO - PID 2161 | 1536318213894	Marionette	TRACE	0 -> [0,2,"Marionette:Quit",{"flags":["eForceQuit"]}]
04:03:33     INFO - PID 2161 | 1536318213895	Marionette	INFO	Stopped listening on port 2828
04:03:33     INFO - PID 2161 | JavaScript error: resource://activity-stream/lib/TopSitesFeed.jsm, line 86: TypeError: setting getter-only property "_currentSearchHostname"
04:03:33     INFO - PID 2161 | 1536318213954	Marionette	TRACE	0 <- [1,2,null,{"cause":"shutdown"}]
04:03:33     INFO - PID 2161 | 1536318213988	webdriver::server	DEBUG	Deleting session
04:03:34     INFO - PID 2161 | 1536318213993	Marionette	TRACE	0 -> [0,3,"Marionette:Quit",{"flags":["eForceQuit"]}]
04:03:34     INFO - PID 2161 | 1536318213994	Marionette	TRACE	0 <- [1,3,{"error":"invalid session id","message":"Tried to run command without establishing a connection","stacktrace":"WebDriver ... et@chrome://marionette/content/server.js:245:8\n_onJSONObjectReady/<@chrome://marionette/content/transport.js:490:9\n"},null]
04:03:34     INFO - PID 2161 | 1536318214011	Marionette	DEBUG	Closed connection 0
04:03:34     INFO - PID 2161 | JavaScript error: resource://gre/modules/Sqlite.jsm, line 841: Error: Connection is not open.
04:03:34     INFO - PID 2161 | 1536318214167	Marionette	DEBUG	Received observer notification xpcom-will-shutdown
04:03:34     INFO - PID 2161 | 1536318214167	Marionette	DEBUG	Resetting recommended pref security.turn_off_all_security_so_that_viruses_can_take_over_this_computer
04:03:34     INFO - PID 2161 | 1536318214167	Marionette	DEBUG	Resetting recommended pref apz.content_response_timeout
04:03:34     INFO - PID 2161 | 1536318214167	Marionette	DEBUG	Resetting recommended pref browser.download.panel.shown
04:03:34     INFO - PID 2161 | 1536318214167	Marionette	DEBUG	Resetting recommended pref browser.pagethumbnails.capturing_disabled
04:03:34     INFO - PID 2161 | 1536318214170	Marionette	DEBUG	Resetting recommended pref browser.search.update
04:03:34     INFO - PID 2161 | 1536318214172	Marionette	DEBUG	Resetting recommended pref toolkit.cosmeticAnimations.enabled
04:03:34     INFO - PID 2161 | 1536318214174	Marionette	DEBUG	Resetting recommended pref browser.tabs.disableBackgroundZombification
04:03:34     INFO - PID 2161 | 1536318214174	Marionette	DEBUG	Resetting recommended pref browser.tabs.warnOnCloseOtherTabs
04:03:34     INFO - PID 2161 | 1536318214174	Marionette	DEBUG	Resetting recommended pref browser.tabs.warnOnOpen
04:03:34     INFO - PID 2161 | 1536318214176	Marionette	DEBUG	Resetting recommended pref browser.usedOnWindows10.introURL
04:03:34     INFO - PID 2161 | 1536318214177	Marionette	DEBUG	Resetting recommended pref browser.urlbar.suggest.searches
04:03:34     INFO - PID 2161 | 1536318214177	Marionette	DEBUG	Resetting recommended pref datareporting.policy.dataSubmissionPolicyAccepted
04:03:34     INFO - PID 2161 | 1536318214177	Marionette	DEBUG	Resetting recommended pref dom.disable_beforeunload
04:03:34     INFO - PID 2161 | 1536318214178	Marionette	DEBUG	Resetting recommended pref dom.disable_open_during_load
04:03:34     INFO - PID 2161 | 1536318214178	Marionette	DEBUG	Resetting recommended pref dom.file.createInChild
04:03:34     INFO - PID 2161 | 1536318214179	Marionette	DEBUG	Resetting recommended pref dom.max_chrome_script_run_time
04:03:34     INFO - PID 2161 | 1536318214179	Marionette	DEBUG	Resetting recommended pref dom.max_script_run_time
04:03:34     INFO - PID 2161 | 1536318214180	Marionette	DEBUG	Resetting recommended pref extensions.getAddons.cache.enabled
04:03:34     INFO - PID 2161 | 1536318214180	Marionette	DEBUG	Resetting recommended pref extensions.webservice.discoverURL
04:03:34     INFO - PID 2161 | 1536318214180	Marionette	DEBUG	Resetting recommended pref network.http.prompt-temp-redirect
04:03:34     INFO - PID 2161 | 1536318214180	Marionette	DEBUG	Resetting recommended pref network.http.speculative-parallel-limit
04:03:34     INFO - PID 2161 | 1536318214180	Marionette	DEBUG	Resetting recommended pref security.fileuri.strict_origin_policy
04:03:34     INFO - PID 2161 | 1536318214180	Marionette	DEBUG	Resetting recommended pref security.notification_enable_delay
04:03:34     INFO - PID 2161 | 1536318214180	Marionette	DEBUG	Resetting recommended pref signon.autofillForms
04:03:34     INFO - PID 2161 | 1536318214180	Marionette	DEBUG	Resetting recommended pref signon.rememberSignons
04:03:34     INFO - PID 2161 | 1536318214181	Marionette	DEBUG	Remote service is inactive
04:04:01     INFO - TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/new_session/platform_name.py | expected OK
04:04:01     INFO - TEST-INFO took 30002ms
04:04:01  WARNING - u'runner_teardown': ()
Shutdown hang of Firefox.
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
Status: NEW → RESOLVED
Closed: 6 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 6 years ago5 years ago
Resolution: --- → INCOMPLETE
Status: REOPENED → RESOLVED
Closed: 5 years ago5 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 5 years ago5 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 5 years ago5 years ago
Resolution: --- → INCOMPLETE
Status: REOPENED → RESOLVED
Closed: 5 years ago4 years ago
Resolution: --- → INCOMPLETE
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.