On Appium 1.4.8, running Xcode6, I had a test working where I would open an iOS app on the simulator, find a WebElement named “Contact Menu” and tap it. Now with the update to Appium 1.4.13 and Xcode7, elements can’t be tapped.
This is the Android Studio log. It finds the WebElement but just can’t tap it:
[[IOSDriver: iOS on UNIX (a01c41b5-9169-45d2-ab95-112f95bdc4ff)] -> name: Contact Menu]
org.openqa.selenium.WebDriverException: An unknown server-side error occurred while processing the command. (WARNING: The server did not provide any stacktrace information)
Command duration or timeout: 1.29 seconds
Build info: version: '2.45.0', revision: '5017cb8e7ca8e37638dc3091b2440b90a1d8686f', time: '2015-02-27 09:10:26'
System info: host: 'Amandas-MacBook-Pro.local', ip: '192.168.88.144', os.name: 'Mac OS X', os.arch: 'x86_64', os.version: '10.10.5', java.version: '1.8.0_51'
Driver info: io.appium.java_client.ios.IOSDriver
Capabilities [{app=/Users/amanda/Desktop/RHR.app, networkConnectionEnabled=false, warnings={}, bundleId=com.homespotter.rhr, databaseEnabled=false, deviceName=iPhone 6, platform=UNIX, desired={app=/Users/amanda/Desktop/RHR.app, locationServicesAuthorized=true, platformVersion=8.4, bundleId=com.homespotter.rhr, platformName=iOS, deviceName=iPhone 6, platform=iOS}, locationServicesAuthorized=true, platformVersion=8.4, webStorageEnabled=false, locationContextEnabled=false, browserName=iOS, takesScreenshot=true, javascriptEnabled=true, platformName=iOS}]
Session ID: a01c41b5-9169-45d2-ab95-112f95bdc4ff
at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:62)
at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
at java.lang.reflect.Constructor.newInstance(Constructor.java:422)
at org.openqa.selenium.remote.ErrorHandler.createThrowable(ErrorHandler.java:204)
at org.openqa.selenium.remote.ErrorHandler.throwIfResponseFailed(ErrorHandler.java:156)
at org.openqa.selenium.remote.RemoteWebDriver.execute(RemoteWebDriver.java:599)
at io.appium.java_client.AppiumDriver.execute(AppiumDriver.java:180)
at org.openqa.selenium.remote.RemoteWebElement.execute(RemoteWebElement.java:268)
at org.openqa.selenium.remote.RemoteWebElement.click(RemoteWebElement.java:79)
at com.mobilerealtyapps.testing.PageObjects.MapView.openContactSlider(MapView.java:71)
at com.mobilerealtyapps.testing.tests.ContactSliderTests.BrandToAgent.BrandToAgent(BrandToAgent.java:25)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:47)
at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:44)
at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:26)
at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:27)
at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:271)
at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:70)
at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:50)
at org.junit.runners.ParentRunner$3.run(ParentRunner.java:238)
at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:63)
at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:236)
at org.junit.runners.ParentRunner.access$000(ParentRunner.java:53)
at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:229)
at org.junit.runners.ParentRunner.run(ParentRunner.java:309)
at org.junit.runner.JUnitCore.run(JUnitCore.java:160)
at com.intellij.junit4.JUnit4IdeaTestRunner.startRunnerWithArgs(JUnit4IdeaTestRunner.java:78)
at com.intellij.rt.execution.junit.JUnitStarter.prepareStreamsAndStart(JUnitStarter.java:212)
at com.intellij.rt.execution.junit.JUnitStarter.main(JUnitStarter.java:68)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at com.intellij.rt.execution.application.AppMain.main(AppMain.java:140)
This is the Appium log. It finds it but fails to click the button. I also tried MobileElement tap(1,500) without any luck. Ideas?
info: Old session shut down OK, proceeding to new session
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. : platform
info: [debug] Using local app from desired caps: /Users/amanda/Desktop/RHR.app
info: [debug] Creating new appium session a01c41b5-9169-45d2-ab95-112f95bdc4ff
info: [debug] Removing any remaining instruments sockets
info: [debug] Cleaned up instruments socket /tmp/instruments_sock
info: [debug] Setting Xcode version
info: [debug] Xcode version set to 7.0
info: [debug] Setting iOS SDK Version
info: [debug] iOS SDK Version set to 9.0
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":"7.0","iOSSDKVersion":"9.0","deviceName":"iPhone 6","platformVersion":"8.4"}
info: [debug] fixDevice is on
info: [debug] Final device string is: 'iPhone 6 (8.4)'
info: [debug] iOS sim UDID is 050CD921-6DE1-4708-A955-8FED333FD334
info: [debug] Auto-detecting iOS udid...
info: [debug] Not auto-detecting udid, running on sim
info: [debug] Could not parse plist file (as binary) at /Users/amanda/Desktop/RHR.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 /Users/amanda/Desktop/RHR.app/en.lproj/Localizable.strings
warn: Could not parse app Localizable.strings assuming it doesn't exist
info: [debug] Creating instruments
info: [debug] Preparing uiauto bootstrap
info: [debug] Dynamic bootstrap dir: /Users/amanda/Library/Application Support/appium/bootstrap
info: [debug] Dynamic env: {"nodePath":"/usr/local/Cellar/node/4.1.0/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":false,"autoDismissAlerts":false,"sendKeyStrategy":"oneByOne"}
info: [debug] Dynamic bootstrap code: // This file is automatically generated. D...ot manually modify!
info: [debug] Dynamic bootstrap path: /Users/amanda/Library/Application Support/appium/bootstrap/bootstrap-bf740a9394e481ef.js
info: [debug] Reusing dynamic bootstrap: /Users/amanda/Library/Application Support/appium/bootstrap/bootstrap-bf740a9394e481ef.js
info: [debug] Getting device string from opts: {"forceIphone":false,"forceIpad":false,"xcodeVersion":"7.0","iOSSDKVersion":"9.0","deviceName":"iPhone 6","platformVersion":"8.4"}
info: [debug] fixDevice is on
info: [debug] Final device string is: 'iPhone 6 (8.4)'
info: [debug] We're on iOS7.1+ so forcing defaultDevice on
info: [debug] Getting device string from opts: {"forceIphone":false,"forceIpad":false,"xcodeVersion":"7.0","iOSSDKVersion":"9.0","deviceName":"iPhone 6","platformVersion":"8.4"}
info: [debug] fixDevice is on
info: [debug] Final device string is: 'iPhone 6 (8.4)'
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] Killing any other simulator daemons
info: [debug] Killall Simulator
info: [debug] Cleaning sim data files
info: node-simctl: Executing: xcrun with args: simctl erase 050CD921-6DE1-4708-A955-8FED333FD334 and timeout: 2000
info: node-simctl: Executing: xcrun with args: simctl erase 050CD921-6DE1-4708-A955-8FED333FD334 and timeout: 2000
info: node-simctl: Executing: xcrun with args: simctl erase 050CD921-6DE1-4708-A955-8FED333FD334 and timeout: 2000
info: node-simctl: Executing: xcrun with args: simctl erase 050CD921-6DE1-4708-A955-8FED333FD334 and timeout: 2000
info: node-simctl: Executing: xcrun with args: simctl erase 050CD921-6DE1-4708-A955-8FED333FD334 and timeout: 2000
info: [debug] Not setting locale
info: [debug] Setting iOS and app preferences
info: [debug] Setting location services to 1
info: [debug] Updating settings for locationServices
info: [debug] Getting current settings for locationServices from /Users/amanda/Library/Developer/CoreSimulator/Devices/050CD921-6DE1-4708-A955-8FED333FD334/data/Library/Preferences/com.apple.locationd.plist
warn: Settings file /Users/amanda/Library/Developer/CoreSimulator/Devices/050CD921-6DE1-4708-A955-8FED333FD334/data/Library/Preferences/com.apple.locationd.plist did not exist
info: [debug] Writing settings for locationServices to /Users/amanda/Library/Developer/CoreSimulator/Devices/050CD921-6DE1-4708-A955-8FED333FD334/data/Library/Preferences/com.apple.locationd.plist:
info: [debug] {"LocationServicesEnabled":1,"LocationServicesEnabledIn7.0":1,"LocationServicesEnabledIn8.0":1}
info: [debug] Authorizing location services for app
info: [debug] Getting current settings for locationCache from /Users/amanda/Library/Developer/CoreSimulator/Devices/050CD921-6DE1-4708-A955-8FED333FD334/data/Library/Preferences/cache.plist
warn: Settings file /Users/amanda/Library/Developer/CoreSimulator/Devices/050CD921-6DE1-4708-A955-8FED333FD334/data/Library/Preferences/cache.plist did not exist
info: [debug] Getting current settings for locationCache from /Users/amanda/Library/Developer/CoreSimulator/Devices/050CD921-6DE1-4708-A955-8FED333FD334/data/Library/Caches/locationd/cache.plist
warn: Settings file /Users/amanda/Library/Developer/CoreSimulator/Devices/050CD921-6DE1-4708-A955-8FED333FD334/data/Library/Caches/locationd/cache.plist did not exist
info: [debug] Getting current settings for locationClients from /Users/amanda/Library/Developer/CoreSimulator/Devices/050CD921-6DE1-4708-A955-8FED333FD334/data/Library/Caches/locationd/clients.plist
warn: Settings file /Users/amanda/Library/Developer/CoreSimulator/Devices/050CD921-6DE1-4708-A955-8FED333FD334/data/Library/Caches/locationd/clients.plist did not exist
info: [debug] Writing settings for locationClients to /Users/amanda/Library/Developer/CoreSimulator/Devices/050CD921-6DE1-4708-A955-8FED333FD334/data/Library/Caches/locationd/clients.plist:
info: [debug] {"com.apple.locationd.bundle-/System/Library/PrivateFrameworks/AOSNotification.framework":{"BundlePath":"/System/Library/PrivateFrameworks/AOSNotification.framework","Whitelisted":false,"Executable":"","Registered":""},"com.homespotter.rhr":{"BundleId":"com.homespotter.rhr","Authorized":true,"Whitelisted":false},"Executable":"","Registered":""}
warn: Base directory /Users/amanda/Library/Developer/CoreSimulator/Devices/050CD921-6DE1-4708-A955-8FED333FD334/data/Library/Caches/locationd doesn't exist, creating it
info: [debug] Writing settings for locationCache to /Users/amanda/Library/Developer/CoreSimulator/Devices/050CD921-6DE1-4708-A955-8FED333FD334/data/Library/Preferences/cache.plist:
info: [debug] {"LastFenceActivityTimestamp":412122103.232983,"CleanShutdown":true}
info: [debug] Writing settings for locationCache to /Users/amanda/Library/Developer/CoreSimulator/Devices/050CD921-6DE1-4708-A955-8FED333FD334/data/Library/Caches/locationd/cache.plist:
info: [debug] {"LastFenceActivityTimestamp":412122103.232983,"CleanShutdown":true}
info: [debug] Starting iOS 8.* simulator log capture
info: [debug] Not pre-launching simulator
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.4)
info: On xcode 7.0, instruments-without-delay does not work, skippinginstruments-without-delay
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.4)" /Users/amanda/Desktop/RHR.app -e UIASCRIPT "/Users/amanda/Library/Application Support/appium/bootstrap/bootstrap-bf740a9394e481ef.js" -e UIARESULTSPATH /tmp/appium-instruments
info: [debug] And launch timeouts (in ms): {"global":90000}
info: [debug] [INST] Waiting for device to boot...
info: [debug] [INST STDERR] 2015-10-01 09:41:01.883 instruments[15772:740783] WebKit Threading Violation - initial use of WebKit from a secondary thread.
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-10-01 14:41:09 +0000 Debug: evaluation finished
info: [debug] [INST] 2015-10-01 14:41:09 +0000 Debug: responding with:
info: [debug] [INST] 2015-10-01 14:41:09 +0000 Debug: Running system command #1: /usr/local/Cellar/node/4.1.0/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] 2015-10-01 14:41:10 +0000 Debug: Got new command 1 from instruments: au.setScreenOrientation('PORTRAIT')
info: [debug] [INST] 2015-10-01 14:41:10 +0000 Debug: evaluating au.setScreenOrientation('PORTRAIT')
info: [debug] [INST] 2015-10-01 14:41:10 +0000 Debug: target.setDeviceOrientation("1")
info: [debug] [INST] 2015-10-01 14:41:11 +0000 Debug: evaluation finished
info: [debug] [INST] 2015-10-01 14:41:11 +0000 Debug: responding with:
info: [debug] [INST] 2015-10-01 14:41:11 +0000 Debug: Running system command #2: /usr/local/Cellar/node/4.1.0/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] 2015-10-01 14:41:12 +0000 Debug: Got new command 2 from instruments: au.mainApp().getTreeForXML()
info: [debug] [INST] 2015-10-01 14:41:12 +0000 Debug: evaluating au.mainApp().getTreeForXML()
info: [debug] [INST] 2015-10-01 14:41:12 +0000 Debug: evaluation finished
info: [debug] [INST] 2015-10-01 14:41:12 +0000 Debug: responding with:
info: [debug] [INST] 2015-10-01 14:41:12 +0000 Debug: Running system command #3: /usr/local/Cellar/node/4.1.0/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":null,"label":null,"value":null,"dom":null,"enabled":false,"valid":false,"visible":false,"hint":nu...
info: [debug] Socket data received (208 bytes)
info: [debug] Socket data being routed.
info: [debug] Got result from instruments: {"status":0,"value":{"UIAApplication":{"@":{"name":null,"label":null,"value":null,"dom":null,"enabled":false,"valid":false,"visible":false,"hint":null,"path":"/0","x":0,"y":0,"width":0,"height":0},">":[]}}}
info: [debug] Waited for 1081ms so far
info: [debug] Pushing command to appium work queue: "au.mainApp().getTreeForXML()"
info: [debug] Sending command to instruments: au.mainApp().getTreeForXML()
info: [debug] [INST] 2015-10-01 14:41:13 +0000 Debug: Got new command 3 from instruments: au.mainApp().getTreeForXML()
info: [debug] [INST] 2015-10-01 14:41:13 +0000 Debug: evaluating au.mainApp().getTreeForXML()
info: [debug] [INST] 2015-10-01 14:41:23 +0000 Debug: evaluation finished
...
info: [debug] Socket data received (8192 bytes)
info: [debug] Socket data received (8192 bytes)
info: [debug] Socket data received (8192 bytes)
info: [debug] Socket data received (8192 bytes)
info: [debug] Socket data received (8192 bytes)
info: [debug] Socket data received (8192 bytes)
info: [debug] Socket data received (850 bytes)
info: [debug] Socket data being routed.
info: [debug] Got result from instruments: {"status":0,"value":{"UIAApplication":{"@":{"name":"Home Search","label":"Home Search","value":null,"dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0","x":0,"y":20,"width":375,"height":647},">":[{"UIAWindow":{"@":{"name":null,"label":null,"value":null,"dom":null,"enabled":
info: [debug] Got chunk data, current resultBuffer length: 50000
info: [debug] Not the last chunk, trying to get more
info: [debug] Sending command to instruments: #more
info: [debug] [INST] 2015-10-01 14:41:24 +0000 Debug: Got new command 4 from instruments: #more
info: [debug] [INST] 2015-10-01 14:41:24 +0000 Debug: Running system command #5: /usr/local/Cellar/node/4.1.0/bin/node /usr/local/lib/node_modules/appium/node_modules/appium-uiauto/bin/command-proxy-client.js /tmp/instruments_sock 4,null,"dom":null,"enabled":true,"valid":true,"visible":false,"hint":null,"path":"/0/0/33","x":5,"y":95,"width":84,"height":18},">":[{"UIAImage":{"@":...
info: [debug] Socket data received (4945 bytes)
info: [debug] Socket data being routed.
info: [debug] Got result from instruments: null,"dom":null,"enabled":true,"valid":true,"visible":false,"hint":null,"path":"/0/0/33","x":5,"y":95,"width":84,"height":18},">":[{"UIAImage":{"@":{"name":"btn_reset_favorites_norm.png","label":null,"value":null,"dom":null,"enabled":true,"valid":true,"visible":false,"hint":null,"path":"/0/0/33/0","
info: [debug] Got chunk data, current resultBuffer length: 54943
info: [debug] This is the last data final length: 54943
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 a01c41b5-9169-45d2-ab95-112f95bdc4ff
info: <-- POST /wd/hub/session 303 37082.745 ms - 74
info: --> GET /wd/hub/session/a01c41b5-9169-45d2-ab95-112f95bdc4ff {}
info: [debug] Responding to client with success: {"status":0,"value":{"webStorageEnabled":false,"locationContextEnabled":false,"browserName":"iOS","platform":"iOS","javascriptEnabled":true,"databaseEnabled":false,"takesScreenshot":true,"networkConnectionEnabled":false,"warnings":{},"desired":{"app":"/Users/amanda/Desktop/RHR.app","locationServicesAuthorized":true,"platformVersion":"8.4","bundleId":"com.homespotter.rhr","platformName":"iOS","deviceName":"iPhone 6","platform":"iOS"},"app":"/Users/amanda/Desktop/RHR.app","locationServicesAuthorized":true,"platformVersion":"8.4","bundleId":"com.homespotter.rhr","platformName":"iOS","deviceName":"iPhone 6"},"sessionId":"a01c41b5-9169-45d2-ab95-112f95bdc4ff"}
info: <-- GET /wd/hub/session/a01c41b5-9169-45d2-ab95-112f95bdc4ff 200 1.579 ms - 663 {"status":0,"value":{"webStorageEnabled":false,"locationContextEnabled":false,"browserName":"iOS","platform":"iOS","javascriptEnabled":true,"databaseEnabled":false,"takesScreenshot":true,"networkConnectionEnabled":false,"warnings":{},"desired":{"app":"/Users/amanda/Desktop/RHR.app","locationServicesAuthorized":true,"platformVersion":"8.4","bundleId":"com.homespotter.rhr","platformName":"iOS","deviceName":"iPhone 6","platform":"iOS"},"app":"/Users/amanda/Desktop/RHR.app","locationServicesAuthorized":true,"platformVersion":"8.4","bundleId":"com.homespotter.rhr","platformName":"iOS","deviceName":"iPhone 6"},"sessionId":"a01c41b5-9169-45d2-ab95-112f95bdc4ff"}
info: --> POST /wd/hub/session/a01c41b5-9169-45d2-ab95-112f95bdc4ff/timeouts/implicit_wait {"ms":15000}
info: [debug] Set iOS implicit wait to 15000ms
info: [debug] Responding to client with success: {"status":0,"value":null,"sessionId":"a01c41b5-9169-45d2-ab95-112f95bdc4ff"}
info: <-- POST /wd/hub/session/a01c41b5-9169-45d2-ab95-112f95bdc4ff/timeouts/implicit_wait 200 1.370 ms - 76 {"status":0,"value":null,"sessionId":"a01c41b5-9169-45d2-ab95-112f95bdc4ff"}
info: --> POST /wd/hub/session/a01c41b5-9169-45d2-ab95-112f95bdc4ff/element {"using":"name","value":"Contact Menu"}
info: [debug] Waiting up to 15000ms for condition
info: [debug] Pushing command to appium work queue: "au.getElementByName('Contact Menu')"
info: [debug] Sending command to instruments: au.getElementByName('Contact Menu')
info: [debug] [INST] 2015-10-01 14:41:27 +0000 Debug: Got new command 5 from instruments: au.getElementByName('Contact Menu')
info: [debug] [INST] 2015-10-01 14:41:27 +0000 Debug: evaluating au.getElementByName('Contact Menu')
info: [debug] [INST] 2015-10-01 14:41:27 +0000 Debug: evaluation finished
info: [debug] [INST] 2015-10-01 14:41:27 +0000 Debug: responding with:
info: [debug] [INST] 2015-10-01 14:41:27 +0000 Debug: Running system command #6: /usr/local/Cellar/node/4.1.0/bin/node /usr/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] Waited for 410ms so far
info: [debug] Pushing command to appium work queue: "au.getElementByName('Contact Menu')"
info: [debug] Sending command to instruments: au.getElementByName('Contact Menu')
info: [debug] [INST] 2015-10-01 14:41:28 +0000 Debug: Got new command 6 from instruments: au.getElementByName('Contact Menu')
info: [debug] [INST] 2015-10-01 14:41:28 +0000 Debug: evaluating au.getElementByName('Contact Menu')
info: [debug] [INST] 2015-10-01 14:41:28 +0000 Debug: evaluation finished
info: [debug] [INST] 2015-10-01 14:41:28 +0000 Debug: Lookup returned [object UIAButton] with the name "Contact Menu" (id: 0).
info: [debug] [INST] 2015-10-01 14:41:28 +0000 Debug: responding with:
info: [debug] [INST] 2015-10-01 14:41:28 +0000 Debug: Running system command #7: /usr/local/Cellar/node/4.1.0/bin/node /usr/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":"a01c41b5-9169-45d2-ab95-112f95bdc4ff"}
info: <-- POST /wd/hub/session/a01c41b5-9169-45d2-ab95-112f95bdc4ff/element 200 1489.243 ms - 87 {"status":0,"value":{"ELEMENT":"0"},"sessionId":"a01c41b5-9169-45d2-ab95-112f95bdc4ff"}
info: --> POST /wd/hub/session/a01c41b5-9169-45d2-ab95-112f95bdc4ff/element/0/click {"id":"0"}
info: [debug] Pushing command to appium work queue: "au.tapById('0')"
info: [debug] Sending command to instruments: au.tapById('0')
info: [debug] [INST] 2015-10-01 14:41:29 +0000 Debug: Got new command 7 from instruments: au.tapById('0')
info: [debug] [INST] 2015-10-01 14:41:29 +0000 Debug: evaluating au.tapById('0')
info: [debug] [INST] 2015-10-01 14:41:29 +0000 Debug: UIAButton.tap()
info: [debug] [INST] 2015-10-01 14:41:30 +0000 Debug: UIAButton could not be tapped
info: [debug] [INST] 2015-10-01 14:41:30 +0000 Error: VerboseError: elementId 0 could not be tapped
info: [debug] Socket data received (57 bytes)
info: [debug] Socket data being routed.
info: [debug] Got result from instruments: {"status":13,"value":"elementId 0 could not be tapped"}
info: [debug] Responding to client with error: {"status":13,"value":{"message":"An unknown server-side error occurred while processing the command.","origValue":"elementId 0 could not be tapped"},"sessionId":"a01c41b5-9169-45d2-ab95-112f95bdc4ff"}
info: <-- POST /wd/hub/session/a01c41b5-9169-45d2-ab95-112f95bdc4ff/element/0/click 500 1283.946 ms - 200
info: --> POST /wd/hub/session/a01c41b5-9169-45d2-ab95-112f95bdc4ff/appium/app/close {}
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-10-01 14:41:30 +0000 Error: Error during eval: tapById@file:///usr/local/lib/node_modules/appium/957389E4-7DA7-42C7-A0B9-6020E9E0F69C/bootstrap-bf740a9394e481ef.js:1114:25
eval code
eval@[native code]
startProcessing@file:///usr/local/lib/node_modules/appium/957389E4-7DA7-42C7-A0B9-6020E9E0F69C/bootstrap-bf740a9394e481ef.js:2710:30
bootstrap@file:///usr/local/lib/node_modules/appium/957389E4-7DA7-42C7-A0B9-6020E9E0F69C/bootstrap-bf740a9394e481ef.js:2778:31
global code@file:///usr/local/lib/node_modules/appium/957389E4-7DA7-42C7-A0B9-6020E9E0F69C/bootstrap-bf740a9394e481ef.js:2787:10
info: [debug] [INST] 2015-10-01 14:41:30 +0000 Debug: responding with:
info: [debug] [INST] 2015-10-01 14:41:30 +0000 Debug: Running system command #8: /usr/local/Cellar/node/4.1.0/bin/node /usr/local/lib/node_modules/appium/node_modules/appium-uiauto/bin/command-proxy-client.js /tmp/instruments_sock 2,{"status":13,"value":"elementId 0 could not be tapped"}...
info: [debug] [INST] 2015-10-01 14:41:30 +0000 Stopped: Script was stopped by the user
info: [debug] [INST STDERR] 2015-10-01 09:41:30.575 instruments[15772:740783] Attempting to change event horizon while disengage
info: [debug] [INST] Instruments Trace Complete (Duration : 27.366541s; 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] Killing any other simulator daemons
info: [debug] Killall Simulator
info: [debug] Cleaning sim data files