Can't click hybrid app button in iOS 8 simulator

i have the following setup

  • appium 1.3.1 with the node (JavaScript) bindings (admc/wd version 0.3.10)
  • iOS simulator 8.1
  • Mac OSX 10.9.5

i’m automating an ionic/cordova hybrid app. all of the tests were working with xcode 5 / iOS 7 simulator using element.click(). after an upgrade to xcode 6 / ios 8 simulator, appium was not able to click buttons that were tied to the on-tap directive.

these buttons are waiting for an onTap event and then call out to some javascript code. the click seems to succeed, but the corresponding JavaScript code is never executed. any following test steps will fail because they’re expecting an action to take place when the button is clicked (transition to another screen, for example).

i’m able to switch to the webview context and click on input fields and send text without problems. i can also click on elements (such as anchors) that have an href attribute.

i have tried the following and none of these have helped.

  • upgraded appium, ios-sim, wd, ionic and cordova via npm to the newest versions
  • element.tap() with and without the nativeWebTap capability enabled

i’ve also seen these related posts which get close to the issue i am having, but don’t completely address the problem.
#4076
#3246

Code and test snippet, capabilities, and logging listed below.

<a on-tap="newUserTapped()" class="button button-positive button-utility new-user">New User?</a>

return _driver.waitForElementByClassName("new-user", environment.wd.asserters.isDisplayed, 20000).click().then(_commands.pageTitle.shouldEqual(_driver, environment, "TERMS & CONDITIONS"))

exports.ios81 = {
    browserName: '',
    'appium-version': '1.0',
    platformName: 'iOS',
    platformVersion: '8.1',
    deviceName: 'iPhone 6',
    autoAcceptAlerts: true,
    app: undefined // will be set later
};

 > CALL init({"browserName":"","appium-version":"1.0","platformName":"iOS","platformVersion":"8.1","deviceName":"iPhone 6","autoAcceptAlerts":true,"app":"/path/to/app.app"}) 
 > POST /session {"desiredCapabilities":{"browserName":"","appium-version":"1.0","platformName":"iOS","platformVersion":"8.1","deviceName":"iPhone 6","autoAcceptAlerts":true,"app":"/path/to/app.app"}}

Driving the web on session: ffa28c3f-bca5-41cd-aaa0-052476284fc3

 > RESPONSE init({"browserName":"","appium-version":"1.0","platformName":"iOS","platformVersion":"8.1","deviceName":"iPhone 6","autoAcceptAlerts":true,"app":"/path/to/app.app"}) "ffa28c3f-bca5-41cd-aaa0-052476284fc3",null
 > CALL setImplicitWaitTimeout(5000) 
 > POST /session/:sessionID/timeouts/implicit_wait {"ms":5000}
 > RESPONSE setImplicitWaitTimeout(5000) 
 > CALL resetApp() 
 > POST /session/:sessionID/appium/app/reset 
 > RESPONSE resetApp() 
 > CALL sleep(5000) 
 > RESPONSE sleep(5000) 
 > CALL contexts() 
 > GET /session/:sessionID/contexts 
 > RESPONSE contexts() ["NATIVE_APP","WEBVIEW_1"]
 > CALL context("WEBVIEW_1") 
 > POST /session/:sessionID/context {"name":"WEBVIEW_1"}
 > RESPONSE context("WEBVIEW_1") 
^^^^^^^^^^^^^^^^^^^^ [this line added] ^^^^^^^^^^^^^^^^^^^
 > CALL waitForElementByClassName("new-user",{},20000) 
 > CALL elements("class name","new-user") 
 > POST /session/:sessionID/elements {"using":"class name","value":"new-user"}
 > RESPONSE elements("class name","new-user") [{"ELEMENT":"5000"}]
 > CALL element.isDisplayed() 
 > GET /session/:sessionID/element/5000/displayed 
 > RESPONSE element.isDisplayed() true
 > RESPONSE waitForElementByClassName("new-user",{},20000) {"ELEMENT":"5000"}
 > CALL element.click() 
 > POST /session/:sessionID/element/5000/click 
 > RESPONSE element.click() 
^^^^^^^^^^^^^^^^^^^^ [this line added] ^^^^^^^^^^^^^^^^^^^
 > CALL waitForElementByClassName("page-title",{},20000) 
 > CALL elements("class name","page-title") 
 > POST /session/:sessionID/elements {"using":"class name","value":"page-title"}
 > RESPONSE elements("class name","page-title") [{"ELEMENT":"5001"}]
 > CALL element.text() 
 > GET /session/:sessionID/element/5001/text 
 > RESPONSE element.text() ""
 > CALL elements("class name","page-title") 
 > POST /session/:sessionID/elements {"using":"class name","value":"page-title"}
 > RESPONSE elements("class name","page-title") [{"ELEMENT":"5002"}]
 > CALL element.text() 
 > GET /session/:sessionID/element/5002/text 
 > RESPONSE element.text() ""
 > CALL elements("class name","page-title") 
 > POST /session/:sessionID/elements {"using":"class name","value":"page-title"}
 > RESPONSE elements("class name","page-title") [{"ELEMENT":"5003"}]
