1692009 - Intermittent devtools/client/inspector/test/browser_inspector_highlighter-eyedropper-zoom.js | The eyedropper holds the expected color for the first div - Got "", expected "#aa0000"

Status: RESOLVED DUPLICATE of bug 1691392

(DevTools :: Inspector, defect, P5)

Description

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

---

[task 2021-02-10T16:36:42.941Z] 16:36:42     INFO - TEST-START | devtools/client/inspector/test/browser_inspector_highlighter-eyedropper-zoom.js
[task 2021-02-10T16:36:42.978Z] 16:36:42     INFO - GECKO(2646) | [Child 2840: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7fd65b56e800 == 2 [pid = 2840] [id = 56]
[task 2021-02-10T16:36:42.978Z] 16:36:42     INFO - GECKO(2646) | [Child 2840: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 6 (0x7fd65cf05040) [pid = 2840] [serial = 166] [outer = (nil)]
[task 2021-02-10T16:36:42.979Z] 16:36:42     INFO - GECKO(2646) | [Child 2840: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 7 (0x7fd65b571c00) [pid = 2840] [serial = 167] [outer = 0x7fd65cf05040]
[task 2021-02-10T16:36:43.101Z] 16:36:43     INFO - GECKO(2646) | [Child 2840: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 8 (0x7fd65b5bb000) [pid = 2840] [serial = 168] [outer = 0x7fd65cf05040]
[task 2021-02-10T16:36:43.258Z] 16:36:43     INFO - GECKO(2646) | [Parent 2646: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7fef6d219800 == 16 [pid = 2646] [id = 187]
[task 2021-02-10T16:36:43.258Z] 16:36:43     INFO - GECKO(2646) | [Parent 2646: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 60 (0x7fef730aa040) [pid = 2646] [serial = 437] [outer = (nil)]
[task 2021-02-10T16:36:43.258Z] 16:36:43     INFO - GECKO(2646) | [Parent 2646: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 61 (0x7fef6d21f800) [pid = 2646] [serial = 438] [outer = 0x7fef730aa040]
[task 2021-02-10T16:36:43.273Z] 16:36:43     INFO - GECKO(2646) | [Child 2840, Main Thread] WARNING: Scrolled rect smaller than scrollport?: file /builds/worker/checkouts/gecko/layout/generic/nsGfxScrollFrame.cpp:7013
[task 2021-02-10T16:36:43.309Z] 16:36:43     INFO - GECKO(2646) | [Parent 2646: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 62 (0x7fef73830800) [pid = 2646] [serial = 439] [outer = 0x7fef730aa040]
[task 2021-02-10T16:36:43.393Z] 16:36:43     INFO - GECKO(2646) | [Parent 2646, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/dom/base/nsContentUtils.cpp:3896
[task 2021-02-10T16:36:43.476Z] 16:36:43     INFO - GECKO(2646) | [Parent 2646: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7fef73826400 == 17 [pid = 2646] [id = 188]
[task 2021-02-10T16:36:43.476Z] 16:36:43     INFO - GECKO(2646) | [Parent 2646: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 63 (0x7fef73e2ee40) [pid = 2646] [serial = 440] [outer = (nil)]
[task 2021-02-10T16:36:43.477Z] 16:36:43     INFO - GECKO(2646) | [Parent 2646: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 64 (0x7fef73e8a000) [pid = 2646] [serial = 441] [outer = 0x7fef73e2ee40]
[task 2021-02-10T16:36:43.883Z] 16:36:43     INFO - GECKO(2646) | [Child 2705: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (0x7f9cd1493800) [pid = 2705] [serial = 78] [outer = (nil)] [url = about:blank]
[task 2021-02-10T16:36:43.899Z] 16:36:43     INFO - GECKO(2646) | [Child 2705: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7f9cd148f800 == 0 [pid = 2705] [id = 35] [url = about:blank]
[task 2021-02-10T16:36:44.264Z] 16:36:44     INFO - GECKO(2646) | [Child 2812: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 3 (0x7fc10f95d040) [pid = 2812] [serial = 83] [outer = (nil)] [url = about:blank]
[task 2021-02-10T16:36:44.321Z] 16:36:44     INFO - GECKO(2646) | [Parent 2646: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7fef6f7eb800 == 18 [pid = 2646] [id = 189]
[task 2021-02-10T16:36:44.322Z] 16:36:44     INFO - GECKO(2646) | [Parent 2646: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 65 (0x7fef740b3200) [pid = 2646] [serial = 442] [outer = (nil)]
[task 2021-02-10T16:36:44.323Z] 16:36:44     INFO - GECKO(2646) | [Parent 2646: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 66 (0x7fef6f7f3c00) [pid = 2646] [serial = 443] [outer = 0x7fef740b3200]
[task 2021-02-10T16:36:44.424Z] 16:36:44     INFO - GECKO(2646) | [Parent 2646, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, nullptr) failed with result 0x804B000A (NS_ERROR_MALFORMED_URI): file /builds/worker/checkouts/gecko/extensions/permissions/Permission.cpp:46
[task 2021-02-10T16:36:44.425Z] 16:36:44     INFO - GECKO(2646) | [Parent 2646, Main Thread] WARNING: NS_ENSURE_TRUE(principal) failed: file /builds/worker/checkouts/gecko/extensions/permissions/Permission.cpp:58
[task 2021-02-10T16:36:44.453Z] 16:36:44     INFO - GECKO(2646) | console.log: "[DISPATCH] action type:" "UPDATE_GRIDS"
[task 2021-02-10T16:36:44.569Z] 16:36:44     INFO - GECKO(2646) | console.log: "[DISPATCH] action type:" "CLEAR_FLEXBOX"
[task 2021-02-10T16:36:44.794Z] 16:36:44     INFO - GECKO(2646) | console.log: "[DISPATCH] action type:" "UPDATE_GRIDS"
[task 2021-02-10T16:36:44.831Z] 16:36:44     INFO - GECKO(2646) | console.log: "[DISPATCH] action type:" "CLEAR_FLEXBOX"
[task 2021-02-10T16:36:44.832Z] 16:36:44     INFO - GECKO(2646) | console.log: "[DISPATCH] action type:" "UPDATE_OFFSET_PARENT"
[task 2021-02-10T16:36:44.848Z] 16:36:44     INFO - GECKO(2646) | console.log: "[DISPATCH] action type:" "UPDATE_LAYOUT"
[task 2021-02-10T16:36:44.863Z] 16:36:44     INFO - GECKO(2646) | Zooming page to 2
[task 2021-02-10T16:36:44.863Z] 16:36:44     INFO - GECKO(2646) | [Child 2840, Main Thread] WARNING: Scrolled rect smaller than scrollport?: file /builds/worker/checkouts/gecko/layout/generic/nsGfxScrollFrame.cpp:7010
[task 2021-02-10T16:36:44.865Z] 16:36:44     INFO - GECKO(2646) | [Child 2840, Main Thread] WARNING: Scrolled rect smaller than scrollport?: file /builds/worker/checkouts/gecko/layout/generic/nsGfxScrollFrame.cpp:7013
[task 2021-02-10T16:36:45.131Z] 16:36:45     INFO - GECKO(2646) | console.log: "[DISPATCH] action type:" "CLEAR_FLEXBOX"
[task 2021-02-10T16:36:45.173Z] 16:36:45     INFO - TEST-INFO | started process screentopng
[task 2021-02-10T16:36:45.483Z] 16:36:45     INFO - TEST-INFO | screentopng: exit 0
[task 2021-02-10T16:36:45.483Z] 16:36:45     INFO - Buffered messages logged at 16:36:42
[task 2021-02-10T16:36:45.483Z] 16:36:45     INFO - Entering test bound 
[task 2021-02-10T16:36:45.483Z] 16:36:45     INFO - Adding a new tab with URL: http://example.com/document-builder.sjs?html=%0A%3Cstyle%3E%0A%20%20body%20%7B%0A%20%20%20%20margin%3A%200%3B%0A%20%20%7D%0A%20%20div%20%7B%0A%20%20%20%20height%3A%2050px%3B%0A%20%20%20%20width%3A%2050px%3B%0A%20%20%7D%0A%3C%2Fstyle%3E%0A%3Cdiv%20style%3D%22background-color%3A%20%23aa0000%3B%22%3E%3C%2Fdiv%3E%0A%3Cdiv%20style%3D%22background-color%3A%20%230bb000%3B%22%3E%3C%2Fdiv%3E%0A%3Cdiv%20style%3D%22background-color%3A%20%2300cc00%3B%22%3E%3C%2Fdiv%3E%0A%3Cdiv%20style%3D%22background-color%3A%20%23000dd0%3B%22%3E%3C%2Fdiv%3E
[task 2021-02-10T16:36:45.483Z] 16:36:45     INFO - Buffered messages logged at 16:36:43
[task 2021-02-10T16:36:45.484Z] 16:36:45     INFO - Tab added and finished loading
[task 2021-02-10T16:36:45.485Z] 16:36:45     INFO - Opening the inspector
[task 2021-02-10T16:36:45.485Z] 16:36:45     INFO - Opening the toolbox
[task 2021-02-10T16:36:45.486Z] 16:36:45     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: "http://example.com/document-builder.sjs?html=%0A%3Cstyle%3E%0A%20%20body%20%7B%0A%20%20%20%20margin%3A%200%3B%0A%20%20%7D%0A%20%20div%20%7B%0A%20%20%20%20height%3A%2050px%3B%0A%20%20%20%20width%3A%2050px%3B%0A%20%20%7D%0A%3C%2Fstyle%3E%0A%3Cdiv%20style%3D%22background-color%3A%20%23aa0000%3B%22%3E%3C%2Fdiv%3E%0A%3Cdiv%20style%3D%22background-color%3A%20%230bb000%3B%22%3E%3C%2Fdiv%3E%0A%3Cdiv%20style%3D%22background-color%3A%20%2300cc00%3B%22%3E%3C%2Fdiv%3E%0A%3Cdiv%20style%3D%22background-color%3A%20%23000d" line: 0}]
[task 2021-02-10T16:36:45.488Z] 16:36:45     INFO - Buffered messages logged at 16:36:44
[task 2021-02-10T16:36:45.488Z] 16:36:45     INFO - Toolbox opened and focused
[task 2021-02-10T16:36:45.488Z] 16:36:45     INFO - Zoom in the page
[task 2021-02-10T16:36:45.488Z] 16:36:45     INFO - Buffered messages logged at 16:36:45
[task 2021-02-10T16:36:45.489Z] 16:36:45     INFO - TEST-PASS | devtools/client/inspector/test/browser_inspector_highlighter-eyedropper-zoom.js | Eye dropper is visible - 
[task 2021-02-10T16:36:45.489Z] 16:36:45     INFO - Move mouse to 25,10
[task 2021-02-10T16:36:45.489Z] 16:36:45     INFO - Buffered messages finished
[task 2021-02-10T16:36:45.490Z] 16:36:45     INFO - TEST-UNEXPECTED-FAIL | devtools/client/inspector/test/browser_inspector_highlighter-eyedropper-zoom.js | The eyedropper holds the expected color for the first div - Got "", expected "#aa0000"
[task 2021-02-10T16:36:45.490Z] 16:36:45     INFO - Stack trace:
[task 2021-02-10T16:36:45.491Z] 16:36:45     INFO - chrome://mochikit/content/browser-test.js:test_is:1351
[task 2021-02-10T16:36:45.491Z] 16:36:45     INFO - chrome://mochitests/content/browser/devtools/client/inspector/test/head.js:checkEyeDropperColorAt:1421
[task 2021-02-10T16:36:45.492Z] 16:36:45     INFO - chrome://mochitests/content/browser/devtools/client/inspector/test/browser_inspector_highlighter-eyedropper-zoom.js:checkColorAt:49
[task 2021-02-10T16:36:45.493Z] 16:36:45     INFO - chrome://mochitests/content/browser/devtools/client/inspector/test/browser_inspector_highlighter-eyedropper-zoom.js:null:53
[task 2021-02-10T16:36:45.493Z] 16:36:45     INFO - chrome://mochikit/content/browser-test.js:Tester_execTest/<:1088
[task 2021-02-10T16:36:45.493Z] 16:36:45     INFO - chrome://mochikit/content/browser-test.js:Tester_execTest:1128
[task 2021-02-10T16:36:45.493Z] 16:36:45     INFO - chrome://mochikit/content/browser-test.js:nextTest/<:948
[task 2021-02-10T16:36:45.494Z] 16:36:45     INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<:1037
[task 2021-02-10T16:36:45.494Z] 16:36:45     INFO - Move mouse to 25,60
[task 2021-02-10T16:36:45.501Z] 16:36:45     INFO - GECKO(2646) | console.log: "[DISPATCH] action type:" "UPDATE_OFFSET_PARENT"
[task 2021-02-10T16:36:45.502Z] 16:36:45     INFO - GECKO(2646) | console.log: "[DISPATCH] action type:" "UPDATE_LAYOUT"
[task 2021-02-10T16:36:45.502Z] 16:36:45     INFO - TEST-PASS | devtools/client/inspector/test/browser_inspector_highlighter-eyedropper-zoom.js | The eyedropper holds the expected color for the second div - 
[task 2021-02-10T16:36:45.504Z] 16:36:45     INFO - Move mouse to 25,110
[task 2021-02-10T16:36:45.505Z] 16:36:45     INFO - TEST-PASS | devtools/client/inspector/test/browser_inspector_highlighter-eyedropper-zoom.js | The eyedropper holds the expected color for the third div - 
[task 2021-02-10T16:36:45.506Z] 16:36:45     INFO - Move mouse to 25,160
[task 2021-02-10T16:36:45.506Z] 16:36:45     INFO - TEST-PASS | devtools/client/inspector/test/browser_inspector_highlighter-eyedropper-zoom.js | The eyedropper holds the expected color for the fourth div - 
[task 2021-02-10T16:36:45.507Z] 16:36:45     INFO - Hide the eyedropper
[task 2021-02-10T16:36:45.508Z] 16:36:45     INFO - GECKO(2646) | console.log: "[DISPATCH] action type:" "UPDATE_OFFSET_PARENT"
[task 2021-02-10T16:36:45.512Z] 16:36:45     INFO - GECKO(2646) | console.log: "[DISPATCH] action type:" "UPDATE_LAYOUT"
[task 2021-02-10T16:36:45.553Z] 16:36:45     INFO - GECKO(2646) | Zooming page to 1
[task 2021-02-10T16:36:45.569Z] 16:36:45     INFO - GECKO(2646) | console.log: "[DISPATCH] action type:" "CLEAR_FLEXBOX"
[task 2021-02-10T16:36:45.569Z] 16:36:45     INFO - Leaving test bound 
[task 2021-02-10T16:36:45.926Z] 16:36:45     INFO - GECKO(2646) | [Child 2840: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 7 (0x7fd65b5ba000) [pid = 2840] [serial = 161] [outer = (nil)] [url = data:text/html;charset=utf-8,eye-dropper test]
[task 2021-02-10T16:36:45.926Z] 16:36:45     INFO - GECKO(2646) | [Child 2840: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 6 (0x7fd65b56fc00) [pid = 2840] [serial = 163] [outer = (nil)] [url = about:blank]
[task 2021-02-10T16:36:45.926Z] 16:36:45     INFO - GECKO(2646) | [Child 2840: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7fd65b56d800 == 1 [pid = 2840] [id = 55] [url = data:text/html;charset=utf-8,<h1 style='color:red'>HTML test page</h1>]
[task 2021-02-10T16:36:46.710Z] 16:36:46     INFO - GECKO(2646) | [Child 2812: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (0x7fc10ee8a800) [pid = 2812] [serial = 84] [outer = (nil)] [url = about:blank]
[task 2021-02-10T16:36:48.116Z] 16:36:48     INFO - GECKO(2646) | [Child 2705: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (0x7f9cd21dc040) [pid = 2705] [serial = 79] [outer = (nil)] [url = about:blank]
[task 2021-02-10T16:36:48.244Z] 16:36:48     INFO - GECKO(2646) | [Parent 2646: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 65 (0x7fef733f5800) [pid = 2646] [serial = 431] [outer = (nil)] [url = about:blank]
[task 2021-02-10T16:36:48.252Z] 16:36:48     INFO - GECKO(2646) | [Parent 2646: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7fef73895000 == 17 [pid = 2646] [id = 179] [url = chrome://devtools/content/inspector/index.xhtml]
[task 2021-02-10T16:36:48.252Z] 16:36:48     INFO - GECKO(2646) | [Parent 2646: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7fef733f0400 == 16 [pid = 2646] [id = 172] [url = about:devtools-toolbox]
[task 2021-02-10T16:36:48.252Z] 16:36:48     INFO - GECKO(2646) | [Parent 2646: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7fef7304a800 == 15 [pid = 2646] [id = 178] [url = about:devtools-toolbox]
[task 2021-02-10T16:36:48.253Z] 16:36:48     INFO - GECKO(2646) | [Parent 2646: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7fef715d1800 == 14 [pid = 2646] [id = 169] [url = about:devtools-toolbox]
[task 2021-02-10T16:36:48.253Z] 16:36:48     INFO - GECKO(2646) | [Parent 2646: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7fef715d3000 == 13 [pid = 2646] [id = 181] [url = about:devtools-toolbox]
[task 2021-02-10T16:36:48.254Z] 16:36:48     INFO - GECKO(2646) | [Parent 2646: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7fef73049800 == 12 [pid = 2646] [id = 175] [url = about:devtools-toolbox]
[task 2021-02-10T16:36:48.261Z] 16:36:48     INFO - GECKO(2646) | [Parent 2646: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7fef715d3c00 == 11 [pid = 2646] [id = 184] [url = about:devtools-toolbox]
[task 2021-02-10T16:36:48.390Z] 16:36:48     INFO - Removing tab.
[task 2021-02-10T16:36:48.392Z] 16:36:48     INFO - Waiting for event: 'TabClose' on [object XULElement].
[task 2021-02-10T16:36:48.408Z] 16:36:48     INFO - Got event: 'TabClose' on [object XULElement].
[task 2021-02-10T16:36:48.417Z] 16:36:48     INFO - GECKO(2646) | [Parent 2646, Main Thread] WARNING: NS_ENSURE_TRUE(uri) failed: file /builds/worker/checkouts/gecko/caps/BasePrincipal.cpp:1327
[task 2021-02-10T16:36:48.419Z] 16:36:48     INFO - GECKO(2646) | [Parent 2646, Main Thread] WARNING: '!inner', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorProtocol.cpp:181
[task 2021-02-10T16:36:48.423Z] 16:36:48     INFO - Tab removed and finished closing
[task 2021-02-10T16:36:48.448Z] 16:36:48     INFO - GECKO(2646) | [Parent 2646: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7fef6f7eb800 == 10 [pid = 2646] [id = 189] [url = chrome://devtools/content/inspector/markup/markup.xhtml]
[task 2021-02-10T16:36:48.474Z] 16:36:48     INFO - TEST-PASS | devtools/client/inspector/test/browser_inspector_highlighter-eyedropper-zoom.js | The main process DevToolsServer has no pending connection when the test ends - 
[task 2021-02-10T16:36:48.514Z] 16:36:48     INFO - GECKO(2646) | MEMORY STAT | vsize 3363MB | residentFast 528MB | heapAllocated 212MB
[task 2021-02-10T16:36:48.515Z] 16:36:48     INFO - TEST-OK | devtools/client/inspector/test/browser_inspector_highlighter-eyedropper-zoom.js | took 5581ms
[task 2021-02-10T16:36:48.530Z] 16:36:48     INFO - GECKO(2646) | [Child 2705: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7f9cd148f800 == 1 [pid = 2705] [id = 36]
[task 2021-02-10T16:36:48.531Z] 16:36:48     INFO - GECKO(2646) | [Child 2705: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (0x7f9cd21dc040) [pid = 2705] [serial = 81] [outer = (nil)]
[task 2021-02-10T16:36:48.532Z] 16:36:48     INFO - GECKO(2646) | [Child 2705: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (0x7f9cd1491400) [pid = 2705] [serial = 82] [outer = 0x7f9cd21dc040]
[task 2021-02-10T16:36:48.571Z] 16:36:48     INFO - checking window state
[task 2021-02-10T16:36:48.612Z] 16:36:48     INFO - TEST-START | devtools/client/inspector/test/browser_inspector_highlighter-geometry_01.js```

Comment 2

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 1
• autoland: 4

Platform and build breakdown:

• linux1804-64: 5
  • debug: 5

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1692009&startday=2021-02-08&endday=2021-02-14&tree=all