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/tmpw1D8gU.json --task-output-dir /b/s/w/ir/k/recipe_cleanup/22_5hSj7Bn --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/tmpZOCMRjmerge_script_log --merge-additional-args '[]' --summary-json-file /b/s/w/ir/tmp/t/tmpkXFOHL.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/tmpCznv_x.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/tmpw1D8gU.json', '--task-output-dir', '/b/s/w/ir/k/recipe_cleanup/22_5hSj7Bn', '--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/tmpZOCMRjmerge_script_log', '--merge-additional-args', '[]', '--summary-json-file', '/b/s/w/ir/tmp/t/tmpkXFOHL.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/tmpCznv_x.json'] env: {} env_prefixes: {'PATH': '/b/s/w/ir/cache/swarming_client'} env_suffixes: {} infra_step: False name_tokens: ('ios_chrome_ui_egtests (iPhone 7 iOS 11.4) shard 5 on Mac-10.13.6',) ok_ret: ALL_OK step_test_data: <lambda>(...) trigger_specs: () full environment: Apple_PubSub_Socket_Render: /private/tmp/com.apple.launchd.enlCZCtQsV/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: 49184 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/8918086242148063328 LOGDOG_STREAM_PROJECT: chromium LOGDOG_STREAM_SERVER_PATH: unix:/b/s/w/ir/tmp/ld.sock LOGNAME: chrome-bot LUCI_CONTEXT: /b/s/w/itIK2o26/luci_context.452888109 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.vFlUDbypRz/Listeners SWARMING_BOT_ID: build111-m9 SWARMING_HEADLESS: 1 SWARMING_SERVER: https://chromium-swarm.appspot.com SWARMING_TASK_ID: 43c97d0393b20511 TEMP: /b/s/w/ir/tmp/t TEMPDIR: /b/s/w/ir/tmp/t TMP: /b/s/w/ir/tmp/t TMPDIR: /b/s/w/ir/tmp/t USER: chrome-bot VERSIONER_PYTHON_PREFER_32_BIT: no VERSIONER_PYTHON_VERSION: 2.7 VPYTHON_VIRTUALENV_ROOT: /b/s/w/ir/cache/vpython XPC_FLAGS: 0x0 XPC_SERVICE_NAME: 0 _: /b/s/w/ir/cipd_bin_packages/vpython __CF_USER_TEXT_ENCODING: 0x1F4:0x0:0x0 Set PYTHONPATH: /b/s/w/ir/kitchen-checkout/build/scripts:/b/s/w/ir/kitchen-checkout/build/site_config:/b/s/w/ir/kitchen-checkout/build/third_party:/b/s/w/ir/kitchen-checkout/build/third_party/buildbot_8_4p1:/b/s/w/ir/kitchen-checkout/build/third_party/buildbot_slave_8_4:/b/s/w/ir/kitchen-checkout/build/third_party/coverage-3.7.1:/b/s/w/ir/kitchen-checkout/build/third_party/decorator_3_3_1:/b/s/w/ir/kitchen-checkout/build/third_party/google_api_python_client:/b/s/w/ir/kitchen-checkout/build/third_party/httplib2/python2:/b/s/w/ir/kitchen-checkout/build/third_party/infra_libs:/b/s/w/ir/kitchen-checkout/build/third_party/jinja2:/b/s/w/ir/kitchen-checkout/build/third_party/markupsafe:/b/s/w/ir/kitchen-checkout/build/third_party/oauth2client:/b/s/w/ir/kitchen-checkout/build/third_party/pyasn1:/b/s/w/ir/kitchen-checkout/build/third_party/pyasn1-modules:/b/s/w/ir/kitchen-checkout/build/third_party/python-rsa:/b/s/w/ir/kitchen-checkout/build/third_party/requests_2_10_0:/b/s/w/ir/kitchen-checkout/build/third_party/setuptools-0.6c11:/b/s/w/ir/kitchen-checkout/build/third_party/sqlalchemy_0_7_1:/b/s/w/ir/kitchen-checkout/build/third_party/sqlalchemy_migrate_0_7_1:/b/s/w/ir/kitchen-checkout/build/third_party/tempita_0_5:/b/s/w/ir/kitchen-checkout/build/third_party/twisted_10_2:/b/s/w/ir/kitchen-checkout/build/third_party/uritemplate:/b/s/w/ir/kitchen-checkout/build/third_party/site-packages 2019-03-24 11:05:20,056 - root: [DEBUG] Using task_output_dir: '/b/s/w/ir/k/recipe_cleanup/22_5hSj7Bn' 2019-03-24 11:05:20,056 - root: [WARNING] task_output_dir '/b/s/w/ir/k/recipe_cleanup/22_5hSj7Bn' already exists! 2019-03-24 11:05:20,056 - root: [WARNING] task_output_dir existing content: [] 2019-03-24 11:05:20,056 - 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/tmpCznv_x.json -output-dir /b/s/w/ir/k/recipe_cleanup/22_5hSj7Bn -task-summary-json /b/s/w/ir/tmp/t/tmpkXFOHL.json [D2019-03-24T11:05:20.070650-07:00 15256 0 auth.go:1265] Minting a new token {"key":"luci_ctx/00a7970b21ec10571d1521e0058a5f29ebaa354a6b687287289dc870e3cbe05d", "scopes":"https://www.googleapis.com/auth/userinfo.email"} [D2019-03-24T11:05:20.070720-07:00 15256 0 luci_ctx.go:138] POST http://127.0.0.1:49173/rpc/LuciLocalAuthService.GetOAuthToken {"key":"luci_ctx/00a7970b21ec10571d1521e0058a5f29ebaa354a6b687287289dc870e3cbe05d", "scopes":"https://www.googleapis.com/auth/userinfo.email"} [D2019-03-24T11:05:20.072250-07:00 15256 0 auth.go:1222] Token expires in 42m1.927768s {"key":"luci_ctx/00a7970b21ec10571d1521e0058a5f29ebaa354a6b687287289dc870e3cbe05d", "scopes":"https://www.googleapis.com/auth/userinfo.email"} 43c98682ed62ef10: exit 0 [I 2019-03-24 10:17:47] About to install Xcode 10b61 in Xcode.app for ios [10:17:49:INFO] Using Xcode version 10.1 build 10B61 at /b/s/w/ir/Xcode.app/Contents/Developer [10:18:03:INFO] Start test attempt #0 for command [xcodebuild test-without-building -xctestrun /b/s/w/iohFxnto/run_1553447883.xctestrun -destination platform=iOS Simulator,OS=11.4,name=iPhone 7 -resultBundlePath /b/s/w/iohFxnto/11.4 iPhone 7/attempt_0] [10:18:03:INFO] User defaults from command line: Erasing all simulators. Launching ['xcodebuild', 'test-without-building', '-xctestrun', '/b/s/w/iohFxnto/run_1553447883.xctestrun', '-destination', 'platform=iOS Simulator,OS=11.4,name=iPhone 7', '-resultBundlePath', '/b/s/w/iohFxnto/11.4 iPhone 7/attempt_0'] with env {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'MAC_CHROMIUM_TMPDIR': '/b/s/w/itilm8wF', 'LOGNAME': 'chrome-bot', 'USER': 'chrome-bot', 'HOME': '/Users/chrome-bot', 'BOTO_CONFIG': '/Users/chrome-bot/.boto', 'PATH': '/b/s/w/ir/.swarming_module_cache/vpython/b6ec90/bin:/b/s/w/ir/.swarming_module:/b/s/w/ir/.swarming_module/bin:/b/s/cipd_cache/bin:/opt/local/bin:/opt/local/sbin:/usr/local/sbin:/usr/local/git/bin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin', 'SWARMING_BOT_ID': 'vm995-m4', 'VIRTUAL_ENV': '/b/s/w/ir/.swarming_module_cache/vpython/b6ec90', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JXEyzgUmP9/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/itilm8wF/luci_ctx.i1f1AP.json', 'CIPD_CACHE_DIR': '/b/s/cipd_cache/cache', 'XPC_SERVICE_NAME': 'org.swarm.bot.plist', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sRnt9mstiu/Listeners', 'VPYTHON_VIRTUALENV_ROOT': '/b/s/w/ir/.swarming_module_cache/vpython', 'SWARMING_TASK_ID': '43c98682ed62ef11', 'SHELL': '/bin/bash', 'NO_GCE_CHECK': 'False', 'TMPDIR': '/b/s/w/itilm8wF', 'CIPD_PROTOCOL': 'v2', '__CF_USER_TEXT_ENCODING': '0x1F4:0x0:0x0', 'NSUnbufferedIO': 'YES'} [10:18:03:INFO] IDEBuildOperationResultBundlePath = /b/s/w/iohFxnto/11.4 iPhone 7/attempt_0 [10:18:03:INFO] IDETestRunSpecificationPath = /b/s/w/iohFxnto/run_1553447883.xctestrun [10:18:03:INFO] [10:18:04:INFO] Testing started on 'iPhone 7' [10:18:05:INFO] 2019-03-24 10:18:05.257 xcodebuild[24973:1208776] IDETestOperationsObserverDebug: Writing diagnostic log for test session to: [10:18:05:INFO] /b/s/w/iohFxnto/11.4 iPhone 7/attempt_0/1_Test/Diagnostics/ios_chrome_ui_egtests_module-FD1574BF-D455-4920-9CDC-E64E3B9A3542/ios_chrome_ui_egtests_module-4B4BD432-8FF8-489F-919D-F342CFB1141E/Session-ios_chrome_ui_egtests_module-2019-03-24_101805-Ocf77i.log [10:18:05:INFO] 2019-03-24 10:18:05.258 xcodebuild[24973:1208757] [MT] IDETestOperationsObserverDebug: (BC84910B-F56C-4FA8-8BE6-A19A05ACB53C) Beginning test session ios_chrome_ui_egtests_module-BC84910B-F56C-4FA8-8BE6-A19A05ACB53C at 2019-03-24 10:18:05.258 with Xcode 10B61 on target <DVTiPhoneSimulator: 0x7ffcf3b02bb0> { [10:18:05:INFO] SimDevice: iPhone 7 (227EE7DE-D24F-4526-B271-27AB354C87DE, iOS 11.4, Shutdown) [10:18:05:INFO] } (11.4 (15F79)) [10:18:30:INFO] 2019-03-24 10:18:30.917 ios_chrome_ui_egtests[25099:1210371] Crash handler setup started. [10:18:30:INFO] 2019-03-24 10:18:30.917 ios_chrome_ui_egtests[25099:1210371] Crash handler setup completed. [10:18:30:INFO] 2019-03-24 10:18:30.918 ios_chrome_ui_egtests[25099:1210371] Enabling accessibility for automation on Simulator. [10:18:31:INFO] objc[25099]: Class VCWeakObjectHolder is implemented in both /b/s/w/ir/Xcode.app/Contents/Developer/Platforms/iPhoneOS.platform/Developer/Library/CoreSimulator/Profiles/Runtimes/iOS 11.4.simruntime/Contents/Resources/RuntimeRoot/System/Library/PrivateFrameworks/AVConference.framework/Frameworks/ViceroyTrace.framework/ViceroyTrace (0x1422714d0) and /b/s/w/ir/Xcode.app/Contents/Developer/Platforms/iPhoneOS.platform/Developer/Library/CoreSimulator/Profiles/Runtimes/iOS 11.4.simruntime/Contents/Resources/RuntimeRoot/System/Library/PrivateFrameworks/AVConference.framework/AVConference (0x142087e38). One of the two will be used. Which one is undefined. [10:18:34:INFO] 2019-03-24 10:18:34.592 ios_chrome_ui_egtests[25099:1210371] Unbalanced calls to begin/end appearance transitions for <TabGridViewController: 0x7f7ff603c400>. [10:18:34:INFO] [0324/101834.860312:WARNING:leveldb_database.cc(106)] Unable to open /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/227EE7DE-D24F-4526-B271-27AB354C87DE/data/Containers/Data/Application/EC77ABEB-F298-4FC8-AF65-6EF0946BB3D2/Library/Application Support/Chromium/Default/shared_proto_db: Invalid argument: /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/227EE7DE-D24F-4526-B271-27AB354C87DE/data/Containers/Data/Application/EC77ABEB-F298-4FC8-AF65-6EF0946BB3D2/Library/Application Support/Chromium/Default/shared_proto_db: does not exist (create_if_missing is false) [10:18:41:INFO] Test Suite 'Selected tests' started at 2019-03-24 10:18:41.026 [10:18:41:INFO] Test Suite 'ios_chrome_ui_egtests_module.xctest' started at 2019-03-24 10:18:41.027 [10:18:41:INFO] Test Suite 'SadTabViewTestCase' started at 2019-03-24 10:18:41.027 [10:18:41:INFO] [0324/101841.037297:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist. [10:18:42:INFO] Test Case '-[SadTabViewTestCase testSadTabView]' started. [10:18:45:INFO] [0324/101845.081042:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [10:19:03:INFO] Test Case '-[SadTabViewTestCase testSadTabView]' passed (20.140 seconds). [10:19:03:INFO] Test Suite 'SadTabViewTestCase' passed at 2019-03-24 10:19:03.037. [10:19:03:INFO] Executed 1 test, with 0 failures (0 unexpected) in 20.140 (22.010) seconds [10:19:03:INFO] Test Suite 'SafeModeTestCase' started at 2019-03-24 10:19:03.038 [10:19:03:INFO] [0324/101903.039114:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist. [10:19:03:INFO] Test Case '-[SafeModeTestCase testSafeModeBothThirdPartyModsAndHasReport]' started. [10:19:07:INFO] Test Case '-[SafeModeTestCase testSafeModeBothThirdPartyModsAndHasReport]' passed (4.522 seconds). [10:19:07:INFO] Test Case '-[SafeModeTestCase testSafeModeDetectedThirdPartyMods]' started. [10:19:12:INFO] Test Case '-[SafeModeTestCase testSafeModeDetectedThirdPartyMods]' passed (4.862 seconds). [10:19:12:INFO] Test Case '-[SafeModeTestCase testSafeModeSendingCrashReport]' started. [10:19:17:INFO] Test Case '-[SafeModeTestCase testSafeModeSendingCrashReport]' passed (4.918 seconds). [10:19:17:INFO] Test Suite 'SafeModeTestCase' passed at 2019-03-24 10:19:17.347. [10:19:17:INFO] Executed 3 tests, with 0 failures (0 unexpected) in 14.302 (14.309) seconds [10:19:17:INFO] Test Suite 'SaveCardInfobarEGTest' started at 2019-03-24 10:19:17.347 [10:19:17:INFO] [0324/101917.349058:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist. [10:19:17:INFO] Test Case '-[SaveCardInfobarEGTest testNotOfferLocalSave_MaxStrikesReached]' started. [10:19:19:INFO] [0324/101919.494522:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [10:19:20:INFO] [0324/101920.557492:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /credit_card_upload_done.html [10:19:27:INFO] Test Case '-[SaveCardInfobarEGTest testNotOfferLocalSave_MaxStrikesReached]' passed (10.583 seconds). [10:19:27:INFO] Test Case '-[SaveCardInfobarEGTest testNotOfferLocalSave_PartialData_PaymentsDeclines]' started. [10:19:31:INFO] [0324/101931.069897:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /credit_card_upload_done.html [10:19:38:INFO] Test Case '-[SaveCardInfobarEGTest testNotOfferLocalSave_PartialData_PaymentsDeclines]' passed (10.110 seconds). [10:19:38:INFO] Test Case '-[SaveCardInfobarEGTest testOfferLocalSave_FullData_PaymentsDeclines_StrikeDatabaseDisabled]' started. [10:19:41:INFO] [0324/101941.142375:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /credit_card_upload_done.html [10:19:42:INFO] 2019-03-24 10:19:42.243 ios_chrome_ui_egtests[25099:1210371] <CATransformLayer: 0x6000006271a0> - changing property masksToBounds in transform-only layer, will have no effect [10:19:42:INFO] 2019-03-24 10:19:42.243 ios_chrome_ui_egtests[25099:1210371] <CATransformLayer: 0x604000634660> - changing property masksToBounds in transform-only layer, will have no effect [10:19:42:INFO] 2019-03-24 10:19:42.245 ios_chrome_ui_egtests[25099:1210371] <CATransformLayer: 0x618000637a00> - changing property masksToBounds in transform-only layer, will have no effect [10:19:44:INFO] Test Case '-[SaveCardInfobarEGTest testOfferLocalSave_FullData_PaymentsDeclines_StrikeDatabaseDisabled]' passed (6.525 seconds). [10:19:44:INFO] Test Case '-[SaveCardInfobarEGTest testOfferLocalSave_FullData_PaymentsDeclines_StrikeDatabaseEnabled]' started. [10:19:47:INFO] [0324/101947.743103:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /credit_card_upload_done.html [10:19:48:INFO] 2019-03-24 10:19:48.836 ios_chrome_ui_egtests[25099:1210371] <CATransformLayer: 0x61400062a2a0> - changing property masksToBounds in transform-only layer, will have no effect [10:19:48:INFO] 2019-03-24 10:19:48.837 ios_chrome_ui_egtests[25099:1210371] <CATransformLayer: 0x60c00082c8c0> - changing property masksToBounds in transform-only layer, will have no effect [10:19:48:INFO] 2019-03-24 10:19:48.838 ios_chrome_ui_egtests[25099:1210371] <CATransformLayer: 0x608000623d20> - changing property masksToBounds in transform-only layer, will have no effect [10:19:51:INFO] Test Case '-[SaveCardInfobarEGTest testOfferLocalSave_FullData_PaymentsDeclines_StrikeDatabaseEnabled]' passed (6.564 seconds). [10:19:51:INFO] Test Case '-[SaveCardInfobarEGTest testOfferLocalSave_FullData_RequestFails_StrikeDatabaseDisabled]' started. [10:19:54:INFO] [0324/101954.238286:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /credit_card_upload_done.html [10:19:55:INFO] 2019-03-24 10:19:55.349 ios_chrome_ui_egtests[25099:1210371] <CATransformLayer: 0x6100006214c0> - changing property masksToBounds in transform-only layer, will have no effect [10:19:55:INFO] 2019-03-24 10:19:55.349 ios_chrome_ui_egtests[25099:1210371] <CATransformLayer: 0x618000634940> - changing property masksToBounds in transform-only layer, will have no effect [10:19:55:INFO] 2019-03-24 10:19:55.351 ios_chrome_ui_egtests[25099:1210371] <CATransformLayer: 0x608000426800> - changing property masksToBounds in transform-only layer, will have no effect [10:19:57:INFO] Test Case '-[SaveCardInfobarEGTest testOfferLocalSave_FullData_RequestFails_StrikeDatabaseDisabled]' passed (6.488 seconds). [10:19:57:INFO] Test Case '-[SaveCardInfobarEGTest testOfferLocalSave_FullData_RequestFails_StrikeDatabaseEnabled]' started. [10:20:00:INFO] [0324/102000.788747:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /credit_card_upload_done.html [10:20:01:INFO] 2019-03-24 10:20:01.881 ios_chrome_ui_egtests[25099:1210371] <CATransformLayer: 0x60400042f400> - changing property masksToBounds in transform-only layer, will have no effect [10:20:01:INFO] 2019-03-24 10:20:01.881 ios_chrome_ui_egtests[25099:1210371] <CATransformLayer: 0x61800062eb40> - changing property masksToBounds in transform-only layer, will have no effect [10:20:01:INFO] 2019-03-24 10:20:01.883 ios_chrome_ui_egtests[25099:1210371] <CATransformLayer: 0x608000423c20> - changing property masksToBounds in transform-only layer, will have no effect [10:20:04:INFO] Test Case '-[SaveCardInfobarEGTest testOfferLocalSave_FullData_RequestFails_StrikeDatabaseEnabled]' passed (6.546 seconds). [10:20:04:INFO] Test Case '-[SaveCardInfobarEGTest testOfferUpstream_FullData_PaymentsAccepts_StrikeDatabaseDisabled]' started. [10:20:07:INFO] [0324/102007.353554:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /credit_card_upload_done.html [10:20:08:INFO] 2019-03-24 10:20:08.448 ios_chrome_ui_egtests[25099:1210371] <CATransformLayer: 0x600000a20600> - changing property masksToBounds in transform-only layer, will have no effect [10:20:08:INFO] 2019-03-24 10:20:08.449 ios_chrome_ui_egtests[25099:1210371] <CATransformLayer: 0x6040006235a0> - changing property masksToBounds in transform-only layer, will have no effect [10:20:08:INFO] 2019-03-24 10:20:08.452 ios_chrome_ui_egtests[25099:1210371] <CATransformLayer: 0x60000063e720> - changing property masksToBounds in transform-only layer, will have no effect [10:20:10:INFO] Test Case '-[SaveCardInfobarEGTest testOfferUpstream_FullData_PaymentsAccepts_StrikeDatabaseDisabled]' passed (6.602 seconds). [10:20:10:INFO] Test Case '-[SaveCardInfobarEGTest testOfferUpstream_FullData_PaymentsAccepts_StrikeDatabaseEnabled]' started. [10:20:13:INFO] [0324/102013.901224:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /credit_card_upload_done.html [10:20:14:INFO] 2019-03-24 10:20:14.995 ios_chrome_ui_egtests[25099:1210371] <CATransformLayer: 0x60000043d800> - changing property masksToBounds in transform-only layer, will have no effect [10:20:14:INFO] 2019-03-24 10:20:14.996 ios_chrome_ui_egtests[25099:1210371] <CATransformLayer: 0x610000620300> - changing property masksToBounds in transform-only layer, will have no effect [10:20:14:INFO] 2019-03-24 10:20:14.998 ios_chrome_ui_egtests[25099:1210371] <CATransformLayer: 0x60400042a420> - changing property masksToBounds in transform-only layer, will have no effect [10:20:17:INFO] Test Case '-[SaveCardInfobarEGTest testOfferUpstream_FullData_PaymentsAccepts_StrikeDatabaseEnabled]' passed (6.552 seconds). [10:20:17:INFO] Test Case '-[SaveCardInfobarEGTest testOfferUpstream_PartialData_PaymentsAccepts_StrikeDatabaseDisabled]' started. [10:20:20:INFO] [0324/102020.464440:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /credit_card_upload_done.html [10:20:21:INFO] 2019-03-24 10:20:21.565 ios_chrome_ui_egtests[25099:1210371] <CATransformLayer: 0x6040006262a0> - changing property masksToBounds in transform-only layer, will have no effect [10:20:21:INFO] 2019-03-24 10:20:21.566 ios_chrome_ui_egtests[25099:1210371] <CATransformLayer: 0x618000430500> - changing property masksToBounds in transform-only layer, will have no effect [10:20:21:INFO] 2019-03-24 10:20:21.569 ios_chrome_ui_egtests[25099:1210371] <CATransformLayer: 0x60800022f920> - changing property masksToBounds in transform-only layer, will have no effect [10:20:23:INFO] Test Case '-[SaveCardInfobarEGTest testOfferUpstream_PartialData_PaymentsAccepts_StrikeDatabaseDisabled]' passed (6.560 seconds). [10:20:23:INFO] Test Case '-[SaveCardInfobarEGTest testOfferUpstream_PartialData_PaymentsAccepts_StrikeDatabaseEnabled]' started. [10:20:26:INFO] [0324/102026.965218:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /credit_card_upload_done.html [10:20:28:INFO] 2019-03-24 10:20:28.058 ios_chrome_ui_egtests[25099:1210371] <CATransformLayer: 0x618000623c80> - changing property masksToBounds in transform-only layer, will have no effect [10:20:28:INFO] 2019-03-24 10:20:28.059 ios_chrome_ui_egtests[25099:1210371] <CATransformLayer: 0x60800042cf20> - changing property masksToBounds in transform-only layer, will have no effect [10:20:28:INFO] 2019-03-24 10:20:28.061 ios_chrome_ui_egtests[25099:1210371] <CATransformLayer: 0x61c0004396e0> - changing property masksToBounds in transform-only layer, will have no effect [10:20:30:INFO] Test Case '-[SaveCardInfobarEGTest testOfferUpstream_PartialData_PaymentsAccepts_StrikeDatabaseEnabled]' passed (6.494 seconds). [10:20:30:INFO] Test Case '-[SaveCardInfobarEGTest testUMA_Upstream_UserAccepts]' started. [10:20:32:INFO] 2019-03-24 10:20:32.178 ios_chrome_ui_egtests[25099:1210371] -- Earl Grey Test Disabled -- Test disabled. [10:20:34:INFO] Test Case '-[SaveCardInfobarEGTest testUMA_Upstream_UserAccepts]' passed (3.646 seconds). [10:20:34:INFO] Test Case '-[SaveCardInfobarEGTest testUMA_Upstream_UserDeclines_StrikeDatabaseDisabled]' started. [10:20:35:INFO] 2019-03-24 10:20:35.793 ios_chrome_ui_egtests[25099:1210371] -- Earl Grey Test Disabled -- Failing regularly on the bots. [10:20:37:INFO] Test Case '-[SaveCardInfobarEGTest testUMA_Upstream_UserDeclines_StrikeDatabaseDisabled]' passed (3.616 seconds). [10:20:37:INFO] Test Case '-[SaveCardInfobarEGTest testUMA_Upstream_UserDeclines_StrikeDatabaseEnabled]' started. [10:20:39:INFO] 2019-03-24 10:20:39.490 ios_chrome_ui_egtests[25099:1210371] -- Earl Grey Test Disabled -- Failing regularly on the bots. [10:20:41:INFO] Test Case '-[SaveCardInfobarEGTest testUMA_Upstream_UserDeclines_StrikeDatabaseEnabled]' passed (3.693 seconds). [10:20:41:INFO] Test Case '-[SaveCardInfobarEGTest testUserData_LocalSave_UserAccepts]' started. [10:20:44:INFO] [0324/102044.436065:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /credit_card_upload_done.html [10:20:45:INFO] 2019-03-24 10:20:45.546 ios_chrome_ui_egtests[25099:1210371] <CATransformLayer: 0x61400042dd60> - changing property masksToBounds in transform-only layer, will have no effect [10:20:45:INFO] 2019-03-24 10:20:45.547 ios_chrome_ui_egtests[25099:1210371] <CATransformLayer: 0x6100006256e0> - changing property masksToBounds in transform-only layer, will have no effect [10:20:45:INFO] 2019-03-24 10:20:45.549 ios_chrome_ui_egtests[25099:1210371] <CATransformLayer: 0x604000439580> - changing property masksToBounds in transform-only layer, will have no effect [10:20:45:INFO] 2019-03-24 10:20:45.934 ios_chrome_ui_egtests[25099:1210371] Button touch target does not meet minimum size guidlines of (48, 48). Button: <MDCFlatButton: 0x7f7ff50634d0; baseClass = UIButton; frame = (288 32; 71 37); opaque = NO; tintColor = UIExtendedGrayColorSpace 1 1; layer = <MDCShapedShadowLayer: 0x6100004e0880>>, Touch Target: {71, 37} [10:20:48:INFO] Test Case '-[SaveCardInfobarEGTest testUserData_LocalSave_UserAccepts]' passed (6.876 seconds). [10:20:48:INFO] Test Case '-[SaveCardInfobarEGTest testUserData_LocalSave_UserDeclines_StrikeDatabaseDisabled]' started. [10:20:49:INFO] 2019-03-24 10:20:49.976 ios_chrome_ui_egtests[25099:1210371] -- Earl Grey Test Disabled -- Failing regularly on the bots. [10:20:51:INFO] Test Case '-[SaveCardInfobarEGTest testUserData_LocalSave_UserDeclines_StrikeDatabaseDisabled]' passed (3.612 seconds). [10:20:51:INFO] Test Case '-[SaveCardInfobarEGTest testUserData_LocalSave_UserDeclines_StrikeDatabaseEnabled]' started. [10:20:53:INFO] 2019-03-24 10:20:53.641 ios_chrome_ui_egtests[25099:1210371] -- Earl Grey Test Disabled -- Failing regularly on the bots. [10:20:55:INFO] Test Case '-[SaveCardInfobarEGTest testUserData_LocalSave_UserDeclines_StrikeDatabaseEnabled]' passed (3.661 seconds). [10:20:55:INFO] Test Suite 'SaveCardInfobarEGTest' passed at 2019-03-24 10:20:55.516. [10:20:55:INFO] Executed 16 tests, with 0 failures (0 unexpected) in 98.129 (98.168) seconds [10:20:55:INFO] Test Suite 'SaveProfileEGTest' started at 2019-03-24 10:20:55.517 [10:20:55:INFO] [0324/102055.518030:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist. [10:20:55:INFO] Test Case '-[SaveProfileEGTest testUserData_LocalSave]' started. [10:20:57:INFO] [0324/102057.580125:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [10:21:01:INFO] Test Case '-[SaveProfileEGTest testUserData_LocalSave]' passed (6.013 seconds). [10:21:01:INFO] Test Suite 'SaveProfileEGTest' passed at 2019-03-24 10:21:01.536. [10:21:01:INFO] Executed 1 test, with 0 failures (0 unexpected) in 6.013 (6.020) seconds [10:21:01:INFO] Test Suite 'ShortcutsTestCase' started at 2019-03-24 10:21:01.537 [10:21:01:INFO] [0324/102101.538468:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist. [10:21:01:INFO] Test Case '-[ShortcutsTestCase testBookmarksShortcut]' started. [10:21:03:INFO] [0324/102103.849993:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [10:21:18:INFO] Test Case '-[ShortcutsTestCase testBookmarksShortcut]' passed (16.574 seconds). [10:21:18:INFO] Test Case '-[ShortcutsTestCase testHistoryShortcut]' started. [10:21:20:INFO] [0324/102120.295002:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [10:21:34:INFO] Test Case '-[ShortcutsTestCase testHistoryShortcut]' passed (16.318 seconds). [10:21:34:INFO] Test Case '-[ShortcutsTestCase testNotShownWhenSuggestionsAvailable]' started. [10:21:36:INFO] [0324/102136.657390:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [10:21:44:INFO] 2019-03-24 10:21:44.477 ios_chrome_ui_egtests[25099:1210371] Attempting to type key f. [10:21:44:INFO] 2019-03-24 10:21:44.478 ios_chrome_ui_egtests[25099:1210371] Tapping on key: f. [10:21:44:INFO] 2019-03-24 10:21:44.589 ios_chrome_ui_egtests[25099:1210371] Attempting to type key o. [10:21:44:INFO] 2019-03-24 10:21:44.590 ios_chrome_ui_egtests[25099:1210371] Tapping on key: o. [10:21:44:INFO] 2019-03-24 10:21:44.647 ios_chrome_ui_egtests[25099:1210371] Attempting to type key o. [10:21:44:INFO] 2019-03-24 10:21:44.648 ios_chrome_ui_egtests[25099:1210371] Tapping on key: o. [10:21:44:INFO] [0324/102144.694747:WARNING:spdy_session.cc(3232)] Received HEADERS for invalid stream 5 [10:21:47:INFO] Test Case '-[ShortcutsTestCase testNotShownWhenSuggestionsAvailable]' passed (13.317 seconds). [10:21:47:INFO] Test Case '-[ShortcutsTestCase testNTPShortcutsDontShowUp]' started. [10:21:49:INFO] [0324/102149.959083:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [10:22:03:INFO] Test Case '-[ShortcutsTestCase testNTPShortcutsDontShowUp]' passed (15.450 seconds). [10:22:03:INFO] Test Case '-[ShortcutsTestCase testReadingListShortcut]' started. [10:22:05:INFO] [0324/102205.389492:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [10:22:19:INFO] Test Case '-[ShortcutsTestCase testReadingListShortcut]' passed (16.333 seconds). [10:22:19:INFO] Test Case '-[ShortcutsTestCase testRecentTabsShortcut]' started. [10:22:21:INFO] [0324/102221.770295:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [10:22:35:INFO] Test Case '-[ShortcutsTestCase testRecentTabsShortcut]' passed (16.440 seconds). [10:22:35:INFO] Test Case '-[ShortcutsTestCase testShowsUp]' started. [10:22:38:INFO] [0324/102238.136288:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [10:22:48:INFO] Test Case '-[ShortcutsTestCase testShowsUp]' passed (12.895 seconds). [10:22:48:INFO] Test Case '-[ShortcutsTestCase testShowsUpWhenOmniboxIsEmpty]' started. [10:22:51:INFO] [0324/102251.070271:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [10:22:58:INFO] 2019-03-24 10:22:58.906 ios_chrome_ui_egtests[25099:1210371] Attempting to type key . [10:22:58:INFO] 2019-03-24 10:22:58.907 ios_chrome_ui_egtests[25099:1210371] Tapping on key: delete. [10:23:02:INFO] Test Case '-[ShortcutsTestCase testShowsUpWhenOmniboxIsEmpty]' passed (13.245 seconds). [10:23:02:INFO] Test Case '-[ShortcutsTestCase testTapMostVisitedTile]' started. [10:23:04:INFO] [0324/102304.291406:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [10:23:15:INFO] Test Case '-[ShortcutsTestCase testTapMostVisitedTile]' passed (13.387 seconds). [10:23:15:INFO] Test Suite 'ShortcutsTestCase' passed at 2019-03-24 10:23:15.506. [10:23:15:INFO] Executed 9 tests, with 0 failures (0 unexpected) in 133.958 (133.969) seconds [10:23:15:INFO] Test Suite 'SideSwipeTestCase' started at 2019-03-24 10:23:15.507 [10:23:15:INFO] [0324/102315.507824:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist. [10:23:15:INFO] Test Case '-[SideSwipeTestCase testSideSwipeBottomToolbar]' started. [10:23:17:INFO] [0324/102317.505217:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [10:23:27:INFO] Test Case '-[SideSwipeTestCase testSideSwipeBottomToolbar]' passed (11.594 seconds). [10:23:27:INFO] Test Case '-[SideSwipeTestCase testSideSwipeTopToolbar]' started. [10:23:29:INFO] [0324/102329.151932:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [10:23:38:INFO] Test Case '-[SideSwipeTestCase testSideSwipeTopToolbar]' passed (11.624 seconds). [10:23:38:INFO] Test Suite 'SideSwipeTestCase' passed at 2019-03-24 10:23:38.735. [10:23:38:INFO] Executed 2 tests, with 0 failures (0 unexpected) in 23.218 (23.228) seconds [10:23:38:INFO] Test Suite 'SigninInteractionControllerTestCase' started at 2019-03-24 10:23:38.736 [10:23:38:INFO] [0324/102338.737825:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist. [10:23:38:INFO] Test Case '-[SigninInteractionControllerTestCase testSignInCancelAddAccount]' started. [10:23:58:INFO] Test Case '-[SigninInteractionControllerTestCase testSignInCancelAddAccount]' passed (19.892 seconds). [10:23:58:INFO] Test Case '-[SigninInteractionControllerTestCase testSignInCancelAuthenticationFlow]' started. [10:24:06:INFO] [0324/102406.829972:WARNING:sync_encryption_handler_impl.cc(1086)] Nigori had empty encryption keybag. [10:24:06:INFO] [0324/102406.839269:WARNING:sync_encryption_handler_impl.cc(1086)] Nigori had empty encryption keybag. [10:24:06:INFO] [0324/102406.865021:ERROR:account_tracker.cc(264)] OnOAuthError [10:24:06:INFO] [0324/102406.865185:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID [10:24:06:INFO] [0324/102406.895077:ERROR:account_tracker.cc(264)] OnOAuthError [10:24:06:INFO] [0324/102406.895213:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo2ID [10:24:07:INFO] [0324/102407.853218:INFO:profile_sync_service.cc(1822)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now [10:24:19:INFO] [0324/102419.719870:INFO:signin_manager.cc(128)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1 [10:24:35:INFO] Test Case '-[SigninInteractionControllerTestCase testSignInCancelAuthenticationFlow]' passed (37.176 seconds). [10:24:35:INFO] Test Case '-[SigninInteractionControllerTestCase testSignInCancelFromBookmarks]' started. [10:24:55:INFO] Test Case '-[SigninInteractionControllerTestCase testSignInCancelFromBookmarks]' passed (19.654 seconds). [10:24:55:INFO] Test Case '-[SigninInteractionControllerTestCase testSignInCancelIdentityPicker]' started. [10:25:14:INFO] Test Case '-[SigninInteractionControllerTestCase testSignInCancelIdentityPicker]' passed (19.361 seconds). [10:25:14:INFO] Test Case '-[SigninInteractionControllerTestCase testSignInDisconnectFromChrome]' started. [10:25:23:INFO] [0324/102523.328025:ERROR:account_tracker.cc(264)] OnOAuthError [10:25:23:INFO] [0324/102523.328212:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID [10:25:24:INFO] [0324/102524.331348:INFO:profile_sync_service.cc(1822)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now [10:25:24:INFO] [0324/102524.406097:WARNING:session_sync_bridge.cc(228)] Dropping modification to local session. [10:25:36:INFO] [0324/102536.047977:INFO:signin_manager.cc(128)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1 [10:25:41:INFO] Test Case '-[SigninInteractionControllerTestCase testSignInDisconnectFromChrome]' passed (26.940 seconds). [10:25:41:INFO] Test Case '-[SigninInteractionControllerTestCase testSignInOneUser]' started. [10:25:50:INFO] [0324/102550.192491:ERROR:account_tracker.cc(264)] OnOAuthError [10:25:50:INFO] [0324/102550.192635:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID [10:25:51:INFO] [0324/102551.204925:INFO:profile_sync_service.cc(1822)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now [10:25:51:INFO] [0324/102551.280478:WARNING:session_sync_bridge.cc(228)] Dropping modification to local session. [10:25:55:INFO] [0324/102555.044705:INFO:signin_manager.cc(128)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1 [10:25:57:INFO] Test Case '-[SigninInteractionControllerTestCase testSignInOneUser]' passed (15.243 seconds). [10:25:57:INFO] Test Case '-[SigninInteractionControllerTestCase testSignInOpenSettings]' started. [10:26:05:INFO] [0324/102605.471622:ERROR:account_tracker.cc(264)] OnOAuthError [10:26:05:INFO] [0324/102605.471772:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID [10:26:09:INFO] [0324/102609.405593:INFO:profile_sync_service.cc(1822)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now [10:26:09:INFO] [0324/102609.511502:WARNING:session_sync_bridge.cc(228)] Dropping modification to local session. [10:26:11:INFO] [0324/102611.152572:INFO:signin_manager.cc(128)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1 [10:26:13:INFO] Test Case '-[SigninInteractionControllerTestCase testSignInOpenSettings]' passed (16.108 seconds). [10:26:13:INFO] Test Case '-[SigninInteractionControllerTestCase testSignInSwitchAccountsAndImportData]' started. [10:26:21:INFO] [0324/102621.603551:ERROR:account_tracker.cc(264)] OnOAuthError [10:26:21:INFO] [0324/102621.603691:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID [10:26:21:INFO] [0324/102621.628574:ERROR:account_tracker.cc(264)] OnOAuthError [10:26:21:INFO] [0324/102621.628728:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo2ID [10:26:22:INFO] [0324/102622.612884:INFO:profile_sync_service.cc(1822)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now [10:26:22:INFO] [0324/102622.687309:WARNING:session_sync_bridge.cc(228)] Dropping modification to local session. [10:26:38:INFO] [0324/102638.869360:INFO:signin_manager.cc(128)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1 [10:26:39:INFO] [0324/102639.070730:ERROR:account_tracker.cc(264)] OnOAuthError [10:26:39:INFO] [0324/102639.070893:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID [10:26:39:INFO] [0324/102639.071614:ERROR:account_tracker.cc(264)] OnOAuthError [10:26:39:INFO] [0324/102639.071732:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo2ID [10:26:39:INFO] [0324/102639.259161:WARNING:gaia_auth_fetcher.cc(898)] ClientLogin failed with [10:26:39:INFO] [0324/102639.259314:WARNING:gaia_auth_fetcher.cc(921)] Incomprehensible response from Google Accounts servers. [10:26:40:INFO] [0324/102640.439461:INFO:profile_sync_service.cc(1822)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now [10:26:40:INFO] [0324/102640.512708:WARNING:model_type_controller.cc(197)] Deferring stop for Reading List because it's still starting [10:26:40:INFO] [0324/102640.512889:WARNING:model_type_controller.cc(197)] Deferring stop for User Events because it's still starting [10:26:40:INFO] [0324/102640.513029:WARNING:model_type_controller.cc(197)] Deferring stop for User Consents because it's still starting [10:26:40:INFO] [0324/102640.513270:INFO:profile_sync_service.cc(1092)] ProfileSyncService::Observe Sync Configure aborted [10:26:40:INFO] [0324/102640.515581:INFO:signin_manager.cc(128)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1 [10:26:44:INFO] Test Case '-[SigninInteractionControllerTestCase testSignInSwitchAccountsAndImportData]' passed (30.919 seconds). [10:26:44:INFO] Test Case '-[SigninInteractionControllerTestCase testSignInSwitchAccountsAndKeepDataSeparate]' started. [10:26:52:INFO] [0324/102652.505990:ERROR:account_tracker.cc(264)] OnOAuthError [10:26:52:INFO] [0324/102652.506138:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID [10:26:52:INFO] [0324/102652.523941:ERROR:account_tracker.cc(264)] OnOAuthError [10:26:52:INFO] [0324/102652.524086:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo2ID [10:26:53:INFO] [0324/102653.515119:INFO:profile_sync_service.cc(1822)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now [10:26:53:INFO] [0324/102653.595985:WARNING:session_sync_bridge.cc(228)] Dropping modification to local session. [10:27:10:INFO] [0324/102709.999717:INFO:signin_manager.cc(128)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1 [10:27:10:INFO] [0324/102710.734167:ERROR:account_tracker.cc(264)] OnOAuthError [10:27:10:INFO] [0324/102710.734339:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID [10:27:10:INFO] [0324/102710.737263:ERROR:account_tracker.cc(264)] OnOAuthError [10:27:10:INFO] [0324/102710.737406:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo2ID [10:27:12:INFO] [0324/102712.167954:INFO:profile_sync_service.cc(1822)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now [10:27:12:INFO] [0324/102712.248040:INFO:profile_sync_service.cc(1092)] ProfileSyncService::Observe Sync Configure aborted [10:27:12:INFO] [0324/102712.251345:INFO:signin_manager.cc(128)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1 [10:27:12:INFO] [0324/102712.262092:WARNING:syncer_proto_util.cc(379)] Error posting from syncer: Response Code (bogus on error): -1 Content-Length (bogus on error): -1 Server Status: CONNECTION_UNAVAILABLE [10:27:12:INFO] [0324/102712.262472:ERROR:get_updates_processor.cc(242)] PostClientToServerMessage() failed during GetUpdates [10:27:15:INFO] Test Case '-[SigninInteractionControllerTestCase testSignInSwitchAccountsAndKeepDataSeparate]' passed (31.753 seconds). [10:27:15:INFO] Test Case '-[SigninInteractionControllerTestCase testSignInSwitchManagedAccount]' started. [10:27:25:INFO] [0324/102725.346109:ERROR:account_tracker.cc(264)] OnOAuthError [10:27:25:INFO] [0324/102725.346253:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID [10:27:25:INFO] [0324/102725.378191:ERROR:account_tracker.cc(264)] OnOAuthError [10:27:25:INFO] [0324/102725.378373:WARNING:account_tracker.cc(190)] Failed to get UserInfo for fooManagedID [10:27:26:INFO] [0324/102726.099224:INFO:profile_sync_service.cc(1822)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now [10:27:26:INFO] [0324/102726.207294:WARNING:session_sync_bridge.cc(228)] Dropping modification to local session. [10:27:33:INFO] [0324/102733.976415:INFO:signin_manager.cc(128)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1 [10:27:34:INFO] [0324/102734.776220:ERROR:account_tracker.cc(264)] OnOAuthError [10:27:34:INFO] [0324/102734.776364:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID [10:27:34:INFO] [0324/102734.780114:ERROR:account_tracker.cc(264)] OnOAuthError [10:27:34:INFO] [0324/102734.780252:WARNING:account_tracker.cc(190)] Failed to get UserInfo for fooManagedID [10:27:36:INFO] [0324/102736.166475:INFO:profile_sync_service.cc(1822)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now [10:27:36:INFO] [0324/102736.240583:INFO:profile_sync_service.cc(1092)] ProfileSyncService::Observe Sync Configure aborted [10:27:36:INFO] [0324/102736.242875:INFO:signin_manager.cc(128)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1 [10:27:36:INFO] [0324/102736.252865:WARNING:syncer_proto_util.cc(379)] Error posting from syncer: Response Code (bogus on error): -1 Content-Length (bogus on error): -1 Server Status: CONNECTION_UNAVAILABLE [10:27:36:INFO] [0324/102736.253082:ERROR:get_updates_processor.cc(242)] PostClientToServerMessage() failed during GetUpdates [10:27:39:INFO] Test Case '-[SigninInteractionControllerTestCase testSignInSwitchManagedAccount]' passed (24.003 seconds). [10:27:39:INFO] Test Suite 'SigninInteractionControllerTestCase' passed at 2019-03-24 10:27:39.796. [10:27:39:INFO] Executed 10 tests, with 0 failures (0 unexpected) in 241.048 (241.060) seconds [10:27:39:INFO] Test Suite 'TabStripTestCase' started at 2019-03-24 10:27:39.797 [10:27:39:INFO] [0324/102739.798971:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist. [10:27:39:INFO] Test Case '-[TabStripTestCase testTabStripSwitchTabs]' started. [10:27:43:INFO] Test Case '-[TabStripTestCase testTabStripSwitchTabs]' passed (3.606 seconds). [10:27:43:INFO] Test Suite 'TabStripTestCase' passed at 2019-03-24 10:27:43.422. [10:27:43:INFO] Executed 1 test, with 0 failures (0 unexpected) in 3.606 (3.625) seconds [10:27:43:INFO] Test Suite 'ios_chrome_ui_egtests_module.xctest' passed at 2019-03-24 10:27:43.423. [10:27:43:INFO] Executed 43 tests, with 0 failures (0 unexpected) in 540.414 (542.396) seconds [10:27:43:INFO] Test Suite 'Selected tests' passed at 2019-03-24 10:27:43.424. [10:27:43:INFO] Executed 43 tests, with 0 failures (0 unexpected) in 540.414 (542.398) seconds [10:27:43:INFO] [10:27:43:INFO] [10:27:43:INFO] Test session log: [10:27:43:INFO] /b/s/w/iohFxnto/11.4 iPhone 7/attempt_0/1_Test/Diagnostics/ios_chrome_ui_egtests_module-FD1574BF-D455-4920-9CDC-E64E3B9A3542/ios_chrome_ui_egtests_module-4B4BD432-8FF8-489F-919D-F342CFB1141E/Session-ios_chrome_ui_egtests_module-2019-03-24_101805-Ocf77i.log [10:27:43:INFO] [10:27:43:INFO] 2019-03-24 10:27:43.878 xcodebuild[24973:1208757] [MT] IDETestOperationsObserverDebug: 579.267 elapsed -- Testing started completed. [10:27:43:INFO] 2019-03-24 10:27:43.878 xcodebuild[24973:1208757] [MT] IDETestOperationsObserverDebug: 0.000 sec, +0.000 sec -- start [10:27:43:INFO] 2019-03-24 10:27:43.879 xcodebuild[24973:1208757] [MT] IDETestOperationsObserverDebug: 579.267 sec, +579.267 sec -- end [10:27:43:INFO] ** TEST EXECUTE SUCCEEDED ** [10:27:43:INFO] [10:27:48:INFO] Command ['xcodebuild', 'test-without-building', '-xctestrun', '/b/s/w/iohFxnto/run_1553447883.xctestrun', '-destination', 'platform=iOS Simulator,OS=11.4,name=iPhone 7', '-resultBundlePath', '/b/s/w/iohFxnto/11.4 iPhone 7/attempt_0'] finished with 0 [10:27:48:DEBUG] Test ended. [10:27:48:DEBUG] Extracting test data. [10:27:48:DEBUG] Retrieving crash reports. [10:27:48:DEBUG] Retrieving derived data. [10:27:48:DEBUG] Making desktop screenshots. [10:27:48:DEBUG] Killing simulators. [10:27:53:DEBUG] Wiping simulator. Device wiped. [10:27:57:DEBUG] End of tear_down. 2019-03-24 11:05:21,585 - root: [DEBUG] Contents of task_output_dir: ['/b/s/w/ir/k/recipe_cleanup/22_5hSj7Bn/43c98682ed62ef10'] 2019-03-24 11:05:21,586 - root: [WARNING] Expected output.json file missing: set(['/b/s/w/ir/k/recipe_cleanup/22_5hSj7Bn/43c98682ed62ef10/output.json']) Found: [] Expected: ['/b/s/w/ir/k/recipe_cleanup/22_5hSj7Bn/43c98682ed62ef10/output.json'] 2019-03-24 11:05:21,586 - root: [WARNING] No shard json files found in task_output_dir: '/b/s/w/ir/k/recipe_cleanup/22_5hSj7Bn' Found ['/b/s/w/ir/k/recipe_cleanup/22_5hSj7Bn/43c98682ed62ef10'] 2019-03-24 11:05:21,586 - root: [DEBUG] Found shard_json_files: ['/b/s/w/ir/k/recipe_cleanup/22_5hSj7Bn/43c98682ed62ef10/output.json'] 2019-03-24 11:05:21,586 - 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/tmpkXFOHL.json --task-output-dir /b/s/w/ir/k/recipe_cleanup/22_5hSj7Bn -o /b/s/w/ir/tmp/t/tmpw1D8gU.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/tmpkXFOHL.json', '--task-output-dir', '/b/s/w/ir/k/recipe_cleanup/22_5hSj7Bn', '-o', '/b/s/w/ir/tmp/t/tmpw1D8gU.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/tmpkXFOHL.json', '--task-output-dir', '/b/s/w/ir/k/recipe_cleanup/22_5hSj7Bn', '-o', '/b/s/w/ir/tmp/t/tmpw1D8gU.json'] returned exit code 0