Appium issue 13453

appium-13453.log

2019-11-22 19:05:40:476 - [Appium] Welcome to Appium v1.16.0-beta.2
2019-11-22 19:05:40:478 - [Appium] Non-default server args:
2019-11-22 19:05:40:479 - [Appium]   port: 4828
2019-11-22 19:05:40:479 - [Appium]   callbackPort: 4829
2019-11-22 19:05:40:479 - [Appium]   sessionOverride: true
2019-11-22 19:05:40:479 - [Appium]   logTimestamp: true
2019-11-22 19:05:40:479 - [Appium]   logNoColors: true
2019-11-22 19:05:40:479 - [Appium]   nodeconfig: /Users/bdouglas/build_tools/ios_phone/7090eac95fe26e87ec013646c75a289c235003c7/selenium-conf.json
2019-11-22 19:05:40:479 - [Appium]   debugLogSpacing: true
2019-11-22 19:05:40:480 - [Appium]   defaultCapabilities: {
2019-11-22 19:05:40:480 - [Appium]     deviceName: 7090eac95fe26e87ec013646c75a289c235003c7
2019-11-22 19:05:40:480 - [Appium]     udid: 7090eac95fe26e87ec013646c75a289c235003c7
2019-11-22 19:05:40:480 - [Appium]     systemPort: 7828
2019-11-22 19:05:40:480 - [Appium]     wdaLocalPort: 7828
2019-11-22 19:05:40:480 - [Appium]     webkitDebugProxyPort: 9828
2019-11-22 19:05:40:480 - [Appium]     startIWDP: true
2019-11-22 19:05:40:480 - [Appium]   }
2019-11-22 19:05:40:481 - [Appium] Default capabilities, which will be added to each request unless overridden by desired capabilities:
2019-11-22 19:05:40:481 - [Appium]   deviceName: 7090eac95fe26e87ec013646c75a289c235003c7
2019-11-22 19:05:40:481 - [Appium]   udid: 7090eac95fe26e87ec013646c75a289c235003c7
2019-11-22 19:05:40:481 - [Appium]   systemPort: 7828
2019-11-22 19:05:40:481 - [Appium]   wdaLocalPort: 7828
2019-11-22 19:05:40:481 - [Appium]   webkitDebugProxyPort: 9828
2019-11-22 19:05:40:482 - [Appium]   startIWDP: true
2019-11-22 19:05:40:506 - [debug] [Appium] Starting auto register thread for grid. Will try to register every 5000 ms.
2019-11-22 19:05:40:507 - [Appium] Appium REST http interface listener started on 0.0.0.0:4828
2019-11-22 19:05:45:530 - [debug] [Appium] Appium successfully registered with the grid on http://10.96.10.141:4444
2019-11-22 19:05:50:537 - [HTTP] --> GET /wd/hub/status
2019-11-22 19:05:50:537 - [HTTP] {}
2019-11-22 19:05:50:538 - [debug] [GENERIC] Calling AppiumDriver.getStatus() with args: []
2019-11-22 19:05:50:539 - [debug] [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.16.0-beta.2"}}
2019-11-22 19:05:50:542 - [HTTP] <-- GET /wd/hub/status 200 5 ms - 75

#####################
###### SNIP
#####################

2019-11-22 19:58:39:611 - [HTTP] 
2019-11-22 19:58:43:416 - [HTTP] --> POST /wd/hub/session
2019-11-22 19:58:43:416 - [HTTP] {"desiredCapabilities":{"server:CONFIG_UUID":"e9e2d5d7-1ca4-4a78-a69a-1f3f6d28f4f4","preventWDAAttachments":true,"simpleIsVisibleCheck":true,"clearSystemFiles":true,"noReset":false,"xcodeOrgId":"24X86T8MC6","bundleId":"com.apple.Preferences","mycompany:testng.suite":"Default Suite","deviceName":"*","updatedWDABundleId":"com.mycompany.qa.WebDriverAgentRunner","xcodeSigningId":"iPhone Developer","mycompany:node.defaultDevice":false,"sendKeyStrategy":"grouped","mycompany:node.platformType":"Phone","mycompany:testng.session.hash":"99d5a67a-1a96-4292-add8-9a16afc810b4","newCommandTimeout":100,"mycompany:testng.test":"testAttractionsSearchBoston","platformVersion":"13.2","automationName":"XCuiTest","useNewWDA":true,"browserName":"","mycompany:testng.hostname":"bedouglas-mac","platformName":"ios"},"capabilities":{"firstMatch":[{"browserName":"","platformName":"ios","server:CONFIG_UUID":"e9e2d5d7-1ca4-4a78-a69a-1f3f6d28f4f4","mycompany:node.defaultDevice":false,"mycompany:nod
2019-11-22 19:58:43:417 - [debug] [W3C] Calling AppiumDriver.createSession() with args: [{"server:CONFIG_UUID":"e9e2d5d7-1ca4-4a78-a69a-1f3f6d28f4f4","preventWDAAttachments":true,"simpleIsVisibleCheck":true,"clearSystemFiles":true,"noReset":false,"xcodeOrgId":"24X86T8MC6","bundleId":"com.apple.Preferences","mycompany:testng.suite":"Default Suite","deviceName":"*","updatedWDABundleId":"com.mycompany.qa.WebDriverAgentRunner","xcodeSigningId":"iPhone Developer","mycompany:node.defaultDevice":false,"sendKeyStrategy":"grouped","mycompany:node.platformType":"Phone","mycompany:testng.session.hash":"99d5a67a-1a96-4292-add8-9a16afc810b4","newCommandTimeout":100,"mycompany:testng.test":"testAttractionsSearchBoston","platformVersion":"13.2","automationName":"XCuiTest","useNewWDA":true,"browserName":"","mycompany:testng.hostname":"bedouglas-mac","platformName":"ios"},null,{"firstMatch":[{"browserName":"","platformName":"ios","server:CONFIG_UUID":"e9e2d5d7-1ca4-4a78-a69a-1f3f6d28f4f4","mycompany:node.defaultDevice":false,"mycompany:node.platformType":"Phone","mycompany:testng.hostname"...
2019-11-22 19:58:43:419 - [debug] [BaseDriver] Event 'newSessionRequested' logged at 1574452723418 (14:58:43 GMT-0500 (Eastern Standard Time))
2019-11-22 19:58:43:423 - [BaseDriver] The capabilities ["deviceName","udid","systemPort","wdaLocalPort","webkitDebugProxyPort","startIWDP"] are not standard capabilities and should have an extension prefix
2019-11-22 19:58:43:426 - [Appium] The following capabilities were provided in the JSONWP desired capabilities that are missing in W3C capabilities: ["preventWDAAttachments","simpleIsVisibleCheck","clearSystemFiles","noReset","xcodeOrgId","bundleId","updatedWDABundleId","xcodeSigningId","sendKeyStrategy","newCommandTimeout","platformVersion","automationName","useNewWDA"]
2019-11-22 19:58:43:427 - [Appium] Trying to fix W3C capabilities by merging them with JSONWP caps
2019-11-22 19:58:43:427 - [BaseDriver] The capabilities ["preventWDAAttachments","simpleIsVisibleCheck","clearSystemFiles","noReset","xcodeOrgId","bundleId","updatedWDABundleId","xcodeSigningId","sendKeyStrategy","newCommandTimeout","platformVersion","automationName","useNewWDA","deviceName","udid","systemPort","wdaLocalPort","webkitDebugProxyPort","startIWDP"] are not standard capabilities and should have an extension prefix
2019-11-22 19:58:43:949 - [Appium] Appium v1.16.0-beta.2 creating new XCUITestDriver (v3.2.0) session
2019-11-22 19:58:43:951 - [debug] [BaseDriver] W3C capabilities and MJSONWP desired capabilities were provided
2019-11-22 19:58:43:952 - [debug] [BaseDriver] Creating session with W3C capabilities: {
2019-11-22 19:58:43:952 - [debug] [BaseDriver]   "alwaysMatch": {
2019-11-22 19:58:43:952 - [debug] [BaseDriver]     "appium:preventWDAAttachments": true,
2019-11-22 19:58:43:952 - [debug] [BaseDriver]     "appium:simpleIsVisibleCheck": true,
2019-11-22 19:58:43:952 - [debug] [BaseDriver]     "appium:clearSystemFiles": true,
2019-11-22 19:58:43:952 - [debug] [BaseDriver]     "appium:noReset": false,
2019-11-22 19:58:43:952 - [debug] [BaseDriver]     "appium:xcodeOrgId": "24X86T8MC6",
2019-11-22 19:58:43:952 - [debug] [BaseDriver]     "appium:bundleId": "com.apple.Preferences",
2019-11-22 19:58:43:952 - [debug] [BaseDriver]     "appium:updatedWDABundleId": "com.mycompany.qa.WebDriverAgentRunner",
2019-11-22 19:58:43:952 - [debug] [BaseDriver]     "appium:xcodeSigningId": "iPhone Developer",
2019-11-22 19:58:43:953 - [debug] [BaseDriver]     "appium:sendKeyStrategy": "grouped",
2019-11-22 19:58:43:953 - [debug] [BaseDriver]     "appium:newCommandTimeout": 100,
2019-11-22 19:58:43:953 - [debug] [BaseDriver]     "appium:platformVersion": "13.2",
2019-11-22 19:58:43:953 - [debug] [BaseDriver]     "appium:automationName": "XCuiTest",
2019-11-22 19:58:43:953 - [debug] [BaseDriver]     "appium:useNewWDA": true,
2019-11-22 19:58:43:953 - [debug] [BaseDriver]     "browserName": "",
2019-11-22 19:58:43:953 - [debug] [BaseDriver]     "platformName": "ios",
2019-11-22 19:58:43:953 - [debug] [BaseDriver]     "server:CONFIG_UUID": "e9e2d5d7-1ca4-4a78-a69a-1f3f6d28f4f4",
2019-11-22 19:58:43:953 - [debug] [BaseDriver]     "mycompany:node.defaultDevice": false,
2019-11-22 19:58:43:953 - [debug] [BaseDriver]     "mycompany:node.platformType": "Phone",
2019-11-22 19:58:43:953 - [debug] [BaseDriver]     "mycompany:testng.hostname": "bedouglas-mac",
2019-11-22 19:58:43:953 - [debug] [BaseDriver]     "mycompany:testng.session.hash": "99d5a67a-1a96-4292-add8-9a16afc810b4",
2019-11-22 19:58:43:954 - [debug] [BaseDriver]     "mycompany:testng.suite": "Default Suite",
2019-11-22 19:58:43:954 - [debug] [BaseDriver]     "mycompany:testng.test": "testAttractionsSearchBoston",
2019-11-22 19:58:43:954 - [debug] [BaseDriver]     "appium:deviceName": "7090eac95fe26e87ec013646c75a289c235003c7",
2019-11-22 19:58:43:954 - [debug] [BaseDriver]     "appium:udid": "7090eac95fe26e87ec013646c75a289c235003c7",
2019-11-22 19:58:43:954 - [debug] [BaseDriver]     "appium:systemPort": 7828,
2019-11-22 19:58:43:954 - [debug] [BaseDriver]     "appium:wdaLocalPort": 7828,
2019-11-22 19:58:43:954 - [debug] [BaseDriver]     "appium:webkitDebugProxyPort": 9828,
2019-11-22 19:58:43:954 - [debug] [BaseDriver]     "appium:startIWDP": true
2019-11-22 19:58:43:954 - [debug] [BaseDriver]   },
2019-11-22 19:58:43:954 - [debug] [BaseDriver]   "firstMatch": [
2019-11-22 19:58:43:954 - [debug] [BaseDriver]     {}
2019-11-22 19:58:43:954 - [debug] [BaseDriver]   ]
2019-11-22 19:58:43:955 - [debug] [BaseDriver] }
2019-11-22 19:58:43:961 - [BaseDriver] The following capabilities were provided, but are not recognized by Appium:
2019-11-22 19:58:43:961 - [BaseDriver]   server:CONFIG_UUID
2019-11-22 19:58:43:961 - [BaseDriver]   mycompany:node.defaultDevice
2019-11-22 19:58:43:961 - [BaseDriver]   mycompany:node.platformType
2019-11-22 19:58:43:961 - [BaseDriver]   mycompany:testng.hostname
2019-11-22 19:58:43:961 - [BaseDriver]   mycompany:testng.session.hash
2019-11-22 19:58:43:961 - [BaseDriver]   mycompany:testng.suite
2019-11-22 19:58:43:961 - [BaseDriver]   mycompany:testng.test
2019-11-22 19:58:43:961 - [BaseDriver]   preventWDAAttachments
2019-11-22 19:58:43:962 - [BaseDriver]   systemPort
2019-11-22 19:58:43:963 - [BaseDriver] Session created with session id: a1a12684-68e8-4702-8dcd-d500fada7e09
2019-11-22 19:58:39:611 - [HTTP] 
2019-11-22 19:58:43:416 - [HTTP] --> POST /wd/hub/session
2019-11-22 19:58:43:416 - [HTTP] {"desiredCapabilities":{"server:CONFIG_UUID":"e9e2d5d7-1ca4-4a78-a69a-1f3f6d28f4f4","preventWDAAttachments":true,"simpleIsVisibleCheck":true,"clearSystemFiles":true,"noReset":false,"xcodeOrgId":"24X86T8MC6","bundleId":"com.apple.Preferences","mycompany:testng.suite":"Default Suite","deviceName":"*","updatedWDABundleId":"com.mycompany.qa.WebDriverAgentRunner","xcodeSigningId":"iPhone Developer","mycompany:node.defaultDevice":false,"sendKeyStrategy":"grouped","mycompany:node.platformType":"Phone","mycompany:testng.session.hash":"99d5a67a-1a96-4292-add8-9a16afc810b4","newCommandTimeout":100,"mycompany:testng.test":"testAttractionsSearchBoston","platformVersion":"13.2","automationName":"XCuiTest","useNewWDA":true,"browserName":"","mycompany:testng.hostname":"bedouglas-mac","platformName":"ios"},"capabilities":{"firstMatch":[{"browserName":"","platformName":"ios","server:CONFIG_UUID":"e9e2d5d7-1ca4-4a78-a69a-1f3f6d28f4f4","mycompany:node.defaultDevice":false,"mycompany:nod
2019-11-22 19:58:43:417 - [debug] [W3C] Calling AppiumDriver.createSession() with args: [{"server:CONFIG_UUID":"e9e2d5d7-1ca4-4a78-a69a-1f3f6d28f4f4","preventWDAAttachments":true,"simpleIsVisibleCheck":true,"clearSystemFiles":true,"noReset":false,"xcodeOrgId":"24X86T8MC6","bundleId":"com.apple.Preferences","mycompany:testng.suite":"Default Suite","deviceName":"*","updatedWDABundleId":"com.mycompany.qa.WebDriverAgentRunner","xcodeSigningId":"iPhone Developer","mycompany:node.defaultDevice":false,"sendKeyStrategy":"grouped","mycompany:node.platformType":"Phone","mycompany:testng.session.hash":"99d5a67a-1a96-4292-add8-9a16afc810b4","newCommandTimeout":100,"mycompany:testng.test":"testAttractionsSearchBoston","platformVersion":"13.2","automationName":"XCuiTest","useNewWDA":true,"browserName":"","mycompany:testng.hostname":"bedouglas-mac","platformName":"ios"},null,{"firstMatch":[{"browserName":"","platformName":"ios","server:CONFIG_UUID":"e9e2d5d7-1ca4-4a78-a69a-1f3f6d28f4f4","mycompany:node.defaultDevice":false,"mycompany:node.platformType":"Phone","mycompany:testng.hostname"...
2019-11-22 19:58:43:419 - [debug] [BaseDriver] Event 'newSessionRequested' logged at 1574452723418 (14:58:43 GMT-0500 (Eastern Standard Time))
2019-11-22 19:58:43:423 - [BaseDriver] The capabilities ["deviceName","udid","systemPort","wdaLocalPort","webkitDebugProxyPort","startIWDP"] are not standard capabilities and should have an extension prefix
2019-11-22 19:58:43:426 - [Appium] The following capabilities were provided in the JSONWP desired capabilities that are missing in W3C capabilities: ["preventWDAAttachments","simpleIsVisibleCheck","clearSystemFiles","noReset","xcodeOrgId","bundleId","updatedWDABundleId","xcodeSigningId","sendKeyStrategy","newCommandTimeout","platformVersion","automationName","useNewWDA"]
2019-11-22 19:58:43:427 - [Appium] Trying to fix W3C capabilities by merging them with JSONWP caps
2019-11-22 19:58:43:427 - [BaseDriver] The capabilities ["preventWDAAttachments","simpleIsVisibleCheck","clearSystemFiles","noReset","xcodeOrgId","bundleId","updatedWDABundleId","xcodeSigningId","sendKeyStrategy","newCommandTimeout","platformVersion","automationName","useNewWDA","deviceName","udid","systemPort","wdaLocalPort","webkitDebugProxyPort","startIWDP"] are not standard capabilities and should have an extension prefix
2019-11-22 19:58:43:949 - [Appium] Appium v1.16.0-beta.2 creating new XCUITestDriver (v3.2.0) session
2019-11-22 19:58:43:951 - [debug] [BaseDriver] W3C capabilities and MJSONWP desired capabilities were provided
2019-11-22 19:58:43:952 - [debug] [BaseDriver] Creating session with W3C capabilities: {
2019-11-22 19:58:43:952 - [debug] [BaseDriver]   "alwaysMatch": {
2019-11-22 19:58:43:952 - [debug] [BaseDriver]     "appium:preventWDAAttachments": true,
2019-11-22 19:58:43:952 - [debug] [BaseDriver]     "appium:simpleIsVisibleCheck": true,
2019-11-22 19:58:43:952 - [debug] [BaseDriver]     "appium:clearSystemFiles": true,
2019-11-22 19:58:43:952 - [debug] [BaseDriver]     "appium:noReset": false,
2019-11-22 19:58:43:952 - [debug] [BaseDriver]     "appium:xcodeOrgId": "24X86T8MC6",
2019-11-22 19:58:43:952 - [debug] [BaseDriver]     "appium:bundleId": "com.apple.Preferences",
2019-11-22 19:58:43:952 - [debug] [BaseDriver]     "appium:updatedWDABundleId": "com.mycompany.qa.WebDriverAgentRunner",
2019-11-22 19:58:43:952 - [debug] [BaseDriver]     "appium:xcodeSigningId": "iPhone Developer",
2019-11-22 19:58:43:953 - [debug] [BaseDriver]     "appium:sendKeyStrategy": "grouped",
2019-11-22 19:58:43:953 - [debug] [BaseDriver]     "appium:newCommandTimeout": 100,
2019-11-22 19:58:43:953 - [debug] [BaseDriver]     "appium:platformVersion": "13.2",
2019-11-22 19:58:43:953 - [debug] [BaseDriver]     "appium:automationName": "XCuiTest",
2019-11-22 19:58:43:953 - [debug] [BaseDriver]     "appium:useNewWDA": true,
2019-11-22 19:58:43:953 - [debug] [BaseDriver]     "browserName": "",
2019-11-22 19:58:43:953 - [debug] [BaseDriver]     "platformName": "ios",
2019-11-22 19:58:43:953 - [debug] [BaseDriver]     "server:CONFIG_UUID": "e9e2d5d7-1ca4-4a78-a69a-1f3f6d28f4f4",
2019-11-22 19:58:43:953 - [debug] [BaseDriver]     "mycompany:node.defaultDevice": false,
2019-11-22 19:58:43:953 - [debug] [BaseDriver]     "mycompany:node.platformType": "Phone",
2019-11-22 19:58:43:953 - [debug] [BaseDriver]     "mycompany:testng.hostname": "bedouglas-mac",
2019-11-22 19:58:43:953 - [debug] [BaseDriver]     "mycompany:testng.session.hash": "99d5a67a-1a96-4292-add8-9a16afc810b4",
2019-11-22 19:58:43:954 - [debug] [BaseDriver]     "mycompany:testng.suite": "Default Suite",
2019-11-22 19:58:43:954 - [debug] [BaseDriver]     "mycompany:testng.test": "testAttractionsSearchBoston",
2019-11-22 19:58:43:954 - [debug] [BaseDriver]     "appium:deviceName": "7090eac95fe26e87ec013646c75a289c235003c7",
2019-11-22 19:58:43:954 - [debug] [BaseDriver]     "appium:udid": "7090eac95fe26e87ec013646c75a289c235003c7",
2019-11-22 19:58:43:954 - [debug] [BaseDriver]     "appium:systemPort": 7828,
2019-11-22 19:58:43:954 - [debug] [BaseDriver]     "appium:wdaLocalPort": 7828,
2019-11-22 19:58:43:954 - [debug] [BaseDriver]     "appium:webkitDebugProxyPort": 9828,
2019-11-22 19:58:43:954 - [debug] [BaseDriver]     "appium:startIWDP": true
2019-11-22 19:58:43:954 - [debug] [BaseDriver]   },
2019-11-22 19:58:43:954 - [debug] [BaseDriver]   "firstMatch": [
2019-11-22 19:58:43:954 - [debug] [BaseDriver]     {}
2019-11-22 19:58:43:954 - [debug] [BaseDriver]   ]
2019-11-22 19:58:43:955 - [debug] [BaseDriver] }
2019-11-22 19:58:43:961 - [BaseDriver] The following capabilities were provided, but are not recognized by Appium:
2019-11-22 19:58:43:961 - [BaseDriver]   server:CONFIG_UUID
2019-11-22 19:58:43:961 - [BaseDriver]   mycompany:node.defaultDevice
2019-11-22 19:58:43:961 - [BaseDriver]   mycompany:node.platformType
2019-11-22 19:58:43:961 - [BaseDriver]   mycompany:testng.hostname
2019-11-22 19:58:43:961 - [BaseDriver]   mycompany:testng.session.hash
2019-11-22 19:58:43:961 - [BaseDriver]   mycompany:testng.suite
2019-11-22 19:58:43:961 - [BaseDriver]   mycompany:testng.test
2019-11-22 19:58:43:961 - [BaseDriver]   preventWDAAttachments
2019-11-22 19:58:43:962 - [BaseDriver]   systemPort
2019-11-22 19:58:43:963 - [BaseDriver] Session created with session id: a1a12684-68e8-4702-8dcd-d500fada7e09
2019-11-22 19:58:43:991 - [debug] [XCUITest] Current user: 'bdouglas'
2019-11-22 19:58:44:003 - [debug] [XCUITest] Available devices: 7090eac95fe26e87ec013646c75a289c235003c7
2019-11-22 19:58:44:004 - [debug] [XCUITest] Creating iDevice object with udid '7090eac95fe26e87ec013646c75a289c235003c7'
2019-11-22 19:58:44:004 - [XCUITest] Determining device to run tests on: udid: '7090eac95fe26e87ec013646c75a289c235003c7', real device: true
2019-11-22 19:58:44:064 - [debug] [BaseDriver] Event 'xcodeDetailsRetrieved' logged at 1574452724064 (14:58:44 GMT-0500 (Eastern Standard Time))
2019-11-22 19:58:44:065 - [debug] [BaseDriver] Event 'appConfigured' logged at 1574452724065 (14:58:44 GMT-0500 (Eastern Standard Time))
2019-11-22 19:58:44:065 - [debug] [BaseDriver] Event 'resetStarted' logged at 1574452724065 (14:58:44 GMT-0500 (Eastern Standard Time))
2019-11-22 19:58:44:065 - [debug] [XCUITest] Reset: running ios real device reset flow
2019-11-22 19:58:44:066 - [debug] [BaseDriver] Event 'resetComplete' logged at 1574452724066 (14:58:44 GMT-0500 (Eastern Standard Time))
2019-11-22 19:58:44:315 - [debug] [XCUITest] Crash reports root '/Users/bdouglas/Library/Logs/CrashReporter/MobileDevice/HL 7090' does not exist. Got nothing to gather.
2019-11-22 19:58:44:316 - [debug] [BaseDriver] Event 'logCaptureStarted' logged at 1574452724315 (14:58:44 GMT-0500 (Eastern Standard Time))
2019-11-22 19:58:44:316 - [XCUITest] Setting up real device
2019-11-22 19:58:44:316 - [RemoteDebugger] Remote Debugger version 6.2.0
2019-11-22 19:58:44:317 - [debug] [RemoteDebugger] useNewSafari --> false
2019-11-22 19:58:44:318 - [debug] [RemoteDebugger] Checking which communication style to use (non-Safari on platform version '13.2')
2019-11-22 19:58:44:318 - [debug] [RemoteDebugger] Platform version equal or higher than '12.2': true
2019-11-22 19:58:44:318 - [RemoteDebugger] Setting communication protocol: using full Web Inspector protocol communication
2019-11-22 19:58:44:440 - [debug] [RemoteDebugger] Sending connection key request
2019-11-22 19:58:44:441 - [debug] [RemoteDebugger] Sending '_rpc_reportIdentifier:' message (id: 0)
2019-11-22 19:58:44:443 - [debug] [RemoteDebugger] Sending '_rpc_forwardSocketData:' message (id: 1)
2019-11-22 19:58:44:446 - [debug] [RemoteDebugger] Sending to Web Inspector took 5ms
2019-11-22 19:58:44:446 - [debug] [RemoteDebugger] Connected to application
2019-11-22 19:58:44:496 - [debug] [RemoteDebugger] Received connected applications list: PID:67
2019-11-22 19:58:44:497 - [debug] [RemoteDebugger] Received connected driver list: {}
2019-11-22 19:58:44:614 - [XCUITest] Using WDA path: '/Users/bdouglas/build_tools/ios_phone/7090eac95fe26e87ec013646c75a289c235003c7/node_modules/appium-webdriveragent'
2019-11-22 19:58:44:614 - [XCUITest] Using WDA agent: '/Users/bdouglas/build_tools/ios_phone/7090eac95fe26e87ec013646c75a289c235003c7/node_modules/appium-webdriveragent/WebDriverAgent.xcodeproj'
2019-11-22 19:58:44:624 - [HTTP] --> GET /wd/hub/status
2019-11-22 19:58:44:624 - [HTTP] {}
2019-11-22 19:58:44:625 - [debug] [GENERIC] Calling AppiumDriver.getStatus() with args: []
2019-11-22 19:58:44:625 - [debug] [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.16.0-beta.2"}}
2019-11-22 19:58:44:626 - [HTTP] <-- GET /wd/hub/status 200 1 ms - 75
2019-11-22 19:58:44:626 - [HTTP] 
2019-11-22 19:58:44:746 - [debug] [XCUITest] No obsolete cached processes from previous WDA sessions listening on port 7828 have been found
2019-11-22 19:58:44:747 - [DevCon Factory] Requesting connection for device 7090eac95fe26e87ec013646c75a289c235003c7 on local port 7828, device port 7828
2019-11-22 19:58:44:747 - [debug] [DevCon Factory] Cached connections count: 0
2019-11-22 19:58:44:748 - [DevCon Factory] Port #7828 is busy
2019-11-22 19:58:44:750 - [DevCon Factory] Successfully requested the connection for 7090eac95fe26e87ec013646c75a289c235003c7:7828
2019-11-22 19:58:44:751 - [debug] [XCUITest] Starting WebDriverAgent initialization with the synchronization key 'XCUITestDriver'
2019-11-22 19:58:44:751 - [debug] [XCUITest] Capability 'useNewWDA' set to true, so uninstalling WDA before proceeding
2019-11-22 19:58:44:751 - [XCUITest] Shutting down sub-processes
2019-11-22 19:58:45:031 - [debug] [XCUITest] No WDAs on the device.
2019-11-22 19:58:45:031 - [debug] [BaseDriver] Event 'wdaUninstalled' logged at 1574452725031 (14:58:45 GMT-0500 (Eastern Standard Time))
2019-11-22 19:58:45:031 - [debug] [XCUITest] Trying to start WebDriverAgent 1 times with 10000ms interval
2019-11-22 19:58:45:032 - [debug] [XCUITest] These values can be customized by changing wdaStartupRetries/wdaStartupRetryInterval capabilities
2019-11-22 19:58:45:032 - [debug] [BaseDriver] Event 'wdaStartAttempted' logged at 1574452725032 (14:58:45 GMT-0500 (Eastern Standard Time))
2019-11-22 19:58:45:032 - [XCUITest] Launching WebDriverAgent on the device
2019-11-22 19:58:45:033 - [WebDriverAgent] Fetching dependencies
2019-11-22 19:58:45:040 - [WebDriverAgent] Dependencies up-to-date
2019-11-22 19:58:45:040 - [debug] [XCUITest] Killing running processes 'xcodebuild.*7090eac95fe26e87ec013646c75a289c235003c7' for the device 7090eac95fe26e87ec013646c75a289c235003c7...
2019-11-22 19:58:45:084 - [debug] [XCUITest] 'pgrep -ifn xcodebuild.*7090eac95fe26e87ec013646c75a289c235003c7' didn't detect any matching processes. Return code: 1
2019-11-22 19:58:45:093 - [debug] [XCUITest] Successfully updated '/Users/bdouglas/build_tools/ios_phone/7090eac95fe26e87ec013646c75a289c235003c7/node_modules/appium-webdriveragent/WebDriverAgent.xcodeproj/project.pbxproj' with bundle id 'com.mycompany.qa.WebDriverAgentRunner'
2019-11-22 19:58:45:094 - [debug] [XCUITest] Generating xcode config file for orgId '24X86T8MC6' and signingId 'iPhone Developer'
2019-11-22 19:58:45:094 - [debug] [XCUITest] Writing xcode config file to /var/folders/r3/c1fz8xvj78557t5tj1vw_kmc0000gp/T/20191022-42414-8qlwwm.gnb/appium-temp.xcconfig
2019-11-22 19:58:45:095 - [debug] [XCUITest] Using Xcode configuration file: '/var/folders/r3/c1fz8xvj78557t5tj1vw_kmc0000gp/T/20191022-42414-8qlwwm.gnb/appium-temp.xcconfig'
2019-11-22 19:58:45:095 - [debug] [XCUITest] Beginning test with command 'xcodebuild build-for-testing test-without-building -project /Users/bdouglas/build_tools/ios_phone/7090eac95fe26e87ec013646c75a289c235003c7/node_modules/appium-webdriveragent/WebDriverAgent.xcodeproj -scheme WebDriverAgentRunner -destination id=7090eac95fe26e87ec013646c75a289c235003c7 IPHONEOS_DEPLOYMENT_TARGET=13.2 -xcconfig /var/folders/r3/c1fz8xvj78557t5tj1vw_kmc0000gp/T/20191022-42414-8qlwwm.gnb/appium-temp.xcconfig GCC_TREAT_WARNINGS_AS_ERRORS=0 COMPILER_INDEX_STORE_ENABLE=NO' in directory '/Users/bdouglas/build_tools/ios_phone/7090eac95fe26e87ec013646c75a289c235003c7/node_modules/appium-webdriveragent'
2019-11-22 19:58:45:096 - [debug] [XCUITest] Output from xcodebuild will only be logged if any errors are present there. To change this, use 'showXcodeLog' desired capability
2019-11-22 19:58:46:320 - [debug] [XCUITest] Waiting up to 60000ms for WebDriverAgent to start
2019-11-22 19:58:46:322 - [debug] [WD Proxy] Matched '/status' to command name 'getStatus'
2019-11-22 19:58:46:323 - [debug] [WD Proxy] Proxying [GET /status] to [GET http://localhost:7828/status] with no body
2019-11-22 19:58:46:334 - [iProxy@7090e...] Connection was refused to port 7828
2019-11-22 19:58:46:339 - [WD Proxy] Got an unexpected response with status undefined: {"code":"ECONNRESET"}
2019-11-22 19:58:47:342 - [debug] [WD Proxy] Matched '/status' to command name 'getStatus'
2019-11-22 19:58:47:342 - [debug] [WD Proxy] Proxying [GET /status] to [GET http://localhost:7828/status] with no body
2019-11-22 19:58:47:348 - [iProxy@7090e...] Connection was refused to port 7828
2019-11-22 19:58:47:349 - [WD Proxy] Got an unexpected response with status undefined: {"code":"ECONNRESET"}
2019-11-22 19:58:48:350 - [debug] [WD Proxy] Matched '/status' to command name 'getStatus'
2019-11-22 19:58:48:351 - [debug] [WD Proxy] Proxying [GET /status] to [GET http://localhost:7828/status] with no body
2019-11-22 19:58:48:357 - [iProxy@7090e...] Connection was refused to port 7828
2019-11-22 19:58:48:358 - [WD Proxy] Got an unexpected response with status undefined: {"code":"ECONNRESET"}
2019-11-22 19:58:48:696 - [debug] [XCUITest] Log file for xcodebuild test: /Users/bdouglas/Library/Developer/Xcode/DerivedData/WebDriverAgent-gxgzcbycukqjqzfsaldovetvqunn/Logs/Test/Test-WebDriverAgentRunner-2019.11.22_14-58-47--0500.xcresult/Staging/2_Test/Diagnostics/WebDriverAgentRunner-FA446810-5F6C-4ABA-9DD3-3ED69004343F/WebDriverAgentRunner-C34A72B0-9BD0-4D27-BC84-783BB32FB6B8/Session-WebDriverAgentRunner-2019-11-22_145848-Y1AHmm.log
2019-11-22 19:58:49:358 - [debug] [WD Proxy] Matched '/status' to command name 'getStatus'
2019-11-22 19:58:49:359 - [debug] [WD Proxy] Proxying [GET /status] to [GET http://localhost:7828/status] with no body
2019-11-22 19:58:49:364 - [iProxy@7090e...] Connection was refused to port 7828
2019-11-22 19:58:49:365 - [WD Proxy] Got an unexpected response with status undefined: {"code":"ECONNRESET"}
2019-11-22 19:58:49:632 - [HTTP] --> GET /wd/hub/status
2019-11-22 19:58:49:632 - [HTTP] {}
2019-11-22 19:58:49:632 - [debug] [GENERIC] Calling AppiumDriver.getStatus() with args: []
2019-11-22 19:58:49:633 - [debug] [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.16.0-beta.2"}}
2019-11-22 19:58:49:633 - [HTTP] <-- GET /wd/hub/status 200 1 ms - 75
2019-11-22 19:58:49:633 - [HTTP] 
2019-11-22 19:58:50:364 - [debug] [WD Proxy] Matched '/status' to command name 'getStatus'
2019-11-22 19:58:50:364 - [debug] [WD Proxy] Proxying [GET /status] to [GET http://localhost:7828/status] with no body
2019-11-22 19:58:50:371 - [iProxy@7090e...] Connection was refused to port 7828
2019-11-22 19:58:50:372 - [WD Proxy] Got an unexpected response with status undefined: {"code":"ECONNRESET"}
2019-11-22 19:58:51:373 - [debug] [WD Proxy] Matched '/status' to command name 'getStatus'
2019-11-22 19:58:51:383 - [debug] [WD Proxy] Proxying [GET /status] to [GET http://localhost:7828/status] with no body
2019-11-22 19:58:51:389 - [iProxy@7090e...] Connection was refused to port 7828
2019-11-22 19:58:51:390 - [WD Proxy] Got an unexpected response with status undefined: {"code":"ECONNRESET"}
2019-11-22 19:58:52:392 - [debug] [WD Proxy] Matched '/status' to command name 'getStatus'
2019-11-22 19:58:52:392 - [debug] [WD Proxy] Proxying [GET /status] to [GET http://localhost:7828/status] with no body
2019-11-22 19:58:52:398 - [iProxy@7090e...] Connection was refused to port 7828
2019-11-22 19:58:52:399 - [WD Proxy] Got an unexpected response with status undefined: {"code":"ECONNRESET"}
2019-11-22 19:58:53:400 - [debug] [WD Proxy] Matched '/status' to command name 'getStatus'
2019-11-22 19:58:53:401 - [debug] [WD Proxy] Proxying [GET /status] to [GET http://localhost:7828/status] with no body
2019-11-22 19:58:53:407 - [iProxy@7090e...] Connection was refused to port 7828
2019-11-22 19:58:53:407 - [WD Proxy] Got an unexpected response with status undefined: {"code":"ECONNRESET"}
2019-11-22 19:58:54:408 - [debug] [WD Proxy] Matched '/status' to command name 'getStatus'
2019-11-22 19:58:54:408 - [debug] [WD Proxy] Proxying [GET /status] to [GET http://localhost:7828/status] with no body
2019-11-22 19:58:54:417 - [debug] [WD Proxy] Got response with status 200: {
2019-11-22 19:58:54:417 - [debug] [WD Proxy]   "value" : {
2019-11-22 19:58:54:417 - [debug] [WD Proxy]     "message" : "WebDriverAgent is ready to accept commands",
2019-11-22 19:58:54:417 - [debug] [WD Proxy]     "state" : "success",
2019-11-22 19:58:54:417 - [debug] [WD Proxy]     "os" : {
2019-11-22 19:58:54:417 - [debug] [WD Proxy]       "name" : "iOS",
2019-11-22 19:58:54:417 - [debug] [WD Proxy]       "version" : "13.2.3",
2019-11-22 19:58:54:417 - [debug] [WD Proxy]       "sdkVersion" : "13.0"
2019-11-22 19:58:54:418 - [debug] [WD Proxy]     },
2019-11-22 19:58:54:418 - [debug] [WD Proxy]     "ios" : {
2019-11-22 19:58:54:418 - [debug] [WD Proxy]       "simulatorVersion" : "13.2.3",
2019-11-22 19:58:54:418 - [debug] [WD Proxy]       "ip" : "172.16.100.66"
2019-11-22 19:58:54:418 - [debug] [WD Proxy]     },
2019-11-22 19:58:54:418 - [debug] [WD Proxy]     "ready" : true,
2019-11-22 19:58:54:418 - [debug] [WD Proxy]     "build" : {
2019-11-22 19:58:54:418 - [debug] [WD Proxy]       "upgradedAt" : "1571410078635",
2019-11-22 19:58:54:418 - [debug] [WD Proxy]       "time" : "Oct 18 2019 10:48:24",
2019-11-22 19:58:54:418 - [debug] [WD Proxy]       "productBundleIdentifier" : "com.mycompany.qa.WebDriverAgentRunner"
2019-11-22 19:58:54:418 - [debug] [WD Proxy]     }
2019-11-22 19:58:54:418 - [debug] [WD Proxy]   },
2019-11-22 19:58:54:419 - [debug] [WD Proxy]   "sessionId" : "62DB98DF-7E33-4FD6-AEFB-DBDBAD2460C2"
2019-11-22 19:58:54:419 - [debug] [WD Proxy] }
2019-11-22 19:58:54:419 - [debug] [XCUITest] WebDriverAgent information:
2019-11-22 19:58:54:419 - [debug] [XCUITest] {
2019-11-22 19:58:54:419 - [debug] [XCUITest]   "message": "WebDriverAgent is ready to accept commands",
2019-11-22 19:58:54:419 - [debug] [XCUITest]   "state": "success",
2019-11-22 19:58:54:420 - [debug] [XCUITest]   "os": {
2019-11-22 19:58:54:420 - [debug] [XCUITest]     "name": "iOS",
2019-11-22 19:58:54:420 - [debug] [XCUITest]     "version": "13.2.3",
2019-11-22 19:58:54:420 - [debug] [XCUITest]     "sdkVersion": "13.0"
2019-11-22 19:58:54:420 - [debug] [XCUITest]   },
2019-11-22 19:58:54:420 - [debug] [XCUITest]   "ios": {
2019-11-22 19:58:54:420 - [debug] [XCUITest]     "simulatorVersion": "13.2.3",
2019-11-22 19:58:54:420 - [debug] [XCUITest]     "ip": "172.16.100.66"
2019-11-22 19:58:54:420 - [debug] [XCUITest]   },
2019-11-22 19:58:54:423 - [debug] [XCUITest]   "ready": true,
2019-11-22 19:58:54:423 - [debug] [XCUITest]   "build": {
2019-11-22 19:58:54:423 - [debug] [XCUITest]     "upgradedAt": "1571410078635",
2019-11-22 19:58:54:423 - [debug] [XCUITest]     "time": "Oct 18 2019 10:48:24",
2019-11-22 19:58:54:423 - [debug] [XCUITest]     "productBundleIdentifier": "com.mycompany.qa.WebDriverAgentRunner"
2019-11-22 19:58:54:424 - [debug] [XCUITest]   }
2019-11-22 19:58:54:424 - [debug] [XCUITest] }
2019-11-22 19:58:54:424 - [debug] [XCUITest] WebDriverAgent successfully started after 9326ms
2019-11-22 19:58:54:425 - [debug] [BaseDriver] Event 'wdaSessionAttempted' logged at 1574452734425 (14:58:54 GMT-0500 (Eastern Standard Time))
2019-11-22 19:58:54:425 - [debug] [XCUITest] Sending createSession command to WDA
2019-11-22 19:58:54:426 - [debug] [WD Proxy] Matched '/session' to command name 'createSession'
2019-11-22 19:58:54:426 - [debug] [WD Proxy] Proxying [POST /session] to [POST http://localhost:7828/session] with body: {"capabilities":{"firstMatch":[{"bundleId":"com.apple.Preferences","arguments":[],"environment":{},"eventloopIdleDelaySec":0,"shouldWaitForQuiescence":true,"shouldUseTestManagerForVisibilityDetection":true,"maxTypingFrequency":60,"shouldUseSingletonTestManager":true}],"alwaysMatch":{}}}
2019-11-22 19:58:54:639 - [HTTP] --> GET /wd/hub/status
2019-11-22 19:58:54:639 - [HTTP] {}
2019-11-22 19:58:54:639 - [debug] [GENERIC] Calling AppiumDriver.getStatus() with args: []
2019-11-22 19:58:54:640 - [debug] [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.16.0-beta.2"}}
2019-11-22 19:58:54:640 - [HTTP] <-- GET /wd/hub/status 200 1 ms - 75
2019-11-22 19:58:54:641 - [HTTP] 
2019-11-22 19:58:56:175 - [debug] [WD Proxy] Got response with status 200: {"value":{"sessionId":"CD5F0472-F9C1-42DC-BCF9-1B378805A72E","capabilities":{"device":"iphone","browserName":"Settings","sdkVersion":"13.2.3","CFBundleIdentifier":"com.apple.Preferences"}},"sessionId":"CD5F0472-F9C1-42DC-BCF9-1B378805A72E"}
2019-11-22 19:58:56:175 - [WD Proxy] Determined the downstream protocol as 'W3C'
2019-11-22 19:58:56:175 - [debug] [BaseDriver] Event 'wdaSessionStarted' logged at 1574452736175 (14:58:56 GMT-0500 (Eastern Standard Time))
2019-11-22 19:58:58:523 - [debug] [XCUITest] Parsed BUILD_DIR configuration value: '/Users/bdouglas/Library/Developer/Xcode/DerivedData/WebDriverAgent-gxgzcbycukqjqzfsaldovetvqunn/Build/Products'
2019-11-22 19:58:58:523 - [debug] [XCUITest] Got derived data root: '/Users/bdouglas/Library/Developer/Xcode/DerivedData/WebDriverAgent-gxgzcbycukqjqzfsaldovetvqunn'
2019-11-22 19:58:58:523 - [debug] [BaseDriver] Event 'wdaStarted' logged at 1574452738523 (14:58:58 GMT-0500 (Eastern Standard Time))
2019-11-22 19:58:58:524 - [XCUITest] Skipping setting of the initial display orientation. Set the "orientation" capability to either "LANDSCAPE" or "PORTRAIT", if this is an undesired behavior.
2019-11-22 19:58:58:524 - [debug] [BaseDriver] Event 'orientationSet' logged at 1574452738524 (14:58:58 GMT-0500 (Eastern Standard Time))
2019-11-22 19:58:58:525 - [Appium] New XCUITestDriver session created successfully, session a1a12684-68e8-4702-8dcd-d500fada7e09 added to master session list
2019-11-22 19:58:58:525 - [debug] [BaseDriver] Event 'newSessionStarted' logged at 1574452738525 (14:58:58 GMT-0500 (Eastern Standard Time))
2019-11-22 19:58:58:526 - [debug] [W3C (a1a12684)] Cached the protocol value 'W3C' for the new session a1a12684-68e8-4702-8dcd-d500fada7e09
2019-11-22 19:58:58:526 - [debug] [W3C (a1a12684)] Responding to client with driver.createSession() result: {"capabilities":{"webStorageEnabled":false,"locationContextEnabled":false,"browserName":"","platform":"MAC","javascriptEnabled":true,"databaseEnabled":false,"takesScreenshot":true,"networkConnectionEnabled":false,"platformName":"ios","server:CONFIG_UUID":"e9e2d5d7-1ca4-4a78-a69a-1f3f6d28f4f4","mycompany:node.defaultDevice":false,"mycompany:node.platformType":"Phone","mycompany:testng.hostname":"bedouglas-mac","mycompany:testng.session.hash":"99d5a67a-1a96-4292-add8-9a16afc810b4","mycompany:testng.suite":"Default Suite","mycompany:testng.test":"testAttractionsSearchBoston","preventWDAAttachments":true,"simpleIsVisibleCheck":true,"clearSystemFiles":true,"noReset":false,"xcodeOrgId":"24X86T8MC6","bundleId":"com.apple.Preferences","updatedWDABundleId":"com.mycompany.qa.WebDriverAgentRunner","xcodeSigningId":"iPhone Developer","sendKeyStrategy":"grouped","newCommandTimeout":100,"platformVersion":"13.2","automationName":"XCuiTest","useNewWDA":true,"deviceName":"7090eac95fe26e87ec013646c75a289c23500...
2019-11-22 19:58:58:527 - [HTTP] <-- POST /wd/hub/session 200 15111 ms - 1221
2019-11-22 19:58:58:527 - [HTTP] 
2019-11-22 19:58:58:568 - [HTTP] --> GET /wd/hub/session/a1a12684-68e8-4702-8dcd-d500fada7e09
2019-11-22 19:58:58:568 - [HTTP] {}
2019-11-22 19:58:58:569 - [debug] [W3C (a1a12684)] Calling AppiumDriver.getSession() with args: ["a1a12684-68e8-4702-8dcd-d500fada7e09"]
2019-11-22 19:58:58:569 - [debug] [XCUITest] Executing command 'getSession'
2019-11-22 19:58:58:570 - [debug] [WD Proxy] Matched '/' to command name 'getSession'
2019-11-22 19:58:58:570 - [debug] [WD Proxy] Proxying [GET /] to [GET http://localhost:7828/session/CD5F0472-F9C1-42DC-BCF9-1B378805A72E] with no body
2019-11-22 19:58:58:772 - [debug] [WD Proxy] Got response with status 200: {
2019-11-22 19:58:58:772 - [debug] [WD Proxy]   "value" : {
2019-11-22 19:58:58:772 - [debug] [WD Proxy]     "sessionId" : "CD5F0472-F9C1-42DC-BCF9-1B378805A72E",
2019-11-22 19:58:58:772 - [debug] [WD Proxy]     "capabilities" : {
2019-11-22 19:58:58:772 - [debug] [WD Proxy]       "device" : "iphone",
2019-11-22 19:58:58:772 - [debug] [WD Proxy]       "browserName" : "Settings",
2019-11-22 19:58:58:772 - [debug] [WD Proxy]       "sdkVersion" : "13.2.3",
2019-11-22 19:58:58:772 - [debug] [WD Proxy]       "CFBundleIdentifier" : "com.apple.Preferences"
2019-11-22 19:58:58:772 - [debug] [WD Proxy]     }
2019-11-22 19:58:58:772 - [debug] [WD Proxy]   },
2019-11-22 19:58:58:773 - [debug] [WD Proxy]   "sessionId" : "CD5F0472-F9C1-42DC-BCF9-1B378805A72E"
2019-11-22 19:58:58:773 - [debug] [WD Proxy] }
2019-11-22 19:58:58:780 - [debug] [WD Proxy] Proxying [GET /wda/screen] to [GET http://localhost:7828/session/CD5F0472-F9C1-42DC-BCF9-1B378805A72E/wda/screen] with no body
2019-11-22 19:58:58:886 - [debug] [WD Proxy] Got response with status 200: {
2019-11-22 19:58:58:886 - [debug] [WD Proxy]   "value" : {
2019-11-22 19:58:58:886 - [debug] [WD Proxy]     "statusBarSize" : {
2019-11-22 19:58:58:886 - [debug] [WD Proxy]       "width" : 0,
2019-11-22 19:58:58:886 - [debug] [WD Proxy]       "height" : 0
2019-11-22 19:58:58:886 - [debug] [WD Proxy]     },
2019-11-22 19:58:58:886 - [debug] [WD Proxy]     "scale" : 2
2019-11-22 19:58:58:887 - [debug] [WD Proxy]   },
2019-11-22 19:58:58:887 - [debug] [WD Proxy]   "sessionId" : "CD5F0472-F9C1-42DC-BCF9-1B378805A72E"
2019-11-22 19:58:58:887 - [debug] [WD Proxy] }
2019-11-22 19:58:58:889 - [debug] [WD Proxy] Proxying [GET /window/size] to [GET http://localhost:7828/session/CD5F0472-F9C1-42DC-BCF9-1B378805A72E/window/size] with no body
2019-11-22 19:58:59:156 - [debug] [WD Proxy] Got response with status 200: {
2019-11-22 19:58:59:156 - [debug] [WD Proxy]   "value" : {
2019-11-22 19:58:59:156 - [debug] [WD Proxy]     "width" : 375,
2019-11-22 19:58:59:156 - [debug] [WD Proxy]     "height" : 667
2019-11-22 19:58:59:156 - [debug] [WD Proxy]   },
2019-11-22 19:58:59:156 - [debug] [WD Proxy]   "sessionId" : "CD5F0472-F9C1-42DC-BCF9-1B378805A72E"
2019-11-22 19:58:59:157 - [debug] [WD Proxy] }
2019-11-22 19:58:59:157 - [XCUITest] Merging WDA caps over Appium caps for session detail response
2019-11-22 19:58:59:157 - [debug] [W3C (a1a12684)] Responding to client with driver.getSession() result: {"udid":"7090eac95fe26e87ec013646c75a289c235003c7","browserName":"Settings","platformName":"ios","server:CONFIG_UUID":"e9e2d5d7-1ca4-4a78-a69a-1f3f6d28f4f4","mycompany:node.defaultDevice":false,"mycompany:node.platformType":"Phone","mycompany:testng.hostname":"bedouglas-mac","mycompany:testng.session.hash":"99d5a67a-1a96-4292-add8-9a16afc810b4","mycompany:testng.suite":"Default Suite","mycompany:testng.test":"testAttractionsSearchBoston","preventWDAAttachments":true,"simpleIsVisibleCheck":true,"clearSystemFiles":true,"noReset":false,"xcodeOrgId":"24X86T8MC6","bundleId":"com.apple.Preferences","updatedWDABundleId":"com.mycompany.qa.WebDriverAgentRunner","xcodeSigningId":"iPhone Developer","sendKeyStrategy":"grouped","newCommandTimeout":100,"platformVersion":"13.2","automationName":"XCuiTest","useNewWDA":true,"deviceName":"7090eac95fe26e87ec013646c75a289c235003c7","systemPort":7828,"wdaLocalPort":7828,"webkitDebugProxyPort":9828,"startIWDP":true,"device":"iphone","sdkVersion":"13.2.3","CFBundle...
2019-11-22 19:58:59:158 - [HTTP] <-- GET /wd/hub/session/a1a12684-68e8-4702-8dcd-d500fada7e09 200 590 ms - 1160
2019-11-22 19:58:59:158 - [HTTP] 
2019-11-22 19:58:59:340 - [HTTP] --> POST /wd/hub/session/a1a12684-68e8-4702-8dcd-d500fada7e09/appium/device/terminate_app
2019-11-22 19:58:59:340 - [HTTP] {"bundleId":"com.mycompany.release"}
2019-11-22 19:58:59:346 - [debug] [W3C (a1a12684)] Calling AppiumDriver.terminateApp() with args: ["com.mycompany.release",null,null,null,"a1a12684-68e8-4702-8dcd-d500fada7e09"]
2019-11-22 19:58:59:347 - [debug] [XCUITest] Executing command 'terminateApp'
2019-11-22 19:58:59:349 - [debug] [WD Proxy] Proxying [POST /wda/apps/terminate] to [POST http://localhost:7828/session/CD5F0472-F9C1-42DC-BCF9-1B378805A72E/wda/apps/terminate] with body: {"bundleId":"com.mycompany.release"}
2019-11-22 19:58:59:366 - [debug] [WD Proxy] Got response with status 200: {"value":false,"sessionId":"CD5F0472-F9C1-42DC-BCF9-1B378805A72E"}
2019-11-22 19:58:59:366 - [debug] [W3C (a1a12684)] Responding to client with driver.terminateApp() result: false
2019-11-22 19:58:59:367 - [HTTP] <-- POST /wd/hub/session/a1a12684-68e8-4702-8dcd-d500fada7e09/appium/device/terminate_app 200 27 ms - 15
2019-11-22 19:58:59:367 - [HTTP] 
2019-11-22 19:58:59:376 - [HTTP] --> POST /wd/hub/session/a1a12684-68e8-4702-8dcd-d500fada7e09/appium/device/remove_app
2019-11-22 19:58:59:376 - [HTTP] {"bundleId":"com.mycompany.release"}
2019-11-22 19:58:59:377 - [debug] [W3C (a1a12684)] Calling AppiumDriver.removeApp() with args: ["com.mycompany.release",null,null,null,"a1a12684-68e8-4702-8dcd-d500fada7e09"]
2019-11-22 19:58:59:377 - [debug] [XCUITest] Executing command 'removeApp'
2019-11-22 19:58:59:377 - [XCUITest] Uninstalling the application with bundle identifier 'com.mycompany.release' from the real device with UDID 7090eac95fe26e87ec013646c75a289c235003c7
2019-11-22 19:58:59:644 - [HTTP] --> GET /wd/hub/status
2019-11-22 19:58:59:644 - [HTTP] {}
2019-11-22 19:58:59:644 - [debug] [GENERIC] Calling AppiumDriver.getStatus() with args: []
2019-11-22 19:58:59:644 - [debug] [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.16.0-beta.2"}}
2019-11-22 19:58:59:645 - [HTTP] <-- GET /wd/hub/status 200 1 ms - 75
2019-11-22 19:58:59:645 - [HTTP] 
2019-11-22 19:58:59:659 - [XCUITest] Removal of 'com.mycompany.release' succeeded
2019-11-22 19:58:59:659 - [debug] [W3C (a1a12684)] Responding to client with driver.removeApp() result: true
2019-11-22 19:58:59:659 - [HTTP] <-- POST /wd/hub/session/a1a12684-68e8-4702-8dcd-d500fada7e09/appium/device/remove_app 200 283 ms - 14
2019-11-22 19:58:59:660 - [HTTP] 
2019-11-22 19:58:59:666 - [HTTP] --> POST /wd/hub/session/a1a12684-68e8-4702-8dcd-d500fada7e09/appium/device/remove_app
2019-11-22 19:58:59:666 - [HTTP] {"bundleId":"com.mycompany.prerelease"}
2019-11-22 19:58:59:667 - [debug] [W3C (a1a12684)] Calling AppiumDriver.removeApp() with args: ["com.mycompany.prerelease",null,null,null,"a1a12684-68e8-4702-8dcd-d500fada7e09"]
2019-11-22 19:58:59:667 - [debug] [XCUITest] Executing command 'removeApp'
2019-11-22 19:58:59:667 - [XCUITest] Uninstalling the application with bundle identifier 'com.mycompany.prerelease' from the real device with UDID 7090eac95fe26e87ec013646c75a289c235003c7
2019-11-22 19:58:59:891 - [XCUITest] Removal of 'com.mycompany.prerelease' succeeded
2019-11-22 19:58:59:891 - [debug] [W3C (a1a12684)] Responding to client with driver.removeApp() result: true
2019-11-22 19:58:59:892 - [HTTP] <-- POST /wd/hub/session/a1a12684-68e8-4702-8dcd-d500fada7e09/appium/device/remove_app 200 226 ms - 14
2019-11-22 19:58:59:892 - [HTTP] 
2019-11-22 19:58:59:898 - [HTTP] --> POST /wd/hub/session/a1a12684-68e8-4702-8dcd-d500fada7e09/appium/device/remove_app
2019-11-22 19:58:59:898 - [HTTP] {"bundleId":"com.mycompany.mainline"}
2019-11-22 19:58:59:899 - [debug] [W3C (a1a12684)] Calling AppiumDriver.removeApp() with args: ["com.mycompany.mainline",null,null,null,"a1a12684-68e8-4702-8dcd-d500fada7e09"]
2019-11-22 19:58:59:899 - [debug] [XCUITest] Executing command 'removeApp'
2019-11-22 19:58:59:899 - [XCUITest] Uninstalling the application with bundle identifier 'com.mycompany.mainline' from the real device with UDID 7090eac95fe26e87ec013646c75a289c235003c7
2019-11-22 19:59:00:122 - [XCUITest] Removal of 'com.mycompany.mainline' succeeded
2019-11-22 19:59:00:123 - [debug] [W3C (a1a12684)] Responding to client with driver.removeApp() result: true
2019-11-22 19:59:00:123 - [HTTP] <-- POST /wd/hub/session/a1a12684-68e8-4702-8dcd-d500fada7e09/appium/device/remove_app 200 225 ms - 14
2019-11-22 19:59:00:123 - [HTTP] 
2019-11-22 19:59:00:130 - [HTTP] --> POST /wd/hub/session/a1a12684-68e8-4702-8dcd-d500fada7e09/appium/device/remove_app
2019-11-22 19:59:00:130 - [HTTP] {"bundleId":"com.mycompany.mainline"}
2019-11-22 19:59:00:130 - [debug] [W3C (a1a12684)] Calling AppiumDriver.removeApp() with args: ["com.mycompany.mainline",null,null,null,"a1a12684-68e8-4702-8dcd-d500fada7e09"]
2019-11-22 19:59:00:130 - [debug] [XCUITest] Executing command 'removeApp'
2019-11-22 19:59:00:131 - [XCUITest] Uninstalling the application with bundle identifier 'com.mycompany.mainline' from the real device with UDID 7090eac95fe26e87ec013646c75a289c235003c7
2019-11-22 19:59:00:301 - [XCUITest] Removal of 'com.mycompany.mainline' succeeded
2019-11-22 19:59:00:301 - [debug] [W3C (a1a12684)] Responding to client with driver.removeApp() result: true
2019-11-22 19:59:00:302 - [HTTP] <-- POST /wd/hub/session/a1a12684-68e8-4702-8dcd-d500fada7e09/appium/device/remove_app 200 172 ms - 14
2019-11-22 19:59:00:302 - [HTTP] 
2019-11-22 19:59:00:309 - [HTTP] --> POST /wd/hub/session/a1a12684-68e8-4702-8dcd-d500fada7e09/appium/device/install_app
2019-11-22 19:59:00:309 - [HTTP] {"appPath":"http://vr-qa01.dhcp.mycompany.com:8000/files/mycompany-release-1-34.1-191111022.ipa"}
2019-11-22 19:59:00:309 - [debug] [W3C (a1a12684)] Calling AppiumDriver.installApp() with args: ["http://vr-qa01.dhcp.mycompany.com:8000/files/mycompany-release-1-34.1-191111022.ipa",null,null,null,"a1a12684-68e8-4702-8dcd-d500fada7e09"]
2019-11-22 19:59:00:309 - [debug] [XCUITest] Executing command 'installApp'
2019-11-22 19:59:00:310 - [BaseDriver] Using downloadable app 'http://vr-qa01.dhcp.mycompany.com:8000/files/mycompany-release-1-34.1-191111022.ipa'
2019-11-22 19:59:00:323 - [debug] [BaseDriver] App Last-Modified: Tue, 12 Nov 2019 18:54:58 GMT
2019-11-22 19:59:00:324 - [debug] [BaseDriver] Content-Type: application/octet-stream
2019-11-22 19:59:00:325 - [debug] [BaseDriver] Content-Disposition: attachment; filename="mycompany-release-1-34.1-191111022.ipa"
2019-11-22 19:59:01:405 - [debug] [BaseDriver] 'http://vr-qa01.dhcp.mycompany.com:8000/files/mycompany-release-1-34.1-191111022.ipa' (118.34 MB) has been downloaded to '/var/folders/r3/c1fz8xvj78557t5tj1vw_kmc0000gp/T/20191022-42414-1nlrdkm.z7xsl/mycompany-release-1-34.1-191111022.ipa' in 1.078s
2019-11-22 19:59:01:672 - [debug] [BaseDriver] Unzipping '/var/folders/r3/c1fz8xvj78557t5tj1vw_kmc0000gp/T/20191022-42414-1nlrdkm.z7xsl/mycompany-release-1-34.1-191111022.ipa'
2019-11-22 19:59:04:655 - [HTTP] --> GET /wd/hub/status
2019-11-22 19:59:04:656 - [HTTP] {}
2019-11-22 19:59:04:656 - [debug] [GENERIC] Calling AppiumDriver.getStatus() with args: []
2019-11-22 19:59:04:658 - [debug] [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.16.0-beta.2"}}
2019-11-22 19:59:04:661 - [HTTP] <-- GET /wd/hub/status 200 4 ms - 75
2019-11-22 19:59:04:661 - [HTTP] 
2019-11-22 19:59:09:671 - [HTTP] --> GET /wd/hub/status
2019-11-22 19:59:09:672 - [HTTP] {}
2019-11-22 19:59:09:673 - [debug] [GENERIC] Calling AppiumDriver.getStatus() with args: []
2019-11-22 19:59:09:675 - [debug] [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.16.0-beta.2"}}
2019-11-22 19:59:09:678 - [HTTP] <-- GET /wd/hub/status 200 6 ms - 75
2019-11-22 19:59:09:678 - [HTTP] 
2019-11-22 19:59:11:265 - [debug] [BaseDriver] Extracted 8721 item(s) from '/var/folders/r3/c1fz8xvj78557t5tj1vw_kmc0000gp/T/20191022-42414-1nlrdkm.z7xsl/mycompany-release-1-34.1-191111022.ipa'
2019-11-22 19:59:11:825 - [debug] [BaseDriver] Matched 8720 item(s) in the extracted archive. Assuming 'Payload/TripAdvisor.app' is the correct bundle
2019-11-22 19:59:11:844 - [BaseDriver] Unzipped local app to '/var/folders/r3/c1fz8xvj78557t5tj1vw_kmc0000gp/T/20191022-42414-1fobd25.ew8pg/Payload/TripAdvisor.app'
2019-11-22 19:59:11:845 - [XCUITest] Installing '/var/folders/r3/c1fz8xvj78557t5tj1vw_kmc0000gp/T/20191022-42414-1fobd25.ew8pg/Payload/TripAdvisor.app' to the real device with UDID 7090eac95fe26e87ec013646c75a289c235003c7
2019-11-22 19:59:14:687 - [HTTP] --> GET /wd/hub/status
2019-11-22 19:59:14:687 - [HTTP] {}
2019-11-22 19:59:14:688 - [debug] [GENERIC] Calling AppiumDriver.getStatus() with args: []
2019-11-22 19:59:14:689 - [debug] [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.16.0-beta.2"}}
2019-11-22 19:59:14:692 - [HTTP] <-- GET /wd/hub/status 200 4 ms - 75
2019-11-22 19:59:14:692 - [HTTP] 
2019-11-22 19:59:19:701 - [HTTP] --> GET /wd/hub/status
2019-11-22 19:59:19:702 - [HTTP] {}
2019-11-22 19:59:19:702 - [debug] [GENERIC] Calling AppiumDriver.getStatus() with args: []
2019-11-22 19:59:19:704 - [debug] [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.16.0-beta.2"}}
2019-11-22 19:59:19:706 - [HTTP] <-- GET /wd/hub/status 200 5 ms - 75
2019-11-22 19:59:19:707 - [HTTP] 
2019-11-22 19:59:24:717 - [HTTP] --> GET /wd/hub/status
2019-11-22 19:59:24:717 - [HTTP] {}
2019-11-22 19:59:24:718 - [debug] [GENERIC] Calling AppiumDriver.getStatus() with args: []
2019-11-22 19:59:24:719 - [debug] [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.16.0-beta.2"}}
2019-11-22 19:59:24:722 - [HTTP] <-- GET /wd/hub/status 200 5 ms - 75
2019-11-22 19:59:24:722 - [HTTP] 
2019-11-22 19:59:29:727 - [HTTP] --> GET /wd/hub/status
2019-11-22 19:59:29:728 - [HTTP] {}
2019-11-22 19:59:29:728 - [debug] [GENERIC] Calling AppiumDriver.getStatus() with args: []
2019-11-22 19:59:29:728 - [debug] [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.16.0-beta.2"}}
2019-11-22 19:59:29:729 - [HTTP] <-- GET /wd/hub/status 200 1 ms - 75
2019-11-22 19:59:29:729 - [HTTP] 
2019-11-22 19:59:34:734 - [HTTP] --> GET /wd/hub/status
2019-11-22 19:59:34:734 - [HTTP] {}
2019-11-22 19:59:34:735 - [debug] [GENERIC] Calling AppiumDriver.getStatus() with args: []
2019-11-22 19:59:34:735 - [debug] [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.16.0-beta.2"}}
2019-11-22 19:59:34:735 - [HTTP] <-- GET /wd/hub/status 200 1 ms - 75
2019-11-22 19:59:34:736 - [HTTP] 
2019-11-22 19:59:38:445 - [debug] [XCUITest] Pushed the app files successfully after 26600ms
2019-11-22 19:59:39:742 - [HTTP] --> GET /wd/hub/status
2019-11-22 19:59:39:743 - [HTTP] {}
2019-11-22 19:59:39:743 - [debug] [GENERIC] Calling AppiumDriver.getStatus() with args: []
2019-11-22 19:59:39:743 - [debug] [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.16.0-beta.2"}}
2019-11-22 19:59:39:744 - [HTTP] <-- GET /wd/hub/status 200 1 ms - 75
2019-11-22 19:59:39:744 - [HTTP] 
2019-11-22 19:59:44:751 - [HTTP] --> GET /wd/hub/status
2019-11-22 19:59:44:751 - [HTTP] {}
2019-11-22 19:59:44:751 - [debug] [GENERIC] Calling AppiumDriver.getStatus() with args: []
2019-11-22 19:59:44:751 - [debug] [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.16.0-beta.2"}}
2019-11-22 19:59:44:752 - [HTTP] <-- GET /wd/hub/status 200 1 ms - 75
2019-11-22 19:59:44:752 - [HTTP] 
2019-11-22 19:59:48:402 - [XCUITest] Error was thrown during the installation process
2019-11-22 19:59:48:410 - [XCUITest] Error: Unexpected data: {"Error":"ApplicationVerificationFailed","ErrorDetail":-1,"ErrorDescription":"Failed to verify code signature of /private/var/installd/Library/Caches/com.apple.mobile.installd.staging/temp.YmeRCi/extracted/TripAdvisor.app : 0xe8008017 (A signed resource has been added, modified, or deleted.)"}
2019-11-22 19:59:48:410 - [XCUITest]     at InstallationProxyService._isFinished (/Users/bdouglas/build_tools/ios_phone/7090eac95fe26e87ec013646c75a289c235003c7/node_modules/appium-ios-device/lib/installation-proxy/index.js:132:13)
2019-11-22 19:59:48:410 - [XCUITest]     at InstallationProxyService._isFinished [as _waitMessageCompletion] (/Users/bdouglas/build_tools/ios_phone/7090eac95fe26e87ec013646c75a289c235003c7/node_modules/appium-ios-device/lib/installation-proxy/index.js:124:16)
2019-11-22 19:59:48:410 - [XCUITest]     at InstallationProxyService.installApplication (/Users/bdouglas/build_tools/ios_phone/7090eac95fe26e87ec013646c75a289c235003c7/node_modules/appium-ios-device/lib/installation-proxy/index.js:24:12)
2019-11-22 19:59:48:410 - [XCUITest]     at IOSDeploy.installApplication (/Users/bdouglas/build_tools/ios_phone/7090eac95fe26e87ec013646c75a289c235003c7/node_modules/appium-xcuitest-driver/lib/ios-deploy.js:52:7)
2019-11-22 19:59:48:410 - [XCUITest]     at IOSDeploy.install (/Users/bdouglas/build_tools/ios_phone/7090eac95fe26e87ec013646c75a289c235003c7/node_modules/appium-xcuitest-driver/lib/ios-deploy.js:37:7)
2019-11-22 19:59:48:410 - [XCUITest]     at IOSDeploy.installApp (/Users/bdouglas/build_tools/ios_phone/7090eac95fe26e87ec013646c75a289c235003c7/node_modules/appium-xcuitest-driver/lib/ios-deploy.js:104:5)
2019-11-22 19:59:48:411 - [XCUITest]     at XCUITestDriver.mobileInstallApp (/Users/bdouglas/build_tools/ios_phone/7090eac95fe26e87ec013646c75a289c235003c7/node_modules/appium-xcuitest-driver/lib/commands/app-management.js:28:5)
2019-11-22 19:59:48:411 - [XCUITest]     at XCUITestDriver.installApp (/Users/bdouglas/build_tools/ios_phone/7090eac95fe26e87ec013646c75a289c235003c7/node_modules/appium-xcuitest-driver/lib/commands/app-management.js:90:3)
2019-11-22 19:59:49:618 - [debug] [W3C (a1a12684)] Encountered internal error running command: Error: Could not install app: 'Unexpected data: {"Error":"ApplicationVerificationFailed","ErrorDetail":-1,"ErrorDescription":"Failed to verify code signature of /private/var/installd/Library/Caches/com.apple.mobile.installd.staging/temp.YmeRCi/extracted/TripAdvisor.app : 0xe8008017 (A signed resource has been added, modified, or deleted.)"}'
2019-11-22 19:59:49:618 - [debug] [W3C (a1a12684)]     at IOSDeploy.install (/Users/bdouglas/build_tools/ios_phone/7090eac95fe26e87ec013646c75a289c235003c7/node_modules/appium-xcuitest-driver/lib/ios-deploy.js:41:13)
2019-11-22 19:59:49:629 - [HTTP] <-- POST /wd/hub/session/a1a12684-68e8-4702-8dcd-d500fada7e09/appium/device/install_app 500 49319 ms - 1277
2019-11-22 19:59:49:629 - [HTTP] 
2019-11-22 19:59:49:659 - [HTTP] --> POST /wd/hub/session/a1a12684-68e8-4702-8dcd-d500fada7e09/appium/device/install_app
2019-11-22 19:59:49:659 - [HTTP] {"appPath":"http://vr-qa01.dhcp.mycompany.com:8000/files/mycompany-release-1-34.1-191111022.ipa"}
2019-11-22 19:59:49:661 - [debug] [W3C (a1a12684)] Calling AppiumDriver.installApp() with args: ["http://vr-qa01.dhcp.mycompany.com:8000/files/mycompany-release-1-34.1-191111022.ipa",null,null,null,"a1a12684-68e8-4702-8dcd-d500fada7e09"]
2019-11-22 19:59:49:662 - [debug] [XCUITest] Executing command 'installApp'
2019-11-22 19:59:49:662 - [BaseDriver] Using downloadable app 'http://vr-qa01.dhcp.mycompany.com:8000/files/mycompany-release-1-34.1-191111022.ipa'
2019-11-22 19:59:49:669 - [debug] [BaseDriver] App Last-Modified: Tue, 12 Nov 2019 18:54:58 GMT
2019-11-22 19:59:49:670 - [BaseDriver] The application at '/var/folders/r3/c1fz8xvj78557t5tj1vw_kmc0000gp/T/20191022-42414-1fobd25.ew8pg/Payload/TripAdvisor.app' does not exist anymore. Deleting it from the cache
2019-11-22 19:59:49:671 - [debug] [BaseDriver] Content-Type: application/octet-stream
2019-11-22 19:59:49:671 - [debug] [BaseDriver] Content-Disposition: attachment; filename="mycompany-release-1-34.1-191111022.ipa"
2019-11-22 19:59:49:758 - [HTTP] --> GET /wd/hub/status
2019-11-22 19:59:49:758 - [HTTP] {}
2019-11-22 19:59:49:758 - [debug] [GENERIC] Calling AppiumDriver.getStatus() with args: []
2019-11-22 19:59:49:758 - [debug] [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.16.0-beta.2"}}
2019-11-22 19:59:49:759 - [HTTP] <-- GET /wd/hub/status 200 1 ms - 75
2019-11-22 19:59:49:759 - [HTTP] 
2019-11-22 19:59:50:807 - [debug] [BaseDriver] 'http://vr-qa01.dhcp.mycompany.com:8000/files/mycompany-release-1-34.1-191111022.ipa' (118.34 MB) has been downloaded to '/var/folders/r3/c1fz8xvj78557t5tj1vw_kmc0000gp/T/20191022-42414-14b58sp.4al6/mycompany-release-1-34.1-191111022.ipa' in 1.134s
2019-11-22 19:59:51:074 - [debug] [BaseDriver] Unzipping '/var/folders/r3/c1fz8xvj78557t5tj1vw_kmc0000gp/T/20191022-42414-14b58sp.4al6/mycompany-release-1-34.1-191111022.ipa'
2019-11-22 19:59:54:769 - [HTTP] --> GET /wd/hub/status
2019-11-22 19:59:54:770 - [HTTP] {}
2019-11-22 19:59:54:771 - [debug] [GENERIC] Calling AppiumDriver.getStatus() with args: []
2019-11-22 19:59:54:772 - [debug] [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.16.0-beta.2"}}
2019-11-22 19:59:54:775 - [HTTP] <-- GET /wd/hub/status 200 5 ms - 75
2019-11-22 19:59:54:775 - [HTTP] 
2019-11-22 19:59:59:670 - [debug] [BaseDriver] Extracted 8721 item(s) from '/var/folders/r3/c1fz8xvj78557t5tj1vw_kmc0000gp/T/20191022-42414-14b58sp.4al6/mycompany-release-1-34.1-191111022.ipa'
2019-11-22 19:59:59:780 - [HTTP] --> GET /wd/hub/status
2019-11-22 19:59:59:780 - [HTTP] {}
2019-11-22 19:59:59:780 - [debug] [GENERIC] Calling AppiumDriver.getStatus() with args: []
2019-11-22 19:59:59:780 - [debug] [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.16.0-beta.2"}}
2019-11-22 19:59:59:782 - [HTTP] <-- GET /wd/hub/status 200 2 ms - 75
2019-11-22 19:59:59:782 - [HTTP] 
2019-11-22 20:00:00:336 - [debug] [BaseDriver] Matched 8720 item(s) in the extracted archive. Assuming 'Payload/TripAdvisor.app' is the correct bundle
2019-11-22 20:00:00:353 - [BaseDriver] Unzipped local app to '/var/folders/r3/c1fz8xvj78557t5tj1vw_kmc0000gp/T/20191022-42414-1iqnse5.vrk4/Payload/TripAdvisor.app'
2019-11-22 20:00:00:354 - [XCUITest] Installing '/var/folders/r3/c1fz8xvj78557t5tj1vw_kmc0000gp/T/20191022-42414-1iqnse5.vrk4/Payload/TripAdvisor.app' to the real device with UDID 7090eac95fe26e87ec013646c75a289c235003c7
2019-11-22 20:00:04:793 - [HTTP] --> GET /wd/hub/status
2019-11-22 20:00:04:793 - [HTTP] {}
2019-11-22 20:00:04:794 - [debug] [GENERIC] Calling AppiumDriver.getStatus() with args: []
2019-11-22 20:00:04:796 - [debug] [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.16.0-beta.2"}}
2019-11-22 20:00:04:799 - [HTTP] <-- GET /wd/hub/status 200 6 ms - 75
2019-11-22 20:00:04:800 - [HTTP] 
2019-11-22 20:00:09:811 - [HTTP] --> GET /wd/hub/status
2019-11-22 20:00:09:812 - [HTTP] {}
2019-11-22 20:00:09:813 - [debug] [GENERIC] Calling AppiumDriver.getStatus() with args: []
2019-11-22 20:00:09:814 - [debug] [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.16.0-beta.2"}}
2019-11-22 20:00:09:818 - [HTTP] <-- GET /wd/hub/status 200 6 ms - 75
2019-11-22 20:00:09:818 - [HTTP] 
2019-11-22 20:00:14:826 - [HTTP] --> GET /wd/hub/status
2019-11-22 20:00:14:827 - [HTTP] {}
2019-11-22 20:00:14:828 - [debug] [GENERIC] Calling AppiumDriver.getStatus() with args: []
2019-11-22 20:00:14:830 - [debug] [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.16.0-beta.2"}}
2019-11-22 20:00:14:834 - [HTTP] <-- GET /wd/hub/status 200 7 ms - 75
2019-11-22 20:00:14:834 - [HTTP] 
2019-11-22 20:00:19:840 - [HTTP] --> GET /wd/hub/status
2019-11-22 20:00:19:840 - [HTTP] {}
2019-11-22 20:00:19:841 - [debug] [GENERIC] Calling AppiumDriver.getStatus() with args: []
2019-11-22 20:00:19:841 - [debug] [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.16.0-beta.2"}}
2019-11-22 20:00:19:842 - [HTTP] <-- GET /wd/hub/status 200 1 ms - 75
2019-11-22 20:00:19:842 - [HTTP] 
2019-11-22 20:00:24:847 - [HTTP] --> GET /wd/hub/status
2019-11-22 20:00:24:847 - [HTTP] {}
2019-11-22 20:00:24:847 - [debug] [GENERIC] Calling AppiumDriver.getStatus() with args: []
2019-11-22 20:00:24:848 - [debug] [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.16.0-beta.2"}}
2019-11-22 20:00:24:848 - [HTTP] <-- GET /wd/hub/status 200 1 ms - 75
2019-11-22 20:00:24:849 - [HTTP] 
2019-11-22 20:00:28:929 - [debug] [XCUITest] Pushed the app files successfully after 28575ms
2019-11-22 20:00:29:855 - [HTTP] --> GET /wd/hub/status
2019-11-22 20:00:29:855 - [HTTP] {}
2019-11-22 20:00:29:855 - [debug] [GENERIC] Calling AppiumDriver.getStatus() with args: []
2019-11-22 20:00:29:855 - [debug] [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.16.0-beta.2"}}
2019-11-22 20:00:29:856 - [HTTP] <-- GET /wd/hub/status 200 1 ms - 75
2019-11-22 20:00:29:857 - [HTTP] 
2019-11-22 20:00:34:860 - [HTTP] --> GET /wd/hub/status
2019-11-22 20:00:34:860 - [HTTP] {}
2019-11-22 20:00:34:860 - [debug] [GENERIC] Calling AppiumDriver.getStatus() with args: []
2019-11-22 20:00:34:861 - [debug] [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.16.0-beta.2"}}
2019-11-22 20:00:34:861 - [HTTP] <-- GET /wd/hub/status 200 1 ms - 75
2019-11-22 20:00:34:861 - [HTTP] 
2019-11-22 20:00:38:378 - [XCUITest] Error was thrown during the installation process
2019-11-22 20:00:38:379 - [XCUITest] Error: Unexpected data: {"Error":"ApplicationVerificationFailed","ErrorDetail":-1,"ErrorDescription":"Failed to verify code signature of /private/var/installd/Library/Caches/com.apple.mobile.installd.staging/temp.DJ9Vig/extracted/TripAdvisor.app : 0xe8008017 (A signed resource has been added, modified, or deleted.)"}
2019-11-22 20:00:38:379 - [XCUITest]     at InstallationProxyService._isFinished (/Users/bdouglas/build_tools/ios_phone/7090eac95fe26e87ec013646c75a289c235003c7/node_modules/appium-ios-device/lib/installation-proxy/index.js:132:13)
2019-11-22 20:00:38:379 - [XCUITest]     at InstallationProxyService._isFinished [as _waitMessageCompletion] (/Users/bdouglas/build_tools/ios_phone/7090eac95fe26e87ec013646c75a289c235003c7/node_modules/appium-ios-device/lib/installation-proxy/index.js:124:16)
2019-11-22 20:00:38:379 - [XCUITest]     at InstallationProxyService.installApplication (/Users/bdouglas/build_tools/ios_phone/7090eac95fe26e87ec013646c75a289c235003c7/node_modules/appium-ios-device/lib/installation-proxy/index.js:24:12)
2019-11-22 20:00:38:380 - [XCUITest]     at IOSDeploy.installApplication (/Users/bdouglas/build_tools/ios_phone/7090eac95fe26e87ec013646c75a289c235003c7/node_modules/appium-xcuitest-driver/lib/ios-deploy.js:52:7)
2019-11-22 20:00:38:380 - [XCUITest]     at IOSDeploy.install (/Users/bdouglas/build_tools/ios_phone/7090eac95fe26e87ec013646c75a289c235003c7/node_modules/appium-xcuitest-driver/lib/ios-deploy.js:37:7)
2019-11-22 20:00:38:380 - [XCUITest]     at IOSDeploy.installApp (/Users/bdouglas/build_tools/ios_phone/7090eac95fe26e87ec013646c75a289c235003c7/node_modules/appium-xcuitest-driver/lib/ios-deploy.js:104:5)
2019-11-22 20:00:38:380 - [XCUITest]     at XCUITestDriver.mobileInstallApp (/Users/bdouglas/build_tools/ios_phone/7090eac95fe26e87ec013646c75a289c235003c7/node_modules/appium-xcuitest-driver/lib/commands/app-management.js:28:5)
2019-11-22 20:00:38:380 - [XCUITest]     at XCUITestDriver.installApp (/Users/bdouglas/build_tools/ios_phone/7090eac95fe26e87ec013646c75a289c235003c7/node_modules/appium-xcuitest-driver/lib/commands/app-management.js:90:3)
2019-11-22 20:00:39:499 - [debug] [W3C (a1a12684)] Encountered internal error running command: Error: Could not install app: 'Unexpected data: {"Error":"ApplicationVerificationFailed","ErrorDetail":-1,"ErrorDescription":"Failed to verify code signature of /private/var/installd/Library/Caches/com.apple.mobile.installd.staging/temp.DJ9Vig/extracted/TripAdvisor.app : 0xe8008017 (A signed resource has been added, modified, or deleted.)"}'
2019-11-22 20:00:39:500 - [debug] [W3C (a1a12684)]     at IOSDeploy.install (/Users/bdouglas/build_tools/ios_phone/7090eac95fe26e87ec013646c75a289c235003c7/node_modules/appium-xcuitest-driver/lib/ios-deploy.js:41:13)
2019-11-22 20:00:39:501 - [HTTP] <-- POST /wd/hub/session/a1a12684-68e8-4702-8dcd-d500fada7e09/appium/device/install_app 500 49841 ms - 1277