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-17 15:41:35,075 - root: [DEBUG] Using task_output_dir: '/b/s/w/ir/tmp/t/tmpiLzMiz' 2019-05-17 15:41:35,075 - root: [WARNING] task_output_dir '/b/s/w/ir/tmp/t/tmpiLzMiz' already exists! 2019-05-17 15:41:35,076 - root: [WARNING] task_output_dir existing content: [] 2019-05-17 15:41:35,076 - 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/tmpXgHUfH.json -output-dir /b/s/w/ir/tmp/t/tmpiLzMiz -task-summary-json /b/s/w/ir/tmp/t/tmpgG0g5L.json [D2019-05-17T15:41:35.088040-07:00 5848 0 auth.go:1265] Minting a new token {"key":"luci_ctx/47ed78a7143844bee28aacdc84c2052305a36695f2334b6c7a63bf893c23adfa", "scopes":"https://www.googleapis.com/auth/userinfo.email"} [D2019-05-17T15:41:35.088162-07:00 5848 0 luci_ctx.go:138] POST http://127.0.0.1:40571/rpc/LuciLocalAuthService.GetOAuthToken {"key":"luci_ctx/47ed78a7143844bee28aacdc84c2052305a36695f2334b6c7a63bf893c23adfa", "scopes":"https://www.googleapis.com/auth/userinfo.email"} [D2019-05-17T15:41:35.090614-07:00 5848 0 auth.go:1222] Token expires in 33m1.909477795s {"key":"luci_ctx/47ed78a7143844bee28aacdc84c2052305a36695f2334b6c7a63bf893c23adfa", "scopes":"https://www.googleapis.com/auth/userinfo.email"} 44e0b62189d86110: 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/webrtc_nonparallel_tests__test_runner_script.runtime_deps --suite webrtc_nonparallel_tests --shard-timeout=900 -v --logcat-output-file /b/swarming/w/io0MKj5O/logcats --store-tombstones --test-launcher-summary-output=/b/swarming/w/io0MKj5O/output.json I 0.007s Main condition '<lambda>' met I 0.009s Main condition '<lambda>' met I 3.200s TimeoutThread-1-for-prepare_device(01efd956e97b4f92) condition 'sd_card_ready' met (0.0s) I 3.866s TimeoutThread-1-for-prepare_device(01efd956e97b4f92) condition 'pm_ready' met (0.7s) I 3.902s TimeoutThread-1-for-prepare_device(01efd956e97b4f92) condition 'boot_completed' met (0.7s) I 13.719s TimeoutThread-1-for-individual_device_set_up(01efd956e97b4f92) Setting permissions for org.webrtc.native_test. I 16.501s calculate_device_checksums condition '<lambda>' met (0.2s) I 18.977s list_tests(01efd956e97b4f92) flags: I 18.978s list_tests(01efd956e97b4f92) --gtest_list_tests I 18.996s TimeoutThread-1-for-list_tests(01efd956e97b4f92) 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-b2876738dd2f5.gtest_out -e org.chromium.native_test.NativeTest.CommandLineFlags --gtest_list_tests "$p"/org.chromium.native_test.NativeTestInstrumentationTestRunner )>/ ... I 20.812s list_tests(01efd956e97b4f92) /sdcard/temp_file-b2876738dd2f5.gtest_out size on device: 2149 I 20.885s Main Using external sharding settings. This is shard 0/1 I 20.885s Main STARTING TRY #1/3 I 20.885s Main Will run 65 tests on 1 devices: 01efd956e97b4f92 W 20.953s run_tests_on_device(01efd956e97b4f92) No tombstones to clear. I 24.867s run_tests_on_device(01efd956e97b4f92) flags: I 24.867s run_tests_on_device(01efd956e97b4f92) Getting logdog client. I 24.962s TimeoutThread-1-for-run_tests_on_device(01efd956e97b4f92) 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-937189e314b41 -e org.chromium.native_test.NativeTestInstrumentationTestRunner.StdoutFile /sdcard/temp_file-e690792c5979.gtest_out "$p"/org.chromium.native_test.N ... I 34.427s run_tests_on_device(01efd956e97b4f92) /sdcard/temp_file-e690792c5979.gtest_out size on device: 12176 I 34.563s run_tests_on_device(01efd956e97b4f92) Logcat saved to https://luci-logdog.appspot.com/v/?s=chromium%2Fandroid%2Fswarming%2Flogcats%2F44dfaf08858e8b11%2F%2B%2Flogcat_logcat_2598194053912766760_20190517T173649-UTC_01efd956e97b4f92 I 34.563s archive Writing text to logdog stream, logcat_logcat_2598194053912766760_20190517T173649-UTC_01efd956e97b4f92 I 35.209s TimeoutThread-1-for-run_tests_on_device(01efd956e97b4f92) Setting permissions for org.webrtc.native_test. I 37.834s run_tests_on_device(01efd956e97b4f92) >>ScopedMainEntryLogger I 37.834s run_tests_on_device(01efd956e97b4f92) 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 37.834s run_tests_on_device(01efd956e97b4f92) [==========] Running 65 tests from 5 test suites. I 37.834s run_tests_on_device(01efd956e97b4f92) [----------] Global test environment set-up. I 37.834s run_tests_on_device(01efd956e97b4f92) [----------] 3 tests from CpuTimeTest I 37.834s run_tests_on_device(01efd956e97b4f92) [ RUN ] CpuTimeTest.BasicTest I 37.834s run_tests_on_device(01efd956e97b4f92) [ OK ] CpuTimeTest.BasicTest (0 ms) I 37.834s run_tests_on_device(01efd956e97b4f92) [ RUN ] CpuTimeTest.TwoThreads I 37.834s run_tests_on_device(01efd956e97b4f92) [ OK ] CpuTimeTest.TwoThreads (302 ms) I 37.835s run_tests_on_device(01efd956e97b4f92) [ RUN ] CpuTimeTest.Sleeping I 37.835s run_tests_on_device(01efd956e97b4f92) [ OK ] CpuTimeTest.Sleeping (300 ms) I 37.835s run_tests_on_device(01efd956e97b4f92) [----------] 3 tests from CpuTimeTest (604 ms total) I 37.835s run_tests_on_device(01efd956e97b4f92) I 37.835s run_tests_on_device(01efd956e97b4f92) [----------] 2 tests from NullSocketServerTest I 37.835s run_tests_on_device(01efd956e97b4f92) [ RUN ] NullSocketServerTest.WaitAndSet I 37.835s run_tests_on_device(01efd956e97b4f92) [ OK ] NullSocketServerTest.WaitAndSet (1 ms) I 37.835s run_tests_on_device(01efd956e97b4f92) [ RUN ] NullSocketServerTest.TestWait I 37.835s run_tests_on_device(01efd956e97b4f92) [ OK ] NullSocketServerTest.TestWait (200 ms) I 37.835s run_tests_on_device(01efd956e97b4f92) [----------] 2 tests from NullSocketServerTest (203 ms total) I 37.835s run_tests_on_device(01efd956e97b4f92) I 37.835s run_tests_on_device(01efd956e97b4f92) [----------] 38 tests from PhysicalSocketTest I 37.835s run_tests_on_device(01efd956e97b4f92) [ RUN ] PhysicalSocketTest.TestConnectIPv4 I 37.835s run_tests_on_device(01efd956e97b4f92) [ OK ] PhysicalSocketTest.TestConnectIPv4 (19 ms) I 37.835s run_tests_on_device(01efd956e97b4f92) [ RUN ] PhysicalSocketTest.TestConnectIPv6 I 37.835s run_tests_on_device(01efd956e97b4f92) [ OK ] PhysicalSocketTest.TestConnectIPv6 (8 ms) I 37.835s run_tests_on_device(01efd956e97b4f92) [ RUN ] PhysicalSocketTest.TestConnectWithDnsLookupIPv4 I 37.835s run_tests_on_device(01efd956e97b4f92) [ OK ] PhysicalSocketTest.TestConnectWithDnsLookupIPv4 (18 ms) I 37.835s run_tests_on_device(01efd956e97b4f92) [ RUN ] PhysicalSocketTest.TestConnectWithDnsLookupIPv6 I 37.835s run_tests_on_device(01efd956e97b4f92) [000:000] (line 75): Skipping IPv6 DNS test I 37.835s run_tests_on_device(01efd956e97b4f92) [ OK ] PhysicalSocketTest.TestConnectWithDnsLookupIPv6 (2 ms) I 37.835s run_tests_on_device(01efd956e97b4f92) [ RUN ] PhysicalSocketTest.TestConnectFailIPv4 I 37.835s run_tests_on_device(01efd956e97b4f92) [000:009] (line 170): GetRemoteAddress: unable to get remote addr, socket=31 I 37.835s run_tests_on_device(01efd956e97b4f92) [ OK ] PhysicalSocketTest.TestConnectFailIPv4 (8 ms) I 37.836s run_tests_on_device(01efd956e97b4f92) [ RUN ] PhysicalSocketTest.TestConnectAcceptErrorIPv4 I 37.836s run_tests_on_device(01efd956e97b4f92) [ OK ] PhysicalSocketTest.TestConnectAcceptErrorIPv4 (13 ms) I 37.836s run_tests_on_device(01efd956e97b4f92) [ RUN ] PhysicalSocketTest.TestConnectAcceptErrorIPv6 I 37.836s run_tests_on_device(01efd956e97b4f92) [ OK ] PhysicalSocketTest.TestConnectAcceptErrorIPv6 (13 ms) I 37.836s run_tests_on_device(01efd956e97b4f92) [ RUN ] PhysicalSocketTest.TestWritableAfterPartialWriteIPv4 I 37.836s run_tests_on_device(01efd956e97b4f92) [ OK ] PhysicalSocketTest.TestWritableAfterPartialWriteIPv4 (1801 ms) I 37.836s run_tests_on_device(01efd956e97b4f92) [ RUN ] PhysicalSocketTest.TestWritableAfterPartialWriteIPv6 I 37.836s run_tests_on_device(01efd956e97b4f92) [ OK ] PhysicalSocketTest.TestWritableAfterPartialWriteIPv6 (1922 ms) I 37.836s run_tests_on_device(01efd956e97b4f92) [ RUN ] PhysicalSocketTest.TestConnectFailIPv6 I 37.836s run_tests_on_device(01efd956e97b4f92) [003:769] (line 170): GetRemoteAddress: unable to get remote addr, socket=31 I 37.836s run_tests_on_device(01efd956e97b4f92) [ OK ] PhysicalSocketTest.TestConnectFailIPv6 (7 ms) I 37.836s run_tests_on_device(01efd956e97b4f92) [ RUN ] PhysicalSocketTest.TestConnectWithDnsLookupFailIPv4 I 37.836s run_tests_on_device(01efd956e97b4f92) [003:778] (line 170): GetRemoteAddress: unable to get remote addr, socket=-1 I 37.836s run_tests_on_device(01efd956e97b4f92) [ OK ] PhysicalSocketTest.TestConnectWithDnsLookupFailIPv4 (8 ms) I 37.836s run_tests_on_device(01efd956e97b4f92) [ RUN ] PhysicalSocketTest.TestConnectWithDnsLookupFailIPv6 I 37.836s run_tests_on_device(01efd956e97b4f92) [003:787] (line 170): GetRemoteAddress: unable to get remote addr, socket=-1 I 37.836s run_tests_on_device(01efd956e97b4f92) [ OK ] PhysicalSocketTest.TestConnectWithDnsLookupFailIPv6 (8 ms) I 37.836s run_tests_on_device(01efd956e97b4f92) [ RUN ] PhysicalSocketTest.TestConnectWithClosedSocketIPv4 I 37.836s run_tests_on_device(01efd956e97b4f92) [ OK ] PhysicalSocketTest.TestConnectWithClosedSocketIPv4 (5 ms) I 37.836s run_tests_on_device(01efd956e97b4f92) [ RUN ] PhysicalSocketTest.TestConnectWithClosedSocketIPv6 I 37.836s run_tests_on_device(01efd956e97b4f92) [ OK ] PhysicalSocketTest.TestConnectWithClosedSocketIPv6 (5 ms) I 37.836s run_tests_on_device(01efd956e97b4f92) [ RUN ] PhysicalSocketTest.TestConnectWhileNotClosedIPv4 I 37.836s run_tests_on_device(01efd956e97b4f92) [ OK ] PhysicalSocketTest.TestConnectWhileNotClosedIPv4 (6 ms) I 37.836s run_tests_on_device(01efd956e97b4f92) [ RUN ] PhysicalSocketTest.TestConnectWhileNotClosedIPv6 I 37.836s run_tests_on_device(01efd956e97b4f92) [ OK ] PhysicalSocketTest.TestConnectWhileNotClosedIPv6 (7 ms) I 37.837s run_tests_on_device(01efd956e97b4f92) [ RUN ] PhysicalSocketTest.TestServerCloseDuringConnectIPv4 I 37.837s run_tests_on_device(01efd956e97b4f92) [ OK ] PhysicalSocketTest.TestServerCloseDuringConnectIPv4 (7 ms) I 37.837s run_tests_on_device(01efd956e97b4f92) [ RUN ] PhysicalSocketTest.TestServerCloseDuringConnectIPv6 I 37.837s run_tests_on_device(01efd956e97b4f92) [ OK ] PhysicalSocketTest.TestServerCloseDuringConnectIPv6 (7 ms) I 37.837s run_tests_on_device(01efd956e97b4f92) [ RUN ] PhysicalSocketTest.TestClientCloseDuringConnectIPv4 I 37.837s run_tests_on_device(01efd956e97b4f92) [ OK ] PhysicalSocketTest.TestClientCloseDuringConnectIPv4 (7 ms) I 37.837s run_tests_on_device(01efd956e97b4f92) [ RUN ] PhysicalSocketTest.TestClientCloseDuringConnectIPv6 I 37.837s run_tests_on_device(01efd956e97b4f92) [ OK ] PhysicalSocketTest.TestClientCloseDuringConnectIPv6 (6 ms) I 37.837s run_tests_on_device(01efd956e97b4f92) [ RUN ] PhysicalSocketTest.TestServerCloseIPv4 I 37.837s run_tests_on_device(01efd956e97b4f92) [003:855] (line 170): GetRemoteAddress: unable to get remote addr, socket=-1 I 37.837s run_tests_on_device(01efd956e97b4f92) [003:856] (line 170): GetRemoteAddress: unable to get remote addr, socket=-1 I 37.837s run_tests_on_device(01efd956e97b4f92) [ OK ] PhysicalSocketTest.TestServerCloseIPv4 (10 ms) I 37.837s run_tests_on_device(01efd956e97b4f92) [ RUN ] PhysicalSocketTest.TestServerCloseIPv6 I 37.837s run_tests_on_device(01efd956e97b4f92) [003:866] (line 170): GetRemoteAddress: unable to get remote addr, socket=-1 I 37.837s run_tests_on_device(01efd956e97b4f92) [003:867] (line 170): GetRemoteAddress: unable to get remote addr, socket=-1 I 37.837s run_tests_on_device(01efd956e97b4f92) [ OK ] PhysicalSocketTest.TestServerCloseIPv6 (11 ms) I 37.837s run_tests_on_device(01efd956e97b4f92) [ RUN ] PhysicalSocketTest.TestCloseInClosedCallbackIPv4 I 37.837s run_tests_on_device(01efd956e97b4f92) [ OK ] PhysicalSocketTest.TestCloseInClosedCallbackIPv4 (8 ms) I 37.837s run_tests_on_device(01efd956e97b4f92) [ RUN ] PhysicalSocketTest.TestCloseInClosedCallbackIPv6 I 37.837s run_tests_on_device(01efd956e97b4f92) [ OK ] PhysicalSocketTest.TestCloseInClosedCallbackIPv6 (7 ms) I 37.837s run_tests_on_device(01efd956e97b4f92) [ RUN ] PhysicalSocketTest.TestSocketServerWaitIPv4 I 37.837s run_tests_on_device(01efd956e97b4f92) [004:392] (line 517): Message took 500ms to dispatch. Posted from: SocketServerWaitInternal@../../rtc_base/socket_unittest.cc:700 I 37.837s run_tests_on_device(01efd956e97b4f92) [ OK ] PhysicalSocketTest.TestSocketServerWaitIPv4 (512 ms) I 37.837s run_tests_on_device(01efd956e97b4f92) [ RUN ] PhysicalSocketTest.TestSocketServerWaitIPv6 I 37.838s run_tests_on_device(01efd956e97b4f92) [004:907] (line 517): Message took 500ms to dispatch. Posted from: SocketServerWaitInternal@../../rtc_base/socket_unittest.cc:700 I 37.838s run_tests_on_device(01efd956e97b4f92) [ OK ] PhysicalSocketTest.TestSocketServerWaitIPv6 (514 ms) I 37.838s run_tests_on_device(01efd956e97b4f92) [ RUN ] PhysicalSocketTest.TestTcpIPv4 I 37.838s run_tests_on_device(01efd956e97b4f92) [ OK ] PhysicalSocketTest.TestTcpIPv4 (148 ms) I 37.838s run_tests_on_device(01efd956e97b4f92) [ RUN ] PhysicalSocketTest.TestTcpIPv6 I 37.838s run_tests_on_device(01efd956e97b4f92) [ OK ] PhysicalSocketTest.TestTcpIPv6 (94 ms) I 37.838s run_tests_on_device(01efd956e97b4f92) [ RUN ] PhysicalSocketTest.TestUdpIPv4 I 37.838s run_tests_on_device(01efd956e97b4f92) [ OK ] PhysicalSocketTest.TestUdpIPv4 (37 ms) I 37.838s run_tests_on_device(01efd956e97b4f92) [ RUN ] PhysicalSocketTest.TestUdpIPv6 I 37.838s run_tests_on_device(01efd956e97b4f92) [ OK ] PhysicalSocketTest.TestUdpIPv6 (36 ms) I 37.838s run_tests_on_device(01efd956e97b4f92) [ RUN ] PhysicalSocketTest.TestUdpReadyToSendIPv6 I 37.838s run_tests_on_device(01efd956e97b4f92) [ OK ] PhysicalSocketTest.TestUdpReadyToSendIPv6 (0 ms) I 37.838s run_tests_on_device(01efd956e97b4f92) [ RUN ] PhysicalSocketTest.TestGetSetOptionsIPv4 I 37.838s run_tests_on_device(01efd956e97b4f92) [ OK ] PhysicalSocketTest.TestGetSetOptionsIPv4 (1 ms) I 37.838s run_tests_on_device(01efd956e97b4f92) [ RUN ] PhysicalSocketTest.TestGetSetOptionsIPv6 I 37.838s run_tests_on_device(01efd956e97b4f92) [ OK ] PhysicalSocketTest.TestGetSetOptionsIPv6 (1 ms) I 37.838s run_tests_on_device(01efd956e97b4f92) [ RUN ] PhysicalSocketTest.TestSocketRecvTimestampIPv4 I 37.838s run_tests_on_device(01efd956e97b4f92) [ OK ] PhysicalSocketTest.TestSocketRecvTimestampIPv4 (103 ms) I 37.838s run_tests_on_device(01efd956e97b4f92) [ RUN ] PhysicalSocketTest.TestSocketRecvTimestampIPv6 I 37.838s run_tests_on_device(01efd956e97b4f92) [ OK ] PhysicalSocketTest.TestSocketRecvTimestampIPv6 (103 ms) I 37.838s run_tests_on_device(01efd956e97b4f92) [ RUN ] PhysicalSocketTest.BindFailsIfNetworkBinderFailsForNonLoopbackInterface I 37.838s run_tests_on_device(01efd956e97b4f92) [005:445] (line 200): Binding socket to network address 192.168.0.1 failed; result: -1 I 37.838s run_tests_on_device(01efd956e97b4f92) [ OK ] PhysicalSocketTest.BindFailsIfNetworkBinderFailsForNonLoopbackInterface (3 ms) I 37.838s run_tests_on_device(01efd956e97b4f92) [ RUN ] PhysicalSocketTest.NetworkBinderIsNotUsedForAnyIp I 37.839s run_tests_on_device(01efd956e97b4f92) [ OK ] PhysicalSocketTest.NetworkBinderIsNotUsedForAnyIp (2 ms) I 37.839s run_tests_on_device(01efd956e97b4f92) [ RUN ] PhysicalSocketTest.BindSucceedsIfNetworkBinderFailsForLoopbackInterface I 37.839s run_tests_on_device(01efd956e97b4f92) [ OK ] PhysicalSocketTest.BindSucceedsIfNetworkBinderFailsForLoopbackInterface (2 ms) I 37.839s run_tests_on_device(01efd956e97b4f92) [----------] 38 tests from PhysicalSocketTest (5501 ms total) I 37.839s run_tests_on_device(01efd956e97b4f92) I 37.839s run_tests_on_device(01efd956e97b4f92) [----------] 3 tests from PosixSignalDeliveryTest I 37.839s run_tests_on_device(01efd956e97b4f92) [ RUN ] PosixSignalDeliveryTest.RaiseThenWait I 37.839s run_tests_on_device(01efd956e97b4f92) [ OK ] PosixSignalDeliveryTest.RaiseThenWait (0 ms) I 37.839s run_tests_on_device(01efd956e97b4f92) [ RUN ] PosixSignalDeliveryTest.InsanelyManySignals I 37.839s run_tests_on_device(01efd956e97b4f92) [ OK ] PosixSignalDeliveryTest.InsanelyManySignals (95 ms) I 37.839s run_tests_on_device(01efd956e97b4f92) [ RUN ] PosixSignalDeliveryTest.SignalDuringWait I 37.839s run_tests_on_device(01efd956e97b4f92) [ OK ] PosixSignalDeliveryTest.SignalDuringWait (1502 ms) I 37.839s run_tests_on_device(01efd956e97b4f92) [----------] 3 tests from PosixSignalDeliveryTest (1601 ms total) I 37.839s run_tests_on_device(01efd956e97b4f92) I 37.839s run_tests_on_device(01efd956e97b4f92) [----------] 19 tests from SocketAddressTest I 37.839s run_tests_on_device(01efd956e97b4f92) [ RUN ] SocketAddressTest.TestDefaultCtor I 37.839s run_tests_on_device(01efd956e97b4f92) [ OK ] SocketAddressTest.TestDefaultCtor (0 ms) I 37.839s run_tests_on_device(01efd956e97b4f92) [ RUN ] SocketAddressTest.TestIPPortCtor I 37.839s run_tests_on_device(01efd956e97b4f92) [ OK ] SocketAddressTest.TestIPPortCtor (0 ms) I 37.839s run_tests_on_device(01efd956e97b4f92) [ RUN ] SocketAddressTest.TestIPv4StringPortCtor I 37.839s run_tests_on_device(01efd956e97b4f92) [ OK ] SocketAddressTest.TestIPv4StringPortCtor (0 ms) I 37.839s run_tests_on_device(01efd956e97b4f92) [ RUN ] SocketAddressTest.TestIPv6StringPortCtor I 37.839s run_tests_on_device(01efd956e97b4f92) [ OK ] SocketAddressTest.TestIPv6StringPortCtor (1 ms) I 37.839s run_tests_on_device(01efd956e97b4f92) [ RUN ] SocketAddressTest.TestSpecialStringPortCtor I 37.839s run_tests_on_device(01efd956e97b4f92) [ OK ] SocketAddressTest.TestSpecialStringPortCtor (0 ms) I 37.839s run_tests_on_device(01efd956e97b4f92) [ RUN ] SocketAddressTest.TestHostnamePortCtor I 37.839s run_tests_on_device(01efd956e97b4f92) [ OK ] SocketAddressTest.TestHostnamePortCtor (1 ms) I 37.839s run_tests_on_device(01efd956e97b4f92) [ RUN ] SocketAddressTest.TestCopyCtor I 37.839s run_tests_on_device(01efd956e97b4f92) [ OK ] SocketAddressTest.TestCopyCtor (0 ms) I 37.839s run_tests_on_device(01efd956e97b4f92) [ RUN ] SocketAddressTest.TestAssign I 37.839s run_tests_on_device(01efd956e97b4f92) [ OK ] SocketAddressTest.TestAssign (0 ms) I 37.839s run_tests_on_device(01efd956e97b4f92) [ RUN ] SocketAddressTest.TestSetIPPort I 37.839s run_tests_on_device(01efd956e97b4f92) [ OK ] SocketAddressTest.TestSetIPPort (0 ms) I 37.839s run_tests_on_device(01efd956e97b4f92) [ RUN ] SocketAddressTest.TestSetIPFromString I 37.839s run_tests_on_device(01efd956e97b4f92) [ OK ] SocketAddressTest.TestSetIPFromString (0 ms) I 37.839s run_tests_on_device(01efd956e97b4f92) [ RUN ] SocketAddressTest.TestSetIPFromHostname I 37.839s run_tests_on_device(01efd956e97b4f92) [ OK ] SocketAddressTest.TestSetIPFromHostname (0 ms) I 37.839s run_tests_on_device(01efd956e97b4f92) [ RUN ] SocketAddressTest.TestFromIPv4String I 37.839s run_tests_on_device(01efd956e97b4f92) [ OK ] SocketAddressTest.TestFromIPv4String (0 ms) I 37.839s run_tests_on_device(01efd956e97b4f92) [ RUN ] SocketAddressTest.TestFromIPv6String I 37.840s run_tests_on_device(01efd956e97b4f92) [ OK ] SocketAddressTest.TestFromIPv6String (0 ms) I 37.840s run_tests_on_device(01efd956e97b4f92) [ RUN ] SocketAddressTest.TestFromHostname I 37.840s run_tests_on_device(01efd956e97b4f92) [ OK ] SocketAddressTest.TestFromHostname (0 ms) I 37.840s run_tests_on_device(01efd956e97b4f92) [ RUN ] SocketAddressTest.TestToFromSockAddr I 37.840s run_tests_on_device(01efd956e97b4f92) [ OK ] SocketAddressTest.TestToFromSockAddr (1 ms) I 37.840s run_tests_on_device(01efd956e97b4f92) [ RUN ] SocketAddressTest.TestToFromSockAddrStorage I 37.840s run_tests_on_device(01efd956e97b4f92) [ OK ] SocketAddressTest.TestToFromSockAddrStorage (0 ms) I 37.840s run_tests_on_device(01efd956e97b4f92) [ RUN ] SocketAddressTest.TestEqualityOperators I 37.840s run_tests_on_device(01efd956e97b4f92) [ OK ] SocketAddressTest.TestEqualityOperators (0 ms) I 37.840s run_tests_on_device(01efd956e97b4f92) [ RUN ] SocketAddressTest.TestComparisonOperator I 37.840s run_tests_on_device(01efd956e97b4f92) [ OK ] SocketAddressTest.TestComparisonOperator (0 ms) I 37.840s run_tests_on_device(01efd956e97b4f92) [ RUN ] SocketAddressTest.TestToSensitiveString I 37.840s run_tests_on_device(01efd956e97b4f92) [ OK ] SocketAddressTest.TestToSensitiveString (1 ms) I 37.840s run_tests_on_device(01efd956e97b4f92) [----------] 19 tests from SocketAddressTest (38 ms total) I 37.840s run_tests_on_device(01efd956e97b4f92) I 37.840s run_tests_on_device(01efd956e97b4f92) [----------] Global test environment tear-down I 37.840s run_tests_on_device(01efd956e97b4f92) [==========] 65 tests from 5 test suites ran. (7953 ms total) I 37.840s run_tests_on_device(01efd956e97b4f92) [ PASSED ] 65 tests. I 37.840s run_tests_on_device(01efd956e97b4f92) <<ScopedMainEntryLogger I 37.842s run_tests_on_device(01efd956e97b4f92) Finished running tests on this device. I 37.870s Main FINISHED TRY #1/3 I 37.870s Main All tests completed. C 37.871s Main ******************************************************************************** C 37.871s Main Summary C 37.871s Main ******************************************************************************** C 37.871s Main [==========] 65 tests ran. C 37.871s Main [ PASSED ] 65 tests. C 37.871s Main ******************************************************************************** I 37.872s tear_down_device(01efd956e97b4f92) Wrote device cache: /b/swarming/w/ir/out/Android32_M_Nexus5X_dbg/device_cache_01efd956e97b4f92.json I 38.092s Main Generated json results file at /b/swarming/w/itf3UiX_/tmpsBxNjm I 38.093s Main Finishing archiving output. [W2019-05-17T17:37:03.247205Z 22489 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-17T17:37:03.360748Z 22489 0 main.go:205] Butler terminated with error. {"error":"context canceled"} [E2019-05-17T17:37:03.360799Z 22489 0 main.go:233] Failed to serve. original error: context canceled [I2019-05-17T17:37:03.360895Z 22489 0 main.go:349] Terminating. {"returnCode":250} INFO:devil.utils.timeout_retry:condition 'logdog_stopped' met 2019-05-17 15:41:35,655 - root: [DEBUG] Contents of task_output_dir: ['/b/s/w/ir/tmp/t/tmpiLzMiz/44e0b62189d86110'] 2019-05-17 15:41:35,656 - root: [DEBUG] Found shard_json_files: ['/b/s/w/ir/tmp/t/tmpiLzMiz/44e0b62189d86110/output.json'] 2019-05-17 15:41:35,656 - 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/tmpgG0g5L.json --task-output-dir /b/s/w/ir/tmp/t/tmpiLzMiz -o /b/s/w/ir/tmp/t/tmpqx2JXP.json /b/s/w/ir/tmp/t/tmpiLzMiz/44e0b62189d86110/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/tmpgG0g5L.json', '--task-output-dir', '/b/s/w/ir/tmp/t/tmpiLzMiz', '-o', '/b/s/w/ir/tmp/t/tmpqx2JXP.json', '/b/s/w/ir/tmp/t/tmpiLzMiz/44e0b62189d86110/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/tmpgG0g5L.json', '--task-output-dir', '/b/s/w/ir/tmp/t/tmpiLzMiz', '-o', '/b/s/w/ir/tmp/t/tmpqx2JXP.json', '/b/s/w/ir/tmp/t/tmpiLzMiz/44e0b62189d86110/output.json'] returned exit code 0