Appium Log Output - Coordinates on page

appium-coordinates-bug.txt

Launching Appium with command: /usr/local/bin/appium --pre-launch --debug-log-spacing --log-timestamp --platform-version "9.3" --platform-name "iOS" --safari --browser-name "Safari" --udid "bbd3a999b6e882b312c5de660f2ffea333fdc0bb" --default-device --launch-timeout "90000"

2016-09-05 08:50:30:852 - [Appium] Welcome to Appium v1.5.3 (REV cc10c632a8c2803b7e62a37c9490beca8fb48128)

2016-09-05 08:50:30:855 - [Appium] Non-default server args:

2016-09-05 08:50:30:856 - [Appium]   launch: true

2016-09-05 08:50:30:856 - [Appium]   logTimestamp: true
2016-09-05 08:50:30:857 - [Appium]   safari: true
2016-09-05 08:50:30:857 - [Appium]   defaultDevice: true
2016-09-05 08:50:30:857 - [Appium]   debugLogSpacing: true
2016-09-05 08:50:30:857 - [Appium]   platformName: 'iOS'
2016-09-05 08:50:30:858 - [Appium]   platformVersion: '9.3'
2016-09-05 08:50:30:858 - [Appium]   browserName: 'Safari'
2016-09-05 08:50:30:859 - [Appium]   udid: 'bbd3a999b6e882b312c5de660f2ffea333fdc0bb'

2016-09-05 08:50:30:860 - [Appium] Deprecated server args:
2016-09-05 08:50:30:860 - [Appium]   --platform-name => --default-capabilities '{"platformName":"iOS"}'
2016-09-05 08:50:30:860 - [Appium]   --platform-version => --default-capabilities '{"platformVersion":"9.3"}'
2016-09-05 08:50:30:860 - [Appium]   --browser-name => --default-capabilities '{"browserName":"Safari"}'
2016-09-05 08:50:30:861 - [Appium]   -U,--udid => --default-capabilities '{"udid":"bbd3a999b6e882b312c5de660f2ffea333fdc0bb"}'
2016-09-05 08:50:30:861 - [Appium] Default capabilities, which will be added to each request unless overridden by desired capabilities:

2016-09-05 08:50:30:874 - [Appium]   platformName: 'iOS'

2016-09-05 08:50:30:874 - [Appium]   platformVersion: '9.3'
2016-09-05 08:50:30:874 - [Appium]   browserName: 'Safari'

2016-09-05 08:50:30:874 - [Appium]   udid: 'bbd3a999b6e882b312c5de660f2ffea333fdc0bb'

2016-09-05 08:50:30:933 - [Appium] Appium REST http interface listener started on 0.0.0.0:4723

2016-09-05 08:50:31:889 - [HTTP] --> GET /wd/hub/status {}

2016-09-05 08:50:31:892 - [MJSONWP] Calling AppiumDriver.getStatus() with args: []

2016-09-05 08:50:31:906 - [MJSONWP] Responding to client with driver.getStatus() result: {"build":{"version":"1.5.3"...

2016-09-05 08:50:31:915 - [HTTP] <-- GET /wd/hub/status 200 21 ms - 121

2016-09-05 08:50:33:354 - [HTTP] --> POST /wd/hub/session {"desiredCapabilities":{"browserName":"Safari","platformName":"iOS","version":"9.3","deviceName":"iPhone 4s","platform":"MAC"}}

2016-09-05 08:50:33:355 - [MJSONWP] Calling AppiumDriver.createSession() with args: [{"browserName":"Safari","p...

2016-09-05 08:50:33:357 - [Appium] Creating new IosDriver session
2016-09-05 08:50:33:357 - [Appium] Capabilities:

2016-09-05 08:50:33:358 - [Appium]   browserName: 'Safari'
2016-09-05 08:50:33:358 - [Appium]   platformName: 'iOS'
2016-09-05 08:50:33:358 - [Appium]   version: '9.3'
2016-09-05 08:50:33:359 - [Appium]   deviceName: 'iPhone 4s'
2016-09-05 08:50:33:359 - [Appium]   platform: 'MAC'
2016-09-05 08:50:33:359 - [Appium]   platformVersion: '9.3'
2016-09-05 08:50:33:359 - [Appium]   udid: 'bbd3a999b6e882b312c5de660f2ffea333fdc0bb'

2016-09-05 08:50:33:367 - [BaseDriver] The following capabilities were provided, but are not recognized by appium: version, platform.

2016-09-05 08:50:33:369 - [BaseDriver] Session created with session id: d47c3437-7512-447c-bb71-9e18cd29d3a7

2016-09-05 08:50:33:370 - [debug] [iOS] Not auto-detecting udid.

2016-09-05 08:50:33:372 - [debug] [iOS] Creating iDevice object with udid bbd3a999b6e882b312c5de660f2ffea333fdc0bb

2016-09-05 08:50:35:267 - [debug] [iOS] Removing any remaining instruments sockets

2016-09-05 08:50:35:271 - [debug] [iOS] Cleaned up instruments socket /var/folders/v7/d4fvcxfx4_qbmv6fqk1z7bnr0000gn/T/instruments_sock

2016-09-05 08:50:35:272 - [debug] [iOS] No language specified. Using default strings
2016-09-05 08:50:35:275 - [debug] [iOS] Strings file not found. Looking in 'en.lproj' directory
2016-09-05 08:50:35:275 - [iOS] Could not file localizable strings file 'Localizable.strings'!
2016-09-05 08:50:35:276 - [debug] [iOS] Creating instruments
2016-09-05 08:50:35:277 - [debug] [UIAuto] Preparing bootstrap code
2016-09-05 08:50:35:277 - [debug] [UIAuto] Dynamic bootstrap dir: /Users/workray/Library/Application Support/appium/bootstrap
2016-09-05 08:50:35:278 - [debug] [UIAuto] Dynamic env: {"nodePath":"/usr/local/bin/node","commandProxyClientPath":"/usr/local/lib/node_modules/appium/node_modules/appium-ios-driver/node_modules/appium-uiauto/build/lib/bin/command-proxy-client.js","instrumentsSock":"/var/folders/v7/d4fvcxfx4_qbmv6fqk1z7bnr0000gn/T/instruments_sock","interKeyDelay":null,"justLoopInfinitely":false,"autoAcceptAlerts":false,"autoDismissAlerts":false,"sendKeyStrategy":"grouped"}

2016-09-05 08:50:35:449 - [debug] [UIAuto] Dynamic bootstrap code: // This file is automatically generated. Do not manually modify!
...

2016-09-05 08:50:35:449 - [debug] [UIAuto] Dynamic bootstrap path: /Users/workray/Library/Application Support/appium/bootstrap/bootstrap-e1aaef5fbb1f0b1b.js

2016-09-05 08:50:35:464 - [debug] [UIAuto] Reusing dynamic bootstrap: /Users/workray/Library/Application Support/appium/bootstrap/bootstrap-e1aaef5fbb1f0b1b.js

2016-09-05 08:50:35:466 - [debug] [iOS] Running ios real device reset flow
2016-09-05 08:50:35:469 - [debug] [iOSLog] Attempting iOS device log capture via libimobiledevice idevicesyslog

2016-09-05 08:50:35:480 - [debug] [iOSLog] Found idevicesyslog: '/usr/local/bin/idevicesyslog'

2016-09-05 08:50:36:268 - [HTTP] --> GET /wd/hub/status {}

2016-09-05 08:50:36:269 - [MJSONWP] Calling AppiumDriver.getStatus() with args: []

2016-09-05 08:50:36:340 - [HTTP] --> GET /wd/hub/status {}

2016-09-05 08:50:36:341 - [MJSONWP] Calling AppiumDriver.getStatus() with args: []

2016-09-05 08:50:37:268 - [HTTP] <-- GET /wd/hub/status - - ms - -

2016-09-05 08:50:37:339 - [HTTP] <-- GET /wd/hub/status - - ms - -

2016-09-05 08:50:37:427 - [debug] [iOS] App is installed.

2016-09-05 08:50:37:427 - [debug] [iOS] fullReset not requested. No need to install.
2016-09-05 08:50:37:428 - [debug] [iOS] Starting UIAutoClient, and launching Instruments.

2016-09-05 08:50:37:452 - [debug] [UIAuto] Instruments socket server started at /var/folders/v7/d4fvcxfx4_qbmv6fqk1z7bnr0000gn/T/instruments_sock

2016-09-05 08:50:37:508 - [debug] [Instruments] Instruments is at: /Applications/Xcode.app/Contents/Developer/usr/bin/instruments

2016-09-05 08:50:37:508 - [debug] [Instruments] Attempting to launch instruments, this is try #1
2016-09-05 08:50:37:509 - [Instruments] Launching instruments

2016-09-05 08:50:37:522 - [debug] [Instruments] Attempting to run app on real device with UDID 'bbd3a999b6e882b312c5de660f2ffea333fdc0bb'
2016-09-05 08:50:37:523 - [debug] [Instruments] Found Insruments-Without-Delay: /usr/local/lib/node_modules/appium/node_modules/appium-ios-driver/node_modules/appium-instruments/thirdparty/iwd7
2016-09-05 08:50:37:524 - [debug] [Instruments] Spawning instruments with command: '/Applications/Xcode.app/Contents/Developer/usr/bin/instruments -t /Applications/Xcode.app/Contents/Applications/Instruments.app/Contents/PlugIns/AutomationInstrument.xrplugin/Contents/Resources/Automation.tracetemplate -D /var/folders/v7/d4fvcxfx4_qbmv6fqk1z7bnr0000gn/T/appium-instruments/instrumentscli0.trace -w bbd3a999b6e882b312c5de660f2ffea333fdc0bb com.bytearc.SafariLauncher -e UIASCRIPT "/Users/workray/Library/Application Support/appium/bootstrap/bootstrap-e1aaef5fbb1f0b1b.js" -e UIARESULTSPATH /var/folders/v7/d4fvcxfx4_qbmv6fqk1z7bnr0000gn/T/appium-instruments'
2016-09-05 08:50:37:524 - [debug] [Instruments] And extra without-delay env: {}
2016-09-05 08:50:37:524 - [debug] [Instruments] And launch timeouts (in ms): {"global":90000}

2016-09-05 08:50:38:340 - [HTTP] --> GET /wd/hub/status {}

2016-09-05 08:50:38:340 - [MJSONWP] Calling AppiumDriver.getStatus() with args: []

2016-09-05 08:50:38:411 - [HTTP] --> GET /wd/hub/status {}

2016-09-05 08:50:38:411 - [MJSONWP] Calling AppiumDriver.getStatus() with args: []

2016-09-05 08:50:39:339 - [HTTP] <-- GET /wd/hub/status - - ms - -

2016-09-05 08:50:39:410 - [HTTP] <-- GET /wd/hub/status - - ms - -

2016-09-05 08:50:40:410 - [HTTP] --> GET /wd/hub/status {}

2016-09-05 08:50:40:410 - [MJSONWP] Calling AppiumDriver.getStatus() with args: []

2016-09-05 08:50:40:486 - [HTTP] --> GET /wd/hub/status {}

2016-09-05 08:50:40:486 - [MJSONWP] Calling AppiumDriver.getStatus() with args: []

2016-09-05 08:50:40:937 - [debug] [Instruments] [INST STDERR] 2016-09-05 09:50:40.936 instruments[53611:986374] WebKit Threading Violation - initial use of WebKit from a secondary thread.

2016-09-05 08:50:41:409 - [HTTP] <-- GET /wd/hub/status - - ms - -

2016-09-05 08:50:41:481 - [HTTP] <-- GET /wd/hub/status - - ms - -

2016-09-05 08:50:42:471 - [HTTP] --> GET /wd/hub/status {}

2016-09-05 08:50:42:472 - [MJSONWP] Calling AppiumDriver.getStatus() with args: []

2016-09-05 08:50:42:555 - [HTTP] --> GET /wd/hub/status {}

2016-09-05 08:50:42:556 - [MJSONWP] Calling AppiumDriver.getStatus() with args: []

2016-09-05 08:50:43:057 - [HTTP] --> GET /wd/hub/status {}

2016-09-05 08:50:43:058 - [MJSONWP] Calling AppiumDriver.getStatus() with args: []

2016-09-05 08:50:43:471 - [HTTP] <-- GET /wd/hub/status - - ms - -

2016-09-05 08:50:43:555 - [HTTP] <-- GET /wd/hub/status - - ms - -

2016-09-05 08:50:44:057 - [HTTP] <-- GET /wd/hub/status - - ms - -

2016-09-05 08:50:44:547 - [HTTP] --> GET /wd/hub/status {}

2016-09-05 08:50:44:547 - [MJSONWP] Calling AppiumDriver.getStatus() with args: []

2016-09-05 08:50:44:629 - [HTTP] --> GET /wd/hub/status {}

2016-09-05 08:50:44:629 - [MJSONWP] Calling AppiumDriver.getStatus() with args: []

2016-09-05 08:50:45:128 - [HTTP] --> GET /wd/hub/status {}

2016-09-05 08:50:45:129 - [MJSONWP] Calling AppiumDriver.getStatus() with args: []

2016-09-05 08:50:45:546 - [HTTP] <-- GET /wd/hub/status - - ms - -

2016-09-05 08:50:45:627 - [HTTP] <-- GET /wd/hub/status - - ms - -

2016-09-05 08:50:46:128 - [HTTP] <-- GET /wd/hub/status - - ms - -

2016-09-05 08:50:46:621 - [HTTP] --> GET /wd/hub/status {}

2016-09-05 08:50:46:621 - [MJSONWP] Calling AppiumDriver.getStatus() with args: []

2016-09-05 08:50:46:696 - [HTTP] --> GET /wd/hub/status {}

2016-09-05 08:50:46:697 - [MJSONWP] Calling AppiumDriver.getStatus() with args: []

2016-09-05 08:50:47:203 - [HTTP] --> GET /wd/hub/status {}

2016-09-05 08:50:47:204 - [MJSONWP] Calling AppiumDriver.getStatus() with args: []

2016-09-05 08:50:47:620 - [HTTP] <-- GET /wd/hub/status - - ms - -

2016-09-05 08:50:47:694 - [HTTP] <-- GET /wd/hub/status - - ms - -

2016-09-05 08:50:48:203 - [HTTP] <-- GET /wd/hub/status - - ms - -

2016-09-05 08:50:48:696 - [HTTP] --> GET /wd/hub/status {}

2016-09-05 08:50:48:697 - [MJSONWP] Calling AppiumDriver.getStatus() with args: []

2016-09-05 08:50:48:771 - [HTTP] --> GET /wd/hub/status {}

2016-09-05 08:50:48:771 - [MJSONWP] Calling AppiumDriver.getStatus() with args: []

2016-09-05 08:50:49:278 - [HTTP] --> GET /wd/hub/status {}

2016-09-05 08:50:49:279 - [MJSONWP] Calling AppiumDriver.getStatus() with args: []

2016-09-05 08:50:49:696 - [HTTP] <-- GET /wd/hub/status - - ms - -

2016-09-05 08:50:49:771 - [HTTP] <-- GET /wd/hub/status - - ms - -

2016-09-05 08:50:50:278 - [HTTP] <-- GET /wd/hub/status - - ms - -

2016-09-05 08:50:50:768 - [HTTP] --> GET /wd/hub/status {}

2016-09-05 08:50:50:768 - [MJSONWP] Calling AppiumDriver.getStatus() with args: []

2016-09-05 08:50:50:818 - [HTTP] --> GET /wd/hub/status {}

2016-09-05 08:50:50:819 - [MJSONWP] Calling AppiumDriver.getStatus() with args: []

2016-09-05 08:50:51:342 - [HTTP] --> GET /wd/hub/status {}

2016-09-05 08:50:51:343 - [MJSONWP] Calling AppiumDriver.getStatus() with args: []

2016-09-05 08:50:51:767 - [HTTP] <-- GET /wd/hub/status - - ms - -

2016-09-05 08:50:51:818 - [HTTP] <-- GET /wd/hub/status - - ms - -

2016-09-05 08:50:52:344 - [HTTP] <-- GET /wd/hub/status - - ms - -

2016-09-05 08:50:52:842 - [HTTP] --> GET /wd/hub/status {}

2016-09-05 08:50:52:842 - [MJSONWP] Calling AppiumDriver.getStatus() with args: []

2016-09-05 08:50:52:891 - [HTTP] --> GET /wd/hub/status {}

2016-09-05 08:50:52:891 - [MJSONWP] Calling AppiumDriver.getStatus() with args: []

2016-09-05 08:50:53:415 - [HTTP] --> GET /wd/hub/status {}

2016-09-05 08:50:53:415 - [MJSONWP] Calling AppiumDriver.getStatus() with args: []

2016-09-05 08:50:53:841 - [HTTP] <-- GET /wd/hub/status - - ms - -

2016-09-05 08:50:53:891 - [HTTP] <-- GET /wd/hub/status - - ms - -

2016-09-05 08:50:54:414 - [HTTP] <-- GET /wd/hub/status - - ms - -

2016-09-05 08:50:54:914 - [HTTP] --> GET /wd/hub/status {}

2016-09-05 08:50:54:914 - [MJSONWP] Calling AppiumDriver.getStatus() with args: []

2016-09-05 08:50:54:964 - [HTTP] --> GET /wd/hub/status {}

2016-09-05 08:50:54:964 - [MJSONWP] Calling AppiumDriver.getStatus() with args: []

2016-09-05 08:50:55:488 - [HTTP] --> GET /wd/hub/status {}

2016-09-05 08:50:55:488 - [MJSONWP] Calling AppiumDriver.getStatus() with args: []

2016-09-05 08:50:55:913 - [HTTP] <-- GET /wd/hub/status - - ms - -

2016-09-05 08:50:55:964 - [HTTP] <-- GET /wd/hub/status - - ms - -

2016-09-05 08:50:56:487 - [HTTP] <-- GET /wd/hub/status - - ms - -

2016-09-05 08:50:56:984 - [HTTP] --> GET /wd/hub/status {}

2016-09-05 08:50:56:985 - [MJSONWP] Calling AppiumDriver.getStatus() with args: []

2016-09-05 08:50:57:040 - [HTTP] --> GET /wd/hub/status {}

2016-09-05 08:50:57:041 - [MJSONWP] Calling AppiumDriver.getStatus() with args: []

2016-09-05 08:50:57:562 - [HTTP] --> GET /wd/hub/status {}

2016-09-05 08:50:57:563 - [MJSONWP] Calling AppiumDriver.getStatus() with args: []

2016-09-05 08:50:57:984 - [HTTP] <-- GET /wd/hub/status - - ms - -

2016-09-05 08:50:58:039 - [HTTP] <-- GET /wd/hub/status - - ms - -

2016-09-05 08:50:58:561 - [HTTP] <-- GET /wd/hub/status - - ms - -

2016-09-05 08:50:59:116 - [HTTP] --> GET /wd/hub/status {}

2016-09-05 08:50:59:117 - [MJSONWP] Calling AppiumDriver.getStatus() with args: []

2016-09-05 08:50:59:118 - [HTTP] --> GET /wd/hub/status {}
2016-09-05 08:50:59:118 - [MJSONWP] Calling AppiumDriver.getStatus() with args: []
2016-09-05 08:50:59:142 - [UIAuto] Instruments is ready to receive commands
2016-09-05 08:50:59:143 - [debug] [iOS] Instruments launched. Starting poll loop for new commands.
2016-09-05 08:50:59:144 - [debug] [iOS] Setting bootstrap config keys/values
2016-09-05 08:50:59:145 - [debug] [UIAuto] Socket data received (2 bytes)
2016-09-05 08:50:59:147 - [debug] [UIAuto] Got a result when we were not expecting one! Ignoring it

2016-09-05 08:50:59:147 - [debug] [UIAuto] Sending command to instruments: target = $.target();
au = $;
$.isVerbose = true;


2016-09-05 08:50:59:631 - [HTTP] --> GET /wd/hub/status {}

2016-09-05 08:50:59:631 - [MJSONWP] Calling AppiumDriver.getStatus() with args: []

2016-09-05 08:51:00:057 - [HTTP] <-- GET /wd/hub/status - - ms - -

2016-09-05 08:51:00:077 - [debug] [Instruments] [INST] 2016-09-05 08:51:00 +0000 Debug: evaluation finished

2016-09-05 08:51:00:077 - [debug] [Instruments] [INST] 2016-09-05 08:51:00 +0000 Debug: responding with:
2016-09-05 08:51:00:078 - [debug] [Instruments] [INST] 2016-09-05 08:51:00 +0000 Debug: Running system command #1: /usr/local/bin/node /usr/local/lib/node_modules/appium/node_modules/appium-ios-driver/node_modules/appium-uiauto/build/lib/bin/command-proxy-client.js /var/folders/v7/d4fvcxfx4_qbmv6fqk1z7bnr0000gn/T/instruments_sock 2,{"status":0,"value":true}...

2016-09-05 08:51:00:093 - [HTTP] <-- GET /wd/hub/status - - ms - -

2016-09-05 08:51:00:151 - [debug] [UIAuto] Socket data received (27 bytes)
2016-09-05 08:51:00:152 - [debug] [UIAuto] Got result from instruments: {"status":0,"value":true}
2016-09-05 08:51:00:153 - [debug] [iOS] Setting initial orientation to PORTRAIT
2016-09-05 08:51:00:153 - [debug] [UIAuto] Sending command to instruments: au.setScreenOrientation('PORTRAIT')

2016-09-05 08:51:00:631 - [HTTP] <-- GET /wd/hub/status - - ms - -

2016-09-05 08:51:01:131 - [HTTP] --> GET /wd/hub/status {}

2016-09-05 08:51:01:131 - [MJSONWP] Calling AppiumDriver.getStatus() with args: []

2016-09-05 08:51:01:168 - [HTTP] --> GET /wd/hub/status {}

2016-09-05 08:51:01:168 - [MJSONWP] Calling AppiumDriver.getStatus() with args: []
2016-09-05 08:51:01:178 - [debug] [Instruments] [INST] 2016-09-05 08:51:01 +0000 Debug: Got new command 1 from instruments: au.setScreenOrientation('PORTRAIT')
2016-09-05 08:51:01:179 - [debug] [Instruments] [INST] 2016-09-05 08:51:01 +0000 Debug: evaluating au.setScreenOrientation('PORTRAIT')
2016-09-05 08:51:01:179 - [debug] [Instruments] [INST] 2016-09-05 08:51:01 +0000 Debug: target.setDeviceOrientation("1")

2016-09-05 08:51:01:702 - [HTTP] --> GET /wd/hub/status {}

2016-09-05 08:51:01:703 - [MJSONWP] Calling AppiumDriver.getStatus() with args: []

2016-09-05 08:51:02:131 - [HTTP] <-- GET /wd/hub/status - - ms - -

2016-09-05 08:51:02:146 - [debug] [Instruments] [INST] 2016-09-05 08:51:02 +0000 Debug: evaluation finished

2016-09-05 08:51:02:146 - [debug] [Instruments] [INST] 2016-09-05 08:51:02 +0000 Debug: responding with:
2016-09-05 08:51:02:147 - [debug] [Instruments] [INST] 2016-09-05 08:51:02 +0000 Debug: Running system command #2: /usr/local/bin/node /usr/local/lib/node_modules/appium/node_modules/appium-ios-driver/node_modules/appium-uiauto/build/lib/bin/command-proxy-client.js /var/folders/v7/d4fvcxfx4_qbmv6fqk1z7bnr0000gn/T/instruments_sock 2,{"status":0,"value":"PORTRAIT"}...

2016-09-05 08:51:02:166 - [HTTP] <-- GET /wd/hub/status - - ms - -

2016-09-05 08:51:02:221 - [debug] [UIAuto] Socket data received (33 bytes)
2016-09-05 08:51:02:222 - [debug] [UIAuto] Got result from instruments: {"status":0,"value":"PORTRAIT"}
2016-09-05 08:51:02:224 - [debug] [iOS] Attempting to launch Safari from Safari Launcher
2016-09-05 08:51:02:226 - [debug] [BaseDriver] Waiting up to 0 ms for condition
2016-09-05 08:51:02:228 - [debug] [UIAuto] Sending command to instruments: au.getElementByAccessibilityId('launch safari')

2016-09-05 08:51:02:699 - [HTTP] <-- GET /wd/hub/status - - ms - -

2016-09-05 08:51:03:107 - [debug] [Instruments] [INST] 2016-09-05 08:51:03 +0000 Debug: Got new command 2 from instruments: au.getElementByAccessibilityId('launch safari')

2016-09-05 08:51:03:107 - [debug] [Instruments] [INST] 2016-09-05 08:51:03 +0000 Debug: evaluating au.getElementByAccessibilityId('launch safari')

2016-09-05 08:51:03:204 - [HTTP] --> GET /wd/hub/status {}

2016-09-05 08:51:03:204 - [MJSONWP] Calling AppiumDriver.getStatus() with args: []

2016-09-05 08:51:03:240 - [HTTP] --> GET /wd/hub/status {}

2016-09-05 08:51:03:241 - [MJSONWP] Calling AppiumDriver.getStatus() with args: []

2016-09-05 08:51:03:394 - [debug] [Instruments] [INST] 2016-09-05 08:51:03 +0000 Debug: evaluation finished

2016-09-05 08:51:03:395 - [debug] [Instruments] [INST] 2016-09-05 08:51:03 +0000 Debug: Lookup returned [object UIAButton] with the name "launch safari" (id: 0).

2016-09-05 08:51:03:396 - [debug] [Instruments] [INST] 2016-09-05 08:51:03 +0000 Debug: responding with:
2016-09-05 08:51:03:397 - [debug] [Instruments] [INST] 2016-09-05 08:51:03 +0000 Debug: Running system command #3: /usr/local/bin/node /usr/local/lib/node_modules/appium/node_modules/appium-ios-driver/node_modules/appium-uiauto/build/lib/bin/command-proxy-client.js /var/folders/v7/d4fvcxfx4_qbmv6fqk1z7bnr0000gn/T/instruments_sock 2,{"status":0,"value":{"ELEMENT":"0"}}...

2016-09-05 08:51:03:467 - [debug] [UIAuto] Socket data received (38 bytes)
2016-09-05 08:51:03:467 - [debug] [UIAuto] Got result from instruments: {"status":0,"value":{"ELEMENT":"0"}}
2016-09-05 08:51:03:468 - [debug] [UIAuto] Sending command to instruments: au.tapById('0')

2016-09-05 08:51:03:770 - [HTTP] --> GET /wd/hub/status {}

2016-09-05 08:51:03:770 - [MJSONWP] Calling AppiumDriver.getStatus() with args: []

2016-09-05 08:51:04:203 - [HTTP] <-- GET /wd/hub/status - - ms - -

2016-09-05 08:51:04:249 - [HTTP] <-- GET /wd/hub/status - - ms - -

2016-09-05 08:51:04:354 - [debug] [Instruments] [INST] 2016-09-05 08:51:04 +0000 Debug: Got new command 3 from instruments: au.tapById('0')

2016-09-05 08:51:04:355 - [debug] [Instruments] [INST] 2016-09-05 08:51:04 +0000 Debug: evaluating au.tapById('0')

2016-09-05 08:51:04:356 - [debug] [Instruments] [INST] 2016-09-05 08:51:04 +0000 Debug: UIAButton.tap()

2016-09-05 08:51:04:635 - [debug] [Instruments] [INST] 2016-09-05 08:51:04 +0000 Debug: evaluation finished

2016-09-05 08:51:04:709 - [debug] [UIAuto] Socket data received (25 bytes)

2016-09-05 08:51:04:710 - [debug] [UIAuto] Got result from instruments: {"status":0,"value":""}
2016-09-05 08:51:04:710 - [debug] [iOS] Clicked button, safari should be launching.
2016-09-05 08:51:04:710 - [debug] [iOS] Waiting for initial webview

2016-09-05 08:51:04:711 - [debug] [iOS] Waiting for 3000 ms before navigating to view.

2016-09-05 08:51:04:770 - [HTTP] <-- GET /wd/hub/status - - ms - -

2016-09-05 08:51:05:277 - [HTTP] --> GET /wd/hub/status {}

2016-09-05 08:51:05:277 - [MJSONWP] Calling AppiumDriver.getStatus() with args: []

2016-09-05 08:51:05:300 - [HTTP] --> GET /wd/hub/status {}
2016-09-05 08:51:05:300 - [MJSONWP] Calling AppiumDriver.getStatus() with args: []

2016-09-05 08:51:05:844 - [HTTP] --> GET /wd/hub/status {}

2016-09-05 08:51:05:845 - [MJSONWP] Calling AppiumDriver.getStatus() with args: []

2016-09-05 08:51:06:275 - [HTTP] <-- GET /wd/hub/status - - ms - -

2016-09-05 08:51:06:298 - [HTTP] <-- GET /wd/hub/status - - ms - -

2016-09-05 08:51:06:844 - [HTTP] <-- GET /wd/hub/status - - ms - -

2016-09-05 08:51:07:351 - [HTTP] --> GET /wd/hub/status {}

2016-09-05 08:51:07:351 - [MJSONWP] Calling AppiumDriver.getStatus() with args: []

2016-09-05 08:51:07:373 - [HTTP] --> GET /wd/hub/status {}
2016-09-05 08:51:07:374 - [MJSONWP] Calling AppiumDriver.getStatus() with args: []

2016-09-05 08:51:07:716 - [debug] [iOS] Navigating to most recently opened webview

2016-09-05 08:51:07:718 - [debug] [iOS] Retrieving contexts and views

2016-09-05 08:51:07:719 - [debug] [iOS] Selecting by url: true (expected url: 'undefined')
2016-09-05 08:51:07:720 - [debug] [RemoteDebugger] Getting WebKitRemoteDebugger pageArray: localhost, 27753
2016-09-05 08:51:07:721 - [debug] [RemoteDebugger] Sending request to: http://localhost:27753/json
2016-09-05 08:51:07:745 - [debug] [RemoteDebugger] Page element JSON: [{"devtoolsFrontendUrl":"/devtools/devtools.html?ws=localhost:27753/devtools/page/2","faviconUrl":"","thumbnailUrl":"/thumb/about:blank","title":"","url":"about:blank","webSocketDebuggerUrl":"ws://localhost:27753/devtools/page/2","appId":"PID:361"}]
2016-09-05 08:51:07:746 - [iOS] Could not find any webviews yet, refreshing/retrying

2016-09-05 08:51:07:920 - [HTTP] --> GET /wd/hub/status {}

2016-09-05 08:51:07:921 - [MJSONWP] Calling AppiumDriver.getStatus() with args: []

2016-09-05 08:51:08:248 - [debug] [iOS] Retrieving contexts and views

2016-09-05 08:51:08:249 - [debug] [iOS] Selecting by url: true (expected url: 'undefined')
2016-09-05 08:51:08:249 - [debug] [RemoteDebugger] Getting WebKitRemoteDebugger pageArray: localhost, 27753
2016-09-05 08:51:08:249 - [debug] [RemoteDebugger] Sending request to: http://localhost:27753/json

2016-09-05 08:51:08:256 - [debug] [RemoteDebugger] Page element JSON: [{"devtoolsFrontendUrl":"/devtools/devtools.html?ws=localhost:27753/devtools/page/2","faviconUrl":"","thumbnailUrl":"/thumb/about:blank","title":"","url":"about:blank","webSocketDebuggerUrl":"ws://localhost:27753/devtools/page/2","appId":"PID:361"}]
2016-09-05 08:51:08:256 - [iOS] Could not find any webviews yet, refreshing/retrying

2016-09-05 08:51:08:351 - [HTTP] <-- GET /wd/hub/status - - ms - -

2016-09-05 08:51:08:373 - [HTTP] <-- GET /wd/hub/status - - ms - -

2016-09-05 08:51:08:760 - [debug] [iOS] Retrieving contexts and views

2016-09-05 08:51:08:760 - [debug] [iOS] Selecting by url: true (expected url: 'undefined')
2016-09-05 08:51:08:760 - [debug] [RemoteDebugger] Getting WebKitRemoteDebugger pageArray: localhost, 27753
2016-09-05 08:51:08:761 - [debug] [RemoteDebugger] Sending request to: http://localhost:27753/json

2016-09-05 08:51:08:765 - [debug] [RemoteDebugger] Page element JSON: [{"devtoolsFrontendUrl":"/devtools/devtools.html?ws=localhost:27753/devtools/page/2","faviconUrl":"","thumbnailUrl":"/thumb/about:blank","title":"","url":"about:blank","webSocketDebuggerUrl":"ws://localhost:27753/devtools/page/2","appId":"PID:361"}]
2016-09-05 08:51:08:765 - [iOS] Could not find any webviews yet, refreshing/retrying

2016-09-05 08:51:08:919 - [HTTP] <-- GET /wd/hub/status - - ms - -

2016-09-05 08:51:09:305 - [debug] [iOS] Retrieving contexts and views

2016-09-05 08:51:09:305 - [debug] [iOS] Selecting by url: true (expected url: 'undefined')

2016-09-05 08:51:09:312 - [debug] [RemoteDebugger] Getting WebKitRemoteDebugger pageArray: localhost, 27753
2016-09-05 08:51:09:312 - [debug] [RemoteDebugger] Sending request to: http://localhost:27753/json
2016-09-05 08:51:09:316 - [debug] [RemoteDebugger] Page element JSON: [{"devtoolsFrontendUrl":"/devtools/devtools.html?ws=localhost:27753/devtools/page/2","faviconUrl":"","thumbnailUrl":"/thumb/http://www.apple.com/","title":"Apple","url":"http://www.apple.com/","webSocketDebuggerUrl":"ws://localhost:27753/devtools/page/2","appId":"PID:361"}]
2016-09-05 08:51:09:316 - [debug] [iOS] Picking webview 'WEBVIEW_2'
2016-09-05 08:51:09:317 - [debug] [iOS] Attempting to set context to 'WEBVIEW_2'
2016-09-05 08:51:09:326 - [debug] [RemoteDebugger] WebKit debugger web socket connected to url: ws://localhost:27753/devtools/page/2
2016-09-05 08:51:09:326 - [debug] [RemoteDebugger] Unregistering from page readiness notifications
2016-09-05 08:51:09:328 - [Appium] New IosDriver session created successfully, session d47c3437-7512-447c-bb71-9e18cd29d3a7 added to master session list
2016-09-05 08:51:09:328 - [MJSONWP] Responding to client with driver.createSession() result: {"webStorageEnabled":false,...
2016-09-05 08:51:09:330 - [HTTP] <-- POST /wd/hub/session 200 35976 ms - 599
2016-09-05 08:51:09:340 - [MJSONWP] Responding to client with driver.getStatus() result: {"build":{"version":"1.5.3"...
2016-09-05 08:51:09:351 - [MJSONWP] Responding to client with driver.getStatus() result: {"build":{"version":"1.5.3"...

2016-09-05 08:51:09:359 - [MJSONWP] Responding to client with driver.getStatus() result: {"build":{"version":"1.5.3"...
2016-09-05 08:51:09:369 - [MJSONWP] Responding to client with driver.getStatus() result: {"build":{"version":"1.5.3"...
2016-09-05 08:51:09:378 - [MJSONWP] Responding to client with driver.getStatus() result: {"build":{"version":"1.5.3"...
2016-09-05 08:51:09:380 - [HTTP] --> POST /wd/hub/session/d47c3437-7512-447c-bb71-9e18cd29d3a7/url {"url":"http://appium.io/slate/en/master/?java#"}
2016-09-05 08:51:09:382 - [MJSONWP] Calling AppiumDriver.setUrl() with args: ["http://appium.io/slate/en...
2016-09-05 08:51:09:383 - [debug] [iOS] Executing iOS command 'setUrl'
2016-09-05 08:51:09:384 - [debug] [iOS] Attempting to set url 'http://appium.io/slate/en/master/?java#'
2016-09-05 08:51:09:384 - [debug] [RemoteDebugger] Navigating to new URL: http://appium.io/slate/en/master/?java#
2016-09-05 08:51:09:385 - [debug] [RemoteDebugger] Sending WebKit data: {"method":"Page.navigate","...
2016-09-05 08:51:09:395 - [MJSONWP] Responding to client with driver.getStatus() result: {"build":{"version":"1.5.3"...

2016-09-05 08:51:09:403 - [MJSONWP] Responding to client with driver.getStatus() result: {"build":{"version":"1.5.3"...
2016-09-05 08:51:09:411 - [MJSONWP] Responding to client with driver.getStatus() result: {"build":{"version":"1.5.3"...
2016-09-05 08:51:09:418 - [MJSONWP] Responding to client with driver.getStatus() result: {"build":{"version":"1.5.3"...
2016-09-05 08:51:09:425 - [HTTP] --> GET /wd/hub/status {}
2016-09-05 08:51:09:425 - [MJSONWP] Calling AppiumDriver.getStatus() with args: []
2016-09-05 08:51:09:426 - [MJSONWP] Responding to client with driver.getStatus() result: {"build":{"version":"1.5.3"...
2016-09-05 08:51:09:434 - [MJSONWP] Responding to client with driver.getStatus() result: {"build":{"version":"1.5.3"...
2016-09-05 08:51:09:443 - [MJSONWP] Responding to client with driver.getStatus() result: {"build":{"version":"1.5.3"...

2016-09-05 08:51:09:449 - [HTTP] --> GET /wd/hub/status {}
2016-09-05 08:51:09:449 - [MJSONWP] Calling AppiumDriver.getStatus() with args: []
2016-09-05 08:51:09:453 - [MJSONWP] Responding to client with driver.getStatus() result: {"build":{"version":"1.5.3"...
2016-09-05 08:51:09:462 - [MJSONWP] Responding to client with driver.getStatus() result: {"build":{"version":"1.5.3"...
2016-09-05 08:51:09:471 - [MJSONWP] Responding to client with driver.getStatus() result: {"build":{"version":"1.5.3"...
2016-09-05 08:51:09:478 - [MJSONWP] Responding to client with driver.getStatus() result: {"build":{"version":"1.5.3"...
2016-09-05 08:51:09:487 - [MJSONWP] Responding to client with driver.getStatus() result: {"build":{"version":"1.5.3"...

2016-09-05 08:51:09:495 - [MJSONWP] Responding to client with driver.getStatus() result: {"build":{"version":"1.5.3"...
2016-09-05 08:51:09:503 - [MJSONWP] Responding to client with driver.getStatus() result: {"build":{"version":"1.5.3"...
2016-09-05 08:51:09:513 - [MJSONWP] Responding to client with driver.getStatus() result: {"build":{"version":"1.5.3"...
2016-09-05 08:51:09:524 - [MJSONWP] Responding to client with driver.getStatus() result: {"build":{"version":"1.5.3"...
2016-09-05 08:51:09:534 - [MJSONWP] Responding to client with driver.getStatus() result: {"build":{"version":"1.5.3"...

2016-09-05 08:51:09:542 - [MJSONWP] Responding to client with driver.getStatus() result: {"build":{"version":"1.5.3"...
2016-09-05 08:51:09:550 - [MJSONWP] Responding to client with driver.getStatus() result: {"build":{"version":"1.5.3"...
2016-09-05 08:51:09:558 - [MJSONWP] Responding to client with driver.getStatus() result: {"build":{"version":"1.5.3"...
2016-09-05 08:51:09:566 - [MJSONWP] Responding to client with driver.getStatus() result: {"build":{"version":"1.5.3"...
2016-09-05 08:51:09:576 - [MJSONWP] Responding to client with driver.getStatus() result: {"build":{"version":"1.5.3"...
2016-09-05 08:51:09:584 - [MJSONWP] Responding to client with driver.getStatus() result: {"build":{"version":"1.5.3"...

2016-09-05 08:51:09:592 - [MJSONWP] Responding to client with driver.getStatus() result: {"build":{"version":"1.5.3"...
2016-09-05 08:51:09:600 - [MJSONWP] Responding to client with driver.getStatus() result: {"build":{"version":"1.5.3"...
2016-09-05 08:51:09:608 - [MJSONWP] Responding to client with driver.getStatus() result: {"build":{"version":"1.5.3"...
2016-09-05 08:51:09:615 - [MJSONWP] Responding to client with driver.getStatus() result: {"build":{"version":"1.5.3"...
2016-09-05 08:51:09:623 - [MJSONWP] Responding to client with driver.getStatus() result: {"build":{"version":"1.5.3"...
2016-09-05 08:51:09:631 - [MJSONWP] Responding to client with driver.getStatus() result: {"build":{"version":"1.5.3"...
2016-09-05 08:51:09:639 - [MJSONWP] Responding to client with driver.getStatus() result: {"build":{"version":"1.5.3"...

2016-09-05 08:51:09:647 - [MJSONWP] Responding to client with driver.getStatus() result: {"build":{"version":"1.5.3"...
2016-09-05 08:51:09:654 - [MJSONWP] Responding to client with driver.getStatus() result: {"build":{"version":"1.5.3"...
2016-09-05 08:51:09:663 - [MJSONWP] Responding to client with driver.getStatus() result: {"build":{"version":"1.5.3"...
2016-09-05 08:51:09:670 - [MJSONWP] Responding to client with driver.getStatus() result: {"build":{"version":"1.5.3"...
2016-09-05 08:51:09:678 - [MJSONWP] Responding to client with driver.getStatus() result: {"build":{"version":"1.5.3"...
2016-09-05 08:51:09:687 - [MJSONWP] Responding to client with driver.getStatus() result: {"build":{"version":"1.5.3"...
2016-09-05 08:51:09:697 - [MJSONWP] Responding to client with driver.getStatus() result: {"build":{"version":"1.5.3"...
2016-09-05 08:51:09:705 - [MJSONWP] Responding to client with driver.getStatus() result: {"build":{"version":"1.5.3"...
2016-09-05 08:51:09:712 - [MJSONWP] Responding to client with driver.getStatus() result: {"build":{"version":"1.5.3"...

2016-09-05 08:51:09:720 - [MJSONWP] Responding to client with driver.getStatus() result: {"build":{"version":"1.5.3"...
2016-09-05 08:51:09:727 - [MJSONWP] Responding to client with driver.getStatus() result: {"build":{"version":"1.5.3"...
2016-09-05 08:51:09:728 - [HTTP] <-- GET /wd/hub/status 200 303 ms - 121
2016-09-05 08:51:09:736 - [MJSONWP] Responding to client with driver.getStatus() result: {"build":{"version":"1.5.3"...
2016-09-05 08:51:09:737 - [HTTP] <-- GET /wd/hub/status 200 288 ms - 121

2016-09-05 08:51:09:990 - [HTTP] --> GET /wd/hub/status {}

2016-09-05 08:51:09:990 - [MJSONWP] Calling AppiumDriver.getStatus() with args: []

2016-09-05 08:51:09:999 - [MJSONWP] Responding to client with driver.getStatus() result: {"build":{"version":"1.5.3"...
2016-09-05 08:51:10:000 - [HTTP] <-- GET /wd/hub/status 200 10 ms - 121

2016-09-05 08:51:10:725 - [debug] [RemoteDebugger] Receiving WebKit data: {"result":{},"id":1}

2016-09-05 08:51:10:725 - [debug] [RemoteDebugger] Found handler for message '1'
2016-09-05 08:51:10:726 - [debug] [RemoteDebugger] Received page navigated message: {"result":{},"id":1}

2016-09-05 08:51:11:729 - [debug] [RemoteDebugger] Waiting for dom...

2016-09-05 08:51:11:730 - [debug] [RemoteDebugger] Page loaded, verifying whether ready

2016-09-05 08:51:12:233 - [debug] [RemoteDebugger] Checking document readyState

2016-09-05 08:51:12:233 - [debug] [RemoteDebugger] Sending javascript command (function (){ return docume...
2016-09-05 08:51:12:234 - [debug] [RemoteDebugger] Sending WebKit data: {"method":"Runtime.evaluate...

2016-09-05 08:51:20:862 - [HTTP] --> GET /wd/hub/status {}

2016-09-05 08:51:20:862 - [MJSONWP] Calling AppiumDriver.getStatus() with args: []

2016-09-05 08:51:20:870 - [MJSONWP] Responding to client with driver.getStatus() result: {"build":{"version":"1.5.3"...
2016-09-05 08:51:20:871 - [HTTP] <-- GET /wd/hub/status 200 9 ms - 121
2016-09-05 08:51:20:884 - [HTTP] --> GET /wd/hub/status {}
2016-09-05 08:51:20:884 - [MJSONWP] Calling AppiumDriver.getStatus() with args: []
2016-09-05 08:51:20:891 - [MJSONWP] Responding to client with driver.getStatus() result: {"build":{"version":"1.5.3"...
2016-09-05 08:51:20:892 - [HTTP] <-- GET /wd/hub/status 200 8 ms - 121

2016-09-05 08:51:21:134 - [HTTP] --> GET /wd/hub/status {}

2016-09-05 08:51:21:137 - [MJSONWP] Calling AppiumDriver.getStatus() with args: []

2016-09-05 08:51:21:144 - [MJSONWP] Responding to client with driver.getStatus() result: {"build":{"version":"1.5.3"...
2016-09-05 08:51:21:145 - [HTTP] <-- GET /wd/hub/status 200 11 ms - 121

2016-09-05 08:51:25:636 - [HTTP] --> GET /wd/hub/status {}

2016-09-05 08:51:25:636 - [MJSONWP] Calling AppiumDriver.getStatus() with args: []

2016-09-05 08:51:25:644 - [MJSONWP] Responding to client with driver.getStatus() result: {"build":{"version":"1.5.3"...
2016-09-05 08:51:25:644 - [HTTP] <-- GET /wd/hub/status 200 8 ms - 121

2016-09-05 08:51:26:287 - [debug] [RemoteDebugger] Receiving WebKit data: {"result":{"result":{"type"...

2016-09-05 08:51:26:287 - [debug] [RemoteDebugger] Found handler for message '2'
2016-09-05 08:51:26:288 - [debug] [RemoteDebugger] readyState was "interactive"

2016-09-05 08:51:26:288 - [debug] [RemoteDebugger] Page was not ready, retrying

2016-09-05 08:51:26:793 - [debug] [RemoteDebugger] Checking document readyState

2016-09-05 08:51:26:794 - [debug] [RemoteDebugger] Sending javascript command (function (){ return docume...
2016-09-05 08:51:26:794 - [debug] [RemoteDebugger] Sending WebKit data: {"method":"Runtime.evaluate...

2016-09-05 08:51:26:806 - [debug] [RemoteDebugger] Receiving WebKit data: {"result":{"result":{"type"...
2016-09-05 08:51:26:807 - [debug] [RemoteDebugger] Found handler for message '3'
2016-09-05 08:51:26:807 - [debug] [RemoteDebugger] readyState was "interactive"
2016-09-05 08:51:26:807 - [debug] [RemoteDebugger] Page was not ready, retrying

2016-09-05 08:51:27:310 - [debug] [RemoteDebugger] Checking document readyState

2016-09-05 08:51:27:310 - [debug] [RemoteDebugger] Sending javascript command (function (){ return docume...
2016-09-05 08:51:27:310 - [debug] [RemoteDebugger] Sending WebKit data: {"method":"Runtime.evaluate...

2016-09-05 08:51:27:319 - [debug] [RemoteDebugger] Receiving WebKit data: {"result":{"result":{"type"...
2016-09-05 08:51:27:319 - [debug] [RemoteDebugger] Found handler for message '4'
2016-09-05 08:51:27:319 - [debug] [RemoteDebugger] readyState was "interactive"
2016-09-05 08:51:27:319 - [debug] [RemoteDebugger] Page was not ready, retrying

2016-09-05 08:51:27:822 - [debug] [RemoteDebugger] Checking document readyState

2016-09-05 08:51:27:822 - [debug] [RemoteDebugger] Sending javascript command (function (){ return docume...
2016-09-05 08:51:27:822 - [debug] [RemoteDebugger] Sending WebKit data: {"method":"Runtime.evaluate...

2016-09-05 08:51:27:830 - [debug] [RemoteDebugger] Receiving WebKit data: {"result":{"result":{"type"...
2016-09-05 08:51:27:830 - [debug] [RemoteDebugger] Found handler for message '5'
2016-09-05 08:51:27:831 - [debug] [RemoteDebugger] readyState was "interactive"
2016-09-05 08:51:27:831 - [debug] [RemoteDebugger] Page was not ready, retrying

2016-09-05 08:51:28:334 - [debug] [RemoteDebugger] Checking document readyState

2016-09-05 08:51:28:334 - [debug] [RemoteDebugger] Sending javascript command (function (){ return docume...
2016-09-05 08:51:28:334 - [debug] [RemoteDebugger] Sending WebKit data: {"method":"Runtime.evaluate...

2016-09-05 08:51:28:343 - [debug] [RemoteDebugger] Receiving WebKit data: {"result":{"result":{"type"...
2016-09-05 08:51:28:344 - [debug] [RemoteDebugger] Found handler for message '6'
2016-09-05 08:51:28:345 - [debug] [RemoteDebugger] readyState was "interactive"
2016-09-05 08:51:28:345 - [debug] [RemoteDebugger] Page was not ready, retrying

2016-09-05 08:51:28:848 - [debug] [RemoteDebugger] Checking document readyState

2016-09-05 08:51:28:849 - [debug] [RemoteDebugger] Sending javascript command (function (){ return docume...
2016-09-05 08:51:28:849 - [debug] [RemoteDebugger] Sending WebKit data: {"method":"Runtime.evaluate...

2016-09-05 08:51:28:857 - [debug] [RemoteDebugger] Receiving WebKit data: {"result":{"result":{"type"...
2016-09-05 08:51:28:857 - [debug] [RemoteDebugger] Found handler for message '7'
2016-09-05 08:51:28:858 - [debug] [RemoteDebugger] readyState was "interactive"
2016-09-05 08:51:28:858 - [debug] [RemoteDebugger] Page was not ready, retrying

2016-09-05 08:51:29:364 - [debug] [RemoteDebugger] Checking document readyState

2016-09-05 08:51:29:364 - [debug] [RemoteDebugger] Sending javascript command (function (){ return docume...
2016-09-05 08:51:29:364 - [debug] [RemoteDebugger] Sending WebKit data: {"method":"Runtime.evaluate...

2016-09-05 08:51:29:450 - [debug] [RemoteDebugger] Receiving WebKit data: {"result":{"result":{"type"...

2016-09-05 08:51:29:450 - [debug] [RemoteDebugger] Found handler for message '8'
2016-09-05 08:51:29:450 - [debug] [RemoteDebugger] readyState was "interactive"
2016-09-05 08:51:29:450 - [debug] [RemoteDebugger] Page was not ready, retrying

2016-09-05 08:51:29:952 - [debug] [RemoteDebugger] Checking document readyState

2016-09-05 08:51:29:952 - [debug] [RemoteDebugger] Sending javascript command (function (){ return docume...
2016-09-05 08:51:29:952 - [debug] [RemoteDebugger] Sending WebKit data: {"method":"Runtime.evaluate...

2016-09-05 08:51:29:960 - [debug] [RemoteDebugger] Receiving WebKit data: {"result":{"result":{"type"...
2016-09-05 08:51:29:960 - [debug] [RemoteDebugger] Found handler for message '9'
2016-09-05 08:51:29:961 - [debug] [RemoteDebugger] readyState was "complete"
2016-09-05 08:51:29:961 - [debug] [RemoteDebugger] Page is ready
2016-09-05 08:51:29:962 - [MJSONWP] Responding to client with driver.setUrl() result: null
2016-09-05 08:51:29:963 - [HTTP] <-- POST /wd/hub/session/d47c3437-7512-447c-bb71-9e18cd29d3a7/url 200 20582 ms - 76
2016-09-05 08:51:29:992 - [HTTP] --> POST /wd/hub/session/d47c3437-7512-447c-bb71-9e18cd29d3a7/element {"using":"link text","value":"Appium GUI"}
2016-09-05 08:51:29:993 - [MJSONWP] Calling AppiumDriver.findElement() with args: ["link text","Appium GUI","...
2016-09-05 08:51:29:993 - [debug] [iOS] Executing iOS command 'findElement'
2016-09-05 08:51:29:994 - [debug] [BaseDriver] Waiting up to 0 ms for condition

2016-09-05 08:51:30:010 - [debug] [RemoteDebugger] Executing 'find_element' atom in default context
2016-09-05 08:51:30:011 - [debug] [RemoteDebugger] Sending javascript command (function(){return function...
2016-09-05 08:51:30:012 - [debug] [RemoteDebugger] Sending WebKit data: {"method":"Runtime.evaluate...

2016-09-05 08:51:30:203 - [debug] [RemoteDebugger] Receiving WebKit data: {"result":{"result":{"type"...

2016-09-05 08:51:30:203 - [debug] [RemoteDebugger] Found handler for message '10'
2016-09-05 08:51:30:203 - [debug] [RemoteDebugger] Received result for atom 'find_element' execution: {"ELEMENT":":wdc:1473065490196"}

2016-09-05 08:51:30:522 - [MJSONWP] Responding to client with driver.findElement() result: {"ELEMENT":"5000"}

2016-09-05 08:51:30:523 - [HTTP] <-- POST /wd/hub/session/d47c3437-7512-447c-bb71-9e18cd29d3a7/element 200 531 ms - 90

2016-09-05 08:51:30:549 - [HTTP] --> POST /wd/hub/session/d47c3437-7512-447c-bb71-9e18cd29d3a7/element/5000/click {"id":"5000"}
2016-09-05 08:51:30:549 - [MJSONWP] Calling AppiumDriver.click() with args: ["5000","d47c3437-7512-447c...
2016-09-05 08:51:30:550 - [debug] [iOS] Executing iOS command 'click'
2016-09-05 08:51:30:561 - [debug] [RemoteDebugger] Executing 'click' atom in default context
2016-09-05 08:51:30:561 - [debug] [RemoteDebugger] Sending javascript command (function(){return function...
2016-09-05 08:51:30:562 - [debug] [RemoteDebugger] Sending WebKit data: {"method":"Runtime.evaluate...

2016-09-05 08:51:31:064 - [debug] [RemoteDebugger] Receiving WebKit data: {"result":{"result":{"type"...

2016-09-05 08:51:31:064 - [debug] [RemoteDebugger] Found handler for message '11'
2016-09-05 08:51:31:064 - [debug] [RemoteDebugger] Received result for atom 'click' execution: null

2016-09-05 08:51:31:066 - [MJSONWP] Responding to client with driver.click() result: null
2016-09-05 08:51:31:067 - [HTTP] <-- POST /wd/hub/session/d47c3437-7512-447c-bb71-9e18cd29d3a7/element/5000/click 200 518 ms - 76
2016-09-05 08:51:31:072 - [HTTP] --> POST /wd/hub/session/d47c3437-7512-447c-bb71-9e18cd29d3a7/element {"using":"css selector","value":"#section-appium-gui>h1"}
2016-09-05 08:51:31:073 - [MJSONWP] Calling AppiumDriver.findElement() with args: ["css selector","#section-a...
2016-09-05 08:51:31:073 - [debug] [iOS] Executing iOS command 'findElement'
2016-09-05 08:51:31:073 - [debug] [BaseDriver] Waiting up to 0 ms for condition
2016-09-05 08:51:31:074 - [debug] [RemoteDebugger] Executing 'find_element' atom in default context
2016-09-05 08:51:31:074 - [debug] [RemoteDebugger] Sending javascript command (function(){return function...
2016-09-05 08:51:31:075 - [debug] [RemoteDebugger] Sending WebKit data: {"method":"Runtime.evaluate...

2016-09-05 08:51:31:171 - [debug] [RemoteDebugger] Receiving WebKit data: {"result":{"result":{"type"...
2016-09-05 08:51:31:171 - [debug] [RemoteDebugger] Found handler for message '12'
2016-09-05 08:51:31:172 - [debug] [RemoteDebugger] Received result for atom 'find_element' execution: {"ELEMENT":":wdc:1473065490197"}

2016-09-05 08:51:31:582 - [MJSONWP] Responding to client with driver.findElement() result: {"ELEMENT":"5001"}

2016-09-05 08:51:31:582 - [HTTP] <-- POST /wd/hub/session/d47c3437-7512-447c-bb71-9e18cd29d3a7/element 200 511 ms - 90

2016-09-05 08:51:31:587 - [HTTP] --> GET /wd/hub/session/d47c3437-7512-447c-bb71-9e18cd29d3a7/element/5001/location {}
2016-09-05 08:51:31:587 - [MJSONWP] Calling AppiumDriver.getLocation() with args: ["5001","d47c3437-7512-447c...
2016-09-05 08:51:31:588 - [debug] [iOS] Executing iOS command 'getLocation'
2016-09-05 08:51:31:599 - [debug] [RemoteDebugger] Executing 'get_top_left_coordinates' atom in default context
2016-09-05 08:51:31:599 - [debug] [RemoteDebugger] Sending javascript command (function(){return function...
2016-09-05 08:51:31:600 - [debug] [RemoteDebugger] Sending WebKit data: {"method":"Runtime.evaluate...

2016-09-05 08:51:31:714 - [debug] [RemoteDebugger] Receiving WebKit data: {"result":{"result":{"type"...

2016-09-05 08:51:31:714 - [debug] [RemoteDebugger] Found handler for message '13'
2016-09-05 08:51:31:715 - [debug] [RemoteDebugger] Received result for atom 'get_top_left_coordinates' execution: {"x":230,"y":-2.46875}

2016-09-05 08:51:32:105 - [MJSONWP] Responding to client with driver.getLocation() result: {"x":230,"y":-2.46875,"clon...

2016-09-05 08:51:32:105 - [HTTP] <-- GET /wd/hub/session/d47c3437-7512-447c-bb71-9e18cd29d3a7/element/5001/location 200 518 ms - 543

2016-09-05 08:51:32:153 - [HTTP] --> DELETE /wd/hub/session/d47c3437-7512-447c-bb71-9e18cd29d3a7 {}
2016-09-05 08:51:32:153 - [MJSONWP] Calling AppiumDriver.deleteSession() with args: ["d47c3437-7512-447c-bb71-9...
2016-09-05 08:51:32:154 - [debug] [iOS] Deleting ios session
2016-09-05 08:51:32:154 - [debug] [UIAuto] Destroying instruments client socket.
2016-09-05 08:51:32:155 - [debug] [UIAuto] Closing socket server.
2016-09-05 08:51:32:156 - [debug] [UIAuto] Instruments socket server was closed
2016-09-05 08:51:32:157 - [debug] [Instruments] Starting shutdown.
2016-09-05 08:51:32:157 - [debug] [Instruments] Sending sigterm to instruments

2016-09-05 08:51:32:162 - [debug] [Instruments] [INST] 2016-09-05 08:51:32 +0000 Stopped: Script was stopped by the user
2016-09-05 08:51:32:194 - [debug] [Instruments] [INST] 2016-09-05 08:51:04 +0000 Debug: responding with:
2016-09-05 08:51:32:195 - [debug] [Instruments] [INST] 2016-09-05 08:51:04 +0000 Debug: Running system command #4: /usr/local/bin/node /usr/local/lib/node_modules/appium/node_modules/appium-ios-driver/node_modules/appium-uiauto/build/lib/bin/command-proxy-client.js /var/folders/v7/d4fvcxfx4_qbmv6fqk1z7bnr0000gn/T/instruments_sock 2,{"status":0,"value":""}...

2016-09-05 08:51:32:271 - [debug] [Instruments] [INST STDERR] 2016-09-05 09:51:32.271 instruments[53611:987029] Attempting to change event horizon while disengage

2016-09-05 08:51:33:366 - [debug] [Instruments] [INST] Instruments Trace Complete (Duration : 47.844215s; Output : /var/folders/v7/d4fvcxfx4_qbmv6fqk1z7bnr0000gn/T/appium-instruments/instrumentscli0.trace)

2016-09-05 08:51:33:406 - [debug] [Instruments] Instruments exited with code 0

2016-09-05 08:51:33:408 - [debug] [iOSLog] Stopping iOS log capture
2016-09-05 08:51:33:409 - [debug] [RemoteDebugger] Disconnecting from WebKit remote debugger
2016-09-05 08:51:33:410 - [debug] [iOS] Running ios real device reset flow
2016-09-05 08:51:33:410 - [Appium] Removing session d47c3437-7512-447c-bb71-9e18cd29d3a7 from our master session list
2016-09-05 08:51:33:413 - [debug] [MJSONWP] Received response: null
2016-09-05 08:51:33:413 - [debug] [MJSONWP] But deleting session, so not returning
2016-09-05 08:51:33:413 - [MJSONWP] Responding to client with driver.deleteSession() result: null
2016-09-05 08:51:33:414 - [HTTP] <-- DELETE /wd/hub/session/d47c3437-7512-447c-bb71-9e18cd29d3a7 200 1261 ms - 76
2016-09-05 08:51:33:415 - [debug] [RemoteDebugger] WebKit remote debugger socket disconnected