The server is running Start New SessionStop Server [Appium] Welcome to Appium v1.6.5 [Appium] Appium REST http interface listener started on 0.0.0.0:4723 [HTTP] --> POST /wd/hub/session {"desiredCapabilities":{"browserName":"","version":"","platform":"ANY","javascriptEnabled":true,"cssSelectorsEnabled":true,"takesScreenshot":true,"nativeEvents":false,"rotatable":true,"platformName":"Android","platformVersion":"4.1","automationName":"Selendroid","deviceName":"Android 4.1 Emulator","app":"/Users/mario/Ruby/one off scripts/take screenshots for android/app-debug.apk","avd":"Nexus_5X_API_16","avdArgs":"-no-boot-anim","newCommandTimeout":30,"orientation":"PORTRAIT"}} [MJSONWP] Calling AppiumDriver.createSession() with args: [{"browserName":"","version":"","platform":"ANY","javascriptEnabled":true,"cssSelectorsEnabled":true,"takesScreenshot":true,"nativeEvents":false,"rotatable":true,"platformName":"Android","platformVersion":"4.1","automationName":"Selendroid","deviceName":"Android 4.1 Emulator","app":"/Users/mario/Ruby/one off scripts/take screenshots for android/app-debug.apk","avd":"Nexus_5X_API_16","avdArgs":"-no-boot-anim","newCommandTimeout":30,"orientation":"PORTRAIT"},null,null] [BaseDriver] Event 'newSessionRequested' logged at 1500449006532 (00:23:26 GMT-0700 (PDT)) [Appium] Creating new SelendroidDriver (v1.6.2) session [Appium] Capabilities: [Appium] browserName: '' [Appium] version: '' [Appium] platform: 'ANY' [Appium] javascriptEnabled: true [Appium] cssSelectorsEnabled: true [Appium] takesScreenshot: true [Appium] nativeEvents: false [Appium] rotatable: true [Appium] platformName: 'Android' [Appium] platformVersion: '4.1' [Appium] automationName: 'Selendroid' [Appium] deviceName: 'Android 4.1 Emulator' [Appium] app: '/Users/mario/Ruby/one off scripts/take screenshots for android/app-debug.apk' [Appium] avd: 'Nexus_5X_API_16' [Appium] avdArgs: '-no-boot-anim' [Appium] newCommandTimeout: 30 [Appium] orientation: 'PORTRAIT' [BaseDriver] The following capabilities were provided, but are not recognized by appium: version, platform, javascriptEnabled, cssSelectorsEnabled, takesScreenshot, nativeEvents, rotatable. [BaseDriver] Session created with session id: a0d27eba-ddcb-4265-b364-958a9b85ab2c [BaseDriver] Using local app '/Users/mario/Ruby/one off scripts/take screenshots for android/app-debug.apk' [Selendroid] Checking whether app is actually present [AndroidDriver] Getting Java version [AndroidDriver] Java version is: 1.8.0_40 [ADB] Checking whether adb is present [ADB] Using adb from /Users/mario/Library/Android/sdk/platform-tools/adb [ADB] Trying to find Nexus_5X_API_16 emulator [ADB] Getting connected emulators [ADB] Getting connected devices... [ADB] 1 device(s) connected [ADB] 1 emulator(s) connected [ADB] Sending telnet command to device: avd name [ADB] Getting running emulator port [ADB] Socket connection to device created [ADB] Socket connection to device ready [ADB] Telnet command got response: Nexus_5X_API_16 [ADB] Found emulator Nexus_5X_API_16 in port 5554 [ADB] Setting device id to emulator-5554 [AndroidDriver] Not launching AVD because it is already running. [AndroidDriver] Using device: emulator-5554 [ADB] Checking whether adb is present [ADB] Using adb from /Users/mario/Library/Android/sdk/platform-tools/adb [ADB] Setting device id to emulator-5554 [ADB] Checking whether aapt is present [ADB] Using aapt from /Users/mario/Library/Android/sdk/build-tools/25.0.2/aapt [ADB] Checking if has internet permission from manifest [AndroidDriver] Parsing package and activity from app manifest [ADB] Checking whether aapt is present [ADB] Using aapt from /Users/mario/Library/Android/sdk/build-tools/25.0.2/aapt [ADB] Extracting package and launch activity from manifest [ADB] badging package: com.company.testapp.demo [ADB] badging act: com.company.testapp.demo.MainActivity [AndroidDriver] Parsed package and activity are: com.company.testapp.demo/com.company.testapp.demo.MainActivity [ADB] Checking app cert for /var/folders/gw/g089b35s6d78n26n2xv12f91zjw115/T/selendroid.com.company.testapp.demo.apk. [ADB] App already signed. [ADB] Zip-aligning '/var/folders/gw/g089b35s6d78n26n2xv12f91zjw115/T/selendroid.com.company.testapp.demo.apk' [ADB] Checking whether zipalign is present [ADB] Using zipalign from /Users/mario/Library/Android/sdk/build-tools/25.0.2/zipalign [ADB] Zip-aligning apk. [ADB] Running '/Users/mario/Library/Android/sdk/platform-tools/adb' with args: ["-P",5037,"-s","emulator-5554","wait-for-device"] [ADB] Getting connected devices... [ADB] 1 device(s) connected [ADB] Running '/Users/mario/Library/Android/sdk/platform-tools/adb' with args: ["-P",5037,"-s","emulator-5554","shell","echo","ping"] [Logcat] Starting logcat capture [AndroidDriver] Pushing unlock helper app to device... [ADB] Running '/Users/mario/Library/Android/sdk/platform-tools/adb' with args: ["-P",5037,"-s","emulator-5554","install","/private/var/folders/gw/g089b35s6d78n26n2xv12f91zjw115/T/AppTranslocation/824188FE-2DE0-46A5-A06D-B2A0DC47E091/d/Appium.app/Contents/Resources/app/node_modules/appium/node_modules/appium-unlock/bin/unlock_apk-debug.apk"] [ADB] Getting connected devices... [ADB] 1 device(s) connected [ADB] Running '/Users/mario/Library/Android/sdk/platform-tools/adb' with args: ["-P",5037,"-s","emulator-5554","shell","getprop","ro.build.version.sdk"] [ADB] Device API level: 16 [ADB] Getting connected devices... [ADB] 1 device(s) connected [ADB] Running '/Users/mario/Library/Android/sdk/platform-tools/adb' with args: ["-P",5037,"-s","emulator-5554","shell","settings","put","secure","mock_location","1"] [ADB] Forwarding system: 8080 to device: 8080 [ADB] Running '/Users/mario/Library/Android/sdk/platform-tools/adb' with args: ["-P",5037,"-s","emulator-5554","forward","tcp:8080","tcp:8080"] [Selendroid] Initializing application under test [AndroidDriver] Extracting strings from apk /Users/mario/Ruby/one off scripts/take screenshots for android/app-debug.apk undefined /var/folders/gw/g089b35s6d78n26n2xv12f91zjw115/T/com.company.testapp.demo [ADB] Extracting strings for language: default [ADB] Device API level: 16 [ADB] Getting connected devices... [ADB] 1 device(s) connected [ADB] Running '/Users/mario/Library/Android/sdk/platform-tools/adb' with args: ["-P",5037,"-s","emulator-5554","shell","getprop","persist.sys.language"] [ADB] Current device property 'persist.sys.language': en [ADB] No strings.xml for language 'en', getting default strings.xml [ADB] Reading strings from converted strings.json [ADB] Running '/Users/mario/Library/Android/sdk/platform-tools/adb' with args: ["-P",5037,"-s","emulator-5554","push","/var/folders/gw/g089b35s6d78n26n2xv12f91zjw115/T/com.company.testapp.demo/strings.json","/data/local/tmp"] [ADB] Checking app cert for /Users/mario/Ruby/one off scripts/take screenshots for android/app-debug.apk. [ADB] App already signed. [ADB] Zip-aligning '/Users/mario/Ruby/one off scripts/take screenshots for android/app-debug.apk' [ADB] Checking whether zipalign is present [ADB] Using zipalign from /Users/mario/Library/Android/sdk/build-tools/25.0.2/zipalign [ADB] Zip-aligning apk. [AndroidDriver] Remote apk path is /data/local/tmp/09871acb9e3becb71a87edd6c6fa70d5.apk [ADB] Getting connected devices... [ADB] 1 device(s) connected [ADB] Running '/Users/mario/Library/Android/sdk/platform-tools/adb' with args: ["-P",5037,"-s","emulator-5554","shell","ls","/data/local/tmp/09871acb9e3becb71a87edd6c6fa70d5.apk"] [AndroidDriver] Checking if app is installed [ADB] Getting install status for com.company.testapp.demo [ADB] Getting connected devices... [ADB] 1 device(s) connected [ADB] Running '/Users/mario/Library/Android/sdk/platform-tools/adb' with args: ["-P",5037,"-s","emulator-5554","shell","pm","list","packages","com.company.testapp.demo"] [ADB] App is installed [AndroidDriver] Apk is already on remote and installed, resetting [AndroidDriver] Running fast reset (stop and clear) [ADB] Getting connected devices... [ADB] 1 device(s) connected [ADB] Running '/Users/mario/Library/Android/sdk/platform-tools/adb' with args: ["-P",5037,"-s","emulator-5554","shell","am","force-stop","com.company.testapp.demo"] [ADB] Getting connected devices... [ADB] 1 device(s) connected [ADB] Running '/Users/mario/Library/Android/sdk/platform-tools/adb' with args: ["-P",5037,"-s","emulator-5554","shell","pm","clear","com.company.testapp.demo"] [ADB] Getting install status for selendroid.com.company.testapp.demo [ADB] Getting connected devices... [ADB] 1 device(s) connected [ADB] Running '/Users/mario/Library/Android/sdk/platform-tools/adb' with args: ["-P",5037,"-s","emulator-5554","shell","pm","list","packages","selendroid.com.company.testapp.demo"] [ADB] App is installed [ADB] Getting connected devices... [ADB] 1 device(s) connected [ADB] Running '/Users/mario/Library/Android/sdk/platform-tools/adb' with args: ["-P",5037,"-s","emulator-5554","shell","dumpsys","window"] [AndroidDriver] Screen already unlocked, doing nothing [Selendroid] Starting selendroid server with instrumentation: selendroid.com.company.testapp.demo/io.selendroid.server.ServerInstrumentation [ADB] Getting connected devices... [ADB] 1 device(s) connected [ADB] Running '/Users/mario/Library/Android/sdk/platform-tools/adb' with args: ["-P",5037,"-s","emulator-5554","shell","am","instrument","-e","main_activity","com.company.testapp.demo.MainActivity","selendroid.com.company.testapp.demo/io.selendroid.server.ServerInstrumentation"] [Selendroid] Waiting for Selendroid to be online... [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8080/wd/hub/status] with no body [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8080/wd/hub/status] with no body [JSONWP Proxy] Got response with status 200: "{\"value\":{\"supportedApps\":[],\"build\":{\"browserName\":\"selendroid\",\"version\":\"0.17.0\"},\"os\":{\"arch\":\"x86\",\"version\":\"16\",\"name\":\"Android\"},\"supportedDevices\":[]},\"status\":0}" [JSONWP Proxy] Proxying [POST /session] to [POST http://localhost:8080/wd/hub/session] with body: {"desiredCapabilities":{"browserName":"","version":"","platform":"ANY","javascriptEnabled":true,"cssSelectorsEnabled":true,"takesScreenshot":true,"nativeEvents":false,"rotatable":true,"platformName":"Android","platformVersion":"4.1","automationName":"Selendroid","deviceName":"Android 4.1 Emulator","app":"/Users/mario/Ruby/one off scripts/take screenshots for android/app-debug.apk","avd":"Nexus_5X_API_16","avdArgs":"-no-boot-anim","newCommandTimeout":30,"orientation":"PORTRAIT"}} [JSONWP Proxy] Got response with status 200: {"value":{"avd":"Nexus_5X_API_16","automationName":"Selendroid","orientation":"PORTRAIT","app":"/Users/mario/Ruby/one off scripts/take screenshots for android/app-debug.apk","platform":"ANY","javascriptEnabled":true,"browserName":"","rotatable":true,"version":"","newCommandTimeout":30,"platformVersion":"4.1","cssSelectorsEnabled":true,"deviceName":"Android 4.1 Emulator","platformName":"Android","nativeEvents":false,"avdArgs":"-no-boot-anim","takesScreenshot":true},"status":0,"sessionId":"a3c305e7-109d-f78a-78d3-13e7d5106a38"} [ADB] Waiting for activity matching pkg: 'com.company.testapp.demo' and activity: 'com.company.testapp.demo.MainActivity' to be focused [ADB] Possible activities, to be checked: com.company.testapp.demo.MainActivity, com.company.testapp.demo.com.company.testapp.demo.MainActivity [ADB] Getting focused package and activity [ADB] Getting connected devices... [ADB] 1 device(s) connected [ADB] Running '/Users/mario/Library/Android/sdk/platform-tools/adb' with args: ["-P",5037,"-s","emulator-5554","shell","dumpsys","window","windows"] [ADB] Found package: 'com.company.testapp.demo' and fully qualified activity name : 'com.company.testapp.demo.MainActivity' [Appium] New SelendroidDriver session created successfully, session a0d27eba-ddcb-4265-b364-958a9b85ab2c added to master session list [BaseDriver] Event 'newSessionStarted' logged at 1500449011776 (00:23:31 GMT-0700 (PDT)) [MJSONWP] Responding to client with driver.createSession() result: {"browserName":"","version":"","platform":"ANY","javascriptEnabled":true,"cssSelectorsEnabled":true,"takesScreenshot":true,"nativeEvents":false,"rotatable":true,"platformName":"Android","platformVersion":"4.1","automationName":"Selendroid","deviceName":"Android 4.1 Emulator","app":"/Users/mario/Ruby/one off scripts/take screenshots for android/app-debug.apk","avd":"Nexus_5X_API_16","avdArgs":"-no-boot-anim","newCommandTimeout":30,"orientation":"PORTRAIT"} [HTTP] <-- POST /wd/hub/session 200 5246 ms - 531 [HTTP] --> GET /wd/hub/status {} [MJSONWP] Calling AppiumDriver.getStatus() with args: [] [MJSONWP] Responding to client with driver.getStatus() result: {"build":{"version":"1.6.5","revision":null}} [HTTP] <-- GET /wd/hub/status 200 10 ms - 83 [HTTP] --> POST /wd/hub/session/a0d27eba-ddcb-4265-b364-958a9b85ab2c/timeouts/implicit_wait {"ms":0} [MJSONWP] Driver proxy active, passing request on via HTTP proxy [JSONWP Proxy] Proxying [POST /wd/hub/session/a0d27eba-ddcb-4265-b364-958a9b85ab2c/timeouts/implicit_wait] to [POST http://localhost:8080/wd/hub/session/a3c305e7-109d-f78a-78d3-13e7d5106a38/timeouts/implicit_wait] with body: {"ms":0} [JSONWP Proxy] Got response with status 200: {"value":"","status":0,"sessionId":"a3c305e7-109d-f78a-78d3-13e7d5106a38"} [JSONWP Proxy] Replacing sessionId a3c305e7-109d-f78a-78d3-13e7d5106a38 with a0d27eba-ddcb-4265-b364-958a9b85ab2c [HTTP] <-- POST /wd/hub/session/a0d27eba-ddcb-4265-b364-958a9b85ab2c/timeouts/implicit_wait 200 12 ms - 74 [HTTP] --> POST /wd/hub/session/a0d27eba-ddcb-4265-b364-958a9b85ab2c/element {"using":"xpath","value":"//*[@value='testappDemo']"} [MJSONWP] Driver proxy active, passing request on via HTTP proxy [JSONWP Proxy] Proxying [POST /wd/hub/session/a0d27eba-ddcb-4265-b364-958a9b85ab2c/element] to [POST http://localhost:8080/wd/hub/session/a3c305e7-109d-f78a-78d3-13e7d5106a38/element] with body: {"using":"xpath","value":"//*[@value='testappDemo']"} [JSONWP Proxy] Got response with status 200: {"value":{"ELEMENT":"f53554fe-8c2f-14b6-fd3b-af19a5b0eee4"},"status":0,"sessionId":"a3c305e7-109d-f78a-78d3-13e7d5106a38"} [JSONWP Proxy] Replacing sessionId a3c305e7-109d-f78a-78d3-13e7d5106a38 with a0d27eba-ddcb-4265-b364-958a9b85ab2c [HTTP] <-- POST /wd/hub/session/a0d27eba-ddcb-4265-b364-958a9b85ab2c/element 200 48 ms - 122 [HTTP] --> GET /wd/hub/session/a0d27eba-ddcb-4265-b364-958a9b85ab2c/orientation {} [MJSONWP] Driver proxy active, passing request on via HTTP proxy [JSONWP Proxy] Proxying [GET /wd/hub/session/a0d27eba-ddcb-4265-b364-958a9b85ab2c/orientation] to [GET http://localhost:8080/wd/hub/session/a3c305e7-109d-f78a-78d3-13e7d5106a38/orientation] with body: {} [JSONWP Proxy] Got response with status 200: "{\"value\":\"PORTRAIT\",\"status\":0,\"sessionId\":\"a3c305e7-109d-f78a-78d3-13e7d5106a38\"}" [JSONWP Proxy] Replacing sessionId a3c305e7-109d-f78a-78d3-13e7d5106a38 with a0d27eba-ddcb-4265-b364-958a9b85ab2c [HTTP] <-- GET /wd/hub/session/a0d27eba-ddcb-4265-b364-958a9b85ab2c/orientation 200 11 ms - 82 ########################################################################################################################## SCREEN SHOT METHOD IS FIRED AND THE RESPONSE RETURNS A GARBAGE VALUE THAT TAKES A 1 BYTE PICTURE OF NOTHING ########################################################################################################################## [HTTP] --> GET /wd/hub/session/a0d27eba-ddcb-4265-b364-958a9b85ab2c/screenshot {} [MJSONWP] Driver proxy active, passing request on via HTTP proxy [JSONWP Proxy] Proxying [GET /wd/hub/session/a0d27eba-ddcb-4265-b364-958a9b85ab2c/screenshot] to [GET http://localhost:8080/wd/hub/session/a3c305e7-109d-f78a-78d3-13e7d5106a38/screenshot] with body: {} [JSONWP Proxy] Got response with status 200: "{\"value\":\"AA==\",\"status\":0,\"sessionId\":\"a3c305e7-109d-f78a-78d3-13e7d5106a38\"}" [JSONWP Proxy] Replacing sessionId a3c305e7-109d-f78a-78d3-13e7d5106a38 with a0d27eba-ddcb-4265-b364-958a9b85ab2c [HTTP] <-- GET /wd/hub/session/a0d27eba-ddcb-4265-b364-958a9b85ab2c/screenshot 200 38 ms - 78 ########################################################################################################################## SCREEN SHOT METHOD IS FIRED A SECOND TIME AND THE RESPONSE RETURNS A GOOD VALUE THAT TAKES AN ACTUAL PICTURE OF SOMETHING ########################################################################################################################## [HTTP] --> GET /wd/hub/session/a0d27eba-ddcb-4265-b364-958a9b85ab2c/screenshot {} [MJSONWP] Driver proxy active, passing request on via HTTP proxy [JSONWP Proxy] Proxying [GET /wd/hub/session/a0d27eba-ddcb-4265-b364-958a9b85ab2c/screenshot] to [GET http://localhost:8080/wd/hub/session/a3c305e7-109d-f78a-78d3-13e7d5106a38/screenshot] with body: {} [JSONWP Proxy] Got response with status 200: "{\"value\":\"iVBORw0KGgoAAAANSUhEUgAABDgAAAcCCAYAAAB4GVLZAAAABHNCSVQICAgIfAhkiAAAIABJREFU\\neJzs3Xd8HPWd\\/\\/G3tOrVVu\\/FstzkJskFdxvbuBFwDKFcjiOQhBypEHLJ5QgJOdITEpK7Sy4kR0I4\\nckACMQQDBoxNccFVtnG3XGTJ6r1Llvb3h35atJpZadVWGvN6Ph5+PDyzs\\/OdWY3snfd8v5+v1+zZ\\ns+0CAAAAAACwMO\\/RPgAAAAAAAIChIuAAAAAAAACWR8ABAAAAAAAsj4ADAAAAAABYHgEHAAAAAACw\\nPAIOAAAAAABgeQQcAAAAAADA8gg4AAAAAACA5RFwAAAAAAAAyyPgAAAAAAAAlkfAAQAAAAAALI+A\\nAwAAAAAAWB4BBwAAAAAAsDwCDgAAAAAAYHkEHAAAAAAAwPIIOAAAAAAAgOURcAAAAAAAAMsj4AAA\\nAAAAAJZHwAEAAAAAACyPgAMAAAAAAFgeAQcAAAAAALA8Ag4AAAAAAGB5BBwAAAAAAMDyCDgAAAAA\\nAIDlEXAAAAAAAADLI+AAAAAAAACWR8ABAAAAAAAsj4ADAAAAAABYHgEHAAAAAACwPAIOAAAAAABg\\neQQcAAAAAADA8gg4AAAAAACA5RFwAAAAAAAAyyPgAAAAAAAAlkfAAQAAAAAALI+AAwAAAAAAWB4B\\nBwAAAAAAsDwCDgAAAAAAYHkEHAAAAAAAwPIIOAAAAAAAgOURcAAAAAAAAMsj4AAAAAAAAJZHwAEA\\nAAAAACyPgAMAAAAAAFgeAQcAAAAAALA8Ag4AAAAAAGB5BBwAAAAAAMDyCDgAAAAAAIDlEXAAAAAA\\nAADLI+AAAAAAAACWR8ABAAAAAAAsj4ADAAAAAABYHgEHAAAAAACwPAIOAAAAAABgeQQcAAAAAADA\\n8gg4AAAAAACA5RFwAAAAAAAAyyPgAAAAAAAAlkfAAQAAAAAAL... [JSONWP Proxy] Replacing sessionId a3c305e7-109d-f78a-78d3-13e7d5106a38 with a0d27eba-ddcb-4265-b364-958a9b85ab2c [HTTP] <-- GET /wd/hub/session/a0d27eba-ddcb-4265-b364-958a9b85ab2c/screenshot 200 586 ms - 102894 [HTTP] --> DELETE /wd/hub/session/a0d27eba-ddcb-4265-b364-958a9b85ab2c {} [MJSONWP] Calling AppiumDriver.deleteSession() with args: ["a0d27eba-ddcb-4265-b364-958a9b85ab2c"] [BaseDriver] Event 'quitSessionRequested' logged at 1500449016520 (00:23:36 GMT-0700 (PDT)) [Selendroid] Deleting Selendroid session [Selendroid] Deleting Selendroid server session [JSONWP Proxy] Proxying [DELETE /] to [DELETE http://localhost:8080/wd/hub/session/a3c305e7-109d-f78a-78d3-13e7d5106a38] with no body [JSONWP Proxy] Got response with status 200: "{\"value\":\"\",\"status\":0,\"sessionId\":\"a3c305e7-109d-f78a-78d3-13e7d5106a38\"}" [ADB] Getting connected devices... [ADB] 1 device(s) connected [ADB] Running '/Users/mario/Library/Android/sdk/platform-tools/adb' with args: ["-P",5037,"-s","emulator-5554","shell","am","force-stop","com.company.testapp.demo"] [Logcat] Stopping logcat capture [Appium] Removing session a0d27eba-ddcb-4265-b364-958a9b85ab2c from our master session list [BaseDriver] Event 'quitSessionFinished' logged at 1500449016952 (00:23:36 GMT-0700 (PDT)) [MJSONWP] Received response: null [MJSONWP] But deleting session, so not returning [MJSONWP] Responding to client with driver.deleteSession() result: null [HTTP] <-- DELETE /wd/hub/session/a0d27eba-ddcb-4265-b364-958a9b85ab2c 200 436 ms - 76