.
.
.
[repeats page-title cycle until timeout is reached]




info: Welcome to Appium v1.3.1 (REV 1160ce02bb89c354cb99317985123acf39f0e7d3)
info: Appium REST http interface listener started on 0.0.0.0:4723
info: Console LogLevel: debug
info: --> POST /wd/hub/session {"desiredCapabilities":{"browserName":"","appium-version":"1.0","platformName":"iOS","platformVersion":"8.1","deviceName":"iPhone 6","autoAcceptAlerts":true,"app":"/path/to/app.app"}}
info: Client User-Agent string: undefined
info: [debug] The following desired capabilities were provided, but not recognized by appium. They will be passed on to any other services running on this server. : appium-version
info: [debug] Using local app from desired caps: /path/to/app.app
info: [debug] Creating new appium session ffa28c3f-bca5-41cd-aaa0-052476284fc3
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.1
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.1","iOSSDKVersion":"8.1","deviceName":"iPhone 6","platformVersion":"8.1"}
info: [debug] fixDevice is on
info: [debug] Final device string is: 'iPhone 6 (8.1 Simulator)'
info: [debug] iOS sim UDID is 6958C66F-8343-4743-895B-458C6AE7AA66
info: [debug] Detecting automation tracetemplate
info: [debug] Not auto-detecting udid, running on sim
info: [debug] Parsed app Info.plist (as binary)
info: [debug] Parsed app Localizable.strings
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/dev1/Library/Application Support/appium/bootstrap
info: [debug] Dynamic env: {"nodePath":"/usr/local/Cellar/node/0.10.30/bin/node","commandProxyClientPath":"/usr/local/lib/node_modules/appium/node_modules/appium-uiauto/bin/command-proxy-client.js","instrumentsSock":"/tmp/instruments_sock","interKeyDelay":null,"justLoopInfinitely":false,"autoAcceptAlerts":true}
...o: [debug] Dynamic bootstrap code: // This file is automatically generated. Do not manually modify!
info: [debug] Dynamic bootstrap path: /Users/dev1/Library/Application Support/appium/bootstrap/bootstrap-4c8030a82e57e676.js
info: [debug] Reusing dynamic bootstrap: /Users/dev1/Library/Application Support/appium/bootstrap/bootstrap-4c8030a82e57e676.js
info: [debug] Getting device string from opts: {"forceIphone":false,"forceIpad":false,"xcodeVersion":"6.1","iOSSDKVersion":"8.1","deviceName":"iPhone 6","platformVersion":"8.1"}
info: [debug] fixDevice is on
info: [debug] Final device string is: 'iPhone 6 (8.1 Simulator)'
info: [debug] We're on iOS7.1+ so forcing defaultDevice on
info: [debug] Getting device string from opts: {"forceIphone":false,"forceIpad":false,"xcodeVersion":"6.1","iOSSDKVersion":"8.1","deviceName":"iPhone 6","platformVersion":"8.1"}
info: [debug] fixDevice is on
info: [debug] Final device string is: 'iPhone 6 (8.1 Simulator)'
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: [debug] Executing: xcrun simctl erase 6958C66F-8343-4743-895B-458C6AE7AA66
info: [debug] Cleaning app data files
warn: Applications directory /Users/dev1/Library/Developer/CoreSimulator/Devices/6958C66F-8343-4743-895B-458C6AE7AA66/data/Containers/Data/Application doesn't exist. Have you run this simulator before?
warn: Applications directory /Users/dev1/Library/Developer/CoreSimulator/Devices/6958C66F-8343-4743-895B-458C6AE7AA66/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 iPhone 6 (8.1 Simulator)
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 "iPhone 6 (8.1 Simulator)" /path/to/app.app -e UIASCRIPT "/Users/dev1/Library/Application Support/appium/bootstrap/bootstrap-4c8030a82e57e676.js" -e UIARESULTSPATH /tmp/appium-instruments
info: [debug] And extra without-delay env: {"DYLD_INSERT_LIBRARIES":"/usr/local/lib/node_modules/appium/node_modules/appium-instruments/thirdparty/iwd6/InstrumentsShim.dylib","LIB_PATH":"/usr/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] 2014-12-23 16:22:11 +0000 Debug: evaluation finished
info: [debug] [INST] 2014-12-23 16:22:11 +0000 Debug: responding with:
info: [debug] [INST] 2014-12-23 16:22:11 +0000 Debug: Running system command #1: /usr/local/Cellar/node/0.10.30/bin/node /usr/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] 2014-12-23 16:22:11 +0000 Debug: Got new command 1 from instruments: au.setScreenOrientation('PORTRAIT')
info: [debug] [INST] 2014-12-23 16:22:11 +0000 Debug: evaluating au.setScreenOrientation('PORTRAIT')
info: [debug] [INST] 2014-12-23 16:22:11 +0000 Debug: target.setDeviceOrientation("1")
info: [debug] [INST] 2014-12-23 16:22:11 +0000 Debug: evaluation finished
info: [debug] [INST] 2014-12-23 16:22:11 +0000 Debug: responding with:
info: [debug] [INST] 2014-12-23 16:22:11 +0000 Debug: Running system command #2: /usr/local/Cellar/node/0.10.30/bin/node /usr/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] 2014-12-23 16:22:11 +0000 Debug: Got new command 2 from instruments: au.mainApp().getTreeForXML()
info: [debug] [INST] 2014-12-23 16:22:11 +0000 Debug: evaluating au.mainApp().getTreeForXML()
info: [debug] Socket data received (5175 bytes)
info: [debug] Socket data being routed.
info: [debug] Got result from instruments: {"status":0,"value":{"UIAApplication":{"@":{"name":"APP","label":"APP","value":null,"dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0","x":0,"y":24.125,"width":375,"height":642.1875},">":[{"UIAWindow":{"@":{"name":null,"label":null,"value":null,"dom":null,"enabled":true,"v
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 ffa28c3f-bca5-41cd-aaa0-052476284fc3
info: <-- POST /wd/hub/session 303 13978.386 ms - 9 
info: --> POST /wd/hub/session/ffa28c3f-bca5-41cd-aaa0-052476284fc3/timeouts/implicit_wait {"ms":5000}
info: [debug] Set iOS implicit wait to 5000ms
info: [debug] Responding to client with success: {"status":0,"value":null,"sessionId":"ffa28c3f-bca5-41cd-aaa0-052476284fc3"}
info: <-- POST /wd/hub/session/ffa28c3f-bca5-41cd-aaa0-052476284fc3/timeouts/implicit_wait 200 3.738 ms - 76 {"status":0,"value":null,"sessionId":"ffa28c3f-bca5-41cd-aaa0-052476284fc3"}
info: [debug] [INST] 2014-12-23 16:22:11 +0000 Debug: evaluation finished
info: [debug] [INST] 2014-12-23 16:22:11 +0000 Debug: responding with:alid":true,"visible":true,"hint":null,"path":"/0/0","x":0,"y":0,"width":375,"height":667},">":[]}},{"UIAWindow":{"@":{"name":null,"label":null,"value":null,"dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/1","x":0,"y":0.6875,"width":375,"height":665.625},">":[{"UIAScrollView":{"@":{"name":null,"label":null,"value":null,"dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/1/0","x":0,"y":0.6875,"width":375,"height":665.625},">":[{"UIAWebView":{"@":{"name":null,"label":null,"value":null,"dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/1/0/0","x":0,"y":0.6875,"width":375,"height":665.625},">":[{"UIAImage":{"@":{"name":"---","label":"---","value":"","dom":{"id":""},"enabled":true,"valid":true,"visible":true,"hint":"","path":"/0/1/0/0/0","x":29.296875,"y":90.921875,"width":316.40625,"height":131.25},">":[]}},{"UIATextField":{"@":{"name":null,"label":null,"value":"User ID","dom":{"id":""},"enabled":true,"valid":true,"visible":true,"hint":"","path":"/0/1/0/0/1","x":65.625,"y":253.8125,"width":296.484375,"height":39.84375},">":[]}},{"UIASecureTextField":{"@":{"name":null,"label":null,"value":"Password","dom":{"id":""},"enabled":true,"valid":true,"visible":true,"hint":"","path":"/0/1/0/0/2","x":65.625,"y":311.234375,"width":296.484375,"height":39.84375},">":[]}},{"UIAButton":{"@":{"name":"LOG IN","label":"LOG IN","value":"","dom":{"id":""},"enabled":true,"valid":true,"visible":true,"hint":"","path":"/0/1/0/0/3","x":11.71875,"y":369.828125,"width":351.5625,"height":49.21875},">":[]}},{"UIAStaticText":{"@":{"name":"Remember Username","label":"Remember Username","value":"","dom":{"id":""},"enabled":true,"valid":true,"visible":false,"hint":"","path":"/0/1/0/0/4","x":123.046875,"y":453.03125,"width":150,"height":18.75},">":[]}},{"UIASwitch":{"@":{"name":null,"label":null,"value":0,"dom":{"id":""},"enabled":true,"valid":true,"visible":true,"hint":"","path":"/0/1/0/0/5","x":79.6875,"y":442.484375,"width":215.625,"height":43.359375},">":[]}},{"UIAStaticText":{"@":{"name":"NEW USER?","label":"NEW USER?","value":"","dom":{"id":""},"enabled":true,"valid":true,"visible":false,"hint":"","path":"/0/1/0/0/6","x":140.625,"y":523.34375,"width":93.75,"height":21.09375},">":[]}}]}},{"UIAImage":{"@":{"name":null,"label":null,"value":null,"dom":null,"enabled":true,"valid":true,"visible":false,"hint":null,"path":"/0/1/0/1","x":372.0703125,"y":0.6875,"width":2.9296875,"height":665.625},">":[]}},{"UIAImage":{"@":{"name":null,"label":null,"value":null,"dom":null,"enabled":true,"valid":true,"visible":false,"hint":null,"path":"/0/1/0/2","x":0,"y":663.3828125,"width":375,"height":2.9296875},">":[]}}]}}]}},{"UIAWindow":{"@":{"name":null,"label":null,"value":null,"dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/2","x":0,"y":0.6875,"width":375,"height":665.625},">":[{"UIAStatusBar":{"@":{"name":null,"label":null,"value":null,"dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/2/0","x":0,"y":0.6875,"width":375,"height":23.4375},">":[{"UIAElement":{"@":{"name":"Swipe down with three fingers to reveal the notification center., Swipe up with three fingers to reveal the control center, Double-tap to scroll to top","label":null,"value":null,"dom":null,"enabled":true,"valid":true,"visible":true,"hint":"Swipe down with three fingers to reveal the notification center., Swipe up with three fingers to reveal the control center, Double-tap to scroll to top","path":"/0/2/0/0","x":7.03125,"y":0.6875,"width":44.53125,"height":23.4375},">":[]}},{"UIAElement":{"@":{"name":"3 of 3 Wi-Fi bars","label":"3 of 3 Wi-Fi bars","value":null,"dom":null,"enabled":true,"valid":true,"visible":true,"hint":"Swipe down with three fingers to reveal the notification center., Swipe up with three fingers to reveal the control center, Double-tap to scroll to top","path":"/0/2/0/1","x":57.421875,"y":0.6875,"width":15.234375,"height":23.4375},">":[]}},{"UIAElement":{"@":{"name":"10:22 AM","label":"10:22 AM","value":null,"dom":null,"enabled":true,"valid":true,"visible":true,"hint":"Swipe down with three fingers to reveal the notification center., Swipe up with three fingers to reveal the control center, Double-tap to scroll to top","path":"/0/2/0/2","x":157.03125,"y":0.6875,"width":60.9375,"height":23.4375},">":[]}},{"UIAElement":{"@":{"name":"100% battery power","label":"100% battery power","value":null,"dom":null,"enabled":true,"valid":true,"visible":true,"hint":"Swipe down with three fingers to reveal the notification center., Swipe up with three fingers to reveal the control center, Double-tap to scroll to top","path":"/0/2/0/3","x":339.84375,"y":0.6875,"width":29.296875,"height":23.4375},">":[]}}]}}]}}]}}}
info: [debug] [INST] 2014-12-23 16:22:11 +0000 Debug: Running system command #3: /usr/local/Cellar/node/0.10.30/bin/node /usr/local/lib/node_modules/appium/node_modules/appium-uiauto/bin/command-proxy-client.js /tmp/instruments_sock 2,{"status":0,"value":{"UIAApplication":{"@":{"name":"APP","label":"APP","value":null,"dom":null,"enabled":true,"valid":true,"visible":true,"hint":n...
info: --> POST /wd/hub/session/ffa28c3f-bca5-41cd-aaa0-052476284fc3/appium/app/reset {}
info: [debug] Resetting app mid-session
info: [debug] Running generic full reset
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] 2014-12-23 16:22:11 +0000 Stopped: Script was stopped by the user
info: [debug] [INST] 2014-12-23 16:22:11 +0000 Error: Script threw an uncaught JavaScript error: JSON Parse error: Invalid number on line 2564 of bootstrap-4c8030a82e57e676.js
info: [debug] [INST] Instruments Trace Complete (Duration : 10.305925s; 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] 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: [debug] Executing: xcrun simctl erase 6958C66F-8343-4743-895B-458C6AE7AA66
info: [debug] Cleaning app data files
warn: Applications directory /Users/dev1/Library/Developer/CoreSimulator/Devices/6958C66F-8343-4743-895B-458C6AE7AA66/data/Containers/Data/Application doesn't exist. Have you run this simulator before?
warn: Applications directory /Users/dev1/Library/Developer/CoreSimulator/Devices/6958C66F-8343-4743-895B-458C6AE7AA66/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] Restarting app
info: [debug] The following desired capabilities were provided, but not recognized by appium. They will be passed on to any other services running on this server. : appium-version
info: [debug] Using local app from desired caps: /path/to/app.app
info: [debug] Creating new appium session 28999a79-5d0c-42b5-b8d8-d8a5a6a05abc
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.1
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.1","iOSSDKVersion":"8.1","deviceName":"iPhone 6","platformVersion":"8.1"}
info: [debug] fixDevice is on
info: [debug] Final device string is: 'iPhone 6 (8.1 Simulator)'
info: [debug] iOS sim UDID is 6958C66F-8343-4743-895B-458C6AE7AA66
info: [debug] Detecting automation tracetemplate
info: [debug] Not auto-detecting udid, running on sim
info: [debug] Parsed app Info.plist (as binary)
info: [debug] Parsed app Localizable.strings
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/dev1/Library/Application Support/appium/bootstrap
info: [debug] Dynamic env: {"nodePath":"/usr/local/Cellar/node/0.10.30/bin/node","commandProxyClientPath":"/usr/local/lib/node_modules/appium/node_modules/appium-uiauto/bin/command-proxy-client.js","instrumentsSock":"/tmp/instruments_sock","interKeyDelay":null,"justLoopInfinitely":false,"autoAcceptAlerts":true}
...o: [debug] Dynamic bootstrap code: // This file is automatically generated. Do not manually modify!
info: [debug] Dynamic bootstrap path: /Users/dev1/Library/Application Support/appium/bootstrap/bootstrap-4c8030a82e57e676.js
info: [debug] Reusing dynamic bootstrap: /Users/dev1/Library/Application Support/appium/bootstrap/bootstrap-4c8030a82e57e676.js
info: [debug] Getting device string from opts: {"forceIphone":false,"forceIpad":false,"xcodeVersion":"6.1","iOSSDKVersion":"8.1","deviceName":"iPhone 6","platformVersion":"8.1"}
info: [debug] fixDevice is on
info: [debug] Final device string is: 'iPhone 6 (8.1 Simulator)'
info: [debug] We're on iOS7.1+ so forcing defaultDevice on
info: [debug] Getting device string from opts: {"forceIphone":false,"forceIpad":false,"xcodeVersion":"6.1","iOSSDKVersion":"8.1","deviceName":"iPhone 6","platformVersion":"8.1"}
info: [debug] fixDevice is on
info: [debug] Final device string is: 'iPhone 6 (8.1 Simulator)'
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: [debug] Executing: xcrun simctl erase 6958C66F-8343-4743-895B-458C6AE7AA66
info: [debug] Cleaning app data files
warn: Applications directory /Users/dev1/Library/Developer/CoreSimulator/Devices/6958C66F-8343-4743-895B-458C6AE7AA66/data/Containers/Data/Application doesn't exist. Have you run this simulator before?
warn: Applications directory /Users/dev1/Library/Developer/CoreSimulator/Devices/6958C66F-8343-4743-895B-458C6AE7AA66/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 iPhone 6 (8.1 Simulator)
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 "iPhone 6 (8.1 Simulator)" /path/to/app.app -e UIASCRIPT "/Users/dev1/Library/Application Support/appium/bootstrap/bootstrap-4c8030a82e57e676.js" -e UIARESULTSPATH /tmp/appium-instruments
info: [debug] And extra without-delay env: {"DYLD_INSERT_LIBRARIES":"/usr/local/lib/node_modules/appium/node_modules/appium-instruments/thirdparty/iwd6/InstrumentsShim.dylib","LIB_PATH":"/usr/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] 2014-12-23 16:22:23 +0000 Debug: evaluation finished
info: [debug] [INST] 2014-12-23 16:22:23 +0000 Debug: responding with:
       2014-12-23 16:22:23 +0000 Debug: Running system command #1: /usr/local/Cellar/node/0.10.30/bin/node /usr/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] 2014-12-23 16:22:23 +0000 Debug: Got new command 1 from instruments: au.setScreenOrientation('PORTRAIT')
info: [debug] [INST] 2014-12-23 16:22:23 +0000 Debug: evaluating au.setScreenOrientation('PORTRAIT')
info: [debug] [INST] 2014-12-23 16:22:23 +0000 Debug: target.setDeviceOrientation("1")
info: [debug] [INST] 2014-12-23 16:22:23 +0000 Debug: evaluation finished
info: [debug] [INST] 2014-12-23 16:22:23 +0000 Debug: responding with:
info: [debug] [INST] 2014-12-23 16:22:23 +0000 Debug: Running system command #2: /usr/local/Cellar/node/0.10.30/bin/node /usr/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] 2014-12-23 16:22:23 +0000 Debug: Got new command 2 from instruments: au.mainApp().getTreeForXML()
info: [debug] [INST] 2014-12-23 16:22:23 +0000 Debug: evaluating au.mainApp().getTreeForXML()
info: [debug] [INST] 2014-12-23 16:22:24 +0000 Debug: evaluation finished
info: [debug] [INST] 2014-12-23 16:22:24 +0000 Debug: responding with:alid":true,"visible":true,"hint":null,"path":"/0/0","x":0,"y":0,"width":375,"height":667},">":[]}},{"UIAWindow":{"@":{"name":null,"label":null,"value":null,"dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/1","x":0,"y":0.6875,"width":375,"height":665.625},">":[{"UIAScrollView":{"@":{"name":null,"label":null,"value":null,"dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/1/0","x":0,"y":0.6875,"width":375,"height":665.625},">":[{"UIAWebView":{"@":{"name":null,"label":null,"value":null,"dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/1/0/0","x":0,"y":0.6875,"width":375,"height":665.625},">":[]}},{"UIAImage":{"@":{"name":null,"label":null,"value":null,"dom":null,"enabled":true,"valid":true,"visible":false,"hint":null,"path":"/0/1/0/1","x":372.0703125,"y":0.6875,"width":2.9296875,"height":665.625},">":[]}},{"UIAImage":{"@":{"name":null,"label":null,"value":null,"dom":null,"enabled":true,"valid":true,"visible":false,"hint":null,"path":"/0/1/0/2","x":0,"y":663.3828125,"width":375,"height":2.9296875},">":[]}}]}}]}},{"UIAWindow":{"@":{"name":null,"label":null,"value":null,"dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/2","x":0,"y":0.6875,"width":375,"height":665.625},">":[{"UIAStatusBar":{"@":{"name":null,"label":null,"value":null,"dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/2/0","x":0,"y":0.6875,"width":375,"height":23.4375},">":[{"UIAElement":{"@":{"name":"Swipe down with three fingers to reveal the notification center., Swipe up with three fingers to reveal the control center, Double-tap to scroll to top","label":null,"value":null,"dom":null,"enabled":true,"valid":true,"visible":true,"hint":"Swipe down with three fingers to reveal the notification center., Swipe up with three fingers to reveal the control center, Double-tap to scroll to top","path":"/0/2/0/0","x":7.03125,"y":0.6875,"width":44.53125,"height":23.4375},">":[]}},{"UIAElement":{"@":{"name":"3 of 3 Wi-Fi bars","label":"3 of 3 Wi-Fi bars","value":null,"dom":null,"enabled":true,"valid":true,"visible":true,"hint":"Swipe down with three fingers to reveal the notification center., Swipe up with three fingers to reveal the control center, Double-tap to scroll to top","path":"/0/2/0/1","x":57.421875,"y":0.6875,"width":15.234375,"height":23.4375},">":[]}},{"UIAElement":{"@":{"name":"10:22 AM","label":"10:22 AM","value":null,"dom":null,"enabled":true,"valid":true,"visible":true,"hint":"Swipe down with three fingers to reveal the notification center., Swipe up with three fingers to reveal the control center, Double-tap to scroll to top","path":"/0/2/0/2","x":157.03125,"y":0.6875,"width":60.9375,"height":23.4375},">":[]}},{"UIAElement":{"@":{"name":"100% battery power","label":"100% battery power","value":null,"dom":null,"enabled":true,"valid":true,"visible":true,"hint":"Swipe down with three fingers to reveal the notification center., Swipe up with three fingers to reveal the control center, Double-tap to scroll to top","path":"/0/2/0/3","x":339.84375,"y":0.6875,"width":29.296875,"height":23.4375},">":[]}}]}}]}}]}}}
info: [debug] [INST] 2014-12-23 16:22:24 +0000 Debug: Running system command #3: /usr/local/Cellar/node/0.10.30/bin/node /usr/local/lib/node_modules/appium/node_modules/appium-uiauto/bin/command-proxy-client.js /tmp/instruments_sock 2,{"status":0,"value":{"UIAApplication":{"@":{"name":"APP","label":"APP","value":null,"dom":null,"enabled":true,"valid":true,"visible":true,"hint":n...
info: [debug] Socket data received (3460 bytes)
info: [debug] Socket data being routed.
info: [debug] Got result from instruments: {"status":0,"value":{"UIAApplication":{"@":{"name":"APP","label":"APP","value":null,"dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0","x":0,"y":24.125,"width":375,"height":642.1875},">":[{"UIAWindow":{"@":{"name":null,"label":null,"value":null,"dom":null,"enabled":true,"v
info: [debug] Device launched! Ready for commands
info: [debug] Setting command timeout to the default of 60 secs
info: [debug] Setting command timeout to 60 secs
info: [debug] Responding to client with success: {"status":0,"value":60,"sessionId":"ffa28c3f-bca5-41cd-aaa0-052476284fc3"}
info: <-- POST /wd/hub/session/ffa28c3f-bca5-41cd-aaa0-052476284fc3/appium/app/reset 200 12973.548 ms - 74 {"status":0,"value":60,"sessionId":"ffa28c3f-bca5-41cd-aaa0-052476284fc3"}
info: --> GET /wd/hub/session/ffa28c3f-bca5-41cd-aaa0-052476284fc3/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":"c853e81c-3e02-42a8-a5c2-add765c9e5fe"},"__selector":"_rpc_reportIdentifier:"}
info: [debug] [REMOTE] Receiving data from remote debugger
info: [debug] [REMOTE] Receiving data from remote debugger
info: [debug] [REMOTE] {"__argument":{"WIRSimulatorBuildKey":"12B411","WIRSimulatorProductVersionKey":"8.1","WIRSimulatorNameKey":"iPhone Simulator"},"__selector":"_rpc_reportSetup:"}
info: [debug] [REMOTE] Sim name: iPhone Simulator
info: [debug] [REMOTE] Sim build: 12B411
info: [debug] [REMOTE] {"__argument":{"WIRApplicationDictionaryKey":{"PID:35675":{"WIRApplicationIdentifierKey":"PID:35675","WIRIsApplicationProxyKey":false,"WIRApplicationNameKey":"APP","WIRApplicationBundleIdentifierKey":"com.app.app","WIRIsApplicationActiveKey":1}}},"__selector":"_rpc_reportConnectedApplicationList:"}
info: [debug] Using remote debugger app key: PID:35675
info: [debug] [REMOTE] Selecting app PID:35675
info: [debug] [REMOTE] function () { [native code] }
info: [debug] [REMOTE] Sending _rpc_forwardGetListing: message to remote debugger
info: [debug] [REMOTE] {"__argument":{"WIRConnectionIdentifierKey":"c853e81c-3e02-42a8-a5c2-add765c9e5fe","WIRApplicationIdentifierKey":"PID:35675"},"__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:35675","WIRListingKey":{"1":{"WIRTypeKey":"WIRTypeWeb","WIRTitleKey":"","WIRURLKey":"file:///Users/dev1/Library/Developer/CoreSimulator/Devices/6958C66F-8343-4743-895B-458C6AE7AA66/data/Containers/Bundle/Application/114FEEE7-B177-44DE-A7E5-DDC6220FE96F/app.app/www/index.html","WIRPageIdentifierKey":1}}},"__selector":"_rpc_applicationSentListing:"}
info: [debug] Responding to client with success: {"status":0,"value":["NATIVE_APP","WEBVIEW_1"],"sessionId":"ffa28c3f-bca5-41cd-aaa0-052476284fc3"}
info: <-- GET /wd/hub/session/ffa28c3f-bca5-41cd-aaa0-052476284fc3/contexts 200 454.738 ms - 98 {"status":0,"value":["NATIVE_APP","WEBVIEW_1"],"sessionId":"ffa28c3f-bca5-41cd-aaa0-052476284fc3"}
info: --> POST /wd/hub/session/ffa28c3f-bca5-41cd-aaa0-052476284fc3/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:35675","WIRConnectionIdentifierKey":"c853e81c-3e02-42a8-a5c2-add765c9e5fe","WIRSenderKey":"5e98874c-dba3-421e-9172-a64f014e6eee","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] 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] 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":"ffa28c3f-bca5-41cd-aaa0-052476284fc3"}
info: <-- POST /wd/hub/session/ffa28c3f-bca5-41cd-aaa0-052476284fc3/context 200 11.864 ms - 74 {"status":0,"value":"","sessionId":"ffa28c3f-bca5-41cd-aaa0-052476284fc3"}
info: --> POST /wd/hub/session/ffa28c3f-bca5-41cd-aaa0-052476284fc3/elements {"using":"class name","value":"new-user"}
info: [debug] Waiting up to 5000ms 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] Receiving data from remote debugger
info: [debug] [REMOTE] got applicationSentData response
info: [debug] Responding to client with success: {"status":0,"value":[{"ELEMENT":"5000"}],"sessionId":"ffa28c3f-bca5-41cd-aaa0-052476284fc3"}
info: <-- POST /wd/hub/session/ffa28c3f-bca5-41cd-aaa0-052476284fc3/elements 200 13.868 ms - 92 {"status":0,"value":[{"ELEMENT":"5000"}],"sessionId":"ffa28c3f-bca5-41cd-aaa0-052476284fc3"}
info: --> GET /wd/hub/session/ffa28c3f-bca5-41cd-aaa0-052476284fc3/element/5000/displayed {}
info: [debug] [REMOTE] Executing 'is_displayed' 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":true,"sessionId":"ffa28c3f-bca5-41cd-aaa0-052476284fc3"}
info: <-- GET /wd/hub/session/ffa28c3f-bca5-41cd-aaa0-052476284fc3/element/5000/displayed 200 14.767 ms - 76 {"status":0,"value":true,"sessionId":"ffa28c3f-bca5-41cd-aaa0-052476284fc3"}
^^^^^^^^^^^^^^^^^^^^ [this line added] ^^^^^^^^^^^^^^^^^^^
info: --> POST /wd/hub/session/ffa28c3f-bca5-41cd-aaa0-052476284fc3/element/5000/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] Receiving data from remote debugger
info: [debug] [REMOTE] got applicationSentData response
info: [debug] Responding to client with success: {"status":0,"value":null,"sessionId":"ffa28c3f-bca5-41cd-aaa0-052476284fc3"}
info: <-- POST /wd/hub/session/ffa28c3f-bca5-41cd-aaa0-052476284fc3/element/5000/click 200 25.040 ms - 76 {"status":0,"value":null,"sessionId":"ffa28c3f-bca5-41cd-aaa0-052476284fc3"}
^^^^^^^^^^^^^^^^^^^^ [this line added] ^^^^^^^^^^^^^^^^^^^
info: --> POST /wd/hub/session/ffa28c3f-bca5-41cd-aaa0-052476284fc3/elements {"using":"class name","value":"page-title"}
info: [debug] Waiting up to 5000ms 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] Responding to client with success: {"status":0,"value":[{"ELEMENT":"5001"}],"sessionId":"ffa28c3f-bca5-41cd-aaa0-052476284fc3"}
info: <-- POST /wd/hub/session/ffa28c3f-bca5-41cd-aaa0-052476284fc3/elements 200 16.310 ms - 92 {"status":0,"value":[{"ELEMENT":"5001"}],"sessionId":"ffa28c3f-bca5-41cd-aaa0-052476284fc3"}
info: --> GET /wd/hub/session/ffa28c3f-bca5-41cd-aaa0-052476284fc3/element/5001/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] {"__argument":{"WIRApplicationIdentifierKey":"PID:35675","WIRListingKey":{"1":{"WIRTypeKey":"WIRTypeWeb","WIRTitleKey":"","WIRURLKey":"file:///Users/dev1/Library/Developer/CoreSimulator/Devices/6958C66F-8343-4743-895B-458C6AE7AA66/data/Containers/Bundle/Application/114FEEE7-B177-44DE-A7E5-DDC6220FE96F/app.app/www/index.html","WIRConnectionIdentifierKey":"c853e81c-3e02-42a8-a5c2-add765c9e5fe","WIRPageIdentifierKey":1}}},"__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":"","sessionId":"ffa28c3f-bca5-41cd-aaa0-052476284fc3"}
info: <-- GET /wd/hub/session/ffa28c3f-bca5-41cd-aaa0-052476284fc3/element/5001/text 200 14.008 ms - 74 {"status":0,"value":"","sessionId":"ffa28c3f-bca5-41cd-aaa0-052476284fc3"}
info: --> POST /wd/hub/session/ffa28c3f-bca5-41cd-aaa0-052476284fc3/elements {"using":"class name","value":"page-title"}
info: [debug] Waiting up to 5000ms 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] Receiving data from remote debugger
info: [debug] [REMOTE] got applicationSentData response
info: [debug] Responding to client with success: {"status":0,"value":[{"ELEMENT":"5002"}],"sessionId":"ffa28c3f-bca5-41cd-aaa0-052476284fc3"}
info: <-- POST /wd/hub/session/ffa28c3f-bca5-41cd-aaa0-052476284fc3/elements 200 9.864 ms - 92 {"status":0,"value":[{"ELEMENT":"5002"}],"sessionId":"ffa28c3f-bca5-41cd-aaa0-052476284fc3"}
info: --> GET /wd/hub/session/ffa28c3f-bca5-41cd-aaa0-052476284fc3/element/5002/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] Receiving data from remote debugger
info: [debug] [REMOTE] got applicationSentData response
info: [debug] Responding to client with success: {"status":0,"value":"","sessionId":"ffa28c3f-bca5-41cd-aaa0-052476284fc3"}
info: <-- GET /wd/hub/session/ffa28c3f-bca5-41cd-aaa0-052476284fc3/element/5002/text 200 20.994 ms - 74 {"status":0,"value":"","sessionId":"ffa28c3f-bca5-41cd-aaa0-052476284fc3"}
info: --> POST /wd/hub/session/ffa28c3f-bca5-41cd-aaa0-052476284fc3/elements {"using":"class name","value":"page-title"}
info: [debug] Waiting up to 5000ms 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] Receiving data from remote debugger
info: [debug] [REMOTE] got applicationSentData response
info: [debug] Responding to client with success: {"status":0,"value":[{"ELEMENT":"5003"}],"sessionId":"ffa28c3f-bca5-41cd-aaa0-052476284fc3"}
info: <-- POST /wd/hub/session/ffa28c3f-bca5-41cd-aaa0-052476284fc3/elements 200 8.732 ms - 92 {"status":0,"value":[{"ELEMENT":"5003"}],"sessionId":"ffa28c3f-bca5-41cd-aaa0-052476284fc3"}
info: --> GET /wd/hub/session/ffa28c3f-bca5-41cd-aaa0-052476284fc3/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":"","sessionId":"ffa28c3f-bca5-41cd-aaa0-052476284fc3"}
info: <-- GET /wd/hub/session/ffa28c3f-bca5-41cd-aaa0-052476284fc3/element/5003/text 200 11.152 ms - 74 {"status":0,"value":"","sessionId":"ffa28c3f-bca5-41cd-aaa0-052476284fc3"}
.
.
.
[repeats this cycle until timeout is reached]