Closed Bug 1302655 Opened 8 years ago Closed 7 years ago

Intermittent devtools/client/scratchpad/test/browser_scratchpad_edit_ui_updates.js | Test timed out -

Categories

(DevTools Graveyard :: Scratchpad, defect, P3)

defect

Tracking

(firefox50 unaffected, firefox51 affected, firefox52 wontfix)

RESOLVED INCOMPLETE
Tracking Status
firefox50 --- unaffected
firefox51 --- affected
firefox52 --- wontfix

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: bulk-close-intermittents, intermittent-failure, Whiteboard: [stockwell unknown])

this test is interesting becase on linux64 opt (it fails on opt) we see it timing out and we typically run in ~1second. With that knowledge something odd is going on here. I see a large uptick in this failure rate on January 9th: https://brasstacks.mozilla.com/orangefactor/?display=Bug&bugid=1302655&startday=2017-01-08&endday=2017-01-18&tree=all here is what I see in a failing log: [task 2017-01-09T04:13:30.449772Z] 04:13:30 INFO - TEST-START | devtools/client/scratchpad/test/browser_scratchpad_edit_ui_updates.js [task 2017-01-09T04:14:15.516637Z] 04:14:15 INFO - TEST-INFO | started process screentopng [task 2017-01-09T04:14:15.804043Z] 04:14:15 INFO - TEST-INFO | screentopng: exit 0 [task 2017-01-09T04:14:15.804175Z] 04:14:15 INFO - Buffered messages logged at 04:13:30 [task 2017-01-09T04:14:15.805183Z] 04:14:15 INFO - Console message: [JavaScript Error: "The character encoding of the HTML document was not declared. The document will render with garbled text in some browser configurations if the document contains characters from outside the US-ASCII range. The character encoding of the page must be declared in the document or in the transfer protocol." {file: "data:text/html,test%20Edit%20menu%20updates%20Scratchpad%20-%20bug%20699130" line: 0}] [task 2017-01-09T04:14:15.806156Z] 04:14:15 INFO - Console message: [JavaScript Warning: "Property contained reference to invalid variable. Error in parsing value for ‘background-attachment’. Falling back to ‘initial’." {file: "chrome://devtools/skin/light-theme.css" line: 16 column: 453 source: " var(--theme-body-background)"}] [task 2017-01-09T04:14:15.807535Z] 04:14:15 INFO - Console message: [JavaScript Warning: "Property contained reference to invalid variable. Error in parsing value for ‘background-clip’. Falling back to ‘initial’." {file: "chrome://devtools/skin/light-theme.css" line: 16 column: 453 source: " var(--theme-body-background)"}] [task 2017-01-09T04:14:15.809613Z] 04:14:15 INFO - Console message: [JavaScript Warning: "Property contained reference to invalid variable. Error in parsing value for ‘background-color’. Falling back to ‘initial’." {file: "chrome://devtools/skin/light-theme.css" line: 16 column: 453 source: " var(--theme-body-background)"}] [task 2017-01-09T04:14:15.812083Z] 04:14:15 INFO - Console message: [JavaScript Warning: "Property contained reference to invalid variable. Error in parsing value for ‘background-image’. Falling back to ‘initial’." {file: "chrome://devtools/skin/light-theme.css" line: 16 column: 453 source: " var(--theme-body-background)"}] [task 2017-01-09T04:14:15.816125Z] 04:14:15 INFO - Console message: [JavaScript Warning: "Property contained reference to invalid variable. Error in parsing value for ‘background-origin’. Falling back to ‘initial’." {file: "chrome://devtools/skin/light-theme.css" line: 16 column: 453 source: " var(--theme-body-background)"}] [task 2017-01-09T04:14:15.817954Z] 04:14:15 INFO - Console message: [JavaScript Warning: "Property contained reference to invalid variable. Error in parsing value for ‘background-position-x’. Falling back to ‘initial’." {file: "chrome://devtools/skin/light-theme.css" line: 16 column: 453 source: " var(--theme-body-background)"}] [task 2017-01-09T04:14:15.819801Z] 04:14:15 INFO - Console message: [JavaScript Warning: "Property contained reference to invalid variable. Error in parsing value for ‘background-position-y’. Falling back to ‘initial’." {file: "chrome://devtools/skin/light-theme.css" line: 16 column: 453 source: " var(--theme-body-background)"}] [task 2017-01-09T04:14:15.821108Z] 04:14:15 INFO - Console message: [JavaScript Warning: "Property contained reference to invalid variable. Error in parsing value for ‘background-repeat’. Falling back to ‘initial’." {file: "chrome://devtools/skin/light-theme.css" line: 16 column: 453 source: " var(--theme-body-background)"}] [task 2017-01-09T04:14:15.822368Z] 04:14:15 INFO - Console message: [JavaScript Warning: "Property contained reference to invalid variable. Error in parsing value for ‘background-size’. Falling back to ‘initial’." {file: "chrome://devtools/skin/light-theme.css" line: 16 column: 453 source: " var(--theme-body-background)"}] [task 2017-01-09T04:14:15.823965Z] 04:14:15 INFO - Console message: [JavaScript Warning: "Property contained reference to invalid variable. Error in parsing value for ‘background-attachment’. Falling back to ‘initial’." {file: "chrome://devtools/skin/light-theme.css" line: 16 column: 453 source: " var(--theme-body-background)"}] [task 2017-01-09T04:14:15.825743Z] 04:14:15 INFO - Console message: [JavaScript Warning: "Property contained reference to invalid variable. Error in parsing value for ‘background-clip’. Falling back to ‘initial’." {file: "chrome://devtools/skin/light-theme.css" line: 16 column: 453 source: " var(--theme-body-background)"}] [task 2017-01-09T04:14:15.827617Z] 04:14:15 INFO - Console message: [JavaScript Warning: "Property contained reference to invalid variable. Error in parsing value for ‘background-color’. Falling back to ‘initial’." {file: "chrome://devtools/skin/light-theme.css" line: 16 column: 453 source: " var(--theme-body-background)"}] [task 2017-01-09T04:14:15.829889Z] 04:14:15 INFO - Console message: [JavaScript Warning: "Property contained reference to invalid variable. Error in parsing value for ‘background-image’. Falling back to ‘initial’." {file: "chrome://devtools/skin/light-theme.css" line: 16 column: 453 source: " var(--theme-body-background)"}] [task 2017-01-09T04:14:15.832387Z] 04:14:15 INFO - Console message: [JavaScript Warning: "Property contained reference to invalid variable. Error in parsing value for ‘background-origin’. Falling back to ‘initial’." {file: "chrome://devtools/skin/light-theme.css" line: 16 column: 453 source: " var(--theme-body-background)"}] [task 2017-01-09T04:14:15.836373Z] 04:14:15 INFO - Console message: [JavaScript Warning: "Property contained reference to invalid variable. Error in parsing value for ‘background-position-x’. Falling back to ‘initial’." {file: "chrome://devtools/skin/light-theme.css" line: 16 column: 453 source: " var(--theme-body-background)"}] [task 2017-01-09T04:14:15.838050Z] 04:14:15 INFO - Console message: [JavaScript Warning: "Property contained reference to invalid variable. Error in parsing value for ‘background-position-y’. Falling back to ‘initial’." {file: "chrome://devtools/skin/light-theme.css" line: 16 column: 453 source: " var(--theme-body-background)"}] [task 2017-01-09T04:14:15.839066Z] 04:14:15 INFO - Console message: [JavaScript Warning: "Property contained reference to invalid variable. Error in parsing value for ‘background-repeat’. Falling back to ‘initial’." {file: "chrome://devtools/skin/light-theme.css" line: 16 column: 453 source: " var(--theme-body-background)"}] [task 2017-01-09T04:14:15.840108Z] 04:14:15 INFO - Console message: [JavaScript Warning: "Property contained reference to invalid variable. Error in parsing value for ‘background-size’. Falling back to ‘initial’." {file: "chrome://devtools/skin/light-theme.css" line: 16 column: 453 source: " var(--theme-body-background)"}] [task 2017-01-09T04:14:15.841123Z] 04:14:15 INFO - Console message: [JavaScript Warning: "Property contained reference to invalid variable. Error in parsing value for ‘border-right-color’. Falling back to ‘initial’." {file: "chrome://devtools/skin/light-theme.css" line: 252 column: 6034 source: " var(--theme-splitter-color)"}] [task 2017-01-09T04:14:15.842127Z] 04:14:15 INFO - Console message: [JavaScript Warning: "Property contained reference to invalid variable. Error in parsing value for ‘background-color’. Falling back to ‘initial’." {file: "chrome://devtools/skin/light-theme.css" line: 253 column: 6085 source: " var(--theme-sidebar-background)"}] [task 2017-01-09T04:14:15.843308Z] 04:14:15 INFO - Console message: [JavaScript Warning: "Property contained reference to invalid variable. Error in parsing value for ‘border-right-color’. Falling back to ‘initial’." {file: "chrome://devtools/skin/light-theme.css" line: 252 column: 6034 source: " var(--theme-splitter-color)"}] [task 2017-01-09T04:14:15.845451Z] 04:14:15 INFO - Console message: [JavaScript Warning: "Property contained reference to invalid variable. Error in parsing value for ‘background-color’. Falling back to ‘initial’." {file: "chrome://devtools/skin/light-theme.css" line: 253 column: 6085 source: " var(--theme-sidebar-background)"}] [task 2017-01-09T04:14:15.846463Z] 04:14:15 INFO - Console message: [JavaScript Warning: "Property contained reference to invalid variable. Error in parsing value for ‘border-right-color’. Falling back to ‘initial’." {file: "chrome://devtools/skin/light-theme.css" line: 252 column: 6034 source: " var(--theme-splitter-color)"}] [task 2017-01-09T04:14:15.847674Z] 04:14:15 INFO - Console message: [JavaScript Warning: "Property contained reference to invalid variable. Error in parsing value for ‘background-color’. Falling back to ‘initial’." {file: "chrome://devtools/skin/light-theme.css" line: 253 column: 6085 source: " var(--theme-sidebar-background)"}] [task 2017-01-09T04:14:15.850426Z] 04:14:15 INFO - Console message: [JavaScript Warning: "Property contained reference to invalid variable. Error in parsing value for ‘border-right-color’. Falling back to ‘initial’." {file: "chrome://devtools/skin/light-theme.css" line: 252 column: 6034 source: " var(--theme-splitter-color)"}] [task 2017-01-09T04:14:15.851434Z] 04:14:15 INFO - Console message: [JavaScript Warning: "Property contained reference to invalid variable. Error in parsing value for ‘background-color’. Falling back to ‘initial’." {file: "chrome://devtools/skin/light-theme.css" line: 253 column: 6085 source: " var(--theme-sidebar-background)"}] [task 2017-01-09T04:14:15.852477Z] 04:14:15 INFO - Console message: [JavaScript Warning: "Property contained reference to invalid variable. Error in parsing value for ‘border-right-color’. Falling back to ‘initial’." {file: "chrome://devtools/skin/light-theme.css" line: 252 column: 6034 source: " var(--theme-splitter-color)"}] [task 2017-01-09T04:14:15.854788Z] 04:14:15 INFO - Console message: [JavaScript Warning: "Property contained reference to invalid variable. Error in parsing value for ‘background-color’. Falling back to ‘initial’." {file: "chrome://devtools/skin/light-theme.css" line: 253 column: 6085 source: " var(--theme-sidebar-background)"}] [task 2017-01-09T04:14:15.855806Z] 04:14:15 INFO - Console message: [JavaScript Warning: "Property contained reference to invalid variable. Error in parsing value for ‘border-right-color’. Falling back to ‘initial’." {file: "chrome://devtools/skin/light-theme.css" line: 252 column: 6034 source: " var(--theme-splitter-color)"}] [task 2017-01-09T04:14:15.856831Z] 04:14:15 INFO - Console message: [JavaScript Warning: "Property contained reference to invalid variable. Error in parsing value for ‘background-color’. Falling back to ‘initial’." {file: "chrome://devtools/skin/light-theme.css" line: 253 column: 6085 source: " var(--theme-sidebar-background)"}] [task 2017-01-09T04:14:15.857885Z] 04:14:15 INFO - Console message: [JavaScript Warning: "Property contained reference to invalid variable. Error in parsing value for ‘border-right-color’. Falling back to ‘initial’." {file: "chrome://devtools/skin/light-theme.css" line: 252 column: 6034 source: " var(--theme-splitter-color)"}] [task 2017-01-09T04:14:15.858925Z] 04:14:15 INFO - Console message: [JavaScript Warning: "Property contained reference to invalid variable. Error in parsing value for ‘background-color’. Falling back to ‘initial’." {file: "chrome://devtools/skin/light-theme.css" line: 253 column: 6085 source: " var(--theme-sidebar-background)"}] [task 2017-01-09T04:14:15.859920Z] 04:14:15 INFO - Console message: [JavaScript Warning: "Property contained reference to invalid variable. Error in parsing value for ‘border-right-color’. Falling back to ‘initial’." {file: "chrome://devtools/skin/light-theme.css" line: 252 column: 6034 source: " var(--theme-splitter-color)"}] [task 2017-01-09T04:14:15.860924Z] 04:14:15 INFO - Console message: [JavaScript Warning: "Property contained reference to invalid variable. Error in parsing value for ‘background-color’. Falling back to ‘initial’." {file: "chrome://devtools/skin/light-theme.css" line: 253 column: 6085 source: " var(--theme-sidebar-background)"}] [task 2017-01-09T04:14:15.861953Z] 04:14:15 INFO - Console message: [JavaScript Warning: "Property contained reference to invalid variable. Error in parsing value for ‘border-right-color’. Falling back to ‘initial’." {file: "chrome://devtools/skin/light-theme.css" line: 252 column: 6034 source: " var(--theme-splitter-color)"}] [task 2017-01-09T04:14:15.863005Z] 04:14:15 INFO - Console message: [JavaScript Warning: "Property contained reference to invalid variable. Error in parsing value for ‘background-color’. Falling back to ‘initial’." {file: "chrome://devtools/skin/light-theme.css" line: 253 column: 6085 source: " var(--theme-sidebar-background)"}] [task 2017-01-09T04:14:15.864432Z] 04:14:15 INFO - Console message: [JavaScript Warning: "Property contained reference to invalid variable. Error in parsing value for ‘border-right-color’. Falling back to ‘initial’." {file: "chrome://devtools/skin/light-theme.css" line: 252 column: 6034 source: " var(--theme-splitter-color)"}] [task 2017-01-09T04:14:15.865562Z] 04:14:15 INFO - Console message: [JavaScript Warning: "Property contained reference to invalid variable. Error in parsing value for ‘background-color’. Falling back to ‘initial’." {file: "chrome://devtools/skin/light-theme.css" line: 253 column: 6085 source: " var(--theme-sidebar-background)"}] [task 2017-01-09T04:14:15.866602Z] 04:14:15 INFO - Console message: [JavaScript Warning: "Property contained reference to invalid variable. Error in parsing value for ‘border-right-color’. Falling back to ‘initial’." {file: "chrome://devtools/skin/light-theme.css" line: 252 column: 6034 source: " var(--theme-splitter-color)"}] [task 2017-01-09T04:14:15.867600Z] 04:14:15 INFO - Console message: [JavaScript Warning: "Property contained reference to invalid variable. Error in parsing value for ‘background-color’. Falling back to ‘initial’." {file: "chrome://devtools/skin/light-theme.css" line: 253 column: 6085 source: " var(--theme-sidebar-background)"}] [task 2017-01-09T04:14:15.868695Z] 04:14:15 INFO - Console message: [JavaScript Warning: "XUL box for toolbar element contained an inline #text child, forcing all its children to be wrapped in a block." {file: "chrome://devtools/content/sourceeditor/codemirror/codemirror.bundle.js" line: 804}] [task 2017-01-09T04:14:15.869648Z] 04:14:15 INFO - Console message: [JavaScript Warning: "Property contained reference to invalid variable. Error in parsing value for ‘border-right-color’. Falling back to ‘initial’." {file: "chrome://devtools/skin/light-theme.css" line: 252 column: 6034 source: " var(--theme-splitter-color)"}] [task 2017-01-09T04:14:15.870712Z] 04:14:15 INFO - Console message: [JavaScript Warning: "Property contained reference to invalid variable. Error in parsing value for ‘background-color’. Falling back to ‘initial’." {file: "chrome://devtools/skin/light-theme.css" line: 253 column: 6085 source: " var(--theme-sidebar-background)"}] [task 2017-01-09T04:14:15.871713Z] 04:14:15 INFO - Console message: [JavaScript Warning: "Property contained reference to invalid variable. Error in parsing value for ‘border-right-color’. Falling back to ‘initial’." {file: "chrome://devtools/skin/light-theme.css" line: 252 column: 6034 source: " var(--theme-splitter-color)"}] [task 2017-01-09T04:14:15.872797Z] 04:14:15 INFO - Console message: [JavaScript Warning: "Property contained reference to invalid variable. Error in parsing value for ‘background-color’. Falling back to ‘initial’." {file: "chrome://devtools/skin/light-theme.css" line: 253 column: 6085 source: " var(--theme-sidebar-background)"}] [task 2017-01-09T04:14:15.873677Z] 04:14:15 INFO - will test the Edit menu [task 2017-01-09T04:14:15.874545Z] 04:14:15 INFO - TEST-PASS | devtools/client/scratchpad/test/browser_scratchpad_edit_ui_updates.js | the Edit menu - [task 2017-01-09T04:14:15.875423Z] 04:14:15 INFO - TEST-PASS | devtools/client/scratchpad/test/browser_scratchpad_edit_ui_updates.js | menubar found - [task 2017-01-09T04:14:15.876302Z] 04:14:15 INFO - TEST-PASS | devtools/client/scratchpad/test/browser_scratchpad_edit_ui_updates.js | Edit menu index is correct - [task 2017-01-09T04:14:15.877542Z] 04:14:15 INFO - TEST-PASS | devtools/client/scratchpad/test/browser_scratchpad_edit_ui_updates.js | the Edit menupopup - [task 2017-01-09T04:14:15.878420Z] 04:14:15 INFO - TEST-PASS | devtools/client/scratchpad/test/browser_scratchpad_edit_ui_updates.js | the Cut menuitem - [task 2017-01-09T04:14:15.879347Z] 04:14:15 INFO - TEST-PASS | devtools/client/scratchpad/test/browser_scratchpad_edit_ui_updates.js | the Paste menuitem - [task 2017-01-09T04:14:15.880293Z] 04:14:15 INFO - Console message: [JavaScript Warning: "XUL box for toolbar element contained an inline #text child, forcing all its children to be wrapped in a block." {file: "chrome://devtools/content/scratchpad/scratchpad.xul" line: 0}] [task 2017-01-09T04:14:15.881283Z] 04:14:15 INFO - Console message: [JavaScript Warning: "XUL box for toolbar element contained an inline #text child, forcing all its children to be wrapped in a block." {file: "chrome://devtools/content/sourceeditor/codemirror/codemirror.bundle.js" line: 804}] [task 2017-01-09T04:14:15.882177Z] 04:14:15 INFO - Self xss paste tests [task 2017-01-09T04:14:15.883008Z] 04:14:15 INFO - TEST-PASS | devtools/client/scratchpad/test/browser_scratchpad_edit_ui_updates.js | Test for usage count getter - [task 2017-01-09T04:14:15.883902Z] 04:14:15 INFO - TEST-PASS | devtools/client/scratchpad/test/browser_scratchpad_edit_ui_updates.js | Self-xss notification shown - [task 2017-01-09T04:14:15.885225Z] 04:14:15 INFO - TEST-PASS | devtools/client/scratchpad/test/browser_scratchpad_edit_ui_updates.js | Paste blocked by self-xss prevention - [task 2017-01-09T04:14:15.886684Z] 04:14:15 INFO - Buffered messages logged at 04:13:31 [task 2017-01-09T04:14:15.887605Z] 04:14:15 INFO - TEST-PASS | devtools/client/scratchpad/test/browser_scratchpad_edit_ui_updates.js | cut menuitem is enabled - [task 2017-01-09T04:14:15.888491Z] 04:14:15 INFO - Buffered messages finished [task 2017-01-09T04:14:15.889395Z] 04:14:15 INFO - TEST-UNEXPECTED-FAIL | devtools/client/scratchpad/test/browser_scratchpad_edit_ui_updates.js | Test timed out - [task 2017-01-09T04:14:15.890280Z] 04:14:15 INFO - MEMORY STAT | vsize 1218MB | residentFast 288MB | heapAllocated 115MB [task 2017-01-09T04:14:15.891172Z] 04:14:15 INFO - TEST-OK | devtools/client/scratchpad/test/browser_scratchpad_edit_ui_updates.js | took 45177ms on top of that, I have done a pile of retriggers on Jan 9th to see if there is a pattern which emerges: https://treeherder.mozilla.org/#/jobs?repo=autoland&filter-searchStr=Linux%20x64%20opt%20Mochitests%20executed%20by%20TaskCluster%20test-linux64%2Fopt-mochitest-devtools-chrome-8%20tc-M(dt8)&tochange=c273666082fac0829db0ec585247dc8914abab61&fromchange=72e93073be64937b7eee2732a4cbf527e399c2ae&selectedJob=67067136 :jdescottes, I see you are the triage owner for devtools scratchpad, is there a chance you could find someone to look into this?
Flags: needinfo?(jdescottes)
based on the retriggers we might have a root cause of bug 1289536, I can look more later when I am back online.
:markh, I see you authored the two patches in bug 1289536, and according to data from a bunch of retriggers, we didn't see instances of the intermittent (in this bug) prior, and we see them consistently after: https://treeherder.mozilla.org/#/jobs?repo=autoland&filter-searchStr=Linux%20x64%20opt%20Mochitests%20executed%20by%20TaskCluster%20test-linux64%2Fopt-mochitest-devtools-chrome-8%20tc-M(dt8)&tochange=c273666082fac0829db0ec585247dc8914abab61&fromchange=72e93073be64937b7eee2732a4cbf527e399c2ae I am not clear on how sync/telemetry code could affect the scratchpad, possibly if you could take a look and see if there is something that might be connected, otherwise it will help rule this out and maybe :jdescottes might have other ideas.
Flags: needinfo?(markh)
(In reply to Joel Maher ( :jmaher) from comment #20) > I am not clear on how sync/telemetry code could affect the scratchpad, > possibly if you could take a look and see if there is something that might > be connected, otherwise it will help rule this out and maybe :jdescottes > might have other ideas. hrm - sorry, but yeah, I can't see a way that bug could have caused mochitest-browser devtools tests to fail - that code is almost certainly not exercised in those tests.
Flags: needinfo?(markh)
Looking at bug 1289536 I don't see how it could be making this scratchpad test fail. For now I can't reproduce locally on a Linux VM. I added a few logs in the test and pushed to try https://treeherder.mozilla.org/#/jobs?repo=try&revision=24a08ec1ccd80395007ee7c5feee381a6c0a2e33
Flags: needinfo?(jdescottes)
I added 20 retriggers on the autoland try run for bug 1289536, and I managed to get a failure.
this has been greatly reduced, lets lower this in priority for now
Whiteboard: [stockwell unknown]
Too late for firefox 52, mass-wontfix.
Scratchpad triage. Filter on TRIAGE-JD201705
Priority: -- → P2
Priority: P2 → P3
Status: NEW → RESOLVED
Closed: 7 years ago
Resolution: --- → INCOMPLETE
Product: Firefox → DevTools
Product: DevTools → DevTools Graveyard
You need to log in before you can comment on or make changes to this bug.