Closed Bug 1645024 Opened 5 years ago Closed 5 years ago

Intermittent TEST-UNEXPECTED-TIMEOUT | browser/components/sessionstore/test/browser_595601-restore_hidden.js | application timed out after 370 seconds with no output

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: abutkovits [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=305912198&repo=mozilla-central
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/Q4UR7BxtRSGHWt-76QXyEg/runs/0/artifacts/public/logs/live_backing.log

[task 2020-06-11T10:58:35.173Z] 10:58:35 INFO - TEST-START | browser/components/sessionstore/test/browser_595601-restore_hidden.js
[task 2020-06-11T10:58:37.174Z] 10:58:37 INFO - GECKO(10868) | JavaScript error: resource://gre/modules/sessionstore/SessionHistory.jsm, line 109: TypeError: can't access property "legacySHistory", history is null
[task 2020-06-11T10:58:37.174Z] 10:58:37 INFO - GECKO(10868) | JavaScript error: resource:///modules/sessionstore/ContentSessionStore.jsm, line 466: Error: TelemetryStopwatch: key "FX_SESSION_RESTORE_CONTENT_COLLECT_DATA_MS" was already initialized
[task 2020-06-11T10:58:37.174Z] 10:58:37 INFO - GECKO(10868) | JavaScript error: resource:///modules/sessionstore/ContentSessionStore.jsm, line 472: Error: TelemetryStopwatch: requesting elapsed time for nonexisting stopwatch. Histogram: "FX_SESSION_RESTORE_CONTENT_COLLECT_DATA_MS", key: "historychange"
[task 2020-06-11T10:58:37.235Z] 10:58:37 INFO - GECKO(10868) | JavaScript error: resource://gre/modules/sessionstore/SessionHistory.jsm, line 109: TypeError: can't access property "legacySHistory", history is null
[task 2020-06-11T11:10:05.676Z] 11:10:05 INFO - Buffered messages logged at 10:58:35
[task 2020-06-11T11:10:05.677Z] 11:10:05 INFO - TEST-PASS | browser/components/sessionstore/test/browser_595601-restore_hidden.js | restoring 3 tabs concurrently -
[task 2020-06-11T11:10:05.677Z] 11:10:05 INFO - TEST-PASS | browser/components/sessionstore/test/browser_595601-restore_hidden.js | restoring max. 3 tabs concurrently -
[task 2020-06-11T11:10:05.678Z] 11:10:05 INFO - TEST-PASS | browser/components/sessionstore/test/browser_595601-restore_hidden.js | restoring max. 3 tabs concurrently -
[task 2020-06-11T11:10:05.678Z] 11:10:05 INFO - TEST-PASS | browser/components/sessionstore/test/browser_595601-restore_hidden.js | restoring max. 3 tabs concurrently -
[task 2020-06-11T11:10:05.678Z] 11:10:05 INFO - Buffered messages logged at 10:58:36
[task 2020-06-11T11:10:05.679Z] 11:10:05 INFO - TEST-PASS | browser/components/sessionstore/test/browser_595601-restore_hidden.js | restoring max. 3 tabs concurrently -
[task 2020-06-11T11:10:05.679Z] 11:10:05 INFO - TEST-PASS | browser/components/sessionstore/test/browser_595601-restore_hidden.js | restoring max. 3 tabs concurrently -
[task 2020-06-11T11:10:05.679Z] 11:10:05 INFO - TEST-PASS | browser/components/sessionstore/test/browser_595601-restore_hidden.js | restoring max. 3 tabs concurrently -
[task 2020-06-11T11:10:05.680Z] 11:10:05 INFO - TEST-PASS | browser/components/sessionstore/test/browser_595601-restore_hidden.js | restoring max. 3 tabs concurrently -
[task 2020-06-11T11:10:05.680Z] 11:10:05 INFO - TEST-PASS | browser/components/sessionstore/test/browser_595601-restore_hidden.js | only 4 visible tabs -
[task 2020-06-11T11:10:05.680Z] 11:10:05 INFO - TEST-PASS | browser/components/sessionstore/test/browser_595601-restore_hidden.js | restoring 3 tabs concurrently -
[task 2020-06-11T11:10:05.680Z] 11:10:05 INFO - TEST-PASS | browser/components/sessionstore/test/browser_595601-restore_hidden.js | restoring max. 3 tabs concurrently -
[task 2020-06-11T11:10:05.680Z] 11:10:05 INFO - Buffered messages logged at 10:58:37
[task 2020-06-11T11:10:05.680Z] 11:10:05 INFO - TEST-PASS | browser/components/sessionstore/test/browser_595601-restore_hidden.js | restoring max. 3 tabs concurrently -
[task 2020-06-11T11:10:05.681Z] 11:10:05 INFO - TEST-PASS | browser/components/sessionstore/test/browser_595601-restore_hidden.js | restoring max. 3 tabs concurrently -
[task 2020-06-11T11:10:05.681Z] 11:10:05 INFO - TEST-PASS | browser/components/sessionstore/test/browser_595601-restore_hidden.js | only 4 visible tabs -
[task 2020-06-11T11:10:05.681Z] 11:10:05 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-11T11:10:05.681Z] 11:10:05 INFO - collect@resource://gre/modules/sessionstore/SessionHistory.jsm:109:1
[task 2020-06-11T11:10:05.681Z] 11:10:05 INFO - collect@resource://gre/modules/sessionstore/SessionHistory.jsm:39:35
[task 2020-06-11T11:10:05.681Z] 11:10:05 INFO - collectFrom/<@resource:///modules/sessionstore/ContentSessionStore.jsm:200:36
[task 2020-06-11T11:10:05.681Z] 11:10:05 INFO - send@resource:///modules/sessionstore/ContentSessionStore.jsm:469:19
[task 2020-06-11T11:10:05.681Z] 11:10:05 INFO - flush@resource:///modules/sessionstore/ContentSessionStore.jsm:800:23
[task 2020-06-11T11:10:05.681Z] 11:10:05 INFO - receiveMessage@resource:///modules/sessionstore/ContentSessionStore.jsm:616:14
[task 2020-06-11T11:10:05.681Z] 11:10:05 INFO - MessageListener.receiveMessageContentSessionStore/<@resource:///modules/sessionstore/ContentSessionStore.jsm:545:30
[task 2020-06-11T11:10:05.681Z] 11:10:05 INFO - ContentSessionStore@resource:///modules/sessionstore/ContentSessionStore.jsm:545:14
[task 2020-06-11T11:10:05.681Z] 11:10:05 INFO - @chrome://browser/content/content-sessionStore.js:13:6
[task 2020-06-11T11:10:05.681Z] 11:10:05 INFO -
[task 2020-06-11T11:10:05.682Z] 11:10:05 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: 466}]
[task 2020-06-11T11:10:05.682Z] 11:10:05 INFO - send@resource:///modules/sessionstore/ContentSessionStore.jsm:466:28
[task 2020-06-11T11:10:05.682Z] 11:10:05 INFO - flush@resource:///modules/sessionstore/ContentSessionStore.jsm:800:23
[task 2020-06-11T11:10:05.682Z] 11:10:05 INFO - receiveMessage@resource:///modules/sessionstore/ContentSessionStore.jsm:616:14
[task 2020-06-11T11:10:05.683Z] 11:10:05 INFO - MessageListener.receiveMessageContentSessionStore/<@resource:///modules/sessionstore/ContentSessionStore.jsm:545:30
[task 2020-06-11T11:10:05.683Z] 11:10:05 INFO - ContentSessionStore@resource:///modules/sessionstore/ContentSessionStore.jsm:545:14
[task 2020-06-11T11:10:05.683Z] 11:10:05 INFO - @chrome://browser/content/content-sessionStore.js:13:6
[task 2020-06-11T11:10:05.683Z] 11:10:05 INFO -
[task 2020-06-11T11:10:05.684Z] 11:10:05 INFO - Console message: [JavaScript Error: "Error: TelemetryStopwatch: requesting elapsed time for nonexisting stopwatch. Histogram: "FX_SESSION_RESTORE_CONTENT_COLLECT_DATA_MS", key: "historychange"" {file: "resource:///modules/sessionstore/ContentSessionStore.jsm" line: 472}]
[task 2020-06-11T11:10:05.684Z] 11:10:05 INFO - send@resource:///modules/sessionstore/ContentSessionStore.jsm:472:28
[task 2020-06-11T11:10:05.684Z] 11:10:05 INFO - flush@resource:///modules/sessionstore/ContentSessionStore.jsm:800:23
[task 2020-06-11T11:10:05.684Z] 11:10:05 INFO - receiveMessage@resource:///modules/sessionstore/ContentSessionStore.jsm:616:14
[task 2020-06-11T11:10:05.684Z] 11:10:05 INFO - MessageListener.receiveMessageContentSessionStore/<@resource:///modules/sessionstore/ContentSessionStore.jsm:545:30
[task 2020-06-11T11:10:05.684Z] 11:10:05 INFO - ContentSessionStore@resource:///modules/sessionstore/ContentSessionStore.jsm:545:14
[task 2020-06-11T11:10:05.685Z] 11:10:05 INFO - @chrome://browser/content/content-sessionStore.js:13:6
[task 2020-06-11T11:10:05.685Z] 11:10:05 INFO -
[task 2020-06-11T11:10:05.685Z] 11:10:05 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-11T11:10:05.685Z] 11:10:05 INFO - collect@resource://gre/modules/sessionstore/SessionHistory.jsm:109:1
[task 2020-06-11T11:10:05.685Z] 11:10:05 INFO - collect@resource://gre/modules/sessionstore/SessionHistory.jsm:39:35
[task 2020-06-11T11:10:05.686Z] 11:10:05 INFO - collectFrom/<@resource:///modules/sessionstore/ContentSessionStore.jsm:200:36
[task 2020-06-11T11:10:05.686Z] 11:10:05 INFO - send@resource:///modules/sessionstore/ContentSessionStore.jsm:469:19
[task 2020-06-11T11:10:05.686Z] 11:10:05 INFO - onUnload@resource:///modules/sessionstore/ContentSessionStore.jsm:814:23
[task 2020-06-11T11:10:05.686Z] 11:10:05 INFO - handleEvent@resource:///modules/sessionstore/ContentSessionStore.jsm:807:12
[task 2020-06-11T11:10:05.686Z] 11:10:05 INFO - EventListener.handleEventContentSessionStore@resource:///modules/sessionstore/ContentSessionStore.jsm:550:8
[task 2020-06-11T11:10:05.687Z] 11:10:05 INFO - @chrome://browser/content/content-sessionStore.js:13:6
[task 2020-06-11T11:10:05.687Z] 11:10:05 INFO -
[task 2020-06-11T11:10:05.687Z] 11:10:05 INFO - Buffered messages logged at 11:03:55
[task 2020-06-11T11:10:05.688Z] 11:10:05 INFO - Console message: [JavaScript Error: "Unknown Collection "main/messaging-experiments"" {file: "resource://services-settings/RemoteSettingsClient.jsm" line: 154}]
[task 2020-06-11T11:10:05.688Z] 11:10:05 INFO - UnknownCollectionError@resource://services-settings/RemoteSettingsClient.jsm:154:5
[task 2020-06-11T11:10:05.688Z] 11:10:05 INFO - sync@resource://services-settings/RemoteSettingsClient.jsm:413:13
[task 2020-06-11T11:10:05.688Z] 11:10:05 INFO - asyncget@resource://services-settings/RemoteSettingsClient.jsm:350:22
[task 2020-06-11T11:10:05.688Z] 11:10:05 INFO - asyncupdateRecipes@resource://messaging-system/lib/RemoteSettingsExperimentLoader.jsm:142:49
[task 2020-06-11T11:10:05.689Z] 11:10:05 INFO - setTimer/<@resource://messaging-system/lib/RemoteSettingsExperimentLoader.jsm:191:18
[task 2020-06-11T11:10:05.689Z] 11:10:05 INFO - TM_notify/</<@resource://gre/modules/UpdateTimerManager.jsm:259:36
[task 2020-06-11T11:10:05.689Z] 11:10:05 INFO - ChromeUtils::IdleDispatch handler*TM_notify/<@resource://gre/modules/UpdateTimerManager.jsm:257:25
[task 2020-06-11T11:10:05.689Z] 11:10:05 INFO - TM_notify@resource://gre/modules/UpdateTimerManager.jsm:292:7
[task 2020-06-11T11:10:05.689Z] 11:10:05 INFO -
[task 2020-06-11T11:10:05.689Z] 11:10:05 INFO - Buffered messages finished
[task 2020-06-11T11:10:05.690Z] 11:10:05 ERROR - TEST-UNEXPECTED-TIMEOUT | browser/components/sessionstore/test/browser_595601-restore_hidden.js | application timed out after 370 seconds with no output

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.