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/tmpfRBzUq.json --task-output-dir /b/s/w/ir/k/recipe_cleanup/54_0cp7WHv --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/tmpnIqLhGmerge_script_log --merge-additional-args '[]' --summary-json-file /b/s/w/ir/tmp/t/tmpB09Kcj.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/tmpklYLKR.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/tmpfRBzUq.json', '--task-output-dir', '/b/s/w/ir/k/recipe_cleanup/54_0cp7WHv', '--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/tmpnIqLhGmerge_script_log', '--merge-additional-args', '[]', '--summary-json-file', '/b/s/w/ir/tmp/t/tmpB09Kcj.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/tmpklYLKR.json'] env: {} env_prefixes: {'PATH': '/b/s/w/ir/cache/swarming_client'} env_suffixes: {} infra_step: False name_tokens: ('ios_chrome_ui_egtests (iPhone X iOS 12.1) shard 0 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.iPqM1Qktve/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: 61831 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/8915520686788118848 LOGDOG_STREAM_PROJECT: chromium LOGDOG_STREAM_SERVER_PATH: unix:/b/s/w/ir/tmp/ld.sock LOGNAME: chrome-bot LUCI_CONTEXT: /b/s/w/itRU3E2p/luci_context.299968890 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.C3RIlXylzW/Listeners SWARMING_BOT_ID: vm124-m9 SWARMING_HEADLESS: 1 SWARMING_SERVER: https://chromium-swarm.appspot.com SWARMING_TASK_ID: 445b52c472f59d11 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-21 18:22:17,535 - root: [DEBUG] Using task_output_dir: '/b/s/w/ir/k/recipe_cleanup/54_0cp7WHv' 2019-04-21 18:22:17,535 - root: [WARNING] task_output_dir '/b/s/w/ir/k/recipe_cleanup/54_0cp7WHv' already exists! 2019-04-21 18:22:17,535 - root: [WARNING] task_output_dir existing content: [] 2019-04-21 18:22:17,535 - 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/tmpklYLKR.json -output-dir /b/s/w/ir/k/recipe_cleanup/54_0cp7WHv -task-summary-json /b/s/w/ir/tmp/t/tmpB09Kcj.json [D2019-04-21T18:22:17.555554-07:00 44493 0 auth.go:1265] Minting a new token {"key":"luci_ctx/af31990707dfd132ff936158bf84cdfcebcc999f6f35eaed6e81e1188596a602", "scopes":"https://www.googleapis.com/auth/userinfo.email"} [D2019-04-21T18:22:17.555813-07:00 44493 0 luci_ctx.go:138] POST http://127.0.0.1:61819/rpc/LuciLocalAuthService.GetOAuthToken {"key":"luci_ctx/af31990707dfd132ff936158bf84cdfcebcc999f6f35eaed6e81e1188596a602", "scopes":"https://www.googleapis.com/auth/userinfo.email"} [D2019-04-21T18:22:17.557749-07:00 44493 0 auth.go:1222] Token expires in 51m44.442276s {"key":"luci_ctx/af31990707dfd132ff936158bf84cdfcebcc999f6f35eaed6e81e1188596a602", "scopes":"https://www.googleapis.com/auth/userinfo.email"} 445b5e8978437210: exit 1 [I 2019-04-21 17:58:33] About to install Xcode 10b61 in Xcode.app for ios [05:58:34:INFO] Using Xcode version 10.1 build 10B61 at /b/s/w/ir/Xcode.app/Contents/Developer [05:58:34:INFO] Erasing all simulators. [05:58:41:INFO] Start test attempt #0 for command [xcodebuild test-without-building -xctestrun /b/s/w/ioJvj6rR/run_1555894721.xctestrun -destination platform=iOS Simulator,OS=12.1,name=iPhone X -resultBundlePath /b/s/w/ioJvj6rR/12.1 iPhone X/attempt_0 -parallel-testing-enabled YES -parallel-testing-worker-count 3] [05:58:41:INFO] Launching ['xcodebuild', 'test-without-building', '-xctestrun', '/b/s/w/ioJvj6rR/run_1555894721.xctestrun', '-destination', 'platform=iOS Simulator,OS=12.1,name=iPhone X', '-resultBundlePath', '/b/s/w/ioJvj6rR/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/itXWcNt1', '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': 'build215-m4', 'VIRTUAL_ENV': '/b/s/w/ir/.swarming_module_cache/vpython/367cfb', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N5LLHajBN1/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/itXWcNt1/luci_ctx.ravAWe.json', 'CIPD_CACHE_DIR': '/b/s/cipd_cache/cache', 'XPC_SERVICE_NAME': 'org.swarm.bot.plist', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8GdXOnxYk5/Listeners', 'VPYTHON_VIRTUALENV_ROOT': '/b/s/w/ir/.swarming_module_cache/vpython', 'SWARMING_TASK_ID': '445b5e8978437211', 'SHELL': '/bin/bash', 'NO_GCE_CHECK': 'False', 'TMPDIR': '/b/s/w/itXWcNt1', 'CIPD_PROTOCOL': 'v2', '__CF_USER_TEXT_ENCODING': '0x1F4:0x0:0x0', 'NSUnbufferedIO': 'YES'} [05:58:42:INFO] User defaults from command line: [05:58:42:INFO] IDEBuildOperationResultBundlePath = /b/s/w/ioJvj6rR/12.1 iPhone X/attempt_0 [05:58:42:INFO] IDETestRunSpecificationPath = /b/s/w/ioJvj6rR/run_1555894721.xctestrun [05:58:42:INFO] [05:58:42:INFO] Testing started on 'iPhone X' [05:58:43:INFO] 2019-04-21 17:58:43.123 xcodebuild[25350:348378] IDETestOperationsObserverDebug: Writing diagnostic log for test session to: [05:58:43:INFO] /b/s/w/ioJvj6rR/12.1 iPhone X/attempt_0/1_Test/Diagnostics/ios_chrome_ui_egtests_module-5658654F-2E62-40D7-802D-38416D8AB5B6/ios_chrome_ui_egtests_module-0BACAF32-673F-4771-9169-D0C0FAFA7AD4/Session-ios_chrome_ui_egtests_module-2019-04-21_175843-ZhvIUH.log [05:58:43:INFO] 2019-04-21 17:58:43.124 xcodebuild[25350:348323] [MT] IDETestOperationsObserverDebug: (11D7CC45-E5B3-4160-ABF0-CD887271975A) Beginning test session ios_chrome_ui_egtests_module-11D7CC45-E5B3-4160-ABF0-CD887271975A at 2019-04-21 17:58:43.124 with Xcode 10B61 on target <DVTiPhoneSimulator: 0x7f9d4a7dbd50> { [05:58:43:INFO] SimDevice: Clone 1 of iPhone X (14787612-D349-4D73-B081-85DE97518234, iOS 12.1, Shutdown) [05:58:43:INFO] } (12.1 (16B91)) [05:59:11:INFO] 2019-04-21 17:59:11.515 xcodebuild[25350:348323] [MT] IDETestOperationsObserverDebug: (F07644F1-D428-42F3-9DA6-19F242E17816) Beginning test session ios_chrome_ui_egtests_module-F07644F1-D428-42F3-9DA6-19F242E17816 at 2019-04-21 17:59:11.515 with Xcode 10B61 on target <DVTiPhoneSimulator: 0x7f9d4a8847a0> { [05:59:11:INFO] SimDevice: Clone 3 of iPhone X (042EC415-ED10-453E-AF7A-DA60CB5E3005, iOS 12.1, Shutdown) [05:59:11:INFO] } (12.1 (16B91)) [05:59:11:INFO] 2019-04-21 17:59:11.515 xcodebuild[25350:349349] IDETestOperationsObserverDebug: Writing diagnostic log for test session to: [05:59:11:INFO] /b/s/w/ioJvj6rR/12.1 iPhone X/attempt_0/1_Test/Diagnostics/ios_chrome_ui_egtests_module-5658654F-2E62-40D7-802D-38416D8AB5B6/ios_chrome_ui_egtests_module-8D3088F6-86F5-42A0-A595-F16C3E0E7E38/Session-ios_chrome_ui_egtests_module-2019-04-21_175911-aG39pb.log [05:59:11:INFO] 2019-04-21 17:59:11.518 xcodebuild[25350:348323] [MT] IDETestOperationsObserverDebug: (93880C7E-0EAC-4C89-BE53-600B0755244E) Beginning test session ios_chrome_ui_egtests_module-93880C7E-0EAC-4C89-BE53-600B0755244E at 2019-04-21 17:59:11.518 with Xcode 10B61 on target <DVTiPhoneSimulator: 0x7f9d47f82200> { [05:59:11:INFO] SimDevice: Clone 2 of iPhone X (903ED552-D0B8-471A-B237-001BCCA9EA2F, iOS 12.1, Shutdown) [05:59:11:INFO] } (12.1 (16B91)) [05:59:11:INFO] 2019-04-21 17:59:11.519 xcodebuild[25350:349349] IDETestOperationsObserverDebug: Writing diagnostic log for test session to: [05:59:11:INFO] /b/s/w/ioJvj6rR/12.1 iPhone X/attempt_0/1_Test/Diagnostics/ios_chrome_ui_egtests_module-5658654F-2E62-40D7-802D-38416D8AB5B6/ios_chrome_ui_egtests_module-99F4B6B7-8373-4914-8465-F3322DD29CB7/Session-ios_chrome_ui_egtests_module-2019-04-21_175911-NHgmGK.log [05:59:12:INFO] Test suite 'ActivityServiceControllerTestCase' started on 'Clone 1 of iPhone X - ios_chrome_ui_egtests.app (25472)' [05:59:21:INFO] Test case '-[ActivityServiceControllerTestCase testActivityServiceControllerCantPrintUnprintablePages]' passed on 'Clone 1 of iPhone X - ios_chrome_ui_egtests.app (25472)' (7.533 seconds) [05:59:31:INFO] Test case '-[ActivityServiceControllerTestCase testActivityServiceControllerIsDisabled]' passed on 'Clone 1 of iPhone X - ios_chrome_ui_egtests.app (25472)' (9.702 seconds) [05:59:36:INFO] Test case '-[ActivityServiceControllerTestCase testOpenActivityServiceControllerAndCopy]' passed on 'Clone 1 of iPhone X - ios_chrome_ui_egtests.app (25472)' (4.311 seconds) [05:59:36:INFO] Test suite 'BrowserViewControllerTestCase' started on 'Clone 1 of iPhone X - ios_chrome_ui_egtests.app (25472)' [05:59:49:INFO] Test case '-[BrowserViewControllerTestCase testFixForCrbug801165]' passed on 'Clone 1 of iPhone X - ios_chrome_ui_egtests.app (25472)' (13.653 seconds) [06:00:16:INFO] Test case '-[BrowserViewControllerTestCase testJavaScriptInOmnibox]' passed on 'Clone 1 of iPhone X - ios_chrome_ui_egtests.app (25472)' (26.876 seconds) [06:00:25:INFO] Test case '-[BrowserViewControllerTestCase testOpenURLFromNTP]' passed on 'Clone 1 of iPhone X - ios_chrome_ui_egtests.app (25472)' (8.383 seconds) [06:00:33:INFO] Test case '-[BrowserViewControllerTestCase testOpenURLFromTab]' passed on 'Clone 1 of iPhone X - ios_chrome_ui_egtests.app (25472)' (8.250 seconds) [06:00:43:INFO] Test case '-[BrowserViewControllerTestCase testOpenURLFromTabSwitcher]' passed on 'Clone 1 of iPhone X - ios_chrome_ui_egtests.app (25472)' (10.290 seconds) [06:00:53:INFO] Test suite 'AdaptiveToolbarTestCase' started on 'Clone 2 of iPhone X - ios_chrome_ui_egtests.app (25709)' [06:00:53:INFO] Test suite 'HistoryUITestCase' started on 'Clone 3 of iPhone X - ios_chrome_ui_egtests.app (25712)' [06:00:54:INFO] Test case '-[BrowserViewControllerTestCase testWebStateVisibilityAfterTabSwitch]' passed on 'Clone 1 of iPhone X - ios_chrome_ui_egtests.app (25472)' (10.251 seconds) [06:00:54:INFO] Test suite 'FindInPageTestCase' started on 'Clone 1 of iPhone X - ios_chrome_ui_egtests.app (25472)' [06:01:00:INFO] Test case '-[AdaptiveToolbarTestCase testBookmarkButton]' passed on 'Clone 2 of iPhone X - ios_chrome_ui_egtests.app (25709)' (5.487 seconds) [06:01:10:INFO] Test case '-[FindInPageTestCase testAccessibilityOnFindInPage]' passed on 'Clone 1 of iPhone X - ios_chrome_ui_egtests.app (25472)' (16.232 seconds) [06:01:15:INFO] Test case '-[HistoryUITestCase testAccessibilityOnHistory]' passed on 'Clone 3 of iPhone X - ios_chrome_ui_egtests.app (25712)' (20.395 seconds) [06:01:21:INFO] Test case '-[AdaptiveToolbarTestCase testBottomToolbarHeightAfterClosingTab]' passed on 'Clone 2 of iPhone X - ios_chrome_ui_egtests.app (25709)' (21.412 seconds) [06:01:26:INFO] Test case '-[AdaptiveToolbarTestCase testCancelOmniboxEdit]' passed on 'Clone 2 of iPhone X - ios_chrome_ui_egtests.app (25709)' (5.166 seconds) [06:01:27:INFO] Test case '-[FindInPageTestCase testFindInPage]' passed on 'Clone 1 of iPhone X - ios_chrome_ui_egtests.app (25472)' (17.366 seconds) [06:01:44:INFO] Test case '-[HistoryUITestCase testClearBrowsingHistory]' passed on 'Clone 3 of iPhone X - ios_chrome_ui_egtests.app (25712)' (28.732 seconds) [06:01:54:INFO] Test case '-[AdaptiveToolbarTestCase testFocusOmniboxFromOtherOrientation]' passed on 'Clone 2 of iPhone X - ios_chrome_ui_egtests.app (25709)' (27.435 seconds) [06:02:05:INFO] Test case '-[HistoryUITestCase testContextMenuCopy]' passed on 'Clone 3 of iPhone X - ios_chrome_ui_egtests.app (25712)' (21.270 seconds) [06:02:15:INFO] Test case '-[HistoryUITestCase testContextMenuOpenInNewIncognitoTab]' failed on 'Clone 3 of iPhone X - ios_chrome_ui_egtests.app (25712)' (9.703 seconds) [06:02:17:INFO] Test case '-[FindInPageTestCase testFindInPageRetainsSearchTerm]' passed on 'Clone 1 of iPhone X - ios_chrome_ui_egtests.app (25472)' (50.178 seconds) [06:02:18:INFO] Test suite 'FirstRunTestCase' started on 'Clone 1 of iPhone X - ios_chrome_ui_egtests.app (25472)' [06:02:28:INFO] Test case '-[FirstRunTestCase testDismissFirstRun]' passed on 'Clone 1 of iPhone X - ios_chrome_ui_egtests.app (25472)' (9.952 seconds) [06:02:31:INFO] Test case '-[AdaptiveToolbarTestCase testFocusOmniboxFromPortrait]' passed on 'Clone 2 of iPhone X - ios_chrome_ui_egtests.app (25709)' (37.299 seconds) [06:02:37:INFO] Test case '-[HistoryUITestCase testContextMenuOpenInNewTab]' passed on 'Clone 3 of iPhone X - ios_chrome_ui_egtests.app (25712)' (21.401 seconds) [06:02:38:INFO] Test case '-[FirstRunTestCase testPrivacy]' passed on 'Clone 1 of iPhone X - ios_chrome_ui_egtests.app (25472)' (10.528 seconds) [06:02:42:INFO] Test case '-[AdaptiveToolbarTestCase testInfobarFullscreen]' passed on 'Clone 2 of iPhone X - ios_chrome_ui_egtests.app (25709)' (11.046 seconds) [06:02:55:INFO] Test case '-[FirstRunTestCase testSignInAndTapSettingsLink]' passed on 'Clone 1 of iPhone X - ios_chrome_ui_egtests.app (25472)' (16.654 seconds) [06:03:03:INFO] Test case '-[HistoryUITestCase testDeleteHistory]' passed on 'Clone 3 of iPhone X - ios_chrome_ui_egtests.app (25712)' (26.910 seconds) [06:03:08:INFO] Test case '-[FirstRunTestCase testSignInAndUndo]' passed on 'Clone 1 of iPhone X - ios_chrome_ui_egtests.app (25472)' (13.405 seconds) [06:03:11:INFO] Test case '-[AdaptiveToolbarTestCase testNavigationButtons]' passed on 'Clone 2 of iPhone X - ios_chrome_ui_egtests.app (25709)' (28.957 seconds) [06:03:18:INFO] Test case '-[FirstRunTestCase testTermsAndConditions]' passed on 'Clone 1 of iPhone X - ios_chrome_ui_egtests.app (25472)' (9.977 seconds) [06:03:19:INFO] Test case '-[AdaptiveToolbarTestCase testOmniboxButton]' passed on 'Clone 2 of iPhone X - ios_chrome_ui_egtests.app (25709)' (7.221 seconds) [06:03:22:INFO] Test case '-[HistoryUITestCase testDisplayHistory]' passed on 'Clone 3 of iPhone X - ios_chrome_ui_egtests.app (25712)' (18.932 seconds) [06:03:27:INFO] Test case '-[AdaptiveToolbarTestCase testShareButton]' passed on 'Clone 2 of iPhone X - ios_chrome_ui_egtests.app (25709)' (8.315 seconds) [06:03:27:INFO] Test case '-[FirstRunTestCase testToggleMetricsOn]' passed on 'Clone 1 of iPhone X - ios_chrome_ui_egtests.app (25472)' (9.194 seconds) [06:03:28:INFO] Test suite 'ContentSuggestionsTestCase' started on 'Clone 1 of iPhone X - ios_chrome_ui_egtests.app (25472)' [06:03:38:INFO] Test case '-[HistoryUITestCase testDisplayNoHistory]' passed on 'Clone 3 of iPhone X - ios_chrome_ui_egtests.app (25712)' (15.208 seconds) [06:03:43:INFO] Test case '-[AdaptiveToolbarTestCase testToolbarUI]' passed on 'Clone 2 of iPhone X - ios_chrome_ui_egtests.app (25709)' (16.458 seconds) [06:03:44:INFO] Test suite 'FullscreenTestCase' started on 'Clone 2 of iPhone X - ios_chrome_ui_egtests.app (25709)' [06:03:50:INFO] Test case '-[ContentSuggestionsTestCase testAdditionalItemsKept]' passed on 'Clone 1 of iPhone X - ios_chrome_ui_egtests.app (25472)' (22.766 seconds) [06:03:57:INFO] Test case '-[HistoryUITestCase testHistoryUpdateAfterBackNavigation]' passed on 'Clone 3 of iPhone X - ios_chrome_ui_egtests.app (25712)' (19.316 seconds) [06:03:58:INFO] Test case '-[FullscreenTestCase testChromeToChromeURLKeepsHeaderOnScreen]' passed on 'Clone 2 of iPhone X - ios_chrome_ui_egtests.app (25709)' (13.918 seconds) [06:04:02:INFO] Test case '-[ContentSuggestionsTestCase testLearnMore]' passed on 'Clone 1 of iPhone X - ios_chrome_ui_egtests.app (25472)' (11.598 seconds) [06:04:09:INFO] Test case '-[FullscreenTestCase testHideHeaderUserScrollLongPage]' passed on 'Clone 2 of iPhone X - ios_chrome_ui_egtests.app (25709)' (12.031 seconds) [06:04:20:INFO] Test case '-[ContentSuggestionsTestCase testMostVisitedLongPress]' passed on 'Clone 1 of iPhone X - ios_chrome_ui_egtests.app (25472)' (17.767 seconds) [06:04:21:INFO] Test case '-[HistoryUITestCase testSearchHistory]' passed on 'Clone 3 of iPhone X - ios_chrome_ui_egtests.app (25712)' (24.410 seconds) [06:04:26:INFO] Test case '-[FullscreenTestCase testLongPDFInitialState]' failed on 'Clone 2 of iPhone X - ios_chrome_ui_egtests.app (25709)' (16.141 seconds) [06:04:41:INFO] Test case '-[FullscreenTestCase testLongPDFScroll]' passed on 'Clone 2 of iPhone X - ios_chrome_ui_egtests.app (25709)' (15.041 seconds) [06:04:41:INFO] Test case '-[ContentSuggestionsTestCase testMostVisitedNewIncognitoTab]' passed on 'Clone 1 of iPhone X - ios_chrome_ui_egtests.app (25472)' (21.051 seconds) [06:04:47:INFO] Test case '-[HistoryUITestCase testSearchLongPressOnScrimCancelsSearchController]' passed on 'Clone 3 of iPhone X - ios_chrome_ui_egtests.app (25712)' (25.924 seconds) [06:04:47:INFO] Test suite 'DownloadManagerTestCase' started on 'Clone 3 of iPhone X - ios_chrome_ui_egtests.app (25712)' [06:04:52:INFO] Test case '-[FullscreenTestCase testShowHeaderOnErrorPage]' passed on 'Clone 2 of iPhone X - ios_chrome_ui_egtests.app (25709)' (11.492 seconds) [06:05:03:INFO] Test case '-[FullscreenTestCase testShowHeaderOnNativePageLoad]' passed on 'Clone 2 of iPhone X - ios_chrome_ui_egtests.app (25709)' (10.987 seconds) [06:05:03:INFO] Test case '-[ContentSuggestionsTestCase testMostVisitedNewTab]' passed on 'Clone 1 of iPhone X - ios_chrome_ui_egtests.app (25472)' (22.533 seconds) [06:05:18:INFO] Test case '-[FullscreenTestCase testShowHeaderOnRegularPageLoad]' passed on 'Clone 2 of iPhone X - ios_chrome_ui_egtests.app (25709)' (15.067 seconds) [06:05:27:INFO] Test case '-[ContentSuggestionsTestCase testMostVisitedRemoveUndo]' failed on 'Clone 1 of iPhone X - ios_chrome_ui_egtests.app (25472)' (23.510 seconds) [06:05:29:INFO] Test case '-[FullscreenTestCase testShowHeaderOnReload]' passed on 'Clone 2 of iPhone X - ios_chrome_ui_egtests.app (25709)' (10.878 seconds) [06:05:32:INFO] Test case '-[DownloadManagerTestCase testAccessibilityOnCompletedDownloadToolbar]' failed on 'Clone 3 of iPhone X - ios_chrome_ui_egtests.app (25712)' (44.606 seconds) [06:05:41:INFO] Test case '-[DownloadManagerTestCase testAccessibilityOnNotStartedDownloadToolbar]' passed on 'Clone 3 of iPhone X - ios_chrome_ui_egtests.app (25712)' (9.078 seconds) [06:05:44:INFO] Test case '-[FullscreenTestCase testShowHeaderWhenChildTabCloses]' passed on 'Clone 2 of iPhone X - ios_chrome_ui_egtests.app (25709)' (15.393 seconds) [06:05:52:INFO] Test case '-[DownloadManagerTestCase testCancellingDownload]' passed on 'Clone 3 of iPhone X - ios_chrome_ui_egtests.app (25712)' (10.451 seconds) [06:05:57:INFO] Test case '-[FullscreenTestCase testSmallWidePDFScroll]' passed on 'Clone 2 of iPhone X - ios_chrome_ui_egtests.app (25709)' (12.437 seconds) [06:05:57:INFO] Test suite 'AlertCoordinatorTestCase' started on 'Clone 2 of iPhone X - ios_chrome_ui_egtests.app (25709)' [06:06:04:INFO] Test case '-[AlertCoordinatorTestCase testDismissOnDestroy]' passed on 'Clone 2 of iPhone X - ios_chrome_ui_egtests.app (25709)' (6.757 seconds) [06:06:11:INFO] Test case '-[AlertCoordinatorTestCase testNoInteractionActionAfterTap]' passed on 'Clone 2 of iPhone X - ios_chrome_ui_egtests.app (25709)' (7.354 seconds) [06:06:11:INFO] Test suite 'ARQuickLookEGTest' started on 'Clone 2 of iPhone X - ios_chrome_ui_egtests.app (25709)' [06:06:17:INFO] Test case '-[ContentSuggestionsTestCase testOpenPageAndGoBackWithMoreContent]' failed on 'Clone 1 of iPhone X - ios_chrome_ui_egtests.app (25472)' (50.529 seconds) [06:06:24:INFO] Test case '-[ARQuickLookEGTest testDownloadChangingMimeType]' passed on 'Clone 2 of iPhone X - ios_chrome_ui_egtests.app (25709)' (12.177 seconds) [06:06:33:INFO] Test case '-[ContentSuggestionsTestCase testPrivacySwitch]' passed on 'Clone 1 of iPhone X - ios_chrome_ui_egtests.app (25472)' (15.567 seconds) [06:06:33:INFO] Test suite 'InfobarTestCase' started on 'Clone 1 of iPhone X - ios_chrome_ui_egtests.app (25472)' [06:06:35:INFO] Test case '-[ARQuickLookEGTest testDownloadForbidden]' passed on 'Clone 2 of iPhone X - ios_chrome_ui_egtests.app (25709)' (11.902 seconds) [06:06:42:INFO] Test case '-[DownloadManagerTestCase testDownloadInNewTab]' failed on 'Clone 3 of iPhone X - ios_chrome_ui_egtests.app (25712)' (50.638 seconds) [06:06:45:INFO] Test case '-[InfobarTestCase testInfobarButtonDismissal]' passed on 'Clone 1 of iPhone X - ios_chrome_ui_egtests.app (25472)' (11.910 seconds) [06:06:48:INFO] Test case '-[ARQuickLookEGTest testDownloadUnauthorized]' passed on 'Clone 2 of iPhone X - ios_chrome_ui_egtests.app (25709)' (12.230 seconds) [06:06:57:INFO] Test case '-[InfobarTestCase testInfobarsDismissOnNavigate]' passed on 'Clone 1 of iPhone X - ios_chrome_ui_egtests.app (25472)' (12.357 seconds) [06:06:58:INFO] Test case '-[ARQuickLookEGTest testDownloadUsdz]' passed on 'Clone 2 of iPhone X - ios_chrome_ui_egtests.app (25709)' (10.248 seconds) [06:07:18:INFO] Test case '-[InfobarTestCase testInfobarTabSwitch]' passed on 'Clone 1 of iPhone X - ios_chrome_ui_egtests.app (25472)' (20.183 seconds) [06:07:22:INFO] Test case '-[DownloadManagerTestCase testDownloadWhileBrowsing]' passed on 'Clone 3 of iPhone X - ios_chrome_ui_egtests.app (25712)' (39.765 seconds) [06:07:29:INFO] Test case '-[InfobarTestCase testInfobarTallerLayout]' passed on 'Clone 1 of iPhone X - ios_chrome_ui_egtests.app (25472)' (10.951 seconds) [06:07:43:INFO] Test case '-[InfobarTestCase testInfobarTopMostVisible]' passed on 'Clone 1 of iPhone X - ios_chrome_ui_egtests.app (25472)' (14.549 seconds) [06:07:55:INFO] Test case '-[DownloadManagerTestCase testSucessfullDownload]' passed on 'Clone 3 of iPhone X - ios_chrome_ui_egtests.app (25712)' (33.354 seconds) [06:08:04:INFO] Test case '-[InfobarTestCase testInfobarTopMostVisibleHeight]' passed on 'Clone 1 of iPhone X - ios_chrome_ui_egtests.app (25472)' (20.602 seconds) [06:08:22:INFO] Test case '-[DownloadManagerTestCase testSucessfullDownloadInIncognito]' passed on 'Clone 3 of iPhone X - ios_chrome_ui_egtests.app (25712)' (26.511 seconds) [06:09:38:INFO] 2019-04-21 18:09:38.405 xcodebuild[25350:348323] [MT] IDETestOperationsObserverDebug: 655.559 elapsed -- Testing started completed. [06:09:38:INFO] 2019-04-21 18:09:38.405 xcodebuild[25350:348323] [MT] IDETestOperationsObserverDebug: 0.000 sec, +0.000 sec -- start [06:09:38:INFO] 2019-04-21 18:09:38.405 xcodebuild[25350:348323] [MT] IDETestOperationsObserverDebug: 655.559 sec, +655.559 sec -- end [06:09:38:INFO] Failing tests: [06:09:38:INFO] -[ContentSuggestionsTestCase testMostVisitedRemoveUndo] [06:09:38:INFO] -[ContentSuggestionsTestCase testOpenPageAndGoBackWithMoreContent] [06:09:38:INFO] -[ContentSuggestionsTestCase testOpenPageAndGoBackWithMoreContent] [06:09:38:INFO] -[HistoryUITestCase testContextMenuOpenInNewIncognitoTab] [06:09:38:INFO] -[DownloadManagerTestCase testAccessibilityOnCompletedDownloadToolbar] [06:09:38:INFO] -[DownloadManagerTestCase testDownloadInNewTab] [06:09:38:INFO] -[FullscreenTestCase testLongPDFInitialState] [06:09:38:INFO] -[FullscreenTestCase testLongPDFInitialState] [06:09:38:INFO] ** TEST EXECUTE FAILED ** [06:09:38:INFO] [06:09:56:INFO] Command ['xcodebuild', 'test-without-building', '-xctestrun', '/b/s/w/ioJvj6rR/run_1555894721.xctestrun', '-destination', 'platform=iOS Simulator,OS=12.1,name=iPhone X', '-resultBundlePath', '/b/s/w/ioJvj6rR/12.1 iPhone X/attempt_0', '-parallel-testing-enabled', 'YES', '-parallel-testing-worker-count', '3'] finished with 65 [06:09:56:INFO] Screenshots for failure "-[ContentSuggestionsTestCase testMostVisitedRemoveUndo]" in "/b/s/w/ioJvj6rR/12.1 iPhone X/attempt_0/failures/ContentSuggestionsTestCase_testMostVisitedRemoveUndo" [06:09:56:INFO] Screenshots for failure "-[ContentSuggestionsTestCase testOpenPageAndGoBackWithMoreContent]" in "/b/s/w/ioJvj6rR/12.1 iPhone X/attempt_0/failures/ContentSuggestionsTestCase_testOpenPageAndGoBackWithMoreContent" [06:09:56:INFO] Screenshots for failure "-[HistoryUITestCase testContextMenuOpenInNewIncognitoTab]" in "/b/s/w/ioJvj6rR/12.1 iPhone X/attempt_0/failures/HistoryUITestCase_testContextMenuOpenInNewIncognitoTab" [06:09:56:INFO] Screenshots for failure "-[DownloadManagerTestCase testAccessibilityOnCompletedDownloadToolbar]" in "/b/s/w/ioJvj6rR/12.1 iPhone X/attempt_0/failures/DownloadManagerTestCase_testAccessibilityOnCompletedDownloadToolbar" [06:09:56:INFO] Screenshots for failure "-[DownloadManagerTestCase testDownloadInNewTab]" in "/b/s/w/ioJvj6rR/12.1 iPhone X/attempt_0/failures/DownloadManagerTestCase_testDownloadInNewTab" [06:09:56:INFO] Screenshots for failure "-[FullscreenTestCase testLongPDFInitialState]" in "/b/s/w/ioJvj6rR/12.1 iPhone X/attempt_0/failures/FullscreenTestCase_testLongPDFInitialState" [06:09:56:INFO] Start test attempt #1 for command [xcodebuild test-without-building -xctestrun /b/s/w/ioJvj6rR/run_1555895396.xctestrun -destination platform=iOS Simulator,OS=12.1,name=iPhone X -resultBundlePath /b/s/w/ioJvj6rR/12.1 iPhone X/attempt_1 -parallel-testing-enabled YES -parallel-testing-worker-count 1] [06:09:56:INFO] Launching ['xcodebuild', 'test-without-building', '-xctestrun', '/b/s/w/ioJvj6rR/run_1555895396.xctestrun', '-destination', 'platform=iOS Simulator,OS=12.1,name=iPhone X', '-resultBundlePath', '/b/s/w/ioJvj6rR/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/itXWcNt1', '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': 'build215-m4', 'VIRTUAL_ENV': '/b/s/w/ir/.swarming_module_cache/vpython/367cfb', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N5LLHajBN1/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/itXWcNt1/luci_ctx.ravAWe.json', 'CIPD_CACHE_DIR': '/b/s/cipd_cache/cache', 'XPC_SERVICE_NAME': 'org.swarm.bot.plist', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8GdXOnxYk5/Listeners', 'VPYTHON_VIRTUALENV_ROOT': '/b/s/w/ir/.swarming_module_cache/vpython', 'SWARMING_TASK_ID': '445b5e8978437211', 'SHELL': '/bin/bash', 'NO_GCE_CHECK': 'False', 'TMPDIR': '/b/s/w/itXWcNt1', 'CIPD_PROTOCOL': 'v2', '__CF_USER_TEXT_ENCODING': '0x1F4:0x0:0x0', 'NSUnbufferedIO': 'YES'} [06:09:57:INFO] User defaults from command line: [06:09:57:INFO] IDEBuildOperationResultBundlePath = /b/s/w/ioJvj6rR/12.1 iPhone X/attempt_1 [06:09:57:INFO] IDETestRunSpecificationPath = /b/s/w/ioJvj6rR/run_1555895396.xctestrun [06:09:57:INFO] [06:09:57:INFO] Testing started on 'iPhone X' [06:09:58:INFO] 2019-04-21 18:09:58.073 xcodebuild[26267:363939] IDETestOperationsObserverDebug: Writing diagnostic log for test session to: [06:09:58:INFO] /b/s/w/ioJvj6rR/12.1 iPhone X/attempt_1/1_Test/Diagnostics/ios_chrome_ui_egtests_module-50192E8B-B371-478E-94CB-CB6C14331687/ios_chrome_ui_egtests_module-62BA87D8-0BFB-4C60-8F60-CD2CD249AF2D/Session-ios_chrome_ui_egtests_module-2019-04-21_180958-PUXMC8.log [06:09:58:INFO] 2019-04-21 18:09:58.073 xcodebuild[26267:363873] [MT] IDETestOperationsObserverDebug: (873156FD-8E4C-4F11-B0C4-94A7821C248E) Beginning test session ios_chrome_ui_egtests_module-873156FD-8E4C-4F11-B0C4-94A7821C248E at 2019-04-21 18:09:58.073 with Xcode 10B61 on target <DVTiPhoneSimulator: 0x7fb5e2ef8960> { [06:09:58:INFO] SimDevice: Clone 1 of iPhone X (703519C9-352A-4C1B-9EDF-A414F32F1F06, iOS 12.1, Shutdown) [06:09:58:INFO] } (12.1 (16B91)) [06:10:35:INFO] Test suite 'FullscreenTestCase' started on 'Clone 1 of iPhone X - ios_chrome_ui_egtests.app (26697)' [06:10:51:INFO] Test case '-[FullscreenTestCase testLongPDFInitialState]' failed on 'Clone 1 of iPhone X - ios_chrome_ui_egtests.app (26697)' (13.999 seconds) [06:10:51:INFO] Test suite 'ContentSuggestionsTestCase' started on 'Clone 1 of iPhone X - ios_chrome_ui_egtests.app (26697)' [06:11:07:INFO] Test case '-[ContentSuggestionsTestCase testMostVisitedRemoveUndo]' passed on 'Clone 1 of iPhone X - ios_chrome_ui_egtests.app (26697)' (15.389 seconds) [06:11:31:INFO] Test case '-[ContentSuggestionsTestCase testOpenPageAndGoBackWithMoreContent]' passed on 'Clone 1 of iPhone X - ios_chrome_ui_egtests.app (26697)' (23.633 seconds) [06:11:31:INFO] Test suite 'DownloadManagerTestCase' started on 'Clone 1 of iPhone X - ios_chrome_ui_egtests.app (26697)' [06:11:56:INFO] Test case '-[DownloadManagerTestCase testAccessibilityOnCompletedDownloadToolbar]' passed on 'Clone 1 of iPhone X - ios_chrome_ui_egtests.app (26697)' (25.148 seconds) [06:12:18:INFO] Test case '-[DownloadManagerTestCase testDownloadInNewTab]' passed on 'Clone 1 of iPhone X - ios_chrome_ui_egtests.app (26697)' (22.561 seconds) [06:12:18:INFO] Test suite 'HistoryUITestCase' started on 'Clone 1 of iPhone X - ios_chrome_ui_egtests.app (26697)' [06:12:35:INFO] Test case '-[HistoryUITestCase testContextMenuOpenInNewIncognitoTab]' passed on 'Clone 1 of iPhone X - ios_chrome_ui_egtests.app (26697)' (16.747 seconds) [06:12:42:INFO] 2019-04-21 18:12:42.503 xcodebuild[26267:363873] [MT] IDETestOperationsObserverDebug: 164.678 elapsed -- Testing started completed. [06:12:42:INFO] 2019-04-21 18:12:42.503 xcodebuild[26267:363873] [MT] IDETestOperationsObserverDebug: 0.000 sec, +0.000 sec -- start [06:12:42:INFO] 2019-04-21 18:12:42.503 xcodebuild[26267:363873] [MT] IDETestOperationsObserverDebug: 164.678 sec, +164.678 sec -- end [06:12:42:INFO] Failing tests: [06:12:42:INFO] -[FullscreenTestCase testLongPDFInitialState] [06:12:42:INFO] -[FullscreenTestCase testLongPDFInitialState] [06:12:42:INFO] ** TEST EXECUTE FAILED ** [06:12:42:INFO] [06:12:48:INFO] Command ['xcodebuild', 'test-without-building', '-xctestrun', '/b/s/w/ioJvj6rR/run_1555895396.xctestrun', '-destination', 'platform=iOS Simulator,OS=12.1,name=iPhone X', '-resultBundlePath', '/b/s/w/ioJvj6rR/12.1 iPhone X/attempt_1', '-parallel-testing-enabled', 'YES', '-parallel-testing-worker-count', '1'] finished with 65 [06:12:48:INFO] Screenshots for failure "-[FullscreenTestCase testLongPDFInitialState]" in "/b/s/w/ioJvj6rR/12.1 iPhone X/attempt_1/failures/FullscreenTestCase_testLongPDFInitialState" [06:12:48:INFO] Start test attempt #2 for command [xcodebuild test-without-building -xctestrun /b/s/w/ioJvj6rR/run_1555895568.xctestrun -destination platform=iOS Simulator,OS=12.1,name=iPhone X -resultBundlePath /b/s/w/ioJvj6rR/12.1 iPhone X/attempt_2 -parallel-testing-enabled YES -parallel-testing-worker-count 1] [06:12:48:INFO] Launching ['xcodebuild', 'test-without-building', '-xctestrun', '/b/s/w/ioJvj6rR/run_1555895568.xctestrun', '-destination', 'platform=iOS Simulator,OS=12.1,name=iPhone X', '-resultBundlePath', '/b/s/w/ioJvj6rR/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/itXWcNt1', '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': 'build215-m4', 'VIRTUAL_ENV': '/b/s/w/ir/.swarming_module_cache/vpython/367cfb', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N5LLHajBN1/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/itXWcNt1/luci_ctx.ravAWe.json', 'CIPD_CACHE_DIR': '/b/s/cipd_cache/cache', 'XPC_SERVICE_NAME': 'org.swarm.bot.plist', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8GdXOnxYk5/Listeners', 'VPYTHON_VIRTUALENV_ROOT': '/b/s/w/ir/.swarming_module_cache/vpython', 'SWARMING_TASK_ID': '445b5e8978437211', 'SHELL': '/bin/bash', 'NO_GCE_CHECK': 'False', 'TMPDIR': '/b/s/w/itXWcNt1', 'CIPD_PROTOCOL': 'v2', '__CF_USER_TEXT_ENCODING': '0x1F4:0x0:0x0', 'NSUnbufferedIO': 'YES'} [06:12:49:INFO] User defaults from command line: [06:12:49:INFO] IDEBuildOperationResultBundlePath = /b/s/w/ioJvj6rR/12.1 iPhone X/attempt_2 [06:12:49:INFO] IDETestRunSpecificationPath = /b/s/w/ioJvj6rR/run_1555895568.xctestrun [06:12:49:INFO] [06:12:49:INFO] Testing started on 'iPhone X' [06:12:49:INFO] 2019-04-21 18:12:49.410 xcodebuild[27140:369631] IDETestOperationsObserverDebug: Writing diagnostic log for test session to: [06:12:49:INFO] /b/s/w/ioJvj6rR/12.1 iPhone X/attempt_2/1_Test/Diagnostics/ios_chrome_ui_egtests_module-B80E6738-8721-4422-BAB0-E7A5891DB9C2/ios_chrome_ui_egtests_module-9D31869A-E865-4E37-9E48-86B1158F0285/Session-ios_chrome_ui_egtests_module-2019-04-21_181249-IXFlEG.log [06:12:49:INFO] 2019-04-21 18:12:49.411 xcodebuild[27140:369572] [MT] IDETestOperationsObserverDebug: (C6BC4ECC-A5A5-4DA6-B3F3-76F775C17AE2) Beginning test session ios_chrome_ui_egtests_module-C6BC4ECC-A5A5-4DA6-B3F3-76F775C17AE2 at 2019-04-21 18:12:49.411 with Xcode 10B61 on target <DVTiPhoneSimulator: 0x7f83292c8ba0> { [06:12:49:INFO] SimDevice: Clone 1 of iPhone X (C1E67AE8-B182-4D04-A001-FA0BA250FCA5, iOS 12.1, Shutdown) [06:12:49:INFO] } (12.1 (16B91)) [06:13:19:INFO] Test suite 'FullscreenTestCase' started on 'Clone 1 of iPhone X - ios_chrome_ui_egtests.app (27255)' [06:13:38:INFO] Test case '-[FullscreenTestCase testLongPDFInitialState]' failed on 'Clone 1 of iPhone X - ios_chrome_ui_egtests.app (27255)' (12.755 seconds) [06:13:51:INFO] 2019-04-21 18:13:51.847 xcodebuild[27140:369572] [MT] IDETestOperationsObserverDebug: 62.673 elapsed -- Testing started completed. [06:13:51:INFO] 2019-04-21 18:13:51.847 xcodebuild[27140:369572] [MT] IDETestOperationsObserverDebug: 0.000 sec, +0.000 sec -- start [06:13:51:INFO] 2019-04-21 18:13:51.847 xcodebuild[27140:369572] [MT] IDETestOperationsObserverDebug: 62.673 sec, +62.673 sec -- end [06:13:51:INFO] Failing tests: [06:13:51:INFO] -[FullscreenTestCase testLongPDFInitialState] [06:13:51:INFO] -[FullscreenTestCase testLongPDFInitialState] [06:13:51:INFO] ** TEST EXECUTE FAILED ** [06:13:51:INFO] [06:13:58:INFO] Command ['xcodebuild', 'test-without-building', '-xctestrun', '/b/s/w/ioJvj6rR/run_1555895568.xctestrun', '-destination', 'platform=iOS Simulator,OS=12.1,name=iPhone X', '-resultBundlePath', '/b/s/w/ioJvj6rR/12.1 iPhone X/attempt_2', '-parallel-testing-enabled', 'YES', '-parallel-testing-worker-count', '1'] finished with 65 [06:13:58:INFO] Screenshots for failure "-[FullscreenTestCase testLongPDFInitialState]" in "/b/s/w/ioJvj6rR/12.1 iPhone X/attempt_2/failures/FullscreenTestCase_testLongPDFInitialState" [06:13:58:INFO] Start test attempt #3 for command [xcodebuild test-without-building -xctestrun /b/s/w/ioJvj6rR/run_1555895638.xctestrun -destination platform=iOS Simulator,OS=12.1,name=iPhone X -resultBundlePath /b/s/w/ioJvj6rR/12.1 iPhone X/attempt_3 -parallel-testing-enabled YES -parallel-testing-worker-count 1] [06:13:58:INFO] Launching ['xcodebuild', 'test-without-building', '-xctestrun', '/b/s/w/ioJvj6rR/run_1555895638.xctestrun', '-destination', 'platform=iOS Simulator,OS=12.1,name=iPhone X', '-resultBundlePath', '/b/s/w/ioJvj6rR/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/itXWcNt1', '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': 'build215-m4', 'VIRTUAL_ENV': '/b/s/w/ir/.swarming_module_cache/vpython/367cfb', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N5LLHajBN1/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/itXWcNt1/luci_ctx.ravAWe.json', 'CIPD_CACHE_DIR': '/b/s/cipd_cache/cache', 'XPC_SERVICE_NAME': 'org.swarm.bot.plist', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8GdXOnxYk5/Listeners', 'VPYTHON_VIRTUALENV_ROOT': '/b/s/w/ir/.swarming_module_cache/vpython', 'SWARMING_TASK_ID': '445b5e8978437211', 'SHELL': '/bin/bash', 'NO_GCE_CHECK': 'False', 'TMPDIR': '/b/s/w/itXWcNt1', 'CIPD_PROTOCOL': 'v2', '__CF_USER_TEXT_ENCODING': '0x1F4:0x0:0x0', 'NSUnbufferedIO': 'YES'} [06:13:58:INFO] User defaults from command line: [06:13:58:INFO] IDEBuildOperationResultBundlePath = /b/s/w/ioJvj6rR/12.1 iPhone X/attempt_3 [06:13:58:INFO] IDETestRunSpecificationPath = /b/s/w/ioJvj6rR/run_1555895638.xctestrun [06:13:58:INFO] [06:13:58:INFO] Testing started on 'iPhone X' [06:13:58:INFO] 2019-04-21 18:13:58.939 xcodebuild[27283:372020] IDETestOperationsObserverDebug: Writing diagnostic log for test session to: [06:13:58:INFO] /b/s/w/ioJvj6rR/12.1 iPhone X/attempt_3/1_Test/Diagnostics/ios_chrome_ui_egtests_module-629304FC-BEE7-460A-B50A-6684B48BC124/ios_chrome_ui_egtests_module-981F9E7D-D460-4F13-8C32-7736D1C43BE8/Session-ios_chrome_ui_egtests_module-2019-04-21_181358-CrU37M.log [06:13:58:INFO] 2019-04-21 18:13:58.939 xcodebuild[27283:371916] [MT] IDETestOperationsObserverDebug: (829069EE-F0A9-4E2D-9E72-A46A21C7AF6C) Beginning test session ios_chrome_ui_egtests_module-829069EE-F0A9-4E2D-9E72-A46A21C7AF6C at 2019-04-21 18:13:58.939 with Xcode 10B61 on target <DVTiPhoneSimulator: 0x7f9792d53910> { [06:13:58:INFO] SimDevice: Clone 1 of iPhone X (C4A80FDE-35BD-4BB4-AB93-FF6D6FB71ECF, iOS 12.1, Shutdown) [06:13:58:INFO] } (12.1 (16B91)) [06:14:31:INFO] Test suite 'FullscreenTestCase' started on 'Clone 1 of iPhone X - ios_chrome_ui_egtests.app (27403)' [06:14:48:INFO] Test case '-[FullscreenTestCase testLongPDFInitialState]' failed on 'Clone 1 of iPhone X - ios_chrome_ui_egtests.app (27403)' (12.092 seconds) [06:15:00:INFO] 2019-04-21 18:15:00.252 xcodebuild[27283:371916] [MT] IDETestOperationsObserverDebug: 61.496 elapsed -- Testing started completed. [06:15:00:INFO] 2019-04-21 18:15:00.252 xcodebuild[27283:371916] [MT] IDETestOperationsObserverDebug: 0.000 sec, +0.000 sec -- start [06:15:00:INFO] 2019-04-21 18:15:00.252 xcodebuild[27283:371916] [MT] IDETestOperationsObserverDebug: 61.496 sec, +61.496 sec -- end [06:15:00:INFO] Failing tests: [06:15:00:INFO] -[FullscreenTestCase testLongPDFInitialState] [06:15:00:INFO] -[FullscreenTestCase testLongPDFInitialState] [06:15:00:INFO] ** TEST EXECUTE FAILED ** [06:15:00:INFO] [06:15:06:INFO] Command ['xcodebuild', 'test-without-building', '-xctestrun', '/b/s/w/ioJvj6rR/run_1555895638.xctestrun', '-destination', 'platform=iOS Simulator,OS=12.1,name=iPhone X', '-resultBundlePath', '/b/s/w/ioJvj6rR/12.1 iPhone X/attempt_3', '-parallel-testing-enabled', 'YES', '-parallel-testing-worker-count', '1'] finished with 65 2019-04-21 18:22:35,375 - root: [DEBUG] Contents of task_output_dir: ['/b/s/w/ir/k/recipe_cleanup/54_0cp7WHv/445b5e8978437210'] 2019-04-21 18:22:35,376 - root: [WARNING] Expected output.json file missing: set(['/b/s/w/ir/k/recipe_cleanup/54_0cp7WHv/445b5e8978437210/output.json']) Found: [] Expected: ['/b/s/w/ir/k/recipe_cleanup/54_0cp7WHv/445b5e8978437210/output.json'] 2019-04-21 18:22:35,376 - root: [WARNING] No shard json files found in task_output_dir: '/b/s/w/ir/k/recipe_cleanup/54_0cp7WHv' Found ['/b/s/w/ir/k/recipe_cleanup/54_0cp7WHv/445b5e8978437210'] 2019-04-21 18:22:35,376 - root: [DEBUG] Found shard_json_files: ['/b/s/w/ir/k/recipe_cleanup/54_0cp7WHv/445b5e8978437210/output.json'] 2019-04-21 18:22:35,376 - 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/tmpB09Kcj.json --task-output-dir /b/s/w/ir/k/recipe_cleanup/54_0cp7WHv -o /b/s/w/ir/tmp/t/tmpfRBzUq.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/tmpB09Kcj.json', '--task-output-dir', '/b/s/w/ir/k/recipe_cleanup/54_0cp7WHv', '-o', '/b/s/w/ir/tmp/t/tmpfRBzUq.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/tmpB09Kcj.json', '--task-output-dir', '/b/s/w/ir/k/recipe_cleanup/54_0cp7WHv', '-o', '/b/s/w/ir/tmp/t/tmpfRBzUq.json'] returned exit code 0