appium_server_1.1.0.log

info: Welcome to Appium v1.1.0 (REV e433bbc31511f199287db7724e1ce692bcb32117)
info: Appium REST http interface listener started on 0.0.0.0:6001
info: socket.io started
info: Non-default server args: {"udid":"HT26HW300907","port":6001,"bootstrapPort":4001,"log":"/home/testuser/appium_server_Alice.log","selendroidPort":9001}
debug: Appium request initiated at /wd/hub/session
debug: Request received with params: {"desiredCapabilities":{"automationName":"Selendroid","platformVersion":"4.4.2","app":"/home/testuser/Java_Projects/Alice/target/test-classes/en-android.apk","platformName":"Android","deviceName":"Android","appActivity":"com.test.app.android.gui.WelcomeActivity","appium-version":"1.2.0","name":"TestApp Automation","appPackage":"com.test.app.android"}}
info: Using local app from desired caps: /home/testuser/Java_Projects/Alice/target/test-classes/en-android.apk
info: Creating new appium session 51d8ac75-0bbc-4c51-bb66-4382aa630b94
info: Starting selendroid server
info: Checking whether selendroid is built yet
info: Selendroid server exists!
info: Preparing device for session
info: Checking whether app is actually present
info: Checking whether adb is present
info: [ADB] Using adb from /opt/android-sdk-linux/platform-tools/adb
info: Trying to find a connected android device
info: [ADB] Getting connected devices...
debug: executing: "/opt/android-sdk-linux/platform-tools/adb" devices
info: [ADB] 1 device(s) connected
info: Setting device id to HT26HW300907
info: [ADB] Waiting for device to be ready and to respond to shell commands (timeout = 5)
debug: executing: "/opt/android-sdk-linux/platform-tools/adb" -s HT26HW300907 wait-for-device
debug: executing: "/opt/android-sdk-linux/platform-tools/adb" -s HT26HW300907 shell "echo 'ready'"
info: Starting logcat capture
info: Rebuilt selendroid apk exists, doing nothing
info: Rebuilt selendroid server already exists, no need to rebuild it with a new manifest
info: Checking signed status of /tmp/selendroid.com.test.app.android.apk
debug: Checking app cert for /tmp/selendroid.com.test.app.android.apk: java -jar "/home/testuser/.linuxbrew/lib/node_modules/appium/lib/devices/android/helpers/verify.jar" "/tmp/selendroid.com.test.app.android.apk"
info: Checking signed status of /home/testuser/Java_Projects/Alice/target/test-classes/en-android.apk
debug: Checking app cert for /home/testuser/Java_Projects/Alice/target/test-classes/en-android.apk: java -jar "/home/testuser/.linuxbrew/lib/node_modules/appium/lib/devices/android/helpers/verify.jar" "/home/testuser/Java_Projects/Alice/target/test-classes/en-android.apk"
debug: App not signed with debug cert.
debug: Resigning apk with: java -jar "/home/testuser/.linuxbrew/lib/node_modules/appium/lib/devices/android/helpers/sign.jar" "/home/testuser/Java_Projects/Alice/target/test-classes/en-android.apk" --override
debug: App already signed.
info: Zip-aligning /tmp/selendroid.com.test.app.android.apk
info: Checking whether zipalign is present
info: [ADB] Using zipalign from /opt/android-sdk-linux/build-tools/17.0.0/zipalign
debug: zipAlignApk: "/opt/android-sdk-linux/build-tools/17.0.0/zipalign" -f 4 "/tmp/selendroid.com.test.app.android.apk" "/tmp/appium11475-5491-1b1q92i.tmp"
info: Zip-aligning /home/testuser/Java_Projects/Alice/target/test-classes/en-android.apk
info: Checking whether zipalign is present
info: [ADB] Using zipalign from /opt/android-sdk-linux/build-tools/17.0.0/zipalign
debug: zipAlignApk: "/opt/android-sdk-linux/build-tools/17.0.0/zipalign" -f 4 "/home/testuser/Java_Projects/Alice/target/test-classes/en-android.apk" "/tmp/appium11475-5491-1ep2m29.tmp"
debug: Getting install status for com.test.app.android.selendroid
debug: executing: "/opt/android-sdk-linux/platform-tools/adb" -s HT26HW300907 shell "pm list packages -3 com.test.app.android.selendroid"
info: [ADB] App is installed
info: Rebuilt selendroid is already installed
debug: executing: "/opt/android-sdk-linux/platform-tools/adb" -s HT26HW300907 shell "getprop persist.sys.language"
debug: Current device language: en
debug: java -jar "/home/testuser/.linuxbrew/lib/node_modules/appium/lib/devices/android/helpers/strings_from_apk.jar" "/home/testuser/Java_Projects/Alice/target/test-classes/en-android.apk" "/tmp/com.test.app.android" en
info: No strings.xml for language 'en', getting default strings.xml
debug: java -jar "/home/testuser/.linuxbrew/lib/node_modules/appium/lib/devices/android/helpers/strings_from_apk.jar" "/home/testuser/Java_Projects/Alice/target/test-classes/en-android.apk" "/tmp/com.test.app.android"
debug: Not uninstalling app since server not started with --full-reset
debug: Checking app cert for /home/testuser/Java_Projects/Alice/target/test-classes/en-android.apk: java -jar "/home/testuser/.linuxbrew/lib/node_modules/appium/lib/devices/android/helpers/verify.jar" "/home/testuser/Java_Projects/Alice/target/test-classes/en-android.apk"
debug: App already signed.
info: Zip-aligning /home/testuser/Java_Projects/Alice/target/test-classes/en-android.apk
info: Checking whether zipalign is present
info: [ADB] Using zipalign from /opt/android-sdk-linux/build-tools/17.0.0/zipalign
debug: zipAlignApk: "/opt/android-sdk-linux/build-tools/17.0.0/zipalign" -f 4 "/home/testuser/Java_Projects/Alice/target/test-classes/en-android.apk" "/tmp/appium11475-5491-hwvkpp.tmp"
info: MD5 for app is a3771bb9f1b6e3bbc919edb73b16dfb1
debug: executing: "/opt/android-sdk-linux/platform-tools/adb" -s HT26HW300907 shell "ls /data/local/tmp/a3771bb9f1b6e3bbc919edb73b16dfb1.apk"
debug: Getting install status for com.test.app.android
debug: executing: "/opt/android-sdk-linux/platform-tools/adb" -s HT26HW300907 shell "pm list packages -3 com.test.app.android"
info: [ADB] App is installed
info: Running fast reset (stop and clear)
debug: executing: "/opt/android-sdk-linux/platform-tools/adb" -s HT26HW300907 shell "am force-stop com.test.app.android"
debug: executing: "/opt/android-sdk-linux/platform-tools/adb" -s HT26HW300907 shell "pm clear com.test.app.android"
info: [ADB] Forwarding system:9001 to device:8080
debug: executing: "/opt/android-sdk-linux/platform-tools/adb" -s HT26HW300907 forward tcp:9001 tcp:8080
debug: Pushing unlock helper app to device...
debug: executing: "/opt/android-sdk-linux/platform-tools/adb" -s HT26HW300907 install "/home/testuser/.linuxbrew/lib/node_modules/appium/build/unlock_apk/unlock_apk-debug.apk"
debug: executing: "/opt/android-sdk-linux/platform-tools/adb" -s HT26HW300907 shell "dumpsys window"
info: Writing dumpsys output to /home/testuser/.linuxbrew/lib/node_modules/appium/.dumpsys.log
debug: Screen already unlocked, continuing.
debug: executing: "/opt/android-sdk-linux/platform-tools/adb" -s HT26HW300907 shell "am instrument -e main_activity 'com.test.app.android.gui.WelcomeActivity' com.test.app.android.selendroid/io.selendroid.ServerInstrumentation"
info: Making http request with opts: {"url":"http://localhost:9001/wd/hub/status","method":"GET"}
info: Making http request with opts: {"url":"http://localhost:9001/wd/hub/status","method":"GET"}
info: Making http request with opts: {"url":"http://localhost:9001/wd/hub/status","method":"GET"}
info: Making http request with opts: {"url":"http://localhost:9001/wd/hub/status","method":"GET"}
info: Selendroid server is alive!
info: Listening for Selendroid logs
info: Creating Selendroid session
info: Making http request with opts: {"url":"http://localhost:9001/wd/hub/session","method":"POST","json":{"desiredCapabilities":{"warnings":{},"desired":{"automationName":"Selendroid","platformVersion":"4.4.2","app":"/home/testuser/Java_Projects/Alice/target/test-classes/en-android.apk","platformName":"Android","deviceName":"Android","appActivity":"com.test.app.android.gui.WelcomeActivity","appium-version":"1.2.0","name":"TestApp Automation","appPackage":"com.test.app.android"},"automationName":"Selendroid","platformVersion":"4.4.2","app":"/home/testuser/Java_Projects/Alice/target/test-classes/en-android.apk","platformName":"Android","deviceName":"Android","appActivity":"com.test.app.android.gui.WelcomeActivity","appium-version":"1.2.0","name":"TestApp Automation","appPackage":"com.test.app.android"}}}
info: Successfully started selendroid session
info: Waiting for pkg "com.test.app.android" and activity "com.test.app.android.gui.WelcomeActivity" to be focused
info: Getting focused package and activity
debug: executing: "/opt/android-sdk-linux/platform-tools/adb" -s HT26HW300907 shell "dumpsys window windows"
info: [SELENDROID] http://localhost:9001/wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b
info: Overriding session id with "ea2154af-3a85-ed67-2142-d7093b1f4a7b"
info: Device launched! Ready for commands
info: Setting command timeout to the default of 60 secs
info: Appium session started with sessionId ea2154af-3a85-ed67-2142-d7093b1f4a7b
debug: 127.0.0.1 - - "POST /wd/hub/session HTTP/1.1" 303 9 "-" "Apache-HttpClient/4.4-alpha1 (Java 1.5 minimum; Java/1.7.0_65)"
debug: Appium request initiated at /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b
debug: Request received with params: {}
debug: Proxying command to localhost:9001
info: Making http request with opts: {"url":"http://localhost:9001/wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b","method":"GET"}
debug: Proxied response received with status 200: "{\"value\":{\"automationName\":\"selendroid\",\"platform\":\"android\",\"platformVersion\":\"15\",\"acceptSslCerts\":true,\"javascriptEnabled\":true,\"platformName\":\"android\",\"handlesAlerts\":true,\"browserName\":\"selendroid\",\"networkConnectionEnabled\":true,\"rotatable\":true,\"takesScreenshot\":true,\"version\":\"0.10.0\"},\"status\":0,\"sessionId\":\"ea2154af-3a85-ed67-2142-d7093b1f4a7b\"}"
debug: 127.0.0.1 - - "GET /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b HTTP/1.1" 200 358 "-" "Apache-HttpClient/4.4-alpha1 (Java 1.5 minimum; Java/1.7.0_65)"
debug: Appium request initiated at /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element
debug: Request received with params: {"using":"id","value":"buttonGetStarted"}
debug: Proxying command to localhost:9001
info: Making http request with opts: {"url":"http://localhost:9001/wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element","method":"POST","json":{"using":"id","value":"buttonGetStarted"}}
debug: Proxied response received with status 200: {"value":{"ELEMENT":"60b7f581-ce4f-6549-dc12-5dcdb03be948"},"status":0,"sessionId":"ea2154af-3a85-ed67-2142-d7093b1f4a7b"}
debug: 127.0.0.1 - - "POST /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element HTTP/1.1" 200 144 "-" "Apache-HttpClient/4.4-alpha1 (Java 1.5 minimum; Java/1.7.0_65)"
debug: Appium request initiated at /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element/60b7f581-ce4f-6549-dc12-5dcdb03be948/click
debug: Request received with params: {"id":"60b7f581-ce4f-6549-dc12-5dcdb03be948"}
debug: Proxying command to localhost:9001
info: Making http request with opts: {"url":"http://localhost:9001/wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element/60b7f581-ce4f-6549-dc12-5dcdb03be948/click","method":"POST","json":{"id":"60b7f581-ce4f-6549-dc12-5dcdb03be948"}}
debug: Proxied response received with status 200: {"value":"","status":0,"sessionId":"ea2154af-3a85-ed67-2142-d7093b1f4a7b"}
debug: 127.0.0.1 - - "POST /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element/60b7f581-ce4f-6549-dc12-5dcdb03be948/click HTTP/1.1" 200 87 "-" "Apache-HttpClient/4.4-alpha1 (Java 1.5 minimum; Java/1.7.0_65)"
debug: Appium request initiated at /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element
debug: Request received with params: {"using":"id","value":"passwordField"}
debug: Proxying command to localhost:9001
info: Making http request with opts: {"url":"http://localhost:9001/wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element","method":"POST","json":{"using":"id","value":"passwordField"}}
info: Responding to client with error: {"status":7,"value":{"message":"An element could not be located on the page using the given search parameters.","origValue":"Element was not found.\nio.selendroid.exceptions.NoSuchElementException: Element was not found.\n\tat io.selendroid.server.handler.FindElement.handle(FindElement.java:57)\n\tat io.selendroid.server.AndroidServlet.handleRequest(AndroidServlet.java:290)\n\tat io.selendroid.server.BaseServlet.handleHttpRequest(BaseServlet.java:70)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:78)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:62)\n\tat io.selendroid.server.inspector.InspectorServlet.handleHttpRequest(InspectorServlet.java:78)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:78)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:62)\n\tat org.webbitserver.handler.PathMatchHandler.handleHttpRequest(PathMatchHandler.java:33)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:78)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:62)\n\tat org.webbitserver.handler.DateHeaderHandler.handleHttpRequest(DateHeaderHandler.java:21)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:78)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:62)\n\tat org.webbitserver.handler.ServerHeaderHandler.handleHttpRequest(ServerHeaderHandler.java:25)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:78)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:67)\n\tat org.webbitserver.netty.NettyHttpChannelHandler$2.run(NettyHttpChannelHandler.java:72)\n\tat java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1076)\n\tat java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:569)\n\tat java.lang.Thread.run(Thread.java:864)\n"},"sessionId":"ea2154af-3a85-ed67-2142-d7093b1f4a7b"}
debug: 127.0.0.1 - - "POST /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element HTTP/1.1" 500 2068 "-" "Apache-HttpClient/4.4-alpha1 (Java 1.5 minimum; Java/1.7.0_65)"
debug: Appium request initiated at /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element
debug: Request received with params: {"using":"id","value":"passwordSetupPasswordField"}
debug: Proxying command to localhost:9001
info: Making http request with opts: {"url":"http://localhost:9001/wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element","method":"POST","json":{"using":"id","value":"passwordSetupPasswordField"}}
debug: Proxied response received with status 200: {"value":{"ELEMENT":"3b74590a-d4e9-7606-63b1-6c45f9e189f2"},"status":0,"sessionId":"ea2154af-3a85-ed67-2142-d7093b1f4a7b"}
debug: 127.0.0.1 - - "POST /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element HTTP/1.1" 200 144 "-" "Apache-HttpClient/4.4-alpha1 (Java 1.5 minimum; Java/1.7.0_65)"
debug: Appium request initiated at /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element/3b74590a-d4e9-7606-63b1-6c45f9e189f2/value
debug: Request received with params: {"id":"3b74590a-d4e9-7606-63b1-6c45f9e189f2","value":["abc"]}
debug: Proxying command to localhost:9001
info: Making http request with opts: {"url":"http://localhost:9001/wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element/3b74590a-d4e9-7606-63b1-6c45f9e189f2/value","method":"POST","json":{"id":"3b74590a-d4e9-7606-63b1-6c45f9e189f2","value":["abc"]}}
debug: Proxied response received with status 200: {"value":"","status":0,"sessionId":"ea2154af-3a85-ed67-2142-d7093b1f4a7b"}
debug: 127.0.0.1 - - "POST /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element/3b74590a-d4e9-7606-63b1-6c45f9e189f2/value HTTP/1.1" 200 87 "-" "Apache-HttpClient/4.4-alpha1 (Java 1.5 minimum; Java/1.7.0_65)"
debug: Appium request initiated at /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element
debug: Request received with params: {"using":"id","value":"passwordSetupConfirmPasswordField"}
debug: Proxying command to localhost:9001
info: Making http request with opts: {"url":"http://localhost:9001/wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element","method":"POST","json":{"using":"id","value":"passwordSetupConfirmPasswordField"}}
debug: Proxied response received with status 200: {"value":{"ELEMENT":"dc5ef531-07c2-6342-5720-55f4dd03f394"},"status":0,"sessionId":"ea2154af-3a85-ed67-2142-d7093b1f4a7b"}
debug: 127.0.0.1 - - "POST /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element HTTP/1.1" 200 144 "-" "Apache-HttpClient/4.4-alpha1 (Java 1.5 minimum; Java/1.7.0_65)"
debug: Appium request initiated at /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element/dc5ef531-07c2-6342-5720-55f4dd03f394/value
debug: Request received with params: {"id":"dc5ef531-07c2-6342-5720-55f4dd03f394","value":["abc"]}
debug: Proxying command to localhost:9001
info: Making http request with opts: {"url":"http://localhost:9001/wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element/dc5ef531-07c2-6342-5720-55f4dd03f394/value","method":"POST","json":{"id":"dc5ef531-07c2-6342-5720-55f4dd03f394","value":["abc"]}}
debug: Proxied response received with status 200: {"value":"","status":0,"sessionId":"ea2154af-3a85-ed67-2142-d7093b1f4a7b"}
debug: 127.0.0.1 - - "POST /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element/dc5ef531-07c2-6342-5720-55f4dd03f394/value HTTP/1.1" 200 87 "-" "Apache-HttpClient/4.4-alpha1 (Java 1.5 minimum; Java/1.7.0_65)"
debug: Appium request initiated at /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element
debug: Request received with params: {"using":"id","value":"passwordSetupButton"}
debug: Proxying command to localhost:9001
info: Making http request with opts: {"url":"http://localhost:9001/wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element","method":"POST","json":{"using":"id","value":"passwordSetupButton"}}
debug: Proxied response received with status 200: {"value":{"ELEMENT":"25d66942-3690-6251-06e7-d3d69ee97e12"},"status":0,"sessionId":"ea2154af-3a85-ed67-2142-d7093b1f4a7b"}
debug: 127.0.0.1 - - "POST /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element HTTP/1.1" 200 144 "-" "Apache-HttpClient/4.4-alpha1 (Java 1.5 minimum; Java/1.7.0_65)"
debug: Appium request initiated at /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element/25d66942-3690-6251-06e7-d3d69ee97e12/click
debug: Request received with params: {"id":"25d66942-3690-6251-06e7-d3d69ee97e12"}
debug: Proxying command to localhost:9001
info: Making http request with opts: {"url":"http://localhost:9001/wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element/25d66942-3690-6251-06e7-d3d69ee97e12/click","method":"POST","json":{"id":"25d66942-3690-6251-06e7-d3d69ee97e12"}}
debug: Proxied response received with status 200: {"value":"","status":0,"sessionId":"ea2154af-3a85-ed67-2142-d7093b1f4a7b"}
debug: 127.0.0.1 - - "POST /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element/25d66942-3690-6251-06e7-d3d69ee97e12/click HTTP/1.1" 200 87 "-" "Apache-HttpClient/4.4-alpha1 (Java 1.5 minimum; Java/1.7.0_65)"
debug: Appium request initiated at /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element
debug: Request received with params: {"using":"id","value":"alertTitle"}
debug: Proxying command to localhost:9001
info: Making http request with opts: {"url":"http://localhost:9001/wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element","method":"POST","json":{"using":"id","value":"alertTitle"}}
debug: Proxied response received with status 200: {"value":{"ELEMENT":"dd91c659-0820-5d38-7788-2cc0140a31c9"},"status":0,"sessionId":"ea2154af-3a85-ed67-2142-d7093b1f4a7b"}
debug: 127.0.0.1 - - "POST /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element HTTP/1.1" 200 144 "-" "Apache-HttpClient/4.4-alpha1 (Java 1.5 minimum; Java/1.7.0_65)"
debug: Appium request initiated at /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element/dd91c659-0820-5d38-7788-2cc0140a31c9/displayed
debug: Request received with params: {}
debug: Proxying command to localhost:9001
info: Making http request with opts: {"url":"http://localhost:9001/wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element/dd91c659-0820-5d38-7788-2cc0140a31c9/displayed","method":"GET"}
debug: Proxied response received with status 200: "{\"value\":true,\"status\":0,\"sessionId\":\"ea2154af-3a85-ed67-2142-d7093b1f4a7b\"}"
debug: 127.0.0.1 - - "GET /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element/dd91c659-0820-5d38-7788-2cc0140a31c9/displayed HTTP/1.1" 200 76 "-" "Apache-HttpClient/4.4-alpha1 (Java 1.5 minimum; Java/1.7.0_65)"
debug: Appium request initiated at /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element
debug: Request received with params: {"using":"id","value":"alertTitle"}
debug: Proxying command to localhost:9001
info: Making http request with opts: {"url":"http://localhost:9001/wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element","method":"POST","json":{"using":"id","value":"alertTitle"}}
debug: Proxied response received with status 200: {"value":{"ELEMENT":"dd91c659-0820-5d38-7788-2cc0140a31c9"},"status":0,"sessionId":"ea2154af-3a85-ed67-2142-d7093b1f4a7b"}
debug: 127.0.0.1 - - "POST /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element HTTP/1.1" 200 144 "-" "Apache-HttpClient/4.4-alpha1 (Java 1.5 minimum; Java/1.7.0_65)"
debug: Appium request initiated at /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element/dd91c659-0820-5d38-7788-2cc0140a31c9/displayed
debug: Request received with params: {}
debug: Proxying command to localhost:9001
info: Making http request with opts: {"url":"http://localhost:9001/wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element/dd91c659-0820-5d38-7788-2cc0140a31c9/displayed","method":"GET"}
debug: Proxied response received with status 200: "{\"value\":true,\"status\":0,\"sessionId\":\"ea2154af-3a85-ed67-2142-d7093b1f4a7b\"}"
debug: 127.0.0.1 - - "GET /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element/dd91c659-0820-5d38-7788-2cc0140a31c9/displayed HTTP/1.1" 200 76 "-" "Apache-HttpClient/4.4-alpha1 (Java 1.5 minimum; Java/1.7.0_65)"
debug: Appium request initiated at /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element
debug: Request received with params: {"using":"id","value":"alertTitle"}
debug: Proxying command to localhost:9001
info: Making http request with opts: {"url":"http://localhost:9001/wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element","method":"POST","json":{"using":"id","value":"alertTitle"}}
debug: Proxied response received with status 200: {"value":{"ELEMENT":"dd91c659-0820-5d38-7788-2cc0140a31c9"},"status":0,"sessionId":"ea2154af-3a85-ed67-2142-d7093b1f4a7b"}
debug: 127.0.0.1 - - "POST /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element HTTP/1.1" 200 144 "-" "Apache-HttpClient/4.4-alpha1 (Java 1.5 minimum; Java/1.7.0_65)"
debug: Appium request initiated at /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element/dd91c659-0820-5d38-7788-2cc0140a31c9/displayed
debug: Request received with params: {}
debug: Proxying command to localhost:9001
info: Making http request with opts: {"url":"http://localhost:9001/wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element/dd91c659-0820-5d38-7788-2cc0140a31c9/displayed","method":"GET"}
debug: Proxied response received with status 200: "{\"value\":true,\"status\":0,\"sessionId\":\"ea2154af-3a85-ed67-2142-d7093b1f4a7b\"}"
debug: 127.0.0.1 - - "GET /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element/dd91c659-0820-5d38-7788-2cc0140a31c9/displayed HTTP/1.1" 200 76 "-" "Apache-HttpClient/4.4-alpha1 (Java 1.5 minimum; Java/1.7.0_65)"
debug: Appium request initiated at /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element
debug: Request received with params: {"using":"id","value":"alertTitle"}
debug: Proxying command to localhost:9001
info: Making http request with opts: {"url":"http://localhost:9001/wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element","method":"POST","json":{"using":"id","value":"alertTitle"}}
debug: Proxied response received with status 200: {"value":{"ELEMENT":"dd91c659-0820-5d38-7788-2cc0140a31c9"},"status":0,"sessionId":"ea2154af-3a85-ed67-2142-d7093b1f4a7b"}
debug: 127.0.0.1 - - "POST /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element HTTP/1.1" 200 144 "-" "Apache-HttpClient/4.4-alpha1 (Java 1.5 minimum; Java/1.7.0_65)"
debug: Appium request initiated at /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element/dd91c659-0820-5d38-7788-2cc0140a31c9/displayed
debug: Request received with params: {}
debug: Proxying command to localhost:9001
info: Making http request with opts: {"url":"http://localhost:9001/wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element/dd91c659-0820-5d38-7788-2cc0140a31c9/displayed","method":"GET"}
debug: Proxied response received with status 200: "{\"value\":true,\"status\":0,\"sessionId\":\"ea2154af-3a85-ed67-2142-d7093b1f4a7b\"}"
debug: 127.0.0.1 - - "GET /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element/dd91c659-0820-5d38-7788-2cc0140a31c9/displayed HTTP/1.1" 200 76 "-" "Apache-HttpClient/4.4-alpha1 (Java 1.5 minimum; Java/1.7.0_65)"
debug: Appium request initiated at /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element
debug: Request received with params: {"using":"id","value":"alertTitle"}
debug: Proxying command to localhost:9001
info: Making http request with opts: {"url":"http://localhost:9001/wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element","method":"POST","json":{"using":"id","value":"alertTitle"}}
info: Responding to client with error: {"status":7,"value":{"message":"An element could not be located on the page using the given search parameters.","origValue":"Element was not found.\nio.selendroid.exceptions.NoSuchElementException: Element was not found.\n\tat io.selendroid.server.handler.FindElement.handle(FindElement.java:57)\n\tat io.selendroid.server.AndroidServlet.handleRequest(AndroidServlet.java:290)\n\tat io.selendroid.server.BaseServlet.handleHttpRequest(BaseServlet.java:70)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:78)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:62)\n\tat io.selendroid.server.inspector.InspectorServlet.handleHttpRequest(InspectorServlet.java:78)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:78)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:62)\n\tat org.webbitserver.handler.PathMatchHandler.handleHttpRequest(PathMatchHandler.java:33)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:78)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:62)\n\tat org.webbitserver.handler.DateHeaderHandler.handleHttpRequest(DateHeaderHandler.java:21)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:78)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:62)\n\tat org.webbitserver.handler.ServerHeaderHandler.handleHttpRequest(ServerHeaderHandler.java:25)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:78)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:67)\n\tat org.webbitserver.netty.NettyHttpChannelHandler$2.run(NettyHttpChannelHandler.java:72)\n\tat java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1076)\n\tat java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:569)\n\tat java.lang.Thread.run(Thread.java:864)\n"},"sessionId":"ea2154af-3a85-ed67-2142-d7093b1f4a7b"}
debug: 127.0.0.1 - - "POST /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element HTTP/1.1" 500 2068 "-" "Apache-HttpClient/4.4-alpha1 (Java 1.5 minimum; Java/1.7.0_65)"
debug: Appium request initiated at /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element
debug: Request received with params: {"using":"id","value":"alertTitle"}
debug: Proxying command to localhost:9001
info: Making http request with opts: {"url":"http://localhost:9001/wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element","method":"POST","json":{"using":"id","value":"alertTitle"}}
info: Responding to client with error: {"status":7,"value":{"message":"An element could not be located on the page using the given search parameters.","origValue":"Element was not found.\nio.selendroid.exceptions.NoSuchElementException: Element was not found.\n\tat io.selendroid.server.handler.FindElement.handle(FindElement.java:57)\n\tat io.selendroid.server.AndroidServlet.handleRequest(AndroidServlet.java:290)\n\tat io.selendroid.server.BaseServlet.handleHttpRequest(BaseServlet.java:70)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:78)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:62)\n\tat io.selendroid.server.inspector.InspectorServlet.handleHttpRequest(InspectorServlet.java:78)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:78)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:62)\n\tat org.webbitserver.handler.PathMatchHandler.handleHttpRequest(PathMatchHandler.java:33)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:78)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:62)\n\tat org.webbitserver.handler.DateHeaderHandler.handleHttpRequest(DateHeaderHandler.java:21)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:78)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:62)\n\tat org.webbitserver.handler.ServerHeaderHandler.handleHttpRequest(ServerHeaderHandler.java:25)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:78)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:67)\n\tat org.webbitserver.netty.NettyHttpChannelHandler$2.run(NettyHttpChannelHandler.java:72)\n\tat java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1076)\n\tat java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:569)\n\tat java.lang.Thread.run(Thread.java:864)\n"},"sessionId":"ea2154af-3a85-ed67-2142-d7093b1f4a7b"}
debug: 127.0.0.1 - - "POST /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element HTTP/1.1" 500 2068 "-" "Apache-HttpClient/4.4-alpha1 (Java 1.5 minimum; Java/1.7.0_65)"
debug: Appium request initiated at /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element
debug: Request received with params: {"using":"id","value":"alertTitle"}
debug: Proxying command to localhost:9001
info: Making http request with opts: {"url":"http://localhost:9001/wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element","method":"POST","json":{"using":"id","value":"alertTitle"}}
info: Responding to client with error: {"status":7,"value":{"message":"An element could not be located on the page using the given search parameters.","origValue":"Element was not found.\nio.selendroid.exceptions.NoSuchElementException: Element was not found.\n\tat io.selendroid.server.handler.FindElement.handle(FindElement.java:57)\n\tat io.selendroid.server.AndroidServlet.handleRequest(AndroidServlet.java:290)\n\tat io.selendroid.server.BaseServlet.handleHttpRequest(BaseServlet.java:70)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:78)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:62)\n\tat io.selendroid.server.inspector.InspectorServlet.handleHttpRequest(InspectorServlet.java:78)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:78)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:62)\n\tat org.webbitserver.handler.PathMatchHandler.handleHttpRequest(PathMatchHandler.java:33)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:78)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:62)\n\tat org.webbitserver.handler.DateHeaderHandler.handleHttpRequest(DateHeaderHandler.java:21)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:78)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:62)\n\tat org.webbitserver.handler.ServerHeaderHandler.handleHttpRequest(ServerHeaderHandler.java:25)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:78)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:67)\n\tat org.webbitserver.netty.NettyHttpChannelHandler$2.run(NettyHttpChannelHandler.java:72)\n\tat java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1076)\n\tat java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:569)\n\tat java.lang.Thread.run(Thread.java:864)\n"},"sessionId":"ea2154af-3a85-ed67-2142-d7093b1f4a7b"}
debug: 127.0.0.1 - - "POST /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element HTTP/1.1" 500 2068 "-" "Apache-HttpClient/4.4-alpha1 (Java 1.5 minimum; Java/1.7.0_65)"
debug: Appium request initiated at /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element
debug: Request received with params: {"using":"id","value":"localProfileShortNameField"}
debug: Proxying command to localhost:9001
info: Making http request with opts: {"url":"http://localhost:9001/wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element","method":"POST","json":{"using":"id","value":"localProfileShortNameField"}}
debug: Proxied response received with status 200: {"value":{"ELEMENT":"8553d881-a703-7fd0-b6ad-f5867f79fd84"},"status":0,"sessionId":"ea2154af-3a85-ed67-2142-d7093b1f4a7b"}
debug: 127.0.0.1 - - "POST /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element HTTP/1.1" 200 144 "-" "Apache-HttpClient/4.4-alpha1 (Java 1.5 minimum; Java/1.7.0_65)"
debug: Appium request initiated at /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element
debug: Request received with params: {"using":"id","value":"localProfileShortNameField"}
debug: Proxying command to localhost:9001
info: Making http request with opts: {"url":"http://localhost:9001/wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element","method":"POST","json":{"using":"id","value":"localProfileShortNameField"}}
debug: Proxied response received with status 200: {"value":{"ELEMENT":"8553d881-a703-7fd0-b6ad-f5867f79fd84"},"status":0,"sessionId":"ea2154af-3a85-ed67-2142-d7093b1f4a7b"}
debug: 127.0.0.1 - - "POST /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element HTTP/1.1" 200 144 "-" "Apache-HttpClient/4.4-alpha1 (Java 1.5 minimum; Java/1.7.0_65)"
debug: Appium request initiated at /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element/8553d881-a703-7fd0-b6ad-f5867f79fd84/text
debug: Request received with params: {}
debug: Proxying command to localhost:9001
info: Making http request with opts: {"url":"http://localhost:9001/wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element/8553d881-a703-7fd0-b6ad-f5867f79fd84/text","method":"GET"}
debug: Proxied response received with status 200: "{\"value\":\"ishi.test2013@gmail.com\",\"status\":0,\"sessionId\":\"ea2154af-3a85-ed67-2142-d7093b1f4a7b\"}"
debug: 127.0.0.1 - - "GET /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element/8553d881-a703-7fd0-b6ad-f5867f79fd84/text HTTP/1.1" 200 97 "-" "Apache-HttpClient/4.4-alpha1 (Java 1.5 minimum; Java/1.7.0_65)"
debug: Appium request initiated at /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element
debug: Request received with params: {"using":"id","value":"localProfileDeviceDescriptionField"}
debug: Proxying command to localhost:9001
info: Making http request with opts: {"url":"http://localhost:9001/wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element","method":"POST","json":{"using":"id","value":"localProfileDeviceDescriptionField"}}
debug: Proxied response received with status 200: {"value":{"ELEMENT":"78de98dc-7f3c-f77e-0818-970bc789dd66"},"status":0,"sessionId":"ea2154af-3a85-ed67-2142-d7093b1f4a7b"}
debug: 127.0.0.1 - - "POST /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element HTTP/1.1" 200 144 "-" "Apache-HttpClient/4.4-alpha1 (Java 1.5 minimum; Java/1.7.0_65)"
debug: Appium request initiated at /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element/78de98dc-7f3c-f77e-0818-970bc789dd66/text
debug: Request received with params: {}
debug: Proxying command to localhost:9001
info: Making http request with opts: {"url":"http://localhost:9001/wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element/78de98dc-7f3c-f77e-0818-970bc789dd66/text","method":"GET"}
debug: Proxied response received with status 200: "{\"value\":\"HTC One X\",\"status\":0,\"sessionId\":\"ea2154af-3a85-ed67-2142-d7093b1f4a7b\"}"
debug: 127.0.0.1 - - "GET /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element/78de98dc-7f3c-f77e-0818-970bc789dd66/text HTTP/1.1" 200 83 "-" "Apache-HttpClient/4.4-alpha1 (Java 1.5 minimum; Java/1.7.0_65)"
debug: Appium request initiated at /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element
debug: Request received with params: {"using":"id","value":"localProfileButtonSave"}
debug: Proxying command to localhost:9001
info: Making http request with opts: {"url":"http://localhost:9001/wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element","method":"POST","json":{"using":"id","value":"localProfileButtonSave"}}
debug: Proxied response received with status 200: {"value":{"ELEMENT":"5dc00900-999e-f2a1-b21d-7f2d02882433"},"status":0,"sessionId":"ea2154af-3a85-ed67-2142-d7093b1f4a7b"}
debug: 127.0.0.1 - - "POST /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element HTTP/1.1" 200 144 "-" "Apache-HttpClient/4.4-alpha1 (Java 1.5 minimum; Java/1.7.0_65)"
debug: Appium request initiated at /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element/5dc00900-999e-f2a1-b21d-7f2d02882433/click
debug: Request received with params: {"id":"5dc00900-999e-f2a1-b21d-7f2d02882433"}
debug: Proxying command to localhost:9001
info: Making http request with opts: {"url":"http://localhost:9001/wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element/5dc00900-999e-f2a1-b21d-7f2d02882433/click","method":"POST","json":{"id":"5dc00900-999e-f2a1-b21d-7f2d02882433"}}
info: [SELENDROID] WIFI ishisys
info: [SELENDROID] [AsyncTask #2] System state cleared
debug: Proxied response received with status 200: {"value":"","status":0,"sessionId":"ea2154af-3a85-ed67-2142-d7093b1f4a7b"}
debug: 127.0.0.1 - - "POST /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element/5dc00900-999e-f2a1-b21d-7f2d02882433/click HTTP/1.1" 200 87 "-" "Apache-HttpClient/4.4-alpha1 (Java 1.5 minimum; Java/1.7.0_65)"
debug: Appium request initiated at /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/timeouts/implicit_wait
debug: Request received with params: {"ms":5000}
debug: Proxying command to localhost:9001
info: Making http request with opts: {"url":"http://localhost:9001/wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/timeouts/implicit_wait","method":"POST","json":{"ms":5000}}
debug: Proxied response received with status 200: {"value":"","status":0,"sessionId":"ea2154af-3a85-ed67-2142-d7093b1f4a7b"}
debug: 127.0.0.1 - - "POST /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/timeouts/implicit_wait HTTP/1.1" 200 87 "-" "Apache-HttpClient/4.4-alpha1 (Java 1.5 minimum; Java/1.7.0_65)"
debug: Appium request initiated at /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element
debug: Request received with params: {"using":"name","value":"Add Contact"}
debug: Proxying command to localhost:9001
info: Making http request with opts: {"url":"http://localhost:9001/wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element","method":"POST","json":{"using":"name","value":"Add Contact"}}
debug: Proxied response received with status 200: {"value":{"ELEMENT":"0e351ab4-3e3d-92db-8839-ddf229f5f6e3"},"status":0,"sessionId":"ea2154af-3a85-ed67-2142-d7093b1f4a7b"}
debug: 127.0.0.1 - - "POST /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element HTTP/1.1" 200 144 "-" "Apache-HttpClient/4.4-alpha1 (Java 1.5 minimum; Java/1.7.0_65)"
debug: Appium request initiated at /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element/0e351ab4-3e3d-92db-8839-ddf229f5f6e3/click
debug: Request received with params: {"id":"0e351ab4-3e3d-92db-8839-ddf229f5f6e3"}
debug: Proxying command to localhost:9001
info: Making http request with opts: {"url":"http://localhost:9001/wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element/0e351ab4-3e3d-92db-8839-ddf229f5f6e3/click","method":"POST","json":{"id":"0e351ab4-3e3d-92db-8839-ddf229f5f6e3"}}
debug: Proxied response received with status 200: {"value":"","status":0,"sessionId":"ea2154af-3a85-ed67-2142-d7093b1f4a7b"}
debug: 127.0.0.1 - - "POST /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element/0e351ab4-3e3d-92db-8839-ddf229f5f6e3/click HTTP/1.1" 200 87 "-" "Apache-HttpClient/4.4-alpha1 (Java 1.5 minimum; Java/1.7.0_65)"
debug: Appium request initiated at /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/timeouts/implicit_wait
debug: Request received with params: {"ms":3000}
debug: Proxying command to localhost:9001
info: Making http request with opts: {"url":"http://localhost:9001/wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/timeouts/implicit_wait","method":"POST","json":{"ms":3000}}
debug: Proxied response received with status 200: {"value":"","status":0,"sessionId":"ea2154af-3a85-ed67-2142-d7093b1f4a7b"}
debug: 127.0.0.1 - - "POST /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/timeouts/implicit_wait HTTP/1.1" 200 87 "-" "Apache-HttpClient/4.4-alpha1 (Java 1.5 minimum; Java/1.7.0_65)"
debug: Appium request initiated at /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element
debug: Request received with params: {"using":"id","value":"contactAliasText"}
debug: Proxying command to localhost:9001
info: Making http request with opts: {"url":"http://localhost:9001/wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element","method":"POST","json":{"using":"id","value":"contactAliasText"}}
debug: Proxied response received with status 200: {"value":{"ELEMENT":"7f734f4b-d6c6-ebd5-7d68-2cfb8539bae7"},"status":0,"sessionId":"ea2154af-3a85-ed67-2142-d7093b1f4a7b"}
debug: 127.0.0.1 - - "POST /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element HTTP/1.1" 200 144 "-" "Apache-HttpClient/4.4-alpha1 (Java 1.5 minimum; Java/1.7.0_65)"
debug: Appium request initiated at /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element/7f734f4b-d6c6-ebd5-7d68-2cfb8539bae7/value
debug: Request received with params: {"id":"7f734f4b-d6c6-ebd5-7d68-2cfb8539bae7","value":["Bob"]}
debug: Proxying command to localhost:9001
info: Making http request with opts: {"url":"http://localhost:9001/wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element/7f734f4b-d6c6-ebd5-7d68-2cfb8539bae7/value","method":"POST","json":{"id":"7f734f4b-d6c6-ebd5-7d68-2cfb8539bae7","value":["Bob"]}}
debug: Proxied response received with status 200: {"value":"","status":0,"sessionId":"ea2154af-3a85-ed67-2142-d7093b1f4a7b"}
debug: 127.0.0.1 - - "POST /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element/7f734f4b-d6c6-ebd5-7d68-2cfb8539bae7/value HTTP/1.1" 200 87 "-" "Apache-HttpClient/4.4-alpha1 (Java 1.5 minimum; Java/1.7.0_65)"
debug: Appium request initiated at /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element
debug: Request received with params: {"using":"id","value":"thrContryCodeText"}
debug: Proxying command to localhost:9001
info: Making http request with opts: {"url":"http://localhost:9001/wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element","method":"POST","json":{"using":"id","value":"thrContryCodeText"}}
debug: Proxied response received with status 200: {"value":{"ELEMENT":"d64a6f7d-7385-103f-5ea2-c171c751cf16"},"status":0,"sessionId":"ea2154af-3a85-ed67-2142-d7093b1f4a7b"}
debug: 127.0.0.1 - - "POST /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element HTTP/1.1" 200 144 "-" "Apache-HttpClient/4.4-alpha1 (Java 1.5 minimum; Java/1.7.0_65)"
debug: Appium request initiated at /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element/d64a6f7d-7385-103f-5ea2-c171c751cf16/value
debug: Request received with params: {"id":"d64a6f7d-7385-103f-5ea2-c171c751cf16","value":["91"]}
debug: Proxying command to localhost:9001
info: Making http request with opts: {"url":"http://localhost:9001/wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element/d64a6f7d-7385-103f-5ea2-c171c751cf16/value","method":"POST","json":{"id":"d64a6f7d-7385-103f-5ea2-c171c751cf16","value":["91"]}}
debug: Proxied response received with status 200: {"value":"","status":0,"sessionId":"ea2154af-3a85-ed67-2142-d7093b1f4a7b"}
debug: 127.0.0.1 - - "POST /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element/d64a6f7d-7385-103f-5ea2-c171c751cf16/value HTTP/1.1" 200 87 "-" "Apache-HttpClient/4.4-alpha1 (Java 1.5 minimum; Java/1.7.0_65)"
debug: Appium request initiated at /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element
debug: Request received with params: {"using":"id","value":"contactPhoneText"}
debug: Proxying command to localhost:9001
info: Making http request with opts: {"url":"http://localhost:9001/wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element","method":"POST","json":{"using":"id","value":"contactPhoneText"}}
debug: Proxied response received with status 200: {"value":{"ELEMENT":"20645dd4-9071-1498-00d1-dabfd1e05d23"},"status":0,"sessionId":"ea2154af-3a85-ed67-2142-d7093b1f4a7b"}
debug: 127.0.0.1 - - "POST /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element HTTP/1.1" 200 144 "-" "Apache-HttpClient/4.4-alpha1 (Java 1.5 minimum; Java/1.7.0_65)"
debug: Appium request initiated at /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element/20645dd4-9071-1498-00d1-dabfd1e05d23/value
debug: Request received with params: {"id":"20645dd4-9071-1498-00d1-dabfd1e05d23","value":["2001"]}
debug: Proxying command to localhost:9001
info: Making http request with opts: {"url":"http://localhost:9001/wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element/20645dd4-9071-1498-00d1-dabfd1e05d23/value","method":"POST","json":{"id":"20645dd4-9071-1498-00d1-dabfd1e05d23","value":["2001"]}}
debug: Proxied response received with status 200: {"value":"","status":0,"sessionId":"ea2154af-3a85-ed67-2142-d7093b1f4a7b"}
debug: 127.0.0.1 - - "POST /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element/20645dd4-9071-1498-00d1-dabfd1e05d23/value HTTP/1.1" 200 87 "-" "Apache-HttpClient/4.4-alpha1 (Java 1.5 minimum; Java/1.7.0_65)"
debug: Appium request initiated at /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element
debug: Request received with params: {"using":"id","value":"myContryCodeText"}
debug: Proxying command to localhost:9001
info: Making http request with opts: {"url":"http://localhost:9001/wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element","method":"POST","json":{"using":"id","value":"myContryCodeText"}}
debug: Proxied response received with status 200: {"value":{"ELEMENT":"e07eacab-a437-7eb0-fc37-6b7c5670af16"},"status":0,"sessionId":"ea2154af-3a85-ed67-2142-d7093b1f4a7b"}
debug: 127.0.0.1 - - "POST /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element HTTP/1.1" 200 144 "-" "Apache-HttpClient/4.4-alpha1 (Java 1.5 minimum; Java/1.7.0_65)"
debug: Appium request initiated at /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element/e07eacab-a437-7eb0-fc37-6b7c5670af16/clear
debug: Request received with params: {"id":"e07eacab-a437-7eb0-fc37-6b7c5670af16"}
debug: Proxying command to localhost:9001
info: Making http request with opts: {"url":"http://localhost:9001/wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element/e07eacab-a437-7eb0-fc37-6b7c5670af16/clear","method":"POST","json":{"id":"e07eacab-a437-7eb0-fc37-6b7c5670af16"}}
debug: Proxied response received with status 200: {"value":"","status":0,"sessionId":"ea2154af-3a85-ed67-2142-d7093b1f4a7b"}
debug: 127.0.0.1 - - "POST /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element/e07eacab-a437-7eb0-fc37-6b7c5670af16/clear HTTP/1.1" 200 87 "-" "Apache-HttpClient/4.4-alpha1 (Java 1.5 minimum; Java/1.7.0_65)"
debug: Appium request initiated at /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element
debug: Request received with params: {"using":"id","value":"myContryCodeText"}
debug: Proxying command to localhost:9001
info: Making http request with opts: {"url":"http://localhost:9001/wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element","method":"POST","json":{"using":"id","value":"myContryCodeText"}}
debug: Proxied response received with status 200: {"value":{"ELEMENT":"e07eacab-a437-7eb0-fc37-6b7c5670af16"},"status":0,"sessionId":"ea2154af-3a85-ed67-2142-d7093b1f4a7b"}
debug: 127.0.0.1 - - "POST /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element HTTP/1.1" 200 144 "-" "Apache-HttpClient/4.4-alpha1 (Java 1.5 minimum; Java/1.7.0_65)"
debug: Appium request initiated at /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element/e07eacab-a437-7eb0-fc37-6b7c5670af16/value
debug: Request received with params: {"id":"e07eacab-a437-7eb0-fc37-6b7c5670af16","value":["91"]}
debug: Proxying command to localhost:9001
info: Making http request with opts: {"url":"http://localhost:9001/wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element/e07eacab-a437-7eb0-fc37-6b7c5670af16/value","method":"POST","json":{"id":"e07eacab-a437-7eb0-fc37-6b7c5670af16","value":["91"]}}
debug: Proxied response received with status 200: {"value":"","status":0,"sessionId":"ea2154af-3a85-ed67-2142-d7093b1f4a7b"}
debug: 127.0.0.1 - - "POST /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element/e07eacab-a437-7eb0-fc37-6b7c5670af16/value HTTP/1.1" 200 87 "-" "Apache-HttpClient/4.4-alpha1 (Java 1.5 minimum; Java/1.7.0_65)"
debug: Appium request initiated at /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element
debug: Request received with params: {"using":"id","value":"myPhoneText"}
debug: Proxying command to localhost:9001
info: Making http request with opts: {"url":"http://localhost:9001/wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element","method":"POST","json":{"using":"id","value":"myPhoneText"}}
debug: Proxied response received with status 200: {"value":{"ELEMENT":"21687e89-2096-b2a0-8224-821200f75f08"},"status":0,"sessionId":"ea2154af-3a85-ed67-2142-d7093b1f4a7b"}
debug: 127.0.0.1 - - "POST /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element HTTP/1.1" 200 144 "-" "Apache-HttpClient/4.4-alpha1 (Java 1.5 minimum; Java/1.7.0_65)"
debug: Appium request initiated at /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element/21687e89-2096-b2a0-8224-821200f75f08/clear
debug: Request received with params: {"id":"21687e89-2096-b2a0-8224-821200f75f08"}
debug: Proxying command to localhost:9001
info: Making http request with opts: {"url":"http://localhost:9001/wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element/21687e89-2096-b2a0-8224-821200f75f08/clear","method":"POST","json":{"id":"21687e89-2096-b2a0-8224-821200f75f08"}}
debug: Proxied response received with status 200: {"value":"","status":0,"sessionId":"ea2154af-3a85-ed67-2142-d7093b1f4a7b"}
debug: 127.0.0.1 - - "POST /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element/21687e89-2096-b2a0-8224-821200f75f08/clear HTTP/1.1" 200 87 "-" "Apache-HttpClient/4.4-alpha1 (Java 1.5 minimum; Java/1.7.0_65)"
debug: Appium request initiated at /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element
debug: Request received with params: {"using":"id","value":"myPhoneText"}
debug: Proxying command to localhost:9001
info: Making http request with opts: {"url":"http://localhost:9001/wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element","method":"POST","json":{"using":"id","value":"myPhoneText"}}
debug: Proxied response received with status 200: {"value":{"ELEMENT":"21687e89-2096-b2a0-8224-821200f75f08"},"status":0,"sessionId":"ea2154af-3a85-ed67-2142-d7093b1f4a7b"}
debug: 127.0.0.1 - - "POST /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element HTTP/1.1" 200 144 "-" "Apache-HttpClient/4.4-alpha1 (Java 1.5 minimum; Java/1.7.0_65)"
debug: Appium request initiated at /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element/21687e89-2096-b2a0-8224-821200f75f08/value
debug: Request received with params: {"id":"21687e89-2096-b2a0-8224-821200f75f08","value":["1001"]}
debug: Proxying command to localhost:9001
info: Making http request with opts: {"url":"http://localhost:9001/wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element/21687e89-2096-b2a0-8224-821200f75f08/value","method":"POST","json":{"id":"21687e89-2096-b2a0-8224-821200f75f08","value":["1001"]}}
debug: Proxied response received with status 200: {"value":"","status":0,"sessionId":"ea2154af-3a85-ed67-2142-d7093b1f4a7b"}
debug: 127.0.0.1 - - "POST /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element/21687e89-2096-b2a0-8224-821200f75f08/value HTTP/1.1" 200 87 "-" "Apache-HttpClient/4.4-alpha1 (Java 1.5 minimum; Java/1.7.0_65)"
debug: Appium request initiated at /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element
debug: Request received with params: {"using":"id","value":"sendInviteButton"}
debug: Proxying command to localhost:9001
info: Making http request with opts: {"url":"http://localhost:9001/wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element","method":"POST","json":{"using":"id","value":"sendInviteButton"}}
debug: Proxied response received with status 200: {"value":{"ELEMENT":"b5fa90fa-7547-4865-15bf-3e36d4386afb"},"status":0,"sessionId":"ea2154af-3a85-ed67-2142-d7093b1f4a7b"}
debug: 127.0.0.1 - - "POST /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element HTTP/1.1" 200 144 "-" "Apache-HttpClient/4.4-alpha1 (Java 1.5 minimum; Java/1.7.0_65)"
debug: Appium request initiated at /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element/b5fa90fa-7547-4865-15bf-3e36d4386afb/click
debug: Request received with params: {"id":"b5fa90fa-7547-4865-15bf-3e36d4386afb"}
debug: Proxying command to localhost:9001
info: Making http request with opts: {"url":"http://localhost:9001/wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element/b5fa90fa-7547-4865-15bf-3e36d4386afb/click","method":"POST","json":{"id":"b5fa90fa-7547-4865-15bf-3e36d4386afb"}}
debug: Proxied response received with status 200: {"value":"","status":0,"sessionId":"ea2154af-3a85-ed67-2142-d7093b1f4a7b"}
debug: 127.0.0.1 - - "POST /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element/b5fa90fa-7547-4865-15bf-3e36d4386afb/click HTTP/1.1" 200 87 "-" "Apache-HttpClient/4.4-alpha1 (Java 1.5 minimum; Java/1.7.0_65)"
debug: Appium request initiated at /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element
debug: Request received with params: {"using":"id","value":"buddyName"}
debug: Proxying command to localhost:9001
info: Making http request with opts: {"url":"http://localhost:9001/wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element","method":"POST","json":{"using":"id","value":"buddyName"}}
debug: Proxied response received with status 200: {"value":{"ELEMENT":"bb3461f5-ef19-abcc-5416-fa6e9827b2fc"},"status":0,"sessionId":"ea2154af-3a85-ed67-2142-d7093b1f4a7b"}
debug: 127.0.0.1 - - "POST /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element HTTP/1.1" 200 144 "-" "Apache-HttpClient/4.4-alpha1 (Java 1.5 minimum; Java/1.7.0_65)"
debug: Appium request initiated at /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/touch/longclick
debug: Request received with params: {"element":"bb3461f5-ef19-abcc-5416-fa6e9827b2fc"}
debug: Proxying command to localhost:9001
info: Making http request with opts: {"url":"http://localhost:9001/wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/touch/longclick","method":"POST","json":{"element":"bb3461f5-ef19-abcc-5416-fa6e9827b2fc"}}
info: Responding to client with error: {"status":13,"value":{"message":"An unknown server-side error occurred while processing the command.","origValue":"Did not successfully proxy server command"},"sessionId":"ea2154af-3a85-ed67-2142-d7093b1f4a7b"}
debug: 127.0.0.1 - - "POST /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/touch/longclick HTTP/1.1" 500 238 "-" "Apache-HttpClient/4.4-alpha1 (Java 1.5 minimum; Java/1.7.0_65)"
debug: Logcat terminated with code 0, signal null
debug: Appium request initiated at /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element
debug: Request received with params: {"using":"id","value":"message"}
debug: Proxying command to localhost:9001
info: Making http request with opts: {"url":"http://localhost:9001/wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element","method":"POST","json":{"using":"id","value":"message"}}
info: Responding to client with error: {"status":13,"value":{"message":"An unknown server-side error occurred while processing the command.","origValue":"Did not successfully proxy server command"},"sessionId":"ea2154af-3a85-ed67-2142-d7093b1f4a7b"}
debug: 127.0.0.1 - - "POST /wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b/element HTTP/1.1" 500 238 "-" "Apache-HttpClient/4.4-alpha1 (Java 1.5 minimum; Java/1.7.0_65)"
info: Didn't get a new command in 60 secs, shutting down...
info: Shutting down appium session...
info: Stopping selendroid server
info: Making http request with opts: {"url":"http://localhost:9001/wd/hub/session/ea2154af-3a85-ed67-2142-d7093b1f4a7b","method":"DELETE"}
info: Cleaning up appium session
info: We shut down because no new commands came in

appium_server_1.2.0.log

2014-08-05T12:15:54.750Z - info: Welcome to Appium v1.2.0 (REV e53f49c706a25242e66d36685c268b599cc18da5)
2014-08-05T12:15:54.752Z - info: Appium REST http interface listener started on 0.0.0.0:6001
2014-08-05T12:15:54.755Z - debug: Non-default server args: {"udid":"HT26HW300907","port":6001,"bootstrapPort":4001,"log":"/home/testuser/appium_server_Alice.log","selendroidPort":9001}
2014-08-05T12:15:54.755Z - info: LogLevel: debug
2014-08-05T12:16:01.233Z - info: --> POST /wd/hub/session {"desiredCapabilities":{"automationName":"Selendroid","platformVersion":"4.4.2","app":"/home/testuser/Java_Projects/Alice/target/test-classes/en-android.apk","platformName":"Android","deviceName":"Android","appActivity":"com.test.app.android.gui.WelcomeActivity","appium-version":"1.2.0","name":"TestApp Automation","appPackage":"com.test.app.android"}}
2014-08-05T12:16:01.235Z - debug: Appium request initiated at /wd/hub/session
2014-08-05T12:16:01.235Z - debug: Request received with params: {"desiredCapabilities":{"automationName":"Selendroid","platformVersion":"4.4.2","app":"/home/testuser/Java_Projects/Alice/target/test-classes/en-android.apk","platformName":"Android","deviceName":"Android","appActivity":"com.test.app.android.gui.WelcomeActivity","appium-version":"1.2.0","name":"TestApp Automation","appPackage":"com.test.app.android"}}
2014-08-05T12:16:01.237Z - 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, name
2014-08-05T12:16:01.238Z - debug: Using local app from desired caps: /home/testuser/Java_Projects/Alice/target/test-classes/en-android.apk
2014-08-05T12:16:01.239Z - debug: Creating new appium session 2891b998-88c8-4c54-a381-4c8912f9dbb8
2014-08-05T12:16:01.239Z - debug: Starting selendroid server
2014-08-05T12:16:01.241Z - debug: Checking whether selendroid is built yet
2014-08-05T12:16:01.241Z - debug: Selendroid server exists!
2014-08-05T12:16:01.241Z - debug: Preparing device for session
2014-08-05T12:16:01.241Z - debug: Checking whether app is actually present
2014-08-05T12:16:01.242Z - debug: Checking whether adb is present
2014-08-05T12:16:01.243Z - debug: Using adb from /opt/android-sdk-linux/platform-tools/adb
2014-08-05T12:16:01.243Z - info: Retrieving device
2014-08-05T12:16:01.244Z - debug: Trying to find a connected android device
2014-08-05T12:16:01.244Z - debug: Getting connected devices...
2014-08-05T12:16:01.244Z - debug: executing: "/opt/android-sdk-linux/platform-tools/adb" devices
2014-08-05T12:16:01.258Z - debug: 1 device(s) connected
2014-08-05T12:16:01.259Z - info: Found device HT26HW300907
2014-08-05T12:16:01.259Z - debug: Setting device id to HT26HW300907
2014-08-05T12:16:01.260Z - debug: Waiting for device to be ready and to respond to shell commands (timeout = 5)
2014-08-05T12:16:01.261Z - debug: executing: "/opt/android-sdk-linux/platform-tools/adb" -s HT26HW300907 wait-for-device
2014-08-05T12:16:01.274Z - debug: executing: "/opt/android-sdk-linux/platform-tools/adb" -s HT26HW300907 shell "echo 'ready'"
2014-08-05T12:16:01.359Z - debug: Starting logcat capture
2014-08-05T12:16:01.458Z - debug: Rebuilt selendroid apk exists, doing nothing
2014-08-05T12:16:01.458Z - debug: Rebuilt selendroid server already exists, no need to rebuild it with a new manifest
2014-08-05T12:16:01.459Z - debug: Checking signed status of /tmp/selendroid.com.test.app.android.apk
2014-08-05T12:16:01.460Z - debug: Checking app cert for /tmp/selendroid.com.test.app.android.apk: java -jar "/home/testuser/.linuxbrew/lib/node_modules/appium/node_modules/appium-adb/jars/verify.jar" "/tmp/selendroid.com.test.app.android.apk"
2014-08-05T12:16:01.469Z - debug: Checking signed status of /home/testuser/Java_Projects/Alice/target/test-classes/en-android.apk
2014-08-05T12:16:01.470Z - debug: Checking app cert for /home/testuser/Java_Projects/Alice/target/test-classes/en-android.apk: java -jar "/home/testuser/.linuxbrew/lib/node_modules/appium/node_modules/appium-adb/jars/verify.jar" "/home/testuser/Java_Projects/Alice/target/test-classes/en-android.apk"
2014-08-05T12:16:01.823Z - debug: App not signed with debug cert.
2014-08-05T12:16:01.824Z - debug: Resigning apk with: java -jar "/home/testuser/.linuxbrew/lib/node_modules/appium/node_modules/appium-adb/jars/sign.jar" "/home/testuser/Java_Projects/Alice/target/test-classes/en-android.apk" --override
2014-08-05T12:16:01.873Z - debug: App already signed.
2014-08-05T12:16:01.874Z - debug: Zip-aligning /tmp/selendroid.com.test.app.android.apk
2014-08-05T12:16:01.874Z - debug: Checking whether zipalign is present
2014-08-05T12:16:01.875Z - debug: Using zipalign from /opt/android-sdk-linux/build-tools/20.0.0/zipalign
2014-08-05T12:16:01.876Z - debug: zipAlignApk: "/opt/android-sdk-linux/build-tools/20.0.0/zipalign" -f 4 "/tmp/selendroid.com.test.app.android.apk" "/tmp/appium11475-4807-131fbdo.tmp"
2014-08-05T12:16:05.739Z - debug: Zip-aligning /home/testuser/Java_Projects/Alice/target/test-classes/en-android.apk
2014-08-05T12:16:05.740Z - debug: Checking whether zipalign is present
2014-08-05T12:16:05.742Z - debug: Using zipalign from /opt/android-sdk-linux/build-tools/20.0.0/zipalign
2014-08-05T12:16:05.742Z - debug: zipAlignApk: "/opt/android-sdk-linux/build-tools/20.0.0/zipalign" -f 4 "/home/testuser/Java_Projects/Alice/target/test-classes/en-android.apk" "/tmp/appium11475-4807-3oeo8y.tmp"
2014-08-05T12:16:05.787Z - debug: Getting install status for com.test.app.android.selendroid
2014-08-05T12:16:05.788Z - debug: Getting device API level
2014-08-05T12:16:05.789Z - debug: executing: "/opt/android-sdk-linux/platform-tools/adb" -s HT26HW300907 shell "getprop ro.build.version.sdk"
2014-08-05T12:16:05.889Z - debug: Device is at API Level 15
2014-08-05T12:16:05.890Z - debug: executing: "/opt/android-sdk-linux/platform-tools/adb" -s HT26HW300907 shell "pm list packages -3 com.test.app.android.selendroid"
2014-08-05T12:16:06.993Z - debug: App is installed
2014-08-05T12:16:06.993Z - debug: Rebuilt selendroid is already installed
2014-08-05T12:16:06.995Z - debug: executing: "/opt/android-sdk-linux/platform-tools/adb" -s HT26HW300907 shell "getprop persist.sys.language"
2014-08-05T12:16:07.087Z - debug: Current device language: en
2014-08-05T12:16:07.087Z - debug: java -jar "" "/home/testuser/Java_Projects/Alice/target/test-classes/en-android.apk" "/tmp/com.test.app.android" en
2014-08-05T12:16:07.093Z - debug: No strings.xml for language 'en', getting default strings.xml
2014-08-05T12:16:07.093Z - debug: java -jar "" "/home/testuser/Java_Projects/Alice/target/test-classes/en-android.apk" "/tmp/com.test.app.android"
2014-08-05T12:16:07.102Z - warn: Error getting strings.xml from apk
2014-08-05T12:16:07.102Z - debug: Error: Unable to access jarfile 

2014-08-05T12:16:07.103Z - debug: Not uninstalling app since server not started with --full-reset
2014-08-05T12:16:07.104Z - debug: Checking app cert for /home/testuser/Java_Projects/Alice/target/test-classes/en-android.apk: java -jar "/home/testuser/.linuxbrew/lib/node_modules/appium/node_modules/appium-adb/jars/verify.jar" "/home/testuser/Java_Projects/Alice/target/test-classes/en-android.apk"
2014-08-05T12:16:07.772Z - debug: App already signed.
2014-08-05T12:16:07.773Z - debug: Zip-aligning /home/testuser/Java_Projects/Alice/target/test-classes/en-android.apk
2014-08-05T12:16:07.773Z - debug: Checking whether zipalign is present
2014-08-05T12:16:07.775Z - debug: Using zipalign from /opt/android-sdk-linux/build-tools/20.0.0/zipalign
2014-08-05T12:16:07.775Z - debug: zipAlignApk: "/opt/android-sdk-linux/build-tools/20.0.0/zipalign" -f 4 "/home/testuser/Java_Projects/Alice/target/test-classes/en-android.apk" "/tmp/appium11475-4807-1jpo1lr.tmp"
2014-08-05T12:16:07.866Z - debug: MD5 for app is a3771bb9f1b6e3bbc919edb73b16dfb1
2014-08-05T12:16:07.867Z - debug: executing: "/opt/android-sdk-linux/platform-tools/adb" -s HT26HW300907 shell "ls /data/local/tmp/a3771bb9f1b6e3bbc919edb73b16dfb1.apk"
2014-08-05T12:16:07.941Z - debug: Getting install status for com.test.app.android
2014-08-05T12:16:07.942Z - debug: Getting device API level
2014-08-05T12:16:07.942Z - debug: executing: "/opt/android-sdk-linux/platform-tools/adb" -s HT26HW300907 shell "getprop ro.build.version.sdk"
2014-08-05T12:16:08.018Z - debug: Device is at API Level 15
2014-08-05T12:16:08.019Z - debug: executing: "/opt/android-sdk-linux/platform-tools/adb" -s HT26HW300907 shell "pm list packages -3 com.test.app.android"
2014-08-05T12:16:09.428Z - debug: App is installed
2014-08-05T12:16:09.429Z - info: App is already installed, resetting app
2014-08-05T12:16:09.431Z - debug: Running fast reset (stop and clear)
2014-08-05T12:16:09.432Z - debug: executing: "/opt/android-sdk-linux/platform-tools/adb" -s HT26HW300907 shell "am force-stop com.test.app.android"
2014-08-05T12:16:10.217Z - debug: executing: "/opt/android-sdk-linux/platform-tools/adb" -s HT26HW300907 shell "pm clear com.test.app.android"
2014-08-05T12:16:10.878Z - debug: Forwarding system:9001 to device:8080
2014-08-05T12:16:10.879Z - debug: executing: "/opt/android-sdk-linux/platform-tools/adb" -s HT26HW300907 forward tcp:9001 tcp:8080
2014-08-05T12:16:10.895Z - debug: Pushing settings apk to device...
2014-08-05T12:16:10.896Z - debug: executing: "/opt/android-sdk-linux/platform-tools/adb" -s HT26HW300907 install "/home/testuser/.linuxbrew/lib/node_modules/appium/build/settings_apk/settings_apk-debug.apk"
2014-08-05T12:16:12.137Z - debug: Pushing unlock helper app to device...
2014-08-05T12:16:12.138Z - debug: executing: "/opt/android-sdk-linux/platform-tools/adb" -s HT26HW300907 install "/home/testuser/.linuxbrew/lib/node_modules/appium/build/unlock_apk/unlock_apk-debug.apk"
2014-08-05T12:16:13.201Z - debug: executing: "/opt/android-sdk-linux/platform-tools/adb" -s HT26HW300907 shell "dumpsys window"
2014-08-05T12:16:13.384Z - debug: Writing dumpsys output to /home/testuser/.linuxbrew/lib/node_modules/appium/.dumpsys.log
2014-08-05T12:16:13.386Z - debug: Screen already unlocked, continuing.
2014-08-05T12:16:13.388Z - debug: executing: "/opt/android-sdk-linux/platform-tools/adb" -s HT26HW300907 shell "am instrument -e main_activity 'com.test.app.android.gui.WelcomeActivity' com.test.app.android.selendroid/io.selendroid.ServerInstrumentation"
2014-08-05T12:16:14.013Z - debug: Making http request with opts: {"url":"http://localhost:9001/wd/hub/status","method":"GET"}
2014-08-05T12:16:14.833Z - debug: Making http request with opts: {"url":"http://localhost:9001/wd/hub/status","method":"GET"}
2014-08-05T12:16:15.641Z - debug: Making http request with opts: {"url":"http://localhost:9001/wd/hub/status","method":"GET"}
2014-08-05T12:16:16.446Z - debug: Making http request with opts: {"url":"http://localhost:9001/wd/hub/status","method":"GET"}
2014-08-05T12:16:16.485Z - debug: Selendroid server is alive!
2014-08-05T12:16:16.485Z - debug: Listening for Selendroid logs
2014-08-05T12:16:16.486Z - debug: Creating Selendroid session
2014-08-05T12:16:16.486Z - debug: Making http request with opts: {"url":"http://localhost:9001/wd/hub/session","method":"POST","json":{"desiredCapabilities":{"warnings":{},"desired":{"automationName":"Selendroid","platformVersion":"4.4.2","app":"/home/testuser/Java_Projects/Alice/target/test-classes/en-android.apk","platformName":"Android","deviceName":"Android","appActivity":"com.test.app.android.gui.WelcomeActivity","appium-version":"1.2.0","name":"TestApp Automation","appPackage":"com.test.app.android"},"automationName":"Selendroid","platformVersion":"4.4.2","app":"/home/testuser/Java_Projects/Alice/target/test-classes/en-android.apk","platformName":"Android","deviceName":"Android","appActivity":"com.test.app.android.gui.WelcomeActivity","appium-version":"1.2.0","name":"TestApp Automation","appPackage":"com.test.app.android"}}}
2014-08-05T12:16:16.514Z - debug: Successfully started selendroid session
2014-08-05T12:16:16.516Z - debug: Waiting for pkg "com.test.app.android" and activity "com.test.app.android.gui.WelcomeActivity" to be focused
2014-08-05T12:16:16.518Z - debug: Getting focused package and activity
2014-08-05T12:16:16.519Z - debug: executing: "/opt/android-sdk-linux/platform-tools/adb" -s HT26HW300907 shell "dumpsys window windows"
2014-08-05T12:16:16.526Z - debug: [SELENDROID] http://localhost:9001/wd/hub/session/5ce7ce86-3dd5-ee90-7744-e5ec8dae26b2
2014-08-05T12:16:16.678Z - debug: Overriding session id with "5ce7ce86-3dd5-ee90-7744-e5ec8dae26b2"
2014-08-05T12:16:16.679Z - debug: Device launched! Ready for commands
2014-08-05T12:16:16.680Z - debug: Setting command timeout to the default of 60 secs
2014-08-05T12:16:16.682Z - debug: Appium session started with sessionId 5ce7ce86-3dd5-ee90-7744-e5ec8dae26b2
2014-08-05T12:16:16.687Z - info: <-- POST /wd/hub/session 303 15453.671 ms - 9 
2014-08-05T12:16:16.713Z - info: --> GET /wd/hub/session/5ce7ce86-3dd5-ee90-7744-e5ec8dae26b2 {}
2014-08-05T12:16:16.714Z - debug: Appium request initiated at /wd/hub/session/5ce7ce86-3dd5-ee90-7744-e5ec8dae26b2
2014-08-05T12:16:16.714Z - debug: Request received with params: {}
2014-08-05T12:16:16.717Z - debug: Proxying command to localhost:9001
2014-08-05T12:16:16.718Z - debug: Making http request with opts: {"url":"http://localhost:9001/wd/hub/session/5ce7ce86-3dd5-ee90-7744-e5ec8dae26b2","method":"GET"}
2014-08-05T12:16:16.738Z - debug: Proxied response received with status 200: "{\"value\":{\"automationName\":\"selendroid\",\"platform\":\"android\",\"platformVersion\":\"15\",\"acceptSslCerts\":true,\"javascriptEnabled\":true,\"platformName\":\"android\",\"handlesAlerts\":true,\"browserName\":\"selendroid\",\"networkConnectionEnabled\":true,\"rotatable\":true,\"takesScreenshot\":true,\"version\":\"0.10.0\"},\"status\":0,\"sessionId\":\"5ce7ce86-3dd5-ee90-7744-e5ec8dae26b2\"}"
2014-08-05T12:16:16.743Z - info: <-- GET /wd/hub/session/5ce7ce86-3dd5-ee90-7744-e5ec8dae26b2 200 29.593 ms - 358 
2014-08-05T12:16:16.810Z - info: --> POST /wd/hub/session/5ce7ce86-3dd5-ee90-7744-e5ec8dae26b2/element {"using":"id","value":"buttonGetStarted"}
2014-08-05T12:16:16.811Z - debug: Appium request initiated at /wd/hub/session/5ce7ce86-3dd5-ee90-7744-e5ec8dae26b2/element
2014-08-05T12:16:16.812Z - debug: Request received with params: {"using":"id","value":"buttonGetStarted"}
2014-08-05T12:16:16.812Z - debug: Proxying command to localhost:9001
2014-08-05T12:16:16.814Z - debug: Making http request with opts: {"url":"http://localhost:9001/wd/hub/session/5ce7ce86-3dd5-ee90-7744-e5ec8dae26b2/element","method":"POST","json":{"using":"id","value":"buttonGetStarted"}}
2014-08-05T12:16:16.834Z - debug: Proxied response received with status 200: {"value":{"ELEMENT":"ea6d0667-ce49-fb77-bcdd-8b6f256c3847"},"status":0,"sessionId":"5ce7ce86-3dd5-ee90-7744-e5ec8dae26b2"}
2014-08-05T12:16:16.837Z - info: <-- POST /wd/hub/session/5ce7ce86-3dd5-ee90-7744-e5ec8dae26b2/element 200 25.924 ms - 122 
2014-08-05T12:16:16.853Z - info: --> POST /wd/hub/session/5ce7ce86-3dd5-ee90-7744-e5ec8dae26b2/element/ea6d0667-ce49-fb77-bcdd-8b6f256c3847/click {"id":"ea6d0667-ce49-fb77-bcdd-8b6f256c3847"}
2014-08-05T12:16:16.853Z - debug: Appium request initiated at /wd/hub/session/5ce7ce86-3dd5-ee90-7744-e5ec8dae26b2/element/ea6d0667-ce49-fb77-bcdd-8b6f256c3847/click
2014-08-05T12:16:16.853Z - debug: Request received with params: {"id":"ea6d0667-ce49-fb77-bcdd-8b6f256c3847"}
2014-08-05T12:16:16.854Z - debug: Proxying command to localhost:9001
2014-08-05T12:16:16.854Z - debug: Making http request with opts: {"url":"http://localhost:9001/wd/hub/session/5ce7ce86-3dd5-ee90-7744-e5ec8dae26b2/element/ea6d0667-ce49-fb77-bcdd-8b6f256c3847/click","method":"POST","json":{"id":"ea6d0667-ce49-fb77-bcdd-8b6f256c3847"}}
2014-08-05T12:16:17.506Z - debug: Proxied response received with status 200: {"value":"","status":0,"sessionId":"5ce7ce86-3dd5-ee90-7744-e5ec8dae26b2"}
2014-08-05T12:16:17.507Z - info: <-- POST /wd/hub/session/5ce7ce86-3dd5-ee90-7744-e5ec8dae26b2/element/ea6d0667-ce49-fb77-bcdd-8b6f256c3847/click 200 654.872 ms - 74 
2014-08-05T12:16:17.520Z - info: --> POST /wd/hub/session/5ce7ce86-3dd5-ee90-7744-e5ec8dae26b2/element {"using":"id","value":"passwordField"}
2014-08-05T12:16:17.521Z - debug: Appium request initiated at /wd/hub/session/5ce7ce86-3dd5-ee90-7744-e5ec8dae26b2/element
2014-08-05T12:16:17.523Z - debug: Request received with params: {"using":"id","value":"passwordField"}
2014-08-05T12:16:17.523Z - debug: Proxying command to localhost:9001
2014-08-05T12:16:17.526Z - debug: Making http request with opts: {"url":"http://localhost:9001/wd/hub/session/5ce7ce86-3dd5-ee90-7744-e5ec8dae26b2/element","method":"POST","json":{"using":"id","value":"passwordField"}}
2014-08-05T12:16:22.676Z - 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":"Element was not found.\nio.selendroid.exceptions.NoSuchElementException: Element was not found.\n\tat io.selendroid.server.handler.FindElement.handle(FindElement.java:57)\n\tat io.selendroid.server.AndroidServlet.handleRequest(AndroidServlet.java:290)\n\tat io.selendroid.server.BaseServlet.handleHttpRequest(BaseServlet.java:70)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:78)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:62)\n\tat io.selendroid.server.inspector.InspectorServlet.handleHttpRequest(InspectorServlet.java:78)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:78)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:62)\n\tat org.webbitserver.handler.PathMatchHandler.handleHttpRequest(PathMatchHandler.java:33)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:78)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:62)\n\tat org.webbitserver.handler.DateHeaderHandler.handleHttpRequest(DateHeaderHandler.java:21)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:78)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:62)\n\tat org.webbitserver.handler.ServerHeaderHandler.handleHttpRequest(ServerHeaderHandler.java:25)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:78)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:67)\n\tat org.webbitserver.netty.NettyHttpChannelHandler$2.run(NettyHttpChannelHandler.java:72)\n\tat java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1076)\n\tat java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:569)\n\tat java.lang.Thread.run(Thread.java:864)\n"},"sessionId":"5ce7ce86-3dd5-ee90-7744-e5ec8dae26b2"}
2014-08-05T12:16:22.677Z - info: <-- POST /wd/hub/session/5ce7ce86-3dd5-ee90-7744-e5ec8dae26b2/element 500 5158.199 ms - 2040 
2014-08-05T12:16:22.694Z - info: --> POST /wd/hub/session/5ce7ce86-3dd5-ee90-7744-e5ec8dae26b2/element {"using":"id","value":"passwordSetupPasswordField"}
2014-08-05T12:16:22.695Z - debug: Appium request initiated at /wd/hub/session/5ce7ce86-3dd5-ee90-7744-e5ec8dae26b2/element
2014-08-05T12:16:22.695Z - debug: Request received with params: {"using":"id","value":"passwordSetupPasswordField"}
2014-08-05T12:16:22.696Z - debug: Proxying command to localhost:9001
2014-08-05T12:16:22.698Z - debug: Making http request with opts: {"url":"http://localhost:9001/wd/hub/session/5ce7ce86-3dd5-ee90-7744-e5ec8dae26b2/element","method":"POST","json":{"using":"id","value":"passwordSetupPasswordField"}}
2014-08-05T12:16:22.731Z - debug: Proxied response received with status 200: {"value":{"ELEMENT":"a13b5a55-7664-d210-f1a1-fab9b57e036b"},"status":0,"sessionId":"5ce7ce86-3dd5-ee90-7744-e5ec8dae26b2"}
2014-08-05T12:16:22.734Z - info: <-- POST /wd/hub/session/5ce7ce86-3dd5-ee90-7744-e5ec8dae26b2/element 200 39.366 ms - 122 
2014-08-05T12:16:22.744Z - info: --> POST /wd/hub/session/5ce7ce86-3dd5-ee90-7744-e5ec8dae26b2/element/a13b5a55-7664-d210-f1a1-fab9b57e036b/value {"id":"a13b5a55-7664-d210-f1a1-fab9b57e036b","value":["abc"]}
2014-08-05T12:16:22.744Z - debug: Appium request initiated at /wd/hub/session/5ce7ce86-3dd5-ee90-7744-e5ec8dae26b2/element/a13b5a55-7664-d210-f1a1-fab9b57e036b/value
2014-08-05T12:16:22.745Z - debug: Request received with params: {"id":"a13b5a55-7664-d210-f1a1-fab9b57e036b","value":["abc"]}
2014-08-05T12:16:22.746Z - debug: Setting text on element 'a13b5a55-7664-d210-f1a1-fab9b57e036b': 'abc'
2014-08-05T12:16:22.746Z - debug: Making http request with opts: {"url":"http://localhost:8080/wd/hub/session/5ce7ce86-3dd5-ee90-7744-e5ec8dae26b2/element/a13b5a55-7664-d210-f1a1-fab9b57e036b/value","method":"POST","json":{"value":["abc"]}}
2014-08-05T12:16:22.752Z - debug: Responding to client with success: {"status":0,"value":"","sessionId":"5ce7ce86-3dd5-ee90-7744-e5ec8dae26b2"}
2014-08-05T12:16:22.752Z - info: <-- POST /wd/hub/session/5ce7ce86-3dd5-ee90-7744-e5ec8dae26b2/element/a13b5a55-7664-d210-f1a1-fab9b57e036b/value 200 8.941 ms - 74 {"status":0,"value":"","sessionId":"5ce7ce86-3dd5-ee90-7744-e5ec8dae26b2"}
2014-08-05T12:16:22.760Z - info: --> POST /wd/hub/session/5ce7ce86-3dd5-ee90-7744-e5ec8dae26b2/element {"using":"id","value":"passwordSetupConfirmPasswordField"}
2014-08-05T12:16:22.760Z - debug: Appium request initiated at /wd/hub/session/5ce7ce86-3dd5-ee90-7744-e5ec8dae26b2/element
2014-08-05T12:16:22.761Z - debug: Request received with params: {"using":"id","value":"passwordSetupConfirmPasswordField"}
2014-08-05T12:16:22.761Z - debug: Proxying command to localhost:9001
2014-08-05T12:16:22.761Z - debug: Making http request with opts: {"url":"http://localhost:9001/wd/hub/session/5ce7ce86-3dd5-ee90-7744-e5ec8dae26b2/element","method":"POST","json":{"using":"id","value":"passwordSetupConfirmPasswordField"}}
2014-08-05T12:16:22.799Z - debug: Proxied response received with status 200: {"value":{"ELEMENT":"aee838be-c5be-0279-e35f-f3503502980d"},"status":0,"sessionId":"5ce7ce86-3dd5-ee90-7744-e5ec8dae26b2"}
2014-08-05T12:16:22.799Z - info: <-- POST /wd/hub/session/5ce7ce86-3dd5-ee90-7744-e5ec8dae26b2/element 200 39.769 ms - 122 
2014-08-05T12:16:22.804Z - info: --> POST /wd/hub/session/5ce7ce86-3dd5-ee90-7744-e5ec8dae26b2/element/aee838be-c5be-0279-e35f-f3503502980d/value {"id":"aee838be-c5be-0279-e35f-f3503502980d","value":["abc"]}
2014-08-05T12:16:22.804Z - debug: Appium request initiated at /wd/hub/session/5ce7ce86-3dd5-ee90-7744-e5ec8dae26b2/element/aee838be-c5be-0279-e35f-f3503502980d/value
2014-08-05T12:16:22.804Z - debug: Request received with params: {"id":"aee838be-c5be-0279-e35f-f3503502980d","value":["abc"]}
2014-08-05T12:16:22.805Z - debug: Setting text on element 'aee838be-c5be-0279-e35f-f3503502980d': 'abc'
2014-08-05T12:16:22.805Z - debug: Making http request with opts: {"url":"http://localhost:8080/wd/hub/session/5ce7ce86-3dd5-ee90-7744-e5ec8dae26b2/element/aee838be-c5be-0279-e35f-f3503502980d/value","method":"POST","json":{"value":["abc"]}}
2014-08-05T12:16:22.813Z - debug: Responding to client with success: {"status":0,"value":"","sessionId":"5ce7ce86-3dd5-ee90-7744-e5ec8dae26b2"}
2014-08-05T12:16:22.817Z - info: <-- POST /wd/hub/session/5ce7ce86-3dd5-ee90-7744-e5ec8dae26b2/element/aee838be-c5be-0279-e35f-f3503502980d/value 200 12.603 ms - 74 {"status":0,"value":"","sessionId":"5ce7ce86-3dd5-ee90-7744-e5ec8dae26b2"}
2014-08-05T12:16:22.821Z - info: --> POST /wd/hub/session/5ce7ce86-3dd5-ee90-7744-e5ec8dae26b2/element {"using":"id","value":"passwordSetupButton"}
2014-08-05T12:16:22.822Z - debug: Appium request initiated at /wd/hub/session/5ce7ce86-3dd5-ee90-7744-e5ec8dae26b2/element
2014-08-05T12:16:22.822Z - debug: Request received with params: {"using":"id","value":"passwordSetupButton"}
2014-08-05T12:16:22.822Z - debug: Proxying command to localhost:9001
2014-08-05T12:16:22.822Z - debug: Making http request with opts: {"url":"http://localhost:9001/wd/hub/session/5ce7ce86-3dd5-ee90-7744-e5ec8dae26b2/element","method":"POST","json":{"using":"id","value":"passwordSetupButton"}}
2014-08-05T12:16:22.861Z - debug: Proxied response received with status 200: {"value":{"ELEMENT":"0cb4d25a-1323-5666-3425-403873d66db2"},"status":0,"sessionId":"5ce7ce86-3dd5-ee90-7744-e5ec8dae26b2"}
2014-08-05T12:16:22.862Z - info: <-- POST /wd/hub/session/5ce7ce86-3dd5-ee90-7744-e5ec8dae26b2/element 200 42.613 ms - 122 
2014-08-05T12:16:22.873Z - info: --> POST /wd/hub/session/5ce7ce86-3dd5-ee90-7744-e5ec8dae26b2/element/0cb4d25a-1323-5666-3425-403873d66db2/click {"id":"0cb4d25a-1323-5666-3425-403873d66db2"}
2014-08-05T12:16:22.873Z - debug: Appium request initiated at /wd/hub/session/5ce7ce86-3dd5-ee90-7744-e5ec8dae26b2/element/0cb4d25a-1323-5666-3425-403873d66db2/click
2014-08-05T12:16:22.873Z - debug: Request received with params: {"id":"0cb4d25a-1323-5666-3425-403873d66db2"}
2014-08-05T12:16:22.873Z - debug: Proxying command to localhost:9001
2014-08-05T12:16:22.874Z - debug: Making http request with opts: {"url":"http://localhost:9001/wd/hub/session/5ce7ce86-3dd5-ee90-7744-e5ec8dae26b2/element/0cb4d25a-1323-5666-3425-403873d66db2/click","method":"POST","json":{"id":"0cb4d25a-1323-5666-3425-403873d66db2"}}
2014-08-05T12:16:23.556Z - debug: Proxied response received with status 200: {"value":"","status":0,"sessionId":"5ce7ce86-3dd5-ee90-7744-e5ec8dae26b2"}
2014-08-05T12:16:23.560Z - info: <-- POST /wd/hub/session/5ce7ce86-3dd5-ee90-7744-e5ec8dae26b2/element/0cb4d25a-1323-5666-3425-403873d66db2/click 200 687.100 ms - 74 
2014-08-05T12:16:23.584Z - info: --> POST /wd/hub/session/5ce7ce86-3dd5-ee90-7744-e5ec8dae26b2/element {"using":"id","value":"alertTitle"}
2014-08-05T12:16:23.585Z - debug: Appium request initiated at /wd/hub/session/5ce7ce86-3dd5-ee90-7744-e5ec8dae26b2/element
2014-08-05T12:16:23.585Z - debug: Request received with params: {"using":"id","value":"alertTitle"}
2014-08-05T12:16:23.586Z - debug: Proxying command to localhost:9001
2014-08-05T12:16:23.586Z - debug: Making http request with opts: {"url":"http://localhost:9001/wd/hub/session/5ce7ce86-3dd5-ee90-7744-e5ec8dae26b2/element","method":"POST","json":{"using":"id","value":"alertTitle"}}
2014-08-05T12:16:28.807Z - 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":"Element was not found.\nio.selendroid.exceptions.NoSuchElementException: Element was not found.\n\tat io.selendroid.server.handler.FindElement.handle(FindElement.java:57)\n\tat io.selendroid.server.AndroidServlet.handleRequest(AndroidServlet.java:290)\n\tat io.selendroid.server.BaseServlet.handleHttpRequest(BaseServlet.java:70)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:78)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:62)\n\tat io.selendroid.server.inspector.InspectorServlet.handleHttpRequest(InspectorServlet.java:78)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:78)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:62)\n\tat org.webbitserver.handler.PathMatchHandler.handleHttpRequest(PathMatchHandler.java:33)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:78)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:62)\n\tat org.webbitserver.handler.DateHeaderHandler.handleHttpRequest(DateHeaderHandler.java:21)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:78)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:62)\n\tat org.webbitserver.handler.ServerHeaderHandler.handleHttpRequest(ServerHeaderHandler.java:25)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:78)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:67)\n\tat org.webbitserver.netty.NettyHttpChannelHandler$2.run(NettyHttpChannelHandler.java:72)\n\tat java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1076)\n\tat java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:569)\n\tat java.lang.Thread.run(Thread.java:864)\n"},"sessionId":"5ce7ce86-3dd5-ee90-7744-e5ec8dae26b2"}
2014-08-05T12:16:28.810Z - info: <-- POST /wd/hub/session/5ce7ce86-3dd5-ee90-7744-e5ec8dae26b2/element 500 5225.286 ms - 2040 
2014-08-05T12:16:28.823Z - info: --> POST /wd/hub/session/5ce7ce86-3dd5-ee90-7744-e5ec8dae26b2/element {"using":"id","value":"alertTitle"}
2014-08-05T12:16:28.823Z - debug: Appium request initiated at /wd/hub/session/5ce7ce86-3dd5-ee90-7744-e5ec8dae26b2/element
2014-08-05T12:16:28.824Z - debug: Request received with params: {"using":"id","value":"alertTitle"}
2014-08-05T12:16:28.824Z - debug: Proxying command to localhost:9001
2014-08-05T12:16:28.824Z - debug: Making http request with opts: {"url":"http://localhost:9001/wd/hub/session/5ce7ce86-3dd5-ee90-7744-e5ec8dae26b2/element","method":"POST","json":{"using":"id","value":"alertTitle"}}
2014-08-05T12:16:33.911Z - 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":"Element was not found.\nio.selendroid.exceptions.NoSuchElementException: Element was not found.\n\tat io.selendroid.server.handler.FindElement.handle(FindElement.java:57)\n\tat io.selendroid.server.AndroidServlet.handleRequest(AndroidServlet.java:290)\n\tat io.selendroid.server.BaseServlet.handleHttpRequest(BaseServlet.java:70)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:78)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:62)\n\tat io.selendroid.server.inspector.InspectorServlet.handleHttpRequest(InspectorServlet.java:78)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:78)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:62)\n\tat org.webbitserver.handler.PathMatchHandler.handleHttpRequest(PathMatchHandler.java:33)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:78)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:62)\n\tat org.webbitserver.handler.DateHeaderHandler.handleHttpRequest(DateHeaderHandler.java:21)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:78)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:62)\n\tat org.webbitserver.handler.ServerHeaderHandler.handleHttpRequest(ServerHeaderHandler.java:25)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:78)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:67)\n\tat org.webbitserver.netty.NettyHttpChannelHandler$2.run(NettyHttpChannelHandler.java:72)\n\tat java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1076)\n\tat java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:569)\n\tat java.lang.Thread.run(Thread.java:864)\n"},"sessionId":"5ce7ce86-3dd5-ee90-7744-e5ec8dae26b2"}
2014-08-05T12:16:33.912Z - info: <-- POST /wd/hub/session/5ce7ce86-3dd5-ee90-7744-e5ec8dae26b2/element 500 5088.698 ms - 2040 
2014-08-05T12:16:33.918Z - info: --> POST /wd/hub/session/5ce7ce86-3dd5-ee90-7744-e5ec8dae26b2/element {"using":"id","value":"alertTitle"}
2014-08-05T12:16:33.918Z - debug: Appium request initiated at /wd/hub/session/5ce7ce86-3dd5-ee90-7744-e5ec8dae26b2/element
2014-08-05T12:16:33.918Z - debug: Request received with params: {"using":"id","value":"alertTitle"}
2014-08-05T12:16:33.918Z - debug: Proxying command to localhost:9001
2014-08-05T12:16:33.918Z - debug: Making http request with opts: {"url":"http://localhost:9001/wd/hub/session/5ce7ce86-3dd5-ee90-7744-e5ec8dae26b2/element","method":"POST","json":{"using":"id","value":"alertTitle"}}
2014-08-05T12:16:39.200Z - 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":"Element was not found.\nio.selendroid.exceptions.NoSuchElementException: Element was not found.\n\tat io.selendroid.server.handler.FindElement.handle(FindElement.java:57)\n\tat io.selendroid.server.AndroidServlet.handleRequest(AndroidServlet.java:290)\n\tat io.selendroid.server.BaseServlet.handleHttpRequest(BaseServlet.java:70)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:78)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:62)\n\tat io.selendroid.server.inspector.InspectorServlet.handleHttpRequest(InspectorServlet.java:78)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:78)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:62)\n\tat org.webbitserver.handler.PathMatchHandler.handleHttpRequest(PathMatchHandler.java:33)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:78)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:62)\n\tat org.webbitserver.handler.DateHeaderHandler.handleHttpRequest(DateHeaderHandler.java:21)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:78)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:62)\n\tat org.webbitserver.handler.ServerHeaderHandler.handleHttpRequest(ServerHeaderHandler.java:25)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:78)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:67)\n\tat org.webbitserver.netty.NettyHttpChannelHandler$2.run(NettyHttpChannelHandler.java:72)\n\tat java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1076)\n\tat java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:569)\n\tat java.lang.Thread.run(Thread.java:864)\n"},"sessionId":"5ce7ce86-3dd5-ee90-7744-e5ec8dae26b2"}
2014-08-05T12:16:39.201Z - info: <-- POST /wd/hub/session/5ce7ce86-3dd5-ee90-7744-e5ec8dae26b2/element 500 5283.702 ms - 2040 
2014-08-05T12:16:39.214Z - info: --> POST /wd/hub/session/5ce7ce86-3dd5-ee90-7744-e5ec8dae26b2/element {"using":"id","value":"localProfileShortNameField"}
2014-08-05T12:16:39.215Z - debug: Appium request initiated at /wd/hub/session/5ce7ce86-3dd5-ee90-7744-e5ec8dae26b2/element
2014-08-05T12:16:39.215Z - debug: Request received with params: {"using":"id","value":"localProfileShortNameField"}
2014-08-05T12:16:39.215Z - debug: Proxying command to localhost:9001
2014-08-05T12:16:39.215Z - debug: Making http request with opts: {"url":"http://localhost:9001/wd/hub/session/5ce7ce86-3dd5-ee90-7744-e5ec8dae26b2/element","method":"POST","json":{"using":"id","value":"localProfileShortNameField"}}
2014-08-05T12:16:44.349Z - 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":"Element was not found.\nio.selendroid.exceptions.NoSuchElementException: Element was not found.\n\tat io.selendroid.server.handler.FindElement.handle(FindElement.java:57)\n\tat io.selendroid.server.AndroidServlet.handleRequest(AndroidServlet.java:290)\n\tat io.selendroid.server.BaseServlet.handleHttpRequest(BaseServlet.java:70)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:78)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:62)\n\tat io.selendroid.server.inspector.InspectorServlet.handleHttpRequest(InspectorServlet.java:78)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:78)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:62)\n\tat org.webbitserver.handler.PathMatchHandler.handleHttpRequest(PathMatchHandler.java:33)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:78)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:62)\n\tat org.webbitserver.handler.DateHeaderHandler.handleHttpRequest(DateHeaderHandler.java:21)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:78)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:62)\n\tat org.webbitserver.handler.ServerHeaderHandler.handleHttpRequest(ServerHeaderHandler.java:25)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:78)\n\tat org.webbitserver.netty.NettyHttpControl.nextHandler(NettyHttpControl.java:67)\n\tat org.webbitserver.netty.NettyHttpChannelHandler$2.run(NettyHttpChannelHandler.java:72)\n\tat java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1076)\n\tat java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:569)\n\tat java.lang.Thread.run(Thread.java:864)\n"},"sessionId":"5ce7ce86-3dd5-ee90-7744-e5ec8dae26b2"}
2014-08-05T12:16:44.358Z - info: <-- POST /wd/hub/session/5ce7ce86-3dd5-ee90-7744-e5ec8dae26b2/element 500 5140.037 ms - 2040 

SelendroidInstrumentation1.1.0.log

08-05 17:52:11.015: W/ActivityManager(427): Crash of app com.test.app.android running instrumentation ComponentInfo{com.test.app.android.selendroid/io.selendroid.ServerInstrumentation}
08-05 17:52:13.918: I/SELENDROID(21263): io.selendroid.ServerInstrumentation#onCreate:121 - Instrumentation initialized with main activity: com.test.app.android.gui.WelcomeActivity
08-05 17:52:13.928: E/SELENDROID(21263): io.selendroid.ServerInstrumentation#onStart:139 - Error
08-05 17:52:16.451: I/SELENDROID(21263): io.selendroid.ServerInstrumentation$HttpdThread#startServer:319 - Started selendroid http server on port 8080
08-05 17:52:16.551: I/SELENDROID(21263): io.selendroid.server.handler.NewSession#handle:35 - new session command
08-05 17:52:16.561: I/SELENDROID(21263): io.selendroid.server.model.DefaultSelendroidDriver#initializeSession:388 - new s: ea2154af-3a85-ed67-2142-d7093b1f4a7b
08-05 17:52:16.791: I/SELENDROID(21263): io.selendroid.server.handler.GetCapabilities#handle:36 - get capabilities command
08-05 17:52:16.791: I/SELENDROID(21263): io.selendroid.server.model.DefaultSelendroidDriver#getSessionCapabilities:180 - session: ea2154af-3a85-ed67-2142-d7093b1f4a7b
08-05 17:52:16.791: I/SELENDROID(21263): io.selendroid.server.model.DefaultSelendroidDriver#getSessionCapabilities:203 - capabilities: {"automationName":"selendroid","platform":"android","platformVersion":"15","acceptSslCerts":true,"javascriptEnabled":true,"platformName":"android","handlesAlerts":true,"browserName":"selendroid","networkConnectionEnabled":true,"rotatable":true,"takesScreenshot":true,"version":"0.10.0"}
08-05 17:52:16.891: I/SELENDROID(21263): io.selendroid.server.handler.FindElement#handle:40 - find element command using 'id' with selector 'buttonGetStarted'.
08-05 17:52:16.931: I/SELENDROID(21263): io.selendroid.server.handler.ClickElement#handle:35 - Click element command
08-05 17:52:17.602: I/SELENDROID(21263): io.selendroid.server.handler.FindElement#handle:40 - find element command using 'id' with selector 'passwordField'.
08-05 17:52:22.747: I/SELENDROID(21263): io.selendroid.server.handler.FindElement#handle:40 - find element command using 'id' with selector 'passwordSetupPasswordField'.
08-05 17:52:22.798: I/SELENDROID(21263): io.selendroid.server.handler.SendKeys#handle:36 - send keys command
08-05 17:52:23.618: I/SELENDROID(21263): io.selendroid.server.handler.FindElement#handle:40 - find element command using 'id' with selector 'passwordSetupConfirmPasswordField'.
08-05 17:52:23.658: I/SELENDROID(21263): io.selendroid.server.handler.SendKeys#handle:36 - send keys command
08-05 17:52:24.559: I/SELENDROID(21263): io.selendroid.server.handler.FindElement#handle:40 - find element command using 'id' with selector 'passwordSetupButton'.
08-05 17:52:24.609: I/SELENDROID(21263): io.selendroid.server.handler.ClickElement#handle:35 - Click element command
08-05 17:52:25.510: I/SELENDROID(21263): io.selendroid.server.handler.FindElement#handle:40 - find element command using 'id' with selector 'alertTitle'.
08-05 17:52:25.530: I/SELENDROID(21263): io.selendroid.server.handler.GetElementDisplayed#handle:34 - is element displayed command
08-05 17:52:26.051: I/SELENDROID(21263): io.selendroid.server.handler.FindElement#handle:40 - find element command using 'id' with selector 'alertTitle'.
08-05 17:52:26.081: I/SELENDROID(21263): io.selendroid.server.handler.GetElementDisplayed#handle:34 - is element displayed command
08-05 17:52:26.612: I/SELENDROID(21263): io.selendroid.server.handler.FindElement#handle:40 - find element command using 'id' with selector 'alertTitle'.
08-05 17:52:26.642: I/SELENDROID(21263): io.selendroid.server.handler.GetElementDisplayed#handle:34 - is element displayed command
08-05 17:52:27.192: I/SELENDROID(21263): io.selendroid.server.handler.FindElement#handle:40 - find element command using 'id' with selector 'alertTitle'.
08-05 17:52:27.242: I/SELENDROID(21263): io.selendroid.server.handler.GetElementDisplayed#handle:34 - is element displayed command
08-05 17:52:27.753: I/SELENDROID(21263): io.selendroid.server.handler.FindElement#handle:40 - find element command using 'id' with selector 'alertTitle'.
08-05 17:52:32.898: I/SELENDROID(21263): io.selendroid.server.handler.FindElement#handle:40 - find element command using 'id' with selector 'alertTitle'.
08-05 17:52:38.144: I/SELENDROID(21263): io.selendroid.server.handler.FindElement#handle:40 - find element command using 'id' with selector 'alertTitle'.
08-05 17:52:43.359: I/SELENDROID(21263): io.selendroid.server.handler.FindElement#handle:40 - find element command using 'id' with selector 'localProfileShortNameField'.
08-05 17:52:43.400: I/SELENDROID(21263): io.selendroid.server.handler.FindElement#handle:40 - find element command using 'id' with selector 'localProfileShortNameField'.
08-05 17:52:43.450: I/SELENDROID(21263): io.selendroid.server.handler.GetText#handle:35 - get text command
08-05 17:52:43.470: I/SELENDROID(21263): io.selendroid.server.handler.FindElement#handle:40 - find element command using 'id' with selector 'localProfileDeviceDescriptionField'.
08-05 17:52:43.520: I/SELENDROID(21263): io.selendroid.server.handler.GetText#handle:35 - get text command
08-05 17:52:43.550: I/SELENDROID(21263): io.selendroid.server.handler.FindElement#handle:40 - find element command using 'id' with selector 'localProfileButtonSave'.
08-05 17:52:43.580: I/SELENDROID(21263): io.selendroid.server.handler.ClickElement#handle:35 - Click element command
08-05 17:52:44.371: I/SELENDROID(21263): io.selendroid.server.handler.timeouts.SetImplicitWaitTimeout#handle:32 - set implicit wait timeout called
08-05 17:52:44.371: I/SELENDROID(21263): io.selendroid.server.AndroidServer#setConnectionTimeout:76 - using staleConnectionTimeout: 5000
08-05 17:52:44.391: I/SELENDROID(21263): io.selendroid.server.handler.FindElement#handle:40 - find element command using 'name' with selector 'Add Contact'.
08-05 17:52:44.411: I/SELENDROID(21263): io.selendroid.server.handler.ClickElement#handle:35 - Click element command
08-05 17:52:45.071: I/SELENDROID(21263): io.selendroid.server.handler.timeouts.SetImplicitWaitTimeout#handle:32 - set implicit wait timeout called
08-05 17:52:45.071: I/SELENDROID(21263): io.selendroid.server.AndroidServer#setConnectionTimeout:76 - using staleConnectionTimeout: 3000
08-05 17:52:45.091: I/SELENDROID(21263): io.selendroid.server.handler.FindElement#handle:40 - find element command using 'id' with selector 'contactAliasText'.
08-05 17:52:45.121: I/SELENDROID(21263): io.selendroid.server.handler.SendKeys#handle:36 - send keys command
08-05 17:52:45.882: I/SELENDROID(21263): io.selendroid.server.handler.FindElement#handle:40 - find element command using 'id' with selector 'thrContryCodeText'.
08-05 17:52:45.922: I/SELENDROID(21263): io.selendroid.server.handler.SendKeys#handle:36 - send keys command
08-05 17:52:46.763: I/SELENDROID(21263): io.selendroid.server.handler.FindElement#handle:40 - find element command using 'id' with selector 'contactPhoneText'.
08-05 17:52:46.803: I/SELENDROID(21263): io.selendroid.server.handler.SendKeys#handle:36 - send keys command
08-05 17:52:47.604: I/SELENDROID(21263): io.selendroid.server.handler.FindElement#handle:40 - find element command using 'id' with selector 'myContryCodeText'.
08-05 17:52:47.644: I/SELENDROID(21263): io.selendroid.server.handler.ClearElement#handle:34 - Clear element command
08-05 17:52:47.704: I/SELENDROID(21263): io.selendroid.server.handler.FindElement#handle:40 - find element command using 'id' with selector 'myContryCodeText'.
08-05 17:52:47.734: I/SELENDROID(21263): io.selendroid.server.handler.SendKeys#handle:36 - send keys command
08-05 17:52:48.505: I/SELENDROID(21263): io.selendroid.server.handler.FindElement#handle:40 - find element command using 'id' with selector 'myPhoneText'.
08-05 17:52:48.545: I/SELENDROID(21263): io.selendroid.server.handler.ClearElement#handle:34 - Clear element command
08-05 17:52:48.615: I/SELENDROID(21263): io.selendroid.server.handler.FindElement#handle:40 - find element command using 'id' with selector 'myPhoneText'.
08-05 17:52:48.655: I/SELENDROID(21263): io.selendroid.server.handler.SendKeys#handle:36 - send keys command
08-05 17:52:49.416: I/SELENDROID(21263): io.selendroid.server.handler.FindElement#handle:40 - find element command using 'id' with selector 'sendInviteButton'.
08-05 17:52:49.456: I/SELENDROID(21263): io.selendroid.server.handler.ClickElement#handle:35 - Click element command
08-05 17:52:50.437: I/SELENDROID(21263): io.selendroid.server.handler.FindElement#handle:40 - find element command using 'id' with selector 'buddyName'.
08-05 17:52:50.477: I/SELENDROID(21263): io.selendroid.server.handler.LongPressOnElement#handle:36 - Long press on element gesture

SelendroidInstrumentation1.2.0.log

08-05 17:45:48.737: W/ActivityManager(427): Crash of app com.test.app.android running instrumentation ComponentInfo{com.test.app.android.selendroid/io.selendroid.ServerInstrumentation}
08-05 17:45:52.721: I/SELENDROID(20694): io.selendroid.ServerInstrumentation#onCreate:121 - Instrumentation initialized with main activity: com.test.app.android.gui.WelcomeActivity
08-05 17:45:52.741: E/SELENDROID(20694): io.selendroid.ServerInstrumentation#onStart:139 - Error
08-05 17:45:55.154: I/SELENDROID(20694): io.selendroid.ServerInstrumentation$HttpdThread#startServer:319 - Started selendroid http server on port 8080
08-05 17:45:55.304: I/SELENDROID(20694): io.selendroid.server.handler.NewSession#handle:35 - new session command
08-05 17:45:55.304: I/SELENDROID(20694): io.selendroid.server.model.DefaultSelendroidDriver#initializeSession:388 - new s: 5ce7ce86-3dd5-ee90-7744-e5ec8dae26b2
08-05 17:45:55.534: I/SELENDROID(20694): io.selendroid.server.handler.GetCapabilities#handle:36 - get capabilities command
08-05 17:45:55.534: I/SELENDROID(20694): io.selendroid.server.model.DefaultSelendroidDriver#getSessionCapabilities:180 - session: 5ce7ce86-3dd5-ee90-7744-e5ec8dae26b2
08-05 17:45:55.534: I/SELENDROID(20694): io.selendroid.server.model.DefaultSelendroidDriver#getSessionCapabilities:203 - capabilities: {"automationName":"selendroid","platform":"android","platformVersion":"15","acceptSslCerts":true,"javascriptEnabled":true,"platformName":"android","handlesAlerts":true,"browserName":"selendroid","networkConnectionEnabled":true,"rotatable":true,"takesScreenshot":true,"version":"0.10.0"}
08-05 17:45:55.624: I/SELENDROID(20694): io.selendroid.server.handler.FindElement#handle:40 - find element command using 'id' with selector 'buttonGetStarted'.
08-05 17:45:55.664: I/SELENDROID(20694): io.selendroid.server.handler.ClickElement#handle:35 - Click element command
08-05 17:45:56.335: I/SELENDROID(20694): io.selendroid.server.handler.FindElement#handle:40 - find element command using 'id' with selector 'passwordField'.
08-05 17:46:01.521: I/SELENDROID(20694): io.selendroid.server.handler.FindElement#handle:40 - find element command using 'id' with selector 'passwordSetupPasswordField'.
08-05 17:46:01.581: I/SELENDROID(20694): io.selendroid.server.handler.FindElement#handle:40 - find element command using 'id' with selector 'passwordSetupConfirmPasswordField'.
08-05 17:46:01.641: I/SELENDROID(20694): io.selendroid.server.handler.FindElement#handle:40 - find element command using 'id' with selector 'passwordSetupButton'.
08-05 17:46:01.691: I/SELENDROID(20694): io.selendroid.server.handler.ClickElement#handle:35 - Click element command
08-05 17:46:02.412: I/SELENDROID(20694): io.selendroid.server.handler.FindElement#handle:40 - find element command using 'id' with selector 'alertTitle'.
08-05 17:46:07.637: I/SELENDROID(20694): io.selendroid.server.handler.FindElement#handle:40 - find element command using 'id' with selector 'alertTitle'.
08-05 17:46:12.743: I/SELENDROID(20694): io.selendroid.server.handler.FindElement#handle:40 - find element command using 'id' with selector 'alertTitle'.
08-05 17:46:18.048: I/SELENDROID(20694): io.selendroid.server.handler.FindElement#handle:40 - find element command using 'id' with selector 'localProfileShortNameField'.