alice_API17.log

2015-01-19 12:23:15:444 - info: Welcome to Appium v1.3.4 (REV 96dc94037c3f5747d173f6bc6e3abe249026090d)
2015-01-19 12:23:15:447 - info: Appium REST http interface listener started on 0.0.0.0:6001
2015-01-19 12:23:15:454 - info: [debug] Non-default server args: {"udid":"HT26HW300907","port":6001,"bootstrapPort":4001,"sessionOverride":true,"log":"/home/adminuser/Java_Projects/PhantomAutomationMaster/alice.log","selendroidPort":9001}
2015-01-19 12:23:15:455 - info: Console LogLevel: debug
2015-01-19 12:23:15:455 - info: File LogLevel: debug
2015-01-19 12:23:25:872 - info: --> POST /wd/hub/session {"desiredCapabilities":{"automationName":"Appium","newCommandTimeout":"3600","platformVersion":"4.4.2","app":"/home/adminuser/Java_Projects/alice/target/test-classes/en-android.apk","platformName":"Android","deviceName":"Android","browserName":"","appActivity":".gui.SplashActivity","deviceReadyTimeout":"3600","appPackage":"com.test.app"}}
2015-01-19 12:23:25:876 - info: Client User-Agent string: Apache-HttpClient/4.4-beta1 (Java 1.5 minimum; Java/1.7.0_72)
2015-01-19 12:23:25:882 - info: [debug] Using local app from desired caps: /home/adminuser/Java_Projects/alice/target/test-classes/en-android.apk
2015-01-19 12:23:25:884 - info: [debug] Creating new appium session 0f38909a-71ed-4983-861f-a4a00b6fe9e4
2015-01-19 12:23:25:885 - info: Starting android appium
2015-01-19 12:23:25:888 - info: [debug] Getting Java version
2015-01-19 12:23:25:977 - info: Java version is: 1.7.0_72
2015-01-19 12:23:25:979 - info: [debug] Checking whether adb is present
2015-01-19 12:23:25:980 - info: [debug] Using adb from /opt/android-sdk-linux/platform-tools/adb
2015-01-19 12:23:25:981 - info: [debug] Using fast reset? true
2015-01-19 12:23:25:982 - info: [debug] Preparing device for session
2015-01-19 12:23:25:982 - info: [debug] Checking whether app is actually present
2015-01-19 12:23:25:983 - info: Retrieving device
2015-01-19 12:23:25:984 - info: [debug] Trying to find a connected android device
2015-01-19 12:23:25:985 - info: [debug] Getting connected devices...
2015-01-19 12:23:25:987 - info: [debug] executing cmd: /opt/android-sdk-linux/platform-tools/adb devices
2015-01-19 12:23:27:974 - info: --> POST /wd/hub/session {"desiredCapabilities":{"automationName":"Appium","newCommandTimeout":"3600","platformVersion":"4.4.2","app":"/home/adminuser/Java_Projects/bob/target/test-classes/en-android.apk","platformName":"Android","deviceName":"Android","browserName":"","appActivity":".gui.SplashActivity","deviceReadyTimeout":"3600","appPackage":"com.test.app"}}
2015-01-19 12:23:27:975 - info: Client User-Agent string: Apache-HttpClient/4.4-beta1 (Java 1.5 minimum; Java/1.7.0_72)
2015-01-19 12:23:27:976 - info: Found an existing session to clobber, shutting it down first...
2015-01-19 12:23:27:977 - info: Shutting down appium session
2015-01-19 12:23:27:978 - info: [debug] Pressing the HOME button
2015-01-19 12:23:27:979 - info: [debug] executing cmd: /opt/android-sdk-linux/platform-tools/adb shell "input keyevent 3"
2015-01-19 12:23:28:006 - info: [debug] Could not find devices, restarting adb server...
2015-01-19 12:23:29:010 - info: [debug] executing cmd: /opt/android-sdk-linux/platform-tools/adb kill-server
2015-01-19 12:23:29:017 - info: [debug] Getting connected devices...
2015-01-19 12:23:29:018 - info: [debug] executing cmd: /opt/android-sdk-linux/platform-tools/adb devices
2015-01-19 12:23:30:997 - info: [debug] Sent shutdown command, waiting for UiAutomator to stop...
2015-01-19 12:23:32:029 - info: [debug] 2 device(s) connected
2015-01-19 12:23:32:030 - info: Found device HT26HW300907
2015-01-19 12:23:32:031 - info: [debug] Setting device id to HT26HW300907
2015-01-19 12:23:32:032 - info: [debug] Waiting for device to be ready and to respond to shell commands (timeout = 3600)
2015-01-19 12:23:32:034 - info: [debug] executing cmd: /opt/android-sdk-linux/platform-tools/adb -s HT26HW300907 wait-for-device
2015-01-19 12:23:32:046 - info: [debug] executing cmd: /opt/android-sdk-linux/platform-tools/adb -s HT26HW300907 shell "echo 'ready'"
2015-01-19 12:23:32:111 - info: [debug] Starting logcat capture
2015-01-19 12:23:32:203 - info: [debug] Getting device API level
2015-01-19 12:23:32:204 - info: [debug] executing cmd: /opt/android-sdk-linux/platform-tools/adb -s HT26HW300907 shell "getprop ro.build.version.sdk"
2015-01-19 12:23:32:314 - info: [debug] Device is at API Level 17
2015-01-19 12:23:32:315 - info: Device API level is: 17
2015-01-19 12:23:32:316 - info: [debug] executing cmd: /opt/android-sdk-linux/platform-tools/adb -s HT26HW300907 shell "getprop persist.sys.language"
2015-01-19 12:23:32:371 - info: [debug] Current device persist.sys.language: en
2015-01-19 12:23:32:371 - info: [debug] java -jar "/home/adminuser/Java_Projects/Appium/appium/node_modules/appium-adb/jars/appium_apk_tools.jar" "stringsFromApk" "/home/adminuser/Java_Projects/alice/target/test-classes/en-android.apk" "/tmp/com.test.app" en
2015-01-19 12:23:32:869 - info: [debug] No strings.xml for language 'en', getting default strings.xml
2015-01-19 12:23:32:872 - info: [debug] java -jar "/home/adminuser/Java_Projects/Appium/appium/node_modules/appium-adb/jars/appium_apk_tools.jar" "stringsFromApk" "/home/adminuser/Java_Projects/alice/target/test-classes/en-android.apk" "/tmp/com.test.app"
2015-01-19 12:23:33:306 - info: [debug] executing cmd: /opt/android-sdk-linux/platform-tools/adb -s HT26HW300907 push "/tmp/com.test.app/strings.json" /data/local/tmp
2015-01-19 12:23:33:459 - info: [debug] Checking whether aapt is present
2015-01-19 12:23:33:460 - info: [debug] Using aapt from /opt/android-sdk-linux/build-tools/19.1.0/aapt
2015-01-19 12:23:33:460 - info: [debug] Retrieving process from manifest.
2015-01-19 12:23:33:461 - info: [debug] executing cmd: /opt/android-sdk-linux/build-tools/19.1.0/aapt dump xmltree /home/adminuser/Java_Projects/alice/target/test-classes/en-android.apk AndroidManifest.xml
2015-01-19 12:23:33:467 - info: [debug] Set app process to: com.test.app
2015-01-19 12:23:33:468 - info: [debug] Not uninstalling app since server not started with --full-reset
2015-01-19 12:23:33:468 - info: [debug] Checking app cert for /home/adminuser/Java_Projects/alice/target/test-classes/en-android.apk.
2015-01-19 12:23:33:469 - info: [debug] executing cmd: java -jar /home/adminuser/Java_Projects/Appium/appium/node_modules/appium-adb/jars/verify.jar /home/adminuser/Java_Projects/alice/target/test-classes/en-android.apk
2015-01-19 12:23:33:707 - info: [debug] App not signed with debug cert.
2015-01-19 12:23:33:708 - info: [debug] Resigning apk.
2015-01-19 12:23:33:708 - info: [debug] executing cmd: java -jar /home/adminuser/Java_Projects/Appium/appium/node_modules/appium-adb/jars/sign.jar /home/adminuser/Java_Projects/alice/target/test-classes/en-android.apk --override
2015-01-19 12:23:37:999 - warn: UiAutomator did not shut down fast enough, calling it gone
2015-01-19 12:23:38:000 - info: [debug] Cleaning up android objects
2015-01-19 12:23:38:000 - info: [debug] Cleaning up appium session
2015-01-19 12:23:38:000 - info: Old session shut down OK, proceeding to new session
2015-01-19 12:23:38:002 - info: [debug] Using local app from desired caps: /home/adminuser/Java_Projects/bob/target/test-classes/en-android.apk
2015-01-19 12:23:38:004 - info: [debug] Creating new appium session 9aefa8ef-4064-4c5c-a5dc-ac857f165494
2015-01-19 12:23:38:006 - info: Starting android appium
2015-01-19 12:23:38:006 - info: [debug] Getting Java version
2015-01-19 12:23:38:079 - info: Java version is: 1.7.0_72
2015-01-19 12:23:38:081 - info: [debug] Checking whether adb is present
2015-01-19 12:23:38:082 - info: [debug] Using adb from /opt/android-sdk-linux/platform-tools/adb
2015-01-19 12:23:38:082 - info: [debug] Using fast reset? true
2015-01-19 12:23:38:083 - info: [debug] Preparing device for session
2015-01-19 12:23:38:083 - info: [debug] Checking whether app is actually present
2015-01-19 12:23:38:083 - info: Retrieving device
2015-01-19 12:23:38:083 - info: [debug] Trying to find a connected android device
2015-01-19 12:23:38:083 - info: [debug] Getting connected devices...
2015-01-19 12:23:38:084 - info: [debug] executing cmd: /opt/android-sdk-linux/platform-tools/adb devices
2015-01-19 12:23:38:089 - info: [debug] 2 device(s) connected
2015-01-19 12:23:38:090 - info: Found device HT26HW300907
2015-01-19 12:23:38:091 - info: [debug] Setting device id to HT26HW300907
2015-01-19 12:23:38:091 - info: [debug] Waiting for device to be ready and to respond to shell commands (timeout = 3600)
2015-01-19 12:23:38:092 - info: [debug] executing cmd: /opt/android-sdk-linux/platform-tools/adb -s HT26HW300907 wait-for-device
2015-01-19 12:23:38:099 - info: [debug] executing cmd: /opt/android-sdk-linux/platform-tools/adb -s HT26HW300907 shell "echo 'ready'"
2015-01-19 12:23:38:158 - info: [debug] Starting logcat capture
2015-01-19 12:23:38:246 - info: [debug] Getting device API level
2015-01-19 12:23:38:246 - info: [debug] executing cmd: /opt/android-sdk-linux/platform-tools/adb -s HT26HW300907 shell "getprop ro.build.version.sdk"
2015-01-19 12:23:38:372 - info: [debug] Device is at API Level 17
2015-01-19 12:23:38:372 - info: Device API level is: 17
2015-01-19 12:23:38:373 - info: [debug] executing cmd: /opt/android-sdk-linux/platform-tools/adb -s HT26HW300907 shell "getprop persist.sys.language"
2015-01-19 12:23:38:430 - info: [debug] Current device persist.sys.language: en
2015-01-19 12:23:38:430 - info: [debug] java -jar "/home/adminuser/Java_Projects/Appium/appium/node_modules/appium-adb/jars/appium_apk_tools.jar" "stringsFromApk" "/home/adminuser/Java_Projects/bob/target/test-classes/en-android.apk" "/tmp/com.test.app" en
2015-01-19 12:23:39:713 - info: [debug] Zip-aligning /home/adminuser/Java_Projects/alice/target/test-classes/en-android.apk
2015-01-19 12:23:39:713 - info: [debug] Checking whether zipalign is present
2015-01-19 12:23:39:714 - info: [debug] Using zipalign from /opt/android-sdk-linux/build-tools/19.1.0/zipalign
2015-01-19 12:23:39:714 - info: [debug] Zip-aligning apk.
2015-01-19 12:23:39:715 - info: [debug] executing cmd: /opt/android-sdk-linux/build-tools/19.1.0/zipalign -f 4 /home/adminuser/Java_Projects/alice/target/test-classes/en-android.apk /tmp/115019-31133-smd4i2/appium.tmp
2015-01-19 12:23:40:029 - info: [debug] No strings.xml for language 'en', getting default strings.xml
2015-01-19 12:23:40:029 - info: [debug] java -jar "/home/adminuser/Java_Projects/Appium/appium/node_modules/appium-adb/jars/appium_apk_tools.jar" "stringsFromApk" "/home/adminuser/Java_Projects/bob/target/test-classes/en-android.apk" "/tmp/com.test.app"
2015-01-19 12:23:40:135 - info: [debug] MD5 for app is 1ce2739ca6cfb2b711e52c7febd0b399
2015-01-19 12:23:40:143 - error: Unhandled error: TypeError: Cannot call method 'shell' of null
    at null.<anonymous> (/home/adminuser/Java_Projects/Appium/appium/lib/devices/android/android-common.js:458:14)
    at null.<anonymous> (/home/adminuser/Java_Projects/Appium/appium/lib/devices/android/android-common.js:441:5)
    at null.<anonymous> (/home/adminuser/Java_Projects/Appium/appium/lib/devices/android/android-common.js:450:7)
    at null.<anonymous> (/home/adminuser/Java_Projects/Appium/appium/lib/devices/android/android-common.js:559:7)
    at ReadStream.<anonymous> (/home/adminuser/Java_Projects/Appium/appium/node_modules/md5calculator/src/utils/md5.js:31:12)
    at ReadStream.emit (events.js:117:20)
    at _stream_readable.js:944:16
    at process._tickDomainCallback (node.js:486:13) context: [POST /wd/hub/session {"desiredCapabilities":{"automationName":"Appium","newCommandTimeout":"3600","platformVersion":"4.4.2","app":"/home/adminuser/Java_Projects/alice/target/test-classes/en-android.apk","platformName"]
2015-01-19 12:23:40:473 - info: [debug] executing cmd: /opt/android-sdk-linux/platform-tools/adb -s HT26HW300907 push "/tmp/com.test.app/strings.json" /data/local/tmp
2015-01-19 12:23:40:607 - info: [debug] Checking whether aapt is present
2015-01-19 12:23:40:614 - info: [debug] Using aapt from /opt/android-sdk-linux/build-tools/19.1.0/aapt
2015-01-19 12:23:40:614 - info: [debug] Retrieving process from manifest.
2015-01-19 12:23:40:615 - info: [debug] executing cmd: /opt/android-sdk-linux/build-tools/19.1.0/aapt dump xmltree /home/adminuser/Java_Projects/bob/target/test-classes/en-android.apk AndroidManifest.xml
2015-01-19 12:23:40:628 - info: [debug] Set app process to: com.test.app
2015-01-19 12:23:40:629 - info: [debug] Not uninstalling app since server not started with --full-reset
2015-01-19 12:23:40:629 - info: [debug] Checking app cert for /home/adminuser/Java_Projects/bob/target/test-classes/en-android.apk.
2015-01-19 12:23:40:631 - info: [debug] executing cmd: java -jar /home/adminuser/Java_Projects/Appium/appium/node_modules/appium-adb/jars/verify.jar /home/adminuser/Java_Projects/bob/target/test-classes/en-android.apk
2015-01-19 12:23:40:856 - info: [debug] App not signed with debug cert.
2015-01-19 12:23:40:856 - info: [debug] Resigning apk.
2015-01-19 12:23:40:856 - info: [debug] executing cmd: java -jar /home/adminuser/Java_Projects/Appium/appium/node_modules/appium-adb/jars/sign.jar /home/adminuser/Java_Projects/bob/target/test-classes/en-android.apk --override
2015-01-19 12:23:45:143 - info: [debug] Zip-aligning /home/adminuser/Java_Projects/bob/target/test-classes/en-android.apk
2015-01-19 12:23:45:143 - info: [debug] Checking whether zipalign is present
2015-01-19 12:23:45:144 - info: [debug] Using zipalign from /opt/android-sdk-linux/build-tools/19.1.0/zipalign
2015-01-19 12:23:45:144 - info: [debug] Zip-aligning apk.
2015-01-19 12:23:45:144 - info: [debug] executing cmd: /opt/android-sdk-linux/build-tools/19.1.0/zipalign -f 4 /home/adminuser/Java_Projects/bob/target/test-classes/en-android.apk /tmp/115019-31133-twyehf/appium.tmp
2015-01-19 12:23:45:232 - info: [debug] MD5 for app is 1ce2739ca6cfb2b711e52c7febd0b399
2015-01-19 12:23:45:232 - info: [debug] executing cmd: /opt/android-sdk-linux/platform-tools/adb -s HT26HW300907 shell "ls /data/local/tmp/1ce2739ca6cfb2b711e52c7febd0b399.apk"
2015-01-19 12:23:45:311 - info: [debug] Getting install status for com.test.app
2015-01-19 12:23:45:312 - info: [debug] Getting device API level
2015-01-19 12:23:45:313 - info: [debug] executing cmd: /opt/android-sdk-linux/platform-tools/adb -s HT26HW300907 shell "getprop ro.build.version.sdk"
2015-01-19 12:23:45:380 - info: [debug] Device is at API Level 17
2015-01-19 12:23:45:383 - info: [debug] executing cmd: /opt/android-sdk-linux/platform-tools/adb -s HT26HW300907 shell "pm list packages -3 com.test.app"
2015-01-19 12:23:46:582 - info: [debug] App is not installed
2015-01-19 12:23:46:585 - info: Installing App
2015-01-19 12:23:46:586 - info: [debug] executing cmd: /opt/android-sdk-linux/platform-tools/adb -s HT26HW300907 shell "mkdir -p /data/local/tmp/"
2015-01-19 12:23:46:649 - info: [debug] Removing any old apks
2015-01-19 12:23:46:653 - info: [debug] executing cmd: /opt/android-sdk-linux/platform-tools/adb -s HT26HW300907 shell "ls /data/local/tmp/*.apk"
2015-01-19 12:23:46:714 - info: [debug] Found an apk we want to keep at /data/local/tmp/1ce2739ca6cfb2b711e52c7febd0b399.apk
2015-01-19 12:23:46:718 - info: [debug] Couldn't find any apks to remove
2015-01-19 12:23:46:722 - info: [debug] Uninstalling com.test.app
2015-01-19 12:23:46:723 - info: [debug] executing cmd: /opt/android-sdk-linux/platform-tools/adb -s HT26HW300907 shell "am force-stop com.test.app"
2015-01-19 12:23:47:494 - info: [debug] executing cmd: /opt/android-sdk-linux/platform-tools/adb -s HT26HW300907 uninstall com.test.app
2015-01-19 12:23:48:310 - info: [debug] App was not uninstalled, maybe it wasn't on device?
2015-01-19 12:23:48:311 - info: [debug] executing cmd: /opt/android-sdk-linux/platform-tools/adb -s HT26HW300907 shell "pm install -r /data/local/tmp/1ce2739ca6cfb2b711e52c7febd0b399.apk"
2015-01-19 12:24:03:649 - info: [debug] Forwarding system:4001 to device:4724
2015-01-19 12:24:03:650 - info: [debug] executing cmd: /opt/android-sdk-linux/platform-tools/adb -s HT26HW300907 forward tcp:4001 tcp:4724
2015-01-19 12:24:03:662 - info: [debug] Pushing appium bootstrap to device...
2015-01-19 12:24:03:666 - info: [debug] executing cmd: /opt/android-sdk-linux/platform-tools/adb -s HT26HW300907 push "/home/adminuser/Java_Projects/Appium/appium/build/android_bootstrap/AppiumBootstrap.jar" /data/local/tmp/
2015-01-19 12:24:04:196 - info: [debug] Pushing settings apk to device...
2015-01-19 12:24:04:197 - info: [debug] executing cmd: /opt/android-sdk-linux/platform-tools/adb -s HT26HW300907 install "/home/adminuser/Java_Projects/Appium/appium/build/settings_apk/settings_apk-debug.apk"
2015-01-19 12:24:10:596 - info: [debug] Pushing unlock helper app to device...
2015-01-19 12:24:10:598 - info: [debug] executing cmd: /opt/android-sdk-linux/platform-tools/adb -s HT26HW300907 install "/home/adminuser/Java_Projects/Appium/appium/build/unlock_apk/unlock_apk-debug.apk"
2015-01-19 12:24:12:736 - info: Starting App
2015-01-19 12:24:12:737 - info: [debug] Attempting to kill all 'uiautomator' processes
2015-01-19 12:24:12:738 - info: [debug] Getting all processes with 'uiautomator'
2015-01-19 12:24:12:739 - info: [debug] executing cmd: /opt/android-sdk-linux/platform-tools/adb -s HT26HW300907 shell "ps 'uiautomator'"
2015-01-19 12:24:12:824 - info: [debug] No matching processes found
2015-01-19 12:24:12:825 - info: [debug] Running bootstrap
2015-01-19 12:24:12:825 - info: [debug] spawning: /opt/android-sdk-linux/platform-tools/adb -s HT26HW300907 shell uiautomator runtest AppiumBootstrap.jar -c io.appium.android.bootstrap.Bootstrap
2015-01-19 12:24:13:744 - info: [debug] [UIAUTOMATOR STDOUT] INSTRUMENTATION_STATUS: current=1
2015-01-19 12:24:13:745 - info: [debug] [UIAUTOMATOR STDOUT] INSTRUMENTATION_STATUS: id=UiAutomatorTestRunner
2015-01-19 12:24:13:746 - info: [debug] [UIAUTOMATOR STDOUT] INSTRUMENTATION_STATUS: class=io.appium.android.bootstrap.Bootstrap
2015-01-19 12:24:13:746 - info: [debug] [UIAUTOMATOR STDOUT] INSTRUMENTATION_STATUS: stream=
2015-01-19 12:24:13:747 - info: [debug] [UIAUTOMATOR STDOUT] io.appium.android.bootstrap.Bootstrap:
2015-01-19 12:24:13:747 - info: [debug] [UIAUTOMATOR STDOUT] INSTRUMENTATION_STATUS: numtests=1
2015-01-19 12:24:13:747 - info: [debug] [UIAUTOMATOR STDOUT] INSTRUMENTATION_STATUS: test=testRunServer
2015-01-19 12:24:13:748 - info: [debug] [UIAUTOMATOR STDOUT] INSTRUMENTATION_STATUS_CODE: 1
2015-01-19 12:24:13:768 - info: [debug] [BOOTSTRAP] [debug] Socket opened on port 4724
2015-01-19 12:24:13:770 - info: [debug] [BOOTSTRAP] [debug] Appium Socket Server Ready
2015-01-19 12:24:13:770 - info: [debug] [BOOTSTRAP] [debug] Loading json...
2015-01-19 12:24:13:771 - info: [debug] Waking up device if it's not alive
2015-01-19 12:24:13:771 - info: [debug] Pushing command to appium work queue: ["wake",{}]
2015-01-19 12:24:13:788 - info: [debug] [BOOTSTRAP] [debug] json loading complete.
2015-01-19 12:24:13:789 - info: [debug] [BOOTSTRAP] [debug] Registered crash watchers.
2015-01-19 12:24:13:789 - info: [debug] [BOOTSTRAP] [debug] Client connected
2015-01-19 12:24:13:790 - info: [debug] [BOOTSTRAP] [debug] Got data from client: {"cmd":"action","action":"wake","params":{}}
2015-01-19 12:24:13:790 - info: [debug] [BOOTSTRAP] [debug] Got command of type ACTION
2015-01-19 12:24:13:790 - info: [debug] [BOOTSTRAP] [debug] Got command action: wake
2015-01-19 12:24:13:791 - info: [debug] [BOOTSTRAP] [debug] Returning result: {"value":true,"status":0}
2015-01-19 12:24:13:793 - info: [debug] executing cmd: /opt/android-sdk-linux/platform-tools/adb -s HT26HW300907 shell "dumpsys window"
2015-01-19 12:24:13:956 - info: [debug] Screen already unlocked, continuing.
2015-01-19 12:24:13:958 - info: [debug] Pushing command to appium work queue: ["getDataDir",{}]
2015-01-19 12:24:13:962 - info: [debug] [BOOTSTRAP] [debug] Got data from client: {"cmd":"action","action":"getDataDir","params":{}}
2015-01-19 12:24:13:963 - info: [debug] [BOOTSTRAP] [debug] Got command of type ACTION
2015-01-19 12:24:13:963 - info: [debug] [BOOTSTRAP] [debug] Got command action: getDataDir
2015-01-19 12:24:13:964 - info: [debug] [BOOTSTRAP] [debug] Returning result: {"value":"\/data\/local\/tmp","status":0}
2015-01-19 12:24:13:964 - info: [debug] dataDir set to: /data/local/tmp
2015-01-19 12:24:13:965 - info: [debug] Pushing command to appium work queue: ["compressedLayoutHierarchy",{"compressLayout":false}]
2015-01-19 12:24:13:968 - info: [debug] [BOOTSTRAP] [debug] Got data from client: {"cmd":"action","action":"compressedLayoutHierarchy","params":{"compressLayout":false}}
2015-01-19 12:24:13:968 - info: [debug] [BOOTSTRAP] [debug] Got command of type ACTION
2015-01-19 12:24:13:968 - info: [debug] [BOOTSTRAP] [debug] Got command action: compressedLayoutHierarchy
2015-01-19 12:24:13:969 - info: [debug] [BOOTSTRAP] [debug] Returning result: {"value":false,"status":0}
2015-01-19 12:24:13:970 - info: [debug] Getting device API level
2015-01-19 12:24:13:971 - info: [debug] executing cmd: /opt/android-sdk-linux/platform-tools/adb -s HT26HW300907 shell "getprop ro.build.version.sdk"
2015-01-19 12:24:14:040 - info: [debug] Device is at API Level 17
2015-01-19 12:24:14:040 - info: [debug] executing cmd: /opt/android-sdk-linux/platform-tools/adb -s HT26HW300907 shell "am start -S -a android.intent.action.MAIN -c android.intent.category.LAUNCHER -f 0x10200000 -n com.test.app/.gui.SplashActivity"
2015-01-19 12:24:15:052 - info: [debug] Waiting for pkg "com.test.app" and activity ".gui.SplashActivity" to be focused
2015-01-19 12:24:15:054 - info: [debug] Getting focused package and activity
2015-01-19 12:24:15:055 - info: [debug] executing cmd: /opt/android-sdk-linux/platform-tools/adb -s HT26HW300907 shell "dumpsys window windows"
2015-01-19 12:24:15:286 - info: [debug] Device launched! Ready for commands
2015-01-19 12:24:15:287 - info: [debug] Setting command timeout to 3600 secs
2015-01-19 12:24:15:288 - info: [debug] Appium session started with sessionId 9aefa8ef-4064-4c5c-a5dc-ac857f165494
2015-01-19 12:24:15:297 - info: <-- POST /wd/hub/session 303 47323.592 ms - 9 
2015-01-19 12:24:15:335 - info: --> GET /wd/hub/session/9aefa8ef-4064-4c5c-a5dc-ac857f165494 {}
2015-01-19 12:24:15:337 - info: [debug] Responding to client with success: {"status":0,"value":{"platform":"LINUX","browserName":"","platformVersion":"4.4.2","webStorageEnabled":false,"takesScreenshot":true,"javascriptEnabled":true,"databaseEnabled":false,"networkConnectionEnabled":true,"locationContextEnabled":false,"warnings":{},"desired":{"automationName":"Appium","newCommandTimeout":"3600","platformVersion":"4.4.2","app":"/home/adminuser/Java_Projects/bob/target/test-classes/en-android.apk","platformName":"Android","deviceName":"Android","browserName":"","appActivity":".gui.SplashActivity","deviceReadyTimeout":"3600","appPackage":"com.test.app"},"automationName":"Appium","newCommandTimeout":"3600","app":"/home/adminuser/Java_Projects/bob/target/test-classes/en-android.apk","platformName":"Android","deviceName":"Android","appActivity":".gui.SplashActivity","deviceReadyTimeout":"3600","appPackage":"com.test.app"},"sessionId":"9aefa8ef-4064-4c5c-a5dc-ac857f165494"}
2015-01-19 12:24:15:339 - info: <-- GET /wd/hub/session/9aefa8ef-4064-4c5c-a5dc-ac857f165494 200 8.510 ms - 931 {"status":0,"value":{"platform":"LINUX","browserName":"","platformVersion":"4.4.2","webStorageEnabled":false,"takesScreenshot":true,"javascriptEnabled":true,"databaseEnabled":false,"networkConnectionEnabled":true,"locationContextEnabled":false,"warnings":{},"desired":{"automationName":"Appium","newCommandTimeout":"3600","platformVersion":"4.4.2","app":"/home/adminuser/Java_Projects/bob/target/test-classes/en-android.apk","platformName":"Android","deviceName":"Android","browserName":"","appActivity":".gui.SplashActivity","deviceReadyTimeout":"3600","appPackage":"com.test.app"},"automationName":"Appium","newCommandTimeout":"3600","app":"/home/adminuser/Java_Projects/bob/target/test-classes/en-android.apk","platformName":"Android","deviceName":"Android","appActivity":".gui.SplashActivity","deviceReadyTimeout":"3600","appPackage":"com.test.app"},"sessionId":"9aefa8ef-4064-4c5c-a5dc-ac857f165494"}
2015-01-19 12:24:41:672 - info: --> DELETE /wd/hub/session/9aefa8ef-4064-4c5c-a5dc-ac857f165494 {}
2015-01-19 12:24:41:673 - info: Shutting down appium session
2015-01-19 12:24:41:674 - info: [debug] Pressing the HOME button
2015-01-19 12:24:41:674 - info: [debug] executing cmd: /opt/android-sdk-linux/platform-tools/adb -s HT26HW300907 shell "input keyevent 3"
2015-01-19 12:24:42:616 - info: [debug] Stopping logcat capture
2015-01-19 12:24:42:627 - info: [debug] Logcat terminated with code null, signal SIGTERM
2015-01-19 12:24:42:633 - info: [debug] [BOOTSTRAP] [debug] Got data from client: {"cmd":"shutdown"}
2015-01-19 12:24:42:633 - info: [debug] [BOOTSTRAP] [debug] Got command of type SHUTDOWN
2015-01-19 12:24:42:633 - info: [debug] [BOOTSTRAP] [debug] Returning result: {"value":"OK, shutting down","status":0}
2015-01-19 12:24:42:633 - info: [debug] [BOOTSTRAP] [debug] Closed client connection
2015-01-19 12:24:42:634 - info: [debug] [UIAUTOMATOR STDOUT] INSTRUMENTATION_STATUS: current=1
2015-01-19 12:24:42:634 - info: [debug] [UIAUTOMATOR STDOUT] INSTRUMENTATION_STATUS: id=UiAutomatorTestRunner
2015-01-19 12:24:42:634 - info: [debug] [UIAUTOMATOR STDOUT] INSTRUMENTATION_STATUS: class=io.appium.android.bootstrap.Bootstrap
2015-01-19 12:24:42:634 - info: [debug] [UIAUTOMATOR STDOUT] INSTRUMENTATION_STATUS: stream=.
2015-01-19 12:24:42:634 - info: [debug] [UIAUTOMATOR STDOUT] INSTRUMENTATION_STATUS: numtests=1
2015-01-19 12:24:42:634 - info: [debug] [UIAUTOMATOR STDOUT] INSTRUMENTATION_STATUS: test=testRunServer
2015-01-19 12:24:42:634 - info: [debug] [UIAUTOMATOR STDOUT] INSTRUMENTATION_STATUS_CODE: 0
2015-01-19 12:24:42:635 - info: [debug] [UIAUTOMATOR STDOUT] INSTRUMENTATION_STATUS: stream=
2015-01-19 12:24:42:635 - info: [debug] [UIAUTOMATOR STDOUT] Test results for WatcherResultPrinter=.
2015-01-19 12:24:42:635 - info: [debug] [UIAUTOMATOR STDOUT] Time: 28.936
2015-01-19 12:24:42:635 - info: [debug] [UIAUTOMATOR STDOUT] OK (1 test)
2015-01-19 12:24:42:635 - info: [debug] [UIAUTOMATOR STDOUT] INSTRUMENTATION_STATUS_CODE: -1
2015-01-19 12:24:42:636 - info: [debug] Sent shutdown command, waiting for UiAutomator to stop...
2015-01-19 12:24:42:644 - info: [debug] UiAutomator shut down normally
2015-01-19 12:24:42:644 - info: [debug] Cleaning up android objects
2015-01-19 12:24:42:644 - info: [debug] Cleaning up appium session
2015-01-19 12:24:42:646 - info: [debug] Responding to client with success: {"status":0,"value":null,"sessionId":"9aefa8ef-4064-4c5c-a5dc-ac857f165494"}
2015-01-19 12:24:42:648 - info: <-- DELETE /wd/hub/session/9aefa8ef-4064-4c5c-a5dc-ac857f165494 200 976.930 ms - 76 {"status":0,"value":null,"sessionId":"9aefa8ef-4064-4c5c-a5dc-ac857f165494"}

bob_API19.log

2015-01-19 12:23:20:433 - info: Welcome to Appium v1.3.4 (REV 96dc94037c3f5747d173f6bc6e3abe249026090d)
2015-01-19 12:23:20:442 - info: Appium REST http interface listener started on 0.0.0.0:6002
2015-01-19 12:23:20:452 - info: [debug] Non-default server args: {"udid":"ZX1D62B2JM","port":6002,"bootstrapPort":4002,"sessionOverride":true,"log":"/home/adminuser/Java_Projects/PhantomAutomationMaster/bob.log","selendroidPort":9002}
2015-01-19 12:23:20:452 - info: Console LogLevel: debug
2015-01-19 12:23:20:452 - info: File LogLevel: debug
2015-01-19 12:24:15:402 - info: --> POST /wd/hub/session {"desiredCapabilities":{"automationName":"Appium","newCommandTimeout":"3600","platformVersion":"4.4.2","app":"/home/adminuser/Java_Projects/bob/target/test-classes/en-android.apk","platformName":"Android","deviceName":"Android","browserName":"","appActivity":".gui.SplashActivity","deviceReadyTimeout":"3600","appPackage":"com.test.app"}}
2015-01-19 12:24:15:404 - info: Client User-Agent string: Apache-HttpClient/4.4-beta1 (Java 1.5 minimum; Java/1.7.0_72)
2015-01-19 12:24:15:407 - info: [debug] Using local app from desired caps: /home/adminuser/Java_Projects/bob/target/test-classes/en-android.apk
2015-01-19 12:24:15:408 - info: [debug] Creating new appium session 0506b147-75c2-40a4-9983-7e99dda88035
2015-01-19 12:24:15:408 - info: Starting android appium
2015-01-19 12:24:15:410 - info: [debug] Getting Java version
2015-01-19 12:24:15:490 - info: Java version is: 1.7.0_72
2015-01-19 12:24:15:492 - info: [debug] Checking whether adb is present
2015-01-19 12:24:15:493 - info: [debug] Using adb from /opt/android-sdk-linux/platform-tools/adb
2015-01-19 12:24:15:494 - info: [debug] Using fast reset? true
2015-01-19 12:24:15:494 - info: [debug] Preparing device for session
2015-01-19 12:24:15:495 - info: [debug] Checking whether app is actually present
2015-01-19 12:24:15:496 - info: Retrieving device
2015-01-19 12:24:15:496 - info: [debug] Trying to find a connected android device
2015-01-19 12:24:15:498 - info: [debug] Getting connected devices...
2015-01-19 12:24:15:500 - info: [debug] executing cmd: /opt/android-sdk-linux/platform-tools/adb devices
2015-01-19 12:24:15:510 - info: [debug] 2 device(s) connected
2015-01-19 12:24:15:510 - info: Found device ZX1D62B2JM
2015-01-19 12:24:15:511 - info: [debug] Setting device id to ZX1D62B2JM
2015-01-19 12:24:15:513 - info: [debug] Waiting for device to be ready and to respond to shell commands (timeout = 3600)
2015-01-19 12:24:15:513 - info: [debug] executing cmd: /opt/android-sdk-linux/platform-tools/adb -s ZX1D62B2JM wait-for-device
2015-01-19 12:24:15:521 - info: [debug] executing cmd: /opt/android-sdk-linux/platform-tools/adb -s ZX1D62B2JM shell "echo 'ready'"
2015-01-19 12:24:15:580 - info: [debug] Starting logcat capture
2015-01-19 12:24:15:671 - info: [debug] Getting device API level
2015-01-19 12:24:15:673 - info: [debug] executing cmd: /opt/android-sdk-linux/platform-tools/adb -s ZX1D62B2JM shell "getprop ro.build.version.sdk"
2015-01-19 12:24:15:757 - info: [debug] Device is at API Level 19
2015-01-19 12:24:15:758 - info: Device API level is: 19
2015-01-19 12:24:15:759 - info: [debug] executing cmd: /opt/android-sdk-linux/platform-tools/adb -s ZX1D62B2JM shell "getprop persist.sys.language"
2015-01-19 12:24:15:824 - info: [debug] Current device persist.sys.language: en
2015-01-19 12:24:15:825 - info: [debug] java -jar "/home/adminuser/Java_Projects/Appium/appium/node_modules/appium-adb/jars/appium_apk_tools.jar" "stringsFromApk" "/home/adminuser/Java_Projects/bob/target/test-classes/en-android.apk" "/tmp/com.test.app" en
2015-01-19 12:24:16:299 - info: [debug] No strings.xml for language 'en', getting default strings.xml
2015-01-19 12:24:16:300 - info: [debug] java -jar "/home/adminuser/Java_Projects/Appium/appium/node_modules/appium-adb/jars/appium_apk_tools.jar" "stringsFromApk" "/home/adminuser/Java_Projects/bob/target/test-classes/en-android.apk" "/tmp/com.test.app"
2015-01-19 12:24:16:718 - info: [debug] executing cmd: /opt/android-sdk-linux/platform-tools/adb -s ZX1D62B2JM push "/tmp/com.test.app/strings.json" /data/local/tmp
2015-01-19 12:24:16:857 - info: [debug] Checking whether aapt is present
2015-01-19 12:24:16:859 - info: [debug] Using aapt from /opt/android-sdk-linux/build-tools/19.1.0/aapt
2015-01-19 12:24:16:859 - info: [debug] Retrieving process from manifest.
2015-01-19 12:24:16:860 - info: [debug] executing cmd: /opt/android-sdk-linux/build-tools/19.1.0/aapt dump xmltree /home/adminuser/Java_Projects/bob/target/test-classes/en-android.apk AndroidManifest.xml
2015-01-19 12:24:16:875 - info: [debug] Set app process to: com.test.app
2015-01-19 12:24:16:876 - info: [debug] Not uninstalling app since server not started with --full-reset
2015-01-19 12:24:16:878 - info: [debug] Checking app cert for /home/adminuser/Java_Projects/bob/target/test-classes/en-android.apk.
2015-01-19 12:24:16:879 - info: [debug] executing cmd: java -jar /home/adminuser/Java_Projects/Appium/appium/node_modules/appium-adb/jars/verify.jar /home/adminuser/Java_Projects/bob/target/test-classes/en-android.apk
2015-01-19 12:24:17:693 - info: [debug] App already signed.
2015-01-19 12:24:17:695 - info: [debug] Zip-aligning /home/adminuser/Java_Projects/bob/target/test-classes/en-android.apk
2015-01-19 12:24:17:696 - info: [debug] Checking whether zipalign is present
2015-01-19 12:24:17:697 - info: [debug] Using zipalign from /opt/android-sdk-linux/build-tools/19.1.0/zipalign
2015-01-19 12:24:17:698 - info: [debug] Zip-aligning apk.
2015-01-19 12:24:17:698 - info: [debug] executing cmd: /opt/android-sdk-linux/build-tools/19.1.0/zipalign -f 4 /home/adminuser/Java_Projects/bob/target/test-classes/en-android.apk /tmp/115019-31181-a38aqo/appium.tmp
2015-01-19 12:24:17:796 - info: [debug] MD5 for app is 1ce2739ca6cfb2b711e52c7febd0b399
2015-01-19 12:24:17:796 - info: [debug] executing cmd: /opt/android-sdk-linux/platform-tools/adb -s ZX1D62B2JM shell "ls /data/local/tmp/1ce2739ca6cfb2b711e52c7febd0b399.apk"
2015-01-19 12:24:17:854 - info: [debug] Getting install status for com.test.app
2015-01-19 12:24:17:855 - info: [debug] Getting device API level
2015-01-19 12:24:17:856 - info: [debug] executing cmd: /opt/android-sdk-linux/platform-tools/adb -s ZX1D62B2JM shell "getprop ro.build.version.sdk"
2015-01-19 12:24:17:921 - info: [debug] Device is at API Level 19
2015-01-19 12:24:17:923 - info: [debug] executing cmd: /opt/android-sdk-linux/platform-tools/adb -s ZX1D62B2JM shell "pm list packages -3 com.test.app"
2015-01-19 12:24:18:775 - info: [debug] App is not installed
2015-01-19 12:24:18:776 - info: Installing App
2015-01-19 12:24:18:777 - info: [debug] executing cmd: /opt/android-sdk-linux/platform-tools/adb -s ZX1D62B2JM shell "mkdir -p /data/local/tmp/"
2015-01-19 12:24:18:840 - info: [debug] Removing any old apks
2015-01-19 12:24:18:844 - info: [debug] executing cmd: /opt/android-sdk-linux/platform-tools/adb -s ZX1D62B2JM shell "ls /data/local/tmp/*.apk"
2015-01-19 12:24:18:910 - info: [debug] Found an apk we want to keep at /data/local/tmp/1ce2739ca6cfb2b711e52c7febd0b399.apk
2015-01-19 12:24:18:913 - info: [debug] Couldn't find any apks to remove
2015-01-19 12:24:18:921 - info: [debug] Uninstalling com.test.app
2015-01-19 12:24:18:922 - info: [debug] executing cmd: /opt/android-sdk-linux/platform-tools/adb -s ZX1D62B2JM shell "am force-stop com.test.app"
2015-01-19 12:24:19:776 - info: [debug] executing cmd: /opt/android-sdk-linux/platform-tools/adb -s ZX1D62B2JM uninstall com.test.app
2015-01-19 12:24:20:710 - info: [debug] App was not uninstalled, maybe it wasn't on device?
2015-01-19 12:24:20:711 - info: [debug] executing cmd: /opt/android-sdk-linux/platform-tools/adb -s ZX1D62B2JM shell "pm install -r /data/local/tmp/1ce2739ca6cfb2b711e52c7febd0b399.apk"
2015-01-19 12:24:32:937 - info: [debug] Forwarding system:4002 to device:4724
2015-01-19 12:24:32:938 - info: [debug] executing cmd: /opt/android-sdk-linux/platform-tools/adb -s ZX1D62B2JM forward tcp:4002 tcp:4724
2015-01-19 12:24:32:952 - info: [debug] Pushing appium bootstrap to device...
2015-01-19 12:24:32:953 - info: [debug] executing cmd: /opt/android-sdk-linux/platform-tools/adb -s ZX1D62B2JM push "/home/adminuser/Java_Projects/Appium/appium/build/android_bootstrap/AppiumBootstrap.jar" /data/local/tmp/
2015-01-19 12:24:33:049 - info: [debug] Pushing settings apk to device...
2015-01-19 12:24:33:051 - info: [debug] executing cmd: /opt/android-sdk-linux/platform-tools/adb -s ZX1D62B2JM install "/home/adminuser/Java_Projects/Appium/appium/build/settings_apk/settings_apk-debug.apk"
2015-01-19 12:24:35:728 - info: [debug] Pushing unlock helper app to device...
2015-01-19 12:24:35:729 - info: [debug] executing cmd: /opt/android-sdk-linux/platform-tools/adb -s ZX1D62B2JM install "/home/adminuser/Java_Projects/Appium/appium/build/unlock_apk/unlock_apk-debug.apk"
2015-01-19 12:24:38:745 - info: Starting App
2015-01-19 12:24:38:746 - info: [debug] Attempting to kill all 'uiautomator' processes
2015-01-19 12:24:38:747 - info: [debug] Getting all processes with 'uiautomator'
2015-01-19 12:24:38:748 - info: [debug] executing cmd: /opt/android-sdk-linux/platform-tools/adb -s ZX1D62B2JM shell "ps 'uiautomator'"
2015-01-19 12:24:38:851 - info: [debug] No matching processes found
2015-01-19 12:24:38:852 - info: [debug] Running bootstrap
2015-01-19 12:24:38:852 - info: [debug] spawning: /opt/android-sdk-linux/platform-tools/adb -s ZX1D62B2JM shell uiautomator runtest AppiumBootstrap.jar -c io.appium.android.bootstrap.Bootstrap
2015-01-19 12:24:39:583 - info: [debug] [UIAUTOMATOR STDOUT] INSTRUMENTATION_STATUS: numtests=1
2015-01-19 12:24:39:584 - info: [debug] [UIAUTOMATOR STDOUT] INSTRUMENTATION_STATUS: stream=
2015-01-19 12:24:39:585 - info: [debug] [UIAUTOMATOR STDOUT] io.appium.android.bootstrap.Bootstrap:
2015-01-19 12:24:39:585 - info: [debug] [UIAUTOMATOR STDOUT] INSTRUMENTATION_STATUS: id=UiAutomatorTestRunner
2015-01-19 12:24:39:585 - info: [debug] [UIAUTOMATOR STDOUT] INSTRUMENTATION_STATUS: test=testRunServer
2015-01-19 12:24:39:585 - info: [debug] [UIAUTOMATOR STDOUT] INSTRUMENTATION_STATUS: class=io.appium.android.bootstrap.Bootstrap
2015-01-19 12:24:39:585 - info: [debug] [UIAUTOMATOR STDOUT] INSTRUMENTATION_STATUS: current=1
2015-01-19 12:24:39:585 - info: [debug] [UIAUTOMATOR STDOUT] INSTRUMENTATION_STATUS_CODE: 1
2015-01-19 12:24:39:606 - info: [debug] [BOOTSTRAP] [debug] Socket opened on port 4724
2015-01-19 12:24:39:610 - info: [debug] [BOOTSTRAP] [debug] Appium Socket Server Ready
2015-01-19 12:24:39:610 - info: [debug] [BOOTSTRAP] [debug] Loading json...
2015-01-19 12:24:39:611 - info: [debug] Waking up device if it's not alive
2015-01-19 12:24:39:611 - info: [debug] Pushing command to appium work queue: ["wake",{}]
2015-01-19 12:24:39:624 - info: [debug] [BOOTSTRAP] [debug] json loading complete.
2015-01-19 12:24:39:625 - info: [debug] [BOOTSTRAP] [debug] Registered crash watchers.
2015-01-19 12:24:39:625 - info: [debug] [BOOTSTRAP] [debug] Client connected
2015-01-19 12:24:39:626 - info: [debug] [BOOTSTRAP] [debug] Got data from client: {"cmd":"action","action":"wake","params":{}}
2015-01-19 12:24:39:626 - info: [debug] [BOOTSTRAP] [debug] Got command of type ACTION
2015-01-19 12:24:39:626 - info: [debug] [BOOTSTRAP] [debug] Got command action: wake
2015-01-19 12:24:40:140 - info: [debug] [BOOTSTRAP] [debug] Returning result: {"value":true,"status":0}
2015-01-19 12:24:40:143 - info: [debug] executing cmd: /opt/android-sdk-linux/platform-tools/adb -s ZX1D62B2JM shell "dumpsys window"
2015-01-19 12:24:40:231 - info: [debug] Screen already unlocked, continuing.
2015-01-19 12:24:40:232 - info: [debug] Pushing command to appium work queue: ["getDataDir",{}]
2015-01-19 12:24:40:238 - info: [debug] [BOOTSTRAP] [debug] Got data from client: {"cmd":"action","action":"getDataDir","params":{}}
2015-01-19 12:24:40:240 - info: [debug] [BOOTSTRAP] [debug] Got command of type ACTION
2015-01-19 12:24:40:240 - info: [debug] [BOOTSTRAP] [debug] Got command action: getDataDir
2015-01-19 12:24:40:240 - info: [debug] [BOOTSTRAP] [debug] Returning result: {"value":"\/data\/local\/tmp","status":0}
2015-01-19 12:24:40:241 - info: [debug] dataDir set to: /data/local/tmp
2015-01-19 12:24:40:241 - info: [debug] Pushing command to appium work queue: ["compressedLayoutHierarchy",{"compressLayout":false}]
2015-01-19 12:24:40:245 - info: [debug] [BOOTSTRAP] [debug] Got data from client: {"cmd":"action","action":"compressedLayoutHierarchy","params":{"compressLayout":false}}
2015-01-19 12:24:40:245 - info: [debug] [BOOTSTRAP] [debug] Got command of type ACTION
2015-01-19 12:24:40:246 - info: [debug] [BOOTSTRAP] [debug] Got command action: compressedLayoutHierarchy
2015-01-19 12:24:40:247 - info: [debug] [BOOTSTRAP] [debug] Returning result: {"value":false,"status":0}
2015-01-19 12:24:40:252 - info: [debug] Getting device API level
2015-01-19 12:24:40:253 - info: [debug] executing cmd: /opt/android-sdk-linux/platform-tools/adb -s ZX1D62B2JM shell "getprop ro.build.version.sdk"
2015-01-19 12:24:40:314 - info: [debug] Device is at API Level 19
2015-01-19 12:24:40:317 - info: [debug] executing cmd: /opt/android-sdk-linux/platform-tools/adb -s ZX1D62B2JM shell "am start -S -a android.intent.action.MAIN -c android.intent.category.LAUNCHER -f 0x10200000 -n com.test.app/.gui.SplashActivity"
2015-01-19 12:24:41:503 - info: [debug] Waiting for pkg "com.test.app" and activity ".gui.SplashActivity" to be focused
2015-01-19 12:24:41:505 - info: [debug] Getting focused package and activity
2015-01-19 12:24:41:506 - info: [debug] executing cmd: /opt/android-sdk-linux/platform-tools/adb -s ZX1D62B2JM shell "dumpsys window windows"
2015-01-19 12:24:41:608 - info: [debug] Device launched! Ready for commands
2015-01-19 12:24:41:608 - info: [debug] Setting command timeout to 3600 secs
2015-01-19 12:24:41:611 - info: [debug] Appium session started with sessionId 0506b147-75c2-40a4-9983-7e99dda88035
2015-01-19 12:24:41:616 - info: <-- POST /wd/hub/session 303 26213.631 ms - 9 
2015-01-19 12:24:41:620 - info: --> GET /wd/hub/session/0506b147-75c2-40a4-9983-7e99dda88035 {}
2015-01-19 12:24:41:622 - info: [debug] Responding to client with success: {"status":0,"value":{"platform":"LINUX","browserName":"","platformVersion":"4.4.2","webStorageEnabled":false,"takesScreenshot":true,"javascriptEnabled":true,"databaseEnabled":false,"networkConnectionEnabled":true,"locationContextEnabled":false,"warnings":{},"desired":{"automationName":"Appium","newCommandTimeout":"3600","platformVersion":"4.4.2","app":"/home/adminuser/Java_Projects/bob/target/test-classes/en-android.apk","platformName":"Android","deviceName":"Android","browserName":"","appActivity":".gui.SplashActivity","deviceReadyTimeout":"3600","appPackage":"com.test.app"},"automationName":"Appium","newCommandTimeout":"3600","app":"/home/adminuser/Java_Projects/bob/target/test-classes/en-android.apk","platformName":"Android","deviceName":"Android","appActivity":".gui.SplashActivity","deviceReadyTimeout":"3600","appPackage":"com.test.app"},"sessionId":"0506b147-75c2-40a4-9983-7e99dda88035"}
2015-01-19 12:24:41:625 - info: <-- GET /wd/hub/session/0506b147-75c2-40a4-9983-7e99dda88035 200 4.975 ms - 931 {"status":0,"value":{"platform":"LINUX","browserName":"","platformVersion":"4.4.2","webStorageEnabled":false,"takesScreenshot":true,"javascriptEnabled":true,"databaseEnabled":false,"networkConnectionEnabled":true,"locationContextEnabled":false,"warnings":{},"desired":{"automationName":"Appium","newCommandTimeout":"3600","platformVersion":"4.4.2","app":"/home/adminuser/Java_Projects/bob/target/test-classes/en-android.apk","platformName":"Android","deviceName":"Android","browserName":"","appActivity":".gui.SplashActivity","deviceReadyTimeout":"3600","appPackage":"com.test.app"},"automationName":"Appium","newCommandTimeout":"3600","app":"/home/adminuser/Java_Projects/bob/target/test-classes/en-android.apk","platformName":"Android","deviceName":"Android","appActivity":".gui.SplashActivity","deviceReadyTimeout":"3600","appPackage":"com.test.app"},"sessionId":"0506b147-75c2-40a4-9983-7e99dda88035"}
2015-01-19 12:24:42:660 - info: --> DELETE /wd/hub/session/0506b147-75c2-40a4-9983-7e99dda88035 {}
2015-01-19 12:24:42:662 - info: Shutting down appium session
2015-01-19 12:24:42:663 - info: [debug] Pressing the HOME button
2015-01-19 12:24:42:664 - info: [debug] executing cmd: /opt/android-sdk-linux/platform-tools/adb -s ZX1D62B2JM shell "input keyevent 3"
2015-01-19 12:24:43:683 - info: [debug] Stopping logcat capture
2015-01-19 12:24:43:685 - info: [debug] Logcat terminated with code null, signal SIGTERM
2015-01-19 12:24:43:691 - info: [debug] [BOOTSTRAP] [debug] Got data from client: {"cmd":"shutdown"}
2015-01-19 12:24:43:691 - info: [debug] [BOOTSTRAP] [debug] Got command of type SHUTDOWN
2015-01-19 12:24:43:692 - info: [debug] [BOOTSTRAP] [debug] Returning result: {"value":"OK, shutting down","status":0}
2015-01-19 12:24:43:693 - info: [debug] Sent shutdown command, waiting for UiAutomator to stop...
2015-01-19 12:24:43:695 - info: [debug] [BOOTSTRAP] [debug] Closed client connection
2015-01-19 12:24:43:695 - info: [debug] [UIAUTOMATOR STDOUT] INSTRUMENTATION_STATUS: numtests=1
2015-01-19 12:24:43:696 - info: [debug] [UIAUTOMATOR STDOUT] INSTRUMENTATION_STATUS: stream=.
2015-01-19 12:24:43:696 - info: [debug] [UIAUTOMATOR STDOUT] INSTRUMENTATION_STATUS: id=UiAutomatorTestRunner
2015-01-19 12:24:43:697 - info: [debug] [UIAUTOMATOR STDOUT] INSTRUMENTATION_STATUS: test=testRunServer
2015-01-19 12:24:43:697 - info: [debug] [UIAUTOMATOR STDOUT] INSTRUMENTATION_STATUS: class=io.appium.android.bootstrap.Bootstrap
2015-01-19 12:24:43:698 - info: [debug] [UIAUTOMATOR STDOUT] INSTRUMENTATION_STATUS: current=1
2015-01-19 12:24:43:698 - info: [debug] [UIAUTOMATOR STDOUT] INSTRUMENTATION_STATUS_CODE: 0
2015-01-19 12:24:43:698 - info: [debug] [UIAUTOMATOR STDOUT] INSTRUMENTATION_STATUS: stream=
2015-01-19 12:24:43:698 - info: [debug] [UIAUTOMATOR STDOUT] Test results for WatcherResultPrinter=.
2015-01-19 12:24:43:699 - info: [debug] [UIAUTOMATOR STDOUT] Time: 4.202
2015-01-19 12:24:43:699 - info: [debug] [UIAUTOMATOR STDOUT] OK (1 test)
2015-01-19 12:24:43:699 - info: [debug] [UIAUTOMATOR STDOUT] INSTRUMENTATION_STATUS_CODE: -1
2015-01-19 12:24:43:700 - info: [debug] UiAutomator shut down normally
2015-01-19 12:24:43:701 - info: [debug] Cleaning up android objects
2015-01-19 12:24:43:701 - info: [debug] Cleaning up appium session
2015-01-19 12:24:43:701 - info: [debug] Responding to client with success: {"status":0,"value":null,"sessionId":"0506b147-75c2-40a4-9983-7e99dda88035"}
2015-01-19 12:24:43:704 - info: <-- DELETE /wd/hub/session/0506b147-75c2-40a4-9983-7e99dda88035 200 1044.404 ms - 76 {"status":0,"value":null,"sessionId":"0506b147-75c2-40a4-9983-7e99dda88035"}