2017-02-13 21:26:28:798 - info: [debug] Starting Appium in pre-launch mode
2017-02-13 21:26:28:813 - info: Pre-launching app
2017-02-13 21:26:28:818 - info: [debug] Using local app from command line: /tmp/scratchepQu_2.scratch/share-pfEtlT.scratch/app-DUjQos.apk
2017-02-13 21:26:28:819 - info: [debug] Creating new appium session 75bf90f3-09b4-4be2-bb2c-9a9a5c89b514
2017-02-13 21:26:28:820 - info: Starting android appium
2017-02-13 21:26:28:838 - info: [debug] Getting Java version
2017-02-13 21:26:28:997 - info: Java version is: 1.8.0_65
2017-02-13 21:26:28:999 - info: [debug] Checking whether adb is present
2017-02-13 21:26:29:000 - info: [debug] Using adb from /opt/dev/android-sdk-linux/platform-tools/adb
2017-02-13 21:26:29:002 - info: [debug] Using fast reset? true
2017-02-13 21:26:29:002 - info: [debug] Preparing device for session
2017-02-13 21:26:29:003 - info: [debug] Checking whether app is actually present
2017-02-13 21:26:29:012 - info: Retrieving device
2017-02-13 21:26:29:012 - info: [debug] Trying to find a connected android device
2017-02-13 21:26:29:013 - info: [debug] Getting connected devices...
2017-02-13 21:26:29:015 - info: [debug] executing cmd: /opt/dev/android-sdk-linux/platform-tools/adb devices
2017-02-13 21:26:29:500 - info: [debug] 1 device(s) connected
2017-02-13 21:26:29:500 - info: Found device ad071603090702e246
2017-02-13 21:26:29:500 - info: [debug] Setting device id to ad071603090702e246
2017-02-13 21:26:29:501 - info: [debug] Waiting for device to be ready and to respond to shell commands (timeout = 5)
2017-02-13 21:26:29:503 - info: [debug] executing cmd: /opt/dev/android-sdk-linux/platform-tools/adb -s ad071603090702e246 wait-for-device
2017-02-13 21:26:29:925 - info: [debug] executing cmd: /opt/dev/android-sdk-linux/platform-tools/adb -s ad071603090702e246 shell "echo 'ready'"
2017-02-13 21:26:30:511 - info: [debug] Starting logcat capture
2017-02-13 21:26:31:187 - info: [debug] Getting device API level
2017-02-13 21:26:31:189 - info: [debug] executing cmd: /opt/dev/android-sdk-linux/platform-tools/adb -s ad071603090702e246 shell "getprop ro.build.version.sdk"
2017-02-13 21:26:32:129 - info: [debug] Device is at API Level 23
2017-02-13 21:26:32:130 - info: Device API level is: 23
2017-02-13 21:26:32:130 - info: [debug] Extracting strings for language: default
2017-02-13 21:26:32:156 - info: [debug] executing cmd: /opt/dev/android-sdk-linux/platform-tools/adb -s ad071603090702e246 shell "getprop persist.sys.language"
2017-02-13 21:26:32:667 - info: [debug] Current device persist.sys.language: 
2017-02-13 21:26:32:668 - info: [debug] java -jar "/usr/local/avm/versions/1.4.16/node_modules/appium/node_modules/appium-adb/jars/appium_apk_tools.jar" "stringsFromApk" "/tmp/scratchepQu_2.scratch/share-pfEtlT.scratch/app-DUjQos.apk" "/tmp/scratchepQu_2.scratch/appium-Zz8fWR/com.youchat" 
2017-02-13 21:26:36:274 - info: [debug] Reading strings from converted strings.json
2017-02-13 21:26:36:276 - info: [debug] Setting language to default
2017-02-13 21:26:36:277 - info: [debug] executing cmd: /opt/dev/android-sdk-linux/platform-tools/adb -s ad071603090702e246 push "/tmp/scratchepQu_2.scratch/appium-Zz8fWR/com.youchat/strings.json" /data/local/tmp
2017-02-13 21:26:37:111 - info: [debug] Checking whether aapt is present
2017-02-13 21:26:37:113 - info: [debug] Using aapt from /opt/dev/android-sdk-linux/build-tools/21.1.1/aapt
2017-02-13 21:26:37:113 - info: [debug] Retrieving process from manifest.
2017-02-13 21:26:37:114 - info: [debug] executing cmd: /opt/dev/android-sdk-linux/build-tools/21.1.1/aapt dump xmltree /tmp/scratchepQu_2.scratch/share-pfEtlT.scratch/app-DUjQos.apk AndroidManifest.xml
2017-02-13 21:26:37:136 - info: [debug] Set app process to: com.youchat
2017-02-13 21:26:37:136 - info: [debug] Not uninstalling app since server not started with --full-reset
2017-02-13 21:26:37:153 - info: [debug] Printing keystore md5.
2017-02-13 21:26:37:154 - info: [debug] executing cmd: "/usr/lib/jvm/jdk1.8.0_65/bin/keytool" -v -list -alias androiddebugkey -keystore /home/rwx/.android/debug.keystore -storepass android
2017-02-13 21:26:38:212 - info: [debug] Keystore MD5: ED:3D:99:9A:1D:27:5B:88:48:AB:C1:8D:12:3C:CC:3B
2017-02-13 21:26:39:160 - info: [debug] Entry: META-INF/ANDROIDD.RSA
2017-02-13 21:26:39:160 - info: [debug] entryPath: /tmp/scratchepQu_2.scratch/appium-Zz8fWR/com.youchat/cert
2017-02-13 21:26:39:161 - info: [debug] entryFile: /tmp/scratchepQu_2.scratch/appium-Zz8fWR/com.youchat/cert/META-INF/ANDROIDD.RSA
2017-02-13 21:26:39:210 - info: [debug] extracted!
2017-02-13 21:26:39:210 - info: [debug] Printing apk md5.
2017-02-13 21:26:39:210 - info: [debug] executing cmd: "/usr/lib/jvm/jdk1.8.0_65/bin/keytool" -v -printcert -file /tmp/scratchepQu_2.scratch/appium-Zz8fWR/com.youchat/cert/META-INF/ANDROIDD.RSA
2017-02-13 21:26:40:220 - info: [debug] entryHash MD5: ED:3D:99:9A:1D:27:5B:88:48:AB:C1:8D:12:3C:CC:3B
2017-02-13 21:26:40:222 - info: [debug]  keystore MD5: ED:3D:99:9A:1D:27:5B:88:48:AB:C1:8D:12:3C:CC:3B
2017-02-13 21:26:40:223 - info: [debug] Matches keystore? true
2017-02-13 21:26:40:533 - info: [debug] MD5 for app is 12b904daf612f172b703b34497369931
2017-02-13 21:26:40:534 - info: [debug] executing cmd: /opt/dev/android-sdk-linux/platform-tools/adb -s ad071603090702e246 shell "ls /data/local/tmp/12b904daf612f172b703b34497369931.apk"
2017-02-13 21:26:41:211 - info: [debug] Getting install status for com.youchat
2017-02-13 21:26:41:212 - info: [debug] Getting device API level
2017-02-13 21:26:41:214 - info: [debug] executing cmd: /opt/dev/android-sdk-linux/platform-tools/adb -s ad071603090702e246 shell "getprop ro.build.version.sdk"
2017-02-13 21:26:41:717 - info: [debug] Device is at API Level 23
2017-02-13 21:26:41:719 - info: [debug] executing cmd: /opt/dev/android-sdk-linux/platform-tools/adb -s ad071603090702e246 shell "pm list packages -3 com.youchat"
2017-02-13 21:26:42:806 - info: [debug] App is installed
2017-02-13 21:26:42:806 - info: App is already installed, resetting app
2017-02-13 21:26:42:807 - info: [debug] Running fast reset (stop and clear)
2017-02-13 21:26:42:809 - info: [debug] executing cmd: /opt/dev/android-sdk-linux/platform-tools/adb -s ad071603090702e246 shell "am force-stop com.youchat"
2017-02-13 21:26:44:013 - info: [debug] executing cmd: /opt/dev/android-sdk-linux/platform-tools/adb -s ad071603090702e246 shell "pm clear com.youchat"
2017-02-13 21:26:45:202 - info: [debug] Forwarding system:4724 to device:4724
2017-02-13 21:26:45:240 - info: [debug] executing cmd: /opt/dev/android-sdk-linux/platform-tools/adb -s ad071603090702e246 forward tcp:4724 tcp:4724
2017-02-13 21:26:45:901 - info: [debug] Pushing appium bootstrap to device...
2017-02-13 21:26:45:903 - info: [debug] executing cmd: /opt/dev/android-sdk-linux/platform-tools/adb -s ad071603090702e246 push "/usr/local/avm/versions/1.4.16/node_modules/appium/build/android_bootstrap/AppiumBootstrap.jar" /data/local/tmp/
2017-02-13 21:26:47:004 - info: [debug] Pushing settings apk to device...
2017-02-13 21:26:47:007 - info: [debug] executing cmd: /opt/dev/android-sdk-linux/platform-tools/adb -s ad071603090702e246 install "/usr/local/avm/versions/1.4.16/node_modules/appium/build/settings_apk/settings_apk-debug.apk"
2017-02-13 21:26:50:148 - info: [debug] Pushing unlock helper app to device...
2017-02-13 21:26:50:151 - info: [debug] executing cmd: /opt/dev/android-sdk-linux/platform-tools/adb -s ad071603090702e246 install "/usr/local/avm/versions/1.4.16/node_modules/appium/build/unlock_apk/unlock_apk-debug.apk"
2017-02-13 21:26:52:996 - info: Starting App
2017-02-13 21:26:52:998 - info: [debug] Attempting to kill all 'uiautomator' processes
2017-02-13 21:26:52:999 - info: [debug] Getting all processes with 'uiautomator'
2017-02-13 21:26:53:000 - info: [debug] executing cmd: /opt/dev/android-sdk-linux/platform-tools/adb -s ad071603090702e246 shell "ps 'uiautomator'"
2017-02-13 21:26:53:535 - info: [debug] No matching processes found
2017-02-13 21:26:53:537 - info: [debug] Running bootstrap
2017-02-13 21:26:53:537 - info: [debug] spawning: /opt/dev/android-sdk-linux/platform-tools/adb -s ad071603090702e246 shell uiautomator runtest AppiumBootstrap.jar -c io.appium.android.bootstrap.Bootstrap -e pkg com.youchat -e disableAndroidWatchers false
2017-02-13 21:26:54:622 - info: [debug] [UIAUTOMATOR STDOUT] INSTRUMENTATION_STATUS: numtests=1
2017-02-13 21:26:54:622 - info: [debug] [UIAUTOMATOR STDOUT] INSTRUMENTATION_STATUS: stream=
2017-02-13 21:26:54:622 - info: [debug] [UIAUTOMATOR STDOUT] io.appium.android.bootstrap.Bootstrap:
2017-02-13 21:26:54:622 - info: [debug] [UIAUTOMATOR STDOUT] INSTRUMENTATION_STATUS: id=UiAutomatorTestRunner
2017-02-13 21:26:54:708 - info: [debug] [UIAUTOMATOR STDOUT] INSTRUMENTATION_STATUS: test=testRunServer
2017-02-13 21:26:54:708 - info: [debug] [UIAUTOMATOR STDOUT] INSTRUMENTATION_STATUS: class=io.appium.android.bootstrap.Bootstrap
2017-02-13 21:26:54:721 - info: [debug] [UIAUTOMATOR STDOUT] INSTRUMENTATION_STATUS: current=1
2017-02-13 21:26:54:722 - info: [debug] [UIAUTOMATOR STDOUT] INSTRUMENTATION_STATUS_CODE: 1
2017-02-13 21:26:54:749 - info: [debug] [BOOTSTRAP] [debug] Socket opened on port 4724
2017-02-13 21:26:54:749 - info: [debug] [BOOTSTRAP] [debug] Appium Socket Server Ready
2017-02-13 21:26:54:749 - info: [debug] [BOOTSTRAP] [debug] Loading json...
2017-02-13 21:26:54:749 - info: [debug] [BOOTSTRAP] [debug] json loading complete.
2017-02-13 21:26:54:750 - info: [debug] [BOOTSTRAP] [debug] Registered crash watchers.
2017-02-13 21:26:54:751 - info: [debug] Waking up device if it's not alive
2017-02-13 21:26:54:751 - info: [debug] Pushing command to appium work queue: ["wake",{}]
2017-02-13 21:26:54:770 - info: [debug] [BOOTSTRAP] [debug] Client connected
2017-02-13 21:26:54:781 - info: [debug] [BOOTSTRAP] [debug] Got data from client: {"cmd":"action","action":"wake","params":{}}
2017-02-13 21:26:54:787 - info: [debug] executing cmd: /opt/dev/android-sdk-linux/platform-tools/adb -s ad071603090702e246 shell "dumpsys window"
2017-02-13 21:26:54:816 - info: [debug] [BOOTSTRAP] [debug] Got command of type ACTION
2017-02-13 21:26:54:816 - info: [debug] [BOOTSTRAP] [debug] Got command action: wake
2017-02-13 21:26:54:816 - info: [debug] [BOOTSTRAP] [debug] Returning result: {"status":0,"value":true}
2017-02-13 21:26:55:423 - info: [debug] Screen already unlocked, continuing.
2017-02-13 21:26:55:424 - info: [debug] Pushing command to appium work queue: ["getDataDir",{}]
2017-02-13 21:26:55:438 - info: [debug] dataDir set to: /data/local/tmp
2017-02-13 21:26:55:438 - info: [debug] Pushing command to appium work queue: ["compressedLayoutHierarchy",{"compressLayout":false}]
2017-02-13 21:26:55:442 - info: [debug] [BOOTSTRAP] [debug] Got data from client: {"cmd":"action","action":"getDataDir","params":{}}
2017-02-13 21:26:55:442 - info: [debug] [BOOTSTRAP] [debug] Got command of type ACTION
2017-02-13 21:26:55:442 - info: [debug] [BOOTSTRAP] [debug] Got command action: getDataDir
2017-02-13 21:26:55:442 - info: [debug] [BOOTSTRAP] [debug] Returning result: {"status":0,"value":"\/data\/local\/tmp"}
2017-02-13 21:26:55:455 - info: [debug] [BOOTSTRAP] [debug] Got data from client: {"cmd":"action","action":"compressedLayoutHierarchy","params":{"compressLayout":false}}
2017-02-13 21:26:55:455 - info: [debug] [BOOTSTRAP] [debug] Got command of type ACTION
2017-02-13 21:26:55:455 - info: [debug] [BOOTSTRAP] [debug] Got command action: compressedLayoutHierarchy
2017-02-13 21:26:55:457 - info: [debug] Getting device API level
2017-02-13 21:26:55:458 - info: [debug] executing cmd: /opt/dev/android-sdk-linux/platform-tools/adb -s ad071603090702e246 shell "getprop ro.build.version.sdk"
2017-02-13 21:26:55:496 - info: [debug] [BOOTSTRAP] [debug] Returning result: {"status":0,"value":false}
2017-02-13 21:26:55:983 - info: [debug] Device is at API Level 23
2017-02-13 21:26:55:984 - info: [debug] executing cmd: /opt/dev/android-sdk-linux/platform-tools/adb -s ad071603090702e246 shell "am start -S -a android.intent.action.MAIN -c android.intent.category.LAUNCHER -f 0x10200000 -n com.youchat/com.youchat.activities.SignedOutActivity_"
2017-02-13 21:26:57:566 - info: [debug] Waiting for pkg "com.youchat" and activity "com.youchat.activities.SignedOutActivity_" to be focused
2017-02-13 21:26:57:566 - info: [debug] Getting focused package and activity
2017-02-13 21:26:57:567 - info: [debug] executing cmd: /opt/dev/android-sdk-linux/platform-tools/adb -s ad071603090702e246 shell "dumpsys window windows"
2017-02-13 21:26:58:893 - info: [debug] Getting focused package and activity
2017-02-13 21:26:58:894 - info: [debug] executing cmd: /opt/dev/android-sdk-linux/platform-tools/adb -s ad071603090702e246 shell "dumpsys window windows"
2017-02-13 21:27:00:280 - info: [debug] Getting focused package and activity
2017-02-13 21:27:00:280 - info: [debug] executing cmd: /opt/dev/android-sdk-linux/platform-tools/adb -s ad071603090702e246 shell "dumpsys window windows"
2017-02-13 21:27:01:729 - info: [debug] Getting focused package and activity
2017-02-13 21:27:01:732 - info: [debug] executing cmd: /opt/dev/android-sdk-linux/platform-tools/adb -s ad071603090702e246 shell "dumpsys window windows"
2017-02-13 21:27:03:181 - info: [debug] Getting focused package and activity
2017-02-13 21:27:03:183 - info: [debug] executing cmd: /opt/dev/android-sdk-linux/platform-tools/adb -s ad071603090702e246 shell "dumpsys window windows"
2017-02-13 21:27:04:713 - info: [debug] Getting focused package and activity
2017-02-13 21:27:04:714 - info: [debug] executing cmd: /opt/dev/android-sdk-linux/platform-tools/adb -s ad071603090702e246 shell "dumpsys window windows"
2017-02-13 21:27:06:179 - info: [debug] Getting focused package and activity
2017-02-13 21:27:06:180 - info: [debug] executing cmd: /opt/dev/android-sdk-linux/platform-tools/adb -s ad071603090702e246 shell "dumpsys window windows"
2017-02-13 21:27:07:586 - info: [debug] Getting focused package and activity
2017-02-13 21:27:07:587 - info: [debug] executing cmd: /opt/dev/android-sdk-linux/platform-tools/adb -s ad071603090702e246 shell "dumpsys window windows"
2017-02-13 21:27:09:119 - info: [debug] Getting focused package and activity
2017-02-13 21:27:09:120 - info: [debug] executing cmd: /opt/dev/android-sdk-linux/platform-tools/adb -s ad071603090702e246 shell "dumpsys window windows"
2017-02-13 21:27:10:441 - info: [debug] Getting focused package and activity
2017-02-13 21:27:10:441 - info: [debug] executing cmd: /opt/dev/android-sdk-linux/platform-tools/adb -s ad071603090702e246 shell "dumpsys window windows"
2017-02-13 21:27:12:000 - info: [debug] Getting focused package and activity
2017-02-13 21:27:12:006 - info: [debug] executing cmd: /opt/dev/android-sdk-linux/platform-tools/adb -s ad071603090702e246 shell "dumpsys window windows"
2017-02-13 21:27:13:459 - info: [debug] Getting focused package and activity
2017-02-13 21:27:13:459 - info: [debug] executing cmd: /opt/dev/android-sdk-linux/platform-tools/adb -s ad071603090702e246 shell "dumpsys window windows"
2017-02-13 21:27:14:946 - info: [debug] Getting focused package and activity
2017-02-13 21:27:14:946 - info: [debug] executing cmd: /opt/dev/android-sdk-linux/platform-tools/adb -s ad071603090702e246 shell "dumpsys window windows"
2017-02-13 21:27:16:372 - info: [debug] Getting focused package and activity
2017-02-13 21:27:16:372 - info: [debug] executing cmd: /opt/dev/android-sdk-linux/platform-tools/adb -s ad071603090702e246 shell "dumpsys window windows"
2017-02-13 21:27:17:833 - info: [debug] Getting focused package and activity
2017-02-13 21:27:17:834 - info: [debug] executing cmd: /opt/dev/android-sdk-linux/platform-tools/adb -s ad071603090702e246 shell "dumpsys window windows"
2017-02-13 21:27:18:585 - error: com.youchat/.activities.SignedOutActivity_ never started. Current: com.youchat/.activities.SignedOutActivity
2017-02-13 21:27:18:589 - info: [debug] Stopping logcat capture
2017-02-13 21:27:18:603 - info: [debug] Logcat terminated with code 143, signal null
2017-02-13 21:27:18:616 - info: [debug] Sent shutdown command, waiting for UiAutomator to stop...
2017-02-13 21:27:18:621 - info: [debug] [BOOTSTRAP] [debug] Got data from client: {"cmd":"shutdown"}
2017-02-13 21:27:18:621 - info: [debug] [BOOTSTRAP] [debug] Got command of type SHUTDOWN
2017-02-13 21:27:18:621 - info: [debug] [BOOTSTRAP] [debug] Returning result: {"status":0,"value":"OK, shutting down"}
2017-02-13 21:27:18:621 - info: [debug] [BOOTSTRAP] [debug] Closed client connection
2017-02-13 21:27:18:621 - info: [debug] [UIAUTOMATOR STDOUT] INSTRUMENTATION_STATUS: numtests=1
2017-02-13 21:27:18:621 - info: [debug] [UIAUTOMATOR STDOUT] INSTRUMENTATION_STATUS: stream=.
2017-02-13 21:27:18:621 - info: [debug] [UIAUTOMATOR STDOUT] INSTRUMENTATION_STATUS: id=UiAutomatorTestRunner
2017-02-13 21:27:18:622 - info: [debug] [UIAUTOMATOR STDOUT] INSTRUMENTATION_STATUS: test=testRunServer
2017-02-13 21:27:18:622 - info: [debug] [UIAUTOMATOR STDOUT] INSTRUMENTATION_STATUS: class=io.appium.android.bootstrap.Bootstrap
2017-02-13 21:27:18:622 - info: [debug] [UIAUTOMATOR STDOUT] INSTRUMENTATION_STATUS: current=1
2017-02-13 21:27:18:622 - info: [debug] [UIAUTOMATOR STDOUT] INSTRUMENTATION_STATUS_CODE: 0
2017-02-13 21:27:18:622 - info: [debug] [UIAUTOMATOR STDOUT] INSTRUMENTATION_STATUS: stream=
2017-02-13 21:27:18:622 - info: [debug] [UIAUTOMATOR STDOUT] Test results for WatcherResultPrinter=.
2017-02-13 21:27:18:622 - info: [debug] [UIAUTOMATOR STDOUT] Time: 24.127
2017-02-13 21:27:18:623 - info: [debug] [UIAUTOMATOR STDOUT] OK (1 test)
2017-02-13 21:27:18:623 - info: [debug] [UIAUTOMATOR STDOUT] INSTRUMENTATION_STATUS_CODE: -1
2017-02-13 21:27:18:688 - info: [debug] UiAutomator shut down normally
2017-02-13 21:27:18:688 - info: [debug] Cleaning up android objects
2017-02-13 21:27:18:689 - info: [debug] Cleaning up appium session
2017-02-13 21:27:18:714 - error: Could not pre-launch appium: Error: com.youchat/.activities.SignedOutActivity_ never started. Current: com.youchat/.activities.SignedOutActivity