cheets_StartAndroid.stress failure because Chrome did not start |
||||
Issue descriptionhttps://cros-goldeneye.corp.google.com/chromeos/healthmonitoring/buildDetails?buildbucketId=8928715851182488592 11/27 03:31:13.183 DEBUG| autotest:1283| AUTOTEST_STATUS:: FAIL cheets_StartAndroid.stress cheets_StartAndroid.stress timestamp=1543318272 localtime=Nov 27 03:31:12 Unhandled TimeoutException: Timed out while waiting 30s for py_utils.WaitFor(lambda: self.oobe_exists, 30). It looks like that on iteration 7 the attempt to restart chrome never succeeded and chrome did not start, but there are no Chrome crash dumps: https://stainless.corp.google.com/browse/chromeos-autotest-results/261552005-chromeos-test/ 11/27 03:30:35.097 INFO |cros_browser_backe:0067| Restarting Chrome (pid=28264) with remote port 11/27 03:30:35.098 INFO |cros_browser_backe:0075| dbus-send --system --type=method_call --dest=org.chromium.SessionManager /org/chromium/SessionManager org.chromium.SessionManagerInterface.EnableChromeTesting boolean:true array:string:"--disable-arc-opt-in-verification,--enable-net-benchmarking,--metrics-recording-only,--no-default-browser-check,--no-first-run,--ignore-background-tasks,--enable-gpu-benchmarking,--deny-permission-prompts,--autoplay-policy=no-user-gesture-required,--disable-component-extensions-with-background-pages,--disable-default-apps,--disable-search-geolocation-disclosure,--load-extension=/tmp/extension_fRZFP/autotest_private_ext,--enable-smooth-scrolling,--enable-threaded-compositing,--remote-debugging-port=0,--start-maximized,--ash-disable-system-sounds,--mute-audio,--oobe-skip-postlogin,--disable-logging-redirect,--vmodule=*/chromeos/net/*=2\,*/chromeos/login/*=2\,chrome_browser_main_posix=2,--allow-failed-policy-fetch-for-test,--disable-gaia-services" array:string: 11/27 03:30:35.098 DEBUG| cros_interface:0058| sh -c dbus-send --system --type=method_call --dest=org.chromium.SessionManager /org/chromium/SessionManager org.chromium.SessionManagerInterface.EnableChromeTesting boolean:true array:string:"--disable-arc-opt-in-verification,--enable-net-benchmarking,--metrics-recording-only,--no-default-browser-check,--no-first-run,--ignore-background-tasks,--enable-gpu-benchmarking,--deny-permission-prompts,--autoplay-policy=no-user-gesture-required,--disable-component-extensions-with-background-pages,--disable-default-apps,--disable-search-geolocation-disclosure,--load-extension=/tmp/extension_fRZFP/autotest_private_ext,--enable-smooth-scrolling,--enable-threaded-compositing,--remote-debugging-port=0,--start-maximized,--ash-disable-system-sounds,--mute-audio,--oobe-skip-postlogin,--disable-logging-redirect,--vmodule=*/chromeos/net/*=2\,*/chromeos/login/*=2\,chrome_browser_main_posix=2,--allow-failed-policy-fetch-for-test,--disable-gaia-services" array:string: 11/27 03:30:35.098 DEBUG| global_hooks:0056| ['sh', '-c', 'dbus-send --system --type=method_call --dest=org.chromium.SessionManager /org/chromium/SessionManager org.chromium.SessionManagerInterface.EnableChromeTesting boolean:true array:string:"--disable-arc-opt-in-verification,--enable-net-benchmarking,--metrics-recording-only,--no-default-browser-check,--no-first-run,--ignore-background-tasks,--enable-gpu-benchmarking,--deny-permission-prompts,--autoplay-policy=no-user-gesture-required,--disable-component-extensions-with-background-pages,--disable-default-apps,--disable-search-geolocation-disclosure,--load-extension=/tmp/extension_fRZFP/autotest_private_ext,--enable-smooth-scrolling,--enable-threaded-compositing,--remote-debugging-port=0,--start-maximized,--ash-disable-system-sounds,--mute-audio,--oobe-skip-postlogin,--disable-logging-redirect,--vmodule=*/chromeos/net/*=2\\,*/chromeos/login/*=2\\,chrome_browser_main_posix=2,--allow-failed-policy-fetch-for-test,--disable-gaia-services" array:string:'] 11/27 03:30:35.106 DEBUG| cros_interface:0067| > stdout=[], stderr=[] 11/27 03:30:35.107 DEBUG| global_hooks:0056| ['sh', '-c', '/bin/ps --no-headers -A -o pid,ppid,args:4096,state'] 11/27 03:30:35.130 DEBUG| cros_interface:0378| ListProcesses(<predicate>)->[163 processes] 11/27 03:30:35.132 DEBUG| cros_interface:0320| GetFile(/home/chronos/DevToolsActivePort, /tmp/tmpPMudNT) 11/27 03:30:35.133 DEBUG| cros_interface:0320| GetFile(/home/chronos/DevToolsActivePort, /tmp/tmp2snhDH) 11/27 03:30:35.233 DEBUG| cros_interface:0320| GetFile(/home/chronos/DevToolsActivePort, /tmp/tmpZ21MB6) 11/27 03:30:35.334 DEBUG| cros_interface:0320| GetFile(/home/chronos/DevToolsActivePort, /tmp/tmpV7bVCt) 11/27 03:30:35.436 DEBUG| cros_interface:0320| GetFile(/home/chronos/DevToolsActivePort, /tmp/tmpgrjkBQ) 11/27 03:30:35.537 DEBUG| cros_interface:0320| GetFile(/home/chronos/DevToolsActivePort, /tmp/tmpUkRX_s) 11/27 03:30:35.638 DEBUG| cros_interface:0320| GetFile(/home/chronos/DevToolsActivePort, /tmp/tmp0F1r8e) 11/27 03:30:35.739 DEBUG| cros_interface:0320| GetFile(/home/chronos/DevToolsActivePort, /tmp/tmph6Du6f) 11/27 03:30:35.840 DEBUG| cros_interface:0320| GetFile(/home/chronos/DevToolsActivePort, /tmp/tmp8VuQM5) 11/27 03:30:35.941 DEBUG| cros_interface:0320| GetFile(/home/chronos/DevToolsActivePort, /tmp/tmpwjbJ1h) 11/27 03:30:36.043 DEBUG| cros_interface:0320| GetFile(/home/chronos/DevToolsActivePort, /tmp/tmpWcjepb) 11/27 03:30:36.144 DEBUG| cros_interface:0320| GetFile(/home/chronos/DevToolsActivePort, /tmp/tmpqmKF6n) 11/27 03:30:36.246 DEBUG| cros_interface:0320| GetFile(/home/chronos/DevToolsActivePort, /tmp/tmpWfjqpT) 11/27 03:30:36.358 DEBUG| cros_interface:0320| GetFile(/home/chronos/DevToolsActivePort, /tmp/tmpEFLQv5) 11/27 03:30:36.482 DEBUG| cros_interface:0320| GetFile(/home/chronos/DevToolsActivePort, /tmp/tmpPMYpJE) 11/27 03:30:36.483 DEBUG| cros_interface:0350| GetFileContents(/home/chronos/DevToolsActivePort)->40078 /devtools/browser/60cff937-9f92-41b1-befb-ffebf76475a5 11/27 03:30:36.483 INFO | __init__:0047| DoNothingForwarder started between 127.0.0.1:40078 and 40078 11/27 03:31:10.138 ERROR| browser:0054| Failed with TimeoutException while starting the browser backend. 11/27 03:31:10.145 INFO | browser:0348| *************** BROWSER STANDARD OUTPUT *************** 11/27 03:31:10.146 INFO | browser:0350| Cannot get standard output on CrOS 11/27 03:31:10.146 INFO | browser:0353| *********** END OF BROWSER STANDARD OUTPUT ************ 11/27 03:31:10.147 INFO | browser:0355| ********************* BROWSER LOG ********************* 11/27 03:31:10.147 INFO | browser:0357| No log file 11/27 03:31:10.147 INFO | browser:0360| ***************** END OF BROWSER LOG ******************
,
Nov 27
It looks like both times this specific test ran on the same host chromeos4-row8-rack4-host6, but I don't see any specific signs of system level issues on this host in the logs.
,
Jan 15
This has happened again here: https://stainless.corp.google.com/browse/chromeos-autotest-results/277164581-chromeos-test/ https://cros-goldeneye.corp.google.com/chromeos/healthmonitoring/buildDetails?buildbucketId=8924267766970539520 and it ruined a CQ run.
,
Jan 15
Assigning to current ARC constable.
,
Jan 15
|
||||
►
Sign in to add a comment |
||||
Comment 1 by dlaurie@google.com
, Nov 27