Open Bug 1629729 Opened 4 years ago Updated 2 years ago

Intermittent [tier2] devtools/client/responsive/test/browser/browser_container_tab.js | Test timed out -

Categories

(DevTools :: Responsive Design Mode, defect, P5)

Product:
DevTools
Component:
Responsive Design Mode
Type:
defect

Tracking

(firefox78 affected)

Status:
REOPENED
Tracking Flags:
Tracking Status
firefox78 --- affected

People

(Reporter: intermittent-bug-filer, Unassigned)

References

(Regression)

Details

(Keywords: intermittent-failure, leave-open, regression, Whiteboard: [retriggered][stockwell disabled])

Attachments

(2 files)

Bug 1629729 - Use BrowserTestUtils.loadURI to navigate to a new URL.
(deleted), text/x-phabricator-request
Details
Bug 1629729 - temporarily disable browser_container_tab.js on linux r?#intermittent-reviewers
(deleted), text/x-phabricator-request
Details

Filed by: opoprus [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=297439188&repo=mozilla-central
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/agVFE0ZATyq-hHCKGE25MQ/runs/0/artifacts/public/logs/live_backing.log

TEST-START | devtools/client/responsive/test/browser/browser_container_tab.js
[task 2020-04-14T00:05:48.895Z] 00:05:48 INFO - Entering test bound
[task 2020-04-14T00:05:48.923Z] 00:05:48 INFO - Adding a new tab with URL: about:newtab
[task 2020-04-14T00:05:49.872Z] 00:05:49 INFO - GECKO(19594) | [CodeCoverage] Setting handlers for process 19962.
[task 2020-04-14T00:05:51.346Z] 00:05:51 INFO - Tab added and finished loading
[task 2020-04-14T00:05:51.346Z] 00:05:51 INFO - TEST-PASS | devtools/client/responsive/test/browser/browser_container_tab.js | Tab's container ID is correct -
[task 2020-04-14T00:05:51.346Z] 00:05:51 INFO - Opening responsive design mode
[task 2020-04-14T00:05:52.128Z] 00:05:52 INFO - GECKO(19594) | console.log: "[DISPATCH] action type:" "CHANGE_DISPLAY_PIXEL_RATIO"
[task 2020-04-14T00:05:52.128Z] 00:05:52 INFO - GECKO(19594) | console.log: "[DISPATCH] action type:" "ADD_VIEWPORT"
[task 2020-04-14T00:05:52.770Z] 00:05:52 INFO - Responsive design mode opened
[task 2020-04-14T00:05:52.771Z] 00:05:52 INFO - Waiting for state predicate "state =>
[task 2020-04-14T00:05:52.771Z] 00:05:52 INFO - state.viewports.length == 1 &&
[task 2020-04-14T00:05:52.771Z] 00:05:52 INFO - state.devices.listState == localTypes.loadableState.LOADED"
[task 2020-04-14T00:05:52.787Z] 00:05:52 INFO - GECKO(19594) | console.log: "[DISPATCH] action type:" "LOAD_DEVICE_LIST_START"
[task 2020-04-14T00:05:52.909Z] 00:05:52 INFO - GECKO(19594) | console.log: "[DISPATCH] action type:" "ADD_DEVICE_TYPE"
[task 2020-04-14T00:05:52.925Z] 00:05:52 INFO - GECKO(19594) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-04-14T00:05:52.941Z] 00:05:52 INFO - GECKO(19594) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-04-14T00:05:52.949Z] 00:05:52 INFO - GECKO(19594) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-04-14T00:05:52.969Z] 00:05:52 INFO - GECKO(19594) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-04-14T00:05:52.977Z] 00:05:52 INFO - GECKO(19594) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-04-14T00:05:52.997Z] 00:05:52 INFO - GECKO(19594) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-04-14T00:05:53.018Z] 00:05:53 INFO - GECKO(19594) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-04-14T00:05:53.033Z] 00:05:53 INFO - GECKO(19594) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-04-14T00:05:53.041Z] 00:05:53 INFO - GECKO(19594) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-04-14T00:05:53.048Z] 00:05:53 INFO - GECKO(19594) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-04-14T00:05:53.056Z] 00:05:53 INFO - GECKO(19594) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-04-14T00:05:53.064Z] 00:05:53 INFO - GECKO(19594) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-04-14T00:05:53.079Z] 00:05:53 INFO - GECKO(19594) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-04-14T00:05:53.083Z] 00:05:53 INFO - GECKO(19594) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-04-14T00:05:53.099Z] 00:05:53 INFO - GECKO(19594) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-04-14T00:05:53.100Z] 00:05:53 INFO - GECKO(19594) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-04-14T00:05:53.116Z] 00:05:53 INFO - GECKO(19594) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-04-14T00:05:53.123Z] 00:05:53 INFO - GECKO(19594) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-04-14T00:05:53.131Z] 00:05:53 INFO - GECKO(19594) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-04-14T00:05:53.146Z] 00:05:53 INFO - GECKO(19594) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-04-14T00:05:53.162Z] 00:05:53 INFO - GECKO(19594) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-04-14T00:05:53.170Z] 00:05:53 INFO - GECKO(19594) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-04-14T00:05:53.177Z] 00:05:53 INFO - GECKO(19594) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-04-14T00:05:53.193Z] 00:05:53 INFO - GECKO(19594) | console.log: "[DISPATCH] action type:" "ADD_DEVICE_TYPE"
[task 2020-04-14T00:05:53.194Z] 00:05:53 INFO - GECKO(19594) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-04-14T00:05:53.215Z] 00:05:53 INFO - GECKO(19594) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-04-14T00:05:53.215Z] 00:05:53 INFO - GECKO(19594) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-04-14T00:05:53.231Z] 00:05:53 INFO - GECKO(19594) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-04-14T00:05:53.234Z] 00:05:53 INFO - GECKO(19594) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-04-14T00:05:53.241Z] 00:05:53 INFO - GECKO(19594) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-04-14T00:05:53.249Z] 00:05:53 INFO - GECKO(19594) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-04-14T00:05:53.257Z] 00:05:53 INFO - GECKO(19594) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-04-14T00:05:53.264Z] 00:05:53 INFO - GECKO(19594) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-04-14T00:05:53.280Z] 00:05:53 INFO - GECKO(19594) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-04-14T00:05:53.287Z] 00:05:53 INFO - GECKO(19594) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-04-14T00:05:53.295Z] 00:05:53 INFO - GECKO(19594) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-04-14T00:05:53.298Z] 00:05:53 INFO - GECKO(19594) | console.log: "[DISPATCH] action type:" "ADD_DEVICE_TYPE"
[task 2020-04-14T00:05:53.306Z] 00:05:53 INFO - GECKO(19594) | [CodeCoverage] Setting handlers for process 20000.
[task 2020-04-14T00:05:53.306Z] 00:05:53 INFO - GECKO(19594) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-04-14T00:05:53.322Z] 00:05:53 INFO - GECKO(19594) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-04-14T00:05:53.323Z] 00:05:53 INFO - GECKO(19594) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-04-14T00:05:53.331Z] 00:05:53 INFO - GECKO(19594) | console.log: "[DISPATCH] action type:" "ADD_DEVICE_TYPE"
[task 2020-04-14T00:05:53.346Z] 00:05:53 INFO - GECKO(19594) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-04-14T00:05:53.348Z] 00:05:53 INFO - GECKO(19594) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-04-14T00:05:53.363Z] 00:05:53 INFO - GECKO(19594) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-04-14T00:05:53.365Z] 00:05:53 INFO - GECKO(19594) | console.log: "[DISPATCH] action type:" "ADD_DEVICE_TYPE"
[task 2020-04-14T00:05:53.380Z] 00:05:53 INFO - GECKO(19594) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-04-14T00:05:53.396Z] 00:05:53 INFO - GECKO(19594) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-04-14T00:05:53.412Z] 00:05:53 INFO - GECKO(19594) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-04-14T00:05:53.413Z] 00:05:53 INFO - GECKO(19594) | console.log: "[DISPATCH] action type:" "ADD_DEVICE_TYPE"
[task 2020-04-14T00:05:53.421Z] 00:05:53 INFO - GECKO(19594) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-04-14T00:05:53.429Z] 00:05:53 INFO - GECKO(19594) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-04-14T00:05:53.436Z] 00:05:53 INFO - GECKO(19594) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-04-14T00:05:53.440Z] 00:05:53 INFO - GECKO(19594) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-04-14T00:05:53.447Z] 00:05:53 INFO - GECKO(19594) | console.log: "[DISPATCH] action type:" "ADD_DEVICE_TYPE"
[task 2020-04-14T00:05:53.455Z] 00:05:53 INFO - GECKO(19594) | console.log: "[DISPATCH] action type:" "LOAD_DEVICE_LIST_END"
[task 2020-04-14T00:05:53.462Z] 00:05:53 INFO - Found state predicate "state =>
[task 2020-04-14T00:05:53.462Z] 00:05:53 INFO - state.viewports.length == 1 &&
[task 2020-04-14T00:05:53.462Z] 00:05:53 INFO - state.devices.listState == localTypes.loadableState.LOADED"
[task 2020-04-14T00:06:33.899Z] 00:06:33 INFO - Longer timeout required, waiting longer... Remaining timeouts: 1
[task 2020-04-14T00:07:18.901Z] 00:07:18 INFO - TEST-INFO | started process screentopng
[task 2020-04-14T00:07:19.063Z] 00:07:19 INFO - TEST-INFO | screentopng: exit 0
[task 2020-04-14T00:07:19.063Z] 00:07:19 INFO - TEST-UNEXPECTED-FAIL | devtools/client/responsive/test/browser/browser_container_tab.js | Test timed out -
[task 2020-04-14T00:07:19.063Z] 00:07:19 INFO - GECKO(19594) | [CodeCoverage] Requested flush for 19594.
[task 2020-04-14T00:07:19.152Z] 00:07:19 INFO - GECKO(19594) | [CodeCoverage] flush completed.
[task 2020-04-14T00:07:19.999Z] 00:07:19 INFO - GECKO(19594) | [CodeCoverage] JS flush completed.
[task 2020-04-14T00:07:20.000Z] 00:07:19 INFO - GECKO(19594) | [CodeCoverage] Requested flush for 19676.
[task 2020-04-14T00:07:20.000Z] 00:07:20 INFO - GECKO(19594) | [CodeCoverage] Requested flush for 19814.
[task 2020-04-14T00:07:20.000Z] 00:07:20 INFO - GECKO(19594) | [CodeCoverage] Requested flush for 19883.
[task 2020-04-14T00:07:20.000Z] 00:07:20 INFO - GECKO(19594) | [CodeCoverage] Requested flush for 19962.
[task 2020-04-14T00:07:20.000Z] 00:07:20 INFO - GECKO(19594) | [CodeCoverage] Requested flush for 20000.
[task 2020-04-14T00:07:20.243Z] 00:07:20 INFO - GECKO(19594) | [CodeCoverage] flush completed.
[task 2020-04-14T00:07:20.324Z] 00:07:20 INFO - GECKO(19594) | [CodeCoverage] JS flush completed.
[task 2020-04-14T00:07:20.566Z] 00:07:20 INFO - GECKO(19594) | [CodeCoverage] flush completed.
[task 2020-04-14T00:07:20.707Z] 00:07:20 INFO - GECKO(19594) | [CodeCoverage] JS flush completed.
[task 2020-04-14T00:07:20.949Z] 00:07:20 INFO - GECKO(19594) | [CodeCoverage] flush completed.
[task 2020-04-14T00:07:21.010Z] 00:07:21 INFO - GECKO(19594) | [CodeCoverage] JS flush completed.
[task 2020-04-14T00:07:21.256Z] 00:07:21 INFO - GECKO(19594) | [CodeCoverage] flush completed.
[task 2020-04-14T00:07:21.352Z] 00:07:21 INFO - GECKO(19594) | [CodeCoverage] JS flush completed.
[task 2020-04-14T00:07:21.602Z] 00:07:21 INFO - GECKO(19594) | [CodeCoverage] flush completed.
[task 2020-04-14T00:07:21.646Z] 00:07:21 INFO - GECKO(19594) | [CodeCoverage] JS flush completed.
[task 2020-04-14T00:07:21.661Z] 00:07:21 INFO - Removing tab.
[task 2020-04-14T00:07:21.661Z] 00:07:21 INFO - Waiting for event: 'TabClose' on [object XULElement].
[task 2020-04-14T00:07:21.718Z] 00:07:21 INFO - Got event: 'TabClose' on [object XULElement].
[task 2020-04-14T00:07:21.734Z] 00:07:21 INFO - Tab removed and finished closing
[task 2020-04-14T00:07:21.768Z] 00:07:21 INFO - TEST-PASS | devtools/client/responsive/test/browser/browser_container_tab.js | The main process DevToolsServer has no pending connection when the test ends -
[task 2020-04-14T00:07:21.804Z] 00:07:21 INFO - GECKO(19594) | MEMORY STAT vsizeMaxContiguous not supported in this build configuration.
[task 2020-04-14T00:07:21.804Z] 00:07:21 INFO - GECKO(19594) | MEMORY STAT | vsize 3226MB | residentFast 508MB | heapAllocated 119MB
[task 2020-04-14T00:07:21.804Z] 00:07:21 INFO - TEST-OK | devtools/client/responsive/test/browser/browser_container_tab.js | took 93331ms
[task 2020-04-14T00:07:21.845Z] 00:07:21 INFO - checking window state

