Closed Bug 1070107 Opened 10 years ago Closed 10 years ago

Intermittent test_memory_allocations_02.html,test_memory_allocations_03.html,test_memory_attach_01.html,test_memory_attach_02.html | Test timed out

Categories

(DevTools :: Memory, defect)

Product:
DevTools
Component:
Memory
Platform:
x86
Windows 8
Type:
defect
Priority:
Not set
Severity:
normal

Tracking

(firefox33 unaffected, firefox34 unaffected, firefox35 fixed, firefox-esr31 unaffected)

Status:
RESOLVED FIXED
Milestone:
Firefox 35
Tracking Flags:
Tracking Status
firefox33 --- unaffected
firefox34 --- unaffected
firefox35 --- fixed
firefox-esr31 --- unaffected

People

(Reporter: RyanVM, Assigned: fitzgen)

References

Details

(Keywords: intermittent-failure)

Attachments

(1 file)

memory-actor-intermittent.patch
2.32 KB, patch
jryans
: review+
Details | Diff | Splinter Review 
https://tbpl.mozilla.org/php/getParsedLog.php?id=48476803&tree=Fx-Team

WINNT 6.2 fx-team opt test mochitest-other on 2014-09-19 09:57:56 PDT for push 1a986002b264
slave: t-w864-ix-054

