1535598 - Intermittent testing/marionette/harness/marionette_harness/tests/unit/test_navigation.py TestBackForwardNavigation.test_frameset_after_navigating_in_frame | TimeoutException: Timed out after 300.1 seconds with message:Target element 'email' has not been f

Status: RESOLVED FIXED

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

Description

[Treeherder Bug Filer]

#[markdown(off)]
Filed by: opoprus [at] mozilla.com

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

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

[task 2019-03-15T10:51:54.617Z] 10:51:54 INFO - TEST-START | testing/marionette/harness/marionette_harness/tests/unit/test_navigation.py TestBackForwardNavigation.test_frameset_after_navigating_in_frame
[task 2019-03-15T10:56:56.003Z] 10:56:56 WARNING - TEST-UNEXPECTED-ERROR | testing/marionette/harness/marionette_harness/tests/unit/test_navigation.py TestBackForwardNavigation.test_frameset_after_navigating_in_frame | TimeoutException: Timed out after 300.1 seconds with message: Target element 'email' has not been found
[task 2019-03-15T10:56:56.003Z] 10:56:56 INFO - Traceback (most recent call last):
[task 2019-03-15T10:56:56.003Z] 10:56:56 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_harness/marionette_test/testcases.py", line 159, in run
[task 2019-03-15T10:56:56.003Z] 10:56:56 INFO - testMethod()
[task 2019-03-15T10:56:56.003Z] 10:56:56 INFO - File "/builds/worker/workspace/build/tests/marionette/tests/testing/marionette/harness/marionette_harness/tests/unit/test_navigation.py", line 471, in test_frameset_after_navigating_in_frame
[task 2019-03-15T10:56:56.003Z] 10:56:56 INFO - message="Target element 'email' has not been found")
[task 2019-03-15T10:56:56.003Z] 10:56:56 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/wait.py", line 154, in until
[task 2019-03-15T10:56:56.003Z] 10:56:56 INFO - cause=last_exc)
[task 2019-03-15T10:56:56.003Z] 10:56:56 INFO - TEST-INFO took 301386ms

Comment 1

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 1
• autoland: 1

Platform breakdown:

• android-em-7-0-x86_64: 2

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

Comment 2

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 5

Platform breakdown:

• android-em-7-0-x86_64: 5

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

Comment 3

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1
• mozilla-inbound: 2

Platform breakdown:

• android-em-7-0-x86_64: 3

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

Comment 4

[Intermittent Failures Robot]

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

Repository breakdown:

• try: 1
• autoland: 1
• mozilla-beta: 1

Platform breakdown:

• android-em-7-0-x86_64: 3

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

Comment 5

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 3
• mozilla-central: 1

Platform breakdown:

• android-em-7-0-x86_64: 4

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

Comment 6

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 8
• mozilla-central: 1

Platform breakdown:

• android-em-7-0-x86_64: 9

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

Comment 7

[Intermittent Failures Robot]

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

Repository breakdown:

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

Platform breakdown:

• android-em-7-0-x86_64: 12

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

Comment 8

[Intermittent Failures Robot]

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

** 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: 25
• mozilla-central: 1
• try: 2
• mozilla-inbound: 3
• mozilla-beta: 1

Platform breakdown:

• android-em-7-0-x86_64: 32

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

Comment 9

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-inbound: 4
• autoland: 21
• mozilla-central: 2

Platform breakdown:

• android-em-7-0-x86_64: 27

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

Comment 10

[Intermittent Failures Robot]

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

** 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:

• mozilla-beta: 1
• autoland: 20
• mozilla-inbound: 6
• mozilla-central: 2
• mozilla-release: 1

Platform breakdown:

• android-em-7-0-x86_64: 30

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

Comment 11

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

Here an excerpt from the android logcat:

05-18 22:14:46.876 2373 2389 I Gecko : 1558214086876 Marionette DEBUG 497 -> [0,24,"WebDriver:FindElement",{"using":"id","value":"linkId"}]
05-18 22:14:46.877 2373 2389 I Gecko : 1558214086877 Marionette DEBUG 497 <- [1,24,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"77cd54cd-aaca-4b38-8426-f34f170a4607"}}]
05-18 22:14:46.878 2373 2389 I Gecko : 1558214086878 Marionette DEBUG 497 -> [0,25,"WebDriver:ElementClick",{"id":"77cd54cd-aaca-4b38-8426-f34f170a4607"}]
05-18 22:14:47.395 2373 2373 D GeckoToolbar: onTabChanged: THUMBNAIL
05-18 22:14:47.395 2373 2373 D GeckoBrowserApp: BrowserApp.onTabChanged: 8: THUMBNAIL
05-18 22:14:47.395 2373 2389 I Gecko : 1558214087395 Marionette WARN TimedPromise timed out after 500 ms: stacktrace:
05-18 22:14:47.395 2373 2389 I Gecko : bail@chrome://marionette/content/sync.js:223:64
05-18 22:14:47.609 2373 2389 I Gecko : 1558214087609 Marionette DEBUG [363] Canceled page load listener because no navigation has been detected
05-18 22:14:47.610 2373 2389 I Gecko : 1558214087610 Marionette DEBUG 497 <- [1,25,null,{"value":null}]
05-18 22:14:47.610 2373 2389 I Gecko : 1558214087610 Marionette DEBUG 497 -> [0,26,"WebDriver:GetTimeouts",{}]
05-18 22:14:47.611 2373 2389 I Gecko : 1558214087611 Marionette DEBUG 497 <- [1,26,null,{"implicit":0,"pageLoad":300000,"script":30000}]
05-18 22:14:47.611 2373 2389 I Gecko : 1558214087611 Marionette DEBUG 497 -> [0,27,"WebDriver:FindElement",{"using":"id","value":"email"}]
05-18 22:14:47.612 2373 2389 I Gecko : 1558214087612 Marionette DEBUG 497 <- [1,27,{"error":"no such element","message":"Unable to locate element: email","stacktrace":"WebDriverError@chrome://marionette ... entError@chrome://marionette/content/error.js:389:5\nelement.find/</<@chrome://marionette/content/element.js:339:16\n"},null]

