1634208 - Perma [tier2][fission] /webconsole/test/browser/browser_jsterm_screenshot_command_warnings.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. -

Status: RESOLVED FIXED

(DevTools :: Console, defect)

Description

[Treeherder Bug Filer]

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

---

[task 2020-04-29T23:44:29.404Z] 23:44:29     INFO - TEST-START | devtools/client/webconsole/test/browser/browser_jsterm_screenshot_command_warnings.js
[task 2020-04-29T23:44:59.591Z] 23:44:59     INFO - GECKO(1243) | [Parent 1243, MediaDecoderStateMachine #1] WARNING: Decoder=7f4883286000 Decode error: NS_ERROR_DOM_MEDIA_FATAL_ERR (0x806e0005) - error creating Audio decoder: file /builds/worker/checkouts/gecko/dom/media/MediaDecoderStateMachine.cpp, line 3370
[task 2020-04-29T23:45:15.440Z] 23:45:15     INFO - GECKO(1243) | [Parent 1243, MediaDecoderStateMachine #1] WARNING: Decoder=7f488328ac00 Decode error: NS_ERROR_DOM_MEDIA_FATAL_ERR (0x806e0005) - error creating Audio decoder: file /builds/worker/checkouts/gecko/dom/media/MediaDecoderStateMachine.cpp, line 3370
[task 2020-04-29T23:45:16.452Z] 23:45:16     INFO - GECKO(1243) | console.warn: "IGNORED REDUX ACTION:" ({type:"AUTOCOMPLETE_CLEAR"})
[task 2020-04-29T23:45:16.541Z] 23:45:16     INFO - TEST-INFO | started process screentopng
[task 2020-04-29T23:45:16.786Z] 23:45:16     INFO - TEST-INFO | screentopng: exit 0
[task 2020-04-29T23:45:16.786Z] 23:45:16     INFO - Buffered messages logged at 23:44:29
[task 2020-04-29T23:45:16.787Z] 23:45:16     INFO - Entering test bound 
[task 2020-04-29T23:45:16.787Z] 23:45:16     INFO - Adding a new tab with URL: data:text/html;charset=utf8,
[task 2020-04-29T23:45:16.787Z] 23:45:16     INFO -    <style>
[task 2020-04-29T23:45:16.787Z] 23:45:16     INFO -      body { margin:0; }
[task 2020-04-29T23:45:16.787Z] 23:45:16     INFO -      .big { width:20000px; height:20000px; }
[task 2020-04-29T23:45:16.787Z] 23:45:16     INFO -      .small { width:5px; height:5px; }
[task 2020-04-29T23:45:16.787Z] 23:45:16     INFO -    </style>
[task 2020-04-29T23:45:16.787Z] 23:45:16     INFO -    <div class="big"></div>
[task 2020-04-29T23:45:16.787Z] 23:45:16     INFO -    <div class="small"></div>
[task 2020-04-29T23:45:16.787Z] 23:45:16     INFO - Tab added and finished loading
[task 2020-04-29T23:45:16.787Z] 23:45:16     INFO - Buffered messages logged at 23:44:31
[task 2020-04-29T23:45:16.787Z] 23:45:16     INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_jsterm_screenshot_command_warnings.js | web console opened - 
[task 2020-04-29T23:45:16.787Z] 23:45:16     INFO - Check that large screenshots get cut off if necessary
[task 2020-04-29T23:45:16.787Z] 23:45:16     INFO - Buffered messages logged at 23:44:59
[task 2020-04-29T23:45:16.787Z] 23:45:16     INFO - Matched a message with text: "Screenshot copied to clipboard.", still waiting for 1 messages.
[task 2020-04-29T23:45:16.788Z] 23:45:16     INFO - Console message: [JavaScript Warning: "The image was cut off to 10000×10000 as the resulting image was too large"]
[task 2020-04-29T23:45:16.788Z] 23:45:16     INFO - Console message: [JavaScript Warning: "Media resource resource://devtools/client/themes/audio/shutter.wav could not be decoded." {file: "chrome://devtools/content/webconsole/index.html" line: 0}]
[task 2020-04-29T23:45:16.788Z] 23:45:16     INFO - Matched a message with text: "The image was cut off to 10000×10000 as the resulting image was too large", all messages received.
[task 2020-04-29T23:45:16.788Z] 23:45:16     INFO - Buffered messages logged at 23:45:15
[task 2020-04-29T23:45:16.789Z] 23:45:16     INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_jsterm_screenshot_command_warnings.js | screenshot exists - 
[task 2020-04-29T23:45:16.789Z] 23:45:16     INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_jsterm_screenshot_command_warnings.js | Read expected amount of data - 
[task 2020-04-29T23:45:16.790Z] 23:45:16     INFO - Console message: [JavaScript Warning: "Media resource resource://devtools/client/themes/audio/shutter.wav could not be decoded, error: Error Code: NS_ERROR_DOM_MEDIA_FATAL_ERR (0x806e0005)
[task 2020-04-29T23:45:16.790Z] 23:45:16     INFO - Details: error creating Audio decoder" {file: "chrome://devtools/content/webconsole/index.html" line: 0}]
[task 2020-04-29T23:45:16.791Z] 23:45:16     INFO - Waiting for the clipboard image to load in the content page
[task 2020-04-29T23:45:16.792Z] 23:45:16     INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_jsterm_screenshot_command_warnings.js | The resulting image is 10000px wide - 
[task 2020-04-29T23:45:16.792Z] 23:45:16     INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_jsterm_screenshot_command_warnings.js | The resulting image is 10000px high - 
[task 2020-04-29T23:45:16.793Z] 23:45:16     INFO - Matched a message with text: "Screenshot copied to clipboard.", all messages received.
[task 2020-04-29T23:45:16.794Z] 23:45:16     INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_jsterm_screenshot_command_warnings.js | screenshot exists - 
[task 2020-04-29T23:45:16.794Z] 23:45:16     INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_jsterm_screenshot_command_warnings.js | Read expected amount of data - 
[task 2020-04-29T23:45:16.795Z] 23:45:16     INFO - Console message: [JavaScript Warning: "Media resource resource://devtools/client/themes/audio/shutter.wav could not be decoded." {file: "chrome://devtools/content/webconsole/index.html" line: 0}]
[task 2020-04-29T23:45:16.796Z] 23:45:16     INFO - Waiting for the clipboard image to load in the content page
[task 2020-04-29T23:45:16.797Z] 23:45:16     INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_jsterm_screenshot_command_warnings.js | The resulting image is 5px wide - 
[task 2020-04-29T23:45:16.797Z] 23:45:16     INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_jsterm_screenshot_command_warnings.js | The resulting image is 5px high - 
[task 2020-04-29T23:45:16.798Z] 23:45:16     INFO - Check that fullpage screenshots are taken at dpr 1
[task 2020-04-29T23:45:16.799Z] 23:45:16     INFO - This machine already has a dpr of 1, no need to test this
[task 2020-04-29T23:45:16.800Z] 23:45:16     INFO - Check that when a screenshot fails, an error message is displayed
[task 2020-04-29T23:45:16.801Z] 23:45:16     INFO - Console message: [JavaScript Error: "Error creating the image. The resulting image was probably too large."]
[task 2020-04-29T23:45:16.802Z] 23:45:16     INFO - Matched a message with text: "Error creating the image. The resulting image was probably too large.", all messages received.
[task 2020-04-29T23:45:16.803Z] 23:45:16     INFO - Leaving test bound 
[task 2020-04-29T23:45:16.807Z] 23:45:16     INFO - Buffered messages logged at 23:45:16
[task 2020-04-29T23:45:16.807Z] 23:45:16     INFO - Removing tab.
[task 2020-04-29T23:45:16.807Z] 23:45:16     INFO - Waiting for event: 'TabClose' on [object XULElement].
[task 2020-04-29T23:45:16.807Z] 23:45:16     INFO - Got event: 'TabClose' on [object XULElement].
[task 2020-04-29T23:45:16.808Z] 23:45:16     INFO - Tab removed and finished closing
[task 2020-04-29T23:45:16.808Z] 23:45:16     INFO - Console message: [JavaScript Warning: "Media resource resource://devtools/client/themes/audio/shutter.wav could not be decoded, error: Error Code: NS_ERROR_DOM_MEDIA_FATAL_ERR (0x806e0005)
[task 2020-04-29T23:45:16.808Z] 23:45:16     INFO - Details: error creating Audio decoder" {file: "chrome://devtools/content/webconsole/index.html" line: 0}]
[task 2020-04-29T23:45:16.808Z] 23:45:16     INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_jsterm_screenshot_command_warnings.js | The main process DevToolsServer has no pending connection when the test ends - 
[task 2020-04-29T23:45:16.809Z] 23:45:16     INFO - Buffered messages finished
[task 2020-04-29T23:45:16.809Z] 23:45:16     INFO - TEST-UNEXPECTED-FAIL | devtools/client/webconsole/test/browser/browser_jsterm_screenshot_command_warnings.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 2020-04-29T23:45:16.809Z] 23:45:16     INFO - GECKO(1243) | MEMORY STAT | vsize 3832MB | residentFast 617MB | heapAllocated 155MB
[task 2020-04-29T23:45:16.809Z] 23:45:16     INFO - TEST-OK | devtools/client/webconsole/test/browser/browser_jsterm_screenshot_command_warnings.js | took 47141ms
[task 2020-04-29T23:45:16.811Z] 23:45:16     INFO - checking window state
[task 2020-04-29T23:45:16.812Z] 23:45:16     INFO - TEST-START | devtools/client/webconsole/test/browser/browser_jsterm_selfxss.js
[task 2020-04-29T23:45:20.795Z] 23:45:20     INFO - GECKO(1243) | console.warn: "IGNORED REDUX ACTION:" ({type:"AUTOCOMPLETE_CLEAR"})
[task 2020-04-29T23:45:20.891Z] 23:45:20     INFO - GECKO(1243) | MEMORY STAT | vsize 3835MB | residentFast 626MB | heapAllocated 162MB
[task 2020-04-29T23:45:20.892Z] 23:45:20     INFO - TEST-OK | devtools/client/webconsole/test/browser/browser_jsterm_selfxss.js | took 4208ms
[task 2020-04-29T23:45:20.948Z] 23:45:20     INFO - checking window state
[task 2020-04-29T23:45:20.999Z] 23:45:20     INFO - TEST-START | devtools/client/webconsole/test/browser/browser_jsterm_syntax_highlight_output.js
[task 2020-04-29T23:45:23.042Z] 23:45:23     INFO - GECKO(1243) | console.warn: "IGNORED REDUX ACTION:" async ({ dispatch, webConsoleUI }) => {
[task 2020-04-29T23:45:23.043Z] 23:45:23     INFO - GECKO(1243) |     if (response.error) {
[task 2020-04-29T23:45:23.043Z] 23:45:23     INFO - GECKO(1243) |       console.error(`Evaluation error`, response.error, ": ", response.message);
[task 2020-04-29T23:45:23.044Z] 23:45:23     INFO - GECKO(1243) |       return;
[task 2020-04-29T23:45:23.044Z] 23:45:23     INFO - GECKO(1243) |     }
[task 2020-04-29T23:45:23.044Z] 23:45:23     INFO - GECKO(1243) |     // If the evaluation was a top-level await expression that was rejected, there will
[task 2020-04-29T23:45:23.044Z] 23:45:23     INFO - GECKO(1243) |     // be an uncaught exception reported, so we don't need to do anything.
[task 2020-04-29T23:45:23.044Z] 23:45:23     INFO - GECKO(1243) |     if (response.topLevelAwaitRejected === true) {
[task 2020-04-29T23:45:23.045Z] 23:45:23     INFO - GECKO(1243) |       return;
[task 2020-04-29T23:45:23.045Z] 23:45:23     INFO - GECKO(1243) |     }
[task 2020-04-29T23:45:23.045Z] 23:45:23     INFO - GECKO(1243) |     if (!response.helperResult) {
[task 2020-04-29T23:45:23.045Z] 23:45:23     INFO - GECKO(1243) |       webConsoleUI.wrapper.dispatchMessageAdd(response);
[task 2020-04-29T23:45:23.046Z] 23:45:23     INFO - GECKO(1243) |       return;
[task 2020-04-29T23:45:23.046Z] 23:45:23     INFO - GECKO(1243) |     }
[task 2020-04-29T23:45:23.046Z] 23:45:23     INFO - GECKO(1243) |     await dispatch(handleHelperResult(response));
[task 2020-04-29T23:45:23.046Z] 23:45:23     INFO - GECKO(1243) |   }
[task 2020-04-29T23:45:24.171Z] 23:45:24     INFO - GECKO(1243) | console.warn: "IGNORED REDUX ACTION:" ({type:"AUTOCOMPLETE_CLEAR"})
[task 2020-04-29T23:45:24.253Z] 23:45:24     INFO - GECKO(1243) | MEMORY STAT | vsize 3838MB | residentFast 646MB | heapAllocated 175MB
[task 2020-04-29T23:45:24.253Z] 23:45:24     INFO - TEST-OK | devtools/client/webconsole/test/browser/browser_jsterm_syntax_highlight_output.js | took 3260ms
[task 2020-04-29T23:45:24.301Z] 23:45:24     INFO - checking window state
[task 2020-04-29T23:45:29.269Z] 23:45:29     INFO - GECKO(1243) | Completed ShutdownLeaks collections in process 1758
[task 2020-04-29T23:45:29.627Z] 23:45:29     INFO - GECKO(1243) | Completed ShutdownLeaks collections in process 1677
[task 2020-04-29T23:45:29.665Z] 23:45:29     INFO - GECKO(1243) | Completed ShutdownLeaks collections in process 1729
[task 2020-04-29T23:45:29.727Z] 23:45:29     INFO - GECKO(1243) | Completed ShutdownLeaks collections in process 1513
[task 2020-04-29T23:45:29.809Z] 23:45:29     INFO - GECKO(1243) | Completed ShutdownLeaks collections in process 1441
[task 2020-04-29T23:45:29.967Z] 23:45:29     INFO - GECKO(1243) | Completed ShutdownLeaks collections in process 1705
[task 2020-04-29T23:45:29.967Z] 23:45:29     INFO - GECKO(1243) | Completed ShutdownLeaks collections in process 1590
[task 2020-04-29T23:45:29.991Z] 23:45:29     INFO - GECKO(1243) | Completed ShutdownLeaks collections in process 1623
[task 2020-04-29T23:45:30.269Z] 23:45:30     INFO - GECKO(1243) | Completed ShutdownLeaks collections in process 1425
[task 2020-04-29T23:45:33.675Z] 23:45:33     INFO - GECKO(1243) | Completed ShutdownLeaks collections in process 1243
[task 2020-04-29T23:45:33.676Z] 23:45:33     INFO - TEST-START | Shutdown

Comment 1

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 1

Platform breakdown:

• linux1804-64-ccov: 1

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

Comment 2

[BugBot [:suhaib / :marco/ :calixte]]

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

Comment 3

[Andreea Pavel [:apavel]]

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

[task 2020-09-29T18:04:35.801Z] 18:04:35 INFO - TEST-START | devtools/client/webconsole/test/browser/browser_jsterm_screenshot_command_warnings.js
[task 2020-09-29T18:04:56.584Z] 18:04:56 INFO - GECKO(1520) | [Parent 1520, MediaDecoderStateMachine #1] WARNING: Decoder=7f17922b3400 Decode error: NS_ERROR_DOM_MEDIA_FATAL_ERR (0x806e0005) - mozilla::MediaResult mozilla::RemoteAudioDecoderChild::InitIPDL(const mozilla::AudioInfo&, const OptionSet&): RemoteDecoderManager is not available.: file /builds/worker/checkouts/gecko/dom/media/MediaDecoderStateMachine.cpp, line 3471
[task 2020-09-29T18:05:06.701Z] 18:05:06 INFO - GECKO(1520) | [Parent 1520, MediaDecoderStateMachine #1] WARNING: Decoder=7f17922ba000 Decode error: NS_ERROR_DOM_MEDIA_FATAL_ERR (0x806e0005) - mozilla::MediaResult mozilla::RemoteAudioDecoderChild::InitIPDL(const mozilla::AudioInfo&, const OptionSet&): RemoteDecoderManager is not available.: file /builds/worker/checkouts/gecko/dom/media/MediaDecoderStateMachine.cpp, line 3471
[task 2020-09-29T18:05:25.609Z] 18:05:25 INFO - GECKO(1520) | [Parent 1520, MediaDecoderStateMachine #1] WARNING: Decoder=7f1792cbf800 Decode error: NS_ERROR_DOM_MEDIA_FATAL_ERR (0x806e0005) - mozilla::MediaResult mozilla::RemoteAudioDecoderChild::InitIPDL(const mozilla::AudioInfo&, const OptionSet&): RemoteDecoderManager is not available.: file /builds/worker/checkouts/gecko/dom/media/MediaDecoderStateMachine.cpp, line 3471
[task 2020-09-29T18:05:36.239Z] 18:05:36 INFO - GECKO(1520) | console.warn: "IGNORED REDUX ACTION:" "AUTOCOMPLETE_CLEAR"
[task 2020-09-29T18:05:36.320Z] 18:05:36 INFO - TEST-INFO | started process screentopng
[task 2020-09-29T18:05:36.564Z] 18:05:36 INFO - TEST-INFO | screentopng: exit 0
[task 2020-09-29T18:05:36.564Z] 18:05:36 INFO - Buffered messages logged at 18:04:35
[task 2020-09-29T18:05:36.564Z] 18:05:36 INFO - Entering test bound
[task 2020-09-29T18:05:36.564Z] 18:05:36 INFO - Adding a new tab with URL: data:text/html;charset=utf8,
[task 2020-09-29T18:05:36.564Z] 18:05:36 INFO - <style>
[task 2020-09-29T18:05:36.564Z] 18:05:36 INFO - body { margin:0; }
[task 2020-09-29T18:05:36.564Z] 18:05:36 INFO - .big { width:20000px; height:20000px; }
[task 2020-09-29T18:05:36.564Z] 18:05:36 INFO - .small { width:5px; height:5px; }
[task 2020-09-29T18:05:36.564Z] 18:05:36 INFO - </style>
[task 2020-09-29T18:05:36.564Z] 18:05:36 INFO - <div class="big"></div>
[task 2020-09-29T18:05:36.564Z] 18:05:36 INFO - <div class="small"></div>
[task 2020-09-29T18:05:36.564Z] 18:05:36 INFO - Buffered messages logged at 18:04:36
[task 2020-09-29T18:05:36.564Z] 18:05:36 INFO - Tab added and finished loading
[task 2020-09-29T18:05:36.564Z] 18:05:36 INFO - Buffered messages logged at 18:04:37
[task 2020-09-29T18:05:36.564Z] 18:05:36 INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_jsterm_screenshot_command_warnings.js | web console opened -
[task 2020-09-29T18:05:36.564Z] 18:05:36 INFO - Check that large screenshots get cut off if necessary
[task 2020-09-29T18:05:36.564Z] 18:05:36 INFO - Buffered messages logged at 18:04:56
[task 2020-09-29T18:05:36.564Z] 18:05:36 INFO - Matched a message with text: "Screenshot copied to clipboard.", still waiting for 1 messages.
[task 2020-09-29T18:05:36.564Z] 18:05:36 INFO - Console message: [JavaScript Warning: "The image was cut off to 10000×10000 as the resulting image was too large"]
[task 2020-09-29T18:05:36.564Z] 18:05:36 INFO - Console message: [JavaScript Warning: "Media resource resource://devtools/client/themes/audio/shutter.wav could not be decoded." {file: "chrome://devtools/content/webconsole/index.html" line: 0}]
[task 2020-09-29T18:05:36.564Z] 18:05:36 INFO - Matched a message with text: "The image was cut off to 10000×10000 as the resulting image was too large", all messages received.
[task 2020-09-29T18:05:36.564Z] 18:05:36 INFO - Buffered messages logged at 18:05:06
task 2020-09-29T18:05:36.564Z] 18:05:36 INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_jsterm_screenshot_command_warnings.js | screenshot exists -
[task 2020-09-29T18:05:36.564Z] 18:05:36 INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_jsterm_screenshot_command_warnings.js | Read expected amount of data -
[task 2020-09-29T18:05:36.564Z] 18:05:36 INFO - Console message: [JavaScript Warning: "Media resource resource://devtools/client/themes/audio/shutter.wav could not be decoded, error: Error Code: NS_ERROR_DOM_MEDIA_FATAL_ERR (0x806e0005)
[task 2020-09-29T18:05:36.564Z] 18:05:36 INFO - Details: mozilla::MediaResult mozilla::RemoteAudioDecoderChild::InitIPDL(const mozilla::AudioInfo&, const OptionSet&): RemoteDecoderManager is not available." {file: "chrome://devtools/content/webconsole/index.html" line: 0}]
[task 2020-09-29T18:05:36.564Z] 18:05:36 INFO - Waiting for the clipboard image to load in the content page
[task 2020-09-29T18:05:36.564Z] 18:05:36 INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_jsterm_screenshot_command_warnings.js | The resulting image is 10000px wide -
[task 2020-09-29T18:05:36.564Z] 18:05:36 INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_jsterm_screenshot_command_warnings.js | The resulting image is 10000px high -
[task 2020-09-29T18:05:36.564Z] 18:05:36 INFO - Matched a message with text: "Screenshot copied to clipboard.", all messages received.
[task 2020-09-29T18:05:36.566Z] 18:05:36 INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_jsterm_screenshot_command_warnings.js | screenshot exists -
[task 2020-09-29T18:05:36.566Z] 18:05:36 INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_jsterm_screenshot_command_warnings.js | Read expected amount of data -
[task 2020-09-29T18:05:36.566Z] 18:05:36 INFO - Console message: [JavaScript Warning: "Media resource resource://devtools/client/themes/audio/shutter.wav could not be decoded." {file: "chrome://devtools/content/webconsole/index.html" line: 0}]
[task 2020-09-29T18:05:36.566Z] 18:05:36 INFO - Waiting for the clipboard image to load in the content page
[task 2020-09-29T18:05:36.566Z] 18:05:36 INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_jsterm_screenshot_command_warnings.js | The resulting image is 5px wide -
[task 2020-09-29T18:05:36.566Z] 18:05:36 INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_jsterm_screenshot_command_warnings.js | The resulting image is 5px high -
[task 2020-09-29T18:05:36.570Z] 18:05:36 INFO - Check that DPR is reduced to 1 after failure
[task 2020-09-29T18:05:36.571Z] 18:05:36 INFO - Buffered messages logged at 18:05:07
[task 2020-09-29T18:05:36.571Z] 18:05:36 INFO - Console message: [JavaScript Warning: "Media resource resource://devtools/client/themes/audio/shutter.wav could not be decoded, error: Error Code: NS_ERROR_DOM_MEDIA_FATAL_ERR (0x806e0005)
[task 2020-09-29T18:05:36.571Z] 18:05:36 INFO - Details: mozilla::MediaResult mozilla::RemoteAudioDecoderChild::InitIPDL(const mozilla::AudioInfo&, const OptionSet&): RemoteDecoderManager is not available." {file: "chrome://devtools/content/webconsole/index.html" line: 0}]
[task 2020-09-29T18:05:36.571Z] 18:05:36 INFO - Buffered messages logged at 18:05:25
[task 2020-09-29T18:05:36.571Z] 18:05:36 INFO - Matched a message with text: "Screenshot copied to clipboard.", still waiting for 2 messages.
[task 2020-09-29T18:05:36.571Z] 18:05:36 INFO - Console message: [JavaScript Warning: "The image was cut off to 10000×10000 as the resulting image was too large"]
[task 2020-09-29T18:05:36.572Z] 18:05:36 INFO - Console message: [JavaScript Warning: "The device pixel ratio was reduced to 1 as the resulting image was too large"]
[task 2020-09-29T18:05:36.572Z] 18:05:36 INFO - Console message: [JavaScript Warning: "Media resource resource://devtools/client/themes/audio/shutter.wav could not be decoded." {file: "chrome://devtools/content/webconsole/index.html" line: 0}]
[task 2020-09-29T18:05:36.572Z] 18:05:36 INFO - Matched a message with text: "The image was cut off to 10000×10000 as the resulting image was too large", still waiting for 1 messages.
[task 2020-09-29T18:05:36.572Z] 18:05:36 INFO - Matched a message with text: "The device pixel ratio was reduced to 1 as the resulting image was too large", all messages received.
[task 2020-09-29T18:05:36.572Z] 18:05:36 INFO - Buffered messages logged at 18:05:35
[task 2020-09-29T18:05:36.573Z] 18:05:36 INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_jsterm_screenshot_command_warnings.js | screenshot exists -
[task 2020-09-29T18:05:36.573Z] 18:05:36 INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_jsterm_screenshot_command_warnings.js | Read expected amount of data -
[task 2020-09-29T18:05:36.573Z] 18:05:36 INFO - Console message: [JavaScript Warning: "Media resource resource://devtools/client/themes/audio/shutter.wav could not be decoded, error: Error Code: NS_ERROR_DOM_MEDIA_FATAL_ERR (0x806e0005)
[task 2020-09-29T18:05:36.573Z] 18:05:36 INFO - Details: mozilla::MediaResult mozilla::RemoteAudioDecoderChild::InitIPDL(const mozilla::AudioInfo&, const OptionSet&): RemoteDecoderManager is not available." {file: "chrome://devtools/content/webconsole/index.html" line: 0}]
[task 2020-09-29T18:05:36.573Z] 18:05:36 INFO - Waiting for the clipboard image to load in the content page
[task 2020-09-29T18:05:36.573Z] 18:05:36 INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_jsterm_screenshot_command_warnings.js | The resulting image is 10000px wide -
[task 2020-09-29T18:05:36.574Z] 18:05:36 INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_jsterm_screenshot_command_warnings.js | The resulting image is 10000px high -
[task 2020-09-29T18:05:36.574Z] 18:05:36 INFO - Leaving test bound
[task 2020-09-29T18:05:36.574Z] 18:05:36 INFO - Buffered messages logged at 18:05:36
[task 2020-09-29T18:05:36.574Z] 18:05:36 INFO - Removing tab.
[task 2020-09-29T18:05:36.574Z] 18:05:36 INFO - Waiting for event: 'TabClose' on [object XULElement].
[task 2020-09-29T18:05:36.576Z] 18:05:36 INFO - Got event: 'TabClose' on [object XULElement].
[task 2020-09-29T18:05:36.584Z] 18:05:36 INFO - Tab removed and finished closing
[task 2020-09-29T18:05:36.584Z] 18:05:36 INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_jsterm_screenshot_command_warnings.js | The main process DevToolsServer has no pending connection when the test ends -
[task 2020-09-29T18:05:36.584Z] 18:05:36 INFO - Buffered messages finished
[task 2020-09-29T18:05:36.584Z] 18:05:36 INFO - TEST-UNEXPECTED-FAIL | devtools/client/webconsole/test/browser/browser_jsterm_screenshot_command_warnings.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 2020-09-29T18:05:36.584Z] 18:05:36 INFO - GECKO(1520) | MEMORY STAT | vsize 4428MB | residentFast 1058MB | heapAllocated 543MB
[task 2020-09-29T18:05:36.584Z] 18:05:36 INFO - TEST-OK | devtools/client/webconsole/test/browser/browser_jsterm_screenshot_command_warnings.js | took 60517ms
[task 2020-09-29T18:05:36.584Z] 18:05:36 INFO - checking window state

Comment 4

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 18

Platform breakdown:

• linux1804-64-ccov: 18

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

Comment 5

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 18

Platform breakdown:

• linux1804-64-ccov: 18

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

Comment 6

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 20

Platform breakdown:

• linux1804-64-ccov: 20

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

Comment 7

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 16

Platform breakdown:

• linux1804-64-ccov: 16

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

Comment 8

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 16

Platform breakdown:

• linux1804-64-ccov: 16

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

Comment 9

[Andreea Pavel [:apavel]]

There are 21 total failures in the last 7 days on linux1804-64-ccov opt

Recent failure log: https://treeherder.mozilla.org/logviewer?job_id=321125404&repo=mozilla-central&lineNumber=2819

[task 2020-11-08T22:37:57.606Z] 22:37:57 INFO - TEST-START | devtools/client/webconsole/test/browser/browser_jsterm_screenshot_command_warnings.js
[task 2020-11-08T22:38:58.821Z] 22:38:58 INFO - GECKO(1548) | console.warn: "IGNORED REDUX ACTION:" "AUTOCOMPLETE_CLEAR"
[task 2020-11-08T22:38:58.906Z] 22:38:58 INFO - TEST-INFO | started process screentopng
[task 2020-11-08T22:38:59.136Z] 22:38:59 INFO - TEST-INFO | screentopng: exit 0
[task 2020-11-08T22:38:59.140Z] 22:38:59 INFO - Buffered messages logged at 22:37:57
[task 2020-11-08T22:38:59.140Z] 22:38:59 INFO - Entering test bound
[task 2020-11-08T22:38:59.140Z] 22:38:59 INFO - Adding a new tab with URL: data:text/html;charset=utf8,
[task 2020-11-08T22:38:59.140Z] 22:38:59 INFO - <style>
[task 2020-11-08T22:38:59.141Z] 22:38:59 INFO - body { margin:0; }
[task 2020-11-08T22:38:59.141Z] 22:38:59 INFO - .big { width:20000px; height:20000px; }
[task 2020-11-08T22:38:59.141Z] 22:38:59 INFO - .small { width:5px; height:5px; }
[task 2020-11-08T22:38:59.141Z] 22:38:59 INFO - </style>
[task 2020-11-08T22:38:59.141Z] 22:38:59 INFO - <div class="big"></div>
[task 2020-11-08T22:38:59.141Z] 22:38:59 INFO - <div class="small"></div>
[task 2020-11-08T22:38:59.144Z] 22:38:59 INFO - Tab added and finished loading
[task 2020-11-08T22:38:59.144Z] 22:38:59 INFO - Buffered messages logged at 22:37:58
[task 2020-11-08T22:38:59.145Z] 22:38:59 INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_jsterm_screenshot_command_warnings.js | web console opened -
[task 2020-11-08T22:38:59.145Z] 22:38:59 INFO - Check that large screenshots get cut off if necessary
[task 2020-11-08T22:38:59.145Z] 22:38:59 INFO - Buffered messages logged at 22:38:18
[task 2020-11-08T22:38:59.145Z] 22:38:59 INFO - Matched a message with text: "Screenshot copied to clipboard.", still waiting for 1 messages.
[task 2020-11-08T22:38:59.145Z] 22:38:59 INFO - Console message: [JavaScript Warning: "The image was cut off to 10000×10000 as the resulting image was too large"]
[task 2020-11-08T22:38:59.145Z] 22:38:59 INFO - Matched a message with text: "The image was cut off to 10000×10000 as the resulting image was too large", all messages received.
[task 2020-11-08T22:38:59.145Z] 22:38:59 INFO - Buffered messages logged at 22:38:28
[task 2020-11-08T22:38:59.146Z] 22:38:59 INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_jsterm_screenshot_command_warnings.js | screenshot exists -
[task 2020-11-08T22:38:59.147Z] 22:38:59 INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_jsterm_screenshot_command_warnings.js | Read expected amount of data -
[task 2020-11-08T22:38:59.149Z] 22:38:59 INFO - Console message: [JavaScript Warning: "Cannot play media. No decoders for requested formats: audio/x-wav" {file: "chrome://devtools/content/webconsole/index.html" line: 0}]
[task 2020-11-08T22:38:59.150Z] 22:38:59 INFO - Waiting for the clipboard image to load in the content page
[task 2020-11-08T22:38:59.160Z] 22:38:59 INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_jsterm_screenshot_command_warnings.js | The resulting image is 10000px wide -
[task 2020-11-08T22:38:59.161Z] 22:38:59 INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_jsterm_screenshot_command_warnings.js | The resulting image is 10000px high -
[task 2020-11-08T22:38:59.162Z] 22:38:59 INFO - Matched a message with text: "Screenshot copied to clipboard.", all messages received.
[task 2020-11-08T22:38:59.162Z] 22:38:59 INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_jsterm_screenshot_command_warnings.js | screenshot exists -
[task 2020-11-08T22:38:59.163Z] 22:38:59 INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_jsterm_screenshot_command_warnings.js | Read expected amount of data -
[task 2020-11-08T22:38:59.164Z] 22:38:59 INFO - Waiting for the clipboard image to load in the content page
[task 2020-11-08T22:38:59.165Z] 22:38:59 INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_jsterm_screenshot_command_warnings.js | The resulting image is 5px wide -
[task 2020-11-08T22:38:59.166Z] 22:38:59 INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_jsterm_screenshot_command_warnings.js | The resulting image is 5px high -
[task 2020-11-08T22:38:59.166Z] 22:38:59 INFO - Check that DPR is reduced to 1 after failure
[task 2020-11-08T22:38:59.167Z] 22:38:59 INFO - Buffered messages logged at 22:38:48
[task 2020-11-08T22:38:59.168Z] 22:38:59 INFO - Matched a message with text: "Screenshot copied to clipboard.", still waiting for 2 messages.
[task 2020-11-08T22:38:59.169Z] 22:38:59 INFO - Console message: [JavaScript Warning: "The image was cut off to 10000×10000 as the resulting image was too large"]
[task 2020-11-08T22:38:59.170Z] 22:38:59 INFO - Console message: [JavaScript Warning: "The device pixel ratio was reduced to 1 as the resulting image was too large"]
[task 2020-11-08T22:38:59.171Z] 22:38:59 INFO - Matched a message with text: "The image was cut off to 10000×10000 as the resulting image was too large", still waiting for 1 messages.
[task 2020-11-08T22:38:59.171Z] 22:38:59 INFO - Matched a message with text: "The device pixel ratio was reduced to 1 as the resulting image was too large", all messages received.
[task 2020-11-08T22:38:59.172Z] 22:38:59 INFO - Buffered messages logged at 22:38:58
[task 2020-11-08T22:38:59.173Z] 22:38:59 INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_jsterm_screenshot_command_warnings.js | screenshot exists -
[task 2020-11-08T22:38:59.174Z] 22:38:59 INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_jsterm_screenshot_command_warnings.js | Read expected amount of data -
[task 2020-11-08T22:38:59.174Z] 22:38:59 INFO - Waiting for the clipboard image to load in the content page
[task 2020-11-08T22:38:59.174Z] 22:38:59 INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_jsterm_screenshot_command_warnings.js | The resulting image is 10000px wide -
[task 2020-11-08T22:38:59.174Z] 22:38:59 INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_jsterm_screenshot_command_warnings.js | The resulting image is 10000px high -
[task 2020-11-08T22:38:59.174Z] 22:38:59 INFO - Leaving test bound
[task 2020-11-08T22:38:59.175Z] 22:38:59 INFO - Removing tab.
[task 2020-11-08T22:38:59.175Z] 22:38:59 INFO - Waiting for event: 'TabClose' on [object XULElement].
[task 2020-11-08T22:38:59.175Z] 22:38:59 INFO - Got event: 'TabClose' on [object XULElement].
[task 2020-11-08T22:38:59.175Z] 22:38:59 INFO - Tab removed and finished closing
[task 2020-11-08T22:38:59.175Z] 22:38:59 INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_jsterm_screenshot_command_warnings.js | The main process DevToolsServer has no pending connection when the test ends -
[task 2020-11-08T22:38:59.175Z] 22:38:59 INFO - Buffered messages finished
[task 2020-11-08T22:38:59.175Z] 22:38:59 INFO - TEST-UNEXPECTED-FAIL | devtools/client/webconsole/test/browser/browser_jsterm_screenshot_command_warnings.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 2020-11-08T22:38:59.175Z] 22:38:59 INFO - GECKO(1548) | MEMORY STAT | vsize 4652MB | residentFast 1031MB | heapAllocated 544MB
[task 2020-11-08T22:38:59.175Z] 22:38:59 INFO - TEST-OK | devtools/client/webconsole/test/browser/browser_jsterm_screenshot_command_warnings.js | took 61301ms
[task 2020-11-08T22:38:59.175Z] 22:38:59 INFO - checking window state

Nicolas can you please assign someone to take a look?

Comment 10

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 20

Platform breakdown:

• linux1804-64-ccov: 20

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

Comment 11

[Nicolas Chevobbe [:nchevobbe]]

Attachment: Bug 1634208 - [devtools] Fix browser_jsterm_screenshot_command_warnings intermittent. r=ladybenko.

It looks like the test was hitting the timeout limit on slow hardware (e.g. on
linux ccov on TRY).
Requesting a longer timeout seems to fix the failure.

Comment 12

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/aa8f5c12522e
[devtools] Fix browser_jsterm_screenshot_command_warnings intermittent. r=ladybenko.

Comment 13

[Dorel Luca [:dluca]]

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

Comment 14

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 5

Platform breakdown:

• linux1804-64-ccov: 5

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1634208&startday=2020-11-09&endday=2020-11-15&tree=all