10:11:00     INFO -  2100 INFO TEST-START | chrome://mochitests/content/chrome/toolkit/devtools/server/tests/mochitest/test_memory_allocations_02.html
10:11:00     INFO -  *************************
10:11:00     INFO -  A coding exception was thrown and uncaught in a Task.
10:11:00     INFO -  Full message: TypeError: response.frames[index] is null
10:11:00     INFO -  Full stack: window.onload/<@chrome://mochitests/content/chrome/toolkit/devtools/server/tests/mochitest/test_memory_allocations_02.html:54:5
10:11:00     INFO -  TaskImpl_run@resource://gre/modules/Task.jsm:314:40
10:11:00     INFO -  resolve@resource://gre/modules/commonjs/toolkit/loader.js -> resource://gre/modules/devtools/deprecated-sync-thenables.js:40:40
10:11:00     INFO -  then@resource://gre/modules/commonjs/toolkit/loader.js -> resource://gre/modules/devtools/deprecated-sync-thenables.js:20:43
10:11:00     INFO -  resolve@resource://gre/modules/commonjs/toolkit/loader.js -> resource://gre/modules/devtools/deprecated-sync-thenables.js:72:11
10:11:00     INFO -  resolve@resource://gre/modules/commonjs/toolkit/loader.js -> resource://gre/modules/devtools/deprecated-sync-thenables.js:40:11
10:11:00     INFO -  then@resource://gre/modules/commonjs/toolkit/loader.js -> resource://gre/modules/devtools/deprecated-sync-thenables.js:20:43
10:11:00     INFO -  resolve@resource://gre/modules/commonjs/toolkit/loader.js -> resource://gre/modules/devtools/deprecated-sync-thenables.js:72:11
10:11:00     INFO -  resolve@resource://gre/modules/commonjs/toolkit/loader.js -> resource://gre/modules/devtools/deprecated-sync-thenables.js:40:11
10:11:00     INFO -  then@resource://gre/modules/commonjs/toolkit/loader.js -> resource://gre/modules/devtools/deprecated-sync-thenables.js:20:43
10:11:00     INFO -  resolve@resource://gre/modules/commonjs/toolkit/loader.js -> resource://gre/modules/devtools/deprecated-sync-thenables.js:72:11
10:11:00     INFO -  Front<.onPacket@resource://gre/modules/commonjs/toolkit/loader.js -> resource://gre/modules/devtools/server/protocol.js:1118:7
10:11:00     INFO -  DebuggerClient.prototype.onPacket/<@resource://gre/modules/devtools/dbg-client.jsm:882:9
10:11:00     INFO -  resolve@resource://gre/modules/devtools/deprecated-sync-thenables.js:40:40
10:11:00     INFO -  then@resource://gre/modules/devtools/deprecated-sync-thenables.js:20:43
10:11:00     INFO -  then@resource://gre/modules/devtools/deprecated-sync-thenables.js:58:9
10:11:00     INFO -  DebuggerClient.prototype.onPacket@resource://gre/modules/devtools/dbg-client.jsm:869:1
10:11:00     INFO -  LocalDebuggerTransport.prototype.send/<@resource://gre/modules/devtools/dbg-client.jsm -> resource://gre/modules/devtools/transport/transport.js:545:11
10:11:00     INFO -  makeInfallible/<@resource://gre/modules/commonjs/toolkit/loader.js -> resource://gre/modules/devtools/DevToolsUtils.js:83:14
10:11:00     INFO -  makeInfallible/<@resource://gre/modules/commonjs/toolkit/loader.js -> resource://gre/modules/devtools/DevToolsUtils.js:83:14
10:11:00     INFO -  *************************
10:11:11     INFO -  COM initialized.
10:11:11     INFO -  COM initialized.
10:11:11     INFO -  JavaScript error: resource://app/modules/WindowsJumpLists.jsm, line 525: ReferenceError: _idle is not defined
10:13:11     INFO -  JavaScript error: resource://app/modules/WindowsJumpLists.jsm, line 525: ReferenceError: _idle is not defined
10:15:11     INFO -  JavaScript error: resource://app/modules/WindowsJumpLists.jsm, line 525: ReferenceError: _idle is not defined
10:16:02     INFO -  TEST-INFO | screenshot: exit status 0
10:16:02     INFO -  dumping last 3 message(s)
10:16:02     INFO -  if you need more context, please use SimpleTest.requestCompleteLog() in your test
10:16:02     INFO -  2101 INFO TEST-PASS | chrome://mochitests/content/chrome/toolkit/devtools/server/tests/mochitest/test_memory_allocations_02.html | Can start recording allocations
10:16:02     INFO -  2102 INFO TEST-PASS | chrome://mochitests/content/chrome/toolkit/devtools/server/tests/mochitest/test_memory_allocations_02.html | Can stop recording allocations
10:16:02     INFO -  2103 INFO TEST-PASS | chrome://mochitests/content/chrome/toolkit/devtools/server/tests/mochitest/test_memory_allocations_02.html | Should find the 10 allocations.
10:16:02     INFO -  2104 INFO TEST-UNEXPECTED-FAIL | chrome://mochitests/content/chrome/toolkit/devtools/server/tests/mochitest/test_memory_allocations_02.html | Test timed out. - expected PASS
10:16:02     INFO -  2105 INFO TEST-OK | chrome://mochitests/content/chrome/toolkit/devtools/server/tests/mochitest/test_memory_allocations_02.html | took 302768ms
10:16:02     INFO -  2106 INFO TEST-START | chrome://mochitests/content/chrome/toolkit/devtools/server/tests/mochitest/test_memory_allocations_03.html
10:17:11     INFO -  JavaScript error: resource://app/modules/WindowsJumpLists.jsm, line 525: ReferenceError: _idle is not defined
10:19:11     INFO -  JavaScript error: resource://app/modules/WindowsJumpLists.jsm, line 525: ReferenceError: _idle is not defined
10:21:10     INFO -  JavaScript error: resource://app/modules/WindowsJumpLists.jsm, line 525: ReferenceError: _idle is not defined
10:21:31     INFO -  Not taking screenshot here: see the one that was previously logged
10:21:31     INFO -  2107 INFO TEST-UNEXPECTED-FAIL | chrome://mochitests/content/chrome/toolkit/devtools/server/tests/mochitest/test_memory_allocations_03.html | Test timed out. - expected PASS
10:21:32     INFO -  2108 INFO TEST-OK | chrome://mochitests/content/chrome/toolkit/devtools/server/tests/mochitest/test_memory_allocations_03.html | took 329948ms
10:21:32     INFO -  2109 INFO TEST-START | chrome://mochitests/content/chrome/toolkit/devtools/server/tests/mochitest/test_memory_attach_01.html
10:23:11     INFO -  JavaScript error: resource://app/modules/WindowsJumpLists.jsm, line 525: ReferenceError: _idle is not defined
10:25:10     INFO -  JavaScript error: resource://app/modules/WindowsJumpLists.jsm, line 525: ReferenceError: _idle is not defined
10:27:01     INFO -  Not taking screenshot here: see the one that was previously logged
10:27:01     INFO -  2110 INFO TEST-UNEXPECTED-FAIL | chrome://mochitests/content/chrome/toolkit/devtools/server/tests/mochitest/test_memory_attach_01.html | Test timed out. - expected PASS
10:27:02     INFO -  2111 INFO TEST-OK | chrome://mochitests/content/chrome/toolkit/devtools/server/tests/mochitest/test_memory_attach_01.html | took 329948ms
10:27:02     INFO -  2112 INFO TEST-START | chrome://mochitests/content/chrome/toolkit/devtools/server/tests/mochitest/test_memory_attach_02.html
10:27:11     INFO -  JavaScript error: resource://app/modules/WindowsJumpLists.jsm, line 525: ReferenceError: _idle is not defined
10:29:10     INFO -  JavaScript error: resource://app/modules/WindowsJumpLists.jsm, line 525: ReferenceError: _idle is not defined
10:31:10     INFO -  JavaScript error: resource://app/modules/WindowsJumpLists.jsm, line 525: ReferenceError: _idle is not defined
10:32:31     INFO -  Not taking screenshot here: see the one that was previously logged
10:32:31     INFO -  2113 INFO TEST-UNEXPECTED-FAIL | chrome://mochitests/content/chrome/toolkit/devtools/server/tests/mochitest/test_memory_attach_02.html | Test timed out. - expected PASS
10:32:31     INFO -  2114 INFO TEST-UNEXPECTED-FAIL | (SimpleTest/TestRunner.js) | 4 test timeouts, giving up. - expected PASS
10:32:31     INFO -  2115 INFO TEST-UNEXPECTED-FAIL | (SimpleTest/TestRunner.js) | Skipping 161 remaining tests. - expected PASS
10:32:32     INFO -  dumping last 4 message(s)
10:32:32     INFO -  if you need more context, please use SimpleTest.requestCompleteLog() in your test
10:32:32     INFO -  2116 INFO MEMORY STAT vsize after test: 954028032
10:32:32     INFO -  2117 INFO MEMORY STAT vsizeMaxContiguous after test: 1893662720
10:32:32     INFO -  2118 INFO MEMORY STAT residentFast after test: 33062912
10:32:32     INFO -  2119 INFO MEMORY STAT heapAllocated after test: 88100434
10:32:32     INFO -  2120 INFO TEST-UNEXPECTED-FAIL | (SimpleTest/TestRunner.js) | chrome://mochitests/content/chrome/toolkit/devtools/server/tests/mochitest/test_memory_attach_02.html - finished in a non-clean fashion, probably because it didn't call SimpleTest.finish()
10:32:32     INFO -  2121 INFO TEST-OK | (SimpleTest/TestRunner.js) |
10:32:32     INFO -  2122 INFO TEST-UNEXPECTED-FAIL | (SimpleTest/TestRunner.js) | undefined assertion name - Result logged after SimpleTest.finish()
10:32:32     INFO -  Debugger Hook threw an exception: TypeError: metadata is undefined
10:32:32     INFO -  Stack: TabActor.prototype._shouldAddNewGlobalAsDebuggee@resource://gre/modules/commonjs/toolkit/loader.js -> resource://gre/modules/devtools/server/actors/webbrowser.js:792:1
10:32:32     INFO -  makeDebugger/dbg.onNewGlobalObject@resource://gre/modules/commonjs/toolkit/loader.js -> resource://gre/modules/devtools/server/actors/utils/make-debugger.js:72:9
10:32:32     INFO -  Line: 792, column: 0
10:32:32     INFO -  *** WIFI GEO: shutdown called
10:32:33     INFO -  ###!!! [Parent][OnMaybeDequeueOne] Error: Channel closing: too late to send/recv, messages will be lost
10:32:33     INFO -  1411147953299	Browser.Experiments.Experiments	TRACE	Experiments #0::uninit: started
10:32:33     INFO -  1411147953300	Browser.Experiments.Experiments	TRACE	Experiments #0::uninit: finished with _loadTask
10:32:33     INFO -  1411147953300	Browser.Experiments.Experiments	TRACE	Experiments #0::uninit: no previous shutdown
10:32:33     INFO -  1411147953300	Browser.Experiments.Experiments	TRACE	Experiments #0::Unregistering instance with Addon Manager.
10:32:33     INFO -  1411147953300	Browser.Experiments.Experiments	TRACE	Experiments #0::Removing install listener from add-on manager.
10:32:33     INFO -  1411147953300	Browser.Experiments.Experiments	TRACE	Experiments #0::Removing addon listener from add-on manager.
10:32:33     INFO -  1411147953300	Browser.Experiments.Experiments	TRACE	Experiments #0::Finished unregistering with addon manager.
10:32:33     INFO -  1411147953300	Browser.Experiments.Experiments	TRACE	Experiments #0::Unregistering previous experiment add-on provider.
10:32:33     INFO -  1411147953301	Browser.Experiments.Experiments	TRACE	PreviousExperimentProvider #0::shutdown()
10:32:33     INFO -  1411147953301	Browser.Experiments.Experiments	INFO	Experiments #0::Completed uninitialization.
10:32:34     INFO -  ###!!! [Parent][OnMaybeDequeueOne] Error: Channel closing: too late to send/recv, messages will be lost
10:32:34     INFO -  ###!!! [Parent][OnMaybeDequeueOne] Error: Channel closing: too late to send/recv, messages will be lost
10:32:34     INFO -  ###!!! [Parent][OnMaybeDequeueOne] Error: Channel closing: too late to send/recv, messages will be lost
10:32:34     INFO -  ###!!! [Parent][OnMaybeDequeueOne] Error: Channel closing: too late to send/recv, messages will be lost
10:32:34     INFO -  ###!!! [Parent][OnMaybeDequeueOne] Error: Channel closing: too late to send/recv, messages will be lost
10:32:45     INFO -  TEST-INFO | Main app process: exit status 0
Ok so two errors in the log:

