1492259 - Intermittent accessible/tests/browser/events/browser_test_focus_urlbar.js | Test timed out -

Status: RESOLVED DUPLICATE of bug 1818994

(Core :: Disability Access APIs, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: apavel [at] mozilla.com

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

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

15:38:20     INFO - TEST-START | accessible/tests/browser/events/browser_test_focus_urlbar.js
15:38:21     INFO - GECKO(5480) | MEMORY STAT | vsize 1740MB | vsizeMaxContiguous 131465564MB | residentFast 283MB | heapAllocated 134MB
15:38:21     INFO - TEST-OK | accessible/tests/browser/events/browser_test_focus_urlbar.js | took 1192ms
15:38:21     INFO - checking window state
15:38:21     INFO - TEST-START | accessible/tests/browser/events/browser_test_focus_urlbar.js
15:39:06     INFO - TEST-INFO | started process screenshot
15:39:06     INFO - TEST-INFO | screenshot: exit 0
15:39:06     INFO - Buffered messages logged at 15:38:21
15:39:06     INFO - Entering test bound 
15:39:06     INFO - Buffered messages finished
15:39:06     INFO - TEST-UNEXPECTED-FAIL | accessible/tests/browser/events/browser_test_focus_urlbar.js | Test timed out - 
15:39:06     INFO - GECKO(5480) | MEMORY STAT | vsize 1747MB | vsizeMaxContiguous 131465564MB | residentFast 247MB | heapAllocated 86MB
15:39:06     INFO - TEST-OK | accessible/tests/browser/events/browser_test_focus_urlbar.js | took 45072ms
15:39:06     INFO - checking window state
15:39:08     INFO - GECKO(5480) | Completed ShutdownLeaks collections in process 6636
15:39:08     INFO - GECKO(5480) | Completed ShutdownLeaks collections in process 8880
15:39:08     INFO - GECKO(5480) | Completed ShutdownLeaks collections in process 2476
15:39:08     INFO - GECKO(5480) | Completed ShutdownLeaks collections in process 7528
15:39:08     INFO - GECKO(5480) | Completed ShutdownLeaks collections in process 2208
15:39:08     INFO - GECKO(5480) | Completed ShutdownLeaks collections in process 5480
15:39:08     INFO - TEST-START | Shutdown
15:39:08     INFO - Browser Chrome Test Summary
15:39:08     INFO - Passed:  156
15:39:08     INFO - Failed:  1
15:39:08     INFO - Todo:    0
15:39:08     INFO - Mode:    e10s
15:39:08     INFO - *** End BrowserChrome Test Results ***
15:39:08     INFO - GECKO(5480) | JavaScript error: resource://activity-stream/lib/TopSitesFeed.jsm, line 86: TypeError: setting getter-only property "_currentSearchHostname"
15:39:08     INFO - GECKO(5480) | [Parent 5480, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346
15:39:08     INFO - GECKO(5480) | [Child 2208, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346
15:39:08     INFO - GECKO(5480) | [Parent 5480, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346
15:39:08     INFO - GECKO(5480) | [Child 2476, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346
15:39:08     INFO - GECKO(5480) | [Child 2476, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346
15:39:08     INFO - GECKO(5480) | [Parent 5480, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346
15:39:08     INFO - GECKO(5480) | [Parent 5480, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346
15:39:08     INFO - GECKO(5480) | [Child 7528, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346
15:39:08     INFO - GECKO(5480) | [Parent 5480, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346
15:39:08     INFO - GECKO(5480) | [Parent 5480, Gecko_IOThread] WARNING: file z:/build/build/src/ipc/chromium/src/base/process_util_win.cc, line 188
15:39:08     INFO - GECKO(5480) | 1537285148758	Marionette	DEBUG	Received observer notification xpcom-will-shutdown
15:39:08     INFO - GECKO(5480) | 1537285148758	Marionette	INFO	Stopped listening on port 2828
15:39:08     INFO - GECKO(5480) | 1537285148758	Marionette	DEBUG	Remote service is inactive
15:39:09     INFO - TEST-INFO | Main app process: exit 0
15:39:09     INFO - runtests.py | Application ran for: 0:00:54.533000
15:39:09     INFO - zombiecheck | Reading PID log: c:\users\task_1537282787\appdata\local\temp\tmplgm2mjpidlog
15:39:09     INFO - ==> process 5480 launched child process 8880 ("Z:\task_1537282787\build\application\firefox\firefox.exe" -contentproc --channel="5480.0.1051658218\917103098" -childID 1 -isForBrowser -prefsHandle 2524 -prefMapHandle 2596 -prefsLen 1 -prefMapSize 186866 -schedulerPrefs 0001,2 -parentBuildID 20180918143834 -greomni "Z:\task_1537282787\build\application\firefox\omni.ja" -appomni "Z:\task_1537282787\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1537282787\build\application\firefox\browser" - 5480 "\\.\pipe\gecko-crash-server-pipe.5480" 1736 tab)
15:39:09     INFO - ==> process 5480 launched child process 2208 ("Z:\task_1537282787\build\application\firefox\firefox.exe" -contentproc --channel="5480.6.514412466\839560857" -childID 2 -isForBrowser -prefsHandle 2816 -prefMapHandle 2616 -prefsLen 1 -prefMapSize 186866 -schedulerPrefs 0001,2 -parentBuildID 20180918143834 -greomni "Z:\task_1537282787\build\application\firefox\omni.ja" -appomni "Z:\task_1537282787\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1537282787\build\application\firefox\browser" - 5480 "\\.\pipe\gecko-crash-server-pipe.5480" 2620 tab)
15:39:09     INFO - ==> process 5480 launched child process 7528 ("Z:\task_1537282787\build\application\firefox\firefox.exe" -contentproc --channel="5480.12.1226866219\39108464" -childID 3 -isForBrowser -prefsHandle 3004 -prefMapHandle 3008 -prefsLen 41 -prefMapSize 186866 -schedulerPrefs 0001,2 -parentBuildID 20180918143834 -greomni "Z:\task_1537282787\build\application\firefox\omni.ja" -appomni "Z:\task_1537282787\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1537282787\build\application\firefox\browser" - 5480 "\\.\pipe\gecko-crash-server-pipe.5480" 2672 tab)
15:39:09     INFO - ==> process 5480 launched child process 6636 ("Z:\task_1537282787\build\application\firefox\firefox.exe" -contentproc --channel="5480.18.1804126553\703568526" -childID 4 -isForBrowser -prefsHandle 3936 -prefMapHandle 3940 -prefsLen 6581 -prefMapSize 186866 -schedulerPrefs 0001,2 -parentBuildID 20180918143834 -greomni "Z:\task_1537282787\build\application\firefox\omni.ja" -appomni "Z:\task_1537282787\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1537282787\build\application\firefox\browser" - 5480 "\\.\pipe\gecko-crash-server-pipe.5480" 3964 tab)
15:39:09     INFO - ==> process 5480 launched child process 2476 ("Z:\task_1537282787\build\application\firefox\firefox.exe" -contentproc --channel="5480.24.2036073296\1805930537" -childID 5 -isForBrowser -prefsHandle 4496 -prefMapHandle 4508 -prefsLen 6581 -prefMapSize 186866 -schedulerPrefs 0001,2 -parentBuildID 20180918143834 -greomni "Z:\task_1537282787\build\application\firefox\omni.ja" -appomni "Z:\task_1537282787\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1537282787\build\application\firefox\browser" - 5480 "\\.\pipe\gecko-crash-server-pipe.5480" 4520 tab)
15:39:09     INFO - zombiecheck | Checking for orphan process with PID: 8880
15:39:09     INFO - zombiecheck | Checking for orphan process with PID: 2208
15:39:09     INFO - zombiecheck | Checking for orphan process with PID: 2476
15:39:09     INFO - zombiecheck | Checking for orphan process with PID: 6636
15:39:09     INFO - zombiecheck | Checking for orphan process with PID: 7528
15:39:09     INFO - Stopping web server
15:39:09     INFO - Stopping web socket server
15:39:09     INFO - Stopping ssltunnel
15:39:09  WARNING - leakcheck | refcount logging is off, so leaks can't be detected!
15:39:09     INFO - runtests.py | Running tests: end.
15:39:09     INFO - Buffered messages finished
15:39:09     INFO -  TEST-INFO | checking window state
15:39:09     INFO -  Browser Chrome Test Summary
15:39:09     INFO -  	Passed: 156
15:39:09     INFO -  	Failed: 1
15:39:09     INFO -  	Todo: 0
15:39:09     INFO -  	Mode: e10s
15:39:09     INFO -  *** End BrowserChrome Test Results ***
15:39:09     INFO - Buffered messages finished
15:39:09     INFO - SUITE-END | took 55s

Comment 1

[Marco Zehe (:MarcoZ)]

Hm, is that really in our test? The log seems to suggest that something temporarily got broken in the activity-stream JSM files, which bug 1331755 never touched. That's the bug that introduced this test file. Jamie, ever seen this one while you were struggling with the tests for this?

Comment 2

[James Teh [:Jamie]]

(In reply to Marco Zehe (:MarcoZ) from comment #1)
> Hm, is that really in our test? The log seems to suggest that something
> temporarily got broken in the activity-stream JSM files

It does suggest that, but the test did time out here, which is the relevant part to us:

> 15:38:21     INFO - TEST-START |
> accessible/tests/browser/events/browser_test_focus_urlbar.js
> 15:39:06     INFO - TEST-INFO | started process screenshot
> 15:39:06     INFO - TEST-INFO | screenshot: exit 0
> 15:39:06     INFO - Buffered messages logged at 15:38:21
> 15:39:06     INFO - Entering test bound 
> 15:39:06     INFO - Buffered messages finished
> 15:39:06     INFO - TEST-UNEXPECTED-FAIL |
> accessible/tests/browser/events/browser_test_focus_urlbar.js | Test timed
> out - 

We're seeing the buffered output here, so there should have been a lot more output from the test after "Entering test bound". That means the test hung right at the start. I definitely saw that intermittently while I was stress testing this test.

As far as I can tell, it's not actually a bug in this test, but rather, a bug in our a11y engine. The browser test harness gets stuck waiting for a doc load complete event, which for some reason the a11y engine intermittently fails to fire. Yura has run into this as well and has plans to look into this after he's finished dealing with some high priority work.

In an ideal world, we want to fix the a11y engine so it fires that event reliably. However, I suspect that's going to be super painful to debug and fix. Failing a quick fix for that, in the interim, we want to hack around this in the test harness (e.g. watching for the show event and then polling for the disappearance of the busy state) so our tests are more reliable.

Comment 3

[James Teh [:Jamie]]

Yura, I'm assigning this to you for now since you already started looking into it, but feel free to drop it if higher priority work swamps you.

Comment 4

[Intermittent Failures Robot]

8 failures in 2902 pushes (0.003 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:
* autoland: 1
* mozilla-inbound: 1
* try: 2
* mozilla-central: 4

Platform breakdown:
* windows10-64-nightly: 1
* windows10-64: 4
* windows10-64-devedition: 1
* windows7-32: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1492259&startday=2018-09-17&endday=2018-09-23&tree=all

Comment 5

[Yura Zenevich [:yzen] (please use "needinfo")]

Attachment: Bug 1492259 - made shared test infrastructure more resilient to various doc load events not firing. r=jteh

MozReview-Commit-ID: 2iMpprlR1gs

Comment 6

[Yura Zenevich [:yzen] (please use "needinfo")]

Jamie, could you see if this fails locally with --verify or --run-until-faulure?

Comment 7

[James Teh [:Jamie]]

Ug. This is still failing with --run-until-failure. It looks like we don't get any events from the document at all, which is worrying and means we can't get an accessible for a remote document. Full event log below:

 0:22.16 INFO Wait for event
 0:37.07 GECKO(18276) Event type: document load stopped. Target: [role: document, address: [xpconnect wrapped (nsISupports, nsIAccessible, nsIAccessibleDocument)]]
 0:37.07 GECKO(18276) Event type: document load stopped. Target: [role: document, address: [xpconnect wrapped (nsISupports, nsIAccessible, nsIAccessibleDocument)]]
 0:37.07 GECKO(18276) Event type: document load stopped. Target: [role: document, address: [xpconnect wrapped (nsISupports, nsIAccessible, nsIAccessibleDocument)]]
 0:37.07 GECKO(18276) Event type: focus. Target: [role: document, address: [xpconnect wrapped (nsISupports, nsIAccessible, nsIAccessibleDocument)]]
 0:37.08 GECKO(18276) Event type: focus. Target: [role: document, address: [xpconnect wrapped (nsISupports, nsIAccessible, nsIAccessibleDocument)]]
 0:37.10 GECKO(18276) Event type: focus. Target: [role: document, address: [xpconnect wrapped (nsISupports, nsIAccessible, nsIAccessibleDocument)]]
 0:37.10 GECKO(18276) Event type: hide. Target: [DOM node id: statuspanel-label, role: statusbar, address: [xpconnect wrapped nsIAccessible]]
 0:37.10 GECKO(18276) Event type: hide. Target: [DOM node id: statuspanel-label, role: statusbar, address: [xpconnect wrapped nsIAccessible]]
 0:37.10 GECKO(18276) Event type: hide. Target: [DOM node id: statuspanel-label, role: statusbar, address: [xpconnect wrapped nsIAccessible]]
 0:37.11 GECKO(18276) Event type: hide. Target: [role: pagetab, name: 'New Tab', address: [xpconnect wrapped nsIAccessible]]
 0:37.12 GECKO(18276) Event type: hide. Target: [role: pagetab, name: 'New Tab', address: [xpconnect wrapped nsIAccessible]]
 0:37.14 GECKO(18276) Event type: hide. Target: [role: pagetab, name: 'New Tab', address: [xpconnect wrapped nsIAccessible]]
 0:37.17 GECKO(18276) Event type: show. Target: [DOM node id: panel-5-25, role: propertypage, address: [xpconnect wrapped nsIAccessible]]
 0:37.19 GECKO(18276) Event type: show. Target: [DOM node id: panel-5-25, role: propertypage, address: [xpconnect wrapped nsIAccessible]]
 0:37.21 GECKO(18276) Event type: show. Target: [DOM node id: panel-5-25, role: propertypage, address: [xpconnect wrapped nsIAccessible]]
 0:37.24 GECKO(18276) Event type: show. Target: [role: pagetab, name: 'New Tab', address: [xpconnect wrapped nsIAccessible]]
 0:37.25 GECKO(18276) Event type: show. Target: [role: pagetab, name: 'New Tab', address: [xpconnect wrapped nsIAccessible]]
 0:37.26 GECKO(18276) Event type: show. Target: [role: pagetab, name: 'New Tab', address: [xpconnect wrapped nsIAccessible]]
 0:37.29 GECKO(18276) Event type: show. Target: [role: pagetab, name: 'Accessibility Test', address: [xpconnect wrapped nsIAccessible]]
 0:37.31 GECKO(18276) Event type: show. Target: [role: pagetab, name: 'Accessibility Test', address: [xpconnect wrapped nsIAccessible]]
 0:37.33 GECKO(18276) Event type: show. Target: [role: pagetab, name: 'Accessibility Test', address: [xpconnect wrapped nsIAccessible]]
 0:37.35 GECKO(18276) Event type: show. Target: [DOM node id: pageActionButton, role: pushbutton, name: 'Page actions', address: [xpconnect wrapped nsIAccessible]]
 0:37.39 GECKO(18276) Event type: show. Target: [DOM node id: pageActionButton, role: pushbutton, name: 'Page actions', address: [xpconnect wrapped nsIAccessible]]
 0:37.41 GECKO(18276) Event type: show. Target: [DOM node id: pageActionButton, role: pushbutton, name: 'Page actions', address: [xpconnect wrapped nsIAccessible]]
 0:37.43 GECKO(18276) Event type: show. Target: [DOM node id: pocket-button, role: pushbutton, name: 'Save to Pocket', address: [xpconnect wrapped nsIAccessible]]
 0:37.46 GECKO(18276) Event type: show. Target: [DOM node id: pocket-button, role: pushbutton, name: 'Save to Pocket', address: [xpconnect wrapped nsIAccessible]]
 0:37.48 GECKO(18276) Event type: show. Target: [DOM node id: pocket-button, role: pushbutton, name: 'Save to Pocket', address: [xpconnect wrapped nsIAccessible]]
 0:37.50 GECKO(18276) Event type: show. Target: [DOM node id: star-button, role: pushbutton, name: 'Bookmark this page (Ctrl+D)', address: [xpconnect wrapped nsIAccessible]]
 0:37.53 GECKO(18276) Event type: show. Target: [DOM node id: star-button, role: pushbutton, name: 'Bookmark this page (Ctrl+D)', address: [xpconnect wrapped nsIAccessible]]
 0:37.56 GECKO(18276) Event type: show. Target: [DOM node id: star-button, role: pushbutton, name: 'Bookmark this page (Ctrl+D)', address: [xpconnect wrapped nsIAccessible]]
 0:37.57 GECKO(18276) Event type: show. Target: [role: text leaf, name: 'data:text/html;charset=utf-8;base64,PGh0bWw+CiAgI...AgPGJvZHkgaWQ9ImJvZHkiPjwvYm9keT4KICAgIDwvaHRtbD4=', address: [xpconnect wrapped nsIAccessible]]
 0:37.58 GECKO(18276) Event type: show. Target: [role: text leaf, name: 'data:text/html;charset=utf-8;base64,PGh0bWw+CiAgI...AgPGJvZHkgaWQ9ImJvZHkiPjwvYm9keT4KICAgIDwvaHRtbD4=', address: [xpconnect wrapped nsIAccessible]]
 0:37.60 GECKO(18276) Event type: show. Target: [role: text leaf, name: 'data:text/html;charset=utf-8;base64,PGh0bWw+CiAgI...AgPGJvZHkgaWQ9ImJvZHkiPjwvYm9keT4KICAgIDwvaHRtbD4=', address: [xpconnect wrapped nsIAccessible]]
 0:37.62 GECKO(18276) Event type: text inserted, start: 0, length: 236, inserted text: data:text/html;charset=utf-8;base64,PGh0bWw+CiAgICAgIDxoZWFkPgogICAgICAgIDxtZXRhIGNoYXJzZXQ9InV0Zi04Ii8+CiAgICAgICAgPHRpdGxlPkFjY2Vzc2liaWxpdHkgVGVzdDwvdGl0bGU+CiAgICAgIDwvaGVhZD4KICAgICAgPGJvZHkgaWQ9ImJvZHkiPjwvYm9keT4KICAgIDwvaHRtbD4=. Target: [role: entry, name: 'Search with Google or enter address', address: [xpconnect wrapped nsIAccessible]]
 0:37.65 GECKO(18276) Event type: text inserted, start: 0, length: 236, inserted text: data:text/html;charset=utf-8;base64,PGh0bWw+CiAgICAgIDxoZWFkPgogICAgICAgIDxtZXRhIGNoYXJzZXQ9InV0Zi04Ii8+CiAgICAgICAgPHRpdGxlPkFjY2Vzc2liaWxpdHkgVGVzdDwvdGl0bGU+CiAgICAgIDwvaGVhZD4KICAgICAgPGJvZHkgaWQ9ImJvZHkiPjwvYm9keT4KICAgIDwvaHRtbD4=. Target: [role: entry, name: 'Search with Google or enter address', address: [xpconnect wrapped nsIAccessible]]
 0:37.67 GECKO(18276) Event type: text inserted, start: 0, length: 236, inserted text: data:text/html;charset=utf-8;base64,PGh0bWw+CiAgICAgIDxoZWFkPgogICAgICAgIDxtZXRhIGNoYXJzZXQ9InV0Zi04Ii8+CiAgICAgICAgPHRpdGxlPkFjY2Vzc2liaWxpdHkgVGVzdDwvdGl0bGU+CiAgICAgIDwvaGVhZD4KICAgICAgPGJvZHkgaWQ9ImJvZHkiPjwvYm9keT4KICAgIDwvaHRtbD4=. Target: [role: entry, name: 'Search with Google or enter address', address: [xpconnect wrapped nsIAccessible]]
 0:37.70 GECKO(18276) Event type: reorder. Target: [DOM node id: tabbrowser-tabs, role: pagetablist, address: [xpconnect wrapped nsIAccessible]]
 0:37.72 GECKO(18276) Event type: reorder. Target: [DOM node id: tabbrowser-tabs, role: pagetablist, address: [xpconnect wrapped nsIAccessible]]
 0:37.74 GECKO(18276) Event type: reorder. Target: [DOM node id: tabbrowser-tabs, role: pagetablist, address: [xpconnect wrapped nsIAccessible]]
 0:37.77 GECKO(18276) Event type: reorder. Target: [DOM node id: tabbrowser-tabpanels, role: pane, address: [xpconnect wrapped nsIAccessible]]
 0:37.78 GECKO(18276) Event type: reorder. Target: [DOM node id: tabbrowser-tabpanels, role: pane, address: [xpconnect wrapped nsIAccessible]]
 0:37.79 GECKO(18276) Event type: reorder. Target: [DOM node id: tabbrowser-tabpanels, role: pane, address: [xpconnect wrapped nsIAccessible]]
 0:37.81 GECKO(18276) Event type: reorder. Target: [DOM node id: urlbar, role: autocomplete, address: [xpconnect wrapped nsIAccessible]]
 0:37.84 GECKO(18276) Event type: reorder. Target: [DOM node id: urlbar, role: autocomplete, address: [xpconnect wrapped nsIAccessible]]
 0:37.86 GECKO(18276) Event type: reorder. Target: [DOM node id: urlbar, role: autocomplete, address: [xpconnect wrapped nsIAccessible]]
 0:37.89 GECKO(18276) Event type: state change, state: enabled, is enabled: true. Target: [DOM node id: reload-button, role: pushbutton, name: 'Reload', address: [xpconnect wrapped nsIAccessible]]
 0:37.92 GECKO(18276) Event type: state change, state: enabled, is enabled: true. Target: [DOM node id: reload-button, role: pushbutton, name: 'Reload', address: [xpconnect wrapped nsIAccessible]]
 0:37.94 GECKO(18276) Event type: state change, state: enabled, is enabled: true. Target: [DOM node id: reload-button, role: pushbutton, name: 'Reload', address: [xpconnect wrapped nsIAccessible]]
 0:37.96 GECKO(18276) Event type: state change, state: sensitive, is enabled: true. Target: [DOM node id: reload-button, role: pushbutton, name: 'Reload', address: [xpconnect wrapped nsIAccessible]]
 0:37.99 GECKO(18276) Event type: state change, state: sensitive, is enabled: true. Target: [DOM node id: reload-button, role: pushbutton, name: 'Reload', address: [xpconnect wrapped nsIAccessible]]
 0:38.01 GECKO(18276) Event type: state change, state: sensitive, is enabled: true. Target: [DOM node id: reload-button, role: pushbutton, name: 'Reload', address: [xpconnect wrapped nsIAccessible]]
 0:38.04 GECKO(18276) Event type: name changed. Target: [role: chrome window, name: 'Accessibility Test - Nightly', address: [xpconnect wrapped (nsISupports, nsIAccessible, nsIAccessibleDocument)]]
 0:38.06 GECKO(18276) Event type: name changed. Target: [role: chrome window, name: 'Accessibility Test - Nightly', address: [xpconnect wrapped (nsISupports, nsIAccessible, nsIAccessibleDocument)]]
 0:38.09 GECKO(18276) Event type: name changed. Target: [role: chrome window, name: 'Accessibility Test - Nightly', address: [xpconnect wrapped (nsISupports, nsIAccessible, nsIAccessibleDocument)]]
 0:38.11 GECKO(18276) Event type: text value change. Target: [role: entry, name: 'Search with Google or enter address', address: [xpconnect wrapped nsIAccessible]]
 0:38.13 GECKO(18276) Event type: text value change. Target: [role: entry, name: 'Search with Google or enter address', address: [xpconnect wrapped nsIAccessible]]
 0:38.17 GECKO(18276) Event type: text value change. Target: [role: entry, name: 'Search with Google or enter address', address: [xpconnect wrapped nsIAccessible]]
 0:38.17 GECKO(18276) e10s enabled: true
 0:38.17 GECKO(18276) Actually remote browser: true
 1:07.21 INFO Failed to retrieve MOZ_UPLOAD_DIR env var
 1:07.20 FAIL Test timed out -

Comment 8

[James Teh [:Jamie]]

(In reply to James Teh [:Jamie] from comment #7)
>  0:22.16 INFO Wait for event

Note that this is a log statement I put in locally and occurs just after:

0:22.16 INFO Entering test bound

Sorry; missed that line in my copy/paste.

Comment 9

[Intermittent Failures Robot]

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

Repository breakdown:
* autoland: 1

Platform breakdown:
* linux32: 1

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

Comment 10

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

• linux32: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1492259&startday=2019-02-04&endday=2019-02-10&tree=all

Comment 11

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-inbound: 1

Platform breakdown:

• windows10-64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1492259&startday=2019-03-18&endday=2019-03-24&tree=all

Comment 12

[Intermittent Failures Robot]

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

Repository breakdown:

• try: 1

Platform breakdown:

• linux64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1492259&startday=2019-05-06&endday=2019-05-12&tree=all

Comment 13

[Intermittent Failures Robot]

3 failures in 4658 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• try: 3

Platform breakdown:

• osx-10-10: 2
• linux64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1492259&startday=2019-05-13&endday=2019-05-19&tree=all

Comment 14

[Intermittent Failures Robot]

4 failures in 4730 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• try: 4

Platform breakdown:

• windows10-64: 1
• macosx1010-64: 1
• linux32: 1
• linux64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1492259&startday=2019-05-20&endday=2019-05-26&tree=all

Comment 15

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-beta: 1

Platform breakdown:

• linux64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1492259&startday=2019-06-10&endday=2019-06-16&tree=all

Comment 16

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

• windows10-64-qr: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1492259&startday=2019-07-08&endday=2019-07-14&tree=all

Comment 17

[Intermittent Failures Robot]

5 failures in 4666 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 5

Platform breakdown:

• linux64: 2
• macosx1014-64: 1
• linux64-qr: 1
• windows10-64-qr: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1492259&startday=2019-08-05&endday=2019-08-11&tree=all

Comment 18

[Razvan Maries]

No new occurrences since 17th of October: https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?startday=2019-10-14&endday=2019-10-21&tree=trunk&bug=1492259

Comment 19

[Intermittent Failures Robot]

21 failures in 3964 pushes (0.005 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 21

Platform breakdown:

• linux64: 17
• windows10-64-qr: 1
• linux64-shippable: 1
• windows10-64-shippable-qr: 1
• windows7-32-shippable: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1492259&startday=2019-10-14&endday=2019-10-20&tree=all

Comment 20

[Intermittent Failures Robot]

33 failures in 4004 pushes (0.008 failures/push) were associated with this bug in the last 7 days.

This is the #41 most frequent failure this week.

** This failure happened more than 30 times this week! Resolving this bug is a high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 2 weeks, the affected test(s) may be disabled. **

Repository breakdown:

• try: 33

Platform breakdown:

• linux64: 6
• windows7-32-shippable: 3
• windows10-64-shippable: 3
• windows10-64-shippable-qr: 3
• windows10-64-devedition: 3
• windows7-32-devedition: 3
• windows10-64-qr: 3
• windows10-64: 4
• linux64-devedition: 2
• linux64-shippable: 2
• windows7-32: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1492259&startday=2020-01-20&endday=2020-01-26&tree=all

Comment 21

[Intermittent Failures Robot]

22 failures in 394 pushes (0.056 failures/push) were associated with this bug yesterday.

Repository breakdown:

• try: 22

Platform breakdown:

• windows7-32: 1
• windows10-64: 4
• windows10-64-qr: 2
• linux64-shippable: 2
• linux64-devedition: 2
• windows10-64-devedition: 2
• windows7-32-shippable: 2
• windows7-32-devedition: 2
• windows10-64-shippable: 2
• windows10-64-shippable-qr: 2
• linux64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1492259&startday=2020-01-28&endday=2020-01-28&tree=all

Comment 22

[Andreea Pavel [:apavel]]

Aryx: there are 57 total failures in the last 7 days on:

• linux64 asan and debug
• linux64-devedition opt
• windows10-64 asan and debug
• windows10-64-devedition opt
• windows10-64-qr debug
• windows10-64-shippable opt
• windows7-32 debug
• windows7-32-devedition opt

I looked through the revisions and seems this only fails on betas sims: https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?startday=2020-01-22&endday=2020-01-29&tree=all&bug=1492259

Do we file a beta bug for tracking or use this one?

Comment 23

[Sebastian Hengst [:aryx] (needinfo me if it's about an intermittent or backout)]

These are bug 1611132.

Comment 24

[Andreea Pavel [:apavel]]

(In reply to Sebastian Hengst [:aryx] (needinfo on intermittent or backout) from comment #23)

These are bug 1611132.

Can we change something in the summary or summaries of the bugs so that this doesn't get missclassified again? TH shows just this bug as a suggestion for the failure on try.

Comment 25

[Sebastian Hengst [:aryx] (needinfo me if it's about an intermittent or backout)]

Bug 1611132 lacked the intermittent-failure keyword, it has been added now.

Comment 26

[Andreea Pavel [:apavel]]

(In reply to Sebastian Hengst [:aryx] (needinfo on intermittent or backout) from comment #25)

Bug 1611132 lacked the intermittent-failure keyword, it has been added now.

Awesome, thank you!

Comment 27

[Intermittent Failures Robot]

24 failures in 2190 pushes (0.011 failures/push) were associated with this bug in the last 7 days.

This is the #24 most frequent failure this week.

Repository breakdown:

• try: 24

Platform breakdown:

• linux64-shippable: 3
• linux64: 2
• windows7-32: 1
• windows10-64: 4
• windows10-64-qr: 2
• linux64-devedition: 2
• windows10-64-devedition: 2
• windows10-64-shippable-qr: 2
• windows10-64-shippable: 2
• windows7-32-shippable: 2
• windows7-32-devedition: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1492259&startday=2020-01-27&endday=2020-02-02&tree=all

Comment 28

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

• windows7-32-shippable: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1492259&startday=2020-02-03&endday=2020-02-09&tree=all

Comment 29

[Intermittent Failures Robot]

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

Repository breakdown:

• try: 1

Platform breakdown:

• windows10-64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1492259&startday=2020-02-17&endday=2020-02-23&tree=all

Comment 30

[Intermittent Failures Robot]

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

Repository breakdown:

• try: 1

Platform breakdown:

• windows10-64-qr: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1492259&startday=2020-03-02&endday=2020-03-08&tree=all

Comment 31

[Intermittent Failures Robot]

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

Repository breakdown:

• try: 2

Platform breakdown:

• windows10-64-devedition: 1
• windows10-64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1492259&startday=2020-03-16&endday=2020-03-22&tree=all

Comment 32

[Intermittent Failures Robot]

3 failures in 5015 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• try: 2
• mozilla-beta: 1

Platform breakdown:

• windows10-64: 3

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1492259&startday=2020-03-30&endday=2020-04-05&tree=all

Comment 33

[Intermittent Failures Robot]

3 failures in 4823 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-beta: 1
• try: 2

Platform breakdown:

• windows10-64-qr: 1
• windows10-64: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1492259&startday=2020-04-06&endday=2020-04-12&tree=all

Comment 34

[Intermittent Failures Robot]

7 failures in 4528 pushes (0.002 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-beta: 5
• try: 2

Platform breakdown:

• windows10-64-qr: 2
• windows10-64: 5

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1492259&startday=2020-04-13&endday=2020-04-19&tree=all

Comment 35

[Intermittent Failures Robot]

3 failures in 5582 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-beta: 3

Platform breakdown:

• windows10-64-devedition: 1
• windows7-32: 1
• windows10-64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1492259&startday=2020-04-20&endday=2020-04-26&tree=all

Comment 36

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-release: 1

Platform breakdown:

• windows10-64-qr: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1492259&startday=2020-05-25&endday=2020-05-31&tree=all

Comment 37

[Intermittent Failures Robot]

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

Repository breakdown:

• try: 1

Platform breakdown:

• windows10-64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1492259&startday=2020-06-29&endday=2020-07-05&tree=all

Comment 38

[Intermittent Failures Robot]

17 failures in 3683 pushes (0.005 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 8
• mozilla-central: 9

Platform and build breakdown:

• windows10-64-qr: 8
  • opt: 8
• windows10-64-shippable-qr: 9
  • opt: 9

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1492259&startday=2021-03-01&endday=2021-03-07&tree=all

Comment 39

[Intermittent Failures Robot]

17 failures in 4622 pushes (0.004 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 7
• autoland: 9
• mozilla-beta: 1

Platform and build breakdown:

• windows10-64-shippable-qr: 8
  • opt: 8
• windows10-64-qr: 9
  • opt: 9

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1492259&startday=2021-03-08&endday=2021-03-14&tree=all

Comment 40

[Sandor Molnar[:smolnar]]

There have been a total of 32 failures in the last 7 days on:

• windows10-64-qr opt
• windows10-64-shippable-qr opt

Recent failure log

INFO - TEST-START | accessible/tests/browser/events/browser_test_focus_urlbar.js
[task 2021-03-20T10:33:16.515Z] 10:33:16 INFO - TEST-INFO | started process screenshot
[task 2021-03-20T10:33:16.576Z] 10:33:16 INFO - TEST-INFO | screenshot: exit 0
[task 2021-03-20T10:33:16.576Z] 10:33:16 INFO - Buffered messages logged at 10:32:31
[task 2021-03-20T10:33:16.576Z] 10:33:16 INFO - Entering test bound
[task 2021-03-20T10:33:16.576Z] 10:33:16 INFO - TEST-PASS | accessible/tests/browser/events/browser_test_focus_urlbar.js | Actually remote browser -
[task 2021-03-20T10:33:16.577Z] 10:33:16 INFO - Buffered messages finished
[task 2021-03-20T10:33:16.577Z] 10:33:16 INFO - TEST-UNEXPECTED-FAIL | accessible/tests/browser/events/browser_test_focus_urlbar.js | Test timed out -
[task 2021-03-20T10:33:16.577Z] 10:33:16 INFO - GECKO(1848) | MEMORY STAT | vsize 2104134MB | vsizeMaxContiguous 72017628MB | residentFast 215MB | heapAllocated 75MB
[task 2021-03-20T10:33:16.577Z] 10:33:16 INFO - TEST-OK | accessible/tests/browser/events/browser_test_focus_urlbar.js | took 45086ms
[task 2021-03-20T10:33:16.577Z] 10:33:16 INFO - checking window state
[task 2021-03-20T10:33:16.597Z] 10:33:16 INFO - TEST-START | accessible/tests/browser/events/browser_test_scrolling.js

Comment 41

[Andreea Pavel [:apavel]]

Jamie, please take a look.

Comment 42

[Intermittent Failures Robot]

30 failures in 4257 pushes (0.007 failures/push) were associated with this bug in the last 7 days.

This is the #37 most frequent failure this week.

** This failure happened more than 30 times this week! Resolving this bug is a high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 2 weeks, the affected test(s) may be disabled. **

Repository breakdown:

• autoland: 11
• mozilla-central: 19

Platform and build breakdown:

• windows10-64-qr: 11
  • opt: 11
• windows10-64-shippable-qr: 19
  • opt: 19

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1492259&startday=2021-03-15&endday=2021-03-21&tree=all

Comment 43

[James Teh [:Jamie]]

I suspect this is the same failure we're seeing in bug 1652192, but despite spending days on this, I can't fathom what's going wrong and I can't reproduce it locally nor on try. I'll keep thinking on it, but at this stage, I think we might need to just disable this on Win10 QR.

Comment 44

[Atila Butkovits]

Attachment: Bug 1492259 - disable browser_test_focus_urlbar.js on Win10_QR for frequent failures. r=#intermittent-reviewers

Comment 45

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/d586fdb65f78
disable browser_test_focus_urlbar.js on Win10_QR for frequent failures. r=intermittent-reviewers,jmaher DONTBUILD

Comment 46

[Natalia Csoregi [:nataliaCs]]

https://hg.mozilla.org/mozilla-central/rev/d586fdb65f78

Comment 47

[Intermittent Failures Robot]

14 failures in 3967 pushes (0.004 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 4
• autoland: 2
• try: 8

Platform and build breakdown:

• windows10-64-shippable-qr: 12
  • opt: 12
• windows10-64-qr: 2
  • opt: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1492259&startday=2021-03-22&endday=2021-03-28&tree=all

Comment 48

[Morgan Reschenberg [:morgan]]

:Jamie I can't grab screenshots from any of these logs, I think because they're over a month old and artifacts get deleted. Is there a way to re-run things? I tried retriggering the job on treeherder, but it gives me an error saying "Wrong version of actions.json, unable to continue"

Comment 49

[Morgan Reschenberg [:morgan]]

Nevermind, found a recent enough log set :)

Comment 50

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• windows7-32-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1492259&startday=2022-08-01&endday=2022-08-07&tree=all