getPageSource() doesn't, because FileNotFoundException

This test is about as simple as I can make it. I load and app and then try to print the page source. I’m on 1.2.4.1, and it was working before I upgraded to 1.3.4, so I reinstalled. But, it is still broken. I’ve been tasked with evaluating Appium for use in our group, and I was set to give a demo tomorrow (12/9/2014). I’m going to be very embarrassed if I can’t get this working, but right now I don’t even know where else to look. Please, HELLPP!!!

package mypackage;

import io.appium.java_client.AppiumDriver;
import io.appium.java_client.android.AndroidDriver;

import java.io.File;
import java.net.URL;
import java.util.List;

import org.openqa.selenium.;
import org.testng.annotations.
;
import org.openqa.selenium.logging.LogEntries;
import org.openqa.selenium.remote.CapabilityType;
import org.openqa.selenium.remote.DesiredCapabilities;

public class AppiumTest {

private AppiumDriver androidDriver;

@BeforeMethod
public void setUp() throws Exception
{
    //setup appium
    File app = new File("C:\\Users\\502263844\\Documents\\Laundry_v5.1.0.12e.apk");
    DesiredCapabilities capabilities = new DesiredCapabilities();
    capabilities.setCapability("deviceName", "Nexus7");
    capabilities.setCapability("platformName", "Android");
    capabilities.setCapability(CapabilityType.VERSION, "4.2");
    capabilities.setCapability("automationName", "Appium");
    capabilities.setCapability("app", app.getAbsolutePath());
    capabilities.setCapability("appPackage", "com.ge.laundryhome");
    capabilities.setCapability("appActivity", "com.ge.laundryhome.LaundryHomeActivity");
    androidDriver= new AndroidDriver(new URL("http://127.0.0.1:4723/wd/hub"), capabilities);
}

@Test
public void loginTest() throws InterruptedException
{
    System.out.println("Starting test");
    Thread.sleep(100);
    System.out.println(androidDriver.getPageSource());

}

}

