Closed Bug 1576480 Opened 5 months ago Closed 3 months ago

Intermittent Fission /tests/dom/tests/mochitest/dom-level0/test_setting_document.domain_idn.html logged result after SimpleTest.finish(): wrong sender | unexpected data: punycode-whitelist-response | wrong source | wrong response for punycode

Categories

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

defect

Tracking

()

RESOLVED FIXED
mozilla71
Fission Milestone M4
Tracking Status
firefox-esr60 --- unaffected
firefox-esr68 --- unaffected
firefox67 --- unaffected
firefox68 --- unaffected
firefox69 --- unaffected
firefox70 --- disabled
firefox71 --- fixed

People

(Reporter: intermittent-bug-filer, Assigned: valentin)

Details

(Keywords: intermittent-failure, regression, test-disabled, Whiteboard: [necko-triaged])

Attachments

(2 files)

Filed by: btara [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=263388217&repo=mozilla-central
Full log: https://queue.taskcluster.net/v1/task/eZA3c87LTYu0a6wrg_tSzg/runs/0/artifacts/public/logs/live_backing.log


[task 2019-08-25T11:41:21.985Z] 11:41:21 INFO - TEST-START | dom/tests/mochitest/dom-level0/test_setting_document.domain_idn.html
[task 2019-08-25T11:41:22.069Z] 11:41:22 INFO - GECKO(4186) | [Parent 4186, Main Thread] WARNING: Cannot set transparency mode on non-popup windows.: file /builds/worker/workspace/build/src/widget/gtk/nsWindow.cpp, line 4456
[task 2019-08-25T11:41:22.105Z] 11:41:22 INFO - GECKO(4186) | ++DOMWINDOW == 18 (0x7f6b9020dc00) [pid = 4362] [serial = 49] [outer = 0x7f6b9108c880]
[task 2019-08-25T11:41:22.140Z] 11:41:22 INFO - GECKO(4186) | ++DOCSHELL 0x7f6b8fb5f800 == 6 [pid = 4362] [id = {3734eb88-9004-4cfa-b6cc-4cbd073d7121}]
[task 2019-08-25T11:41:22.141Z] 11:41:22 INFO - GECKO(4186) | ++DOMWINDOW == 19 (0x7f6b90284200) [pid = 4362] [serial = 50] [outer = (nil)]
[task 2019-08-25T11:41:22.142Z] 11:41:22 INFO - GECKO(4186) | ++DOCSHELL 0x7f6b8fbc4000 == 7 [pid = 4362] [id = {abea235c-1adb-4e63-af4f-3d76b4ce0254}]
[task 2019-08-25T11:41:22.142Z] 11:41:22 INFO - GECKO(4186) | ++DOMWINDOW == 20 (0x7f6b902843e0) [pid = 4362] [serial = 51] [outer = (nil)]
[task 2019-08-25T11:41:22.143Z] 11:41:22 INFO - GECKO(4186) | ++DOCSHELL 0x7f6b8fbc4800 == 8 [pid = 4362] [id = {93313687-1205-4e0c-8bf9-6556e97b14a7}]
[task 2019-08-25T11:41:22.143Z] 11:41:22 INFO - GECKO(4186) | ++DOMWINDOW == 21 (0x7f6b902845c0) [pid = 4362] [serial = 52] [outer = (nil)]
[task 2019-08-25T11:41:22.143Z] 11:41:22 INFO - GECKO(4186) | ++DOCSHELL 0x7f6b8fbc5000 == 9 [pid = 4362] [id = {3a509cf0-e30b-47e0-afda-c1f9f13fcbaa}]
[task 2019-08-25T11:41:22.143Z] 11:41:22 INFO - GECKO(4186) | ++DOMWINDOW == 22 (0x7f6b902847a0) [pid = 4362] [serial = 53] [outer = (nil)]
[task 2019-08-25T11:41:22.200Z] 11:41:22 INFO - GECKO(4186) | [Child 4362, Main Thread] WARNING: CompleteRedirectSetup failed, HttpChannelChild already open?: file /builds/worker/workspace/build/src/netwerk/protocol/http/HttpChannelChild.cpp, line 2125
[task 2019-08-25T11:41:22.262Z] 11:41:22 INFO - GECKO(4186) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmpptlrHM.mozrunner/runtests_leaks_tab_pid4498.log
[task 2019-08-25T11:41:22.379Z] 11:41:22 INFO - GECKO(4186) | [Child 4362, Main Thread] WARNING: NS_ENSURE_TRUE(currentInner) failed: file /builds/worker/workspace/build/src/dom/base/WindowDestroyedEvent.cpp, line 106
[task 2019-08-25T11:41:22.380Z] 11:41:22 INFO - GECKO(4186) | --DOMWINDOW == 21 (0x7f6b90284200) [pid = 4362] [serial = 50] [outer = (nil)] [url = ]
[task 2019-08-25T11:41:22.422Z] 11:41:22 INFO - GECKO(4186) | [Child 4362, Main Thread] WARNING: CompleteRedirectSetup failed, HttpChannelChild already open?: file /builds/worker/workspace/build/src/netwerk/protocol/http/HttpChannelChild.cpp, line 2125
[task 2019-08-25T11:41:22.423Z] 11:41:22 INFO - GECKO(4186) | [Child 4362, Main Thread] WARNING: CompleteRedirectSetup failed, HttpChannelChild already open?: file /builds/worker/workspace/build/src/netwerk/protocol/http/HttpChannelChild.cpp, line 2125
[task 2019-08-25T11:41:22.426Z] 11:41:22 INFO - GECKO(4186) | [Child 4362, Main Thread] WARNING: CompleteRedirectSetup failed, HttpChannelChild already open?: file /builds/worker/workspace/build/src/netwerk/protocol/http/HttpChannelChild.cpp, line 2125
[task 2019-08-25T11:41:22.457Z] 11:41:22 INFO - GECKO(4186) | [Child 4362, Main Thread] WARNING: NS_ENSURE_TRUE(currentInner) failed: file /builds/worker/workspace/build/src/dom/base/WindowDestroyedEvent.cpp, line 106
[task 2019-08-25T11:41:22.463Z] 11:41:22 INFO - GECKO(4186) | [Child 4362, Main Thread] WARNING: NS_ENSURE_TRUE(currentInner) failed: file /builds/worker/workspace/build/src/dom/base/WindowDestroyedEvent.cpp, line 106
[task 2019-08-25T11:41:22.463Z] 11:41:22 INFO - GECKO(4186) | [Child 4362, Main Thread] WARNING: NS_ENSURE_TRUE(currentInner) failed: file /builds/worker/workspace/build/src/dom/base/WindowDestroyedEvent.cpp, line 106
[task 2019-08-25T11:41:22.464Z] 11:41:22 INFO - GECKO(4186) | --DOCSHELL 0x7f6b8fb5b000 == 8 [pid = 4362] [id = {90bace18-75ee-4294-9ae1-9bcf72efe817}] [url = http://mochi.test:8888/tests/dom/tests/mochitest/dom-level0/iframe2_location_setters.html]
[task 2019-08-25T11:41:22.464Z] 11:41:22 INFO - GECKO(4186) | --DOCSHELL 0x7f6b8fb56000 == 7 [pid = 4362] [id = {05e30c2c-d767-4a56-afe7-dd0c9e6eb021}] [url = http://mochi.test:8888/tests/dom/tests/mochitest/dom-level0/framed_location.html]
[task 2019-08-25T11:41:22.464Z] 11:41:22 INFO - GECKO(4186) | --DOMWINDOW == 20 (0x7f6b902847a0) [pid = 4362] [serial = 53] [outer = (nil)] [url = ]
[task 2019-08-25T11:41:22.465Z] 11:41:22 INFO - GECKO(4186) | --DOMWINDOW == 19 (0x7f6b910e7400) [pid = 4362] [serial = 40] [outer = (nil)] [url = http://mochi.test:8888/tests/dom/tests/mochitest/dom-level0/iframe1_location_setters.html]
[task 2019-08-25T11:41:22.465Z] 11:41:22 INFO - GECKO(4186) | --DOMWINDOW == 18 (0x7f6b8fbec400) [pid = 4362] [serial = 41] [outer = (nil)] [url = http://mochi.test:8888/tests/dom/tests/mochitest/dom-level0/iframe2_location_setters.html]
[task 2019-08-25T11:41:22.466Z] 11:41:22 INFO - GECKO(4186) | --DOMWINDOW == 17 (0x7f6b902cb000) [pid = 4362] [serial = 39] [outer = (nil)] [url = about:blank]
[task 2019-08-25T11:41:22.466Z] 11:41:22 INFO - GECKO(4186) | --DOMWINDOW == 16 (0x7f6b902c8400) [pid = 4362] [serial = 36] [outer = (nil)] [url = http://mochi.test:8888/tests/SimpleTest/iframe-between-tests.html]
[task 2019-08-25T11:41:22.467Z] 11:41:22 INFO - GECKO(4186) | --DOMWINDOW == 15 (0x7f6b902845c0) [pid = 4362] [serial = 52] [outer = (nil)] [url = ]
[task 2019-08-25T11:41:22.467Z] 11:41:22 INFO - GECKO(4186) | --DOMWINDOW == 14 (0x7f6b902843e0) [pid = 4362] [serial = 51] [outer = (nil)] [url = ]
[task 2019-08-25T11:41:22.468Z] 11:41:22 INFO - GECKO(4186) | --DOCSHELL 0x7f6b90236000 == 6 [pid = 4362] [id = {f291be9d-aeed-4565-9991-b49ff9d4395c}] [url = http://mochi.test:8888/tests/dom/tests/mochitest/dom-level0/file_separate_post_message_queue.html]
[task 2019-08-25T11:41:22.544Z] 11:41:22 INFO - GECKO(4186) | Couldn't convert chrome URL: chrome://branding/locale/brand.properties
[task 2019-08-25T11:41:22.607Z] 11:41:22 INFO - GECKO(4186) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmpptlrHM.mozrunner/runtests_leaks_tab_pid4508.log
[task 2019-08-25T11:41:22.787Z] 11:41:22 INFO - GECKO(4186) | --DOMWINDOW == 13 (0x7f6b9108cc40) [pid = 4362] [serial = 18] [outer = (nil)] [url = http://mochi.test:8888/tests/dom/tests/mochitest/dom-level0/innerWidthHeight_script.html]
[task 2019-08-25T11:41:22.815Z] 11:41:22 INFO - GECKO(4186) | WARN 2019-08-25T11:41:22Z: audio_thread_priority::rt_linux: Could not make thread real-time.
[task 2019-08-25T11:41:22.816Z] 11:41:22 INFO - GECKO(4186) | WARN 2019-08-25T11:41:22Z: audioipc_client: Could not promote audio threads to real-time during initialization.
[task 2019-08-25T11:41:22.834Z] 11:41:22 INFO - GECKO(4186) | Couldn't convert chrome URL: chrome://branding/locale/brand.properties
[task 2019-08-25T11:41:22.836Z] 11:41:22 INFO - GECKO(4186) | ++DOCSHELL 0x7f9febbbc000 == 1 [pid = 4498] [id = {575184c4-c88a-43a2-a26e-52a7cfe749d1}]
[task 2019-08-25T11:41:22.960Z] 11:41:22 INFO - GECKO(4186) | ++DOMWINDOW == 1 (0x7fa004fee100) [pid = 4498] [serial = 1] [outer = (nil)]
[task 2019-08-25T11:41:22.964Z] 11:41:22 INFO - GECKO(4186) | ++DOMWINDOW == 2 (0x7f9febb4e400) [pid = 4498] [serial = 2] [outer = 0x7fa004fee100]
[task 2019-08-25T11:41:22.985Z] 11:41:22 INFO - GECKO(4186) | WARN 2019-08-25T11:41:22Z: audio_thread_priority::rt_linux: Could not make thread real-time.
[task 2019-08-25T11:41:22.985Z] 11:41:22 INFO - GECKO(4186) | WARN 2019-08-25T11:41:22Z: audioipc_client: Could not promote audio threads to real-time during initialization.
[task 2019-08-25T11:41:22.988Z] 11:41:22 INFO - GECKO(4186) | [Child 4498, Main Thread] WARNING: '!topWindow', file /builds/worker/workspace/build/src/toolkit/components/antitracking/AntiTrackingCommon.cpp, line 1306
[task 2019-08-25T11:41:22.996Z] 11:41:22 INFO - GECKO(4186) | ++DOCSHELL 0x7fcdb38bb800 == 1 [pid = 4508] [id = {cbba9830-7b3a-4cf2-a716-9d850a736c08}]
[task 2019-08-25T11:41:23.066Z] 11:41:23 INFO - GECKO(4186) | ++DOMWINDOW == 1 (0x7fcdcccee100) [pid = 4508] [serial = 1] [outer = (nil)]
[task 2019-08-25T11:41:23.066Z] 11:41:23 INFO - GECKO(4186) | ++DOMWINDOW == 2 (0x7fcdb41e1800) [pid = 4508] [serial = 2] [outer = 0x7fcdcccee100]
[task 2019-08-25T11:41:23.069Z] 11:41:23 INFO - GECKO(4186) | [Child 4508, Main Thread] WARNING: '!topWindow', file /builds/worker/workspace/build/src/toolkit/components/antitracking/AntiTrackingCommon.cpp, line 1306
[task 2019-08-25T11:41:23.105Z] 11:41:23 INFO - GECKO(4186) | ++DOCSHELL 0x7f9feb665000 == 2 [pid = 4498] [id = {8515552e-e089-4b9b-82b1-2bd78c635d9d}]
[task 2019-08-25T11:41:23.105Z] 11:41:23 INFO - GECKO(4186) | ++DOMWINDOW == 3 (0x7fa004fee880) [pid = 4498] [serial = 3] [outer = (nil)]
[task 2019-08-25T11:41:23.105Z] 11:41:23 INFO - GECKO(4186) | ++DOMWINDOW == 4 (0x7f9febc9c400) [pid = 4498] [serial = 4] [outer = 0x7fa004fee880]
[task 2019-08-25T11:41:23.121Z] 11:41:23 INFO - GECKO(4186) | ++DOCSHELL 0x7fcdb4779800 == 2 [pid = 4508] [id = {be5108bd-84a6-4231-bb9b-279fa652d60c}]
[task 2019-08-25T11:41:23.121Z] 11:41:23 INFO - GECKO(4186) | ++DOMWINDOW == 3 (0x7fcdcccee880) [pid = 4508] [serial = 3] [outer = (nil)]
[task 2019-08-25T11:41:23.121Z] 11:41:23 INFO - GECKO(4186) | ++DOMWINDOW == 4 (0x7fcdb470ec00) [pid = 4508] [serial = 4] [outer = 0x7fcdcccee880]
[task 2019-08-25T11:41:23.125Z] 11:41:23 INFO - GECKO(4186) | [Child 4498, Main Thread] WARNING: '!topWindow', file /builds/worker/workspace/build/src/toolkit/components/antitracking/AntiTrackingCommon.cpp, line 1306
[task 2019-08-25T11:41:23.125Z] 11:41:23 INFO - GECKO(4186) | [Child 4508, Main Thread] WARNING: '!topWindow', file /builds/worker/workspace/build/src/toolkit/components/antitracking/AntiTrackingCommon.cpp, line 1306
[task 2019-08-25T11:41:23.226Z] 11:41:23 INFO - GECKO(4186) | ++DOMWINDOW == 5 (0x7f9feb6d8800) [pid = 4498] [serial = 5] [outer = 0x7fa004fee100]
[task 2019-08-25T11:41:23.229Z] 11:41:23 INFO - GECKO(4186) | [Child 4498, Main Thread] WARNING: '!topWindow', file /builds/worker/workspace/build/src/toolkit/components/antitracking/AntiTrackingCommon.cpp, line 1306
[task 2019-08-25T11:41:23.250Z] 11:41:23 INFO - GECKO(4186) | ++DOMWINDOW == 6 (0x7f9feb6dd800) [pid = 4498] [serial = 6] [outer = 0x7fa004fee880]
[task 2019-08-25T11:41:23.267Z] 11:41:23 INFO - GECKO(4186) | [Child 4498, Main Thread] WARNING: '!topWindow', file /builds/worker/workspace/build/src/toolkit/components/antitracking/AntiTrackingCommon.cpp, line 1306
[task 2019-08-25T11:41:23.268Z] 11:41:23 INFO - GECKO(4186) | ++DOMWINDOW == 5 (0x7fcdb48a6800) [pid = 4508] [serial = 5] [outer = 0x7fcdcccee100]
[task 2019-08-25T11:41:23.284Z] 11:41:23 INFO - GECKO(4186) | [Child 4508, Main Thread] WARNING: '!topWindow', file /builds/worker/workspace/build/src/toolkit/components/antitracking/AntiTrackingCommon.cpp, line 1306
[task 2019-08-25T11:41:23.300Z] 11:41:23 INFO - GECKO(4186) | ++DOMWINDOW == 6 (0x7fcdb48ab400) [pid = 4508] [serial = 6] [outer = 0x7fcdcccee880]
[task 2019-08-25T11:41:23.320Z] 11:41:23 INFO - GECKO(4186) | [Child 4508, Main Thread] WARNING: '!topWindow', file /builds/worker/workspace/build/src/toolkit/components/antitracking/AntiTrackingCommon.cpp, line 1306
[task 2019-08-25T11:41:23.478Z] 11:41:23 INFO - GECKO(4186) | MEMORY STAT | vsize 2512MB | residentFast 132MB | heapAllocated 13MB
[task 2019-08-25T11:41:23.559Z] 11:41:23 INFO - TEST-OK | dom/tests/mochitest/dom-level0/test_setting_document.domain_idn.html | took 1575ms
[task 2019-08-25T11:41:23.620Z] 11:41:23 INFO - GECKO(4186) | [Parent 4186, Main Thread] WARNING: Cannot set transparency mode on non-popup windows.: file /builds/worker/workspace/build/src/widget/gtk/nsWindow.cpp, line 4456
[task 2019-08-25T11:41:23.640Z] 11:41:23 ERROR - /tests/dom/tests/mochitest/dom-level0/test_setting_document.domain_idn.html logged result after SimpleTest.finish(): wrong sender
[task 2019-08-25T11:41:23.642Z] 11:41:23 ERROR - /tests/dom/tests/mochitest/dom-level0/test_setting_document.domain_idn.html logged result after SimpleTest.finish(): unexpected data: punycode-whitelist-response
[task 2019-08-25T11:41:23.646Z] 11:41:23 ERROR - /tests/dom/tests/mochitest/dom-level0/test_setting_document.domain_idn.html logged result after SimpleTest.finish(): wrong source
[task 2019-08-25T11:41:23.647Z] 11:41:23 ERROR - /tests/dom/tests/mochitest/dom-level0/test_setting_document.domain_idn.html logged result after SimpleTest.finish(): wrong response for punycode
[task 2019-08-25T11:41:23.648Z] 11:41:23 ERROR - /tests/dom/tests/mochitest/dom-level0/test_setting_document.domain_idn.html logged result after SimpleTest.finish(): wrong sender
[task 2019-08-25T11:41:23.652Z] 11:41:23 ERROR - /tests/dom/tests/mochitest/dom-level0/test_setting_document.domain_idn.html logged result after SimpleTest.finish(): wrong sender
[task 2019-08-25T11:41:23.653Z] 11:41:23 ERROR - /tests/dom/tests/mochitest/dom-level0/test_setting_document.domain_idn.html logged result after SimpleTest.finish(): unexpected data: idn-nowhitelist-response
[task 2019-08-25T11:41:23.654Z] 11:41:23 ERROR - /tests/dom/tests/mochitest/dom-level0/test_setting_document.domain_idn.html logged result after SimpleTest.finish(): wrong source
[task 2019-08-25T11:41:23.655Z] 11:41:23 ERROR - /tests/dom/tests/mochitest/dom-level0/test_setting_document.domain_idn.html logged result after SimpleTest.finish(): wrong response for IDN
[task 2019-08-25T11:41:23.671Z] 11:41:23 ERROR - /tests/dom/tests/mochitest/dom-level0/test_setting_document.domain_idn.html logged result after SimpleTest.finish(): wrong sender
[task 2019-08-25T11:41:23.672Z] 11:41:23 ERROR - /tests/dom/tests/mochitest/dom-level0/test_setting_document.domain_idn.html logged result after SimpleTest.finish(): wrong sender
[task 2019-08-25T11:41:23.673Z] 11:41:23 ERROR - /tests/dom/tests/mochitest/dom-level0/test_setting_document.domain_idn.html logged result after SimpleTest.finish(): unexpected data: punycode-nowhitelist-response
[task 2019-08-25T11:41:23.674Z] 11:41:23 ERROR - /tests/dom/tests/mochitest/dom-level0/test_setting_document.domain_idn.html logged result after SimpleTest.finish(): wrong source
[task 2019-08-25T11:41:23.675Z] 11:41:23 ERROR - /tests/dom/tests/mochitest/dom-level0/test_setting_document.domain_idn.html logged result after SimpleTest.finish(): wrong response for punycode
[task 2019-08-25T11:41:23.676Z] 11:41:23 INFO - GECKO(4186) | ++DOMWINDOW == 14 (0x7f6b8e384800) [pid = 4362] [serial = 54] [outer = 0x7f6b9108c880]

Summary: Intermittent /tests/dom/tests/mochitest/dom-level0/test_setting_document.domain_idn.html logged result after SimpleTest.finish(): wrong sender | unexpected data: punycode-whitelist-response | wrong source | wrong response for punycode → Intermittent Fission /tests/dom/tests/mochitest/dom-level0/test_setting_document.domain_idn.html logged result after SimpleTest.finish(): wrong sender | unexpected data: punycode-whitelist-response | wrong source | wrong response for punycode

Skip test on fission to avoid frequent intermittent failures. Although already
marked as failing, the way this test fails still causes task failures unless
the test is skipped.

Pushed by gbrown@mozilla.com:
https://hg.mozilla.org/integration/autoland/rev/59dd2996a78e
Skip test_setting_document.domain_idn.html on fission for frequent failures; r=kmag
Status: NEW → RESOLVED
Closed: 4 months ago
Resolution: --- → FIXED
Target Milestone: --- → mozilla71
Assignee: nobody → gbrown
Assignee: gbrown → nobody
Status: RESOLVED → REOPENED
Keywords: test-disabled
Resolution: FIXED → ---
Target Milestone: mozilla71 → ---
No longer blocks: 1582881

Hi Valentin,
There's a TODO in the test saying "TODO: after bug 945240 this test is no longer relevant. Should fix." Does that mean we should remove the test entirely?

(According to the test cases and related bug 945240, I think Necko team may have more insights and expertise on this, so moving the component. )

Component: DOM: Core & HTML → DOM: Networking
Flags: needinfo?(valentin.gosu)
Priority: P5 → --

So, following bug 945240 all URLs are represented are punycode - so the whitelisted/not-whitelisted part of the test no longer applies. Not sure if we should actually remove the test or not. Also, since bug 414090 was actually fixed, I don't think the test is checking for the right thing anymore.

In any case, the reason it's failing is that it's doing setTimeout(..., 0) to wait for the postMessage from the iframes. - which will fail under fission. We should properly wait for the message.

Flags: needinfo?(valentin.gosu)
Assignee: nobody → valentin.gosu
Priority: -- → P2
Whiteboard: [necko-triaged]
Pushed by valentin.gosu@gmail.com:
https://hg.mozilla.org/integration/autoland/rev/51771d8af5b1
Fix test_setting_document.domain_idn.html for fission r=JuniorHsu
Status: REOPENED → RESOLVED
Closed: 4 months ago3 months ago
Resolution: --- → FIXED
Target Milestone: --- → mozilla71

Retroactively moving fixed bugs whose summaries mention "Fission" (or other Fission-related keywords) but are not assigned to a Fission Milestone to an appropriate Fission Milestone.

This will generate a lot of bugmail, so you can filter your bugmail for the following UUID and delete them en masse:

0ee3c76a-bc79-4eb2-8d12-05dc0b68e732

Fission Milestone: --- → M4
You need to log in before you can comment on or make changes to this bug.