1605807 - Intermittent docshell/test/mochitest/test_bug529119-1.html | Is on an error page - got "about:blank", expected "http://www.some-nonexistent-domain-27489274c892748217cn2384.com/"

Status: RESOLVED FIXED

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

Description

[Treeherder Bug Filer]

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

---

[task 2019-12-23T22:17:08.779Z] 22:17:08 INFO - TEST-START | docshell/test/mochitest/test_bug529119-1.html
[task 2019-12-23T22:17:08.820Z] 22:17:08 INFO - GECKO(1117) | [Parent 1117, Main Thread] WARNING: we only accept nsIURI interface type, patch welcome: file /builds/worker/workspace/build/src/dom/ipc/PropertyBagUtils.cpp, line 112
[task 2019-12-23T22:17:08.821Z] 22:17:08 INFO - GECKO(1117) | [Child 1283, Main Thread] WARNING: Trying to request nsIHttpChannel from DocumentChannelChild, this is likely broken: file /builds/worker/workspace/build/src/netwerk/ipc/DocumentChannelChild.cpp, line 63
[task 2019-12-23T22:17:09.084Z] 22:17:09 INFO - GECKO(1117) | [Parent 1117, Main Thread] WARNING: we only accept nsIURI interface type, patch welcome: file /builds/worker/workspace/build/src/dom/ipc/PropertyBagUtils.cpp, line 112
[task 2019-12-23T22:17:09.084Z] 22:17:09 INFO - GECKO(1117) | [Child 1283, Main Thread] WARNING: Trying to request nsIHttpChannel from DocumentChannelChild, this is likely broken: file /builds/worker/workspace/build/src/netwerk/ipc/DocumentChannelChild.cpp, line 63
[task 2019-12-23T22:17:10.829Z] 22:17:10 INFO - GECKO(1117) | [Child 1283, Main Thread] WARNING: Trying to request nsIHttpChannel from DocumentChannelChild, this is likely broken: file /builds/worker/workspace/build/src/netwerk/ipc/DocumentChannelChild.cpp, line 63
[task 2019-12-23T22:17:10.829Z] 22:17:10 INFO - GECKO(1117) | [Child 1283, Main Thread] WARNING: Trying to request nsIHttpChannel from DocumentChannelChild, this is likely broken: file /builds/worker/workspace/build/src/netwerk/ipc/DocumentChannelChild.cpp, line 63
[task 2019-12-23T22:17:10.829Z] 22:17:10 INFO - GECKO(1117) | [Child 1283, Main Thread] WARNING: NS_ENSURE_TRUE(request) failed: file /builds/worker/workspace/build/src/netwerk/base/nsLoadGroup.cpp, line 574
[task 2019-12-23T22:17:10.877Z] 22:17:10 INFO - GECKO(1117) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmpk0j0Up.mozrunner/runtests_leaks_tab_pid1324.log
[task 2019-12-23T22:17:10.892Z] 22:17:10 INFO - GECKO(1117) | [Child 1324, Main Thread] WARNING: No CID found when attempting to map contract ID: file /builds/worker/workspace/build/src/xpcom/components/nsComponentManager.cpp, line 721
[task 2019-12-23T22:17:11.009Z] 22:17:11 INFO - GECKO(1117) | [Parent 1117, Main Thread] WARNING: we only accept nsIURI interface type, patch welcome: file /builds/worker/workspace/build/src/dom/ipc/PropertyBagUtils.cpp, line 112
[task 2019-12-23T22:17:11.016Z] 22:17:11 INFO - GECKO(1117) | Couldn't convert chrome URL: chrome://branding/locale/brand.properties
[task 2019-12-23T22:17:11.017Z] 22:17:11 INFO - GECKO(1117) | [Child 1324, Main Thread] WARNING: Could not get the program name for a cubeb stream.: 'NS_SUCCEEDED(rv)', file /builds/worker/workspace/build/src/dom/media/CubebUtils.cpp, line 381
[task 2019-12-23T22:17:11.120Z] 22:17:11 INFO - GECKO(1117) | [Child 1324, Main Thread] WARNING: could not set real-time limit at process startup: file /builds/worker/workspace/build/src/dom/ipc/ContentChild.cpp, line 1820
[task 2019-12-23T22:17:11.176Z] 22:17:11 INFO - GECKO(1117) | [Child 1324, Main Thread] WARNING: NS_ENSURE_TRUE(mPresShell) failed: file /builds/worker/workspace/build/src/layout/base/nsPresContext.cpp, line 848
[task 2019-12-23T22:17:13.249Z] 22:17:13 INFO - TEST-INFO | started process screentopng
[task 2019-12-23T22:17:13.891Z] 22:17:13 INFO - TEST-INFO | screentopng: exit 0
[task 2019-12-23T22:17:13.892Z] 22:17:13 INFO - Buffered messages logged at 22:17:08
[task 2019-12-23T22:17:13.893Z] 22:17:13 INFO - TEST-PASS | docshell/test/mochitest/test_bug529119-1.html | A valid string reason is expected
[task 2019-12-23T22:17:13.893Z] 22:17:13 INFO - TEST-PASS | docshell/test/mochitest/test_bug529119-1.html | Reason cannot be empty
[task 2019-12-23T22:17:13.901Z] 22:17:13 INFO - Buffered messages logged at 22:17:09
[task 2019-12-23T22:17:13.901Z] 22:17:13 INFO - TEST-FAIL | docshell/test/mochitest/test_bug529119-1.html | The author of the test has indicated that flaky timeouts are expected. Reason: untriaged
[task 2019-12-23T22:17:13.903Z] 22:17:13 INFO - Buffered messages logged at 22:17:10
[task 2019-12-23T22:17:13.904Z] 22:17:13 INFO - TEST-FAIL | docshell/test/mochitest/test_bug529119-1.html | The author of the test has indicated that flaky timeouts are expected. Reason: untriaged
[task 2019-12-23T22:17:13.904Z] 22:17:13 INFO - Buffered messages logged at 22:17:11
[task 2019-12-23T22:17:13.905Z] 22:17:13 INFO - TEST-PASS | docshell/test/mochitest/test_bug529119-1.html | Waiting for error page succeeded
[task 2019-12-23T22:17:13.907Z] 22:17:13 INFO - TEST-FAIL | docshell/test/mochitest/test_bug529119-1.html | The author of the test has indicated that flaky timeouts are expected. Reason: untriaged
[task 2019-12-23T22:17:13.908Z] 22:17:13 INFO - Buffered messages logged at 22:17:12
[task 2019-12-23T22:17:13.908Z] 22:17:13 INFO - TEST-FAIL | docshell/test/mochitest/test_bug529119-1.html | The author of the test has indicated that flaky timeouts are expected. Reason: untriaged
[task 2019-12-23T22:17:13.909Z] 22:17:13 INFO - Buffered messages logged at 22:17:13
[task 2019-12-23T22:17:13.910Z] 22:17:13 INFO - TEST-PASS | docshell/test/mochitest/test_bug529119-1.html | Waiting for error page succeeded
[task 2019-12-23T22:17:13.910Z] 22:17:13 INFO - Buffered messages finished
[task 2019-12-23T22:17:13.912Z] 22:17:13 INFO - TEST-UNEXPECTED-FAIL | docshell/test/mochitest/test_bug529119-1.html | Is on an error page - got "about:blank", expected "http://www.some-nonexistent-domain-27489274c892748217cn2384.com/"
[task 2019-12-23T22:17:13.912Z] 22:17:13 INFO - SimpleTest.is@SimpleTest/SimpleTest.js:325:16
[task 2019-12-23T22:17:13.913Z] 22:17:13 INFO - windowLoaded@docshell/test/mochitest/test_bug529119-1.html:60:9
[task 2019-12-23T22:17:13.913Z] 22:17:13 INFO - async*onload@docshell/test/mochitest/bug529119-window.html:1:15
[task 2019-12-23T22:17:13.914Z] 22:17:13 INFO - TEST-PASS | docshell/test/mochitest/test_bug529119-1.html | Must not get www.example.com page on reload of an error page
[task 2019-12-23T22:17:13.915Z] 22:17:13 INFO - GECKO(1117) | MEMORY STAT | vsize 2530MB | residentFast 150MB | heapAllocated 15MB
[task 2019-12-23T22:17:13.915Z] 22:17:13 INFO - TEST-OK | docshell/test/mochitest/test_bug529119-1.html | took 4608ms

