The following error is widely seen with trybot runs on chromeos-4.14.
01/19 20:53:40.169 WARNI| test:0637| The test failed with the following exception
Traceback (most recent call last):
File "/usr/local/autotest/client/common_lib/test.py", line 631, in _exec
_call_test_function(self.execute, *p_args, **p_dargs)
File "/usr/local/autotest/client/common_lib/test.py", line 831, in _call_test_function
return func(*args, **dargs)
File "/usr/local/autotest/client/common_lib/test.py", line 495, in execute
dargs)
File "/usr/local/autotest/client/common_lib/test.py", line 362, in _call_run_once_with_retry
postprocess_profiled_run, args, dargs)
File "/usr/local/autotest/client/common_lib/test.py", line 400, in _call_run_once
self.run_once(*args, **dargs)
File "/usr/local/autotest/server/site_tests/cheets_CTS_N/cheets_CTS_N.py", line 246, in run_once
precondition_commands)
File "/usr/local/autotest/server/cros/tradefed_test.py", line 1422, in _run_tradefed_with_retries
(steps, passed, failed, lnotexecuted, lwaived, self.summary))
TestFail: Failed: after 3 retries giving up. passed=5, failed=1, notexecuted=0, waived=0. run(t=6, p=5, f=1, ne=0, w=0) retry(t=1, p=0, f=1, ne=0, w=0) retry(t=1, p=0, f=1, ne=0, w=0) retry(t=1, p=0, f=1, ne=0, w=0)
Test log also shows:
01/19 20:51:28.919 DEBUG| utils:0282| [stdout] 01-19 20:51:28 I/StayAwakePreparer: Changing value for stay_on_while_plugged_in from 0 to 7
01/19 20:51:29.891 DEBUG| utils:0282| [stdout] 01-19 20:51:29 I/SettingsPreparer: Setting verifier_verify_adb_installs to value 0
01/19 20:51:30.699 DEBUG| utils:0282| [stdout] 01-19 20:51:30 I/ApkPreconditionCheck: Instrumenting package com.android.preconditions.cts:
01/19 20:51:53.040 DEBUG| utils:0282| [stdout] 01-19 20:51:53 I/ApkPreconditionCheck: Target preparation successful
01/19 20:52:05.003 DEBUG| utils:0282| [stdout] 01-19 20:52:05 I/WifiCheck: Wifi is connected
01/19 20:52:05.326 DEBUG| utils:0282| [stdout] 01-19 20:52:05 I/DeviceInfoCollector: Instrumenting package com.android.compatibility.common.deviceinfo:
01/19 20:52:15.993 DEBUG| utils:0282| [stdout] 01-19 20:52:15 I/DeviceInfoCollector: Target preparation successful
01/19 20:52:23.648 DEBUG| utils:0282| [stdout] 01-19 20:52:23 I/ModuleRepo: chromeos6-row3-rack10-host11:22 running 1 modules, expected to complete in 1m 0s
01/19 20:52:23.650 DEBUG| utils:0282| [stdout] 01-19 20:52:23 I/CompatibilityTest: Starting 1 module on chromeos6-row3-rack10-host11:22
01/19 20:52:27.921 DEBUG| utils:0282| [stdout] 01-19 20:52:27 I/ConsoleReporter: [chromeos6-row3-rack10-host11:22] Starting x86 CtsAccelerationTestCases with 1 test
01/19 20:52:29.191 DEBUG| utils:0282| [stdout] 01-19 20:52:29 I/ConsoleReporter: [1/1 x86 CtsAccelerationTestCases chromeos6-row3-rack10-host11:22] android.acceleration.cts.SoftwareAccelerationTest#testIsHardwareAccelerated fail: java.lang.IllegalStateException: View was not drawn...
01/19 20:52:29.191 DEBUG| utils:0282| [stdout] at android.acceleration.AcceleratedView.isCanvasHardwareAccelerated(AcceleratedView.java:61)
01/19 20:52:29.192 DEBUG| utils:0282| [stdout] at android.acceleration.cts.SoftwareAccelerationTest.testIsHardwareAccelerated(SoftwareAccelerationTest.java:45)
01/19 20:52:29.192 DEBUG| utils:0282| [stdout] at java.lang.reflect.Method.invoke(Native Method)
01/19 20:52:29.193 DEBUG| utils:0282| [stdout] at android.test.InstrumentationTestCase.runMethod(InstrumentationTestCase.java:220)
01/19 20:52:29.193 DEBUG| utils:0282| [stdout] at android.test.InstrumentationTestCase.runTest(InstrumentationTestCase.java:205)
01/19 20:52:29.193 DEBUG| utils:0282| [stdout] at android.test.ActivityInstrumentationTestCase2.runTest(ActivityInstrumentationTestCase2.java:192)
01/19 20:52:29.194 DEBUG| utils:0282| [stdout] at junit.framework.TestCase.runBare(TestCase.java:134)
01/19 20:52:29.194 DEBUG| utils:0282| [stdout] at junit.framework.TestResult$1.protect(TestResult.java:115)
01/19 20:52:29.195 DEBUG| utils:0282| [stdout] at android.support.test.internal.runner.junit3.AndroidTestResult.runProtected(AndroidTestResult.java:77)
01/19 20:52:29.195 DEBUG| utils:0282| [stdout] at junit.framework.TestResult.run(TestResult.java:118)
01/19 20:52:29.196 DEBUG| utils:0282| [stdout] at android.support.test.internal.runner.junit3.AndroidTestResult.run(AndroidTestResult.java:55)
01/19 20:52:29.196 DEBUG| utils:0282| [stdout] at junit.framework.TestCase.run(TestCase.java:124)
01/19 20:52:29.196 DEBUG| utils:0282| [stdout] at android.support.test.internal.runner.junit3.NonLeakyTestSuite$NonLeakyTest.run(NonLeakyTestSuite.java:63)
01/19 20:52:29.197 DEBUG| utils:0282| [stdout] at android.support.test.internal.runner.junit3.AndroidTestSuite$1.run(AndroidTestSuite.java:97)
01/19 20:52:29.197 DEBUG| utils:0282| [stdout] at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:428)
01/19 20:52:29.197 DEBUG| utils:0282| [stdout] at java.util.concurrent.FutureTask.run(FutureTask.java:237)
01/19 20:52:29.197 DEBUG| utils:0282| [stdout] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1133)
01/19 20:52:29.198 DEBUG| utils:0282| [stdout] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:607)
01/19 20:52:29.198 DEBUG| utils:0282| [stdout] at java.lang.Thread.run(Thread.java:761)
01/19 20:52:29.198 DEBUG| utils:0282| [stdout]
01/19 20:52:31.256 DEBUG| utils:0282| [stdout] 01-19 20:52:31 I/ResultReporter: Saved logs for android.acceleration.cts.SoftwareAccelerationTest#testIsHardwareAccelerated-logcat in /tmp/autotest-tradefed-install_RagjvG/08717dbd22c87b32c11dfa80dc6da859/android-cts-7.1_r13-linux_x86-x86/android-cts/tools/../../android-cts/logs/2018.01.19_20.51.26/android.acceleration.cts.SoftwareAccelerationTest#testIsHardwareAccelerated-logcat_3197318928417774199.zip
01/19 20:52:31.362 DEBUG| utils:0282| [stdout] 01-19 20:52:31 I/ConsoleReporter: [chromeos6-row3-rack10-host11:22] x86 CtsAccelerationTestCases completed in 1s. 0 passed, 1 failed, 0 not executed
01/19 20:52:32.049 DEBUG| utils:0282| [stdout] 01-19 20:52:32 W/CompatibilityTest: Inaccurate runtime hint for x86 CtsAccelerationTestCases, expected 1m 0s was 8s
01/19 20:52:33.231 DEBUG| utils:0282| [stdout] 01-19 20:52:33 I/ResultReporter: Saved logs for device_logcat in /tmp/autotest-tradefed-install_RagjvG/08717dbd22c87b32c11dfa80dc6da859/android-cts-7.1_r13-linux_x86-x86/android-cts/tools/../../android-cts/logs/2018.01.19_20.51.26/device_logcat_8491816341056715873.zip
01/19 20:52:33.313 DEBUG| utils:0282| [stdout] 01-19 20:52:33 I/ResultReporter: Saved logs for host_log in /tmp/autotest-tradefed-install_RagjvG/08717dbd22c87b32c11dfa80dc6da859/android-cts-7.1_r13-linux_x86-x86/android-cts/tools/../../android-cts/logs/2018.01.19_20.51.26/host_log_6613197957226887153.zip
01/19 20:52:33.360 DEBUG| utils:0282| [stdout] 01-19 20:52:33 I/ResultReporter: Invocation finished in 1m 6s. PASSED: 5, FAILED: 1, MODULES: 1 of 1
01/19 20:52:35.462 DEBUG| utils:0282| [stdout] 01-19 20:52:35 I/ResultReporter: Test Result: /tmp/autotest-tradefed-install_RagjvG/08717dbd22c87b32c11dfa80dc6da859/android-cts-7.1_r13-linux_x86-x86/android-cts/results/2018.01.19_20.51.26/test_result_failures.html
01/19 20:52:35.463 DEBUG| utils:0282| [stdout] 01-19 20:52:35 I/ResultReporter: Full Result: /tmp/autotest-tradefed-install_RagjvG/08717dbd22c87b32c11dfa80dc6da859/android-cts-7.1_r13-linux_x86-x86/android-cts/results/2018.01.19_20.51.26.zip
01/19 20:52:35.492 DEBUG| utils:0282| [stdout] Saved log to /tmp/tradefed_global_log_2972830186511105473.txt
01/19 20:52:35.493 DEBUG| utils:0282| [stdout] 01-19 20:52:35 I/CommandScheduler: All done
01/19 20:52:35.785 INFO | cheets_CTS_N:0146| END: ./cts-tradefed run commandAndExit cts --module CtsAccelerationTestCases --precondition-arg skip-media-download --disable-reboot --logcat-on-failure --retry 2
01/19 20:52:35.790 INFO | tradefed_test:1079| Tradefed identified results and logs with 2018.01.19_20.51.26.
01/19 20:52:35.790 INFO | tradefed_test:1104| Collecting tradefed testResult.xml and logs to /usr/local/autotest/results/171052648-chromeos-test/cheets_CTS_N.CtsAccelerationTestCases/results/android-cts.
01/19 20:52:35.853 INFO | tradefed_test:0453| tests=1, passed=0, failed=1, not_executed=0, waived=0
01/19 20:52:35.854 INFO | cheets_CTS_N:0135| RUN: ./cts-tradefed list results
Comment 1 by efoo@chromium.org
, Jul 6