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

Reporter

Description

•

6 years ago

treeherder

Filed by: ncsoregi [at] mozilla.com

https://treeherder.mozilla.org/logviewer.html#?job_id=202828732&repo=mozilla-inbound

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

18-10-02T09:39:15.223Z] 09:39:15     INFO - PID 24895 | 
[task 2018-10-02T09:39:15.421Z] 09:39:15     INFO - PID 24895 | 1538473155408	Marionette	DEBUG	Received observer notification xpcom-will-shutdown
[task 2018-10-02T09:39:15.423Z] 09:39:15     INFO - PID 24895 | 1538473155408	Marionette	DEBUG	Resetting recommended pref security.turn_off_all_security_so_that_viruses_can_take_over_this_computer
[task 2018-10-02T09:39:15.423Z] 09:39:15     INFO - PID 24895 | 1538473155409	Marionette	DEBUG	Resetting recommended pref apz.content_response_timeout
[task 2018-10-02T09:39:15.425Z] 09:39:15     INFO - PID 24895 | 1538473155409	Marionette	DEBUG	Resetting recommended pref browser.download.panel.shown
[task 2018-10-02T09:39:15.426Z] 09:39:15     INFO - PID 24895 | 1538473155409	Marionette	DEBUG	Resetting recommended pref browser.pagethumbnails.capturing_disabled
[task 2018-10-02T09:39:15.431Z] 09:39:15     INFO - PID 24895 | 1538473155409	Marionette	DEBUG	Resetting recommended pref browser.search.update
[task 2018-10-02T09:39:15.431Z] 09:39:15     INFO - PID 24895 | 1538473155409	Marionette	DEBUG	Resetting recommended pref toolkit.cosmeticAnimations.enabled
[task 2018-10-02T09:39:15.431Z] 09:39:15     INFO - PID 24895 | 1538473155410	Marionette	DEBUG	Resetting recommended pref browser.tabs.disableBackgroundZombification
[task 2018-10-02T09:39:15.431Z] 09:39:15     INFO - PID 24895 | 1538473155410	Marionette	DEBUG	Resetting recommended pref browser.tabs.warnOnCloseOtherTabs
[task 2018-10-02T09:39:15.431Z] 09:39:15     INFO - PID 24895 | 1538473155410	Marionette	DEBUG	Resetting recommended pref browser.tabs.warnOnOpen
[task 2018-10-02T09:39:15.431Z] 09:39:15     INFO - PID 24895 | 1538473155410	Marionette	DEBUG	Resetting recommended pref browser.usedOnWindows10.introURL
[task 2018-10-02T09:39:15.431Z] 09:39:15     INFO - PID 24895 | 1538473155410	Marionette	DEBUG	Resetting recommended pref browser.urlbar.suggest.searches
[task 2018-10-02T09:39:15.432Z] 09:39:15     INFO - PID 24895 | 1538473155411	Marionette	DEBUG	Resetting recommended pref datareporting.policy.dataSubmissionPolicyAccepted
[task 2018-10-02T09:39:15.433Z] 09:39:15     INFO - PID 24895 | 1538473155411	Marionette	DEBUG	Resetting recommended pref dom.disable_beforeunload
[task 2018-10-02T09:39:15.433Z] 09:39:15     INFO - PID 24895 | 1538473155411	Marionette	DEBUG	Resetting recommended pref dom.disable_open_during_load
[task 2018-10-02T09:39:15.433Z] 09:39:15     INFO - PID 24895 | 1538473155411	Marionette	DEBUG	Resetting recommended pref dom.file.createInChild
[task 2018-10-02T09:39:15.434Z] 09:39:15     INFO - PID 24895 | 1538473155411	Marionette	DEBUG	Resetting recommended pref dom.max_chrome_script_run_time
[task 2018-10-02T09:39:15.435Z] 09:39:15     INFO - PID 24895 | 1538473155412	Marionette	DEBUG	Resetting recommended pref dom.max_script_run_time
[task 2018-10-02T09:39:15.436Z] 09:39:15     INFO - PID 24895 | 1538473155412	Marionette	DEBUG	Resetting recommended pref extensions.getAddons.cache.enabled
[task 2018-10-02T09:39:15.436Z] 09:39:15     INFO - PID 24895 | 1538473155412	Marionette	DEBUG	Resetting recommended pref extensions.webservice.discoverURL
[task 2018-10-02T09:39:15.437Z] 09:39:15     INFO - PID 24895 | 1538473155412	Marionette	DEBUG	Resetting recommended pref network.http.prompt-temp-redirect
[task 2018-10-02T09:39:15.439Z] 09:39:15     INFO - PID 24895 | 1538473155412	Marionette	DEBUG	Resetting recommended pref network.http.speculative-parallel-limit
[task 2018-10-02T09:39:15.440Z] 09:39:15     INFO - PID 24895 | 1538473155412	Marionette	DEBUG	Resetting recommended pref security.fileuri.strict_origin_policy
[task 2018-10-02T09:39:15.440Z] 09:39:15     INFO - PID 24895 | 1538473155412	Marionette	DEBUG	Resetting recommended pref security.notification_enable_delay
[task 2018-10-02T09:39:15.440Z] 09:39:15     INFO - PID 24895 | 1538473155413	Marionette	DEBUG	Resetting recommended pref signon.autofillForms
[task 2018-10-02T09:39:15.441Z] 09:39:15     INFO - PID 24895 | 1538473155413	Marionette	DEBUG	Resetting recommended pref signon.rememberSignons
[task 2018-10-02T09:39:15.441Z] 09:39:15     INFO - PID 24895 | 1538473155413	Marionette	DEBUG	Remote service is inactive
[task 2018-10-02T09:39:42.454Z] 09:39:42     INFO - TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/new_session/page_load_strategy.py | expected OK
[task 2018-10-02T09:39:42.455Z] 09:39:42     INFO - TEST-INFO took 30002ms
[task 2018-10-02T09:39:42.482Z] 09:39:42     INFO - STDERR: Traceback (most recent call last):
[task 2018-10-02T09:39:42.483Z] 09:39:42     INFO - STDERR:   File "/usr/lib/python2.7/multiprocessing/queues.py", line 268, in _feed
[task 2018-10-02T09:39:42.483Z] 09:39:42     INFO - STDERR:     send(obj)
[task 2018-10-02T09:39:42.519Z] 09:39:42  WARNING - u'runner_teardown': ()
[task 2018-10-02T09:39:42.519Z] 09:39:42     INFO - Closing logging queue
[task 2018-10-02T09:39:42.520Z] 09:39:42     INFO - STDOUT: tests/web-platform/tests/webdriver/tests/new_session/page_load_strategy.py::test_pageLoadStrategy 
[task 2018-10-02T09:39:42.521Z] 09:39:42     INFO - STDOUT: ERROR
[task 2018-10-02T09:39:42.522Z] 09:39:42     INFO - queue closed

