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

Issue 844825 link

Starred by 2 users
Status: Closed
Owner:
█ levarum@chromium.org
Last visit > 30 days ago
Closed: Nov 9
Cc:
kinaba@chromium.org
khmel@chromium.org
█ levarum@chromium.org
█ xiaoyinh@chromium.org
yusukes@chromium.org
nya@chromium.org
█ patricia@chromium.org
dchan@chromium.org
Components:
EstimatedDays: ----
NextAction: ----
OS: Chrome
Pri: 2
Type: Bug



Sign in to add a comment

cheets_PlayStoreTest failed on eve-tot-chrome-pfq-informational with "TimeoutException: Timed out while waiting 60s for py_utils.WaitFor(lambda: not self._GaiaWebviewContext(), 60)"

Project Member Reported by derat@chromium.org, May 19 2018 
cheets_PlayStoreTest failed on eve-tot-chrome-pfq-informational at http://cros-goldeneye/chromeos/healthmonitoring/buildDetails?buildbucketId=8946150401395693744

...
05/18 19:30:27.147 INFO |          __init__:0047| DoNothingForwarder started between 127.0.0.1:39532 and 39532
05/18 19:30:27.147 INFO |chrome_browser_bac:0118| Got devtools config: ws://127.0.0.1:39532/devtools/browser/0e601f5f-52bd-4736-a997-2e138c5c5c03
05/18 19:30:27.563 INFO |              oobe:0035| Invoking Oobe.skipToLoginForTesting
05/18 19:31:46.173 ERROR|           browser:0054| Failed with TimeoutException while starting the browser backend.
05/18 19:31:46.238 INFO |           browser:0207| Closing browser (pid=31879) ...
05/18 19:31:46.242 INFO |    cros_interface:0575| (Re)starting the ui (logs the user out)
05/18 19:31:46.805 INFO |           browser:0220| Browser is closed.
05/18 19:31:46.805 WARNI|              test:0637| The test failed with the following exception
Traceback (most recent call last):
  File "/usr/local/autotest/common_lib/test.py", line 598, in _exec
    _cherry_pick_call(self.initialize, *args, **dargs)
  File "/usr/local/autotest/common_lib/test.py", line 746, in _cherry_pick_call
    return func(*p_args, **p_dargs)
  File "/usr/local/autotest/tests/cheets_PlayStoreTest/cheets_PlayStoreTest.py", line 38, in initialize
    super(cheets_PlayStoreTest, self).initialize(disable_arc_opt_in=False)
  File "/usr/local/autotest/common_lib/cros/arc.py", line 599, in initialize
    **chrome_kargs)
  File "/usr/local/autotest/common_lib/cros/chrome.py", line 172, in __init__
    self._browser = self._browser_to_create.Create()
  File "/usr/local/telemetry/src/third_party/catapult/telemetry/telemetry/internal/backends/chrome/cros_browser_finder.py", line 101, in Create
    browser_backend, self._platform_backend, startup_args)
  File "/usr/local/telemetry/src/third_party/catapult/telemetry/telemetry/internal/backends/chrome/cros_browser_with_oobe.py", line 14, in __init__
    backend, platform_backend, startup_args)
  File "/usr/local/telemetry/src/third_party/catapult/telemetry/telemetry/internal/browser/browser.py", line 48, in __init__
    self._browser_backend.Start(startup_args, startup_url=startup_url)
  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/cros_browser_backend.py", line 97, in Start
    self.oobe.NavigateGaiaLogin(self._username, self._password)
  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 88, in NavigateGaiaLogin
    wait_for_close=not enterprise_enroll)
  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 100, in _NavigateWebviewLogin
    py_utils.WaitFor(lambda: not self._GaiaWebviewContext(), 60)
  File "/usr/local/telemetry/src/third_party/catapult/common/py_utils/py_utils/__init__.py", line 148, in WaitFor
    (timeout, GetConditionString()))
