[XCUITest] Failed to create WDA session. Retrying...

gistfile1.txt

Appium] Requested iOS support with version >= 10, using 'xcuitest' driver instead of UIAutomation-based driver, since the latter is unsupported on iOS 10 and up.

3153
    [Appium] Creating new XCUITestDriver (v2.76.2) session

3154
    [Appium] Capabilities:

3155
    [Appium]   deviceName: iPhone 8

3156
    [Appium]   wdaLocalPort: 8739

3157
    [Appium]   launchTimeout: 20000

3158
    [Appium]   app: /tmp/sandbox/5ae8d3cc9eafaa00019c2c7b/build.xcarchive/Products/Applications/app com.app

3159
    [Appium]   useNewWDA: true

3160
    [Appium]   newCommandTimeout: 120000

3161
    [Appium]   platformVersion: 11.3

3162
    [Appium]   platformName: iOS

3163
    [debug] [BaseDriver] Creating session with MJSONWP desired capabilities: {"deviceName":"iPhone 8","w...

3164
    [BaseDriver] Capability 'useNewWDA' changed from string to boolean. This may cause unexpected behavior

3165
    [BaseDriver] Session created with session id: 83c60780-3bef-4a6d-b733-efa77c5e01b2

3166
    [debug] [XCUITest] Current user: 'buddybuild'

3167
    [debug] [XCUITest] Xcode version set to '9.3' (tools v9.2.0.0.1.1510905681)

3168
    [debug] [XCUITest] iOS SDK Version set to '11.3'

3169
    [debug] [BaseDriver] Event 'xcodeDetailsRetrieved' logged at 1525208761464 (14:06:01 GMT-0700 (PDT))

3170
    [iOSSim] Constructing iOS simulator for Xcode version 9.3 with udid 'D4EB73D1-D4EF-469E-A002-BE440D525202'

3171
    [XCUITest] Determining device to run tests on: udid: 'D4EB73D1-D4EF-469E-A002-BE440D525202', real device: false

3172
    [BaseDriver] Using local app '/tmp/sandbox/5ae8d3cc9eafaa00019c2c7b/build.xcarchive/Products/Applications/app com.app'

3173
    [debug] [BaseDriver] Event 'appConfigured' logged at 1525208762050 (14:06:02 GMT-0700 (PDT))

3174
    [debug] [XCUITest] Checking whether app '/tmp/sandbox/5ae8d3cc9eafaa00019c2c7b/build.xcarchive/Products/Applications/app com.app' is actually present on file system

3175
    [debug] [XCUITest] App is present

3176
    [debug] [iOS] Getting bundle ID from app '/tmp/sandbox/5ae8d3cc9eafaa00019c2c7b/build.xcarchive/Products/Applications/app com.app': 'com.goapp.com'

3177
    [debug] [BaseDriver] Event 'resetStarted' logged at 1525208762071 (14:06:02 GMT-0700 (PDT))

3178
    [XCUITest] Not scrubbing third party app in anticipation of uninstall

3179
    [debug] [BaseDriver] Event 'resetComplete' logged at 1525208762340 (14:06:02 GMT-0700 (PDT))

3180
    [XCUITest] Continuing without capturing logs: iOS Simulator with udid D4EB73D1-D4EF-469E-A002-BE440D525202 is not running

3181
    [XCUITest] Setting up simulator

3182
    [debug] [iOS] No reason to set locale

3183
    [debug] [iOS] No iOS / app preferences to set

3184
    [debug] [iOSSim] No Simulator cache items for cleanup were matched in '/Users/buddybuild/Library/Developer/CoreSimulator/Devices/D4EB73D1-D4EF-469E-A002-BE440D525202/data/Library/Caches'

3185
    [debug] [iOSSim] Setting common Simulator preferences to {"ConnectHardwareKeyboard":false}

3186
    [iOSSim] Simulator preferences file '/Users/buddybuild/Library/Preferences/com.apple.iphonesimulator.plist' is not accessible. Ignoring Simulator preferences update.

3187
    [debug] [iOSSim] The count of running Simulator UI client instances is 0

3188
    [iOSSim] Booting Simulator with UDID D4EB73D1-D4EF-469E-A002-BE440D525202...

3189
    [iOSSim] Starting Simulator UI with command: open -Fn /Applications/Xcode-9.3.app/Contents/Developer/Applications/Simulator.app --args -CurrentDeviceUDID D4EB73D1-D4EF-469E-A002-BE440D525202 -ConnectHardwareKeyboard 0

3190
    [iOSSim] Simulator with UDID D4EB73D1-D4EF-469E-A002-BE440D525202 booted in 26 seconds

3191
    [debug] [BaseDriver] Event 'simStarted' logged at 1525208789077 (14:06:29 GMT-0700 (PDT))

3192
    [debug] [XCUITest] Starting log capture for iOS Simulator with udid 'D4EB73D1-D4EF-469E-A002-BE440D525202', using 'xcrun simctl spawn D4EB73D1-D4EF-469E-A002-BE440D525202 log stream --style compact'

3193
    [debug] [XCUITest] Crash reports root '/Users/buddybuild/Library/Logs/DiagnosticReports' does not exist. Got nothing to gather.

3194
    [debug] [BaseDriver] Event 'logCaptureStarted' logged at 1525208790189 (14:06:30 GMT-0700 (PDT))

3195
    [debug] [XCUITest] Installing '/tmp/sandbox/5ae8d3cc9eafaa00019c2c7b/build.xcarchive/Products/Applications/app com.app' on Simulator with UUID 'D4EB73D1-D4EF-469E-A002-BE440D525202'...

3196
    [debug] [XCUITest] The app has been installed successfully.

3197
    [debug] [BaseDriver] Event 'appInstalled' logged at 1525208799683 (14:06:39 GMT-0700 (PDT))

3198
    [XCUITest] Using WDA path: '/usr/local/lib/node_modules/appium/node_modules/appium-xcuitest-driver/WebDriverAgent'

3199
    [XCUITest] Using WDA agent: '/usr/local/lib/node_modules/appium/node_modules/appium-xcuitest-driver/WebDriverAgent/WebDriverAgent.xcodeproj'

3200
    [debug] [XCUITest] No obsolete cached processes from previous WDA sessions listening on port 8739 have been found

3201
    [debug] [XCUITest] Capability 'useNewWDA' set to true, so uninstalling WDA before proceeding

3202
    [XCUITest] Shutting down sub-processes

3203
    [debug] [XCUITest] Removing WDA application from device

3204
    [debug] [BaseDriver] Event 'wdaUninstalled' logged at 1525208801263 (14:06:41 GMT-0700 (PDT))

3205
    [debug] [BaseDriver] Event 'wdaStartAttempted' logged at 1525208801265 (14:06:41 GMT-0700 (PDT))

3206
    [XCUITest] Launching WebDriverAgent on the device

3207
    [debug] [XCUITest] Carthage found: '/usr/local/bin/carthage'

3208
    [debug] [XCUITest] Running WebDriverAgent bootstrap script to install dependencies

3209
    [debug] [XCUITest] Creating WebDriverAgent resources directory

3210
    [debug] [XCUITest] Creating WebDriverAgent resource bundle directory

3211
    [debug] [XCUITest] Killing running processes 'xcodebuild.*D4EB73D1-D4EF-469E-A002-BE440D525202, iproxy 8739, D4EB73D1-D4EF-469E-A002-BE440D525202.*XCTRunner' for the device D4EB73D1-D4EF-469E-A002-BE440D525202...

3212
    [debug] [XCUITest] 'pgrep -nif xcodebuild.*D4EB73D1-D4EF-469E-A002-BE440D525202' didn't detect any matching processes. Return code: 1

3213
    [debug] [XCUITest] 'pgrep -nif iproxy 8739' didn't detect any matching processes. Return code: 1

3214
    [debug] [XCUITest] 'pgrep -nif D4EB73D1-D4EF-469E-A002-BE440D525202.*XCTRunner' didn't detect any matching processes. Return code: 1

3215
    [debug] [XCUITest] Using Xcode 9.3, so fixing WDA codebase

3216
    [debug] [XCUITest] Beginning test with command 'xcodebuild build-for-testing test-without-building -project /usr/local/lib/node_modules/appium/node_modules/appium-xcuitest-driver/WebDriverAgent/WebDriverAgent.xcodeproj -scheme WebDriverAgentRunner -destination id=D4EB73D1-D4EF-469E-A002-BE440D525202 IPHONEOS_DEPLOYMENT_TARGET=11.3' in directory '/usr/local/lib/node_modules/appium/node_modules/appium-xcuitest-driver/WebDriverAgent'

3217
    [debug] [XCUITest] Output from xcodebuild will not be logged. To change this, use 'showXcodeLog' desired capability

3218
    [debug] [XCUITest] Waiting up to 60000ms for WebDriverAgent to start

3219
    [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8739/status] with no body

3220
    [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8739/status] with no body

3221
    [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8739/status] with no body

3222
    [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8739/status] with no body

3223
    [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8739/status] with no body

3224
    [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8739/status] with no body

3225
    [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8739/status] with no body

3226
    [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8739/status] with no body

3227
    [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8739/status] with no body

3228
    [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8739/status] with no body

3229
    [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8739/status] with no body

3230
    [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8739/status] with no body

3231
    [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8739/status] with no body

3232
    [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8739/status] with no body

3233
    [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8739/status] with no body

3234
    [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8739/status] with no body

3235
    [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8739/status] with no body

3236
    [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8739/status] with no body

3237
    [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8739/status] with no body

3238
    [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8739/status] with no body

3239
    [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8739/status] with no body

3240
    [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8739/status] with no body

3241
    [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8739/status] with no body

3242
    [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8739/status] with no body

3243
    [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8739/status] with no body

3244
    [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8739/status] with no body

3245
    [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8739/status] with no body

3246
    [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8739/status] with no body

3247
    [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8739/status] with no body

3248
    [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8739/status] with no body

3249
    [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8739/status] with no body

3250
    [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8739/status] with no body

3251
    [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8739/status] with no body

3252
    [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8739/status] with no body

3253
    [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8739/status] with no body

3254
    [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8739/status] with no body

3255
    [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8739/status] with no body

3256
    [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8739/status] with no body

3257
    [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8739/status] with no body

3258
    [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8739/status] with no body

3259
    [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8739/status] with no body

3260
    [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8739/status] with no body

3261
    [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8739/status] with no body

3262
    [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8739/status] with no body

3263
    [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8739/status] with no body

3264
    [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8739/status] with no body

3265
    [debug] [XCUITest] Log file for xcodebuild test: /var/folders/zs/8kd7pzhj58g2l19xw514kc_c0000gn/T/com.apple.dt.XCTest/IDETestRunSession-647EBDF3-4219-4C42-9CD1-C352DA2ED6FD/WebDriverAgentRunner-495A4E32-05CC-4635-9332-870BA2028D48/Session-WebDriverAgentRunner-2018-05-01_140857-u3JHcl.log

3266
    [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8739/status] with no body

3267
    [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8739/status] with no body

3268
    [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8739/status] with no body

3269
    [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8739/status] with no body

3270
    [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8739/status] with no body

3271
    [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8739/status] with no body

3272
    [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8739/status] with no body

3273
    [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8739/status] with no body

3274
    [debug] [JSONWP Proxy] Got response with status 200: "{\n  \"value\" : {\n    \"state\" : \"success\",\n    \"os\" : {\n      \"name\" : \"iOS\",\n      \"version\" : \"11.3\",\n      \"sdkVersion\" : \"11.3\"\n    },\n    \"ios\" : {\n      \"simulatorVersion\" : \"11.3\",\n      \"ip\" : \"10.100.6.120\"\n    },\n    \"build\" : {\n      \"time\" : \"May  1 2018 14:08:43\"\n    }\n  },\n  \"sessionId\" : \"F9F1570D-E110-4BF4-827C-DB88AF1102F6\",\n  \"status\" : 0\n}"

3275
    [debug] [XCUITest] WebDriverAgent running on ip '10.100.6.120'

3276
    [debug] [XCUITest] WebDriverAgent successfully started after 27928ms

3277
    [debug] [BaseDriver] Event 'wdaSessionAttempted' logged at 1525208941512 (14:09:01 GMT-0700 (PDT))

3278
    [debug] [XCUITest] Sending createSession command to WDA

3279
    [debug] [JSONWP Proxy] Proxying [POST /session] to [POST http://localhost:8739/session] with body: {"desiredCapabilities":{"bundleId":"com.goapp.com","arguments":[],"environment":{},"shouldWaitForQuiescence":true,"shouldUseTestManagerForVisibilityDetection":false,"maxTypingFrequency":60,"shouldUseSingletonTestManager":true}}

3280
    [debug] [JSONWP Proxy] Got response with status 200: {"value":"Failed to launch com.goapp.com application","sessionId":"F9F1570D-E110-4BF4-827C-DB88AF1102F6","status":13}

3281
    [debug] [XCUITest] Failed to create WDA session. Retrying...

3282
    [debug] [BaseDriver] Event 'wdaSessionAttempted' logged at 1525209126369 (14:12:06 GMT-0700 (PDT))

3283
    [debug] [XCUITest] Sending createSession command to WDA

3284
    [debug] [JSONWP Proxy] Proxying [POST /session] to [POST http://localhost:8739/session] with body: {"desiredCapabilities":{"bundleId":"com.goapp.com","arguments":[],"environment":{},"shouldWaitForQuiescence":true,"shouldUseTestManagerForVisibilityDetection":false,"maxTypingFrequency":60,"shouldUseSingletonTestManager":true}}

3285
    [debug] [JSONWP Proxy] Got response with status 200: {"value":"Failed to launch com.goapp.com application","sessionId":"F9F1570D-E110-4BF4-827C-DB88AF1102F6","status":13}

3286
    [debug] [XCUITest] Failed to create WDA session. Retrying...

3287
    [debug] [BaseDriver] Event 'wdaSessionAttempted' logged at 1525209310638 (14:15:10 GMT-0700 (PDT))

3288
    [debug] [XCUITest] Sending createSession command to WDA

3289
    [debug] [JSONWP Proxy] Proxying [POST /session] to [POST http://localhost:8739/session] with body: {"desiredCapabilities":{"bundleId":"com.goapp.com","arguments":[],"environment":{},"shouldWaitForQuiescence":true,"shouldUseTestManagerForVisibilityDetection":false,"maxTypingFrequency":60,"shouldUseSingletonTestManager":true}}

3290
    [HTTP] <-- POST /wd/hub/session - - ms - -

3291
    [HTTP]