Summary: Intermittent devtools/client/responsive/test/browser/browser_container_tab.js | Test timed out - → Intermittent [tier2] devtools/client/responsive/test/browser/browser_container_tab.js | Test timed out -
Has Regression Range: --- → yes
Keywords: regression

Thanks for the ping. I'll switch the needinfo over to my Mozilla account to put it on my radar.

Flags: needinfo?(tigleym) → needinfo?(mtigley)

There are 22 failures associated to this bug in the last 7 days. These are occurring on linux1804-64-ccov opt builds.

log: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=299965077&repo=mozilla-central&lineNumber=2606

Micah any updates on this?

Whiteboard: [retriggered] → [retriggered][stockwell needswork]

Micah, any update on this regarding a fix for this on Linux with code coverage? Or does it need to be disabled?

Apologies for the delay on this. I'll be investigating this issue now.

https://treeherder.mozilla.org/#/jobs?repo=try&revision=2762899b03ac5275cf438f976cf36085d22dd049

Assignee: nobody → mtigley
Status: NEW → ASSIGNED
Flags: needinfo?(mtigley)
Pushed by mtigley@mozilla.com:
https://hg.mozilla.org/integration/autoland/rev/1ef4c046c263
Use BrowserTestUtils.loadURI to navigate to a new URL. r=bradwerth

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

