cheets_WindowManagerTest timed out on bob-paladin |
|||||
Issue descriptionhttps://luci-milo.appspot.com/buildbot/chromeos/bob-paladin/1958 01/03 09:30:29.180 INFO | server_job:0218| START ---- ---- timestamp=1515000628 localtime=Jan 03 09:30:28 01/03 09:30:29.181 INFO | server_job:1401| Parsing lines in fast mode 01/03 09:30:29.182 ERROR| logging_manager:0626| tko parser: parsing partial test None CLIENT_JOB.0 01/03 09:30:29.183 ERROR| logging_manager:0626| tko parser: RUNNING: RUNNING 01/03 09:30:29.184 ERROR| logging_manager:0626| CLIENT_JOB.0 01/03 09:30:29.184 ERROR| logging_manager:0626| 01/03 09:30:29.303 DEBUG| autotest:1310| AUTOTEST_STATUS:: START cheets_WindowManagerTest cheets_WindowManagerTest timestamp=1515000629 localtime=Jan 03 09:30:29 01/03 09:30:29.304 INFO | server_job:0218| START cheets_WindowManagerTest cheets_WindowManagerTest timestamp=1515000629 localtime=Jan 03 09:30:29 01/03 09:30:29.304 INFO | server_job:1401| Parsing lines in fast mode 01/03 09:30:29.305 ERROR| logging_manager:0626| tko parser: parsing partial test cheets_WindowManagerTest cheets_WindowManagerTest 01/03 09:30:29.305 ERROR| logging_manager:0626| tko parser: RUNNING: RUNNING 01/03 09:30:29.306 ERROR| logging_manager:0626| Subdir: cheets_WindowManagerTest 01/03 09:30:29.307 ERROR| logging_manager:0626| Testname: cheets_WindowManagerTest 01/03 09:30:29.307 ERROR| logging_manager:0626| 01/03 10:47:15.750 INFO | autoserv:0685| Results placed in /usr/local/autotest/results/167265307-chromeos-test/chromeos6-row4-rack13-host1 01/03 10:47:15.751 DEBUG| autoserv:0693| autoserv is running in drone chromeos-server97.mtv.corp.google.com. 01/03 10:47:15.751 DEBUG| autoserv:0694| autoserv command was: /usr/local/autotest/server/autoserv -p --pidfile-label=collect_crashinfo --use-existing-results --collect-crashinfo -m chromeos6-row4-rack13-host1 -r /usr/local/autotest/results/167265307-chromeos-test/chromeos6-row4-rack13-host1 01/03 10:47:15.752 INFO | pidfile:0016| Logged pid 167303 to /usr/local/autotest/results/167265307-chromeos-test/chromeos6-row4-rack13-host1/.collect_crashinfo_execute 01/03 10:47:15.755 DEBUG| autoserv:0383| faulthandler registered on SIGTERM. Took an hour to start autoserv?
,
Jan 4 2018
According to suite detail: https://viceroy.corp.google.com/chromeos/suite_details?job_id=167265098 It seems like DUT is crashed while testing cheets_WindowManagerTest, so timeout occurs after about 1 hour for autoserv. FYI, it didn't recur in the later builds https://uberchromegw.corp.google.com/i/chromeos/builders/bob-paladin
,
Jan 4 2018
This looks like a product bug: Either the test is flaky, or the underlying feature under test is. Passing to the ARC++ constable for further evaluation.
,
Jan 4 2018
We might want to add some timeouts to the intermediate adb commands (cc-ing ricardoq@ for that), but the test itself is working well. A system_server crash is the root cause:
01-03 09:37:24.821 123 136 I ActivityManager: START u0 {flg=0x10008000 cmp=org.chromium.arc.testapp.windowmanager24/org.chromium.arc.testapp.windowmanager.MainActivity (has extras)} from uid 10052 on display 0
01-03 09:37:24.825 123 136 I TaskWindowPlacerArc: Topmost activity added to task with orientation: SCREEN_ORIENTATION_SENSOR
01-03 09:37:24.830 123 145 D DecorView[windowmanager24]: Cannot get the activity to get CaptionConfiguration.
01-03 09:37:24.835 123 1056 I TaskWindowPlacerArc: Root activity removed from task: ActivityRecord{aa26e29 u0 org.chromium.arc.testapp.windowmanager24/org.chromium.arc.testapp.windowmanager.MainPortraitActivity t3 f}
01-03 09:37:24.836 123 138 E AndroidRuntime: *** FATAL EXCEPTION IN SYSTEM PROCESS: ActivityManager
01-03 09:37:24.836 123 138 E AndroidRuntime: java.lang.IndexOutOfBoundsException: Index: 2, Size: 2
01-03 09:37:24.836 123 138 E AndroidRuntime: at java.util.ArrayList.get(ArrayList.java:411)
01-03 09:37:24.836 123 138 E AndroidRuntime: at com.android.server.am.TaskRecord.getTopActivity(TaskRecord.java:705)
01-03 09:37:24.836 123 138 E AndroidRuntime: at com.android.server.am.TaskWindowStateArc.getTopActivity(TaskWindowStateArc.java:657)
01-03 09:37:24.836 123 138 E AndroidRuntime: at com.android.server.am.TaskWindowPlacerArc$MainHandler.handleMessage(TaskWindowPlacerArc.java:389)
01-03 09:37:24.836 123 138 E AndroidRuntime: at android.os.Handler.dispatchMessage(Handler.java:102)
01-03 09:37:24.836 123 138 E AndroidRuntime: at android.os.Looper.loop(Looper.java:154)
01-03 09:37:24.836 123 138 E AndroidRuntime: at android.os.HandlerThread.run(HandlerThread.java:61)
01-03 09:37:24.836 123 138 E AndroidRuntime: at com.android.server.ServiceThread.run(ServiceThread.java:46)
01-03 09:37:24.837 123 469 E ActivityManager: applyOptionsLocked: Unknown animationType=0
I'll keep looking.
,
Jan 5 2018
Figured out the root cause, and tracking in https://b.corp.google.com/issues/71602109. This is going to take some time to fix :( Ricardo: given that the cheets_WindowManager test does exercise the racy code significantly more than regular tests, do you think there is a way to detect these kinds of crashes while the test is running? Otherwise we might need to temporarily disable this test until it's fixed.
,
Jan 5 2018
@luis: (I don't know the answer, not sure what's possible from python/autotests) do you know if there is any other test that detects crash? If so, I can do the same.
,
Feb 1 2018
Marking it as fixed since https://b.corp.google.com/issues/71602109 was fixed.
,
Feb 1 2018
|
|||||
►
Sign in to add a comment |
|||||
Comment 1 by frankhu@chromium.org
, Jan 3 2018