gistfile1.txt

2018-03-14 08:53:24:627 - info: [Appium] Welcome to Appium v1.8.0-beta3
2018-03-14 08:53:24:628 - info: [Appium] Non-default server args:
2018-03-14 08:53:24:629 - info: [Appium]   port: 4002
2018-03-14 08:53:24:629 - info: [Appium]   bootstrapPort: 6002
2018-03-14 08:53:24:629 - info: [Appium]   log: /home/trixo/Code/AutomatedTesting/logs/237_CleanInstall_988678503543525154/Appium.log
2018-03-14 08:53:24:629 - info: [Appium]   logTimestamp: true
2018-03-14 08:53:24:648 - info: [Appium] Appium REST http interface listener started on 0.0.0.0:4002
2018-03-14 08:53:24:675 - info: [HTTP] --> POST /wd/hub/session {"desiredCapabilities":{"javascriptEnabled":true,"locationContextEnabled":true,"handlesAlerts":true,"rotatable":true,"platformName":"Android","deviceName":"Android","appPackage":"com.gorro.nothing","appActivity":"com.gorro.nothing.NothingActivity","fullReset":false,"noReset":true,"newCommandTimeout":120000,"skipUnlock":true,"androidInstallTimeout":150000,"systemPort":8202,"udid":"localhost:7409","requestOrigins":{"url":"http://webdriver.io","version":"4.12.0","name":"webdriverio"}}}
2018-03-14 08:53:24:677 - info: [debug] [MJSONWP] Calling AppiumDriver.createSession() with args: [{"javascriptEnabled":true,"locationContextEnabled":true,"handlesAlerts":true,"rotatable":true,"platformName":"Android","deviceName":"Android","appPackage":"com.gorro.nothing","appActivity":"com.gorro.nothing.NothingActivity","fullReset":false,"noReset":true,"newCommandTimeout":120000,"skipUnlock":true,"androidInstallTimeout":150000,"systemPort":8202,"udid":"localhost:7409","requestOrigins":{"url":"http://webdriver.io","version":"4.12.0","name":"webdriverio"}},null,null]
2018-03-14 08:53:24:678 - info: [debug] [BaseDriver] Event 'newSessionRequested' logged at 1521017604677 (09:53:24 GMT+0100 (CET))
2018-03-14 08:53:24:679 - info: [Appium] Creating new AndroidDriver (v2.1.1) session
2018-03-14 08:53:24:679 - info: [Appium] Capabilities:
2018-03-14 08:53:24:679 - info: [Appium]   javascriptEnabled: true
2018-03-14 08:53:24:680 - info: [Appium]   locationContextEnabled: true
2018-03-14 08:53:24:680 - info: [Appium]   handlesAlerts: true
2018-03-14 08:53:24:680 - info: [Appium]   rotatable: true
2018-03-14 08:53:24:680 - info: [Appium]   platformName: Android
2018-03-14 08:53:24:680 - info: [Appium]   deviceName: Android
2018-03-14 08:53:24:680 - info: [Appium]   appPackage: com.gorro.nothing
2018-03-14 08:53:24:680 - info: [Appium]   appActivity: com.gorro.nothing.NothingActivity
2018-03-14 08:53:24:681 - info: [Appium]   fullReset: false
2018-03-14 08:53:24:681 - info: [Appium]   noReset: true
2018-03-14 08:53:24:681 - info: [Appium]   newCommandTimeout: 120000
2018-03-14 08:53:24:681 - info: [Appium]   skipUnlock: true
2018-03-14 08:53:24:681 - info: [Appium]   androidInstallTimeout: 150000
2018-03-14 08:53:24:681 - info: [Appium]   systemPort: 8202
2018-03-14 08:53:24:681 - info: [Appium]   udid: localhost:7409
2018-03-14 08:53:24:681 - info: [Appium]   requestOrigins: {
2018-03-14 08:53:24:682 - info: [Appium]     url: http://webdriver.io
2018-03-14 08:53:24:682 - info: [Appium]     version: 4.12.0
2018-03-14 08:53:24:682 - info: [Appium]     name: webdriverio
2018-03-14 08:53:24:682 - info: [Appium]   }
2018-03-14 08:53:24:687 - info: [debug] [BaseDriver] 
2018-03-14 08:53:24:688 - info: [debug] [BaseDriver] Creating session with MJSONWP desired capabilities: {"javascriptEnabled":true,"...
2018-03-14 08:53:24:692 - warn: [BaseDriver] The following capabilities were provided, but are not recognized by appium: javascriptEnabled, locationContextEnabled, handlesAlerts, rotatable, systemPort, requestOrigins.
2018-03-14 08:53:24:693 - info: [BaseDriver] Session created with session id: 627002a4-c935-4de7-ba36-272326ad3de8
2018-03-14 08:53:24:694 - info: [debug] [AndroidDriver] Getting Java version
2018-03-14 08:53:24:737 - info: [AndroidDriver] Java version is: 1.8.0_151
2018-03-14 08:53:24:739 - info: [ADB] Checking whether adb is present
2018-03-14 08:53:24:746 - info: [ADB] Found 2 'build-tools' folders under '/home/trixo/Android/Sdk' (newest first):
2018-03-14 08:53:24:746 - info: [ADB]     /home/trixo/Android/Sdk/build-tools/27.0.3
2018-03-14 08:53:24:746 - info: [ADB]     /home/trixo/Android/Sdk/build-tools/25.0.0
2018-03-14 08:53:24:748 - info: [ADB] Using adb from /home/trixo/Android/Sdk/platform-tools/adb
2018-03-14 08:53:24:748 - info: [AndroidDriver] Retrieving device list
2018-03-14 08:53:24:749 - info: [debug] [ADB] Trying to find a connected android device
2018-03-14 08:53:24:750 - info: [debug] [ADB] Getting connected devices...
2018-03-14 08:53:24:760 - info: [debug] [ADB] 2 device(s) connected
2018-03-14 08:53:24:761 - info: [AndroidDriver] Using device: localhost:7409
2018-03-14 08:53:24:762 - info: [ADB] Checking whether adb is present
2018-03-14 08:53:24:763 - info: [ADB] Using adb from /home/trixo/Android/Sdk/platform-tools/adb
2018-03-14 08:53:24:763 - info: [debug] [ADB] Setting device id to localhost:7409
2018-03-14 08:53:24:764 - info: [AndroidDriver] App file was not listed, instead we're going to run com.gorro.nothing directly on the device
2018-03-14 08:53:24:764 - info: [debug] [AndroidDriver] Checking whether package is present on the device
2018-03-14 08:53:24:765 - info: [debug] [ADB] Getting connected devices...
2018-03-14 08:53:24:772 - info: [debug] [ADB] 2 device(s) connected
2018-03-14 08:53:24:773 - info: [debug] [ADB] Running '/home/trixo/Android/Sdk/platform-tools/adb' with args: ["-P",5037,"-s","localhost:7409","shell","pm","list","packages","com.gorro.nothing"]
2018-03-14 08:53:25:747 - info: [AndroidDriver] Starting Android session
2018-03-14 08:53:25:750 - info: [debug] [ADB] Running '/home/trixo/Android/Sdk/platform-tools/adb' with args: ["-P",5037,"-s","localhost:7409","wait-for-device"]
2018-03-14 08:53:25:761 - info: [debug] [ADB] Getting connected devices...
2018-03-14 08:53:25:770 - info: [debug] [ADB] 2 device(s) connected
2018-03-14 08:53:25:770 - info: [debug] [ADB] Running '/home/trixo/Android/Sdk/platform-tools/adb' with args: ["-P",5037,"-s","localhost:7409","shell","echo","ping"]
2018-03-14 08:53:25:803 - info: [debug] [AndroidDriver] Pushing settings apk to device...
2018-03-14 08:53:25:805 - info: [debug] [ADB] Getting install status for io.appium.settings
2018-03-14 08:53:25:805 - info: [debug] [ADB] Getting connected devices...
2018-03-14 08:53:25:814 - info: [debug] [ADB] 2 device(s) connected
2018-03-14 08:53:25:815 - info: [debug] [ADB] Running '/home/trixo/Android/Sdk/platform-tools/adb' with args: ["-P",5037,"-s","localhost:7409","shell","pm","list","packages","io.appium.settings"]
2018-03-14 08:53:26:677 - info: [debug] [ADB] App is not installed
2018-03-14 08:53:26:677 - info: [debug] [ADB] App '/home/trixo/Downloads/appium-1.8.0-beta3/node_modules/appium-android-driver/node_modules/io.appium.settings/app/build/outputs/apk/settings_apk-debug.apk' not installed. Installing
2018-03-14 08:53:26:680 - info: [debug] [ADB] Getting connected devices...
2018-03-14 08:53:26:690 - info: [debug] [ADB] 2 device(s) connected
2018-03-14 08:53:26:690 - info: [debug] [ADB] Running '/home/trixo/Android/Sdk/platform-tools/adb' with args: ["-P",5037,"-s","localhost:7409","shell","getprop","ro.build.version.sdk"]
2018-03-14 08:53:26:748 - info: [debug] [ADB] Current device property 'ro.build.version.sdk': 24
2018-03-14 08:53:26:749 - info: [debug] [ADB] Device API level: 24
2018-03-14 08:53:26:750 - info: [debug] [ADB] Running '/home/trixo/Android/Sdk/platform-tools/adb' with args: ["-P",5037,"-s","localhost:7409","install","-g","/home/trixo/Downloads/appium-1.8.0-beta3/node_modules/appium-android-driver/node_modules/io.appium.settings/app/build/outputs/apk/settings_apk-debug.apk"]
2018-03-14 08:53:28:791 - info: [debug] [ADB] Install command stdout: [100%] /data/local/tmp/settings_apk-debug.apk
/home/trixo/Downloads/appium-1.8.0-beta3/node_modules/appium-android-driver/node_modules/io.appium.settings/app/build/outputs/apk/settings_apk-debug.apk: 1 file pushed. 6.5 MB/s (45149 bytes in 0.007s)
Success
2018-03-14 08:53:28:793 - info: [debug] [ADB] Getting connected devices...
2018-03-14 08:53:28:806 - info: [debug] [ADB] 2 device(s) connected
2018-03-14 08:53:28:806 - info: [debug] [ADB] Running '/home/trixo/Android/Sdk/platform-tools/adb' with args: ["-P",5037,"-s","localhost:7409","shell","ps"]
2018-03-14 08:53:28:914 - info: [debug] [ADB] Device API level: 24
2018-03-14 08:53:28:915 - info: [debug] [ADB] Getting connected devices...
2018-03-14 08:53:28:926 - info: [debug] [ADB] 2 device(s) connected
2018-03-14 08:53:28:927 - info: [debug] [ADB] Running '/home/trixo/Android/Sdk/platform-tools/adb' with args: ["-P",5037,"-s","localhost:7409","shell","am","start","-W","-n","io.appium.settings/.Settings","-a","android.intent.action.MAIN","-c","android.intent.category.LAUNCHER","-f","0x10200000"]
2018-03-14 08:53:29:886 - info: [debug] [ADB] Device API level: 24
2018-03-14 08:53:29:886 - info: [debug] [ADB] Getting connected devices...
2018-03-14 08:53:29:895 - info: [debug] [ADB] 2 device(s) connected
2018-03-14 08:53:29:895 - info: [debug] [ADB] Running '/home/trixo/Android/Sdk/platform-tools/adb' with args: ["-P",5037,"-s","localhost:7409","shell","appops","set","io.appium.settings","android:mock_location","allow"]
2018-03-14 08:53:29:938 - warn: [AndroidDriver] setDeviceLanguageCountry requires language or country.
2018-03-14 08:53:29:938 - warn: [AndroidDriver] Got language: 'null' and country: 'null'
2018-03-14 08:53:29:939 - info: [debug] [Logcat] Starting logcat capture
2018-03-14 08:53:29:981 - info: [debug] [AndroidDriver] Pushing unlock helper app to device...
2018-03-14 08:53:29:982 - info: [debug] [ADB] Getting install status for io.appium.unlock
2018-03-14 08:53:29:982 - info: [debug] [ADB] Getting connected devices...
2018-03-14 08:53:29:994 - info: [debug] [ADB] 2 device(s) connected
2018-03-14 08:53:29:995 - info: [debug] [ADB] Running '/home/trixo/Android/Sdk/platform-tools/adb' with args: ["-P",5037,"-s","localhost:7409","shell","pm","list","packages","io.appium.unlock"]
2018-03-14 08:53:30:687 - info: [debug] [ADB] App is not installed
2018-03-14 08:53:30:688 - info: [debug] [ADB] App '/home/trixo/Downloads/appium-1.8.0-beta3/node_modules/appium-android-driver/node_modules/appium-unlock/bin/unlock_apk-debug.apk' not installed. Installing
2018-03-14 08:53:30:689 - info: [debug] [ADB] Device API level: 24
2018-03-14 08:53:30:689 - info: [debug] [ADB] Running '/home/trixo/Android/Sdk/platform-tools/adb' with args: ["-P",5037,"-s","localhost:7409","install","-g","/home/trixo/Downloads/appium-1.8.0-beta3/node_modules/appium-android-driver/node_modules/appium-unlock/bin/unlock_apk-debug.apk"]
2018-03-14 08:53:32:170 - info: [debug] [ADB] Install command stdout: [100%] /data/local/tmp/unlock_apk-debug.apk
/home/trixo/Downloads/appium-1.8.0-beta3/node_modules/appium-android-driver/node_modules/appium-unlock/bin/unlock_apk-debug.apk: 1 file pushed. 11.5 MB/s (34611 bytes in 0.003s)
Success
2018-03-14 08:53:32:171 - info: [ADB] Getting device platform version
2018-03-14 08:53:32:172 - info: [debug] [ADB] Getting connected devices...
2018-03-14 08:53:32:186 - info: [debug] [ADB] 2 device(s) connected
2018-03-14 08:53:32:186 - info: [debug] [ADB] Running '/home/trixo/Android/Sdk/platform-tools/adb' with args: ["-P",5037,"-s","localhost:7409","shell","getprop","ro.build.version.release"]
2018-03-14 08:53:32:245 - info: [debug] [ADB] Current device property 'ro.build.version.release': 7.0
2018-03-14 08:53:32:246 - info: [debug] [ADB] Getting connected devices...
2018-03-14 08:53:32:259 - info: [debug] [ADB] 2 device(s) connected
2018-03-14 08:53:32:259 - info: [debug] [ADB] Running '/home/trixo/Android/Sdk/platform-tools/adb' with args: ["-P",5037,"-s","localhost:7409","shell","wm","size"]
2018-03-14 08:53:32:810 - info: [debug] [ADB] Getting connected devices...
2018-03-14 08:53:32:825 - info: [debug] [ADB] 2 device(s) connected
2018-03-14 08:53:32:825 - info: [debug] [ADB] Running '/home/trixo/Android/Sdk/platform-tools/adb' with args: ["-P",5037,"-s","localhost:7409","shell","getprop","ro.product.model"]
2018-03-14 08:53:32:863 - info: [debug] [ADB] Current device property 'ro.product.model': SM-G935F
2018-03-14 08:53:32:864 - info: [debug] [ADB] Getting connected devices...
2018-03-14 08:53:32:878 - info: [debug] [ADB] 2 device(s) connected
2018-03-14 08:53:32:878 - info: [debug] [ADB] Running '/home/trixo/Android/Sdk/platform-tools/adb' with args: ["-P",5037,"-s","localhost:7409","shell","getprop","ro.product.manufacturer"]
2018-03-14 08:53:32:935 - info: [debug] [ADB] Current device property 'ro.product.manufacturer': samsung
2018-03-14 08:53:32:937 - warn: [AndroidDriver] No app sent in, not parsing package/activity
2018-03-14 08:53:32:938 - info: [debug] [AndroidDriver] No app capability. Assuming it is already on the device
2018-03-14 08:53:32:939 - info: [debug] [AndroidBootstrap] Watching for bootstrap disconnect
2018-03-14 08:53:32:942 - info: [debug] [ADB] Forwarding system: 6002 to device: 4724
2018-03-14 08:53:32:943 - info: [debug] [ADB] Running '/home/trixo/Android/Sdk/platform-tools/adb' with args: ["-P",5037,"-s","localhost:7409","forward","tcp:6002","tcp:4724"]
2018-03-14 08:53:32:952 - info: [debug] [UiAutomator] Starting UiAutomator
2018-03-14 08:53:32:952 - info: [debug] [UiAutomator] Moving to state 'starting'
2018-03-14 08:53:32:953 - info: [debug] [UiAutomator] Parsing uiautomator jar
2018-03-14 08:53:32:953 - info: [debug] [UiAutomator] Found jar name: 'AppiumBootstrap.jar'
2018-03-14 08:53:32:953 - info: [debug] [ADB] Running '/home/trixo/Android/Sdk/platform-tools/adb' with args: ["-P",5037,"-s","localhost:7409","push","/home/trixo/Downloads/appium-1.8.0-beta3/node_modules/appium-android-driver/node_modules/appium-android-bootstrap/bootstrap/bin/AppiumBootstrap.jar","/data/local/tmp/"]
2018-03-14 08:53:33:026 - info: [debug] [ADB] Attempting to kill all uiautomator processes
2018-03-14 08:53:33:026 - info: [debug] [ADB] Getting all processes with uiautomator
2018-03-14 08:53:33:027 - info: [debug] [ADB] Getting connected devices...
2018-03-14 08:53:33:034 - info: [debug] [ADB] 2 device(s) connected
2018-03-14 08:53:33:035 - info: [debug] [ADB] Running '/home/trixo/Android/Sdk/platform-tools/adb' with args: ["-P",5037,"-s","localhost:7409","shell","ps"]
2018-03-14 08:53:33:163 - info: [ADB] No uiautomator process found to kill, continuing...
2018-03-14 08:53:33:163 - info: [debug] [UiAutomator] Starting UIAutomator
2018-03-14 08:53:33:164 - info: [debug] [ADB] Creating ADB subprocess with args: ["-P",5037,"-s","localhost:7409","shell","uiautomator","runtest","AppiumBootstrap.jar","-c","io.appium.android.bootstrap.Bootstrap","-e","pkg","com.gorro.nothing","-e","disableAndroidWatchers",false,"-e","acceptSslCerts",false]
2018-03-14 08:53:35:247 - info: [debug] [UiAutomator] Moving to state 'online'
2018-03-14 08:53:35:252 - info: [debug] [AndroidBootstrap] [BOOTSTRAP LOG] [debug] Loading json...
2018-03-14 08:53:35:252 - info: [debug] [AndroidBootstrap] [BOOTSTRAP LOG] [debug] Registered crash watchers.
2018-03-14 08:53:35:253 - info: [AndroidBootstrap] Android bootstrap socket is now connected
2018-03-14 08:53:35:256 - info: [debug] [ADB] Device API level: 24
2018-03-14 08:53:35:257 - info: [debug] [ADB] Getting connected devices...
2018-03-14 08:53:35:266 - info: [debug] [AndroidBootstrap] [BOOTSTRAP LOG] [debug] Client connected
2018-03-14 08:53:35:266 - info: [debug] [ADB] 2 device(s) connected
2018-03-14 08:53:35:266 - info: [debug] [ADB] Running '/home/trixo/Android/Sdk/platform-tools/adb' with args: ["-P",5037,"-s","localhost:7409","shell","am","start","-W","-n","com.gorro.nothing/com.gorro.nothing.NothingActivity","-S","-a","android.intent.action.MAIN","-c","android.intent.category.LAUNCHER","-f","0x10200000"]
2018-03-14 08:53:36:678 - info: [Appium] New AndroidDriver session created successfully, session 627002a4-c935-4de7-ba36-272326ad3de8 added to master session list
2018-03-14 08:53:36:680 - info: [debug] [BaseDriver] Event 'newSessionStarted' logged at 1521017616680 (09:53:36 GMT+0100 (CET))
2018-03-14 08:53:36:683 - info: [debug] [MJSONWP] Responding to client with driver.createSession() result: {"platform":"LINUX","webStorageEnabled":false,"takesScreenshot":true,"javascriptEnabled":true,"databaseEnabled":false,"networkConnectionEnabled":true,"locationContextEnabled":true,"warnings":{},"desired":{"javascriptEnabled":true,"locationContextEnabled":true,"handlesAlerts":true,"rotatable":true,"platformName":"Android","deviceName":"Android","appPackage":"com.gorro.nothing","appActivity":"com.gorro.nothing.NothingActivity","fullReset":false,"noReset":true,"newCommandTimeout":120000,"skipUnlock":true,"androidInstallTimeout":150000,"systemPort":8202,"udid":"localhost:7409","requestOrigins":{"url":"http://webdriver.io","version":"4.12.0","name":"webdriverio"}},"handlesAlerts":true,"rotatable":true,"platformName":"Android","deviceName":"localhost:7409","appPackage":"com.gorro.nothing","appActivity":"com.gorro.nothing.NothingActivity","fullReset":false,"noReset":true,"newCommandTimeout":120000,"skipUnlock":true,"androidInstallTimeout":150000,"systemPort":8202,"udid":"localhost:7409","requestOrigins":{"url":"h...
2018-03-14 08:53:36:689 - info: [HTTP] <-- POST /wd/hub/session 200 12012 ms - 1295 
2018-03-14 08:53:36:695 - info: [HTTP] --> GET /wd/hub/session/627002a4-c935-4de7-ba36-272326ad3de8/window/current/size {}
2018-03-14 08:53:36:697 - info: [debug] [MJSONWP] Calling AppiumDriver.getWindowSize() with args: ["current","627002a4-c935-4de7-ba36-272326ad3de8"]
2018-03-14 08:53:36:704 - info: [debug] [AndroidBootstrap] Sending command to android: {"cmd":"action","action":"getDeviceSize","params":{}}
2018-03-14 08:53:36:707 - info: [debug] [AndroidBootstrap] [BOOTSTRAP LOG] [debug] Got data from client: {"cmd":"action","action":"getDeviceSize","params":{}}
2018-03-14 08:53:36:707 - info: [debug] [AndroidBootstrap] [BOOTSTRAP LOG] [debug] Got command of type ACTION
2018-03-14 08:53:36:708 - info: [debug] [AndroidBootstrap] [BOOTSTRAP LOG] [debug] Got command action: getDeviceSize
2018-03-14 08:53:36:715 - info: [debug] [AndroidBootstrap] [BOOTSTRAP LOG] [debug] Returning result: {"status":0,"value":{"height":1920,"width":1080}}
2018-03-14 08:53:36:716 - info: [debug] [AndroidBootstrap] Received command result from bootstrap
2018-03-14 08:53:36:718 - info: [debug] [MJSONWP] Responding to client with driver.getWindowSize() result: {"height":1920,"width":1080}
2018-03-14 08:53:36:719 - info: [HTTP] <-- GET /wd/hub/session/627002a4-c935-4de7-ba36-272326ad3de8/window/current/size 200 24 ms - 100 
2018-03-14 08:53:36:722 - info: [HTTP] --> POST /wd/hub/session/627002a4-c935-4de7-ba36-272326ad3de8/appium/device/app_installed {"bundleId":"com.pointvoucher.playlondonpv"}
2018-03-14 08:53:36:725 - info: [debug] [MJSONWP] Calling AppiumDriver.isAppInstalled() with args: ["com.pointvoucher.playlondonpv","627002a4-c935-4de7-ba36-272326ad3de8"]
2018-03-14 08:53:36:727 - info: [debug] [ADB] Getting install status for com.pointvoucher.playlondonpv
2018-03-14 08:53:36:728 - info: [debug] [ADB] Getting connected devices...
2018-03-14 08:53:36:736 - info: [debug] [ADB] 2 device(s) connected
2018-03-14 08:53:36:736 - info: [debug] [ADB] Running '/home/trixo/Android/Sdk/platform-tools/adb' with args: ["-P",5037,"-s","localhost:7409","shell","pm","list","packages","com.pointvoucher.playlondonpv"]
2018-03-14 08:53:37:501 - info: [debug] [ADB] App is not installed
2018-03-14 08:53:37:502 - info: [debug] [MJSONWP] Responding to client with driver.isAppInstalled() result: false
2018-03-14 08:53:37:506 - info: [HTTP] <-- POST /wd/hub/session/627002a4-c935-4de7-ba36-272326ad3de8/appium/device/app_installed 200 781 ms - 77 
2018-03-14 08:53:37:510 - info: [HTTP] --> POST /wd/hub/session/627002a4-c935-4de7-ba36-272326ad3de8/appium/device/install_app {"appPath":"/home/trixo/Code/AutomatedTesting/Tests/CleanInstall/apk/pl.apk"}
2018-03-14 08:53:37:511 - info: [debug] [MJSONWP] Calling AppiumDriver.installApp() with args: ["/home/trixo/Code/AutomatedTesting/Tests/CleanInstall/apk/pl.apk",null,null,null,"627002a4-c935-4de7-ba36-272326ad3de8"]
2018-03-14 08:53:37:515 - info: [BaseDriver] Using local app '/home/trixo/Code/AutomatedTesting/Tests/CleanInstall/apk/pl.apk'
2018-03-14 08:53:37:516 - info: [debug] [ADB] Running '/home/trixo/Android/Sdk/platform-tools/adb' with args: ["-P",5037,"-s","localhost:7409","install","-r","/home/trixo/Code/AutomatedTesting/Tests/CleanInstall/apk/pl.apk"]
2018-03-14 08:53:48:787 - info: [debug] [ADB] Install command stdout: [  0%] /data/local/tmp/pl.apk
[  0%] /data/local/tmp/pl.apk
[  0%] /data/local/tmp/pl.apk
[  0%] /data/local/tmp/pl.apk
[  0%] /data/local/tmp/pl.apk
...%] /data/local/tmp/pl.apk
/home/trixo/Code/AutomatedTesting/Tests/CleanInstall/apk/pl.apk: 1 file pushed. 15.1 MB/s (98884171 bytes in 6.258s)
Success
2018-03-14 08:53:48:788 - info: [debug] [MJSONWP] Responding to client with driver.installApp() result: null
2018-03-14 08:53:48:790 - info: [HTTP] <-- POST /wd/hub/session/627002a4-c935-4de7-ba36-272326ad3de8/appium/device/install_app 200 11279 ms - 76 
2018-03-14 08:53:48:794 - info: [HTTP] --> POST /wd/hub/session/627002a4-c935-4de7-ba36-272326ad3de8/appium/device/app_installed {"bundleId":"com.pointvoucher.playlondonpv"}
2018-03-14 08:53:48:795 - info: [debug] [MJSONWP] Calling AppiumDriver.isAppInstalled() with args: ["com.pointvoucher.playlondonpv","627002a4-c935-4de7-ba36-272326ad3de8"]
2018-03-14 08:53:48:798 - info: [debug] [ADB] Getting install status for com.pointvoucher.playlondonpv
2018-03-14 08:53:48:798 - info: [debug] [ADB] Getting connected devices...
2018-03-14 08:53:48:805 - info: [debug] [ADB] 2 device(s) connected
2018-03-14 08:53:48:806 - info: [debug] [ADB] Running '/home/trixo/Android/Sdk/platform-tools/adb' with args: ["-P",5037,"-s","localhost:7409","shell","pm","list","packages","com.pointvoucher.playlondonpv"]
2018-03-14 08:53:49:531 - info: [debug] [ADB] App is installed
2018-03-14 08:53:49:532 - info: [debug] [MJSONWP] Responding to client with driver.isAppInstalled() result: true
2018-03-14 08:53:49:534 - info: [HTTP] <-- POST /wd/hub/session/627002a4-c935-4de7-ba36-272326ad3de8/appium/device/app_installed 200 739 ms - 76 
2018-03-14 08:53:49:539 - info: [HTTP] --> POST /wd/hub/session/627002a4-c935-4de7-ba36-272326ad3de8/appium/device/start_activity {"appPackage":"com.pointvoucher.playlondonpv","appActivity":"com.unity3d.player.UnityPlayerActivity","appWaitPackage":"com.google.android.packageinstaller","appWaitActivity":"com.android.packageinstaller.permission.ui.GrantPermissionsActivity"}
2018-03-14 08:53:49:541 - info: [debug] [MJSONWP] Calling AppiumDriver.startActivity() with args: ["com.pointvoucher.playlondonpv","com.unity3d.player.UnityPlayerActivity","com.google.android.packageinstaller","com.android.packageinstaller.permission.ui.GrantPermissionsActivity",null,null,null,null,null,null,null,"627002a4-c935-4de7-ba36-272326ad3de8"]
2018-03-14 08:53:49:543 - info: [debug] [AndroidDriver] Starting package 'com.pointvoucher.playlondonpv' and activity 'com.unity3d.player.UnityPlayerActivity'
2018-03-14 08:53:49:544 - info: [debug] [ADB] Device API level: 24
2018-03-14 08:53:49:544 - info: [debug] [ADB] Getting connected devices...
2018-03-14 08:53:49:551 - info: [debug] [ADB] 2 device(s) connected
2018-03-14 08:53:49:552 - info: [debug] [ADB] Running '/home/trixo/Android/Sdk/platform-tools/adb' with args: ["-P",5037,"-s","localhost:7409","shell","am","start","-W","-n","com.pointvoucher.playlondonpv/com.unity3d.player.UnityPlayerActivity","-S"]
2018-03-14 08:53:51:491 - info: [debug] [ADB] Waiting up to 20000ms for activity matching pkg: 'com.google.android.packageinstaller' and activity: 'com.android.packageinstaller.permission.ui.GrantPermissionsActivity' to be focused
2018-03-14 08:53:51:492 - info: [debug] [ADB] Possible activities, to be checked: 'com.android.packageinstaller.permission.ui.GrantPermissionsActivity'
2018-03-14 08:53:51:497 - info: [debug] [ADB] Getting focused package and activity
2018-03-14 08:53:51:498 - info: [debug] [ADB] Getting connected devices...
2018-03-14 08:53:51:505 - info: [debug] [ADB] 2 device(s) connected
2018-03-14 08:53:51:505 - info: [debug] [ADB] Running '/home/trixo/Android/Sdk/platform-tools/adb' with args: ["-P",5037,"-s","localhost:7409","shell","dumpsys","window","windows"]
2018-03-14 08:53:51:546 - info: [debug] [ADB] Found package: 'com.google.android.packageinstaller' and fully qualified activity name : 'com.android.packageinstaller.permission.ui.GrantPermissionsActivity'
2018-03-14 08:53:51:547 - info: [debug] [MJSONWP] Responding to client with driver.startActivity() result: null
2018-03-14 08:53:51:551 - info: [HTTP] <-- POST /wd/hub/session/627002a4-c935-4de7-ba36-272326ad3de8/appium/device/start_activity 200 2011 ms - 76 
2018-03-14 08:53:51:557 - info: [HTTP] --> POST /wd/hub/session/627002a4-c935-4de7-ba36-272326ad3de8/element {"using":"-android uiautomator","value":"new UiSelector().resourceId(\"com.android.packageinstaller:id/permission_allow_button\")"}
2018-03-14 08:53:51:558 - info: [debug] [MJSONWP] Calling AppiumDriver.findElement() with args: ["-android uiautomator","new UiSelector().resourceId(\"com.android.packageinstaller:id/permission_allow_button\")","627002a4-c935-4de7-ba36-272326ad3de8"]
2018-03-14 08:53:51:560 - info: [debug] [BaseDriver] Valid locator strategies for this request: xpath, id, class name, accessibility id, -android uiautomator
2018-03-14 08:53:51:562 - info: [debug] [BaseDriver] Waiting up to 0 ms for condition
2018-03-14 08:53:51:564 - info: [debug] [AndroidBootstrap] Sending command to android: {"cmd":"action","action":"find","params":{"strategy":"-android uiautomator","selector":"new UiSelector().resourceId(\"com.android.packageinstaller:id/permission_allow_button\")","context":"","multiple":false}}
2018-03-14 08:53:51:567 - info: [debug] [AndroidBootstrap] [BOOTSTRAP LOG] [debug] Got data from client: {"cmd":"action","action":"find","params":{"strategy":"-android uiautomator","selector":"new UiSelector().resourceId(\"com.android.packageinstaller:id/permission_allow_button\")","context":"","multiple":false}}
2018-03-14 08:53:51:567 - info: [debug] [AndroidBootstrap] [BOOTSTRAP LOG] [debug] Got command of type ACTION
2018-03-14 08:53:51:568 - info: [debug] [AndroidBootstrap] [BOOTSTRAP LOG] [debug] Got command action: find
2018-03-14 08:53:51:568 - info: [debug] [AndroidBootstrap] [BOOTSTRAP LOG] [debug] Finding 'new UiSelector().resourceId("com.android.packageinstaller:id/permission_allow_button")' using 'ANDROID_UIAUTOMATOR' with the contextId: '' multiple: false
2018-03-14 08:53:51:568 - info: [debug] [AndroidBootstrap] [BOOTSTRAP LOG] [debug] Parsing selector: new UiSelector().resourceId("com.android.packageinstaller:id/permission_allow_button")
2018-03-14 08:53:51:568 - info: [debug] [AndroidBootstrap] [BOOTSTRAP LOG] [debug] UiSelector coerce type: class java.lang.String arg: "com.android.packageinstaller:id/permission_allow_button"
2018-03-14 08:53:51:568 - info: [debug] [AndroidBootstrap] [BOOTSTRAP LOG] [debug] Using: UiSelector[RESOURCE_ID=com.android.packageinstaller:id/permission_allow_button]
2018-03-14 08:53:52:004 - info: [debug] [AndroidBootstrap] Received command result from bootstrap
2018-03-14 08:53:52:006 - info: [debug] [AndroidBootstrap] [BOOTSTRAP LOG] [debug] Returning result: {"status":0,"value":{"ELEMENT":"1"}}
2018-03-14 08:53:52:008 - info: [debug] [MJSONWP] Responding to client with driver.findElement() result: {"ELEMENT":"1"}
2018-03-14 08:53:52:010 - info: [HTTP] <-- POST /wd/hub/session/627002a4-c935-4de7-ba36-272326ad3de8/element 200 452 ms - 87 
2018-03-14 08:53:52:014 - info: [HTTP] --> POST /wd/hub/session/627002a4-c935-4de7-ba36-272326ad3de8/element/1/click {}
2018-03-14 08:53:52:014 - info: [debug] [MJSONWP] Calling AppiumDriver.click() with args: ["1","627002a4-c935-4de7-ba36-272326ad3de8"]
2018-03-14 08:53:52:015 - info: [debug] [AndroidBootstrap] Sending command to android: {"cmd":"action","action":"element:click","params":{"elementId":"1"}}
2018-03-14 08:53:52:020 - info: [debug] [AndroidBootstrap] [BOOTSTRAP LOG] [debug] Got data from client: {"cmd":"action","action":"element:click","params":{"elementId":"1"}}
2018-03-14 08:53:52:021 - info: [debug] [AndroidBootstrap] [BOOTSTRAP LOG] [debug] Got command of type ACTION
2018-03-14 08:53:52:021 - info: [debug] [AndroidBootstrap] [BOOTSTRAP LOG] [debug] Got command action: click
2018-03-14 08:53:55:148 - info: [debug] [AndroidBootstrap] Received command result from bootstrap
2018-03-14 08:53:55:150 - info: [debug] [AndroidBootstrap] [BOOTSTRAP LOG] [debug] Returning result: {"status":0,"value":true}
2018-03-14 08:53:55:151 - info: [debug] [MJSONWP] Responding to client with driver.click() result: true
2018-03-14 08:53:55:152 - info: [HTTP] <-- POST /wd/hub/session/627002a4-c935-4de7-ba36-272326ad3de8/element/1/click 200 3138 ms - 76 
2018-03-14 08:54:25:191 - info: [HTTP] --> GET /wd/hub/session/627002a4-c935-4de7-ba36-272326ad3de8/screenshot {}
2018-03-14 08:54:25:191 - info: [debug] [MJSONWP] Calling AppiumDriver.getScreenshot() with args: ["627002a4-c935-4de7-ba36-272326ad3de8"]
2018-03-14 08:54:25:193 - info: [debug] [ADB] Device API level: 24
2018-03-14 08:54:26:732 - info: [debug] [MJSONWP] Responding to client with driver.getScreenshot() result: "iVBORw0KGgoAAAANSUhEUgAABDgAAAeACAYAAAArYecKAACAAElEQVR4AezhPa9ma4ImaF3386x37x0RlZVdVdAGYpoviZGQQNPjAeIfMMLDHAxsXAxwkDCw4Fdg0SZ/AInxB43UwkQtnNGg7qyTmSci9rvWem72R54TESfOyarMysqurHqvK//X//d/2jd/Nt3dbS6Xi8vlYs4yrzqujN2XTrKTA8uXKhbqh4qqHxfE1+JV/Okp6mv1qn4ogoh4FhHPhmGKTUyfy7oz1r1x3ks3bSka4/ogjw/G9cGxn/Z9t++7dcXHOz7ecb0YYxhzGGN6mO+8nX/m7fyZ++2NOae5beacNptpM00RSRCvIvUkvtIgfrtST+pViCfxIvGTWj8q8bcSP6nxvbaetbVaq8tay3mezvN0HIcPHx+9//jBt+8/+PW33/rmm2/88ptv/PKXv/TLX//KL7/9tW9+/SsfPnz0+PjRx4+PHq9X13P3eBz283Cep/M8nedhqbN1qvpSsUS9aut7oUJ8JSU+aevVEsXyuSCppJJKYiSS2CbbxmVyd7958+aNN2/eePfurb/8i5/5i7/4M3/5Fz/z85//uZ///Od+/vOfe/v2jcs2bds05/DhwwcfPnzw4cMHf/3X3/p3v/jWv/3Fr/31L37lr7/5a7/85pd++ctfev/+8OHD7sOHw1rV1rNiidVY4nP1JNTNH1e8iq80iK9kkZIliSSSyKxcdrkcxuV02Tbb5eKybX72F8Of/9Xw5381/LO/eusv//Iv/dVf/ZV/9hc/9/Cz08OfLw8/O81tGGOYczKWNR6t8ahj94UszSIL9aWhBoavxau4ubm5ubn596Wo300Mo0NMXwoGDYYvrEGnrKkr1lrWWs5zOT/G+RjHx/jw/oNvv/3Wt99+69tfXv36F8uvf7H8+henX/3qV371q1/61a9+5eOvl4/fxsdfx/5Yba21tAwX6cVwQSQhaGjooPGsre+kxI+peFafC6KGihIihDlizpgzLtvm...
2018-03-14 08:54:26:739 - info: [HTTP] <-- GET /wd/hub/session/627002a4-c935-4de7-ba36-272326ad3de8/screenshot 200 1547 ms - 966694 
2018-03-14 08:54:27:004 - info: [HTTP] --> POST /wd/hub/session/627002a4-c935-4de7-ba36-272326ad3de8/touch/perform {"actions":[{"action":"tap","options":{"x":1014.5,"y":65,"count":1}}]}
2018-03-14 08:54:27:005 - info: [debug] [MJSONWP] Calling AppiumDriver.performTouch() with args: [[{"action":"tap","options":{"x":1014.5,"y":65,"count":1}}],"627002a4-c935-4de7-ba36-272326ad3de8"]
2018-03-14 08:54:27:008 - info: [debug] [AndroidBootstrap] Sending command to android: {"cmd":"action","action":"click","params":{"x":1014.5,"y":65}}
2018-03-14 08:54:27:018 - info: [debug] [AndroidBootstrap] [BOOTSTRAP LOG] [debug] Got data from client: {"cmd":"action","action":"click","params":{"x":1014.5,"y":65}}
2018-03-14 08:54:27:018 - info: [debug] [AndroidBootstrap] [BOOTSTRAP LOG] [debug] Got command of type ACTION
2018-03-14 08:54:27:018 - info: [debug] [AndroidBootstrap] [BOOTSTRAP LOG] [debug] Got command action: click
2018-03-14 08:54:27:025 - info: [debug] [AndroidBootstrap] [BOOTSTRAP LOG] [debug] Display bounds: [0,0][1080,1920]
2018-03-14 08:54:27:156 - info: [debug] [AndroidBootstrap] Received command result from bootstrap
2018-03-14 08:54:27:156 - info: [debug] [AndroidBootstrap] [BOOTSTRAP LOG] [debug] Returning result: {"status":0,"value":true}
2018-03-14 08:54:27:157 - info: [debug] [MJSONWP] Responding to client with driver.performTouch() result: null
2018-03-14 08:54:27:158 - info: [HTTP] <-- POST /wd/hub/session/627002a4-c935-4de7-ba36-272326ad3de8/touch/perform 200 153 ms - 76 
2018-03-14 08:54:35:221 - info: [HTTP] --> GET /wd/hub/session/627002a4-c935-4de7-ba36-272326ad3de8/screenshot {}
2018-03-14 08:54:35:222 - info: [debug] [MJSONWP] Calling AppiumDriver.getScreenshot() with args: ["627002a4-c935-4de7-ba36-272326ad3de8"]
2018-03-14 08:54:35:226 - info: [debug] [ADB] Device API level: 24
2018-03-14 08:54:37:358 - info: [debug] [MJSONWP] Responding to client with driver.getScreenshot() result: "iVBORw0KGgoAAAANSUhEUgAABDgAAAeACAYAAAArYecKAACAAElEQVR4AezB2XIcCYId2HM9AmAuVVkt9TzMi/Ql8/9fIrPRyKSutkouWML9DgEnGUQiAgQDCCyknxPUYrFYLBaLxWKxWCwWi8Urtl7/3/+PxWKx+GkEsVtRR9FUY1Yyxu+//s1/+2//zfp05X/9x//y9t1bFxcXxnE0TZPP0kgtjmywEoNdmpoyor6orVosXpSmGjulpHEUQexW1GKxWNwUi3tqqnYL0tinqdotSGOv2quhqc+GYbBarZyenlqv166M48b5+YVxHE3T5LMgjZ1Chlit1968eWOaJufn59q6NlXH+qu1xWKx+NnUizCOo3fv3hnO4+zszGaz0dbi+Q3D4OTkxMnJidPTU5fjpbfv/2UzbiwWi2+oxWKxWPzE2tpsNqZp1NJWW4do6/LyQute1haLxWLxLMZx9O7dOwbOzs5txo3F8ymiriScnJz49bdf/fbrb84vz7w/f8e4sVi8DkHtFovFYrF43WKr7if2icfU1maz8RimaTJNk79q7bS2WCwWi2cxjqP3798xxNTR4nk1k9Gs6mw8M52NzjdnNuPGOG4sFq/F0AGxU6smi8Vi8SLE4p6GEnUlw2AYBm1N06SlsVOwSuzTMsVudVyx19TS2iV2W1ssFoufSREvwjRNzs7OZSAnYbB4RjVpXKsyXthMl5zTVqfJYvFaRAxWVquVJKZp0tY0TWpSQS0Wi8XidQgGBEkMidUwmKbJpjWJ2m8QqyGSuDJNk89GTK2dgnoWbe1VIv5qbbFYLH42tVjcqa1xHCVxrWgtFq/JarXyxx9/ODk5cX5+5vz8wvn5uXGaHE0tFovF4oiSGIbBarUyDIO2klDftFqt/PLLL6ZpcnZ2ZpomP5o1cT91uDhcvTxxPHUccTz1c4njqf3ieGq/uFvtF3er44i71WHibrVfUFciBDELRWpWIqq+KW6rRxJ3q8PF3eowcbfaL6jP2mrrSkTcIag7...
2018-03-14 08:54:37:372 - info: [HTTP] <-- GET /wd/hub/session/627002a4-c935-4de7-ba36-272326ad3de8/screenshot 200 2150 ms - 2302730 
2018-03-14 08:54:39:701 - info: [HTTP] --> GET /wd/hub/session/627002a4-c935-4de7-ba36-272326ad3de8/screenshot {}
2018-03-14 08:54:39:702 - info: [debug] [MJSONWP] Calling AppiumDriver.getScreenshot() with args: ["627002a4-c935-4de7-ba36-272326ad3de8"]
2018-03-14 08:54:39:706 - info: [debug] [ADB] Device API level: 24
2018-03-14 08:54:41:864 - info: [debug] [MJSONWP] Responding to client with driver.getScreenshot() result: "iVBORw0KGgoAAAANSUhEUgAABDgAAAeACAYAAAArYecKAACAAElEQVR4AezB2XIcCYId2HM9AmAuVVkt9TzMi/Ql8/9fIrPRyKSutkouWML9DgEnGUQiAgQDCCyknxPUYrFYLBaLxWKxWCwWi8Urtl7/3/+PxWKx+GkEsVtRR9FUY1Yyxu+//s1/+2//zfp05X/9x//y9t1bFxcXxnE0TZPP0kgtjmywEoNdmpoyor6orVosXpSmGjulpHEUQexW1GKxWNwUi3tqqnYL0tinqdotSGOv2quhqc+GYbBarZyenlqv166M48b5+YVxHE3T5LMgjZ1Chlit1968eWOaJufn59q6NlXH+qu1xWKx+NnUizCOo3fv3hnO4+zszGaz0dbi+Q3D4OTkxMnJidPTU5fjpbfv/2UzbiwWi2+oxWKxWPzE2tpsNqZp1NJWW4do6/LyQute1haLxWLxLMZx9O7dOwbOzs5txo3F8ymiriScnJz49bdf/fbrb84vz7w/f8e4sVi8DkHtFovFYrF43WKr7if2icfU1maz8RimaTJNk79q7bS2WCwWi2cxjqP3798xxNTR4nk1k9Gs6mw8M52NzjdnNuPGOG4sFq/F0AGxU6smi8Vi8SLE4p6GEnUlw2AYBm1N06SlsVOwSuzTMsVudVyx19TS2iV2W1ssFoufSREvwjRNzs7OZSAnYbB4RjVpXKsyXthMl5zTVqfJYvFaRAxWVquVJKZp0tY0TWpSQS0Wi8XidQgGBEkMidUwmKbJpjWJ2m8QqyGSuDJNk89GTK2dgnoWbe1VIv5qbbFYLH42tVjcqa1xHCVxrWgtFq/JarXyxx9/ODk5cX5+5vz8wvn5uXGaHE0tFovF4oiSGIbBarUyDIO2klDftFqt/PLLL6ZpcnZ2ZpomP5o1cT91uDhcvTxxPHUccTz1c4njqf3ieGq/uFvtF3er44i71WHibrVfUFciBDELRWpWIqq+KW6rRxJ3q8PF3eowcbfaL6jP2mrrSkTcIag7...
2018-03-14 08:54:41:877 - info: [HTTP] <-- GET /wd/hub/session/627002a4-c935-4de7-ba36-272326ad3de8/screenshot 200 2176 ms - 2278646 
2018-03-14 08:54:44:202 - info: [HTTP] --> GET /wd/hub/session/627002a4-c935-4de7-ba36-272326ad3de8/screenshot {}
2018-03-14 08:54:44:203 - info: [debug] [MJSONWP] Calling AppiumDriver.getScreenshot() with args: ["627002a4-c935-4de7-ba36-272326ad3de8"]
2018-03-14 08:54:44:205 - info: [debug] [ADB] Device API level: 24
2018-03-14 08:54:45:993 - info: [debug] [MJSONWP] Responding to client with driver.getScreenshot() result: "iVBORw0KGgoAAAANSUhEUgAABDgAAAeACAYAAAArYecKAACAAElEQVR4AezBCYCWA/7A8e/ved/maq6mUyWlRKQSiiztJuTKue6jZdnWtZZd1i6WP3bdlnUvdu1iWbcNKVcoQkWkEkq6m6a55533eH7/Xs+OmWne9513aqaZd+b3+che4y5WjDHGmP9xHHAcvteneybDd+7JwQcfTJ8+3fD5woRCIaZO/Zjnn5+F60aocqHKxRhj4lJVRATT8agqIoLpeFQVEcF0PKqKiNAa/Au/XE5HpoBgOiIFBNMRKSCYeBxH8Psdotau6URpSRl9th+I40To3LkTixatZc6cb/jyy+VEVUSU8ohLKlBAMB2RAoLpiBQQTEekgGA6IgUE0xH5VZWOTjEdlWI6KsXEJ9QoLg+xeHkJc+d/ic9x6VqQz4wZC1m8eDW1FFUlVSimo1JMR6WYjkoxHZViOiK/4mKMMcbUEuqqrAoy7d0FfDznKzr7/ZSUVFFRUUUNRVFcjDHGGGOMaU1+UIwxxpgailJXJOJSWFRGeVEZmcSgbKIYY4wxxhjTmvyqGGOMMT9QBVVlcy4QoSEXRRVjjDHGGGNalR8cjDHGmBquC8GQy+ZCQCUNucomDsYYY4wxxrQmPwjGGGNMDVVQVWKJEI9gjDHGGGNMa3IwxhhjjDHGGGOMSXEOxhhjjDHGGGOMMSnOwRhjjDHGGGOMMSbFORhjjDHGGGOMMcakOAdjjDHGGGOMMcaYFOdgjDHGGGOMMcYYk+IcjDHGGGOMMcYYY1KcgzHGGGOMMcYYY0yKczDGGGOMMcYYY4xJcQ7GGGOMMcYYY4wxKc7BGGOMMcYYY4wxJsU5GGOMMcYYY4wxxqQ4B2OMMcYYY4wxxpgU52CMMcYYY4wxxhiT4hyMMcYYY4wxxhhjUpyDMcYYY4wxxhhjTIrz8wOX2AQQjDHGGGOMMcYYY9oqP/Uo9QnGGGOMMcYYY4wxbZ2fegRjjDHGGGOMMcaYVOPnB0JsgjHGGGOM...
2018-03-14 08:54:46:006 - info: [HTTP] <-- GET /wd/hub/session/627002a4-c935-4de7-ba36-272326ad3de8/screenshot 200 1803 ms - 2165566 
2018-03-14 08:54:48:321 - info: [HTTP] --> GET /wd/hub/session/627002a4-c935-4de7-ba36-272326ad3de8/screenshot {}
2018-03-14 08:54:48:322 - info: [debug] [MJSONWP] Calling AppiumDriver.getScreenshot() with args: ["627002a4-c935-4de7-ba36-272326ad3de8"]
2018-03-14 08:54:48:323 - info: [debug] [ADB] Device API level: 24
2018-03-14 08:54:49:787 - info: [debug] [MJSONWP] Responding to client with driver.getScreenshot() result: "iVBORw0KGgoAAAANSUhEUgAABDgAAAeACAYAAAArYecKAACAAElEQVR4AezBbZOe533f9+/vfxznde0ugAWwIECaoChajnUT23FUdxLJVlNNXE9dN5m4bt26M30B7Yw707fgN9CZvoM+iqfTdpxxHmXs3NTyXeJM69iyJEsmLZImKZK43cXeXed5/H/FBWjJBXAtCCwWwBI4Px/9L2urZjR6asSe/+3KdZD4tV/7NT7/+c+zyG/8xm+w30/91Jd59dXXsBObW2azGVtbW+z2jd0GNrcIE4BtMpM2NFo2MKBCxBIQtGEggbTYkykyucU2c2YfCxB3cw6Yg5mbLDCYRcxHbNKNPcIIA+YOBtvYiUkyk2yJbeZKKUQIGSICKZAaMNDaQGuNYYDWxHQ6oesm7CdACBB2ssdO5iTuIvYIIw5mJ3ZyMPMRD5A92QaG1qjdlNot8TGT2bBNZtK3gczENiUKpU6QhLhJAsRBDKQb2NwtPWB6WksykxoTSlQkgUSyR+zJbEBym1nETjIbdmKDJEop2CYzMSIRkgmJ28SDsMHcSQSZM8CAEWIuPeA2AEKqTE+9yokLP8kisogMZLFIRtLU2M82rTU87MCwTjAQEdgiE2qtdF2HywlcV7gfSSxSgWozl25sb11hd7YBNumCc0rXLbNy4gQlOuwKiDkFhDgUaQBmbG1tsrOzw21CElIgVUrtmE6nhCp2BcScIyHMbaKFSERmkjYNYxaL2XVido2+72nNTE+eZ7J8GkkEorgQZiGFCSV93zPrd9nducqs32QuJEopRIiFbJRJ3/cMw4AiiAhqrURMcZwgyoRSKiWCiACJORFIQYSQAluAeFStDezubjEMM9JGNKQGMnMhE2Eyk9YGdnY2GdoutVYm3RLTpVVqnSIJcSd7gunYTwhJKEQokITEPewAxFHKnPH+W7/D7s5l7IHRaDQajZ4s8eDEJzOLiYNIQWU0eqrMnhdOnODDzU1aaxyklEJrjT1//ud/yje/+afYZnT0trYY...
2018-03-14 08:54:49:794 - info: [HTTP] <-- GET /wd/hub/session/627002a4-c935-4de7-ba36-272326ad3de8/screenshot 200 1473 ms - 1329018 
2018-03-14 08:54:50:147 - info: [HTTP] --> DELETE /wd/hub/session/627002a4-c935-4de7-ba36-272326ad3de8 {}
2018-03-14 08:54:50:147 - info: [debug] [MJSONWP] Calling AppiumDriver.deleteSession() with args: ["627002a4-c935-4de7-ba36-272326ad3de8"]
2018-03-14 08:54:50:148 - info: [debug] [BaseDriver] Event 'quitSessionRequested' logged at 1521017690147 (09:54:50 GMT+0100 (CET))
2018-03-14 08:54:50:148 - info: [Appium] Removing session 627002a4-c935-4de7-ba36-272326ad3de8 from our master session list
2018-03-14 08:54:50:149 - info: [debug] [AndroidDriver] Shutting down Android driver
2018-03-14 08:54:50:150 - info: [debug] [ADB] Getting connected devices...
2018-03-14 08:54:50:162 - info: [debug] [ADB] 2 device(s) connected
2018-03-14 08:54:50:163 - info: [debug] [ADB] Running '/home/trixo/Android/Sdk/platform-tools/adb' with args: ["-P",5037,"-s","localhost:7409","shell","am","force-stop","com.gorro.nothing"]
2018-03-14 08:54:50:851 - info: [debug] [ADB] Pressing the HOME button
2018-03-14 08:54:50:852 - info: [debug] [ADB] Getting connected devices...
2018-03-14 08:54:50:866 - info: [debug] [ADB] 2 device(s) connected
2018-03-14 08:54:50:866 - info: [debug] [ADB] Running '/home/trixo/Android/Sdk/platform-tools/adb' with args: ["-P",5037,"-s","localhost:7409","shell","input","keyevent",3]
2018-03-14 08:54:51:588 - info: [debug] [AndroidBootstrap] Sending command to android: {"cmd":"shutdown"}
2018-03-14 08:54:51:596 - info: [debug] [AndroidBootstrap] Received command result from bootstrap
2018-03-14 08:54:51:598 - info: [debug] [UiAutomator] Shutting down UiAutomator
2018-03-14 08:54:51:598 - info: [debug] [UiAutomator] Moving to state 'stopping'
2018-03-14 08:54:51:600 - info: [debug] [AndroidBootstrap] [BOOTSTRAP LOG] [debug] Got data from client: {"cmd":"shutdown"}
2018-03-14 08:54:51:600 - info: [debug] [AndroidBootstrap] [BOOTSTRAP LOG] [debug] Got command of type SHUTDOWN
2018-03-14 08:54:51:600 - info: [debug] [AndroidBootstrap] [BOOTSTRAP LOG] [debug] Returning result: {"status":0,"value":"OK, shutting down"}
2018-03-14 08:54:51:600 - info: [debug] [AndroidBootstrap] [BOOTSTRAP LOG] [debug] Closed client connection
2018-03-14 08:54:51:600 - info: [debug] [AndroidBootstrap] [UIAUTO STDOUT] INSTRUMENTATION_STATUS: numtests=1
2018-03-14 08:54:51:601 - info: [debug] [AndroidBootstrap] [UIAUTO STDOUT] INSTRUMENTATION_STATUS: stream=.
2018-03-14 08:54:51:601 - info: [debug] [AndroidBootstrap] [UIAUTO STDOUT] INSTRUMENTATION_STATUS: id=UiAutomatorTestRunner
2018-03-14 08:54:51:601 - info: [debug] [AndroidBootstrap] [UIAUTO STDOUT] INSTRUMENTATION_STATUS: test=testRunServer
2018-03-14 08:54:51:601 - info: [debug] [AndroidBootstrap] [UIAUTO STDOUT] INSTRUMENTATION_STATUS: class=io.appium.android.bootstrap.Bootstrap
2018-03-14 08:54:51:601 - info: [debug] [AndroidBootstrap] [UIAUTO STDOUT] INSTRUMENTATION_STATUS: current=1
2018-03-14 08:54:51:601 - info: [debug] [AndroidBootstrap] [UIAUTO STDOUT] INSTRUMENTATION_STATUS_CODE: 0
2018-03-14 08:54:51:603 - info: [debug] [UiAutomator] UiAutomator shut down normally
2018-03-14 08:54:51:603 - info: [debug] [UiAutomator] Moving to state 'stopped'
2018-03-14 08:54:51:604 - info: [debug] [ADB] Attempting to kill all uiautomator processes
2018-03-14 08:54:51:604 - info: [debug] [ADB] Getting all processes with uiautomator
2018-03-14 08:54:51:604 - info: [debug] [ADB] Getting connected devices...
2018-03-14 08:54:51:615 - info: [debug] [ADB] 2 device(s) connected
2018-03-14 08:54:51:615 - info: [debug] [ADB] Running '/home/trixo/Android/Sdk/platform-tools/adb' with args: ["-P",5037,"-s","localhost:7409","shell","ps"]
2018-03-14 08:54:51:706 - info: [ADB] No uiautomator process found to kill, continuing...
2018-03-14 08:54:51:706 - info: [debug] [UiAutomator] Moving to state 'stopped'
2018-03-14 08:54:51:707 - info: [debug] [Logcat] Stopping logcat capture
2018-03-14 08:54:51:709 - info: [debug] [ADB] Getting connected devices...
2018-03-14 08:54:51:723 - info: [debug] [ADB] 2 device(s) connected
2018-03-14 08:54:51:723 - info: [debug] [ADB] Running '/home/trixo/Android/Sdk/platform-tools/adb' with args: ["-P",5037,"-s","localhost:7409","shell","am","force-stop","io.appium.unlock"]
2018-03-14 08:54:52:346 - info: [debug] [AndroidDriver] Not cleaning generated files. Add `clearSystemFiles` capability if wanted.
2018-03-14 08:54:52:347 - info: [debug] [BaseDriver] Event 'quitSessionFinished' logged at 1521017692346 (09:54:52 GMT+0100 (CET))
2018-03-14 08:54:52:347 - info: [debug] [MJSONWP] Received response: null
2018-03-14 08:54:52:348 - info: [debug] [MJSONWP] But deleting session, so not returning
2018-03-14 08:54:52:348 - info: [debug] [MJSONWP] Responding to client with driver.deleteSession() result: null
2018-03-14 08:54:52:350 - info: [HTTP] <-- DELETE /wd/hub/session/627002a4-c935-4de7-ba36-272326ad3de8 200 2202 ms - 76