Status: ASSIGNED → RESOLVED
Closed: 4 years ago
status-firefox78: --- → fixed
Resolution: --- → FIXED
Target Milestone: --- → Firefox 78

This is still happening: https://treeherder.mozilla.org/#/jobs?repo=mozilla-central&group_state=expanded&selectedTaskRun=dVfo94brSGiWvIq--JGang-0&resultStatus=testfailed%2Cbusted%2Cexception&classifiedState=unclassified

Log link: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=301848143&repo=mozilla-central&lineNumber=2333

Log snippet:
''' [task 2020-05-12T05:31:51.938Z] 05:31:51 INFO - TEST-START | devtools/client/responsive/test/browser/browser_container_tab.js
[task 2020-05-12T05:31:51.962Z] 05:31:51 INFO - Entering test bound
[task 2020-05-12T05:31:51.965Z] 05:31:51 INFO - Adding a new tab with URL: about:newtab
[task 2020-05-12T05:31:52.204Z] 05:31:52 INFO - GECKO(2440) | [CodeCoverage] Setting handlers for process 2826.
[task 2020-05-12T05:31:53.162Z] 05:31:53 INFO - GECKO(2440) | [CodeCoverage] Setting handlers for process 2849.
[task 2020-05-12T05:31:54.757Z] 05:31:54 INFO - Tab added and finished loading
[task 2020-05-12T05:31:54.757Z] 05:31:54 INFO - TEST-PASS | devtools/client/responsive/test/browser/browser_container_tab.js | Tab's container ID is correct -
[task 2020-05-12T05:31:54.757Z] 05:31:54 INFO - Opening responsive design mode
[task 2020-05-12T05:31:55.060Z] 05:31:55 INFO - GECKO(2440) | console.log: "[DISPATCH] action type:" "CHANGE_DISPLAY_PIXEL_RATIO"
[task 2020-05-12T05:31:55.068Z] 05:31:55 INFO - GECKO(2440) | console.log: "[DISPATCH] action type:" "ADD_VIEWPORT"
[task 2020-05-12T05:31:55.431Z] 05:31:55 INFO - Responsive design mode opened
[task 2020-05-12T05:31:55.432Z] 05:31:55 INFO - Waiting for state predicate "state =>
[task 2020-05-12T05:31:55.432Z] 05:31:55 INFO - state.viewports.length == 1 &&
[task 2020-05-12T05:31:55.432Z] 05:31:55 INFO - state.devices.listState == localTypes.loadableState.LOADED"
[task 2020-05-12T05:31:55.432Z] 05:31:55 INFO - GECKO(2440) | console.log: "[DISPATCH] action type:" "LOAD_DEVICE_LIST_START"
[task 2020-05-12T05:31:55.493Z] 05:31:55 INFO - GECKO(2440) | console.log: "[DISPATCH] action type:" "ADD_DEVICE_TYPE"
[task 2020-05-12T05:31:55.509Z] 05:31:55 INFO - GECKO(2440) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-05-12T05:31:55.525Z] 05:31:55 INFO - GECKO(2440) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-05-12T05:31:55.541Z] 05:31:55 INFO - GECKO(2440) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-05-12T05:31:55.565Z] 05:31:55 INFO - GECKO(2440) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-05-12T05:31:55.581Z] 05:31:55 INFO - GECKO(2440) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-05-12T05:31:55.597Z] 05:31:55 INFO - GECKO(2440) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-05-12T05:31:55.617Z] 05:31:55 INFO - GECKO(2440) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-05-12T05:31:55.625Z] 05:31:55 INFO - GECKO(2440) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-05-12T05:31:55.640Z] 05:31:55 INFO - GECKO(2440) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-05-12T05:31:55.648Z] 05:31:55 INFO - GECKO(2440) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-05-12T05:31:55.655Z] 05:31:55 INFO - GECKO(2440) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-05-12T05:31:55.671Z] 05:31:55 INFO - GECKO(2440) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-05-12T05:31:55.687Z] 05:31:55 INFO - GECKO(2440) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-05-12T05:31:55.687Z] 05:31:55 INFO - GECKO(2440) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-05-12T05:31:55.703Z] 05:31:55 INFO - GECKO(2440) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-05-12T05:31:55.703Z] 05:31:55 INFO - GECKO(2440) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-05-12T05:31:55.720Z] 05:31:55 INFO - GECKO(2440) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-05-12T05:31:55.728Z] 05:31:55 INFO - GECKO(2440) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-05-12T05:31:55.743Z] 05:31:55 INFO - GECKO(2440) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-05-12T05:31:55.751Z] 05:31:55 INFO - GECKO(2440) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-05-12T05:31:55.774Z] 05:31:55 INFO - GECKO(2440) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-05-12T05:31:55.782Z] 05:31:55 INFO - GECKO(2440) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-05-12T05:31:55.790Z] 05:31:55 INFO - GECKO(2440) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-05-12T05:31:55.805Z] 05:31:55 INFO - GECKO(2440) | console.log: "[DISPATCH] action type:" "ADD_DEVICE_TYPE"
[task 2020-05-12T05:31:55.813Z] 05:31:55 INFO - GECKO(2440) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-05-12T05:31:55.820Z] 05:31:55 INFO - GECKO(2440) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-05-12T05:31:55.828Z] 05:31:55 INFO - GECKO(2440) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-05-12T05:31:55.844Z] 05:31:55 INFO - GECKO(2440) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-05-12T05:31:55.851Z] 05:31:55 INFO - GECKO(2440) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-05-12T05:31:55.867Z] 05:31:55 INFO - GECKO(2440) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-05-12T05:31:55.870Z] 05:31:55 INFO - GECKO(2440) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-05-12T05:31:55.878Z] 05:31:55 INFO - GECKO(2440) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-05-12T05:31:55.894Z] 05:31:55 INFO - GECKO(2440) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-05-12T05:31:55.894Z] 05:31:55 INFO - GECKO(2440) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-05-12T05:31:55.901Z] 05:31:55 INFO - GECKO(2440) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-05-12T05:31:55.909Z] 05:31:55 INFO - GECKO(2440) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-05-12T05:31:55.925Z] 05:31:55 INFO - GECKO(2440) | console.log: "[DISPATCH] action type:" "ADD_DEVICE_TYPE"
[task 2020-05-12T05:31:55.925Z] 05:31:55 INFO - GECKO(2440) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-05-12T05:31:55.933Z] 05:31:55 INFO - GECKO(2440) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-05-12T05:31:55.949Z] 05:31:55 INFO - GECKO(2440) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-05-12T05:31:55.949Z] 05:31:55 INFO - GECKO(2440) | console.log: "[DISPATCH] action type:" "ADD_DEVICE_TYPE"
[task 2020-05-12T05:31:55.965Z] 05:31:55 INFO - GECKO(2440) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-05-12T05:31:55.968Z] 05:31:55 INFO - GECKO(2440) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-05-12T05:31:55.976Z] 05:31:55 INFO - GECKO(2440) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-05-12T05:31:55.984Z] 05:31:55 INFO - GECKO(2440) | console.log: "[DISPATCH] action type:" "ADD_DEVICE_TYPE"
[task 2020-05-12T05:31:55.999Z] 05:31:55 INFO - GECKO(2440) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-05-12T05:31:56.020Z] 05:31:56 INFO - GECKO(2440) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-05-12T05:31:56.036Z] 05:31:56 INFO - GECKO(2440) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-05-12T05:31:56.036Z] 05:31:56 INFO - GECKO(2440) | console.log: "[DISPATCH] action type:" "ADD_DEVICE_TYPE"
[task 2020-05-12T05:31:56.044Z] 05:31:56 INFO - GECKO(2440) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-05-12T05:31:56.051Z] 05:31:56 INFO - GECKO(2440) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-05-12T05:31:56.059Z] 05:31:56 INFO - GECKO(2440) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-05-12T05:31:56.075Z] 05:31:56 INFO - GECKO(2440) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-05-12T05:31:56.076Z] 05:31:56 INFO - GECKO(2440) | console.log: "[DISPATCH] action type:" "ADD_DEVICE_TYPE"
[task 2020-05-12T05:31:56.084Z] 05:31:56 INFO - GECKO(2440) | console.log: "[DISPATCH] action type:" "LOAD_DEVICE_LIST_END"
[task 2020-05-12T05:31:56.099Z] 05:31:56 INFO - Found state predicate "state =>
[task 2020-05-12T05:31:56.100Z] 05:31:56 INFO - state.viewports.length == 1 &&
[task 2020-05-12T05:31:56.100Z] 05:31:56 INFO - state.devices.listState == localTypes.loadableState.LOADED"
[task 2020-05-12T05:31:57.380Z] 05:31:57 INFO - GECKO(2440) | [CodeCoverage] Setting handlers for process 2902.
[task 2020-05-12T05:32:36.963Z] 05:32:36 INFO - Longer timeout required, waiting longer... Remaining timeouts: 1
[task 2020-05-12T05:33:21.969Z] 05:33:21 INFO - TEST-INFO | started process screentopng
[task 2020-05-12T05:33:22.192Z] 05:33:22 INFO - TEST-INFO | screentopng: exit 0
[task 2020-05-12T05:33:22.193Z] 05:33:22 INFO - TEST-UNEXPECTED-FAIL | devtools/client/responsive/test/browser/browser_container_tab.js | Test timed out -
[task 2020-05-12T05:33:22.193Z] 05:33:22 INFO - Removing tab.
[task 2020-05-12T05:33:22.193Z] 05:33:22 INFO - Waiting for event: 'TabClose' on [object XULElement].
[task 2020-05-12T05:33:22.193Z] 05:33:22 INFO - Got event: 'TabClose' on [object XULElement].
[task 2020-05-12T05:33:22.193Z] 05:33:22 INFO - Tab removed and finished closing '''

