[info] [Appium] Welcome to Appium v1.9.0
[info] [Appium] Appium REST http interface listener started on 0.0.0.0:4723[info] [HTTP] --> POST /wd/hub/session
[info] [HTTP] {"desiredCapabilities":{"appPackage":"com.imggaming.dicemobile","appActivity":"com.imggaming.dicemobile.MainActivity","app":"/Users/Lewina/Downloads/app-release-sandbox.apk","appium-version":"1.9.0","automationName":"uiautomator2","name":"Dice test Tue Sep 11 21:31:00 BST 2018","autoGrantPermissions":true,"platformName":"android","deviceName":"emulator-5554"},"requiredCapabilities":{},"capabilities":{"desiredCapabilities":{"appPackage":"com.imggaming.dicemobile","appActivity":"com.imggaming.dicemobile.MainActivity","app":"/Users/Lewina/Downloads/app-release-sandbox.apk","appium-version":"1.9.0","automationName":"uiautomator2","name":"Dice test Tue Sep 11 21:31:00 BST 2018","autoGrantPermissions":true,"platformName":"android","deviceName":"emulator-5554"},"requiredCapabilities":{},"alwaysMatch":{"platformName":"android"},"firstMatch":[]}}
[debug] [MJSONWP] Calling AppiumDriver.createSession() with args: [{"appPackage":"com.imggaming.dicemobile","appActivity":"com.imggaming.dicemobile.MainActivity","app":"/Users/Lewina/Downloads/app-release-sandbox.apk","appium-version":"1.9.0","automationName":"uiautomator2","name":"Dice test Tue Sep 11 21:31:00 BST 2018","autoGrantPermissions":true,"platformName":"android","deviceName":"emulator-5554"},{},{"desiredCapabilities":{"appPackage":"com.imggaming.dicemobile","appActivity":"com.imggaming.dicemobile.MainActivity","app":"/Users/Lewina/Downloads/app-release-sandbox.apk","appium-version":"1.9.0","automationName":"uiautomator2","name":"Dice test Tue Sep 11 21:31:00 BST 2018","autoGrantPermissions":true,"platformName":"android","deviceName":"emulator-5554"},"requiredCapabilities":{},"alwaysMatch":{"platformName":"android"},"firstMatch":[]}]
[debug] [BaseDriver] Event 'newSessionRequested' logged at 1536697861158 (21:31:01 GMT+0100 (BST))
[info] [Appium] Could not parse W3C capabilities: 'deviceName' can't be blank
[info] [Appium] Trying to fix W3C capabilities by merging them with JSONWP caps
[warn] [BaseDriver] The capabilities ["appPackage","appActivity","app","appium-version","automationName","name","autoGrantPermissions","deviceName"] are not standard capabilities and should have an extension prefix
[info] [Appium] Creating new AndroidUiautomator2Driver (v1.16.0) session
[info] [Appium] Capabilities:
[info] [Appium]   platformName: android
[info] [Appium]   appPackage: com.imggaming.dicemobile
[info] [Appium]   appActivity: com.imggaming.dicemobile.MainActivity
[info] [Appium]   app: /Users/Lewina/Downloads/app-release-sandbox.apk
[info] [Appium]   appium-version: 1.9.0
[info] [Appium]   automationName: uiautomator2
[info] [Appium]   name: Dice test Tue Sep 11 21:31:00 BST 2018
[info] [Appium]   autoGrantPermissions: true
[info] [Appium]   deviceName: emulator-5554
[debug] [BaseDriver] W3C capabilities {"alwaysMatch":{"platformNa... and MJSONWP desired capabilities {"appPackage":"com.imggamin... were provided
[debug] [BaseDriver] Creating session with W3C capabilities: {"alwaysMatch":{"platformNa...
[warn] [BaseDriver] The following capabilities were provided, but are not recognized by appium: appium-version, name.
[info] [BaseDriver] Session created with session id: 41f474e1-80c6-4732-b34a-a6d695c86ce6[info] [AndroidDriver] Java version is: 1.8.0_152
[info] [ADB] Checking whether adb is present
[info] [ADB] Found 2 'build-tools' folders under '/Users/Lewina/Library/Android/sdk' (newest first):
[info] [ADB]     /Users/Lewina/Library/Android/sdk/build-tools/28.0.2
[info] [ADB]     /Users/Lewina/Library/Android/sdk/build-tools/27.0.3
[info] [ADB] Using adb from /Users/Lewina/Library/Android/sdk/platform-tools/adb
[info] [AndroidDriver] Retrieving device list
[debug] [ADB] Trying to find a connected android device
[debug] [ADB] Getting connected devices...
[debug] [ADB] 1 device(s) connected
[info] [AndroidDriver] Using device: emulator-5554
[debug] [ADB] Setting device id to emulator-5554
[debug] [ADB] Running '/Users/Lewina/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 shell getprop ro.build.version.sdk'
[debug] [ADB] Current device property 'ro.build.version.sdk': 28
[debug] [ADB] Device API level: 28
[info] [ADB] Getting device platform version
[debug] [ADB] Running '/Users/Lewina/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 shell getprop ro.build.version.release'
[debug] [ADB] Current device property 'ro.build.version.release': 9
[debug] [ADB] Running '/Users/Lewina/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 shell wm size'
[debug] [ADB] Running '/Users/Lewina/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 shell wm density'[debug] [ADB] Running '/Users/Lewina/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 shell getprop ro.product.model'
[debug] [ADB] Current device property 'ro.product.model': Android SDK built for x86
[debug] [ADB] Running '/Users/Lewina/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 shell getprop ro.product.manufacturer'
[debug] [ADB] Current device property 'ro.product.manufacturer': Google
[debug] [ADB] Device API level: 28
[debug] [ADB] Running '/Users/Lewina/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 shell am force-stop io.appium.uiautomator2.server'
[debug] [ADB] Checking app cert for /Applications/Appium.app/Contents/Resources/app/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-v1.15.1.apk
[info] [ADB] Using apksigner from /Users/Lewina/Library/Android/sdk/build-tools/28.0.2/apksigner
[debug] [ADB] Starting '/Users/Lewina/Library/Android/sdk/build-tools/28.0.2/apksigner' with args '["verify","--print-certs","/Applications/Appium.app/Contents/Resources/app/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-v1.15.1.apk"]'[debug] [ADB] apksigner stdout: Signer #1 certificate DN: EMAILADDRESS=android@android.com, CN=Android, OU=Android, O=Android, L=Mountain View, ST=California, C=US
[debug] [ADB] Signer #1 certificate SHA-256 digest: a40da80a59d170caa950cf15c18c454d47a39b26989d8b640ecd745ba71bf5dc
[debug] [ADB] Signer #1 certificate SHA-1 digest: 61ed377e85d386a8dfee6b864bd85b0bfaa5af81
[debug] [ADB] Signer #1 certificate MD5 digest: e89b158e4bcf988ebd09eb83f5378e87
[debug] [ADB] WARNING: META-INF/INDEX.LIST not protected by signature. Unauthorized modifications to this JAR entry will not be detected. Delete or move the entry outside of META-INF/.
[debug] [ADB] WARNING: META-INF/io.netty.versions.properties not protected by signature. Unauthorized modifications to this JAR entry will not be detected. Delete or move the entry outside of META-INF/.
[debug] [ADB] 
[debug] [ADB] '/Applications/Appium.app/Contents/Resources/app/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-v1.15.1.apk' is already signed.
[debug] [ADB] Checking app cert for /Applications/Appium.app/Contents/Resources/app/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-debug-androidTest.apk
[debug] [ADB] Starting '/Users/Lewina/Library/Android/sdk/build-tools/28.0.2/apksigner' with args '["verify","--print-certs","/Applications/Appium.app/Contents/Resources/app/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-debug-androidTest.apk"]'[debug] [ADB] apksigner stdout: Signer #1 certificate DN: EMAILADDRESS=android@android.com, CN=Android, OU=Android, O=Android, L=Mountain View, ST=California, C=US
[debug] [ADB] Signer #1 certificate SHA-256 digest: a40da80a59d170caa950cf15c18c454d47a39b26989d8b640ecd745ba71bf5dc
[debug] [ADB] Signer #1 certificate SHA-1 digest: 61ed377e85d386a8dfee6b864bd85b0bfaa5af81
[debug] [ADB] Signer #1 certificate MD5 digest: e89b158e4bcf988ebd09eb83f5378e87
[debug] [ADB] 
[debug] [ADB] '/Applications/Appium.app/Contents/Resources/app/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-debug-androidTest.apk' is already signed.
[debug] [ADB] Getting install status for io.appium.uiautomator2.server
[debug] [ADB] Running '/Users/Lewina/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 shell pm list packages io.appium.uiautomator2.server'[debug] [ADB] App is installed
[debug] [ADB] Getting package info for 'io.appium.uiautomator2.server'
[debug] [ADB] Running '/Users/Lewina/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 shell dumpsys package io.appium.uiautomator2.server'
[info] [ADB] Checking whether aapt is present
[info] [ADB] Using aapt from /Users/Lewina/Library/Android/sdk/build-tools/28.0.2/aapt
[debug] [ADB] The installed 'io.appium.uiautomator2.server' package does not require upgrade ('1.15.1' >= '1.15.1')
[debug] [ADB] Getting install status for io.appium.uiautomator2.server.test
[debug] [ADB] Running '/Users/Lewina/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 shell pm list packages io.appium.uiautomator2.server.test'
[debug] [ADB] App is installed
[debug] [ADB] Getting package info for 'io.appium.uiautomator2.server.test'
[debug] [ADB] Running '/Users/Lewina/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 shell dumpsys package io.appium.uiautomator2.server.test'
[warn] [ADB] Cannot read version codes of '/Applications/Appium.app/Contents/Resources/app/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-debug-androidTest.apk' and/or 'io.appium.uiautomator2.server.test'
[warn] [ADB] Cannot read version names of '/Applications/Appium.app/Contents/Resources/app/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-debug-androidTest.apk' and/or 'io.appium.uiautomator2.server.test'. Assuming correct app version is already installed
[debug] [UiAutomator2] Waiting up to 20000ms for instrumentation 'io.appium.uiautomator2.server.test/android.support.test.runner.AndroidJUnitRunner' to be available
[debug] [ADB] Running '/Users/Lewina/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 shell pm list instrumentation'
[debug] [UiAutomator2] Instrumentation 'io.appium.uiautomator2.server.test/android.support.test.runner.AndroidJUnitRunner' available
[debug] [ADB] Running '/Users/Lewina/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 wait-for-device'
[debug] [ADB] Running '/Users/Lewina/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 shell echo ping'
[debug] [AndroidDriver] Pushing settings apk to device...
[debug] [ADB] Getting install status for io.appium.settings
[debug] [ADB] Running '/Users/Lewina/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 shell pm list packages io.appium.settings'
[debug] [ADB] App is installed
[debug] [ADB] Getting package info for 'io.appium.settings'
[debug] [ADB] Running '/Users/Lewina/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 shell dumpsys package io.appium.settings'[debug] [ADB] The installed 'io.appium.settings' package does not require upgrade ('2.3.0' >= '2.3.0')
[debug] [ADB] Running '/Users/Lewina/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 shell ps'
[debug] [ADB] Device API level: 28
[debug] [ADB] Running '/Users/Lewina/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 shell am start -W -n io.appium.settings/.Settings -a android.intent.action.MAIN -c android.intent.category.LAUNCHER -f 0x10200000'[debug] [ADB] Device API level: 28
[debug] [ADB] Running '/Users/Lewina/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 shell appops set io.appium.settings android\:mock_location allow'
[warn] [AndroidDriver] setDeviceLanguageCountry requires language or country.
[warn] [AndroidDriver] Got language: 'undefined' and country: 'undefined'
[debug] [Logcat] Starting logcat capture[debug] [AndroidDriver] Pushing unlock helper app to device...
[debug] [ADB] Getting install status for io.appium.unlock
[debug] [ADB] Running '/Users/Lewina/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 shell pm list packages io.appium.unlock'
[debug] [ADB] App is installed
[debug] [ADB] Getting package info for 'io.appium.unlock'
[debug] [ADB] Running '/Users/Lewina/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 shell dumpsys package io.appium.unlock'
[debug] [ADB] The installed 'io.appium.unlock' package does not require upgrade ('2.0.0' >= '2.0.0')
[debug] [UiAutomator2] Forwarding UiAutomator2 Server port 6790 to 8200
[debug] [ADB] Forwarding system: 8200 to device: 6790
[debug] [ADB] Running '/Users/Lewina/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 forward tcp\:8200 tcp\:6790'[debug] [ADB] Running '/Users/Lewina/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 shell rm -rf /data/local/tmp/strings.json'
[debug] [AndroidDriver] Extracting strings from apk
[debug] [AndroidDriver] /Users/Lewina/Downloads/app-release-sandbox.apk
[debug] [AndroidDriver] undefined
[debug] [AndroidDriver] /var/folders/2n/h_bxx4bx57xb3q5fl92lb01c0000gr/T/com.imggaming.dicemobile
[debug] [ADB] Extracting strings for language: default
[info] [ADB] Successfully extracted 194 strings from '/Users/Lewina/Downloads/app-release-sandbox.apk' resources for '(default)' configuration[debug] [ADB] Running '/Users/Lewina/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 push /var/folders/2n/h_bxx4bx57xb3q5fl92lb01c0000gr/T/com.imggaming.dicemobile/strings.json /data/local/tmp'
[debug] [ADB] Checking app cert for /Users/Lewina/Downloads/app-release-sandbox.apk
[debug] [ADB] Starting '/Users/Lewina/Library/Android/sdk/build-tools/28.0.2/apksigner' with args '["verify","--print-certs","/Users/Lewina/Downloads/app-release-sandbox.apk"]'[debug] [ADB] apksigner stdout: Signer #1 certificate DN: EMAILADDRESS=android@android.com, CN=Android, OU=Android, O=Android, L=Mountain View, ST=California, C=US
[debug] [ADB] Signer #1 certificate SHA-256 digest: a40da80a59d170caa950cf15c18c454d47a39b26989d8b640ecd745ba71bf5dc
[debug] [ADB] Signer #1 certificate SHA-1 digest: 61ed377e85d386a8dfee6b864bd85b0bfaa5af81
[debug] [ADB] Signer #1 certificate MD5 digest: e89b158e4bcf988ebd09eb83f5378e87
[debug] [ADB] WARNING: META-INF/android.arch.core_runtime.version not protected by signature. Unauthorized modifications to this JAR entry will not be detected. Delete or move the entry outside of META-INF/.
[debug] [ADB] WARNING: META-INF/android.arch.lifecycle_livedata-core.version not protected by signature. Unauthorized modifications to this JAR entry will not be detected. Delete or move the entry outside of META-INF/.
[debug] [ADB] WARNING: META-INF/android.arch.lifecycle_runtime.version not protected by signature. Unauthorized modifications to this JAR entry will not be detected. Delete or move the entry outside of META-INF/.
[debug] [ADB] WARNING: META-INF/android.arch.lifecycle_viewmodel.version not protected by signature. Unauthorized modifications to this JAR entry will not be detected. Delete or move the entry outside of META-INF/.
[debug] [ADB] WARNING: META-INF/com.android.support_animated-vector-drawable.version not protected by signature. Unauthorized modifications to this JAR entry will not be detected. Delete or move the entry outside of META-INF/.
[debug] [ADB] WARNING: META-INF/com.android.support_appcompat-v7.version not protected by signature. Unauthorized modifications to this JAR entry will not be detected. Delete or move the entry outside of META-INF/.
[debug] [ADB] WARNING: META-INF/com.android.support_mediarouter-v7.version not protected by signature. Unauthorized modifications to this JAR entry will not be detected. Delete or move the entry outside of META-INF/.
[debug] [ADB] WARNING: META-INF/com.android.support_palette-v7.version not protected by signature. Unauthorized modifications to this JAR entry will not be detected. Delete or move the entry outside of META-INF/.
[debug] [ADB] WARNING: META-INF/com.android.support_support-compat.version not protected by signature. Unauthorized modifications to this JAR entry will not be detected. Delete or move the entry outside of META-INF/.
[debug] [ADB] WARNING: META-INF/com.android.support_support-core-ui.version not protected by signature. Unauthorized modifications to this JAR entry will not be detected. Delete or move the entry outside of META-INF/.
[debug] [ADB] WARNING: META-INF/com.android.support_support-core-utils.version not protected by signature. Unauthorized modifications to this JAR entry will not be detected. Delete or move the entry outside of META-INF/.
[debug] [ADB] WARNING: META-INF/com.android.support_support-fragment.version not protected by signature. Unauthorized modifications to this JAR entry will not be detected. Delete or move the entry outside of META-INF/.
[debug] [ADB] WARNING: META-INF/com.android.support_support-media-compat.version not protected by signature. Unauthorized modifications to this JAR entry will not be detected. Delete or move the entry outside of META-INF/.
[debug] [ADB] WARNING: META-INF/com.android.support_support-v4.version not protected by signature. Unauthorized modifications to this JAR entry will not be detected. Delete or move the entry outside of META-INF/.
[debug] [ADB] WARNING: META-INF/com.android.support_support-vector-drawable.version not protected by signature. Unauthorized modifications to this JAR entry will not be detected. Delete or move the entry outside of META-INF/.
[debug] [ADB] WARNING: META-INF/services/com.fasterxml.jackson.core.JsonFactory not protected by signature. Unauthorized modifications to this JAR entry will not be detected. Delete or move the entry outside of META-INF/.
[debug] [ADB] WARNING: META-INF/services/javax.servlet.ServletContainerInitializer not protected by signature. Unauthorized modifications to this JAR entry will not be detected. Delete or move the entry outside of META-INF/.
[debug] [ADB] 
[debug] [ADB] '/Users/Lewina/Downloads/app-release-sandbox.apk' is already signed.
[debug] [ADB] Getting install status for com.imggaming.dicemobile
[debug] [ADB] Running '/Users/Lewina/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 shell pm list packages com.imggaming.dicemobile'[debug] [ADB] App is installed
[debug] [ADB] Getting install status for com.imggaming.dicemobile
[debug] [ADB] Running '/Users/Lewina/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 shell pm list packages com.imggaming.dicemobile'
[debug] [ADB] App is installed
[debug] [ADB] Getting package info for 'com.imggaming.dicemobile'
[debug] [ADB] Running '/Users/Lewina/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 shell dumpsys package com.imggaming.dicemobile'
[debug] [ADB] The installed 'com.imggaming.dicemobile' package does not require upgrade ('1.1.3' >= '1.1.3')
[info] [AndroidDriver] Performing fast reset on 'com.imggaming.dicemobile'
[debug] [ADB] Getting install status for com.imggaming.dicemobile
[debug] [ADB] Running '/Users/Lewina/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 shell pm list packages com.imggaming.dicemobile'
[debug] [ADB] App is installed
[debug] [ADB] Running '/Users/Lewina/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 shell am force-stop com.imggaming.dicemobile'
[debug] [ADB] Running '/Users/Lewina/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 shell pm clear com.imggaming.dicemobile'[debug] [ADB] Device API level: 28
[debug] [ADB] Running '/Users/Lewina/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 shell dumpsys package com.imggaming.dicemobile'
[debug] [ADB] Running '/Users/Lewina/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 shell pm dump com.imggaming.dicemobile'[debug] [ADB] Got the following command chunks to execute: pm,grant,com.imggaming.dicemobile,android.permission.READ_APP_BADGE,;,pm,grant,com.imggaming.dicemobile,android.permission.WRITE_EXTERNAL_STORAGE,;,pm,grant,com.imggaming.dicemobile,android.permission.READ_PHONE_STATE,;,pm,grant,com.imggaming.dicemobile,android.permission.READ_EXTERNAL_STORAGE,;
[debug] [ADB] Running '/Users/Lewina/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 shell pm grant com.imggaming.dicemobile android.permission.READ_APP_BADGE \; pm grant com.imggaming.dicemobile android.permission.WRITE_EXTERNAL_STORAGE \; pm grant com.imggaming.dicemobile android.permission.READ_PHONE_STATE \; pm grant com.imggaming.dicemobile android.permission.READ_EXTERNAL_STORAGE \;'
[debug] [AndroidDriver] Performed fast reset on the installed 'com.imggaming.dicemobile' application (stop and clear)
[debug] [ADB] Getting all processes with uiautomator
[debug] [ADB] Running '/Users/Lewina/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 shell ps'
[debug] [ADB] Running '/Users/Lewina/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 shell am force-stop io.appium.uiautomator2.server'[info] [UiAutomator2] Starting uiautomator2 server 1.15.1
[info] [UiAutomator2] Using UIAutomator2 server from '/Applications/Appium.app/Contents/Resources/app/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-v1.15.1.apk' and test from '/Applications/Appium.app/Contents/Resources/app/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-debug-androidTest.apk'
[info] [UiAutomator2] Running command: 'adb -s emulator-5554 shell am instrument -w io.appium.uiautomator2.server.test/android.support.test.runner.AndroidJUnitRunner'
[info] [UiAutomator2] Waiting up to 20000ms for UiAutomator2 to be online...
[debug] [JSONWP Proxy] Matched '/status' to command name 'getStatus'
[debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8200/wd/hub/status] with no body[debug] [JSONWP Proxy] Matched '/status' to command name 'getStatus'
[debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8200/wd/hub/status] with no body[debug] [JSONWP Proxy] Matched '/status' to command name 'getStatus'
[debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8200/wd/hub/status] with no body[debug] [JSONWP Proxy] Matched '/status' to command name 'getStatus'
[debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8200/wd/hub/status] with no body
[debug] [JSONWP Proxy] Got response with status 200: "{\"sessionId\":\"SESSIONID\",\"status\":0,\"value\":\"Status Invoked\"}"
[debug] [JSONWP Proxy] Matched '/session' to command name 'createSession'
[debug] [JSONWP Proxy] Proxying [POST /session] to [POST http://localhost:8200/wd/hub/session] with body: {"desiredCapabilities":{"platform":"LINUX","webStorageEnabled":false,"takesScreenshot":true,"javascriptEnabled":true,"databaseEnabled":false,"networkConnectionEnabled":true,"locationContextEnabled":false,"warnings":{},"desired":{"platformName":"android","appPackage":"com.imggaming.dicemobile","appActivity":"com.imggaming.dicemobile.MainActivity","app":"/Users/Lewina/Downloads/app-release-sandbox.apk","appium-version":"1.9.0","automationName":"uiautomator2","name":"Dice test Tue Sep 11 21:31:00 BST 2018","autoGrantPermissions":true,"deviceName":"emulator-5554"},"platformName":"android","appPackage":"com.imggaming.dicemobile","appActivity":"com.imggaming.dicemobile.MainActivity","app":"/Users/Lewina/Downloads/app-release-sandbox.apk","appium-version":"1.9.0","automationName":"uiautomator2","name":"Dice test Tue Sep 11 21:31:00 BST 2018","autoGrantPermissions":true,"deviceName":"emulator-5554","deviceUDID":"emulator-5554","platformVersion":"9","deviceScreenSize":"1440x2560","deviceScreenDensity":560,"deviceMo...
[debug] [JSONWP Proxy] Got response with status 200: {"sessionId":"6ce2dbdc-fa4b-4f22-91af-1fe6d5b1e869","status":0,"value":"Created Session"}
[debug] [ADB] Running '/Users/Lewina/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 shell dumpsys window'
[info] [AndroidDriver] Screen already unlocked, doing nothing
[info] [UiAutomator2] UiAutomator2 did not start the activity we were waiting for, 'com.imggaming.dicemobile/com.imggaming.dicemobile.MainActivity'. Starting it ourselves
[debug] [ADB] Device API level: 28
[debug] [ADB] Running '/Users/Lewina/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 shell am start -W -n com.imggaming.dicemobile/com.imggaming.dicemobile.MainActivity -S'[debug] [JSONWP Proxy] Proxying [GET /appium/device/pixel_ratio] to [GET http://localhost:8200/wd/hub/session/6ce2dbdc-fa4b-4f22-91af-1fe6d5b1e869/appium/device/pixel_ratio] with body: {}
[debug] [JSONWP Proxy] Got response with status 200: "{\"sessionId\":\"6ce2dbdc-fa4b-4f22-91af-1fe6d5b1e869\",\"status\":0,\"value\":3.5}"
[debug] [JSONWP Proxy] Matched '/appium/device/system_bars' to command name 'getSystemBars'
[debug] [JSONWP Proxy] Proxying [GET /appium/device/system_bars] to [GET http://localhost:8200/wd/hub/session/6ce2dbdc-fa4b-4f22-91af-1fe6d5b1e869/appium/device/system_bars] with body: {}
[debug] [JSONWP Proxy] Got response with status 200: "{\"sessionId\":\"6ce2dbdc-fa4b-4f22-91af-1fe6d5b1e869\",\"status\":0,\"value\":{\"statusBar\":84}}"
[debug] [JSONWP Proxy] Matched '/window/current/size' to command name 'getWindowSize'
[debug] [JSONWP Proxy] Proxying [GET /window/current/size] to [GET http://localhost:8200/wd/hub/session/6ce2dbdc-fa4b-4f22-91af-1fe6d5b1e869/window/current/size] with body: {}
[debug] [JSONWP Proxy] Got response with status 200: "{\"sessionId\":\"6ce2dbdc-fa4b-4f22-91af-1fe6d5b1e869\",\"status\":0,\"value\":{\"height\":2392,\"width\":1440}}"
[info] [Appium] New AndroidUiautomator2Driver session created successfully, session 41f474e1-80c6-4732-b34a-a6d695c86ce6 added to master session list
[debug] [BaseDriver] Event 'newSessionStarted' logged at 1536697871984 (21:31:11 GMT+0100 (BST))
[debug] [W3C] Responding to client with driver.createSession() result: {"capabilities":{"platform":"LINUX","webStorageEnabled":false,"takesScreenshot":true,"javascriptEnabled":true,"databaseEnabled":false,"networkConnectionEnabled":true,"locationContextEnabled":false,"warnings":{},"desired":{"platformName":"android","appPackage":"com.imggaming.dicemobile","appActivity":"com.imggaming.dicemobile.MainActivity","app":"/Users/Lewina/Downloads/app-release-sandbox.apk","appium-version":"1.9.0","automationName":"uiautomator2","name":"Dice test Tue Sep 11 21:31:00 BST 2018","autoGrantPermissions":true,"deviceName":"emulator-5554"},"platformName":"android","appPackage":"com.imggaming.dicemobile","appActivity":"com.imggaming.dicemobile.MainActivity","app":"/Users/Lewina/Downloads/app-release-sandbox.apk","appium-version":"1.9.0","automationName":"uiautomator2","name":"Dice test Tue Sep 11 21:31:00 BST 2018","autoGrantPermissions":true,"deviceName":"emulator-5554","deviceUDID":"emulator-5554","platformVersion":"9","deviceScreenSize":"1440x2560","deviceScreenDensity":560,"deviceModel":"A...
[info] [HTTP] <-- POST /wd/hub/session 200 10832 ms - 1257
[info] [HTTP] [info] [HTTP] --> POST /wd/hub/session/41f474e1-80c6-4732-b34a-a6d695c86ce6/timeouts
[info] [HTTP] {"implicit":30000}
[debug] [W3C] Calling AppiumDriver.timeouts() with args: [null,null,null,null,30000,"41f474e1-80c6-4732-b34a-a6d695c86ce6"]
[debug] [BaseDriver] W3C timeout argument: {"implicit":30000}}
[debug] [BaseDriver] Set implicit wait to 30000ms
[debug] [W3C] Responding to client with driver.timeouts() result: null
[info] [HTTP] <-- POST /wd/hub/session/41f474e1-80c6-4732-b34a-a6d695c86ce6/timeouts 200 13 ms - 14
[info] [HTTP] 
[info] [HTTP] --> POST /wd/hub/session/41f474e1-80c6-4732-b34a-a6d695c86ce6/element
[info] [HTTP] {"value":"id","using":"accessibility id"}
[debug] [W3C] Calling AppiumDriver.findElement() with args: ["accessibility id","id","41f474e1-80c6-4732-b34a-a6d695c86ce6"]
[debug] [BaseDriver] Valid locator strategies for this request: xpath, id, class name, accessibility id, -android uiautomator
[debug] [BaseDriver] Waiting up to 30000 ms for condition
[debug] [JSONWP Proxy] Matched '/element' to command name 'findElement'
[debug] [JSONWP Proxy] Proxying [POST /element] to [POST http://localhost:8200/wd/hub/session/6ce2dbdc-fa4b-4f22-91af-1fe6d5b1e869/element] with body: {"strategy":"accessibility id","selector":"id","context":"","multiple":false}
[debug] [MJSONWP] Matched JSONWP error code 7 to NoSuchElementError
[debug] [BaseDriver] Waited for 85 ms so far[debug] [JSONWP Proxy] Matched '/element' to command name 'findElement'
[debug] [JSONWP Proxy] Proxying [POST /element] to [POST http://localhost:8200/wd/hub/session/6ce2dbdc-fa4b-4f22-91af-1fe6d5b1e869/element] with body: {"strategy":"accessibility id","selector":"id","context":"","multiple":false}
[debug] [MJSONWP] Matched JSONWP error code 7 to NoSuchElementError
[debug] [BaseDriver] Waited for 611 ms so far[debug] [JSONWP Proxy] Matched '/element' to command name 'findElement'
[debug] [JSONWP Proxy] Proxying [POST /element] to [POST http://localhost:8200/wd/hub/session/6ce2dbdc-fa4b-4f22-91af-1fe6d5b1e869/element] with body: {"strategy":"accessibility id","selector":"id","context":"","multiple":false}[debug] [MJSONWP] Matched JSONWP error code 7 to NoSuchElementError
[debug] [BaseDriver] Waited for 1135 ms so far[debug] [JSONWP Proxy] Matched '/element' to command name 'findElement'
[debug] [JSONWP Proxy] Proxying [POST /element] to [POST http://localhost:8200/wd/hub/session/6ce2dbdc-fa4b-4f22-91af-1fe6d5b1e869/element] with body: {"strategy":"accessibility id","selector":"id","context":"","multiple":false}
[debug] [MJSONWP] Matched JSONWP error code 7 to NoSuchElementError
[debug] [BaseDriver] Waited for 1765 ms so far[debug] [JSONWP Proxy] Matched '/element' to command name 'findElement'
[debug] [JSONWP Proxy] Proxying [POST /element] to [POST http://localhost:8200/wd/hub/session/6ce2dbdc-fa4b-4f22-91af-1fe6d5b1e869/element] with body: {"strategy":"accessibility id","selector":"id","context":"","multiple":false}
[debug] [MJSONWP] Matched JSONWP error code 7 to NoSuchElementError
[debug] [BaseDriver] Waited for 2294 ms so far[debug] [JSONWP Proxy] Matched '/element' to command name 'findElement'
[debug] [JSONWP Proxy] Proxying [POST /element] to [POST http://localhost:8200/wd/hub/session/6ce2dbdc-fa4b-4f22-91af-1fe6d5b1e869/element] with body: {"strategy":"accessibility id","selector":"id","context":"","multiple":false}[debug] [MJSONWP] Matched JSONWP error code 7 to NoSuchElementError
[debug] [BaseDriver] Waited for 3285 ms so far[debug] [JSONWP Proxy] Matched '/element' to command name 'findElement'
[debug] [JSONWP Proxy] Proxying [POST /element] to [POST http://localhost:8200/wd/hub/session/6ce2dbdc-fa4b-4f22-91af-1fe6d5b1e869/element] with body: {"strategy":"accessibility id","selector":"id","context":"","multiple":false}
[debug] [MJSONWP] Matched JSONWP error code 7 to NoSuchElementError
[debug] [BaseDriver] Waited for 3808 ms so far[debug] [JSONWP Proxy] Matched '/element' to command name 'findElement'
[debug] [JSONWP Proxy] Proxying [POST /element] to [POST http://localhost:8200/wd/hub/session/6ce2dbdc-fa4b-4f22-91af-1fe6d5b1e869/element] with body: {"strategy":"accessibility id","selector":"id","context":"","multiple":false}[debug] [JSONWP Proxy] Got response with status 200: {"sessionId":"6ce2dbdc-fa4b-4f22-91af-1fe6d5b1e869","status":0,"value":{"ELEMENT":"fc686242-7269-4e64-ab41-265f61353b55"}}
[debug] [W3C] Responding to client with driver.findElement() result: {"element-6066-11e4-a52e-4f735466cecf":"fc686242-7269-4e64-ab41-265f61353b55"}
[info] [HTTP] <-- POST /wd/hub/session/41f474e1-80c6-4732-b34a-a6d695c86ce6/element 200 5064 ms - 88
[info] [HTTP] 
[info] [HTTP] --> DELETE /wd/hub/session/41f474e1-80c6-4732-b34a-a6d695c86ce6
[info] [HTTP] {}
[debug] [W3C] Calling AppiumDriver.deleteSession() with args: ["41f474e1-80c6-4732-b34a-a6d695c86ce6"]
[debug] [BaseDriver] Event 'quitSessionRequested' logged at 1536697877278 (21:31:17 GMT+0100 (BST))
[info] [Appium] Removing session 41f474e1-80c6-4732-b34a-a6d695c86ce6 from our master session list
[debug] [UiAutomator2] Deleting UiAutomator2 session
[debug] [UiAutomator2] Deleting UiAutomator2 server session
[debug] [JSONWP Proxy] Matched '/' to command name 'deleteSession'
[debug] [JSONWP Proxy] Proxying [DELETE /] to [DELETE http://localhost:8200/wd/hub/session/6ce2dbdc-fa4b-4f22-91af-1fe6d5b1e869] with no body
[debug] [JSONWP Proxy] Got response with status 200: "{\"sessionId\":\"6ce2dbdc-fa4b-4f22-91af-1fe6d5b1e869\",\"status\":0,\"value\":\"Session deleted\"}"
[debug] [ADB] Running '/Users/Lewina/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 shell am force-stop com.imggaming.dicemobile'
[debug] [Logcat] Stopping logcat capture
[debug] [ADB] Removing forwarded port socket connection: 8200 
[debug] [ADB] Running '/Users/Lewina/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 forward --remove tcp\:8200'
[debug] [BaseDriver] Event 'quitSessionFinished' logged at 1536697877424 (21:31:17 GMT+0100 (BST))
[debug] [W3C] Received response: null
[debug] [W3C] But deleting session, so not returning
[debug] [W3C] Responding to client with driver.deleteSession() result: null
[info] [HTTP] <-- DELETE /wd/hub/session/41f474e1-80c6-4732-b34a-a6d695c86ce6 200 148 ms - 14
[info] [HTTP] [debug] [UiAutomator2] [UIAutomator2] io.appium.uiautomator2.server.test.AppiumUiAutomator2Server:.
[debug] [UiAutomator2] [UIAutomator2] Time: 10.054
[debug] [UiAutomator2] [UIAutomator2] OK (1 test)