[
https://issues.apache.org/jira/browse/CB-11128?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel
]
Alexander Sorokin closed CB-11128.
----------------------------------
Resolution: Fixed
> Appium test failures related to lost session
> --------------------------------------------
>
> Key: CB-11128
> URL: https://issues.apache.org/jira/browse/CB-11128
> Project: Apache Cordova
> Issue Type: Test
> Components: Appium
> Reporter: Alexander Sorokin
> Assignee: Alexander Sorokin
>
> Logs for reference:
> Test logs:
> {noformat}
> [MEDIC LOG Thu, 21 Apr 2016 01:52:43 GMT] Spec passed: camera.ui.util Delete
> test image from device library
> [MEDIC LOG Thu, 21 Apr 2016 01:52:43 GMT] Suite done: Specs.
> [MEDIC LOG Thu, 21 Apr 2016 01:52:43 GMT] Result was: finished
> [MEDIC LOG Thu, 21 Apr 2016 01:52:43 GMT] Starting new spec: camera.ui.util
> Destroy the session
> > CALL quit()
> > DELETE /session/:sessionID
> [MEDIC LOG Thu, 21 Apr 2016 01:53:43 GMT] Spec failed: camera.ui.util Destroy
> the session
> [MEDIC LOG Thu, 21 Apr 2016 01:53:43 GMT] Suite done: Camera tests Android.
> [MEDIC LOG Thu, 21 Apr 2016 01:53:43 GMT] Result was: finished
> [MEDIC LOG Thu, 21 Apr 2016 01:53:43 GMT] Starting new spec: contacts.ui.util
> configuring driver and starting a session
> > CALL
> init({"browserName":"","appium-version":"1.5","platformName":"Android","platformVersion":19,"deviceName":"Galaxy_Nexus","app":"C:\\Users\\buildbot\\slaves\\apache\\cordova-android-win\\mobilespec\\platforms\\android\\build\\outputs\\apk\\android-debug.apk","autoAcceptAlerts":true})
>
> > POST /session
> {"desiredCapabilities":{"browserName":"","appium-version":"1.5","platformName":"Android","platformVersion":19,"deviceName":"Galaxy_Nexus","app":"C:\\Users\\buildbot\\slaves\\apache\\cordova-android-win\\mobilespec\\platforms\\android\\build\\outputs\\apk\\android-debug.apk","autoAcceptAlerts":true}}
> Ending your web drivage..
> > RESPONSE quit()
> [MEDIC LOG Thu, 21 Apr 2016 01:58:43 GMT] Spec failed: contacts.ui.util
> configuring driver and starting a session
> [MEDIC LOG Thu, 21 Apr 2016 01:58:43 GMT] Starting new spec:
> contacts.ui.spec.1 Pick a contact
> Driving the web on session: 9a9dd635-643a-43b1-a61c-c42225b79d35
> > RESPONSE
> init({"browserName":"","appium-version":"1.5","platformName":"Android","platformVersion":19,"deviceName":"Galaxy_Nexus","app":"C:\\Users\\buildbot\\slaves\\apache\\cordova-android-win\\mobilespec\\platforms\\android\\build\\outputs\\apk\\android-debug.apk","autoAcceptAlerts":true})
>
> "9a9dd635-643a-43b1-a61c-c42225b79d35",{"platform":"LINUX","webStorageEnabled":false,"takesScreenshot":true,"javascriptEnabled":true,"databaseEnabled":false,"networkConnectionEnabled":true,"locationContextEnabled":false,"warnings":{},"desired":{"browserName":"","appium-version":"1.5","platformName":"Android","platformVersion":19,"deviceName":"Galaxy_Nexus","app":"C:\\Users\\buildbot\\slaves\\apache\\cordova-android-win\\mobilespec\\platforms\\android\\build\\outputs\\apk\\android-debug.apk","autoAcceptAlerts":true,"automationName":"Appium","avd":"Galaxy_Nexus"},"browserName":"","appium-version":"1.5","platformName":"Android","platformVersion":"4.4.4","deviceName":"emulator-5554","app":"C:\\Users\\buildbot\\slaves\\apache\\cordova-android-win\\mobilespec\\platforms\\android\\build\\outputs\\apk\\android-debug.apk","autoAcceptAlerts":true,"automationName":"Appium","avd":"Galaxy_Nexus"}
> > CALL setImplicitWaitTimeout(10000)
> > POST /session/:sessionID/timeouts/implicit_wait {"ms":10000}
> > RESPONSE setImplicitWaitTimeout(10000)
> > CALL contexts()
> > GET /session/:sessionID/contexts
> > CALL context()
> > POST /session/:sessionID/context {}
> > CALL currentContext()
> > GET /session/:sessionID/context
> > RESPONSE contexts() ["NATIVE_APP"]
> Couldn't get webview context. Retries remaining: 2
> > CALL sleep(5000)
> > RESPONSE currentContext() "NATIVE_APP"
> > CALL context("NATIVE_APP")
> > POST /session/:sessionID/context {"name":"NATIVE_APP"}
> > RESPONSE sleep(5000)
> > CALL contexts()
> > GET /session/:sessionID/contexts
> > RESPONSE context("NATIVE_APP")
> > CALL
> saveScreenshot("C:\\Users\\buildbot\\slaves\\apache\\cordova-android-win\\appium_screenshots\\2016-04-20_19.03.05.png")
>
> > GET /session/:sessionID/screenshot
> > CALL context()
> > POST /session/:sessionID/context {}
> [MEDIC LOG Thu, 21 Apr 2016 02:03:43 GMT] Spec failed: contacts.ui.spec.1
> Pick a contact
> [MEDIC LOG Thu, 21 Apr 2016 02:03:43 GMT] Starting new spec:
> contacts.ui.spec.2 Update an existing contact
> > CALL context()
> > POST /session/:sessionID/context {}
> > CALL currentContext()
> > GET /session/:sessionID/context
> > RESPONSE contexts() ["NATIVE_APP"]
> Couldn't get webview context. Retries remaining: 1
> > CALL sleep(5000)
> > RESPONSE sleep(5000)
> > CALL contexts()
> > GET /session/:sessionID/contexts
> > RESPONSE
> saveScreenshot("C:\\Users\\buildbot\\slaves\\apache\\cordova-android-win\\appium_screenshots\\2016-04-20_19.03.05.png")
>
> "C:\\Users\\buildbot\\slaves\\apache\\cordova-android-win\\appium_screenshots\\2016-04-20_19.03.05.png"
> > CALL context("NATIVE_APP")
> > POST /session/:sessionID/context {"name":"NATIVE_APP"}
> > RESPONSE currentContext() "NATIVE_APP"
> > CALL context("NATIVE_APP")
> > POST /session/:sessionID/context {"name":"NATIVE_APP"}
> > RESPONSE contexts() ["NATIVE_APP"]
> > RESPONSE context("NATIVE_APP")
> > CALL quit()
> > DELETE /session/:sessionID
> > RESPONSE context("NATIVE_APP")
> > CALL
> saveScreenshot("C:\\Users\\buildbot\\slaves\\apache\\cordova-android-win\\appium_screenshots\\2016-04-20_19.03.43.png")
>
> > GET /session/:sessionID/screenshot
> [MEDIC LOG Thu, 21 Apr 2016 02:05:16 GMT] Uncaught exception! Killing server
> and exiting in 2 seconds...
> {noformat}
> Appium server logs:
> {noformat}
> 2016-04-21 01:52:29:939 - info: [AndroidBootstrap] [BOOTSTRAP LOG] [debug]
> Got command of type ACTION
> 2016-04-21 01:52:29:949 - info: [AndroidBootstrap] [BOOTSTRAP LOG] [debug]
> Got command action: find
> 2016-04-21 01:52:29:951 - info: [AndroidBootstrap] [BOOTSTRAP LOG] [debug]
> Finding //android.widget.TextView[@text="Delete"] using XPATH with the
> contextId: multiple: false
> 2016-04-21 01:52:31:856 - info: [AndroidBootstrap] [BOOTSTRAP LOG] [debug]
> Using: UiSelector[CLASS=android.widget.TextView, INSTANCE=4]
> 2016-04-21 01:52:32:163 - info: [AndroidBootstrap] [BOOTSTRAP LOG] [debug]
> Returning result: {"value":{"ELEMENT":"85"},"status":0}
> 2016-04-21 01:52:32:167 - info: [debug] [AndroidBootstrap] Received command
> result from bootstrap
> 2016-04-21 01:52:32:168 - info: [MJSONWP] Responding to client with
> driver.findElement() result: {"ELEMENT":"85"}
> 2016-04-21 01:52:32:169 - info: [HTTP] <-- POST
> /wd/hub/session/9d212f73-bd26-4613-afe4-f2f3a3744caf/element 200 3860 ms - 88
> 2016-04-21 01:52:32:177 - info: [HTTP] --> POST
> /wd/hub/session/9d212f73-bd26-4613-afe4-f2f3a3744caf/element/85/click
> 2016-04-21 01:52:32:178 - info: [MJSONWP] Calling AppiumDriver.click() with
> args: ["85","9d212f73-bd26-4613-afe4-f2f3a3744caf"]
> 2016-04-21 01:52:32:178 - info: [debug] [AndroidBootstrap] Sending command to
> android: {"cmd":"action","action":"element:click","params":{"elementId":"85"}}
> 2016-04-21 01:52:32:201 - info: [AndroidBootstrap] [BOOTSTRAP LOG] [debug]
> Got data from client:
> {"cmd":"action","action":"element:click","params":{"elementId":"85"}}
> 2016-04-21 01:52:32:201 - info: [AndroidBootstrap] [BOOTSTRAP LOG] [debug]
> Got command of type ACTION
> 2016-04-21 01:52:32:205 - info: [AndroidBootstrap] [BOOTSTRAP LOG] [debug]
> Got command action: click
> 2016-04-21 01:52:35:675 - info: [AndroidBootstrap] [BOOTSTRAP LOG] [debug]
> Returning result: {"value":true,"status":0}
> 2016-04-21 01:52:35:676 - info: [debug] [AndroidBootstrap] Received command
> result from bootstrap
> 2016-04-21 01:52:35:676 - info: [MJSONWP] Responding to client with
> driver.click() result: true
> 2016-04-21 01:52:35:677 - info: [HTTP] <-- POST
> /wd/hub/session/9d212f73-bd26-4613-afe4-f2f3a3744caf/element/85/click 200
> 3500 ms - 76
> 2016-04-21 01:52:35:682 - info: [HTTP] --> POST
> /wd/hub/session/9d212f73-bd26-4613-afe4-f2f3a3744caf/element
> 2016-04-21 01:52:35:683 - info: [MJSONWP] Calling AppiumDriver.findElement()
> with args:
> ["xpath","//android.widget.Button[@text=\"OK\"]","9d212f73-bd26-4613-afe4-f2f3a3744caf"]
> 2016-04-21 01:52:35:683 - info: [debug] [BaseDriver] Waiting up to 10000 ms
> for condition
> 2016-04-21 01:52:35:683 - info: [debug] [AndroidBootstrap] Sending command to
> android:
> {"cmd":"action","action":"find","params":{"strategy":"xpath","selector":"//android.widget.Button[@text=\"OK\"]","context":"","multiple":false}}
> 2016-04-21 01:52:35:712 - info: [AndroidBootstrap] [BOOTSTRAP LOG] [debug]
> Got data from client:
> {"cmd":"action","action":"find","params":{"strategy":"xpath","selector":"//android.widget.Button[@text=\"OK\"]","context":"","multiple":false}}
> 2016-04-21 01:52:35:717 - info: [AndroidBootstrap] [BOOTSTRAP LOG] [debug]
> Got command of type ACTION
> 2016-04-21 01:52:35:719 - info: [AndroidBootstrap] [BOOTSTRAP LOG] [debug]
> Got command action: find
> 2016-04-21 01:52:35:752 - info: [AndroidBootstrap] [BOOTSTRAP LOG] [debug]
> Finding //android.widget.Button[@text="OK"] using XPATH with the contextId:
> multiple: false
> 2016-04-21 01:52:36:207 - info: [AndroidBootstrap] [BOOTSTRAP LOG] [debug]
> Using: UiSelector[CLASS=android.widget.Button, INSTANCE=0]
> 2016-04-21 01:52:36:530 - info: [AndroidBootstrap] [BOOTSTRAP LOG] [debug]
> Returning result: {"value":{"ELEMENT":"86"},"status":0}
> 2016-04-21 01:52:36:533 - info: [debug] [AndroidBootstrap] Received command
> result from bootstrap
> 2016-04-21 01:52:36:534 - info: [MJSONWP] Responding to client with
> driver.findElement() result: {"ELEMENT":"86"}
> 2016-04-21 01:52:36:534 - info: [HTTP] <-- POST
> /wd/hub/session/9d212f73-bd26-4613-afe4-f2f3a3744caf/element 200 852 ms - 88
> 2016-04-21 01:52:36:541 - info: [HTTP] --> POST
> /wd/hub/session/9d212f73-bd26-4613-afe4-f2f3a3744caf/element/86/click
> 2016-04-21 01:52:36:542 - info: [MJSONWP] Calling AppiumDriver.click() with
> args: ["86","9d212f73-bd26-4613-afe4-f2f3a3744caf"]
> 2016-04-21 01:52:36:542 - info: [debug] [AndroidBootstrap] Sending command to
> android: {"cmd":"action","action":"element:click","params":{"elementId":"86"}}
> 2016-04-21 01:52:36:549 - info: [AndroidBootstrap] [BOOTSTRAP LOG] [debug]
> Got data from client:
> {"cmd":"action","action":"element:click","params":{"elementId":"86"}}
> 2016-04-21 01:52:36:549 - info: [AndroidBootstrap] [BOOTSTRAP LOG] [debug]
> Got command of type ACTION
> 2016-04-21 01:52:36:549 - info: [AndroidBootstrap] [BOOTSTRAP LOG] [debug]
> Got command action: click
> 2016-04-21 01:52:39:824 - info: [AndroidBootstrap] [BOOTSTRAP LOG] [debug]
> Returning result: {"value":true,"status":0}
> 2016-04-21 01:52:39:825 - info: [debug] [AndroidBootstrap] Received command
> result from bootstrap
> 2016-04-21 01:52:39:825 - info: [MJSONWP] Responding to client with
> driver.click() result: true
> 2016-04-21 01:52:39:826 - info: [HTTP] <-- POST
> /wd/hub/session/9d212f73-bd26-4613-afe4-f2f3a3744caf/element/86/click 200
> 3284 ms - 76
> 2016-04-21 01:52:39:832 - info: [HTTP] --> POST
> /wd/hub/session/9d212f73-bd26-4613-afe4-f2f3a3744caf/appium/device/keyevent
> 2016-04-21 01:52:39:832 - info: [MJSONWP] Calling AppiumDriver.keyevent()
> with args:
> [4,null,null,null,null,null,null,null,null,null,null,null,null,null,null,null,"9d212f73-bd26-4613-afe4-f2f3a3744caf"]
> 2016-04-21 01:52:39:833 - warn: [AndroidDriver] keyevent will be deprecated
> use pressKeyCode
> 2016-04-21 01:52:39:833 - info: [debug] [AndroidBootstrap] Sending command to
> android:
> {"cmd":"action","action":"pressKeyCode","params":{"keycode":4,"metastate":null}}
> 2016-04-21 01:52:39:839 - info: [AndroidBootstrap] [BOOTSTRAP LOG] [debug]
> Got data from client:
> {"cmd":"action","action":"pressKeyCode","params":{"keycode":4,"metastate":null}}
> 2016-04-21 01:52:39:850 - info: [AndroidBootstrap] [BOOTSTRAP LOG] [debug]
> Got command of type ACTION
> 2016-04-21 01:52:39:853 - info: [AndroidBootstrap] [BOOTSTRAP LOG] [debug]
> Got command action: pressKeyCode
> 2016-04-21 01:52:39:976 - info: [AndroidBootstrap] [BOOTSTRAP LOG] [debug]
> Returning result: {"value":true,"status":0}
> 2016-04-21 01:52:39:977 - info: [debug] [AndroidBootstrap] Received command
> result from bootstrap
> 2016-04-21 01:52:39:977 - info: [MJSONWP] Responding to client with
> driver.keyevent() result: true
> 2016-04-21 01:52:39:978 - info: [HTTP] <-- POST
> /wd/hub/session/9d212f73-bd26-4613-afe4-f2f3a3744caf/appium/device/keyevent
> 200 146 ms - 76
> 2016-04-21 01:52:40:985 - info: [HTTP] --> POST
> /wd/hub/session/9d212f73-bd26-4613-afe4-f2f3a3744caf/appium/device/keyevent
> 2016-04-21 01:52:40:985 - info: [MJSONWP] Calling AppiumDriver.keyevent()
> with args:
> [4,null,null,null,null,null,null,null,null,null,null,null,null,null,null,null,null,null,"9d212f73-bd26-4613-afe4-f2f3a3744caf"]
> 2016-04-21 01:52:40:986 - warn: [AndroidDriver] keyevent will be deprecated
> use pressKeyCode
> 2016-04-21 01:52:40:986 - info: [debug] [AndroidBootstrap] Sending command to
> android:
> {"cmd":"action","action":"pressKeyCode","params":{"keycode":4,"metastate":null}}
> 2016-04-21 01:52:40:997 - info: [AndroidBootstrap] [BOOTSTRAP LOG] [debug]
> Got data from client:
> {"cmd":"action","action":"pressKeyCode","params":{"keycode":4,"metastate":null}}
> 2016-04-21 01:52:40:997 - info: [AndroidBootstrap] [BOOTSTRAP LOG] [debug]
> Got command of type ACTION
> 2016-04-21 01:52:40:997 - info: [AndroidBootstrap] [BOOTSTRAP LOG] [debug]
> Got command action: pressKeyCode
> 2016-04-21 01:52:42:115 - info: [AndroidBootstrap] [BOOTSTRAP LOG] [debug]
> Returning result: {"value":true,"status":0}
> 2016-04-21 01:52:42:116 - info: [debug] [AndroidBootstrap] Received command
> result from bootstrap
> 2016-04-21 01:52:42:117 - info: [MJSONWP] Responding to client with
> driver.keyevent() result: true
> 2016-04-21 01:52:42:118 - info: [HTTP] <-- POST
> /wd/hub/session/9d212f73-bd26-4613-afe4-f2f3a3744caf/appium/device/keyevent
> 200 1131 ms - 76
> 2016-04-21 01:52:43:127 - info: [HTTP] --> POST
> /wd/hub/session/9d212f73-bd26-4613-afe4-f2f3a3744caf/appium/device/keyevent
> 2016-04-21 01:52:43:128 - info: [MJSONWP] Calling AppiumDriver.keyevent()
> with args:
> [4,null,null,null,null,null,null,null,null,null,null,null,null,null,null,null,null,null,null,null,"9d212f73-bd26-4613-afe4-f2f3a3744caf"]
> 2016-04-21 01:52:43:128 - warn: [AndroidDriver] keyevent will be deprecated
> use pressKeyCode
> 2016-04-21 01:52:43:129 - info: [debug] [AndroidBootstrap] Sending command to
> android:
> {"cmd":"action","action":"pressKeyCode","params":{"keycode":4,"metastate":null}}
> 2016-04-21 01:52:43:137 - info: [AndroidBootstrap] [BOOTSTRAP LOG] [debug]
> Got data from client:
> {"cmd":"action","action":"pressKeyCode","params":{"keycode":4,"metastate":null}}
> 2016-04-21 01:52:43:137 - info: [AndroidBootstrap] [BOOTSTRAP LOG] [debug]
> Got command of type ACTION
> 2016-04-21 01:52:43:137 - info: [AndroidBootstrap] [BOOTSTRAP LOG] [debug]
> Got command action: pressKeyCode
> 2016-04-21 01:52:43:817 - info: [AndroidBootstrap] [BOOTSTRAP LOG] [debug]
> Returning result: {"value":true,"status":0}
> 2016-04-21 01:52:43:817 - info: [debug] [AndroidBootstrap] Received command
> result from bootstrap
> 2016-04-21 01:52:43:818 - info: [MJSONWP] Responding to client with
> driver.keyevent() result: true
> 2016-04-21 01:52:43:818 - info: [HTTP] <-- POST
> /wd/hub/session/9d212f73-bd26-4613-afe4-f2f3a3744caf/appium/device/keyevent
> 200 691 ms - 76
> 2016-04-21 01:52:43:832 - info: [HTTP] --> DELETE
> /wd/hub/session/9d212f73-bd26-4613-afe4-f2f3a3744caf
> 2016-04-21 01:52:43:834 - info: [MJSONWP] Calling
> AppiumDriver.deleteSession() with args:
> ["9d212f73-bd26-4613-afe4-f2f3a3744caf"]
> 2016-04-21 01:52:43:835 - info: [debug] [AndroidDriver] Shutting down Android
> driver
> 2016-04-21 01:52:43:835 - info: [debug] [AndroidDriver] Stopping chromedriver
> for context WEBVIEW_org.apache.mobilespec
> 2016-04-21 01:52:43:836 - info: [debug] [Chromedriver] Changed state to
> 'stopping'
> 2016-04-21 01:52:43:836 - info: [JSONWP Proxy] Proxying [DELETE /] to [DELETE
> http://127.0.0.1:9515/wd/hub/session/b4f71f15914c88ded8a92a1dc3a6108e] with
> no body
> 2016-04-21 01:52:43:839 - info: [JSONWP Proxy] Got response with status 200:
> "{\"sessionId\":\"b4f71f15914c88ded8a92a1dc3a6108e\",\"status\":0,\"value\":null}"
> 2016-04-21 01:52:43:844 - info: [debug] [Chromedriver] Changed state to
> 'stopped'
> 2016-04-21 01:52:43:844 - info: [debug] [ADB] Getting connected devices...
> 2016-04-21 01:52:52:467 - info: [debug] [ADB] 1 device(s) connected
> 2016-04-21 01:52:52:467 - info: [debug] [ADB] Running
> C:\Android\android-sdk\platform-tools\adb.exe with args:
> ["-P",5037,"-s","emulator-5554","-s","emulator-5554","shell","am","force-stop","org.apache.mobilespec"]
> 2016-04-21 01:53:05:247 - info: [debug] [ADB] Pressing the HOME button
> 2016-04-21 01:53:05:248 - info: [debug] [ADB] Getting connected devices...
> 2016-04-21 01:53:14:083 - info: [debug] [ADB] 1 device(s) connected
> 2016-04-21 01:53:14:083 - info: [debug] [ADB] Running
> C:\Android\android-sdk\platform-tools\adb.exe with args:
> ["-P",5037,"-s","emulator-5554","-s","emulator-5554","shell","input","keyevent",3]
> 2016-04-21 01:53:26:808 - info: [debug] [Logcat] Stopping logcat capture
> 2016-04-21 01:53:27:062 - info: [debug] [AndroidBootstrap] Sending command to
> android: {"cmd":"shutdown"}
> 2016-04-21 01:53:27:071 - info: [AndroidBootstrap] [BOOTSTRAP LOG] [debug]
> Got data from client: {"cmd":"shutdown"}
> 2016-04-21 01:53:27:071 - info: [AndroidBootstrap] [BOOTSTRAP LOG] [debug]
> Got command of type SHUTDOWN
> 2016-04-21 01:53:27:082 - info: [AndroidBootstrap] [BOOTSTRAP LOG] [debug]
> Returning result: {"value":"OK, shutting down","status":0}
> 2016-04-21 01:53:27:086 - info: [debug] [AndroidBootstrap] Received command
> result from bootstrap
> 2016-04-21 01:53:27:086 - info: [debug] [UiAutomator] Shutting down
> UiAutomator
> 2016-04-21 01:53:27:087 - info: [debug] [UiAutomator] Moving to state
> 'stopping'
> 2016-04-21 01:53:27:258 - info: [AndroidBootstrap] [BOOTSTRAP LOG] [debug]
> Closed client connection
> 2016-04-21 01:53:27:258 - info: [debug] [AndroidBootstrap] [UIAUTO STDOUT]
> INSTRUMENTATION_STATUS: numtests=1
> 2016-04-21 01:53:27:258 - info: [debug] [AndroidBootstrap] [UIAUTO STDOUT]
> INSTRUMENTATION_STATUS: stream=.
> 2016-04-21 01:53:27:258 - info: [debug] [AndroidBootstrap] [UIAUTO STDOUT]
> INSTRUMENTATION_STATUS: id=UiAutomatorTestRunner
> 2016-04-21 01:53:27:258 - info: [debug] [AndroidBootstrap] [UIAUTO STDOUT]
> INSTRUMENTATION_STATUS: test=testRunServer
> 2016-04-21 01:53:27:258 - info: [debug] [AndroidBootstrap] [UIAUTO STDOUT]
> INSTRUMENTATION_STATUS: class=io.appium.android.bootstrap.Bootstrap
> 2016-04-21 01:53:27:259 - info: [debug] [AndroidBootstrap] [UIAUTO STDOUT]
> INSTRUMENTATION_STATUS: current=1
> 2016-04-21 01:53:27:259 - info: [debug] [AndroidBootstrap] [UIAUTO STDOUT]
> INSTRUMENTATION_STATUS_CODE: 0
> 2016-04-21 01:53:27:259 - info: [debug] [AndroidBootstrap] [UIAUTO STDOUT]
> INSTRUMENTATION_STATUS: stream=
> 2016-04-21 01:53:27:263 - info: [debug] [AndroidBootstrap] [UIAUTO STDOUT]
> Test results for WatcherResultPrinter=.
> 2016-04-21 01:53:27:263 - info: [debug] [AndroidBootstrap] [UIAUTO STDOUT]
> Time: 1060.744
> 2016-04-21 01:53:27:263 - info: [debug] [AndroidBootstrap] [UIAUTO STDOUT] OK
> (1 test)
> 2016-04-21 01:53:27:263 - info: [debug] [AndroidBootstrap] [UIAUTO STDOUT]
> INSTRUMENTATION_STATUS_CODE: -1
> 2016-04-21 01:53:27:339 - info: [debug] [UiAutomator] UiAutomator shut down
> normally
> 2016-04-21 01:53:27:339 - info: [debug] [UiAutomator] Moving to state
> 'stopped'
> 2016-04-21 01:53:27:339 - info: [debug] [ADB] Attempting to kill all
> uiautomator processes
> 2016-04-21 01:53:27:340 - info: [debug] [ADB] Getting all processes with
> uiautomator
> 2016-04-21 01:53:27:340 - info: [debug] [ADB] Getting connected devices...
> 2016-04-21 01:53:35:045 - info: [debug] [ADB] 1 device(s) connected
> 2016-04-21 01:53:35:045 - info: [debug] [ADB] Running
> C:\Android\android-sdk\platform-tools\adb.exe with args:
> ["-P",5037,"-s","emulator-5554","-s","emulator-5554","shell","ps","uiautomator"]
> 2016-04-21 01:53:43:845 - info: [HTTP] --> POST /wd/hub/session
> 2016-04-21 01:53:43:847 - info: [MJSONWP] Calling
> AppiumDriver.createSession() with args:
> [{"browserName":"","appium-version":"1.5","platformName":"Android","platformVersion":19,"deviceName":"Galaxy_Nexus","app":"C:\\Users\\buildbot\\sla...
> 2016-04-21 01:53:43:959 - info: [ADB] No uiautomator process found to kill,
> continuing...
> 2016-04-21 01:53:43:959 - info: [debug] [UiAutomator] Moving to state
> 'stopped'
> 2016-04-21 01:53:43:959 - info: [Appium] Removing session
> 9d212f73-bd26-4613-afe4-f2f3a3744caf from our master session list
> 2016-04-21 01:53:43:960 - info: [Appium] Creating new AndroidDriver session
> 2016-04-21 01:53:43:960 - info: [Appium] Capabilities:
> 2016-04-21 01:53:43:961 - info: [Appium] browserName: ''
> 2016-04-21 01:53:43:961 - info: [Appium] appium-version: '1.5'
> 2016-04-21 01:53:43:961 - info: [Appium] platformName: 'Android'
> 2016-04-21 01:53:43:961 - info: [Appium] platformVersion: 19
> 2016-04-21 01:53:43:961 - info: [Appium] deviceName: 'Galaxy_Nexus'
> 2016-04-21 01:53:43:961 - info: [Appium] app:
> 'C:\\Users\\buildbot\\slaves\\apache\\cordova-android-win\\mobilespec\\platforms\\android\\build\\outputs\\apk\\android-debug.apk'
> 2016-04-21 01:53:43:961 - info: [Appium] autoAcceptAlerts: true
> 2016-04-21 01:53:43:962 - info: [Appium] automationName: 'Appium'
> 2016-04-21 01:53:43:962 - info: [Appium] avd: 'Galaxy_Nexus'
> 2016-04-21 01:53:43:965 - warn: [BaseDriver] The following capabilities were
> provided, but are not recognized by appium: appium-version, autoAcceptAlerts.
> 2016-04-21 01:53:43:966 - info: [BaseDriver] Session created with session id:
> 9a9dd635-643a-43b1-a61c-c42225b79d35
> 2016-04-21 01:53:43:967 - info: [debug] [AndroidDriver] Getting Java version
> 2016-04-21 01:53:45:915 - info: [debug] [MJSONWP] Received response: null
> 2016-04-21 01:53:45:915 - info: [debug] [MJSONWP] But deleting session, so
> not returning
> 2016-04-21 01:53:45:915 - info: [MJSONWP] Responding to client with
> driver.deleteSession() result: null
> 2016-04-21 01:53:45:916 - info: [HTTP] <-- DELETE
> /wd/hub/session/9d212f73-bd26-4613-afe4-f2f3a3744caf 200 62083 ms - 76
> 2016-04-21 01:54:27:043 - info: [AndroidDriver] Java version is: 1.8.0_77
> 2016-04-21 01:54:27:044 - info: [ADB] Checking whether adb is present
> 2016-04-21 01:54:29:864 - info: [ADB] Using adb.exe from
> C:\Android\android-sdk\platform-tools\adb.exe
> 2016-04-21 01:54:29:865 - info: [AndroidDriver] Retrieving device list
> 2016-04-21 01:54:29:865 - info: [debug] [ADB] Trying to find a connected
> android device
> 2016-04-21 01:54:29:865 - info: [debug] [ADB] Getting connected devices...
> 2016-04-21 01:54:39:869 - info: [debug] [ADB] 1 device(s) connected
> 2016-04-21 01:54:39:870 - info: [AndroidDriver] Using device: emulator-5554
> 2016-04-21 01:54:39:870 - info: [ADB] Checking whether adb is present
> 2016-04-21 01:54:41:439 - info: [ADB] Using adb.exe from
> C:\Android\android-sdk\platform-tools\adb.exe
> 2016-04-21 01:54:41:439 - info: [debug] [ADB] Setting device id to
> emulator-5554
> 2016-04-21 01:54:41:440 - info: [BaseDriver] Using local app
> 'C:\Users\buildbot\slaves\apache\cordova-android-win\mobilespec\platforms\android\build\outputs\apk\android-debug.apk'
> 2016-04-21 01:54:41:440 - info: [debug] [AndroidDriver] Checking whether app
> is actually present
> 2016-04-21 01:54:41:688 - info: [AndroidDriver] Starting Android session
> 2016-04-21 01:54:41:689 - info: [debug] [ADB] Trying to find Galaxy_Nexus
> emulator
> 2016-04-21 01:54:41:689 - info: [debug] [ADB] Getting connected emulators
> 2016-04-21 01:54:41:689 - info: [debug] [ADB] Getting connected devices...
> 2016-04-21 01:54:50:815 - info: [debug] [ADB] 1 device(s) connected
> 2016-04-21 01:54:50:816 - info: [debug] [ADB] 1 emulator(s) connected
> 2016-04-21 01:54:50:816 - info: [debug] [ADB] Sending telnet command to
> device: avd name
> 2016-04-21 01:54:50:816 - info: [debug] [ADB] Getting running emulator port
> 2016-04-21 01:54:50:819 - info: [debug] [ADB] Socket connection to device
> created
> 2016-04-21 01:54:50:824 - info: [debug] [ADB] Socket connection to device
> ready
> 2016-04-21 01:54:50:828 - info: [debug] [ADB] Telnet command got response:
> Galaxy_Nexus
> 2016-04-21 01:54:50:829 - info: [debug] [ADB] Found emulator Galaxy_Nexus in
> port 5554
> 2016-04-21 01:54:50:829 - info: [debug] [ADB] Setting device id to
> emulator-5554
> 2016-04-21 01:54:50:830 - info: [debug] [AndroidDriver] Not launching AVD
> because it is already running.
> 2016-04-21 01:54:50:830 - info: [debug] [ADB] Running
> C:\Android\android-sdk\platform-tools\adb.exe with args:
> ["-P",5037,"-s","emulator-5554","-s","emulator-5554","wait-for-device"]
> 2016-04-21 01:55:00:276 - info: [debug] [ADB] Getting connected devices...
> 2016-04-21 01:55:07:806 - info: [debug] [ADB] 1 device(s) connected
> 2016-04-21 01:55:07:807 - info: [debug] [ADB] Running
> C:\Android\android-sdk\platform-tools\adb.exe with args:
> ["-P",5037,"-s","emulator-5554","-s","emulator-5554","shell","echo","ping"]
> 2016-04-21 01:55:16:242 - info: [debug] [Logcat] Starting logcat capture
> 2016-04-21 01:55:23:981 - info: [debug] [AndroidDriver] Pushing settings apk
> to device...
> 2016-04-21 01:55:23:981 - info: [debug] [ADB] Running
> C:\Android\android-sdk\platform-tools\adb.exe with args:
> ["-P",5037,"-s","emulator-5554","-s","emulator-5554","install","C:\\Users\\buildbot\\slaves\\apache\\cordova-android-win\\cordova-medic\\node_modules\\appium\\node_modules\\io.appium.settings\\bin\\settings_apk-debug.apk"]
> 2016-04-21 01:55:37:745 - info: [debug] [AndroidDriver] Pushing unlock helper
> app to device...
> 2016-04-21 01:55:37:746 - info: [debug] [ADB] Running
> C:\Android\android-sdk\platform-tools\adb.exe with args:
> ["-P",5037,"-s","emulator-5554","-s","emulator-5554","install","C:\\Users\\buildbot\\slaves\\apache\\cordova-android-win\\cordova-medic\\node_modules\\appium\\node_modules\\appium-unlock\\bin\\unlock_apk-debug.apk"]
> 2016-04-21 01:55:50:454 - info: [ADB] Getting device platform version
> 2016-04-21 01:55:50:455 - info: [debug] [ADB] Getting connected devices...
> 2016-04-21 01:55:59:727 - info: [debug] [ADB] 1 device(s) connected
> 2016-04-21 01:55:59:728 - info: [debug] [ADB] Running
> C:\Android\android-sdk\platform-tools\adb.exe with args:
> ["-P",5037,"-s","emulator-5554","-s","emulator-5554","shell","getprop","ro.build.version.release"]
> 2016-04-21 01:56:09:940 - info: [debug] [AndroidDriver] Parsing package and
> activity from app manifest
> 2016-04-21 01:56:09:940 - info: [ADB] Checking whether aapt is present
> 2016-04-21 01:56:12:455 - info: [ADB] Using aapt.exe from
> C:\Android\android-sdk\build-tools\23.0.2\aapt.exe
> 2016-04-21 01:56:12:455 - info: [ADB] Extracting package and launch activity
> from manifest
> 2016-04-21 01:56:16:980 - info: [debug] [ADB] badging package:
> org.apache.mobilespec
> 2016-04-21 01:56:16:980 - info: [debug] [ADB] badging act:
> org.apache.mobilespec.MainActivity
> 2016-04-21 01:56:16:980 - info: [debug] [AndroidDriver] Parsed package and
> activity are: org.apache.mobilespec/org.apache.mobilespec.MainActivity
> 2016-04-21 01:56:16:981 - info: [debug] [ADB] Uninstalling
> org.apache.mobilespec
> 2016-04-21 01:56:16:981 - info: [debug] [ADB] Getting connected devices...
> 2016-04-21 01:56:24:851 - info: [debug] [ADB] 1 device(s) connected
> 2016-04-21 01:56:24:851 - info: [debug] [ADB] Running
> C:\Android\android-sdk\platform-tools\adb.exe with args:
> ["-P",5037,"-s","emulator-5554","-s","emulator-5554","shell","am","force-stop","org.apache.mobilespec"]
> 2016-04-21 01:56:34:593 - info: [debug] [ADB] Running
> C:\Android\android-sdk\platform-tools\adb.exe with args:
> ["-P",5037,"-s","emulator-5554","-s","emulator-5554","uninstall","org.apache.mobilespec"]
> 2016-04-21 01:56:47:379 - info: [ADB] App was uninstalled
> 2016-04-21 01:56:49:390 - info: [AndroidDriver] Remote apk path is
> /data/local/tmp/0854e43c550c953b8c1cecbac58bb822.apk
> 2016-04-21 01:56:49:391 - info: [debug] [ADB] Getting connected devices...
> 2016-04-21 01:56:57:380 - info: [debug] [ADB] 1 device(s) connected
> 2016-04-21 01:56:57:380 - info: [debug] [ADB] Running
> C:\Android\android-sdk\platform-tools\adb.exe with args:
> ["-P",5037,"-s","emulator-5554","-s","emulator-5554","shell","ls","/data/local/tmp/0854e43c550c953b8c1cecbac58bb822.apk"]
> 2016-04-21 01:57:05:357 - info: [debug] [AndroidDriver] Checking if app is
> installed
> 2016-04-21 01:57:05:358 - info: [debug] [ADB] Getting install status for
> org.apache.mobilespec
> 2016-04-21 01:57:05:358 - info: [debug] [ADB] Getting connected devices...
> 2016-04-21 01:57:12:904 - info: [debug] [ADB] 1 device(s) connected
> 2016-04-21 01:57:12:904 - info: [debug] [ADB] Running
> C:\Android\android-sdk\platform-tools\adb.exe with args:
> ["-P",5037,"-s","emulator-5554","-s","emulator-5554","shell","getprop","ro.build.version.sdk"]
> 2016-04-21 01:57:20:907 - info: [debug] [ADB] Device API level: 19
> 2016-04-21 01:57:20:907 - info: [debug] [ADB] Getting connected devices...
> 2016-04-21 01:57:28:343 - info: [debug] [ADB] 1 device(s) connected
> 2016-04-21 01:57:28:343 - info: [debug] [ADB] Running
> C:\Android\android-sdk\platform-tools\adb.exe with args:
> ["-P",5037,"-s","emulator-5554","-s","emulator-5554","shell","pm","list","packages","-3","org.apache.mobilespec"]
> 2016-04-21 01:57:38:507 - info: [debug] [ADB] App is not installed
> 2016-04-21 01:57:38:508 - info: [AndroidDriver] Apk is not yet installed
> 2016-04-21 01:57:38:508 - info: [AndroidDriver] installing apk from remote
> 2016-04-21 01:57:38:508 - info: [debug] [ADB] Getting connected devices...
> 2016-04-21 01:57:46:244 - info: [debug] [ADB] 1 device(s) connected
> 2016-04-21 01:57:46:244 - info: [debug] [ADB] Running
> C:\Android\android-sdk\platform-tools\adb.exe with args:
> ["-P",5037,"-s","emulator-5554","-s","emulator-5554","shell","mkdir","-p","/data/local/tmp"]
> 2016-04-21 01:57:53:760 - info: [AndroidDriver] Clearing out any existing
> remote apks with the same hash
> 2016-04-21 01:57:53:761 - info: [debug] [AndroidDriver] Removing any old apks
> 2016-04-21 01:57:53:761 - info: [debug] [AndroidDriver] Except
> ["0854e43c550c953b8c1cecbac58bb822"]
> 2016-04-21 01:57:53:761 - info: [debug] [ADB] Getting connected devices...
> 2016-04-21 01:58:01:505 - info: [debug] [ADB] 1 device(s) connected
> 2016-04-21 01:58:01:505 - info: [debug] [ADB] Running
> C:\Android\android-sdk\platform-tools\adb.exe with args:
> ["-P",5037,"-s","emulator-5554","-s","emulator-5554","shell","ls","/data/local/tmp/*.apk"]
> 2016-04-21 01:58:09:480 - info: [debug] [ADB] Uninstalling
> org.apache.mobilespec
> 2016-04-21 01:58:09:480 - info: [debug] [ADB] Getting connected devices...
> 2016-04-21 01:58:17:269 - info: [debug] [ADB] 1 device(s) connected
> 2016-04-21 01:58:17:269 - info: [debug] [ADB] Running
> C:\Android\android-sdk\platform-tools\adb.exe with args:
> ["-P",5037,"-s","emulator-5554","-s","emulator-5554","shell","am","force-stop","org.apache.mobilespec"]
> 2016-04-21 01:58:27:358 - info: [debug] [ADB] Running
> C:\Android\android-sdk\platform-tools\adb.exe with args:
> ["-P",5037,"-s","emulator-5554","-s","emulator-5554","uninstall","org.apache.mobilespec"]
> 2016-04-21 01:58:37:466 - info: [ADB] App was not uninstalled, maybe it
> wasn't on device?
> 2016-04-21 01:58:37:466 - info: [debug] [ADB] Getting connected devices...
> 2016-04-21 01:58:46:090 - info: [debug] [ADB] 1 device(s) connected
> 2016-04-21 01:58:46:090 - info: [debug] [ADB] Running
> C:\Android\android-sdk\platform-tools\adb.exe with args:
> ["-P",5037,"-s","emulator-5554","-s","emulator-5554","shell","pm","install","-r","/data/local/tmp/0854e43c550c953b8c1cecbac58bb822.apk"]
> 2016-04-21 01:59:00:846 - info: [debug] [AndroidDriver] Extracting strings
> from apk
> C:\Users\buildbot\slaves\apache\cordova-android-win\mobilespec\platforms\android\build\outputs\apk\android-debug.apk
> null C:\Users\buildbot\AppData\Local\Temp\org.apache.mobilespec
> 2016-04-21 01:59:00:847 - info: [debug] [ADB] Extracting strings for
> language: default
> 2016-04-21 01:59:00:847 - info: [debug] [ADB] Getting connected devices...
> 2016-04-21 01:59:08:746 - info: [debug] [ADB] 1 device(s) connected
> 2016-04-21 01:59:08:747 - info: [debug] [ADB] Running
> C:\Android\android-sdk\platform-tools\adb.exe with args:
> ["-P",5037,"-s","emulator-5554","-s","emulator-5554","shell","getprop","persist.sys.language"]
> 2016-04-21 01:59:16:398 - info: [debug] [ADB] Current device property
> 'persist.sys.language': en
> 2016-04-21 02:00:19:858 - info: [debug] [ADB] No strings.xml for language
> 'en', getting default strings.xml
> 2016-04-21 02:01:23:713 - info: [debug] [ADB] Reading strings from converted
> strings.json
> 2016-04-21 02:01:24:566 - info: [debug] [ADB] Running
> C:\Android\android-sdk\platform-tools\adb.exe with args:
> ["-P",5037,"-s","emulator-5554","-s","emulator-5554","push","C:\\Users\\buildbot\\AppData\\Local\\Temp\\org.apache.mobilespec\\strings.json","/data/local/tmp"]
> 2016-04-21 02:01:33:805 - info: [debug] [AndroidBootstrap] Watching for
> bootstrap disconnect
> 2016-04-21 02:01:33:806 - info: [debug] [ADB] Forwarding system: 4724 to
> device: 4724
> 2016-04-21 02:01:33:806 - info: [debug] [ADB] Running
> C:\Android\android-sdk\platform-tools\adb.exe with args:
> ["-P",5037,"-s","emulator-5554","-s","emulator-5554","forward","tcp:4724","tcp:4724"]
> 2016-04-21 02:01:34:868 - info: [debug] [UiAutomator] Starting UiAutomator
> 2016-04-21 02:01:34:868 - info: [debug] [UiAutomator] Moving to state
> 'starting'
> 2016-04-21 02:01:34:869 - info: [debug] [UiAutomator] Parsing uiautomator jar
> 2016-04-21 02:01:34:869 - info: [debug] [UiAutomator] Found jar name:
> 'AppiumBootstrap.jar'
> 2016-04-21 02:01:34:869 - info: [debug] [ADB] Running
> C:\Android\android-sdk\platform-tools\adb.exe with args:
> ["-P",5037,"-s","emulator-5554","-s","emulator-5554","push","C:\\Users\\buildbot\\slaves\\apache\\cordova-android-win\\cordova-medic\\node_modules\\appium\\node_modules\\appium-android-bootstrap\\bootstrap\\bin\\AppiumBootstrap.jar","/data/local/tmp/"]
> 2016-04-21 02:01:44:673 - info: [debug] [ADB] Attempting to kill all
> uiautomator processes
> 2016-04-21 02:01:44:673 - info: [debug] [ADB] Getting all processes with
> uiautomator
> 2016-04-21 02:01:44:673 - info: [debug] [ADB] Getting connected devices...
> 2016-04-21 02:01:52:257 - info: [debug] [ADB] 1 device(s) connected
> 2016-04-21 02:01:52:257 - info: [debug] [ADB] Running
> C:\Android\android-sdk\platform-tools\adb.exe with args:
> ["-P",5037,"-s","emulator-5554","-s","emulator-5554","shell","ps","uiautomator"]
> 2016-04-21 02:01:59:764 - info: [ADB] No uiautomator process found to kill,
> continuing...
> 2016-04-21 02:01:59:764 - info: [debug] [UiAutomator] Starting UIAutomator
> 2016-04-21 02:01:59:764 - info: [debug] [ADB] Creating ADB subprocess with
> args: -P, 5037, -s, emulator-5554, -s, emulator-5554, shell, uiautomator,
> runtest, AppiumBootstrap.jar, -c, io.appium.android.bootstrap.Bootstrap, -e,
> pkg, org.apache.mobilespec, -e, disableAndroidWatchers, false, -e,
> acceptSslCerts, false
> 2016-04-21 02:02:10:266 - info: [debug] [UiAutomator] Moving to state 'online'
> 2016-04-21 02:02:10:268 - info: [AndroidBootstrap] Android bootstrap socket
> is now connected
> 2016-04-21 02:02:10:268 - info: [debug] [ADB] Getting connected devices...
> 2016-04-21 02:02:11:452 - info: [AndroidBootstrap] [BOOTSTRAP LOG] [debug]
> Loading json...
> 2016-04-21 02:02:11:452 - info: [AndroidBootstrap] [BOOTSTRAP LOG] [debug]
> json loading complete.
> 2016-04-21 02:02:11:452 - info: [AndroidBootstrap] [BOOTSTRAP LOG] [debug]
> Registered crash watchers.
> 2016-04-21 02:02:11:452 - info: [AndroidBootstrap] [BOOTSTRAP LOG] [debug]
> Client connected
> 2016-04-21 02:02:17:685 - info: [debug] [ADB] 1 device(s) connected
> 2016-04-21 02:02:17:686 - info: [debug] [ADB] Running
> C:\Android\android-sdk\platform-tools\adb.exe with args:
> ["-P",5037,"-s","emulator-5554","-s","emulator-5554","shell","dumpsys","window"]
> 2016-04-21 02:02:27:419 - info: [AndroidDriver] Screen already unlocked,
> doing nothing
> 2016-04-21 02:02:27:419 - info: [debug] [ADB] Device API level: 19
> 2016-04-21 02:02:27:420 - info: [debug] [ADB] Getting connected devices...
> 2016-04-21 02:02:35:356 - info: [debug] [ADB] 1 device(s) connected
> 2016-04-21 02:02:35:357 - info: [debug] [ADB] Running
> C:\Android\android-sdk\platform-tools\adb.exe with args:
> ["-P",5037,"-s","emulator-5554","-s","emulator-5554","shell","am","start","-n","org.apache.mobilespec/org.apache.mobilespec.MainActivity","-S","-a","android.intent.action.MAIN","-c","android.intent.category.LAUNCHER","-f","0x10200000"]
> 2016-04-21 02:02:49:055 - info: [debug] [ADB] Waiting for pkg:
> 'org.apache.mobilespec' and activity: 'org.apache.mobilespec.MainActivity' to
> be focused
> 2016-04-21 02:02:49:055 - info: [debug] [ADB] Possible activities, to be
> checked: org.apache.mobilespec.MainActivity, .MainActivity,
> .org.apache.mobilespec.MainActivity
> 2016-04-21 02:02:49:056 - info: [debug] [ADB] Getting focused package and
> activity
> 2016-04-21 02:02:49:056 - info: [debug] [ADB] Getting connected devices...
> 2016-04-21 02:02:57:528 - info: [debug] [ADB] 1 device(s) connected
> 2016-04-21 02:02:57:528 - info: [debug] [ADB] Running
> C:\Android\android-sdk\platform-tools\adb.exe with args:
> ["-P",5037,"-s","emulator-5554","-s","emulator-5554","shell","dumpsys","window","windows"]
> 2016-04-21 02:03:05:248 - info: [debug] [ADB] Found package:
> 'org.apache.mobilespec' and activity: '.MainActivity'
> 2016-04-21 02:03:05:249 - info: [Appium] New AndroidDriver session created
> successfully, session 9a9dd635-643a-43b1-a61c-c42225b79d35 added to master
> session list
> 2016-04-21 02:03:05:249 - info: [MJSONWP] Responding to client with
> driver.createSession() result:
> {"platform":"LINUX","webStorageEnabled":false,"takesScreenshot":true,"javascriptEnabled":true,"databaseEnabled":false,"networkConnectionEnabled":tr...
> 2016-04-21 02:03:05:250 - info: [HTTP] <-- POST /wd/hub/session 200 561389 ms
> - 929
> 2016-04-21 02:03:05:257 - info: [HTTP] --> POST
> /wd/hub/session/9a9dd635-643a-43b1-a61c-c42225b79d35/timeouts/implicit_wait
> 2016-04-21 02:03:05:258 - info: [MJSONWP] Calling AppiumDriver.implicitWait()
> with args: [10000,"9a9dd635-643a-43b1-a61c-c42225b79d35"]
> 2016-04-21 02:03:05:259 - info: [debug] [BaseDriver] Set implicit wait to
> 10000ms
> 2016-04-21 02:03:05:259 - info: [MJSONWP] Responding to client with
> driver.implicitWait() result: null
> 2016-04-21 02:03:05:260 - info: [HTTP] <-- POST
> /wd/hub/session/9a9dd635-643a-43b1-a61c-c42225b79d35/timeouts/implicit_wait
> 200 2 ms - 76
> 2016-04-21 02:03:05:288 - info: [HTTP] --> GET
> /wd/hub/session/9a9dd635-643a-43b1-a61c-c42225b79d35/contexts
> 2016-04-21 02:03:05:289 - info: [HTTP] --> POST
> /wd/hub/session/9a9dd635-643a-43b1-a61c-c42225b79d35/context
> 2016-04-21 02:03:05:290 - info: [debug] [MJSONWP] Bad parameters:
> BadParametersError: Parameters were incorrect. We wanted
> {"required":["name"]} and you sent []
> 2016-04-21 02:03:05:291 - info: [HTTP] <-- POST
> /wd/hub/session/9a9dd635-643a-43b1-a61c-c42225b79d35/context 400 2 ms - 74
> 2016-04-21 02:03:05:508 - info: [MJSONWP] Calling AppiumDriver.getContexts()
> with args: ["9a9dd635-643a-43b1-a61c-c42225b79d35"]
> 2016-04-21 02:03:05:508 - info: [debug] [AndroidDriver] Getting a list of
> available webviews
> 2016-04-21 02:03:05:509 - info: [debug] [ADB] Getting connected devices...
> 2016-04-21 02:03:06:806 - info: [HTTP] --> GET
> /wd/hub/session/9a9dd635-643a-43b1-a61c-c42225b79d35/context
> 2016-04-21 02:03:07:063 - info: [MJSONWP] Calling
> AppiumDriver.getCurrentContext() with args:
> ["9a9dd635-643a-43b1-a61c-c42225b79d35"]
> 2016-04-21 02:03:12:973 - info: [debug] [ADB] 1 device(s) connected
> 2016-04-21 02:03:12:973 - info: [debug] [ADB] Running
> C:\Android\android-sdk\platform-tools\adb.exe with args:
> ["-P",5037,"-s","emulator-5554","-s","emulator-5554","shell","cat","/proc/net/unix"]
> 2016-04-21 02:03:21:515 - info: [debug] [AndroidDriver] Found webviews: []
> 2016-04-21 02:03:21:516 - info: [debug] [AndroidDriver] Available contexts:
> ["NATIVE_APP"]
> 2016-04-21 02:03:21:516 - info: [MJSONWP] Responding to client with
> driver.getContexts() result: ["NATIVE_APP"]
> 2016-04-21 02:03:21:517 - info: [HTTP] <-- GET
> /wd/hub/session/9a9dd635-643a-43b1-a61c-c42225b79d35/contexts 200 16230 ms -
> 86
> 2016-04-21 02:03:21:517 - info: [MJSONWP] Responding to client with
> driver.getCurrentContext() result: "NATIVE_APP"
> 2016-04-21 02:03:21:518 - info: [HTTP] <-- GET
> /wd/hub/session/9a9dd635-643a-43b1-a61c-c42225b79d35/context 200 14712 ms -
> 84
> 2016-04-21 02:03:21:536 - info: [HTTP] --> POST
> /wd/hub/session/9a9dd635-643a-43b1-a61c-c42225b79d35/context
> 2016-04-21 02:03:21:537 - info: [MJSONWP] Calling AppiumDriver.setContext()
> with args: ["NATIVE_APP","9a9dd635-643a-43b1-a61c-c42225b79d35"]
> 2016-04-21 02:03:21:537 - info: [debug] [AndroidDriver] Getting a list of
> available webviews
> 2016-04-21 02:03:21:537 - info: [debug] [ADB] Getting connected devices...
> 2016-04-21 02:03:26:541 - info: [HTTP] --> GET
> /wd/hub/session/9a9dd635-643a-43b1-a61c-c42225b79d35/contexts
> 2016-04-21 02:03:26:634 - info: [MJSONWP] Calling AppiumDriver.getContexts()
> with args: ["9a9dd635-643a-43b1-a61c-c42225b79d35"]
> 2016-04-21 02:03:29:021 - info: [debug] [ADB] 1 device(s) connected
> 2016-04-21 02:03:29:021 - info: [debug] [ADB] Running
> C:\Android\android-sdk\platform-tools\adb.exe with args:
> ["-P",5037,"-s","emulator-5554","-s","emulator-5554","shell","cat","/proc/net/unix"]
> 2016-04-21 02:03:37:376 - info: [debug] [AndroidDriver] Found webviews: []
> 2016-04-21 02:03:37:376 - info: [debug] [AndroidDriver] Available contexts:
> ["NATIVE_APP"]
> 2016-04-21 02:03:37:377 - info: [debug] [AndroidDriver] Getting a list of
> available webviews
> 2016-04-21 02:03:37:377 - info: [debug] [ADB] Getting connected devices...
> 2016-04-21 02:03:38:742 - info: [MJSONWP] Responding to client with
> driver.setContext() result: null
> 2016-04-21 02:03:38:743 - info: [HTTP] <-- POST
> /wd/hub/session/9a9dd635-643a-43b1-a61c-c42225b79d35/context 200 17206 ms -
> 76
> 2016-04-21 02:03:38:751 - info: [HTTP] --> GET
> /wd/hub/session/9a9dd635-643a-43b1-a61c-c42225b79d35/screenshot
> 2016-04-21 02:03:39:009 - info: [MJSONWP] Calling
> AppiumDriver.getScreenshot() with args:
> ["9a9dd635-643a-43b1-a61c-c42225b79d35"]
> 2016-04-21 02:03:43:878 - info: [HTTP] --> POST
> /wd/hub/session/9a9dd635-643a-43b1-a61c-c42225b79d35/context
> 2016-04-21 02:03:43:880 - info: [debug] [MJSONWP] Bad parameters:
> BadParametersError: Parameters were incorrect. We wanted
> {"required":["name"]} and you sent []
> 2016-04-21 02:03:43:881 - info: [HTTP] <-- POST
> /wd/hub/session/9a9dd635-643a-43b1-a61c-c42225b79d35/context 400 3 ms - 74
> 2016-04-21 02:03:43:912 - info: [HTTP] --> POST
> /wd/hub/session/9a9dd635-643a-43b1-a61c-c42225b79d35/context
> 2016-04-21 02:03:43:913 - info: [debug] [MJSONWP] Bad parameters:
> BadParametersError: Parameters were incorrect. We wanted
> {"required":["name"]} and you sent []
> 2016-04-21 02:03:43:914 - info: [HTTP] <-- POST
> /wd/hub/session/9a9dd635-643a-43b1-a61c-c42225b79d35/context 400 2 ms - 74
> 2016-04-21 02:03:43:934 - info: [HTTP] --> GET
> /wd/hub/session/9a9dd635-643a-43b1-a61c-c42225b79d35/context
> 2016-04-21 02:03:44:094 - info: [MJSONWP] Calling
> AppiumDriver.getCurrentContext() with args:
> ["9a9dd635-643a-43b1-a61c-c42225b79d35"]
> 2016-04-21 02:03:44:895 - info: [debug] [ADB] 1 device(s) connected
> 2016-04-21 02:03:44:896 - info: [debug] [ADB] Running
> C:\Android\android-sdk\platform-tools\adb.exe with args:
> ["-P",5037,"-s","emulator-5554","-s","emulator-5554","shell","cat","/proc/net/unix"]
> 2016-04-21 02:03:52:820 - info: [debug] [AndroidDriver] Found webviews: []
> 2016-04-21 02:03:52:821 - info: [debug] [AndroidDriver] Available contexts:
> ["NATIVE_APP"]
> 2016-04-21 02:03:52:822 - info: [debug] [ADB] Getting connected devices...
> 2016-04-21 02:03:54:336 - info: [MJSONWP] Responding to client with
> driver.getContexts() result: ["NATIVE_APP"]
> 2016-04-21 02:03:54:337 - info: [HTTP] <-- GET
> /wd/hub/session/9a9dd635-643a-43b1-a61c-c42225b79d35/contexts 200 27795 ms -
> 86
> 2016-04-21 02:03:59:360 - info: [HTTP] --> GET
> /wd/hub/session/9a9dd635-643a-43b1-a61c-c42225b79d35/contexts
> 2016-04-21 02:03:59:611 - info: [MJSONWP] Calling AppiumDriver.getContexts()
> with args: ["9a9dd635-643a-43b1-a61c-c42225b79d35"]
> 2016-04-21 02:04:02:437 - info: [debug] [ADB] 1 device(s) connected
> 2016-04-21 02:04:02:438 - info: [debug] [ADB] Running
> C:\Android\android-sdk\platform-tools\adb.exe with args:
> ["-P",5037,"-s","emulator-5554","-s","emulator-5554","shell","/system/bin/rm","/data/local/tmp/screenshot.png;","/system/bin/screencap","-p","/data/local/tmp/screenshot.png"]
> 2016-04-21 02:04:13:515 - info: [debug] [ADB] Running
> C:\Android\android-sdk\platform-tools\adb.exe with args:
> ["-P",5037,"-s","emulator-5554","-s","emulator-5554","pull","/data/local/tmp/screenshot.png","C:\\Users\\buildbot\\AppData\\Local\\Temp\\appium116320-7464-1smu9fj.png"]
> 2016-04-21 02:04:26:187 - info: [MJSONWP] Responding to client with
> driver.getScreenshot() result:
> "iVBORw0KGgoAAAANSUhEUgAAAtAAAAUACAYAAABuzmU9AAAABHNCSVQICAgIfAhkiAAAIABJREFUeJzs3Xt0nXd56Plna0vbutmSfEscxw7O3bmdQAxJSEJIuEwhlDA9PdNCB1Y4hUWBFHrmdJ...
> 2016-04-21 02:04:26:188 - info: [HTTP] <-- GET
> /wd/hub/session/9a9dd635-643a-43b1-a61c-c42225b79d35/screenshot 200 47435 ms
> - 24766
> 2016-04-21 02:04:26:189 - info: [debug] [AndroidDriver] Getting a list of
> available webviews
> 2016-04-21 02:04:26:189 - info: [debug] [ADB] Getting connected devices...
> 2016-04-21 02:04:27:061 - info: [MJSONWP] Responding to client with
> driver.getCurrentContext() result: "NATIVE_APP"
> 2016-04-21 02:04:27:062 - info: [HTTP] <-- GET
> /wd/hub/session/9a9dd635-643a-43b1-a61c-c42225b79d35/context 200 43125 ms -
> 84
> 2016-04-21 02:04:27:067 - info: [HTTP] --> POST
> /wd/hub/session/9a9dd635-643a-43b1-a61c-c42225b79d35/context
> 2016-04-21 02:04:27:068 - info: [MJSONWP] Calling AppiumDriver.setContext()
> with args: ["NATIVE_APP","9a9dd635-643a-43b1-a61c-c42225b79d35"]
> 2016-04-21 02:04:27:068 - info: [HTTP] --> POST
> /wd/hub/session/9a9dd635-643a-43b1-a61c-c42225b79d35/context
> 2016-04-21 02:04:27:069 - info: [MJSONWP] Calling AppiumDriver.setContext()
> with args: ["NATIVE_APP","9a9dd635-643a-43b1-a61c-c42225b79d35"]
> 2016-04-21 02:04:33:845 - info: [debug] [ADB] 1 device(s) connected
> 2016-04-21 02:04:33:846 - info: [debug] [ADB] Running
> C:\Android\android-sdk\platform-tools\adb.exe with args:
> ["-P",5037,"-s","emulator-5554","-s","emulator-5554","shell","cat","/proc/net/unix"]
> 2016-04-21 02:04:42:137 - info: [debug] [AndroidDriver] Found webviews: []
> 2016-04-21 02:04:42:137 - info: [debug] [AndroidDriver] Available contexts:
> ["NATIVE_APP"]
> 2016-04-21 02:04:42:138 - info: [debug] [AndroidDriver] Getting a list of
> available webviews
> 2016-04-21 02:04:42:138 - info: [debug] [ADB] Getting connected devices...
> 2016-04-21 02:04:43:513 - info: [MJSONWP] Responding to client with
> driver.getContexts() result: ["NATIVE_APP"]
> 2016-04-21 02:04:43:513 - info: [HTTP] <-- GET
> /wd/hub/session/9a9dd635-643a-43b1-a61c-c42225b79d35/contexts 200 44156 ms -
> 86
> 2016-04-21 02:04:50:544 - info: [debug] [ADB] 1 device(s) connected
> 2016-04-21 02:04:50:544 - info: [debug] [ADB] Running
> C:\Android\android-sdk\platform-tools\adb.exe with args:
> ["-P",5037,"-s","emulator-5554","-s","emulator-5554","shell","cat","/proc/net/unix"]
> 2016-04-21 02:04:58:744 - info: [debug] [AndroidDriver] Found webviews: []
> 2016-04-21 02:04:58:745 - info: [debug] [AndroidDriver] Available contexts:
> ["NATIVE_APP"]
> 2016-04-21 02:04:58:745 - info: [debug] [AndroidDriver] Getting a list of
> available webviews
> 2016-04-21 02:04:58:745 - info: [debug] [ADB] Getting connected devices...
> 2016-04-21 02:05:00:105 - info: [MJSONWP] Responding to client with
> driver.setContext() result: null
> 2016-04-21 02:05:00:106 - info: [HTTP] <-- POST
> /wd/hub/session/9a9dd635-643a-43b1-a61c-c42225b79d35/context 200 33038 ms -
> 76
> 2016-04-21 02:05:00:112 - info: [HTTP] --> DELETE
> /wd/hub/session/9a9dd635-643a-43b1-a61c-c42225b79d35
> 2016-04-21 02:05:00:113 - info: [MJSONWP] Calling
> AppiumDriver.deleteSession() with args:
> ["9a9dd635-643a-43b1-a61c-c42225b79d35"]
> 2016-04-21 02:05:00:113 - info: [debug] [AndroidDriver] Shutting down Android
> driver
> 2016-04-21 02:05:00:114 - info: [debug] [ADB] Getting connected devices...
> 2016-04-21 02:05:07:356 - info: [debug] [ADB] 1 device(s) connected
> 2016-04-21 02:05:07:357 - info: [debug] [ADB] Running
> C:\Android\android-sdk\platform-tools\adb.exe with args:
> ["-P",5037,"-s","emulator-5554","-s","emulator-5554","shell","cat","/proc/net/unix"]
> 2016-04-21 02:05:08:715 - info: [debug] [ADB] 1 device(s) connected
> 2016-04-21 02:05:08:715 - info: [debug] [ADB] Running
> C:\Android\android-sdk\platform-tools\adb.exe with args:
> ["-P",5037,"-s","emulator-5554","-s","emulator-5554","shell","am","force-stop","org.apache.mobilespec"]
> 2016-04-21 02:05:15:824 - info: [debug] [AndroidDriver] Found webviews: []
> 2016-04-21 02:05:15:824 - info: [debug] [AndroidDriver] Available contexts:
> ["NATIVE_APP"]
> 2016-04-21 02:05:15:824 - info: [MJSONWP] Responding to client with
> driver.setContext() result: null
> 2016-04-21 02:05:15:825 - info: [HTTP] <-- POST
> /wd/hub/session/9a9dd635-643a-43b1-a61c-c42225b79d35/context 200 48757 ms -
> 76
> 2016-04-21 02:05:15:830 - info: [HTTP] --> GET
> /wd/hub/session/9a9dd635-643a-43b1-a61c-c42225b79d35/screenshot
> 2016-04-21 02:05:16:099 - info: [HTTP] <-- GET
> /wd/hub/session/9a9dd635-643a-43b1-a61c-c42225b79d35/screenshot 404 268 ms -
> 131
> 2016-04-21 02:05:19:294 - info: [debug] [ADB] Pressing the HOME button
> 2016-04-21 02:05:19:295 - info: [debug] [ADB] Getting connected devices...
> 2016-04-21 02:05:27:907 - info: [debug] [ADB] 1 device(s) connected
> 2016-04-21 02:05:27:907 - info: [debug] [ADB] Running
> C:\Android\android-sdk\platform-tools\adb.exe with args:
> ["-P",5037,"-s","emulator-5554","-s","emulator-5554","shell","input","keyevent",3]
> 2016-04-21 02:05:40:170 - info: [debug] [Logcat] Stopping logcat capture
> 2016-04-21 02:05:40:664 - info: [debug] [AndroidBootstrap] Sending command to
> android: {"cmd":"shutdown"}
> 2016-04-21 02:05:40:684 - info: [AndroidBootstrap] [BOOTSTRAP LOG] [debug]
> Got data from client: {"cmd":"shutdown"}
> 2016-04-21 02:05:40:706 - info: [AndroidBootstrap] [BOOTSTRAP LOG] [debug]
> Got command of type SHUTDOWN
> 2016-04-21 02:05:40:716 - info: [AndroidBootstrap] [BOOTSTRAP LOG] [debug]
> Returning result: {"value":"OK, shutting down","status":0}
> 2016-04-21 02:05:40:718 - info: [debug] [AndroidBootstrap] Received command
> result from bootstrap
> 2016-04-21 02:05:40:719 - info: [debug] [UiAutomator] Shutting down
> UiAutomator
> 2016-04-21 02:05:40:719 - info: [debug] [UiAutomator] Moving to state
> 'stopping'
> 2016-04-21 02:05:41:087 - info: [AndroidBootstrap] [BOOTSTRAP LOG] [debug]
> Closed client connection
> 2016-04-21 02:05:41:087 - info: [debug] [AndroidBootstrap] [UIAUTO STDOUT]
> INSTRUMENTATION_STATUS: numtests=1
> 2016-04-21 02:05:41:088 - info: [debug] [AndroidBootstrap] [UIAUTO STDOUT]
> INSTRUMENTATION_STATUS: stream=.
> 2016-04-21 02:05:41:088 - info: [debug] [AndroidBootstrap] [UIAUTO STDOUT]
> INSTRUMENTATION_STATUS: id=UiAutomatorTestRunner
> 2016-04-21 02:05:41:088 - info: [debug] [AndroidBootstrap] [UIAUTO STDOUT]
> INSTRUMENTATION_STATUS: test=testRunServer
> 2016-04-21 02:05:41:088 - info: [debug] [AndroidBootstrap] [UIAUTO STDOUT]
> INSTRUMENTATION_STATUS: class=io.appium.android.bootstrap.Bootstrap
> 2016-04-21 02:05:41:088 - info: [debug] [AndroidBootstrap] [UIAUTO STDOUT]
> INSTRUMENTATION_STATUS: current=1
> 2016-04-21 02:05:41:088 - info: [debug] [AndroidBootstrap] [UIAUTO STDOUT]
> INSTRUMENTATION_STATUS_CODE: 0
> 2016-04-21 02:05:41:088 - info: [debug] [AndroidBootstrap] [UIAUTO STDOUT]
> INSTRUMENTATION_STATUS: stream=
> 2016-04-21 02:05:41:089 - info: [debug] [AndroidBootstrap] [UIAUTO STDOUT]
> Test results for WatcherResultPrinter=.
> 2016-04-21 02:05:41:089 - info: [debug] [AndroidBootstrap] [UIAUTO STDOUT]
> Time: 210.905
> 2016-04-21 02:05:41:089 - info: [debug] [AndroidBootstrap] [UIAUTO STDOUT] OK
> (1 test)
> 2016-04-21 02:05:41:089 - info: [debug] [AndroidBootstrap] [UIAUTO STDOUT]
> INSTRUMENTATION_STATUS_CODE: -1
> 2016-04-21 02:05:41:167 - info: [debug] [UiAutomator] UiAutomator shut down
> normally
> 2016-04-21 02:05:41:167 - info: [debug] [UiAutomator] Moving to state
> 'stopped'
> 2016-04-21 02:05:41:167 - info: [debug] [ADB] Attempting to kill all
> uiautomator processes
> 2016-04-21 02:05:41:168 - info: [debug] [ADB] Getting all processes with
> uiautomator
> 2016-04-21 02:05:41:168 - info: [debug] [ADB] Getting connected devices...
> 2016-04-21 02:05:50:266 - info: [debug] [ADB] 1 device(s) connected
> 2016-04-21 02:05:50:266 - info: [debug] [ADB] Running
> C:\Android\android-sdk\platform-tools\adb.exe with args:
> ["-P",5037,"-s","emulator-5554","-s","emulator-5554","shell","ps","uiautomator"]
> 2016-04-21 02:05:58:461 - info: [ADB] No uiautomator process found to kill,
> continuing...
> 2016-04-21 02:05:58:461 - info: [debug] [UiAutomator] Moving to state
> 'stopped'
> 2016-04-21 02:05:58:461 - info: [Appium] Removing session
> 9a9dd635-643a-43b1-a61c-c42225b79d35 from our master session list
> 2016-04-21 02:05:58:462 - info: [debug] [MJSONWP] Received response: null
> 2016-04-21 02:05:58:462 - info: [debug] [MJSONWP] But deleting session, so
> not returning
> 2016-04-21 02:05:58:462 - info: [MJSONWP] Responding to client with
> driver.deleteSession() result: null
> 2016-04-21 02:05:58:463 - info: [HTTP] <-- DELETE
> /wd/hub/session/9a9dd635-643a-43b1-a61c-c42225b79d35 200 58350 ms - 76
> 2016-04-21 02:05:58:480 - info: [HTTP] --> POST /wd/hub/session
> 2016-04-21 02:05:58:481 - info: [MJSONWP] Calling
> AppiumDriver.createSession() with args:
> [{"browserName":"","appium-version":"1.5","platformName":"Android","platformVersion":19,"deviceName":"Galaxy_Nexus","app":"C:\\Users\\buildbot\\sla...
> 2016-04-21 02:05:58:481 - info: [Appium] Creating new AndroidDriver session
> 2016-04-21 02:05:58:482 - info: [Appium] Capabilities:
> 2016-04-21 02:05:58:482 - info: [Appium] browserName: ''
> 2016-04-21 02:05:58:482 - info: [Appium] appium-version: '1.5'
> 2016-04-21 02:05:58:482 - info: [Appium] platformName: 'Android'
> 2016-04-21 02:05:58:482 - info: [Appium] platformVersion: 19
> 2016-04-21 02:05:58:482 - info: [Appium] deviceName: 'Galaxy_Nexus'
> 2016-04-21 02:05:58:483 - info: [Appium] app:
> 'C:\\Users\\buildbot\\slaves\\apache\\cordova-android-win\\mobilespec\\platforms\\android\\build\\outputs\\apk\\android-debug.apk'
> 2016-04-21 02:05:58:483 - info: [Appium] autoAcceptAlerts: true
> 2016-04-21 02:05:58:483 - info: [Appium] automationName: 'Appium'
> 2016-04-21 02:05:58:483 - info: [Appium] avd: 'Galaxy_Nexus'
> 2016-04-21 02:05:58:486 - warn: [BaseDriver] The following capabilities were
> provided, but are not recognized by appium: appium-version, autoAcceptAlerts.
> 2016-04-21 02:05:58:486 - info: [BaseDriver] Session created with session id:
> 9861e32d-e4b4-4beb-94dd-41b45e0b75e7
> 2016-04-21 02:05:58:487 - info: [debug] [AndroidDriver] Getting Java version
> 2016-04-21 02:06:15:827 - warn: [BaseDriver] Shutting down because we waited
> 60 seconds for a command
> 2016-04-21 02:06:15:828 - info: [debug] [AndroidDriver] Shutting down Android
> driver
> 2016-04-21 02:06:15:829 - warn: [Appium] Closing session, cause was 'New
> Command Timeout of 60 seconds expired. Try customizing the timeout using the
> 'newCommandTimeout' desired capability'
> 2016-04-21 02:06:15:829 - info: [Appium] Removing session
> 9a9dd635-643a-43b1-a61c-c42225b79d35 from our master session list
> 2016-04-21 02:06:15:829 - warn: [AndroidDriver] Cannot shut down Android
> driver; it has already shut down
> 2016-04-21 02:06:43:954 - info: [AndroidDriver] Java version is: 1.8.0_77
> 2016-04-21 02:06:43:954 - info: [ADB] Checking whether adb is present
> 2016-04-21 02:06:45:640 - info: [ADB] Using adb.exe from
> C:\Android\android-sdk\platform-tools\adb.exe
> 2016-04-21 02:06:45:640 - info: [AndroidDriver] Retrieving device list
> 2016-04-21 02:06:45:640 - info: [debug] [ADB] Trying to find a connected
> android device
> 2016-04-21 02:06:45:641 - info: [debug] [ADB] Getting connected devices...
> 2016-04-21 02:06:53:686 - info: [debug] [ADB] 1 device(s) connected
> 2016-04-21 02:06:53:686 - info: [AndroidDriver] Using device: emulator-5554
> 2016-04-21 02:06:53:686 - info: [ADB] Checking whether adb is present
> 2016-04-21 02:06:55:291 - info: [ADB] Using adb.exe from
> C:\Android\android-sdk\platform-tools\adb.exe
> 2016-04-21 02:06:55:291 - info: [debug] [ADB] Setting device id to
> emulator-5554
> 2016-04-21 02:06:55:291 - info: [BaseDriver] Using local app
> 'C:\Users\buildbot\slaves\apache\cordova-android-win\mobilespec\platforms\android\build\outputs\apk\android-debug.apk'
> 2016-04-21 02:06:55:292 - info: [debug] [AndroidDriver] Checking whether app
> is actually present
> 2016-04-21 02:06:55:551 - info: [AndroidDriver] Starting Android session
> 2016-04-21 02:06:55:551 - info: [debug] [ADB] Trying to find Galaxy_Nexus
> emulator
> 2016-04-21 02:06:55:551 - info: [debug] [ADB] Getting connected emulators
> 2016-04-21 02:06:55:552 - info: [debug] [ADB] Getting connected devices...
> 2016-04-21 02:07:03:860 - info: [debug] [ADB] 1 device(s) connected
> 2016-04-21 02:07:03:861 - info: [debug] [ADB] 1 emulator(s) connected
> 2016-04-21 02:07:03:861 - info: [debug] [ADB] Sending telnet command to
> device: avd name
> 2016-04-21 02:07:03:861 - info: [debug] [ADB] Getting running emulator port
> 2016-04-21 02:07:03:863 - info: [debug] [ADB] Socket connection to device
> created
> 2016-04-21 02:07:03:863 - info: [debug] [ADB] Socket connection to device
> ready
> 2016-04-21 02:07:03:864 - info: [debug] [ADB] Telnet command got response:
> Galaxy_Nexus
> 2016-04-21 02:07:03:865 - info: [debug] [ADB] Found emulator Galaxy_Nexus in
> port 5554
> 2016-04-21 02:07:03:865 - info: [debug] [ADB] Setting device id to
> emulator-5554
> 2016-04-21 02:07:03:865 - info: [debug] [AndroidDriver] Not launching AVD
> because it is already running.
> 2016-04-21 02:07:03:866 - info: [debug] [ADB] Running
> C:\Android\android-sdk\platform-tools\adb.exe with args:
> ["-P",5037,"-s","emulator-5554","-s","emulator-5554","wait-for-device"]
> 2016-04-21 02:07:12:629 - info: [debug] [ADB] Getting connected devices...
> 2016-04-21 02:07:21:034 - info: [debug] [ADB] 1 device(s) connected
> 2016-04-21 02:07:21:034 - info: [debug] [ADB] Running
> C:\Android\android-sdk\platform-tools\adb.exe with args:
> ["-P",5037,"-s","emulator-5554","-s","emulator-5554","shell","echo","ping"]
> 2016-04-21 02:07:28:995 - info: [debug] [Logcat] Starting logcat capture
> 2016-04-21 02:07:37:077 - info: [debug] [AndroidDriver] Pushing settings apk
> to device...
> 2016-04-21 02:07:37:077 - info: [debug] [ADB] Running
> C:\Android\android-sdk\platform-tools\adb.exe with args:
> ["-P",5037,"-s","emulator-5554","-s","emulator-5554","install","C:\\Users\\buildbot\\slaves\\apache\\cordova-android-win\\cordova-medic\\node_modules\\appium\\node_modules\\io.appium.settings\\bin\\settings_apk-debug.apk"]
> 2016-04-21 02:07:49:091 - info: [debug] [AndroidDriver] Pushing unlock helper
> app to device...
> 2016-04-21 02:07:49:092 - info: [debug] [ADB] Running
> C:\Android\android-sdk\platform-tools\adb.exe with args:
> ["-P",5037,"-s","emulator-5554","-s","emulator-5554","install","C:\\Users\\buildbot\\slaves\\apache\\cordova-android-win\\cordova-medic\\node_modules\\appium\\node_modules\\appium-unlock\\bin\\unlock_apk-debug.apk"]
> 2016-04-21 02:08:02:031 - info: [ADB] Getting device platform version
> 2016-04-21 02:08:02:031 - info: [debug] [ADB] Getting connected devices...
> program finished with exit code 0
> {noformat}
--
This message was sent by Atlassian JIRA
(v6.3.4#6332)
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
