1476095 - Intermittent devtools/client/inspector/fonts/test/browser_fontinspector_editor-font-size-conversion.js | Font size in font editor is 100% - Got 36px, expected 100%

Status: RESOLVED FIXED

(DevTools :: Inspector, defect, P2)

Description

[Treeherder Bug Filer]

Filed by: rgurzau [at] mozilla.com

https://treeherder.mozilla.org/logviewer.html#?job_id=188430854&repo=mozilla-inbound

https://queue.taskcluster.net/v1/task/OC2oX2VARHC4XCzxSt_MjA/runs/0/artifacts/public/logs/live_backing.log

[task 2018-07-16T19:24:10.116Z] 19:24:10     INFO - TEST-START | devtools/client/inspector/fonts/test/browser_fontinspector_editor-font-size-conversion.js
[task 2018-07-16T19:24:10.233Z] 19:24:10     INFO - GECKO(2512) | ++DOCSHELL 0x7f73f25c1000 == 1 [pid = 2640] [id = {e1a6522a-ce01-4bba-9778-f2713fb2299c}]
[task 2018-07-16T19:24:10.235Z] 19:24:10     INFO - GECKO(2512) | ++DOMWINDOW == 1 (0x7f73fa2ed400) [pid = 2640] [serial = 6] [outer = (nil)]
[task 2018-07-16T19:24:10.336Z] 19:24:10     INFO - GECKO(2512) | ++DOMWINDOW == 2 (0x7f73f2533400) [pid = 2640] [serial = 7] [outer = 0x7f73fa2ed400]
[task 2018-07-16T19:24:10.566Z] 19:24:10     INFO - GECKO(2512) | ++DOMWINDOW == 3 (0x7f73f2537800) [pid = 2640] [serial = 8] [outer = 0x7f73fa2ed400]
[task 2018-07-16T19:24:10.611Z] 19:24:10     INFO - GECKO(2512) | ++DOCSHELL 0x7f73f484d000 == 2 [pid = 2640] [id = {3c2a35cd-a77f-4a5d-9fee-2085065a08a5}]
[task 2018-07-16T19:24:10.611Z] 19:24:10     INFO - GECKO(2512) | ++DOMWINDOW == 4 (0x7f73fc69ec00) [pid = 2640] [serial = 9] [outer = (nil)]
[task 2018-07-16T19:24:10.754Z] 19:24:10     INFO - GECKO(2512) | --DOMWINDOW == 31 (0x7f8b2b3a9400) [pid = 2512] [serial = 36] [outer = (nil)] [url = about:blank]
[task 2018-07-16T19:24:10.754Z] 19:24:10     INFO - GECKO(2512) | --DOMWINDOW == 30 (0x7f8b05e21e00) [pid = 2512] [serial = 38] [outer = (nil)] [url = about:blank]
[task 2018-07-16T19:24:10.774Z] 19:24:10     INFO - GECKO(2512) | ++DOMWINDOW == 5 (0x7f73f253c000) [pid = 2640] [serial = 10] [outer = 0x7f73fc69ec00]
[task 2018-07-16T19:24:11.039Z] 19:24:11     INFO - GECKO(2512) | ++DOCSHELL 0x7f8b05d7e800 == 7 [pid = 2512] [id = {fe7a8931-b4ef-4356-8ba3-42c600a6b374}]
[task 2018-07-16T19:24:11.039Z] 19:24:11     INFO - GECKO(2512) | ++DOMWINDOW == 31 (0x7f8b05909000) [pid = 2512] [serial = 43] [outer = (nil)]
[task 2018-07-16T19:24:11.039Z] 19:24:11     INFO - GECKO(2512) | ++DOMWINDOW == 32 (0x7f8b056d7400) [pid = 2512] [serial = 44] [outer = 0x7f8b05909000]
[task 2018-07-16T19:24:11.156Z] 19:24:11     INFO - GECKO(2512) | ++DOMWINDOW == 33 (0x7f8b05f8c000) [pid = 2512] [serial = 45] [outer = 0x7f8b05909000]
[task 2018-07-16T19:24:12.156Z] 19:24:12     INFO - GECKO(2512) | ++DOCSHELL 0x7f8b1703c000 == 8 [pid = 2512] [id = {41261b65-705e-4bfc-be0b-26616a340274}]
[task 2018-07-16T19:24:12.156Z] 19:24:12     INFO - GECKO(2512) | ++DOMWINDOW == 34 (0x7f8b1ce3c400) [pid = 2512] [serial = 46] [outer = (nil)]
[task 2018-07-16T19:24:12.156Z] 19:24:12     INFO - GECKO(2512) | ++DOMWINDOW == 35 (0x7f8b29176c00) [pid = 2512] [serial = 47] [outer = 0x7f8b1ce3c400]
[task 2018-07-16T19:24:12.254Z] 19:24:12     INFO - GECKO(2512) | ++DOCSHELL 0x7f8b0510c000 == 9 [pid = 2512] [id = {e5efa127-f83c-4810-94da-705f8d00b7c6}]
[task 2018-07-16T19:24:12.254Z] 19:24:12     INFO - GECKO(2512) | ++DOMWINDOW == 36 (0x7f8b15d6c200) [pid = 2512] [serial = 48] [outer = (nil)]
[task 2018-07-16T19:24:12.256Z] 19:24:12     INFO - GECKO(2512) | ++DOMWINDOW == 37 (0x7f8b46c1bc00) [pid = 2512] [serial = 49] [outer = 0x7f8b15d6c200]
[task 2018-07-16T19:24:12.786Z] 19:24:12     INFO - GECKO(2512) | ++DOCSHELL 0x7f8b05119000 == 10 [pid = 2512] [id = {17abc22d-a1c5-4248-a63d-4a80f13501d5}]
[task 2018-07-16T19:24:12.787Z] 19:24:12     INFO - GECKO(2512) | ++DOMWINDOW == 38 (0x7f8b0bc72e00) [pid = 2512] [serial = 50] [outer = (nil)]
[task 2018-07-16T19:24:12.791Z] 19:24:12     INFO - GECKO(2512) | ++DOMWINDOW == 39 (0x7f8b0599f000) [pid = 2512] [serial = 51] [outer = 0x7f8b0bc72e00]
[task 2018-07-16T19:24:12.848Z] 19:24:12     INFO - GECKO(2512) | ++DOCSHELL 0x7f8b0511e000 == 11 [pid = 2512] [id = {2fc344e9-16cd-4ab6-81a4-14d3d5e73edd}]
[task 2018-07-16T19:24:12.851Z] 19:24:12     INFO - GECKO(2512) | ++DOMWINDOW == 40 (0x7f8b111e3200) [pid = 2512] [serial = 52] [outer = (nil)]
[task 2018-07-16T19:24:13.054Z] 19:24:13     INFO - GECKO(2512) | console.log: "[DISPATCH]" "{\n  \"type\": \"UPDATE_FONTS\",\n  \"fonts\": [],\n  \"otherFonts\": []\n}"
[task 2018-07-16T19:24:13.062Z] 19:24:13     INFO - GECKO(2512) | console.log: "[DISPATCH]" "{\n  \"type\": \"RESET_EDITOR\"\n}"
[task 2018-07-16T19:24:13.078Z] 19:24:13     INFO - GECKO(2512) | console.log: "[DISPATCH]" "{\n  \"type\": \"UPDATE_FONTS\",\n  \"fonts\": [],\n  \"otherFonts\": []\n}"
[task 2018-07-16T19:24:13.081Z] 19:24:13     INFO - GECKO(2512) | console.log: "[DISPATCH]" "{\n  \"type\": \"RESET_EDITOR\"\n}"
[task 2018-07-16T19:24:13.166Z] 19:24:13     INFO - GECKO(2512) | ++DOMWINDOW == 41 (0x7f8b0599e800) [pid = 2512] [serial = 53] [outer = 0x7f8b111e3200]
[task 2018-07-16T19:24:13.285Z] 19:24:13     INFO - GECKO(2512) | --DOCSHELL 0x7f8b05119000 == 10 [pid = 2512] [id = {17abc22d-a1c5-4248-a63d-4a80f13501d5}]
[task 2018-07-16T19:24:14.296Z] 19:24:14     INFO - GECKO(2512) | console.log: "[DISPATCH]"
[task 2018-07-16T19:24:14.413Z] 19:24:14     INFO - GECKO(2512) | console.log: "[DISPATCH]" "{\n  \"type\": \"UPDATE_EDITOR_STATE\",\n  \"fonts\": [\n    {\n      \"name\": \"Liberation Sans\",\n      \"CSSFamilyName\": \"Liberation Sans\",\n      \"CSSGeneric\": null,\n      \"srcIndex\": -1,\n      \"URI\": \"\",\n      \"format\": \"\",\n      \"localName\": \"\",\n      \"metadata\": \"\",\n      \"weight\": 400,\n      \"style\": \"\"\n    }\n  ],\n  \"families\": {\n    \"used\": [\n      \"Liberation Sans\"\n    ],\n    \"notUsed\": [\n      \"Arial\"\n    ]\n  },\n  \"properties\": {\n    \"font-family\": \"Arial, \\\"Liberation Sans\\\"\",\n    \"font-optical-sizing\": \"\",\n    \"font-size\": \"36px\",\n    \"font-stretch\": \"100%\",\n    \"font-style\": \"normal\",\n    \"font-variation-settings\": \"\",\n    \"font-weight\": \"400\"\n  }\n}"
[task 2018-07-16T19:24:15.907Z] 19:24:15     INFO - GECKO(2512) | console.log: "[DISPATCH]"
[task 2018-07-16T19:24:15.948Z] 19:24:15     INFO - GECKO(2512) | console.log: "[DISPATCH]" "{\n  \"type\": \"UPDATE_EDITOR_STATE\",\n  \"fonts\": [\n    {\n      \"name\": \"Liberation Sans\",\n      \"CSSFamilyName\": \"Liberation Sans\",\n      \"CSSGeneric\": null,\n      \"srcIndex\": -1,\n      \"URI\": \"\",\n      \"format\": \"\",\n      \"localName\": \"\",\n      \"metadata\": \"\",\n      \"weight\": 400,\n      \"style\": \"\"\n    }\n  ],\n  \"families\": {\n    \"used\": [\n      \"Liberation Sans\"\n    ],\n    \"notUsed\": [\n      \"Arial\"\n    ]\n  },\n  \"properties\": {\n    \"font-family\": \"Arial, \\\"Liberation Sans\\\"\",\n    \"font-optical-sizing\": \"\",\n    \"font-size\": \"36px\",\n    \"font-stretch\": \"100%\",\n    \"font-style\": \"normal\",\n    \"font-variation-settings\": \"\",\n    \"font-weight\": \"400\"\n  }\n}"
[task 2018-07-16T19:24:16.529Z] 19:24:16     INFO - GECKO(2512) | console.log: "[DISPATCH]"
[task 2018-07-16T19:24:16.573Z] 19:24:16     INFO - GECKO(2512) | console.log: "[DISPATCH]"
[task 2018-07-16T19:24:16.791Z] 19:24:16     INFO - GECKO(2512) | console.log: "[DISPATCH]" "{\n  \"type\": \"UPDATE_PROPERTY_VALUE\",\n  \"property\": \"font-size\",\n  \"value\": \"36px\"\n}"
[task 2018-07-16T19:24:17.136Z] 19:24:17     INFO - GECKO(2512) | console.log: "[DISPATCH]"
[task 2018-07-16T19:24:17.442Z] 19:24:17     INFO - GECKO(2512) | console.log: "[DISPATCH]"
[task 2018-07-16T19:24:17.612Z] 19:24:17     INFO - GECKO(2512) | console.log: "[DISPATCH]" "{\n  \"type\": \"UPDATE_PROPERTY_VALUE\",\n  \"property\": \"font-size\",\n  \"value\": \"100%\"\n}"
[task 2018-07-16T19:24:17.729Z] 19:24:17     INFO - GECKO(2512) | console.log: "[DISPATCH]"
[task 2018-07-16T19:24:18.002Z] 19:24:18     INFO - TEST-INFO | started process screentopng
[task 2018-07-16T19:24:18.893Z] 19:24:18     INFO - TEST-INFO | screentopng: exit 0
[task 2018-07-16T19:24:18.893Z] 19:24:18     INFO - Buffered messages logged at 19:24:10
[task 2018-07-16T19:24:18.893Z] 19:24:18     INFO - Entering test bound 
[task 2018-07-16T19:24:18.895Z] 19:24:18     INFO - Adding a new tab with URL: http://example.com/browser/devtools/client/inspector/fonts/test/browser_fontinspector.html
[task 2018-07-16T19:24:18.896Z] 19:24:18     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/browser/devtools/client/inspector/fonts/test/browser_fontinspector.html" line: 0}]
[task 2018-07-16T19:24:18.896Z] 19:24:18     INFO - Tab added and finished loading
[task 2018-07-16T19:24:18.897Z] 19:24:18     INFO - Opening the inspector
[task 2018-07-16T19:24:18.897Z] 19:24:18     INFO - Opening the toolbox
[task 2018-07-16T19:24:18.897Z] 19:24:18     INFO - Console message: [JavaScript Error: "downloadable font: download failed (font-family: "bar" style:normal weight:400 stretch:100 src index:0): status=2147746065 source: http://example.com/browser/devtools/client/inspector/fonts/test/bad/font/name.ttf" {file: "unknown" line: 4 column: 13 source: "@font-face {
[task 2018-07-16T19:24:18.898Z] 19:24:18     INFO -   font-family: bar;
[task 2018-07-16T19:24:18.898Z] 19:24:18     INFO -   src: url("bad/font/name.ttf"), url("ostrich-regular.ttf") format("truetype");
[task 2018-07-16T19:24:18.899Z] 19:24:18     INFO - }"}]
[task 2018-07-16T19:24:18.900Z] 19:24:18     INFO - Buffered messages logged at 19:24:15
[task 2018-07-16T19:24:18.902Z] 19:24:18     INFO - Toolbox opened and focused
[task 2018-07-16T19:24:18.903Z] 19:24:18     INFO - Selecting the node for 'body'
[task 2018-07-16T19:24:18.905Z] 19:24:18     INFO - Selecting the node for 'div'
[task 2018-07-16T19:24:18.906Z] 19:24:18     INFO - Buffered messages logged at 19:24:16
[task 2018-07-16T19:24:18.907Z] 19:24:18     INFO - Check that font editor shows font-size value in original units
[task 2018-07-16T19:24:18.910Z] 19:24:18     INFO - TEST-PASS | devtools/client/inspector/fonts/test/browser_fontinspector_editor-font-size-conversion.js | Original unit for font size is em - 
[task 2018-07-16T19:24:18.913Z] 19:24:18     INFO - TEST-PASS | devtools/client/inspector/fonts/test/browser_fontinspector_editor-font-size-conversion.js | Original font size is 1em - 
[task 2018-07-16T19:24:18.915Z] 19:24:18     INFO - Convert font-size from 1em to px
[task 2018-07-16T19:24:18.915Z] 19:24:18     INFO - Waiting for font editor to re-render
[task 2018-07-16T19:24:18.920Z] 19:24:18     INFO - Buffered messages logged at 19:24:17
[task 2018-07-16T19:24:18.923Z] 19:24:18     INFO - Waiting for font-size unit dropdown to re-render with selected value: px
[task 2018-07-16T19:24:18.924Z] 19:24:18     INFO - Waiting for testactor reflow
[task 2018-07-16T19:24:18.924Z] 19:24:18     INFO - Check that font editor font-size value is converted to px
[task 2018-07-16T19:24:18.925Z] 19:24:18     INFO - TEST-PASS | devtools/client/inspector/fonts/test/browser_fontinspector_editor-font-size-conversion.js | Font size unit is converted to px - 
[task 2018-07-16T19:24:18.929Z] 19:24:18     INFO - TEST-PASS | devtools/client/inspector/fonts/test/browser_fontinspector_editor-font-size-conversion.js | Font size in font editor is 36px - 
[task 2018-07-16T19:24:18.932Z] 19:24:18     INFO - Check that inline style font-size value is converted to px
[task 2018-07-16T19:24:18.933Z] 19:24:18     INFO - TEST-PASS | devtools/client/inspector/fonts/test/browser_fontinspector_editor-font-size-conversion.js | Font size on inline style is 36px - 
[task 2018-07-16T19:24:18.934Z] 19:24:18     INFO - Convert font-size from 36px to %
[task 2018-07-16T19:24:18.935Z] 19:24:18     INFO - Waiting for font editor to re-render
[task 2018-07-16T19:24:18.936Z] 19:24:18     INFO - Waiting for font-size unit dropdown to re-render with selected value: %
[task 2018-07-16T19:24:18.936Z] 19:24:18     INFO - Waiting for testactor reflow
[task 2018-07-16T19:24:18.937Z] 19:24:18     INFO - Check that font editor font-size value is converted to %
[task 2018-07-16T19:24:18.938Z] 19:24:18     INFO - Buffered messages finished
[task 2018-07-16T19:24:18.939Z] 19:24:18     INFO - TEST-UNEXPECTED-FAIL | devtools/client/inspector/fonts/test/browser_fontinspector_editor-font-size-conversion.js | Font size unit is converted to % - Got px, expected %
[task 2018-07-16T19:24:18.940Z] 19:24:18     INFO - Stack trace:
[task 2018-07-16T19:24:18.940Z] 19:24:18     INFO - chrome://mochikit/content/browser-test.js:test_is:1305
[task 2018-07-16T19:24:18.941Z] 19:24:18     INFO - chrome://mochitests/content/browser/devtools/client/inspector/fonts/test/browser_fontinspector_editor-font-size-conversion.js:null:53
[task 2018-07-16T19:24:18.942Z] 19:24:18     INFO - chrome://mochikit/content/browser-test.js:Tester_execTest/<:1103
[task 2018-07-16T19:24:18.942Z] 19:24:18     INFO - chrome://mochikit/content/browser-test.js:Tester_execTest:1094
[task 2018-07-16T19:24:18.943Z] 19:24:18     INFO - chrome://mochikit/content/browser-test.js:nextTest/<:996
[task 2018-07-16T19:24:18.944Z] 19:24:18     INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<:795
[task 2018-07-16T19:24:18.945Z] 19:24:18     INFO - Not taking screenshot here: see the one that was previously logged
[task 2018-07-16T19:24:18.945Z] 19:24:18     INFO - TEST-UNEXPECTED-FAIL | devtools/client/inspector/fonts/test/browser_fontinspector_editor-font-size-conversion.js | Font size in font editor is 100% - Got 36px, expected 100%
[task 2018-07-16T19:24:18.946Z] 19:24:18     INFO - Stack trace:
[task 2018-07-16T19:24:18.947Z] 19:24:18     INFO - chrome://mochikit/content/browser-test.js:test_is:1305
[task 2018-07-16T19:24:18.948Z] 19:24:18     INFO - chrome://mochitests/content/browser/devtools/client/inspector/fonts/test/browser_fontinspector_editor-font-size-conversion.js:null:54
[task 2018-07-16T19:24:18.948Z] 19:24:18     INFO - chrome://mochikit/content/browser-test.js:Tester_execTest/<:1103
[task 2018-07-16T19:24:18.949Z] 19:24:18     INFO - chrome://mochikit/content/browser-test.js:Tester_execTest:1094
[task 2018-07-16T19:24:18.950Z] 19:24:18     INFO - chrome://mochikit/content/browser-test.js:nextTest/<:996
[task 2018-07-16T19:24:18.950Z] 19:24:18     INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<:795
[task 2018-07-16T19:24:18.951Z] 19:24:18     INFO - Check that inline style font-size value is converted to %

Comment 1

[Joel Maher ( :jmaher ) (UTC -8)]

this test reproduces on this revision and previous revision with test-verify:
https://treeherder.mozilla.org/#/jobs?repo=mozilla-inbound&filter-searchStr=-bf%20linux&tochange=d75a549c4e930a50b240b0d28f574dd211d6be28&fromchange=896f75ea76345497797778f66b7ad9386052229a

appears to be related to other issues with the same test.

Comment 2

[Intermittent Failures Robot]

7 failures in 653 pushes (0.011 failures/push) were associated with this bug in the last 7 days.    

Repository breakdown:
* autoland: 4
* mozilla-inbound: 2
* try: 1

Platform breakdown:
* linux64: 7

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1476095&startday=2018-07-16&endday=2018-07-22&tree=trunk

Comment 3

[meck93]

We are trying to build a tool to automatically classify intermittent failures, which would provide a starting point for fixing the bug, reducing the manual work for the developers.
We are collecting some feedback on the results, to see if they’re good enough and where we need to improve.
    
For this bug, the tool says that the intermittent failure is most likely a:
Concurrency Issue: This includes tests in which failures occur due to thread management issues (different threads or their outcomes depending on an implicit ordering), race conditions and/or deadlocks, and issues related to an asynchronous waits (e.g. a process trying to access an external resource or continuing before the external resource is available).


Once you’re done investigating and/or fixing the bug, could you tell me:
- Did the tool correctly recognize the type of intermittent failure?
- Did the information from the tool help your analysis, the bug fixing process, or anything in the process? (please also let us know how the tool was useful and/or what would improve the tool's usefulness for you)

Comment 4

[Razvan Caliman [:rcaliman]]

The cause of this intermittent failure was addressed in Bug 1475782. No new failures since that landed. Can we close this bug?