Henrik Skupin [:whimboo][⌚️UTC+1]

Comment 1

•

6 years ago

Hang of Firefox during shutdown:

> [task 2018-10-02T09:39:15.441Z] 09:39:15     INFO - PID 24895 | 1538473155413	Marionette	DEBUG	Resetting recommended pref signon.rememberSignons
> [task 2018-10-02T09:39:15.441Z] 09:39:15     INFO - PID 24895 | 1538473155413	Marionette	DEBUG	Remote service is inactive
> [task 2018-10-02T09:39:42.454Z] 09:39:42     INFO - TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/new_session/page_load_strategy.py | expected OK

Comment hidden (Intermittent Failures Robot)

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

Repository breakdown:
* mozilla-inbound: 1

Platform breakdown:
* linux32: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1495691&startday=2018-10-01&endday=2018-10-07&tree=all

Firefox Bug Husbandry Bot

Comment 3

•

6 years ago

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup

Status: NEW → RESOLVED

Closed: 6 years ago

Resolution: --- → INCOMPLETE

Comment hidden (Intermittent Failures Robot)

Noemi Erli[:noemi_erli]

Comment 5

•

5 years ago

Recent log failure: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=242343506&repo=mozilla-beta&lineNumber=90705

[task 2019-04-24T16:05:06.419Z] 16:05:06 [task 2019-04-24T16:05:06.455Z] 16:05:06 [task 2019-04-24T16:05:06.456Z] 16:05:06 [task 2019-04-24T16:05:06.456Z] 16:05:06 [task 2019-04-24T16:05:06.456Z] 16:05:06 [task 2019-04-24T16:05:06.498Z] 16:05:06 [task 2019-04-24T16:05:06.501Z] 16:05:06 [task 2019-04-24T16:05:06.509Z] 16:05:06 [task 2019-04-24T16:05:06.510Z] 16:05:06 [task 2019-04-24T16:05:06.511Z] 16:05:06 [task 2019-04-24T16:05:07.032Z] 16:05:07 [task 2019-04-24T16:05:07.033Z] 16:05:07 [task 2019-04-24T16:05:07.034Z] 16:05:07 [task 2019-04-24T16:05:07.171Z] 16:05:07 [task 2019-04-24T16:05:07.219Z] 16:05:07 [task 2019-04-24T16:05:07.220Z] 16:05:07 [task 2019-04-24T16:05:07.220Z] 16:05:07 [task 2019-04-24T16:05:07.745Z] 16:05:07 [task 2019-04-24T16:05:10.576Z] 16:05:10 [task 2019-04-24T16:05:10.578Z] 16:05:10 [task 2019-04-24T16:05:10.622Z] 16:05:10 [task 2019-04-24T16:05:10.622Z] 16:05:10 [task 2019-04-24T16:05:10.624Z] 16:05:10 [task 2019-04-24T16:05:10.627Z] 16:05:10 [task 2019-04-24T16:05:10.637Z] 16:05:10 [task 2019-04-24T16:05:10.638Z] 16:05:10 [task 2019-04-24T16:05:10.639Z] 16:05:10 [task 2019-04-24T16:05:10.640Z] 16:05:10 [task 2019-04-24T16:05:10.641Z] 16:05:10 [task 2019-04-24T16:05:10.641Z] 16:05:10 [task 2019-04-24T16:05:10.642Z] 16:05:10 [task 2019-04-24T16:05:10.643Z] 16:05:10 [task 2019-04-24T16:05:10.643Z] 16:05:10 [task 2019-04-24T16:05:10.644Z] 16:05:10 [task 2019-04-24T16:05:10.644Z] 16:05:10 [task 2019-04-24T16:05:10.645Z] 16:05:10 [task 2019-04-24T16:05:10.648Z] 16:05:10 [task 2019-04-24T16:05:10.648Z] 16:05:10 [task 2019-04-24T16:05:10.648Z] 16:05:10 [task 2019-04-24T16:05:10.649Z] 16:05:10 [task 2019-04-24T16:05:10.649Z] 16:05:10 [task 2019-04-24T16:05:10.650Z] 16:05:10 [task 2019-04-24T16:05:10.651Z] 16:05:10 [task 2019-04-24T16:05:10.652Z] 16:05:10 [task 2019-04-24T16:05:10.653Z] 16:05:10 [task 2019-04-24T16:05:10.653Z] 16:05:10 [task 2019-04-24T16:05:10.656Z] 16:05:10 [task 2019-04-24T16:05:10.692Z] 16:05:10 [task 2019-04-24T16:05:10.693Z] 16:05:10 [task 2019-04-24T16:05:10.729Z] 16:05:10 [task 2019-04-24T16:05:11.472Z] 16:05:11 [task 2019-04-24T16:05:11.472Z] 16:05:11 [task 2019-04-24T16:05:11.473Z] 16:05:11 [task 2019-04-24T16:05:11.489Z] 16:05:11 [task 2019-04-24T16:05:11.491Z] 16:05:11 [task 2019-04-24T16:05:11.499Z] 16:05:11 [task 2019-04-24T16:05:11.503Z] 16:05:11 [task 2019-04-24T16:05:11.507Z] 16:05:11 [task 2019-04-24T16:05:11.508Z] 16:05:11 [task 2019-04-24T16:05:11.509Z] 16:05:11 [task 2019-04-24T16:05:11.509Z] 16:05:11 [task 2019-04-24T16:05:11.511Z] 16:05:11 [task 2019-04-24T16:05:11.576Z] 16:05:11 [task 2019-04-24T16:05:11.614Z] 16:05:11 [task 2019-04-24T16:05:11.616Z] 16:05:11 [task 2019-04-24T16:05:11.626Z] 16:05:11 [task 2019-04-24T16:05:11.626Z] 16:05:11 [task 2019-04-24T16:05:11.633Z] 16:05:11 [task 2019-04-24T16:05:11.710Z] 16:05:11 [task 2019-04-24T16:05:11.711Z] 16:05:11 [task 2019-04-24T16:05:36.420Z] 16:05:36 [task 2019-04-24T16:05:36.421Z] 16:05:36 [task 2019-04-24T16:05:36.457Z] 16:05:36 [task 2019-04-24T16:05:36.458Z] 16:05:36 [task 2019-04-24T16:05:36.458Z] 16:05:36 [task 2019-04-24T16:05:36.458Z] 16:05:36 [task 2019-04-24T16:05:36.486Z] 16:05:36 [task 2019-04-24T16:05:36.486Z] 16:05:36 [task 2019-04-24T16:05:36.487Z] 16:05:36 [task 2019-04-24T16:05:36.487Z] 16:05:36 [task 2019-04-24T16:05:36.488Z] 16:05:36 [task 2019-04-24T16:05:36.488Z] 16:05:36 [task 2019-04-24T16:05:36.488Z] 16:05:36 [task 2019-04-24T16:05:36.489Z] 16:05:36 [task 2019-04-24T16:05:36.489Z] 16:05:36 [task 2019-04-24T16:05:36.489Z] 16:05:36 [task 2019-04-24T16:05:36.489Z] 16:05:36 [task 2019-04-24T16:05:36.517Z] 16:05:36 [task 2019-04-24T16:05:36.519Z] 16:05:36 [task 2019-04-24T16:05:36.519Z] 16:05:36 [task 2019-04-24T16:05:36.535Z] 16:05:36 [task 2019-04-24T16:05:36.773Z] 16:05:36 [task 2019-04-24T16:05:36.774Z] 16:05:36 [task 2019-04-24T16:05:36.774Z] 16:05:36 [task 2019-04-24T16:05:36.775Z] 16:05:36 [task 2019-04-24T16:05:36.896Z] 16:05:36 [task 2019-04-24T16:05:36.898Z] 16:05:36 [task 2019-04-24T16:05:36.904Z] 16:05:36 [task 2019-04-24T16:05:36.904Z] 16:05:36 [task 2019-04-24T16:05:36.906Z] 16:05:36 [task 2019-04-24T16:05:37.378Z] 16:05:37 [task 2019-04-24T16:05:37.379Z] 16:05:37 [task 2019-04-24T16:05:37.379Z] 16:05:37 INFO - TEST-START | /webdriver/tests/new_session/page_load_strategy.py
INFO - STDOUT: ============================= test session starts ==============================
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
INFO - STDOUT: rootdir: /builds/worker/workspace/build, inifile:
INFO - STDOUT: collecting ...
INFO - STDOUT: collected 1 item
INFO - STDOUT: tests/web-platform/tests/webdriver/tests/new_session/page_load_strategy.py::test_pageLoadStrategy
INFO - PID 5747 | 1556121906500 webdriver::server DEBUG -> POST /session {"capabilities": {"alwaysMatch": {"pageLoadStrategy": "eager", "moz:firefoxOptions": {"binary": "/builds/worker/workspace/build/application/firefox/firefox", "prefs": {"network.dns.localDomains": "www1.xn--n8j6ds53lwwkrqhv28a.web-platform.test,xn--lve-6lad.www1.web-platform.test,www.www2.not-web-platform.test,www2.www2.not-web-platform.test,xn--lve-6lad.www.not-web-platform.test,xn--n8j6ds53lwwkrqhv28a.not-web-platform.test,www.not-web-platform.test,xn--n8j6ds53lwwkrqhv28a.www.not-web-platform.test,not-web-platform.test,www1.www.not-web-platform.test,xn--n8j6ds53lwwkrqhv28a.www1.web-platform.test,www2.not-web-platform.test,www1.not-web-platform.test,www1.www1.web-platform.test,www2.xn--lve-6lad.web-platform.test,www1.xn--n8j6ds53lwwkrqhv28a.not-web-platform.test,xn--n8j6ds53lwwkrqhv28a.xn--n8j6ds53lwwkrqhv28a.web-platform.test,www1.www2.web-platform.test,xn--lve-6lad.xn--n8j6ds53lwwkrqhv28a.not-web-platform.test,xn--lve-6lad.xn--lve-6lad.not-web-platform.test,www2.www1.not-web-platform.test,www2.xn--n8j6ds53lwwkrqhv28a.web-platform.test,www.xn--n8j6ds53lwwkrqhv28a.not-web-platform.test,web-platform.test,xn--n8j6ds53lwwkrqhv28a.xn--lve-6lad.not-web-platform.test,xn--lve-6lad.www.web-platform.test,www.www.web-platform.test,www1.xn--lve-6lad.web-platform.test,xn--lve-6lad.www2.web-platform.test,www.www2.web-platform.test,www2.www.web-platform.test,xn--n8j6ds53lwwkrqhv28a.www2.not-web-platform.test,www1.www.web-platform.test,www.xn--lve-6lad.web-platform.test,www.www.not-web-platform.test,xn--lve-6lad.xn--lve-6lad.web-platform.test,xn--lve-6lad.www1.not-web-platform.test,www1.www1.not-web-platform.test,xn--n8j6ds53lwwkrqhv28a.xn--n8j6ds53lwwkrqhv28a.not-web-platform.test,xn--n8j6ds53lwwkrqhv28a.www2.web-platform.test,xn--n8j6ds53lwwkrqhv28a.www1.not-web-platform.test,www2.www2.web-platform.test,xn--lve-6lad.web-platform.test,xn--n8j6ds53lwwkrqhv28a.web-platform.test,xn--n8j6ds53lwwkrqhv28a.www.web-platform.test,xn--lve-6lad.not-web-platform.test,www2.xn--n8j6ds53lwwkrqhv28a.not-web-platform.test,www2.xn--lve-6lad.not-web-platform.test,xn--n8j6ds53lwwkrqhv28a.xn--lve-6lad.web-platform.test,www1.xn--lve-6lad.not-web-platform.test,www1.web-platform.test,www.www1.web-platform.test,www2.web-platform.test,www.xn--lve-6lad.not-web-platform.test,www.web-platform.test,xn--lve-6lad.xn--n8j6ds53lwwkrqhv28a.web-platform.test,www1.www2.not-web-platform.test,www2.www1.web-platform.test,www.www1.not-web-platform.test,www.xn--n8j6ds53lwwkrqhv28a.web-platform.test,xn--lve-6lad.www2.not-web-platform.test,www2.www.not-web-platform.test"}}}}}
INFO - PID 5747 | 1556121906502 mozrunner::runner INFO Running command: "/builds/worker/workspace/build/application/firefox/firefox" "-marionette" "-foreground" "-no-remote" "-profile" "/tmp/rust_mozprofile.m59DPm7rY6KU"
INFO - PID 5747 | 1556121906503 geckodriver::marionette DEBUG Waiting 60s to connect to browser on 127.0.0.1:2828
INFO - PID 5747 | 1556121907019 addons.webextension.screenshots@mozilla.org WARN Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid extension permission: mozillaAddons
INFO - PID 5747 | 1556121907020 addons.webextension.screenshots@mozilla.org WARN Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid extension permission: resource://pdf.js/
INFO - PID 5747 | 1556121907020 addons.webextension.screenshots@mozilla.org WARN Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid extension permission: about:reader*
INFO - PID 5747 | 1556121907165 Marionette TRACE Received observer notification profile-after-change
INFO - PID 5747 | 1556121907211 Marionette TRACE Received observer notification command-line-startup
INFO - PID 5747 | 1556121907212 Marionette TRACE Received observer notification nsPref:changed
INFO - PID 5747 | 1556121907212 Marionette DEBUG Init aborted (running=false, enabled=true, finalUIStartup=false)
INFO - PID 5747 | 1556121907742 Marionette TRACE Received observer notification toplevel-window-ready
INFO - PID 5747 | 1556121910570 Marionette TRACE Received observer notification marionette-startup-requested
INFO - PID 5747 | 1556121910570 Marionette TRACE Waiting until startup recorder finished recording startup scripts...
INFO - PID 5747 | 1556121910611 Marionette TRACE All scripts recorded.
INFO - PID 5747 | 1556121910611 Marionette DEBUG Setting recommended pref apz.content_response_timeout to 60000
INFO - PID 5747 | 1556121910611 Marionette DEBUG Setting recommended pref browser.contentblocking.introCount to 99
INFO - PID 5747 | 1556121910612 Marionette DEBUG Setting recommended pref browser.download.panel.shown to true
INFO - PID 5747 | 1556121910612 Marionette DEBUG Setting recommended pref browser.newtabpage.enabled to false
INFO - PID 5747 | 1556121910613 Marionette DEBUG Setting recommended pref browser.pagethumbnails.capturing_disabled to true
INFO - PID 5747 | 1556121910614 Marionette DEBUG Setting recommended pref browser.search.update to false
INFO - PID 5747 | 1556121910614 Marionette DEBUG Setting recommended pref browser.tabs.disableBackgroundZombification to false
INFO - PID 5747 | 1556121910614 Marionette DEBUG Setting recommended pref browser.tabs.warnOnCloseOtherTabs to false
INFO - PID 5747 | 1556121910615 Marionette DEBUG Setting recommended pref browser.tabs.warnOnOpen to false
INFO - PID 5747 | 1556121910615 Marionette DEBUG Setting recommended pref browser.usedOnWindows10.introURL to
INFO - PID 5747 | 1556121910615 Marionette DEBUG Setting recommended pref browser.urlbar.suggest.searches to false
INFO - PID 5747 | 1556121910616 Marionette DEBUG Setting recommended pref datareporting.policy.dataSubmissionPolicyAccepted to false
INFO - PID 5747 | 1556121910617 Marionette DEBUG Setting recommended pref dom.disable_beforeunload to true
INFO - PID 5747 | 1556121910617 Marionette DEBUG Setting recommended pref dom.disable_open_during_load to false
INFO - PID 5747 | 1556121910617 Marionette DEBUG Setting recommended pref dom.file.createInChild to true
INFO - PID 5747 | 1556121910617 Marionette DEBUG Setting recommended pref dom.max_chrome_script_run_time to 0
INFO - PID 5747 | 1556121910618 Marionette DEBUG Setting recommended pref dom.max_script_run_time to 0
INFO - PID 5747 | 1556121910619 Marionette DEBUG Setting recommended pref extensions.getAddons.cache.enabled to false
INFO - PID 5747 | 1556121910619 Marionette DEBUG Setting recommended pref extensions.webservice.discoverURL to http://%(server)s/dummy/discoveryURL
INFO - PID 5747 | 1556121910619 Marionette DEBUG Setting recommended pref network.http.prompt-temp-redirect to false
INFO - PID 5747 | 1556121910619 Marionette DEBUG Setting recommended pref network.http.speculative-parallel-limit to 0
INFO - PID 5747 | 1556121910619 Marionette DEBUG Setting recommended pref security.fileuri.strict_origin_policy to false
INFO - PID 5747 | 1556121910620 Marionette DEBUG Setting recommended pref security.notification_enable_delay to 0
INFO - PID 5747 | 1556121910620 Marionette DEBUG Setting recommended pref signon.autofillForms to false
INFO - PID 5747 | 1556121910620 Marionette DEBUG Setting recommended pref signon.rememberSignons to false
INFO - PID 5747 | 1556121910620 Marionette DEBUG Setting recommended pref toolkit.cosmeticAnimations.enabled to false
INFO - PID 5747 | 1556121910683 Marionette INFO Listening on port 2828
INFO - PID 5747 | 1556121910683 Marionette DEBUG Remote service is active
INFO - PID 5747 | 1556121910713 geckodriver::marionette DEBUG Connection established on 127.0.0.1:2828. Waiting for Marionette handshake
INFO - PID 5747 | 1556121911458 Marionette DEBUG Accepted connection 0 from 127.0.0.1:39888
INFO - PID 5747 | 1556121911460 geckodriver::marionette DEBUG Connected to Marionette
INFO - PID 5747 | 1556121911460 Marionette DEBUG 0 -> [0,1,"WebDriver:NewSession",{"pageLoadStrategy":"eager"}]
INFO - PID 5747 | 1556121911487 Marionette TRACE [4294967297] Frame script loaded
INFO - PID 5747 | 1556121911488 Marionette TRACE [4294967297] Frame script registered
INFO - PID 5747 | 1556121911495 Marionette DEBUG 0 <- [1,1,null,{"sessionId":"e25cba6f-747b-4cec-94bf-40bf95903970","capabilities":{"browserName":"firefox","browserVersion":"67.0" ... ozprofile.m59DPm7rY6KU","moz:shutdownTimeout":60000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true}}]
INFO - STDOUT: PASSED
INFO - PID 5747 | 1556121911498 webdriver::server DEBUG <- 200 OK {"value":{"sessionId":"e25cba6f-747b-4cec-94bf-40bf95903970","capabilities":{"acceptInsecureCerts":false,"browserName":"firefox","browserVersion":"67.0","moz:accessibilityChecks":false,"moz:buildID":"20190424140259","moz:geckodriverVersion":"0.24.0","moz:headless":false,"moz:processID":8771,"moz:profile":"/tmp/rust_mozprofile.m59DPm7rY6KU","moz:shutdownTimeout":60000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true,"pageLoadStrategy":"eager","platformName":"linux","platformVersion":"4.4.0-1014-aws","rotatable":false,"setWindowRect":true,"strictFileInteractability":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"unhandledPromptBehavior":"dismiss and notify"}}}
INFO - PID 5747 | 1556121911501 webdriver::server DEBUG -> DELETE /session/e25cba6f-747b-4cec-94bf-40bf95903970
INFO - PID 5747 | 1556121911502 Marionette DEBUG 0 -> [0,2,"Marionette:Quit",{"flags":["eForceQuit"]}]
INFO - PID 5747 | 1556121911503 Marionette INFO Stopped listening on port 2828
INFO - PID 5747 | JavaScript error: resource://activity-stream/lib/ActivityStreamPrefs.jsm, line 23: NS_ERROR_ILLEGAL_VALUE: Component returned failure code: 0x80070057 (NS_ERROR_ILLEGAL_VALUE) [nsIPrefBranch.removeObserver]
INFO - PID 5747 | 1556121911570 Marionette TRACE Received observer notification quit-application
INFO - PID 5747 | 1556121911584 Marionette DEBUG 0 <- [1,2,null,{"cause":"shutdown"}]
INFO - PID 5747 | 1556121911613 webdriver::server DEBUG Deleting session
INFO - PID 5747 | 1556121911622 Marionette DEBUG 0 -> [0,3,"Marionette:Quit",{"flags":["eForceQuit"]}]
INFO - PID 5747 | 1556121911622 Marionette DEBUG 0 <- [1,3,{"error":"invalid session id","message":"Tried to run command without establishing a connection","stacktrace":"WebDriver ... t@chrome://marionette/content/server.js:236:9\n_onJSONObjectReady/<@chrome://marionette/content/transport.js:492:20\n"},null]
INFO - PID 5747 | 1556121911629 Marionette DEBUG Closed connection 0
INFO - PID 5747 | JavaScript error: resource://gre/modules/ExtensionSettingsStore.jsm, line 105: Error: The ExtensionSettingsStore was accessed before the initialize promise resolved.
INFO - PID 5747 | JavaScript error: resource://gre/modules/Sqlite.jsm, line 834: Error: Connection is not open.
INFO - TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/new_session/page_load_strategy.py | expected OK
INFO - TEST-INFO took 30002ms
INFO - STDERR: Traceback (most recent call last):
INFO - STDERR: File "/usr/lib/python2.7/multiprocessing/queues.py", line 268, in _feed
INFO - STDERR: send(obj)
INFO - STDERR: IOError: [Errno 32] Broken pipe
INFO - STDOUT: tests/web-platform/tests/webdriver/tests/new_session/page_load_strategy.py::test_pageLoadStrategy
INFO - STDOUT: ERROR
INFO - STDOUT: ==================================== ERRORS ====================================
INFO - Closing logging queue
INFO - STDOUT: __________________ ERROR at teardown of test_pageLoadStrategy __________________
INFO - STDOUT: request = <SubRequest 'new_session' for <Function 'test_pageLoadStrategy'>>
INFO - STDOUT: configuration = {'capabilities': {'moz:firefoxOptions': {'binary': '/builds/worker/workspace/build/application/firefox/firefox', 'pref...orm.test,xn--lve-6lad.www2.not-web-platform.test,www2.www.not-web-platform.test'}}}, 'host': '127.0.0.1', 'port': 4446}
INFO - STDOUT: current_session = None
INFO - STDOUT: @pytest.fixture(name="new_session")
INFO - queue closed
INFO - Starting runner
INFO - WebDriver HTTP server listening at http://127.0.0.1:4444/
INFO - TEST-START | /webdriver/tests/new_session/platform_name.py
INFO - PID 9040 | 1556121936508 webdriver::httpapi DEBUG Creating routes
INFO - PID 9040 | 1556121936516 geckodriver DEBUG Listening on 127.0.0.1:4444
INFO - STDOUT: ============================= test session starts ==============================
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
INFO - STDOUT: rootdir: /builds/worker/workspace/build, inifile:
INFO - STDOUT: collecting ...
INFO - STDOUT: collected 1 item
INFO - STDOUT: tests/web-platform/tests/webdriver/tests/new_session/platform_name.py::test_corresponds_to_local_system
INFO - PID 9040 | 1556121936896 webdriver::server DEBUG -> POST /session {"capabilities": {"alwaysMatch": {"moz:firefoxOptions": {"binary": "/builds/worker/workspace/build/application/firefox/firefox", "prefs": {"network.dns.localDomains": "www1.xn--n8j6ds53lwwkrqhv28a.web-platform.test,xn--lve-6lad.www1.web-platform.test,www.www2.not-web-platform.test,www2.www2.not-web-platform.test,xn--lve-6lad.www.not-web-platform.test,xn--n8j6ds53lwwkrqhv28a.not-web-platform.test,www.not-web-platform.test,xn--n8j6ds53lwwkrqhv28a.www.not-web-platform.test,not-web-platform.test,www1.www.not-web-platform.test,xn--n8j6ds53lwwkrqhv28a.www1.web-platform.test,www2.not-web-platform.test,www1.not-web-platform.test,www1.www1.web-platform.test,www2.xn--lve-6lad.web-platform.test,www1.xn--n8j6ds53lwwkrqhv28a.not-web-platform.test,xn--n8j6ds53lwwkrqhv28a.xn--n8j6ds53lwwkrqhv28a.web-platform.test,www1.www2.web-platform.test,xn--lve-6lad.xn--n8j6ds53lwwkrqhv28a.not-web-platform.test,xn--lve-6lad.xn--lve-6lad.not-web-platform.test,www2.www1.not-web-platform.test,www2.xn--n8j6ds53lwwkrqhv28a.web-platform.test,www.xn--n8j6ds53lwwkrqhv28a.not-web-platform.test,web-platform.test,xn--n8j6ds53lwwkrqhv28a.xn--lve-6lad.not-web-platform.test,xn--lve-6lad.www.web-platform.test,www.www.web-platform.test,www1.xn--lve-6lad.web-platform.test,xn--lve-6lad.www2.web-platform.test,www.www2.web-platform.test,www2.www.web-platform.test,xn--n8j6ds53lwwkrqhv28a.www2.not-web-platform.test,www1.www.web-platform.test,www.xn--lve-6lad.web-platform.test,www.www.not-web-platform.test,xn--lve-6lad.xn--lve-6lad.web-platform.test,xn--lve-6lad.www1.not-web-platform.test,www1.www1.not-web-platform.test,xn--n8j6ds53lwwkrqhv28a.xn--n8j6ds53lwwkrqhv28a.not-web-platform.test,xn--n8j6ds53lwwkrqhv28a.www2.web-platform.test,xn--n8j6ds53lwwkrqhv28a.www1.not-web-platform.test,www2.www2.web-platform.test,xn--lve-6lad.web-platform.test,xn--n8j6ds53lwwkrqhv28a.web-platform.test,xn--n8j6ds53lwwkrqhv28a.www.web-platform.test,xn--lve-6lad.not-web-platform.test,www2.xn--n8j6ds53lwwkrqhv28a.not-web-platform.test,www2.xn--lve-6lad.not-web-platform.test,xn--n8j6ds53lwwkrqhv28a.xn--lve-6lad.web-platform.test,www1.xn--lve-6lad.not-web-platform.test,www1.web-platform.test,www.www1.web-platform.test,www2.web-platform.test,www.xn--lve-6lad.not-web-platform.test,www.web-platform.test,xn--lve-6lad.xn--n8j6ds53lwwkrqhv28a.web-platform.test,www1.www2.not-web-platform.test,www2.www1.web-platform.test,www.www1.not-web-platform.test,www.xn--n8j6ds53lwwkrqhv28a.web-platform.test,xn--lve-6lad.www2.not-web-platform.test,www2.www.not-web-platform.test"}}}}}
INFO - PID 9040 | 1556121936898 mozrunner::runner INFO Running command: "/builds/worker/workspace/build/application/firefox/firefox" "-marionette" "-foreground" "-no-remote" "-profile" "/tmp/rust_mozprofile.NSxtKb8Yu96o"
INFO - PID 9040 | 1556121936899 geckodriver::marionette DEBUG Waiting 60s to connect to browser on 127.0.0.1:2828
INFO - PID 9040 | 1556121937373 addons.webextension.screenshots@mozilla.org WARN Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid extension permission: mozillaAddons
INFO - PID 9040 | 1556121937374 addons.webextension.screenshots@mozilla.org WARN Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid extension permission: resource://pdf.js/
INFO - PID 9040 | 1556121937374 addons.webextension.screenshots@mozilla.org WARN Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid extension permission: about:reader*

Status: RESOLVED → REOPENED

Resolution: INCOMPLETE → ---

Comment hidden (Intermittent Failures Robot)

Firefox Bug Husbandry Bot

Comment 8

•

5 years ago

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup

Status: REOPENED → RESOLVED

Closed: 6 years ago → 5 years ago

Resolution: --- → INCOMPLETE

Andrei Ciure[:aciure]

Comment 9

•

5 years ago

New occurrence:

https://treeherder.mozilla.org/#/jobs?repo=mozilla-central&resultStatus=testfailed%2Cbusted%2Cexception&classifiedState=unclassified&selectedJob=255178693&searchStr=linux%2Cx64%2Copt%2Cweb%2Cplatform%2Ctests%2Ctest-linux64%2Fopt-web-platform-tests-wdspec-e10s-1%2Cw%28wd1%29

Status: RESOLVED → REOPENED

Resolution: INCOMPLETE → ---

Comment hidden (Intermittent Failures Robot)

Firefox Bug Husbandry Bot

Comment 15

•

5 years ago

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup

Status: REOPENED → RESOLVED

Closed: 5 years ago → 5 years ago

Resolution: --- → INCOMPLETE

Comment hidden (Intermittent Failures Robot)

Cristian Brindusan [:cbrindusan]

Comment 17

•

5 years ago

New occurrence:
https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=278063330&repo=autoland&lineNumber=98789

Status: RESOLVED → REOPENED

Resolution: INCOMPLETE → ---

Comment hidden (Intermittent Failures Robot)

Henrik Skupin [:whimboo][⌚️UTC+1]

Updated

•

4 years ago

Comment 35

•

4 years ago

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

Status: REOPENED → RESOLVED

Closed: 5 years ago → 4 years ago

Resolution: --- → INCOMPLETE

BMO Automation

Comment 36

•

1 year ago

Moving bug to Remote Protocol::Marionette component per bug 1815831.

Component: geckodriver → Marionette

Product: Testing → Remote Protocol