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": [ "8.1.86" ] }, { "key": "device_os", "value": [ "M", "MMB29Q" ] }, { "key": "device_os_flavor", "value": [ "google" ] }, { "key": "device_playstore_version", "value": [ "5.12.7" ] }, { "key": "device_type", "value": [ "bullhead" ] }, { "key": "gce", "value": [ "0" ] }, { "key": "id", "value": [ "build421-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": [ "4243-f38882f" ] }, { "key": "temp_band", "value": [ "<30" ] }, { "key": "zone", "value": [ "us", "us-golo", "us-golo-1" ] } ], "bot_id": "build421-m1--device4", "bot_version": "064ec812aa4f9a2440e16ca0cb8c7e782b386213be8a0d98838983ab46572591", "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": "FKuIl8zBIQXKXk1zkimViQOM9pDPDJY7B6ljc0DunJMC" }, { "package_name": "infra/tools/luci/vpython/linux-amd64", "path": ".swarming_module", "version": "iZzzC2-wxiQIxGFIJ86vgSeuxBHE_V8DIdVb6fEfRM8C" }, { "package_name": "infra/tools/luci/logdog/butler/linux-amd64", "path": "bin", "version": "a57ad614c01fec9fa4259473c8ea3fd992d7c349" } ] }, "completed_ts": "2019-04-05T23:52:49.937829", "costs_usd": [ 0.04376613815650419 ], "created_ts": "2019-04-05T23:46:21.876043", "duration": 302.6860120296478, "modified_ts": "2019-04-05T23:52:49.937829", "name": "vr_android_unittests on Android device Nexus 5X (with patch)/Android/fcbdcd8e55/android_n5x_swarming_dbg/127", "output": "Additional 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/9ede37/bin/python ../../build/android/test_wrapper/logdog_wrapper.py --target vr_android_unittests --logdog-bin-cmd ../../bin/logdog_butler --store-tombstones --gs-results-bucket=chromium-result-details --recover-devices --test-launcher-summary-output=/b/swarming/w/ioFzDVLi/output.json\n\nI 0.000s Main command: bin/../../../build/android/test_runner.py gtest --output-directory bin/../. --runtime-deps-path bin/../gen.runtime/chrome/browser/android/vr/vr_android_unittests__test_runner_script.runtime_deps --suite vr_android_unittests -v --store-tombstones --gs-results-bucket=chromium-result-details --recover-devices --test-launcher-summary-output=/b/swarming/w/ioFzDVLi/output.json\nI 0.006s Main condition '<lambda>' met\nI 0.007s Main condition '<lambda>' met\nI 3.209s TimeoutThread-1-for-prepare_device(01e15e7f90cc936a) condition 'sd_card_ready' met (0.0s)\nI 4.269s TimeoutThread-1-for-prepare_device(01e15e7f90cc936a) condition 'pm_ready' met (1.1s)\nI 4.296s TimeoutThread-1-for-prepare_device(01e15e7f90cc936a) condition 'boot_completed' met (1.1s)\nI 64.397s individual_device_set_up(01e15e7f90cc936a) Still working on Install(01e15e7f90cc936a, <devil.android.apk_helper.ApkHelper object at 0x7f37dde9eb50>, allow_downgrade=True, retries=2, reinstall=True, timeout=120, permissions=['android.permission.ACCESS_NETWORK_STATE', 'android.permission.BLUETOOTH', 'android.permission.BLUETOOTH_ADMIN', 'android.permission.CHANGE_CONFIGURATION', 'android.permission.CAMERA', 'android.permission.INTERNET', 'android.permission.MODIFY_AUDIO_SETTINGS', 'android.permission.RECORD_AUDIO', 'android.permission.SET_TIME_ZONE', 'android.permission.WAKE_LOCK', 'android.permission.WRITE_EXTERNAL_STORAGE', 'android.permission.READ_PHONE_STATE', 'android.permission.READ_EXTERNAL_STORAGE'])\nC 118.963s individual_device_set_up(01e15e7f90cc936a) (TimeoutThread-1-for-individual_device_set_up(01e15e7f90cc936a)) Exception on Install(01e15e7f90cc936a, <devil.android.apk_helper.ApkHelper object at 0x7f37dde9eb50>, allow_downgrade=True, retries=2, reinstall=True, timeout=120, permissions=['android.permission.ACCESS_NETWORK_STATE', 'android.permission.BLUETOOTH', 'android.permission.BLUETOOTH_ADMIN', 'android.permission.CHANGE_CONFIGURATION', 'android.permission.CAMERA', 'android.permission.INTERNET', 'android.permission.MODIFY_AUDIO_SETTINGS', 'android.permission.RECORD_AUDIO', 'android.permission.SET_TIME_ZONE', 'android.permission.WAKE_LOCK', 'android.permission.WRITE_EXTERNAL_STORAGE', 'android.permission.READ_PHONE_STATE', 'android.permission.READ_EXTERNAL_STORAGE']), attempt 1 of 3: CommandTimeoutError('Timeout',)\nI 179.040s individual_device_set_up(01e15e7f90cc936a) Still working on Install(01e15e7f90cc936a, <devil.android.apk_helper.ApkHelper object at 0x7f37dde9eb50>, allow_downgrade=True, retries=2, reinstall=True, timeout=120, permissions=['android.permission.ACCESS_NETWORK_STATE', 'android.permission.BLUETOOTH', 'android.permission.BLUETOOTH_ADMIN', 'android.permission.CHANGE_CONFIGURATION', 'android.permission.CAMERA', 'android.permission.INTERNET', 'android.permission.MODIFY_AUDIO_SETTINGS', 'android.permission.RECORD_AUDIO', 'android.permission.SET_TIME_ZONE', 'android.permission.WAKE_LOCK', 'android.permission.WRITE_EXTERNAL_STORAGE', 'android.permission.READ_PHONE_STATE', 'android.permission.READ_EXTERNAL_STORAGE'])\nI 229.663s TimeoutThread-2-for-individual_device_set_up(01e15e7f90cc936a) Setting permissions for org.chromium.native_test.\nI 233.770s calculate_device_checksums condition '<lambda>' met (0.2s)\nI 237.632s TimeoutThread-1-for-individual_device_set_up(01e15e7f90cc936a) Pushing 2530 files via .zip of size 6010251\nI 281.932s list_tests(01e15e7f90cc936a) flags:\nI 281.932s list_tests(01e15e7f90cc936a) --gtest_list_tests\nI 286.804s list_tests(01e15e7f90cc936a) /sdcard/temp_file-9d3f3cf56f240.gtest_out size on device: 446\nI 286.903s Main Using external sharding settings. This is shard 0/1\nI 286.904s Main STARTING TRY #1/3\nI 286.904s Main Will run 4 tests on 1 devices: 01e15e7f90cc936a\nW 286.969s run_tests_on_device(01e15e7f90cc936a) No tombstones to clear.\nI 292.973s run_tests_on_device(01e15e7f90cc936a) flags:\nI 292.973s run_tests_on_device(01e15e7f90cc936a) Getting logdog client.\nI 296.375s run_tests_on_device(01e15e7f90cc936a) /sdcard/temp_file-f6381d37ceba9.gtest_out size on device: 1235\nI 296.448s run_tests_on_device(01e15e7f90cc936a) Logcat saved to https://luci-logdog.appspot.com/v/?s=chromium%2Fandroid%2Fswarming%2Flogcats%2F4408b6ec40e44111%2F%2B%2Flogcat_logcat_-1765853114214702429_20190405T235240-UTC_01e15e7f90cc936a\nI 296.448s archive Writing text to logdog stream, logcat_logcat_-1765853114214702429_20190405T235240-UTC_01e15e7f90cc936a\nI 297.066s TimeoutThread-1-for-run_tests_on_device(01e15e7f90cc936a) Setting permissions for org.chromium.native_test.\nI 300.154s run_tests_on_device(01e15e7f90cc936a) [ERROR:memory_mapped_file.cc(53)] Couldn't open /storage/emulated/0/chromium_tests_root/paks/vr_test.pak\nI 300.154s run_tests_on_device(01e15e7f90cc936a) [ERROR:data_pack.cc(250)] Failed to mmap datapack\nI 300.154s run_tests_on_device(01e15e7f90cc936a) [ERROR:memory_mapped_file.cc(53)] Couldn't open /storage/emulated/0/chromium_tests_root/paks/vr_test.pak\nI 300.154s run_tests_on_device(01e15e7f90cc936a) [ERROR:data_pack.cc(250)] Failed to mmap datapack\nI 300.155s run_tests_on_device(01e15e7f90cc936a) >>ScopedMainEntryLogger\nI 300.155s run_tests_on_device(01e15e7f90cc936a) Note: Google Test filter = ArCoreDeviceTest.GetFrameData:ArCoreDeviceTest.RequestHitTest:ArCoreDeviceTest.RequestSession:ArCoreDeviceTest.SetDisplayGeometry\nI 300.155s run_tests_on_device(01e15e7f90cc936a) [==========] Running 4 tests from 1 test suite.\nI 300.155s run_tests_on_device(01e15e7f90cc936a) [----------] Global test environment set-up.\nI 300.155s run_tests_on_device(01e15e7f90cc936a) [----------] 4 tests from ArCoreDeviceTest\nI 300.155s run_tests_on_device(01e15e7f90cc936a) [ RUN ] ArCoreDeviceTest.RequestSession\nI 300.155s run_tests_on_device(01e15e7f90cc936a) [ OK ] ArCoreDeviceTest.RequestSession (206 ms)\nI 300.155s run_tests_on_device(01e15e7f90cc936a) [ RUN ] ArCoreDeviceTest.GetFrameData\nI 300.155s run_tests_on_device(01e15e7f90cc936a) [ OK ] ArCoreDeviceTest.GetFrameData (63 ms)\nI 300.155s run_tests_on_device(01e15e7f90cc936a) [ RUN ] ArCoreDeviceTest.SetDisplayGeometry\nI 300.155s run_tests_on_device(01e15e7f90cc936a) [ OK ] ArCoreDeviceTest.SetDisplayGeometry (45 ms)\nI 300.156s run_tests_on_device(01e15e7f90cc936a) [ RUN ] ArCoreDeviceTest.RequestHitTest\nI 300.156s run_tests_on_device(01e15e7f90cc936a) [ OK ] ArCoreDeviceTest.RequestHitTest (47 ms)\nI 300.156s run_tests_on_device(01e15e7f90cc936a) [----------] 4 tests from ArCoreDeviceTest (369 ms total)\nI 300.156s run_tests_on_device(01e15e7f90cc936a) \nI 300.156s run_tests_on_device(01e15e7f90cc936a) [----------] Global test environment tear-down\nI 300.156s run_tests_on_device(01e15e7f90cc936a) [==========] 4 tests from 1 test suite ran. (503 ms total)\nI 300.156s run_tests_on_device(01e15e7f90cc936a) [ PASSED ] 4 tests.\nI 300.156s run_tests_on_device(01e15e7f90cc936a) <<ScopedMainEntryLogger\nI 300.158s run_tests_on_device(01e15e7f90cc936a) Finished running tests on this device.\nI 300.185s Main FINISHED TRY #1/3\nI 300.185s Main All tests completed.\nC 300.186s Main ********************************************************************************\nC 300.186s Main Summary\nC 300.186s Main ********************************************************************************\nC 300.186s Main [==========] 4 tests ran.\nC 300.186s Main [ PASSED ] 4 tests.\nC 300.186s Main ********************************************************************************\nI 300.190s tear_down_device(01e15e7f90cc936a) Wrote device cache: /b/swarming/w/ir/out/Debug/device_cache_01e15e7f90cc936a.json\nI 300.192s Main Generated json results file at /b/swarming/w/itgVLPaU/tmplJLrWG\nI 300.193s Main Finishing archiving output.\n[W2019-04-05T23:52:47.489192Z 10399 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-05T23:52:47.590296Z 10399 0 main.go:205] Butler terminated with error. {\"error\":\"context canceled\"}\n[E2019-04-05T23:52:47.590349Z 10399 0 main.go:233] Failed to serve.\noriginal error: context canceled\n\n[I2019-04-05T23:52:47.590490Z 10399 0 main.go:349] Terminating. {\"returnCode\":250}\nINFO:devil.utils.timeout_retry:condition 'logdog_stopped' met\n", "outputs_ref": { "isolated": "51dff7354b6f198a88883902e0ba10ca37b2ac8a", "isolatedserver": "https://isolateserver.appspot.com", "namespace": "default-gzip" }, "run_id": "4408b6ec40e44111", "server_versions": [ "4243-f38882f" ], "started_ts": "2019-04-05T23:46:22.450206", "state": "COMPLETED", "tags": [ "build_is_experimental:false", "buildername:android_n5x_swarming_dbg", "buildnumber:127", "data:fcbdcd8e550dd170cd2f869f7400bc05979115d9", "device_os:MMB29Q", "device_type:bullhead", "gerrit:https://chromium-review.googlesource.com/c/1552116/11", "master:tryserver.chromium.android", "name:vr_android_unittests", "os:Android", "patch_project:chromium/src", "pool:Chrome", "priority:30", "project:chromium", "purpose:ManualTS", "purpose:luci", "purpose:pre-commit", "service_account:none", "slavename:swarm989-c4", "spec_name:chromium.try:android_n5x_swarming_dbg", "stepname:vr_android_unittests on Android device Nexus 5X (with patch)", "swarming.pool.template:none", "swarming.pool.version:fdda6585e37b99b10d91a62a778692b0bd04f909", "user:None" ], "task_id": "4408b6ec40e44110", "try_number": "1" } ] }