TimeoutException: Timed out while waiting 60s for py_utils.WaitFor(lambda: not self._GaiaWebviewContext(), 60).
05/18 19:31:46.816 INFO |cheets_PlayStoreTe:0046| Ensure app is removed or next test run will fail.
05/18 19:31:46.817 INFO |cheets_PlayStoreTe:0050| We failed to uninstall the apk during cleanup.
05/18 19:31:46.817 INFO |               arc:0628| Skipping ARC cleanup: not initialized
05/18 19:31:46.821 INFO |              test:0924| Starting after_hook for cheets_PlayStoreTest
05/18 19:31:47.123 INFO |              test:0928| after_hook completed
05/18 19:31:47.124 ERROR|          parallel:0026| child process failed
05/18 19:31:47.160 INFO |               job:0232| 		ERROR	cheets_PlayStoreTest	cheets_PlayStoreTest	timestamp=1526697107	localtime=May 18 19:31:47	Unhandled TimeoutException: Timed out while waiting 60s for py_utils.WaitFor(lambda: not self._GaiaWebviewContext(), 60).
  Traceback (most recent call last):
    File "/usr/local/autotest/common_lib/test.py", line 598, in _exec
      _cherry_pick_call(self.initialize, *args, **dargs)
    File "/usr/local/autotest/common_lib/test.py", line 746, in _cherry_pick_call
      return func(*p_args, **p_dargs)
    File "/usr/local/autotest/tests/cheets_PlayStoreTest/cheets_PlayStoreTest.py", line 38, in initialize
      super(cheets_PlayStoreTest, self).initialize(disable_arc_opt_in=False)
    File "/usr/local/autotest/common_lib/cros/arc.py", line 599, in initialize
      **chrome_kargs)
    File "/usr/local/autotest/common_lib/cros/chrome.py", line 172, in __init__
      self._browser = self._browser_to_create.Create()
    File "/usr/local/telemetry/src/third_party/catapult/telemetry/telemetry/internal/backends/chrome/cros_browser_finder.py", line 101, in Create
      browser_backend, self._platform_backend, startup_args)
    File "/usr/local/telemetry/src/third_party/catapult/telemetry/telemetry/internal/backends/chrome/cros_browser_with_oobe.py", line 14, in __init__
      backend, platform_backend, startup_args)
    File "/usr/local/telemetry/src/third_party/catapult/telemetry/telemetry/internal/browser/browser.py", line 48, in __init__
      self._browser_backend.Start(startup_args, startup_url=startup_url)
    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/cros_browser_backend.py", line 97, in Start
      self.oobe.NavigateGaiaLogin(self._username, self._password)
    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 88, in NavigateGaiaLogin
      wait_for_close=not enterprise_enroll)
    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 100, in _NavigateWebviewLogin
      py_utils.WaitFor(lambda: not self._GaiaWebviewContext(), 60)
    File "/usr/local/telemetry/src/third_party/catapult/common/py_utils/py_utils/__init__.py", line 148, in WaitFor
      (timeout, GetConditionString()))
  TimeoutException: Timed out while waiting 60s for py_utils.WaitFor(lambda: not self._GaiaWebviewContext(), 60).

Comment 1 by yusukes@chromium.org, May 19 2018

Cc: yusukes@chromium.org
Owner: khmel@chromium.org
Yury, is this the one you've been working on? Please check, and if not, please reassign to nya@ or Lev.

Comment 2 by xiaoyinh@chromium.org, May 22 2018

Cc: xiaoyinh@chromium.org
This is still seen on most recent eve-tot-chrome-pfq-informational builder. 
https://cros-goldeneye.corp.google.com/chromeos/healthmonitoring/buildDetails?buildbucketId=8945895865944346912

Comment 3 by khmel@chromium.org, May 24 2018

Owner: levarum@chromium.org
This is generic ARC boot failure error. My recent fix for Play Store test itself, which was even not invoked in #2
Sending to Lev for further investigation

Comment 4 by jamescook@chromium.org, May 28 2018 

cheets_PlayStoreTest failed again this weekend, killing a CQ run: https://luci-milo.appspot.com/buildbot/chromeos/veyron_minnie-paladin/6180

I don't know if it's this bug or not. Screenshots show an error attempting to connect to some server. Does this test try to connect to the live play store?

Comment 5 by uekawa@google.com, May 29 2018

Labels: ArcConstable

Comment 6 by kinaba@chromium.org, May 29 2018 

The original issue (WaitFor(lambda: not self._GaiaWebviewContext(), 60)) does not look to have anything related to ARC.
It is from OOBE screen automation, and is seen even on non-ARC devices:
https://stainless.corp.google.com/search?view=list&first_date=2018-05-23&last_date=2018-05-29&status=GOOD&status=WARN&status=FAIL&status=ERROR&reason=self._GaiaWebviewContext&exclude_cts=false&exclude_not_run=false&exclude_non_release=true&exclude_au=true&exclude_acts=true&exclude_retried=true&exclude_non_production=true


#4 looks indeed the failure looks inside ARC.

Comment 7 by zork@chromium.org, Sep 7

Components: Test Platform>Apps>ARC
Labels: -Pri-1 Pri-2

Comment 8 by niwa@google.com, Nov 9

Status: Closed (was: Assigned)
I believe the original issue has been fixed, but cheets_PlayStoreTest is failing for other reasons today.

b/113197541 is the tracking bug. Closing this one.
►

Sign in to add a comment