1622057 - Intermittent browser/base/content/test/outOfProcess/browser_controller.js | step 0 root focused copy - Got false, expected true | step X <some text>

Status: RESOLVED FIXED

(Core :: DOM: Editor, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: btara [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=292876590&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/F0QtvsWvQw-8NquFxfFvgQ/runs/0/artifacts/public/logs/live_backing.log
Reftest URL: https://hg.mozilla.org/mozilla-central/raw-file/tip/layout/tools/reftest/reftest-analyzer.xhtml#logurl=https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/F0QtvsWvQw-8NquFxfFvgQ/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1

---

[task 2020-03-12T17:28:32.400Z] 17:28:32     INFO - TEST-START | browser/base/content/test/outOfProcess/browser_controller.js
[task 2020-03-12T17:28:32.430Z] 17:28:32     INFO - GECKO(3849) | [Child 3994: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7f379c818000 == 1 [pid = 3994] [id = {d99e499e-6dc8-4b5c-a5a6-37aa9d433e7e}]
[task 2020-03-12T17:28:32.431Z] 17:28:32     INFO - GECKO(3849) | [Child 3994: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (0x7f37b7d93350) [pid = 3994] [serial = 5] [outer = (nil)]
[task 2020-03-12T17:28:32.431Z] 17:28:32     INFO - GECKO(3849) | [Child 3994, Main Thread] WARNING: NS_ENSURE_TRUE(mPresShell) failed: file /builds/worker/checkouts/gecko/layout/base/nsPresContext.cpp, line 839
[task 2020-03-12T17:28:32.432Z] 17:28:32     INFO - GECKO(3849) | [Child 3994: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (0x7f379d38e800) [pid = 3994] [serial = 6] [outer = 0x7f37b7d93350]
[task 2020-03-12T17:28:32.467Z] 17:28:32     INFO - GECKO(3849) | [Parent 3849, Main Thread] WARNING: '!mName', file /builds/worker/checkouts/gecko/editor/libeditor/EditAggregateTransaction.cpp, line 91
[task 2020-03-12T17:28:32.467Z] 17:28:32     INFO - GECKO(3849) | [Parent 3849, Main Thread] WARNING: EditAggregationTransaction::GetName() failed: 'NS_SUCCEEDED(rv)', file /builds/worker/checkouts/gecko/editor/libeditor/PlaceholderTransaction.cpp, line 217
[task 2020-03-12T17:28:32.468Z] 17:28:32     INFO - GECKO(3849) | [Parent 3849, Main Thread] WARNING: nsIAbsorbingTransaction::GetTxnName() failed, but ignored: 'NS_SUCCEEDED(rvIgnored)', file /builds/worker/checkouts/gecko/editor/libeditor/PlaceholderTransaction.cpp, line 188
[task 2020-03-12T17:28:32.469Z] 17:28:32     INFO - GECKO(3849) | [Parent 3849, Main Thread] WARNING: '!mName', file /builds/worker/checkouts/gecko/editor/libeditor/EditAggregateTransaction.cpp, line 91
[task 2020-03-12T17:28:32.470Z] 17:28:32     INFO - GECKO(3849) | [Parent 3849, Main Thread] WARNING: EditAggregationTransaction::GetName() failed: 'NS_SUCCEEDED(rv)', file /builds/worker/checkouts/gecko/editor/libeditor/PlaceholderTransaction.cpp, line 217
[task 2020-03-12T17:28:32.470Z] 17:28:32     INFO - GECKO(3849) | [Parent 3849, Main Thread] WARNING: nsIAbsorbingTransaction::GetTxnName() failed, but ignored: 'NS_SUCCEEDED(rvIgnored)', file /builds/worker/checkouts/gecko/editor/libeditor/PlaceholderTransaction.cpp, line 188
[task 2020-03-12T17:28:32.771Z] 17:28:32     INFO - GECKO(3849) | [Child 3994: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (0x7f379d399800) [pid = 3994] [serial = 7] [outer = 0x7f37b7d93350]
[task 2020-03-12T17:28:32.840Z] 17:28:32     INFO - GECKO(3849) | [Child 3994: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7f379ca06000 == 2 [pid = 3994] [id = {0dc622ce-a84a-4ff7-875a-14f24ebb8f59}]
[task 2020-03-12T17:28:32.841Z] 17:28:32     INFO - GECKO(3849) | [Child 3994: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 4 (0x7f37b7d93520) [pid = 3994] [serial = 8] [outer = (nil)]
[task 2020-03-12T17:28:32.841Z] 17:28:32     INFO - GECKO(3849) | [Child 3994: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7f379ca06800 == 3 [pid = 3994] [id = {db1e54f9-85cd-4537-a717-3e6e668d48a1}]
[task 2020-03-12T17:28:32.842Z] 17:28:32     INFO - GECKO(3849) | [Child 3994: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 5 (0x7f37b7d936f0) [pid = 3994] [serial = 9] [outer = (nil)]
[task 2020-03-12T17:28:32.861Z] 17:28:32     INFO - GECKO(3849) | [Parent 3849, Main Thread] WARNING: '!mName', file /builds/worker/checkouts/gecko/editor/libeditor/EditAggregateTransaction.cpp, line 91
[task 2020-03-12T17:28:32.862Z] 17:28:32     INFO - GECKO(3849) | [Parent 3849, Main Thread] WARNING: EditAggregationTransaction::GetName() failed: 'NS_SUCCEEDED(rv)', file /builds/worker/checkouts/gecko/editor/libeditor/PlaceholderTransaction.cpp, line 217
[task 2020-03-12T17:28:32.863Z] 17:28:32     INFO - GECKO(3849) | [Parent 3849, Main Thread] WARNING: nsIAbsorbingTransaction::GetTxnName() failed, but ignored: 'NS_SUCCEEDED(rvIgnored)', file /builds/worker/checkouts/gecko/editor/libeditor/PlaceholderTransaction.cpp, line 188
[task 2020-03-12T17:28:32.901Z] 17:28:32     INFO - GECKO(3849) | [Parent 3849, Main Thread] WARNING: '!mName', file /builds/worker/checkouts/gecko/editor/libeditor/EditAggregateTransaction.cpp, line 91
[task 2020-03-12T17:28:32.904Z] 17:28:32     INFO - GECKO(3849) | [Parent 3849, Main Thread] WARNING: EditAggregationTransaction::GetName() failed: 'NS_SUCCEEDED(rv)', file /builds/worker/checkouts/gecko/editor/libeditor/PlaceholderTransaction.cpp, line 217
[task 2020-03-12T17:28:32.905Z] 17:28:32     INFO - GECKO(3849) | [Parent 3849, Main Thread] WARNING: nsIAbsorbingTransaction::GetTxnName() failed, but ignored: 'NS_SUCCEEDED(rvIgnored)', file /builds/worker/checkouts/gecko/editor/libeditor/PlaceholderTransaction.cpp, line 188
[task 2020-03-12T17:28:32.981Z] 17:28:32     INFO - GECKO(3849) | [Child 3994: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 6 (0x7f37a2988800) [pid = 3994] [serial = 10] [outer = 0x7f37b7d93520]
[task 2020-03-12T17:28:33.001Z] 17:28:33     INFO - GECKO(3849) | [Child 3994: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 7 (0x7f37a298b000) [pid = 3994] [serial = 11] [outer = 0x7f37b7d936f0]
[task 2020-03-12T17:28:33.021Z] 17:28:33     INFO - GECKO(3849) | [Child 3994: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7f379ca0a000 == 4 [pid = 3994] [id = {45e91cf5-f452-4834-aa2c-12115d90629a}]
[task 2020-03-12T17:28:33.023Z] 17:28:33     INFO - GECKO(3849) | [Child 3994: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 8 (0x7f37b7d938c0) [pid = 3994] [serial = 12] [outer = (nil)]
[task 2020-03-12T17:28:33.039Z] 17:28:33     INFO - GECKO(3849) | [Child 3994: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7f379ca0b800 == 5 [pid = 3994] [id = {7b8371fe-4d99-4276-aec4-ff400a715a6b}]
[task 2020-03-12T17:28:33.040Z] 17:28:33     INFO - GECKO(3849) | [Child 3994: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 9 (0x7f37b7d93a90) [pid = 3994] [serial = 13] [outer = (nil)]
[task 2020-03-12T17:28:33.041Z] 17:28:33     INFO - GECKO(3849) | [Child 3994: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7f379ca0c800 == 6 [pid = 3994] [id = {2dc606f8-a8e7-42d1-bab2-b87ace2fcdf6}]
[task 2020-03-12T17:28:33.042Z] 17:28:33     INFO - GECKO(3849) | [Child 3994: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 10 (0x7f37b7d93c60) [pid = 3994] [serial = 14] [outer = (nil)]
[task 2020-03-12T17:28:33.043Z] 17:28:33     INFO - GECKO(3849) | [Child 3994: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7f379ca0d000 == 7 [pid = 3994] [id = {73a77db0-8781-41e8-bbbb-8c7c0f038935}]
[task 2020-03-12T17:28:33.044Z] 17:28:33     INFO - GECKO(3849) | [Child 3994: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 11 (0x7f37b7d93e30) [pid = 3994] [serial = 15] [outer = (nil)]
[task 2020-03-12T17:28:33.044Z] 17:28:33     INFO - GECKO(3849) | [Child 3994: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7f379ca0d800 == 8 [pid = 3994] [id = {05e15715-7220-417a-8649-efcb313283d7}]
[task 2020-03-12T17:28:33.045Z] 17:28:33     INFO - GECKO(3849) | [Child 3994: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 12 (0x7f379bc19090) [pid = 3994] [serial = 16] [outer = (nil)]
[task 2020-03-12T17:28:33.097Z] 17:28:33     INFO - GECKO(3849) | [Child 3994: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 13 (0x7f379bc0dc00) [pid = 3994] [serial = 17] [outer = 0x7f37b7d938c0]
[task 2020-03-12T17:28:33.118Z] 17:28:33     INFO - GECKO(3849) | [Child 3994: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 14 (0x7f379bc10800) [pid = 3994] [serial = 18] [outer = 0x7f37b7d93a90]
[task 2020-03-12T17:28:33.153Z] 17:28:33     INFO - GECKO(3849) | [Child 3994: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 15 (0x7f379bc78800) [pid = 3994] [serial = 19] [outer = 0x7f37b7d93c60]
[task 2020-03-12T17:28:33.169Z] 17:28:33     INFO - GECKO(3849) | [Child 3994: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 16 (0x7f379bc7b400) [pid = 3994] [serial = 20] [outer = 0x7f37b7d93e30]
[task 2020-03-12T17:28:33.205Z] 17:28:33     INFO - GECKO(3849) | [Child 3994: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 17 (0x7f379bce9c00) [pid = 3994] [serial = 21] [outer = 0x7f379bc19090]
[task 2020-03-12T17:28:33.375Z] 17:28:33     INFO - GECKO(3849) | [Child 4114: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 9 (0x7f8ba5741800) [pid = 4114] [serial = 2] [outer = (nil)] [url = about:blank]
[task 2020-03-12T17:28:33.376Z] 17:28:33     INFO - GECKO(3849) | [Child 4114: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 8 (0x7f8b9df69000) [pid = 4114] [serial = 15] [outer = (nil)] [url = https://test1.example.org/browser/browser/base/content/test/outOfProcess/file_innerframe.html]
[task 2020-03-12T17:28:33.377Z] 17:28:33     INFO - GECKO(3849) | [Child 4114: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 7 (0x7f8b9df66400) [pid = 4114] [serial = 14] [outer = (nil)] [url = https://www.mozilla.org/browser/browser/base/content/test/outOfProcess/file_innerframe.html]
[task 2020-03-12T17:28:33.378Z] 17:28:33     INFO - GECKO(3849) | [Child 4114: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 6 (0x7f8b9dfe4800) [pid = 4114] [serial = 17] [outer = (nil)] [url = https://example.com/browser/browser/base/content/test/outOfProcess/file_innerframe.html]
[task 2020-03-12T17:28:33.381Z] 17:28:33     INFO - GECKO(3849) | [Child 4114: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 5 (0x7f8ba5772800) [pid = 4114] [serial = 16] [outer = (nil)] [url = https://w3c-test.org/browser/browser/base/content/test/outOfProcess/file_innerframe.html]
[task 2020-03-12T17:28:33.381Z] 17:28:33     INFO - GECKO(3849) | [Child 4114: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 4 (0x7f8ba5779800) [pid = 4114] [serial = 13] [outer = (nil)] [url = https://www.mozilla.org/browser/browser/base/content/test/outOfProcess/file_innerframe.html]
[task 2020-03-12T17:28:33.386Z] 17:28:33     INFO - GECKO(3849) | [Child 4114: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 3 (0x7f8ba56a7400) [pid = 4114] [serial = 7] [outer = (nil)] [url = https://test1.example.org/browser/browser/base/content/test/outOfProcess/file_frame2.html]
[task 2020-03-12T17:28:33.388Z] 17:28:33     INFO - GECKO(3849) | [Child 4114: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (0x7f8ba56a5000) [pid = 4114] [serial = 6] [outer = (nil)] [url = https://www.mozilla.org/browser/browser/base/content/test/outOfProcess/file_frame1.html]
[task 2020-03-12T17:28:33.469Z] 17:28:33     INFO - GECKO(3849) | [Child 4114: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (0x7f8bbaa93520) [pid = 4114] [serial = 1] [outer = (nil)] [url = https://example.com/browser/browser/base/content/test/outOfProcess/file_base.html]
[task 2020-03-12T17:28:33.779Z] 17:28:33     INFO - TEST-INFO | started process screentopng
[task 2020-03-12T17:28:34.104Z] 17:28:34     INFO - TEST-INFO | screentopng: exit 0
[task 2020-03-12T17:28:34.105Z] 17:28:34     INFO - Buffered messages logged at 17:28:32
[task 2020-03-12T17:28:34.105Z] 17:28:34     INFO - Entering test bound test_controllers_subframes
[task 2020-03-12T17:28:34.105Z] 17:28:34     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: "https://example.com/browser/browser/base/content/test/outOfProcess/file_base.html" line: 0}]
[task 2020-03-12T17:28:34.105Z] 17:28:34     INFO - Buffered messages logged at 17:28:33
[task 2020-03-12T17:28:34.105Z] 17:28:34     INFO - Console message: [JavaScript Warning: "The character encoding of a framed document was not declared. The document may appear different if viewed without the document framing it." {file: "https://www.mozilla.org/browser/browser/base/content/test/outOfProcess/file_innerframe.html" line: 0}]
[task 2020-03-12T17:28:34.108Z] 17:28:34     INFO - Console message: [JavaScript Warning: "The character encoding of a framed document was not declared. The document may appear different if viewed without the document framing it." {file: "https://test1.example.org/browser/browser/base/content/test/outOfProcess/file_innerframe.html" line: 0}]
[task 2020-03-12T17:28:34.108Z] 17:28:34     INFO - TEST-PASS | browser/base/content/test/outOfProcess/browser_controller.js | root focused - {} == {} - 
[task 2020-03-12T17:28:34.109Z] 17:28:34     INFO - TEST-PASS | browser/base/content/test/outOfProcess/browser_controller.js | step 0 root focused undo - 
[task 2020-03-12T17:28:34.109Z] 17:28:34     INFO - Buffered messages finished
[task 2020-03-12T17:28:34.110Z] 17:28:34     INFO - TEST-UNEXPECTED-FAIL | browser/base/content/test/outOfProcess/browser_controller.js | step 0 root focused copy - Got false, expected true
[task 2020-03-12T17:28:34.110Z] 17:28:34     INFO - Stack trace:
[task 2020-03-12T17:28:34.110Z] 17:28:34     INFO - chrome://mochikit/content/browser-test.js:test_is:1320
[task 2020-03-12T17:28:34.111Z] 17:28:34     INFO - chrome://mochitests/content/browser/browser/base/content/test/outOfProcess/browser_controller.js:checkCommandState:7
[task 2020-03-12T17:28:34.111Z] 17:28:34     INFO - chrome://mochitests/content/browser/browser/base/content/test/outOfProcess/browser_controller.js:test_controllers_subframes:76
[task 2020-03-12T17:28:34.111Z] 17:28:34     INFO - chrome://mochikit/content/browser-test.js:Tester_execTest/<:1062
[task 2020-03-12T17:28:34.111Z] 17:28:34     INFO - chrome://mochikit/content/browser-test.js:Tester_execTest:1097
[task 2020-03-12T17:28:34.112Z] 17:28:34     INFO - chrome://mochikit/content/browser-test.js:nextTest/<:919
[task 2020-03-12T17:28:34.112Z] 17:28:34     INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<:918
[task 2020-03-12T17:28:34.112Z] 17:28:34     INFO - TEST-PASS | browser/base/content/test/outOfProcess/browser_controller.js | step 0 root focused delete - 
[task 2020-03-12T17:28:34.113Z] 17:28:34     INFO - TEST-PASS | browser/base/content/test/outOfProcess/browser_controller.js | input focused - {} == {} - 
[task 2020-03-12T17:28:34.113Z] 17:28:34     INFO - TEST-PASS | browser/base/content/test/outOfProcess/browser_controller.js | step 0 input focused undo - 
[task 2020-03-12T17:28:34.114Z] 17:28:34     INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-03-12T17:28:34.119Z] 17:28:34     INFO - TEST-UNEXPECTED-FAIL | browser/base/content/test/outOfProcess/browser_controller.js | step 0 input focused copy - Got false, expected true
[task 2020-03-12T17:28:34.119Z] 17:28:34     INFO - Stack trace:
[task 2020-03-12T17:28:34.119Z] 17:28:34     INFO - chrome://mochikit/content/browser-test.js:test_is:1320
[task 2020-03-12T17:28:34.119Z] 17:28:34     INFO - chrome://mochitests/content/browser/browser/base/content/test/outOfProcess/browser_controller.js:checkCommandState:7
[task 2020-03-12T17:28:34.119Z] 17:28:34     INFO - chrome://mochitests/content/browser/browser/base/content/test/outOfProcess/browser_controller.js:test_controllers_subframes:92
[task 2020-03-12T17:28:34.119Z] 17:28:34     INFO - chrome://mochikit/content/browser-test.js:Tester_execTest/<:1062
[task 2020-03-12T17:28:34.119Z] 17:28:34     INFO - chrome://mochikit/content/browser-test.js:Tester_execTest:1097
[task 2020-03-12T17:28:34.119Z] 17:28:34     INFO - chrome://mochikit/content/browser-test.js:nextTest/<:919
[task 2020-03-12T17:28:34.119Z] 17:28:34     INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<:918
[task 2020-03-12T17:28:34.119Z] 17:28:34     INFO - TEST-PASS | browser/base/content/test/outOfProcess/browser_controller.js | step 0 input focused delete - 
[task 2020-03-12T17:28:34.119Z] 17:28:34     INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-03-12T17:28:34.120Z] 17:28:34     INFO - TEST-UNEXPECTED-FAIL | browser/base/content/test/outOfProcess/browser_controller.js | step 0 typed undo - Got false, expected true
[task 2020-03-12T17:28:34.120Z] 17:28:34     INFO - Stack trace:
[task 2020-03-12T17:28:34.120Z] 17:28:34     INFO - chrome://mochikit/content/browser-test.js:test_is:1320
[task 2020-03-12T17:28:34.120Z] 17:28:34     INFO - chrome://mochitests/content/browser/browser/base/content/test/outOfProcess/browser_controller.js:checkCommandState:2
[task 2020-03-12T17:28:34.120Z] 17:28:34     INFO - chrome://mochitests/content/browser/browser/base/content/test/outOfProcess/browser_controller.js:test_controllers_subframes:96
[task 2020-03-12T17:28:34.120Z] 17:28:34     INFO - chrome://mochikit/content/browser-test.js:Tester_execTest/<:1062
[task 2020-03-12T17:28:34.120Z] 17:28:34     INFO - chrome://mochikit/content/browser-test.js:Tester_execTest:1097
[task 2020-03-12T17:28:34.120Z] 17:28:34     INFO - chrome://mochikit/content/browser-test.js:nextTest/<:919
[task 2020-03-12T17:28:34.120Z] 17:28:34     INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<:918
[task 2020-03-12T17:28:34.120Z] 17:28:34     INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-03-12T17:28:34.124Z] 17:28:34     INFO - TEST-UNEXPECTED-FAIL | browser/base/content/test/outOfProcess/browser_controller.js | step 0 typed copy - Got false, expected true
[task 2020-03-12T17:28:34.124Z] 17:28:34     INFO - Stack trace:
[task 2020-03-12T17:28:34.124Z] 17:28:34     INFO - chrome://mochikit/content/browser-test.js:test_is:1320
[task 2020-03-12T17:28:34.124Z] 17:28:34     INFO - chrome://mochitests/content/browser/browser/base/content/test/outOfProcess/browser_controller.js:checkCommandState:7
[task 2020-03-12T17:28:34.124Z] 17:28:34     INFO - chrome://mochitests/content/browser/browser/base/content/test/outOfProcess/browser_controller.js:test_controllers_subframes:96
[task 2020-03-12T17:28:34.124Z] 17:28:34     INFO - chrome://mochikit/content/browser-test.js:Tester_execTest/<:1062
[task 2020-03-12T17:28:34.124Z] 17:28:34     INFO - chrome://mochikit/content/browser-test.js:Tester_execTest:1097
[task 2020-03-12T17:28:34.124Z] 17:28:34     INFO - chrome://mochikit/content/browser-test.js:nextTest/<:919
[task 2020-03-12T17:28:34.124Z] 17:28:34     INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<:918
[task 2020-03-12T17:28:34.125Z] 17:28:34     INFO - TEST-PASS | browser/base/content/test/outOfProcess/browser_controller.js | step 0 typed delete - 
[task 2020-03-12T17:28:34.125Z] 17:28:34     INFO - TEST-PASS | browser/base/content/test/outOfProcess/browser_controller.js | input focused - {} == {} - 
[task 2020-03-12T17:28:34.126Z] 17:28:34     INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-03-12T17:28:34.126Z] 17:28:34     INFO - TEST-UNEXPECTED-FAIL | browser/base/content/test/outOfProcess/browser_controller.js | step 0 selected undo - Got false, expected true
[task 2020-03-12T17:28:34.126Z] 17:28:34     INFO - Stack trace:
[task 2020-03-12T17:28:34.126Z] 17:28:34     INFO - chrome://mochikit/content/browser-test.js:test_is:1320
[task 2020-03-12T17:28:34.126Z] 17:28:34     INFO - chrome://mochitests/content/browser/browser/base/content/test/outOfProcess/browser_controller.js:checkCommandState:2
[task 2020-03-12T17:28:34.126Z] 17:28:34     INFO - chrome://mochitests/content/browser/browser/base/content/test/outOfProcess/browser_controller.js:test_controllers_subframes:112
[task 2020-03-12T17:28:34.127Z] 17:28:34     INFO - chrome://mochikit/content/browser-test.js:Tester_execTest/<:1062
[task 2020-03-12T17:28:34.127Z] 17:28:34     INFO - chrome://mochikit/content/browser-test.js:Tester_execTest:1097
[task 2020-03-12T17:28:34.127Z] 17:28:34     INFO - chrome://mochikit/content/browser-test.js:nextTest/<:919
[task 2020-03-12T17:28:34.127Z] 17:28:34     INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<:918
[task 2020-03-12T17:28:34.127Z] 17:28:34     INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-03-12T17:28:34.128Z] 17:28:34     INFO - TEST-UNEXPECTED-FAIL | browser/base/content/test/outOfProcess/browser_controller.js | step 0 selected copy - Got false, expected true
[task 2020-03-12T17:28:34.128Z] 17:28:34     INFO - Stack trace:
[task 2020-03-12T17:28:34.128Z] 17:28:34     INFO - chrome://mochikit/content/browser-test.js:test_is:1320
[task 2020-03-12T17:28:34.128Z] 17:28:34     INFO - chrome://mochitests/content/browser/browser/base/content/test/outOfProcess/browser_controller.js:checkCommandState:7
[task 2020-03-12T17:28:34.129Z] 17:28:34     INFO - chrome://mochitests/content/browser/browser/base/content/test/outOfProcess/browser_controller.js:test_controllers_subframes:112
[task 2020-03-12T17:28:34.129Z] 17:28:34     INFO - chrome://mochikit/content/browser-test.js:Tester_execTest/<:1062
[task 2020-03-12T17:28:34.129Z] 17:28:34     INFO - chrome://mochikit/content/browser-test.js:Tester_execTest:1097
[task 2020-03-12T17:28:34.129Z] 17:28:34     INFO - chrome://mochikit/content/browser-test.js:nextTest/<:919
[task 2020-03-12T17:28:34.129Z] 17:28:34     INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<:918
[task 2020-03-12T17:28:34.130Z] 17:28:34     INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-03-12T17:28:34.132Z] 17:28:34     INFO - TEST-UNEXPECTED-FAIL | browser/base/content/test/outOfProcess/browser_controller.js | step 0 selected delete - Got false, expected true
[task 2020-03-12T17:28:34.132Z] 17:28:34     INFO - Stack trace:
[task 2020-03-12T17:28:34.133Z] 17:28:34     INFO - chrome://mochikit/content/browser-test.js:test_is:1320
[task 2020-03-12T17:28:34.133Z] 17:28:34     INFO - chrome://mochitests/content/browser/browser/base/content/test/outOfProcess/browser_controller.js:checkCommandState:12
[task 2020-03-12T17:28:34.133Z] 17:28:34     INFO - chrome://mochitests/content/browser/browser/base/content/test/outOfProcess/browser_controller.js:test_controllers_subframes:112
[task 2020-03-12T17:28:34.133Z] 17:28:34     INFO - chrome://mochikit/content/browser-test.js:Tester_execTest/<:1062
[task 2020-03-12T17:28:34.133Z] 17:28:34     INFO - chrome://mochikit/content/browser-test.js:Tester_execTest:1097
[task 2020-03-12T17:28:34.134Z] 17:28:34     INFO - chrome://mochikit/content/browser-test.js:nextTest/<:919
[task 2020-03-12T17:28:34.134Z] 17:28:34     INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<:918
[task 2020-03-12T17:28:34.134Z] 17:28:34     INFO - TEST-PASS | browser/base/content/test/outOfProcess/browser_controller.js | text matches - "a" == "a" - 
[task 2020-03-12T17:28:34.134Z] 17:28:34     INFO - TEST-PASS | browser/base/content/test/outOfProcess/browser_controller.js | selectionStart matches - 0 == 0 - 
[task 2020-03-12T17:28:34.135Z] 17:28:34     INFO - TEST-PASS | browser/base/content/test/outOfProcess/browser_controller.js | selectionEnd matches - 1 == 1 - 
[task 2020-03-12T17:28:34.217Z] 17:28:34     INFO - TEST-PASS | browser/base/content/test/outOfProcess/browser_controller.js | root focused - {} == {} - 
[task 2020-03-12T17:28:34.218Z] 17:28:34     INFO - TEST-PASS | browser/base/content/test/outOfProcess/browser_controller.js | step 1 root focused undo - 
[task 2020-03-12T17:28:34.219Z] 17:28:34     INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-03-12T17:28:34.221Z] 17:28:34     INFO - TEST-UNEXPECTED-FAIL | browser/base/content/test/outOfProcess/browser_controller.js | step 1 root focused copy - Got false, expected true
[task 2020-03-12T17:28:34.222Z] 17:28:34     INFO - Stack trace:
[task 2020-03-12T17:28:34.222Z] 17:28:34     INFO - chrome://mochikit/content/browser-test.js:test_is:1320
[task 2020-03-12T17:28:34.223Z] 17:28:34     INFO - chrome://mochitests/content/browser/browser/base/content/test/outOfProcess/browser_controller.js:checkCommandState:7
[task 2020-03-12T17:28:34.223Z] 17:28:34     INFO - chrome://mochitests/content/browser/browser/base/content/test/outOfProcess/browser_controller.js:test_controllers_subframes:76
[task 2020-03-12T17:28:34.224Z] 17:28:34     INFO - chrome://mochikit/content/browser-test.js:Tester_execTest/<:1062
[task 2020-03-12T17:28:34.224Z] 17:28:34     INFO - chrome://mochikit/content/browser-test.js:Tester_execTest:1097
[task 2020-03-12T17:28:34.225Z] 17:28:34     INFO - chrome://mochikit/content/browser-test.js:nextTest/<:919
[task 2020-03-12T17:28:34.226Z] 17:28:34     INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<:918
[task 2020-03-12T17:28:34.227Z] 17:28:34     INFO - TEST-PASS | browser/base/content/test/outOfProcess/browser_controller.js | step 1 root focused delete - 
....

Comment 1

[Bogdan Tara[:bogdan_tara | bogdant]]

Seeing this on Tier 1 now: https://treeherder.mozilla.org/logviewer.html#?job_id=292930480&repo=autoland

Comment 2

[Neil Deakin]

This is a new test, so we can disable the test for now if needed, or back out just the test part of patch set ( https://phabricator.services.mozilla.com/D58795 )

Comment 3

[Alexandru Michis [:malexandru]]

Neil, failure rate is not that high, I think we are good keeping the coverage going.

Comment 4

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 14
• mozilla-central: 4
• try: 2

Platform breakdown:

• linux1804-64: 3
• windows10-64: 1
• windows7-32: 1
• linux1804-64-shippable: 10
• macosx1014-64-shippable: 2
• windows10-64-shippable: 1
• windows7-32-shippable: 1
• macosx1014-64-devedition: 1

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

Comment 5

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

Bugbug thinks this bug should belong to this component, but please revert this change in case of error.

Comment 6

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 18
• try: 7
• mozilla-central: 2

Platform breakdown:

• macosx1014-64-shippable: 7
• linux1804-64-shippable: 12
• linux1804-64-devedition: 3
• linux1804-64: 4
• macosx1014-64-devedition: 1

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

Comment 7

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 6
• autoland: 11
• try: 6

Platform breakdown:

• linux1804-64: 5
• macosx1014-64-shippable: 3
• linux1804-64-shippable: 12
• windows7-32-shippable: 1
• macosx1014-64-devedition: 2

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

Comment 8

[Andrei Ciure[:aciure]]

There are 20 failures associated to this bug in the last 7 days. These are occurring on linux1804-64-shippable opt.

recent log: https://treeherder.mozilla.org/logviewer.html#?job_id=295824785&repo=autoland
[task 2020-04-01T20:42:02.602Z] 20:42:02 INFO - TEST-PASS | browser/base/content/test/outOfProcess/browser_controller.js | step 0 root focused undo -
[task 2020-04-01T20:42:02.603Z] 20:42:02 INFO - Buffered messages finished
[task 2020-04-01T20:42:02.603Z] 20:42:02 INFO - TEST-UNEXPECTED-FAIL | browser/base/content/test/outOfProcess/browser_controller.js | step 0 root focused copy - Got false, expected true
[task 2020-04-01T20:42:02.604Z] 20:42:02 INFO - Stack trace:
[task 2020-04-01T20:42:02.604Z] 20:42:02 INFO - chrome://mochikit/content/browser-test.js:test_is:1320
[task 2020-04-01T20:42:02.605Z] 20:42:02 INFO - chrome://mochitests/content/browser/browser/base/content/test/outOfProcess/browser_controller.js:checkCommandState:7
[task 2020-04-01T20:42:02.605Z] 20:42:02 INFO - chrome://mochitests/content/browser/browser/base/content/test/outOfProcess/browser_controller.js:test_controllers_subframes:76
[task 2020-04-01T20:42:02.605Z] 20:42:02 INFO - chrome://mochikit/content/browser-test.js:Tester_execTest/<:1062
[task 2020-04-01T20:42:02.606Z] 20:42:02 INFO - chrome://mochikit/content/browser-test.js:Tester_execTest:1097
[task 2020-04-01T20:42:02.606Z] 20:42:02 INFO - chrome://mochikit/content/browser-test.js:nextTest/<:925
[task 2020-04-01T20:42:02.607Z] 20:42:02 INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<:918

:m_kato can you take a look?

Comment 9

[Makoto Kato [:m_kato]]

Enn, could you look this?

Comment 10

[Intermittent Failures Robot]

30 failures in 5015 pushes (0.006 failures/push) were associated with this bug in the last 7 days.

This is the #48 most frequent failure this week.

** This failure happened more than 30 times this week! Resolving this bug is a high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 2 weeks, the affected test(s) may be disabled. **

Repository breakdown:

• autoland: 21
• try: 5
• mozilla-central: 4

Platform breakdown:

• macosx1014-64-shippable: 10
• linux1804-64-shippable: 15
• linux1804-64: 4
• macosx1014-64-devedition: 1

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

Comment 11

[Neil Deakin]

Attachment: Bug 1622057, try putting the focus in a known place at the beginning of the test to see if this fixes the intermittent, r=gijs

The failure might be caused by a race setting the active/focused window between processes

Comment 12

[Intermittent Failures Robot]

29 failures in 4823 pushes (0.006 failures/push) were associated with this bug in the last 7 days.

This is the #48 most frequent failure this week.

Repository breakdown:

• mozilla-central: 4
• autoland: 19
• try: 5
• mozilla-beta: 1

Platform breakdown:

• linux1804-64-shippable: 8
• linux1804-64: 5
• macosx1014-64-shippable: 14
• macosx1014-64-devedition: 1
• linux1804-64-devedition: 1

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

Comment 13

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/6396ce31334d
try putting the focus in a known place at the beginning of the test to see if this fixes the intermittent, r=Gijs

Comment 14

[Stefan Hindli [:stefan_hindli]]

https://hg.mozilla.org/mozilla-central/rev/6396ce31334d

Comment 15

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 2
• try: 1
• autoland: 11
• mozilla-beta: 5

Platform breakdown:

• linux1804-64: 1
• linux1804-64-devedition: 1
• macosx1014-64-shippable: 11
• linux1804-64-shippable: 6

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

Comment 16

[Ryan VanderMeulen [:RyanVM]]

https://hg.mozilla.org/releases/mozilla-beta/rev/87eb399574b6

Comment 17

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-beta: 4

Platform breakdown:

• linux1804-64-devedition: 1
• linux1804-64-shippable: 3

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