1. response.frames[index] is undefined. This could be because there happens to be some system code (null frame) that allocates 10 objects and we find it before our allocator function. To deal with this, I modified the test to assert that we can find a frame with 10 allocations whose function name is "allocator" rather than asserting that the first frame with 10 allocations must be our allocator frame.

2. An issue with undefined metadata in TabActor's _shouldAddNewGlobalAsDebuggee. I suspect this was an outstanding bug that just happened to be triggered in this failure. Either way, I just added a check that metadata exists before we try and access its properties.

https://tbpl.mozilla.org/?tree=Try&rev=2f197058f0d9
Assignee: nobody → nfitzgerald
Status: NEW → ASSIGNED
Attachment #8493364 - Flags: review?(jryans)
https://hg.mozilla.org/mozilla-central/rev/70ab0d3eaf0a
Status: ASSIGNED → RESOLVED
Closed: 10 years ago
Flags: in-testsuite+
Resolution: --- → FIXED
Whiteboard: [fixed-in-fx-team]
Target Milestone: --- → Firefox 35
status-firefox35: --- → fixed
(In reply to TBPL Robot from comment #18)
> jimb
> https://tbpl.mozilla.org/php/getParsedLog.php?id=49236120&tree=Try

That try push was based on a tree from Sep 23, which didn't have the changeset landed in comment 17.
Product: Firefox → DevTools
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Creator:
Created:
Updated:
Size: