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

{ "shards": [ { "bot_dimensions": [ { "key": "android_devices", "value": [ "1" ] }, { "key": "caches", "value": [ "swarming_module_cache_vpython" ] }, { "key": "cpu_governor", "value": [ "powersave" ] }, { "key": "device_gms_core_version", "value": [ "4.3.23" ] }, { "key": "device_os", "value": [ "K", "KTU84P" ] }, { "key": "device_os_flavor", "value": [ "google" ] }, { "key": "device_playstore_version", "value": [ "4.5.10" ] }, { "key": "device_type", "value": [ "hammerhead" ] }, { "key": "gce", "value": [ "0" ] }, { "key": "id", "value": [ "build273-m1--device4" ] }, { "key": "inside_docker", "value": [ "1", "stock" ] }, { "key": "locale", "value": [ "en_US.UTF-8" ] }, { "key": "os", "value": [ "Android" ] }, { "key": "pool", "value": [ "Chrome" ] }, { "key": "python", "value": [ "2.7.12" ] }, { "key": "server_version", "value": [ "4274-a0a5f8c" ] }, { "key": "temp_band", "value": [ "<30" ] }, { "key": "zone", "value": [ "us", "us-golo", "us-golo-1" ] } ], "bot_id": "build273-m1--device4", "bot_version": "ed4565f416d57730e75be543776f9c0eb206896ef8831a69fda252ac9fa2095a", "cipd_pins": { "client_package": { "package_name": "infra/tools/cipd/linux-amd64", "version": "nFftZl2kFquaI2EUmPGwzbc5sIbCocl9L55UeASqlLsC" }, "packages": [ { "package_name": "infra/python/cpython/linux-amd64", "path": ".swarming_module", "version": "46c0c897ca0f053799ee41fd148bb7a47232df47" }, { "package_name": "infra/tools/luci/logdog/butler/linux-amd64", "path": ".swarming_module", "version": "2737ea8ed9b958f4d5aa9ffe106115a649ada241" }, { "package_name": "infra/tools/luci/vpython-native/linux-amd64", "path": ".swarming_module", "version": "tfTmX4aGCfFtfQucj_nEfwiNisK0RmpKrITkZMBBq3cC" }, { "package_name": "infra/tools/luci/vpython/linux-amd64", "path": ".swarming_module", "version": "bmnYyAg9mbM_538s1dIP1hZZmOtc8C7ssF41ztN2cr0C" }, { "package_name": "infra/tools/luci/logdog/butler/linux-amd64", "path": "bin", "version": "a57ad614c01fec9fa4259473c8ea3fd992d7c349" } ] }, "completed_ts": "2019-04-26T02:58:23.817848", "costs_usd": [ 0.01275112784546709 ], "created_ts": "2019-04-26T02:56:31.209054", "duration": 63.96485686302185, "modified_ts": "2019-04-26T02:58:23.817848", "name": "cronet_unittests_android on Android device Nexus 5/Android/a731d38390/android-cronet-asan-arm-rel/26186", "output": "/b/swarming/swarming_bot.1.zip/third_party/requests/__init__.py:83: RequestsDependencyWarning: Old version of cryptography ([1, 2, 3]) may cause slowdown.\n>> Remounting /system rw\n* daemon not running; starting now at tcp:5037\n* daemon started successfully\nadbd is already running as root\nremount succeeded\nTarget architecture: arm\n>> Copying files from the device\nadb: error: remote object '/system/lib/libclang_rt.asan-arm-android.so' does not exist\n/system/bin/app_process32: 1 file pulled. 0.2 MB/s (9560 bytes in 0.037s)\nadb: error: remote object '/system/bin/app_process.wrap' does not exist\nadb: error: remote object '/system/bin/asanwrapper' does not exist\n>> New installation\n>> Generating wrappers\nOnly in new/: app_process.wrap\nOnly in new/: asanwrapper\nOnly in new/: libclang_rt.asan-arm-android.so\n>> Pushing files to the device\nInstalling /system/lib/libclang_rt.asan-arm-android.so 644 \n/b/swarming/w/ituY21nA/tmp.9iF2fjna2x/new/libclang_rt.asan-arm-android.so: 1 file pushed. 3.9 MB/s (1168488 bytes in 0.283s)\nInstalling /system/bin/app_process32 755 u:object_r:system_file:s0\n/b/swarming/w/ituY21nA/tmp.9iF2fjna2x/new/app_process32: 1 file pushed. 1.4 MB/s (9560 bytes in 0.007s)\nInstalling /system/bin/app_process.wrap 755 u:object_r:system_file:s0\n/b/swarming/w/ituY21nA/tmp.9iF2fjna2x/new/app_process.wrap: 1 file pushed. 0.1 MB/s (254 bytes in 0.002s)\nInstalling /system/bin/asanwrapper 755 u:object_r:system_file:s0\n/b/swarming/w/ituY21nA/tmp.9iF2fjna2x/new/asanwrapper: 1 file pushed. 0.1 MB/s (71 bytes in 0.001s)\n>> Restarting shell (asynchronous)\n>> Please wait until the device restarts\nAdditional test environment:\n CHROME_DEVEL_SANDBOX=/opt/chromium/chrome_sandbox\n CHROME_HEADLESS=1\n LANG=en_US.UTF-8\nCommand: /b/swarming/w/ir/.swarming_module_cache/vpython/3bcbb9/bin/python ../../build/android/test_wrapper/logdog_wrapper.py --target cronet_unittests_android --logdog-bin-cmd ../../bin/logdog_butler --store-tombstones --gs-results-bucket=chromium-result-details --recover-devices --test-launcher-summary-output=/b/swarming/w/ioYtK8Q8/output.json\n\nI 0.000s Main command: bin/../../../build/android/test_runner.py gtest --output-directory bin/../. --runtime-deps-path bin/../gen.runtime/components/cronet/android/cronet_unittests_android__test_runner_script.runtime_deps --suite cronet_unittests_android --shard-timeout=180 --tool=asan -v --store-tombstones --gs-results-bucket=chromium-result-details --recover-devices --test-launcher-summary-output=/b/swarming/w/ioYtK8Q8/output.json\nI 0.006s Main condition '<lambda>' met\nI 0.008s Main condition '<lambda>' met\nI 0.154s TimeoutThread-1-for-prepare_device(097c2c07258187a6) condition 'sd_card_ready' met (0.0s)\nI 1.027s TimeoutThread-1-for-prepare_device(097c2c07258187a6) condition 'pm_ready' not met (0.9s)\nI 6.873s TimeoutThread-1-for-prepare_device(097c2c07258187a6) condition 'pm_ready' not met (6.8s)\nI 12.705s TimeoutThread-1-for-prepare_device(097c2c07258187a6) condition 'pm_ready' met (12.6s)\nI 12.725s TimeoutThread-1-for-prepare_device(097c2c07258187a6) condition 'boot_completed' met (12.6s)\nI 23.645s calculate_device_checksums condition '<lambda>' met (0.1s)\nI 26.260s TimeoutThread-1-for-individual_device_set_up(097c2c07258187a6) Pushing 2555 files via .zip of size 6118663\n>> Remounting /system rw\nadbd is already running as root\nremount succeeded\nTarget architecture: arm\n>> Copying files from the device\n/system/lib/libclang_rt.asan-arm-android.so: 1 file pulled. 4.3 MB/s (1168488 bytes in 0.261s)\n/system/bin/app_process32: 1 file pulled. 0.2 MB/s (9560 bytes in 0.042s)\n/system/bin/app_process.wrap: 1 file pulled. 0.1 MB/s (254 bytes in 0.004s)\n/system/bin/asanwrapper: 1 file pulled. 0.0 MB/s (71 bytes in 0.003s)\n>> Previous installation detected\n>> Generating wrappers\nFiles old/app_process.wrap and new/app_process.wrap differ\n>> Pushing files to the device\nInstalling /system/lib/libclang_rt.asan-arm-android.so 644 \n/b/swarming/w/ituY21nA/tmp.e3afMAZmnv/new/libclang_rt.asan-arm-android.so: 1 file pushed. 4.6 MB/s (1168488 bytes in 0.240s)\nInstalling /system/bin/app_process32 755 u:object_r:system_file:s0\n/b/swarming/w/ituY21nA/tmp.e3afMAZmnv/new/app_process32: 1 file pushed. 3.1 MB/s (9560 bytes in 0.003s)\nInstalling /system/bin/app_process.wrap 755 u:object_r:system_file:s0\n/b/swarming/w/ituY21nA/tmp.e3afMAZmnv/new/app_process.wrap: 1 file pushed. 0.1 MB/s (288 bytes in 0.002s)\nInstalling /system/bin/asanwrapper 755 u:object_r:system_file:s0\n/b/swarming/w/ituY21nA/tmp.e3afMAZmnv/new/asanwrapper: 1 file pushed. 0.0 MB/s (71 bytes in 0.002s)\n>> Restarting shell (asynchronous)\n>> Please wait until the device restarts\nI 35.107s TimeoutThread-1-for-individual_device_set_up(097c2c07258187a6) condition 'sd_card_ready' met (0.0s)\nI 36.278s TimeoutThread-1-for-individual_device_set_up(097c2c07258187a6) condition 'pm_ready' not met (1.2s)\nI 42.127s TimeoutThread-1-for-individual_device_set_up(097c2c07258187a6) condition 'pm_ready' not met (7.0s)\nI 47.992s TimeoutThread-1-for-individual_device_set_up(097c2c07258187a6) condition 'pm_ready' met (12.9s)\nI 48.019s TimeoutThread-1-for-individual_device_set_up(097c2c07258187a6) condition 'boot_completed' met (12.9s)\nI 48.063s TimeoutThread-1-for-individual_device_set_up(097c2c07258187a6) condition 'device_online_with_root' met (0.0s)\nI 48.171s list_tests(097c2c07258187a6) flags:\nI 48.171s list_tests(097c2c07258187a6) --gtest_list_tests\nI 50.014s list_tests(097c2c07258187a6) /storage/emulated/legacy/temp_file-543059be2517a.gtest_out size on device: 2292\nI 50.088s Main Using external sharding settings. This is shard 0/1\nI 50.089s Main STARTING TRY #1/3\nI 50.089s Main Will run 69 tests on 1 devices: 097c2c07258187a6\nW 50.125s run_tests_on_device(097c2c07258187a6) No tombstones to clear.\nI 53.034s run_tests_on_device(097c2c07258187a6) flags:\nI 53.034s run_tests_on_device(097c2c07258187a6) Getting logdog client.\nI 56.093s run_tests_on_device(097c2c07258187a6) /storage/emulated/legacy/temp_file-fe6b29c2b99be.gtest_out size on device: 14987\nI 56.171s run_tests_on_device(097c2c07258187a6) Logcat saved to https://luci-logdog.appspot.com/v/?s=chromium%2Fandroid%2Fswarming%2Flogcats%2F4470643415276e11%2F%2B%2Flogcat_logcat_-7259686211817933107_20190426T025816-UTC_097c2c07258187a6\nI 56.172s archive Writing text to logdog stream, logcat_logcat_-7259686211817933107_20190426T025816-UTC_097c2c07258187a6\nI 57.078s run_tests_on_device(097c2c07258187a6) >>ScopedMainEntryLogger\nI 57.078s run_tests_on_device(097c2c07258187a6) Note: Google Test filter = CronetStructTest.TestCronet_DateTime:CronetStructTest.TestCronet_EngineParams:CronetStructTest.TestCronet_Error:CronetStructTest.TestCronet_HttpHeader:CronetStructTest.TestCronet_Metrics:CronetStructTest.TestCronet_PublicKeyPins:CronetStructTest.TestCronet_QuicHint:CronetStructTest.TestCronet_RequestFinishedInfo:CronetStructTest.TestCronet_UrlRequestParams:CronetStructTest.TestCronet_UrlResponseInfo:Cronet_BufferCallbackTest.TestCreate:Cronet_BufferTest.TestCreate:Cronet_EngineTest.TestCreate:Cronet_ExecutorTest.TestCreate:Cronet_RequestFinishedInfoListenerTest.TestCreate:Cronet_RunnableTest.TestCreate:Cronet_UploadDataProviderTest.TestCreate:Cronet_UploadDataSinkTest.TestCreate:Cronet_UrlRequestCallbackTest.TestCreate:Cronet_UrlRequestStatusListenerTest.TestCreate:Cronet_UrlRequestTest.TestCreate:EngineUnitTest.AddListenerTwice:EngineUnitTest.AddNullRequestFinishedInfoExecutor:EngineUnitTest.AddNullRequestFinishedInfoListener:EngineUnitTest.AddNullRequestFinishedInfoListenerAndExecutor:EngineUnitTest.HasNoRequestFinishedInfoListener:EngineUnitTest.HasRequestFinishedInfoListener:EngineUnitTest.RemoveNonexistentListener:EngineUnitTest.RemoveNonexistentListenerWithAddedListener:EngineUnitTest.RemoveNullListener:HistogramManager.HistogramBucketFields:HostCachePersistenceManagerTest.BatchedWrites:HostCachePersistenceManagerTest.InitAfterPrefs:HostCachePersistenceManagerTest.InitBeforePrefs:HostCachePersistenceManagerTest.MultipleWrites:HostCachePersistenceManagerTest.SeparateWrites:NativeMetricsUtilTest.ConvertsTimes:NativeMetricsUtilTest.NullStartTicks:NativeMetricsUtilTest.NullStartTime:NativeMetricsUtilTest.NullTicks:NativeMetricsUtilTest.OverwritesOldOutParam:RunnablesTest.TestCronetBuffer:RunnablesTest.TestRunCallbackOnExecutor:RunnablesTest.TestRunOnceClosureOnExecutor:StaleHostResolverTest.CancelWithNoCache:StaleHostResolverTest.CancelWithStaleCache:StaleHostResolverTest.Create:StaleHostResolverTest.CreatedByContext:StaleHostResolverTest.FreshCache:StaleHostResolverTest.Hosts:StaleHostResolverTest.Network:StaleHostResolverTest.NetworkWithStaleCache:StaleHostResolverTest.Null:StaleHostResolverTest.ReturnStaleCacheSync:StaleHostResolverTest.StaleCacheNameNotResolvedDisabled:StaleHostResolverTest.StaleCacheNameNotResolvedEnabled:StaleHostResolverTest.StaleCache_DestroyedResolver:StaleHostResolverTest.StaleUsability:URLRequestContextConfigTest.SetQuicConnectionMigrationV2Options:URLRequestContextConfigTest.SetQuicGoAwaySessionsOnIPChangeViaExperimentOptions:URLRequestContextConfigTest.SetQuicHostWhitelist:URLRequestContextConfigTest.SetQuicMaxTimeBeforeCryptoHandshake:URLRequestContextConfigTest.SetQuicServerMigrationOptions:URLRequestContextConfigTest.SetQuicStaleDNSracing:URLRequestContextConfigTest.SetSupportedQuicVersion:URLRequestContextConfigTest.SetUnsupportedQuicVersion:URLRequestContextConfigTest.TestExperimentalOptionParsing:URLURLRequestContextConfigTest.SetAcceptLanguageAndUserAgent:URLURLRequestContextConfigTest.SetQuicConnectionOptions\nI 57.078s run_tests_on_device(097c2c07258187a6) [==========] Running 69 tests from 20 test suites.\nI 57.078s run_tests_on_device(097c2c07258187a6) [----------] Global test environment set-up.\nI 57.078s run_tests_on_device(097c2c07258187a6) [----------] 1 test from HistogramManager\nI 57.078s run_tests_on_device(097c2c07258187a6) [ RUN ] HistogramManager.HistogramBucketFields\nI 57.078s run_tests_on_device(097c2c07258187a6) [ OK ] HistogramManager.HistogramBucketFields (2 ms)\nI 57.078s run_tests_on_device(097c2c07258187a6) [----------] 1 test from HistogramManager (7 ms total)\nI 57.078s run_tests_on_device(097c2c07258187a6) \nI 57.079s run_tests_on_device(097c2c07258187a6) [----------] 5 tests from HostCachePersistenceManagerTest\nI 57.079s run_tests_on_device(097c2c07258187a6) [ RUN ] HostCachePersistenceManagerTest.SeparateWrites\nI 57.079s run_tests_on_device(097c2c07258187a6) [ OK ] HostCachePersistenceManagerTest.SeparateWrites (6 ms)\nI 57.079s run_tests_on_device(097c2c07258187a6) [ RUN ] HostCachePersistenceManagerTest.MultipleWrites\nI 57.079s run_tests_on_device(097c2c07258187a6) [ OK ] HostCachePersistenceManagerTest.MultipleWrites (3 ms)\nI 57.079s run_tests_on_device(097c2c07258187a6) [ RUN ] HostCachePersistenceManagerTest.BatchedWrites\nI 57.079s run_tests_on_device(097c2c07258187a6) [ OK ] HostCachePersistenceManagerTest.BatchedWrites (4 ms)\nI 57.079s run_tests_on_device(097c2c07258187a6) [ RUN ] HostCachePersistenceManagerTest.InitAfterPrefs\nI 57.079s run_tests_on_device(097c2c07258187a6) [ OK ] HostCachePersistenceManagerTest.InitAfterPrefs (5 ms)\nI 57.079s run_tests_on_device(097c2c07258187a6) [ RUN ] HostCachePersistenceManagerTest.InitBeforePrefs\nI 57.079s run_tests_on_device(097c2c07258187a6) [ OK ] HostCachePersistenceManagerTest.InitBeforePrefs (4 ms)\nI 57.079s run_tests_on_device(097c2c07258187a6) [----------] 5 tests from HostCachePersistenceManagerTest (24 ms total)\nI 57.079s run_tests_on_device(097c2c07258187a6) \nI 57.079s run_tests_on_device(097c2c07258187a6) [----------] 14 tests from StaleHostResolverTest\nI 57.080s run_tests_on_device(097c2c07258187a6) [ RUN ] StaleHostResolverTest.Null\nI 57.080s run_tests_on_device(097c2c07258187a6) [ OK ] StaleHostResolverTest.Null (3 ms)\nI 57.080s run_tests_on_device(097c2c07258187a6) [ RUN ] StaleHostResolverTest.Create\nI 57.080s run_tests_on_device(097c2c07258187a6) [ OK ] StaleHostResolverTest.Create (8 ms)\nI 57.080s run_tests_on_device(097c2c07258187a6) [ RUN ] StaleHostResolverTest.Network\nI 57.080s run_tests_on_device(097c2c07258187a6) [ OK ] StaleHostResolverTest.Network (5 ms)\nI 57.080s run_tests_on_device(097c2c07258187a6) [ RUN ] StaleHostResolverTest.Hosts\nI 57.080s run_tests_on_device(097c2c07258187a6) [ OK ] StaleHostResolverTest.Hosts (4 ms)\nI 57.080s run_tests_on_device(097c2c07258187a6) [ RUN ] StaleHostResolverTest.FreshCache\nI 57.080s run_tests_on_device(097c2c07258187a6) [ OK ] StaleHostResolverTest.FreshCache (4 ms)\nI 57.080s run_tests_on_device(097c2c07258187a6) [ RUN ] StaleHostResolverTest.StaleCache_DestroyedResolver\nI 57.080s run_tests_on_device(097c2c07258187a6) [ OK ] StaleHostResolverTest.StaleCache_DestroyedResolver (1005 ms)\nI 57.080s run_tests_on_device(097c2c07258187a6) [ RUN ] StaleHostResolverTest.StaleCacheNameNotResolvedEnabled\nI 57.080s run_tests_on_device(097c2c07258187a6) [ OK ] StaleHostResolverTest.StaleCacheNameNotResolvedEnabled (5 ms)\nI 57.081s run_tests_on_device(097c2c07258187a6) [ RUN ] StaleHostResolverTest.StaleCacheNameNotResolvedDisabled\nI 57.081s run_tests_on_device(097c2c07258187a6) [ OK ] StaleHostResolverTest.StaleCacheNameNotResolvedDisabled (4 ms)\nI 57.081s run_tests_on_device(097c2c07258187a6) [ RUN ] StaleHostResolverTest.NetworkWithStaleCache\nI 57.081s run_tests_on_device(097c2c07258187a6) [ OK ] StaleHostResolverTest.NetworkWithStaleCache (5 ms)\nI 57.081s run_tests_on_device(097c2c07258187a6) [ RUN ] StaleHostResolverTest.CancelWithNoCache\nI 57.081s run_tests_on_device(097c2c07258187a6) [ OK ] StaleHostResolverTest.CancelWithNoCache (4 ms)\nI 57.081s run_tests_on_device(097c2c07258187a6) [ RUN ] StaleHostResolverTest.CancelWithStaleCache\nI 57.081s run_tests_on_device(097c2c07258187a6) [ OK ] StaleHostResolverTest.CancelWithStaleCache (4 ms)\nI 57.081s run_tests_on_device(097c2c07258187a6) [ RUN ] StaleHostResolverTest.ReturnStaleCacheSync\nI 57.081s run_tests_on_device(097c2c07258187a6) [ OK ] StaleHostResolverTest.ReturnStaleCacheSync (3 ms)\nI 57.081s run_tests_on_device(097c2c07258187a6) [ RUN ] StaleHostResolverTest.StaleUsability\nI 57.081s run_tests_on_device(097c2c07258187a6) [ OK ] StaleHostResolverTest.StaleUsability (47 ms)\nI 57.081s run_tests_on_device(097c2c07258187a6) [ RUN ] StaleHostResolverTest.CreatedByContext\nI 57.081s run_tests_on_device(097c2c07258187a6) [ OK ] StaleHostResolverTest.CreatedByContext (7 ms)\nI 57.082s run_tests_on_device(097c2c07258187a6) [----------] 14 tests from StaleHostResolverTest (1114 ms total)\nI 57.082s run_tests_on_device(097c2c07258187a6) \nI 57.082s run_tests_on_device(097c2c07258187a6) [----------] 9 tests from URLRequestContextConfigTest\nI 57.082s run_tests_on_device(097c2c07258187a6) [ RUN ] URLRequestContextConfigTest.TestExperimentalOptionParsing\nI 57.082s run_tests_on_device(097c2c07258187a6) [WARNING:url_request_context_config.cc(665)] Unrecognized Cronet experimental option \"UnknownOption\" with params \"{\nI 57.082s run_tests_on_device(097c2c07258187a6) \"foo\": true\nI 57.082s run_tests_on_device(097c2c07258187a6) }\nI 57.082s run_tests_on_device(097c2c07258187a6) \nI 57.082s run_tests_on_device(097c2c07258187a6) [ OK ] URLRequestContextConfigTest.TestExperimentalOptionParsing (8 ms)\nI 57.082s run_tests_on_device(097c2c07258187a6) [ RUN ] URLRequestContextConfigTest.SetSupportedQuicVersion\nI 57.082s run_tests_on_device(097c2c07258187a6) [ OK ] URLRequestContextConfigTest.SetSupportedQuicVersion (4 ms)\nI 57.082s run_tests_on_device(097c2c07258187a6) [ RUN ] URLRequestContextConfigTest.SetUnsupportedQuicVersion\nI 57.082s run_tests_on_device(097c2c07258187a6) [ OK ] URLRequestContextConfigTest.SetUnsupportedQuicVersion (14 ms)\nI 57.083s run_tests_on_device(097c2c07258187a6) [ RUN ] URLRequestContextConfigTest.SetQuicServerMigrationOptions\nI 57.083s run_tests_on_device(097c2c07258187a6) [ OK ] URLRequestContextConfigTest.SetQuicServerMigrationOptions (4 ms)\nI 57.083s run_tests_on_device(097c2c07258187a6) [ RUN ] URLRequestContextConfigTest.SetQuicGoAwaySessionsOnIPChangeViaExperimentOptions\nI 57.083s run_tests_on_device(097c2c07258187a6) [ OK ] URLRequestContextConfigTest.SetQuicGoAwaySessionsOnIPChangeViaExperimentOptions (3 ms)\nI 57.083s run_tests_on_device(097c2c07258187a6) [ RUN ] URLRequestContextConfigTest.SetQuicConnectionMigrationV2Options\nI 57.083s run_tests_on_device(097c2c07258187a6) [ OK ] URLRequestContextConfigTest.SetQuicConnectionMigrationV2Options (3 ms)\nI 57.083s run_tests_on_device(097c2c07258187a6) [ RUN ] URLRequestContextConfigTest.SetQuicStaleDNSracing\nI 57.083s run_tests_on_device(097c2c07258187a6) [ OK ] URLRequestContextConfigTest.SetQuicStaleDNSracing (3 ms)\nI 57.083s run_tests_on_device(097c2c07258187a6) [ RUN ] URLRequestContextConfigTest.SetQuicHostWhitelist\nI 57.083s run_tests_on_device(097c2c07258187a6) [ OK ] URLRequestContextConfigTest.SetQuicHostWhitelist (14 ms)\nI 57.083s run_tests_on_device(097c2c07258187a6) [ RUN ] URLRequestContextConfigTest.SetQuicMaxTimeBeforeCryptoHandshake\nI 57.083s run_tests_on_device(097c2c07258187a6) [ OK ] URLRequestContextConfigTest.SetQuicMaxTimeBeforeCryptoHandshake (3 ms)\nI 57.083s run_tests_on_device(097c2c07258187a6) [----------] 9 tests from URLRequestContextConfigTest (59 ms total)\nI 57.083s run_tests_on_device(097c2c07258187a6) \nI 57.084s run_tests_on_device(097c2c07258187a6) [----------] 2 tests from URLURLRequestContextConfigTest\nI 57.084s run_tests_on_device(097c2c07258187a6) [ RUN ] URLURLRequestContextConfigTest.SetQuicConnectionOptions\nI 57.084s run_tests_on_device(097c2c07258187a6) [ OK ] URLURLRequestContextConfigTest.SetQuicConnectionOptions (4 ms)\nI 57.084s run_tests_on_device(097c2c07258187a6) [ RUN ] URLURLRequestContextConfigTest.SetAcceptLanguageAndUserAgent\nI 57.084s run_tests_on_device(097c2c07258187a6) [ OK ] URLURLRequestContextConfigTest.SetAcceptLanguageAndUserAgent (4 ms)\nI 57.084s run_tests_on_device(097c2c07258187a6) [----------] 2 tests from URLURLRequestContextConfigTest (8 ms total)\nI 57.084s run_tests_on_device(097c2c07258187a6) \nI 57.084s run_tests_on_device(097c2c07258187a6) [----------] 9 tests from EngineUnitTest\nI 57.084s run_tests_on_device(097c2c07258187a6) [ RUN ] EngineUnitTest.HasNoRequestFinishedInfoListener\nI 57.084s run_tests_on_device(097c2c07258187a6) [ OK ] EngineUnitTest.HasNoRequestFinishedInfoListener (0 ms)\nI 57.084s run_tests_on_device(097c2c07258187a6) [ RUN ] EngineUnitTest.HasRequestFinishedInfoListener\nI 57.084s run_tests_on_device(097c2c07258187a6) [ OK ] EngineUnitTest.HasRequestFinishedInfoListener (0 ms)\nI 57.084s run_tests_on_device(097c2c07258187a6) [ RUN ] EngineUnitTest.AddNullRequestFinishedInfoListener\nI 57.085s run_tests_on_device(097c2c07258187a6) [ERROR:engine.cc(267)] Both listener and executor must be non-null. listener: 0x0 executor: 0x5aea2c70.\nI 57.085s run_tests_on_device(097c2c07258187a6) [ OK ] EngineUnitTest.AddNullRequestFinishedInfoListener (1 ms)\nI 57.085s run_tests_on_device(097c2c07258187a6) [ RUN ] EngineUnitTest.AddNullRequestFinishedInfoExecutor\nI 57.085s run_tests_on_device(097c2c07258187a6) [ERROR:engine.cc(267)] Both listener and executor must be non-null. listener: 0x5aea2c30 executor: 0x0.\nI 57.085s run_tests_on_device(097c2c07258187a6) [ OK ] EngineUnitTest.AddNullRequestFinishedInfoExecutor (1 ms)\nI 57.085s run_tests_on_device(097c2c07258187a6) [ RUN ] EngineUnitTest.AddNullRequestFinishedInfoListenerAndExecutor\nI 57.085s run_tests_on_device(097c2c07258187a6) [ERROR:engine.cc(267)] Both listener and executor must be non-null. listener: 0x0 executor: 0x0.\nI 57.085s run_tests_on_device(097c2c07258187a6) [ OK ] EngineUnitTest.AddNullRequestFinishedInfoListenerAndExecutor (2 ms)\nI 57.085s run_tests_on_device(097c2c07258187a6) [ RUN ] EngineUnitTest.AddListenerTwice\nI 57.085s run_tests_on_device(097c2c07258187a6) [ERROR:engine.cc(273)] Listener 0x5aea01d0 already registered with executor 0x5aea01b0, *NOT* changing to new executor 0x5aea01b0.\nI 57.085s run_tests_on_device(097c2c07258187a6) [ OK ] EngineUnitTest.AddListenerTwice (1 ms)\nI 57.085s run_tests_on_device(097c2c07258187a6) [ RUN ] EngineUnitTest.RemoveNonexistentListener\nI 57.085s run_tests_on_device(097c2c07258187a6) [ERROR:engine.cc(286)] Asked to erase non-existent RequestFinishedInfoListener 0x5aea0150.\nI 57.086s run_tests_on_device(097c2c07258187a6) [ OK ] EngineUnitTest.RemoveNonexistentListener (2 ms)\nI 57.086s run_tests_on_device(097c2c07258187a6) [ RUN ] EngineUnitTest.RemoveNonexistentListenerWithAddedListener\nI 57.086s run_tests_on_device(097c2c07258187a6) [ERROR:engine.cc(286)] Asked to erase non-existent RequestFinishedInfoListener 0x5aea00f0.\nI 57.086s run_tests_on_device(097c2c07258187a6) [ OK ] EngineUnitTest.RemoveNonexistentListenerWithAddedListener (1 ms)\nI 57.086s run_tests_on_device(097c2c07258187a6) [ RUN ] EngineUnitTest.RemoveNullListener\nI 57.086s run_tests_on_device(097c2c07258187a6) [ERROR:engine.cc(286)] Asked to erase non-existent RequestFinishedInfoListener 0x0.\nI 57.086s run_tests_on_device(097c2c07258187a6) [ OK ] EngineUnitTest.RemoveNullListener (2 ms)\nI 57.086s run_tests_on_device(097c2c07258187a6) [----------] 9 tests from EngineUnitTest (13 ms total)\nI 57.086s run_tests_on_device(097c2c07258187a6) \nI 57.086s run_tests_on_device(097c2c07258187a6) [----------] 5 tests from NativeMetricsUtilTest\nI 57.086s run_tests_on_device(097c2c07258187a6) [ RUN ] NativeMetricsUtilTest.ConvertsTimes\nI 57.086s run_tests_on_device(097c2c07258187a6) [ OK ] NativeMetricsUtilTest.ConvertsTimes (1 ms)\nI 57.086s run_tests_on_device(097c2c07258187a6) [ RUN ] NativeMetricsUtilTest.OverwritesOldOutParam\nI 57.087s run_tests_on_device(097c2c07258187a6) [ OK ] NativeMetricsUtilTest.OverwritesOldOutParam (1 ms)\nI 57.087s run_tests_on_device(097c2c07258187a6) [ RUN ] NativeMetricsUtilTest.NullTicks\nI 57.087s run_tests_on_device(097c2c07258187a6) [ OK ] NativeMetricsUtilTest.NullTicks (1 ms)\nI 57.087s run_tests_on_device(097c2c07258187a6) [ RUN ] NativeMetricsUtilTest.NullStartTicks\nI 57.087s run_tests_on_device(097c2c07258187a6) [ OK ] NativeMetricsUtilTest.NullStartTicks (1 ms)\nI 57.087s run_tests_on_device(097c2c07258187a6) [ RUN ] NativeMetricsUtilTest.NullStartTime\nI 57.087s run_tests_on_device(097c2c07258187a6) \nI 57.087s run_tests_on_device(097c2c07258187a6) [WARNING] ../../components/cronet/native/native_metrics_util_test.cc:69:: Death tests are not supported on this platform.\nI 57.087s run_tests_on_device(097c2c07258187a6) Statement 'ConvertTime(base::TimeTicks::UnixEpoch() + event_delta, base::TimeTicks::UnixEpoch() + start_delta, base::Time(), &converted)' cannot be verified.\nI 57.087s run_tests_on_device(097c2c07258187a6) [ OK ] NativeMetricsUtilTest.NullStartTime (1 ms)\nI 57.087s run_tests_on_device(097c2c07258187a6) [----------] 5 tests from NativeMetricsUtilTest (8 ms total)\nI 57.087s run_tests_on_device(097c2c07258187a6) \nI 57.087s run_tests_on_device(097c2c07258187a6) [----------] 3 tests from RunnablesTest\nI 57.088s run_tests_on_device(097c2c07258187a6) [ RUN ] RunnablesTest.TestRunCallbackOnExecutor\nI 57.088s run_tests_on_device(097c2c07258187a6) [ OK ] RunnablesTest.TestRunCallbackOnExecutor (3 ms)\nI 57.088s run_tests_on_device(097c2c07258187a6) [ RUN ] RunnablesTest.TestRunOnceClosureOnExecutor\nI 57.088s run_tests_on_device(097c2c07258187a6) [ OK ] RunnablesTest.TestRunOnceClosureOnExecutor (3 ms)\nI 57.088s run_tests_on_device(097c2c07258187a6) [ RUN ] RunnablesTest.TestCronetBuffer\nI 57.088s run_tests_on_device(097c2c07258187a6) [ OK ] RunnablesTest.TestCronetBuffer (3 ms)\nI 57.088s run_tests_on_device(097c2c07258187a6) [----------] 3 tests from RunnablesTest (10 ms total)\nI 57.088s run_tests_on_device(097c2c07258187a6) \nI 57.088s run_tests_on_device(097c2c07258187a6) [----------] 1 test from Cronet_BufferTest\nI 57.088s run_tests_on_device(097c2c07258187a6) [ RUN ] Cronet_BufferTest.TestCreate\nI 57.088s run_tests_on_device(097c2c07258187a6) [ OK ] Cronet_BufferTest.TestCreate (1 ms)\nI 57.088s run_tests_on_device(097c2c07258187a6) [----------] 1 test from Cronet_BufferTest (1 ms total)\nI 57.088s run_tests_on_device(097c2c07258187a6) \nI 57.089s run_tests_on_device(097c2c07258187a6) [----------] 1 test from Cronet_BufferCallbackTest\nI 57.089s run_tests_on_device(097c2c07258187a6) [ RUN ] Cronet_BufferCallbackTest.TestCreate\nI 57.089s run_tests_on_device(097c2c07258187a6) [ OK ] Cronet_BufferCallbackTest.TestCreate (1 ms)\nI 57.089s run_tests_on_device(097c2c07258187a6) [----------] 1 test from Cronet_BufferCallbackTest (1 ms total)\nI 57.089s run_tests_on_device(097c2c07258187a6) \nI 57.089s run_tests_on_device(097c2c07258187a6) [----------] 1 test from Cronet_RunnableTest\nI 57.089s run_tests_on_device(097c2c07258187a6) [ RUN ] Cronet_RunnableTest.TestCreate\nI 57.089s run_tests_on_device(097c2c07258187a6) [ OK ] Cronet_RunnableTest.TestCreate (1 ms)\nI 57.089s run_tests_on_device(097c2c07258187a6) [----------] 1 test from Cronet_RunnableTest (1 ms total)\nI 57.089s run_tests_on_device(097c2c07258187a6) \nI 57.089s run_tests_on_device(097c2c07258187a6) [----------] 1 test from Cronet_ExecutorTest\nI 57.089s run_tests_on_device(097c2c07258187a6) [ RUN ] Cronet_ExecutorTest.TestCreate\nI 57.090s run_tests_on_device(097c2c07258187a6) [ OK ] Cronet_ExecutorTest.TestCreate (1 ms)\nI 57.090s run_tests_on_device(097c2c07258187a6) [----------] 1 test from Cronet_ExecutorTest (1 ms total)\nI 57.090s run_tests_on_device(097c2c07258187a6) \nI 57.090s run_tests_on_device(097c2c07258187a6) [----------] 1 test from Cronet_EngineTest\nI 57.090s run_tests_on_device(097c2c07258187a6) [ RUN ] Cronet_EngineTest.TestCreate\nI 57.090s run_tests_on_device(097c2c07258187a6) [ OK ] Cronet_EngineTest.TestCreate (1 ms)\nI 57.090s run_tests_on_device(097c2c07258187a6) [----------] 1 test from Cronet_EngineTest (2 ms total)\nI 57.090s run_tests_on_device(097c2c07258187a6) \nI 57.090s run_tests_on_device(097c2c07258187a6) [----------] 1 test from Cronet_UrlRequestStatusListenerTest\nI 57.090s run_tests_on_device(097c2c07258187a6) [ RUN ] Cronet_UrlRequestStatusListenerTest.TestCreate\nI 57.090s run_tests_on_device(097c2c07258187a6) [ OK ] Cronet_UrlRequestStatusListenerTest.TestCreate (1 ms)\nI 57.090s run_tests_on_device(097c2c07258187a6) [----------] 1 test from Cronet_UrlRequestStatusListenerTest (2 ms total)\nI 57.091s run_tests_on_device(097c2c07258187a6) \nI 57.091s run_tests_on_device(097c2c07258187a6) [----------] 1 test from Cronet_UrlRequestCallbackTest\nI 57.091s run_tests_on_device(097c2c07258187a6) [ RUN ] Cronet_UrlRequestCallbackTest.TestCreate\nI 57.091s run_tests_on_device(097c2c07258187a6) [ OK ] Cronet_UrlRequestCallbackTest.TestCreate (1 ms)\nI 57.091s run_tests_on_device(097c2c07258187a6) [----------] 1 test from Cronet_UrlRequestCallbackTest (2 ms total)\nI 57.091s run_tests_on_device(097c2c07258187a6) \nI 57.091s run_tests_on_device(097c2c07258187a6) [----------] 1 test from Cronet_UploadDataSinkTest\nI 57.091s run_tests_on_device(097c2c07258187a6) [ RUN ] Cronet_UploadDataSinkTest.TestCreate\nI 57.091s run_tests_on_device(097c2c07258187a6) [ OK ] Cronet_UploadDataSinkTest.TestCreate (2 ms)\nI 57.091s run_tests_on_device(097c2c07258187a6) [----------] 1 test from Cronet_UploadDataSinkTest (2 ms total)\nI 57.091s run_tests_on_device(097c2c07258187a6) \nI 57.091s run_tests_on_device(097c2c07258187a6) [----------] 1 test from Cronet_UploadDataProviderTest\nI 57.092s run_tests_on_device(097c2c07258187a6) [ RUN ] Cronet_UploadDataProviderTest.TestCreate\nI 57.092s run_tests_on_device(097c2c07258187a6) [ OK ] Cronet_UploadDataProviderTest.TestCreate (1 ms)\nI 57.092s run_tests_on_device(097c2c07258187a6) [----------] 1 test from Cronet_UploadDataProviderTest (2 ms total)\nI 57.092s run_tests_on_device(097c2c07258187a6) \nI 57.092s run_tests_on_device(097c2c07258187a6) [----------] 1 test from Cronet_UrlRequestTest\nI 57.092s run_tests_on_device(097c2c07258187a6) [ RUN ] Cronet_UrlRequestTest.TestCreate\nI 57.092s run_tests_on_device(097c2c07258187a6) [ OK ] Cronet_UrlRequestTest.TestCreate (1 ms)\nI 57.092s run_tests_on_device(097c2c07258187a6) [----------] 1 test from Cronet_UrlRequestTest (1 ms total)\nI 57.092s run_tests_on_device(097c2c07258187a6) \nI 57.092s run_tests_on_device(097c2c07258187a6) [----------] 1 test from Cronet_RequestFinishedInfoListenerTest\nI 57.092s run_tests_on_device(097c2c07258187a6) [ RUN ] Cronet_RequestFinishedInfoListenerTest.TestCreate\nI 57.092s run_tests_on_device(097c2c07258187a6) [ OK ] Cronet_RequestFinishedInfoListenerTest.TestCreate (1 ms)\nI 57.092s run_tests_on_device(097c2c07258187a6) [----------] 1 test from Cronet_RequestFinishedInfoListenerTest (2 ms total)\nI 57.092s run_tests_on_device(097c2c07258187a6) \nI 57.093s run_tests_on_device(097c2c07258187a6) [----------] 10 tests from CronetStructTest\nI 57.093s run_tests_on_device(097c2c07258187a6) [ RUN ] CronetStructTest.TestCronet_Error\nI 57.093s run_tests_on_device(097c2c07258187a6) [ OK ] CronetStructTest.TestCronet_Error (2 ms)\nI 57.093s run_tests_on_device(097c2c07258187a6) [ RUN ] CronetStructTest.TestCronet_QuicHint\nI 57.093s run_tests_on_device(097c2c07258187a6) [ OK ] CronetStructTest.TestCronet_QuicHint (1 ms)\nI 57.093s run_tests_on_device(097c2c07258187a6) [ RUN ] CronetStructTest.TestCronet_PublicKeyPins\nI 57.093s run_tests_on_device(097c2c07258187a6) [ OK ] CronetStructTest.TestCronet_PublicKeyPins (1 ms)\nI 57.093s run_tests_on_device(097c2c07258187a6) [ RUN ] CronetStructTest.TestCronet_EngineParams\nI 57.093s run_tests_on_device(097c2c07258187a6) [ OK ] CronetStructTest.TestCronet_EngineParams (1 ms)\nI 57.093s run_tests_on_device(097c2c07258187a6) [ RUN ] CronetStructTest.TestCronet_HttpHeader\nI 57.093s run_tests_on_device(097c2c07258187a6) [ OK ] CronetStructTest.TestCronet_HttpHeader (1 ms)\nI 57.093s run_tests_on_device(097c2c07258187a6) [ RUN ] CronetStructTest.TestCronet_UrlResponseInfo\nI 57.093s run_tests_on_device(097c2c07258187a6) [ OK ] CronetStructTest.TestCronet_UrlResponseInfo (1 ms)\nI 57.094s run_tests_on_device(097c2c07258187a6) [ RUN ] CronetStructTest.TestCronet_UrlRequestParams\nI 57.094s run_tests_on_device(097c2c07258187a6) [ OK ] CronetStructTest.TestCronet_UrlRequestParams (1 ms)\nI 57.094s run_tests_on_device(097c2c07258187a6) [ RUN ] CronetStructTest.TestCronet_DateTime\nI 57.094s run_tests_on_device(097c2c07258187a6) [ OK ] CronetStructTest.TestCronet_DateTime (1 ms)\nI 57.094s run_tests_on_device(097c2c07258187a6) [ RUN ] CronetStructTest.TestCronet_Metrics\nI 57.094s run_tests_on_device(097c2c07258187a6) [ OK ] CronetStructTest.TestCronet_Metrics (1 ms)\nI 57.094s run_tests_on_device(097c2c07258187a6) [ RUN ] CronetStructTest.TestCronet_RequestFinishedInfo\nI 57.094s run_tests_on_device(097c2c07258187a6) [ OK ] CronetStructTest.TestCronet_RequestFinishedInfo (1 ms)\nI 57.094s run_tests_on_device(097c2c07258187a6) [----------] 10 tests from CronetStructTest (11 ms total)\nI 57.094s run_tests_on_device(097c2c07258187a6) \nI 57.094s run_tests_on_device(097c2c07258187a6) [----------] Global test environment tear-down\nI 57.094s run_tests_on_device(097c2c07258187a6) [==========] 69 tests from 20 test suites ran. (1281 ms total)\nI 57.094s run_tests_on_device(097c2c07258187a6) [ PASSED ] 69 tests.\nI 57.095s run_tests_on_device(097c2c07258187a6) <<ScopedMainEntryLogger\nI 57.103s run_tests_on_device(097c2c07258187a6) Finished running tests on this device.\nI 57.105s Main FINISHED TRY #1/3\nI 57.105s Main All tests completed.\nC 57.106s Main ********************************************************************************\nC 57.106s Main Summary\nC 57.106s Main ********************************************************************************\nC 57.106s Main [==========] 69 tests ran.\nC 57.106s Main [ PASSED ] 69 tests.\nC 57.106s Main ********************************************************************************\nI 57.148s tear_down_device(097c2c07258187a6) Wrote device cache: /b/swarming/w/ir/out/Release/device_cache_097c2c07258187a6.json\nI 57.156s Main Generated json results file at /b/swarming/w/ituY21nA/tmplVzZPQ\nI 57.156s Main Finishing archiving output.\n[W2019-04-26T02:58:21.141677Z 10670 0 butler.go:240] Butler Context was cancelled. Initiating shutdown. {\"error\":\"context canceled\"}\nINFO:devil.utils.timeout_retry:condition 'logdog_stopped' not met\n[E2019-04-26T02:58:21.176297Z 10670 0 main.go:205] Butler terminated with error. {\"error\":\"context canceled\"}\n[E2019-04-26T02:58:21.176388Z 10670 0 main.go:233] Failed to serve.\noriginal error: context canceled\n\n[I2019-04-26T02:58:21.176632Z 10670 0 main.go:349] Terminating. {\"returnCode\":250}\nINFO:devil.utils.timeout_retry:condition 'logdog_stopped' met\n>> Remounting /system rw\nadbd is already running as root\nremount succeeded\nTarget architecture: arm\n>> Uninstalling ASan\n>> Restarting shell\n>> Done\n", "outputs_ref": { "isolated": "0185cd79befe8c716cceac994143655fc049856e", "isolatedserver": "https://isolateserver.appspot.com", "namespace": "default-gzip" }, "run_id": "4470643415276e11", "server_versions": [ "4274-a0a5f8c" ], "started_ts": "2019-04-26T02:56:32.261730", "state": "COMPLETED", "tags": [ "build_is_experimental:false", "buildername:android-cronet-asan-arm-rel", "buildnumber:26186", "data:a731d383906d46e2c8c4bbc8fad528d49e56a397", "device_os:KTU84P", "device_type:hammerhead", "master:chromium.android", "name:cronet_unittests_android", "os:Android", "pool:Chrome", "priority:25", "project:chromium", "purpose:CI", "purpose:luci", "purpose:post-commit", "service_account:none", "slavename:swarm2473-c4", "spec_name:chromium.ci:android-cronet-asan-arm-rel", "stepname:cronet_unittests_android on Android device Nexus 5", "swarming.pool.template:none", "swarming.pool.version:745db0db4f4d785f07bf98c41e6efece4c45d99b", "user:None" ], "task_id": "4470643415276e10", "try_number": "1" } ] }