Enterprise enrollment test times out on panther & zako waiting on isEnrollmentSuccessfulForTest() |
|||
Issue descriptionThis is happening on about 50% of the test runs on panther and zako. Rikku is also affected to a lessor degree. It seems that the test is able to get through most of the steps but gets hung on trying to click the 'passwordNext' button after entering password (it never gets past the password enter window). https://cs.corp.google.com/github/catapult-project/catapult/telemetry/telemetry/internal/backends/chrome/oobe.py?l=112 I tried it on a local setup with panther and observed the test times-out about 30-40% of the time because of this. Here is a snippet from the debug logs of the test run: 12/19 01:09:10.519 DEBUG|inspector_websocke:0097| sent [{ "id": 61, "method": "Runtime.evaluate", "params": { "expression": "Oobe.isEnrollmentSuccessfulForTest()", "returnByValue": true } }] 12/19 01:09:10.521 DEBUG|inspector_websocke:0164| got [{ "id": 61, "result": { "result": { "type": "boolean", "value": false } } }] 12/19 01:09:13.820 DEBUG|inspector_websocke:0097| sent [{ "id": 62, "method": "Console.enable" }] 12/19 01:09:13.822 DEBUG|inspector_websocke:0164| got [{ "method": "Console.messageAdded", "params": { "message": { "column": 9, "level": "warning", "line": 1, "source": "console-api", "text": "This file is deprecated. Please use `iron-flex-layout/iron-flex-layout-classes.html`, and one of the specific dom-modules instead", "url": "chrome://resources/polymer/v1_0/iron-flex-layout/classes/iron-shadow-flex-layout-extracted.js" } } }] 12/19 01:09:13.823 DEBUG|inspector_websocke:0164| got [{ "method": "Console.messageAdded", "params": { "message": { "column": 9, "level": "warning", "line": 1, "source": "console-api", "text": "This file is deprecated. Please use `iron-flex-layout/iron-flex-layout-classes.html`, and one of the specific dom-modules instead", "url": "chrome://resources/polymer/v1_0/iron-flex-layout/classes/iron-flex-layout-extracted.js" } } }] 12/19 01:09:13.824 DEBUG|inspector_websocke:0164| got [{ "method": "Console.messageAdded", "params": { "message": { "column": 13, "level": "log", "line": 7768, "source": "console-api", "text": "Loading asset bundle oauth-enrollment", "url": "chrome://oobe/oobe.js" } } }] 12/19 01:09:13.825 DEBUG|inspector_websocke:0164| got [{ "method": "Console.messageAdded", "params": { "message": { "column": 13, "level": "log", "line": 7752, "source": "console-api", "text": "Finished loading asset bundle oauth-enrollment", "url": "chrome://oobe/oobe.js" } } }] 12/19 01:09:13.826 DEBUG|inspector_websocke:0164| got [{ "method": "Console.messageAdded", "params": { "message": { "column": 15, "level": "warning", "line": 15964, "source": "console-api", "text": "Unrecognized message from GAIA: showConfirmCancel", "url": "chrome://oobe/oobe.js" } } }] 12/19 01:09:13.827 DEBUG|inspector_websocke:0164| got [{ "method": "Console.messageAdded", "params": { "message": { "column": 15, "level": "warning", "line": 15964, "source": "console-api", "text": "Unrecognized message from GAIA: clearOldAttempts", "url": "chrome://oobe/oobe.js" } } }] 12/19 01:09:13.828 DEBUG|inspector_websocke:0164| got [{ "method": "Console.messageAdded", "params": { "message": { "column": 15, "level": "warning", "line": 15964, "source": "console-api", "text": "Unrecognized message from GAIA: showConfirmCancel", "url": "chrome://oobe/oobe.js" } } }] 12/19 01:09:13.828 DEBUG|inspector_websocke:0164| got [{ "id": 62, "result": {} }] 12/19 01:09:23.836 DEBUG|inspector_websocke:0097| sent [{ "id": 63, "method": "Console.disable" }] 12/19 01:09:23.837 DEBUG|inspector_websocke:0164| got [{ "id": 63, "result": {} }] 12/19 01:09:23.856 DEBUG| cros_interface:0363| ListProcesses(<predicate>)->[165 processes] 12/19 01:09:23.857 INFO | cros_interface:0539| (Re)starting the ui (logs the user out) 12/19 01:09:23.866 DEBUG| cros_interface:0439| IsServiceRunning(ui)->True 12/19 01:09:23.867 DEBUG| cros_interface:0058| sh -c restart ui 12/19 01:09:24.234 DEBUG| cros_interface:0067| > stdout=[ui start/running, process 5726 ], stderr=[] 12/19 01:09:24.235 DEBUG| cros_interface:0058| sh -c cryptohome-path user 'test@test.test' 12/19 01:09:24.244 DEBUG| cros_interface:0067| > stdout=[/home/user/62dd99158cb28d56fe5f52ed5cd37aa0a1586220 ], stderr=[] 12/19 01:09:24.244 DEBUG| cros_interface:0058| sh -c /bin/df /home/user/62dd99158cb28d56fe5f52ed5cd37aa0a1586220 12/19 01:09:24.249 DEBUG| cros_interface:0067| > stdout=[], stderr=[df: '/home/user/62dd99158cb28d56fe5f52ed5cd37aa0a1586220': No such file or directory ] 12/19 01:09:24.251 DEBUG| base_utils:0185| Running 'logger "autotest finished iteration /usr/local/autotest/results/default/enterprise_RemoraRequisition/sysinfo/iteration.1"' 12/19 01:09:24.257 WARNI| test:0606| Autotest caught exception when running test: Traceback (most recent call last): File "/usr/local/autotest/common_lib/test.py", line 600, in _exec _call_test_function(self.execute, *p_args, **p_dargs) File "/usr/local/autotest/common_lib/test.py", line 810, in _call_test_function raise error.UnhandledTestFail(e) UnhandledTestFail: Unhandled TimeoutException: Timed out while waiting 30s for IsJavaScriptExpressionTrue. Console output: (warning) chrome://resources/polymer/v1_0/iron-flex-layout/classes/iron-shadow-flex-layout-extracted.js:1: This file is deprecated. Please use `iron-flex-layout/iron-flex-layout-classes.html`, and one of the specific dom-modules instead (warning) chrome://resources/polymer/v1_0/iron-flex-layout/classes/iron-flex-layout-extracted.js:1: This file is deprecated. Please use `iron-flex-layout/iron-flex-layout-classes.html`, and one of the specific dom-modules instead (log) chrome://oobe/oobe.js:7768: Loading asset bundle oauth-enrollment (log) chrome://oobe/oobe.js:7752: Finished loading asset bundle oauth-enrollment (warning) chrome://oobe/oobe.js:15964: Unrecognized message from GAIA: showConfirmCancel (warning) chrome://oobe/oobe.js:15964: Unrecognized message from GAIA: clearOldAttempts (warning) chrome://oobe/oobe.js:15964: Unrecognized message from GAIA: showConfirmCancel Traceback (most recent call last): File "/usr/local/autotest/common_lib/test.py", line 804, in _call_test_function return func(*args, **dargs) File "/usr/local/autotest/common_lib/test.py", line 461, in execute dargs) File "/usr/local/autotest/common_lib/test.py", line 347, in _call_run_once_with_retry postprocess_profiled_run, args, dargs) File "/usr/local/autotest/common_lib/test.py", line 376, in _call_run_once self.run_once(*args, **dargs) File "/usr/local/autotest/tests/enterprise_RemoraRequisition/enterprise_RemoraRequisition.py", line 42, in run_once enrollment.RemoraEnrollment(cr.browser, user_id, password) File "/usr/local/autotest/common_lib/cros/enrollment.py", line 38, in RemoraEnrollment lambda: browser.oobe.NavigateGaiaLogin( File "/usr/local/autotest/common_lib/cros/chrome.py", line 288, in wait_for_browser_restart Chrome.did_browser_crash(func) File "/usr/local/autotest/common_lib/cros/chrome.py", line 273, in did_browser_crash func() File "/usr/local/autotest/common_lib/cros/enrollment.py", line 40, in <lambda> for_user_triggered_enrollment=False)) File "/usr/local/telemetry/src/third_party/catapult/common/py_trace_event/py_trace_event/trace_event_impl/decorators.py", line 52, in traced_function return func(*args, **kwargs) File "/usr/local/telemetry/src/third_party/catapult/telemetry/telemetry/internal/backends/chrome/oobe.py", line 80, in NavigateGaiaLogin 30) File "/usr/local/telemetry/src/third_party/catapult/common/py_trace_event/py_trace_event/trace_event_impl/decorators.py", line 52, in traced_function return func(*args, **kwargs) File "/usr/local/telemetry/src/third_party/catapult/telemetry/telemetry/internal/browser/web_contents.py", line 143, in WaitForJavaScriptExpression e.message + '\n' + debug_message) TimeoutException: Timed out while waiting 30s for IsJavaScriptExpressionTrue. Console output: (warning) chrome://resources/polymer/v1_0/iron-flex-layout/classes/iron-shadow-flex-layout-extracted.js:1: This file is deprecated. Please use `iron-flex-layout/iron-flex-layout-classes.html`, and one of the specific dom-modules instead (warning) chrome://resources/polymer/v1_0/iron-flex-layout/classes/iron-flex-layout-extracted.js:1: This file is deprecated. Please use `iron-flex-layout/iron-flex-layout-classes.html`, and one of the specific dom-modules instead (log) chrome://oobe/oobe.js:7768: Loading asset bundle oauth-enrollment (log) chrome://oobe/oobe.js:7752: Finished loading asset bundle oauth-enrollment (warning) chrome://oobe/oobe.js:15964: Unrecognized message from GAIA: showConfirmCancel (warning) chrome://oobe/oobe.js:15964: Unrecognized message from GAIA: clearOldAttempts (warning) chrome://oobe/oobe.js:15964: Unrecognized message from GAIA: showConfirmCancel 12/19 01:09:24.261 DEBUG| logging_manager:0627| Logging subprocess finished 12/19 01:09:24.263 DEBUG| logging_manager:0627| Logging subprocess finished
,
Dec 21 2016
This should be looked at by the cros gardener (especially since I'm OOO for another 2 weeks).
,
Dec 22 2016
I found an issue 558424 which matches the bluetooth-keyboard-and-mouse search symptom we are seeing on the lab machines ("Enable Bluetooth pairing when no input methods detected"), but there isn't an indication in that issue that any code changes were made or included in a build.
,
Dec 22 2016
There is also issue 570007 which has a M-57 tag on it.
,
Sep 12 2017
|
|||
►
Sign in to add a comment |
|||
Comment 1 by omrilio@chromium.org
, Dec 20 2016