Closed Bug 1561940 Opened 5 years ago Closed 5 years ago

Intermittent browser/base/content/test/performance/browser_startup_syncIPC.js | unexpected PCompositorBridge::Msg_FlushRendering sync IPC before becoming idle -

Categories

(Firefox :: General, defect, P5)

Product:
Firefox
Component:
General
Type:
defect

Tracking

()

Status:
RESOLVED FIXED
Milestone:
Firefox 70
Tracking Flags:
Tracking Status
firefox-esr60 --- unaffected
firefox-esr68 --- unaffected
firefox68 --- unaffected
firefox69 --- fixed
firefox70 --- fixed

People

(Reporter: intermittent-bug-filer, Assigned: mconley)

References

Details

(Keywords: intermittent-failure, regression)

Filed by: dluca [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=253701077&repo=autoland
Full log: https://queue.taskcluster.net/v1/task/TE-JL195RfmhBh5o5xRqzA/runs/0/artifacts/public/logs/live_backing.log

NFO - TEST-START | browser/base/content/test/performance/browser_startup_syncIPC.js
[task 2019-06-27T11:40:41.752Z] 11:40:41 INFO - TEST-INFO | started process screentopng
[task 2019-06-27T11:40:42.509Z] 11:40:42 INFO - TEST-INFO | screentopng: exit 0
[task 2019-06-27T11:40:42.509Z] 11:40:42 INFO - Buffered messages logged at 11:40:41
[task 2019-06-27T11:40:42.509Z] 11:40:42 INFO - Entering test bound
[task 2019-06-27T11:40:42.509Z] 11:40:42 INFO - whitelisted sync IPC before opening first browser window:
[task 2019-06-27T11:40:42.509Z] 11:40:42 INFO - PLayerTransaction::Msg_GetTextureFactoryIdentifier - at most 1 times
[task 2019-06-27T11:40:42.509Z] 11:40:42 INFO - TEST-PASS | browser/base/content/test/performance/browser_startup_syncIPC.js | sync IPC PLayerTransaction::Msg_GetTextureFactoryIdentifier happened as many times as expected before opening first browser window -
[task 2019-06-27T11:40:42.509Z] 11:40:42 INFO - whitelisted sync IPC before first paint:
[task 2019-06-27T11:40:42.509Z] 11:40:42 INFO - PCompositorBridge::Msg_NotifyChildCreated - at most 1 times
[task 2019-06-27T11:40:42.509Z] 11:40:42 INFO - TEST-PASS | browser/base/content/test/performance/browser_startup_syncIPC.js | sync IPC PCompositorBridge::Msg_NotifyChildCreated happened as many times as expected before first paint -
[task 2019-06-27T11:40:42.510Z] 11:40:42 INFO - whitelisted sync IPC before handling user events:
[task 2019-06-27T11:40:42.510Z] 11:40:42 INFO - PCompositorBridge::Msg_FlushRendering - at most 1 times
[task 2019-06-27T11:40:42.510Z] 11:40:42 INFO - PLayerTransaction::Msg_GetTextureFactoryIdentifier - at most 1 times
[task 2019-06-27T11:40:42.511Z] 11:40:42 INFO - TEST-PASS | browser/base/content/test/performance/browser_startup_syncIPC.js | sync IPC PCompositorBridge::Msg_FlushRendering allowed 1 more times before handling user events -
[task 2019-06-27T11:40:42.512Z] 11:40:42 INFO - TEST-PASS | browser/base/content/test/performance/browser_startup_syncIPC.js | sync IPC PLayerTransaction::Msg_GetTextureFactoryIdentifier happened as many times as expected before handling user events -
[task 2019-06-27T11:40:42.513Z] 11:40:42 INFO - whitelisted sync IPC before becoming idle:
[task 2019-06-27T11:40:42.513Z] 11:40:42 INFO - PCompositorBridge::Msg_NotifyChildCreated - at most 1 times
[task 2019-06-27T11:40:42.517Z] 11:40:42 INFO - Buffered messages finished
[task 2019-06-27T11:40:42.517Z] 11:40:42 INFO - TEST-UNEXPECTED-FAIL | browser/base/content/test/performance/browser_startup_syncIPC.js | unexpected PCompositorBridge::Msg_FlushRendering sync IPC before becoming idle -
[task 2019-06-27T11:40:42.518Z] 11:40:42 INFO - Stack trace:
[task 2019-06-27T11:40:42.518Z] 11:40:42 INFO - chrome://mochikit/content/browser-test.js:test_ok:1313
[task 2019-06-27T11:40:42.518Z] 11:40:42 INFO - chrome://mochitests/content/browser/browser/base/content/test/performance/browser_startup_syncIPC.js:null:278
[task 2019-06-27T11:40:42.519Z] 11:40:42 INFO - chrome://mochikit/content/browser-test.js:Tester_execTest/<:1115
[task 2019-06-27T11:40:42.519Z] 11:40:42 INFO - chrome://mochikit/content/browser-test.js:Tester_execTest:1143
[task 2019-06-27T11:40:42.519Z] 11:40:42 INFO - chrome://mochikit/content/browser-test.js:nextTest/<:1004
[task 2019-06-27T11:40:42.520Z] 11:40:42 INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<:803
[task 2019-06-27T11:40:42.520Z] 11:40:42 INFO - TEST-PASS | browser/base/content/test/performance/browser_startup_syncIPC.js | sync IPC PCompositorBridge::Msg_NotifyChildCreated happened as many times as expected before becoming idle -
[task 2019-06-27T11:40:42.521Z] 11:40:42 INFO - Not taking screenshot here: see the one that was previously logged
[task 2019-06-27T11:40:42.521Z] 11:40:42 INFO - TEST-UNEXPECTED-FAIL | browser/base/content/test/performance/browser_startup_syncIPC.js | Unexpected sync IPC behavior during startup; open the profile_startup_syncIPC.json artifact in the Firefox Profiler to see what happened -
[task 2019-06-27T11:40:42.522Z] 11:40:42 INFO - Stack trace:
[task 2019-06-27T11:40:42.523Z] 11:40:42 INFO - chrome://mochikit/content/browser-test.js:test_ok:1313
[task 2019-06-27T11:40:42.524Z] 11:40:42 INFO - chrome://mochitests/content/browser/browser/base/content/test/performance/browser_startup_syncIPC.js:null:313
[task 2019-06-27T11:40:42.524Z] 11:40:42 INFO - chrome://mochikit/content/browser-test.js:Tester_execTest/<:1115
[task 2019-06-27T11:40:42.525Z] 11:40:42 INFO - chrome://mochikit/content/browser-test.js:Tester_execTest:1143
[task 2019-06-27T11:40:42.525Z] 11:40:42 INFO - chrome://mochikit/content/browser-test.js:nextTest/<:1004
[task 2019-06-27T11:40:42.526Z] 11:40:42 INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<:803
[task 2019-06-27T11:40:42.527Z] 11:40:42 INFO - Leaving test bound
[task 2019-06-27T11:40:42.528Z] 11:40:42 INFO - GECKO(2833) | JavaScript error: resource://testing-common/PromiseTestUtils.jsm, line 110: uncaught exception: Object
[task 2019-06-27T11:40:42.531Z] 11:40:42 INFO - Console message: [JavaScript Error: "uncaught exception: Object" {file: "resource://testing-common/PromiseTestUtils.jsm" line: 110}]
[task 2019-06-27T11:40:42.531Z] 11:40:42 INFO - GECKO(2833) | MEMORY STAT | vsize 3122MB | residentFast 441MB | heapAllocated 304MB
[task 2019-06-27T11:40:42.532Z] 11:40:42 INFO - TEST-OK | browser/base/content/test/performance/browser_startup_syncIPC.js | took 273ms

Test has been disabled on macosx1014 for mochitest-browser-chrome pending investigation, as it appears to be a permafail.

https://phabricator.services.mozilla.com/D38783

Flags: needinfo?(mconley)
Assignee: nobody → mconley
Flags: needinfo?(mconley)
See Also: → 1569421

Fixed by bug 1569421.

Status: NEW → RESOLVED
Closed: 5 years ago
status-firefox70: --- → fixed
Resolution: --- → FIXED
Target Milestone: --- → Firefox 70
See Also: → 1683530
You need to log in before you can comment on or make changes to this bug.