Back to build
Raw log | Switch to full mode | Login

Set PYTHONPATH: /b/s/w/ir/kitchen-checkout/build/scripts:/b/s/w/ir/kitchen-checkout/build/site_config:/b/s/w/ir/kitchen-checkout/build/third_party:/b/s/w/ir/kitchen-checkout/build/third_party/buildbot_8_4p1:/b/s/w/ir/kitchen-checkout/build/third_party/buildbot_slave_8_4:/b/s/w/ir/kitchen-checkout/build/third_party/coverage-3.7.1:/b/s/w/ir/kitchen-checkout/build/third_party/decorator_3_3_1:/b/s/w/ir/kitchen-checkout/build/third_party/google_api_python_client:/b/s/w/ir/kitchen-checkout/build/third_party/httplib2/python2:/b/s/w/ir/kitchen-checkout/build/third_party/infra_libs:/b/s/w/ir/kitchen-checkout/build/third_party/jinja2:/b/s/w/ir/kitchen-checkout/build/third_party/markupsafe:/b/s/w/ir/kitchen-checkout/build/third_party/oauth2client:/b/s/w/ir/kitchen-checkout/build/third_party/pyasn1:/b/s/w/ir/kitchen-checkout/build/third_party/pyasn1-modules:/b/s/w/ir/kitchen-checkout/build/third_party/python-rsa:/b/s/w/ir/kitchen-checkout/build/third_party/requests_2_10_0:/b/s/w/ir/kitchen-checkout/build/third_party/setuptools-0.6c11:/b/s/w/ir/kitchen-checkout/build/third_party/sqlalchemy_0_7_1:/b/s/w/ir/kitchen-checkout/build/third_party/sqlalchemy_migrate_0_7_1:/b/s/w/ir/kitchen-checkout/build/third_party/tempita_0_5:/b/s/w/ir/kitchen-checkout/build/third_party/twisted_10_2:/b/s/w/ir/kitchen-checkout/build/third_party/uritemplate:/b/s/w/ir/kitchen-checkout/build/third_party/site-packages 2019-05-23 07:36:57,148 - root: [DEBUG] Using task_output_dir: '/b/s/w/ir/tmp/t/tmpVr0ZRo' 2019-05-23 07:36:57,148 - root: [WARNING] task_output_dir '/b/s/w/ir/tmp/t/tmpVr0ZRo' already exists! 2019-05-23 07:36:57,148 - root: [WARNING] task_output_dir existing content: [] 2019-05-23 07:36:57,148 - root: [INFO] collect_cmd: swarming collect -server https://chromium-swarm.appspot.com -worker 50 -task-summary-python -task-output-stdout all -verbose -requests-json /b/s/w/ir/tmp/t/tmpqZM7RN.json -output-dir /b/s/w/ir/tmp/t/tmpVr0ZRo -task-summary-json /b/s/w/ir/tmp/t/tmpl3t9gQ.json [D2019-05-23T07:36:57.157523-07:00 32343 0 auth.go:1265] Minting a new token {"key":"luci_ctx/b8c8e562bfb7c4af5d7f856c76a522413c57018a0782fe59511d2f282ad4a991", "scopes":"https://www.googleapis.com/auth/userinfo.email"} [D2019-05-23T07:36:57.157607-07:00 32343 0 luci_ctx.go:138] POST http://127.0.0.1:45627/rpc/LuciLocalAuthService.GetOAuthToken {"key":"luci_ctx/b8c8e562bfb7c4af5d7f856c76a522413c57018a0782fe59511d2f282ad4a991", "scopes":"https://www.googleapis.com/auth/userinfo.email"} [D2019-05-23T07:36:57.159271-07:00 32343 0 auth.go:1222] Token expires in 4m51.840756118s {"key":"luci_ctx/b8c8e562bfb7c4af5d7f856c76a522413c57018a0782fe59511d2f282ad4a991", "scopes":"https://www.googleapis.com/auth/userinfo.email"} 44fde3d5fffa3f10: exit 0 /b/swarming/swarming_bot.1.zip/third_party/requests/__init__.py:83: RequestsDependencyWarning: Old version of cryptography ([1, 2, 3]) may cause slowdown. I 0.000s Main command: bin/../../../build/android/test_runner.py gtest --output-directory bin/../. --runtime-deps-path bin/../gen.runtime/modules/audio_coding/audio_decoder_unittests__test_runner_script.runtime_deps --suite audio_decoder_unittests --shard-timeout=900 -v --logcat-output-file /b/swarming/w/iojcSz3b/logcats --store-tombstones --test-launcher-summary-output=/b/swarming/w/iojcSz3b/output.json I 0.007s Main condition '<lambda>' met I 0.008s Main condition '<lambda>' met I 3.352s TimeoutThread-1-for-prepare_device(01e37e2326e03291) condition 'sd_card_ready' met (0.0s) I 3.945s TimeoutThread-1-for-prepare_device(01e37e2326e03291) condition 'pm_ready' met (0.6s) I 3.999s TimeoutThread-1-for-prepare_device(01e37e2326e03291) condition 'boot_completed' met (0.7s) I 14.234s TimeoutThread-1-for-individual_device_set_up(01e37e2326e03291) Setting permissions for org.webrtc.native_test. I 16.565s calculate_device_checksums condition '<lambda>' met (0.1s) I 19.139s list_tests(01e37e2326e03291) flags: I 19.140s list_tests(01e37e2326e03291) --gtest_list_tests I 19.153s TimeoutThread-1-for-list_tests(01e37e2326e03291) Large shell command will be run from file: ( p=org.webrtc.native_test;am instrument -w -e org.chromium.native_test.NativeTestInstrumentationTestRunner.ShardNanoTimeout 30000000000 -e org.chromium.native_test.NativeTestInstrumentationTestRunner.NativeTestActivity "$p".RTCNativeUnitTestActivity -e org.chromium.native_test.NativeTestInstrumentationTestRunner.StdoutFile /sdcard/temp_file-465041c1d828b.gtest_out -e org.chromium.native_test.NativeTest.CommandLineFlags --gtest_list_tests "$p"/org.chromium.native_test.NativeTestInstrumentationTestRunner )>/ ... I 20.738s list_tests(01e37e2326e03291) /sdcard/temp_file-465041c1d828b.gtest_out size on device: 689 I 20.806s Main Using external sharding settings. This is shard 0/1 I 20.806s Main STARTING TRY #1/3 I 20.806s Main Will run 22 tests on 1 devices: 01e37e2326e03291 W 20.873s run_tests_on_device(01e37e2326e03291) No tombstones to clear. I 24.345s run_tests_on_device(01e37e2326e03291) flags: I 24.345s run_tests_on_device(01e37e2326e03291) Getting logdog client. I 24.452s TimeoutThread-1-for-run_tests_on_device(01e37e2326e03291) Large shell command will be run from file: ( p=org.webrtc.native_test;am instrument -w -e org.chromium.native_test.NativeTestInstrumentationTestRunner.ShardNanoTimeout 900000000000 -e org.chromium.native_test.NativeTestInstrumentationTestRunner.NativeTestActivity "$p".RTCNativeUnitTestActivity -e org.chromium.native_test.NativeTestInstrumentationTestRunner.TestList /data/local/tmp/temp_file-ead38ae14e9dc -e org.chromium.native_test.NativeTestInstrumentationTestRunner.StdoutFile /sdcard/temp_file-f205677cebfe1.gtest_out "$p"/org.chromium.native_test. ... I 26.071s run_tests_on_device(01e37e2326e03291) /sdcard/temp_file-f205677cebfe1.gtest_out size on device: 4801 I 26.257s archive Writing text to logdog stream, logcat_logcat_-8315840777862943659_20190523T142309-UTC_01e37e2326e03291 I 26.257s run_tests_on_device(01e37e2326e03291) Logcat saved to https://luci-logdog.appspot.com/v/?s=chromium%2Fandroid%2Fswarming%2Flogcats%2F44fde3d5fffa3f11%2F%2B%2Flogcat_logcat_-8315840777862943659_20190523T142309-UTC_01e37e2326e03291 I 26.879s TimeoutThread-1-for-run_tests_on_device(01e37e2326e03291) Setting permissions for org.webrtc.native_test. I 29.463s run_tests_on_device(01e37e2326e03291) >>ScopedMainEntryLogger I 29.463s run_tests_on_device(01e37e2326e03291) Note: Google Test filter = AudioDecoderG722StereoTest.EncodeDecode:AudioDecoderG722StereoTest.SetTargetBitrate:AudioDecoderG722Test.EncodeDecode:AudioDecoderG722Test.SetTargetBitrate:AudioDecoderIlbcTest.EncodeDecode:AudioDecoderIlbcTest.SetTargetBitrate:AudioDecoderIsacFixTest.EncodeDecode:AudioDecoderIsacFixTest.SetTargetBitrate:AudioDecoderIsacFloatTest.EncodeDecode:AudioDecoderIsacFloatTest.SetTargetBitrate:AudioDecoderIsacSwbTest.EncodeDecode:AudioDecoderIsacSwbTest.SetTargetBitrate:AudioDecoderOpusStereoTest.EncodeDecode:AudioDecoderOpusStereoTest.SetTargetBitrate:AudioDecoderOpusTest.EncodeDecode:AudioDecoderOpusTest.SetTargetBitrate:AudioDecoderPcm16BTest.EncodeDecode:AudioDecoderPcm16BTest.SetTargetBitrate:AudioDecoderPcmATest.EncodeDecode:AudioDecoderPcmATest.SetTargetBitrate:AudioDecoderPcmUTest.EncodeDecode:AudioDecoderPcmUTest.SetTargetBitrate I 29.463s run_tests_on_device(01e37e2326e03291) [==========] Running 22 tests from 11 test suites. I 29.463s run_tests_on_device(01e37e2326e03291) [----------] Global test environment set-up. I 29.463s run_tests_on_device(01e37e2326e03291) [----------] 2 tests from AudioDecoderPcmUTest I 29.463s run_tests_on_device(01e37e2326e03291) [ RUN ] AudioDecoderPcmUTest.EncodeDecode I 29.463s run_tests_on_device(01e37e2326e03291) [ OK ] AudioDecoderPcmUTest.EncodeDecode (2 ms) I 29.463s run_tests_on_device(01e37e2326e03291) [ RUN ] AudioDecoderPcmUTest.SetTargetBitrate I 29.463s run_tests_on_device(01e37e2326e03291) [ OK ] AudioDecoderPcmUTest.SetTargetBitrate (0 ms) I 29.463s run_tests_on_device(01e37e2326e03291) [----------] 2 tests from AudioDecoderPcmUTest (2 ms total) I 29.463s run_tests_on_device(01e37e2326e03291) I 29.463s run_tests_on_device(01e37e2326e03291) [----------] 2 tests from AudioDecoderPcmATest I 29.463s run_tests_on_device(01e37e2326e03291) [ RUN ] AudioDecoderPcmATest.EncodeDecode I 29.463s run_tests_on_device(01e37e2326e03291) [ OK ] AudioDecoderPcmATest.EncodeDecode (1 ms) I 29.463s run_tests_on_device(01e37e2326e03291) [ RUN ] AudioDecoderPcmATest.SetTargetBitrate I 29.463s run_tests_on_device(01e37e2326e03291) [ OK ] AudioDecoderPcmATest.SetTargetBitrate (0 ms) I 29.463s run_tests_on_device(01e37e2326e03291) [----------] 2 tests from AudioDecoderPcmATest (2 ms total) I 29.463s run_tests_on_device(01e37e2326e03291) I 29.464s run_tests_on_device(01e37e2326e03291) [----------] 2 tests from AudioDecoderPcm16BTest I 29.464s run_tests_on_device(01e37e2326e03291) [ RUN ] AudioDecoderPcm16BTest.EncodeDecode I 29.464s run_tests_on_device(01e37e2326e03291) [ OK ] AudioDecoderPcm16BTest.EncodeDecode (1 ms) I 29.464s run_tests_on_device(01e37e2326e03291) [ RUN ] AudioDecoderPcm16BTest.SetTargetBitrate I 29.464s run_tests_on_device(01e37e2326e03291) [ OK ] AudioDecoderPcm16BTest.SetTargetBitrate (1 ms) I 29.464s run_tests_on_device(01e37e2326e03291) [----------] 2 tests from AudioDecoderPcm16BTest (2 ms total) I 29.464s run_tests_on_device(01e37e2326e03291) I 29.464s run_tests_on_device(01e37e2326e03291) [----------] 2 tests from AudioDecoderIlbcTest I 29.464s run_tests_on_device(01e37e2326e03291) [ RUN ] AudioDecoderIlbcTest.EncodeDecode I 29.464s run_tests_on_device(01e37e2326e03291) [ OK ] AudioDecoderIlbcTest.EncodeDecode (12 ms) I 29.464s run_tests_on_device(01e37e2326e03291) [ RUN ] AudioDecoderIlbcTest.SetTargetBitrate I 29.464s run_tests_on_device(01e37e2326e03291) [ OK ] AudioDecoderIlbcTest.SetTargetBitrate (1 ms) I 29.464s run_tests_on_device(01e37e2326e03291) [----------] 2 tests from AudioDecoderIlbcTest (13 ms total) I 29.464s run_tests_on_device(01e37e2326e03291) I 29.464s run_tests_on_device(01e37e2326e03291) [----------] 2 tests from AudioDecoderIsacFloatTest I 29.464s run_tests_on_device(01e37e2326e03291) [ RUN ] AudioDecoderIsacFloatTest.EncodeDecode I 29.464s run_tests_on_device(01e37e2326e03291) [ OK ] AudioDecoderIsacFloatTest.EncodeDecode (26 ms) I 29.464s run_tests_on_device(01e37e2326e03291) [ RUN ] AudioDecoderIsacFloatTest.SetTargetBitrate I 29.464s run_tests_on_device(01e37e2326e03291) [ OK ] AudioDecoderIsacFloatTest.SetTargetBitrate (1 ms) I 29.464s run_tests_on_device(01e37e2326e03291) [----------] 2 tests from AudioDecoderIsacFloatTest (27 ms total) I 29.464s run_tests_on_device(01e37e2326e03291) I 29.464s run_tests_on_device(01e37e2326e03291) [----------] 2 tests from AudioDecoderIsacSwbTest I 29.464s run_tests_on_device(01e37e2326e03291) [ RUN ] AudioDecoderIsacSwbTest.EncodeDecode I 29.464s run_tests_on_device(01e37e2326e03291) [ OK ] AudioDecoderIsacSwbTest.EncodeDecode (23 ms) I 29.464s run_tests_on_device(01e37e2326e03291) [ RUN ] AudioDecoderIsacSwbTest.SetTargetBitrate I 29.464s run_tests_on_device(01e37e2326e03291) [ OK ] AudioDecoderIsacSwbTest.SetTargetBitrate (1 ms) I 29.464s run_tests_on_device(01e37e2326e03291) [----------] 2 tests from AudioDecoderIsacSwbTest (25 ms total) I 29.465s run_tests_on_device(01e37e2326e03291) I 29.465s run_tests_on_device(01e37e2326e03291) [----------] 2 tests from AudioDecoderIsacFixTest I 29.465s run_tests_on_device(01e37e2326e03291) [ RUN ] AudioDecoderIsacFixTest.EncodeDecode I 29.465s run_tests_on_device(01e37e2326e03291) [ OK ] AudioDecoderIsacFixTest.EncodeDecode (7 ms) I 29.465s run_tests_on_device(01e37e2326e03291) [ RUN ] AudioDecoderIsacFixTest.SetTargetBitrate I 29.465s run_tests_on_device(01e37e2326e03291) [ OK ] AudioDecoderIsacFixTest.SetTargetBitrate (1 ms) I 29.465s run_tests_on_device(01e37e2326e03291) [----------] 2 tests from AudioDecoderIsacFixTest (8 ms total) I 29.465s run_tests_on_device(01e37e2326e03291) I 29.465s run_tests_on_device(01e37e2326e03291) [----------] 2 tests from AudioDecoderG722Test I 29.465s run_tests_on_device(01e37e2326e03291) [ RUN ] AudioDecoderG722Test.EncodeDecode I 29.465s run_tests_on_device(01e37e2326e03291) [ OK ] AudioDecoderG722Test.EncodeDecode (2 ms) I 29.465s run_tests_on_device(01e37e2326e03291) [ RUN ] AudioDecoderG722Test.SetTargetBitrate I 29.465s run_tests_on_device(01e37e2326e03291) [ OK ] AudioDecoderG722Test.SetTargetBitrate (1 ms) I 29.465s run_tests_on_device(01e37e2326e03291) [----------] 2 tests from AudioDecoderG722Test (3 ms total) I 29.465s run_tests_on_device(01e37e2326e03291) I 29.465s run_tests_on_device(01e37e2326e03291) [----------] 2 tests from AudioDecoderG722StereoTest I 29.465s run_tests_on_device(01e37e2326e03291) [ RUN ] AudioDecoderG722StereoTest.EncodeDecode I 29.465s run_tests_on_device(01e37e2326e03291) [ OK ] AudioDecoderG722StereoTest.EncodeDecode (4 ms) I 29.465s run_tests_on_device(01e37e2326e03291) [ RUN ] AudioDecoderG722StereoTest.SetTargetBitrate I 29.465s run_tests_on_device(01e37e2326e03291) [ OK ] AudioDecoderG722StereoTest.SetTargetBitrate (0 ms) I 29.465s run_tests_on_device(01e37e2326e03291) [----------] 2 tests from AudioDecoderG722StereoTest (4 ms total) I 29.465s run_tests_on_device(01e37e2326e03291) I 29.465s run_tests_on_device(01e37e2326e03291) [----------] 2 tests from AudioDecoderOpusTest I 29.465s run_tests_on_device(01e37e2326e03291) [ RUN ] AudioDecoderOpusTest.EncodeDecode I 29.465s run_tests_on_device(01e37e2326e03291) [ OK ] AudioDecoderOpusTest.EncodeDecode (8 ms) I 29.465s run_tests_on_device(01e37e2326e03291) [ RUN ] AudioDecoderOpusTest.SetTargetBitrate I 29.465s run_tests_on_device(01e37e2326e03291) [ OK ] AudioDecoderOpusTest.SetTargetBitrate (2 ms) I 29.466s run_tests_on_device(01e37e2326e03291) [----------] 2 tests from AudioDecoderOpusTest (11 ms total) I 29.466s run_tests_on_device(01e37e2326e03291) I 29.466s run_tests_on_device(01e37e2326e03291) [----------] 2 tests from AudioDecoderOpusStereoTest I 29.466s run_tests_on_device(01e37e2326e03291) [ RUN ] AudioDecoderOpusStereoTest.EncodeDecode I 29.466s run_tests_on_device(01e37e2326e03291) [ OK ] AudioDecoderOpusStereoTest.EncodeDecode (9 ms) I 29.466s run_tests_on_device(01e37e2326e03291) [ RUN ] AudioDecoderOpusStereoTest.SetTargetBitrate I 29.466s run_tests_on_device(01e37e2326e03291) [ OK ] AudioDecoderOpusStereoTest.SetTargetBitrate (2 ms) I 29.466s run_tests_on_device(01e37e2326e03291) [----------] 2 tests from AudioDecoderOpusStereoTest (11 ms total) I 29.466s run_tests_on_device(01e37e2326e03291) I 29.466s run_tests_on_device(01e37e2326e03291) [----------] Global test environment tear-down I 29.466s run_tests_on_device(01e37e2326e03291) [==========] 22 tests from 11 test suites ran. (110 ms total) I 29.466s run_tests_on_device(01e37e2326e03291) [ PASSED ] 22 tests. I 29.466s run_tests_on_device(01e37e2326e03291) <<ScopedMainEntryLogger I 29.469s run_tests_on_device(01e37e2326e03291) Finished running tests on this device. I 29.484s Main FINISHED TRY #1/3 I 29.484s Main All tests completed. C 29.485s Main ******************************************************************************** C 29.485s Main Summary C 29.485s Main ******************************************************************************** C 29.485s Main [==========] 22 tests ran. C 29.485s Main [ PASSED ] 22 tests. C 29.485s Main ******************************************************************************** I 29.486s tear_down_device(01e37e2326e03291) Wrote device cache: /b/swarming/w/ir/out/Android32_M_Nexus5X_dbg/device_cache_01e37e2326e03291.json I 29.519s Main Generated json results file at /b/swarming/w/ituYPqUd/tmpl0A68u I 29.519s Main Finishing archiving output. [W2019-05-23T14:23:14.453783Z 2016 0 butler.go:240] Butler Context was cancelled. Initiating shutdown. {"error":"context canceled"} INFO:devil.utils.timeout_retry:condition 'logdog_stopped' not met [E2019-05-23T14:23:14.493945Z 2016 0 main.go:205] Butler terminated with error. {"error":"context canceled"} [E2019-05-23T14:23:14.493989Z 2016 0 main.go:233] Failed to serve. original error: context canceled [I2019-05-23T14:23:14.494043Z 2016 0 main.go:349] Terminating. {"returnCode":250} INFO:devil.utils.timeout_retry:condition 'logdog_stopped' met 2019-05-23 07:36:57,897 - root: [DEBUG] Contents of task_output_dir: ['/b/s/w/ir/tmp/t/tmpVr0ZRo/44fde3d5fffa3f10'] 2019-05-23 07:36:57,898 - root: [DEBUG] Found shard_json_files: ['/b/s/w/ir/tmp/t/tmpVr0ZRo/44fde3d5fffa3f10/output.json'] 2019-05-23 07:36:57,898 - root: [INFO] merge_cmd: /b/s/w/ir/cache/vpython/f6423e/bin/python /b/s/w/ir/cache/builder/src/testing/merge_scripts/standard_gtest_merge.py --summary-json /b/s/w/ir/tmp/t/tmpl3t9gQ.json --task-output-dir /b/s/w/ir/tmp/t/tmpVr0ZRo -o /b/s/w/ir/tmp/t/tmpnOVpDz.json /b/s/w/ir/tmp/t/tmpVr0ZRo/44fde3d5fffa3f10/output.json Running ['/b/s/w/ir/cache/vpython/f6423e/bin/python', '/b/s/w/ir/cache/builder/src/testing/merge_scripts/standard_gtest_merge.py', '--summary-json', '/b/s/w/ir/tmp/t/tmpl3t9gQ.json', '--task-output-dir', '/b/s/w/ir/tmp/t/tmpVr0ZRo', '-o', '/b/s/w/ir/tmp/t/tmpnOVpDz.json', '/b/s/w/ir/tmp/t/tmpVr0ZRo/44fde3d5fffa3f10/output.json'] in None (env: None) Command ['/b/s/w/ir/cache/vpython/f6423e/bin/python', '/b/s/w/ir/cache/builder/src/testing/merge_scripts/standard_gtest_merge.py', '--summary-json', '/b/s/w/ir/tmp/t/tmpl3t9gQ.json', '--task-output-dir', '/b/s/w/ir/tmp/t/tmpVr0ZRo', '-o', '/b/s/w/ir/tmp/t/tmpnOVpDz.json', '/b/s/w/ir/tmp/t/tmpVr0ZRo/44fde3d5fffa3f10/output.json'] returned exit code 0