1646846 - Intermittent toolkit/components/satchel/test/test_bug_511615.html | Test timed out.

Status: RESOLVED INCOMPLETE

(Toolkit :: Form Manager, defect, P5)

Description

[Treeherder Bug Filer]

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

---

INFO - TEST-PASS | toolkit/components/satchel/test/test_bug_511615.html | The selected item should not have been used. 
[task 2020-06-18T19:53:31.817Z] 19:53:31     INFO - add_task | Leaving test test_untrusted_events_ignored
[task 2020-06-18T19:53:31.817Z] 19:53:31     INFO - Buffered messages finished
[task 2020-06-18T19:53:31.817Z] 19:53:31     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/satchel/test/test_bug_511615.html | Test timed out. 
[task 2020-06-18T19:53:31.818Z] 19:53:31     INFO -     SimpleTest.ok@SimpleTest/SimpleTest.js:299:16
[task 2020-06-18T19:53:31.818Z] 19:53:31     INFO -     reportError@SimpleTest/TestRunner.js:128:22
[task 2020-06-18T19:53:31.818Z] 19:53:31     INFO -     TestRunner._checkForHangs@SimpleTest/TestRunner.js:150:18
[task 2020-06-18T19:53:32.645Z] 19:53:32    ERROR - [SimpleTest.finish()] this test already called finish!
[task 2020-06-18T19:53:32.669Z] 19:53:32     INFO - TEST-UNEXPECTED-ERROR | toolkit/components/satchel/test/test_bug_511615.html | called finish() multiple times
[task 2020-06-18T19:53:32.670Z] 19:53:32     INFO - TEST-INFO took 331011ms
[task 2020-06-18T19:54:01.649Z] 19:54:01     INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-06-18T19:54:01.649Z] 19:54:01     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/satchel/test/test_bug_511615.html | Test timed out. 
[task 2020-06-18T19:54:01.649Z] 19:54:01     INFO -     SimpleTest.ok@SimpleTest/SimpleTest.js:299:16
[task 2020-06-18T19:54:01.650Z] 19:54:01     INFO -     reportError@SimpleTest/TestRunner.js:128:22
[task 2020-06-18T19:54:01.650Z] 19:54:01     INFO -     TestRunner._checkForHangs@SimpleTest/TestRunner.js:150:18
[task 2020-06-18T19:54:02.649Z] 19:54:02    ERROR - [SimpleTest.finish()] this test already called finish!
[task 2020-06-18T19:54:02.658Z] 19:54:02     INFO - TEST-UNEXPECTED-ERROR | toolkit/components/satchel/test/test_bug_511615.html | called finish() multiple times
[task 2020-06-18T19:54:02.658Z] 19:54:02     INFO - TEST-INFO 
[task 2020-06-18T19:54:31.654Z] 19:54:31     INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-06-18T19:54:31.655Z] 19:54:31     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/satchel/test/test_bug_511615.html | Test timed out. 
[task 2020-06-18T19:54:31.656Z] 19:54:31     INFO -     SimpleTest.ok@SimpleTest/SimpleTest.js:299:16
[task 2020-06-18T19:54:31.656Z] 19:54:31     INFO -     reportError@SimpleTest/TestRunner.js:128:22
[task 2020-06-18T19:54:31.656Z] 19:54:31     INFO -     TestRunner._checkForHangs@SimpleTest/TestRunner.js:150:18
[task 2020-06-18T19:54:32.654Z] 19:54:32    ERROR - [SimpleTest.finish()] this test already called finish!
[task 2020-06-18T19:54:32.658Z] 19:54:32     INFO - TEST-UNEXPECTED-ERROR | toolkit/components/satchel/test/test_bug_511615.html | called finish() multiple times
[task 2020-06-18T19:54:32.658Z] 19:54:32     INFO - TEST-INFO 
[task 2020-06-18T19:55:01.650Z] 19:55:01     INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-06-18T19:55:01.650Z] 19:55:01     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/satchel/test/test_bug_511615.html | Test timed out. 
[task 2020-06-18T19:55:01.651Z] 19:55:01     INFO -     SimpleTest.ok@SimpleTest/SimpleTest.js:299:16
[task 2020-06-18T19:55:01.651Z] 19:55:01     INFO -     reportError@SimpleTest/TestRunner.js:128:22
[task 2020-06-18T19:55:01.651Z] 19:55:01     INFO -     TestRunner._checkForHangs@SimpleTest/TestRunner.js:150:18
[task 2020-06-18T19:55:01.651Z] 19:55:01     INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-06-18T19:55:01.652Z] 19:55:01     INFO - TEST-UNEXPECTED-FAIL | (SimpleTest/TestRunner.js) | 4 test timeouts, giving up. 
[task 2020-06-18T19:55:01.652Z] 19:55:01     INFO -     SimpleTest.ok@SimpleTest/SimpleTest.js:299:16
[task 2020-06-18T19:55:01.652Z] 19:55:01     INFO -     reportError@SimpleTest/TestRunner.js:128:22
[task 2020-06-18T19:55:01.652Z] 19:55:01     INFO -     TestRunner._checkForHangs@SimpleTest/TestRunner.js:158:20
[task 2020-06-18T19:55:01.652Z] 19:55:01     INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-06-18T19:55:01.652Z] 19:55:01     INFO - TEST-UNEXPECTED-FAIL | (SimpleTest/TestRunner.js) | Skipping 15 remaining tests. 
[task 2020-06-18T19:55:01.652Z] 19:55:01     INFO -     SimpleTest.ok@SimpleTest/SimpleTest.js:299:16
[task 2020-06-18T19:55:01.652Z] 19:55:01     INFO -     reportError@SimpleTest/TestRunner.js:128:22
[task 2020-06-18T19:55:01.653Z] 19:55:01     INFO -     TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:20
[task 2020-06-18T19:55:02.655Z] 19:55:02    ERROR - [SimpleTest.finish()] this test already called finish!
[task 2020-06-18T19:55:02.656Z] 19:55:02     INFO - TEST-UNEXPECTED-ERROR | (SimpleTest/TestRunner.js) | called finish() multiple times
[task 2020-06-18T19:55:02.657Z] 19:55:02     INFO - TEST-INFO 
[task 2020-06-18T20:01:12.677Z] 20:01:12     INFO - Buffered messages finished
[task 2020-06-18T20:01:12.677Z] 20:01:12    ERROR - TEST-UNEXPECTED-TIMEOUT | (SimpleTest/TestRunner.js) (finished) | application timed out after 370 seconds with no output
[task 2020-06-18T20:01:12.678Z] 20:01:12    ERROR - Force-terminating active process(es).
[task 2020-06-18T20:01:12.679Z] 20:01:12     INFO - Determining child pids from psutil...
[task 2020-06-18T20:01:12.687Z] 20:01:12     INFO - [5704, 5769, 5792, 5822, 5445, 5635, 5694, 5651]
[task 2020-06-18T20:01:12.687Z] 20:01:12     INFO - ==> process 5407 launched child process 5425
[task 2020-06-18T20:01:12.698Z] 20:01:12     INFO - ==> process 5407 launched child process 5445
[task 2020-06-18T20:01:12.698Z] 20:01:12     INFO - ==> process 5407 launched child process 5635
[task 2020-06-18T20:01:12.698Z] 20:01:12     INFO - ==> process 5407 launched child process 5651
[task 2020-06-18T20:01:12.698Z] 20:01:12     INFO - ==> process 5407 launched child process 5694
[task 2020-06-18T20:01:12.699Z] 20:01:12     INFO - ==> process 5407 launched child process 5704
[task 2020-06-18T20:01:12.699Z] 20:01:12     INFO - ==> process 5407 launched child process 5769
[task 2020-06-18T20:01:12.699Z] 20:01:12     INFO - ==> process 5407 launched child process 5792
[task 2020-06-18T20:01:12.699Z] 20:01:12     INFO - ==> process 5407 launched child process 5822
[task 2020-06-18T20:01:12.700Z] 20:01:12     INFO - Found child pids: set([5792, 5635, 5445, 5704, 5769, 5425, 5651, 5694, 5822])
[task 2020-06-18T20:01:12.700Z] 20:01:12     INFO - Failed to get child procs

Comment 1

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 6
• try: 1

Platform breakdown:

• linux1804-64-qr: 5
• linux1804-64: 2

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

Comment 2

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 3
• try: 3

Platform breakdown:

• linux1804-64-shippable: 1
• linux1804-64: 2
• linux1804-64-ccov: 1
• linux1804-64-qr: 2

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

Comment 3

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

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