1692923 - Intermittent TEST-UNEXPECTED-TIMEOUT | browser/components/preferences/tests/browser_subdialogs.js | application timed out after 370 seconds with no output

Status: RESOLVED INCOMPLETE

(Firefox :: Settings UI, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: btara [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=330045066&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/Mqvv7iSUSZuauMT6AhMVSQ/runs/2/artifacts/public/logs/live_backing.log

---

[task 2021-02-15T22:20:30.076Z] 22:20:30     INFO - TEST-START | browser/components/preferences/tests/browser_subdialogs.js
[task 2021-02-15T22:26:55.440Z] 22:26:55     INFO - Buffered messages logged at 22:20:30
[task 2021-02-15T22:26:55.449Z] 22:26:55     INFO - Entering test bound test_initialize
[task 2021-02-15T22:26:55.449Z] 22:26:55     INFO - Buffered messages logged at 22:20:35
[task 2021-02-15T22:26:55.449Z] 22:26:55     INFO - Leaving test bound test_initialize
[task 2021-02-15T22:26:55.449Z] 22:26:55     INFO - Entering test bound check_titlebar_focus_returnval_titlechanges_accepting
[task 2021-02-15T22:26:55.449Z] 22:26:55     INFO - waiting for subdialog DOMFrameContentLoaded
[task 2021-02-15T22:26:55.449Z] 22:26:55     INFO - Buffered messages logged at 22:20:36
[task 2021-02-15T22:26:55.449Z] 22:26:55     INFO - waiting for subdialog load
[task 2021-02-15T22:26:55.449Z] 22:26:55     INFO - Buffered messages logged at 22:20:41
[task 2021-02-15T22:26:55.449Z] 22:26:55     INFO - subdialog window is loaded
[task 2021-02-15T22:26:55.449Z] 22:26:55     INFO - TEST-PASS | browser/components/preferences/tests/browser_subdialogs.js | The dialog should be non-null - true == true - 
[task 2021-02-15T22:26:55.449Z] 22:26:55     INFO - TEST-PASS | browser/components/preferences/tests/browser_subdialogs.js | Subdialog URL should not be about:blank - "chrome://mochitests/content/browser/browser/components/preferences/tests/subdialog.xhtml" != "about:blank" - 
[task 2021-02-15T22:26:55.449Z] 22:26:55     INFO - TEST-PASS | browser/components/preferences/tests/browser_subdialogs.js | Overlay should be visible - "visible" == "visible" - 
[task 2021-02-15T22:26:55.449Z] 22:26:55     INFO - TEST-PASS | browser/components/preferences/tests/browser_subdialogs.js | No stylesheets that were expected are missing - 0 == 0 - 
[task 2021-02-15T22:26:55.449Z] 22:26:55     INFO - Buffered messages logged at 22:20:42
[task 2021-02-15T22:26:55.449Z] 22:26:55     INFO - TEST-PASS | browser/components/preferences/tests/browser_subdialogs.js | Title should be correct initially - "Sample sub-dialog" == "Sample sub-dialog" - 
[task 2021-02-15T22:26:55.449Z] 22:26:55     INFO - TEST-PASS | browser/components/preferences/tests/browser_subdialogs.js | Textbox with correct text is focused - "Default text" == "Default text" - 
[task 2021-02-15T22:26:55.449Z] 22:26:55     INFO - waiting for DOMTitleChanged event
[task 2021-02-15T22:26:55.449Z] 22:26:55     INFO - Buffered messages logged at 22:20:43
[task 2021-02-15T22:26:55.449Z] 22:26:55     INFO - TEST-PASS | browser/components/preferences/tests/browser_subdialogs.js | subdialog should have updated title - "Updated title" == "Updated title" - 
[task 2021-02-15T22:26:55.449Z] 22:26:55     INFO - waiting for dialogclosing
[task 2021-02-15T22:26:55.449Z] 22:26:55     INFO - URI chrome://mochitests/content/browser/browser/components/preferences/tests/subdialog.xhtml
[task 2021-02-15T22:26:55.449Z] 22:26:55     INFO - Buffered messages logged at 22:20:44
[task 2021-02-15T22:26:55.449Z] 22:26:55     INFO - Waiting for subdialog unload
[task 2021-02-15T22:26:55.449Z] 22:26:55     INFO - TEST-PASS | browser/components/preferences/tests/browser_subdialogs.js | overlay is not visible - "" != "visible" - 
[task 2021-02-15T22:26:55.449Z] 22:26:55     INFO - TEST-PASS | browser/components/preferences/tests/browser_subdialogs.js | inline styles should be cleared - "" == "" - 
[task 2021-02-15T22:26:55.449Z] 22:26:55     INFO - TEST-PASS | browser/components/preferences/tests/browser_subdialogs.js | closing event should indicate button was 'accept' - "accept" == "accept" - 
[task 2021-02-15T22:26:55.449Z] 22:26:55     INFO - TEST-PASS | browser/components/preferences/tests/browser_subdialogs.js | should be 1 if accepted, 0 if canceled, undefined if closed w/out button - 1 == 1 - 
[task 2021-02-15T22:26:55.449Z] 22:26:55     INFO - TEST-PASS | browser/components/preferences/tests/browser_subdialogs.js | dialog count should decrease by 1 - 0 == 0 - 
[task 2021-02-15T22:26:55.449Z] 22:26:55     INFO - TEST-PASS | browser/components/preferences/tests/browser_subdialogs.js | stack children count should decrease by 1 - 1 == 1 - 
[task 2021-02-15T22:26:55.449Z] 22:26:55     INFO - Leaving test bound check_titlebar_focus_returnval_titlechanges_accepting
[task 2021-02-15T22:26:55.449Z] 22:26:55     INFO - Entering test bound check_canceling_dialog
[task 2021-02-15T22:26:55.449Z] 22:26:55     INFO - Buffered messages logged at 22:20:45
[task 2021-02-15T22:26:55.449Z] 22:26:55     INFO - waiting for subdialog DOMFrameContentLoaded
[task 2021-02-15T22:26:55.449Z] 22:26:55     INFO - Buffered messages finished
[task 2021-02-15T22:26:55.449Z] 22:26:55    ERROR - TEST-UNEXPECTED-TIMEOUT | browser/components/preferences/tests/browser_subdialogs.js | application timed out after 370 seconds with no output
[task 2021-02-15T22:26:55.449Z] 22:26:55    ERROR - Force-terminating active process(es).
[task 2021-02-15T22:26:55.449Z] 22:26:55     INFO - Determining child pids from psutil...
[task 2021-02-15T22:26:55.449Z] 22:26:55     INFO - [1635, 1660, 1719, 1750, 1787, 1817, 1819]
[task 2021-02-15T22:26:55.449Z] 22:26:55     INFO - ==> process 1570 launched child process 1585
[task 2021-02-15T22:26:55.449Z] 22:26:55     INFO - ==> process 1570 launched child process 1635
[task 2021-02-15T22:26:55.449Z] 22:26:55     INFO - ==> process 1570 launched child process 1660
[task 2021-02-15T22:26:55.449Z] 22:26:55     INFO - ==> process 1570 launched child process 1719
[task 2021-02-15T22:26:55.449Z] 22:26:55     INFO - ==> process 1570 launched child process 1750
[task 2021-02-15T22:26:55.449Z] 22:26:55     INFO - ==> process 1570 launched child process 1787
[task 2021-02-15T22:26:55.449Z] 22:26:55     INFO - ==> process 1570 launched child process 1819
[task 2021-02-15T22:26:55.449Z] 22:26:55     INFO - Found child pids: set([1635, 1819, 1585, 1750, 1719, 1817, 1787, 1660])
[task 2021-02-15T22:26:55.453Z] 22:26:55     INFO - Failed to get child procs
[task 2021-02-15T22:26:55.453Z] 22:26:55     INFO - Killing process: 1635
[task 2021-02-15T22:26:55.461Z] 22:26:55     INFO - TEST-INFO | started process screentopng
[task 2021-02-15T22:29:16.212Z] 22:29:16     INFO - TEST-INFO | screentopng: exit 0

Comment 1

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• linux1804-64-tsan: 1
  • opt: 1

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

Comment 2

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 1
• autoland: 1

Platform and build breakdown:

• linux1804-64-tsan: 2
  • opt: 2

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

Comment 3

[BugBot [:suhaib / :marco]]

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

Comment 4

[Treeherder Bug Filer]

New failure instance: https://treeherder.mozilla.org/logviewer?job_id=370504812&repo=autoland

Comment 5

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• linux1804-64-tsan-qr: 1
  • opt: 1

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

Comment 6

[BugBot [:suhaib / :marco]]

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