As it can be seen we were trying to click the link, but no navigation has been taken place within 500ms. As such the target element of the expected new page isn't present.

Comment 12

[Intermittent Failures Robot]

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

This is the #50 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-inbound: 6
• mozilla-central: 2
• mozilla-beta: 3

Platform breakdown:

• android-em-7-0-x86_64: 46

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

Comment 13

[Bogdan Tara[:bogdan_tara | bogdant]]

In the last 7 days there have been 42 occurrences on Andoid 7 32 Opt.

Comment 14

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 21
• mozilla-beta: 1
• mozilla-central: 1
• mozilla-release: 1
• mozilla-inbound: 1

Platform breakdown:

• android-em-7-0-x86_64: 25

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

Comment 15

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

The rate of failures actually went down drastically the last days. I will keep an eye on it and will re-check by next Monday.

Comment 16

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-beta: 1
• try: 1

Platform breakdown:

• android-em-7-0-x86_64-beta: 1
• android-em-7-0-x86_64: 1

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

Comment 17

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

No more failures on trunk. Most likely this was fixed with my patch on bug 1335085.

Comment 18

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

The patches on bug 1335085 got uplifted to beta:

https://hg.mozilla.org/releases/mozilla-beta/rev/dde54edc86e1
https://hg.mozilla.org/releases/mozilla-beta/rev/1e1c5ad53cfc
https://hg.mozilla.org/releases/mozilla-beta/rev/992cbc55ecf7

Comment 19

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-esr68: 2
• mozilla-beta: 1

Platform breakdown:

• android-em-7-0-x86_64-beta: 2
• android-em-7-0-x86_64: 1

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

Comment 20

[Bogdan Tara[:bogdan_tara | bogdant]]

Seeing this on beta:
https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=252198473&repo=mozilla-beta&lineNumber=2387

[task 2019-06-17T17:40:04.061Z] 17:40:04 INFO - TEST-START | testing/marionette/harness/marionette_harness/tests/unit/test_navigation.py TestBackForwardNavigation.test_frameset
[task 2019-06-17T17:40:05.593Z] 17:40:05 INFO - TEST-PASS | testing/marionette/harness/marionette_harness/tests/unit/test_navigation.py TestBackForwardNavigation.test_frameset | took 1531ms
[task 2019-06-17T17:40:05.799Z] 17:40:05 INFO - TEST-START | testing/marionette/harness/marionette_harness/tests/unit/test_navigation.py TestBackForwardNavigation.test_frameset_after_navigating_in_frame
[task 2019-06-17T17:45:07.097Z] 17:45:07 WARNING - TEST-UNEXPECTED-ERROR | testing/marionette/harness/marionette_harness/tests/unit/test_navigation.py TestBackForwardNavigation.test_frameset_after_navigating_in_frame | TimeoutException: Timed out after 300.1 seconds with message: Target element 'email' has not been found
[task 2019-06-17T17:45:07.097Z] 17:45:07 INFO - Traceback (most recent call last):
[task 2019-06-17T17:45:07.097Z] 17:45:07 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_harness/marionette_test/testcases.py", line 159, in run
[task 2019-06-17T17:45:07.097Z] 17:45:07 INFO - testMethod()
[task 2019-06-17T17:45:07.097Z] 17:45:07 INFO - File "/builds/worker/workspace/build/tests/marionette/tests/testing/marionette/harness/marionette_harness/tests/unit/test_navigation.py", line 471, in test_frameset_after_navigating_in_frame
[task 2019-06-17T17:45:07.097Z] 17:45:07 INFO - message="Target element 'email' has not been found")
[task 2019-06-17T17:45:07.097Z] 17:45:07 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/wait.py", line 154, in until
[task 2019-06-17T17:45:07.097Z] 17:45:07 INFO - cause=last_exc)
[task 2019-06-17T17:45:07.097Z] 17:45:07 INFO - TEST-INFO took 301297ms

Comment 21

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-beta: 1

Platform breakdown:

• android-em-7-0-x86_64: 1

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

Comment 22

[Andreea Pavel [:apavel]]

Hi Henrik, this is still ongoing on mozilla-beta: https://treeherder.mozilla.org/#/jobs?repo=mozilla-beta&selectedJob=254134544&resultStatus=testfailed%2Cbusted%2Cexception

Should we file a new bug or reopen this one?

Comment 23

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

Please file a new bug. It could be something different. Thanks.

Comment 24

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-beta: 1

Platform breakdown:

• android-em-7-0-x86_64-release: 1

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

Comment 26

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-esr68: 2

Platform breakdown:

• android-em-7-0-x86_64-release: 2

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

Comment 27

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-esr68: 1

Platform breakdown:

• android-em-7-0-x86_64-release: 1

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

Comment 28

[BMO Automation]

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