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

python -u /b/s/w/ir/kitchen-checkout/build/scripts/tools/runit.py --show-path --with-third-party-lib -- python /b/s/w/ir/kitchen-checkout/build/scripts/slave/recipe_modules/swarming/resources/collect_task.py --verbose -o /b/s/w/ir/tmp/t/tmpqCth5e.json --task-output-dir /b/s/w/ir/kitchen-workdir/recipe_cleanup/0zEhB47 --merge-script /b/s/w/ir/kitchen-checkout/build/scripts/slave/recipe_modules/swarming/resources/noop_merge.py --merge-script-stdout-file /b/s/w/ir/tmp/t/tmpKpA06Vmerge_script_log --merge-additional-args '[]' --summary-json-file /b/s/w/ir/tmp/t/tmpIg5wkO.json -- swarming collect -server https://chromium-swarm.appspot.com -worker 50 -task-summary-python -task-output-stdout all -verbose -requests-json /b/s/w/ir/tmp/t/tmpNn7M9L.json in dir /b/s/w/ir/kitchen-workdir: allow_subannotations: False base_name: 'ios_chrome_integration_egtests (iPhone X iOS 12.1) on Mac-10.13.6' cmd: ['python', '-u', '/b/s/w/ir/kitchen-checkout/build/scripts/tools/runit.py', '--show-path', '--with-third-party-lib', '--', 'python', '/b/s/w/ir/kitchen-checkout/build/scripts/slave/recipe_modules/swarming/resources/collect_task.py', '--verbose', '-o', '/b/s/w/ir/tmp/t/tmpqCth5e.json', '--task-output-dir', '/b/s/w/ir/kitchen-workdir/recipe_cleanup/0zEhB47', '--merge-script', '/b/s/w/ir/kitchen-checkout/build/scripts/slave/recipe_modules/swarming/resources/noop_merge.py', '--merge-script-stdout-file', '/b/s/w/ir/tmp/t/tmpKpA06Vmerge_script_log', '--merge-additional-args', '[]', '--summary-json-file', '/b/s/w/ir/tmp/t/tmpIg5wkO.json', '--', 'swarming', 'collect', '-server', 'https://chromium-swarm.appspot.com', '-worker', '50', '-task-summary-python', '-task-output-stdout', 'all', '-verbose', '-requests-json', '/b/s/w/ir/tmp/t/tmpNn7M9L.json'] env: {} env_prefixes: {'PATH': '/b/s/w/ir/cache/swarming_client'} env_suffixes: {} infra_step: False name: 'ios_chrome_integration_egtests (iPhone X iOS 12.1) on Mac-10.13.6' nest_level: 0 ok_ret: frozenset([0]) step_test_data: <lambda>(...) trigger_specs: () full environment: Apple_PubSub_Socket_Render: /private/tmp/com.apple.launchd.kJ2W0LmJbT/Render BOTO_CONFIG: /b/s/w/ir/tmp/gsutil_task/.boto BUILDBUCKET_EXPERIMENTAL: FALSE CIPD_CACHE_DIR: /b/s/cipd_cache/cache CIPD_PROTOCOL: v2 DEVSHELL_CLIENT_PORT: 51859 DOCKER_CONFIG: /b/s/w/ir/tmp/docker_cfg_task DOCKER_TMPDIR: /b/s/w/ir/tmp/docker_tmp_task GIT_CONFIG_NOSYSTEM: 1 GIT_TERMINAL_PROMPT: 0 HOME: /Users/chrome-bot INFRA_GIT_WRAPPER_HOME: /b/s/w/ir/tmp/git_home_task LOGDOG_COORDINATOR_HOST: logs.chromium.org LOGDOG_STREAM_PREFIX: buildbucket/cr-buildbucket.appspot.com/8921479472293839760 LOGDOG_STREAM_PROJECT: chromium LOGDOG_STREAM_SERVER_PATH: unix:/b/s/w/ir/tmp/ld.sock LOGNAME: chrome-bot LUCI_CONTEXT: /b/s/w/itBHg6vx/luci_context.559872433 MAC_CHROMIUM_TMPDIR: /b/s/w/ir/tmp/t NO_GCE_CHECK: False PATH: /b/s/w/ir/cache/swarming_client:/b/s/w/ir/cipd_bin_packages:/b/s/w/ir/cipd_bin_packages/bin:/b/s/cipd_cache/bin:/opt/local/bin:/opt/local/sbin:/usr/local/sbin:/usr/local/git/bin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin PWD: /b/s/w/ir PYTHONIOENCODING: UTF-8 PYTHONUNBUFFERED: 1 SHELL: /bin/bash SHLVL: 1 SSH_AUTH_SOCK: /private/tmp/com.apple.launchd.prG1uA48wc/Listeners SWARMING_BOT_ID: vm149-m9 SWARMING_HEADLESS: 1 SWARMING_SERVER: https://chromium-swarm.appspot.com SWARMING_TASK_ID: 43089b03d2bdc511 TEMP: /b/s/w/ir/tmp/t TEMPDIR: /b/s/w/ir/tmp/t TMP: /b/s/w/ir/tmp/t TMPDIR: /b/s/w/ir/tmp/t USER: chrome-bot VERSIONER_PYTHON_PREFER_32_BIT: no VERSIONER_PYTHON_VERSION: 2.7 VPYTHON_VIRTUALENV_ROOT: /b/s/w/ir/cache/vpython XPC_FLAGS: 0x0 XPC_SERVICE_NAME: 0 _: /b/s/w/ir/cipd_bin_packages/vpython __CF_USER_TEXT_ENCODING: 0x1F4:0x0:0x0 Set PYTHONPATH: /b/s/w/ir/kitchen-checkout/build/scripts:/b/s/w/ir/kitchen-checkout/build/site_config:/b/s/w/ir/kitchen-checkout/build/third_party:/b/s/w/ir/kitchen-checkout/build/third_party/buildbot_8_4p1:/b/s/w/ir/kitchen-checkout/build/third_party/buildbot_slave_8_4:/b/s/w/ir/kitchen-checkout/build/third_party/coverage-3.7.1:/b/s/w/ir/kitchen-checkout/build/third_party/decorator_3_3_1:/b/s/w/ir/kitchen-checkout/build/third_party/google_api_python_client:/b/s/w/ir/kitchen-checkout/build/third_party/httplib2/python2:/b/s/w/ir/kitchen-checkout/build/third_party/infra_libs:/b/s/w/ir/kitchen-checkout/build/third_party/jinja2:/b/s/w/ir/kitchen-checkout/build/third_party/markupsafe:/b/s/w/ir/kitchen-checkout/build/third_party/oauth2client:/b/s/w/ir/kitchen-checkout/build/third_party/pyasn1:/b/s/w/ir/kitchen-checkout/build/third_party/pyasn1-modules:/b/s/w/ir/kitchen-checkout/build/third_party/python-rsa:/b/s/w/ir/kitchen-checkout/build/third_party/requests_2_10_0:/b/s/w/ir/kitchen-checkout/build/third_party/setuptools-0.6c11:/b/s/w/ir/kitchen-checkout/build/third_party/sqlalchemy_0_7_1:/b/s/w/ir/kitchen-checkout/build/third_party/sqlalchemy_migrate_0_7_1:/b/s/w/ir/kitchen-checkout/build/third_party/tempita_0_5:/b/s/w/ir/kitchen-checkout/build/third_party/twisted_10_2:/b/s/w/ir/kitchen-checkout/build/third_party/uritemplate:/b/s/w/ir/kitchen-checkout/build/third_party/site-packages 2019-02-14 22:30:57,078 - root: [DEBUG] Using task_output_dir: '/b/s/w/ir/kitchen-workdir/recipe_cleanup/0zEhB47' 2019-02-14 22:30:57,078 - root: [WARNING] task_output_dir '/b/s/w/ir/kitchen-workdir/recipe_cleanup/0zEhB47' already exists! 2019-02-14 22:30:57,079 - root: [WARNING] task_output_dir existing content: [] 2019-02-14 22:30:57,079 - root: [INFO] collect_cmd: swarming collect -server https://chromium-swarm.appspot.com -worker 50 -task-summary-python -task-output-stdout all -verbose -requests-json /b/s/w/ir/tmp/t/tmpNn7M9L.json -output-dir /b/s/w/ir/kitchen-workdir/recipe_cleanup/0zEhB47 -task-summary-json /b/s/w/ir/tmp/t/tmpIg5wkO.json [D2019-02-14T22:30:58.597367-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:30:59.905928-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:31:01.315554-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:31:02.713093-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:31:04.115842-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:31:05.560183-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:31:07.056651-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:31:08.628088-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:31:10.358122-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:31:12.053245-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:31:13.783002-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:31:15.585479-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:31:17.476891-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:31:19.413111-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:31:21.444020-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:31:23.510121-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:31:25.617952-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:31:27.912250-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:31:30.302321-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:31:32.644747-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:31:35.147132-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:31:37.645062-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:31:40.301847-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:31:42.993570-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:31:45.817787-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:31:48.751715-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:31:51.793548-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:31:54.891834-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:31:58.111803-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:32:01.356702-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:32:04.819899-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:32:08.391139-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:32:11.999928-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:32:15.735784-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:32:19.835383-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:32:23.839356-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:32:27.972687-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:32:32.331720-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:32:36.763035-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:32:41.435282-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:32:46.266014-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:32:51.613285-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:32:56.749330-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:33:02.139531-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:33:07.600469-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:33:13.255886-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:33:19.126443-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:33:25.143195-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:33:31.411423-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:33:37.837027-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:33:44.810813-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:33:51.824122-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:33:58.837836-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:34:06.425336-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:34:14.005753-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:34:21.775135-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:34:29.863543-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:34:38.274777-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:34:46.892654-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:34:55.912220-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:35:05.218752-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:35:14.823529-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:35:24.732469-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:35:34.903877-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:35:45.756048-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:35:56.634179-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:36:07.812623-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:36:19.522798-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:36:31.648269-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:36:44.032159-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:36:56.818853-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:37:10.122330-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:37:23.808034-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:37:38.212322-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:37:52.803565-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:38:07.982867-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:38:23.358079-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:38:38.628474-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:38:53.980316-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:39:09.373631-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:39:24.757426-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:39:39.978265-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:39:55.197093-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:40:10.426567-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:40:25.926757-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:40:41.150602-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:40:56.352503-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:41:11.730459-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:41:27.166797-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:41:42.627107-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:41:57.936030-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:42:13.287761-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:42:28.660045-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:42:43.966305-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:42:59.317158-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:43:14.518792-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:43:29.867056-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:43:45.137992-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:44:00.432958-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:44:15.658128-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:44:31.037385-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:44:46.503112-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:45:01.727430-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:45:17.420503-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:45:32.734023-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 [D2019-02-14T22:45:48.030892-08:00 36621 0 collect.go:343] Waiting task_id: 4308a8b930924f10 4308a8b930924f10: exit 0 [I 2019-02-14 22:28:18] About to install Xcode 10b61 in Xcode.app for ios [10:28:19:INFO] Using Xcode version 10.1 build 10B61 at /b/s/w/ir/Xcode.app/Contents/Developer Device wiped. [10:28:27:DEBUG] Setting sigterm handler. [10:28:28:INFO] User defaults from command line: [10:28:28:INFO] IDETestRunSpecificationPath = /var/folders/2j/22s2gz0s7hn48k32d47clxf80000gm/T/9ECF84ED-48E3-42F7-A612-9C318F4EC81B [10:28:28:INFO] [10:28:29:INFO] Testing started on 'iPhone X' [10:28:29:INFO] 2019-02-14 22:28:29.093 xcodebuild[6602:75826] IDETestOperationsObserverDebug: Writing diagnostic log for test session to: [10:28:29:INFO] /Users/chrome-bot/Library/Developer/Xcode/DerivedData/temporary-auteyumowoxdgmgmtlwickdyinff/Logs/Test/Test-Transient Testing-2019.02.14_22-28-29--0800.xcresult/1_Test/Diagnostics/ios_chrome_integration_egtests_module-315050AB-973F-4B34-8044-EDF7F7A90BD9/ios_chrome_integration_egtests_module-7C54ABCA-9D17-4178-95E4-E225B60EACC5/Session-ios_chrome_integration_egtests_module-2019-02-14_222829-InR4GK.log [10:28:29:INFO] 2019-02-14 22:28:29.093 xcodebuild[6602:75810] [MT] IDETestOperationsObserverDebug: (93E3424D-4BAB-4D95-85F2-6DD064F5A906) Beginning test session ios_chrome_integration_egtests_module-93E3424D-4BAB-4D95-85F2-6DD064F5A906 at 2019-02-14 22:28:29.093 with Xcode 10B61 on target <DVTiPhoneSimulator: 0x7fb04c030860> { [10:28:29:INFO] SimDevice: iPhone X (49E94E9E-CDD0-487B-AE36-62B087F03DE6, iOS 12.1, Shutdown) [10:28:29:INFO] } (12.1 (16B91)) [10:28:46:INFO] dyld: warning: could not load inserted library '/b/s/w/ir/Xcode.app/Contents/Developer/Platforms/iPhoneSimulator.platform/Developer/Library/PrivateFrameworks/IDEBundleInjection.framework/IDEBundleInjection' into hardened process because image not found [10:28:47:INFO] 2019-02-14 22:28:47.839 ios_chrome_integration_egtests[6714:76941] Crash handler setup started. [10:28:48:INFO] 2019-02-14 22:28:47.839 ios_chrome_integration_egtests[6714:76941] Crash handler setup completed. [10:28:48:INFO] 2019-02-14 22:28:47.839 ios_chrome_integration_egtests[6714:76941] Enabling accessibility for automation on Simulator. [10:28:50:INFO] 2019-02-14 22:28:50.315 ios_chrome_integration_egtests[6714:76941] Unbalanced calls to begin/end appearance transitions for <TabGridViewController: 0x7faade029c00>. [10:28:51:INFO] [0214/222851.833805:WARNING:leveldb_database.cc(111)] Unable to open /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/49E94E9E-CDD0-487B-AE36-62B087F03DE6/data/Containers/Data/Application/E8C52919-EF43-4E99-83EA-95CB2DB392BB/Library/Application Support/Chromium/Default/shared_proto_db: Invalid argument: /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/49E94E9E-CDD0-487B-AE36-62B087F03DE6/data/Containers/Data/Application/E8C52919-EF43-4E99-83EA-95CB2DB392BB/Library/Application Support/Chromium/Default/shared_proto_db: does not exist (create_if_missing is false) [10:28:57:INFO] Test Suite 'All tests' started at 2019-02-14 22:28:57.547 [10:28:57:INFO] Test Suite 'ios_chrome_integration_egtests.app' started at 2019-02-14 22:28:57.550 [10:28:57:INFO] Test Suite 'BrowserStateServicesTestCase' started at 2019-02-14 22:28:57.552 [10:28:57:INFO] [0214/222857.573319:WARNING:loopback_server.cc(723)] Loopback sync persistent state file does not exist. [10:28:59:INFO] Test Case '-[BrowserStateServicesTestCase testConnectionToIdentityService]' started. [10:29:03:INFO] Test Case '-[BrowserStateServicesTestCase testConnectionToIdentityService]' passed (4.008 seconds). [10:29:03:INFO] Test Suite 'BrowserStateServicesTestCase' passed at 2019-02-14 22:29:03.519. [10:29:03:INFO] Executed 1 test, with 0 failures (0 unexpected) in 4.008 (5.967) seconds [10:29:03:INFO] Test Suite 'ContextMenuTestCase' started at 2019-02-14 22:29:03.520 [10:29:03:INFO] [0214/222903.520771:WARNING:loopback_server.cc(723)] Loopback sync persistent state file does not exist. [10:29:03:INFO] Test Case '-[ContextMenuTestCase testAppropriateContextMenu]' started. [10:29:05:INFO] [0214/222905.690308:WARNING:embedded_test_server.cc(237)] Request not handled. Returning 404: /favicon.ico [10:29:12:INFO] Test Case '-[ContextMenuTestCase testAppropriateContextMenu]' passed (9.029 seconds). [10:29:12:INFO] Test Case '-[ContextMenuTestCase testContextMenuCancelSystemTouchesMetric]' started. [10:29:14:INFO] [0214/222914.598088:WARNING:embedded_test_server.cc(237)] Request not handled. Returning 404: /favicon.ico [10:29:21:INFO] Test Case '-[ContextMenuTestCase testContextMenuCancelSystemTouchesMetric]' passed (9.217 seconds). [10:29:21:INFO] Test Case '-[ContextMenuTestCase testContextMenuDisplayedOnImage]' started. [10:29:24:INFO] [0214/222924.107209:WARNING:embedded_test_server.cc(237)] Request not handled. Returning 404: /favicon.ico [10:29:32:INFO] Test Case '-[ContextMenuTestCase testContextMenuDisplayedOnImage]' passed (10.240 seconds). [10:29:32:INFO] Test Case '-[ContextMenuTestCase testContextMenuElementFetchDurationMetric]' started. [10:29:34:INFO] [0214/222934.068634:WARNING:embedded_test_server.cc(237)] Request not handled. Returning 404: /favicon.ico [10:29:40:INFO] Test Case '-[ContextMenuTestCase testContextMenuElementFetchDurationMetric]' passed (8.544 seconds). [10:29:40:INFO] Test Case '-[ContextMenuTestCase testContextMenuOpenInNewTab]' started. [10:29:42:INFO] [0214/222942.554629:WARNING:embedded_test_server.cc(237)] Request not handled. Returning 404: /favicon.ico [10:29:50:INFO] Test Case '-[ContextMenuTestCase testContextMenuOpenInNewTab]' passed (9.915 seconds). [10:29:50:INFO] Test Case '-[ContextMenuTestCase testContextMenuSelectedTextCallout]' started. [10:29:52:INFO] [0214/222952.634342:WARNING:embedded_test_server.cc(237)] Request not handled. Returning 404: /favicon.ico [10:29:56:INFO] Test Case '-[ContextMenuTestCase testContextMenuSelectedTextCallout]' passed (6.521 seconds). [10:29:56:INFO] Test Case '-[ContextMenuTestCase testDismissContextMenu]' started. [10:29:58:INFO] [0214/222958.956160:WARNING:embedded_test_server.cc(237)] Request not handled. Returning 404: /favicon.ico [10:30:08:INFO] Test Case '-[ContextMenuTestCase testDismissContextMenu]' passed (11.493 seconds). [10:30:08:INFO] Test Case '-[ContextMenuTestCase testOpenImageInCurrentTabFromContextMenu]' started. [10:30:10:INFO] [0214/223010.444312:WARNING:embedded_test_server.cc(237)] Request not handled. Returning 404: /favicon.ico [10:30:16:INFO] Test Case '-[ContextMenuTestCase testOpenImageInCurrentTabFromContextMenu]' passed (8.428 seconds). [10:30:16:INFO] Test Case '-[ContextMenuTestCase testOpenImageInNewTabFromContextMenu]' started. [10:30:18:INFO] [0214/223018.833662:WARNING:embedded_test_server.cc(237)] Request not handled. Returning 404: /favicon.ico [10:30:26:INFO] Test Case '-[ContextMenuTestCase testOpenImageInNewTabFromContextMenu]' passed (9.770 seconds). [10:30:26:INFO] Test Suite 'ContextMenuTestCase' passed at 2019-02-14 22:30:26.689. [10:30:26:INFO] Executed 9 tests, with 0 failures (0 unexpected) in 83.157 (83.170) seconds [10:30:26:INFO] Test Suite 'CookiesTestCase' started at 2019-02-14 22:30:26.690 [10:30:26:INFO] [0214/223026.691550:WARNING:loopback_server.cc(723)] Loopback sync persistent state file does not exist. [10:30:26:INFO] Test Case '-[CookiesTestCase testClearIncognitoFromIncognito]' started. [10:30:28:INFO] [0214/223028.686994:WARNING:embedded_test_server.cc(237)] Request not handled. Returning 404: /favicon.ico [10:30:44:INFO] Test Case '-[CookiesTestCase testClearIncognitoFromIncognito]' passed (17.517 seconds). [10:30:44:INFO] Test Case '-[CookiesTestCase testClearIncognitoFromMain]' started. [10:30:58:INFO] Test Case '-[CookiesTestCase testClearIncognitoFromMain]' passed (14.529 seconds). [10:30:58:INFO] Test Case '-[CookiesTestCase testShareCookiesBetweenTabs]' started. [10:31:07:INFO] Test Case '-[CookiesTestCase testShareCookiesBetweenTabs]' passed (8.805 seconds). [10:31:07:INFO] Test Case '-[CookiesTestCase testSwitchToIncognito]' started. [10:31:21:INFO] Test Case '-[CookiesTestCase testSwitchToIncognito]' passed (13.470 seconds). [10:31:21:INFO] Test Case '-[CookiesTestCase testSwitchToMain]' started. [10:31:35:INFO] Test Case '-[CookiesTestCase testSwitchToMain]' passed (14.505 seconds). [10:31:35:INFO] Test Suite 'CookiesTestCase' passed at 2019-02-14 22:31:35.523. [10:31:35:INFO] Executed 5 tests, with 0 failures (0 unexpected) in 68.826 (68.833) seconds [10:31:35:INFO] Test Suite 'CredentialManagerEGTest' started at 2019-02-14 22:31:35.524 [10:31:35:INFO] [0214/223135.524938:WARNING:loopback_server.cc(723)] Loopback sync persistent state file does not exist. [10:31:35:INFO] Test Case '-[CredentialManagerEGTest testNotificationAppearsOnAutoSignIn]' started. [10:31:37:INFO] 2019-02-14 22:31:37.129 ios_chrome_integration_egtests[6714:76941] -- Earl Grey Test Disabled -- Fails on iOS 11.0. [10:31:39:INFO] Test Case '-[CredentialManagerEGTest testNotificationAppearsOnAutoSignIn]' passed (3.556 seconds). [10:31:39:INFO] Test Case '-[CredentialManagerEGTest testNotificationAppearsWhenTabIsActive]' started. [10:31:40:INFO] 2019-02-14 22:31:40.927 ios_chrome_integration_egtests[6714:76941] -- Earl Grey Test Disabled -- Fails on iOS 11.0. [10:31:42:INFO] Test Case '-[CredentialManagerEGTest testNotificationAppearsWhenTabIsActive]' passed (3.788 seconds). [10:31:42:INFO] Test Suite 'CredentialManagerEGTest' passed at 2019-02-14 22:31:42.875. [10:31:42:INFO] Executed 2 tests, with 0 failures (0 unexpected) in 7.343 (7.351) seconds [10:31:42:INFO] Test Suite 'FeatureEngagementTestCase' started at 2019-02-14 22:31:42.876 [10:31:42:INFO] [0214/223142.877118:WARNING:loopback_server.cc(723)] Loopback sync persistent state file does not exist. [10:31:42:INFO] Test Case '-[FeatureEngagementTestCase testBadgedReadingListFeatureReadingListAlreadyUsed]' started. [10:31:52:INFO] Test Case '-[FeatureEngagementTestCase testBadgedReadingListFeatureReadingListAlreadyUsed]' passed (10.000 seconds). [10:31:52:INFO] Test Case '-[FeatureEngagementTestCase testBadgedReadingListFeatureShouldShow]' started. [10:32:03:INFO] Test Case '-[FeatureEngagementTestCase testBadgedReadingListFeatureShouldShow]' passed (10.191 seconds). [10:32:03:INFO] Test Case '-[FeatureEngagementTestCase testBadgedReadingListFeatureTooFewChromeOpens]' started. [10:32:09:INFO] Test Case '-[FeatureEngagementTestCase testBadgedReadingListFeatureTooFewChromeOpens]' passed (6.080 seconds). [10:32:09:INFO] Test Case '-[FeatureEngagementTestCase testBottomToolbarAppear]' started. [10:32:18:INFO] Test Case '-[FeatureEngagementTestCase testBottomToolbarAppear]' passed (9.556 seconds). [10:32:18:INFO] Test Case '-[FeatureEngagementTestCase testBottomToolbarDontAppearOnNonSplitToolbar]' started. [10:32:22:INFO] Test Case '-[FeatureEngagementTestCase testBottomToolbarDontAppearOnNonSplitToolbar]' passed (3.734 seconds). [10:32:22:INFO] Test Case '-[FeatureEngagementTestCase testLongPressTipAppearAfterBottomToolbar]' started. [10:32:45:INFO] Test Case '-[FeatureEngagementTestCase testLongPressTipAppearAfterBottomToolbar]' passed (22.978 seconds). [10:32:45:INFO] Test Case '-[FeatureEngagementTestCase testNewTabTipPromoDoesNotAppearOnNTP]' started. [10:32:53:INFO] Test Case '-[FeatureEngagementTestCase testNewTabTipPromoDoesNotAppearOnNTP]' passed (8.257 seconds). [10:32:53:INFO] Test Case '-[FeatureEngagementTestCase testNewTabTipPromoShouldShow]' started. [10:33:03:INFO] Test Case '-[FeatureEngagementTestCase testNewTabTipPromoShouldShow]' passed (10.035 seconds). [10:33:03:INFO] Test Suite 'FeatureEngagementTestCase' passed at 2019-02-14 22:33:03.717. [10:33:03:INFO] Executed 8 tests, with 0 failures (0 unexpected) in 80.831 (80.841) seconds [10:33:03:INFO] Test Suite 'FormInputTestCase' started at 2019-02-14 22:33:03.718 [10:33:03:INFO] [0214/223303.719221:WARNING:loopback_server.cc(723)] Loopback sync persistent state file does not exist. [10:33:03:INFO] Test Case '-[FormInputTestCase testCloseKeyboardWhenNotVisible]' started. [10:33:07:INFO] Test Case '-[FormInputTestCase testCloseKeyboardWhenNotVisible]' passed (3.812 seconds). [10:33:07:INFO] Test Case '-[FormInputTestCase testFindDefaultFormAssistControls]' started. [10:33:09:INFO] [0214/223309.394799:WARNING:embedded_test_server.cc(237)] Request not handled. Returning 404: /favicon.ico [10:33:11:INFO] 2019-02-14 22:33:11.150 ios_chrome_integration_egtests[6714:76941] API error: <_UIKBCompatInputView: 0x7faae1cbd280; frame = (0 0; 0 0); layer = <CALayer: 0x600001581a60>> returned 0 width, assuming UIViewNoIntrinsicMetric [10:33:11:INFO] 2019-02-14 22:33:11.151 ios_chrome_integration_egtests[6714:76941] API error: <_UIKBCompatInputView: 0x7faae1cbd280; frame = (0 0; 0 0); layer = <CALayer: 0x600001581a60>> returned 0 width, assuming UIViewNoIntrinsicMetric [10:33:16:INFO] Test Case '-[FormInputTestCase testFindDefaultFormAssistControls]' passed (8.996 seconds). [10:33:16:INFO] Test Suite 'FormInputTestCase' passed at 2019-02-14 22:33:16.531. [10:33:16:INFO] Executed 2 tests, with 0 failures (0 unexpected) in 12.808 (12.813) seconds [10:33:16:INFO] Test Suite 'HandoffManagerTestCase' started at 2019-02-14 22:33:16.532 [10:33:16:INFO] [0214/223316.533071:WARNING:loopback_server.cc(723)] Loopback sync persistent state file does not exist. [10:33:16:INFO] Test Case '-[HandoffManagerTestCase testMultipleSwitchingTabs]' started. [10:33:18:INFO] [0214/223318.496650:WARNING:embedded_test_server.cc(237)] Request not handled. Returning 404: /favicon.ico [10:33:27:INFO] Test Case '-[HandoffManagerTestCase testMultipleSwitchingTabs]' passed (11.104 seconds). [10:33:27:INFO] Test Case '-[HandoffManagerTestCase testNewTabPageEmptyURL]' started. [10:33:31:INFO] Test Case '-[HandoffManagerTestCase testNewTabPageEmptyURL]' passed (3.805 seconds). [10:33:31:INFO] Test Case '-[HandoffManagerTestCase testSwitchBetweenNormalAndIncognitoTabs]' started. [10:33:41:INFO] Test Case '-[HandoffManagerTestCase testSwitchBetweenNormalAndIncognitoTabs]' passed (10.513 seconds). [10:33:41:INFO] Test Case '-[HandoffManagerTestCase testTypicalURL]' started. [10:33:46:INFO] Test Case '-[HandoffManagerTestCase testTypicalURL]' passed (4.872 seconds). [10:33:46:INFO] Test Case '-[HandoffManagerTestCase testTypicalURLInNewIncognitoTab]' started. [10:33:54:INFO] Test Case '-[HandoffManagerTestCase testTypicalURLInNewIncognitoTab]' passed (7.775 seconds). [10:33:54:INFO] Test Case '-[HandoffManagerTestCase testTypicalURLInNewTab]' started. [10:34:00:INFO] Test Case '-[HandoffManagerTestCase testTypicalURLInNewTab]' passed (6.245 seconds). [10:34:00:INFO] Test Suite 'HandoffManagerTestCase' passed at 2019-02-14 22:34:00.855. [10:34:00:INFO] Executed 6 tests, with 0 failures (0 unexpected) in 44.315 (44.323) seconds [10:34:00:INFO] Test Suite 'NTPTilesTest' started at 2019-02-14 22:34:00.856 [10:34:00:INFO] [0214/223400.856910:WARNING:loopback_server.cc(723)] Loopback sync persistent state file does not exist. [10:34:00:INFO] Test Case '-[NTPTilesTest testTopSitesTileAfterLoadURL]' started. [10:34:04:INFO] [0214/223404.738949:WARNING:embedded_test_server.cc(237)] Request not handled. Returning 404: /favicon.ico [10:34:10:INFO] Test Case '-[NTPTilesTest testTopSitesTileAfterLoadURL]' passed (9.751 seconds). [10:34:10:INFO] Test Case '-[NTPTilesTest testTopSitesTileAfterRedirect]' started. [10:34:35:INFO] Test Case '-[NTPTilesTest testTopSitesTileAfterRedirect]' passed (24.489 seconds). [10:34:35:INFO] Test Suite 'NTPTilesTest' passed at 2019-02-14 22:34:35.104. [10:34:35:INFO] Executed 2 tests, with 0 failures (0 unexpected) in 34.240 (34.249) seconds [10:34:35:INFO] Test Suite 'PrerenderTestCase' started at 2019-02-14 22:34:35.105 [10:34:35:INFO] [0214/223435.106451:WARNING:loopback_server.cc(723)] Loopback sync persistent state file does not exist. [10:34:35:INFO] Test Case '-[PrerenderTestCase testTapPrerenderSuggestions]' started. [10:34:37:INFO] [0214/223437.200805:WARNING:embedded_test_server.cc(237)] Request not handled. Returning 404: /favicon.ico [10:34:44:INFO] 2019-02-14 22:34:44.863 ios_chrome_integration_egtests[6714:76941] Attempting to type key 1. [10:34:44:INFO] 2019-02-14 22:34:44.966 ios_chrome_integration_egtests[6714:76941] Tapping on key: more, numbers. [10:34:45:INFO] 2019-02-14 22:34:45.050 ios_chrome_integration_egtests[6714:76941] Tapping on key: 1. [10:34:45:INFO] 2019-02-14 22:34:45.158 ios_chrome_integration_egtests[6714:76941] Attempting to type key 2. [10:34:45:INFO] 2019-02-14 22:34:45.158 ios_chrome_integration_egtests[6714:76941] Tapping on key: 2. [10:34:45:INFO] 2019-02-14 22:34:45.234 ios_chrome_integration_egtests[6714:76941] Attempting to type key 7. [10:34:45:INFO] 2019-02-14 22:34:45.235 ios_chrome_integration_egtests[6714:76941] Tapping on key: 7. [10:34:45:INFO] 2019-02-14 22:34:45.304 ios_chrome_integration_egtests[6714:76941] Attempting to type key .. [10:34:45:INFO] 2019-02-14 22:34:45.306 ios_chrome_integration_egtests[6714:76941] Tapping on key: .. [10:34:45:INFO] 2019-02-14 22:34:45.370 ios_chrome_integration_egtests[6714:76941] Attempting to type key 0. [10:34:45:INFO] 2019-02-14 22:34:45.371 ios_chrome_integration_egtests[6714:76941] Tapping on key: 0. [10:34:45:INFO] 2019-02-14 22:34:45.437 ios_chrome_integration_egtests[6714:76941] Attempting to type key .. [10:34:45:INFO] 2019-02-14 22:34:45.439 ios_chrome_integration_egtests[6714:76941] Tapping on key: .. [10:34:45:INFO] 2019-02-14 22:34:45.562 ios_chrome_integration_egtests[6714:76941] Attempting to type key 0. [10:34:45:INFO] 2019-02-14 22:34:45.565 ios_chrome_integration_egtests[6714:76941] Tapping on key: 0. [10:34:45:INFO] 2019-02-14 22:34:45.674 ios_chrome_integration_egtests[6714:76941] Attempting to type key .. [10:34:45:INFO] 2019-02-14 22:34:45.676 ios_chrome_integration_egtests[6714:76941] Tapping on key: .. [10:34:45:INFO] 2019-02-14 22:34:45.788 ios_chrome_integration_egtests[6714:76941] Attempting to type key 1. [10:34:45:INFO] 2019-02-14 22:34:45.789 ios_chrome_integration_egtests[6714:76941] Tapping on key: 1. [10:34:45:INFO] 2019-02-14 22:34:45.897 ios_chrome_integration_egtests[6714:76941] Attempting to type key :. [10:34:45:INFO] 2019-02-14 22:34:45.898 ios_chrome_integration_egtests[6714:76941] Tapping on key: :. [10:34:46:INFO] 2019-02-14 22:34:46.000 ios_chrome_integration_egtests[6714:76941] Attempting to type key 5. [10:34:46:INFO] 2019-02-14 22:34:46.001 ios_chrome_integration_egtests[6714:76941] Tapping on key: 5. [10:34:46:INFO] 2019-02-14 22:34:46.095 ios_chrome_integration_egtests[6714:76941] Attempting to type key 1. [10:34:46:INFO] 2019-02-14 22:34:46.095 ios_chrome_integration_egtests[6714:76941] Tapping on key: 1. [10:34:46:INFO] 2019-02-14 22:34:46.166 ios_chrome_integration_egtests[6714:76941] Attempting to type key 2. [10:34:46:INFO] 2019-02-14 22:34:46.166 ios_chrome_integration_egtests[6714:76941] Tapping on key: 2. [10:34:46:INFO] 2019-02-14 22:34:46.240 ios_chrome_integration_egtests[6714:76941] Attempting to type key 9. [10:34:46:INFO] 2019-02-14 22:34:46.241 ios_chrome_integration_egtests[6714:76941] Tapping on key: 9. [10:34:46:INFO] 2019-02-14 22:34:46.302 ios_chrome_integration_egtests[6714:76941] Attempting to type key 6. [10:34:46:INFO] 2019-02-14 22:34:46.302 ios_chrome_integration_egtests[6714:76941] Tapping on key: 6. [10:34:46:INFO] 2019-02-14 22:34:46.362 ios_chrome_integration_egtests[6714:76941] Attempting to type key /. [10:34:46:INFO] 2019-02-14 22:34:46.363 ios_chrome_integration_egtests[6714:76941] Tapping on key: /. [10:34:46:INFO] 2019-02-14 22:34:46.424 ios_chrome_integration_egtests[6714:76941] Attempting to type key t. [10:34:46:INFO] 2019-02-14 22:34:46.730 ios_chrome_integration_egtests[6714:76941] Tapping on key: more, letters. [10:34:46:INFO] 2019-02-14 22:34:46.783 ios_chrome_integration_egtests[6714:76941] Tapping on key: t. [10:34:46:INFO] 2019-02-14 22:34:46.840 ios_chrome_integration_egtests[6714:76941] Attempting to type key e. [10:34:46:INFO] 2019-02-14 22:34:46.841 ios_chrome_integration_egtests[6714:76941] Tapping on key: e. [10:34:46:INFO] 2019-02-14 22:34:46.903 ios_chrome_integration_egtests[6714:76941] Attempting to type key s. [10:34:46:INFO] 2019-02-14 22:34:46.904 ios_chrome_integration_egtests[6714:76941] Tapping on key: s. [10:34:46:INFO] 2019-02-14 22:34:46.966 ios_chrome_integration_egtests[6714:76941] Attempting to type key t. [10:34:46:INFO] 2019-02-14 22:34:46.967 ios_chrome_integration_egtests[6714:76941] Tapping on key: t. [10:34:47:INFO] 2019-02-14 22:34:47.028 ios_chrome_integration_egtests[6714:76941] Attempting to type key -. [10:34:47:INFO] 2019-02-14 22:34:47.129 ios_chrome_integration_egtests[6714:76941] Tapping on key: more, numbers. [10:34:47:INFO] 2019-02-14 22:34:47.181 ios_chrome_integration_egtests[6714:76941] Tapping on key: -. [10:34:47:INFO] 2019-02-14 22:34:47.244 ios_chrome_integration_egtests[6714:76941] Attempting to type key p. [10:34:47:INFO] 2019-02-14 22:34:47.551 ios_chrome_integration_egtests[6714:76941] Tapping on key: more, letters. [10:34:47:INFO] 2019-02-14 22:34:47.604 ios_chrome_integration_egtests[6714:76941] Tapping on key: p. [10:34:47:INFO] 2019-02-14 22:34:47.661 ios_chrome_integration_egtests[6714:76941] Attempting to type key a. [10:34:47:INFO] 2019-02-14 22:34:47.662 ios_chrome_integration_egtests[6714:76941] Tapping on key: a. [10:34:47:INFO] 2019-02-14 22:34:47.723 ios_chrome_integration_egtests[6714:76941] Attempting to type key g. [10:34:47:INFO] 2019-02-14 22:34:47.725 ios_chrome_integration_egtests[6714:76941] Tapping on key: g. [10:34:47:INFO] 2019-02-14 22:34:47.786 ios_chrome_integration_egtests[6714:76941] Attempting to type key e. [10:34:47:INFO] 2019-02-14 22:34:47.787 ios_chrome_integration_egtests[6714:76941] Tapping on key: e. [10:34:47:INFO] 2019-02-14 22:34:47.848 ios_chrome_integration_egtests[6714:76941] Attempting to type key .. [10:34:47:INFO] 2019-02-14 22:34:47.850 ios_chrome_integration_egtests[6714:76941] Tapping on key: .. [10:34:47:INFO] 2019-02-14 22:34:47.912 ios_chrome_integration_egtests[6714:76941] Attempting to type key h. [10:34:47:INFO] 2019-02-14 22:34:47.913 ios_chrome_integration_egtests[6714:76941] Tapping on key: h. [10:34:47:INFO] 2019-02-14 22:34:47.993 ios_chrome_integration_egtests[6714:76941] Attempting to type key t. [10:34:47:INFO] 2019-02-14 22:34:47.995 ios_chrome_integration_egtests[6714:76941] Tapping on key: t. [10:34:48:INFO] 2019-02-14 22:34:48.052 ios_chrome_integration_egtests[6714:76941] Attempting to type key m. [10:34:48:INFO] 2019-02-14 22:34:48.053 ios_chrome_integration_egtests[6714:76941] Tapping on key: m. [10:34:48:INFO] 2019-02-14 22:34:48.115 ios_chrome_integration_egtests[6714:76941] Attempting to type key l. [10:34:48:INFO] 2019-02-14 22:34:48.115 ios_chrome_integration_egtests[6714:76941] Tapping on key: l. [10:34:48:INFO] 2019-02-14 22:34:48.176 ios_chrome_integration_egtests[6714:76941] Attempting to type key [10:34:48:INFO] . [10:34:48:INFO] 2019-02-14 22:34:48.177 ios_chrome_integration_egtests[6714:76941] Tapping on key: Go. [10:34:54:INFO] 2019-02-14 22:34:54.946 ios_chrome_integration_egtests[6714:76941] Attempting to type key 1. [10:34:55:INFO] 2019-02-14 22:34:55.049 ios_chrome_integration_egtests[6714:76941] Tapping on key: more, numbers. [10:34:55:INFO] 2019-02-14 22:34:55.101 ios_chrome_integration_egtests[6714:76941] Tapping on key: 1. [10:34:55:INFO] 2019-02-14 22:34:55.180 ios_chrome_integration_egtests[6714:76941] Attempting to type key 2. [10:34:55:INFO] 2019-02-14 22:34:55.181 ios_chrome_integration_egtests[6714:76941] Tapping on key: 2. [10:34:55:INFO] 2019-02-14 22:34:55.294 ios_chrome_integration_egtests[6714:76941] Attempting to type key 7. [10:34:55:INFO] 2019-02-14 22:34:55.295 ios_chrome_integration_egtests[6714:76941] Tapping on key: 7. [10:34:55:INFO] 2019-02-14 22:34:55.419 ios_chrome_integration_egtests[6714:76941] Attempting to type key .. [10:34:55:INFO] 2019-02-14 22:34:55.421 ios_chrome_integration_egtests[6714:76941] Tapping on key: .. [10:34:55:INFO] 2019-02-14 22:34:55.544 ios_chrome_integration_egtests[6714:76941] Attempting to type key 0. [10:34:55:INFO] 2019-02-14 22:34:55.545 ios_chrome_integration_egtests[6714:76941] Tapping on key: 0. [10:34:55:INFO] 2019-02-14 22:34:55.677 ios_chrome_integration_egtests[6714:76941] Attempting to type key .. [10:34:55:INFO] 2019-02-14 22:34:55.679 ios_chrome_integration_egtests[6714:76941] Tapping on key: .. [10:34:55:INFO] 2019-02-14 22:34:55.802 ios_chrome_integration_egtests[6714:76941] Attempting to type key 0. [10:34:55:INFO] 2019-02-14 22:34:55.803 ios_chrome_integration_egtests[6714:76941] Tapping on key: 0. [10:34:55:INFO] 2019-02-14 22:34:55.920 ios_chrome_integration_egtests[6714:76941] Attempting to type key .. [10:34:55:INFO] 2019-02-14 22:34:55.923 ios_chrome_integration_egtests[6714:76941] Tapping on key: .. [10:34:56:INFO] 2019-02-14 22:34:56.018 ios_chrome_integration_egtests[6714:76941] Attempting to type key 1. [10:34:56:INFO] 2019-02-14 22:34:56.020 ios_chrome_integration_egtests[6714:76941] Tapping on key: 1. [10:34:56:INFO] 2019-02-14 22:34:56.106 ios_chrome_integration_egtests[6714:76941] Attempting to type key :. [10:34:56:INFO] 2019-02-14 22:34:56.107 ios_chrome_integration_egtests[6714:76941] Tapping on key: :. [10:34:56:INFO] 2019-02-14 22:34:56.193 ios_chrome_integration_egtests[6714:76941] Attempting to type key 5. [10:34:56:INFO] 2019-02-14 22:34:56.194 ios_chrome_integration_egtests[6714:76941] Tapping on key: 5. [10:34:56:INFO] 2019-02-14 22:34:56.270 ios_chrome_integration_egtests[6714:76941] Attempting to type key 1. [10:34:56:INFO] 2019-02-14 22:34:56.271 ios_chrome_integration_egtests[6714:76941] Tapping on key: 1. [10:34:56:INFO] 2019-02-14 22:34:56.333 ios_chrome_integration_egtests[6714:76941] Attempting to type key 2. [10:34:56:INFO] 2019-02-14 22:34:56.334 ios_chrome_integration_egtests[6714:76941] Tapping on key: 2. [10:34:56:INFO] 2019-02-14 22:34:56.397 ios_chrome_integration_egtests[6714:76941] Attempting to type key 9. [10:34:56:INFO] 2019-02-14 22:34:56.398 ios_chrome_integration_egtests[6714:76941] Tapping on key: 9. [10:34:56:INFO] 2019-02-14 22:34:56.462 ios_chrome_integration_egtests[6714:76941] Attempting to type key 6. [10:34:56:INFO] 2019-02-14 22:34:56.463 ios_chrome_integration_egtests[6714:76941] Tapping on key: 6. [10:34:56:INFO] 2019-02-14 22:34:56.524 ios_chrome_integration_egtests[6714:76941] Attempting to type key /. [10:34:56:INFO] 2019-02-14 22:34:56.525 ios_chrome_integration_egtests[6714:76941] Tapping on key: /. [10:34:56:INFO] 2019-02-14 22:34:56.590 ios_chrome_integration_egtests[6714:76941] Attempting to type key t. [10:34:56:INFO] 2019-02-14 22:34:56.897 ios_chrome_integration_egtests[6714:76941] Tapping on key: more, letters. [10:34:56:INFO] 2019-02-14 22:34:56.950 ios_chrome_integration_egtests[6714:76941] Tapping on key: t. [10:34:57:INFO] 2019-02-14 22:34:57.009 ios_chrome_integration_egtests[6714:76941] Attempting to type key e. [10:34:57:INFO] 2019-02-14 22:34:57.009 ios_chrome_integration_egtests[6714:76941] Tapping on key: e. [10:34:57:INFO] 2019-02-14 22:34:57.075 ios_chrome_integration_egtests[6714:76941] Attempting to type key s. [10:34:57:INFO] 2019-02-14 22:34:57.076 ios_chrome_integration_egtests[6714:76941] Tapping on key: s. [10:34:57:INFO] 2019-02-14 22:34:57.138 ios_chrome_integration_egtests[6714:76941] Attempting to type key t. [10:34:57:INFO] 2019-02-14 22:34:57.139 ios_chrome_integration_egtests[6714:76941] Tapping on key: t. [10:34:57:INFO] 2019-02-14 22:34:57.200 ios_chrome_integration_egtests[6714:76941] Attempting to type key -. [10:34:57:INFO] 2019-02-14 22:34:57.303 ios_chrome_integration_egtests[6714:76941] Tapping on key: more, numbers. [10:34:57:INFO] 2019-02-14 22:34:57.355 ios_chrome_integration_egtests[6714:76941] Tapping on key: -. [10:34:57:INFO] 2019-02-14 22:34:57.418 ios_chrome_integration_egtests[6714:76941] Attempting to type key p. [10:34:57:INFO] 2019-02-14 22:34:57.725 ios_chrome_integration_egtests[6714:76941] Tapping on key: more, letters. [10:34:57:INFO] 2019-02-14 22:34:57.779 ios_chrome_integration_egtests[6714:76941] Tapping on key: p. [10:34:57:INFO] 2019-02-14 22:34:57.838 ios_chrome_integration_egtests[6714:76941] Attempting to type key a. [10:34:57:INFO] 2019-02-14 22:34:57.839 ios_chrome_integration_egtests[6714:76941] Tapping on key: a. [10:34:57:INFO] 2019-02-14 22:34:57.901 ios_chrome_integration_egtests[6714:76941] Attempting to type key g. [10:34:57:INFO] 2019-02-14 22:34:57.902 ios_chrome_integration_egtests[6714:76941] Tapping on key: g. [10:35:02:INFO] Test Case '-[PrerenderTestCase testTapPrerenderSuggestions]' passed (27.590 seconds). [10:35:02:INFO] Test Suite 'PrerenderTestCase' passed at 2019-02-14 22:35:02.703. [10:35:02:INFO] Executed 1 test, with 0 failures (0 unexpected) in 27.590 (27.598) seconds [10:35:02:INFO] Test Suite 'TabUsageRecorderTestCase' started at 2019-02-14 22:35:02.703 [10:35:02:INFO] [0214/223502.704735:WARNING:loopback_server.cc(723)] Loopback sync persistent state file does not exist. [10:35:02:INFO] Test Case '-[TabUsageRecorderTestCase testBackgroundingReloadCount]' started. [10:35:08:INFO] [0214/223508.471467:WARNING:embedded_test_server.cc(237)] Request not handled. Returning 404: /favicon.ico [10:35:25:INFO] Test Case '-[TabUsageRecorderTestCase testBackgroundingReloadCount]' passed (22.432 seconds). [10:35:25:INFO] Test Case '-[TabUsageRecorderTestCase testColdLaunchReloadCount]' started. [10:35:51:INFO] Test Case '-[TabUsageRecorderTestCase testColdLaunchReloadCount]' passed (26.554 seconds). [10:35:51:INFO] Test Case '-[TabUsageRecorderTestCase testEvictedTabReloadBackgrounded]' started. [10:36:07:INFO] Test Case '-[TabUsageRecorderTestCase testEvictedTabReloadBackgrounded]' passed (16.025 seconds). [10:36:07:INFO] Test Case '-[TabUsageRecorderTestCase testEvictedTabReloadSettingsAndBack]' started. [10:36:36:INFO] Test Case '-[TabUsageRecorderTestCase testEvictedTabReloadSettingsAndBack]' passed (28.763 seconds). [10:36:36:INFO] Test Case '-[TabUsageRecorderTestCase testEvictedTabReloadSuccess]' started. [10:36:54:INFO] Test Case '-[TabUsageRecorderTestCase testEvictedTabReloadSuccess]' passed (18.128 seconds). [10:36:54:INFO] Test Case '-[TabUsageRecorderTestCase testEvictedTabReloadSwitchToNTP]' started. [10:37:11:INFO] Test Case '-[TabUsageRecorderTestCase testEvictedTabReloadSwitchToNTP]' passed (16.928 seconds). [10:37:11:INFO] Test Case '-[TabUsageRecorderTestCase testEvictedTabSlowReload]' started. [10:37:29:INFO] Test Case '-[TabUsageRecorderTestCase testEvictedTabSlowReload]' passed (17.671 seconds). [10:37:29:INFO] Test Case '-[TabUsageRecorderTestCase testLinkClickNavigation]' started. [10:37:47:INFO] Test Case '-[TabUsageRecorderTestCase testLinkClickNavigation]' passed (17.967 seconds). [10:37:47:INFO] Test Case '-[TabUsageRecorderTestCase testLiveTabReloadBackgrounded]' started. [10:37:53:INFO] Test Case '-[TabUsageRecorderTestCase testLiveTabReloadBackgrounded]' passed (6.416 seconds). [10:37:53:INFO] Test Case '-[TabUsageRecorderTestCase testOpenFromApp]' started. [10:38:00:INFO] Test Case '-[TabUsageRecorderTestCase testOpenFromApp]' passed (7.240 seconds). [10:38:00:INFO] Test Case '-[TabUsageRecorderTestCase testOpenLinkInNewTab]' started. [10:38:11:INFO] Test Case '-[TabUsageRecorderTestCase testOpenLinkInNewTab]' passed (10.227 seconds). [10:38:11:INFO] Test Case '-[TabUsageRecorderTestCase testPageLoadCountBeforeEvictedTab]' started. [10:38:47:INFO] Test Case '-[TabUsageRecorderTestCase testPageLoadCountBeforeEvictedTab]' passed (36.885 seconds). [10:38:47:INFO] Test Case '-[TabUsageRecorderTestCase testPageRedirect]' started. [10:39:06:INFO] Test Case '-[TabUsageRecorderTestCase testPageRedirect]' passed (18.075 seconds). [10:39:06:INFO] Test Case '-[TabUsageRecorderTestCase testTabDeletion]' started. [10:39:21:INFO] Test Case '-[TabUsageRecorderTestCase testTabDeletion]' passed (15.310 seconds). [10:39:21:INFO] Test Case '-[TabUsageRecorderTestCase testTabSwitchRecorder]' started. [10:39:42:INFO] Test Case '-[TabUsageRecorderTestCase testTabSwitchRecorder]' passed (21.165 seconds). [10:39:42:INFO] Test Suite 'TabUsageRecorderTestCase' passed at 2019-02-14 22:39:42.502. [10:39:42:INFO] Executed 15 tests, with 0 failures (0 unexpected) in 279.786 (279.799) seconds [10:39:42:INFO] Test Suite 'UKMTestCase' started at 2019-02-14 22:39:42.503 [10:39:42:INFO] [0214/223942.504278:WARNING:loopback_server.cc(723)] Loopback sync persistent state file does not exist. [10:39:42:INFO] Test Case '-[UKMTestCase testConsentAddedButNoSync]' started. [10:39:49:INFO] 2019-02-14 22:39:49.872 ios_chrome_integration_egtests[6714:76941] Button touch target does not meet minimum size guidlines of (48, 48). Button: <MDCFlatButton: 0x7faadde71d90; baseClass = UIButton; frame = (16 726; 86 36); opaque = NO; layer = <MDCShapedShadowLayer: 0x600003a93f00>>, Touch Target: {86, 36} [10:39:51:INFO] [0214/223951.317664:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag. [10:39:51:INFO] [0214/223951.341044:ERROR:account_tracker.cc(264)] OnOAuthError [10:39:51:INFO] [0214/223951.341150:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID [10:39:51:INFO] [0214/223951.352791:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag. [10:39:52:INFO] [0214/223952.408568:INFO:profile_sync_service.cc(1926)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now [10:40:04:INFO] [0214/224004.107677:INFO:signin_manager.cc(124)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1 [10:40:14:INFO] [0214/224014.860615:ERROR:account_tracker.cc(264)] OnOAuthError [10:40:14:INFO] [0214/224014.860722:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID [10:40:15:INFO] [0214/224015.961102:INFO:profile_sync_service.cc(1926)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now [10:40:16:INFO] [0214/224016.073577:WARNING:session_sync_bridge.cc(237)] Dropping modification to local session. [10:40:27:INFO] [0214/224027.645719:INFO:signin_manager.cc(124)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1 [10:40:27:INFO] [0214/224027.860134:WARNING:gaia_auth_fetcher.cc(898)] ClientLogin failed with [10:40:27:INFO] [0214/224027.860279:WARNING:gaia_auth_fetcher.cc(921)] Incomprehensible response from Google Accounts servers. [10:40:31:INFO] [0214/224031.587158:WARNING:loopback_server.cc(723)] Loopback sync persistent state file does not exist. [10:40:33:INFO] Test Case '-[UKMTestCase testConsentAddedButNoSync]' passed (51.042 seconds). [10:40:33:INFO] Test Case '-[UKMTestCase testIncognitoPlusRegular]' started. [10:40:42:INFO] [0214/224042.573294:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag. [10:40:42:INFO] [0214/224042.575443:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag. [10:40:42:INFO] [0214/224042.580892:ERROR:account_tracker.cc(264)] OnOAuthError [10:40:42:INFO] [0214/224042.580994:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID [10:40:43:INFO] [0214/224043.677049:INFO:profile_sync_service.cc(1926)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now [10:41:01:INFO] [0214/224101.119156:INFO:signin_manager.cc(124)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1 [10:41:05:INFO] [0214/224105.084477:WARNING:loopback_server.cc(723)] Loopback sync persistent state file does not exist. [10:41:07:INFO] Test Case '-[UKMTestCase testIncognitoPlusRegular]' passed (33.493 seconds). [10:41:07:INFO] Test Case '-[UKMTestCase testMetricsConsent]' started. [10:41:16:INFO] [0214/224116.081729:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag. [10:41:16:INFO] [0214/224116.083983:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag. [10:41:16:INFO] [0214/224116.087169:ERROR:account_tracker.cc(264)] OnOAuthError [10:41:16:INFO] [0214/224116.087280:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID [10:41:17:INFO] [0214/224117.186125:INFO:profile_sync_service.cc(1926)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now [10:41:28:INFO] [0214/224128.884016:INFO:signin_manager.cc(124)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1 [10:41:29:INFO] [0214/224129.102309:WARNING:gaia_auth_fetcher.cc(898)] ClientLogin failed with [10:41:29:INFO] [0214/224129.102421:WARNING:gaia_auth_fetcher.cc(921)] Incomprehensible response from Google Accounts servers. [10:41:32:INFO] [0214/224132.836148:WARNING:loopback_server.cc(723)] Loopback sync persistent state file does not exist. [10:41:34:INFO] Test Case '-[UKMTestCase testMetricsConsent]' passed (27.749 seconds). [10:41:34:INFO] Test Case '-[UKMTestCase testRegularPlusIncognito]' started. [10:41:43:INFO] [0214/224143.747438:ERROR:account_tracker.cc(264)] OnOAuthError [10:41:43:INFO] [0214/224143.747553:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID [10:41:43:INFO] [0214/224143.752093:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag. [10:41:43:INFO] [0214/224143.754603:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag. [10:41:44:INFO] [0214/224144.844893:INFO:profile_sync_service.cc(1926)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now [10:42:06:INFO] [0214/224206.643305:INFO:signin_manager.cc(124)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1 [10:42:10:INFO] [0214/224210.589884:WARNING:loopback_server.cc(723)] Loopback sync persistent state file does not exist. [10:42:12:INFO] Test Case '-[UKMTestCase testRegularPlusIncognito]' passed (37.787 seconds). [10:42:12:INFO] Test Case '-[UKMTestCase testSecondaryPassphrase]' started. [10:42:21:INFO] [0214/224221.628410:ERROR:account_tracker.cc(264)] OnOAuthError [10:42:21:INFO] [0214/224221.628515:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID [10:42:21:INFO] [0214/224221.628754:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag. [10:42:21:INFO] [0214/224221.633357:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag. [10:42:22:INFO] [0214/224222.727499:INFO:profile_sync_service.cc(1926)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now [10:42:54:INFO] [0214/224254.355491:INFO:signin_manager.cc(124)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1 [10:42:58:INFO] [0214/224258.308223:WARNING:loopback_server.cc(723)] Loopback sync persistent state file does not exist. [10:43:05:INFO] [0214/224305.232015:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag. [10:43:05:INFO] [0214/224305.237385:ERROR:account_tracker.cc(264)] OnOAuthError [10:43:05:INFO] [0214/224305.237517:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID [10:43:05:INFO] [0214/224305.238216:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag. [10:43:06:INFO] [0214/224306.342688:INFO:profile_sync_service.cc(1926)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now [10:43:18:INFO] [0214/224318.049986:INFO:profile_sync_service.cc(1176)] ProfileSyncService::Observe Sync Configure aborted [10:43:18:INFO] [0214/224318.062021:INFO:signin_manager.cc(124)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1 [10:43:22:INFO] [0214/224322.006867:WARNING:loopback_server.cc(723)] Loopback sync persistent state file does not exist. [10:43:23:INFO] Test Case '-[UKMTestCase testSecondaryPassphrase]' passed (71.384 seconds). [10:43:23:INFO] Test Case '-[UKMTestCase testSingleDisableSync]' started. [10:43:33:INFO] [0214/224333.009482:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag. [10:43:33:INFO] [0214/224333.011353:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag. [10:43:33:INFO] [0214/224333.013034:ERROR:account_tracker.cc(264)] OnOAuthError [10:43:33:INFO] [0214/224333.013172:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID [10:43:34:INFO] [0214/224334.123728:INFO:profile_sync_service.cc(1926)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now [10:43:48:INFO] [0214/224348.371152:INFO:profile_sync_service.cc(1926)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now [10:43:49:INFO] [0214/224349.952192:INFO:profile_sync_service.cc(1926)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now [10:43:51:INFO] [0214/224351.519636:INFO:profile_sync_service.cc(1926)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now [10:43:53:INFO] [0214/224353.085119:INFO:profile_sync_service.cc(1926)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now [10:44:03:INFO] [0214/224403.270205:INFO:profile_sync_service.cc(1176)] ProfileSyncService::Observe Sync Configure aborted [10:44:03:INFO] [0214/224403.281068:INFO:signin_manager.cc(124)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1 [10:44:03:INFO] [0214/224403.491854:WARNING:gaia_auth_fetcher.cc(898)] ClientLogin failed with [10:44:03:INFO] [0214/224403.492016:WARNING:gaia_auth_fetcher.cc(921)] Incomprehensible response from Google Accounts servers. [10:44:07:INFO] [0214/224407.238868:WARNING:loopback_server.cc(723)] Loopback sync persistent state file does not exist. [10:44:09:INFO] Test Case '-[UKMTestCase testSingleDisableSync]' passed (45.228 seconds). [10:44:09:INFO] Test Case '-[UKMTestCase testSingleSyncSignout]' started. [10:44:18:INFO] [0214/224418.267549:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag. [10:44:18:INFO] [0214/224418.270198:ERROR:account_tracker.cc(264)] OnOAuthError [10:44:18:INFO] [0214/224418.270118:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag. [10:44:18:INFO] [0214/224418.270329:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID [10:44:19:INFO] [0214/224419.374855:INFO:profile_sync_service.cc(1926)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now [10:44:31:INFO] [0214/224431.107464:INFO:profile_sync_service.cc(1176)] ProfileSyncService::Observe Sync Configure aborted [10:44:31:INFO] [0214/224431.119418:INFO:signin_manager.cc(124)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1 [10:44:42:INFO] [0214/224442.234791:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag. [10:44:42:INFO] [0214/224442.238356:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag. [10:44:42:INFO] [0214/224442.243712:ERROR:account_tracker.cc(264)] OnOAuthError [10:44:42:INFO] [0214/224442.243832:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID [10:44:43:INFO] [0214/224443.347182:INFO:profile_sync_service.cc(1926)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now [10:44:55:INFO] [0214/224455.054082:INFO:profile_sync_service.cc(1176)] ProfileSyncService::Observe Sync Configure aborted [10:44:55:INFO] [0214/224455.066262:INFO:signin_manager.cc(124)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1 [10:44:55:INFO] [0214/224455.282300:WARNING:gaia_auth_fetcher.cc(898)] ClientLogin failed with [10:44:55:INFO] [0214/224455.282418:WARNING:gaia_auth_fetcher.cc(921)] Incomprehensible response from Google Accounts servers. [10:44:59:INFO] [0214/224459.020875:WARNING:loopback_server.cc(723)] Loopback sync persistent state file does not exist. [10:45:00:INFO] Test Case '-[UKMTestCase testSingleSyncSignout]' passed (51.788 seconds). [10:45:00:INFO] Test Suite 'UKMTestCase' passed at 2019-02-14 22:45:00.984. [10:45:00:INFO] Executed 7 tests, with 0 failures (0 unexpected) in 318.471 (318.481) seconds [10:45:00:INFO] Test Suite 'ios_chrome_integration_egtests.app' passed at 2019-02-14 22:45:00.985. [10:45:01:INFO] Executed 58 tests, with 0 failures (0 unexpected) in 961.376 (963.435) seconds [10:45:01:INFO] Test Suite 'All tests' passed at 2019-02-14 22:45:00.986. [10:45:01:INFO] Executed 58 tests, with 0 failures (0 unexpected) in 961.376 (963.438) seconds [10:45:01:INFO] [10:45:01:INFO] [10:45:01:INFO] Test session results and logs: [10:45:01:INFO] /Users/chrome-bot/Library/Developer/Xcode/DerivedData/temporary-auteyumowoxdgmgmtlwickdyinff/Logs/Test/Test-Transient Testing-2019.02.14_22-28-29--0800.xcresult [10:45:01:INFO] [10:45:01:INFO] 2019-02-14 22:45:01.486 xcodebuild[6602:75810] [MT] IDETestOperationsObserverDebug: 992.465 elapsed -- Testing started completed. [10:45:01:INFO] 2019-02-14 22:45:01.486 xcodebuild[6602:75810] [MT] IDETestOperationsObserverDebug: 0.000 sec, +0.000 sec -- start [10:45:01:INFO] 2019-02-14 22:45:01.486 xcodebuild[6602:75810] [MT] IDETestOperationsObserverDebug: 992.465 sec, +992.465 sec -- end [10:45:01:INFO] ** TEST EXECUTE SUCCEEDED ** [10:45:01:INFO] [10:45:07:INFO] Waiting for test process to terminate. [10:45:07:INFO] Test process terminated. [10:45:07:DEBUG] Setting sigterm handler. [10:45:07:DEBUG] Stdout flushed after test process. [10:45:07:DEBUG] Processing test results. [10:45:07:INFO] /b/s/w/ir/src/out/Debug-iphonesimulator/iossim returned 0 [10:45:07:DEBUG] Extracting test data. [10:45:08:DEBUG] Retrieving crash reports. [10:45:08:DEBUG] Retrieving derived data. [10:45:08:DEBUG] Making desktop screenshots. [10:45:08:DEBUG] Killing simulators. [10:45:13:DEBUG] Wiping simulator. Device wiped. [10:45:15:DEBUG] End of tear_down. 2019-02-14 22:45:48,763 - root: [DEBUG] Contents of task_output_dir: ['/b/s/w/ir/kitchen-workdir/recipe_cleanup/0zEhB47/4308a8b930924f10'] 2019-02-14 22:45:48,764 - root: [WARNING] Expected output.json file missing: set(['/b/s/w/ir/kitchen-workdir/recipe_cleanup/0zEhB47/4308a8b930924f10/output.json']) Found: [] Expected: ['/b/s/w/ir/kitchen-workdir/recipe_cleanup/0zEhB47/4308a8b930924f10/output.json'] 2019-02-14 22:45:48,764 - root: [WARNING] No shard json files found in task_output_dir: '/b/s/w/ir/kitchen-workdir/recipe_cleanup/0zEhB47' Found ['/b/s/w/ir/kitchen-workdir/recipe_cleanup/0zEhB47/4308a8b930924f10'] 2019-02-14 22:45:48,764 - root: [DEBUG] Found shard_json_files: ['/b/s/w/ir/kitchen-workdir/recipe_cleanup/0zEhB47/4308a8b930924f10/output.json'] 2019-02-14 22:45:48,765 - root: [INFO] merge_cmd: /b/s/w/ir/cache/vpython/5b0713/bin/python /b/s/w/ir/kitchen-checkout/build/scripts/slave/recipe_modules/swarming/resources/noop_merge.py --summary-json /b/s/w/ir/tmp/t/tmpIg5wkO.json --task-output-dir /b/s/w/ir/kitchen-workdir/recipe_cleanup/0zEhB47 -o /b/s/w/ir/tmp/t/tmpqCth5e.json Running ['/b/s/w/ir/cache/vpython/5b0713/bin/python', '/b/s/w/ir/kitchen-checkout/build/scripts/slave/recipe_modules/swarming/resources/noop_merge.py', '--summary-json', '/b/s/w/ir/tmp/t/tmpIg5wkO.json', '--task-output-dir', '/b/s/w/ir/kitchen-workdir/recipe_cleanup/0zEhB47', '-o', '/b/s/w/ir/tmp/t/tmpqCth5e.json'] in None (env: None) Command ['/b/s/w/ir/cache/vpython/5b0713/bin/python', '/b/s/w/ir/kitchen-checkout/build/scripts/slave/recipe_modules/swarming/resources/noop_merge.py', '--summary-json', '/b/s/w/ir/tmp/t/tmpIg5wkO.json', '--task-output-dir', '/b/s/w/ir/kitchen-workdir/recipe_cleanup/0zEhB47', '-o', '/b/s/w/ir/tmp/t/tmpqCth5e.json'] returned exit code 0