house_arrest.txt

2016-11-22 03:58:17:603 - [Appium] Welcome to Appium v1.6.0
2016-11-22 03:58:17:605 - [Appium] Non-default server args:
2016-11-22 03:58:17:606 - [Appium]   sessionOverride: true
2016-11-22 03:58:17:606 - [Appium]   logTimestamp: true
2016-11-22 03:58:17:606 - [Appium]   logNoColors: true
2016-11-22 03:58:17:607 - [Appium]   defaultCapabilities: { fullReset: true }
2016-11-22 03:58:17:608 - [Appium]   defaultCommandTimeout: 40000
2016-11-22 03:58:17:608 - [Appium] Default capabilities, which will be added to each request unless overridden by desired capabilities:
2016-11-22 03:58:17:618 - [Appium]   fullReset: true
2016-11-22 03:58:17:661 - [Appium] Appium REST http interface listener started on 0.0.0.0:4723
2016-11-22 03:58:35:482 - [HTTP] --> POST /wd/hub/session {"desiredCapabilities":{"app":"/Users/michael/applause/*********-4.3.0-10123-f3f5a3c-resigned.ipa","newCommandTimeout":300,"automationName":"XCUITest","bundleId":"com.*********","waitForAppScript":"$.delay(10000); true;","xcodeConfigFile":"/Users/michael/Applause/AppiumWDA.xcconfig","platformName":"iOS","udid":"f9147b5ef1cfe727f2ca61dc4d6cec8785afb118","deviceName":"foobar","autoAcceptAlerts":true}}
2016-11-22 03:58:35:485 - [MJSONWP] Calling AppiumDriver.createSession() with args: [{"app":"/Users/michael/app...
2016-11-22 03:58:35:490 - [Appium] Creating new XCUITestDriver session
2016-11-22 03:58:35:490 - [Appium] Capabilities:
2016-11-22 03:58:35:491 - [Appium]   app: '/Users/michael/applause/*********-4.3.0-10123-f3f5a3c-resigned.ipa'
2016-11-22 03:58:35:491 - [Appium]   newCommandTimeout: 300
2016-11-22 03:58:35:492 - [Appium]   automationName: 'XCUITest'
2016-11-22 03:58:35:492 - [Appium]   bundleId: 'com.*********'
2016-11-22 03:58:35:492 - [Appium]   waitForAppScript: '$.delay(10000); true;'
2016-11-22 03:58:35:493 - [Appium]   xcodeConfigFile: '/Users/michael/Applause/AppiumWDA.xcconfig'
2016-11-22 03:58:35:493 - [Appium]   platformName: 'iOS'
2016-11-22 03:58:35:493 - [Appium]   udid: 'f9147b5ef1cfe727f2ca61dc4d6cec8785afb118'
2016-11-22 03:58:35:493 - [Appium]   deviceName: 'foobar'
2016-11-22 03:58:35:493 - [Appium]   autoAcceptAlerts: true
2016-11-22 03:58:35:494 - [Appium]   fullReset: true
2016-11-22 03:58:35:496 - [debug] [XCUITest] XCUITestDriver version: 2.0.26
2016-11-22 03:58:35:503 - [BaseDriver] Session created with session id: 104c7838-f170-4629-800b-3bd2e1a6edb6
2016-11-22 03:58:35:557 - [debug] [XCUITest] Xcode version set to '8.2'
2016-11-22 03:58:35:565 - [debug] [XCUITest] iOS SDK Version set to '10.2'
2016-11-22 03:58:35:697 - [debug] [XCUITest] Available devices: f9147b5ef1cfe727f2ca61dc4d6cec8785afb118
2016-11-22 03:58:35:698 - [debug] [XCUITest] Creating iDevice object with udid f9147b5ef1cfe727f2ca61dc4d6cec8785afb118
2016-11-22 03:58:35:700 - [XCUITest] Determining device to run tests on: udid: 'f9147b5ef1cfe727f2ca61dc4d6cec8785afb118', real device: true
2016-11-22 03:58:35:701 - [BaseDriver] Using local app '/Users/michael/applause/*********-4.3.0-10123-f3f5a3c-resigned.ipa'
2016-11-22 03:58:35:702 - [debug] [BaseDriver] Copying local zip to tmp dir
2016-11-22 03:58:35:756 - [debug] [BaseDriver] Unzipping /var/folders/h1/hhhl70790x5292_zf3q7qhx00000gp/T/20161021-40366-j7vus4/appium-app.zip
2016-11-22 03:58:35:757 - [debug] [BaseDriver] Testing zip archive: /var/folders/h1/hhhl70790x5292_zf3q7qhx00000gp/T/20161021-40366-j7vus4/appium-app.zip
2016-11-22 03:58:36:343 - [BaseDriver] Unzipped local app to '/var/folders/h1/hhhl70790x5292_zf3q7qhx00000gp/T/20161021-40366-j7vus4/Payload/******.app'
2016-11-22 03:58:36:344 - [debug] [XCUITest] Checking whether app is actually present
2016-11-22 03:58:36:347 - [debug] [iOSLog] Attempting iOS device log capture via libimobiledevice idevicesyslog
2016-11-22 03:58:36:348 - [debug] [iOSLog] Found idevicesyslog: '/usr/local/bin/idevicesyslog'
2016-11-22 03:58:36:503 - [XCUITest] Setting up real device
2016-11-22 03:58:37:002 - [debug] [XCUITest] Stdout from app isInstalled check: [....] Waiting for iOS device to be connected
[....] Using iPad 4 'ipadipadipadipad' (f9147b5ef1cfe727f2ca61dc4d6cec8785afb118).
true

2016-11-22 03:58:37:003 - [debug] [XCUITest] App is installed.
2016-11-22 03:58:37:003 - [debug] [XCUITest] Full reset requested. Forcing app install after uninstall app
2016-11-22 03:58:38:358 - [debug] [XCUITest] app uninstall stdout : [....] Waiting for iOS device to be connected
[....] Using iPad 4 'ipadipadipadipad' (f9147b5ef1cfe727f2ca61dc4d6cec8785afb118).
------ Uninstall phase ------
[ OK ] Uninstalled package with bundle id com.*********

2016-11-22 03:58:47:846 - [debug] [XCUITest] app install stdout : [....] Waiting for iOS device to be connected
[....] Using iPad 4 'ipadipadipadipad' (f9147b5ef1cfe727f2ca61dc4d6cec8785afb118).
------ Uninstall phase ------
[ OK ] Uninstalled package with bundle id com.*********
------ Install phase ------
[  0%] Found iPad 4 'ipadipadipadipad' (f9147b5ef1cfe727f2ca61dc4d6cec8785afb118) connected through USB, beginning install
[ 52%] CreatingStagingDirectory
[ 57%] ExtractingPackage
[ 60%] InspectingPackage
[ 60%] TakingInstallLock
[ 65%] PreflightingApplication
[ 65%] InstallingEmbeddedProfile
[ 70%] VerifyingApplication
[ 75%] CreatingContainer
[ 80%] InstallingApplication
[ 85%] PostflightingApplication
[ 90%] SandboxingApplication
[ 95%] GeneratingApplicationMap
[100%] Installed package /var/folders/h1/hhhl70790x5292_zf3q7qhx00000gp/T/20161021-40366-j7vus4/Payload/******.app

2016-11-22 03:58:47:849 - [debug] [XCUITest] App installed.
2016-11-22 03:58:47:850 - [XCUITest] Using default agent: /usr/local/lib/node_modules/appium/node_modules/appium-xcuitest-driver/WebDriverAgent/WebDriverAgent.xcodeproj
2016-11-22 03:58:47:850 - [XCUITest] Using default bootstrap: /usr/local/lib/node_modules/appium/node_modules/appium-xcuitest-driver/WebDriverAgent
2016-11-22 03:58:47:851 - [XCUITest] Launching WebDriverAgent on the device
2016-11-22 03:58:47:899 - [debug] [XCUITest] Carthage found: /usr/local/bin/carthage
2016-11-22 03:58:47:901 - [debug] [XCUITest] Using Xcode configuration file: '/Users/michael/Applause/AppiumWDA.xcconfig'
2016-11-22 03:58:47:901 - [debug] [XCUITest] Beginning test with command '/usr/local/lib/node_modules/appium/node_modules/appium-xcuitest-driver/bin/run-xcodebuild.sh --project /usr/local/lib/node_modules/appium/node_modules/appium-xcuitest-driver/WebDriverAgent/WebDriverAgent.xcodeproj --scheme WebDriverAgentRunner --destination id=f9147b5ef1cfe727f2ca61dc4d6cec8785afb118 --xcode-config-file "/Users/michael/Applause/AppiumWDA.xcconfig"' in directory '/usr/local/lib/node_modules/appium/node_modules/appium-xcuitest-driver/WebDriverAgent'
2016-11-22 03:58:47:908 - [XCUITest] Waiting for WebDriverAgent to start on device
2016-11-22 03:58:53:291 - [debug] [XCUITest] Log file for xcodebuild test: /Users/michael/Library/Developer/Xcode/DerivedData/WebDriverAgent-brdadhpuduowllgivnnvuygpwhzy/Logs/Test/0B01AD59-9DD6-4BFB-A5B0-B73A5F8431F0/Session-WebDriverAgentRunner-2016-11-21_195853-aeGYO3.log
2016-11-22 03:59:01:349 - [debug] [XCUITest] Waiting for WebDriverAgent server to finish loading...
2016-11-22 03:59:03:747 - [debug] [WebDriverAgent] Device: Nov 21 19:59:03 ipadipadipadipad XCTRunner[224] <Notice>: Running tests...
2016-11-22 03:59:07:290 - [debug] [WebDriverAgent] Device: Nov 21 19:59:07 ipadipadipadipad XCTRunner[224] <Notice>: Continuing to run tests in the background with task ID 1
2016-11-22 03:59:08:797 - [debug] [XCUITest] Waiting for WebDriverAgent server to finish loading...
2016-11-22 03:59:09:228 - [XCUITest] Detected that WebDriverAgent is running at url 'http://192.168.1.232:8100'
2016-11-22 03:59:09:229 - [XCUITest] WebDriverAgent started at url 'http://192.168.1.232:8100'
2016-11-22 03:59:09:234 - [debug] [XCUITest] Starting iproxy to forward traffic from local port 8100 to device port 8100 over USB
2016-11-22 03:59:11:246 - [JSONWP Proxy] Proxying [POST /session] to [POST http://localhost:8100/session] with body: {"desiredCapabilities":{"bu...
2016-11-22 03:59:15:448 - [debug] [XCUITest] Waiting for WebDriverAgent server to finish loading...
2016-11-22 03:59:16:360 - [JSONWP Proxy] Got response with status 200: {"sessionId":"B82A9139-5C27...
2016-11-22 03:59:16:363 - [Appium] New XCUITestDriver session created successfully, session 104c7838-f170-4629-800b-3bd2e1a6edb6 added to master session list
2016-11-22 03:59:16:364 - [MJSONWP] Responding to client with driver.createSession() result: {"webStorageEnabled":false,...
2016-11-22 03:59:16:369 - [HTTP] <-- POST /wd/hub/session 200 40886 ms - 674 
2016-11-22 03:59:16:442 - [HTTP] --> POST /wd/hub/session/104c7838-f170-4629-800b-3bd2e1a6edb6/element {"using":"xpath","value":"//XCUIElementTypeButton[@label='To my books']"}
2016-11-22 03:59:16:444 - [MJSONWP] Calling AppiumDriver.findElement() with args: ["xpath","//XCUIElementType...
2016-11-22 03:59:16:445 - [debug] [XCUITest] Executing command 'findElement'
2016-11-22 03:59:16:446 - [debug] [BaseDriver] Valid locator strategies for this request: xpath, id, name, class name, -ios predicate string, accessibility id
2016-11-22 03:59:16:447 - [XCUITest] Rewrote incoming selector from '//XCUIElementTypeButton[@label='To my books']' to '//XCUIElementTypeButton[@label='To my books']' to match XCUI type. You should consider updating your tests to use the new selectors directly
2016-11-22 03:59:16:448 - [debug] [BaseDriver] Waiting up to 0 ms for condition
2016-11-22 03:59:16:449 - [JSONWP Proxy] Proxying [POST /element] to [POST http://localhost:8100/session/B82A9139-5C27-4EC5-A6FB-B37DB78BED40/element] with body: {"using":"xpath","value":"/...
2016-11-22 03:59:18:294 - [JSONWP Proxy] Got response with status 200: {"sessionId":"B82A9139-5C27...
2016-11-22 03:59:18:299 - [HTTP] <-- POST /wd/hub/session/104c7838-f170-4629-800b-3bd2e1a6edb6/element 500 1857 ms - 164 
2016-11-22 03:59:18:307 - [HTTP] --> GET /wd/hub/session/104c7838-f170-4629-800b-3bd2e1a6edb6/source {}
2016-11-22 03:59:18:308 - [MJSONWP] Calling AppiumDriver.getPageSource() with args: ["104c7838-f170-4629-800b-3...
2016-11-22 03:59:18:308 - [debug] [XCUITest] Executing command 'getPageSource'
2016-11-22 03:59:18:309 - [JSONWP Proxy] Proxying [POST /source] to [POST http://localhost:8100/session/B82A9139-5C27-4EC5-A6FB-B37DB78BED40/source] with no body
2016-11-22 03:59:19:017 - [JSONWP Proxy] Got response with status 200: "{\n  \"sessionId\" : \"B82...
2016-11-22 03:59:19:023 - [MJSONWP] Responding to client with driver.getPageSource() result: "<?xml version=\"1.0\" enco...
2016-11-22 03:59:19:025 - [HTTP] <-- GET /wd/hub/session/104c7838-f170-4629-800b-3bd2e1a6edb6/source 200 717 ms - 8858 
2016-11-22 03:59:19:031 - [HTTP] --> POST /wd/hub/session/104c7838-f170-4629-800b-3bd2e1a6edb6/element {"using":"xpath","value":"//XCUIElementTypeButton[@label='To my books']"}
2016-11-22 03:59:19:031 - [MJSONWP] Calling AppiumDriver.findElement() with args: ["xpath","//XCUIElementType...
2016-11-22 03:59:19:031 - [debug] [XCUITest] Executing command 'findElement'
2016-11-22 03:59:19:032 - [debug] [BaseDriver] Valid locator strategies for this request: xpath, id, name, class name, -ios predicate string, accessibility id
2016-11-22 03:59:19:033 - [XCUITest] Rewrote incoming selector from '//XCUIElementTypeButton[@label='To my books']' to '//XCUIElementTypeButton[@label='To my books']' to match XCUI type. You should consider updating your tests to use the new selectors directly
2016-11-22 03:59:19:033 - [debug] [BaseDriver] Waiting up to 0 ms for condition
2016-11-22 03:59:19:033 - [JSONWP Proxy] Proxying [POST /element] to [POST http://localhost:8100/session/B82A9139-5C27-4EC5-A6FB-B37DB78BED40/element] with body: {"using":"xpath","value":"/...
2016-11-22 03:59:20:053 - [debug] [XCUITest] Waiting for WebDriverAgent server to finish loading...
2016-11-22 03:59:20:531 - [JSONWP Proxy] Got response with status 200: {"sessionId":"B82A9139-5C27...
2016-11-22 03:59:20:533 - [HTTP] <-- POST /wd/hub/session/104c7838-f170-4629-800b-3bd2e1a6edb6/element 500 1502 ms - 164 
2016-11-22 03:59:20:538 - [HTTP] --> GET /wd/hub/session/104c7838-f170-4629-800b-3bd2e1a6edb6/source {}
2016-11-22 03:59:20:538 - [MJSONWP] Calling AppiumDriver.getPageSource() with args: ["104c7838-f170-4629-800b-3...
2016-11-22 03:59:20:539 - [debug] [XCUITest] Executing command 'getPageSource'
2016-11-22 03:59:20:540 - [JSONWP Proxy] Proxying [POST /source] to [POST http://localhost:8100/session/B82A9139-5C27-4EC5-A6FB-B37DB78BED40/source] with no body
2016-11-22 03:59:21:237 - [JSONWP Proxy] Got response with status 200: "{\n  \"sessionId\" : \"B82...
2016-11-22 03:59:21:241 - [MJSONWP] Responding to client with driver.getPageSource() result: "<?xml version=\"1.0\" enco...
2016-11-22 03:59:21:242 - [HTTP] <-- GET /wd/hub/session/104c7838-f170-4629-800b-3bd2e1a6edb6/source 200 704 ms - 9322 
2016-11-22 03:59:21:248 - [HTTP] --> POST /wd/hub/session/104c7838-f170-4629-800b-3bd2e1a6edb6/element {"using":"xpath","value":"//XCUIElementTypeButton[@label='To my books']"}
2016-11-22 03:59:21:249 - [MJSONWP] Calling AppiumDriver.findElement() with args: ["xpath","//XCUIElementType...
2016-11-22 03:59:21:250 - [debug] [XCUITest] Executing command 'findElement'
2016-11-22 03:59:21:250 - [debug] [BaseDriver] Valid locator strategies for this request: xpath, id, name, class name, -ios predicate string, accessibility id
2016-11-22 03:59:21:251 - [XCUITest] Rewrote incoming selector from '//XCUIElementTypeButton[@label='To my books']' to '//XCUIElementTypeButton[@label='To my books']' to match XCUI type. You should consider updating your tests to use the new selectors directly
2016-11-22 03:59:21:251 - [debug] [BaseDriver] Waiting up to 0 ms for condition
2016-11-22 03:59:21:251 - [JSONWP Proxy] Proxying [POST /element] to [POST http://localhost:8100/session/B82A9139-5C27-4EC5-A6FB-B37DB78BED40/element] with body: {"using":"xpath","value":"/...
2016-11-22 03:59:22:740 - [JSONWP Proxy] Got response with status 200: {"sessionId":"B82A9139-5C27...
2016-11-22 03:59:22:742 - [HTTP] <-- POST /wd/hub/session/104c7838-f170-4629-800b-3bd2e1a6edb6/element 500 1493 ms - 164 
2016-11-22 03:59:22:745 - [HTTP] --> GET /wd/hub/session/104c7838-f170-4629-800b-3bd2e1a6edb6/source {}
2016-11-22 03:59:22:746 - [MJSONWP] Calling AppiumDriver.getPageSource() with args: ["104c7838-f170-4629-800b-3...
2016-11-22 03:59:22:746 - [debug] [XCUITest] Executing command 'getPageSource'
2016-11-22 03:59:22:747 - [JSONWP Proxy] Proxying [POST /source] to [POST http://localhost:8100/session/B82A9139-5C27-4EC5-A6FB-B37DB78BED40/source] with no body
2016-11-22 03:59:23:401 - [JSONWP Proxy] Got response with status 200: "{\n  \"sessionId\" : \"B82...
2016-11-22 03:59:23:405 - [MJSONWP] Responding to client with driver.getPageSource() result: "<?xml version=\"1.0\" enco...
2016-11-22 03:59:23:405 - [HTTP] <-- GET /wd/hub/session/104c7838-f170-4629-800b-3bd2e1a6edb6/source 200 660 ms - 9322 
2016-11-22 03:59:23:411 - [HTTP] --> POST /wd/hub/session/104c7838-f170-4629-800b-3bd2e1a6edb6/element {"using":"xpath","value":"//XCUIElementTypeButton[@label='To my books']"}
2016-11-22 03:59:23:412 - [MJSONWP] Calling AppiumDriver.findElement() with args: ["xpath","//XCUIElementType...
2016-11-22 03:59:23:412 - [debug] [XCUITest] Executing command 'findElement'
2016-11-22 03:59:23:413 - [debug] [BaseDriver] Valid locator strategies for this request: xpath, id, name, class name, -ios predicate string, accessibility id
2016-11-22 03:59:23:413 - [XCUITest] Rewrote incoming selector from '//XCUIElementTypeButton[@label='To my books']' to '//XCUIElementTypeButton[@label='To my books']' to match XCUI type. You should consider updating your tests to use the new selectors directly
2016-11-22 03:59:23:413 - [debug] [BaseDriver] Waiting up to 0 ms for condition
2016-11-22 03:59:23:414 - [JSONWP Proxy] Proxying [POST /element] to [POST http://localhost:8100/session/B82A9139-5C27-4EC5-A6FB-B37DB78BED40/element] with body: {"using":"xpath","value":"/...
2016-11-22 03:59:24:916 - [JSONWP Proxy] Got response with status 200: {"sessionId":"B82A9139-5C27...
2016-11-22 03:59:24:920 - [HTTP] <-- POST /wd/hub/session/104c7838-f170-4629-800b-3bd2e1a6edb6/element 500 1508 ms - 164 
2016-11-22 03:59:24:924 - [HTTP] --> GET /wd/hub/session/104c7838-f170-4629-800b-3bd2e1a6edb6/source {}
2016-11-22 03:59:24:925 - [MJSONWP] Calling AppiumDriver.getPageSource() with args: ["104c7838-f170-4629-800b-3...
2016-11-22 03:59:24:925 - [debug] [XCUITest] Executing command 'getPageSource'
2016-11-22 03:59:24:926 - [JSONWP Proxy] Proxying [POST /source] to [POST http://localhost:8100/session/B82A9139-5C27-4EC5-A6FB-B37DB78BED40/source] with no body
2016-11-22 03:59:25:278 - [debug] [XCUITest] Waiting for WebDriverAgent server to finish loading...
2016-11-22 03:59:25:598 - [JSONWP Proxy] Got response with status 200: "{\n  \"sessionId\" : \"B82...
2016-11-22 03:59:25:604 - [MJSONWP] Responding to client with driver.getPageSource() result: "<?xml version=\"1.0\" enco...
2016-11-22 03:59:25:605 - [HTTP] <-- GET /wd/hub/session/104c7838-f170-4629-800b-3bd2e1a6edb6/source 200 680 ms - 9322 
2016-11-22 03:59:25:610 - [HTTP] --> POST /wd/hub/session/104c7838-f170-4629-800b-3bd2e1a6edb6/element {"using":"xpath","value":"//XCUIElementTypeButton[@label='To my books']"}
2016-11-22 03:59:25:610 - [MJSONWP] Calling AppiumDriver.findElement() with args: ["xpath","//XCUIElementType...
2016-11-22 03:59:25:611 - [debug] [XCUITest] Executing command 'findElement'
2016-11-22 03:59:25:611 - [debug] [BaseDriver] Valid locator strategies for this request: xpath, id, name, class name, -ios predicate string, accessibility id
2016-11-22 03:59:25:611 - [XCUITest] Rewrote incoming selector from '//XCUIElementTypeButton[@label='To my books']' to '//XCUIElementTypeButton[@label='To my books']' to match XCUI type. You should consider updating your tests to use the new selectors directly
2016-11-22 03:59:25:612 - [debug] [BaseDriver] Waiting up to 0 ms for condition
2016-11-22 03:59:25:612 - [JSONWP Proxy] Proxying [POST /element] to [POST http://localhost:8100/session/B82A9139-5C27-4EC5-A6FB-B37DB78BED40/element] with body: {"using":"xpath","value":"/...
2016-11-22 03:59:27:093 - [JSONWP Proxy] Got response with status 200: {"sessionId":"B82A9139-5C27...
2016-11-22 03:59:27:096 - [HTTP] <-- POST /wd/hub/session/104c7838-f170-4629-800b-3bd2e1a6edb6/element 500 1485 ms - 164 
2016-11-22 03:59:27:100 - [HTTP] --> GET /wd/hub/session/104c7838-f170-4629-800b-3bd2e1a6edb6/source {}
2016-11-22 03:59:27:100 - [MJSONWP] Calling AppiumDriver.getPageSource() with args: ["104c7838-f170-4629-800b-3...
2016-11-22 03:59:27:101 - [debug] [XCUITest] Executing command 'getPageSource'
2016-11-22 03:59:27:102 - [JSONWP Proxy] Proxying [POST /source] to [POST http://localhost:8100/session/B82A9139-5C27-4EC5-A6FB-B37DB78BED40/source] with no body
2016-11-22 03:59:27:764 - [JSONWP Proxy] Got response with status 200: "{\n  \"sessionId\" : \"B82...
2016-11-22 03:59:27:768 - [MJSONWP] Responding to client with driver.getPageSource() result: "<?xml version=\"1.0\" enco...
2016-11-22 03:59:27:768 - [HTTP] <-- GET /wd/hub/session/104c7838-f170-4629-800b-3bd2e1a6edb6/source 200 668 ms - 9322 
2016-11-22 03:59:27:773 - [HTTP] --> POST /wd/hub/session/104c7838-f170-4629-800b-3bd2e1a6edb6/element {"using":"xpath","value":"//XCUIElementTypeButton[@label='To my books']"}
2016-11-22 03:59:27:774 - [MJSONWP] Calling AppiumDriver.findElement() with args: ["xpath","//XCUIElementType...
2016-11-22 03:59:27:774 - [debug] [XCUITest] Executing command 'findElement'
2016-11-22 03:59:27:775 - [debug] [BaseDriver] Valid locator strategies for this request: xpath, id, name, class name, -ios predicate string, accessibility id
2016-11-22 03:59:27:776 - [XCUITest] Rewrote incoming selector from '//XCUIElementTypeButton[@label='To my books']' to '//XCUIElementTypeButton[@label='To my books']' to match XCUI type. You should consider updating your tests to use the new selectors directly
2016-11-22 03:59:27:776 - [debug] [BaseDriver] Waiting up to 0 ms for condition
2016-11-22 03:59:27:777 - [JSONWP Proxy] Proxying [POST /element] to [POST http://localhost:8100/session/B82A9139-5C27-4EC5-A6FB-B37DB78BED40/element] with body: {"using":"xpath","value":"/...
2016-11-22 03:59:29:261 - [JSONWP Proxy] Got response with status 200: {"sessionId":"B82A9139-5C27...
2016-11-22 03:59:29:263 - [HTTP] <-- POST /wd/hub/session/104c7838-f170-4629-800b-3bd2e1a6edb6/element 500 1489 ms - 164 
2016-11-22 03:59:29:267 - [HTTP] --> GET /wd/hub/session/104c7838-f170-4629-800b-3bd2e1a6edb6/source {}
2016-11-22 03:59:29:267 - [MJSONWP] Calling AppiumDriver.getPageSource() with args: ["104c7838-f170-4629-800b-3...
2016-11-22 03:59:29:268 - [debug] [XCUITest] Executing command 'getPageSource'
2016-11-22 03:59:29:269 - [JSONWP Proxy] Proxying [POST /source] to [POST http://localhost:8100/session/B82A9139-5C27-4EC5-A6FB-B37DB78BED40/source] with no body
2016-11-22 03:59:29:940 - [JSONWP Proxy] Got response with status 200: "{\n  \"sessionId\" : \"B82...
2016-11-22 03:59:29:943 - [MJSONWP] Responding to client with driver.getPageSource() result: "<?xml version=\"1.0\" enco...
2016-11-22 03:59:29:944 - [HTTP] <-- GET /wd/hub/session/104c7838-f170-4629-800b-3bd2e1a6edb6/source 200 677 ms - 9322 
2016-11-22 03:59:29:949 - [HTTP] --> POST /wd/hub/session/104c7838-f170-4629-800b-3bd2e1a6edb6/element {"using":"xpath","value":"//XCUIElementTypeButton[@label='To my books']"}
2016-11-22 03:59:29:950 - [MJSONWP] Calling AppiumDriver.findElement() with args: ["xpath","//XCUIElementType...
2016-11-22 03:59:29:950 - [debug] [XCUITest] Executing command 'findElement'
2016-11-22 03:59:29:952 - [debug] [BaseDriver] Valid locator strategies for this request: xpath, id, name, class name, -ios predicate string, accessibility id
2016-11-22 03:59:29:952 - [XCUITest] Rewrote incoming selector from '//XCUIElementTypeButton[@label='To my books']' to '//XCUIElementTypeButton[@label='To my books']' to match XCUI type. You should consider updating your tests to use the new selectors directly
2016-11-22 03:59:29:952 - [debug] [BaseDriver] Waiting up to 0 ms for condition
2016-11-22 03:59:29:953 - [JSONWP Proxy] Proxying [POST /element] to [POST http://localhost:8100/session/B82A9139-5C27-4EC5-A6FB-B37DB78BED40/element] with body: {"using":"xpath","value":"/...
2016-11-22 03:59:30:710 - [debug] [XCUITest] Waiting for WebDriverAgent server to finish loading...
2016-11-22 03:59:31:439 - [JSONWP Proxy] Got response with status 200: {"sessionId":"B82A9139-5C27...
2016-11-22 03:59:31:442 - [HTTP] <-- POST /wd/hub/session/104c7838-f170-4629-800b-3bd2e1a6edb6/element 500 1492 ms - 164 
2016-11-22 03:59:31:446 - [HTTP] --> GET /wd/hub/session/104c7838-f170-4629-800b-3bd2e1a6edb6/source {}
2016-11-22 03:59:31:447 - [MJSONWP] Calling AppiumDriver.getPageSource() with args: ["104c7838-f170-4629-800b-3...
2016-11-22 03:59:31:447 - [debug] [XCUITest] Executing command 'getPageSource'
2016-11-22 03:59:31:449 - [JSONWP Proxy] Proxying [POST /source] to [POST http://localhost:8100/session/B82A9139-5C27-4EC5-A6FB-B37DB78BED40/source] with no body
2016-11-22 03:59:32:115 - [JSONWP Proxy] Got response with status 200: "{\n  \"sessionId\" : \"B82...
2016-11-22 03:59:32:120 - [MJSONWP] Responding to client with driver.getPageSource() result: "<?xml version=\"1.0\" enco...
2016-11-22 03:59:32:121 - [HTTP] <-- GET /wd/hub/session/104c7838-f170-4629-800b-3bd2e1a6edb6/source 200 675 ms - 9322 
2016-11-22 03:59:32:129 - [HTTP] --> POST /wd/hub/session/104c7838-f170-4629-800b-3bd2e1a6edb6/element {"using":"xpath","value":"//XCUIElementTypeStaticText[@name='Welcome']"}
2016-11-22 03:59:32:130 - [MJSONWP] Calling AppiumDriver.findElement() with args: ["xpath","//XCUIElementType...
2016-11-22 03:59:32:130 - [debug] [XCUITest] Executing command 'findElement'
2016-11-22 03:59:32:131 - [debug] [BaseDriver] Valid locator strategies for this request: xpath, id, name, class name, -ios predicate string, accessibility id
2016-11-22 03:59:32:131 - [XCUITest] Rewrote incoming selector from '//XCUIElementTypeStaticText[@name='Welcome']' to '//XCUIElementTypeStaticText[@name='Welcome']' to match XCUI type. You should consider updating your tests to use the new selectors directly
2016-11-22 03:59:32:131 - [debug] [BaseDriver] Waiting up to 0 ms for condition
2016-11-22 03:59:32:132 - [JSONWP Proxy] Proxying [POST /element] to [POST http://localhost:8100/session/B82A9139-5C27-4EC5-A6FB-B37DB78BED40/element] with body: {"using":"xpath","value":"/...
2016-11-22 03:59:33:761 - [JSONWP Proxy] Got response with status 200: {"sessionId":"B82A9139-5C27...
2016-11-22 03:59:33:763 - [MJSONWP] Responding to client with driver.findElement() result: {"label":"Welcome","type":"...
2016-11-22 03:59:33:763 - [HTTP] <-- POST /wd/hub/session/104c7838-f170-4629-800b-3bd2e1a6edb6/element 200 1634 ms - 175 
2016-11-22 03:59:33:770 - [HTTP] --> GET /wd/hub/session/104c7838-f170-4629-800b-3bd2e1a6edb6/screenshot {}
2016-11-22 03:59:33:770 - [MJSONWP] Driver proxy active, passing request on via HTTP proxy
2016-11-22 03:59:33:771 - [debug] [XCUITest] Executing command 'proxyReqRes'
2016-11-22 03:59:33:772 - [JSONWP Proxy] Proxying [GET /wd/hub/session/104c7838-f170-4629-800b-3bd2e1a6edb6/screenshot] to [GET http://localhost:8100/session/B82A9139-5C27-4EC5-A6FB-B37DB78BED40/screenshot] with body: {}
2016-11-22 03:59:34:482 - [JSONWP Proxy] Got response with status 200: "{\n  \"sessionId\" : \"B82...
2016-11-22 03:59:34:484 - [JSONWP Proxy] Replacing sessionId B82A9139-5C27-4EC5-A6FB-B37DB78BED40 with 104c7838-f170-4629-800b-3bd2e1a6edb6
2016-11-22 03:59:34:487 - [HTTP] <-- GET /wd/hub/session/104c7838-f170-4629-800b-3bd2e1a6edb6/screenshot 200 717 ms - 162706 
2016-11-22 03:59:35:392 - [HTTP] --> GET /wd/hub/session/104c7838-f170-4629-800b-3bd2e1a6edb6/screenshot {}
2016-11-22 03:59:35:392 - [MJSONWP] Driver proxy active, passing request on via HTTP proxy
2016-11-22 03:59:35:394 - [debug] [XCUITest] Executing command 'proxyReqRes'
2016-11-22 03:59:35:395 - [JSONWP Proxy] Proxying [GET /wd/hub/session/104c7838-f170-4629-800b-3bd2e1a6edb6/screenshot] to [GET http://localhost:8100/session/B82A9139-5C27-4EC5-A6FB-B37DB78BED40/screenshot] with body: {}
2016-11-22 03:59:36:103 - [JSONWP Proxy] Got response with status 200: "{\n  \"sessionId\" : \"B82...
2016-11-22 03:59:36:106 - [JSONWP Proxy] Replacing sessionId B82A9139-5C27-4EC5-A6FB-B37DB78BED40 with 104c7838-f170-4629-800b-3bd2e1a6edb6
2016-11-22 03:59:36:108 - [HTTP] <-- GET /wd/hub/session/104c7838-f170-4629-800b-3bd2e1a6edb6/screenshot 200 715 ms - 162706 
2016-11-22 03:59:36:364 - [HTTP] --> POST /wd/hub/session/104c7838-f170-4629-800b-3bd2e1a6edb6/element {"using":"xpath","value":"//XCUIElementTypeTable[1]/XCUIElementTypeCell[2]/XCUIElementTypeStaticText[1]"}
2016-11-22 03:59:36:365 - [MJSONWP] Calling AppiumDriver.findElement() with args: ["xpath","//XCUIElementType...
2016-11-22 03:59:36:365 - [debug] [XCUITest] Executing command 'findElement'
2016-11-22 03:59:36:366 - [debug] [BaseDriver] Valid locator strategies for this request: xpath, id, name, class name, -ios predicate string, accessibility id
2016-11-22 03:59:36:366 - [XCUITest] Rewrote incoming selector from '//XCUIElementTypeTable[1]/XCUIElementTypeCell[2]/XCUIElementTypeStaticText[1]' to '//XCUIElementTypeTable[1]/XCUIElementTypeCell[2]/XCUIElementTypeStaticText[1]' to match XCUI type. You should consider updating your tests to use the new selectors directly
2016-11-22 03:59:36:367 - [debug] [BaseDriver] Waiting up to 0 ms for condition
2016-11-22 03:59:36:367 - [JSONWP Proxy] Proxying [POST /element] to [POST http://localhost:8100/session/B82A9139-5C27-4EC5-A6FB-B37DB78BED40/element] with body: {"using":"xpath","value":"/...
2016-11-22 03:59:38:387 - [JSONWP Proxy] Got response with status 200: {"sessionId":"B82A9139-5C27...
2016-11-22 03:59:38:388 - [MJSONWP] Responding to client with driver.findElement() result: {"label":"˃","type":"XCUIEl...
2016-11-22 03:59:38:389 - [HTTP] <-- POST /wd/hub/session/104c7838-f170-4629-800b-3bd2e1a6edb6/element 200 2024 ms - 170 
2016-11-22 03:59:38:396 - [HTTP] --> GET /wd/hub/session/104c7838-f170-4629-800b-3bd2e1a6edb6/element/6984FA1B-66C4-48F5-B38C-BC21859A044B/displayed {}
2016-11-22 03:59:38:397 - [MJSONWP] Driver proxy active, passing request on via HTTP proxy
2016-11-22 03:59:38:398 - [debug] [XCUITest] Executing command 'proxyReqRes'
2016-11-22 03:59:38:398 - [JSONWP Proxy] Proxying [GET /wd/hub/session/104c7838-f170-4629-800b-3bd2e1a6edb6/element/6984FA1B-66C4-48F5-B38C-BC21859A044B/displayed] to [GET http://localhost:8100/session/B82A9139-5C27-4EC5-A6FB-B37DB78BED40/element/6984FA1B-66C4-48F5-B38C-BC21859A044B/displayed] with body: {}
2016-11-22 03:59:39:001 - [JSONWP Proxy] Got response with status 200: "{\n  \"sessionId\" : \"B82...
2016-11-22 03:59:39:001 - [JSONWP Proxy] Replacing sessionId B82A9139-5C27-4EC5-A6FB-B37DB78BED40 with 104c7838-f170-4629-800b-3bd2e1a6edb6
2016-11-22 03:59:39:001 - [HTTP] <-- GET /wd/hub/session/104c7838-f170-4629-800b-3bd2e1a6edb6/element/6984FA1B-66C4-48F5-B38C-BC21859A044B/displayed 200 605 ms - 76 
2016-11-22 03:59:39:010 - [HTTP] --> POST /wd/hub/session/104c7838-f170-4629-800b-3bd2e1a6edb6/element {"using":"xpath","value":"//XCUIElementTypeTable[1]/XCUIElementTypeCell[2]/XCUIElementTypeStaticText[1]"}
2016-11-22 03:59:39:011 - [MJSONWP] Calling AppiumDriver.findElement() with args: ["xpath","//XCUIElementType...
2016-11-22 03:59:39:011 - [debug] [XCUITest] Executing command 'findElement'
2016-11-22 03:59:39:012 - [debug] [BaseDriver] Valid locator strategies for this request: xpath, id, name, class name, -ios predicate string, accessibility id
2016-11-22 03:59:39:012 - [XCUITest] Rewrote incoming selector from '//XCUIElementTypeTable[1]/XCUIElementTypeCell[2]/XCUIElementTypeStaticText[1]' to '//XCUIElementTypeTable[1]/XCUIElementTypeCell[2]/XCUIElementTypeStaticText[1]' to match XCUI type. You should consider updating your tests to use the new selectors directly
2016-11-22 03:59:39:012 - [debug] [BaseDriver] Waiting up to 0 ms for condition
2016-11-22 03:59:39:013 - [JSONWP Proxy] Proxying [POST /element] to [POST http://localhost:8100/session/B82A9139-5C27-4EC5-A6FB-B37DB78BED40/element] with body: {"using":"xpath","value":"/...
2016-11-22 03:59:40:062 - [debug] [XCUITest] Waiting for WebDriverAgent server to finish loading...
2016-11-22 03:59:41:065 - [JSONWP Proxy] Got response with status 200: {"sessionId":"B82A9139-5C27...
2016-11-22 03:59:41:066 - [MJSONWP] Responding to client with driver.findElement() result: {"label":"˃","type":"XCUIEl...
2016-11-22 03:59:41:067 - [HTTP] <-- POST /wd/hub/session/104c7838-f170-4629-800b-3bd2e1a6edb6/element 200 2057 ms - 170 
2016-11-22 03:59:41:173 - [HTTP] --> POST /wd/hub/session/104c7838-f170-4629-800b-3bd2e1a6edb6/element/67354436-480E-475A-AC81-E92EBA8329E5/click {"id":"67354436-480E-475A-AC81-E92EBA8329E5"}
2016-11-22 03:59:41:174 - [MJSONWP] Calling AppiumDriver.click() with args: ["67354436-480E-475A-AC81-E...
2016-11-22 03:59:41:175 - [debug] [XCUITest] Executing command 'click'
2016-11-22 03:59:41:178 - [JSONWP Proxy] Proxying [POST /uiaElement/67354436-480E-475A-AC81-E92EBA8329E5/scroll] to [POST http://localhost:8100/session/B82A9139-5C27-4EC5-A6FB-B37DB78BED40/uiaElement/67354436-480E-475A-AC81-E92EBA8329E5/scroll] with body: {"toVisible":true}
2016-11-22 03:59:42:218 - [JSONWP Proxy] Got response with status 200: {"sessionId":"B82A9139-5C27...
2016-11-22 03:59:42:219 - [JSONWP Proxy] Proxying [POST /element/67354436-480E-475A-AC81-E92EBA8329E5/click] to [POST http://localhost:8100/session/B82A9139-5C27-4EC5-A6FB-B37DB78BED40/element/67354436-480E-475A-AC81-E92EBA8329E5/click] with body: {}
2016-11-22 03:59:43:954 - [JSONWP Proxy] Got response with status 200: {"status":0,"id":"67354436-...
2016-11-22 03:59:43:955 - [MJSONWP] Responding to client with driver.click() result: ""
2016-11-22 03:59:43:955 - [HTTP] <-- POST /wd/hub/session/104c7838-f170-4629-800b-3bd2e1a6edb6/element/67354436-480E-475A-AC81-E92EBA8329E5/click 200 2782 ms - 74 
2016-11-22 03:59:43:961 - [HTTP] --> POST /wd/hub/session/104c7838-f170-4629-800b-3bd2e1a6edb6/element {"using":"xpath","value":"//XCUIElementTypeTable[1]"}
2016-11-22 03:59:43:961 - [MJSONWP] Calling AppiumDriver.findElement() with args: ["xpath","//XCUIElementType...
2016-11-22 03:59:43:962 - [debug] [XCUITest] Executing command 'findElement'
2016-11-22 03:59:43:962 - [debug] [BaseDriver] Valid locator strategies for this request: xpath, id, name, class name, -ios predicate string, accessibility id
2016-11-22 03:59:43:963 - [XCUITest] Rewrote incoming selector from '//XCUIElementTypeTable[1]' to '//XCUIElementTypeTable[1]' to match XCUI type. You should consider updating your tests to use the new selectors directly
2016-11-22 03:59:43:963 - [debug] [BaseDriver] Waiting up to 0 ms for condition
2016-11-22 03:59:43:964 - [JSONWP Proxy] Proxying [POST /element] to [POST http://localhost:8100/session/B82A9139-5C27-4EC5-A6FB-B37DB78BED40/element] with body: {"using":"xpath","value":"/...
2016-11-22 03:59:47:720 - [JSONWP Proxy] Got response with status 200: {"sessionId":"B82A9139-5C27...
2016-11-22 03:59:47:721 - [MJSONWP] Responding to client with driver.findElement() result: {"label":null,"type":"XCUIE...
2016-11-22 03:59:47:722 - [HTTP] <-- POST /wd/hub/session/104c7838-f170-4629-800b-3bd2e1a6edb6/element 200 3761 ms - 165 
2016-11-22 03:59:47:726 - [HTTP] --> GET /wd/hub/session/104c7838-f170-4629-800b-3bd2e1a6edb6/screenshot {}
2016-11-22 03:59:47:727 - [MJSONWP] Driver proxy active, passing request on via HTTP proxy
2016-11-22 03:59:47:728 - [debug] [XCUITest] Executing command 'proxyReqRes'
2016-11-22 03:59:47:728 - [JSONWP Proxy] Proxying [GET /wd/hub/session/104c7838-f170-4629-800b-3bd2e1a6edb6/screenshot] to [GET http://localhost:8100/session/B82A9139-5C27-4EC5-A6FB-B37DB78BED40/screenshot] with body: {}
2016-11-22 03:59:48:450 - [JSONWP Proxy] Got response with status 200: "{\n  \"sessionId\" : \"B82...
2016-11-22 03:59:48:451 - [JSONWP Proxy] Replacing sessionId B82A9139-5C27-4EC5-A6FB-B37DB78BED40 with 104c7838-f170-4629-800b-3bd2e1a6edb6
2016-11-22 03:59:48:453 - [HTTP] <-- GET /wd/hub/session/104c7838-f170-4629-800b-3bd2e1a6edb6/screenshot 200 726 ms - 127066 
2016-11-22 04:00:12:241 - [HTTP] --> POST /wd/hub/session {"desiredCapabilities":{"app":"/Users/michael/applause/*********-4.3.0-10123-f3f5a3c-resigned.ipa","newCommandTimeout":300,"automationName":"XCUITest","bundleId":"com.*********","waitForAppScript":"$.delay(10000); true;","xcodeConfigFile":"/Users/michael/Applause/AppiumWDA.xcconfig","platformName":"iOS","udid":"f9147b5ef1cfe727f2ca61dc4d6cec8785afb118","deviceName":"foobar","autoAcceptAlerts":true}}
2016-11-22 04:00:12:243 - [MJSONWP] Calling AppiumDriver.createSession() with args: [{"app":"/Users/michael/app...
2016-11-22 04:00:12:245 - [Appium] Creating new XCUITestDriver session
2016-11-22 04:00:12:246 - [Appium] Capabilities:
2016-11-22 04:00:12:247 - [Appium]   app: '/Users/michael/applause/*********-4.3.0-10123-f3f5a3c-resigned.ipa'
2016-11-22 04:00:12:248 - [Appium]   newCommandTimeout: 300
2016-11-22 04:00:12:249 - [Appium]   automationName: 'XCUITest'
2016-11-22 04:00:12:249 - [Appium]   bundleId: 'com.*********'
2016-11-22 04:00:12:249 - [Appium]   waitForAppScript: '$.delay(10000); true;'
2016-11-22 04:00:12:250 - [Appium]   xcodeConfigFile: '/Users/michael/Applause/AppiumWDA.xcconfig'
2016-11-22 04:00:12:250 - [Appium]   platformName: 'iOS'
2016-11-22 04:00:12:250 - [Appium]   udid: 'f9147b5ef1cfe727f2ca61dc4d6cec8785afb118'
2016-11-22 04:00:12:251 - [Appium]   deviceName: 'foobar'
2016-11-22 04:00:12:251 - [Appium]   autoAcceptAlerts: true
2016-11-22 04:00:12:252 - [Appium]   fullReset: true
2016-11-22 04:00:12:252 - [Appium] Session override is on. Deleting other sessions.
2016-11-22 04:00:12:252 - [Appium]     Deleting session '104c7838-f170-4629-800b-3bd2e1a6edb6'
2016-11-22 04:00:12:254 - [JSONWP Proxy] Proxying [DELETE /session/104c7838-f170-4629-800b-3bd2e1a6edb6] to [DELETE http://localhost:8100/session/B82A9139-5C27-4EC5-A6FB-B37DB78BED40] with no body
2016-11-22 04:00:13:004 - [debug] [XCUITest] Waiting for WebDriverAgent server to finish loading...
2016-11-22 04:00:13:253 - [JSONWP Proxy] Got response with status 200: "{\n  \"sessionId\" : \"05E...
2016-11-22 04:00:13:254 - [XCUITest] Shutting down WebDriverAgent
2016-11-22 04:00:13:258 - [XCUITest] xcodebuild exited with code 'null' and signal 'SIGTERM'
2016-11-22 04:00:13:259 - [XCUITest] System log exited with code '0'
2016-11-22 04:00:23:262 - [debug] [XCUITest] WebDriverAgent process did not end in a timely fashion. Sending SIGHUP signal...
2016-11-22 04:00:23:265 - [debug] [XCUITest] Running ios real device reset flow
2016-11-22 04:00:23:265 - [debug] [XCUITest] Full reset requested. Will try to uninstall the app 'com.*********'.
2016-11-22 04:00:24:020 - [debug] [XCUITest] app uninstall stdout : [....] Waiting for iOS device to be connected
[....] Using iPad 4 'ipadipadipadipad' (f9147b5ef1cfe727f2ca61dc4d6cec8785afb118).
------ Uninstall phase ------
[ OK ] Uninstalled package with bundle id com.*********

2016-11-22 04:00:24:020 - [debug] [XCUITest] Removed 'com.*********'
2016-11-22 04:00:24:021 - [debug] [XCUITest] Resetting simulator
2016-11-22 04:00:24:021 - [debug] [iOSLog] Stopping iOS log capture
2016-11-22 04:00:24:022 - [Appium] Removing session 104c7838-f170-4629-800b-3bd2e1a6edb6 from our master session list
2016-11-22 04:00:24:025 - [debug] [XCUITest] XCUITestDriver version: 2.0.26
2016-11-22 04:00:24:028 - [BaseDriver] Session created with session id: 6badaab4-a564-4869-93c3-c94520035d8c
2016-11-22 04:00:24:029 - [debug] [XCUITest] Xcode version set to '8.2'
2016-11-22 04:00:24:031 - [debug] [XCUITest] iOS SDK Version set to '10.2'
2016-11-22 04:00:24:163 - [debug] [XCUITest] Available devices: f9147b5ef1cfe727f2ca61dc4d6cec8785afb118
2016-11-22 04:00:24:164 - [debug] [XCUITest] Creating iDevice object with udid f9147b5ef1cfe727f2ca61dc4d6cec8785afb118
2016-11-22 04:00:24:165 - [XCUITest] Determining device to run tests on: udid: 'f9147b5ef1cfe727f2ca61dc4d6cec8785afb118', real device: true
2016-11-22 04:00:24:165 - [BaseDriver] Using local app '/Users/michael/applause/*********-4.3.0-10123-f3f5a3c-resigned.ipa'
2016-11-22 04:00:24:165 - [debug] [BaseDriver] Copying local zip to tmp dir
2016-11-22 04:00:24:209 - [debug] [BaseDriver] Unzipping /var/folders/h1/hhhl70790x5292_zf3q7qhx00000gp/T/20161021-40366-ynd0in/appium-app.zip
2016-11-22 04:00:24:209 - [debug] [BaseDriver] Testing zip archive: /var/folders/h1/hhhl70790x5292_zf3q7qhx00000gp/T/20161021-40366-ynd0in/appium-app.zip
2016-11-22 04:00:24:816 - [BaseDriver] Unzipped local app to '/var/folders/h1/hhhl70790x5292_zf3q7qhx00000gp/T/20161021-40366-ynd0in/Payload/******.app'
2016-11-22 04:00:24:817 - [debug] [XCUITest] Checking whether app is actually present
2016-11-22 04:00:24:818 - [debug] [iOSLog] Attempting iOS device log capture via libimobiledevice idevicesyslog
2016-11-22 04:00:24:818 - [debug] [iOSLog] Found idevicesyslog: '/usr/local/bin/idevicesyslog'
2016-11-22 04:00:25:016 - [XCUITest] Setting up real device
2016-11-22 04:00:25:701 - [debug] [XCUITest] Error checking install status: Command 'ios-deploy --exists --id f9147b5ef1cfe727f2ca61dc4d6cec8785afb118 --bundle_id com.*********' exited with code 255
2016-11-22 04:00:25:701 - [debug] [XCUITest] App is not installed. Will try to install.
2016-11-22 04:00:29:779 - [debug] [XCUITest] app install stdout : [....] Waiting for iOS device to be connected
[....] Using iPad 4 'ipadipadipadipad' (f9147b5ef1cfe727f2ca61dc4d6cec8785afb118).
------ Uninstall phase ------
[ OK ] Uninstalled package with bundle id com.*********
------ Install phase ------
[  0%] Found iPad 4 'ipadipadipadipad' (f9147b5ef1cfe727f2ca61dc4d6cec8785afb118) connected through USB, beginning install
[ 52%] CreatingStagingDirectory
[ 57%] ExtractingPackage
[ 60%] InspectingPackage
[ 60%] TakingInstallLock
[ 65%] PreflightingApplication
[ 65%] InstallingEmbeddedProfile
[ 70%] VerifyingApplication
[ 75%] CreatingContainer
[ 80%] InstallingApplication
[ 85%] PostflightingApplication
[ 90%] SandboxingApplication
[ 95%] GeneratingApplicationMap
[100%] Installed package /var/folders/h1/hhhl70790x5292_zf3q7qhx00000gp/T/20161021-40366-ynd0in/Payload/******.app

2016-11-22 04:00:29:782 - [debug] [XCUITest] App installed.
2016-11-22 04:00:29:782 - [XCUITest] Using default agent: /usr/local/lib/node_modules/appium/node_modules/appium-xcuitest-driver/WebDriverAgent/WebDriverAgent.xcodeproj
2016-11-22 04:00:29:782 - [XCUITest] Using default bootstrap: /usr/local/lib/node_modules/appium/node_modules/appium-xcuitest-driver/WebDriverAgent
2016-11-22 04:00:29:783 - [XCUITest] Launching WebDriverAgent on the device
2016-11-22 04:00:29:828 - [XCUITest] killing xcodebuild process: 40385
2016-11-22 04:00:29:832 - [debug] [XCUITest] Carthage found: /usr/local/bin/carthage
2016-11-22 04:00:29:834 - [debug] [XCUITest] Using Xcode configuration file: '/Users/michael/Applause/AppiumWDA.xcconfig'
2016-11-22 04:00:29:835 - [debug] [XCUITest] Beginning test with command '/usr/local/lib/node_modules/appium/node_modules/appium-xcuitest-driver/bin/run-xcodebuild.sh --project /usr/local/lib/node_modules/appium/node_modules/appium-xcuitest-driver/WebDriverAgent/WebDriverAgent.xcodeproj --scheme WebDriverAgentRunner --destination id=f9147b5ef1cfe727f2ca61dc4d6cec8785afb118 --xcode-config-file "/Users/michael/Applause/AppiumWDA.xcconfig"' in directory '/usr/local/lib/node_modules/appium/node_modules/appium-xcuitest-driver/WebDriverAgent'
2016-11-22 04:00:29:841 - [XCUITest] Waiting for WebDriverAgent to start on device
2016-11-22 04:00:35:298 - [debug] [XCUITest] Log file for xcodebuild test: /Users/michael/Library/Developer/Xcode/DerivedData/WebDriverAgent-brdadhpuduowllgivnnvuygpwhzy/Logs/Test/1F1E544B-BD05-4014-8360-6609014DABCE/Session-WebDriverAgentRunner-2016-11-21_200035-euMpz0.log
2016-11-22 04:00:41:987 - [debug] [XCUITest] Waiting for WebDriverAgent server to finish loading...
2016-11-22 04:00:45:658 - [debug] [WebDriverAgent] Device: Nov 21 20:00:45 ipadipadipadipad XCTRunner[232] <Notice>: Running tests...
2016-11-22 04:00:47:017 - [debug] [XCUITest] Waiting for WebDriverAgent server to finish loading...
2016-11-22 04:00:47:375 - [debug] [WebDriverAgent] Device: Nov 21 20:00:47 ipadipadipadipad XCTRunner[232] <Notice>: Continuing to run tests in the background with task ID 1
2016-11-22 04:00:48:424 - [XCUITest] Detected that WebDriverAgent is running at url 'http://192.168.1.232:8100'
2016-11-22 04:00:48:424 - [XCUITest] WebDriverAgent started at url 'http://192.168.1.232:8100'
2016-11-22 04:00:48:425 - [debug] [XCUITest] Starting iproxy to forward traffic from local port 8100 to device port 8100 over USB
2016-11-22 04:00:50:434 - [JSONWP Proxy] Proxying [POST /session] to [POST http://localhost:8100/session] with body: {"desiredCapabilities":{"bu...
2016-11-22 04:00:53:923 - [debug] [XCUITest] Waiting for WebDriverAgent server to finish loading...
2016-11-22 04:00:55:168 - [JSONWP Proxy] Got response with status 200: {"sessionId":"6A6B387A-0E90...
2016-11-22 04:00:55:170 - [Appium] New XCUITestDriver session created successfully, session 6badaab4-a564-4869-93c3-c94520035d8c added to master session list
2016-11-22 04:00:55:172 - [MJSONWP] Responding to client with driver.createSession() result: {"webStorageEnabled":false,...
2016-11-22 04:00:55:174 - [HTTP] <-- POST /wd/hub/session 200 42933 ms - 674 
2016-11-22 04:00:55:250 - [HTTP] --> POST /wd/hub/session/6badaab4-a564-4869-93c3-c94520035d8c/element {"using":"xpath","value":"//XCUIElementTypeButton[@label='To my books']"}
2016-11-22 04:00:55:250 - [MJSONWP] Calling AppiumDriver.findElement() with args: ["xpath","//XCUIElementType...
2016-11-22 04:00:55:250 - [debug] [XCUITest] Executing command 'findElement'
2016-11-22 04:00:55:251 - [debug] [BaseDriver] Valid locator strategies for this request: xpath, id, name, class name, -ios predicate string, accessibility id
2016-11-22 04:00:55:251 - [XCUITest] Rewrote incoming selector from '//XCUIElementTypeButton[@label='To my books']' to '//XCUIElementTypeButton[@label='To my books']' to match XCUI type. You should consider updating your tests to use the new selectors directly
2016-11-22 04:00:55:252 - [debug] [BaseDriver] Waiting up to 0 ms for condition
2016-11-22 04:00:55:252 - [JSONWP Proxy] Proxying [POST /element] to [POST http://localhost:8100/session/6A6B387A-0E90-4800-A5BF-15C9F560AFE2/element] with body: {"using":"xpath","value":"/...
2016-11-22 04:00:56:928 - [JSONWP Proxy] Got response with status 200: {"sessionId":"6A6B387A-0E90...
2016-11-22 04:00:56:930 - [HTTP] <-- POST /wd/hub/session/6badaab4-a564-4869-93c3-c94520035d8c/element 500 1680 ms - 164 
2016-11-22 04:00:56:938 - [HTTP] --> GET /wd/hub/session/6badaab4-a564-4869-93c3-c94520035d8c/source {}
2016-11-22 04:00:56:938 - [MJSONWP] Calling AppiumDriver.getPageSource() with args: ["6badaab4-a564-4869-93c3-c...
2016-11-22 04:00:56:940 - [debug] [XCUITest] Executing command 'getPageSource'
2016-11-22 04:00:56:940 - [JSONWP Proxy] Proxying [POST /source] to [POST http://localhost:8100/session/6A6B387A-0E90-4800-A5BF-15C9F560AFE2/source] with no body
2016-11-22 04:00:57:598 - [JSONWP Proxy] Got response with status 200: "{\n  \"sessionId\" : \"6A6...
2016-11-22 04:00:57:602 - [MJSONWP] Responding to client with driver.getPageSource() result: "<?xml version=\"1.0\" enco...
2016-11-22 04:00:57:603 - [HTTP] <-- GET /wd/hub/session/6badaab4-a564-4869-93c3-c94520035d8c/source 200 665 ms - 8858 
2016-11-22 04:00:57:608 - [HTTP] --> POST /wd/hub/session/6badaab4-a564-4869-93c3-c94520035d8c/element {"using":"xpath","value":"//XCUIElementTypeButton[@label='To my books']"}
2016-11-22 04:00:57:609 - [MJSONWP] Calling AppiumDriver.findElement() with args: ["xpath","//XCUIElementType...
2016-11-22 04:00:57:609 - [debug] [XCUITest] Executing command 'findElement'
2016-11-22 04:00:57:610 - [debug] [BaseDriver] Valid locator strategies for this request: xpath, id, name, class name, -ios predicate string, accessibility id
2016-11-22 04:00:57:610 - [XCUITest] Rewrote incoming selector from '//XCUIElementTypeButton[@label='To my books']' to '//XCUIElementTypeButton[@label='To my books']' to match XCUI type. You should consider updating your tests to use the new selectors directly
2016-11-22 04:00:57:610 - [debug] [BaseDriver] Waiting up to 0 ms for condition
2016-11-22 04:00:57:611 - [JSONWP Proxy] Proxying [POST /element] to [POST http://localhost:8100/session/6A6B387A-0E90-4800-A5BF-15C9F560AFE2/element] with body: {"using":"xpath","value":"/...
2016-11-22 04:00:57:688 - [debug] [XCUITest] Waiting for WebDriverAgent server to finish loading...
2016-11-22 04:00:58:069 - [HTTP] <-- POST /wd/hub/session/6badaab4-a564-4869-93c3-c94520035d8c/element - - ms - - 
2016-11-22 04:00:59:102 - [JSONWP Proxy] Got response with status 200: {"sessionId":"6A6B387A-0E90...