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/chromium_swarming/resources/collect_task.py --verbose -o /b/s/w/ir/tmp/t/tmpzRVxgf.json --task-output-dir /b/s/w/ir/k/recipe_cleanup/0clJZLM --merge-script /b/s/w/ir/kitchen-checkout/build/scripts/slave/recipe_modules/chromium_swarming/resources/noop_merge.py --merge-script-stdout-file /b/s/w/ir/tmp/t/tmpDXZrM7merge_script_log --merge-additional-args '[]' --summary-json-file /b/s/w/ir/tmp/t/tmpK9B1EK.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/tmp2fVLlE.json in dir /b/s/w/ir/k: allow_subannotations: False 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/chromium_swarming/resources/collect_task.py', '--verbose', '-o', '/b/s/w/ir/tmp/t/tmpzRVxgf.json', '--task-output-dir', '/b/s/w/ir/k/recipe_cleanup/0clJZLM', '--merge-script', '/b/s/w/ir/kitchen-checkout/build/scripts/slave/recipe_modules/chromium_swarming/resources/noop_merge.py', '--merge-script-stdout-file', '/b/s/w/ir/tmp/t/tmpDXZrM7merge_script_log', '--merge-additional-args', '[]', '--summary-json-file', '/b/s/w/ir/tmp/t/tmpK9B1EK.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/tmp2fVLlE.json'] env: {} env_prefixes: {'PATH': '/b/s/w/ir/cache/swarming_client'} env_suffixes: {} infra_step: False name_tokens: ('ios_chrome_integration_egtests (iPhone X iOS 12.1) on Mac-10.13.6',) ok_ret: frozenset([0]) step_test_data: <lambda>(...) trigger_specs: () full environment: Apple_PubSub_Socket_Render: /private/tmp/com.apple.launchd.JX2gFDpmne/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: 52887 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/8918588703229952624 LOGDOG_STREAM_PROJECT: chromium LOGDOG_STREAM_SERVER_PATH: unix:/b/s/w/ir/tmp/ld.sock LOGNAME: chrome-bot LUCI_CONTEXT: /b/s/w/itHK41_q/luci_context.297468729 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/k PYTHONIOENCODING: UTF-8 PYTHONUNBUFFERED: 1 SHELL: /bin/bash SHLVL: 1 SSH_AUTH_SOCK: /private/tmp/com.apple.launchd.1g6oZ4lsEp/Listeners SWARMING_BOT_ID: build101-m9 SWARMING_HEADLESS: 1 SWARMING_SERVER: https://chromium-swarm.appspot.com SWARMING_TASK_ID: 43aced3d83db5b11 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-03-18 21:47:50,561 - root: [DEBUG] Using task_output_dir: '/b/s/w/ir/k/recipe_cleanup/0clJZLM' 2019-03-18 21:47:50,561 - root: [WARNING] task_output_dir '/b/s/w/ir/k/recipe_cleanup/0clJZLM' already exists! 2019-03-18 21:47:50,561 - root: [WARNING] task_output_dir existing content: [] 2019-03-18 21:47:50,561 - 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/tmp2fVLlE.json -output-dir /b/s/w/ir/k/recipe_cleanup/0clJZLM -task-summary-json /b/s/w/ir/tmp/t/tmpK9B1EK.json [D2019-03-18T21:47:50.577125-07:00 83266 0 auth.go:1265] Minting a new token {"key":"luci_ctx/309b6379e9c657a666e26f6268523329ddc95d89ef1ed993d25a7c0ee30042f2", "scopes":"https://www.googleapis.com/auth/userinfo.email"} [D2019-03-18T21:47:50.577233-07:00 83266 0 luci_ctx.go:138] POST http://127.0.0.1:52876/rpc/LuciLocalAuthService.GetOAuthToken {"key":"luci_ctx/309b6379e9c657a666e26f6268523329ddc95d89ef1ed993d25a7c0ee30042f2", "scopes":"https://www.googleapis.com/auth/userinfo.email"} [D2019-03-18T21:47:50.578844-07:00 83266 0 auth.go:1222] Token expires in 43m59.421226s {"key":"luci_ctx/309b6379e9c657a666e26f6268523329ddc95d89ef1ed993d25a7c0ee30042f2", "scopes":"https://www.googleapis.com/auth/userinfo.email"} [D2019-03-18T21:47:52.069886-07:00 83266 0 collect.go:344] Waiting task_id: 43ad1759bd814b10 [D2019-03-18T21:47:53.462034-07:00 83266 0 collect.go:344] Waiting task_id: 43ad1759bd814b10 [D2019-03-18T21:47:55.588023-07:00 83266 0 collect.go:344] Waiting task_id: 43ad1759bd814b10 [D2019-03-18T21:47:57.301007-07:00 83266 0 collect.go:344] Waiting task_id: 43ad1759bd814b10 [D2019-03-18T21:47:58.938115-07:00 83266 0 collect.go:344] Waiting task_id: 43ad1759bd814b10 [D2019-03-18T21:48:00.551153-07:00 83266 0 collect.go:344] Waiting task_id: 43ad1759bd814b10 [D2019-03-18T21:48:02.301102-07:00 83266 0 collect.go:344] Waiting task_id: 43ad1759bd814b10 [D2019-03-18T21:48:04.147983-07:00 83266 0 collect.go:344] Waiting task_id: 43ad1759bd814b10 [D2019-03-18T21:48:05.956894-07:00 83266 0 collect.go:344] Waiting task_id: 43ad1759bd814b10 [D2019-03-18T21:48:07.809682-07:00 83266 0 collect.go:344] Waiting task_id: 43ad1759bd814b10 [D2019-03-18T21:48:09.746129-07:00 83266 0 collect.go:344] Waiting task_id: 43ad1759bd814b10 [D2019-03-18T21:48:11.838586-07:00 83266 0 collect.go:344] Waiting task_id: 43ad1759bd814b10 [D2019-03-18T21:48:13.997742-07:00 83266 0 collect.go:344] Waiting task_id: 43ad1759bd814b10 [D2019-03-18T21:48:16.346351-07:00 83266 0 collect.go:344] Waiting task_id: 43ad1759bd814b10 [D2019-03-18T21:48:18.696465-07:00 83266 0 collect.go:344] Waiting task_id: 43ad1759bd814b10 [D2019-03-18T21:48:21.160408-07:00 83266 0 collect.go:344] Waiting task_id: 43ad1759bd814b10 [D2019-03-18T21:48:23.588856-07:00 83266 0 collect.go:344] Waiting task_id: 43ad1759bd814b10 [D2019-03-18T21:48:26.092895-07:00 83266 0 collect.go:344] Waiting task_id: 43ad1759bd814b10 [D2019-03-18T21:48:28.608793-07:00 83266 0 collect.go:344] Waiting task_id: 43ad1759bd814b10 [D2019-03-18T21:48:31.249687-07:00 83266 0 collect.go:344] Waiting task_id: 43ad1759bd814b10 [D2019-03-18T21:48:33.940604-07:00 83266 0 collect.go:344] Waiting task_id: 43ad1759bd814b10 [D2019-03-18T21:48:36.716799-07:00 83266 0 collect.go:344] Waiting task_id: 43ad1759bd814b10 [D2019-03-18T21:48:39.590341-07:00 83266 0 collect.go:344] Waiting task_id: 43ad1759bd814b10 [D2019-03-18T21:48:42.573079-07:00 83266 0 collect.go:344] Waiting task_id: 43ad1759bd814b10 [D2019-03-18T21:48:45.774771-07:00 83266 0 collect.go:344] Waiting task_id: 43ad1759bd814b10 [D2019-03-18T21:48:48.947273-07:00 83266 0 collect.go:344] Waiting task_id: 43ad1759bd814b10 [D2019-03-18T21:48:52.300975-07:00 83266 0 collect.go:344] Waiting task_id: 43ad1759bd814b10 [D2019-03-18T21:48:55.756604-07:00 83266 0 collect.go:344] Waiting task_id: 43ad1759bd814b10 [D2019-03-18T21:48:59.298032-07:00 83266 0 collect.go:344] Waiting task_id: 43ad1759bd814b10 [D2019-03-18T21:49:02.933737-07:00 83266 0 collect.go:344] Waiting task_id: 43ad1759bd814b10 [D2019-03-18T21:49:06.656847-07:00 83266 0 collect.go:344] Waiting task_id: 43ad1759bd814b10 [D2019-03-18T21:49:10.510568-07:00 83266 0 collect.go:344] Waiting task_id: 43ad1759bd814b10 [D2019-03-18T21:49:14.512278-07:00 83266 0 collect.go:344] Waiting task_id: 43ad1759bd814b10 [D2019-03-18T21:49:18.622302-07:00 83266 0 collect.go:344] Waiting task_id: 43ad1759bd814b10 [D2019-03-18T21:49:22.896103-07:00 83266 0 collect.go:344] Waiting task_id: 43ad1759bd814b10 [D2019-03-18T21:49:27.325069-07:00 83266 0 collect.go:344] Waiting task_id: 43ad1759bd814b10 [D2019-03-18T21:49:31.966092-07:00 83266 0 collect.go:344] Waiting task_id: 43ad1759bd814b10 [D2019-03-18T21:49:36.833070-07:00 83266 0 collect.go:344] Waiting task_id: 43ad1759bd814b10 [D2019-03-18T21:49:41.585108-07:00 83266 0 collect.go:344] Waiting task_id: 43ad1759bd814b10 [D2019-03-18T21:49:46.625104-07:00 83266 0 collect.go:344] Waiting task_id: 43ad1759bd814b10 [D2019-03-18T21:49:51.820523-07:00 83266 0 collect.go:344] Waiting task_id: 43ad1759bd814b10 [D2019-03-18T21:49:57.201474-07:00 83266 0 collect.go:344] Waiting task_id: 43ad1759bd814b10 [D2019-03-18T21:50:02.972895-07:00 83266 0 collect.go:344] Waiting task_id: 43ad1759bd814b10 [D2019-03-18T21:50:08.847622-07:00 83266 0 collect.go:344] Waiting task_id: 43ad1759bd814b10 [D2019-03-18T21:50:14.908339-07:00 83266 0 collect.go:344] Waiting task_id: 43ad1759bd814b10 [D2019-03-18T21:50:21.156645-07:00 83266 0 collect.go:344] Waiting task_id: 43ad1759bd814b10 [D2019-03-18T21:50:27.656968-07:00 83266 0 collect.go:344] Waiting task_id: 43ad1759bd814b10 [D2019-03-18T21:50:34.302932-07:00 83266 0 collect.go:344] Waiting task_id: 43ad1759bd814b10 [D2019-03-18T21:50:41.010782-07:00 83266 0 collect.go:344] Waiting task_id: 43ad1759bd814b10 [D2019-03-18T21:50:48.012748-07:00 83266 0 collect.go:344] Waiting task_id: 43ad1759bd814b10 [D2019-03-18T21:50:55.264762-07:00 83266 0 collect.go:344] Waiting task_id: 43ad1759bd814b10 [D2019-03-18T21:51:02.736924-07:00 83266 0 collect.go:344] Waiting task_id: 43ad1759bd814b10 [D2019-03-18T21:51:10.445200-07:00 83266 0 collect.go:344] Waiting task_id: 43ad1759bd814b10 [D2019-03-18T21:51:18.463796-07:00 83266 0 collect.go:344] Waiting task_id: 43ad1759bd814b10 [D2019-03-18T21:51:26.745933-07:00 83266 0 collect.go:344] Waiting task_id: 43ad1759bd814b10 [D2019-03-18T21:51:35.200565-07:00 83266 0 collect.go:344] Waiting task_id: 43ad1759bd814b10 [D2019-03-18T21:51:43.933925-07:00 83266 0 collect.go:344] Waiting task_id: 43ad1759bd814b10 [D2019-03-18T21:51:53.055564-07:00 83266 0 collect.go:344] Waiting task_id: 43ad1759bd814b10 [D2019-03-18T21:52:02.507223-07:00 83266 0 collect.go:344] Waiting task_id: 43ad1759bd814b10 [D2019-03-18T21:52:12.235611-07:00 83266 0 collect.go:344] Waiting task_id: 43ad1759bd814b10 [D2019-03-18T21:52:22.583067-07:00 83266 0 collect.go:344] Waiting task_id: 43ad1759bd814b10 [D2019-03-18T21:52:32.982768-07:00 83266 0 collect.go:344] Waiting task_id: 43ad1759bd814b10 [D2019-03-18T21:52:43.723024-07:00 83266 0 collect.go:344] Waiting task_id: 43ad1759bd814b10 [D2019-03-18T21:52:54.801926-07:00 83266 0 collect.go:344] Waiting task_id: 43ad1759bd814b10 [D2019-03-18T21:53:06.137310-07:00 83266 0 collect.go:344] Waiting task_id: 43ad1759bd814b10 [D2019-03-18T21:53:18.019712-07:00 83266 0 collect.go:344] Waiting task_id: 43ad1759bd814b10 [D2019-03-18T21:53:30.283934-07:00 83266 0 collect.go:344] Waiting task_id: 43ad1759bd814b10 [D2019-03-18T21:53:42.969327-07:00 83266 0 collect.go:344] Waiting task_id: 43ad1759bd814b10 [D2019-03-18T21:53:56.242713-07:00 83266 0 collect.go:344] Waiting task_id: 43ad1759bd814b10 [D2019-03-18T21:54:09.675270-07:00 83266 0 collect.go:344] Waiting task_id: 43ad1759bd814b10 [D2019-03-18T21:54:23.550439-07:00 83266 0 collect.go:344] Waiting task_id: 43ad1759bd814b10 [D2019-03-18T21:54:38.055832-07:00 83266 0 collect.go:344] Waiting task_id: 43ad1759bd814b10 [D2019-03-18T21:54:52.903918-07:00 83266 0 collect.go:344] Waiting task_id: 43ad1759bd814b10 [D2019-03-18T21:55:08.214418-07:00 83266 0 collect.go:344] Waiting task_id: 43ad1759bd814b10 [D2019-03-18T21:55:23.541245-07:00 83266 0 collect.go:344] Waiting task_id: 43ad1759bd814b10 [D2019-03-18T21:55:38.868190-07:00 83266 0 collect.go:344] Waiting task_id: 43ad1759bd814b10 [D2019-03-18T21:55:54.211488-07:00 83266 0 collect.go:344] Waiting task_id: 43ad1759bd814b10 [D2019-03-18T21:56:09.508855-07:00 83266 0 collect.go:344] Waiting task_id: 43ad1759bd814b10 [D2019-03-18T21:56:24.834410-07:00 83266 0 collect.go:344] Waiting task_id: 43ad1759bd814b10 [D2019-03-18T21:56:40.169034-07:00 83266 0 collect.go:344] Waiting task_id: 43ad1759bd814b10 [D2019-03-18T21:56:55.646341-07:00 83266 0 collect.go:344] Waiting task_id: 43ad1759bd814b10 43ad1759bd814b10: exit 0 [I 2019-03-18 21:46:56] About to install Xcode 10b61 in Xcode.app for ios [09:46:57:INFO] Using Xcode version 10.1 build 10B61 at /b/s/w/ir/Xcode.app/Contents/Developer [09:46:57:INFO] Erasing all simulators. [09:47:07:INFO] Start test attempt #0 for command [xcodebuild test-without-building -xctestrun /b/s/w/ioBUzSBF/run_1552970827.xctestrun -destination platform=iOS Simulator,OS=12.1,name=iPhone X -resultBundlePath /b/s/w/ioBUzSBF/12.1 iPhone X/attempt_0 -parallel-testing-enabled YES -parallel-testing-worker-count 3] [09:47:07:INFO] Launching ['xcodebuild', 'test-without-building', '-xctestrun', '/b/s/w/ioBUzSBF/run_1552970827.xctestrun', '-destination', 'platform=iOS Simulator,OS=12.1,name=iPhone X', '-resultBundlePath', '/b/s/w/ioBUzSBF/12.1 iPhone X/attempt_0', '-parallel-testing-enabled', 'YES', '-parallel-testing-worker-count', '3'] with env {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'MAC_CHROMIUM_TMPDIR': '/b/s/w/itPSquJx', 'LOGNAME': 'chrome-bot', 'USER': 'chrome-bot', 'HOME': '/Users/chrome-bot', 'BOTO_CONFIG': '/Users/chrome-bot/.boto', 'PATH': '/b/s/w/ir/.swarming_module_cache/vpython/b6ec90/bin:/b/s/w/ir/.swarming_module:/b/s/w/ir/.swarming_module/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', 'SWARMING_BOT_ID': 'vm934-m4', 'VIRTUAL_ENV': '/b/s/w/ir/.swarming_module_cache/vpython/b6ec90', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.T3K9oFxZhw/Render', 'SWARMING_SERVER': 'https://chromium-swarm.appspot.com', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'SWARMING_HEADLESS': '1', 'PYTHONNOUSERSITE': '1', 'LUCI_CONTEXT': '/b/s/w/itPSquJx/luci_ctx.YVDS6z.json', 'CIPD_CACHE_DIR': '/b/s/cipd_cache/cache', 'XPC_SERVICE_NAME': 'org.swarm.bot.plist', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lid7XDkOC8/Listeners', 'VPYTHON_VIRTUALENV_ROOT': '/b/s/w/ir/.swarming_module_cache/vpython', 'SWARMING_TASK_ID': '43ad1759bd814b11', 'SHELL': '/bin/bash', 'NO_GCE_CHECK': 'False', 'TMPDIR': '/b/s/w/itPSquJx', 'CIPD_PROTOCOL': 'v2', '__CF_USER_TEXT_ENCODING': '0x1F4:0x0:0x0', 'NSUnbufferedIO': 'YES'} [09:47:11:INFO] User defaults from command line: [09:47:11:INFO] IDEBuildOperationResultBundlePath = /b/s/w/ioBUzSBF/12.1 iPhone X/attempt_0 [09:47:11:INFO] IDETestRunSpecificationPath = /b/s/w/ioBUzSBF/run_1552970827.xctestrun [09:47:11:INFO] [09:47:12:INFO] Testing started on 'iPhone X' [09:47:13:INFO] 2019-03-18 21:47:13.108 xcodebuild[19572:363378] IDETestOperationsObserverDebug: Writing diagnostic log for test session to: [09:47:13:INFO] /b/s/w/ioBUzSBF/12.1 iPhone X/attempt_0/1_Test/Diagnostics/ios_chrome_integration_egtests_module-5A3EFE85-9D99-4E1C-83E4-F408D22F047D/ios_chrome_integration_egtests_module-826854B6-6CBB-486B-B4D0-9DA8A0AD770E/Session-ios_chrome_integration_egtests_module-2019-03-18_214713-NVgAQQ.log [09:47:13:INFO] 2019-03-18 21:47:13.109 xcodebuild[19572:363376] [MT] IDETestOperationsObserverDebug: (1D9F5D78-B53B-42D1-A933-6BDA3B2731A8) Beginning test session ios_chrome_integration_egtests_module-1D9F5D78-B53B-42D1-A933-6BDA3B2731A8 at 2019-03-18 21:47:13.109 with Xcode 10B61 on target <DVTiPhoneSimulator: 0x7ff600ff72e0> { [09:47:13:INFO] SimDevice: Clone 1 of iPhone X (A8B70BBF-FF0A-4F1F-A1E1-A43757DF4D4A, iOS 12.1, Shutdown) [09:47:13:INFO] } (12.1 (16B91)) [09:47:36:INFO] 2019-03-18 21:47:36.185 xcodebuild[19572:363505] IDETestOperationsObserverDebug: Writing diagnostic log for test session to: [09:47:36:INFO] /b/s/w/ioBUzSBF/12.1 iPhone X/attempt_0/1_Test/Diagnostics/ios_chrome_integration_egtests_module-5A3EFE85-9D99-4E1C-83E4-F408D22F047D/ios_chrome_integration_egtests_module-C28F5597-2A81-4722-B5F2-22F2B0C85005/Session-ios_chrome_integration_egtests_module-2019-03-18_214736-yOe4X6.log [09:47:36:INFO] 2019-03-18 21:47:36.186 xcodebuild[19572:363376] [MT] IDETestOperationsObserverDebug: (C52D4806-E5F1-448B-A0A6-7F12BF8BAE93) Beginning test session ios_chrome_integration_egtests_module-C52D4806-E5F1-448B-A0A6-7F12BF8BAE93 at 2019-03-18 21:47:36.186 with Xcode 10B61 on target <DVTiPhoneSimulator: 0x7ff600e4cee0> { [09:47:36:INFO] SimDevice: Clone 2 of iPhone X (2683F6B4-E232-4C3B-8552-F6AC2CDB4FC6, iOS 12.1, Shutdown) [09:47:36:INFO] } (12.1 (16B91)) [09:47:36:INFO] 2019-03-18 21:47:36.189 xcodebuild[19572:364779] IDETestOperationsObserverDebug: Writing diagnostic log for test session to: [09:47:36:INFO] /b/s/w/ioBUzSBF/12.1 iPhone X/attempt_0/1_Test/Diagnostics/ios_chrome_integration_egtests_module-5A3EFE85-9D99-4E1C-83E4-F408D22F047D/ios_chrome_integration_egtests_module-58B9090F-24EB-48F5-93B7-FD969BFDC0ED/Session-ios_chrome_integration_egtests_module-2019-03-18_214736-CbuxIO.log [09:47:36:INFO] 2019-03-18 21:47:36.189 xcodebuild[19572:363376] [MT] IDETestOperationsObserverDebug: (7CA58D50-CFF9-45E0-BBE5-D9FB6D73EE2F) Beginning test session ios_chrome_integration_egtests_module-7CA58D50-CFF9-45E0-BBE5-D9FB6D73EE2F at 2019-03-18 21:47:36.190 with Xcode 10B61 on target <DVTiPhoneSimulator: 0x7ff602784a50> { [09:47:36:INFO] SimDevice: Clone 3 of iPhone X (4AF44214-A6B7-4147-9610-9A6F6F7BB7EE, iOS 12.1, Shutdown) [09:47:36:INFO] } (12.1 (16B91)) [09:47:49:INFO] Test suite 'CredentialManagerEGTest' started on 'Clone 1 of iPhone X - ios_chrome_integration_egtests.app (19910)' [09:48:17:INFO] Test suite 'FormInputTestCase' started on 'Clone 3 of iPhone X - ios_chrome_integration_egtests.app (20577)' [09:48:18:INFO] Test suite 'UKMTestCase' started on 'Clone 2 of iPhone X - ios_chrome_integration_egtests.app (20586)' [09:48:19:INFO] Test case '-[CredentialManagerEGTest testNotificationAppearsOnAutoSignIn]' passed on 'Clone 1 of iPhone X - ios_chrome_integration_egtests.app (19910)' (28.355 seconds) [09:48:24:INFO] Test case '-[FormInputTestCase testCloseKeyboardWhenNotVisible]' passed on 'Clone 3 of iPhone X - ios_chrome_integration_egtests.app (20577)' (5.164 seconds) [09:48:27:INFO] Test case '-[CredentialManagerEGTest testNotificationAppearsWhenTabIsActive]' passed on 'Clone 1 of iPhone X - ios_chrome_integration_egtests.app (19910)' (8.243 seconds) [09:48:28:INFO] Test suite 'NTPTilesTest' started on 'Clone 1 of iPhone X - ios_chrome_integration_egtests.app (19910)' [09:48:39:INFO] Test case '-[FormInputTestCase testFindDefaultFormAssistControls]' passed on 'Clone 3 of iPhone X - ios_chrome_integration_egtests.app (20577)' (15.082 seconds) [09:48:39:INFO] Test suite 'BrowserStateServicesTestCase' started on 'Clone 3 of iPhone X - ios_chrome_integration_egtests.app (20577)' [09:48:44:INFO] Test case '-[BrowserStateServicesTestCase testConnectionToIdentityService]' passed on 'Clone 3 of iPhone X - ios_chrome_integration_egtests.app (20577)' (4.216 seconds) [09:48:44:INFO] Test suite 'HandoffManagerTestCase' started on 'Clone 3 of iPhone X - ios_chrome_integration_egtests.app (20577)' [09:48:56:INFO] Test case '-[HandoffManagerTestCase testMultipleSwitchingTabs]' passed on 'Clone 3 of iPhone X - ios_chrome_integration_egtests.app (20577)' (12.749 seconds) [09:48:58:INFO] Test case '-[NTPTilesTest testTopSitesTileAfterLoadURL]' passed on 'Clone 1 of iPhone X - ios_chrome_integration_egtests.app (19910)' (30.580 seconds) [09:49:00:INFO] Test case '-[HandoffManagerTestCase testNewTabPageEmptyURL]' passed on 'Clone 3 of iPhone X - ios_chrome_integration_egtests.app (20577)' (3.971 seconds) [09:49:12:INFO] Test case '-[HandoffManagerTestCase testSwitchBetweenNormalAndIncognitoTabs]' passed on 'Clone 3 of iPhone X - ios_chrome_integration_egtests.app (20577)' (11.441 seconds) [09:49:17:INFO] Test case '-[HandoffManagerTestCase testTypicalURL]' passed on 'Clone 3 of iPhone X - ios_chrome_integration_egtests.app (20577)' (5.491 seconds) [09:49:27:INFO] Test case '-[HandoffManagerTestCase testTypicalURLInNewIncognitoTab]' passed on 'Clone 3 of iPhone X - ios_chrome_integration_egtests.app (20577)' (9.756 seconds) [09:49:30:INFO] Test case '-[UKMTestCase testConsentAddedButNoSync]' passed on 'Clone 2 of iPhone X - ios_chrome_integration_egtests.app (20586)' (70.741 seconds) [09:49:35:INFO] Test case '-[HandoffManagerTestCase testTypicalURLInNewTab]' passed on 'Clone 3 of iPhone X - ios_chrome_integration_egtests.app (20577)' (7.820 seconds) [09:49:35:INFO] Test suite 'TabUsageRecorderTestCase' started on 'Clone 3 of iPhone X - ios_chrome_integration_egtests.app (20577)' [09:49:57:INFO] Test case '-[TabUsageRecorderTestCase testEvictedTabReloadBackgrounded]' passed on 'Clone 3 of iPhone X - ios_chrome_integration_egtests.app (20577)' (22.543 seconds) [09:50:02:INFO] Test case '-[NTPTilesTest testTopSitesTileAfterRedirect]' passed on 'Clone 1 of iPhone X - ios_chrome_integration_egtests.app (19910)' (63.957 seconds) [09:50:02:INFO] Test suite 'CookiesTestCase' started on 'Clone 1 of iPhone X - ios_chrome_integration_egtests.app (19910)' [09:50:12:INFO] Test case '-[UKMTestCase testIncognitoPlusRegular]' passed on 'Clone 2 of iPhone X - ios_chrome_integration_egtests.app (20586)' (41.890 seconds) [09:50:28:INFO] Test case '-[CookiesTestCase testClearIncognitoFromIncognito]' passed on 'Clone 1 of iPhone X - ios_chrome_integration_egtests.app (19910)' (25.926 seconds) [09:50:33:INFO] Test case '-[TabUsageRecorderTestCase testEvictedTabReloadSettingsAndBack]' passed on 'Clone 3 of iPhone X - ios_chrome_integration_egtests.app (20577)' (35.840 seconds) [09:50:49:INFO] Test case '-[UKMTestCase testMetricsConsent]' passed on 'Clone 2 of iPhone X - ios_chrome_integration_egtests.app (20586)' (36.382 seconds) [09:50:51:INFO] Test case '-[CookiesTestCase testClearIncognitoFromMain]' passed on 'Clone 1 of iPhone X - ios_chrome_integration_egtests.app (19910)' (22.819 seconds) [09:51:00:INFO] Test case '-[TabUsageRecorderTestCase testEvictedTabReloadSwitchToNTP]' passed on 'Clone 3 of iPhone X - ios_chrome_integration_egtests.app (20577)' (27.151 seconds) [09:51:07:INFO] Test case '-[CookiesTestCase testShareCookiesBetweenTabs]' passed on 'Clone 1 of iPhone X - ios_chrome_integration_egtests.app (19910)' (15.829 seconds) [09:51:27:INFO] Test case '-[TabUsageRecorderTestCase testEvictedTabSlowReload]' passed on 'Clone 3 of iPhone X - ios_chrome_integration_egtests.app (20577)' (26.922 seconds) [09:51:29:INFO] Test case '-[CookiesTestCase testSwitchToIncognito]' passed on 'Clone 1 of iPhone X - ios_chrome_integration_egtests.app (19910)' (22.250 seconds) [09:51:40:INFO] Test case '-[UKMTestCase testRegularPlusIncognito]' passed on 'Clone 2 of iPhone X - ios_chrome_integration_egtests.app (20586)' (51.253 seconds) [09:51:55:INFO] Test case '-[CookiesTestCase testSwitchToMain]' passed on 'Clone 1 of iPhone X - ios_chrome_integration_egtests.app (19910)' (26.154 seconds) [09:51:55:INFO] Test suite 'ContextMenuTestCase' started on 'Clone 1 of iPhone X - ios_chrome_integration_egtests.app (19910)' [09:51:56:INFO] Test case '-[TabUsageRecorderTestCase testLinkClickNavigation]' passed on 'Clone 3 of iPhone X - ios_chrome_integration_egtests.app (20577)' (28.689 seconds) [09:52:04:INFO] Test case '-[TabUsageRecorderTestCase testLiveTabReloadBackgrounded]' passed on 'Clone 3 of iPhone X - ios_chrome_integration_egtests.app (20577)' (8.050 seconds) [09:52:14:INFO] Test case '-[TabUsageRecorderTestCase testOpenFromApp]' passed on 'Clone 3 of iPhone X - ios_chrome_integration_egtests.app (20577)' (9.666 seconds) [09:52:24:INFO] Test case '-[ContextMenuTestCase testAppropriateContextMenu]' passed on 'Clone 1 of iPhone X - ios_chrome_integration_egtests.app (19910)' (28.233 seconds) [09:52:29:INFO] Test case '-[TabUsageRecorderTestCase testOpenLinkInNewTab]' passed on 'Clone 3 of iPhone X - ios_chrome_integration_egtests.app (20577)' (15.219 seconds) [09:52:43:INFO] Test case '-[ContextMenuTestCase testContextMenuCancelSystemTouchesMetric]' passed on 'Clone 1 of iPhone X - ios_chrome_integration_egtests.app (19910)' (18.988 seconds) [09:53:07:INFO] Test case '-[ContextMenuTestCase testContextMenuDisplayedOnImage]' passed on 'Clone 1 of iPhone X - ios_chrome_integration_egtests.app (19910)' (24.770 seconds) [09:53:20:INFO] Test case '-[UKMTestCase testSecondaryPassphrase]' passed on 'Clone 2 of iPhone X - ios_chrome_integration_egtests.app (20586)' (100.150 seconds) [09:53:21:INFO] Test case '-[TabUsageRecorderTestCase testPageLoadCountBeforeEvictedTab]' passed on 'Clone 3 of iPhone X - ios_chrome_integration_egtests.app (20577)' (51.642 seconds) [09:53:26:INFO] Test case '-[ContextMenuTestCase testContextMenuElementFetchDurationMetric]' passed on 'Clone 1 of iPhone X - ios_chrome_integration_egtests.app (19910)' (19.026 seconds) [09:53:46:INFO] Test case '-[ContextMenuTestCase testContextMenuOpenInNewTab]' passed on 'Clone 1 of iPhone X - ios_chrome_integration_egtests.app (19910)' (19.756 seconds) [09:53:49:INFO] Test case '-[TabUsageRecorderTestCase testPageRedirect]' passed on 'Clone 3 of iPhone X - ios_chrome_integration_egtests.app (20577)' (28.352 seconds) [09:53:59:INFO] Test case '-[ContextMenuTestCase testContextMenuSelectedTextCallout]' passed on 'Clone 1 of iPhone X - ios_chrome_integration_egtests.app (19910)' (12.783 seconds) [09:54:08:INFO] Test case '-[TabUsageRecorderTestCase testTabDeletion]' passed on 'Clone 3 of iPhone X - ios_chrome_integration_egtests.app (20577)' (18.553 seconds) [09:54:08:INFO] Test suite 'FeatureEngagementTestCase' started on 'Clone 3 of iPhone X - ios_chrome_integration_egtests.app (20577)' [09:54:19:INFO] Test case '-[FeatureEngagementTestCase testBadgedReadingListFeatureReadingListAlreadyUsed]' passed on 'Clone 3 of iPhone X - ios_chrome_integration_egtests.app (20577)' (11.569 seconds) [09:54:22:INFO] Test case '-[UKMTestCase testSingleDisableSync]' passed on 'Clone 2 of iPhone X - ios_chrome_integration_egtests.app (20586)' (61.722 seconds) [09:54:30:INFO] Test case '-[ContextMenuTestCase testDismissContextMenu]' passed on 'Clone 1 of iPhone X - ios_chrome_integration_egtests.app (19910)' (31.580 seconds) [09:54:34:INFO] Test case '-[FeatureEngagementTestCase testBadgedReadingListFeatureShouldShow]' passed on 'Clone 3 of iPhone X - ios_chrome_integration_egtests.app (20577)' (14.235 seconds) [09:54:42:INFO] Test case '-[FeatureEngagementTestCase testBadgedReadingListFeatureTooFewChromeOpens]' passed on 'Clone 3 of iPhone X - ios_chrome_integration_egtests.app (20577)' (8.138 seconds) [09:54:46:INFO] Test case '-[FeatureEngagementTestCase testBottomToolbarDontAppearOnNonSplitToolbar]' passed on 'Clone 3 of iPhone X - ios_chrome_integration_egtests.app (20577)' (4.604 seconds) [09:54:49:INFO] Test case '-[ContextMenuTestCase testOpenImageInCurrentTabFromContextMenu]' passed on 'Clone 1 of iPhone X - ios_chrome_integration_egtests.app (19910)' (18.044 seconds) [09:54:59:INFO] Test case '-[FeatureEngagementTestCase testNewTabTipPromoShouldShow]' passed on 'Clone 3 of iPhone X - ios_chrome_integration_egtests.app (20577)' (13.197 seconds) [09:55:00:INFO] Test suite 'PrerenderTestCase' started on 'Clone 3 of iPhone X - ios_chrome_integration_egtests.app (20577)' [09:55:07:INFO] Test case '-[ContextMenuTestCase testOpenImageInNewTabFromContextMenu]' passed on 'Clone 1 of iPhone X - ios_chrome_integration_egtests.app (19910)' (18.288 seconds) [09:55:28:INFO] Test case '-[UKMTestCase testSingleSyncSignout]' passed on 'Clone 2 of iPhone X - ios_chrome_integration_egtests.app (20586)' (66.183 seconds) [09:55:36:INFO] Test case '-[PrerenderTestCase testTapPrerenderSuggestions]' passed on 'Clone 3 of iPhone X - ios_chrome_integration_egtests.app (20577)' (36.541 seconds) [09:55:36:INFO] 2019-03-18 21:55:36.941 xcodebuild[19572:363376] [MT] IDETestOperationsObserverDebug: 504.774 elapsed -- Testing started completed. [09:55:36:INFO] 2019-03-18 21:55:36.941 xcodebuild[19572:363376] [MT] IDETestOperationsObserverDebug: 0.000 sec, +0.000 sec -- start [09:55:36:INFO] 2019-03-18 21:55:36.941 xcodebuild[19572:363376] [MT] IDETestOperationsObserverDebug: 504.774 sec, +504.774 sec -- end [09:55:36:INFO] ** TEST EXECUTE SUCCEEDED ** [09:55:36:INFO] [09:55:37:INFO] 2019-03-18 21:55:37.756 xcodebuild[19572:363377] CoreSimulatorService connection interrupted. Resubscribing to notifications. [09:55:43:INFO] Command ['xcodebuild', 'test-without-building', '-xctestrun', '/b/s/w/ioBUzSBF/run_1552970827.xctestrun', '-destination', 'platform=iOS Simulator,OS=12.1,name=iPhone X', '-resultBundlePath', '/b/s/w/ioBUzSBF/12.1 iPhone X/attempt_0', '-parallel-testing-enabled', 'YES', '-parallel-testing-worker-count', '3'] finished with 0 [09:55:43:DEBUG] self.logs: [09:55:43:DEBUG] {'failed': [], 'flaked': [], 'passed': ['CredentialManagerEGTest/testNotificationAppearsOnAutoSignIn', 'CredentialManagerEGTest/testNotificationAppearsWhenTabIsActive', 'FormInputTestCase/testCloseKeyboardWhenNotVisible', 'FormInputTestCase/testFindDefaultFormAssistControls', 'UKMTestCase/testConsentAddedButNoSync', 'UKMTestCase/testIncognitoPlusRegular', 'UKMTestCase/testMetricsConsent', 'UKMTestCase/testRegularPlusIncognito', 'UKMTestCase/testSecondaryPassphrase', 'UKMTestCase/testSingleDisableSync', 'UKMTestCase/testSingleSyncSignout', 'NTPTilesTest/testTopSitesTileAfterLoadURL', 'NTPTilesTest/testTopSitesTileAfterRedirect', 'BrowserStateServicesTestCase/testConnectionToIdentityService', 'HandoffManagerTestCase/testMultipleSwitchingTabs', 'HandoffManagerTestCase/testNewTabPageEmptyURL', 'HandoffManagerTestCase/testSwitchBetweenNormalAndIncognitoTabs', 'HandoffManagerTestCase/testTypicalURL', 'HandoffManagerTestCase/testTypicalURLInNewIncognitoTab', 'HandoffManagerTestCase/testTypicalURLInNewTab', 'TabUsageRecorderTestCase/testEvictedTabReloadBackgrounded', 'TabUsageRecorderTestCase/testEvictedTabReloadSettingsAndBack', 'TabUsageRecorderTestCase/testEvictedTabReloadSwitchToNTP', 'TabUsageRecorderTestCase/testEvictedTabSlowReload', 'TabUsageRecorderTestCase/testLinkClickNavigation', 'TabUsageRecorderTestCase/testLiveTabReloadBackgrounded', 'TabUsageRecorderTestCase/testOpenFromApp', 'TabUsageRecorderTestCase/testOpenLinkInNewTab', 'TabUsageRecorderTestCase/testPageLoadCountBeforeEvictedTab', 'TabUsageRecorderTestCase/testPageRedirect', 'TabUsageRecorderTestCase/testTabDeletion', 'CookiesTestCase/testClearIncognitoFromIncognito', 'CookiesTestCase/testClearIncognitoFromMain', 'CookiesTestCase/testShareCookiesBetweenTabs', 'CookiesTestCase/testSwitchToIncognito', 'CookiesTestCase/testSwitchToMain', 'ContextMenuTestCase/testAppropriateContextMenu', 'ContextMenuTestCase/testContextMenuCancelSystemTouchesMetric', 'ContextMenuTestCase/testContextMenuDisplayedOnImage', 'ContextMenuTestCase/testContextMenuElementFetchDurationMetric', 'ContextMenuTestCase/testContextMenuOpenInNewTab', 'ContextMenuTestCase/testContextMenuSelectedTextCallout', 'ContextMenuTestCase/testDismissContextMenu', 'ContextMenuTestCase/testOpenImageInCurrentTabFromContextMenu', 'ContextMenuTestCase/testOpenImageInNewTabFromContextMenu', 'FeatureEngagementTestCase/testBadgedReadingListFeatureReadingListAlreadyUsed', 'FeatureEngagementTestCase/testBadgedReadingListFeatureShouldShow', 'FeatureEngagementTestCase/testBadgedReadingListFeatureTooFewChromeOpens', 'FeatureEngagementTestCase/testBottomToolbarDontAppearOnNonSplitToolbar', 'FeatureEngagementTestCase/testNewTabTipPromoShouldShow', 'PrerenderTestCase/testTapPrerenderSuggestions']} 2019-03-18 21:56:56,432 - root: [DEBUG] Contents of task_output_dir: ['/b/s/w/ir/k/recipe_cleanup/0clJZLM/43ad1759bd814b10'] 2019-03-18 21:56:56,432 - root: [WARNING] Expected output.json file missing: set(['/b/s/w/ir/k/recipe_cleanup/0clJZLM/43ad1759bd814b10/output.json']) Found: [] Expected: ['/b/s/w/ir/k/recipe_cleanup/0clJZLM/43ad1759bd814b10/output.json'] 2019-03-18 21:56:56,432 - root: [WARNING] No shard json files found in task_output_dir: '/b/s/w/ir/k/recipe_cleanup/0clJZLM' Found ['/b/s/w/ir/k/recipe_cleanup/0clJZLM/43ad1759bd814b10'] 2019-03-18 21:56:56,432 - root: [DEBUG] Found shard_json_files: ['/b/s/w/ir/k/recipe_cleanup/0clJZLM/43ad1759bd814b10/output.json'] 2019-03-18 21:56:56,433 - root: [INFO] merge_cmd: /b/s/w/ir/cache/vpython/5b0713/bin/python /b/s/w/ir/kitchen-checkout/build/scripts/slave/recipe_modules/chromium_swarming/resources/noop_merge.py --summary-json /b/s/w/ir/tmp/t/tmpK9B1EK.json --task-output-dir /b/s/w/ir/k/recipe_cleanup/0clJZLM -o /b/s/w/ir/tmp/t/tmpzRVxgf.json Running ['/b/s/w/ir/cache/vpython/5b0713/bin/python', '/b/s/w/ir/kitchen-checkout/build/scripts/slave/recipe_modules/chromium_swarming/resources/noop_merge.py', '--summary-json', '/b/s/w/ir/tmp/t/tmpK9B1EK.json', '--task-output-dir', '/b/s/w/ir/k/recipe_cleanup/0clJZLM', '-o', '/b/s/w/ir/tmp/t/tmpzRVxgf.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/chromium_swarming/resources/noop_merge.py', '--summary-json', '/b/s/w/ir/tmp/t/tmpK9B1EK.json', '--task-output-dir', '/b/s/w/ir/k/recipe_cleanup/0clJZLM', '-o', '/b/s/w/ir/tmp/t/tmpzRVxgf.json'] returned exit code 0