New issue
Advanced search Search tips
Note: Color blocks (like or ) mean that a user may not be available. Tooltip shows the reason.

Issue 675802 link

Starred by 1 user

Issue metadata

Status: WontFix
Owner:
Closed: Sep 2017
Cc:
Components:
EstimatedDays: ----
NextAction: ----
OS: Chrome
Pri: 2
Type: Bug



Sign in to add a comment

Enterprise enrollment test times out on panther & zako waiting on isEnrollmentSuccessfulForTest()

Project Member Reported by harpreet@chromium.org, Dec 20 2016

Issue description

This 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
 
Status: Assigned (was: Untriaged)
Cc: achuith@chromium.org
Owner: jdufault@chromium.org
This should be looked at by the cros gardener (especially since I'm OOO for another 2 weeks).

Comment 3 by mlight@chromium.org, 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.

Comment 4 Deleted

Comment 5 by mlight@chromium.org, Dec 22 2016

There is also  issue 570007  which has a M-57 tag on it.  
Status: WontFix (was: Assigned)

Sign in to add a comment