appium-error.log

[debug] [HTTP] Request idempotency key: ecf4ede7-9e3e-4472-96a1-1c5a3d339d0c
[HTTP] --> POST /wd/hub/session
[HTTP] {"capabilities":{"firstMatch":[{"appium:app":"scanmobile_e2e/e2e/utils/apps/tested.apk","appium:fullReset":true,"appium:clearSystemFiles":true,"platformName":"Android","appium:automationName":"UiAutomator2","appium:deviceName":"MC18N0","appium:allowTestPackage":true,"appium:udid":"17117523021209","appium:platformVersion":"5.1.1","appium:appWaitPackage":"com.budgetbox.appmetromc","appium:appWaitActivity":"com.budgetbox.mcspecific.diagnostic.ui.DiagnosticActivity","appium:settings[shutdownOnPowerDisconnect]":[false]}]},"desiredCapabilities":{"app":"scanmobile_e2e/e2e/utils/apps/tested.apk","fullReset":true,"clearSystemFiles":true,"platformName":"Android","automationName":"UiAutomator2","deviceName":"MC18N0","allowTestPackage":true,"udid":"17117523021209","platformVersion":"5.1.1","appWaitPackage":"com.budgetbox.appmetromc","appWaitActivity":"com.budgetbox.mcspecific.diagnostic.ui.DiagnosticActivity","settings[shutdownOnPowerDisconnect]":[false]}}
[debug] [W3C] Calling AppiumDriver.createSession() with args: [{"app":"scanmobile_e2e/e2e/utils/apps/tested.apk","fullReset":true,"clearSystemFiles":true,"platformName":"Android","automationName":"UiAutomator2","deviceName":"MC18N0","allowTestPackage":true,"udid":"17117523021209","platformVersion":"5.1.1","appWaitPackage":"com.budgetbox.appmetromc","appWaitActivity":"com.budgetbox.mcspecific.diagnostic.ui.DiagnosticActivity","settings[shutdownOnPowerDisconnect]":[false]},null,{"firstMatch":[{"appium:app":"scanmobile_e2e/e2e/utils/apps/tested.apk","appium:fullReset":true,"appium:clearSystemFiles":true,"platformName":"Android","appium:automationName":"UiAutomator2","appium:deviceName":"MC18N0","appium:allowTestPackage":true,"appium:udid":"17117523021209","appium:platformVersion":"5.1.1","appium:appWaitPackage":"com.budgetbox.appmetromc","appium:appWaitActivity":"com.budgetbox.mcspecific.diagnostic.ui.DiagnosticActivity","appium:settings[shutdownOnPowerDisconnect]":[false]}]}]
[debug] [BaseDriver] Event 'newSessionRequested' logged at 1597049305579 (01:48:25 GMT-0700 (Pacific Daylight Time))
[Appium] Appium v1.18.0 creating new AndroidUiautomator2Driver (v1.56.1) 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:app": "scanmobile_e2e/e2e/utils/apps/tested.apk",
[debug] [BaseDriver]     "appium:fullReset": true,
[debug] [BaseDriver]     "appium:clearSystemFiles": true,
[debug] [BaseDriver]     "appium:automationName": "UiAutomator2",
[debug] [BaseDriver]     "appium:deviceName": "MC18N0",
[debug] [BaseDriver]     "appium:allowTestPackage": true,
[debug] [BaseDriver]     "appium:udid": "17117523021209",
[debug] [BaseDriver]     "appium:platformVersion": "5.1.1",
[debug] [BaseDriver]     "appium:appWaitPackage": "com.budgetbox.appmetromc",
[debug] [BaseDriver]     "appium:appWaitActivity": "com.budgetbox.mcspecific.diagnostic.ui.DiagnosticActivity"
[debug] [BaseDriver]   },
[debug] [BaseDriver]   "firstMatch": [
[debug] [BaseDriver]     {}
[debug] [BaseDriver]   ]
[debug] [BaseDriver] }
[BaseDriver] The following capabilities were provided, but are not recognized by Appium:
[BaseDriver]   allowTestPackage
[BaseDriver] Session created with session id: 112ec39b-acd4-4667-9eb1-a940d2c6d013
[BaseDriver] Using local app 'scanmobile_e2e/e2e/utils/apps/tested.apk'
[BaseDriver] The current application path 'scanmobile_e2e/e2e/utils/apps/tested.apk' is not absolute and has been rewritten to '/root/scanmobile_e2e/e2e/utils/apps/tested.apk'. Consider using absolute paths rather than relative
[debug] [UiAutomator2] Checking whether app is actually present
[ADB] Using 'adb' from '/root/platform-tools/adb'
[debug] [ADB] Running '/root/platform-tools/adb -P 5037 start-server'
[AndroidDriver] Retrieving device list
[debug] [ADB] Trying to find a connected android device
[debug] [ADB] Getting connected devices
[debug] [ADB] Connected devices: [{"udid":"15119523020278","state":"device"},{"udid":"16011523020810","state":"device"},{"udid":"17117523021209","state":"device"}]
[AndroidDriver] Using device: 17117523021209
[ADB] Using 'adb' from '/root/platform-tools/adb'
[debug] [ADB] Running '/root/platform-tools/adb -P 5037 start-server'
[debug] [ADB] Setting device id to 17117523021209
[debug] [ADB] Running '/root/platform-tools/adb -P 5037 -s 17117523021209 shell getprop ro.build.version.sdk'
[debug] [ADB] Current device property 'ro.build.version.sdk': 22
[debug] [ADB] Device API level: 22
[debug] [AndroidDriver] Parsing package and activity from app manifest
[ADB] Package name: 'com.budgetbox.appmetromc'
[ADB] Main activity name: 'com.budgetbox.mcspecific.splashscreen.SplashScreenActivity'
[debug] [AndroidDriver] Parsed package and activity are: com.budgetbox.appmetromc/com.budgetbox.mcspecific.splashscreen.SplashScreenActivity
[debug] [ADB] Running '/root/platform-tools/adb -P 5037 -s 17117523021209 wait-for-device'
[debug] [ADB] Running '/root/platform-tools/adb -P 5037 -s 17117523021209 shell echo ping'
[debug] [AndroidDriver] Pushing settings apk to device...
[debug] [ADB] Getting install status for io.appium.settings
[debug] [ADB] Running '/root/platform-tools/adb -P 5037 -s 17117523021209 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 '/root/platform-tools/adb -P 5037 -s 17117523021209 shell dumpsys package io.appium.settings'
[debug] [ADB] The version name of the installed 'io.appium.settings' is greater or equal to the application version name ('3.2.0' >= '3.2.0')
[debug] [ADB] There is no need to install/upgrade '/usr/lib/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] Using ps-based PID detection
[debug] [ADB] Running '/root/platform-tools/adb -P 5037 -s 17117523021209 shell ps'
[AndroidDriver] Granting permissions SET_ANIMATION_SCALE,CHANGE_CONFIGURATION,ACCESS_FINE_LOCATION to 'io.appium.settings'
[debug] [ADB] Granting permissions ["android.permission.SET_ANIMATION_SCALE","android.permission.CHANGE_CONFIGURATION","android.permission.ACCESS_FINE_LOCATION"] to 'io.appium.settings'
[debug] [ADB] Got the following command chunks to execute: [["pm","grant","io.appium.settings","android.permission.SET_ANIMATION_SCALE",";","pm","grant","io.appium.settings","android.permission.CHANGE_CONFIGURATION",";","pm","grant","io.appium.settings","android.permission.ACCESS_FINE_LOCATION",";"]]
[debug] [ADB] Running '/root/platform-tools/adb -P 5037 -s 17117523021209 shell pm grant io.appium.settings android.permission.SET_ANIMATION_SCALE \; pm grant io.appium.settings android.permission.CHANGE_CONFIGURATION \; pm grant io.appium.settings android.permission.ACCESS_FINE_LOCATION \;'
[debug] [ADB] Getting IDs of all 'io.appium.settings' processes
[debug] [ADB] Using ps-based PID detection
[debug] [ADB] Running '/root/platform-tools/adb -P 5037 -s 17117523021209 shell ps'
[debug] [ADB] Starting Appium Settings app
[debug] [ADB] Running '/root/platform-tools/adb -P 5037 -s 17117523021209 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] Using ps-based PID detection
[debug] [ADB] Running '/root/platform-tools/adb -P 5037 -s 17117523021209 shell ps'
[debug] [ADB] Running '/root/platform-tools/adb -P 5037 -s 17117523021209 shell settings put secure mock_location 1'
[debug] [Logcat] Starting logs capture with command: /root/platform-tools/adb -P 5037 -s 17117523021209 logcat -v threadtime
[debug] [UiAutomator2] Forwarding UiAutomator2 Server port 6790 to 8200
[debug] [ADB] Forwarding system: 8200 to device: 6790
[debug] [ADB] Running '/root/platform-tools/adb -P 5037 -s 17117523021209 forward tcp\:8200 tcp\:6790'
[debug] [ADB] Getting install status for io.appium.uiautomator2.server
[debug] [ADB] Running '/root/platform-tools/adb -P 5037 -s 17117523021209 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 '/root/platform-tools/adb -P 5037 -s 17117523021209 shell dumpsys package io.appium.uiautomator2.server'
[debug] [ADB] The installed 'io.appium.uiautomator2.server' package is older than '/usr/lib/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-v4.11.0.apk' (48 < 64 or '4.5.5' < '4.11.0')'
[debug] [UiAutomator2] io.appium.uiautomator2.server installation state: olderVersionInstalled
[debug] [ADB] Checking app cert for /usr/lib/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-v4.11.0.apk
[ADB] Using 'apksigner.jar' from '/root/build-tools/30.0.1/lib/apksigner.jar'
[debug] [ADB] Starting apksigner: /usr/lib/jvm/java-8-openjdk-amd64/jre/bin/java -Xmx1024M -Xss1m -jar /root/build-tools/30.0.1/lib/apksigner.jar verify --print-certs /usr/lib/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-v4.11.0.apk
[ADB] Cannot use apksigner tool for signature verification. Original error: Command '/usr/lib/jvm/java-8-openjdk-amd64/jre/bin/java -Xmx1024M -Xss1m -jar /root/build-tools/30.0.1/lib/apksigner.jar verify --print-certs /usr/lib/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-v4.11.0.apk' exited with code 1
[debug] [ADB] Defaulting to verify.jar
[debug] [ADB] '/usr/lib/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-v4.11.0.apk' is signed with the default certificate
[debug] [ADB] Getting install status for io.appium.uiautomator2.server.test
[debug] [ADB] Running '/root/platform-tools/adb -P 5037 -s 17117523021209 shell dumpsys package io.appium.uiautomator2.server.test'
[debug] [ADB] 'io.appium.uiautomator2.server.test' is installed
[debug] [ADB] Checking app cert for /usr/lib/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-debug-androidTest.apk
[debug] [ADB] Starting apksigner: /usr/lib/jvm/java-8-openjdk-amd64/jre/bin/java -Xmx1024M -Xss1m -jar /root/build-tools/30.0.1/lib/apksigner.jar verify --print-certs /usr/lib/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-debug-androidTest.apk
[ADB] Cannot use apksigner tool for signature verification. Original error: Command '/usr/lib/jvm/java-8-openjdk-amd64/jre/bin/java -Xmx1024M -Xss1m -jar /root/build-tools/30.0.1/lib/apksigner.jar verify --print-certs /usr/lib/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-debug-androidTest.apk' exited with code 1
[debug] [ADB] Defaulting to verify.jar
[debug] [ADB] '/usr/lib/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-debug-androidTest.apk' is signed with the default certificate
[UiAutomator2] Server packages are going to be (re)installed
[UiAutomator2] Full packages reinstall is going to be performed
[debug] [ADB] Uninstalling io.appium.uiautomator2.server
[debug] [ADB] Getting install status for io.appium.uiautomator2.server
[debug] [ADB] Running '/root/platform-tools/adb -P 5037 -s 17117523021209 shell dumpsys package io.appium.uiautomator2.server'
[debug] [ADB] 'io.appium.uiautomator2.server' is installed
[debug] [ADB] Running '/root/platform-tools/adb -P 5037 -s 17117523021209 shell am force-stop io.appium.uiautomator2.server'
[debug] [ADB] Running '/root/platform-tools/adb -P 5037 -s 17117523021209 uninstall io.appium.uiautomator2.server'
[debug] [ADB] 'adb uninstall io.appium.uiautomator2.server' command output: Success
[ADB] io.appium.uiautomator2.server was successfully uninstalled
[debug] [ADB] Running '/root/platform-tools/adb -P 5037 -s 17117523021209 version'
[debug] [ADB] Running '/root/platform-tools/adb -P 5037 -s 17117523021209 features'
[debug] [ADB] Running '/root/platform-tools/adb -P 5037 -s 17117523021209 help'
[debug] [ADB] Running '/root/platform-tools/adb -P 5037 -s 17117523021209 shell 'ls -t -1 /data/local/tmp/appium_cache 2>&1 || echo _ERROR_''
[debug] [ADB] The current Android API does not support extended ls options. Defaulting to no-options call
[debug] [ADB] Running '/root/platform-tools/adb -P 5037 -s 17117523021209 shell 'ls /data/local/tmp/appium_cache 2>&1 || echo _ERROR_''
[debug] [ADB] The count of applications in the cache: 10
[ADB] The application at '/usr/lib/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-v4.11.0.apk' is already cached to '/data/local/tmp/appium_cache/01344cac04b875e34c3a26482ec05d7f3b053505.apk'
[debug] [ADB] Running '/root/platform-tools/adb -P 5037 -s 17117523021209 shell touch -am /data/local/tmp/appium_cache/01344cac04b875e34c3a26482ec05d7f3b053505.apk'
[debug] [ADB] Running '/root/platform-tools/adb -P 5037 -s 17117523021209 shell pm install -r /data/local/tmp/appium_cache/01344cac04b875e34c3a26482ec05d7f3b053505.apk'
[ADB] The installation of 'appium-uiautomator2-server-v4.11.0.apk' took 11428ms
[debug] [ADB] Install command stdout: pkg: /data/local/tmp/appium_cache/01344cac04b875e34c3a26482ec05d7f3b053505.apk
[debug] [ADB] Success
[debug] [ADB] Uninstalling io.appium.uiautomator2.server.test
[debug] [ADB] Getting install status for io.appium.uiautomator2.server.test
[debug] [ADB] Running '/root/platform-tools/adb -P 5037 -s 17117523021209 shell dumpsys package io.appium.uiautomator2.server.test'
[debug] [ADB] 'io.appium.uiautomator2.server.test' is installed
[debug] [ADB] Running '/root/platform-tools/adb -P 5037 -s 17117523021209 shell am force-stop io.appium.uiautomator2.server.test'
[debug] [ADB] Running '/root/platform-tools/adb -P 5037 -s 17117523021209 uninstall io.appium.uiautomator2.server.test'
[debug] [ADB] 'adb uninstall io.appium.uiautomator2.server.test' command output: Success
[ADB] io.appium.uiautomator2.server.test was successfully uninstalled
[debug] [ADB] Running '/root/platform-tools/adb -P 5037 -s 17117523021209 shell 'ls /data/local/tmp/appium_cache 2>&1 || echo _ERROR_''
[debug] [ADB] The count of applications in the cache: 10
[ADB] The application at '/usr/lib/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-debug-androidTest.apk' is already cached to '/data/local/tmp/appium_cache/3188d05a92585e7f2a2cb8880cea331370de08e6.apk'
[debug] [ADB] Running '/root/platform-tools/adb -P 5037 -s 17117523021209 shell touch -am /data/local/tmp/appium_cache/3188d05a92585e7f2a2cb8880cea331370de08e6.apk'
[debug] [ADB] Running '/root/platform-tools/adb -P 5037 -s 17117523021209 shell pm install -r /data/local/tmp/appium_cache/3188d05a92585e7f2a2cb8880cea331370de08e6.apk'
[ADB] The installation of 'appium-uiautomator2-server-debug-androidTest.apk' took 4185ms
[debug] [ADB] Install command stdout: pkg: /data/local/tmp/appium_cache/3188d05a92585e7f2a2cb8880cea331370de08e6.apk
[debug] [ADB] Success
[debug] [UiAutomator2] Waiting up to 30000ms for services to be available
[debug] [ADB] Running '/root/platform-tools/adb -P 5037 -s 17117523021209 shell pm list instrumentation'
[debug] [UiAutomator2] Instrumentation target 'io.appium.uiautomator2.server.test/androidx.test.runner.AndroidJUnitRunner' is available
[debug] [ADB] Checking app cert for /root/scanmobile_e2e/e2e/utils/apps/tested.apk
[debug] [ADB] Starting apksigner: /usr/lib/jvm/java-8-openjdk-amd64/jre/bin/java -Xmx1024M -Xss1m -jar /root/build-tools/30.0.1/lib/apksigner.jar verify --print-certs /root/scanmobile_e2e/e2e/utils/apps/tested.apk
[ADB] Cannot use apksigner tool for signature verification. Original error: Command '/usr/lib/jvm/java-8-openjdk-amd64/jre/bin/java -Xmx1024M -Xss1m -jar /root/build-tools/30.0.1/lib/apksigner.jar verify --print-certs /root/scanmobile_e2e/e2e/utils/apps/tested.apk' exited with code 1
[debug] [ADB] Defaulting to verify.jar
[debug] [ADB] '/root/scanmobile_e2e/e2e/utils/apps/tested.apk' is signed with a non-default certificate
[debug] [ADB] Uninstalling com.budgetbox.appmetromc
[debug] [ADB] Getting install status for com.budgetbox.appmetromc
[debug] [ADB] Running '/root/platform-tools/adb -P 5037 -s 17117523021209 shell dumpsys package com.budgetbox.appmetromc'
[debug] [ADB] 'com.budgetbox.appmetromc' is not installed
[ADB] com.budgetbox.appmetromc was not uninstalled, because it was not present on the device
[debug] [ADB] Getting install status for com.budgetbox.appmetromc
[debug] [ADB] Running '/root/platform-tools/adb -P 5037 -s 17117523021209 shell dumpsys package com.budgetbox.appmetromc'
[debug] [ADB] 'com.budgetbox.appmetromc' is not installed
[debug] [AndroidDriver] Running full reset on 'com.budgetbox.appmetromc' (reinstall)
[debug] [ADB] Running '/root/platform-tools/adb -P 5037 -s 17117523021209 shell 'ls /data/local/tmp/appium_cache 2>&1 || echo _ERROR_''
[debug] [ADB] The count of applications in the cache: 10
[ADB] Caching the application at '/root/scanmobile_e2e/e2e/utils/apps/tested.apk' to '/data/local/tmp/appium_cache/9edef43d0e9d8841aec4c46d4e762d8af668f3d1.apk'
[debug] [ADB] Running '/root/platform-tools/adb -P 5037 -s 17117523021209 shell mkdir -p /data/local/tmp/appium_cache'
[debug] [ADB] Running '/root/platform-tools/adb -P 5037 -s 17117523021209 push /root/scanmobile_e2e/e2e/utils/apps/tested.apk /data/local/tmp/appium_cache/9edef43d0e9d8841aec4c46d4e762d8af668f3d1.apk'
[ADB] The upload of 'tested.apk' (6.26 MB) took 1249ms
[debug] [ADB] Running '/root/platform-tools/adb -P 5037 -s 17117523021209 shell rm -f /data/local/tmp/appium_cache/d01e2ee846cecb2e15b5735bb211cb01b6bbeab8.apk'
[debug] [ADB] Deleted 1 expired application cache entries
[debug] [ADB] Running '/root/platform-tools/adb -P 5037 -s 17117523021209 shell pm install -r /data/local/tmp/appium_cache/9edef43d0e9d8841aec4c46d4e762d8af668f3d1.apk'
[ADB] The installation of 'tested.apk' took 24579ms
[debug] [ADB] Install command stdout: pkg: /data/local/tmp/appium_cache/9edef43d0e9d8841aec4c46d4e762d8af668f3d1.apk
[debug] [ADB] Success
[debug] [UiAutomator2] Performing shallow cleanup of automation leftovers
[debug] [UiAutomator2] No obsolete sessions have been detected (socket hang up)
[debug] [ADB] Running '/root/platform-tools/adb -P 5037 -s 17117523021209 shell am force-stop io.appium.uiautomator2.server.test'
[UiAutomator2] Starting UIAutomator2 server 4.11.0
[UiAutomator2] Using UIAutomator2 server from '/usr/lib/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-v4.11.0.apk' and test from '/usr/lib/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-debug-androidTest.apk'
[UiAutomator2] Waiting up to 30000ms for UiAutomator2 to be online...
[debug] [ADB] Creating ADB subprocess with args: ["-P",5037,"-s","17117523021209","shell","am","instrument","-w","io.appium.uiautomator2.server.test/androidx.test.runner.AndroidJUnitRunner"]
[debug] [WD Proxy] Matched '/status' to command name 'getStatus'
[debug] [WD Proxy] Proxying [GET /status] to [GET http://127.0.0.1:8200/wd/hub/status] with no body
[WD Proxy] socket hang up
[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://127.0.0.1:8200/wd/hub/status] with no body
[WD Proxy] socket hang up
[debug] [WD Proxy] Matched '/status' to command name 'getStatus'
[debug] [WD Proxy] Proxying [GET /status] to [GET http://127.0.0.1:8200/wd/hub/status] with no body
[WD Proxy] socket hang up
[debug] [WD Proxy] Matched '/status' to command name 'getStatus'
[debug] [WD Proxy] Proxying [GET /status] to [GET http://127.0.0.1:8200/wd/hub/status] with no body
[debug] [WD Proxy] Got response with status 200: {"sessionId":"None","value":{"message":"UiAutomator2 Server is ready to accept commands","ready":true}}
[debug] [UiAutomator2] The initialization of the instrumentation process took 4133ms
[debug] [WD Proxy] Matched '/session' to command name 'createSession'
[debug] [WD Proxy] Proxying [POST /session] to [POST http://127.0.0.1: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","app":"scanmobile_e2e/e2e/utils/apps/tested.apk","fullReset":true,"clearSystemFiles":true,"automationName":"UiAutomator2","deviceName":"MC18N0","allowTestPackage":true,"udid":"17117523021209","platformVersion":"5.1.1","appWaitPackage":"com.budgetbox.appmetromc","appWaitActivity":"com.budgetbox.mcspecific.diagnostic.ui.DiagnosticActivity"},"platformName":"Android","app":"scanmobile_e2e/e2e/utils/apps/tested.apk","fullReset":true,"clearSystemFiles":true,"automationName":"UiAutomator2","deviceName":"17117523021209","allowTestPackage":true,"udid":"17117523021209","platformVersion":"5.1.1","appWaitPackage":"com.budgetbox.appmetromc","appWaitActivity":"com.budgetbox.mcspecific.diagnostic.ui.DiagnosticActivity","deviceUDID":"17117523021209","appPackage":"c...
[debug] [WD Proxy] Got response with status 200: {"sessionId":"4998d234-7a58-4e3b-a01e-6336bcedb84a","value":{"capabilities":{"firstMatch":[{"platform":"LINUX","webStorageEnabled":false,"takesScreenshot":true,"javascriptEnabled":true,"databaseEnabled":false,"networkConnectionEnabled":true,"locationContextEnabled":false,"warnings":{},"desired":{"platformName":"Android","app":"scanmobile_e2e/e2e/utils/apps/tested.apk","fullReset":true,"clearSystemFiles":true,"automationName":"UiAutomator2","deviceName":"MC18N0","allowTestPackage":true,"udid":"17117523021209","platformVersion":"5.1.1","appWaitPackage":"com.budgetbox.appmetromc","appWaitActivity":"com.budgetbox.mcspecific.diagnostic.ui.DiagnosticActivity"},"platformName":"Android","app":"scanmobile_e2e/e2e/utils/apps/tested.apk","fullReset":true,"clearSystemFiles":true,"automationName":"UiAutomator2","deviceName":"17117523021209","allowTestPackage":true,"udid":"17117523021209","platformVersion":"5.1.1","appWaitPackage":"com.budgetbox.appmetromc","appWaitActivity":"com.budgetbox.mcspecific.diagnostic.ui.Diagn...
[WD Proxy] Determined the downstream protocol as 'W3C'
[debug] [WD Proxy] Proxying [GET /appium/device/info] to [GET http://127.0.0.1:8200/wd/hub/session/4998d234-7a58-4e3b-a01e-6336bcedb84a/appium/device/info] with no body
[debug] [WD Proxy] Got response with status 200: {"sessionId":"4998d234-7a58-4e3b-a01e-6336bcedb84a","value":{"androidId":"ef03a1bbf7b1681a","apiVersion":"22","bluetooth":{"state":"ON"},"brand":"Zebra","carrierName":"","displayDensity":240,"locale":"fr_FR","manufacturer":"Zebra Technologies","model":"MC18N0","networks":[{"capabilities":{"SSID":null,"linkDownBandwidthKbps":1048576,"linkUpstreamBandwidthKbps":1048576,"networkCapabilities":"NET_CAPABILITY_NOT_RESTRICTED,NET_CAPABILITY_NOT_VPN,NET_CAPABILITY_INTERNET,NET_CAPABILITY_TRUSTED","signalStrength":null,"transportTypes":"TRANSPORT_WIFI"},"detailedState":"CONNECTED","extraInfo":"\"BBox-SCA\"","isAvailable":true,"isConnected":true,"isFailover":false,"isRoaming":false,"state":"CONNECTED","subtype":0,"subtypeName":"","type":1,"typeName":"WIFI"}],"platformVersion":"5.1.1","realDisplaySize":"480x800","timeZone":"Europe/London"}}
[debug] [ADB] Running '/root/platform-tools/adb -P 5037 -s 17117523021209 shell dumpsys window'
[AndroidDriver] Screen already unlocked, doing nothing
[UiAutomator2] Starting 'com.budgetbox.appmetromc/com.budgetbox.mcspecific.splashscreen.SplashScreenActivity and waiting for 'com.budgetbox.appmetromc/com.budgetbox.mcspecific.diagnostic.ui.DiagnosticActivity'
[debug] [ADB] Running '/root/platform-tools/adb -P 5037 -s 17117523021209 shell am start -W -n com.budgetbox.appmetromc/com.budgetbox.mcspecific.splashscreen.SplashScreenActivity -S -a android.intent.action.MAIN -c android.intent.category.LAUNCHER -f 0x10200000'
[debug] [ADB] Waiting up to 20000ms for activity matching pkg: 'com.budgetbox.appmetromc' and activity: 'com.budgetbox.mcspecific.diagnostic.ui.DiagnosticActivity' to be focused
[debug] [ADB] Possible activities, to be checked: 'com.budgetbox.mcspecific.diagnostic.ui.DiagnosticActivity', 'com.budgetbox.appmetromc.com.budgetbox.mcspecific.diagnostic.ui.DiagnosticActivity'
[debug] [ADB] Getting focused package and activity
[debug] [ADB] Running '/root/platform-tools/adb -P 5037 -s 17117523021209 shell dumpsys window windows'
[debug] [ADB] Found package: 'com.budgetbox.appmetromc' and fully qualified activity name : 'com.budgetbox.mcspecific.diagnostic.ui.DiagnosticActivity'
[debug] [WD Proxy] Proxying [GET /appium/device/pixel_ratio] to [GET http://127.0.0.1:8200/wd/hub/session/4998d234-7a58-4e3b-a01e-6336bcedb84a/appium/device/pixel_ratio] with no body
[debug] [WD Proxy] Got response with status 200: {"sessionId":"4998d234-7a58-4e3b-a01e-6336bcedb84a","value":1.5}
[debug] [WD Proxy] Matched '/appium/device/system_bars' to command name 'getSystemBars'
[debug] [WD Proxy] Proxying [GET /appium/device/system_bars] to [GET http://127.0.0.1:8200/wd/hub/session/4998d234-7a58-4e3b-a01e-6336bcedb84a/appium/device/system_bars] with no body
[debug] [WD Proxy] Got response with status 200: {"sessionId":"4998d234-7a58-4e3b-a01e-6336bcedb84a","value":{"statusBar":38}}
[debug] [WD Proxy] Matched '/window/current/size' to command name 'getWindowSize'
[debug] [WD Proxy] Proxying [GET /window/current/size] to [GET http://127.0.0.1:8200/wd/hub/session/4998d234-7a58-4e3b-a01e-6336bcedb84a/window/current/size] with no body
[debug] [WD Proxy] Got response with status 200: {"sessionId":"4998d234-7a58-4e3b-a01e-6336bcedb84a","value":{"height":728,"width":480}}
[Appium] New AndroidUiautomator2Driver session created successfully, session 112ec39b-acd4-4667-9eb1-a940d2c6d013 added to master session list
[Appium] Applying the initial values to Appium settings parsed from W3C caps: {"shutdownOnPowerDisconnect":[false]}
[UiAutomator2] Forwarding the following settings to the UiAutomator2 server: ["shutdownOnPowerDisconnect"]
[debug] [WD Proxy] Matched '/appium/settings' to command name 'updateSettings'
[debug] [WD Proxy] Proxying [POST /appium/settings] to [POST http://127.0.0.1:8200/wd/hub/session/4998d234-7a58-4e3b-a01e-6336bcedb84a/appium/settings] with body: {"settings":{"shutdownOnPowerDisconnect":[false]}}
[WD Proxy] Got response with status 500: {"sessionId":"4998d234-7a58-4e3b-a01e-6336bcedb84a","value":{"error":"unknown error","message":"Invalid setting value type. Got: java.util.ArrayList. Expected: java.lang.Boolean.","stacktrace":"io.appium.uiautomator2.common.exceptions.UiAutomator2Exception: Invalid setting value type. Got: java.util.ArrayList. Expected: java.lang.Boolean.\n\tat io.appium.uiautomator2.model.settings.AbstractSetting.convertValue(AbstractSetting.java:67)\n\tat io.appium.uiautomator2.model.settings.AbstractSetting.update(AbstractSetting.java:34)\n\tat io.appium.uiautomator2.handler.UpdateSettings.safeHandle(UpdateSettings.java:52)\n\tat io.appium.uiautomator2.handler.request.SafeRequestHandler.handle(SafeRequestHandler.java:41)\n\tat io.appium.uiautomator2.server.AppiumServlet.handleRequest(AppiumServlet.java:257)\n\tat io.appium.uiautomator2.server.AppiumServlet.handleHttpRequest(AppiumServlet.java:247)\n\tat io.appium.uiautomator2.http.ServerHandler.channelRead(ServerHandler.java:50)\n\tat io.netty.channel.AbstractChannelHan...
[debug] [W3C] Matched W3C error code 'unknown error' to UnknownError
[debug] [BaseDriver] Event 'newSessionStarted' logged at 1597049369439 (01:49:29 GMT-0700 (Pacific Daylight Time))
[debug] [W3C] Encountered internal error running command: An unknown server-side error occurred while processing the command. Original error: Invalid setting value type. Got: java.util.ArrayList. Expected: java.lang.Boolean.
[debug] [W3C] io.appium.uiautomator2.common.exceptions.UiAutomator2Exception: Invalid setting value type. Got: java.util.ArrayList. Expected: java.lang.Boolean.
[debug] [W3C]   at io.appium.uiautomator2.model.settings.AbstractSetting.convertValue(AbstractSetting.java:67)
[debug] [W3C]   at io.appium.uiautomator2.model.settings.AbstractSetting.update(AbstractSetting.java:34)
[debug] [W3C]   at io.appium.uiautomator2.handler.UpdateSettings.safeHandle(UpdateSettings.java:52)
[debug] [W3C]   at io.appium.uiautomator2.handler.request.SafeRequestHandler.handle(SafeRequestHandler.java:41)
[debug] [W3C]   at io.appium.uiautomator2.server.AppiumServlet.handleRequest(AppiumServlet.java:257)
[debug] [W3C]   at io.appium.uiautomator2.server.AppiumServlet.handleHttpRequest(AppiumServlet.java:247)
[debug] [W3C]   at io.appium.uiautomator2.http.ServerHandler.channelRead(ServerHandler.java:50)
[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.handler.timeout.IdleStateHandler.channelRead(IdleStateHandler.java:266)
[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.processSelectedKeysPlain(NioEventLoop.java:514)
[debug] [W3C]   at io.netty.channel.nio.NioEventLoop.processSelectedKeys(NioEventLoop.java:468)
[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:818)
[debug] [W3C] 
[debug] [W3C] Destroying socket connection
[HTTP] <-- POST /wd/hub/session 500 63868 ms - 4057
[HTTP] 
[BaseDriver] Shutting down because we waited 60 seconds for a command
[Appium] Closing session, cause was 'New Command Timeout of 60 seconds expired. Try customizing the timeout using the 'newCommandTimeout' desired capability'
[Appium] Removing session '112ec39b-acd4-4667-9eb1-a940d2c6d013' from our master session list
[debug] [UiAutomator2] Deleting UiAutomator2 session
[debug] [UiAutomator2] Deleting UiAutomator2 server session
[debug] [WD Proxy] Matched '/' to command name 'deleteSession'
[debug] [WD Proxy] Proxying [DELETE /] to [DELETE http://127.0.0.1:8200/wd/hub/session/4998d234-7a58-4e3b-a01e-6336bcedb84a] with no body
[debug] [WD Proxy] Got response with status 200: {"sessionId":"4998d234-7a58-4e3b-a01e-6336bcedb84a","value":null}
[debug] [ADB] Running '/root/platform-tools/adb -P 5037 -s 17117523021209 shell am force-stop com.budgetbox.appmetromc'
[debug] [Instrumentation] .
[debug] [UiAutomator2] Capability 'fullReset' set to 'true', Uninstalling 'com.budgetbox.appmetromc'
[debug] [ADB] Uninstalling com.budgetbox.appmetromc
[debug] [ADB] Getting install status for com.budgetbox.appmetromc
[debug] [ADB] Running '/root/platform-tools/adb -P 5037 -s 17117523021209 shell dumpsys package com.budgetbox.appmetromc'
[debug] [Instrumentation] Time: 69,174
[debug] [Instrumentation] 
[debug] [Instrumentation] OK (1 test)
[debug] [ADB] 'com.budgetbox.appmetromc' is installed
[debug] [ADB] Running '/root/platform-tools/adb -P 5037 -s 17117523021209 shell am force-stop com.budgetbox.appmetromc'
[debug] [Instrumentation] The process has exited with code 0
[debug] [ADB] Running '/root/platform-tools/adb -P 5037 -s 17117523021209 uninstall com.budgetbox.appmetromc'
[debug] [ADB] 'adb uninstall com.budgetbox.appmetromc' command output: Success
[ADB] com.budgetbox.appmetromc was successfully uninstalled
[debug] [Logcat] Stopping logcat capture
[debug] [ADB] Removing forwarded port socket connection: 8200 
[debug] [ADB] Running '/root/platform-tools/adb -P 5037 -s 17117523021209 forward --remove tcp\:8200'