Intermittent dom/base/test/test_bug435425.html | Wrong event! - got "load", expected "progress" | Wrong event! - got "loadend", expected "load" etc
Categories
(Core :: DOM: Networking, defect, P2)
Tracking
()
People
(Reporter: intermittent-bug-filer, Assigned: kershaw)
References
Details
(Keywords: intermittent-failure, Whiteboard: [stockwell unknown][necko-triaged])
Attachments
(1 file)
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
Updated•8 years ago
|
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
Comment 42•7 years ago
|
||
Comment 43•7 years ago
|
||
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
Comment 46•7 years ago
|
||
Comment 47•7 years ago
|
||
| Comment hidden (Intermittent Failures Robot) |
Comment 49•7 years ago
|
||
Updated•7 years ago
|
| Comment hidden (Intermittent Failures Robot) |
Comment 51•7 years ago
|
||
Comment 52•7 years ago
|
||
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
Updated•6 years ago
|
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
Comment 116•6 years ago
|
||
There are 27 total failures i the last 7 days: https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?startday=2019-07-10&endday=2019-07-17&tree=trunk&bug=1370132
Recent failure log: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=257030743&repo=autoland&lineNumber=3595
[task 2019-07-17T21:35:31.057Z] 21:35:31 INFO - 721 INFO TEST-START | dom/base/test/test_bug435425.html[task 2019-07-17T21:35:31.077Z] 21:35:31 INFO - 822 INFO TEST-UNEXPECTED-FAIL | dom/base/test/test_bug435425.html | Wrong event! - got "load", expected "progress"
[task 2019-07-17T21:35:31.078Z] 21:35:31 INFO - SimpleTest.is@SimpleTest/SimpleTest.js:320:16
[task 2019-07-17T21:35:31.078Z] 21:35:31 INFO - logEvent@dom/base/test/test_bug435425.html:67:3
[task 2019-07-17T21:35:31.078Z] 21:35:31 INFO - start/xhr.upload.onload@dom/base/test/test_bug435425.html:148:9
[task 2019-07-17T21:35:31.078Z] 21:35:31 INFO - 823 INFO TEST-PASS | dom/base/test/test_bug435425.html | Wrong event target [[object XMLHttpRequestUpload],load]!
[task 2019-07-17T21:35:31.078Z] 21:35:31 INFO - 824 INFO TEST-PASS | dom/base/test/test_bug435425.html | Extra or wrong event?
[task 2019-07-17T21:35:31.078Z] 21:35:31 INFO - 825 INFO TEST-UNEXPECTED-FAIL | dom/base/test/test_bug435425.html | Wrong event! - got "loadend", expected "load"
[task 2019-07-17T21:35:31.078Z] 21:35:31 INFO - SimpleTest.is@SimpleTest/SimpleTest.js:320:16
[task 2019-07-17T21:35:31.078Z] 21:35:31 INFO - logEvent@dom/base/test/test_bug435425.html:67:3
[task 2019-07-17T21:35:31.078Z] 21:35:31 INFO - maybeStop@dom/base/test/test_bug435425.html:91:3
[task 2019-07-17T21:35:31.079Z] 21:35:31 INFO - start/xhr.upload.onloadend@dom/base/test/test_bug435425.html:160:9
[task 2019-07-17T21:35:31.079Z] 21:35:31 INFO - 826 INFO TEST-PASS | dom/base/test/test_bug435425.html | Wrong event target [[object XMLHttpRequestUpload],loadend]!
[task 2019-07-17T21:35:31.079Z] 21:35:31 INFO - 827 INFO TEST-PASS | dom/base/test/test_bug435425.html | Extra or wrong event?
[task 2019-07-17T21:35:31.079Z] 21:35:31 INFO - 828 INFO TEST-UNEXPECTED-FAIL | dom/base/test/test_bug435425.html | Wrong event! - got "progress", expected "loadend"
[task 2019-07-17T21:35:31.079Z] 21:35:31 INFO - SimpleTest.is@SimpleTest/SimpleTest.js:320:16
[task 2019-07-17T21:35:31.079Z] 21:35:31 INFO - logEvent@dom/base/test/test_bug435425.html:67:3
[task 2019-07-17T21:35:31.079Z] 21:35:31 INFO - start/xhr.onprogress@dom/base/test/test_bug435425.html:134:7
| Comment hidden (Intermittent Failures Robot) |
Comment 119•6 years ago
|
||
There are 24 total failures in the last 7 days on android-em-7-0-x86_64 debug and 2 occurences on linux64-shippable-qr opt.
Recent failure log: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=258428353&repo=autoland&lineNumber=3472
[task 2019-07-26T01:46:50.668Z] 01:46:50 INFO - 627 INFO TEST-START | dom/base/test/test_bug435425.html
[task 2019-07-26T01:46:50.673Z] 01:46:50 INFO - <snipped 187 output lines - if you need more context, please use SimpleTest.requestCompleteLog() in your test>
[task 2019-07-26T01:46:50.674Z] 01:46:50 INFO - Buffered messages logged at 01:46:48
[task 2019-07-26T01:46:50.674Z] 01:46:50 INFO - 628 INFO TEST-PASS | dom/base/test/test_bug435425.html | Wrong event target [[object XMLHttpRequest],error]!
[task 2019-07-26T01:46:50.674Z] 01:46:50 INFO - 629 INFO TEST-PASS | dom/base/test/test_bug435425.html | Extra or wrong event?
[task 2019-07-26T01:46:50.674Z] 01:46:50 INFO - 630 INFO TEST-PASS | dom/base/test/test_bug435425.html | Wrong event!
[task 2019-07-26T01:46:50.674Z] 01:46:50 INFO - 631 INFO TEST-PASS | dom/base/test/test_bug435425.html | Wrong event target [[object XMLHttpRequest],loadend]!
[task 2019-07-26T01:46:50.687Z] 01:46:50 INFO - 727 INFO TEST-PASS | dom/base/test/test_bug435425.html | Extra or wrong event?
[task 2019-07-26T01:46:50.687Z] 01:46:50 INFO - Buffered messages finished
[task 2019-07-26T01:46:50.687Z] 01:46:50 INFO - 728 INFO TEST-UNEXPECTED-FAIL | dom/base/test/test_bug435425.html | Wrong event! - got "load", expected "progress"
[task 2019-07-26T01:46:50.687Z] 01:46:50 INFO - SimpleTest.is@SimpleTest/SimpleTest.js:320:16
[task 2019-07-26T01:46:50.687Z] 01:46:50 INFO - logEvent@dom/base/test/test_bug435425.html:67:3
[task 2019-07-26T01:46:50.687Z] 01:46:50 INFO - start/xhr.upload.onload@dom/base/test/test_bug435425.html:148:9
[task 2019-07-26T01:46:50.687Z] 01:46:50 INFO - 729 INFO TEST-PASS | dom/base/test/test_bug435425.html | Wrong event target [[object XMLHttpRequestUpload],load]!
[task 2019-07-26T01:46:50.687Z] 01:46:50 INFO - 730 INFO TEST-PASS | dom/base/test/test_bug435425.html | Extra or wrong event?
[task 2019-07-26T01:46:50.687Z] 01:46:50 INFO - 731 INFO TEST-UNEXPECTED-FAIL | dom/base/test/test_bug435425.html | Wrong event! - got "loadend", expected "load"
[task 2019-07-26T01:46:50.687Z] 01:46:50 INFO - SimpleTest.is@SimpleTest/SimpleTest.js:320:16
[task 2019-07-26T01:46:50.688Z] 01:46:50 INFO - logEvent@dom/base/test/test_bug435425.html:67:3
[task 2019-07-26T01:46:50.688Z] 01:46:50 INFO - maybeStop@dom/base/test/test_bug435425.html:91:3
[task 2019-07-26T01:46:50.688Z] 01:46:50 INFO - start/xhr.upload.onloadend@dom/base/test/test_bug435425.html:160:9
[task 2019-07-26T01:46:50.688Z] 01:46:50 INFO - 732 INFO TEST-PASS | dom/base/test/test_bug435425.html | Wrong event target [[object XMLHttpRequestUpload],loadend]!
[task 2019-07-26T01:46:50.688Z] 01:46:50 INFO - 733 INFO TEST-PASS | dom/base/test/test_bug435425.html | Extra or wrong event?
[task 2019-07-26T01:46:50.688Z] 01:46:50 INFO - 734 INFO TEST-UNEXPECTED-FAIL | dom/base/test/test_bug435425.html | Wrong event! - got "progress", expected "loadend"
[task 2019-07-26T01:46:50.688Z] 01:46:50 INFO - SimpleTest.is@SimpleTest/SimpleTest.js:320:16
[task 2019-07-26T01:46:50.688Z] 01:46:50 INFO - logEvent@dom/base/test/test_bug435425.html:67:3
[task 2019-07-26T01:46:50.688Z] 01:46:50 INFO - start/xhr.onprogress@dom/base/test/test_bug435425.html:134:7
[task 2019-07-26T01:46:50.688Z] 01:46:50 INFO - 735 INFO TEST-UNEXPECTED-FAIL | dom/base/test/test_bug435425.html | Wrong event target [[object XMLHttpRequest],progress]!
[task 2019-07-26T01:46:50.688Z] 01:46:50 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:275:18
[task 2019-07-26T01:46:50.688Z] 01:46:50 INFO - logEvent@dom/base/test/test_bug435425.html:68:3
[task 2019-07-26T01:46:50.688Z] 01:46:50 INFO - start/xhr.onprogress@dom/base/test/test_bug435425.html:134:7
[task 2019-07-26T01:46:50.688Z] 01:46:50 INFO - 736 INFO TEST-PASS | dom/base/test/test_bug435425.html | Progress should increase, got 4097 after 1440
[task 2019-07-26T01:46:50.688Z] 01:46:50 INFO - 737 INFO TEST-PASS | dom/base/test/test_bug435425.html | Wrong responseText
[task 2019-07-26T01:46:50.688Z] 01:46:50 INFO - 738 INFO TEST-PASS | dom/base/test/test_bug435425.html | Extra or wrong event?
[task 2019-07-26T01:46:50.688Z] 01:46:50 INFO - 739 INFO TEST-UNEXPECTED-FAIL | dom/base/test/test_bug435425.html | Wrong event! - got "load", expected "progress"
[task 2019-07-26T01:46:50.688Z] 01:46:50 INFO - SimpleTest.is@SimpleTest/SimpleTest.js:320:16
[task 2019-07-26T01:46:50.688Z] 01:46:50 INFO - logEvent@dom/base/test/test_bug435425.html:67:3
[task 2019-07-26T01:46:50.688Z] 01:46:50 INFO - start/xhr.onload@dom/base/test/test_bug435425.html:114:7
[task 2019-07-26T01:46:50.688Z] 01:46:50 INFO - 740 INFO TEST-PASS | dom/base/test/test_bug435425.html | Wrong event target [[object XMLHttpRequest],load]!
[task 2019-07-26T01:46:50.688Z] 01:46:50 INFO - 741 INFO TEST-PASS | dom/base/test/test_bug435425.html | Extra or wrong event?
[task 2019-07-26T01:46:50.689Z] 01:46:50 INFO - 742 INFO TEST-UNEXPECTED-FAIL | dom/base/test/test_bug435425.html | Wrong event! - got "loadend", expected "load"
[task 2019-07-26T01:46:50.689Z] 01:46:50 INFO - SimpleTest.is@SimpleTest/SimpleTest.js:320:16
[task 2019-07-26T01:46:50.689Z] 01:46:50 INFO - logEvent@dom/base/test/test_bug435425.html:67:3
[task 2019-07-26T01:46:50.689Z] 01:46:50 INFO - maybeStop@dom/base/test/test_bug435425.html:91:3
[task 2019-07-26T01:46:50.689Z] 01:46:50 INFO - start/xhr.onloadend@dom/base/test/test_bug435425.html:126:7
[task 2019-07-26T01:46:50.689Z] 01:46:50 INFO - 743 INFO TEST-PASS | dom/base/test/test_bug435425.html | Wrong event target [[object XMLHttpRequest],loadend]!
[task 2019-07-26T01:52:11.106Z] 01:52:11 INFO - 744 INFO TEST-UNEXPECTED-FAIL | dom/base/test/test_bug435425.html | Test timed out.
[task 2019-07-26T01:52:11.106Z] 01:52:11 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:275:18
[task 2019-07-26T01:52:11.106Z] 01:52:11 INFO - reportError@SimpleTest/TestRunner.js:121:22
[task 2019-07-26T01:52:11.106Z] 01:52:11 INFO - TestRunner._checkForHangs@SimpleTest/TestRunner.js:142:7
[task 2019-07-26T01:52:11.106Z] 01:52:11 INFO - 745 INFO TEST-OK | dom/base/test/test_bug435425.html | took 315335ms
Joel, the assignee did not reply, could you redirect this to someone else?
Comment 120•6 years ago
|
||
Peter was looking at this testcase a year ago, lets ask :hsinyi to triage again.
| Comment hidden (Intermittent Failures Robot) |
Comment 122•6 years ago
|
||
(In reply to Andrew Overholt [:overholt] from comment #49)
(In reply to Peter Van der Beken [:peterv] from comment #47)
XMLHttpRequestMainThread::OnProgress is not called, so it doesn't set
mProgressSinceLastProgressEvent to true.Do you/we know what should set this?
Hi Nhi, do you know who may have the answer and can help us here? Thank you.
| Comment hidden (Intermittent Failures Robot) |
Updated•6 years ago
|
| Comment hidden (Intermittent Failures Robot) |
| Assignee | ||
Comment 125•6 years ago
|
||
I'll keep my eye on this.
The failure rate is not too high now.
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
Comment 133•6 years ago
|
||
There have been 27 failures in the last 7 days.
Affected platforms are:
android-em-7-0-x86_64 debug
windows10-64-qr opt
Recent failure log: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=269549694&repo=autoland&lineNumber=3552
| Comment hidden (Intermittent Failures Robot) |
Comment 135•6 years ago
|
||
There are 21 total failures in the last 7 days on android-em-7-0-x86_64 debug.
Recent failure log: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=270706340&repo=autoland&lineNumber=3538
[task 2019-10-10T16:04:52.060Z] 16:04:52 INFO - 629 INFO TEST-START | dom/base/test/test_bug435425.html
[task 2019-10-10T16:04:52.064Z] 16:04:52 INFO - <snipped 107 output lines - if you need more context, please use SimpleTest.requestCompleteLog() in your test>
[task 2019-10-10T16:04:52.064Z] 16:04:52 INFO - Buffered messages logged at 16:04:43
[task 2019-10-10T16:04:52.064Z] 16:04:52 INFO - 630 INFO TEST-PASS | dom/base/test/test_bug435425.html | Wrong event target [[object XMLHttpRequest],error]!
[task 2019-10-10T16:04:52.064Z] 16:04:52 INFO - 631 INFO TEST-PASS | dom/base/test/test_bug435425.html | Extra or wrong event?
[task 2019-10-10T16:04:52.064Z] 16:04:52 INFO - 632 INFO TEST-PASS | dom/base/test/test_bug435425.html | Wrong event!
[task 2019-10-10T16:04:52.064Z] 16:04:52 INFO - 633 INFO TEST-PASS | dom/base/test/test_bug435425.html | Wrong event target [[object XMLHttpRequest],loadend]!
[task 2019-10-10T16:04:52.072Z] 16:04:52 INFO - 729 INFO TEST-PASS | dom/base/test/test_bug435425.html | Extra or wrong event?
[task 2019-10-10T16:04:52.072Z] 16:04:52 INFO - Buffered messages finished
[task 2019-10-10T16:04:52.072Z] 16:04:52 INFO - 730 INFO TEST-UNEXPECTED-FAIL | dom/base/test/test_bug435425.html | Wrong event! - got "load", expected "progress"
[task 2019-10-10T16:04:52.072Z] 16:04:52 INFO - SimpleTest.is@SimpleTest/SimpleTest.js:322:16
[task 2019-10-10T16:04:52.072Z] 16:04:52 INFO - logEvent@dom/base/test/test_bug435425.html:67:5
[task 2019-10-10T16:04:52.072Z] 16:04:52 INFO - start/xhr.upload.onload@dom/base/test/test_bug435425.html:148:17
[task 2019-10-10T16:04:52.072Z] 16:04:52 INFO - 731 INFO TEST-PASS | dom/base/test/test_bug435425.html | Wrong event target [[object XMLHttpRequestUpload],load]!
[task 2019-10-10T16:04:52.072Z] 16:04:52 INFO - 732 INFO TEST-PASS | dom/base/test/test_bug435425.html | Extra or wrong event?
[task 2019-10-10T16:04:52.072Z] 16:04:52 INFO - 733 INFO TEST-UNEXPECTED-FAIL | dom/base/test/test_bug435425.html | Wrong event! - got "loadend", expected "load"
[task 2019-10-10T16:04:52.072Z] 16:04:52 INFO - SimpleTest.is@SimpleTest/SimpleTest.js:322:16
[task 2019-10-10T16:04:52.072Z] 16:04:52 INFO - logEvent@dom/base/test/test_bug435425.html:67:5
[task 2019-10-10T16:04:52.073Z] 16:04:52 INFO - maybeStop@dom/base/test/test_bug435425.html:91:11
[task 2019-10-10T16:04:52.073Z] 16:04:52 INFO - start/xhr.upload.onloadend@dom/base/test/test_bug435425.html:160:18
[task 2019-10-10T16:04:52.073Z] 16:04:52 INFO - 734 INFO TEST-PASS | dom/base/test/test_bug435425.html | Wrong event target [[object XMLHttpRequestUpload],loadend]!
[task 2019-10-10T16:04:52.073Z] 16:04:52 INFO - 735 INFO TEST-PASS | dom/base/test/test_bug435425.html | Extra or wrong event?
[task 2019-10-10T16:04:52.073Z] 16:04:52 INFO - 736 INFO TEST-UNEXPECTED-FAIL | dom/base/test/test_bug435425.html | Wrong event! - got "progress", expected "loadend"
[task 2019-10-10T16:04:52.073Z] 16:04:52 INFO - SimpleTest.is@SimpleTest/SimpleTest.js:322:16
[task 2019-10-10T16:04:52.073Z] 16:04:52 INFO - logEvent@dom/base/test/test_bug435425.html:67:5
[task 2019-10-10T16:04:52.073Z] 16:04:52 INFO - start/xhr.onprogress@dom/base/test/test_bug435425.html:134:15
[task 2019-10-10T16:04:52.073Z] 16:04:52 INFO - 737 INFO TEST-UNEXPECTED-FAIL | dom/base/test/test_bug435425.html | Wrong event target [[object XMLHttpRequest],progress]!
[task 2019-10-10T16:04:52.073Z] 16:04:52 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:277:18
[task 2019-10-10T16:04:52.073Z] 16:04:52 INFO - logEvent@dom/base/test/test_bug435425.html:68:5
[task 2019-10-10T16:04:52.073Z] 16:04:52 INFO - start/xhr.onprogress@dom/base/test/test_bug435425.html:134:15
[task 2019-10-10T16:04:52.073Z] 16:04:52 INFO - 738 INFO TEST-PASS | dom/base/test/test_bug435425.html | Wrong responseText
[task 2019-10-10T16:04:52.073Z] 16:04:52 INFO - 739 INFO TEST-PASS | dom/base/test/test_bug435425.html | Extra or wrong event?
[task 2019-10-10T16:04:52.074Z] 16:04:52 INFO - 740 INFO TEST-UNEXPECTED-FAIL | dom/base/test/test_bug435425.html | Wrong event! - got "load", expected "progress"
[task 2019-10-10T16:04:52.074Z] 16:04:52 INFO - SimpleTest.is@SimpleTest/SimpleTest.js:322:16
[task 2019-10-10T16:04:52.074Z] 16:04:52 INFO - logEvent@dom/base/test/test_bug435425.html:67:5
[task 2019-10-10T16:04:52.074Z] 16:04:52 INFO - start/xhr.onload@dom/base/test/test_bug435425.html:114:15
[task 2019-10-10T16:04:52.074Z] 16:04:52 INFO - 741 INFO TEST-PASS | dom/base/test/test_bug435425.html | Wrong event target [[object XMLHttpRequest],load]!
[task 2019-10-10T16:04:52.074Z] 16:04:52 INFO - 742 INFO TEST-PASS | dom/base/test/test_bug435425.html | Extra or wrong event?
[task 2019-10-10T16:04:52.074Z] 16:04:52 INFO - 743 INFO TEST-UNEXPECTED-FAIL | dom/base/test/test_bug435425.html | Wrong event! - got "loadend", expected "load"
[task 2019-10-10T16:04:52.074Z] 16:04:52 INFO - SimpleTest.is@SimpleTest/SimpleTest.js:322:16
[task 2019-10-10T16:04:52.074Z] 16:04:52 INFO - logEvent@dom/base/test/test_bug435425.html:67:5
[task 2019-10-10T16:04:52.074Z] 16:04:52 INFO - maybeStop@dom/base/test/test_bug435425.html:91:11
[task 2019-10-10T16:04:52.074Z] 16:04:52 INFO - start/xhr.onloadend@dom/base/test/test_bug435425.html:126:16
[task 2019-10-10T16:04:52.074Z] 16:04:52 INFO - 744 INFO TEST-PASS | dom/base/test/test_bug435425.html | Wrong event target [[object XMLHttpRequest],loadend]!
[task 2019-10-10T16:10:10.762Z] 16:10:10 INFO - 745 INFO TEST-UNEXPECTED-FAIL | dom/base/test/test_bug435425.html | Test timed out.
[task 2019-10-10T16:10:10.762Z] 16:10:10 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:277:18
[task 2019-10-10T16:10:10.762Z] 16:10:10 INFO - reportError@SimpleTest/TestRunner.js:121:22
[task 2019-10-10T16:10:10.762Z] 16:10:10 INFO - TestRunner._checkForHangs@SimpleTest/TestRunner.js:142:18
[task 2019-10-10T16:10:10.762Z] 16:10:10 INFO - 746 INFO TEST-OK | dom/base/test/test_bug435425.html | took 325928ms
Kershaw, can you please take a look when you're back?
| Comment hidden (Intermittent Failures Robot) |
| Assignee | ||
Comment 137•6 years ago
|
||
(In reply to Andreea Pavel [:apavel] from comment #135)
There are 21 total failures in the last 7 days on android-em-7-0-x86_64 debug.
Recent failure log: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=270706340&repo=autoland&lineNumber=3538
[task 2019-10-10T16:04:52.060Z] 16:04:52 INFO - 629 INFO TEST-START | dom/base/test/test_bug435425.html
[task 2019-10-10T16:04:52.064Z] 16:04:52 INFO - <snipped 107 output lines - if you need more context, please use SimpleTest.requestCompleteLog() in your test>
[task 2019-10-10T16:04:52.064Z] 16:04:52 INFO - Buffered messages logged at 16:04:43
[task 2019-10-10T16:04:52.064Z] 16:04:52 INFO - 630 INFO TEST-PASS | dom/base/test/test_bug435425.html | Wrong event target [[object XMLHttpRequest],error]!
[task 2019-10-10T16:04:52.064Z] 16:04:52 INFO - 631 INFO TEST-PASS | dom/base/test/test_bug435425.html | Extra or wrong event?
[task 2019-10-10T16:04:52.064Z] 16:04:52 INFO - 632 INFO TEST-PASS | dom/base/test/test_bug435425.html | Wrong event!
[task 2019-10-10T16:04:52.064Z] 16:04:52 INFO - 633 INFO TEST-PASS | dom/base/test/test_bug435425.html | Wrong event target [[object XMLHttpRequest],loadend]![task 2019-10-10T16:04:52.072Z] 16:04:52 INFO - 729 INFO TEST-PASS | dom/base/test/test_bug435425.html | Extra or wrong event?
[task 2019-10-10T16:04:52.072Z] 16:04:52 INFO - Buffered messages finished
[task 2019-10-10T16:04:52.072Z] 16:04:52 INFO - 730 INFO TEST-UNEXPECTED-FAIL | dom/base/test/test_bug435425.html | Wrong event! - got "load", expected "progress"
[task 2019-10-10T16:04:52.072Z] 16:04:52 INFO - SimpleTest.is@SimpleTest/SimpleTest.js:322:16
[task 2019-10-10T16:04:52.072Z] 16:04:52 INFO - logEvent@dom/base/test/test_bug435425.html:67:5
[task 2019-10-10T16:04:52.072Z] 16:04:52 INFO - start/xhr.upload.onload@dom/base/test/test_bug435425.html:148:17
[task 2019-10-10T16:04:52.072Z] 16:04:52 INFO - 731 INFO TEST-PASS | dom/base/test/test_bug435425.html | Wrong event target [[object XMLHttpRequestUpload],load]!
[task 2019-10-10T16:04:52.072Z] 16:04:52 INFO - 732 INFO TEST-PASS | dom/base/test/test_bug435425.html | Extra or wrong event?
[task 2019-10-10T16:04:52.072Z] 16:04:52 INFO - 733 INFO TEST-UNEXPECTED-FAIL | dom/base/test/test_bug435425.html | Wrong event! - got "loadend", expected "load"
[task 2019-10-10T16:04:52.072Z] 16:04:52 INFO - SimpleTest.is@SimpleTest/SimpleTest.js:322:16
[task 2019-10-10T16:04:52.072Z] 16:04:52 INFO - logEvent@dom/base/test/test_bug435425.html:67:5
[task 2019-10-10T16:04:52.073Z] 16:04:52 INFO - maybeStop@dom/base/test/test_bug435425.html:91:11
[task 2019-10-10T16:04:52.073Z] 16:04:52 INFO - start/xhr.upload.onloadend@dom/base/test/test_bug435425.html:160:18
[task 2019-10-10T16:04:52.073Z] 16:04:52 INFO - 734 INFO TEST-PASS | dom/base/test/test_bug435425.html | Wrong event target [[object XMLHttpRequestUpload],loadend]!
[task 2019-10-10T16:04:52.073Z] 16:04:52 INFO - 735 INFO TEST-PASS | dom/base/test/test_bug435425.html | Extra or wrong event?
[task 2019-10-10T16:04:52.073Z] 16:04:52 INFO - 736 INFO TEST-UNEXPECTED-FAIL | dom/base/test/test_bug435425.html | Wrong event! - got "progress", expected "loadend"
[task 2019-10-10T16:04:52.073Z] 16:04:52 INFO - SimpleTest.is@SimpleTest/SimpleTest.js:322:16
[task 2019-10-10T16:04:52.073Z] 16:04:52 INFO - logEvent@dom/base/test/test_bug435425.html:67:5
[task 2019-10-10T16:04:52.073Z] 16:04:52 INFO - start/xhr.onprogress@dom/base/test/test_bug435425.html:134:15
[task 2019-10-10T16:04:52.073Z] 16:04:52 INFO - 737 INFO TEST-UNEXPECTED-FAIL | dom/base/test/test_bug435425.html | Wrong event target [[object XMLHttpRequest],progress]!
[task 2019-10-10T16:04:52.073Z] 16:04:52 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:277:18
[task 2019-10-10T16:04:52.073Z] 16:04:52 INFO - logEvent@dom/base/test/test_bug435425.html:68:5
[task 2019-10-10T16:04:52.073Z] 16:04:52 INFO - start/xhr.onprogress@dom/base/test/test_bug435425.html:134:15
[task 2019-10-10T16:04:52.073Z] 16:04:52 INFO - 738 INFO TEST-PASS | dom/base/test/test_bug435425.html | Wrong responseText
[task 2019-10-10T16:04:52.073Z] 16:04:52 INFO - 739 INFO TEST-PASS | dom/base/test/test_bug435425.html | Extra or wrong event?
[task 2019-10-10T16:04:52.074Z] 16:04:52 INFO - 740 INFO TEST-UNEXPECTED-FAIL | dom/base/test/test_bug435425.html | Wrong event! - got "load", expected "progress"
[task 2019-10-10T16:04:52.074Z] 16:04:52 INFO - SimpleTest.is@SimpleTest/SimpleTest.js:322:16
[task 2019-10-10T16:04:52.074Z] 16:04:52 INFO - logEvent@dom/base/test/test_bug435425.html:67:5
[task 2019-10-10T16:04:52.074Z] 16:04:52 INFO - start/xhr.onload@dom/base/test/test_bug435425.html:114:15
[task 2019-10-10T16:04:52.074Z] 16:04:52 INFO - 741 INFO TEST-PASS | dom/base/test/test_bug435425.html | Wrong event target [[object XMLHttpRequest],load]!
[task 2019-10-10T16:04:52.074Z] 16:04:52 INFO - 742 INFO TEST-PASS | dom/base/test/test_bug435425.html | Extra or wrong event?
[task 2019-10-10T16:04:52.074Z] 16:04:52 INFO - 743 INFO TEST-UNEXPECTED-FAIL | dom/base/test/test_bug435425.html | Wrong event! - got "loadend", expected "load"
[task 2019-10-10T16:04:52.074Z] 16:04:52 INFO - SimpleTest.is@SimpleTest/SimpleTest.js:322:16
[task 2019-10-10T16:04:52.074Z] 16:04:52 INFO - logEvent@dom/base/test/test_bug435425.html:67:5
[task 2019-10-10T16:04:52.074Z] 16:04:52 INFO - maybeStop@dom/base/test/test_bug435425.html:91:11
[task 2019-10-10T16:04:52.074Z] 16:04:52 INFO - start/xhr.onloadend@dom/base/test/test_bug435425.html:126:16
[task 2019-10-10T16:04:52.074Z] 16:04:52 INFO - 744 INFO TEST-PASS | dom/base/test/test_bug435425.html | Wrong event target [[object XMLHttpRequest],loadend]!
[task 2019-10-10T16:10:10.762Z] 16:10:10 INFO - 745 INFO TEST-UNEXPECTED-FAIL | dom/base/test/test_bug435425.html | Test timed out.
[task 2019-10-10T16:10:10.762Z] 16:10:10 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:277:18
[task 2019-10-10T16:10:10.762Z] 16:10:10 INFO - reportError@SimpleTest/TestRunner.js:121:22
[task 2019-10-10T16:10:10.762Z] 16:10:10 INFO - TestRunner._checkForHangs@SimpleTest/TestRunner.js:142:18
[task 2019-10-10T16:10:10.762Z] 16:10:10 INFO - 746 INFO TEST-OK | dom/base/test/test_bug435425.html | took 325928msKershaw, can you please take a look when you're back?
I'll try to fix this next week.
Comment 138•6 years ago
|
||
Thank you.
| Comment hidden (Intermittent Failures Robot) |
| Assignee | ||
Comment 140•6 years ago
|
||
The failed log below shows that the progress event happens at the wrong time, which is after loadend event. Still have no clue about this.
[task 2019-10-24T15:14:21.395Z] 15:14:21 INFO - 384 INFO TEST-UNEXPECTED-FAIL | dom/base/test/test_bug435425.html | Wrong event! - got "load", expected "progress"
[task 2019-10-24T15:14:21.395Z] 15:14:21 INFO - SimpleTest.is@SimpleTest/SimpleTest.js:322:16
[task 2019-10-24T15:14:21.395Z] 15:14:21 INFO - logEvent@dom/base/test/test_bug435425.html:69:5
[task 2019-10-24T15:14:21.396Z] 15:14:21 INFO - start/xhr.upload.onload@dom/base/test/test_bug435425.html:150:17
[task 2019-10-24T15:14:21.396Z] 15:14:21 INFO - 385 INFO TEST-PASS | dom/base/test/test_bug435425.html | Wrong event target [[object XMLHttpRequestUpload],load]!
[task 2019-10-24T15:14:21.396Z] 15:14:21 INFO - 386 INFO testCount=24, evt.type=loadend, currentEvents.type=load
[task 2019-10-24T15:14:21.396Z] 15:14:21 INFO - 387 INFO TEST-PASS | dom/base/test/test_bug435425.html | Extra or wrong event?
[task 2019-10-24T15:14:21.396Z] 15:14:21 INFO - 388 INFO TEST-UNEXPECTED-FAIL | dom/base/test/test_bug435425.html | Wrong event! - got "loadend", expected "load"
[task 2019-10-24T15:14:21.396Z] 15:14:21 INFO - SimpleTest.is@SimpleTest/SimpleTest.js:322:16
[task 2019-10-24T15:14:21.396Z] 15:14:21 INFO - logEvent@dom/base/test/test_bug435425.html:69:5
[task 2019-10-24T15:14:21.396Z] 15:14:21 INFO - maybeStop@dom/base/test/test_bug435425.html:93:11
[task 2019-10-24T15:14:21.396Z] 15:14:21 INFO - start/xhr.upload.onloadend@dom/base/test/test_bug435425.html:162:18
[task 2019-10-24T15:14:21.396Z] 15:14:21 INFO - 389 INFO TEST-PASS | dom/base/test/test_bug435425.html | Wrong event target [[object XMLHttpRequestUpload],loadend]!
[task 2019-10-24T15:14:21.396Z] 15:14:21 INFO - 390 INFO testCount=24, evt.type=progress, currentEvents.type=loadend
[task 2019-10-24T15:14:21.396Z] 15:14:21 INFO - 391 INFO TEST-PASS | dom/base/test/test_bug435425.html | Extra or wrong event?
[task 2019-10-24T15:14:21.396Z] 15:14:21 INFO - 392 INFO TEST-UNEXPECTED-FAIL | dom/base/test/test_bug435425.html | Wrong event! - got "progress", expected "loadend"
[task 2019-10-24T15:14:21.396Z] 15:14:21 INFO - SimpleTest.is@SimpleTest/SimpleTest.js:322:16
[task 2019-10-24T15:14:21.396Z] 15:14:21 INFO - logEvent@dom/base/test/test_bug435425.html:69:5
[task 2019-10-24T15:14:21.397Z] 15:14:21 INFO - start/xhr.onprogress@dom/base/test/test_bug435425.html:136:15
[task 2019-10-24T15:14:21.397Z] 15:14:21 INFO - 393 INFO TEST-UNEXPECTED-FAIL | dom/base/test/test_bug435425.html | Wrong event target [[object XMLHttpRequest],progress]!
[task 2019-10-24T15:14:21.397Z] 15:14:21 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:277:18
[task 2019-10-24T15:14:21.397Z] 15:14:21 INFO - logEvent@dom/base/test/test_bug435425.html:70:5
[task 2019-10-24T15:14:21.397Z] 15:14:21 INFO - start/xhr.onprogress@dom/base/test/test_bug435425.html:136:15
[task 2019-10-24T15:14:21.397Z] 15:14:21 INFO - 394 INFO TEST-PASS | dom/base/test/test_bug435425.html | Wrong responseText
[task 2019-10-24T15:14:21.398Z] 15:14:21 INFO - 395 INFO testCount=24, evt.type=load, currentEvents.type=progress
[task 2019-10-24T15:14:21.398Z] 15:14:21 INFO - 396 INFO TEST-PASS | dom/base/test/test_bug435425.html | Extra or wrong event?
[task 2019-10-24T15:14:21.399Z] 15:14:21 INFO - 397 INFO TEST-UNEXPECTED-FAIL | dom/base/test/test_bug435425.html | Wrong event! - got "load", expected "progress"
[task 2019-10-24T15:14:21.399Z] 15:14:21 INFO - SimpleTest.is@SimpleTest/SimpleTest.js:322:16
[task 2019-10-24T15:14:21.399Z] 15:14:21 INFO - logEvent@dom/base/test/test_bug435425.html:69:5
[task 2019-10-24T15:14:21.399Z] 15:14:21 INFO - start/xhr.onload@dom/base/test/test_bug435425.html:116:15
[task 2019-10-24T15:14:21.399Z] 15:14:21 INFO - 398 INFO TEST-PASS | dom/base/test/test_bug435425.html | Wrong event target [[object XMLHttpRequest],load]!
[task 2019-10-24T15:14:21.399Z] 15:14:21 INFO - 399 INFO testCount=24, evt.type=loadend, currentEvents.type=load
[task 2019-10-24T15:14:21.399Z] 15:14:21 INFO - 400 INFO TEST-PASS | dom/base/test/test_bug435425.html | Extra or wrong event?
[task 2019-10-24T15:14:21.399Z] 15:14:21 INFO - 401 INFO TEST-UNEXPECTED-FAIL | dom/base/test/test_bug435425.html | Wrong event! - got "loadend", expected "load"
[task 2019-10-24T15:14:21.399Z] 15:14:21 INFO - SimpleTest.is@SimpleTest/SimpleTest.js:322:16
[task 2019-10-24T15:14:21.399Z] 15:14:21 INFO - logEvent@dom/base/test/test_bug435425.html:69:5
[task 2019-10-24T15:14:21.399Z] 15:14:21 INFO - maybeStop@dom/base/test/test_bug435425.html:93:11
[task 2019-10-24T15:14:21.399Z] 15:14:21 INFO - start/xhr.onloadend@dom/base/test/test_bug435425.html:128:16
[task 2019-10-24T15:14:21.399Z] 15:14:21 INFO - 402 INFO TEST-PASS | dom/base/test/test_bug435425.html | Wrong event target [[object XMLHttpRequest],loadend]!
[task 2019-10-24T15:19:12.735Z] 15:19:12 INFO - 403 INFO TEST-UNEXPECTED-FAIL | dom/base/test/test_bug435425.html | Test timed out.
| Comment hidden (Intermittent Failures Robot) |
| Assignee | ||
Comment 142•6 years ago
|
||
I think I've figured out the root cause of this.
- This test dom/base/test/test_bug435425.html expects that we should get a
progressevent afterloadstartbut we getloadinstead. - The
progressevent is triggered at XMLHttpRequestMainThread::OnProgress and theloadevent is at XMLHttpRequestMainThread::OnStartRequest. - Which means that HttpChannelChild::OnProgress should be called before HttpChannelChild::DoOnStartRequest.
- In parent process, the log did shows that HttpChannelParent::OnProgress happened before HttpChannelParent::OnStartRequest.
- The problem is that
OnProgressevent is dispatched to background thread and sent by PBackground andOnStartRequestis sent on main thread. So, this could cause that child process getOnStartRequestbeforeOnProgress.
I think maybe we should send OnProgress and OnStatus IPC events by main thread to make sure the event order is not changed.
We can also save one time event dispatch since HttpChannelChild::OnProgress and HttpChannelChild::OnStatus are expected to be called on main thread.
Honza, what do you think?
Comment 143•6 years ago
|
||
If the final consumer is on the main thread, then there is no need to send via PBck to a bck thread. Yes, I agree with what you suggest. Let's try how it goes, but please make sure, or at least keep an eye on, that there is no major perf regression with this. You know that adhoc reordering of events may always break stuff.
Thanks!
| Assignee | ||
Comment 144•6 years ago
|
||
Set the correct component for this bug and take this from Peter.
| Assignee | ||
Comment 145•6 years ago
|
||
Comment 146•6 years ago
|
||
| Comment hidden (Intermittent Failures Robot) |
Comment 148•6 years ago
|
||
Backed out changeset 582ee0d10d0c (bug 1370132) requested by kershaw
Push with failure: https://treeherder.mozilla.org/#/jobs?repo=autoland&resultStatus=superseded%2Ctestfailed%2Cbusted%2Cexception%2Crunnable&revision=582ee0d10d0c876207ef584a631e785993653e46
Backout: https://hg.mozilla.org/integration/autoland/rev/18389b50e525a6a97a3f99f48b016af1cc2082f6
Comment 149•6 years ago
|
||
| bugherder | ||
Updated•6 years ago
|
Comment 150•6 years ago
|
||
| Assignee | ||
Updated•6 years ago
|
Comment 151•6 years ago
|
||
| bugherder | ||
Updated•6 years ago
|
| Comment hidden (Intermittent Failures Robot) |
Description
•