Selenium Server doesn't repond in 20s

AlexisDurden · June 9, 2015, 3:02pm

Hello,
I have to set up a test automation for Android Device. But i have some trouble with the selendroid server

info: [debug] Making http request with opts: {"url":"http://localhost:8080/wd/hub/status","method":"GET"}
info: [debug] Making http request with opts: {"url":"http://localhost:8080/wd/hub/status","method":"GET"}
info: [debug] Making http request with opts: {"url":"http://localhost:8080/wd/hub/status","method":"GET"}
info: [debug] Making http request with opts: {"url":"http://localhost:8080/wd/hub/status","method":"GET"}
info: [debug] Making http request with opts: {"url":"http://localhost:8080/wd/hub/status","method":"GET"}
info: [debug] Making http request with opts: {"url":"http://localhost:8080/wd/hub/status","method":"GET"}
info: [debug] Making http request with opts: {"url":"http://localhost:8080/wd/hub/status","method":"GET"}
info: [debug] Making http request with opts: {"url":"http://localhost:8080/wd/hub/status","method":"GET"}
info: [debug] Making http request with opts: {"url":"http://localhost:8080/wd/hub/status","method":"GET"}
info: [debug] Making http request with opts: {"url":"http://localhost:8080/wd/hub/status","method":"GET"}
info: [debug] Making http request with opts: {"url":"http://localhost:8080/wd/hub/status","method":"GET"}
info: [debug] Cleaning up appium session
error: Failed to start an Appium session, err was: Error: Waited 20 secs for selendroid server and it never showed up
info: [debug] Error: Waited 20 secs for selendroid server and it never showed up
    at D:\TestAuto\SeleniumBot\tools\appium\node_modules\appium\lib\devices\android\selendroid.js:338:14
    at Request._callback (D:\TestAuto\SeleniumBot\tools\appium\node_modules\appium\lib\devices\common.js:121:5)
    at self.callback (D:\TestAuto\SeleniumBot\tools\appium\node_modules\appium\node_modules\request\request.js:121:22)
    at Request.EventEmitter.emit (events.js:95:17)
    at ClientRequest.self.clientErrorHandler (D:\TestAuto\SeleniumBot\tools\appium\node_modules\appium\node_modules\request\request.js:230:10)
    at ClientRequest.EventEmitter.emit (events.js:95:17)
    at Socket.socketErrorListener (http.js:1547:9)
    at Socket.EventEmitter.emit (events.js:95:17)
    at net.js:440:14
    at process._tickDomainCallback (node.js:459:13)
