1627965 - Intermittent testing/marionette/harness/marionette_harness/tests/unit/test_click.py TestClickNavigation.test_click_link_install_addon | InvalidSessionIdException: Please start a session

Status: RESOLVED FIXED

(Testing :: Marionette Client and Harness, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: ccoroiu [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=296586327&repo=try
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/Jm3VW2bZQ5alZQUxKEdKGA/runs/0/artifacts/public/logs/live_backing.log

---

task 2020-04-07T11:25:52.778Z] 11:25:52 INFO - TEST-START | testing/marionette/harness/marionette_harness/tests/unit/test_click.py TestClickNavigation.test_click_link_install_addon
[task 2020-04-07T11:25:52.818Z] 11:25:52 INFO - 1586258752812 Marionette DEBUG Closed connection 206
[task 2020-04-07T11:25:52.818Z] 11:25:52 INFO - 1586258752813 Marionette DEBUG Accepted connection 207 from 127.0.0.1:50481
[task 2020-04-07T11:25:52.818Z] 11:25:52 INFO - 1586258752817 Marionette DEBUG 207 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2020-04-07T11:25:52.827Z] 11:25:52 INFO - 1586258752820 Marionette TRACE [20] Frame script loaded
[task 2020-04-07T11:25:52.827Z] 11:25:52 INFO - 1586258752823 Marionette TRACE [20] Frame script registered
[task 2020-04-07T11:25:52.827Z] 11:25:52 INFO - 1586258752825 Marionette DEBUG 207 <- [1,1,null,{"sessionId":"8a212854-3a53-4306-85af-54aa5f3cd8b4","capabilities":{"browserName":"firefox","browserVersion":"77.0" ... \tmp2z90nz.mozrunner","moz:shutdownTimeout":180000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true}}]
[task 2020-04-07T11:25:52.829Z] 11:25:52 INFO - 1586258752828 Marionette DEBUG 207 -> [0,2,"WebDriver:SetTimeouts",{"script":30000}]
[task 2020-04-07T11:25:52.829Z] 11:25:52 INFO - 1586258752828 Marionette DEBUG 207 <- [1,2,null,{"value":null}]
[task 2020-04-07T11:25:52.831Z] 11:25:52 INFO - 1586258752829 Marionette DEBUG 207 -> [0,3,"WebDriver:SetTimeouts",{"pageLoad":300000}]
[task 2020-04-07T11:25:52.831Z] 11:25:52 INFO - 1586258752830 Marionette DEBUG 207 <- [1,3,null,{"value":null}]
[task 2020-04-07T11:25:52.833Z] 11:25:52 INFO - 1586258752831 Marionette DEBUG 207 -> [0,4,"WebDriver:SetTimeouts",{"implicit":0}]
[task 2020-04-07T11:25:52.833Z] 11:25:52 INFO - 1586258752831 Marionette DEBUG 207 <- [1,4,null,{"value":null}]
[task 2020-04-07T11:25:52.835Z] 11:25:52 INFO - 1586258752833 Marionette DEBUG 207 -> [0,5,"WebDriver:Navigate",{"url":"http://127.0.0.1:50036/clicks.html"}]
[task 2020-04-07T11:25:52.840Z] 11:25:52 INFO - 1586258752838 Marionette TRACE [20] Received DOM event beforeunload for http://127.0.0.1:50036/clicks.html#
[task 2020-04-07T11:25:52.878Z] 11:25:52 INFO - 1586258752875 Marionette TRACE [20] Received DOM event pagehide for http://127.0.0.1:50036/clicks.html#
[task 2020-04-07T11:25:52.880Z] 11:25:52 INFO - 1586258752878 Marionette TRACE [20] Received DOM event unload for http://127.0.0.1:50036/clicks.html#
[task 2020-04-07T11:25:52.941Z] 11:25:52 INFO - 1586258752939 Marionette TRACE [20] Received DOM event DOMContentLoaded for http://127.0.0.1:50036/clicks.html
[task 2020-04-07T11:25:52.959Z] 11:25:52 INFO - 1586258752949 Marionette TRACE [20] Received DOM event pageshow for http://127.0.0.1:50036/clicks.html
[task 2020-04-07T11:25:52.959Z] 11:25:52 INFO - 1586258752952 Marionette DEBUG 207 <- [1,5,null,{"value":null}]
[task 2020-04-07T11:25:52.964Z] 11:25:52 INFO - 1586258752958 Marionette DEBUG 207 -> [0,6,"WebDriver:FindElement",{"using":"id","value":"install-addon"}]
[task 2020-04-07T11:25:52.964Z] 11:25:52 INFO - 1586258752963 Marionette DEBUG 207 <- [1,6,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"7dc24240-64a5-457e-bb43-4770e4b31c35"}}]
[task 2020-04-07T11:25:52.969Z] 11:25:52 INFO - 1586258752965 Marionette DEBUG 207 -> [0,7,"WebDriver:ElementClick",{"id":"7dc24240-64a5-457e-bb43-4770e4b31c35"}]
[task 2020-04-07T11:25:53.008Z] 11:25:53 INFO - 1586258753000 Marionette TRACE [20] Received DOM event beforeunload for http://127.0.0.1:50036/clicks.html
[task 2020-04-07T11:25:53.028Z] 11:25:53 INFO - DEBUG: Adding blocker UserInteractionTimer 24 for document 11d2e4b22080 for phase xpcom-will-shutdown
[task 2020-04-07T11:25:58.212Z] 11:25:58 INFO - 1586258758203 Marionette DEBUG [20] Canceled page load listener because no navigation has been detected
[task 2020-04-07T11:35:04.471Z] 11:35:04 ERROR - TEST-UNEXPECTED-ERROR | testing/marionette/harness/marionette_harness/tests/unit/test_click.py TestClickNavigation.test_click_link_install_addon | InvalidSessionIdException: Please start a session
[task 2020-04-07T11:35:04.471Z] 11:35:04 INFO - Traceback (most recent call last):
[task 2020-04-07T11:35:04.471Z] 11:35:04 INFO - File "Z:\task_1586257507\build\venv\lib\site-packages\marionette_harness\marionette_test\testcases.py", line 159, in run
[task 2020-04-07T11:35:04.471Z] 11:35:04 INFO - testMethod()
[task 2020-04-07T11:35:04.471Z] 11:35:04 INFO - File "Z:\task_1586257507\build\tests\marionette\tests\testing\marionette\harness\marionette_harness\tests\unit\test_click.py", line 420, in test_click_link_install_addon
[task 2020-04-07T11:35:04.471Z] 11:35:04 INFO - self.close_notification()
[task 2020-04-07T11:35:04.472Z] 11:35:04 INFO - File "Z:\task_1586257507\build\tests\marionette\tests\testing\marionette\harness\marionette_harness\tests\unit\test_click.py", line 383, in close_notification
[task 2020-04-07T11:35:04.472Z] 11:35:04 INFO - with self.marionette.using_context("chrome"):
[task 2020-04-07T11:35:04.472Z] 11:35:04 INFO - File "c:\mozilla-build\python\Lib\contextlib.py", line 17, in enter
[task 2020-04-07T11:35:04.472Z] 11:35:04 INFO - return self.gen.next()
[task 2020-04-07T11:35:04.472Z] 11:35:04 INFO - File "Z:\task_1586257507\build\venv\lib\site-packages\marionette_driver\marionette.py", line 1327, in using_context
[task 2020-04-07T11:35:04.472Z] 11:35:04 INFO - key="value")
[task 2020-04-07T11:35:04.472Z] 11:35:04 INFO - File "Z:\task_1586257507\build\venv\lib\site-packages\marionette_driver\decorators.py", line 26, in _
[task 2020-04-07T11:35:04.472Z] 11:35:04 INFO - return func(*args, **kwargs)
[task 2020-04-07T11:35:04.472Z] 11:35:04 INFO - File "Z:\task_1586257507\build\venv\lib\site-packages\marionette_driver\marionette.py", line 586, in _send_message
[task 2020-04-07T11:35:04.472Z] 11:35:04 INFO - raise errors.InvalidSessionIdException("Please start a session")
[task 2020-04-07T11:35:04.472Z] 11:35:04 INFO - TEST-INFO took 551692ms

