1628898 - Intermittent devtools/client/webconsole/test/browser/browser_webconsole_output_order.js | console.log() is followed by 'foo' 'bar' - Got null, expected [object HTMLDivElement]

Status: RESOLVED FIXED

(DevTools :: Console, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: malexandru [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=297020118&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/UfXZ-bBiSeK2Fx68ioRzEA/runs/0/artifacts/public/logs/live_backing.log

---

[task 2020-04-09T21:50:34.597Z] 21:50:34     INFO - TEST-START | devtools/client/webconsole/test/browser/browser_webconsole_output_order.js
[task 2020-04-09T21:50:35.053Z] 21:50:35     INFO - TEST-INFO | started process screenshot
[task 2020-04-09T21:50:35.132Z] 21:50:35     INFO - TEST-INFO | screenshot: exit 0
[task 2020-04-09T21:50:35.133Z] 21:50:35     INFO - Buffered messages logged at 21:50:34
[task 2020-04-09T21:50:35.133Z] 21:50:35     INFO - Entering test bound 
[task 2020-04-09T21:50:35.133Z] 21:50:35     INFO - Adding a new tab with URL: http://example.com/browser/devtools/client/webconsole/test/browser/test-console.html
[task 2020-04-09T21:50:35.133Z] 21:50:35     INFO - Tab added and finished loading
[task 2020-04-09T21:50:35.134Z] 21:50:35     INFO - Opening the toolbox
[task 2020-04-09T21:50:35.134Z] 21:50:35     INFO - Console message: [JavaScript Error: "Content Security Policy: The page’s settings blocked the loading of a resource at inline (“default-src”)." {file: "chrome://global/content/elements/panel.js" line: 62}]
[task 2020-04-09T21:50:35.135Z] 21:50:35     INFO - Console message: [JavaScript Error: "Content Security Policy: The page’s settings blocked the loading of a resource at inline (“default-src”)." {file: "chrome://global/content/elements/panel.js" line: 62}]
[task 2020-04-09T21:50:35.135Z] 21:50:35     INFO - Toolbox opened and focused
[task 2020-04-09T21:50:35.135Z] 21:50:35     INFO - Matched a message with text: "console.log('foo', 'bar');", still waiting for 2 messages.
[task 2020-04-09T21:50:35.135Z] 21:50:35     INFO - Buffered messages logged at 21:50:35
[task 2020-04-09T21:50:35.135Z] 21:50:35     INFO - Matched a message with text: "foo bar", still waiting for 1 messages.
[task 2020-04-09T21:50:35.136Z] 21:50:35     INFO - Matched a message with text: "undefined", all messages received.
[task 2020-04-09T21:50:35.136Z] 21:50:35     INFO - Buffered messages finished
[task 2020-04-09T21:50:35.136Z] 21:50:35     INFO - TEST-UNEXPECTED-FAIL | devtools/client/webconsole/test/browser/browser_webconsole_output_order.js | console.log() is followed by 'foo' 'bar' - Got null, expected [object HTMLDivElement]
[task 2020-04-09T21:50:35.136Z] 21:50:35     INFO - Stack trace:
[task 2020-04-09T21:50:35.136Z] 21:50:35     INFO - chrome://mochikit/content/browser-test.js:test_is:1320
[task 2020-04-09T21:50:35.136Z] 21:50:35     INFO - chrome://mochitests/content/browser/devtools/client/webconsole/test/browser/browser_webconsole_output_order.js:null:28
[task 2020-04-09T21:50:35.136Z] 21:50:35     INFO - chrome://mochikit/content/browser-test.js:Tester_execTest/<:1062
[task 2020-04-09T21:50:35.136Z] 21:50:35     INFO - chrome://mochikit/content/browser-test.js:Tester_execTest:1097
[task 2020-04-09T21:50:35.136Z] 21:50:35     INFO - chrome://mochikit/content/browser-test.js:nextTest/<:925
[task 2020-04-09T21:50:35.136Z] 21:50:35     INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<:918
[task 2020-04-09T21:50:35.137Z] 21:50:35     INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_webconsole_output_order.js | 'foo' 'bar' is followed by undefined - 
[task 2020-04-09T21:50:35.137Z] 21:50:35     INFO - Leaving test bound 
[task 2020-04-09T21:50:35.138Z] 21:50:35     INFO - GECKO(4228) | console.log: "getSources failed. Connection may have closed: Error: Connection closed, pending request to server0.conn143.child2/thread21, type sources failed\n\nRequest stack:\nrequest@resource://devtools/shared/protocol/Front.js:256:14\ngenerateRequestMethods/</frontProto[name]@resource://devtools/shared/protocol/Front/FrontClassWithSpec.js:47:19\ngetSources@resource://devtools/client/fronts/thread.js:181:34\nSourceMapURLService.prototype._getLoadingPromise/this._loadingPromise<@resource://devtools/client/framework/source-map-url-service.js:85:56\nasync*SourceMapURLService.prototype._getLoadingPromise@resource://devtools/client/framework/source-map-url-service.js:102:7\nSourceMapURLService.prototype.originalPositionFor@resource://devtools/client/framework/source-map-url-service.js:279:16\nSourceMapURLService.prototype._callOneCallback@resource://devtools/client/framework/source-map-url-service.js:332:38\nSourceMapURLService.prototype.subscribe@resource://devtools/client/framework/source-map-url-service.js:395:10\ncomponentWillMount@resource://devtools/client/shared/components/Frame.js:78:70\ncallComponentWillMount@resource://devtools/client/shared/vendor/react-dom.js:8286:14\nmountClassInstance@resource://devtools/client/shared/vendor/react-dom.js:8345:27\nupdateClassComponent@resource://devtools/client/shared/vendor/react-dom.js:10593:23\nbeginWork@resource://devtools/client/shared/vendor/react-dom.js:11419:16\nperformUnitOfWork@resource://devtools/client/shared/vendor/react-dom.js:14702:12\nworkLoop@resource://devtools/client/shared/vendor/react-dom.js:14720:24\nrenderRoot@resource://devtools/client/shared/vendor/react-dom.js:14803:15\nperformWorkOnRoot@resource://devtools/client/shared/vendor/react-dom.js:15655:17\nperformWork@resource://devtools/client/shared/vendor/react-dom.js:15567:24\nperformSyncWork@resource://devtools/client/shared/vendor/react-dom.js:15541:14\nrequestWork@resource://devtools/client/shared/vendor/react-dom.js:15410:5\nscheduleWork@resource://devtools/client/shared/vendor/react-dom.js:15224:16\nenqueueSetState@resource://devtools/client/shared/vendor/react-dom.js:8192:17\nComponent.prototype.setState@resource://devtools/client/shared/vendor/react.js:328:16\nonStateChange@resource://devtools/client/shared/vendor/react-redux.js:1412:16\nnotify@resource://devtools/client/shared/vendor/react-redux.js:1159:21\nnotifyNestedSubs@resource://devtools/client/shared/vendor/react-redux.js:1198:20\nonStateChange@resource://devtools/client/shared/vendor/react-redux.js:1409:16\ndispatch@resource://devtools/client/shared/vendor/redux.js:265:7\neventTelemetryMiddleware/</<@resource://devtools/client/webconsole/middleware/event-telemetry.js:22:21\nhistoryPersistenceMiddleware/</<@resource://devtools/client/webconsole/middleware/history-persistence.js:37:21\nthunkWithOptions/</<@resource://devtools/client/shared/redux/middleware/thunk-with-options.js:17:9\nignore/</<@resource://devtools/client/shared/redux/middleware/ignore.js:28:12\nsetTimeoutIfNeeded/this.throttledDispatchPromise</<@resource://devtools/client/webconsole/webconsole-wrapper.js:324:15\nsetTimeout handler*setTimeoutIfNeeded/this.throttledDispatchPromise<@resource://devtools/client/webconsole/webconsole-wrapper.js:314:17\nsetTimeoutIfNeeded@resource://devtools/client/webconsole/webconsole-wrapper.js:313:37\nbatchedMessagesAdd@resource://devtools/client/webconsole/webconsole-wrapper.js:276:10\ndispatchMessageAdd@resource://devtools/client/webconsole/webconsole-wrapper.js:140:10\n_onResourceAvailable@resource://devtools/client/webconsole/webconsole-ui.js:347:20\n_emit@resource://devtools/shared/event-emitter.js:226:34\nemit@resource://devtools/shared/event-emitter.js:172:18\nemit@resource://devtools/shared/event-emitter.js:324:18\n_onResourceAvailable@resource://devtools/shared/resources/resource-watcher.js:183:30\n_emit@resource://devtools/shared/event-emitter.js:226:34\nemit@resource://devtools/shared/event-emitter.js:172:18\nemit@resource://devtools/shared/event-emitter.js:324:18\nonPacket@resource://devtools/shared/protocol/Front.js:294:13\nonPacket@resource://devtools/client/devtools-client.js:493:13\nsend/<@resource://devtools/shared/transport/local-transport.js:68:25\nexports.makeInfallible/<@resource://devtools/shared/ThreadSafeDevToolsUtils.js:111:22\nDevToolsUtils.executeSoon*exports.executeSoon@resource://devtools/shared/DevToolsUtils.js:62:21\nsend@resource://devtools/shared/transport/local-transport.js:56:21\nsend@resource://devtools/server/devtools-server-connection.js:91:20\nreceiveMessage@resource://devtools/shared/transport/child-transport.js:66:16\nMessageListener.receiveMessage*_addListener@resource://devtools/shared/transport/child-transport.js:40:14\nready@resource://devtools/shared/transport/child-transport.js:57:10\nconnectToFrame/</onActorCreated<@resource://devtools/server/connectors/frame-connector.js:183:22\nexports.makeInfallible/<@resource://devtools/shared/ThreadSafeDevToolsUtils.js:111:22\nMessageListener.receiveMessage*trackMessageManager@resource://devtools/server/connectors/frame-connector.js:55:12\nconnectToFrame/<@resource://devtools/server/connectors/frame-connector.js:283:5\n"
[task 2020-04-09T21:50:36.118Z] 21:50:36     INFO - Removing tab.
[task 2020-04-09T21:50:36.119Z] 21:50:36     INFO - Waiting for event: 'TabClose' on [object XULElement].
[task 2020-04-09T21:50:36.119Z] 21:50:36     INFO - Got event: 'TabClose' on [object XULElement].
[task 2020-04-09T21:50:36.122Z] 21:50:36     INFO - GECKO(4228) | console.warn: "IGNORED REDUX ACTION:" ({type:"AUTOCOMPLETE_CLEAR"})
[task 2020-04-09T21:50:36.129Z] 21:50:36     INFO - Tab removed and finished closing
[task 2020-04-09T21:50:36.144Z] 21:50:36     INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_webconsole_output_order.js | The main process DevToolsServer has no pending connection when the test ends - 
[task 2020-04-09T21:50:36.171Z] 21:50:36     INFO - GECKO(4228) | MEMORY STAT | vsize 1479MB | vsizeMaxContiguous 134MB | residentFast 912MB | heapAllocated 547MB
[task 2020-04-09T21:50:36.171Z] 21:50:36     INFO - TEST-OK | devtools/client/webconsole/test/browser/browser_webconsole_output_order.js | took 1576ms
[task 2020-04-09T21:50:36.186Z] 21:50:36     INFO - checking window state
[task 2020-04-09T21:50:36.189Z] 21:50:36     INFO - TEST-START | devtools/client/webconsole/test/browser/browser_webconsole_output_trimmed.js

Comment 1

[Intermittent Failures Robot]

4 failures in 4823 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 4

Platform breakdown:

• windows7-32-shippable: 4

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1628898&startday=2020-04-06&endday=2020-04-12&tree=all

Comment 2

[Intermittent Failures Robot]

5 failures in 4528 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 5

Platform breakdown:

• windows7-32-shippable: 5

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1628898&startday=2020-04-13&endday=2020-04-19&tree=all

Comment 3

[Intermittent Failures Robot]

9 failures in 5582 pushes (0.002 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 8
• mozilla-central: 1

Platform breakdown:

• windows7-32-shippable: 9

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1628898&startday=2020-04-20&endday=2020-04-26&tree=all

Comment 4

[Intermittent Failures Robot]

20 failures in 5423 pushes (0.004 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 17
• mozilla-central: 3

Platform breakdown:

• windows7-32-shippable: 20

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1628898&startday=2020-04-27&endday=2020-05-03&tree=all

Comment 5

[Intermittent Failures Robot]

16 failures in 5276 pushes (0.003 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 10
• mozilla-beta: 3
• mozilla-central: 1
• try: 2

Platform breakdown:

• windows7-32-shippable: 13
• windows7-32-devedition: 3

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1628898&startday=2020-05-04&endday=2020-05-10&tree=all

Comment 6

[Intermittent Failures Robot]

29 failures in 5488 pushes (0.005 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 21
• mozilla-central: 3
• mozilla-beta: 1
• try: 4

Platform breakdown:

• windows7-32-shippable: 25
• windows7-32-devedition: 4

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1628898&startday=2020-05-11&endday=2020-05-17&tree=all

Comment 7

[Intermittent Failures Robot]

36 failures in 4727 pushes (0.008 failures/push) were associated with this bug in the last 7 days.

This is the #37 most frequent failure this week.

** This failure happened more than 30 times this week! Resolving this bug is a high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 2 weeks, the affected test(s) may be disabled. **

Repository breakdown:

• autoland: 28
• mozilla-beta: 3
• mozilla-central: 4
• try: 1

Platform breakdown:

• windows7-32-shippable: 34
• windows7-32-devedition: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1628898&startday=2020-05-18&endday=2020-05-24&tree=all

Comment 8

[Intermittent Failures Robot]

12 failures in 5458 pushes (0.002 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 11
• try: 1

Platform breakdown:

• windows7-32-shippable: 11
• windows7-32-devedition: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1628898&startday=2020-05-25&endday=2020-05-31&tree=all

Comment 9

[Intermittent Failures Robot]

8 failures in 4949 pushes (0.002 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 8

Platform breakdown:

• windows7-32-shippable: 8

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1628898&startday=2020-06-01&endday=2020-06-07&tree=all

Comment 10

[Nicolas Chevobbe [:nchevobbe]]

Attachment: Bug 1628898 - Fix intermittent on browser_webconsole_output_order. r=loganfsmyth.

Comment 11

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/6e07f51a5fac
Fix intermittent on browser_webconsole_output_order. r=loganfsmyth.

Comment 12

[Atila Butkovits]

https://hg.mozilla.org/mozilla-central/rev/6e07f51a5fac

Comment 13

[Intermittent Failures Robot]

7 failures in 5211 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 6
• mozilla-beta: 1

Platform breakdown:

• windows7-32-shippable: 6
• windows7-32-devedition: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1628898&startday=2020-06-08&endday=2020-06-14&tree=all

Comment 14

[Intermittent Failures Robot]

1 failures in 3694 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-esr78: 1

Platform breakdown:

• windows7-32-shippable: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1628898&startday=2020-10-12&endday=2020-10-18&tree=all

Comment 15

[Intermittent Failures Robot]

1 failures in 4230 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-esr78: 1

Platform breakdown:

• windows7-32-shippable: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1628898&startday=2020-10-19&endday=2020-10-25&tree=all

Comment 16

[Ryan VanderMeulen [:RyanVM]]

https://hg.mozilla.org/releases/mozilla-esr78/rev/04b29f60145d

Comment 17

[Intermittent Failures Robot]

1 failures in 2956 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-esr78: 1

Platform breakdown:

• windows7-32-shippable: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1628898&startday=2021-01-04&endday=2021-01-10&tree=all