appium_ios_simulator_crashes

gistfile1.txt

info: --> POST /wd/hub/session {"desiredCapabilities":{"app":"/Users/saikrisv/git/VodQa_MobileAutomationWorkShop/VodQA_WorkShop/build/TestApp.app.zip","platformName":"iOS","deviceName":"iPhone 5s","platformVersion":"9.0"}}
info: Client User-Agent string: Apache-HttpClient/4.3.3 (java 1.5)
info: [debug] Using local .zip from desired caps: /Users/saikrisv/git/VodQa_MobileAutomationWorkShop/VodQA_WorkShop/build/TestApp.app.zip
info: [debug] Copying local zip to tmp dir
info: [debug] /Users/saikrisv/git/VodQa_MobileAutomationWorkShop/VodQA_WorkShop/build/TestApp.app.zip copied to /var/folders/wd/9s7s80fd66j88vf5119rwt300000gp/T/115823-10614-wmfiz3/appium-app.zip
info: [debug] Unzipping /var/folders/wd/9s7s80fd66j88vf5119rwt300000gp/T/115823-10614-wmfiz3/appium-app.zip
info: [debug] Testing zip archive: /var/folders/wd/9s7s80fd66j88vf5119rwt300000gp/T/115823-10614-wmfiz3/appium-app.zip
info: [debug] Zip archive tested clean
info: [debug] Unzip successful
info: [debug] Using locally extracted app: /var/folders/wd/9s7s80fd66j88vf5119rwt300000gp/T/115823-10614-wmfiz3/TestApp.app
info: [debug] Creating new appium session 8dba0ef3-6a42-4b88-8c96-e29f77218d53
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 5s","platformVersion":"9.0"}
info: [debug] fixDevice is on
info: [debug] Final device string is: 'iPhone 5s (9.0)'
info: [debug] iOS sim UDID is 15FBCDC5-96A7-42F9-89EC-A8F9887064D3
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 /var/folders/wd/9s7s80fd66j88vf5119rwt300000gp/T/115823-10614-wmfiz3/TestApp.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/wd/9s7s80fd66j88vf5119rwt300000gp/T/115823-10614-wmfiz3/TestApp.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: [debug] Preparing uiauto bootstrap
info: [debug] Dynamic bootstrap dir: /Users/saikrisv/Library/Application Support/appium/bootstrap
info: [debug] Dynamic env: {"nodePath":"/usr/local/Cellar/node/0.12.7/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"}
...o: [debug] Dynamic bootstrap code: // This file is automatically generated. Do not manually modify!
info: [debug] Dynamic bootstrap path: /Users/saikrisv/Library/Application Support/appium/bootstrap/bootstrap-48099ec8c70cb579.js
info: [debug] Reusing dynamic bootstrap: /Users/saikrisv/Library/Application Support/appium/bootstrap/bootstrap-48099ec8c70cb579.js
info: [debug] Getting device string from opts: {"forceIphone":false,"forceIpad":false,"xcodeVersion":"7.0","iOSSDKVersion":"9.0","deviceName":"iPhone 5s","platformVersion":"9.0"}
info: [debug] fixDevice is on
info: [debug] Final device string is: 'iPhone 5s (9.0)'
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 5s","platformVersion":"9.0"}
info: [debug] fixDevice is on
info: [debug] Final device string is: 'iPhone 5s (9.0)'
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 iOS Simulator
info: [debug] Cleaning sim data files
info: node-simctl: Executing: xcrun with args: simctl erase 15FBCDC5-96A7-42F9-89EC-A8F9887064D3 and timeout: 2000
info: [debug] Cleaning app data files
warn: Applications directory /Users/saikrisv/Library/Developer/CoreSimulator/Devices/15FBCDC5-96A7-42F9-89EC-A8F9887064D3/data/Containers/Data/Application doesn't exist. Have you run this simulator before?
warn: Applications directory /Users/saikrisv/Library/Developer/CoreSimulator/Devices/15FBCDC5-96A7-42F9-89EC-A8F9887064D3/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] 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 5s (9.0)
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 5s (9.0)" /var/folders/wd/9s7s80fd66j88vf5119rwt300000gp/T/115823-10614-wmfiz3/TestApp.app -e UIASCRIPT "/Users/saikrisv/Library/Application Support/appium/bootstrap/bootstrap-48099ec8c70cb579.js" -e UIARESULTSPATH /tmp/appium-instruments
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-09-23 11:12:33 +0000 Debug: evaluation finished
info: [debug] [INST] 2015-09-23 11:12:33 +0000 Debug: responding with:
info: [debug] [INST] 2015-09-23 11:12:33 +0000 Debug: Running system command #1: /usr/local/Cellar/node/0.12.7/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-09-23 11:12:34 +0000 Debug: Got new command 1 from instruments: au.setScreenOrientation('PORTRAIT')
info: [debug] [INST] 2015-09-23 11:12:34 +0000 Debug: evaluating au.setScreenOrientation('PORTRAIT')
info: [debug] [INST] 2015-09-23 11:12:34 +0000 Debug: target.setDeviceOrientation("1")
info: [debug] [INST] 2015-09-23 11:12:35 +0000 Debug: evaluation finished
info: [debug] [INST] 2015-09-23 11:12:35 +0000 Debug: responding with:
info: [debug] [INST] 2015-09-23 11:12:35 +0000 Debug: Running system command #2: /usr/local/Cellar/node/0.12.7/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-09-23 11:12:36 +0000 Debug: Got new command 2 from instruments: au.mainApp().getTreeForXML()
info: [debug] [INST] 2015-09-23 11:12:36 +0000 Debug: evaluating au.mainApp().getTreeForXML()
info: [debug] [INST] 2015-09-23 11:12:36 +0000 Debug: evaluation finished
info: [debug] [INST] 2015-09-23 11:12:36 +0000 Debug: responding with:id":true,"visible":true,"hint":null,"path":"/0/0","x":0,"y":0,"width":320,"height":568},">":[{"UIATextField":{"@":{"name":"TextField1","label":"TextField1","value":"","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/0","x":102,"y":38,"width":97,"height":31},">":[{"UIATextField":{"@":{"name":"TextField1","label":"TextField1","value":"","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/0/0","x":102,"y":38,"width":97,"height":31},">":[]}}]}},{"UIATextField":{"@":{"name":"TextField2","label":"TextField2","value":"","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/1","x":102,"y":77,"width":97,"height":31},">":[{"UIATextField":{"@":{"name":"TextField2","label":"TextField2","value":"","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/1/0","x":102,"y":77,"width":97,"height":31},">":[]}}]}},{"UIAButton":{"@":{"name":"ComputeSumButton","label":"ComputeSumButton","value":null,"dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/2","x":94,"y":122,"width":113,"height":37},">":[]}},{"UIAStaticText":{"@":{"name":"SumLabel","label":"SumLabel","value":"SumLabel","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/3","x":129,"y":167,"width":42,"height":21},">":[]}},{"UIAButton":{"@":{"name":"show alert","label":"show alert","value":null,"dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/4","x":26,"y":207,"width":76,"height":44},">":[]}},{"UIAButton":{"@":{"name":"contact alert","label":"contact alert","value":null,"dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/5","x":110,"y":214,"width":86,"height":30},">":[]}},{"UIAButton":{"@":{"name":"location alert","label":"location alert","value":null,"dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/6","x":204,"y":214,"width":89,"height":30},">":[]}},{"UIAStaticText":{"@":{"name":"AppElem","label":"AppElem","value":"AppElem","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/7","x":122,"y":259,"width":56,"height":21},">":[]}},{"UIASlider":{"@":{"name":"AppElem","label":"AppElem","value":"50%","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/8","x":91,"y":288,"width":118,"height":29},">":[]}},{"UIAStaticText":{"@":{"name":"AppElem","label":"AppElem","value":"AppElem","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/9","x":122,"y":318,"width":56,"height":21},">":[]}},{"UIAButton":{"@":{"name":"DisabledButton","label":"disabled button","value":null,"dom":null,"enabled":false,"valid":true,"visible":true,"hint":null,"path":"/0/0/10","x":83,"y":347,"width":136,"height":28},">":[]}},{"UIAStaticText":{"@":{"name":null,"label":"","value":"","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/11","x":82,"y":383,"width":73,"height":21},">":[]}},{"UIASwitch":{"@":{"name":"locationStatus","label":"locationStatus","value":0,"dom":null,"enabled":false,"valid":true,"visible":true,"hint":null,"path":"/0/0/12","x":170,"y":378,"width":51,"height":31},">":[]}},{"UIAButton":{"@":{"name":"Test Gesture","label":"Test Gesture","value":null,"dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/13","x":93,"y":397,"width":116,"height":44},">":[]}}]}},{"UIAWindow":{"@":{"name":null,"label":null,"value":null,"dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/1","x":0,"y":0,"width":320,"height":568},">":[{"UIAStatusBar":{"@":{"name":null,"label":null,"value":null,"dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/1/0","x":0,"y":0,"width":320,"height":20},">":[{"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/1/0/0","x":6,"y":0,"width":39,"height":20},">":[]}},{"UIAElement":{"@":{"name":"3 of 3 Wi-Fi bars","label":"3 of 3 Wi-Fi bars","value":"SSID","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/1/0/1","x":50,"y":0,"width":13,"height":20},">":[]}},{"UIAElement":{"@":{"name":"4:42 PM","label":"4:42 PM","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/1/0/2","x":137,"y":0,"width":50,"height":20},">":[]}},{"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/1/0/3","x":290,"y":0,"width":25,"height":20},">":[]}}]}}]}}]}}}
info: [debug] [INST] 2015-09-23 11:12:36 +0000 Debug: Running system command #3: /usr/local/Cellar/node/0.12.7/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":"TestApp","label":"TestApp","value":null,"dom":null,"enabled":true,"valid":true,"visible":true,"...
info: [debug] Socket data received (5650 bytes)
info: [debug] Socket data being routed.
info: [debug] Got result from instruments: {"status":0,"value":{"UIAApplication":{"@":{"name":"TestApp","label":"TestApp","value":null,"dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0","x":0,"y":0,"width":320,"height":568},">":[{"UIAWindow":{"@":{"name":null,"label":null,"value":null,"dom":null,"enabled":true,"val
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 8dba0ef3-6a42-4b88-8c96-e29f77218d53
info: <-- POST /wd/hub/session 303 23923.542 ms - 74 
info: --> GET /wd/hub/session/8dba0ef3-6a42-4b88-8c96-e29f77218d53 {}
info: [debug] Responding to client with success: {"status":0,"value":{"webStorageEnabled":false,"locationContextEnabled":false,"browserName":"iOS","platform":"MAC","javascriptEnabled":true,"databaseEnabled":false,"takesScreenshot":true,"networkConnectionEnabled":false,"warnings":{},"desired":{"app":"/Users/saikrisv/git/VodQa_MobileAutomationWorkShop/VodQA_WorkShop/build/TestApp.app.zip","platformName":"iOS","deviceName":"iPhone 5s","platformVersion":"9.0"},"app":"/Users/saikrisv/git/VodQa_MobileAutomationWorkShop/VodQA_WorkShop/build/TestApp.app.zip","platformName":"iOS","deviceName":"iPhone 5s","platformVersion":"9.0"},"sessionId":"8dba0ef3-6a42-4b88-8c96-e29f77218d53"}
info: <-- GET /wd/hub/session/8dba0ef3-6a42-4b88-8c96-e29f77218d53 200 7.229 ms - 630 {"status":0,"value":{"webStorageEnabled":false,"locationContextEnabled":false,"browserName":"iOS","platform":"MAC","javascriptEnabled":true,"databaseEnabled":false,"takesScreenshot":true,"networkConnectionEnabled":false,"warnings":{},"desired":{"app":"/Users/saikrisv/git/VodQa_MobileAutomationWorkShop/VodQA_WorkShop/build/TestApp.app.zip","platformName":"iOS","deviceName":"iPhone 5s","platformVersion":"9.0"},"app":"/Users/saikrisv/git/VodQa_MobileAutomationWorkShop/VodQA_WorkShop/build/TestApp.app.zip","platformName":"iOS","deviceName":"iPhone 5s","platformVersion":"9.0"},"sessionId":"8dba0ef3-6a42-4b88-8c96-e29f77218d53"}
info: --> POST /wd/hub/session/8dba0ef3-6a42-4b88-8c96-e29f77218d53/elements {"using":"class name","value":"UIAButton"}
info: [debug] Waiting up to 0ms for condition
info: [debug] Pushing command to appium work queue: "au.getElementsByType('UIAButton')"
info: [debug] Sending command to instruments: au.getElementsByType('UIAButton')
info: [debug] [INST] 2015-09-23 11:12:37 +0000 Debug: Got new command 3 from instruments: au.getElementsByType('UIAButton')
info: [debug] [INST] 2015-09-23 11:12:37 +0000 Debug: evaluating au.getElementsByType('UIAButton')
info: [debug] [INST] 2015-09-23 11:12:37 +0000 Debug: evaluation finished
info: [debug] [INST] 2015-09-23 11:12:37 +0000 Debug: Lookup returned [object UIAButton] with the name "ComputeSumButton" (id: 0).
info: [debug] [INST] 2015-09-23 11:12:37 +0000 Debug: Lookup returned [object UIAButton] with the name "show alert" (id: 1).
info: [debug] [INST] 2015-09-23 11:12:37 +0000 Debug: Lookup returned [object UIAButton] with the name "contact alert" (id: 2).
info: [debug] [INST] 2015-09-23 11:12:37 +0000 Debug: Lookup returned [object UIAButton] with the name "location alert" (id: 3).
info: [debug] [INST] 2015-09-23 11:12:37 +0000 Debug: Lookup returned [object UIAButton] with the name "DisabledButton" (id: 4).
info: [debug] [INST] 2015-09-23 11:12:37 +0000 Debug: Lookup returned [object UIAButton] with the name "Test Gesture" (id: 5).
info: [debug] [INST] 2015-09-23 11:12:37 +0000 Debug: responding with:
info: [debug] [INST] 2015-09-23 11:12:37 +0000 Debug: Running system command #4: /usr/local/Cellar/node/0.12.7/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"},{"ELEMENT":"1"},{"ELEMENT":"2"},{"ELEMENT":"3"},{"ELEMENT":"4"},{"ELEMENT":"5"}]}...
info: [debug] Socket data received (120 bytes)
info: [debug] Socket data being routed.
info: [debug] Got result from instruments: {"status":0,"value":[{"ELEMENT":"0"},{"ELEMENT":"1"},{"ELEMENT":"2"},{"ELEMENT":"3"},{"ELEMENT":"4"},{"ELEMENT":"5"}]}
info: [debug] Responding to client with success: {"status":0,"value":[{"ELEMENT":"0"},{"ELEMENT":"1"},{"ELEMENT":"2"},{"ELEMENT":"3"},{"ELEMENT":"4"},{"ELEMENT":"5"}],"sessionId":"8dba0ef3-6a42-4b88-8c96-e29f77218d53"}
info: <-- POST /wd/hub/session/8dba0ef3-6a42-4b88-8c96-e29f77218d53/elements 200 1032.360 ms - 169 {"status":0,"value":[{"ELEMENT":"0"},{"ELEMENT":"1"},{"ELEMENT":"2"},{"ELEMENT":"3"},{"ELEMENT":"4"},{"ELEMENT":"5"}],"sessionId":"8dba0ef3-6a42-4b88-8c96-e29f77218d53"}
info: --> POST /wd/hub/session/8dba0ef3-6a42-4b88-8c96-e29f77218d53/touch/perform {"actions":[{"action":"press","options":{"element":"5"}}]}
info: [debug] Pushing command to appium work queue: "au.getElement('5').rect()"
info: [debug] Sending command to instruments: au.getElement('5').rect()
info: [debug] [INST] 2015-09-23 11:12:38 +0000 Debug: Got new command 4 from instruments: au.getElement('5').rect()
info: [debug] [INST] 2015-09-23 11:12:38 +0000 Debug: evaluating au.getElement('5').rect()
info: [debug] [INST] 2015-09-23 11:12:38 +0000 Debug: evaluation finished
info: [debug] [INST] 2015-09-23 11:12:39 +0000 Debug: responding with:
info: [debug] [INST] 2015-09-23 11:12:39 +0000 Debug: Running system command #5: /usr/local/Cellar/node/0.12.7/bin/node /usr/local/lib/node_modules/appium/node_modules/appium-uiauto/bin/command-proxy-client.js /tmp/instruments_sock 2,{"status":0,"value":{"origin":{"x":93,"y":397},"size":{"width":116,"height":44}}}...
info: [debug] Socket data received (83 bytes)
info: [debug] Socket data being routed.
info: [debug] Got result from instruments: {"status":0,"value":{"origin":{"x":93,"y":397},"size":{"width":116,"height":44}}}
info: [debug] Pushing command to appium work queue: "target.touch([{\"touch\":[{\"x\":151,\"y\":419}],\"time\":0.2}])"
info: [debug] Sending command to instruments: target.touch([{"touch":[{"x":151,"y":419}],"time":0.2}])
info: [debug] [INST] 2015-09-23 11:12:40 +0000 Debug: Got new command 5 from instruments: target.touch([{"touch":[{"x":151,"y":419}],"time":0.2}])
info: [debug] [INST] 2015-09-23 11:12:40 +0000 Debug: evaluating target.touch([{"touch":[{"x":151,"y":419}],"time":0.2}])
info: [debug] [INST] 2015-09-23 11:12:40 +0000 Debug: target.touch(__NSCFArray)
info: [debug] [INST] 2015-09-23 11:12:41 +0000 Debug: evaluation finished
info: [debug] [INST] 2015-09-23 11:12:41 +0000 Debug: responding with:
info: [debug] [INST] 2015-09-23 11:12:41 +0000 Debug: Running system command #6: /usr/local/Cellar/node/0.12.7/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] Responding to client with success: {"status":0,"value":"","sessionId":"8dba0ef3-6a42-4b88-8c96-e29f77218d53"}
info: <-- POST /wd/hub/session/8dba0ef3-6a42-4b88-8c96-e29f77218d53/touch/perform 200 2971.513 ms - 74 {"status":0,"value":"","sessionId":"8dba0ef3-6a42-4b88-8c96-e29f77218d53"}
info: --> GET /wd/hub/session/8dba0ef3-6a42-4b88-8c96-e29f77218d53/alert_text {}
info: [debug] Pushing command to appium work queue: "au.getAlertText()"
info: [debug] Sending command to instruments: au.getAlertText()
info: [debug] [INST] 2015-09-23 11:12:42 +0000 Debug: Got new command 6 from instruments: au.getAlertText()
info: [debug] [INST] 2015-09-23 11:12:42 +0000 Debug: evaluating au.getAlertText()
info: [debug] Socket data received (97 bytes)
info: [debug] Socket data being routed.
info: [debug] Got result from instruments: {"status":27,"value":"An attempt was made to operate on a modal dialog when one was not open."}
info: [debug] Responding to client with error: {"status":27,"value":{"message":"An attempt was made to operate on a modal dialog when one was not open.","origValue":"An attempt was made to operate on a modal dialog when one was not open."},"sessionId":"8dba0ef3-6a42-4b88-8c96-e29f77218d53"}
info: <-- GET /wd/hub/session/8dba0ef3-6a42-4b88-8c96-e29f77218d53/alert_text 500 1087.016 ms - 244 
info: [debug] [INST] 2015-09-23 11:12:42 +0000 Error: VerboseError: An attempt was made to operate on a modal dialog when one was not open.
info: [debug] [INST] 2015-09-23 11:12:42 +0000 Error: Error during eval: [native code]
       file:///usr/local/lib/node_modules/appium/BE0184F0-2926-4701-B9BA-823A751B3BCA/bootstrap-48099ec8c70cb579.js:711:30
getAlertText@file:///usr/local/lib/node_modules/appium/BE0184F0-2926-4701-B9BA-823A751B3BCA/bootstrap-48099ec8c70cb579.js:939:41
eval code
eval@[native code]
startProcessing@file:///usr/local/lib/node_modules/appium/BE0184F0-2926-4701-B9BA-823A751B3BCA/bootstrap-48099ec8c70cb579.js:2702:30
bootstrap@file:///usr/local/lib/node_modules/appium/BE0184F0-2926-4701-B9BA-823A751B3BCA/bootstrap-48099ec8c70cb579.js:2770:31
global code@file:///usr/local/lib/node_modules/appium/BE0184F0-2926-4701-B9BA-823A751B3BCA/bootstrap-48099ec8c70cb579.js:2779:10
info: [debug] [INST] 2015-09-23 11:12:42 +0000 Debug: responding with:
info: [debug] [INST] 2015-09-23 11:12:42 +0000 Debug: Running system command #7: /usr/local/Cellar/node/0.12.7/bin/node /usr/local/lib/node_modules/appium/node_modules/appium-uiauto/bin/command-proxy-client.js /tmp/instruments_sock 2,{"status":27,"value":"An attempt was made to operate on a modal dialog when one was not open."}...
info: --> GET /wd/hub/session/8dba0ef3-6a42-4b88-8c96-e29f77218d53/alert_text {}
info: [debug] Pushing command to appium work queue: "au.getAlertText()"
info: [debug] Sending command to instruments: au.getAlertText()
info: [debug] [INST] 2015-09-23 11:12:43 +0000 Debug: Got new command 7 from instruments: au.getAlertText()
info: [debug] [INST] 2015-09-23 11:12:43 +0000 Debug: evaluating au.getAlertText()
info: [debug] [INST] 2015-09-23 11:12:43 +0000 Error: VerboseError: An attempt was made to operate on a modal dialog when one was not open.
info: [debug] Socket data received (97 bytes)
info: [debug] Socket data being routed.
info: [debug] Got result from instruments: {"status":27,"value":"An attempt was made to operate on a modal dialog when one was not open."}
info: [debug] Responding to client with error: {"status":27,"value":{"message":"An attempt was made to operate on a modal dialog when one was not open.","origValue":"An attempt was made to operate on a modal dialog when one was not open."},"sessionId":"8dba0ef3-6a42-4b88-8c96-e29f77218d53"}
info: <-- GET /wd/hub/session/8dba0ef3-6a42-4b88-8c96-e29f77218d53/alert_text 500 663.273 ms - 244 
info: --> POST /wd/hub/session/8dba0ef3-6a42-4b88-8c96-e29f77218d53/touch/perform {"actions":[{"action":"press","options":{"x":100,"y":200}},{"action":"wait","options":{"ms":1000}},{"action":"release","options":{}}]}
info: [debug] Pushing command to appium work queue: "target.touch([{\"touch\":[{\"x\":100,\"y\":200}],\"time\":0.2},{\"touch\":[{\"x\":100,\"y\":200}],\"time\":1.2}])"
info: [debug] Sending command to instruments: target.touch([{"touch":[{"x":100,"y":200}],"time":0.2},{"touch":[{"x":100,"y":200}],"time":1.2}])
info: [debug] [INST] 2015-09-23 11:12:43 +0000 Error: Error during eval: [native code]
       file:///usr/local/lib/node_modules/appium/BE0184F0-2926-4701-B9BA-823A751B3BCA/bootstrap-48099ec8c70cb579.js:711:30
getAlertText@file:///usr/local/lib/node_modules/appium/BE0184F0-2926-4701-B9BA-823A751B3BCA/bootstrap-48099ec8c70cb579.js:939:41
eval code
eval@[native code]
startProcessing@file:///usr/local/lib/node_modules/appium/BE0184F0-2926-4701-B9BA-823A751B3BCA/bootstrap-48099ec8c70cb579.js:2702:30
bootstrap@file:///usr/local/lib/node_modules/appium/BE0184F0-2926-4701-B9BA-823A751B3BCA/bootstrap-48099ec8c70cb579.js:2770:31
global code@file:///usr/local/lib/node_modules/appium/BE0184F0-2926-4701-B9BA-823A751B3BCA/bootstrap-48099ec8c70cb579.js:2779:10
info: [debug] [INST] 2015-09-23 11:12:43 +0000 Debug: responding with:
info: [debug] [INST] 2015-09-23 11:12:43 +0000 Debug: Running system command #8: /usr/local/Cellar/node/0.12.7/bin/node /usr/local/lib/node_modules/appium/node_modules/appium-uiauto/bin/command-proxy-client.js /tmp/instruments_sock 2,{"status":27,"value":"An attempt was made to operate on a modal dialog when one was not open."}...
info: [debug] [INST] 2015-09-23 11:12:44 +0000 Debug: Got new command 8 from instruments: target.touch([{"touch":[{"x":100,"y":200}],"time":0.2},{"touch":[{"x":100,"y":200}],"time":1.2}])
info: [debug] [INST] 2015-09-23 11:12:44 +0000 Debug: evaluating target.touch([{"touch":[{"x":100,"y":200}],"time":0.2},{"touch":[{"x":100,"y":200}],"time":1.2}])
info: [debug] [INST] 2015-09-23 11:12:44 +0000 Debug: target.touch(__NSCFArray)
info: [debug] [INST] 2015-09-23 11:12:45 +0000 Debug: evaluation finished
info: [debug] [INST] 2015-09-23 11:12:45 +0000 Debug: responding with:
info: [debug] [INST] 2015-09-23 11:12:45 +0000 Debug: Running system command #9: /usr/local/Cellar/node/0.12.7/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] Responding to client with success: {"status":0,"value":"","sessionId":"8dba0ef3-6a42-4b88-8c96-e29f77218d53"}
info: <-- POST /wd/hub/session/8dba0ef3-6a42-4b88-8c96-e29f77218d53/touch/perform 200 2594.067 ms - 74 {"status":0,"value":"","sessionId":"8dba0ef3-6a42-4b88-8c96-e29f77218d53"}
info: --> POST /wd/hub/session/8dba0ef3-6a42-4b88-8c96-e29f77218d53/elements {"using":"class name","value":"UIAButton"}
info: [debug] Waiting up to 0ms for condition
info: [debug] Pushing command to appium work queue: "au.getElementsByType('UIAButton')"
info: [debug] Sending command to instruments: au.getElementsByType('UIAButton')
info: [debug] [INST] 2015-09-23 11:12:46 +0000 Debug: Got new command 9 from instruments: au.getElementsByType('UIAButton')
info: [debug] [INST] 2015-09-23 11:12:46 +0000 Debug: evaluating au.getElementsByType('UIAButton')
info: [debug] [INST] 2015-09-23 11:12:46 +0000 Debug: evaluation finished
info: [debug] [INST] 2015-09-23 11:12:46 +0000 Debug: Lookup returned [object UIAButton] with the name "Double tap to open" (id: 6).
info: [debug] [INST] 2015-09-23 11:12:46 +0000 Debug: Lookup returned [object UIAButton] with the name "Don’t Allow" (id: 7).
info: [debug] [INST] 2015-09-23 11:12:46 +0000 Debug: Lookup returned [object UIAButton] with the name "Allow" (id: 8).
info: [debug] [INST] 2015-09-23 11:12:46 +0000 Debug: responding with:
info: [debug] [INST] 2015-09-23 11:12:46 +0000 Debug: Running system command #10: /usr/local/Cellar/node/0.12.7/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":"6"},{"ELEMENT":"7"},{"ELEMENT":"8"}]}...
info: [debug] Socket data received (72 bytes)
info: [debug] Socket data being routed.
info: [debug] Got result from instruments: {"status":0,"value":[{"ELEMENT":"6"},{"ELEMENT":"7"},{"ELEMENT":"8"}]}
info: [debug] Responding to client with success: {"status":0,"value":[{"ELEMENT":"6"},{"ELEMENT":"7"},{"ELEMENT":"8"}],"sessionId":"8dba0ef3-6a42-4b88-8c96-e29f77218d53"}
info: <-- POST /wd/hub/session/8dba0ef3-6a42-4b88-8c96-e29f77218d53/elements 200 945.303 ms - 121 {"status":0,"value":[{"ELEMENT":"6"},{"ELEMENT":"7"},{"ELEMENT":"8"}],"sessionId":"8dba0ef3-6a42-4b88-8c96-e29f77218d53"}
info: --> POST /wd/hub/session/8dba0ef3-6a42-4b88-8c96-e29f77218d53/elements {"using":"class name","value":"UIAButton"}
info: [debug] Waiting up to 0ms for condition
info: [debug] Pushing command to appium work queue: "au.getElementsByType('UIAButton')"
info: [debug] Sending command to instruments: au.getElementsByType('UIAButton')
info: [debug] [INST] 2015-09-23 11:12:47 +0000 Debug: Got new command 10 from instruments: au.getElementsByType('UIAButton')
info: [debug] [INST] 2015-09-23 11:12:47 +0000 Debug: evaluating au.getElementsByType('UIAButton')
info: [debug] [INST] 2015-09-23 11:12:47 +0000 Debug: evaluation finished
info: [debug] [INST] 2015-09-23 11:12:47 +0000 Debug: Lookup returned [object UIAButton] with the name "Double tap to open" (id: 9).
info: [debug] [INST] 2015-09-23 11:12:47 +0000 Debug: Lookup returned [object UIAButton] with the name "Don’t Allow" (id: 10).
info: [debug] [INST] 2015-09-23 11:12:47 +0000 Debug: Lookup returned [object UIAButton] with the name "Allow" (id: 11).
info: [debug] [INST] 2015-09-23 11:12:47 +0000 Debug: responding with:
info: [debug] [INST] 2015-09-23 11:12:47 +0000 Debug: Running system command #11: /usr/local/Cellar/node/0.12.7/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":"9"},{"ELEMENT":"10"},{"ELEMENT":"11"}]}...
info: [debug] Socket data received (74 bytes)
info: [debug] Socket data being routed.
info: [debug] Got result from instruments: {"status":0,"value":[{"ELEMENT":"9"},{"ELEMENT":"10"},{"ELEMENT":"11"}]}
info: [debug] Responding to client with success: {"status":0,"value":[{"ELEMENT":"9"},{"ELEMENT":"10"},{"ELEMENT":"11"}],"sessionId":"8dba0ef3-6a42-4b88-8c96-e29f77218d53"}
info: <-- POST /wd/hub/session/8dba0ef3-6a42-4b88-8c96-e29f77218d53/elements 200 1070.509 ms - 123 {"status":0,"value":[{"ELEMENT":"9"},{"ELEMENT":"10"},{"ELEMENT":"11"}],"sessionId":"8dba0ef3-6a42-4b88-8c96-e29f77218d53"}
info: --> POST /wd/hub/session/8dba0ef3-6a42-4b88-8c96-e29f77218d53/elements {"using":"class name","value":"UIAButton"}
info: [debug] Waiting up to 0ms for condition
info: [debug] Pushing command to appium work queue: "au.getElementsByType('UIAButton')"
info: [debug] Sending command to instruments: au.getElementsByType('UIAButton')
info: [debug] [INST] 2015-09-23 11:12:48 +0000 Debug: Got new command 11 from instruments: au.getElementsByType('UIAButton')
info: [debug] [INST] 2015-09-23 11:12:48 +0000 Debug: evaluating au.getElementsByType('UIAButton')
info: [debug] [INST] 2015-09-23 11:12:48 +0000 Debug: evaluation finished
info: [debug] [INST] 2015-09-23 11:12:48 +0000 Debug: Lookup returned [object UIAButton] with the name "Double tap to open" (id: 12).
info: [debug] [INST] 2015-09-23 11:12:48 +0000 Debug: Lookup returned [object UIAButton] with the name "Don’t Allow" (id: 13).
info: [debug] [INST] 2015-09-23 11:12:48 +0000 Debug: Lookup returned [object UIAButton] with the name "Allow" (id: 14).
info: [debug] [INST] 2015-09-23 11:12:48 +0000 Debug: responding with:
info: [debug] [INST] 2015-09-23 11:12:48 +0000 Debug: Running system command #12: /usr/local/Cellar/node/0.12.7/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":"12"},{"ELEMENT":"13"},{"ELEMENT":"14"}]}...
info: [debug] Socket data received (75 bytes)
info: [debug] Socket data being routed.
info: [debug] Got result from instruments: {"status":0,"value":[{"ELEMENT":"12"},{"ELEMENT":"13"},{"ELEMENT":"14"}]}
info: [debug] Responding to client with success: {"status":0,"value":[{"ELEMENT":"12"},{"ELEMENT":"13"},{"ELEMENT":"14"}],"sessionId":"8dba0ef3-6a42-4b88-8c96-e29f77218d53"}
info: <-- POST /wd/hub/session/8dba0ef3-6a42-4b88-8c96-e29f77218d53/elements 200 977.509 ms - 124 {"status":0,"value":[{"ELEMENT":"12"},{"ELEMENT":"13"},{"ELEMENT":"14"}],"sessionId":"8dba0ef3-6a42-4b88-8c96-e29f77218d53"}
info: --> POST /wd/hub/session/8dba0ef3-6a42-4b88-8c96-e29f77218d53/element {"using":"name","value":"TextField1"}
info: [debug] Waiting up to 0ms for condition
info: [debug] Pushing command to appium work queue: "au.getElementByName('TextField1')"
info: [debug] Sending command to instruments: au.getElementByName('TextField1')
info: [debug] [INST] 2015-09-23 11:12:49 +0000 Debug: Got new command 12 from instruments: au.getElementByName('TextField1')
info: [debug] [INST] 2015-09-23 11:12:49 +0000 Debug: evaluating au.getElementByName('TextField1')
info: [debug] [INST] 2015-09-23 11:12:49 +0000 Debug: evaluation finished
info: [debug] [INST] 2015-09-23 11:12:49 +0000 Debug: responding with:
info: [debug] [INST] 2015-09-23 11:12:49 +0000 Debug: Running system command #13: /usr/local/Cellar/node/0.12.7/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] Condition unmet after 1065ms. Timing out.
info: [debug] Responding to client with error: {"status":7,"value":{"message":"An element could not be located on the page using the given search parameters.","origValue":""},"sessionId":"8dba0ef3-6a42-4b88-8c96-e29f77218d53"}
info: <-- POST /wd/hub/session/8dba0ef3-6a42-4b88-8c96-e29f77218d53/element 500 1065.549 ms - 179 
info: --> DELETE /wd/hub/session/8dba0ef3-6a42-4b88-8c96-e29f77218d53 {}
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-09-23 11:12:50 +0000 Stopped: Script was stopped by the user
info: [debug] [INST STDERR] 2015-09-23 16:42:50.200 instruments[12287:402810] Attempting to change event horizon while disengage
info: [debug] [INST] Instruments Trace Complete (Duration : 33.882305s; 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 iOS Simulator
info: [debug] Cleaning sim data files
info: node-simctl: Executing: xcrun with args: simctl erase 15FBCDC5-96A7-42F9-89EC-A8F9887064D3 and timeout: 2000
info: node-simctl: Executing: xcrun with args: simctl erase 15FBCDC5-96A7-42F9-89EC-A8F9887064D3 and timeout: 2000
info: node-simctl: Executing: xcrun with args: simctl erase 15FBCDC5-96A7-42F9-89EC-A8F9887064D3 and timeout: 2000
info: node-simctl: Executing: xcrun with args: simctl erase 15FBCDC5-96A7-42F9-89EC-A8F9887064D3 and timeout: 2000
info: node-simctl: Executing: xcrun with args: simctl erase 15FBCDC5-96A7-42F9-89EC-A8F9887064D3 and timeout: 2000
error: Could not reset simulator. Leaving as is. Error: Command 'xcrun simctl erase 15FBCDC5-96A7-42F9-89EC-A8F9887064D3' exited with code 159
info: [debug] Cleaning app data files
info: [debug] Deleting /Users/saikrisv/Library/Developer/CoreSimulator/Devices/15FBCDC5-96A7-42F9-89EC-A8F9887064D3/data/Containers/Data/Application/98DA5912-3199-4B9D-9EA1-550D8616CBFE
info: [debug] Deleting /Users/saikrisv/Library/Developer/CoreSimulator/Devices/15FBCDC5-96A7-42F9-89EC-A8F9887064D3/data/Containers/Bundle/Application/A1041CD4-D295-48C1-BB21-E5FE33ED91FF
info: [debug] Deleting /Users/saikrisv/Library/Developer/CoreSimulator/Devices/15FBCDC5-96A7-42F9-89EC-A8F9887064D3/data/Library/Preferences/io.appium.TestApp.plist
info: [debug] Cleaning up appium session
info: [debug] Responding to client with success: {"status":0,"value":null,"sessionId":"8dba0ef3-6a42-4b88-8c96-e29f77218d53"}
info: <-- DELETE /wd/hub/session/8dba0ef3-6a42-4b88-8c96-e29f77218d53 200 11100.540 ms - 76 {"status":0,"value":null,"sessionId":"8dba0ef3-6a42-4b88-8c96-e29f77218d53"}