2023-03-02 20:33:38:267 [Appium] Attempting to load plugin images...
2023-03-02 20:33:38:270 [Appium] Requiring plugin at /Users/eliekhoury/.appium/node_modules/@appium/images-plugin
2023-03-02 20:33:38:915 [Appium] Welcome to Appium v2.0.0-beta.56
2023-03-02 20:33:38:920 [Appium] Non-default server args:
2023-03-02 20:33:38:924 [Appium] { address: '127.0.0.1', port: 63522, usePlugins: [ 'images' ] }
2023-03-02 20:33:38:925 [Appium] Attempting to load driver xcuitest...
2023-03-02 20:33:38:927 [Appium] Requiring driver at /Users/eliekhoury/.appium/node_modules/appium-xcuitest-driver
2023-03-02 20:33:41:260 [Appium] Attempting to load driver uiautomator2...
2023-03-02 20:33:41:261 [Appium] Requiring driver at /Users/eliekhoury/.appium/node_modules/appium-uiautomator2-driver
2023-03-02 20:33:43:625 [Appium] Attempting to load driver espresso...
2023-03-02 20:33:43:627 [Appium] Requiring driver at /Users/eliekhoury/.appium/node_modules/appium-espresso-driver
2023-03-02 20:33:46:093 [Appium] Appium REST http interface listener started on 127.0.0.1:63522
2023-03-02 20:33:46:094 [Appium] Available drivers:
2023-03-02 20:33:46:094 [Appium]   - xcuitest@4.12.1 (automationName 'XCUITest')
2023-03-02 20:33:46:094 [Appium]   - uiautomator2@2.12.2 (automationName 'UiAutomator2')
2023-03-02 20:33:46:095 [Appium]   - espresso@2.15.1 (automationName 'Espresso')
2023-03-02 20:33:46:095 [Appium] Available plugins:
2023-03-02 20:33:46:096 [Appium]   - images@1.3.7 (ACTIVE)
2023-03-02 20:33:46:148 [HTTP] --> GET /status
2023-03-02 20:33:46:149 [HTTP] {}
2023-03-02 20:33:46:150 [AppiumDriver@6a5e] Calling AppiumDriver.getStatus() with args: []
2023-03-02 20:33:46:152 [AppiumDriver@6a5e] Plugins which can handle cmd 'getStatus': images
2023-03-02 20:33:46:153 [AppiumDriver@6a5e] Plugin images is now handling cmd 'getStatus'
2023-03-02 20:33:46:153 [AppiumDriver@6a5e] Executing default handling behavior for command 'getStatus'
2023-03-02 20:33:46:158 [AppiumDriver@6a5e] Responding to client with driver.getStatus() result: {"build":{"version":"2.0.0-beta.56"}}
2023-03-02 20:33:46:170 [HTTP] <-- GET /status 200 17 ms - 47
2023-03-02 20:33:46:170 [HTTP] 
2023-03-02 20:33:46:542 [HTTP] --> GET /status
2023-03-02 20:33:46:543 [HTTP] {}
2023-03-02 20:33:46:543 [AppiumDriver@6a5e] Calling AppiumDriver.getStatus() with args: []
2023-03-02 20:33:46:543 [AppiumDriver@6a5e] Plugins which can handle cmd 'getStatus': images
2023-03-02 20:33:46:543 [AppiumDriver@6a5e] Plugin images is now handling cmd 'getStatus'
2023-03-02 20:33:46:543 [AppiumDriver@6a5e] Executing default handling behavior for command 'getStatus'
2023-03-02 20:33:46:544 [AppiumDriver@6a5e] Responding to client with driver.getStatus() result: {"build":{"version":"2.0.0-beta.56"}}
2023-03-02 20:33:46:545 [HTTP] <-- GET /status 200 2 ms - 47
2023-03-02 20:33:46:545 [HTTP] 
2023-03-02 20:33:46:768 [HTTP] Request idempotency key: 04adeed3-c8aa-4fc4-a6fe-8cf48d5a5607
2023-03-02 20:33:46:787 [HTTP] --> POST /session
2023-03-02 20:33:46:788 [HTTP] {"capabilities":{"firstMatch":[{"platformName":"Android","appium:app":"/Users/eliekhoury/Documents/source/DrivingRangeApp/TrackMan.DrivingRangeApp.Droid.Kiosk/bin/Debug/net7.0-android/dk.trackman.range.kiosk-Signed.apk","appium:automationName":"Espresso","appium:deviceName":"yeti","appium:platformVersion":"9.0","appium:appPackage":"dk.trackman.range.kiosk","appium:appActivity":"dk.TrackMan.SplashScreen","appium:noReset":"false","appium:androidInstallTimeout":240000,"appium:forceEspressoRebuild":null,"appium:espressoBuildConfig":"{\"additionalAppDependencies\": [\"com.google.android.material:material:1.0.0\", \"androidx.lifecycle:lifecycle-extensions:2.1.0\"]}","appium:avdLaunchTimeout":120000,"appium:autoAcceptAlerts":"true"}]}}
2023-03-02 20:33:46:789 [AppiumDriver@6a5e] Calling AppiumDriver.createSession() with args: [null,null,{"firstMatch":[{"platformName":"Android","appium:app":"/Users/eliekhoury/Documents/source/DrivingRangeApp/TrackMan.DrivingRangeApp.Droid.Kiosk/bin/Debug/net7.0-android/dk.trackman.range.kiosk-Signed.apk","appium:automationName":"Espresso","appium:deviceName":"yeti","appium:platformVersion":"9.0","appium:appPackage":"dk.trackman.range.kiosk","appium:appActivity":"dk.TrackMan.SplashScreen","appium:noReset":"false","appium:androidInstallTimeout":240000,"appium:forceEspressoRebuild":null,"appium:espressoBuildConfig":"{\"additionalAppDependencies\": [\"com.google.android.material:material:1.0.0\", \"androidx.lifecycle:lifecycle-extensions:2.1.0\"]}","appium:avdLaunchTimeout":120000,"appium:autoAcceptAlerts":"true"}]}]
2023-03-02 20:33:46:789 [AppiumDriver@6a5e] Plugins which can handle cmd 'createSession': images
2023-03-02 20:33:46:789 [AppiumDriver@6a5e] Plugin images is now handling cmd 'createSession'
2023-03-02 20:33:46:790 [AppiumDriver@6a5e] Executing default handling behavior for command 'createSession'
2023-03-02 20:33:46:790 [AppiumDriver@6a5e] Event 'newSessionRequested' logged at 1677789226790 (21:33:46 GMT+0100 (Central European Standard Time))
2023-03-02 20:33:46:800 [Appium] Attempting to find matching driver for automationName 'Espresso' and platformName 'Android'
2023-03-02 20:33:46:800 [Appium] The 'espresso' driver was installed and matched caps.
2023-03-02 20:33:46:800 [Appium] Will require it at /Users/eliekhoury/.appium/node_modules/appium-espresso-driver
2023-03-02 20:33:46:800 [Appium] Requiring driver at /Users/eliekhoury/.appium/node_modules/appium-espresso-driver
2023-03-02 20:33:46:801 [AppiumDriver@6a5e] Appium v2.0.0-beta.56 creating new EspressoDriver (v2.15.1) session
2023-03-02 20:33:46:801 [AppiumDriver@6a5e] Checking BaseDriver versions for Appium and EspressoDriver
2023-03-02 20:33:46:801 [AppiumDriver@6a5e] Appium's BaseDriver version is 9.3.2
2023-03-02 20:33:46:801 [AppiumDriver@6a5e] EspressoDriver's BaseDriver version is 9.3.2
2023-03-02 20:33:46:803 [EspressoDriver@a954] Creating session with W3C capabilities: {
2023-03-02 20:33:46:804 [EspressoDriver@a954]   "alwaysMatch": {
2023-03-02 20:33:46:804 [EspressoDriver@a954]     "platformName": "Android",
2023-03-02 20:33:46:804 [EspressoDriver@a954]     "appium:app": "/Users/eliekhoury/Documents/source/DrivingRangeApp/TrackMan.DrivingRangeApp.Droid.Kiosk/bin/Debug/net7.0-android/dk.trackman.range.kiosk-Signed.apk",
2023-03-02 20:33:46:804 [EspressoDriver@a954]     "appium:automationName": "Espresso",
2023-03-02 20:33:46:804 [EspressoDriver@a954]     "appium:deviceName": "yeti",
2023-03-02 20:33:46:804 [EspressoDriver@a954]     "appium:platformVersion": "9.0",
2023-03-02 20:33:46:804 [EspressoDriver@a954]     "appium:appPackage": "dk.trackman.range.kiosk",
2023-03-02 20:33:46:805 [EspressoDriver@a954]     "appium:appActivity": "dk.TrackMan.SplashScreen",
2023-03-02 20:33:46:805 [EspressoDriver@a954]     "appium:noReset": "false",
2023-03-02 20:33:46:805 [EspressoDriver@a954]     "appium:androidInstallTimeout": 240000,
2023-03-02 20:33:46:805 [EspressoDriver@a954]     "appium:forceEspressoRebuild": null,
2023-03-02 20:33:46:805 [EspressoDriver@a954]     "appium:espressoBuildConfig": "{\"additionalAppDependencies\": [\"com.google.android.material:material:1.0.0\", \"androidx.lifecycle:lifecycle-extensions:2.1.0\"]}",
2023-03-02 20:33:46:805 [EspressoDriver@a954]     "appium:avdLaunchTimeout": 120000,
2023-03-02 20:33:46:805 [EspressoDriver@a954]     "appium:autoAcceptAlerts": "true"
2023-03-02 20:33:46:806 [EspressoDriver@a954]   },
2023-03-02 20:33:46:806 [EspressoDriver@a954]   "firstMatch": [
2023-03-02 20:33:46:806 [EspressoDriver@a954]     {}
2023-03-02 20:33:46:806 [EspressoDriver@a954]   ]
2023-03-02 20:33:46:806 [EspressoDriver@a954] }
2023-03-02 20:33:46:814 [EspressoDriver@a954] Capability 'noReset' changed from string to boolean. This may cause unexpected behavior
2023-03-02 20:33:46:818 [EspressoDriver@a954] The following capabilities were provided, but are not recognized by Appium:
2023-03-02 20:33:46:825 [EspressoDriver@a954]   autoAcceptAlerts
2023-03-02 20:33:46:828 [EspressoDriver@a954 (65f26bad)] Session created with session id: 65f26bad-d721-4ed2-b2f9-5e71db53b025
2023-03-02 20:33:46:876 [ADB] Found 3 'build-tools' folders under '/Users/eliekhoury/Library/Android/sdk' (newest first):
2023-03-02 20:33:46:876 [ADB]     /Users/eliekhoury/Library/Android/sdk/build-tools/33.0.0
2023-03-02 20:33:46:876 [ADB]     /Users/eliekhoury/Library/Android/sdk/build-tools/30.0.3
2023-03-02 20:33:46:877 [ADB]     /Users/eliekhoury/Library/Android/sdk/build-tools/29.0.2
2023-03-02 20:33:46:877 [ADB] Using 'adb' from '/Users/eliekhoury/Library/Android/sdk/platform-tools/adb'
2023-03-02 20:33:46:878 [ADB] Running '/Users/eliekhoury/Library/Android/sdk/platform-tools/adb -P 5037 start-server'
2023-03-02 20:33:46:933 [AndroidDriver] Retrieving device list
2023-03-02 20:33:46:934 [ADB] Trying to find a connected android device
2023-03-02 20:33:46:936 [ADB] Getting connected devices
2023-03-02 20:33:46:965 [ADB] Connected devices: [{"udid":"emulator-5554","state":"device"}]
2023-03-02 20:33:46:966 [AndroidDriver] Looking for a device with Android '9.0.0'
2023-03-02 20:33:46:966 [ADB] Setting device id to emulator-5554
2023-03-02 20:33:46:967 [ADB] Getting device platform version
2023-03-02 20:33:46:967 [ADB] Running '/Users/eliekhoury/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 shell getprop ro.build.version.release'
2023-03-02 20:33:47:025 [ADB] Current device property 'ro.build.version.release': 9
2023-03-02 20:33:47:025 [AndroidDriver] Using device: emulator-5554
2023-03-02 20:33:47:027 [ADB] Using 'adb' from '/Users/eliekhoury/Library/Android/sdk/platform-tools/adb'
2023-03-02 20:33:47:028 [ADB] Running '/Users/eliekhoury/Library/Android/sdk/platform-tools/adb -P 5037 start-server'
2023-03-02 20:33:47:057 [ADB] Setting device id to emulator-5554
2023-03-02 20:33:47:059 [BaseDriver] Using local app '/Users/eliekhoury/Documents/source/DrivingRangeApp/TrackMan.DrivingRangeApp.Droid.Kiosk/bin/Debug/net7.0-android/dk.trackman.range.kiosk-Signed.apk'
2023-03-02 20:33:48:088 [ADB] Checking app cert for /Users/eliekhoury/Documents/source/DrivingRangeApp/TrackMan.DrivingRangeApp.Droid.Kiosk/bin/Debug/net7.0-android/dk.trackman.range.kiosk-Signed.apk
2023-03-02 20:33:48:753 [ADB] Using 'apksigner.jar' from '/Users/eliekhoury/Library/Android/sdk/build-tools/33.0.0/lib/apksigner.jar'
2023-03-02 20:33:48:755 [ADB] Starting apksigner: /Library/Java/JavaVirtualMachines/jdk-18.0.1.1.jdk/Contents/Home/bin/java -Xmx1024M -Xss1m -jar /Users/eliekhoury/Library/Android/sdk/build-tools/33.0.0/lib/apksigner.jar verify --print-certs /Users/eliekhoury/Documents/source/DrivingRangeApp/TrackMan.DrivingRangeApp.Droid.Kiosk/bin/Debug/net7.0-android/dk.trackman.range.kiosk-Signed.apk
2023-03-02 20:33:50:600 [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-03-02 20:33:50:600 [ADB] Signer #1 certificate SHA-256 digest: a40da80a59d170caa950cf15c18c454d47a39b26989d8b640ecd745ba71bf5dc
2023-03-02 20:33:50:600 [ADB] Signer #1 certificate SHA-1 digest: 61ed377e85d386a8dfee6b864bd85b0bfaa5af81
2023-03-02 20:33:50:600 [ADB] Signer #1 certificate MD5 digest: e89b158e4bcf988ebd09eb83f5378e87
2023-03-02 20:33:50:601 [ADB] 
2023-03-02 20:33:50:601 [ADB] sha256 hash did match for 'dk.trackman.range.kiosk-Signed.apk'
2023-03-02 20:33:50:601 [ADB] '/Users/eliekhoury/Documents/source/DrivingRangeApp/TrackMan.DrivingRangeApp.Droid.Kiosk/bin/Debug/net7.0-android/dk.trackman.range.kiosk-Signed.apk' is signed with the default certificate
2023-03-02 20:33:50:603 [EspressoDriver@a954 (65f26bad)] EspressoDriver version: 2.15.1
2023-03-02 20:33:50:603 [ADB] Running '/Users/eliekhoury/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 shell getprop ro.build.version.sdk'
2023-03-02 20:33:50:640 [ADB] Current device property 'ro.build.version.sdk': 28
2023-03-02 20:33:50:640 [ADB] Getting device platform version
2023-03-02 20:33:50:641 [ADB] Running '/Users/eliekhoury/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 shell getprop ro.build.version.release'
2023-03-02 20:33:50:679 [ADB] Current device property 'ro.build.version.release': 9
2023-03-02 20:33:50:679 [ADB] Device API level: 28
2023-03-02 20:33:50:679 [EspressoDriver@a954 (65f26bad)] Relaxing hidden api policy
2023-03-02 20:33:50:680 [ADB] Running '/Users/eliekhoury/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 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-03-02 20:33:50:790 [ADB] Running '/Users/eliekhoury/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 wait-for-device'
2023-03-02 20:33:50:813 [ADB] Running '/Users/eliekhoury/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 shell echo ping'
2023-03-02 20:33:50:847 [AndroidDriver] Pushing settings apk to device...
2023-03-02 20:33:50:847 [ADB] Getting install status for io.appium.settings
2023-03-02 20:33:50:848 [ADB] Running '/Users/eliekhoury/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 shell dumpsys package io.appium.settings'
2023-03-02 20:33:50:913 [ADB] 'io.appium.settings' is installed
2023-03-02 20:33:50:914 [ADB] Getting package info for 'io.appium.settings'
2023-03-02 20:33:50:914 [ADB] Running '/Users/eliekhoury/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 shell dumpsys package io.appium.settings'
2023-03-02 20:33:51:055 [ADB] The version name of the installed 'io.appium.settings' is greater or equal to the application version name ('5.0.3' >= '5.0.3')
2023-03-02 20:33:51:057 [ADB] There is no need to install/upgrade '/Users/eliekhoury/.appium/node_modules/appium-espresso-driver/node_modules/io.appium.settings/apks/settings_apk-debug.apk'
2023-03-02 20:33:51:058 [ADB] Getting IDs of all 'io.appium.settings' processes
2023-03-02 20:33:51:059 [ADB] Running '/Users/eliekhoury/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 shell 'pgrep --help; echo $?''
2023-03-02 20:33:51:099 [ADB] Running '/Users/eliekhoury/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 shell pgrep -f \(\[\[:blank:\]\]\|\^\)io\.appium\.settings\(\[\[:blank:\]\]\|\$\)'
2023-03-02 20:33:51:162 [AndroidDriver] io.appium.settings is already running. There is no need to reset its permissions.
2023-03-02 20:33:51:163 [Logcat] Starting logs capture with command: /Users/eliekhoury/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 logcat -v threadtime
2023-03-02 20:33:51:201 [ADB] Running '/Users/eliekhoury/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 shell settings get global animator_duration_scale'
2023-03-02 20:33:51:293 [ADB] Running '/Users/eliekhoury/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 shell settings get global transition_animation_scale'
2023-03-02 20:33:51:375 [ADB] Running '/Users/eliekhoury/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 shell settings get global window_animation_scale'
2023-03-02 20:33:51:460 [EspressoDriver@a954 (65f26bad)] Forwarding Espresso Server port 6791 to 8302
2023-03-02 20:33:51:460 [ADB] Forwarding system: 8302 to device: 6791
2023-03-02 20:33:51:460 [ADB] Running '/Users/eliekhoury/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 forward tcp:8302 tcp:6791'
2023-03-02 20:33:51:493 [ADB] Running '/Users/eliekhoury/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 shell dumpsys window'
2023-03-02 20:33:51:597 [AndroidDriver] Screen already unlocked, doing nothing
2023-03-02 20:33:51:598 [ADB] Getting install status for dk.trackman.range.kiosk
2023-03-02 20:33:51:598 [ADB] Running '/Users/eliekhoury/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 shell dumpsys package dk.trackman.range.kiosk'
2023-03-02 20:33:51:726 [ADB] 'dk.trackman.range.kiosk' is installed
2023-03-02 20:33:51:726 [ADB] Getting package info for 'dk.trackman.range.kiosk'
2023-03-02 20:33:51:726 [ADB] Running '/Users/eliekhoury/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 shell dumpsys package dk.trackman.range.kiosk'
2023-03-02 20:33:51:803 [ADB] The version name of the installed 'dk.trackman.range.kiosk' is greater or equal to the application version name ('1.6.0' >= '1.6.0')
2023-03-02 20:33:51:804 [ADB] There is no need to install/upgrade '/Users/eliekhoury/Documents/source/DrivingRangeApp/TrackMan.DrivingRangeApp.Droid.Kiosk/bin/Debug/net7.0-android/dk.trackman.range.kiosk-Signed.apk'
2023-03-02 20:33:51:804 [AndroidDriver] Performing fast reset on 'dk.trackman.range.kiosk'
2023-03-02 20:33:51:804 [ADB] Getting install status for dk.trackman.range.kiosk
2023-03-02 20:33:51:804 [ADB] Running '/Users/eliekhoury/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 shell dumpsys package dk.trackman.range.kiosk'
2023-03-02 20:33:51:871 [ADB] 'dk.trackman.range.kiosk' is installed
2023-03-02 20:33:51:872 [ADB] Running '/Users/eliekhoury/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 shell am force-stop dk.trackman.range.kiosk'
2023-03-02 20:33:51:986 [ADB] Running '/Users/eliekhoury/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 shell pm clear dk.trackman.range.kiosk'
2023-03-02 20:33:52:437 [AndroidDriver] Performed fast reset on the installed 'dk.trackman.range.kiosk' application (stop and clear)
2023-03-02 20:33:52:437 [ADB] Running '/Users/eliekhoury/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 shell "[ -e '/data/local/tmp/espresso.apppackage' ] && echo __PASS__"'
2023-03-02 20:33:52:486 [ADB] Running '/Users/eliekhoury/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 shell cat /data/local/tmp/espresso.apppackage'
2023-03-02 20:33:52:550 [EspressoDriver@a954 (65f26bad)] The previous target application package was 'dk.trackman.range.kiosk'. The current package is 'dk.trackman.range.kiosk'
2023-03-02 20:33:52:551 [ADB] Checking app cert for /var/folders/db/jj1dvpd568jctjmlqc590cd00000gn/T/io.appium.espressoserver.test_2.15.1_dk.trackman.range.kiosk_emulator-5554.apk
2023-03-02 20:33:52:577 [ADB] Starting apksigner: /Library/Java/JavaVirtualMachines/jdk-18.0.1.1.jdk/Contents/Home/bin/java -Xmx1024M -Xss1m -jar /Users/eliekhoury/Library/Android/sdk/build-tools/33.0.0/lib/apksigner.jar verify --print-certs /var/folders/db/jj1dvpd568jctjmlqc590cd00000gn/T/io.appium.espressoserver.test_2.15.1_dk.trackman.range.kiosk_emulator-5554.apk
2023-03-02 20:33:53:288 [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-03-02 20:33:53:288 [ADB] Signer #1 certificate SHA-256 digest: a40da80a59d170caa950cf15c18c454d47a39b26989d8b640ecd745ba71bf5dc
2023-03-02 20:33:53:288 [ADB] Signer #1 certificate SHA-1 digest: 61ed377e85d386a8dfee6b864bd85b0bfaa5af81
2023-03-02 20:33:53:288 [ADB] Signer #1 certificate MD5 digest: e89b158e4bcf988ebd09eb83f5378e87
2023-03-02 20:33:53:289 [ADB] 
2023-03-02 20:33:53:289 [ADB] sha256 hash did match for 'io.appium.espressoserver.test_2.15.1_dk.trackman.range.kiosk_emulator-5554.apk'
2023-03-02 20:33:53:289 [ADB] '/var/folders/db/jj1dvpd568jctjmlqc590cd00000gn/T/io.appium.espressoserver.test_2.15.1_dk.trackman.range.kiosk_emulator-5554.apk' is signed with the default certificate
2023-03-02 20:33:53:289 [ADB] Getting install status for io.appium.espressoserver.test
2023-03-02 20:33:53:290 [ADB] Running '/Users/eliekhoury/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 shell dumpsys package io.appium.espressoserver.test'
2023-03-02 20:33:53:335 [ADB] 'io.appium.espressoserver.test' is installed
2023-03-02 20:33:53:335 [ADB] Getting package info for 'io.appium.espressoserver.test'
2023-03-02 20:33:53:336 [ADB] Running '/Users/eliekhoury/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 shell dumpsys package io.appium.espressoserver.test'
2023-03-02 20:33:53:427 [ADB] Cannot read version codes of '/var/folders/db/jj1dvpd568jctjmlqc590cd00000gn/T/io.appium.espressoserver.test_2.15.1_dk.trackman.range.kiosk_emulator-5554.apk' and/or 'io.appium.espressoserver.test'
2023-03-02 20:33:53:427 [ADB] Cannot read version names of '/var/folders/db/jj1dvpd568jctjmlqc590cd00000gn/T/io.appium.espressoserver.test_2.15.1_dk.trackman.range.kiosk_emulator-5554.apk' and/or 'io.appium.espressoserver.test'
2023-03-02 20:33:53:430 [ADB] Adding packages ["io.appium.settings","io.appium.espressoserver.test"] to Doze whitelist
2023-03-02 20:33:53:430 [ADB] Got the following command chunks to execute: [["dumpsys","deviceidle","whitelist","+io.appium.settings",";","dumpsys","deviceidle","whitelist","+io.appium.espressoserver.test",";"]]
2023-03-02 20:33:53:430 [ADB] Running '/Users/eliekhoury/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 shell dumpsys deviceidle whitelist +io.appium.settings ; dumpsys deviceidle whitelist +io.appium.espressoserver.test ;'
2023-03-02 20:33:53:499 [EspressoDriver@a954 (65f26bad)] Performing cleanup of automation leftovers
2023-03-02 20:33:53:511 [EspressoDriver@a954 (65f26bad)] No obsolete sessions have been detected (socket hang up)
2023-03-02 20:33:53:512 [EspressoDriver@a954 (65f26bad)] Starting Espresso Server v2.15.1 with cmd: adb shell am instrument -w -e debug false -e disableAnalytics true io.appium.espressoserver.test/androidx.test.runner.AndroidJUnitRunner
2023-03-02 20:33:53:512 [ADB] Creating ADB subprocess with args: ["-P",5037,"-s","emulator-5554","shell","am","instrument","-w","-e","debug",false,"-e","disableAnalytics",true,"io.appium.espressoserver.test/androidx.test.runner.AndroidJUnitRunner"]
2023-03-02 20:33:53:514 [EspressoDriver@a954 (65f26bad)] Waiting up to 45000ms for Espresso server to be online
2023-03-02 20:33:53:516 [EspressoDriver@a954 (65f26bad)] Matched '/status' to command name 'getStatus'
2023-03-02 20:33:53:517 [EspressoDriver@a954 (65f26bad)] Proxying [GET /status] to [GET http://127.0.0.1:8302/status] with no body
2023-03-02 20:33:53:530 [EspressoDriver@a954 (65f26bad)] socket hang up
2023-03-02 20:33:54:032 [EspressoDriver@a954 (65f26bad)] Matched '/status' to command name 'getStatus'
2023-03-02 20:33:54:033 [EspressoDriver@a954 (65f26bad)] Proxying [GET /status] to [GET http://127.0.0.1:8302/status] with no body
2023-03-02 20:33:54:038 [EspressoDriver@a954 (65f26bad)] socket hang up
2023-03-02 20:33:54:539 [EspressoDriver@a954 (65f26bad)] Matched '/status' to command name 'getStatus'
2023-03-02 20:33:54:539 [EspressoDriver@a954 (65f26bad)] Proxying [GET /status] to [GET http://127.0.0.1:8302/status] with no body
2023-03-02 20:33:54:543 [EspressoDriver@a954 (65f26bad)] socket hang up
2023-03-02 20:33:55:044 [EspressoDriver@a954 (65f26bad)] Matched '/status' to command name 'getStatus'
2023-03-02 20:33:55:044 [EspressoDriver@a954 (65f26bad)] Proxying [GET /status] to [GET http://127.0.0.1:8302/status] with no body
2023-03-02 20:33:55:049 [EspressoDriver@a954 (65f26bad)] socket hang up
2023-03-02 20:33:55:550 [EspressoDriver@a954 (65f26bad)] Matched '/status' to command name 'getStatus'
2023-03-02 20:33:55:551 [EspressoDriver@a954 (65f26bad)] Proxying [GET /status] to [GET http://127.0.0.1:8302/status] with no body
2023-03-02 20:33:55:556 [EspressoDriver@a954 (65f26bad)] socket hang up
2023-03-02 20:33:56:056 [EspressoDriver@a954 (65f26bad)] Matched '/status' to command name 'getStatus'
2023-03-02 20:33:56:057 [EspressoDriver@a954 (65f26bad)] Proxying [GET /status] to [GET http://127.0.0.1:8302/status] with no body
2023-03-02 20:33:56:060 [EspressoDriver@a954 (65f26bad)] socket hang up
2023-03-02 20:33:56:561 [EspressoDriver@a954 (65f26bad)] Matched '/status' to command name 'getStatus'
2023-03-02 20:33:56:562 [EspressoDriver@a954 (65f26bad)] Proxying [GET /status] to [GET http://127.0.0.1:8302/status] with no body
2023-03-02 20:33:56:569 [EspressoDriver@a954 (65f26bad)] socket hang up
2023-03-02 20:33:56:771 [EspressoDriver@a954 (65f26bad)] [Instrumentation] io.appium.espressoserver.EspressoServerRunnerTest:
2023-03-02 20:33:57:070 [EspressoDriver@a954 (65f26bad)] Matched '/status' to command name 'getStatus'
2023-03-02 20:33:57:070 [EspressoDriver@a954 (65f26bad)] Proxying [GET /status] to [GET http://127.0.0.1:8302/status] with no body
2023-03-02 20:33:57:206 [EspressoDriver@a954 (65f26bad)] Got response with status 200: {"id":"ab1aea34-9e79-4fa0-9a35-95b5ea08bc96","sessionId":null,"value":null}
2023-03-02 20:33:57:206 [EspressoDriver@a954 (65f26bad)] Espresso server is online. The initialization process took 3694ms
2023-03-02 20:33:57:206 [EspressoDriver@a954 (65f26bad)] Starting the session
2023-03-02 20:33:57:207 [EspressoDriver@a954 (65f26bad)] Matched '/session' to command name 'createSession'
2023-03-02 20:33:57:207 [EspressoDriver@a954 (65f26bad)] Proxying [POST /session] to [POST http://127.0.0.1:8302/session] with body: {"capabilities":{"firstMatch":[{"platform":"LINUX","webStorageEnabled":false,"takesScreenshot":true,"javascriptEnabled":true,"databaseEnabled":false,"networkConnectionEnabled":true,"locationContextEnabled":false,"warnings":{},"desired":{"platformName":"Android","app":"/Users/eliekhoury/Documents/source/DrivingRangeApp/TrackMan.DrivingRangeApp.Droid.Kiosk/bin/Debug/net7.0-android/dk.trackman.range.kiosk-Signed.apk","automationName":"Espresso","deviceName":"yeti","platformVersion":"9.0","appPackage":"dk.trackman.range.kiosk","appActivity":"dk.TrackMan.SplashScreen","noReset":false,"androidInstallTimeout":240000,"forceEspressoRebuild":null,"espressoBuildConfig":"{\"additionalAppDependencies\": [\"com.google.android.material:material:1.0.0\", \"androidx.lifecycle:lifecycle-extensions:2.1.0\"]}","avdLaunchTimeout":120000,"autoAcceptAlerts":"true"},"platformName":"Android","app":"/Users/eliekhoury/Documents/source/DrivingRangeApp/TrackMan.DrivingRangeApp.Droid.Kiosk/bin/Debug/net7.0-android/dk.trackman.range.kio...
2023-03-02 20:34:02:258 [EspressoDriver@a954 (65f26bad)] Got response with status 200: {"id":"c8c8d835-1905-4a7c-bb49-7fbb9eaefb99","sessionId":"14faa01a-b87f-4f93-8bd7-dd70950f3d82","value":{}}
2023-03-02 20:34:02:258 [EspressoDriver@a954 (65f26bad)] Determined the downstream protocol as 'W3C'
2023-03-02 20:34:02:259 [ADB] Running '/Users/eliekhoury/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 shell 'echo "dk.trackman.range.kiosk" > "/data/local/tmp/espresso.apppackage"''
2023-03-02 20:34:02:301 [EspressoDriver@a954 (65f26bad)] Recorded the target application package 'dk.trackman.range.kiosk' to /data/local/tmp/espresso.apppackage
2023-03-02 20:34:02:302 [ADB] Waiting up to 20000ms for activity matching pkg: 'dk.trackman.range.kiosk' and activity: 'dk.TrackMan.SplashScreen' to be focused
2023-03-02 20:34:02:302 [ADB] Possible activities, to be checked: 'dk.TrackMan.SplashScreen', 'dk.trackman.range.kiosk.dk.TrackMan.SplashScreen'
2023-03-02 20:34:02:302 [ADB] Getting focused package and activity
2023-03-02 20:34:02:302 [ADB] Running '/Users/eliekhoury/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 shell dumpsys window windows'
2023-03-02 20:34:02:388 [ADB] Found package: 'dk.trackman.range.kiosk' and fully qualified activity name : 'dk.TrackMan.SplashScreen'
2023-03-02 20:34:02:398 [EspressoDriver@a954 (65f26bad)] Proxying [GET /appium/device/info] to [GET http://127.0.0.1:8302/session/14faa01a-b87f-4f93-8bd7-dd70950f3d82/appium/device/info] with no body
2023-03-02 20:34:02:428 [EspressoDriver@a954 (65f26bad)] Got response with status 200: {"id":"560f6379-5220-49a3-a77f-cc3d9f191a72","sessionId":"14faa01a-b87f-4f93-8bd7-dd70950f3d82","value":{"realDisplaySize":"1920x1080","apiVersion":"28","carrierName":"Android","platformVersion":"9","timeZone":"Europe/Copenhagen","model":"AOSP on IA Emulator","locale":"en_US","brand":"google","androidId":"b757f7b4054fe380","manufacturer":"Google","displayDensity":234}}
2023-03-02 20:34:02:428 [AppiumDriver@6a5e] New EspressoDriver session created successfully, session 65f26bad-d721-4ed2-b2f9-5e71db53b025 added to master session list
2023-03-02 20:34:02:429 [AppiumDriver@6a5e] Event 'newSessionStarted' logged at 1677789242429 (21:34:02 GMT+0100 (Central European Standard Time))
2023-03-02 20:34:02:429 [AppiumDriver@6a5e] Promoting 1 sessionless plugins to be attached to session ID 65f26bad-d721-4ed2-b2f9-5e71db53b025
2023-03-02 20:34:02:429 [EspressoDriver@a954 (65f26bad)] Cached the protocol value 'W3C' for the new session 65f26bad-d721-4ed2-b2f9-5e71db53b025
2023-03-02 20:34:02:430 [EspressoDriver@a954 (65f26bad)] Responding to client with driver.createSession() result: {"capabilities":{"platformName":"Android","app":"/Users/eliekhoury/Documents/source/DrivingRangeApp/TrackMan.DrivingRangeApp.Droid.Kiosk/bin/Debug/net7.0-android/dk.trackman.range.kiosk-Signed.apk","automationName":"Espresso","deviceName":"yeti","platformVersion":"9.0","appPackage":"dk.trackman.range.kiosk","appActivity":"dk.TrackMan.SplashScreen","noReset":false,"androidInstallTimeout":240000,"forceEspressoRebuild":null,"espressoBuildConfig":"{\"additionalAppDependencies\": [\"com.google.android.material:material:1.0.0\", \"androidx.lifecycle:lifecycle-extensions:2.1.0\"]}","avdLaunchTimeout":120000,"autoAcceptAlerts":"true"}}
2023-03-02 20:34:02:431 [HTTP] <-- POST /session 200 15643 ms - 696
2023-03-02 20:34:02:431 [HTTP] 
2023-03-02 20:34:05:755 [HTTP] Request idempotency key: 1a5c433c-ca88-4b2a-a9c4-ceb4b8dc9522
2023-03-02 20:34:05:757 [HTTP] --> POST /session/65f26bad-d721-4ed2-b2f9-5e71db53b025/appium/settings
2023-03-02 20:34:05:757 [HTTP] {"settings":{"imageMatchThreshold":"0.7"}}
2023-03-02 20:34:05:767 [EspressoDriver@a954 (65f26bad)] Calling AppiumDriver.updateSettings() with args: [{"imageMatchThreshold":"0.7"},"65f26bad-d721-4ed2-b2f9-5e71db53b025"]
2023-03-02 20:34:05:767 [AppiumDriver@6a5e] Plugins which can handle cmd 'updateSettings': images
2023-03-02 20:34:05:768 [AppiumDriver@6a5e] Plugin images is now handling cmd 'updateSettings'
2023-03-02 20:34:05:768 [AppiumDriver@6a5e] Executing default handling behavior for command 'updateSettings'
2023-03-02 20:34:05:769 [EspressoDriver@a954 (65f26bad)] Matched '/appium/settings' to command name 'updateSettings'
2023-03-02 20:34:05:770 [EspressoDriver@a954 (65f26bad)] Proxying [POST /appium/settings] to [POST http://127.0.0.1:8302/session/14faa01a-b87f-4f93-8bd7-dd70950f3d82/appium/settings] with body: {"settings":{"imageMatchThreshold":"0.7"}}
2023-03-02 20:34:05:822 [EspressoDriver@a954 (65f26bad)] Got response with status 500: {"id":"eedb4247-49ab-4085-9d08-f968f3017619","sessionId":null,"value":{"error":"unknown error","message":"No such route: /session/14faa01a-b87f-4f93-8bd7-dd70950f3d82/appium/settings","stacktrace":"io.appium.espressoserver.lib.handlers.exceptions.AppiumException: No such route: /session/14faa01a-b87f-4f93-8bd7-dd70950f3d82/appium/settings\n\tat io.appium.espressoserver.lib.http.Router.route(Router.kt:188)\n\tat io.appium.espressoserver.lib.http.Server.serve(Server.kt:51)\n\tat fi.iki.elonen.NanoHTTPD$HTTPSession.execute(NanoHTTPD.java:945)\n\tat fi.iki.elonen.NanoHTTPD$ClientHandler.run(NanoHTTPD.java:192)\n\tat java.lang.Thread.run(Thread.java:764)\n"}}
2023-03-02 20:34:05:822 [W3C] Matched W3C error code 'unknown error' to UnknownError
2023-03-02 20:34:05:848 [EspressoDriver@a954 (65f26bad)] Encountered internal error running command: An unknown server-side error occurred while processing the command. Original error: No such route: /session/14faa01a-b87f-4f93-8bd7-dd70950f3d82/appium/settings
2023-03-02 20:34:05:848 [EspressoDriver@a954 (65f26bad)] io.appium.espressoserver.lib.handlers.exceptions.AppiumException: No such route: /session/14faa01a-b87f-4f93-8bd7-dd70950f3d82/appium/settings
2023-03-02 20:34:05:848 [EspressoDriver@a954 (65f26bad)] 	at io.appium.espressoserver.lib.http.Router.route(Router.kt:188)
2023-03-02 20:34:05:848 [EspressoDriver@a954 (65f26bad)] 	at io.appium.espressoserver.lib.http.Server.serve(Server.kt:51)
2023-03-02 20:34:05:849 [EspressoDriver@a954 (65f26bad)] 	at fi.iki.elonen.NanoHTTPD$HTTPSession.execute(NanoHTTPD.java:945)
2023-03-02 20:34:05:849 [EspressoDriver@a954 (65f26bad)] 	at fi.iki.elonen.NanoHTTPD$ClientHandler.run(NanoHTTPD.java:192)
2023-03-02 20:34:05:849 [EspressoDriver@a954 (65f26bad)] 	at java.lang.Thread.run(Thread.java:764)
2023-03-02 20:34:05:849 [EspressoDriver@a954 (65f26bad)] 
2023-03-02 20:34:05:850 [HTTP] <-- POST /session/65f26bad-d721-4ed2-b2f9-5e71db53b025/appium/settings 500 93 ms - 685
2023-03-02 20:34:05:850 [HTTP]