1755010 - Intermittent dom/xhr/tests/test_bug1752863.html | Got TERMINATE | this test already called finish!

Status: RESOLVED DUPLICATE of bug 1777096

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

Description

[Treeherder Bug Filer]

Filed by: mlaza [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=367573572&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/RsLRidr5RY-BXxhNz-fVNg/runs/0/artifacts/public/logs/live_backing.log

---

[task 2022-02-11T15:02:46.266Z] 15:02:46     INFO - TEST-START | dom/xhr/tests/test_bug1752863.html
[task 2022-02-11T15:02:46.631Z] 15:02:46     INFO - GECKO(7376) | JavaScript error: http://mochi.test:8888/tests/dom/xhr/tests/test_bug1752863_worker.js, line 9: InvalidStateError: XMLHttpRequest has an invalid context.
[task 2022-02-11T15:02:46.634Z] 15:02:46     INFO - GECKO(7376) | MEMORY STAT | vsize 2496MB | residentFast 84MB | heapAllocated 5MB
[task 2022-02-11T15:02:46.637Z] 15:02:46     INFO - TEST-INFO | started process screentopng
[task 2022-02-11T15:02:47.496Z] 15:02:47     INFO - TEST-INFO | screentopng: exit 0
[task 2022-02-11T15:02:47.496Z] 15:02:47     INFO - Buffered messages logged at 15:02:46
[task 2022-02-11T15:02:47.496Z] 15:02:47     INFO - TEST-PASS | dom/xhr/tests/test_bug1752863.html | Got DOMException 
[task 2022-02-11T15:02:47.496Z] 15:02:47     INFO - Buffered messages finished
[task 2022-02-11T15:02:47.497Z] 15:02:47     INFO - TEST-UNEXPECTED-FAIL | dom/xhr/tests/test_bug1752863.html | Got TERMINATE 
[task 2022-02-11T15:02:47.497Z] 15:02:47     INFO -     SimpleTest.ok@SimpleTest/SimpleTest.js:417:16
[task 2022-02-11T15:02:47.497Z] 15:02:47     INFO -     worker.onmessage@dom/xhr/tests/test_bug1752863.html?currentTestURL=dom%2Fxhr%2Ftests%2Ftest_bug1752863.html&closeWhenDone=1&showTestReport=false&expected=pass:18:11
[task 2022-02-11T15:02:47.499Z] 15:02:47    ERROR - TEST-UNEXPECTED-FAIL | SimpleTest | this test already called finish!
[task 2022-02-11T15:02:47.501Z] 15:02:47     INFO - TEST-UNEXPECTED-ERROR | dom/xhr/tests/test_bug1752863.html | called finish() multiple times
[task 2022-02-11T15:02:47.502Z] 15:02:47     INFO - TEST-INFO took 374ms
[task 2022-02-11T15:02:47.502Z] 15:02:47     INFO - TEST-OK | dom/xhr/tests/test_bug1752863.html
[task 2022-02-11T15:02:47.504Z] 15:02:47     INFO - TEST-START | dom/xhr/tests/test_event_listener_leaks.html

Comment 1

[Intermittent Failures Robot]

2 failures in 3711 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-shippable-qr: 2
  • opt: 2

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

Comment 2

[Intermittent Failures Robot]

2 failures in 3281 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-shippable-qr: 1
  • opt: 1
• linux1804-64-qr: 1
  • opt: 1

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

Comment 3

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 2
• mozilla-central: 3

Platform and build breakdown:

• linux1804-64-shippable-qr: 4
  • opt: 4
• linux1804-64-asan-qr: 1
  • opt: 1

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

Comment 4

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 3
• mozilla-central: 3

Platform and build breakdown:

• linux1804-64-shippable-qr: 4
  • opt: 4
• linux1804-64-qr: 2
  • opt: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1755010&startday=2022-02-28&endday=2022-03-06&tree=all

Comment 5

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 5
• autoland: 5

Platform and build breakdown:

• linux1804-64-shippable-qr: 9
  • opt: 9
• linux1804-64-qr: 1
  • opt: 1

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

Comment 6

[Intermittent Failures Robot]

9 failures in 3304 pushes (0.003 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 6
• try: 1
• mozilla-central: 2

Platform and build breakdown:

• linux1804-64-qr: 3
  • opt: 2
  • debug: 1
• linux1804-64-asan-qr: 3
  • opt: 3
• linux1804-64-ccov-qr: 1
  • opt: 1
• linux1804-64-shippable-qr: 2
  • opt: 2

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

Comment 7

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 4

Platform and build breakdown:

• linux1804-64-qr: 1
  • debug: 1
• linux1804-64-shippable-qr: 3
  • opt: 3

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

Comment 8

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 3
• mozilla-central: 1

Platform and build breakdown:

• linux1804-64-shippable-qr: 3
  • opt: 3
• linux1804-64-qr: 1
  • opt: 1

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

Comment 9

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 4

Platform and build breakdown:

• linux1804-64-qr: 2
  • opt: 2
• linux1804-64-shippable-qr: 2
  • opt: 2

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

Comment 10

[Intermittent Failures Robot]

8 failures in 2948 pushes (0.003 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 1
• autoland: 5
• mozilla-beta: 2

Platform and build breakdown:

• linux1804-64-shippable-qr: 5
  • opt: 5
• linux1804-64-qr: 1
  • opt: 1
• linux1804-64-devedition-qr: 1
  • opt: 1
• linux1804-64-asan-qr: 1
  • opt: 1

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

Comment 11

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 3
• mozilla-central: 2

Platform and build breakdown:

• linux1804-64-shippable-qr: 4
  • opt: 4
• linux1804-64-asan-qr: 1
  • opt: 1

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

Comment 12

[Intermittent Failures Robot]

18 failures in 340 pushes (0.053 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 11
• mozilla-central: 4
• try: 3

Platform and build breakdown:

• linux1804-64-shippable-qr: 11
  • opt: 11
• linux1804-64-qr: 6
  • opt: 6
• linux1804-64-devedition-qr: 1
  • opt: 1

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

Comment 13

[Intermittent Failures Robot]

19 failures in 353 pushes (0.054 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 14
• try: 3
• mozilla-central: 2

Platform and build breakdown:

• linux1804-64-shippable-qr: 7
  • opt: 7
• linux1804-64-devedition-qr: 1
  • opt: 1
• linux1804-64-qr: 11
  • opt: 10
  • debug: 1

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

Comment 14

[Marian-Vasile Laza]

There have been 44 total failures in the last 7 days, recent failure log.
Affected platforms are:

• linux1804-64-qr
• linux1804-64-shippable-qr

Comment 15

[Intermittent Failures Robot]

56 failures in 3289 pushes (0.017 failures/push) were associated with this bug in the last 7 days.

This is the #10 most frequent failure this week.

** This failure happened more than 30 times this week! Resolving this bug is a high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 2 weeks, the affected test(s) may be disabled. **

Repository breakdown:

• autoland: 42
• mozilla-central: 7
• try: 7

Platform and build breakdown:

• linux1804-64-shippable-qr: 27
  • opt: 27
• linux1804-64-qr: 27
  • opt: 26
  • debug: 1
• linux1804-64-devedition-qr: 2
  • opt: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1755010&startday=2022-04-25&endday=2022-05-01&tree=all

Comment 16

[Intermittent Failures Robot]

15 failures in 647 pushes (0.023 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 13
• mozilla-central: 2

Platform and build breakdown:

• linux1804-64-shippable-qr: 8
  • opt: 8
• linux1804-64-qr: 7
  • opt: 7

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

Comment 17

[Intermittent Failures Robot]

16 failures in 778 pushes (0.021 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 11
• mozilla-central: 2
• try: 1
• mozilla-beta: 2

Platform and build breakdown:

• linux1804-64-asan-qr: 1
  • opt: 1
• linux1804-64-shippable-qr: 9
  • opt: 9
• linux1804-64-qr: 5
  • opt: 5
• linux1804-64-devedition-qr: 1
  • opt: 1

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

Comment 18

[Intermittent Failures Robot]

16 failures in 663 pushes (0.024 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 10
• mozilla-central: 2
• try: 1
• mozilla-beta: 3

Platform and build breakdown:

• linux1804-64-shippable-qr: 8
  • opt: 8
• linux1804-64-qr: 5
  • opt: 5
• linux1804-64-devedition-qr: 3
  • opt: 3

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

Comment 19

[Andreea Pavel [:apavel]]

There are 61 total failures in the last 7 days on

• linux1804-64-qr opt
• linux1804-64-shippable-qr opt
  Recent failure log: https://treeherder.mozilla.org/logviewer?job_id=377250408&repo=autoland&lineNumber=10430

[task 2022-05-07T15:49:18.954Z] 15:49:18     INFO - TEST-START | dom/xhr/tests/test_bug1752863.html
[task 2022-05-07T15:49:19.069Z] 15:49:19     INFO - GECKO(18780) | MEMORY STAT | vsize 2501MB | residentFast 114MB | heapAllocated 9MB
[task 2022-05-07T15:49:19.071Z] 15:49:19     INFO - GECKO(18780) | JavaScript error: http://mochi.test:8888/tests/dom/xhr/tests/test_bug1752863_worker.js, line 9: InvalidStateError: XMLHttpRequest has an invalid context.
[task 2022-05-07T15:49:19.073Z] 15:49:19     INFO - TEST-INFO | started process screentopng
[task 2022-05-07T15:49:19.681Z] 15:49:19     INFO - TEST-INFO | screentopng: exit 0
[task 2022-05-07T15:49:19.681Z] 15:49:19     INFO - Buffered messages logged at 15:49:19
[task 2022-05-07T15:49:19.681Z] 15:49:19     INFO - TEST-PASS | dom/xhr/tests/test_bug1752863.html | Got DOMException 
[task 2022-05-07T15:49:19.682Z] 15:49:19     INFO - Buffered messages finished
[task 2022-05-07T15:49:19.682Z] 15:49:19     INFO - TEST-UNEXPECTED-FAIL | dom/xhr/tests/test_bug1752863.html | Got TERMINATE 
[task 2022-05-07T15:49:19.682Z] 15:49:19     INFO -     SimpleTest.ok@SimpleTest/SimpleTest.js:417:16
[task 2022-05-07T15:49:19.682Z] 15:49:19     INFO -     worker.onmessage@dom/xhr/tests/test_bug1752863.html:18:11
[task 2022-05-07T15:49:19.684Z] 15:49:19    ERROR - TEST-UNEXPECTED-FAIL | SimpleTest | this test already called finish!
[task 2022-05-07T15:49:19.684Z] 15:49:19     INFO - TEST-UNEXPECTED-ERROR | dom/xhr/tests/test_bug1752863.html | called finish() multiple times
[task 2022-05-07T15:49:19.684Z] 15:49:19     INFO - TEST-INFO took 126ms
[task 2022-05-07T15:49:19.686Z] 15:49:19     INFO - TEST-OK | dom/xhr/tests/test_bug1752863.html

Dragana, can you please take a look?

Comment 20

[Intermittent Failures Robot]

77 failures in 3540 pushes (0.022 failures/push) were associated with this bug in the last 7 days.

This is the #10 most frequent failure this week.

** This failure happened more than 75 times this week! Resolving this bug is a very high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 1 week, the affected test(s) may be disabled. **

Repository breakdown:

• autoland: 48
• try: 5
• mozilla-beta: 11
• mozilla-central: 13

Platform and build breakdown:

• linux1804-64-qr: 25
  • opt: 25
• linux1804-64-shippable-qr: 43
  • opt: 43
• linux1804-64-devedition-qr: 7
  • opt: 7
• linux1804-64-ccov-qr: 1
  • opt: 1
• linux1804-64-asan-qr: 1
  • opt: 1

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

Comment 21

[Atila Butkovits]

Attachment: Bug 1755010 - disable test_bug1752863.html on Linux_64_opt for frequent failures. r=#intermittent-reviewers

Comment 22

[Intermittent Failures Robot]

15 failures in 549 pushes (0.027 failures/push) were associated with this bug yesterday.

** This test has failed more than 150 times in the last 21 days. It should be disabled until it can be fixed. **

Repository breakdown:

• autoland: 10
• mozilla-central: 3
• try: 1
• mozilla-beta: 1

Platform and build breakdown:

• linux1804-64-qr: 4
  • opt: 4
• linux1804-64-shippable-qr: 11
  • opt: 11

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

Comment 23

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/20113634e429
disable test_bug1752863.html on Linux_64_opt for frequent failures. r=intermittent-reviewers,jmaher DONTBUILD

Comment 24

[bszekely]

https://hg.mozilla.org/mozilla-central/rev/20113634e429

Comment 25

[Intermittent Failures Robot]

49 failures in 3317 pushes (0.015 failures/push) were associated with this bug in the last 7 days.

This is the #18 most frequent failure this week.

** This failure happened more than 30 times this week! Resolving this bug is a high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 2 weeks, the affected test(s) may be disabled. **

Repository breakdown:

• autoland: 31
• mozilla-central: 9
• try: 6
• mozilla-beta: 3

Platform and build breakdown:

• linux1804-64-qr: 19
  • opt: 19
• linux1804-64-shippable-qr: 29
  • opt: 29
• linux1804-64-devedition-qr: 1
  • opt: 1

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

Comment 26

[Kershaw Chang [:kershaw]]

I'll take this.

Comment 27

[Kershaw Chang [:kershaw]]

Based on the failed log below, it looks like this line is executed after throw err in test_bug1752863_worker.js.

[task 2022-05-07T15:49:19.681Z] 15:49:19     INFO - TEST-PASS | dom/xhr/tests/test_bug1752863.html | Got DOMException 
[task 2022-05-07T15:49:19.682Z] 15:49:19     INFO - Buffered messages finished
[task 2022-05-07T15:49:19.682Z] 15:49:19     INFO - TEST-UNEXPECTED-FAIL | dom/xhr/tests/test_bug1752863.html | Got TERMINATE 

Not sure if this is an expected racing behavior or not, but I think this bug is more related to worker thread than XHR.

Jens, could you find someone to have a look?
Thanks.

Comment 28

[Jens Stutte [:jstutte]]

This seems to be initially regressed by bug 1754411, though the recent spike must have other reasons.

There might be a hint in this comment.

There might also be some connection to what we see in bug 1769913, in a sense that having postMessage to the parent go through the throtteled event queue might foster situations like this and we simply cannot be sure that the entire test is going to be executed as part of the same C++ stack? And some other change just influenced the probability of delaying the dispatch?

Actually the potential execution delay of the postMessage itself should not matter, it is just the "loadstart" event handler we expect to be part of the same stack.

Comment 29

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

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

Comment 30

[Jens Stutte [:jstutte]]

Attachment: Bug 1755010: Harden test_bug1752863.html against loadstart event queuing. r?asuth

Comment 31

[Jens Stutte [:jstutte]]

Right before this spiked up bug 1766844 landed. Just a wild guess, but it might be related?

In any case it seems we cannot rely on being always executed within the same microtask anymore.

Comment 32

[Intermittent Failures Robot]

13 failures in 2694 pushes (0.005 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• try: 1
• mozilla-beta: 11
• autoland: 1

Platform and build breakdown:

• linux1804-64-qr: 2
  • opt: 1
  • debug: 1
• linux1804-64-shippable-qr: 4
  • opt: 4
• linux1804-64-devedition-qr: 7
  • opt: 7

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

Comment 33

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/c36ca41e04d4
Harden test_bug1752863.html against loadstart event queuing. r=asuth

Comment 34

[Marian-Vasile Laza]

https://hg.mozilla.org/mozilla-central/rev/c36ca41e04d4

Comment 35

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

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

Comment 36

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-release: 1

Platform and build breakdown:

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

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

Comment 37

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-release: 1

Platform and build breakdown:

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

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