1772418 - Intermittent browser/components/resistfingerprinting/test/mochitest/test_animation_api.html | Test timed out. -

Status: RESOLVED DUPLICATE of bug 1781698

(Core :: Privacy: Anti-Tracking, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2022-06-02T19:19:55.937Z] 19:19:55     INFO - TEST-PASS | browser/components/resistfingerprinting/test/mochitest/test_animation_api.html | pref: privacy.reduceTimerPrecision  - document.timeline.currentTime with precision 0.013 is not rounded: 200.04 
[task 2022-06-02T19:19:55.938Z] 19:19:55     INFO - Buffered messages finished
[task 2022-06-02T19:19:55.938Z] 19:19:55     INFO - TEST-UNEXPECTED-FAIL | browser/components/resistfingerprinting/test/mochitest/test_animation_api.html | Test timed out. - 
[task 2022-06-02T19:19:56.933Z] 19:19:56    ERROR - TEST-UNEXPECTED-FAIL | SimpleTest | this test already called finish!
[task 2022-06-02T19:19:56.939Z] 19:19:56     INFO - TEST-UNEXPECTED-ERROR | browser/components/resistfingerprinting/test/mochitest/test_animation_api.html | called finish() multiple times
[task 2022-06-02T19:19:56.939Z] 19:19:56     INFO - TEST-INFO took 302086ms
[task 2022-06-02T19:20:07.631Z] 19:20:07     INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-06-02T19:20:07.631Z] 19:20:07     INFO - TEST-UNEXPECTED-FAIL | browser/components/resistfingerprinting/test/mochitest/test_animation_api.html | Test timed out. - 
[task 2022-06-02T19:20:08.698Z] 19:20:08    ERROR - TEST-UNEXPECTED-FAIL | SimpleTest | this test already called finish!
[task 2022-06-02T19:20:08.703Z] 19:20:08     INFO - TEST-UNEXPECTED-ERROR | browser/components/resistfingerprinting/test/mochitest/test_animation_api.html | called finish() multiple times
[task 2022-06-02T19:20:08.703Z] 19:20:08     INFO - TEST-INFO 
[task 2022-06-02T19:20:11.604Z] 19:20:11     INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-06-02T19:20:11.604Z] 19:20:11     INFO - TEST-UNEXPECTED-FAIL | browser/components/resistfingerprinting/test/mochitest/test_animation_api.html | Test timed out. - 
[task 2022-06-02T19:20:12.690Z] 19:20:12    ERROR - TEST-UNEXPECTED-FAIL | SimpleTest | this test already called finish!
[task 2022-06-02T19:20:12.693Z] 19:20:12     INFO - TEST-UNEXPECTED-ERROR | browser/components/resistfingerprinting/test/mochitest/test_animation_api.html | called finish() multiple times
[task 2022-06-02T19:20:12.693Z] 19:20:12     INFO - TEST-INFO 
[task 2022-06-02T19:20:15.027Z] 19:20:15     INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-06-02T19:20:15.028Z] 19:20:15     INFO - TEST-UNEXPECTED-FAIL | browser/components/resistfingerprinting/test/mochitest/test_animation_api.html | Test timed out. - 
[task 2022-06-02T19:20:15.029Z] 19:20:15     INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-06-02T19:20:15.029Z] 19:20:15     INFO - TEST-UNEXPECTED-FAIL | (SimpleTest/TestRunner.js) | 4 test timeouts, giving up. - 
[task 2022-06-02T19:20:15.030Z] 19:20:15     INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-06-02T19:20:15.030Z] 19:20:15     INFO - TEST-UNEXPECTED-FAIL | (SimpleTest/TestRunner.js) | Skipping 1 remaining tests. - 
[task 2022-06-02T19:20:16.035Z] 19:20:16    ERROR - TEST-UNEXPECTED-FAIL | SimpleTest | this test already called finish!
[task 2022-06-02T19:20:16.038Z] 19:20:16     INFO - TEST-UNEXPECTED-ERROR | (SimpleTest/TestRunner.js) | called finish() multiple times
[task 2022-06-02T19:20:16.038Z] 19:20:16     INFO - TEST-INFO 
[task 2022-06-02T19:20:17.861Z] 19:20:17     INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-06-02T19:20:17.862Z] 19:20:17     INFO - TEST-UNEXPECTED-FAIL | (SimpleTest/TestRunner.js) | Test timed out. - 
[task 2022-06-02T19:20:17.862Z] 19:20:17     INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-06-02T19:20:17.862Z] 19:20:17     INFO - TEST-UNEXPECTED-FAIL | (SimpleTest/TestRunner.js) | 4 test timeouts, giving up. - 
[task 2022-06-02T19:20:17.863Z] 19:20:17     INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-06-02T19:20:17.863Z] 19:20:17     INFO - TEST-UNEXPECTED-FAIL | (SimpleTest/TestRunner.js) | Skipping 1 remaining tests. - 
[task 2022-06-02T19:20:18.906Z] 19:20:18    ERROR - TEST-UNEXPECTED-FAIL | SimpleTest | this test already called finish!
[task 2022-06-02T19:20:18.909Z] 19:20:18     INFO - TEST-UNEXPECTED-ERROR | (SimpleTest/TestRunner.js) | called finish() multiple times
[task 2022-06-02T19:20:18.909Z] 19:20:18     INFO - TEST-INFO 
[task 2022-06-02T19:20:20.478Z] 19:20:20     INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-06-02T19:20:20.479Z] 19:20:20     INFO - TEST-UNEXPECTED-FAIL | (SimpleTest/TestRunner.js) | Test timed out. - 
[task 2022-06-02T19:20:20.479Z] 19:20:20     INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-06-02T19:20:20.479Z] 19:20:20     INFO - TEST-UNEXPECTED-FAIL | (SimpleTest/TestRunner.js) | 4 test timeouts, giving up. - 
[task 2022-06-02T19:20:20.480Z] 19:20:20     INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-06-02T19:20:20.480Z] 19:20:20     INFO - TEST-UNEXPECTED-FAIL | (SimpleTest/TestRunner.js) | Skipping 1 remaining tests. - 
[task 2022-06-02T19:20:21.596Z] 19:20:21    ERROR - TEST-UNEXPECTED-FAIL | SimpleTest | this test already called finish!
[task 2022-06-02T19:20:21.599Z] 19:20:21     INFO - TEST-UNEXPECTED-ERROR | (SimpleTest/TestRunner.js) | called finish() multiple times
[task 2022-06-02T19:20:21.599Z] 19:20:21     INFO - TEST-INFO 
[task 2022-06-02T19:20:23.429Z] 19:20:23     INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-06-02T19:20:23.430Z] 19:20:23     INFO - TEST-UNEXPECTED-FAIL | (SimpleTest/TestRunner.js) | Test timed out. - 
[task 2022-06-02T19:20:23.430Z] 19:20:23     INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-06-02T19:20:23.431Z] 19:20:23     INFO - TEST-UNEXPECTED-FAIL | (SimpleTest/TestRunner.js) | 4 test timeouts, giving up. - 
[task 2022-06-02T19:20:23.431Z] 19:20:23     INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-06-02T19:20:23.431Z] 19:20:23     INFO - TEST-UNEXPECTED-FAIL | (SimpleTest/TestRunner.js) | Skipping 1 remaining tests. - 
[task 2022-06-02T19:20:24.529Z] 19:20:24    ERROR - TEST-UNEXPECTED-FAIL | SimpleTest | this test already called finish!
[task 2022-06-02T19:20:24.531Z] 19:20:24     INFO - TEST-UNEXPECTED-ERROR | (SimpleTest/TestRunner.js) | called finish() multiple times
[task 2022-06-02T19:20:24.531Z] 19:20:24     INFO - TEST-INFO 
[task 2022-06-02T19:26:34.558Z] 19:26:34     INFO - Buffered messages finished
[task 2022-06-02T19:26:34.558Z] 19:26:34    ERROR - TEST-UNEXPECTED-TIMEOUT | (SimpleTest/TestRunner.js) (finished) | application timed out after 370 seconds with no output
[task 2022-06-02T19:26:34.559Z] 19:26:34    ERROR - Force-terminating active process(es).
[task 2022-06-02T19:26:34.559Z] 19:26:34     INFO - Determining child pids from psutil...
[task 2022-06-02T19:26:34.561Z] 19:26:34     INFO - [1325, 1328, 1329, 1330, 1331, 1336]
[task 2022-06-02T19:26:34.561Z] 19:26:34     INFO - ==> process 1323 launched child process 1325
[task 2022-06-02T19:26:34.561Z] 19:26:34     INFO - ==> process 1323 launched child process 1326
[task 2022-06-02T19:26:34.562Z] 19:26:34     INFO - ==> process 1323 launched child process 1328
[task 2022-06-02T19:26:34.562Z] 19:26:34     INFO - ==> process 1323 launched child process 1329
[task 2022-06-02T19:26:34.562Z] 19:26:34     INFO - ==> process 1323 launched child process 1330
[task 2022-06-02T19:26:34.563Z] 19:26:34     INFO - ==> process 1323 launched child process 1331
[task 2022-06-02T19:26:34.563Z] 19:26:34     INFO - ==> process 1323 launched child process 1336
[task 2022-06-02T19:26:34.564Z] 19:26:34     INFO - Found child pids: {1325, 1326, 1328, 1329, 1330, 1331, 1336}
[task 2022-06-02T19:26:34.564Z] 19:26:34     INFO - Failed to get child procs
[task 2022-06-02T19:26:34.564Z] 19:26:34     INFO - Killing process: 1325
[task 2022-06-02T19:26:34.565Z] 19:26:34     INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-06-02T19:26:34.565Z] 19:26:34     INFO - Killing process: 1326
[task 2022-06-02T19:26:34.565Z] 19:26:34     INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-06-02T19:26:34.566Z] 19:26:34     INFO - Can't trigger Breakpad, process no longer exists
[task 2022-06-02T19:26:34.566Z] 19:26:34     INFO - Killing process: 1328
[task 2022-06-02T19:26:34.566Z] 19:26:34     INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-06-02T19:26:34.567Z] 19:26:34     INFO - Killing process: 1329
[task 2022-06-02T19:26:34.567Z] 19:26:34     INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-06-02T19:26:34.567Z] 19:26:34     INFO - Killing process: 1330
[task 2022-06-02T19:26:34.568Z] 19:26:34     INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-06-02T19:26:34.568Z] 19:26:34     INFO - Killing process: 1331
[task 2022-06-02T19:26:34.568Z] 19:26:34     INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-06-02T19:26:34.569Z] 19:26:34     INFO - Killing process: 1336
[task 2022-06-02T19:26:34.569Z] 19:26:34     INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-06-02T19:26:34.569Z] 19:26:34     INFO - Killing process: 1323
[task 2022-06-02T19:26:34.570Z] 19:26:34     INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-06-02T19:26:34.924Z] 19:26:34     INFO - psutil found pid 1323 dead
[task 2022-06-02T19:26:34.925Z] 19:26:34     INFO - TEST-INFO | Main app process: exit 0
[task 2022-06-02T19:26:34.925Z] 19:26:34     INFO - runtests.py | Application ran for: 0:12:03.017414

Comment 1

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

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

Comment 2

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

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

Comment 3

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform and build breakdown:

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

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1772418&startday=2022-05-30&endday=2022-06-05&tree=all

Comment 4

[Tom Ritter [:tjr]]

As there's only one failure so far I'm going to monitor it and see how often it fails.

Comment 5

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform and build breakdown:

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

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1772418&startday=2022-06-20&endday=2022-06-26&tree=all

Comment 6

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform and build breakdown:

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

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