1556085 - Intermittent devtools/client/framework/test/browser_toolbox_remoteness_change.js | The console inspects the second document -

Status: RESOLVED INCOMPLETE

(DevTools :: Framework, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: csabou [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=249375768&repo=try
Full log: https://queue.taskcluster.net/v1/task/LkdBmL0qTlO5PkbmLm0f3A/runs/0/artifacts/public/logs/live_backing.log

---

13:24:00 INFO - TEST-PASS | devtools/client/framework/test/browser_toolbox_remoteness_change.js | And running in parent process -
13:24:00 INFO - Opening the toolbox
13:24:00 INFO - Buffered messages logged at 13:23:58
13:24:00 INFO - Toolbox opened and focused
13:24:00 INFO - Navigate to a URL supporting remote process
13:24:00 INFO - TEST-PASS | devtools/client/framework/test/browser_toolbox_remoteness_change.js | Navigated to a data: URI and switching to remote -
13:24:00 INFO - Waiting for the toolbox to be destroyed
13:24:00 INFO - Buffered messages logged at 13:23:59
13:24:00 INFO - Waiting for a new toolbox to be created
13:24:00 INFO - Waiting for the new toolbox to be ready
13:24:00 INFO - Buffered messages logged at 13:24:00
13:24:00 INFO - Veryify we are inspecting the new document
13:24:00 INFO - Buffered messages finished
13:24:00 INFO - TEST-UNEXPECTED-FAIL | devtools/client/framework/test/browser_toolbox_remoteness_change.js | The console inspects the second document -
13:24:00 INFO - Stack trace:
13:24:00 INFO - chrome://mochikit/content/browser-test.js:test_ok:1314
13:24:00 INFO - chrome://mochitests/content/browser/devtools/client/framework/test/browser_toolbox_remoteness_change.js:null:40
13:24:00 INFO - Leaving test bound
13:24:00 INFO - GECKO(4164) | [Parent 4284, DOM Worker] WARNING: '!holder->HoldWorker(aWorkerPrivate, aFailStatus)', file z:/build/build/src/dom/workers/WorkerRef.cpp, line 176
13:24:00 INFO - GECKO(4164) | [Parent 4284, DOM Worker] WARNING: '!holder->HoldWorker(aWorkerPrivate, aFailStatus)', file z:/build/build/src/dom/workers/WorkerRef.cpp, line 176
13:24:00 INFO - GECKO(4164) | [Parent 4284, Main Thread] WARNING: NS_ENSURE_TRUE(aCSP) failed: file z:/build/build/src/dom/security/FramingChecker.cpp, line 167
13:24:00 INFO - GECKO(4164) | ++DOMWINDOW == 36 (167B7000) [pid = 4284] [serial = 526] [outer = 1154D280]
13:24:00 INFO - GECKO(4164) | console.warn: "Error while detaching the thread front: 'detach' request packet to 'server1.conn72.child1/context18' can't be sent as the connection is closed."
13:24:02 INFO - GECKO(4164) | --DOMWINDOW == 35 (0D69D800) [pid = 4284] [serial = 513] [outer = 00000000] [url = about:blank]
13:24:02 INFO - GECKO(4164) | --DOCSHELL 167B9400 == 10 [pid = 4284] [id = {2699bdc7-de35-4af2-aa10-e9a0d083a199}] [url = chrome://devtools/content/webconsole/index.html]
13:24:02 INFO - GECKO(4164) | --DOCSHELL 0D69EC00 == 9 [pid = 4284] [id = {b55b0072-7ced-4689-b3f9-517b8a154b52}] [url = about:blank]
13:24:02 INFO - GECKO(4164) | --DOCSHELL 00981000 == 8 [pid = 4284] [id = {2ad82c27-d251-4f4c-8958-c1babe0baba4}] [url = chrome://devtools/content/webconsole/index.html]
13:24:02 INFO - GECKO(4164) | --DOCSHELL 0D698C00 == 7 [pid = 4284] [id = {2b96b03a-6519-42da-9f1e-44ebb508b312}] [url = about:blank]
13:24:02 INFO - Removing tab.
13:24:02 INFO - Waiting for event: 'TabClose' on [object XULElement].
13:24:02 INFO - Got event: 'TabClose' on [object XULElement].
13:24:02 INFO - GECKO(4164) | [Child 968, Main Thread] WARNING: No active window: file z:/build/build/src/js/xpconnect/src/XPCJSContext.cpp, line 662
13:24:02 INFO - Tab removed and finished closing
13:24:02 INFO - GECKO(4164) | --DOMWINDOW == 34 (0EB92820) [pid = 4284] [serial = 512] [outer = 00000000] [url = about:robots]
13:24:02 INFO - GECKO(4164) | MEMORY STAT | vsize 997MB | vsizeMaxContiguous 393MB | residentFast 449MB | heapAllocated 172MB
13:24:02 INFO - TEST-OK | devtools/client/framework/test/browser_toolbox_remoteness_change.js | took 5140ms

Comment 1

[Intermittent Failures Robot]

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

Repository breakdown:

• try: 1
• autoland: 1

Platform breakdown:

• windows7-32: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1556085&startday=2019-05-27&endday=2019-06-02&tree=all

Comment 2

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

• windows7-32: 1

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

Comment 3

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

• windows7-32: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1556085&startday=2019-06-10&endday=2019-06-16&tree=all

Comment 4

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

• windows7-32: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1556085&startday=2019-06-17&endday=2019-06-23&tree=all

Comment 5

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 2

Platform breakdown:

• windows7-32: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1556085&startday=2019-07-08&endday=2019-07-14&tree=all

Comment 6

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

• windows7-32: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1556085&startday=2019-07-29&endday=2019-08-04&tree=all

Comment 7

[Firefox Bug Husbandry Bot]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup

Comment 8

[Cristina Coroiu [:ccoroiu]]

Recent failure log: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=265165915&repo=mozilla-esr68&lineNumber=8053

15:43:53 INFO - TEST-PASS | devtools/client/framework/test/browser_toolbox_remoteness_change.js | Navigated to a data: URI and switching to remote -
15:43:53 INFO - Waiting for the toolbox to be destroyed
15:43:53 INFO - Buffered messages logged at 15:43:52
15:43:53 INFO - Waiting for a new toolbox to be created
15:43:53 INFO - Waiting for the new toolbox to be ready
15:43:53 INFO - Buffered messages logged at 15:43:53
15:43:53 INFO - Veryify we are inspecting the new document
15:43:53 INFO - Buffered messages finished
15:43:53 INFO - TEST-UNEXPECTED-FAIL | devtools/client/framework/test/browser_toolbox_remoteness_change.js | The console inspects the second document -
15:43:53 INFO - Stack trace:
15:43:53 INFO - chrome://mochikit/content/browser-test.js:test_ok:1587
15:43:53 INFO - chrome://mochitests/content/browser/devtools/client/framework/test/browser_toolbox_remoteness_change.js:null:53
15:43:53 INFO - Leaving test bound
15:43:53 INFO - GECKO(156) | [Parent 3672, DOM Worker] WARNING: '!holder->HoldWorker(aWorkerPrivate, aFailStatus)', file z:/build/build/src/dom/workers/WorkerRef.cpp, line 176
15:43:53 INFO - GECKO(156) | [Parent 3672, DOM Worker] WARNING: '!holder->HoldWorker(aWorkerPrivate, aFailStatus)', file z:/build/build/src/dom/workers/WorkerRef.cpp, line 176
15:43:53 INFO - GECKO(156) | ++DOMWINDOW == 36 (1925F800) [pid = 3672] [serial = 526] [outer = 0F632040]
15:43:53 INFO - GECKO(156) | console.warn: "Error while detaching the thread front: 'detach' request packet to 'server1.conn72.child1/context18' can't be sent as the connection is closed."
15:43:54 INFO - GECKO(156) | --DOMWINDOW == 35 (0DA48C00) [pid = 3672] [serial = 513] [outer = 00000000] [url = about:blank]
15:43:54 INFO - GECKO(156) | --DOCSHELL 0DA46800 == 10 [pid = 3672] [id = {52332e07-5472-4e80-9387-ef1007d1196d}] [url = chrome://devtools/content/webconsole/index.html]
15:43:54 INFO - GECKO(156) | --DOCSHELL 19D1B800 == 9 [pid = 3672] [id = {f3f52816-952b-4b07-8977-365684440ea2}] [url = about:blank]
15:43:54 INFO - GECKO(156) | --DOCSHELL 0DA49C00 == 8 [pid = 3672] [id = {d79c6bc7-9cc1-4457-be0d-711640569515}] [url = about:blank]
15:43:54 INFO - Removing tab.
15:43:54 INFO - Waiting for event: 'TabClose' on [object XULElement].
15:43:54 INFO - Got event: 'TabClose' on [object XULElement].
15:43:54 INFO - GECKO(156) | [Child 3520, Main Thread] WARNING: No active window: file z:/build/build/src/js/xpconnect/src/XPCJSContext.cpp, line 662
15:43:54 INFO - Tab removed and finished closing
15:43:54 INFO - GECKO(156) | --DOMWINDOW == 34 (0EFF2CA0) [pid = 3672] [serial = 512] [outer = 00000000] [url = about:robots]
15:43:54 INFO - GECKO(156) | MEMORY STAT | vsize 1011MB | vsizeMaxContiguous 496MB | residentFast 447MB | heapAllocated 164MB

Comment 9

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-esr68: 1

Platform breakdown:

• windows7-32: 1

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

Comment 10

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

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

Comment 11

[Alexandru Michis [:malexandru]]

Recent failure log: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=274356495&repo=mozilla-esr68&lineNumber=7374

Comment 12

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-esr68: 1

Platform breakdown:

• windows7-32: 1

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

Comment 13

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

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

Comment 14

[Bogdan Tara[:bogdan_tara | bogdant]]

This is still happening.

Recent failure: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=290647706&repo=autoland&lineNumber=6402

...
[task 2020-02-26T21:29:40.473Z] 21:29:40     INFO - TEST-PASS | devtools/client/framework/test/browser_toolbox_remoteness_change.js | And running in parent process - 
[task 2020-02-26T21:29:40.473Z] 21:29:40     INFO - Opening the toolbox
[task 2020-02-26T21:29:40.473Z] 21:29:40     INFO - Buffered messages logged at 21:29:22
[task 2020-02-26T21:29:40.473Z] 21:29:40     INFO - Console message: [JavaScript Error: "Content Security Policy: The page’s settings blocked the loading of a resource at inline (“default-src”)." {file: "chrome://global/content/elements/panel.js" line: 62}]
[task 2020-02-26T21:29:40.474Z] 21:29:40     INFO - Console message: [JavaScript Error: "Content Security Policy: The page’s settings blocked the loading of a resource at inline (“default-src”)." {file: "chrome://global/content/elements/panel.js" line: 62}]
[task 2020-02-26T21:29:40.474Z] 21:29:40     INFO - Console message: [JavaScript Error: "Content Security Policy: The page’s settings blocked the loading of a resource at inline (“default-src”)." {file: "chrome://global/content/elements/panel.js" line: 62}]
[task 2020-02-26T21:29:40.474Z] 21:29:40     INFO - Toolbox opened and focused
[task 2020-02-26T21:29:40.474Z] 21:29:40     INFO - Navigate to a URL supporting remote process
[task 2020-02-26T21:29:40.475Z] 21:29:40     INFO - Load document "data:text/html;charset=UTF-8,%3Cdiv%20id%3D%22remote-page%22%3Efoo%3C%2Fdiv%3E"
[task 2020-02-26T21:29:40.475Z] 21:29:40     INFO - Buffered messages logged at 21:29:23
[task 2020-02-26T21:29:40.475Z] 21:29:40     INFO - Waiting for page to be loaded…
[task 2020-02-26T21:29:40.475Z] 21:29:40     INFO - → page loaded
[task 2020-02-26T21:29:40.475Z] 21:29:40     INFO - Waiting for target switch…
[task 2020-02-26T21:29:40.476Z] 21:29:40     INFO - → switched-target emitted
[task 2020-02-26T21:29:40.476Z] 21:29:40     INFO - TEST-PASS | devtools/client/framework/test/browser_toolbox_remoteness_change.js | Navigated to a data: URI and switching to remote - 
[task 2020-02-26T21:29:40.476Z] 21:29:40     INFO - Waiting for the toolbox to be switched to the new target
[task 2020-02-26T21:29:40.476Z] 21:29:40     INFO - Veryify we are inspecting the new document
[task 2020-02-26T21:29:40.477Z] 21:29:40     INFO - Buffered messages finished
[task 2020-02-26T21:29:40.477Z] 21:29:40     INFO - TEST-UNEXPECTED-FAIL | devtools/client/framework/test/browser_toolbox_remoteness_change.js | The console inspects the second document - 
[task 2020-02-26T21:29:40.477Z] 21:29:40     INFO - Stack trace:
[task 2020-02-26T21:29:40.477Z] 21:29:40     INFO - chrome://mochikit/content/browser-test.js:test_ok:1292
[task 2020-02-26T21:29:40.477Z] 21:29:40     INFO - chrome://mochitests/content/browser/devtools/client/framework/test/browser_toolbox_remoteness_change.js:navigateBetweenProcesses:83
[task 2020-02-26T21:29:40.477Z] 21:29:40     INFO - chrome://mochitests/content/browser/devtools/client/framework/test/browser_toolbox_remoteness_change.js:null:14
[task 2020-02-26T21:29:40.477Z] 21:29:40     INFO - chrome://mochikit/content/browser-test.js:Tester_execTest/<:1062
[task 2020-02-26T21:29:40.477Z] 21:29:40     INFO - chrome://mochikit/content/browser-test.js:Tester_execTest:1097
[task 2020-02-26T21:29:40.477Z] 21:29:40     INFO - chrome://mochikit/content/browser-test.js:nextTest/<:925
[task 2020-02-26T21:29:40.477Z] 21:29:40     INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<:914
[task 2020-02-26T21:29:40.478Z] 21:29:40     INFO - GECKO(1676) | [Child 1680: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (0x126c11800) [pid = 1680] [serial = 129] [outer = 0x0] [url = about:blank]
[task 2020-02-26T21:29:40.478Z] 21:29:40     INFO - TEST-PASS | devtools/client/framework/test/browser_toolbox_remoteness_change.js | The client is closed after closing the toolbox - 
[task 2020-02-26T21:29:40.478Z] 21:29:40     INFO - Leaving test bound 
[task 2020-02-26T21:29:40.478Z] 21:29:40     INFO - GECKO(1676) | console.warn: "IGNORED REDUX ACTION:" ({type:"MESSAGES_ADD", messages:[]})
[task 2020-02-26T21:29:40.479Z] 21:29:40     INFO - GECKO(1676) | [Parent 1676: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 56 (0x10ede2c00) [pid = 1676] [serial = 597] [outer = 0x12bc0f820]
[task 2020-02-26T21:29:40.479Z] 21:29:40     INFO - Removing tab.
[task 2020-02-26T21:29:40.479Z] 21:29:40     INFO - Waiting for event: 'TabClose' on [object XULElement].
[task 2020-02-26T21:29:40.479Z] 21:29:40     INFO - Got event: 'TabClose' on [object XULElement].
[task 2020-02-26T21:29:40.479Z] 21:29:40     INFO - GECKO(1676) | [Parent 1676, Main Thread] WARNING: '!inner', file /builds/worker/workspace/build/src/dom/ipc/JSWindowActorService.cpp, line 182
[task 2020-02-26T21:29:40.484Z] 21:29:40     INFO - Tab removed and finished closing
[task 2020-02-26T21:29:40.484Z] 21:29:40     INFO - Removing tab.
[task 2020-02-26T21:29:40.488Z] 21:29:40     INFO - Waiting for event: 'TabClose' on [object XULElement].
[task 2020-02-26T21:29:40.488Z] 21:29:40     INFO - Got event: 'TabClose' on [object XULElement].
[task 2020-02-26T21:29:40.489Z] 21:29:40     INFO - Tab removed and finished closing
[task 2020-02-26T21:29:40.491Z] 21:29:40     INFO - TEST-PASS | devtools/client/framework/test/browser_toolbox_remoteness_change.js | The main process DevToolsServer has no pending connection when the test ends - 
[task 2020-02-26T21:29:40.491Z] 21:29:40     INFO - TEST-FAIL | devtools/client/framework/test/browser_toolbox_remoteness_change.js | Assertion count 16 is greater than expected range 0-0 assertions. - 
[task 2020-02-26T21:29:40.491Z] 21:29:40     INFO - GECKO(1676) | MEMORY STAT | vsize 7900MB | residentFast 550MB | heapAllocated 224MB
[task 2020-02-26T21:29:40.491Z] 21:29:40     INFO - TEST-OK | devtools/client/framework/test/browser_toolbox_remoteness_change.js | took 18117ms

Comment 15

[Cosmin Sabou [:CosminS]]

The above was caused by Bug 1605329 and was backed out by https://hg.mozilla.org/integration/autoland/rev/214b1d66b3bc5b9dafa864f85e7835ccddfec18d

Comment 16

[Intermittent Failures Robot]

15 failures in 1063 pushes (0.014 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 15

Platform breakdown:

• linux1804-64-asan: 2
• macosx1014-64: 2
• linux1804-64: 5
• macosx1014-64-shippable: 1
• linux1804-64-shippable: 1
• windows10-64: 2
• windows10-64-shippable: 1
• windows7-32-shippable: 1

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

Comment 17

[Intermittent Failures Robot]

15 failures in 5233 pushes (0.003 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 15

Platform breakdown:

• linux1804-64-asan: 2
• macosx1014-64: 2
• linux1804-64: 5
• macosx1014-64-shippable: 1
• linux1804-64-shippable: 1
• windows10-64: 2
• windows10-64-shippable: 1
• windows7-32-shippable: 1

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