1570191 - Intermittent TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/add_cookie/add.py | expected OK

Status: RESOLVED DUPLICATE of bug 1630162

(Remote Protocol :: Marionette, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: nerli [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=259098481&repo=autoland
Full log: https://queue.taskcluster.net/v1/task/fG5URrQ0Q56QV4Hx3SH2FA/runs/0/artifacts/public/logs/live_backing.log

---

[task 2019-07-30T23:20:11.508Z] 23:20:11 INFO - STDOUT: tests/web-platform/tests/webdriver/tests/add_cookie/add.py::test_null_response_value
[task 2019-07-30T23:20:11.508Z] 23:20:11 INFO - PID 1094 | 1564528811491 webdriver::server DEBUG -> POST /session/6aa4b702-a140-4d25-9d88-9c954e33034e/window/rect {"width": 800, "height": 600}
[task 2019-07-30T23:20:11.509Z] 23:20:11 INFO - PID 1094 | 1564528811492 Marionette DEBUG 0 -> [0,18,"WebDriver:SetWindowRect",{"height":600,"width":800}]
[task 2019-07-30T23:20:11.510Z] 23:20:11 INFO - PID 1094 | 1564528811492 Marionette DEBUG 0 <- [1,18,null,{"x":100,"y":100,"width":800,"height":600}]
[task 2019-07-30T23:20:11.511Z] 23:20:11 INFO - TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/add_cookie/add.py | expected OK
[task 2019-07-30T23:20:11.511Z] 23:20:11 INFO - TEST-INFO took 30010ms
[task 2019-07-30T23:20:11.722Z] 23:20:11 INFO - PID 1094 | 1564528811493 webdriver::server DEBUG <- 200 OK {"value":{"x":100,"y":100,"width":800,"height":600}}
[task 2019-07-30T23:20:11.723Z] 23:20:11 INFO - PID 1094 | 1564528811496 webdriver::server DEBUG -> POST /session/6aa4b702-a140-4d25-9d88-9c954e33034e/window/rect {"y": 100, "x": 100}
[task 2019-07-30T23:20:11.724Z] 23:20:11 INFO - PID 1094 | 1564528811497 Marionette DEBUG 0 -> [0,19,"WebDriver:SetWindowRect",{"x":100,"y":100}]
[task 2019-07-30T23:20:11.725Z] 23:20:11 INFO - PID 1094 | 1564528811497 Marionette DEBUG 0 <- [1,19,null,{"x":100,"y":100,"width":800,"height":600}]
[task 2019-07-30T23:20:11.727Z] 23:20:11 INFO - PID 1094 | 1564528811498 webdriver::server DEBUG <- 200 OK {"value":{"x":100,"y":100,"width":800,"height":600}}
[task 2019-07-30T23:20:11.727Z] 23:20:11 INFO - STDOUT: FAILED
[task 2019-07-30T23:20:11.727Z] 23:20:11 INFO - Closing logging queue
[task 2019-07-30T23:20:11.728Z] 23:20:11 INFO - queue closed
[task 2019-07-30T23:20:11.730Z] 23:20:11 INFO - Starting runner
[task 2019-07-30T23:20:11.744Z] 23:20:11 INFO - PID 1275 | 1564528811741 geckodriver DEBUG Listening on 127.0.0.1:43911
[task 2019-07-30T23:20:12.247Z] 23:20:12 INFO - WebDriver HTTP server listening at http://127.0.0.1:43911/

Comment 1

[Henrik Skupin [:whimboo][⌚️UTC+2] (away 10/03 - 10/13)]

Timeout due to a slow startup of Firefox, which alone took 28s:

[task 2019-07-30T23:19:43.183Z] 23:19:43 INFO - PID 1094 | 1564528783169 geckodriver::marionette DEBUG Waiting 60s to connect to browser on 127.0.0.1:2828
[task 2019-07-30T23:19:43.199Z] 23:19:43 INFO - PID 1094 | *** You are running in headless mode.
[task 2019-07-30T23:19:50.838Z] 23:19:50 INFO - PID 1094 | 1564528790831 [e-mail] WARN Loading extension '[e-mail]': Reading manifest: Invalid extension permission: mozillaAddons
[task 2019-07-30T23:19:50.839Z] 23:19:50 INFO - PID 1094 | 1564528790831 [e-mail] WARN Loading extension '[e-mail]': Reading manifest: Invalid extension permission: telemetry
[task 2019-07-30T23:19:50.839Z] 23:19:50 INFO - PID 1094 | 1564528790832 [e-mail] WARN Loading extension '[e-mail]': Reading manifest: Invalid extension permission: resource://pdf.js/
[task 2019-07-30T23:19:50.840Z] 23:19:50 INFO - PID 1094 | 1564528790832 [e-mail] WARN Loading extension '[e-mail]': Reading manifest: Invalid extension permission: about:reader*
[task 2019-07-30T23:19:52.345Z] 23:19:52 INFO - PID 1094 | 1564528792335 Marionette TRACE Received observer notification profile-after-change
[task 2019-07-30T23:19:54.897Z] 23:19:54 INFO - PID 1094 | 1564528794892 Marionette TRACE Received observer notification command-line-startup
[task 2019-07-30T23:19:54.898Z] 23:19:54 INFO - PID 1094 | 1564528794892 Marionette TRACE Received observer notification nsPref:changed
[task 2019-07-30T23:19:54.898Z] 23:19:54 INFO - PID 1094 | 1564528794893 Marionette DEBUG Init aborted (running=false, enabled=true, finalUIStartup=false)
[task 2019-07-30T23:20:05.422Z] 23:20:05 INFO - PID 1094 | 1564528805416 Marionette TRACE Received observer notification toplevel-window-ready
[task 2019-07-30T23:20:11.141Z] 23:20:11 INFO - PID 1094 | 1564528811137 Marionette TRACE Received observer notification marionette-startup-requested
[task 2019-07-30T23:20:11.141Z] 23:20:11 INFO - PID 1094 | 1564528811137 Marionette TRACE Waiting until startup recorder finished recording startup scripts...
[task 2019-07-30T23:20:11.264Z] 23:20:11 INFO - PID 1094 | 1564528811249 Marionette TRACE All scripts recorded.

Comment 2

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

• linux64-shippable: 1

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

Comment 3

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 1

Platform breakdown:

• linux64-ccov: 1

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

Comment 4

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 1

Platform breakdown:

• linux64-ccov: 1

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

Comment 5

[Firefox Bug Husbandry Bot]

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

Comment 6

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

• linux64-shippable: 1

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

Comment 7

[Raul Gurzau (:RaulG)]

New failure: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=270578034&repo=mozilla-central&lineNumber=1804

Comment 8

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 1

Platform breakdown:

• linux64-qr: 1

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

Comment 9

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

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

Comment 10

[Noemi Erli[:noemi_erli]]

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

Comment 11

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

• windows10-64-shippable: 1

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

Comment 12

[Intermittent Failures Robot]

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

Repository breakdown:

• try: 1

Platform breakdown:

• linux64-shippable: 1

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

Comment 13

[Intermittent Failures Robot]

1 failures in 4582 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=1570191&startday=2019-12-16&endday=2019-12-22&tree=all

Comment 14

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

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

Comment 15

[Razvan Maries]

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

Comment 16

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

• linux1804-64-asan: 1

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

Comment 17

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

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

Comment 18

[Alexandru Michis [:malexandru]]

Recent failure log: https://treeherder.mozilla.org/logviewer?job_id=325386229&repo=autoland&lineNumber=1096

Comment 19

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

• windows10-64-shippable-qr: 1

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

Comment 20

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

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

Comment 21

[Atila Butkovits]

Recent failure log: https://treeherder.mozilla.org/logviewer?job_id=356891336&repo=autoland&lineNumber=1330

Comment 22

[Henrik Skupin [:whimboo][⌚️UTC+2] (away 10/03 - 10/13)]

(In reply to Atila Butkovits from comment #21)

Recent failure log: https://treeherder.mozilla.org/logviewer?job_id=356891336&repo=autoland&lineNumber=1330

That's a different issue which results in the same kind of failure:
https://treeherder.mozilla.org/logviewer?job_id=356891336&repo=autoland&lineNumber=1163

[task 2021-11-03T16:35:13.327Z] 16:35:13     INFO - PID 5576 | 1635957313326	geckodriver::marionette	TRACE	No connection could be made because the target machine actively refused it. (os error 10061). Retrying in 100ms

Comment 23

[Henrik Skupin [:whimboo][⌚️UTC+2] (away 10/03 - 10/13)]

Actually there is no marionette-startup-requested notification sent by Firefox.

Comment 24

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• windows10-64-2004-qr: 1
  • debug: 1

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

Comment 25

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

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

Comment 26

[Cosmin Sabou [:CosminS]]

https://treeherder.mozilla.org/logviewer?job_id=360013190&repo=autoland

Comment 27

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• windows10-64-2004-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1570191&startday=2021-11-29&endday=2021-12-05&tree=all

Comment 28

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

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

Comment 29

[Iulian Moraru]

Recent failure log: https://treeherder.mozilla.org/logviewer?job_id=362791254&repo=autoland&lineNumber=1229

Comment 30

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• windows10-64-2004-qr: 1
  • debug: 1

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

Comment 31

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

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

Comment 32

[Treeherder Bug Filer]

New failure instance: https://treeherder.mozilla.org/logviewer?job_id=367599302&repo=mozilla-central

Comment 33

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 1

Platform and build breakdown:

• windows10-32-2004-shippable-qr: 1
  • opt: 1

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

Comment 34

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

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

Comment 35

[Treeherder Bug Filer]

New failure instance: https://treeherder.mozilla.org/logviewer?job_id=370797786&repo=mozilla-beta

Comment 36

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-beta: 1

Platform and build breakdown:

• windows10-32-2004-shippable-qr: 1
  • opt: 1

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

Comment 37

[Henrik Skupin [:whimboo][⌚️UTC+2] (away 10/03 - 10/13)]

All the latest failures on this issue are related to shutdown hangs. As such I'm going and dupe it for bug 1630162.

Comment 38

[BMO Automation]

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