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/tmpLz4FAp.json --task-output-dir /b/s/w/ir/k/recipe_cleanup/114nZElWD --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/tmp5H3HDbmerge_script_log --merge-additional-args '[]' --summary-json-file /b/s/w/ir/tmp/t/tmpUK8Ua7.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/tmpeeQ95I.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/tmpLz4FAp.json', '--task-output-dir', '/b/s/w/ir/k/recipe_cleanup/114nZElWD', '--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/tmp5H3HDbmerge_script_log', '--merge-additional-args', '[]', '--summary-json-file', '/b/s/w/ir/tmp/t/tmpUK8Ua7.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/tmpeeQ95I.json'] env: {} env_prefixes: {'PATH': '/b/s/w/ir/cache/swarming_client'} env_suffixes: {} infra_step: False name_tokens: ('ios_showcase_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.TqU8rt0Ega/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: 51548 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/8915289161291423808 LOGDOG_STREAM_PROJECT: chromium LOGDOG_STREAM_SERVER_PATH: unix:/b/s/w/ir/tmp/ld.sock LOGNAME: chrome-bot LUCI_CONTEXT: /b/s/w/ir/tmp/luci_context.936571391 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.JSNO5QoBDA/Listeners SWARMING_BOT_ID: vm1101-m4 SWARMING_HEADLESS: 1 SWARMING_SERVER: https://chromium-swarm.appspot.com SWARMING_TASK_ID: 44687be6ea93bf11 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-24 07:53:04,318 - root: [DEBUG] Using task_output_dir: '/b/s/w/ir/k/recipe_cleanup/114nZElWD' 2019-04-24 07:53:04,318 - root: [WARNING] task_output_dir '/b/s/w/ir/k/recipe_cleanup/114nZElWD' already exists! 2019-04-24 07:53:04,318 - root: [WARNING] task_output_dir existing content: [] 2019-04-24 07:53:04,318 - 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/tmpeeQ95I.json -output-dir /b/s/w/ir/k/recipe_cleanup/114nZElWD -task-summary-json /b/s/w/ir/tmp/t/tmpUK8Ua7.json [D2019-04-24T07:53:04.338689-07:00 80938 0 auth.go:1265] Minting a new token {"key":"luci_ctx/0089f96c44adbc1977d4d1b2a86449f89e9a934164136f5263abf3f725fce760", "scopes":"https://www.googleapis.com/auth/userinfo.email"} [D2019-04-24T07:53:04.338809-07:00 80938 0 luci_ctx.go:138] POST http://127.0.0.1:51535/rpc/LuciLocalAuthService.GetOAuthToken {"key":"luci_ctx/0089f96c44adbc1977d4d1b2a86449f89e9a934164136f5263abf3f725fce760", "scopes":"https://www.googleapis.com/auth/userinfo.email"} [D2019-04-24T07:53:04.340726-07:00 80938 0 auth.go:1222] Token expires in 50m20.659302s {"key":"luci_ctx/0089f96c44adbc1977d4d1b2a86449f89e9a934164136f5263abf3f725fce760", "scopes":"https://www.googleapis.com/auth/userinfo.email"} 4468879a36bd8e10: exit 1 [I 2019-04-24 07:18:36] About to install Xcode 10b61 in Xcode.app for ios [07:18:38:INFO] Using Xcode version 10.1 build 10B61 at /b/s/w/ir/Xcode.app/Contents/Developer Device wiped. [07:18:52:DEBUG] Setting sigterm handler. [07:18:54:INFO] User defaults from command line: [07:18:54:INFO] IDETestRunSpecificationPath = /var/folders/2j/22s2gz0s7hn48k32d47clxf80000gm/T/3B27810D-80D4-49F0-8F03-AF991806B3B3 [07:18:54:INFO] [07:18:54:INFO] Testing started on 'iPhone X' [07:18:54:INFO] 2019-04-24 07:18:54.724 xcodebuild[12335:291852] IDETestOperationsObserverDebug: Writing diagnostic log for test session to: [07:18:54:INFO] /Users/chrome-bot/Library/Developer/Xcode/DerivedData/temporary-eccpoxtqhhkfqkbfzrysvzibqoyv/Logs/Test/Test-Transient Testing-2019.04.24_07-18-54--0700.xcresult/1_Test/Diagnostics/ios_showcase_egtests_module-F7653762-DDC1-4CEB-B235-66960660AB3E/ios_showcase_egtests_module-5FBEB795-B7CF-4815-9A38-C421A3A34776/Session-ios_showcase_egtests_module-2019-04-24_071854-I8VT5b.log [07:18:54:INFO] 2019-04-24 07:18:54.725 xcodebuild[12335:291842] [MT] IDETestOperationsObserverDebug: (845CDC62-FEBA-4C81-A2D8-7FE2921803C6) Beginning test session ios_showcase_egtests_module-845CDC62-FEBA-4C81-A2D8-7FE2921803C6 at 2019-04-24 07:18:54.725 with Xcode 10B61 on target <DVTiPhoneSimulator: 0x7fe06ea7f900> { [07:18:54:INFO] SimDevice: iPhone X (0C3A2C53-DA6D-47DE-991E-33541343A813, iOS 12.1, Shutdown) [07:18:54:INFO] } (12.1 (16B91)) [07:19:14:INFO] 2019-04-24 07:19:14.413 ios_showcase_egtests[12462:293147] Crash handler setup started. [07:19:14:INFO] 2019-04-24 07:19:14.413 ios_showcase_egtests[12462:293147] Crash handler setup completed. [07:19:14:INFO] 2019-04-24 07:19:14.414 ios_showcase_egtests[12462:293147] Enabling accessibility for automation on Simulator. [07:19:19:INFO] Test Suite 'All tests' started at 2019-04-24 07:19:19.537 [07:19:19:INFO] Test Suite 'ios_showcase_egtests.app' started at 2019-04-24 07:19:19.539 [07:19:19:INFO] Test Suite 'SCBubbleTestCase' started at 2019-04-24 07:19:19.539 [07:19:19:INFO] Test Case '-[SCBubbleTestCase testTextBadgeAccessibilityLabel]' started. [07:19:38:INFO] Test Case '-[SCBubbleTestCase testTextBadgeAccessibilityLabel]' passed (18.567 seconds). [07:19:38:INFO] Test Suite 'SCBubbleTestCase' passed at 2019-04-24 07:19:38.109. [07:19:38:INFO] Executed 1 test, with 0 failures (0 unexpected) in 18.567 (18.570) seconds [07:19:38:INFO] Test Suite 'SCContentSuggestionsTestCase' started at 2019-04-24 07:19:38.111 [07:19:38:INFO] Test Case '-[SCContentSuggestionsTestCase testLaunch]' started. [07:20:07:INFO] Test Case '-[SCContentSuggestionsTestCase testLaunch]' passed (29.567 seconds). [07:20:07:INFO] Test Case '-[SCContentSuggestionsTestCase testLongPressItem]' started. [07:20:37:INFO] Test Case '-[SCContentSuggestionsTestCase testLongPressItem]' passed (29.474 seconds). [07:20:37:INFO] Test Case '-[SCContentSuggestionsTestCase testOpenItem]' started. [07:21:06:INFO] Test Case '-[SCContentSuggestionsTestCase testOpenItem]' passed (29.220 seconds). [07:21:06:INFO] Test Case '-[SCContentSuggestionsTestCase testSwipeToDismiss]' started. [07:21:38:INFO] Test Case '-[SCContentSuggestionsTestCase testSwipeToDismiss]' passed (32.353 seconds). [07:21:38:INFO] Test Case '-[SCContentSuggestionsTestCase testTapLearnMore]' started. [07:22:12:INFO] Test Case '-[SCContentSuggestionsTestCase testTapLearnMore]' passed (33.877 seconds). [07:22:12:INFO] Test Suite 'SCContentSuggestionsTestCase' passed at 2019-04-24 07:22:12.605. [07:22:12:INFO] Executed 5 tests, with 0 failures (0 unexpected) in 154.490 (154.494) seconds [07:22:12:INFO] Test Suite 'SCInfobarBannerTestCase' started at 2019-04-24 07:22:12.605 [07:22:12:INFO] Test Case '-[SCInfobarBannerTestCase testInfobarBannerConfiguration]' started. [07:22:35:INFO] Test Case '-[SCInfobarBannerTestCase testInfobarBannerConfiguration]' passed (22.635 seconds). [07:22:35:INFO] Test Case '-[SCInfobarBannerTestCase testInfobarBannerDismissButton]' started. [07:22:56:INFO] Test Case '-[SCInfobarBannerTestCase testInfobarBannerDismissButton]' passed (21.227 seconds). [07:22:56:INFO] Test Case '-[SCInfobarBannerTestCase testInfobarBannerDismissSwipe]' started. [07:23:18:INFO] Test Case '-[SCInfobarBannerTestCase testInfobarBannerDismissSwipe]' passed (21.761 seconds). [07:23:18:INFO] Test Case '-[SCInfobarBannerTestCase testInfobarBannerSwipeDown]' started. [07:23:44:INFO] Test Case '-[SCInfobarBannerTestCase testInfobarBannerSwipeDown]' passed (26.477 seconds). [07:23:44:INFO] Test Suite 'SCInfobarBannerTestCase' passed at 2019-04-24 07:23:44.708. [07:23:44:INFO] Executed 4 tests, with 0 failures (0 unexpected) in 92.100 (92.103) seconds [07:23:44:INFO] Test Suite 'SCPaymentsEditorTestCase' started at 2019-04-24 07:23:44.709 [07:23:44:INFO] Test Case '-[SCPaymentsEditorTestCase testInputAccessoryViewCloseButton]' started. [07:24:15:INFO] Test Case '-[SCPaymentsEditorTestCase testInputAccessoryViewCloseButton]' passed (30.742 seconds). [07:24:15:INFO] Test Case '-[SCPaymentsEditorTestCase testInputAccessoryViewNavigationButtons]' started. [07:25:25:INFO] Test Case '-[SCPaymentsEditorTestCase testInputAccessoryViewNavigationButtons]' passed (69.726 seconds). [07:25:25:INFO] Test Case '-[SCPaymentsEditorTestCase testNavigationByTappingReturn]' started. [07:26:01:INFO] Test Case '-[SCPaymentsEditorTestCase testNavigationByTappingReturn]' passed (36.419 seconds). [07:26:01:INFO] Test Case '-[SCPaymentsEditorTestCase testVerifyLabelsAndFields]' started. [07:26:22:INFO] Test Case '-[SCPaymentsEditorTestCase testVerifyLabelsAndFields]' passed (20.538 seconds). [07:26:22:INFO] Test Case '-[SCPaymentsEditorTestCase testVerifyProvinceFieldInputView]' started. [07:26:48:INFO] Test Case '-[SCPaymentsEditorTestCase testVerifyProvinceFieldInputView]' passed (26.446 seconds). [07:26:48:INFO] Test Case '-[SCPaymentsEditorTestCase testVerifyTappingSelectorFieldNotifiesDelegate]' started. [07:27:19:INFO] Test Case '-[SCPaymentsEditorTestCase testVerifyTappingSelectorFieldNotifiesDelegate]' passed (31.079 seconds). [07:27:19:INFO] Test Suite 'SCPaymentsEditorTestCase' passed at 2019-04-24 07:27:19.662. [07:27:19:INFO] Executed 6 tests, with 0 failures (0 unexpected) in 214.948 (214.953) seconds [07:27:19:INFO] Test Suite 'SCPaymentsPickerTestCase' started at 2019-04-24 07:27:19.663 [07:27:19:INFO] Test Case '-[SCPaymentsPickerTestCase testVerifyFiltering]' started. [07:27:37:INFO] 2019-04-24 07:27:37.805 ios_showcase_egtests[12462:293147] Attempting to type key c. [07:27:38:INFO] 2019-04-24 07:27:38.012 ios_showcase_egtests[12462:293147] Tapping on key: shift. [07:27:38:INFO] 2019-04-24 07:27:38.116 ios_showcase_egtests[12462:293147] Tapping on key: c. [07:27:38:INFO] 2019-04-24 07:27:38.178 ios_showcase_egtests[12462:293147] Attempting to type key h. [07:27:38:INFO] 2019-04-24 07:27:38.179 ios_showcase_egtests[12462:293147] Tapping on key: h. [07:27:38:INFO] 2019-04-24 07:27:38.231 ios_showcase_egtests[12462:293147] Attempting to type key i. [07:27:38:INFO] 2019-04-24 07:27:38.232 ios_showcase_egtests[12462:293147] Tapping on key: i. [07:29:11:INFO] 2019-04-24 07:29:11.550 ios_showcase_egtests[12462:293147] Attempting to type key l. [07:29:11:INFO] 2019-04-24 07:29:11.551 ios_showcase_egtests[12462:293147] Tapping on key: l. [07:32:11:INFO] Killing hung process 12329 [07:32:11:INFO] Process iossim is still alive! Xcodebuild process might block it. [07:32:11:DEBUG] List of running xcodebuild processes: [<psutil.Process(pid=12335, name='xcodebuild') at 4525832208>] [07:32:11:DEBUG] Finished print_process_output. [07:32:11:INFO] Waiting for test process to terminate. [07:32:11:INFO] Test process terminated. [07:32:11:DEBUG] Setting sigterm handler. [07:32:11:DEBUG] Stdout flushed after test process. [07:32:11:DEBUG] Processing test results. [07:32:11:INFO] /b/s/w/ir/src/out/Debug-iphonesimulator/iossim returned -15 [07:32:11:WARNING] 1 tests failed and will be retried. [07:32:11:INFO] Retry #1 for SCPaymentsPickerTestCase/testVerifyFiltering. [07:32:11:DEBUG] Setting sigterm handler. [07:32:11:INFO] Process was killed! [07:32:13:INFO] User defaults from command line: [07:32:13:INFO] IDETestRunSpecificationPath = /var/folders/2j/22s2gz0s7hn48k32d47clxf80000gm/T/854978D2-EC50-4FC0-8301-B19362019741 [07:32:13:INFO] [07:32:14:INFO] Testing started on 'iPhone X' [07:32:14:INFO] 2019-04-24 07:32:14.074 xcodebuild[12634:296700] IDETestOperationsObserverDebug: Writing diagnostic log for test session to: [07:32:14:INFO] /Users/chrome-bot/Library/Developer/Xcode/DerivedData/temporary-cpltrgynwymhghfdgxazmgbthquq/Logs/Test/Test-Transient Testing-2019.04.24_07-32-13--0700.xcresult/1_Test/Diagnostics/ios_showcase_egtests_module-76B6A362-354C-48E5-809B-325B34AA82D7/ios_showcase_egtests_module-27DA56C6-0A9D-4C6F-A28D-2D5E0D46D8FD/Session-ios_showcase_egtests_module-2019-04-24_073214-FXFk41.log [07:32:14:INFO] 2019-04-24 07:32:14.075 xcodebuild[12634:296675] [MT] IDETestOperationsObserverDebug: (B8822566-E9EA-42A8-A362-80D8B8B7D2ED) Beginning test session ios_showcase_egtests_module-B8822566-E9EA-42A8-A362-80D8B8B7D2ED at 2019-04-24 07:32:14.074 with Xcode 10B61 on target <DVTiPhoneSimulator: 0x7feb1f78d710> { [07:32:14:INFO] SimDevice: iPhone X (0C3A2C53-DA6D-47DE-991E-33541343A813, iOS 12.1, Booted) [07:32:14:INFO] } (12.1 (16B91)) [07:32:16:INFO] 2019-04-24 07:32:16.275 ios_showcase_egtests[12639:296845] Crash handler setup started. [07:32:16:INFO] 2019-04-24 07:32:16.275 ios_showcase_egtests[12639:296845] Crash handler setup completed. [07:32:16:INFO] 2019-04-24 07:32:16.276 ios_showcase_egtests[12639:296845] Enabling accessibility for automation on Simulator. [07:32:17:INFO] Test Suite 'Selected tests' started at 2019-04-24 07:32:17.575 [07:32:17:INFO] Test Suite 'ios_showcase_egtests_module.xctest' started at 2019-04-24 07:32:17.576 [07:32:17:INFO] Test Suite 'SCPaymentsPickerTestCase' started at 2019-04-24 07:32:17.577 [07:32:17:INFO] Test Case '-[SCPaymentsPickerTestCase testVerifyFiltering]' started. [07:32:36:INFO] 2019-04-24 07:32:36.575 ios_showcase_egtests[12639:296845] Attempting to type key c. [07:32:36:INFO] 2019-04-24 07:32:36.781 ios_showcase_egtests[12639:296845] Tapping on key: shift. [07:32:37:INFO] 2019-04-24 07:32:37.653 ios_showcase_egtests[12639:296845] Tapping on key: c. [07:32:37:INFO] 2019-04-24 07:32:37.773 ios_showcase_egtests[12639:296845] Attempting to type key h. [07:32:37:INFO] 2019-04-24 07:32:37.775 ios_showcase_egtests[12639:296845] Tapping on key: h. [07:32:37:INFO] 2019-04-24 07:32:37.830 ios_showcase_egtests[12639:296845] Attempting to type key i. [07:32:37:INFO] 2019-04-24 07:32:37.831 ios_showcase_egtests[12639:296845] Tapping on key: i. [07:34:08:INFO] 2019-04-24 07:34:08.301 ios_showcase_egtests[12639:296845] Attempting to type key l. [07:34:08:INFO] 2019-04-24 07:34:08.301 ios_showcase_egtests[12639:296845] Tapping on key: l. [07:37:08:INFO] Killing hung process 12628 [07:37:08:INFO] Process iossim is still alive! Xcodebuild process might block it. [07:37:08:DEBUG] List of running xcodebuild processes: [<psutil.Process(pid=12634, name='xcodebuild') at 4525948176>] [07:37:08:DEBUG] Finished print_process_output. [07:37:08:INFO] Waiting for test process to terminate. [07:37:08:INFO] Test process terminated. [07:37:08:DEBUG] Setting sigterm handler. [07:37:08:DEBUG] Stdout flushed after test process. [07:37:08:INFO] Process was killed! [07:37:08:DEBUG] Processing test results. [07:37:08:INFO] /b/s/w/ir/src/out/Debug-iphonesimulator/iossim returned -15 [07:37:08:INFO] Retry #2 for SCPaymentsPickerTestCase/testVerifyFiltering. [07:37:08:DEBUG] Setting sigterm handler. [07:37:10:INFO] User defaults from command line: [07:37:10:INFO] IDETestRunSpecificationPath = /var/folders/2j/22s2gz0s7hn48k32d47clxf80000gm/T/6011E25D-9ADB-4215-80B6-2477FC0CB954 [07:37:10:INFO] [07:37:10:INFO] Testing started on 'iPhone X' [07:37:10:INFO] 2019-04-24 07:37:10.525 xcodebuild[12707:298086] IDETestOperationsObserverDebug: Writing diagnostic log for test session to: [07:37:10:INFO] /Users/chrome-bot/Library/Developer/Xcode/DerivedData/temporary-bpncuczlvmddukgahbdbsdtyywzi/Logs/Test/Test-Transient Testing-2019.04.24_07-37-10--0700.xcresult/1_Test/Diagnostics/ios_showcase_egtests_module-6DDE1D86-DF10-432D-B36F-E139541203B2/ios_showcase_egtests_module-74081983-AF2C-4D1D-BDD5-2A6071066739/Session-ios_showcase_egtests_module-2019-04-24_073710-QJfpTE.log [07:37:10:INFO] 2019-04-24 07:37:10.526 xcodebuild[12707:298061] [MT] IDETestOperationsObserverDebug: (960A61F6-4091-43F3-9ECA-CD7BDDB94801) Beginning test session ios_showcase_egtests_module-960A61F6-4091-43F3-9ECA-CD7BDDB94801 at 2019-04-24 07:37:10.526 with Xcode 10B61 on target <DVTiPhoneSimulator: 0x7f8ce70de240> { [07:37:10:INFO] SimDevice: iPhone X (0C3A2C53-DA6D-47DE-991E-33541343A813, iOS 12.1, Booted) [07:37:10:INFO] } (12.1 (16B91)) [07:37:12:INFO] 2019-04-24 07:37:12.521 ios_showcase_egtests[12713:298216] Crash handler setup started. [07:37:12:INFO] 2019-04-24 07:37:12.522 ios_showcase_egtests[12713:298216] Crash handler setup completed. [07:37:12:INFO] 2019-04-24 07:37:12.522 ios_showcase_egtests[12713:298216] Enabling accessibility for automation on Simulator. [07:37:13:INFO] Test Suite 'Selected tests' started at 2019-04-24 07:37:13.829 [07:37:13:INFO] Test Suite 'ios_showcase_egtests_module.xctest' started at 2019-04-24 07:37:13.831 [07:37:13:INFO] Test Suite 'SCPaymentsPickerTestCase' started at 2019-04-24 07:37:13.832 [07:37:13:INFO] Test Case '-[SCPaymentsPickerTestCase testVerifyFiltering]' started. [07:37:31:INFO] 2019-04-24 07:37:31.792 ios_showcase_egtests[12713:298216] Attempting to type key c. [07:37:32:INFO] 2019-04-24 07:37:31.999 ios_showcase_egtests[12713:298216] Tapping on key: shift. [07:37:32:INFO] 2019-04-24 07:37:32.112 ios_showcase_egtests[12713:298216] Tapping on key: c. [07:37:32:INFO] 2019-04-24 07:37:32.232 ios_showcase_egtests[12713:298216] Attempting to type key h. [07:37:32:INFO] 2019-04-24 07:37:32.235 ios_showcase_egtests[12713:298216] Tapping on key: h. [07:37:32:INFO] 2019-04-24 07:37:32.334 ios_showcase_egtests[12713:298216] Attempting to type key i. [07:37:32:INFO] 2019-04-24 07:37:32.335 ios_showcase_egtests[12713:298216] Tapping on key: i. [07:39:07:INFO] 2019-04-24 07:39:07.639 ios_showcase_egtests[12713:298216] Attempting to type key l. [07:39:07:INFO] 2019-04-24 07:39:07.639 ios_showcase_egtests[12713:298216] Tapping on key: l. [07:42:07:INFO] Killing hung process 12701 [07:42:07:INFO] Process iossim is still alive! Xcodebuild process might block it. [07:42:07:DEBUG] List of running xcodebuild processes: [<psutil.Process(pid=12707, name='xcodebuild') at 4525947792>] [07:42:07:DEBUG] Finished print_process_output. [07:42:07:INFO] Waiting for test process to terminate. [07:42:07:INFO] Test process terminated. [07:42:07:DEBUG] Setting sigterm handler. [07:42:07:DEBUG] Stdout flushed after test process. [07:42:07:DEBUG] Processing test results. [07:42:07:INFO] /b/s/w/ir/src/out/Debug-iphonesimulator/iossim returned -15 [07:42:07:INFO] Retry #3 for SCPaymentsPickerTestCase/testVerifyFiltering. [07:42:07:INFO] Process was killed! [07:42:07:DEBUG] Setting sigterm handler. [07:42:09:INFO] User defaults from command line: [07:42:09:INFO] IDETestRunSpecificationPath = /var/folders/2j/22s2gz0s7hn48k32d47clxf80000gm/T/89EDAA07-AB0C-4C11-8B85-E5F44987FAA5 [07:42:09:INFO] [07:42:09:INFO] Testing started on 'iPhone X' [07:42:09:INFO] 2019-04-24 07:42:09.607 xcodebuild[12781:299502] IDETestOperationsObserverDebug: Writing diagnostic log for test session to: [07:42:09:INFO] /Users/chrome-bot/Library/Developer/Xcode/DerivedData/temporary-fzevnqyqkjuhvdeqgqsihhwikzee/Logs/Test/Test-Transient Testing-2019.04.24_07-42-09--0700.xcresult/1_Test/Diagnostics/ios_showcase_egtests_module-0E65719C-39AE-48D4-975A-754101EB4DF3/ios_showcase_egtests_module-09BFA114-B76A-4A85-90A3-79253F638A35/Session-ios_showcase_egtests_module-2019-04-24_074209-CBp8h9.log [07:42:09:INFO] 2019-04-24 07:42:09.608 xcodebuild[12781:299474] [MT] IDETestOperationsObserverDebug: (1D351386-D425-416D-9E16-C1B67B573345) Beginning test session ios_showcase_egtests_module-1D351386-D425-416D-9E16-C1B67B573345 at 2019-04-24 07:42:09.608 with Xcode 10B61 on target <DVTiPhoneSimulator: 0x7fea2e2243f0> { [07:42:09:INFO] SimDevice: iPhone X (0C3A2C53-DA6D-47DE-991E-33541343A813, iOS 12.1, Booted) [07:42:09:INFO] } (12.1 (16B91)) [07:42:11:INFO] 2019-04-24 07:42:11.588 ios_showcase_egtests[12786:299629] Crash handler setup started. [07:42:11:INFO] 2019-04-24 07:42:11.588 ios_showcase_egtests[12786:299629] Crash handler setup completed. [07:42:11:INFO] 2019-04-24 07:42:11.588 ios_showcase_egtests[12786:299629] Enabling accessibility for automation on Simulator. [07:42:12:INFO] Test Suite 'Selected tests' started at 2019-04-24 07:42:12.863 [07:42:12:INFO] Test Suite 'ios_showcase_egtests_module.xctest' started at 2019-04-24 07:42:12.864 [07:42:12:INFO] Test Suite 'SCPaymentsPickerTestCase' started at 2019-04-24 07:42:12.865 [07:42:12:INFO] Test Case '-[SCPaymentsPickerTestCase testVerifyFiltering]' started. [07:42:28:INFO] 2019-04-24 07:42:28.080 ios_showcase_egtests[12786:299629] Attempting to type key c. [07:42:28:INFO] 2019-04-24 07:42:28.286 ios_showcase_egtests[12786:299629] Tapping on key: shift. [07:42:29:INFO] 2019-04-24 07:42:29.141 ios_showcase_egtests[12786:299629] Tapping on key: c. [07:42:29:INFO] 2019-04-24 07:42:29.249 ios_showcase_egtests[12786:299629] Attempting to type key h. [07:42:29:INFO] 2019-04-24 07:42:29.251 ios_showcase_egtests[12786:299629] Tapping on key: h. [07:42:29:INFO] 2019-04-24 07:42:29.310 ios_showcase_egtests[12786:299629] Attempting to type key i. [07:42:29:INFO] 2019-04-24 07:42:29.310 ios_showcase_egtests[12786:299629] Tapping on key: i. [07:43:53:INFO] 2019-04-24 07:43:53.609 ios_showcase_egtests[12786:299629] Attempting to type key l. [07:43:53:INFO] 2019-04-24 07:43:53.609 ios_showcase_egtests[12786:299629] Tapping on key: l. [07:46:53:INFO] Killing hung process 12775 [07:46:53:INFO] Process iossim is still alive! Xcodebuild process might block it. [07:46:53:DEBUG] List of running xcodebuild processes: [<psutil.Process(pid=12781, name='xcodebuild') at 4525948112>] [07:46:53:DEBUG] Finished print_process_output. [07:46:53:INFO] Waiting for test process to terminate. [07:46:53:INFO] Process iossim is still alive! Xcodebuild process might block it. [07:46:53:INFO] Test process terminated. [07:46:53:DEBUG] Setting sigterm handler. [07:46:53:DEBUG] There are no running xcodebuild processes. [07:46:53:DEBUG] Stdout flushed after test process. [07:46:53:DEBUG] Processing test results. [07:46:53:INFO] /b/s/w/ir/src/out/Debug-iphonesimulator/iossim returned -15 [07:46:53:DEBUG] Extracting test data. [07:46:53:DEBUG] Retrieving crash reports. [07:46:53:DEBUG] Retrieving derived data. [07:46:53:DEBUG] Making desktop screenshots. [07:46:53:DEBUG] Killing simulators. [07:46:59:DEBUG] Wiping simulator. Device wiped. [07:47:01:DEBUG] End of tear_down. 2019-04-24 07:53:05,603 - root: [DEBUG] Contents of task_output_dir: ['/b/s/w/ir/k/recipe_cleanup/114nZElWD/4468879a36bd8e10'] 2019-04-24 07:53:05,603 - root: [WARNING] Expected output.json file missing: set(['/b/s/w/ir/k/recipe_cleanup/114nZElWD/4468879a36bd8e10/output.json']) Found: [] Expected: ['/b/s/w/ir/k/recipe_cleanup/114nZElWD/4468879a36bd8e10/output.json'] 2019-04-24 07:53:05,603 - root: [WARNING] No shard json files found in task_output_dir: '/b/s/w/ir/k/recipe_cleanup/114nZElWD' Found ['/b/s/w/ir/k/recipe_cleanup/114nZElWD/4468879a36bd8e10'] 2019-04-24 07:53:05,603 - root: [DEBUG] Found shard_json_files: ['/b/s/w/ir/k/recipe_cleanup/114nZElWD/4468879a36bd8e10/output.json'] 2019-04-24 07:53:05,604 - 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/tmpUK8Ua7.json --task-output-dir /b/s/w/ir/k/recipe_cleanup/114nZElWD -o /b/s/w/ir/tmp/t/tmpLz4FAp.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/tmpUK8Ua7.json', '--task-output-dir', '/b/s/w/ir/k/recipe_cleanup/114nZElWD', '-o', '/b/s/w/ir/tmp/t/tmpLz4FAp.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/tmpUK8Ua7.json', '--task-output-dir', '/b/s/w/ir/k/recipe_cleanup/114nZElWD', '-o', '/b/s/w/ir/tmp/t/tmpLz4FAp.json'] returned exit code 0