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

python -u /b/swarming/w/ir/kitchen-checkout/build/scripts/tools/runit.py --show-path --with-third-party-lib -- python /b/swarming/w/ir/kitchen-checkout/build/scripts/slave/recipe_modules/chromium_swarming/resources/collect_task.py --verbose -o /b/swarming/w/ir/tmp/t/tmpYhCoEd.json --task-output-dir /b/swarming/w/ir/tmp/t/tmpulPu2x --merge-script /b/swarming/w/ir/cache/builder/src/tools/perf/process_perf_results.py --merge-script-stdout-file /b/swarming/w/ir/tmp/t/tmpvFCn6smerge_script_log --merge-additional-args '["--smoke-test-mode"]' --build-properties '{"bot_id": "swarm1596-c4", "branch": "refs/heads/master", "buildbucket": {"build": {"bucket": "luci.chromium.ci", "created_by": "project:chromium", "created_ts": 1556219469762629, "id": "8915179329232731856", "project": "chromium", "tags": ["build_address:luci.chromium.ci/android-marshmallow-arm64-rel/14713", "builder:android-marshmallow-arm64-rel", "buildset:commit/git/09ef820ccf81b4e38eb55dbfe01719ca4fdd8b63", "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/09ef820ccf81b4e38eb55dbfe01719ca4fdd8b63", "gitiles_ref:refs/heads/master", "scheduler_invocation_id:9080609391506703872", "scheduler_job_id:chromium/android-marshmallow-arm64-rel", "user_agent:luci-scheduler"]}, "hostname": "cr-buildbucket.appspot.com"}, "buildername": "android-marshmallow-arm64-rel", "buildnumber": 14713, "got_angle_revision": "fb6fbdbc582dff1ab9c376f4e759cd728bd74411", "got_dawn_revision": "22cee9ae36b23a8908525d668ef4728a26c931c4", "got_nacl_revision": "6abc006f6760ec49350cd45e8bccbff4809725ac", "got_revision": "09ef820ccf81b4e38eb55dbfe01719ca4fdd8b63", "got_revision_cp": "refs/heads/master@{#654136}", "got_swarming_client_revision": "aa60736aded9fc32a0e21a81f5fc51f6009d01f3", "got_v8_revision": "2531f0784031894907b009a0a2de46ea8bc04547", "got_v8_revision_cp": "refs/heads/7.6.29@{#1}", "got_webrtc_revision": "ff727a8abb360373fa9051518810e7d00604e695", "got_webrtc_revision_cp": "refs/heads/master@{#27776}", "mastername": "chromium.android", "path_config": "generic", "recipe": "chromium", "repository": "https://chromium.googlesource.com/chromium/src.git", "revision": "09ef820ccf81b4e38eb55dbfe01719ca4fdd8b63"}' --summary-json-file /b/swarming/w/ir/tmp/t/tmpqqWjPu.json -- swarming collect -server https://chromium-swarm.appspot.com -worker 50 -task-summary-python -task-output-stdout all -verbose -requests-json /b/swarming/w/ir/tmp/t/tmpsgzMKX.json in dir /b/swarming/w/ir/k: allow_subannotations: False cmd: ['python', '-u', '/b/swarming/w/ir/kitchen-checkout/build/scripts/tools/runit.py', '--show-path', '--with-third-party-lib', '--', 'python', '/b/swarming/w/ir/kitchen-checkout/build/scripts/slave/recipe_modules/chromium_swarming/resources/collect_task.py', '--verbose', '-o', '/b/swarming/w/ir/tmp/t/tmpYhCoEd.json', '--task-output-dir', '/b/swarming/w/ir/tmp/t/tmpulPu2x', '--merge-script', '/b/swarming/w/ir/cache/builder/src/tools/perf/process_perf_results.py', '--merge-script-stdout-file', '/b/swarming/w/ir/tmp/t/tmpvFCn6smerge_script_log', '--merge-additional-args', '["--smoke-test-mode"]', '--build-properties', '{"bot_id": "swarm1596-c4", "branch": "refs/heads/master", "buildbucket": {"build": {"bucket": "luci.chromium.ci", "created_by": "project:chromium", "created_ts": 1556219469762629, "id": "8915179329232731856", "project": "chromium", "tags": ["build_address:luci.chromium.ci/android-marshmallow-arm64-rel/14713", "builder:android-marshmallow-arm64-rel", "buildset:commit/git/09ef820ccf81b4e38eb55dbfe01719ca4fdd8b63", "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/09ef820ccf81b4e38eb55dbfe01719ca4fdd8b63", "gitiles_ref:refs/heads/master", "scheduler_invocation_id:9080609391506703872", "scheduler_job_id:chromium/android-marshmallow-arm64-rel", "user_agent:luci-scheduler"]}, "hostname": "cr-buildbucket.appspot.com"}, "buildername": "android-marshmallow-arm64-rel", "buildnumber": 14713, "got_angle_revision": "fb6fbdbc582dff1ab9c376f4e759cd728bd74411", "got_dawn_revision": "22cee9ae36b23a8908525d668ef4728a26c931c4", "got_nacl_revision": "6abc006f6760ec49350cd45e8bccbff4809725ac", "got_revision": "09ef820ccf81b4e38eb55dbfe01719ca4fdd8b63", "got_revision_cp": "refs/heads/master@{#654136}", "got_swarming_client_revision": "aa60736aded9fc32a0e21a81f5fc51f6009d01f3", "got_v8_revision": "2531f0784031894907b009a0a2de46ea8bc04547", "got_v8_revision_cp": "refs/heads/7.6.29@{#1}", "got_webrtc_revision": "ff727a8abb360373fa9051518810e7d00604e695", "got_webrtc_revision_cp": "refs/heads/master@{#27776}", "mastername": "chromium.android", "path_config": "generic", "recipe": "chromium", "repository": "https://chromium.googlesource.com/chromium/src.git", "revision": "09ef820ccf81b4e38eb55dbfe01719ca4fdd8b63"}', '--summary-json-file', '/b/swarming/w/ir/tmp/t/tmpqqWjPu.json', '--', 'swarming', 'collect', '-server', 'https://chromium-swarm.appspot.com', '-worker', '50', '-task-summary-python', '-task-output-stdout', 'all', '-verbose', '-requests-json', '/b/swarming/w/ir/tmp/t/tmpsgzMKX.json'] env: FrozenDict([('PATH', '/b/swarming/w/ir/cache/builder/src/third_party/android_tools/sdk/platform-tools:/b/swarming/w/ir/cache/builder/src/build/android:%(PATH)s'), ('CHROME_HEADLESS', '1')]) env_prefixes: {'mapping': FrozenDict([('PATH', ('/b/swarming/w/ir/cache/swarming_client',))]), 'pathsep': ':'} env_suffixes: {'mapping': FrozenDict([]), 'pathsep': ':'} infra_step: False name_tokens: ['components_perftests on Android device Nexus 5X'] ok_ret: ALL_OK step_test_data: gen_default_step_test_data(...) trigger_specs: [] full environment: ADDRFAM: inet BOTO_CONFIG: /b/swarming/w/ir/tmp/gsutil-task/.boto BUILDBUCKET_EXPERIMENTAL: FALSE CHROME_HEADLESS: 1 CIPD_CACHE_DIR: /b/swarming/cipd_cache/cache CIPD_PROTOCOL: v2 DEVSHELL_CLIENT_PORT: 37089 DOCKER_CONFIG: /b/swarming/w/ir/tmp/docker-cfg-task DOCKER_TMPDIR: /b/swarming/w/ir/tmp/docker-tmp-task GIT_CONFIG_NOSYSTEM: 1 GIT_TERMINAL_PROMPT: 0 HOME: /home/chrome-bot IFACE: eth0 INFRA_GIT_WRAPPER_HOME: /b/swarming/w/ir/tmp/git-home-task LOGDOG_COORDINATOR_HOST: logs.chromium.org LOGDOG_STREAM_PREFIX: buildbucket/cr-buildbucket.appspot.com/8915179329232731856 LOGDOG_STREAM_PROJECT: chromium LOGDOG_STREAM_SERVER_PATH: unix:/b/swarming/w/ir/tmp/ld.sock LOGICAL: eth0 LUCI_CONTEXT: /b/swarming/w/ir/tmp/luci_context.042753076 MAC_CHROMIUM_TMPDIR: /b/swarming/w/ir/tmp/t METHOD: dhcp NO_GCE_CHECK: False PATH: /b/swarming/w/ir/cache/swarming_client:/b/swarming/w/ir/cache/builder/src/third_party/android_tools/sdk/platform-tools:/b/swarming/w/ir/cache/builder/src/build/android:/b/swarming/w/ir/cipd_bin_packages:/b/swarming/w/ir/cipd_bin_packages/bin:/b/swarming/cipd_cache/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin PWD: /b/swarming/w/ir/k PYTHONIOENCODING: UTF-8 PYTHONUNBUFFERED: 1 ROOT_SETUP_LOG: /var/log/messages/chromebuild/root-setup.log SHLVL: 1 STARTUP_LOG: /var/log/messages/chromebuild/startup.log SWARMING_BOT_ID: swarm1596-c4 SWARMING_EXTERNAL_BOT_SETUP: 1 SWARMING_HEADLESS: 1 SWARMING_SERVER: https://chromium-swarm.appspot.com SWARMING_TASK_ID: 446eba2f2c8d9c11 TEMP: /b/swarming/w/ir/tmp/t TEMPDIR: /b/swarming/w/ir/tmp/t TERM: linux TMP: /b/swarming/w/ir/tmp/t TMPDIR: /b/swarming/w/ir/tmp/t UPSTART_EVENTS: net-device-up UPSTART_INSTANCE: UPSTART_JOB: chromebuild-startup USER: chrome-bot USERNAME: chrome-bot VPYTHON_VIRTUALENV_ROOT: /b/swarming/w/ir/cache/vpython _: /b/swarming/w/ir/cipd_bin_packages/vpython Set PYTHONPATH: /b/swarming/w/ir/kitchen-checkout/build/scripts:/b/swarming/w/ir/kitchen-checkout/build/site_config:/b/swarming/w/ir/kitchen-checkout/build/third_party:/b/swarming/w/ir/kitchen-checkout/build/third_party/buildbot_8_4p1:/b/swarming/w/ir/kitchen-checkout/build/third_party/buildbot_slave_8_4:/b/swarming/w/ir/kitchen-checkout/build/third_party/coverage-3.7.1:/b/swarming/w/ir/kitchen-checkout/build/third_party/decorator_3_3_1:/b/swarming/w/ir/kitchen-checkout/build/third_party/google_api_python_client:/b/swarming/w/ir/kitchen-checkout/build/third_party/httplib2/python2:/b/swarming/w/ir/kitchen-checkout/build/third_party/infra_libs:/b/swarming/w/ir/kitchen-checkout/build/third_party/jinja2:/b/swarming/w/ir/kitchen-checkout/build/third_party/markupsafe:/b/swarming/w/ir/kitchen-checkout/build/third_party/oauth2client:/b/swarming/w/ir/kitchen-checkout/build/third_party/pyasn1:/b/swarming/w/ir/kitchen-checkout/build/third_party/pyasn1-modules:/b/swarming/w/ir/kitchen-checkout/build/third_party/python-rsa:/b/swarming/w/ir/kitchen-checkout/build/third_party/requests_2_10_0:/b/swarming/w/ir/kitchen-checkout/build/third_party/setuptools-0.6c11:/b/swarming/w/ir/kitchen-checkout/build/third_party/sqlalchemy_0_7_1:/b/swarming/w/ir/kitchen-checkout/build/third_party/sqlalchemy_migrate_0_7_1:/b/swarming/w/ir/kitchen-checkout/build/third_party/tempita_0_5:/b/swarming/w/ir/kitchen-checkout/build/third_party/twisted_10_2:/b/swarming/w/ir/kitchen-checkout/build/third_party/uritemplate:/b/swarming/w/ir/kitchen-checkout/build/third_party/site-packages 2019-04-25 12:46:47,665 - root: [DEBUG] Using task_output_dir: '/b/swarming/w/ir/tmp/t/tmpulPu2x' 2019-04-25 12:46:47,666 - root: [WARNING] task_output_dir '/b/swarming/w/ir/tmp/t/tmpulPu2x' already exists! 2019-04-25 12:46:47,666 - root: [WARNING] task_output_dir existing content: [] 2019-04-25 12:46:47,666 - 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/swarming/w/ir/tmp/t/tmpsgzMKX.json -output-dir /b/swarming/w/ir/tmp/t/tmpulPu2x -task-summary-json /b/swarming/w/ir/tmp/t/tmpqqWjPu.json [D2019-04-25T12:46:47.672817-07:00 32470 0 auth.go:1265] Minting a new token {"key":"luci_ctx/dbe64a3c0f518e5b91d56e42c8644f9fd190bff84a3c5c619cf39b72419bab66", "scopes":"https://www.googleapis.com/auth/userinfo.email"} [D2019-04-25T12:46:47.672932-07:00 32470 0 luci_ctx.go:138] POST http://127.0.0.1:46628/rpc/LuciLocalAuthService.GetOAuthToken {"key":"luci_ctx/dbe64a3c0f518e5b91d56e42c8644f9fd190bff84a3c5c619cf39b72419bab66", "scopes":"https://www.googleapis.com/auth/userinfo.email"} [D2019-04-25T12:46:47.674694-07:00 32470 0 auth.go:1222] Token expires in 31m1.325333822s {"key":"luci_ctx/dbe64a3c0f518e5b91d56e42c8644f9fd190bff84a3c5c619cf39b72419bab66", "scopes":"https://www.googleapis.com/auth/userinfo.email"} 446ed7368b37c110: 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. Additional test environment: CHROME_DEVEL_SANDBOX=/opt/chromium/chrome_sandbox CHROME_HEADLESS=1 LANG=en_US.UTF-8 Command: /b/swarming/w/ir/.swarming_module_cache/vpython/3bcbb9/bin/python ../../testing/scripts/run_performance_tests.py --xvfb --non-telemetry=true components_perftests --gtest-benchmark-name=components_perftests --isolated-script-test-output=/b/swarming/w/io0bYWqq/output.json --isolated-script-test-perf-output=/b/swarming/w/io0bYWqq/perftest-output.json _XSERVTransmkdir: ERROR: euid != 0,directory /tmp/.X11-unix will not be created. Additional test environment: CHROME_DEVEL_SANDBOX=/opt/chromium/chrome_sandbox CHROME_HEADLESS=1 LANG=en_US.UTF-8 Command: ./components_perftests --verbose --test-launcher-print-test-stdio=always Openbox-Message: Unable to find a valid menu file "/var/lib/openbox/debian-menu.xml" Running ['./components_perftests', '--verbose', '--test-launcher-print-test-stdio=always'] in None (env: {'HOSTNAME': 'build373-m1--device7', 'LOGNAME': 'chrome-bot', 'USER': 'chrome-bot', 'ADB_LIBUSB': '0', 'BOTO_CONFIG': '/home/chrome-bot/.boto', 'HOME': '/home/chrome-bot', 'SWARMING_BOT_ID': 'build373-m1--device7', 'DISPLAY': ':101', 'LANG': 'en_US.UTF-8', 'VIRTUAL_ENV': '/b/swarming/w/ir/.swarming_module_cache/vpython/3bcbb9', 'SHELL': '/bin/bash', 'SWARMING_SERVER': 'https://chromium-swarm.appspot.com', 'LANGUAGE': 'en_US:en', 'NUM_CONFIGURED_CONTAINERS': '7', 'SHLVL': '2', 'CHROME_DEVEL_SANDBOX': '/opt/chromium/chrome_sandbox', 'SWARMING_HEADLESS': '1', 'TMPDIR': '/b/swarming/w/it4YLyGs', 'PYTHONNOUSERSITE': '1', 'LUCI_CONTEXT': '/b/swarming/w/it4YLyGs/luci_ctx.zNarKZ.json', 'CIPD_CACHE_DIR': '/b/swarming/cipd_cache/cache', 'CHROMIUM_REV': 'e41aeaed051c8cc401e0bce2a6d57dcc330808e6', 'DEBIAN_FRONTEND': 'noninteractive', 'VPYTHON_VIRTUALENV_ROOT': '/b/swarming/w/ir/.swarming_module_cache/vpython', 'PATH': '/b/swarming/w/ir/.swarming_module_cache/vpython/3bcbb9/bin:/b/swarming/w/ir/.swarming_module:/b/swarming/w/ir/.swarming_module/bin:/b/swarming/cipd_cache/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games', 'NO_GCE_CHECK': 'False', 'CHROME_HEADLESS': '1', 'LC_ALL': 'en_US.UTF-8', '_': '/usr/bin/python', 'SWARMING_TASK_ID': '446ed7368b37c111', '_CHROMIUM_INSIDE_XVFB': '1', 'CIPD_PROTOCOL': 'v2', 'DOCKER_HOST_HOSTNAME': 'build373-m1.golo.chromium.org', 'ISOLATED_CACHE_SIZE': '88081833984', 'PWD': '/b/swarming', 'SWARM_URL': 'https://chromium-swarm.appspot.com/bot_code', 'MAIL': '/var/mail/chrome-bot'}) I 0.000s Main command: ././../../build/android/test_runner.py gtest --output-directory ././. --runtime-deps-path ././gen.runtime/components/components_perftests.runtime_deps --suite components_perftests --verbose --test-launcher-print-test-stdio=always I 0.007s Main condition '<lambda>' met I 0.008s Main condition '<lambda>' met I 3.201s TimeoutThread-1-for-prepare_device(01e405b326d82e95) condition 'sd_card_ready' met (0.0s) I 3.787s TimeoutThread-1-for-prepare_device(01e405b326d82e95) condition 'pm_ready' met (0.6s) I 3.831s TimeoutThread-1-for-prepare_device(01e405b326d82e95) condition 'boot_completed' met (0.7s) I 24.112s TimeoutThread-1-for-individual_device_set_up(01e405b326d82e95) Setting permissions for org.chromium.native_test. I 27.154s calculate_device_checksums condition '<lambda>' met (0.2s) I 31.050s TimeoutThread-1-for-individual_device_set_up(01e405b326d82e95) Large shell command will be run from file: mkdir -p /sdcard/chromium_tests_root/gen.runtime/components /sdcard/chromium_tests_root/third_party/android_sdk/public/platform-tools /sdcard/chromium_tests_root/paks /sdcard/chromium_tests_root/build/android/pylib/gtest/filter /sdcard/chromium_tests_root/clang_x64 /sdcard/chromium_tests_root/components/subresource_filter/core/common/perftests/data /sdcard/chromium_tests_root/third_party/catapult/devil/devil /sdcard/chromium_tests_root/third_party/android_sdk/public/build-tools/27.0.3 /sdcard/chromium_tests ... I 34.356s TimeoutThread-1-for-individual_device_set_up(01e405b326d82e95) Pushing 4650 files via .zip of size 25384869 I 38.763s TimeoutThread-1-for-individual_device_set_up(01e405b326d82e95) Large shell command will be run from file: PATH="/data/local/tmp/bin:$PATH" unzip /data/local/tmp/temp_file-776f3e4af04d7.zip&&chmod -R 777 /sdcard/chromium_tests_root/paks/components_tests_resources.pak /sdcard/chromium_tests_root/paks/ui_test.pak /sdcard/chromium_tests_root/components/subresource_filter/core/common/perftests/data /sdcard/chromium_tests_root/paks/components_tests_resources.pak /sdcard/chromium_tests_root/components_tests_resources.pak.info /sdcard/chromium_tests_root/paks/en-US.pak /sdcard/chromium_tests_root/icudtl.dat /sdcard/chr ... I 87.019s individual_device_set_up(01e405b326d82e95) Still working on PushChangedFiles(01e405b326d82e95, [('/b/swarming/w/ir/out/Release/components_tests_resources.pak', '/sdcard/chromium_tests_root/paks/components_tests_resources.pak'), ('/b/swarming/w/ir/out/Release/ui_test.pak', '/sdcard/chromium_tests_root/paks/ui_test.pak'), ('/b/swarming/w/ir/components/subresource_filter/core/common/perftests/data', '/sdcard/chromium_tests_root/components/subresource_filter/core/common/perftests/data'), ('/b/swarming/w/ir/out/Release/components_tests_resources.pak', '/sdcard/chromium_tests_root/paks/components_tests_resources.pak'), ('/b/swarming/w/ir/out/Release/components_tests_resources.pak.info', '/sdcard/chromium_tests_root/components_tests_resources.pak.info'), ('/b/swarming/w/ir/out/Release/ui/en-US.pak', '/sdcard/chromium_tests_root/paks/en-US.pak'), ('/b/swarming/w/ir/out/Release/icudtl.dat', '/sdcard/chromium_tests_root/icudtl.dat'), ('/b/swarming/w/ir/net/data', '/sdcard/chromium_tests_root/net/data'), ('/b/swarming/w/ir/out/Release/content_shell.pak', '/sdcard/chromium_tests_root/paks/content_shell.pak'), ('/b/swarming/w/ir/out/Release/content_shell.pak.info', '/sdcard/chromium_tests_root/content_shell.pak.info'), ('/b/swarming/w/ir/out/Release/gen.runtime/components/components_perftests__test_runner_script.runtime_deps', '/sdcard/chromium_tests_root/gen.runtime/components/components_perftests__test_runner_script.runtime_deps'), ('/b/swarming/w/ir/build/android/pylib/gtest/filter', '/sdcard/chromium_tests_root/build/android/pylib/gtest/filter'), ('/b/swarming/w/ir/build/android/pylib/instrumentation/render_test.html.jinja', '/sdcard/chromium_tests_root/build/android/pylib/instrumentation/render_test.html.jinja'), ('/b/swarming/w/ir/third_party/android_sdk/public/build-tools/27.0.3/aapt', '/sdcard/chromium_tests_root/third_party/android_sdk/public/build-tools/27.0.3/aapt'), ('/b/swarming/w/ir/third_party/android_sdk/public/build-tools/27.0.3/dexdump', '/sdcard/chromium_tests_root/third_party/android_sdk/public/build-tools/27.0.3/dexdump'), ('/b/swarming/w/ir/third_party/android_sdk/public/build-tools/27.0.3/split-select', '/sdcard/chromium_tests_root/third_party/android_sdk/public/build-tools/27.0.3/split-select'), ('/b/swarming/w/ir/third_party/android_sdk/public/platform-tools/adb', '/sdcard/chromium_tests_root/third_party/android_sdk/public/platform-tools/adb'), ('/b/swarming/w/ir/build/android/devil_chromium.json', '/sdcard/chromium_tests_root/build/android/devil_chromium.json'), ('/b/swarming/w/ir/third_party/catapult/third_party/gsutil', '/sdcard/chromium_tests_root/third_party/catapult/third_party/gsutil'), ('/b/swarming/w/ir/third_party/catapult/devil/devil/devil_dependencies.json', '/sdcard/chromium_tests_root/third_party/catapult/devil/devil/devil_dependencies.json'), ('/b/swarming/w/ir/out/Release/components_tests_resources.pak', '/sdcard/chromium_tests_root/paks/components_tests_resources.pak'), ('/b/swarming/w/ir/out/Release/ui_test.pak', '/sdcard/chromium_tests_root/paks/ui_test.pak'), ('/b/swarming/w/ir/components/subresource_filter/core/common/perftests/data', '/sdcard/chromium_tests_root/components/subresource_filter/core/common/perftests/data'), ('/b/swarming/w/ir/out/Release/md5sum_dist', '/sdcard/chromium_tests_root/md5sum_dist'), ('/b/swarming/w/ir/out/Release/host_forwarder', '/sdcard/chromium_tests_root/host_forwarder'), ('/b/swarming/w/ir/out/Release/clang_x64/host_forwarder', '/sdcard/chromium_tests_root/clang_x64/host_forwarder'), ('/b/swarming/w/ir/out/Release/host_forwarder_unittests', '/sdcard/chromium_tests_root/host_forwarder_unittests'), ('/b/swarming/w/ir/out/Release/clang_x64/host_forwarder_unittests', '/sdcard/chromium_tests_root/clang_x64/host_forwarder_unittests'), ('/b/swarming/w/ir/out/Release/forwarder_dist', '/sdcard/chromium_tests_root/forwarder_dist'), ('/b/swarming/w/ir/out/Release/exe.unstripped/device_forwarder', '/sdcard/chromium_tests_root/exe.unstripped/device_forwarder'), ('/b/swarming/w/ir/out/Release/components_tests_resources.pak', '/sdcard/chromium_tests_root/paks/components_tests_resources.pak'), ('/b/swarming/w/ir/out/Release/ui_test.pak', '/sdcard/chromium_tests_root/paks/ui_test.pak'), ('/b/swarming/w/ir/components/subresource_filter/core/common/perftests/data', '/sdcard/chromium_tests_root/components/subresource_filter/core/common/perftests/data')], retries=2, timeout=600, delete_device_stale=True) I 104.349s list_tests(01e405b326d82e95) flags: I 104.349s list_tests(01e405b326d82e95) --test-launcher-print-test-stdio=always I 104.349s list_tests(01e405b326d82e95) --gtest_list_tests I 106.091s list_tests(01e405b326d82e95) /sdcard/temp_file-be9c8247c1293.gtest_out size on device: 3099 I 106.271s Main Using external sharding settings. This is shard 0/1 I 106.271s Main STARTING TRY #1/3 I 106.272s Main Will run 68 tests on 1 devices: 01e405b326d82e95 I 111.017s run_tests_on_device(01e405b326d82e95) flags: I 111.017s run_tests_on_device(01e405b326d82e95) --test-launcher-print-test-stdio=always I 111.017s run_tests_on_device(01e405b326d82e95) Getting logdog client. I 111.120s TimeoutThread-1-for-run_tests_on_device(01e405b326d82e95) Large shell command will be run from file: ( p=org.chromium.native_test;am instrument -w -e "$p".NativeTestInstrumentationTestRunner.ShardNanoTimeout 120000000000 -e "$p".NativeTestInstrumentationTestRunner.NativeTestActivity "$p".NativeUnitTestActivity -e "$p".NativeTestInstrumentationTestRunner.StdoutFile /sdcard/temp_file-71092a04a59fc.gtest_out -e "$p".NativeTestInstrumentationTestRunner.TestList /data/local/tmp/temp_file-3f1128da29ed6 -e "$p".NativeTest.CommandLineFlags --test-launcher-print-test-stdio=always "$p"/"$p".NativeTestInstrumentation ... I 147.639s run_tests_on_device(01e405b326d82e95) /sdcard/temp_file-71092a04a59fc.gtest_out size on device: 39780 I 148.500s TimeoutThread-1-for-run_tests_on_device(01e405b326d82e95) Setting permissions for org.chromium.native_test. I 151.670s run_tests_on_device(01e405b326d82e95) >>ScopedMainEntryLogger I 151.671s run_tests_on_device(01e405b326d82e95) Note: Google Test filter = DiscardableSharedMemoryHeapTest.SearchFreeLists:HQPPerfTestOnePopularURL.Backspacing:HQPPerfTestOnePopularURL.Typing:IndexedRulesetPerftest.IndexRuleset:IndexedRulesetPerftest.MatchAll:ProtoDBPerfTest.DistributionTestSmall_FewEntries_Multi:ProtoDBPerfTest.DistributionTestSmall_FewEntries_Single:ProtoDBPerfTest.DistributionTestSmall_ManyEntries_Batch_Multi:ProtoDBPerfTest.DistributionTestSmall_ManyEntries_Batch_Single:ProtoDBPerfTest.DistributionTestSmall_ManyEntries_Multi:ProtoDBPerfTest.DistributionTestSmall_ManyEntries_Single:ProtoDBPerfTest.InsertMultipleDBsAlternating_Individual_1000b:ProtoDBPerfTest.InsertMultipleDBsAlternating_Individual_100b:ProtoDBPerfTest.InsertMultipleDBsAlternating_LargeBatch_1000b:ProtoDBPerfTest.InsertMultipleDBsAlternating_LargeBatch_100b:ProtoDBPerfTest.InsertSingleDBAlternating_Individual_1000b:ProtoDBPerfTest.InsertSingleDBAlternating_Individual_100b:ProtoDBPerfTest.InsertSingleDBAlternating_LargeBatch_1000b:ProtoDBPerfTest.InsertSingleDBAlternating_LargeBatch_100b:ProtoDBPerfTest.LoadEntriesMulti_DifferingNumDBs_Large:ProtoDBPerfTest.LoadEntriesMulti_DifferingNumDBs_Medium:ProtoDBPerfTest.LoadEntriesMulti_DifferingNumDBs_SkipReadCache_Large:ProtoDBPerfTest.LoadEntriesMulti_DifferingNumDBs_SkipReadCache_Medium:ProtoDBPerfTest.LoadEntriesMulti_DifferingNumDBs_SkipReadCache_Small:ProtoDBPerfTest.LoadEntriesMulti_DifferingNumDBs_Small:ProtoDBPerfTest.LoadEntriesMulti_Large:ProtoDBPerfTest.LoadEntriesMulti_Medium:ProtoDBPerfTest.LoadEntriesMulti_OnePrefix_DifferingNumDBs_Large:ProtoDBPerfTest.LoadEntriesMulti_OnePrefix_DifferingNumDBs_Medium:ProtoDBPerfTest.LoadEntriesMulti_OnePrefix_DifferingNumDBs_SkipReadCache_Large:ProtoDBPerfTest.LoadEntriesMulti_OnePrefix_DifferingNumDBs_SkipReadCache_Medium:ProtoDBPerfTest.LoadEntriesMulti_OnePrefix_DifferingNumDBs_SkipReadCache_Small:ProtoDBPerfTest.LoadEntriesMulti_OnePrefix_DifferingNumDBs_Small:ProtoDBPerfTest.LoadEntriesMulti_OnePrefix_Large:ProtoDBPerfTest.LoadEntriesMulti_OnePrefix_Medium:ProtoDBPerfTest.LoadEntriesMulti_OnePrefix_SkipReadCache_Large:ProtoDBPerfTest.LoadEntriesMulti_OnePrefix_SkipReadCache_Medium:ProtoDBPerfTest.LoadEntriesMulti_OnePrefix_SkipReadCache_Small:ProtoDBPerfTest.LoadEntriesMulti_OnePrefix_Small:ProtoDBPerfTest.LoadEntriesMulti_SkipReadCache_Large:ProtoDBPerfTest.LoadEntriesMulti_SkipReadCache_Medium:ProtoDBPerfTest.LoadEntriesMulti_SkipReadCache_Small:ProtoDBPerfTest.LoadEntriesMulti_Small:ProtoDBPerfTest.LoadEntriesSingle_DifferingNumDBs_Large:ProtoDBPerfTest.LoadEntriesSingle_DifferingNumDBs_Medium:ProtoDBPerfTest.LoadEntriesSingle_DifferingNumDBs_SkipReadCache_Large:ProtoDBPerfTest.LoadEntriesSingle_DifferingNumDBs_SkipReadCache_Medium:ProtoDBPerfTest.LoadEntriesSingle_DifferingNumDBs_SkipReadCache_Small:ProtoDBPerfTest.LoadEntriesSingle_DifferingNumDBs_Small:ProtoDBPerfTest.LoadEntriesSingle_Large:ProtoDBPerfTest.LoadEntriesSingle_Medium:ProtoDBPerfTest.LoadEntriesSingle_OnePrefix_DifferingNumDBs_Large:ProtoDBPerfTest.LoadEntriesSingle_OnePrefix_DifferingNumDBs_Medium:ProtoDBPerfTest.LoadEntriesSingle_OnePrefix_DifferingNumDBs_SkipReadCache_Large:ProtoDBPerfTest.LoadEntriesSingle_OnePrefix_DifferingNumDBs_SkipReadCache_Medium:ProtoDBPerfTest.LoadEntriesSingle_OnePrefix_DifferingNumDBs_SkipReadCache_Small:ProtoDBPerfTest.LoadEntriesSingle_OnePrefix_DifferingNumDBs_Small:ProtoDBPerfTest.LoadEntriesSingle_OnePrefix_Large:ProtoDBPerfTest.LoadEntriesSingle_OnePrefix_Medium:ProtoDBPerfTest.LoadEntriesSingle_OnePrefix_SkipReadCache_Large:ProtoDBPerfTest.LoadEntriesSingle_OnePrefix_SkipReadCache_Medium:ProtoDBPerfTest.LoadEntriesSingle_OnePrefix_SkipReadCache_Small:ProtoDBPerfTest.LoadEntriesSingle_OnePrefix_Small:ProtoDBPerfTest.LoadEntriesSingle_SkipReadCache_Large:ProtoDBPerfTest.LoadEntriesSingle_SkipReadCache_Medium:ProtoDBPerfTest.LoadEntriesSingle_SkipReadCache_Small:ProtoDBPerfTest.LoadEntriesSingle_Small:VisitedLink.TestAddAndQuery I 151.671s run_tests_on_device(01e405b326d82e95) [==========] Running 68 tests from 5 test suites. I 151.671s run_tests_on_device(01e405b326d82e95) [----------] Global test environment set-up. I 151.671s run_tests_on_device(01e405b326d82e95) [----------] 1 test from DiscardableSharedMemoryHeapTest I 151.671s run_tests_on_device(01e405b326d82e95) [ RUN ] DiscardableSharedMemoryHeapTest.SearchFreeLists I 151.671s run_tests_on_device(01e405b326d82e95) *RESULT search_free_list: = 1097192.0216974008 runs/s I 151.672s run_tests_on_device(01e405b326d82e95) [ OK ] DiscardableSharedMemoryHeapTest.SearchFreeLists (2007 ms) I 151.672s run_tests_on_device(01e405b326d82e95) [----------] 1 test from DiscardableSharedMemoryHeapTest (2008 ms total) I 151.672s run_tests_on_device(01e405b326d82e95) I 151.672s run_tests_on_device(01e405b326d82e95) [----------] 62 tests from ProtoDBPerfTest I 151.672s run_tests_on_device(01e405b326d82e95) [ RUN ] ProtoDBPerfTest.InsertMultipleDBsAlternating_Individual_100b I 151.672s run_tests_on_device(01e405b326d82e95) *RESULT InsertMultipleDBsAlternating_Individual1_200_100: num_runs= 1000 I 151.672s run_tests_on_device(01e405b326d82e95) *RESULT InsertMultipleDBsAlternating_Individual1_200_100: time= 2477.2379999999976 ms I 151.672s run_tests_on_device(01e405b326d82e95) *RESULT InsertMultipleDBsAlternating_Individual1_200_100: max memory use= 184680 bytes I 151.672s run_tests_on_device(01e405b326d82e95) *RESULT InsertMultipleDBsAlternating_Individual1_200_100: average memory use= 97141 bytes I 151.672s run_tests_on_device(01e405b326d82e95) [ OK ] ProtoDBPerfTest.InsertMultipleDBsAlternating_Individual_100b (2565 ms) I 151.672s run_tests_on_device(01e405b326d82e95) [ RUN ] ProtoDBPerfTest.InsertMultipleDBsAlternating_Individual_1000b I 151.672s run_tests_on_device(01e405b326d82e95) *RESULT InsertMultipleDBsAlternating_Individual1_200_1000: num_runs= 1000 I 151.673s run_tests_on_device(01e405b326d82e95) *RESULT InsertMultipleDBsAlternating_Individual1_200_1000: time= 2319.1100000000006 ms I 151.673s run_tests_on_device(01e405b326d82e95) *RESULT InsertMultipleDBsAlternating_Individual1_200_1000: max memory use= 1081195 bytes I 151.673s run_tests_on_device(01e405b326d82e95) *RESULT InsertMultipleDBsAlternating_Individual1_200_1000: average memory use= 541923 bytes I 151.673s run_tests_on_device(01e405b326d82e95) [ OK ] ProtoDBPerfTest.InsertMultipleDBsAlternating_Individual_1000b (2391 ms) I 151.673s run_tests_on_device(01e405b326d82e95) [ RUN ] ProtoDBPerfTest.InsertSingleDBAlternating_Individual_100b I 151.673s run_tests_on_device(01e405b326d82e95) *RESULT InsertSingleDBAlternating_Individual1_200_100: num_runs= 1000 I 151.673s run_tests_on_device(01e405b326d82e95) *RESULT InsertSingleDBAlternating_Individual1_200_100: time= 2163.587000000001 ms I 151.673s run_tests_on_device(01e405b326d82e95) *RESULT InsertSingleDBAlternating_Individual1_200_100: max memory use= 176472 bytes I 151.673s run_tests_on_device(01e405b326d82e95) *RESULT InsertSingleDBAlternating_Individual1_200_100: average memory use= 88432 bytes I 151.673s run_tests_on_device(01e405b326d82e95) [ OK ] ProtoDBPerfTest.InsertSingleDBAlternating_Individual_100b (2204 ms) I 151.673s run_tests_on_device(01e405b326d82e95) [ RUN ] ProtoDBPerfTest.InsertSingleDBAlternating_Individual_1000b I 151.673s run_tests_on_device(01e405b326d82e95) *RESULT InsertSingleDBAlternating_Individual1_200_1000: num_runs= 1000 I 151.673s run_tests_on_device(01e405b326d82e95) *RESULT InsertSingleDBAlternating_Individual1_200_1000: time= 2329.048999999999 ms I 151.674s run_tests_on_device(01e405b326d82e95) *RESULT InsertSingleDBAlternating_Individual1_200_1000: max memory use= 532331 bytes I 151.674s run_tests_on_device(01e405b326d82e95) *RESULT InsertSingleDBAlternating_Individual1_200_1000: average memory use= 262609 bytes I 151.674s run_tests_on_device(01e405b326d82e95) [ OK ] ProtoDBPerfTest.InsertSingleDBAlternating_Individual_1000b (2374 ms) I 151.674s run_tests_on_device(01e405b326d82e95) [ RUN ] ProtoDBPerfTest.InsertMultipleDBsAlternating_LargeBatch_100b I 151.674s run_tests_on_device(01e405b326d82e95) *RESULT InsertMultipleDBsAlternating_LargeBatch200_200_100: num_runs= 5 I 151.674s run_tests_on_device(01e405b326d82e95) *RESULT InsertMultipleDBsAlternating_LargeBatch200_200_100: time= 25.293 ms I 151.674s run_tests_on_device(01e405b326d82e95) *RESULT InsertMultipleDBsAlternating_LargeBatch200_200_100: max memory use= 184680 bytes I 151.674s run_tests_on_device(01e405b326d82e95) *RESULT InsertMultipleDBsAlternating_LargeBatch200_200_100: average memory use= 119016 bytes I 151.674s run_tests_on_device(01e405b326d82e95) [ OK ] ProtoDBPerfTest.InsertMultipleDBsAlternating_LargeBatch_100b (85 ms) I 151.674s run_tests_on_device(01e405b326d82e95) [ RUN ] ProtoDBPerfTest.InsertMultipleDBsAlternating_LargeBatch_1000b I 151.674s run_tests_on_device(01e405b326d82e95) *RESULT InsertMultipleDBsAlternating_LargeBatch200_200_1000: num_runs= 5 I 151.674s run_tests_on_device(01e405b326d82e95) *RESULT InsertMultipleDBsAlternating_LargeBatch200_200_1000: time= 64.797 ms I 151.674s run_tests_on_device(01e405b326d82e95) *RESULT InsertMultipleDBsAlternating_LargeBatch200_200_1000: max memory use= 1081195 bytes I 151.675s run_tests_on_device(01e405b326d82e95) *RESULT InsertMultipleDBsAlternating_LargeBatch200_200_1000: average memory use= 656925 bytes I 151.675s run_tests_on_device(01e405b326d82e95) [ OK ] ProtoDBPerfTest.InsertMultipleDBsAlternating_LargeBatch_1000b (121 ms) I 151.675s run_tests_on_device(01e405b326d82e95) [ RUN ] ProtoDBPerfTest.InsertSingleDBAlternating_LargeBatch_100b I 151.675s run_tests_on_device(01e405b326d82e95) *RESULT InsertSingleDBAlternating_LargeBatch200_200_100: num_runs= 5 I 151.675s run_tests_on_device(01e405b326d82e95) *RESULT InsertSingleDBAlternating_LargeBatch200_200_100: time= 23.506 ms I 151.675s run_tests_on_device(01e405b326d82e95) *RESULT InsertSingleDBAlternating_LargeBatch200_200_100: max memory use= 176472 bytes I 151.675s run_tests_on_device(01e405b326d82e95) *RESULT InsertSingleDBAlternating_LargeBatch200_200_100: average memory use= 105883 bytes I 151.675s run_tests_on_device(01e405b326d82e95) [ OK ] ProtoDBPerfTest.InsertSingleDBAlternating_LargeBatch_100b (48 ms) I 151.675s run_tests_on_device(01e405b326d82e95) [ RUN ] ProtoDBPerfTest.InsertSingleDBAlternating_LargeBatch_1000b I 151.675s run_tests_on_device(01e405b326d82e95) *RESULT InsertSingleDBAlternating_LargeBatch200_200_1000: num_runs= 5 I 151.675s run_tests_on_device(01e405b326d82e95) *RESULT InsertSingleDBAlternating_LargeBatch200_200_1000: time= 66.80499999999999 ms I 151.675s run_tests_on_device(01e405b326d82e95) *RESULT InsertSingleDBAlternating_LargeBatch200_200_1000: max memory use= 863082 bytes I 151.676s run_tests_on_device(01e405b326d82e95) *RESULT InsertSingleDBAlternating_LargeBatch200_200_1000: average memory use= 518272 bytes I 151.676s run_tests_on_device(01e405b326d82e95) [ OK ] ProtoDBPerfTest.InsertSingleDBAlternating_LargeBatch_1000b (96 ms) I 151.676s run_tests_on_device(01e405b326d82e95) [ RUN ] ProtoDBPerfTest.DistributionTestSmall_FewEntries_Single I 151.676s run_tests_on_device(01e405b326d82e95) *RESULT DistributionTestSmall_FewEntriesSingle: Total memory use= 4104 bytes I 151.676s run_tests_on_device(01e405b326d82e95) [ OK ] ProtoDBPerfTest.DistributionTestSmall_FewEntries_Single (102 ms) I 151.676s run_tests_on_device(01e405b326d82e95) [ RUN ] ProtoDBPerfTest.DistributionTestSmall_FewEntries_Multi I 151.676s run_tests_on_device(01e405b326d82e95) *RESULT DistributionTestSmall_FewEntriesMulti: Total memory use= 36936 bytes I 151.676s run_tests_on_device(01e405b326d82e95) [ OK ] ProtoDBPerfTest.DistributionTestSmall_FewEntries_Multi (169 ms) I 151.676s run_tests_on_device(01e405b326d82e95) [ RUN ] ProtoDBPerfTest.DistributionTestSmall_ManyEntries_Single I 151.676s run_tests_on_device(01e405b326d82e95) *RESULT DistributionTestSmall_ManyEntriesSingle: Total memory use= 36936 bytes I 151.676s run_tests_on_device(01e405b326d82e95) [ OK ] ProtoDBPerfTest.DistributionTestSmall_ManyEntries_Single (998 ms) I 151.676s run_tests_on_device(01e405b326d82e95) [ RUN ] ProtoDBPerfTest.DistributionTestSmall_ManyEntries_Multi I 151.676s run_tests_on_device(01e405b326d82e95) *RESULT DistributionTestSmall_ManyEntriesMulti: Total memory use= 61560 bytes I 151.677s run_tests_on_device(01e405b326d82e95) [ OK ] ProtoDBPerfTest.DistributionTestSmall_ManyEntries_Multi (1054 ms) I 151.677s run_tests_on_device(01e405b326d82e95) [ RUN ] ProtoDBPerfTest.DistributionTestSmall_ManyEntries_Batch_Single I 151.677s run_tests_on_device(01e405b326d82e95) *RESULT DistributionTestSmall_ManyEntries_BatchSingle: Total memory use= 36936 bytes I 151.677s run_tests_on_device(01e405b326d82e95) [ OK ] ProtoDBPerfTest.DistributionTestSmall_ManyEntries_Batch_Single (983 ms) I 151.677s run_tests_on_device(01e405b326d82e95) [ RUN ] ProtoDBPerfTest.DistributionTestSmall_ManyEntries_Batch_Multi I 151.677s run_tests_on_device(01e405b326d82e95) *RESULT DistributionTestSmall_ManyEntries_BatchMulti: Total memory use= 61560 bytes I 151.677s run_tests_on_device(01e405b326d82e95) [ OK ] ProtoDBPerfTest.DistributionTestSmall_ManyEntries_Batch_Multi (1022 ms) I 151.677s run_tests_on_device(01e405b326d82e95) [ RUN ] ProtoDBPerfTest.LoadEntriesSingle_Small I 151.677s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_5_300_10: Memory use after writes= 127224 bytes I 151.677s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_5_300_10: Memory use after load= 127224 bytes I 151.677s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_5_300_10: Total time taken= 12 ms I 151.677s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_5_300_10: Max individual time taken= 0 ms I 151.678s run_tests_on_device(01e405b326d82e95) [ OK ] ProtoDBPerfTest.LoadEntriesSingle_Small (75 ms) I 151.678s run_tests_on_device(01e405b326d82e95) [ RUN ] ProtoDBPerfTest.LoadEntriesSingle_Medium I 151.678s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_5_3000_10: Memory use after writes= 500688 bytes I 151.678s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_5_3000_10: Memory use after load= 906553 bytes I 151.678s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_5_3000_10: Total time taken= 78 ms I 151.678s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_5_3000_10: Max individual time taken= 0 ms I 151.678s run_tests_on_device(01e405b326d82e95) [ OK ] ProtoDBPerfTest.LoadEntriesSingle_Medium (268 ms) I 151.678s run_tests_on_device(01e405b326d82e95) [ RUN ] ProtoDBPerfTest.LoadEntriesSingle_Large I 151.678s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_5_3000_100: Memory use after writes= 1559520 bytes I 151.678s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_5_3000_100: Memory use after load= 2143118 bytes I 151.678s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_5_3000_100: Total time taken= 93 ms I 151.678s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_5_3000_100: Max individual time taken= 0 ms I 151.679s run_tests_on_device(01e405b326d82e95) [ OK ] ProtoDBPerfTest.LoadEntriesSingle_Large (362 ms) I 151.679s run_tests_on_device(01e405b326d82e95) [ RUN ] ProtoDBPerfTest.LoadEntriesSingle_OnePrefix_Small I 151.679s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_OnePrefix_5_300_10: Memory use after writes= 127224 bytes I 151.679s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_OnePrefix_5_300_10: Memory use after load= 127224 bytes I 151.679s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_OnePrefix_5_300_10: Total time taken= 1 ms I 151.679s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_OnePrefix_5_300_10: Max individual time taken= 1 ms I 151.679s run_tests_on_device(01e405b326d82e95) [ OK ] ProtoDBPerfTest.LoadEntriesSingle_OnePrefix_Small (56 ms) I 151.679s run_tests_on_device(01e405b326d82e95) [ RUN ] ProtoDBPerfTest.LoadEntriesSingle_OnePrefix_Medium I 151.679s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_OnePrefix_5_3000_10: Memory use after writes= 1251720 bytes I 151.679s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_OnePrefix_5_3000_10: Memory use after load= 640932 bytes I 151.679s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_OnePrefix_5_3000_10: Total time taken= 13 ms I 151.679s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_OnePrefix_5_3000_10: Max individual time taken= 13 ms I 151.680s run_tests_on_device(01e405b326d82e95) [ OK ] ProtoDBPerfTest.LoadEntriesSingle_OnePrefix_Medium (230 ms) I 151.680s run_tests_on_device(01e405b326d82e95) [ RUN ] ProtoDBPerfTest.LoadEntriesSingle_OnePrefix_Large I 151.680s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_OnePrefix_5_3000_100: Memory use after writes= 1559520 bytes I 151.680s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_OnePrefix_5_3000_100: Memory use after load= 934115 bytes I 151.680s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_OnePrefix_5_3000_100: Total time taken= 18 ms I 151.680s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_OnePrefix_5_3000_100: Max individual time taken= 18 ms I 151.680s run_tests_on_device(01e405b326d82e95) [ OK ] ProtoDBPerfTest.LoadEntriesSingle_OnePrefix_Large (279 ms) I 151.680s run_tests_on_device(01e405b326d82e95) [ RUN ] ProtoDBPerfTest.LoadEntriesMulti_Small I 151.680s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_5_300_10: Memory use after writes= 143640 bytes I 151.680s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_5_300_10: Memory use after load= 143640 bytes I 151.680s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_5_300_10: Total time taken= 15 ms I 151.680s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_5_300_10: Max individual time taken= 4 ms I 151.680s run_tests_on_device(01e405b326d82e95) [ OK ] ProtoDBPerfTest.LoadEntriesMulti_Small (146 ms) I 151.681s run_tests_on_device(01e405b326d82e95) [ RUN ] ProtoDBPerfTest.LoadEntriesMulti_Medium I 151.681s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_5_3000_10: Memory use after writes= 1251720 bytes I 151.681s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_5_3000_10: Memory use after load= 1251720 bytes I 151.681s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_5_3000_10: Total time taken= 71 ms I 151.681s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_5_3000_10: Max individual time taken= 16 ms I 151.681s run_tests_on_device(01e405b326d82e95) [ OK ] ProtoDBPerfTest.LoadEntriesMulti_Medium (350 ms) I 151.681s run_tests_on_device(01e405b326d82e95) [ RUN ] ProtoDBPerfTest.LoadEntriesMulti_Large I 151.681s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_5_3000_100: Memory use after writes= 2606040 bytes I 151.681s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_5_3000_100: Memory use after load= 2606040 bytes I 151.681s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_5_3000_100: Total time taken= 82 ms I 151.681s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_5_3000_100: Max individual time taken= 17 ms I 151.681s run_tests_on_device(01e405b326d82e95) [ OK ] ProtoDBPerfTest.LoadEntriesMulti_Large (418 ms) I 151.681s run_tests_on_device(01e405b326d82e95) [ RUN ] ProtoDBPerfTest.LoadEntriesMulti_OnePrefix_Small I 151.682s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_OnePrefix_5_300_10: Memory use after writes= 143640 bytes I 151.682s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_OnePrefix_5_300_10: Memory use after load= 28728 bytes I 151.682s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_OnePrefix_5_300_10: Total time taken= 2 ms I 151.682s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_OnePrefix_5_300_10: Max individual time taken= 2 ms I 151.682s run_tests_on_device(01e405b326d82e95) [ OK ] ProtoDBPerfTest.LoadEntriesMulti_OnePrefix_Small (153 ms) I 151.682s run_tests_on_device(01e405b326d82e95) [ RUN ] ProtoDBPerfTest.LoadEntriesMulti_OnePrefix_Medium I 151.682s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_OnePrefix_5_3000_10: Memory use after writes= 1251720 bytes I 151.682s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_OnePrefix_5_3000_10: Memory use after load= 250344 bytes I 151.682s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_OnePrefix_5_3000_10: Total time taken= 27 ms I 151.682s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_OnePrefix_5_3000_10: Max individual time taken= 27 ms I 151.682s run_tests_on_device(01e405b326d82e95) [ OK ] ProtoDBPerfTest.LoadEntriesMulti_OnePrefix_Medium (352 ms) I 151.682s run_tests_on_device(01e405b326d82e95) [ RUN ] ProtoDBPerfTest.LoadEntriesMulti_OnePrefix_Large I 151.682s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_OnePrefix_5_3000_100: Memory use after writes= 2606040 bytes I 151.683s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_OnePrefix_5_3000_100: Memory use after load= 521208 bytes I 151.683s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_OnePrefix_5_3000_100: Total time taken= 19 ms I 151.683s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_OnePrefix_5_3000_100: Max individual time taken= 19 ms I 151.683s run_tests_on_device(01e405b326d82e95) [ OK ] ProtoDBPerfTest.LoadEntriesMulti_OnePrefix_Large (339 ms) I 151.683s run_tests_on_device(01e405b326d82e95) [ RUN ] ProtoDBPerfTest.LoadEntriesSingle_SkipReadCache_Small I 151.683s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_SkipReadCache_5_300_10: Memory use after writes= 127224 bytes I 151.683s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_SkipReadCache_5_300_10: Memory use after load= 127224 bytes I 151.683s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_SkipReadCache_5_300_10: Total time taken= 22 ms I 151.683s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_SkipReadCache_5_300_10: Max individual time taken= 0 ms I 151.683s run_tests_on_device(01e405b326d82e95) [ OK ] ProtoDBPerfTest.LoadEntriesSingle_SkipReadCache_Small (130 ms) I 151.683s run_tests_on_device(01e405b326d82e95) [ RUN ] ProtoDBPerfTest.LoadEntriesSingle_SkipReadCache_Medium I 151.683s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_SkipReadCache_5_3000_10: Memory use after writes= 500688 bytes I 151.683s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_SkipReadCache_5_3000_10: Memory use after load= 500688 bytes I 151.683s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_SkipReadCache_5_3000_10: Total time taken= 95 ms I 151.684s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_SkipReadCache_5_3000_10: Max individual time taken= 0 ms I 151.684s run_tests_on_device(01e405b326d82e95) [ OK ] ProtoDBPerfTest.LoadEntriesSingle_SkipReadCache_Medium (376 ms) I 151.684s run_tests_on_device(01e405b326d82e95) [ RUN ] ProtoDBPerfTest.LoadEntriesSingle_SkipReadCache_Large I 151.684s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_SkipReadCache_5_3000_100: Memory use after writes= 521208 bytes I 151.684s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_SkipReadCache_5_3000_100: Memory use after load= 521208 bytes I 151.684s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_SkipReadCache_5_3000_100: Total time taken= 84 ms I 151.684s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_SkipReadCache_5_3000_100: Max individual time taken= 0 ms I 151.684s run_tests_on_device(01e405b326d82e95) [ OK ] ProtoDBPerfTest.LoadEntriesSingle_SkipReadCache_Large (331 ms) I 151.684s run_tests_on_device(01e405b326d82e95) [ RUN ] ProtoDBPerfTest.LoadEntriesSingle_OnePrefix_SkipReadCache_Small I 151.684s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_OnePrefix_SkipReadCache_5_300_10: Memory use after writes= 127224 bytes I 151.684s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_OnePrefix_SkipReadCache_5_300_10: Memory use after load= 127224 bytes I 151.684s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_OnePrefix_SkipReadCache_5_300_10: Total time taken= 1 ms I 151.684s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_OnePrefix_SkipReadCache_5_300_10: Max individual time taken= 1 ms I 151.685s run_tests_on_device(01e405b326d82e95) [ OK ] ProtoDBPerfTest.LoadEntriesSingle_OnePrefix_SkipReadCache_Small (60 ms) I 151.685s run_tests_on_device(01e405b326d82e95) [ RUN ] ProtoDBPerfTest.LoadEntriesSingle_OnePrefix_SkipReadCache_Medium I 151.685s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_OnePrefix_SkipReadCache_5_3000_10: Memory use after writes= 1251720 bytes I 151.685s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_OnePrefix_SkipReadCache_5_3000_10: Memory use after load= 500688 bytes I 151.685s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_OnePrefix_SkipReadCache_5_3000_10: Total time taken= 12 ms I 151.685s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_OnePrefix_SkipReadCache_5_3000_10: Max individual time taken= 12 ms I 151.685s run_tests_on_device(01e405b326d82e95) [ OK ] ProtoDBPerfTest.LoadEntriesSingle_OnePrefix_SkipReadCache_Medium (227 ms) I 151.685s run_tests_on_device(01e405b326d82e95) [ RUN ] ProtoDBPerfTest.LoadEntriesSingle_OnePrefix_SkipReadCache_Large I 151.685s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_OnePrefix_SkipReadCache_5_3000_100: Memory use after writes= 1559520 bytes I 151.685s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_OnePrefix_SkipReadCache_5_3000_100: Memory use after load= 521208 bytes I 151.685s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_OnePrefix_SkipReadCache_5_3000_100: Total time taken= 17 ms I 151.685s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_OnePrefix_SkipReadCache_5_3000_100: Max individual time taken= 17 ms I 151.685s run_tests_on_device(01e405b326d82e95) [ OK ] ProtoDBPerfTest.LoadEntriesSingle_OnePrefix_SkipReadCache_Large (314 ms) I 151.686s run_tests_on_device(01e405b326d82e95) [ RUN ] ProtoDBPerfTest.LoadEntriesMulti_SkipReadCache_Small I 151.686s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_SkipReadCache_5_300_10: Memory use after writes= 143640 bytes I 151.686s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_SkipReadCache_5_300_10: Memory use after load= 143640 bytes I 151.686s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_SkipReadCache_5_300_10: Total time taken= 5 ms I 151.686s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_SkipReadCache_5_300_10: Max individual time taken= 1 ms I 151.686s run_tests_on_device(01e405b326d82e95) [ OK ] ProtoDBPerfTest.LoadEntriesMulti_SkipReadCache_Small (86 ms) I 151.686s run_tests_on_device(01e405b326d82e95) [ RUN ] ProtoDBPerfTest.LoadEntriesMulti_SkipReadCache_Medium I 151.686s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_SkipReadCache_5_3000_10: Memory use after writes= 1251720 bytes I 151.686s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_SkipReadCache_5_3000_10: Memory use after load= 1251720 bytes I 151.686s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_SkipReadCache_5_3000_10: Total time taken= 70 ms I 151.686s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_SkipReadCache_5_3000_10: Max individual time taken= 15 ms I 151.686s run_tests_on_device(01e405b326d82e95) [ OK ] ProtoDBPerfTest.LoadEntriesMulti_SkipReadCache_Medium (315 ms) I 151.686s run_tests_on_device(01e405b326d82e95) [ RUN ] ProtoDBPerfTest.LoadEntriesMulti_SkipReadCache_Large I 151.687s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_SkipReadCache_5_3000_100: Memory use after writes= 2606040 bytes I 151.687s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_SkipReadCache_5_3000_100: Memory use after load= 2606040 bytes I 151.687s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_SkipReadCache_5_3000_100: Total time taken= 82 ms I 151.687s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_SkipReadCache_5_3000_100: Max individual time taken= 17 ms I 151.687s run_tests_on_device(01e405b326d82e95) [ OK ] ProtoDBPerfTest.LoadEntriesMulti_SkipReadCache_Large (460 ms) I 151.687s run_tests_on_device(01e405b326d82e95) [ RUN ] ProtoDBPerfTest.LoadEntriesMulti_OnePrefix_SkipReadCache_Small I 151.687s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_OnePrefix_SkipReadCache_5_300_10: Memory use after writes= 143640 bytes I 151.687s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_OnePrefix_SkipReadCache_5_300_10: Memory use after load= 28728 bytes I 151.687s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_OnePrefix_SkipReadCache_5_300_10: Total time taken= 2 ms I 151.687s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_OnePrefix_SkipReadCache_5_300_10: Max individual time taken= 2 ms I 151.687s run_tests_on_device(01e405b326d82e95) [ OK ] ProtoDBPerfTest.LoadEntriesMulti_OnePrefix_SkipReadCache_Small (179 ms) I 151.687s run_tests_on_device(01e405b326d82e95) [ RUN ] ProtoDBPerfTest.LoadEntriesMulti_OnePrefix_SkipReadCache_Medium I 151.687s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_OnePrefix_SkipReadCache_5_3000_10: Memory use after writes= 1251720 bytes I 151.688s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_OnePrefix_SkipReadCache_5_3000_10: Memory use after load= 250344 bytes I 151.688s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_OnePrefix_SkipReadCache_5_3000_10: Total time taken= 32 ms I 151.688s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_OnePrefix_SkipReadCache_5_3000_10: Max individual time taken= 32 ms I 151.688s run_tests_on_device(01e405b326d82e95) [ OK ] ProtoDBPerfTest.LoadEntriesMulti_OnePrefix_SkipReadCache_Medium (408 ms) I 151.688s run_tests_on_device(01e405b326d82e95) [ RUN ] ProtoDBPerfTest.LoadEntriesMulti_OnePrefix_SkipReadCache_Large I 151.688s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_OnePrefix_SkipReadCache_5_3000_100: Memory use after writes= 2606040 bytes I 151.688s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_OnePrefix_SkipReadCache_5_3000_100: Memory use after load= 521208 bytes I 151.688s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_OnePrefix_SkipReadCache_5_3000_100: Total time taken= 20 ms I 151.688s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_OnePrefix_SkipReadCache_5_3000_100: Max individual time taken= 20 ms I 151.688s run_tests_on_device(01e405b326d82e95) [ OK ] ProtoDBPerfTest.LoadEntriesMulti_OnePrefix_SkipReadCache_Large (443 ms) I 151.688s run_tests_on_device(01e405b326d82e95) [ RUN ] ProtoDBPerfTest.LoadEntriesSingle_OnePrefix_DifferingNumDBs_Small I 151.688s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_OnePrefix_DifferingNumDBs_5_300_10: Memory use after writes= 127224 bytes I 151.688s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_OnePrefix_DifferingNumDBs_5_300_10: Memory use after load= 127224 bytes I 151.689s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_OnePrefix_DifferingNumDBs_5_300_10: Total time taken= 5 ms I 151.689s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_OnePrefix_DifferingNumDBs_5_300_10: Max individual time taken= 5 ms I 151.689s run_tests_on_device(01e405b326d82e95) [ OK ] ProtoDBPerfTest.LoadEntriesSingle_OnePrefix_DifferingNumDBs_Small (110 ms) I 151.689s run_tests_on_device(01e405b326d82e95) [ RUN ] ProtoDBPerfTest.LoadEntriesSingle_OnePrefix_DifferingNumDBs_Medium I 151.689s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_OnePrefix_DifferingNumDBs_10_300_10: Memory use after writes= 250344 bytes I 151.689s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_OnePrefix_DifferingNumDBs_10_300_10: Memory use after load= 250344 bytes I 151.689s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_OnePrefix_DifferingNumDBs_10_300_10: Total time taken= 3 ms I 151.689s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_OnePrefix_DifferingNumDBs_10_300_10: Max individual time taken= 3 ms I 151.689s run_tests_on_device(01e405b326d82e95) [ OK ] ProtoDBPerfTest.LoadEntriesSingle_OnePrefix_DifferingNumDBs_Medium (220 ms) I 151.689s run_tests_on_device(01e405b326d82e95) [ RUN ] ProtoDBPerfTest.LoadEntriesSingle_OnePrefix_DifferingNumDBs_Large I 151.689s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_OnePrefix_DifferingNumDBs_20_300_10: Memory use after writes= 500688 bytes I 151.689s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_OnePrefix_DifferingNumDBs_20_300_10: Memory use after load= 500688 bytes I 151.689s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_OnePrefix_DifferingNumDBs_20_300_10: Total time taken= 3 ms I 151.690s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_OnePrefix_DifferingNumDBs_20_300_10: Max individual time taken= 3 ms I 151.690s run_tests_on_device(01e405b326d82e95) [ OK ] ProtoDBPerfTest.LoadEntriesSingle_OnePrefix_DifferingNumDBs_Large (325 ms) I 151.690s run_tests_on_device(01e405b326d82e95) [ RUN ] ProtoDBPerfTest.LoadEntriesMulti_OnePrefix_DifferingNumDBs_Small I 151.690s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_OnePrefix_DifferingNumDBs_5_300_10: Memory use after writes= 143640 bytes I 151.690s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_OnePrefix_DifferingNumDBs_5_300_10: Memory use after load= 28728 bytes I 151.690s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_OnePrefix_DifferingNumDBs_5_300_10: Total time taken= 2 ms I 151.690s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_OnePrefix_DifferingNumDBs_5_300_10: Max individual time taken= 2 ms I 151.690s run_tests_on_device(01e405b326d82e95) [ OK ] ProtoDBPerfTest.LoadEntriesMulti_OnePrefix_DifferingNumDBs_Small (137 ms) I 151.690s run_tests_on_device(01e405b326d82e95) [ RUN ] ProtoDBPerfTest.LoadEntriesMulti_OnePrefix_DifferingNumDBs_Medium I 151.690s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_OnePrefix_DifferingNumDBs_10_300_10: Memory use after writes= 287280 bytes I 151.690s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_OnePrefix_DifferingNumDBs_10_300_10: Memory use after load= 28728 bytes I 151.690s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_OnePrefix_DifferingNumDBs_10_300_10: Total time taken= 2 ms I 151.690s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_OnePrefix_DifferingNumDBs_10_300_10: Max individual time taken= 2 ms I 151.691s run_tests_on_device(01e405b326d82e95) [ OK ] ProtoDBPerfTest.LoadEntriesMulti_OnePrefix_DifferingNumDBs_Medium (282 ms) I 151.691s run_tests_on_device(01e405b326d82e95) [ RUN ] ProtoDBPerfTest.LoadEntriesMulti_OnePrefix_DifferingNumDBs_Large I 151.691s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_OnePrefix_DifferingNumDBs_20_300_10: Memory use after writes= 574560 bytes I 151.691s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_OnePrefix_DifferingNumDBs_20_300_10: Memory use after load= 28728 bytes I 151.691s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_OnePrefix_DifferingNumDBs_20_300_10: Total time taken= 2 ms I 151.691s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_OnePrefix_DifferingNumDBs_20_300_10: Max individual time taken= 2 ms I 151.691s run_tests_on_device(01e405b326d82e95) [ OK ] ProtoDBPerfTest.LoadEntriesMulti_OnePrefix_DifferingNumDBs_Large (534 ms) I 151.691s run_tests_on_device(01e405b326d82e95) [ RUN ] ProtoDBPerfTest.LoadEntriesSingle_OnePrefix_DifferingNumDBs_SkipReadCache_Small I 151.691s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_OnePrefix_DifferingNumDBs_SkipReadCache_5_300_10: Memory use after writes= 127224 bytes I 151.691s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_OnePrefix_DifferingNumDBs_SkipReadCache_5_300_10: Memory use after load= 127224 bytes I 151.691s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_OnePrefix_DifferingNumDBs_SkipReadCache_5_300_10: Total time taken= 2 ms I 151.691s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_OnePrefix_DifferingNumDBs_SkipReadCache_5_300_10: Max individual time taken= 2 ms I 151.691s run_tests_on_device(01e405b326d82e95) [ OK ] ProtoDBPerfTest.LoadEntriesSingle_OnePrefix_DifferingNumDBs_SkipReadCache_Small (94 ms) I 151.691s run_tests_on_device(01e405b326d82e95) [ RUN ] ProtoDBPerfTest.LoadEntriesSingle_OnePrefix_DifferingNumDBs_SkipReadCache_Medium I 151.692s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_OnePrefix_DifferingNumDBs_SkipReadCache_10_300_10: Memory use after writes= 250344 bytes I 151.692s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_OnePrefix_DifferingNumDBs_SkipReadCache_10_300_10: Memory use after load= 250344 bytes I 151.692s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_OnePrefix_DifferingNumDBs_SkipReadCache_10_300_10: Total time taken= 2 ms I 151.692s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_OnePrefix_DifferingNumDBs_SkipReadCache_10_300_10: Max individual time taken= 2 ms I 151.692s run_tests_on_device(01e405b326d82e95) [ OK ] ProtoDBPerfTest.LoadEntriesSingle_OnePrefix_DifferingNumDBs_SkipReadCache_Medium (181 ms) I 151.692s run_tests_on_device(01e405b326d82e95) [ RUN ] ProtoDBPerfTest.LoadEntriesSingle_OnePrefix_DifferingNumDBs_SkipReadCache_Large I 151.692s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_OnePrefix_DifferingNumDBs_SkipReadCache_20_300_10: Memory use after writes= 500688 bytes I 151.692s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_OnePrefix_DifferingNumDBs_SkipReadCache_20_300_10: Memory use after load= 500688 bytes I 151.692s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_OnePrefix_DifferingNumDBs_SkipReadCache_20_300_10: Total time taken= 3 ms I 151.692s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_OnePrefix_DifferingNumDBs_SkipReadCache_20_300_10: Max individual time taken= 3 ms I 151.692s run_tests_on_device(01e405b326d82e95) [ OK ] ProtoDBPerfTest.LoadEntriesSingle_OnePrefix_DifferingNumDBs_SkipReadCache_Large (327 ms) I 151.692s run_tests_on_device(01e405b326d82e95) [ RUN ] ProtoDBPerfTest.LoadEntriesMulti_OnePrefix_DifferingNumDBs_SkipReadCache_Small I 151.692s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_OnePrefix_DifferingNumDBs_SkipReadCache_5_300_10: Memory use after writes= 143640 bytes I 151.693s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_OnePrefix_DifferingNumDBs_SkipReadCache_5_300_10: Memory use after load= 28728 bytes I 151.693s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_OnePrefix_DifferingNumDBs_SkipReadCache_5_300_10: Total time taken= 2 ms I 151.693s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_OnePrefix_DifferingNumDBs_SkipReadCache_5_300_10: Max individual time taken= 2 ms I 151.693s run_tests_on_device(01e405b326d82e95) [ OK ] ProtoDBPerfTest.LoadEntriesMulti_OnePrefix_DifferingNumDBs_SkipReadCache_Small (162 ms) I 151.693s run_tests_on_device(01e405b326d82e95) [ RUN ] ProtoDBPerfTest.LoadEntriesMulti_OnePrefix_DifferingNumDBs_SkipReadCache_Medium I 151.693s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_OnePrefix_DifferingNumDBs_SkipReadCache_10_300_10: Memory use after writes= 287280 bytes I 151.693s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_OnePrefix_DifferingNumDBs_SkipReadCache_10_300_10: Memory use after load= 28728 bytes I 151.693s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_OnePrefix_DifferingNumDBs_SkipReadCache_10_300_10: Total time taken= 2 ms I 151.693s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_OnePrefix_DifferingNumDBs_SkipReadCache_10_300_10: Max individual time taken= 2 ms I 151.693s run_tests_on_device(01e405b326d82e95) [ OK ] ProtoDBPerfTest.LoadEntriesMulti_OnePrefix_DifferingNumDBs_SkipReadCache_Medium (261 ms) I 151.693s run_tests_on_device(01e405b326d82e95) [ RUN ] ProtoDBPerfTest.LoadEntriesMulti_OnePrefix_DifferingNumDBs_SkipReadCache_Large I 151.693s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_OnePrefix_DifferingNumDBs_SkipReadCache_20_300_10: Memory use after writes= 574560 bytes I 151.694s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_OnePrefix_DifferingNumDBs_SkipReadCache_20_300_10: Memory use after load= 28728 bytes I 151.694s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_OnePrefix_DifferingNumDBs_SkipReadCache_20_300_10: Total time taken= 2 ms I 151.694s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_OnePrefix_DifferingNumDBs_SkipReadCache_20_300_10: Max individual time taken= 2 ms I 151.694s run_tests_on_device(01e405b326d82e95) [ OK ] ProtoDBPerfTest.LoadEntriesMulti_OnePrefix_DifferingNumDBs_SkipReadCache_Large (532 ms) I 151.694s run_tests_on_device(01e405b326d82e95) [ RUN ] ProtoDBPerfTest.LoadEntriesSingle_DifferingNumDBs_Small I 151.694s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_DifferingNumDBs_5_300_10: Memory use after writes= 127224 bytes I 151.694s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_DifferingNumDBs_5_300_10: Memory use after load= 127224 bytes I 151.694s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_DifferingNumDBs_5_300_10: Total time taken= 14 ms I 151.694s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_DifferingNumDBs_5_300_10: Max individual time taken= 0 ms I 151.694s run_tests_on_device(01e405b326d82e95) [ OK ] ProtoDBPerfTest.LoadEntriesSingle_DifferingNumDBs_Small (106 ms) I 151.694s run_tests_on_device(01e405b326d82e95) [ RUN ] ProtoDBPerfTest.LoadEntriesSingle_DifferingNumDBs_Medium I 151.694s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_DifferingNumDBs_10_300_10: Memory use after writes= 250344 bytes I 151.694s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_DifferingNumDBs_10_300_10: Memory use after load= 250344 bytes I 151.694s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_DifferingNumDBs_10_300_10: Total time taken= 30 ms I 151.694s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_DifferingNumDBs_10_300_10: Max individual time taken= 0 ms I 151.694s run_tests_on_device(01e405b326d82e95) [ OK ] ProtoDBPerfTest.LoadEntriesSingle_DifferingNumDBs_Medium (187 ms) I 151.694s run_tests_on_device(01e405b326d82e95) [ RUN ] ProtoDBPerfTest.LoadEntriesSingle_DifferingNumDBs_Large I 151.694s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_DifferingNumDBs_20_300_10: Memory use after writes= 500688 bytes I 151.695s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_DifferingNumDBs_20_300_10: Memory use after load= 500688 bytes I 151.695s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_DifferingNumDBs_20_300_10: Total time taken= 63 ms I 151.695s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_DifferingNumDBs_20_300_10: Max individual time taken= 0 ms I 151.695s run_tests_on_device(01e405b326d82e95) [ OK ] ProtoDBPerfTest.LoadEntriesSingle_DifferingNumDBs_Large (399 ms) I 151.695s run_tests_on_device(01e405b326d82e95) [ RUN ] ProtoDBPerfTest.LoadEntriesMulti_DifferingNumDBs_Small I 151.695s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_DifferingNumDBs_5_300_10: Memory use after writes= 143640 bytes I 151.695s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_DifferingNumDBs_5_300_10: Memory use after load= 143640 bytes I 151.695s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_DifferingNumDBs_5_300_10: Total time taken= 14 ms I 151.695s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_DifferingNumDBs_5_300_10: Max individual time taken= 5 ms I 151.695s run_tests_on_device(01e405b326d82e95) [ OK ] ProtoDBPerfTest.LoadEntriesMulti_DifferingNumDBs_Small (171 ms) I 151.695s run_tests_on_device(01e405b326d82e95) [ RUN ] ProtoDBPerfTest.LoadEntriesMulti_DifferingNumDBs_Medium I 151.695s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_DifferingNumDBs_10_300_10: Memory use after writes= 287280 bytes I 151.695s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_DifferingNumDBs_10_300_10: Memory use after load= 287280 bytes I 151.695s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_DifferingNumDBs_10_300_10: Total time taken= 25 ms I 151.695s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_DifferingNumDBs_10_300_10: Max individual time taken= 4 ms I 151.695s run_tests_on_device(01e405b326d82e95) [ OK ] ProtoDBPerfTest.LoadEntriesMulti_DifferingNumDBs_Medium (316 ms) I 151.695s run_tests_on_device(01e405b326d82e95) [ RUN ] ProtoDBPerfTest.LoadEntriesMulti_DifferingNumDBs_Large I 151.695s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_DifferingNumDBs_20_300_10: Memory use after writes= 574560 bytes I 151.695s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_DifferingNumDBs_20_300_10: Memory use after load= 574560 bytes I 151.696s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_DifferingNumDBs_20_300_10: Total time taken= 29 ms I 151.696s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_DifferingNumDBs_20_300_10: Max individual time taken= 4 ms I 151.696s run_tests_on_device(01e405b326d82e95) [ OK ] ProtoDBPerfTest.LoadEntriesMulti_DifferingNumDBs_Large (620 ms) I 151.696s run_tests_on_device(01e405b326d82e95) [ RUN ] ProtoDBPerfTest.LoadEntriesSingle_DifferingNumDBs_SkipReadCache_Small I 151.696s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_DifferingNumDBs_SkipReadCache_5_300_10: Memory use after writes= 127224 bytes I 151.696s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_DifferingNumDBs_SkipReadCache_5_300_10: Memory use after load= 127224 bytes I 151.696s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_DifferingNumDBs_SkipReadCache_5_300_10: Total time taken= 19 ms I 151.696s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_DifferingNumDBs_SkipReadCache_5_300_10: Max individual time taken= 0 ms I 151.696s run_tests_on_device(01e405b326d82e95) [ OK ] ProtoDBPerfTest.LoadEntriesSingle_DifferingNumDBs_SkipReadCache_Small (127 ms) I 151.696s run_tests_on_device(01e405b326d82e95) [ RUN ] ProtoDBPerfTest.LoadEntriesSingle_DifferingNumDBs_SkipReadCache_Medium I 151.696s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_DifferingNumDBs_SkipReadCache_10_300_10: Memory use after writes= 250344 bytes I 151.696s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_DifferingNumDBs_SkipReadCache_10_300_10: Memory use after load= 250344 bytes I 151.696s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_DifferingNumDBs_SkipReadCache_10_300_10: Total time taken= 32 ms I 151.696s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_DifferingNumDBs_SkipReadCache_10_300_10: Max individual time taken= 0 ms I 151.696s run_tests_on_device(01e405b326d82e95) [ OK ] ProtoDBPerfTest.LoadEntriesSingle_DifferingNumDBs_SkipReadCache_Medium (217 ms) I 151.696s run_tests_on_device(01e405b326d82e95) [ RUN ] ProtoDBPerfTest.LoadEntriesSingle_DifferingNumDBs_SkipReadCache_Large I 151.696s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_DifferingNumDBs_SkipReadCache_20_300_10: Memory use after writes= 500688 bytes I 151.696s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_DifferingNumDBs_SkipReadCache_20_300_10: Memory use after load= 500688 bytes I 151.696s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_DifferingNumDBs_SkipReadCache_20_300_10: Total time taken= 52 ms I 151.697s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesSingle_DifferingNumDBs_SkipReadCache_20_300_10: Max individual time taken= 0 ms I 151.697s run_tests_on_device(01e405b326d82e95) [ OK ] ProtoDBPerfTest.LoadEntriesSingle_DifferingNumDBs_SkipReadCache_Large (372 ms) I 151.697s run_tests_on_device(01e405b326d82e95) [ RUN ] ProtoDBPerfTest.LoadEntriesMulti_DifferingNumDBs_SkipReadCache_Small I 151.697s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_DifferingNumDBs_SkipReadCache_5_300_10: Memory use after writes= 143640 bytes I 151.697s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_DifferingNumDBs_SkipReadCache_5_300_10: Memory use after load= 143640 bytes I 151.697s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_DifferingNumDBs_SkipReadCache_5_300_10: Total time taken= 16 ms I 151.697s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_DifferingNumDBs_SkipReadCache_5_300_10: Max individual time taken= 5 ms I 151.697s run_tests_on_device(01e405b326d82e95) [ OK ] ProtoDBPerfTest.LoadEntriesMulti_DifferingNumDBs_SkipReadCache_Small (179 ms) I 151.697s run_tests_on_device(01e405b326d82e95) [ RUN ] ProtoDBPerfTest.LoadEntriesMulti_DifferingNumDBs_SkipReadCache_Medium I 151.697s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_DifferingNumDBs_SkipReadCache_10_300_10: Memory use after writes= 287280 bytes I 151.697s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_DifferingNumDBs_SkipReadCache_10_300_10: Memory use after load= 287280 bytes I 151.697s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_DifferingNumDBs_SkipReadCache_10_300_10: Total time taken= 22 ms I 151.697s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_DifferingNumDBs_SkipReadCache_10_300_10: Max individual time taken= 4 ms I 151.697s run_tests_on_device(01e405b326d82e95) [ OK ] ProtoDBPerfTest.LoadEntriesMulti_DifferingNumDBs_SkipReadCache_Medium (306 ms) I 151.697s run_tests_on_device(01e405b326d82e95) [ RUN ] ProtoDBPerfTest.LoadEntriesMulti_DifferingNumDBs_SkipReadCache_Large I 151.697s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_DifferingNumDBs_SkipReadCache_20_300_10: Memory use after writes= 574560 bytes I 151.697s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_DifferingNumDBs_SkipReadCache_20_300_10: Memory use after load= 574560 bytes I 151.697s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_DifferingNumDBs_SkipReadCache_20_300_10: Total time taken= 30 ms I 151.697s run_tests_on_device(01e405b326d82e95) *RESULT ProtoDBPerfTestLoadEntriesMulti_DifferingNumDBs_SkipReadCache_20_300_10: Max individual time taken= 2 ms I 151.698s run_tests_on_device(01e405b326d82e95) [ OK ] ProtoDBPerfTest.LoadEntriesMulti_DifferingNumDBs_SkipReadCache_Large (612 ms) I 151.698s run_tests_on_device(01e405b326d82e95) [----------] 62 tests from ProtoDBPerfTest (27473 ms total) I 151.698s run_tests_on_device(01e405b326d82e95) I 151.698s run_tests_on_device(01e405b326d82e95) [----------] 2 tests from HQPPerfTestOnePopularURL I 151.698s run_tests_on_device(01e405b326d82e95) [ RUN ] HQPPerfTestOnePopularURL.Typing I 151.698s run_tests_on_device(01e405b326d82e95) *RESULT HQPPerfTestOnePopularURLTyping: 0-4= [1,16,13,13,13,] ms I 151.698s run_tests_on_device(01e405b326d82e95) *RESULT HQPPerfTestOnePopularURLTyping: 5-9= [13,13,13,14,13,] ms I 151.698s run_tests_on_device(01e405b326d82e95) *RESULT HQPPerfTestOnePopularURLTyping: 10-14= [15,14,14,15,14,] ms I 151.698s run_tests_on_device(01e405b326d82e95) *RESULT HQPPerfTestOnePopularURLTyping: 15-19= [15,14,14,14,13,] ms I 151.698s run_tests_on_device(01e405b326d82e95) *RESULT HQPPerfTestOnePopularURLTyping: 20-24= [1,15,14,14,1,] ms I 151.698s run_tests_on_device(01e405b326d82e95) *RESULT HQPPerfTestOnePopularURLTyping: 25-29= [15,14,14,14,14,] ms I 151.698s run_tests_on_device(01e405b326d82e95) *RESULT HQPPerfTestOnePopularURLTyping: 30-34= [16,14,14,14,1,] ms I 151.698s run_tests_on_device(01e405b326d82e95) *RESULT HQPPerfTestOnePopularURLTyping: 35-39= [15,15,14,14,14,] ms I 151.698s run_tests_on_device(01e405b326d82e95) *RESULT HQPPerfTestOnePopularURLTyping: 40-44= [15,14,14,14,14,] ms I 151.698s run_tests_on_device(01e405b326d82e95) *RESULT HQPPerfTestOnePopularURLTyping: 45-49= [15,4,1,1,1,] ms I 151.698s run_tests_on_device(01e405b326d82e95) *RESULT HQPPerfTestOnePopularURLTyping: 50-54= [1,1,1,1,1,] ms I 151.698s run_tests_on_device(01e405b326d82e95) [ OK ] HQPPerfTestOnePopularURL.Typing (1825 ms) I 151.698s run_tests_on_device(01e405b326d82e95) [ RUN ] HQPPerfTestOnePopularURL.Backspacing I 151.698s run_tests_on_device(01e405b326d82e95) *RESULT HQPPerfTestOnePopularURLBackspacing: 54-50= [1,1,1,1,1,] ms I 151.699s run_tests_on_device(01e405b326d82e95) *RESULT HQPPerfTestOnePopularURLBackspacing: 49-45= [2,8,8,8,14,] ms I 151.699s run_tests_on_device(01e405b326d82e95) *RESULT HQPPerfTestOnePopularURLBackspacing: 44-40= [14,14,14,14,14,] ms I 151.699s run_tests_on_device(01e405b326d82e95) *RESULT HQPPerfTestOnePopularURLBackspacing: 39-35= [14,14,14,15,16,] ms I 151.699s run_tests_on_device(01e405b326d82e95) *RESULT HQPPerfTestOnePopularURLBackspacing: 34-30= [1,14,14,14,15,] ms I 151.699s run_tests_on_device(01e405b326d82e95) *RESULT HQPPerfTestOnePopularURLBackspacing: 29-25= [15,14,14,14,15,] ms I 151.699s run_tests_on_device(01e405b326d82e95) *RESULT HQPPerfTestOnePopularURLBackspacing: 24-20= [1,14,14,15,1,] ms I 151.699s run_tests_on_device(01e405b326d82e95) *RESULT HQPPerfTestOnePopularURLBackspacing: 19-15= [14,14,14,14,14,] ms I 151.699s run_tests_on_device(01e405b326d82e95) *RESULT HQPPerfTestOnePopularURLBackspacing: 14-10= [15,15,14,14,14,] ms I 151.699s run_tests_on_device(01e405b326d82e95) *RESULT HQPPerfTestOnePopularURLBackspacing: 9-5= [15,15,14,13,13,] ms I 151.699s run_tests_on_device(01e405b326d82e95) *RESULT HQPPerfTestOnePopularURLBackspacing: 4-0= [14,13,13,17,1,] ms I 151.699s run_tests_on_device(01e405b326d82e95) [ OK ] HQPPerfTestOnePopularURL.Backspacing (1708 ms) I 151.699s run_tests_on_device(01e405b326d82e95) [----------] 2 tests from HQPPerfTestOnePopularURL (3539 ms total) I 151.699s run_tests_on_device(01e405b326d82e95) I 151.699s run_tests_on_device(01e405b326d82e95) [----------] 2 tests from IndexedRulesetPerftest I 151.699s run_tests_on_device(01e405b326d82e95) [ RUN ] IndexedRulesetPerftest.IndexRuleset I 151.699s run_tests_on_device(01e405b326d82e95) *RESULT index_and_write_time: = 51488 microseconds I 151.699s run_tests_on_device(01e405b326d82e95) [ OK ] IndexedRulesetPerftest.IndexRuleset (166 ms) I 151.699s run_tests_on_device(01e405b326d82e95) [ RUN ] IndexedRulesetPerftest.MatchAll I 151.699s run_tests_on_device(01e405b326d82e95) *RESULT median_match_time: = 244809 microseconds I 151.699s run_tests_on_device(01e405b326d82e95) [ OK ] IndexedRulesetPerftest.MatchAll (1324 ms) I 151.699s run_tests_on_device(01e405b326d82e95) [----------] 2 tests from IndexedRulesetPerftest (1495 ms total) I 151.699s run_tests_on_device(01e405b326d82e95) I 151.699s run_tests_on_device(01e405b326d82e95) [----------] 1 test from VisitedLink I 151.699s run_tests_on_device(01e405b326d82e95) [ RUN ] VisitedLink.TestAddAndQuery I 151.700s run_tests_on_device(01e405b326d82e95) *RESULT Visited_link_add_and_query: = 462.344 ms I 151.700s run_tests_on_device(01e405b326d82e95) [ OK ] VisitedLink.TestAddAndQuery (485 ms) I 151.700s run_tests_on_device(01e405b326d82e95) [----------] 1 test from VisitedLink (489 ms total) I 151.700s run_tests_on_device(01e405b326d82e95) I 151.700s run_tests_on_device(01e405b326d82e95) [----------] Global test environment tear-down I 151.700s run_tests_on_device(01e405b326d82e95) [==========] 68 tests from 5 test suites ran. (35022 ms total) I 151.700s run_tests_on_device(01e405b326d82e95) [ PASSED ] 68 tests. I 151.700s run_tests_on_device(01e405b326d82e95) <<ScopedMainEntryLogger I 151.703s run_tests_on_device(01e405b326d82e95) Finished running tests on this device. I 151.710s Main FINISHED TRY #1/3 I 151.710s Main All tests completed. C 151.710s Main ******************************************************************************** C 151.710s Main Summary C 151.710s Main ******************************************************************************** C 151.711s Main [==========] 68 tests ran. C 151.711s Main [ PASSED ] 68 tests. C 151.711s Main ******************************************************************************** I 151.716s tear_down_device(01e405b326d82e95) Wrote device cache: /b/swarming/w/ir/out/Release/device_cache_01e405b326d82e95.json I 151.720s Main Generated json results file at /b/swarming/w/it4YLyGs/tmpsDwOUj I 151.720s Main Finishing archiving output. Command ['./components_perftests', '--verbose', '--test-launcher-print-test-stdio=always'] returned exit code 0 2019-04-25 12:46:48,198 - root: [DEBUG] Contents of task_output_dir: ['/b/swarming/w/ir/tmp/t/tmpulPu2x/446ed7368b37c110'] 2019-04-25 12:46:48,198 - root: [DEBUG] Found shard_json_files: ['/b/swarming/w/ir/tmp/t/tmpulPu2x/446ed7368b37c110/output.json'] 2019-04-25 12:46:48,198 - root: [INFO] merge_cmd: /b/swarming/w/ir/cache/vpython/ba947a/bin/python /b/swarming/w/ir/cache/builder/src/tools/perf/process_perf_results.py --build-properties {"bot_id": "swarm1596-c4", "branch": "refs/heads/master", "buildbucket": {"build": {"bucket": "luci.chromium.ci", "created_by": "project:chromium", "created_ts": 1556219469762629, "id": "8915179329232731856", "project": "chromium", "tags": ["build_address:luci.chromium.ci/android-marshmallow-arm64-rel/14713", "builder:android-marshmallow-arm64-rel", "buildset:commit/git/09ef820ccf81b4e38eb55dbfe01719ca4fdd8b63", "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/09ef820ccf81b4e38eb55dbfe01719ca4fdd8b63", "gitiles_ref:refs/heads/master", "scheduler_invocation_id:9080609391506703872", "scheduler_job_id:chromium/android-marshmallow-arm64-rel", "user_agent:luci-scheduler"]}, "hostname": "cr-buildbucket.appspot.com"}, "buildername": "android-marshmallow-arm64-rel", "buildnumber": 14713, "got_angle_revision": "fb6fbdbc582dff1ab9c376f4e759cd728bd74411", "got_dawn_revision": "22cee9ae36b23a8908525d668ef4728a26c931c4", "got_nacl_revision": "6abc006f6760ec49350cd45e8bccbff4809725ac", "got_revision": "09ef820ccf81b4e38eb55dbfe01719ca4fdd8b63", "got_revision_cp": "refs/heads/master@{#654136}", "got_swarming_client_revision": "aa60736aded9fc32a0e21a81f5fc51f6009d01f3", "got_v8_revision": "2531f0784031894907b009a0a2de46ea8bc04547", "got_v8_revision_cp": "refs/heads/7.6.29@{#1}", "got_webrtc_revision": "ff727a8abb360373fa9051518810e7d00604e695", "got_webrtc_revision_cp": "refs/heads/master@{#27776}", "mastername": "chromium.android", "path_config": "generic", "recipe": "chromium", "repository": "https://chromium.googlesource.com/chromium/src.git", "revision": "09ef820ccf81b4e38eb55dbfe01719ca4fdd8b63"} --summary-json /b/swarming/w/ir/tmp/t/tmpqqWjPu.json --task-output-dir /b/swarming/w/ir/tmp/t/tmpulPu2x --smoke-test-mode -o /b/swarming/w/ir/tmp/t/tmpYhCoEd.json /b/swarming/w/ir/tmp/t/tmpulPu2x/446ed7368b37c110/output.json Running ['/b/swarming/w/ir/cache/vpython/ba947a/bin/python', '/b/swarming/w/ir/cache/builder/src/tools/perf/process_perf_results.py', '--build-properties', '{"bot_id": "swarm1596-c4", "branch": "refs/heads/master", "buildbucket": {"build": {"bucket": "luci.chromium.ci", "created_by": "project:chromium", "created_ts": 1556219469762629, "id": "8915179329232731856", "project": "chromium", "tags": ["build_address:luci.chromium.ci/android-marshmallow-arm64-rel/14713", "builder:android-marshmallow-arm64-rel", "buildset:commit/git/09ef820ccf81b4e38eb55dbfe01719ca4fdd8b63", "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/09ef820ccf81b4e38eb55dbfe01719ca4fdd8b63", "gitiles_ref:refs/heads/master", "scheduler_invocation_id:9080609391506703872", "scheduler_job_id:chromium/android-marshmallow-arm64-rel", "user_agent:luci-scheduler"]}, "hostname": "cr-buildbucket.appspot.com"}, "buildername": "android-marshmallow-arm64-rel", "buildnumber": 14713, "got_angle_revision": "fb6fbdbc582dff1ab9c376f4e759cd728bd74411", "got_dawn_revision": "22cee9ae36b23a8908525d668ef4728a26c931c4", "got_nacl_revision": "6abc006f6760ec49350cd45e8bccbff4809725ac", "got_revision": "09ef820ccf81b4e38eb55dbfe01719ca4fdd8b63", "got_revision_cp": "refs/heads/master@{#654136}", "got_swarming_client_revision": "aa60736aded9fc32a0e21a81f5fc51f6009d01f3", "got_v8_revision": "2531f0784031894907b009a0a2de46ea8bc04547", "got_v8_revision_cp": "refs/heads/7.6.29@{#1}", "got_webrtc_revision": "ff727a8abb360373fa9051518810e7d00604e695", "got_webrtc_revision_cp": "refs/heads/master@{#27776}", "mastername": "chromium.android", "path_config": "generic", "recipe": "chromium", "repository": "https://chromium.googlesource.com/chromium/src.git", "revision": "09ef820ccf81b4e38eb55dbfe01719ca4fdd8b63"}', '--summary-json', '/b/swarming/w/ir/tmp/t/tmpqqWjPu.json', '--task-output-dir', '/b/swarming/w/ir/tmp/t/tmpulPu2x', u'--smoke-test-mode', '-o', '/b/swarming/w/ir/tmp/t/tmpYhCoEd.json', '/b/swarming/w/ir/tmp/t/tmpulPu2x/446ed7368b37c110/output.json'] in None (env: None) ['/b/swarming/w/ir/cache/builder/src/tools/perf/process_perf_results.py', '--build-properties', '{"bot_id": "swarm1596-c4", "branch": "refs/heads/master", "buildbucket": {"build": {"bucket": "luci.chromium.ci", "created_by": "project:chromium", "created_ts": 1556219469762629, "id": "8915179329232731856", "project": "chromium", "tags": ["build_address:luci.chromium.ci/android-marshmallow-arm64-rel/14713", "builder:android-marshmallow-arm64-rel", "buildset:commit/git/09ef820ccf81b4e38eb55dbfe01719ca4fdd8b63", "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/09ef820ccf81b4e38eb55dbfe01719ca4fdd8b63", "gitiles_ref:refs/heads/master", "scheduler_invocation_id:9080609391506703872", "scheduler_job_id:chromium/android-marshmallow-arm64-rel", "user_agent:luci-scheduler"]}, "hostname": "cr-buildbucket.appspot.com"}, "buildername": "android-marshmallow-arm64-rel", "buildnumber": 14713, "got_angle_revision": "fb6fbdbc582dff1ab9c376f4e759cd728bd74411", "got_dawn_revision": "22cee9ae36b23a8908525d668ef4728a26c931c4", "got_nacl_revision": "6abc006f6760ec49350cd45e8bccbff4809725ac", "got_revision": "09ef820ccf81b4e38eb55dbfe01719ca4fdd8b63", "got_revision_cp": "refs/heads/master@{#654136}", "got_swarming_client_revision": "aa60736aded9fc32a0e21a81f5fc51f6009d01f3", "got_v8_revision": "2531f0784031894907b009a0a2de46ea8bc04547", "got_v8_revision_cp": "refs/heads/7.6.29@{#1}", "got_webrtc_revision": "ff727a8abb360373fa9051518810e7d00604e695", "got_webrtc_revision_cp": "refs/heads/master@{#27776}", "mastername": "chromium.android", "path_config": "generic", "recipe": "chromium", "repository": "https://chromium.googlesource.com/chromium/src.git", "revision": "09ef820ccf81b4e38eb55dbfe01719ca4fdd8b63"}', '--summary-json', '/b/swarming/w/ir/tmp/t/tmpqqWjPu.json', '--task-output-dir', '/b/swarming/w/ir/tmp/t/tmpulPu2x', '--smoke-test-mode', '-o', '/b/swarming/w/ir/tmp/t/tmpYhCoEd.json', '/b/swarming/w/ir/tmp/t/tmpulPu2x/446ed7368b37c110/output.json'] Duration of Generating perf log streams: 0 seconds Duration of Analyzing perf json test results: 0 seconds Duration of Merging json test results: 0 seconds Duration of Total process_perf_results: 0 seconds Command ['/b/swarming/w/ir/cache/vpython/ba947a/bin/python', '/b/swarming/w/ir/cache/builder/src/tools/perf/process_perf_results.py', '--build-properties', '{"bot_id": "swarm1596-c4", "branch": "refs/heads/master", "buildbucket": {"build": {"bucket": "luci.chromium.ci", "created_by": "project:chromium", "created_ts": 1556219469762629, "id": "8915179329232731856", "project": "chromium", "tags": ["build_address:luci.chromium.ci/android-marshmallow-arm64-rel/14713", "builder:android-marshmallow-arm64-rel", "buildset:commit/git/09ef820ccf81b4e38eb55dbfe01719ca4fdd8b63", "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/09ef820ccf81b4e38eb55dbfe01719ca4fdd8b63", "gitiles_ref:refs/heads/master", "scheduler_invocation_id:9080609391506703872", "scheduler_job_id:chromium/android-marshmallow-arm64-rel", "user_agent:luci-scheduler"]}, "hostname": "cr-buildbucket.appspot.com"}, "buildername": "android-marshmallow-arm64-rel", "buildnumber": 14713, "got_angle_revision": "fb6fbdbc582dff1ab9c376f4e759cd728bd74411", "got_dawn_revision": "22cee9ae36b23a8908525d668ef4728a26c931c4", "got_nacl_revision": "6abc006f6760ec49350cd45e8bccbff4809725ac", "got_revision": "09ef820ccf81b4e38eb55dbfe01719ca4fdd8b63", "got_revision_cp": "refs/heads/master@{#654136}", "got_swarming_client_revision": "aa60736aded9fc32a0e21a81f5fc51f6009d01f3", "got_v8_revision": "2531f0784031894907b009a0a2de46ea8bc04547", "got_v8_revision_cp": "refs/heads/7.6.29@{#1}", "got_webrtc_revision": "ff727a8abb360373fa9051518810e7d00604e695", "got_webrtc_revision_cp": "refs/heads/master@{#27776}", "mastername": "chromium.android", "path_config": "generic", "recipe": "chromium", "repository": "https://chromium.googlesource.com/chromium/src.git", "revision": "09ef820ccf81b4e38eb55dbfe01719ca4fdd8b63"}', '--summary-json', '/b/swarming/w/ir/tmp/t/tmpqqWjPu.json', '--task-output-dir', '/b/swarming/w/ir/tmp/t/tmpulPu2x', u'--smoke-test-mode', '-o', '/b/swarming/w/ir/tmp/t/tmpYhCoEd.json', '/b/swarming/w/ir/tmp/t/tmpulPu2x/446ed7368b37c110/output.json'] returned exit code 0