1665250 - Intermittent Wd | test_no_browsing_context - AssertionError: assert 'no such element' == 'no such window'

Status: RESOLVED FIXED

(Remote Protocol :: Marionette, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: nbeleuzu [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=315796440&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/R5T0rgYlQl-7f-FnLUhRRw/runs/0/artifacts/public/logs/live_backing.log

---

[task 2020-09-16T00:11:19.040Z] 00:11:19     INFO - TEST-PASS | /webdriver/tests/find_element/find.py | test_no_top_browsing_context 
[task 2020-09-16T00:11:19.040Z] 00:11:19     INFO - TEST-UNEXPECTED-FAIL | /webdriver/tests/find_element/find.py | test_no_browsing_context - AssertionError: assert 'no such element' == 'no such window'
[task 2020-09-16T00:11:19.040Z] 00:11:19     INFO - session = <Session 36406b03-77cb-3a4b-81ed-8963b159f027>, closed_frame = None
[task 2020-09-16T00:11:19.040Z] 00:11:19     INFO - 
[task 2020-09-16T00:11:19.041Z] 00:11:19     INFO -     def test_no_browsing_context(session, closed_frame):
[task 2020-09-16T00:11:19.041Z] 00:11:19     INFO -         response = find_element(session, "css selector", "foo")
[task 2020-09-16T00:11:19.041Z] 00:11:19     INFO - >       assert_error(response, "no such window")
[task 2020-09-16T00:11:19.041Z] 00:11:19     INFO - 
[task 2020-09-16T00:11:19.041Z] 00:11:19     INFO - closed_frame = None
[task 2020-09-16T00:11:19.041Z] 00:11:19     INFO - response   = <Response status=404 error=<NoSuchElementException http_status=404>>
[task 2020-09-16T00:11:19.041Z] 00:11:19     INFO - session    = <Session 36406b03-77cb-3a4b-81ed-8963b159f027>
[task 2020-09-16T00:11:19.041Z] 00:11:19     INFO - 
[task 2020-09-16T00:11:19.041Z] 00:11:19     INFO - tests/web-platform/tests/webdriver/tests/find_element/find.py:28: 
[task 2020-09-16T00:11:19.041Z] 00:11:19     INFO - _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ 
[task 2020-09-16T00:11:19.042Z] 00:11:19     INFO - 
[task 2020-09-16T00:11:19.042Z] 00:11:19     INFO - response = <Response status=404 error=<NoSuchElementException http_status=404>>
[task 2020-09-16T00:11:19.042Z] 00:11:19     INFO - error_code = 'no such window'
[task 2020-09-16T00:11:19.042Z] 00:11:19     INFO - 
[task 2020-09-16T00:11:19.042Z] 00:11:19     INFO -     def assert_error(response, error_code):
[task 2020-09-16T00:11:19.042Z] 00:11:19     INFO -         """
[task 2020-09-16T00:11:19.042Z] 00:11:19     INFO -         Verify that the provided webdriver.Response instance described
[task 2020-09-16T00:11:19.042Z] 00:11:19     INFO -         a valid error response as defined by `dfn-send-an-error` and
[task 2020-09-16T00:11:19.042Z] 00:11:19     INFO -         the provided error code.
[task 2020-09-16T00:11:19.042Z] 00:11:19     INFO -     
[task 2020-09-16T00:11:19.043Z] 00:11:19     INFO -         :param response: ``webdriver.Response`` instance.
[task 2020-09-16T00:11:19.043Z] 00:11:19     INFO -         :param error_code: String value of the expected error code
[task 2020-09-16T00:11:19.043Z] 00:11:19     INFO -         """
[task 2020-09-16T00:11:19.043Z] 00:11:19     INFO -         assert response.status == errors[error_code]
[task 2020-09-16T00:11:19.043Z] 00:11:19     INFO -         assert "value" in response.body
[task 2020-09-16T00:11:19.043Z] 00:11:19     INFO - >       assert response.body["value"]["error"] == error_code
[task 2020-09-16T00:11:19.043Z] 00:11:19     INFO - E       AssertionError: assert 'no such element' == 'no such window'
[task 2020-09-16T00:11:19.043Z] 00:11:19     INFO - E         - no such element
[task 2020-09-16T00:11:19.043Z] 00:11:19     INFO - E         + no such window
[task 2020-09-16T00:11:19.043Z] 00:11:19     INFO - 
[task 2020-09-16T00:11:19.043Z] 00:11:19     INFO - error_code = 'no such window'
[task 2020-09-16T00:11:19.043Z] 00:11:19     INFO - response   = <Response status=404 error=<NoSuchElementException http_status=404>>
[task 2020-09-16T00:11:19.043Z] 00:11:19     INFO - 
[task 2020-09-16T00:11:19.044Z] 00:11:19     INFO - tests/web-platform/tests/webdriver/tests/support/asserts.py:54: AssertionError
[task 2020-09-16T00:11:19.055Z] 00:11:19     INFO - ....................................
[task 2020-09-16T00:11:19.055Z] 00:11:19     INFO - TEST-OK | /webdriver/tests/find_element/find.py | took 16852ms```

Comment 1

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

As it looks like WebDriver:ElementClick actually didn't cause a click event and as such the sub frame has not been closed:

2020-09-16T00:11:10.498Z] 00:11:10     INFO - PID 3345 | 1600215070489	Marionette	DEBUG	0 -> [0,52,"WebDriver:ElementClick",{"id":"1e5f2637-1b58-8140-9273-74c286a479da"}]
[task 2020-09-16T00:11:10.954Z] 00:11:10     INFO - PID 3345 | 1600215070939	Marionette	TRACE	Canceled page load listener because no navigation has been detected
[task 2020-09-16T00:11:10.954Z] 00:11:10     INFO - PID 3345 | 1600215070939	Marionette	DEBUG	0 <- [1,52,null,{"value":null}]
[task 2020-09-16T00:11:10.955Z] 00:11:10     INFO - PID 3345 | 1600215070944	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2020-09-16T00:11:10.955Z] 00:11:10     INFO - PID 3345 | 1600215070947	webdriver::server	DEBUG	-> POST /session/36406b03-77cb-3a4b-81ed-8963b159f027/element {"using": "css selector", "value": "foo"}

It's not particular a regression from bug 1493108, but it added new tests, and this test is such one. Also it failed once so far, so lets see how often it happens.

Comment 2

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

Should there be one bug for this assertion? See also bug 1665695 and bug 1665801.

Comment 6

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 5
• try: 2
• mozilla-central: 2

Platform breakdown:

• macosx1014-64: 1
• linux1804-64: 3
• linux1804-64-qr: 2
• windows10-64: 2
• linux1804-64-ccov: 1

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

Comment 7

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

Actually it most likely depends on a fix for bug 1666204.

Comment 8

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

Checking the log again this is most likely a problem with a delayed click due to the synchronous capabilities update with the parent process. This has been removed with the patch on bug 1394354, and as such this failure should no longer happen.

Comment 9

[Intermittent Failures Robot]

23 failures in 4019 pushes (0.006 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 17
• mozilla-central: 6

Platform breakdown:

• macosx1014-64: 1
• linux1804-64-asan: 3
• windows7-32: 2
• linux1804-64-qr: 11
• linux1804-64-shippable-qr: 4
• linux1804-64-shippable: 1
• linux1804-64-ccov: 1

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

Comment 11

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

After the recent landings that fixed races this seems to mostly only happen for Fission jobs.

Comment 12

[Chris Peterson [:cpeterson]]

Tracking for Fission Beta milestone (M7).

Comment 13

[Intermittent Failures Robot]

39 failures in 4195 pushes (0.009 failures/push) were associated with this bug in the last 7 days.

This is the #24 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: 33
• try: 2
• mozilla-central: 4

Platform breakdown:

• linux1804-64-shippable-qr: 12
• windows7-32: 1
• linux1804-64-qr: 19
• linux1804-64-asan: 3
• macosx1014-64: 1
• linux1804-64-ccov: 2
• linux1804-64: 1

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

Comment 14

[Andreea Pavel [:apavel]]

There are 73 total failures in the last 7 days on

• linux1804-64-asan opt
• linux1804-64-ccov opt
• linux1804-64-qr opt

Recent failure log: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=318208611&repo=autoland&lineNumber=71626

[task 2020-10-09T19:15:26.599Z] 19:15:26 INFO - TEST-START | /webdriver/tests/get_element_css_value/get.py
[task 2020-10-09T19:15:26.796Z] 19:15:26 INFO - STDOUT: ============================= test session starts ==============================
[task 2020-10-09T19:15:26.796Z] 19:15:26 INFO - STDOUT: platform linux2 -- Python 2.7.17, pytest-unknown, py-1.5.2, pluggy-0.5.3.dev -- /builds/worker/workspace/build/venv/bin/python
[task 2020-10-09T19:15:26.797Z] 19:15:26 INFO - STDOUT: rootdir: /builds/worker/workspace/build, inifile:
[task 2020-10-09T19:15:26.800Z] 19:15:26 INFO - STDOUT: collecting ...
[task 2020-10-09T19:15:26.805Z] 19:15:26 INFO - STDOUT: collected 6 items
[task 2020-10-09T19:15:26.806Z] 19:15:26 INFO - STDOUT: tests/web-platform/tests/webdriver/tests/get_element_css_value/get.py::test_no_top_browsing_context
[task 2020-10-09T19:15:26.816Z] 19:15:26 INFO - PID 17077 | 1602270926805 webdriver::server DEBUG -> POST /session {"capabilities": {"alwaysMatch": {"moz:firefoxOptions": {"binary": "/builds/worker/workspace/build/application/firefox/firefox", "prefs": {"dom.serviceWorkers.parent_intercept": true, "media.peerconnection.mtransport_process": false, "layers.d3d11.enable-blacklist": false, "toolkit.asyncshutdown.log": true, "fission.autostart": true, "network.dns.localDomains": "www1.xn--n8j6ds53lwwkrqhv28a.web-platform.test,op88.web-platform.test,op36.not-web-platform.test,op53.not-web-platform.test,op50.not-web-platform.test,xn--lve-6lad.www.web-platform.test,op98.web-platform.test,op24.not-web-platform.test,op31.not-web-platform.test,op95.not-web-platform.test,op85.web-platform.test,op83.not-web-platform.test,www2.not-web-platform.test,xn--lve-6lad.www.not-web-platform.test,op73.not-web-platform.test,op8.web-platform.test,www2.www2.not-web-platform.test,op89.web-platform.test,op66.web-platform.test,xn--lve-6lad.web-platform.test,op19.not-web-platform.test,www1.www2.web-platform.test,op72.web-platform.test,op24.web-platform.test,op21.not-web-platform.test,xn--lve-6lad.not-web-platform.test,op41.web-platform.test,op79.web-platform.test,op81.not-web-platform.test,op70.not-web-platform.test,xn--n8j6ds53lwwkrqhv28a.xn--lve-6lad.not-web-platform.test,op78.not-web-platform.test,op6.not-web-platform.test,www1.www.not-web-platform.test,op40.not-web-platform.test,op25.not-web-platform.test,op3.not-web-platform.test,op65.not-web-platform.test,op91.web-platform.test,www.www2.web-platform.test,op80.not-web-platform.test,op59.web-platform.test,op52.not-web-platform.test,xn--lve-6lad.xn--lve-6lad.web-platform.test,op68.not-web-platform.test,op45.not-web-platform.test,op71.not-web-platform.test,op72.not-web-platform.test,xn--n8j6ds53lwwkrqhv28a.www2.web-platform.test,op39.web-platform.test,op90.not-web-platform.test,op60.web-platform.test,op58.web-platform.test,op28.web-platform.test,www1.web-platform.test,xn--n8j6ds53lwwkrqhv28a.xn--lve-6lad.web-platform.test,op14.web-platform.test,op89.not-web-platform.test,op69.web-platform.test,op49.not-web-platform.test,op40.web-platform.test,op2.not-web-platform.test,op5.not-web-platform.test,www.www2.not-web-platform.test,op77.not-web-platform.test,www.xn--n8j6ds53lwwkrqhv28a.web-platform.test,op7.web-platform.test,op74.web-platform.test,op79.not-web-platform.test,op82.not-web-platform.test,www.www1.web-platform.test,op12.not-web-platform.test,op39.not-web-platform.test,op31.web-platform.test,www.not-web-platform.test,www.www.not-web-platform.test,op44.not-web-platform.test,www1.not-web-platform.test,xn--n8j6ds53lwwkrqhv28a.www1.web-platform.test,op58.not-web-platform.test,op14.not-web-platform.test,op30.not-web-platform.test,op62.not-web-platform.test,op61.not-web-platform.test,op92.not-web-platform.test,www2.xn--lve-6lad.web-platform.test,op29.not-web-platform.test,op18.web-platform.test,op73.web-platform.test,xn--n8j6ds53lwwkrqhv28a.xn--n8j6ds53lwwkrqhv28a.web-platform.test,op77.web-platform.test,op12.web-platform.test,op54.web-platform.test,op63.web-platform.test,op71.web-platform.test,www2.www1.not-web-platform.test,op95.web-platform.test,op16.web-platform.test,op36.web-platform.test,op27.web-platform.test,www.www.web-platform.test,op98.not-web-platform.test,op64.not-web-platform.test,op29.web-platform.test,op9.web-platform.test,op26.not-web-platform.test,op22.not-web-platform.test,op94.web-platform.test,xn--n8j6ds53lwwkrqhv28a.www2.not-web-platform.test,op44.web-platform.test,op94.not-web-platform.test,op33.web-platform.test,op38.not-web-platform.test,op33.not-web-platform.test,op84.web-platform.test,www1.www1.not-web-platform.test,op23.not-web-platform.test,op57.not-web-platform.test,op54.not-web-platform.test,op85.not-web-platform.test,www2.www2.web-platform.test,op46.not-web-platform.test,op97.not-web-platform.test,op32.web-platform.test,op61.web-platform.test,op70.web-platform.test,www2.web-platform.test,op32.not-web-platform.test,op60.not-web-platform.test,op4.web-platform.test,op43.web-platform.test,op7.not-web-platform.test,op78.web-platform.test,op26.web-platform.test,xn--lve-6lad.xn--n8j6ds53lwwkrqhv28a.web-platform.test,op96.not-web-platform.test,op51.not-web-platform.test,op41.not-web-platform.test,op76.web-platform.test,op52.web-platform.test,op99.web-platform.test,op35.not-web-platform.test,op99.not-web-platform.test,op86.web-platform.test,not-web-platform.test,op42.not-web-platform.test,op46.web-platform.test,op67.not-web-platform.test,op17.web-platform.test,op90.web-platform.test,op93.web-platform.test,op37.not-web-platform.test,op48.not-web-platform.test,op10.web-platform.test,op55.not-web-platform.test,op4.not-web-platform.test,www1.xn--n8j6ds53lwwkrqhv28a.not-web-platform.test,op55.web-platform.test,xn--lve-6lad.www2.web-platform.test,op47.web-platform.test,op51.web-platform.test,op45.web-platform.test,op80.web-platform.test,op68.web-platform.test,op49.web-platform.test,op57.web-platform.test,www2.xn--n8j6ds53lwwkrqhv28a.web-platform.test,www.xn--n8j6ds53lwwkrqhv28a.not-web-platform.test,op56.not-web-platform.test,web-platform.test,op84.not-web-platform.test,xn--n8j6ds53lwwkrqhv28a.not-web-platform.test,xn--lve-6lad.xn--n8j6ds53lwwkrqhv28a.not-web-platform.test,op34.not-web-platform.test,op6.web-platform.test,op35.web-platform.test,op67.web-platform.test,op69.not-web-platform.test,op11.not-web-platform.test,op93.not-web-platform.test,www1.www.web-platform.test,op86.not-web-platform.test,op8.not-web-platform.test,www2.xn--n8j6ds53lwwkrqhv28a.not-web-platform.test,op92.web-platform.test,xn--lve-6lad.www1.not-web-platform.test,op15.web-platform.test,op13.not-web-platform.test,op13.web-platform.test,xn--n8j6ds53lwwkrqhv28a.web-platform.test,xn--n8j6ds53lwwkrqhv28a.www.web-platform.test,op75.web-platform.test,op20.not-web-platform.test,op76.not-web-platform.test,op64.web-platform.test,op97.web-platform.test,op37.web-platform.test,op56.web-platform.test,op62.web-platform.test,op82.web-platform.test,op25.web-platform.test,op11.web-platform.test,www.xn--lve-6lad.not-web-platform.test,www2.www1.web-platform.test,op27.not-web-platform.test,op50.web-platform.test,op17.not-web-platform.test,op38.web-platform.test,www2.www.not-web-platform.test,xn--lve-6lad.www1.web-platform.test,op75.not-web-platform.test,op83.web-platform.test,op81.web-platform.test,op15.not-web-platform.test,xn--n8j6ds53lwwkrqhv28a.www.not-web-platform.test,op20.web-platform.test,op3.web-platform.test,www1.www2.not-web-platform.test,xn--n8j6ds53lwwkrqhv28a.xn--n8j6ds53lwwkrqhv28a.not-web-platform.test,op2.web-platform.test,op21.web-platform.test,op23.web-platform.test,op42.web-platform.test,op47.not-web-platform.test,www1.www1.web-platform.test,op18.not-web-platform.test,op22.web-platform.test,xn--lve-6lad.xn--lve-6lad.not-web-platform.test,op63.not-web-platform.test,op28.not-web-platform.test,op65.web-platform.test,www.www1.not-web-platform.test,www1.xn--lve-6lad.web-platform.test,op43.not-web-platform.test,op66.not-web-platform.test,www2.www.web-platform.test,op96.web-platform.test,op91.not-web-platform.test,www.xn--lve-6lad.web-platform.test,op1.web-platform.test,op74.not-web-platform.test,op87.web-platform.test,op59.not-web-platform.test,op19.web-platform.test,xn--n8j6ds53lwwkrqhv28a.www1.not-web-platform.test,op9.not-web-platform.test,op88.not-web-platform.test,op53.web-platform.test,www2.xn--lve-6lad.not-web-platform.test,op87.not-web-platform.test,op30.web-platform.test,op10.not-web-platform.test,op48.web-platform.test,op16.not-web-platform.test,op34.web-platform.test,op1.not-web-platform.test,www.web-platform.test,op5.web-platform.test,www1.xn--lve-6lad.not-web-platform.test,xn--lve-6lad.www2.not-web-platform.test", "network.process.enabled": false}}}}}
[task 2020-10-09T19:15:26.817Z] 19:15:26 INFO - PID 17077 | 1602270926805 geckodriver::capabilities DEBUG Trying to read firefox version from ini files
[task 2020-10-09T19:15:26.818Z] 19:15:26 INFO - PID 17077 | 1602270926805 geckodriver::capabilities DEBUG Found version 83.0a1
[task 2020-10-09T19:15:26.818Z] 19:15:26 INFO - PID 17077 | 1602270926807 mozrunner::runner INFO Running command: "/builds/worker/workspace/build/application/firefox/firefox" "--marionette" "-foreground" "-no-remote" "-profile" "/tmp/rust_mozprofileuDYz81"
[task 2020-10-09T19:15:26.818Z] 19:15:26 INFO - PID 17077 | 1602270926807 geckodriver::marionette DEBUG Waiting 60s to connect to browser on 127.0.0.1:2828
[task 2020-10-09T19:15:26.954Z] 19:15:26 INFO - PID 17077 | DEBUG: Adding blocker OS.File: flush pending requests, warn about unclosed files, shut down service. for phase xpcom-will-shutdown
[task 2020-10-09T19:15:26.955Z] 19:15:26 INFO - PID 17077 | DEBUG: Adding blocker OS.File: flush I/O queued before profileBeforeChange for phase profile-before-change
[task 2020-10-09T19:15:26.955Z] 19:15:26 INFO - PID 17077 | DEBUG: Adding blocker AddonManager: shutting down. for phase profile-before-change
[task 2020-10-09T19:15:26.975Z] 19:15:26 INFO - PID 17077 | DEBUG: Adding blocker ClientManagerService: start destroying IPC actors early for phase xpcom-will-shutdown
[task 2020-10-09T19:15:27.035Z] 19:15:27 INFO - PID 17077 | DEBUG: Adding blocker Flush WebExtension StartupCache for phase profile-before-change
[task 2020-10-09T19:15:27.119Z] 19:15:27 INFO - PID 17077 | DEBUG: Adding blocker JSON store: writing data for phase AddonManager: Waiting for providers to shut down.
[task 2020-10-09T19:15:27.119Z] 19:15:27 INFO - PID 17077 | DEBUG: Adding blocker Update add-on blocklist state into add-on DB for phase AddonManager: Waiting to start provider shutdown.
[task 2020-10-09T19:15:27.121Z] 19:15:27 INFO - PID 17077 | DEBUG: Completed blocker Update add-on blocklist state into add-on DB for phase AddonManager: Waiting to start provider shutdown.
[task 2020-10-09T19:15:27.128Z] 19:15:27 INFO - PID 17077 | DEBUG: Adding blocker XPIProvider shutdown for phase quit-application-granted
[task 2020-10-09T19:15:27.128Z] 19:15:27 INFO - PID 17077 | DEBUG: Adding blocker XPIProvider for phase AddonManager: Waiting for providers to shut down.
[task 2020-10-09T19:15:27.128Z] 19:15:27 INFO - PID 17077 | DEBUG: Adding blocker PluginProvider for phase AddonManager: Waiting for providers to shut down.
[task 2020-10-09T19:15:27.129Z] 19:15:27 INFO - PID 17077 | DEBUG: Adding blocker ServiceWorkerRegistrar: Flushing data for phase profile-before-change
[task 2020-10-09T19:15:27.130Z] 19:15:27 INFO - PID 17077 | DEBUG: Adding blocker CrashMonitor: Writing notifications to file after receiving profile-before-change for phase OS.File: Waiting for clients before profileBeforeChange
[task 2020-10-09T19:15:27.145Z] 19:15:27 INFO - PID 17077 | DEBUG: Adding blocker TelemetryController: shutting down for phase profile-before-change-telemetry
[task 2020-10-09T19:15:27.193Z] 19:15:27 INFO - PID 17077 | DEBUG: Adding blocker EnvironmentAddonBuilder for phase AddonManager: Waiting to start provider shutdown.
[task 2020-10-09T19:15:27.200Z] 19:15:27 INFO - PID 17077 | DEBUG: Adding blocker RemoteSettingsClient - finish IDB access. for phase profile-before-change
[task 2020-10-09T19:15:27.208Z] 19:15:27 INFO - PID 17077 | 1602270927201 Marionette TRACE Marionette enabled
[task 2020-10-09T19:15:27.284Z] 19:15:27 INFO - PID 17077 | 1602270927275 Marionette TRACE Received observer notification toplevel-window-ready
[task 2020-10-09T19:15:27.344Z] 19:15:27 INFO - PID 17077 | DEBUG: Adding blocker Flush AddonRepository for phase profile-before-change
[task 2020-10-09T19:15:27.346Z] 19:15:27 INFO - PID 17077 | DEBUG: Adding blocker JSON store: writing data for phase profile-before-change

[task 2020-10-09T19:15:30.836Z] 19:15:30 INFO - PID 17077 | 1602270930824 Marionette DEBUG 0 <- [1,129,null,{"value":null}]
[task 2020-10-09T19:15:30.837Z] 19:15:30 INFO - PID 17077 | 1602270930825 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2020-10-09T19:15:30.837Z] 19:15:30 INFO - STDOUT: =================================== FAILURES ===================================
[task 2020-10-09T19:15:30.837Z] 19:15:30 INFO - STDOUT: ___________________________ test_no_browsing_context ___________________________
[task 2020-10-09T19:15:30.838Z] 19:15:30 INFO - STDOUT: session = <Session 1698e898-87ba-4e13-b11d-199daa591e39>, closed_frame = None
[task 2020-10-09T19:15:30.838Z] 19:15:30 INFO - STDOUT: def test_no_browsing_context(session, closed_frame):
[task 2020-10-09T19:15:30.838Z] 19:15:30 INFO - STDOUT: response = get_element_css_value(session, "foo", "bar")
[task 2020-10-09T19:15:30.840Z] 19:15:30 INFO - STDOUT: > assert_error(response, "no such window")
[task 2020-10-09T19:15:30.840Z] 19:15:30 INFO - STDOUT: closed_frame = None
[task 2020-10-09T19:15:30.840Z] 19:15:30 INFO - STDOUT: response = <Response status=404 error=<NoSuchElementException http_status=404>>
[task 2020-10-09T19:15:30.840Z] 19:15:30 INFO - STDOUT: session = <Session 1698e898-87ba-4e13-b11d-199daa591e39>
[task 2020-10-09T19:15:30.840Z] 19:15:30 INFO - STDOUT: tests/web-platform/tests/webdriver/tests/get_element_css_value/get.py
[task 2020-10-09T19:15:30.841Z] 19:15:30 INFO - STDOUT: :23:
[task 2020-10-09T19:15:30.842Z] 19:15:30 INFO - STDOUT: _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
[task 2020-10-09T19:15:30.842Z] 19:15:30 INFO - STDOUT: response = <Response status=404 error=<NoSuchElementException http_status=404>>
[task 2020-10-09T19:15:30.842Z] 19:15:30 INFO - STDOUT: error_code = 'no such window'
[task 2020-10-09T19:15:30.843Z] 19:15:30 INFO - STDOUT: def assert_error(response, error_code):
[task 2020-10-09T19:15:30.843Z] 19:15:30 INFO - STDOUT: """
[task 2020-10-09T19:15:30.844Z] 19:15:30 INFO - STDOUT: Verify that the provided webdriver.Response instance described
[task 2020-10-09T19:15:30.844Z] 19:15:30 INFO - STDOUT: a valid error response as defined by dfn-send-an-error and
[task 2020-10-09T19:15:30.845Z] 19:15:30 INFO - STDOUT: the provided error code.
[task 2020-10-09T19:15:30.845Z] 19:15:30 INFO - STDOUT:
[task 2020-10-09T19:15:30.845Z] 19:15:30 INFO - STDOUT: :param response: webdriver.Response instance.
[task 2020-10-09T19:15:30.845Z] 19:15:30 INFO - STDOUT: :param error_code: String value of the expected error code
[task 2020-10-09T19:15:30.846Z] 19:15:30 INFO - STDOUT: """
[task 2020-10-09T19:15:30.846Z] 19:15:30 INFO - STDOUT: assert response.status == errors[error_code]
[task 2020-10-09T19:15:30.847Z] 19:15:30 INFO - STDOUT: assert "value" in response.body
[task 2020-10-09T19:15:30.847Z] 19:15:30 INFO - STDOUT: > assert response.body["value"]["error"] == error_code
[task 2020-10-09T19:15:30.847Z] 19:15:30 INFO - STDOUT: E AssertionError: assert 'no such element' == 'no such window'
[task 2020-10-09T19:15:30.848Z] 19:15:30 INFO - STDOUT: E - no such element
[task 2020-10-09T19:15:30.848Z] 19:15:30 INFO - STDOUT: E + no such window
[task 2020-10-09T19:15:30.849Z] 19:15:30 INFO - STDOUT: error_code = 'no such window'
[task 2020-10-09T19:15:30.849Z] 19:15:30 INFO - STDOUT: response = <Response status=404 error=<NoSuchElementException http_status=404>>
[task 2020-10-09T19:15:30.849Z] 19:15:30 INFO - STDOUT: tests/web-platform/tests/webdriver/tests/support/asserts.py
[task 2020-10-09T19:15:30.850Z] 19:15:30 INFO - STDOUT: :54: AssertionError
[task 2020-10-09T19:15:30.850Z] 19:15:30 INFO - STDOUT: ====================== 1 failed, 5 passed in 4.04 seconds ======================
[task 2020-10-09T19:15:30.885Z] 19:15:30 INFO -
[task 2020-10-09T19:15:30.885Z] 19:15:30 INFO - TEST-PASS | /webdriver/tests/get_element_css_value/get.py | test_no_top_browsing_context
[task 2020-10-09T19:15:30.885Z] 19:15:30 INFO - TEST-UNEXPECTED-FAIL | /webdriver/tests/get_element_css_value/get.py | test_no_browsing_context - AssertionError: assert 'no such element' == 'no such window'
[task 2020-10-09T19:15:30.885Z] 19:15:30 INFO - session = <Session 1698e898-87ba-4e13-b11d-199daa591e39>, closed_frame = None
[task 2020-10-09T19:15:30.886Z] 19:15:30 INFO -
[task 2020-10-09T19:15:30.886Z] 19:15:30 INFO - def test_no_browsing_context(session, closed_frame):
[task 2020-10-09T19:15:30.886Z] 19:15:30 INFO - response = get_element_css_value(session, "foo", "bar")
[task 2020-10-09T19:15:30.886Z] 19:15:30 INFO - > assert_error(response, "no such window")
[task 2020-10-09T19:15:30.886Z] 19:15:30 INFO -
[task 2020-10-09T19:15:30.886Z] 19:15:30 INFO - closed_frame = None
[task 2020-10-09T19:15:30.886Z] 19:15:30 INFO - response = <Response status=404 error=<NoSuchElementException http_status=404>>
[task 2020-10-09T19:15:30.886Z] 19:15:30 INFO - session = <Session 1698e898-87ba-4e13-b11d-199daa591e39>
[task 2020-10-09T19:15:30.886Z] 19:15:30 INFO -
[task 2020-10-09T19:15:30.886Z] 19:15:30 INFO - tests/web-platform/tests/webdriver/tests/get_element_css_value/get.py:23:
[task 2020-10-09T19:15:30.886Z] 19:15:30 INFO - _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
[task 2020-10-09T19:15:30.886Z] 19:15:30 INFO -
[task 2020-10-09T19:15:30.886Z] 19:15:30 INFO - response = <Response status=404 error=<NoSuchElementException http_status=404>>
[task 2020-10-09T19:15:30.886Z] 19:15:30 INFO - error_code = 'no such window'
[task 2020-10-09T19:15:30.886Z] 19:15:30 INFO -
[task 2020-10-09T19:15:30.886Z] 19:15:30 INFO - def assert_error(response, error_code):
[task 2020-10-09T19:15:30.886Z] 19:15:30 INFO - """
[task 2020-10-09T19:15:30.886Z] 19:15:30 INFO - Verify that the provided webdriver.Response instance described
[task 2020-10-09T19:15:30.886Z] 19:15:30 INFO - a valid error response as defined by dfn-send-an-error and
[task 2020-10-09T19:15:30.886Z] 19:15:30 INFO - the provided error code.
[task 2020-10-09T19:15:30.886Z] 19:15:30 INFO -
[task 2020-10-09T19:15:30.886Z] 19:15:30 INFO - :param response: webdriver.Response instance.
[task 2020-10-09T19:15:30.886Z] 19:15:30 INFO - :param error_code: String value of the expected error code
[task 2020-10-09T19:15:30.886Z] 19:15:30 INFO - """
[task 2020-10-09T19:15:30.886Z] 19:15:30 INFO - assert response.status == errors[error_code]
[task 2020-10-09T19:15:30.887Z] 19:15:30 INFO - assert "value" in response.body
[task 2020-10-09T19:15:30.887Z] 19:15:30 INFO - > assert response.body["value"]["error"] == error_code
[task 2020-10-09T19:15:30.887Z] 19:15:30 INFO - E AssertionError: assert 'no such element' == 'no such window'
[task 2020-10-09T19:15:30.887Z] 19:15:30 INFO - E - no such element
[task 2020-10-09T19:15:30.887Z] 19:15:30 INFO - E + no such window
[task 2020-10-09T19:15:30.887Z] 19:15:30 INFO -
[task 2020-10-09T19:15:30.887Z] 19:15:30 INFO - error_code = 'no such window'
[task 2020-10-09T19:15:30.887Z] 19:15:30 INFO - response = <Response status=404 error=<NoSuchElementException http_status=404>>
[task 2020-10-09T19:15:30.887Z] 19:15:30 INFO -
[task 2020-10-09T19:15:30.887Z] 19:15:30 INFO - tests/web-platform/tests/webdriver/tests/support/asserts.py:54: AssertionError
[task 2020-10-09T19:15:30.887Z] 19:15:30 INFO - ....
[task 2020-10-09T19:15:30.887Z] 19:15:30 INFO - TEST-OK | /webdriver/tests/get_element_css_value/get.py | took 4278ms
[task 2020-10-09T19:15:30.943Z] 19:15:30 INFO - Closing logging queue
[task 2020-10-09T19:15:30.943Z] 19:15:30 INFO - queue closed
[task 2020-10-09T19:15:30.944Z] 19:15:30 INFO - Starting runner
[task 2020-10-09T19:15:30.959Z] 19:15:30 INFO - PID 17615 | 1602270930956 geckodriver INFO Listening on 127.0.0.1:44553
[task 2020-10-09T19:15:30.960Z] 19:15:30 INFO - WebDriver HTTP server listening at http://127.0.0.1:44553/

Henrik are there any updates here?

Comment 15

[Intermittent Failures Robot]

60 failures in 4130 pushes (0.015 failures/push) were associated with this bug in the last 7 days.

This is the #18 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: 49
• try: 2
• mozilla-central: 9

Platform breakdown:

• linux1804-64-shippable-qr: 12
• linux1804-64-qr: 37
• linux1804-64-ccov: 4
• macosx1014-64-shippable: 1
• macosx1014-64: 1
• linux1804-64-asan: 2
• linux1804-64: 1
• windows7-32: 1
• linux1804-64-shippable: 1

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

Comment 16

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

It depends on bug 1666204, which itself depends on bug 1666755. I will have a look at it this week.

Comment 17

[Intermittent Failures Robot]

44 failures in 3694 pushes (0.012 failures/push) were associated with this bug in the last 7 days.

This is the #30 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: 39
• mozilla-central: 4
• try: 1

Platform breakdown:

• linux1804-64-qr: 25
• linux1804-64-shippable-qr: 13
• linux1804-64: 2
• linux1804-64-asan: 1
• macosx1014-64: 1
• windows7-32: 1
• linux1804-64-ccov: 1

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

Comment 18

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

No more failures since my patch on bug 1666204 got landed. Marking as fixed.

Comment 19

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-release: 1

Platform breakdown:

• linux1804-64-asan: 1

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

Comment 20

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-release: 1

Platform breakdown:

• macosx1014-64-shippable: 1

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

Comment 21

[BMO Automation]

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