Closed Bug 1571006 Opened 5 years ago Closed 5 years ago

Intermittent devtools/client/webconsole/test/mochitest/browser_jsterm_await_paused.js | Test timed out -

Tracking

(firefox-esr60 unaffected, firefox-esr68 unaffected, firefox68 unaffected, firefox69 unaffected, firefox70 fixed)

Status:

RESOLVED FIXED

Milestone:

Firefox 70

Tracking Flags:

Tracking

Status

firefox-esr60

---

unaffected

firefox-esr68

---

unaffected

firefox68

---

unaffected

firefox69

---

unaffected

firefox70

---

fixed

People

(Reporter: intermittent-bug-filer, Assigned: nchevobbe)

References

(Regression)

Details

(Keywords: intermittent-failure, regression, Whiteboard: [stockwell unknown])

Attachments

(1 file)

Bug 1571006 - Fix intermittent in browser_jsterm_await_paused. r=Honza. 5 years ago Nicolas Chevobbe [:nchevobbe] 47 bytes, text/x-phabricator-request		Details \| Review

Treeherder Bug Filer

Reporter

Description

•

5 years ago

treeherder

Filed by: nerli [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=259605849&repo=autoland
Full log: https://queue.taskcluster.net/v1/task/E44gUCk8SRW4FF4VRZG28A/runs/0/artifacts/public/logs/live_backing.log
Reftest URL: https://hg.mozilla.org/mozilla-central/raw-file/tip/layout/tools/reftest/reftest-analyzer.xhtml#logurl=https://queue.taskcluster.net/v1/task/E44gUCk8SRW4FF4VRZG28A/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1

[task 2019-08-02T14:34:42.448Z] 14:34:42 [task 2019-08-02T14:34:44.764Z] 14:34:44 [task 2019-08-02T14:35:27.465Z] 14:35:27 [task 2019-08-02T14:35:27.554Z] 14:35:27 [task 2019-08-02T14:35:27.554Z] 14:35:27 [task 2019-08-02T14:35:27.554Z] 14:35:27 [task 2019-08-02T14:35:27.554Z] 14:35:27 [task 2019-08-02T14:35:27.554Z] 14:35:27 [task 2019-08-02T14:35:27.554Z] 14:35:27 [task 2019-08-02T14:35:27.554Z] 14:35:27 [task 2019-08-02T14:35:27.555Z] 14:35:27 [task 2019-08-02T14:35:27.555Z] 14:35:27 [task 2019-08-02T14:35:27.555Z] 14:35:27 [task 2019-08-02T14:35:27.555Z] 14:35:27 [task 2019-08-02T14:35:27.555Z] 14:35:27 [task 2019-08-02T14:35:27.555Z] 14:35:27 [task 2019-08-02T14:35:27.555Z] 14:35:27 [task 2019-08-02T14:35:27.556Z] 14:35:27 [task 2019-08-02T14:35:27.556Z] 14:35:27 [task 2019-08-02T14:35:27.556Z] 14:35:27 [task 2019-08-02T14:35:27.556Z] 14:35:27 [task 2019-08-02T14:35:27.556Z] 14:35:27 [task 2019-08-02T14:35:27.556Z] 14:35:27 [task 2019-08-02T14:35:27.556Z] 14:35:27 [task 2019-08-02T14:35:27.556Z] 14:35:27 [task 2019-08-02T14:35:27.556Z] 14:35:27 [task 2019-08-02T14:35:27.556Z] 14:35:27 [task 2019-08-02T14:35:27.556Z] 14:35:27 [task 2019-08-02T14:35:27.556Z] 14:35:27 [task 2019-08-02T14:35:27.556Z] 14:35:27 [task 2019-08-02T14:35:27.557Z] 14:35:27 [task 2019-08-02T14:35:27.557Z] 14:35:27 [task 2019-08-02T14:35:27.557Z] 14:35:27 [task 2019-08-02T14:35:27.557Z] 14:35:27 [task 2019-08-02T14:35:27.557Z] 14:35:27 [task 2019-08-02T14:35:27.557Z] 14:35:27 [task 2019-08-02T14:35:27.557Z] 14:35:27 [task 2019-08-02T14:35:27.557Z] 14:35:27 [task 2019-08-02T14:35:27.557Z] 14:35:27 [task 2019-08-02T14:35:27.557Z] 14:35:27 [task 2019-08-02T14:35:27.557Z] 14:35:27 [task 2019-08-02T14:35:27.557Z] 14:35:27 [task 2019-08-02T14:35:27.557Z] 14:35:27 [task 2019-08-02T14:35:27.700Z] 14:35:27 [task 2019-08-02T14:35:27.700Z] 14:35:27 [task 2019-08-02T14:35:27.718Z] 14:35:27 [task 2019-08-02T14:35:27.720Z] 14:35:27 [task 2019-08-02T14:35:27.780Z] 14:35:27 [task 2019-08-02T14:35:27.781Z] 14:35:27 INFO - TEST-START | devtools/client/webconsole/test/mochitest/browser_jsterm_await_paused.js
INFO - GECKO(920) | JavaScript error: debugger eval code, line 3: ReferenceError: foo is not defined
INFO - TEST-INFO | started process screenshot
INFO - TEST-INFO | screenshot: exit 0
INFO - Buffered messages logged at 14:34:42
INFO - Entering test bound
INFO - Console message: [JavaScript Error: "uncaught exception: Object" {file: "resource://testing-common/PromiseTestUtils.jsm" line: 112}]
INFO - Adding a new tab with URL: data:text/html;charset=utf-8,Web Console test top-level await when debugger paused
INFO - Tab added and finished loading
INFO - Opening the toolbox
INFO - Toolbox opened and focused
INFO - Buffered messages logged at 14:34:43
INFO - Waiting for state change: paused
INFO - Finished waiting for state change: paused
INFO - Finished waiting for state change: paused
INFO - Waiting for state change: selected source
INFO - Finished waiting for state change: selected source
INFO - Matched a message with text: ""smoke"", all messages received.
INFO - Resuming from 4
INFO - Buffered messages logged at 14:34:44
INFO - Console message: [JavaScript Error: "ReferenceError: foo is not defined" {file: "debugger eval code" line: 3}]
INFO - @debugger eval code:3:34
INFO - setTimeout handler*@debugger eval code:3:15
INFO - @debugger eval code:2:16
INFO - @debugger eval code:5:3
INFO - getEvalResult@resource://devtools/server/actors/webconsole/eval-with-debugger.js:153:28
INFO - exports.evalWithDebugger@resource://devtools/server/actors/webconsole/eval-with-debugger.js:124:31
INFO - evaluateJS@resource://devtools/server/actors/webconsole.js:1162:38
INFO - evaluateJSAsync@resource://devtools/server/actors/webconsole.js:1061:27
INFO - handler@resource://devtools/shared/protocol/Actor.js:154:37
INFO - onPacket@resource://devtools/server/main.js:1402:58
INFO - receiveMessage@resource://devtools/shared/transport/child-transport.js:66:16
INFO - MessageListener.receiveMessage*_addListener@resource://devtools/shared/transport/child-transport.js:40:14
INFO - ready@resource://devtools/shared/transport/child-transport.js:57:10
INFO - _onConnection@resource://devtools/server/main.js:963:15
INFO - connectToParent@resource://devtools/server/main.js:323:17
INFO - onConnect<@resource://devtools/server/startup/frame.js:62:35
INFO - exports.makeInfallible/<@resource://devtools/shared/ThreadSafeDevToolsUtils.js:111:22
INFO - MessageListener.receiveMessage*@resource://devtools/server/startup/frame.js:92:23
INFO - @resource://devtools/server/startup/frame.js:174:5
INFO -
INFO - Buffered messages finished
INFO - TEST-UNEXPECTED-FAIL | devtools/client/webconsole/test/mochitest/browser_jsterm_await_paused.js | Test timed out -
INFO - Removing tab.
INFO - Waiting for event: 'TabClose' on [object XULElement].
INFO - Got event: 'TabClose' on [object XULElement].
INFO - Tab removed and finished closing
INFO - GECKO(920) | MEMORY STAT | vsize 2104334MB | vsizeMaxContiguous 65530564MB | residentFast 354MB | heapAllocated 116MB
INFO - TEST-OK | devtools/client/webconsole/test/mochitest/browser_jsterm_await_paused.js | took 45334ms

Noemi Erli[:noemi_erli]

Comment 1

•

5 years ago

https://treeherder.mozilla.org/#/jobs?repo=autoland&resultStatus=testfailed%2Cbusted%2Cexception&revision=378f98a28d1b0608c9630c4eeebb1541886c5de9&searchStr=test%2Cverify&selectedJob=259605849

This is also a regression from Bug 1568143

Flags: needinfo?(nchevobbe)

Regressed by: 1568143

Summary: Intermittent devtools/client/webconsole/test/mochitest/browser_jsterm_await_paused.js | Test timed out - → Intermittent TV devtools/client/webconsole/test/mochitest/browser_jsterm_await_paused.js | Test timed out -

Bogdan Tara[:bogdan_tara | bogdant]

Comment 2

•

5 years ago

Seeing this on Tier 1: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=259757311&repo=mozilla-central&lineNumber=3508

[task 2019-08-03T11:09:22.163Z] 11:09:22 INFO - TEST-START | devtools/client/webconsole/test/mochitest/browser_jsterm_await_paused.js
[task 2019-08-03T11:09:24.573Z] 11:09:24 INFO - GECKO(7332) | JavaScript error: debugger eval code, line 3: ReferenceError: foo is not defined
[task 2019-08-03T11:10:07.180Z] 11:10:07 INFO - TEST-INFO | started process screenshot
[task 2019-08-03T11:10:07.264Z] 11:10:07 INFO - TEST-INFO | screenshot: exit 0
[task 2019-08-03T11:10:07.264Z] 11:10:07 INFO - Buffered messages logged at 11:09:22
[task 2019-08-03T11:10:07.264Z] 11:10:07 INFO - Entering test bound
[task 2019-08-03T11:10:07.264Z] 11:10:07 INFO - Console message: [JavaScript Error: "uncaught exception: Object" {file: "resource://testing-common/PromiseTestUtils.jsm" line: 112}]
[task 2019-08-03T11:10:07.264Z] 11:10:07 INFO - Adding a new tab with URL: data:text/html;charset=utf-8,Web Console test top-level await when debugger paused
[task 2019-08-03T11:10:07.264Z] 11:10:07 INFO - Tab added and finished loading
[task 2019-08-03T11:10:07.265Z] 11:10:07 INFO - Opening the toolbox
[task 2019-08-03T11:10:07.265Z] 11:10:07 INFO - Toolbox opened and focused
[task 2019-08-03T11:10:07.265Z] 11:10:07 INFO - Console message: [JavaScript Warning: "Use of nsIFile in content process is deprecated." {file: "resource://gre/modules/NetUtil.jsm" line: 253}]
[task 2019-08-03T11:10:07.265Z] 11:10:07 INFO - Buffered messages logged at 11:09:23
[task 2019-08-03T11:10:07.265Z] 11:10:07 INFO - Waiting for state change: paused
[task 2019-08-03T11:10:07.265Z] 11:10:07 INFO - Finished waiting for state change: paused
[task 2019-08-03T11:10:07.265Z] 11:10:07 INFO - Finished waiting for state change: paused
[task 2019-08-03T11:10:07.265Z] 11:10:07 INFO - Waiting for state change: selected source
[task 2019-08-03T11:10:07.265Z] 11:10:07 INFO - Finished waiting for state change: selected source
[task 2019-08-03T11:10:07.265Z] 11:10:07 INFO - Matched a message with text: ""smoke"", all messages received.
[task 2019-08-03T11:10:07.266Z] 11:10:07 INFO - Resuming from 4
[task 2019-08-03T11:10:07.266Z] 11:10:07 INFO - Buffered messages logged at 11:09:24
[task 2019-08-03T11:10:07.266Z] 11:10:07 INFO - Console message: [JavaScript Error: "ReferenceError: foo is not defined" {file: "debugger eval code" line: 3}]
[task 2019-08-03T11:10:07.266Z] 11:10:07 INFO - @debugger eval code:3:34
[task 2019-08-03T11:10:07.266Z] 11:10:07 INFO - setTimeout handler*@debugger eval code:3:15
[task 2019-08-03T11:10:07.266Z] 11:10:07 INFO - @debugger eval code:2:16
[task 2019-08-03T11:10:07.266Z] 11:10:07 INFO - @debugger eval code:5:3
[task 2019-08-03T11:10:07.266Z] 11:10:07 INFO - getEvalResult@resource://devtools/server/actors/webconsole/eval-with-debugger.js:153:28
[task 2019-08-03T11:10:07.266Z] 11:10:07 INFO - exports.evalWithDebugger@resource://devtools/server/actors/webconsole/eval-with-debugger.js:124:31
[task 2019-08-03T11:10:07.266Z] 11:10:07 INFO - evaluateJS@resource://devtools/server/actors/webconsole.js:1162:38
[task 2019-08-03T11:10:07.266Z] 11:10:07 INFO - evaluateJSAsync@resource://devtools/server/actors/webconsole.js:1061:27
[task 2019-08-03T11:10:07.266Z] 11:10:07 INFO - handler@resource://devtools/shared/protocol/Actor.js:154:37
[task 2019-08-03T11:10:07.266Z] 11:10:07 INFO - onPacket@resource://devtools/server/main.js:1402:58
[task 2019-08-03T11:10:07.266Z] 11:10:07 INFO - receiveMessage@resource://devtools/shared/transport/child-transport.js:66:16
[task 2019-08-03T11:10:07.266Z] 11:10:07 INFO - MessageListener.receiveMessage*_addListener@resource://devtools/shared/transport/child-transport.js:40:14
[task 2019-08-03T11:10:07.266Z] 11:10:07 INFO - ready@resource://devtools/shared/transport/child-transport.js:57:10
[task 2019-08-03T11:10:07.266Z] 11:10:07 INFO - _onConnection@resource://devtools/server/main.js:963:15
[task 2019-08-03T11:10:07.266Z] 11:10:07 INFO - connectToParent@resource://devtools/server/main.js:323:17
[task 2019-08-03T11:10:07.266Z] 11:10:07 INFO - onConnect<@resource://devtools/server/startup/frame.js:62:35
[task 2019-08-03T11:10:07.266Z] 11:10:07 INFO - exports.makeInfallible/<@resource://devtools/shared/ThreadSafeDevToolsUtils.js:111:22
[task 2019-08-03T11:10:07.266Z] 11:10:07 INFO - MessageListener.receiveMessage*@resource://devtools/server/startup/frame.js:92:23
[task 2019-08-03T11:10:07.266Z] 11:10:07 INFO - @resource://devtools/server/startup/frame.js:174:5
[task 2019-08-03T11:10:07.266Z] 11:10:07 INFO -
[task 2019-08-03T11:10:07.267Z] 11:10:07 INFO - Buffered messages finished
[task 2019-08-03T11:10:07.267Z] 11:10:07 INFO - TEST-UNEXPECTED-FAIL | devtools/client/webconsole/test/mochitest/browser_jsterm_await_paused.js | Test timed out -
[task 2019-08-03T11:10:07.865Z] 11:10:07 INFO - Removing tab.
[task 2019-08-03T11:10:07.865Z] 11:10:07 INFO - Waiting for event: 'TabClose' on [object XULElement].
[task 2019-08-03T11:10:07.874Z] 11:10:07 INFO - Got event: 'TabClose' on [object XULElement].
[task 2019-08-03T11:10:07.893Z] 11:10:07 INFO - Tab removed and finished closing
[task 2019-08-03T11:10:07.952Z] 11:10:07 INFO - GECKO(7332) | MEMORY STAT | vsize 2104822MB | vsizeMaxContiguous 65152768MB | residentFast 550MB | heapAllocated 143MB
[task 2019-08-03T11:10:07.952Z] 11:10:07 INFO - TEST-OK | devtools/client/webconsole/test/mochitest/browser_jsterm_await_paused.js | took 45794ms

Natalia Csoregi [:nataliaCs]

Updated

•

5 years ago

Summary: Intermittent TV devtools/client/webconsole/test/mochitest/browser_jsterm_await_paused.js | Test timed out - → Intermittent devtools/client/webconsole/test/mochitest/browser_jsterm_await_paused.js | Test timed out -

Comment hidden (Intermittent Failures Robot)

Razvan Maries

Comment 4

•

5 years ago

There are 46 total failures in the last 7 days: https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?startday=2019-08-04&endday=2019-08-11&tree=trunk&bug=1571006

Recent failure log: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=261017429&repo=mozilla-central&lineNumber=15353

Flags: needinfo?(nchevobbe)

Whiteboard: [stockwell needswork:owner]

Razvan Maries

Comment 5

•

5 years ago

Nicolas, could you assign this to someone?

Flags: needinfo?(nchevobbe)

Comment hidden (Intermittent Failures Robot)

Nicolas Chevobbe [:nchevobbe]

Assignee

Comment 7

•

5 years ago

Mh, looking at the test again I'm maybe seeing what could happen.
I'm on it

Assignee: nobody → nchevobbe

Status: NEW → ASSIGNED

Flags: needinfo?(nchevobbe)

Nicolas Chevobbe [:nchevobbe]

Assignee

Comment 8

•

5 years ago

Attached file Bug 1571006 - Fix intermittent in browser_jsterm_await_paused. r=Honza. — Details

Since we're having a setTimeout in which we call
the Promise resolve function with a local variable
for a script we're paused in, it might occur that
when resuming, the setTimeout delay would be big
enough for the local variable to not be available
anymore.
So here, we compute the results directly in the
Promise callback body so we're sure the local
variable still exists.

Comment hidden (Intermittent Failures Robot)

Pulsebot

Comment 10

•

5 years ago

Pushed by nchevobbe@mozilla.com:
https://hg.mozilla.org/integration/autoland/rev/904ec0fa15ae
Fix intermittent in browser_jsterm_await_paused. r=Honza.

Oana Pop-Rus

Comment 11

•

5 years ago

bugherder

https://hg.mozilla.org/mozilla-central/rev/904ec0fa15ae

Status: ASSIGNED → RESOLVED

Closed: 5 years ago

status-firefox70: --- → fixed

Resolution: --- → FIXED

Target Milestone: --- → Firefox 70

Ryan VanderMeulen [:RyanVM]

Updated

•

5 years ago

status-firefox68: --- → unaffected

status-firefox69: --- → unaffected

status-firefox-esr60: --- → unaffected

status-firefox-esr68: --- → unaffected

BugBot [:suhaib / :marco/ :calixte]

Updated

•

2 years ago

Has Regression Range: --- → yes

You need to log in before you can comment on or make changes to this bug.

Bugzilla

Quick Search

Intermittent devtools/client/webconsole/test/mochitest/browser_jsterm_await_paused.js | Test timed out -

Categories

(DevTools :: Console, defect, P5)

Tracking

(firefox-esr60 unaffected, firefox-esr68 unaffected, firefox68 unaffected, firefox69 unaffected, firefox70 fixed)

People

(Reporter: intermittent-bug-filer, Assigned: nchevobbe)

References

(Regression)

Details

(Keywords: intermittent-failure, regression, Whiteboard: [stockwell unknown])

Crash Data

Security

(public)

User Story

Attachments

(1 file)

Description

Comment 1

Comment 2

Updated

Comment 3

Comment 4

Comment 5

Comment 6

Comment 7

Comment 8

Comment 9

Comment 10

Comment 11

Updated

Updated

Attachment

General

Description

File Name

Content Type