Comment 1

[Intermittent Failures Robot]

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

Repository breakdown:

• try: 1
• autoland: 1

Platform breakdown:

• windows10-64: 2

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

Comment 2

[BugBot [:suhaib / :marco/ :calixte]]

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

Comment 3

[Bogdan Tara[:bogdan_tara | bogdant]]

This is still happening.

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

Comment 4

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

• windows10-64: 1

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

Comment 5

[Intermittent Failures Robot]

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

Repository breakdown:

• try: 1
• mozilla-central: 1
• autoland: 6

Platform breakdown:

• windows10-64: 7
• windows10-64-shippable: 1

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

Comment 6

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

The click onto the link definitely happened, but the click command didn't return after 5s:

[task 2020-09-26T04:42:43.870Z] 04:42:43     INFO -  1601095363868	Marionette	DEBUG	196 -> [0,16,"WebDriver:ElementClick",{"id":"79e625ee-3d4d-4860-9c71-bfa0feba0896"}]
[task 2020-09-26T04:42:43.875Z] 04:42:43     INFO -  1601095363874	Marionette	TRACE	Received DOM event click for http://127.0.0.1:49905/addons/webextension-unsigned.xpi
[task 2020-09-26T04:42:43.880Z] 04:42:43     INFO -  DEBUG: Adding blocker UserInteractionTimer 29 for document 1aba6c7a000 for phase xpcom-will-shutdown
[task 2020-09-26T04:42:43.880Z] 04:42:43     INFO -  1601095363879	Marionette	TRACE	Received message beforeunload for http://127.0.0.1:49905/clicks.html
[task 2020-09-26T04:49:55.186Z] 04:49:55    ERROR -  TEST-UNEXPECTED-ERROR | testing/marionette/harness/marionette_harness/tests/unit/test_click.py TestClickNavigation.test_click_link_install_addon | InvalidSessionIdException: Please start a session

