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-20 07:58:47,783 - root: [DEBUG] Using task_output_dir: '/b/s/w/ir/tmp/t/tmpdhCYoF' 2019-05-20 07:58:47,783 - root: [WARNING] task_output_dir '/b/s/w/ir/tmp/t/tmpdhCYoF' already exists! 2019-05-20 07:58:47,783 - root: [WARNING] task_output_dir existing content: [] 2019-05-20 07:58:47,783 - 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/tmp_8BvQP.json -output-dir /b/s/w/ir/tmp/t/tmpdhCYoF -task-summary-json /b/s/w/ir/tmp/t/tmpdUpExx.json [D2019-05-20T07:58:47.794093-07:00 1373 0 auth.go:1265] Minting a new token {"key":"luci_ctx/f05170ccf33f0685bc1e7adb08fc611a9c9501df101ebe2f63e7b4f5050a5652", "scopes":"https://www.googleapis.com/auth/userinfo.email"} [D2019-05-20T07:58:47.794190-07:00 1373 0 luci_ctx.go:138] POST http://127.0.0.1:41405/rpc/LuciLocalAuthService.GetOAuthToken {"key":"luci_ctx/f05170ccf33f0685bc1e7adb08fc611a9c9501df101ebe2f63e7b4f5050a5652", "scopes":"https://www.googleapis.com/auth/userinfo.email"} [D2019-05-20T07:58:47.798851-07:00 1373 0 auth.go:1222] Token expires in 21m40.201193814s {"key":"luci_ctx/f05170ccf33f0685bc1e7adb08fc611a9c9501df101ebe2f63e7b4f5050a5652", "scopes":"https://www.googleapis.com/auth/userinfo.email"} 44ee8a9408a06210: 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/iop2j4wv/logcats --store-tombstones --test-launcher-summary-output=/b/swarming/w/iop2j4wv/output.json I 0.007s Main condition '<lambda>' met I 0.008s Main condition '<lambda>' met I 3.180s TimeoutThread-1-for-prepare_device(01e20f080c8cc6e8) condition 'sd_card_ready' met (0.0s) I 3.768s TimeoutThread-1-for-prepare_device(01e20f080c8cc6e8) condition 'pm_ready' met (0.6s) I 3.819s TimeoutThread-1-for-prepare_device(01e20f080c8cc6e8) condition 'boot_completed' met (0.7s) I 13.737s TimeoutThread-1-for-individual_device_set_up(01e20f080c8cc6e8) Setting permissions for org.webrtc.native_test. I 16.385s calculate_device_checksums condition '<lambda>' met (0.2s) I 18.943s list_tests(01e20f080c8cc6e8) flags: I 18.943s list_tests(01e20f080c8cc6e8) --gtest_list_tests I 18.951s TimeoutThread-1-for-list_tests(01e20f080c8cc6e8) 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-fe553c7562024.gtest_out -e org.chromium.native_test.NativeTest.CommandLineFlags --gtest_list_tests "$p"/org.chromium.native_test.NativeTestInstrumentationTestRunner )>/ ... I 20.649s list_tests(01e20f080c8cc6e8) /sdcard/temp_file-fe553c7562024.gtest_out size on device: 689 I 20.751s Main Using external sharding settings. This is shard 0/1 I 20.751s Main STARTING TRY #1/3 I 20.751s Main Will run 22 tests on 1 devices: 01e20f080c8cc6e8 I 24.355s run_tests_on_device(01e20f080c8cc6e8) flags: I 24.356s run_tests_on_device(01e20f080c8cc6e8) Getting logdog client. I 24.473s TimeoutThread-1-for-run_tests_on_device(01e20f080c8cc6e8) 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-c0153cf433766 -e org.chromium.native_test.NativeTestInstrumentationTestRunner.StdoutFile /sdcard/temp_file-5118e55173c74.gtest_out "$p"/org.chromium.native_test. ... I 25.992s run_tests_on_device(01e20f080c8cc6e8) /sdcard/temp_file-5118e55173c74.gtest_out size on device: 4797 I 26.275s run_tests_on_device(01e20f080c8cc6e8) Logcat saved to https://luci-logdog.appspot.com/v/?s=chromium%2Fandroid%2Fswarming%2Flogcats%2F44ee8a9408a06211%2F%2B%2Flogcat_logcat_-8315840777862943659_20190520T145122-UTC_01e20f080c8cc6e8 I 26.275s archive Writing text to logdog stream, logcat_logcat_-8315840777862943659_20190520T145122-UTC_01e20f080c8cc6e8 I 26.939s TimeoutThread-1-for-run_tests_on_device(01e20f080c8cc6e8) Setting permissions for org.webrtc.native_test. I 29.620s run_tests_on_device(01e20f080c8cc6e8) >>ScopedMainEntryLogger I 29.620s run_tests_on_device(01e20f080c8cc6e8) 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.621s run_tests_on_device(01e20f080c8cc6e8) [==========] Running 22 tests from 11 test suites. I 29.621s run_tests_on_device(01e20f080c8cc6e8) [----------] Global test environment set-up. I 29.621s run_tests_on_device(01e20f080c8cc6e8) [----------] 2 tests from AudioDecoderPcmUTest I 29.621s run_tests_on_device(01e20f080c8cc6e8) [ RUN ] AudioDecoderPcmUTest.EncodeDecode I 29.621s run_tests_on_device(01e20f080c8cc6e8) [ OK ] AudioDecoderPcmUTest.EncodeDecode (1 ms) I 29.621s run_tests_on_device(01e20f080c8cc6e8) [ RUN ] AudioDecoderPcmUTest.SetTargetBitrate I 29.621s run_tests_on_device(01e20f080c8cc6e8) [ OK ] AudioDecoderPcmUTest.SetTargetBitrate (0 ms) I 29.621s run_tests_on_device(01e20f080c8cc6e8) [----------] 2 tests from AudioDecoderPcmUTest (2 ms total) I 29.621s run_tests_on_device(01e20f080c8cc6e8) I 29.621s run_tests_on_device(01e20f080c8cc6e8) [----------] 2 tests from AudioDecoderPcmATest I 29.621s run_tests_on_device(01e20f080c8cc6e8) [ RUN ] AudioDecoderPcmATest.EncodeDecode I 29.621s run_tests_on_device(01e20f080c8cc6e8) [ OK ] AudioDecoderPcmATest.EncodeDecode (1 ms) I 29.621s run_tests_on_device(01e20f080c8cc6e8) [ RUN ] AudioDecoderPcmATest.SetTargetBitrate I 29.622s run_tests_on_device(01e20f080c8cc6e8) [ OK ] AudioDecoderPcmATest.SetTargetBitrate (1 ms) I 29.622s run_tests_on_device(01e20f080c8cc6e8) [----------] 2 tests from AudioDecoderPcmATest (2 ms total) I 29.622s run_tests_on_device(01e20f080c8cc6e8) I 29.622s run_tests_on_device(01e20f080c8cc6e8) [----------] 2 tests from AudioDecoderPcm16BTest I 29.622s run_tests_on_device(01e20f080c8cc6e8) [ RUN ] AudioDecoderPcm16BTest.EncodeDecode I 29.622s run_tests_on_device(01e20f080c8cc6e8) [ OK ] AudioDecoderPcm16BTest.EncodeDecode (2 ms) I 29.623s run_tests_on_device(01e20f080c8cc6e8) [ RUN ] AudioDecoderPcm16BTest.SetTargetBitrate I 29.623s run_tests_on_device(01e20f080c8cc6e8) [ OK ] AudioDecoderPcm16BTest.SetTargetBitrate (0 ms) I 29.623s run_tests_on_device(01e20f080c8cc6e8) [----------] 2 tests from AudioDecoderPcm16BTest (2 ms total) I 29.623s run_tests_on_device(01e20f080c8cc6e8) I 29.623s run_tests_on_device(01e20f080c8cc6e8) [----------] 2 tests from AudioDecoderIlbcTest I 29.623s run_tests_on_device(01e20f080c8cc6e8) [ RUN ] AudioDecoderIlbcTest.EncodeDecode I 29.623s run_tests_on_device(01e20f080c8cc6e8) [ OK ] AudioDecoderIlbcTest.EncodeDecode (9 ms) I 29.623s run_tests_on_device(01e20f080c8cc6e8) [ RUN ] AudioDecoderIlbcTest.SetTargetBitrate I 29.623s run_tests_on_device(01e20f080c8cc6e8) [ OK ] AudioDecoderIlbcTest.SetTargetBitrate (0 ms) I 29.623s run_tests_on_device(01e20f080c8cc6e8) [----------] 2 tests from AudioDecoderIlbcTest (10 ms total) I 29.623s run_tests_on_device(01e20f080c8cc6e8) I 29.623s run_tests_on_device(01e20f080c8cc6e8) [----------] 2 tests from AudioDecoderIsacFloatTest I 29.623s run_tests_on_device(01e20f080c8cc6e8) [ RUN ] AudioDecoderIsacFloatTest.EncodeDecode I 29.623s run_tests_on_device(01e20f080c8cc6e8) [ OK ] AudioDecoderIsacFloatTest.EncodeDecode (14 ms) I 29.624s run_tests_on_device(01e20f080c8cc6e8) [ RUN ] AudioDecoderIsacFloatTest.SetTargetBitrate I 29.624s run_tests_on_device(01e20f080c8cc6e8) [ OK ] AudioDecoderIsacFloatTest.SetTargetBitrate (1 ms) I 29.624s run_tests_on_device(01e20f080c8cc6e8) [----------] 2 tests from AudioDecoderIsacFloatTest (15 ms total) I 29.624s run_tests_on_device(01e20f080c8cc6e8) I 29.624s run_tests_on_device(01e20f080c8cc6e8) [----------] 2 tests from AudioDecoderIsacSwbTest I 29.624s run_tests_on_device(01e20f080c8cc6e8) [ RUN ] AudioDecoderIsacSwbTest.EncodeDecode I 29.624s run_tests_on_device(01e20f080c8cc6e8) [ OK ] AudioDecoderIsacSwbTest.EncodeDecode (14 ms) I 29.624s run_tests_on_device(01e20f080c8cc6e8) [ RUN ] AudioDecoderIsacSwbTest.SetTargetBitrate I 29.624s run_tests_on_device(01e20f080c8cc6e8) [ OK ] AudioDecoderIsacSwbTest.SetTargetBitrate (1 ms) I 29.625s run_tests_on_device(01e20f080c8cc6e8) [----------] 2 tests from AudioDecoderIsacSwbTest (15 ms total) I 29.625s run_tests_on_device(01e20f080c8cc6e8) I 29.625s run_tests_on_device(01e20f080c8cc6e8) [----------] 2 tests from AudioDecoderIsacFixTest I 29.625s run_tests_on_device(01e20f080c8cc6e8) [ RUN ] AudioDecoderIsacFixTest.EncodeDecode I 29.625s run_tests_on_device(01e20f080c8cc6e8) [ OK ] AudioDecoderIsacFixTest.EncodeDecode (5 ms) I 29.625s run_tests_on_device(01e20f080c8cc6e8) [ RUN ] AudioDecoderIsacFixTest.SetTargetBitrate I 29.625s run_tests_on_device(01e20f080c8cc6e8) [ OK ] AudioDecoderIsacFixTest.SetTargetBitrate (0 ms) I 29.625s run_tests_on_device(01e20f080c8cc6e8) [----------] 2 tests from AudioDecoderIsacFixTest (6 ms total) I 29.625s run_tests_on_device(01e20f080c8cc6e8) I 29.625s run_tests_on_device(01e20f080c8cc6e8) [----------] 2 tests from AudioDecoderG722Test I 29.625s run_tests_on_device(01e20f080c8cc6e8) [ RUN ] AudioDecoderG722Test.EncodeDecode I 29.625s run_tests_on_device(01e20f080c8cc6e8) [ OK ] AudioDecoderG722Test.EncodeDecode (1 ms) I 29.625s run_tests_on_device(01e20f080c8cc6e8) [ RUN ] AudioDecoderG722Test.SetTargetBitrate I 29.626s run_tests_on_device(01e20f080c8cc6e8) [ OK ] AudioDecoderG722Test.SetTargetBitrate (1 ms) I 29.626s run_tests_on_device(01e20f080c8cc6e8) [----------] 2 tests from AudioDecoderG722Test (2 ms total) I 29.626s run_tests_on_device(01e20f080c8cc6e8) I 29.626s run_tests_on_device(01e20f080c8cc6e8) [----------] 2 tests from AudioDecoderG722StereoTest I 29.626s run_tests_on_device(01e20f080c8cc6e8) [ RUN ] AudioDecoderG722StereoTest.EncodeDecode I 29.626s run_tests_on_device(01e20f080c8cc6e8) [ OK ] AudioDecoderG722StereoTest.EncodeDecode (3 ms) I 29.626s run_tests_on_device(01e20f080c8cc6e8) [ RUN ] AudioDecoderG722StereoTest.SetTargetBitrate I 29.626s run_tests_on_device(01e20f080c8cc6e8) [ OK ] AudioDecoderG722StereoTest.SetTargetBitrate (0 ms) I 29.626s run_tests_on_device(01e20f080c8cc6e8) [----------] 2 tests from AudioDecoderG722StereoTest (3 ms total) I 29.626s run_tests_on_device(01e20f080c8cc6e8) I 29.626s run_tests_on_device(01e20f080c8cc6e8) [----------] 2 tests from AudioDecoderOpusTest I 29.626s run_tests_on_device(01e20f080c8cc6e8) [ RUN ] AudioDecoderOpusTest.EncodeDecode I 29.626s run_tests_on_device(01e20f080c8cc6e8) [ OK ] AudioDecoderOpusTest.EncodeDecode (7 ms) I 29.627s run_tests_on_device(01e20f080c8cc6e8) [ RUN ] AudioDecoderOpusTest.SetTargetBitrate I 29.627s run_tests_on_device(01e20f080c8cc6e8) [ OK ] AudioDecoderOpusTest.SetTargetBitrate (1 ms) I 29.627s run_tests_on_device(01e20f080c8cc6e8) [----------] 2 tests from AudioDecoderOpusTest (8 ms total) I 29.627s run_tests_on_device(01e20f080c8cc6e8) I 29.627s run_tests_on_device(01e20f080c8cc6e8) [----------] 2 tests from AudioDecoderOpusStereoTest I 29.627s run_tests_on_device(01e20f080c8cc6e8) [ RUN ] AudioDecoderOpusStereoTest.EncodeDecode I 29.627s run_tests_on_device(01e20f080c8cc6e8) [ OK ] AudioDecoderOpusStereoTest.EncodeDecode (6 ms) I 29.627s run_tests_on_device(01e20f080c8cc6e8) [ RUN ] AudioDecoderOpusStereoTest.SetTargetBitrate I 29.627s run_tests_on_device(01e20f080c8cc6e8) [ OK ] AudioDecoderOpusStereoTest.SetTargetBitrate (1 ms) I 29.627s run_tests_on_device(01e20f080c8cc6e8) [----------] 2 tests from AudioDecoderOpusStereoTest (8 ms total) I 29.627s run_tests_on_device(01e20f080c8cc6e8) I 29.627s run_tests_on_device(01e20f080c8cc6e8) [----------] Global test environment tear-down I 29.627s run_tests_on_device(01e20f080c8cc6e8) [==========] 22 tests from 11 test suites ran. (75 ms total) I 29.627s run_tests_on_device(01e20f080c8cc6e8) [ PASSED ] 22 tests. I 29.627s run_tests_on_device(01e20f080c8cc6e8) <<ScopedMainEntryLogger I 29.631s run_tests_on_device(01e20f080c8cc6e8) Finished running tests on this device. I 29.669s Main FINISHED TRY #1/3 I 29.669s Main All tests completed. C 29.669s Main ******************************************************************************** C 29.669s Main Summary C 29.669s Main ******************************************************************************** C 29.670s Main [==========] 22 tests ran. C 29.670s Main [ PASSED ] 22 tests. C 29.670s Main ******************************************************************************** I 29.672s tear_down_device(01e20f080c8cc6e8) Wrote device cache: /b/swarming/w/ir/out/Android32_M_Nexus5X_dbg/device_cache_01e20f080c8cc6e8.json I 29.791s Main Generated json results file at /b/swarming/w/itdfDANE/tmpIGqXrG I 29.791s Main Finishing archiving output. [W2019-05-20T14:51:27.503506Z 5654 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-20T14:51:27.552491Z 5654 0 main.go:205] Butler terminated with error. {"error":"context canceled"} [E2019-05-20T14:51:27.552553Z 5654 0 main.go:233] Failed to serve. original error: context canceled [I2019-05-20T14:51:27.552743Z 5654 0 main.go:349] Terminating. {"returnCode":250} INFO:devil.utils.timeout_retry:condition 'logdog_stopped' met 2019-05-20 07:58:48,441 - root: [DEBUG] Contents of task_output_dir: ['/b/s/w/ir/tmp/t/tmpdhCYoF/44ee8a9408a06210'] 2019-05-20 07:58:48,442 - root: [DEBUG] Found shard_json_files: ['/b/s/w/ir/tmp/t/tmpdhCYoF/44ee8a9408a06210/output.json'] 2019-05-20 07:58:48,442 - 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/tmpdUpExx.json --task-output-dir /b/s/w/ir/tmp/t/tmpdhCYoF -o /b/s/w/ir/tmp/t/tmpm1Keyo.json /b/s/w/ir/tmp/t/tmpdhCYoF/44ee8a9408a06210/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/tmpdUpExx.json', '--task-output-dir', '/b/s/w/ir/tmp/t/tmpdhCYoF', '-o', '/b/s/w/ir/tmp/t/tmpm1Keyo.json', '/b/s/w/ir/tmp/t/tmpdhCYoF/44ee8a9408a06210/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/tmpdUpExx.json', '--task-output-dir', '/b/s/w/ir/tmp/t/tmpdhCYoF', '-o', '/b/s/w/ir/tmp/t/tmpm1Keyo.json', '/b/s/w/ir/tmp/t/tmpdhCYoF/44ee8a9408a06210/output.json'] returned exit code 0