2023-02-14 10:32:28:765 [Appium] Welcome to Appium v1.22.3 2023-02-14 10:32:28:766 [Appium] Non-default server args: 2023-02-14 10:32:28:766 [Appium] port: 49204 2023-02-14 10:32:28:766 [Appium] logFile: /home/ltadmin/Documents/appium/assets/logs/R5CRA1SQC1R.log 2023-02-14 10:32:28:766 [Appium] relaxedSecurityEnabled: true 2023-02-14 10:32:28:767 [Appium] allowInsecure: { 2023-02-14 10:32:28:767 [Appium] 0: chromedriver_autodownload 2023-02-14 10:32:28:767 [Appium] } 2023-02-14 10:32:28:767 [Appium] chromedriverPort: 10389 2023-02-14 10:32:28:767 [Appium] Deprecated server args: 2023-02-14 10:32:28:768 [Appium] --chromedriver-port => --default-capabilities 2023-02-14 10:32:28:768 [Appium] Default capabilities, which will be added to each request unless overridden by desired capabilities: 2023-02-14 10:32:28:768 [Appium] chromedriverPort: 10389 2023-02-14 10:32:28:781 [Appium] Appium REST http interface listener started on 0.0.0.0:49204 2023-02-14 10:32:29:500 [HTTP] --> GET /wd/hub/status 2023-02-14 10:32:29:500 [HTTP] {} 2023-02-14 10:32:29:501 [GENERIC] Calling AppiumDriver.getStatus() with args: [] 2023-02-14 10:32:29:502 [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.22.3"}} 2023-02-14 10:32:29:506 [HTTP] <-- GET /wd/hub/status 200 5 ms - 68 2023-02-14 10:32:29:506 [HTTP] 2023-02-14 10:32:38:548 [HTTP] Request idempotency key: 8651b527-10ad-4e98-8ead-0d8174335575 2023-02-14 10:32:38:563 [HTTP] --> POST /wd/hub/session 2023-02-14 10:32:38:563 [HTTP] {"customData":{},"desiredCapabilities":{"adbExecTimeout":"120000","appActivity":"com.viator.android.app.splash.SplashActivity","appPackage":"com.viator.mobile.android.prerelease","autoAcceptAlerts":false,"automationName":"uiautomator2","chromeOptions":{"args":[],"w3c":false},"deviceName":"Galaxy S21 5G","headless":false,"language":"en","locale":"GB","nativeWebScreenshot":true,"newCommandTimeout":"0","orientation":"PORTRAIT","platformName":"android","platformVersion":"11","resetKeyboard":true,"systemPort":"12332","udid":"R5CRA1SQC1R","uiautomator2ServerLaunchTimeout":"60000","unicodeKeyboard":true,"waitForQuiescence":false}} 2023-02-14 10:32:38:564 [MJSONWP] Calling AppiumDriver.createSession() with args: [{"adbExecTimeout":"120000","appActivity":"com.viator.android.app.splash.SplashActivity","appPackage":"com.viator.mobile.android.prerelease","autoAcceptAlerts":false,"automationName":"uiautomator2","chromeOptions":{"args":[],"w3c":false},"deviceName":"Galaxy S21 5G","headless":false,"language":"en","locale":"GB","nativeWebScreenshot":true,"newCommandTimeout":"0","orientation":"PORTRAIT","platformName":"android","platformVersion":"11","resetKeyboard":true,"systemPort":"12332","udid":"R5CRA1SQC1R","uiautomator2ServerLaunchTimeout":"60000","unicodeKeyboard":true,"waitForQuiescence":false},null,null] 2023-02-14 10:32:38:564 [BaseDriver] Event 'newSessionRequested' logged at 1676370758564 (10:32:38 GMT+0000 (Greenwich Mean Time)) 2023-02-14 10:32:38:674 [Appium] Appium v1.22.3 creating new AndroidUiautomator2Driver (v1.70.1) session 2023-02-14 10:32:38:675 [Appium] Applying relaxed security to 'AndroidUiautomator2Driver' as per server command line argument. All insecure features will be enabled unless explicitly disabled by --deny-insecure 2023-02-14 10:32:38:675 [Appium] Explicitly enabling use of insecure features: 2023-02-14 10:32:38:675 [Appium] chromedriver_autodownload 2023-02-14 10:32:38:676 [BaseDriver] Creating session with MJSONWP desired capabilities: { 2023-02-14 10:32:38:676 [BaseDriver] "chromedriverPort": 10389, 2023-02-14 10:32:38:676 [BaseDriver] "adbExecTimeout": "120000", 2023-02-14 10:32:38:677 [BaseDriver] "appActivity": "com.viator.android.app.splash.SplashActivity", 2023-02-14 10:32:38:677 [BaseDriver] "appPackage": "com.viator.mobile.android.prerelease", 2023-02-14 10:32:38:677 [BaseDriver] "autoAcceptAlerts": false, 2023-02-14 10:32:38:677 [BaseDriver] "automationName": "uiautomator2", 2023-02-14 10:32:38:677 [BaseDriver] "chromeOptions": { 2023-02-14 10:32:38:677 [BaseDriver] "args": [], 2023-02-14 10:32:38:677 [BaseDriver] "w3c": false 2023-02-14 10:32:38:677 [BaseDriver] }, 2023-02-14 10:32:38:677 [BaseDriver] "deviceName": "Galaxy S21 5G", 2023-02-14 10:32:38:677 [BaseDriver] "headless": false, 2023-02-14 10:32:38:677 [BaseDriver] "language": "en", 2023-02-14 10:32:38:677 [BaseDriver] "locale": "GB", 2023-02-14 10:32:38:678 [BaseDriver] "nativeWebScreenshot": true, 2023-02-14 10:32:38:678 [BaseDriver] "newCommandTimeout": "0", 2023-02-14 10:32:38:678 [BaseDriver] "orientation": "PORTRAIT", 2023-02-14 10:32:38:678 [BaseDriver] "platformName": "android", 2023-02-14 10:32:38:679 [BaseDriver] "platformVersion": "11", 2023-02-14 10:32:38:679 [BaseDriver] "resetKeyboard": true, 2023-02-14 10:32:38:679 [BaseDriver] "systemPort": "12332", 2023-02-14 10:32:38:679 [BaseDriver] "udid": "R5CRA1SQC1R", 2023-02-14 10:32:38:679 [BaseDriver] "uiautomator2ServerLaunchTimeout": "60000", 2023-02-14 10:32:38:679 [BaseDriver] "unicodeKeyboard": true, 2023-02-14 10:32:38:679 [BaseDriver] "waitForQuiescence": false 2023-02-14 10:32:38:680 [BaseDriver] } 2023-02-14 10:32:38:680 [BaseDriver] Capability 'newCommandTimeout' changed from string ('0') to integer (0). This may cause unexpected behavior 2023-02-14 10:32:38:680 [BaseDriver] Capability 'uiautomator2ServerLaunchTimeout' changed from string ('60000') to integer (60000). This may cause unexpected behavior 2023-02-14 10:32:38:681 [BaseDriver] Capability 'systemPort' changed from string ('12332') to integer (12332). This may cause unexpected behavior 2023-02-14 10:32:38:681 [BaseDriver] Capability 'adbExecTimeout' changed from string ('120000') to integer (120000). This may cause unexpected behavior 2023-02-14 10:32:38:684 [BaseDriver] The following capabilities were provided, but are not recognized by Appium: 2023-02-14 10:32:38:684 [BaseDriver] autoAcceptAlerts 2023-02-14 10:32:38:684 [BaseDriver] headless 2023-02-14 10:32:38:684 [BaseDriver] waitForQuiescence 2023-02-14 10:32:38:684 [BaseDriver] Session created with session id: aaf91695-3568-46d3-9627-c5b05eb282cf 2023-02-14 10:32:38:685 [UiAutomator2] Starting 'com.viator.mobile.android.prerelease' directly on the device 2023-02-14 10:32:38:699 [ADB] Cannot sort build-tools folders ["27.0.1","debian"] by semantic version names. 2023-02-14 10:32:38:699 [ADB] Falling back to sorting by modification date. Original error: Invalid Version: debian 2023-02-14 10:32:38:704 [ADB] Found 2 'build-tools' folders under '/usr/lib/android-sdk' (newest first): 2023-02-14 10:32:38:704 [ADB] /usr/lib/android-sdk/build-tools/27.0.1 2023-02-14 10:32:38:704 [ADB] /usr/lib/android-sdk/build-tools/debian 2023-02-14 10:32:38:705 [ADB] Using 'adb' from '/usr/lib/android-sdk/platform-tools/adb' 2023-02-14 10:32:38:705 [ADB] Running '/usr/lib/android-sdk/platform-tools/adb -P 5037 start-server' 2023-02-14 10:32:38:721 [AndroidDriver] Retrieving device list 2023-02-14 10:32:38:721 [ADB] Trying to find a connected android device 2023-02-14 10:32:38:721 [ADB] Getting connected devices 2023-02-14 10:32:38:733 [ADB] Connected devices: [{"udid":"aa44739a","state":"device"},{"udid":"8f86991d","state":"device"},{"udid":"98895a43524e315a31","state":"device"},{"udid":"8735ebee","state":"device"},{"udid":"R5CRA1SQC1R","state":"device"},{"udid":"RF8N51NZA6X","state":"device"},{"udid":"R5CT234SCAD","state":"device"},{"udid":"R5CT23583SJ","state":"device"},{"udid":"RZCN800LKJL","state":"device"},{"udid":"RZ8M816Z9TJ","state":"device"},{"udid":"R5CNC13ANEW","state":"device"},{"udid":"c3d3f2be","state":"device"},{"udid":"19271FDF60046F","state":"device"},{"udid":"149dddc90706","state":"device"},{"udid":"R5CR11301JB","state":"device"},{"udid":"RZ8N813K1VH","state":"device"},{"udid":"MZGE5HNNEMZ5AYI7","state":"device"},{"udid":"2ab07a1d473f7ece","state":"device"},{"udid":"R28M317C9BR","state":"device"},{"udid":"RZ8N80A11NN","state":"device"},{"udid":"R5CT21L5MYW","state":"device"},{"udid":"R5CT31BJ3TD","state":"device"}] 2023-02-14 10:32:38:733 [AndroidDriver] Using device: R5CRA1SQC1R 2023-02-14 10:32:38:734 [ADB] Using 'adb' from '/usr/lib/android-sdk/platform-tools/adb' 2023-02-14 10:32:38:735 [ADB] Running '/usr/lib/android-sdk/platform-tools/adb -P 5037 start-server' 2023-02-14 10:32:38:745 [ADB] Setting device id to R5CRA1SQC1R 2023-02-14 10:32:38:746 [ADB] Running '/usr/lib/android-sdk/platform-tools/adb -P 5037 -s R5CRA1SQC1R shell getprop ro.build.version.sdk' 2023-02-14 10:32:38:811 [ADB] Current device property 'ro.build.version.sdk': 30 2023-02-14 10:32:38:812 [ADB] Getting device platform version 2023-02-14 10:32:38:813 [ADB] Running '/usr/lib/android-sdk/platform-tools/adb -P 5037 -s R5CRA1SQC1R shell getprop ro.build.version.release' 2023-02-14 10:32:38:862 [ADB] Current device property 'ro.build.version.release': 11 2023-02-14 10:32:38:862 [ADB] Device API level: 30 2023-02-14 10:32:38:862 [UiAutomator2] Relaxing hidden api policy 2023-02-14 10:32:38:864 [ADB] Running '/usr/lib/android-sdk/platform-tools/adb -P 5037 -s R5CRA1SQC1R shell 'settings put global hidden_api_policy_pre_p_apps 1;settings put global hidden_api_policy_p_apps 1;settings put global hidden_api_policy 1'' 2023-02-14 10:32:39:058 [AndroidDriver] No app sent in, not parsing package/activity 2023-02-14 10:32:39:060 [ADB] Running '/usr/lib/android-sdk/platform-tools/adb -P 5037 -s R5CRA1SQC1R wait-for-device' 2023-02-14 10:32:39:087 [ADB] Running '/usr/lib/android-sdk/platform-tools/adb -P 5037 -s R5CRA1SQC1R shell echo ping' 2023-02-14 10:32:39:122 [AndroidDriver] Pushing settings apk to device... 2023-02-14 10:32:39:123 [ADB] Getting install status for io.appium.settings 2023-02-14 10:32:39:123 [ADB] Running '/usr/lib/android-sdk/platform-tools/adb -P 5037 -s R5CRA1SQC1R shell dumpsys package io.appium.settings' 2023-02-14 10:32:39:455 [ADB] 'io.appium.settings' is installed 2023-02-14 10:32:39:455 [ADB] Getting package info for 'io.appium.settings' 2023-02-14 10:32:39:456 [ADB] Running '/usr/lib/android-sdk/platform-tools/adb -P 5037 -s R5CRA1SQC1R shell dumpsys package io.appium.settings' 2023-02-14 10:32:39:578 [ADB] The version code of the installed 'io.appium.settings' is greater than the application version code (48 > 30) 2023-02-14 10:32:39:578 [ADB] There is no need to downgrade 'io.appium.settings' 2023-02-14 10:32:39:578 [ADB] Getting IDs of all 'io.appium.settings' processes 2023-02-14 10:32:39:579 [ADB] Running '/usr/lib/android-sdk/platform-tools/adb -P 5037 -s R5CRA1SQC1R shell 'pgrep --help; echo $?'' 2023-02-14 10:32:39:639 [ADB] Running '/usr/lib/android-sdk/platform-tools/adb -P 5037 -s R5CRA1SQC1R shell pgrep -f \(\[\[:blank:\]\]\|\^\)io\.appium\.settings\(\[\[:blank:\]\]\|\$\)' 2023-02-14 10:32:39:758 [AndroidDriver] io.appium.settings is already running. There is no need to reset its permissions. 2023-02-14 10:32:39:759 [ADB] Running '/usr/lib/android-sdk/platform-tools/adb -P 5037 -s R5CRA1SQC1R shell appops set io.appium.settings android:mock_location allow' 2023-02-14 10:32:39:848 [ADB] Running '/usr/lib/android-sdk/platform-tools/adb -P 5037 -s R5CRA1SQC1R shell getprop persist.sys.locale' 2023-02-14 10:32:39:899 [ADB] Current device property 'persist.sys.locale': en-US 2023-02-14 10:32:39:900 [ADB] Current locale: 'en-US'; requested locale: 'en-GB' 2023-02-14 10:32:39:901 [ADB] Running '/usr/lib/android-sdk/platform-tools/adb -P 5037 -s R5CRA1SQC1R shell am broadcast -a io.appium.settings.locale -n io.appium.settings/.receivers.LocaleSettingReceiver --es lang en --es country GB' 2023-02-14 10:32:40:805 [ADB] Running '/usr/lib/android-sdk/platform-tools/adb -P 5037 -s R5CRA1SQC1R shell getprop persist.sys.locale' 2023-02-14 10:32:40:906 [ADB] Current device property 'persist.sys.locale': en-GB 2023-02-14 10:32:40:906 [ADB] Requested locale is equal to current locale: 'en-gb' 2023-02-14 10:32:40:908 [Logcat] Starting logs capture with command: /usr/lib/android-sdk/platform-tools/adb -P 5037 -s R5CRA1SQC1R logcat -v threadtime 2023-02-14 10:32:40:990 [AndroidDriver] Enabling Unicode keyboard support 2023-02-14 10:32:40:991 [ADB] Running '/usr/lib/android-sdk/platform-tools/adb -P 5037 -s R5CRA1SQC1R shell settings get secure default_input_method' 2023-02-14 10:32:41:187 [AndroidDriver] Unsetting previous IME com.samsung.android.honeyboard/.service.HoneyBoardService 2023-02-14 10:32:41:188 [AndroidDriver] Setting IME to 'io.appium.settings/.UnicodeIME' 2023-02-14 10:32:41:188 [ADB] Running '/usr/lib/android-sdk/platform-tools/adb -P 5037 -s R5CRA1SQC1R shell ime enable io.appium.settings/.UnicodeIME' 2023-02-14 10:32:41:336 [ADB] Running '/usr/lib/android-sdk/platform-tools/adb -P 5037 -s R5CRA1SQC1R shell ime set io.appium.settings/.UnicodeIME' 2023-02-14 10:32:41:517 [UiAutomator2] Forwarding UiAutomator2 Server port 6790 to local port 12332 2023-02-14 10:32:41:525 [ADB] Forwarding system: 12332 to device: 6790 2023-02-14 10:32:41:525 [ADB] Running '/usr/lib/android-sdk/platform-tools/adb -P 5037 -s R5CRA1SQC1R forward tcp:12332 tcp:6790' 2023-02-14 10:32:41:540 [ADB] Getting install status for io.appium.uiautomator2.server 2023-02-14 10:32:41:541 [ADB] Running '/usr/lib/android-sdk/platform-tools/adb -P 5037 -s R5CRA1SQC1R shell dumpsys package io.appium.uiautomator2.server' 2023-02-14 10:32:41:696 [ADB] 'io.appium.uiautomator2.server' is installed 2023-02-14 10:32:41:697 [ADB] Getting package info for 'io.appium.uiautomator2.server' 2023-02-14 10:32:41:697 [ADB] Running '/usr/lib/android-sdk/platform-tools/adb -P 5037 -s R5CRA1SQC1R shell dumpsys package io.appium.uiautomator2.server' 2023-02-14 10:32:41:968 [ADB] The version name of the installed 'io.appium.uiautomator2.server' is greater or equal to the application version name ('4.27.0' >= '4.27.0') 2023-02-14 10:32:41:968 [UiAutomator2] io.appium.uiautomator2.server installation state: sameVersionInstalled 2023-02-14 10:32:41:969 [ADB] Checking app cert for /home/ltadmin/Documents/appium/latest/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-v4.27.0.apk 2023-02-14 10:32:42:005 [ADB] Using 'apksigner.jar' from '/usr/lib/android-sdk/build-tools/27.0.1/apksigner.jar' 2023-02-14 10:32:42:006 [ADB] Starting apksigner: /usr/lib/jvm/java-11-openjdk-amd64/bin/java -Xmx1024M -Xss1m -jar /usr/lib/android-sdk/build-tools/27.0.1/apksigner.jar verify --print-certs /home/ltadmin/Documents/appium/latest/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-v4.27.0.apk 2023-02-14 10:32:42:534 [ADB] apksigner stdout: Signer #1 certificate DN: EMAILADDRESS=android@android.com, CN=Android, OU=Android, O=Android, L=Mountain View, ST=California, C=US 2023-02-14 10:32:42:535 [ADB] Signer #1 certificate SHA-256 digest: a40da80a59d170caa950cf15c18c454d47a39b26989d8b640ecd745ba71bf5dc 2023-02-14 10:32:42:535 [ADB] Signer #1 certificate SHA-1 digest: 61ed377e85d386a8dfee6b864bd85b0bfaa5af81 2023-02-14 10:32:42:535 [ADB] Signer #1 certificate MD5 digest: e89b158e4bcf988ebd09eb83f5378e87 2023-02-14 10:32:42:535 [ADB] 2023-02-14 10:32:42:537 [ADB] sha256 hash did match for 'appium-uiautomator2-server-v4.27.0.apk' 2023-02-14 10:32:42:538 [ADB] '/home/ltadmin/Documents/appium/latest/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-v4.27.0.apk' is signed with the default certificate 2023-02-14 10:32:42:540 [ADB] Getting install status for io.appium.uiautomator2.server.test 2023-02-14 10:32:42:542 [ADB] Running '/usr/lib/android-sdk/platform-tools/adb -P 5037 -s R5CRA1SQC1R shell dumpsys package io.appium.uiautomator2.server.test' 2023-02-14 10:32:42:709 [ADB] 'io.appium.uiautomator2.server.test' is installed 2023-02-14 10:32:42:709 [ADB] Checking app cert for /home/ltadmin/Documents/appium/latest/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-debug-androidTest.apk 2023-02-14 10:32:42:716 [ADB] Starting apksigner: /usr/lib/jvm/java-11-openjdk-amd64/bin/java -Xmx1024M -Xss1m -jar /usr/lib/android-sdk/build-tools/27.0.1/apksigner.jar verify --print-certs /home/ltadmin/Documents/appium/latest/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-debug-androidTest.apk 2023-02-14 10:32:42:914 [ADB] apksigner stdout: Signer #1 certificate DN: EMAILADDRESS=android@android.com, CN=Android, OU=Android, O=Android, L=Mountain View, ST=California, C=US 2023-02-14 10:32:42:914 [ADB] Signer #1 certificate SHA-256 digest: a40da80a59d170caa950cf15c18c454d47a39b26989d8b640ecd745ba71bf5dc 2023-02-14 10:32:42:914 [ADB] Signer #1 certificate SHA-1 digest: 61ed377e85d386a8dfee6b864bd85b0bfaa5af81 2023-02-14 10:32:42:915 [ADB] Signer #1 certificate MD5 digest: e89b158e4bcf988ebd09eb83f5378e87 2023-02-14 10:32:42:915 [ADB] 2023-02-14 10:32:42:915 [ADB] sha256 hash did match for 'appium-uiautomator2-server-debug-androidTest.apk' 2023-02-14 10:32:42:915 [ADB] '/home/ltadmin/Documents/appium/latest/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-debug-androidTest.apk' is signed with the default certificate 2023-02-14 10:32:42:916 [UiAutomator2] Server packages are not going to be (re)installed 2023-02-14 10:32:42:920 [UiAutomator2] Waiting up to 30000ms for services to be available 2023-02-14 10:32:42:921 [ADB] Running '/usr/lib/android-sdk/platform-tools/adb -P 5037 -s R5CRA1SQC1R shell pm list instrumentation' 2023-02-14 10:32:43:109 [UiAutomator2] Instrumentation target 'io.appium.uiautomator2.server.test/androidx.test.runner.AndroidJUnitRunner' is available 2023-02-14 10:32:43:115 [ADB] Adding packages ["io.appium.settings","io.appium.uiautomator2.server","io.appium.uiautomator2.server.test"] to Doze whitelist 2023-02-14 10:32:43:116 [ADB] Got the following command chunks to execute: [["dumpsys","deviceidle","whitelist","+io.appium.settings",";","dumpsys","deviceidle","whitelist","+io.appium.uiautomator2.server",";","dumpsys","deviceidle","whitelist","+io.appium.uiautomator2.server.test",";"]] 2023-02-14 10:32:43:116 [ADB] Running '/usr/lib/android-sdk/platform-tools/adb -P 5037 -s R5CRA1SQC1R shell dumpsys deviceidle whitelist +io.appium.settings ; dumpsys deviceidle whitelist +io.appium.uiautomator2.server ; dumpsys deviceidle whitelist +io.appium.uiautomator2.server.test ;' 2023-02-14 10:32:43:402 [UiAutomator2] No app capability. Assuming it is already on the device 2023-02-14 10:32:43:403 [ADB] Getting install status for com.viator.mobile.android.prerelease 2023-02-14 10:32:43:403 [ADB] Running '/usr/lib/android-sdk/platform-tools/adb -P 5037 -s R5CRA1SQC1R shell dumpsys package com.viator.mobile.android.prerelease' 2023-02-14 10:32:43:633 [ADB] 'com.viator.mobile.android.prerelease' is installed 2023-02-14 10:32:43:634 [ADB] Running '/usr/lib/android-sdk/platform-tools/adb -P 5037 -s R5CRA1SQC1R shell am force-stop com.viator.mobile.android.prerelease' 2023-02-14 10:32:43:986 [ADB] Running '/usr/lib/android-sdk/platform-tools/adb -P 5037 -s R5CRA1SQC1R shell pm clear com.viator.mobile.android.prerelease' 2023-02-14 10:32:44:357 [AndroidDriver] Performed fast reset on the installed 'com.viator.mobile.android.prerelease' application (stop and clear) 2023-02-14 10:32:44:358 [UiAutomator2] Performing shallow cleanup of automation leftovers 2023-02-14 10:32:44:385 [UiAutomator2] No obsolete sessions have been detected (read ECONNRESET) 2023-02-14 10:32:44:385 [ADB] Running '/usr/lib/android-sdk/platform-tools/adb -P 5037 -s R5CRA1SQC1R shell am force-stop io.appium.uiautomator2.server.test' 2023-02-14 10:32:44:590 [UiAutomator2] Starting UIAutomator2 server 4.27.0 2023-02-14 10:32:44:590 [UiAutomator2] Using UIAutomator2 server from '/home/ltadmin/Documents/appium/latest/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-v4.27.0.apk' and test from '/home/ltadmin/Documents/appium/latest/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-debug-androidTest.apk' 2023-02-14 10:32:44:591 [UiAutomator2] Waiting up to 60000ms for UiAutomator2 to be online... 2023-02-14 10:32:44:592 [ADB] Creating ADB subprocess with args: ["-P",5037,"-s","R5CRA1SQC1R","shell","am","instrument","-w","-e","disableAnalytics",true,"io.appium.uiautomator2.server.test/androidx.test.runner.AndroidJUnitRunner"] 2023-02-14 10:32:44:603 [WD Proxy] Matched '/status' to command name 'getStatus' 2023-02-14 10:32:44:605 [WD Proxy] Proxying [GET /status] to [GET http://127.0.0.1:12332/wd/hub/status] with no body 2023-02-14 10:32:44:635 [WD Proxy] Error: read ECONNRESET 2023-02-14 10:32:44:635 [WD Proxy] at TCP.onStreamRead (internal/stream_base_commons.js:209:20) 2023-02-14 10:32:45:638 [WD Proxy] Matched '/status' to command name 'getStatus' 2023-02-14 10:32:45:639 [WD Proxy] Proxying [GET /status] to [GET http://127.0.0.1:12332/wd/hub/status] with no body 2023-02-14 10:32:45:657 [WD Proxy] Error: read ECONNRESET 2023-02-14 10:32:45:658 [WD Proxy] at TCP.onStreamRead (internal/stream_base_commons.js:209:20) 2023-02-14 10:32:46:658 [WD Proxy] Matched '/status' to command name 'getStatus' 2023-02-14 10:32:46:659 [WD Proxy] Proxying [GET /status] to [GET http://127.0.0.1:12332/wd/hub/status] with no body 2023-02-14 10:32:46:668 [WD Proxy] Error: read ECONNRESET 2023-02-14 10:32:46:668 [WD Proxy] at TCP.onStreamRead (internal/stream_base_commons.js:209:20) 2023-02-14 10:32:46:871 [Instrumentation] io.appium.uiautomator2.server.test.AppiumUiAutomator2Server: 2023-02-14 10:32:47:670 [WD Proxy] Matched '/status' to command name 'getStatus' 2023-02-14 10:32:47:670 [WD Proxy] Proxying [GET /status] to [GET http://127.0.0.1:12332/wd/hub/status] with no body 2023-02-14 10:32:47:682 [WD Proxy] Error: read ECONNRESET 2023-02-14 10:32:47:682 [WD Proxy] at TCP.onStreamRead (internal/stream_base_commons.js:209:20) 2023-02-14 10:32:47:903 [Instrumentation] Error in startServer(io.appium.uiautomator2.server.test.AppiumUiAutomator2Server): 2023-02-14 10:32:47:904 [Instrumentation] java.lang.ExceptionInInitializerError 2023-02-14 10:32:47:904 [Instrumentation] at io.appium.uiautomator2.server.mjpeg.MjpegScreenshotServer.(MjpegScreenshotServer.java:41) 2023-02-14 10:32:47:904 [Instrumentation] at io.appium.uiautomator2.server.mjpeg.MjpegScreenshotServer.(MjpegScreenshotServer.java:35) 2023-02-14 10:32:47:904 [Instrumentation] at io.appium.uiautomator2.server.ServerInstrumentation.startMjpegServer(ServerInstrumentation.java:241) 2023-02-14 10:32:47:904 [Instrumentation] at io.appium.uiautomator2.server.test.AppiumUiAutomator2Server.startServer(AppiumUiAutomator2Server.java:31) 2023-02-14 10:32:47:905 [Instrumentation] ... 27 trimmed 2023-02-14 10:32:47:905 [Instrumentation] Caused by: java.lang.IllegalStateException: UiAutomationService android.accessibilityservice.IAccessibilityServiceClient$Stub$Proxy@a4fc225already registered! 2023-02-14 10:32:47:905 [Instrumentation] at android.os.Parcel.createExceptionOrNull(Parcel.java:2393) 2023-02-14 10:32:47:905 [Instrumentation] at android.os.Parcel.createException(Parcel.java:2369) 2023-02-14 10:32:47:906 [Instrumentation] at android.os.Parcel.readException(Parcel.java:2352) 2023-02-14 10:32:47:906 [Instrumentation] at android.os.Parcel.readException(Parcel.java:2294) 2023-02-14 10:32:47:906 [Instrumentation] at android.app.IUiAutomationConnection$Stub$Proxy.connect(IUiAutomationConnection.java:427) 2023-02-14 10:32:47:906 [Instrumentation] at android.app.UiAutomation.connect(UiAutomation.java:243) 2023-02-14 10:32:47:906 [Instrumentation] at android.app.Instrumentation.getUiAutomation(Instrumentation.java:2189) 2023-02-14 10:32:47:907 [Instrumentation] at androidx.test.uiautomator.UiDevice.getUiAutomation(UiDevice.java:1129) 2023-02-14 10:32:47:907 [Instrumentation] at androidx.test.uiautomator.QueryController.(QueryController.java:95) 2023-02-14 10:32:47:907 [Instrumentation] at androidx.test.uiautomator.UiDevice.(UiDevice.java:109) 2023-02-14 10:32:47:907 [Instrumentation] at androidx.test.uiautomator.UiDevice.getInstance(UiDevice.java:261) 2023-02-14 10:32:47:907 [Instrumentation] at io.appium.uiautomator2.utils.Device.getUiDevice(Device.java:33) 2023-02-14 10:32:47:908 [Instrumentation] at io.appium.uiautomator2.model.internal.CustomUiDevice.(CustomUiDevice.java:70) 2023-02-14 10:32:47:908 [Instrumentation] at io.appium.uiautomator2.model.internal.CustomUiDevice.getInstance(CustomUiDevice.java:80) 2023-02-14 10:32:47:908 [Instrumentation] at io.appium.uiautomator2.server.mjpeg.MjpegScreenshotStream.(MjpegScreenshotStream.java:43) 2023-02-14 10:32:47:908 [Instrumentation] ... 32 more 2023-02-14 10:32:47:952 [Instrumentation] INSTRUMENTATION_RESULT: shortMsg=Process crashed. 2023-02-14 10:32:47:952 [Instrumentation] INSTRUMENTATION_CODE: 0 2023-02-14 10:32:48:443 [Instrumentation] The process has exited with code 0 2023-02-14 10:32:48:684 [UiAutomator2] The instrumentation process has been unexpectedly terminated. Retrying UiAutomator2 startup (#1 of 1) 2023-02-14 10:32:48:684 [UiAutomator2] Performing strict cleanup of automation leftovers 2023-02-14 10:32:48:707 [UiAutomator2] No obsolete sessions have been detected (read ECONNRESET) 2023-02-14 10:32:48:707 [ADB] Running '/usr/lib/android-sdk/platform-tools/adb -P 5037 -s R5CRA1SQC1R shell am force-stop io.appium.uiautomator2.server.test' 2023-02-14 10:32:48:812 [ADB] Attempting to kill all uiautomator processes 2023-02-14 10:32:48:812 [ADB] Getting IDs of all 'uiautomator' processes 2023-02-14 10:32:48:813 [ADB] Running '/usr/lib/android-sdk/platform-tools/adb -P 5037 -s R5CRA1SQC1R shell pgrep -f \(\[\[:blank:\]\]\|\^\)uiautomator\(\[\[:blank:\]\]\|\$\)' 2023-02-14 10:32:48:959 [ADB] No 'uiautomator' process has been found 2023-02-14 10:32:51:960 [UiAutomator2] Waiting up to 60000ms for UiAutomator2 to be online... 2023-02-14 10:32:51:961 [ADB] Creating ADB subprocess with args: ["-P",5037,"-s","R5CRA1SQC1R","shell","am","instrument","-w","-e","disableAnalytics",true,"io.appium.uiautomator2.server.test/androidx.test.runner.AndroidJUnitRunner"] 2023-02-14 10:32:51:974 [WD Proxy] Matched '/status' to command name 'getStatus' 2023-02-14 10:32:51:975 [WD Proxy] Proxying [GET /status] to [GET http://127.0.0.1:12332/wd/hub/status] with no body 2023-02-14 10:32:52:000 [WD Proxy] Error: read ECONNRESET 2023-02-14 10:32:52:000 [WD Proxy] at TCP.onStreamRead (internal/stream_base_commons.js:209:20) 2023-02-14 10:32:53:002 [WD Proxy] Matched '/status' to command name 'getStatus' 2023-02-14 10:32:53:002 [WD Proxy] Proxying [GET /status] to [GET http://127.0.0.1:12332/wd/hub/status] with no body 2023-02-14 10:32:53:015 [WD Proxy] Error: read ECONNRESET 2023-02-14 10:32:53:015 [WD Proxy] at TCP.onStreamRead (internal/stream_base_commons.js:209:20) 2023-02-14 10:32:53:856 [Instrumentation] io.appium.uiautomator2.server.test.AppiumUiAutomator2Server: 2023-02-14 10:32:54:016 [WD Proxy] Matched '/status' to command name 'getStatus' 2023-02-14 10:32:54:017 [WD Proxy] Proxying [GET /status] to [GET http://127.0.0.1:12332/wd/hub/status] with no body 2023-02-14 10:32:54:028 [WD Proxy] Error: read ECONNRESET 2023-02-14 10:32:54:028 [WD Proxy] at TCP.onStreamRead (internal/stream_base_commons.js:209:20) 2023-02-14 10:32:54:895 [Instrumentation] Error in startServer(io.appium.uiautomator2.server.test.AppiumUiAutomator2Server): 2023-02-14 10:32:54:895 [Instrumentation] java.lang.ExceptionInInitializerError 2023-02-14 10:32:54:895 [Instrumentation] at io.appium.uiautomator2.server.mjpeg.MjpegScreenshotServer.(MjpegScreenshotServer.java:41) 2023-02-14 10:32:54:895 [Instrumentation] at io.appium.uiautomator2.server.mjpeg.MjpegScreenshotServer.(MjpegScreenshotServer.java:35) 2023-02-14 10:32:54:896 [Instrumentation] at io.appium.uiautomator2.server.ServerInstrumentation.startMjpegServer(ServerInstrumentation.java:241) 2023-02-14 10:32:54:896 [Instrumentation] at io.appium.uiautomator2.server.test.AppiumUiAutomator2Server.startServer(AppiumUiAutomator2Server.java:31) 2023-02-14 10:32:54:896 [Instrumentation] ... 27 trimmed 2023-02-14 10:32:54:896 [Instrumentation] Caused by: java.lang.IllegalStateException: UiAutomationService android.accessibilityservice.IAccessibilityServiceClient$Stub$Proxy@939608dalready registered! 2023-02-14 10:32:54:897 [Instrumentation] at android.os.Parcel.createExceptionOrNull(Parcel.java:2393) 2023-02-14 10:32:54:897 [Instrumentation] at android.os.Parcel.createException(Parcel.java:2369) 2023-02-14 10:32:54:897 [Instrumentation] at android.os.Parcel.readException(Parcel.java:2352) 2023-02-14 10:32:54:897 [Instrumentation] at android.os.Parcel.readException(Parcel.java:2294) 2023-02-14 10:32:54:897 [Instrumentation] at android.app.IUiAutomationConnection$Stub$Proxy.connect(IUiAutomationConnection.java:427) 2023-02-14 10:32:54:898 [Instrumentation] at android.app.UiAutomation.connect(UiAutomation.java:243) 2023-02-14 10:32:54:898 [Instrumentation] at android.app.Instrumentation.getUiAutomation(Instrumentation.java:2189) 2023-02-14 10:32:54:898 [Instrumentation] at androidx.test.uiautomator.UiDevice.getUiAutomation(UiDevice.java:1129) 2023-02-14 10:32:54:898 [Instrumentation] at androidx.test.uiautomator.QueryController.(QueryController.java:95) 2023-02-14 10:32:54:898 [Instrumentation] at androidx.test.uiautomator.UiDevice.(UiDevice.java:109) 2023-02-14 10:32:54:899 [Instrumentation] at androidx.test.uiautomator.UiDevice.getInstance(UiDevice.java:261) 2023-02-14 10:32:54:899 [Instrumentation] at io.appium.uiautomator2.utils.Device.getUiDevice(Device.java:33) 2023-02-14 10:32:54:899 [Instrumentation] at io.appium.uiautomator2.model.internal.CustomUiDevice.(CustomUiDevice.java:70) 2023-02-14 10:32:54:899 [Instrumentation] at io.appium.uiautomator2.model.internal.CustomUiDevice.getInstance(CustomUiDevice.java:80) 2023-02-14 10:32:54:899 [Instrumentation] at io.appium.uiautomator2.server.mjpeg.MjpegScreenshotStream.(MjpegScreenshotStream.java:43) 2023-02-14 10:32:54:900 [Instrumentation] ... 32 more 2023-02-14 10:32:54:968 [Instrumentation] INSTRUMENTATION_RESULT: shortMsg=Process crashed. 2023-02-14 10:32:54:978 [Instrumentation] INSTRUMENTATION_CODE: 0 2023-02-14 10:32:55:028 [WD Proxy] Matched '/status' to command name 'getStatus' 2023-02-14 10:32:55:028 [WD Proxy] Proxying [GET /status] to [GET http://127.0.0.1:12332/wd/hub/status] with no body 2023-02-14 10:32:55:039 [WD Proxy] Error: read ECONNRESET 2023-02-14 10:32:55:040 [WD Proxy] at TCP.onStreamRead (internal/stream_base_commons.js:209:20) 2023-02-14 10:32:55:440 [Instrumentation] The process has exited with code 0 2023-02-14 10:32:56:042 [UiAutomator2] The instrumentation process cannot be initialized. Make sure the application under test does not crash and investigate the logcat output. 2023-02-14 10:32:56:043 [UiAutomator2] Deleting UiAutomator2 session 2023-02-14 10:32:56:044 [UiAutomator2] Deleting UiAutomator2 server session 2023-02-14 10:32:56:045 [UiAutomator2] Did not get confirmation UiAutomator2 deleteSession worked; Error was: UnknownError: An unknown server-side error occurred while processing the command. Original error: 'DELETE /' cannot be proxied to UiAutomator2 server because the instrumentation process is not running (probably crashed). Check the server log and/or the logcat output for more details 2023-02-14 10:32:56:045 [UiAutomator2] Resetting IME to 'com.samsung.android.honeyboard/.service.HoneyBoardService' 2023-02-14 10:32:56:046 [ADB] Running '/usr/lib/android-sdk/platform-tools/adb -P 5037 -s R5CRA1SQC1R shell ime set com.samsung.android.honeyboard/.service.HoneyBoardService' 2023-02-14 10:32:56:175 [ADB] Running '/usr/lib/android-sdk/platform-tools/adb -P 5037 -s R5CRA1SQC1R shell am force-stop com.viator.mobile.android.prerelease' 2023-02-14 10:32:56:339 [Logcat] Stopping logcat capture 2023-02-14 10:32:56:341 [ADB] Removing forwarded port socket connection: 12332 2023-02-14 10:32:56:342 [ADB] Running '/usr/lib/android-sdk/platform-tools/adb -P 5037 -s R5CRA1SQC1R forward --remove tcp:12332' 2023-02-14 10:32:56:361 [UiAutomator2] Restoring hidden api policy to the device default configuration 2023-02-14 10:32:56:362 [ADB] Running '/usr/lib/android-sdk/platform-tools/adb -P 5037 -s R5CRA1SQC1R shell 'settings delete global hidden_api_policy_pre_p_apps;settings delete global hidden_api_policy_p_apps;settings delete global hidden_api_policy'' 2023-02-14 10:32:56:580 [BaseDriver] Event 'newSessionStarted' logged at 1676370776579 (10:32:56 GMT+0000 (Greenwich Mean Time)) 2023-02-14 10:32:56:633 [MJSONWP] Encountered internal error running command: Error: The instrumentation process cannot be initialized. Make sure the application under test does not crash and investigate the logcat output. 2023-02-14 10:32:56:633 [MJSONWP] at Object.errorAndThrow (/home/ltadmin/Documents/appium/latest/node_modules/appium/node_modules/appium-support/lib/logging.js:94:35) 2023-02-14 10:32:56:633 [MJSONWP] at UiAutomator2Server.startSession (/home/ltadmin/Documents/appium/latest/node_modules/appium/node_modules/appium-uiautomator2-driver/lib/uiautomator2.js:247:13) 2023-02-14 10:32:56:633 [MJSONWP] at AndroidUiautomator2Driver.startUiAutomator2Session (/home/ltadmin/Documents/appium/latest/node_modules/appium/node_modules/appium-uiautomator2-driver/lib/driver.js:416:5) 2023-02-14 10:32:56:633 [MJSONWP] at AndroidUiautomator2Driver.createSession (/home/ltadmin/Documents/appium/latest/node_modules/appium/node_modules/appium-uiautomator2-driver/lib/driver.js:229:7) 2023-02-14 10:32:56:633 [MJSONWP] at AppiumDriver.createSession (/home/ltadmin/Documents/appium/latest/node_modules/appium/lib/appium.js:387:35) 2023-02-14 10:32:56:636 [HTTP] <-- POST /wd/hub/session 500 18071 ms - 274 2023-02-14 10:32:56:636 [HTTP] 2023-02-14 10:32:58:582 [HTTP] --> DELETE /wd/hub/session/ 2023-02-14 10:32:58:582 [HTTP] {} 2023-02-14 10:32:58:596 [HTTP] No route found for /wd/hub/session/ 2023-02-14 10:32:58:597 [HTTP] <-- DELETE /wd/hub/session/ 404 15 ms - 211 2023-02-14 10:32:58:597 [HTTP]