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

(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.