1565010 - Intermittent devtools/client/webconsole/test/mochitest/browser_webconsole_warn_about_replaced_api.js | Uncaught exception - waitFor - timed out after 500 tries.

Status: RESOLVED FIXED

(DevTools :: Console, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: csabou [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=255759780&repo=mozilla-beta
Full log: https://queue.taskcluster.net/v1/task/CARjg4S_QiKKLwhd9eYKqw/runs/0/artifacts/public/logs/live_backing.log

---

17:11:39 INFO - TEST-PASS | devtools/client/webconsole/test/mochitest/browser_webconsole_warn_about_replaced_api.js | no warning displayed -
17:11:39 INFO - wait for the page to refresh and make sure the warning still isn't there
17:11:39 INFO - Refreshing tab.
17:11:39 INFO - Tab finished refreshing.
17:11:39 INFO - Buffered messages finished
17:11:39 INFO - TEST-UNEXPECTED-FAIL | devtools/client/webconsole/test/mochitest/browser_webconsole_warn_about_replaced_api.js | Uncaught exception - waitFor - timed out after 500 tries.
17:11:39 INFO - Leaving test bound
17:11:40 INFO - Removing tab.
17:11:40 INFO - Waiting for event: 'TabClose' on [object XULElement].
17:11:40 INFO - Got event: 'TabClose' on [object XULElement].
17:11:40 INFO - Tab removed and finished closing
17:11:40 INFO - GECKO(2908) | MEMORY STAT | vsize 1640MB | vsizeMaxContiguous 121MB | residentFast 680MB | heapAllocated 202MB
17:11:40 INFO - TEST-OK | devtools/client/webconsole/test/mochitest/browser_webconsole_warn_about_replaced_api.js | took 6542ms

Comment 1

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-beta: 1
• try: 1

Platform breakdown:

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

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

Comment 2

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-beta: 2

Platform breakdown:

• windows7-32-devedition: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1565010&startday=2019-07-22&endday=2019-07-28&tree=all

Comment 3

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-beta: 1

Platform breakdown:

• windows7-32-shippable: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1565010&startday=2019-07-29&endday=2019-08-04&tree=all

Comment 4

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-beta: 1

Platform breakdown:

• windows7-32-devedition: 1

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

Comment 5

[Intermittent Failures Robot]

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

Repository breakdown:

• try: 1

Platform breakdown:

• windows7-32-shippable: 1

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

Comment 6

[Firefox Bug Husbandry Bot]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup

Comment 7

[Andrei Ciure[:aciure]]

New occurrence:

https://treeherder.mozilla.org/#/jobs?repo=autoland&resultStatus=testfailed%2Cbusted%2Cexception&classifiedState=unclassified&searchStr=windows%2C7%2Cshippable%2Copt%2Cmochitests%2Ctest-windows7-32-shippable%2Fopt-mochitest-devtools-chrome-e10s-1%2Cm%28dt1%29&revision=241aa49e1c23023e46b4d17375d3b9a19b125db9&selectedJob=296959397

Comment 8

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 11
• mozilla-central: 2

Platform breakdown:

• windows7-32-shippable: 12
• windows7-32: 1

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

Comment 9

[Cristina Coroiu [:ccoroiu]]

This bug has 41 failures in the last 7 days, on windows7-32-shippable and windows7-32 platform, opt build type.
Recent log file: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=298092996&repo=autoland&lineNumber=7151
Log snippet:
[task 2020-04-17T13:03:30.144Z] 13:03:30 INFO - TEST-START | devtools/client/webconsole/test/browser/browser_webconsole_warn_about_replaced_api.js
[task 2020-04-17T13:03:30.539Z] 13:03:30 INFO - GECKO(3356) | defineSharedArrayBufferConstructor: 1
[task 2020-04-17T13:03:30.622Z] 13:03:30 INFO - GECKO(3356) | console.log: "getSources failed. Connection may have closed: Error: Connection closed, pending request to server0.conn207.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\nasyncSourceMapURLService.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:25: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:328:15\nsetTimeout handlersetTimeoutIfNeeded/this.throttledDispatchPromise<@resource://devtools/client/webconsole/webconsole-wrapper.js:318:17\nsetTimeoutIfNeeded@resource://devtools/client/webconsole/webconsole-wrapper.js:317:37\nbatchedMessagesAdd@resource://devtools/client/webconsole/webconsole-wrapper.js:280:10\ndispatchMessageAdd@resource://devtools/client/webconsole/webconsole-wrapper.js:144:10\ndispatchTabWillNavigate@resource://devtools/client/webconsole/webconsole-wrapper.js:258:12\nhandleTabWillNavigate@resource://devtools/client/webconsole/webconsole-ui.js:592:18\n_onTabWillNavigate@resource://devtools/client/webconsole/webconsole-connection-proxy.js:314:23\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_onTabNavigated@resource://devtools/client/fronts/targets/browsing-context.js:74:12\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.executeSoonexports.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\n"
[task 2020-04-17T13:03:31.517Z] 13:03:31 INFO - GECKO(3356) | defineSharedArrayBufferConstructor: 1
[task 2020-04-17T13:03:37.056Z] 13:03:37 INFO - TEST-INFO | started process screenshot
[task 2020-04-17T13:03:37.131Z] 13:03:37 INFO - TEST-INFO | screenshot: exit 0
[task 2020-04-17T13:03:37.131Z] 13:03:37 INFO - Buffered messages logged at 13:03:30
[task 2020-04-17T13:03:37.131Z] 13:03:37 INFO - Entering test bound
[task 2020-04-17T13:03:37.131Z] 13:03:37 INFO - Adding a new tab with URL: data:text/html;charset=utf8,<script>console.log('foo')</script>
[task 2020-04-17T13:03:37.131Z] 13:03:37 INFO - Tab added and finished loading
[task 2020-04-17T13:03:37.132Z] 13:03:37 INFO - Opening the toolbox
[task 2020-04-17T13:03:37.132Z] 13:03:37 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: 78}]
[task 2020-04-17T13:03:37.132Z] 13:03:37 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: 78}]
[task 2020-04-17T13:03:37.132Z] 13:03:37 INFO - Toolbox opened and focused
[task 2020-04-17T13:03:37.132Z] 13:03:37 INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_webconsole_warn_about_replaced_api.js | no warning displayed -
[task 2020-04-17T13:03:37.132Z] 13:03:37 INFO - wait for the page to refresh and make sure the warning still isn't there
[task 2020-04-17T13:03:37.133Z] 13:03:37 INFO - Refreshing tab.
[task 2020-04-17T13:03:37.133Z] 13:03:37 INFO - Tab finished refreshing.
[task 2020-04-17T13:03:37.133Z] 13:03:37 INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_webconsole_warn_about_replaced_api.js | no warning displayed -
[task 2020-04-17T13:03:37.133Z] 13:03:37 INFO - Buffered messages logged at 13:03:31
[task 2020-04-17T13:03:37.133Z] 13:03:37 INFO - Opening the toolbox
[task 2020-04-17T13:03:37.133Z] 13:03:37 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: 78}]
[task 2020-04-17T13:03:37.133Z] 13:03:37 INFO - Console message: [JavaScript Warning: "The Web Console logging API (console.log, console.info, console.warn, console.error) has been disabled by a script on this page."]
[task 2020-04-17T13:03:37.133Z] 13:03:37 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: 78}]
[task 2020-04-17T13:03:37.133Z] 13:03:37 INFO - Toolbox opened and focused
[task 2020-04-17T13:03:37.134Z] 13:03:37 INFO - wait for the warning to show
[task 2020-04-17T13:03:37.134Z] 13:03:37 INFO - reload the test page and wait for the warning to show
[task 2020-04-17T13:03:37.134Z] 13:03:37 INFO - Refreshing tab.
[task 2020-04-17T13:03:37.134Z] 13:03:37 INFO - Tab finished refreshing.
[task 2020-04-17T13:03:37.134Z] 13:03:37 INFO - Console message: [JavaScript Warning: "The Web Console logging API (console.log, console.info, console.warn, console.error) has been disabled by a script on this page."]
[task 2020-04-17T13:03:37.134Z] 13:03:37 INFO - Buffered messages finished
[task 2020-04-17T13:03:37.135Z] 13:03:37 INFO - TEST-UNEXPECTED-FAIL | devtools/client/webconsole/test/browser/browser_webconsole_warn_about_replaced_api.js | Uncaught exception - waitFor - timed out after 500 tries.
[task 2020-04-17T13:03:37.135Z] 13:03:37 INFO - Leaving test bound
[task 2020-04-17T13:03:37.350Z] 13:03:37 INFO - Removing tab.
[task 2020-04-17T13:03:37.350Z] 13:03:37 INFO - Waiting for event: 'TabClose' on [object XULElement].
[task 2020-04-17T13:03:37.350Z] 13:03:37 INFO - Got event: 'TabClose' on [object XULElement].
[task 2020-04-17T13:03:37.350Z] 13:03:37 INFO - GECKO(3356) | console.warn: "IGNORED REDUX ACTION:" ({type:"AUTOCOMPLETE_CLEAR"})
[task 2020-04-17T13:03:37.351Z] 13:03:37 INFO - Tab removed and finished closing

