[info] [Appium] Welcome to Appium v1.15.1 [info] [Appium] Non-default server args: [info] [Appium] address: 127.0.0.1 [info] [Appium] allowInsecure: { [info] [Appium] } [info] [Appium] denyInsecure: { [info] [Appium] } [info] [Appium] Appium REST http interface listener started on 127.0.0.1:4723[info] [HTTP] --> POST /wd/hub/session [info] [HTTP] {"desiredCapabilities":{"appActivity":"com.slava.box.rich.com.slava.tv.activities.SplashScreen","appPackage":"ca.slava.tv.google.dev","platformVersion":"9","automationName":"UiAutomator2","platformName":"Android","deviceName":"emulator-5554"},"capabilities":{"firstMatch":[{"appium:appActivity":"com.slava.box.rich.com.slava.tv.activities.SplashScreen","appium:appPackage":"ca.slava.tv.google.dev","appium:automationName":"UiAutomator2","appium:deviceName":"emulator-5554","platformName":"android","appium:platformVersion":"9"}]}} [debug] [W3C] Calling AppiumDriver.createSession() with args: [{"appActivity":"com.slava.box.rich.com.slava.tv.activities.SplashScreen","appPackage":"ca.slava.tv.google.dev","platformVersion":"9","automationName":"UiAutomator2","platformName":"Android","deviceName":"emulator-5554"},null,{"firstMatch":[{"appium:appActivity":"com.slava.box.rich.com.slava.tv.activities.SplashScreen","appium:appPackage":"ca.slava.tv.google.dev","appium:automationName":"UiAutomator2","appium:deviceName":"emulator-5554","platformName":"android","appium:platformVersion":"9"}]}] [debug] [BaseDriver] Event 'newSessionRequested' logged at 1581941640181 (15:14:00 GMT+0300 (Russia TZ 2 Standard Time)) [info] [Appium] Appium v1.15.1 creating new AndroidUiautomator2Driver (v1.37.2) session [debug] [BaseDriver] W3C capabilities and MJSONWP desired capabilities were provided [debug] [BaseDriver] Creating session with W3C capabilities: { [debug] [BaseDriver] "alwaysMatch": { [debug] [BaseDriver] "platformName": "android", [debug] [BaseDriver] "appium:appActivity": "com.slava.box.rich.com.slava.tv.activities.SplashScreen", [debug] [BaseDriver] "appium:appPackage": "ca.slava.tv.google.dev", [debug] [BaseDriver] "appium:automationName": "UiAutomator2", [debug] [BaseDriver] "appium:deviceName": "emulator-5554", [debug] [BaseDriver] "appium:platformVersion": "9" [debug] [BaseDriver] }, [debug] [BaseDriver] "firstMatch": [ [debug] [BaseDriver] {} [debug] [BaseDriver] ] [debug] [BaseDriver] } [info] [BaseDriver] Session created with session id: 59f83235-ef88-4fc1-8a30-439100bb1e92 [info] [UiAutomator2] Starting 'ca.slava.tv.google.dev' directly on the device[info] [ADB] Using 'adb.exe' from 'C:\Users\slavan\AppData\Local\Android\Sdk\platform-tools\adb.exe' [info] [AndroidDriver] Retrieving device list [debug] [ADB] Trying to find a connected android device [debug] [ADB] Getting connected devices... [debug] [ADB] Connected devices: [{"udid":"emulator-5554","state":"device"}] [info] [AndroidDriver] Looking for a device with Android '9' [debug] [ADB] Setting device id to emulator-5554 [info] [ADB] Getting device platform version [debug] [ADB] Running 'C:\Users\slavan\AppData\Local\Android\Sdk\platform-tools\adb.exe -P 5037 -s emulator-5554 shell getprop ro.build.version.release'[debug] [ADB] Current device property 'ro.build.version.release': 9 [info] [AndroidDriver] Using device: emulator-5554 [info] [ADB] Using 'adb.exe' from 'C:\Users\slavan\AppData\Local\Android\Sdk\platform-tools\adb.exe' [debug] [ADB] Setting device id to emulator-5554 [debug] [ADB] Running 'C:\Users\slavan\AppData\Local\Android\Sdk\platform-tools\adb.exe -P 5037 -s emulator-5554 shell getprop ro.build.version.sdk' [debug] [ADB] Current device property 'ro.build.version.sdk': 28 [debug] [ADB] Running 'C:\Users\slavan\AppData\Local\Android\Sdk\platform-tools\adb.exe -P 5037 -s emulator-5554 shell getprop ro.build.version.release' [debug] [ADB] Current device property 'ro.build.version.release': 9 [debug] [ADB] Device API level: 28 [warn] [UiAutomator2] Relaxing hidden api policy [debug] [ADB] Running 'C:\Users\slavan\AppData\Local\Android\Sdk\platform-tools\adb.exe -P 5037 -s emulator-5554 shell settings put global hidden_api_policy_pre_p_apps 1'[debug] [ADB] Running 'C:\Users\slavan\AppData\Local\Android\Sdk\platform-tools\adb.exe -P 5037 -s emulator-5554 shell settings put global hidden_api_policy_p_apps 1' [debug] [ADB] Running 'C:\Users\slavan\AppData\Local\Android\Sdk\platform-tools\adb.exe -P 5037 -s emulator-5554 shell settings put global hidden_api_policy 1' [warn] [AndroidDriver] No app sent in, not parsing package/activity [debug] [ADB] Running 'C:\Users\slavan\AppData\Local\Android\Sdk\platform-tools\adb.exe -P 5037 -s emulator-5554 wait-for-device'[debug] [ADB] Running 'C:\Users\slavan\AppData\Local\Android\Sdk\platform-tools\adb.exe -P 5037 -s emulator-5554 shell echo ping' [debug] [AndroidDriver] Pushing settings apk to device... [debug] [ADB] Getting install status for io.appium.settings [debug] [ADB] Running 'C:\Users\slavan\AppData\Local\Android\Sdk\platform-tools\adb.exe -P 5037 -s emulator-5554 shell dumpsys package io.appium.settings' [debug] [ADB] 'io.appium.settings' is installed [debug] [ADB] Getting package info for 'io.appium.settings' [debug] [ADB] Running 'C:\Users\slavan\AppData\Local\Android\Sdk\platform-tools\adb.exe -P 5037 -s emulator-5554 shell dumpsys package io.appium.settings'[info] [ADB] Cannot extract apk info using apkanalyzer. Falling back to aapt. Original error: Could not find 'apkanalyzer.bat' in ["C:\\Users\\slavan\\AppData\\Local\\Android\\Sdk\\platform-tools\\apkanalyzer.bat","C:\\Users\\slavan\\AppData\\Local\\Android\\Sdk\\emulator\\apkanalyzer.bat","C:\\Users\\slavan\\AppData\\Local\\Android\\Sdk\\tools\\apkanalyzer.bat","C:\\Users\\slavan\\AppData\\Local\\Android\\Sdk\\tools\\bin\\apkanalyzer.bat","C:\\Users\\slavan\\AppData\\Local\\Android\\Sdk\\build-tools\\29.0.3\\apkanalyzer.bat","C:\\Users\\slavan\\AppData\\Local\\Android\\Sdk\\build-tools\\29.0.2\\apkanalyzer.bat","C:\\Users\\slavan\\AppData\\Local\\Android\\Sdk\\build-tools\\28.0.3\\apkanalyzer.bat","C:\\Users\\slavan\\AppData\\Local\\Android\\Sdk\\build-tools\\27.0.3\\apkanalyzer.bat"]. Do you have Android Build Tools installed at 'C:\Users\slavan\AppData\Local\Android\Sdk'? [info] [ADB] Using 'aapt.exe' from 'C:\Users\slavan\AppData\Local\Android\Sdk\build-tools\29.0.3\aapt.exe' [debug] [ADB] The version name of the installed 'io.appium.settings' is greater or equal to the application version name ('2.14.2' >= '2.14.2') [debug] [ADB] There is no need to install/upgrade 'C:\Users\slavan\AppData\Local\Programs\Appium\resources\app\node_modules\appium\node_modules\io.appium.settings\apks\settings_apk-debug.apk' [debug] [ADB] Getting IDs of all 'io.appium.settings' processes [debug] [ADB] Running 'C:\Users\slavan\AppData\Local\Android\Sdk\platform-tools\adb.exe -P 5037 -s emulator-5554 shell 'pgrep --help; echo $?'' [debug] [ADB] Running 'C:\Users\slavan\AppData\Local\Android\Sdk\platform-tools\adb.exe -P 5037 -s emulator-5554 shell pgrep -f io\\.appium\\.settings'[debug] [AndroidDriver] io.appium.settings is already running. There is no need to reset its permissions. [debug] [ADB] Running 'C:\Users\slavan\AppData\Local\Android\Sdk\platform-tools\adb.exe -P 5037 -s emulator-5554 shell appops set io.appium.settings android\:mock_location allow' [debug] [Logcat] Starting logcat capture [debug] [ADB] Getting install status for io.appium.uiautomator2.server [debug] [ADB] Running 'C:\Users\slavan\AppData\Local\Android\Sdk\platform-tools\adb.exe -P 5037 -s emulator-5554 shell dumpsys package io.appium.uiautomator2.server'[debug] [ADB] 'io.appium.uiautomator2.server' is installed [debug] [ADB] Getting package info for 'io.appium.uiautomator2.server' [debug] [ADB] Running 'C:\Users\slavan\AppData\Local\Android\Sdk\platform-tools\adb.exe -P 5037 -s emulator-5554 shell dumpsys package io.appium.uiautomator2.server' [info] [ADB] Cannot extract apk info using apkanalyzer. Falling back to aapt. Original error: Could not find 'apkanalyzer.bat' in ["C:\\Users\\slavan\\AppData\\Local\\Android\\Sdk\\platform-tools\\apkanalyzer.bat","C:\\Users\\slavan\\AppData\\Local\\Android\\Sdk\\emulator\\apkanalyzer.bat","C:\\Users\\slavan\\AppData\\Local\\Android\\Sdk\\tools\\apkanalyzer.bat","C:\\Users\\slavan\\AppData\\Local\\Android\\Sdk\\tools\\bin\\apkanalyzer.bat","C:\\Users\\slavan\\AppData\\Local\\Android\\Sdk\\build-tools\\29.0.3\\apkanalyzer.bat","C:\\Users\\slavan\\AppData\\Local\\Android\\Sdk\\build-tools\\29.0.2\\apkanalyzer.bat","C:\\Users\\slavan\\AppData\\Local\\Android\\Sdk\\build-tools\\28.0.3\\apkanalyzer.bat","C:\\Users\\slavan\\AppData\\Local\\Android\\Sdk\\build-tools\\27.0.3\\apkanalyzer.bat"]. Do you have Android Build Tools installed at 'C:\Users\slavan\AppData\Local\Android\Sdk'?[debug] [ADB] The version name of the installed 'io.appium.uiautomator2.server' is greater or equal to the application version name ('4.3.0' >= '4.3.0') [debug] [UiAutomator2] io.appium.uiautomator2.server installation state: sameVersionInstalled [debug] [ADB] Checking app cert for C:\Users\slavan\AppData\Local\Programs\Appium\resources\app\node_modules\appium\node_modules\appium-uiautomator2-server\apks\appium-uiautomator2-server-v4.3.0.apk [info] [ADB] Using 'apksigner.bat' from 'C:\Users\slavan\AppData\Local\Android\Sdk\build-tools\29.0.3\apksigner.bat' [debug] [ADB] Starting 'C:\Users\slavan\AppData\Local\Android\Sdk\build-tools\29.0.3\apksigner.bat' with args '["verify","--print-certs","C:\\Users\\slavan\\AppData\\Local\\Programs\\Appium\\resources\\app\\node_modules\\appium\\node_modules\\appium-uiautomator2-server\\apks\\appium-uiautomator2-server-v4.3.0.apk"]'[debug] [ADB] apksigner stdout: Signer #1 certificate DN: EMAILADDRESS=android@android.com, CN=Android, OU=Android, O=Android, L=Mountain View, ST=California, C=US [debug] [ADB] Signer #1 certificate SHA-256 digest: a40da80a59d170caa950cf15c18c454d47a39b26989d8b640ecd745ba71bf5dc [debug] [ADB] Signer #1 certificate SHA-1 digest: 61ed377e85d386a8dfee6b864bd85b0bfaa5af81 [debug] [ADB] Signer #1 certificate MD5 digest: e89b158e4bcf988ebd09eb83f5378e87 [debug] [ADB] [debug] [ADB] 'C:\Users\slavan\AppData\Local\Programs\Appium\resources\app\node_modules\appium\node_modules\appium-uiautomator2-server\apks\appium-uiautomator2-server-v4.3.0.apk' is already signed. [debug] [ADB] Getting install status for io.appium.uiautomator2.server.test [debug] [ADB] Running 'C:\Users\slavan\AppData\Local\Android\Sdk\platform-tools\adb.exe -P 5037 -s emulator-5554 shell dumpsys package io.appium.uiautomator2.server.test'[debug] [ADB] 'io.appium.uiautomator2.server.test' is installed [debug] [ADB] Checking app cert for C:\Users\slavan\AppData\Local\Programs\Appium\resources\app\node_modules\appium\node_modules\appium-uiautomator2-server\apks\appium-uiautomator2-server-debug-androidTest.apk [debug] [ADB] Starting 'C:\Users\slavan\AppData\Local\Android\Sdk\build-tools\29.0.3\apksigner.bat' with args '["verify","--print-certs","C:\\Users\\slavan\\AppData\\Local\\Programs\\Appium\\resources\\app\\node_modules\\appium\\node_modules\\appium-uiautomator2-server\\apks\\appium-uiautomator2-server-debug-androidTest.apk"]'[debug] [ADB] apksigner stdout: Signer #1 certificate DN: EMAILADDRESS=android@android.com, CN=Android, OU=Android, O=Android, L=Mountain View, ST=California, C=US [debug] [ADB] Signer #1 certificate SHA-256 digest: a40da80a59d170caa950cf15c18c454d47a39b26989d8b640ecd745ba71bf5dc [debug] [ADB] Signer #1 certificate SHA-1 digest: 61ed377e85d386a8dfee6b864bd85b0bfaa5af81 [debug] [ADB] Signer #1 certificate MD5 digest: e89b158e4bcf988ebd09eb83f5378e87 [debug] [ADB] [debug] [ADB] 'C:\Users\slavan\AppData\Local\Programs\Appium\resources\app\node_modules\appium\node_modules\appium-uiautomator2-server\apks\appium-uiautomator2-server-debug-androidTest.apk' is already signed. [info] [UiAutomator2] Server packages are not going to be (re)installed [debug] [UiAutomator2] Waiting up to 30000ms for services to be available [debug] [ADB] Running 'C:\Users\slavan\AppData\Local\Android\Sdk\platform-tools\adb.exe -P 5037 -s emulator-5554 shell pm list instrumentation'[debug] [UiAutomator2] No app capability. Assuming it is already on the device [debug] [ADB] Getting install status for ca.slava.tv.google.dev [debug] [ADB] Running 'C:\Users\slavan\AppData\Local\Android\Sdk\platform-tools\adb.exe -P 5037 -s emulator-5554 shell dumpsys package ca.slava.tv.google.dev' [debug] [ADB] 'ca.slava.tv.google.dev' is installed [debug] [ADB] Running 'C:\Users\slavan\AppData\Local\Android\Sdk\platform-tools\adb.exe -P 5037 -s emulator-5554 shell am force-stop ca.slava.tv.google.dev'[debug] [ADB] Running 'C:\Users\slavan\AppData\Local\Android\Sdk\platform-tools\adb.exe -P 5037 -s emulator-5554 shell pm clear ca.slava.tv.google.dev' [debug] [AndroidDriver] Performed fast reset on the installed 'ca.slava.tv.google.dev' application (stop and clear) [debug] [UiAutomator2] Performing shallow cleanup of automation leftovers [debug] [UiAutomator2] The following obsolete sessions are still running: ["ad7252d7-dd1c-4c07-a259-9d44a634b82b"] [debug] [UiAutomator2] Cleaning up the obsolete sessions[debug] [Instrumentation] .[debug] [ADB] Running 'C:\Users\slavan\AppData\Local\Android\Sdk\platform-tools\adb.exe -P 5037 -s emulator-5554 shell am force-stop io.appium.uiautomator2.server.test'[info] [UiAutomator2] Starting UIAutomator2 server 4.3.0 [info] [UiAutomator2] Using UIAutomator2 server from 'C:\Users\slavan\AppData\Local\Programs\Appium\resources\app\node_modules\appium\node_modules\appium-uiautomator2-server\apks\appium-uiautomator2-server-v4.3.0.apk' and test from 'C:\Users\slavan\AppData\Local\Programs\Appium\resources\app\node_modules\appium\node_modules\appium-uiautomator2-server\apks\appium-uiautomator2-server-debug-androidTest.apk' [info] [UiAutomator2] Waiting up to 30000ms for UiAutomator2 to be online... [debug] [ADB] Creating ADB subprocess with args: ["-P",5037,"-s","emulator-5554","shell","am","instrument","-w","io.appium.uiautomator2.server.test/androidx.test.runner.AndroidJUnitRunner"] [debug] [Instrumentation] INSTRUMENTATION_RESULT: shortMsg=Process crashed. [debug] [Instrumentation] INSTRUMENTATION_CODE: 0 [debug] [Instrumentation] The process has exited with code 0[debug] [Instrumentation] io.appium.uiautomator2.server.test.AppiumUiAutomator2Server:[debug] [WD Proxy] Matched '/status' to command name 'getStatus' [debug] [WD Proxy] Proxying [GET /status] to [GET http://localhost:8202/wd/hub/status] with no body [info] [WD Proxy] Got an unexpected response with status undefined: {"code":"ECONNRESET"}[debug] [WD Proxy] Matched '/status' to command name 'getStatus' [debug] [WD Proxy] Proxying [GET /status] to [GET http://localhost:8202/wd/hub/status] with no body [debug] [WD Proxy] Got response with status 200: {"sessionId":"None","value":{"ready":true,"message":"UiAutomator2 Server is ready to accept commands"}} [debug] [UiAutomator2] The initialization of the instrumentation process took 2095ms [debug] [WD Proxy] Matched '/session' to command name 'createSession' [debug] [WD Proxy] Proxying [POST /session] to [POST http://localhost:8202/wd/hub/session] with body: {"capabilities":{"firstMatch":[{"platform":"LINUX","webStorageEnabled":false,"takesScreenshot":true,"javascriptEnabled":true,"databaseEnabled":false,"networkConnectionEnabled":true,"locationContextEnabled":false,"warnings":{},"desired":{"platformName":"android","appActivity":"com.slava.box.rich.com.slava.tv.activities.SplashScreen","appPackage":"ca.slava.tv.google.dev","automationName":"UiAutomator2","deviceName":"emulator-5554","platformVersion":"9"},"platformName":"android","appActivity":"com.slava.box.rich.com.slava.tv.activities.SplashScreen","appPackage":"ca.slava.tv.google.dev","automationName":"UiAutomator2","deviceName":"emulator-5554","platformVersion":"9","deviceUDID":"emulator-5554"}],"alwaysMatch":{}}} [debug] [WD Proxy] Got response with status 200: {"sessionId":"ba5e5c7a-9293-4f80-a803-ef8e090e4a30","value":{"sessionId":"ba5e5c7a-9293-4f80-a803-ef8e090e4a30","capabilities":{"firstMatch":[{"platform":"LINUX","webStorageEnabled":false,"takesScreenshot":true,"javascriptEnabled":true,"databaseEnabled":false,"networkConnectionEnabled":true,"locationContextEnabled":false,"warnings":{},"desired":{"platformName":"android","appActivity":"com.slava.box.rich.com.slava.tv.activities.SplashScreen","appPackage":"ca.slava.tv.google.dev","automationName":"UiAutomator2","deviceName":"emulator-5554","platformVersion":"9"},"platformName":"android","appActivity":"com.slava.box.rich.com.slava.tv.activities.SplashScreen","appPackage":"ca.slava.tv.google.dev","automationName":"UiAutomator2","deviceName":"emulator-5554","platformVersion":"9","deviceUDID":"emulator-5554"}],"alwaysMatch":{}}}} [info] [WD Proxy] Determined the downstream protocol as 'W3C' [debug] [WD Proxy] Proxying [GET /appium/device/info] to [GET http://localhost:8202/wd/hub/session/ba5e5c7a-9293-4f80-a803-ef8e090e4a30/appium/device/info] with no body [debug] [WD Proxy] Got response with status 200: {"sessionId":"ba5e5c7a-9293-4f80-a803-ef8e090e4a30","value":{"androidId":"fba969b3f9232a19","manufacturer":"unknown","model":"sdk_google_atv_x86","brand":"google","apiVersion":"28","platformVersion":"9","carrierName":"","realDisplaySize":"1280x720","displayDensity":213,"networks":[{"type":1,"typeName":"WIFI","subtype":0,"subtypeName":"","isConnected":true,"detailedState":"CONNECTED","state":"CONNECTED","extraInfo":null,"isAvailable":true,"isFailover":false,"isRoaming":false,"capabilities":{"transportTypes":"NET_CAPABILITY_SUPL","networkCapabilities":"","linkUpstreamBandwidthKbps":1048576,"linkDownBandwidthKbps":1048576,"signalStrength":-50,"networkSpecifier":null,"SSID":null}}],"locale":"en_US","timeZone":"GMT"}} [debug] [ADB] Running 'C:\Users\slavan\AppData\Local\Android\Sdk\platform-tools\adb.exe -P 5037 -s emulator-5554 shell dumpsys window' [info] [AndroidDriver] Screen already unlocked, doing nothing [info] [UiAutomator2] Starting 'ca.slava.tv.google.dev/com.slava.box.rich.com.slava.tv.activities.SplashScreen and waiting for 'ca.slava.tv.google.dev/com.slava.box.rich.com.slava.tv.activities.SplashScreen' [debug] [ADB] Running 'C:\Users\slavan\AppData\Local\Android\Sdk\platform-tools\adb.exe -P 5037 -s emulator-5554 shell am start -W -n ca.slava.tv.google.dev/com.slava.box.rich.com.slava.tv.activities.SplashScreen -S'[debug] [WD Proxy] Proxying [GET /appium/device/pixel_ratio] to [GET http://localhost:8202/wd/hub/session/ba5e5c7a-9293-4f80-a803-ef8e090e4a30/appium/device/pixel_ratio] with body: {} [debug] [WD Proxy] Got response with status 200: {"sessionId":"ba5e5c7a-9293-4f80-a803-ef8e090e4a30","value":1.3312501} [debug] [WD Proxy] Matched '/appium/device/system_bars' to command name 'getSystemBars' [debug] [WD Proxy] Proxying [GET /appium/device/system_bars] to [GET http://localhost:8202/wd/hub/session/ba5e5c7a-9293-4f80-a803-ef8e090e4a30/appium/device/system_bars] with body: {} [debug] [WD Proxy] Got response with status 200: {"sessionId":"ba5e5c7a-9293-4f80-a803-ef8e090e4a30","value":{"statusBar":0}} [debug] [WD Proxy] Matched '/window/current/size' to command name 'getWindowSize' [debug] [WD Proxy] Proxying [GET /window/current/size] to [GET http://localhost:8202/wd/hub/session/ba5e5c7a-9293-4f80-a803-ef8e090e4a30/window/current/size] with body: {} [debug] [WD Proxy] Got response with status 200: {"sessionId":"ba5e5c7a-9293-4f80-a803-ef8e090e4a30","value":{"height":720,"width":1280}} [info] [Appium] New AndroidUiautomator2Driver session created successfully, session 59f83235-ef88-4fc1-8a30-439100bb1e92 added to master session list [debug] [BaseDriver] Event 'newSessionStarted' logged at 1581941649366 (15:14:09 GMT+0300 (Russia TZ 2 Standard Time)) [debug] [W3C (59f83235)] Cached the protocol value 'W3C' for the new session 59f83235-ef88-4fc1-8a30-439100bb1e92 [debug] [W3C (59f83235)] Responding to client with driver.createSession() result: {"capabilities":{"platform":"LINUX","webStorageEnabled":false,"takesScreenshot":true,"javascriptEnabled":true,"databaseEnabled":false,"networkConnectionEnabled":true,"locationContextEnabled":false,"warnings":{},"desired":{"platformName":"android","appActivity":"com.slava.box.rich.com.slava.tv.activities.SplashScreen","appPackage":"ca.slava.tv.google.dev","automationName":"UiAutomator2","deviceName":"emulator-5554","platformVersion":"9"},"platformName":"android","appActivity":"com.slava.box.rich.com.slava.tv.activities.SplashScreen","appPackage":"ca.slava.tv.google.dev","automationName":"UiAutomator2","deviceName":"emulator-5554","platformVersion":"9","deviceUDID":"emulator-5554","deviceApiLevel":28,"deviceScreenSize":"1280x720","deviceScreenDensity":213,"deviceModel":"sdk_google_atv_x86","deviceManufacturer":"unknown","pixelRatio":1.3312501,"statBarHeight":0,"viewportRect":{"left":0,"top":0,"width":1280,"height":720}}} [info] [HTTP] <-- POST /wd/hub/session 200 9186 ms - 999 [info] [HTTP]  [info] [HTTP] --> GET /wd/hub/session/59f83235-ef88-4fc1-8a30-439100bb1e92 [info] [HTTP] {} [debug] [W3C (59f83235)] Calling AppiumDriver.getSession() with args: ["59f83235-ef88-4fc1-8a30-439100bb1e92"] [debug] [UiAutomator2] Getting session details from server to mix in [debug] [WD Proxy] Matched '/' to command name 'getSession' [debug] [WD Proxy] Proxying [GET /] to [GET http://localhost:8202/wd/hub/session/ba5e5c7a-9293-4f80-a803-ef8e090e4a30] with body: {} [debug] [WD Proxy] Got response with status 200: {"sessionId":"ba5e5c7a-9293-4f80-a803-ef8e090e4a30","value":{}} [debug] [W3C (59f83235)] Responding to client with driver.getSession() result: {"platform":"LINUX","webStorageEnabled":false,"takesScreenshot":true,"javascriptEnabled":true,"databaseEnabled":false,"networkConnectionEnabled":true,"locationContextEnabled":false,"warnings":{},"desired":{"platformName":"android","appActivity":"com.slava.box.rich.com.slava.tv.activities.SplashScreen","appPackage":"ca.slava.tv.google.dev","automationName":"UiAutomator2","deviceName":"emulator-5554","platformVersion":"9"},"platformName":"android","appActivity":"com.slava.box.rich.com.slava.tv.activities.SplashScreen","appPackage":"ca.slava.tv.google.dev","automationName":"UiAutomator2","deviceName":"emulator-5554","platformVersion":"9","deviceUDID":"emulator-5554","deviceApiLevel":28,"deviceScreenSize":"1280x720","deviceScreenDensity":213,"deviceModel":"sdk_google_atv_x86","deviceManufacturer":"unknown","pixelRatio":1.3312501,"statBarHeight":0,"viewportRect":{"left":0,"top":0,"width":1280,"height":720}} [info] [HTTP] <-- GET /wd/hub/session/59f83235-ef88-4fc1-8a30-439100bb1e92 200 12 ms - 931 [info] [HTTP]  [info] [HTTP] --> GET /wd/hub/session/59f83235-ef88-4fc1-8a30-439100bb1e92 [info] [HTTP] {} [debug] [W3C (59f83235)] Calling AppiumDriver.getSession() with args: ["59f83235-ef88-4fc1-8a30-439100bb1e92"] [debug] [UiAutomator2] Getting session details from server to mix in [debug] [WD Proxy] Matched '/' to command name 'getSession' [debug] [WD Proxy] Proxying [GET /] to [GET http://localhost:8202/wd/hub/session/ba5e5c7a-9293-4f80-a803-ef8e090e4a30] with body: {} [debug] [WD Proxy] Got response with status 200: {"sessionId":"ba5e5c7a-9293-4f80-a803-ef8e090e4a30","value":{}} [debug] [W3C (59f83235)] Responding to client with driver.getSession() result: {"platform":"LINUX","webStorageEnabled":false,"takesScreenshot":true,"javascriptEnabled":true,"databaseEnabled":false,"networkConnectionEnabled":true,"locationContextEnabled":false,"warnings":{},"desired":{"platformName":"android","appActivity":"com.slava.box.rich.com.slava.tv.activities.SplashScreen","appPackage":"ca.slava.tv.google.dev","automationName":"UiAutomator2","deviceName":"emulator-5554","platformVersion":"9"},"platformName":"android","appActivity":"com.slava.box.rich.com.slava.tv.activities.SplashScreen","appPackage":"ca.slava.tv.google.dev","automationName":"UiAutomator2","deviceName":"emulator-5554","platformVersion":"9","deviceUDID":"emulator-5554","deviceApiLevel":28,"deviceScreenSize":"1280x720","deviceScreenDensity":213,"deviceModel":"sdk_google_atv_x86","deviceManufacturer":"unknown","pixelRatio":1.3312501,"statBarHeight":0,"viewportRect":{"left":0,"top":0,"width":1280,"height":720}} [info] [HTTP] <-- GET /wd/hub/session/59f83235-ef88-4fc1-8a30-439100bb1e92 200 10 ms - 931 [info] [HTTP]  [info] [HTTP] --> POST /wd/hub/session/59f83235-ef88-4fc1-8a30-439100bb1e92/timeouts [info] [HTTP] {"implicit":5000} [debug] [W3C (59f83235)] Calling AppiumDriver.timeouts() with args: [null,null,null,null,5000,"59f83235-ef88-4fc1-8a30-439100bb1e92"] [debug] [BaseDriver] W3C timeout argument: {"implicit":5000}} [debug] [BaseDriver] Set implicit wait to 5000ms [debug] [W3C (59f83235)] Responding to client with driver.timeouts() result: null [info] [HTTP] <-- POST /wd/hub/session/59f83235-ef88-4fc1-8a30-439100bb1e92/timeouts 200 1 ms - 14 [info] [HTTP]  [info] [HTTP] --> POST /wd/hub/session/59f83235-ef88-4fc1-8a30-439100bb1e92/element [info] [HTTP] {"using":"-android uiautomator","value":"new UiSelector().resourceIdMatches(\".*guidedactions_item_title\").text(\"Next\")"} [debug] [W3C (59f83235)] Calling AppiumDriver.findElement() with args: ["-android uiautomator","new UiSelector().resourceIdMatches(\".*guidedactions_item_title\").text(\"Next\")","59f83235-ef88-4fc1-8a30-439100bb1e92"] [debug] [BaseDriver] Valid locator strategies for this request: xpath, id, class name, accessibility id, -android uiautomator [debug] [BaseDriver] Waiting up to 5000 ms for condition [debug] [WD Proxy] Matched '/element' to command name 'findElement' [debug] [WD Proxy] Proxying [POST /element] to [POST http://localhost:8202/wd/hub/session/ba5e5c7a-9293-4f80-a803-ef8e090e4a30/element] with body: {"strategy":"-android uiautomator","selector":"new UiSelector().resourceIdMatches(\".*guidedactions_item_title\").text(\"Next\")","context":"","multiple":false}[debug] [WD Proxy] Got response with status 200: {"sessionId":"ba5e5c7a-9293-4f80-a803-ef8e090e4a30","value":{"ELEMENT":"608f6760-81f6-45f2-8a0c-b8686303a45e","element-6066-11e4-a52e-4f735466cecf":"608f6760-81f6-45f2-8a0c-b8686303a45e"}} [debug] [W3C (59f83235)] Responding to client with driver.findElement() result: {"element-6066-11e4-a52e-4f735466cecf":"608f6760-81f6-45f2-8a0c-b8686303a45e","ELEMENT":"608f6760-81f6-45f2-8a0c-b8686303a45e"} [info] [HTTP] <-- POST /wd/hub/session/59f83235-ef88-4fc1-8a30-439100bb1e92/element 200 413 ms - 137 [info] [HTTP]  [info] [HTTP] --> POST /wd/hub/session/59f83235-ef88-4fc1-8a30-439100bb1e92/timeouts [info] [HTTP] {"implicit":5000} [debug] [W3C (59f83235)] Calling AppiumDriver.timeouts() with args: [null,null,null,null,5000,"59f83235-ef88-4fc1-8a30-439100bb1e92"] [debug] [BaseDriver] W3C timeout argument: {"implicit":5000}} [debug] [BaseDriver] Set implicit wait to 5000ms [debug] [W3C (59f83235)] Responding to client with driver.timeouts() result: null [info] [HTTP] <-- POST /wd/hub/session/59f83235-ef88-4fc1-8a30-439100bb1e92/timeouts 200 1 ms - 14 [info] [HTTP]  [info] [HTTP] --> DELETE /wd/hub/session/59f83235-ef88-4fc1-8a30-439100bb1e92 [info] [HTTP] {} [debug] [W3C (59f83235)] Calling AppiumDriver.deleteSession() with args: ["59f83235-ef88-4fc1-8a30-439100bb1e92"] [debug] [BaseDriver] Event 'quitSessionRequested' logged at 1581941649892 (15:14:09 GMT+0300 (Russia TZ 2 Standard Time)) [info] [Appium] Removing session 59f83235-ef88-4fc1-8a30-439100bb1e92 from our master session list [debug] [UiAutomator2] Deleting UiAutomator2 session [debug] [UiAutomator2] Deleting UiAutomator2 server session [debug] [WD Proxy] Matched '/' to command name 'deleteSession' [debug] [WD Proxy] Proxying [DELETE /] to [DELETE http://localhost:8202/wd/hub/session/ba5e5c7a-9293-4f80-a803-ef8e090e4a30] with no body [debug] [WD Proxy] Got response with status 200: {"sessionId":"ba5e5c7a-9293-4f80-a803-ef8e090e4a30","value":null} [debug] [ADB] Running 'C:\Users\slavan\AppData\Local\Android\Sdk\platform-tools\adb.exe -P 5037 -s emulator-5554 shell am force-stop ca.slava.tv.google.dev'[debug] [Logcat] Stopping logcat capture [debug] [ADB] Removing forwarded port socket connection: 8202 [debug] [ADB] Running 'C:\Users\slavan\AppData\Local\Android\Sdk\platform-tools\adb.exe -P 5037 -s emulator-5554 forward --remove tcp\:8202' [info] [UiAutomator2] Restoring hidden api policy to the device default configuration [debug] [ADB] Running 'C:\Users\slavan\AppData\Local\Android\Sdk\platform-tools\adb.exe -P 5037 -s emulator-5554 shell settings delete global hidden_api_policy_pre_p_apps' [debug] [ADB] Running 'C:\Users\slavan\AppData\Local\Android\Sdk\platform-tools\adb.exe -P 5037 -s emulator-5554 shell settings delete global hidden_api_policy_p_apps'[debug] [ADB] Running 'C:\Users\slavan\AppData\Local\Android\Sdk\platform-tools\adb.exe -P 5037 -s emulator-5554 shell settings delete global hidden_api_policy' [debug] [Instrumentation] . [debug] [BaseDriver] Event 'quitSessionFinished' logged at 1581941650378 (15:14:10 GMT+0300 (Russia TZ 2 Standard Time)) [debug] [W3C (59f83235)] Received response: null [debug] [W3C (59f83235)] But deleting session, so not returning [debug] [W3C (59f83235)] Responding to client with driver.deleteSession() result: null [info] [HTTP] <-- DELETE /wd/hub/session/59f83235-ef88-4fc1-8a30-439100bb1e92 200 486 ms - 14 [info] [HTTP] [debug] [Instrumentation] Time: 4.033 [debug] [Instrumentation] [debug] [Instrumentation] OK (1 test) [debug] [Instrumentation] The process has exited with code 0[warn] [BaseDriver] Shutting down because we waited 60 seconds for a command [debug] [UiAutomator2] Deleting UiAutomator2 session [warn] [Appium] Closing session, cause was 'New Command Timeout of 60 seconds expired. Try customizing the timeout using the 'newCommandTimeout' desired capability' [info] [Appium] Removing session bc8a60a9-5479-4c77-ac47-4c9f6ff98f13 from our master session list [debug] [UiAutomator2] Deleting UiAutomator2 server session [debug] [WD Proxy] Matched '/' to command name 'deleteSession' [debug] [WD Proxy] Proxying [DELETE /] to [DELETE http://localhost:8200/wd/hub/session/ad7252d7-dd1c-4c07-a259-9d44a634b82b] with no body [info] [WD Proxy] Got an unexpected response with status undefined: {"code":"ECONNRESET"} [warn] [UiAutomator2] Did not get confirmation UiAutomator2 deleteSession worked; Error was: UnknownError: An unknown server-side error occurred while processing the command. Original error: Could not proxy command to remote server. Original error: Error: socket hang up [debug] [ADB] Running 'C:\Users\slavan\AppData\Local\Android\Sdk\platform-tools\adb.exe -P 5037 -s emulator-5554 shell am force-stop ca.slava.tv.google.dev' [debug] [Logcat] Stopping logcat capture [debug] [ADB] Removing forwarded port socket connection: 8200 [debug] [ADB] Running 'C:\Users\slavan\AppData\Local\Android\Sdk\platform-tools\adb.exe -P 5037 -s emulator-5554 forward --remove tcp\:8200' [info] [UiAutomator2] Restoring hidden api policy to the device default configuration [debug] [ADB] Running 'C:\Users\slavan\AppData\Local\Android\Sdk\platform-tools\adb.exe -P 5037 -s emulator-5554 shell settings delete global hidden_api_policy_pre_p_apps'[debug] [ADB] Running 'C:\Users\slavan\AppData\Local\Android\Sdk\platform-tools\adb.exe -P 5037 -s emulator-5554 shell settings delete global hidden_api_policy_p_apps' [debug] [ADB] Running 'C:\Users\slavan\AppData\Local\Android\Sdk\platform-tools\adb.exe -P 5037 -s emulator-5554 shell settings delete global hidden_api_policy'