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

RESOLVED FIXED in Firefox 35

Status

DevTools
Memory
RESOLVED FIXED
4 years ago
a month ago

People

(Reporter: RyanVM, Assigned: fitzgen)

Tracking

({intermittent-failure})

unspecified
Firefox 35
x86
Windows 8
intermittent-failure
Bug Flags:
in-testsuite +

Firefox Tracking Flags

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

Details

Attachments

(1 attachment)

(Reporter)

Description

4 years ago
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
Comment hidden (Treeherder Robot)
Comment hidden (Treeherder Robot)
Comment hidden (Treeherder Robot)
Comment hidden (Treeherder Robot)
Comment hidden (Treeherder Robot)
Created attachment 8493364 [details] [diff] [review]
memory-actor-intermittent.patch

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)
Comment hidden (Treeherder Robot)
Comment hidden (Treeherder Robot)
Attachment #8493364 - Flags: review?(jryans) → review+
Keywords: checkin-needed
Comment hidden (Treeherder Robot)
Comment hidden (Treeherder Robot)
Comment hidden (Treeherder Robot)
Comment hidden (Treeherder Robot)
Comment hidden (Treeherder Robot)
https://hg.mozilla.org/integration/fx-team/rev/70ab0d3eaf0a
Keywords: checkin-needed
Whiteboard: [fixed-in-fx-team]
Comment hidden (Treeherder Robot)
(Reporter)

Comment 17

4 years ago
https://hg.mozilla.org/mozilla-central/rev/70ab0d3eaf0a
Status: ASSIGNED → RESOLVED
Last Resolved: 4 years ago
Flags: in-testsuite+
Resolution: --- → FIXED
Whiteboard: [fixed-in-fx-team]
Target Milestone: --- → Firefox 35
(Reporter)

Updated

4 years ago
status-firefox35: --- → fixed
(Reporter)

Updated

4 years ago
status-firefox33: --- → unaffected
status-firefox34: --- → unaffected
status-firefox-esr31: --- → unaffected
Comment hidden (Treeherder Robot)

Comment 19

4 years ago
(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.
Comment hidden (Treeherder Robot)
Comment hidden (Treeherder Robot)

Updated

a month ago
Product: Firefox → DevTools
You need to log in before you can comment on or make changes to this bug.