Comment 1

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 1

Platform breakdown:

• linux64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1605807&startday=2019-12-23&endday=2019-12-29&tree=all

Comment 2

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

• linux64-qr: 1

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

Comment 3

[Edwin Takahashi (:egao | infrequent contributor)]

This is a high frequency intermittence on ubuntu1804 for linux1804-64-qr/debug specifically.

try push: https://treeherder.mozilla.org/#/jobs?repo=try&group_state=expanded&tier=1%2C2%2C3&revision=97c5c868af245b1247f5f4f150d6fbfe889e8f15

:neha - could you please pass on the ni to someone that can take a look?

Would temporarily disabling this test like skip-if = (os == 'linux' && os_version == '18.04' && debug && webrender && fission) be an acceptable way forward? I am looking to migrate mochitest suites to run on ubuntu1804 by the Berlin All Hands, and this is the last failure preventing the migration of mochitest-devtools-chrome.

Comment 4

[Andrew McCreight (out of office until 8/21) [:mccr8]]

Neha is on leave. Maybe Boris has an opinion, as the docshell module owner.

Comment 5

[Boris Zbarsky [:bzbarsky]]

Yes, I think doing that is fine.

This test is fundamentally racy: once it does the reload, it kinda hopes that waiting 1s is going to be long enough for it to be dealing with the "new" page, and so on. I'll see if I can figure out a sane way to improve it...

Comment 6

[Boris Zbarsky [:bzbarsky]]

Attachment: Bug 1605807. Fix flaky polling in test. r=kmag

Comment 7

[Boris Zbarsky [:bzbarsky]]

Does https://phabricator.services.mozilla.com/D60079 fix things?

Comment 8

[Edwin Takahashi (:egao | infrequent contributor)]

Thanks for your quick responses. I can try applying the patch and making a push.

Comment 9

[Edwin Takahashi (:egao | infrequent contributor)]

Try push: https://treeherder.mozilla.org/#/jobs?repo=try&group_state=expanded&selectedJob=285104581&tier=1%2C2%2C3&revision=5f5ea13c7b48c97085fb0a104df237a510f190a0

Comment 10

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/f37597c14427
Fix flaky polling in test.  r=kmag

Comment 11

[Edwin Takahashi (:egao | infrequent contributor)]

Try push on linux1804-qr/debug on ubuntu1804 image:
https://treeherder.mozilla.org/#/jobs?repo=try&revision=c05ebaf0d718a1c22ec2775cb24f51fab960ae7e

This push should show that the fix works for ubuntu1804.

EDIT: this patch works for ubuntu1804.

Comment 12

[Intermittent Failures Robot]

18 failures in 667 pushes (0.027 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 18

Platform breakdown:

• linux64-qr: 18

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1605807&startday=2020-01-16&endday=2020-01-16&tree=all

Comment 13

[Oana Pop-Rus]

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

Comment 14

[Sebastian Hengst [:aryx] (needinfo me if it's about an intermittent or backout)]

This continues to fail frequently on Linux x64 QR debug: https://treeherder.mozilla.org/#/jobs?repo=autoland&resultStatus=success%2Ctestfailed%2Cbusted%2Cexception%2Cretry%2Cusercancel%2Crunnable&tochange=771c8ad59ac0d06ab1edab023362c95b7292dfc3&searchStr=m-fis%282%29%2Clinux%2Cx64%2Cquantumrender%2Cdebug

Comment 15

[Boris Zbarsky [:bzbarsky]]

Like I said in bug 1609806, I will try to improve this on Monday. We're catching the transient about:blank in the new process, which doesn't exist in the non-fission world.

Comment 16

[Boris Zbarsky [:bzbarsky]]

Attachment: Bug 1605807 followup. Fix more polling flakiness in this test. r=kmag

I tested this by using delay(1) instead of delay(100), which reliably gives me
the "Is on an error page initially" failure without these changes and reliably
passs with them.

Comment 17

[Intermittent Failures Robot]

39 failures in 659 pushes (0.059 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 35
• mozilla-central: 4

Platform breakdown:

• linux64-qr: 26
• windows10-64-qr: 1
• linux1804-64-qr: 11
• linux1804-64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1605807&startday=2020-01-17&endday=2020-01-17&tree=all

Comment 18

[Intermittent Failures Robot]

18 failures in 143 pushes (0.126 failures/push) were associated with this bug yesterday.

Repository breakdown:

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

Platform breakdown:

• linux1804-64-qr: 13
• linux1804-64: 3
• windows10-64: 1
• windows10-64-qr: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1605807&startday=2020-01-18&endday=2020-01-18&tree=all

Comment 19

[Intermittent Failures Robot]

92 failures in 3773 pushes (0.024 failures/push) were associated with this bug in the last 7 days.

This is the #9 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: 76
• try: 4
• mozilla-central: 12

Platform breakdown:

• linux64-qr: 49
• linux1804-64-qr: 35
• windows10-64-qr: 3
• linux1804-64: 4
• windows10-64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1605807&startday=2020-01-13&endday=2020-01-19&tree=all

Comment 20

[Cosmin Sabou [:CosminS]]

Kris, could you please review Boris's patch as it's one of the most frequent failures on trees having 96 total failures in the last 7 days. https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?startday=2020-01-13&endday=2020-01-20&tree=trunk&bug=1605807
Thank you.

Comment 21

[Intermittent Failures Robot]

101 failures in 543 pushes (0.186 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: 96
• try: 1
• mozilla-central: 4

Platform breakdown:

• linux1804-64-qr: 97
• linux1804-64: 3
• windows10-64-qr: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1605807&startday=2020-01-20&endday=2020-01-20&tree=all

Comment 22

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/43bfa2564534
followup.  Fix more polling flakiness in this test.  r=test-only

Comment 23

[Noemi Erli[:noemi_erli]]

https://hg.mozilla.org/mozilla-central/rev/43bfa2564534

Comment 24

[Intermittent Failures Robot]

59 failures in 732 pushes (0.081 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 17
• mozilla-central: 35
• try: 7

Platform breakdown:

• linux1804-64-qr: 32
• linux1804-64: 14
• windows10-64: 7
• windows10-64-qr: 6

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

Comment 25

[Intermittent Failures Robot]

160 failures in 4004 pushes (0.04 failures/push) were associated with this bug in the last 7 days.

This is the #1 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: 113
• try: 8
• mozilla-central: 39

Platform breakdown:

• linux1804-64-qr: 129
• linux1804-64: 17
• windows10-64-qr: 7
• windows10-64: 7

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1605807&startday=2020-01-20&endday=2020-01-26&tree=all

Comment 26

[Boris Zbarsky [:bzbarsky]]

All the failures are from before the second changeset here landed.