Status: RESOLVED → REOPENED
Flags: needinfo?(gl)
Resolution: FIXED → ---
Flags: needinfo?(gl) → needinfo?(mtigley)

There were 21 total failures in the past week, on linux1804-64-ccov opt.

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

[task 2020-06-05T23:10:38.908Z] 23:10:38 INFO - TEST-START | devtools/client/responsive/test/browser/browser_container_tab.js
[task 2020-06-05T23:10:38.931Z] 23:10:38 INFO - Entering test bound
[task 2020-06-05T23:10:38.933Z] 23:10:38 INFO - Adding a new tab with URL: about:newtab
[task 2020-06-05T23:10:39.393Z] 23:10:39 INFO - GECKO(3458) | [CodeCoverage] Setting handlers for process 3767.
[task 2020-06-05T23:10:39.795Z] 23:10:39 INFO - GECKO(3458) | [CodeCoverage] Setting handlers for process 3772.
[task 2020-06-05T23:10:40.931Z] 23:10:40 INFO - Tab added and finished loading
[task 2020-06-05T23:10:40.932Z] 23:10:40 INFO - TEST-PASS | devtools/client/responsive/test/browser/browser_container_tab.js | Tab's container ID is correct -
[task 2020-06-05T23:10:40.932Z] 23:10:40 INFO - Opening responsive design mode
[task 2020-06-05T23:10:41.249Z] 23:10:41 INFO - GECKO(3458) | console.log: "[DISPATCH] action type:" "CHANGE_DISPLAY_PIXEL_RATIO"
[task 2020-06-05T23:10:41.252Z] 23:10:41 INFO - GECKO(3458) | console.log: "[DISPATCH] action type:" "ADD_VIEWPORT"
[task 2020-06-05T23:10:41.457Z] 23:10:41 INFO - Responsive design mode opened
[task 2020-06-05T23:10:41.457Z] 23:10:41 INFO - Waiting for state predicate "state =>
[task 2020-06-05T23:10:41.457Z] 23:10:41 INFO - state.viewports.length == 1 &&
[task 2020-06-05T23:10:41.457Z] 23:10:41 INFO - state.devices.listState == localTypes.loadableState.LOADED"
[task 2020-06-05T23:10:41.457Z] 23:10:41 INFO - GECKO(3458) | console.log: "[DISPATCH] action type:" "LOAD_DEVICE_LIST_START"
[task 2020-06-05T23:10:41.485Z] 23:10:41 INFO - GECKO(3458) | console.log: "[DISPATCH] action type:" "ADD_DEVICE_TYPE"
[task 2020-06-05T23:10:41.505Z] 23:10:41 INFO - GECKO(3458) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-06-05T23:10:41.521Z] 23:10:41 INFO - GECKO(3458) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-06-05T23:10:41.524Z] 23:10:41 INFO - GECKO(3458) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-06-05T23:10:41.540Z] 23:10:41 INFO - GECKO(3458) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-06-05T23:10:41.540Z] 23:10:41 INFO - GECKO(3458) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-06-05T23:10:41.556Z] 23:10:41 INFO - GECKO(3458) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-06-05T23:10:41.571Z] 23:10:41 INFO - GECKO(3458) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-06-05T23:10:41.575Z] 23:10:41 INFO - GECKO(3458) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-06-05T23:10:41.582Z] 23:10:41 INFO - GECKO(3458) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-06-05T23:10:41.589Z] 23:10:41 INFO - GECKO(3458) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-06-05T23:10:41.593Z] 23:10:41 INFO - GECKO(3458) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-06-05T23:10:41.596Z] 23:10:41 INFO - GECKO(3458) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-06-05T23:10:41.603Z] 23:10:41 INFO - GECKO(3458) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-06-05T23:10:41.611Z] 23:10:41 INFO - GECKO(3458) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-06-05T23:10:41.614Z] 23:10:41 INFO - GECKO(3458) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-06-05T23:10:41.621Z] 23:10:41 INFO - GECKO(3458) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-06-05T23:10:41.630Z] 23:10:41 INFO - GECKO(3458) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-06-05T23:10:41.638Z] 23:10:41 INFO - GECKO(3458) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-06-05T23:10:41.645Z] 23:10:41 INFO - GECKO(3458) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-06-05T23:10:41.661Z] 23:10:41 INFO - GECKO(3458) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-06-05T23:10:41.676Z] 23:10:41 INFO - GECKO(3458) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-06-05T23:10:41.676Z] 23:10:41 INFO - GECKO(3458) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-06-05T23:10:41.684Z] 23:10:41 INFO - GECKO(3458) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-06-05T23:10:41.685Z] 23:10:41 INFO - GECKO(3458) | console.log: "[DISPATCH] action type:" "ADD_DEVICE_TYPE"
[task 2020-06-05T23:10:41.693Z] 23:10:41 INFO - GECKO(3458) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-06-05T23:10:41.700Z] 23:10:41 INFO - GECKO(3458) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-06-05T23:10:41.707Z] 23:10:41 INFO - GECKO(3458) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-06-05T23:10:41.715Z] 23:10:41 INFO - GECKO(3458) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-06-05T23:10:41.722Z] 23:10:41 INFO - GECKO(3458) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-06-05T23:10:41.730Z] 23:10:41 INFO - GECKO(3458) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-06-05T23:10:41.733Z] 23:10:41 INFO - GECKO(3458) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-06-05T23:10:41.736Z] 23:10:41 INFO - GECKO(3458) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-06-05T23:10:41.745Z] 23:10:41 INFO - GECKO(3458) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-06-05T23:10:41.751Z] 23:10:41 INFO - GECKO(3458) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-06-05T23:10:41.754Z] 23:10:41 INFO - GECKO(3458) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-06-05T23:10:41.762Z] 23:10:41 INFO - GECKO(3458) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-06-05T23:10:41.765Z] 23:10:41 INFO - GECKO(3458) | console.log: "[DISPATCH] action type:" "ADD_DEVICE_TYPE"
[task 2020-06-05T23:10:41.772Z] 23:10:41 INFO - GECKO(3458) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-06-05T23:10:41.780Z] 23:10:41 INFO - GECKO(3458) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-06-05T23:10:41.783Z] 23:10:41 INFO - GECKO(3458) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-06-05T23:10:41.790Z] 23:10:41 INFO - GECKO(3458) | console.log: "[DISPATCH] action type:" "ADD_DEVICE_TYPE"
[task 2020-06-05T23:10:41.794Z] 23:10:41 INFO - GECKO(3458) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-06-05T23:10:41.801Z] 23:10:41 INFO - GECKO(3458) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-06-05T23:10:41.808Z] 23:10:41 INFO - GECKO(3458) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-06-05T23:10:41.816Z] 23:10:41 INFO - GECKO(3458) | console.log: "[DISPATCH] action type:" "ADD_DEVICE_TYPE"
[task 2020-06-05T23:10:41.831Z] 23:10:41 INFO - GECKO(3458) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-06-05T23:10:41.839Z] 23:10:41 INFO - GECKO(3458) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-06-05T23:10:41.846Z] 23:10:41 INFO - GECKO(3458) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-06-05T23:10:41.849Z] 23:10:41 INFO - GECKO(3458) | console.log: "[DISPATCH] action type:" "ADD_DEVICE_TYPE"
[task 2020-06-05T23:10:41.857Z] 23:10:41 INFO - GECKO(3458) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-06-05T23:10:41.858Z] 23:10:41 INFO - GECKO(3458) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-06-05T23:10:41.865Z] 23:10:41 INFO - GECKO(3458) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-06-05T23:10:41.866Z] 23:10:41 INFO - GECKO(3458) | console.log: "[DISPATCH] action type:" "ADD_DEVICE"
[task 2020-06-05T23:10:41.874Z] 23:10:41 INFO - GECKO(3458) | console.log: "[DISPATCH] action type:" "ADD_DEVICE_TYPE"
[task 2020-06-05T23:10:41.875Z] 23:10:41 INFO - GECKO(3458) | console.log: "[DISPATCH] action type:" "LOAD_DEVICE_LIST_END"
[task 2020-06-05T23:10:41.886Z] 23:10:41 INFO - Found state predicate "state =>
[task 2020-06-05T23:10:41.886Z] 23:10:41 INFO - state.viewports.length == 1 &&
[task 2020-06-05T23:10:41.886Z] 23:10:41 INFO - state.devices.listState == localTypes.loadableState.LOADED"
[task 2020-06-05T23:11:23.940Z] 23:11:23 INFO - Longer timeout required, waiting longer... Remaining timeouts: 1
[task 2020-06-05T23:12:08.937Z] 23:12:08 INFO - TEST-INFO | started process screentopng
[task 2020-06-05T23:12:09.106Z] 23:12:09 INFO - TEST-INFO | screentopng: exit 0
[task 2020-06-05T23:12:09.106Z] 23:12:09 INFO - TEST-UNEXPECTED-FAIL | devtools/client/responsive/test/browser/browser_container_tab.js | Test timed out -