Comment 7

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 5
• mozilla-beta: 1
• mozilla-central: 1

Platform breakdown:

• windows10-64: 5
• windows10-64-devedition: 1
• windows10-64-shippable: 1

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

Comment 8

[Intermittent Failures Robot]

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

Repository breakdown:

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

Platform breakdown:

• windows10-64-shippable: 3
• windows10-64: 1

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

Comment 9

[Intermittent Failures Robot]

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

Repository breakdown:

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

Platform breakdown:

• windows10-64: 6
• windows10-64-shippable: 3

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

Comment 10

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 3
• autoland: 6
• try: 1
• mozilla-release: 1

Platform breakdown:

• windows10-64-shippable: 3
• windows10-64: 7
• windows10-64-devedition: 1

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

Comment 11

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

Windows 10 only failure, which stopped happening on Friday last week (or newer failures haven't been classified yet due to the weekend). Lets have a look again mid this week.

Comment 12

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 4
• try: 3
• mozilla-beta: 2
• mozilla-central: 3

Platform breakdown:

• windows10-64: 5
• windows10-64-devedition: 3
• windows10-64-shippable: 4

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

Comment 13

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 6
• mozilla-central: 1
• mozilla-beta: 2

Platform breakdown:

• windows10-64: 6
• windows10-64-shippable: 2
• windows10-64-devedition: 1

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

Comment 14

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 2
• autoland: 7
• mozilla-release: 1
• try: 3

Platform breakdown:

• windows10-64: 9
• windows10-64-shippable: 1
• windows10-64-devedition: 3

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1627965&startday=2020-11-09&endday=2020-11-15&tree=all

Comment 15

[Intermittent Failures Robot]

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

Repository breakdown:

• try: 3
• autoland: 6
• mozilla-central: 2
• mozilla-beta: 2

Platform breakdown:

• windows10-64: 7
• windows10-64-shippable: 6

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

Comment 16

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

[task 2020-11-25T00:21:02.821Z] 00:21:02     INFO -  1606263662819	Marionette	DEBUG	196 -> [0,16,"WebDriver:ElementClick",{"id":"be50dff6-cfb6-4cb9-bd8a-fe3cdd648a68"}]
[task 2020-11-25T00:21:02.840Z] 00:21:02     INFO -  1606263662838	Marionette	TRACE	Received DOM event click for http://127.0.0.1:49923/addons/webextension-unsigned.xpi
[task 2020-11-25T00:21:02.842Z] 00:21:02     INFO -  [Child 1244, Main Thread] WARNING: NS_ENSURE_TRUE(info) failed: file /builds/worker/checkouts/gecko/extensions/permissions/PermissionDelegateHandler.cpp:348
[task 2020-11-25T00:21:02.842Z] 00:21:02     INFO -  [Child 1244, Main Thread] WARNING: NS_ENSURE_TRUE(info) failed: file /builds/worker/checkouts/gecko/extensions/permissions/PermissionDelegateHandler.cpp:348
[task 2020-11-25T00:21:02.842Z] 00:21:02     INFO -  1606263662841	Marionette	TRACE	[154] MarionetteEvents actor created for window id 6442450968
[task 2020-11-25T00:21:02.844Z] 00:21:02     INFO -  1606263662842	Marionette	TRACE	Received event beforeunload for http://127.0.0.1:49923/clicks.html
[task 2020-11-25T00:21:02.849Z] 00:21:02     INFO -  DEBUG: Adding blocker UserInteractionTimer 13 for document 12eb34a3000 for phase xpcom-will-shutdown
[task 2020-11-25T00:28:13.947Z] 00:28:13    ERROR -  TEST-UNEXPECTED-ERROR | testing/marionette/harness/marionette_harness/tests/unit/test_click.py TestClickNavigation.test_click_link_install_addon | InvalidSessionIdException: Please start a session

The click event is received, and also the unbeforeunload event, but the extended unload timer doesn't get fired:

https://searchfox.org/mozilla-central/rev/f081504642a115cb8236bea4d8250e5cb0f39b02/testing/marionette/navigate.js#244-250

Comment 17

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 7
• try: 1

Platform breakdown:

• windows10-64: 7
• windows10-64-shippable: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1627965&startday=2020-11-23&endday=2020-11-29&tree=all

Comment 18

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 15
• mozilla-beta: 1
• mozilla-central: 2
• mozilla-release: 1

Platform breakdown:

• windows10-64: 15
• windows10-64-shippable: 4

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1627965&startday=2020-11-30&endday=2020-12-06&tree=all

Comment 19

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 15
• mozilla-central: 3
• try: 2

Platform breakdown:

• windows10-64: 16
• windows10-64-shippable: 3
• windows10-64-devedition: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1627965&startday=2020-12-07&endday=2020-12-13&tree=all

Comment 20

[Bogdan Tara[:bogdan_tara | bogdant]]

In the last 7 days there have been 29 occurrences, all on windows10-64 debug and opt.

Recent failure: https://treeherder.mozilla.org/logviewer?job_id=325043030&repo=mozilla-central&lineNumber=7114

Comment 21

[Intermittent Failures Robot]

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

This is the #42 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: 23
• mozilla-central: 5
• try: 1
• mozilla-beta: 1

Platform breakdown:

• windows10-64: 25
• windows10-64-shippable: 4
• windows10-64-ccov-qr: 1

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

Comment 22

[Intermittent Failures Robot]

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

This is the #42 most frequent failure this week.

Repository breakdown:

• autoland: 16

Platform breakdown:

• windows10-64: 16

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

Comment 23

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 1
• autoland: 2

Platform breakdown:

• windows10-64-shippable: 1
• windows10-64: 2

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

Comment 24

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 8
• mozilla-central: 3
• try: 1
• mozilla-beta: 1

Platform breakdown:

• windows10-64: 9
• windows10-64-shippable: 2
• windows10-64-ccov-qr: 1
• windows10-64-devedition: 1

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

Comment 25

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 17
• try: 1
• mozilla-central: 3
• mozilla-beta: 2

Platform breakdown:

• windows10-64: 18
• windows10-64-shippable: 4
• windows10-64-devedition: 1

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

Comment 26

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 3
• autoland: 12
• try: 1

Platform and build breakdown:

• windows10-64-shippable: 2
  • opt: 2
• windows10-64: 14
  • opt: 8
  • debug: 6

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

Comment 27

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 13
• mozilla-central: 3
• try: 1
• mozilla-beta: 1

Platform and build breakdown:

• windows10-64: 14
  • opt: 10
  • debug: 4
• windows10-64-shippable: 3
  • opt: 3
• windows10-64-devedition: 1
  • opt: 1

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

Comment 28

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 15
• try: 2
• mozilla-central: 4
• mozilla-beta: 1

Platform and build breakdown:

• windows10-64: 17
  • debug: 9
  • opt: 8
• windows10-64-shippable: 4
  • opt: 4
• windows10-64-devedition: 1
  • opt: 1

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

Comment 29

[Intermittent Failures Robot]

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

This is the #49 most frequent failure this week.

Repository breakdown:

• autoland: 21
• mozilla-beta: 2
• try: 2
• mozilla-central: 2

Platform and build breakdown:

• windows10-64: 23
  • debug: 17
  • opt: 6
• windows10-64-shippable: 3
  • opt: 3
• windows10-64-ccov-qr: 1
  • opt: 1

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

Comment 30

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 12
• mozilla-central: 5
• mozilla-release: 1
• try: 1

Platform and build breakdown:

• windows10-64: 14
  • debug: 13
  • opt: 1
• windows10-64-shippable: 4
  • opt: 4
• windows10-64-devedition: 1
  • opt: 1

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

Comment 31

[Andreea Pavel [:apavel]]

There are 34 total failures in the last 7 days on windows10-64 debug and opt
Recent failure log: https://treeherder.mozilla.org/logviewer?job_id=331364584&repo=autoland&lineNumber=7091

[task 2021-02-26T17:43:09.592Z] 17:43:09 INFO - TEST-START | testing/marionette/harness/marionette_harness/tests/unit/test_click.py TestClickNavigation.test_click_link_install_addon
[task 2021-02-26T17:43:09.594Z] 17:43:09 INFO - 1614361389593 Marionette DEBUG Accepted connection 196 from 127.0.0.1:50304
[task 2021-02-26T17:43:09.596Z] 17:43:09 INFO - 1614361389593 Marionette DEBUG 196 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2021-02-26T17:43:09.596Z] 17:43:09 INFO - 1614361389594 Marionette DEBUG 196 <- [1,1,null,{"sessionId":"53b8416e-0a34-463c-ad2b-36536d22eb75","capabilities":{"browserName":"firefox","browserVersion":"88.0a ... p\tmpygsydh.mozrunner","moz:shutdownTimeout":60000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true}}]
[task 2021-02-26T17:43:09.596Z] 17:43:09 INFO - 1614361389595 Marionette DEBUG 196 -> [0,2,"WebDriver:SetTimeouts",{"script":30000}]
[task 2021-02-26T17:43:09.598Z] 17:43:09 INFO - 1614361389595 Marionette DEBUG 196 <- [1,2,null,{"value":null}]
[task 2021-02-26T17:43:09.598Z] 17:43:09 INFO - 1614361389596 Marionette DEBUG 196 -> [0,3,"WebDriver:SetTimeouts",{"pageLoad":300000}]
[task 2021-02-26T17:43:09.598Z] 17:43:09 INFO - 1614361389596 Marionette DEBUG 196 <- [1,3,null,{"value":null}]
[task 2021-02-26T17:43:09.598Z] 17:43:09 INFO - 1614361389596 Marionette DEBUG 196 -> [0,4,"WebDriver:SetTimeouts",{"implicit":0}]
[task 2021-02-26T17:43:09.598Z] 17:43:09 INFO - 1614361389596 Marionette DEBUG 196 <- [1,4,null,{"value":null}]
[task 2021-02-26T17:43:09.600Z] 17:43:09 INFO - 1614361389597 Marionette DEBUG 196 -> [0,5,"WebDriver:GetChromeWindowHandle",{}]
[task 2021-02-26T17:43:09.600Z] 17:43:09 INFO - 1614361389597 Marionette DEBUG 196 <- [1,5,null,{"value":"6"}]
[task 2021-02-26T17:43:09.600Z] 17:43:09 INFO - 1614361389598 Marionette DEBUG 196 -> [0,6,"WebDriver:GetChromeWindowHandles",{}]
[task 2021-02-26T17:43:09.600Z] 17:43:09 INFO - 1614361389598 Marionette DEBUG 196 <- [1,6,null,["6"]]
[task 2021-02-26T17:43:09.602Z] 17:43:09 INFO - 1614361389600 Marionette DEBUG 196 -> [0,7,"WebDriver:GetWindowHandle",{}]
[task 2021-02-26T17:43:09.602Z] 17:43:09 INFO - 1614361389600 Marionette DEBUG 196 <- [1,7,null,{"value":"17"}]
[task 2021-02-26T17:43:09.602Z] 17:43:09 INFO - 1614361389600 Marionette DEBUG 196 -> [0,8,"WebDriver:GetWindowHandles",{}]
[task 2021-02-26T17:43:09.602Z] 17:43:09 INFO - 1614361389600 Marionette DEBUG 196 <- [1,8,null,["17"]]
[task 2021-02-26T17:43:09.602Z] 17:43:09 INFO - 1614361389601 Marionette DEBUG 196 -> [0,9,"WebDriver:GetWindowHandles",{}]
[task 2021-02-26T17:43:09.602Z] 17:43:09 INFO - 1614361389601 Marionette DEBUG 196 <- [1,9,null,["17"]]
[task 2021-02-26T17:43:09.604Z] 17:43:09 INFO - 1614361389602 Marionette DEBUG 196 -> [0,10,"WebDriver:NewWindow",{"type":"tab","focus":false,"private":false}]
[task 2021-02-26T17:43:09.606Z] 17:43:09 INFO - 1614361389604 Marionette TRACE Received DOM event TabOpen for [object XULElement]
[task 2021-02-26T17:43:09.611Z] 17:43:09 INFO - 1614361389608 Marionette TRACE [154] MarionetteEvents actor created for window id 201
[task 2021-02-26T17:43:09.630Z] 17:43:09 INFO - 1614361389627 Marionette TRACE Received event beforeunload for about:blank
[task 2021-02-26T17:43:09.630Z] 17:43:09 INFO - 1614361389629 Marionette TRACE Received event pagehide for about:blank
[task 2021-02-26T17:43:09.640Z] 17:43:09 INFO - 1614361389634 Marionette TRACE [154] MarionetteEvents actor created for window id 6442451009
[task 2021-02-26T17:43:09.640Z] 17:43:09 INFO - 1614361389635 Marionette TRACE Received event DOMContentLoaded for about:blank
[task 2021-02-26T17:43:09.640Z] 17:43:09 INFO - 1614361389635 Marionette TRACE Received event pageshow for about:blank
[task 2021-02-26T17:43:09.640Z] 17:43:09 INFO - 1614361389635 Marionette DEBUG 196 <- [1,10,null,{"handle":"154","type":"tab"}]
[task 2021-02-26T17:43:09.640Z] 17:43:09 INFO - 1614361389636 Marionette DEBUG 196 -> [0,11,"WebDriver:GetWindowHandles",{}]
[task 2021-02-26T17:43:09.640Z] 17:43:09 INFO - 1614361389636 Marionette DEBUG 196 <- [1,11,null,["17","154"]]
[task 2021-02-26T17:43:09.640Z] 17:43:09 INFO - 1614361389637 Marionette DEBUG 196 -> [0,12,"WebDriver:GetWindowHandles",{}]
[task 2021-02-26T17:43:09.640Z] 17:43:09 INFO - 1614361389637 Marionette DEBUG 196 <- [1,12,null,["17","154"]]
[task 2021-02-26T17:43:09.640Z] 17:43:09 INFO - 1614361389638 Marionette DEBUG 196 -> [0,13,"WebDriver:SwitchToWindow",{"handle":"154","focus":true}]
[task 2021-02-26T17:43:09.645Z] 17:43:09 INFO - 1614361389642 Marionette TRACE Received DOM event TabSelect for [object XULElement]
[task 2021-02-26T17:43:09.650Z] 17:43:09 INFO - 1614361389647 Marionette DEBUG 196 <- [1,13,null,{"value":null}]
[task 2021-02-26T17:43:09.660Z] 17:43:09 INFO - 1614361389654 Marionette DEBUG 196 -> [0,14,"WebDriver:Navigate",{"url":"http://127.0.0.1:49895/clicks.html"}]
[task 2021-02-26T17:43:09.660Z] 17:43:09 INFO - 1614361389654 Marionette TRACE [154] MarionetteCommands actor created for window id 6442451009
[task 2021-02-26T17:43:09.660Z] 17:43:09 INFO - 1614361389656 Marionette TRACE Received event beforeunload for about:blank
[task 2021-02-26T17:43:09.665Z] 17:43:09 INFO - 1614361389662 Marionette TRACE Received event pagehide for about:blank
[task 2021-02-26T17:43:09.670Z] 17:43:09 INFO - 1614361389667 Marionette TRACE [154] MarionetteEvents actor created for window id 203
[task 2021-02-26T17:43:09.680Z] 17:43:09 INFO - 1614361389675 Marionette TRACE Received event beforeunload for about:blank
[task 2021-02-26T17:43:09.680Z] 17:43:09 INFO - 1614361389677 Marionette TRACE Received event pagehide for about:blank
[task 2021-02-26T17:43:09.685Z] 17:43:09 INFO - 1614361389682 Marionette TRACE [154] MarionetteEvents actor created for window id 10737418245
[task 2021-02-26T17:43:09.695Z] 17:43:09 INFO - 1614361389690 Marionette TRACE Received event DOMContentLoaded for http://127.0.0.1:49895/clicks.html
[task 2021-02-26T17:43:09.695Z] 17:43:09 INFO - 1614361389691 Marionette TRACE Received event pageshow for http://127.0.0.1:49895/clicks.html
[task 2021-02-26T17:43:09.695Z] 17:43:09 INFO - 1614361389693 Marionette DEBUG 196 <- [1,14,null,{"value":null}]
[task 2021-02-26T17:43:09.705Z] 17:43:09 INFO - 1614361389697 Marionette DEBUG 196 -> [0,15,"WebDriver:FindElement",{"using":"id","value":"install-addon"}]
[task 2021-02-26T17:43:09.705Z] 17:43:09 INFO - 1614361389697 Marionette TRACE [154] MarionetteCommands actor created for window id 10737418245
[task 2021-02-26T17:43:09.705Z] 17:43:09 INFO - 1614361389698 Marionette DEBUG 196 <- [1,15,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8da530ef-b220-4c93-a875-739e0d9bb353"}}]
[task 2021-02-26T17:43:09.705Z] 17:43:09 INFO - 1614361389699 Marionette DEBUG 196 -> [0,16,"WebDriver:ElementClick",{"id":"8da530ef-b220-4c93-a875-739e0d9bb353"}]
[task 2021-02-26T17:43:09.715Z] 17:43:09 INFO - 1614361389707 Marionette TRACE Received DOM event click for http://127.0.0.1:49895/addons/webextension-unsigned.xpi
[task 2021-02-26T17:43:09.715Z] 17:43:09 INFO - 1614361389708 Marionette TRACE Received event beforeunload for http://127.0.0.1:49895/clicks.html
[task 2021-02-26T17:43:09.715Z] 17:43:09 INFO - DEBUG: Adding blocker UserInteractionTimer 4 for document 2a4e4083000 for phase xpcom-will-shutdown
[task 2021-02-26T17:50:20.971Z] 17:50:20 ERROR - TEST-UNEXPECTED-ERROR | testing/marionette/harness/marionette_harness/tests/unit/test_click.py TestClickNavigation.test_click_link_install_addon | InvalidSessionIdException: Please start a session
[task 2021-02-26T17:50:20.971Z] 17:50:20 INFO - Traceback (most recent call last):
[task 2021-02-26T17:50:20.971Z] 17:50:20 INFO - File "C:\Users\task_1614358816\build\venv\lib\site-packages\marionette_harness\marionette_test\testcases.py", line 214, in run
[task 2021-02-26T17:50:20.972Z] 17:50:20 INFO - testMethod()
[task 2021-02-26T17:50:20.972Z] 17:50:20 INFO - File "C:\Users\task_1614358816\build\tests\marionette\tests\testing\marionette\harness\marionette_harness\tests\unit\test_click.py", line 539, in test_click_link_install_addon
[task 2021-02-26T17:50:20.973Z] 17:50:20 INFO - self.close_notification()
[task 2021-02-26T17:50:20.973Z] 17:50:20 INFO - File "C:\Users\task_1614358816\build\tests\marionette\tests\testing\marionette\harness\marionette_harness\tests\unit\test_click.py", line 493, in close_notification
[task 2021-02-26T17:50:20.973Z] 17:50:20 INFO - with self.marionette.using_context("chrome"):
[task 2021-02-26T17:50:20.973Z] 17:50:20 INFO - File "c:\mozilla-build\python\lib\contextlib.py", line 17, in enter
[task 2021-02-26T17:50:20.973Z] 17:50:20 INFO - return self.gen.next()
[task 2021-02-26T17:50:20.973Z] 17:50:20 INFO - File "C:\Users\task_1614358816\build\venv\lib\site-packages\marionette_driver\marionette.py", line 1403, in using_context
[task 2021-02-26T17:50:20.973Z] 17:50:20 INFO - scope = self._send_message("Marionette:GetContext", key="value")
[task 2021-02-26T17:50:20.973Z] 17:50:20 INFO - File "C:\Users\task_1614358816\build\venv\lib\site-packages\marionette_driver\decorators.py", line 27, in _
[task 2021-02-26T17:50:20.973Z] 17:50:20 INFO - return func(*args, **kwargs)
[task 2021-02-26T17:50:20.974Z] 17:50:20 INFO - File "C:\Users\task_1614358816\build\venv\lib\site-packages\marionette_driver\marionette.py", line 618, in _send_message
[task 2021-02-26T17:50:20.974Z] 17:50:20 INFO - raise errors.InvalidSessionIdException("Please start a session")
[task 2021-02-26T17:50:20.974Z] 17:50:20 INFO - TEST-INFO took 431378ms
[task 2021-02-26T17:50:20.974Z] 17:50:20 INFO - test_end for testing/marionette/harness/marionette_harness/tests/unit/test_click.py TestClickNavigation.test_click_link_install_addon logged while not in progress. Logged with data: {"status": "ERROR", "extra": {"class_name": "test_click.TestClickNavigation", "method_name": "test_click_link_install_addon"}, "expected": "PASS", "test": "testing/marionette/harness/marionette_harness/tests/unit/test_click.py TestClickNavigation.test_click_link_install_addon", "message": "InvalidSessionIdException: Please start a session", "stack": "Traceback (most recent call last):\n File "C:\Users\task_1614358816\build\venv\lib\site-packages\marionette_harness\marionette_test\testcases.py", line 247, in run\n self.tearDown()\n File "C:\Users\task_1614358816\build\tests\marionette\tests\testing\marionette\harness\marionette_harness\tests\unit\test_click.py", line 489, in tearDown\n self.close_all_tabs()\n File "C:\Users\task_1614358816\build\venv\lib\site-packages\marionette_harness\runner\mixins\window_manager.py", line 33, in close_all_tabs\n current_window_handles = self.marionette.window_handles\n File "C:\Users\task_1614358816\build\venv\lib\site-packages\marionette_driver\marionette.py", line 1324, in window_handles\n return self._send_message("WebDriver:GetWindowHandles")\n File "C:\Users\task_1614358816\build\venv\lib\site-packages\marionette_driver\decorators.py", line 27, in _\n return func(*args, **kwargs)\n File "C:\Users\task_1614358816\build\venv\lib\site-packages\marionette_driver\marionette.py", line 618, in _send_message\n raise errors.InvalidSessionIdException("Please start a session")\n"}

Henrik can you assign someone to take a look?

Comment 32

[Intermittent Failures Robot]

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

This is the #39 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: 26
• try: 2
• mozilla-beta: 1
• mozilla-central: 1

Platform and build breakdown:

• windows10-64: 29
  • debug: 21
  • opt: 8
• windows10-64-shippable: 1
  • opt: 1

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

Comment 33

[Intermittent Failures Robot]

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

This is the #38 most frequent failure this week.

Repository breakdown:

• mozilla-central: 3
• autoland: 17
• try: 4
• mozilla-release: 1

Platform and build breakdown:

• windows10-64-shippable: 3
  • opt: 3
• windows10-64: 21
  • debug: 20
  • opt: 1
• windows10-64-devedition: 1
  • opt: 1

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

Comment 34

[Intermittent Failures Robot]

21 failures in 1036 pushes (0.02 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 19
• try: 1
• mozilla-central: 1

Platform and build breakdown:

• windows10-64: 2
  • debug: 2
• windows10-64-devedition: 1
  • opt: 1
• windows10-64-qr: 17
  • opt: 8
  • debug: 9
• windows10-64-shippable: 1
  • opt: 1

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

Comment 35

[Intermittent Failures Robot]

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

This is the #21 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: 2
• autoland: 46
• mozilla-central: 2

Platform and build breakdown:

• windows10-64: 14
  • debug: 12
  • opt: 2
• windows10-64-shippable: 2
  • opt: 2
• windows10-64-devedition: 1
  • opt: 1
• windows10-64-qr: 32
  • opt: 14
  • debug: 18
• linux1804-64-qr: 1
  • debug: 1

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

Comment 36

[Sandor Molnar[:smolnar]]

Attachment: Bug 1627965 - Disabled test_click.py for frequent win failures. r=#intermittent-reviewers

Comment 37

[Julian Descottes [:jdescottes]]

Attachment: Bug 1627965 - [marionette] Skip test_click_link_install_addon in test_click.py on Windows

See discussion in D108637

Comment 38

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/458e9aec2d06
[marionette] Skip test_click_link_install_addon in test_click.py on Windows r=marionette-reviewers,whimboo

Comment 39

[Alexandru Michis [:malexandru]]

https://hg.mozilla.org/mozilla-central/rev/458e9aec2d06

Comment 40

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 14
• mozilla-central: 3
• try: 1

Platform and build breakdown:

• windows10-64: 7
  • debug: 7
• windows10-64-qr: 11
  • opt: 4
  • debug: 7

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

Comment 41

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-release: 1

Platform and build breakdown:

• windows10-64-shippable: 1
  • opt: 1

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

Comment 42

[BMO Automation]

Moving bug to Testing::Marionette Client and Harness component per bug 1815831.

Comment 43

[BugBot [:suhaib / :marco/ :calixte]]

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

Comment 44

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

Not sure why this was closed given that the particular test is disabled and we had the leave-open keyword set. Calixte, can you please check the bot? Thanks.

Comment 45

[Marco Castelluccio [:marco]]

The bot doesn't consider the leave-open keyword because it is normally used to mean that the bug isn't completely fixed yet. In the case of intermittents, there might be something else changing somewhere else causing the bug to be fixed, so we ignore the leave-open keyword.

We should take into account the test-disabled keyword though, so there's a bug there (the bot is looking for test-disabled in the whiteboard but not in the keywords).

Comment 46

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

Thanks Marco. And as well for the fix that already got landed.

I've pushed a try build to check if the test actually works these days:
https://treeherder.mozilla.org/jobs?repo=try&revision=da3ceddfda35167839a09e5e771b16284e791c9a

Comment 47

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

Attachment: Bug 1627965 - [marionette] Re-enable test_click_link_install_addon in test_click.py on Windows.

Comment 48

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/882b1cbb73c1
[marionette] Re-enable test_click_link_install_addon in test_click.py on Windows. r=webdriver-reviewers,jgraham

Comment 49

[Cosmin Sabou [:CosminS]]

https://hg.mozilla.org/mozilla-central/rev/882b1cbb73c1