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/tmpyg09KU.json --task-output-dir /b/s/w/ir/k/recipe_cleanup/16_5WmftwA --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/tmperT7wPmerge_script_log --merge-additional-args '[]' --summary-json-file /b/s/w/ir/tmp/t/tmpJkXWC_.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/tmpYseuR5.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/tmpyg09KU.json', '--task-output-dir', '/b/s/w/ir/k/recipe_cleanup/16_5WmftwA', '--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/tmperT7wPmerge_script_log', '--merge-additional-args', '[]', '--summary-json-file', '/b/s/w/ir/tmp/t/tmpJkXWC_.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/tmpYseuR5.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 12.1) shard 5 on Mac-10.13.6',) ok_ret: frozenset([0]) step_test_data: <lambda>(...) trigger_specs: () full environment: Apple_PubSub_Socket_Render: /private/tmp/com.apple.launchd.tJkQZgGM0B/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: 60870 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/8918896334374431552 LOGDOG_STREAM_PROJECT: chromium LOGDOG_STREAM_SERVER_PATH: unix:/b/s/w/ir/tmp/ld.sock LOGNAME: chrome-bot LUCI_CONTEXT: /b/s/w/itTnyOTX/luci_context.959012472 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.BS8kfjmxhf/Listeners SWARMING_BOT_ID: build111-m9 SWARMING_HEADLESS: 1 SWARMING_SERVER: https://chromium-swarm.appspot.com SWARMING_TASK_ID: 439b709e47595811 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-15 12:22:15,274 - root: [DEBUG] Using task_output_dir: '/b/s/w/ir/k/recipe_cleanup/16_5WmftwA' 2019-03-15 12:22:15,275 - root: [WARNING] task_output_dir '/b/s/w/ir/k/recipe_cleanup/16_5WmftwA' already exists! 2019-03-15 12:22:15,275 - root: [WARNING] task_output_dir existing content: [] 2019-03-15 12:22:15,275 - 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/tmpYseuR5.json -output-dir /b/s/w/ir/k/recipe_cleanup/16_5WmftwA -task-summary-json /b/s/w/ir/tmp/t/tmpJkXWC_.json [D2019-03-15T12:22:15.289629-07:00 63232 0 auth.go:1265] Minting a new token {"key":"luci_ctx/d8692dc480bfbcd30a275c4ffee7b5f4712b62ae05e0648934fad9f0785935a3", "scopes":"https://www.googleapis.com/auth/userinfo.email"} [D2019-03-15T12:22:15.289697-07:00 63232 0 luci_ctx.go:138] POST http://127.0.0.1:60859/rpc/LuciLocalAuthService.GetOAuthToken {"key":"luci_ctx/d8692dc480bfbcd30a275c4ffee7b5f4712b62ae05e0648934fad9f0785935a3", "scopes":"https://www.googleapis.com/auth/userinfo.email"} [D2019-03-15T12:22:15.291318-07:00 63232 0 auth.go:1222] Token expires in 33m37.7087s {"key":"luci_ctx/d8692dc480bfbcd30a275c4ffee7b5f4712b62ae05e0648934fad9f0785935a3", "scopes":"https://www.googleapis.com/auth/userinfo.email"} 439b79e30bf61110: exit 0 [I 2019-03-15 11:43:01] About to install Xcode 10b61 in Xcode.app for ios [11:43:03:INFO] Using Xcode version 10.1 build 10B61 at /b/s/w/ir/Xcode.app/Contents/Developer [11:43:12:INFO] Start test attempt #0 for command [xcodebuild test-without-building -xctestrun /b/s/w/io2S7Obh/run_1552675392.xctestrun -destination platform=iOS Simulator,OS=12.1,name=iPhone 7 -resultBundlePath /b/s/w/io2S7Obh/12.1 iPhone 7/attempt_0] [11:43:13:INFO] User defaults from command line: Erasing all simulators. Launching ['xcodebuild', 'test-without-building', '-xctestrun', '/b/s/w/io2S7Obh/run_1552675392.xctestrun', '-destination', 'platform=iOS Simulator,OS=12.1,name=iPhone 7', '-resultBundlePath', '/b/s/w/io2S7Obh/12.1 iPhone 7/attempt_0'] with env {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'MAC_CHROMIUM_TMPDIR': '/b/s/w/itUqYKCk', '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': 'vm319-m1', 'VIRTUAL_ENV': '/b/s/w/ir/.swarming_module_cache/vpython/b6ec90', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tIT06OGLHd/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/itUqYKCk/luci_ctx.UqV5Sa.json', 'CIPD_CACHE_DIR': '/b/s/cipd_cache/cache', 'XPC_SERVICE_NAME': 'org.swarm.bot.plist', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ewnFxZ4MtV/Listeners', 'VPYTHON_VIRTUALENV_ROOT': '/b/s/w/ir/.swarming_module_cache/vpython', 'SWARMING_TASK_ID': '439b79e30bf61111', 'SHELL': '/bin/bash', 'NO_GCE_CHECK': 'False', 'TMPDIR': '/b/s/w/itUqYKCk', 'CIPD_PROTOCOL': 'v2', '__CF_USER_TEXT_ENCODING': '0x1F4:0x0:0x0', 'NSUnbufferedIO': 'YES'} [11:43:13:INFO] IDEBuildOperationResultBundlePath = /b/s/w/io2S7Obh/12.1 iPhone 7/attempt_0 [11:43:13:INFO] IDETestRunSpecificationPath = /b/s/w/io2S7Obh/run_1552675392.xctestrun [11:43:13:INFO] [11:43:13:INFO] Testing started on 'iPhone 7' [11:43:13:INFO] 2019-03-15 11:43:13.468 xcodebuild[23996:311032] IDETestOperationsObserverDebug: Writing diagnostic log for test session to: [11:43:13:INFO] /b/s/w/io2S7Obh/12.1 iPhone 7/attempt_0/1_Test/Diagnostics/ios_chrome_ui_egtests_module-6AEC6F82-CDFD-4FB1-9F04-498B806DA91C/ios_chrome_ui_egtests_module-F95FD228-A0C8-4748-9924-3862581568D5/Session-ios_chrome_ui_egtests_module-2019-03-15_114313-479cdb.log [11:43:13:INFO] 2019-03-15 11:43:13.468 xcodebuild[23996:311030] [MT] IDETestOperationsObserverDebug: (BABC1BE0-0708-48F6-BE42-8F5CB0BB218E) Beginning test session ios_chrome_ui_egtests_module-BABC1BE0-0708-48F6-BE42-8F5CB0BB218E at 2019-03-15 11:43:13.468 with Xcode 10B61 on target <DVTiPhoneSimulator: 0x7fccc21ec2f0> { [11:43:13:INFO] SimDevice: iPhone 7 (31B94085-EB0A-4827-A5E8-3B5934130B31, iOS 12.1, Shutdown) [11:43:13:INFO] } (12.1 (16B91)) [11:43:29:INFO] 2019-03-15 11:43:29.412 ios_chrome_ui_egtests[24118:312251] Crash handler setup started. [11:43:29:INFO] 2019-03-15 11:43:29.413 ios_chrome_ui_egtests[24118:312251] Crash handler setup completed. [11:43:29:INFO] 2019-03-15 11:43:29.413 ios_chrome_ui_egtests[24118:312251] Enabling accessibility for automation on Simulator. [11:43:31:INFO] 2019-03-15 11:43:31.845 ios_chrome_ui_egtests[24118:312251] Unbalanced calls to begin/end appearance transitions for <TabGridViewController: 0x7fce1e02da00>. [11:43:31:INFO] [0315/114331.954401:WARNING:leveldb_database.cc(106)] Unable to open /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/31B94085-EB0A-4827-A5E8-3B5934130B31/data/Containers/Data/Application/7C53C678-F009-472C-A396-4F79FBA0637A/Library/Application Support/Chromium/Default/shared_proto_db: Invalid argument: /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/31B94085-EB0A-4827-A5E8-3B5934130B31/data/Containers/Data/Application/7C53C678-F009-472C-A396-4F79FBA0637A/Library/Application Support/Chromium/Default/shared_proto_db: does not exist (create_if_missing is false) [11:43:38:INFO] Test Suite 'Selected tests' started at 2019-03-15 11:43:38.322 [11:43:38:INFO] Test Suite 'ios_chrome_ui_egtests_module.xctest' started at 2019-03-15 11:43:38.324 [11:43:38:INFO] Test Suite 'SadTabViewTestCase' started at 2019-03-15 11:43:38.324 [11:43:38:INFO] [0315/114338.333863:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist. [11:43:40:INFO] Test Case '-[SadTabViewTestCase testSadTabView]' started. [11:43:42:INFO] [0315/114342.268653:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [11:44:00:INFO] Test Case '-[SadTabViewTestCase testSadTabView]' passed (20.131 seconds). [11:44:00:INFO] Test Suite 'SadTabViewTestCase' passed at 2019-03-15 11:44:00.330. [11:44:00:INFO] Executed 1 test, with 0 failures (0 unexpected) in 20.131 (22.005) seconds [11:44:00:INFO] Test Suite 'SafeModeTestCase' started at 2019-03-15 11:44:00.331 [11:44:00:INFO] [0315/114400.332339:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist. [11:44:00:INFO] Test Case '-[SafeModeTestCase testSafeModeBothThirdPartyModsAndHasReport]' started. [11:44:05:INFO] Test Case '-[SafeModeTestCase testSafeModeBothThirdPartyModsAndHasReport]' passed (4.738 seconds). [11:44:05:INFO] Test Case '-[SafeModeTestCase testSafeModeDetectedThirdPartyMods]' started. [11:44:10:INFO] Test Case '-[SafeModeTestCase testSafeModeDetectedThirdPartyMods]' passed (5.010 seconds). [11:44:10:INFO] Test Case '-[SafeModeTestCase testSafeModeSendingCrashReport]' started. [11:44:15:INFO] Test Case '-[SafeModeTestCase testSafeModeSendingCrashReport]' passed (4.933 seconds). [11:44:15:INFO] Test Suite 'SafeModeTestCase' passed at 2019-03-15 11:44:15.020. [11:44:15:INFO] Executed 3 tests, with 0 failures (0 unexpected) in 14.680 (14.689) seconds [11:44:15:INFO] Test Suite 'SaveCardInfobarEGTest' started at 2019-03-15 11:44:15.020 [11:44:15:INFO] [0315/114415.022040:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist. [11:44:15:INFO] Test Case '-[SaveCardInfobarEGTest testNotOfferLocalSave_MaxStrikesReached]' started. [11:44:17:INFO] [0315/114417.034003:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [11:44:18:INFO] [0315/114418.104443:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /credit_card_upload_done.html [11:44:25:INFO] Test Case '-[SaveCardInfobarEGTest testNotOfferLocalSave_MaxStrikesReached]' passed (10.512 seconds). [11:44:25:INFO] Test Case '-[SaveCardInfobarEGTest testNotOfferLocalSave_PartialData_PaymentsDeclines]' started. [11:44:28:INFO] [0315/114428.489010:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /credit_card_upload_done.html [11:44:35:INFO] Test Case '-[SaveCardInfobarEGTest testNotOfferLocalSave_PartialData_PaymentsDeclines]' passed (9.911 seconds). [11:44:35:INFO] Test Case '-[SaveCardInfobarEGTest testOfferLocalSave_FullData_PaymentsDeclines_StrikeDatabaseDisabled]' started. [11:44:38:INFO] [0315/114438.452934:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /credit_card_upload_done.html [11:44:39:INFO] 2019-03-15 11:44:39.553 ios_chrome_ui_egtests[24118:312251] <CATransformLayer: 0x60000119fa20> - changing property masksToBounds in transform-only layer, will have no effect [11:44:39:INFO] 2019-03-15 11:44:39.554 ios_chrome_ui_egtests[24118:312251] <CATransformLayer: 0x60000118cc20> - changing property masksToBounds in transform-only layer, will have no effect [11:44:39:INFO] 2019-03-15 11:44:39.556 ios_chrome_ui_egtests[24118:312251] <CATransformLayer: 0x60000103bf20> - changing property masksToBounds in transform-only layer, will have no effect [11:44:41:INFO] Test Case '-[SaveCardInfobarEGTest testOfferLocalSave_FullData_PaymentsDeclines_StrikeDatabaseDisabled]' passed (6.387 seconds). [11:44:41:INFO] Test Case '-[SaveCardInfobarEGTest testOfferLocalSave_FullData_PaymentsDeclines_StrikeDatabaseEnabled]' started. [11:44:44:INFO] [0315/114444.937390:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /credit_card_upload_done.html [11:44:46:INFO] 2019-03-15 11:44:46.053 ios_chrome_ui_egtests[24118:312251] <CATransformLayer: 0x600001168bc0> - changing property masksToBounds in transform-only layer, will have no effect [11:44:46:INFO] 2019-03-15 11:44:46.054 ios_chrome_ui_egtests[24118:312251] <CATransformLayer: 0x60000102df80> - changing property masksToBounds in transform-only layer, will have no effect [11:44:46:INFO] 2019-03-15 11:44:46.056 ios_chrome_ui_egtests[24118:312251] <CATransformLayer: 0x600001039e20> - changing property masksToBounds in transform-only layer, will have no effect [11:44:48:INFO] Test Case '-[SaveCardInfobarEGTest testOfferLocalSave_FullData_PaymentsDeclines_StrikeDatabaseEnabled]' passed (6.536 seconds). [11:44:48:INFO] Test Case '-[SaveCardInfobarEGTest testOfferLocalSave_FullData_RequestFails_StrikeDatabaseDisabled]' started. [11:44:51:INFO] [0315/114451.414161:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /credit_card_upload_done.html [11:44:52:INFO] 2019-03-15 11:44:52.534 ios_chrome_ui_egtests[24118:312251] <CATransformLayer: 0x600001114e00> - changing property masksToBounds in transform-only layer, will have no effect [11:44:52:INFO] 2019-03-15 11:44:52.535 ios_chrome_ui_egtests[24118:312251] <CATransformLayer: 0x600001056580> - changing property masksToBounds in transform-only layer, will have no effect [11:44:52:INFO] 2019-03-15 11:44:52.537 ios_chrome_ui_egtests[24118:312251] <CATransformLayer: 0x600001041160> - changing property masksToBounds in transform-only layer, will have no effect [11:44:54:INFO] Test Case '-[SaveCardInfobarEGTest testOfferLocalSave_FullData_RequestFails_StrikeDatabaseDisabled]' passed (6.430 seconds). [11:44:54:INFO] Test Case '-[SaveCardInfobarEGTest testOfferLocalSave_FullData_RequestFails_StrikeDatabaseEnabled]' started. [11:44:57:INFO] [0315/114457.823708:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /credit_card_upload_done.html [11:44:58:INFO] 2019-03-15 11:44:58.921 ios_chrome_ui_egtests[24118:312251] <CATransformLayer: 0x600001115ae0> - changing property masksToBounds in transform-only layer, will have no effect [11:44:58:INFO] 2019-03-15 11:44:58.921 ios_chrome_ui_egtests[24118:312251] <CATransformLayer: 0x60000107e800> - changing property masksToBounds in transform-only layer, will have no effect [11:44:58:INFO] 2019-03-15 11:44:58.923 ios_chrome_ui_egtests[24118:312251] <CATransformLayer: 0x600001116de0> - changing property masksToBounds in transform-only layer, will have no effect [11:45:01:INFO] Test Case '-[SaveCardInfobarEGTest testOfferLocalSave_FullData_RequestFails_StrikeDatabaseEnabled]' passed (6.404 seconds). [11:45:01:INFO] Test Case '-[SaveCardInfobarEGTest testOfferUpstream_FullData_PaymentsAccepts_StrikeDatabaseDisabled]' started. [11:45:04:INFO] [0315/114504.291248:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /credit_card_upload_done.html [11:45:05:INFO] 2019-03-15 11:45:05.397 ios_chrome_ui_egtests[24118:312251] <CATransformLayer: 0x60000100a200> - changing property masksToBounds in transform-only layer, will have no effect [11:45:05:INFO] 2019-03-15 11:45:05.398 ios_chrome_ui_egtests[24118:312251] <CATransformLayer: 0x60000104cc80> - changing property masksToBounds in transform-only layer, will have no effect [11:45:05:INFO] 2019-03-15 11:45:05.402 ios_chrome_ui_egtests[24118:312251] <CATransformLayer: 0x60000100be40> - changing property masksToBounds in transform-only layer, will have no effect [11:45:07:INFO] Test Case '-[SaveCardInfobarEGTest testOfferUpstream_FullData_PaymentsAccepts_StrikeDatabaseDisabled]' passed (6.547 seconds). [11:45:07:INFO] Test Case '-[SaveCardInfobarEGTest testOfferUpstream_FullData_PaymentsAccepts_StrikeDatabaseEnabled]' started. [11:45:10:INFO] [0315/114510.809336:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /credit_card_upload_done.html [11:45:11:INFO] 2019-03-15 11:45:11.943 ios_chrome_ui_egtests[24118:312251] <CATransformLayer: 0x600001020520> - changing property masksToBounds in transform-only layer, will have no effect [11:45:11:INFO] 2019-03-15 11:45:11.945 ios_chrome_ui_egtests[24118:312251] <CATransformLayer: 0x60000102c640> - changing property masksToBounds in transform-only layer, will have no effect [11:45:11:INFO] 2019-03-15 11:45:11.948 ios_chrome_ui_egtests[24118:312251] <CATransformLayer: 0x6000010c4d60> - changing property masksToBounds in transform-only layer, will have no effect [11:45:14:INFO] Test Case '-[SaveCardInfobarEGTest testOfferUpstream_FullData_PaymentsAccepts_StrikeDatabaseEnabled]' passed (6.511 seconds). [11:45:14:INFO] Test Case '-[SaveCardInfobarEGTest testOfferUpstream_PartialData_PaymentsAccepts_StrikeDatabaseDisabled]' started. [11:45:17:INFO] [0315/114517.255255:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /credit_card_upload_done.html [11:45:18:INFO] 2019-03-15 11:45:18.354 ios_chrome_ui_egtests[24118:312251] <CATransformLayer: 0x600001117040> - changing property masksToBounds in transform-only layer, will have no effect [11:45:18:INFO] 2019-03-15 11:45:18.355 ios_chrome_ui_egtests[24118:312251] <CATransformLayer: 0x600001003800> - changing property masksToBounds in transform-only layer, will have no effect [11:45:18:INFO] 2019-03-15 11:45:18.358 ios_chrome_ui_egtests[24118:312251] <CATransformLayer: 0x6000010dfc60> - changing property masksToBounds in transform-only layer, will have no effect [11:45:20:INFO] Test Case '-[SaveCardInfobarEGTest testOfferUpstream_PartialData_PaymentsAccepts_StrikeDatabaseDisabled]' passed (6.374 seconds). [11:45:20:INFO] Test Case '-[SaveCardInfobarEGTest testOfferUpstream_PartialData_PaymentsAccepts_StrikeDatabaseEnabled]' started. [11:45:23:INFO] [0315/114523.640095:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /credit_card_upload_done.html [11:45:24:INFO] 2019-03-15 11:45:24.737 ios_chrome_ui_egtests[24118:312251] <CATransformLayer: 0x6000011af980> - changing property masksToBounds in transform-only layer, will have no effect [11:45:24:INFO] 2019-03-15 11:45:24.739 ios_chrome_ui_egtests[24118:312251] <CATransformLayer: 0x600001116bc0> - changing property masksToBounds in transform-only layer, will have no effect [11:45:24:INFO] 2019-03-15 11:45:24.742 ios_chrome_ui_egtests[24118:312251] <CATransformLayer: 0x6000010dbea0> - changing property masksToBounds in transform-only layer, will have no effect [11:45:27:INFO] Test Case '-[SaveCardInfobarEGTest testOfferUpstream_PartialData_PaymentsAccepts_StrikeDatabaseEnabled]' passed (6.450 seconds). [11:45:27:INFO] Test Case '-[SaveCardInfobarEGTest testUMA_Upstream_UserAccepts]' started. [11:45:28:INFO] 2019-03-15 11:45:28.900 ios_chrome_ui_egtests[24118:312251] -- Earl Grey Test Disabled -- Test disabled. [11:45:30:INFO] Test Case '-[SaveCardInfobarEGTest testUMA_Upstream_UserAccepts]' passed (3.656 seconds). [11:45:30:INFO] Test Case '-[SaveCardInfobarEGTest testUMA_Upstream_UserDeclines_StrikeDatabaseDisabled]' started. [11:45:32:INFO] 2019-03-15 11:45:32.550 ios_chrome_ui_egtests[24118:312251] -- Earl Grey Test Disabled -- Failing regularly on the bots. [11:45:34:INFO] Test Case '-[SaveCardInfobarEGTest testUMA_Upstream_UserDeclines_StrikeDatabaseDisabled]' passed (3.662 seconds). [11:45:34:INFO] Test Case '-[SaveCardInfobarEGTest testUMA_Upstream_UserDeclines_StrikeDatabaseEnabled]' started. [11:45:36:INFO] 2019-03-15 11:45:36.182 ios_chrome_ui_egtests[24118:312251] -- Earl Grey Test Disabled -- Failing regularly on the bots. [11:45:38:INFO] Test Case '-[SaveCardInfobarEGTest testUMA_Upstream_UserDeclines_StrikeDatabaseEnabled]' passed (3.618 seconds). [11:45:38:INFO] Test Case '-[SaveCardInfobarEGTest testUserData_LocalSave_UserAccepts]' started. [11:45:41:INFO] [0315/114541.010383:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /credit_card_upload_done.html [11:45:42:INFO] 2019-03-15 11:45:42.109 ios_chrome_ui_egtests[24118:312251] <CATransformLayer: 0x600001199820> - changing property masksToBounds in transform-only layer, will have no effect [11:45:42:INFO] 2019-03-15 11:45:42.110 ios_chrome_ui_egtests[24118:312251] <CATransformLayer: 0x60000104f040> - changing property masksToBounds in transform-only layer, will have no effect [11:45:42:INFO] 2019-03-15 11:45:42.112 ios_chrome_ui_egtests[24118:312251] <CATransformLayer: 0x6000010d8c40> - changing property masksToBounds in transform-only layer, will have no effect [11:45:42:INFO] 2019-03-15 11:45:42.526 ios_chrome_ui_egtests[24118:312251] Button touch target does not meet minimum size guidlines of (48, 48). Button: <MDCFlatButton: 0x7fce20064070; baseClass = UIButton; frame = (288 32; 71 37); opaque = NO; tintColor = UIExtendedGrayColorSpace 1 1; layer = <MDCShapedShadowLayer: 0x600003f2c980>>, Touch Target: {71, 37} [11:45:44:INFO] Test Case '-[SaveCardInfobarEGTest testUserData_LocalSave_UserAccepts]' passed (6.756 seconds). [11:45:44:INFO] Test Case '-[SaveCardInfobarEGTest testUserData_LocalSave_UserDeclines_StrikeDatabaseDisabled]' started. [11:45:46:INFO] 2019-03-15 11:45:46.666 ios_chrome_ui_egtests[24118:312251] -- Earl Grey Test Disabled -- Failing regularly on the bots. [11:45:48:INFO] Test Case '-[SaveCardInfobarEGTest testUserData_LocalSave_UserDeclines_StrikeDatabaseDisabled]' passed (3.742 seconds). [11:45:48:INFO] Test Case '-[SaveCardInfobarEGTest testUserData_LocalSave_UserDeclines_StrikeDatabaseEnabled]' started. [11:45:50:INFO] 2019-03-15 11:45:50.330 ios_chrome_ui_egtests[24118:312251] -- Earl Grey Test Disabled -- Failing regularly on the bots. [11:45:52:INFO] Test Case '-[SaveCardInfobarEGTest testUserData_LocalSave_UserDeclines_StrikeDatabaseEnabled]' passed (3.652 seconds). [11:45:52:INFO] Test Suite 'SaveCardInfobarEGTest' passed at 2019-03-15 11:45:52.212. [11:45:52:INFO] Executed 16 tests, with 0 failures (0 unexpected) in 97.147 (97.192) seconds [11:45:52:INFO] Test Suite 'SaveProfileEGTest' started at 2019-03-15 11:45:52.214 [11:45:52:INFO] [0315/114552.215759:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist. [11:45:52:INFO] Test Case '-[SaveProfileEGTest testUserData_LocalSave]' started. [11:45:54:INFO] [0315/114554.173681:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [11:45:58:INFO] Test Case '-[SaveProfileEGTest testUserData_LocalSave]' passed (5.912 seconds). [11:45:58:INFO] Test Suite 'SaveProfileEGTest' passed at 2019-03-15 11:45:58.135. [11:45:58:INFO] Executed 1 test, with 0 failures (0 unexpected) in 5.912 (5.921) seconds [11:45:58:INFO] Test Suite 'ShortcutsTestCase' started at 2019-03-15 11:45:58.135 [11:45:58:INFO] [0315/114558.136854:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist. [11:45:58:INFO] Test Case '-[ShortcutsTestCase testBookmarksShortcut]' started. [11:46:00:INFO] [0315/114600.352874:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [11:46:14:INFO] Test Case '-[ShortcutsTestCase testBookmarksShortcut]' passed (16.411 seconds). [11:46:14:INFO] Test Case '-[ShortcutsTestCase testHistoryShortcut]' started. [11:46:16:INFO] [0315/114616.738868:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [11:46:30:INFO] Test Case '-[ShortcutsTestCase testHistoryShortcut]' passed (16.111 seconds). [11:46:30:INFO] Test Case '-[ShortcutsTestCase testNotShownWhenSuggestionsAvailable]' started. [11:46:32:INFO] [0315/114632.846008:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [11:46:40:INFO] 2019-03-15 11:46:40.486 ios_chrome_ui_egtests[24118:312251] Attempting to type key f. [11:46:40:INFO] 2019-03-15 11:46:40.487 ios_chrome_ui_egtests[24118:312251] Tapping on key: f. [11:46:40:INFO] 2019-03-15 11:46:40.617 ios_chrome_ui_egtests[24118:312251] Attempting to type key o. [11:46:40:INFO] 2019-03-15 11:46:40.618 ios_chrome_ui_egtests[24118:312251] Tapping on key: o. [11:46:40:INFO] [0315/114640.673869:WARNING:spdy_session.cc(3232)] Received HEADERS for invalid stream 3 [11:46:40:INFO] 2019-03-15 11:46:40.678 ios_chrome_ui_egtests[24118:312251] Attempting to type key o. [11:46:40:INFO] 2019-03-15 11:46:40.679 ios_chrome_ui_egtests[24118:312251] Tapping on key: o. [11:46:43:INFO] Test Case '-[ShortcutsTestCase testNotShownWhenSuggestionsAvailable]' passed (13.034 seconds). [11:46:43:INFO] Test Case '-[ShortcutsTestCase testNTPShortcutsDontShowUp]' started. [11:46:46:INFO] [0315/114646.032289:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [11:46:58:INFO] Test Case '-[ShortcutsTestCase testNTPShortcutsDontShowUp]' passed (15.248 seconds). [11:46:58:INFO] Test Case '-[ShortcutsTestCase testReadingListShortcut]' started. [11:47:01:INFO] [0315/114701.219488:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [11:47:15:INFO] Test Case '-[ShortcutsTestCase testReadingListShortcut]' passed (16.115 seconds). [11:47:15:INFO] Test Case '-[ShortcutsTestCase testRecentTabsShortcut]' started. [11:47:17:INFO] [0315/114717.229565:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [11:47:31:INFO] Test Case '-[ShortcutsTestCase testRecentTabsShortcut]' passed (16.410 seconds). [11:47:31:INFO] Test Case '-[ShortcutsTestCase testShowsUp]' started. [11:47:33:INFO] [0315/114733.646859:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [11:47:44:INFO] Test Case '-[ShortcutsTestCase testShowsUp]' passed (12.799 seconds). [11:47:44:INFO] Test Case '-[ShortcutsTestCase testShowsUpWhenOmniboxIsEmpty]' started. [11:47:46:INFO] [0315/114746.563573:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [11:47:54:INFO] 2019-03-15 11:47:54.302 ios_chrome_ui_egtests[24118:312251] Attempting to type key . [11:47:54:INFO] 2019-03-15 11:47:54.303 ios_chrome_ui_egtests[24118:312251] Tapping on key: delete. [11:47:57:INFO] Test Case '-[ShortcutsTestCase testShowsUpWhenOmniboxIsEmpty]' passed (13.198 seconds). [11:47:57:INFO] Test Case '-[ShortcutsTestCase testTapMostVisitedTile]' started. [11:47:59:INFO] [0315/114759.660341:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [11:48:10:INFO] Test Case '-[ShortcutsTestCase testTapMostVisitedTile]' passed (13.365 seconds). [11:48:10:INFO] Test Suite 'ShortcutsTestCase' passed at 2019-03-15 11:48:10.838. [11:48:10:INFO] Executed 9 tests, with 0 failures (0 unexpected) in 132.691 (132.703) seconds [11:48:10:INFO] Test Suite 'SideSwipeTestCase' started at 2019-03-15 11:48:10.839 [11:48:10:INFO] [0315/114810.840989:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist. [11:48:10:INFO] Test Case '-[SideSwipeTestCase testSideSwipeBottomToolbar]' started. [11:48:12:INFO] [0315/114812.764569:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [11:48:22:INFO] Test Case '-[SideSwipeTestCase testSideSwipeBottomToolbar]' passed (11.905 seconds). [11:48:22:INFO] Test Case '-[SideSwipeTestCase testSideSwipeTopToolbar]' started. [11:48:24:INFO] [0315/114824.739876:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [11:48:34:INFO] Test Case '-[SideSwipeTestCase testSideSwipeTopToolbar]' passed (11.936 seconds). [11:48:34:INFO] Test Suite 'SideSwipeTestCase' passed at 2019-03-15 11:48:34.695. [11:48:34:INFO] Executed 2 tests, with 0 failures (0 unexpected) in 23.841 (23.855) seconds [11:48:34:INFO] Test Suite 'SigninInteractionControllerTestCase' started at 2019-03-15 11:48:34.696 [11:48:34:INFO] [0315/114834.697560:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist. [11:48:34:INFO] Test Case '-[SigninInteractionControllerTestCase testSignInCancelAddAccount]' started. [11:48:55:INFO] Test Case '-[SigninInteractionControllerTestCase testSignInCancelAddAccount]' passed (20.642 seconds). [11:48:55:INFO] Test Case '-[SigninInteractionControllerTestCase testSignInCancelAuthenticationFlow]' started. [11:49:03:INFO] [0315/114903.863495:WARNING:sync_encryption_handler_impl.cc(1079)] Nigori had empty encryption keybag. [11:49:03:INFO] [0315/114903.871932:WARNING:sync_encryption_handler_impl.cc(1079)] Nigori had empty encryption keybag. [11:49:03:INFO] [0315/114903.887677:ERROR:account_tracker.cc(264)] OnOAuthError [11:49:03:INFO] [0315/114903.887813:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID [11:49:03:INFO] [0315/114903.927192:ERROR:account_tracker.cc(264)] OnOAuthError [11:49:03:INFO] [0315/114903.927353:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo2ID [11:49:04:INFO] [0315/114904.942335:INFO:profile_sync_service.cc(1780)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now [11:49:17:INFO] [0315/114917.156482:INFO:signin_manager.cc(125)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1 [11:49:17:INFO] [0315/114917.592189:WARNING:gaia_auth_fetcher.cc(882)] Could not reach Google Accounts servers: errno -12 [11:49:18:INFO] [0315/114918.796995:WARNING:gaia_auth_fetcher.cc(882)] Could not reach Google Accounts servers: errno -12 [11:49:21:INFO] [0315/114921.631421:WARNING:gaia_auth_fetcher.cc(882)] Could not reach Google Accounts servers: errno -12 [11:49:30:INFO] [0315/114930.749997:WARNING:gaia_auth_fetcher.cc(882)] Could not reach Google Accounts servers: errno -12 [11:49:34:INFO] Test Case '-[SigninInteractionControllerTestCase testSignInCancelAuthenticationFlow]' passed (38.894 seconds). [11:49:34:INFO] Test Case '-[SigninInteractionControllerTestCase testSignInCancelFromBookmarks]' started. [11:49:54:INFO] Test Case '-[SigninInteractionControllerTestCase testSignInCancelFromBookmarks]' passed (20.371 seconds). [11:49:54:INFO] Test Case '-[SigninInteractionControllerTestCase testSignInCancelIdentityPicker]' started. [11:49:57:INFO] [0315/114957.948000:WARNING:gaia_auth_fetcher.cc(882)] Could not reach Google Accounts servers: errno -12 [11:50:14:INFO] Test Case '-[SigninInteractionControllerTestCase testSignInCancelIdentityPicker]' passed (20.361 seconds). [11:50:14:INFO] Test Case '-[SigninInteractionControllerTestCase testSignInDisconnectFromChrome]' started. [11:50:23:INFO] [0315/115023.805632:ERROR:account_tracker.cc(264)] OnOAuthError [11:50:23:INFO] [0315/115023.805769:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID [11:50:24:INFO] [0315/115024.816064:INFO:profile_sync_service.cc(1780)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now [11:50:24:INFO] [0315/115024.894120:WARNING:session_sync_bridge.cc(228)] Dropping modification to local session. [11:50:36:INFO] [0315/115036.669676:INFO:signin_manager.cc(125)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1 [11:50:42:INFO] Test Case '-[SigninInteractionControllerTestCase testSignInDisconnectFromChrome]' passed (27.509 seconds). [11:50:42:INFO] Test Case '-[SigninInteractionControllerTestCase testSignInOneUser]' started. [11:50:51:INFO] [0315/115051.093739:ERROR:account_tracker.cc(264)] OnOAuthError [11:50:51:INFO] [0315/115051.093871:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID [11:50:52:INFO] [0315/115052.127849:INFO:profile_sync_service.cc(1780)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now [11:50:52:INFO] [0315/115052.206892:WARNING:session_sync_bridge.cc(228)] Dropping modification to local session. [11:50:55:INFO] [0315/115055.984491:INFO:signin_manager.cc(125)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1 [11:50:57:INFO] Test Case '-[SigninInteractionControllerTestCase testSignInOneUser]' passed (15.485 seconds). [11:50:57:INFO] Test Case '-[SigninInteractionControllerTestCase testSignInOpenSettings]' started. [11:51:06:INFO] [0315/115106.676759:ERROR:account_tracker.cc(264)] OnOAuthError [11:51:06:INFO] [0315/115106.676912:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID [11:51:06:INFO] [0315/115106.745849:WARNING:gaia_auth_fetcher.cc(898)] ClientLogin failed with [11:51:06:INFO] [0315/115106.746031:WARNING:gaia_auth_fetcher.cc(921)] Incomprehensible response from Google Accounts servers. [11:51:10:INFO] [0315/115110.703591:INFO:profile_sync_service.cc(1780)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now [11:51:10:INFO] [0315/115110.832958:WARNING:session_sync_bridge.cc(228)] Dropping modification to local session. [11:51:12:INFO] [0315/115112.474023:INFO:signin_manager.cc(125)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1 [11:51:14:INFO] Test Case '-[SigninInteractionControllerTestCase testSignInOpenSettings]' passed (16.484 seconds). [11:51:14:INFO] Test Case '-[SigninInteractionControllerTestCase testSignInSwitchAccountsAndImportData]' started. [11:51:23:INFO] [0315/115123.218948:WARNING:http_cache_transaction.cc(1277)] Unable to create cache entry [11:51:23:INFO] [0315/115123.251850:ERROR:account_tracker.cc(264)] OnOAuthError [11:51:23:INFO] [0315/115123.251978:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID [11:51:23:INFO] [0315/115123.252729:ERROR:account_tracker.cc(264)] OnOAuthError [11:51:23:INFO] [0315/115123.252830:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo2ID [11:51:24:INFO] [0315/115124.275959:INFO:profile_sync_service.cc(1780)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now [11:51:24:INFO] [0315/115124.356837:WARNING:session_sync_bridge.cc(228)] Dropping modification to local session. [11:51:40:INFO] [0315/115140.583206:INFO:signin_manager.cc(125)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1 [11:51:40:INFO] [0315/115140.758989:ERROR:account_tracker.cc(264)] OnOAuthError [11:51:40:INFO] [0315/115140.759125:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID [11:51:40:INFO] [0315/115140.759803:ERROR:account_tracker.cc(264)] OnOAuthError [11:51:40:INFO] [0315/115140.759903:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo2ID [11:51:42:INFO] [0315/115142.171543:INFO:profile_sync_service.cc(1780)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now [11:51:42:INFO] [0315/115142.257165:WARNING:model_type_controller.cc(197)] Deferring stop for Reading List because it's still starting [11:51:42:INFO] [0315/115142.257354:WARNING:model_type_controller.cc(197)] Deferring stop for User Events because it's still starting [11:51:42:INFO] [0315/115142.257467:WARNING:model_type_controller.cc(197)] Deferring stop for User Consents because it's still starting [11:51:42:INFO] [0315/115142.257689:INFO:profile_sync_service.cc(1048)] ProfileSyncService::Observe Sync Configure aborted [11:51:42:INFO] [0315/115142.260017:INFO:signin_manager.cc(125)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1 [11:51:45:INFO] Test Case '-[SigninInteractionControllerTestCase testSignInSwitchAccountsAndImportData]' passed (31.321 seconds). [11:51:45:INFO] Test Case '-[SigninInteractionControllerTestCase testSignInSwitchAccountsAndKeepDataSeparate]' started. [11:51:54:INFO] [0315/115154.354771:ERROR:account_tracker.cc(264)] OnOAuthError [11:51:54:INFO] [0315/115154.354908:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID [11:51:54:INFO] [0315/115154.373810:ERROR:account_tracker.cc(264)] OnOAuthError [11:51:54:INFO] [0315/115154.373926:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo2ID [11:51:55:INFO] [0315/115155.378003:INFO:profile_sync_service.cc(1780)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now [11:51:55:INFO] [0315/115155.452589:WARNING:session_sync_bridge.cc(228)] Dropping modification to local session. [11:52:11:INFO] [0315/115211.607764:INFO:signin_manager.cc(125)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1 [11:52:12:INFO] [0315/115212.262062:ERROR:account_tracker.cc(264)] OnOAuthError [11:52:12:INFO] [0315/115212.262232:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID [11:52:12:INFO] [0315/115212.266772:ERROR:account_tracker.cc(264)] OnOAuthError [11:52:12:INFO] [0315/115212.266899:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo2ID [11:52:13:INFO] [0315/115213.849125:INFO:profile_sync_service.cc(1780)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now [11:52:13:INFO] [0315/115213.921699:INFO:profile_sync_service.cc(1048)] ProfileSyncService::Observe Sync Configure aborted [11:52:13:INFO] [0315/115213.923984:INFO:signin_manager.cc(125)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1 [11:52:13:INFO] [0315/115213.933390: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 [11:52:13:INFO] [0315/115213.933593:ERROR:get_updates_processor.cc(245)] PostClientToServerMessage() failed during GetUpdates [11:52:17:INFO] Test Case '-[SigninInteractionControllerTestCase testSignInSwitchAccountsAndKeepDataSeparate]' passed (31.652 seconds). [11:52:17:INFO] Test Case '-[SigninInteractionControllerTestCase testSignInSwitchManagedAccount]' started. [11:52:26:INFO] [0315/115226.979628:ERROR:account_tracker.cc(264)] OnOAuthError [11:52:26:INFO] [0315/115226.979751:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID [11:52:27:INFO] [0315/115227.002700:ERROR:account_tracker.cc(264)] OnOAuthError [11:52:27:INFO] [0315/115227.002827:WARNING:account_tracker.cc(190)] Failed to get UserInfo for fooManagedID [11:52:27:INFO] [0315/115227.723026:INFO:profile_sync_service.cc(1780)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now [11:52:27:INFO] [0315/115227.821585:WARNING:session_sync_bridge.cc(228)] Dropping modification to local session. [11:52:35:INFO] [0315/115235.704282:INFO:signin_manager.cc(125)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1 [11:52:36:INFO] [0315/115236.366516:ERROR:account_tracker.cc(264)] OnOAuthError [11:52:36:INFO] [0315/115236.366676:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID [11:52:36:INFO] [0315/115236.370463:ERROR:account_tracker.cc(264)] OnOAuthError [11:52:36:INFO] [0315/115236.370596:WARNING:account_tracker.cc(190)] Failed to get UserInfo for fooManagedID [11:52:37:INFO] [0315/115237.950554:INFO:profile_sync_service.cc(1780)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now [11:52:38:INFO] [0315/115238.039092:INFO:profile_sync_service.cc(1048)] ProfileSyncService::Observe Sync Configure aborted [11:52:38:INFO] [0315/115238.042198:INFO:signin_manager.cc(125)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1 [11:52:38:INFO] [0315/115238.051272: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 [11:52:38:INFO] [0315/115238.051502:ERROR:get_updates_processor.cc(245)] PostClientToServerMessage() failed during GetUpdates [11:52:41:INFO] Test Case '-[SigninInteractionControllerTestCase testSignInSwitchManagedAccount]' passed (24.130 seconds). [11:52:41:INFO] Test Suite 'SigninInteractionControllerTestCase' passed at 2019-03-15 11:52:41.557. [11:52:41:INFO] Executed 10 tests, with 0 failures (0 unexpected) in 246.848 (246.862) seconds [11:52:41:INFO] Test Suite 'TabStripTestCase' started at 2019-03-15 11:52:41.558 [11:52:41:INFO] [0315/115241.559425:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist. [11:52:41:INFO] Test Case '-[TabStripTestCase testTabStripSwitchTabs]' started. [11:52:45:INFO] Test Case '-[TabStripTestCase testTabStripSwitchTabs]' passed (3.660 seconds). [11:52:45:INFO] Test Suite 'TabStripTestCase' passed at 2019-03-15 11:52:45.236. [11:52:45:INFO] Executed 1 test, with 0 failures (0 unexpected) in 3.660 (3.678) seconds [11:52:45:INFO] Test Suite 'ios_chrome_ui_egtests_module.xctest' passed at 2019-03-15 11:52:45.237. [11:52:45:INFO] Executed 43 tests, with 0 failures (0 unexpected) in 544.910 (546.913) seconds [11:52:45:INFO] Test Suite 'Selected tests' passed at 2019-03-15 11:52:45.238. [11:52:45:INFO] Executed 43 tests, with 0 failures (0 unexpected) in 544.910 (546.916) seconds [11:52:45:INFO] [11:52:45:INFO] [11:52:45:INFO] Test session log: [11:52:45:INFO] /b/s/w/io2S7Obh/12.1 iPhone 7/attempt_0/1_Test/Diagnostics/ios_chrome_ui_egtests_module-6AEC6F82-CDFD-4FB1-9F04-498B806DA91C/ios_chrome_ui_egtests_module-F95FD228-A0C8-4748-9924-3862581568D5/Session-ios_chrome_ui_egtests_module-2019-03-15_114313-479cdb.log [11:52:45:INFO] [11:52:45:INFO] 2019-03-15 11:52:45.603 xcodebuild[23996:311030] [MT] IDETestOperationsObserverDebug: 572.225 elapsed -- Testing started completed. [11:52:45:INFO] 2019-03-15 11:52:45.603 xcodebuild[23996:311030] [MT] IDETestOperationsObserverDebug: 0.000 sec, +0.000 sec -- start [11:52:45:INFO] 2019-03-15 11:52:45.603 xcodebuild[23996:311030] [MT] IDETestOperationsObserverDebug: 572.225 sec, +572.225 sec -- end [11:52:45:INFO] ** TEST EXECUTE SUCCEEDED ** [11:52:45:INFO] [11:52:51:INFO] Command ['xcodebuild', 'test-without-building', '-xctestrun', '/b/s/w/io2S7Obh/run_1552675392.xctestrun', '-destination', 'platform=iOS Simulator,OS=12.1,name=iPhone 7', '-resultBundlePath', '/b/s/w/io2S7Obh/12.1 iPhone 7/attempt_0'] finished with 0 [11:52:51:DEBUG] Test ended. [11:52:51:DEBUG] Extracting test data. [11:52:51:DEBUG] Retrieving crash reports. [11:52:51:DEBUG] Retrieving derived data. [11:52:51:DEBUG] Making desktop screenshots. [11:52:51:DEBUG] Killing simulators. [11:52:56:DEBUG] Wiping simulator. Device wiped. [11:52:59:DEBUG] End of tear_down. 2019-03-15 12:22:16,279 - root: [DEBUG] Contents of task_output_dir: ['/b/s/w/ir/k/recipe_cleanup/16_5WmftwA/439b79e30bf61110'] 2019-03-15 12:22:16,280 - root: [WARNING] Expected output.json file missing: set(['/b/s/w/ir/k/recipe_cleanup/16_5WmftwA/439b79e30bf61110/output.json']) Found: [] Expected: ['/b/s/w/ir/k/recipe_cleanup/16_5WmftwA/439b79e30bf61110/output.json'] 2019-03-15 12:22:16,280 - root: [WARNING] No shard json files found in task_output_dir: '/b/s/w/ir/k/recipe_cleanup/16_5WmftwA' Found ['/b/s/w/ir/k/recipe_cleanup/16_5WmftwA/439b79e30bf61110'] 2019-03-15 12:22:16,280 - root: [DEBUG] Found shard_json_files: ['/b/s/w/ir/k/recipe_cleanup/16_5WmftwA/439b79e30bf61110/output.json'] 2019-03-15 12:22:16,280 - 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/tmpJkXWC_.json --task-output-dir /b/s/w/ir/k/recipe_cleanup/16_5WmftwA -o /b/s/w/ir/tmp/t/tmpyg09KU.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/tmpJkXWC_.json', '--task-output-dir', '/b/s/w/ir/k/recipe_cleanup/16_5WmftwA', '-o', '/b/s/w/ir/tmp/t/tmpyg09KU.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/tmpJkXWC_.json', '--task-output-dir', '/b/s/w/ir/k/recipe_cleanup/16_5WmftwA', '-o', '/b/s/w/ir/tmp/t/tmpyg09KU.json'] returned exit code 0