1615804 - Intermittent TEST-UNEXPECTED-TIMEOUT | toolkit/mozapps/extensions/test/browser/browser_dragdrop.js | application timed out after 370 seconds with no output

Status: RESOLVED INCOMPLETE

(Toolkit :: Add-ons Manager, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2020-02-15T12:08:39.672Z] 12:08:39 INFO - TEST-START | toolkit/mozapps/extensions/test/browser/browser_dragdrop.js
[task 2020-02-15T12:08:40.804Z] 12:08:40 INFO - GECKO(3044) | 1581768520800 addons.xpi DEBUG Download started for http://example.com/browser/toolkit/mozapps/extensions/test/browser/addons/browser_dragdrop1.xpi to file C:\Users\task_1581764694\AppData\Local\Temp\tmp-r8y.xpi
[task 2020-02-15T12:08:40.809Z] 12:08:40 INFO - GECKO(3044) | 1581768520803 addons.xpi DEBUG Download of http://example.com/browser/toolkit/mozapps/extensions/test/browser/addons/browser_dragdrop1.xpi completed.
[task 2020-02-15T12:08:40.929Z] 12:08:40 INFO - GECKO(3044) | 1581768520924 addons.repository DEBUG cacheAddons: enabled false IDs ["[e-mail]"]
[task 2020-02-15T12:08:41.070Z] 12:08:41 INFO - GECKO(3044) | Manager window unload handler
[task 2020-02-15T12:08:41.070Z] 12:08:41 INFO - GECKO(3044) | 1581768521068 addons.xpi INFO Install of [e-mail] cancelled by user
[task 2020-02-15T12:08:41.151Z] 12:08:41 INFO - GECKO(3044) | JavaScript error: resource://gre/actors/BrowserElementParent.jsm, line 81: TypeError: browser is null
[task 2020-02-15T12:08:42.326Z] 12:08:42 INFO - GECKO(3044) | 1581768522324 addons.repository DEBUG cacheAddons: enabled false IDs ["[e-mail]"]
[task 2020-02-15T12:08:42.628Z] 12:08:42 INFO - GECKO(3044) | Manager window unload handler
[task 2020-02-15T12:08:42.633Z] 12:08:42 INFO - GECKO(3044) | 1581768522629 addons.xpi INFO Install of [e-mail] cancelled by user
[task 2020-02-15T12:08:42.693Z] 12:08:42 INFO - GECKO(3044) | JavaScript error: resource://gre/actors/BrowserElementParent.jsm, line 81: TypeError: browser is null
[task 2020-02-15T12:08:44.038Z] 12:08:44 INFO - GECKO(3044) | 1581768524035 addons.xpi DEBUG Download started for http://example.com/browser/toolkit/mozapps/extensions/test/browser/addons/browser_dragdrop1.xpi to file C:\Users\task_1581764694\AppData\Local\Temp\tmp-9hm.xpi
[task 2020-02-15T12:08:44.040Z] 12:08:44 INFO - GECKO(3044) | 1581768524037 addons.xpi DEBUG Download of http://example.com/browser/toolkit/mozapps/extensions/test/browser/addons/browser_dragdrop1.xpi completed.
[task 2020-02-15T12:08:44.070Z] 12:08:44 INFO - GECKO(3044) | 1581768524065 addons.xpi DEBUG Download started for http://example.org/browser/toolkit/mozapps/extensions/test/browser/addons/browser_dragdrop2.xpi to file C:\Users\task_1581764694\AppData\Local\Temp\tmp-jrv.xpi
[task 2020-02-15T12:08:44.070Z] 12:08:44 INFO - GECKO(3044) | 1581768524067 addons.xpi DEBUG Download of http://example.org/browser/toolkit/mozapps/extensions/test/browser/addons/browser_dragdrop2.xpi completed.
[task 2020-02-15T12:08:44.176Z] 12:08:44 INFO - GECKO(3044) | 1581768524171 addons.repository DEBUG cacheAddons: enabled false IDs ["[e-mail]"]
[task 2020-02-15T12:08:44.218Z] 12:08:44 INFO - GECKO(3044) | 1581768524216 addons.repository DEBUG cacheAddons: enabled false IDs ["[e-mail]"]
[task 2020-02-15T12:08:44.318Z] 12:08:44 INFO - GECKO(3044) | 1581768524307 addons.xpi INFO Install of [e-mail] cancelled by user
[task 2020-02-15T12:08:44.387Z] 12:08:44 INFO - GECKO(3044) | Manager window unload handler
[task 2020-02-15T12:08:44.397Z] 12:08:44 INFO - GECKO(3044) | 1581768524394 addons.xpi INFO Install of [e-mail] cancelled by user
[task 2020-02-15T12:08:44.476Z] 12:08:44 INFO - GECKO(3044) | JavaScript error: resource://gre/actors/BrowserElementParent.jsm, line 81: TypeError: browser is null
[task 2020-02-15T12:08:45.671Z] 12:08:45 INFO - GECKO(3044) | 1581768525661 addons.repository DEBUG cacheAddons: enabled false IDs ["[e-mail]"]
[task 2020-02-15T12:08:45.741Z] 12:08:45 INFO - GECKO(3044) | 1581768525737 addons.repository DEBUG cacheAddons: enabled false IDs ["[e-mail]"]
[task 2020-02-15T12:08:45.921Z] 12:08:45 INFO - GECKO(3044) | 1581768525909 addons.xpi INFO Install of [e-mail] cancelled by user
[task 2020-02-15T12:08:46.000Z] 12:08:46 INFO - GECKO(3044) | Manager window unload handler
[task 2020-02-15T12:08:46.002Z] 12:08:46 INFO - GECKO(3044) | 1581768525999 addons.xpi INFO Install of [e-mail] cancelled by user
[task 2020-02-15T12:08:46.068Z] 12:08:46 INFO - GECKO(3044) | JavaScript error: resource://gre/actors/BrowserElementParent.jsm, line 81: TypeError: browser is null
[task 2020-02-15T12:08:46.897Z] 12:08:46 INFO - GECKO(3044) | 1581768526886 addons.xpi DEBUG Download started for http://example.com/browser/toolkit/mozapps/extensions/test/browser/addons/browser_dragdrop1.xpi to file C:\Users\task_1581764694\AppData\Local\Temp\tmp-r03.xpi
[task 2020-02-15T12:08:46.897Z] 12:08:46 INFO - GECKO(3044) | 1581768526889 addons.xpi DEBUG Download of http://example.com/browser/toolkit/mozapps/extensions/test/browser/addons/browser_dragdrop1.xpi completed.
[task 2020-02-15T12:08:46.976Z] 12:08:46 INFO - GECKO(3044) | 1581768526969 addons.repository DEBUG cacheAddons: enabled false IDs ["[e-mail]"]
[task 2020-02-15T12:08:47.022Z] 12:08:47 INFO - GECKO(3044) | 1581768527017 addons.repository DEBUG cacheAddons: enabled false IDs ["[e-mail]"]
[task 2020-02-15T12:08:47.165Z] 12:08:47 INFO - GECKO(3044) | 1581768527160 addons.xpi INFO Install of [e-mail] cancelled by user
[task 2020-02-15T12:14:57.192Z] 12:14:57 INFO - Buffered messages logged at 12:08:39
[task 2020-02-15T12:14:57.193Z] 12:14:57 INFO - Entering test bound test_drop_url
[task 2020-02-15T12:14:57.193Z] 12:14:57 INFO - Loading manager window in tab
[task 2020-02-15T12:14:57.194Z] 12:14:57 INFO - Buffered messages logged at 12:08:40
[task 2020-02-15T12:14:57.194Z] 12:14:57 INFO - TEST-PASS | toolkit/mozapps/extensions/test/browser/browser_dragdrop.js | Should have an add-ons manager window -

