Closed Bug 1821910 Opened 2 years ago Closed 2 years ago

Intermittent Linux Wd TEST-UNEXPECTED-TIMEOUT | expected OK (TEST-INFO took 80006ms)

Categories

(Remote Protocol :: Marionette, defect, P5)

defect

Tracking

(Not tracked)

RESOLVED DUPLICATE of bug 1791951

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: intermittent-failure)

Filed by: imoraru [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=408649751&repo=try
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/Vbl0YCvyT8G5FwZci5o1bA/runs/0/artifacts/public/logs/live_backing.log


[task 2023-03-12T11:45:40.675Z] 11:45:40     INFO - STDOUT: FAILED
[task 2023-03-12T11:45:40.675Z] 11:45:40     INFO - PID 1832 | 1678621540674	webdriver::server	DEBUG	-> POST /session/873dd24b-49e2-4217-9054-81b893584c63/timeouts {"implicit": 0}
[task 2023-03-12T11:45:40.753Z] 11:45:40     INFO - PID 1832 | 1678621540750	Marionette	DEBUG	0 -> [0,791,"WebDriver:SetTimeouts",{"implicit":0}]
[task 2023-03-12T11:45:40.754Z] 11:45:40     INFO - PID 1832 | 1678621540751	Marionette	DEBUG	0 <- [1,791,null,{"value":null}]
[task 2023-03-12T11:45:40.782Z] 11:45:40     INFO - PID 1832 | 1678621540781	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2023-03-12T11:45:40.785Z] 11:45:40     INFO - PID 1832 | 1678621540784	webdriver::server	DEBUG	-> POST /session/873dd24b-49e2-4217-9054-81b893584c63/timeouts {"pageLoad": 300000}
[task 2023-03-12T11:45:40.798Z] 11:45:40     INFO - PID 1832 | 1678621540797	Marionette	DEBUG	0 -> [0,792,"WebDriver:SetTimeouts",{"pageLoad":300000}]
[task 2023-03-12T11:45:40.801Z] 11:45:40     INFO - PID 1832 | 1678621540798	Marionette	DEBUG	0 <- [1,792,null,{"value":null}]
[task 2023-03-12T11:45:40.811Z] 11:45:40     INFO - PID 1832 | 1678621540810	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2023-03-12T11:45:40.813Z] 11:45:40     INFO - PID 1832 | 1678621540812	webdriver::server	DEBUG	-> POST /session/873dd24b-49e2-4217-9054-81b893584c63/timeouts {"script": 30000}
[task 2023-03-12T11:45:40.872Z] 11:45:40     INFO - PID 1832 | 1678621540871	Marionette	DEBUG	0 -> [0,793,"WebDriver:SetTimeouts",{"script":30000}]
[task 2023-03-12T11:45:40.873Z] 11:45:40     INFO - PID 1832 | 1678621540872	Marionette	DEBUG	0 <- [1,793,null,{"value":null}]
[task 2023-03-12T11:45:40.891Z] 11:45:40     INFO - PID 1832 | 1678621540889	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2023-03-12T11:45:40.892Z] 11:45:40     INFO - PID 1832 | 1678621540890	webdriver::server	DEBUG	-> GET /session/873dd24b-49e2-4217-9054-81b893584c63/window
[task 2023-03-12T11:45:40.965Z] 11:45:40     INFO - PID 1832 | 1678621540964	Marionette	DEBUG	0 -> [0,794,"WebDriver:GetWindowHandle",{}]
[task 2023-03-12T11:45:40.967Z] 11:45:40     INFO - PID 1832 | 1678621540965	Marionette	DEBUG	0 <- [1,794,null,{"value":"214dc363-59f5-4649-a34e-a51585690ec7"}]
[task 2023-03-12T11:45:41.001Z] 11:45:41     INFO - PID 1832 | 1678621541000	webdriver::server	DEBUG	<- 200 OK {"value":"214dc363-59f5-4649-a34e-a51585690ec7"}
[task 2023-03-12T11:45:41.003Z] 11:45:41     INFO - PID 1832 | 1678621541001	webdriver::server	DEBUG	-> GET /session/873dd24b-49e2-4217-9054-81b893584c63/window
[task 2023-03-12T11:45:41.044Z] 11:45:41     INFO - PID 1832 | 1678621541042	Marionette	DEBUG	0 -> [0,795,"WebDriver:GetWindowHandle",{}]
[task 2023-03-12T11:45:41.045Z] 11:45:41     INFO - PID 1832 | 1678621541043	Marionette	DEBUG	0 <- [1,795,null,{"value":"214dc363-59f5-4649-a34e-a51585690ec7"}]
[task 2023-03-12T11:45:41.047Z] 11:45:41     INFO - PID 1832 | 1678621541046	webdriver::server	DEBUG	<- 200 OK {"value":"214dc363-59f5-4649-a34e-a51585690ec7"}
[task 2023-03-12T11:45:41.049Z] 11:45:41     INFO - PID 1832 | 1678621541047	webdriver::server	DEBUG	-> GET /session/873dd24b-49e2-4217-9054-81b893584c63/window/handles
[task 2023-03-12T11:45:41.100Z] 11:45:41     INFO - PID 1832 | 1678621541099	Marionette	DEBUG	0 -> [0,796,"WebDriver:GetWindowHandles",{}]
[task 2023-03-12T11:45:41.102Z] 11:45:41     INFO - PID 1832 | 1678621541101	Marionette	DEBUG	0 <- [1,796,null,["214dc363-59f5-4649-a34e-a51585690ec7"]]
[task 2023-03-12T11:45:41.146Z] 11:45:41     INFO - PID 1832 | 1678621541145	webdriver::server	DEBUG	<- 200 OK {"value":["214dc363-59f5-4649-a34e-a51585690ec7"]}
[task 2023-03-12T11:45:41.147Z] 11:45:41     INFO - PID 1832 | 1678621541146	webdriver::server	DEBUG	-> POST /session/873dd24b-49e2-4217-9054-81b893584c63/window {"handle": "214dc363-59f5-4649-a34e-a51585690ec7"}
[task 2023-03-12T11:45:41.157Z] 11:45:41     INFO - PID 1832 | 1678621541156	Marionette	DEBUG	0 -> [0,797,"WebDriver:SwitchToWindow",{"handle":"214dc363-59f5-4649-a34e-a51585690ec7"}]
[task 2023-03-12T11:45:41.160Z] 11:45:41     INFO - PID 1832 | 1678621541159	Marionette	DEBUG	0 <- [1,797,null,{"value":null}]
[task 2023-03-12T11:45:41.163Z] 11:45:41     INFO - PID 1832 | 1678621541163	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2023-03-12T11:45:41.165Z] 11:45:41     INFO - PID 1832 | 1678621541164	webdriver::server	DEBUG	-> POST /session/873dd24b-49e2-4217-9054-81b893584c63/alert/dismiss {}
[task 2023-03-12T11:45:41.228Z] 11:45:41     INFO - PID 1832 | 1678621541227	Marionette	DEBUG	0 -> [0,798,"WebDriver:DismissAlert",{}]
[task 2023-03-12T11:45:41.229Z] 11:45:41     INFO - PID 1832 | 1678621541228	Marionette	DEBUG	0 <- [1,798,{"error":"no such alert","message":"","stacktrace":"RemoteError@chrome://remote/content/shared/RemoteError.sys.mjs:8:8\nWebDriverError@chrome://remote/content/shared/webdriver/Er ... ote/content/marionette/server.sys.mjs:249:9\n_onJSONObjectReady/<@chrome://remote/content/marionette/transport.sys.mjs:496:20\n"},null]
[task 2023-03-12T11:45:41.252Z] 11:45:41     INFO - PID 1832 | 1678621541251	webdriver::server	DEBUG	<- 404 Not Found {"value":{"error":"no such alert","message":"","stacktrace":"RemoteError@chrome://remote/content/shared/Remo ... ontent/marionette/server.sys.mjs:249:9\n_onJSONObjectReady/<@chrome://remote/content/marionette/transport.sys.mjs:496:20\n"}}
[task 2023-03-12T11:45:41.255Z] 11:45:41     INFO - PID 1832 | 1678621541252	webdriver::server	DEBUG	-> POST /session/873dd24b-49e2-4217-9054-81b893584c63/window {"handle": "214dc363-59f5-4649-a34e-a51585690ec7"}
[task 2023-03-12T11:45:41.265Z] 11:45:41     INFO - PID 1832 | 1678621541264	Marionette	DEBUG	0 -> [0,799,"WebDriver:SwitchToWindow",{"handle":"214dc363-59f5-4649-a34e-a51585690ec7"}]
[task 2023-03-12T11:45:41.267Z] 11:45:41     INFO - PID 1832 | 1678621541266	Marionette	DEBUG	0 <- [1,799,null,{"value":null}]
[task 2023-03-12T11:45:41.293Z] 11:45:41     INFO - PID 1832 | 1678621541292	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2023-03-12T11:45:41.295Z] 11:45:41     INFO - PID 1832 | 1678621541293	webdriver::server	DEBUG	-> GET /session/873dd24b-49e2-4217-9054-81b893584c63/window
[task 2023-03-12T11:45:41.334Z] 11:45:41     INFO - PID 1832 | 1678621541333	Marionette	DEBUG	0 -> [0,800,"WebDriver:GetWindowHandle",{}]
[task 2023-03-12T11:45:41.334Z] 11:45:41     INFO - PID 1832 | 1678621541333	Marionette	DEBUG	0 <- [1,800,null,{"value":"214dc363-59f5-4649-a34e-a51585690ec7"}]
[task 2023-03-12T11:45:41.336Z] 11:45:41     INFO - PID 1832 | 1678621541335	webdriver::server	DEBUG	<- 200 OK {"value":"214dc363-59f5-4649-a34e-a51585690ec7"}
[task 2023-03-12T11:45:41.338Z] 11:45:41     INFO - PID 1832 | 1678621541337	webdriver::server	DEBUG	-> GET /session/873dd24b-49e2-4217-9054-81b893584c63/window/handles
[task 2023-03-12T11:45:41.374Z] 11:45:41     INFO - PID 1832 | 1678621541372	Marionette	DEBUG	0 -> [0,801,"WebDriver:GetWindowHandles",{}]
[task 2023-03-12T11:45:41.375Z] 11:45:41     INFO - PID 1832 | 1678621541373	Marionette	DEBUG	0 <- [1,801,null,["214dc363-59f5-4649-a34e-a51585690ec7"]]
[task 2023-03-12T11:45:41.425Z] 11:45:41     INFO - PID 1832 | 1678621541424	webdriver::server	DEBUG	<- 200 OK {"value":["214dc363-59f5-4649-a34e-a51585690ec7"]}
[task 2023-03-12T11:45:41.427Z] 11:45:41     INFO - PID 1832 | 1678621541426	webdriver::server	DEBUG	-> POST /session/873dd24b-49e2-4217-9054-81b893584c63/window {"handle": "214dc363-59f5-4649-a34e-a51585690ec7"}
[task 2023-03-12T11:45:41.438Z] 11:45:41     INFO - PID 1832 | 1678621541437	Marionette	DEBUG	0 -> [0,802,"WebDriver:SwitchToWindow",{"handle":"214dc363-59f5-4649-a34e-a51585690ec7"}]
[task 2023-03-12T11:45:41.440Z] 11:45:41     INFO - PID 1832 | 1678621541439	Marionette	DEBUG	0 <- [1,802,null,{"value":null}]
[task 2023-03-12T11:45:41.444Z] 11:45:41     INFO - PID 1832 | 1678621541443	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2023-03-12T11:45:41.446Z] 11:45:41     INFO - PID 1832 | 1678621541445	webdriver::server	DEBUG	-> POST /session/873dd24b-49e2-4217-9054-81b893584c63/window/rect {"width": 800, "height": 600}
[task 2023-03-12T11:45:41.509Z] 11:45:41     INFO - PID 1832 | 1678621541508	Marionette	DEBUG	0 -> [0,803,"WebDriver:SetWindowRect",{"height":600,"width":800}]
[task 2023-03-12T11:45:41.511Z] 11:45:41     INFO - PID 1832 | 1678621541509	Marionette	TRACE	Requested window geometry matches
[task 2023-03-12T11:45:41.512Z] 11:45:41     INFO - PID 1832 | 1678621541511	Marionette	DEBUG	0 <- [1,803,null,{"x":100,"y":100,"width":800,"height":600}]
[task 2023-03-12T11:45:41.547Z] 11:45:41     INFO - PID 1832 | 1678621541546	webdriver::server	DEBUG	<- 200 OK {"value":{"x":100,"y":100,"width":800,"height":600}}
[task 2023-03-12T11:45:41.549Z] 11:45:41     INFO - PID 1832 | 1678621541547	webdriver::server	DEBUG	-> POST /session/873dd24b-49e2-4217-9054-81b893584c63/frame {"id": null}
[task 2023-03-12T11:45:41.565Z] 11:45:41     INFO - PID 1832 | 1678621541564	Marionette	DEBUG	0 -> [0,804,"WebDriver:SwitchToFrame",{"id":null}]
[task 2023-03-12T11:45:41.606Z] 11:45:41     INFO - PID 1832 | 1678621541605	Marionette	DEBUG	0 <- [1,804,null,{"value":null}]
[task 2023-03-12T11:45:41.650Z] 11:45:41     INFO - PID 1832 | 1678621541648	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2023-03-12T11:45:41.656Z] 11:45:41     INFO - PID 1832 | 1678621541655	webdriver::server	DEBUG	-> DELETE /session/873dd24b-49e2-4217-9054-81b893584c63
[task 2023-03-12T11:45:41.694Z] 11:45:41     INFO - PID 1832 | 1678621541693	Marionette	DEBUG	0 -> [0,805,"Marionette:Quit",{"flags":["eForceQuit"]}]
[task 2023-03-12T11:45:41.695Z] 11:45:41     INFO - PID 1832 | 1678621541694	Marionette	INFO	Stopped listening on port 35052
[task 2023-03-12T11:45:41.700Z] 11:45:41     INFO - PID 1832 | 1678621541697	Marionette	TRACE	[30] MarionetteCommands actor destroyed for window id 21474836575
[task 2023-03-12T11:45:41.701Z] 11:45:41     INFO - PID 1832 | 1678621541699	Marionette	TRACE	[33] MarionetteCommands actor destroyed for window id 21474836584
[task 2023-03-12T11:45:41.702Z] 11:45:41     INFO - PID 1832 | 1678621541700	Marionette	TRACE	[31] MarionetteCommands actor destroyed for window id 21474836578
[task 2023-03-12T11:45:41.702Z] 11:45:41     INFO - PID 1832 | 1678621541700	Marionette	TRACE	[32] MarionetteCommands actor destroyed for window id 21474836581
[task 2023-03-12T11:45:42.093Z] 11:45:42     INFO - PID 1832 | 1678621542091	Marionette	TRACE	Received observer notification quit-application
[task 2023-03-12T11:45:42.101Z] 11:45:42     INFO - PID 1832 | 1678621542100	Marionette	TRACE	Received observer notification quit-application
[task 2023-03-12T11:45:42.103Z] 11:45:42     INFO - PID 1832 | 1678621542102	Marionette	DEBUG	Marionette stopped listening
[task 2023-03-12T11:45:42.118Z] 11:45:42     INFO - PID 1832 | 1678621542116	Marionette	DEBUG	0 <- [1,805,null,{"cause":"shutdown","forced":false,"in_app":true}]
[task 2023-03-12T11:45:42.308Z] 11:45:42     INFO - PID 1832 | 1678621542306	webdriver::server	DEBUG	Teardown session
[task 2023-03-12T11:45:42.334Z] 11:45:42     INFO - PID 1832 | 1678621542329	Marionette	DEBUG	Closed connection 0
[task 2023-03-12T11:45:43.524Z] 11:45:43     INFO - PID 1832 | console.error: RemoteSecuritySettings.jsm:
[task 2023-03-12T11:45:43.525Z] 11:45:43     INFO - PID 1832 |   Failed to download attachment: TypeError: NetworkError: Network request failed
[task 2023-03-12T11:45:43.538Z] 11:45:43     INFO - PID 1832 | console.error: RemoteSecuritySettings.jsm:
<...>
[task 2023-03-12T11:45:45.592Z] 11:45:45     INFO - PID 1832 |           Phase: profile-before-change-telemetry
[task 2023-03-12T11:45:45.593Z] 11:45:45     INFO - PID 1832 |           State: Error getting state: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange" at addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:727:15
[task 2023-03-12T11:45:45.593Z] 11:45:45     INFO - PID 1832 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:523:26
[task 2023-03-12T11:45:45.593Z] 11:45:45     INFO - PID 1832 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:458:15
[task 2023-03-12T11:45:45.593Z] 11:45:45     INFO - PID 1832 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
[task 2023-03-12T11:45:45.593Z] 11:45:45     INFO - PID 1832 | @resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
[task 2023-03-12T11:45:45.593Z] 11:45:45     INFO - PID 1832 | @resource://gre/modules/osfile.jsm:12:30
[task 2023-03-12T11:45:45.593Z] 11:45:45     INFO - PID 1832 | @resource://gre/modules/TelemetryStorage.sys.mjs:10:28
[task 2023-03-12T11:45:45.594Z] 11:45:45     INFO - PID 1832 | _checkPendingPings@resource://gre/modules/TelemetrySend.sys.mjs:863:17
[task 2023-03-12T11:45:45.594Z] 11:45:45     INFO - PID 1832 | setup@resource://gre/modules/TelemetrySend.sys.mjs:803:18
[task 2023-03-12T11:45:45.595Z] 11:45:45     INFO - PID 1832 | setup@resource://gre/modules/TelemetrySend.sys.mjs:241:30
[task 2023-03-12T11:45:45.596Z] 11:45:45     INFO - PID 1832 | setupTelemetry/this._delayedInitTask<@resource://gre/modules/TelemetryControllerParent.sys.mjs:824:36
[task 2023-03-12T11:45:45.596Z] 11:45:45     INFO - PID 1832 | observe@resource://gre/modules/AsyncShutdown.sys.mjs:576:16
[task 2023-03-12T11:45:45.599Z] 11:45:45     INFO - PID 1832 | WARNING: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange"
[task 2023-03-12T11:45:45.599Z] 11:45:45     INFO - PID 1832 | WARNING: addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:727:15
[task 2023-03-12T11:45:45.599Z] 11:45:45     INFO - PID 1832 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:523:26
[task 2023-03-12T11:45:45.599Z] 11:45:45     INFO - PID 1832 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:458:15
[task 2023-03-12T11:45:45.599Z] 11:45:45     INFO - PID 1832 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
[task 2023-03-12T11:45:45.599Z] 11:45:45     INFO - PID 1832 | @resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
[task 2023-03-12T11:45:45.600Z] 11:45:45     INFO - PID 1832 | @resource://gre/modules/osfile.jsm:12:30
[task 2023-03-12T11:45:45.600Z] 11:45:45     INFO - PID 1832 | @resource://gre/modules/TelemetryStorage.sys.mjs:10:28
[task 2023-03-12T11:45:45.600Z] 11:45:45     INFO - PID 1832 | _checkPendingPings@resource://gre/modules/TelemetrySend.sys.mjs:863:17
[task 2023-03-12T11:45:45.600Z] 11:45:45     INFO - PID 1832 | setup@resource://gre/modules/TelemetrySend.sys.mjs:803:18
[task 2023-03-12T11:45:45.600Z] 11:45:45     INFO - PID 1832 | setup@resource://gre/modules/TelemetrySend.sys.mjs:241:30
[task 2023-03-12T11:45:45.600Z] 11:45:45     INFO - PID 1832 | setupTelemetry/this._delayedInitTask<@resource://gre/modules/TelemetryControllerParent.sys.mjs:824:36
[task 2023-03-12T11:45:45.600Z] 11:45:45     INFO - PID 1832 | observe@resource://gre/modules/AsyncShutdown.sys.mjs:576:16
[task 2023-03-12T11:45:45.605Z] 11:45:45     INFO - PID 1832 | JavaScript error: resource://gre/modules/AsyncShutdown.sys.mjs, line 727: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange"
[task 2023-03-12T11:45:45.606Z] 11:45:45     INFO - PID 1832 | JavaScript error: resource://gre/modules/AsyncShutdown.sys.mjs, line 727: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange"
[task 2023-03-12T11:45:45.607Z] 11:45:45     INFO - PID 1832 | JavaScript error: resource://gre/modules/AsyncShutdown.sys.mjs, line 727: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange"
[task 2023-03-12T11:45:45.608Z] 11:45:45     INFO - PID 1832 | JavaScript error: resource://gre/modules/AsyncShutdown.sys.mjs, line 727: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange"
[task 2023-03-12T11:45:45.609Z] 11:45:45     INFO - PID 1832 | JavaScript error: resource://gre/modules/AsyncShutdown.sys.mjs, line 727: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange"
[task 2023-03-12T11:45:48.423Z] 11:45:48     INFO - TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/find_element_from_shadow_root/find.py | expected OK
[task 2023-03-12T11:45:48.423Z] 11:45:48     INFO - TEST-INFO took 80006ms
[task 2023-03-12T11:45:48.435Z] 11:45:48     INFO - PID 1832 | ==1846==WARNING: Can't write to symbolizer at fd 39
[task 2023-03-12T11:45:49.126Z] 11:45:49     INFO - Closing logging queue
[task 2023-03-12T11:45:49.126Z] 11:45:49     INFO - queue closed
[task 2023-03-12T11:45:49.133Z] 11:45:49     INFO - PID 2337 | 1678621549131	geckodriver	INFO	Listening on 127.0.0.1:42969
[task 2023-03-12T11:45:49.134Z] 11:45:49     INFO - Starting runner
[task 2023-03-12T11:45:49.367Z] 11:45:49     INFO - TEST-START | /webdriver/tests/find_element_from_shadow_root/user_prompts.py

