Closed Bug 1255945 Opened 10 years ago Closed 7 years ago

Intermittent browser_cached_messages.js | Test timed out

Categories

(DevTools :: Console, defect, P3)

Product:
DevTools
Component:
Console
Platform:
Unspecified
Windows 8
Type:
defect

Tracking

(Not tracked)

Status:
RESOLVED INVALID

People

(Reporter: RyanVM, Unassigned)

Details

(Keywords: intermittent-failure, Whiteboard: [btpp-backlog])

Attachments

(1 file)

screenshot
311.70 KB, image/png
Details
Attached image screenshot —
https://treeherder.mozilla.org/logviewer.html#?job_id=23593119&repo=mozilla-inbound 14:47:35 INFO - 19 INFO TEST-START | devtools/client/webconsole/test/browser_cached_messages.js 14:47:35 INFO - JavaScript error: http://example.com/browser/devtools/client/webconsole/test/test-webconsole-error-observer.html, line 12: Error: Permission denied for <http://example.com> to create wrapper for object of class UnnamedClass 14:47:35 INFO - JavaScript error: http://example.com/browser/devtools/client/webconsole/test/test-webconsole-error-observer.html, line 15: TypeError: foo.bazBug611032 is not a function 14:48:20 INFO - TEST-INFO | started process screenshot 14:48:20 INFO - TEST-INFO | screenshot: exit 0 14:48:20 INFO - 20 INFO checking window state 14:48:20 INFO - 21 INFO Console message: [JavaScript Error: "TelemetryStopwatch: requesting elapsed time for nonexisting stopwatch. Histogram: "FX_PAGE_LOAD_MS", key: "null"" {file: "resource://gre/modules/TelemetryStopwatch.jsm" line: 297}] 14:48:20 INFO - this.TelemetryStopwatchImpl.timeElapsed@resource://gre/modules/TelemetryStopwatch.jsm:297:7 14:48:20 INFO - this.TelemetryStopwatchImpl.finish@resource://gre/modules/TelemetryStopwatch.jsm:315:17 14:48:20 INFO - this.TelemetryStopwatch.finish@resource://gre/modules/TelemetryStopwatch.jsm:192:12 14:48:20 INFO - TabsProgressListener.onStateChange@chrome://browser/content/browser.js:4645:11 14:48:20 INFO - callListeners@chrome://browser/content/tabbrowser.xml:501:24 14:48:20 INFO - _callProgressListeners@chrome://browser/content/tabbrowser.xml:522:13 14:48:20 INFO - mTabProgressListener/<._callProgressListeners@chrome://browser/content/tabbrowser.xml:571:22 14:48:20 INFO - mTabProgressListener/<.onStateChange@chrome://browser/content/tabbrowser.xml:731:15 14:48:20 INFO - stop@chrome://global/content/bindings/browser.xml:100:13 14:48:20 INFO - stop@chrome://browser/content/tabbrowser.xml:3818:20 14:48:20 INFO - Tester_waitForWindowsState@chrome://mochikit/content/browser-test.js:306:7 14:48:20 INFO - Tester.prototype.nextTest<@chrome://mochikit/content/browser-test.js:594:5 14:48:20 INFO - TaskImpl_run@resource://gre/modules/Task.jsm:319:40 14:48:20 INFO - promise callback*TaskImpl_handleResultValue@resource://gre/modules/Task.jsm:395:7 14:48:20 INFO - TaskImpl_run@resource://gre/modules/Task.jsm:327:13 14:48:20 INFO - TaskImpl_handleResultValue@resource://gre/modules/Task.jsm:400:7 14:48:20 INFO - TaskImpl_run@resource://gre/modules/Task.jsm:327:13 14:48:20 INFO - Handler.prototype.process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:937:23 14:48:20 INFO - this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7 14:48:20 INFO - Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11 14:48:20 INFO - this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7 14:48:20 INFO - this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7 14:48:20 INFO - TaskImpl_run@resource://gre/modules/Task.jsm:324:13 14:48:20 INFO - Handler.prototype.process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:937:23 14:48:20 INFO - this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7 14:48:20 INFO - Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11 14:48:20 INFO - this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7 14:48:20 INFO - this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7 14:48:20 INFO - EventHandlerNonNull*removeItem/module.exports</<@resource://gre/modules/commonjs/toolkit/loader.js -> resource://devtools/shared/async-storage.js:111:9 14:48:20 INFO - withStore@resource://gre/modules/commonjs/toolkit/loader.js -> resource://devtools/shared/async-storage.js:58:7 14:48:20 INFO - removeItem/module.exports<@resource://gre/modules/commonjs/toolkit/loader.js -> resource://devtools/shared/async-storage.js:110:7 14:48:20 INFO - Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:388:5 14:48:20 INFO - removeItem@resource://gre/modules/commonjs/toolkit/loader.js -> resource://devtools/shared/async-storage.js:109:12 14:48:20 INFO - @chrome://mochitests/content/browser/devtools/client/webconsole/test/head.js:372:9 14:48:20 INFO - TaskImpl_run@resource://gre/modules/Task.jsm:319:40 14:48:20 INFO - TaskImpl@resource://gre/modules/Task.jsm:280:3 14:48:20 INFO - createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:254:14 14:48:20 INFO - Task_spawn@resource://gre/modules/Task.jsm:168:12 14:48:20 INFO - TaskImpl_handleResultValue@resource://gre/modules/Task.jsm:388:16 14:48:20 INFO - TaskImpl_run@resource://gre/modules/Task.jsm:327:13 14:48:20 INFO - Handler.prototype.process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:937:23 14:48:20 INFO - 22 INFO Console message: [JavaScript Error: "TelemetryStopwatch: key "FX_TAB_SWITCH_TOTAL_MS" was already initialized" {file: "resource://gre/modules/TelemetryStopwatch.jsm" line: 282}] 14:48:20 INFO - this.TelemetryStopwatchImpl.start@resource://gre/modules/TelemetryStopwatch.jsm:282:7 14:48:20 INFO - this.TelemetryStopwatch.start@resource://gre/modules/TelemetryStopwatch.jsm:136:12 14:48:20 INFO - updateCurrentBrowser@chrome://browser/content/tabbrowser.xml:1036:17 14:48:20 INFO - onselect@chrome://browser/content/browser.xul:1:44 14:48:20 INFO - set_selectedIndex@chrome://global/content/bindings/tabbox.xml:656:13 14:48:20 INFO - set_selectedPanel@chrome://global/content/bindings/tabbox.xml:675:13 14:48:20 INFO - set_selectedIndex@chrome://global/content/bindings/tabbox.xml:403:15 14:48:20 INFO - set_selectedItem@chrome://global/content/bindings/tabbox.xml:435:34 14:48:20 INFO - set_selectedTab@chrome://global/content/bindings/tabbox.xml:114:15 14:48:20 INFO - set_selectedTab@chrome://browser/content/tabbrowser.xml:2828:11 14:48:20 INFO - loadTab@chrome://mochitests/content/browser/devtools/client/webconsole/test/head.js:49:36 14:48:20 INFO - test@chrome://mochitests/content/browser/devtools/client/webconsole/test/browser_cached_messages.js:23:3 14:48:20 INFO - Tester_execTest@chrome://mochikit/content/browser-test.js:808:9 14:48:20 INFO - Tester.prototype.nextTest</<@chrome://mochikit/content/browser-test.js:709:7 14:48:20 INFO - SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:740:59 14:48:20 INFO - 23 INFO Console message: [JavaScript Error: "TelemetryStopwatch: requesting elapsed time for nonexisting stopwatch. Histogram: "FX_TAB_SWITCH_TOTAL_MS", key: "null"" {file: "resource://gre/modules/TelemetryStopwatch.jsm" line: 297}] 14:48:20 INFO - this.TelemetryStopwatchImpl.timeElapsed@resource://gre/modules/TelemetryStopwatch.jsm:297:7 14:48:20 INFO - this.TelemetryStopwatchImpl.finish@resource://gre/modules/TelemetryStopwatch.jsm:315:17 14:48:20 INFO - this.TelemetryStopwatch.finish@resource://gre/modules/TelemetryStopwatch.jsm:192:12 14:48:20 INFO - onMozAfterPaint@chrome://browser/content/tabbrowser.xml:1038:19 14:48:20 INFO - EventListener.handleEvent*updateCurrentBrowser@chrome://browser/content/tabbrowser.xml:1037:17 14:48:20 INFO - onselect@chrome://browser/content/browser.xul:1:44 14:48:20 INFO - set_selectedIndex@chrome://global/content/bindings/tabbox.xml:656:13 14:48:20 INFO - set_selectedPanel@chrome://global/content/bindings/tabbox.xml:675:13 14:48:20 INFO - set_selectedIndex@chrome://global/content/bindings/tabbox.xml:403:15 14:48:20 INFO - set_selectedItem@chrome://global/content/bindings/tabbox.xml:435:34 14:48:20 INFO - set_selectedTab@chrome://global/content/bindings/tabbox.xml:114:15 14:48:20 INFO - set_selectedTab@chrome://browser/content/tabbrowser.xml:2828:11 14:48:20 INFO - _blurTab@chrome://browser/content/tabbrowser.xml:2479:13 14:48:20 INFO - _endRemoveTab@chrome://browser/content/tabbrowser.xml:2353:13 14:48:20 INFO - removeTab@chrome://browser/content/tabbrowser.xml:2195:15 14:48:20 INFO - Tester_waitForWindowsState@chrome://mochikit/content/browser-test.js:305:7 14:48:20 INFO - Tester.prototype.nextTest<@chrome://mochikit/content/browser-test.js:594:5 14:48:20 INFO - TaskImpl_run@resource://gre/modules/Task.jsm:319:40 14:48:20 INFO - promise callback*TaskImpl_handleResultValue@resource://gre/modules/Task.jsm:395:7 14:48:20 INFO - TaskImpl_run@resource://gre/modules/Task.jsm:327:13 14:48:20 INFO - TaskImpl_handleResultValue@resource://gre/modules/Task.jsm:400:7 14:48:20 INFO - TaskImpl_run@resource://gre/modules/Task.jsm:327:13 14:48:20 INFO - Handler.prototype.process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:937:23 14:48:20 INFO - this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7 14:48:20 INFO - Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11 14:48:20 INFO - this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7 14:48:20 INFO - this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7 14:48:20 INFO - TaskImpl_run@resource://gre/modules/Task.jsm:324:13 14:48:20 INFO - Handler.prototype.process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:937:23 14:48:20 INFO - this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7 14:48:20 INFO - Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11 14:48:20 INFO - this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7 14:48:20 INFO - this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7 14:48:20 INFO - EventHandlerNonNull*removeItem/module.exports</<@resource://gre/modules/commonjs/toolkit/loader.js -> resource://devtools/shared/async-storage.js:111:9 14:48:20 INFO - withStore@resource://gre/modules/commonjs/toolkit/loader.js -> resource://devtools/shared/async-storage.js:58:7 14:48:20 INFO - removeItem/module.exports<@resource://gre/modules/commonjs/toolkit/loader.js -> resource://devtools/shared/async-storage.js:110:7 14:48:20 INFO - Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:388:5 14:48:20 INFO - removeItem@resource://gre/modules/commonjs/toolkit/loader.js -> resource://devtools/shared/async-storage.js:109:12 14:48:20 INFO - @chrome://mochitests/content/browser/devtools/client/webconsole/test/head.js:372:9 14:48:20 INFO - TaskImpl_run@resource://gre/modules/Task.jsm:319:40 14:48:20 INFO - TaskImpl@resource://gre/modules/Task.jsm:280:3 14:48:20 INFO - createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:254:14 14:48:20 INFO - Task_spawn@resource://gre/modules/Task.jsm:168:12 14:48:20 INFO - TaskImpl_handleResultValue@resource://gre/modules/Task.jsm:388:16 14:48:20 INFO - TaskImpl_run@resource://gre/modules/Task.jsm:327:13 14:48:20 INFO - Handler.prototype.process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:937:23 14:48:20 INFO - 24 INFO Console message: [JavaScript Error: "TelemetryStopwatch: requesting elapsed time for nonexisting stopwatch. Histogram: "FX_TAB_SWITCH_TOTAL_MS", key: "null"" {file: "resource://gre/modules/TelemetryStopwatch.jsm" line: 297}] 14:48:20 INFO - this.TelemetryStopwatchImpl.timeElapsed@resource://gre/modules/TelemetryStopwatch.jsm:297:7 14:48:20 INFO - this.TelemetryStopwatchImpl.finish@resource://gre/modules/TelemetryStopwatch.jsm:315:17 14:48:20 INFO - this.TelemetryStopwatch.finish@resource://gre/modules/TelemetryStopwatch.jsm:192:12 14:48:20 INFO - onMozAfterPaint@chrome://browser/content/tabbrowser.xml:1038:19 14:48:20 INFO - EventListener.handleEvent*updateCurrentBrowser@chrome://browser/content/tabbrowser.xml:1037:17 14:48:20 INFO - onselect@chrome://browser/content/browser.xul:1:44 14:48:20 INFO - set_selectedIndex@chrome://global/content/bindings/tabbox.xml:656:13 14:48:20 INFO - set_selectedPanel@chrome://global/content/bindings/tabbox.xml:675:13 14:48:20 INFO - set_selectedIndex@chrome://global/content/bindings/tabbox.xml:403:15 14:48:20 INFO - set_selectedItem@chrome://global/content/bindings/tabbox.xml:435:34 14:48:20 INFO - set_selectedTab@chrome://global/content/bindings/tabbox.xml:114:15 14:48:20 INFO - set_selectedTab@chrome://browser/content/tabbrowser.xml:2828:11 14:48:20 INFO - loadTab@chrome://mochitests/content/browser/devtools/client/webconsole/test/head.js:49:36 14:48:20 INFO - test@chrome://mochitests/content/browser/devtools/client/webconsole/test/browser_cached_messages.js:23:3 14:48:20 INFO - Tester_execTest@chrome://mochikit/content/browser-test.js:808:9 14:48:20 INFO - Tester.prototype.nextTest</<@chrome://mochikit/content/browser-test.js:709:7 14:48:20 INFO - SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:740:59 14:48:20 INFO - 25 INFO TEST-PASS | devtools/client/webconsole/test/browser_cached_messages.js | expected uncaught exception - 14:48:20 INFO - 26 INFO Console message: [JavaScript Error: "Error: Permission denied for <http://example.com> to create wrapper for object of class UnnamedClass" {file: "http://example.com/browser/devtools/client/webconsole/test/test-webconsole-error-observer.html" line: 12}] 14:48:20 INFO - 27 INFO Console message: [JavaScript Error: "TypeError: foo.bazBug611032 is not a function" {file: "http://example.com/browser/devtools/client/webconsole/test/test-webconsole-error-observer.html" line: 15}] 14:48:20 INFO - @http://example.com/browser/devtools/client/webconsole/test/test-webconsole-error-observer.html:15:7 14:48:20 INFO - 28 INFO Console message: [JavaScript Warning: "Expected color but found 'cssColorBug611032'. Error in parsing value for 'color'. Declaration dropped." {file: "http://example.com/browser/devtools/client/webconsole/test/test-webconsole-error-observer.html" line: 18 column: 20 source: " .foo { color: cssColorBug611032; }"}] 14:48:20 INFO - 29 INFO Waiting for messages... 14:48:20 INFO - 30 INFO TEST-PASS | devtools/client/webconsole/test/browser_cached_messages.js | matched rule: log Bazzle - 14:48:20 INFO - 31 INFO TEST-PASS | devtools/client/webconsole/test/browser_cached_messages.js | matched rule: bazBug611032 - 14:48:20 INFO - 32 INFO TEST-PASS | devtools/client/webconsole/test/browser_cached_messages.js | matched rule: cssColorBug611032 - 14:48:20 INFO - 33 INFO TEST-UNEXPECTED-FAIL | devtools/client/webconsole/test/browser_cached_messages.js | Test timed out - 14:48:20 INFO - console.log: dumpConsoles start 14:48:20 INFO - console.log: dumpConsoles end 14:48:20 INFO - Not taking screenshot here: see the one that was previously logged 14:48:20 INFO - 34 INFO TEST-UNEXPECTED-FAIL | devtools/client/webconsole/test/browser_cached_messages.js | failed to match rule: error Bazzle - 14:48:20 INFO - Stack trace: 14:48:20 INFO - chrome://mochitests/content/browser/devtools/client/webconsole/test/head.js:testCleanup:1376 14:48:20 INFO - chrome://mochikit/content/browser-test.js:Tester.prototype.nextTest<:464 14:48:20 INFO - timeoutFn@chrome://mochikit/content/browser-test.js:871:9 14:48:20 INFO - setTimeout handler*Tester_execTest@chrome://mochikit/content/browser-test.js:833:9 14:48:20 INFO - Tester.prototype.nextTest</<@chrome://mochikit/content/browser-test.js:709:7 14:48:20 INFO - SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:740:59 14:48:20 INFO - MEMORY STAT | vsize 844MB | vsizeMaxContiguous 4346593MB | residentFast 293MB | heapAllocated 83MB 14:48:20 INFO - 35 INFO TEST-OK | devtools/client/webconsole/test/browser_cached_messages.js | took 45129ms
Priority: -- → P3
Whiteboard: [btpp-backlog]
Product: Firefox → DevTools
All the console tests were migrated and rewritten when we enabled the new console. If they're intermittent, new bugs were probably created.
Status: NEW → RESOLVED
Closed: 7 years ago
Resolution: --- → INVALID
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Creator:
Created:
Updated:
Size: