Hi,
I am working with hybrid iOS Mobile applicaion using appium [email protected]( Appium UI tool) and protractor to automate an angularjs mobile app.
The application is getting launched, after that, not able to communicate and send the commands to interact with the app. It always displays timeout error. Attached the appium logs and protractor logs… Kindly advise.
Appium Logs:
Launching Appium with command: ‘/Applications/Appium.app/Contents/Resources/node/bin/node’ lib/server/main.js --address “127.0.0.1” --command-timeout “7200” --platform-version “7.1.1” --platform-name “iOS” --app “/Users/qualcomm/Desktop/16Mar15/AbbVie Care Global.ipa” --udid “b1863f83f4d9c9ea7ee1ac2f2a8a8e478dd3aa38” --show-ios-log --default-device
info: Welcome to Appium v1.3.6 (REV 004f52f249d3513809e7d0734d9205d1fec19f8e)
info: Appium REST http interface listener started on 127.0.0.1:4723
info: [debug] Non-default server args: {“app”:"/Users/qualcomm/Desktop/16Mar15/AbbVie Care Global.ipa",“udid”:“b1863f83f4d9c9ea7ee1ac2f2a8a8e478dd3aa38”,“address”:“127.0.0.1”,“platformName”:“iOS”,“platformVersion”:“7.1.1”,“defaultDevice”:true,“showIOSLog”:true,“defaultCommandTimeout”:7200}
info: Console LogLevel: debug
info: --> GET /wd/hub/status {}
info: [debug] Responding to client with success: {“status”:0,“value”:{“build”:{“version”:“1.3.6”,“revision”:“004f52f249d3513809e7d0734d9205d1fec19f8e”}}}
info: <-- GET /wd/hub/status 200 14.404 ms - 104 {“status”:0,“value”:{“build”:{“version”:“1.3.6”,“revision”:“004f52f249d3513809e7d0734d9205d1fec19f8e”}}}
info: --> GET /wd/hub/status {}
info: [debug] Responding to client with success: {“status”:0,“value”:{“build”:{“version”:“1.3.6”,“revision”:“004f52f249d3513809e7d0734d9205d1fec19f8e”}}}
info: <-- GET /wd/hub/status 200 6.231 ms - 104 {“status”:0,“value”:{“build”:{“version”:“1.3.6”,“revision”:“004f52f249d3513809e7d0734d9205d1fec19f8e”}}}
info: --> POST /wd/hub/session {“desiredCapabilities”:{“browserName”:“iOS”,“appium-version”:“1.3.4”,“platformName”:“iOS”,“platformVersion”:7.1,“deviceName”:“iPhone”,“autoWebview”:true,“count”:1}}
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, count
info: [debug] Using local .ipa from command line: /Users/qualcomm/Desktop/16Mar15/AbbVie Care Global.ipa
info: [debug] Copying local zip to tmp dir
info: [debug] /Users/qualcomm/Desktop/16Mar15/AbbVie Care Global.ipa copied to /var/folders/rm/jr880wjn44n2flx11y563cjh0000gp/T/115216-3183-1ec3pdh/appium-app.zip
info: [debug] Unzipping /var/folders/rm/jr880wjn44n2flx11y563cjh0000gp/T/115216-3183-1ec3pdh/appium-app.zip
info: [debug] Testing zip archive: /var/folders/rm/jr880wjn44n2flx11y563cjh0000gp/T/115216-3183-1ec3pdh/appium-app.zip
info: [debug] Zip archive tested clean
info: [debug] Unzip successful
info: [debug] Using locally extracted app: /var/folders/rm/jr880wjn44n2flx11y563cjh0000gp/T/115216-3183-1ec3pdh/Payload/AbbVie Care Global.app
info: [debug] Creating new appium session 42a64d1f-7d7b-4bb3-98d8-7de4e4637c8b
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 7.1
info: [debug] Not checking whether simulator is available since we’re on a real device
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: [debug] Preparing uiauto bootstrap
info: [debug] Dynamic bootstrap dir: /Users/qualcomm/Library/Application Support/appium/bootstrap
info: [debug] Dynamic env: {“nodePath”:"/Applications/Appium.app/Contents/Resources/node/bin/node",“commandProxyClientPath”:"/Applications/Appium.app/Contents/Resources/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”:“grouped”}
info: [debug] Dynamic bootstrap code: // This file is automatically generated. Do not manually modify!
…
info: [debug] Dynamic bootstrap path: /Users/qualcomm/Library/Application Support/appium/bootstrap/bootstrap-2911698fabce8e2c.js
info: [debug] Reusing dynamic bootstrap: /Users/qualcomm/Library/Application Support/appium/bootstrap/bootstrap-2911698fabce8e2c.js
info: [debug] Getting device string from opts: {“forceIphone”:false,“forceIpad”:false,“xcodeVersion”:“5.1.1”,“iOSSDKVersion”:“7.1”,“deviceName”:“iPhone”,“platformVersion”:7.1}
info: [debug] fixDevice is on
info: [debug] Fixing device. Changed from: “iPhone - Simulator - iOS 7.1” to: “iPhone Retina (4-inch 64-bit) - Simulator - iOS 7.1”
info: [debug] Final device string is: ‘iPhone Retina (4-inch 64-bit) - Simulator - iOS 7.1’
info: [debug] Not setting device type since we’re on a real device
info: [debug] Checking whether we need to set app preferences
info: [debug] Not setting iOS and app preferences since we’re on a real device
info: [debug] Running ios sim reset flow
info: [debug] Killing the simulator process
info: [debug] Killall iPhoneSimulator
info: [debug] Killing any other simulator daemons
info: [debug] On a real device; cannot clean device state
info: [debug] Not setting locale because we’re using a real device
info: [debug] No iOS / app preferences to set
info: [debug] Starting iOS device log capture via deviceconsole
info: [debug] Not pre-launching simulator
info: [debug] Creating iDevice object with udid b1863f83f4d9c9ea7ee1ac2f2a8a8e478dd3aa38
info: [debug] Checking app install status using: /Applications/Appium.app/Contents/Resources/node_modules/appium/build/fruitstrap/fruitstrap isInstalled --id b1863f83f4d9c9ea7ee1ac2f2a8a8e478dd3aa38 --bundle com.span.spansoftphone
info: [debug] App is installed.
info: [debug] fullReset not requested. No need to install.
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 2.app/Contents/Developer/usr/bin/instruments
info: Launching instruments
info: [debug] Attempting to run app on real device with UDID b1863f83f4d9c9ea7ee1ac2f2a8a8e478dd3aa38
info: [debug] Spawning instruments with command: “/Applications/Xcode 2.app/Contents/Developer/usr/bin/instruments” -t “/Applications/Xcode 2.app/Contents/Applications/Instruments.app/Contents/PlugIns/AutomationInstrument.bundle/Contents/Resources/Automation.tracetemplate” -D /tmp/appium-instruments/instrumentscli0.trace -w b1863f83f4d9c9ea7ee1ac2f2a8a8e478dd3aa38 com.span.spansoftphone -e UIASCRIPT “/Users/qualcomm/Library/Application Support/appium/bootstrap/bootstrap-2911698fabce8e2c.js” -e UIARESULTSPATH /tmp/appium-instruments
info: [debug] And extra without-delay env: {}
info: [debug] And launch timeouts (in ms): {“global”:90000}
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-03-16 08:41:10 +0000 Debug: evaluation finished
info: [debug] [INST] 2015-03-16 08:41:10 +0000 Debug: responding with:
info: [debug] [INST] 2015-03-16 08:41:10 +0000 Debug: Running system command #1: /Applications/Appium.app/Contents/Resources/node/bin/node /Applications/Appium.app/Contents/Resources/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-03-16 08:41:11 +0000 Debug: Got new command 1 from instruments: au.setScreenOrientation(‘PORTRAIT’)
info: [debug] [INST] 2015-03-16 08:41:11 +0000 Debug: evaluating au.setScreenOrientation(‘PORTRAIT’)
info: [debug] [INST] 2015-03-16 08:41:11 +0000 Debug: target.setDeviceOrientation(“1”)
info: [debug] [INST] 2015-03-16 08:41:12 +0000 Debug: evaluation finished
info: [debug] [INST] 2015-03-16 08:41:12 +0000 Debug: responding with:
info: [debug] [INST] 2015-03-16 08:41:12 +0000 Debug: Running system command #2: /Applications/Appium.app/Contents/Resources/node/bin/node /Applications/Appium.app/Contents/Resources/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] Setting auto webview
info: [debug] Waiting for 6000 ms before navigating to view.
info: [debug] Navigating to most recently opened webview
info: [debug] [REMOTE] Getting WebKitRemoteDebugger pageArray
info: [debug] Picking webview WEBVIEW_7
info: [debug] Attempting to set context to ‘WEBVIEW_7’
info: [debug] [REMOTE] Debugger web socket connected to url [ws://localhost:27753/devtools/page/7]
info: [debug] [REMOTE] Unregistering from page readiness notifications
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-03-16 08:41:19 +0000 Debug: Got new command 2 from instruments: au.mainApp().getTreeForXML()
info: [debug] [INST] 2015-03-16 08:41:19 +0000 Debug: evaluating au.mainApp().getTreeForXML()
info: [debug] [INST] 2015-03-16 08:41:19 +0000 Debug: evaluation finished
info: [debug] [INST] 2015-03-16 08:41:19 +0000 Debug: responding with:ull,“enabled”:true,“valid”:true,“visible”:true,“hint”:null,“path”:"/0/0",“x”:0,“y”:0,“width”:320,“height”:568},">":[{“UIAScrollView”:{"@":{“name”:null,“label”:null,“value”:null,“dom”:null,“enabled”:true,“valid”:true,“visible”:true,“hint”:null,“path”:"/0/0/0",“x”:0,“y”:0,“width”:320,“height”:568},">":[{“UIAWebView”:{"@":{“name”:null,“label”:null,“value”:null,“dom”:null,“enabled”:true,“valid”:true,“visible”:true,“hint”:null,“path”:"/0/0/0/0",“x”:0,“y”:0,“width”:320,“height”:568},">":[]}},{“UIAImage”:{"@":{“name”:null,“label”:null,“value”:null,“dom”:null,“enabled”:true,“valid”:true,“visible”:false,“hint”:null,“path”:"/0/0/0/1",“x”:316.5,“y”:0,“width”:3.5,“height”:568},">":[]}},{“UIAImage”:{"@":{“name”:null,“label”:null,“value”:null,“dom”:null,“enabled”:true,“valid”:true,“visible”:false,“hint”:null,“path”:"/0/0/0/2",“x”:0,“y”:564.5,“width”:320,“height”:3.5},">":[]}}]}}]}},{“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”:“No SIM”,“label”:“No SIM”,“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”:42,“height”:20},">":[]}},{“UIAElement”:{"@":{“name”:“3 of 3 Wi-Fi bars”,“label”:“3 of 3 Wi-Fi bars”,“value”:“SPAN-GENERAL”,“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”:53,“y”:0,“width”:13,“height”:20},">":[]}},{“UIAElement”:{"@":{“name”:“2:11 pm”,“label”:“2:11 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”:139,“y”:0,“width”:43,“height”:20},">":[]}},{“UIAElement”:{"@":{“name”:“100% battery power, On AC Power”,“label”:“100% battery power, On AC 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”:248,“y”:0,“width”:67,“height”:20},">":[]}}]}}]}}]}}}
2015-03-16 08:41:19 +0000 Debug: Running system command #3: /Applications/Appium.app/Contents/Resources/node/bin/node /Applications/Appium.app/Contents/Resources/node_modules/appium/node_modules/appium-uiauto/bin/command-proxy-client.js /tmp/instruments_sock 2,{“status”:0,“value”:{“UIAApplication”:{"@":{“name”:“AbbVie Care Global”,“label”:"AbbVie Care Global…
info: [debug] Socket data received (3021 bytes)
info: [debug] Socket data being routed.
info: [debug] Got result from instruments: {“status”:0,“value”:{“UIAApplication”:{"@":{“name”:“AbbVie Care Global”,“label”:“AbbVie Care Global”,“value”:null,“dom”:null,“enabled”:true,“valid”:true,“visible”:true,“hint”:null,“path”:"/0",“x”:0,“y”:20,“width”:320,“height”:548},">":[{“UIAWindow”:{"@":{“name”:null,“label”:null,“value”:null,“dom”:n
info: [debug] Device launched! Ready for commands
info: [debug] Setting command timeout to the default of 7200 secs
info: [debug] Appium session started with sessionId 42a64d1f-7d7b-4bb3-98d8-7de4e4637c8b
info: <-- POST /wd/hub/session 303 24650.811 ms - 9
info: --> GET /wd/hub/session/42a64d1f-7d7b-4bb3-98d8-7de4e4637c8b {}
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”:{“browserName”:“iOS”,“appium-version”:“1.3.4”,“platformName”:“iOS”,“platformVersion”:7.1,“deviceName”:“iPhone”,“autoWebview”:true,“count”:1},“appium-version”:“1.3.4”,“platformName”:“iOS”,“platformVersion”:7.1,“deviceName”:“iPhone”,“autoWebview”:true,“count”:1},“sessionId”:“42a64d1f-7d7b-4bb3-98d8-7de4e4637c8b”}
info: <-- GET /wd/hub/session/42a64d1f-7d7b-4bb3-98d8-7de4e4637c8b 200 11.761 ms - 556 {“status”:0,“value”:{“webStorageEnabled”:false,“locationContextEnabled”:false,“browserName”:“iOS”,“platform”:“MAC”,“javascriptEnabled”:true,“databaseEnabled”:false,“takesScreenshot”:true,“networkConnectionEnabled”:false,“warnings”:{},“desired”:{“browserName”:“iOS”,“appium-version”:“1.3.4”,“platformName”:“iOS”,“platformVersion”:7.1,“deviceName”:“iPhone”,“autoWebview”:true,“count”:1},“appium-version”:“1.3.4”,“platformName”:“iOS”,“platformVersion”:7.1,“deviceName”:“iPhone”,“autoWebview”:true,“count”:1},“sessionId”:“42a64d1f-7d7b-4bb3-98d8-7de4e4637c8b”}
info: --> POST /wd/hub/session/42a64d1f-7d7b-4bb3-98d8-7de4e4637c8b/timeouts/async_script {“ms”:30000}
info: [debug] Set iOS async script timeout to 30000ms
info: [debug] Responding to client with success: {“status”:0,“value”:null,“sessionId”:“42a64d1f-7d7b-4bb3-98d8-7de4e4637c8b”}
info: <-- POST /wd/hub/session/42a64d1f-7d7b-4bb3-98d8-7de4e4637c8b/timeouts/async_script 200 6.441 ms - 76 {“status”:0,“value”:null,“sessionId”:“42a64d1f-7d7b-4bb3-98d8-7de4e4637c8b”}
info: --> POST /wd/hub/session/42a64d1f-7d7b-4bb3-98d8-7de4e4637c8b/timeouts/async_script {“ms”:60000}
info: [debug] Set iOS async script timeout to 60000ms
info: [debug] Responding to client with success: {“status”:0,“value”:null,“sessionId”:“42a64d1f-7d7b-4bb3-98d8-7de4e4637c8b”}
info: <-- POST /wd/hub/session/42a64d1f-7d7b-4bb3-98d8-7de4e4637c8b/timeouts/async_script 200 2.048 ms - 76 {“status”:0,“value”:null,“sessionId”:“42a64d1f-7d7b-4bb3-98d8-7de4e4637c8b”}
info: --> POST /wd/hub/session/42a64d1f-7d7b-4bb3-98d8-7de4e4637c8b/execute_async {“script”:“try { return (function (rootSelector, callback) {\n var el = document.querySelector(rootSelector);\n\n try {\n if (!window.angular) {\n throw new Error(‘angular could not be found on the window’);\n }\n if (angular.getTestability) {\n angular.getTestability(el).whenStable(callback);\n } else {\n if (!angular.element(el).injector()) {\n throw new Error(‘root element (’ + rootSelector + ‘) has no injector.’ +\n ’ this may mean it is not inside ng-app.’);\n }\n angular.element(el).injector().get(’$browser’).\n notifyWhenNoOutstandingRequests(callback);\n }\n } catch (err) {\n callback(err.message);\n }\n}).apply(this, arguments); }\ncatch(e) { throw (e instanceof Error) ? e : new Error(e); }”,“args”:[“body”]}
info: [debug] Response url for executeAsync is http://127.0.0.1:4723/wd/hub/session/42a64d1f-7d7b-4bb3-98d8-7de4e4637c8b/receive_async_response
info: [debug] [REMOTE] Executing atom in default context
info: [debug] [REMOTE] Sending javascript command
info: [debug] [REMOTE] Remote debugger data sent [{“method”:“Runtime.evaluate”,“params”:{“objectGroup”:“console”,“includeCommandLineAPI”:true,“doNotPauseOnExceptionsAndMuteConsole”:true,“expression”:"(function(){return function(){var l=this;\nfunctio…]
Debugger web socket received data : {“result”:{“result”:{“type”:“undefined”},“wasThrown”:false},“id”:1}
info: [debug] atom did not return yet, checking to see if we are blocked by an alert
info: [debug] Pushing command to appium work queue: “au.alertIsPresent()”
info: [debug] Sending command to instruments: au.alertIsPresent()
info: [debug] [INST] 2015-03-16 08:41:25 +0000 Debug: Got new command 3 from instruments: au.alertIsPresent()
info: [debug] [INST] 2015-03-16 08:41:25 +0000 Debug: evaluating au.alertIsPresent()
info: [debug] [INST] 2015-03-16 08:41:25 +0000 Debug: evaluation finished
2015-03-16 08:41:25 +0000 Debug: responding with:
info: [debug] [INST] 2015-03-16 08:41:25 +0000 Debug: Running system command #4: /Applications/Appium.app/Contents/Resources/node/bin/node /Applications/Appium.app/Contents/Resources/node_modules/appium/node_modules/appium-uiauto/bin/command-proxy-client.js /tmp/instruments_sock 2,{“status”:0,“value”:false}…
info: [debug] Socket data received (28 bytes)
info: [debug] Socket data being routed.
info: [debug] Got result from instruments: {“status”:0,“value”:false}
info: [debug] atom did not return yet, checking to see if we are blocked by an alert
info: [debug] Pushing command to appium work queue: “au.alertIsPresent()”
info: [debug] Sending command to instruments: au.alertIsPresent()
info: [debug] [INST] 2015-03-16 08:41:26 +0000 Debug: Got new command 4 from instruments: au.alertIsPresent()
info: [debug] [INST] 2015-03-16 08:41:26 +0000 Debug: evaluating au.alertIsPresent()
info: [debug] [INST] 2015-03-16 08:41:26 +0000 Debug: evaluation finished
info: [debug] [INST] 2015-03-16 08:41:26 +0000 Debug: responding with:
2015-03-16 08:41:26 +0000 Debug: Running system command #5: /Applications/Appium.app/Contents/Resources/node/bin/node /Applications/Appium.app/Contents/Resources/node_modules/appium/node_modules/appium-uiauto/bin/command-proxy-client.js /tmp/instruments_sock 2,{“status”:0,“value”:false}…
info: [debug] Socket data received (28 bytes)
info: [debug] Socket data being routed.
info: [debug] Got result from instruments: {“status”:0,“value”:false}
info: [debug] atom did not return yet, checking to see if we are blocked by an alert
info: [debug] Pushing command to appium work queue: “au.alertIsPresent()”
info: [debug] Sending command to instruments: au.alertIsPresent()
info: [debug] [INST] 2015-03-16 08:41:27 +0000 Debug: Got new command 5 from instruments: au.alertIsPresent()
info: [debug] [INST] 2015-03-16 08:41:27 +0000 Debug: evaluating au.alertIsPresent()
info: [debug] [INST] 2015-03-16 08:41:27 +0000 Debug: evaluation finished
info: [debug] [INST] 2015-03-16 08:41:27 +0000 Debug: responding with:
info: [debug] [INST] 2015-03-16 08:41:27 +0000 Debug: Running system command #6: /Applications/Appium.app/Contents/Resources/node/bin/node /Applications/Appium.app/Contents/Resources/node_modules/appium/node_modules/appium-uiauto/bin/command-proxy-client.js /tmp/instruments_sock 2,{“status”:0,“value”:false}…
info: [debug] Socket data received (28 bytes)
info: [debug] Socket data being routed.
info: [debug] Got result from instruments: {“status”:0,“value”:false}
info: [debug] atom did not return yet, checking to see if we are blocked by an alert
info: [debug] Pushing command to appium work queue: “au.alertIsPresent()”
info: [debug] Sending command to instruments: au.alertIsPresent()
info: [debug] [INST] 2015-03-16 08:41:28 +0000 Debug: Got new command 6 from instruments: au.alertIsPresent()
info: [debug] [INST] 2015-03-16 08:41:28 +0000 Debug: evaluating au.alertIsPresent()
info: [debug] [INST] 2015-03-16 08:41:28 +0000 Debug: evaluation finished
2015-03-16 08:41:28 +0000 Debug: responding with:
2015-03-16 08:41:28 +0000 Debug: Running system command #7: /Applications/Appium.app/Contents/Resources/node/bin/node /Applications/Appium.app/Contents/Resources/node_modules/appium/node_modules/appium-uiauto/bin/command-proxy-client.js /tmp/instruments_sock 2,{“status”:0,“value”:false}…
info: [debug] Socket data received (28 bytes)
info: [debug] Socket data being routed.
info: [debug] Got result from instruments: {“status”:0,“value”:false}
info: [debug] atom did not return yet, checking to see if we are blocked by an alert
info: [debug] Pushing command to appium work queue: “au.alertIsPresent()”
info: [debug] Sending command to instruments: au.alertIsPresent()
info: [debug] [INST] 2015-03-16 08:41:29 +0000 Debug: Got new command 7 from instruments: au.alertIsPresent()
info: [debug] [INST] 2015-03-16 08:41:29 +0000 Debug: evaluating au.alertIsPresent()
info: [debug] [INST] 2015-03-16 08:41:29 +0000 Debug: evaluation finished
2015-03-16 08:41:29 +0000 Debug: responding with:
2015-03-16 08:41:29 +0000 Debug: Running system command #8: /Applications/Appium.app/Contents/Resources/node/bin/node /Applications/Appium.app/Contents/Resources/node_modules/appium/node_modules/appium-uiauto/bin/command-proxy-client.js /tmp/instruments_sock 2,{“status”:0,“value”:false}…
info: [debug] Socket data received (28 bytes)
info: [debug] Socket data being routed.
info: [debug] Got result from instruments: {“status”:0,“value”:false}
info: [debug] atom did not return yet, checking to see if we are blocked by an alert
info: [debug] Pushing command to appium work queue: “au.alertIsPresent()”
info: [debug] Sending command to instruments: au.alertIsPresent()
info: [debug] [INST] 2015-03-16 08:41:30 +0000 Debug: Got new command 8 from instruments: au.alertIsPresent()
info: [debug] [INST] 2015-03-16 08:41:30 +0000 Debug: evaluating au.alertIsPresent()
info: [debug] [INST] 2015-03-16 08:41:30 +0000 Debug: evaluation finished
2015-03-16 08:41:30 +0000 Debug: responding with:
info: [debug] [INST] 2015-03-16 08:41:30 +0000 Debug: Running system command #9: /Applications/Appium.app/Contents/Resources/node/bin/node /Applications/Appium.app/Contents/Resources/node_modules/appium/node_modules/appium-uiauto/bin/command-proxy-client.js /tmp/instruments_sock 2,{“status”:0,“value”:false}…
info: [debug] Socket data received (28 bytes)
info: [debug] Socket data being routed.
info: [debug] Got result from instruments: {“status”:0,“value”:false}
info: [debug] atom did not return yet, checking to see if we are blocked by an alert
info: [debug] Pushing command to appium work queue: “au.alertIsPresent()”
info: [debug] Sending command to instruments: au.alertIsPresent()
info: [debug] [INST] 2015-03-16 08:41:31 +0000 Debug: Got new command 9 from instruments: au.alertIsPresent()
info: [debug] [INST] 2015-03-16 08:41:31 +0000 Debug: evaluating au.alertIsPresent()
info: [debug] [INST] 2015-03-16 08:41:31 +0000 Debug: evaluation finished
2015-03-16 08:41:31 +0000 Debug: responding with:
info: [debug] [INST] 2015-03-16 08:41:31 +0000 Debug: Running system command #10: /Applications/Appium.app/Contents/Resources/node/bin/node /Applications/Appium.app/Contents/Resources/node_modules/appium/node_modules/appium-uiauto/bin/command-proxy-client.js /tmp/instruments_sock 2,{“status”:0,“value”:false}…
info: [debug] Socket data received (28 bytes)
info: [debug] Socket data being routed.
info: [debug] Got result from instruments: {“status”:0,“value”:false}
info: [debug] atom did not return yet, checking to see if we are blocked by an alert
info: [debug] Pushing command to appium work queue: “au.alertIsPresent()”
info: [debug] Sending command to instruments: au.alertIsPresent()
info: [debug] [INST] 2015-03-16 08:41:32 +0000 Debug: Got new command 10 from instruments: au.alertIsPresent()
info: [debug] [INST] 2015-03-16 08:41:32 +0000 Debug: evaluating au.alertIsPresent()
info: [debug] [INST] 2015-03-16 08:41:32 +0000 Debug: evaluation finished
info: [debug] [INST] 2015-03-16 08:41:32 +0000 Debug: responding with:
info: [debug] [INST] 2015-03-16 08:41:32 +0000 Debug: Running system command #11: /Applications/Appium.app/Contents/Resources/node/bin/node /Applications/Appium.app/Contents/Resources/node_modules/appium/node_modules/appium-uiauto/bin/command-proxy-client.js /tmp/instruments_sock 2,{“status”:0,“value”:false}…
info: [debug] Socket data received (28 bytes)
info: [debug] Socket data being routed.
info: [debug] Got result from instruments: {“status”:0,“value”:false}
info: [debug] atom did not return yet, checking to see if we are blocked by an alert
info: [debug] Pushing command to appium work queue: “au.alertIsPresent()”
info: [debug] Sending command to instruments: au.alertIsPresent()
info: [debug] [INST] 2015-03-16 08:41:33 +0000 Debug: Got new command 11 from instruments: au.alertIsPresent()
info: [debug] [INST] 2015-03-16 08:41:33 +0000 Debug: evaluating au.alertIsPresent()
2015-03-16 08:41:33 +0000 Debug: evaluation finished
2015-03-16 08:41:33 +0000 Debug: responding with:
2015-03-16 08:41:33 +0000 Debug: Running system command #12: /Applications/Appium.app/Contents/Resources/node/bin/node /Applications/Appium.app/Contents/Resources/node_modules/appium/node_modules/appium-uiauto/bin/command-proxy-client.js /tmp/instruments_sock 2,{“status”:0,“value”:false}…
info: [debug] Socket data received (28 bytes)
info: [debug] Socket data being routed.
info: [debug] Got result from instruments: {“status”:0,“value”:false}
info: [debug] atom did not return yet, checking to see if we are blocked by an alert
info: [debug] Pushing command to appium work queue: “au.alertIsPresent()”
info: [debug] Sending command to instruments: au.alertIsPresent()
info: [debug] [INST] 2015-03-16 08:41:34 +0000 Debug: Got new command 12 from instruments: au.alertIsPresent()
info: [debug] [INST] 2015-03-16 08:41:34 +0000 Debug: evaluating au.alertIsPresent()
info: [debug] [INST] 2015-03-16 08:41:34 +0000 Debug: evaluation finished
info: [debug] [INST] 2015-03-16 08:41:34 +0000 Debug: responding with:
2015-03-16 08:41:34 +0000 Debug: Running system command #13: /Applications/Appium.app/Contents/Resources/node/bin/node /Applications/Appium.app/Contents/Resources/node_modules/appium/node_modules/appium-uiauto/bin/command-proxy-client.js /tmp/instruments_sock 2,{“status”:0,“value”:false}…
info: [debug] Socket data received (28 bytes)
info: [debug] Socket data being routed.
info: [debug] Got result from instruments: {“status”:0,“value”:false}
info: [debug] atom did not return yet, checking to see if we are blocked by an alert
info: [debug] Pushing command to appium work queue: “au.alertIsPresent()”
info: [debug] Sending command to instruments: au.alertIsPresent()
info: [debug] [INST] 2015-03-16 08:41:35 +0000 Debug: Got new command 13 from instruments: au.alertIsPresent()
info: [debug] [INST] 2015-03-16 08:41:35 +0000 Debug: evaluating au.alertIsPresent()
info: [debug] [INST] 2015-03-16 08:41:35 +0000 Debug: evaluation finished
2015-03-16 08:41:35 +0000 Debug: responding with:
info: [debug] [INST] 2015-03-16 08:41:35 +0000 Debug: Running system command #14: /Applications/Appium.app/Contents/Resources/node/bin/node /Applications/Appium.app/Contents/Resources/node_modules/appium/node_modules/appium-uiauto/bin/command-proxy-client.js /tmp/instruments_sock 2,{“status”:0,“value”:false}…
info: [debug] Socket data received (28 bytes)
info: [debug] Socket data being routed.
info: [debug] Got result from instruments: {“status”:0,“value”:false}
info: --> GET /wd/hub/status {}
info: [debug] Responding to client with success: {“status”:0,“value”:{“build”:{“version”:“1.3.6”,“revision”:“004f52f249d3513809e7d0734d9205d1fec19f8e”},“isShuttingDown”:false},“sessionId”:“42a64d1f-7d7b-4bb3-98d8-7de4e4637c8b”}
info: <-- GET /wd/hub/status 200 4.174 ms - 178 {“status”:0,“value”:{“build”:{“version”:“1.3.6”,“revision”:“004f52f249d3513809e7d0734d9205d1fec19f8e”},“isShuttingDown”:false},“sessionId”:“42a64d1f-7d7b-4bb3-98d8-7de4e4637c8b”}
info: --> DELETE /wd/hub/session/42a64d1f-7d7b-4bb3-98d8-7de4e4637c8b {}
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-03-16 08:43:22 +0000 Stopped: Script was stopped by the user
info: [IOS_SYSLOG_ROW ] Mar 16 14:12:29 iPhone com.apple.launchd[1] (UIKitApplication:com.span.spansoftphone[0xa677][276]) : (UIKitApplication:com.span.spansoftphone[0xa677]) Exited: Killed: 9
info: [IOS_SYSLOG_ROW ] Mar 16 14:12:29 iPhone kernel[0] : 009248.094430 wlan.A[296] AppleBCMWLANNetManager::checkRealTimeTraffic(): now 9248.094415875 num entries 4
info: [IOS_SYSLOG_ROW ] Mar 16 14:12:29 iPhone kernel[0] : 009248.094457 wlan.A[297] AppleBCMWLANCore::dumpWmeCounters(): per TIDs tx counters: 869 65 0 0 0 0 62 0, per TIDs rx counters: 23213 136771 0 0 0 1651 1190 0
info: [IOS_SYSLOG_ROW ] Mar 16 14:12:29 iPhone kernel[0] : 009248.094475 wlan.A[298] AppleBCMWLANCore::dumpWmeCounters(): AWDL: Tx 0 0 0 0 0 0 0 0, Rx: 0 0 0 0 0 0 0 0
info: [IOS_SYSLOG_ROW ] Mar 16 14:12:29 iPhone backboardd[31] : Application ‘UIKitApplication:com.span.spansoftphone[0xa677]’ exited abnormally with signal 9: Killed: 9
info: [debug] [INST] Instruments Trace Complete (Duration : 82.421288s; 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 iPhoneSimulator
info: [debug] Killing any other simulator daemons
info: [debug] [REMOTE] Disconnecting from remote debugger
info: [debug] On a real device; cannot clean device state
info: [debug] Cleaning up appium session
info: [debug] Responding to client with success: {“status”:0,“value”:null,“sessionId”:“42a64d1f-7d7b-4bb3-98d8-7de4e4637c8b”}
info: <-- DELETE /wd/hub/session/42a64d1f-7d7b-4bb3-98d8-7de4e4637c8b 200 820.328 ms - 76 {“status”:0,“value”:null,“sessionId”:“42a64d1f-7d7b-4bb3-98d8-7de4e4637c8b”}
info: <-- POST /wd/hub/session/42a64d1f-7d7b-4bb3-98d8-7de4e4637c8b/execute_async - - ms - -
undefined
Protractor Logs:
Using the selenium server at http://localhost:4723/wd/hub
[launcher] Running 1 instances of WebDriver
A Jasmine spec timed out. Resetting the WebDriver Control Flow.
The last active task was:
Protractor.waitForAngular()
at [object Object].webdriver.WebDriver.schedule (/usr/local/lib/node_modules/protractor/node_modules/selenium-webdriver/lib/webdriver/webdriver.js:345:15)
at [object Object].Protractor.executeAsyncScript_ (/usr/local/lib/node_modules/protractor/lib/protractor.js:251:26)
at [object Object].Protractor.waitForAngular (/usr/local/lib/node_modules/protractor/lib/protractor.js:274:15)
at [object Object].getWebElements (/usr/local/lib/node_modules/protractor/lib/element.js:136:19)
at [object Object].getWebElements (/usr/local/lib/node_modules/protractor/lib/element.js:669:31)
at [object Object].ElementArrayFinder.applyAction_ (/usr/local/lib/node_modules/protractor/lib/element.js:391:28)
at [object Object].self.(anonymous function) [as click] (/usr/local/lib/node_modules/protractor/lib/element.js:77:19)
at [object Object].self.(anonymous function) [as click] (/usr/local/lib/node_modules/protractor/lib/element.js:698:11)
at [object Object]. (/Users/qualcomm/Desktop/ProtractorTest/PageObjectSpec.js:13:11)
F
Failures:
- Abbvie app test Login Page test
Message:
timeout: timed out after 60000 msec waiting for spec to complete
Stacktrace:
undefined
Finished in 68.803 seconds
1 test, 1 assertion, 1 failure
[launcher] 0 instance(s) of WebDriver still running
[launcher] iOS #1 failed 1 test(s)
[launcher] overall: 1 failed spec(s)
[launcher] Process exited with error code 1