info: [debug] Responding to client with error: {"status":33,"value":{"message":"A new session could not be created. (Original error: Waited 20 secs for selendro
id server and it never showed up)","origValue":"Waited 20 secs for selendroid server and it never showed up"},"sessionId":null}
info: <-- POST /wd/hub/session 500 26531.658 ms - 240

Thank you for your help

sebv · June 9, 2015, 4:31pm

Any error before? You need to check the part where the Selendroid server is starting.

AlexisDurden · June 10, 2015, 7:21am

I don’t see any other error at the start-up of the Selendroid server

info: [debug] Starting selendroid server
info: [debug] Getting Java version
info: Java version is: 1.7.0_67
info: [debug] Checking whether selendroid is built yet
info: [debug] Selendroid server exists!
info: [debug] Using fast reset? false
info: [debug] Preparing device for session

sebv · June 11, 2015, 2:51am

Could you post full log, still server should start just after that.

AlexisDurden · June 11, 2015, 7:08am

The full log :

info: Welcome to Appium v1.3.4 (REV c8c79a85fbd6870cd6fc3d66d038a115ebe22efe)
info: Appium REST http interface listener started on 127.0.0.1:37088
info: [debug] Non-default server args: {"app":"D:\\Profiles\\alCarpentier\\Documents\\testOnAPP\\ContactManager-selendroid.apk","address":"127.0.0.1","port":370
88,"fullReset":true,"log":"C:\\tmp\\appium.log","deviceName":"192.168.56.101:5555","automationName":"Selendroid","defaultCommandTimeout":300}
info: Console LogLevel: debug
info: File LogLevel: debug
info: --> POST /wd/hub/session {"desiredCapabilities":{"automationName":"Selendroid","platformVersion":"4.1","app":"D:\\Profiles\\alCarpentier\\Documents\\testO
nAPP\\ContactManager-selendroid.apk","platformName":"Android","deviceName":"Android Emulator","appActivity":"ContactManager"}}
info: Client User-Agent string: Apache-HttpClient/4.3.5 (java 1.5)
info: [debug] No appPackage desired capability or server param. Parsing from apk.
info: [debug] Using local app from desired caps: D:\Profiles\alCarpentier\Documents\testOnAPP\ContactManager-selendroid.apk
info: [debug] Creating new appium session 94141713-9f52-4c55-9332-76a2f403317f
info: [debug] Starting selendroid server
info: [debug] Getting Java version
info: Java version is: 1.7.0_67
info: [debug] Checking whether selendroid is built yet
info: [debug] Selendroid server exists!
info: [debug] Using fast reset? false
info: [debug] Preparing device for session
info: [debug] Checking whether app is actually present
info: [debug] Checking whether adb is present
info: [debug] Using adb from D:\Profiles\alCarpentier\AppData\Local\Android\android-sdk\platform-tools\adb.exe
info: Retrieving device
info: [debug] Trying to find a connected android device
info: [debug] Getting connected devices...
info: [debug] executing cmd: D:\Profiles\alCarpentier\AppData\Local\Android\android-sdk\platform-tools\adb.exe devices
info: [debug] 1 device(s) connected
info: Found device 192.168.56.101:5555
info: [debug] Setting device id to 192.168.56.101:5555
info: [debug] Waiting for device to be ready and to respond to shell commands (timeout = 5)
info: [debug] executing cmd: D:\Profiles\alCarpentier\AppData\Local\Android\android-sdk\platform-tools\adb.exe -s 192.168.56.101:5555 wait-for-device
info: [debug] executing cmd: D:\Profiles\alCarpentier\AppData\Local\Android\android-sdk\platform-tools\adb.exe -s 192.168.56.101:5555 shell "echo 'ready'"
info: [debug] Starting logcat capture
info: [debug] Checking whether aapt is present
info: [debug] Using aapt from D:\Profiles\alCarpentier\AppData\Local\Android\android-sdk\build-tools\22.0.1\aapt.exe
info: [debug] Checking if has internet permission from manifest.
info: [debug] executing cmd: D:\Profiles\alCarpentier\AppData\Local\Android\android-sdk\build-tools\22.0.1\aapt.exe dump badging D:\Profiles\alCarpentier\Docume
nts\testOnAPP\ContactManager-selendroid.apk
info: [debug] Parsing package and activity from app manifest
info: [debug] Checking whether aapt is present
info: [debug] Using aapt from D:\Profiles\alCarpentier\AppData\Local\Android\android-sdk\build-tools\22.0.1\aapt.exe
info: [debug] Extracting package and launch activity from manifest.
info: [debug] executing cmd: D:\Profiles\alCarpentier\AppData\Local\Android\android-sdk\build-tools\22.0.1\aapt.exe dump badging D:\Profiles\alCarpentier\Docume
nts\testOnAPP\ContactManager-selendroid.apk
info: [debug] badging package: com.example.android.contactmanager
info: [debug] badging act: com.example.android.contactmanager.ContactManager
info: [debug] Parsed package and activity are: com.example.android.contactmanager/com.example.android.contactmanager.ContactManager
info: [debug] Rebuilt selendroid server already exists, no need to rebuild it with a new manifest
info: [debug] Checking signed status of d:\Profiles\ALCARP~1\AppData\Local\Temp\selendroid.com.example.android.contactmanager.apk
info: [debug] Checking app cert for d:\Profiles\ALCARP~1\AppData\Local\Temp\selendroid.com.example.android.contactmanager.apk.
info: [debug] executing cmd: java -jar D:\TestAuto\SeleniumBot\tools\appium\node_modules\appium\node_modules\appium-adb\jars\verify.jar d:\Profiles\ALCARP~1\App
Data\Local\Temp\selendroid.com.example.android.contactmanager.apk
info: [debug] Checking signed status of D:\Profiles\alCarpentier\Documents\testOnAPP\ContactManager-selendroid.apk
info: [debug] Checking app cert for D:\Profiles\alCarpentier\Documents\testOnAPP\ContactManager-selendroid.apk.
info: [debug] executing cmd: java -jar D:\TestAuto\SeleniumBot\tools\appium\node_modules\appium\node_modules\appium-adb\jars\verify.jar D:\Profiles\alCarpentier
\Documents\testOnAPP\ContactManager-selendroid.apk
info: [debug] App already signed.
info: [debug] Zip-aligning D:\Profiles\alCarpentier\Documents\testOnAPP\ContactManager-selendroid.apk
info: [debug] Checking whether zipalign is present
info: [debug] Using zipalign from D:\Profiles\alCarpentier\AppData\Local\Android\android-sdk\build-tools\22.0.1\zipalign.exe
info: [debug] Zip-aligning apk.
info: [debug] executing cmd: D:\Profiles\alCarpentier\AppData\Local\Android\android-sdk\build-tools\22.0.1\zipalign.exe -f 4 D:\Profiles\alCarpentier\Documents\
testOnAPP\ContactManager-selendroid.apk d:\Profiles\ALCARP~1\AppData\Local\Temp\appium115511-6160-1gkwn8.tmp
info: [debug] App already signed.
info: [debug] Zip-aligning d:\Profiles\ALCARP~1\AppData\Local\Temp\selendroid.com.example.android.contactmanager.apk
info: [debug] Checking whether zipalign is present
info: [debug] Using zipalign from D:\Profiles\alCarpentier\AppData\Local\Android\android-sdk\build-tools\22.0.1\zipalign.exe
info: [debug] Zip-aligning apk.
info: [debug] executing cmd: D:\Profiles\alCarpentier\AppData\Local\Android\android-sdk\build-tools\22.0.1\zipalign.exe -f 4 d:\Profiles\ALCARP~1\AppData\Local\
Temp\selendroid.com.example.android.contactmanager.apk d:\Profiles\ALCARP~1\AppData\Local\Temp\appium115511-6160-5ii2ii.tmp
info: [debug] Rebuilt selendroid apk does not exist, uninstalling any instances of it on device to make way for new one
info: [debug] Uninstalling com.example.android.contactmanager.selendroid
info: [debug] executing cmd: D:\Profiles\alCarpentier\AppData\Local\Android\android-sdk\platform-tools\adb.exe -s 192.168.56.101:5555 shell "am force-stop com.e
xample.android.contactmanager.selendroid"
info: [debug] executing cmd: D:\Profiles\alCarpentier\AppData\Local\Android\android-sdk\platform-tools\adb.exe -s 192.168.56.101:5555 uninstall com.example.andr
oid.contactmanager.selendroid
info: [debug] App was uninstalled
info: [debug] Getting install status for com.example.android.contactmanager.selendroid
info: [debug] Getting device API level
info: [debug] executing cmd: D:\Profiles\alCarpentier\AppData\Local\Android\android-sdk\platform-tools\adb.exe -s 192.168.56.101:5555 shell "getprop ro.build.ve
rsion.sdk"
info: [debug] Device is at API Level 16
info: [debug] executing cmd: D:\Profiles\alCarpentier\AppData\Local\Android\android-sdk\platform-tools\adb.exe -s 192.168.56.101:5555 shell "pm list packages -3
 com.example.android.contactmanager.selendroid"
info: [debug] App is not installed
info: [debug] Rebuilt selendroid is not installed, installing it
info: [debug] executing cmd: D:\Profiles\alCarpentier\AppData\Local\Android\android-sdk\platform-tools\adb.exe -s 192.168.56.101:5555 install -r "d:\Profiles\AL
CARP~1\AppData\Local\Temp\selendroid.com.example.android.contactmanager.apk"
info: [debug] executing cmd: D:\Profiles\alCarpentier\AppData\Local\Android\android-sdk\platform-tools\adb.exe -s 192.168.56.101:5555 shell "getprop persist.sys
.language"
info: [debug] Current device persist.sys.language: en
info: [debug] java -jar "D:\TestAuto\SeleniumBot\tools\appium\node_modules\appium\node_modules\appium-adb\jars\appium_apk_tools.jar" "stringsFromApk" "D:\Profil
es\alCarpentier\Documents\testOnAPP\ContactManager-selendroid.apk" "d:\Profiles\ALCARP~1\AppData\Local\Temp\com.example.android.contactmanager" en
info: [debug] No strings.xml for language 'en', getting default strings.xml
info: [debug] java -jar "D:\TestAuto\SeleniumBot\tools\appium\node_modules\appium\node_modules\appium-adb\jars\appium_apk_tools.jar" "stringsFromApk" "D:\Profil
es\alCarpentier\Documents\testOnAPP\ContactManager-selendroid.apk" "d:\Profiles\ALCARP~1\AppData\Local\Temp\com.example.android.contactmanager"
info: [debug] Uninstalling com.example.android.contactmanager
info: [debug] executing cmd: D:\Profiles\alCarpentier\AppData\Local\Android\android-sdk\platform-tools\adb.exe -s 192.168.56.101:5555 shell "am force-stop com.e
xample.android.contactmanager"
info: [debug] executing cmd: D:\Profiles\alCarpentier\AppData\Local\Android\android-sdk\platform-tools\adb.exe -s 192.168.56.101:5555 uninstall com.example.andr
oid.contactmanager
info: [debug] App was uninstalled
info: [debug] Checking app cert for D:\Profiles\alCarpentier\Documents\testOnAPP\ContactManager-selendroid.apk.
info: [debug] executing cmd: java -jar D:\TestAuto\SeleniumBot\tools\appium\node_modules\appium\node_modules\appium-adb\jars\verify.jar D:\Profiles\alCarpentier
\Documents\testOnAPP\ContactManager-selendroid.apk
info: [debug] App already signed.
info: [debug] Zip-aligning D:\Profiles\alCarpentier\Documents\testOnAPP\ContactManager-selendroid.apk
info: [debug] Checking whether zipalign is present
info: [debug] Using zipalign from D:\Profiles\alCarpentier\AppData\Local\Android\android-sdk\build-tools\22.0.1\zipalign.exe
info: [debug] Zip-aligning apk.
info: [debug] executing cmd: D:\Profiles\alCarpentier\AppData\Local\Android\android-sdk\build-tools\22.0.1\zipalign.exe -f 4 D:\Profiles\alCarpentier\Documents\
testOnAPP\ContactManager-selendroid.apk d:\Profiles\ALCARP~1\AppData\Local\Temp\appium115511-6160-12sph5x.tmp
info: [debug] MD5 for app is 6d2ffe9de6e0da4dac74094e843664ce
info: [debug] executing cmd: D:\Profiles\alCarpentier\AppData\Local\Android\android-sdk\platform-tools\adb.exe -s 192.168.56.101:5555 shell "ls /data/local/tmp/
6d2ffe9de6e0da4dac74094e843664ce.apk"
info: [debug] Getting install status for com.example.android.contactmanager
info: [debug] Getting device API level
info: [debug] executing cmd: D:\Profiles\alCarpentier\AppData\Local\Android\android-sdk\platform-tools\adb.exe -s 192.168.56.101:5555 shell "getprop ro.build.ve
rsion.sdk"
info: [debug] Device is at API Level 16
info: [debug] executing cmd: D:\Profiles\alCarpentier\AppData\Local\Android\android-sdk\platform-tools\adb.exe -s 192.168.56.101:5555 shell "pm list packages -3
 com.example.android.contactmanager"
info: [debug] App is not installed
info: Installing App
info: [debug] executing cmd: D:\Profiles\alCarpentier\AppData\Local\Android\android-sdk\platform-tools\adb.exe -s 192.168.56.101:5555 shell "mkdir -p /data/loca
l/tmp/"
info: [debug] Removing any old apks
info: [debug] executing cmd: D:\Profiles\alCarpentier\AppData\Local\Android\android-sdk\platform-tools\adb.exe -s 192.168.56.101:5555 shell "ls /data/local/tmp/
*.apk"
info: [debug] Found an apk we want to keep at /data/local/tmp/6d2ffe9de6e0da4dac74094e843664ce.apk
info: [debug] executing cmd: D:\Profiles\alCarpentier\AppData\Local\Android\android-sdk\platform-tools\adb.exe -s 192.168.56.101:5555 shell rm "/data/local/tmp/
selendroid.com.example.android.contactmanager.apk" && rm "/data/local/tmp/settings_apk-debug.apk" && rm "/data/local/tmp/unlock_apk-debug.apk"
info: [debug] Uninstalling com.example.android.contactmanager
info: [debug] executing cmd: D:\Profiles\alCarpentier\AppData\Local\Android\android-sdk\platform-tools\adb.exe -s 192.168.56.101:5555 shell "am force-stop com.e
xample.android.contactmanager"
info: [debug] executing cmd: D:\Profiles\alCarpentier\AppData\Local\Android\android-sdk\platform-tools\adb.exe -s 192.168.56.101:5555 uninstall com.example.andr
oid.contactmanager
info: [debug] App was not uninstalled, maybe it wasn't on device?
info: [debug] executing cmd: D:\Profiles\alCarpentier\AppData\Local\Android\android-sdk\platform-tools\adb.exe -s 192.168.56.101:5555 shell "pm install -r /data
/local/tmp/6d2ffe9de6e0da4dac74094e843664ce.apk"
info: [debug] Forwarding system:8080 to device:8080
info: [debug] executing cmd: D:\Profiles\alCarpentier\AppData\Local\Android\android-sdk\platform-tools\adb.exe -s 192.168.56.101:5555 forward tcp:8080 tcp:8080
info: [debug] Pushing settings apk to device...
info: [debug] executing cmd: D:\Profiles\alCarpentier\AppData\Local\Android\android-sdk\platform-tools\adb.exe -s 192.168.56.101:5555 install "D:\TestAuto\Selen
iumBot\tools\appium\node_modules\appium\build\settings_apk\settings_apk-debug.apk"
info: [debug] Pushing unlock helper app to device...
info: [debug] executing cmd: D:\Profiles\alCarpentier\AppData\Local\Android\android-sdk\platform-tools\adb.exe -s 192.168.56.101:5555 install "D:\TestAuto\Selen
iumBot\tools\appium\node_modules\appium\build\unlock_apk\unlock_apk-debug.apk"
info: [debug] executing cmd: D:\Profiles\alCarpentier\AppData\Local\Android\android-sdk\platform-tools\adb.exe -s 192.168.56.101:5555 shell "dumpsys window"
info: [debug] Screen already unlocked, continuing.
info: [debug] executing cmd: D:\Profiles\alCarpentier\AppData\Local\Android\android-sdk\platform-tools\adb.exe -s 192.168.56.101:5555 shell "am instrument -e ma
in_activity 'ContactManager' com.example.android.contactmanager.selendroid/io.selendroid.ServerInstrumentation"
info: [debug] Making http request with opts: {"url":"http://localhost:8080/wd/hub/status","method":"GET"}
info: [debug] Making http request with opts: {"url":"http://localhost:8080/wd/hub/status","method":"GET"}
info: [debug] Making http request with opts: {"url":"http://localhost:8080/wd/hub/status","method":"GET"}
info: [debug] Making http request with opts: {"url":"http://localhost:8080/wd/hub/status","method":"GET"}
info: [debug] Making http request with opts: {"url":"http://localhost:8080/wd/hub/status","method":"GET"}
info: [debug] Making http request with opts: {"url":"http://localhost:8080/wd/hub/status","method":"GET"}
info: [debug] Making http request with opts: {"url":"http://localhost:8080/wd/hub/status","method":"GET"}
info: [debug] Making http request with opts: {"url":"http://localhost:8080/wd/hub/status","method":"GET"}
info: [debug] Making http request with opts: {"url":"http://localhost:8080/wd/hub/status","method":"GET"}
info: [debug] Making http request with opts: {"url":"http://localhost:8080/wd/hub/status","method":"GET"}
info: [debug] Making http request with opts: {"url":"http://localhost:8080/wd/hub/status","method":"GET"}
info: [debug] Making http request with opts: {"url":"http://localhost:8080/wd/hub/status","method":"GET"}
info: [debug] Making http request with opts: {"url":"http://localhost:8080/wd/hub/status","method":"GET"}
info: [debug] Making http request with opts: {"url":"http://localhost:8080/wd/hub/status","method":"GET"}
info: [debug] Making http request with opts: {"url":"http://localhost:8080/wd/hub/status","method":"GET"}
info: [debug] Making http request with opts: {"url":"http://localhost:8080/wd/hub/status","method":"GET"}
info: [debug] Making http request with opts: {"url":"http://localhost:8080/wd/hub/status","method":"GET"}
info: [debug] Making http request with opts: {"url":"http://localhost:8080/wd/hub/status","method":"GET"}
info: [debug] Making http request with opts: {"url":"http://localhost:8080/wd/hub/status","method":"GET"}
info: [debug] Making http request with opts: {"url":"http://localhost:8080/wd/hub/status","method":"GET"}
info: [debug] Making http request with opts: {"url":"http://localhost:8080/wd/hub/status","method":"GET"}
info: [debug] Making http request with opts: {"url":"http://localhost:8080/wd/hub/status","method":"GET"}
info: [debug] Making http request with opts: {"url":"http://localhost:8080/wd/hub/status","method":"GET"}
info: [debug] Making http request with opts: {"url":"http://localhost:8080/wd/hub/status","method":"GET"}
info: [debug] Making http request with opts: {"url":"http://localhost:8080/wd/hub/status","method":"GET"}
info: [debug] Making http request with opts: {"url":"http://localhost:8080/wd/hub/status","method":"GET"}
info: [debug] Cleaning up appium session
error: Failed to start an Appium session, err was: Error: Waited 20 secs for selendroid server and it never showed up
info: [debug] Error: Waited 20 secs for selendroid server and it never showed up
    at D:\TestAuto\SeleniumBot\tools\appium\node_modules\appium\lib\devices\android\selendroid.js:338:14
    at Request._callback (D:\TestAuto\SeleniumBot\tools\appium\node_modules\appium\lib\devices\common.js:121:5)
    at self.callback (D:\TestAuto\SeleniumBot\tools\appium\node_modules\appium\node_modules\request\request.js:121:22)
    at Request.EventEmitter.emit (events.js:95:17)
    at ClientRequest.self.clientErrorHandler (D:\TestAuto\SeleniumBot\tools\appium\node_modules\appium\node_modules\request\request.js:230:10)
    at ClientRequest.EventEmitter.emit (events.js:95:17)
    at Socket.socketErrorListener (http.js:1547:9)
    at Socket.EventEmitter.emit (events.js:95:17)
    at net.js:440:14
    at process._tickDomainCallback (node.js:459:13)
info: [debug] Responding to client with error: {"status":33,"value":{"message":"A new session could not be created. (Original error: Waited 20 secs for selendro
id server and it never showed up)","origValue":"Waited 20 secs for selendroid server and it never showed up"},"sessionId":null}

info: <-- POST /wd/hub/session 500 27905.717 ms - 240

And when I use the latest version of Appium i have this

info: Welcome to Appium v1.4.0 (REV 8f63e2f91ef7907aed8bda763f4e5ca08e86970a)
info: Appium REST http interface listener started on 127.0.0.1:26487
info: [debug] Non-default server args: {"app":"D:\\Profiles\\alCarpentier\\Documents\\testOnAPP\\ContactManager-selendroid.apk","address":"127.0.0.1","port":264
87,"fullReset":true,"log":"C:\\tmp\\appium.log","deviceName":"192.168.56.101:5555","automationName":"Selendroid","defaultCommandTimeout":300}
info: Console LogLevel: debug
info: File LogLevel: debug
info: --> POST /wd/hub/session {"desiredCapabilities":{"automationName":"Selendroid","platformVersion":"4.1","app":"D:\\Profiles\\alCarpentier\\Documents\\testO
nAPP\\ContactManager-selendroid.apk","platformName":"Android","deviceName":"Android Emulator","appActivity":"ContactManager"}}
info: Client User-Agent string: Apache-HttpClient/4.3.5 (java 1.5)
info: [debug] No appPackage desired capability or server param. Parsing from apk.
info: [debug] Using local app from desired caps: D:\Profiles\alCarpentier\Documents\testOnAPP\ContactManager-selendroid.apk
info: [debug] Creating new appium session bc49a84f-b329-4760-8846-d8e62e419b5a
info: [debug] Starting selendroid server
info: [debug] Getting Java version
info: Java version is: 1.7.0_67
info: [debug] Checking whether adb is present
info: [debug] Using adb from D:\Profiles\alCarpentier\AppData\Local\Android\android-sdk\platform-tools\adb.exe
info: [debug] Checking whether selendroid is built yet
info: [debug] Selendroid server exists!
info: [debug] Using fast reset? false
info: [debug] Preparing device for session
info: [debug] Checking whether app is actually present
info: Retrieving device
info: [debug] Trying to find a connected android device
info: [debug] Getting connected devices...
info: [debug] executing cmd: D:\Profiles\alCarpentier\AppData\Local\Android\android-sdk\platform-tools\adb.exe devices
info: [debug] 1 device(s) connected
info: Found device 192.168.56.101:5555
info: [debug] Setting device id to 192.168.56.101:5555
info: [debug] Waiting for device to be ready and to respond to shell commands (timeout = 5)
info: [debug] executing cmd: D:\Profiles\alCarpentier\AppData\Local\Android\android-sdk\platform-tools\adb.exe -s 192.168.56.101:5555 wait-for-device
info: [debug] executing cmd: D:\Profiles\alCarpentier\AppData\Local\Android\android-sdk\platform-tools\adb.exe -s 192.168.56.101:5555 shell "echo 'ready'"
info: [debug] Starting logcat capture
info: [debug] Checking whether aapt is present
info: [debug] Using aapt from D:\Profiles\alCarpentier\AppData\Local\Android\android-sdk\build-tools\22.0.1\aapt.exe
info: [debug] Checking if has internet permission from manifest.
info: [debug] executing cmd: D:\Profiles\alCarpentier\AppData\Local\Android\android-sdk\build-tools\22.0.1\aapt.exe dump badging D:\Profiles\alCarpentier\Docume
nts\testOnAPP\ContactManager-selendroid.apk
info: [debug] Parsing package and activity from app manifest
info: [debug] Checking whether aapt is present
info: [debug] Using aapt from D:\Profiles\alCarpentier\AppData\Local\Android\android-sdk\build-tools\22.0.1\aapt.exe
info: [debug] Extracting package and launch activity from manifest.
info: [debug] executing cmd: D:\Profiles\alCarpentier\AppData\Local\Android\android-sdk\build-tools\22.0.1\aapt.exe dump badging D:\Profiles\alCarpentier\Docume
nts\testOnAPP\ContactManager-selendroid.apk
info: [debug] badging package: com.example.android.contactmanager
info: [debug] badging act: com.example.android.contactmanager.ContactManager
info: [debug] Parsed package and activity are: com.example.android.contactmanager/com.example.android.contactmanager.ContactManager
info: [debug] Rebuilt selendroid server already exists, no need to rebuild it with a new manifest
info: [debug] Checking signed status of d:\Profiles\ALCARP~1\AppData\Local\Temp\selendroid.com.example.android.contactmanager.apk
info: [debug] Checking app cert for d:\Profiles\ALCARP~1\AppData\Local\Temp\selendroid.com.example.android.contactmanager.apk.
info: [debug] executing cmd: java -jar "C:\Program Files (x86)\Appium\node_modules\appium\node_modules\appium-adb\jars\verify.jar" d:\Profiles\ALCARP~1\AppData\
Local\Temp\selendroid.com.example.android.contactmanager.apk
info: [debug] Checking signed status of D:\Profiles\alCarpentier\Documents\testOnAPP\ContactManager-selendroid.apk
info: [debug] Checking app cert for D:\Profiles\alCarpentier\Documents\testOnAPP\ContactManager-selendroid.apk.
info: [debug] executing cmd: java -jar "C:\Program Files (x86)\Appium\node_modules\appium\node_modules\appium-adb\jars\verify.jar" D:\Profiles\alCarpentier\Docu
ments\testOnAPP\ContactManager-selendroid.apk
info: [debug] App already signed.
info: [debug] Zip-aligning D:\Profiles\alCarpentier\Documents\testOnAPP\ContactManager-selendroid.apk
info: [debug] Checking whether zipalign is present
info: [debug] Using zipalign from D:\Profiles\alCarpentier\AppData\Local\Android\android-sdk\build-tools\22.0.1\zipalign.exe
info: [debug] Zip-aligning apk.
info: [debug] executing cmd: D:\Profiles\alCarpentier\AppData\Local\Android\android-sdk\build-tools\22.0.1\zipalign.exe -f 4 D:\Profiles\alCarpentier\Documents\
testOnAPP\ContactManager-selendroid.apk d:\Profiles\ALCARP~1\AppData\Local\Temp\115511-2440-cevir7\appium.tmp
info: [debug] App already signed.
info: [debug] Zip-aligning d:\Profiles\ALCARP~1\AppData\Local\Temp\selendroid.com.example.android.contactmanager.apk
info: [debug] Checking whether zipalign is present
info: [debug] Using zipalign from D:\Profiles\alCarpentier\AppData\Local\Android\android-sdk\build-tools\22.0.1\zipalign.exe
info: [debug] Zip-aligning apk.
info: [debug] executing cmd: D:\Profiles\alCarpentier\AppData\Local\Android\android-sdk\build-tools\22.0.1\zipalign.exe -f 4 d:\Profiles\ALCARP~1\AppData\Local\
Temp\selendroid.com.example.android.contactmanager.apk d:\Profiles\ALCARP~1\AppData\Local\Temp\115511-2440-n860m2\appium.tmp
info: [debug] Rebuilt selendroid apk exists, doing nothing
info: [debug] Getting install status for com.example.android.contactmanager.selendroid
info: [debug] Getting device API level
info: [debug] executing cmd: D:\Profiles\alCarpentier\AppData\Local\Android\android-sdk\platform-tools\adb.exe -s 192.168.56.101:5555 shell "getprop ro.build.ve
rsion.sdk"
info: [debug] MD5 for selendroid server is 61bc63070fffde918467210b023ebe5a
info: [debug] Device is at API Level 16
info: [debug] executing cmd: D:\Profiles\alCarpentier\AppData\Local\Android\android-sdk\platform-tools\adb.exe -s 192.168.56.101:5555 shell "pm list packages -3
 com.example.android.contactmanager.selendroid"
info: [debug] App is installed
info: [debug] Rebuilt selendroid is already installed
info: [debug] Extracting strings for language: default
info: [debug] executing cmd: D:\Profiles\alCarpentier\AppData\Local\Android\android-sdk\platform-tools\adb.exe -s 192.168.56.101:5555 shell "getprop persist.sys
.language"
info: [debug] Current device persist.sys.language: en
info: [debug] java -jar "C:\Program Files (x86)\Appium\node_modules\appium\node_modules\appium-adb\jars\appium_apk_tools.jar" "stringsFromApk" "D:\Profiles\alCa
rpentier\Documents\testOnAPP\ContactManager-selendroid.apk" "d:\Profiles\ALCARP~1\AppData\Local\Temp\com.example.android.contactmanager" en
info: [debug] No strings.xml for language 'en', getting default strings.xml
info: [debug] java -jar "C:\Program Files (x86)\Appium\node_modules\appium\node_modules\appium-adb\jars\appium_apk_tools.jar" "stringsFromApk" "D:\Profiles\alCa
rpentier\Documents\testOnAPP\ContactManager-selendroid.apk" "d:\Profiles\ALCARP~1\AppData\Local\Temp\com.example.android.contactmanager"
info: [debug] Reading strings from converted strings.json
info: [debug] Setting language to default
info: [debug] Uninstalling com.example.android.contactmanager
info: [debug] executing cmd: D:\Profiles\alCarpentier\AppData\Local\Android\android-sdk\platform-tools\adb.exe -s 192.168.56.101:5555 shell "am force-stop com.e
xample.android.contactmanager"
info: [debug] executing cmd: D:\Profiles\alCarpentier\AppData\Local\Android\android-sdk\platform-tools\adb.exe -s 192.168.56.101:5555 uninstall com.example.andr
oid.contactmanager
info: [debug] App was uninstalled
info: [debug] Checking app cert for D:\Profiles\alCarpentier\Documents\testOnAPP\ContactManager-selendroid.apk.
info: [debug] executing cmd: java -jar "C:\Program Files (x86)\Appium\node_modules\appium\node_modules\appium-adb\jars\verify.jar" D:\Profiles\alCarpentier\Docu
ments\testOnAPP\ContactManager-selendroid.apk
info: [debug] App already signed.
info: [debug] Zip-aligning D:\Profiles\alCarpentier\Documents\testOnAPP\ContactManager-selendroid.apk
info: [debug] Checking whether zipalign is present
info: [debug] Using zipalign from D:\Profiles\alCarpentier\AppData\Local\Android\android-sdk\build-tools\22.0.1\zipalign.exe
info: [debug] Zip-aligning apk.
info: [debug] executing cmd: D:\Profiles\alCarpentier\AppData\Local\Android\android-sdk\build-tools\22.0.1\zipalign.exe -f 4 D:\Profiles\alCarpentier\Documents\
testOnAPP\ContactManager-selendroid.apk d:\Profiles\ALCARP~1\AppData\Local\Temp\115511-2440-hbnsyw\appium.tmp
info: [debug] MD5 for app is 6d2ffe9de6e0da4dac74094e843664ce
info: [debug] executing cmd: D:\Profiles\alCarpentier\AppData\Local\Android\android-sdk\platform-tools\adb.exe -s 192.168.56.101:5555 shell "ls /data/local/tmp/
6d2ffe9de6e0da4dac74094e843664ce.apk"
info: [debug] Getting install status for com.example.android.contactmanager
info: [debug] Getting device API level
info: [debug] executing cmd: D:\Profiles\alCarpentier\AppData\Local\Android\android-sdk\platform-tools\adb.exe -s 192.168.56.101:5555 shell "getprop ro.build.ve
rsion.sdk"
info: [debug] Device is at API Level 16
info: [debug] executing cmd: D:\Profiles\alCarpentier\AppData\Local\Android\android-sdk\platform-tools\adb.exe -s 192.168.56.101:5555 shell "pm list packages -3
 com.example.android.contactmanager"
info: [debug] App is not installed
info: Installing App
info: [debug] executing cmd: D:\Profiles\alCarpentier\AppData\Local\Android\android-sdk\platform-tools\adb.exe -s 192.168.56.101:5555 shell "mkdir -p /data/loca
l/tmp/"
info: [debug] Removing any old apks
info: [debug] executing cmd: D:\Profiles\alCarpentier\AppData\Local\Android\android-sdk\platform-tools\adb.exe -s 192.168.56.101:5555 shell "ls /data/local/tmp/
*.apk"
info: [debug] Found an apk we want to keep at /data/local/tmp/6d2ffe9de6e0da4dac74094e843664ce.apk
info: [debug] executing cmd: D:\Profiles\alCarpentier\AppData\Local\Android\android-sdk\platform-tools\adb.exe -s 192.168.56.101:5555 shell rm "/data/local/tmp/
settings_apk-debug.apk" && rm "/data/local/tmp/unlock_apk-debug.apk"
info: [debug] Uninstalling com.example.android.contactmanager
info: [debug] executing cmd: D:\Profiles\alCarpentier\AppData\Local\Android\android-sdk\platform-tools\adb.exe -s 192.168.56.101:5555 shell "am force-stop com.e
xample.android.contactmanager"
info: [debug] executing cmd: D:\Profiles\alCarpentier\AppData\Local\Android\android-sdk\platform-tools\adb.exe -s 192.168.56.101:5555 uninstall com.example.andr
oid.contactmanager
info: [debug] App was not uninstalled, maybe it wasn't on device?
info: [debug] executing cmd: D:\Profiles\alCarpentier\AppData\Local\Android\android-sdk\platform-tools\adb.exe -s 192.168.56.101:5555 shell "pm install -r /data
/local/tmp/6d2ffe9de6e0da4dac74094e843664ce.apk"
info: [debug] Forwarding system:8080 to device:8080
info: [debug] executing cmd: D:\Profiles\alCarpentier\AppData\Local\Android\android-sdk\platform-tools\adb.exe -s 192.168.56.101:5555 forward tcp:8080 tcp:8080
info: [debug] Pushing settings apk to device...
info: [debug] executing cmd: D:\Profiles\alCarpentier\AppData\Local\Android\android-sdk\platform-tools\adb.exe -s 192.168.56.101:5555 install "C:\Program Files
(x86)\Appium\node_modules\appium\build\settings_apk\settings_apk-debug.apk"
info: [debug] Pushing unlock helper app to device...
info: [debug] executing cmd: D:\Profiles\alCarpentier\AppData\Local\Android\android-sdk\platform-tools\adb.exe -s 192.168.56.101:5555 install "C:\Program Files
(x86)\Appium\node_modules\appium\build\unlock_apk\unlock_apk-debug.apk"
info: [debug] executing cmd: D:\Profiles\alCarpentier\AppData\Local\Android\android-sdk\platform-tools\adb.exe -s 192.168.56.101:5555 shell "dumpsys window"
info: [debug] Screen already unlocked, continuing.
info: [debug] executing cmd: D:\Profiles\alCarpentier\AppData\Local\Android\android-sdk\platform-tools\adb.exe -s 192.168.56.101:5555 shell "am instrument -e ma
in_activity 'ContactManager' com.example.android.contactmanager.selendroid/io.selendroid.server.ServerInstrumentation"
error: android.util.AndroidException: INSTRUMENTATION_FAILED: com.example.android.contactmanager.selendroid/io.selendroid.server.ServerInstrumentation

        at com.android.commands.am.Am.runInstrument(Am.java:616)

        at com.android.commands.am.Am.run(Am.java:118)

        at com.android.commands.am.Am.main(Am.java:81)

        at com.android.internal.os.RuntimeInit.nativeFinishInit(Native Method)

        at com.android.internal.os.RuntimeInit.main(RuntimeInit.java:235)

        at dalvik.system.NativeStart.main(Native Method)
info: [debug] Cleaning up appium session
error: Failed to start an Appium session, err was: Error: android.util.AndroidException: INSTRUMENTATION_FAILED: com.example.android.contactmanager.selendroid/i
o.selendroid.server.ServerInstrumentation

info: [debug] Error: android.util.AndroidException: INSTRUMENTATION_FAILED: com.example.android.contactmanager.selendroid/io.selendroid.server.ServerInstrumenta
tion

    at C:\Program Files (x86)\Appium\node_modules\appium\node_modules\appium-adb\lib\adb.js:1394:17
    at [object Object].<anonymous> (C:\Program Files (x86)\Appium\node_modules\appium\node_modules\appium-adb\lib\adb.js:180:9)
    at ChildProcess.exithandler (child_process.js:742:7)
    at ChildProcess.emit (events.js:110:17)
    at maybeClose (child_process.js:1015:16)
    at Process.ChildProcess._handle.onexit (child_process.js:1087:5)
info: [debug] Responding to client with error: {"status":33,"value":{"message":"A new session could not be created. (Original error: android.util.AndroidExcepti
on: INSTRUMENTATION_FAILED: com.example.android.contactmanager.selendroid/io.selendroid.server.ServerInstrumentation\r\r)","origValue":"android.util.AndroidExce
ption: INSTRUMENTATION_FAILED: com.example.android.contactmanager.selendroid/io.selendroid.server.ServerInstrumentation\r\r"},"sessionId":null}
info: <-- POST /wd/hub/session 500 4228.061 ms - 416

sebv · June 11, 2015, 11:39am

Look like similar issue here: Original error: Waited 20 secs for selendroid server and it never showed up. · Issue #4617 · appium/appium · GitHub

Could you also check the logcat to see why selendroid crashed.

AlexisDurden · June 11, 2015, 1:05pm

I find this line in the logcat :

06-11 12:20:25.844 W/PackageManager(  293): Not granting permission android.permission.INJECT_EVENTS to package com.example.contactmanager.android.selendroid.selendroid (protectionLevel=2 flags=0x28be44)

I think it’s explainning at least one error, but I don’t know how to solve it