Closed Bug 1643314 Opened 6 years ago Closed 5 years ago

Intermittent browser/components/sessionstore/test/browser_586068-browser_state_interrupted.js | Test timed out - | Found a tab after previous test timed out: http://example.org/#4 -

Categories

(Firefox :: Session Restore, defect, P5)

Product:
Firefox
Component:
Session Restore
Type:
defect

Tracking

()

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

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

[task 2020-06-04T10:41:34.861Z] 10:41:34 INFO - TEST-START | browser/components/sessionstore/test/browser_586068-browser_state_interrupted.js
[task 2020-06-04T10:41:44.957Z] 10:41:44 INFO - GECKO(11460) | [GFX1-]: Killing GPU process due to IPC reply timeout
[task 2020-06-04T10:41:44.957Z] 10:41:44 INFO - GECKO(11460) | [GFX1-]: Failed to connect WebRenderBridgeChild.
[task 2020-06-04T10:41:44.957Z] 10:41:44 INFO - GECKO(11460) | [GFX1-]: Failed to create remote compositor
[task 2020-06-04T10:41:44.958Z] 10:41:44 INFO - GECKO(11460) | [Parent 8936, Main Thread] WARNING: base::KillProcess refusing to terminate process handle 0: file /builds/worker/checkouts/gecko/ipc/chromium/src/base/process_util_win.cc, line 401
[task 2020-06-04T10:41:44.963Z] 10:41:44 INFO - GECKO(11460) | [GFX1-]: Compositors might be mixed (5,1)
[task 2020-06-04T10:41:45.248Z] 10:41:45 INFO - GECKO(11460) | JavaScript error: resource://gre/modules/URIFixup.jsm, line 281: NS_ERROR_FAILURE: Should pass a non-null uri
[task 2020-06-04T10:41:45.704Z] 10:41:45 INFO - GECKO(11460) | ###!!! [Child][MessageChannel::SendAndWait] Error: Channel error: cannot send/recv
[task 2020-06-04T10:41:45.705Z] 10:41:45 INFO - GECKO(11460) | ###!!! [Child][MessageChannel] Error: (msgtype=0x9F0003,name=PTexture::Msg_Destroy) Channel error: cannot send/recv
[task 2020-06-04T10:41:45.707Z] 10:41:45 INFO - GECKO(11460) | ###!!! [Child][MessageChannel::SendAndWait] Error: (msgtype=0x5F0005,name=PImageBridge::Msg_WillClose) Channel error: cannot send/recv
[task 2020-06-04T10:41:45.709Z] 10:41:45 INFO - GECKO(11460) | [GFX1-]: Receive IPC close with reason=AbnormalShutdown

