Skip to content

Instantly share code, notes, and snippets.

@imurchie
Last active April 1, 2020 14:07
Show Gist options
  • Star 0 You must be signed in to star a gist
  • Fork 0 You must be signed in to fork a gist
  • Save imurchie/68c2ffece6fac2f8474454a2d84fe4c8 to your computer and use it in GitHub Desktop.
Save imurchie/68c2ffece6fac2f8474454a2d84fe4c8 to your computer and use it in GitHub Desktop.
Popup permission dialog box, iOS 12.2, iPhone 6, Xcode 11.4
<?xml version="1.0" encoding="UTF-8"?><AppiumAUT><XCUIElementTypeApplication type="XCUIElementTypeApplication" name="Safari" label="Safari" enabled="true" visible="true" x="0" y="0" width="375" height="667">
<XCUIElementTypeWindow type="XCUIElementTypeWindow" enabled="true" visible="false" x="0" y="0" width="375" height="667">
<XCUIElementTypeOther type="XCUIElementTypeOther" enabled="true" visible="false" x="0" y="0" width="375" height="667"/>
</XCUIElementTypeWindow>
<XCUIElementTypeWindow type="XCUIElementTypeWindow" enabled="true" visible="true" x="0" y="0" width="375" height="667">
<XCUIElementTypeStatusBar type="XCUIElementTypeStatusBar" enabled="true" visible="true" x="0" y="0" width="375" height="20">
<XCUIElementTypeOther type="XCUIElementTypeOther" enabled="true" visible="false" x="0" y="0" width="375" height="20"/>
<XCUIElementTypeOther type="XCUIElementTypeOther" enabled="true" visible="true" x="0" y="0" width="375" height="20">
<XCUIElementTypeOther type="XCUIElementTypeOther" enabled="true" visible="true" x="6" y="0" width="39" height="20"/>
<XCUIElementTypeOther type="XCUIElementTypeOther" value="SSID" name="3 of 3 Wi-Fi bars" label="3 of 3 Wi-Fi bars" enabled="true" visible="true" x="50" y="0" width="15" height="20"/>
<XCUIElementTypeOther type="XCUIElementTypeOther" name="10:01 AM" label="10:01 AM" enabled="true" visible="true" x="162" y="0" width="55" height="20"/>
<XCUIElementTypeOther type="XCUIElementTypeOther" name="100% battery power" label="100% battery power" enabled="true" visible="true" x="335" y="0" width="35" height="20"/>
</XCUIElementTypeOther>
</XCUIElementTypeStatusBar>
</XCUIElementTypeWindow>
</XCUIElementTypeApplication></AppiumAUT>
PLATFORM_VERSION=12.2 DEVICE_NAME="iPhone 6" _LOG_TIMESTAMP=1 npm run e2e-test:web
> appium-xcuitest-driver@3.19.0 e2e-test:web /Users/isaacmurchie/code/appium-xcuitest-driver
> npm run build && _FORCE_LOGS=1 npm run mocha -- -t 0 -R spec build/test/functional/web --exit
> appium-xcuitest-driver@3.19.0 build /Users/isaacmurchie/code/appium-xcuitest-driver
> gulp transpile
[10:03:05] Using gulpfile ~/code/appium-xcuitest-driver/gulpfile.js
[10:03:05] Starting 'transpile'...
[10:03:08] Finished 'transpile' after 3.35 s
> appium-xcuitest-driver@3.19.0 mocha /Users/isaacmurchie/code/appium-xcuitest-driver
> mocha "-t" "0" "-R" "spec" "build/test/functional/web" "--exit"
safari - windows and frames
with safariAllowPopups
info [10-03-11:962] XCUITest XCUITestDriver server listening on http://localhost:4994
info [10-03-12:020] HTTP --> POST /wd/hub/session
info [10-03-12:020] HTTP {"desiredCapabilities":{"safariInitialUrl":"http://localhost:4994/test/guinea-pig","safariAllowPopups":true,"nativeWebTap":true,"showXcodeLog":true,"browserName":"Safari","platformName":"iOS","platformVersion":"12.2","deviceName":"iPhone 6","automationName":"XCUITest","launchWithIDB":false,"noReset":true,"maxTypingFrequency":30,"clearSystemFiles":true,"wdaLaunchTimeout":240000,"wdaConnectionTimeout":480000,"useNewWDA":true,"webviewConnectTimeout":30000}}
dbug [10-03-12:023] MJSONWP Calling XCUITestDriver.createSession() with args: [{"safariInitialUrl":"http://localhost:4994/test/guinea-pig","safariAllowPopups":true,"nativeWebTap":true,"showXcodeLog":true,"browserName":"Safari","platformName":"iOS","platformVersion":"12.2","deviceName":"iPhone 6","automationName":"XCUITest","launchWithIDB":false,"noReset":true,"maxTypingFrequency":30,"clearSystemFiles":true,"wdaLaunchTimeout":240000,"wdaConnectionTimeout":480000,"useNewWDA":true,"webviewConnectTimeout":30000},null,null]
dbug [10-03-12:023] XCUITest Executing command 'createSession'
dbug [10-03-12:028] BaseDriver Event 'newSessionRequested' logged at 1585749792024 (10:03:12 GMT-0400 (Eastern Daylight Time))
dbug [10-03-12:029] BaseDriver Creating session with MJSONWP desired capabilities: {
dbug [10-03-12:029] BaseDriver "safariInitialUrl": "http://localhost:4994/test/guinea-pig",
dbug [10-03-12:029] BaseDriver "safariAllowPopups": true,
dbug [10-03-12:029] BaseDriver "nativeWebTap": true,
dbug [10-03-12:029] BaseDriver "showXcodeLog": true,
dbug [10-03-12:029] BaseDriver "browserName": "Safari",
dbug [10-03-12:029] BaseDriver "platformName": "iOS",
dbug [10-03-12:029] BaseDriver "platformVersion": "12.2",
dbug [10-03-12:029] BaseDriver "deviceName": "iPhone 6",
dbug [10-03-12:029] BaseDriver "automationName": "XCUITest",
dbug [10-03-12:029] BaseDriver "launchWithIDB": false,
dbug [10-03-12:029] BaseDriver "noReset": true,
dbug [10-03-12:029] BaseDriver "maxTypingFrequency": 30,
dbug [10-03-12:029] BaseDriver "clearSystemFiles": true,
dbug [10-03-12:029] BaseDriver "wdaLaunchTimeout": 240000,
dbug [10-03-12:029] BaseDriver "wdaConnectionTimeout": 480000,
dbug [10-03-12:029] BaseDriver "useNewWDA": true,
dbug [10-03-12:029] BaseDriver "webviewConnectTimeout": 30000
dbug [10-03-12:029] BaseDriver }
info [10-03-12:042] BaseDriver Session created with session id: 3a020003-d294-49f7-889e-c18f604197ce
dbug [10-03-12:064] XCUITest Current user: 'isaacmurchie'
info [10-03-12:141] XCUITest iOS SDK Version set to '13.4'
WARN [10-03-13:245] XCUITest Unable to find device 'iPhone 6'. Found 'appiumTest-A6CB9D5D-BA5F-4FF5-AD8A-3B1A616977C8-iPhone 6' (udid: '52BA7683-4D2B-438B-A369-BB03AFB3A5B8') instead
info [10-03-13:246] iOSSim Constructing iOS simulator for Xcode version 11.4 with udid '52BA7683-4D2B-438B-A369-BB03AFB3A5B8'
info [10-03-13:247] XCUITest Determining device to run tests on: udid: '52BA7683-4D2B-438B-A369-BB03AFB3A5B8', real device: false
dbug [10-03-13:250] BaseDriver Event 'xcodeDetailsRetrieved' logged at 1585749793250 (10:03:13 GMT-0400 (Eastern Daylight Time))
info [10-03-13:250] XCUITest Safari test requested
dbug [10-03-13:251] BaseDriver Event 'appConfigured' logged at 1585749793251 (10:03:13 GMT-0400 (Eastern Daylight Time))
dbug [10-03-13:251] BaseDriver Event 'resetStarted' logged at 1585749793251 (10:03:13 GMT-0400 (Eastern Daylight Time))
dbug [10-03-13:252] XCUITest Reset: noReset is on. Leaving simulator as is
dbug [10-03-13:252] BaseDriver Event 'resetComplete' logged at 1585749793252 (10:03:13 GMT-0400 (Eastern Daylight Time))
dbug [10-03-13:409] IOSSimulatorLog Starting log capture for iOS Simulator with udid '52BA7683-4D2B-438B-A369-BB03AFB3A5B8' using simctl
dbug [10-03-13:745] BaseDriver Event 'logCaptureStarted' logged at 1585749793745 (10:03:13 GMT-0400 (Eastern Daylight Time))
info [10-03-13:745] XCUITest Setting up simulator
dbug [10-03-13:746] iOS No reason to set locale
dbug [10-03-13:746] iOS Setting iOS and app preferences
dbug [10-03-13:932] iOSSim Checking whether simulator has been run before: yes
dbug [10-03-13:933] iOS Setting javascript window opening to 'true'
dbug [10-03-13:933] iOSSim Updating Safari user settings
dbug [10-03-13:946] iOSSim Checking whether simulator has been run before: yes
dbug [10-03-13:947] iOSSim Building bundle path map
dbug [10-03-13:985] iOS Setting did not need to be updated
dbug [10-03-13:986] iOSSim Setting preferences of 52BA7683-4D2B-438B-A369-BB03AFB3A5B8 Simulator to {"ConnectHardwareKeyboard":false}
dbug [10-03-13:986] iOSSim Setting common Simulator preferences to {"RotateWindowWhenSignaledByGuest":true,"ConnectHardwareKeyboard":false}
dbug [10-03-14:061] iOSSim Updated 52BA7683-4D2B-438B-A369-BB03AFB3A5B8 Simulator preferences at '/Users/isaacmurchie/Library/Preferences/com.apple.iphonesimulator.plist' with {"DevicePreferences":{"52BA7683-4D2B-438B-A369-BB03AFB3A5B8":{"ConnectHardwareKeyboard":false}},"RotateWindowWhenSignaledByGuest":true,"ConnectHardwareKeyboard":false}
dbug [10-03-14:313] iOSSim Got Simulator UI client PID: 16198
info [10-03-14:313] iOSSim Both Simulator with UDID '52BA7683-4D2B-438B-A369-BB03AFB3A5B8' and the UI client are currently running
dbug [10-03-14:314] BaseDriver Event 'simStarted' logged at 1585749794313 (10:03:14 GMT-0400 (Eastern Daylight Time))
info [10-03-14:315] WebDriverAgent Using WDA path: '/Users/isaacmurchie/code/appium-xcuitest-driver/node_modules/appium-webdriveragent'
info [10-03-14:315] WebDriverAgent Using WDA agent: '/Users/isaacmurchie/code/appium-xcuitest-driver/node_modules/appium-webdriveragent/WebDriverAgent.xcodeproj'
dbug [10-03-16:001] WebDriverAgent No obsolete cached processes from previous WDA sessions listening on port 8100 have been found
info [10-03-16:001] DevCon Factory Requesting connection for device 52BA7683-4D2B-438B-A369-BB03AFB3A5B8 on local port 8100, device port 8100
dbug [10-03-16:002] DevCon Factory Cached connections count: 0
info [10-03-16:002] DevCon Factory Successfully requested the connection for 52BA7683-4D2B-438B-A369-BB03AFB3A5B8:8100
dbug [10-03-16:003] XCUITest Starting WebDriverAgent initialization with the synchronization key 'XCUITestDriver'
dbug [10-03-16:004] XCUITest Capability 'useNewWDA' set to true, so uninstalling WDA before proceeding
info [10-03-16:004] WebDriverAgent Shutting down sub-processes
dbug [10-03-16:005] iOSSim Building bundle path map
dbug [10-03-16:015] iOSSim The simulator has '1' bundles which have 'WebDriverAgentRunner-Runner' as their 'CFBundleName':
dbug [10-03-16:015] iOSSim 'com.facebook.WebDriverAgentRunner.xctrunner'
dbug [10-03-16:015] WebDriverAgent Uninstalling WDAs: 'com.facebook.WebDriverAgentRunner.xctrunner'
dbug [10-03-17:302] BaseDriver Event 'wdaUninstalled' logged at 1585749797302 (10:03:17 GMT-0400 (Eastern Daylight Time))
dbug [10-03-17:302] XCUITest Trying to start WebDriverAgent 2 times with 10000ms interval
dbug [10-03-17:302] XCUITest These values can be customized by changing wdaStartupRetries/wdaStartupRetryInterval capabilities
dbug [10-03-17:303] BaseDriver Event 'wdaStartAttempted' logged at 1585749797303 (10:03:17 GMT-0400 (Eastern Daylight Time))
info [10-03-17:303] WebDriverAgent Launching WebDriverAgent on the device
info [10-03-17:304] WebDriverAgent Fetching dependencies
info [10-03-17:312] WebDriverAgent Dependencies up-to-date
dbug [10-03-17:312] WebDriverAgent Killing running processes 'xcodebuild.*52BA7683-4D2B-438B-A369-BB03AFB3A5B8, 52BA7683-4D2B-438B-A369-BB03AFB3A5B8.*XCTRunner, xctest.*52BA7683-4D2B-438B-A369-BB03AFB3A5B8' for the device 52BA7683-4D2B-438B-A369-BB03AFB3A5B8...
dbug [10-03-17:426] WebDriverAgent 'pgrep -if 52BA7683-4D2B-438B-A369-BB03AFB3A5B8.*XCTRunner' didn't detect any matching processes. Return code: 1
dbug [10-03-17:433] WebDriverAgent 'pgrep -if xctest.*52BA7683-4D2B-438B-A369-BB03AFB3A5B8' didn't detect any matching processes. Return code: 1
dbug [10-03-17:975] WebDriverAgent Beginning test with command 'xcodebuild build-for-testing test-without-building -project /Users/isaacmurchie/code/appium-xcuitest-driver/node_modules/appium-webdriveragent/WebDriverAgent.xcodeproj -scheme WebDriverAgentRunner -destination id=52BA7683-4D2B-438B-A369-BB03AFB3A5B8 IPHONEOS_DEPLOYMENT_TARGET=12.2 GCC_TREAT_WARNINGS_AS_ERRORS=0 COMPILER_INDEX_STORE_ENABLE=NO' in directory '/Users/isaacmurchie/code/appium-xcuitest-driver/node_modules/appium-webdriveragent'
dbug [10-03-17:977] WebDriverAgent Output from xcodebuild will be logged. To change this, use 'showXcodeLog' desired capability
ERR! [10-03-21:542] Xcode Command line invocation:
ERR! [10-03-21:542] Xcode
dbug [10-03-21:543] WebDriverAgent Waiting up to 240000ms for WebDriverAgent to start
dbug [10-03-21:545] WD Proxy Matched '/status' to command name 'getStatus'
dbug [10-03-21:548] WD Proxy Proxying [GET /status] to [GET http://127.0.0.1:8100/status] with no body
ERR! [10-03-21:563] Xcode /Applications/Xcode-11.4.app/Contents/Developer/usr/bin/xcodebuild build-for-testing test-without-building -project /Users/isaacmurchie/code/appium-xcuitest-driver/node_modules/appium-webdriveragent/WebDriverAgent.xcodeproj -scheme WebDriverAgentRunner -destination id=52BA7683-4D2B-438B-A369-BB03AFB3A5B8 IPHONEOS_DEPLOYMENT_TARGET=12.2 GCC_TREAT_WARNINGS_AS_ERRORS=0 COMPILER_INDEX_STORE_ENABLE=NO
ERR! [10-03-21:563] Xcode
ERR! [10-03-21:563] Xcode Build settings from command line:
ERR! [10-03-21:563] Xcode COMPILER_INDEX_STORE_ENABLE = NO
ERR! [10-03-21:563] Xcode GCC_TREAT_WARNINGS_AS_ERRORS = 0
ERR! [10-03-21:563] Xcode IPHONEOS_DEPLOYMENT_TARGET = 12.2
ERR! [10-03-21:563] Xcode
ERR! [10-03-21:564] Xcode
info [10-03-21:571] WD Proxy Got response with unknown status: {"errno":"ECONNREFUSED","code":"ECONNREFUSED","syscall":"connect","address":"127.0.0.1","port":8100}
dbug [10-03-22:575] WD Proxy Matched '/status' to command name 'getStatus'
dbug [10-03-22:575] WD Proxy Proxying [GET /status] to [GET http://127.0.0.1:8100/status] with no body
info [10-03-22:577] WD Proxy Got response with unknown status: {"errno":"ECONNREFUSED","code":"ECONNREFUSED","syscall":"connect","address":"127.0.0.1","port":8100}
dbug [10-03-23:577] WD Proxy Matched '/status' to command name 'getStatus'
dbug [10-03-23:578] WD Proxy Proxying [GET /status] to [GET http://127.0.0.1:8100/status] with no body
info [10-03-23:580] WD Proxy Got response with unknown status: {"errno":"ECONNREFUSED","code":"ECONNREFUSED","syscall":"connect","address":"127.0.0.1","port":8100}
dbug [10-03-24:582] WD Proxy Matched '/status' to command name 'getStatus'
dbug [10-03-24:582] WD Proxy Proxying [GET /status] to [GET http://127.0.0.1:8100/status] with no body
info [10-03-24:584] WD Proxy Got response with unknown status: {"errno":"ECONNREFUSED","code":"ECONNREFUSED","syscall":"connect","address":"127.0.0.1","port":8100}
ERR! [10-03-25:097] Xcode note: Using new build system
ERR! [10-03-25:097] Xcode
ERR! [10-03-25:097] Xcode note: Building targets in parallel
ERR! [10-03-25:098] Xcode
ERR! [10-03-25:115] Xcode note: Planning build
ERR! [10-03-25:116] Xcode
dbug [10-03-25:587] WD Proxy Matched '/status' to command name 'getStatus'
dbug [10-03-25:587] WD Proxy Proxying [GET /status] to [GET http://127.0.0.1:8100/status] with no body
info [10-03-25:588] WD Proxy Got response with unknown status: {"errno":"ECONNREFUSED","code":"ECONNREFUSED","syscall":"connect","address":"127.0.0.1","port":8100}
dbug [10-03-26:588] WD Proxy Matched '/status' to command name 'getStatus'
dbug [10-03-26:589] WD Proxy Proxying [GET /status] to [GET http://127.0.0.1:8100/status] with no body
info [10-03-26:590] WD Proxy Got response with unknown status: {"errno":"ECONNREFUSED","code":"ECONNREFUSED","syscall":"connect","address":"127.0.0.1","port":8100}
ERR! [10-03-27:155] Xcode note: Using build description from disk
ERR! [10-03-27:155] Xcode
dbug [10-03-27:596] WD Proxy Matched '/status' to command name 'getStatus'
dbug [10-03-27:596] WD Proxy Proxying [GET /status] to [GET http://127.0.0.1:8100/status] with no body
info [10-03-27:598] WD Proxy Got response with unknown status: {"errno":"ECONNREFUSED","code":"ECONNREFUSED","syscall":"connect","address":"127.0.0.1","port":8100}
ERR! [10-03-27:903] Xcode
ERR! [10-03-27:903] Xcode
ERR! [10-03-27:905] Xcode ** TEST BUILD SUCCEEDED **
ERR! [10-03-27:905] Xcode
ERR! [10-03-27:905] Xcode
dbug [10-03-27:962] WebDriverAgent Log file for xcodebuild test: /Users/isaacmurchie/Library/Developer/Xcode/DerivedData/WebDriverAgent-ggtfvfiudcwynpeazldxeoltwllp/Logs/Test/Test-WebDriverAgentRunner-2020.04.01_10-03-25--0400.xcresult/Staging/2_Test/Diagnostics/WebDriverAgentRunner-2631FD95-16DF-46D0-8BB8-94DF06E306D1/WebDriverAgentRunner-797BC306-5A05-4054-9D69-3C9311285C8B/Session-WebDriverAgentRunner-2020-04-01_100327-Zqvn4f.log
ERR! [10-03-27:962] Xcode 2020-04-01 10:03:27.961 xcodebuild[68325:42464234] IDETestOperationsObserverDebug: Writing diagnostic log for test session to:
ERR! [10-03-27:962] Xcode /Users/isaacmurchie/Library/Developer/Xcode/DerivedData/WebDriverAgent-ggtfvfiudcwynpeazldxeoltwllp/Logs/Test/Test-WebDriverAgentRunner-2020.04.01_10-03-25--0400.xcresult/Staging/2_Test/Diagnostics/WebDriverAgentRunner-2631FD95-16DF-46D0-8BB8-94DF06E306D1/WebDriverAgentRunner-797BC306-5A05-4054-9D69-3C9311285C8B/Session-WebDriverAgentRunner-2020-04-01_100327-Zqvn4f.log
ERR! [10-03-27:962] Xcode
ERR! [10-03-27:962] Xcode 2020-04-01 10:03:27.962 xcodebuild[68325:42464150] [MT] IDETestOperationsObserverDebug: (871DA88F-D142-4099-AD95-0C8290DC8470) Beginning test session WebDriverAgentRunner-871DA88F-D142-4099-AD95-0C8290DC8470 at 2020-04-01 10:03:27.962 with Xcode 11E146 on target <DVTiPhoneSimulator: 0x7f9b062a03b0> {
ERR! [10-03-27:962] Xcode SimDevice: appiumTest-A6CB9D5D-BA5F-4FF5-AD8A-3B1A616977C8-iPhone 6 (52BA7683-4D2B-438B-A369-BB03AFB3A5B8, iOS 12.2, Booted)
ERR! [10-03-27:963] Xcode } (12.2 (16E226))
ERR! [10-03-27:963] Xcode
dbug [10-03-28:600] WD Proxy Matched '/status' to command name 'getStatus'
dbug [10-03-28:601] WD Proxy Proxying [GET /status] to [GET http://127.0.0.1:8100/status] with no body
info [10-03-28:604] WD Proxy Got response with unknown status: {"errno":"ECONNREFUSED","code":"ECONNREFUSED","syscall":"connect","address":"127.0.0.1","port":8100}
ERR! [10-03-28:913] Xcode 2020-04-01 10:03:28.913 xcodebuild[68325:42464150] [MT] IDETestOperationsObserverDebug: (871DA88F-D142-4099-AD95-0C8290DC8470) Finished requesting crash reports. Continuing with testing.
ERR! [10-03-28:913] Xcode
dbug [10-03-29:606] WD Proxy Matched '/status' to command name 'getStatus'
dbug [10-03-29:606] WD Proxy Proxying [GET /status] to [GET http://127.0.0.1:8100/status] with no body
info [10-03-29:613] WD Proxy Got response with unknown status: {"errno":"ECONNREFUSED","code":"ECONNREFUSED","syscall":"connect","address":"127.0.0.1","port":8100}
dbug [10-03-30:614] WD Proxy Matched '/status' to command name 'getStatus'
dbug [10-03-30:614] WD Proxy Proxying [GET /status] to [GET http://127.0.0.1:8100/status] with no body
info [10-03-30:615] WD Proxy Got response with unknown status: {"errno":"ECONNREFUSED","code":"ECONNREFUSED","syscall":"connect","address":"127.0.0.1","port":8100}
dbug [10-03-31:615] WD Proxy Matched '/status' to command name 'getStatus'
dbug [10-03-31:616] WD Proxy Proxying [GET /status] to [GET http://127.0.0.1:8100/status] with no body
info [10-03-31:618] WD Proxy Got response with unknown status: {"errno":"ECONNREFUSED","code":"ECONNREFUSED","syscall":"connect","address":"127.0.0.1","port":8100}
dbug [10-03-32:619] WD Proxy Matched '/status' to command name 'getStatus'
dbug [10-03-32:620] WD Proxy Proxying [GET /status] to [GET http://127.0.0.1:8100/status] with no body
info [10-03-32:621] WD Proxy Got response with unknown status: {"errno":"ECONNREFUSED","code":"ECONNREFUSED","syscall":"connect","address":"127.0.0.1","port":8100}
ERR! [10-03-32:908] Xcode 2020-04-01 10:03:32.908522-0400 WebDriverAgentRunner-Runner[68331:42464584] libMobileGestalt MobileGestalt.c:890: MGIsDeviceOneOfType is not supported on this platform.
ERR! [10-03-32:909] Xcode
ERR! [10-03-32:999] Xcode 2020-04-01 10:03:32.998504-0400 WebDriverAgentRunner-Runner[68331:42464584] Running tests...
ERR! [10-03-32:999] Xcode
ERR! [10-03-33:421] Xcode 2020-04-01 10:03:33.421137-0400 WebDriverAgentRunner-Runner[68331:42464584] [AXMediaCommon] Unable to look up screen scale
ERR! [10-03-33:421] Xcode
ERR! [10-03-33:421] Xcode 2020-04-01 10:03:33.421258-0400 WebDriverAgentRunner-Runner[68331:42464584] [AXMediaCommon] Unexpected physical screen orientation
ERR! [10-03-33:422] Xcode
ERR! [10-03-33:581] Xcode 2020-04-01 10:03:33.580750-0400 WebDriverAgentRunner-Runner[68331:42464584] [AXMediaCommon] Unable to look up screen scale
ERR! [10-03-33:581] Xcode
ERR! [10-03-33:584] Xcode 2020-04-01 10:03:33.583785-0400 WebDriverAgentRunner-Runner[68331:42464584] [AXMediaCommon] Unable to look up screen scale
ERR! [10-03-33:584] Xcode
ERR! [10-03-33:584] Xcode 2020-04-01 10:03:33.583859-0400 WebDriverAgentRunner-Runner[68331:42464584] [AXMediaCommon] Unexpected physical screen orientation
ERR! [10-03-33:584] Xcode
dbug [10-03-33:625] WD Proxy Matched '/status' to command name 'getStatus'
dbug [10-03-33:626] WD Proxy Proxying [GET /status] to [GET http://127.0.0.1:8100/status] with no body
info [10-03-33:628] WD Proxy Got response with unknown status: {"errno":"ECONNREFUSED","code":"ECONNREFUSED","syscall":"connect","address":"127.0.0.1","port":8100}
ERR! [10-03-33:660] Xcode Test Suite 'All tests' started at 2020-04-01 10:03:33.660
ERR! [10-03-33:661] Xcode
ERR! [10-03-33:661] Xcode Test Suite 'WebDriverAgentLib.framework' started at 2020-04-01 10:03:33.661
ERR! [10-03-33:661] Xcode
ERR! [10-03-33:665] Xcode Test Suite 'WebDriverAgentLib.framework' passed at 2020-04-01 10:03:33.662.
ERR! [10-03-33:665] Xcode Executed 0 tests, with 0 failures (0 unexpected) in 0.000 (0.000) seconds
ERR! [10-03-33:665] Xcode Test Suite 'WebDriverAgentRunner.xctest' started at 2020-04-01 10:03:33.662
ERR! [10-03-33:665] Xcode Test Suite 'UITestingUITests' started at 2020-04-01 10:03:33.663
ERR! [10-03-33:666] Xcode
ERR! [10-03-33:737] Xcode 2020-04-01 10:03:33.736895-0400 WebDriverAgentRunner-Runner[68331:42464584] [MC] System group container for systemgroup.com.apple.configurationprofiles path is /Users/isaacmurchie/Library/Developer/CoreSimulator/Devices/52BA7683-4D2B-438B-A369-BB03AFB3A5B8/data/Containers/Shared/SystemGroup/systemgroup.com.apple.configurationprofiles
ERR! [10-03-33:737] Xcode
ERR! [10-03-33:750] Xcode 2020-04-01 10:03:33.749858-0400 WebDriverAgentRunner-Runner[68331:42464696] [MC] Invalidating cache
ERR! [10-03-33:750] Xcode
ERR! [10-03-33:752] Xcode 2020-04-01 10:03:33.751956-0400 WebDriverAgentRunner-Runner[68331:42464584] [MC] Reading from private effective user settings.
ERR! [10-03-33:752] Xcode
ERR! [10-03-33:764] Xcode 2020-04-01 10:03:33.764424-0400 WebDriverAgentRunner-Runner[68331:42464700] [MC] Invalidating cache
ERR! [10-03-33:765] Xcode
ERR! [10-03-33:766] Xcode 2020-04-01 10:03:33.765983-0400 WebDriverAgentRunner-Runner[68331:42464584] [MC] Reading from private effective user settings.
ERR! [10-03-33:766] Xcode
ERR! [10-03-33:774] Xcode Test Case '-[UITestingUITests testRunner]' started.
ERR! [10-03-33:774] Xcode
ERR! [10-03-33:775] Xcode t = 0.00s Start Test at 2020-04-01 10:03:33.775
ERR! [10-03-33:775] Xcode
ERR! [10-03-33:778] Xcode t = 0.00s Set Up
ERR! [10-03-33:778] Xcode
ERR! [10-03-33:779] Xcode 2020-04-01 10:03:33.777804-0400 WebDriverAgentRunner-Runner[68331:42464584] Built at Apr 1 2020 09:57:10
ERR! [10-03-33:779] Xcode
ERR! [10-03-34:079] Xcode 2020-04-01 10:03:34.079216-0400 WebDriverAgentRunner-Runner[68331:42464584] ServerURLHere->http://192.168.86.70:8100<-ServerURLHere
ERR! [10-03-34:079] Xcode
ERR! [10-03-34:080] Xcode 2020-04-01 10:03:34.079879-0400 WebDriverAgentRunner-Runner[68331:42464700] Using singleton test manager
ERR! [10-03-34:080] Xcode
dbug [10-03-34:631] WD Proxy Matched '/status' to command name 'getStatus'
dbug [10-03-34:631] WD Proxy Proxying [GET /status] to [GET http://127.0.0.1:8100/status] with no body
dbug [10-03-34:641] WD Proxy Got response with status 200: {
dbug [10-03-34:641] WD Proxy "value" : {
dbug [10-03-34:641] WD Proxy "message" : "WebDriverAgent is ready to accept commands",
dbug [10-03-34:641] WD Proxy "state" : "success",
dbug [10-03-34:641] WD Proxy "os" : {
dbug [10-03-34:641] WD Proxy "name" : "iOS",
dbug [10-03-34:641] WD Proxy "version" : "12.2",
dbug [10-03-34:641] WD Proxy "sdkVersion" : "13.4"
dbug [10-03-34:641] WD Proxy },
dbug [10-03-34:641] WD Proxy "ios" : {
dbug [10-03-34:641] WD Proxy "simulatorVersion" : "12.2",
dbug [10-03-34:641] WD Proxy "ip" : "192.168.86.70"
dbug [10-03-34:641] WD Proxy },
dbug [10-03-34:641] WD Proxy "ready" : true,
dbug [10-03-34:641] WD Proxy "build" : {
dbug [10-03-34:641] WD Proxy "upgradedAt" : "1585749422668",
dbug [10-03-34:641] WD Proxy "time" : "Apr 1 2020 09:57:11",
dbug [10-03-34:641] WD Proxy "productBundleIdentifier" : "com.facebook.WebDriverAgentRunner"
dbug [10-03-34:641] WD Proxy }
dbug [10-03-34:641] WD Proxy },
dbug [10-03-34:641] WD Proxy "sessionId" : "F25F7579-63B8-4CFC-9CA6-576157DB6109"
dbug [10-03-34:641] WD Proxy }
dbug [10-03-34:643] WebDriverAgent WebDriverAgent information:
dbug [10-03-34:643] WebDriverAgent {
dbug [10-03-34:643] WebDriverAgent "message": "WebDriverAgent is ready to accept commands",
dbug [10-03-34:643] WebDriverAgent "state": "success",
dbug [10-03-34:643] WebDriverAgent "os": {
dbug [10-03-34:643] WebDriverAgent "name": "iOS",
dbug [10-03-34:643] WebDriverAgent "version": "12.2",
dbug [10-03-34:643] WebDriverAgent "sdkVersion": "13.4"
dbug [10-03-34:643] WebDriverAgent },
dbug [10-03-34:643] WebDriverAgent "ios": {
dbug [10-03-34:643] WebDriverAgent "simulatorVersion": "12.2",
dbug [10-03-34:643] WebDriverAgent "ip": "192.168.86.70"
dbug [10-03-34:643] WebDriverAgent },
dbug [10-03-34:643] WebDriverAgent "ready": true,
dbug [10-03-34:643] WebDriverAgent "build": {
dbug [10-03-34:643] WebDriverAgent "upgradedAt": "1585749422668",
dbug [10-03-34:643] WebDriverAgent "time": "Apr 1 2020 09:57:11",
dbug [10-03-34:643] WebDriverAgent "productBundleIdentifier": "com.facebook.WebDriverAgentRunner"
dbug [10-03-34:643] WebDriverAgent }
dbug [10-03-34:643] WebDriverAgent }
dbug [10-03-34:649] WebDriverAgent WebDriverAgent successfully started after 16666ms
dbug [10-03-34:650] BaseDriver Event 'wdaSessionAttempted' logged at 1585749814650 (10:03:34 GMT-0400 (Eastern Daylight Time))
dbug [10-03-34:650] XCUITest Sending createSession command to WDA
dbug [10-03-34:652] WD Proxy Matched '/session' to command name 'createSession'
dbug [10-03-34:653] WD Proxy Proxying [POST /session] to [POST http://127.0.0.1:8100/session] with body: {"capabilities":{"firstMatch":[{"bundleId":"com.apple.mobilesafari","arguments":[],"environment":{},"eventloopIdleDelaySec":0,"shouldWaitForQuiescence":true,"shouldUseTestManagerForVisibilityDetection":false,"maxTypingFrequency":30,"shouldUseSingletonTestManager":true}],"alwaysMatch":{}}}
ERR! [10-03-34:662] Xcode t = 0.89s Open com.apple.mobilesafari
ERR! [10-03-34:662] Xcode
ERR! [10-03-34:663] Xcode t = 0.89s Launch com.apple.mobilesafari
ERR! [10-03-34:663] Xcode
ERR! [10-03-34:664] Xcode t = 0.89s Terminate com.apple.mobilesafari:62227
ERR! [10-03-34:664] Xcode
ERR! [10-03-35:760] Xcode t = 1.99s Wait for accessibility to load
ERR! [10-03-35:760] Xcode
ERR! [10-03-36:247] Xcode t = 2.20s Wait for com.apple.mobilesafari to idle
ERR! [10-03-36:247] Xcode
ERR! [10-03-38:778] Xcode t = 5.01s Find the Application 'com.apple.mobilesafari'
ERR! [10-03-38:779] Xcode
ERR! [10-03-38:780] Xcode t = 5.01s Requesting snapshot of accessibility hierarchy for app with pid 68335
ERR! [10-03-38:780] Xcode
dbug [10-03-38:832] WD Proxy Got response with status 200: {"value":{"sessionId":"BF6FAEC8-2CE9-4099-AA61-E58AF44BC353","capabilities":{"device":"iphone","browserName":"Safari","sdkVersion":"12.2","CFBundleIdentifier":"com.apple.mobilesafari"}},"sessionId":"BF6FAEC8-2CE9-4099-AA61-E58AF44BC353"}
info [10-03-38:832] WD Proxy Determined the downstream protocol as 'W3C'
dbug [10-03-38:833] BaseDriver Event 'wdaSessionStarted' logged at 1585749818832 (10:03:38 GMT-0400 (Eastern Daylight Time))
dbug [10-03-43:580] WebDriverAgent Parsed BUILD_DIR configuration value: '/Users/isaacmurchie/Library/Developer/Xcode/DerivedData/WebDriverAgent-ggtfvfiudcwynpeazldxeoltwllp/Build/Products'
dbug [10-03-43:580] WebDriverAgent Got derived data root: '/Users/isaacmurchie/Library/Developer/Xcode/DerivedData/WebDriverAgent-ggtfvfiudcwynpeazldxeoltwllp'
dbug [10-03-43:581] BaseDriver Event 'wdaStarted' logged at 1585749823581 (10:03:43 GMT-0400 (Eastern Daylight Time))
info [10-03-43:581] XCUITest Skipping setting of the initial display orientation. Set the "orientation" capability to either "LANDSCAPE" or "PORTRAIT", if this is an undesired behavior.
dbug [10-03-43:581] BaseDriver Event 'orientationSet' logged at 1585749823581 (10:03:43 GMT-0400 (Eastern Daylight Time))
info [10-03-44:146] HTTP --> GET /test/guinea-pig
info [10-03-44:146] HTTP {}
dbug [10-03-44:159] HTTP Sending guinea pig response with params: {"throwError":"","serverTime":"2020-04-01T14:03:44.159Z","userAgent":"Mozilla/5.0 (iPhone; CPU iPhone OS 12_2 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/12.1 Mobile/15E148 Safari/604.1","comment":"None"}
info [10-03-44:169] HTTP <-- GET /js/jquery.min.js 304 21 ms - -
info [10-03-44:169] HTTP
info [10-03-44:178] HTTP <-- GET /test/guinea-pig 200 30 ms - 3662
info [10-03-44:178] HTTP
dbug [10-03-44:241] iOSSim Safari successfully opened 'http://localhost:4994/test/guinea-pig' in 0.499s
dbug [10-03-44:241] XCUITest Waiting for initial webview
dbug [10-03-44:242] iOS Navigating to most recently opened webview
dbug [10-03-44:242] iOS Retrieving contexts and views
dbug [10-03-44:242] XCUITest Selecting by url: true (expected url: 'http://localhost:4994/test/guinea-pig')
info [10-03-44:583] RemoteDebugger Remote Debugger version 8.9.0
dbug [10-03-44:583] RemoteDebugger useNewSafari --> false
dbug [10-03-44:585] RemoteDebugger Checking which communication style to use (Safari on platform version '12.2')
dbug [10-03-44:585] RemoteDebugger Platform version equal or higher than '12.2': true
WARN [10-03-44:587] RemoteDebugger Setting communication protocol: using Target-based communication
dbug [10-03-44:589] RemoteDebugger Connecting to remote debugger through unix domain socket: '/private/tmp/com.apple.launchd.SkdckOxk8E/com.apple.webinspectord_sim.socket'
dbug [10-03-44:596] RemoteDebugger Debugger socket connected
dbug [10-03-44:597] RemoteDebugger Sending connection key request
dbug [10-03-44:601] RemoteDebugger Sending '_rpc_reportIdentifier:' message (id: 0): 'setConnectionKey'
dbug [10-03-44:670] RemoteDebugger Sending to Web Inspector took 72ms
dbug [10-03-44:670] RemoteDebugger Waiting up to 30000ms for applications to be reported
dbug [10-03-45:090] RemoteDebugger Received connected applications list: PID:68335
dbug [10-03-45:091] RemoteDebugger Received connected driver list: {}
dbug [10-03-45:093] RemoteDebugger Notified that new application 'PID:68368' has connected
dbug [10-03-45:094] RemoteDebugger Found app id key 'PID:68335' for bundle 'com.apple.mobilesafari'
dbug [10-03-45:094] RemoteDebugger Found separate bundleId 'com.apple.WebKit.WebContent' acting as proxy for 'com.apple.mobilesafari', with app id 'PID:68368'
dbug [10-03-45:094] RemoteDebugger Using proxied app id 'PID:68368'
dbug [10-03-45:109] RemoteDebugger Notified that new application 'PID:68336' has connected
dbug [10-03-45:171] RemoteDebugger Sending connection key request
dbug [10-03-45:171] RemoteDebugger Sending '_rpc_reportIdentifier:' message (id: 2): 'setConnectionKey'
dbug [10-03-45:173] RemoteDebugger Sending to Web Inspector took 2ms
dbug [10-03-45:173] RemoteDebugger Selecting application
dbug [10-03-45:174] RemoteDebugger Current applications available:
dbug [10-03-45:174] RemoteDebugger Application: "PID:68335"
dbug [10-03-45:174] RemoteDebugger id: "PID:68335"
dbug [10-03-45:174] RemoteDebugger isProxy: false
dbug [10-03-45:174] RemoteDebugger name: "Safari"
dbug [10-03-45:174] RemoteDebugger bundleId: "com.apple.mobilesafari"
dbug [10-03-45:174] RemoteDebugger hostId: undefined
dbug [10-03-45:175] RemoteDebugger isActive: true
dbug [10-03-45:175] RemoteDebugger isAutomationEnabled: false
dbug [10-03-45:175] RemoteDebugger Application: "PID:68368"
dbug [10-03-45:175] RemoteDebugger id: "PID:68368"
dbug [10-03-45:175] RemoteDebugger isProxy: true
dbug [10-03-45:175] RemoteDebugger name: "com.apple.WebKit.WebContent"
dbug [10-03-45:175] RemoteDebugger bundleId: "com.apple.WebKit.WebContent"
dbug [10-03-45:175] RemoteDebugger hostId: "PID:68335"
dbug [10-03-45:175] RemoteDebugger isActive: true
dbug [10-03-45:175] RemoteDebugger isAutomationEnabled: false
dbug [10-03-45:175] RemoteDebugger pageArray: "Waiting for data"
dbug [10-03-45:176] RemoteDebugger Application: "PID:68336"
dbug [10-03-45:176] RemoteDebugger id: "PID:68336"
dbug [10-03-45:176] RemoteDebugger isProxy: true
dbug [10-03-45:176] RemoteDebugger name: "com.apple.WebKit.WebContent"
dbug [10-03-45:176] RemoteDebugger bundleId: "com.apple.WebKit.WebContent"
dbug [10-03-45:176] RemoteDebugger hostId: "PID:68335"
dbug [10-03-45:176] RemoteDebugger isActive: true
dbug [10-03-45:177] RemoteDebugger isAutomationEnabled: false
dbug [10-03-45:177] RemoteDebugger pageArray: "Waiting for data"
dbug [10-03-45:209] RemoteDebugger Checking for bundle identifiers: com.apple.WebKit.WebContent, process-com.apple.WebKit.WebContent, process-SafariViewService, com.apple.SafariViewService, *, com.apple.mobilesafari
dbug [10-03-45:210] RemoteDebugger Found app id key 'PID:68368' for bundle 'com.apple.WebKit.WebContent'
dbug [10-03-45:210] RemoteDebugger Found app id key 'PID:68368' for bundle 'process-com.apple.WebKit.WebContent'
dbug [10-03-45:210] RemoteDebugger Found app id key 'PID:68368' for bundle 'process-SafariViewService'
dbug [10-03-45:210] RemoteDebugger Found app id key 'PID:68368' for bundle 'com.apple.SafariViewService'
dbug [10-03-45:211] RemoteDebugger Found app id key 'PID:68368' for bundle '*'
dbug [10-03-45:211] RemoteDebugger Found app id key 'PID:68335' for bundle 'com.apple.mobilesafari'
dbug [10-03-45:211] RemoteDebugger Found separate bundleId 'com.apple.WebKit.WebContent' acting as proxy for 'com.apple.mobilesafari', with app id 'PID:68368'
dbug [10-03-45:211] RemoteDebugger Found separate bundleId 'com.apple.WebKit.WebContent' acting as proxy for 'com.apple.mobilesafari', with app id 'PID:68336'
dbug [10-03-45:211] RemoteDebugger Trying out the possible app ids: PID:68368, PID:68335, PID:68336 (try #1 of 20)
dbug [10-03-45:211] RemoteDebugger Attempting app 'PID:68368'
dbug [10-03-45:212] RemoteDebugger Sending '_rpc_forwardGetListing:' message to app 'PID:68368' (id: 4): 'connectToApp'
dbug [10-03-45:215] RemoteDebugger Received response from send (id: 4): '["PID:68368",{}]'
dbug [10-03-45:215] RemoteDebugger Sending to Web Inspector took 4ms
dbug [10-03-45:216] RemoteDebugger Empty page dictionary received
dbug [10-03-45:235] RemoteDebugger Error checking application: 'Empty page dictionary received'. Retrying connection
dbug [10-03-45:235] RemoteDebugger Attempting app 'PID:68335'
dbug [10-03-45:235] RemoteDebugger Sending '_rpc_forwardGetListing:' message to app 'PID:68335' (id: 6): 'connectToApp'
dbug [10-03-45:260] RemoteDebugger Received response from send (id: 6): '["PID:68335",{"1":{"WIRTitleKey":"I am a page title","WIRTypeKey":"WIRTypeWeb","WIRURLKey":"http://localhost:4994/test/guinea-pig","WIRPageIdentifierKey":1}}]'
dbug [10-03-45:260] RemoteDebugger Page changed: {
dbug [10-03-45:260] RemoteDebugger "1": {
dbug [10-03-45:260] RemoteDebugger "WIRTitleKey": "I am a page title",
dbug [10-03-45:260] RemoteDebugger "WIRTypeKey": "WIRTypeWeb",
dbug [10-03-45:260] RemoteDebugger "WIRURLKey": "http://localhost:4994/test/guinea-pig",
dbug [10-03-45:260] RemoteDebugger "WIRPageIdentifierKey": 1
dbug [10-03-45:260] RemoteDebugger }
dbug [10-03-45:260] RemoteDebugger }
dbug [10-03-45:261] XCUITest Remote debugger notified us of a new page listing: {"appIdKey":"68335","pageArray":[{"id":1,"title":"I am a page title","url":"http://localhost:4994/test/guinea-pig","isKey":false}]}
dbug [10-03-45:262] XCUITest No key id found. Choosing first id from page array
dbug [10-03-45:262] XCUITest We do not appear to have window set yet, ignoring
dbug [10-03-45:262] RemoteDebugger Sending to Web Inspector took 27ms
dbug [10-03-45:262] RemoteDebugger Received altered app id, updating from 'PID:68368' to 'PID:68335'
dbug [10-03-45:263] RemoteDebugger Current applications available:
dbug [10-03-45:263] RemoteDebugger Application: "PID:68335"
dbug [10-03-45:263] RemoteDebugger id: "PID:68335"
dbug [10-03-45:263] RemoteDebugger isProxy: false
dbug [10-03-45:263] RemoteDebugger name: "Safari"
dbug [10-03-45:263] RemoteDebugger bundleId: "com.apple.mobilesafari"
dbug [10-03-45:263] RemoteDebugger hostId: undefined
dbug [10-03-45:263] RemoteDebugger isActive: true
dbug [10-03-45:263] RemoteDebugger isAutomationEnabled: false
dbug [10-03-45:263] RemoteDebugger pageArray:
dbug [10-03-45:263] RemoteDebugger - id: 1
dbug [10-03-45:263] RemoteDebugger title: "I am a page title"
dbug [10-03-45:263] RemoteDebugger url: "http://localhost:4994/test/guinea-pig"
dbug [10-03-45:264] RemoteDebugger isKey: false
dbug [10-03-45:264] RemoteDebugger Application: "PID:68368"
dbug [10-03-45:264] RemoteDebugger id: "PID:68368"
dbug [10-03-45:264] RemoteDebugger isProxy: true
dbug [10-03-45:264] RemoteDebugger name: "com.apple.WebKit.WebContent"
dbug [10-03-45:264] RemoteDebugger bundleId: "com.apple.WebKit.WebContent"
dbug [10-03-45:264] RemoteDebugger hostId: "PID:68335"
dbug [10-03-45:264] RemoteDebugger isActive: true
dbug [10-03-45:264] RemoteDebugger isAutomationEnabled: false
dbug [10-03-45:265] RemoteDebugger pageArray: "Waiting for data"
dbug [10-03-45:265] RemoteDebugger Application: "PID:68336"
dbug [10-03-45:265] RemoteDebugger id: "PID:68336"
dbug [10-03-45:265] RemoteDebugger isProxy: true
dbug [10-03-45:266] RemoteDebugger name: "com.apple.WebKit.WebContent"
dbug [10-03-45:266] RemoteDebugger bundleId: "com.apple.WebKit.WebContent"
dbug [10-03-45:266] RemoteDebugger hostId: "PID:68335"
dbug [10-03-45:266] RemoteDebugger isActive: true
dbug [10-03-45:266] RemoteDebugger isAutomationEnabled: false
dbug [10-03-45:266] RemoteDebugger pageArray: "Waiting for data"
dbug [10-03-45:267] RemoteDebugger Finally selecting app PID:68335: [{"id":1,"title":"I am a page title","url":"http://localhost:4994/test/guinea-pig","isKey":false}]
dbug [10-03-45:267] RemoteDebugger Selected app after 94ms
dbug [10-03-45:268] iOS Picking webview 'WEBVIEW_68335.1'
dbug [10-03-45:268] XCUITest Attempting to set context to 'WEBVIEW_68335.1' from 'NATIVE_APP'
dbug [10-03-45:269] RemoteDebugger Selecting page '1' on app 'PID:68335' and forwarding socket setup
dbug [10-03-45:269] RemoteDebugger Sending '_rpc_forwardIndicateWebView:' message to app 'PID:68335', page '1' (id: 8): 'indicateWebView'
dbug [10-03-45:271] RemoteDebugger Sending to Web Inspector took 2ms
dbug [10-03-45:271] RemoteDebugger Sending '_rpc_forwardIndicateWebView:' message to app 'PID:68335', page '1' (id: 10): 'indicateWebView'
dbug [10-03-45:275] RemoteDebugger Sending to Web Inspector took 4ms
dbug [10-03-45:275] RemoteDebugger Sending '_rpc_forwardSocketSetup:' message to app 'PID:68335', page '1' (id: 12): 'setSenderKey'
dbug [10-03-45:279] RemoteDebugger Sending to Web Inspector took 4ms
dbug [10-03-45:279] RemoteDebugger Sender key set
dbug [10-03-45:280] RemoteDebugger Sending '_rpc_forwardSocketData:' message to app 'PID:68335', page '1' (id: 14): 'Target.exists'
dbug [10-03-45:283] RemoteDebugger Sending to Web Inspector took 3ms
dbug [10-03-45:283] RemoteDebugger Sending '_rpc_forwardSocketData:' message to app 'PID:68335', page '1' (id: 16): 'Inspector.enable'
dbug [10-03-45:286] RemoteDebugger Sending to Web Inspector took 3ms
dbug [10-03-45:286] RemoteDebugger Sending '_rpc_forwardSocketData:' message to app 'PID:68335', page '1' (id: 18): 'Page.enable'
dbug [10-03-45:316] RemoteDebugger Target created for app 'PID:68335' and page '1': {"targetId":"page-1","type":"page"}
dbug [10-03-45:316] RemoteDebugger Handling message (id: '17')
dbug [10-03-45:317] RemoteDebugger Handling message (id: '19')
dbug [10-03-45:318] RemoteDebugger Sending to Web Inspector took 31ms
dbug [10-03-45:318] RemoteDebugger Sending '_rpc_forwardSocketData:' message to app 'PID:68335', page '1', target 'page-1' (id: 20): 'Network.enable'
dbug [10-03-45:323] RemoteDebugger Sending to Web Inspector took 6ms
dbug [10-03-45:324] RemoteDebugger Sending '_rpc_forwardSocketData:' message to app 'PID:68335', page '1', target 'page-1' (id: 22): 'Runtime.enable'
dbug [10-03-45:331] RemoteDebugger Sending to Web Inspector took 7ms
dbug [10-03-45:331] RemoteDebugger Sending '_rpc_forwardSocketData:' message to app 'PID:68335', page '1', target 'page-1' (id: 24): 'Heap.enable'
dbug [10-03-45:338] RemoteDebugger Sending to Web Inspector took 7ms
dbug [10-03-45:359] RemoteDebugger Sending '_rpc_forwardSocketData:' message to app 'PID:68335', page '1', target 'page-1' (id: 26): 'Debugger.enable'
dbug [10-03-45:366] RemoteDebugger Sending to Web Inspector took 7ms
dbug [10-03-45:367] RemoteDebugger Sending '_rpc_forwardSocketData:' message to app 'PID:68335', page '1', target 'page-1' (id: 28): 'Console.enable'
dbug [10-03-45:373] RemoteDebugger Sending to Web Inspector took 6ms
dbug [10-03-45:373] RemoteDebugger Sending '_rpc_forwardSocketData:' message to app 'PID:68335', page '1', target 'page-1' (id: 30): 'Inspector.initialized'
dbug [10-03-45:377] RemoteDebugger Sending to Web Inspector took 4ms
dbug [10-03-45:377] RemoteDebugger Checking document readyState
dbug [10-03-45:378] RemoteDebugger Sending javascript command: 'document.readyState;'
dbug [10-03-45:378] RemoteDebugger Sending '_rpc_forwardSocketData:' message to app 'PID:68335', page '1', target 'page-1' (id: 32): 'Runtime.evaluate'
dbug [10-03-45:470] RemoteDebugger Received data response from send (id: 32): '"complete"'
dbug [10-03-45:470] RemoteDebugger Sending to Web Inspector took 92ms
dbug [10-03-45:471] RemoteDebugger Document readyState is 'complete'
dbug [10-03-45:471] RemoteDebugger Selected page after 202ms
dbug [10-03-45:471] RemoteDebugger Starting to listen for JavaScript console
dbug [10-03-45:471] RemoteDebugger Starting to listen for network events
dbug [10-03-45:471] RemoteDebugger Unregistering from page readiness notifications
dbug [10-03-45:472] BaseDriver Event 'initialWebviewNavigated' logged at 1585749825472 (10:03:45 GMT-0400 (Eastern Daylight Time))
dbug [10-03-45:472] BaseDriver The value of 'elementResponseAttributes' setting did not change. Skipping the update for it
dbug [10-03-45:472] BaseDriver The value of 'shouldUseCompactResponses' setting did not change. Skipping the update for it
dbug [10-03-45:473] BaseDriver Event 'newSessionStarted' logged at 1585749825473 (10:03:45 GMT-0400 (Eastern Daylight Time))
dbug [10-03-45:474] MJSONWP (3a020003) Cached the protocol value 'MJSONWP' for the new session 3a020003-d294-49f7-889e-c18f604197ce
dbug [10-03-45:474] MJSONWP (3a020003) Responding to client with driver.createSession() result: {"webStorageEnabled":false,"locationContextEnabled":false,"browserName":"Safari","platform":"MAC","javascriptEnabled":true,"databaseEnabled":false,"takesScreenshot":true,"networkConnectionEnabled":false,"safariInitialUrl":"http://localhost:4994/test/guinea-pig","safariAllowPopups":true,"nativeWebTap":true,"showXcodeLog":true,"platformName":"iOS","platformVersion":"12.2","deviceName":"iPhone 6","automationName":"XCUITest","launchWithIDB":false,"noReset":true,"maxTypingFrequency":30,"clearSystemFiles":true,"wdaLaunchTimeout":240000,"wdaConnectionTimeout":480000,"useNewWDA":true,"webviewConnectTimeout":30000,"udid":"52BA7683-4D2B-438B-A369-BB03AFB3A5B8"}
info [10-03-45:475] HTTP <-- POST /wd/hub/session 200 33454 ms - 731
info [10-03-45:475] HTTP
windows
info [10-03-45:485] HTTP --> POST /wd/hub/session/3a020003-d294-49f7-889e-c18f604197ce/timeouts/implicit_wait
info [10-03-45:485] HTTP {"ms":1000}
dbug [10-03-45:487] MJSONWP (3a020003) Calling XCUITestDriver.implicitWait() with args: [1000,"3a020003-d294-49f7-889e-c18f604197ce"]
dbug [10-03-45:487] XCUITest Executing command 'implicitWait'
dbug [10-03-45:487] BaseDriver Set implicit wait to 1000ms
dbug [10-03-45:488] MJSONWP (3a020003) Responding to client with driver.implicitWait() result: null
info [10-03-45:488] HTTP <-- POST /wd/hub/session/3a020003-d294-49f7-889e-c18f604197ce/timeouts/implicit_wait 200 3 ms - 76
info [10-03-45:488] HTTP
info [10-03-45:495] HTTP --> POST /wd/hub/session/3a020003-d294-49f7-889e-c18f604197ce/url
info [10-03-45:495] HTTP {"url":"http://localhost:4994/test/guinea-pig"}
dbug [10-03-45:510] MJSONWP (3a020003) Calling XCUITestDriver.setUrl() with args: ["http://localhost:4994/test/guinea-pig","3a020003-d294-49f7-889e-c18f604197ce"]
dbug [10-03-45:510] XCUITest Executing command 'setUrl'
dbug [10-03-45:510] iOS Attempting to set url 'http://localhost:4994/test/guinea-pig'
dbug [10-03-45:511] RemoteDebugger Navigating to new URL: 'http://localhost:4994/test/guinea-pig'
dbug [10-03-45:511] RemoteDebugger Waiting for frame navigated message...
dbug [10-03-45:512] RemoteDebugger Sending '_rpc_forwardSocketData:' message to app 'PID:68335', page '1', target 'page-1' (id: 34): 'Page.navigate'
dbug [10-03-45:522] RemoteDebugger Received data response from send (id: 34): '{}'
dbug [10-03-45:522] RemoteDebugger Sending to Web Inspector took 11ms
info [10-03-45:526] HTTP --> GET /test/guinea-pig
info [10-03-45:526] HTTP {}
dbug [10-03-45:527] HTTP Sending guinea pig response with params: {"throwError":"","serverTime":"2020-04-01T14:03:45.527Z","userAgent":"Mozilla/5.0 (iPhone; CPU iPhone OS 12_2 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/12.1 Mobile/15E148 Safari/604.1","comment":"None"}
info [10-03-45:528] HTTP <-- GET /test/guinea-pig 200 2 ms - 3662
info [10-03-45:528] HTTP
info [10-03-45:539] HTTP <-- GET /js/jquery.min.js 304 1 ms - -
info [10-03-45:539] HTTP
dbug [10-03-45:759] RemoteDebugger Received page change notice for app 'PID:68335' but the listing has not changed. Ignoring.
dbug [10-03-46:013] RemoteDebugger Frame navigated in 502ms from: 500ms timeout
dbug [10-03-46:526] RemoteDebugger Waiting for dom...
dbug [10-03-46:527] RemoteDebugger Page loaded, verifying whether ready
dbug [10-03-47:028] RemoteDebugger Checking document readyState
dbug [10-03-47:029] RemoteDebugger Sending javascript command: 'document.readyState;'
dbug [10-03-47:029] RemoteDebugger Sending '_rpc_forwardSocketData:' message to app 'PID:68335', page '1', target 'page-1' (id: 36): 'Runtime.evaluate'
dbug [10-03-47:060] RemoteDebugger Received data response from send (id: 36): '"complete"'
dbug [10-03-47:061] RemoteDebugger Sending to Web Inspector took 32ms
dbug [10-03-47:061] RemoteDebugger Document readyState is 'complete'
dbug [10-03-47:061] RemoteDebugger Page is ready
dbug [10-03-47:061] RemoteDebugger Page load completed in 535ms
dbug [10-03-47:061] RemoteDebugger Sending '_rpc_forwardSocketData:' message to app 'PID:68335', page '1', target 'page-1' (id: 38): 'Console.enable'
dbug [10-03-47:065] RemoteDebugger Received data response from send (id: 38): '{}'
dbug [10-03-47:066] RemoteDebugger Sending to Web Inspector took 4ms
dbug [10-03-47:066] MJSONWP (3a020003) Responding to client with driver.setUrl() result: null
info [10-03-47:067] HTTP <-- POST /wd/hub/session/3a020003-d294-49f7-889e-c18f604197ce/url 200 1571 ms - 76
info [10-03-47:067] HTTP
info [10-03-47:073] HTTP --> GET /wd/hub/session/3a020003-d294-49f7-889e-c18f604197ce/title
info [10-03-47:073] HTTP {}
dbug [10-03-47:073] MJSONWP (3a020003) Calling XCUITestDriver.title() with args: ["3a020003-d294-49f7-889e-c18f604197ce"]
dbug [10-03-47:073] XCUITest Executing command 'title'
dbug [10-03-47:074] RemoteDebugger Executing atom 'title'
dbug [10-03-47:076] RemoteDebugger Executing 'title' atom in default context
dbug [10-03-47:076] RemoteDebugger Sending javascript command: '(function(){return function(){var g=this;functi...'
dbug [10-03-47:077] RemoteDebugger Sending '_rpc_forwardSocketData:' message to app 'PID:68335', page '1', target 'page-1' (id: 40): 'Runtime.evaluate'
dbug [10-03-47:115] RemoteDebugger Received data response from send (id: 40): '"{\"status\":0,\"value\":\"I am a page title\"}"'
dbug [10-03-47:115] RemoteDebugger Sending to Web Inspector took 39ms
dbug [10-03-47:115] RemoteDebugger Received result for atom 'title' execution: "I am a page title"
dbug [10-03-47:117] MJSONWP (3a020003) Responding to client with driver.title() result: "I am a page title"
info [10-03-47:118] HTTP <-- GET /wd/hub/session/3a020003-d294-49f7-889e-c18f604197ce/title 200 45 ms - 91
info [10-03-47:118] HTTP
info [10-03-47:124] HTTP --> POST /wd/hub/session/3a020003-d294-49f7-889e-c18f604197ce/execute
info [10-03-47:124] HTTP {"script":"window.open('/test/guinea-pig2.html', '_blank');","args":[]}
dbug [10-03-47:124] MJSONWP (3a020003) Calling XCUITestDriver.execute() with args: ["window.open('/test/guinea-pig2.html', '_blank');",[],"3a020003-d294-49f7-889e-c18f604197ce"]
dbug [10-03-47:124] XCUITest Executing command 'execute'
dbug [10-03-47:124] RemoteDebugger Executing atom 'execute_script'
dbug [10-03-47:126] RemoteDebugger Executing 'execute_script' atom in default context
dbug [10-03-47:126] RemoteDebugger Sending javascript command: '(function(){return function(){var f=this;
dbug [10-03-47:126] RemoteDebugger funct...'
dbug [10-03-47:126] RemoteDebugger Sending '_rpc_forwardSocketData:' message to app 'PID:68335', page '1', target 'page-1' (id: 42): 'Runtime.evaluate'
dbug [10-03-48:131] WD Proxy Matched '/alert/text' to command name 'getAlertText'
dbug [10-03-48:131] WD Proxy Proxying [GET /alert/text] to [GET http://127.0.0.1:8100/session/BF6FAEC8-2CE9-4099-AA61-E58AF44BC353/alert/text] with no body
ERR! [10-03-48:135] Xcode t = 14.36s Get all elements bound by index for: Elements matching predicate 'elementType IN {7, 5, 46}'
ERR! [10-03-48:136] Xcode
ERR! [10-03-48:400] Xcode t = 14.36s Requesting snapshot of accessibility hierarchy for app with pid 68335
ERR! [10-03-48:400] Xcode
ERR! [10-03-53:167] Xcode t = 19.39s Find: Descendants matching type Any
ERR! [10-03-53:167] Xcode
ERR! [10-03-53:169] Xcode t = 19.40s Find: Elements matching predicate 'elementType IN {7, 5, 46}'
ERR! [10-03-53:169] Xcode
ERR! [10-03-53:172] Xcode t = 19.40s Find the Any
ERR! [10-03-53:172] Xcode
ERR! [10-03-53:173] Xcode t = 19.40s Requesting snapshot of accessibility hierarchy for app with pid 68335
ERR! [10-03-53:174] Xcode
ERR! [10-03-58:195] Xcode t = 24.42s Find: Descendants matching type Any
ERR! [10-03-58:195] Xcode
ERR! [10-03-58:196] Xcode t = 24.42s Find: Elements matching predicate 'elementType IN {7, 5, 46}'
ERR! [10-03-58:197] Xcode
ERR! [10-03-58:198] Xcode t = 24.42s Find: Element at index 0
ERR! [10-03-58:198] Xcode
ERR! [10-03-58:201] Xcode t = 24.43s Find the Application 'com.apple.mobilesafari'
ERR! [10-03-58:201] Xcode
ERR! [10-03-58:202] Xcode t = 24.43s Requesting snapshot of accessibility hierarchy for app with pid 68335
ERR! [10-03-58:202] Xcode
ERR! [10-04-03:228] Xcode t = 29.45s Find the Application 'com.apple.mobilesafari'
ERR! [10-04-03:228] Xcode
ERR! [10-04-03:228] Xcode t = 29.46s Requesting snapshot of accessibility hierarchy for app with pid 68335
ERR! [10-04-03:229] Xcode
ERR! [10-04-08:254] Xcode t = 34.48s Get all elements bound by index for: Elements matching predicate 'BLOCKPREDICATE(0x60000340ec80)'
ERR! [10-04-08:254] Xcode
ERR! [10-04-08:255] Xcode t = 34.48s Requesting snapshot of accessibility hierarchy for app with pid 68335
ERR! [10-04-08:255] Xcode
ERR! [10-04-13:308] Xcode t = 39.53s Find: Descendants matching type Any
ERR! [10-04-13:308] Xcode
ERR! [10-04-13:309] Xcode t = 39.54s Find: Elements matching predicate 'elementType IN {7, 5, 46}'
ERR! [10-04-13:310] Xcode
ERR! [10-04-13:311] Xcode t = 39.54s Find: Element at index 0
ERR! [10-04-13:312] Xcode
ERR! [10-04-13:313] Xcode t = 39.54s Find: Descendants matching type Any
ERR! [10-04-13:313] Xcode
ERR! [10-04-13:314] Xcode t = 39.54s Find: Elements matching predicate '"WebView" IN identifiers'
ERR! [10-04-13:314] Xcode
ERR! [10-04-13:315] Xcode t = 39.54s Find: Children matching type Other
ERR! [10-04-13:315] Xcode
ERR! [10-04-13:316] Xcode t = 39.54s Find: Children matching type Other
ERR! [10-04-13:316] Xcode
ERR! [10-04-13:316] Xcode t = 39.54s Find: Elements matching predicate 'BLOCKPREDICATE(0x60000340ec80)'
ERR! [10-04-13:317] Xcode
ERR! [10-04-13:320] Xcode t = 39.55s Requesting snapshot of accessibility hierarchy for app with pid 68335
ERR! [10-04-13:320] Xcode
ERR! [10-04-18:401] Xcode t = 44.63s Get all elements bound by index for: Elements matching predicate 'elementType IN {7, 5, 46}'
ERR! [10-04-18:401] Xcode
ERR! [10-04-18:402] Xcode t = 44.63s Requesting snapshot of accessibility hierarchy for app with pid 61773
ERR! [10-04-18:402] Xcode
ERR! [10-04-18:431] Xcode t = 44.66s Find: Descendants matching type Any
ERR! [10-04-18:431] Xcode
ERR! [10-04-18:433] Xcode t = 44.66s Find: Elements matching predicate 'elementType IN {7, 5, 46}'
ERR! [10-04-18:433] Xcode
ERR! [10-04-18:436] Xcode t = 44.66s Find the Any
ERR! [10-04-18:436] Xcode
ERR! [10-04-18:437] Xcode t = 44.66s Requesting snapshot of accessibility hierarchy for app with pid 61773
ERR! [10-04-18:437] Xcode
ERR! [10-04-18:462] Xcode t = 44.69s Find: Descendants matching type Any
ERR! [10-04-18:463] Xcode
ERR! [10-04-18:464] Xcode t = 44.69s Find: Elements matching predicate 'elementType IN {7, 5, 46}'
ERR! [10-04-18:464] Xcode
ERR! [10-04-18:465] Xcode t = 44.69s Find: Element at index 0
ERR! [10-04-18:466] Xcode
ERR! [10-04-18:469] Xcode t = 44.70s Find the Application 'com.apple.springboard'
ERR! [10-04-18:469] Xcode
ERR! [10-04-18:469] Xcode t = 44.70s Requesting snapshot of accessibility hierarchy for app with pid 61773
ERR! [10-04-18:469] Xcode
info [10-04-18:500] WD Proxy Got response with status 404: {
info [10-04-18:500] WD Proxy "value" : {
info [10-04-18:500] WD Proxy "error" : "no such alert",
info [10-04-18:500] WD Proxy "message" : "An attempt was made to operate on a modal dialog when one was not open",
info [10-04-18:500] WD Proxy "traceback" : ""
info [10-04-18:500] WD Proxy },
info [10-04-18:500] WD Proxy "sessionId" : "BF6FAEC8-2CE9-4099-AA61-E58AF44BC353"
info [10-04-18:500] WD Proxy }
dbug [10-04-18:501] W3C Matched W3C error code 'no such alert' to NoSuchAlertError
dbug [10-04-18:501] XCUITest No alert found: An attempt was made to operate on a modal dialog when one was not open
dbug [10-04-18:904] WD Proxy Matched '/alert/text' to command name 'getAlertText'
dbug [10-04-18:905] WD Proxy Proxying [GET /alert/text] to [GET http://127.0.0.1:8100/session/BF6FAEC8-2CE9-4099-AA61-E58AF44BC353/alert/text] with no body
ERR! [10-04-18:909] Xcode t = 45.14s Get all elements bound by index for: Elements matching predicate 'elementType IN {7, 5, 46}'
ERR! [10-04-18:909] Xcode
ERR! [10-04-18:910] Xcode t = 45.14s Requesting snapshot of accessibility hierarchy for app with pid 68335
ERR! [10-04-18:910] Xcode
ERR! [10-04-23:931] Xcode t = 50.16s Find: Descendants matching type Any
ERR! [10-04-23:931] Xcode
ERR! [10-04-23:932] Xcode t = 50.16s Find: Elements matching predicate 'elementType IN {7, 5, 46}'
ERR! [10-04-23:933] Xcode
ERR! [10-04-23:935] Xcode t = 50.16s Find the Any
ERR! [10-04-23:936] Xcode
ERR! [10-04-23:937] Xcode t = 50.16s Requesting snapshot of accessibility hierarchy for app with pid 68335
ERR! [10-04-23:937] Xcode
ERR! [10-04-28:960] Xcode t = 55.19s Find: Descendants matching type Any
ERR! [10-04-28:960] Xcode
ERR! [10-04-28:962] Xcode t = 55.19s Find: Elements matching predicate 'elementType IN {7, 5, 46}'
ERR! [10-04-28:962] Xcode
ERR! [10-04-28:963] Xcode t = 55.19s Find: Element at index 0
ERR! [10-04-28:963] Xcode
ERR! [10-04-28:965] Xcode t = 55.19s Find the Application 'com.apple.mobilesafari'
ERR! [10-04-28:965] Xcode
ERR! [10-04-28:966] Xcode t = 55.19s Requesting snapshot of accessibility hierarchy for app with pid 68335
ERR! [10-04-28:966] Xcode
ERR! [10-04-33:988] Xcode t = 60.21s Find the Application 'com.apple.mobilesafari'
ERR! [10-04-33:988] Xcode
ERR! [10-04-33:989] Xcode t = 60.22s Requesting snapshot of accessibility hierarchy for app with pid 68335
ERR! [10-04-33:990] Xcode
ERR! [10-04-39:017] Xcode t = 65.24s Get all elements bound by index for: Elements matching predicate 'BLOCKPREDICATE(0x600003428e80)'
ERR! [10-04-39:018] Xcode
ERR! [10-04-39:019] Xcode t = 65.25s Requesting snapshot of accessibility hierarchy for app with pid 68335
ERR! [10-04-39:019] Xcode
ERR! [10-04-44:058] Xcode t = 70.28s Find: Descendants matching type Any
ERR! [10-04-44:058] Xcode
ERR! [10-04-44:061] Xcode t = 70.29s Find: Elements matching predicate 'elementType IN {7, 5, 46}'
ERR! [10-04-44:061] Xcode
ERR! [10-04-44:063] Xcode t = 70.29s Find: Element at index 0
ERR! [10-04-44:063] Xcode
ERR! [10-04-44:064] Xcode t = 70.29s Find: Descendants matching type Any
ERR! [10-04-44:064] Xcode
ERR! [10-04-44:065] Xcode t = 70.29s Find: Elements matching predicate '"WebView" IN identifiers'
ERR! [10-04-44:066] Xcode
ERR! [10-04-44:067] Xcode t = 70.29s Find: Children matching type Other
ERR! [10-04-44:068] Xcode
ERR! [10-04-44:069] Xcode t = 70.30s Find: Children matching type Other
ERR! [10-04-44:069] Xcode
ERR! [10-04-44:070] Xcode t = 70.30s Find: Elements matching predicate 'BLOCKPREDICATE(0x600003428e80)'
ERR! [10-04-44:070] Xcode
ERR! [10-04-44:074] Xcode t = 70.30s Requesting snapshot of accessibility hierarchy for app with pid 68335
ERR! [10-04-44:075] Xcode
ERR! [10-04-49:165] Xcode t = 75.39s Get all elements bound by index for: Elements matching predicate 'elementType IN {7, 5, 46}'
ERR! [10-04-49:165] Xcode
ERR! [10-04-49:166] Xcode t = 75.39s Requesting snapshot of accessibility hierarchy for app with pid 61773
ERR! [10-04-49:166] Xcode
ERR! [10-04-49:194] Xcode t = 75.42s Find: Descendants matching type Any
ERR! [10-04-49:194] Xcode
ERR! [10-04-49:196] Xcode t = 75.42s Find: Elements matching predicate 'elementType IN {7, 5, 46}'
ERR! [10-04-49:196] Xcode
ERR! [10-04-49:200] Xcode t = 75.43s Find the Any
ERR! [10-04-49:200] Xcode
ERR! [10-04-49:201] Xcode t = 75.43s Requesting snapshot of accessibility hierarchy for app with pid 61773
ERR! [10-04-49:201] Xcode
ERR! [10-04-49:229] Xcode t = 75.46s Find: Descendants matching type Any
ERR! [10-04-49:229] Xcode
ERR! [10-04-49:230] Xcode t = 75.46s Find: Elements matching predicate 'elementType IN {7, 5, 46}'
ERR! [10-04-49:230] Xcode
ERR! [10-04-49:232] Xcode t = 75.46s Find: Element at index 0
ERR! [10-04-49:232] Xcode
ERR! [10-04-49:235] Xcode t = 75.46s Find the Application 'com.apple.springboard'
ERR! [10-04-49:235] Xcode
ERR! [10-04-49:235] Xcode t = 75.46s Requesting snapshot of accessibility hierarchy for app with pid 61773
ERR! [10-04-49:236] Xcode
info [10-04-49:267] WD Proxy Got response with status 404: {
info [10-04-49:267] WD Proxy "value" : {
info [10-04-49:267] WD Proxy "error" : "no such alert",
info [10-04-49:267] WD Proxy "message" : "An attempt was made to operate on a modal dialog when one was not open",
info [10-04-49:267] WD Proxy "traceback" : ""
info [10-04-49:267] WD Proxy },
info [10-04-49:267] WD Proxy "sessionId" : "BF6FAEC8-2CE9-4099-AA61-E58AF44BC353"
info [10-04-49:267] WD Proxy }
dbug [10-04-49:268] W3C Matched W3C error code 'no such alert' to NoSuchAlertError
dbug [10-04-49:268] XCUITest No alert found: An attempt was made to operate on a modal dialog when one was not open
dbug [10-04-49:671] WD Proxy Matched '/alert/text' to command name 'getAlertText'
dbug [10-04-49:671] WD Proxy Proxying [GET /alert/text] to [GET http://127.0.0.1:8100/session/BF6FAEC8-2CE9-4099-AA61-E58AF44BC353/alert/text] with no body
ERR! [10-04-49:676] Xcode t = 75.90s Get all elements bound by index for: Elements matching predicate 'elementType IN {7, 5, 46}'
ERR! [10-04-49:676] Xcode
ERR! [10-04-49:677] Xcode t = 75.90s Requesting snapshot of accessibility hierarchy for app with pid 68335
ERR! [10-04-49:677] Xcode
ERR! [10-04-54:695] Xcode t = 80.92s Find: Descendants matching type Any
ERR! [10-04-54:695] Xcode
ERR! [10-04-54:696] Xcode t = 80.92s Find: Elements matching predicate 'elementType IN {7, 5, 46}'
ERR! [10-04-54:697] Xcode
ERR! [10-04-54:699] Xcode t = 80.93s Find the Any
ERR! [10-04-54:700] Xcode
ERR! [10-04-54:701] Xcode t = 80.93s Requesting snapshot of accessibility hierarchy for app with pid 68335
ERR! [10-04-54:701] Xcode
ERR! [10-04-59:726] Xcode t = 85.95s Find: Descendants matching type Any
ERR! [10-04-59:726] Xcode
ERR! [10-04-59:728] Xcode t = 85.95s Find: Elements matching predicate 'elementType IN {7, 5, 46}'
ERR! [10-04-59:728] Xcode
ERR! [10-04-59:729] Xcode t = 85.96s Find: Element at index 0
ERR! [10-04-59:729] Xcode
ERR! [10-04-59:731] Xcode t = 85.96s Find the Application 'com.apple.mobilesafari'
ERR! [10-04-59:731] Xcode
ERR! [10-04-59:732] Xcode t = 85.96s Requesting snapshot of accessibility hierarchy for app with pid 68335
ERR! [10-04-59:732] Xcode
ERR! [10-05-04:753] Xcode t = 90.98s Find the Application 'com.apple.mobilesafari'
ERR! [10-05-04:753] Xcode
ERR! [10-05-04:754] Xcode t = 90.98s Requesting snapshot of accessibility hierarchy for app with pid 68335
ERR! [10-05-04:754] Xcode
ERR! [10-05-09:779] Xcode t = 96.01s Get all elements bound by index for: Elements matching predicate 'BLOCKPREDICATE(0x600003440980)'
ERR! [10-05-09:779] Xcode
ERR! [10-05-09:780] Xcode t = 96.01s Requesting snapshot of accessibility hierarchy for app with pid 68335
ERR! [10-05-09:780] Xcode
ERR! [10-05-14:813] Xcode t = 101.04s Find: Descendants matching type Any
ERR! [10-05-14:813] Xcode
ERR! [10-05-14:815] Xcode t = 101.04s Find: Elements matching predicate 'elementType IN {7, 5, 46}'
ERR! [10-05-14:815] Xcode
ERR! [10-05-14:816] Xcode t = 101.04s Find: Element at index 0
ERR! [10-05-14:817] Xcode
ERR! [10-05-14:817] Xcode t = 101.04s Find: Descendants matching type Any
ERR! [10-05-14:818] Xcode
ERR! [10-05-14:818] Xcode t = 101.05s Find: Elements matching predicate '"WebView" IN identifiers'
ERR! [10-05-14:819] Xcode
ERR! [10-05-14:819] Xcode t = 101.05s Find: Children matching type Other
ERR! [10-05-14:820] Xcode
ERR! [10-05-14:820] Xcode t = 101.05s Find: Children matching type Other
ERR! [10-05-14:820] Xcode
ERR! [10-05-14:821] Xcode t = 101.05s Find: Elements matching predicate 'BLOCKPREDICATE(0x600003440980)'
ERR! [10-05-14:821] Xcode
ERR! [10-05-14:824] Xcode t = 101.05s Requesting snapshot of accessibility hierarchy for app with pid 68335
ERR! [10-05-14:824] Xcode
ERR! [10-05-19:919] Xcode t = 106.15s Get all elements bound by index for: Elements matching predicate 'elementType IN {7, 5, 46}'
ERR! [10-05-19:920] Xcode
ERR! [10-05-19:921] Xcode t = 106.15s Requesting snapshot of accessibility hierarchy for app with pid 61773
ERR! [10-05-19:921] Xcode
ERR! [10-05-19:949] Xcode t = 106.18s Find: Descendants matching type Any
ERR! [10-05-19:949] Xcode
ERR! [10-05-19:950] Xcode t = 106.18s Find: Elements matching predicate 'elementType IN {7, 5, 46}'
ERR! [10-05-19:950] Xcode
ERR! [10-05-19:954] Xcode t = 106.18s Find the Any
ERR! [10-05-19:954] Xcode
ERR! [10-05-19:955] Xcode t = 106.18s Requesting snapshot of accessibility hierarchy for app with pid 61773
ERR! [10-05-19:955] Xcode
ERR! [10-05-19:984] Xcode t = 106.21s Find: Descendants matching type Any
ERR! [10-05-19:984] Xcode
ERR! [10-05-19:985] Xcode t = 106.21s Find: Elements matching predicate 'elementType IN {7, 5, 46}'
ERR! [10-05-19:985] Xcode
ERR! [10-05-19:987] Xcode t = 106.21s Find: Element at index 0
ERR! [10-05-19:987] Xcode
ERR! [10-05-19:990] Xcode t = 106.22s Find the Application 'com.apple.springboard'
ERR! [10-05-19:990] Xcode
ERR! [10-05-19:991] Xcode t = 106.22s Requesting snapshot of accessibility hierarchy for app with pid 61773
ERR! [10-05-19:991] Xcode
info [10-05-20:021] WD Proxy Got response with status 404: {
info [10-05-20:021] WD Proxy "value" : {
info [10-05-20:021] WD Proxy "error" : "no such alert",
info [10-05-20:021] WD Proxy "message" : "An attempt was made to operate on a modal dialog when one was not open",
info [10-05-20:021] WD Proxy "traceback" : ""
info [10-05-20:021] WD Proxy },
info [10-05-20:021] WD Proxy "sessionId" : "BF6FAEC8-2CE9-4099-AA61-E58AF44BC353"
info [10-05-20:021] WD Proxy }
dbug [10-05-20:022] W3C Matched W3C error code 'no such alert' to NoSuchAlertError
dbug [10-05-20:022] XCUITest No alert found: An attempt was made to operate on a modal dialog when one was not open
dbug [10-05-20:431] WD Proxy Matched '/alert/text' to command name 'getAlertText'
dbug [10-05-20:431] WD Proxy Proxying [GET /alert/text] to [GET http://127.0.0.1:8100/session/BF6FAEC8-2CE9-4099-AA61-E58AF44BC353/alert/text] with no body
ERR! [10-05-20:436] Xcode t = 106.66s Get all elements bound by index for: Elements matching predicate 'elementType IN {7, 5, 46}'
ERR! [10-05-20:436] Xcode
ERR! [10-05-20:437] Xcode t = 106.66s Requesting snapshot of accessibility hierarchy for app with pid 68335
ERR! [10-05-20:437] Xcode
ERR! [10-05-25:461] Xcode t = 111.69s Find: Descendants matching type Any
ERR! [10-05-25:461] Xcode
ERR! [10-05-25:576] Xcode t = 111.69s Find: Elements matching predicate 'elementType IN {7, 5, 46}'
ERR! [10-05-25:576] Xcode t = 111.69s Find the Any
ERR! [10-05-25:577] Xcode t = 111.69s Requesting snapshot of accessibility hierarchy for app with pid 68335
ERR! [10-05-25:577] Xcode
ERR! [10-05-30:494] Xcode t = 116.72s Find: Descendants matching type Any
ERR! [10-05-30:494] Xcode
ERR! [10-05-30:495] Xcode t = 116.72s Find: Elements matching predicate 'elementType IN {7, 5, 46}'
ERR! [10-05-30:496] Xcode
ERR! [10-05-30:497] Xcode t = 116.72s Find: Element at index 0
ERR! [10-05-30:497] Xcode
ERR! [10-05-30:500] Xcode t = 116.73s Find the Application 'com.apple.mobilesafari'
ERR! [10-05-30:500] Xcode
ERR! [10-05-30:501] Xcode t = 116.73s Requesting snapshot of accessibility hierarchy for app with pid 68335
ERR! [10-05-30:501] Xcode
ERR! [10-05-35:527] Xcode t = 121.75s Find the Application 'com.apple.mobilesafari'
ERR! [10-05-35:528] Xcode
ERR! [10-05-35:529] Xcode t = 121.75s Requesting snapshot of accessibility hierarchy for app with pid 68335
ERR! [10-05-35:529] Xcode
ERR! [10-05-40:554] Xcode t = 126.78s Get all elements bound by index for: Elements matching predicate 'BLOCKPREDICATE(0x600003476f40)'
ERR! [10-05-40:554] Xcode
ERR! [10-05-40:555] Xcode t = 126.78s Requesting snapshot of accessibility hierarchy for app with pid 68335
ERR! [10-05-40:555] Xcode
ERR! [10-05-45:584] Xcode t = 131.81s Find: Descendants matching type Any
ERR! [10-05-45:584] Xcode
ERR! [10-05-45:585] Xcode t = 131.81s Find: Elements matching predicate 'elementType IN {7, 5, 46}'
ERR! [10-05-45:586] Xcode
ERR! [10-05-45:587] Xcode t = 131.81s Find: Element at index 0
ERR! [10-05-45:587] Xcode
ERR! [10-05-45:588] Xcode t = 131.81s Find: Descendants matching type Any
ERR! [10-05-45:588] Xcode
ERR! [10-05-45:589] Xcode t = 131.82s Find: Elements matching predicate '"WebView" IN identifiers'
ERR! [10-05-45:589] Xcode
ERR! [10-05-45:591] Xcode t = 131.82s Find: Children matching type Other
ERR! [10-05-45:591] Xcode
ERR! [10-05-45:596] Xcode t = 131.82s Find: Children matching type Other
ERR! [10-05-45:596] Xcode t = 131.82s Find: Elements matching predicate 'BLOCKPREDICATE(0x600003476f40)'
ERR! [10-05-45:596] Xcode
ERR! [10-05-45:596] Xcode t = 131.82s Requesting snapshot of accessibility hierarchy for app with pid 68335
ERR! [10-05-45:596] Xcode
dbug [10-05-47:131] XCUITest Error received while executing atom: operation timed out
ERR! [10-05-50:702] Xcode t = 136.93s Get all elements bound by index for: Elements matching predicate 'elementType IN {7, 5, 46}'
ERR! [10-05-50:702] Xcode
ERR! [10-05-50:704] Xcode t = 136.93s Requesting snapshot of accessibility hierarchy for app with pid 61773
ERR! [10-05-50:704] Xcode
ERR! [10-05-50:730] Xcode t = 136.96s Find: Descendants matching type Any
ERR! [10-05-50:730] Xcode
ERR! [10-05-50:732] Xcode t = 136.96s Find: Elements matching predicate 'elementType IN {7, 5, 46}'
ERR! [10-05-50:732] Xcode
ERR! [10-05-50:737] Xcode t = 136.96s Find the Any
ERR! [10-05-50:737] Xcode
ERR! [10-05-50:738] Xcode t = 136.96s Requesting snapshot of accessibility hierarchy for app with pid 61773
ERR! [10-05-50:738] Xcode
ERR! [10-05-50:763] Xcode t = 136.99s Find: Descendants matching type Any
ERR! [10-05-50:763] Xcode
ERR! [10-05-50:765] Xcode t = 136.99s Find: Elements matching predicate 'elementType IN {7, 5, 46}'
ERR! [10-05-50:765] Xcode
ERR! [10-05-50:767] Xcode t = 136.99s Find: Element at index 0
ERR! [10-05-50:767] Xcode
ERR! [10-05-50:771] Xcode t = 137.00s Find the Application 'com.apple.springboard'
ERR! [10-05-50:772] Xcode
ERR! [10-05-50:772] Xcode t = 137.00s Requesting snapshot of accessibility hierarchy for app with pid 61773
ERR! [10-05-50:772] Xcode
info [10-05-50:802] WD Proxy Got response with status 404: {
info [10-05-50:802] WD Proxy "value" : {
info [10-05-50:802] WD Proxy "error" : "no such alert",
info [10-05-50:802] WD Proxy "message" : "An attempt was made to operate on a modal dialog when one was not open",
info [10-05-50:802] WD Proxy "traceback" : ""
info [10-05-50:802] WD Proxy },
info [10-05-50:802] WD Proxy "sessionId" : "BF6FAEC8-2CE9-4099-AA61-E58AF44BC353"
info [10-05-50:802] WD Proxy }
dbug [10-05-50:802] W3C Matched W3C error code 'no such alert' to NoSuchAlertError
dbug [10-05-50:802] XCUITest No alert found: An attempt was made to operate on a modal dialog when one was not open
dbug [10-05-50:811] MJSONWP (3a020003) Encountered internal error running command: Error: Did not get any response for atom execution after 120005ms
dbug [10-05-50:811] MJSONWP (3a020003) at /Users/isaacmurchie/code/appium-xcuitest-driver/lib/commands/web.js:288:15
dbug [10-05-50:811] MJSONWP (3a020003) at tryCatcher (/Users/isaacmurchie/code/appium-xcuitest-driver/node_modules/bluebird/js/release/util.js:16:23)
dbug [10-05-50:811] MJSONWP (3a020003) at Promise._settlePromiseFromHandler (/Users/isaacmurchie/code/appium-xcuitest-driver/node_modules/bluebird/js/release/promise.js:547:31)
dbug [10-05-50:811] MJSONWP (3a020003) at Promise._settlePromise (/Users/isaacmurchie/code/appium-xcuitest-driver/node_modules/bluebird/js/release/promise.js:604:18)
dbug [10-05-50:811] MJSONWP (3a020003) at Promise._settlePromise0 (/Users/isaacmurchie/code/appium-xcuitest-driver/node_modules/bluebird/js/release/promise.js:649:10)
dbug [10-05-50:811] MJSONWP (3a020003) at Promise._settlePromises (/Users/isaacmurchie/code/appium-xcuitest-driver/node_modules/bluebird/js/release/promise.js:725:18)
dbug [10-05-50:811] MJSONWP (3a020003) at _drainQueueStep (/Users/isaacmurchie/code/appium-xcuitest-driver/node_modules/bluebird/js/release/async.js:93:12)
dbug [10-05-50:811] MJSONWP (3a020003) at _drainQueue (/Users/isaacmurchie/code/appium-xcuitest-driver/node_modules/bluebird/js/release/async.js:86:9)
dbug [10-05-50:811] MJSONWP (3a020003) at Async._drainQueues (/Users/isaacmurchie/code/appium-xcuitest-driver/node_modules/bluebird/js/release/async.js:102:5)
dbug [10-05-50:811] MJSONWP (3a020003) at Immediate.Async.drainQueues (/Users/isaacmurchie/code/appium-xcuitest-driver/node_modules/bluebird/js/release/async.js:15:14)
dbug [10-05-50:811] MJSONWP (3a020003) at runCallback (timers.js:705:18)
dbug [10-05-50:811] MJSONWP (3a020003) at tryOnImmediate (timers.js:676:5)
dbug [10-05-50:811] MJSONWP (3a020003) at processImmediate (timers.js:658:5)
info [10-05-50:816] HTTP <-- POST /wd/hub/session/3a020003-d294-49f7-889e-c18f604197ce/execute 500 123690 ms - 229
info [10-05-50:816] HTTP
1) should be able to open js popup windows
info [10-05-50:837] HTTP --> DELETE /wd/hub/session/3a020003-d294-49f7-889e-c18f604197ce
info [10-05-50:837] HTTP {}
dbug [10-05-50:837] MJSONWP (3a020003) Calling XCUITestDriver.deleteSession() with args: ["3a020003-d294-49f7-889e-c18f604197ce"]
dbug [10-05-50:838] XCUITest Executing command 'deleteSession'
dbug [10-05-50:838] BaseDriver Event 'quitSessionRequested' logged at 1585749950838 (10:05:50 GMT-0400 (Eastern Daylight Time))
dbug [10-05-50:839] WD Proxy Matched '/session/3a020003-d294-49f7-889e-c18f604197ce' to command name 'deleteSession'
dbug [10-05-50:839] WD Proxy Proxying [DELETE /session/3a020003-d294-49f7-889e-c18f604197ce] to [DELETE http://127.0.0.1:8100/session/BF6FAEC8-2CE9-4099-AA61-E58AF44BC353] with no body
ERR! [10-05-50:845] Xcode t = 137.07s Terminate com.apple.mobilesafari:68335
ERR! [10-05-50:846] Xcode
dbug [10-05-50:915] RemoteDebugger Application 'PID:68335' disconnected. Removing from app dictionary.
dbug [10-05-50:916] RemoteDebugger Current app is 'PID:68335'
dbug [10-05-50:916] RemoteDebugger No longer have app id. Attempting to find new one.
dbug [10-05-50:916] RemoteDebugger Application 'PID:68336' disconnected. Removing from app dictionary.
dbug [10-05-50:916] RemoteDebugger Current app is 'undefined'
dbug [10-05-50:917] RemoteDebugger Application 'PID:68368' disconnected. Removing from app dictionary.
dbug [10-05-50:917] RemoteDebugger Current app is 'undefined'
ERR! [10-05-51:880] Xcode t = 138.11s Wait for com.apple.mobilesafari to become Not Running
ERR! [10-05-51:880] Xcode
dbug [10-05-51:881] WD Proxy Got response with status 200: {
dbug [10-05-51:881] WD Proxy "value" : null,
dbug [10-05-51:881] WD Proxy "sessionId" : "B8B34255-0C4D-432E-8ACE-0C0575932FBA"
dbug [10-05-51:881] WD Proxy }
info [10-05-51:881] WebDriverAgent Shutting down sub-processes
info [10-05-51:881] WebDriverAgent Shutting down 'xcodebuild' process (pid '68325')
info [10-05-51:881] WebDriverAgent Sending 'SIGTERM'...
ERR! [10-05-51:883] Xcode ** BUILD INTERRUPTED **
ERR! [10-05-51:883] Xcode
ERR! [10-05-51:895] WebDriverAgent xcodebuild exited with code 'null' and signal 'SIGTERM'
ERR! [10-05-51:896] Xcode Contents of xcodebuild log file '/Users/isaacmurchie/Library/Developer/Xcode/DerivedData/WebDriverAgent-ggtfvfiudcwynpeazldxeoltwllp/Logs/Test/Test-WebDriverAgentRunner-2020.04.01_10-03-25--0400.xcresult/Staging/2_Test/Diagnostics/WebDriverAgentRunner-2631FD95-16DF-46D0-8BB8-94DF06E306D1/WebDriverAgentRunner-797BC306-5A05-4054-9D69-3C9311285C8B/Session-WebDriverAgentRunner-2020-04-01_100327-Zqvn4f.log':
ERR! [10-05-51:897] WebDriverAgent Unable to access xcodebuild log file: 'ENOENT: no such file or directory, open '/Users/isaacmurchie/Library/Developer/Xcode/DerivedData/WebDriverAgent-ggtfvfiudcwynpeazldxeoltwllp/Logs/Test/Test-WebDriverAgentRunner-2020.04.01_10-03-25--0400.xcresult/Staging/2_Test/Diagnostics/WebDriverAgentRunner-2631FD95-16DF-46D0-8BB8-94DF06E306D1/WebDriverAgentRunner-797BC306-5A05-4054-9D69-3C9311285C8B/Session-WebDriverAgentRunner-2020-04-01_100327-Zqvn4f.log''
info [10-05-51:927] DevCon Factory Releasing connections for 52BA7683-4D2B-438B-A369-BB03AFB3A5B8 device on any port number
info [10-05-51:927] DevCon Factory Found cached connections to release: ["52BA7683-4D2B-438B-A369-BB03AFB3A5B8:8100"]
dbug [10-05-51:928] DevCon Factory Cached connections count: 0
dbug [10-05-51:990] XCUITest Did not find the temporary XCTest logs root at '/var/folders/4f/p80yvcn97qsb4l2ym_jmfpmh0000gp/T/com.apple.dt.XCTest*/'
info [10-05-51:991] XCUITest Cleaning test logs in '/Users/isaacmurchie/Library/Developer/Xcode/DerivedData/WebDriverAgent-ggtfvfiudcwynpeazldxeoltwllp/Logs' folder
dbug [10-05-51:994] iOS Clearing log files
dbug [10-05-52:017] iOS Deleting '/Users/isaacmurchie/Library/Developer/Xcode/DerivedData/WebDriverAgent-ggtfvfiudcwynpeazldxeoltwllp/Logs'. Freeing 68K.
dbug [10-05-52:038] iOS Finished clearing log files
dbug [10-05-52:038] XCUITest Found a remote debugger session. Removing...
dbug [10-05-52:039] RemoteDebugger Disconnecting from remote debugger
dbug [10-05-52:039] RemoteDebugger Cleaning up listeners
dbug [10-05-52:039] IOSSimulatorLog Stopping iOS log capture
dbug [10-05-52:059] BaseDriver Event 'quitSessionFinished' logged at 1585749952059 (10:05:52 GMT-0400 (Eastern Daylight Time))
dbug [10-05-52:059] MJSONWP (3a020003) Received response: null
dbug [10-05-52:059] MJSONWP (3a020003) But deleting session, so not returning
dbug [10-05-52:059] MJSONWP (3a020003) Responding to client with driver.deleteSession() result: null
dbug [10-05-52:060] MJSONWP (3a020003) Destroying socket connection
info [10-05-52:060] HTTP <-- DELETE /wd/hub/session/3a020003-d294-49f7-889e-c18f604197ce 200 1222 ms - 76
info [10-05-52:060] HTTP
0 passing (3m)
1 failing
1) safari - windows and frames
with safariAllowPopups
windows
should be able to open js popup windows:
Error: [execute("window.open('/test/guinea-pig2.html', '_blank');")] Error response status: 13, , UnknownError - An unknown server-side error occurred while processing the command. Selenium error: An unknown server-side error occurred while processing the command. Original error: Did not get any response for atom execution after 120005ms
at exports.newError (node_modules/wd/lib/utils.js:152:13)
at /Users/isaacmurchie/code/appium-xcuitest-driver/node_modules/wd/lib/callbacks.js:94:19
at /Users/isaacmurchie/code/appium-xcuitest-driver/node_modules/wd/lib/webdriver.js:202:5
at Request._callback (node_modules/wd/lib/http-utils.js:89:7)
at Request.self.callback (node_modules/wd/node_modules/request/request.js:185:22)
at Request.<anonymous> (node_modules/wd/node_modules/request/request.js:1161:10)
at IncomingMessage.<anonymous> (node_modules/wd/node_modules/request/request.js:1083:12)
at endReadableNT (_stream_readable.js:1145:12)
at process._tickCallback (internal/process/next_tick.js:63:19)
npm ERR! code ELIFECYCLE
npm ERR! errno 1
npm ERR! appium-xcuitest-driver@3.19.0 mocha: `mocha "-t" "0" "-R" "spec" "build/test/functional/web" "--exit"`
npm ERR! Exit status 1
npm ERR!
npm ERR! Failed at the appium-xcuitest-driver@3.19.0 mocha script.
npm ERR! This is probably not a problem with npm. There is likely additional logging output above.
npm ERR! A complete log of this run can be found in:
npm ERR! /Users/isaacmurchie/.npm/_logs/2020-04-01T14_05_52_110Z-debug.log
npm ERR! code ELIFECYCLE
npm ERR! errno 1
npm ERR! appium-xcuitest-driver@3.19.0 e2e-test:web: `npm run build && _FORCE_LOGS=1 npm run mocha -- -t 0 -R spec build/test/functional/web --exit`
npm ERR! Exit status 1
npm ERR!
npm ERR! Failed at the appium-xcuitest-driver@3.19.0 e2e-test:web script.
npm ERR! This is probably not a problem with npm. There is likely additional logging output above.
npm ERR! A complete log of this run can be found in:
npm ERR! /Users/isaacmurchie/.npm/_logs/2020-04-01T14_05_52_182Z-debug.log
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment