This is a copy of a thread from Appium forums, which is not seeing much activity… https://discuss.appium.io/t/webview-context-is-not-found-for-ios-app/4535. The problem affects many users though. There is another issue here on github, but affecting Android devices; apart from that, the problems seems to be just the same https://github.com/appium/appium/issues/5089.
---
I am experiencing an issue with Appium and iOS simulator, where a call to `contexts()` returns only `NATIVE_APP`, and no webview. This happens often, but not always, i.e. sometimes it runs correctly. In all cases, he webview is visible in the iOS simulator, and if the test is re-ran, it sometimes finds it.
I never experienced it on XCODE 6.1 and Appium 1.3.7, but since upgrading XCode and / or Appium (I tried both), it happens often.
I will post the full log below, including two cases, the first one passes, the second one fails.
It may be of some importance, that for every test suite, the app is installed from a remote location (a .zip file).
I tried setting timeouts, and also polling for the context on fail - it does not help. What does help, is restarting the tests. I don't want to do that though. All the tests execute correctly on desktop, against selenium, everytime.
The exact issue that Appium reports is:
> info: --> GET /wd/hub/session/b45cc384-a726-45c2-94c3-4edcedd95a38/contexts {}
> info: [debug] [REMOTE] Debugger socket connected to ::1:27753
> info: [debug] [REMOTE] Sending connection key
> info: [debug] [REMOTE] Sending _rpc_reportIdentifier: message to remote debugger
> info: [debug] [REMOTE] {"__argument":{"WIRConnectionIdentifierKey":"6e6cb8cd-8b54-4ee7-87fe-617904f3607a"},"__selector":"_rpc_reportIdentifier:"}
> info: [debug] [INST] 2015-05-30 07:28:50 +0000 Debug: evaluation finished
> info: [debug] [INST] 2015-05-30 07:28:50 +0000 Debug: responding with:
> info: [debug] [INST] 2015-05-30 07:28:50 +0000 Debug: Running system command #11: /Users/michalmikolajczyk/local/bin/node /Users/michalmikolajczyk/local/lib/node_modules/appium/node_modules/appium-uiauto/bin/command-proxy-client.js /tmp/instruments_sock 2,{"status":0,"value":""}...
> info: [debug] [REMOTE] Receiving data from remote debugger
> info: [debug] [REMOTE] Receiving data from remote debugger
> info: [debug] [REMOTE] {"__argument":{"WIRSimulatorBuildKey":"12F69","WIRSimulatorProductVersionKey":"8.3","WIRSimulatorNameKey":"iPad Simulator"},"__selector":"_rpc_reportSetup:"}
> info: [debug] [REMOTE] Sim name: iPad Simulator
> info: [debug] [REMOTE] Sim build: 12F69
> info: [debug] [REMOTE] {"__argument":{"WIRApplicationDictionaryKey":{"PID:99797":{"WIRApplicationIdentifierKey":"PID:99797","WIRIsApplicationProxyKey":false,"WIRApplicationNameKey":"June20","WIRApplicationBundleIdentifierKey":"com.june20.June20","WIRIsApplicationActiveKey":1}}},"__selector":"_rpc_reportConnectedApplicationList:"}
> info: [debug] Using remote debugger app key: PID:99797
> info: [debug] [REMOTE] Selecting app PID:99797 (try #1)
> info: [debug] [REMOTE] function () { [native code] }
> info: [debug] [REMOTE] Sending _rpc_forwardGetListing: message to remote debugger
> info: [debug] [REMOTE] {"__argument":{"WIRConnectionIdentifierKey":"6e6cb8cd-8b54-4ee7-87fe-617904f3607a","WIRApplicationIdentifierKey":"PID:99797"},"__selector":"_rpc_forwardGetListing:"}
> info: [debug] [REMOTE] Receiving data from remote debugger
> info: [debug] [REMOTE] Receiving data from remote debugger
> info: [debug] [REMOTE] {"__argument":{"WIRApplicationIdentifierKey":"PID:99839","WIRIsApplicationProxyKey":true,"WIRApplicationNameKey":"","WIRApplicationBundleIdentifierKey":"com.apple.WebKit.WebContent","WIRIsApplicationActiveKey":0,"WIRHostApplicationIdentifierKey":"PID:99797"},"__selector":"_rpc_applicationConnected:"}
> info: [REMOTE] We were notified we connected to possibly the wrong app. Using the id key suggested and trying again
> info: [debug] [REMOTE] Selecting app PID:99839 (try #2)
> info: [debug] [REMOTE] function () { [native code] }
> info: [debug] [REMOTE] Sending _rpc_forwardGetListing: message to remote debugger
> info: [debug] [REMOTE] {"__argument":{"WIRConnectionIdentifierKey":"6e6cb8cd-8b54-4ee7-87fe-617904f3607a","WIRApplicationIdentifierKey":"PID:99839"},"__selector":"_rpc_forwardGetListing:"}
> info: [debug] [REMOTE] Receiving data from remote debugger
> info: [debug] [REMOTE] {"__argument":{"WIRApplicationIdentifierKey":"PID:99808","WIRIsApplicationProxyKey":true,"WIRApplicationNameKey":"","WIRApplicationBundleIdentifierKey":"com.apple.WebKit.WebContent","WIRIsApplicationActiveKey":0,"WIRHostApplicationIdentifierKey":"PID:99797"},"__selector":"_rpc_applicationConnected:"}
> info: [REMOTE] We were notified we connected to possibly the wrong app. Using the id key suggested and trying again
> info: [debug] [REMOTE] Selecting app PID:99808 (try #3)
> info: [debug] [REMOTE] function () { [native code] }
> info: [debug] [REMOTE] Sending _rpc_forwardGetListing: message to remote debugger
> info: [debug] [REMOTE] {"__argument":{"WIRConnectionIdentifierKey":"6e6cb8cd-8b54-4ee7-87fe-617904f3607a","WIRApplicationIdentifierKey":"PID:99808"},"__selector":"_rpc_forwardGetListing:"}
> info: [debug] [REMOTE] Receiving data from remote debugger
> info: [debug] [REMOTE] Receiving data from remote debugger
> info: [debug] [REMOTE] {"__argument":{"WIRApplicationIdentifierKey":"PID:99810","WIRIsApplicationProxyKey":true,"WIRApplicationNameKey":"","WIRApplicationBundleIdentifierKey":"com.apple.WebKit.WebContent","WIRIsApplicationActiveKey":0,"WIRHostApplicationIdentifierKey":"PID:99797"},"__selector":"_rpc_applicationConnected:"}
> info: [REMOTE] We were notified we connected to possibly the wrong app. Using the id key suggested and trying again
> info: [debug] [REMOTE] Selecting app PID:99810 (try #4)
> info: [debug] [REMOTE] function () { [native code] }
> info: [debug] [REMOTE] Sending _rpc_forwardGetListing: message to remote debugger
> info: [debug] [REMOTE] {"__argument":{"WIRConnectionIdentifierKey":"6e6cb8cd-8b54-4ee7-87fe-617904f3607a","WIRApplicationIdentifierKey":"PID:99810"},"__selector":"_rpc_forwardGetListing:"}
> info: [debug] [REMOTE] Receiving data from remote debugger
> info: [debug] [REMOTE] {"__argument":{"WIRApplicationIdentifierKey":"PID:99797","WIRListingKey":{}},"__selector":"_rpc_applicationSentListing:"}
> error: [REMOTE] Could not connect to a valid app after 4 tries.
> info: [debug] Responding to client with success: {"status":0,"value":["NATIVE_APP"],"sessionId":"b45cc384-a726-45c2-94c3-4edcedd95a38"}
> info: <-- GET /wd/hub/session/b45cc384-a726-45c2-94c3-4edcedd95a38/contexts 200 979.221 ms - 86 {"status":0,"value":["NATIVE_APP"],"sessionId":"b45cc384-a726-45c2-94c3-4edcedd95a38"}
The full log below:
> info: Welcome to Appium v1.3.7 (REV 72fbfaa116d3d9f6a862600ee99cf02f6d0e2182)
> info: Appium REST http interface listener started on 0.0.0.0:4723
> info: Console LogLevel: debug
> info: --> POST /wd/hub/session {"desiredCapabilities":{"platformName":"iOS","platformVersion":"8.3","deviceName":"=iPad Air","app":"http://myappserver.com/app/June20.zip","browserName":"June20 DEBUG"}}
> info: Client User-Agent string: undefined
> info: [debug] Using downloadable app from desired caps: [object Object]
> info: [debug] http://myappserver.com/app/June20.zip downloaded to /var/folders/1b/t3nrqtb93mx1mswtcp3bnh3m0000gn/T/115430-99547-mjxnmi/appium-app.zip
> info: [debug] Unzipping /var/folders/1b/t3nrqtb93mx1mswtcp3bnh3m0000gn/T/115430-99547-mjxnmi/appium-app.zip
> info: [debug] Testing zip archive: /var/folders/1b/t3nrqtb93mx1mswtcp3bnh3m0000gn/T/115430-99547-mjxnmi/appium-app.zip
> info: [debug] Zip archive tested clean
> info: [debug] Unzip successful
> info: [debug] Using extracted app: /var/folders/1b/t3nrqtb93mx1mswtcp3bnh3m0000gn/T/115430-99547-mjxnmi/June20.app
> info: [debug] Creating new appium session aec8da59-9600-42d8-9003-d7c36d638c8f
> info: [debug] Removing any remaining instruments sockets
> info: [debug] Cleaned up instruments socket /tmp/instruments_sock
> info: [debug] Setting Xcode folder
> info: [debug] Setting Xcode version
> info: [debug] Setting iOS SDK Version
> info: [debug] Getting sdk version from xcrun with a timeout
> info: [debug] iOS SDK Version set to 8.3
> info: [debug] Checking whether instruments supports our device string
> info: [debug] Getting list of devices instruments supports
> info: [debug] Instruments is at: /Applications/Xcode.app/Contents/Developer/usr/bin/instruments
> info: [debug] Getting device string from opts: {"forceIphone":false,"forceIpad":false,"xcodeVersion":"6.3.1","iOSSDKVersion":"8.3","deviceName":"=iPad Air","platformVersion":"8.3"}
> info: [debug] iOS sim UDID is F95857C6-DC1C-4918-AE2B-2532F5E50E45
> info: [debug] Detecting automation tracetemplate
> info: [debug] Not auto-detecting udid, running on sim
> info: [debug] Could not parse plist file (as binary) at /var/folders/1b/t3nrqtb93mx1mswtcp3bnh3m0000gn/T/115430-99547-mjxnmi/June20.app/en.lproj/Localizable.strings
> info: Will try to parse the plist file as XML
> info: [debug] Could not parse plist file (as XML) at /var/folders/1b/t3nrqtb93mx1mswtcp3bnh3m0000gn/T/115430-99547-mjxnmi/June20.app/en.lproj/Localizable.strings
> warn: Could not parse app Localizable.strings assuming it doesn't exist
> info: [debug] Getting bundle ID from app
> info: [debug] Parsed app Info.plist (as binary)
> info: [debug] Creating instruments
> info: On some xcode 6 platforms, instruments-without-delay does not work. If you experience this, you will need to re-run appium with the --native-instruments-lib flag
> info: [debug] Preparing uiauto bootstrap
> info: [debug] Dynamic bootstrap dir: /Users/michalmikolajczyk/Library/Application Support/appium/bootstrap
> info: [debug] Dynamic env: {"nodePath":"/Users/michalmikolajczyk/local/bin/node","commandProxyClientPath":"/Users/michalmikolajczyk/local/lib/node_modules/appium/node_modules/appium-uiauto/bin/command-proxy-client.js","instrumentsSock":"/tmp/instruments_sock","interKeyDelay":null,"justLoopInfinitely":false,"autoAcceptAlerts":false,"autoDismissAlerts":false,"sendKeyStrategy":"oneByOne"}
> ...o: [debug] Dynamic bootstrap code: // This file is automatically generated. Do not manually modify!
> info: [debug] Dynamic bootstrap path: /Users/michalmikolajczyk/Library/Application Support/appium/bootstrap/bootstrap-567117569bfdde4b.js
> info: [debug] Reusing dynamic bootstrap: /Users/michalmikolajczyk/Library/Application Support/appium/bootstrap/bootstrap-567117569bfdde4b.js
> info: [debug] Getting device string from opts: {"forceIphone":false,"forceIpad":false,"xcodeVersion":"6.3.1","iOSSDKVersion":"8.3","deviceName":"=iPad Air","platformVersion":"8.3"}
> info: [debug] We're on iOS7.1+ so forcing defaultDevice on
> info: [debug] Getting device string from opts: {"forceIphone":false,"forceIpad":false,"xcodeVersion":"6.3.1","iOSSDKVersion":"8.3","deviceName":"=iPad Air","platformVersion":"8.3"}
> info: [debug] Parsed app Info.plist (as binary)
> info: [debug] Wrote new app Info.plist with device type
> info: [debug] Checking whether we need to set app preferences
> info: [debug] Running ios sim reset flow
> info: [debug] Killing the simulator process
> info: [debug] Killall iOS Simulator
> info: [debug] Killing any other simulator daemons
> info: [debug] Cleaning sim data files
> info: Executing: xcrun simctl erase F95857C6-DC1C-4918-AE2B-2532F5E50E45 with timeout 2000
> info: [debug] Cleaning app data files
> warn: Applications directory /Users/michalmikolajczyk/Library/Developer/CoreSimulator/Devices/F95857C6-DC1C-4918-AE2B-2532F5E50E45/data/Containers/Data/Application doesn't exist. Have you run this simulator before?
> warn: Applications directory /Users/michalmikolajczyk/Library/Developer/CoreSimulator/Devices/F95857C6-DC1C-4918-AE2B-2532F5E50E45/data/Containers/Bundle/Application doesn't exist. Have you run this simulator before?
> info: Couldn't find app directories to delete. Probably it's not installed
> info: [debug] Not setting locale
> info: [debug] No iOS / app preferences to set
> info: [debug] Starting iOS 8.\* simulator log capture
> info: [debug] Not pre-launching simulator
> info: [debug] No device id or app, not installing to real device.
> info: [debug] Starting command proxy.
> info: [debug] Instruments socket server started at /tmp/instruments_sock
> info: [debug] Starting instruments
> info: [debug] Instruments is at: /Applications/Xcode.app/Contents/Developer/usr/bin/instruments
> info: Launching instruments
> info: [debug] Attempting to run app on iPad Air
> info: [debug] 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 /tmp/appium-instruments/instrumentscli0.trace -w "iPad Air" /var/folders/1b/t3nrqtb93mx1mswtcp3bnh3m0000gn/T/115430-99547-mjxnmi/June20.app -e UIASCRIPT "/Users/michalmikolajczyk/Library/Application Support/appium/bootstrap/bootstrap-567117569bfdde4b.js" -e UIARESULTSPATH /tmp/appium-instruments
> info: [debug] And extra without-delay env: {"DYLD_INSERT_LIBRARIES":"/Users/michalmikolajczyk/local/lib/node_modules/appium/node_modules/appium-instruments/thirdparty/iwd6/InstrumentsShim.dylib","LIB_PATH":"/Users/michalmikolajczyk/local/lib/node_modules/appium/node_modules/appium-instruments/thirdparty/iwd6"}
> info: [debug] And launch timeouts (in ms): {"global":90000}
> info: [debug] [INST] Waiting for device to boot...
> info: Instruments is ready to receive commands
> info: [debug] Instruments launched. Starting poll loop for new commands.
> info: [debug] Setting bootstrap config keys/values
> info: [debug] Pushing command to appium work queue: "target = $.target();\nau = $;\n$.isVerbose = true;\n"
> info: [debug] Socket data received (2 bytes)
> info: [debug] Socket data being routed.
> info: [debug] Sending command to instruments: target = $.target();
> au = $;
> $.isVerbose = true;
>
> info: [debug] [INST] 2015-05-30 07:27:25 +0000 Debug: evaluation finished
> info: [debug] [INST] 2015-05-30 07:27:25 +0000 Debug: responding with:
> info: [debug] [INST] 2015-05-30 07:27:25 +0000 Debug: Running system command #1: /Users/michalmikolajczyk/local/bin/node /Users/michalmikolajczyk/local/lib/node_modules/appium/node_modules/appium-uiauto/bin/command-proxy-client.js /tmp/instruments_sock 2,{"status":0,"value":true}...
> info: [debug] Socket data received (27 bytes)
> info: [debug] Socket data being routed.
> info: [debug] Got result from instruments: {"status":0,"value":true}
> info: [debug] Setting initial orientation to PORTRAIT
> info: [debug] Pushing command to appium work queue: "au.setScreenOrientation('PORTRAIT')"
> info: [debug] Sending command to instruments: au.setScreenOrientation('PORTRAIT')
> info: [debug] [INST] 2015-05-30 07:27:26 +0000 Debug: Got new command 1 from instruments: au.setScreenOrientation('PORTRAIT')
> info: [debug] [INST] 2015-05-30 07:27:26 +0000 Debug: evaluating au.setScreenOrientation('PORTRAIT')
> info: [debug] [INST] 2015-05-30 07:27:26 +0000 Debug: target.setDeviceOrientation("1")
> info: [debug] [INST] 2015-05-30 07:27:26 +0000 Debug: evaluation finished
> info: [debug] [INST] 2015-05-30 07:27:26 +0000 Debug: responding with:
> info: [debug] [INST] 2015-05-30 07:27:26 +0000 Debug: Running system command #2: /Users/michalmikolajczyk/local/bin/node /Users/michalmikolajczyk/local/lib/node_modules/appium/node_modules/appium-uiauto/bin/command-proxy-client.js /tmp/instruments_sock 2,{"status":0,"value":"PORTRAIT"}...
> info: [debug] Socket data received (33 bytes)
> info: [debug] Socket data being routed.
> info: [debug] Got result from instruments: {"status":0,"value":"PORTRAIT"}
> info: [debug] Waiting for app source to contain elements
> info: [debug] Pushing command to appium work queue: "au.mainApp().getTreeForXML()"
> info: [debug] Sending command to instruments: au.mainApp().getTreeForXML()
> info: [debug] [INST] 2015-05-30 07:27:26 +0000 Debug: Got new command 2 from instruments: au.mainApp().getTreeForXML()
> info: [debug] [INST] 2015-05-30 07:27:26 +0000 Debug: evaluating au.mainApp().getTreeForXML()
> info: [debug] [INST] 2015-05-30 07:27:26 +0000 Debug: evaluation finished
> info: [debug] [INST] 2015-05-30 07:27:26 +0000 Debug: responding with:d":true,"visible":true,"hint":null,"path":"/0/0","x":0,"y":0,"width":768,"height":1024},">":[{"UIAStaticText":{"@":{"name":"Offline content","label":"Offline content","value":"Offline content","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/0","x":337,"y":1.5,"width":94.5,"height":17},">":[]}},{"UIAScrollView":{"@":{"name":null,"label":null,"value":null,"dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/1","x":0,"y":20,"width":768,"height":1004},">":[{"UIAImage":{"@":{"name":null,"label":null,"value":null,"dom":null,"enabled":true,"valid":true,"visible":false,"hint":null,"path":"/0/0/1/0","x":765.5,"y":-16,"width":2.5,"height":36},">":[]}},{"UIAWebView":{"@":{"name":null,"label":null,"value":null,"dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/1/1","x":0,"y":20,"width":768,"height":1004},">":[{"UIAStaticText":{"@":{"name":"Timed out!","label":"Timed out!","value":"1","dom":{"id":""},"enabled":true,"valid":true,"visible":true,"hint":"","path":"/0/0/1/1/0","x":251,"y":29,"width":266,"height":74},">":[]}}]}},{"UIAImage":{"@":{"name":null,"label":null,"value":null,"dom":null,"enabled":true,"valid":true,"visible":false,"hint":null,"path":"/0/0/1/2","x":-36,"y":1021.5,"width":36,"height":2.5},">":[]}}]}},{"UIASegmentedControl":{"@":{"name":null,"label":null,"value":null,"dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/2","x":224,"y":8,"width":320,"height":29},">":[{"UIAButton":{"@":{"name":"Camera","label":"Camera","value":"","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/2/0","x":224,"y":8,"width":106,"height":29},">":[]}},{"UIAButton":{"@":{"name":"Content","label":"Content","value":"","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/2/1","x":331,"y":8,"width":106,"height":29},">":[]}},{"UIAButton":{"@":{"name":"Log","label":"Log","value":"","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/2/2","x":438,"y":8,"width":106,"height":29},">":[]}}]}},{"UIAStaticText":{"@":{"name":"version 0.9.2 – beta 1 [DEBUG]\nhttp://192.168.1.100:8080/","label":"version 0.9.2 – beta 1 [DEBUG]\nhttp://192.168.1.100:8080/","value":"version 0.9.2 – beta 1 [DEBUG]\nhttp://192.168.1.100:8080/","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/3","x":559.5,"y":8,"width":200.5,"height":33.5},">":[]}},{"UIAStaticText":{"@":{"name":"Camera Controls","label":"Camera Controls","value":"Camera Controls","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/4","x":291,"y":52,"width":186.5,"height":29},">":[]}},{"UIAStaticText":{"@":{"name":"Beep on scan:","label":"Beep on scan:","value":"Beep on scan:","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/5","x":226,"y":102.5,"width":110.5,"height":20.5},">":[]}},{"UIASwitch":{"@":{"name":null,"label":null,"value":1,"dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/6","x":344.5,"y":97,"width":51,"height":31},">":[]}},{"UIAStaticText":{"@":{"name":"Scan interval:","label":"Scan interval:","value":"Scan interval:","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/7","x":233,"y":135,"width":104,"height":20.5},">":[]}},{"UIASlider":{"@":{"name":null,"label":null,"value":"20%","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/8","x":343,"y":130,"width":191,"height":31},">":[]}},{"UIAStaticText":{"@":{"name":"1.00 seconds","label":"1.00 seconds","value":"1.00 seconds","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/9","x":540,"y":135,"width":103.5,"height":20.5},">":[]}},{"UIAStaticText":{"@":{"name":"Auto-focus:","label":"Auto-focus:","value":"Auto-focus:","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/10","x":246,"y":197.5,"width":90,"height":20.5},">":[]}},{"UIASwitch":{"@":{"name":null,"label":null,"value":1,"dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/11","x":344,"y":192,"width":51,"height":31},">":[]}},{"UIAStaticText":{"@":{"name":"Focus position:","label":"Focus position:","value":"Focus position:","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/12","x":219,"y":234,"width":118,"height":20.5},">":[]}},{"UIASlider":{"@":{"name":null,"label":null,"value":"50%","dom":null,"enabled":false,"valid":true,"visible":true,"hint":null,"path":"/0/0/13","x":343,"y":229,"width":191,"height":31},">":[]}},{"UIAStaticText":{"@":{"name":"0.50","label":"0.50","value":"0.50","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/14","x":540,"y":234,"width":33.5,"height":20.5},">":[]}},{"UIAStaticText":{"@":{"name":"Auto-exposure:","label":"Auto-exposure:","value":"Auto-exposure:","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/15","x":219,"y":296.5,"width":118.5,"height":20.5},">":[]}},{"UIASwitch":{"@":{"name":null,"label":null,"value":1,"dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/16","x":345.5,"y":291,"width":51,"height":31},">":[]}},{"UIAStaticText":{"@":{"name":"Offset:","label":"Offset:","value":"Offset:","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/17","x":284.5,"y":333,"width":51,"height":20.5},">":[]}},{"UIASlider":{"@":{"name":null,"label":null,"value":"NaN","dom":null,"enabled":false,"valid":true,"visible":true,"hint":null,"path":"/0/0/18","x":341.5,"y":328,"width":191,"height":31},">":[]}},{"UIAStaticText":{"@":{"name":"0","label":"0","value":"0","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/19","x":538.5,"y":333,"width":10,"height":20.5},">":[]}},{"UIAStaticText":{"@":{"name":"Shutter speed:","label":"Shutter speed:","value":"Shutter speed:","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/20","x":222.5,"y":369.5,"width":113,"height":20.5},">":[]}},{"UIASlider":{"@":{"name":null,"label":null,"value":"0%","dom":null,"enabled":false,"valid":true,"visible":true,"hint":null,"path":"/0/0/21","x":341.5,"y":364.5,"width":191,"height":31},">":[]}},{"UIAStaticText":{"@":{"name":"1/inf","label":"1/inf","value":"1/inf","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/22","x":538.5,"y":369.5,"width":34,"height":20.5},">":[]}},{"UIAStaticText":{"@":{"name":"ISO:","label":"ISO:","value":"ISO:","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/23","x":303.5,"y":406,"width":33.5,"height":20.5},">":[]}},{"UIASlider":{"@":{"name":null,"label":null,"value":"NaN","dom":null,"enabled":false,"valid":true,"visible":true,"hint":null,"path":"/0/0/24","x":343,"y":401,"width":191,"height":31},">":[]}},{"UIAStaticText":{"@":{"name":"0.50","label":"0.50","value":"0.50","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/25","x":540,"y":406,"width":33.5,"height":20.5},">":[]}},{"UIAStaticText":{"@":{"name":"Target bias:","label":"Target bias:","value":"Target bias:","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/26","x":249,"y":442.5,"width":88.5,"height":20.5},">":[]}},{"UIASlider":{"@":{"name":null,"label":null,"value":"NaN","dom":null,"enabled":false,"valid":true,"visible":true,"hint":null,"path":"/0/0/27","x":343.5,"y":437.5,"width":191,"height":31},">":[]}},{"UIAStaticText":{"@":{"name":"0.50","label":"0.50","value":"0.50","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/28","x":540.5,"y":442.5,"width":33.5,"height":20.5},">":[]}},{"UIAStaticText":{"@":{"name":"Auto-white balance:","label":"Auto-white balance:","value":"Auto-white balance:","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/29","x":183.5,"y":505,"width":153.5,"height":20.5},">":[]}},{"UIASwitch":{"@":{"name":null,"label":null,"value":1,"dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/30","x":345,"y":499.5,"width":51,"height":31},">":[]}},{"UIAStaticTex
> info: [debug] [INST] t":{"@":{"name":"Temperature:","label":"Temperature:","value":"Temperature:","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/31","x":237,"y":557.5,"width":100.5,"height":20.5},">":[]}},{"UIASlider":{"@":{"name":null,"label":null,"value":"0%","dom":null,"enabled":false,"valid":true,"visible":true,"hint":null,"path":"/0/0/32","x":343.5,"y":552.5,"width":191,"height":31},">":[]}},{"UIAStaticText":{"@":{"name":"0.00","label":"0.00","value":"0.00","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/33","x":540.5,"y":557.5,"width":33.5,"height":20.5},">":[]}},{"UIAStaticText":{"@":{"name":"Tint:","label":"Tint:","value":"Tint:","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/34","x":302.5,"y":594,"width":33.5,"height":20.5},">":[]}},{"UIASlider":{"@":{"name":null,"label":null,"value":"50%","dom":null,"enabled":false,"valid":true,"visible":true,"hint":null,"path":"/0/0/35","x":342,"y":589,"width":191,"height":31},">":[]}},{"UIAStaticText":{"@":{"name":"0.00","label":"0.00","value":"0.00","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/36","x":539,"y":594,"width":33.5,"height":20.5},">":[]}},{"UIAStaticText":{"@":{"name":"Overlay brightness:","label":"Overlay brightness:","value":"Overlay brightness:","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/37","x":8,"y":991,"width":148.5,"height":20.5},">":[]}},{"UIASlider":{"@":{"name":null,"label":null,"value":"25%","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/38","x":162.5,"y":986,"width":462,"height":31},">":[]}},{"UIAStaticText":{"@":{"name":"CPU: 30%","label":"CPU: 30%","value":"CPU: 30%","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/39","x":630.5,"y":991,"width":81.5,"height":20.5},">":[]}},{"UIAButton":{"@":{"name":"Close","label":"Close","value":null,"dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/40","x":720,"y":986,"width":40,"height":30},">":[]}}]}},{"UIAWindow":{"@":{"name":null,"label":null,"value":null,"dom":null,"enabled":true,"valid":true,"visible":false,"hint":null,"path":"/0/1","x":0,"y":0,"width":768,"height":1024},">":[{"UIAStatusBar":{"@":{"name":null,"label":null,"value":null,"dom":null,"enabled":true,"valid":true,"visible":false,"hint":null,"path":"/0/1/0","x":0,"y":0,"width":768,"height":0},">":[]}}]}}]}}}
> info: [debug] [INST] 2015-05-30 07:27:26 +0000 Debug: Running system command #3: /Users/michalmikolajczyk/local/bin/node /Users/michalmikolajczyk/local/lib/node_modules/appium/node_modules/appium-uiauto/bin/command-proxy-client.js /tmp/instruments_sock 2,{"status":0,"value":{"UIAApplication":{"@":{"name":"June20","label":"June20","value":null,"dom":null,"enabled":true,"valid":tr...
> info: [debug] Socket data received (8186 bytes)
> info: [debug] Socket data received (2705 bytes)
> info: [debug] Socket data being routed.
> info: [debug] Got result from instruments: {"status":0,"value":{"UIAApplication":{"@":{"name":"June20","label":"June20","value":null,"dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0","x":0,"y":0,"width":768,"height":1024},">":[{"UIAWindow":{"@":{"name":null,"label":null,"value":null,"dom":null,"enabled":true,"vali
> info: [debug] Device launched! Ready for commands
> info: [debug] Setting command timeout to the default of 60 secs
> info: [debug] Appium session started with sessionId aec8da59-9600-42d8-9003-d7c36d638c8f
> info: <-- POST /wd/hub/session 303 21300.420 ms - 74
> info: --> POST /wd/hub/session/aec8da59-9600-42d8-9003-d7c36d638c8f/element {"using":"name","value":"Content"}
> warn: [DEPRECATED] The name locator strategy has been deprecated and will be removed. Please use the accessibility id locator strategy instead.
> info: [debug] Waiting up to 0ms for condition
> info: [debug] Pushing command to appium work queue: "au.getElementByName('Content')"
> info: [debug] Sending command to instruments: au.getElementByName('Content')
> info: [debug] [INST] 2015-05-30 07:27:31 +0000 Debug: Got new command 3 from instruments: au.getElementByName('Content')
> info: [debug] [INST] 2015-05-30 07:27:31 +0000 Debug: evaluating au.getElementByName('Content')
> info: [debug] [INST] 2015-05-30 07:27:31 +0000 Debug: evaluation finished
> info: [debug] [INST] 2015-05-30 07:27:31 +0000 Debug: Lookup returned [object UIAButton] with the name "Content" (id: 0).
> info: [debug] [INST] 2015-05-30 07:27:31 +0000 Debug: responding with:
> info: [debug] [INST] 2015-05-30 07:27:31 +0000 Debug: Running system command #4: /Users/michalmikolajczyk/local/bin/node /Users/michalmikolajczyk/local/lib/node_modules/appium/node_modules/appium-uiauto/bin/command-proxy-client.js /tmp/instruments_sock 2,{"status":0,"value":{"ELEMENT":"0"}}...
> info: [debug] Socket data received (38 bytes)
> info: [debug] Socket data being routed.
> info: [debug] Got result from instruments: {"status":0,"value":{"ELEMENT":"0"}}
> info: [debug] Responding to client with success: {"status":0,"value":{"ELEMENT":"0"},"sessionId":"aec8da59-9600-42d8-9003-d7c36d638c8f"}
> info: <-- POST /wd/hub/session/aec8da59-9600-42d8-9003-d7c36d638c8f/element 200 247.621 ms - 87 {"status":0,"value":{"ELEMENT":"0"},"sessionId":"aec8da59-9600-42d8-9003-d7c36d638c8f"}
> info: --> POST /wd/hub/session/aec8da59-9600-42d8-9003-d7c36d638c8f/element/0/click {}
> info: [debug] Pushing command to appium work queue: "au.tapById('0')"
> info: [debug] Sending command to instruments: au.tapById('0')
> info: [debug] [INST] 2015-05-30 07:27:31 +0000 Debug: Got new command 4 from instruments: au.tapById('0')
> info: [debug] [INST] 2015-05-30 07:27:31 +0000 Debug: evaluating au.tapById('0')
> info: [debug] [INST] 2015-05-30 07:27:31 +0000 Debug: UIAButton.tap()
> info: [debug] [INST] 2015-05-30 07:27:31 +0000 Debug: evaluation finished
> info: [debug] [INST] 2015-05-30 07:27:31 +0000 Debug: responding with:
> info: [debug] [INST] 2015-05-30 07:27:31 +0000 Debug: Running system command #5: /Users/michalmikolajczyk/local/bin/node /Users/michalmikolajczyk/local/lib/node_modules/appium/node_modules/appium-uiauto/bin/command-proxy-client.js /tmp/instruments_sock 2,{"status":0,"value":""}...
> info: [debug] Socket data received (25 bytes)
> info: [debug] Socket data being routed.
> info: [debug] Got result from instruments: {"status":0,"value":""}
> info: [debug] Responding to client with success: {"status":0,"value":"","sessionId":"aec8da59-9600-42d8-9003-d7c36d638c8f"}
> info: <-- POST /wd/hub/session/aec8da59-9600-42d8-9003-d7c36d638c8f/element/0/click 200 319.797 ms - 74 {"status":0,"value":"","sessionId":"aec8da59-9600-42d8-9003-d7c36d638c8f"}
> info: --> POST /wd/hub/session/aec8da59-9600-42d8-9003-d7c36d638c8f/element {"using":"xpath","value":"//UIAApplication[1]/UIAWindow[1]/UIATextField[1]"}
> info: [debug] Waiting up to 0ms for condition
> info: [debug] Pushing command to appium work queue: "au.mainApp().getTreeForXML()"
> info: [debug] Sending command to instruments: au.mainApp().getTreeForXML()
> info: [debug] [INST] 2015-05-30 07:27:34 +0000 Debug: Got new command 5 from instruments: au.mainApp().getTreeForXML()
> info: [debug] [INST] 2015-05-30 07:27:34 +0000 Debug: evaluating au.mainApp().getTreeForXML()
> info: [debug] [INST] 2015-05-30 07:27:34 +0000 Debug: evaluation finished
> info: [debug] [INST] 2015-05-30 07:27:34 +0000 Debug: responding with:d":true,"visible":true,"hint":null,"path":"/0/0","x":0,"y":0,"width":768,"height":1024},">":[{"UIAStaticText":{"@":{"name":"Offline content","label":"Offline content","value":"Offline content","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/0","x":337,"y":1.5,"width":94.5,"height":17},">":[]}},{"UIAScrollView":{"@":{"name":null,"label":null,"value":null,"dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/1","x":0,"y":20,"width":768,"height":1004},">":[{"UIAImage":{"@":{"name":null,"label":null,"value":null,"dom":null,"enabled":true,"valid":true,"visible":false,"hint":null,"path":"/0/0/1/0","x":765.5,"y":-16,"width":2.5,"height":36},">":[]}},{"UIAWebView":{"@":{"name":null,"label":null,"value":null,"dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/1/1","x":0,"y":20,"width":768,"height":1004},">":[{"UIAStaticText":{"@":{"name":"Timed out!","label":"Timed out!","value":"1","dom":{"id":""},"enabled":true,"valid":true,"visible":true,"hint":"","path":"/0/0/1/1/0","x":251,"y":29,"width":266,"height":74},">":[]}}]}},{"UIAImage":{"@":{"name":null,"label":null,"value":null,"dom":null,"enabled":true,"valid":true,"visible":false,"hint":null,"path":"/0/0/1/2","x":-36,"y":1021.5,"width":36,"height":2.5},">":[]}}]}},{"UIASegmentedControl":{"@":{"name":null,"label":null,"value":null,"dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/2","x":224,"y":8,"width":320,"height":29},">":[{"UIAButton":{"@":{"name":"Camera","label":"Camera","value":"","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/2/0","x":224,"y":8,"width":106,"height":29},">":[]}},{"UIAButton":{"@":{"name":"Content","label":"Content","value":"","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/2/1","x":331,"y":8,"width":106,"height":29},">":[]}},{"UIAButton":{"@":{"name":"Log","label":"Log","value":"","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/2/2","x":438,"y":8,"width":106,"height":29},">":[]}}]}},{"UIAStaticText":{"@":{"name":"version 0.9.2 – beta 1 [DEBUG]\nhttp://192.168.1.100:8080/","label":"version 0.9.2 – beta 1 [DEBUG]\nhttp://192.168.1.100:8080/","value":"version 0.9.2 – beta 1 [DEBUG]\nhttp://192.168.1.100:8080/","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/3","x":559.5,"y":8,"width":200.5,"height":33.5},">":[]}},{"UIAButton":{"@":{"name":"Reset to default","label":"Reset to default","value":null,"dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/4","x":114,"y":57,"width":112,"height":30},">":[]}},{"UIATextField":{"@":{"name":"urlField","label":"urlField","value":"http://localhost:8080/config/index.html","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/5","x":234,"y":57,"width":300,"height":30},">":[{"UIATextField":{"@":{"name":"urlField","label":"urlField","value":"http://localhost:8080/config/index.html","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/5/0","x":234,"y":57,"width":300,"height":30},">":[]}}]}},{"UIAButton":{"@":{"name":"Load URL","label":"Load URL","value":null,"dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/6","x":542,"y":57,"width":70,"height":30},">":[]}},{"UIAButton":{"@":{"name":"Reload Config","label":"Reload Config","value":null,"dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/7","x":660,"y":57,"width":100,"height":30},">":[]}},{"UIAStaticText":{"@":{"name":"Local Cache","label":"Local Cache","value":"Local Cache","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/8","x":315.5,"y":103,"width":137.5,"height":29},">":[]}},{"UIATableView":{"@":{"name":null,"label":null,"value":null,"dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/9","x":0,"y":140,"width":768,"height":838},">":[{"UIATableGroup":{"@":{"name":"Free space: 4.00 GB - Total space: 111.86 GB","label":null,"value":null,"dom":null,"enabled":true,"valid":true,"visible":false,"hint":null,"path":"/0/0/9/0","x":0,"y":140,"width":768,"height":70},">":[{"UIAStaticText":{"@":{"name":"Free space: 4.00 GB - Total space: 111.86 GB","label":"Free space: 4.00 GB - Total space: 111.86 GB","value":"Free space: 4.00 GB - Total space: 111.86 GB","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/9/0/0","x":8,"y":145,"width":352.5,"height":20.5},">":[]}},{"UIAButton":{"@":{"name":"Empty Cache","label":"Empty Cache","value":null,"dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/9/0/1","x":665,"y":140,"width":95,"height":30},">":[]}},{"UIAStaticText":{"@":{"name":"\"Offline Content\" bar:","label":"\"Offline Content\" bar:","value":"\"Offline Content\" bar:","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/9/0/2","x":8,"y":176.5,"width":165,"height":20.5},">":[]}},{"UIASwitch":{"@":{"name":null,"label":null,"value":1,"dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/9/0/3","x":181,"y":171,"width":51,"height":31},">":[]}},{"UIAStaticText":{"@":{"name":"Show Javascript errors:","label":"Show Javascript errors:","value":"Show Javascript errors:","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/9/0/4","x":294,"y":176.5,"width":181,"height":20.5},">":[]}},{"UIASwitch":{"@":{"name":null,"label":null,"value":0,"dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/9/0/5","x":483,"y":171,"width":51,"height":31},">":[]}}]}}]}},{"UIAStaticText":{"@":{"name":"Overlay brightness:","label":"Overlay brightness:","value":"Overlay brightness:","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/10","x":8,"y":991,"width":148.5,"height":20.5},">":[]}},{"UIASlider":{"@":{"name":null,"label":null,"value":"25%","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/11","x":162.5,"y":986,"width":471.5,"height":31},">":[]}},{"UIAStaticText":{"@":{"name":"CPU: 0%","label":"CPU: 0%","value":"CPU: 0%","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/12","x":640,"y":991,"width":72,"height":20.5},">":[]}},{"UIAButton":{"@":{"name":"Close","label":"Close","value":null,"dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/13","x":720,"y":986,"width":40,"height":30},">":[]}}]}},{"UIAWindow":{"@":{"name":null,"label":null,"value":null,"dom":null,"enabled":true,"valid":true,"visible":false,"hint":null,"path":"/0/1","x":0,"y":0,"width":768,"height":1024},">":[{"UIAStatusBar":{"@":{"name":null,"label":null,"value":null,"dom":null,"enabled":true,"valid":true,"visible":false,"hint":null,"path":"/0/1/0","x":0,"y":0,"width":768,"height":0},">":[]}}]}}]}}}
> info: [debug] [INST] 2015-05-30 07:27:34 +0000 Debug: Running system command #6: /Users/michalmikolajczyk/local/bin/node /Users/michalmikolajczyk/local/lib/node_modules/appium/node_modules/appium-uiauto/bin/command-proxy-client.js /tmp/instruments_sock 2,{"status":0,"value":{"UIAApplication":{"@":{"name":"June20","label":"June20","value":null,"dom":null,"enabled":true,"valid":tr...
> info: [debug] Socket data received (7175 bytes)
> info: [debug] Socket data being routed.
> info: [debug] Got result from instruments: {"status":0,"value":{"UIAApplication":{"@":{"name":"June20","label":"June20","value":null,"dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0","x":0,"y":0,"width":768,"height":1024},">":[{"UIAWindow":{"@":{"name":null,"label":null,"value":null,"dom":null,"enabled":true,"vali
> info: [debug] Pushing command to appium work queue: "au.getElementByIndexPath('/0/0/5')"
> info: [debug] Sending command to instruments: au.getElementByIndexPath('/0/0/5')
> info: [debug] [INST] 2015-05-30 07:27:34 +0000 Debug: Got new command 6 from instruments: au.getElementByIndexPath('/0/0/5')
> info: [debug] [INST] 2015-05-30 07:27:34 +0000 Debug: evaluating au.getElementByIndexPath('/0/0/5')
> info: [debug] [INST] 2015-05-30 07:27:34 +0000 Debug: evaluation finished
> info: [debug] [INST] 2015-05-30 07:27:34 +0000 Debug: Lookup returned [object UIATextField] with the name "urlField" (id: 1).
> info: [debug] [INST] 2015-05-30 07:27:34 +0000 Debug: responding with:
> info: [debug] [INST] 2015-05-30 07:27:34 +0000 Debug: Running system command #7: /Users/michalmikolajczyk/local/bin/node /Users/michalmikolajczyk/local/lib/node_modules/appium/node_modules/appium-uiauto/bin/command-proxy-client.js /tmp/instruments_sock 2,{"status":0,"value":{"ELEMENT":"1"}}...
> info: [debug] Socket data received (38 bytes)
> info: [debug] Socket data being routed.
> info: [debug] Got result from instruments: {"status":0,"value":{"ELEMENT":"1"}}
> info: [debug] Responding to client with success: {"status":0,"value":{"ELEMENT":"1"},"sessionId":"aec8da59-9600-42d8-9003-d7c36d638c8f"}
> info: <-- POST /wd/hub/session/aec8da59-9600-42d8-9003-d7c36d638c8f/element 200 295.024 ms - 87 {"status":0,"value":{"ELEMENT":"1"},"sessionId":"aec8da59-9600-42d8-9003-d7c36d638c8f"}
> info: --> POST /wd/hub/session/aec8da59-9600-42d8-9003-d7c36d638c8f/element/1/clear {}
> info: [debug] Pushing command to appium work queue: "au.getElement('1').setValue('')"
> info: [debug] Sending command to instruments: au.getElement('1').setValue('')
> info: [debug] [INST] 2015-05-30 07:27:34 +0000 Debug: Got new command 7 from instruments: au.getElement('1').setValue('')
> info: [debug] [INST] 2015-05-30 07:27:34 +0000 Debug: evaluating au.getElement('1').setValue('')
> info: [debug] [INST] 2015-05-30 07:27:34 +0000 Debug: target.frontMostApp().elements()[0].elements()[5].tap()
> info: [debug] [INST] 2015-05-30 07:27:35 +0000 Debug: evaluation finished
> info: [debug] [INST] 2015-05-30 07:27:35 +0000 Debug: responding with:
> info: [debug] [INST] 2015-05-30 07:27:35 +0000 Debug: Running system command #8: /Users/michalmikolajczyk/local/bin/node /Users/michalmikolajczyk/local/lib/node_modules/appium/node_modules/appium-uiauto/bin/command-proxy-client.js /tmp/instruments_sock 2,{"status":0,"value":""}...
> info: [debug] Socket data received (25 bytes)
> info: [debug] Socket data being routed.
> info: [debug] Got result from instruments: {"status":0,"value":""}
> info: [debug] Responding to client with success: {"status":0,"value":"","sessionId":"aec8da59-9600-42d8-9003-d7c36d638c8f"}
> info: <-- POST /wd/hub/session/aec8da59-9600-42d8-9003-d7c36d638c8f/element/1/clear 200 651.008 ms - 74 {"status":0,"value":"","sessionId":"aec8da59-9600-42d8-9003-d7c36d638c8f"}
> info: --> POST /wd/hub/session/aec8da59-9600-42d8-9003-d7c36d638c8f/element/1/value {"value":["http://0.0.0.0:8888/test/e2e/analytics/"]}
> info: [debug] Pushing command to appium work queue: "au.getElement('1').setValueByType('http:\/\/0.0.0.0:8888\/test\/e2e\/analytics\/')"
> info: [debug] Sending command to instruments: au.getElement('1').setValueByType('http:\/\/0.0.0.0:8888\/test\/e2e\/analytics\/')
> info: [debug] [INST] 2015-05-30 07:27:35 +0000 Debug: Got new command 8 from instruments: au.getElement('1').setValueByType('http:\/\/0.0.0.0:8888\/test\/e2e\/analytics\/')
> info: [debug] [INST] 2015-05-30 07:27:35 +0000 Debug: evaluating au.getElement('1').setValueByType('http:\/\/0.0.0.0:8888\/test\/e2e\/analytics\/')
> info: [debug] [INST] 2015-05-30 07:27:35 +0000 Debug: target.frontMostApp().keyboard().typeString("h")
> info: [debug] [INST] 2015-05-30 07:27:36 +0000 Debug: target.frontMostApp().keyboard().typeString("t")
> info: [debug] [INST] 2015-05-30 07:27:36 +0000 Debug: target.frontMostApp().keyboard().typeString("t")
> info: [debug] [INST] 2015-05-30 07:27:37 +0000 Debug: target.frontMostApp().keyboard().typeString("p")
> info: [debug] [INST] 2015-05-30 07:27:37 +0000 Debug: target.frontMostApp().keyboard().typeString(":")
> info: [debug] [INST] 2015-05-30 07:27:37 +0000 Debug: target.frontMostApp().keyboard().typeString("/")
> info: [debug] [INST] 2015-05-30 07:27:38 +0000 Debug: target.frontMostApp().keyboard().typeString("/")
> info: [debug] [INST] 2015-05-30 07:27:38 +0000 Debug: target.frontMostApp().keyboard().typeString("0")
> info: [debug] [INST] 2015-05-30 07:27:39 +0000 Debug: target.frontMostApp().keyboard().typeString(".")
> info: [debug] [INST] 2015-05-30 07:27:39 +0000 Debug: target.frontMostApp().keyboard().typeString("0")
> info: [debug] [INST] 2015-05-30 07:27:40 +0000 Debug: target.frontMostApp().keyboard().typeString(".")
> info: [debug] [INST] 2015-05-30 07:27:40 +0000 Debug: target.frontMostApp().keyboard().typeString("0")
> info: [debug] [INST] 2015-05-30 07:27:40 +0000 Debug: target.frontMostApp().keyboard().typeString(".")
> info: [debug] [INST] 2015-05-30 07:27:41 +0000 Debug: target.frontMostApp().keyboard().typeString("0")
> info: [debug] [INST] 2015-05-30 07:27:41 +0000 Debug: target.frontMostApp().keyboard().typeString(":")
> info: [debug] [INST] 2015-05-30 07:27:42 +0000 Debug: target.frontMostApp().keyboard().typeString("8")
> info: [debug] [INST] 2015-05-30 07:27:42 +0000 Debug: target.frontMostApp().keyboard().typeString("8")
> info: [debug] [INST] 2015-05-30 07:27:42 +0000 Debug: target.frontMostApp().keyboard().typeString("8")
> info: [debug] [INST] 2015-05-30 07:27:43 +0000 Debug: target.frontMostApp().keyboard().typeString("8")
> info: [debug] [INST] 2015-05-30 07:27:43 +0000 Debug: target.frontMostApp().keyboard().typeString("/")
> info: [debug] [INST] 2015-05-30 07:27:43 +0000 Debug: target.frontMostApp().keyboard().typeString("t")
> info: [debug] [INST] 2015-05-30 07:27:44 +0000 Debug: target.frontMostApp().keyboard().typeString("e")
> info: [debug] [INST] 2015-05-30 07:27:44 +0000 Debug: target.frontMostApp().keyboard().typeString("s")
> info: [debug] [INST] 2015-05-30 07:27:45 +0000 Debug: target.frontMostApp().keyboard().typeString("t")
> info: [debug] [INST] 2015-05-30 07:27:45 +0000 Debug: target.frontMostApp().keyboard().typeString("/")
> info: [debug] [INST] 2015-05-30 07:27:45 +0000 Debug: target.frontMostApp().keyboard().typeString("e")
> info: [debug] [INST] 2015-05-30 07:27:46 +0000 Debug: target.frontMostApp().keyboard().typeString("2")
> info: [debug] [INST] 2015-05-30 07:27:46 +0000 Debug: target.frontMostApp().keyboard().typeString("e")
> info: [debug] [INST] 2015-05-30 07:27:47 +0000 Debug: target.frontMostApp().keyboard().typeString("/")
> info: [debug] [INST] 2015-05-30 07:27:47 +0000 Debug: target.frontMostApp().keyboard().typeString("a")
> info: [debug] [INST] 2015-05-30 07:27:48 +0000 Debug: target.frontMostApp().keyboard().typeString("n")
> info: [debug] [INST] 2015-05-30 07:27:48 +0000 Debug: target.frontMostApp().keyboard().typeString("a")
> info: [debug] [INST] 2015-05-30 07:27:48 +0000 Debug: target.frontMostApp().keyboard().typeString("l")
> info: [debug] [INST] 2015-05-30 07:27:49 +0000 Debug: target.frontMostApp().keyboard().typeString("y")
> info: [debug] [INST] 2015-05-30 07:27:49 +0000 Debug: target.frontMostApp().keyboard().typeString("t")
> info: [debug] [INST] 2015-05-30 07:27:49 +0000 Debug: target.frontMostApp().keyboard().typeString("i")
> info: [debug] [INST] 2015-05-30 07:27:50 +0000 Debug: target.frontMostApp().keyboard().typeString("c")
> info: [debug] [INST] 2015-05-30 07:27:50 +0000 Debug: target.frontMostApp().keyboard().typeString("s")
> info: [debug] [INST] 2015-05-30 07:27:51 +0000 Debug: target.frontMostApp().keyboard().typeString("/")
> info: [debug] [INST] 2015-05-30 07:27:51 +0000 Debug: evaluation finished
> info: [debug] [INST] 2015-05-30 07:27:51 +0000 Debug: responding with:
> info: [debug] [INST] 2015-05-30 07:27:51 +0000 Debug: Running system command #9: /Users/michalmikolajczyk/local/bin/node /Users/michalmikolajczyk/local/lib/node_modules/appium/node_modules/appium-uiauto/bin/command-proxy-client.js /tmp/instruments_sock 2,{"status":0,"value":""}...
> info: [debug] Socket data received (25 bytes)
> info: [debug] Socket data being routed.
> info: [debug] Got result from instruments: {"status":0,"value":""}
> info: [debug] Responding to client with success: {"status":0,"value":"","sessionId":"aec8da59-9600-42d8-9003-d7c36d638c8f"}
> info: <-- POST /wd/hub/session/aec8da59-9600-42d8-9003-d7c36d638c8f/element/1/value 200 16510.079 ms - 74 {"status":0,"value":"","sessionId":"aec8da59-9600-42d8-9003-d7c36d638c8f"}
> info: --> POST /wd/hub/session/aec8da59-9600-42d8-9003-d7c36d638c8f/element {"using":"name","value":"Load URL"}
> info: [debug] Waiting up to 0ms for condition
> info: [debug] Pushing command to appium work queue: "au.getElementByName('Load URL')"
> info: [debug] Sending command to instruments: au.getElementByName('Load URL')
> info: [debug] [INST] 2015-05-30 07:27:51 +0000 Debug: Got new command 9 from instruments: au.getElementByName('Load URL')
> info: [debug] [INST] 2015-05-30 07:27:51 +0000 Debug: evaluating au.getElementByName('Load URL')
> info: [debug] [INST] 2015-05-30 07:27:52 +0000 Debug: evaluation finished
> info: [debug] [INST] 2015-05-30 07:27:52 +0000 Debug: Lookup returned [object UIAButton] with the name "Load URL" (id: 2).
> info: [debug] [INST] 2015-05-30 07:27:52 +0000 Debug: responding with:
> info: [debug] [INST] 2015-05-30 07:27:52 +0000 Debug: Running system command #10: /Users/michalmikolajczyk/local/bin/node /Users/michalmikolajczyk/local/lib/node_modules/appium/node_modules/appium-uiauto/bin/command-proxy-client.js /tmp/instruments_sock 2,{"status":0,"value":{"ELEMENT":"2"}}...
> info: [debug] Socket data received (38 bytes)
> info: [debug] Socket data being routed.
> info: [debug] Got result from instruments: {"status":0,"value":{"ELEMENT":"2"}}
> info: [debug] Responding to client with success: {"status":0,"value":{"ELEMENT":"2"},"sessionId":"aec8da59-9600-42d8-9003-d7c36d638c8f"}
> info: <-- POST /wd/hub/session/aec8da59-9600-42d8-9003-d7c36d638c8f/element 200 145.680 ms - 87 {"status":0,"value":{"ELEMENT":"2"},"sessionId":"aec8da59-9600-42d8-9003-d7c36d638c8f"}
> info: --> POST /wd/hub/session/aec8da59-9600-42d8-9003-d7c36d638c8f/element/2/click {}
> info: [debug] Pushing command to appium work queue: "au.tapById('2')"
> info: [debug] Sending command to instruments: au.tapById('2')
> info: [debug] [INST] 2015-05-30 07:27:52 +0000 Debug: Got new command 10 from instruments: au.tapById('2')
> info: [debug] [INST] 2015-05-30 07:27:52 +0000 Debug: evaluating au.tapById('2')
> info: [debug] [INST] 2015-05-30 07:27:52 +0000 Debug: UIAButton.tap()
> info: [debug] [INST] 2015-05-30 07:27:52 +0000 Debug: evaluation finished
> info: [debug] [INST] 2015-05-30 07:27:52 +0000 Debug: responding with:
> info: [debug] [INST] 2015-05-30 07:27:52 +0000 Debug: Running system command #11: /Users/michalmikolajczyk/local/bin/node /Users/michalmikolajczyk/local/lib/node_modules/appium/node_modules/appium-uiauto/bin/command-proxy-client.js /tmp/instruments_sock 2,{"status":0,"value":""}...
> info: [debug] Socket data received (25 bytes)
> info: [debug] Socket data being routed.
> info: [debug] Got result from instruments: {"status":0,"value":""}
> info: [debug] Responding to client with success: {"status":0,"value":"","sessionId":"aec8da59-9600-42d8-9003-d7c36d638c8f"}
> info: <-- POST /wd/hub/session/aec8da59-9600-42d8-9003-d7c36d638c8f/element/2/click 200 280.456 ms - 74 {"status":0,"value":"","sessionId":"aec8da59-9600-42d8-9003-d7c36d638c8f"}
> info: --> GET /wd/hub/session/aec8da59-9600-42d8-9003-d7c36d638c8f/contexts {}
> info: [debug] [REMOTE] Debugger socket connected to ::1:27753
> info: [debug] [REMOTE] Sending connection key
> info: [debug] [REMOTE] Sending _rpc_reportIdentifier: message to remote debugger
> info: [debug] [REMOTE] {"__argument":{"WIRConnectionIdentifierKey":"3059539b-ba5f-45d3-99d5-cf91f5a9a03f"},"__selector":"_rpc_reportIdentifier:"}
> info: [debug] [REMOTE] Receiving data from remote debugger
> info: [debug] [REMOTE] {"__argument":{"WIRSimulatorBuildKey":"12F69","WIRSimulatorProductVersionKey":"8.3","WIRSimulatorNameKey":"iPad Simulator"},"__selector":"_rpc_reportSetup:"}
> info: [debug] [REMOTE] Sim name: iPad Simulator
> info: [debug] [REMOTE] Sim build: 12F69
> info: [debug] [REMOTE] Receiving data from remote debugger
> info: [debug] [REMOTE] {"__argument":{"WIRApplicationDictionaryKey":{"PID:99650":{"WIRApplicationIdentifierKey":"PID:99650","WIRIsApplicationProxyKey":false,"WIRApplicationNameKey":"June20","WIRApplicationBundleIdentifierKey":"com.june20.June20","WIRIsApplicationActiveKey":1}}},"__selector":"_rpc_reportConnectedApplicationList:"}
> info: [debug] Using remote debugger app key: PID:99650
> info: [debug] [REMOTE] Selecting app PID:99650 (try #1)
> info: [debug] [REMOTE] function () { [native code] }
> info: [debug] [REMOTE] Sending _rpc_forwardGetListing: message to remote debugger
> info: [debug] [REMOTE] {"__argument":{"WIRConnectionIdentifierKey":"3059539b-ba5f-45d3-99d5-cf91f5a9a03f","WIRApplicationIdentifierKey":"PID:99650"},"__selector":"_rpc_forwardGetListing:"}
> info: [debug] [REMOTE] Receiving data from remote debugger
> info: [debug] [REMOTE] Receiving data from remote debugger
> info: [debug] [REMOTE] {"__argument":{"WIRApplicationIdentifierKey":"PID:99690","WIRIsApplicationProxyKey":true,"WIRApplicationNameKey":"","WIRApplicationBundleIdentifierKey":"com.apple.WebKit.WebContent","WIRIsApplicationActiveKey":0,"WIRHostApplicationIdentifierKey":"PID:99650"},"__selector":"_rpc_applicationConnected:"}
> info: [REMOTE] We were notified we connected to possibly the wrong app. Using the id key suggested and trying again
> info: [debug] [REMOTE] Selecting app PID:99690 (try #2)
> info: [debug] [REMOTE] function () { [native code] }
> info: [debug] [REMOTE] Sending _rpc_forwardGetListing: message to remote debugger
> info: [debug] [REMOTE] {"__argument":{"WIRConnectionIdentifierKey":"3059539b-ba5f-45d3-99d5-cf91f5a9a03f","WIRApplicationIdentifierKey":"PID:99690"},"__selector":"_rpc_forwardGetListing:"}
> info: [debug] [REMOTE] Receiving data from remote debugger
> info: [debug] [REMOTE] Receiving data from remote debugger
> info: [debug] [REMOTE] {"__argument":{"WIRApplicationIdentifierKey":"PID:99666","WIRIsApplicationProxyKey":true,"WIRApplicationNameKey":"","WIRApplicationBundleIdentifierKey":"com.apple.WebKit.WebContent","WIRIsApplicationActiveKey":0,"WIRHostApplicationIdentifierKey":"PID:99650"},"__selector":"_rpc_applicationConnected:"}
> info: [REMOTE] We were notified we connected to possibly the wrong app. Using the id key suggested and trying again
> info: [debug] [REMOTE] Selecting app PID:99666 (try #3)
> info: [debug] [REMOTE] function () { [native code] }
> info: [debug] [REMOTE] Sending _rpc_forwardGetListing: message to remote debugger
> info: [debug] [REMOTE] {"__argument":{"WIRConnectionIdentifierKey":"3059539b-ba5f-45d3-99d5-cf91f5a9a03f","WIRApplicationIdentifierKey":"PID:99666"},"__selector":"_rpc_forwardGetListing:"}
> info: [debug] [REMOTE] Receiving data from remote debugger
> info: [debug] [REMOTE] {"__argument":{"WIRApplicationIdentifierKey":"PID:99662","WIRIsApplicationProxyKey":true,"WIRApplicationNameKey":"","WIRApplicationBundleIdentifierKey":"com.apple.WebKit.WebContent","WIRIsApplicationActiveKey":0,"WIRHostApplicationIdentifierKey":"PID:99650"},"__selector":"_rpc_applicationConnected:"}
> info: [REMOTE] We were notified we connected to possibly the wrong app. Using the id key suggested and trying again
> info: [debug] [REMOTE] Selecting app PID:99662 (try #4)
> info: [debug] [REMOTE] function () { [native code] }
> info: [debug] [REMOTE] Sending _rpc_forwardGetListing: message to remote debugger
> info: [debug] [REMOTE] {"__argument":{"WIRConnectionIdentifierKey":"3059539b-ba5f-45d3-99d5-cf91f5a9a03f","WIRApplicationIdentifierKey":"PID:99662"},"__selector":"_rpc_forwardGetListing:"}
> info: [debug] [REMOTE] Receiving data from remote debugger
> info: [debug] [REMOTE] {"__argument":{"WIRApplicationIdentifierKey":"PID:99690","WIRListingKey":{"1":{"WIRTitleKey":"","WIRTypeKey":"WIRTypeWeb","WIRURLKey":"about:blank","WIRPageIdentifierKey":1}}},"__selector":"_rpc_applicationSentListing:"}
> info: [debug] Responding to client with success: {"status":0,"value":["NATIVE_APP","WEBVIEW_1"],"sessionId":"aec8da59-9600-42d8-9003-d7c36d638c8f"}
> info: <-- GET /wd/hub/session/aec8da59-9600-42d8-9003-d7c36d638c8f/contexts 200 873.380 ms - 98 {"status":0,"value":["NATIVE_APP","WEBVIEW_1"],"sessionId":"aec8da59-9600-42d8-9003-d7c36d638c8f"}
> info: [debug] [REMOTE] {"__argument":{"WIRApplicationIdentifierKey":"PID:99666","WIRListingKey":{"1":{"WIRTitleKey":"","WIRTypeKey":"WIRTypeWeb","WIRURLKey":"about:blank","WIRPageIdentifierKey":1}}},"__selector":"_rpc_applicationSentListing:"}
> info: [debug] [REMOTE] New page listing did not match the app id we are currently using, ignoring
> info: [debug] [REMOTE] {"__argument":{"WIRApplicationIdentifierKey":"PID:99650","WIRListingKey":{}},"__selector":"_rpc_applicationSentListing:"}
> info: [debug] [REMOTE] New page listing did not match the app id we are currently using, ignoring
> info: [debug] [REMOTE] Receiving data from remote debugger
> info: [debug] [REMOTE] {"__argument":{"WIRApplicationIdentifierKey":"PID:99662","WIRListingKey":{"1":{"WIRTitleKey":"test","WIRTypeKey":"WIRTypeWeb","WIRURLKey":"http://0.0.0.0:8888/test/e2e/analytics/","WIRPageIdentifierKey":1}}},"__selector":"_rpc_applicationSentListing:"}
> info: [debug] Remote debugger notified us of a new page listing
> info: [debug] We don't appear to have window set yet, ignoring
> info: --> POST /wd/hub/session/aec8da59-9600-42d8-9003-d7c36d638c8f/context {"name":"WEBVIEW_1"}
> info: [debug] Attempting to set context to 'WEBVIEW_1'
> info: [debug] [REMOTE] Selecting page 1 and forwarding socket setup
> info: [debug] [REMOTE] Sending _rpc_forwardSocketSetup: message to remote debugger
> info: [debug] [REMOTE] {"__argument":{"WIRApplicationIdentifierKey":"PID:99662","WIRConnectionIdentifierKey":"3059539b-ba5f-45d3-99d5-cf91f5a9a03f","WIRSenderKey":"e0d99d98-da60-4197-83e8-b4cfc3ab281b","WIRPageIdentifierKey":1},"__selector":"_rpc_forwardSocketSetup:"}
> info: [debug] [REMOTE] Set sender key
> info: [debug] [REMOTE] Sending _rpc_forwardSocketData: message to remote debugger
> info: [debug] [REMOTE] Receiving data from remote debugger
> info: [debug] [REMOTE] got applicationSentData response
> info: [debug] [REMOTE] Enabled activity on page
> info: [debug] [REMOTE] Checking document readyState
> info: [debug] [REMOTE] Sending javascript command
> info: [debug] [REMOTE] Sending _rpc_forwardSocketData: message to remote debugger
> info: [debug] [REMOTE] Receiving data from remote debugger
> info: [debug] [REMOTE] got applicationSentData response
> info: [debug] [REMOTE] readyState was complete
> info: [debug] Responding to client with success: {"status":0,"value":"","sessionId":"aec8da59-9600-42d8-9003-d7c36d638c8f"}
> info: <-- POST /wd/hub/session/aec8da59-9600-42d8-9003-d7c36d638c8f/context 200 10.138 ms - 74 {"status":0,"value":"","sessionId":"aec8da59-9600-42d8-9003-d7c36d638c8f"}
> info: --> POST /wd/hub/session/aec8da59-9600-42d8-9003-d7c36d638c8f/execute {"script":"return angular.element(document.body).injector().get(\"$rootScope\").__dataloaded;\n","args":[]}
> info: [debug] [REMOTE] Executing 'execute_script' atom in default context
> info: [debug] [REMOTE] Sending javascript command
> info: [debug] [REMOTE] Sending _rpc_forwardSocketData: message to remote debugger
> info: [debug] [REMOTE] Receiving data from remote debugger
> info: [debug] [REMOTE] got applicationSentData response
> info: [debug] Responding to client with success: {"status":0,"value":true,"sessionId":"aec8da59-9600-42d8-9003-d7c36d638c8f"}
> info: <-- POST /wd/hub/session/aec8da59-9600-42d8-9003-d7c36d638c8f/execute 200 7.240 ms - 76 {"status":0,"value":true,"sessionId":"aec8da59-9600-42d8-9003-d7c36d638c8f"}
> info: --> POST /wd/hub/session/aec8da59-9600-42d8-9003-d7c36d638c8f/elements {"using":"css selector","value":"ul.events-results li"}
> info: [debug] Waiting up to 0ms for condition
> info: [debug] [REMOTE] Executing 'find_elements' atom in default context
> info: [debug] [REMOTE] Sending javascript command
> info: [debug] [REMOTE] Sending _rpc_forwardSocketData: message to remote debugger
> info: [debug] [REMOTE] Receiving data from remote debugger
> info: [debug] [REMOTE] got applicationSentData response
> info: [debug] Condition unmet after 15ms. Timing out.
> info: [debug] Responding to client with success: {"status":0,"value":[],"sessionId":"aec8da59-9600-42d8-9003-d7c36d638c8f"}
> info: <-- POST /wd/hub/session/aec8da59-9600-42d8-9003-d7c36d638c8f/elements 200 17.568 ms - 74 {"status":0,"value":[],"sessionId":"aec8da59-9600-42d8-9003-d7c36d638c8f"}
> info: --> POST /wd/hub/session/aec8da59-9600-42d8-9003-d7c36d638c8f/element {"using":"css selector","value":".magenta-surface.another-class"}
> info: [debug] Waiting up to 0ms for condition
> info: [debug] [REMOTE] Executing 'find_element' atom in default context
> info: [debug] [REMOTE] Sending javascript command
> info: [debug] [REMOTE] Sending _rpc_forwardSocketData: message to remote debugger
> info: [debug] [REMOTE] Receiving data from remote debugger
> info: [debug] [REMOTE] got applicationSentData response
> info: [debug] Responding to client with success: {"status":0,"value":{"ELEMENT":"5000"},"sessionId":"aec8da59-9600-42d8-9003-d7c36d638c8f"}
> info: <-- POST /wd/hub/session/aec8da59-9600-42d8-9003-d7c36d638c8f/element 200 10.877 ms - 90 {"status":0,"value":{"ELEMENT":"5000"},"sessionId":"aec8da59-9600-42d8-9003-d7c36d638c8f"}
> info: --> POST /wd/hub/session/aec8da59-9600-42d8-9003-d7c36d638c8f/moveto {"element":"5000"}
> info: [debug] [REMOTE] Executing 'get_top_left_coordinates' atom in default context
> info: [debug] [REMOTE] Sending javascript command
> info: [debug] [REMOTE] Sending _rpc_forwardSocketData: message to remote debugger
> info: [debug] [REMOTE] Receiving data from remote debugger
> info: [debug] [REMOTE] got applicationSentData response
> info: [debug] [REMOTE] Executing 'move_mouse' atom in default context
> info: [debug] [REMOTE] Sending javascript command
> info: [debug] [REMOTE] Sending _rpc_forwardSocketData: message to remote debugger
> info: [debug] [REMOTE] Receiving data from remote debugger
> info: [debug] [REMOTE] {"__argument":{"WIRApplicationIdentifierKey":"PID:99662","WIRListingKey":{"1":{"WIRTitleKey":"test","WIRTypeKey":"WIRTypeWeb","WIRURLKey":"http://0.0.0.0:8888/test/e2e/analytics/","WIRPageIdentifierKey":1,"WIRConnectionIdentifierKey":"3059539b-ba5f-45d3-99d5-cf91f5a9a03f"}}},"__selector":"_rpc_applicationSentListing:"}
> info: [debug] Remote debugger notified us of a new page listing
> info: [debug] New page listing is same as old, doing nothing
> info: [debug] [REMOTE] Receiving data from remote debugger
> info: [debug] [REMOTE] got applicationSentData response
> info: [debug] Responding to client with success: {"status":0,"value":null,"sessionId":"aec8da59-9600-42d8-9003-d7c36d638c8f"}
> info: <-- POST /wd/hub/session/aec8da59-9600-42d8-9003-d7c36d638c8f/moveto 200 71.893 ms - 76 {"status":0,"value":null,"sessionId":"aec8da59-9600-42d8-9003-d7c36d638c8f"}
> info: --> POST /wd/hub/session/aec8da59-9600-42d8-9003-d7c36d638c8f/element {"using":"css selector","value":".cyan-surface"}
> info: [debug] Waiting up to 0ms for condition
> info: [debug] [REMOTE] Executing 'find_element' atom in default context
> info: [debug] [REMOTE] Sending javascript command
> info: [debug] [REMOTE] Sending _rpc_forwardSocketData: message to remote debugger
> info: [debug] [REMOTE] Receiving data from remote debugger
> info: [debug] [REMOTE] Receiving data from remote debugger
> info: [debug] [REMOTE] got applicationSentData response
> info: [debug] Responding to client with success: {"status":0,"value":{"ELEMENT":"5001"},"sessionId":"aec8da59-9600-42d8-9003-d7c36d638c8f"}
> info: <-- POST /wd/hub/session/aec8da59-9600-42d8-9003-d7c36d638c8f/element 200 28.197 ms - 90 {"status":0,"value":{"ELEMENT":"5001"},"sessionId":"aec8da59-9600-42d8-9003-d7c36d638c8f"}
> info: --> POST /wd/hub/session/aec8da59-9600-42d8-9003-d7c36d638c8f/element/5001/click {}
> info: [debug] [REMOTE] Executing 'click' atom in default context
> info: [debug] [REMOTE] Sending javascript command
> info: [debug] [REMOTE] Sending _rpc_forwardSocketData: message to remote debugger
> info: [debug] [REMOTE] Receiving data from remote debugger
> info: [debug] [REMOTE] got applicationSentData response
> info: [debug] Responding to client with success: {"status":0,"value":null,"sessionId":"aec8da59-9600-42d8-9003-d7c36d638c8f"}
> info: <-- POST /wd/hub/session/aec8da59-9600-42d8-9003-d7c36d638c8f/element/5001/click 200 41.411 ms - 76 {"status":0,"value":null,"sessionId":"aec8da59-9600-42d8-9003-d7c36d638c8f"}
> info: --> POST /wd/hub/session/aec8da59-9600-42d8-9003-d7c36d638c8f/element {"using":"css selector","value":".below-metered"}
> info: [debug] Waiting up to 0ms for condition
> info: [debug] [REMOTE] Executing 'find_element' atom in default context
> info: [debug] [REMOTE] Sending javascript command
> info: [debug] [REMOTE] Sending _rpc_forwardSocketData: message to remote debugger
> info: [debug] [REMOTE] Receiving data from remote debugger
> info: [debug] [REMOTE] got applicationSentData response
> info: [debug] Responding to client with success: {"status":0,"value":{"ELEMENT":"5002"},"sessionId":"aec8da59-9600-42d8-9003-d7c36d638c8f"}
> info: <-- POST /wd/hub/session/aec8da59-9600-42d8-9003-d7c36d638c8f/element 200 42.963 ms - 90 {"status":0,"value":{"ELEMENT":"5002"},"sessionId":"aec8da59-9600-42d8-9003-d7c36d638c8f"}
> info: --> POST /wd/hub/session/aec8da59-9600-42d8-9003-d7c36d638c8f/moveto {"element":"5002"}
> info: [debug] [REMOTE] Executing 'get_top_left_coordinates' atom in default context
> info: [debug] [REMOTE] Sending javascript command
> info: [debug] [REMOTE] Sending _rpc_forwardSocketData: message to remote debugger
> info: [debug] [REMOTE] Receiving data from remote debugger
> info: [debug] [REMOTE] got applicationSentData response
> info: [debug] [REMOTE] Executing 'move_mouse' atom in default context
> info: [debug] [REMOTE] Sending javascript command
> info: [debug] [REMOTE] Sending _rpc_forwardSocketData: message to remote debugger
> info: [debug] [REMOTE] Receiving data from remote debugger
> info: [debug] [REMOTE] got applicationSentData response
> info: [debug] Responding to client with error: {"status":11,"value":{"message":"Element is not currently visible and may not be manipulated","origValue":"Element is not currently visible and may not be manipulated"},"sessionId":"aec8da59-9600-42d8-9003-d7c36d638c8f"}
> info: <-- POST /wd/hub/session/aec8da59-9600-42d8-9003-d7c36d638c8f/moveto 500 27.883 ms - 220
> info: --> POST /wd/hub/session/aec8da59-9600-42d8-9003-d7c36d638c8f/element {"using":"tag name","value":"body"}
> info: [debug] Waiting up to 0ms for condition
> info: [debug] [REMOTE] Executing 'find_element' atom in default context
> info: [debug] [REMOTE] Sending javascript command
> info: [debug] [REMOTE] Sending _rpc_forwardSocketData: message to remote debugger
> info: [debug] [REMOTE] Receiving data from remote debugger
> info: [debug] [REMOTE] got applicationSentData response
> info: [debug] Responding to client with success: {"status":0,"value":{"ELEMENT":"5003"},"sessionId":"aec8da59-9600-42d8-9003-d7c36d638c8f"}
> info: <-- POST /wd/hub/session/aec8da59-9600-42d8-9003-d7c36d638c8f/element 200 15.920 ms - 90 {"status":0,"value":{"ELEMENT":"5003"},"sessionId":"aec8da59-9600-42d8-9003-d7c36d638c8f"}
> info: --> GET /wd/hub/session/aec8da59-9600-42d8-9003-d7c36d638c8f/element/5003/text {}
> info: [debug] [REMOTE] Executing 'get_text' atom in default context
> info: [debug] [REMOTE] Sending javascript command
> info: [debug] [REMOTE] Sending _rpc_forwardSocketData: message to remote debugger
> info: [debug] [REMOTE] Receiving data from remote debugger
> info: [debug] [REMOTE] got applicationSentData response
> info: [debug] Responding to client with success: {"status":0,"value":"ANALYTICS TEST\nLog gathered events to console\n\nSend gathered events to iOS app analytics\n\nEvents output\nenter: #j20-metered-element.magenta-surface.another-class\nenter: .cyan-surface\nentered: .cyan-surface\nenter: unidentified element\nentered: unidentified element\nentered: #j20-metered-element.magenta-surface.another-class\nexit: #j20-metered-element.magenta-surface.another-class\nclick: .cyan-surface\n\n\nscroll to bottom and up again to see effects\nj20-metered test\nInner element\nAnother element with no id or class\nkeep scrolling\nthe end","sessionId":"aec8da59-9600-42d8-9003-d7c36d638c8f"}
> info: <-- GET /wd/hub/session/aec8da59-9600-42d8-9003-d7c36d638c8f/element/5003/text 200 28.323 ms - 633 {"status":0,"value":"ANALYTICS TEST\nLog gathered events to console\n\nSend gathered events to iOS app analytics\n\nEvents output\nenter: #j20-metered-element.magenta-surface.another-class\nenter: .cyan-surface\nentered: .cyan-surface\nenter: unidentified element\nentered: unidentified element\nentered: #j20-metered-element.magenta-surface.another-class\nexit: #j20-metered-element.magenta-surface.another-class\nclick: .cyan-surface\n\n\nscroll to bottom and up again to see effects\nj20-metered test\nInner element\nAnother element with no id or class\nkeep scrolling\nthe end","sessionId":"aec8da59-9600-42d8-9003-d7c36d638c8f"}
> info: --> POST /wd/hub/session/aec8da59-9600-42d8-9003-d7c36d638c8f/element {"using":"tag name","value":"body"}
> info: [debug] Waiting up to 0ms for condition
> info: [debug] [REMOTE] Executing 'find_element' atom in default context
> info: [debug] [REMOTE] Sending javascript command
> info: [debug] [REMOTE] Sending _rpc_forwardSocketData: message to remote debugger
> info: [debug] [REMOTE] Receiving data from remote debugger
> info: [debug] [REMOTE] got applicationSentData response
> info: [debug] Responding to client with success: {"status":0,"value":{"ELEMENT":"5004"},"sessionId":"aec8da59-9600-42d8-9003-d7c36d638c8f"}
> info: <-- POST /wd/hub/session/aec8da59-9600-42d8-9003-d7c36d638c8f/element 200 13.859 ms - 90 {"status":0,"value":{"ELEMENT":"5004"},"sessionId":"aec8da59-9600-42d8-9003-d7c36d638c8f"}
> info: --> GET /wd/hub/session/aec8da59-9600-42d8-9003-d7c36d638c8f/element/5004/text {}
> info: [debug] [REMOTE] Executing 'get_text' atom in default context
> info: [debug] [REMOTE] Sending javascript command
> info: [debug] [REMOTE] Sending _rpc_forwardSocketData: message to remote debugger
> info: [debug] [REMOTE] Receiving data from remote debugger
> info: [debug] [REMOTE] got applicationSentData response
> info: [debug] Responding to client with success: {"status":0,"value":"ANALYTICS TEST\nLog gathered events to console\n\nSend gathered events to iOS app analytics\n\nEvents output\nenter: #j20-metered-element.magenta-surface.another-class\nenter: .cyan-surface\nentered: .cyan-surface\nenter: unidentified element\nentered: unidentified element\nentered: #j20-metered-element.magenta-surface.another-class\nexit: #j20-metered-element.magenta-surface.another-class\nclick: .cyan-surface\n\n\nscroll to bottom and up again to see effects\nj20-metered test\nInner element\nAnother element with no id or class\nkeep scrolling\nthe end","sessionId":"aec8da59-9600-42d8-9003-d7c36d638c8f"}
> info: <-- GET /wd/hub/session/aec8da59-9600-42d8-9003-d7c36d638c8f/element/5004/text 200 22.743 ms - 633 {"status":0,"value":"ANALYTICS TEST\nLog gathered events to console\n\nSend gathered events to iOS app analytics\n\nEvents output\nenter: #j20-metered-element.magenta-surface.another-class\nenter: .cyan-surface\nentered: .cyan-surface\nenter: unidentified element\nentered: unidentified element\nentered: #j20-metered-element.magenta-surface.another-class\nexit: #j20-metered-element.magenta-surface.another-class\nclick: .cyan-surface\n\n\nscroll to bottom and up again to see effects\nj20-metered test\nInner element\nAnother element with no id or class\nkeep scrolling\nthe end","sessionId":"aec8da59-9600-42d8-9003-d7c36d638c8f"}
> info: --> POST /wd/hub/session/aec8da59-9600-42d8-9003-d7c36d638c8f/element {"using":"tag name","value":"body"}
> info: [debug] Waiting up to 0ms for condition
> info: [debug] [REMOTE] Executing 'find_element' atom in default context
> info: [debug] [REMOTE] Sending javascript command
> info: [debug] [REMOTE] Sending _rpc_forwardSocketData: message to remote debugger
> info: [debug] [REMOTE] Receiving data from remote debugger
> info: [debug] [REMOTE] got applicationSentData response
> info: [debug] Responding to client with success: {"status":0,"value":{"ELEMENT":"5005"},"sessionId":"aec8da59-9600-42d8-9003-d7c36d638c8f"}
> info: <-- POST /wd/hub/session/aec8da59-9600-42d8-9003-d7c36d638c8f/element 200 8.518 ms - 90 {"status":0,"value":{"ELEMENT":"5005"},"sessionId":"aec8da59-9600-42d8-9003-d7c36d638c8f"}
> info: --> GET /wd/hub/session/aec8da59-9600-42d8-9003-d7c36d638c8f/element/5005/text {}
> info: [debug] [REMOTE] Executing 'get_text' atom in default context
> info: [debug] [REMOTE] Sending javascript command
> info: [debug] [REMOTE] Sending _rpc_forwardSocketData: message to remote debugger
> info: [debug] [REMOTE] Receiving data from remote debugger
> info: [debug] [REMOTE] got applicationSentData response
> info: [debug] Responding to client with success: {"status":0,"value":"ANALYTICS TEST\nLog gathered events to console\n\nSend gathered events to iOS app analytics\n\nEvents output\nenter: #j20-metered-element.magenta-surface.another-class\nenter: .cyan-surface\nentered: .cyan-surface\nenter: unidentified element\nentered: unidentified element\nentered: #j20-metered-element.magenta-surface.another-class\nexit: #j20-metered-element.magenta-surface.another-class\nclick: .cyan-surface\n\n\nscroll to bottom and up again to see effects\nj20-metered test\nInner element\nAnother element with no id or class\nkeep scrolling\nthe end","sessionId":"aec8da59-9600-42d8-9003-d7c36d638c8f"}
> info: <-- GET /wd/hub/session/aec8da59-9600-42d8-9003-d7c36d638c8f/element/5005/text 200 25.664 ms - 633 {"status":0,"value":"ANALYTICS TEST\nLog gathered events to console\n\nSend gathered events to iOS app analytics\n\nEvents output\nenter: #j20-metered-element.magenta-surface.another-class\nenter: .cyan-surface\nentered: .cyan-surface\nenter: unidentified element\nentered: unidentified element\nentered: #j20-metered-element.magenta-surface.another-class\nexit: #j20-metered-element.magenta-surface.another-class\nclick: .cyan-surface\n\n\nscroll to bottom and up again to see effects\nj20-metered test\nInner element\nAnother element with no id or class\nkeep scrolling\nthe end","sessionId":"aec8da59-9600-42d8-9003-d7c36d638c8f"}
> info: --> POST /wd/hub/session/aec8da59-9600-42d8-9003-d7c36d638c8f/element {"using":"tag name","value":"body"}
> info: [debug] Waiting up to 0ms for condition
> info: [debug] [REMOTE] Executing 'find_element' atom in default context
> info: [debug] [REMOTE] Sending javascript command
> info: [debug] [REMOTE] Sending _rpc_forwardSocketData: message to remote debugger
> info: [debug] [REMOTE] Receiving data from remote debugger
> info: [debug] [REMOTE] got applicationSentData response
> info: [debug] Responding to client with success: {"status":0,"value":{"ELEMENT":"5006"},"sessionId":"aec8da59-9600-42d8-9003-d7c36d638c8f"}
> info: <-- POST /wd/hub/session/aec8da59-9600-42d8-9003-d7c36d638c8f/element 200 12.984 ms - 90 {"status":0,"value":{"ELEMENT":"5006"},"sessionId":"aec8da59-9600-42d8-9003-d7c36d638c8f"}
> info: --> GET /wd/hub/session/aec8da59-9600-42d8-9003-d7c36d638c8f/element/5006/text {}
> info: [debug] [REMOTE] Executing 'get_text' atom in default context
> info: [debug] [REMOTE] Sending javascript command
> info: [debug] [REMOTE] Sending _rpc_forwardSocketData: message to remote debugger
> info: [debug] [REMOTE] Receiving data from remote debugger
> info: [debug] [REMOTE] got applicationSentData response
> info: [debug] Responding to client with success: {"status":0,"value":"ANALYTICS TEST\nLog gathered events to console\n\nSend gathered events to iOS app analytics\n\nEvents output\nenter: #j20-metered-element.magenta-surface.another-class\nenter: .cyan-surface\nentered: .cyan-surface\nenter: unidentified element\nentered: unidentified element\nentered: #j20-metered-element.magenta-surface.another-class\nexit: #j20-metered-element.magenta-surface.another-class\nclick: .cyan-surface\n\n\nscroll to bottom and up again to see effects\nj20-metered test\nInner element\nAnother element with no id or class\nkeep scrolling\nthe end","sessionId":"aec8da59-9600-42d8-9003-d7c36d638c8f"}
> info: <-- GET /wd/hub/session/aec8da59-9600-42d8-9003-d7c36d638c8f/element/5006/text 200 23.056 ms - 633 {"status":0,"value":"ANALYTICS TEST\nLog gathered events to console\n\nSend gathered events to iOS app analytics\n\nEvents output\nenter: #j20-metered-element.magenta-surface.another-class\nenter: .cyan-surface\nentered: .cyan-surface\nenter: unidentified element\nentered: unidentified element\nentered: #j20-metered-element.magenta-surface.another-class\nexit: #j20-metered-element.magenta-surface.another-class\nclick: .cyan-surface\n\n\nscroll to bottom and up again to see effects\nj20-metered test\nInner element\nAnother element with no id or class\nkeep scrolling\nthe end","sessionId":"aec8da59-9600-42d8-9003-d7c36d638c8f"}
> info: --> POST /wd/hub/session/aec8da59-9600-42d8-9003-d7c36d638c8f/element {"using":"tag name","value":"body"}
> info: [debug] Waiting up to 0ms for condition
> info: [debug] [REMOTE] Executing 'find_element' atom in default context
> info: [debug] [REMOTE] Sending javascript command
> info: [debug] [REMOTE] Sending _rpc_forwardSocketData: message to remote debugger
> info: [debug] [REMOTE] Receiving data from remote debugger
> info: [debug] [REMOTE] got applicationSentData response
> info: [debug] Responding to client with success: {"status":0,"value":{"ELEMENT":"5007"},"sessionId":"aec8da59-9600-42d8-9003-d7c36d638c8f"}
> info: <-- POST /wd/hub/session/aec8da59-9600-42d8-9003-d7c36d638c8f/element 200 11.365 ms - 90 {"status":0,"value":{"ELEMENT":"5007"},"sessionId":"aec8da59-9600-42d8-9003-d7c36d638c8f"}
> info: --> GET /wd/hub/session/aec8da59-9600-42d8-9003-d7c36d638c8f/element/5007/text {}
> info: [debug] [REMOTE] Executing 'get_text' atom in default context
> info: [debug] [REMOTE] Sending javascript command
> info: [debug] [REMOTE] Sending _rpc_forwardSocketData: message to remote debugger
> info: [debug] [REMOTE] Receiving data from remote debugger
> info: [debug] [REMOTE] got applicationSentData response
> info: [debug] Responding to client with success: {"status":0,"value":"ANALYTICS TEST\nLog gathered events to console\n\nSend gathered events to iOS app analytics\n\nEvents output\nenter: #j20-metered-element.magenta-surface.another-class\nenter: .cyan-surface\nentered: .cyan-surface\nenter: unidentified element\nentered: unidentified element\nentered: #j20-metered-element.magenta-surface.another-class\nexit: #j20-metered-element.magenta-surface.another-class\nclick: .cyan-surface\n\n\nscroll to bottom and up again to see effects\nj20-metered test\nInner element\nAnother element with no id or class\nkeep scrolling\nthe end","sessionId":"aec8da59-9600-42d8-9003-d7c36d638c8f"}
> info: <-- GET /wd/hub/session/aec8da59-9600-42d8-9003-d7c36d638c8f/element/5007/text 200 20.438 ms - 633 {"status":0,"value":"ANALYTICS TEST\nLog gathered events to console\n\nSend gathered events to iOS app analytics\n\nEvents output\nenter: #j20-metered-element.magenta-surface.another-class\nenter: .cyan-surface\nentered: .cyan-surface\nenter: unidentified element\nentered: unidentified element\nentered: #j20-metered-element.magenta-surface.another-class\nexit: #j20-metered-element.magenta-surface.another-class\nclick: .cyan-surface\n\n\nscroll to bottom and up again to see effects\nj20-metered test\nInner element\nAnother element with no id or class\nkeep scrolling\nthe end","sessionId":"aec8da59-9600-42d8-9003-d7c36d638c8f"}
> info: --> POST /wd/hub/session/aec8da59-9600-42d8-9003-d7c36d638c8f/element {"using":"tag name","value":"body"}
> info: [debug] Waiting up to 0ms for condition
> info: [debug] [REMOTE] Executing 'find_element' atom in default context
> info: [debug] [REMOTE] Sending javascript command
> info: [debug] [REMOTE] Sending _rpc_forwardSocketData: message to remote debugger
> info: [debug] [REMOTE] Receiving data from remote debugger
> info: [debug] [REMOTE] Receiving data from remote debugger
> info: [debug] [REMOTE] got applicationSentData response
> info: [debug] Responding to client with success: {"status":0,"value":{"ELEMENT":"5008"},"sessionId":"aec8da59-9600-42d8-9003-d7c36d638c8f"}
> info: <-- POST /wd/hub/session/aec8da59-9600-42d8-9003-d7c36d638c8f/element 200 17.059 ms - 90 {"status":0,"value":{"ELEMENT":"5008"},"sessionId":"aec8da59-9600-42d8-9003-d7c36d638c8f"}
> info: --> GET /wd/hub/session/aec8da59-9600-42d8-9003-d7c36d638c8f/element/5008/text {}
> info: [debug] [REMOTE] Executing 'get_text' atom in default context
> info: [debug] [REMOTE] Sending javascript command
> info: [debug] [REMOTE] Sending _rpc_forwardSocketData: message to remote debugger
> info: [debug] [REMOTE] Receiving data from remote debugger
> info: [debug] [REMOTE] got applicationSentData response
> info: [debug] Responding to client with success: {"status":0,"value":"ANALYTICS TEST\nLog gathered events to console\n\nSend gathered events to iOS app analytics\n\nEvents output\nenter: #j20-metered-element.magenta-surface.another-class\nenter: .cyan-surface\nentered: .cyan-surface\nenter: unidentified element\nentered: unidentified element\nentered: #j20-metered-element.magenta-surface.another-class\nexit: #j20-metered-element.magenta-surface.another-class\nclick: .cyan-surface\n\n\nscroll to bottom and up again to see effects\nj20-metered test\nInner element\nAnother element with no id or class\nkeep scrolling\nthe end","sessionId":"aec8da59-9600-42d8-9003-d7c36d638c8f"}
> info: <-- GET /wd/hub/session/aec8da59-9600-42d8-9003-d7c36d638c8f/element/5008/text 200 24.298 ms - 633 {"status":0,"value":"ANALYTICS TEST\nLog gathered events to console\n\nSend gathered events to iOS app analytics\n\nEvents output\nenter: #j20-metered-element.magenta-surface.another-class\nenter: .cyan-surface\nentered: .cyan-surface\nenter: unidentified element\nentered: unidentified element\nentered: #j20-metered-element.magenta-surface.another-class\nexit: #j20-metered-element.magenta-surface.another-class\nclick: .cyan-surface\n\n\nscroll to bottom and up again to see effects\nj20-metered test\nInner element\nAnother element with no id or class\nkeep scrolling\nthe end","sessionId":"aec8da59-9600-42d8-9003-d7c36d638c8f"}
> info: --> POST /wd/hub/session/aec8da59-9600-42d8-9003-d7c36d638c8f/element {"using":"tag name","value":"body"}
> info: [debug] Waiting up to 0ms for condition
> info: [debug] [REMOTE] Executing 'find_element' atom in default context
> info: [debug] [REMOTE] Sending javascript command
> info: [debug] [REMOTE] Sending _rpc_forwardSocketData: message to remote debugger
> info: [debug] [REMOTE] Receiving data from remote debugger
> info: [debug] [REMOTE] got applicationSentData response
> info: [debug] Responding to client with success: {"status":0,"value":{"ELEMENT":"5009"},"sessionId":"aec8da59-9600-42d8-9003-d7c36d638c8f"}
> info: <-- POST /wd/hub/session/aec8da59-9600-42d8-9003-d7c36d638c8f/element 200 10.710 ms - 90 {"status":0,"value":{"ELEMENT":"5009"},"sessionId":"aec8da59-9600-42d8-9003-d7c36d638c8f"}
> info: --> GET /wd/hub/session/aec8da59-9600-42d8-9003-d7c36d638c8f/element/5009/text {}
> info: [debug] [REMOTE] Executing 'get_text' atom in default context
> info: [debug] [REMOTE] Sending javascript command
> info: [debug] [REMOTE] Sending _rpc_forwardSocketData: message to remote debugger
> info: [debug] [REMOTE] Receiving data from remote debugger
> info: [debug] [REMOTE] got applicationSentData response
> info: [debug] Responding to client with success: {"status":0,"value":"ANALYTICS TEST\nLog gathered events to console\n\nSend gathered events to iOS app analytics\n\nEvents output\nenter: #j20-metered-element.magenta-surface.another-class\nenter: .cyan-surface\nentered: .cyan-surface\nenter: unidentified element\nentered: unidentified element\nentered: #j20-metered-element.magenta-surface.another-class\nexit: #j20-metered-element.magenta-surface.another-class\nclick: .cyan-surface\n\n\nscroll to bottom and up again to see effects\nj20-metered test\nInner element\nAnother element with no id or class\nkeep scrolling\nthe end","sessionId":"aec8da59-9600-42d8-9003-d7c36d638c8f"}
> info: <-- GET /wd/hub/session/aec8da59-9600-42d8-9003-d7c36d638c8f/element/5009/text 200 16.002 ms - 633 {"status":0,"value":"ANALYTICS TEST\nLog gathered events to console\n\nSend gathered events to iOS app analytics\n\nEvents output\nenter: #j20-metered-element.magenta-surface.another-class\nenter: .cyan-surface\nentered: .cyan-surface\nenter: unidentified element\nentered: unidentified element\nentered: #j20-metered-element.magenta-surface.another-class\nexit: #j20-metered-element.magenta-surface.another-class\nclick: .cyan-surface\n\n\nscroll to bottom and up again to see effects\nj20-metered test\nInner element\nAnother element with no id or class\nkeep scrolling\nthe end","sessionId":"aec8da59-9600-42d8-9003-d7c36d638c8f"}
> info: --> POST /wd/hub/session/aec8da59-9600-42d8-9003-d7c36d638c8f/element {"using":"tag name","value":"body"}
> info: [debug] Waiting up to 0ms for condition
> info: [debug] [REMOTE] Executing 'find_element' atom in default context
> info: [debug] [REMOTE] Sending javascript command
> info: [debug] [REMOTE] Sending _rpc_forwardSocketData: message to remote debugger
> info: [debug] [REMOTE] Receiving data from remote debugger
> info: [debug] [REMOTE] got applicationSentData response
> info: [debug] Responding to client with success: {"status":0,"value":{"ELEMENT":"5010"},"sessionId":"aec8da59-9600-42d8-9003-d7c36d638c8f"}
> info: <-- POST /wd/hub/session/aec8da59-9600-42d8-9003-d7c36d638c8f/element 200 8.747 ms - 90 {"status":0,"value":{"ELEMENT":"5010"},"sessionId":"aec8da59-9600-42d8-9003-d7c36d638c8f"}
> info: --> GET /wd/hub/session/aec8da59-9600-42d8-9003-d7c36d638c8f/element/5010/text {}
> info: [debug] [REMOTE] Executing 'get_text' atom in default context
> info: [debug] [REMOTE] Sending javascript command
> info: [debug] [REMOTE] Sending _rpc_forwardSocketData: message to remote debugger
> info: [debug] [REMOTE] Receiving data from remote debugger
> info: [debug] [REMOTE] got applicationSentData response
> info: [debug] Responding to client with success: {"status":0,"value":"ANALYTICS TEST\nLog gathered events to console\n\nSend gathered events to iOS app analytics\n\nEvents output\nenter: #j20-metered-element.magenta-surface.another-class\nenter: .cyan-surface\nentered: .cyan-surface\nenter: unidentified element\nentered: unidentified element\nentered: #j20-metered-element.magenta-surface.another-class\nexit: #j20-metered-element.magenta-surface.another-class\nclick: .cyan-surface\n\n\nscroll to bottom and up again to see effects\nj20-metered test\nInner element\nAnother element with no id or class\nkeep scrolling\nthe end","sessionId":"aec8da59-9600-42d8-9003-d7c36d638c8f"}
> info: <-- GET /wd/hub/session/aec8da59-9600-42d8-9003-d7c36d638c8f/element/5010/text 200 15.575 ms - 633 {"status":0,"value":"ANALYTICS TEST\nLog gathered events to console\n\nSend gathered events to iOS app analytics\n\nEvents output\nenter: #j20-metered-element.magenta-surface.another-class\nenter: .cyan-surface\nentered: .cyan-surface\nenter: unidentified element\nentered: unidentified element\nentered: #j20-metered-element.magenta-surface.another-class\nexit: #j20-metered-element.magenta-surface.another-class\nclick: .cyan-surface\n\n\nscroll to bottom and up again to see effects\nj20-metered test\nInner element\nAnother element with no id or class\nkeep scrolling\nthe end","sessionId":"aec8da59-9600-42d8-9003-d7c36d638c8f"}
> info: --> POST /wd/hub/session/aec8da59-9600-42d8-9003-d7c36d638c8f/element {"using":"tag name","value":"body"}
> info: [debug] Waiting up to 0ms for condition
> info: [debug] [REMOTE] Executing 'find_element' atom in default context
> info: [debug] [REMOTE] Sending javascript command
> info: [debug] [REMOTE] Sending _rpc_forwardSocketData: message to remote debugger
> info: [debug] [REMOTE] Receiving data from remote debugger
> info: [debug] [REMOTE] got applicationSentData response
> info: [debug] Responding to client with success: {"status":0,"value":{"ELEMENT":"5011"},"sessionId":"aec8da59-9600-42d8-9003-d7c36d638c8f"}
> info: <-- POST /wd/hub/session/aec8da59-9600-42d8-9003-d7c36d638c8f/element 200 9.152 ms - 90 {"status":0,"value":{"ELEMENT":"5011"},"sessionId":"aec8da59-9600-42d8-9003-d7c36d638c8f"}
> info: --> GET /wd/hub/session/aec8da59-9600-42d8-9003-d7c36d638c8f/element/5011/text {}
> info: [debug] [REMOTE] Executing 'get_text' atom in default context
> info: [debug] [REMOTE] Sending javascript command
> info: [debug] [REMOTE] Sending _rpc_forwardSocketData: message to remote debugger
> info: [debug] [REMOTE] Receiving data from remote debugger
> info: [debug] [REMOTE] got applicationSentData response
> info: [debug] Responding to client with success: {"status":0,"value":"ANALYTICS TEST\nLog gathered events to console\n\nSend gathered events to iOS app analytics\n\nEvents output\nenter: #j20-metered-element.magenta-surface.another-class\nenter: .cyan-surface\nentered: .cyan-surface\nenter: unidentified element\nentered: unidentified element\nentered: #j20-metered-element.magenta-surface.another-class\nexit: #j20-metered-element.magenta-surface.another-class\nclick: .cyan-surface\n\n\nscroll to bottom and up again to see effects\nj20-metered test\nInner element\nAnother element with no id or class\nkeep scrolling\nthe end","sessionId":"aec8da59-9600-42d8-9003-d7c36d638c8f"}
> info: <-- GET /wd/hub/session/aec8da59-9600-42d8-9003-d7c36d638c8f/element/5011/text 200 16.023 ms - 633 {"status":0,"value":"ANALYTICS TEST\nLog gathered events to console\n\nSend gathered events to iOS app analytics\n\nEvents output\nenter: #j20-metered-element.magenta-surface.another-class\nenter: .cyan-surface\nentered: .cyan-surface\nenter: unidentified element\nentered: unidentified element\nentered: #j20-metered-element.magenta-surface.another-class\nexit: #j20-metered-element.magenta-surface.another-class\nclick: .cyan-surface\n\n\nscroll to bottom and up again to see effects\nj20-metered test\nInner element\nAnother element with no id or class\nkeep scrolling\nthe end","sessionId":"aec8da59-9600-42d8-9003-d7c36d638c8f"}
> info: --> POST /wd/hub/session/aec8da59-9600-42d8-9003-d7c36d638c8f/element {"using":"css selector","value":"button.button-send-events"}
> info: [debug] Waiting up to 0ms for condition
> info: [debug] [REMOTE] Executing 'find_element' atom in default context
> info: [debug] [REMOTE] Sending javascript command
> info: [debug] [REMOTE] Sending _rpc_forwardSocketData: message to remote debugger
> info: [debug] [REMOTE] Receiving data from remote debugger
> info: [debug] [REMOTE] got applicationSentData response
> info: [debug] Responding to client with success: {"status":0,"value":{"ELEMENT":"5012"},"sessionId":"aec8da59-9600-42d8-9003-d7c36d638c8f"}
> info: <-- POST /wd/hub/session/aec8da59-9600-42d8-9003-d7c36d638c8f/element 200 8.570 ms - 90 {"status":0,"value":{"ELEMENT":"5012"},"sessionId":"aec8da59-9600-42d8-9003-d7c36d638c8f"}
> info: --> POST /wd/hub/session/aec8da59-9600-42d8-9003-d7c36d638c8f/element/5012/click {}
> info: [debug] [REMOTE] Executing 'click' atom in default context
> info: [debug] [REMOTE] Sending javascript command
> info: [debug] [REMOTE] Sending _rpc_forwardSocketData: message to remote debugger
> info: [debug] [REMOTE] Receiving data from remote debugger
> info: [debug] [REMOTE] got applicationSentData response
> info: [debug] Responding to client with success: {"status":0,"value":null,"sessionId":"aec8da59-9600-42d8-9003-d7c36d638c8f"}
> info: <-- POST /wd/hub/session/aec8da59-9600-42d8-9003-d7c36d638c8f/element/5012/click 200 28.746 ms - 76 {"status":0,"value":null,"sessionId":"aec8da59-9600-42d8-9003-d7c36d638c8f"}
> info: --> POST /wd/hub/session/aec8da59-9600-42d8-9003-d7c36d638c8f/elements {"using":"css selector","value":"ul.events-results li"}
> info: [debug] Waiting up to 0ms for condition
> info: [debug] [REMOTE] Executing 'find_elements' atom in default context
> info: [debug] [REMOTE] Sending javascript command
> info: [debug] [REMOTE] Sending _rpc_forwardSocketData: message to remote debugger
> info: [debug] [REMOTE] Receiving data from remote debugger
> info: [debug] [REMOTE] got applicationSentData response
> info: [debug] Condition unmet after 29ms. Timing out.
> info: [debug] Responding to client with success: {"status":0,"value":[],"sessionId":"aec8da59-9600-42d8-9003-d7c36d638c8f"}
> info: <-- POST /wd/hub/session/aec8da59-9600-42d8-9003-d7c36d638c8f/elements 200 30.612 ms - 74 {"status":0,"value":[],"sessionId":"aec8da59-9600-42d8-9003-d7c36d638c8f"}
> info: --> DELETE /wd/hub/session/aec8da59-9600-42d8-9003-d7c36d638c8f {}
> info: Shutting down appium session
> info: [debug] Stopping ios
> info: [debug] Destroying instruments client socket.
> info: [debug] Closing socket server.
> info: [debug] Instruments socket server was closed
> info: [debug] Sending sigterm to instruments
> info: [debug] [INST] 2015-05-30 07:27:54 +0000 Stopped: Script was stopped by the user
> info: [debug] [REMOTE] Receiving data from remote debugger
> info: [debug] [REMOTE] {"__argument":{"WIRApplicationIdentifierKey":"PID:99650","WIRIsApplicationProxyKey":false,"WIRApplicationNameKey":"June20","WIRApplicationBundleIdentifierKey":"com.june20.June20","WIRIsApplicationActiveKey":1},"__selector":"_rpc_applicationDisconnected:"}
> info: [debug] [REMOTE] {"__argument":{"WIRApplicationIdentifierKey":"PID:99666","WIRIsApplicationProxyKey":true,"WIRApplicationNameKey":"","WIRApplicationBundleIdentifierKey":"com.apple.WebKit.WebContent","WIRIsApplicationActiveKey":0,"WIRHostApplicationIdentifierKey":"PID:99650"},"__selector":"_rpc_applicationDisconnected:"}
> info: [debug] [REMOTE] {"__argument":{"WIRApplicationIdentifierKey":"PID:99690","WIRIsApplicationProxyKey":true,"WIRApplicationNameKey":"","WIRApplicationBundleIdentifierKey":"com.apple.WebKit.WebContent","WIRIsApplicationActiveKey":0,"WIRHostApplicationIdentifierKey":"PID:99650"},"__selector":"_rpc_applicationDisconnected:"}
> info: [debug] [INST] 2015-05-30 07:27:54 +0000 Error: Script threw an uncaught JavaScript error: JSON Parse error: Unexpected EOF on line 2597 of bootstrap-567117569bfdde4b.js
> info: [debug] [REMOTE] Receiving data from remote debugger
> info: [debug] [REMOTE] {"__argument":{"WIRApplicationIdentifierKey":"PID:99662","WIRIsApplicationProxyKey":true,"WIRApplicationNameKey":"","WIRApplicationBundleIdentifierKey":"com.apple.WebKit.WebContent","WIRIsApplicationActiveKey":0,"WIRHostApplicationIdentifierKey":"PID:99650"},"__selector":"_rpc_applicationDisconnected:"}
> info: [debug] [INST STDERR] 2015-05-30 09:27:54.429 instruments[99590:8066486] Attempting to set event horizon when core is not engaged, request ignored
> info: [debug] [INST STDERR] 2015-05-30 09:27:54.429 instruments[99590:8066486] Attempting to set event horizon when core is not engaged, request ignored
> info: [debug] [INST STDERR] 2015-05-30 09:27:54.459 instruments[99590:8066484] Attempting to set event horizon when core is not engaged, request ignored
> info: [debug] [INST STDERR] 2015-05-30 09:27:54.460 instruments[99590:8067503] Attempting to set event horizon when core is not engaged, request ignored
> info: [debug] [INST] Instruments Trace Complete (Duration : 39.661564s; Output : /tmp/appium-instruments/instrumentscli0.trace)
> info: [debug] [INSTSERVER] Instruments exited with code 0
> info: [debug] Cleaning up after instruments exit
> info: [debug] Stopping iOS log capture
> info: [debug] [REMOTE] Disconnecting from remote debugger
> info: [debug] Running ios sim reset flow
> info: [debug] Killing the simulator process
> info: [debug] Killall iOS Simulator
> info: [debug] Killing any other simulator daemons
> info: [debug] [REMOTE] Debugger socket disconnected
> info: [debug] Cleaning sim data files
> info: Executing: xcrun simctl erase F95857C6-DC1C-4918-AE2B-2532F5E50E45 with timeout 2000
> info: Executing: xcrun simctl erase F95857C6-DC1C-4918-AE2B-2532F5E50E45 with timeout 2000
> info: Executing: xcrun simctl erase F95857C6-DC1C-4918-AE2B-2532F5E50E45 with timeout 2000
> info: [debug] Cleaning app data files
> warn: Applications directory /Users/michalmikolajczyk/Library/Developer/CoreSimulator/Devices/F95857C6-DC1C-4918-AE2B-2532F5E50E45/data/Containers/Data/Application doesn't exist. Have you run this simulator before?
> warn: Applications directory /Users/michalmikolajczyk/Library/Developer/CoreSimulator/Devices/F95857C6-DC1C-4918-AE2B-2532F5E50E45/data/Containers/Bundle/Application doesn't exist. Have you run this simulator before?
> info: Couldn't find app directories to delete. Probably it's not installed
> info: [debug] Cleaning up appium session
> info: [debug] Responding to client with success: {"status":0,"value":null,"sessionId":"aec8da59-9600-42d8-9003-d7c36d638c8f"}
> info: <-- DELETE /wd/hub/session/aec8da59-9600-42d8-9003-d7c36d638c8f 200 5523.285 ms - 76 {"status":0,"value":null,"sessionId":"aec8da59-9600-42d8-9003-d7c36d638c8f"}
> info: --> POST /wd/hub/session {"desiredCapabilities":{"platformName":"iOS","platformVersion":"8.3","deviceName":"=iPad Air","app":"http://myappserver.com/app/June20.zip","browserName":"June20 DEBUG"}}
> info: Client User-Agent string: undefined
> info: [debug] Using downloadable app from desired caps: [object Object]
> info: [debug] http://myappserver.com/app/June20.zip downloaded to /var/folders/1b/t3nrqtb93mx1mswtcp3bnh3m0000gn/T/115430-99547-1xrz6a6/appium-app.zip
> info: [debug] Unzipping /var/folders/1b/t3nrqtb93mx1mswtcp3bnh3m0000gn/T/115430-99547-1xrz6a6/appium-app.zip
> info: [debug] Testing zip archive: /var/folders/1b/t3nrqtb93mx1mswtcp3bnh3m0000gn/T/115430-99547-1xrz6a6/appium-app.zip
> info: [debug] Zip archive tested clean
> info: [debug] Unzip successful
> info: [debug] Using extracted app: /var/folders/1b/t3nrqtb93mx1mswtcp3bnh3m0000gn/T/115430-99547-1xrz6a6/June20.app
> info: [debug] Creating new appium session b45cc384-a726-45c2-94c3-4edcedd95a38
> info: [debug] Removing any remaining instruments sockets
> info: [debug] Cleaned up instruments socket /tmp/instruments_sock
> info: [debug] Setting Xcode folder
> info: [debug] Setting Xcode version
> info: [debug] Setting iOS SDK Version
> info: [debug] Getting sdk version from xcrun with a timeout
> info: [debug] iOS SDK Version set to 8.3
> info: [debug] Checking whether instruments supports our device string
> info: [debug] Getting list of devices instruments supports
> info: [debug] Instruments is at: /Applications/Xcode.app/Contents/Developer/usr/bin/instruments
> info: [debug] Getting device string from opts: {"forceIphone":false,"forceIpad":false,"xcodeVersion":"6.3.1","iOSSDKVersion":"8.3","deviceName":"=iPad Air","platformVersion":"8.3"}
> info: [debug] iOS sim UDID is F95857C6-DC1C-4918-AE2B-2532F5E50E45
> info: [debug] Detecting automation tracetemplate
> info: [debug] Not auto-detecting udid, running on sim
> info: [debug] Could not parse plist file (as binary) at /var/folders/1b/t3nrqtb93mx1mswtcp3bnh3m0000gn/T/115430-99547-1xrz6a6/June20.app/en.lproj/Localizable.strings
> info: Will try to parse the plist file as XML
> info: [debug] Could not parse plist file (as XML) at /var/folders/1b/t3nrqtb93mx1mswtcp3bnh3m0000gn/T/115430-99547-1xrz6a6/June20.app/en.lproj/Localizable.strings
> warn: Could not parse app Localizable.strings assuming it doesn't exist
> info: [debug] Getting bundle ID from app
> info: [debug] Parsed app Info.plist (as binary)
> info: [debug] Creating instruments
> info: On some xcode 6 platforms, instruments-without-delay does not work. If you experience this, you will need to re-run appium with the --native-instruments-lib flag
> info: [debug] Preparing uiauto bootstrap
> info: [debug] Dynamic bootstrap dir: /Users/michalmikolajczyk/Library/Application Support/appium/bootstrap
> info: [debug] Dynamic env: {"nodePath":"/Users/michalmikolajczyk/local/bin/node","commandProxyClientPath":"/Users/michalmikolajczyk/local/lib/node_modules/appium/node_modules/appium-uiauto/bin/command-proxy-client.js","instrumentsSock":"/tmp/instruments_sock","interKeyDelay":null,"justLoopInfinitely":false,"autoAcceptAlerts":false,"autoDismissAlerts":false,"sendKeyStrategy":"oneByOne"}
> ...o: [debug] Dynamic bootstrap code: // This file is automatically generated. Do not manually modify!
> info: [debug] Dynamic bootstrap path: /Users/michalmikolajczyk/Library/Application Support/appium/bootstrap/bootstrap-567117569bfdde4b.js
> info: [debug] Reusing dynamic bootstrap: /Users/michalmikolajczyk/Library/Application Support/appium/bootstrap/bootstrap-567117569bfdde4b.js
> info: [debug] Getting device string from opts: {"forceIphone":false,"forceIpad":false,"xcodeVersion":"6.3.1","iOSSDKVersion":"8.3","deviceName":"=iPad Air","platformVersion":"8.3"}
> info: [debug] We're on iOS7.1+ so forcing defaultDevice on
> info: [debug] Getting device string from opts: {"forceIphone":false,"forceIpad":false,"xcodeVersion":"6.3.1","iOSSDKVersion":"8.3","deviceName":"=iPad Air","platformVersion":"8.3"}
> info: [debug] Parsed app Info.plist (as binary)
> info: [debug] Wrote new app Info.plist with device type
> info: [debug] Checking whether we need to set app preferences
> info: [debug] Running ios sim reset flow
> info: [debug] Killing the simulator process
> info: [debug] Killall iOS Simulator
> info: [debug] Killing any other simulator daemons
> info: [debug] Cleaning sim data files
> info: Executing: xcrun simctl erase F95857C6-DC1C-4918-AE2B-2532F5E50E45 with timeout 2000
> info: [debug] Cleaning app data files
> warn: Applications directory /Users/michalmikolajczyk/Library/Developer/CoreSimulator/Devices/F95857C6-DC1C-4918-AE2B-2532F5E50E45/data/Containers/Data/Application doesn't exist. Have you run this simulator before?
> warn: Applications directory /Users/michalmikolajczyk/Library/Developer/CoreSimulator/Devices/F95857C6-DC1C-4918-AE2B-2532F5E50E45/data/Containers/Bundle/Application doesn't exist. Have you run this simulator before?
> info: Couldn't find app directories to delete. Probably it's not installed
> info: [debug] Not setting locale
> info: [debug] No iOS / app preferences to set
> info: [debug] Starting iOS 8.\* simulator log capture
> info: [debug] Not pre-launching simulator
> info: [debug] No device id or app, not installing to real device.
> info: [debug] Starting command proxy.
> info: [debug] Instruments socket server started at /tmp/instruments_sock
> info: [debug] Starting instruments
> info: [debug] Instruments is at: /Applications/Xcode.app/Contents/Developer/usr/bin/instruments
> info: Launching instruments
> info: [debug] Attempting to run app on iPad Air
> info: [debug] 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 /tmp/appium-instruments/instrumentscli0.trace -w "iPad Air" /var/folders/1b/t3nrqtb93mx1mswtcp3bnh3m0000gn/T/115430-99547-1xrz6a6/June20.app -e UIASCRIPT "/Users/michalmikolajczyk/Library/Application Support/appium/bootstrap/bootstrap-567117569bfdde4b.js" -e UIARESULTSPATH /tmp/appium-instruments
> info: [debug] And extra without-delay env: {"DYLD_INSERT_LIBRARIES":"/Users/michalmikolajczyk/local/lib/node_modules/appium/node_modules/appium-instruments/thirdparty/iwd6/InstrumentsShim.dylib","LIB_PATH":"/Users/michalmikolajczyk/local/lib/node_modules/appium/node_modules/appium-instruments/thirdparty/iwd6"}
> info: [debug] And launch timeouts (in ms): {"global":90000}
> info: [debug] [INST] Waiting for device to boot...
> info: Instruments is ready to receive commands
> info: [debug] Instruments launched. Starting poll loop for new commands.
> info: [debug] Setting bootstrap config keys/values
> info: [debug] Pushing command to appium work queue: "target = $.target();\nau = $;\n$.isVerbose = true;\n"
> info: [debug] Socket data received (2 bytes)
> info: [debug] Socket data being routed.
> info: [debug] Sending command to instruments: target = $.target();
> au = $;
> $.isVerbose = true;
>
> info: [debug] [INST] 2015-05-30 07:28:25 +0000 Debug: evaluation finished
> info: [debug] [INST] 2015-05-30 07:28:25 +0000 Debug: responding with:
> info: [debug] [INST] 2015-05-30 07:28:25 +0000 Debug: Running system command #1: /Users/michalmikolajczyk/local/bin/node /Users/michalmikolajczyk/local/lib/node_modules/appium/node_modules/appium-uiauto/bin/command-proxy-client.js /tmp/instruments_sock 2,{"status":0,"value":true}...
> info: [debug] Socket data received (27 bytes)
> info: [debug] Socket data being routed.
> info: [debug] Got result from instruments: {"status":0,"value":true}
> info: [debug] Setting initial orientation to PORTRAIT
> info: [debug] Pushing command to appium work queue: "au.setScreenOrientation('PORTRAIT')"
> info: [debug] Sending command to instruments: au.setScreenOrientation('PORTRAIT')
> info: [debug] [INST] 2015-05-30 07:28:25 +0000 Debug: Got new command 1 from instruments: au.setScreenOrientation('PORTRAIT')
> info: [debug] [INST] 2015-05-30 07:28:25 +0000 Debug: evaluating au.setScreenOrientation('PORTRAIT')
> info: [debug] [INST] 2015-05-30 07:28:25 +0000 Debug: target.setDeviceOrientation("1")
> info: [debug] [INST] 2015-05-30 07:28:25 +0000 Debug: evaluation finished
> info: [debug] [INST] 2015-05-30 07:28:25 +0000 Debug: responding with:
> info: [debug] [INST] 2015-05-30 07:28:25 +0000 Debug: Running system command #2: /Users/michalmikolajczyk/local/bin/node /Users/michalmikolajczyk/local/lib/node_modules/appium/node_modules/appium-uiauto/bin/command-proxy-client.js /tmp/instruments_sock 2,{"status":0,"value":"PORTRAIT"}...
> info: [debug] Socket data received (33 bytes)
> info: [debug] Socket data being routed.
> info: [debug] Got result from instruments: {"status":0,"value":"PORTRAIT"}
> info: [debug] Waiting for app source to contain elements
> info: [debug] Pushing command to appium work queue: "au.mainApp().getTreeForXML()"
> info: [debug] Sending command to instruments: au.mainApp().getTreeForXML()
> info: [debug] [INST] 2015-05-30 07:28:25 +0000 Debug: Got new command 2 from instruments: au.mainApp().getTreeForXML()
> info: [debug] [INST] 2015-05-30 07:28:25 +0000 Debug: evaluating au.mainApp().getTreeForXML()
> info: [debug] [INST] 2015-05-30 07:28:26 +0000 Debug: evaluation finished
> info: [debug] [INST] 2015-05-30 07:28:26 +0000 Debug: responding with:d":true,"visible":true,"hint":null,"path":"/0/0","x":0,"y":0,"width":768,"height":1024},">":[{"UIAStaticText":{"@":{"name":"Offline content","label":"Offline content","value":"Offline content","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/0","x":337,"y":1.5,"width":94.5,"height":17},">":[]}},{"UIAScrollView":{"@":{"name":null,"label":null,"value":null,"dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/1","x":0,"y":20,"width":768,"height":1004},">":[{"UIAImage":{"@":{"name":null,"label":null,"value":null,"dom":null,"enabled":true,"valid":true,"visible":false,"hint":null,"path":"/0/0/1/0","x":765.5,"y":-16,"width":2.5,"height":36},">":[]}},{"UIAWebView":{"@":{"name":null,"label":null,"value":null,"dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/1/1","x":0,"y":20,"width":768,"height":1004},">":[{"UIAStaticText":{"@":{"name":"Timed out!","label":"Timed out!","value":"1","dom":{"id":""},"enabled":true,"valid":true,"visible":true,"hint":"","path":"/0/0/1/1/0","x":251,"y":29,"width":266,"height":74},">":[]}}]}},{"UIAImage":{"@":{"name":null,"label":null,"value":null,"dom":null,"enabled":true,"valid":true,"visible":false,"hint":null,"path":"/0/0/1/2","x":-36,"y":1021.5,"width":36,"height":2.5},">":[]}}]}},{"UIASegmentedControl":{"@":{"name":null,"label":null,"value":null,"dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/2","x":224,"y":8,"width":320,"height":29},">":[{"UIAButton":{"@":{"name":"Camera","label":"Camera","value":"","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/2/0","x":224,"y":8,"width":106,"height":29},">":[]}},{"UIAButton":{"@":{"name":"Content","label":"Content","value":"","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/2/1","x":331,"y":8,"width":106,"height":29},">":[]}},{"UIAButton":{"@":{"name":"Log","label":"Log","value":"","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/2/2","x":438,"y":8,"width":106,"height":29},">":[]}}]}},{"UIAStaticText":{"@":{"name":"version 0.9.2 – beta 1 [DEBUG]\nhttp://192.168.1.100:8080/","label":"version 0.9.2 – beta 1 [DEBUG]\nhttp://192.168.1.100:8080/","value":"version 0.9.2 – beta 1 [DEBUG]\nhttp://192.168.1.100:8080/","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/3","x":559.5,"y":8,"width":200.5,"height":33.5},">":[]}},{"UIAStaticText":{"@":{"name":"Camera Controls","label":"Camera Controls","value":"Camera Controls","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/4","x":291,"y":52,"width":186.5,"height":29},">":[]}},{"UIAStaticText":{"@":{"name":"Beep on scan:","label":"Beep on scan:","value":"Beep on scan:","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/5","x":226,"y":102.5,"width":110.5,"height":20.5},">":[]}},{"UIASwitch":{"@":{"name":null,"label":null,"value":1,"dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/6","x":344.5,"y":97,"width":51,"height":31},">":[]}},{"UIAStaticText":{"@":{"name":"Scan interval:","label":"Scan interval:","value":"Scan interval:","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/7","x":233,"y":135,"width":104,"height":20.5},">":[]}},{"UIASlider":{"@":{"name":null,"label":null,"value":"20%","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/8","x":343,"y":130,"width":191,"height":31},">":[]}},{"UIAStaticText":{"@":{"name":"1.00 seconds","label":"1.00 seconds","value":"1.00 seconds","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/9","x":540,"y":135,"width":103.5,"height":20.5},">":[]}},{"UIAStaticText":{"@":{"name":"Auto-focus:","label":"Auto-focus:","value":"Auto-focus:","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/10","x":246,"y":197.5,"width":90,"height":20.5},">":[]}},{"UIASwitch":{"@":{"name":null,"label":null,"value":1,"dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/11","x":344,"y":192,"width":51,"height":31},">":[]}},{"UIAStaticText":{"@":{"name":"Focus position:","label":"Focus position:","value":"Focus position:","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/12","x":219,"y":234,"width":118,"height":20.5},">":[]}},{"UIASlider":{"@":{"name":null,"label":null,"value":"50%","dom":null,"enabled":false,"valid":true,"visible":true,"hint":null,"path":"/0/0/13","x":343,"y":229,"width":191,"height":31},">":[]}},{"UIAStaticText":{"@":{"name":"0.50","label":"0.50","value":"0.50","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/14","x":540,"y":234,"width":33.5,"height":20.5},">":[]}},{"UIAStaticText":{"@":{"name":"Auto-exposure:","label":"Auto-exposure:","value":"Auto-exposure:","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/15","x":219,"y":296.5,"width":118.5,"height":20.5},">":[]}},{"UIASwitch":{"@":{"name":null,"label":null,"value":1,"dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/16","x":345.5,"y":291,"width":51,"height":31},">":[]}},{"UIAStaticText":{"@":{"name":"Offset:","label":"Offset:","value":"Offset:","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/17","x":284.5,"y":333,"width":51,"height":20.5},">":[]}},{"UIASlider":{"@":{"name":null,"label":null,"value":"NaN","dom":null,"enabled":false,"valid":true,"visible":true,"hint":null,"path":"/0/0/18","x":341.5,"y":328,"width":191,"height":31},">":[]}},{"UIAStaticText":{"@":{"name":"0","label":"0","value":"0","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/19","x":538.5,"y":333,"width":10,"height":20.5},">":[]}},{"UIAStaticText":{"@":{"name":"Shutter speed:","label":"Shutter speed:","value":"Shutter speed:","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/20","x":222.5,"y":369.5,"width":113,"height":20.5},">":[]}},{"UIASlider":{"@":{"name":null,"label":null,"value":"0%","dom":null,"enabled":false,"valid":true,"visible":true,"hint":null,"path":"/0/0/21","x":341.5,"y":364.5,"width":191,"height":31},">":[]}},{"UIAStaticText":{"@":{"name":"1/inf","label":"1/inf","value":"1/inf","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/22","x":538.5,"y":369.5,"width":34,"height":20.5},">":[]}},{"UIAStaticText":{"@":{"name":"ISO:","label":"ISO:","value":"ISO:","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/23","x":303.5,"y":406,"width":33.5,"height":20.5},">":[]}},{"UIASlider":{"@":{"name":null,"label":null,"value":"NaN","dom":null,"enabled":false,"valid":true,"visible":true,"hint":null,"path":"/0/0/24","x":343,"y":401,"width":191,"height":31},">":[]}},{"UIAStaticText":{"@":{"name":"0.50","label":"0.50","value":"0.50","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/25","x":540,"y":406,"width":33.5,"height":20.5},">":[]}},{"UIAStaticText":{"@":{"name":"Target bias:","label":"Target bias:","value":"Target bias:","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/26","x":249,"y":442.5,"width":88.5,"height":20.5},">":[]}},{"UIASlider":{"@":{"name":null,"label":null,"value":"NaN","dom":null,"enabled":false,"valid":true,"visible":true,"hint":null,"path":"/0/0/27","x":343.5,"y":437.5,"width":191,"height":31},">":[]}},{"UIAStaticText":{"@":{"name":"0.50","label":"0.50","value":"0.50","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/28","x":540.5,"y":442.5,"width":33.5,"height":20.5},">":[]}},{"UIAStaticText":{"@":{"name":"Auto-white balance:","label":"Auto-white balance:","value":"Auto-white balance:","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/29","x":183.5,"y":505,"width":153.5,"height":20.5},">":[]}},{"UIASwitch":{"@":{"name":null,"label":null,"value":1,"dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/30","x":345,"y":499.5,"width":51,"height":31},">":[]}},{"UIAStaticTex
> info: [debug] [INST] t":{"@":{"name":"Temperature:","label":"Temperature:","value":"Temperature:","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/31","x":237,"y":557.5,"width":100.5,"height":20.5},">":[]}},{"UIASlider":{"@":{"name":null,"label":null,"value":"0%","dom":null,"enabled":false,"valid":true,"visible":true,"hint":null,"path":"/0/0/32","x":343.5,"y":552.5,"width":191,"height":31},">":[]}},{"UIAStaticText":{"@":{"name":"0.00","label":"0.00","value":"0.00","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/33","x":540.5,"y":557.5,"width":33.5,"height":20.5},">":[]}},{"UIAStaticText":{"@":{"name":"Tint:","label":"Tint:","value":"Tint:","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/34","x":302.5,"y":594,"width":33.5,"height":20.5},">":[]}},{"UIASlider":{"@":{"name":null,"label":null,"value":"50%","dom":null,"enabled":false,"valid":true,"visible":true,"hint":null,"path":"/0/0/35","x":342,"y":589,"width":191,"height":31},">":[]}},{"UIAStaticText":{"@":{"name":"0.00","label":"0.00","value":"0.00","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/36","x":539,"y":594,"width":33.5,"height":20.5},">":[]}},{"UIAStaticText":{"@":{"name":"Overlay brightness:","label":"Overlay brightness:","value":"Overlay brightness:","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/37","x":8,"y":991,"width":148.5,"height":20.5},">":[]}},{"UIASlider":{"@":{"name":null,"label":null,"value":"25%","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/38","x":162.5,"y":986,"width":462,"height":31},">":[]}},{"UIAStaticText":{"@":{"name":"CPU: 52%","label":"CPU: 52%","value":"CPU: 52%","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/39","x":630.5,"y":991,"width":81.5,"height":20.5},">":[]}},{"UIAButton":{"@":{"name":"Close","label":"Close","value":null,"dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/40","x":720,"y":986,"width":40,"height":30},">":[]}}]}},{"UIAWindow":{"@":{"name":null,"label":null,"value":null,"dom":null,"enabled":true,"valid":true,"visible":false,"hint":null,"path":"/0/1","x":0,"y":0,"width":768,"height":1024},">":[{"UIAStatusBar":{"@":{"name":null,"label":null,"value":null,"dom":null,"enabled":true,"valid":true,"visible":false,"hint":null,"path":"/0/1/0","x":0,"y":0,"width":768,"height":0},">":[]}}]}}]}}}
> 2015-05-30 07:28:26 +0000 Debug: Running system command #3: /Users/michalmikolajczyk/local/bin/node /Users/michalmikolajczyk/local/lib/node_modules/appium/node_modules/appium-uiauto/bin/command-proxy-client.js /tmp/instruments_sock 2,{"status":0,"value":{"UIAApplication":{"@":{"name":"June20","label":"June20","value":null,"dom":null,"enabled":true,"valid":tr...
> info: [debug] Socket data received (8186 bytes)
> info: [debug] Socket data received (2705 bytes)
> info: [debug] Socket data being routed.
> info: [debug] Got result from instruments: {"status":0,"value":{"UIAApplication":{"@":{"name":"June20","label":"June20","value":null,"dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0","x":0,"y":0,"width":768,"height":1024},">":[{"UIAWindow":{"@":{"name":null,"label":null,"value":null,"dom":null,"enabled":true,"vali
> info: [debug] Device launched! Ready for commands
> info: [debug] Setting command timeout to the default of 60 secs
> info: [debug] Appium session started with sessionId b45cc384-a726-45c2-94c3-4edcedd95a38
> info: <-- POST /wd/hub/session 303 19906.557 ms - 74
> info: --> POST /wd/hub/session/b45cc384-a726-45c2-94c3-4edcedd95a38/element {"using":"name","value":"Content"}
> info: [debug] Waiting up to 0ms for condition
> info: [debug] Pushing command to appium work queue: "au.getElementByName('Content')"
> info: [debug] Sending command to instruments: au.getElementByName('Content')
> info: [debug] [INST] 2015-05-30 07:28:30 +0000 Debug: Got new command 3 from instruments: au.getElementByName('Content')
> info: [debug] [INST] 2015-05-30 07:28:30 +0000 Debug: evaluating au.getElementByName('Content')
> info: [debug] [INST] 2015-05-30 07:28:30 +0000 Debug: evaluation finished
> info: [debug] [INST] 2015-05-30 07:28:30 +0000 Debug: Lookup returned [object UIAButton] with the name "Content" (id: 0).
> info: [debug] [INST] 2015-05-30 07:28:30 +0000 Debug: responding with:
> info: [debug] [INST] 2015-05-30 07:28:30 +0000 Debug: Running system command #4: /Users/michalmikolajczyk/local/bin/node /Users/michalmikolajczyk/local/lib/node_modules/appium/node_modules/appium-uiauto/bin/command-proxy-client.js /tmp/instruments_sock 2,{"status":0,"value":{"ELEMENT":"0"}}...
> info: [debug] Socket data received (38 bytes)
> info: [debug] Socket data being routed.
> info: [debug] Got result from instruments: {"status":0,"value":{"ELEMENT":"0"}}
> info: [debug] Responding to client with success: {"status":0,"value":{"ELEMENT":"0"},"sessionId":"b45cc384-a726-45c2-94c3-4edcedd95a38"}
> info: <-- POST /wd/hub/session/b45cc384-a726-45c2-94c3-4edcedd95a38/element 200 145.922 ms - 87 {"status":0,"value":{"ELEMENT":"0"},"sessionId":"b45cc384-a726-45c2-94c3-4edcedd95a38"}
> info: --> POST /wd/hub/session/b45cc384-a726-45c2-94c3-4edcedd95a38/element/0/click {}
> info: [debug] Pushing command to appium work queue: "au.tapById('0')"
> info: [debug] Sending command to instruments: au.tapById('0')
> info: [debug] [INST] 2015-05-30 07:28:30 +0000 Debug: Got new command 4 from instruments: au.tapById('0')
> info: [debug] [INST] 2015-05-30 07:28:30 +0000 Debug: evaluating au.tapById('0')
> info: [debug] [INST] 2015-05-30 07:28:30 +0000 Debug: UIAButton.tap()
> info: [debug] [INST] 2015-05-30 07:28:31 +0000 Debug: evaluation finished
> info: [debug] [INST] 2015-05-30 07:28:31 +0000 Debug: responding with:
> info: [debug] [INST] 2015-05-30 07:28:31 +0000 Debug: Running system command #5: /Users/michalmikolajczyk/local/bin/node /Users/michalmikolajczyk/local/lib/node_modules/appium/node_modules/appium-uiauto/bin/command-proxy-client.js /tmp/instruments_sock 2,{"status":0,"value":""}...
> info: [debug] Socket data received (25 bytes)
> info: [debug] Socket data being routed.
> info: [debug] Got result from instruments: {"status":0,"value":""}
> info: [debug] Responding to client with success: {"status":0,"value":"","sessionId":"b45cc384-a726-45c2-94c3-4edcedd95a38"}
> info: <-- POST /wd/hub/session/b45cc384-a726-45c2-94c3-4edcedd95a38/element/0/click 200 375.368 ms - 74 {"status":0,"value":"","sessionId":"b45cc384-a726-45c2-94c3-4edcedd95a38"}
> info: --> POST /wd/hub/session/b45cc384-a726-45c2-94c3-4edcedd95a38/element {"using":"xpath","value":"//UIAApplication[1]/UIAWindow[1]/UIATextField[1]"}
> info: [debug] Waiting up to 0ms for condition
> info: [debug] Pushing command to appium work queue: "au.mainApp().getTreeForXML()"
> info: [debug] Sending command to instruments: au.mainApp().getTreeForXML()
> info: [debug] [INST] 2015-05-30 07:28:33 +0000 Debug: Got new command 5 from instruments: au.mainApp().getTreeForXML()
> info: [debug] [INST] 2015-05-30 07:28:33 +0000 Debug: evaluating au.mainApp().getTreeForXML()
> info: [debug] [INST] 2015-05-30 07:28:33 +0000 Debug: evaluation finished
> info: [debug] [INST] 2015-05-30 07:28:33 +0000 Debug: responding with:d":true,"visible":true,"hint":null,"path":"/0/0","x":0,"y":0,"width":768,"height":1024},">":[{"UIAStaticText":{"@":{"name":"Offline content","label":"Offline content","value":"Offline content","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/0","x":337,"y":1.5,"width":94.5,"height":17},">":[]}},{"UIAScrollView":{"@":{"name":null,"label":null,"value":null,"dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/1","x":0,"y":20,"width":768,"height":1004},">":[{"UIAImage":{"@":{"name":null,"label":null,"value":null,"dom":null,"enabled":true,"valid":true,"visible":false,"hint":null,"path":"/0/0/1/0","x":765.5,"y":-16,"width":2.5,"height":36},">":[]}},{"UIAWebView":{"@":{"name":null,"label":null,"value":null,"dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/1/1","x":0,"y":20,"width":768,"height":1004},">":[{"UIAStaticText":{"@":{"name":"Timed out!","label":"Timed out!","value":"1","dom":{"id":""},"enabled":true,"valid":true,"visible":true,"hint":"","path":"/0/0/1/1/0","x":251,"y":29,"width":266,"height":74},">":[]}}]}},{"UIAImage":{"@":{"name":null,"label":null,"value":null,"dom":null,"enabled":true,"valid":true,"visible":false,"hint":null,"path":"/0/0/1/2","x":-36,"y":1021.5,"width":36,"height":2.5},">":[]}}]}},{"UIASegmentedControl":{"@":{"name":null,"label":null,"value":null,"dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/2","x":224,"y":8,"width":320,"height":29},">":[{"UIAButton":{"@":{"name":"Camera","label":"Camera","value":"","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/2/0","x":224,"y":8,"width":106,"height":29},">":[]}},{"UIAButton":{"@":{"name":"Content","label":"Content","value":"","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/2/1","x":331,"y":8,"width":106,"height":29},">":[]}},{"UIAButton":{"@":{"name":"Log","label":"Log","value":"","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/2/2","x":438,"y":8,"width":106,"height":29},">":[]}}]}},{"UIAStaticText":{"@":{"name":"version 0.9.2 – beta 1 [DEBUG]\nhttp://192.168.1.100:8080/","label":"version 0.9.2 – beta 1 [DEBUG]\nhttp://192.168.1.100:8080/","value":"version 0.9.2 – beta 1 [DEBUG]\nhttp://192.168.1.100:8080/","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/3","x":559.5,"y":8,"width":200.5,"height":33.5},">":[]}},{"UIAButton":{"@":{"name":"Reset to default","label":"Reset to default","value":null,"dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/4","x":114,"y":57,"width":112,"height":30},">":[]}},{"UIATextField":{"@":{"name":"urlField","label":"urlField","value":"http://localhost:8080/config/index.html","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/5","x":234,"y":57,"width":300,"height":30},">":[{"UIATextField":{"@":{"name":"urlField","label":"urlField","value":"http://localhost:8080/config/index.html","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/5/0","x":234,"y":57,"width":300,"height":30},">":[]}}]}},{"UIAButton":{"@":{"name":"Load URL","label":"Load URL","value":null,"dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/6","x":542,"y":57,"width":70,"height":30},">":[]}},{"UIAButton":{"@":{"name":"Reload Config","label":"Reload Config","value":null,"dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/7","x":660,"y":57,"width":100,"height":30},">":[]}},{"UIAStaticText":{"@":{"name":"Local Cache","label":"Local Cache","value":"Local Cache","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/8","x":315.5,"y":103,"width":137.5,"height":29},">":[]}},{"UIATableView":{"@":{"name":null,"label":null,"value":null,"dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/9","x":0,"y":140,"width":768,"height":838},">":[{"UIATableGroup":{"@":{"name":"Free space: 3.96 GB - Total space: 111.86 GB","label":null,"value":null,"dom":null,"enabled":true,"valid":true,"visible":false,"hint":null,"path":"/0/0/9/0","x":0,"y":140,"width":768,"height":70},">":[{"UIAStaticText":{"@":{"name":"Free space: 3.96 GB - Total space: 111.86 GB","label":"Free space: 3.96 GB - Total space: 111.86 GB","value":"Free space: 3.96 GB - Total space: 111.86 GB","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/9/0/0","x":8,"y":145,"width":352.5,"height":20.5},">":[]}},{"UIAButton":{"@":{"name":"Empty Cache","label":"Empty Cache","value":null,"dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/9/0/1","x":665,"y":140,"width":95,"height":30},">":[]}},{"UIAStaticText":{"@":{"name":"\"Offline Content\" bar:","label":"\"Offline Content\" bar:","value":"\"Offline Content\" bar:","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/9/0/2","x":8,"y":176.5,"width":165,"height":20.5},">":[]}},{"UIASwitch":{"@":{"name":null,"label":null,"value":1,"dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/9/0/3","x":181,"y":171,"width":51,"height":31},">":[]}},{"UIAStaticText":{"@":{"name":"Show Javascript errors:","label":"Show Javascript errors:","value":"Show Javascript errors:","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/9/0/4","x":294,"y":176.5,"width":181,"height":20.5},">":[]}},{"UIASwitch":{"@":{"name":null,"label":null,"value":0,"dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/9/0/5","x":483,"y":171,"width":51,"height":31},">":[]}}]}}]}},{"UIAStaticText":{"@":{"name":"Overlay brightness:","label":"Overlay brightness:","value":"Overlay brightness:","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/10","x":8,"y":991,"width":148.5,"height":20.5},">":[]}},{"UIASlider":{"@":{"name":null,"label":null,"value":"25%","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/11","x":162.5,"y":986,"width":471.5,"height":31},">":[]}},{"UIAStaticText":{"@":{"name":"CPU: 0%","label":"CPU: 0%","value":"CPU: 0%","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/12","x":640,"y":991,"width":72,"height":20.5},">":[]}},{"UIAButton":{"@":{"name":"Close","label":"Close","value":null,"dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/13","x":720,"y":986,"width":40,"height":30},">":[]}}]}},{"UIAWindow":{"@":{"name":null,"label":null,"value":null,"dom":null,"enabled":true,"valid":true,"visible":false,"hint":null,"path":"/0/1","x":0,"y":0,"width":768,"height":1024},">":[{"UIAStatusBar":{"@":{"name":null,"label":null,"value":null,"dom":null,"enabled":true,"valid":true,"visible":false,"hint":null,"path":"/0/1/0","x":0,"y":0,"width":768,"height":0},">":[]}}]}}]}}}
> 2015-05-30 07:28:33 +0000 Debug: Running system command #6: /Users/michalmikolajczyk/local/bin/node /Users/michalmikolajczyk/local/lib/node_modules/appium/node_modules/appium-uiauto/bin/command-proxy-client.js /tmp/instruments_sock 2,{"status":0,"value":{"UIAApplication":{"@":{"name":"June20","label":"June20","value":null,"dom":null,"enabled":true,"valid":tr...
> info: [debug] Socket data received (7175 bytes)
> info: [debug] Socket data being routed.
> info: [debug] Got result from instruments: {"status":0,"value":{"UIAApplication":{"@":{"name":"June20","label":"June20","value":null,"dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0","x":0,"y":0,"width":768,"height":1024},">":[{"UIAWindow":{"@":{"name":null,"label":null,"value":null,"dom":null,"enabled":true,"vali
> info: [debug] Pushing command to appium work queue: "au.getElementByIndexPath('/0/0/5')"
> info: [debug] Sending command to instruments: au.getElementByIndexPath('/0/0/5')
> info: [debug] Socket data received (38 bytes)
> info: [debug] Socket data being routed.
> info: [debug] Got result from instruments: {"status":0,"value":{"ELEMENT":"1"}}
> info: [debug] Responding to client with success: {"status":0,"value":{"ELEMENT":"1"},"sessionId":"b45cc384-a726-45c2-94c3-4edcedd95a38"}
> info: <-- POST /wd/hub/session/b45cc384-a726-45c2-94c3-4edcedd95a38/element 200 467.983 ms - 87 {"status":0,"value":{"ELEMENT":"1"},"sessionId":"b45cc384-a726-45c2-94c3-4edcedd95a38"}
> info: --> POST /wd/hub/session/b45cc384-a726-45c2-94c3-4edcedd95a38/element/1/clear {}
> info: [debug] Pushing command to appium work queue: "au.getElement('1').setValue('')"
> info: [debug] Sending command to instruments: au.getElement('1').setValue('')
> info: [debug] [INST] 2015-05-30 07:28:34 +0000 Debug: Got new command 6 from instruments: au.getElementByIndexPath('/0/0/5')
> info: [debug] [INST] 2015-05-30 07:28:34 +0000 Debug: evaluating au.getElementByIndexPath('/0/0/5')
> info: [debug] [INST] 2015-05-30 07:28:34 +0000 Debug: evaluation finished
> info: [debug] [INST] 2015-05-30 07:28:34 +0000 Debug: Lookup returned [object UIATextField] with the name "urlField" (id: 1).
> info: [debug] [INST] 2015-05-30 07:28:34 +0000 Debug: responding with:
> info: [debug] [INST] 2015-05-30 07:28:34 +0000 Debug: Running system command #7: /Users/michalmikolajczyk/local/bin/node /Users/michalmikolajczyk/local/lib/node_modules/appium/node_modules/appium-uiauto/bin/command-proxy-client.js /tmp/instruments_sock 2,{"status":0,"value":{"ELEMENT":"1"}}...
> info: [debug] [INST] 2015-05-30 07:28:34 +0000 Debug: Got new command 7 from instruments: au.getElement('1').setValue('')
> info: [debug] [INST] 2015-05-30 07:28:34 +0000 Debug: evaluating au.getElement('1').setValue('')
> info: [debug] [INST] 2015-05-30 07:28:34 +0000 Debug: target.frontMostApp().elements()[0].elements()[5].tap()
> info: [debug] [INST] 2015-05-30 07:28:34 +0000 Debug: evaluation finished
> info: [debug] [INST] 2015-05-30 07:28:34 +0000 Debug: responding with:
> info: [debug] [INST] 2015-05-30 07:28:34 +0000 Debug: Running system command #8: /Users/michalmikolajczyk/local/bin/node /Users/michalmikolajczyk/local/lib/node_modules/appium/node_modules/appium-uiauto/bin/command-proxy-client.js /tmp/instruments_sock 2,{"status":0,"value":""}...
> info: [debug] Socket data received (25 bytes)
> info: [debug] Socket data being routed.
> info: [debug] Got result from instruments: {"status":0,"value":""}
> info: [debug] Responding to client with success: {"status":0,"value":"","sessionId":"b45cc384-a726-45c2-94c3-4edcedd95a38"}
> info: <-- POST /wd/hub/session/b45cc384-a726-45c2-94c3-4edcedd95a38/element/1/clear 200 757.219 ms - 74 {"status":0,"value":"","sessionId":"b45cc384-a726-45c2-94c3-4edcedd95a38"}
> info: --> POST /wd/hub/session/b45cc384-a726-45c2-94c3-4edcedd95a38/element/1/value {"value":["http://0.0.0.0:8888/test/e2e/api/"]}
> info: [debug] Pushing command to appium work queue: "au.getElement('1').setValueByType('http:\/\/0.0.0.0:8888\/test\/e2e\/api\/')"
> info: [debug] Sending command to instruments: au.getElement('1').setValueByType('http:\/\/0.0.0.0:8888\/test\/e2e\/api\/')
> info: [debug] [INST] 2015-05-30 07:28:35 +0000 Debug: Got new command 8 from instruments: au.getElement('1').setValueByType('http:\/\/0.0.0.0:8888\/test\/e2e\/api\/')
> info: [debug] [INST] 2015-05-30 07:28:35 +0000 Debug: evaluating au.getElement('1').setValueByType('http:\/\/0.0.0.0:8888\/test\/e2e\/api\/')
> info: [debug] [INST] 2015-05-30 07:28:35 +0000 Debug: target.frontMostApp().keyboard().typeString("h")
> info: [debug] [INST] 2015-05-30 07:28:36 +0000 Debug: target.frontMostApp().keyboard().typeString("t")
> info: [debug] [INST] 2015-05-30 07:28:37 +0000 Debug: target.frontMostApp().keyboard().typeString("t")
> info: [debug] [INST] 2015-05-30 07:28:37 +0000 Debug: target.frontMostApp().keyboard().typeString("p")
> info: [debug] [INST] 2015-05-30 07:28:38 +0000 Debug: target.frontMostApp().keyboard().typeString(":")
> info: [debug] [INST] 2015-05-30 07:28:38 +0000 Debug: target.frontMostApp().keyboard().typeString("/")
> info: [debug] [INST] 2015-05-30 07:28:38 +0000 Debug: target.frontMostApp().keyboard().typeString("/")
> info: [debug] [INST] 2015-05-30 07:28:39 +0000 Debug: target.frontMostApp().keyboard().typeString("0")
> info: [debug] [INST] 2015-05-30 07:28:39 +0000 Debug: target.frontMostApp().keyboard().typeString(".")
> info: [debug] [INST] 2015-05-30 07:28:40 +0000 Debug: target.frontMostApp().keyboard().typeString("0")
> info: [debug] [INST] 2015-05-30 07:28:40 +0000 Debug: target.frontMostApp().keyboard().typeString(".")
> info: [debug] [INST] 2015-05-30 07:28:41 +0000 Debug: target.frontMostApp().keyboard().typeString("0")
> info: [debug] [INST] 2015-05-30 07:28:41 +0000 Debug: target.frontMostApp().keyboard().typeString(".")
> info: [debug] [INST] 2015-05-30 07:28:41 +0000 Debug: target.frontMostApp().keyboard().typeString("0")
> info: [debug] [INST] 2015-05-30 07:28:42 +0000 Debug: target.frontMostApp().keyboard().typeString(":")
> info: [debug] [INST] 2015-05-30 07:28:42 +0000 Debug: target.frontMostApp().keyboard().typeString("8")
> info: [debug] [INST] 2015-05-30 07:28:42 +0000 Debug: target.frontMostApp().keyboard().typeString("8")
> info: [debug] [INST] 2015-05-30 07:28:43 +0000 Debug: target.frontMostApp().keyboard().typeString("8")
> info: [debug] [INST] 2015-05-30 07:28:43 +0000 Debug: target.frontMostApp().keyboard().typeString("8")
> info: [debug] [INST] 2015-05-30 07:28:44 +0000 Debug: target.frontMostApp().keyboard().typeString("/")
> info: [debug] [INST] 2015-05-30 07:28:44 +0000 Debug: target.frontMostApp().keyboard().typeString("t")
> info: [debug] [INST] 2015-05-30 07:28:45 +0000 Debug: target.frontMostApp().keyboard().typeString("e")
> info: [debug] [INST] 2015-05-30 07:28:45 +0000 Debug: target.frontMostApp().keyboard().typeString("s")
> info: [debug] [INST] 2015-05-30 07:28:45 +0000 Debug: target.frontMostApp().keyboard().typeString("t")
> info: [debug] [INST] 2015-05-30 07:28:46 +0000 Debug: target.frontMostApp().keyboard().typeString("/")
> info: [debug] [INST] 2015-05-30 07:28:46 +0000 Debug: target.frontMostApp().keyboard().typeString("e")
> info: [debug] [INST] 2015-05-30 07:28:46 +0000 Debug: target.frontMostApp().keyboard().typeString("2")
> info: [debug] [INST] 2015-05-30 07:28:47 +0000 Debug: target.frontMostApp().keyboard().typeString("e")
> info: [debug] [INST] 2015-05-30 07:28:47 +0000 Debug: target.frontMostApp().keyboard().typeString("/")
> info: [debug] [INST] 2015-05-30 07:28:48 +0000 Debug: target.frontMostApp().keyboard().typeString("a")
> info: [debug] [INST] 2015-05-30 07:28:48 +0000 Debug: target.frontMostApp().keyboard().typeString("p")
> info: [debug] [INST] 2015-05-30 07:28:49 +0000 Debug: target.frontMostApp().keyboard().typeString("i")
> info: [debug] [INST] 2015-05-30 07:28:49 +0000 Debug: target.frontMostApp().keyboard().typeString("/")
> info: [debug] [INST] 2015-05-30 07:28:49 +0000 Debug: evaluation finished
> info: [debug] [INST] 2015-05-30 07:28:49 +0000 Debug: responding with:
> info: [debug] [INST] 2015-05-30 07:28:49 +0000 Debug: Running system command #9: /Users/michalmikolajczyk/local/bin/node /Users/michalmikolajczyk/local/lib/node_modules/appium/node_modules/appium-uiauto/bin/command-proxy-client.js /tmp/instruments_sock 2,{"status":0,"value":""}...
> info: [debug] Socket data received (25 bytes)
> info: [debug] Socket data being routed.
> info: [debug] Got result from instruments: {"status":0,"value":""}
> info: [debug] Responding to client with success: {"status":0,"value":"","sessionId":"b45cc384-a726-45c2-94c3-4edcedd95a38"}
> info: <-- POST /wd/hub/session/b45cc384-a726-45c2-94c3-4edcedd95a38/element/1/value 200 14932.150 ms - 74 {"status":0,"value":"","sessionId":"b45cc384-a726-45c2-94c3-4edcedd95a38"}
> info: --> POST /wd/hub/session/b45cc384-a726-45c2-94c3-4edcedd95a38/element {"using":"name","value":"Load URL"}
> info: [debug] Waiting up to 0ms for condition
> info: [debug] Pushing command to appium work queue: "au.getElementByName('Load URL')"
> info: [debug] Sending command to instruments: au.getElementByName('Load URL')
> info: [debug] [INST] 2015-05-30 07:28:50 +0000 Debug: Got new command 9 from instruments: au.getElementByName('Load URL')
> info: [debug] [INST] 2015-05-30 07:28:50 +0000 Debug: evaluating au.getElementByName('Load URL')
> info: [debug] [INST] 2015-05-30 07:28:50 +0000 Debug: evaluation finished
> info: [debug] [INST] 2015-05-30 07:28:50 +0000 Debug: Lookup returned [object UIAButton] with the name "Load URL" (id: 2).
> info: [debug] [INST] 2015-05-30 07:28:50 +0000 Debug: responding with:
> info: [debug] [INST] 2015-05-30 07:28:50 +0000 Debug: Running system command #10: /Users/michalmikolajczyk/local/bin/node /Users/michalmikolajczyk/local/lib/node_modules/appium/node_modules/appium-uiauto/bin/command-proxy-client.js /tmp/instruments_sock 2,{"status":0,"value":{"ELEMENT":"2"}}...
> info: [debug] Socket data received (38 bytes)
> info: [debug] Socket data being routed.
> info: [debug] Got result from instruments: {"status":0,"value":{"ELEMENT":"2"}}
> info: [debug] Responding to client with success: {"status":0,"value":{"ELEMENT":"2"},"sessionId":"b45cc384-a726-45c2-94c3-4edcedd95a38"}
> info: <-- POST /wd/hub/session/b45cc384-a726-45c2-94c3-4edcedd95a38/element 200 117.097 ms - 87 {"status":0,"value":{"ELEMENT":"2"},"sessionId":"b45cc384-a726-45c2-94c3-4edcedd95a38"}
> info: --> POST /wd/hub/session/b45cc384-a726-45c2-94c3-4edcedd95a38/element/2/click {}
> info: [debug] Pushing command to appium work queue: "au.tapById('2')"
> info: [debug] Sending command to instruments: au.tapById('2')
> info: [debug] [INST] 2015-05-30 07:28:50 +0000 Debug: Got new command 10 from instruments: au.tapById('2')
> info: [debug] [INST] 2015-05-30 07:28:50 +0000 Debug: evaluating au.tapById('2')
> info: [debug] [INST] 2015-05-30 07:28:50 +0000 Debug: UIAButton.tap()
> info: [debug] Socket data received (25 bytes)
> info: [debug] Socket data being routed.
> info: [debug] Got result from instruments: {"status":0,"value":""}
> info: [debug] Responding to client with success: {"status":0,"value":"","sessionId":"b45cc384-a726-45c2-94c3-4edcedd95a38"}
> info: <-- POST /wd/hub/session/b45cc384-a726-45c2-94c3-4edcedd95a38/element/2/click 200 555.608 ms - 74 {"status":0,"value":"","sessionId":"b45cc384-a726-45c2-94c3-4edcedd95a38"}
> info: --> GET /wd/hub/session/b45cc384-a726-45c2-94c3-4edcedd95a38/contexts {}
> info: [debug] [REMOTE] Debugger socket connected to ::1:27753
> info: [debug] [REMOTE] Sending connection key
> info: [debug] [REMOTE] Sending _rpc_reportIdentifier: message to remote debugger
> info: [debug] [REMOTE] {"__argument":{"WIRConnectionIdentifierKey":"6e6cb8cd-8b54-4ee7-87fe-617904f3607a"},"__selector":"_rpc_reportIdentifier:"}
> info: [debug] [INST] 2015-05-30 07:28:50 +0000 Debug: evaluation finished
> info: [debug] [INST] 2015-05-30 07:28:50 +0000 Debug: responding with:
> info: [debug] [INST] 2015-05-30 07:28:50 +0000 Debug: Running system command #11: /Users/michalmikolajczyk/local/bin/node /Users/michalmikolajczyk/local/lib/node_modules/appium/node_modules/appium-uiauto/bin/command-proxy-client.js /tmp/instruments_sock 2,{"status":0,"value":""}...
> info: [debug] [REMOTE] Receiving data from remote debugger
> info: [debug] [REMOTE] Receiving data from remote debugger
> info: [debug] [REMOTE] {"__argument":{"WIRSimulatorBuildKey":"12F69","WIRSimulatorProductVersionKey":"8.3","WIRSimulatorNameKey":"iPad Simulator"},"__selector":"_rpc_reportSetup:"}
> info: [debug] [REMOTE] Sim name: iPad Simulator
> info: [debug] [REMOTE] Sim build: 12F69
> info: [debug] [REMOTE] {"__argument":{"WIRApplicationDictionaryKey":{"PID:99797":{"WIRApplicationIdentifierKey":"PID:99797","WIRIsApplicationProxyKey":false,"WIRApplicationNameKey":"June20","WIRApplicationBundleIdentifierKey":"com.june20.June20","WIRIsApplicationActiveKey":1}}},"__selector":"_rpc_reportConnectedApplicationList:"}
> info: [debug] Using remote debugger app key: PID:99797
> info: [debug] [REMOTE] Selecting app PID:99797 (try #1)
> info: [debug] [REMOTE] function () { [native code] }
> info: [debug] [REMOTE] Sending _rpc_forwardGetListing: message to remote debugger
> info: [debug] [REMOTE] {"__argument":{"WIRConnectionIdentifierKey":"6e6cb8cd-8b54-4ee7-87fe-617904f3607a","WIRApplicationIdentifierKey":"PID:99797"},"__selector":"_rpc_forwardGetListing:"}
> info: [debug] [REMOTE] Receiving data from remote debugger
> info: [debug] [REMOTE] Receiving data from remote debugger
> info: [debug] [REMOTE] {"__argument":{"WIRApplicationIdentifierKey":"PID:99839","WIRIsApplicationProxyKey":true,"WIRApplicationNameKey":"","WIRApplicationBundleIdentifierKey":"com.apple.WebKit.WebContent","WIRIsApplicationActiveKey":0,"WIRHostApplicationIdentifierKey":"PID:99797"},"__selector":"_rpc_applicationConnected:"}
> info: [REMOTE] We were notified we connected to possibly the wrong app. Using the id key suggested and trying again
> info: [debug] [REMOTE] Selecting app PID:99839 (try #2)
> info: [debug] [REMOTE] function () { [native code] }
> info: [debug] [REMOTE] Sending _rpc_forwardGetListing: message to remote debugger
> info: [debug] [REMOTE] {"__argument":{"WIRConnectionIdentifierKey":"6e6cb8cd-8b54-4ee7-87fe-617904f3607a","WIRApplicationIdentifierKey":"PID:99839"},"__selector":"_rpc_forwardGetListing:"}
> info: [debug] [REMOTE] Receiving data from remote debugger
> info: [debug] [REMOTE] {"__argument":{"WIRApplicationIdentifierKey":"PID:99808","WIRIsApplicationProxyKey":true,"WIRApplicationNameKey":"","WIRApplicationBundleIdentifierKey":"com.apple.WebKit.WebContent","WIRIsApplicationActiveKey":0,"WIRHostApplicationIdentifierKey":"PID:99797"},"__selector":"_rpc_applicationConnected:"}
> info: [REMOTE] We were notified we connected to possibly the wrong app. Using the id key suggested and trying again
> info: [debug] [REMOTE] Selecting app PID:99808 (try #3)
> info: [debug] [REMOTE] function () { [native code] }
> info: [debug] [REMOTE] Sending _rpc_forwardGetListing: message to remote debugger
> info: [debug] [REMOTE] {"__argument":{"WIRConnectionIdentifierKey":"6e6cb8cd-8b54-4ee7-87fe-617904f3607a","WIRApplicationIdentifierKey":"PID:99808"},"__selector":"_rpc_forwardGetListing:"}
> info: [debug] [REMOTE] Receiving data from remote debugger
> info: [debug] [REMOTE] Receiving data from remote debugger
> info: [debug] [REMOTE] {"__argument":{"WIRApplicationIdentifierKey":"PID:99810","WIRIsApplicationProxyKey":true,"WIRApplicationNameKey":"","WIRApplicationBundleIdentifierKey":"com.apple.WebKit.WebContent","WIRIsApplicationActiveKey":0,"WIRHostApplicationIdentifierKey":"PID:99797"},"__selector":"_rpc_applicationConnected:"}
> info: [REMOTE] We were notified we connected to possibly the wrong app. Using the id key suggested and trying again
> info: [debug] [REMOTE] Selecting app PID:99810 (try #4)
> info: [debug] [REMOTE] function () { [native code] }
> info: [debug] [REMOTE] Sending _rpc_forwardGetListing: message to remote debugger
> info: [debug] [REMOTE] {"__argument":{"WIRConnectionIdentifierKey":"6e6cb8cd-8b54-4ee7-87fe-617904f3607a","WIRApplicationIdentifierKey":"PID:99810"},"__selector":"_rpc_forwardGetListing:"}
> info: [debug] [REMOTE] Receiving data from remote debugger
> info: [debug] [REMOTE] {"__argument":{"WIRApplicationIdentifierKey":"PID:99797","WIRListingKey":{}},"__selector":"_rpc_applicationSentListing:"}
> error: [REMOTE] Could not connect to a valid app after 4 tries.
> info: [debug] Responding to client with success: {"status":0,"value":["NATIVE_APP"],"sessionId":"b45cc384-a726-45c2-94c3-4edcedd95a38"}
> info: <-- GET /wd/hub/session/b45cc384-a726-45c2-94c3-4edcedd95a38/contexts 200 979.221 ms - 86 {"status":0,"value":["NATIVE_APP"],"sessionId":"b45cc384-a726-45c2-94c3-4edcedd95a38"}
> info: [debug] [REMOTE] {"__argument":{"WIRApplicationIdentifierKey":"PID:99839","WIRListingKey":{"1":{"WIRTitleKey":"","WIRTypeKey":"WIRTypeWeb","WIRURLKey":"about:blank","WIRPageIdentifierKey":1}}},"__selector":"_rpc_applicationSentListing:"}
> info: [debug] [REMOTE] {"__argument":{"WIRApplicationIdentifierKey":"PID:99808","WIRListingKey":{"1":{"WIRTitleKey":"test","WIRTypeKey":"WIRTypeWeb","WIRURLKey":"http://0.0.0.0:8888/test/e2e/api/","WIRPageIdentifierKey":1}}},"__selector":"_rpc_applicationSentListing:"}
> info: [debug] [REMOTE] Receiving data from remote debugger
> info: [debug] [REMOTE] {"__argument":{"WIRApplicationIdentifierKey":"PID:99810","WIRListingKey":{"1":{"WIRTitleKey":"","WIRTypeKey":"WIRTypeWeb","WIRURLKey":"about:blank","WIRPageIdentifierKey":1}}},"__selector":"_rpc_applicationSentListing:"}
> info: --> DELETE /wd/hub/session/b45cc384-a726-45c2-94c3-4edcedd95a38 {}
> info: Shutting down appium session
> info: [debug] Stopping ios
> info: [debug] Destroying instruments client socket.
> info: [debug] Closing socket server.
> info: [debug] Instruments socket server was closed
> info: [debug] Sending sigterm to instruments
> info: [debug] [INST] 2015-05-30 07:28:51 +0000 Stopped: Script was stopped by the user
> info: [debug] [REMOTE] Receiving data from remote debugger
> info: [debug] [REMOTE] {"__argument":{"WIRApplicationIdentifierKey":"PID:99797","WIRIsApplicationProxyKey":false,"WIRApplicationNameKey":"June20","WIRApplicationBundleIdentifierKey":"com.june20.June20","WIRIsApplicationActiveKey":1},"__selector":"_rpc_applicationDisconnected:"}
> info: [debug] [REMOTE] {"__argument":{"WIRApplicationIdentifierKey":"PID:99810","WIRIsApplicationProxyKey":true,"WIRApplicationNameKey":"","WIRApplicationBundleIdentifierKey":"com.apple.WebKit.WebContent","WIRIsApplicationActiveKey":0,"WIRHostApplicationIdentifierKey":"PID:99797"},"__selector":"_rpc_applicationDisconnected:"}
> info: [debug] [REMOTE] {"__argument":{"WIRApplicationIdentifierKey":"PID:99839","WIRIsApplicationProxyKey":true,"WIRApplicationNameKey":"","WIRApplicationBundleIdentifierKey":"com.apple.WebKit.WebContent","WIRIsApplicationActiveKey":0,"WIRHostApplicationIdentifierKey":"PID:99797"},"__selector":"_rpc_applicationDisconnected:"}
> info: [debug] [REMOTE] {"__argument":{"WIRApplicationIdentifierKey":"PID:99808","WIRIsApplicationProxyKey":true,"WIRApplicationNameKey":"","WIRApplicationBundleIdentifierKey":"com.apple.WebKit.WebContent","WIRIsApplicationActiveKey":0,"WIRHostApplicationIdentifierKey":"PID:99797"},"__selector":"_rpc_applicationDisconnected:"}
> info: [debug] [INST STDERR] 2015-05-30 09:28:51.783 instruments[99747:8068170] Attempting to set event horizon when core is not engaged, request ignored
> info: [debug] [INST STDERR] 2015-05-30 09:28:51.783 instruments[99747:8068169] Attempting to set event horizon when core is not engaged, request ignored
> info: [debug] [INST] Instruments Trace Complete (Duration : 37.315399s; Output : /tmp/appium-instruments/instrumentscli0.trace)
> info: [debug] [INSTSERVER] Instruments exited with code 0
> info: [debug] Cleaning up after instruments exit
> info: [debug] Stopping iOS log capture
> info: [debug] [REMOTE] Disconnecting from remote debugger
> info: [debug] Running ios sim reset flow
> info: [debug] Killing the simulator process
> info: [debug] Killall iOS Simulator
> info: [debug] Killing any other simulator daemons
> info: [debug] [REMOTE] Debugger socket disconnected
> info: [debug] Cleaning sim data files
> info: Executing: xcrun simctl erase F95857C6-DC1C-4918-AE2B-2532F5E50E45 with timeout 2000
> info: Executing: xcrun simctl erase F95857C6-DC1C-4918-AE2B-2532F5E50E45 with timeout 2000
> info: <-- DELETE /wd/hub/session/b45cc384-a726-45c2-94c3-4edcedd95a38 - - ms - -
> info: Executing: xcrun simctl erase F95857C6-DC1C-4918-AE2B-2532F5E50E45 with timeout 2000
> info: [debug] Cleaning app data files
> warn: Applications directory /Users/michalmikolajczyk/Library/Developer/CoreSimulator/Devices/F95857C6-DC1C-4918-AE2B-2532F5E50E45/data/Containers/Data/Application doesn't exist. Have you run this simulator before?
> warn: Applications directory /Users/michalmikolajczyk/Library/Developer/CoreSimulator/Devices/F95857C6-DC1C-4918-AE2B-2532F5E50E45/data/Containers/Bundle/Application doesn't exist. Have you run this simulator before?
> info: Couldn't find app directories to delete. Probably it's not installed
> info: [debug] Cleaning up appium session
> info: [debug] Responding to client with success: {"status":0,"value":null,"sessionId":"b45cc384-a726-45c2-94c3-4edcedd95a38"}