Closed Bug 1428163 Opened 7 years ago Closed 7 years ago

Intermittent devtools/client/webconsole/test/browser_webconsole_output_order.js | This test exceeded the timeout threshold. It should be rewritten or split up. If that's not possible, use requestLongerTimeout(N), but only as a last resort. -

Categories

(DevTools :: Console, defect, P5)

Product:
DevTools
Component:
Console
Type:
defect

Tracking

(Not tracked)

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

Filed by: apavel [at] mozilla.com https://treeherder.mozilla.org/logviewer.html#?job_id=154167085&repo=mozilla-inbound https://queue.taskcluster.net/v1/task/cnhSRQfyRRWHqzE0AspyUQ/runs/0/artifacts/public/logs/live_backing.log [task 2018-01-04T19:18:48.794Z] 19:18:48 INFO - TEST-START | devtools/client/webconsole/test/browser_webconsole_output_events.js [task 2018-01-04T19:19:30.834Z] 19:19:30 INFO - GECKO(2992) | ###!!! [Parent][DispatchAsyncMessage] Error: PClientSource::Msg_ExecutionReady Route error: message sent to unknown actor ID [task 2018-01-04T19:19:38.299Z] 19:19:38 INFO - GECKO(2992) | MEMORY STAT | vsize 20975790MB | residentFast 2678MB [task 2018-01-04T19:19:38.302Z] 19:19:38 INFO - TEST-OK | devtools/client/webconsole/test/browser_webconsole_output_events.js | took 49503ms [task 2018-01-04T19:19:39.618Z] 19:19:39 INFO - checking window state [task 2018-01-04T19:19:41.290Z] 19:19:41 INFO - TEST-START | devtools/client/webconsole/test/browser_webconsole_output_order.js [task 2018-01-04T19:21:24.608Z] 19:21:24 INFO - GECKO(2992) | AddressSanitizer:DEADLYSIGNAL [task 2018-01-04T19:21:24.614Z] 19:21:24 INFO - GECKO(2992) | AddressSanitizer:DEADLYSIGNAL [task 2018-01-04T19:21:24.615Z] 19:21:24 INFO - GECKO(2992) | AddressSanitizer:DEADLYSIGNAL [task 2018-01-04T19:21:24.615Z] 19:21:24 INFO - GECKO(2992) | ================================================================= [task 2018-01-04T19:21:24.616Z] 19:21:24 INFO - GECKO(2992) | ================================================================= [task 2018-01-04T19:21:24.616Z] 19:21:24 INFO - GECKO(2992) | ================================================================= [task 2018-01-04T19:21:24.616Z] 19:21:24 INFO - GECKO(2992) | ERROR: Failed to mmap [task 2018-01-04T19:21:24.617Z] 19:21:24 INFO - GECKO(2992) | ERROR: Failed to mmap [task 2018-01-04T19:21:24.617Z] 19:21:24 INFO - GECKO(2992) | ERROR: Failed to mmap [task 2018-01-04T19:21:25.018Z] 19:21:25 INFO - GECKO(2992) | ###!!! [Parent][RunMessage] Error: Channel error: cannot send/recv [task 2018-01-04T19:21:25.019Z] 19:21:25 INFO - GECKO(2992) | ###!!! [Parent][RunMessage] Error: Channel error: cannot send/recv [task 2018-01-04T19:21:25.019Z] 19:21:25 INFO - GECKO(2992) | ###!!! [Parent][RunMessage] Error: Channel error: cannot send/recv [task 2018-01-04T19:21:25.020Z] 19:21:25 INFO - GECKO(2992) | ###!!! [Parent][RunMessage] Error: Channel error: cannot send/recv [task 2018-01-04T19:21:25.210Z] 19:21:25 INFO - GECKO(2992) | ###!!! [Parent][RunMessage] Error: Channel error: cannot send/recv [task 2018-01-04T19:21:25.210Z] 19:21:25 INFO - GECKO(2992) | ###!!! [Parent][RunMessage] Error: Channel error: cannot send/recv [task 2018-01-04T19:21:25.312Z] 19:21:25 INFO - GECKO(2992) | ###!!! [Parent][RunMessage] Error: Channel error: cannot send/recv [task 2018-01-04T19:21:25.313Z] 19:21:25 INFO - GECKO(2992) | ###!!! [Parent][RunMessage] Error: Channel error: cannot send/recv [task 2018-01-04T19:21:25.391Z] 19:21:25 INFO - GECKO(2992) | ###!!! [Parent][RunMessage] Error: Channel error: cannot send/recv [task 2018-01-04T19:21:25.391Z] 19:21:25 INFO - GECKO(2992) | ###!!! [Parent][RunMessage] Error: Channel error: cannot send/recv [task 2018-01-04T19:21:25.407Z] 19:21:25 INFO - GECKO(2992) | ###!!! [Parent][RunMessage] Error: Channel error: cannot send/recv [task 2018-01-04T19:21:25.409Z] 19:21:25 INFO - GECKO(2992) | ###!!! [Parent][RunMessage] Error: Channel error: cannot send/recv [task 2018-01-04T19:21:25.425Z] 19:21:25 INFO - GECKO(2992) | ###!!! [Parent][RunMessage] Error: Channel error: cannot send/recv [task 2018-01-04T19:21:25.426Z] 19:21:25 INFO - GECKO(2992) | ###!!! [Parent][RunMessage] Error: Channel error: cannot send/recv [task 2018-01-04T19:21:25.426Z] 19:21:25 INFO - GECKO(2992) | ###!!! [Parent][RunMessage] Error: Channel error: cannot send/recv [task 2018-01-04T19:21:25.428Z] 19:21:25 INFO - GECKO(2992) | ###!!! [Parent][RunMessage] Error: Channel error: cannot send/recv [task 2018-01-04T19:21:25.428Z] 19:21:25 INFO - GECKO(2992) | ###!!! [Parent][RunMessage] Error: Channel error: cannot send/recv [task 2018-01-04T19:21:25.429Z] 19:21:25 INFO - GECKO(2992) | ###!!! [Parent][RunMessage] Error: Channel error: cannot send/recv [task 2018-01-04T19:21:25.430Z] 19:21:25 INFO - GECKO(2992) | ###!!! [Parent][RunMessage] Error: Channel error: cannot send/recv [task 2018-01-04T19:21:25.430Z] 19:21:25 INFO - GECKO(2992) | ###!!! [Parent][RunMessage] Error: Channel error: cannot send/recv [task 2018-01-04T19:21:25.431Z] 19:21:25 INFO - GECKO(2992) | ###!!! [Parent][RunMessage] Error: Channel error: cannot send/recv [task 2018-01-04T19:21:25.431Z] 19:21:25 INFO - GECKO(2992) | ###!!! [Parent][RunMessage] Error: Channel error: cannot send/recv [task 2018-01-04T19:21:25.432Z] 19:21:25 INFO - GECKO(2992) | ###!!! [Parent][RunMessage] Error: Channel error: cannot send/recv [task 2018-01-04T19:21:25.433Z] 19:21:25 INFO - GECKO(2992) | ###!!! [Parent][RunMessage] Error: Channel error: cannot send/recv [task 2018-01-04T19:21:25.433Z] 19:21:25 INFO - GECKO(2992) | ###!!! [Parent][RunMessage] Error: Channel error: cannot send/recv [task 2018-01-04T19:21:25.433Z] 19:21:25 INFO - GECKO(2992) | ###!!! [Parent][RunMessage] Error: Channel error: cannot send/recv [task 2018-01-04T19:21:25.448Z] 19:21:25 INFO - GECKO(2992) | ###!!! [Parent][RunMessage] Error: Channel error: cannot send/recv [task 2018-01-04T19:21:25.449Z] 19:21:25 INFO - GECKO(2992) | ###!!! [Parent][RunMessage] Error: Channel error: cannot send/recv [task 2018-01-04T19:21:25.449Z] 19:21:25 INFO - GECKO(2992) | ###!!! [Parent][RunMessage] Error: Channel error: cannot send/recv [task 2018-01-04T19:21:25.450Z] 19:21:25 INFO - GECKO(2992) | ###!!! [Parent][RunMessage] Error: Channel error: cannot send/recv [task 2018-01-04T19:21:25.450Z] 19:21:25 INFO - GECKO(2992) | ###!!! [Parent][RunMessage] Error: Channel error: cannot send/recv [task 2018-01-04T19:21:25.450Z] 19:21:25 INFO - GECKO(2992) | ###!!! [Parent][RunMessage] Error: Channel error: cannot send/recv [task 2018-01-04T19:21:25.451Z] 19:21:25 INFO - GECKO(2992) | ###!!! [Parent][RunMessage] Error: Channel error: cannot send/recv [task 2018-01-04T19:21:25.451Z] 19:21:25 INFO - GECKO(2992) | ###!!! [Parent][RunMessage] Error: Channel error: cannot send/recv [task 2018-01-04T19:21:25.452Z] 19:21:25 INFO - GECKO(2992) | ###!!! [Parent][RunMessage] Error: Channel error: cannot send/recv [task 2018-01-04T19:21:25.452Z] 19:21:25 INFO - GECKO(2992) | ###!!! [Parent][RunMessage] Error: Channel error: cannot send/recv [task 2018-01-04T19:21:25.492Z] 19:21:25 INFO - GECKO(2992) | ###!!! [Parent][RunMessage] Error: Channel error: cannot send/recv [task 2018-01-04T19:21:25.493Z] 19:21:25 INFO - GECKO(2992) | ###!!! [Parent][RunMessage] Error: Channel error: cannot send/recv [task 2018-01-04T19:21:25.494Z] 19:21:25 INFO - GECKO(2992) | ###!!! [Parent][RunMessage] Error: Channel error: cannot send/recv [task 2018-01-04T19:21:25.495Z] 19:21:25 INFO - GECKO(2992) | ###!!! [Parent][RunMessage] Error: Channel error: cannot send/recv [task 2018-01-04T19:21:25.495Z] 19:21:25 INFO - GECKO(2992) | ###!!! [Parent][RunMessage] Error: Channel error: cannot send/recv [task 2018-01-04T19:21:25.496Z] 19:21:25 INFO - GECKO(2992) | ###!!! [Parent][RunMessage] Error: Channel error: cannot send/recv [task 2018-01-04T19:21:25.496Z] 19:21:25 INFO - GECKO(2992) | ###!!! [Parent][MessageChannel] Error: (msgtype=0x150084,name=PBrowser::Msg_Destroy) Channel error: cannot send/recv [task 2018-01-04T19:21:25.533Z] 19:21:25 INFO - GECKO(2992) | ###!!! [Parent][MessageChannel] Error: (msgtype=0x150084,name=PBrowser::Msg_Destroy) Channel error: cannot send/recv [task 2018-01-04T19:21:30.040Z] 19:21:30 INFO - TEST-INFO | started process screentopng [task 2018-01-04T19:21:30.494Z] 19:21:30 INFO - TEST-INFO | screentopng: exit 0 [task 2018-01-04T19:21:30.503Z] 19:21:30 INFO - Buffered messages logged at 19:19:41 [task 2018-01-04T19:21:30.504Z] 19:21:30 INFO - Entering test bound [task 2018-01-04T19:21:30.506Z] 19:21:30 INFO - Adding a new tab with URL: http://example.com/browser/devtools/client/webconsole/test/test-console.html [task 2018-01-04T19:21:30.507Z] 19:21:30 INFO - Buffered messages logged at 19:21:25 [task 2018-01-04T19:21:30.508Z] 19:21:30 INFO - Tab added and finished loading [task 2018-01-04T19:21:30.509Z] 19:21:30 INFO - Buffered messages logged at 19:21:26 [task 2018-01-04T19:21:30.510Z] 19:21:30 INFO - Console message: [JavaScript Error: "TelemetryStopwatch: key "FX_SCHEDULE_PRESSURE_IDLE_SAMPLE_MS" was already initialized" {file: "resource://gre/modules/TelemetryStopwatch.jsm" line: 356}] [task 2018-01-04T19:21:30.511Z] 19:21:30 INFO - start@resource://gre/modules/TelemetryStopwatch.jsm:356:9 [task 2018-01-04T19:21:30.511Z] 19:21:30 INFO - start@resource://gre/modules/TelemetryStopwatch.jsm:137:12 [task 2018-01-04T19:21:30.512Z] 19:21:30 INFO - _createTimeoutFn/<@resource:///modules/SchedulePressure.jsm:42:11 [task 2018-01-04T19:21:30.513Z] 19:21:30 INFO - setTimeout handler*SimpleTest_setTimeoutShim@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:676:12 [task 2018-01-04T19:21:30.514Z] 19:21:30 INFO - startMonitoring@resource:///modules/SchedulePressure.jsm:105:7 [task 2018-01-04T19:21:30.515Z] 19:21:30 INFO - onStateChange@chrome://browser/content/tabbrowser.xml:722:21 [task 2018-01-04T19:21:30.516Z] 19:21:30 INFO - _callProgressListeners@resource://gre/modules/RemoteWebProgress.jsm:175:11 [task 2018-01-04T19:21:30.516Z] 19:21:30 INFO - receiveMessage@resource://gre/modules/RemoteWebProgress.jsm:241:7 [task 2018-01-04T19:21:30.517Z] 19:21:30 INFO - [task 2018-01-04T19:21:30.518Z] 19:21:30 INFO - Console message: [JavaScript Error: "remote browser crashed while on http://example.com/browser/devtools/client/webconsole/test/test-console.html [task 2018-01-04T19:21:30.519Z] 19:21:30 INFO - " {file: "chrome://mochikit/content/mochitest-e10s-utils.js" line: 8}] [task 2018-01-04T19:21:30.520Z] 19:21:30 INFO - e10s_init/<@chrome://mochikit/content/mochitest-e10s-utils.js:8:5 [task 2018-01-04T19:21:30.521Z] 19:21:30 INFO - EventListener.handleEvent*EventTargetInterposition.methods.addEventListener@resource://gre/modules/RemoteAddonsParent.jsm:673:5 [task 2018-01-04T19:21:30.522Z] 19:21:30 INFO - interposeProperty/desc.value@jar:file:///builds/worker/workspace/build/application/firefox/omni.ja!/components/multiprocessShims.js:157:52 [task 2018-01-04T19:21:30.523Z] 19:21:30 INFO - e10s_init@chrome://mochikit/content/mochitest-e10s-utils.js:6:3 [task 2018-01-04T19:21:30.523Z] 19:21:30 INFO - testInit@chrome://mochikit/content/browser-test.js:102:5 [task 2018-01-04T19:21:30.524Z] 19:21:30 INFO - setTimeout handler*@chrome://mochikit/content/browser-test.js:26:3 [task 2018-01-04T19:21:30.525Z] 19:21:30 INFO - [task 2018-01-04T19:21:30.526Z] 19:21:30 INFO - Console message: [JavaScript Error: "remote browser crashed while on http://example.com/browser/devtools/client/webconsole/test/test-console.html [task 2018-01-04T19:21:30.527Z] 19:21:30 INFO - " {file: "chrome://mochikit/content/mochitest-e10s-utils.js" line: 8}] [task 2018-01-04T19:21:30.528Z] 19:21:30 INFO - e10s_init/<@chrome://mochikit/content/mochitest-e10s-utils.js:8:5 [task 2018-01-04T19:21:30.529Z] 19:21:30 INFO - EventListener.handleEvent*EventTargetInterposition.methods.addEventListener@resource://gre/modules/RemoteAddonsParent.jsm:673:5 [task 2018-01-04T19:21:30.529Z] 19:21:30 INFO - interposeProperty/desc.value@jar:file:///builds/worker/workspace/build/application/firefox/omni.ja!/components/multiprocessShims.js:157:52 [task 2018-01-04T19:21:30.530Z] 19:21:30 INFO - e10s_init@chrome://mochikit/content/mochitest-e10s-utils.js:6:3 [task 2018-01-04T19:21:30.531Z] 19:21:30 INFO - testInit@chrome://mochikit/content/browser-test.js:102:5 [task 2018-01-04T19:21:30.532Z] 19:21:30 INFO - setTimeout handler*@chrome://mochikit/content/browser-test.js:26:3 [task 2018-01-04T19:21:30.533Z] 19:21:30 INFO - [task 2018-01-04T19:21:30.533Z] 19:21:30 INFO - Buffered messages logged at 19:21:28 [task 2018-01-04T19:21:30.534Z] 19:21:30 INFO - Console message: [JavaScript Warning: "Unknown property ‘user-select’. Declaration dropped." {file: "resource://devtools/client/shared/components/reps/reps.css" line: 257 column: 13 source: " user-select: none;"}] [task 2018-01-04T19:21:30.535Z] 19:21:30 INFO - Buffered messages logged at 19:21:29 [task 2018-01-04T19:21:30.535Z] 19:21:30 INFO - Waiting for messages... [task 2018-01-04T19:21:30.536Z] 19:21:30 INFO - TEST-PASS | devtools/client/webconsole/test/browser_webconsole_output_order.js | matched rule: console.log('foo', 'bar'); - [task 2018-01-04T19:21:30.536Z] 19:21:30 INFO - TEST-PASS | devtools/client/webconsole/test/browser_webconsole_output_order.js | matched rule: foo bar - [task 2018-01-04T19:21:30.537Z] 19:21:30 INFO - TEST-PASS | devtools/client/webconsole/test/browser_webconsole_output_order.js | matched rule: undefined - [task 2018-01-04T19:21:30.538Z] 19:21:30 INFO - TEST-PASS | devtools/client/webconsole/test/browser_webconsole_output_order.js | console.log() is followed by 'foo' 'bar' - [task 2018-01-04T19:21:30.538Z] 19:21:30 INFO - TEST-PASS | devtools/client/webconsole/test/browser_webconsole_output_order.js | 'foo' 'bar' is followed by undefined - [task 2018-01-04T19:21:30.539Z] 19:21:30 INFO - Leaving test bound [task 2018-01-04T19:21:30.539Z] 19:21:30 INFO - Removing tab. [task 2018-01-04T19:21:30.540Z] 19:21:30 INFO - Waiting for event: 'TabClose' on [object XULElement]. [task 2018-01-04T19:21:30.540Z] 19:21:30 INFO - Got event: 'TabClose' on [object XULElement]. [task 2018-01-04T19:21:30.541Z] 19:21:30 INFO - Tab removed and finished closing [task 2018-01-04T19:21:30.541Z] 19:21:30 INFO - Buffered messages finished [task 2018-01-04T19:21:30.542Z] 19:21:30 INFO - TEST-UNEXPECTED-FAIL | devtools/client/webconsole/test/browser_webconsole_output_order.js | This test exceeded the timeout threshold. It should be rewritten or split up. If that's not possible, use requestLongerTimeout(N), but only as a last resort. - [task 2018-01-04T19:21:30.543Z] 19:21:30 INFO - GECKO(2992) | MEMORY STAT | vsize 20975782MB | residentFast 2977MB [task 2018-01-04T19:21:30.543Z] 19:21:30 INFO - TEST-OK | devtools/client/webconsole/test/browser_webconsole_output_order.js | took 108782ms
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
Status: NEW → RESOLVED
Closed: 7 years ago
Resolution: --- → INCOMPLETE
Product: Firefox → DevTools
You need to log in before you can comment on or make changes to this bug.