appium.log

2017-12-14 17:20:29:989 - [Appium] Welcome to Appium v1.7.2-beta2
2017-12-14 17:20:29:992 - [Appium] Non-default server args:
2017-12-14 17:20:29:993 - [Appium]   port: 5712
2017-12-14 17:20:29:993 - [Appium]   backendRetries: 5
2017-12-14 17:20:29:993 - [Appium]   logTimestamp: true
2017-12-14 17:20:29:994 - [Appium]   tmpDir: tmp/5712/
2017-12-14 17:20:29:994 - [Appium]   wdaLocalPort: 4712
2017-12-14 17:20:29:994 - [Appium]   udid: ################################
2017-12-14 17:20:29:995 - [Appium] Deprecated server args:
2017-12-14 17:20:29:996 - [Appium]   -U,--udid => --default-capabilities '{"udid":"################################"}'
2017-12-14 17:20:29:997 - [Appium] Default capabilities, which will be added to each request unless overridden by desired capabilities:
2017-12-14 17:20:29:997 - [Appium]   udid: ################################
2017-12-14 17:20:30:052 - [Appium] Appium REST http interface listener started on 0.0.0.0:5712
2017-12-14 17:20:35:845 - [HTTP] --> POST /wd/hub/session {"desiredCapabilities":{"browserName":"","version":"","platform":"ANY","javascriptEnabled":true,"cssSelectorsEnabled":true,"takesScreenshot":true,"nativeEvents":false,"rotatable":false,"platformName":"ios","deviceName":"iPod 6 gen","platformVersion":"10.3.2","udid":"################################","bundleId":"com.topfreegames.bikeracefree","launchTimeout":200000,"newCommandTimeout":1000000,"screenshotWaitTimeout":100,"fullReset":false,"noReset":true,"automationName":"XCUITest","usePrebuiltWDA":true,"wdaLaunchTimeout":300000,"wdaConnectionTimeout":1000000,"commandTimeouts":"1000000","wdaStartupRetries":3,"simpleIsVisibleCheck":true,"wdaLocalPort":4712,"xcodeSigningId":"iPhone Developer","xcodeOrgId":"3THJCX5EUA"}}
2017-12-14 17:20:35:848 - [debug] [MJSONWP] Calling AppiumDriver.createSession() with args: [{"browserName":"","version":"","platform":"ANY","javascriptEnabled":true,"cssSelectorsEnabled":true,"takesScreenshot":true,"nativeEvents":false,"rotatable":false,"platformName":"ios","deviceName":"iPod 6 gen","platformVersion":"10.3.2","udid":"################################","bundleId":"com.topfreegames.bikeracefree","launchTimeout":200000,"newCommandTimeout":1000000,"screenshotWaitTimeout":100,"fullReset":false,"noReset":true,"automationName":"XCUITest","usePrebuiltWDA":true,"wdaLaunchTimeout":300000,"wdaConnectionTimeout":1000000,"commandTimeouts":"1000000","wdaStartupRetries":3,"simpleIsVisibleCheck":true,"wdaLocalPort":4712,"xcodeSigningId":"iPhone Developer","xcodeOrgId":"3THJCX5EUA"},null,null]
2017-12-14 17:20:35:850 - [debug] [BaseDriver] Event 'newSessionRequested' logged at 1513272035849 (15:20:35 GMT-0200 (-02))
2017-12-14 17:20:35:852 - [Appium] Creating new XCUITestDriver (v2.60.0) session
2017-12-14 17:20:35:852 - [Appium] Capabilities:
2017-12-14 17:20:35:853 - [Appium]   browserName: 
2017-12-14 17:20:35:853 - [Appium]   version: 
2017-12-14 17:20:35:854 - [Appium]   platform: ANY
2017-12-14 17:20:35:854 - [Appium]   javascriptEnabled: true
2017-12-14 17:20:35:854 - [Appium]   cssSelectorsEnabled: true
2017-12-14 17:20:35:855 - [Appium]   takesScreenshot: true
2017-12-14 17:20:35:855 - [Appium]   nativeEvents: false
2017-12-14 17:20:35:855 - [Appium]   rotatable: false
2017-12-14 17:20:35:856 - [Appium]   platformName: ios
2017-12-14 17:20:35:856 - [Appium]   deviceName: iPod 6 gen
2017-12-14 17:20:35:857 - [Appium]   platformVersion: 10.3.2
2017-12-14 17:20:35:857 - [Appium]   udid: ################################
2017-12-14 17:20:35:857 - [Appium]   bundleId: com.topfreegames.bikeracefree
2017-12-14 17:20:35:858 - [Appium]   launchTimeout: 200000
2017-12-14 17:20:35:858 - [Appium]   newCommandTimeout: 1000000
2017-12-14 17:20:35:858 - [Appium]   screenshotWaitTimeout: 100
2017-12-14 17:20:35:859 - [Appium]   fullReset: false
2017-12-14 17:20:35:859 - [Appium]   noReset: true
2017-12-14 17:20:35:860 - [Appium]   automationName: XCUITest
2017-12-14 17:20:35:861 - [Appium]   usePrebuiltWDA: true
2017-12-14 17:20:35:861 - [Appium]   wdaLaunchTimeout: 300000
2017-12-14 17:20:35:862 - [Appium]   wdaConnectionTimeout: 1000000
2017-12-14 17:20:35:862 - [Appium]   commandTimeouts: 1000000
2017-12-14 17:20:35:863 - [Appium]   wdaStartupRetries: 3
2017-12-14 17:20:35:863 - [Appium]   simpleIsVisibleCheck: true
2017-12-14 17:20:35:864 - [Appium]   wdaLocalPort: 4712
2017-12-14 17:20:35:864 - [Appium]   xcodeSigningId: iPhone Developer
2017-12-14 17:20:35:865 - [Appium]   xcodeOrgId: 3THJCX5EUA
2017-12-14 17:20:35:887 - [BaseDriver] The following capabilities were provided, but are not recognized by appium: version, platform, javascriptEnabled, cssSelectorsEnabled, takesScreenshot, nativeEvents, rotatable.
2017-12-14 17:20:35:908 - [BaseDriver] Session created with session id: 8cbc530d-00e3-4962-8c60-9549e25f7c31
2017-12-14 17:20:35:918 - [debug] [XCUITest] Current user: 'felyppe.rodrigues'
2017-12-14 17:20:36:776 - [debug] [XCUITest] Current version of libimobiledevice: stable 1.2.0 (bottled), HEAD
2017-12-14 17:20:36:851 - [debug] [XCUITest] Xcode version set to '9.1' (tools v9.1.0.0.1.1508540944)
2017-12-14 17:20:36:865 - [debug] [XCUITest] iOS SDK Version set to '11.1'
2017-12-14 17:20:36:865 - [debug] [BaseDriver] Event 'xcodeDetailsRetrieved' logged at 1513272036865 (15:20:36 GMT-0200 (-02))
2017-12-14 17:20:37:023 - [debug] [XCUITest] Available devices: ################################
2017-12-14 17:20:37:024 - [debug] [XCUITest] Creating iDevice object with udid '################################'
2017-12-14 17:20:37:027 - [XCUITest] Determining device to run tests on: udid: '################################', real device: true
2017-12-14 17:20:37:029 - [debug] [BaseDriver] Event 'appConfigured' logged at 1513272037029 (15:20:37 GMT-0200 (-02))
2017-12-14 17:20:37:031 - [debug] [BaseDriver] Event 'resetStarted' logged at 1513272037030 (15:20:37 GMT-0200 (-02))
2017-12-14 17:20:37:032 - [debug] [XCUITest] Reset: fullReset not set. Leaving as is
2017-12-14 17:20:37:033 - [debug] [BaseDriver] Event 'resetComplete' logged at 1513272037032 (15:20:37 GMT-0200 (-02))
2017-12-14 17:20:37:037 - [debug] [iOSLog] Attempting iOS device log capture via libimobiledevice idevicesyslog
2017-12-14 17:20:37:039 - [debug] [iOSLog] Starting iOS device log capture with: 'idevicesyslog'
2017-12-14 17:20:37:258 - [debug] [XCUITest] Crash reports root '/Users/felyppe.rodrigues/Library/Logs/CrashReporter/MobileDevice/Automation Felyppe iPod Touch 6 White' does not exist. Got nothing to gather.
2017-12-14 17:20:37:260 - [debug] [BaseDriver] Event 'logCaptureStarted' logged at 1513272037259 (15:20:37 GMT-0200 (-02))
2017-12-14 17:20:37:263 - [XCUITest] Setting up real device
2017-12-14 17:20:37:498 - [XCUITest] Using WDA path: '/usr/local/lib/node_modules/appium/node_modules/appium-xcuitest-driver/WebDriverAgent'
2017-12-14 17:20:37:499 - [XCUITest] Using WDA agent: '/usr/local/lib/node_modules/appium/node_modules/appium-xcuitest-driver/WebDriverAgent/WebDriverAgent.xcodeproj'
2017-12-14 17:20:37:639 - [debug] [XCUITest] No obsolete cached processes from previous WDA sessions listening on port 4712 have been found
2017-12-14 17:20:37:642 - [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:4712/status] with no body
2017-12-14 17:20:37:699 - [debug] [XCUITest] WDA is not listening at 'http://localhost:4712/'
2017-12-14 17:20:37:702 - [debug] [BaseDriver] Event 'wdaStartAttempted' logged at 1513272037701 (15:20:37 GMT-0200 (-02))
2017-12-14 17:20:37:703 - [XCUITest] Launching WebDriverAgent on the device
2017-12-14 17:20:37:708 - [debug] [XCUITest] Carthage found: '/usr/local/bin/carthage'
2017-12-14 17:20:37:710 - [debug] [XCUITest] Killing running processes 'xcodebuild.*################################, iproxy 4712' for the device ################################...
2017-12-14 17:20:37:767 - [debug] [XCUITest] 'pgrep -nif xcodebuild.*################################' didn't detect any matching processes. Return code: 1
2017-12-14 17:20:37:826 - [debug] [XCUITest] 'pgrep -nif iproxy 4712' didn't detect any matching processes. Return code: 1
2017-12-14 17:20:37:827 - [debug] [XCUITest] Starting iproxy to forward traffic from local port 4712 to device port 8100 over USB
2017-12-14 17:20:42:837 - [debug] [XCUITest] Using Xcode 9.1, so fixing WDA codebase
2017-12-14 17:20:42:844 - [debug] [XCUITest] Generating xcode config file for orgId '3THJCX5EUA' and signingId 'iPhone Developer'
2017-12-14 17:20:42:848 - [debug] [XCUITest] Writing xcode config file to /var/folders/m2/rjhbvj_j6tb31shsbywf5pp80000gp/T/20171114-10371-piwcvb.8ldptx1or/appium-temp.xcconfig
2017-12-14 17:20:42:851 - [debug] [XCUITest] Using Xcode configuration file: '/var/folders/m2/rjhbvj_j6tb31shsbywf5pp80000gp/T/20171114-10371-piwcvb.8ldptx1or/appium-temp.xcconfig'
2017-12-14 17:20:42:851 - [debug] [XCUITest] Beginning test with command 'xcodebuild test-without-building -project /usr/local/lib/node_modules/appium/node_modules/appium-xcuitest-driver/WebDriverAgent/WebDriverAgent.xcodeproj -scheme WebDriverAgentRunner -destination id=################################ IPHONEOS_DEPLOYMENT_TARGET=10.3 -xcconfig /var/folders/m2/rjhbvj_j6tb31shsbywf5pp80000gp/T/20171114-10371-piwcvb.8ldptx1or/appium-temp.xcconfig' in directory '/usr/local/lib/node_modules/appium/node_modules/appium-xcuitest-driver/WebDriverAgent'
2017-12-14 17:20:42:852 - [debug] [XCUITest] Output from xcodebuild will not be logged. To see xcode logging, use 'showXcodeLog' desired capability
2017-12-14 17:20:43:611 - [debug] [XCUITest] Waiting up to 300000ms for WebDriverAgent to start
2017-12-14 17:20:43:613 - [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:4712/status] with no body
2017-12-14 17:20:43:677 - [debug] [JSONWP Proxy] Got response with status 200: "{\n  \"value\" : {\n    \"state\" : \"success\",\n    \"os\" : {\n      \"name\" : \"iOS\",\n      \"version\" : \"10.3.1\"\n    },\n    \"ios\" : {\n      \"simulatorVersion\" : \"10.3.1\",\n      \"ip\" : \"10.0.21.7\"\n    },\n    \"build\" : {\n      \"time\" : \"Dec 12 2017 18:37:44\"\n    }\n  },\n  \"sessionId\" : \"7DC9E0A6-3935-411B-B94C-FC034F86B886\",\n  \"status\" : 0\n}"
2017-12-14 17:20:43:679 - [debug] [XCUITest] WebDriverAgent running on ip '10.0.21.7'
2017-12-14 17:20:43:682 - [debug] [XCUITest] WebDriverAgent successfully started after 825ms
2017-12-14 17:20:43:685 - [debug] [BaseDriver] Event 'wdaSessionAttempted' logged at 1513272043685 (15:20:43 GMT-0200 (-02))
2017-12-14 17:20:43:686 - [debug] [XCUITest] Sending createSession command to WDA
2017-12-14 17:20:43:689 - [debug] [XCUITest] Setting custom timeout to 1000000 ms for "createSession" command
2017-12-14 17:20:43:691 - [debug] [JSONWP Proxy] Proxying [POST /session] to [POST http://localhost:4712/session] with body: {"desiredCapabilities":{"bundleId":"com.topfreegames.bikeracefree","arguments":[],"environment":{},"shouldWaitForQuiescence":true,"shouldUseTestManagerForVisibilityDetection":true,"maxTypingFrequency":60,"shouldUseSingletonTestManager":true}}
2017-12-14 17:20:44:691 - [debug] [XCUITest] Log file for xcodebuild test: /var/folders/m2/rjhbvj_j6tb31shsbywf5pp80000gp/T/com.apple.dt.XCTest/IDETestRunSession-AD0E3994-91A4-47C7-A1B5-2180735E2338/WebDriverAgentRunner-15899CC6-0E14-4986-8428-9480875163FA/Session-WebDriverAgentRunner-2017-12-14_152044-pjL1H9.log
2017-12-14 17:20:45:734 - [debug] [XCUITest] Failed to create WDA session. Retrying...
2017-12-14 17:20:46:736 - [debug] [BaseDriver] Event 'wdaSessionAttempted' logged at 1513272046736 (15:20:46 GMT-0200 (-02))
2017-12-14 17:20:46:737 - [debug] [XCUITest] Sending createSession command to WDA
2017-12-14 17:20:46:737 - [debug] [XCUITest] Setting custom timeout to 1000000 ms for "createSession" command
2017-12-14 17:20:46:738 - [debug] [JSONWP Proxy] Proxying [POST /session] to [POST http://localhost:4712/session] with body: {"desiredCapabilities":{"bundleId":"com.topfreegames.bikeracefree","arguments":[],"environment":{},"shouldWaitForQuiescence":true,"shouldUseTestManagerForVisibilityDetection":true,"maxTypingFrequency":60,"shouldUseSingletonTestManager":true}}
2017-12-14 17:20:46:747 - [debug] [XCUITest] Failed to create WDA session. Retrying...
2017-12-14 17:20:47:749 - [debug] [BaseDriver] Event 'wdaSessionAttempted' logged at 1513272047748 (15:20:47 GMT-0200 (-02))
2017-12-14 17:20:47:749 - [debug] [XCUITest] Sending createSession command to WDA
2017-12-14 17:20:47:750 - [debug] [XCUITest] Setting custom timeout to 1000000 ms for "createSession" command
2017-12-14 17:20:47:750 - [debug] [JSONWP Proxy] Proxying [POST /session] to [POST http://localhost:4712/session] with body: {"desiredCapabilities":{"bundleId":"com.topfreegames.bikeracefree","arguments":[],"environment":{},"shouldWaitForQuiescence":true,"shouldUseTestManagerForVisibilityDetection":true,"maxTypingFrequency":60,"shouldUseSingletonTestManager":true}}
2017-12-14 17:20:47:757 - [debug] [XCUITest] Failed to create WDA session. Retrying...
2017-12-14 17:20:48:761 - [debug] [BaseDriver] Event 'wdaSessionAttempted' logged at 1513272048760 (15:20:48 GMT-0200 (-02))
2017-12-14 17:20:48:761 - [debug] [XCUITest] Sending createSession command to WDA
2017-12-14 17:20:48:762 - [debug] [XCUITest] Setting custom timeout to 1000000 ms for "createSession" command
2017-12-14 17:20:48:762 - [debug] [JSONWP Proxy] Proxying [POST /session] to [POST http://localhost:4712/session] with body: {"desiredCapabilities":{"bundleId":"com.topfreegames.bikeracefree","arguments":[],"environment":{},"shouldWaitForQuiescence":true,"shouldUseTestManagerForVisibilityDetection":true,"maxTypingFrequency":60,"shouldUseSingletonTestManager":true}}
2017-12-14 17:20:48:771 - [debug] [XCUITest] Failed to create WDA session. Retrying...
2017-12-14 17:20:49:777 - [debug] [BaseDriver] Event 'wdaSessionAttempted' logged at 1513272049776 (15:20:49 GMT-0200 (-02))
2017-12-14 17:20:49:778 - [debug] [XCUITest] Sending createSession command to WDA
2017-12-14 17:20:49:779 - [debug] [XCUITest] Setting custom timeout to 1000000 ms for "createSession" command
2017-12-14 17:20:49:780 - [debug] [JSONWP Proxy] Proxying [POST /session] to [POST http://localhost:4712/session] with body: {"desiredCapabilities":{"bundleId":"com.topfreegames.bikeracefree","arguments":[],"environment":{},"shouldWaitForQuiescence":true,"shouldUseTestManagerForVisibilityDetection":true,"maxTypingFrequency":60,"shouldUseSingletonTestManager":true}}
2017-12-14 17:20:49:788 - [debug] [XCUITest] Failed to create WDA session. Retrying...
2017-12-14 17:20:50:789 - [debug] [BaseDriver] Event 'wdaSessionAttempted' logged at 1513272050789 (15:20:50 GMT-0200 (-02))
2017-12-14 17:20:50:790 - [debug] [XCUITest] Sending createSession command to WDA
2017-12-14 17:20:50:790 - [debug] [XCUITest] Setting custom timeout to 1000000 ms for "createSession" command
2017-12-14 17:20:50:791 - [debug] [JSONWP Proxy] Proxying [POST /session] to [POST http://localhost:4712/session] with body: {"desiredCapabilities":{"bundleId":"com.topfreegames.bikeracefree","arguments":[],"environment":{},"shouldWaitForQuiescence":true,"shouldUseTestManagerForVisibilityDetection":true,"maxTypingFrequency":60,"shouldUseSingletonTestManager":true}}
2017-12-14 17:20:50:802 - [debug] [XCUITest] Failed to create WDA session. Retrying...
2017-12-14 17:20:51:804 - [debug] [BaseDriver] Event 'wdaSessionAttempted' logged at 1513272051803 (15:20:51 GMT-0200 (-02))
2017-12-14 17:20:51:804 - [debug] [XCUITest] Sending createSession command to WDA
2017-12-14 17:20:51:805 - [debug] [XCUITest] Setting custom timeout to 1000000 ms for "createSession" command
2017-12-14 17:20:51:805 - [debug] [JSONWP Proxy] Proxying [POST /session] to [POST http://localhost:4712/session] with body: {"desiredCapabilities":{"bundleId":"com.topfreegames.bikeracefree","arguments":[],"environment":{},"shouldWaitForQuiescence":true,"shouldUseTestManagerForVisibilityDetection":true,"maxTypingFrequency":60,"shouldUseSingletonTestManager":true}}
2017-12-14 17:20:51:813 - [debug] [XCUITest] Failed to create WDA session. Retrying...
2017-12-14 17:20:52:816 - [debug] [BaseDriver] Event 'wdaSessionAttempted' logged at 1513272052816 (15:20:52 GMT-0200 (-02))
2017-12-14 17:20:52:817 - [debug] [XCUITest] Sending createSession command to WDA
2017-12-14 17:20:52:819 - [debug] [XCUITest] Setting custom timeout to 1000000 ms for "createSession" command
2017-12-14 17:20:52:820 - [debug] [JSONWP Proxy] Proxying [POST /session] to [POST http://localhost:4712/session] with body: {"desiredCapabilities":{"bundleId":"com.topfreegames.bikeracefree","arguments":[],"environment":{},"shouldWaitForQuiescence":true,"shouldUseTestManagerForVisibilityDetection":true,"maxTypingFrequency":60,"shouldUseSingletonTestManager":true}}
2017-12-14 17:20:52:827 - [debug] [XCUITest] Failed to create WDA session. Retrying...
2017-12-14 17:20:53:827 - [debug] [BaseDriver] Event 'wdaSessionAttempted' logged at 1513272053827 (15:20:53 GMT-0200 (-02))
2017-12-14 17:20:53:828 - [debug] [XCUITest] Sending createSession command to WDA
2017-12-14 17:20:53:830 - [debug] [XCUITest] Setting custom timeout to 1000000 ms for "createSession" command
2017-12-14 17:20:53:832 - [debug] [JSONWP Proxy] Proxying [POST /session] to [POST http://localhost:4712/session] with body: {"desiredCapabilities":{"bundleId":"com.topfreegames.bikeracefree","arguments":[],"environment":{},"shouldWaitForQuiescence":true,"shouldUseTestManagerForVisibilityDetection":true,"maxTypingFrequency":60,"shouldUseSingletonTestManager":true}}
2017-12-14 17:20:53:839 - [debug] [XCUITest] Failed to create WDA session. Retrying...
2017-12-14 17:20:54:841 - [debug] [BaseDriver] Event 'wdaSessionAttempted' logged at 1513272054840 (15:20:54 GMT-0200 (-02))
2017-12-14 17:20:54:842 - [debug] [XCUITest] Sending createSession command to WDA
2017-12-14 17:20:54:843 - [debug] [XCUITest] Setting custom timeout to 1000000 ms for "createSession" command
2017-12-14 17:20:54:843 - [debug] [JSONWP Proxy] Proxying [POST /session] to [POST http://localhost:4712/session] with body: {"desiredCapabilities":{"bundleId":"com.topfreegames.bikeracefree","arguments":[],"environment":{},"shouldWaitForQuiescence":true,"shouldUseTestManagerForVisibilityDetection":true,"maxTypingFrequency":60,"shouldUseSingletonTestManager":true}}
2017-12-14 17:21:02:294 - [debug] [JSONWP Proxy] Got response with status 200: {"value":{"sessionId":"3177E211-1D5E-4190-B688-84F767681246","capabilities":{"device":"iphone","browserName":"BikeRaceTFG","sdkVersion":"10.3.1","CFBundleIdentifier":"com.topfreegames.bikeracefree"}},"sessionId":"3177E211-1D5E-4190-B688-84F767681246","status":0}
2017-12-14 17:21:02:295 - [debug] [BaseDriver] Event 'wdaSessionStarted' logged at 1513272062295 (15:21:02 GMT-0200 (-02))
2017-12-14 17:21:02:296 - [debug] [BaseDriver] Event 'wdaStarted' logged at 1513272062296 (15:21:02 GMT-0200 (-02))
2017-12-14 17:21:02:298 - [XCUITest] Skipping setting of the initial display orientation. Set the "orientation" capability to either "LANDSCAPE" or "PORTRAIT", if this is an undesired behavior.
2017-12-14 17:21:02:298 - [debug] [BaseDriver] Event 'orientationSet' logged at 1513272062298 (15:21:02 GMT-0200 (-02))
2017-12-14 17:21:02:300 - [Appium] New XCUITestDriver session created successfully, session 8cbc530d-00e3-4962-8c60-9549e25f7c31 added to master session list
2017-12-14 17:21:02:301 - [debug] [BaseDriver] Event 'newSessionStarted' logged at 1513272062301 (15:21:02 GMT-0200 (-02))
2017-12-14 17:21:02:301 - [debug] [MJSONWP] Responding to client with driver.createSession() result: {"webStorageEnabled":false,"locationContextEnabled":false,"browserName":"","platform":"ANY","javascriptEnabled":true,"databaseEnabled":false,"takesScreenshot":true,"networkConnectionEnabled":false,"version":"","cssSelectorsEnabled":true,"nativeEvents":false,"rotatable":false,"platformName":"ios","deviceName":"iPod 6 gen","platformVersion":"10.3.2","udid":"################################","bundleId":"com.topfreegames.bikeracefree","launchTimeout":200000,"newCommandTimeout":1000000,"screenshotWaitTimeout":100,"fullReset":false,"noReset":true,"automationName":"XCUITest","usePrebuiltWDA":true,"wdaLaunchTimeout":300000,"wdaConnectionTimeout":1000000,"commandTimeouts":"1000000","wdaStartupRetries":3,"simpleIsVisibleCheck":true,"wdaLocalPort":4712,"xcodeSigningId":"iPhone Developer","xcodeOrgId":"3THJCX5EUA"}
2017-12-14 17:21:02:308 - [HTTP] <-- POST /wd/hub/session 200 26459 ms - 894 
2017-12-14 17:21:02:314 - [HTTP] --> GET /wd/hub/status {}
2017-12-14 17:21:02:315 - [debug] [MJSONWP] Calling AppiumDriver.getStatus() with args: []
2017-12-14 17:21:02:327 - [debug] [MJSONWP] Responding to client with driver.getStatus() result: {"build":{"version":"1.7.2-beta2","revision":null}}
2017-12-14 17:21:02:329 - [HTTP] <-- GET /wd/hub/status 200 14 ms - 89 
2017-12-14 17:21:02:332 - [HTTP] --> POST /wd/hub/session/8cbc530d-00e3-4962-8c60-9549e25f7c31/timeouts/implicit_wait {"ms":0}
2017-12-14 17:21:02:334 - [debug] [MJSONWP] Calling AppiumDriver.implicitWait() with args: [0,"8cbc530d-00e3-4962-8c60-9549e25f7c31"]
2017-12-14 17:21:02:335 - [debug] [XCUITest] Executing command 'implicitWait'
2017-12-14 17:21:02:342 - [debug] [BaseDriver] Set implicit wait to 0ms
2017-12-14 17:21:02:345 - [debug] [MJSONWP] Responding to client with driver.implicitWait() result: null
2017-12-14 17:21:02:347 - [HTTP] <-- POST /wd/hub/session/8cbc530d-00e3-4962-8c60-9549e25f7c31/timeouts/implicit_wait 200 14 ms - 76 
2017-12-14 17:21:32:296 - [debug] [XCUITest] Connection to WDA timed out
2017-12-14 17:21:32:297 - [debug] [iProxy] recv failed: Operation not permitted
2017-12-14 17:21:59:919 - [HTTP] --> POST /wd/hub/session/8cbc530d-00e3-4962-8c60-9549e25f7c31/elements {"using":"-ios predicate string","value":"type!=nil"}
2017-12-14 17:21:59:920 - [debug] [MJSONWP] Calling AppiumDriver.findElements() with args: ["-ios predicate string","type!=nil","8cbc530d-00e3-4962-8c60-9549e25f7c31"]
2017-12-14 17:21:59:922 - [debug] [XCUITest] Executing command 'findElements'
2017-12-14 17:21:59:928 - [debug] [BaseDriver] Valid locator strategies for this request: xpath, id, name, class name, -ios predicate string, -ios class chain, accessibility id
2017-12-14 17:21:59:930 - [debug] [BaseDriver] Waiting up to 0 ms for condition
2017-12-14 17:21:59:934 - [debug] [XCUITest] Setting custom timeout to 1000000 ms for "findElements" command
2017-12-14 17:21:59:935 - [debug] [JSONWP Proxy] Proxying [POST /elements] to [POST http://localhost:4712/session/3177E211-1D5E-4190-B688-84F767681246/elements] with body: {"using":"predicate string","value":"type!=nil"}
2017-12-14 17:22:00:363 - [debug] [JSONWP Proxy] Got response with status 200: {"value":[{"ELEMENT":"2A74FD4C-12C4-4430-9132-2E09B88CA726"}],"sessionId":"3177E211-1D5E-4190-B688-84F767681246","status":0}
2017-12-14 17:22:00:365 - [debug] [MJSONWP] Responding to client with driver.findElements() result: [{"ELEMENT":"2A74FD4C-12C4-4430-9132-2E09B88CA726"}]
2017-12-14 17:22:00:369 - [HTTP] <-- POST /wd/hub/session/8cbc530d-00e3-4962-8c60-9549e25f7c31/elements 200 448 ms - 124 
2017-12-14 17:22:30:364 - [debug] [XCUITest] Connection to WDA timed out
2017-12-14 17:22:30:364 - [debug] [iProxy] recv failed: Operation not permitted
2017-12-14 17:22:36:370 - [HTTP] --> GET /wd/hub/session/8cbc530d-00e3-4962-8c60-9549e25f7c31/alert_text {}
2017-12-14 17:22:36:373 - [debug] [MJSONWP] Calling AppiumDriver.getAlertText() with args: ["8cbc530d-00e3-4962-8c60-9549e25f7c31"]
2017-12-14 17:22:36:374 - [debug] [XCUITest] Executing command 'getAlertText'
2017-12-14 17:22:36:385 - [debug] [XCUITest] Setting custom timeout to 1000000 ms for "getAlertText" command
2017-12-14 17:22:36:386 - [debug] [JSONWP Proxy] Proxying [GET /alert/text] to [GET http://localhost:4712/session/3177E211-1D5E-4190-B688-84F767681246/alert/text] with no body
2017-12-14 17:22:41:863 - [debug] [JSONWP Proxy] Got response with status 200: "{\n  \"value\" : \"Sign-In Required\\nIf you have an Apple ID and password, enter them here. If you've used the iTunes Store or iCloud, for example, you have an Apple ID.\",\n  \"sessionId\" : \"3177E211-1D5E-4190-B688-84F767681246\",\n  \"status\" : 0\n}"
2017-12-14 17:22:41:864 - [debug] [MJSONWP] Responding to client with driver.getAlertText() result: "Sign-In Required\nIf you have an Apple ID and password, enter them here. If you've used the iTunes Store or iCloud, for example, you have an Apple ID."
2017-12-14 17:22:41:866 - [HTTP] <-- GET /wd/hub/session/8cbc530d-00e3-4962-8c60-9549e25f7c31/alert_text 200 5495 ms - 224 
2017-12-14 17:22:41:868 - [HTTP] --> POST /wd/hub/session/8cbc530d-00e3-4962-8c60-9549e25f7c31/alert_text {"text":"abc"}
2017-12-14 17:22:41:868 - [debug] [MJSONWP] Calling AppiumDriver.setAlertText() with args: ["abc","8cbc530d-00e3-4962-8c60-9549e25f7c31"]
2017-12-14 17:22:41:869 - [debug] [XCUITest] Executing command 'setAlertText'
2017-12-14 17:22:41:873 - [debug] [XCUITest] Setting custom timeout to 1000000 ms for "setAlertText" command
2017-12-14 17:22:41:874 - [debug] [JSONWP Proxy] Proxying [POST /alert/text] to [POST http://localhost:4712/session/3177E211-1D5E-4190-B688-84F767681246/alert/text] with body: ["a","b","c"]
2017-12-14 17:22:41:879 - [debug] [JSONWP Proxy] Got response with status 200: {"value":"Unhandled endpoint: /session/3177E211-1D5E-4190-B688-84F767681246/alert/text -- http://localhost:4712/ with parameters {\n    wildcards =     (\n        \"session/3177E211-1D5E-4190-B688-84F767681246/alert/text\"\n    );\n}","sessionId":"3177E211-1D5E-4190-B688-84F767681246","status":1}
2017-12-14 17:22:41:881 - [debug] [BaseDriver] Waiting up to 0 ms for condition
2017-12-14 17:22:41:884 - [debug] [XCUITest] Setting custom timeout to 1000000 ms for "findElement" command
2017-12-14 17:22:41:884 - [debug] [JSONWP Proxy] Proxying [POST /element] to [POST http://localhost:4712/session/3177E211-1D5E-4190-B688-84F767681246/element] with body: {"using":"class name","value":"XCUIElementTypeAlert"}
2017-12-14 17:22:41:931 - [debug] [JSONWP Proxy] Got response with status 200: {"value":{"using":"class name","value":"XCUIElementTypeAlert","description":"unable to find an element"},"sessionId":"3177E211-1D5E-4190-B688-84F767681246","status":7}
2017-12-14 17:22:41:936 - [debug] [XCUITest] Error finding alert element: An element could not be located on the page using the given search parameters.
2017-12-14 17:22:41:942 - [HTTP] <-- POST /wd/hub/session/8cbc530d-00e3-4962-8c60-9549e25f7c31/alert_text 500 74 ms - 158 
2017-12-14 17:23:04:481 - [HTTP] --> GET /wd/hub/session/8cbc530d-00e3-4962-8c60-9549e25f7c31/alert_text {}
2017-12-14 17:23:04:482 - [debug] [MJSONWP] Calling AppiumDriver.getAlertText() with args: ["8cbc530d-00e3-4962-8c60-9549e25f7c31"]
2017-12-14 17:23:04:483 - [debug] [XCUITest] Executing command 'getAlertText'
2017-12-14 17:23:04:488 - [debug] [XCUITest] Setting custom timeout to 1000000 ms for "getAlertText" command
2017-12-14 17:23:04:488 - [debug] [JSONWP Proxy] Proxying [GET /alert/text] to [GET http://localhost:4712/session/3177E211-1D5E-4190-B688-84F767681246/alert/text] with no body
2017-12-14 17:23:09:944 - [debug] [JSONWP Proxy] Got response with status 200: "{\n  \"value\" : \"Sign-In Required\\nIf you have an Apple ID and password, enter them here. If you've used the iTunes Store or iCloud, for example, you have an Apple ID.\",\n  \"sessionId\" : \"3177E211-1D5E-4190-B688-84F767681246\",\n  \"status\" : 0\n}"
2017-12-14 17:23:09:955 - [debug] [MJSONWP] Responding to client with driver.getAlertText() result: "Sign-In Required\nIf you have an Apple ID and password, enter them here. If you've used the iTunes Store or iCloud, for example, you have an Apple ID."
2017-12-14 17:23:09:958 - [HTTP] <-- GET /wd/hub/session/8cbc530d-00e3-4962-8c60-9549e25f7c31/alert_text 200 5475 ms - 224 
2017-12-14 17:23:25:918 - [HTTP] --> GET /wd/hub/session/8cbc530d-00e3-4962-8c60-9549e25f7c31/alert_text {}
2017-12-14 17:23:25:918 - [debug] [MJSONWP] Calling AppiumDriver.getAlertText() with args: ["8cbc530d-00e3-4962-8c60-9549e25f7c31"]
2017-12-14 17:23:25:918 - [debug] [XCUITest] Executing command 'getAlertText'
2017-12-14 17:23:25:922 - [debug] [XCUITest] Setting custom timeout to 1000000 ms for "getAlertText" command
2017-12-14 17:23:25:922 - [debug] [JSONWP Proxy] Proxying [GET /alert/text] to [GET http://localhost:4712/session/3177E211-1D5E-4190-B688-84F767681246/alert/text] with no body
2017-12-14 17:23:31:357 - [debug] [JSONWP Proxy] Got response with status 200: "{\n  \"value\" : \"Sign-In Required\\nIf you have an Apple ID and password, enter them here. If you've used the iTunes Store or iCloud, for example, you have an Apple ID.\",\n  \"sessionId\" : \"3177E211-1D5E-4190-B688-84F767681246\",\n  \"status\" : 0\n}"
2017-12-14 17:23:31:359 - [debug] [MJSONWP] Responding to client with driver.getAlertText() result: "Sign-In Required\nIf you have an Apple ID and password, enter them here. If you've used the iTunes Store or iCloud, for example, you have an Apple ID."
2017-12-14 17:23:31:360 - [HTTP] <-- GET /wd/hub/session/8cbc530d-00e3-4962-8c60-9549e25f7c31/alert_text 200 5442 ms - 224 
2017-12-14 17:23:31:363 - [HTTP] --> POST /wd/hub/session/8cbc530d-00e3-4962-8c60-9549e25f7c31/alert_text {"text":"abc"}
2017-12-14 17:23:31:364 - [debug] [MJSONWP] Calling AppiumDriver.setAlertText() with args: ["abc","8cbc530d-00e3-4962-8c60-9549e25f7c31"]
2017-12-14 17:23:31:364 - [debug] [XCUITest] Executing command 'setAlertText'
2017-12-14 17:23:31:371 - [debug] [XCUITest] Setting custom timeout to 1000000 ms for "setAlertText" command
2017-12-14 17:23:31:371 - [debug] [JSONWP Proxy] Proxying [POST /alert/text] to [POST http://localhost:4712/session/3177E211-1D5E-4190-B688-84F767681246/alert/text] with body: ["a","b","c"]
2017-12-14 17:23:31:375 - [debug] [JSONWP Proxy] Got response with status 200: {"value":"Unhandled endpoint: /session/3177E211-1D5E-4190-B688-84F767681246/alert/text -- http://localhost:4712/ with parameters {\n    wildcards =     (\n        \"session/3177E211-1D5E-4190-B688-84F767681246/alert/text\"\n    );\n}","sessionId":"3177E211-1D5E-4190-B688-84F767681246","status":1}
2017-12-14 17:23:31:376 - [debug] [BaseDriver] Waiting up to 0 ms for condition
2017-12-14 17:23:31:377 - [debug] [XCUITest] Setting custom timeout to 1000000 ms for "findElement" command
2017-12-14 17:23:31:377 - [debug] [JSONWP Proxy] Proxying [POST /element] to [POST http://localhost:4712/session/3177E211-1D5E-4190-B688-84F767681246/element] with body: {"using":"class name","value":"XCUIElementTypeAlert"}
2017-12-14 17:23:31:431 - [debug] [JSONWP Proxy] Got response with status 200: {"value":{"using":"class name","value":"XCUIElementTypeAlert","description":"unable to find an element"},"sessionId":"3177E211-1D5E-4190-B688-84F767681246","status":7}
2017-12-14 17:23:31:433 - [debug] [XCUITest] Error finding alert element: An element could not be located on the page using the given search parameters.
2017-12-14 17:23:31:435 - [HTTP] <-- POST /wd/hub/session/8cbc530d-00e3-4962-8c60-9549e25f7c31/alert_text 500 72 ms - 158 
2017-12-14 17:23:36:777 - [HTTP] --> POST /wd/hub/session/8cbc530d-00e3-4962-8c60-9549e25f7c31/elements {"using":"-ios predicate string","value":"type!=nil"}
2017-12-14 17:23:36:778 - [debug] [MJSONWP] Calling AppiumDriver.findElements() with args: ["-ios predicate string","type!=nil","8cbc530d-00e3-4962-8c60-9549e25f7c31"]
2017-12-14 17:23:36:778 - [debug] [XCUITest] Executing command 'findElements'
2017-12-14 17:23:36:779 - [debug] [BaseDriver] Valid locator strategies for this request: xpath, id, name, class name, -ios predicate string, -ios class chain, accessibility id
2017-12-14 17:23:36:780 - [debug] [BaseDriver] Waiting up to 0 ms for condition
2017-12-14 17:23:36:781 - [debug] [XCUITest] Setting custom timeout to 1000000 ms for "findElements" command
2017-12-14 17:23:36:782 - [debug] [JSONWP Proxy] Proxying [POST /elements] to [POST http://localhost:4712/session/3177E211-1D5E-4190-B688-84F767681246/elements] with body: {"using":"predicate string","value":"type!=nil"}
2017-12-14 17:23:36:875 - [debug] [JSONWP Proxy] Got response with status 200: {"value":[{"ELEMENT":"A64F80EF-3CBA-4600-AF58-CCCAFC0F9481"}],"sessionId":"3177E211-1D5E-4190-B688-84F767681246","status":0}
2017-12-14 17:23:36:878 - [debug] [MJSONWP] Responding to client with driver.findElements() result: [{"ELEMENT":"A64F80EF-3CBA-4600-AF58-CCCAFC0F9481"}]
2017-12-14 17:23:36:879 - [HTTP] <-- POST /wd/hub/session/8cbc530d-00e3-4962-8c60-9549e25f7c31/elements 200 101 ms - 124 
2017-12-14 17:24:04:069 - [HTTP] --> POST /wd/hub/session/8cbc530d-00e3-4962-8c60-9549e25f7c31/elements {"using":"-ios predicate string","value":"type!=nil"}
2017-12-14 17:24:04:069 - [debug] [MJSONWP] Calling AppiumDriver.findElements() with args: ["-ios predicate string","type!=nil","8cbc530d-00e3-4962-8c60-9549e25f7c31"]
2017-12-14 17:24:04:069 - [debug] [XCUITest] Executing command 'findElements'
2017-12-14 17:24:04:071 - [debug] [BaseDriver] Valid locator strategies for this request: xpath, id, name, class name, -ios predicate string, -ios class chain, accessibility id
2017-12-14 17:24:04:071 - [debug] [BaseDriver] Waiting up to 0 ms for condition
2017-12-14 17:24:04:072 - [debug] [XCUITest] Setting custom timeout to 1000000 ms for "findElements" command
2017-12-14 17:24:04:072 - [debug] [JSONWP Proxy] Proxying [POST /elements] to [POST http://localhost:4712/session/3177E211-1D5E-4190-B688-84F767681246/elements] with body: {"using":"predicate string","value":"type!=nil"}
2017-12-14 17:24:04:169 - [debug] [JSONWP Proxy] Got response with status 200: {"value":[{"ELEMENT":"816A8232-7F6D-4D67-808E-2A2AE45B0B41"}],"sessionId":"3177E211-1D5E-4190-B688-84F767681246","status":0}
2017-12-14 17:24:04:170 - [debug] [MJSONWP] Responding to client with driver.findElements() result: [{"ELEMENT":"816A8232-7F6D-4D67-808E-2A2AE45B0B41"}]
2017-12-14 17:24:04:172 - [HTTP] <-- POST /wd/hub/session/8cbc530d-00e3-4962-8c60-9549e25f7c31/elements 200 102 ms - 124 
2017-12-14 17:24:04:176 - [HTTP] --> GET /wd/hub/session/8cbc530d-00e3-4962-8c60-9549e25f7c31/element/816A8232-7F6D-4D67-808E-2A2AE45B0B41/attribute/type {}
2017-12-14 17:24:04:177 - [debug] [MJSONWP] Calling AppiumDriver.getAttribute() with args: ["type","816A8232-7F6D-4D67-808E-2A2AE45B0B41","8cbc530d-00e3-4962-8c60-9549e25f7c31"]
2017-12-14 17:24:04:178 - [debug] [XCUITest] Executing command 'getAttribute'
2017-12-14 17:24:04:184 - [debug] [XCUITest] Setting custom timeout to 1000000 ms for "getAttribute" command
2017-12-14 17:24:04:185 - [debug] [JSONWP Proxy] Proxying [GET /element/816A8232-7F6D-4D67-808E-2A2AE45B0B41/attribute/type] to [GET http://localhost:4712/session/3177E211-1D5E-4190-B688-84F767681246/element/816A8232-7F6D-4D67-808E-2A2AE45B0B41/attribute/type] with no body
2017-12-14 17:24:04:194 - [debug] [JSONWP Proxy] Got response with status 200: "{\n  \"value\" : \"XCUIElementTypeAny\",\n  \"sessionId\" : \"3177E211-1D5E-4190-B688-84F767681246\",\n  \"status\" : 0\n}"
2017-12-14 17:24:04:195 - [debug] [MJSONWP] Responding to client with driver.getAttribute() result: "XCUIElementTypeAny"
2017-12-14 17:24:04:196 - [HTTP] <-- GET /wd/hub/session/8cbc530d-00e3-4962-8c60-9549e25f7c31/element/816A8232-7F6D-4D67-808E-2A2AE45B0B41/attribute/type 200 20 ms - 92