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-14 18:55:52,518 - root: [DEBUG] Using task_output_dir: '/b/s/w/ir/tmp/t/tmpIWvjoN'
2019-05-14 18:55:52,519 - root: [WARNING] task_output_dir '/b/s/w/ir/tmp/t/tmpIWvjoN' already exists!
2019-05-14 18:55:52,519 - root: [WARNING] task_output_dir existing content: []
2019-05-14 18:55:52,519 - 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/tmpAXwwW0.json -output-dir /b/s/w/ir/tmp/t/tmpIWvjoN -task-summary-json /b/s/w/ir/tmp/t/tmpcO7UJV.json
[D2019-05-14T18:55:52.543322-07:00 8498 0 auth.go:1265] Minting a new token                         {"key":"luci_ctx/2c261670ca0a062b1e2d20679c567c57327ea9ab7dd3fd4acf95466baa9c192f", "scopes":"https://www.googleapis.com/auth/userinfo.email"}
[D2019-05-14T18:55:52.543410-07:00 8498 0 luci_ctx.go:138] POST http://127.0.0.1:43173/rpc/LuciLocalAuthService.GetOAuthToken {"key":"luci_ctx/2c261670ca0a062b1e2d20679c567c57327ea9ab7dd3fd4acf95466baa9c192f", "scopes":"https://www.googleapis.com/auth/userinfo.email"}
[D2019-05-14T18:55:52.546797-07:00 8498 0 auth.go:1222] Token expires in 15m1.453254189s            {"key":"luci_ctx/2c261670ca0a062b1e2d20679c567c57327ea9ab7dd3fd4acf95466baa9c192f", "scopes":"https://www.googleapis.com/auth/userinfo.email"}
44d1f82737fdc310: 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/stats/rtc_stats_unittests__test_runner_script.runtime_deps --suite rtc_stats_unittests -v --logcat-output-file /b/swarming/w/iosmD1fl/logcats --store-tombstones --test-launcher-summary-output=/b/swarming/w/iosmD1fl/output.json
I    0.007s Main  condition '<lambda>' met
I    0.008s Main  condition '<lambda>' met
I    3.161s TimeoutThread-1-for-prepare_device(01e8c25790a49365)  condition 'sd_card_ready' met (0.0s)
I    3.755s TimeoutThread-1-for-prepare_device(01e8c25790a49365)  condition 'pm_ready' met (0.6s)
I    3.788s TimeoutThread-1-for-prepare_device(01e8c25790a49365)  condition 'boot_completed' met (0.7s)
I   14.183s TimeoutThread-1-for-individual_device_set_up(01e8c25790a49365)  Setting permissions for org.webrtc.native_test.
I   17.444s calculate_device_checksums  condition '<lambda>' met (0.1s)
I   19.650s list_tests(01e8c25790a49365)  flags:
I   19.650s list_tests(01e8c25790a49365)    --gtest_list_tests
I   19.662s TimeoutThread-1-for-list_tests(01e8c25790a49365)  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-67f3fb802429d.gtest_out -e org.chromium.native_test.NativeTest.CommandLineFlags --gtest_list_tests "$p"/org.chromium.native_test.NativeTestInstrumentationTestRunner )>/ ...
I   21.786s list_tests(01e8c25790a49365)  /sdcard/temp_file-67f3fb802429d.gtest_out size on device: 258
I   21.855s Main  Using external sharding settings. This is shard 0/1
I   21.855s Main  STARTING TRY #1/3
I   21.855s Main  Will run 10 tests on 1 devices: 01e8c25790a49365
I   24.518s run_tests_on_device(01e8c25790a49365)  flags:
I   24.519s run_tests_on_device(01e8c25790a49365)  Getting logdog client.
I   24.624s TimeoutThread-1-for-run_tests_on_device(01e8c25790a49365)  Large shell command will be run from file: ( p=org.webrtc.native_test;am instrument -w -e org.chromium.native_test.NativeTestInstrumentationTestRunner.ShardNanoTimeout 120000000000 -e org.chromium.native_test.NativeTestInstrumentationTestRunner.NativeTestActivity "$p".RTCNativeUnitTestActivity -e org.chromium.native_test.NativeTestInstrumentationTestRunner.TestList /data/local/tmp/temp_file-572120721fd4d -e org.chromium.native_test.NativeTestInstrumentationTestRunner.StdoutFile /sdcard/temp_file-596ffe503b5ad.gtest_out "$p"/org.chromium.native_test. ...
I   26.015s run_tests_on_device(01e8c25790a49365)  /sdcard/temp_file-596ffe503b5ad.gtest_out size on device: 2053
I   26.195s archive  Writing text to logdog stream, logcat_logcat_3319692404600788449_20190515T014156-UTC_01e8c25790a49365
I   26.195s run_tests_on_device(01e8c25790a49365)  Logcat saved to https://luci-logdog.appspot.com/v/?s=chromium%2Fandroid%2Fswarming%2Flogcats%2F44d1f82737fdc311%2F%2B%2Flogcat_logcat_3319692404600788449_20190515T014156-UTC_01e8c25790a49365
I   26.813s TimeoutThread-1-for-run_tests_on_device(01e8c25790a49365)  Setting permissions for org.webrtc.native_test.
I   29.404s run_tests_on_device(01e8c25790a49365)  >>ScopedMainEntryLogger
I   29.404s run_tests_on_device(01e8c25790a49365)  Note: Google Test filter = RTCStatsReport.AddAndGetStats:RTCStatsReport.StatsOrder:RTCStatsReport.Take:RTCStatsReport.TakeMembersFrom:RTCStatsTest.EqualityOperator:RTCStatsTest.IsStandardized:RTCStatsTest.NonStandardGroupId:RTCStatsTest.RTCStatsAndMembers:RTCStatsTest.RTCStatsGrandChild:RTCStatsTest.RTCStatsPrintsValidJson
I   29.404s run_tests_on_device(01e8c25790a49365)  [==========] Running 10 tests from 2 test suites.
I   29.404s run_tests_on_device(01e8c25790a49365)  [----------] Global test environment set-up.
I   29.404s run_tests_on_device(01e8c25790a49365)  [----------] 4 tests from RTCStatsReport
I   29.404s run_tests_on_device(01e8c25790a49365)  [ RUN      ] RTCStatsReport.AddAndGetStats
I   29.404s run_tests_on_device(01e8c25790a49365)  [       OK ] RTCStatsReport.AddAndGetStats (0 ms)
I   29.404s run_tests_on_device(01e8c25790a49365)  [ RUN      ] RTCStatsReport.StatsOrder
I   29.404s run_tests_on_device(01e8c25790a49365)  [       OK ] RTCStatsReport.StatsOrder (0 ms)
I   29.405s run_tests_on_device(01e8c25790a49365)  [ RUN      ] RTCStatsReport.Take
I   29.405s run_tests_on_device(01e8c25790a49365)  [       OK ] RTCStatsReport.Take (0 ms)
I   29.405s run_tests_on_device(01e8c25790a49365)  [ RUN      ] RTCStatsReport.TakeMembersFrom
I   29.405s run_tests_on_device(01e8c25790a49365)  [       OK ] RTCStatsReport.TakeMembersFrom (0 ms)
I   29.405s run_tests_on_device(01e8c25790a49365)  [----------] 4 tests from RTCStatsReport (2 ms total)
I   29.405s run_tests_on_device(01e8c25790a49365)  
I   29.405s run_tests_on_device(01e8c25790a49365)  [----------] 6 tests from RTCStatsTest
I   29.405s run_tests_on_device(01e8c25790a49365)  [ RUN      ] RTCStatsTest.RTCStatsAndMembers
I   29.405s run_tests_on_device(01e8c25790a49365)  [       OK ] RTCStatsTest.RTCStatsAndMembers (0 ms)
I   29.405s run_tests_on_device(01e8c25790a49365)  [ RUN      ] RTCStatsTest.EqualityOperator
I   29.405s run_tests_on_device(01e8c25790a49365)  [       OK ] RTCStatsTest.EqualityOperator (0 ms)
I   29.405s run_tests_on_device(01e8c25790a49365)  [ RUN      ] RTCStatsTest.RTCStatsGrandChild
I   29.405s run_tests_on_device(01e8c25790a49365)  [       OK ] RTCStatsTest.RTCStatsGrandChild (0 ms)
I   29.405s run_tests_on_device(01e8c25790a49365)  [ RUN      ] RTCStatsTest.RTCStatsPrintsValidJson
I   29.405s run_tests_on_device(01e8c25790a49365)  {"type":"test-stats","id":"statsId","timestamp":42,"mBool":true,"mInt32":123,"mInt64":1.234567890123457e+18,"mDouble":123.4567890123457,"mString":"123","mSequenceBool":[],"mSequenceInt32":[1],"mSequenceInt64":[-1.234567890123457e+18,1,1.234567890123457e+18],"mSequenceDouble":[123.4567890123457,1234567890123.457],"mSequenceString":["four"]}
I   29.405s run_tests_on_device(01e8c25790a49365)  [       OK ] RTCStatsTest.RTCStatsPrintsValidJson (1 ms)
I   29.405s run_tests_on_device(01e8c25790a49365)  [ RUN      ] RTCStatsTest.IsStandardized
I   29.405s run_tests_on_device(01e8c25790a49365)  [       OK ] RTCStatsTest.IsStandardized (0 ms)
I   29.405s run_tests_on_device(01e8c25790a49365)  [ RUN      ] RTCStatsTest.NonStandardGroupId
I   29.405s run_tests_on_device(01e8c25790a49365)  [       OK ] RTCStatsTest.NonStandardGroupId (0 ms)
I   29.405s run_tests_on_device(01e8c25790a49365)  [----------] 6 tests from RTCStatsTest (2 ms total)
I   29.405s run_tests_on_device(01e8c25790a49365)  
I   29.405s run_tests_on_device(01e8c25790a49365)  [----------] Global test environment tear-down
I   29.405s run_tests_on_device(01e8c25790a49365)  [==========] 10 tests from 2 test suites ran. (5 ms total)
I   29.405s run_tests_on_device(01e8c25790a49365)  [  PASSED  ] 10 tests.
I   29.405s run_tests_on_device(01e8c25790a49365)  <<ScopedMainEntryLogger
I   29.406s run_tests_on_device(01e8c25790a49365)  Finished running tests on this device.
I   29.433s Main  FINISHED TRY #1/3
I   29.433s Main  All tests completed.
C   29.433s Main  ********************************************************************************
C   29.433s Main  Summary
C   29.433s Main  ********************************************************************************
C   29.433s Main  [==========] 10 tests ran.
C   29.433s Main  [  PASSED  ] 10 tests.
C   29.433s Main  ********************************************************************************
I   29.434s tear_down_device(01e8c25790a49365)  Wrote device cache: /b/swarming/w/ir/out/Android32_M_Nexus5X_dbg/device_cache_01e8c25790a49365.json
I   29.468s Main  Generated json results file at /b/swarming/w/itlG3F5M/tmpWA6D9y
I   29.468s Main  Finishing archiving output.
[W2019-05-15T01:42:03.300237Z 14719 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-15T01:42:03.343919Z 14719 0 main.go:205] Butler terminated with error.               {"error":"context canceled"}
[E2019-05-15T01:42:03.343996Z 14719 0 main.go:233] Failed to serve.
original error: context canceled

[I2019-05-15T01:42:03.344127Z 14719 0 main.go:349] Terminating.                                {"returnCode":250}
INFO:devil.utils.timeout_retry:condition 'logdog_stopped' met

2019-05-14 18:55:53,203 - root: [DEBUG] Contents of task_output_dir: ['/b/s/w/ir/tmp/t/tmpIWvjoN/44d1f82737fdc310']
2019-05-14 18:55:53,203 - root: [DEBUG] Found shard_json_files: ['/b/s/w/ir/tmp/t/tmpIWvjoN/44d1f82737fdc310/output.json']
2019-05-14 18:55:53,204 - 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/tmpcO7UJV.json --task-output-dir /b/s/w/ir/tmp/t/tmpIWvjoN -o /b/s/w/ir/tmp/t/tmpnYu8H0.json /b/s/w/ir/tmp/t/tmpIWvjoN/44d1f82737fdc310/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/tmpcO7UJV.json', '--task-output-dir', '/b/s/w/ir/tmp/t/tmpIWvjoN', '-o', '/b/s/w/ir/tmp/t/tmpnYu8H0.json', '/b/s/w/ir/tmp/t/tmpIWvjoN/44d1f82737fdc310/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/tmpcO7UJV.json', '--task-output-dir', '/b/s/w/ir/tmp/t/tmpIWvjoN', '-o', '/b/s/w/ir/tmp/t/tmpnYu8H0.json', '/b/s/w/ir/tmp/t/tmpIWvjoN/44d1f82737fdc310/output.json'] returned exit code 0