Micah, could you take a look, please?

Sorry for the delay on this. I'll take a look today.

Flags: needinfo?(mtigley)

Looking at the screenshot provided in the log, it seems like the test is navigating as expected (to http://example.com/) but gets hung up while waiting for the target to change. It's possible that the current target has already switched to the new target, which would cause the timeout.
I'll run a Try push with a potential fix.

this looks to be a perma fail on linux ccov builds.

:mtigley, can you update your progress?

Flags: needinfo?(mtigley)

In the last 7 days there have been 23 occurrences, all on linux1804-64-ccov opt.

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

In the last 7 days there have been 26 occurrences, all on linux1804-64-ccov opt.

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

There were 25 failures in the past 7 days. These happened on inux1804-64-ccov opt.

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

Flags: needinfo?(mtigley)
Whiteboard: [retriggered][stockwell unknown] → [retriggered][stockwell needswork:owner]
Target Milestone: Firefox 78 → ---

Test was already disabled on linux asan and debug, updated the condition for all linux.
Micah, please take a look at this after you return from PTO. Thank you.

Micah did you get a chance to look at this failure?

Flags: needinfo?(mtigley)

I haven't had much luck reproducing this failure on my local Linux machine and I don't have a lot of time to actively look into this since I'll be helping the Firefox desktop team now.

I think we can disable this test for all Linux platforms for now until I (or someone else) has time to look at it.

Flags: needinfo?(mtigley)
Assignee: mtigley → nobody
Keywords: leave-open
Whiteboard: [retriggered][stockwell unknown] → [retriggered][stockwell disabled]
Pushed by apavel@mozilla.com:
https://hg.mozilla.org/integration/autoland/rev/f789042cdcb9
temporarily disable browser_container_tab.js on linux r=gbrown DONTBUILD
Assignee: nobody → apavel
Assignee: apavel → nobody
Severity: normal → S3
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Creator:
Created:
Updated:
Size: