[AndroidBootstrap] Received command result from bootstrap
[UiAutomator] Shutting down UiAutomator
[UiAutomator] Moving to state 'stopping'
[AndroidBootstrap] [UIAUTO STDOUT] INSTRUMENTATION_STATUS: stream=
[AndroidBootstrap] [UIAUTO STDOUT] Test results for WatcherResultPrinter=.
[UiAutomator] UiAutomator shut down normally
[UiAutomator] Moving to state 'stopped'
[ADB] Attempting to kill all uiautomator processes
[ADB] Getting all processes with uiautomator
[ADB] Getting connected devices...
[ADB] 1 device(s) connected
[ADB] Running 'C:\Users\Chen Chen\AppData\Local\Android\Sdk\platform-tools\adb.exe' with args: ["-P",5037,"-s","emulator-5554","shell","ps"]
[ADB] No uiautomator process found to kill, continuing...
[UiAutomator] Moving to state 'stopped'
[Logcat] Stopping logcat capture
[ADB] Getting connected devices...
[ADB] 1 device(s) connected
[ADB] Running 'C:\Users\Chen Chen\AppData\Local\Android\Sdk\platform-tools\adb.exe' with args: ["-P",5037,"-s","emulator-5554","shell","am","force-stop","io.appium.unlock"]
[AndroidDriver] Not cleaning generated files. Add clearSystemFiles capability if wanted.
[Appium] Removing session f16844dd-c40b-4307-a6dd-41198d328088 from our master session list
[BaseDriver] Event 'quitSessionFinished' logged at 1505438116699 (21:15:16 GMT-0400 (Eastern Daylight Time))
[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/f16844dd-c40b-4307-a6dd-41198d328088 200 4866 ms - 76
STS log:
21:14:49.654 [Forwarding newSession on session null to remote] DEBUG o.a.h.c.protocol.RequestAddCookies - CookieSpec selected: best-match -21:14:49.680 [Forwarding newSession on session null to remote] DEBUG o.a.h.c.protocol.RequestAuthCache - Auth cache not set in the context -21:14:49.682 [Forwarding newSession on session null to remote] DEBUG o.a.h.i.c.PoolingHttpClientConnectionManager - Connection request: [route: {}->http://127.0.0.1:4723][total kept alive: 0; route allocated: 0 of 2000; total allocated: 0 of 2000] -21:14:49.705 [Forwarding newSession on session null to remote] DEBUG o.a.h.i.c.PoolingHttpClientConnectionManager - Connection leased: [id: 0][route: {}->http://127.0.0.1:4723][total kept alive: 0; route allocated: 1 of 2000; total allocated: 1 of 2000] -21:14:49.708 [Forwarding newSession on session null to remote] DEBUG o.a.h.impl.execchain.MainClientExec - Opening connection {}->http://127.0.0.1:4723 -21:14:49.709 [Forwarding newSession on session null to remote] DEBUG o.a.h.i.c.HttpClientConnectionOperator - Connecting to /127.0.0.1:4723 -21:14:49.711 [Forwarding newSession on session null to remote] DEBUG o.a.h.i.c.HttpClientConnectionOperator - Connection established 127.0.0.1:59495<->127.0.0.1:4723 -21:14:49.711 [Forwarding newSession on session null to remote] DEBUG o.a.h.impl.execchain.MainClientExec - Executing request POST /wd/hub/session HTTP/1.1 -21:14:49.711 [Forwarding newSession on session null to remote] DEBUG o.a.h.impl.execchain.MainClientExec - Target auth state: UNCHALLENGED -21:14:49.712 [Forwarding newSession on session null to remote] DEBUG o.a.h.impl.execchain.MainClientExec - Proxy auth state: UNCHALLENGED -21:14:49.713 [Forwarding newSession on session null to remote] DEBUG org.apache.http.headers - http-outgoing-0 >> POST /wd/hub/session HTTP/1.1 -21:14:49.713 [Forwarding newSession on session null to remote] DEBUG org.apache.http.headers - http-outgoing-0 >> Content-Type: application/json; charset=utf-8 -21:14:49.713 [Forwarding newSession on session null to remote] DEBUG org.apache.http.headers - http-outgoing-0 >> Content-Length: 659 -21:14:49.713 [Forwarding newSession on session null to remote] DEBUG org.apache.http.headers - http-outgoing-0 >> Host: 127.0.0.1:4723 -21:14:49.713 [Forwarding newSession on session null to remote] DEBUG org.apache.http.headers - http-outgoing-0 >> Connection: Keep-Alive -21:14:49.713 [Forwarding newSession on session null to remote] DEBUG org.apache.http.headers - http-outgoing-0 >> User-Agent: Apache-HttpClient/4.3.5 (java 1.5) -21:14:49.714 [Forwarding newSession on session null to remote] DEBUG org.apache.http.headers - http-outgoing-0 >> Accept-Encoding: gzip,deflate -21:14:49.715 [Forwarding newSession on session null to remote] DEBUG org.apache.http.wire - http-outgoing-0 >> "POST /wd/hub/session HTTP/1.1[\r][\n]" -21:14:49.715 [Forwarding newSession on session null to remote] DEBUG org.apache.http.wire - http-outgoing-0 >> "Content-Type: application/json; charset=utf-8[\r][\n]" -21:14:49.715 [Forwarding newSession on session null to remote] DEBUG org.apache.http.wire - http-outgoing-0 >> "Content-Length: 659[\r][\n]" -21:14:49.715 [Forwarding newSession on session null to remote] DEBUG org.apache.http.wire - http-outgoing-0 >> "Host: 127.0.0.1:4723[\r][\n]" -21:14:49.715 [Forwarding newSession on session null to remote] DEBUG org.apache.http.wire - http-outgoing-0 >> "Connection: Keep-Alive[\r][\n]" -21:14:49.715 [Forwarding newSession on session null to remote] DEBUG org.apache.http.wire - http-outgoing-0 >> "User-Agent: Apache-HttpClient/4.3.5 (java 1.5)[\r][\n]" -21:14:49.715 [Forwarding newSession on session null to remote] DEBUG org.apache.http.wire - http-outgoing-0 >> "Accept-Encoding: gzip,deflate[\r][\n]" -21:14:49.715 [Forwarding newSession on session null to remote] DEBUG org.apache.http.wire - http-outgoing-0 >> "[\r][\n]" -21:14:49.715 [Forwarding newSession on session null to remote] DEBUG org.apache.http.wire - http-outgoing-0 >> "{[\n]" -21:14:49.715 [Forwarding newSession on session null to remote] DEBUG org.apache.http.wire - http-outgoing-0 >> " "desiredCapabilities": {[\n]" -21:14:49.716 [Forwarding newSession on session null to remote] DEBUG org.apache.http.wire - http-outgoing-0 >> " "appPackage": "com.android.calculator2",[\n]" -21:14:49.716 [Forwarding newSession on session null to remote] DEBUG org.apache.http.wire - http-outgoing-0 >> " "appActivity": ".Calculator",[\n]" -21:14:49.716 [Forwarding newSession on session null to remote] DEBUG org.apache.http.wire - http-outgoing-0 >> " "platformVersion": "6.0",[\n]" -21:14:49.716 [Forwarding newSession on session null to remote] DEBUG org.apache.http.wire - http-outgoing-0 >> " "browserName": "",[\n]" -21:14:49.716 [Forwarding newSession on session null to remote] DEBUG org.apache.http.wire - http-outgoing-0 >> " "platformName": "Android",[\n]" -21:14:49.716 [Forwarding newSession on session null to remote] DEBUG org.apache.http.wire - http-outgoing-0 >> " "deviceName": "Android6"[\n]" -21:14:49.716 [Forwarding newSession on session null to remote] DEBUG org.apache.http.wire - http-outgoing-0 >> " },[\n]" -21:14:49.716 [Forwarding newSession on session null to remote] DEBUG org.apache.http.wire - http-outgoing-0 >> " "requiredCapabilities": {},[\n]" -21:14:49.716 [Forwarding newSession on session null to remote] DEBUG org.apache.http.wire - http-outgoing-0 >> " "capabilities": {[\n]" -21:14:49.716 [Forwarding newSession on session null to remote] DEBUG org.apache.http.wire - http-outgoing-0 >> " "desiredCapabilities": {[\n]" -21:14:49.716 [Forwarding newSession on session null to remote] DEBUG org.apache.http.wire - http-outgoing-0 >> " "appPackage": "com.android.calculator2",[\n]" -21:14:49.716 [Forwarding newSession on session null to remote] DEBUG org.apache.http.wire - http-outgoing-0 >> " "appActivity": ".Calculator",[\n]" -21:14:49.716 [Forwarding newSession on session null to remote] DEBUG org.apache.http.wire - http-outgoing-0 >> " "platformVersion": "6.0",[\n]" -21:14:49.716 [Forwarding newSession on session null to remote] DEBUG org.apache.http.wire - http-outgoing-0 >> " "browserName": "",[\n]" -21:14:49.717 [Forwarding newSession on session null to remote] DEBUG org.apache.http.wire - http-outgoing-0 >> " "platformName": "Android",[\n]" -21:14:49.717 [Forwarding newSession on session null to remote] DEBUG org.apache.http.wire - http-outgoing-0 >> " "deviceName": "Android6"[\n]" -21:14:49.717 [Forwarding newSession on session null to remote] DEBUG org.apache.http.wire - http-outgoing-0 >> " },[\n]" -21:14:49.717 [Forwarding newSession on session null to remote] DEBUG org.apache.http.wire - http-outgoing-0 >> " "requiredCapabilities": {},[\n]" -21:14:49.717 [Forwarding newSession on session null to remote] DEBUG org.apache.http.wire - http-outgoing-0 >> " "alwaysMatch": {[\n]" -21:14:49.717 [Forwarding newSession on session null to remote] DEBUG org.apache.http.wire - http-outgoing-0 >> " "browserName": "",[\n]" -21:14:49.717 [Forwarding newSession on session null to remote] DEBUG org.apache.http.wire - http-outgoing-0 >> " "platformName": "Android"[\n]" -21:14:49.717 [Forwarding newSession on session null to remote] DEBUG org.apache.http.wire - http-outgoing-0 >> " },[\n]" -21:14:49.717 [Forwarding newSession on session null to remote] DEBUG org.apache.http.wire - http-outgoing-0 >> " "firstMatch": [][\n]" -21:14:49.717 [Forwarding newSession on session null to remote] DEBUG org.apache.http.wire - http-outgoing-0 >> " }[\n]" -21:14:49.717 [Forwarding newSession on session null to remote] DEBUG org.apache.http.wire - http-outgoing-0 >> "}" -21:15:11.693 [Forwarding newSession on session null to remote] DEBUG org.apache.http.wire - http-outgoing-0 << "HTTP/1.1 200 OK[\r][\n]" -21:15:11.694 [Forwarding newSession on session null to remote] DEBUG org.apache.http.wire - http-outgoing-0 << "X-Powered-By: Express[\r][\n]" -21:15:11.694 [Forwarding newSession on session null to remote] DEBUG org.apache.http.wire - http-outgoing-0 << "Access-Control-Allow-Origin: [\r][\n]" -21:15:11.694 [Forwarding newSession on session null to remote] DEBUG org.apache.http.wire - http-outgoing-0 << "Access-Control-Allow-Methods: GET,POST,PUT,OPTIONS,DELETE[\r][\n]" -21:15:11.694 [Forwarding newSession on session null to remote] DEBUG org.apache.http.wire - http-outgoing-0 << "Access-Control-Allow-Headers: origin, content-type, accept[\r][\n]" -21:15:11.694 [Forwarding newSession on session null to remote] DEBUG org.apache.http.wire - http-outgoing-0 << "Vary: X-HTTP-Method-Override[\r][\n]" -21:15:11.694 [Forwarding newSession on session null to remote] DEBUG org.apache.http.wire - http-outgoing-0 << "Content-Type: application/json; charset=utf-8[\r][\n]" -21:15:11.694 [Forwarding newSession on session null to remote] DEBUG org.apache.http.wire - http-outgoing-0 << "Content-Length: 731[\r][\n]" -21:15:11.694 [Forwarding newSession on session null to remote] DEBUG org.apache.http.wire - http-outgoing-0 << "ETag: W/"2db-Y21msYuHoebMx38vzFoDmw20F0U"[\r][\n]" -21:15:11.694 [Forwarding newSession on session null to remote] DEBUG org.apache.http.wire - http-outgoing-0 << "Date: Fri, 15 Sep 2017 01:15:11 GMT[\r][\n]" -21:15:11.694 [Forwarding newSession on session null to remote] DEBUG org.apache.http.wire - http-outgoing-0 << "Connection: keep-alive[\r][\n]" -21:15:11.694 [Forwarding newSession on session null to remote] DEBUG org.apache.http.wire - http-outgoing-0 << "[\r][\n]" -21:15:11.695 [Forwarding newSession on session null to remote] DEBUG org.apache.http.wire - http-outgoing-0 << "{"status":0,"value":{"platform":"LINUX","webStorageEnabled":false,"takesScreenshot":true,"javascriptEnabled":true,"databaseEnabled":false,"networkConnectionEnabled":true,"locationContextEnabled":false,"warnings":{},"desired":{"appPackage":"com.android.calculator2","appActivity":".Calculator","platformVersion":"6.0","browserName":"","platformName":"Android","deviceName":"Android6"},"appPackage":"com.android.calculator2","appActivity":".Calculator","platformVersion":"6.0","browserName":"","platformName":"Android","deviceName":"emulator-5554","deviceUDID":"emulator-5554","deviceScreenSize":"1440x2560","deviceModel":"Android SDK built for x86","deviceManufacturer":"unknown"},"sessionId":"f16844dd-c40b-4307-a6dd-41198d328088"}" -21:15:11.699 [Forwarding newSession on session null to remote] DEBUG org.apache.http.headers - http-outgoing-0 << HTTP/1.1 200 OK -21:15:11.699 [Forwarding newSession on session null to remote] DEBUG org.apache.http.headers - http-outgoing-0 << X-Powered-By: Express -21:15:11.699 [Forwarding newSession on session null to remote] DEBUG org.apache.http.headers - http-outgoing-0 << Access-Control-Allow-Origin: -21:15:11.699 [Forwarding newSession on session null to remote] DEBUG org.apache.http.headers - http-outgoing-0 << Access-Control-Allow-Methods: GET,POST,PUT,OPTIONS,DELETE -21:15:11.699 [Forwarding newSession on session null to remote] DEBUG org.apache.http.headers - http-outgoing-0 << Access-Control-Allow-Headers: origin, content-type, accept -21:15:11.699 [Forwarding newSession on session null to remote] DEBUG org.apache.http.headers - http-outgoing-0 << Vary: X-HTTP-Method-Override -21:15:11.699 [Forwarding newSession on session null to remote] DEBUG org.apache.http.headers - http-outgoing-0 << Content-Type: application/json; charset=utf-8 -21:15:11.699 [Forwarding newSession on session null to remote] DEBUG org.apache.http.headers - http-outgoing-0 << Content-Length: 731 -21:15:11.699 [Forwarding newSession on session null to remote] DEBUG org.apache.http.headers - http-outgoing-0 << ETag: W/"2db-Y21msYuHoebMx38vzFoDmw20F0U" -21:15:11.699 [Forwarding newSession on session null to remote] DEBUG org.apache.http.headers - http-outgoing-0 << Date: Fri, 15 Sep 2017 01:15:11 GMT -21:15:11.699 [Forwarding newSession on session null to remote] DEBUG org.apache.http.headers - http-outgoing-0 << Connection: keep-alive -21:15:11.707 [Forwarding newSession on session null to remote] DEBUG o.a.h.impl.execchain.MainClientExec - Connection can be kept alive indefinitely -21:15:11.711 [Forwarding newSession on session null to remote] DEBUG o.a.h.i.c.PoolingHttpClientConnectionManager - Connection [id: 0][route: {}->http://127.0.0.1:4723] can be kept alive indefinitely -21:15:11.711 [Forwarding newSession on session null to remote] DEBUG o.a.h.i.c.PoolingHttpClientConnectionManager - Connection released: [id: 0][route: {}->http://127.0.0.1:4723][total kept alive: 1; route allocated: 1 of 2000; total allocated: 1 of 2000] -Sep 14, 2017 9:15:11 PM org.openqa.selenium.remote.ProtocolHandshake createSession INFO: Detected dialect: OSS 21:15:11.755 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG o.a.h.c.protocol.RequestAddCookies - CookieSpec selected: best-match -21:15:11.755 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG o.a.h.c.protocol.RequestAuthCache - Auth cache not set in the context -21:15:11.755 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG o.a.h.i.c.PoolingHttpClientConnectionManager - Connection request: [route: {}->http://127.0.0.1:4723][total kept alive: 1; route allocated: 1 of 2000; total allocated: 1 of 2000] -21:15:11.756 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG o.a.h.i.c.PoolingHttpClientConnectionManager - Connection leased: [id: 0][route: {}->http://127.0.0.1:4723][total kept alive: 0; route allocated: 1 of 2000; total allocated: 1 of 2000] -21:15:11.756 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG o.a.h.impl.execchain.MainClientExec - Stale connection check -21:15:11.757 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 << "[read] I/O error: Read timed out" -21:15:11.757 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG o.a.h.impl.execchain.MainClientExec - Executing request GET /wd/hub/session/f16844dd-c40b-4307-a6dd-41198d328088 HTTP/1.1 -21:15:11.757 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG o.a.h.impl.execchain.MainClientExec - Target auth state: UNCHALLENGED -21:15:11.757 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG o.a.h.impl.execchain.MainClientExec - Proxy auth state: UNCHALLENGED -21:15:11.757 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.headers - http-outgoing-0 >> GET /wd/hub/session/f16844dd-c40b-4307-a6dd-41198d328088 HTTP/1.1 -21:15:11.757 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.headers - http-outgoing-0 >> Cache-Control: no-cache -21:15:11.757 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.headers - http-outgoing-0 >> Host: 127.0.0.1:4723 -21:15:11.757 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.headers - http-outgoing-0 >> Connection: Keep-Alive -21:15:11.757 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.headers - http-outgoing-0 >> User-Agent: Apache-HttpClient/4.3.5 (java 1.5) -21:15:11.757 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.headers - http-outgoing-0 >> Accept-Encoding: gzip,deflate -21:15:11.758 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 >> "GET /wd/hub/session/f16844dd-c40b-4307-a6dd-41198d328088 HTTP/1.1[\r][\n]" -21:15:11.758 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 >> "Cache-Control: no-cache[\r][\n]" -21:15:11.758 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 >> "Host: 127.0.0.1:4723[\r][\n]" -21:15:11.758 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 >> "Connection: Keep-Alive[\r][\n]" -21:15:11.758 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 >> "User-Agent: Apache-HttpClient/4.3.5 (java 1.5)[\r][\n]" -21:15:11.758 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 >> "Accept-Encoding: gzip,deflate[\r][\n]" -21:15:11.758 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 >> "[\r][\n]" -21:15:11.766 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 << "HTTP/1.1 200 OK[\r][\n]" -21:15:11.767 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 << "X-Powered-By: Express[\r][\n]" -21:15:11.767 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 << "Access-Control-Allow-Origin: [\r][\n]" -21:15:11.767 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 << "Access-Control-Allow-Methods: GET,POST,PUT,OPTIONS,DELETE[\r][\n]" -21:15:11.767 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 << "Access-Control-Allow-Headers: origin, content-type, accept[\r][\n]" -21:15:11.767 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 << "Content-Type: application/json; charset=utf-8[\r][\n]" -21:15:11.767 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 << "Content-Length: 731[\r][\n]" -21:15:11.767 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 << "ETag: W/"2db-Y21msYuHoebMx38vzFoDmw20F0U"[\r][\n]" -21:15:11.767 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 << "Date: Fri, 15 Sep 2017 01:15:11 GMT[\r][\n]" -21:15:11.767 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 << "Connection: keep-alive[\r][\n]" -21:15:11.767 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 << "[\r][\n]" -21:15:11.767 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 << "{"status":0,"value":{"platform":"LINUX","webStorageEnabled":false,"takesScreenshot":true,"javascriptEnabled":true,"databaseEnabled":false,"networkConnectionEnabled":true,"locationContextEnabled":false,"warnings":{},"desired":{"appPackage":"com.android.calculator2","appActivity":".Calculator","platformVersion":"6.0","browserName":"","platformName":"Android","deviceName":"Android6"},"appPackage":"com.android.calculator2","appActivity":".Calculator","platformVersion":"6.0","browserName":"","platformName":"Android","deviceName":"emulator-5554","deviceUDID":"emulator-5554","deviceScreenSize":"1440x2560","deviceModel":"Android SDK built for x86","deviceManufacturer":"unknown"},"sessionId":"f16844dd-c40b-4307-a6dd-41198d328088"}" -21:15:11.767 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.headers - http-outgoing-0 << HTTP/1.1 200 OK -21:15:11.767 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.headers - http-outgoing-0 << X-Powered-By: Express -21:15:11.767 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.headers - http-outgoing-0 << Access-Control-Allow-Origin: -21:15:11.767 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.headers - http-outgoing-0 << Access-Control-Allow-Methods: GET,POST,PUT,OPTIONS,DELETE -21:15:11.768 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.headers - http-outgoing-0 << Access-Control-Allow-Headers: origin, content-type, accept -21:15:11.768 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.headers - http-outgoing-0 << Content-Type: application/json; charset=utf-8 -21:15:11.768 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.headers - http-outgoing-0 << Content-Length: 731 -21:15:11.768 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.headers - http-outgoing-0 << ETag: W/"2db-Y21msYuHoebMx38vzFoDmw20F0U" -21:15:11.768 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.headers - http-outgoing-0 << Date: Fri, 15 Sep 2017 01:15:11 GMT -21:15:11.768 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.headers - http-outgoing-0 << Connection: keep-alive -21:15:11.768 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG o.a.h.impl.execchain.MainClientExec - Connection can be kept alive indefinitely -21:15:11.768 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG o.a.h.i.c.PoolingHttpClientConnectionManager - Connection [id: 0][route: {}->http://127.0.0.1:4723] can be kept alive indefinitely -21:15:11.768 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG o.a.h.i.c.PoolingHttpClientConnectionManager - Connection released: [id: 0][route: {}->http://127.0.0.1:4723][total kept alive: 1; route allocated: 1 of 2000; total allocated: 1 of 2000] -21:15:11.771 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG o.a.h.c.protocol.RequestAddCookies - CookieSpec selected: best-match -21:15:11.771 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG o.a.h.c.protocol.RequestAuthCache - Auth cache not set in the context -21:15:11.771 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG o.a.h.i.c.PoolingHttpClientConnectionManager - Connection request: [route: {}->http://127.0.0.1:4723][total kept alive: 1; route allocated: 1 of 2000; total allocated: 1 of 2000] -21:15:11.771 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG o.a.h.i.c.PoolingHttpClientConnectionManager - Connection leased: [id: 0][route: {}->http://127.0.0.1:4723][total kept alive: 0; route allocated: 1 of 2000; total allocated: 1 of 2000] -21:15:11.771 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG o.a.h.impl.execchain.MainClientExec - Stale connection check -21:15:11.772 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 << "[read] I/O error: Read timed out" -21:15:11.772 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG o.a.h.impl.execchain.MainClientExec - Executing request GET /wd/hub/session/f16844dd-c40b-4307-a6dd-41198d328088 HTTP/1.1 -21:15:11.772 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG o.a.h.impl.execchain.MainClientExec - Target auth state: UNCHALLENGED -21:15:11.772 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG o.a.h.impl.execchain.MainClientExec - Proxy auth state: UNCHALLENGED -21:15:11.772 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.headers - http-outgoing-0 >> GET /wd/hub/session/f16844dd-c40b-4307-a6dd-41198d328088 HTTP/1.1 -21:15:11.772 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.headers - http-outgoing-0 >> Cache-Control: no-cache -21:15:11.773 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.headers - http-outgoing-0 >> Host: 127.0.0.1:4723 -21:15:11.773 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.headers - http-outgoing-0 >> Connection: Keep-Alive -21:15:11.773 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.headers - http-outgoing-0 >> User-Agent: Apache-HttpClient/4.3.5 (java 1.5) -21:15:11.773 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.headers - http-outgoing-0 >> Accept-Encoding: gzip,deflate -21:15:11.773 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 >> "GET /wd/hub/session/f16844dd-c40b-4307-a6dd-41198d328088 HTTP/1.1[\r][\n]" -21:15:11.773 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 >> "Cache-Control: no-cache[\r][\n]" -21:15:11.773 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 >> "Host: 127.0.0.1:4723[\r][\n]" -21:15:11.773 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 >> "Connection: Keep-Alive[\r][\n]" -21:15:11.773 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 >> "User-Agent: Apache-HttpClient/4.3.5 (java 1.5)[\r][\n]" -21:15:11.773 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 >> "Accept-Encoding: gzip,deflate[\r][\n]" -21:15:11.773 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 >> "[\r][\n]" -21:15:11.777 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 << "HTTP/1.1 200 OK[\r][\n]" -21:15:11.777 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 << "X-Powered-By: Express[\r][\n]" -21:15:11.777 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 << "Access-Control-Allow-Origin: [\r][\n]" -21:15:11.778 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 << "Access-Control-Allow-Methods: GET,POST,PUT,OPTIONS,DELETE[\r][\n]" -21:15:11.778 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 << "Access-Control-Allow-Headers: origin, content-type, accept[\r][\n]" -21:15:11.778 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 << "Content-Type: application/json; charset=utf-8[\r][\n]" -21:15:11.778 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 << "Content-Length: 731[\r][\n]" -21:15:11.778 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 << "ETag: W/"2db-Y21msYuHoebMx38vzFoDmw20F0U"[\r][\n]" -21:15:11.778 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 << "Date: Fri, 15 Sep 2017 01:15:11 GMT[\r][\n]" -21:15:11.778 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 << "Connection: keep-alive[\r][\n]" -21:15:11.778 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 << "[\r][\n]" -21:15:11.778 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 << "{"status":0,"value":{"platform":"LINUX","webStorageEnabled":false,"takesScreenshot":true,"javascriptEnabled":true,"databaseEnabled":false,"networkConnectionEnabled":true,"locationContextEnabled":false,"warnings":{},"desired":{"appPackage":"com.android.calculator2","appActivity":".Calculator","platformVersion":"6.0","browserName":"","platformName":"Android","deviceName":"Android6"},"appPackage":"com.android.calculator2","appActivity":".Calculator","platformVersion":"6.0","browserName":"","platformName":"Android","deviceName":"emulator-5554","deviceUDID":"emulator-5554","deviceScreenSize":"1440x2560","deviceModel":"Android SDK built for x86","deviceManufacturer":"unknown"},"sessionId":"f16844dd-c40b-4307-a6dd-41198d328088"}" -21:15:11.778 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.headers - http-outgoing-0 << HTTP/1.1 200 OK -21:15:11.778 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.headers - http-outgoing-0 << X-Powered-By: Express -21:15:11.778 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.headers - http-outgoing-0 << Access-Control-Allow-Origin: -21:15:11.778 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.headers - http-outgoing-0 << Access-Control-Allow-Methods: GET,POST,PUT,OPTIONS,DELETE -21:15:11.778 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.headers - http-outgoing-0 << Access-Control-Allow-Headers: origin, content-type, accept -21:15:11.778 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.headers - http-outgoing-0 << Content-Type: application/json; charset=utf-8 -21:15:11.778 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.headers - http-outgoing-0 << Content-Length: 731 -21:15:11.778 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.headers - http-outgoing-0 << ETag: W/"2db-Y21msYuHoebMx38vzFoDmw20F0U" -21:15:11.778 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.headers - http-outgoing-0 << Date: Fri, 15 Sep 2017 01:15:11 GMT -21:15:11.778 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.headers - http-outgoing-0 << Connection: keep-alive -21:15:11.778 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG o.a.h.impl.execchain.MainClientExec - Connection can be kept alive indefinitely -21:15:11.779 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG o.a.h.i.c.PoolingHttpClientConnectionManager - Connection [id: 0][route: {}->http://127.0.0.1:4723] can be kept alive indefinitely -21:15:11.779 [Forwarding getSession on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG o.a.h.i.c.PoolingHttpClientConnectionManager - Connection released: [id: 0][route: {}->http://127.0.0.1:4723][total kept alive: 1; route allocated: 1 of 2000; total allocated: 1 of 2000] -21:15:11.782 [Forwarding findElement on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG o.a.h.c.protocol.RequestAddCookies - CookieSpec selected: best-match -21:15:11.782 [Forwarding findElement on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG o.a.h.c.protocol.RequestAuthCache - Auth cache not set in the context -21:15:11.782 [Forwarding findElement on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG o.a.h.i.c.PoolingHttpClientConnectionManager - Connection request: [route: {}->http://127.0.0.1:4723][total kept alive: 1; route allocated: 1 of 2000; total allocated: 1 of 2000] -21:15:11.782 [Forwarding findElement on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG o.a.h.i.c.PoolingHttpClientConnectionManager - Connection leased: [id: 0][route: {}->http://127.0.0.1:4723][total kept alive: 0; route allocated: 1 of 2000; total allocated: 1 of 2000] -21:15:11.782 [Forwarding findElement on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG o.a.h.impl.execchain.MainClientExec - Stale connection check -21:15:11.784 [Forwarding findElement on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 << "[read] I/O error: Read timed out" -21:15:11.784 [Forwarding findElement on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG o.a.h.impl.execchain.MainClientExec - Executing request POST /wd/hub/session/f16844dd-c40b-4307-a6dd-41198d328088/element HTTP/1.1 -21:15:11.784 [Forwarding findElement on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG o.a.h.impl.execchain.MainClientExec - Target auth state: UNCHALLENGED -21:15:11.784 [Forwarding findElement on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG o.a.h.impl.execchain.MainClientExec - Proxy auth state: UNCHALLENGED -21:15:11.784 [Forwarding findElement on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.headers - http-outgoing-0 >> POST /wd/hub/session/f16844dd-c40b-4307-a6dd-41198d328088/element HTTP/1.1 -21:15:11.785 [Forwarding findElement on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.headers - http-outgoing-0 >> Content-Type: application/json; charset=utf-8 -21:15:11.786 [Forwarding findElement on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.headers - http-outgoing-0 >> Content-Length: 28 -21:15:11.786 [Forwarding findElement on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.headers - http-outgoing-0 >> Host: 127.0.0.1:4723 -21:15:11.786 [Forwarding findElement on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.headers - http-outgoing-0 >> Connection: Keep-Alive -21:15:11.786 [Forwarding findElement on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.headers - http-outgoing-0 >> User-Agent: Apache-HttpClient/4.3.5 (java 1.5) -21:15:11.786 [Forwarding findElement on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.headers - http-outgoing-0 >> Accept-Encoding: gzip,deflate -21:15:11.786 [Forwarding findElement on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 >> "POST /wd/hub/session/f16844dd-c40b-4307-a6dd-41198d328088/element HTTP/1.1[\r][\n]" -21:15:11.786 [Forwarding findElement on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 >> "Content-Type: application/json; charset=utf-8[\r][\n]" -21:15:11.786 [Forwarding findElement on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 >> "Content-Length: 28[\r][\n]" -21:15:11.786 [Forwarding findElement on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 >> "Host: 127.0.0.1:4723[\r][\n]" -21:15:11.786 [Forwarding findElement on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 >> "Connection: Keep-Alive[\r][\n]" -21:15:11.786 [Forwarding findElement on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 >> "User-Agent: Apache-HttpClient/4.3.5 (java 1.5)[\r][\n]" -21:15:11.787 [Forwarding findElement on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 >> "Accept-Encoding: gzip,deflate[\r][\n]" -21:15:11.787 [Forwarding findElement on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 >> "[\r][\n]" -21:15:11.787 [Forwarding findElement on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 >> "{"using":"name","value":"1"}" -21:15:11.791 [Forwarding findElement on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 << "HTTP/1.1 500 Internal Server Error[\r][\n]" -21:15:11.792 [Forwarding findElement on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 << "X-Powered-By: Express[\r][\n]" -21:15:11.792 [Forwarding findElement on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 << "Access-Control-Allow-Origin: [\r][\n]" -21:15:11.792 [Forwarding findElement on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 << "Access-Control-Allow-Methods: GET,POST,PUT,OPTIONS,DELETE[\r][\n]" -21:15:11.792 [Forwarding findElement on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 << "Access-Control-Allow-Headers: origin, content-type, accept[\r][\n]" -21:15:11.792 [Forwarding findElement on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 << "Vary: X-HTTP-Method-Override[\r][\n]" -21:15:11.792 [Forwarding findElement on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 << "Content-Type: application/json; charset=utf-8[\r][\n]" -21:15:11.792 [Forwarding findElement on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 << "Content-Length: 144[\r][\n]" -21:15:11.792 [Forwarding findElement on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 << "ETag: W/"90-2t4FDoMPig0vj9E8mGdscdNJC6k"[\r][\n]" -21:15:11.792 [Forwarding findElement on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 << "Date: Fri, 15 Sep 2017 01:15:11 GMT[\r][\n]" -21:15:11.792 [Forwarding findElement on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 << "Connection: keep-alive[\r][\n]" -21:15:11.792 [Forwarding findElement on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 << "[\r][\n]" -21:15:11.792 [Forwarding findElement on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 << "{"status":32,"value":{"message":"Locator Strategy 'name' is not supported for this session"},"sessionId":"f16844dd-c40b-4307-a6dd-41198d328088"}" -21:15:11.792 [Forwarding findElement on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.headers - http-outgoing-0 << HTTP/1.1 500 Internal Server Error -21:15:11.792 [Forwarding findElement on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.headers - http-outgoing-0 << X-Powered-By: Express -21:15:11.792 [Forwarding findElement on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.headers - http-outgoing-0 << Access-Control-Allow-Origin: -21:15:11.792 [Forwarding findElement on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.headers - http-outgoing-0 << Access-Control-Allow-Methods: GET,POST,PUT,OPTIONS,DELETE -21:15:11.792 [Forwarding findElement on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.headers - http-outgoing-0 << Access-Control-Allow-Headers: origin, content-type, accept -21:15:11.792 [Forwarding findElement on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.headers - http-outgoing-0 << Vary: X-HTTP-Method-Override -21:15:11.792 [Forwarding findElement on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.headers - http-outgoing-0 << Content-Type: application/json; charset=utf-8 -21:15:11.792 [Forwarding findElement on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.headers - http-outgoing-0 << Content-Length: 144 -21:15:11.792 [Forwarding findElement on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.headers - http-outgoing-0 << ETag: W/"90-2t4FDoMPig0vj9E8mGdscdNJC6k" -21:15:11.792 [Forwarding findElement on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.headers - http-outgoing-0 << Date: Fri, 15 Sep 2017 01:15:11 GMT -21:15:11.792 [Forwarding findElement on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.headers - http-outgoing-0 << Connection: keep-alive -21:15:11.792 [Forwarding findElement on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG o.a.h.impl.execchain.MainClientExec - Connection can be kept alive indefinitely -21:15:11.793 [Forwarding findElement on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG o.a.h.i.c.PoolingHttpClientConnectionManager - Connection [id: 0][route: {}->http://127.0.0.1:4723] can be kept alive indefinitely -21:15:11.793 [Forwarding findElement on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG o.a.h.i.c.PoolingHttpClientConnectionManager - Connection released: [id: 0][route: {}->http://127.0.0.1:4723][total kept alive: 1; route allocated: 1 of 2000; total allocated: 1 of 2000] -21:15:11.831 [Forwarding quit on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG o.a.h.c.protocol.RequestAddCookies - CookieSpec selected: best-match -21:15:11.831 [Forwarding quit on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG o.a.h.c.protocol.RequestAuthCache - Auth cache not set in the context -21:15:11.831 [Forwarding quit on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG o.a.h.i.c.PoolingHttpClientConnectionManager - Connection request: [route: {}->http://127.0.0.1:4723][total kept alive: 1; route allocated: 1 of 2000; total allocated: 1 of 2000] -21:15:11.832 [Forwarding quit on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG o.a.h.i.c.PoolingHttpClientConnectionManager - Connection leased: [id: 0][route: {}->http://127.0.0.1:4723][total kept alive: 0; route allocated: 1 of 2000; total allocated: 1 of 2000] -21:15:11.832 [Forwarding quit on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG o.a.h.impl.execchain.MainClientExec - Stale connection check -21:15:11.833 [Forwarding quit on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 << "[read] I/O error: Read timed out" -21:15:11.833 [Forwarding quit on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG o.a.h.impl.execchain.MainClientExec - Executing request DELETE /wd/hub/session/f16844dd-c40b-4307-a6dd-41198d328088 HTTP/1.1 -21:15:11.833 [Forwarding quit on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG o.a.h.impl.execchain.MainClientExec - Target auth state: UNCHALLENGED -21:15:11.833 [Forwarding quit on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG o.a.h.impl.execchain.MainClientExec - Proxy auth state: UNCHALLENGED -21:15:11.833 [Forwarding quit on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.headers - http-outgoing-0 >> DELETE /wd/hub/session/f16844dd-c40b-4307-a6dd-41198d328088 HTTP/1.1 -21:15:11.833 [Forwarding quit on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.headers - http-outgoing-0 >> Host: 127.0.0.1:4723 -21:15:11.833 [Forwarding quit on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.headers - http-outgoing-0 >> Connection: Keep-Alive -21:15:11.833 [Forwarding quit on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.headers - http-outgoing-0 >> User-Agent: Apache-HttpClient/4.3.5 (java 1.5) -21:15:11.833 [Forwarding quit on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.headers - http-outgoing-0 >> Accept-Encoding: gzip,deflate -21:15:11.833 [Forwarding quit on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 >> "DELETE /wd/hub/session/f16844dd-c40b-4307-a6dd-41198d328088 HTTP/1.1[\r][\n]" -21:15:11.833 [Forwarding quit on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 >> "Host: 127.0.0.1:4723[\r][\n]" -21:15:11.833 [Forwarding quit on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 >> "Connection: Keep-Alive[\r][\n]" -21:15:11.833 [Forwarding quit on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 >> "User-Agent: Apache-HttpClient/4.3.5 (java 1.5)[\r][\n]" -21:15:11.833 [Forwarding quit on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 >> "Accept-Encoding: gzip,deflate[\r][\n]" -21:15:11.833 [Forwarding quit on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 >> "[\r][\n]" -21:15:16.700 [Forwarding quit on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 << "HTTP/1.1 200 OK[\r][\n]" -21:15:16.700 [Forwarding quit on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 << "X-Powered-By: Express[\r][\n]" -21:15:16.700 [Forwarding quit on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 << "Access-Control-Allow-Origin: [\r][\n]" -21:15:16.700 [Forwarding quit on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 << "Access-Control-Allow-Methods: GET,POST,PUT,OPTIONS,DELETE[\r][\n]" -21:15:16.700 [Forwarding quit on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 << "Access-Control-Allow-Headers: origin, content-type, accept[\r][\n]" -21:15:16.700 [Forwarding quit on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 << "Content-Type: application/json; charset=utf-8[\r][\n]" -21:15:16.700 [Forwarding quit on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 << "Content-Length: 76[\r][\n]" -21:15:16.700 [Forwarding quit on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 << "ETag: W/"4c-4T0Ad5J+BQ7Ap+Sm77AyOI7qUP4"[\r][\n]" -21:15:16.700 [Forwarding quit on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 << "Date: Fri, 15 Sep 2017 01:15:16 GMT[\r][\n]" -21:15:16.700 [Forwarding quit on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 << "Connection: keep-alive[\r][\n]" -21:15:16.700 [Forwarding quit on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 << "[\r][\n]" -21:15:16.700 [Forwarding quit on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.wire - http-outgoing-0 << "{"status":0,"value":null,"sessionId":"f16844dd-c40b-4307-a6dd-41198d328088"}" -21:15:16.700 [Forwarding quit on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.headers - http-outgoing-0 << HTTP/1.1 200 OK -21:15:16.700 [Forwarding quit on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.headers - http-outgoing-0 << X-Powered-By: Express -21:15:16.700 [Forwarding quit on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.headers - http-outgoing-0 << Access-Control-Allow-Origin: -21:15:16.700 [Forwarding quit on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.headers - http-outgoing-0 << Access-Control-Allow-Methods: GET,POST,PUT,OPTIONS,DELETE -21:15:16.701 [Forwarding quit on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.headers - http-outgoing-0 << Access-Control-Allow-Headers: origin, content-type, accept -21:15:16.701 [Forwarding quit on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.headers - http-outgoing-0 << Content-Type: application/json; charset=utf-8 -21:15:16.701 [Forwarding quit on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.headers - http-outgoing-0 << Content-Length: 76 -21:15:16.701 [Forwarding quit on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.headers - http-outgoing-0 << ETag: W/"4c-4T0Ad5J+BQ7Ap+Sm77AyOI7qUP4" -21:15:16.701 [Forwarding quit on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.headers - http-outgoing-0 << Date: Fri, 15 Sep 2017 01:15:16 GMT -21:15:16.701 [Forwarding quit on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG org.apache.http.headers - http-outgoing-0 << Connection: keep-alive -21:15:16.701 [Forwarding quit on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG o.a.h.impl.execchain.MainClientExec - Connection can be kept alive indefinitely -21:15:16.701 [Forwarding quit on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG o.a.h.i.c.PoolingHttpClientConnectionManager - Connection [id: 0][route: {}->http://127.0.0.1:4723] can be kept alive indefinitely -21:15:16.702 [Forwarding quit on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG o.a.h.i.c.PoolingHttpClientConnectionManager - Connection released: [id: 0][route: {}->http://127.0.0.1:4723][total kept alive: 1; route allocated: 1 of 2000; total allocated: 1 of 2000] -21:15:16.703 [Forwarding quit on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG o.a.h.i.c.PoolingHttpClientConnectionManager - Closing connections idle longer than 0 SECONDS -21:15:16.703 [Forwarding quit on session f16844dd-c40b-4307-a6dd-41198d328088 to remote] DEBUG o.a.h.i.c.DefaultManagedHttpClientConnection - http-outgoing-0: Close connection
=====================================================================
Appium Server log
The server is running
[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":{"appPackage":"com.android.calculator2","appActivity":".Calculator","platformVersion":"6.0","browserName":"","platformName":"Android","deviceName":"Android6"},"requiredCapabilities":{},"capabilities":{"desiredCapabilities":{"appPackage":"com.android.calculator2","appActivity":".Calculator","platformVersion":"6.0","browserName":"","platformName":"Android","deviceName":"Android6"},"requiredCapabilities":{},"alwaysMatch":{"browserName":"","platformName":"Android"},"firstMatch":[]}} [MJSONWP] Calling AppiumDriver.createSession() with args: [{"appPackage":"com.android.calculator2","appActivity":".Calculator","platformVersion":"6.0","browserName":"","platformName":"Android","deviceName":"Android6"},{},{"desiredCapabilities":{"appPackage":"com.android.calculator2","appActivity":".Calculator","platformVersion":"6.0","browserName":"","platformName":"Android","deviceName":"Android6"},"requiredCapabilities":{},"alwaysMatch":{"browserName":"","platformName":"Android"},"firstMatch":[]}] [BaseDriver] Event 'newSessionRequested' logged at 1505438089762 (21:14:49 GMT-0400 (Eastern Daylight Time)) [Appium] Creating new AndroidDriver (v1.20.0) session [Appium] Capabilities: [Appium] appPackage: 'com.android.calculator2' [Appium] appActivity: '.Calculator' [Appium] platformVersion: '6.0' [Appium] browserName: '' [Appium] platformName: 'Android' [Appium] deviceName: 'Android6' [AndroidDriver] AndroidDriver version: 1.20.0 [BaseDriver] Session created with session id: f16844dd-c40b-4307-a6dd-41198d328088 [AndroidDriver] Getting Java version [AndroidDriver] Java version is: 1.8.0_144 [ADB] Checking whether adb is present [ADB] Using adb.exe from C:\Users\Chen Chen\AppData\Local\Android\Sdk\platform-tools\adb.exe [AndroidDriver] Retrieving device list [ADB] Trying to find a connected android device [ADB] Getting connected devices... [ADB] 1 device(s) connected [AndroidDriver] Looking for a device with Android '6.0' [ADB] Setting device id to emulator-5554 [ADB] Getting device platform version [ADB] Getting connected devices... [ADB] 1 device(s) connected [ADB] Running 'C:\Users\Chen Chen\AppData\Local\Android\Sdk\platform-tools\adb.exe' with args: ["-P",5037,"-s","emulator-5554","shell","getprop","ro.build.version.release"] [AndroidDriver] Using device: emulator-5554 [ADB] Checking whether adb is present [ADB] Using adb.exe from C:\Users\Chen Chen\AppData\Local\Android\Sdk\platform-tools\adb.exe [ADB] Setting device id to emulator-5554 [AndroidDriver] App file was not listed, instead we're going to run com.android.calculator2 directly on the device [AndroidDriver] Checking whether package is present on the device [ADB] Getting connected devices... [ADB] 1 device(s) connected [ADB] Running 'C:\Users\Chen Chen\AppData\Local\Android\Sdk\platform-tools\adb.exe' with args: ["-P",5037,"-s","emulator-5554","shell","pm","list","packages","com.android.calculator2"] [AndroidDriver] Starting Android session [ADB] Running 'C:\Users\Chen Chen\AppData\Local\Android\Sdk\platform-tools\adb.exe' with args: ["-P",5037,"-s","emulator-5554","wait-for-device"] [ADB] Getting connected devices... [ADB] 1 device(s) connected [ADB] Running 'C:\Users\Chen Chen\AppData\Local\Android\Sdk\platform-tools\adb.exe' with args: ["-P",5037,"-s","emulator-5554","shell","echo","ping"] [Logcat] Starting logcat capture [AndroidDriver] Pushing settings apk to device... [ADB] Getting install status for io.appium.settings [ADB] Getting connected devices... [ADB] 1 device(s) connected [ADB] Running 'C:\Users\Chen Chen\AppData\Local\Android\Sdk\platform-tools\adb.exe' with args: ["-P",5037,"-s","emulator-5554","shell","pm","list","packages","io.appium.settings"] [ADB] App is installed [ADB] Getting package info for io.appium.settings [ADB] Getting connected devices... [ADB] Checking whether aapt is present [ADB] Using aapt.exe from C:\Users\Chen Chen\AppData\Local\Android\Sdk\build-tools\26.0.0\aapt.exe [ADB] 1 device(s) connected [ADB] Running 'C:\Users\Chen Chen\AppData\Local\Android\Sdk\platform-tools\adb.exe' with args: ["-P",5037,"-s","emulator-5554","shell","dumpsys","package","io.appium.settings"] [ADB] Cannot read version codes of C:\Users\Chen Chen\AppData\Local\Programs\appium-desktop\resources\app\node_modules\appium\node_modules\io.appium.settings\app\build\outputs\apk\settings_apk-debug.apk and/or io.appium.settings. Assuming correct app version is already installed [ADB] Getting connected devices... [ADB] 1 device(s) connected [ADB] Running 'C:\Users\Chen Chen\AppData\Local\Android\Sdk\platform-tools\adb.exe' with args: ["-P",5037,"-s","emulator-5554","shell","getprop","ro.build.version.sdk"] [ADB] Device API level: 23 [ADB] Getting connected devices... [ADB] 1 device(s) connected [ADB] Running 'C:\Users\Chen Chen\AppData\Local\Android\Sdk\platform-tools\adb.exe' with args: ["-P",5037,"-s","emulator-5554","shell","dumpsys","package","io.appium.settings"] [ADB] Getting connected devices... [ADB] 1 device(s) connected [ADB] Running 'C:\Users\Chen Chen\AppData\Local\Android\Sdk\platform-tools\adb.exe' with args: ["-P",5037,"-s","emulator-5554","shell","pm","dump","io.appium.settings"] [ADB] Got the following command chunks to execute: pm,grant,io.appium.settings,android.permission.CHANGE_NETWORK_STATE,;,pm,grant,io.appium.settings,android.permission.WRITE_SETTINGS,;,pm,grant,io.appium.settings,android.permission.ACCESS_MOCK_LOCATION,; [ADB] Getting connected devices... [ADB] 1 device(s) connected [ADB] Running 'C:\Users\Chen Chen\AppData\Local\Android\Sdk\platform-tools\adb.exe' with args: ["-P",5037,"-s","emulator-5554","shell","pm","grant","io.appium.settings","android.permission.CHANGE_NETWORK_STATE",";","pm","grant","io.appium.settings","android.permission.WRITE_SETTINGS",";","pm","grant","io.appium.settings","android.permission.ACCESS_MOCK_LOCATION",";"] [AndroidDriver] Pushing unlock helper app to device... [ADB] Running 'C:\Users\Chen Chen\AppData\Local\Android\Sdk\platform-tools\adb.exe' with args: ["-P",5037,"-s","emulator-5554","install","C:\Users\Chen Chen\AppData\Local\Programs\appium-desktop\resources\app\node_modules\appium\node_modules\appium-unlock\bin\unlock_apk-debug.apk"] [ADB] Device API level: 23 [ADB] Getting connected devices... [ADB] 1 device(s) connected [ADB] Running 'C:\Users\Chen Chen\AppData\Local\Android\Sdk\platform-tools\adb.exe' with args: ["-P",5037,"-s","emulator-5554","shell","appops","set","io.appium.settings","android:mock_location","allow"] [ADB] Getting device platform version [ADB] Getting connected devices... [ADB] 1 device(s) connected [ADB] Running 'C:\Users\Chen Chen\AppData\Local\Android\Sdk\platform-tools\adb.exe' with args: ["-P",5037,"-s","emulator-5554","shell","getprop","ro.build.version.release"] [ADB] Getting connected devices... [ADB] 1 device(s) connected [ADB] Running 'C:\Users\Chen Chen\AppData\Local\Android\Sdk\platform-tools\adb.exe' with args: ["-P",5037,"-s","emulator-5554","shell","wm","size"] [ADB] Getting connected devices... [ADB] 1 device(s) connected [ADB] Running 'C:\Users\Chen Chen\AppData\Local\Android\Sdk\platform-tools\adb.exe' with args: ["-P",5037,"-s","emulator-5554","shell","getprop","ro.product.model"] [ADB] Current device property 'ro.product.model': Android SDK built for x86 [ADB] Getting connected devices... [ADB] 1 device(s) connected [ADB] Running 'C:\Users\Chen Chen\AppData\Local\Android\Sdk\platform-tools\adb.exe' with args: ["-P",5037,"-s","emulator-5554","shell","getprop","ro.product.manufacturer"] [ADB] Current device property 'ro.product.manufacturer': unknown [AndroidDriver] No app sent in, not parsing package/activity [AndroidDriver] No app capability. Assuming it is already on the device [AndroidDriver] Running fast reset (stop and clear) [ADB] Getting connected devices... [ADB] 1 device(s) connected [ADB] Running 'C:\Users\Chen Chen\AppData\Local\Android\Sdk\platform-tools\adb.exe' with args: ["-P",5037,"-s","emulator-5554","shell","am","force-stop","com.android.calculator2"] [ADB] Getting connected devices... [ADB] 1 device(s) connected [ADB] Running 'C:\Users\Chen Chen\AppData\Local\Android\Sdk\platform-tools\adb.exe' with args: ["-P",5037,"-s","emulator-5554","shell","pm","clear","com.android.calculator2"] [AndroidBootstrap] Watching for bootstrap disconnect [ADB] Forwarding system: 4724 to device: 4724 [ADB] Running 'C:\Users\Chen Chen\AppData\Local\Android\Sdk\platform-tools\adb.exe' with args: ["-P",5037,"-s","emulator-5554","forward","tcp:4724","tcp:4724"] [UiAutomator] Starting UiAutomator [UiAutomator] Moving to state 'starting' [UiAutomator] Parsing uiautomator jar [UiAutomator] Found jar name: 'AppiumBootstrap.jar' [ADB] Running 'C:\Users\Chen Chen\AppData\Local\Android\Sdk\platform-tools\adb.exe' with args: ["-P",5037,"-s","emulator-5554","push","C:\Users\Chen Chen\AppData\Local\Programs\appium-desktop\resources\app\node_modules\appium\node_modules\appium-android-bootstrap\bootstrap\bin\AppiumBootstrap.jar","/data/local/tmp/"] [ADB] Attempting to kill all uiautomator processes [ADB] Getting all processes with uiautomator [ADB] Getting connected devices... [ADB] 1 device(s) connected [ADB] Running 'C:\Users\Chen Chen\AppData\Local\Android\Sdk\platform-tools\adb.exe' with args: ["-P",5037,"-s","emulator-5554","shell","ps"] [ADB] No uiautomator process found to kill, continuing... [UiAutomator] Starting UIAutomator [ADB] Creating ADB subprocess with args: ["-P",5037,"-s","emulator-5554","shell","uiautomator","runtest","AppiumBootstrap.jar","-c","io.appium.android.bootstrap.Bootstrap","-e","pkg","com.android.calculator2","-e","disableAndroidWatchers",false,"-e","acceptSslCerts",false] [UiAutomator] Moving to state 'online' [AndroidBootstrap] [BOOTSTRAP LOG] [debug] Registered crash watchers. [AndroidBootstrap] Android bootstrap socket is now connected [ADB] Getting connected devices... [ADB] 1 device(s) connected [ADB] Running 'C:\Users\Chen Chen\AppData\Local\Android\Sdk\platform-tools\adb.exe' with args: ["-P",5037,"-s","emulator-5554","shell","dumpsys","window"] [AndroidBootstrap] [BOOTSTRAP LOG] [debug] Client connected [AndroidDriver] Screen already unlocked, doing nothing [ADB] Device API level: 23 [ADB] Getting connected devices... [ADB] 1 device(s) connected [ADB] Running 'C:\Users\Chen Chen\AppData\Local\Android\Sdk\platform-tools\adb.exe' with args: ["-P",5037,"-s","emulator-5554","shell","am","start","-W","-n","com.android.calculator2/.Calculator","-S"] [Appium] New AndroidDriver session created successfully, session f16844dd-c40b-4307-a6dd-41198d328088 added to master session list [BaseDriver] Event 'newSessionStarted' logged at 1505438111689 (21:15:11 GMT-0400 (Eastern Daylight Time)) [MJSONWP] Responding to client with driver.createSession() result: {"platform":"LINUX","webStorageEnabled":false,"takesScreenshot":true,"javascriptEnabled":true,"databaseEnabled":false,"networkConnectionEnabled":true,"locationContextEnabled":false,"warnings":{},"desired":{"appPackage":"com.android.calculator2","appActivity":".Calculator","platformVersion":"6.0","browserName":"","platformName":"Android","deviceName":"Android6"},"appPackage":"com.android.calculator2","appActivity":".Calculator","platformVersion":"6.0","browserName":"","platformName":"Android","deviceName":"emulator-5554","deviceUDID":"emulator-5554","deviceScreenSize":"1440x2560","deviceModel":"Android SDK built for x86","deviceManufacturer":"unknown"} [HTTP] <-- POST /wd/hub/session 200 21935 ms - 731 [HTTP] --> GET /wd/hub/session/f16844dd-c40b-4307-a6dd-41198d328088 {} [MJSONWP] Calling AppiumDriver.getSession() with args: ["f16844dd-c40b-4307-a6dd-41198d328088"] [MJSONWP] Responding to client with driver.getSession() result: {"platform":"LINUX","webStorageEnabled":false,"takesScreenshot":true,"javascriptEnabled":true,"databaseEnabled":false,"networkConnectionEnabled":true,"locationContextEnabled":false,"warnings":{},"desired":{"appPackage":"com.android.calculator2","appActivity":".Calculator","platformVersion":"6.0","browserName":"","platformName":"Android","deviceName":"Android6"},"appPackage":"com.android.calculator2","appActivity":".Calculator","platformVersion":"6.0","browserName":"","platformName":"Android","deviceName":"emulator-5554","deviceUDID":"emulator-5554","deviceScreenSize":"1440x2560","deviceModel":"Android SDK built for x86","deviceManufacturer":"unknown"} [HTTP] <-- GET /wd/hub/session/f16844dd-c40b-4307-a6dd-41198d328088 200 3 ms - 731 [HTTP] --> GET /wd/hub/session/f16844dd-c40b-4307-a6dd-41198d328088 {} [MJSONWP] Calling AppiumDriver.getSession() with args: ["f16844dd-c40b-4307-a6dd-41198d328088"] [MJSONWP] Responding to client with driver.getSession() result: {"platform":"LINUX","webStorageEnabled":false,"takesScreenshot":true,"javascriptEnabled":true,"databaseEnabled":false,"networkConnectionEnabled":true,"locationContextEnabled":false,"warnings":{},"desired":{"appPackage":"com.android.calculator2","appActivity":".Calculator","platformVersion":"6.0","browserName":"","platformName":"Android","deviceName":"Android6"},"appPackage":"com.android.calculator2","appActivity":".Calculator","platformVersion":"6.0","browserName":"","platformName":"Android","deviceName":"emulator-5554","deviceUDID":"emulator-5554","deviceScreenSize":"1440x2560","deviceModel":"Android SDK built for x86","deviceManufacturer":"unknown"} [HTTP] <-- GET /wd/hub/session/f16844dd-c40b-4307-a6dd-41198d328088 200 1 ms - 731 [HTTP] --> POST /wd/hub/session/f16844dd-c40b-4307-a6dd-41198d328088/element {"using":"name","value":"1"} [MJSONWP] Calling AppiumDriver.findElement() with args: ["name","1","f16844dd-c40b-4307-a6dd-41198d328088"] [BaseDriver] Valid locator strategies for this request: xpath, id, class name, accessibility id, -android uiautomator [HTTP] <-- POST /wd/hub/session/f16844dd-c40b-4307-a6dd-41198d328088/element 500 3 ms - 144 [HTTP] --> DELETE /wd/hub/session/f16844dd-c40b-4307-a6dd-41198d328088 {} [MJSONWP] Calling AppiumDriver.deleteSession() with args: ["f16844dd-c40b-4307-a6dd-41198d328088"] [BaseDriver] Event 'quitSessionRequested' logged at 1505438111834 (21:15:11 GMT-0400 (Eastern Daylight Time)) [AndroidDriver] Shutting down Android driver [ADB] Getting connected devices... [ADB] 1 device(s) connected [ADB] Running 'C:\Users\Chen Chen\AppData\Local\Android\Sdk\platform-tools\adb.exe' with args: ["-P",5037,"-s","emulator-5554","shell","am","force-stop","com.android.calculator2"] [ADB] Pressing the HOME button [ADB] Getting connected devices... [ADB] 1 device(s) connected [ADB] Running 'C:\Users\Chen Chen\AppData\Local\Android\Sdk\platform-tools\adb.exe' with args: ["-P",5037,"-s","emulator-5554","shell","input","keyevent",3] [AndroidBootstrap] Sending command to android: {"cmd":"shutdown"} [AndroidBootstrap] [BOOTSTRAP LOG] [debug] Got data from client: {"cmd":"shutdown"} [AndroidBootstrap] [BOOTSTRAP LOG] [debug] Got command of type SHUTDOWN [AndroidBootstrap] [BOOTSTRAP LOG] [debug] Returning result: {"status":0,"value":"OK, shutting down"} [AndroidBootstrap] [BOOTSTRAP LOG] [debug] Closed client connection [AndroidBootstrap] [UIAUTO STDOUT] INSTRUMENTATION_STATUS: numtests=1
[AndroidBootstrap] [UIAUTO STDOUT] INSTRUMENTATION_STATUS: stream=.
[AndroidBootstrap] [UIAUTO STDOUT] INSTRUMENTATION_STATUS: id=UiAutomatorTestRunner
[AndroidBootstrap] [UIAUTO STDOUT] INSTRUMENTATION_STATUS: test=testRunServer
[AndroidBootstrap] [UIAUTO STDOUT] INSTRUMENTATION_STATUS: class=io.appium.android.bootstrap.Bootstrap
[AndroidBootstrap] [UIAUTO STDOUT] INSTRUMENTATION_STATUS: current=1
[AndroidBootstrap] [UIAUTO STDOUT] INSTRUMENTATION_STATUS_CODE: 0
[AndroidBootstrap] Received command result from bootstrap [UiAutomator] Shutting down UiAutomator [UiAutomator] Moving to state 'stopping' [AndroidBootstrap] [UIAUTO STDOUT] INSTRUMENTATION_STATUS: stream=
[AndroidBootstrap] [UIAUTO STDOUT] Test results for WatcherResultPrinter=.
[AndroidBootstrap] [UIAUTO STDOUT] Time: 10.338
[AndroidBootstrap] [UIAUTO STDOUT] OK (1 test)
[AndroidBootstrap] [UIAUTO STDOUT] INSTRUMENTATION_STATUS_CODE: -1
[UiAutomator] UiAutomator shut down normally [UiAutomator] Moving to state 'stopped' [ADB] Attempting to kill all uiautomator processes [ADB] Getting all processes with uiautomator [ADB] Getting connected devices... [ADB] 1 device(s) connected [ADB] Running 'C:\Users\Chen Chen\AppData\Local\Android\Sdk\platform-tools\adb.exe' with args: ["-P",5037,"-s","emulator-5554","shell","ps"] [ADB] No uiautomator process found to kill, continuing... [UiAutomator] Moving to state 'stopped' [Logcat] Stopping logcat capture [ADB] Getting connected devices... [ADB] 1 device(s) connected [ADB] Running 'C:\Users\Chen Chen\AppData\Local\Android\Sdk\platform-tools\adb.exe' with args: ["-P",5037,"-s","emulator-5554","shell","am","force-stop","io.appium.unlock"] [AndroidDriver] Not cleaning generated files. Add
clearSystemFiles
capability if wanted. [Appium] Removing session f16844dd-c40b-4307-a6dd-41198d328088 from our master session list [BaseDriver] Event 'quitSessionFinished' logged at 1505438116699 (21:15:16 GMT-0400 (Eastern Daylight Time)) [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/f16844dd-c40b-4307-a6dd-41198d328088 200 4866 ms - 76run Junit error log