[task 2020-06-04T10:43:04.951Z] 10:43:04 INFO - Console message: [JavaScript Error: "Error: TelemetryStopwatch: key "FX_SESSION_RESTORE_CONTENT_COLLECT_DATA_MS" was already initialized" {file: "resource:///modules/sessionstore/ContentSessionStore.jsm" line: 465}]
[task 2020-06-04T10:43:04.951Z] 10:43:04 INFO - send@resource:///modules/sessionstore/ContentSessionStore.jsm:465:28
[task 2020-06-04T10:43:04.951Z] 10:43:04 INFO - sendWhenIdle@resource:///modules/sessionstore/ContentSessionStore.jsm:429:14
[task 2020-06-04T10:43:04.951Z] 10:43:04 INFO - sendWhenIdle/<@resource:///modules/sessionstore/ContentSessionStore.jsm:436:48
[task 2020-06-04T10:43:04.951Z] 10:43:04 INFO - ChromeUtils::IdleDispatch handlersendWhenIdle@resource:///modules/sessionstore/ContentSessionStore.jsm:436:17
[task 2020-06-04T10:43:04.951Z] 10:43:04 INFO - push/this._timeout<@resource:///modules/sessionstore/ContentSessionStore.jsm:403:20
[task 2020-06-04T10:43:04.951Z] 10:43:04 INFO - notify@resource://gre/modules/Timer.jsm:62:17
[task 2020-06-04T10:43:04.951Z] 10:43:04 INFO -
[task 2020-06-04T10:43:04.951Z] 10:43:04 INFO - Console message: [JavaScript Error: "TypeError: can't access property "legacySHistory", history is null" {file: "resource://gre/modules/sessionstore/SessionHistory.jsm" line: 109}]
[task 2020-06-04T10:43:04.951Z] 10:43:04 INFO - collect@resource://gre/modules/sessionstore/SessionHistory.jsm:109:1
[task 2020-06-04T10:43:04.951Z] 10:43:04 INFO - collect@resource://gre/modules/sessionstore/SessionHistory.jsm:39:35
[task 2020-06-04T10:43:04.951Z] 10:43:04 INFO - collectFrom/<@resource:///modules/sessionstore/ContentSessionStore.jsm:200:36
[task 2020-06-04T10:43:04.951Z] 10:43:04 INFO - send@resource:///modules/sessionstore/ContentSessionStore.jsm:468:19
[task 2020-06-04T10:43:04.951Z] 10:43:04 INFO - sendWhenIdle@resource:///modules/sessionstore/ContentSessionStore.jsm:429:14
[task 2020-06-04T10:43:04.951Z] 10:43:04 INFO - sendWhenIdle/<@resource:///modules/sessionstore/ContentSessionStore.jsm:436:48
[task 2020-06-04T10:43:04.951Z] 10:43:04 INFO - ChromeUtils::IdleDispatch handlersendWhenIdle@resource:///modules/sessionstore/ContentSessionStore.jsm:436:17
[task 2020-06-04T10:43:04.951Z] 10:43:04 INFO - push/this._timeout<@resource:///modules/sessionstore/ContentSessionStore.jsm:403:20
[task 2020-06-04T10:43:04.951Z] 10:43:04 INFO - notify@resource://gre/modules/Timer.jsm:62:17
[task 2020-06-04T10:43:04.951Z] 10:43:04 INFO -
[task 2020-06-04T10:43:04.951Z] 10:43:04 INFO - Buffered messages logged at 10:42:19
[task 2020-06-04T10:43:04.951Z] 10:43:04 INFO - Longer timeout required, waiting longer... Remaining timeouts: 1
[task 2020-06-04T10:43:04.951Z] 10:43:04 INFO - Buffered messages finished
[task 2020-06-04T10:43:04.951Z] 10:43:04 INFO - TEST-UNEXPECTED-FAIL | browser/components/sessionstore/test/browser_586068-browser_state_interrupted.js | Test timed out -
[task 2020-06-04T10:43:04.951Z] 10:43:04 INFO - GECKO(11460) | MEMORY STAT | vsize 2104596MB | vsizeMaxContiguous 65200323MB | residentFast 356MB | heapAllocated 97MB
[task 2020-06-04T10:43:04.952Z] 10:43:04 INFO - TEST-OK | browser/components/sessionstore/test/browser_586068-browser_state_interrupted.js | took 90078ms
[task 2020-06-04T10:43:04.952Z] 10:43:04 INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-06-04T10:43:04.952Z] 10:43:04 INFO - TEST-UNEXPECTED-FAIL | browser/components/sessionstore/test/browser_586068-browser_state_interrupted.js | Found a tab after previous test timed out: http://example.org/#4 -
[task 2020-06-04T10:43:04.952Z] 10:43:04 INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-06-04T10:43:04.952Z] 10:43:04 INFO - TEST-UNEXPECTED-FAIL | browser/components/sessionstore/test/browser_586068-browser_state_interrupted.js | Found a tab after previous test timed out: http://example.org/#3 -
[task 2020-06-04T10:43:04.952Z] 10:43:04 INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-06-04T10:43:04.952Z] 10:43:04 INFO - TEST-UNEXPECTED-FAIL | browser/components/sessionstore/test/browser_586068-browser_state_interrupted.js | Found a tab after previous test timed out: http://example.org/#2 -
[task 2020-06-04T10:43:04.953Z] 10:43:04 INFO - GECKO(11460) | JavaScript error: resource://gre/modules/sessionstore/SessionHistory.jsm, line 109: TypeError: can't access property "legacySHistory", history is null
[task 2020-06-04T10:43:04.953Z] 10:43:04 INFO - GECKO(11460) | JavaScript error: resource:///modules/sessionstore/ContentSessionStore.jsm, line 465: Error: TelemetryStopwatch: key "FX_SESSION_RESTORE_CONTENT_COLLECT_DATA_MS" was already initialized
[task 2020-06-04T10:43:04.953Z] 10:43:04 INFO - GECKO(11460) | JavaScript error: resource://gre/modules/sessionstore/SessionHistory.jsm, line 109: TypeError: can't access property "legacySHistory", history is null
[task 2020-06-04T10:43:04.972Z] 10:43:04 INFO - checking window state

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

Status: NEW → RESOLVED
Closed: 5 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.