Hi Henrik! Can you please take a look at this? Can't figure out what is the reason for the timeout. And if this does not need to be a general bug, please do tell so we can file bugs for each of the failures that were classified with this.
Thank you!

Flags: needinfo?(hskupin)

There might be different reasons.

  1. I can see a lot of failures from RemoteSecuritySettings trying to download an attachment. I filed bug 1821981.

  2. There is a slight delay in shutdown as caused by Telemetry. I filed bug 1821983.

  3. But the main problem here is the amount of time Firefox took to startup. It was 11 seconds:

[task 2023-03-12T11:44:30.725Z] 11:44:30     INFO - PID 1832 | 1678621470724	Marionette	DEBUG	Waiting for initial application window
[task 2023-03-12T11:44:37.247Z] 11:44:37     INFO - PID 1832 | console.error: ({})
[task 2023-03-12T11:44:39.373Z] 11:44:39     INFO - PID 1832 | [ERROR glean_core] Error setting metrics feature config: Json(Error("EOF while parsing a value", line: 1, column: 0))
[task 2023-03-12T11:44:41.852Z] 11:44:41     INFO - PID 1832 | 1678621481851	Marionette	TRACE	Received observer notification browser-idle-startup-tasks-finished

Given that this is bug 1791951 I'm going to dupe.

Status: NEW → RESOLVED
Closed: 2 years ago
Duplicate of bug: 1791951
Flags: needinfo?(hskupin)
Resolution: --- → DUPLICATE
You need to log in before you can comment on or make changes to this bug.