Open
Bug 1789187
Opened 2 years ago
Updated 2 years ago
Intermittent TV TEST-UNEXPECTED-TIMEOUT | browser/components/newtab/test/browser/browser_aboutwelcome_upgrade_multistage_mr.js | application timed out after 370 seconds with no output
Categories
(Firefox :: New Tab Page, defect, P5)
Firefox
New Tab Page
Tracking
()
NEW
| Tracking | Status | |
|---|---|---|
| firefox-esr91 | --- | unaffected |
| firefox-esr102 | --- | unaffected |
| firefox104 | --- | unaffected |
| firefox105 | --- | unaffected |
| firefox106 | --- | wontfix |
| firefox107 | --- | wontfix |
People
(Reporter: intermittent-bug-filer, Unassigned)
References
(Blocks 1 open bug, Regression)
Details
(Keywords: intermittent-failure, regression)
Filed by: ccozmuta [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=389471360&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/WFxmB1AKThqqgO-EGgkENg/runs/0/artifacts/public/logs/live_backing.log
Reftest URL: https://hg.mozilla.org/mozilla-central/raw-file/tip/layout/tools/reftest/reftest-analyzer.xhtml#logurl=https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/WFxmB1AKThqqgO-EGgkENg/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1
[task 2022-09-05T05:02:22.977Z] 05:02:22 INFO - TEST-START | browser/components/newtab/test/browser/browser_aboutwelcome_upgrade_multistage_mr.js
[task 2022-09-05T05:02:23.104Z] 05:02:23 INFO - GECKO(1635) | [Child 1790: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 7f6125be8c00 == 6 [pid = 1790] [id = 9]
[task 2022-09-05T05:02:23.104Z] 05:02:23 INFO - GECKO(1635) | [Child 1790: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 14 (7f6125d59d90) [pid = 1790] [serial = 28] [outer = 0]
[task 2022-09-05T05:02:23.106Z] 05:02:23 INFO - GECKO(1635) | [Child 1790: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 15 (7f6125bea000) [pid = 1790] [serial = 29] [outer = 7f6125d59d90]
[task 2022-09-05T05:02:23.115Z] 05:02:23 INFO - GECKO(1635) | [Child 1756, Main Thread] WARNING: '!CanHandleWith(aPresContext)', file /builds/worker/checkouts/gecko/dom/events/IMEStateManager.cpp:498
[task 2022-09-05T05:02:23.443Z] 05:02:23 INFO - GECKO(1635) | [Child 1790: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7f6125be5000 == 5 [pid = 1790] [id = 7] [url = about:home]
[task 2022-09-05T05:02:23.446Z] 05:02:23 INFO - GECKO(1635) | [Child 1790: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7f6125be1400 == 4 [pid = 1790] [id = 6] [url = about:home]
[task 2022-09-05T05:02:23.448Z] 05:02:23 INFO - GECKO(1635) | [Child 1790: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7f6127d32400 == 3 [pid = 1790] [id = 5] [url = about:home]
[task 2022-09-05T05:02:23.450Z] 05:02:23 INFO - GECKO(1635) | [Child 1790: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7f6125be8000 == 2 [pid = 1790] [id = 8] [url = about:home]
[task 2022-09-05T05:02:23.868Z] 05:02:23 INFO - GECKO(1635) | [Child 1790: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 14 (7f6125d58260) [pid = 1790] [serial = 19] [outer = 0] [url = about:home]
[task 2022-09-05T05:02:23.871Z] 05:02:23 INFO - GECKO(1635) | [Child 1790: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 13 (7f6125d589a0) [pid = 1790] [serial = 22] [outer = 0] [url = about:home]
[task 2022-09-05T05:02:23.873Z] 05:02:23 INFO - GECKO(1635) | [Child 1790: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 12 (7f6125d58f10) [pid = 1790] [serial = 16] [outer = 0] [url = about:home]
[task 2022-09-05T05:02:23.875Z] 05:02:23 INFO - GECKO(1635) | [Child 1790: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 11 (7f6125d59650) [pid = 1790] [serial = 25] [outer = 0] [url = about:home]
[task 2022-09-05T05:02:23.957Z] 05:02:23 INFO - GECKO(1635) | [Parent 1635: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 7febf710a400 == 10 [pid = 1635] [id = 30]
[task 2022-09-05T05:02:23.959Z] 05:02:23 INFO - GECKO(1635) | [Parent 1635: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 58 (7fec087079a0) [pid = 1635] [serial = 72] [outer = 0]
[task 2022-09-05T05:02:23.962Z] 05:02:23 INFO - GECKO(1635) | [Parent 1635: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 59 (7febf7103c00) [pid = 1635] [serial = 73] [outer = 7fec087079a0]
[task 2022-09-05T05:02:23.975Z] 05:02:23 INFO - GECKO(1635) | [Parent 1635: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 7febf710bc00 == 11 [pid = 1635] [id = 31]
[task 2022-09-05T05:02:23.977Z] 05:02:23 INFO - GECKO(1635) | [Parent 1635: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 60 (7fec3ca96fb0) [pid = 1635] [serial = 74] [outer = 0]
[task 2022-09-05T05:02:23.980Z] 05:02:23 INFO - GECKO(1635) | [Parent 1635: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 61 (7febf7111000) [pid = 1635] [serial = 75] [outer = 7fec3ca96fb0]
[task 2022-09-05T05:02:23.992Z] 05:02:23 INFO - GECKO(1635) | [Parent 1635: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 7febf7112800 == 12 [pid = 1635] [id = 32]
[task 2022-09-05T05:02:23.993Z] 05:02:23 INFO - GECKO(1635) | [Parent 1635: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 62 (7fec3ca97350) [pid = 1635] [serial = 76] [outer = 0]
[task 2022-09-05T05:02:23.996Z] 05:02:23 INFO - GECKO(1635) | [Parent 1635: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 63 (7febf710dc00) [pid = 1635] [serial = 77] [outer = 7fec3ca97350]
[task 2022-09-05T05:02:24.024Z] 05:02:24 INFO - GECKO(1635) | [Child 1790, Main Thread] WARNING: '!CanHandleWith(aPresContext)', file /builds/worker/checkouts/gecko/dom/events/IMEStateManager.cpp:498
[task 2022-09-05T05:02:24.033Z] 05:02:24 INFO - GECKO(1635) | [Child 1790: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 12 (7f6125bdf800) [pid = 1790] [serial = 30] [outer = 7f6125d59d90]
[task 2022-09-05T05:02:27.605Z] 05:02:27 INFO - GECKO(1635) | [Child 1790: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 11 (7f6125be5400) [pid = 1790] [serial = 23] [outer = 0] [url = about:blank]
[task 2022-09-05T05:02:27.606Z] 05:02:27 INFO - GECKO(1635) | [Child 1790: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 10 (7f6127d39000) [pid = 1790] [serial = 18] [outer = 0] [url = about:home]
[task 2022-09-05T05:02:27.608Z] 05:02:27 INFO - GECKO(1635) | [Child 1790: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 9 (7f6125be6000) [pid = 1790] [serial = 24] [outer = 0] [url = about:home]
[task 2022-09-05T05:02:27.609Z] 05:02:27 INFO - GECKO(1635) | [Child 1790: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 8 (7f6125be2800) [pid = 1790] [serial = 21] [outer = 0] [url = about:home]
[task 2022-09-05T05:02:27.611Z] 05:02:27 INFO - GECKO(1635) | [Child 1790: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 7 (7f6125be8400) [pid = 1790] [serial = 26] [outer = 0] [url = about:blank]
[task 2022-09-05T05:02:27.612Z] 05:02:27 INFO - GECKO(1635) | [Child 1790: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 6 (7f6125be1c00) [pid = 1790] [serial = 20] [outer = 0] [url = about:blank]
[task 2022-09-05T05:02:27.614Z] 05:02:27 INFO - GECKO(1635) | [Child 1790: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 5 (7f6125be9400) [pid = 1790] [serial = 27] [outer = 0] [url = about:home]
[task 2022-09-05T05:02:30.145Z] 05:02:30 INFO - GECKO(1635) | [Child 1792: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7f57bd138000 == 0 [pid = 1792] [id = 0] [url = about:blank]
[task 2022-09-05T05:02:31.712Z] 05:02:31 INFO - GECKO(1635) | [Child 1790: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 4 (7f6125bea000) [pid = 1790] [serial = 29] [outer = 0] [url = about:blank]
[task 2022-09-05T05:02:34.218Z] 05:02:34 INFO - GECKO(1635) | [Child 1792: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (7f57d8691d90) [pid = 1792] [serial = 1] [outer = 0] [url = about:blank]
[task 2022-09-05T05:02:38.246Z] 05:02:38 INFO - GECKO(1635) | [Child 1792: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (7f57bd138400) [pid = 1792] [serial = 2] [outer = 0] [url = about:blank]
[task 2022-09-05T05:08:48.258Z] 05:08:48 INFO - Buffered messages logged at 05:02:22
[task 2022-09-05T05:08:48.259Z] 05:08:48 INFO - Entering setup bound
[task 2022-09-05T05:08:48.259Z] 05:08:48 INFO - Buffered messages logged at 05:02:23
[task 2022-09-05T05:08:48.260Z] 05:08:48 INFO - Leaving setup bound
[task 2022-09-05T05:08:48.260Z] 05:08:48 INFO - Entering test bound test_aboutwelcome_upgrade_mr_prefs_off
[task 2022-09-05T05:08:48.261Z] 05:08:48 INFO - Buffered messages finished
[task 2022-09-05T05:08:48.262Z] 05:08:48 ERROR - TEST-UNEXPECTED-TIMEOUT | browser/components/newtab/test/browser/browser_aboutwelcome_upgrade_multistage_mr.js | application timed out after 370 seconds with no output
[task 2022-09-05T05:08:48.262Z] 05:08:48 ERROR - Force-terminating active process(es).
[task 2022-09-05T05:08:48.263Z] 05:08:48 INFO - Determining child pids from psutil...
[task 2022-09-05T05:08:48.265Z] 05:08:48 INFO - [1732, 1756, 1790, 1792, 1801, 1884, 1903]
[task 2022-09-05T05:08:48.267Z] 05:08:48 INFO - ==> process 1635 launched child process 1654
[task 2022-09-05T05:08:48.268Z] 05:08:48 INFO - ==> process 1635 launched child process 1732
[task 2022-09-05T05:08:48.269Z] 05:08:48 INFO - ==> process 1635 launched child process 1756
[task 2022-09-05T05:08:48.270Z] 05:08:48 INFO - ==> process 1635 launched child process 1790
[task 2022-09-05T05:08:48.271Z] 05:08:48 INFO - ==> process 1635 launched child process 1792
[task 2022-09-05T05:08:48.272Z] 05:08:48 INFO - ==> process 1635 launched child process 1801
[task 2022-09-05T05:08:48.273Z] 05:08:48 INFO - ==> process 1635 launched child process 1884
[task 2022-09-05T05:08:48.274Z] 05:08:48 INFO - ==> process 1635 launched child process 1903
[task 2022-09-05T05:08:48.276Z] 05:08:48 INFO - Found child pids: {1792, 1732, 1884, 1801, 1903, 1654, 1756, 1790}
[task 2022-09-05T05:08:48.277Z] 05:08:48 INFO - Failed to get child procs
[task 2022-09-05T05:08:48.278Z] 05:08:48 INFO - Killing process: 1792
[task 2022-09-05T05:08:48.279Z] 05:08:48 INFO - TEST-INFO | started process screentopng
[task 2022-09-05T05:08:48.492Z] 05:08:48 INFO - TEST-INFO | screentopng: exit 0
[task 2022-09-05T05:08:48.495Z] 05:08:48 INFO - Killing process: 1732
[task 2022-09-05T05:08:48.496Z] 05:08:48 INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-09-05T05:08:48.500Z] 05:08:48 INFO - Killing process: 1884
[task 2022-09-05T05:08:48.501Z] 05:08:48 INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-09-05T05:08:48.501Z] 05:08:48 INFO - Killing process: 1801
[task 2022-09-05T05:08:48.502Z] 05:08:48 INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-09-05T05:08:48.503Z] 05:08:48 INFO - Killing process: 1903
[task 2022-09-05T05:08:48.507Z] 05:08:48 INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-09-05T05:08:48.508Z] 05:08:48 INFO - Killing process: 1654
[task 2022-09-05T05:08:48.509Z] 05:08:48 INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-09-05T05:08:48.510Z] 05:08:48 INFO - Can't trigger Breakpad, process no longer exists
[task 2022-09-05T05:08:48.511Z] 05:08:48 INFO - Killing process: 1756
[task 2022-09-05T05:08:48.512Z] 05:08:48 INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-09-05T05:08:48.513Z] 05:08:48 INFO - Killing process: 1790
[task 2022-09-05T05:08:48.514Z] 05:08:48 INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-09-05T05:08:48.515Z] 05:08:48 INFO - Killing process: 1635
[task 2022-09-05T05:08:48.516Z] 05:08:48 INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-09-05T05:08:48.810Z] 05:08:48 INFO - psutil found pid 1635 dead
[task 2022-09-05T05:08:48.814Z] 05:08:48 INFO - TEST-INFO | Main app process: exit 0
[task 2022-09-05T05:08:48.816Z] 05:08:48 ERROR - TEST-UNEXPECTED-FAIL | ShutdownLeaks | process() called before end of test suite
[task 2022-09-05T05:08:48.817Z] 05:08:48 INFO - TEST-INFO | Confirming we saw 52 DOCSHELL created and 32 destroyed log strings.
[task 2022-09-05T05:08:48.820Z] 05:08:48 INFO - TEST-INFO | Confirming we saw 133 DOMWINDOW created and 54 destroyed log strings.
[task 2022-09-05T05:08:48.822Z] 05:08:48 INFO - runtests.py | Application ran for: 0:07:03.126261
|
||
Comment 1•2 years ago
|
||
:dmosedale, since you are the author of the regressor, bug 1789008, could you take a look?
For more information, please visit auto_nag documentation.
Flags: needinfo?(dmosedale)
|
|
||
Comment 2•2 years ago
|
||
Set release status flags based on info from the regressing bug 1789008
status-firefox104:
--- → unaffected
status-firefox105:
--- → unaffected
status-firefox106:
--- → affected
status-firefox-esr102:
--- → unaffected
status-firefox-esr91:
--- → unaffected
|
||
Comment 3•2 years ago
|
||
It's not entirely clear to me what to make of this log. I think there's some chance that fixing bug 1789186 will fix this, so for now I'm going to mark it as depending on that one.
Depends on: 1789186
Flags: needinfo?(dmosedale)
|
|
||
Updated•2 years ago
|
Blocks: mr-onboarding
|
|
||
Updated•2 years ago
|
Iteration: --- → 106.2 - Sept 5 - Sept 16
| Comment hidden (Intermittent Failures Robot) |
|
|
||
Comment 5•2 years ago
|
||
I suspect that the fix for bug 1789186 will fix this.
|
|
||
Updated•2 years ago
|
Iteration: 106.2 - Sept 5 - Sept 16 → ---
|
|
||
Comment 6•2 years ago
|
||
Set release status flags based on info from the regressing bug 1789008
status-firefox107:
--- → affected
|
|
||
Comment 7•2 years ago
|
||
I landed the fix for bug 1789186 last Tuesday (in 106 nightly). I think there's a good chance it will have fixed this bug as well. There have been no comments here in from the Intermittent Failures Bot in the last 9 days, so we don't (yet?) have evidence that this is happening in 107.
I'm resetting the release status flag to --- as a result.
status-firefox107:
affected → ---
|
|
||
Comment 8•2 years ago
|
||
Set release status flags based on info from the regressing bug 1789008
status-firefox107:
--- → affected
|
|
||
Updated•2 years ago
|
|
||
Updated•2 years ago
|
You need to log in
before you can comment on or make changes to this bug.
Description
•