Closed Bug 1509291 Opened Last year Closed Last year

Intermittent browser/base/content/test/plugins/browser_CTP_drag_drop.js | leaked 1 window(s) until shutdown [url = http://127.0.0.1:8888/browser/browser/base/content/test/plugins/plugin_test.html]

Categories

(Core :: Plug-ins, defect, P5)

defect

Tracking

()

RESOLVED FIXED
mozilla65
Tracking Status
firefox65 --- fixed

People

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

References

Details

(Keywords: intermittent-failure)

Attachments

(1 file)

Filed by: rmaries [at] mozilla.com

https://treeherder.mozilla.org/logviewer.html#?job_id=213354842&repo=mozilla-central

https://queue.taskcluster.net/v1/task/HoSgoxDeSyicEgqFMJfckQ/runs/0/artifacts/public/logs/live_backing.log

[task 2018-11-22T10:30:01.638Z] 10:30:01     INFO - GECKO(1824) | [Parent 1824, Main Thread] WARNING: NS_ENSURE_TRUE(!mHasOrHasHadOwnerWindow || mOwnerWindow) failed: file /builds/worker/workspace/build/src/obj-firefox/dist/include/mozilla/DOMEventTargetHelper.h, line 145
[task 2018-11-22T10:30:01.977Z] 10:30:01     INFO - GECKO(1824) | --DOMWINDOW == 13 (0x7fccf48a0000) [pid = 1824] [serial = 59] [outer = (nil)] [url = about:blank]
[task 2018-11-22T10:30:01.979Z] 10:30:01     INFO - GECKO(1824) | --DOMWINDOW == 12 (0x7fcd1981d800) [pid = 1824] [serial = 6] [outer = (nil)] [url = about:blank]
[task 2018-11-22T10:30:01.979Z] 10:30:01     INFO - GECKO(1824) | --DOCSHELL 0x7fcd044e7800 == 6 [pid = 1824] [id = {e5c08a69-15b7-41cb-b139-83667f7b7b5d}]
[task 2018-11-22T10:30:01.981Z] 10:30:01     INFO - GECKO(1824) | --DOMWINDOW == 11 (0x7fcd0d30dc00) [pid = 1824] [serial = 3] [outer = (nil)] [url = chrome://browser/content/browser.xul]
[task 2018-11-22T10:30:01.983Z] 10:30:01     INFO - GECKO(1824) | --DOMWINDOW == 10 (0x7fcd04435400) [pid = 1824] [serial = 20] [outer = (nil)] [url = chrome://mochikit/content/browser-harness.xul]
[task 2018-11-22T10:30:01.984Z] 10:30:01     INFO - GECKO(1824) | --DOCSHELL 0x7fcd10f54000 == 5 [pid = 1824] [id = {1ff3975a-0a28-4e56-b587-8b8c58784435}]
[task 2018-11-22T10:30:01.986Z] 10:30:01     INFO - GECKO(1824) | --DOCSHELL 0x7fcd0be77000 == 4 [pid = 1824] [id = {a8821c48-0a7d-4e1b-b6ac-a5f2498858aa}]
[task 2018-11-22T10:30:01.988Z] 10:30:01     INFO - GECKO(1824) | --DOMWINDOW == 9 (0x7fcd04435800) [pid = 1824] [serial = 21] [outer = (nil)] [url = about:blank]
[task 2018-11-22T10:30:01.989Z] 10:30:01     INFO - GECKO(1824) | --DOMWINDOW == 8 (0x7fcd0bef1c00) [pid = 1824] [serial = 11] [outer = (nil)] [url = chrome://extensions/content/dummy.xul]
[task 2018-11-22T10:30:01.990Z] 10:30:01     INFO - GECKO(1824) | --DOCSHELL 0x7fcd0bf16800 == 3 [pid = 1824] [id = {0b45fc2d-cf15-4bc6-ac0c-c9d249a24e84}]
[task 2018-11-22T10:30:01.990Z] 10:30:01     INFO - GECKO(1824) | --DOMWINDOW == 7 (0x7fcd0a74a000) [pid = 1824] [serial = 17] [outer = (nil)] [url = chrome://extensions/content/dummy.xul]
[task 2018-11-22T10:30:01.991Z] 10:30:01     INFO - GECKO(1824) | --DOMWINDOW == 6 (0x7fcd0bbc7400) [pid = 1824] [serial = 15] [outer = (nil)] [url = chrome://extensions/content/dummy.xul]
[task 2018-11-22T10:30:01.992Z] 10:30:01     INFO - GECKO(1824) | --DOCSHELL 0x7fcd0be84000 == 2 [pid = 1824] [id = {edb1cfc8-d4fa-4ab2-86e9-046186f46f99}]
[task 2018-11-22T10:30:01.992Z] 10:30:01     INFO - GECKO(1824) | --DOMWINDOW == 5 (0x7fcd0beeec00) [pid = 1824] [serial = 7] [outer = (nil)] [url = chrome://extensions/content/dummy.xul]
[task 2018-11-22T10:30:01.993Z] 10:30:01     INFO - GECKO(1824) | --DOMWINDOW == 4 (0x7fcd0bef0800) [pid = 1824] [serial = 9] [outer = (nil)] [url = chrome://extensions/content/dummy.xul]
[task 2018-11-22T10:30:01.993Z] 10:30:01     INFO - GECKO(1824) | --DOCSHELL 0x7fcd0be87800 == 1 [pid = 1824] [id = {9bb8c050-8783-41d9-b6ae-125838aa9b81}]
[task 2018-11-22T10:30:01.994Z] 10:30:01     INFO - GECKO(1824) | --DOMWINDOW == 3 (0x7fcd0a748000) [pid = 1824] [serial = 16] [outer = (nil)] [url = chrome://extensions/content/dummy.xul]
[task 2018-11-22T10:30:01.995Z] 10:30:01     INFO - GECKO(1824) | --DOCSHELL 0x7fcd11054000 == 0 [pid = 1824] [id = {6798c54f-73da-45cd-80da-905dce1c8630}]
[task 2018-11-22T10:30:01.995Z] 10:30:01     INFO - GECKO(1824) | --DOMWINDOW == 2 (0x7fcd11087400) [pid = 1824] [serial = 1] [outer = (nil)] [url = resource://gre-resources/hiddenWindow.html]
[task 2018-11-22T10:30:01.996Z] 10:30:01     INFO - GECKO(1824) | --DOMWINDOW == 1 (0x7fcd0d319000) [pid = 1824] [serial = 5] [outer = (nil)] [url = resource://gre-resources/hiddenWindow.html]
[task 2018-11-22T10:30:01.996Z] 10:30:01     INFO - GECKO(1824) | --DOMWINDOW == 0 (0x7fcd0d30e000) [pid = 1824] [serial = 4] [outer = (nil)] [url = about:blank]
[task 2018-11-22T10:30:02.159Z] 10:30:02     INFO - GECKO(1824) | nsStringStats
[task 2018-11-22T10:30:02.160Z] 10:30:02     INFO - GECKO(1824) |  => mAllocCount:         333843
[task 2018-11-22T10:30:02.162Z] 10:30:02     INFO - GECKO(1824) |  => mReallocCount:            2
[task 2018-11-22T10:30:02.163Z] 10:30:02     INFO - GECKO(1824) |  => mFreeCount:          333843
[task 2018-11-22T10:30:02.163Z] 10:30:02     INFO - GECKO(1824) |  => mShareCount:         381679
[task 2018-11-22T10:30:02.164Z] 10:30:02     INFO - GECKO(1824) |  => mAdoptCount:           4633
[task 2018-11-22T10:30:02.165Z] 10:30:02     INFO - GECKO(1824) |  => mAdoptFreeCount:       5049
[task 2018-11-22T10:30:02.166Z] 10:30:02     INFO - GECKO(1824) |  => Process ID: 1824, Thread ID: 140519483733824
[task 2018-11-22T10:30:02.707Z] 10:30:02     INFO - TEST-INFO | Main app process: exit 0
[task 2018-11-22T10:30:02.708Z] 10:30:02    ERROR - TEST-UNEXPECTED-FAIL | browser/base/content/test/plugins/browser_CTP_drag_drop.js | leaked 1 window(s) until shutdown [url = http://127.0.0.1:8888/browser/browser/base/content/test/plugins/plugin_test.html]
[task 2018-11-22T10:30:02.708Z] 10:30:02     INFO - TEST-INFO | browser/base/content/test/plugins/browser_CTP_drag_drop.js | windows(s) leaked: [pid = 2103] [serial = 1]
[task 2018-11-22T10:30:02.708Z] 10:30:02     INFO - runtests.py | Application ran for: 0:02:54.496592
[task 2018-11-22T10:30:02.709Z] 10:30:02     INFO - zombiecheck | Reading PID log: /tmp/tmpXDcWjopidlog
[task 2018-11-22T10:30:02.709Z] 10:30:02     INFO - ==> process 1824 launched child process 1850
[task 2018-11-22T10:30:02.710Z] 10:30:02     INFO - ==> process 1824 launched child process 1893
[task 2018-11-22T10:30:02.711Z] 10:30:02     INFO - ==> process 1824 launched child process 1918
[task 2018-11-22T10:30:02.711Z] 10:30:02     INFO - ==> process 1824 launched child process 1959
[task 2018-11-22T10:30:02.711Z] 10:30:02     INFO - ==> process 1824 launched child process 1987
[task 2018-11-22T10:30:02.712Z] 10:30:02     INFO - ==> process 1824 launched child process 2006
[task 2018-11-22T10:30:02.712Z] 10:30:02     INFO - ==> process 1824 launched child process 2039
[task 2018-11-22T10:30:02.713Z] 10:30:02     INFO - ==> process 1824 launched child process 2047
[task 2018-11-22T10:30:02.713Z] 10:30:02     INFO - ==> process 1824 launched child process 2049
[task 2018-11-22T10:30:02.713Z] 10:30:02     INFO - ==> process 1824 launched child process 2073
[task 2018-11-22T10:30:02.714Z] 10:30:02     INFO - ==> process 1824 launched child process 2076
[task 2018-11-22T10:30:02.714Z] 10:30:02     INFO - ==> process 1824 launched child process 2098
[task 2018-11-22T10:30:02.715Z] 10:30:02     INFO - ==> process 1824 launched child process 2103
[task 2018-11-22T10:30:02.715Z] 10:30:02     INFO - ==> process 1824 launched child process 2130
[task 2018-11-22T10:30:02.716Z] 10:30:02     INFO - ==> process 1824 launched child process 2153
[task 2018-11-22T10:30:02.716Z] 10:30:02     INFO - ==> process 1824 launched child process 2172
[task 2018-11-22T10:30:02.717Z] 10:30:02     INFO - ==> process 1824 launched child process 2185
[task 2018-11-22T10:30:02.717Z] 10:30:02     INFO - ==> process 1824 launched child process 2190
[task 2018-11-22T10:30:02.718Z] 10:30:02     INFO - ==> process 1824 launched child process 2193
[task 2018-11-22T10:30:02.718Z] 10:30:02     INFO - ==> process 1824 launched child process 2196
[task 2018-11-22T10:30:02.719Z] 10:30:02     INFO - ==> process 1824 launched child process 2199
[task 2018-11-22T10:30:02.720Z] 10:30:02     INFO - ==> process 1824 launched child process 2203
[task 2018-11-22T10:30:02.720Z] 10:30:02     INFO - ==> process 1824 launched child process 2206
[task 2018-11-22T10:30:02.721Z] 10:30:02     INFO - ==> process 1824 launched child process 2209
[task 2018-11-22T10:30:02.721Z] 10:30:02     INFO - ==> process 1824 launched child process 2215
[task 2018-11-22T10:30:02.722Z] 10:30:02     INFO - ==> process 1824 launched child process 2217
[task 2018-11-22T10:30:02.723Z] 10:30:02     INFO - ==> process 1824 launched child process 2220
[task 2018-11-22T10:30:02.723Z] 10:30:02     INFO - ==> process 1824 launched child process 2222
[task 2018-11-22T10:30:02.724Z] 10:30:02     INFO - ==> process 1824 launched child process 2225
[task 2018-11-22T10:30:02.724Z] 10:30:02     INFO - ==> process 1824 launched child process 2227
[task 2018-11-22T10:30:02.725Z] 10:30:02     INFO - ==> process 1824 launched child process 2233
[task 2018-11-22T10:30:02.725Z] 10:30:02     INFO - ==> process 1824 launched child process 2238
[task 2018-11-22T10:30:02.726Z] 10:30:02     INFO - ==> process 1824 launched child process 2241
[task 2018-11-22T10:30:02.726Z] 10:30:02     INFO - ==> process 1824 launched child process 2243
[task 2018-11-22T10:30:02.727Z] 10:30:02     INFO - ==> process 1824 launched child process 2254
[task 2018-11-22T10:30:02.727Z] 10:30:02     INFO - zombiecheck | Checking for orphan process with PID: 2049
[task 2018-11-22T10:30:02.728Z] 10:30:02     INFO - zombiecheck | Checking for orphan process with PID: 2185
[task 2018-11-22T10:30:02.729Z] 10:30:02     INFO - zombiecheck | Checking for orphan process with PID: 2190
[task 2018-11-22T10:30:02.729Z] 10:30:02     INFO - zombiecheck | Checking for orphan process with PID: 2193
[task 2018-11-22T10:30:02.730Z] 10:30:02     INFO - zombiecheck | Checking for orphan process with PID: 2243
[task 2018-11-22T10:30:02.730Z] 10:30:02     INFO - zombiecheck | Checking for orphan process with PID: 2196
[task 2018-11-22T10:30:02.731Z] 10:30:02     INFO - zombiecheck | Checking for orphan process with PID: 2199
[task 2018-11-22T10:30:02.732Z] 10:30:02     INFO - zombiecheck | Checking for orphan process with PID: 2073
[task 2018-11-22T10:30:02.732Z] 10:30:02     INFO - zombiecheck | Checking for orphan process with PID: 2203
[task 2018-11-22T10:30:02.733Z] 10:30:02     INFO - zombiecheck | Checking for orphan process with PID: 2076
[task 2018-11-22T10:30:02.733Z] 10:30:02     INFO - zombiecheck | Checking for orphan process with PID: 2206
[task 2018-11-22T10:30:02.734Z] 10:30:02     INFO - zombiecheck | Checking for orphan process with PID: 2209
[task 2018-11-22T10:30:02.735Z] 10:30:02     INFO - zombiecheck | Checking for orphan process with PID: 1959
[task 2018-11-22T10:30:02.735Z] 10:30:02     INFO - zombiecheck | Checking for orphan process with PID: 2217
[task 2018-11-22T10:30:02.736Z] 10:30:02     INFO - zombiecheck | Checking for orphan process with PID: 2220
[task 2018-11-22T10:30:02.736Z] 10:30:02     INFO - zombiecheck | Checking for orphan process with PID: 2222
[task 2018-11-22T10:30:02.737Z] 10:30:02     INFO - zombiecheck | Checking for orphan process with PID: 2225
[task 2018-11-22T10:30:02.738Z] 10:30:02     INFO - zombiecheck | Checking for orphan process with PID: 2098
[task 2018-11-22T10:30:02.738Z] 10:30:02     INFO - zombiecheck | Checking for orphan process with PID: 2227
[task 2018-11-22T10:30:02.739Z] 10:30:02     INFO - zombiecheck | Checking for orphan process with PID: 2103
[task 2018-11-22T10:30:02.739Z] 10:30:02     INFO - zombiecheck | Checking for orphan process with PID: 2233
[task 2018-11-22T10:30:02.740Z] 10:30:02     INFO - zombiecheck | Checking for orphan process with PID: 1850
[task 2018-11-22T10:30:02.740Z] 10:30:02     INFO - zombiecheck | Checking for orphan process with PID: 2238
[task 2018-11-22T10:30:02.741Z] 10:30:02     INFO - zombiecheck | Checking for orphan process with PID: 2241
[task 2018-11-22T10:30:02.741Z] 10:30:02     INFO - zombiecheck | Checking for orphan process with PID: 1987
[task 2018-11-22T10:30:02.742Z] 10:30:02     INFO - zombiecheck | Checking for orphan process with PID: 2254
[task 2018-11-22T10:30:02.742Z] 10:30:02     INFO - zombiecheck | Checking for orphan process with PID: 2130
[task 2018-11-22T10:30:02.743Z] 10:30:02     INFO - zombiecheck | Checking for orphan process with PID: 2006
[task 2018-11-22T10:30:02.743Z] 10:30:02     INFO - zombiecheck | Checking for orphan process with PID: 1893
[task 2018-11-22T10:30:02.744Z] 10:30:02     INFO - zombiecheck | Checking for orphan process with PID: 2153
[task 2018-11-22T10:30:02.744Z] 10:30:02     INFO - zombiecheck | Checking for orphan process with PID: 2215
[task 2018-11-22T10:30:02.745Z] 10:30:02     INFO - zombiecheck | Checking for orphan process with PID: 2039
[task 2018-11-22T10:30:02.745Z] 10:30:02     INFO - zombiecheck | Checking for orphan process with PID: 2172
[task 2018-11-22T10:30:02.746Z] 10:30:02     INFO - zombiecheck | Checking for orphan process with PID: 1918
[task 2018-11-22T10:30:02.747Z] 10:30:02     INFO - zombiecheck | Checking for orphan process with PID: 2047
[task 2018-11-22T10:30:02.747Z] 10:30:02     INFO - Stopping web server
[task 2018-11-22T10:30:02.775Z] 10:30:02     INFO - Stopping web socket server
[task 2018-11-22T10:30:02.796Z] 10:30:02     INFO - Stopping ssltunnel
[task 2018-11-22T10:30:02.816Z] 10:30:02     INFO - TEST-INFO | leakcheck | default process: leak threshold set at 0 bytes
[task 2018-11-22T10:30:02.817Z] 10:30:02     INFO - TEST-INFO | leakcheck | plugin process: leak threshold set at 0 bytes
[task 2018-11-22T10:30:02.819Z] 10:30:02     INFO - TEST-INFO | leakcheck | tab process: leak threshold set at 0 bytes
[task 2018-11-22T10:30:02.820Z] 10:30:02     INFO - TEST-INFO | leakcheck | geckomediaplugin process: leak threshold set at 20000 bytes
[task 2018-11-22T10:30:02.820Z] 10:30:02     INFO - TEST-INFO | leakcheck | gpu process: leak threshold set at 0 bytes
[task 2018-11-22T10:30:02.821Z] 10:30:02     INFO - ==> process 2039 will purposefully crash
This started to fail with this merge: https://treeherder.mozilla.org/#/jobs?repo=mozilla-central&searchStr=linux%2Cx64%2Cccov%2Cdebug%2Cbrowser-chrome&revision=785032241b2fe327aa833267416b3eb8d846cb4f&selectedJob=213354842

Failed mostly on mozilla-central, one occurrence on autoland: https://treeherder.mozilla.org/logviewer.html#?job_id=213574542&repo=autoland&lineNumber=4004

Could be from https://bugzilla.mozilla.org/show_bug.cgi?id=1497940? This was backed out for failing browser-chrome in toolkit/mozapps/extensions/test/browser/browser_CTP_plugins.js and then it was relanded. 

:timdream could you please take a look?
Flags: needinfo?(timdream)
See Also: → 1497940
The backout should not be related to this, but based on the timeline this should be related to the bug. I'll investigate.
Assignee: nobody → timdream
Status: NEW → ASSIGNED
Flags: needinfo?(timdream)
Looking at the log and how the tests are run I don't find anything obvious that could cause this.

Let me see if I can reproduce this with

https://treeherder.mozilla.org/#/jobs?repo=try&revision=e502b5c6b34620da7571006831b7fa8865d3110d
Pushed by tchien@mozilla.com:
https://hg.mozilla.org/integration/autoland/rev/1cce2b80c645
Wait for window to unload before ending the test r=Felipe
https://hg.mozilla.org/mozilla-central/rev/1cce2b80c645
Status: ASSIGNED → RESOLVED
Closed: Last year
Resolution: --- → FIXED
Target Milestone: --- → mozilla65
You need to log in before you can comment on or make changes to this bug.