1781186 - Intermittent [TV] editor/spellchecker/tests/test_bug1773802.html | Test timed out. -

Status: RESOLVED DUPLICATE of bug 1784715

(Core :: DOM: Editor, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: nfay [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=385406094&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/UbWo5N9hTOq2FBN2xaEYSw/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/UbWo5N9hTOq2FBN2xaEYSw/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1

---

[task 2022-07-26T04:05:12.795Z] 04:05:12     INFO - TEST-START | editor/spellchecker/tests/test_bug1773802.html
[task 2022-07-26T04:05:19.303Z] 04:05:19     INFO - GECKO(7799) | [Child 7954, Main Thread] WARNING: early callback, or time went backwards: '!aAllowIdleDispatch', file /builds/worker/checkouts/gecko/xpcom/threads/IdleTaskRunner.cpp:198
[task 2022-07-26T04:10:42.798Z] 04:10:42     INFO - TEST-INFO | started process screentopng
[task 2022-07-26T04:10:42.990Z] 04:10:42     INFO - TEST-INFO | screentopng: exit 0
[task 2022-07-26T04:10:42.992Z] 04:10:42     INFO - Buffered messages logged at 04:05:14
[task 2022-07-26T04:10:42.993Z] 04:10:42     INFO - TEST-PASS | editor/spellchecker/tests/test_bug1773802.html | true expected (ru_RU directory should exist) 
[task 2022-07-26T04:10:42.993Z] 04:10:42     INFO - Buffered messages finished
[task 2022-07-26T04:10:42.994Z] 04:10:42     INFO - TEST-UNEXPECTED-FAIL | editor/spellchecker/tests/test_bug1773802.html | Test timed out. - 
[task 2022-07-26T04:10:43.805Z] 04:10:43     INFO - GECKO(7799) | MEMORY STAT vsizeMaxContiguous not supported in this build configuration.
[task 2022-07-26T04:10:43.807Z] 04:10:43     INFO - GECKO(7799) | MEMORY STAT | vsize 6463MB | residentFast 4046MB | heapAllocated 3880MB
[task 2022-07-26T04:11:12.797Z] 04:11:12     INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-07-26T04:11:12.797Z] 04:11:12     INFO - TEST-UNEXPECTED-FAIL | editor/spellchecker/tests/test_bug1773802.html | Test timed out. - 
[task 2022-07-26T04:11:13.797Z] 04:11:13    ERROR - TEST-UNEXPECTED-FAIL | SimpleTest | this test already called finish!
[task 2022-07-26T04:11:13.803Z] 04:11:13     INFO - TEST-UNEXPECTED-ERROR | editor/spellchecker/tests/test_bug1773802.html | called finish() multiple times
[task 2022-07-26T04:11:13.803Z] 04:11:13     INFO - TEST-INFO took 361007ms
[task 2022-07-26T04:11:26.403Z] 04:11:26     INFO - GECKO(7799) | [2022-07-26T04:11:26Z WARN  rkv::backend::impl_safe::environment] `load_ratio()` is irrelevant for this storage backend.
[task 2022-07-26T04:11:42.797Z] 04:11:42     INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-07-26T04:11:42.797Z] 04:11:42     INFO - TEST-UNEXPECTED-FAIL | editor/spellchecker/tests/test_bug1773802.html | Test timed out. - 
[task 2022-07-26T04:11:43.797Z] 04:11:43    ERROR - TEST-UNEXPECTED-FAIL | SimpleTest | this test already called finish!
[task 2022-07-26T04:11:43.803Z] 04:11:43     INFO - TEST-UNEXPECTED-ERROR | editor/spellchecker/tests/test_bug1773802.html | called finish() multiple times
[task 2022-07-26T04:11:43.804Z] 04:11:43     INFO - TEST-INFO 
[task 2022-07-26T04:12:12.798Z] 04:12:12     INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-07-26T04:12:12.798Z] 04:12:12     INFO - TEST-UNEXPECTED-FAIL | editor/spellchecker/tests/test_bug1773802.html | Test timed out. - 
[task 2022-07-26T04:12:12.800Z] 04:12:12     INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-07-26T04:12:12.801Z] 04:12:12     INFO - TEST-UNEXPECTED-FAIL | (SimpleTest/TestRunner.js) | 4 test timeouts, giving up. - 
[task 2022-07-26T04:12:12.802Z] 04:12:12     INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-07-26T04:12:12.803Z] 04:12:12     INFO - TEST-UNEXPECTED-FAIL | (SimpleTest/TestRunner.js) | Skipping 1 remaining tests. - 
[task 2022-07-26T04:12:13.799Z] 04:12:13    ERROR - TEST-UNEXPECTED-FAIL | SimpleTest | this test already called finish!
[task 2022-07-26T04:12:13.803Z] 04:12:13     INFO - TEST-UNEXPECTED-ERROR | (SimpleTest/TestRunner.js) | called finish() multiple times
[task 2022-07-26T04:12:13.803Z] 04:12:13     INFO - TEST-INFO 
[task 2022-07-26T04:13:26.453Z] 04:13:26     INFO - GECKO(7799) | 1658808806452	addons.xpi	ERROR	System addon update list error Error: got node name: html, expected: updates
[task 2022-07-26T04:19:36.457Z] 04:19:36     INFO - Buffered messages finished
[task 2022-07-26T04:19:36.457Z] 04:19:36    ERROR - TEST-UNEXPECTED-TIMEOUT | (SimpleTest/TestRunner.js) (finished) | application timed out after 370 seconds with no output
[task 2022-07-26T04:19:36.457Z] 04:19:36    ERROR - Force-terminating active process(es).
[task 2022-07-26T04:19:36.457Z] 04:19:36     INFO - Determining child pids from psutil...
[task 2022-07-26T04:19:36.513Z] 04:19:36     INFO - [7864, 7890, 7941, 7954, 7978, 8006, 8030]
[task 2022-07-26T04:19:36.513Z] 04:19:36     INFO - ==> process 7799 launched child process 7818
[task 2022-07-26T04:19:36.513Z] 04:19:36     INFO - ==> process 7799 launched child process 7864
[task 2022-07-26T04:19:36.514Z] 04:19:36     INFO - ==> process 7799 launched child process 7890
[task 2022-07-26T04:19:36.514Z] 04:19:36     INFO - ==> process 7799 launched child process 7941
[task 2022-07-26T04:19:36.514Z] 04:19:36     INFO - ==> process 7799 launched child process 7954
[task 2022-07-26T04:19:36.514Z] 04:19:36     INFO - ==> process 7799 launched child process 7978
[task 2022-07-26T04:19:36.514Z] 04:19:36     INFO - ==> process 7799 launched child process 8006
[task 2022-07-26T04:19:36.515Z] 04:19:36     INFO - ==> process 7799 launched child process 8030
[task 2022-07-26T04:19:36.516Z] 04:19:36     INFO - Found child pids: {7941, 8006, 7978, 7818, 7890, 7954, 7864, 8030}
[task 2022-07-26T04:19:36.517Z] 04:19:36     INFO - Failed to get child procs
[task 2022-07-26T04:19:36.517Z] 04:19:36     INFO - Killing process: 7941

Comment 1

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

Set release status flags based on info from the regressing bug 1780543

Comment 2

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

:arai, since you are the author of the regressor, bug 1780543, could you take a look?
For more information, please visit auto_nag documentation.

Comment 3

[Tooru Fujisawa [:arai]]

The patch modifies comment only.
this is pre-existing issue that this test doesn't work in verify mode.

Comment 4

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 2

Platform and build breakdown:

• linux1804-64-qr: 1
  • debug: 1
• macosx1015-64-qr: 1
  • debug: 1

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