[info] [Appium] Welcome to Appium v1.15.1 [info] [Appium] Non-default server args: [info] [Appium] allowInsecure: { [info] [Appium] } [info] [Appium] denyInsecure: { [info] [Appium] } [info] [Appium] Appium REST http interface listener started on 0.0.0.0:4723[info] [HTTP] --> GET /wd/hub/sessions [info] [HTTP] {} [debug] [GENERIC] Calling AppiumDriver.getSessions() with args: [] [debug] [GENERIC] Responding to client with driver.getSessions() result: [] [info] [HTTP] <-- GET /wd/hub/sessions 200 6 ms - 40 [info] [HTTP] [info] [HTTP] --> POST /wd/hub/session [info] [HTTP] {"desiredCapabilities":{"newCommandTimeout":0,"connectHardwareKeyboard":true,"browserName":"firefox","version":"","javascriptEnabled":true,"platform":"ANY"}} [debug] [MJSONWP] Calling AppiumDriver.createSession() with args: [{"newCommandTimeout":0,"connectHardwareKeyboard":true,"browserName":"firefox","version":"","javascriptEnabled":true,"platform":"ANY"},null,null] [debug] [BaseDriver] Event 'newSessionRequested' logged at 1605150131336 (11:02:11 GMT+0800 (中国标准时间)) [debug] [BaseDriver] Event 'newSessionStarted' logged at 1605150131338 (11:02:11 GMT+0800 (中国标准时间)) [debug] [MJSONWP] Encountered internal error running command: Error: You must include a platformName capability [debug] [MJSONWP] at AppiumDriver.getDriverAndVersionForCaps (C:\Program Files\Appium\resources\app\node_modules\appium\lib\appium.js:195:13) [debug] [MJSONWP] at AppiumDriver.getDriverAndVersionForCaps [as createSession] (C:\Program Files\Appium\resources\app\node_modules\appium\lib\appium.js:300:66) [debug] [MJSONWP] at AppiumDriver.executeCommand (C:\Program Files\Appium\resources\app\node_modules\appium\node_modules\appium-base-driver\lib\basedriver\driver.js:376:19) [debug] [MJSONWP] at AppiumDriver.executeCommand (C:\Program Files\Appium\resources\app\node_modules\appium\lib\appium.js:482:26) [debug] [MJSONWP] at executeCommand (C:\Program Files\Appium\resources\app\node_modules\appium\node_modules\appium-base-driver\lib\protocol\protocol.js:306:34) [debug] [MJSONWP] at asyncHandler (C:\Program Files\Appium\resources\app\node_modules\appium\node_modules\appium-base-driver\lib\protocol\protocol.js:432:15) [debug] [MJSONWP] at Layer.handle [as handle_request] (C:\Program Files\Appium\resources\app\node_modules\appium\node_modules\express\lib\router\layer.js:95:5) [debug] [MJSONWP] at next (C:\Program Files\Appium\resources\app\node_modules\appium\node_modules\express\lib\router\route.js:137:13) [debug] [MJSONWP] at Route.dispatch (C:\Program Files\Appium\resources\app\node_modules\appium\node_modules\express\lib\router\route.js:112:3) [debug] [MJSONWP] at Layer.handle [as handle_request] (C:\Program Files\Appium\resources\app\node_modules\appium\node_modules\express\lib\router\layer.js:95:5) [debug] [MJSONWP] at C:\Program Files\Appium\resources\app\node_modules\appium\node_modules\express\lib\router\index.js:281:22 [debug] [MJSONWP] at Function.process_params (C:\Program Files\Appium\resources\app\node_modules\appium\node_modules\express\lib\router\index.js:335:12) [debug] [MJSONWP] at next (C:\Program Files\Appium\resources\app\node_modules\appium\node_modules\express\lib\router\index.js:275:10) [debug] [MJSONWP] at logger (C:\Program Files\Appium\resources\app\node_modules\appium\node_modules\morgan\index.js:144:5) [debug] [MJSONWP] at Layer.handle [as handle_request] (C:\Program Files\Appium\resources\app\node_modules\appium\node_modules\express\lib\router\layer.js:95:5) [debug] [MJSONWP] at trim_prefix (C:\Program Files\Appium\resources\app\node_modules\appium\node_modules\express\lib\router\index.js:317:13) [debug] [MJSONWP] at C:\Program Files\Appium\resources\app\node_modules\appium\node_modules\express\lib\router\index.js:284:7 [debug] [MJSONWP] at Function.process_params (C:\Program Files\Appium\resources\app\node_modules\appium\node_modules\express\lib\router\index.js:335:12) [debug] [MJSONWP] at next (C:\Program Files\Appium\resources\app\node_modules\appium\node_modules\express\lib\router\index.js:275:10) [debug] [MJSONWP] at C:\Program Files\Appium\resources\app\node_modules\appium\node_modules\body-parser\lib\read.js:130:5 [debug] [MJSONWP] at invokeCallback (C:\Program Files\Appium\resources\app\node_modules\appium\node_modules\raw-body\index.js:224:16) [debug] [MJSONWP] at done (C:\Program Files\Appium\resources\app\node_modules\appium\node_modules\raw-body\index.js:213:7) [debug] [MJSONWP] at IncomingMessage.onEnd (C:\Program Files\Appium\resources\app\node_modules\appium\node_modules\raw-body\index.js:273:7) [debug] [MJSONWP] at IncomingMessage.emit (events.js:182:13) [debug] [MJSONWP] at endReadableNT (_stream_readable.js:1090:12) [debug] [MJSONWP] at process._tickCallback (internal/process/next_tick.js:63:19) [info] [HTTP] <-- POST /wd/hub/session 500 57 ms - 179 [info] [HTTP]  [info] [HTTP] --> DELETE /wd/hub/session [info] [HTTP] {} [debug] [HTTP] No route found. Setting content type to 'text/plain' [info] [HTTP] <-- DELETE /wd/hub/session 404 3 ms - 57 [info] [HTTP] [info] [HTTP] --> POST /wd/hub/session [info] [HTTP] {"desiredCapabilities":{"appActivity":"com.gov.customs.zshgupgrade.view.welcome.WelcomeActivity","appPackage":"cn.gov.customs.zshg","automationName":"UiAutomator1","deviceName":"5LM0216906000429","platformName":"Android","platformVersion":"7.0","newCommandTimeout":0,"connectHardwareKeyboard":true}} [debug] [MJSONWP] Calling AppiumDriver.createSession() with args: [{"appActivity":"com.gov.customs.zshgupgrade.view.welcome.WelcomeActivity","appPackage":"cn.gov.customs.zshg","automationName":"UiAutomator1","deviceName":"5LM0216906000429","platformName":"Android","platformVersion":"7.0","newCommandTimeout":0,"connectHardwareKeyboard":true},null,null] [debug] [BaseDriver] Event 'newSessionRequested' logged at 1605150133538 (11:02:13 GMT+0800 (中国标准时间)) [info] [Appium] Appium v1.15.1 creating new AndroidDriver (v4.20.0) session [debug] [BaseDriver] Creating session with MJSONWP desired capabilities: { [debug] [BaseDriver] "appActivity": "com.gov.customs.zshgupgrade.view.welcome.WelcomeActivity", [debug] [BaseDriver] "appPackage": "cn.gov.customs.zshg", [debug] [BaseDriver] "automationName": "UiAutomator1", [debug] [BaseDriver] "deviceName": "5LM0216906000429", [debug] [BaseDriver] "platformName": "Android", [debug] [BaseDriver] "platformVersion": "7.0", [debug] [BaseDriver] "newCommandTimeout": 0, [debug] [BaseDriver] "connectHardwareKeyboard": true [debug] [BaseDriver] } [warn] [BaseDriver] The following capabilities were provided, but are not recognized by Appium: [warn] [BaseDriver] connectHardwareKeyboard [info] [BaseDriver] Session created with session id: e62ef305-4e4c-4b93-929f-47b652199789[debug] [ADB] Connected devices: [{"udid":"5LM0216906000429","state":"device"}] [info] [AndroidDriver] Looking for a device with Android '7.0' [debug] [ADB] Setting device id to 5LM0216906000429 [info] [ADB] Getting device platform version [debug] [ADB] Running 'D:\Program Files (x86)\Android\android-sdk\platform-tools\adb.exe -P 5037 -s 5LM0216906000429 shell getprop ro.build.version.release'[debug] [ADB] Current device property 'ro.build.version.release': 7.0 [info] [AndroidDriver] Using device: 5LM0216906000429 [info] [ADB] Using 'adb.exe' from 'D:\Program Files (x86)\Android\android-sdk\platform-tools\adb.exe' [debug] [ADB] Setting device id to 5LM0216906000429 [debug] [ADB] Running 'D:\Program Files (x86)\Android\android-sdk\platform-tools\adb.exe -P 5037 -s 5LM0216906000429 shell getprop ro.build.version.sdk'[debug] [ADB] Current device property 'ro.build.version.sdk': 24 [debug] [ADB] Device API level: 24 [warn] [AndroidDriver] Consider setting 'automationName' capability to 'uiautomator2' on Android >= 6, since UIAutomator framework is not maintained anymore by the OS vendor. [info] [AndroidDriver] App file was not listed, instead we're going to run cn.gov.customs.zshg directly on the device [debug] [AndroidDriver] Checking whether package is present on the device [debug] [ADB] Running 'D:\Program Files (x86)\Android\android-sdk\platform-tools\adb.exe -P 5037 -s 5LM0216906000429 shell pm list packages cn.gov.customs.zshg'[info] [AndroidDriver] Starting Android session [debug] [ADB] Running 'D:\Program Files (x86)\Android\android-sdk\platform-tools\adb.exe -P 5037 -s 5LM0216906000429 wait-for-device'[debug] [ADB] Running 'D:\Program Files (x86)\Android\android-sdk\platform-tools\adb.exe -P 5037 -s 5LM0216906000429 shell echo ping'[debug] [AndroidDriver] Pushing settings apk to device... [debug] [ADB] Getting install status for io.appium.settings [debug] [ADB] Running 'D:\Program Files (x86)\Android\android-sdk\platform-tools\adb.exe -P 5037 -s 5LM0216906000429 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 'D:\Program Files (x86)\Android\android-sdk\platform-tools\adb.exe -P 5037 -s 5LM0216906000429 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 ["D:\\Program Files (x86)\\Android\\android-sdk\\platform-tools\\apkanalyzer.bat","D:\\Program Files (x86)\\Android\\android-sdk\\emulator\\apkanalyzer.bat","D:\\Program Files (x86)\\Android\\android-sdk\\tools\\apkanalyzer.bat","D:\\Program Files (x86)\\Android\\android-sdk\\tools\\bin\\apkanalyzer.bat","D:\\Program Files (x86)\\Android\\android-sdk\\build-tools\\29.0.3\\apkanalyzer.bat"]. Do you have Android Build Tools installed at 'D:\Program Files (x86)\Android\android-sdk'? [info] [ADB] Using 'aapt.exe' from 'D:\Program Files (x86)\Android\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:\Program Files\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 'D:\Program Files (x86)\Android\android-sdk\platform-tools\adb.exe -P 5037 -s 5LM0216906000429 shell 'pgrep --help; echo $?''[debug] [ADB] Running 'D:\Program Files (x86)\Android\android-sdk\platform-tools\adb.exe -P 5037 -s 5LM0216906000429 shell pgrep \^appium\\.settings\$'[debug] [ADB] Running 'D:\Program Files (x86)\Android\android-sdk\platform-tools\adb.exe -P 5037 -s 5LM0216906000429 shell am start -n io.appium.settings/.Settings -a android.intent.action.MAIN -c android.intent.category.LAUNCHER'[debug] [ADB] Getting IDs of all 'io.appium.settings' processes [debug] [ADB] Running 'D:\Program Files (x86)\Android\android-sdk\platform-tools\adb.exe -P 5037 -s 5LM0216906000429 shell pgrep \^appium\\.settings\$'[debug] [ADB] Running 'D:\Program Files (x86)\Android\android-sdk\platform-tools\adb.exe -P 5037 -s 5LM0216906000429 shell appops set io.appium.settings android\:mock_location allow' [debug] [Logcat] Starting logcat capture[info] [ADB] Getting device platform version [debug] [ADB] Running 'D:\Program Files (x86)\Android\android-sdk\platform-tools\adb.exe -P 5037 -s 5LM0216906000429 shell getprop ro.build.version.release'[debug] [ADB] Current device property 'ro.build.version.release': 7.0 [debug] [ADB] Running 'D:\Program Files (x86)\Android\android-sdk\platform-tools\adb.exe -P 5037 -s 5LM0216906000429 shell wm size'[debug] [ADB] Running 'D:\Program Files (x86)\Android\android-sdk\platform-tools\adb.exe -P 5037 -s 5LM0216906000429 shell getprop ro.product.model'[debug] [ADB] Current device property 'ro.product.model': HUAWEI NXT-AL10 [debug] [ADB] Running 'D:\Program Files (x86)\Android\android-sdk\platform-tools\adb.exe -P 5037 -s 5LM0216906000429 shell getprop ro.product.manufacturer'[debug] [ADB] Current device property 'ro.product.manufacturer': HUAWEI [warn] [AndroidDriver] No app sent in, not parsing package/activity [debug] [AndroidDriver] No app capability. Assuming it is already on the device [debug] [ADB] Getting install status for cn.gov.customs.zshg [debug] [ADB] Running 'D:\Program Files (x86)\Android\android-sdk\platform-tools\adb.exe -P 5037 -s 5LM0216906000429 shell dumpsys package cn.gov.customs.zshg'[debug] [ADB] 'cn.gov.customs.zshg' is installed [debug] [ADB] Running 'D:\Program Files (x86)\Android\android-sdk\platform-tools\adb.exe -P 5037 -s 5LM0216906000429 shell am force-stop cn.gov.customs.zshg'[debug] [ADB] Running 'D:\Program Files (x86)\Android\android-sdk\platform-tools\adb.exe -P 5037 -s 5LM0216906000429 shell pm clear cn.gov.customs.zshg'[debug] [AndroidDriver] Performed fast reset on the installed 'cn.gov.customs.zshg' application (stop and clear) [debug] [AndroidBootstrap] Watching for bootstrap disconnect [debug] [ADB] Forwarding system: 4724 to device: 4724 [debug] [ADB] Running 'D:\Program Files (x86)\Android\android-sdk\platform-tools\adb.exe -P 5037 -s 5LM0216906000429 forward tcp\:4724 tcp\:4724'[debug] [UiAutomator] Starting UiAutomator [debug] [UiAutomator] Moving to state 'starting' [debug] [UiAutomator] Parsing uiautomator jar [debug] [UiAutomator] Found jar name: 'AppiumBootstrap.jar' [debug] [ADB] Running 'D:\Program Files (x86)\Android\android-sdk\platform-tools\adb.exe -P 5037 -s 5LM0216906000429 shell mkdir -p /data/local' [debug] [ADB] Running 'D:\Program Files (x86)\Android\android-sdk\platform-tools\adb.exe -P 5037 -s 5LM0216906000429 push 'C:\\Program Files\\Appium\\resources\\app\\node_modules\\appium\\node_modules\\appium-android-driver\\bootstrap\\bin\\AppiumBootstrap.jar' /data/local/tmp/'[debug] [ADB] Attempting to kill all uiautomator processes [debug] [ADB] Getting IDs of all 'uiautomator' processes [debug] [ADB] Running 'D:\Program Files (x86)\Android\android-sdk\platform-tools\adb.exe -P 5037 -s 5LM0216906000429 shell pgrep \^uiautomator\$'[info] [ADB] No 'uiautomator' process has been found [debug] [UiAutomator] Starting UIAutomator [debug] [ADB] Creating ADB subprocess with args: ["-P",5037,"-s","5LM0216906000429","shell","uiautomator","runtest","AppiumBootstrap.jar","-c","io.appium.android.bootstrap.Bootstrap","-e","pkg","cn.gov.customs.zshg","-e","disableAndroidWatchers",false,"-e","acceptSslCerts",false][debug] [UiAutomator] Moving to state 'online' [debug] [AndroidBootstrap] [BOOTSTRAP LOG] [debug] Registered crash watchers. [info] [AndroidBootstrap] Android bootstrap socket is now connected [debug] [ADB] Running 'D:\Program Files (x86)\Android\android-sdk\platform-tools\adb.exe -P 5037 -s 5LM0216906000429 shell dumpsys window' [debug] [AndroidBootstrap] [BOOTSTRAP LOG] [debug] Client connected[info] [AndroidDriver] Screen already unlocked, doing nothing [debug] [ADB] Running 'D:\Program Files (x86)\Android\android-sdk\platform-tools\adb.exe -P 5037 -s 5LM0216906000429 shell am start -W -n cn.gov.customs.zshg/com.gov.customs.zshgupgrade.view.welcome.WelcomeActivity -S'[info] [Appium] New AndroidDriver session created successfully, session e62ef305-4e4c-4b93-929f-47b652199789 added to master session list [debug] [BaseDriver] Event 'newSessionStarted' logged at 1605150151521 (11:02:31 GMT+0800 (中国标准时间)) [debug] [MJSONWP (e62ef305)] Cached the protocol value 'MJSONWP' for the new session e62ef305-4e4c-4b93-929f-47b652199789 [debug] [MJSONWP (e62ef305)] Responding to client with driver.createSession() result: {"platform":"LINUX","webStorageEnabled":false,"takesScreenshot":true,"javascriptEnabled":true,"databaseEnabled":false,"networkConnectionEnabled":true,"locationContextEnabled":false,"warnings":{},"desired":{"appActivity":"com.gov.customs.zshgupgrade.view.welcome.WelcomeActivity","appPackage":"cn.gov.customs.zshg","automationName":"UiAutomator1","deviceName":"5LM0216906000429","platformName":"Android","platformVersion":"7.0","newCommandTimeout":0,"connectHardwareKeyboard":true},"appActivity":"com.gov.customs.zshgupgrade.view.welcome.WelcomeActivity","appPackage":"cn.gov.customs.zshg","automationName":"UiAutomator1","deviceName":"5LM0216906000429","platformName":"Android","platformVersion":"7.0","newCommandTimeout":0,"connectHardwareKeyboard":true,"deviceUDID":"5LM0216906000429","deviceScreenSize":"1080x1920","deviceModel":"HUAWEI NXT-AL10","deviceManufacturer":"HUAWEI"} [info] [HTTP] <-- POST /wd/hub/session 200 17985 ms - 952 [info] [HTTP]  [info] [HTTP] --> POST /wd/hub/session/e62ef305-4e4c-4b93-929f-47b652199789/context [info] [HTTP] {"name":"NATIVE_APP"} [debug] [MJSONWP (e62ef305)] Calling AppiumDriver.setContext() with args: ["NATIVE_APP","e62ef305-4e4c-4b93-929f-47b652199789"] [debug] [MJSONWP (e62ef305)] Responding to client with driver.setContext() result: null [info] [HTTP] <-- POST /wd/hub/session/e62ef305-4e4c-4b93-929f-47b652199789/context 200 2 ms - 76 [info] [HTTP] [info] [HTTP] --> GET /wd/hub/session/e62ef305-4e4c-4b93-929f-47b652199789/source [info] [HTTP] {} [debug] [MJSONWP (e62ef305)] Calling AppiumDriver.getPageSource() with args: ["e62ef305-4e4c-4b93-929f-47b652199789"] [info] [HTTP] --> GET /wd/hub/session/e62ef305-4e4c-4b93-929f-47b652199789/screenshot [info] [HTTP] {} [debug] [MJSONWP (e62ef305)] Calling AppiumDriver.getScreenshot() with args: ["e62ef305-4e4c-4b93-929f-47b652199789"] [info] [HTTP] --> GET /wd/hub/session/e62ef305-4e4c-4b93-929f-47b652199789/window/current/size [info] [HTTP] {} [debug] [MJSONWP (e62ef305)] Calling AppiumDriver.getWindowSize() with args: ["current","e62ef305-4e4c-4b93-929f-47b652199789"] [debug] [AndroidBootstrap] Sending command to android: {"cmd":"action","action":"source","params":{}} [debug] [AndroidBootstrap] [BOOTSTRAP LOG] [debug] Got data from client: {"cmd":"action","action":"source","params":{}} [debug] [AndroidBootstrap] [BOOTSTRAP LOG] [debug] Got command of type ACTION [debug] [AndroidBootstrap] [BOOTSTRAP LOG] [debug] Got command action: source[debug] [AndroidBootstrap] [BOOTSTRAP LOG] [debug] Returning result: {"status":0,"value":"<\/android.widget.FrameLayout><\/android.widget.LinearLayout><\/android.widget.FrameLayout><\/android.widget.FrameLayout><\/android.widget.FrameLayout><\/hierarchy>"} [debug] [AndroidBootstrap] Received command result from bootstrap [debug] [MJSONWP (e62ef305)] Responding to client with driver.getPageSource() result: " POST /wd/hub/session [info] [HTTP] {"capabilities":{"firstMatch":[{"platformName":"Android","appium:deviceName":"5LM0216906000429","appium:appPackage":"cn.gov.customs.zshg","appium:appActivity":"com.gov.customs.zshgupgrade.view.welcome.WelcomeActivity","appium:noReset":true}]},"desiredCapabilities":{"platformName":"Android","deviceName":"5LM0216906000429","appPackage":"cn.gov.customs.zshg","appActivity":"com.gov.customs.zshgupgrade.view.welcome.WelcomeActivity","noReset":true}} [debug] [W3C] Calling AppiumDriver.createSession() with args: [{"platformName":"Android","deviceName":"5LM0216906000429","appPackage":"cn.gov.customs.zshg","appActivity":"com.gov.customs.zshgupgrade.view.welcome.WelcomeActivity","noReset":true},null,{"firstMatch":[{"platformName":"Android","appium:deviceName":"5LM0216906000429","appium:appPackage":"cn.gov.customs.zshg","appium:appActivity":"com.gov.customs.zshgupgrade.view.welcome.WelcomeActivity","appium:noReset":true}]}] [debug] [BaseDriver] Event 'newSessionRequested' logged at 1605150160463 (11:02:40 GMT+0800 (中国标准时间)) [warn] [Appium] [warn] [Appium] ====================================================================== [warn] [Appium] DEPRECATION WARNING: [warn] [Appium] [warn] [Appium] The 'automationName' capability was not provided in the desired [warn] [Appium] capabilities for this Android session [warn] [Appium] [warn] [Appium] Setting 'automationName=UiAutomator2' by default and using the [warn] [Appium] UiAutomator2 Driver [warn] [Appium] [warn] [Appium] The next major version of Appium (2.x) will **require** the [warn] [Appium] 'automationName' capability to be set for all sessions on all [warn] [Appium] platforms [warn] [Appium] [warn] [Appium] In previous versions (Appium <= 1.13.x), the default was [warn] [Appium] 'automationName=UiAutomator1' [warn] [Appium] [warn] [Appium] If you wish to use that automation instead of UiAutomator2, please [warn] [Appium] add 'automationName=UiAutomator1' to your desired capabilities [warn] [Appium] [warn] [Appium] For more information about drivers, please visit [warn] [Appium] http://appium.io/docs/en/about-appium/intro/ and explore the [warn] [Appium] 'Drivers' menu [warn] [Appium] [warn] [Appium] ====================================================================== [warn] [Appium] [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:deviceName": "5LM0216906000429", [debug] [BaseDriver] "appium:appPackage": "cn.gov.customs.zshg", [debug] [BaseDriver] "appium:appActivity": "com.gov.customs.zshgupgrade.view.welcome.WelcomeActivity", [debug] [BaseDriver] "appium:noReset": true [debug] [BaseDriver] }, [debug] [BaseDriver] "firstMatch": [ [debug] [BaseDriver] {} [debug] [BaseDriver] ] [debug] [BaseDriver] } [info] [BaseDriver] Session created with session id: 8fa8372b-6a98-4e51-a458-d7ebd9f1a754 [info] [UiAutomator2] Starting 'cn.gov.customs.zshg' directly on the device[info] [ADB] Using 'adb.exe' from 'D:\Program Files (x86)\Android\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":"5LM0216906000429","state":"device"}] [info] [AndroidDriver] Using device: 5LM0216906000429 [info] [ADB] Using 'adb.exe' from 'D:\Program Files (x86)\Android\android-sdk\platform-tools\adb.exe' [debug] [ADB] Setting device id to 5LM0216906000429 [debug] [ADB] Running 'D:\Program Files (x86)\Android\android-sdk\platform-tools\adb.exe -P 5037 -s 5LM0216906000429 shell getprop ro.build.version.sdk' [debug] [ADB] Current device property 'ro.build.version.sdk': 24 [debug] [ADB] Device API level: 24 [warn] [AndroidDriver] No app sent in, not parsing package/activity [debug] [ADB] Running 'D:\Program Files (x86)\Android\android-sdk\platform-tools\adb.exe -P 5037 -s 5LM0216906000429 wait-for-device'[debug] [ADB] Running 'D:\Program Files (x86)\Android\android-sdk\platform-tools\adb.exe -P 5037 -s 5LM0216906000429 shell echo ping'[debug] [AndroidDriver] Pushing settings apk to device... [debug] [ADB] Getting install status for io.appium.settings [debug] [ADB] Running 'D:\Program Files (x86)\Android\android-sdk\platform-tools\adb.exe -P 5037 -s 5LM0216906000429 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 'D:\Program Files (x86)\Android\android-sdk\platform-tools\adb.exe -P 5037 -s 5LM0216906000429 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 ["D:\\Program Files (x86)\\Android\\android-sdk\\platform-tools\\apkanalyzer.bat","D:\\Program Files (x86)\\Android\\android-sdk\\emulator\\apkanalyzer.bat","D:\\Program Files (x86)\\Android\\android-sdk\\tools\\apkanalyzer.bat","D:\\Program Files (x86)\\Android\\android-sdk\\tools\\bin\\apkanalyzer.bat","D:\\Program Files (x86)\\Android\\android-sdk\\build-tools\\29.0.3\\apkanalyzer.bat"]. Do you have Android Build Tools installed at 'D:\Program Files (x86)\Android\android-sdk'? [info] [ADB] Using 'aapt.exe' from 'D:\Program Files (x86)\Android\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:\Program Files\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 'D:\Program Files (x86)\Android\android-sdk\platform-tools\adb.exe -P 5037 -s 5LM0216906000429 shell 'pgrep --help; echo $?''[debug] [ADB] Running 'D:\Program Files (x86)\Android\android-sdk\platform-tools\adb.exe -P 5037 -s 5LM0216906000429 shell pgrep \^appium\\.settings\$'[debug] [AndroidDriver] io.appium.settings is already running. There is no need to reset its permissions. [debug] [ADB] Running 'D:\Program Files (x86)\Android\android-sdk\platform-tools\adb.exe -P 5037 -s 5LM0216906000429 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 'D:\Program Files (x86)\Android\android-sdk\platform-tools\adb.exe -P 5037 -s 5LM0216906000429 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 'D:\Program Files (x86)\Android\android-sdk\platform-tools\adb.exe -P 5037 -s 5LM0216906000429 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 ["D:\\Program Files (x86)\\Android\\android-sdk\\platform-tools\\apkanalyzer.bat","D:\\Program Files (x86)\\Android\\android-sdk\\emulator\\apkanalyzer.bat","D:\\Program Files (x86)\\Android\\android-sdk\\tools\\apkanalyzer.bat","D:\\Program Files (x86)\\Android\\android-sdk\\tools\\bin\\apkanalyzer.bat","D:\\Program Files (x86)\\Android\\android-sdk\\build-tools\\29.0.3\\apkanalyzer.bat"]. Do you have Android Build Tools installed at 'D:\Program Files (x86)\Android\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:\Program Files\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 'D:\Program Files (x86)\Android\android-sdk\build-tools\29.0.3\apksigner.bat' [debug] [ADB] Starting 'D:\Program Files (x86)\Android\android-sdk\build-tools\29.0.3\apksigner.bat' with args '["verify","--print-certs","C:\\Program Files\\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:\Program Files\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 'D:\Program Files (x86)\Android\android-sdk\platform-tools\adb.exe -P 5037 -s 5LM0216906000429 shell dumpsys package io.appium.uiautomator2.server.test'[debug] [ADB] 'io.appium.uiautomator2.server.test' is installed [debug] [ADB] Checking app cert for C:\Program Files\Appium\resources\app\node_modules\appium\node_modules\appium-uiautomator2-server\apks\appium-uiautomator2-server-debug-androidTest.apk [debug] [ADB] Starting 'D:\Program Files (x86)\Android\android-sdk\build-tools\29.0.3\apksigner.bat' with args '["verify","--print-certs","C:\\Program Files\\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:\Program Files\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 'D:\Program Files (x86)\Android\android-sdk\platform-tools\adb.exe -P 5037 -s 5LM0216906000429 shell pm list instrumentation'[debug] [UiAutomator2] Instrumentation target 'io.appium.uiautomator2.server.test/androidx.test.runner.AndroidJUnitRunner' is available [debug] [UiAutomator2] Forwarding UiAutomator2 Server port 6790 to 8200 [debug] [ADB] Forwarding system: 8200 to device: 6790 [debug] [ADB] Running 'D:\Program Files (x86)\Android\android-sdk\platform-tools\adb.exe -P 5037 -s 5LM0216906000429 forward tcp\:8200 tcp\:6790'[debug] [UiAutomator2] No app capability. Assuming it is already on the device [debug] [UiAutomator2] Performing shallow cleanup of automation leftovers [debug] [UiAutomator2] No obsolete sessions have been detected (Error: socket hang up) [debug] [ADB] Running 'D:\Program Files (x86)\Android\android-sdk\platform-tools\adb.exe -P 5037 -s 5LM0216906000429 shell am force-stop io.appium.uiautomator2.server.test'[info] [UiAutomator2] Starting UIAutomator2 server 4.3.0 [info] [UiAutomator2] Using UIAutomator2 server from 'C:\Program Files\Appium\resources\app\node_modules\appium\node_modules\appium-uiautomator2-server\apks\appium-uiautomator2-server-v4.3.0.apk' and test from 'C:\Program Files\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","5LM0216906000429","shell","am","instrument","-w","io.appium.uiautomator2.server.test/androidx.test.runner.AndroidJUnitRunner"][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:8200/wd/hub/status] with no body [info] [WD Proxy] Got an unexpected response with status undefined: {"code":"ECONNRESET"}[debug] [Instrumentation] java.lang.IllegalStateException: UiAutomationService android.accessibilityservice.IAccessibilityServiceClient$Stub$Proxy@fe6acf3already registered! [debug] [Instrumentation] at android.os.Parcel.readException(Parcel.java:1694) [debug] [Instrumentation] at android.os.Parcel.readException(Parcel.java:1639) [debug] [Instrumentation] at android.view.accessibility.IAccessibilityManager$Stub$Proxy.registerUiTestAutomationService(IAccessibilityManager.java:389) [debug] [Instrumentation] at android.app.UiAutomationConnection.registerUiTestAutomationServiceLocked(UiAutomationConnection.java:344) [debug] [Instrumentation] at android.app.UiAutomationConnection.connect(UiAutomationConnection.java:92) [debug] [Instrumentation] at android.app.IUiAutomationConnection$Stub.onTransact(IUiAutomationConnection.java:61) [debug] [Instrumentation] at android.os.Binder.execTransact(Binder.java:565) [debug] [WD Proxy] Matched '/status' to command name 'getStatus' [debug] [WD Proxy] Proxying [GET /status] to [GET http://localhost:8200/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 2147ms [debug] [WD Proxy] Matched '/session' to command name 'createSession' [debug] [WD Proxy] Proxying [POST /session] to [POST http://localhost:8200/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","deviceName":"5LM0216906000429","appPackage":"cn.gov.customs.zshg","appActivity":"com.gov.customs.zshgupgrade.view.welcome.WelcomeActivity","noReset":true},"platformName":"Android","deviceName":"5LM0216906000429","appPackage":"cn.gov.customs.zshg","appActivity":"com.gov.customs.zshgupgrade.view.welcome.WelcomeActivity","noReset":true,"deviceUDID":"5LM0216906000429"}],"alwaysMatch":{}}} [info] [WD Proxy] Got an unexpected response with status 500: {"sessionId":null,"value":{"error":"session not created","message":"java.lang.IllegalStateException: UiAutomation not connected!","stacktrace":"io.appium.uiautomator2.common.exceptions.SessionNotCreatedException: java.lang.IllegalStateException: UiAutomation not connected!\n\tat io.appium.uiautomator2.handler.NewSession.safeHandle(NewSession.java:59)\n\tat io.appium.uiautomator2.handler.request.SafeRequestHandler.handle(SafeRequestHandler.java:38)\n\tat io.appium.uiautomator2.server.AppiumServlet.handleRequest(AppiumServlet.java:252)\n\tat io.appium.uiautomator2.server.AppiumServlet.handleHttpRequest(AppiumServlet.java:242)\n\tat io.appium.uiautomator2.http.ServerHandler.channelRead(ServerHandler.java:44)\n\tat io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:366)\n\tat io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:352)\n\tat io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannel... [debug] [W3C] Matched W3C error code 'session not created' to SessionNotCreatedError [debug] [UiAutomator2] Deleting UiAutomator2 session [debug] [UiAutomator2] Deleting UiAutomator2 server session [debug] [WD Proxy] Matched '/' to command name 'deleteSession' [warn] [UiAutomator2] Did not get confirmation UiAutomator2 deleteSession worked; Error was: UnknownError: An unknown server-side error occurred while processing the command. Original error: Trying to proxy a session command without session id [debug] [ADB] Running 'D:\Program Files (x86)\Android\android-sdk\platform-tools\adb.exe -P 5037 -s 5LM0216906000429 shell am force-stop cn.gov.customs.zshg'[debug] [Logcat] Stopping logcat capture [debug] [ADB] Removing forwarded port socket connection: 8200 [debug] [ADB] Running 'D:\Program Files (x86)\Android\android-sdk\platform-tools\adb.exe -P 5037 -s 5LM0216906000429 forward --remove tcp\:8200'[debug] [BaseDriver] Event 'newSessionStarted' logged at 1605150171336 (11:02:51 GMT+0800 (中国标准时间)) [debug] [W3C] Encountered internal error running command: A new session could not be created. Details: java.lang.IllegalStateException: UiAutomation not connected! [debug] [W3C] io.appium.uiautomator2.common.exceptions.SessionNotCreatedException: java.lang.IllegalStateException: UiAutomation not connected! [debug] [W3C] at io.appium.uiautomator2.handler.NewSession.safeHandle(NewSession.java:59) [debug] [W3C] at io.appium.uiautomator2.handler.request.SafeRequestHandler.handle(SafeRequestHandler.java:38) [debug] [W3C] at io.appium.uiautomator2.server.AppiumServlet.handleRequest(AppiumServlet.java:252) [debug] [W3C] at io.appium.uiautomator2.server.AppiumServlet.handleHttpRequest(AppiumServlet.java:242) [debug] [W3C] at io.appium.uiautomator2.http.ServerHandler.channelRead(ServerHandler.java:44) [debug] [W3C] at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:366) [debug] [W3C] at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:352) [debug] [W3C] at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:345) [debug] [W3C] at io.netty.handler.codec.MessageToMessageDecoder.channelRead(MessageToMessageDecoder.java:102) [debug] [W3C] at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:366) [debug] [W3C] at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:352) [debug] [W3C] at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:345) [debug] [W3C] at io.netty.channel.CombinedChannelDuplexHandler$DelegatingChannelHandlerContext.fireChannelRead(CombinedChannelDuplexHandler.java:435) [debug] [W3C] at io.netty.handler.codec.ByteToMessageDecoder.fireChannelRead(ByteToMessageDecoder.java:293) [debug] [W3C] at io.netty.handler.codec.ByteToMessageDecoder.channelRead(ByteToMessageDecoder.java:267) [debug] [W3C] at io.netty.channel.CombinedChannelDuplexHandler.channelRead(CombinedChannelDuplexHandler.java:250) [debug] [W3C] at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:366) [debug] [W3C] at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:352) [debug] [W3C] at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:345) [debug] [W3C] at io.netty.channel.DefaultChannelPipeline$HeadContext.channelRead(DefaultChannelPipeline.java:1294) [debug] [W3C] at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:366) [debug] [W3C] at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:352) [debug] [W3C] at io.netty.channel.DefaultChannelPipeline.fireChannelRead(DefaultChannelPipeline.java:911) [debug] [W3C] at io.netty.channel.nio.AbstractNioByteChannel$NioByteUnsafe.read(AbstractNioByteChannel.java:131) [debug] [W3C] at io.netty.channel.nio.NioEventLoop.processSelectedKey(NioEventLoop.java:611) [debug] [W3C] at io.netty.channel.nio.NioEventLoop.processSelectedKeysOptimized(NioEventLoop.java:552) [debug] [W3C] at io.netty.channel.nio.NioEventLoop.processSelectedKeys(NioEventLoop.java:466) [debug] [W3C] at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:438) [debug] [W3C] at io.netty.util.concurrent.SingleThreadEventExecutor$2.run(SingleThreadEventExecutor.java:140) [debug] [W3C] at io.netty.util.concurrent.DefaultThreadFactory$DefaultRunnableDecorator.run(DefaultThreadFactory.java:144) [debug] [W3C] at java.lang.Thread.run(Thread.java:776) [debug] [W3C] Caused by: java.lang.IllegalStateException: UiAutomation not connected! [debug] [W3C] at android.app.UiAutomation.throwIfNotConnectedLocked(UiAutomation.java:1034) [debug] [W3C] at android.app.UiAutomation.getServiceInfo(UiAutomation.java:399) [debug] [W3C] at androidx.test.uiautomator.UiDevice.(UiDevice.java:115) [debug] [W3C] at androidx.test.uiautomator.UiDevice.getInstance(UiDevice.java:261) [debug] [W3C] at io.appium.uiautomator2.utils.Device.getUiDevice(Device.java:25) [debug] [W3C] at io.appium.uiautomator2.core.UiAutomatorBridge.getUiAutomation(UiAutomatorBridge.java:52) [debug] [W3C] at io.appium.uiautomator2.core.UiAutomation.(UiAutomation.java:32) [debug] [W3C] at io.appium.uiautomator2.core.UiAutomation.getInstance(UiAutomation.java:37) [debug] [W3C] at io.appium.uiautomator2.model.NotificationListener.(NotificationListener.java:42) [debug] [W3C] at io.appium.uiautomator2.model.NotificationListener.getInstance(NotificationListener.java:47) [debug] [W3C] at io.appium.uiautomator2.handler.NewSession.safeHandle(NewSession.java:52) [debug] [W3C] ... 30 more [debug] [W3C] [info] [HTTP] <-- POST /wd/hub/session 500 10879 ms - 4342 [info] [HTTP] [info] [HTTP] --> GET /wd/hub/session/e62ef305-4e4c-4b93-929f-47b652199789/source [info] [HTTP] {} [debug] [MJSONWP (e62ef305)] Calling AppiumDriver.getPageSource() with args: ["e62ef305-4e4c-4b93-929f-47b652199789"] [info] [HTTP] --> GET /wd/hub/session/e62ef305-4e4c-4b93-929f-47b652199789/screenshot [info] [HTTP] {} [debug] [MJSONWP (e62ef305)] Calling AppiumDriver.getScreenshot() with args: ["e62ef305-4e4c-4b93-929f-47b652199789"] [debug] [AndroidBootstrap] Sending command to android: {"cmd":"action","action":"source","params":{}} [info] [HTTP] --> GET /wd/hub/session/e62ef305-4e4c-4b93-929f-47b652199789/window/current/size [info] [HTTP] {} [debug] [MJSONWP (e62ef305)] Calling AppiumDriver.getWindowSize() with args: ["current","e62ef305-4e4c-4b93-929f-47b652199789"] [debug] [AndroidBootstrap] [BOOTSTRAP LOG] [debug] Got data from client: {"cmd":"action","action":"source","params":{}} [debug] [AndroidBootstrap] [BOOTSTRAP LOG] [debug] Got command of type ACTION [debug] [AndroidBootstrap] [BOOTSTRAP LOG] [debug] Got command action: source[debug] [AndroidBootstrap] [UIAUTO STDOUT] [APPIUM-UIAUTO] [debug] Returning result: {"status":0,"value":"<\/android.widget.LinearLayout><\/android.widget.LinearLayout><\/android.view.ViewGroup><\/android.view.ViewGroup><\/android.view.ViewGroup><\/android.view.ViewGroup><\/android.view.ViewGroup><\/android.widget.FrameLayout><\/android.widget.FrameLayout><\/android.widget.FrameLayout><\/android.widget.LinearLayout><\/android.widget.FrameLayout><\/hierarchy>"}[/APPIUM-UIAUTO] [debug] [AndroidBootstrap] Received command result from bootstrap [debug] [MJSONWP (e62ef305)] Responding to client with driver.getPageSource() result: "