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/tmpEYR5Ct.json --task-output-dir /b/s/w/ir/k/recipe_cleanup/35Wi0bId --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/tmp9cVKX5merge_script_log --merge-additional-args '[]' --summary-json-file /b/s/w/ir/tmp/t/tmpNNuGT1.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/tmpvp4G7v.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/tmpEYR5Ct.json', '--task-output-dir', '/b/s/w/ir/k/recipe_cleanup/35Wi0bId', '--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/tmp9cVKX5merge_script_log', '--merge-additional-args', '[]', '--summary-json-file', '/b/s/w/ir/tmp/t/tmpNNuGT1.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/tmpvp4G7v.json'] env: {} env_prefixes: {'PATH': '/b/s/w/ir/cache/swarming_client'} env_suffixes: {} infra_step: False name_tokens: ('ios_chrome_bookmarks_egtests (iPhone X iOS 12.1) on Mac-10.13.6',) ok_ret: ALL_OK step_test_data: gen_default_step_test_data(...) trigger_specs: () full environment: Apple_PubSub_Socket_Render: /private/tmp/com.apple.launchd.mnIQeK7OVe/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: 53925 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/8915782415389077648 LOGDOG_STREAM_PROJECT: chromium LOGDOG_STREAM_SERVER_PATH: unix:/b/s/w/ir/tmp/ld.sock LOGNAME: chrome-bot LUCI_CONTEXT: /b/s/w/it1etsdL/luci_context.060250658 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.53DLkM3IPt/Listeners SWARMING_BOT_ID: vm124-m9 SWARMING_HEADLESS: 1 SWARMING_SERVER: https://chromium-swarm.appspot.com SWARMING_TASK_ID: 444c721e3a177811 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-04-18 21:02:41,878 - root: [DEBUG] Using task_output_dir: '/b/s/w/ir/k/recipe_cleanup/35Wi0bId' 2019-04-18 21:02:41,878 - root: [WARNING] task_output_dir '/b/s/w/ir/k/recipe_cleanup/35Wi0bId' already exists! 2019-04-18 21:02:41,878 - root: [WARNING] task_output_dir existing content: [] 2019-04-18 21:02:41,878 - 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/tmpvp4G7v.json -output-dir /b/s/w/ir/k/recipe_cleanup/35Wi0bId -task-summary-json /b/s/w/ir/tmp/t/tmpNNuGT1.json [D2019-04-18T21:02:41.898639-07:00 88950 0 auth.go:1265] Minting a new token {"key":"luci_ctx/16f18595a9562955720f5d1c153238451686de5c991384fed5b8508eca255db9", "scopes":"https://www.googleapis.com/auth/userinfo.email"} [D2019-04-18T21:02:41.898757-07:00 88950 0 luci_ctx.go:138] POST http://127.0.0.1:53913/rpc/LuciLocalAuthService.GetOAuthToken {"key":"luci_ctx/16f18595a9562955720f5d1c153238451686de5c991384fed5b8508eca255db9", "scopes":"https://www.googleapis.com/auth/userinfo.email"} [D2019-04-18T21:02:41.900670-07:00 88950 0 auth.go:1222] Token expires in 51m6.099384s {"key":"luci_ctx/16f18595a9562955720f5d1c153238451686de5c991384fed5b8508eca255db9", "scopes":"https://www.googleapis.com/auth/userinfo.email"} [D2019-04-18T21:02:43.499916-07:00 88950 0 collect.go:344] Waiting task_id: 444c81189ed8b610 [D2019-04-18T21:02:44.794927-07:00 88950 0 collect.go:344] Waiting task_id: 444c81189ed8b610 [D2019-04-18T21:02:46.425280-07:00 88950 0 collect.go:344] Waiting task_id: 444c81189ed8b610 [D2019-04-18T21:02:48.177926-07:00 88950 0 collect.go:344] Waiting task_id: 444c81189ed8b610 [D2019-04-18T21:02:49.734427-07:00 88950 0 collect.go:344] Waiting task_id: 444c81189ed8b610 [D2019-04-18T21:02:51.346870-07:00 88950 0 collect.go:344] Waiting task_id: 444c81189ed8b610 [D2019-04-18T21:02:53.302253-07:00 88950 0 collect.go:344] Waiting task_id: 444c81189ed8b610 [D2019-04-18T21:02:55.061735-07:00 88950 0 collect.go:344] Waiting task_id: 444c81189ed8b610 [D2019-04-18T21:02:56.921179-07:00 88950 0 collect.go:344] Waiting task_id: 444c81189ed8b610 [D2019-04-18T21:02:58.711170-07:00 88950 0 collect.go:344] Waiting task_id: 444c81189ed8b610 [D2019-04-18T21:03:00.530002-07:00 88950 0 collect.go:344] Waiting task_id: 444c81189ed8b610 [D2019-04-18T21:03:02.460876-07:00 88950 0 collect.go:344] Waiting task_id: 444c81189ed8b610 [D2019-04-18T21:03:04.447020-07:00 88950 0 collect.go:344] Waiting task_id: 444c81189ed8b610 [D2019-04-18T21:03:06.452490-07:00 88950 0 collect.go:344] Waiting task_id: 444c81189ed8b610 [D2019-04-18T21:03:08.532177-07:00 88950 0 collect.go:344] Waiting task_id: 444c81189ed8b610 [D2019-04-18T21:03:10.705658-07:00 88950 0 collect.go:344] Waiting task_id: 444c81189ed8b610 [D2019-04-18T21:03:13.147490-07:00 88950 0 collect.go:344] Waiting task_id: 444c81189ed8b610 [D2019-04-18T21:03:15.683920-07:00 88950 0 collect.go:344] Waiting task_id: 444c81189ed8b610 [D2019-04-18T21:03:18.092291-07:00 88950 0 collect.go:344] Waiting task_id: 444c81189ed8b610 [D2019-04-18T21:03:20.552580-07:00 88950 0 collect.go:344] Waiting task_id: 444c81189ed8b610 [D2019-04-18T21:03:23.174283-07:00 88950 0 collect.go:344] Waiting task_id: 444c81189ed8b610 [D2019-04-18T21:03:25.964426-07:00 88950 0 collect.go:344] Waiting task_id: 444c81189ed8b610 [D2019-04-18T21:03:28.662539-07:00 88950 0 collect.go:344] Waiting task_id: 444c81189ed8b610 [D2019-04-18T21:03:31.440312-07:00 88950 0 collect.go:344] Waiting task_id: 444c81189ed8b610 [D2019-04-18T21:03:34.484044-07:00 88950 0 collect.go:344] Waiting task_id: 444c81189ed8b610 [D2019-04-18T21:03:37.614348-07:00 88950 0 collect.go:344] Waiting task_id: 444c81189ed8b610 [D2019-04-18T21:03:40.891181-07:00 88950 0 collect.go:344] Waiting task_id: 444c81189ed8b610 [D2019-04-18T21:03:44.240408-07:00 88950 0 collect.go:344] Waiting task_id: 444c81189ed8b610 [D2019-04-18T21:03:47.659269-07:00 88950 0 collect.go:344] Waiting task_id: 444c81189ed8b610 [D2019-04-18T21:03:51.187320-07:00 88950 0 collect.go:344] Waiting task_id: 444c81189ed8b610 [D2019-04-18T21:03:55.390499-07:00 88950 0 collect.go:344] Waiting task_id: 444c81189ed8b610 [D2019-04-18T21:03:59.118142-07:00 88950 0 collect.go:344] Waiting task_id: 444c81189ed8b610 [D2019-04-18T21:04:04.035317-07:00 88950 0 collect.go:344] Waiting task_id: 444c81189ed8b610 [D2019-04-18T21:04:08.088075-07:00 88950 0 collect.go:344] Waiting task_id: 444c81189ed8b610 [D2019-04-18T21:04:12.322715-07:00 88950 0 collect.go:344] Waiting task_id: 444c81189ed8b610 [D2019-04-18T21:04:16.567975-07:00 88950 0 collect.go:344] Waiting task_id: 444c81189ed8b610 [D2019-04-18T21:04:21.015327-07:00 88950 0 collect.go:344] Waiting task_id: 444c81189ed8b610 [D2019-04-18T21:04:25.816821-07:00 88950 0 collect.go:344] Waiting task_id: 444c81189ed8b610 [D2019-04-18T21:04:30.719808-07:00 88950 0 collect.go:344] Waiting task_id: 444c81189ed8b610 [D2019-04-18T21:04:35.867716-07:00 88950 0 collect.go:344] Waiting task_id: 444c81189ed8b610 [D2019-04-18T21:04:40.970378-07:00 88950 0 collect.go:344] Waiting task_id: 444c81189ed8b610 [D2019-04-18T21:04:46.304541-07:00 88950 0 collect.go:344] Waiting task_id: 444c81189ed8b610 [D2019-04-18T21:04:51.888068-07:00 88950 0 collect.go:344] Waiting task_id: 444c81189ed8b610 [D2019-04-18T21:04:57.470380-07:00 88950 0 collect.go:344] Waiting task_id: 444c81189ed8b610 [D2019-04-18T21:05:03.207507-07:00 88950 0 collect.go:344] Waiting task_id: 444c81189ed8b610 [D2019-04-18T21:05:09.157599-07:00 88950 0 collect.go:344] Waiting task_id: 444c81189ed8b610 [D2019-04-18T21:05:15.414313-07:00 88950 0 collect.go:344] Waiting task_id: 444c81189ed8b610 [D2019-04-18T21:05:21.840868-07:00 88950 0 collect.go:344] Waiting task_id: 444c81189ed8b610 [D2019-04-18T21:05:28.801959-07:00 88950 0 collect.go:344] Waiting task_id: 444c81189ed8b610 [D2019-04-18T21:05:35.644022-07:00 88950 0 collect.go:344] Waiting task_id: 444c81189ed8b610 [D2019-04-18T21:05:42.670342-07:00 88950 0 collect.go:344] Waiting task_id: 444c81189ed8b610 [D2019-04-18T21:05:50.064095-07:00 88950 0 collect.go:344] Waiting task_id: 444c81189ed8b610 [D2019-04-18T21:05:57.624076-07:00 88950 0 collect.go:344] Waiting task_id: 444c81189ed8b610 [D2019-04-18T21:06:05.375707-07:00 88950 0 collect.go:344] Waiting task_id: 444c81189ed8b610 [D2019-04-18T21:06:13.563390-07:00 88950 0 collect.go:344] Waiting task_id: 444c81189ed8b610 [D2019-04-18T21:06:21.845100-07:00 88950 0 collect.go:344] Waiting task_id: 444c81189ed8b610 [D2019-04-18T21:06:30.536535-07:00 88950 0 collect.go:344] Waiting task_id: 444c81189ed8b610 [D2019-04-18T21:06:39.552335-07:00 88950 0 collect.go:344] Waiting task_id: 444c81189ed8b610 [D2019-04-18T21:06:48.747800-07:00 88950 0 collect.go:344] Waiting task_id: 444c81189ed8b610 [D2019-04-18T21:06:58.368157-07:00 88950 0 collect.go:344] Waiting task_id: 444c81189ed8b610 [D2019-04-18T21:07:08.286811-07:00 88950 0 collect.go:344] Waiting task_id: 444c81189ed8b610 444c81189ed8b610: exit 1 [I 2019-04-18 20:42:04] About to install Xcode 10b61 in Xcode.app for ios [08:42:05:INFO] Using Xcode version 10.1 build 10B61 at /b/s/w/ir/Xcode.app/Contents/Developer [08:42:05:INFO] Erasing all simulators. [08:42:12:INFO] Start test attempt #0 for command [xcodebuild test-without-building -xctestrun /b/s/w/ionkGJA7/run_1555645332.xctestrun -destination platform=iOS Simulator,OS=12.1,name=iPhone X -resultBundlePath /b/s/w/ionkGJA7/12.1 iPhone X/attempt_0 -parallel-testing-enabled YES -parallel-testing-worker-count 3] [08:42:12:INFO] Launching ['xcodebuild', 'test-without-building', '-xctestrun', '/b/s/w/ionkGJA7/run_1555645332.xctestrun', '-destination', 'platform=iOS Simulator,OS=12.1,name=iPhone X', '-resultBundlePath', '/b/s/w/ionkGJA7/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/itud6dTa', '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/367cfb/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': 'vm304-m9', 'VIRTUAL_ENV': '/b/s/w/ir/.swarming_module_cache/vpython/367cfb', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.acCrnAhVRN/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/itud6dTa/luci_ctx.DPPsFz.json', 'CIPD_CACHE_DIR': '/b/s/cipd_cache/cache', 'XPC_SERVICE_NAME': 'org.swarm.bot.plist', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.o9cJ5YaNs9/Listeners', 'VPYTHON_VIRTUALENV_ROOT': '/b/s/w/ir/.swarming_module_cache/vpython', 'SWARMING_TASK_ID': '444c81189ed8b611', 'SHELL': '/bin/bash', 'NO_GCE_CHECK': 'False', 'TMPDIR': '/b/s/w/itud6dTa', 'CIPD_PROTOCOL': 'v2', '__CF_USER_TEXT_ENCODING': '0x1F4:0x0:0x0', 'NSUnbufferedIO': 'YES'} [08:42:13:INFO] User defaults from command line: [08:42:13:INFO] IDEBuildOperationResultBundlePath = /b/s/w/ionkGJA7/12.1 iPhone X/attempt_0 [08:42:13:INFO] IDETestRunSpecificationPath = /b/s/w/ionkGJA7/run_1555645332.xctestrun [08:42:13:INFO] [08:42:14:INFO] Testing started on 'iPhone X' [08:42:14:INFO] 2019-04-18 20:42:14.334 xcodebuild[7457:110966] IDETestOperationsObserverDebug: Writing diagnostic log for test session to: [08:42:14:INFO] /b/s/w/ionkGJA7/12.1 iPhone X/attempt_0/1_Test/Diagnostics/ios_chrome_bookmarks_egtests_module-DCB82C8D-A519-48C4-88CC-9B14F7C361F6/ios_chrome_bookmarks_egtests_module-2CF955B7-CFDA-4FC9-8612-71C03EA463F9/Session-ios_chrome_bookmarks_egtests_module-2019-04-18_204214-Apsru8.log [08:42:14:INFO] 2019-04-18 20:42:14.334 xcodebuild[7457:110901] [MT] IDETestOperationsObserverDebug: (FC3F7B02-5197-45B7-BF2E-89515A3C0C0A) Beginning test session ios_chrome_bookmarks_egtests_module-FC3F7B02-5197-45B7-BF2E-89515A3C0C0A at 2019-04-18 20:42:14.334 with Xcode 10B61 on target <DVTiPhoneSimulator: 0x7fa9fe6ba070> { [08:42:14:INFO] SimDevice: Clone 1 of iPhone X (BBF4E600-622F-4FBD-A6F5-93A0F0195008, iOS 12.1, Shutdown) [08:42:14:INFO] } (12.1 (16B91)) [08:42:35:INFO] 2019-04-18 20:42:35.999 xcodebuild[7457:110990] IDETestOperationsObserverDebug: Writing diagnostic log for test session to: [08:42:36:INFO] /b/s/w/ionkGJA7/12.1 iPhone X/attempt_0/1_Test/Diagnostics/ios_chrome_bookmarks_egtests_module-DCB82C8D-A519-48C4-88CC-9B14F7C361F6/ios_chrome_bookmarks_egtests_module-026EE5E8-DEA7-4842-9CF7-5F05B7C9ED57/Session-ios_chrome_bookmarks_egtests_module-2019-04-18_204235-2Wm1Qm.log [08:42:36:INFO] 2019-04-18 20:42:35.999 xcodebuild[7457:110901] [MT] IDETestOperationsObserverDebug: (39FB6D2E-8476-448F-AA4F-6E4606A9533E) Beginning test session ios_chrome_bookmarks_egtests_module-39FB6D2E-8476-448F-AA4F-6E4606A9533E at 2019-04-18 20:42:35.999 with Xcode 10B61 on target <DVTiPhoneSimulator: 0x7fa9fe5a8c80> { [08:42:36:INFO] SimDevice: Clone 3 of iPhone X (73E6FB83-3C8F-4E48-96B6-6744F8EC8FF9, iOS 12.1, Shutdown) [08:42:36:INFO] } (12.1 (16B91)) [08:42:36:INFO] 2019-04-18 20:42:36.003 xcodebuild[7457:111021] IDETestOperationsObserverDebug: Writing diagnostic log for test session to: [08:42:36:INFO] /b/s/w/ionkGJA7/12.1 iPhone X/attempt_0/1_Test/Diagnostics/ios_chrome_bookmarks_egtests_module-DCB82C8D-A519-48C4-88CC-9B14F7C361F6/ios_chrome_bookmarks_egtests_module-F8DF31B8-7C06-4CC4-A8B1-7C5F560B5057/Session-ios_chrome_bookmarks_egtests_module-2019-04-18_204236-CZVkBi.log [08:42:36:INFO] 2019-04-18 20:42:36.003 xcodebuild[7457:110901] [MT] IDETestOperationsObserverDebug: (E7D95EDC-F7DD-4155-B7E7-E88040CE365F) Beginning test session ios_chrome_bookmarks_egtests_module-E7D95EDC-F7DD-4155-B7E7-E88040CE365F at 2019-04-18 20:42:36.004 with Xcode 10B61 on target <DVTiPhoneSimulator: 0x7fa9fe5a4350> { [08:42:36:INFO] SimDevice: Clone 2 of iPhone X (85A0B3FD-978D-42A4-8AD4-5839FB635592, iOS 12.1, Shutdown) [08:42:36:INFO] } (12.1 (16B91)) [08:42:43:INFO] Test suite 'BookmarksEntriesTestCase' started on 'Clone 1 of iPhone X - ios_chrome_bookmarks_egtests.app (7576)' [08:43:14:INFO] Test suite 'BookmarksTestCase' started on 'Clone 2 of iPhone X - ios_chrome_bookmarks_egtests.app (7804)' [08:43:15:INFO] Test suite 'BookmarksPromoTestCase' started on 'Clone 3 of iPhone X - ios_chrome_bookmarks_egtests.app (7803)' [08:43:39:INFO] Test case '-[BookmarksPromoTestCase testAutomaticSigninPromoDismiss]' passed on 'Clone 3 of iPhone X - ios_chrome_bookmarks_egtests.app (7803)' (21.950 seconds) [08:43:54:INFO] Test case '-[BookmarksPromoTestCase testPromoNoThanksMakeItDisappear]' passed on 'Clone 3 of iPhone X - ios_chrome_bookmarks_egtests.app (7803)' (15.506 seconds) [08:43:55:INFO] Test case '-[BookmarksEntriesTestCase testContextMenuForMixedSelection]' passed on 'Clone 1 of iPhone X - ios_chrome_bookmarks_egtests.app (7576)' (69.083 seconds) [08:44:08:INFO] Test case '-[BookmarksTestCase testAddRemoveBookmark]' passed on 'Clone 2 of iPhone X - ios_chrome_bookmarks_egtests.app (7804)' (51.975 seconds) [08:44:15:INFO] Test case '-[BookmarksPromoTestCase testPromoViewIsSeenOnlyInRootNode]' passed on 'Clone 3 of iPhone X - ios_chrome_bookmarks_egtests.app (7803)' (20.280 seconds) [08:44:33:INFO] Test case '-[BookmarksEntriesTestCase testContextMenuForMultipleURLSelection]' passed on 'Clone 1 of iPhone X - ios_chrome_bookmarks_egtests.app (7576)' (38.000 seconds) [08:44:35:INFO] Test case '-[BookmarksPromoTestCase testSignInPromoWithColdStateUsingPrimaryButton]' passed on 'Clone 3 of iPhone X - ios_chrome_bookmarks_egtests.app (7803)' (20.067 seconds) [08:44:57:INFO] Test case '-[BookmarksTestCase testBookmarkContextBarInMultipleSelectionModes]' passed on 'Clone 2 of iPhone X - ios_chrome_bookmarks_egtests.app (7804)' (48.807 seconds) [08:44:58:INFO] Test case '-[BookmarksPromoTestCase testSignInPromoWithWarmStateUsingPrimaryButton]' passed on 'Clone 3 of iPhone X - ios_chrome_bookmarks_egtests.app (7803)' (22.855 seconds) [08:45:12:INFO] Test case '-[BookmarksEntriesTestCase testContextMenuForSingleURLSelection]' passed on 'Clone 1 of iPhone X - ios_chrome_bookmarks_egtests.app (7576)' (38.943 seconds) [08:45:21:INFO] Test case '-[BookmarksPromoTestCase testSignInPromoWithWarmStateUsingSecondaryButton]' passed on 'Clone 3 of iPhone X - ios_chrome_bookmarks_egtests.app (7803)' (22.996 seconds) [08:45:21:INFO] Test suite 'BookmarksSearchTestCase' started on 'Clone 3 of iPhone X - ios_chrome_bookmarks_egtests.app (7803)' [08:45:37:INFO] Test case '-[BookmarksSearchTestCase testDisablesSearchOnEditMode]' passed on 'Clone 3 of iPhone X - ios_chrome_bookmarks_egtests.app (7803)' (16.274 seconds) [08:45:47:INFO] Test case '-[BookmarksTestCase testBookmarkContextBarInSingleSelectionModes]' passed on 'Clone 2 of iPhone X - ios_chrome_bookmarks_egtests.app (7804)' (49.711 seconds) [08:45:47:INFO] Test case '-[BookmarksEntriesTestCase testCopyFunctionalityOnSingleURL]' passed on 'Clone 1 of iPhone X - ios_chrome_bookmarks_egtests.app (7576)' (35.126 seconds) [08:46:01:INFO] Test case '-[BookmarksTestCase testBookmarkContextBarShown]' passed on 'Clone 2 of iPhone X - ios_chrome_bookmarks_egtests.app (7804)' (14.583 seconds) [08:46:16:INFO] Test case '-[BookmarksSearchTestCase testMoveFunctionalityOnMultipleUrlSelection]' passed on 'Clone 3 of iPhone X - ios_chrome_bookmarks_egtests.app (7803)' (38.945 seconds) [08:46:22:INFO] Test case '-[BookmarksTestCase testBookmarkMultipleTabs]' passed on 'Clone 2 of iPhone X - ios_chrome_bookmarks_egtests.app (7804)' (20.474 seconds) [08:46:25:INFO] Test case '-[BookmarksEntriesTestCase testDeleteMultipleNodes]' passed on 'Clone 1 of iPhone X - ios_chrome_bookmarks_egtests.app (7576)' (38.134 seconds) [08:46:30:INFO] Test case '-[BookmarksSearchTestCase testSearchBarShownOnMobileBookmarks]' passed on 'Clone 3 of iPhone X - ios_chrome_bookmarks_egtests.app (7803)' (14.216 seconds) [08:46:42:INFO] Test case '-[BookmarksSearchTestCase testSearchBarShownOnRoot]' passed on 'Clone 3 of iPhone X - ios_chrome_bookmarks_egtests.app (7803)' (11.558 seconds) [08:46:49:INFO] Test case '-[BookmarksTestCase testBookmarksExceedsScreenHeight]' passed on 'Clone 2 of iPhone X - ios_chrome_bookmarks_egtests.app (7804)' (27.033 seconds) [08:46:55:INFO] Test case '-[BookmarksTestCase testBookmarksURLDisabled]' passed on 'Clone 2 of iPhone X - ios_chrome_bookmarks_egtests.app (7804)' (5.892 seconds) [08:47:00:INFO] Test case '-[BookmarksEntriesTestCase testDeleteSingleURLNode]' passed on 'Clone 1 of iPhone X - ios_chrome_bookmarks_egtests.app (7576)' (34.674 seconds) [08:47:01:INFO] Test case '-[BookmarksSearchTestCase testSearchCancelRestoresNodeBookmarks]' passed on 'Clone 3 of iPhone X - ios_chrome_bookmarks_egtests.app (7803)' (19.803 seconds) [08:47:18:INFO] Test case '-[BookmarksSearchTestCase testSearchDisablesNewFolderButtonOnNavigationBar]' passed on 'Clone 3 of iPhone X - ios_chrome_bookmarks_egtests.app (7803)' (16.681 seconds) [08:47:31:INFO] Test case '-[BookmarksTestCase testCachePositionIsRecreated]' passed on 'Clone 2 of iPhone X - ios_chrome_bookmarks_egtests.app (7804)' (35.678 seconds) [08:47:41:INFO] Test case '-[BookmarksSearchTestCase testSearchEditModeDeleteOnMultipleURL]' passed on 'Clone 3 of iPhone X - ios_chrome_bookmarks_egtests.app (7803)' (22.395 seconds) [08:47:44:INFO] Test case '-[BookmarksEntriesTestCase testEditTextOnSingleURL]' failed on 'Clone 1 of iPhone X - ios_chrome_bookmarks_egtests.app (7576)' (43.437 seconds) [08:48:07:INFO] Test case '-[BookmarksTestCase testCachePositionIsRecreatedWhenNodeIsMoved]' passed on 'Clone 2 of iPhone X - ios_chrome_bookmarks_egtests.app (7804)' (36.127 seconds) [08:48:07:INFO] Test case '-[BookmarksSearchTestCase testSearchEditModeEditOnSingleURL]' passed on 'Clone 3 of iPhone X - ios_chrome_bookmarks_egtests.app (7803)' (26.705 seconds) [08:48:19:INFO] Test case '-[BookmarksEntriesTestCase testLongPressOnSingleURL]' passed on 'Clone 1 of iPhone X - ios_chrome_bookmarks_egtests.app (7576)' (35.422 seconds) [08:48:33:INFO] Test case '-[BookmarksSearchTestCase testSearchEditPossibleOnRoot]' passed on 'Clone 3 of iPhone X - ios_chrome_bookmarks_egtests.app (7803)' (25.558 seconds) [08:48:36:INFO] Test case '-[BookmarksTestCase testCachePositionIsResetWhenNodeIsDeleted]' passed on 'Clone 2 of iPhone X - ios_chrome_bookmarks_egtests.app (7804)' (29.528 seconds) [08:48:52:INFO] Test case '-[BookmarksSearchTestCase testSearchFolderCanBeSwipedToDelete]' passed on 'Clone 3 of iPhone X - ios_chrome_bookmarks_egtests.app (7803)' (18.867 seconds) [08:48:55:INFO] Test case '-[BookmarksEntriesTestCase testMoveCancelledWhenAllSelectionDeleted]' passed on 'Clone 1 of iPhone X - ios_chrome_bookmarks_egtests.app (7576)' (35.711 seconds) [08:48:59:INFO] Test case '-[BookmarksTestCase testCachePositionIsResetWhenNodeIsPermanentAndEmpty]' passed on 'Clone 2 of iPhone X - ios_chrome_bookmarks_egtests.app (7804)' (23.016 seconds) [08:49:30:INFO] Test case '-[BookmarksSearchTestCase testSearchFolders]' passed on 'Clone 3 of iPhone X - ios_chrome_bookmarks_egtests.app (7803)' (38.464 seconds) [08:49:42:INFO] Test case '-[BookmarksTestCase testEmptyBackgroundAndSelectButton]' passed on 'Clone 2 of iPhone X - ios_chrome_bookmarks_egtests.app (7804)' (42.999 seconds) [08:49:46:INFO] Test case '-[BookmarksSearchTestCase testSearchHidesNavigationBar]' passed on 'Clone 3 of iPhone X - ios_chrome_bookmarks_egtests.app (7803)' (16.137 seconds) [08:50:03:INFO] Test case '-[BookmarksEntriesTestCase testMoveFunctionalityOnMixedSelection]' passed on 'Clone 1 of iPhone X - ios_chrome_bookmarks_egtests.app (7576)' (67.860 seconds) [08:50:07:INFO] Test case '-[BookmarksTestCase testKeyboardCommandsNotRegistered_EditBookmark]' passed on 'Clone 2 of iPhone X - ios_chrome_bookmarks_egtests.app (7804)' (25.218 seconds) [08:50:12:INFO] Test case '-[BookmarksSearchTestCase testSearchLongPressEditOnFolder]' passed on 'Clone 3 of iPhone X - ios_chrome_bookmarks_egtests.app (7803)' (25.646 seconds) [08:50:18:INFO] Test case '-[BookmarksTestCase testKeyboardCommandsRegistered_AddBookmark]' passed on 'Clone 2 of iPhone X - ios_chrome_bookmarks_egtests.app (7804)' (10.638 seconds) [08:50:38:INFO] Test case '-[BookmarksSearchTestCase testSearchLongPressEditOnURL]' passed on 'Clone 3 of iPhone X - ios_chrome_bookmarks_egtests.app (7803)' (25.613 seconds) [08:50:57:INFO] Test case '-[BookmarksTestCase testMoveDoesSaveOnSave]' passed on 'Clone 2 of iPhone X - ios_chrome_bookmarks_egtests.app (7804)' (38.688 seconds) [08:50:58:INFO] Test case '-[BookmarksSearchTestCase testSearchLongPressOnScrimCancelsSearchController]' passed on 'Clone 3 of iPhone X - ios_chrome_bookmarks_egtests.app (7803)' (20.735 seconds) [08:51:00:INFO] Test case '-[BookmarksEntriesTestCase testMoveFunctionalityOnMultipleUrlSelection]' passed on 'Clone 1 of iPhone X - ios_chrome_bookmarks_egtests.app (7576)' (57.302 seconds) [08:51:21:INFO] Test case '-[BookmarksTestCase testNavigateBackWithGesture]' passed on 'Clone 2 of iPhone X - ios_chrome_bookmarks_egtests.app (7804)' (24.134 seconds) [08:51:21:INFO] Test suite 'BookmarksAccessibilityTestCase' started on 'Clone 2 of iPhone X - ios_chrome_bookmarks_egtests.app (7804)' [08:51:24:INFO] Test case '-[BookmarksSearchTestCase testSearchResults]' passed on 'Clone 3 of iPhone X - ios_chrome_bookmarks_egtests.app (7803)' (25.401 seconds) [08:51:42:INFO] Test case '-[BookmarksSearchTestCase testSearchScrimShownWhenSearchBoxEnabled]' passed on 'Clone 3 of iPhone X - ios_chrome_bookmarks_egtests.app (7803)' (18.244 seconds) [08:51:42:INFO] Test case '-[BookmarksAccessibilityTestCase testAccessibilityOnBookmarksDeleteUndo]' passed on 'Clone 2 of iPhone X - ios_chrome_bookmarks_egtests.app (7804)' (21.124 seconds) [08:52:02:INFO] Test case '-[BookmarksSearchTestCase testSearchTapOnScrimCancelsSearchController]' passed on 'Clone 3 of iPhone X - ios_chrome_bookmarks_egtests.app (7803)' (20.354 seconds) [08:52:03:INFO] Test case '-[BookmarksAccessibilityTestCase testAccessibilityOnBookmarksEditPage]' passed on 'Clone 2 of iPhone X - ios_chrome_bookmarks_egtests.app (7804)' (21.363 seconds) [08:52:22:INFO] Test case '-[BookmarksSearchTestCase testSearchUrlCanBeSwipedToDelete]' passed on 'Clone 3 of iPhone X - ios_chrome_bookmarks_egtests.app (7803)' (19.728 seconds) [08:52:26:INFO] Test case '-[BookmarksAccessibilityTestCase testAccessibilityOnBookmarksFolderEditPage]' passed on 'Clone 2 of iPhone X - ios_chrome_bookmarks_egtests.app (7804)' (22.168 seconds) [08:52:31:INFO] Test case '-[BookmarksEntriesTestCase testMoveOnSingleURL]' passed on 'Clone 1 of iPhone X - ios_chrome_bookmarks_egtests.app (7576)' (91.586 seconds) [08:52:38:INFO] Test case '-[BookmarksAccessibilityTestCase testAccessibilityOnBookmarksLandingPage]' passed on 'Clone 2 of iPhone X - ios_chrome_bookmarks_egtests.app (7804)' (12.381 seconds) [08:52:39:INFO] Test case '-[BookmarksSearchTestCase testSearchWithNoResults]' passed on 'Clone 3 of iPhone X - ios_chrome_bookmarks_egtests.app (7803)' (16.566 seconds) [08:52:39:INFO] Test suite 'BookmarksFoldersTestCase' started on 'Clone 3 of iPhone X - ios_chrome_bookmarks_egtests.app (7803)' [08:53:00:INFO] Test case '-[BookmarksAccessibilityTestCase testAccessibilityOnBookmarksMovePage]' passed on 'Clone 2 of iPhone X - ios_chrome_bookmarks_egtests.app (7804)' (22.312 seconds) [08:53:05:INFO] Test case '-[BookmarksFoldersTestCase testAddBookmarkInNewFolder]' passed on 'Clone 3 of iPhone X - ios_chrome_bookmarks_egtests.app (7803)' (26.070 seconds) [08:53:22:INFO] Test case '-[BookmarksFoldersTestCase testCantDeleteFolderBeingEdited]' passed on 'Clone 3 of iPhone X - ios_chrome_bookmarks_egtests.app (7803)' (17.794 seconds) [08:53:25:INFO] Test case '-[BookmarksAccessibilityTestCase testAccessibilityOnBookmarksMoveToNewFolderPage]' passed on 'Clone 2 of iPhone X - ios_chrome_bookmarks_egtests.app (7804)' (24.762 seconds) [08:53:41:INFO] Test case '-[BookmarksAccessibilityTestCase testAccessibilityOnBookmarksSelect]' passed on 'Clone 2 of iPhone X - ios_chrome_bookmarks_egtests.app (7804)' (15.868 seconds) [08:53:44:INFO] Test case '-[BookmarksFoldersTestCase testContextBarForSingleFolderSelection]' passed on 'Clone 3 of iPhone X - ios_chrome_bookmarks_egtests.app (7803)' (21.349 seconds) [08:53:56:INFO] Test case '-[BookmarksAccessibilityTestCase testAccessibilityOnMobileBookmarks]' passed on 'Clone 2 of iPhone X - ios_chrome_bookmarks_egtests.app (7804)' (15.163 seconds) [08:54:05:INFO] Test case '-[BookmarksFoldersTestCase testContextMenuForMultipleFolderSelection]' passed on 'Clone 3 of iPhone X - ios_chrome_bookmarks_egtests.app (7803)' (20.846 seconds) [08:54:09:INFO] Test case '-[BookmarksEntriesTestCase testOpenSingleBookmarkInNormalAndIncognitoTab]' passed on 'Clone 1 of iPhone X - ios_chrome_bookmarks_egtests.app (7576)' (98.035 seconds) [08:54:41:INFO] Test case '-[BookmarksFoldersTestCase testCreateNewFolderWhileMovingBookmarks]' passed on 'Clone 3 of iPhone X - ios_chrome_bookmarks_egtests.app (7803)' (36.761 seconds) [08:54:43:INFO] Test case '-[BookmarksEntriesTestCase testSwipeToDeleteDisabledInEditMode]' passed on 'Clone 1 of iPhone X - ios_chrome_bookmarks_egtests.app (7576)' (33.307 seconds) [08:55:08:INFO] Test case '-[BookmarksFoldersTestCase testCreateNewFolderWithContextBar]' passed on 'Clone 3 of iPhone X - ios_chrome_bookmarks_egtests.app (7803)' (26.825 seconds) [08:55:26:INFO] Test case '-[BookmarksEntriesTestCase testUndoDeleteBookmarkFromEditScreen]' passed on 'Clone 1 of iPhone X - ios_chrome_bookmarks_egtests.app (7576)' (42.894 seconds) [08:55:29:INFO] Test case '-[BookmarksFoldersTestCase testDeleteSingleFolderNode]' passed on 'Clone 3 of iPhone X - ios_chrome_bookmarks_egtests.app (7803)' (20.889 seconds) [08:56:01:INFO] Test case '-[BookmarksEntriesTestCase testUndoDeleteBookmarkFromSwipe]' passed on 'Clone 1 of iPhone X - ios_chrome_bookmarks_egtests.app (7576)' (35.359 seconds) [08:56:47:INFO] Test case '-[BookmarksFoldersTestCase testEditFunctionalityOnSingleFolder]' passed on 'Clone 3 of iPhone X - ios_chrome_bookmarks_egtests.app (7803)' (78.009 seconds) [08:57:11:INFO] Test case '-[BookmarksFoldersTestCase testLongPressOnSingleFolder]' passed on 'Clone 3 of iPhone X - ios_chrome_bookmarks_egtests.app (7803)' (23.886 seconds) [08:57:48:INFO] Test case '-[BookmarksFoldersTestCase testMoveFunctionalityOnMultipleFolder]' passed on 'Clone 3 of iPhone X - ios_chrome_bookmarks_egtests.app (7803)' (37.314 seconds) [08:58:31:INFO] Test case '-[BookmarksFoldersTestCase testMoveFunctionalityOnSingleFolder]' passed on 'Clone 3 of iPhone X - ios_chrome_bookmarks_egtests.app (7803)' (42.596 seconds) [08:59:14:INFO] Test case '-[BookmarksFoldersTestCase testNavigateAwayFromFolderBeingEdited]' passed on 'Clone 3 of iPhone X - ios_chrome_bookmarks_egtests.app (7803)' (43.524 seconds) [09:00:18:INFO] Test case '-[BookmarksFoldersTestCase testNewFolderNameCommittedOnNavigatingAway]' passed on 'Clone 3 of iPhone X - ios_chrome_bookmarks_egtests.app (7803)' (63.686 seconds) [09:00:59:INFO] Test case '-[BookmarksFoldersTestCase testStickyDefaultFolder]' passed on 'Clone 3 of iPhone X - ios_chrome_bookmarks_egtests.app (7803)' (40.790 seconds) [09:01:31:INFO] Test case '-[BookmarksFoldersTestCase testWhenCurrentFolderDeletedInBackground]' passed on 'Clone 3 of iPhone X - ios_chrome_bookmarks_egtests.app (7803)' (32.098 seconds) [09:01:39:INFO] 2019-04-18 21:01:39.454 xcodebuild[7457:110901] [MT] IDETestOperationsObserverDebug: 1165.424 elapsed -- Testing started completed. [09:01:39:INFO] 2019-04-18 21:01:39.454 xcodebuild[7457:110901] [MT] IDETestOperationsObserverDebug: 0.000 sec, +0.000 sec -- start [09:01:39:INFO] 2019-04-18 21:01:39.455 xcodebuild[7457:110901] [MT] IDETestOperationsObserverDebug: 1165.424 sec, +1165.424 sec -- end [09:01:39:INFO] Failing tests: [09:01:39:INFO] -[BookmarksEntriesTestCase testEditTextOnSingleURL] [09:01:39:INFO] -[BookmarksEntriesTestCase testEditTextOnSingleURL] [09:01:39:INFO] ** TEST EXECUTE FAILED ** [09:01:39:INFO] [09:01:57:INFO] Command ['xcodebuild', 'test-without-building', '-xctestrun', '/b/s/w/ionkGJA7/run_1555645332.xctestrun', '-destination', 'platform=iOS Simulator,OS=12.1,name=iPhone X', '-resultBundlePath', '/b/s/w/ionkGJA7/12.1 iPhone X/attempt_0', '-parallel-testing-enabled', 'YES', '-parallel-testing-worker-count', '3'] finished with 65 [09:01:57:INFO] Screenshots for failure "-[BookmarksEntriesTestCase testEditTextOnSingleURL]" in "/b/s/w/ionkGJA7/12.1 iPhone X/attempt_0/failures/BookmarksEntriesTestCase_testEditTextOnSingleURL" [09:01:57:INFO] Start test attempt #1 for command [xcodebuild test-without-building -xctestrun /b/s/w/ionkGJA7/run_1555646517.xctestrun -destination platform=iOS Simulator,OS=12.1,name=iPhone X -resultBundlePath /b/s/w/ionkGJA7/12.1 iPhone X/attempt_1 -parallel-testing-enabled YES -parallel-testing-worker-count 1] [09:01:57:INFO] Launching ['xcodebuild', 'test-without-building', '-xctestrun', '/b/s/w/ionkGJA7/run_1555646517.xctestrun', '-destination', 'platform=iOS Simulator,OS=12.1,name=iPhone X', '-resultBundlePath', '/b/s/w/ionkGJA7/12.1 iPhone X/attempt_1', '-parallel-testing-enabled', 'YES', '-parallel-testing-worker-count', '1'] with env {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'MAC_CHROMIUM_TMPDIR': '/b/s/w/itud6dTa', '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/367cfb/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': 'vm304-m9', 'VIRTUAL_ENV': '/b/s/w/ir/.swarming_module_cache/vpython/367cfb', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.acCrnAhVRN/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/itud6dTa/luci_ctx.DPPsFz.json', 'CIPD_CACHE_DIR': '/b/s/cipd_cache/cache', 'XPC_SERVICE_NAME': 'org.swarm.bot.plist', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.o9cJ5YaNs9/Listeners', 'VPYTHON_VIRTUALENV_ROOT': '/b/s/w/ir/.swarming_module_cache/vpython', 'SWARMING_TASK_ID': '444c81189ed8b611', 'SHELL': '/bin/bash', 'NO_GCE_CHECK': 'False', 'TMPDIR': '/b/s/w/itud6dTa', 'CIPD_PROTOCOL': 'v2', '__CF_USER_TEXT_ENCODING': '0x1F4:0x0:0x0', 'NSUnbufferedIO': 'YES'} [09:01:58:INFO] User defaults from command line: [09:01:58:INFO] IDEBuildOperationResultBundlePath = /b/s/w/ionkGJA7/12.1 iPhone X/attempt_1 [09:01:58:INFO] IDETestRunSpecificationPath = /b/s/w/ionkGJA7/run_1555646517.xctestrun [09:01:58:INFO] [09:01:58:INFO] Testing started on 'iPhone X' [09:01:59:INFO] 2019-04-18 21:01:59.100 xcodebuild[8163:127556] IDETestOperationsObserverDebug: Writing diagnostic log for test session to: [09:01:59:INFO] /b/s/w/ionkGJA7/12.1 iPhone X/attempt_1/1_Test/Diagnostics/ios_chrome_bookmarks_egtests_module-1C8C90AC-7C8B-4451-A2EE-F878979E5167/ios_chrome_bookmarks_egtests_module-C2950456-52A3-4CFD-BA14-E2C687EFA9CC/Session-ios_chrome_bookmarks_egtests_module-2019-04-18_210159-0bsY81.log [09:01:59:INFO] 2019-04-18 21:01:59.100 xcodebuild[8163:127478] [MT] IDETestOperationsObserverDebug: (D416699D-1CA7-4508-AEBB-C4D54207B3E2) Beginning test session ios_chrome_bookmarks_egtests_module-D416699D-1CA7-4508-AEBB-C4D54207B3E2 at 2019-04-18 21:01:59.100 with Xcode 10B61 on target <DVTiPhoneSimulator: 0x7ffe0cd67560> { [09:01:59:INFO] SimDevice: Clone 1 of iPhone X (B1DAB253-A04C-4DC5-888E-9911307719BB, iOS 12.1, Shutdown) [09:01:59:INFO] } (12.1 (16B91)) [09:02:16:INFO] Test suite 'BookmarksEntriesTestCase' started on 'Clone 1 of iPhone X - ios_chrome_bookmarks_egtests.app (8290)' [09:03:11:INFO] Test case '-[BookmarksEntriesTestCase testEditTextOnSingleURL]' failed on 'Clone 1 of iPhone X - ios_chrome_bookmarks_egtests.app (8290)' (47.598 seconds) [09:03:15:INFO] 2019-04-18 21:03:15.683 xcodebuild[8163:127478] [MT] IDETestOperationsObserverDebug: 76.835 elapsed -- Testing started completed. [09:03:15:INFO] 2019-04-18 21:03:15.683 xcodebuild[8163:127478] [MT] IDETestOperationsObserverDebug: 0.000 sec, +0.000 sec -- start [09:03:15:INFO] 2019-04-18 21:03:15.683 xcodebuild[8163:127478] [MT] IDETestOperationsObserverDebug: 76.835 sec, +76.835 sec -- end [09:03:15:INFO] Failing tests: [09:03:15:INFO] -[BookmarksEntriesTestCase testEditTextOnSingleURL] [09:03:15:INFO] -[BookmarksEntriesTestCase testEditTextOnSingleURL] [09:03:15:INFO] ** TEST EXECUTE FAILED ** [09:03:15:INFO] [09:03:21:INFO] Command ['xcodebuild', 'test-without-building', '-xctestrun', '/b/s/w/ionkGJA7/run_1555646517.xctestrun', '-destination', 'platform=iOS Simulator,OS=12.1,name=iPhone X', '-resultBundlePath', '/b/s/w/ionkGJA7/12.1 iPhone X/attempt_1', '-parallel-testing-enabled', 'YES', '-parallel-testing-worker-count', '1'] finished with 65 [09:03:21:INFO] Screenshots for failure "-[BookmarksEntriesTestCase testEditTextOnSingleURL]" in "/b/s/w/ionkGJA7/12.1 iPhone X/attempt_1/failures/BookmarksEntriesTestCase_testEditTextOnSingleURL" [09:03:21:INFO] Start test attempt #2 for command [xcodebuild test-without-building -xctestrun /b/s/w/ionkGJA7/run_1555646601.xctestrun -destination platform=iOS Simulator,OS=12.1,name=iPhone X -resultBundlePath /b/s/w/ionkGJA7/12.1 iPhone X/attempt_2 -parallel-testing-enabled YES -parallel-testing-worker-count 1] [09:03:21:INFO] Launching ['xcodebuild', 'test-without-building', '-xctestrun', '/b/s/w/ionkGJA7/run_1555646601.xctestrun', '-destination', 'platform=iOS Simulator,OS=12.1,name=iPhone X', '-resultBundlePath', '/b/s/w/ionkGJA7/12.1 iPhone X/attempt_2', '-parallel-testing-enabled', 'YES', '-parallel-testing-worker-count', '1'] with env {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'MAC_CHROMIUM_TMPDIR': '/b/s/w/itud6dTa', '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/367cfb/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': 'vm304-m9', 'VIRTUAL_ENV': '/b/s/w/ir/.swarming_module_cache/vpython/367cfb', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.acCrnAhVRN/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/itud6dTa/luci_ctx.DPPsFz.json', 'CIPD_CACHE_DIR': '/b/s/cipd_cache/cache', 'XPC_SERVICE_NAME': 'org.swarm.bot.plist', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.o9cJ5YaNs9/Listeners', 'VPYTHON_VIRTUALENV_ROOT': '/b/s/w/ir/.swarming_module_cache/vpython', 'SWARMING_TASK_ID': '444c81189ed8b611', 'SHELL': '/bin/bash', 'NO_GCE_CHECK': 'False', 'TMPDIR': '/b/s/w/itud6dTa', 'CIPD_PROTOCOL': 'v2', '__CF_USER_TEXT_ENCODING': '0x1F4:0x0:0x0', 'NSUnbufferedIO': 'YES'} [09:03:22:INFO] User defaults from command line: [09:03:22:INFO] IDEBuildOperationResultBundlePath = /b/s/w/ionkGJA7/12.1 iPhone X/attempt_2 [09:03:22:INFO] IDETestRunSpecificationPath = /b/s/w/ionkGJA7/run_1555646601.xctestrun [09:03:22:INFO] [09:03:22:INFO] Testing started on 'iPhone X' [09:03:22:INFO] 2019-04-18 21:03:22.787 xcodebuild[8312:129604] IDETestOperationsObserverDebug: Writing diagnostic log for test session to: [09:03:22:INFO] /b/s/w/ionkGJA7/12.1 iPhone X/attempt_2/1_Test/Diagnostics/ios_chrome_bookmarks_egtests_module-D0826D8D-A645-4F82-856D-8F114E23D042/ios_chrome_bookmarks_egtests_module-8441DCB8-863D-43B9-8B76-DCE27C85AC7E/Session-ios_chrome_bookmarks_egtests_module-2019-04-18_210322-kcB1z8.log [09:03:22:INFO] 2019-04-18 21:03:22.787 xcodebuild[8312:129583] [MT] IDETestOperationsObserverDebug: (08631D47-11EE-4E90-8E9D-115B329178C7) Beginning test session ios_chrome_bookmarks_egtests_module-08631D47-11EE-4E90-8E9D-115B329178C7 at 2019-04-18 21:03:22.787 with Xcode 10B61 on target <DVTiPhoneSimulator: 0x7fb5fedbbad0> { [09:03:22:INFO] SimDevice: Clone 1 of iPhone X (63FD68B0-4959-4638-8A28-22378AD22E3E, iOS 12.1, Shutdown) [09:03:22:INFO] } (12.1 (16B91)) [09:03:42:INFO] Test suite 'BookmarksEntriesTestCase' started on 'Clone 1 of iPhone X - ios_chrome_bookmarks_egtests.app (8435)' [09:04:36:INFO] Test case '-[BookmarksEntriesTestCase testEditTextOnSingleURL]' failed on 'Clone 1 of iPhone X - ios_chrome_bookmarks_egtests.app (8435)' (47.664 seconds) [09:04:41:INFO] 2019-04-18 21:04:41.576 xcodebuild[8312:129583] [MT] IDETestOperationsObserverDebug: 78.984 elapsed -- Testing started completed. [09:04:41:INFO] 2019-04-18 21:04:41.576 xcodebuild[8312:129583] [MT] IDETestOperationsObserverDebug: 0.000 sec, +0.000 sec -- start [09:04:41:INFO] 2019-04-18 21:04:41.576 xcodebuild[8312:129583] [MT] IDETestOperationsObserverDebug: 78.984 sec, +78.984 sec -- end [09:04:41:INFO] Failing tests: [09:04:41:INFO] -[BookmarksEntriesTestCase testEditTextOnSingleURL] [09:04:41:INFO] -[BookmarksEntriesTestCase testEditTextOnSingleURL] [09:04:41:INFO] ** TEST EXECUTE FAILED ** [09:04:41:INFO] [09:04:47:INFO] Command ['xcodebuild', 'test-without-building', '-xctestrun', '/b/s/w/ionkGJA7/run_1555646601.xctestrun', '-destination', 'platform=iOS Simulator,OS=12.1,name=iPhone X', '-resultBundlePath', '/b/s/w/ionkGJA7/12.1 iPhone X/attempt_2', '-parallel-testing-enabled', 'YES', '-parallel-testing-worker-count', '1'] finished with 65 [09:04:47:INFO] Screenshots for failure "-[BookmarksEntriesTestCase testEditTextOnSingleURL]" in "/b/s/w/ionkGJA7/12.1 iPhone X/attempt_2/failures/BookmarksEntriesTestCase_testEditTextOnSingleURL" [09:04:47:INFO] Start test attempt #3 for command [xcodebuild test-without-building -xctestrun /b/s/w/ionkGJA7/run_1555646687.xctestrun -destination platform=iOS Simulator,OS=12.1,name=iPhone X -resultBundlePath /b/s/w/ionkGJA7/12.1 iPhone X/attempt_3 -parallel-testing-enabled YES -parallel-testing-worker-count 1] [09:04:47:INFO] Launching ['xcodebuild', 'test-without-building', '-xctestrun', '/b/s/w/ionkGJA7/run_1555646687.xctestrun', '-destination', 'platform=iOS Simulator,OS=12.1,name=iPhone X', '-resultBundlePath', '/b/s/w/ionkGJA7/12.1 iPhone X/attempt_3', '-parallel-testing-enabled', 'YES', '-parallel-testing-worker-count', '1'] with env {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'MAC_CHROMIUM_TMPDIR': '/b/s/w/itud6dTa', '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/367cfb/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': 'vm304-m9', 'VIRTUAL_ENV': '/b/s/w/ir/.swarming_module_cache/vpython/367cfb', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.acCrnAhVRN/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/itud6dTa/luci_ctx.DPPsFz.json', 'CIPD_CACHE_DIR': '/b/s/cipd_cache/cache', 'XPC_SERVICE_NAME': 'org.swarm.bot.plist', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.o9cJ5YaNs9/Listeners', 'VPYTHON_VIRTUALENV_ROOT': '/b/s/w/ir/.swarming_module_cache/vpython', 'SWARMING_TASK_ID': '444c81189ed8b611', 'SHELL': '/bin/bash', 'NO_GCE_CHECK': 'False', 'TMPDIR': '/b/s/w/itud6dTa', 'CIPD_PROTOCOL': 'v2', '__CF_USER_TEXT_ENCODING': '0x1F4:0x0:0x0', 'NSUnbufferedIO': 'YES'} [09:04:48:INFO] User defaults from command line: [09:04:48:INFO] IDEBuildOperationResultBundlePath = /b/s/w/ionkGJA7/12.1 iPhone X/attempt_3 [09:04:48:INFO] IDETestRunSpecificationPath = /b/s/w/ionkGJA7/run_1555646687.xctestrun [09:04:48:INFO] [09:04:48:INFO] Testing started on 'iPhone X' [09:04:48:INFO] 2019-04-18 21:04:48.679 xcodebuild[8457:131734] IDETestOperationsObserverDebug: Writing diagnostic log for test session to: [09:04:48:INFO] /b/s/w/ionkGJA7/12.1 iPhone X/attempt_3/1_Test/Diagnostics/ios_chrome_bookmarks_egtests_module-55AAD5E3-6BD4-45AA-9510-6EC4F3E1CC89/ios_chrome_bookmarks_egtests_module-D91D1594-E6D6-44E2-8186-2FBE32812BBF/Session-ios_chrome_bookmarks_egtests_module-2019-04-18_210448-EZauip.log [09:04:48:INFO] 2019-04-18 21:04:48.679 xcodebuild[8457:131659] [MT] IDETestOperationsObserverDebug: (093B434F-F5EB-461B-A331-511882FB2EDA) Beginning test session ios_chrome_bookmarks_egtests_module-093B434F-F5EB-461B-A331-511882FB2EDA at 2019-04-18 21:04:48.679 with Xcode 10B61 on target <DVTiPhoneSimulator: 0x7fbfd4c3ed10> { [09:04:48:INFO] SimDevice: Clone 1 of iPhone X (E1D0A8A3-B479-4546-930A-87320BA6ED2F, iOS 12.1, Shutdown) [09:04:48:INFO] } (12.1 (16B91)) [09:05:05:INFO] Test suite 'BookmarksEntriesTestCase' started on 'Clone 1 of iPhone X - ios_chrome_bookmarks_egtests.app (8577)' [09:05:51:INFO] Test case '-[BookmarksEntriesTestCase testEditTextOnSingleURL]' failed on 'Clone 1 of iPhone X - ios_chrome_bookmarks_egtests.app (8577)' (38.480 seconds) [09:05:55:INFO] 2019-04-18 21:05:55.815 xcodebuild[8457:131659] [MT] IDETestOperationsObserverDebug: 67.338 elapsed -- Testing started completed. [09:05:55:INFO] 2019-04-18 21:05:55.816 xcodebuild[8457:131659] [MT] IDETestOperationsObserverDebug: 0.000 sec, +0.000 sec -- start [09:05:55:INFO] 2019-04-18 21:05:55.816 xcodebuild[8457:131659] [MT] IDETestOperationsObserverDebug: 67.338 sec, +67.338 sec -- end [09:05:55:INFO] Failing tests: [09:05:55:INFO] -[BookmarksEntriesTestCase testEditTextOnSingleURL] [09:05:55:INFO] -[BookmarksEntriesTestCase testEditTextOnSingleURL] [09:05:55:INFO] ** TEST EXECUTE FAILED ** [09:05:55:INFO] [09:06:01:INFO] Command ['xcodebuild', 'test-without-building', '-xctestrun', '/b/s/w/ionkGJA7/run_1555646687.xctestrun', '-destination', 'platform=iOS Simulator,OS=12.1,name=iPhone X', '-resultBundlePath', '/b/s/w/ionkGJA7/12.1 iPhone X/attempt_3', '-parallel-testing-enabled', 'YES', '-parallel-testing-worker-count', '1'] finished with 65 2019-04-18 21:07:15,586 - root: [DEBUG] Contents of task_output_dir: ['/b/s/w/ir/k/recipe_cleanup/35Wi0bId/444c81189ed8b610'] 2019-04-18 21:07:15,587 - root: [WARNING] Expected output.json file missing: set(['/b/s/w/ir/k/recipe_cleanup/35Wi0bId/444c81189ed8b610/output.json']) Found: [] Expected: ['/b/s/w/ir/k/recipe_cleanup/35Wi0bId/444c81189ed8b610/output.json'] 2019-04-18 21:07:15,587 - root: [WARNING] No shard json files found in task_output_dir: '/b/s/w/ir/k/recipe_cleanup/35Wi0bId' Found ['/b/s/w/ir/k/recipe_cleanup/35Wi0bId/444c81189ed8b610'] 2019-04-18 21:07:15,587 - root: [DEBUG] Found shard_json_files: ['/b/s/w/ir/k/recipe_cleanup/35Wi0bId/444c81189ed8b610/output.json'] 2019-04-18 21:07:15,587 - 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/tmpNNuGT1.json --task-output-dir /b/s/w/ir/k/recipe_cleanup/35Wi0bId -o /b/s/w/ir/tmp/t/tmpEYR5Ct.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/tmpNNuGT1.json', '--task-output-dir', '/b/s/w/ir/k/recipe_cleanup/35Wi0bId', '-o', '/b/s/w/ir/tmp/t/tmpEYR5Ct.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/tmpNNuGT1.json', '--task-output-dir', '/b/s/w/ir/k/recipe_cleanup/35Wi0bId', '-o', '/b/s/w/ir/tmp/t/tmpEYR5Ct.json'] returned exit code 0