1696109 - Intermittent browser/components/shell/test/browser_headless_screenshot_1.js | This test exceeded the timeout threshold. It should be rewritten or split up. If that's not possible, use requestLongerTimeout(N), but only as a last resort. -

Reporter

Description

•

4 years ago

treeherder

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

INFO - TEST-START | browser/components/shell/test/browser_headless_screenshot_1.js
[task 2021-03-03T05:48:01.487Z] 05:48:01     INFO - GECKO(3634) | [CodeCoverage] Setting handlers for process 3857.
[task 2021-03-03T05:48:01.487Z] 05:48:01     INFO - GECKO(3634) | *** You are running in headless mode.
[task 2021-03-03T05:48:01.905Z] 05:48:01     INFO - GECKO(3634) | >>> 1614750481895	Marionette	TRACE	Marionette enabled
[task 2021-03-03T05:48:01.909Z] 05:48:01     INFO - GECKO(3634) | >>> 1614750481896	Marionette	TRACE	Received observer notification profile-after-change
[task 2021-03-03T05:48:02.067Z] 05:48:02     INFO - GECKO(3634) | >>> 1614750482058	Marionette	TRACE	Received observer notification command-line-startup
[task 2021-03-03T05:48:05.250Z] 05:48:05     INFO - GECKO(3634) | [CodeCoverage] Setting handlers for process 3885.
[task 2021-03-03T05:48:05.960Z] 05:48:05     INFO - GECKO(3634) | [CodeCoverage] Setting handlers for process 3908.
[task 2021-03-03T05:48:06.110Z] 05:48:06     INFO - GECKO(3634) | >>> Screenshot saved to: /tmp/headless_test_screenshot.png
[task 2021-03-03T05:48:06.347Z] 05:48:06     INFO - GECKO(3634) | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 575: uncaught exception: Object
[task 2021-03-03T05:48:12.474Z] 05:48:12     INFO - GECKO(3634) | [CodeCoverage] Setting handlers for process 3978.
[task 2021-03-03T05:48:12.924Z] 05:48:12     INFO - GECKO(3634) | >>> 1614750492915	Marionette	TRACE	Marionette enabled
[task 2021-03-03T05:48:12.924Z] 05:48:12     INFO - GECKO(3634) | >>> 1614750492915	Marionette	TRACE	Received observer notification profile-after-change
[task 2021-03-03T05:48:13.060Z] 05:48:13     INFO - GECKO(3634) | >>> 1614750493050	Marionette	TRACE	Received observer notification command-line-startup
[task 2021-03-03T05:48:16.715Z] 05:48:16     INFO - GECKO(3634) | [CodeCoverage] Setting handlers for process 4025.
[task 2021-03-03T05:48:16.856Z] 05:48:16     INFO - GECKO(3634) | >>> Screenshot saved to: /tmp/headless_test_screenshot.png
[task 2021-03-03T05:48:16.973Z] 05:48:16     INFO - GECKO(3634) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-03-03T05:48:17.069Z] 05:48:17     INFO - GECKO(3634) | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 575: uncaught exception: Object
[task 2021-03-03T05:48:22.911Z] 05:48:22     INFO - GECKO(3634) | [CodeCoverage] Setting handlers for process 4077.
[task 2021-03-03T05:48:22.911Z] 05:48:22     INFO - GECKO(3634) | *** You are running in headless mode.
[task 2021-03-03T05:48:23.289Z] 05:48:23     INFO - GECKO(3634) | >>> 1614750503281	Marionette	TRACE	Marionette enabled
[task 2021-03-03T05:48:23.290Z] 05:48:23     INFO - GECKO(3634) | >>> 1614750503282	Marionette	TRACE	Received observer notification profile-after-change
[task 2021-03-03T05:48:23.426Z] 05:48:23     INFO - GECKO(3634) | >>> 1614750503417	Marionette	TRACE	Received observer notification command-line-startup
[task 2021-03-03T05:48:27.027Z] 05:48:27     INFO - GECKO(3634) | [CodeCoverage] Setting handlers for process 4123.
[task 2021-03-03T05:48:27.176Z] 05:48:27     INFO - GECKO(3634) | >>> Screenshot saved to: /tmp/headless_test_screenshot.png
[task 2021-03-03T05:48:27.292Z] 05:48:27     INFO - GECKO(3634) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-03-03T05:48:27.373Z] 05:48:27     INFO - GECKO(3634) | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 575: uncaught exception: Object
[task 2021-03-03T05:48:33.419Z] 05:48:33     INFO - GECKO(3634) | [CodeCoverage] Setting handlers for process 4172.
[task 2021-03-03T05:48:33.877Z] 05:48:33     INFO - GECKO(3634) | >>> 1614750513869	Marionette	TRACE	Marionette enabled
[task 2021-03-03T05:48:33.877Z] 05:48:33     INFO - GECKO(3634) | >>> 1614750513869	Marionette	TRACE	Received observer notification profile-after-change
[task 2021-03-03T05:48:34.014Z] 05:48:34     INFO - GECKO(3634) | >>> 1614750514009	Marionette	TRACE	Received observer notification command-line-startup
[task 2021-03-03T05:48:37.675Z] 05:48:37     INFO - GECKO(3634) | [CodeCoverage] Setting handlers for process 4219.
[task 2021-03-03T05:48:37.816Z] 05:48:37     INFO - GECKO(3634) | >>> Screenshot saved to: /tmp/headless_test_screenshot.png
[task 2021-03-03T05:48:38.045Z] 05:48:38     INFO - GECKO(3634) | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 575: uncaught exception: Object
[task 2021-03-03T05:48:44.151Z] 05:48:44     INFO - GECKO(3634) | [CodeCoverage] Setting handlers for process 4273.
[task 2021-03-03T05:48:44.151Z] 05:48:44     INFO - GECKO(3634) | *** You are running in headless mode.
INFO - GECKO(3634) | >>> 1614750524555	Marionette	TRACE	Marionette enabled
[task 2021-03-03T05:48:44.569Z] 05:48:44     INFO - GECKO(3634) | >>> 1614750524555	Marionette	TRACE	Received observer notification profile-after-change
[task 2021-03-03T05:48:44.707Z] 05:48:44     INFO - GECKO(3634) | >>> 1614750524697	Marionette	TRACE	Received observer notification command-line-startup
[task 2021-03-03T05:48:48.325Z] 05:48:48     INFO - GECKO(3634) | [CodeCoverage] Setting handlers for process 4320.
[task 2021-03-03T05:48:48.466Z] 05:48:48     INFO - GECKO(3634) | >>> Screenshot saved to: /tmp/headless_test_screenshot.png
[task 2021-03-03T05:48:48.668Z] 05:48:48     INFO - GECKO(3634) | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 575: uncaught exception: Object
[task 2021-03-03T05:48:54.873Z] 05:48:54     INFO - TEST-INFO | started process screentopng
[task 2021-03-03T05:48:55.029Z] 05:48:55     INFO - TEST-INFO | screentopng: exit 0
[task 2021-03-03T05:48:55.031Z] 05:48:55     INFO - Buffered messages logged at 05:48:01
[task 2021-03-03T05:48:55.032Z] 05:48:55     INFO - Entering test bound 
[task 2021-03-03T05:48:55.032Z] 05:48:55     INFO - Buffered messages logged at 05:48:12
[task 2021-03-03T05:48:55.032Z] 05:48:55     INFO - TEST-PASS | browser/components/shell/test/browser_headless_screenshot_1.js | Firefox process should exit with code 0 - 
[task 2021-03-03T05:48:55.032Z] 05:48:55     INFO - TEST-PASS | browser/components/shell/test/browser_headless_screenshot_1.js | A screenshot should be saved as /tmp/headless_test_screenshot.png - 
[task 2021-03-03T05:48:55.032Z] 05:48:55     INFO - TEST-PASS | browser/components/shell/test/browser_headless_screenshot_1.js | Screenshot should not be an empty file - 
[task 2021-03-03T05:48:55.032Z] 05:48:55     INFO - Buffered messages logged at 05:48:22
[task 2021-03-03T05:48:55.032Z] 05:48:55     INFO - TEST-PASS | browser/components/shell/test/browser_headless_screenshot_1.js | Firefox process should exit with code 0 - 
[task 2021-03-03T05:48:55.032Z] 05:48:55     INFO - TEST-PASS | browser/components/shell/test/browser_headless_screenshot_1.js | A screenshot should be saved as /tmp/headless_test_screenshot.png - 
[task 2021-03-03T05:48:55.032Z] 05:48:55     INFO - TEST-PASS | browser/components/shell/test/browser_headless_screenshot_1.js | Screenshot should not be an empty file - 
[task 2021-03-03T05:48:55.032Z] 05:48:55     INFO - Buffered messages logged at 05:48:33
[task 2021-03-03T05:48:55.032Z] 05:48:55     INFO - TEST-PASS | browser/components/shell/test/browser_headless_screenshot_1.js | Firefox process should exit with code 0 - 
[task 2021-03-03T05:48:55.032Z] 05:48:55     INFO - TEST-PASS | browser/components/shell/test/browser_headless_screenshot_1.js | A screenshot should be saved as /tmp/headless_test_screenshot.png - 
[task 2021-03-03T05:48:55.032Z] 05:48:55     INFO - TEST-PASS | browser/components/shell/test/browser_headless_screenshot_1.js | Screenshot should not be an empty file - 
[task 2021-03-03T05:48:55.032Z] 05:48:55     INFO - Buffered messages logged at 05:48:44
[task 2021-03-03T05:48:55.032Z] 05:48:55     INFO - TEST-PASS | browser/components/shell/test/browser_headless_screenshot_1.js | Firefox process should exit with code 0 - 
[task 2021-03-03T05:48:55.032Z] 05:48:55     INFO - TEST-PASS | browser/components/shell/test/browser_headless_screenshot_1.js | A screenshot should be saved as /tmp/headless_test_screenshot.png - 
[task 2021-03-03T05:48:55.032Z] 05:48:55     INFO - TEST-PASS | browser/components/shell/test/browser_headless_screenshot_1.js | Screenshot should not be an empty file - 
[task 2021-03-03T05:48:55.033Z] 05:48:55     INFO - Buffered messages logged at 05:48:54
[task 2021-03-03T05:48:55.033Z] 05:48:55     INFO - TEST-PASS | browser/components/shell/test/browser_headless_screenshot_1.js | Firefox process should exit with code 0 - 
[task 2021-03-03T05:48:55.033Z] 05:48:55     INFO - TEST-PASS | browser/components/shell/test/browser_headless_screenshot_1.js | A screenshot should be saved as /tmp/headless_test_screenshot.png - 
[task 2021-03-03T05:48:55.033Z] 05:48:55     INFO - TEST-PASS | browser/components/shell/test/browser_headless_screenshot_1.js | Screenshot should not be an empty file - 
[task 2021-03-03T05:48:55.033Z] 05:48:55     INFO - Leaving test bound 
[task 2021-03-03T05:48:55.033Z] 05:48:55     INFO - Buffered messages finished
[task 2021-03-03T05:48:55.033Z] 05:48:55     INFO - TEST-UNEXPECTED-FAIL | browser/components/shell/test/browser_headless_screenshot_1.js | This test exceeded the timeout threshold. It should be rewritten or split up. If that's not possible, use requestLongerTimeout(N), but only as a last resort. - 
[task 2021-03-03T05:48:55.033Z] 05:48:55     INFO - GECKO(3634) | MEMORY STAT | vsize 3167MB | residentFast 594MB | heapAllocated 92MB
[task 2021-03-03T05:48:55.033Z] 05:48:55     INFO - TEST-OK | browser/components/shell/test/browser_headless_screenshot_1.js | took 53552ms
[task 2021-03-03T05:48:55.033Z] 05:48:55     INFO - checking window state
[task 2021-03-03T05:48:55.033Z] 05:48:55     INFO - TEST-START | browser/components/shell/test/browser_headless_screenshot_2.js```

Sebastian Hengst [:aryx] (needinfo me if it's about an intermittent or backout)

Comment 1

•

4 years ago

This is a new test from bug 1571419 and the failures are on the slow Linux code coverage platform. Brendan, can you check the failure and decide on a fix or if it shall get disabled for ccov?

status-firefox86: --- → unaffected

status-firefox87: --- → unaffected

status-firefox88: --- → affected

status-firefox-esr78: --- → unaffected

Flags: needinfo?(bdahl)

Regressed by: 1571419

BMO Automation

Updated

•

4 years ago

Has Regression Range: --- → yes

Brendan Dahl [:bdahl]

Assignee

Comment 2

•

4 years ago

Attached file Bug 1696109 - Disable headless screenshot test for code coverage. r=kmag (deleted) — Details

Phabricator Automation

Updated

•

4 years ago

Assignee: nobody → bdahl

Status: NEW → ASSIGNED

Comment hidden (Intermittent Failures Robot)

Pulsebot

Comment 4

•

4 years ago

Pushed by bdahl@mozilla.com: https://hg.mozilla.org/integration/autoland/rev/4ce822272b78 Disable headless screenshot test for code coverage. r=kmag

Sandor Molnar

Comment 5

•

4 years ago

bugherder

https://hg.mozilla.org/mozilla-central/rev/4ce822272b78

Status: ASSIGNED → RESOLVED

Closed: 4 years ago

status-firefox88: affected → fixed

Resolution: --- → FIXED

Target Milestone: --- → 88 Branch

Brendan Dahl [:bdahl]

Assignee

Updated

•

4 years ago

Flags: needinfo?(bdahl)

Atila Butkovits

Comment 6

•

3 years ago

Recent failure log: https://treeherder.mozilla.org/logviewer?job_id=361510288&repo=autoland&lineNumber=3222

Status: RESOLVED → REOPENED

Resolution: FIXED → ---

BugBot [:suhaib / :marco/ :calixte]

Updated

•

3 years ago

Keywords: regression

Comment hidden (Intermittent Failures Robot)

BugBot [:suhaib / :marco/ :calixte]

Comment 8

•

3 years ago

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.

Status: REOPENED → RESOLVED

Closed: 4 years ago → 3 years ago

Resolution: --- → INCOMPLETE

Bugzilla

Quick Search

Intermittent browser/components/shell/test/browser_headless_screenshot_1.js | This test exceeded the timeout threshold. It should be rewritten or split up. If that's not possible, use requestLongerTimeout(N), but only as a last resort. -

Categories

(Firefox :: Shell Integration, defect, P5)

Tracking

()

People

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

References

(Regression)

Details

(Keywords: intermittent-failure, regression)

Crash Data

Security

(public)

User Story

Attachments

(1 file)

Description

Comment 1

Updated

Comment 2

Updated

Comment 3

Comment 4

Comment 5

Updated

Comment 6

Updated

Comment 7

Comment 8

Attachment

General

Description

File Name

Content Type