info: e[37m–>e[39m e[37mPOSTe[39m e[37m/wd/hub/sessione[39m e[90m{“desiredCapabilities”:{“app”:“C:\Users\502263844\Documents\Laundry_v5.1.0.12e.apk”,“appPackage”:“com.ge.laundryhome”,“appActivity”:“com.ge.laundryhome.LaundryHomeActivity”,“automationName”:“Appium”,“platformName”:“Android”,“deviceName”:“Nexus7”,“version”:“4.2”}}e[39m
info: [debug] The following desired capabilities were provided, but not recognized by appium. They will be passed on to any other services running on this server. : version
info: [debug] Using local app from desired caps: C:\Users\502263844\Documents\Laundry_v5.1.0.12e.apk
info: [debug] Creating new appium session 2609153d-9dba-48ae-bdbb-612562a1b5db
info: Starting android appium
info: [debug] Using fast reset? true
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 C:\android-sdk\platform-tools\adb.exe
info: [debug] Trying to find Test emulator
info: [debug] Getting connected emulators
info: [debug] Getting connected devices…
info: [debug] executing: “C:\android-sdk\platform-tools\adb.exe” devices
info: [debug] 0 device(s) connected
info: [debug] 0 emulator(s) connected
info: [debug] Launching Emulator with AVD Test, launchTimeout 120000ms and readyTimeout 120000ms
info: [debug] Checking whether emulator is present
info: [debug] Using emulator from C:\android-sdk\tools\emulator.exe
info: [debug] Trying to find Test emulator
info: [debug] Getting connected emulators
info: [debug] Getting connected devices…
info: [debug] executing: “C:\android-sdk\platform-tools\adb.exe” devices
info: [debug] 0 device(s) connected
info: [debug] 0 emulator(s) connected
info: [debug] Trying to find Test emulator
info: [debug] Getting connected emulators
info: [debug] Getting connected devices…
info: [debug] executing: “C:\android-sdk\platform-tools\adb.exe” devices
info: [debug] 0 device(s) connected
info: [debug] 0 emulator(s) connected
info: [debug] Trying to find Test emulator
info: [debug] Getting connected emulators
info: [debug] Getting connected devices…
info: [debug] executing: “C:\android-sdk\platform-tools\adb.exe” devices
info: [debug] 0 device(s) connected
info: [debug] 0 emulator(s) connected
info: [debug] Trying to find Test emulator
info: [debug] Getting connected emulators
info: [debug] Getting connected devices…
info: [debug] executing: “C:\android-sdk\platform-tools\adb.exe” devices
info: [debug] 0 device(s) connected
info: [debug] 0 emulator(s) connected
info: [debug] Trying to find Test emulator
info: [debug] Getting connected emulators
info: [debug] Getting connected devices…
info: [debug] executing: “C:\android-sdk\platform-tools\adb.exe” devices
info: [debug] 0 device(s) connected
info: [debug] 0 emulator(s) connected
info: [debug] Trying to find Test emulator
info: [debug] Getting connected emulators
info: [debug] Getting connected devices…
info: [debug] executing: “C:\android-sdk\platform-tools\adb.exe” devices
info: [debug] 0 device(s) connected
info: [debug] 0 emulator(s) connected
info: [debug] Trying to find Test emulator
info: [debug] Getting connected emulators
info: [debug] Getting connected devices…
info: [debug] executing: “C:\android-sdk\platform-tools\adb.exe” devices
info: [debug] 0 device(s) connected
info: [debug] 0 emulator(s) connected
info: [debug] Trying to find Test emulator
info: [debug] Getting connected emulators
info: [debug] Getting connected devices…
info: [debug] executing: “C:\android-sdk\platform-tools\adb.exe” devices
info: [debug] 0 device(s) connected
info: [debug] 0 emulator(s) connected
info: [debug] Trying to find Test emulator
info: [debug] Getting connected emulators
info: [debug] Getting connected devices…
info: [debug] executing: “C:\android-sdk\platform-tools\adb.exe” devices
info: [debug] 0 device(s) connected
info: [debug] 0 emulator(s) connected
info: [debug] Trying to find Test emulator
info: [debug] Getting connected emulators
info: [debug] Getting connected devices…
info: [debug] executing: “C:\android-sdk\platform-tools\adb.exe” devices
info: [debug] 0 device(s) connected
info: [debug] 0 emulator(s) connected
info: [debug] Trying to find Test emulator
info: [debug] Getting connected emulators
info: [debug] Getting connected devices…
info: [debug] executing: “C:\android-sdk\platform-tools\adb.exe” devices
info: [debug] 0 device(s) connected
info: [debug] 0 emulator(s) connected
info: [debug] Trying to find Test emulator
info: [debug] Getting connected emulators
info: [debug] Getting connected devices…
info: [debug] executing: “C:\android-sdk\platform-tools\adb.exe” devices
info: [debug] 0 device(s) connected
info: [debug] 0 emulator(s) connected
info: [debug] Trying to find Test emulator
info: [debug] Getting connected emulators
info: [debug] Getting connected devices…
info: [debug] executing: “C:\android-sdk\platform-tools\adb.exe” devices
info: [debug] 0 device(s) connected
info: [debug] 0 emulator(s) connected
info: [debug] Trying to find Test emulator
info: [debug] Getting connected emulators
info: [debug] Getting connected devices…
info: [debug] executing: “C:\android-sdk\platform-tools\adb.exe” devices
info: [debug] 0 device(s) connected
info: [debug] 0 emulator(s) connected
info: [debug] Trying to find Test emulator
info: [debug] Getting connected emulators
info: [debug] Getting connected devices…
info: [debug] executing: “C:\android-sdk\platform-tools\adb.exe” devices
info: [debug] 0 device(s) connected
info: [debug] 0 emulator(s) connected
info: [debug] Trying to find Test emulator
info: [debug] Getting connected emulators
info: [debug] Getting connected devices…
info: [debug] executing: “C:\android-sdk\platform-tools\adb.exe” devices
info: [debug] 1 device(s) connected
info: [debug] 1 emulator(s) connected
info: [debug] Sending telnet command to device: avd name
info: [debug] Getting running emulator port
info: [debug] Socket connection to device created
info: [debug] Socket connection to device ready
info: [debug] Telnet command got response: Test
info: [debug] Found emulator Test in port 5554
info: [debug] Setting device id to emulator-5554
info: [debug] Waiting until emulator is ready
info: [debug] executing: “C:\android-sdk\platform-tools\adb.exe” -s emulator-5554 shell “getprop init.svc.bootanim”
info: [debug] executing: “C:\android-sdk\platform-tools\adb.exe” -s emulator-5554 shell “getprop init.svc.bootanim”
info: [debug] executing: “C:\android-sdk\platform-tools\adb.exe” -s emulator-5554 shell “getprop init.svc.bootanim”
info: [debug] executing: “C:\android-sdk\platform-tools\adb.exe” -s emulator-5554 shell “getprop init.svc.bootanim”
info: [debug] executing: “C:\android-sdk\platform-tools\adb.exe” -s emulator-5554 shell “getprop init.svc.bootanim”
info: [debug] executing: “C:\android-sdk\platform-tools\adb.exe” -s emulator-5554 shell “getprop init.svc.bootanim”
info: [debug] executing: “C:\android-sdk\platform-tools\adb.exe” -s emulator-5554 shell “getprop init.svc.bootanim”
info: [debug] executing: “C:\android-sdk\platform-tools\adb.exe” -s emulator-5554 shell “getprop init.svc.bootanim”
info: [debug] executing: “C:\android-sdk\platform-tools\adb.exe” -s emulator-5554 shell “getprop init.svc.bootanim”
info: [debug] executing: “C:\android-sdk\platform-tools\adb.exe” -s emulator-5554 shell “getprop init.svc.bootanim”
info: [debug] executing: “C:\android-sdk\platform-tools\adb.exe” -s emulator-5554 shell “getprop init.svc.bootanim”
info: [debug] executing: “C:\android-sdk\platform-tools\adb.exe” -s emulator-5554 shell “getprop init.svc.bootanim”
info: [debug] executing: “C:\android-sdk\platform-tools\adb.exe” -s emulator-5554 shell “getprop init.svc.bootanim”
info: [debug] executing: “C:\android-sdk\platform-tools\adb.exe” -s emulator-5554 shell “getprop init.svc.bootanim”
info: [debug] executing: “C:\android-sdk\platform-tools\adb.exe” -s emulator-5554 shell “getprop init.svc.bootanim”
info: [debug] executing: “C:\android-sdk\platform-tools\adb.exe” -s emulator-5554 shell “getprop init.svc.bootanim”
info: [debug] executing: “C:\android-sdk\platform-tools\adb.exe” -s emulator-5554 shell “getprop init.svc.bootanim”
info: [debug] executing: “C:\android-sdk\platform-tools\adb.exe” -s emulator-5554 shell “getprop init.svc.bootanim”
info: [debug] Waiting for device to be ready and to respond to shell commands (timeout = 300)
info: [debug] executing: “C:\android-sdk\platform-tools\adb.exe” -s emulator-5554 wait-for-device
info: [debug] executing: “C:\android-sdk\platform-tools\adb.exe” -s emulator-5554 shell “echo ‘ready’”
info: [debug] Starting logcat capture
info: [debug] Getting device API level
info: [debug] executing: “C:\android-sdk\platform-tools\adb.exe” -s emulator-5554 shell “getprop ro.build.version.sdk”
info: [debug] Device is at API Level 17
info: Device API level is: 17
info: [debug] executing: “C:\android-sdk\platform-tools\adb.exe” -s emulator-5554 shell “getprop persist.sys.language”
info: [debug] Current device language: en
info: [debug] java -jar “C:\Appium\node_modules\appium\node_modules\appium-adb\jars\appium_apk_tools.jar” “stringsFromApk” “C:\Users\502263844\Documents\Laundry_v5.1.0.12e.apk” “C:\Windows\Temp\com.ge.laundryhome” en
info: [debug] No strings.xml for language ‘en’, getting default strings.xml
info: [debug] java -jar “C:\Appium\node_modules\appium\node_modules\appium-adb\jars\appium_apk_tools.jar” “stringsFromApk” “C:\Users\502263844\Documents\Laundry_v5.1.0.12e.apk” “C:\Windows\Temp\com.ge.laundryhome”
info: [debug] executing: “C:\android-sdk\platform-tools\adb.exe” -s emulator-5554 push “C:\Windows\Temp\com.ge.laundryhome\strings.json” /data/local/tmp
info: [debug] Checking whether aapt is present
info: [debug] Using aapt from C:\android-sdk\build-tools\21.1.1\aapt.exe
info: [debug] processFromManifest: “C:\android-sdk\build-tools\21.1.1\aapt.exe” dump xmltree “C:\Users\502263844\Documents\Laundry_v5.1.0.12e.apk” AndroidManifest.xml
info: [debug] Set app process to: com.ge.laundryhome
info: [debug] Not uninstalling app since server not started with --full-reset
info: [debug] Checking app cert for C:\Users\502263844\Documents\Laundry_v5.1.0.12e.apk: java -jar “C:\Appium\node_modules\appium\node_modules\appium-adb\jars\verify.jar” “C:\Users\502263844\Documents\Laundry_v5.1.0.12e.apk”
info: [debug] App already signed.
info: [debug] Zip-aligning C:\Users\502263844\Documents\Laundry_v5.1.0.12e.apk
info: [debug] Checking whether zipalign is present
info: [debug] Using zipalign from C:\android-sdk\build-tools\21.1.1\zipalign.exe
info: [debug] zipAlignApk: “C:\android-sdk\build-tools\21.1.1\zipalign.exe” -f 4 “C:\Users\502263844\Documents\Laundry_v5.1.0.12e.apk” “C:\Users\502263~1\AppData\Local\Temp\appium114118-8088-9kqpbh.tmp”
info: [debug] MD5 for app is ffb1cbc5c7dca02b31684203550ae8b1
info: [debug] executing: “C:\android-sdk\platform-tools\adb.exe” -s emulator-5554 shell “ls /data/local/tmp/ffb1cbc5c7dca02b31684203550ae8b1.apk”
info: [debug] Getting install status for com.ge.laundryhome
info: [debug] Getting device API level
info: [debug] executing: “C:\android-sdk\platform-tools\adb.exe” -s emulator-5554 shell “getprop ro.build.version.sdk”
info: [debug] Device is at API Level 17
info: [debug] executing: “C:\android-sdk\platform-tools\adb.exe” -s emulator-5554 shell “pm list packages -3 com.ge.laundryhome”
info: [debug] App is installed
info: App is already installed, resetting app
info: [debug] Running fast reset (stop and clear)
info: [debug] executing: “C:\android-sdk\platform-tools\adb.exe” -s emulator-5554 shell “am force-stop com.ge.laundryhome”
info: [debug] executing: “C:\android-sdk\platform-tools\adb.exe” -s emulator-5554 shell “pm clear com.ge.laundryhome”
info: [debug] Forwarding system:4724 to device:4724
info: [debug] executing: “C:\android-sdk\platform-tools\adb.exe” -s emulator-5554 forward tcp:4724 tcp:4724
info: [debug] Pushing appium bootstrap to device…
info: [debug] executing: “C:\android-sdk\platform-tools\adb.exe” -s emulator-5554 push “C:\Appium\node_modules\appium\build\android_bootstrap\AppiumBootstrap.jar” /data/local/tmp/
info: [debug] Pushing settings apk to device…
info: [debug] executing: “C:\android-sdk\platform-tools\adb.exe” -s emulator-5554 install “C:\Appium\node_modules\appium\build\settings_apk\settings_apk-debug.apk”
info: [debug] Pushing unlock helper app to device…
info: [debug] executing: “C:\android-sdk\platform-tools\adb.exe” -s emulator-5554 install “C:\Appium\node_modules\appium\build\unlock_apk\unlock_apk-debug.apk”
info: Starting App
info: [debug] Attempting to kill all ‘uiautomator’ processes
info: [debug] Getting all processes with ‘uiautomator’
info: [debug] executing: “C:\android-sdk\platform-tools\adb.exe” -s emulator-5554 shell “ps ‘uiautomator’”
info: [debug] No matching processes found
info: [debug] Running bootstrap
info: [debug] spawning: C:\android-sdk\platform-tools\adb.exe -s emulator-5554 shell uiautomator runtest AppiumBootstrap.jar -c io.appium.android.bootstrap.Bootstrap
info: [debug] e[90m[UIAUTOMATOR STDOUT] INSTRUMENTATION_STATUS: current=1e[39m
info: [debug] e[90m[UIAUTOMATOR STDOUT] INSTRUMENTATION_STATUS: id=UiAutomatorTestRunnere[39m
info: [debug] e[90m[UIAUTOMATOR STDOUT] INSTRUMENTATION_STATUS: class=io.appium.android.bootstrap.Bootstrape[39m
info: [debug] e[90m[UIAUTOMATOR STDOUT] INSTRUMENTATION_STATUS: stream=e[39m
info: [debug] e[90m[UIAUTOMATOR STDOUT] io.appium.android.bootstrap.Bootstrap:e[39m
info: [debug] e[90m[UIAUTOMATOR STDOUT] INSTRUMENTATION_STATUS: numtests=1e[39m
info: [debug] e[90m[UIAUTOMATOR STDOUT] INSTRUMENTATION_STATUS: test=testRunServere[39m
info: [debug] e[90m[UIAUTOMATOR STDOUT] INSTRUMENTATION_STATUS_CODE: 1e[39m
info: [debug] [BOOTSTRAP] [debug] Socket opened on port 4724
info: [debug] [BOOTSTRAP] [debug] Appium Socket Server Ready
info: [debug] Waking up device if it’s not alive
info: [debug] Pushing command to appium work queue: [“wake”,{}]
info: [debug] [BOOTSTRAP] [debug] Loading json…
info: [debug] [BOOTSTRAP] [debug] json loading complete.
info: [debug] [BOOTSTRAP] [debug] Registered crash watchers.
info: [debug] [BOOTSTRAP] [debug] Client connected
info: [debug] [BOOTSTRAP] [debug] Got data from client: {“cmd”:“action”,“action”:“wake”,“params”:{}}
info: [debug] [BOOTSTRAP] [debug] Got command of type ACTION
info: [debug] [BOOTSTRAP] [debug] Got command action: wake
info: [debug] [BOOTSTRAP] [debug] Returning result: {“value”:true,“status”:0}
info: [debug] executing: “C:\android-sdk\platform-tools\adb.exe” -s emulator-5554 shell “dumpsys window”
info: [debug] Writing dumpsys output to C:\Appium\node_modules\appium.dumpsys.log
info: Unlocking screen
info: [debug] Screen is locked, trying to unlock
info: [debug] Getting device API level
info: [debug] executing: “C:\android-sdk\platform-tools\adb.exe” -s emulator-5554 shell “getprop ro.build.version.sdk”
info: [debug] Device is at API Level 17
info: [debug] executing: “C:\android-sdk\platform-tools\adb.exe” -s emulator-5554 shell “am start -S -a android.intent.action.MAIN -c android.intent.category.LAUNCHER -f 0x10200000 -n io.appium.unlock/.Unlock”
info: [debug] executing: “C:\android-sdk\platform-tools\adb.exe” -s emulator-5554 shell “dumpsys window”
info: [debug] Writing dumpsys output to C:\Appium\node_modules\appium.dumpsys.log
info: [debug] Screen is locked, trying to unlock
info: [debug] Getting device API level
info: [debug] executing: “C:\android-sdk\platform-tools\adb.exe” -s emulator-5554 shell “getprop ro.build.version.sdk”
info: [debug] Device is at API Level 17
info: [debug] executing: “C:\android-sdk\platform-tools\adb.exe” -s emulator-5554 shell “am start -S -a android.intent.action.MAIN -c android.intent.category.LAUNCHER -f 0x10200000 -n io.appium.unlock/.Unlock”
info: [debug] executing: “C:\android-sdk\platform-tools\adb.exe” -s emulator-5554 shell “dumpsys window”
info: [debug] Writing dumpsys output to C:\Appium\node_modules\appium.dumpsys.log
info: [debug] Screen is unlocked, continuing
info: [debug] Pushing command to appium work queue: [“getDataDir”,{}]
info: [debug] [BOOTSTRAP] [debug] Got data from client: {“cmd”:“action”,“action”:“getDataDir”,“params”:{}}
info: [debug] [BOOTSTRAP] [debug] Got command of type ACTION
info: [debug] [BOOTSTRAP] [debug] Got command action: getDataDir
info: [debug] [BOOTSTRAP] [debug] Returning result: {“value”:“/data”,“status”:0}
info: [debug] dataDir set to: /data
info: [debug] Pushing command to appium work queue: [“compressedLayoutHierarchy”,{“compressLayout”:false}]
info: [debug] [BOOTSTRAP] [debug] Got data from client: {“cmd”:“action”,“action”:“compressedLayoutHierarchy”,“params”:{“compressLayout”:false}}
info: [debug] [BOOTSTRAP] [debug] Got command of type ACTION
info: [debug] [BOOTSTRAP] [debug] Got command action: compressedLayoutHierarchy
info: [debug] [BOOTSTRAP] [debug] Returning result: {“value”:false,“status”:0}
info: [debug] Getting device API level
info: [debug] executing: “C:\android-sdk\platform-tools\adb.exe” -s emulator-5554 shell “getprop ro.build.version.sdk”
info: [debug] Device is at API Level 17
info: [debug] executing: “C:\android-sdk\platform-tools\adb.exe” -s emulator-5554 shell “am start -S -a android.intent.action.MAIN -c android.intent.category.LAUNCHER -f 0x10200000 -n com.ge.laundryhome/com.ge.laundryhome.LaundryHomeActivity”
info: [debug] Waiting for pkg “com.ge.laundryhome” and activity “com.ge.laundryhome.LaundryHomeActivity” to be focused
info: [debug] Getting focused package and activity
info: [debug] executing: “C:\android-sdk\platform-tools\adb.exe” -s emulator-5554 shell “dumpsys window windows”
info: [debug] Getting focused package and activity
info: [debug] executing: “C:\android-sdk\platform-tools\adb.exe” -s emulator-5554 shell “dumpsys window windows”
info: [debug] Getting focused package and activity
info: [debug] executing: “C:\android-sdk\platform-tools\adb.exe” -s emulator-5554 shell “dumpsys window windows”
info: [debug] Device launched! Ready for commands
info: [debug] Setting command timeout to the default of 60 secs
info: [debug] Appium session started with sessionId 2609153d-9dba-48ae-bdbb-612562a1b5db
info: e[37m<-- POST /wd/hub/session e[39me[36m303e[39me[90m 127696.835 ms - 9e[39m e[90me[39m
info: e[37m–>e[39m e[37mGETe[39m e[37m/wd/hub/session/2609153d-9dba-48ae-bdbb-612562a1b5dbe[39m e[90m{}e[39m
info: [debug] Responding to client with success: {“status”:0,“value”:{“platform”:“LINUX”,“browserName”:“Android”,“platformVersion”:“4.1”,“webStorageEnabled”:false,“takesScreenshot”:true,“javascriptEnabled”:true,“databaseEnabled”:false,“networkConnectionEnabled”:true,“locationContextEnabled”:false,“warnings”:{},“desired”:{“app”:“C:\Users\502263844\Documents\Laundry_v5.1.0.12e.apk”,“appPackage”:“com.ge.laundryhome”,“appActivity”:“com.ge.laundryhome.LaundryHomeActivity”,“automationName”:“Appium”,“platformName”:“Android”,“deviceName”:“Nexus7”,“version”:“4.2”},“app”:“C:\Users\502263844\Documents\Laundry_v5.1.0.12e.apk”,“appPackage”:“com.ge.laundryhome”,“appActivity”:“com.ge.laundryhome.LaundryHomeActivity”,“automationName”:“Appium”,“platformName”:“Android”,“deviceName”:“Nexus7”,“version”:“4.2”},“sessionId”:“2609153d-9dba-48ae-bdbb-612562a1b5db”}
info: e[37m<-- GET /wd/hub/session/2609153d-9dba-48ae-bdbb-612562a1b5db e[39me[32m200e[39me[90m 0.680 ms - 811e[39m e[90m{“status”:0,“value”:{“platform”:“LINUX”,“browserName”:“Android”,“platformVersion”:“4.1”,“webStorageEnabled”:false,“takesScreenshot”:true,“javascriptEnabled”:true,“databaseEnabled”:false,“networkConnectionEnabled”:true,“locationContextEnabled”:false,“warnings”:{},“desired”:{“app”:“C:\Users\502263844\Documents\Laundry_v5.1.0.12e.apk”,“appPackage”:“com.ge.laundryhome”,“appActivity”:“com.ge.laundryhome.LaundryHomeActivity”,“automationName”:“Appium”,“platformName”:“Android”,“deviceName”:“Nexus7”,“version”:“4.2”},“app”:“C:\Users\502263844\Documents\Laundry_v5.1.0.12e.apk”,“appPackage”:“com.ge.laundryhome”,“appActivity”:“com.ge.laundryhome.LaundryHomeActivity”,“automationName”:“Appium”,“platformName”:“Android”,“deviceName”:“Nexus7”,“version”:“4.2”},“sessionId”:“2609153d-9dba-48ae-bdbb-612562a1b5db”}e[39m
info: e[37m–>e[39m e[37mGETe[39m e[37m/wd/hub/session/2609153d-9dba-48ae-bdbb-612562a1b5db/sourcee[39m e[90m{}e[39m
info: [debug] Pushing command to appium work queue: [“source”,{}]
info: [debug] [BOOTSTRAP] [debug] Got data from client: {“cmd”:“action”,“action”:“source”,“params”:{}}
info: [debug] [BOOTSTRAP] [debug] Got command of type ACTION
info: [debug] [BOOTSTRAP] [debug] Got command action: source
info: [debug] e[90m[UIAUTOMATOR STDOUT] java.io.FileNotFoundException: /data/local/tmp/dump.xml: open failed: ENOENT (No such file or directory)e[39m
info: [debug] e[90m[UIAUTOMATOR STDOUT] ate[39m
info: [debug] e[90m[UIAUTOMATOR STDOUT] libcore.io.IoBridge.open(IoBridge.java:416)e[39m
info: [debug] e[90m[UIAUTOMATOR STDOUT] ate[39m
info: [debug] e[90m[UIAUTOMATOR STDOUT] java.io.FileInputStream.(FileInputStream.java:78)e[39m
info: [debug] e[90m[UIAUTOMATOR STDOUT] at java.io.FileReader.(FileReader.java:42)e[39m
info: [debug] e[90m[UIAUTOMATOR STDOUT] ate[39m
info: [debug] e[90m[UIAUTOMATOR STDOUT] io.appium.android.bootstrap.utils.XMLHierarchy.getRawXMLHierarchy(XMLHierarchy.java:88)e[39m
info: [debug] e[90m[UIAUTOMATOR STDOUT] ate[39m
info: [debug] e[90m[UIAUTOMATOR STDOUT] io.appium.android.bootstrap.utils.XMLHierarchy.getFormattedXMLDoc(XMLHierarchy.java:96)e[39m
info: [debug] e[90m[UIAUTOMATOR STDOUT] at io.appium.android.bootstrap.handler.Source.execute(Source.java:25)e[39m
info: [debug] e[90m[UIAUTOMATOR STDOUT] at io.appium.android.bootstrap.AndroidCommandExecutor.execute(AndroidCommandExecutor.java:64)e[39m
info: [debug] e[90m[UIAUTOMATOR STDOUT] at io.appium.android.bootstrap.SocketServer.runCommand(SocketServer.java:167)e[39m
info: [debug] e[90m[UIAUTOMATOR STDOUT] ate[39m
info: [debug] e[90m[UIAUTOMATOR STDOUT] io.appium.android.bootstrap.SocketServer.handleClientData(SocketServer.java:88)e[39m
info: [debug] e[90m[UIAUTOMATOR STDOUT] ate[39m
info: [debug] e[90m[UIAUTOMATOR STDOUT] io.appium.android.bootstrap.SocketServer.listenForever(SocketServer.java:132)e[39m
info: [debug] e[90m[UIAUTOMATOR STDOUT] ate[39m
info: [debug] e[90m[UIAUTOMATOR STDOUT] io.appium.android.bootstrap.Bootstrap.testRunServer(Bootstrap.java:16)e[39m
info: [debug] e[90m[UIAUTOMATOR STDOUT] ate[39m
info: [debug] e[90m[UIAUTOMATOR STDOUT] java.lang.reflect.Method.invokeNative(Native Method)e[39m
info: [debug] e[90m[UIAUTOMATOR STDOUT] ate[39m
info: [debug] e[90m[UIAUTOMATOR STDOUT] java.lang.reflect.Method.invoke(Method.java:511)e[39m
info: [debug] e[90m[UIAUTOMATOR STDOUT] ate[39m
info: [debug] e[90m[UIAUTOMATOR STDOUT] junit.framework.TestCase.runTest(TestCase.java:168)e[39m
info: [debug] e[90m[UIAUTOMATOR STDOUT] ate[39m
info: [debug] e[90m[UIAUTOMATOR STDOUT] junit.framework.TestCase.runBare(TestCase.java:134)e[39m
info: [debug] e[90m[UIAUTOMATOR STDOUT] ate[39m
info: [debug] e[90m[UIAUTOMATOR STDOUT] junit.framework.TestResult$1.protect(TestResult.java:115)e[39m
info: [debug] e[90m[UIAUTOMATOR STDOUT] ate[39m
info: [debug] e[90m[UIAUTOMATOR STDOUT] junit.framework.TestResult.runProtected(TestResult.java:133)e[39m
info: [debug] e[90m[UIAUTOMATOR STDOUT] ate[39m
info: [debug] e[90m[UIAUTOMATOR STDOUT] junit.framework.TestResult.run(TestResult.java:118)e[39m
info: [debug] e[90m[UIAUTOMATOR STDOUT] ate[39m
info: [debug] e[90m[UIAUTOMATOR STDOUT] junit.framework.TestCase.run(TestCase.java:124)e[39m
info: [debug] e[90m[UIAUTOMATOR STDOUT] ate[39m
info: [debug] e[90m[UIAUTOMATOR STDOUT] com.android.uiautomator.testrunner.UiAutomatorTestRunner.start(UiAutomatorTestRunner.java:144)e[39m
info: [debug] e[90m[UIAUTOMATOR STDOUT] ate[39m
info: [debug] e[90m[UIAUTOMATOR STDOUT] com.android.uiautomator.testrunner.UiAutomatorTestRunner.run(UiAutomatorTestRunner.java:87)e[39m
info: [debug] e[90m[UIAUTOMATOR STDOUT] at com.android.commands.uiautomator.RunTestCommand.run(RunTestCommand.java:90)e[39m
info: [debug] e[90m[UIAUTOMATOR STDOUT] ate[39m
info: [debug] e[90m[UIAUTOMATOR STDOUT] com.android.commands.uiautomator.Launcher.main(Launcher.java:83)e[39m
info: [debug] e[90m[UIAUTOMATOR STDOUT] ate[39m
info: [debug] e[90m[UIAUTOMATOR STDOUT] com.android.internal.os.RuntimeInit.nativeFinishInit(Native Method)e[39m
info: [debug] e[90m[UIAUTOMATOR STDOUT] ate[39m
info: [debug] e[90m[UIAUTOMATOR STDOUT] com.android.internal.os.RuntimeInit.main(RuntimeInit.java:235)e[39m
info: [debug] e[90m[UIAUTOMATOR STDOUT] ate[39m
info: [debug] e[90m[UIAUTOMATOR STDOUT] dalvik.system.NativeStart.main(Native Method)e[39m
info: [debug] e[90m[UIAUTOMATOR STDOUT] Caused by:e[39m
info: [debug] e[90m[UIAUTOMATOR STDOUT] libcore.io.ErrnoException: open failed: ENOENT (No such file or directory)e[39m
info: [debug] e[90m[UIAUTOMATOR STDOUT] ate[39m
info: [debug] e[90m[UIAUTOMATOR STDOUT] libcore.io.Posix.open(Native Method)e[39m
info: [debug] e[90m[UIAUTOMATOR STDOUT] ate[39m
info: [debug] e[90m[UIAUTOMATOR STDOUT] libcore.io.BlockGuardOs.open(BlockGuardOs.java:110)e[39m
info: [debug] e[90m[UIAUTOMATOR STDOUT] ate[39m
info: [debug] e[90m[UIAUTOMATOR STDOUT] libcore.io.IoBridge.open(IoBridge.java:400)e[39m
info: [debug] e[90m[UIAUTOMATOR STDOUT] …e[39m
info: [debug] e[90m[UIAUTOMATOR STDOUT] 25e[39m
info: [debug] e[90m[UIAUTOMATOR STDOUT] moree[39m
info: [debug] [BOOTSTRAP] [debug] Returning result: {“value”:“Failed to Dump Window Hierarchy”,“status”:13}
info: [debug] Responding to client with error: {“status”:13,“value”:{“message”:“An unknown server-side error occurred while processing the command.”,“origValue”:“Failed to Dump Window Hierarchy”},“sessionId”:“2609153d-9dba-48ae-bdbb-612562a1b5db”}
info: e[37m<-- GET /wd/hub/session/2609153d-9dba-48ae-bdbb-612562a1b5db/source e[39me[31m500e[39me[90m 402.035 ms - 200e[39m e[90me[39m
info: [debug] Didn’t get a new command in 60 secs, shutting down…
info: Shutting down appium session
info: [debug] Pressing the HOME button
info: [debug] executing: “C:\android-sdk\platform-tools\adb.exe” -s emulator-5554 shell “input keyevent 3”
info: [debug] Stopping logcat capture
info: [debug] Logcat terminated with code null, signal SIGTERM
info: [debug] [BOOTSTRAP] [debug] Got data from client: {“cmd”:“shutdown”}
info: [debug] [BOOTSTRAP] [debug] Got command of type SHUTDOWN
info: [debug] [BOOTSTRAP] [debug] Returning result: {“value”:“OK, shutting down”,“status”:0}
info: [debug] [BOOTSTRAP] [debug] Closed client connection
info: [debug] e[90m[UIAUTOMATOR STDOUT] INSTRUMENTATION_STATUS: current=1e[39m
info: [debug] e[90m[UIAUTOMATOR STDOUT] INSTRUMENTATION_STATUS: id=UiAutomatorTestRunnere[39m
info: [debug] e[90m[UIAUTOMATOR STDOUT] INSTRUMENTATION_STATUS: class=io.appium.android.bootstrap.Bootstrape[39m
info: [debug] e[90m[UIAUTOMATOR STDOUT] INSTRUMENTATION_STATUS: stream=.e[39m
info: [debug] e[90m[UIAUTOMATOR STDOUT] INSTRUMENTATION_STATUS: numtests=1e[39m
info: [debug] e[90m[UIAUTOMATOR STDOUT] INSTRUMENTATION_STATUS: test=testRunServere[39m
info: [debug] e[90m[UIAUTOMATOR STDOUT] INSTRUMENTATION_STATUS_CODE: 0e[39m
info: [debug] Sent shutdown command, waiting for UiAutomator to stop…
info: [debug] e[90m[UIAUTOMATOR STDOUT] INSTRUMENTATION_STATUS: stream=e[39m
info: [debug] e[90m[UIAUTOMATOR STDOUT] Test results for WatcherResultPrinter=.e[39m
info: [debug] e[90m[UIAUTOMATOR STDOUT] Time: 83.599e[39m
info: [debug] e[90m[UIAUTOMATOR STDOUT] OK (1 test)e[39m
info: [debug] e[90m[UIAUTOMATOR STDOUT] INSTRUMENTATION_STATUS_CODE: -1e[39m
info: [debug] UiAutomator shut down normally
info: [debug] Cleaning up android objects
info: [debug] Cleaning up appium session
info: [debug] We shut down because no new commands came in

It was a shot in the dark, but increasing the sleep to 1000 got it to working.

Are you sure its the same setup as with 1.2.4. Probably an different device, android version? I am actually experiencing the same issue when appium is trying do dump the ui hierarchy for the first time. I fixed it by adding retries in the getRawXMLHierarchy method. I am actually preparing a pull request for it. But i am not sure if it is the right way to fix it. Find the issue i created here: Failed to Dump Window Hierarchy · Issue #4227 · appium/appium · GitHub

Positive about the version. I’m now upgrading to the 1.3.4.1 version again.