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

python -u /b/s/w/ir/kitchen-checkout/build/scripts/tools/runit.py --show-path --with-third-party-lib -- python /b/s/w/ir/kitchen-checkout/build/scripts/slave/recipe_modules/chromium_swarming/resources/collect_task.py --verbose -o /b/s/w/ir/tmp/t/tmpsbSyw9.json --task-output-dir /b/s/w/ir/tmp/t/tmpivTh2i --merge-script /b/s/w/ir/cache/builder/src/testing/merge_scripts/standard_gtest_merge.py --merge-script-stdout-file /b/s/w/ir/tmp/t/tmpWOAuXBmerge_script_log --merge-additional-args '[]' --summary-json-file /b/s/w/ir/tmp/t/tmpJMgwgl.json -- 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/tmpe61d2Y.json in dir /b/s/w/ir/k: allow_subannotations: True cmd: ['python', '-u', '/b/s/w/ir/kitchen-checkout/build/scripts/tools/runit.py', '--show-path', '--with-third-party-lib', '--', 'python', '/b/s/w/ir/kitchen-checkout/build/scripts/slave/recipe_modules/chromium_swarming/resources/collect_task.py', '--verbose', '-o', '/b/s/w/ir/tmp/t/tmpsbSyw9.json', '--task-output-dir', '/b/s/w/ir/tmp/t/tmpivTh2i', '--merge-script', '/b/s/w/ir/cache/builder/src/testing/merge_scripts/standard_gtest_merge.py', '--merge-script-stdout-file', '/b/s/w/ir/tmp/t/tmpWOAuXBmerge_script_log', '--merge-additional-args', '[]', '--summary-json-file', '/b/s/w/ir/tmp/t/tmpJMgwgl.json', '--', '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/tmpe61d2Y.json'] env: {} env_prefixes: {'PATH': '/b/s/w/ir/cache/swarming_client'} env_suffixes: {} infra_step: False name_tokens: ('webrtc_nonparallel_tests on Android',) ok_ret: ALL_OK step_test_data: gen_default_step_test_data(...) trigger_specs: () full environment: BOTO_CONFIG: /b/s/w/ir/tmp/gsutil_task/.boto BUILDBUCKET_EXPERIMENTAL: FALSE CIPD_CACHE_DIR: /b/s/cipd_cache/cache CIPD_PROTOCOL: v2 DEVSHELL_CLIENT_PORT: 34748 DOCKER_CONFIG: /b/s/w/ir/tmp/docker_cfg_task DOCKER_TMPDIR: /b/s/w/ir/tmp/docker_tmp_task GIT_CONFIG_NOSYSTEM: 1 GIT_TERMINAL_PROMPT: 0 HOME: /home/chrome-bot INFRA_GIT_WRAPPER_HOME: /b/s/w/ir/tmp/git_home_task LOGDOG_COORDINATOR_HOST: logs.chromium.org LOGDOG_STREAM_PREFIX: buildbucket/cr-buildbucket.appspot.com/8915922320902921312 LOGDOG_STREAM_PROJECT: webrtc LOGDOG_STREAM_SERVER_PATH: unix:/b/s/w/ir/tmp/ld.sock LOGNAME: chrome-bot LUCI_CONTEXT: /b/s/w/itLFo6zZ/luci_context.295036201 MAC_CHROMIUM_TMPDIR: /b/s/w/ir/tmp/t MAIL: /var/mail/chrome-bot NO_GCE_CHECK: False PATH: /b/s/w/ir/cache/swarming_client:/b/s/w/ir/cipd_bin_packages:/b/s/w/ir/cipd_bin_packages/bin:/b/s/cipd_cache/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/snap/bin PWD: /b/s/w/ir/k PYTHONIOENCODING: UTF-8 PYTHONUNBUFFERED: 1 SHELL: /bin/bash SHLVL: 1 SUDO_COMMAND: /usr/bin/python /b/s/swarming_bot.zip start_bot SUDO_GID: 0 SUDO_UID: 0 SUDO_USER: root SWARMING_BOT_ID: luci-webrtc-ci-trusty-9-v9vq SWARMING_EXTERNAL_BOT_SETUP: 1 SWARMING_HEADLESS: 1 SWARMING_SERVER: https://chromium-swarm.appspot.com SWARMING_TASK_ID: 44447e385ab14011 TEMP: /b/s/w/ir/tmp/t TEMPDIR: /b/s/w/ir/tmp/t TERM: linux TMP: /b/s/w/ir/tmp/t TMPDIR: /b/s/w/ir/tmp/t USER: chrome-bot USERNAME: chrome-bot VPYTHON_VIRTUALENV_ROOT: /b/s/w/ir/cache/vpython _: /b/s/w/ir/cipd_bin_packages/vpython 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-04-17 07:39:20,123 - root: [DEBUG] Using task_output_dir: '/b/s/w/ir/tmp/t/tmpivTh2i' 2019-04-17 07:39:20,123 - root: [WARNING] task_output_dir '/b/s/w/ir/tmp/t/tmpivTh2i' already exists! 2019-04-17 07:39:20,123 - root: [WARNING] task_output_dir existing content: [] 2019-04-17 07:39:20,123 - 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/tmpe61d2Y.json -output-dir /b/s/w/ir/tmp/t/tmpivTh2i -task-summary-json /b/s/w/ir/tmp/t/tmpJMgwgl.json [D2019-04-17T07:39:20.129010-07:00 19378 0 auth.go:1265] Minting a new token {"key":"luci_ctx/ecd36c929f673d0e6df14988c1bd3f1e674adb723adaca4f572343047f9456f1", "scopes":"https://www.googleapis.com/auth/userinfo.email"} [D2019-04-17T07:39:20.129062-07:00 19378 0 luci_ctx.go:138] POST http://127.0.0.1:40209/rpc/LuciLocalAuthService.GetOAuthToken {"key":"luci_ctx/ecd36c929f673d0e6df14988c1bd3f1e674adb723adaca4f572343047f9456f1", "scopes":"https://www.googleapis.com/auth/userinfo.email"} [D2019-04-17T07:39:20.131339-07:00 19378 0 auth.go:1222] Token expires in 10m26.868702989s {"key":"luci_ctx/ecd36c929f673d0e6df14988c1bd3f1e674adb723adaca4f572343047f9456f1", "scopes":"https://www.googleapis.com/auth/userinfo.email"} 44448617bfcc9a10: exit 0 I 0.000s Main command: bin/../../../build/android/test_runner.py gtest --output-directory bin/../. --runtime-deps-path bin/../gen.runtime/webrtc_nonparallel_tests__test_runner_script.runtime_deps --suite webrtc_nonparallel_tests --shard-timeout=900 -v --logcat-output-file /b/swarming/w/ioc0wTgL/logcats --store-tombstones --test-launcher-summary-output=/b/swarming/w/ioc0wTgL/output.json I 0.006s Main condition '<lambda>' met I 0.008s Main condition '<lambda>' met I 3.166s TimeoutThread-1-for-prepare_device(01dca4b3a6a55779) condition 'sd_card_ready' met (0.0s) I 3.737s TimeoutThread-1-for-prepare_device(01dca4b3a6a55779) condition 'pm_ready' met (0.6s) I 3.795s TimeoutThread-1-for-prepare_device(01dca4b3a6a55779) condition 'boot_completed' met (0.7s) I 13.372s TimeoutThread-1-for-individual_device_set_up(01dca4b3a6a55779) Setting permissions for org.webrtc.native_test. I 16.048s calculate_device_checksums condition '<lambda>' met (0.1s) I 18.048s list_tests(01dca4b3a6a55779) flags: I 18.048s list_tests(01dca4b3a6a55779) --gtest_list_tests I 18.060s TimeoutThread-1-for-list_tests(01dca4b3a6a55779) 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-d51cf6f2076d1.gtest_out -e org.chromium.native_test.NativeTest.CommandLineFlags --gtest_list_tests "$p"/org.chromium.native_test.NativeTestInstrumentationTestRunner )>/ ... I 19.581s list_tests(01dca4b3a6a55779) /sdcard/temp_file-d51cf6f2076d1.gtest_out size on device: 2149 I 19.652s Main Using external sharding settings. This is shard 0/1 I 19.653s Main STARTING TRY #1/3 I 19.653s Main Will run 65 tests on 1 devices: 01dca4b3a6a55779 W 19.721s run_tests_on_device(01dca4b3a6a55779) No tombstones to clear. I 23.191s run_tests_on_device(01dca4b3a6a55779) flags: I 23.191s run_tests_on_device(01dca4b3a6a55779) Getting logdog client. I 23.287s TimeoutThread-1-for-run_tests_on_device(01dca4b3a6a55779) 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-b5fd20f9b396c -e org.chromium.native_test.NativeTestInstrumentationTestRunner.StdoutFile /sdcard/temp_file-29fc42a205317.gtest_out "$p"/org.chromium.native_test. ... I 32.653s run_tests_on_device(01dca4b3a6a55779) /sdcard/temp_file-29fc42a205317.gtest_out size on device: 12173 I 32.885s run_tests_on_device(01dca4b3a6a55779) Logcat saved to https://luci-logdog.appspot.com/v/?s=chromium%2Fandroid%2Fswarming%2Flogcats%2F44448617bfcc9a11%2F%2B%2Flogcat_logcat_2598194053912766760_20190417T143050-UTC_01dca4b3a6a55779 I 32.886s archive Writing text to logdog stream, logcat_logcat_2598194053912766760_20190417T143050-UTC_01dca4b3a6a55779 I 33.476s TimeoutThread-1-for-run_tests_on_device(01dca4b3a6a55779) Setting permissions for org.webrtc.native_test. I 35.995s run_tests_on_device(01dca4b3a6a55779) >>ScopedMainEntryLogger I 35.995s run_tests_on_device(01dca4b3a6a55779) Note: Google Test filter = CpuTimeTest.BasicTest:CpuTimeTest.Sleeping:CpuTimeTest.TwoThreads:NullSocketServerTest.TestWait:NullSocketServerTest.WaitAndSet:PhysicalSocketTest.BindFailsIfNetworkBinderFailsForNonLoopbackInterface:PhysicalSocketTest.BindSucceedsIfNetworkBinderFailsForLoopbackInterface:PhysicalSocketTest.NetworkBinderIsNotUsedForAnyIp:PhysicalSocketTest.TestClientCloseDuringConnectIPv4:PhysicalSocketTest.TestClientCloseDuringConnectIPv6:PhysicalSocketTest.TestCloseInClosedCallbackIPv4:PhysicalSocketTest.TestCloseInClosedCallbackIPv6:PhysicalSocketTest.TestConnectAcceptErrorIPv4:PhysicalSocketTest.TestConnectAcceptErrorIPv6:PhysicalSocketTest.TestConnectFailIPv4:PhysicalSocketTest.TestConnectFailIPv6:PhysicalSocketTest.TestConnectIPv4:PhysicalSocketTest.TestConnectIPv6:PhysicalSocketTest.TestConnectWhileNotClosedIPv4:PhysicalSocketTest.TestConnectWhileNotClosedIPv6:PhysicalSocketTest.TestConnectWithClosedSocketIPv4:PhysicalSocketTest.TestConnectWithClosedSocketIPv6:PhysicalSocketTest.TestConnectWithDnsLookupFailIPv4:PhysicalSocketTest.TestConnectWithDnsLookupFailIPv6:PhysicalSocketTest.TestConnectWithDnsLookupIPv4:PhysicalSocketTest.TestConnectWithDnsLookupIPv6:PhysicalSocketTest.TestGetSetOptionsIPv4:PhysicalSocketTest.TestGetSetOptionsIPv6:PhysicalSocketTest.TestServerCloseDuringConnectIPv4:PhysicalSocketTest.TestServerCloseDuringConnectIPv6:PhysicalSocketTest.TestServerCloseIPv4:PhysicalSocketTest.TestServerCloseIPv6:PhysicalSocketTest.TestSocketRecvTimestampIPv4:PhysicalSocketTest.TestSocketRecvTimestampIPv6:PhysicalSocketTest.TestSocketServerWaitIPv4:PhysicalSocketTest.TestSocketServerWaitIPv6:PhysicalSocketTest.TestTcpIPv4:PhysicalSocketTest.TestTcpIPv6:PhysicalSocketTest.TestUdpIPv4:PhysicalSocketTest.TestUdpIPv6:PhysicalSocketTest.TestUdpReadyToSendIPv6:PhysicalSocketTest.TestWritableAfterPartialWriteIPv4:PhysicalSocketTest.TestWritableAfterPartialWriteIPv6:PosixSignalDeliveryTest.InsanelyManySignals:PosixSignalDeliveryTest.RaiseThenWait:PosixSignalDeliveryTest.SignalDuringWait:SocketAddressTest.TestAssign:SocketAddressTest.TestComparisonOperator:SocketAddressTest.TestCopyCtor:SocketAddressTest.TestDefaultCtor:SocketAddressTest.TestEqualityOperators:SocketAddressTest.TestFromHostname:SocketAddressTest.TestFromIPv4String:SocketAddressTest.TestFromIPv6String:SocketAddressTest.TestHostnamePortCtor:SocketAddressTest.TestIPPortCtor:SocketAddressTest.TestIPv4StringPortCtor:SocketAddressTest.TestIPv6StringPortCtor:SocketAddressTest.TestSetIPFromHostname:SocketAddressTest.TestSetIPFromString:SocketAddressTest.TestSetIPPort:SocketAddressTest.TestSpecialStringPortCtor:SocketAddressTest.TestToFromSockAddr:SocketAddressTest.TestToFromSockAddrStorage:SocketAddressTest.TestToSensitiveString I 35.997s run_tests_on_device(01dca4b3a6a55779) [==========] Running 65 tests from 5 test suites. I 35.997s run_tests_on_device(01dca4b3a6a55779) [----------] Global test environment set-up. I 35.997s run_tests_on_device(01dca4b3a6a55779) [----------] 3 tests from CpuTimeTest I 35.997s run_tests_on_device(01dca4b3a6a55779) [ RUN ] CpuTimeTest.BasicTest I 35.997s run_tests_on_device(01dca4b3a6a55779) [ OK ] CpuTimeTest.BasicTest (0 ms) I 35.997s run_tests_on_device(01dca4b3a6a55779) [ RUN ] CpuTimeTest.TwoThreads I 35.997s run_tests_on_device(01dca4b3a6a55779) [ OK ] CpuTimeTest.TwoThreads (303 ms) I 35.997s run_tests_on_device(01dca4b3a6a55779) [ RUN ] CpuTimeTest.Sleeping I 35.997s run_tests_on_device(01dca4b3a6a55779) [ OK ] CpuTimeTest.Sleeping (300 ms) I 35.997s run_tests_on_device(01dca4b3a6a55779) [----------] 3 tests from CpuTimeTest (604 ms total) I 35.997s run_tests_on_device(01dca4b3a6a55779) I 35.997s run_tests_on_device(01dca4b3a6a55779) [----------] 2 tests from NullSocketServerTest I 35.997s run_tests_on_device(01dca4b3a6a55779) [ RUN ] NullSocketServerTest.WaitAndSet I 35.997s run_tests_on_device(01dca4b3a6a55779) [ OK ] NullSocketServerTest.WaitAndSet (1 ms) I 35.997s run_tests_on_device(01dca4b3a6a55779) [ RUN ] NullSocketServerTest.TestWait I 35.997s run_tests_on_device(01dca4b3a6a55779) [ OK ] NullSocketServerTest.TestWait (201 ms) I 35.998s run_tests_on_device(01dca4b3a6a55779) [----------] 2 tests from NullSocketServerTest (202 ms total) I 35.998s run_tests_on_device(01dca4b3a6a55779) I 35.998s run_tests_on_device(01dca4b3a6a55779) [----------] 38 tests from PhysicalSocketTest I 35.998s run_tests_on_device(01dca4b3a6a55779) [ RUN ] PhysicalSocketTest.TestConnectIPv4 I 35.998s run_tests_on_device(01dca4b3a6a55779) [ OK ] PhysicalSocketTest.TestConnectIPv4 (10 ms) I 35.998s run_tests_on_device(01dca4b3a6a55779) [ RUN ] PhysicalSocketTest.TestConnectIPv6 I 35.998s run_tests_on_device(01dca4b3a6a55779) [ OK ] PhysicalSocketTest.TestConnectIPv6 (8 ms) I 35.998s run_tests_on_device(01dca4b3a6a55779) [ RUN ] PhysicalSocketTest.TestConnectWithDnsLookupIPv4 I 35.998s run_tests_on_device(01dca4b3a6a55779) [ OK ] PhysicalSocketTest.TestConnectWithDnsLookupIPv4 (20 ms) I 35.998s run_tests_on_device(01dca4b3a6a55779) [ RUN ] PhysicalSocketTest.TestConnectWithDnsLookupIPv6 I 35.998s run_tests_on_device(01dca4b3a6a55779) [000:000] (line 75): Skipping IPv6 DNS test I 35.998s run_tests_on_device(01dca4b3a6a55779) [ OK ] PhysicalSocketTest.TestConnectWithDnsLookupIPv6 (0 ms) I 35.998s run_tests_on_device(01dca4b3a6a55779) [ RUN ] PhysicalSocketTest.TestConnectFailIPv4 I 35.998s run_tests_on_device(01dca4b3a6a55779) [000:007] (line 170): GetRemoteAddress: unable to get remote addr, socket=31 I 35.998s run_tests_on_device(01dca4b3a6a55779) [ OK ] PhysicalSocketTest.TestConnectFailIPv4 (7 ms) I 35.998s run_tests_on_device(01dca4b3a6a55779) [ RUN ] PhysicalSocketTest.TestConnectAcceptErrorIPv4 I 35.998s run_tests_on_device(01dca4b3a6a55779) [ OK ] PhysicalSocketTest.TestConnectAcceptErrorIPv4 (10 ms) I 35.998s run_tests_on_device(01dca4b3a6a55779) [ RUN ] PhysicalSocketTest.TestConnectAcceptErrorIPv6 I 35.998s run_tests_on_device(01dca4b3a6a55779) [ OK ] PhysicalSocketTest.TestConnectAcceptErrorIPv6 (9 ms) I 35.998s run_tests_on_device(01dca4b3a6a55779) [ RUN ] PhysicalSocketTest.TestWritableAfterPartialWriteIPv4 I 35.998s run_tests_on_device(01dca4b3a6a55779) [ OK ] PhysicalSocketTest.TestWritableAfterPartialWriteIPv4 (1790 ms) I 35.998s run_tests_on_device(01dca4b3a6a55779) [ RUN ] PhysicalSocketTest.TestWritableAfterPartialWriteIPv6 I 35.999s run_tests_on_device(01dca4b3a6a55779) [ OK ] PhysicalSocketTest.TestWritableAfterPartialWriteIPv6 (1944 ms) I 35.999s run_tests_on_device(01dca4b3a6a55779) [ RUN ] PhysicalSocketTest.TestConnectFailIPv6 I 35.999s run_tests_on_device(01dca4b3a6a55779) [003:771] (line 170): GetRemoteAddress: unable to get remote addr, socket=31 I 35.999s run_tests_on_device(01dca4b3a6a55779) [ OK ] PhysicalSocketTest.TestConnectFailIPv6 (8 ms) I 35.999s run_tests_on_device(01dca4b3a6a55779) [ RUN ] PhysicalSocketTest.TestConnectWithDnsLookupFailIPv4 I 35.999s run_tests_on_device(01dca4b3a6a55779) [003:781] (line 170): GetRemoteAddress: unable to get remote addr, socket=-1 I 35.999s run_tests_on_device(01dca4b3a6a55779) [ OK ] PhysicalSocketTest.TestConnectWithDnsLookupFailIPv4 (9 ms) I 35.999s run_tests_on_device(01dca4b3a6a55779) [ RUN ] PhysicalSocketTest.TestConnectWithDnsLookupFailIPv6 I 35.999s run_tests_on_device(01dca4b3a6a55779) [003:790] (line 170): GetRemoteAddress: unable to get remote addr, socket=-1 I 35.999s run_tests_on_device(01dca4b3a6a55779) [ OK ] PhysicalSocketTest.TestConnectWithDnsLookupFailIPv6 (7 ms) I 35.999s run_tests_on_device(01dca4b3a6a55779) [ RUN ] PhysicalSocketTest.TestConnectWithClosedSocketIPv4 I 35.999s run_tests_on_device(01dca4b3a6a55779) [ OK ] PhysicalSocketTest.TestConnectWithClosedSocketIPv4 (4 ms) I 35.999s run_tests_on_device(01dca4b3a6a55779) [ RUN ] PhysicalSocketTest.TestConnectWithClosedSocketIPv6 I 35.999s run_tests_on_device(01dca4b3a6a55779) [ OK ] PhysicalSocketTest.TestConnectWithClosedSocketIPv6 (3 ms) I 35.999s run_tests_on_device(01dca4b3a6a55779) [ RUN ] PhysicalSocketTest.TestConnectWhileNotClosedIPv4 I 35.999s run_tests_on_device(01dca4b3a6a55779) [ OK ] PhysicalSocketTest.TestConnectWhileNotClosedIPv4 (5 ms) I 35.999s run_tests_on_device(01dca4b3a6a55779) [ RUN ] PhysicalSocketTest.TestConnectWhileNotClosedIPv6 I 35.999s run_tests_on_device(01dca4b3a6a55779) [ OK ] PhysicalSocketTest.TestConnectWhileNotClosedIPv6 (7 ms) I 36.000s run_tests_on_device(01dca4b3a6a55779) [ RUN ] PhysicalSocketTest.TestServerCloseDuringConnectIPv4 I 36.000s run_tests_on_device(01dca4b3a6a55779) [ OK ] PhysicalSocketTest.TestServerCloseDuringConnectIPv4 (5 ms) I 36.000s run_tests_on_device(01dca4b3a6a55779) [ RUN ] PhysicalSocketTest.TestServerCloseDuringConnectIPv6 I 36.000s run_tests_on_device(01dca4b3a6a55779) [ OK ] PhysicalSocketTest.TestServerCloseDuringConnectIPv6 (5 ms) I 36.000s run_tests_on_device(01dca4b3a6a55779) [ RUN ] PhysicalSocketTest.TestClientCloseDuringConnectIPv4 I 36.000s run_tests_on_device(01dca4b3a6a55779) [ OK ] PhysicalSocketTest.TestClientCloseDuringConnectIPv4 (6 ms) I 36.000s run_tests_on_device(01dca4b3a6a55779) [ RUN ] PhysicalSocketTest.TestClientCloseDuringConnectIPv6 I 36.000s run_tests_on_device(01dca4b3a6a55779) [ OK ] PhysicalSocketTest.TestClientCloseDuringConnectIPv6 (6 ms) I 36.000s run_tests_on_device(01dca4b3a6a55779) [ RUN ] PhysicalSocketTest.TestServerCloseIPv4 I 36.000s run_tests_on_device(01dca4b3a6a55779) [003:852] (line 170): GetRemoteAddress: unable to get remote addr, socket=-1 I 36.000s run_tests_on_device(01dca4b3a6a55779) [003:853] (line 170): GetRemoteAddress: unable to get remote addr, socket=-1 I 36.000s run_tests_on_device(01dca4b3a6a55779) [ OK ] PhysicalSocketTest.TestServerCloseIPv4 (9 ms) I 36.000s run_tests_on_device(01dca4b3a6a55779) [ RUN ] PhysicalSocketTest.TestServerCloseIPv6 I 36.000s run_tests_on_device(01dca4b3a6a55779) [003:861] (line 170): GetRemoteAddress: unable to get remote addr, socket=-1 I 36.000s run_tests_on_device(01dca4b3a6a55779) [003:862] (line 170): GetRemoteAddress: unable to get remote addr, socket=-1 I 36.000s run_tests_on_device(01dca4b3a6a55779) [ OK ] PhysicalSocketTest.TestServerCloseIPv6 (9 ms) I 36.000s run_tests_on_device(01dca4b3a6a55779) [ RUN ] PhysicalSocketTest.TestCloseInClosedCallbackIPv4 I 36.000s run_tests_on_device(01dca4b3a6a55779) [ OK ] PhysicalSocketTest.TestCloseInClosedCallbackIPv4 (5 ms) I 36.000s run_tests_on_device(01dca4b3a6a55779) [ RUN ] PhysicalSocketTest.TestCloseInClosedCallbackIPv6 I 36.000s run_tests_on_device(01dca4b3a6a55779) [ OK ] PhysicalSocketTest.TestCloseInClosedCallbackIPv6 (5 ms) I 36.000s run_tests_on_device(01dca4b3a6a55779) [ RUN ] PhysicalSocketTest.TestSocketServerWaitIPv4 I 36.000s run_tests_on_device(01dca4b3a6a55779) [004:379] (line 517): Message took 500ms to dispatch. Posted from: SocketServerWaitInternal@../../rtc_base/socket_unittest.cc:700 I 36.000s run_tests_on_device(01dca4b3a6a55779) [ OK ] PhysicalSocketTest.TestSocketServerWaitIPv4 (510 ms) I 36.000s run_tests_on_device(01dca4b3a6a55779) [ RUN ] PhysicalSocketTest.TestSocketServerWaitIPv6 I 36.000s run_tests_on_device(01dca4b3a6a55779) [004:896] (line 517): Message took 500ms to dispatch. Posted from: SocketServerWaitInternal@../../rtc_base/socket_unittest.cc:700 I 36.001s run_tests_on_device(01dca4b3a6a55779) [ OK ] PhysicalSocketTest.TestSocketServerWaitIPv6 (516 ms) I 36.001s run_tests_on_device(01dca4b3a6a55779) [ RUN ] PhysicalSocketTest.TestTcpIPv4 I 36.001s run_tests_on_device(01dca4b3a6a55779) [ OK ] PhysicalSocketTest.TestTcpIPv4 (141 ms) I 36.001s run_tests_on_device(01dca4b3a6a55779) [ RUN ] PhysicalSocketTest.TestTcpIPv6 I 36.001s run_tests_on_device(01dca4b3a6a55779) [ OK ] PhysicalSocketTest.TestTcpIPv6 (84 ms) I 36.001s run_tests_on_device(01dca4b3a6a55779) [ RUN ] PhysicalSocketTest.TestUdpIPv4 I 36.001s run_tests_on_device(01dca4b3a6a55779) [ OK ] PhysicalSocketTest.TestUdpIPv4 (32 ms) I 36.001s run_tests_on_device(01dca4b3a6a55779) [ RUN ] PhysicalSocketTest.TestUdpIPv6 I 36.001s run_tests_on_device(01dca4b3a6a55779) [ OK ] PhysicalSocketTest.TestUdpIPv6 (31 ms) I 36.001s run_tests_on_device(01dca4b3a6a55779) [ RUN ] PhysicalSocketTest.TestUdpReadyToSendIPv6 I 36.001s run_tests_on_device(01dca4b3a6a55779) [ OK ] PhysicalSocketTest.TestUdpReadyToSendIPv6 (0 ms) I 36.001s run_tests_on_device(01dca4b3a6a55779) [ RUN ] PhysicalSocketTest.TestGetSetOptionsIPv4 I 36.001s run_tests_on_device(01dca4b3a6a55779) [ OK ] PhysicalSocketTest.TestGetSetOptionsIPv4 (2 ms) I 36.001s run_tests_on_device(01dca4b3a6a55779) [ RUN ] PhysicalSocketTest.TestGetSetOptionsIPv6 I 36.001s run_tests_on_device(01dca4b3a6a55779) [ OK ] PhysicalSocketTest.TestGetSetOptionsIPv6 (2 ms) I 36.001s run_tests_on_device(01dca4b3a6a55779) [ RUN ] PhysicalSocketTest.TestSocketRecvTimestampIPv4 I 36.001s run_tests_on_device(01dca4b3a6a55779) [ OK ] PhysicalSocketTest.TestSocketRecvTimestampIPv4 (103 ms) I 36.001s run_tests_on_device(01dca4b3a6a55779) [ RUN ] PhysicalSocketTest.TestSocketRecvTimestampIPv6 I 36.001s run_tests_on_device(01dca4b3a6a55779) [ OK ] PhysicalSocketTest.TestSocketRecvTimestampIPv6 (104 ms) I 36.001s run_tests_on_device(01dca4b3a6a55779) [ RUN ] PhysicalSocketTest.BindFailsIfNetworkBinderFailsForNonLoopbackInterface I 36.001s run_tests_on_device(01dca4b3a6a55779) [005:406] (line 200): Binding socket to network address 192.168.0.1 failed; result: -1 I 36.001s run_tests_on_device(01dca4b3a6a55779) [ OK ] PhysicalSocketTest.BindFailsIfNetworkBinderFailsForNonLoopbackInterface (3 ms) I 36.001s run_tests_on_device(01dca4b3a6a55779) [ RUN ] PhysicalSocketTest.NetworkBinderIsNotUsedForAnyIp I 36.001s run_tests_on_device(01dca4b3a6a55779) [ OK ] PhysicalSocketTest.NetworkBinderIsNotUsedForAnyIp (1 ms) I 36.001s run_tests_on_device(01dca4b3a6a55779) [ RUN ] PhysicalSocketTest.BindSucceedsIfNetworkBinderFailsForLoopbackInterface I 36.001s run_tests_on_device(01dca4b3a6a55779) [ OK ] PhysicalSocketTest.BindSucceedsIfNetworkBinderFailsForLoopbackInterface (2 ms) I 36.002s run_tests_on_device(01dca4b3a6a55779) [----------] 38 tests from PhysicalSocketTest (5456 ms total) I 36.002s run_tests_on_device(01dca4b3a6a55779) I 36.002s run_tests_on_device(01dca4b3a6a55779) [----------] 3 tests from PosixSignalDeliveryTest I 36.002s run_tests_on_device(01dca4b3a6a55779) [ RUN ] PosixSignalDeliveryTest.RaiseThenWait I 36.002s run_tests_on_device(01dca4b3a6a55779) [ OK ] PosixSignalDeliveryTest.RaiseThenWait (0 ms) I 36.002s run_tests_on_device(01dca4b3a6a55779) [ RUN ] PosixSignalDeliveryTest.InsanelyManySignals I 36.002s run_tests_on_device(01dca4b3a6a55779) [ OK ] PosixSignalDeliveryTest.InsanelyManySignals (78 ms) I 36.002s run_tests_on_device(01dca4b3a6a55779) [ RUN ] PosixSignalDeliveryTest.SignalDuringWait I 36.002s run_tests_on_device(01dca4b3a6a55779) [ OK ] PosixSignalDeliveryTest.SignalDuringWait (1502 ms) I 36.002s run_tests_on_device(01dca4b3a6a55779) [----------] 3 tests from PosixSignalDeliveryTest (1583 ms total) I 36.002s run_tests_on_device(01dca4b3a6a55779) I 36.002s run_tests_on_device(01dca4b3a6a55779) [----------] 19 tests from SocketAddressTest I 36.002s run_tests_on_device(01dca4b3a6a55779) [ RUN ] SocketAddressTest.TestDefaultCtor I 36.002s run_tests_on_device(01dca4b3a6a55779) [ OK ] SocketAddressTest.TestDefaultCtor (0 ms) I 36.002s run_tests_on_device(01dca4b3a6a55779) [ RUN ] SocketAddressTest.TestIPPortCtor I 36.002s run_tests_on_device(01dca4b3a6a55779) [ OK ] SocketAddressTest.TestIPPortCtor (0 ms) I 36.002s run_tests_on_device(01dca4b3a6a55779) [ RUN ] SocketAddressTest.TestIPv4StringPortCtor I 36.002s run_tests_on_device(01dca4b3a6a55779) [ OK ] SocketAddressTest.TestIPv4StringPortCtor (0 ms) I 36.002s run_tests_on_device(01dca4b3a6a55779) [ RUN ] SocketAddressTest.TestIPv6StringPortCtor I 36.002s run_tests_on_device(01dca4b3a6a55779) [ OK ] SocketAddressTest.TestIPv6StringPortCtor (0 ms) I 36.002s run_tests_on_device(01dca4b3a6a55779) [ RUN ] SocketAddressTest.TestSpecialStringPortCtor I 36.002s run_tests_on_device(01dca4b3a6a55779) [ OK ] SocketAddressTest.TestSpecialStringPortCtor (0 ms) I 36.002s run_tests_on_device(01dca4b3a6a55779) [ RUN ] SocketAddressTest.TestHostnamePortCtor I 36.002s run_tests_on_device(01dca4b3a6a55779) [ OK ] SocketAddressTest.TestHostnamePortCtor (0 ms) I 36.002s run_tests_on_device(01dca4b3a6a55779) [ RUN ] SocketAddressTest.TestCopyCtor I 36.003s run_tests_on_device(01dca4b3a6a55779) [ OK ] SocketAddressTest.TestCopyCtor (0 ms) I 36.003s run_tests_on_device(01dca4b3a6a55779) [ RUN ] SocketAddressTest.TestAssign I 36.003s run_tests_on_device(01dca4b3a6a55779) [ OK ] SocketAddressTest.TestAssign (1 ms) I 36.003s run_tests_on_device(01dca4b3a6a55779) [ RUN ] SocketAddressTest.TestSetIPPort I 36.003s run_tests_on_device(01dca4b3a6a55779) [ OK ] SocketAddressTest.TestSetIPPort (0 ms) I 36.003s run_tests_on_device(01dca4b3a6a55779) [ RUN ] SocketAddressTest.TestSetIPFromString I 36.003s run_tests_on_device(01dca4b3a6a55779) [ OK ] SocketAddressTest.TestSetIPFromString (0 ms) I 36.003s run_tests_on_device(01dca4b3a6a55779) [ RUN ] SocketAddressTest.TestSetIPFromHostname I 36.003s run_tests_on_device(01dca4b3a6a55779) [ OK ] SocketAddressTest.TestSetIPFromHostname (0 ms) I 36.003s run_tests_on_device(01dca4b3a6a55779) [ RUN ] SocketAddressTest.TestFromIPv4String I 36.003s run_tests_on_device(01dca4b3a6a55779) [ OK ] SocketAddressTest.TestFromIPv4String (0 ms) I 36.003s run_tests_on_device(01dca4b3a6a55779) [ RUN ] SocketAddressTest.TestFromIPv6String I 36.003s run_tests_on_device(01dca4b3a6a55779) [ OK ] SocketAddressTest.TestFromIPv6String (0 ms) I 36.003s run_tests_on_device(01dca4b3a6a55779) [ RUN ] SocketAddressTest.TestFromHostname I 36.003s run_tests_on_device(01dca4b3a6a55779) [ OK ] SocketAddressTest.TestFromHostname (0 ms) I 36.003s run_tests_on_device(01dca4b3a6a55779) [ RUN ] SocketAddressTest.TestToFromSockAddr I 36.003s run_tests_on_device(01dca4b3a6a55779) [ OK ] SocketAddressTest.TestToFromSockAddr (0 ms) I 36.003s run_tests_on_device(01dca4b3a6a55779) [ RUN ] SocketAddressTest.TestToFromSockAddrStorage I 36.003s run_tests_on_device(01dca4b3a6a55779) [ OK ] SocketAddressTest.TestToFromSockAddrStorage (1 ms) I 36.003s run_tests_on_device(01dca4b3a6a55779) [ RUN ] SocketAddressTest.TestEqualityOperators I 36.003s run_tests_on_device(01dca4b3a6a55779) [ OK ] SocketAddressTest.TestEqualityOperators (0 ms) I 36.003s run_tests_on_device(01dca4b3a6a55779) [ RUN ] SocketAddressTest.TestComparisonOperator I 36.003s run_tests_on_device(01dca4b3a6a55779) [ OK ] SocketAddressTest.TestComparisonOperator (1 ms) I 36.003s run_tests_on_device(01dca4b3a6a55779) [ RUN ] SocketAddressTest.TestToSensitiveString I 36.003s run_tests_on_device(01dca4b3a6a55779) [ OK ] SocketAddressTest.TestToSensitiveString (0 ms) I 36.003s run_tests_on_device(01dca4b3a6a55779) [----------] 19 tests from SocketAddressTest (48 ms total) I 36.003s run_tests_on_device(01dca4b3a6a55779) I 36.003s run_tests_on_device(01dca4b3a6a55779) [----------] Global test environment tear-down I 36.003s run_tests_on_device(01dca4b3a6a55779) [==========] 65 tests from 5 test suites ran. (7901 ms total) I 36.003s run_tests_on_device(01dca4b3a6a55779) [ PASSED ] 65 tests. I 36.003s run_tests_on_device(01dca4b3a6a55779) <<ScopedMainEntryLogger I 36.005s run_tests_on_device(01dca4b3a6a55779) Finished running tests on this device. I 36.009s Main FINISHED TRY #1/3 I 36.009s Main All tests completed. C 36.010s Main ******************************************************************************** C 36.010s Main Summary C 36.010s Main ******************************************************************************** C 36.010s Main [==========] 65 tests ran. C 36.010s Main [ PASSED ] 65 tests. C 36.010s Main ******************************************************************************** I 36.011s tear_down_device(01dca4b3a6a55779) Wrote device cache: /b/swarming/w/ir/out/Android32_M_Nexus5X_dbg/device_cache_01dca4b3a6a55779.json I 36.180s Main Generated json results file at /b/swarming/w/itxcuHPh/tmpZ1pM4t I 36.180s Main Finishing archiving output. [W2019-04-17T14:31:03.849664Z 13477 0 butler.go:240] Butler Context was cancelled. Initiating shutdown. {"error":"context canceled"} INFO:devil.utils.timeout_retry:condition 'logdog_stopped' not met [E2019-04-17T14:31:03.945834Z 13477 0 main.go:205] Butler terminated with error. {"error":"context canceled"} [E2019-04-17T14:31:03.945886Z 13477 0 main.go:233] Failed to serve. original error: context canceled [I2019-04-17T14:31:03.946078Z 13477 0 main.go:349] Terminating. {"returnCode":250} INFO:devil.utils.timeout_retry:condition 'logdog_stopped' met 2019-04-17 07:39:20,572 - root: [DEBUG] Contents of task_output_dir: ['/b/s/w/ir/tmp/t/tmpivTh2i/44448617bfcc9a10'] 2019-04-17 07:39:20,573 - root: [DEBUG] Found shard_json_files: ['/b/s/w/ir/tmp/t/tmpivTh2i/44448617bfcc9a10/output.json'] 2019-04-17 07:39:20,573 - root: [INFO] merge_cmd: /b/s/w/ir/cache/vpython/95f307/bin/python /b/s/w/ir/cache/builder/src/testing/merge_scripts/standard_gtest_merge.py --summary-json /b/s/w/ir/tmp/t/tmpJMgwgl.json --task-output-dir /b/s/w/ir/tmp/t/tmpivTh2i -o /b/s/w/ir/tmp/t/tmpsbSyw9.json /b/s/w/ir/tmp/t/tmpivTh2i/44448617bfcc9a10/output.json Running ['/b/s/w/ir/cache/vpython/95f307/bin/python', '/b/s/w/ir/cache/builder/src/testing/merge_scripts/standard_gtest_merge.py', '--summary-json', '/b/s/w/ir/tmp/t/tmpJMgwgl.json', '--task-output-dir', '/b/s/w/ir/tmp/t/tmpivTh2i', '-o', '/b/s/w/ir/tmp/t/tmpsbSyw9.json', '/b/s/w/ir/tmp/t/tmpivTh2i/44448617bfcc9a10/output.json'] in None (env: None) Command ['/b/s/w/ir/cache/vpython/95f307/bin/python', '/b/s/w/ir/cache/builder/src/testing/merge_scripts/standard_gtest_merge.py', '--summary-json', '/b/s/w/ir/tmp/t/tmpJMgwgl.json', '--task-output-dir', '/b/s/w/ir/tmp/t/tmpivTh2i', '-o', '/b/s/w/ir/tmp/t/tmpsbSyw9.json', '/b/s/w/ir/tmp/t/tmpivTh2i/44448617bfcc9a10/output.json'] returned exit code 0