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/tmpeuQK1c.json --task-output-dir /b/s/w/ir/k/recipe_cleanup/09VjKCb --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/tmptx1rtImerge_script_log --merge-additional-args '[]' --summary-json-file /b/s/w/ir/tmp/t/tmpdyczFA.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/tmp6yt8Nn.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/tmpeuQK1c.json', '--task-output-dir', '/b/s/w/ir/k/recipe_cleanup/09VjKCb', '--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/tmptx1rtImerge_script_log', '--merge-additional-args', '[]', '--summary-json-file', '/b/s/w/ir/tmp/t/tmpdyczFA.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/tmp6yt8Nn.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.ZNSHIn7rqE/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: 62218 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/8918560521491664896 LOGDOG_STREAM_PROJECT: chromium LOGDOG_STREAM_SERVER_PATH: unix:/b/s/w/ir/tmp/ld.sock LOGNAME: chrome-bot LUCI_CONTEXT: /b/s/w/it1YCxCw/luci_context.158160206 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.foxcJp0Q4g/Listeners SWARMING_BOT_ID: build111-m9 SWARMING_HEADLESS: 1 SWARMING_SERVER: https://chromium-swarm.appspot.com SWARMING_TASK_ID: 43ae875715495011 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-19 04:39:27,667 - root: [DEBUG] Using task_output_dir: '/b/s/w/ir/k/recipe_cleanup/09VjKCb' 2019-03-19 04:39:27,667 - root: [WARNING] task_output_dir '/b/s/w/ir/k/recipe_cleanup/09VjKCb' already exists! 2019-03-19 04:39:27,667 - root: [WARNING] task_output_dir existing content: [] 2019-03-19 04:39:27,667 - 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/tmp6yt8Nn.json -output-dir /b/s/w/ir/k/recipe_cleanup/09VjKCb -task-summary-json /b/s/w/ir/tmp/t/tmpdyczFA.json [D2019-03-19T04:39:27.682108-07:00 64111 0 auth.go:1265] Minting a new token {"key":"luci_ctx/897e27836dac3d733d8e6445d46b31373d7a844a4ce30988d3a752147164f976", "scopes":"https://www.googleapis.com/auth/userinfo.email"} [D2019-03-19T04:39:27.682193-07:00 64111 0 luci_ctx.go:138] POST http://127.0.0.1:62207/rpc/LuciLocalAuthService.GetOAuthToken {"key":"luci_ctx/897e27836dac3d733d8e6445d46b31373d7a844a4ce30988d3a752147164f976", "scopes":"https://www.googleapis.com/auth/userinfo.email"} [D2019-03-19T04:39:27.683893-07:00 64111 0 auth.go:1222] Token expires in 42m51.316119s {"key":"luci_ctx/897e27836dac3d733d8e6445d46b31373d7a844a4ce30988d3a752147164f976", "scopes":"https://www.googleapis.com/auth/userinfo.email"} [D2019-03-19T04:39:29.285189-07:00 64111 0 collect.go:344] Waiting task_id: 43ae9038e4548310 [D2019-03-19T04:39:30.712515-07:00 64111 0 collect.go:344] Waiting task_id: 43ae9038e4548310 [D2019-03-19T04:39:32.023434-07:00 64111 0 collect.go:344] Waiting task_id: 43ae9038e4548310 [D2019-03-19T04:39:33.528895-07:00 64111 0 collect.go:344] Waiting task_id: 43ae9038e4548310 [D2019-03-19T04:39:35.036277-07:00 64111 0 collect.go:344] Waiting task_id: 43ae9038e4548310 [D2019-03-19T04:39:36.633963-07:00 64111 0 collect.go:344] Waiting task_id: 43ae9038e4548310 [D2019-03-19T04:39:38.269159-07:00 64111 0 collect.go:344] Waiting task_id: 43ae9038e4548310 [D2019-03-19T04:39:39.845424-07:00 64111 0 collect.go:344] Waiting task_id: 43ae9038e4548310 [D2019-03-19T04:39:41.529533-07:00 64111 0 collect.go:344] Waiting task_id: 43ae9038e4548310 [D2019-03-19T04:39:43.349108-07:00 64111 0 collect.go:344] Waiting task_id: 43ae9038e4548310 [D2019-03-19T04:39:45.223469-07:00 64111 0 collect.go:344] Waiting task_id: 43ae9038e4548310 [D2019-03-19T04:39:47.199796-07:00 64111 0 collect.go:344] Waiting task_id: 43ae9038e4548310 [D2019-03-19T04:39:49.203660-07:00 64111 0 collect.go:344] Waiting task_id: 43ae9038e4548310 [D2019-03-19T04:39:51.269242-07:00 64111 0 collect.go:344] Waiting task_id: 43ae9038e4548310 [D2019-03-19T04:39:53.496684-07:00 64111 0 collect.go:344] Waiting task_id: 43ae9038e4548310 [D2019-03-19T04:39:55.728549-07:00 64111 0 collect.go:344] Waiting task_id: 43ae9038e4548310 [D2019-03-19T04:39:57.930251-07:00 64111 0 collect.go:344] Waiting task_id: 43ae9038e4548310 [D2019-03-19T04:40:00.288159-07:00 64111 0 collect.go:344] Waiting task_id: 43ae9038e4548310 [D2019-03-19T04:40:02.696401-07:00 64111 0 collect.go:344] Waiting task_id: 43ae9038e4548310 [D2019-03-19T04:40:05.201479-07:00 64111 0 collect.go:344] Waiting task_id: 43ae9038e4548310 [D2019-03-19T04:40:07.704706-07:00 64111 0 collect.go:344] Waiting task_id: 43ae9038e4548310 [D2019-03-19T04:40:10.523147-07:00 64111 0 collect.go:344] Waiting task_id: 43ae9038e4548310 [D2019-03-19T04:40:13.243501-07:00 64111 0 collect.go:344] Waiting task_id: 43ae9038e4548310 [D2019-03-19T04:40:16.078384-07:00 64111 0 collect.go:344] Waiting task_id: 43ae9038e4548310 [D2019-03-19T04:40:19.097571-07:00 64111 0 collect.go:344] Waiting task_id: 43ae9038e4548310 [D2019-03-19T04:40:22.054117-07:00 64111 0 collect.go:344] Waiting task_id: 43ae9038e4548310 [D2019-03-19T04:40:25.243942-07:00 64111 0 collect.go:344] Waiting task_id: 43ae9038e4548310 [D2019-03-19T04:40:29.156848-07:00 64111 0 collect.go:344] Waiting task_id: 43ae9038e4548310 [D2019-03-19T04:40:32.450397-07:00 64111 0 collect.go:344] Waiting task_id: 43ae9038e4548310 [D2019-03-19T04:40:35.841528-07:00 64111 0 collect.go:344] Waiting task_id: 43ae9038e4548310 [D2019-03-19T04:40:39.425904-07:00 64111 0 collect.go:344] Waiting task_id: 43ae9038e4548310 [D2019-03-19T04:40:43.244379-07:00 64111 0 collect.go:344] Waiting task_id: 43ae9038e4548310 [D2019-03-19T04:40:47.040068-07:00 64111 0 collect.go:344] Waiting task_id: 43ae9038e4548310 [D2019-03-19T04:40:51.029898-07:00 64111 0 collect.go:344] Waiting task_id: 43ae9038e4548310 [D2019-03-19T04:40:55.146294-07:00 64111 0 collect.go:344] Waiting task_id: 43ae9038e4548310 [D2019-03-19T04:40:59.480249-07:00 64111 0 collect.go:344] Waiting task_id: 43ae9038e4548310 [D2019-03-19T04:41:04.037650-07:00 64111 0 collect.go:344] Waiting task_id: 43ae9038e4548310 [D2019-03-19T04:41:08.624214-07:00 64111 0 collect.go:344] Waiting task_id: 43ae9038e4548310 [D2019-03-19T04:41:13.438395-07:00 64111 0 collect.go:344] Waiting task_id: 43ae9038e4548310 [D2019-03-19T04:41:18.440003-07:00 64111 0 collect.go:344] Waiting task_id: 43ae9038e4548310 [D2019-03-19T04:41:23.966202-07:00 64111 0 collect.go:344] Waiting task_id: 43ae9038e4548310 [D2019-03-19T04:41:29.165840-07:00 64111 0 collect.go:344] Waiting task_id: 43ae9038e4548310 [D2019-03-19T04:41:34.593284-07:00 64111 0 collect.go:344] Waiting task_id: 43ae9038e4548310 [D2019-03-19T04:41:40.175579-07:00 64111 0 collect.go:344] Waiting task_id: 43ae9038e4548310 [D2019-03-19T04:41:45.909576-07:00 64111 0 collect.go:344] Waiting task_id: 43ae9038e4548310 [D2019-03-19T04:41:51.868079-07:00 64111 0 collect.go:344] Waiting task_id: 43ae9038e4548310 [D2019-03-19T04:41:58.045557-07:00 64111 0 collect.go:344] Waiting task_id: 43ae9038e4548310 [D2019-03-19T04:42:04.413407-07:00 64111 0 collect.go:344] Waiting task_id: 43ae9038e4548310 [D2019-03-19T04:42:10.845915-07:00 64111 0 collect.go:344] Waiting task_id: 43ae9038e4548310 [D2019-03-19T04:42:17.603638-07:00 64111 0 collect.go:344] Waiting task_id: 43ae9038e4548310 [D2019-03-19T04:42:24.758019-07:00 64111 0 collect.go:344] Waiting task_id: 43ae9038e4548310 [D2019-03-19T04:42:31.994470-07:00 64111 0 collect.go:344] Waiting task_id: 43ae9038e4548310 [D2019-03-19T04:42:39.613800-07:00 64111 0 collect.go:344] Waiting task_id: 43ae9038e4548310 [D2019-03-19T04:42:47.261522-07:00 64111 0 collect.go:344] Waiting task_id: 43ae9038e4548310 [D2019-03-19T04:42:55.365951-07:00 64111 0 collect.go:344] Waiting task_id: 43ae9038e4548310 [D2019-03-19T04:43:03.713136-07:00 64111 0 collect.go:344] Waiting task_id: 43ae9038e4548310 [D2019-03-19T04:43:12.271527-07:00 64111 0 collect.go:344] Waiting task_id: 43ae9038e4548310 [D2019-03-19T04:43:21.249194-07:00 64111 0 collect.go:344] Waiting task_id: 43ae9038e4548310 [D2019-03-19T04:43:30.304859-07:00 64111 0 collect.go:344] Waiting task_id: 43ae9038e4548310 [D2019-03-19T04:43:39.608287-07:00 64111 0 collect.go:344] Waiting task_id: 43ae9038e4548310 [D2019-03-19T04:43:49.415555-07:00 64111 0 collect.go:344] Waiting task_id: 43ae9038e4548310 [D2019-03-19T04:43:59.477539-07:00 64111 0 collect.go:344] Waiting task_id: 43ae9038e4548310 [D2019-03-19T04:44:09.860980-07:00 64111 0 collect.go:344] Waiting task_id: 43ae9038e4548310 [D2019-03-19T04:44:20.492074-07:00 64111 0 collect.go:344] Waiting task_id: 43ae9038e4548310 [D2019-03-19T04:44:31.577441-07:00 64111 0 collect.go:344] Waiting task_id: 43ae9038e4548310 [D2019-03-19T04:44:43.030923-07:00 64111 0 collect.go:344] Waiting task_id: 43ae9038e4548310 [D2019-03-19T04:44:55.025645-07:00 64111 0 collect.go:344] Waiting task_id: 43ae9038e4548310 [D2019-03-19T04:45:07.378354-07:00 64111 0 collect.go:344] Waiting task_id: 43ae9038e4548310 [D2019-03-19T04:45:20.036840-07:00 64111 0 collect.go:344] Waiting task_id: 43ae9038e4548310 [D2019-03-19T04:45:33.291156-07:00 64111 0 collect.go:344] Waiting task_id: 43ae9038e4548310 [D2019-03-19T04:45:46.841494-07:00 64111 0 collect.go:344] Waiting task_id: 43ae9038e4548310 [D2019-03-19T04:46:00.796462-07:00 64111 0 collect.go:344] Waiting task_id: 43ae9038e4548310 [D2019-03-19T04:46:15.254337-07:00 64111 0 collect.go:344] Waiting task_id: 43ae9038e4548310 [D2019-03-19T04:46:30.177499-07:00 64111 0 collect.go:344] Waiting task_id: 43ae9038e4548310 [D2019-03-19T04:46:45.385651-07:00 64111 0 collect.go:344] Waiting task_id: 43ae9038e4548310 [D2019-03-19T04:47:01.642388-07:00 64111 0 collect.go:344] Waiting task_id: 43ae9038e4548310 [D2019-03-19T04:47:16.990408-07:00 64111 0 collect.go:344] Waiting task_id: 43ae9038e4548310 43ae9038e4548310: exit 0 [I 2019-03-19 04:38:31] About to install Xcode 10b61 in Xcode.app for ios [04:38:32:INFO] Using Xcode version 10.1 build 10B61 at /b/s/w/ir/Xcode.app/Contents/Developer [04:38:37:INFO] Start test attempt #0 for command [xcodebuild test-without-building -xctestrun /b/s/w/ioVQiiGW/run_1552995517.xctestrun -destination platform=iOS Simulator,OS=12.1,name=iPhone X -resultBundlePath /b/s/w/ioVQiiGW/12.1 iPhone X/attempt_0 -parallel-testing-enabled YES -parallel-testing-worker-count 4] [04:38:38:INFO] User defaults from command line: Erasing all simulators. Launching ['xcodebuild', 'test-without-building', '-xctestrun', '/b/s/w/ioVQiiGW/run_1552995517.xctestrun', '-destination', 'platform=iOS Simulator,OS=12.1,name=iPhone X', '-resultBundlePath', '/b/s/w/ioVQiiGW/12.1 iPhone X/attempt_0', '-parallel-testing-enabled', 'YES', '-parallel-testing-worker-count', '4'] with env {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'MAC_CHROMIUM_TMPDIR': '/b/s/w/it_q0G8N', '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': 'build588-m4', 'VIRTUAL_ENV': '/b/s/w/ir/.swarming_module_cache/vpython/b6ec90', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0W18aMqTAm/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/it_q0G8N/luci_ctx.sVKylM.json', 'CIPD_CACHE_DIR': '/b/s/cipd_cache/cache', 'XPC_SERVICE_NAME': 'org.swarm.bot.plist', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EsZBGDVFL5/Listeners', 'VPYTHON_VIRTUALENV_ROOT': '/b/s/w/ir/.swarming_module_cache/vpython', 'SWARMING_TASK_ID': '43ae9038e4548311', 'SHELL': '/bin/bash', 'NO_GCE_CHECK': 'False', 'TMPDIR': '/b/s/w/it_q0G8N', 'CIPD_PROTOCOL': 'v2', '__CF_USER_TEXT_ENCODING': '0x1F4:0x0:0x0', 'NSUnbufferedIO': 'YES'} [04:38:38:INFO] IDEBuildOperationResultBundlePath = /b/s/w/ioVQiiGW/12.1 iPhone X/attempt_0 [04:38:38:INFO] IDETestRunSpecificationPath = /b/s/w/ioVQiiGW/run_1552995517.xctestrun [04:38:38:INFO] [04:38:38:INFO] Testing started on 'iPhone X' [04:38:39:INFO] 2019-03-19 04:38:39.195 xcodebuild[8803:126155] IDETestOperationsObserverDebug: Writing diagnostic log for test session to: [04:38:39:INFO] /b/s/w/ioVQiiGW/12.1 iPhone X/attempt_0/1_Test/Diagnostics/ios_chrome_integration_egtests_module-8564AA8E-6AA9-44EF-BA00-F7E98A5C321B/ios_chrome_integration_egtests_module-D6476CD4-E8EB-4E1A-9DCB-21183055DD84/Session-ios_chrome_integration_egtests_module-2019-03-19_043839-cVmKXc.log [04:38:39:INFO] 2019-03-19 04:38:39.195 xcodebuild[8803:126121] [MT] IDETestOperationsObserverDebug: (5FE84239-3EAE-4545-96F4-8110D7017DE3) Beginning test session ios_chrome_integration_egtests_module-5FE84239-3EAE-4545-96F4-8110D7017DE3 at 2019-03-19 04:38:39.196 with Xcode 10B61 on target <DVTiPhoneSimulator: 0x7fb97e034320> { [04:38:39:INFO] SimDevice: Clone 1 of iPhone X (4DE56293-B4DD-4620-B7A0-D6274548822E, iOS 12.1, Shutdown) [04:38:39:INFO] } (12.1 (16B91)) [04:39:04:INFO] 2019-03-19 04:39:04.097 xcodebuild[8803:127564] IDETestOperationsObserverDebug: Writing diagnostic log for test session to: [04:39:04:INFO] /b/s/w/ioVQiiGW/12.1 iPhone X/attempt_0/1_Test/Diagnostics/ios_chrome_integration_egtests_module-8564AA8E-6AA9-44EF-BA00-F7E98A5C321B/ios_chrome_integration_egtests_module-D2A1BAC9-F008-46AA-99D3-A6D27BF8028A/Session-ios_chrome_integration_egtests_module-2019-03-19_043904-WF4wfW.log [04:39:04:INFO] 2019-03-19 04:39:04.097 xcodebuild[8803:126121] [MT] IDETestOperationsObserverDebug: (D3B95DBE-C9D6-4383-8C1D-049B06185013) Beginning test session ios_chrome_integration_egtests_module-D3B95DBE-C9D6-4383-8C1D-049B06185013 at 2019-03-19 04:39:04.097 with Xcode 10B61 on target <DVTiPhoneSimulator: 0x7fb97b5e3e30> { [04:39:04:INFO] SimDevice: Clone 2 of iPhone X (7B3CD0CD-1B6A-4578-9875-BEA2C3814E20, iOS 12.1, Shutdown) [04:39:04:INFO] } (12.1 (16B91)) [04:39:04:INFO] 2019-03-19 04:39:04.331 xcodebuild[8803:127582] IDETestOperationsObserverDebug: Writing diagnostic log for test session to: [04:39:04:INFO] /b/s/w/ioVQiiGW/12.1 iPhone X/attempt_0/1_Test/Diagnostics/ios_chrome_integration_egtests_module-8564AA8E-6AA9-44EF-BA00-F7E98A5C321B/ios_chrome_integration_egtests_module-5BA997EC-4BD4-4857-986D-86F2063B3CD3/Session-ios_chrome_integration_egtests_module-2019-03-19_043904-obyWod.log [04:39:04:INFO] 2019-03-19 04:39:04.332 xcodebuild[8803:126121] [MT] IDETestOperationsObserverDebug: (D81C83E9-BC4C-427B-B7C8-358927BE3603) Beginning test session ios_chrome_integration_egtests_module-D81C83E9-BC4C-427B-B7C8-358927BE3603 at 2019-03-19 04:39:04.332 with Xcode 10B61 on target <DVTiPhoneSimulator: 0x7fb97b756cf0> { [04:39:04:INFO] SimDevice: Clone 4 of iPhone X (D68CB1D8-3727-49AA-A0BB-E0E458A551FA, iOS 12.1, Shutdown) [04:39:04:INFO] } (12.1 (16B91)) [04:39:04:INFO] 2019-03-19 04:39:04.340 xcodebuild[8803:127588] IDETestOperationsObserverDebug: Writing diagnostic log for test session to: [04:39:04:INFO] /b/s/w/ioVQiiGW/12.1 iPhone X/attempt_0/1_Test/Diagnostics/ios_chrome_integration_egtests_module-8564AA8E-6AA9-44EF-BA00-F7E98A5C321B/ios_chrome_integration_egtests_module-B9542C5B-F646-4C1B-9851-7877D574CBB5/Session-ios_chrome_integration_egtests_module-2019-03-19_043904-s7dJJT.log [04:39:04:INFO] 2019-03-19 04:39:04.341 xcodebuild[8803:126121] [MT] IDETestOperationsObserverDebug: (57098166-5CF4-4AB1-9760-649D18809C08) Beginning test session ios_chrome_integration_egtests_module-57098166-5CF4-4AB1-9760-649D18809C08 at 2019-03-19 04:39:04.341 with Xcode 10B61 on target <DVTiPhoneSimulator: 0x7fb97dd71040> { [04:39:04:INFO] SimDevice: Clone 3 of iPhone X (A20EA1CC-86FB-4D4D-B71E-49CC54AA9980, iOS 12.1, Shutdown) [04:39:04:INFO] } (12.1 (16B91)) [04:39:08:INFO] Test suite 'CredentialManagerEGTest' started on 'Clone 1 of iPhone X - ios_chrome_integration_egtests.app (8924)' [04:39:19:INFO] Test case '-[CredentialManagerEGTest testNotificationAppearsOnAutoSignIn]' passed on 'Clone 1 of iPhone X - ios_chrome_integration_egtests.app (8924)' (8.060 seconds) [04:39:24:INFO] Test case '-[CredentialManagerEGTest testNotificationAppearsWhenTabIsActive]' passed on 'Clone 1 of iPhone X - ios_chrome_integration_egtests.app (8924)' (5.338 seconds) [04:39:24:INFO] Test suite 'UKMTestCase' started on 'Clone 1 of iPhone X - ios_chrome_integration_egtests.app (8924)' [04:40:34:INFO] Test case '-[UKMTestCase testConsentAddedButNoSync]' passed on 'Clone 1 of iPhone X - ios_chrome_integration_egtests.app (8924)' (69.604 seconds) [04:40:44:INFO] Test suite 'FormInputTestCase' started on 'Clone 2 of iPhone X - ios_chrome_integration_egtests.app (9261)' [04:40:49:INFO] Test suite 'NTPTilesTest' started on 'Clone 4 of iPhone X - ios_chrome_integration_egtests.app (9265)' [04:40:51:INFO] Test case '-[FormInputTestCase testCloseKeyboardWhenNotVisible]' passed on 'Clone 2 of iPhone X - ios_chrome_integration_egtests.app (9261)' (4.401 seconds) [04:40:53:INFO] Test suite 'BrowserStateServicesTestCase' started on 'Clone 3 of iPhone X - ios_chrome_integration_egtests.app (9268)' [04:41:01:INFO] Test case '-[BrowserStateServicesTestCase testConnectionToIdentityService]' passed on 'Clone 3 of iPhone X - ios_chrome_integration_egtests.app (9268)' (5.369 seconds) [04:41:01:INFO] Test suite 'HandoffManagerTestCase' started on 'Clone 3 of iPhone X - ios_chrome_integration_egtests.app (9268)' [04:41:06:INFO] Test case '-[FormInputTestCase testFindDefaultFormAssistControls]' passed on 'Clone 2 of iPhone X - ios_chrome_integration_egtests.app (9261)' (14.732 seconds) [04:41:06:INFO] Test suite 'TabUsageRecorderTestCase' started on 'Clone 2 of iPhone X - ios_chrome_integration_egtests.app (9261)' [04:41:11:INFO] Test case '-[NTPTilesTest testTopSitesTileAfterLoadURL]' passed on 'Clone 4 of iPhone X - ios_chrome_integration_egtests.app (9265)' (20.724 seconds) [04:41:17:INFO] Test case '-[UKMTestCase testIncognitoPlusRegular]' passed on 'Clone 1 of iPhone X - ios_chrome_integration_egtests.app (8924)' (43.181 seconds) [04:41:24:INFO] Test case '-[HandoffManagerTestCase testMultipleSwitchingTabs]' passed on 'Clone 3 of iPhone X - ios_chrome_integration_egtests.app (9268)' (23.315 seconds) [04:41:27:INFO] Test case '-[TabUsageRecorderTestCase testEvictedTabReloadBackgrounded]' passed on 'Clone 2 of iPhone X - ios_chrome_integration_egtests.app (9261)' (21.143 seconds) [04:41:29:INFO] Test case '-[HandoffManagerTestCase testNewTabPageEmptyURL]' passed on 'Clone 3 of iPhone X - ios_chrome_integration_egtests.app (9268)' (5.174 seconds) [04:41:46:INFO] Test case '-[HandoffManagerTestCase testSwitchBetweenNormalAndIncognitoTabs]' passed on 'Clone 3 of iPhone X - ios_chrome_integration_egtests.app (9268)' (17.047 seconds) [04:41:50:INFO] Test case '-[NTPTilesTest testTopSitesTileAfterRedirect]' passed on 'Clone 4 of iPhone X - ios_chrome_integration_egtests.app (9265)' (38.863 seconds) [04:41:50:INFO] Test suite 'CookiesTestCase' started on 'Clone 4 of iPhone X - ios_chrome_integration_egtests.app (9265)' [04:41:51:INFO] Test case '-[UKMTestCase testMetricsConsent]' passed on 'Clone 1 of iPhone X - ios_chrome_integration_egtests.app (8924)' (34.561 seconds) [04:41:54:INFO] Test case '-[HandoffManagerTestCase testTypicalURL]' passed on 'Clone 3 of iPhone X - ios_chrome_integration_egtests.app (9268)' (7.898 seconds) [04:42:02:INFO] Test case '-[TabUsageRecorderTestCase testEvictedTabReloadSettingsAndBack]' passed on 'Clone 2 of iPhone X - ios_chrome_integration_egtests.app (9261)' (34.877 seconds) [04:42:04:INFO] Test case '-[HandoffManagerTestCase testTypicalURLInNewIncognitoTab]' passed on 'Clone 3 of iPhone X - ios_chrome_integration_egtests.app (9268)' (10.350 seconds) [04:42:14:INFO] Test case '-[HandoffManagerTestCase testTypicalURLInNewTab]' passed on 'Clone 3 of iPhone X - ios_chrome_integration_egtests.app (9268)' (9.122 seconds) [04:42:14:INFO] Test suite 'ContextMenuTestCase' started on 'Clone 3 of iPhone X - ios_chrome_integration_egtests.app (9268)' [04:42:14:INFO] Test case '-[CookiesTestCase testClearIncognitoFromIncognito]' passed on 'Clone 4 of iPhone X - ios_chrome_integration_egtests.app (9265)' (23.595 seconds) [04:42:26:INFO] Test case '-[TabUsageRecorderTestCase testEvictedTabReloadSwitchToNTP]' passed on 'Clone 2 of iPhone X - ios_chrome_integration_egtests.app (9261)' (24.332 seconds) [04:42:29:INFO] Test case '-[ContextMenuTestCase testAppropriateContextMenu]' passed on 'Clone 3 of iPhone X - ios_chrome_integration_egtests.app (9268)' (14.790 seconds) [04:42:37:INFO] Test case '-[CookiesTestCase testClearIncognitoFromMain]' passed on 'Clone 4 of iPhone X - ios_chrome_integration_egtests.app (9265)' (22.571 seconds) [04:42:41:INFO] Test case '-[ContextMenuTestCase testContextMenuCancelSystemTouchesMetric]' passed on 'Clone 3 of iPhone X - ios_chrome_integration_egtests.app (9268)' (12.374 seconds) [04:42:44:INFO] Test case '-[UKMTestCase testRegularPlusIncognito]' passed on 'Clone 1 of iPhone X - ios_chrome_integration_egtests.app (8924)' (52.146 seconds) [04:42:51:INFO] Test case '-[TabUsageRecorderTestCase testEvictedTabSlowReload]' passed on 'Clone 2 of iPhone X - ios_chrome_integration_egtests.app (9261)' (24.443 seconds) [04:42:53:INFO] Test case '-[CookiesTestCase testShareCookiesBetweenTabs]' passed on 'Clone 4 of iPhone X - ios_chrome_integration_egtests.app (9265)' (15.583 seconds) [04:42:55:INFO] Test case '-[ContextMenuTestCase testContextMenuDisplayedOnImage]' passed on 'Clone 3 of iPhone X - ios_chrome_integration_egtests.app (9268)' (13.750 seconds) [04:43:06:INFO] Test case '-[ContextMenuTestCase testContextMenuElementFetchDurationMetric]' passed on 'Clone 3 of iPhone X - ios_chrome_integration_egtests.app (9268)' (11.600 seconds) [04:43:11:INFO] Test case '-[CookiesTestCase testSwitchToIncognito]' passed on 'Clone 4 of iPhone X - ios_chrome_integration_egtests.app (9265)' (18.182 seconds) [04:43:17:INFO] Test case '-[TabUsageRecorderTestCase testLinkClickNavigation]' passed on 'Clone 2 of iPhone X - ios_chrome_integration_egtests.app (9261)' (25.979 seconds) [04:43:19:INFO] Test case '-[ContextMenuTestCase testContextMenuOpenInNewTab]' passed on 'Clone 3 of iPhone X - ios_chrome_integration_egtests.app (9268)' (12.795 seconds) [04:43:26:INFO] Test case '-[TabUsageRecorderTestCase testLiveTabReloadBackgrounded]' passed on 'Clone 2 of iPhone X - ios_chrome_integration_egtests.app (9261)' (9.180 seconds) [04:43:28:INFO] Test case '-[ContextMenuTestCase testContextMenuSelectedTextCallout]' passed on 'Clone 3 of iPhone X - ios_chrome_integration_egtests.app (9268)' (8.537 seconds) [04:43:32:INFO] Test case '-[CookiesTestCase testSwitchToMain]' passed on 'Clone 4 of iPhone X - ios_chrome_integration_egtests.app (9265)' (21.395 seconds) [04:43:32:INFO] Test suite 'FeatureEngagementTestCase' started on 'Clone 4 of iPhone X - ios_chrome_integration_egtests.app (9265)' [04:43:35:INFO] Test case '-[TabUsageRecorderTestCase testOpenFromApp]' passed on 'Clone 2 of iPhone X - ios_chrome_integration_egtests.app (9261)' (8.925 seconds) [04:43:43:INFO] Test case '-[ContextMenuTestCase testDismissContextMenu]' passed on 'Clone 3 of iPhone X - ios_chrome_integration_egtests.app (9268)' (15.710 seconds) [04:43:45:INFO] Test case '-[FeatureEngagementTestCase testBadgedReadingListFeatureReadingListAlreadyUsed]' passed on 'Clone 4 of iPhone X - ios_chrome_integration_egtests.app (9265)' (12.787 seconds) [04:43:51:INFO] Test case '-[TabUsageRecorderTestCase testOpenLinkInNewTab]' passed on 'Clone 2 of iPhone X - ios_chrome_integration_egtests.app (9261)' (15.835 seconds) [04:43:55:INFO] Test case '-[ContextMenuTestCase testOpenImageInCurrentTabFromContextMenu]' passed on 'Clone 3 of iPhone X - ios_chrome_integration_egtests.app (9268)' (11.616 seconds) [04:44:00:INFO] Test case '-[FeatureEngagementTestCase testBadgedReadingListFeatureShouldShow]' passed on 'Clone 4 of iPhone X - ios_chrome_integration_egtests.app (9265)' (14.565 seconds) [04:44:08:INFO] Test case '-[FeatureEngagementTestCase testBadgedReadingListFeatureTooFewChromeOpens]' passed on 'Clone 4 of iPhone X - ios_chrome_integration_egtests.app (9265)' (8.508 seconds) [04:44:09:INFO] Test case '-[ContextMenuTestCase testOpenImageInNewTabFromContextMenu]' passed on 'Clone 3 of iPhone X - ios_chrome_integration_egtests.app (9268)' (14.038 seconds) [04:44:09:INFO] Test suite 'PrerenderTestCase' started on 'Clone 3 of iPhone X - ios_chrome_integration_egtests.app (9268)' [04:44:13:INFO] Test case '-[FeatureEngagementTestCase testBottomToolbarDontAppearOnNonSplitToolbar]' passed on 'Clone 4 of iPhone X - ios_chrome_integration_egtests.app (9265)' (4.806 seconds) [04:44:17:INFO] Test case '-[UKMTestCase testSecondaryPassphrase]' passed on 'Clone 1 of iPhone X - ios_chrome_integration_egtests.app (8924)' (93.884 seconds) [04:44:26:INFO] Test case '-[FeatureEngagementTestCase testNewTabTipPromoShouldShow]' passed on 'Clone 4 of iPhone X - ios_chrome_integration_egtests.app (9265)' (13.371 seconds) [04:44:42:INFO] Test case '-[TabUsageRecorderTestCase testPageLoadCountBeforeEvictedTab]' passed on 'Clone 2 of iPhone X - ios_chrome_integration_egtests.app (9261)' (51.205 seconds) [04:44:50:INFO] Test case '-[PrerenderTestCase testTapPrerenderSuggestions]' passed on 'Clone 3 of iPhone X - ios_chrome_integration_egtests.app (9268)' (41.133 seconds) [04:45:04:INFO] Test case '-[TabUsageRecorderTestCase testPageRedirect]' passed on 'Clone 2 of iPhone X - ios_chrome_integration_egtests.app (9261)' (22.237 seconds) [04:45:10:INFO] Test case '-[UKMTestCase testSingleDisableSync]' passed on 'Clone 1 of iPhone X - ios_chrome_integration_egtests.app (8924)' (52.424 seconds) [04:45:22:INFO] Test case '-[TabUsageRecorderTestCase testTabDeletion]' passed on 'Clone 2 of iPhone X - ios_chrome_integration_egtests.app (9261)' (17.456 seconds) [04:46:02:INFO] Test case '-[UKMTestCase testSingleSyncSignout]' passed on 'Clone 1 of iPhone X - ios_chrome_integration_egtests.app (8924)' (52.759 seconds) [04:46:03:INFO] 2019-03-19 04:46:03.455 xcodebuild[8803:126121] [MT] IDETestOperationsObserverDebug: 444.458 elapsed -- Testing started completed. [04:46:03:INFO] 2019-03-19 04:46:03.455 xcodebuild[8803:126121] [MT] IDETestOperationsObserverDebug: 0.000 sec, +0.000 sec -- start [04:46:03:INFO] 2019-03-19 04:46:03.455 xcodebuild[8803:126121] [MT] IDETestOperationsObserverDebug: 444.458 sec, +444.458 sec -- end [04:46:03:INFO] ** TEST EXECUTE SUCCEEDED ** [04:46:03:INFO] [04:46:27:INFO] Command ['xcodebuild', 'test-without-building', '-xctestrun', '/b/s/w/ioVQiiGW/run_1552995517.xctestrun', '-destination', 'platform=iOS Simulator,OS=12.1,name=iPhone X', '-resultBundlePath', '/b/s/w/ioVQiiGW/12.1 iPhone X/attempt_0', '-parallel-testing-enabled', 'YES', '-parallel-testing-worker-count', '4'] finished with 0 [04:46:27:DEBUG] Test ended. [04:46:27:DEBUG] Extracting test data. [04:46:27:DEBUG] Retrieving crash reports. [04:46:27:DEBUG] Retrieving derived data. [04:46:27:DEBUG] Making desktop screenshots. [04:46:27:DEBUG] Killing simulators. [04:46:32:DEBUG] Wiping simulator. Device wiped. [04:46:34:DEBUG] End of tear_down. 2019-03-19 04:47:17,817 - root: [DEBUG] Contents of task_output_dir: ['/b/s/w/ir/k/recipe_cleanup/09VjKCb/43ae9038e4548310'] 2019-03-19 04:47:17,817 - root: [WARNING] Expected output.json file missing: set(['/b/s/w/ir/k/recipe_cleanup/09VjKCb/43ae9038e4548310/output.json']) Found: [] Expected: ['/b/s/w/ir/k/recipe_cleanup/09VjKCb/43ae9038e4548310/output.json'] 2019-03-19 04:47:17,818 - root: [WARNING] No shard json files found in task_output_dir: '/b/s/w/ir/k/recipe_cleanup/09VjKCb' Found ['/b/s/w/ir/k/recipe_cleanup/09VjKCb/43ae9038e4548310'] 2019-03-19 04:47:17,818 - root: [DEBUG] Found shard_json_files: ['/b/s/w/ir/k/recipe_cleanup/09VjKCb/43ae9038e4548310/output.json'] 2019-03-19 04:47:17,818 - 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/tmpdyczFA.json --task-output-dir /b/s/w/ir/k/recipe_cleanup/09VjKCb -o /b/s/w/ir/tmp/t/tmpeuQK1c.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/tmpdyczFA.json', '--task-output-dir', '/b/s/w/ir/k/recipe_cleanup/09VjKCb', '-o', '/b/s/w/ir/tmp/t/tmpeuQK1c.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/tmpdyczFA.json', '--task-output-dir', '/b/s/w/ir/k/recipe_cleanup/09VjKCb', '-o', '/b/s/w/ir/tmp/t/tmpeuQK1c.json'] returned exit code 0