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-21 12:58:07,490 - root: [DEBUG] Using task_output_dir: '/b/s/w/ir/tmp/t/tmp4LZV7B' 2019-05-21 12:58:07,490 - root: [WARNING] task_output_dir '/b/s/w/ir/tmp/t/tmp4LZV7B' already exists! 2019-05-21 12:58:07,490 - root: [WARNING] task_output_dir existing content: [] 2019-05-21 12:58:07,490 - 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/tmpzRp349.json -output-dir /b/s/w/ir/tmp/t/tmp4LZV7B -task-summary-json /b/s/w/ir/tmp/t/tmpV_bB2Z.json [D2019-05-21T12:58:07.502173-07:00 6228 0 auth.go:1265] Minting a new token {"key":"luci_ctx/f3547caa67b9e7bf8f729dc3953d32790c17ad60489c04d40ef704cb401982ac", "scopes":"https://www.googleapis.com/auth/userinfo.email"} [D2019-05-21T12:58:07.502262-07:00 6228 0 luci_ctx.go:138] POST http://127.0.0.1:33241/rpc/LuciLocalAuthService.GetOAuthToken {"key":"luci_ctx/f3547caa67b9e7bf8f729dc3953d32790c17ad60489c04d40ef704cb401982ac", "scopes":"https://www.googleapis.com/auth/userinfo.email"} [D2019-05-21T12:58:07.504505-07:00 6228 0 auth.go:1222] Token expires in 50m49.495534581s {"key":"luci_ctx/f3547caa67b9e7bf8f729dc3953d32790c17ad60489c04d40ef704cb401982ac", "scopes":"https://www.googleapis.com/auth/userinfo.email"} 44f4c2b55f30df10: exit 0 /b/swarming/swarming_bot.2.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/io21L3xn/logcats --store-tombstones --test-launcher-summary-output=/b/swarming/w/io21L3xn/output.json I 0.005s Main condition '<lambda>' met I 0.006s Main condition '<lambda>' met I 3.191s TimeoutThread-1-for-prepare_device(01e0c5decc7f1cd6) condition 'sd_card_ready' met (0.0s) I 3.794s TimeoutThread-1-for-prepare_device(01e0c5decc7f1cd6) condition 'pm_ready' met (0.6s) I 3.851s TimeoutThread-1-for-prepare_device(01e0c5decc7f1cd6) condition 'boot_completed' met (0.7s) I 13.655s TimeoutThread-1-for-individual_device_set_up(01e0c5decc7f1cd6) Setting permissions for org.webrtc.native_test. I 16.269s calculate_device_checksums condition '<lambda>' met (0.2s) I 19.009s list_tests(01e0c5decc7f1cd6) flags: I 19.009s list_tests(01e0c5decc7f1cd6) --gtest_list_tests I 19.018s TimeoutThread-1-for-list_tests(01e0c5decc7f1cd6) 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-c27ab095ec214.gtest_out -e org.chromium.native_test.NativeTest.CommandLineFlags --gtest_list_tests "$p"/org.chromium.native_test.NativeTestInstrumentationTestRunner )>/ ... I 20.676s list_tests(01e0c5decc7f1cd6) /sdcard/temp_file-c27ab095ec214.gtest_out size on device: 689 I 20.744s Main Using external sharding settings. This is shard 0/1 I 20.744s Main STARTING TRY #1/3 I 20.744s Main Will run 22 tests on 1 devices: 01e0c5decc7f1cd6 W 20.793s run_tests_on_device(01e0c5decc7f1cd6) No tombstones to clear. I 24.526s run_tests_on_device(01e0c5decc7f1cd6) flags: I 24.526s run_tests_on_device(01e0c5decc7f1cd6) Getting logdog client. I 24.680s TimeoutThread-1-for-run_tests_on_device(01e0c5decc7f1cd6) 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-7f3544f961096 -e org.chromium.native_test.NativeTestInstrumentationTestRunner.StdoutFile /sdcard/temp_file-cd9829fea5618.gtest_out "$p"/org.chromium.native_test. ... I 26.237s run_tests_on_device(01e0c5decc7f1cd6) /sdcard/temp_file-cd9829fea5618.gtest_out size on device: 4796 I 26.460s run_tests_on_device(01e0c5decc7f1cd6) Logcat saved to https://luci-logdog.appspot.com/v/?s=chromium%2Fandroid%2Fswarming%2Flogcats%2F44f4c2b55f30df11%2F%2B%2Flogcat_logcat_-8315840777862943659_20190521T195023-UTC_01e0c5decc7f1cd6 I 26.462s archive Writing text to logdog stream, logcat_logcat_-8315840777862943659_20190521T195023-UTC_01e0c5decc7f1cd6 I 27.111s TimeoutThread-1-for-run_tests_on_device(01e0c5decc7f1cd6) Setting permissions for org.webrtc.native_test. I 29.833s run_tests_on_device(01e0c5decc7f1cd6) >>ScopedMainEntryLogger I 29.833s run_tests_on_device(01e0c5decc7f1cd6) 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.833s run_tests_on_device(01e0c5decc7f1cd6) [==========] Running 22 tests from 11 test suites. I 29.833s run_tests_on_device(01e0c5decc7f1cd6) [----------] Global test environment set-up. I 29.833s run_tests_on_device(01e0c5decc7f1cd6) [----------] 2 tests from AudioDecoderPcmUTest I 29.833s run_tests_on_device(01e0c5decc7f1cd6) [ RUN ] AudioDecoderPcmUTest.EncodeDecode I 29.833s run_tests_on_device(01e0c5decc7f1cd6) [ OK ] AudioDecoderPcmUTest.EncodeDecode (1 ms) I 29.833s run_tests_on_device(01e0c5decc7f1cd6) [ RUN ] AudioDecoderPcmUTest.SetTargetBitrate I 29.833s run_tests_on_device(01e0c5decc7f1cd6) [ OK ] AudioDecoderPcmUTest.SetTargetBitrate (0 ms) I 29.834s run_tests_on_device(01e0c5decc7f1cd6) [----------] 2 tests from AudioDecoderPcmUTest (2 ms total) I 29.834s run_tests_on_device(01e0c5decc7f1cd6) I 29.834s run_tests_on_device(01e0c5decc7f1cd6) [----------] 2 tests from AudioDecoderPcmATest I 29.834s run_tests_on_device(01e0c5decc7f1cd6) [ RUN ] AudioDecoderPcmATest.EncodeDecode I 29.834s run_tests_on_device(01e0c5decc7f1cd6) [ OK ] AudioDecoderPcmATest.EncodeDecode (1 ms) I 29.834s run_tests_on_device(01e0c5decc7f1cd6) [ RUN ] AudioDecoderPcmATest.SetTargetBitrate I 29.834s run_tests_on_device(01e0c5decc7f1cd6) [ OK ] AudioDecoderPcmATest.SetTargetBitrate (0 ms) I 29.834s run_tests_on_device(01e0c5decc7f1cd6) [----------] 2 tests from AudioDecoderPcmATest (1 ms total) I 29.834s run_tests_on_device(01e0c5decc7f1cd6) I 29.834s run_tests_on_device(01e0c5decc7f1cd6) [----------] 2 tests from AudioDecoderPcm16BTest I 29.834s run_tests_on_device(01e0c5decc7f1cd6) [ RUN ] AudioDecoderPcm16BTest.EncodeDecode I 29.834s run_tests_on_device(01e0c5decc7f1cd6) [ OK ] AudioDecoderPcm16BTest.EncodeDecode (1 ms) I 29.834s run_tests_on_device(01e0c5decc7f1cd6) [ RUN ] AudioDecoderPcm16BTest.SetTargetBitrate I 29.834s run_tests_on_device(01e0c5decc7f1cd6) [ OK ] AudioDecoderPcm16BTest.SetTargetBitrate (1 ms) I 29.835s run_tests_on_device(01e0c5decc7f1cd6) [----------] 2 tests from AudioDecoderPcm16BTest (2 ms total) I 29.835s run_tests_on_device(01e0c5decc7f1cd6) I 29.835s run_tests_on_device(01e0c5decc7f1cd6) [----------] 2 tests from AudioDecoderIlbcTest I 29.835s run_tests_on_device(01e0c5decc7f1cd6) [ RUN ] AudioDecoderIlbcTest.EncodeDecode I 29.835s run_tests_on_device(01e0c5decc7f1cd6) [ OK ] AudioDecoderIlbcTest.EncodeDecode (8 ms) I 29.835s run_tests_on_device(01e0c5decc7f1cd6) [ RUN ] AudioDecoderIlbcTest.SetTargetBitrate I 29.835s run_tests_on_device(01e0c5decc7f1cd6) [ OK ] AudioDecoderIlbcTest.SetTargetBitrate (0 ms) I 29.835s run_tests_on_device(01e0c5decc7f1cd6) [----------] 2 tests from AudioDecoderIlbcTest (9 ms total) I 29.835s run_tests_on_device(01e0c5decc7f1cd6) I 29.835s run_tests_on_device(01e0c5decc7f1cd6) [----------] 2 tests from AudioDecoderIsacFloatTest I 29.835s run_tests_on_device(01e0c5decc7f1cd6) [ RUN ] AudioDecoderIsacFloatTest.EncodeDecode I 29.835s run_tests_on_device(01e0c5decc7f1cd6) [ OK ] AudioDecoderIsacFloatTest.EncodeDecode (16 ms) I 29.835s run_tests_on_device(01e0c5decc7f1cd6) [ RUN ] AudioDecoderIsacFloatTest.SetTargetBitrate I 29.836s run_tests_on_device(01e0c5decc7f1cd6) [ OK ] AudioDecoderIsacFloatTest.SetTargetBitrate (0 ms) I 29.836s run_tests_on_device(01e0c5decc7f1cd6) [----------] 2 tests from AudioDecoderIsacFloatTest (17 ms total) I 29.836s run_tests_on_device(01e0c5decc7f1cd6) I 29.836s run_tests_on_device(01e0c5decc7f1cd6) [----------] 2 tests from AudioDecoderIsacSwbTest I 29.836s run_tests_on_device(01e0c5decc7f1cd6) [ RUN ] AudioDecoderIsacSwbTest.EncodeDecode I 29.836s run_tests_on_device(01e0c5decc7f1cd6) [ OK ] AudioDecoderIsacSwbTest.EncodeDecode (17 ms) I 29.836s run_tests_on_device(01e0c5decc7f1cd6) [ RUN ] AudioDecoderIsacSwbTest.SetTargetBitrate I 29.836s run_tests_on_device(01e0c5decc7f1cd6) [ OK ] AudioDecoderIsacSwbTest.SetTargetBitrate (1 ms) I 29.836s run_tests_on_device(01e0c5decc7f1cd6) [----------] 2 tests from AudioDecoderIsacSwbTest (18 ms total) I 29.836s run_tests_on_device(01e0c5decc7f1cd6) I 29.836s run_tests_on_device(01e0c5decc7f1cd6) [----------] 2 tests from AudioDecoderIsacFixTest I 29.836s run_tests_on_device(01e0c5decc7f1cd6) [ RUN ] AudioDecoderIsacFixTest.EncodeDecode I 29.836s run_tests_on_device(01e0c5decc7f1cd6) [ OK ] AudioDecoderIsacFixTest.EncodeDecode (6 ms) I 29.837s run_tests_on_device(01e0c5decc7f1cd6) [ RUN ] AudioDecoderIsacFixTest.SetTargetBitrate I 29.837s run_tests_on_device(01e0c5decc7f1cd6) [ OK ] AudioDecoderIsacFixTest.SetTargetBitrate (0 ms) I 29.837s run_tests_on_device(01e0c5decc7f1cd6) [----------] 2 tests from AudioDecoderIsacFixTest (7 ms total) I 29.837s run_tests_on_device(01e0c5decc7f1cd6) I 29.837s run_tests_on_device(01e0c5decc7f1cd6) [----------] 2 tests from AudioDecoderG722Test I 29.837s run_tests_on_device(01e0c5decc7f1cd6) [ RUN ] AudioDecoderG722Test.EncodeDecode I 29.837s run_tests_on_device(01e0c5decc7f1cd6) [ OK ] AudioDecoderG722Test.EncodeDecode (2 ms) I 29.837s run_tests_on_device(01e0c5decc7f1cd6) [ RUN ] AudioDecoderG722Test.SetTargetBitrate I 29.837s run_tests_on_device(01e0c5decc7f1cd6) [ OK ] AudioDecoderG722Test.SetTargetBitrate (0 ms) I 29.837s run_tests_on_device(01e0c5decc7f1cd6) [----------] 2 tests from AudioDecoderG722Test (2 ms total) I 29.837s run_tests_on_device(01e0c5decc7f1cd6) I 29.837s run_tests_on_device(01e0c5decc7f1cd6) [----------] 2 tests from AudioDecoderG722StereoTest I 29.837s run_tests_on_device(01e0c5decc7f1cd6) [ RUN ] AudioDecoderG722StereoTest.EncodeDecode I 29.837s run_tests_on_device(01e0c5decc7f1cd6) [ OK ] AudioDecoderG722StereoTest.EncodeDecode (2 ms) I 29.837s run_tests_on_device(01e0c5decc7f1cd6) [ RUN ] AudioDecoderG722StereoTest.SetTargetBitrate I 29.838s run_tests_on_device(01e0c5decc7f1cd6) [ OK ] AudioDecoderG722StereoTest.SetTargetBitrate (0 ms) I 29.838s run_tests_on_device(01e0c5decc7f1cd6) [----------] 2 tests from AudioDecoderG722StereoTest (3 ms total) I 29.838s run_tests_on_device(01e0c5decc7f1cd6) I 29.838s run_tests_on_device(01e0c5decc7f1cd6) [----------] 2 tests from AudioDecoderOpusTest I 29.838s run_tests_on_device(01e0c5decc7f1cd6) [ RUN ] AudioDecoderOpusTest.EncodeDecode I 29.838s run_tests_on_device(01e0c5decc7f1cd6) [ OK ] AudioDecoderOpusTest.EncodeDecode (7 ms) I 29.838s run_tests_on_device(01e0c5decc7f1cd6) [ RUN ] AudioDecoderOpusTest.SetTargetBitrate I 29.838s run_tests_on_device(01e0c5decc7f1cd6) [ OK ] AudioDecoderOpusTest.SetTargetBitrate (1 ms) I 29.838s run_tests_on_device(01e0c5decc7f1cd6) [----------] 2 tests from AudioDecoderOpusTest (8 ms total) I 29.838s run_tests_on_device(01e0c5decc7f1cd6) I 29.838s run_tests_on_device(01e0c5decc7f1cd6) [----------] 2 tests from AudioDecoderOpusStereoTest I 29.838s run_tests_on_device(01e0c5decc7f1cd6) [ RUN ] AudioDecoderOpusStereoTest.EncodeDecode I 29.838s run_tests_on_device(01e0c5decc7f1cd6) [ OK ] AudioDecoderOpusStereoTest.EncodeDecode (7 ms) I 29.838s run_tests_on_device(01e0c5decc7f1cd6) [ RUN ] AudioDecoderOpusStereoTest.SetTargetBitrate I 29.839s run_tests_on_device(01e0c5decc7f1cd6) [ OK ] AudioDecoderOpusStereoTest.SetTargetBitrate (2 ms) I 29.839s run_tests_on_device(01e0c5decc7f1cd6) [----------] 2 tests from AudioDecoderOpusStereoTest (9 ms total) I 29.839s run_tests_on_device(01e0c5decc7f1cd6) I 29.839s run_tests_on_device(01e0c5decc7f1cd6) [----------] Global test environment tear-down I 29.839s run_tests_on_device(01e0c5decc7f1cd6) [==========] 22 tests from 11 test suites ran. (80 ms total) I 29.839s run_tests_on_device(01e0c5decc7f1cd6) [ PASSED ] 22 tests. I 29.839s run_tests_on_device(01e0c5decc7f1cd6) <<ScopedMainEntryLogger I 29.844s run_tests_on_device(01e0c5decc7f1cd6) Finished running tests on this device. I 29.858s Main FINISHED TRY #1/3 I 29.858s Main All tests completed. C 29.859s Main ******************************************************************************** C 29.859s Main Summary C 29.859s Main ******************************************************************************** C 29.860s Main [==========] 22 tests ran. C 29.860s Main [ PASSED ] 22 tests. C 29.860s Main ******************************************************************************** I 29.862s tear_down_device(01e0c5decc7f1cd6) Wrote device cache: /b/swarming/w/ir/out/Android32_M_Nexus5X_dbg/device_cache_01e0c5decc7f1cd6.json I 29.981s Main Generated json results file at /b/swarming/w/ittCIDU4/tmphT31sA I 29.981s Main Finishing archiving output. [W2019-05-21T19:50:28.944982Z 28089 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-21T19:50:28.996766Z 28089 0 main.go:205] Butler terminated with error. {"error":"context canceled"} [E2019-05-21T19:50:28.996849Z 28089 0 main.go:233] Failed to serve. original error: context canceled [I2019-05-21T19:50:28.997038Z 28089 0 main.go:349] Terminating. {"returnCode":250} INFO:devil.utils.timeout_retry:condition 'logdog_stopped' met 2019-05-21 12:58:11,530 - root: [DEBUG] Contents of task_output_dir: ['/b/s/w/ir/tmp/t/tmp4LZV7B/44f4c2b55f30df10'] 2019-05-21 12:58:11,531 - root: [DEBUG] Found shard_json_files: ['/b/s/w/ir/tmp/t/tmp4LZV7B/44f4c2b55f30df10/output.json'] 2019-05-21 12:58:11,531 - 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/tmpV_bB2Z.json --task-output-dir /b/s/w/ir/tmp/t/tmp4LZV7B -o /b/s/w/ir/tmp/t/tmpIxxZWP.json /b/s/w/ir/tmp/t/tmp4LZV7B/44f4c2b55f30df10/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/tmpV_bB2Z.json', '--task-output-dir', '/b/s/w/ir/tmp/t/tmp4LZV7B', '-o', '/b/s/w/ir/tmp/t/tmpIxxZWP.json', '/b/s/w/ir/tmp/t/tmp4LZV7B/44f4c2b55f30df10/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/tmpV_bB2Z.json', '--task-output-dir', '/b/s/w/ir/tmp/t/tmp4LZV7B', '-o', '/b/s/w/ir/tmp/t/tmpIxxZWP.json', '/b/s/w/ir/tmp/t/tmp4LZV7B/44f4c2b55f30df10/output.json'] returned exit code 0