Nicolas, as you are the triage owner, could you please take a look at this?
Thank you!

Comment 10

[Intermittent Failures Robot]

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

This is the #36 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: 26
• mozilla-central: 15
• try: 2

Platform breakdown:

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

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

Comment 11

[Nicolas Chevobbe [:nchevobbe]]

let's disable this.
This is part of a more global failures in the console autocomplete, only on windows 7 debug builds that I still didn't figure out as it's outside of my knowledge, and the people I asked for help so far were clueless as well :/

Comment 12

[Cristina Coroiu [:ccoroiu]]

Attachment: Bug 1565010 - Disable browser_webconsole_warn_about_replaced_api.js on win r=nchevobbe

Comment 13

[Nicolas Chevobbe [:nchevobbe]]

AH, it might not be related to other windows 7 issue, but looks more like an ordering issue, let me have a look quickly

Comment 14

[Nicolas Chevobbe [:nchevobbe]]

Attachment: Bug 1565010 - Fix intermittent on browser_webconsole_warn_about_replaced_api.js .r=Honza.

Log the console replaced message only after the current batch of messages are consumed.
We also enable the timestamp in the test, so in case of failure we can diagnose
what's going on better.

Comment 15

[Nicolas Chevobbe [:nchevobbe]]

Cristina, I pushed a fix which looks good(https://treeherder.mozilla.org/#/jobs?repo=try&revision=8ded1e6cd98289562eda4fa8cca3b393cb5898e4&selectedJob=298986242).
Let's try to land my patch first and see if that fixes the intermittent, and if not, we can land your patch.

Comment 16

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/d321839de160
Fix intermittent on browser_webconsole_warn_about_replaced_api.js .r=Honza.

Comment 17

[Cristian Brindusan [:cbrindusan]]

https://hg.mozilla.org/mozilla-central/rev/d321839de160

Comment 18

[Cristina Coroiu [:ccoroiu]]

No new failures since 23th of April, it seems that the issue is fixed. Thank you

Comment 19

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 15
• mozilla-central: 7
• try: 4

Platform breakdown:

• windows7-32-shippable: 17
• windows7-32-devedition: 2
• windows7-32: 7

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