1 03-26 16:21:42 I/ModuleRepo: chromeos2-row8-rack4-host19:22 running 1 modules, expected to complete in 6m 38s 2 03-26 16:21:42 I/CompatibilityTest: Starting 1 module on chromeos2-row8-rack4-host19:22 3 03-26 16:21:42 D/ConfigurationFactory: Loading configuration 'system-status-checkers' 4 03-26 16:21:42 D/ModuleDef: Preparer: LocationCheck 5 03-26 16:21:42 I/PreconditionPreparer: Value true for option skip-media-download not applicable for class com.android.compatibility.common.tradefed.targetprep.LocationCheck 6 03-26 16:21:42 I/PreconditionPreparer: Value true for option skip-media-download not applicable for class com.android.compatibility.common.tradefed.targetprep.LocationCheck 7 03-26 16:21:43 I/CompatibilityTest: Running system status checker before module execution: CtsAppTestCases 8 03-26 16:21:43 D/ModuleDef: Preparer: ApkInstaller 9 03-26 16:21:43 D/TestAppInstallSetup: Installing apk from /tmp/autotest-tradefed-install_e5FdR6/8d01e7b907e6e63a12b197e2ce3529c0/android-cts-7.1_r3-linux_x86-x86/android-cts/tools/../../android-cts/testcases/CtsSimpleApp.apk ... 10 03-26 16:21:43 D/CtsSimpleApp.apk: Uploading CtsSimpleApp.apk onto device 'chromeos2-row8-rack4-host19:22' 11 03-26 16:21:43 D/Device: Uploading file onto device 'chromeos2-row8-rack4-host19:22' 12 03-26 16:21:44 D/RunUtil: Running command with timeout: 60000ms 13 03-26 16:21:44 D/RunUtil: Running [aapt, dump, badging, /tmp/autotest-tradefed-install_e5FdR6/8d01e7b907e6e63a12b197e2ce3529c0/android-cts-7.1_r3-linux_x86-x86/android-cts/tools/../../android-cts/testcases/CtsSimpleApp.apk] 14 03-26 16:21:44 D/TestAppInstallSetup: Installing apk from /tmp/autotest-tradefed-install_e5FdR6/8d01e7b907e6e63a12b197e2ce3529c0/android-cts-7.1_r3-linux_x86-x86/android-cts/tools/../../android-cts/testcases/CtsAppTestStubs.apk ... 15 03-26 16:21:44 D/CtsAppTestStubs.apk: Uploading CtsAppTestStubs.apk onto device 'chromeos2-row8-rack4-host19:22' 16 03-26 16:21:44 D/Device: Uploading file onto device 'chromeos2-row8-rack4-host19:22' 17 03-26 16:21:47 D/RunUtil: Running command with timeout: 60000ms 18 03-26 16:21:47 D/RunUtil: Running [aapt, dump, badging, /tmp/autotest-tradefed-install_e5FdR6/8d01e7b907e6e63a12b197e2ce3529c0/android-cts-7.1_r3-linux_x86-x86/android-cts/tools/../../android-cts/testcases/CtsAppTestStubs.apk] 19 03-26 16:21:47 D/TestAppInstallSetup: Installing apk from /tmp/autotest-tradefed-install_e5FdR6/8d01e7b907e6e63a12b197e2ce3529c0/android-cts-7.1_r3-linux_x86-x86/android-cts/tools/../../android-cts/testcases/CtsAppTestCases.apk ... 20 03-26 16:21:47 D/CtsAppTestCases.apk: Uploading CtsAppTestCases.apk onto device 'chromeos2-row8-rack4-host19:22' 21 03-26 16:21:47 D/Device: Uploading file onto device 'chromeos2-row8-rack4-host19:22' 22 03-26 16:21:49 D/RunUtil: Running command with timeout: 60000ms 23 03-26 16:21:49 D/RunUtil: Running [aapt, dump, badging, /tmp/autotest-tradefed-install_e5FdR6/8d01e7b907e6e63a12b197e2ce3529c0/android-cts-7.1_r3-linux_x86-x86/android-cts/tools/../../android-cts/testcases/CtsAppTestCases.apk] 24 03-26 16:21:49 D/ModuleDef: Test: AndroidJUnitTest 25 03-26 16:21:49 D/InstrumentationTest: Collecting test info for android.app.cts on device chromeos2-row8-rack4-host19:22 26 03-26 16:21:49 I/RemoteAndroidTest: Running am instrument -w -r --abi x86 -e testFile /data/local/tmp/ajur/includes.txt -e debug false -e log true -e timeout_msec 300000 android.app.cts/android.support.test.runner.AndroidJUnitRunner on google-chromebook_11_model_3180-chromeos2-row8-rack4-host19:22 27 03-26 16:21:50 I/RemoteAndroidTest: Running am instrument -w -r --abi x86 -e testFile /data/local/tmp/ajur/includes.txt -e debug false -e log false -e timeout_msec 300000 android.app.cts/android.support.test.runner.AndroidJUnitRunner on google-chromebook_11_model_3180-chromeos2-row8-rack4-host19:22 28 03-26 16:21:51 D/ModuleListener: ModuleListener.testRunStarted(android.app.cts, 5) 29 03-26 16:21:51 I/ConsoleReporter: [chromeos2-row8-rack4-host19:22] Starting x86 CtsAppTestCases with 5 tests 30 03-26 16:21:51 D/ModuleListener: ModuleListener.testStarted(android.app.cts.LifecycleTest#testTabBasic) 31 03-26 16:21:51 D/ModuleListener: ModuleListener.testEnded(android.app.cts.LifecycleTest#testTabBasic, {}) 32 03-26 16:21:51 I/ConsoleReporter: [1/5 x86 CtsAppTestCases chromeos2-row8-rack4-host19:22] android.app.cts.LifecycleTest#testTabBasic pass 33 03-26 16:21:51 D/ModuleListener: ModuleListener.testStarted(android.app.cts.SystemFeaturesTest#testCameraFeatures) 34 03-26 16:21:51 D/ModuleListener: ModuleListener.testFailed(android.app.cts.SystemFeaturesTest#testCameraFeatures, junit.framework.AssertionFailedError: PackageManager#hasSystemFeature should NOT return true for android.hardware.camera.front 35 at junit.framework.Assert.fail(Assert.java:50) 36 at junit.framework.Assert.assertTrue(Assert.java:20) 37 at junit.framework.Assert.assertFalse(Assert.java:34) 38 at android.app.cts.SystemFeaturesTest.assertNotAvailable(SystemFeaturesTest.java:508) 39 at android.app.cts.SystemFeaturesTest.testCameraFeatures(SystemFeaturesTest.java:122) 40 at java.lang.reflect.Method.invoke(Native Method) 41 at android.test.InstrumentationTestCase.runMethod(InstrumentationTestCase.java:220) 42 at android.test.InstrumentationTestCase.runTest(InstrumentationTestCase.java:205) 43 at junit.framework.TestCase.runBare(TestCase.java:134) 44 at junit.framework.TestResult$1.protect(TestResult.java:115) 45 at android.support.test.internal.runner.junit3.AndroidTestResult.runProtected(AndroidTestResult.java:77) 46 at junit.framework.TestResult.run(TestResult.java:118) 47 at android.support.test.internal.runner.junit3.AndroidTestResult.run(AndroidTestResult.java:55) 48 at junit.framework.TestCase.run(TestCase.java:124) 49 at android.support.test.internal.runner.junit3.NonLeakyTestSuite$NonLeakyTest.run(NonLeakyTestSuite.java:63) 50 at android.support.test.internal.runner.junit3.AndroidTestSuite$1.run(AndroidTestSuite.java:97) 51 at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:428) 52 at java.util.concurrent.FutureTask.run(FutureTask.java:237) 53 at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1133) 54 at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:607) 55 at java.lang.Thread.run(Thread.java:761) 56 ) 57 03-26 16:21:51 I/ConsoleReporter: [2/5 x86 CtsAppTestCases chromeos2-row8-rack4-host19:22] android.app.cts.SystemFeaturesTest#testCameraFeatures fail: junit.framework.AssertionFailedError: PackageManager#hasSystemFeature should NOT return true for android.hardware.camera.front 58 at junit.framework.Assert.fail(Assert.java:50) 59 at junit.framework.Assert.assertTrue(Assert.java:20) 60 at junit.framework.Assert.assertFalse(Assert.java:34) 61 at android.app.cts.SystemFeaturesTest.assertNotAvailable(SystemFeaturesTest.java:508) 62 at android.app.cts.SystemFeaturesTest.testCameraFeatures(SystemFeaturesTest.java:122) 63 at java.lang.reflect.Method.invoke(Native Method) 64 at android.test.InstrumentationTestCase.runMethod(InstrumentationTestCase.java:220) 65 at android.test.InstrumentationTestCase.runTest(InstrumentationTestCase.java:205) 66 at junit.framework.TestCase.runBare(TestCase.java:134) 67 at junit.framework.TestResult$1.protect(TestResult.java:115) 68 at android.support.test.internal.runner.junit3.AndroidTestResult.runProtected(AndroidTestResult.java:77) 69 at junit.framework.TestResult.run(TestResult.java:118) 70 at android.support.test.internal.runner.junit3.AndroidTestResult.run(AndroidTestResult.java:55) 71 at junit.framework.TestCase.run(TestCase.java:124) 72 at android.support.test.internal.runner.junit3.NonLeakyTestSuite$NonLeakyTest.run(NonLeakyTestSuite.java:63) 73 at android.support.test.internal.runner.junit3.AndroidTestSuite$1.run(AndroidTestSuite.java:97) 74 at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:428) 75 at java.util.concurrent.FutureTask.run(FutureTask.java:237) 76 at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1133) 77 at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:607) 78 at java.lang.Thread.run(Thread.java:761) 79 80 03-26 16:21:51 I/FailureListener: FailureListener.testFailed android.app.cts.SystemFeaturesTest#testCameraFeatures false true false 81 03-26 16:21:53 D/FileUtil: Creating temp file at /tmp/autotest-tradefed-install_e5FdR6/8d01e7b907e6e63a12b197e2ce3529c0/android-cts-7.1_r3-linux_x86-x86/android-cts/tools/../../android-cts/logs/2017.03.26_16.21.05 with prefix "android.app.cts.SystemFeaturesTest#testCameraFeatures-logcat_" suffix ".zip" 82 03-26 16:21:53 I/LogFileSaver: Saved log file /tmp/autotest-tradefed-install_e5FdR6/8d01e7b907e6e63a12b197e2ce3529c0/android-cts-7.1_r3-linux_x86-x86/android-cts/tools/../../android-cts/logs/2017.03.26_16.21.05/android.app.cts.SystemFeaturesTest#testCameraFeatures-logcat_8762735968762869316.zip 83 03-26 16:21:53 I/ResultReporter: Saved logs for android.app.cts.SystemFeaturesTest#testCameraFeatures-logcat in /tmp/autotest-tradefed-install_e5FdR6/8d01e7b907e6e63a12b197e2ce3529c0/android-cts-7.1_r3-linux_x86-x86/android-cts/tools/../../android-cts/logs/2017.03.26_16.21.05/android.app.cts.SystemFeaturesTest#testCameraFeatures-logcat_8762735968762869316.zip 84 03-26 16:21:53 D/FileUtil: Creating temp file at /tmp/3764431/cts/inv_4137984894364964943 with prefix "android.app.cts.SystemFeaturesTest#testCameraFeatures-logcat_" suffix ".zip" 85 03-26 16:21:53 D/RunUtil: Running command with timeout: 10000ms 86 03-26 16:21:53 D/RunUtil: Running [chmod] 87 03-26 16:21:53 D/RunUtil: [chmod] command failed. return code 1 88 03-26 16:21:53 D/FileUtil: Attempting to chmod /tmp/3764431/cts/inv_4137984894364964943/android.app.cts.SystemFeaturesTest#testCameraFeatures-logcat_6209762295598580632.zip to ug+rwx 89 03-26 16:21:53 D/RunUtil: Running command with timeout: 10000ms 90 03-26 16:21:53 D/RunUtil: Running [chmod, ug+rwx, /tmp/3764431/cts/inv_4137984894364964943/android.app.cts.SystemFeaturesTest#testCameraFeatures-logcat_6209762295598580632.zip] 91 03-26 16:21:54 I/FileSystemLogSaver: Saved log file /tmp/3764431/cts/inv_4137984894364964943/android.app.cts.SystemFeaturesTest#testCameraFeatures-logcat_6209762295598580632.zip 92 03-26 16:21:54 D/ModuleListener: ModuleListener.testEnded(android.app.cts.SystemFeaturesTest#testCameraFeatures, {}) 93 03-26 16:21:54 D/ModuleListener: ModuleListener.testStarted(android.app.cts.SystemFeaturesTest#testUsbAccessory) 94 03-26 16:21:54 D/ModuleListener: ModuleListener.testFailed(android.app.cts.SystemFeaturesTest#testUsbAccessory, junit.framework.AssertionFailedError: PackageManager#hasSystemFeature should return true for android.hardware.usb.accessory 95 at junit.framework.Assert.fail(Assert.java:50) 96 at junit.framework.Assert.assertTrue(Assert.java:20) 97 at android.app.cts.SystemFeaturesTest.assertAvailable(SystemFeaturesTest.java:501) 98 at android.app.cts.SystemFeaturesTest.testUsbAccessory(SystemFeaturesTest.java:481) 99 at java.lang.reflect.Method.invoke(Native Method) 100 at android.test.InstrumentationTestCase.runMethod(InstrumentationTestCase.java:220) 101 at android.test.InstrumentationTestCase.runTest(InstrumentationTestCase.java:205) 102 at junit.framework.TestCase.runBare(TestCase.java:134) 103 at junit.framework.TestResult$1.protect(TestResult.java:115) 104 at android.support.test.internal.runner.junit3.AndroidTestResult.runProtected(AndroidTestResult.java:77) 105 at junit.framework.TestResult.run(TestResult.java:118) 106 at android.support.test.internal.runner.junit3.AndroidTestResult.run(AndroidTestResult.java:55) 107 at junit.framework.TestCase.run(TestCase.java:124) 108 at android.support.test.internal.runner.junit3.NonLeakyTestSuite$NonLeakyTest.run(NonLeakyTestSuite.java:63) 109 at android.support.test.internal.runner.junit3.AndroidTestSuite$1.run(AndroidTestSuite.java:97) 110 at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:428) 111 at java.util.concurrent.FutureTask.run(FutureTask.java:237) 112 at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1133) 113 at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:607) 114 at java.lang.Thread.run(Thread.java:761) 115 ) 116 03-26 16:21:54 I/ConsoleReporter: [3/5 x86 CtsAppTestCases chromeos2-row8-rack4-host19:22] android.app.cts.SystemFeaturesTest#testUsbAccessory fail: junit.framework.AssertionFailedError: PackageManager#hasSystemFeature should return true for android.hardware.usb.accessory 117 at junit.framework.Assert.fail(Assert.java:50) 118 at junit.framework.Assert.assertTrue(Assert.java:20) 119 at android.app.cts.SystemFeaturesTest.assertAvailable(SystemFeaturesTest.java:501) 120 at android.app.cts.SystemFeaturesTest.testUsbAccessory(SystemFeaturesTest.java:481) 121 at java.lang.reflect.Method.invoke(Native Method) 122 at android.test.InstrumentationTestCase.runMethod(InstrumentationTestCase.java:220) 123 at android.test.InstrumentationTestCase.runTest(InstrumentationTestCase.java:205) 124 at junit.framework.TestCase.runBare(TestCase.java:134) 125 at junit.framework.TestResult$1.protect(TestResult.java:115) 126 at android.support.test.internal.runner.junit3.AndroidTestResult.runProtected(AndroidTestResult.java:77) 127 at junit.framework.TestResult.run(TestResult.java:118) 128 at android.support.test.internal.runner.junit3.AndroidTestResult.run(AndroidTestResult.java:55) 129 at junit.framework.TestCase.run(TestCase.java:124) 130 at android.support.test.internal.runner.junit3.NonLeakyTestSuite$NonLeakyTest.run(NonLeakyTestSuite.java:63) 131 at android.support.test.internal.runner.junit3.AndroidTestSuite$1.run(AndroidTestSuite.java:97) 132 at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:428) 133 at java.util.concurrent.FutureTask.run(FutureTask.java:237) 134 at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1133) 135 at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:607) 136 at java.lang.Thread.run(Thread.java:761) 137 138 03-26 16:21:54 I/FailureListener: FailureListener.testFailed android.app.cts.SystemFeaturesTest#testUsbAccessory false true false 139 03-26 16:21:56 D/FileUtil: Creating temp file at /tmp/autotest-tradefed-install_e5FdR6/8d01e7b907e6e63a12b197e2ce3529c0/android-cts-7.1_r3-linux_x86-x86/android-cts/tools/../../android-cts/logs/2017.03.26_16.21.05 with prefix "android.app.cts.SystemFeaturesTest#testUsbAccessory-logcat_" suffix ".zip" 140 03-26 16:21:56 I/LogFileSaver: Saved log file /tmp/autotest-tradefed-install_e5FdR6/8d01e7b907e6e63a12b197e2ce3529c0/android-cts-7.1_r3-linux_x86-x86/android-cts/tools/../../android-cts/logs/2017.03.26_16.21.05/android.app.cts.SystemFeaturesTest#testUsbAccessory-logcat_8167304123051235943.zip 141 03-26 16:21:56 I/ResultReporter: Saved logs for android.app.cts.SystemFeaturesTest#testUsbAccessory-logcat in /tmp/autotest-tradefed-install_e5FdR6/8d01e7b907e6e63a12b197e2ce3529c0/android-cts-7.1_r3-linux_x86-x86/android-cts/tools/../../android-cts/logs/2017.03.26_16.21.05/android.app.cts.SystemFeaturesTest#testUsbAccessory-logcat_8167304123051235943.zip 142 03-26 16:21:56 D/FileUtil: Creating temp file at /tmp/3764431/cts/inv_4137984894364964943 with prefix "android.app.cts.SystemFeaturesTest#testUsbAccessory-logcat_" suffix ".zip" 143 03-26 16:21:56 D/RunUtil: Running command with timeout: 10000ms 144 03-26 16:21:56 D/RunUtil: Running [chmod] 145 03-26 16:21:56 D/RunUtil: [chmod] command failed. return code 1 146 03-26 16:21:56 D/FileUtil: Attempting to chmod /tmp/3764431/cts/inv_4137984894364964943/android.app.cts.SystemFeaturesTest#testUsbAccessory-logcat_6713767646175932035.zip to ug+rwx 147 03-26 16:21:56 D/RunUtil: Running command with timeout: 10000ms 148 03-26 16:21:56 D/RunUtil: Running [chmod, ug+rwx, /tmp/3764431/cts/inv_4137984894364964943/android.app.cts.SystemFeaturesTest#testUsbAccessory-logcat_6713767646175932035.zip] 149 03-26 16:21:56 I/FileSystemLogSaver: Saved log file /tmp/3764431/cts/inv_4137984894364964943/android.app.cts.SystemFeaturesTest#testUsbAccessory-logcat_6713767646175932035.zip 150 03-26 16:21:56 D/ModuleListener: ModuleListener.testEnded(android.app.cts.SystemFeaturesTest#testUsbAccessory, {}) 151 03-26 16:21:56 D/ModuleListener: ModuleListener.testStarted(android.app.cts.ActivityManagerTest#testTimeTrackingAPI_ChainedActivityExit) 152 03-26 16:21:56 D/ModuleListener: ModuleListener.testFailed(android.app.cts.ActivityManagerTest#testTimeTrackingAPI_ChainedActivityExit, junit.framework.AssertionFailedError: expected:<3> but was:<1> 153 at junit.framework.Assert.fail(Assert.java:50) 154 at junit.framework.Assert.failNotEquals(Assert.java:287) 155 at junit.framework.Assert.assertEquals(Assert.java:67) 156 at junit.framework.Assert.assertEquals(Assert.java:199) 157 at junit.framework.Assert.assertEquals(Assert.java:205) 158 at android.app.cts.ActivityManagerTest.testTimeTrackingAPI_ChainedActivityExit(ActivityManagerTest.java:526) 159 at java.lang.reflect.Method.invoke(Native Method) 160 at android.test.InstrumentationTestCase.runMethod(InstrumentationTestCase.java:220) 161 at android.test.InstrumentationTestCase.runTest(InstrumentationTestCase.java:205) 162 at junit.framework.TestCase.runBare(TestCase.java:134) 163 at junit.framework.TestResult$1.protect(TestResult.java:115) 164 at android.support.test.internal.runner.junit3.AndroidTestResult.runProtected(AndroidTestResult.java:77) 165 at junit.framework.TestResult.run(TestResult.java:118) 166 at android.support.test.internal.runner.junit3.AndroidTestResult.run(AndroidTestResult.java:55) 167 at junit.framework.TestCase.run(TestCase.java:124) 168 at android.support.test.internal.runner.junit3.NonLeakyTestSuite$NonLeakyTest.run(NonLeakyTestSuite.java:63) 169 at android.support.test.internal.runner.junit3.AndroidTestSuite$1.run(AndroidTestSuite.java:97) 170 at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:428) 171 at java.util.concurrent.FutureTask.run(FutureTask.java:237) 172 at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1133) 173 at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:607) 174 at java.lang.Thread.run(Thread.java:761) 175 ) 176 03-26 16:21:56 I/ConsoleReporter: [4/5 x86 CtsAppTestCases chromeos2-row8-rack4-host19:22] android.app.cts.ActivityManagerTest#testTimeTrackingAPI_ChainedActivityExit fail: junit.framework.AssertionFailedError: expected:<3> but was:<1> 177 at junit.framework.Assert.fail(Assert.java:50) 178 at junit.framework.Assert.failNotEquals(Assert.java:287) 179 at junit.framework.Assert.assertEquals(Assert.java:67) 180 at junit.framework.Assert.assertEquals(Assert.java:199) 181 at junit.framework.Assert.assertEquals(Assert.java:205) 182 at android.app.cts.ActivityManagerTest.testTimeTrackingAPI_ChainedActivityExit(ActivityManagerTest.java:526) 183 at java.lang.reflect.Method.invoke(Native Method) 184 at android.test.InstrumentationTestCase.runMethod(InstrumentationTestCase.java:220) 185 at android.test.InstrumentationTestCase.runTest(InstrumentationTestCase.java:205) 186 at junit.framework.TestCase.runBare(TestCase.java:134) 187 at junit.framework.TestResult$1.protect(TestResult.java:115) 188 at android.support.test.internal.runner.junit3.AndroidTestResult.runProtected(AndroidTestResult.java:77) 189 at junit.framework.TestResult.run(TestResult.java:118) 190 at android.support.test.internal.runner.junit3.AndroidTestResult.run(AndroidTestResult.java:55) 191 at junit.framework.TestCase.run(TestCase.java:124) 192 at android.support.test.internal.runner.junit3.NonLeakyTestSuite$NonLeakyTest.run(NonLeakyTestSuite.java:63) 193 at android.support.test.internal.runner.junit3.AndroidTestSuite$1.run(AndroidTestSuite.java:97) 194 at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:428) 195 at java.util.concurrent.FutureTask.run(FutureTask.java:237) 196 at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1133) 197 at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:607) 198 at java.lang.Thread.run(Thread.java:761) 199 200 03-26 16:21:56 I/FailureListener: FailureListener.testFailed android.app.cts.ActivityManagerTest#testTimeTrackingAPI_ChainedActivityExit false true false 201 03-26 16:21:58 D/FileUtil: Creating temp file at /tmp/autotest-tradefed-install_e5FdR6/8d01e7b907e6e63a12b197e2ce3529c0/android-cts-7.1_r3-linux_x86-x86/android-cts/tools/../../android-cts/logs/2017.03.26_16.21.05 with prefix "android.app.cts.ActivityManagerTest#testTimeTrackingAPI_ChainedActivityExit-logcat_" suffix ".zip" 202 03-26 16:21:58 I/LogFileSaver: Saved log file /tmp/autotest-tradefed-install_e5FdR6/8d01e7b907e6e63a12b197e2ce3529c0/android-cts-7.1_r3-linux_x86-x86/android-cts/tools/../../android-cts/logs/2017.03.26_16.21.05/android.app.cts.ActivityManagerTest#testTimeTrackingAPI_ChainedActivityExit-logcat_6972528964795429366.zip 203 03-26 16:21:58 I/ResultReporter: Saved logs for android.app.cts.ActivityManagerTest#testTimeTrackingAPI_ChainedActivityExit-logcat in /tmp/autotest-tradefed-install_e5FdR6/8d01e7b907e6e63a12b197e2ce3529c0/android-cts-7.1_r3-linux_x86-x86/android-cts/tools/../../android-cts/logs/2017.03.26_16.21.05/android.app.cts.ActivityManagerTest#testTimeTrackingAPI_ChainedActivityExit-logcat_6972528964795429366.zip 204 03-26 16:21:58 D/FileUtil: Creating temp file at /tmp/3764431/cts/inv_4137984894364964943 with prefix "android.app.cts.ActivityManagerTest#testTimeTrackingAPI_ChainedActivityExit-logcat_" suffix ".zip" 205 03-26 16:21:58 D/RunUtil: Running command with timeout: 10000ms 206 03-26 16:21:58 D/RunUtil: Running [chmod] 207 03-26 16:21:58 D/RunUtil: [chmod] command failed. return code 1 208 03-26 16:21:58 D/FileUtil: Attempting to chmod /tmp/3764431/cts/inv_4137984894364964943/android.app.cts.ActivityManagerTest#testTimeTrackingAPI_ChainedActivityExit-logcat_969828295802539563.zip to ug+rwx 209 03-26 16:21:58 D/RunUtil: Running command with timeout: 10000ms 210 03-26 16:21:58 D/RunUtil: Running [chmod, ug+rwx, /tmp/3764431/cts/inv_4137984894364964943/android.app.cts.ActivityManagerTest#testTimeTrackingAPI_ChainedActivityExit-logcat_969828295802539563.zip] 211 03-26 16:21:58 I/FileSystemLogSaver: Saved log file /tmp/3764431/cts/inv_4137984894364964943/android.app.cts.ActivityManagerTest#testTimeTrackingAPI_ChainedActivityExit-logcat_969828295802539563.zip 212 03-26 16:21:58 D/ModuleListener: ModuleListener.testEnded(android.app.cts.ActivityManagerTest#testTimeTrackingAPI_ChainedActivityExit, {}) 213 03-26 16:21:58 D/ModuleListener: ModuleListener.testStarted(android.app.cts.ActivityManagerTest#testTimeTrackingAPI_SimpleStartExit) 214 03-26 16:21:58 D/ModuleListener: ModuleListener.testFailed(android.app.cts.ActivityManagerTest#testTimeTrackingAPI_SimpleStartExit, junit.framework.AssertionFailedError: expected:<3> but was:<1> 215 at junit.framework.Assert.fail(Assert.java:50) 216 at junit.framework.Assert.failNotEquals(Assert.java:287) 217 at junit.framework.Assert.assertEquals(Assert.java:67) 218 at junit.framework.Assert.assertEquals(Assert.java:199) 219 at junit.framework.Assert.assertEquals(Assert.java:205) 220 at android.app.cts.ActivityManagerTest.testTimeTrackingAPI_SimpleStartExit(ActivityManagerTest.java:431) 221 at java.lang.reflect.Method.invoke(Native Method) 222 at android.test.InstrumentationTestCase.runMethod(InstrumentationTestCase.java:220) 223 at android.test.InstrumentationTestCase.runTest(InstrumentationTestCase.java:205) 224 at junit.framework.TestCase.runBare(TestCase.java:134) 225 at junit.framework.TestResult$1.protect(TestResult.java:115) 226 at android.support.test.internal.runner.junit3.AndroidTestResult.runProtected(AndroidTestResult.java:77) 227 at junit.framework.TestResult.run(TestResult.java:118) 228 at android.support.test.internal.runner.junit3.AndroidTestResult.run(AndroidTestResult.java:55) 229 at junit.framework.TestCase.run(TestCase.java:124) 230 at android.support.test.internal.runner.junit3.NonLeakyTestSuite$NonLeakyTest.run(NonLeakyTestSuite.java:63) 231 at android.support.test.internal.runner.junit3.AndroidTestSuite$1.run(AndroidTestSuite.java:97) 232 at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:428) 233 at java.util.concurrent.FutureTask.run(FutureTask.java:237) 234 at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1133) 235 at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:607) 236 at java.lang.Thread.run(Thread.java:761) 237 ) 238 03-26 16:21:58 I/ConsoleReporter: [5/5 x86 CtsAppTestCases chromeos2-row8-rack4-host19:22] android.app.cts.ActivityManagerTest#testTimeTrackingAPI_SimpleStartExit fail: junit.framework.AssertionFailedError: expected:<3> but was:<1> 239 at junit.framework.Assert.fail(Assert.java:50) 240 at junit.framework.Assert.failNotEquals(Assert.java:287) 241 at junit.framework.Assert.assertEquals(Assert.java:67) 242 at junit.framework.Assert.assertEquals(Assert.java:199) 243 at junit.framework.Assert.assertEquals(Assert.java:205) 244 at android.app.cts.ActivityManagerTest.testTimeTrackingAPI_SimpleStartExit(ActivityManagerTest.java:431) 245 at java.lang.reflect.Method.invoke(Native Method) 246 at android.test.InstrumentationTestCase.runMethod(InstrumentationTestCase.java:220) 247 at android.test.InstrumentationTestCase.runTest(InstrumentationTestCase.java:205) 248 at junit.framework.TestCase.runBare(TestCase.java:134) 249 at junit.framework.TestResult$1.protect(TestResult.java:115) 250 at android.support.test.internal.runner.junit3.AndroidTestResult.runProtected(AndroidTestResult.java:77) 251 at junit.framework.TestResult.run(TestResult.java:118) 252 at android.support.test.internal.runner.junit3.AndroidTestResult.run(AndroidTestResult.java:55) 253 at junit.framework.TestCase.run(TestCase.java:124) 254 at android.support.test.internal.runner.junit3.NonLeakyTestSuite$NonLeakyTest.run(NonLeakyTestSuite.java:63) 255 at android.support.test.internal.runner.junit3.AndroidTestSuite$1.run(AndroidTestSuite.java:97) 256 at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:428) 257 at java.util.concurrent.FutureTask.run(FutureTask.java:237) 258 at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1133) 259 at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:607) 260 at java.lang.Thread.run(Thread.java:761) 261 262 03-26 16:21:58 I/FailureListener: FailureListener.testFailed android.app.cts.ActivityManagerTest#testTimeTrackingAPI_SimpleStartExit false true false 263 03-26 16:22:00 D/FileUtil: Creating temp file at /tmp/autotest-tradefed-install_e5FdR6/8d01e7b907e6e63a12b197e2ce3529c0/android-cts-7.1_r3-linux_x86-x86/android-cts/tools/../../android-cts/logs/2017.03.26_16.21.05 with prefix "android.app.cts.ActivityManagerTest#testTimeTrackingAPI_SimpleStartExit-logcat_" suffix ".zip" 264 03-26 16:22:00 I/LogFileSaver: Saved log file /tmp/autotest-tradefed-install_e5FdR6/8d01e7b907e6e63a12b197e2ce3529c0/android-cts-7.1_r3-linux_x86-x86/android-cts/tools/../../android-cts/logs/2017.03.26_16.21.05/android.app.cts.ActivityManagerTest#testTimeTrackingAPI_SimpleStartExit-logcat_6981612307094436497.zip 265 03-26 16:22:00 I/ResultReporter: Saved logs for android.app.cts.ActivityManagerTest#testTimeTrackingAPI_SimpleStartExit-logcat in /tmp/autotest-tradefed-install_e5FdR6/8d01e7b907e6e63a12b197e2ce3529c0/android-cts-7.1_r3-linux_x86-x86/android-cts/tools/../../android-cts/logs/2017.03.26_16.21.05/android.app.cts.ActivityManagerTest#testTimeTrackingAPI_SimpleStartExit-logcat_6981612307094436497.zip 266 03-26 16:22:00 D/FileUtil: Creating temp file at /tmp/3764431/cts/inv_4137984894364964943 with prefix "android.app.cts.ActivityManagerTest#testTimeTrackingAPI_SimpleStartExit-logcat_" suffix ".zip" 267 03-26 16:22:00 D/RunUtil: Running command with timeout: 10000ms 268 03-26 16:22:00 D/RunUtil: Running [chmod] 269 03-26 16:22:00 D/RunUtil: [chmod] command failed. return code 1 270 03-26 16:22:00 D/FileUtil: Attempting to chmod /tmp/3764431/cts/inv_4137984894364964943/android.app.cts.ActivityManagerTest#testTimeTrackingAPI_SimpleStartExit-logcat_4933333784378096516.zip to ug+rwx 271 03-26 16:22:00 D/RunUtil: Running command with timeout: 10000ms 272 03-26 16:22:00 D/RunUtil: Running [chmod, ug+rwx, /tmp/3764431/cts/inv_4137984894364964943/android.app.cts.ActivityManagerTest#testTimeTrackingAPI_SimpleStartExit-logcat_4933333784378096516.zip] 273 03-26 16:22:00 I/FileSystemLogSaver: Saved log file /tmp/3764431/cts/inv_4137984894364964943/android.app.cts.ActivityManagerTest#testTimeTrackingAPI_SimpleStartExit-logcat_4933333784378096516.zip 274 03-26 16:22:00 D/ModuleListener: ModuleListener.testEnded(android.app.cts.ActivityManagerTest#testTimeTrackingAPI_SimpleStartExit, {}) 275 03-26 16:22:00 D/ModuleListener: ModuleListener.testRunEnded(7317, {}) 276 03-26 16:22:00 I/ConsoleReporter: [chromeos2-row8-rack4-host19:22] x86 CtsAppTestCases completed in 7s. 1 passed, 4 failed, 0 not executed 277 03-26 16:22:00 D/ModuleDef: Cleaner: ApkInstaller 278 03-26 16:22:00 D/TestDevice: Uninstalling com.android.cts.launcherapps.simpleapp 279 03-26 16:22:01 D/TestDevice: Uninstalling android.app.stubs 280 03-26 16:22:03 D/TestDevice: Uninstalling android.app.cts 281 03-26 16:22:04 W/CompatibilityTest: Inaccurate runtime hint for x86 CtsAppTestCases, expected 6m 38s was 20s 282 03-26 16:22:04 I/CompatibilityTest: Running system status checker after module execution: CtsAppTestCases 283 03-26 16:22:05 I/MonitoringUtils: Connectivity: passed check. 284 03-26 16:22:05 D/RunUtil: run interrupt allowed: false 285 03-26 16:22:05 I/ResultReporter: Invocation finished in 1m 0s. PASSED: 317, FAILED: 4, NOT EXECUTED: 0, MODULES: 1 of 1 286 03-26 16:22:06 I/ResultReporter: Test Result: /tmp/autotest-tradefed-install_e5FdR6/8d01e7b907e6e63a12b197e2ce3529c0/android-cts-7.1_r3-linux_x86-x86/android-cts/results/2017.03.26_16.21.05/test_result_failures.html 287 03-26 16:22:06 I/ResultReporter: Full Result: /tmp/autotest-tradefed-install_e5FdR6/8d01e7b907e6e63a12b197e2ce3529c0/android-cts-7.1_r3-linux_x86-x86/android-cts/results/2017.03.26_16.21.05.zip 288