1115222 - Intermittent browser_webconsole_bug_599725_response_headers.js | Test timed out

Status: RESOLVED FIXED

(DevTools :: Console, defect)

Description

[Wes Kocher (:KWierso) (Not reading bugmail; email directly if needed)]

13:51:01 INFO - 3550 INFO TEST-START | browser/devtools/webconsole/test/browser_webconsole_bug_599725_response_headers.js
13:51:02 INFO - -*-*- UserCustomizations (child): document created: http://example.com/browser/browser/devtools/webconsole/test/test-bug-599725-response-headers.sjs
13:51:02 INFO - -*-*- UserCustomizations (child): _injectInWindow
13:51:02 INFO - -*-*- UserCustomizations (child): principal status: 0
13:51:02 INFO - -*-*- UserCustomizations (child): document created: http://example.com/browser/browser/devtools/webconsole/test/test-bug-599725-response-headers.sjs
13:51:02 INFO - -*-*- UserCustomizations (child): _injectInWindow
13:51:02 INFO - -*-*- UserCustomizations (child): principal status: 0
13:51:02 INFO - notify event 'networkEventUpdate' threw an exception: TypeError: performTest(...) is undefined
13:51:02 INFO - Stack: waitForRequest/HUDService.lastFinishedRequest.callback@chrome://mochitests/content/browser/browser/devtools/webconsole/test/browser_webconsole_bug_599725_response_headers.js:67:5
13:51:02 INFO - WCF_handleNetworkEventUpdate@resource://gre/modules/commonjs/toolkit/loader.js -> resource:///modules/devtools/webconsole/webconsole.js:1823:7
13:51:02 INFO - WCCP__onNetworkEvenUpdatet@resource://gre/modules/commonjs/toolkit/loader.js -> resource:///modules/devtools/webconsole/webconsole.js:5166:1
13:51:02 INFO - eventSource/aProto.emit@resource://gre/modules/devtools/dbg-client.jsm:190:9
13:51:02 INFO - DebuggerClient.prototype.onPacket@resource://gre/modules/devtools/dbg-client.jsm:942:7
13:51:02 INFO - LocalDebuggerTransport.prototype.send/<@resource://gre/modules/devtools/dbg-client.jsm -> resource://gre/modules/devtools/transport/transport.js:545:11
13:51:02 INFO - makeInfallible/<@resource://gre/modules/commonjs/toolkit/loader.js -> resource://gre/modules/devtools/DevToolsUtils.js:83:14
13:51:02 INFO - makeInfallible/<@resource://gre/modules/commonjs/toolkit/loader.js -> resource://gre/modules/devtools/DevToolsUtils.js:83:14
13:51:02 INFO - Line: 67, column: 4
13:51:46 INFO - Xlib: extension "RANDR" missing on display ":0".
13:51:48 INFO - TEST-INFO | screentopng: exit 0
13:51:48 INFO - 3551 INFO checking window state
13:51:48 INFO - 3552 INFO Console message: [JavaScript Error: "The character encoding of the HTML document was not declared. The document will render with garbled text in some browser configurations if the document contains characters from outside the US-ASCII range. The character encoding of the page must be declared in the document or in the transfer protocol." {file: "http://example.com/browser/browser/devtools/webconsole/test/test-bug-599725-response-headers.sjs" line: 0}]
13:51:48 INFO - 3553 INFO Console message: [JavaScript Error: "TelemetryStopwatch: key "FX_PAGE_LOAD_MS" was already initialized" {file: "resource://gre/modules/TelemetryStopwatch.jsm" line: 52}]
13:51:48 INFO - 3554 INFO TEST-PASS | browser/devtools/webconsole/test/browser_webconsole_bug_599725_response_headers.js | page load was logged
13:51:48 INFO - 3555 INFO Console message: [JavaScript Error: "notify event 'networkEventUpdate' threw an exception: TypeError: performTest(...) is undefined
13:51:48 INFO - Stack: waitForRequest/HUDService.lastFinishedRequest.callback@chrome://mochitests/content/browser/browser/devtools/webconsole/test/browser_webconsole_bug_599725_response_headers.js:67:5
13:51:48 INFO - WCF_handleNetworkEventUpdate@resource://gre/modules/commonjs/toolkit/loader.js -> resource:///modules/devtools/webconsole/webconsole.js:1823:7
13:51:48 INFO - WCCP__onNetworkEvenUpdatet@resource://gre/modules/commonjs/toolkit/loader.js -> resource:///modules/devtools/webconsole/webconsole.js:5166:1
13:51:48 INFO - eventSource/aProto.emit@resource://gre/modules/devtools/dbg-client.jsm:190:9
13:51:48 INFO - DebuggerClient.prototype.onPacket@resource://gre/modules/devtools/dbg-client.jsm:942:7
13:51:48 INFO - LocalDebuggerTransport.prototype.send/<@resource://gre/modules/devtools/dbg-client.jsm -> resource://gre/modules/devtools/transport/transport.js:545:11
13:51:48 INFO - makeInfallible/<@resource://gre/modules/commonjs/toolkit/loader.js -> resource://gre/modules/devtools/DevToolsUtils.js:83:14
13:51:48 INFO - makeInfallible/<@resource://gre/modules/commonjs/toolkit/loader.js -> resource://gre/modules/devtools/DevToolsUtils.js:83:14
13:51:48 INFO - Line: 67, column: 4" {file: "resource://gre/modules/commonjs/toolkit/loader.js -> resource://gre/modules/devtools/DevToolsUtils.js" line: 59}]
13:51:48 INFO - 3556 INFO Console message: [JavaScript Error: "The character encoding of the HTML document was not declared. The document will render with garbled text in some browser configurations if the document contains characters from outside the US-ASCII range. The character encoding of the page must be declared in the document or in the transfer protocol." {file: "http://example.com/browser/browser/devtools/webconsole/test/test-bug-599725-response-headers.sjs" line: 0}]
13:51:48 INFO - 3557 INFO TEST-UNEXPECTED-FAIL | browser/devtools/webconsole/test/browser_webconsole_bug_599725_response_headers.js | Test timed out - expected PASS
13:51:48 INFO - 3558 INFO TEST-OK | browser/devtools/webconsole/test/browser_webconsole_bug_599725_response_headers.js | took 47309ms
13:51:48 INFO - 3559 INFO TEST-START | browser/devtools/webconsole/test/browser_webconsole_bug_600183_charset.js

Comment 1

[Legacy TBPL/Treeherder Robot]

submit_timestamp: 2014-12-23T17:04:23
log: https://treeherder.mozilla.org/ui/logviewer.html#?repo=fx-team&job_id=1540743
repository: fx-team
who: wkocher[at]mozilla[dot]com
machine: tst-linux64-spot-615
buildname: Ubuntu VM 12.04 x64 fx-team pgo test mochitest-e10s-devtools-chrome
revision: f52833a4cf74

3557 INFO TEST-UNEXPECTED-FAIL | browser/devtools/webconsole/test/browser_webconsole_bug_599725_response_headers.js | Test timed out - expected PASS

Comment 2

[Wes Kocher (:KWierso) (Not reading bugmail; email directly if needed)]

Clipboard fail...

Comment 3

[Brian Grinstead [:bgrins]]

Attachment: response-headers-test.patch

Panos, this change [0] seemed to cause the extra log spam seen in the timeout.  Basically, there are calls to performTest that are expecting a promise to be returned, but a promise is only returned on the second load.  So I've rearranged the load checking to happen before we make the call.  I'm not positive if fixing this will fix the intermittent or not, but it seems related / worth a shot.

Pushed this to try: https://tbpl.mozilla.org/?tree=Try&rev=d16e4ae1823f.

[0]: https://hg.mozilla.org/mozilla-central/diff/84880f27bab3/browser/devtools/webconsole/test/browser_webconsole_bug_599725_response_headers.js

Comment 4

[Panos Astithas (he/him) [:past] (please ni?)]

Comment on attachment 8544173 [details] [diff] [review]
response-headers-test.patch

Review of attachment 8544173 [details] [diff] [review]:
-----------------------------------------------------------------

Sure.

Comment 5

[Brian Grinstead [:bgrins]]

https://hg.mozilla.org/integration/fx-team/rev/1bc77d8c8681

Comment 6

[Wes Kocher (:KWierso) (Not reading bugmail; email directly if needed)]

https://hg.mozilla.org/mozilla-central/rev/1bc77d8c8681