[task 2020-02-15T12:14:57.244Z] 12:14:57 INFO - TEST-PASS | toolkit/mozapps/extensions/test/browser/browser_dragdrop.js | addon-webext-permissions notification shown -
[task 2020-02-15T12:14:57.244Z] 12:14:57 INFO - TEST-PASS | toolkit/mozapps/extensions/test/browser/browser_dragdrop.js | notification panel open -
[task 2020-02-15T12:14:57.244Z] 12:14:57 INFO - Saw install for Drag Drop test 2
[task 2020-02-15T12:14:57.244Z] 12:14:57 INFO - Waiting for installs for Drag Drop test 1
[task 2020-02-15T12:14:57.244Z] 12:14:57 INFO - Console message: 1581768527017 addons.repository DEBUG cacheAddons: enabled false IDs ["[e-mail]"]
[task 2020-02-15T12:14:57.244Z] 12:14:57 INFO - Console message: 1581768527160 addons.xpi INFO Install of [e-mail] cancelled by user
[task 2020-02-15T12:14:57.244Z] 12:14:57 INFO - Buffered messages finished
[task 2020-02-15T12:14:57.245Z] 12:14:57 ERROR - TEST-UNEXPECTED-TIMEOUT | toolkit/mozapps/extensions/test/browser/browser_dragdrop.js | application timed out after 370 seconds with no output
[task 2020-02-15T12:14:57.245Z] 12:14:57 ERROR - Force-terminating active process(es).
[task 2020-02-15T12:14:57.245Z] 12:14:57 INFO - Determining child pids from psutil...
[task 2020-02-15T12:14:57.245Z] 12:14:57 INFO - [6648]
[task 2020-02-15T12:14:57.245Z] 12:14:57 INFO - ==> process 6648 launched child process 7240 ("Z:\task_1581764694\build\application\firefox\firefox.exe" -contentproc --channel="6648.0.860741166\782977618" -parentBuildID 20200215102537 -prefsHandle 2020 -prefMapHandle 2012 -prefsLen 1 -prefMapSize 226256 -greomni "Z:\task_1581764694\build\application\firefox\omni.ja" -appomni "Z:\task_1581764694\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1581764694\build\application\firefox\browser" - 6648 gpu)
[task 2020-02-15T12:14:57.245Z] 12:14:57 INFO - ==> process 6648 launched child process 3408 ("Z:\task_1581764694\build\application\firefox\firefox.exe" -contentproc --channel="6648.6.1620688095\1741219993" -childID 1 -isForBrowser -prefsHandle 2656 -prefMapHandle 2652 -prefsLen 1353 -prefMapSize 226256 -parentBuildID 20200215102537 -greomni "Z:\task_1581764694\build\application\firefox\omni.ja" -appomni "Z:\task_1581764694\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1581764694\build\application\firefox\browser" - 6648 tab)
[task 2020-02-15T12:14:57.245Z] 12:14:57 INFO - ==> process 6648 launched child process 6072 ("Z:\task_1581764694\build\application\firefox\firefox.exe" -contentproc --channel="6648.13.1014505207\1003476320" -childID 2 -isForBrowser -prefsHandle 2896 -prefMapHandle 2884 -prefsLen 1484 -prefMapSize 226256 -parentBuildID 20200215102537 -greomni "Z:\task_1581764694\build\application\firefox\omni.ja" -appomni "Z:\task_1581764694\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1581764694\build\application\firefox\browser" - 6648 tab)
[task 2020-02-15T12:14:57.246Z] 12:14:57 INFO - ==> process 6648 launched child process 5412 ("Z:\task_1581764694\build\application\firefox\firefox.exe" -contentproc --channel="6648.20.749868769\785447576" -childID 3 -isForBrowser -prefsHandle 2816 -prefMapHandle 2812 -prefsLen 1545 -prefMapSize 226256 -parentBuildID 20200215102537 -greomni "Z:\task_1581764694\build\application\firefox\omni.ja" -appomni "Z:\task_1581764694\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1581764694\build\application\firefox\browser" - 6648 tab)
[task 2020-02-15T12:14:57.246Z] 12:14:57 INFO - ==> process 6648 launched child process 2668 ("Z:\task_1581764694\build\application\firefox\firefox.exe" -contentproc --channel="6648.27.300246592\417329071" -childID 4 -isForBrowser -prefsHandle 4080 -prefMapHandle 4076 -prefsLen 11227 -prefMapSize 226256 -parentBuildID 20200215102537 -greomni "Z:\task_1581764694\build\application\firefox\omni.ja" -appomni "Z:\task_1581764694\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1581764694\build\application\firefox\browser" - 6648 tab)
[task 2020-02-15T12:14:57.246Z] 12:14:57 INFO - ==> process 6648 launched child process 3372 ("Z:\task_1581764694\build\application\firefox\firefox.exe" -contentproc --channel="6648.34.1538514024\1803393122" -childID 5 -isForBrowser -prefsHandle 4388 -prefMapHandle 4384 -prefsLen 11363 -prefMapSize 226256 -parentBuildID 20200215102537 -greomni "Z:\task_1581764694\build\application\firefox\omni.ja" -appomni "Z:\task_1581764694\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1581764694\build\application\firefox\browser" - 6648 tab)
[task 2020-02-15T12:14:57.246Z] 12:14:57 INFO - ==> process 6648 launched child process 2124 ("Z:\task_1581764694\build\application\firefox\plugin-container.exe" --channel="6648.41.919153081\1983967992" "C:\Users\task_1581764694\AppData\Local\Temp\tmpkh2frg.mozrunner\plugins\nptest.dll" "C:\Users\task_1581764694\AppData\LocalLow\Mozilla\Temp-{322f8f50-ac46-430e-958a-ccd8985056fb}" "Z:\task_1581764694\AppData\Roaming\Adobe\" -greomni "Z:\task_1581764694\build\application\firefox\omni.ja" -appomni "Z:\task_1581764694\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1581764694\build\application\firefox\browser" 177E90DA8142DCA5 6648 plugin)
[task 2020-02-15T12:14:57.246Z] 12:14:57 INFO - Found child pids: set([5412, 7240, 3372, 2668, 3408, 6072, 2124, 6648])
[task 2020-02-15T12:14:57.246Z] 12:14:57 INFO - Failed to get child procs
[task 2020-02-15T12:14:57.247Z] 12:14:57 INFO - Killing process: 5412
[task 2020-02-15T12:14:57.247Z] 12:14:57 INFO - TEST-INFO | started process screenshot

Comment 1

[Intermittent Failures Robot]

2 failures in 4690 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: 1
• windows10-64-qr: 1

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

Comment 2

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 7
• try: 2
• autoland: 6

Platform breakdown:

• windows10-64-ccov: 5
• windows10-64-shippable-qr: 1
• windows10-64-qr: 5
• windows10-64: 3
• windows10-64-shippable: 1

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

Comment 3

[Intermittent Failures Robot]

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

Repository breakdown:

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

Platform breakdown:

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

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

Comment 4

[Intermittent Failures Robot]

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

Repository breakdown:

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

Platform breakdown:

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

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

Comment 5

[Intermittent Failures Robot]

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

Repository breakdown:

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

Platform breakdown:

• windows10-64-qr: 5
• windows10-64-ccov: 1
• windows10-64-shippable-qr: 1
• windows10-64: 5
• windows10-64-shippable: 3

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

Comment 6

[Intermittent Failures Robot]

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

Repository breakdown:

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

Platform breakdown:

• windows10-64-shippable-qr: 1
• windows10-64-qr: 8
• windows10-64-shippable: 1
• windows10-64: 5
• windows10-64-ccov: 1

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

Comment 7

[Intermittent Failures Robot]

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

Repository breakdown:

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

Platform breakdown:

• windows10-64-qr: 16
• windows10-64-ccov: 1
• windows10-64-shippable: 1
• windows10-64: 8
• windows10-64-shippable-qr: 1

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

Comment 8

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 4
• mozilla-release: 2

Platform breakdown:

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

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

Comment 9

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-release: 1

Platform breakdown:

• windows10-64-qr: 1

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

Comment 10

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

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