appium-desktop icon indicating copy to clipboard operation
appium-desktop copied to clipboard

Published 20 hours ago • verified publisher icon appium

Add 500ms delay to press action

Open sivatvu opened this issue 6 years ago • 13 comments

image

sivatvu avatar Jan 02 '19 14:01 sivatvu

I don't have enough info to help you with this. I need Appium logs and I need an explanation of what the bug is. Ie: what are you trying to do, what is the expected behaviour, what is the actual behaviour.

dpgraham avatar Jan 02 '19 17:01 dpgraham

I'm having the exact same problem with Appium Desktop, it will not scroll using the Swipe by Coordinates button. I click a start point and end point but the screen doesn't scroll up or down. I have even tried clicking at roughly the same start and end points used in our automated tests, but it doesn't scroll down in Appium Desktop as expected.

Swiping manually on the iPhone simulator works, as well as in our automated java tests. Only Appium Desktop seems to not swipe. Appium Desktop and Server are both at v1.10.0. Below is the log from Appium when the desktop app attempts to swipe. No errors are received.

What we've been successfully using in our automation to scroll:

new TouchAction(driver).press(poFrom).waitAction(wo).moveTo(poTo).release().perform();

Appium Desktop Failed Swipe Log:

[debug] [35m[W3C (96a832ff)][39m Calling AppiumDriver.getSession() with args: ["96a832ff-72ba-4bc3-9d96-5bc3c40e5416"]
[debug] [35m[XCUITest][39m Executing command 'getSession'
[35m[XCUITest][39m Merging WDA caps over Appium caps for session detail response
[debug] [35m[W3C (96a832ff)][39m Responding to client with driver.getSession() result: {"udid":"A1739AE1-6AFC-404D-9B18-2E14B135498B","platformName":"ios","se:CONFIG_UUID":"7b2ce8a2-f646-460f-948f-3745ac3d37cf","automationName":"XCuiTest","bundleId":"com.___.___.___.beta","connectHardwareKeyboard":true,"deviceName":"Sim - iPhone X","launchTimeout":60000,"newCommandTimeout":0,"platformVersion":"12.1","wdaLocalPort":8201,"device":"iphone","browserName":"______","sdkVersion":"12.1","CFBundleIdentifier":"com.___.___.___.beta","pixelRatio":3,"statBarHeight":44,"viewportRect":{"left":0,"top":132,"width":1125,"height":2304}}
[35m[HTTP][39m [37m<-- GET /wd/hub/session/96a832ff-72ba-4bc3-9d96-5bc3c40e5416 [39m[32m200[39m [90m2 ms - 559[39m
[35m[HTTP][39m [90m[39m
[35m[HTTP][39m [37m-->[39m [37mGET[39m [37m/wd/hub/status[39m
[35m[HTTP][39m [90m{}[39m
[debug] [35m[GENERIC][39m Calling AppiumDriver.getStatus() with args: []
[debug] [35m[GENERIC][39m Responding to client with driver.getStatus() result: {"build":{"version":"1.10.0"}}
[35m[HTTP][39m [37m<-- GET /wd/hub/status [39m[32m200[39m [90m1 ms - 68[39m
[35m[HTTP][39m [90m[39m
[35m[HTTP][39m [37m-->[39m [37mPOST[39m [37m/wd/hub/session/96a832ff-72ba-4bc3-9d96-5bc3c40e5416/touch/perform[39m
[35m[HTTP][39m [90m{"actions":[{"action":"press","options":{"x":205,"y":650}},{"action":"moveTo","options":{"x":202,"y":259}},{"action":"release","options":{}}]}[39m
[debug] [35m[W3C (96a832ff)][39m Calling AppiumDriver.performTouch() with args: [[{"action":"press","options":{"x":205,"y":650}},{"action":"moveTo","options":{"x":202,"y":259}},{"action":"release","options":{}}],"96a832ff-72ba-4bc3-9d96-5bc3c40e5416"]
[debug] [35m[XCUITest][39m Executing command 'performTouch'
[debug] [35m[XCUITest][39m Received the following touch action: press(options={"x":205,"y":650})-moveTo(options={"x":202,"y":259})-release(options={})
[debug] [35m[JSONWP Proxy][39m Proxying [POST /wda/touch/perform] to [POST http://localhost:8201/session/E3EFB412-0B93-4559-AC24-B878FDAD6674/wda/touch/perform] with body: {"actions":[{"action":"press","options":{"x":205,"y":650}},{"action":"moveTo","options":{"x":202,"y":259}},{"action":"release","options":{}}]}
[35m[Xcode][39m     t = 598431.37s Find the Application 'com.___.___.___.beta'
[35m[Xcode][39m 
[35m[Xcode][39m     t = 598431.37s     Snapshot accessibility hierarchy for app with pid 91313
[35m[Xcode][39m 
[35m[Xcode][39m     t = 598431.43s Find the Application 'com.___.___.___.beta'
[35m[Xcode][39m 
[35m[Xcode][39m     t = 598431.43s     Snapshot accessibility hierarchy for app with pid 91313
[35m[Xcode][39m 
[debug] [35m[JSONWP Proxy][39m Got response with status 200: {"value":{},"sessionId":"E3EFB412-0B93-4559-AC24-B878FDAD6674","status":0}
[debug] [35m[W3C (96a832ff)][39m Responding to client with driver.performTouch() result: {}
[35m[HTTP][39m [37m<-- POST /wd/hub/session/96a832ff-72ba-4bc3-9d96-5bc3c40e5416/touch/perform [39m[32m200[39m [90m243 ms - 12[39m
[35m[HTTP][39m [90m[39m
[35m[HTTP][39m [37m-->[39m [37mGET[39m [37m/wd/hub/session/96a832ff-72ba-4bc3-9d96-5bc3c40e5416/source[39m
[35m[HTTP][39m [90m{}[39m
[debug] [35m[W3C (96a832ff)][39m Calling AppiumDriver.getPageSource() with args: ["96a832ff-72ba-4bc3-9d96-5bc3c40e5416"]
[debug] [35m[XCUITest][39m Executing command 'getPageSource'
[debug] [35m[JSONWP Proxy][39m Matched '/source' to command name 'getPageSource'
[debug] [35m[JSONWP Proxy][39m Proxying [GET /source] to [GET http://localhost:8201/session/E3EFB412-0B93-4559-AC24-B878FDAD6674/source] with no body

ZGrauer avatar Jan 30 '19 16:01 ZGrauer

I'm having the same scrolling problem with Appium Desktop as well. It does not do anything when you use the swipe by coordinates. From using the recorder, I noticed that Appium Desktop is using this to scroll: (new TouchAction(driver)).press({x: 163, y: 566}).moveTo({x: 180: y: 180}).release().perform()

I believe it needs a waitAction() in between press() and moveTo(). I have tried scrolling without waitAction() directly in Appium and it does not work.

allan3723 avatar Feb 14 '19 19:02 allan3723

I have the same problem. But also using the wait action the scroll doesn't work.

I'm using python client

Appium v1.10.0

Calling AppiumDriver.performTouch() with args: [[{"action":"press","options":{"x":444,"y":1092}},{"action":"wait","options":{"ms":10000}},{"action":"moveTo","options":{"x":446,"y":400}},{"action":"release","options":{}}],"5bf3f230-feea-4d91-ae66-5a6f0aff2841"]

Nothing happens....

lubbo avatar Mar 06 '19 10:03 lubbo

I need logs to help with this.

dpgraham avatar Mar 18 '19 20:03 dpgraham

Attached the log of scroll down not executed (no error but no action)

[Appium] Appium REST http interface listener started on 0.0.0.0:4723
[HTTP] --> POST /wd/hub/session
[HTTP] {"capabilities":{"firstMatch":[{"appium:platformVersion":"7","appium:deviceName":"5200a44c612cb4ef","appium:app":"/Users/davide/Documents/Binaries/subito.apk","appium:bundleID":"it.subito","appium:mobisecSetup":false,"appium:mobisecSandbox":false,"appium:noReset":true,"appium:fullReset":false,"platformName":"Android","appium:automationName":"UiAutomator2","appium:autoWebview":false,"appium:newCommandTimeout":0}]},"desiredCapabilities":{"platformVersion":"7","deviceName":"5200a44c612cb4ef","app":"/Users/davide/Documents/Binaries/subito.apk","bundleID":"it.subito","mobisecSetup":false,"mobisecSandbox":false,"noReset":true,"fullReset":false,"platformName":"Android","automationName":"UiAutomator2","autoWebview":false,"newCommandTimeout":0}}
[debug] [W3C] Calling AppiumDriver.createSession() with args: [{"platformVersion":"7","deviceName":"5200a44c612cb4ef","app":"/Users/davide/Documents/Binaries/subito.apk","bundleID":"it.subito","mobisecSetup":false,"mobisecSandbox":false,"noReset":true,"fullReset":false,"platformName":"Android","automationName":"UiAutomator2","autoWebview":false,"newCommandTimeout":0},null,{"firstMatch":[{"appium:platformVersion":"7","appium:deviceName":"5200a44c612cb4ef","appium:app":"/Users/davide/Documents/Binaries/subito.apk","appium:bundleID":"it.subito","appium:mobisecSetup":false,"appium:mobisecSandbox":false,"appium:noReset":true,"appium:fullReset":false,"platformName":"Android","appium:automationName":"UiAutomator2","appium:autoWebview":false,"appium:newCommandTimeout":0}]}]
[debug] [BaseDriver] Event 'newSessionRequested' logged at 1553098940716 (17:22:20 GMT+0100 (Central European Standard Time))
[Appium] Appium v1.12.0 creating new AndroidUiautomator2Driver (v1.33.0) session
[Appium] Capabilities:
[Appium]   platformName: Android
[Appium]   platformVersion: 7
[Appium]   deviceName: 5200a44c612cb4ef
[Appium]   app: /Users/davide/Documents/Binaries/subito.apk
[Appium]   bundleID: it.subito
[Appium]   mobisecSetup: false
[Appium]   mobisecSandbox: false
[Appium]   noReset: true
[Appium]   fullReset: false
[Appium]   automationName: UiAutomator2
[Appium]   autoWebview: false
[Appium]   newCommandTimeout: 0
[debug] [BaseDriver] W3C capabilities {"alwaysMatch":{"platformNa... and MJSONWP desired capabilities {"platformVersion":"7","dev... were provided
[debug] [BaseDriver] Creating session with W3C capabilities: {"alwaysMatch":{"platformNa...
[BaseDriver] The following capabilities were provided, but are not recognized by appium: bundleID, mobisecSetup, mobisecSandbox.
[BaseDriver] Session created with session id: 080d875b-2bbb-409a-81a8-93a84a902b42
[BaseDriver] Using local app '/Users/davide/Documents/Binaries/subito.apk'
[debug] [UiAutomator2] Checking whether app is actually present
[ADB] Checking whether adb is present
[ADB] Found 1 'build-tools' folders under '/Users/davide/Library/Android/sdk' (newest first):
[ADB]     /Users/davide/Library/Android/sdk/build-tools/28.0.3
[ADB] Using adb from /Users/davide/Library/Android/sdk/platform-tools/adb
[AndroidDriver] Retrieving device list
[debug] [ADB] Trying to find a connected android device
[debug] [ADB] Getting connected devices...
[debug] [ADB] 1 device(s) connected
[AndroidDriver] Looking for a device with Android '7'
[debug] [ADB] Setting device id to 5200a44c612cb4ef
[ADB] Getting device platform version
[debug] [ADB] Running '/Users/davide/Library/Android/sdk/platform-tools/adb -P 5037 -s 5200a44c612cb4ef shell getprop ro.build.version.release'
[debug] [ADB] Current device property 'ro.build.version.release': 7.0
[AndroidDriver] Using device: 5200a44c612cb4ef
[debug] [ADB] Setting device id to 5200a44c612cb4ef
[debug] [ADB] Running '/Users/davide/Library/Android/sdk/platform-tools/adb -P 5037 -s 5200a44c612cb4ef shell getprop ro.build.version.sdk'
[debug] [ADB] Current device property 'ro.build.version.sdk': 24
[debug] [ADB] Device API level: 24
[debug] [AndroidDriver] Parsing package and activity from app manifest
[ADB] Using apkanalyzer from /Users/davide/Library/Android/sdk/tools/bin/apkanalyzer
[debug] [ADB] Starting '/Users/davide/Library/Android/sdk/tools/bin/apkanalyzer' with args ["-h","manifest","print","/Users/davide/Documents/Binaries/subito.apk"]
[ADB] Package name: 'it.subito'
[ADB] Main activity name: 'it.subito.v2.search.ListingActivity'
[debug] [AndroidDriver] Parsed package and activity are: it.subito/it.subito.v2.search.ListingActivity
[debug] [ADB] Running '/Users/davide/Library/Android/sdk/platform-tools/adb -P 5037 -s 5200a44c612cb4ef wait-for-device'
[debug] [ADB] Running '/Users/davide/Library/Android/sdk/platform-tools/adb -P 5037 -s 5200a44c612cb4ef shell echo ping'
[debug] [AndroidDriver] Pushing settings apk to device...
[debug] [ADB] Getting install status for io.appium.settings
[debug] [ADB] Running '/Users/davide/Library/Android/sdk/platform-tools/adb -P 5037 -s 5200a44c612cb4ef 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 '/Users/davide/Library/Android/sdk/platform-tools/adb -P 5037 -s 5200a44c612cb4ef shell dumpsys package io.appium.settings'
[ADB] Checking whether aapt is present
[ADB] Using aapt from /Users/davide/Library/Android/sdk/build-tools/28.0.3/aapt
[debug] [ADB] The version name of the installed 'io.appium.settings' is greater or equal to the application version name ('2.14.0' >= '2.14.0')
[debug] [ADB] There is no need to install/upgrade '/usr/local/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] Running '/Users/davide/Library/Android/sdk/platform-tools/adb -P 5037 -s 5200a44c612cb4ef shell 'pgrep --help; echo $?''
[debug] [ADB] Running '/Users/davide/Library/Android/sdk/platform-tools/adb -P 5037 -s 5200a44c612cb4ef shell pgrep \^appium\\.settings\$'
[debug] [AndroidDriver] io.appium.settings is already running. There is no need to reset its permissions.
[debug] [ADB] Running '/Users/davide/Library/Android/sdk/platform-tools/adb -P 5037 -s 5200a44c612cb4ef shell appops set io.appium.settings android\:mock_location allow'
[debug] [Logcat] Starting logcat capture
[debug] [ADB] Getting install status for io.appium.uiautomator2.server
[debug] [ADB] Running '/Users/davide/Library/Android/sdk/platform-tools/adb -P 5037 -s 5200a44c612cb4ef 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 '/Users/davide/Library/Android/sdk/platform-tools/adb -P 5037 -s 5200a44c612cb4ef shell dumpsys package io.appium.uiautomator2.server'
[debug] [ADB] The version name of the installed 'io.appium.uiautomator2.server' is greater or equal to the application version name ('3.4.0' >= '3.4.0')
[debug] [UiAutomator2] io.appium.uiautomator2.server installation state: sameVersionInstalled
[debug] [ADB] Checking app cert for /usr/local/lib/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-v3.4.0.apk
[ADB] Using apksigner from /Users/davide/Library/Android/sdk/build-tools/28.0.3/apksigner
[debug] [ADB] Starting '/Users/davide/Library/Android/sdk/build-tools/28.0.3/apksigner' with args '["verify","--print-certs","/usr/local/lib/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-v3.4.0.apk"]'
[debug] [ADB] apksigner stdout: Signer #1 certificate DN: [email protected], 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] '/usr/local/lib/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-v3.4.0.apk' is already signed.
[debug] [ADB] Getting install status for io.appium.uiautomator2.server.test
[debug] [ADB] Running '/Users/davide/Library/Android/sdk/platform-tools/adb -P 5037 -s 5200a44c612cb4ef shell dumpsys package io.appium.uiautomator2.server.test'
[debug] [ADB] 'io.appium.uiautomator2.server.test' is installed
[debug] [ADB] Checking app cert for /usr/local/lib/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-debug-androidTest.apk
[debug] [ADB] Starting '/Users/davide/Library/Android/sdk/build-tools/28.0.3/apksigner' with args '["verify","--print-certs","/usr/local/lib/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-debug-androidTest.apk"]'
[debug] [ADB] apksigner stdout: Signer #1 certificate DN: [email protected], 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] '/usr/local/lib/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-debug-androidTest.apk' is already signed.
[UiAutomator2] Server packages are not going to be (re)installed
[debug] [UiAutomator2] Waiting up to 30000ms for services to be available
[debug] [ADB] Running '/Users/davide/Library/Android/sdk/platform-tools/adb -P 5037 -s 5200a44c612cb4ef shell pm list instrumentation'
[debug] [UiAutomator2] Instrumentation target 'io.appium.uiautomator2.server.test/androidx.test.runner.AndroidJUnitRunner' is available
[debug] [UiAutomator2] Forwarding UiAutomator2 Server port 6790 to 8204
[debug] [ADB] Forwarding system: 8204 to device: 6790
[debug] [ADB] Running '/Users/davide/Library/Android/sdk/platform-tools/adb -P 5037 -s 5200a44c612cb4ef forward tcp\:8204 tcp\:6790'
[debug] [ADB] Checking app cert for /Users/davide/Documents/Binaries/subito.apk
[debug] [ADB] Starting '/Users/davide/Library/Android/sdk/build-tools/28.0.3/apksigner' with args '["verify","--print-certs","/Users/davide/Documents/Binaries/subito.apk"]'
[debug] [ADB] apksigner stdout: Signer #1 certificate DN: [email protected], 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] '/Users/davide/Documents/Binaries/subito.apk' is already signed.
[debug] [ADB] Getting install status for it.subito
[debug] [ADB] Running '/Users/davide/Library/Android/sdk/platform-tools/adb -P 5037 -s 5200a44c612cb4ef shell dumpsys package it.subito'
[debug] [ADB] 'it.subito' is installed
[debug] [ADB] Getting package info for 'it.subito'
[debug] [ADB] Running '/Users/davide/Library/Android/sdk/platform-tools/adb -P 5037 -s 5200a44c612cb4ef shell dumpsys package it.subito'
[debug] [ADB] The version name of the installed 'it.subito' is greater or equal to the application version name ('4.2.2' >= '4.2.2')
[debug] [ADB] There is no need to install/upgrade '/Users/davide/Documents/Binaries/subito.apk'
[debug] [UiAutomator2] Performing shallow cleanup of automation leftovers
[debug] [UiAutomator2] The following obsolete sessions are still running: ["ad0c504a-6b63-4599-813b-e069598c0fcf"]
[debug] [UiAutomator2] Cleaning up the obsolete sessions
[debug] [ADB] Running '/Users/davide/Library/Android/sdk/platform-tools/adb -P 5037 -s 5200a44c612cb4ef shell am force-stop io.appium.uiautomator2.server.test'
[UiAutomator2] Starting UIAutomator2 server 3.4.0
[UiAutomator2] Using UIAutomator2 server from '/usr/local/lib/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-v3.4.0.apk' and test from '/usr/local/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","5200a44c612cb4ef","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://localhost:8204/wd/hub/status] with no body
[WD Proxy] Got an unexpected response: {"errno":"ECONNRESET","code":"ECONNRESET","syscall":"read"}
[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://localhost:8204/wd/hub/status] with no body
[WD Proxy] Got an unexpected response: {"errno":"ECONNRESET","code":"ECONNRESET","syscall":"read"}
[debug] [WD Proxy] Matched '/status' to command name 'getStatus'
[debug] [WD Proxy] Proxying [GET /status] to [GET http://localhost:8204/wd/hub/status] with no body
[debug] [WD Proxy] Got response with status 200: "{\"sessionId\":\"None\",\"status\":0,\"value\":\"Status Invoked\"}"
[debug] [UiAutomator2] The initialization of the instrumentation process took 3126ms
[debug] [WD Proxy] Matched '/session' to command name 'createSession'
[debug] [WD Proxy] Proxying [POST /session] to [POST http://localhost:8204/wd/hub/session] with body: {"desiredCapabilities":{"platform":"LINUX","webStorageEnabled":false,"takesScreenshot":true,"javascriptEnabled":true,"databaseEnabled":false,"networkConnectionEnabled":true,"locationContextEnabled":false,"warnings":{},"desired":{"platformName":"Android","platformVersion":"7","deviceName":"5200a44c612cb4ef","app":"/Users/davide/Documents/Binaries/subito.apk","bundleID":"it.subito","mobisecSetup":false,"mobisecSandbox":false,"noReset":true,"fullReset":false,"automationName":"UiAutomator2","autoWebview":false,"newCommandTimeout":0},"platformName":"Android","platformVersion":"7","deviceName":"5200a44c612cb4ef","app":"/Users/davide/Documents/Binaries/subito.apk","bundleID":"it.subito","mobisecSetup":false,"mobisecSandbox":false,"noReset":true,"fullReset":false,"automationName":"UiAutomator2","autoWebview":false,"newCommandTimeout":0,"deviceUDID":"5200a44c612cb4ef","appPackage":"it.subito"}}
[debug] [WD Proxy] Got response with status 200: {"sessionId":"f182fe9c-0c8c-492c-9435-a9e8e599eac8","status":0,"value":"Created Session"}
[debug] [WD Proxy] Proxying [GET /appium/device/info] to [GET http://localhost:8204/wd/hub/session/f182fe9c-0c8c-492c-9435-a9e8e599eac8/appium/device/info] with no body
[debug] [WD Proxy] Got response with status 200: "{\"sessionId\":\"f182fe9c-0c8c-492c-9435-a9e8e599eac8\",\"status\":0,\"value\":{\"androidId\":\"c4892ade8b3c8863\",\"manufacturer\":\"samsung\",\"model\":\"SM-J530F\",\"brand\":\"samsung\",\"apiVersion\":\"24\",\"platformVersion\":\"7.0\",\"carrierName\":\"\",\"realDisplaySize\":\"720x1280\",\"displayDensity\":320}}"
[debug] [ADB] Running '/Users/davide/Library/Android/sdk/platform-tools/adb -P 5037 -s 5200a44c612cb4ef shell dumpsys window'
[AndroidDriver] Screen already unlocked, doing nothing
[UiAutomator2] UiAutomator2 did not start the activity we were waiting for, 'it.subito/it.subito.v2.search.ListingActivity'. Starting it ourselves
[debug] [ADB] Running '/Users/davide/Library/Android/sdk/platform-tools/adb -P 5037 -s 5200a44c612cb4ef shell am start -W -n it.subito/it.subito.v2.search.ListingActivity -S -a android.intent.action.MAIN -c android.intent.category.LAUNCHER -f 0x10200000'
[debug] [ADB] Waiting up to 20000ms for activity matching pkg: 'it.subito' and activity: 'it.subito.v2.search.ListingActivity' to be focused
[debug] [ADB] Possible activities, to be checked: 'it.subito.v2.search.ListingActivity', 'it.subito.it.subito.v2.search.ListingActivity'
[debug] [ADB] Getting focused package and activity
[debug] [ADB] Running '/Users/davide/Library/Android/sdk/platform-tools/adb -P 5037 -s 5200a44c612cb4ef shell dumpsys window windows'
[debug] [ADB] Found package: 'it.subito' and fully qualified activity name : 'it.subito.v2.search.ListingActivity'
[debug] [WD Proxy] Proxying [GET /appium/device/pixel_ratio] to [GET http://localhost:8204/wd/hub/session/f182fe9c-0c8c-492c-9435-a9e8e599eac8/appium/device/pixel_ratio] with body: {}
[debug] [WD Proxy] Got response with status 200: "{\"sessionId\":\"f182fe9c-0c8c-492c-9435-a9e8e599eac8\",\"status\":0,\"value\":2}"
[debug] [WD Proxy] Matched '/appium/device/system_bars' to command name 'getSystemBars'
[debug] [WD Proxy] Proxying [GET /appium/device/system_bars] to [GET http://localhost:8204/wd/hub/session/f182fe9c-0c8c-492c-9435-a9e8e599eac8/appium/device/system_bars] with body: {}
[debug] [WD Proxy] Got response with status 200: "{\"sessionId\":\"f182fe9c-0c8c-492c-9435-a9e8e599eac8\",\"status\":0,\"value\":{\"statusBar\":48}}"
[debug] [WD Proxy] Matched '/window/current/size' to command name 'getWindowSize'
[debug] [WD Proxy] Proxying [GET /window/current/size] to [GET http://localhost:8204/wd/hub/session/f182fe9c-0c8c-492c-9435-a9e8e599eac8/window/current/size] with body: {}
[debug] [WD Proxy] Got response with status 200: "{\"sessionId\":\"f182fe9c-0c8c-492c-9435-a9e8e599eac8\",\"status\":0,\"value\":{\"height\":1280,\"width\":720}}"
[Appium] New AndroidUiautomator2Driver session created successfully, session 080d875b-2bbb-409a-81a8-93a84a902b42 added to master session list
[debug] [BaseDriver] Event 'newSessionStarted' logged at 1553098955732 (17:22:35 GMT+0100 (Central European Standard Time))
[debug] [W3C (080d875b)] Cached the protocol value 'W3C' for the new session 080d875b-2bbb-409a-81a8-93a84a902b42
[debug] [W3C (080d875b)] 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","platformVersion":"7","deviceName":"5200a44c612cb4ef","app":"/Users/davide/Documents/Binaries/subito.apk","bundleID":"it.subito","mobisecSetup":false,"mobisecSandbox":false,"noReset":true,"fullReset":false,"automationName":"UiAutomator2","autoWebview":false,"newCommandTimeout":0},"platformName":"Android","platformVersion":"7.0","deviceName":"5200a44c612cb4ef","app":"/Users/davide/Documents/Binaries/subito.apk","bundleID":"it.subito","mobisecSetup":false,"mobisecSandbox":false,"noReset":true,"fullReset":false,"automationName":"UiAutomator2","autoWebview":false,"newCommandTimeout":0,"deviceUDID":"5200a44c612cb4ef","appPackage":"it.subito","deviceApiLevel":24,"deviceScreenSize":"720x1280","deviceScreenDensity":320,"deviceModel":"SM-J530F","deviceManufacturer":"samsun...
[HTTP] <-- POST /wd/hub/session 200 15023 ms - 1181
[HTTP] 
[HTTP] --> GET /wd/hub/session/080d875b-2bbb-409a-81a8-93a84a902b42/contexts
[HTTP] {}
[debug] [W3C (080d875b)] Calling AppiumDriver.getContexts() with args: ["080d875b-2bbb-409a-81a8-93a84a902b42"]
[debug] [AndroidDriver] Getting a list of available webviews
[debug] [ADB] Running '/Users/davide/Library/Android/sdk/platform-tools/adb -P 5037 -s 5200a44c612cb4ef shell cat /proc/net/unix'
[debug] [AndroidDriver] Found webviews: []
[debug] [AndroidDriver] Available contexts: ["NATIVE_APP"]
[debug] [W3C (080d875b)] Responding to client with driver.getContexts() result: ["NATIVE_APP"]
[HTTP] <-- GET /wd/hub/session/080d875b-2bbb-409a-81a8-93a84a902b42/contexts 200 43 ms - 24
[HTTP] 
[HTTP] --> GET /wd/hub/session/080d875b-2bbb-409a-81a8-93a84a902b42/context
[HTTP] {}
[debug] [W3C (080d875b)] Calling AppiumDriver.getCurrentContext() with args: ["080d875b-2bbb-409a-81a8-93a84a902b42"]
[debug] [W3C (080d875b)] Responding to client with driver.getCurrentContext() result: "NATIVE_APP"
[HTTP] <-- GET /wd/hub/session/080d875b-2bbb-409a-81a8-93a84a902b42/context 200 2 ms - 22
[HTTP] 
[HTTP] --> POST /wd/hub/session/080d875b-2bbb-409a-81a8-93a84a902b42/element
[HTTP] {"using":"id","value":"it.subito:id/bottom_bar_favorites","sessionId":"080d875b-2bbb-409a-81a8-93a84a902b42"}
[debug] [W3C (080d875b)] Calling AppiumDriver.findElement() with args: ["id","it.subito:id/bottom_bar_favorites","080d875b-2bbb-409a-81a8-93a84a902b42"]
[debug] [BaseDriver] Valid locator strategies for this request: xpath, id, class name, accessibility id, -android uiautomator
[debug] [BaseDriver] Waiting up to 0 ms for condition
[debug] [WD Proxy] Matched '/element' to command name 'findElement'
[debug] [WD Proxy] Proxying [POST /element] to [POST http://localhost:8204/wd/hub/session/f182fe9c-0c8c-492c-9435-a9e8e599eac8/element] with body: {"strategy":"id","selector":"it.subito:id/bottom_bar_favorites","context":"","multiple":false}
[debug] [WD Proxy] Got response with status 200: {"sessionId":"f182fe9c-0c8c-492c-9435-a9e8e599eac8","status":0,"value":{"ELEMENT":"eb7ad345-026b-48f7-909d-b5e0bb043135"}}
[debug] [W3C (080d875b)] Responding to client with driver.findElement() result: {"element-6066-11e4-a52e-4f735466cecf":"eb7ad345-026b-48f7-909d-b5e0bb043135","ELEMENT":"eb7ad345-026b-48f7-909d-b5e0bb043135"}
[HTTP] <-- POST /wd/hub/session/080d875b-2bbb-409a-81a8-93a84a902b42/element 200 1907 ms - 137
[HTTP] 
[HTTP] --> GET /wd/hub/session/080d875b-2bbb-409a-81a8-93a84a902b42/element/eb7ad345-026b-48f7-909d-b5e0bb043135/displayed
[HTTP] {}
[debug] [W3C (080d875b)] Calling AppiumDriver.elementDisplayed() with args: ["eb7ad345-026b-48f7-909d-b5e0bb043135","080d875b-2bbb-409a-81a8-93a84a902b42"]
[debug] [WD Proxy] Matched '/element/eb7ad345-026b-48f7-909d-b5e0bb043135/attribute/displayed' to command name 'getAttribute'
[debug] [WD Proxy] Proxying [GET /element/eb7ad345-026b-48f7-909d-b5e0bb043135/attribute/displayed] to [GET http://localhost:8204/wd/hub/session/f182fe9c-0c8c-492c-9435-a9e8e599eac8/element/eb7ad345-026b-48f7-909d-b5e0bb043135/attribute/displayed] with body: {}
[debug] [WD Proxy] Got response with status 200: "{\"sessionId\":\"f182fe9c-0c8c-492c-9435-a9e8e599eac8\",\"status\":0,\"value\":\"true\"}"
[debug] [W3C (080d875b)] Responding to client with driver.elementDisplayed() result: true
[HTTP] <-- GET /wd/hub/session/080d875b-2bbb-409a-81a8-93a84a902b42/element/eb7ad345-026b-48f7-909d-b5e0bb043135/displayed 200 45 ms - 14
[HTTP] 
[HTTP] --> GET /wd/hub/session/080d875b-2bbb-409a-81a8-93a84a902b42/element/eb7ad345-026b-48f7-909d-b5e0bb043135/enabled
[HTTP] {}
[debug] [W3C (080d875b)] Calling AppiumDriver.elementEnabled() with args: ["eb7ad345-026b-48f7-909d-b5e0bb043135","080d875b-2bbb-409a-81a8-93a84a902b42"]
[debug] [WD Proxy] Matched '/element/eb7ad345-026b-48f7-909d-b5e0bb043135/attribute/enabled' to command name 'getAttribute'
[debug] [WD Proxy] Proxying [GET /element/eb7ad345-026b-48f7-909d-b5e0bb043135/attribute/enabled] to [GET http://localhost:8204/wd/hub/session/f182fe9c-0c8c-492c-9435-a9e8e599eac8/element/eb7ad345-026b-48f7-909d-b5e0bb043135/attribute/enabled] with body: {}
[debug] [WD Proxy] Got response with status 200: "{\"sessionId\":\"f182fe9c-0c8c-492c-9435-a9e8e599eac8\",\"status\":0,\"value\":\"true\"}"
[debug] [W3C (080d875b)] Responding to client with driver.elementEnabled() result: true
[HTTP] <-- GET /wd/hub/session/080d875b-2bbb-409a-81a8-93a84a902b42/element/eb7ad345-026b-48f7-909d-b5e0bb043135/enabled 200 27 ms - 14
[HTTP] 
[HTTP] --> POST /wd/hub/session/080d875b-2bbb-409a-81a8-93a84a902b42/touch/perform
[HTTP] {"actions":[{"action":"tap","options":{"element":"eb7ad345-026b-48f7-909d-b5e0bb043135","count":1}}],"sessionId":"080d875b-2bbb-409a-81a8-93a84a902b42"}
[debug] [W3C (080d875b)] Calling AppiumDriver.performTouch() with args: [[{"action":"tap","options":{"element":"eb7ad345-026b-48f7-909d-b5e0bb043135","count":1}}],"080d875b-2bbb-409a-81a8-93a84a902b42"]
[UiAutomator2] calling get location: eb7ad345-026b-48f7-909d-b5e0bb043135
[debug] [WD Proxy] Matched '/element/eb7ad345-026b-48f7-909d-b5e0bb043135/location' to command name 'getLocation'
[debug] [WD Proxy] Proxying [GET /element/eb7ad345-026b-48f7-909d-b5e0bb043135/location] to [GET http://localhost:8204/wd/hub/session/f182fe9c-0c8c-492c-9435-a9e8e599eac8/element/eb7ad345-026b-48f7-909d-b5e0bb043135/location] with body: {}
[debug] [WD Proxy] Got response with status 200: "{\"sessionId\":\"f182fe9c-0c8c-492c-9435-a9e8e599eac8\",\"status\":0,\"value\":{\"x\":147,\"y\":1183}}"
[debug] [WD Proxy] Matched '/element/eb7ad345-026b-48f7-909d-b5e0bb043135/size' to command name 'getSize'
[debug] [WD Proxy] Proxying [GET /element/eb7ad345-026b-48f7-909d-b5e0bb043135/size] to [GET http://localhost:8204/wd/hub/session/f182fe9c-0c8c-492c-9435-a9e8e599eac8/element/eb7ad345-026b-48f7-909d-b5e0bb043135/size] with body: {}
[debug] [WD Proxy] Got response with status 200: "{\"sessionId\":\"f182fe9c-0c8c-492c-9435-a9e8e599eac8\",\"status\":0,\"value\":{\"width\":140,\"height\":88}}"
[debug] [WD Proxy] Matched '/element/eb7ad345-026b-48f7-909d-b5e0bb043135/click' to command name 'click'
[debug] [WD Proxy] Proxying [POST /element/eb7ad345-026b-48f7-909d-b5e0bb043135/click] to [POST http://localhost:8204/wd/hub/session/f182fe9c-0c8c-492c-9435-a9e8e599eac8/element/eb7ad345-026b-48f7-909d-b5e0bb043135/click] with body: {"element":"eb7ad345-026b-48f7-909d-b5e0bb043135","x":217,"y":1227}
[debug] [WD Proxy] Got response with status 200: {"sessionId":"f182fe9c-0c8c-492c-9435-a9e8e599eac8","status":0,"value":true}
[debug] [W3C (080d875b)] Responding to client with driver.performTouch() result: null
[HTTP] <-- POST /wd/hub/session/080d875b-2bbb-409a-81a8-93a84a902b42/touch/perform 200 120 ms - 14
[HTTP] 
[HTTP] --> POST /wd/hub/session/080d875b-2bbb-409a-81a8-93a84a902b42/element
[HTTP] {"using":"id","value":"it.subito:id/bottom_bar_search","sessionId":"080d875b-2bbb-409a-81a8-93a84a902b42"}
[debug] [W3C (080d875b)] Calling AppiumDriver.findElement() with args: ["id","it.subito:id/bottom_bar_search","080d875b-2bbb-409a-81a8-93a84a902b42"]
[debug] [BaseDriver] Valid locator strategies for this request: xpath, id, class name, accessibility id, -android uiautomator
[debug] [BaseDriver] Waiting up to 0 ms for condition
[debug] [WD Proxy] Matched '/element' to command name 'findElement'
[debug] [WD Proxy] Proxying [POST /element] to [POST http://localhost:8204/wd/hub/session/f182fe9c-0c8c-492c-9435-a9e8e599eac8/element] with body: {"strategy":"id","selector":"it.subito:id/bottom_bar_search","context":"","multiple":false}
[debug] [WD Proxy] Got response with status 200: {"sessionId":"f182fe9c-0c8c-492c-9435-a9e8e599eac8","status":0,"value":{"ELEMENT":"2638aab4-091b-444c-9d8f-c26a6fb24ea7"}}
[debug] [W3C (080d875b)] Responding to client with driver.findElement() result: {"element-6066-11e4-a52e-4f735466cecf":"2638aab4-091b-444c-9d8f-c26a6fb24ea7","ELEMENT":"2638aab4-091b-444c-9d8f-c26a6fb24ea7"}
[HTTP] <-- POST /wd/hub/session/080d875b-2bbb-409a-81a8-93a84a902b42/element 200 1014 ms - 137
[HTTP] 
[HTTP] --> GET /wd/hub/session/080d875b-2bbb-409a-81a8-93a84a902b42/element/2638aab4-091b-444c-9d8f-c26a6fb24ea7/displayed
[HTTP] {}
[debug] [W3C (080d875b)] Calling AppiumDriver.elementDisplayed() with args: ["2638aab4-091b-444c-9d8f-c26a6fb24ea7","080d875b-2bbb-409a-81a8-93a84a902b42"]
[debug] [WD Proxy] Matched '/element/2638aab4-091b-444c-9d8f-c26a6fb24ea7/attribute/displayed' to command name 'getAttribute'
[debug] [WD Proxy] Proxying [GET /element/2638aab4-091b-444c-9d8f-c26a6fb24ea7/attribute/displayed] to [GET http://localhost:8204/wd/hub/session/f182fe9c-0c8c-492c-9435-a9e8e599eac8/element/2638aab4-091b-444c-9d8f-c26a6fb24ea7/attribute/displayed] with body: {}
[debug] [WD Proxy] Got response with status 200: "{\"sessionId\":\"f182fe9c-0c8c-492c-9435-a9e8e599eac8\",\"status\":0,\"value\":\"true\"}"
[debug] [W3C (080d875b)] Responding to client with driver.elementDisplayed() result: true
[HTTP] <-- GET /wd/hub/session/080d875b-2bbb-409a-81a8-93a84a902b42/element/2638aab4-091b-444c-9d8f-c26a6fb24ea7/displayed 200 28 ms - 14
[HTTP] 
[HTTP] --> GET /wd/hub/session/080d875b-2bbb-409a-81a8-93a84a902b42/element/2638aab4-091b-444c-9d8f-c26a6fb24ea7/enabled
[HTTP] {}
[debug] [W3C (080d875b)] Calling AppiumDriver.elementEnabled() with args: ["2638aab4-091b-444c-9d8f-c26a6fb24ea7","080d875b-2bbb-409a-81a8-93a84a902b42"]
[debug] [WD Proxy] Matched '/element/2638aab4-091b-444c-9d8f-c26a6fb24ea7/attribute/enabled' to command name 'getAttribute'
[debug] [WD Proxy] Proxying [GET /element/2638aab4-091b-444c-9d8f-c26a6fb24ea7/attribute/enabled] to [GET http://localhost:8204/wd/hub/session/f182fe9c-0c8c-492c-9435-a9e8e599eac8/element/2638aab4-091b-444c-9d8f-c26a6fb24ea7/attribute/enabled] with body: {}
[debug] [WD Proxy] Got response with status 200: "{\"sessionId\":\"f182fe9c-0c8c-492c-9435-a9e8e599eac8\",\"status\":0,\"value\":\"true\"}"
[debug] [W3C (080d875b)] Responding to client with driver.elementEnabled() result: true
[HTTP] <-- GET /wd/hub/session/080d875b-2bbb-409a-81a8-93a84a902b42/element/2638aab4-091b-444c-9d8f-c26a6fb24ea7/enabled 200 23 ms - 14
[HTTP] 
[HTTP] --> POST /wd/hub/session/080d875b-2bbb-409a-81a8-93a84a902b42/touch/perform
[HTTP] {"actions":[{"action":"tap","options":{"element":"2638aab4-091b-444c-9d8f-c26a6fb24ea7","count":1}}],"sessionId":"080d875b-2bbb-409a-81a8-93a84a902b42"}
[debug] [W3C (080d875b)] Calling AppiumDriver.performTouch() with args: [[{"action":"tap","options":{"element":"2638aab4-091b-444c-9d8f-c26a6fb24ea7","count":1}}],"080d875b-2bbb-409a-81a8-93a84a902b42"]
[UiAutomator2] calling get location: 2638aab4-091b-444c-9d8f-c26a6fb24ea7
[debug] [WD Proxy] Matched '/element/2638aab4-091b-444c-9d8f-c26a6fb24ea7/location' to command name 'getLocation'
[debug] [WD Proxy] Proxying [GET /element/2638aab4-091b-444c-9d8f-c26a6fb24ea7/location] to [GET http://localhost:8204/wd/hub/session/f182fe9c-0c8c-492c-9435-a9e8e599eac8/element/2638aab4-091b-444c-9d8f-c26a6fb24ea7/location] with body: {}
[debug] [WD Proxy] Got response with status 200: "{\"sessionId\":\"f182fe9c-0c8c-492c-9435-a9e8e599eac8\",\"status\":0,\"value\":{\"x\":3,\"y\":1183}}"
[debug] [WD Proxy] Matched '/element/2638aab4-091b-444c-9d8f-c26a6fb24ea7/size' to command name 'getSize'
[debug] [WD Proxy] Proxying [GET /element/2638aab4-091b-444c-9d8f-c26a6fb24ea7/size] to [GET http://localhost:8204/wd/hub/session/f182fe9c-0c8c-492c-9435-a9e8e599eac8/element/2638aab4-091b-444c-9d8f-c26a6fb24ea7/size] with body: {}
[debug] [WD Proxy] Got response with status 200: "{\"sessionId\":\"f182fe9c-0c8c-492c-9435-a9e8e599eac8\",\"status\":0,\"value\":{\"width\":140,\"height\":88}}"
[debug] [WD Proxy] Matched '/element/2638aab4-091b-444c-9d8f-c26a6fb24ea7/click' to command name 'click'
[debug] [WD Proxy] Proxying [POST /element/2638aab4-091b-444c-9d8f-c26a6fb24ea7/click] to [POST http://localhost:8204/wd/hub/session/f182fe9c-0c8c-492c-9435-a9e8e599eac8/element/2638aab4-091b-444c-9d8f-c26a6fb24ea7/click] with body: {"element":"2638aab4-091b-444c-9d8f-c26a6fb24ea7","x":73,"y":1227}
[debug] [WD Proxy] Got response with status 200: {"sessionId":"f182fe9c-0c8c-492c-9435-a9e8e599eac8","status":0,"value":true}
[debug] [W3C (080d875b)] Responding to client with driver.performTouch() result: null
[HTTP] <-- POST /wd/hub/session/080d875b-2bbb-409a-81a8-93a84a902b42/touch/perform 200 68 ms - 14
[HTTP] 
[HTTP] --> POST /wd/hub/session/080d875b-2bbb-409a-81a8-93a84a902b42/touch/perform
[HTTP] {"actions":[{"action":"press","options":{"x":444,"y":1092}},{"action":"wait","options":{"ms":600}},{"action":"moveTo","options":{"x":446,"y":400}},{"action":"release","options":{}}],"sessionId":"080d875b-2bbb-409a-81a8-93a84a902b42"}
[debug] [W3C (080d875b)] Calling AppiumDriver.performTouch() with args: [[{"action":"press","options":{"x":444,"y":1092}},{"action":"wait","options":{"ms":600}},{"action":"moveTo","options":{"x":446,"y":400}},{"action":"release","options":{}}],"080d875b-2bbb-409a-81a8-93a84a902b42"]
[debug] [WD Proxy] Matched '/touch/perform' to command name 'performTouch'
[debug] [WD Proxy] Proxying [POST /touch/perform] to [POST http://localhost:8204/wd/hub/session/f182fe9c-0c8c-492c-9435-a9e8e599eac8/touch/perform] with body: {"startX":444,"startY":1092,"endX":446,"endY":400,"steps":17}
[debug] [WD Proxy] Got response with status 200: {"sessionId":"f182fe9c-0c8c-492c-9435-a9e8e599eac8","status":0,"value":true}
[debug] [W3C (080d875b)] Responding to client with driver.performTouch() result: true
[HTTP] <-- POST /wd/hub/session/080d875b-2bbb-409a-81a8-93a84a902b42/touch/perform 200 1509 ms - 14
[HTTP] 
[HTTP] --> POST /wd/hub/session/080d875b-2bbb-409a-81a8-93a84a902b42/element
[HTTP] {"using":"id","value":"it.subito:id/bottom_bar_favorites","sessionId":"080d875b-2bbb-409a-81a8-93a84a902b42"}
[debug] [W3C (080d875b)] Calling AppiumDriver.findElement() with args: ["id","it.subito:id/bottom_bar_favorites","080d875b-2bbb-409a-81a8-93a84a902b42"]
[debug] [BaseDriver] Valid locator strategies for this request: xpath, id, class name, accessibility id, -android uiautomator
[debug] [BaseDriver] Waiting up to 0 ms for condition
[debug] [WD Proxy] Matched '/element' to command name 'findElement'
[debug] [WD Proxy] Proxying [POST /element] to [POST http://localhost:8204/wd/hub/session/f182fe9c-0c8c-492c-9435-a9e8e599eac8/element] with body: {"strategy":"id","selector":"it.subito:id/bottom_bar_favorites","context":"","multiple":false}
[debug] [WD Proxy] Got response with status 200: {"sessionId":"f182fe9c-0c8c-492c-9435-a9e8e599eac8","status":0,"value":{"ELEMENT":"7838df92-b46f-46ad-872b-7ee2ae42d29f"}}
[debug] [W3C (080d875b)] Responding to client with driver.findElement() result: {"element-6066-11e4-a52e-4f735466cecf":"7838df92-b46f-46ad-872b-7ee2ae42d29f","ELEMENT":"7838df92-b46f-46ad-872b-7ee2ae42d29f"}
[HTTP] <-- POST /wd/hub/session/080d875b-2bbb-409a-81a8-93a84a902b42/element 200 84 ms - 137
[HTTP] 
[HTTP] --> GET /wd/hub/session/080d875b-2bbb-409a-81a8-93a84a902b42/element/7838df92-b46f-46ad-872b-7ee2ae42d29f/displayed
[HTTP] {}
[debug] [W3C (080d875b)] Calling AppiumDriver.elementDisplayed() with args: ["7838df92-b46f-46ad-872b-7ee2ae42d29f","080d875b-2bbb-409a-81a8-93a84a902b42"]
[debug] [WD Proxy] Matched '/element/7838df92-b46f-46ad-872b-7ee2ae42d29f/attribute/displayed' to command name 'getAttribute'
[debug] [WD Proxy] Proxying [GET /element/7838df92-b46f-46ad-872b-7ee2ae42d29f/attribute/displayed] to [GET http://localhost:8204/wd/hub/session/f182fe9c-0c8c-492c-9435-a9e8e599eac8/element/7838df92-b46f-46ad-872b-7ee2ae42d29f/attribute/displayed] with body: {}
[debug] [WD Proxy] Got response with status 200: "{\"sessionId\":\"f182fe9c-0c8c-492c-9435-a9e8e599eac8\",\"status\":0,\"value\":\"true\"}"
[debug] [W3C (080d875b)] Responding to client with driver.elementDisplayed() result: true
[HTTP] <-- GET /wd/hub/session/080d875b-2bbb-409a-81a8-93a84a902b42/element/7838df92-b46f-46ad-872b-7ee2ae42d29f/displayed 200 20 ms - 14
[HTTP] 
[HTTP] --> GET /wd/hub/session/080d875b-2bbb-409a-81a8-93a84a902b42/element/7838df92-b46f-46ad-872b-7ee2ae42d29f/enabled
[HTTP] {}
[debug] [W3C (080d875b)] Calling AppiumDriver.elementEnabled() with args: ["7838df92-b46f-46ad-872b-7ee2ae42d29f","080d875b-2bbb-409a-81a8-93a84a902b42"]
[debug] [WD Proxy] Matched '/element/7838df92-b46f-46ad-872b-7ee2ae42d29f/attribute/enabled' to command name 'getAttribute'
[debug] [WD Proxy] Proxying [GET /element/7838df92-b46f-46ad-872b-7ee2ae42d29f/attribute/enabled] to [GET http://localhost:8204/wd/hub/session/f182fe9c-0c8c-492c-9435-a9e8e599eac8/element/7838df92-b46f-46ad-872b-7ee2ae42d29f/attribute/enabled] with body: {}
[debug] [WD Proxy] Got response with status 200: "{\"sessionId\":\"f182fe9c-0c8c-492c-9435-a9e8e599eac8\",\"status\":0,\"value\":\"true\"}"
[debug] [W3C (080d875b)] Responding to client with driver.elementEnabled() result: true
[HTTP] <-- GET /wd/hub/session/080d875b-2bbb-409a-81a8-93a84a902b42/element/7838df92-b46f-46ad-872b-7ee2ae42d29f/enabled 200 19 ms - 14
[HTTP] 
[HTTP] --> POST /wd/hub/session/080d875b-2bbb-409a-81a8-93a84a902b42/touch/perform
[HTTP] {"actions":[{"action":"tap","options":{"element":"7838df92-b46f-46ad-872b-7ee2ae42d29f","count":1}}],"sessionId":"080d875b-2bbb-409a-81a8-93a84a902b42"}
[debug] [W3C (080d875b)] Calling AppiumDriver.performTouch() with args: [[{"action":"tap","options":{"element":"7838df92-b46f-46ad-872b-7ee2ae42d29f","count":1}}],"080d875b-2bbb-409a-81a8-93a84a902b42"]
[UiAutomator2] calling get location: 7838df92-b46f-46ad-872b-7ee2ae42d29f
[debug] [WD Proxy] Matched '/element/7838df92-b46f-46ad-872b-7ee2ae42d29f/location' to command name 'getLocation'
[debug] [WD Proxy] Proxying [GET /element/7838df92-b46f-46ad-872b-7ee2ae42d29f/location] to [GET http://localhost:8204/wd/hub/session/f182fe9c-0c8c-492c-9435-a9e8e599eac8/element/7838df92-b46f-46ad-872b-7ee2ae42d29f/location] with body: {}
[debug] [WD Proxy] Got response with status 200: "{\"sessionId\":\"f182fe9c-0c8c-492c-9435-a9e8e599eac8\",\"status\":0,\"value\":{\"x\":147,\"y\":1183}}"
[debug] [WD Proxy] Matched '/element/7838df92-b46f-46ad-872b-7ee2ae42d29f/size' to command name 'getSize'
[debug] [WD Proxy] Proxying [GET /element/7838df92-b46f-46ad-872b-7ee2ae42d29f/size] to [GET http://localhost:8204/wd/hub/session/f182fe9c-0c8c-492c-9435-a9e8e599eac8/element/7838df92-b46f-46ad-872b-7ee2ae42d29f/size] with body: {}
[debug] [WD Proxy] Got response with status 200: "{\"sessionId\":\"f182fe9c-0c8c-492c-9435-a9e8e599eac8\",\"status\":0,\"value\":{\"width\":140,\"height\":88}}"
[debug] [WD Proxy] Matched '/element/7838df92-b46f-46ad-872b-7ee2ae42d29f/click' to command name 'click'
[debug] [WD Proxy] Proxying [POST /element/7838df92-b46f-46ad-872b-7ee2ae42d29f/click] to [POST http://localhost:8204/wd/hub/session/f182fe9c-0c8c-492c-9435-a9e8e599eac8/element/7838df92-b46f-46ad-872b-7ee2ae42d29f/click] with body: {"element":"7838df92-b46f-46ad-872b-7ee2ae42d29f","x":217,"y":1227}
[debug] [WD Proxy] Got response with status 200: {"sessionId":"f182fe9c-0c8c-492c-9435-a9e8e599eac8","status":0,"value":true}
[debug] [W3C (080d875b)] Responding to client with driver.performTouch() result: null
[HTTP] <-- POST /wd/hub/session/080d875b-2bbb-409a-81a8-93a84a902b42/touch/perform 200 102 ms - 14
[HTTP] 
[HTTP] --> POST /wd/hub/session/080d875b-2bbb-409a-81a8-93a84a902b42/element
[HTTP] {"using":"id","value":"it.subito:id/bottom_bar_search","sessionId":"080d875b-2bbb-409a-81a8-93a84a902b42"}
[debug] [W3C (080d875b)] Calling AppiumDriver.findElement() with args: ["id","it.subito:id/bottom_bar_search","080d875b-2bbb-409a-81a8-93a84a902b42"]
[debug] [BaseDriver] Valid locator strategies for this request: xpath, id, class name, accessibility id, -android uiautomator
[debug] [BaseDriver] Waiting up to 0 ms for condition
[debug] [WD Proxy] Matched '/element' to command name 'findElement'
[debug] [WD Proxy] Proxying [POST /element] to [POST http://localhost:8204/wd/hub/session/f182fe9c-0c8c-492c-9435-a9e8e599eac8/element] with body: {"strategy":"id","selector":"it.subito:id/bottom_bar_search","context":"","multiple":false}
[debug] [WD Proxy] Got response with status 200: {"sessionId":"f182fe9c-0c8c-492c-9435-a9e8e599eac8","status":0,"value":{"ELEMENT":"c5b35f24-c7ad-4341-8fde-e231d5b7b326"}}
[debug] [W3C (080d875b)] Responding to client with driver.findElement() result: {"element-6066-11e4-a52e-4f735466cecf":"c5b35f24-c7ad-4341-8fde-e231d5b7b326","ELEMENT":"c5b35f24-c7ad-4341-8fde-e231d5b7b326"}
[HTTP] <-- POST /wd/hub/session/080d875b-2bbb-409a-81a8-93a84a902b42/element 200 838 ms - 137
[HTTP] 
[HTTP] --> GET /wd/hub/session/080d875b-2bbb-409a-81a8-93a84a902b42/element/c5b35f24-c7ad-4341-8fde-e231d5b7b326/displayed
[HTTP] {}
[debug] [W3C (080d875b)] Calling AppiumDriver.elementDisplayed() with args: ["c5b35f24-c7ad-4341-8fde-e231d5b7b326","080d875b-2bbb-409a-81a8-93a84a902b42"]
[debug] [WD Proxy] Matched '/element/c5b35f24-c7ad-4341-8fde-e231d5b7b326/attribute/displayed' to command name 'getAttribute'
[debug] [WD Proxy] Proxying [GET /element/c5b35f24-c7ad-4341-8fde-e231d5b7b326/attribute/displayed] to [GET http://localhost:8204/wd/hub/session/f182fe9c-0c8c-492c-9435-a9e8e599eac8/element/c5b35f24-c7ad-4341-8fde-e231d5b7b326/attribute/displayed] with body: {}
[debug] [WD Proxy] Got response with status 200: "{\"sessionId\":\"f182fe9c-0c8c-492c-9435-a9e8e599eac8\",\"status\":0,\"value\":\"true\"}"
[debug] [W3C (080d875b)] Responding to client with driver.elementDisplayed() result: true
[HTTP] <-- GET /wd/hub/session/080d875b-2bbb-409a-81a8-93a84a902b42/element/c5b35f24-c7ad-4341-8fde-e231d5b7b326/displayed 200 35 ms - 14
[HTTP] 
[HTTP] --> GET /wd/hub/session/080d875b-2bbb-409a-81a8-93a84a902b42/element/c5b35f24-c7ad-4341-8fde-e231d5b7b326/enabled
[HTTP] {}
[debug] [W3C (080d875b)] Calling AppiumDriver.elementEnabled() with args: ["c5b35f24-c7ad-4341-8fde-e231d5b7b326","080d875b-2bbb-409a-81a8-93a84a902b42"]
[debug] [WD Proxy] Matched '/element/c5b35f24-c7ad-4341-8fde-e231d5b7b326/attribute/enabled' to command name 'getAttribute'
[debug] [WD Proxy] Proxying [GET /element/c5b35f24-c7ad-4341-8fde-e231d5b7b326/attribute/enabled] to [GET http://localhost:8204/wd/hub/session/f182fe9c-0c8c-492c-9435-a9e8e599eac8/element/c5b35f24-c7ad-4341-8fde-e231d5b7b326/attribute/enabled] with body: {}
[debug] [WD Proxy] Got response with status 200: "{\"sessionId\":\"f182fe9c-0c8c-492c-9435-a9e8e599eac8\",\"status\":0,\"value\":\"true\"}"
[debug] [W3C (080d875b)] Responding to client with driver.elementEnabled() result: true
[HTTP] <-- GET /wd/hub/session/080d875b-2bbb-409a-81a8-93a84a902b42/element/c5b35f24-c7ad-4341-8fde-e231d5b7b326/enabled 200 30 ms - 14
[HTTP] 
[HTTP] --> POST /wd/hub/session/080d875b-2bbb-409a-81a8-93a84a902b42/touch/perform
[HTTP] {"actions":[{"action":"tap","options":{"element":"c5b35f24-c7ad-4341-8fde-e231d5b7b326","count":1}}],"sessionId":"080d875b-2bbb-409a-81a8-93a84a902b42"}
[debug] [W3C (080d875b)] Calling AppiumDriver.performTouch() with args: [[{"action":"tap","options":{"element":"c5b35f24-c7ad-4341-8fde-e231d5b7b326","count":1}}],"080d875b-2bbb-409a-81a8-93a84a902b42"]
[UiAutomator2] calling get location: c5b35f24-c7ad-4341-8fde-e231d5b7b326
[debug] [WD Proxy] Matched '/element/c5b35f24-c7ad-4341-8fde-e231d5b7b326/location' to command name 'getLocation'
[debug] [WD Proxy] Proxying [GET /element/c5b35f24-c7ad-4341-8fde-e231d5b7b326/location] to [GET http://localhost:8204/wd/hub/session/f182fe9c-0c8c-492c-9435-a9e8e599eac8/element/c5b35f24-c7ad-4341-8fde-e231d5b7b326/location] with body: {}
[debug] [WD Proxy] Got response with status 200: "{\"sessionId\":\"f182fe9c-0c8c-492c-9435-a9e8e599eac8\",\"status\":0,\"value\":{\"x\":3,\"y\":1183}}"
[debug] [WD Proxy] Matched '/element/c5b35f24-c7ad-4341-8fde-e231d5b7b326/size' to command name 'getSize'
[debug] [WD Proxy] Proxying [GET /element/c5b35f24-c7ad-4341-8fde-e231d5b7b326/size] to [GET http://localhost:8204/wd/hub/session/f182fe9c-0c8c-492c-9435-a9e8e599eac8/element/c5b35f24-c7ad-4341-8fde-e231d5b7b326/size] with body: {}
[debug] [WD Proxy] Got response with status 200: "{\"sessionId\":\"f182fe9c-0c8c-492c-9435-a9e8e599eac8\",\"status\":0,\"value\":{\"width\":140,\"height\":88}}"
[debug] [WD Proxy] Matched '/element/c5b35f24-c7ad-4341-8fde-e231d5b7b326/click' to command name 'click'
[debug] [WD Proxy] Proxying [POST /element/c5b35f24-c7ad-4341-8fde-e231d5b7b326/click] to [POST http://localhost:8204/wd/hub/session/f182fe9c-0c8c-492c-9435-a9e8e599eac8/element/c5b35f24-c7ad-4341-8fde-e231d5b7b326/click] with body: {"element":"c5b35f24-c7ad-4341-8fde-e231d5b7b326","x":73,"y":1227}
[debug] [WD Proxy] Got response with status 200: {"sessionId":"f182fe9c-0c8c-492c-9435-a9e8e599eac8","status":0,"value":true}
[debug] [W3C (080d875b)] Responding to client with driver.performTouch() result: null
[HTTP] <-- POST /wd/hub/session/080d875b-2bbb-409a-81a8-93a84a902b42/touch/perform 200 95 ms - 14
[HTTP] 
[HTTP] --> DELETE /wd/hub/session/080d875b-2bbb-409a-81a8-93a84a902b42
[HTTP] {}
[debug] [W3C (080d875b)] Calling AppiumDriver.deleteSession() with args: ["080d875b-2bbb-409a-81a8-93a84a902b42"]
[debug] [BaseDriver] Event 'quitSessionRequested' logged at 1553098961802 (17:22:41 GMT+0100 (Central European Standard Time))
[Appium] Removing session 080d875b-2bbb-409a-81a8-93a84a902b42 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://localhost:8204/wd/hub/session/f182fe9c-0c8c-492c-9435-a9e8e599eac8] with no body
[debug] [WD Proxy] Got response with status 200: "{\"sessionId\":\"f182fe9c-0c8c-492c-9435-a9e8e599eac8\",\"status\":0,\"value\":\"Session deleted\"}"
[debug] [ADB] Running '/Users/davide/Library/Android/sdk/platform-tools/adb -P 5037 -s 5200a44c612cb4ef shell am force-stop it.subito'
[debug] [Instrumentation] .
[debug] [Instrumentation] Time: 12,03
[debug] [Instrumentation] 
[debug] [Instrumentation] OK (1 test)
[debug] [Instrumentation] The process has exited with code 0
[debug] [Logcat] Stopping logcat capture
[debug] [ADB] Removing forwarded port socket connection: 8204 
[debug] [ADB] Running '/Users/davide/Library/Android/sdk/platform-tools/adb -P 5037 -s 5200a44c612cb4ef forward --remove tcp\:8204'
[debug] [BaseDriver] Event 'quitSessionFinished' logged at 1553098963080 (17:22:43 GMT+0100 (Central European Standard Time))
[debug] [W3C (080d875b)] Received response: null
[debug] [W3C (080d875b)] But deleting session, so not returning
[debug] [W3C (080d875b)] Responding to client with driver.deleteSession() result: null
[HTTP] <-- DELETE /wd/hub/session/080d875b-2bbb-409a-81a8-93a84a902b42 200 1281 ms - 14
[HTTP] ```

lubbo avatar Mar 20 '19 16:03 lubbo

The action is at Line 272 [HTTP] {"actions":[{"action":"press","options":{"x":444,"y":1092}},{"action":"wait","options":{"ms":600}},{"action":"moveTo","options":{"x":446,"y":400}},{"action":"release","options":{}}],"sessionId":"080d875b-2bbb-409a-81a8-93a84a902b42"}

lubbo avatar Mar 20 '19 16:03 lubbo

I forgot to say that:

  • I'm using Appium 1.12.0
  • I'm using Android 7 on a Samsung J5
  • If I leave automationName empty (old UiAutomator) it works.

lubbo avatar Mar 20 '19 16:03 lubbo

I am also facing same problem.

Swipe towards left doesn't work for iOS inside Appium Desktop using swipe by co-ordinates. Please help.

Note: Similar swipe is working in iOS simulator!

I am using: Appium: 1.11.0 iOS: 12.1 on iPhone XS simulator

gauravs802 avatar Mar 21 '19 10:03 gauravs802

Below are the full logs from a failed "Swipe By Coordinates" attempt on a real iPhone device in Appium Desktop. The swipe didn't scroll the screen vertically as expected.

Appium Desktop v1.11.0 Appium v1.10.0

Swipe By Coordinates Log:

2019-03-22 18:44:08:224 - [35m[HTTP][39m [37m-->[39m [37mPOST[39m [37m/wd/hub/session/34ab0888-cc52-4372-ad4e-ef6eab990a67/touch/perform[39m
2019-03-22 18:44:08:224 - [35m[HTTP][39m [90m{"actions":[{"action":"press","options":{"x":193,"y":585}},{"action":"moveTo","options":{"x":192,"y":291}},{"action":"release","options":{}}]}[39m
2019-03-22 18:44:08:224 - [debug] [35m[W3C (34ab0888)][39m Calling AppiumDriver.performTouch() with args: [[{"action":"press","options":{"x":193,"y":585}},{"action":"moveTo","options":{"x":192,"y":291}},{"action":"release","options":{}}],"34ab0888-cc52-4372-ad4e-ef6eab990a67"]
2019-03-22 18:44:08:225 - [debug] [35m[XCUITest][39m Executing command 'performTouch'
2019-03-22 18:44:08:225 - [debug] [35m[XCUITest][39m Received the following touch action: press(options={"x":193,"y":585})-moveTo(options={"x":192,"y":291})-release(options={})
2019-03-22 18:44:08:228 - [debug] [35m[JSONWP Proxy][39m Proxying [POST /wda/touch/perform] to [POST http://localhost:8100/session/9AA85CCA-C51C-4F7F-9EF2-45B53604505E/wda/touch/perform] with body: {"actions":[{"action":"press","options":{"x":193,"y":585}},{"action":"moveTo","options":{"x":192,"y":291}},{"action":"release","options":{}}]}
2019-03-22 18:44:08:635 - [debug] [35m[JSONWP Proxy][39m Got response with status 200: {"value":{},"sessionId":"9AA85CCA-C51C-4F7F-9EF2-45B53604505E","status":0}
2019-03-22 18:44:08:636 - [debug] [35m[W3C (34ab0888)][39m Responding to client with driver.performTouch() result: {}
2019-03-22 18:44:08:636 - [35m[HTTP][39m [37m<-- POST /wd/hub/session/34ab0888-cc52-4372-ad4e-ef6eab990a67/touch/perform [39m[32m200[39m [90m412 ms - 12[39m

Full log:

2019-03-22 18:43:28:999 - [35m[HTTP][39m [37m-->[39m [37mPOST[39m [37m/wd/hub/session[39m
2019-03-22 18:43:28:999 - [35m[HTTP][39m [90m{"desiredCapabilities":{"automationName":"XCuiTest","bundleId":"com.apple.Preferences","connectHardwareKeyboard":true,"deviceName":"Device - iPhone 6s Plus","launchTimeout":60000,"newCommandTimeout":0,"platformName":"ios","platformVersion":"12","se:CONFIG_UUID":"0c0ba1c2-5171-4255-9ebe-c6dae18ecce7","udid":"4eac46a54b384355dc56696fcc07bfd2700de57d","useJSONSource":true,"wdaLocalPort":8100},"capabilities":{"desiredCapabilities":{"automationName":"XCuiTest","bundleId":"com.apple.Preferences","connectHardwareKeyboard":true,"deviceName":"Device - iPhone 6s Plus","launchTimeout":60000,"newCommandTimeout":0,"platformName":"ios","platformVersion":"12","se:CONFIG_UUID":"0c0ba1c2-5171-4255-9ebe-c6dae18ecce7","udid":"4eac46a54b384355dc56696fcc07bfd2700de57d","useJSONSource":true,"wdaLocalPort":8100},"firstMatch":[{"platformName":"ios","se:CONFIG_UUID":"0c0ba1c2-5171-4255-9ebe-c6dae18ecce7"}]}}[39m
2019-03-22 18:43:28:999 - [debug] [35m[W3C][39m Calling AppiumDriver.createSession() with args: [{"automationName":"XCuiTest","bundleId":"com.apple.Preferences","connectHardwareKeyboard":true,"deviceName":"Device - iPhone 6s Plus","launchTimeout":60000,"newCommandTimeout":0,"platformName":"ios","platformVersion":"12","se:CONFIG_UUID":"0c0ba1c2-5171-4255-9ebe-c6dae18ecce7","udid":"4eac46a54b384355dc56696fcc07bfd2700de57d","useJSONSource":true,"wdaLocalPort":8100},null,{"desiredCapabilities":{"automationName":"XCuiTest","bundleId":"com.apple.Preferences","connectHardwareKeyboard":true,"deviceName":"Device - iPhone 6s Plus","launchTimeout":60000,"newCommandTimeout":0,"platformName":"ios","platformVersion":"12","se:CONFIG_UUID":"0c0ba1c2-5171-4255-9ebe-c6dae18ecce7","udid":"4eac46a54b384355dc56696fcc07bfd2700de57d","useJSONSource":true,"wdaLocalPort":8100},"firstMatch":[{"platformName":"ios","se:CONFIG_UUID":"0c0ba1c2-5171-4255-9ebe-c6dae18ecce7"}]}]
2019-03-22 18:43:29:000 - [debug] [35m[BaseDriver][39m Event 'newSessionRequested' logged at 1553280209000 (13:43:29 GMT-0500 (CDT))
2019-03-22 18:43:29:001 - [35m[Appium][39m Could not parse W3C capabilities: 'deviceName' can't be blank
2019-03-22 18:43:29:001 - [35m[Appium][39m Trying to fix W3C capabilities by merging them with JSONWP caps
2019-03-22 18:43:29:002 - [35m[BaseDriver][39m The capabilities ["automationName","bundleId","connectHardwareKeyboard","deviceName","launchTimeout","newCommandTimeout","platformVersion","udid","useJSONSource","wdaLocalPort"] are not standard capabilities and should have an extension prefix
2019-03-22 18:43:29:003 - [35m[Appium][39m Creating new XCUITestDriver (v2.92.0) session
2019-03-22 18:43:29:003 - [35m[Appium][39m Capabilities:
2019-03-22 18:43:29:004 - [35m[Appium][39m   automationName: XCuiTest
2019-03-22 18:43:29:004 - [35m[Appium][39m   bundleId: com.apple.Preferences
2019-03-22 18:43:29:004 - [35m[Appium][39m   connectHardwareKeyboard: true
2019-03-22 18:43:29:004 - [35m[Appium][39m   deviceName: Device - iPhone 6s Plus
2019-03-22 18:43:29:004 - [35m[Appium][39m   launchTimeout: 60000
2019-03-22 18:43:29:004 - [35m[Appium][39m   newCommandTimeout: 0
2019-03-22 18:43:29:004 - [35m[Appium][39m   platformVersion: 12
2019-03-22 18:43:29:005 - [35m[Appium][39m   udid: 4eac46a54b384355dc56696fcc07bfd2700de57d
2019-03-22 18:43:29:005 - [35m[Appium][39m   useJSONSource: true
2019-03-22 18:43:29:005 - [35m[Appium][39m   wdaLocalPort: 8100
2019-03-22 18:43:29:005 - [35m[Appium][39m   platformName: ios
2019-03-22 18:43:29:005 - [35m[Appium][39m   se:CONFIG_UUID: 0c0ba1c2-5171-4255-9ebe-c6dae18ecce7
2019-03-22 18:43:29:008 - [debug] [35m[BaseDriver][39m W3C capabilities {"alwaysMatch":{"appium:aut... and MJSONWP desired capabilities {"automationName":"XCuiTest... were provided
2019-03-22 18:43:29:008 - [debug] [35m[BaseDriver][39m Creating session with W3C capabilities: {"alwaysMatch":{"appium:aut...
2019-03-22 18:43:29:013 - [35m[BaseDriver][39m The following capabilities were provided, but are not recognized by appium: se:CONFIG_UUID.
2019-03-22 18:43:29:014 - [35m[BaseDriver][39m Session created with session id: 34ab0888-cc52-4372-ad4e-ef6eab990a67
2019-03-22 18:43:29:065 - [debug] [35m[XCUITest][39m Current user: 'i6sautomation'
2019-03-22 18:43:29:671 - [debug] [35m[XCUITest][39m Xcode version set to '10.1' (tools v10.1.0.0.1.1539992718)
2019-03-22 18:43:29:671 - [debug] [35m[XCUITest][39m iOS SDK Version set to '12.1'
2019-03-22 18:43:29:672 - [debug] [35m[BaseDriver][39m Event 'xcodeDetailsRetrieved' logged at 1553280209672 (13:43:29 GMT-0500 (CDT))
2019-03-22 18:43:31:800 - [35m[HTTP][39m [37m-->[39m [37mGET[39m [37m/wd/hub/status[39m
2019-03-22 18:43:31:800 - [35m[HTTP][39m [90m{}[39m
2019-03-22 18:43:31:801 - [debug] [35m[GENERIC][39m Calling AppiumDriver.getStatus() with args: []
2019-03-22 18:43:31:801 - [debug] [35m[GENERIC][39m Responding to client with driver.getStatus() result: {"build":{"version":"1.10.0"}}
2019-03-22 18:43:31:802 - [35m[HTTP][39m [37m<-- GET /wd/hub/status [39m[32m200[39m [90m1 ms - 68[39m
2019-03-22 18:43:31:802 - [35m[HTTP][39m [90m[39m
2019-03-22 18:43:32:394 - [debug] [35m[XCUITest][39m Available devices: 1392a416606863e2338efbfe46f8a2eb6ef39b96, 4eac46a54b384355dc56696fcc07bfd2700de57d
2019-03-22 18:43:32:394 - [debug] [35m[XCUITest][39m Creating iDevice object with udid '4eac46a54b384355dc56696fcc07bfd2700de57d'
2019-03-22 18:43:32:395 - [35m[XCUITest][39m Determining device to run tests on: udid: '4eac46a54b384355dc56696fcc07bfd2700de57d', real device: true
2019-03-22 18:43:32:395 - [debug] [35m[BaseDriver][39m Event 'appConfigured' logged at 1553280212395 (13:43:32 GMT-0500 (CDT))
2019-03-22 18:43:32:395 - [debug] [35m[BaseDriver][39m Event 'resetStarted' logged at 1553280212395 (13:43:32 GMT-0500 (CDT))
2019-03-22 18:43:32:395 - [debug] [35m[XCUITest][39m Reset: running ios real device reset flow
2019-03-22 18:43:32:395 - [debug] [35m[BaseDriver][39m Event 'resetComplete' logged at 1553280212395 (13:43:32 GMT-0500 (CDT))
2019-03-22 18:43:32:396 - [debug] [35m[iOSLog][39m Attempting iOS device log capture via libimobiledevice idevicesyslog
2019-03-22 18:43:32:396 - [debug] [35m[iOSLog][39m Starting iOS device log capture with: 'idevicesyslog'
2019-03-22 18:43:32:600 - [debug] [35m[XCUITest][39m Crash reports root '/Users/i6sautomation/Library/Logs/CrashReporter/MobileDevice/iPhone 6s Plus' does not exist. Got nothing to gather.
2019-03-22 18:43:32:601 - [debug] [35m[BaseDriver][39m Event 'logCaptureStarted' logged at 1553280212601 (13:43:32 GMT-0500 (CDT))
2019-03-22 18:43:32:601 - [35m[XCUITest][39m Setting up real device
2019-03-22 18:43:33:059 - [35m[XCUITest][39m Using WDA path: '/usr/local/lib/node_modules/appium-xcuitest-driver/WebDriverAgent'
2019-03-22 18:43:33:059 - [35m[XCUITest][39m Using WDA agent: '/usr/local/lib/node_modules/appium-xcuitest-driver/WebDriverAgent/WebDriverAgent.xcodeproj'
2019-03-22 18:43:33:186 - [debug] [35m[XCUITest][39m No obsolete cached processes from previous WDA sessions listening on port 8100 have been found
2019-03-22 18:43:33:187 - [debug] [35m[JSONWP Proxy][39m Matched '/status' to command name 'getStatus'
2019-03-22 18:43:33:190 - [debug] [35m[JSONWP Proxy][39m Proxying [GET /status] to [GET http://localhost:8100/status] with no body
2019-03-22 18:43:33:200 - [debug] [35m[JSONWP Proxy][39m Got response with status 200: "{\n  \"value\" : {\n    \"state\" : \"success\",\n    \"os\" : {\n      \"name\" : \"iOS\",\n      \"version\" : \"12.1.4\",\n      \"sdkVersion\" : \"12.0\"\n    },\n    \"ios\" : {\n      \"simulatorVersion\" : \"12.1.4\",\n      \"ip\" : \"10.215.9.183\"\n    },\n    \"build\" : {\n      \"time\" : \"Mar 19 2019 08:21:24\",\n      \"productBundleIdentifier\" : \"com.facebook.WebDriverAgentRunner\"\n    }\n  },\n  \"sessionId\" : \"83F04F4B-B548-4906-BB4E-1A64FC79A6D6\",\n  \"status\" : 0\n}"
2019-03-22 18:43:33:200 - [35m[XCUITest][39m Will reuse previously cached WDA instance at 'http://localhost:8100/' with 'com.facebook.WebDriverAgentRunner'. Set the wdaLocalPort capability to a value different from 8100 if this is an undesired behavior.
2019-03-22 18:43:33:200 - [debug] [35m[XCUITest][39m Trying to start WebDriverAgent 1 times with 10000ms interval
2019-03-22 18:43:33:200 - [debug] [35m[BaseDriver][39m Event 'wdaStartAttempted' logged at 1553280213200 (13:43:33 GMT-0500 (CDT))
2019-03-22 18:43:33:200 - [35m[XCUITest][39m Using provided WebdriverAgent at 'http://localhost:8100/'
2019-03-22 18:43:33:201 - [debug] [35m[BaseDriver][39m Event 'wdaSessionAttempted' logged at 1553280213201 (13:43:33 GMT-0500 (CDT))
2019-03-22 18:43:33:201 - [debug] [35m[XCUITest][39m Sending createSession command to WDA
2019-03-22 18:43:33:201 - [debug] [35m[JSONWP Proxy][39m Matched '/status' to command name 'getStatus'
2019-03-22 18:43:33:202 - [debug] [35m[JSONWP Proxy][39m Proxying [GET /status] to [GET http://localhost:8100/status] with no body
2019-03-22 18:43:33:206 - [debug] [35m[JSONWP Proxy][39m Got response with status 200: "{\n  \"value\" : {\n    \"state\" : \"success\",\n    \"os\" : {\n      \"name\" : \"iOS\",\n      \"version\" : \"12.1.4\",\n      \"sdkVersion\" : \"12.0\"\n    },\n    \"ios\" : {\n      \"simulatorVersion\" : \"12.1.4\",\n      \"ip\" : \"10.215.9.183\"\n    },\n    \"build\" : {\n      \"time\" : \"Mar 19 2019 08:21:24\",\n      \"productBundleIdentifier\" : \"com.facebook.WebDriverAgentRunner\"\n    }\n  },\n  \"sessionId\" : \"83F04F4B-B548-4906-BB4E-1A64FC79A6D6\",\n  \"status\" : 0\n}"
2019-03-22 18:43:33:207 - [debug] [35m[JSONWP Proxy][39m Matched '/session' to command name 'createSession'
2019-03-22 18:43:33:207 - [debug] [35m[JSONWP Proxy][39m Proxying [POST /session] to [POST http://localhost:8100/session] with body: {"desiredCapabilities":{"bundleId":"com.apple.Preferences","arguments":[],"environment":{},"shouldWaitForQuiescence":true,"shouldUseTestManagerForVisibilityDetection":false,"maxTypingFrequency":60,"shouldUseSingletonTestManager":true}}
2019-03-22 18:43:33:228 - [35m[Xcode][39m     t = 275008.91s Open com.apple.Preferences
2019-03-22 18:43:33:228 - [35m[Xcode][39m     t = 275008.92s     Launch com.apple.Preferences
2019-03-22 18:43:33:228 - [35m[Xcode][39m 
2019-03-22 18:43:33:441 - [35m[Xcode][39m     t = 275009.13s         Wait for com.apple.Preferences to idle
2019-03-22 18:43:33:441 - [35m[Xcode][39m 
2019-03-22 18:43:35:029 - [35m[Xcode][39m     t = 275010.72s Find the Application 'com.apple.Preferences'
2019-03-22 18:43:35:029 - [35m[Xcode][39m     t = 275010.72s     Snapshot accessibility hierarchy for app with pid 2454
2019-03-22 18:43:35:029 - [35m[Xcode][39m 
2019-03-22 18:43:35:454 - [debug] [35m[JSONWP Proxy][39m Got response with status 200: {"value":{"sessionId":"9AA85CCA-C51C-4F7F-9EF2-45B53604505E","capabilities":{"device":"iphone","browserName":"Settings","sdkVersion":"12.1.4","CFBundleIdentifier":"com.apple.Preferences"}},"sessionId":"9AA85CCA-C51C-4F7F-9EF2-45B53604505E","status":0}
2019-03-22 18:43:35:454 - [debug] [35m[BaseDriver][39m Event 'wdaSessionStarted' logged at 1553280215454 (13:43:35 GMT-0500 (CDT))
2019-03-22 18:43:35:454 - [debug] [35m[BaseDriver][39m Event 'wdaStarted' logged at 1553280215454 (13:43:35 GMT-0500 (CDT))
2019-03-22 18:43:35:455 - [35m[XCUITest][39m Skipping setting of the initial display orientation. Set the "orientation" capability to either "LANDSCAPE" or "PORTRAIT", if this is an undesired behavior.
2019-03-22 18:43:35:455 - [debug] [35m[BaseDriver][39m Event 'orientationSet' logged at 1553280215455 (13:43:35 GMT-0500 (CDT))
2019-03-22 18:43:35:456 - [35m[Appium][39m New XCUITestDriver session created successfully, session 34ab0888-cc52-4372-ad4e-ef6eab990a67 added to master session list
2019-03-22 18:43:35:456 - [debug] [35m[BaseDriver][39m Event 'newSessionStarted' logged at 1553280215456 (13:43:35 GMT-0500 (CDT))
2019-03-22 18:43:35:456 - [debug] [35m[W3C (34ab0888)][39m Cached the protocol value 'W3C' for the new session 34ab0888-cc52-4372-ad4e-ef6eab990a67
2019-03-22 18:43:35:457 - [debug] [35m[W3C (34ab0888)][39m Responding to client with driver.createSession() result: {"capabilities":{"webStorageEnabled":false,"locationContextEnabled":false,"browserName":"","platform":"MAC","javascriptEnabled":true,"databaseEnabled":false,"takesScreenshot":true,"networkConnectionEnabled":false,"platformName":"ios","se:CONFIG_UUID":"0c0ba1c2-5171-4255-9ebe-c6dae18ecce7","automationName":"XCuiTest","bundleId":"com.apple.Preferences","connectHardwareKeyboard":true,"deviceName":"Device - iPhone 6s Plus","launchTimeout":60000,"newCommandTimeout":0,"platformVersion":"12","udid":"4eac46a54b384355dc56696fcc07bfd2700de57d","useJSONSource":true,"wdaLocalPort":8100}}
2019-03-22 18:43:35:457 - [35m[HTTP][39m [37m<-- POST /wd/hub/session [39m[32m200[39m [90m6458 ms - 643[39m
2019-03-22 18:43:35:457 - [35m[HTTP][39m [90m[39m
2019-03-22 18:43:35:533 - [35m[HTTP][39m [37m-->[39m [37mPOST[39m [37m/wd/hub/session/34ab0888-cc52-4372-ad4e-ef6eab990a67/context[39m
2019-03-22 18:43:35:533 - [35m[HTTP][39m [90m{"name":"NATIVE_APP"}[39m
2019-03-22 18:43:35:533 - [debug] [35m[W3C (34ab0888)][39m Calling AppiumDriver.setContext() with args: ["NATIVE_APP","34ab0888-cc52-4372-ad4e-ef6eab990a67"]
2019-03-22 18:43:35:534 - [debug] [35m[XCUITest][39m Executing command 'setContext'
2019-03-22 18:43:35:534 - [debug] [35m[iOS][39m Attempting to set context to 'NATIVE_APP'
2019-03-22 18:43:35:534 - [debug] [35m[W3C (34ab0888)][39m Responding to client with driver.setContext() result: null
2019-03-22 18:43:35:536 - [35m[HTTP][39m [37m<-- POST /wd/hub/session/34ab0888-cc52-4372-ad4e-ef6eab990a67/context [39m[32m200[39m [90m2 ms - 14[39m
2019-03-22 18:43:35:536 - [35m[HTTP][39m [90m[39m
2019-03-22 18:43:36:667 - [35m[HTTP][39m [37m-->[39m [37mGET[39m [37m/wd/hub/session/34ab0888-cc52-4372-ad4e-ef6eab990a67/source[39m
2019-03-22 18:43:36:668 - [35m[HTTP][39m [90m{}[39m
2019-03-22 18:43:36:668 - [debug] [35m[W3C (34ab0888)][39m Calling AppiumDriver.getPageSource() with args: ["34ab0888-cc52-4372-ad4e-ef6eab990a67"]
2019-03-22 18:43:36:668 - [debug] [35m[XCUITest][39m Executing command 'getPageSource'
2019-03-22 18:43:36:670 - [debug] [35m[JSONWP Proxy][39m Matched '/source?format=json' to command name 'getPageSource'
2019-03-22 18:43:36:671 - [debug] [35m[JSONWP Proxy][39m Proxying [GET /source?format=json] to [GET http://localhost:8100/session/9AA85CCA-C51C-4F7F-9EF2-45B53604505E/source?format=json] with no body
2019-03-22 18:43:36:686 - [35m[Xcode][39m     t = 275012.38s Find the Application 'com.apple.Preferences'
2019-03-22 18:43:36:686 - [35m[Xcode][39m 
2019-03-22 18:43:36:689 - [35m[Xcode][39m     t = 275012.38s     Snapshot accessibility hierarchy for app with pid 2454
2019-03-22 18:43:36:689 - [35m[Xcode][39m 
2019-03-22 18:43:36:830 - [35m[HTTP][39m [37m-->[39m [37mGET[39m [37m/wd/hub/status[39m
2019-03-22 18:43:36:831 - [35m[HTTP][39m [90m{}[39m
2019-03-22 18:43:36:831 - [debug] [35m[GENERIC][39m Calling AppiumDriver.getStatus() with args: []
2019-03-22 18:43:36:831 - [debug] [35m[GENERIC][39m Responding to client with driver.getStatus() result: {"build":{"version":"1.10.0"}}
2019-03-22 18:43:36:832 - [35m[HTTP][39m [37m<-- GET /wd/hub/status [39m[32m200[39m [90m1 ms - 68[39m
2019-03-22 18:43:36:833 - [35m[HTTP][39m [90m[39m
2019-03-22 18:43:40:550 - [35m[HTTP][39m [37m-->[39m [37mGET[39m [37m/wd/hub/session/34ab0888-cc52-4372-ad4e-ef6eab990a67[39m
2019-03-22 18:43:40:551 - [35m[HTTP][39m [90m{}[39m
2019-03-22 18:43:40:553 - [debug] [35m[W3C (34ab0888)][39m Calling AppiumDriver.getSession() with args: ["34ab0888-cc52-4372-ad4e-ef6eab990a67"]
2019-03-22 18:43:40:553 - [debug] [35m[XCUITest][39m Executing command 'getSession'
2019-03-22 18:43:41:862 - [35m[HTTP][39m [37m-->[39m [37mGET[39m [37m/wd/hub/status[39m
2019-03-22 18:43:41:862 - [35m[HTTP][39m [90m{}[39m
2019-03-22 18:43:41:862 - [debug] [35m[GENERIC][39m Calling AppiumDriver.getStatus() with args: []
2019-03-22 18:43:41:862 - [debug] [35m[GENERIC][39m Responding to client with driver.getStatus() result: {"build":{"version":"1.10.0"}}
2019-03-22 18:43:41:863 - [35m[HTTP][39m [37m<-- GET /wd/hub/status [39m[32m200[39m [90m1 ms - 68[39m
2019-03-22 18:43:41:863 - [35m[HTTP][39m [90m[39m
2019-03-22 18:43:43:541 - [35m[Xcode][39m     t = 275019.23s Snapshot accessibility hierarchy for app with pid 2454
2019-03-22 18:43:43:542 - [35m[Xcode][39m 
2019-03-22 18:43:43:542 - [35m[Xcode][39m     t = 275019.23s Get all elements bound by accessibility element for: Elements matching predicate 'wdUID IN {"31000000-0000-0000-9609-000000000000", "32000000-0000-0000-9609-000000000000", "33000000-0000-0000-9609-000000000000"} AND (1 == 1 OR identifier == 0 OR frame == 0 OR value == 0 OR title == 0 OR label == 0 OR elementType == 0 OR enabled == 0 OR placeholderValue == 0)'
2019-03-22 18:43:43:543 - [35m[Xcode][39m 
2019-03-22 18:43:43:548 - [35m[Xcode][39m     t = 275019.24s     Snapshot accessibility hierarchy for app with pid 2454
2019-03-22 18:43:43:548 - [35m[Xcode][39m 
2019-03-22 18:43:43:758 - [35m[Xcode][39m     t = 275019.45s     Find: Descendants matching type Window
2019-03-22 18:43:43:758 - [35m[Xcode][39m 
2019-03-22 18:43:43:760 - [35m[Xcode][39m     t = 275019.45s     Find: Elements matching predicate 'wdUID IN {"31000000-0000-0000-9609-000000000000", "32000000-0000-0000-9609-000000000000", "33000000-0000-0000-9609-000000000000"} AND (1 == 1 OR identifier == 0 OR frame == 0 OR value == 0 OR title == 0 OR label == 0 OR elementType == 0 OR enabled == 0 OR placeholderValue == 0)'

2019-03-22 18:43:43:761 - [35m[Xcode][39m 
2019-03-22 18:43:43:766 - [35m[Xcode][39m     t = 275019.46s Snapshot accessibility hierarchy for app with pid 2454
2019-03-22 18:43:43:766 - [35m[Xcode][39m 
2019-03-22 18:43:43:968 - [35m[Xcode][39m     t = 275019.66s Find: Descendants matching type Window
2019-03-22 18:43:43:968 - [35m[Xcode][39m 
2019-03-22 18:43:43:969 - [35m[Xcode][39m     t = 275019.66s Find: Elements matching predicate 'wdUID IN {"31000000-0000-0000-9609-000000000000", "32000000-0000-0000-9609-000000000000", "33000000-0000-0000-9609-000000000000"} AND (1 == 1 OR identifier == 0 OR frame == 0 OR value == 0 OR title == 0 OR label == 0 OR elementType == 0 OR enabled == 0 OR placeholderValue == 0)'
2019-03-22 18:43:43:970 - [35m[Xcode][39m 
2019-03-22 18:43:43:971 - [35m[Xcode][39m     t = 275019.66s Find: Identity Binding
2019-03-22 18:43:43:972 - [35m[Xcode][39m 
2019-03-22 18:43:43:972 - [35m[Xcode][39m     t = 275019.66s Snapshot accessibility hierarchy for app with pid 2454
2019-03-22 18:43:43:973 - [35m[Xcode][39m 
2019-03-22 18:43:43:976 - [35m[Xcode][39m     t = 275019.67s Find: Descendants matching type Window
2019-03-22 18:43:43:976 - [35m[Xcode][39m 
2019-03-22 18:43:43:977 - [35m[Xcode][39m     t = 275019.67s Find: Elements matching predicate 'wdUID IN {"31000000-0000-0000-9609-000000000000", "32000000-0000-0000-9609-000000000000", "33000000-0000-0000-9609-000000000000"} AND (1 == 1 OR identifier == 0 OR frame == 0 OR value == 0 OR title == 0 OR label == 0 OR elementType == 0 OR enabled == 0 OR placeholderValue == 0)'
2019-03-22 18:43:43:977 - [35m[Xcode][39m 
2019-03-22 18:43:43:979 - [35m[Xcode][39m     t = 275019.67s Find: Identity Binding
2019-03-22 18:43:43:979 - [35m[Xcode][39m 
2019-03-22 18:43:43:984 - [35m[Xcode][39m     t = 275019.67s Snapshot accessibility hierarchy for app with pid 2454
2019-03-22 18:43:43:985 - [35m[Xcode][39m 
2019-03-22 18:43:44:184 - [35m[Xcode][39m     t = 275019.88s Find: Descendants matching type Window
2019-03-22 18:43:44:184 - [35m[Xcode][39m 
2019-03-22 18:43:44:187 - [35m[Xcode][39m     t = 275019.88s Find: Elements matching predicate 'wdUID IN {"31000000-0000-0000-9609-000000000000", "32000000-0000-0000-9609-000000000000", "33000000-0000-0000-9609-000000000000"} AND (1 == 1 OR identifier == 0 OR frame == 0 OR value == 0 OR title == 0 OR label == 0 OR elementType == 0 OR enabled == 0 OR placeholderValue == 0)'
2019-03-22 18:43:44:187 - [35m[Xcode][39m 
2019-03-22 18:43:44:188 - [35m[Xcode][39m     t = 275019.88s Find: Identity Binding
2019-03-22 18:43:44:188 - [35m[Xcode][39m 
2019-03-22 18:43:44:192 - [35m[Xcode][39m     t = 275019.88s Snapshot accessibility hierarchy for app with pid 2454
2019-03-22 18:43:44:192 - [35m[Xcode][39m 
2019-03-22 18:43:44:193 - [35m[Xcode][39m     t = 275019.88s Find: Descendants matching type Window
2019-03-22 18:43:44:193 - [35m[Xcode][39m 
2019-03-22 18:43:44:194 - [35m[Xcode][39m     t = 275019.89s Find: Elements matching predicate 'wdUID IN {"31000000-0000-0000-9609-000000000000", "32000000-0000-0000-9609-000000000000", "33000000-0000-0000-9609-000000000000"} AND (1 == 1 OR identifier == 0 OR frame == 0 OR value == 0 OR title == 0 OR label == 0 OR elementType == 0 OR enabled == 0 OR placeholderValue == 0)'
2019-03-22 18:43:44:194 - [35m[Xcode][39m 
2019-03-22 18:43:44:197 - [35m[Xcode][39m     t = 275019.89s Find: Identity Binding
2019-03-22 18:43:44:198 - [35m[Xcode][39m 
2019-03-22 18:43:44:201 - [35m[Xcode][39m     t = 275019.89s Snapshot accessibility hierarchy for app with pid 2454
2019-03-22 18:43:44:202 - [35m[Xcode][39m 
2019-03-22 18:43:44:402 - [35m[Xcode][39m     t = 275020.09s Find: Descendants matching type Window
2019-03-22 18:43:44:402 - [35m[Xcode][39m 
2019-03-22 18:43:44:404 - [35m[Xcode][39m     t = 275020.10s Find: Elements matching predicate 'wdUID IN {"31000000-0000-0000-9609-000000000000", "32000000-0000-0000-9609-000000000000", "33000000-0000-0000-9609-000000000000"} AND (1 == 1 OR identifier == 0 OR frame == 0 OR value == 0 OR title == 0 OR label == 0 OR elementType == 0 OR enabled == 0 OR placeholderValue == 0)'
2019-03-22 18:43:44:404 - [35m[Xcode][39m 
2019-03-22 18:43:44:405 - [35m[Xcode][39m     t = 275020.10s Find: Identity Binding
2019-03-22 18:43:44:405 - [35m[Xcode][39m 
2019-03-22 18:43:44:408 - [35m[Xcode][39m     t = 275020.10s Snapshot accessibility hierarchy for app with pid 2454
2019-03-22 18:43:44:408 - [35m[Xcode][39m 
2019-03-22 18:43:44:410 - [35m[Xcode][39m     t = 275020.10s Find: Descendants matching type Window
2019-03-22 18:43:44:410 - [35m[Xcode][39m 
2019-03-22 18:43:44:412 - [35m[Xcode][39m     t = 275020.10s Find: Elements matching predicate 'wdUID IN {"31000000-0000-0000-9609-000000000000", "32000000-0000-0000-9609-000000000000", "33000000-0000-0000-9609-000000000000"} AND (1 == 1 OR identifier == 0 OR frame == 0 OR value == 0 OR title == 0 OR label == 0 OR elementType == 0 OR enabled == 0 OR placeholderValue == 0)'
2019-03-22 18:43:44:412 - [35m[Xcode][39m 
2019-03-22 18:43:44:413 - [35m[Xcode][39m     t = 275020.11s Find: Identity Binding
2019-03-22 18:43:44:413 - [35m[Xcode][39m 
2019-03-22 18:43:44:415 - [35m[Xcode][39m     t = 275020.11s Find the Window
2019-03-22 18:43:44:415 - [35m[Xcode][39m 
2019-03-22 18:43:44:419 - [35m[Xcode][39m     t = 275020.11s     Snapshot accessibility hierarchy for app with pid 2454
2019-03-22 18:43:44:419 - [35m[Xcode][39m 
2019-03-22 18:43:44:621 - [35m[Xcode][39m     t = 275020.31s     Find: Descendants matching type Window
2019-03-22 18:43:44:622 - [35m[Xcode][39m 
2019-03-22 18:43:44:624 - [35m[Xcode][39m     t = 275020.32s     Find: Elements matching predicate 'wdUID IN {"31000000-0000-0000-9609-000000000000", "32000000-0000-0000-9609-000000000000", "33000000-0000-0000-9609-000000000000"} AND (1 == 1 OR identifier == 0 OR frame == 0 OR value == 0 OR title == 0 OR label == 0 OR elementType == 0 OR enabled == 0 OR placeholderValue == 0)'
2019-03-22 18:43:44:624 - [35m[Xcode][39m 
2019-03-22 18:43:44:626 - [35m[Xcode][39m     t = 275020.32s     Find: Identity Binding
2019-03-22 18:43:44:626 - [35m[Xcode][39m 
2019-03-22 18:43:45:551 - [35m[HTTP][39m [37m-->[39m [37mGET[39m [37m/wd/hub/session/34ab0888-cc52-4372-ad4e-ef6eab990a67[39m
2019-03-22 18:43:45:551 - [35m[HTTP][39m [90m{}[39m
2019-03-22 18:43:45:551 - [debug] [35m[W3C (34ab0888)][39m Calling AppiumDriver.getSession() with args: ["34ab0888-cc52-4372-ad4e-ef6eab990a67"]
2019-03-22 18:43:45:551 - [debug] [35m[XCUITest][39m Executing command 'getSession'
2019-03-22 18:43:46:892 - [35m[HTTP][39m [37m-->[39m [37mGET[39m [37m/wd/hub/status[39m
2019-03-22 18:43:46:893 - [35m[HTTP][39m [90m{}[39m
2019-03-22 18:43:46:893 - [debug] [35m[GENERIC][39m Calling AppiumDriver.getStatus() with args: []
2019-03-22 18:43:46:893 - [debug] [35m[GENERIC][39m Responding to client with driver.getStatus() result: {"build":{"version":"1.10.0"}}
2019-03-22 18:43:46:894 - [35m[HTTP][39m [37m<-- GET /wd/hub/status [39m[32m200[39m [90m1 ms - 68[39m
2019-03-22 18:43:46:894 - [35m[HTTP][39m [90m[39m
2019-03-22 18:43:50:546 - [35m[HTTP][39m [37m-->[39m [37mGET[39m [37m/wd/hub/session/34ab0888-cc52-4372-ad4e-ef6eab990a67[39m
2019-03-22 18:43:50:547 - [35m[HTTP][39m [90m{}[39m
2019-03-22 18:43:50:547 - [debug] [35m[W3C (34ab0888)][39m Calling AppiumDriver.getSession() with args: ["34ab0888-cc52-4372-ad4e-ef6eab990a67"]
2019-03-22 18:43:50:548 - [debug] [35m[XCUITest][39m Executing command 'getSession'
2019-03-22 18:43:51:262 - [35m[Xcode][39m     t = 275026.95s Find the Window
2019-03-22 18:43:51:262 - [35m[Xcode][39m 
2019-03-22 18:43:51:266 - [35m[Xcode][39m     t = 275026.96s     Snapshot accessibility hierarchy for app with pid 2454
2019-03-22 18:43:51:267 - [35m[Xcode][39m 
2019-03-22 18:43:51:464 - [35m[Xcode][39m     t = 275027.16s     Find: Descendants matching type Window
2019-03-22 18:43:51:465 - [35m[Xcode][39m 
2019-03-22 18:43:51:466 - [35m[Xcode][39m     t = 275027.16s     Find: Elements matching predicate 'wdUID IN {"31000000-0000-0000-9609-000000000000", "32000000-0000-0000-9609-000000000000", "33000000-0000-0000-9609-000000000000"} AND (1 == 1 OR identifier == 0 OR frame == 0 OR value == 0 OR title == 0 OR label == 0 OR elementType == 0 OR enabled == 0 OR placeholderValue == 0)'
2019-03-22 18:43:51:467 - [35m[Xcode][39m 
2019-03-22 18:43:51:468 - [35m[Xcode][39m     t = 275027.16s     Find: Identity Binding
2019-03-22 18:43:51:468 - [35m[Xcode][39m 
2019-03-22 18:43:51:533 - [35m[Xcode][39m     t = 275027.23s Find the Window
2019-03-22 18:43:51:534 - [35m[Xcode][39m 
2019-03-22 18:43:51:537 - [35m[Xcode][39m     t = 275027.23s     Snapshot accessibility hierarchy for app with pid 2454
2019-03-22 18:43:51:537 - [35m[Xcode][39m 
2019-03-22 18:43:51:742 - [35m[Xcode][39m     t = 275027.43s     Find: Descendants matching type Window
2019-03-22 18:43:51:743 - [35m[Xcode][39m 
2019-03-22 18:43:51:743 - [35m[Xcode][39m     t = 275027.44s     Find: Elements matching predicate 'wdUID IN {"31000000-0000-0000-9609-000000000000", "32000000-0000-0000-9609-000000000000", "33000000-0000-0000-9609-000000000000"} AND (1 == 1 OR identifier == 0 OR frame == 0 OR value == 0 OR title == 0 OR label == 0 OR elementType == 0 OR enabled == 0 OR placeholderValue == 0)'
2019-03-22 18:43:51:744 - [35m[Xcode][39m 
2019-03-22 18:43:51:746 - [35m[Xcode][39m     t = 275027.44s     Find: Identity Binding
2019-03-22 18:43:51:746 - [35m[Xcode][39m 
2019-03-22 18:43:51:798 - [debug] [35m[JSONWP Proxy][39m Got response with status 200: "{\n  \"value\" : {\n    \"isEnabled\" : \"1\",\n    \"isVisible\" : \"1\",\n    \"frame\" : \"{{0, 0}, {414, 736}}\",\n    \"children\" : [\n      {\n        \"isEnabled\" : \"1\",\n        \"isVisible\" : \"1\",\n        \"frame\" : \"{{0, 0}, {414, 736}}\",\n        \"children\" : [\n          {\n            \"isEnabled\" : \"1\",\n            \"isVisible\" : \"1\",\n            \"frame\" : \"{{0, 0}, {414, 736}}\",\n            \"children\" : [\n              {\n                \"isEnabled\" : \"1\",\n                \"isVisible\" : \"1\",\n                \"frame\" : \"{{0, 0}, {414, 736}}\",\n                \"children\" : [\n                  {\n                    \"isEnabled\" : \"1\",\n                    \"isVisible\" : \"1\",\n                    \"frame\" : \"{{0, 20}, {414, 96}}\",\n                    \"children\" : [\n                      {\n                        \"isEnabled\" : \"1\",\n                        \"isVisible\" : \"1\",\n                        \"frame\" : \"{{20, 67}, {134,...
2019-03-22 18:43:51:917 - [debug] [35m[JSONWP Proxy][39m Matched '/' to command name 'getSession'
2019-03-22 18:43:51:917 - [debug] [35m[JSONWP Proxy][39m Proxying [GET /] to [GET http://localhost:8100/session/9AA85CCA-C51C-4F7F-9EF2-45B53604505E] with no body
2019-03-22 18:43:51:919 - [debug] [35m[W3C (34ab0888)][39m Responding to client with driver.getPageSource() result: "<?xml version=\"1.0\" encoding=\"UTF-8\"?>\n<AppiumAUT>\n  <XCUIElementTypeApplication type=\"XCUIElementTypeApplication\" enabled=\"true\" visible=\"true\" x=\"0\" y=\"0\" width=\"414\" height=\"736\" name=\"Settings\" label=\"Settings\">\n    <XCUIElementTypeWindow type=\"XCUIElementTypeWindow\" enabled=\"true\" visible=\"true\" x=\"0\" y=\"0\" width=\"414\" height=\"736\">\n      <XCUIElementTypeOther type=\"XCUIElementTypeOther\" enabled=\"true\" visible=\"true\" x=\"0\" y=\"0\" width=\"414\" height=\"736\">\n        <XCUIElementTypeOther type=\"XCUIElementTypeOther\" enabled=\"true\" visible=\"true\" x=\"0\" y=\"0\" width=\"414\" height=\"736\">\n          <XCUIElementTypeNavigationBar type=\"XCUIElementTypeNavigationBar\" enabled=\"true\" visible=\"true\" x=\"0\" y=\"20\" width=\"414\" height=\"96\" name=\"Settings\">\n            <XCUIElementTypeOther type=\"XCUIElementTypeOther\" enabled=\"true\" visible=\"true\" x=\"20\" y=\"67\" width=\"134\" height=\"42\" name=\"Settings\" label=\"Settings\">\n...
2019-03-22 18:43:51:922 - [35m[HTTP][39m [37m<-- GET /wd/hub/session/34ab0888-cc52-4372-ad4e-ef6eab990a67/source [39m[32m200[39m [90m15252 ms - 68509[39m
2019-03-22 18:43:51:927 - [35m[HTTP][39m [90m[39m
2019-03-22 18:43:51:936 - [35m[HTTP][39m [37m-->[39m [37mGET[39m [37m/wd/hub/status[39m
2019-03-22 18:43:51:936 - [35m[HTTP][39m [90m{}[39m
2019-03-22 18:43:51:936 - [debug] [35m[GENERIC][39m Calling AppiumDriver.getStatus() with args: []
2019-03-22 18:43:51:936 - [debug] [35m[GENERIC][39m Responding to client with driver.getStatus() result: {"build":{"version":"1.10.0"}}
2019-03-22 18:43:51:938 - [35m[HTTP][39m [37m<-- GET /wd/hub/status [39m[32m200[39m [90m1 ms - 68[39m
2019-03-22 18:43:51:940 - [35m[HTTP][39m [90m[39m
2019-03-22 18:43:51:954 - [35m[Xcode][39m     t = 275027.64s Find the Application 'com.apple.Preferences'
2019-03-22 18:43:51:954 - [35m[Xcode][39m     t = 275027.64s     Snapshot accessibility hierarchy for app with pid 2454
2019-03-22 18:43:51:954 - [35m[Xcode][39m 
2019-03-22 18:43:52:076 - [debug] [35m[JSONWP Proxy][39m Got response with status 200: "{\n  \"value\" : {\n    \"sessionId\" : \"9AA85CCA-C51C-4F7F-9EF2-45B53604505E\",\n    \"capabilities\" : {\n      \"device\" : \"iphone\",\n      \"browserName\" : \"Settings\",\n      \"sdkVersion\" : \"12.1.4\",\n      \"CFBundleIdentifier\" : \"com.apple.Preferences\"\n    }\n  },\n  \"sessionId\" : \"9AA85CCA-C51C-4F7F-9EF2-45B53604505E\",\n  \"status\" : 0\n}"
2019-03-22 18:43:52:081 - [debug] [35m[JSONWP Proxy][39m Proxying [GET /wda/screen] to [GET http://localhost:8100/session/9AA85CCA-C51C-4F7F-9EF2-45B53604505E/wda/screen] with no body
2019-03-22 18:43:52:102 - [35m[Xcode][39m     t = 275027.79s Snapshot accessibility hierarchy for app with pid 2454
2019-03-22 18:43:52:102 - [35m[Xcode][39m 
2019-03-22 18:43:52:134 - [35m[HTTP][39m [37m-->[39m [37mGET[39m [37m/wd/hub/session/34ab0888-cc52-4372-ad4e-ef6eab990a67/screenshot[39m
2019-03-22 18:43:52:134 - [35m[HTTP][39m [90m{}[39m
2019-03-22 18:43:52:135 - [debug] [35m[W3C (34ab0888)][39m Calling AppiumDriver.getScreenshot() with args: ["34ab0888-cc52-4372-ad4e-ef6eab990a67"]
2019-03-22 18:43:52:135 - [debug] [35m[XCUITest][39m Executing command 'getScreenshot'
2019-03-22 18:43:52:219 - [35m[Xcode][39m     t = 275027.91s Find: Descendants matching type StatusBar
2019-03-22 18:43:52:220 - [35m[Xcode][39m 
2019-03-22 18:43:52:225 - [35m[Xcode][39m     t = 275027.91s Get all elements bound by accessibility element for: Descendants matching type StatusBar
2019-03-22 18:43:52:225 - [35m[Xcode][39m 
2019-03-22 18:43:52:226 - [35m[Xcode][39m     t = 275027.91s     Snapshot accessibility hierarchy for app with pid 2454
2019-03-22 18:43:52:226 - [35m[Xcode][39m 
2019-03-22 18:43:52:344 - [35m[Xcode][39m     t = 275028.03s     Find: Descendants matching type StatusBar
2019-03-22 18:43:52:344 - [35m[Xcode][39m 
2019-03-22 18:43:52:365 - [35m[Xcode][39m     t = 275028.05s Find the StatusBar
2019-03-22 18:43:52:365 - [35m[Xcode][39m     t = 275028.05s     Snapshot accessibility hierarchy for app with pid 2454
2019-03-22 18:43:52:365 - [35m[Xcode][39m 
2019-03-22 18:43:52:485 - [35m[Xcode][39m     t = 275028.18s     Find: Descendants matching type StatusBar
2019-03-22 18:43:52:485 - [35m[Xcode][39m 
2019-03-22 18:43:52:486 - [35m[Xcode][39m     t = 275028.18s     Find: Identity Binding
2019-03-22 18:43:52:486 - [35m[Xcode][39m 
2019-03-22 18:43:52:530 - [35m[Xcode][39m     t = 275028.22s Find the StatusBar
2019-03-22 18:43:52:530 - [35m[Xcode][39m 
2019-03-22 18:43:52:531 - [35m[Xcode][39m     t = 275028.22s     Snapshot accessibility hierarchy for app with pid 2454
2019-03-22 18:43:52:532 - [35m[Xcode][39m 
2019-03-22 18:43:52:650 - [35m[Xcode][39m     t = 275028.34s     Find: Descendants matching type StatusBar
2019-03-22 18:43:52:650 - [35m[Xcode][39m 
2019-03-22 18:43:52:652 - [35m[Xcode][39m     t = 275028.35s     Find: Identity Binding
2019-03-22 18:43:52:653 - [35m[Xcode][39m 
2019-03-22 18:43:52:660 - [debug] [35m[JSONWP Proxy][39m Got response with status 200: "{\n  \"value\" : {\n    \"statusBarSize\" : {\n      \"width\" : 414,\n      \"height\" : 20\n    },\n    \"scale\" : 2\n  },\n  \"sessionId\" : \"9AA85CCA-C51C-4F7F-9EF2-45B53604505E\",\n  \"status\" : 0\n}"
2019-03-22 18:43:52:663 - [debug] [35m[JSONWP Proxy][39m Proxying [GET /window/size] to [GET http://localhost:8100/session/9AA85CCA-C51C-4F7F-9EF2-45B53604505E/window/size] with no body
2019-03-22 18:43:52:678 - [35m[Xcode][39m     t = 275028.37s Snapshot accessibility hierarchy for app with pid 2454
2019-03-22 18:43:52:679 - [35m[Xcode][39m 
2019-03-22 18:43:52:952 - [debug] [35m[JSONWP Proxy][39m Got response with status 200: "{\n  \"value\" : {\n    \"width\" : 414,\n    \"height\" : 736\n  },\n  \"sessionId\" : \"9AA85CCA-C51C-4F7F-9EF2-45B53604505E\",\n  \"status\" : 0\n}"
2019-03-22 18:43:52:953 - [35m[XCUITest][39m Merging WDA caps over Appium caps for session detail response
2019-03-22 18:43:52:953 - [35m[XCUITest][39m Merging WDA caps over Appium caps for session detail response
2019-03-22 18:43:52:954 - [debug] [35m[W3C (34ab0888)][39m Responding to client with driver.getSession() result: {"udid":"4eac46a54b384355dc56696fcc07bfd2700de57d","platformName":"ios","se:CONFIG_UUID":"0c0ba1c2-5171-4255-9ebe-c6dae18ecce7","automationName":"XCuiTest","bundleId":"com.apple.Preferences","connectHardwareKeyboard":true,"deviceName":"Device - iPhone 6s Plus","launchTimeout":60000,"newCommandTimeout":0,"platformVersion":"12","useJSONSource":true,"wdaLocalPort":8100,"device":"iphone","browserName":"Settings","sdkVersion":"12.1.4","CFBundleIdentifier":"com.apple.Preferences","pixelRatio":2,"statBarHeight":20,"viewportRect":{"left":0,"top":40,"width":828,"height":1432}}
2019-03-22 18:43:52:954 - [35m[HTTP][39m [37m<-- GET /wd/hub/session/34ab0888-cc52-4372-ad4e-ef6eab990a67 [39m[32m200[39m [90m12403 ms - 584[39m
2019-03-22 18:43:52:954 - [35m[HTTP][39m [90m[39m
2019-03-22 18:43:52:954 - [35m[XCUITest][39m Merging WDA caps over Appium caps for session detail response
2019-03-22 18:43:52:955 - [debug] [35m[W3C (34ab0888)][39m Responding to client with driver.getSession() result: {"udid":"4eac46a54b384355dc56696fcc07bfd2700de57d","platformName":"ios","se:CONFIG_UUID":"0c0ba1c2-5171-4255-9ebe-c6dae18ecce7","automationName":"XCuiTest","bundleId":"com.apple.Preferences","connectHardwareKeyboard":true,"deviceName":"Device - iPhone 6s Plus","launchTimeout":60000,"newCommandTimeout":0,"platformVersion":"12","useJSONSource":true,"wdaLocalPort":8100,"device":"iphone","browserName":"Settings","sdkVersion":"12.1.4","CFBundleIdentifier":"com.apple.Preferences","pixelRatio":2,"statBarHeight":20,"viewportRect":{"left":0,"top":40,"width":828,"height":1432}}
2019-03-22 18:43:52:955 - [35m[HTTP][39m [37m<-- GET /wd/hub/session/34ab0888-cc52-4372-ad4e-ef6eab990a67 [39m[32m200[39m [90m7404 ms - 584[39m
2019-03-22 18:43:52:955 - [35m[HTTP][39m [90m[39m
2019-03-22 18:43:52:955 - [debug] [35m[XCUITest][39m Taking screenshot with WDA
2019-03-22 18:43:52:956 - [debug] [35m[JSONWP Proxy][39m Matched '/screenshot' to command name 'getScreenshot'
2019-03-22 18:43:52:956 - [debug] [35m[JSONWP Proxy][39m Proxying [GET /screenshot] to [GET http://localhost:8100/session/9AA85CCA-C51C-4F7F-9EF2-45B53604505E/screenshot] with no body
2019-03-22 18:43:52:957 - [debug] [35m[W3C (34ab0888)][39m Responding to client with driver.getSession() result: {"udid":"4eac46a54b384355dc56696fcc07bfd2700de57d","platformName":"ios","se:CONFIG_UUID":"0c0ba1c2-5171-4255-9ebe-c6dae18ecce7","automationName":"XCuiTest","bundleId":"com.apple.Preferences","connectHardwareKeyboard":true,"deviceName":"Device - iPhone 6s Plus","launchTimeout":60000,"newCommandTimeout":0,"platformVersion":"12","useJSONSource":true,"wdaLocalPort":8100,"device":"iphone","browserName":"Settings","sdkVersion":"12.1.4","CFBundleIdentifier":"com.apple.Preferences","pixelRatio":2,"statBarHeight":20,"viewportRect":{"left":0,"top":40,"width":828,"height":1432}}
2019-03-22 18:43:52:957 - [35m[HTTP][39m [37m<-- GET /wd/hub/session/34ab0888-cc52-4372-ad4e-ef6eab990a67 [39m[32m200[39m [90m2410 ms - 584[39m
2019-03-22 18:43:52:957 - [35m[HTTP][39m [90m[39m
2019-03-22 18:43:52:973 - [35m[Xcode][39m     t = 275028.66s Find the Application 'com.apple.Preferences'
2019-03-22 18:43:52:974 - [35m[Xcode][39m 
2019-03-22 18:43:52:975 - [35m[Xcode][39m     t = 275028.67s     Snapshot accessibility hierarchy for app with pid 2454
2019-03-22 18:43:52:975 - [35m[Xcode][39m 
2019-03-22 18:43:53:221 - [debug] [35m[JSONWP Proxy][39m Got response with status 200: "{\n  \"value\" : \"iVBORw0KGgoAAAANSUhEUgAABNoAAAigCAIAAABm8NjOAAAAAXNSR0IArs4c6QAA\\r\\nABxpRE9UAAAAAgAAAAAAAARQAAAAKAAABFAAAARQAALS3nNofGsAAEAASURBVHgB\\r\\n7J0HnCZFnb8RFXO68\\/ifOZ3ZM50RvRMTSg5yiCKSMwiCiiAiKGJAWHYn7S5RzF6S\\r\\nZDwBERHJeSMZcdm8O3k2zP+Z+ULdMDPvy7477+zM7Dzz6a2trq761a+e7q7qb1d3\\r\\nv5u0t3e6SEACEpCABCQgAQlIQAISmAwEOjq6WDo7uxMxrBeBgrSmo2iTmnKbWQIS\\r\\nkIAEJCABCUhAAhKQwMQlEPVVtFO9xJh2CtKajg3lqJPDEpCABCQgAQlIQAISkMBk\\r\\nIaAcHSXlrBydLKdQTfcbzCwBCUhAAhKQgAQkIAEJFALKUeWoulECEpCABCQgAQlI\\r\\nQAISkMAYEFCOKkfH4LArt0OMSEACEpCABCQgAQlIQAKTloByVDmqHJWABCQgAQlI\\r\\nQAISkIAEJDAGBJSjytExOOwm7e0fGy4BCUhAAhKQgAQkIAEJFALKUeWoclQCIyXA\\r\\nWdTW1sGS04kIXQyf8+rq6klfU9KzqXRARiQgAQlIQAISkIAEJjOBXCWWz8BmdWDI\\r\\nplxnEunpWU0ILkKuMxMnc1kdWHD8xHEPZ3IZjNsso+Fbd\\/eqlSvbgiKR1FLT0eUP\\r\\nvYxUF9WE28x1JJBugoM+XQOrnAYsRHJW5CTMeVjHejUlAQlIQAISkIAEJDBxCUQy\\r\\nRbAlPijkepIUMnAZ2dranpbmUpMwm1KETePwUhOX4idOFg+rtDdtqSnEbCihSFet\\r\\nWrNiRWsuyAuWQFuXUDmqHJ2oBHKC0UdwAnA+cLjn3k9Oj5KYbOtyMphHAhKQgAQk\\r\\nIAEJSGCjJxDJVEWeo...
2019-03-22 18:43:53:229 - [debug] [35m[W3C (34ab0888)][39m Responding to client with driver.getScreenshot() result: "iVBORw0KGgoAAAANSUhEUgAABNoAAAigCAIAAABm8NjOAAAAAXNSR0IArs4c6QAA\r\nABxpRE9UAAAAAgAAAAAAAARQAAAAKAAABFAAAARQAALS3nNofGsAAEAASURBVHgB\r\n7J0HnCZFnb8RFXO68/ifOZ3ZM50RvRMTSg5yiCKSMwiCiiAiKGJAWHYn7S5RzF6S\r\nZDwBERHJeSMZcdm8O3k2zP+Z+ULdMDPvy7477+zM7Dzz6a2trq761a+e7q7qb1d3\r\nv5u0t3e6SEACEpCABCQgAQlIQAISmAwEOjq6WDo7uxMxrBeBgrSmo2iTmnKbWQIS\r\nkIAEJCABCUhAAhKQwMQlEPVVtFO9xJh2CtKajg3lqJPDEpCABCQgAQlIQAISkMBk\r\nIaAcHSXlrBydLKdQTfcbzCwBCUhAAhKQgAQkIAEJFALKUeWoulECEpCABCQgAQlI\r\nQAISkMAYEFCOKkfH4LArt0OMSEACEpCABCQgAQlIQAKTloByVDmqHJWABCQgAQlI\r\nQAISkIAEJDAGBJSjytExOOwm7e0fGy4BCUhAAhKQgAQkIAEJFALKUeWoclQCIyXA\r\nWdTW1sGS04kIXQyf8+rq6klfU9KzqXRARiQgAQlIQAISkIAEJjOBXCWWz8BmdWDI\r\nplxnEunpWU0ILkKuMxMnc1kdWHD8xHEPZ3IZjNsso+Fbd/eqlSvbgiKR1FLT0eUP\r\nvYxUF9WE28x1JJBugoM+XQOrnAYsRHJW5CTMeVjHejUlAQlIQAISkIAEJDBxCUQy\r\nRbAlPijkepIUMnAZ2dranpbmUpMwm1KETePwUhOX4idOFg+rtDdtqSnEbCihSFet\r\nWrNiRWsuyAuWQFuXUDmqHJ2oBHKC0UdwAnA+cLjn3k9Oj5KYbOtyMphHAhKQgAQk\r\nIAEJSGCjJxDJVEWeoa+4qmRelJBLSnKSgsAjQgrFiUemIsbWrOnNJej4CfEQV/Et\r\n...
2019-03-22 18:43:53:234 - [35m[HTTP][39m [37m<-- GET /wd/hub/session/34ab0888-cc52-4372-ad4e-ef6eab990a67/screenshot [39m[32m200[39m [90m1099 ms - 575884[39m
2019-03-22 18:43:53:234 - [35m[HTTP][39m [90m[39m
2019-03-22 18:43:53:778 - [35m[HTTP][39m [37m-->[39m [37mGET[39m [37m/wd/hub/session/34ab0888-cc52-4372-ad4e-ef6eab990a67/window/current/size[39m
2019-03-22 18:43:53:778 - [35m[HTTP][39m [90m{}[39m
2019-03-22 18:43:53:778 - [debug] [35m[W3C (34ab0888)][39m Calling AppiumDriver.getWindowSize() with args: ["current","34ab0888-cc52-4372-ad4e-ef6eab990a67"]
2019-03-22 18:43:53:778 - [debug] [35m[XCUITest][39m Executing command 'getWindowSize'
2019-03-22 18:43:53:778 - [debug] [35m[W3C (34ab0888)][39m Responding to client with driver.getWindowSize() result: {"width":414,"height":736}
2019-03-22 18:43:53:780 - [35m[HTTP][39m [37m<-- GET /wd/hub/session/34ab0888-cc52-4372-ad4e-ef6eab990a67/window/current/size [39m[32m200[39m [90m1 ms - 36[39m
2019-03-22 18:43:53:780 - [35m[HTTP][39m [90m[39m
2019-03-22 18:43:55:549 - [35m[HTTP][39m [37m-->[39m [37mGET[39m [37m/wd/hub/session/34ab0888-cc52-4372-ad4e-ef6eab990a67[39m
2019-03-22 18:43:55:550 - [35m[HTTP][39m [90m{}[39m
2019-03-22 18:43:55:550 - [debug] [35m[W3C (34ab0888)][39m Calling AppiumDriver.getSession() with args: ["34ab0888-cc52-4372-ad4e-ef6eab990a67"]
2019-03-22 18:43:55:550 - [debug] [35m[XCUITest][39m Executing command 'getSession'
2019-03-22 18:43:55:550 - [35m[XCUITest][39m Merging WDA caps over Appium caps for session detail response
2019-03-22 18:43:55:551 - [debug] [35m[W3C (34ab0888)][39m Responding to client with driver.getSession() result: {"udid":"4eac46a54b384355dc56696fcc07bfd2700de57d","platformName":"ios","se:CONFIG_UUID":"0c0ba1c2-5171-4255-9ebe-c6dae18ecce7","automationName":"XCuiTest","bundleId":"com.apple.Preferences","connectHardwareKeyboard":true,"deviceName":"Device - iPhone 6s Plus","launchTimeout":60000,"newCommandTimeout":0,"platformVersion":"12","useJSONSource":true,"wdaLocalPort":8100,"device":"iphone","browserName":"Settings","sdkVersion":"12.1.4","CFBundleIdentifier":"com.apple.Preferences","pixelRatio":2,"statBarHeight":20,"viewportRect":{"left":0,"top":40,"width":828,"height":1432}}
2019-03-22 18:43:55:551 - [35m[HTTP][39m [37m<-- GET /wd/hub/session/34ab0888-cc52-4372-ad4e-ef6eab990a67 [39m[32m200[39m [90m1 ms - 584[39m
2019-03-22 18:43:55:551 - [35m[HTTP][39m [90m[39m
2019-03-22 18:43:56:966 - [35m[HTTP][39m [37m-->[39m [37mGET[39m [37m/wd/hub/status[39m
2019-03-22 18:43:56:967 - [35m[HTTP][39m [90m{}[39m
2019-03-22 18:43:56:967 - [debug] [35m[GENERIC][39m Calling AppiumDriver.getStatus() with args: []
2019-03-22 18:43:56:967 - [debug] [35m[GENERIC][39m Responding to client with driver.getStatus() result: {"build":{"version":"1.10.0"}}
2019-03-22 18:43:56:967 - [35m[HTTP][39m [37m<-- GET /wd/hub/status [39m[32m200[39m [90m1 ms - 68[39m
2019-03-22 18:43:56:968 - [35m[HTTP][39m [90m[39m
2019-03-22 18:44:00:543 - [35m[HTTP][39m [37m-->[39m [37mGET[39m [37m/wd/hub/session/34ab0888-cc52-4372-ad4e-ef6eab990a67[39m
2019-03-22 18:44:00:543 - [35m[HTTP][39m [90m{}[39m
2019-03-22 18:44:00:544 - [debug] [35m[W3C (34ab0888)][39m Calling AppiumDriver.getSession() with args: ["34ab0888-cc52-4372-ad4e-ef6eab990a67"]
2019-03-22 18:44:00:544 - [debug] [35m[XCUITest][39m Executing command 'getSession'
2019-03-22 18:44:00:545 - [35m[XCUITest][39m Merging WDA caps over Appium caps for session detail response
2019-03-22 18:44:00:545 - [debug] [35m[W3C (34ab0888)][39m Responding to client with driver.getSession() result: {"udid":"4eac46a54b384355dc56696fcc07bfd2700de57d","platformName":"ios","se:CONFIG_UUID":"0c0ba1c2-5171-4255-9ebe-c6dae18ecce7","automationName":"XCuiTest","bundleId":"com.apple.Preferences","connectHardwareKeyboard":true,"deviceName":"Device - iPhone 6s Plus","launchTimeout":60000,"newCommandTimeout":0,"platformVersion":"12","useJSONSource":true,"wdaLocalPort":8100,"device":"iphone","browserName":"Settings","sdkVersion":"12.1.4","CFBundleIdentifier":"com.apple.Preferences","pixelRatio":2,"statBarHeight":20,"viewportRect":{"left":0,"top":40,"width":828,"height":1432}}
2019-03-22 18:44:00:546 - [35m[HTTP][39m [37m<-- GET /wd/hub/session/34ab0888-cc52-4372-ad4e-ef6eab990a67 [39m[32m200[39m [90m2 ms - 584[39m
2019-03-22 18:44:00:546 - [35m[HTTP][39m [90m[39m
2019-03-22 18:44:01:996 - [35m[HTTP][39m [37m-->[39m [37mGET[39m [37m/wd/hub/status[39m
2019-03-22 18:44:01:996 - [35m[HTTP][39m [90m{}[39m
2019-03-22 18:44:01:997 - [debug] [35m[GENERIC][39m Calling AppiumDriver.getStatus() with args: []
2019-03-22 18:44:01:997 - [debug] [35m[GENERIC][39m Responding to client with driver.getStatus() result: {"build":{"version":"1.10.0"}}
2019-03-22 18:44:01:998 - [35m[HTTP][39m [37m<-- GET /wd/hub/status [39m[32m200[39m [90m1 ms - 68[39m
2019-03-22 18:44:01:998 - [35m[HTTP][39m [90m[39m
2019-03-22 18:44:05:550 - [35m[HTTP][39m [37m-->[39m [37mGET[39m [37m/wd/hub/session/34ab0888-cc52-4372-ad4e-ef6eab990a67[39m
2019-03-22 18:44:05:550 - [35m[HTTP][39m [90m{}[39m
2019-03-22 18:44:05:550 - [debug] [35m[W3C (34ab0888)][39m Calling AppiumDriver.getSession() with args: ["34ab0888-cc52-4372-ad4e-ef6eab990a67"]
2019-03-22 18:44:05:550 - [debug] [35m[XCUITest][39m Executing command 'getSession'
2019-03-22 18:44:05:550 - [35m[XCUITest][39m Merging WDA caps over Appium caps for session detail response
2019-03-22 18:44:05:551 - [debug] [35m[W3C (34ab0888)][39m Responding to client with driver.getSession() result: {"udid":"4eac46a54b384355dc56696fcc07bfd2700de57d","platformName":"ios","se:CONFIG_UUID":"0c0ba1c2-5171-4255-9ebe-c6dae18ecce7","automationName":"XCuiTest","bundleId":"com.apple.Preferences","connectHardwareKeyboard":true,"deviceName":"Device - iPhone 6s Plus","launchTimeout":60000,"newCommandTimeout":0,"platformVersion":"12","useJSONSource":true,"wdaLocalPort":8100,"device":"iphone","browserName":"Settings","sdkVersion":"12.1.4","CFBundleIdentifier":"com.apple.Preferences","pixelRatio":2,"statBarHeight":20,"viewportRect":{"left":0,"top":40,"width":828,"height":1432}}
2019-03-22 18:44:05:551 - [35m[HTTP][39m [37m<-- GET /wd/hub/session/34ab0888-cc52-4372-ad4e-ef6eab990a67 [39m[32m200[39m [90m1 ms - 584[39m
2019-03-22 18:44:05:551 - [35m[HTTP][39m [90m[39m
2019-03-22 18:44:07:026 - [35m[HTTP][39m [37m-->[39m [37mGET[39m [37m/wd/hub/status[39m
2019-03-22 18:44:07:026 - [35m[HTTP][39m [90m{}[39m
2019-03-22 18:44:07:026 - [debug] [35m[GENERIC][39m Calling AppiumDriver.getStatus() with args: []
2019-03-22 18:44:07:026 - [debug] [35m[GENERIC][39m Responding to client with driver.getStatus() result: {"build":{"version":"1.10.0"}}
2019-03-22 18:44:07:027 - [35m[HTTP][39m [37m<-- GET /wd/hub/status [39m[32m200[39m [90m1 ms - 68[39m
2019-03-22 18:44:07:027 - [35m[HTTP][39m [90m[39m
2019-03-22 18:44:08:224 - [35m[HTTP][39m [37m-->[39m [37mPOST[39m [37m/wd/hub/session/34ab0888-cc52-4372-ad4e-ef6eab990a67/touch/perform[39m
2019-03-22 18:44:08:224 - [35m[HTTP][39m [90m{"actions":[{"action":"press","options":{"x":193,"y":585}},{"action":"moveTo","options":{"x":192,"y":291}},{"action":"release","options":{}}]}[39m
2019-03-22 18:44:08:224 - [debug] [35m[W3C (34ab0888)][39m Calling AppiumDriver.performTouch() with args: [[{"action":"press","options":{"x":193,"y":585}},{"action":"moveTo","options":{"x":192,"y":291}},{"action":"release","options":{}}],"34ab0888-cc52-4372-ad4e-ef6eab990a67"]
2019-03-22 18:44:08:225 - [debug] [35m[XCUITest][39m Executing command 'performTouch'
2019-03-22 18:44:08:225 - [debug] [35m[XCUITest][39m Received the following touch action: press(options={"x":193,"y":585})-moveTo(options={"x":192,"y":291})-release(options={})
2019-03-22 18:44:08:228 - [debug] [35m[JSONWP Proxy][39m Proxying [POST /wda/touch/perform] to [POST http://localhost:8100/session/9AA85CCA-C51C-4F7F-9EF2-45B53604505E/wda/touch/perform] with body: {"actions":[{"action":"press","options":{"x":193,"y":585}},{"action":"moveTo","options":{"x":192,"y":291}},{"action":"release","options":{}}]}
2019-03-22 18:44:08:264 - [35m[Xcode][39m     t = 275043.96s Find the Application 'com.apple.Preferences'
2019-03-22 18:44:08:264 - [35m[Xcode][39m 
2019-03-22 18:44:08:270 - [35m[Xcode][39m     t = 275043.96s     Snapshot accessibility hierarchy for app with pid 2454
2019-03-22 18:44:08:271 - [35m[Xcode][39m 
2019-03-22 18:44:08:402 - [35m[Xcode][39m     t = 275044.09s Find the Application 'com.apple.Preferences'
2019-03-22 18:44:08:402 - [35m[Xcode][39m 
2019-03-22 18:44:08:406 - [35m[Xcode][39m     t = 275044.10s     Snapshot accessibility hierarchy for app with pid 2454
2019-03-22 18:44:08:406 - [35m[Xcode][39m 
2019-03-22 18:44:08:635 - [debug] [35m[JSONWP Proxy][39m Got response with status 200: {"value":{},"sessionId":"9AA85CCA-C51C-4F7F-9EF2-45B53604505E","status":0}
2019-03-22 18:44:08:636 - [debug] [35m[W3C (34ab0888)][39m Responding to client with driver.performTouch() result: {}
2019-03-22 18:44:08:636 - [35m[HTTP][39m [37m<-- POST /wd/hub/session/34ab0888-cc52-4372-ad4e-ef6eab990a67/touch/perform [39m[32m200[39m [90m412 ms - 12[39m
2019-03-22 18:44:08:636 - [35m[HTTP][39m [90m[39m
2019-03-22 18:44:09:235 - [35m[HTTP][39m [37m-->[39m [37mGET[39m [37m/wd/hub/session/34ab0888-cc52-4372-ad4e-ef6eab990a67/source[39m
2019-03-22 18:44:09:235 - [35m[HTTP][39m [90m{}[39m
2019-03-22 18:44:09:236 - [debug] [35m[W3C (34ab0888)][39m Calling AppiumDriver.getPageSource() with args: ["34ab0888-cc52-4372-ad4e-ef6eab990a67"]
2019-03-22 18:44:09:236 - [debug] [35m[XCUITest][39m Executing command 'getPageSource'
2019-03-22 18:44:09:238 - [debug] [35m[JSONWP Proxy][39m Matched '/source?format=json' to command name 'getPageSource'
2019-03-22 18:44:09:238 - [debug] [35m[JSONWP Proxy][39m Proxying [GET /source?format=json] to [GET http://localhost:8100/session/9AA85CCA-C51C-4F7F-9EF2-45B53604505E/source?format=json] with no body
2019-03-22 18:44:09:273 - [35m[Xcode][39m     t = 275044.97s Find the Application 'com.apple.Preferences'
2019-03-22 18:44:09:273 - [35m[Xcode][39m 
2019-03-22 18:44:09:279 - [35m[Xcode][39m     t = 275044.97s     Snapshot accessibility hierarchy for app with pid 2454
2019-03-22 18:44:09:280 - [35m[Xcode][39m 
2019-03-22 18:44:10:563 - [35m[HTTP][39m [37m-->[39m [37mGET[39m [37m/wd/hub/session/34ab0888-cc52-4372-ad4e-ef6eab990a67[39m
2019-03-22 18:44:10:563 - [35m[HTTP][39m [90m{}[39m
2019-03-22 18:44:10:563 - [debug] [35m[W3C (34ab0888)][39m Calling AppiumDriver.getSession() with args: ["34ab0888-cc52-4372-ad4e-ef6eab990a67"]
2019-03-22 18:44:10:563 - [debug] [35m[XCUITest][39m Executing command 'getSession'
2019-03-22 18:44:12:056 - [35m[HTTP][39m [37m-->[39m [37mGET[39m [37m/wd/hub/status[39m
2019-03-22 18:44:12:056 - [35m[HTTP][39m [90m{}[39m
2019-03-22 18:44:12:056 - [debug] [35m[GENERIC][39m Calling AppiumDriver.getStatus() with args: []
2019-03-22 18:44:12:056 - [debug] [35m[GENERIC][39m Responding to client with driver.getStatus() result: {"build":{"version":"1.10.0"}}
2019-03-22 18:44:12:057 - [35m[HTTP][39m [37m<-- GET /wd/hub/status [39m[32m200[39m [90m1 ms - 68[39m
2019-03-22 18:44:12:057 - [35m[HTTP][39m [90m[39m
2019-03-22 18:44:15:566 - [35m[HTTP][39m [37m-->[39m [37mGET[39m [37m/wd/hub/session/34ab0888-cc52-4372-ad4e-ef6eab990a67[39m
2019-03-22 18:44:15:566 - [35m[HTTP][39m [90m{}[39m
2019-03-22 18:44:15:566 - [debug] [35m[W3C (34ab0888)][39m Calling AppiumDriver.getSession() with args: ["34ab0888-cc52-4372-ad4e-ef6eab990a67"]
2019-03-22 18:44:15:566 - [debug] [35m[XCUITest][39m Executing command 'getSession'
2019-03-22 18:44:16:052 - [35m[Xcode][39m     t = 275051.74s Snapshot accessibility hierarchy for app with pid 2454
2019-03-22 18:44:16:052 - [35m[Xcode][39m 
2019-03-22 18:44:16:053 - [35m[Xcode][39m     t = 275051.75s Get all elements bound by accessibility element for: Elements matching predicate 'wdUID IN {"31000000-0000-0000-9609-000000000000", "32000000-0000-0000-9609-000000000000", "33000000-0000-0000-9609-000000000000"} AND (1 == 1 OR identifier == 0 OR frame == 0 OR value == 0 OR title == 0 OR label == 0 OR elementType == 0 OR enabled == 0 OR placeholderValue == 0)'
2019-03-22 18:44:16:054 - [35m[Xcode][39m 
2019-03-22 18:44:16:058 - [35m[Xcode][39m     t = 275051.75s     Snapshot accessibility hierarchy for app with pid 2454
2019-03-22 18:44:16:059 - [35m[Xcode][39m 
2019-03-22 18:44:16:260 - [35m[Xcode][39m     t = 275051.95s     Find: Descendants matching type Window
2019-03-22 18:44:16:260 - [35m[Xcode][39m 
2019-03-22 18:44:16:263 - [35m[Xcode][39m     t = 275051.95s     Find: Elements matching predicate 'wdUID IN {"31000000-0000-0000-9609-000000000000", "32000000-0000-0000-9609-000000000000", "33000000-0000-0000-9609-000000000000"} AND (1 == 1 OR identifier == 0 OR frame == 0 OR value == 0 OR title == 0 OR label == 0 OR elementType == 0 OR enabled == 0 OR placeholderValue == 0)'
2019-03-22 18:44:16:263 - [35m[Xcode][39m 
2019-03-22 18:44:16:268 - [35m[Xcode][39m     t = 275051.96s Snapshot accessibility hierarchy for app with pid 2454
2019-03-22 18:44:16:268 - [35m[Xcode][39m 
2019-03-22 18:44:16:469 - [35m[Xcode][39m     t = 275052.16s Find: Descendants matching type Window
2019-03-22 18:44:16:469 - [35m[Xcode][39m 
2019-03-22 18:44:16:471 - [35m[Xcode][39m     t = 275052.16s Find: Elements matching predicate 'wdUID IN {"31000000-0000-0000-9609-000000000000", "32000000-0000-0000-9609-000000000000", "33000000-0000-0000-9609-000000000000"} AND (1 == 1 OR identifier == 0 OR frame == 0 OR value == 0 OR title == 0 OR label == 0 OR elementType == 0 OR enabled == 0 OR placeholderValue == 0)'
2019-03-22 18:44:16:471 - [35m[Xcode][39m 
2019-03-22 18:44:16:473 - [35m[Xcode][39m     t = 275052.17s Find: Identity Binding
2019-03-22 18:44:16:473 - [35m[Xcode][39m 
2019-03-22 18:44:16:476 - [35m[Xcode][39m     t = 275052.17s Snapshot accessibility hierarchy for app with pid 2454
2019-03-22 18:44:16:476 - [35m[Xcode][39m 
2019-03-22 18:44:16:479 - [35m[Xcode][39m     t = 275052.17s Find: Descendants matching type Window
2019-03-22 18:44:16:479 - [35m[Xcode][39m 
2019-03-22 18:44:16:480 - [35m[Xcode][39m     t = 275052.17s Find: Elements matching predicate 'wdUID IN {"31000000-0000-0000-9609-000000000000", "32000000-0000-0000-9609-000000000000", "33000000-0000-0000-9609-000000000000"} AND (1 == 1 OR identifier == 0 OR frame == 0 OR value == 0 OR title == 0 OR label == 0 OR elementType == 0 OR enabled == 0 OR placeholderValue == 0)'
2019-03-22 18:44:16:480 - [35m[Xcode][39m 
2019-03-22 18:44:16:482 - [35m[Xcode][39m     t = 275052.17s Find: Identity Binding
2019-03-22 18:44:16:482 - [35m[Xcode][39m 
2019-03-22 18:44:16:484 - [35m[Xcode][39m     t = 275052.18s Snapshot accessibility hierarchy for app with pid 2454
2019-03-22 18:44:16:485 - [35m[Xcode][39m 
2019-03-22 18:44:16:690 - [35m[Xcode][39m     t = 275052.38s Find: Descendants matching type Window
2019-03-22 18:44:16:690 - [35m[Xcode][39m 
2019-03-22 18:44:16:692 - [35m[Xcode][39m     t = 275052.38s Find: Elements matching predicate 'wdUID IN {"31000000-0000-0000-9609-000000000000", "32000000-0000-0000-9609-000000000000", "33000000-0000-0000-9609-000000000000"} AND (1 == 1 OR identifier == 0 OR frame == 0 OR value == 0 OR title == 0 OR label == 0 OR elementType == 0 OR enabled == 0 OR placeholderValue == 0)'
2019-03-22 18:44:16:692 - [35m[Xcode][39m 
2019-03-22 18:44:16:694 - [35m[Xcode][39m     t = 275052.39s Find: Identity Binding
2019-03-22 18:44:16:694 - [35m[Xcode][39m 
2019-03-22 18:44:16:697 - [35m[Xcode][39m     t = 275052.39s Snapshot accessibility hierarchy for app with pid 2454
2019-03-22 18:44:16:697 - [35m[Xcode][39m 
2019-03-22 18:44:16:698 - [35m[Xcode][39m     t = 275052.39s Find: Descendants matching type Window
2019-03-22 18:44:16:699 - [35m[Xcode][39m 
2019-03-22 18:44:16:702 - [35m[Xcode][39m     t = 275052.39s Find: Elements matching predicate 'wdUID IN {"31000000-0000-0000-9609-000000000000", "32000000-0000-0000-9609-000000000000", "33000000-0000-0000-9609-000000000000"} AND (1 == 1 OR identifier == 0 OR frame == 0 OR value == 0 OR title == 0 OR label == 0 OR elementType == 0 OR enabled == 0 OR placeholderValue == 0)'
2019-03-22 18:44:16:702 - [35m[Xcode][39m 
2019-03-22 18:44:16:705 - [35m[Xcode][39m     t = 275052.40s Find: Identity Binding
2019-03-22 18:44:16:705 - [35m[Xcode][39m 
2019-03-22 18:44:16:710 - [35m[Xcode][39m     t = 275052.40s Snapshot accessibility hierarchy for app with pid 2454
2019-03-22 18:44:16:710 - [35m[Xcode][39m 
2019-03-22 18:44:16:910 - [35m[Xcode][39m     t = 275052.60s Find: Descendants matching type Window
2019-03-22 18:44:16:910 - [35m[Xcode][39m 
2019-03-22 18:44:16:913 - [35m[Xcode][39m     t = 275052.61s Find: Elements matching predicate 'wdUID IN {"31000000-0000-0000-9609-000000000000", "32000000-0000-0000-9609-000000000000", "33000000-0000-0000-9609-000000000000"} AND (1 == 1 OR identifier == 0 OR frame == 0 OR value == 0 OR title == 0 OR label == 0 OR elementType == 0 OR enabled == 0 OR placeholderValue == 0)'
2019-03-22 18:44:16:913 - [35m[Xcode][39m 
2019-03-22 18:44:16:916 - [35m[Xcode][39m     t = 275052.61s Find: Identity Binding
2019-03-22 18:44:16:916 - [35m[Xcode][39m 
2019-03-22 18:44:16:918 - [35m[Xcode][39m     t = 275052.61s Snapshot accessibility hierarchy for app with pid 2454
2019-03-22 18:44:16:918 - [35m[Xcode][39m 
2019-03-22 18:44:16:919 - [35m[Xcode][39m     t = 275052.61s Find: Descendants matching type Window
2019-03-22 18:44:16:923 - [35m[Xcode][39m 
2019-03-22 18:44:16:923 - [35m[Xcode][39m     t = 275052.61s Find: Elements matching predicate 'wdUID IN {"31000000-0000-0000-9609-000000000000", "32000000-0000-0000-9609-000000000000", "33000000-0000-0000-9609-000000000000"} AND (1 == 1 OR identifier == 0 OR frame == 0 OR value == 0 OR title == 0 OR label == 0 OR elementType == 0 OR enabled == 0 OR placeholderValue == 0)'
2019-03-22 18:44:16:923 - [35m[Xcode][39m     t = 275052.61s Find: Identity Binding
2019-03-22 18:44:16:923 - [35m[Xcode][39m 
2019-03-22 18:44:16:924 - [35m[Xcode][39m     t = 275052.62s Find the Window
2019-03-22 18:44:16:924 - [35m[Xcode][39m 
2019-03-22 18:44:16:929 - [35m[Xcode][39m     t = 275052.62s     Snapshot accessibility hierarchy for app with pid 2454
2019-03-22 18:44:16:930 - [35m[Xcode][39m 
2019-03-22 18:44:17:086 - [35m[HTTP][39m [37m-->[39m [37mGET[39m [37m/wd/hub/status[39m
2019-03-22 18:44:17:086 - [35m[HTTP][39m [90m{}[39m
2019-03-22 18:44:17:086 - [debug] [35m[GENERIC][39m Calling AppiumDriver.getStatus() with args: []
2019-03-22 18:44:17:086 - [debug] [35m[GENERIC][39m Responding to client with driver.getStatus() result: {"build":{"version":"1.10.0"}}
2019-03-22 18:44:17:087 - [35m[HTTP][39m [37m<-- GET /wd/hub/status [39m[32m200[39m [90m1 ms - 68[39m
2019-03-22 18:44:17:087 - [35m[HTTP][39m [90m[39m
2019-03-22 18:44:17:130 - [35m[Xcode][39m     t = 275052.82s     Find: Descendants matching type Window
2019-03-22 18:44:17:131 - [35m[Xcode][39m 
2019-03-22 18:44:17:132 - [35m[Xcode][39m     t = 275052.83s     Find: Elements matching predicate 'wdUID IN {"31000000-0000-0000-9609-000000000000", "32000000-0000-0000-9609-000000000000", "33000000-0000-0000-9609-000000000000"} AND (1 == 1 OR identifier == 0 OR frame == 0 OR value == 0 OR title == 0 OR label == 0 OR elementType == 0 OR enabled == 0 OR placeholderValue == 0)'
2019-03-22 18:44:17:133 - [35m[Xcode][39m 
2019-03-22 18:44:17:134 - [35m[Xcode][39m     t = 275052.83s     Find: Identity Binding
2019-03-22 18:44:17:135 - [35m[Xcode][39m 
2019-03-22 18:44:20:552 - [35m[HTTP][39m [37m-->[39m [37mGET[39m [37m/wd/hub/session/34ab0888-cc52-4372-ad4e-ef6eab990a67[39m
2019-03-22 18:44:20:552 - [35m[HTTP][39m [90m{}[39m
2019-03-22 18:44:20:552 - [debug] [35m[W3C (34ab0888)][39m Calling AppiumDriver.getSession() with args: ["34ab0888-cc52-4372-ad4e-ef6eab990a67"]
2019-03-22 18:44:20:552 - [debug] [35m[XCUITest][39m Executing command 'getSession'
2019-03-22 18:44:22:117 - [35m[HTTP][39m [37m-->[39m [37mGET[39m [37m/wd/hub/status[39m
2019-03-22 18:44:22:118 - [35m[HTTP][39m [90m{}[39m
2019-03-22 18:44:22:118 - [debug] [35m[GENERIC][39m Calling AppiumDriver.getStatus() with args: []
2019-03-22 18:44:22:118 - [debug] [35m[GENERIC][39m Responding to client with driver.getStatus() result: {"build":{"version":"1.10.0"}}
2019-03-22 18:44:22:119 - [35m[HTTP][39m [37m<-- GET /wd/hub/status [39m[32m200[39m [90m1 ms - 68[39m
2019-03-22 18:44:22:119 - [35m[HTTP][39m [90m[39m
2019-03-22 18:44:23:723 - [35m[Xcode][39m     t = 275059.42s Find the Window
2019-03-22 18:44:23:723 - [35m[Xcode][39m 
2019-03-22 18:44:23:728 - [35m[Xcode][39m     t = 275059.42s     Snapshot accessibility hierarchy for app with pid 2454
2019-03-22 18:44:23:728 - [35m[Xcode][39m 
2019-03-22 18:44:23:943 - [35m[Xcode][39m     t = 275059.63s     Find: Descendants matching type Window
2019-03-22 18:44:23:944 - [35m[Xcode][39m 
2019-03-22 18:44:23:945 - [35m[Xcode][39m     t = 275059.64s     Find: Elements matching predicate 'wdUID IN {"31000000-0000-0000-9609-000000000000", "32000000-0000-0000-9609-000000000000", "33000000-0000-0000-9609-000000000000"} AND (1 == 1 OR identifier == 0 OR frame == 0 OR value == 0 OR title == 0 OR label == 0 OR elementType == 0 OR enabled == 0 OR placeholderValue == 0)'
2019-03-22 18:44:23:945 - [35m[Xcode][39m 
2019-03-22 18:44:23:947 - [35m[Xcode][39m     t = 275059.64s     Find: Identity Binding
2019-03-22 18:44:23:947 - [35m[Xcode][39m 
2019-03-22 18:44:24:017 - [35m[Xcode][39m     t = 275059.71s Find the Window
2019-03-22 18:44:24:017 - [35m[Xcode][39m 
2019-03-22 18:44:24:024 - [35m[Xcode][39m     t = 275059.71s     Snapshot accessibility hierarchy for app with pid 2454
2019-03-22 18:44:24:024 - [35m[Xcode][39m 
2019-03-22 18:44:24:234 - [35m[Xcode][39m     t = 275059.93s     Find: Descendants matching type Window
2019-03-22 18:44:24:234 - [35m[Xcode][39m 
2019-03-22 18:44:24:235 - [35m[Xcode][39m     t = 275059.93s     Find: Elements matching predicate 'wdUID IN {"31000000-0000-0000-9609-000000000000", "32000000-0000-0000-9609-000000000000", "33000000-0000-0000-9609-000000000000"} AND (1 == 1 OR identifier == 0 OR frame == 0 OR value == 0 OR title == 0 OR label == 0 OR elementType == 0 OR enabled == 0 OR placeholderValue == 0)'
2019-03-22 18:44:24:235 - [35m[Xcode][39m 
2019-03-22 18:44:24:238 - [35m[Xcode][39m     t = 275059.93s     Find: Identity Binding
2019-03-22 18:44:24:238 - [35m[Xcode][39m 
2019-03-22 18:44:24:295 - [debug] [35m[JSONWP Proxy][39m Got response with status 200: "{\n  \"value\" : {\n    \"isEnabled\" : \"1\",\n    \"isVisible\" : \"1\",\n    \"frame\" : \"{{0, 0}, {414, 736}}\",\n    \"children\" : [\n      {\n        \"isEnabled\" : \"1\",\n        \"isVisible\" : \"1\",\n        \"frame\" : \"{{0, 0}, {414, 736}}\",\n        \"children\" : [\n          {\n            \"isEnabled\" : \"1\",\n            \"isVisible\" : \"1\",\n            \"frame\" : \"{{0, 0}, {414, 736}}\",\n            \"children\" : [\n              {\n                \"isEnabled\" : \"1\",\n                \"isVisible\" : \"1\",\n                \"frame\" : \"{{0, 0}, {414, 736}}\",\n                \"children\" : [\n                  {\n                    \"isEnabled\" : \"1\",\n                    \"isVisible\" : \"1\",\n                    \"frame\" : \"{{0, 20}, {414, 96}}\",\n                    \"children\" : [\n                      {\n                        \"isEnabled\" : \"1\",\n                        \"isVisible\" : \"1\",\n                        \"frame\" : \"{{20, 67}, {134,...
2019-03-22 18:44:24:328 - [35m[XCUITest][39m Merging WDA caps over Appium caps for session detail response
2019-03-22 18:44:24:329 - [debug] [35m[W3C (34ab0888)][39m Responding to client with driver.getPageSource() result: "<?xml version=\"1.0\" encoding=\"UTF-8\"?>\n<AppiumAUT>\n  <XCUIElementTypeApplication type=\"XCUIElementTypeApplication\" enabled=\"true\" visible=\"true\" x=\"0\" y=\"0\" width=\"414\" height=\"736\" name=\"Settings\" label=\"Settings\">\n    <XCUIElementTypeWindow type=\"XCUIElementTypeWindow\" enabled=\"true\" visible=\"true\" x=\"0\" y=\"0\" width=\"414\" height=\"736\">\n      <XCUIElementTypeOther type=\"XCUIElementTypeOther\" enabled=\"true\" visible=\"true\" x=\"0\" y=\"0\" width=\"414\" height=\"736\">\n        <XCUIElementTypeOther type=\"XCUIElementTypeOther\" enabled=\"true\" visible=\"true\" x=\"0\" y=\"0\" width=\"414\" height=\"736\">\n          <XCUIElementTypeNavigationBar type=\"XCUIElementTypeNavigationBar\" enabled=\"true\" visible=\"true\" x=\"0\" y=\"20\" width=\"414\" height=\"96\" name=\"Settings\">\n            <XCUIElementTypeOther type=\"XCUIElementTypeOther\" enabled=\"true\" visible=\"true\" x=\"20\" y=\"67\" width=\"134\" height=\"42\" name=\"Settings\" label=\"Settings\">\n...
2019-03-22 18:44:24:332 - [35m[HTTP][39m [37m<-- GET /wd/hub/session/34ab0888-cc52-4372-ad4e-ef6eab990a67/source [39m[32m200[39m [90m15096 ms - 68509[39m
2019-03-22 18:44:24:332 - [35m[HTTP][39m [90m[39m
2019-03-22 18:44:24:333 - [35m[XCUITest][39m Merging WDA caps over Appium caps for session detail response
2019-03-22 18:44:24:333 - [debug] [35m[W3C (34ab0888)][39m Responding to client with driver.getSession() result: {"udid":"4eac46a54b384355dc56696fcc07bfd2700de57d","platformName":"ios","se:CONFIG_UUID":"0c0ba1c2-5171-4255-9ebe-c6dae18ecce7","automationName":"XCuiTest","bundleId":"com.apple.Preferences","connectHardwareKeyboard":true,"deviceName":"Device - iPhone 6s Plus","launchTimeout":60000,"newCommandTimeout":0,"platformVersion":"12","useJSONSource":true,"wdaLocalPort":8100,"device":"iphone","browserName":"Settings","sdkVersion":"12.1.4","CFBundleIdentifier":"com.apple.Preferences","pixelRatio":2,"statBarHeight":20,"viewportRect":{"left":0,"top":40,"width":828,"height":1432}}
2019-03-22 18:44:24:334 - [35m[HTTP][39m [37m<-- GET /wd/hub/session/34ab0888-cc52-4372-ad4e-ef6eab990a67 [39m[32m200[39m [90m13771 ms - 584[39m
2019-03-22 18:44:24:334 - [35m[HTTP][39m [90m[39m
2019-03-22 18:44:24:334 - [35m[XCUITest][39m Merging WDA caps over Appium caps for session detail response
2019-03-22 18:44:24:334 - [debug] [35m[W3C (34ab0888)][39m Responding to client with driver.getSession() result: {"udid":"4eac46a54b384355dc56696fcc07bfd2700de57d","platformName":"ios","se:CONFIG_UUID":"0c0ba1c2-5171-4255-9ebe-c6dae18ecce7","automationName":"XCuiTest","bundleId":"com.apple.Preferences","connectHardwareKeyboard":true,"deviceName":"Device - iPhone 6s Plus","launchTimeout":60000,"newCommandTimeout":0,"platformVersion":"12","useJSONSource":true,"wdaLocalPort":8100,"device":"iphone","browserName":"Settings","sdkVersion":"12.1.4","CFBundleIdentifier":"com.apple.Preferences","pixelRatio":2,"statBarHeight":20,"viewportRect":{"left":0,"top":40,"width":828,"height":1432}}
2019-03-22 18:44:24:335 - [35m[HTTP][39m [37m<-- GET /wd/hub/session/34ab0888-cc52-4372-ad4e-ef6eab990a67 [39m[32m200[39m [90m8769 ms - 584[39m
2019-03-22 18:44:24:335 - [35m[HTTP][39m [90m[39m
2019-03-22 18:44:24:336 - [debug] [35m[W3C (34ab0888)][39m Responding to client with driver.getSession() result: {"udid":"4eac46a54b384355dc56696fcc07bfd2700de57d","platformName":"ios","se:CONFIG_UUID":"0c0ba1c2-5171-4255-9ebe-c6dae18ecce7","automationName":"XCuiTest","bundleId":"com.apple.Preferences","connectHardwareKeyboard":true,"deviceName":"Device - iPhone 6s Plus","launchTimeout":60000,"newCommandTimeout":0,"platformVersion":"12","useJSONSource":true,"wdaLocalPort":8100,"device":"iphone","browserName":"Settings","sdkVersion":"12.1.4","CFBundleIdentifier":"com.apple.Preferences","pixelRatio":2,"statBarHeight":20,"viewportRect":{"left":0,"top":40,"width":828,"height":1432}}
2019-03-22 18:44:24:337 - [35m[HTTP][39m [37m<-- GET /wd/hub/session/34ab0888-cc52-4372-ad4e-ef6eab990a67 [39m[32m200[39m [90m3784 ms - 584[39m
2019-03-22 18:44:24:337 - [35m[HTTP][39m [90m[39m
2019-03-22 18:44:24:538 - [35m[HTTP][39m [37m-->[39m [37mGET[39m [37m/wd/hub/session/34ab0888-cc52-4372-ad4e-ef6eab990a67/screenshot[39m
2019-03-22 18:44:24:538 - [35m[HTTP][39m [90m{}[39m
2019-03-22 18:44:24:539 - [debug] [35m[W3C (34ab0888)][39m Calling AppiumDriver.getScreenshot() with args: ["34ab0888-cc52-4372-ad4e-ef6eab990a67"]
2019-03-22 18:44:24:539 - [debug] [35m[XCUITest][39m Executing command 'getScreenshot'
2019-03-22 18:44:24:539 - [debug] [35m[XCUITest][39m Taking screenshot with WDA
2019-03-22 18:44:24:540 - [debug] [35m[JSONWP Proxy][39m Matched '/screenshot' to command name 'getScreenshot'
2019-03-22 18:44:24:541 - [debug] [35m[JSONWP Proxy][39m Proxying [GET /screenshot] to [GET http://localhost:8100/session/9AA85CCA-C51C-4F7F-9EF2-45B53604505E/screenshot] with no body
2019-03-22 18:44:24:569 - [35m[Xcode][39m     t = 275060.26s Find the Application 'com.apple.Preferences'
2019-03-22 18:44:24:569 - [35m[Xcode][39m 
2019-03-22 18:44:24:575 - [35m[Xcode][39m     t = 275060.26s     Snapshot accessibility hierarchy for app with pid 2454
2019-03-22 18:44:24:576 - [35m[Xcode][39m 
2019-03-22 18:44:24:834 - [debug] [35m[JSONWP Proxy][39m Got response with status 200: "{\n  \"value\" : \"iVBORw0KGgoAAAANSUhEUgAABNoAAAigCAIAAABm8NjOAAAAAXNSR0IArs4c6QAA\\r\\nABxpRE9UAAAAAgAAAAAAAARQAAAAKAAABFAAAARQAALW9iKxEZUAAEAASURBVHgB\\r\\n7J0HnGVFmbcRFdO6ZlflQ4yru2tOa86iZEXEAEjOoBgRRQRFDCjMTM90zwzRHHbd\\r\\nVYK6uioiIpLDwAzDkBEnMalzT+jv6f5D7aVv38vc6dvT6elfdf3q1Kl6663nnFN1\\r\\n\\/ifdLTo7uw0SkIAEJCABCUhAAhKQgASmAoGurh5Cd3dvEsbNIlCQNrQXbdFQaQtL\\r\\nQAISkIAEJCABCUhAAhKYuASivop2apYY005B2tC+oRz15rAEJCABCUhAAhKQgAQk\\r\\nMFUIKEdHSTkrR6fKIdTQ9QYLS0ACEpCABCQgAQlIQAKFgHJUOapulIAEJCABCUhA\\r\\nAhKQgAQkMAYElKPK0THY7crlEBMSkIAEJCABCUhAAhKQwJQloBxVjipHJSABCUhA\\r\\nAhKQgAQkIAEJjAEB5ahydAx2uyl7+ceOS0ACEpCABCQgAQlIQAKFgHJUOaoclcBI\\r\\nCXAUdXR0EXI4kWCI4XNePT19GWtKflaVAciEBCQgAQlIQAISkMBUJpCzxPIZ2CxW\\r\\nxqzKeSaJvr51xOAi5jwzaQqXxcqK4yeNeziT02DcJoyGb729a9es6QiKJNJKQ3uX\\r\\nP\\/QyUl3UEG4LN5FAhgl2+gwNLHIYEEjkqMhBmOOwie1qSgISkIAEJCABCUhg4hKI\\r\\nZIpgS3pIzPkkORTgNLK9vTM9zakmcValCqvG4akmLsVPnCwe1ulv+tJQjNlQQpGu\\r\\nXbt+9er2nJAXLIG2MbFyVDk6UQnkAGOM4ADgeGB3z7WfHB4lM8U25mCwjAQkIAEJ\\r\\nSEACEpDApCcQyVRHn...
2019-03-22 18:44:24:918 - [debug] [35m[W3C (34ab0888)][39m Responding to client with driver.getScreenshot() result: "iVBORw0KGgoAAAANSUhEUgAABNoAAAigCAIAAABm8NjOAAAAAXNSR0IArs4c6QAA\r\nABxpRE9UAAAAAgAAAAAAAARQAAAAKAAABFAAAARQAALW9iKxEZUAAEAASURBVHgB\r\n7J0HnGVFmbcRFdO6ZlflQ4yru2tOa86iZEXEAEjOoBgRRQRFDCjMTM90zwzRHHbd\r\nVYK6uioiIpLDwAzDkBEnMalzT+jv6f5D7aVv38vc6dvT6elfdf3q1Kl6663nnFN1\r\n/ifdLTo7uw0SkIAEJCABCUhAAhKQgASmAoGurh5Cd3dvEsbNIlCQNrQXbdFQaQtL\r\nQAISkIAEJCABCUhAAhKYuASivop2apYY005B2tC+oRz15rAEJCABCUhAAhKQgAQk\r\nMFUIKEdHSTkrR6fKIdTQ9QYLS0ACEpCABCQgAQlIQAKFgHJUOapulIAEJCABCUhA\r\nAhKQgAQkMAYElKPK0THY7crlEBMSkIAEJCABCUhAAhKQwJQloBxVjipHJSABCUhA\r\nAhKQgAQkIAEJjAEB5ahydAx2uyl7+ceOS0ACEpCABCQgAQlIQAKFgHJUOaoclcBI\r\nCXAUdXR0EXI4kWCI4XNePT19GWtKflaVAciEBCQgAQlIQAISkMBUJpCzxPIZ2CxW\r\nxqzKeSaJvr51xOAi5jwzaQqXxcqK4yeNeziT02DcJoyGb729a9es6QiKJNJKQ3uX\r\nP/QyUl3UEG4LN5FAhgl2+gwNLHIYEEjkqMhBmOOwie1qSgISkIAEJCABCUhg4hKI\r\nZIpgS3pIzPkkORTgNLK9vTM9zakmcValCqvG4akmLsVPnCwe1ulv+tJQjNlQQpGu\r\nXbt+9er2nJAXLIG2MbFyVDk6UQnkAGOM4ADgeGB3z7WfHB4lM8U25mCwjAQkIAEJ\r\nSEACEpDApCcQyVRHnqGvOKvkvigxp5SUJAeBR4IcqpOOTEWMrV/fn1PQ8RPjIa7i\r\n...
2019-03-22 18:44:24:926 - [35m[HTTP][39m [37m<-- GET /wd/hub/session/34ab0888-cc52-4372-ad4e-ef6eab990a67/screenshot [39m[32m200[39m [90m386 ms - 577368[39m
2019-03-22 18:44:24:926 - [35m[HTTP][39m [90m[39m
2019-03-22 18:44:25:487 - [35m[HTTP][39m [37m-->[39m [37mGET[39m [37m/wd/hub/session/34ab0888-cc52-4372-ad4e-ef6eab990a67/window/current/size[39m
2019-03-22 18:44:25:487 - [35m[HTTP][39m [90m{}[39m
2019-03-22 18:44:25:487 - [debug] [35m[W3C (34ab0888)][39m Calling AppiumDriver.getWindowSize() with args: ["current","34ab0888-cc52-4372-ad4e-ef6eab990a67"]
2019-03-22 18:44:25:487 - [debug] [35m[XCUITest][39m Executing command 'getWindowSize'
2019-03-22 18:44:25:488 - [debug] [35m[W3C (34ab0888)][39m Responding to client with driver.getWindowSize() result: {"width":414,"height":736}
2019-03-22 18:44:25:488 - [35m[HTTP][39m [37m<-- GET /wd/hub/session/34ab0888-cc52-4372-ad4e-ef6eab990a67/window/current/size [39m[32m200[39m [90m1 ms - 36[39m
2019-03-22 18:44:25:489 - [35m[HTTP][39m [90m[39m
2019-03-22 18:44:25:545 - [35m[HTTP][39m [37m-->[39m [37mGET[39m [37m/wd/hub/session/34ab0888-cc52-4372-ad4e-ef6eab990a67[39m
2019-03-22 18:44:25:545 - [35m[HTTP][39m [90m{}[39m
2019-03-22 18:44:25:545 - [debug] [35m[W3C (34ab0888)][39m Calling AppiumDriver.getSession() with args: ["34ab0888-cc52-4372-ad4e-ef6eab990a67"]
2019-03-22 18:44:25:546 - [debug] [35m[XCUITest][39m Executing command 'getSession'
2019-03-22 18:44:25:546 - [35m[XCUITest][39m Merging WDA caps over Appium caps for session detail response
2019-03-22 18:44:25:547 - [debug] [35m[W3C (34ab0888)][39m Responding to client with driver.getSession() result: {"udid":"4eac46a54b384355dc56696fcc07bfd2700de57d","platformName":"ios","se:CONFIG_UUID":"0c0ba1c2-5171-4255-9ebe-c6dae18ecce7","automationName":"XCuiTest","bundleId":"com.apple.Preferences","connectHardwareKeyboard":true,"deviceName":"Device - iPhone 6s Plus","launchTimeout":60000,"newCommandTimeout":0,"platformVersion":"12","useJSONSource":true,"wdaLocalPort":8100,"device":"iphone","browserName":"Settings","sdkVersion":"12.1.4","CFBundleIdentifier":"com.apple.Preferences","pixelRatio":2,"statBarHeight":20,"viewportRect":{"left":0,"top":40,"width":828,"height":1432}}
2019-03-22 18:44:25:548 - [35m[HTTP][39m [37m<-- GET /wd/hub/session/34ab0888-cc52-4372-ad4e-ef6eab990a67 [39m[32m200[39m [90m3 ms - 584[39m
2019-03-22 18:44:25:548 - [35m[HTTP][39m [90m[39m
2019-03-22 18:44:27:147 - [35m[HTTP][39m [37m-->[39m [37mGET[39m [37m/wd/hub/status[39m
2019-03-22 18:44:27:147 - [35m[HTTP][39m [90m{}[39m
2019-03-22 18:44:27:147 - [debug] [35m[GENERIC][39m Calling AppiumDriver.getStatus() with args: []
2019-03-22 18:44:27:147 - [debug] [35m[GENERIC][39m Responding to client with driver.getStatus() result: {"build":{"version":"1.10.0"}}
2019-03-22 18:44:27:148 - [35m[HTTP][39m [37m<-- GET /wd/hub/status [39m[32m200[39m [90m1 ms - 68[39m
2019-03-22 18:44:27:148 - [35m[HTTP][39m [90m[39m
2019-03-22 18:44:30:544 - [35m[HTTP][39m [37m-->[39m [37mGET[39m [37m/wd/hub/session/34ab0888-cc52-4372-ad4e-ef6eab990a67[39m
2019-03-22 18:44:30:544 - [35m[HTTP][39m [90m{}[39m
2019-03-22 18:44:30:544 - [debug] [35m[W3C (34ab0888)][39m Calling AppiumDriver.getSession() with args: ["34ab0888-cc52-4372-ad4e-ef6eab990a67"]
2019-03-22 18:44:30:544 - [debug] [35m[XCUITest][39m Executing command 'getSession'
2019-03-22 18:44:30:545 - [35m[XCUITest][39m Merging WDA caps over Appium caps for session detail response
2019-03-22 18:44:30:546 - [debug] [35m[W3C (34ab0888)][39m Responding to client with driver.getSession() result: {"udid":"4eac46a54b384355dc56696fcc07bfd2700de57d","platformName":"ios","se:CONFIG_UUID":"0c0ba1c2-5171-4255-9ebe-c6dae18ecce7","automationName":"XCuiTest","bundleId":"com.apple.Preferences","connectHardwareKeyboard":true,"deviceName":"Device - iPhone 6s Plus","launchTimeout":60000,"newCommandTimeout":0,"platformVersion":"12","useJSONSource":true,"wdaLocalPort":8100,"device":"iphone","browserName":"Settings","sdkVersion":"12.1.4","CFBundleIdentifier":"com.apple.Preferences","pixelRatio":2,"statBarHeight":20,"viewportRect":{"left":0,"top":40,"width":828,"height":1432}}
2019-03-22 18:44:30:546 - [35m[HTTP][39m [37m<-- GET /wd/hub/session/34ab0888-cc52-4372-ad4e-ef6eab990a67 [39m[32m200[39m [90m2 ms - 584[39m
2019-03-22 18:44:30:547 - [35m[HTTP][39m [90m[39m
2019-03-22 18:44:32:178 - [35m[HTTP][39m [37m-->[39m [37mGET[39m [37m/wd/hub/status[39m
2019-03-22 18:44:32:178 - [35m[HTTP][39m [90m{}[39m
2019-03-22 18:44:32:178 - [debug] [35m[GENERIC][39m Calling AppiumDriver.getStatus() with args: []
2019-03-22 18:44:32:178 - [debug] [35m[GENERIC][39m Responding to client with driver.getStatus() result: {"build":{"version":"1.10.0"}}
2019-03-22 18:44:32:179 - [35m[HTTP][39m [37m<-- GET /wd/hub/status [39m[32m200[39m [90m1 ms - 68[39m
2019-03-22 18:44:32:179 - [35m[HTTP][39m [90m[39m
2019-03-22 18:44:35:383 - [35m[HTTP][39m [37m-->[39m [37mDELETE[39m [37m/wd/hub/session/34ab0888-cc52-4372-ad4e-ef6eab990a67[39m
2019-03-22 18:44:35:383 - [35m[HTTP][39m [90m{}[39m
2019-03-22 18:44:35:383 - [debug] [35m[W3C (34ab0888)][39m Calling AppiumDriver.deleteSession() with args: ["34ab0888-cc52-4372-ad4e-ef6eab990a67"]
2019-03-22 18:44:35:383 - [debug] [35m[BaseDriver][39m Event 'quitSessionRequested' logged at 1553280275383 (13:44:35 GMT-0500 (CDT))
2019-03-22 18:44:35:383 - [35m[Appium][39m Removing session 34ab0888-cc52-4372-ad4e-ef6eab990a67 from our master session list
2019-03-22 18:44:35:384 - [debug] [35m[JSONWP Proxy][39m Matched '/session/34ab0888-cc52-4372-ad4e-ef6eab990a67' to command name 'deleteSession'
2019-03-22 18:44:35:384 - [debug] [35m[JSONWP Proxy][39m Proxying [DELETE /session/34ab0888-cc52-4372-ad4e-ef6eab990a67] to [DELETE http://localhost:8100/session/9AA85CCA-C51C-4F7F-9EF2-45B53604505E] with no body
2019-03-22 18:44:35:392 - [35m[Xcode][39m     t = 275071.08s Terminate com.apple.Preferences:2454
2019-03-22 18:44:35:392 - [35m[Xcode][39m 
2019-03-22 18:44:36:419 - [debug] [35m[JSONWP Proxy][39m Got response with status 200: "{\n  \"value\" : {\n\n  },\n  \"sessionId\" : \"BDCC699D-DB66-4653-BAD0-A28F65511349\",\n  \"status\" : 0\n}"
2019-03-22 18:44:36:419 - [debug] [35m[XCUITest][39m Not clearing log files. Use `clearSystemFiles` capability to turn on.
2019-03-22 18:44:36:419 - [debug] [35m[iOSLog][39m Stopping iOS log capture
2019-03-22 18:44:36:421 - [debug] [35m[BaseDriver][39m Event 'quitSessionFinished' logged at 1553280276421 (13:44:36 GMT-0500 (CDT))
2019-03-22 18:44:36:422 - [debug] [35m[W3C (34ab0888)][39m Received response: null
2019-03-22 18:44:36:422 - [debug] [35m[W3C (34ab0888)][39m But deleting session, so not returning
2019-03-22 18:44:36:422 - [debug] [35m[W3C (34ab0888)][39m Responding to client with driver.deleteSession() result: null
2019-03-22 18:44:36:422 - [35m[HTTP][39m [37m<-- DELETE /wd/hub/session/34ab0888-cc52-4372-ad4e-ef6eab990a67 [39m[32m200[39m [90m1039 ms - 14[39m
2019-03-22 18:44:36:422 - [35m[HTTP][39m [90m[39m
2019-03-22 18:44:37:209 - [35m[HTTP][39m [37m-->[39m [37mGET[39m [37m/wd/hub/status[39m
2019-03-22 18:44:37:209 - [35m[HTTP][39m [90m{}[39m

ZGrauer avatar Mar 22 '19 19:03 ZGrauer

Try adding a 600ms wait after the press action. See my previous post

lubbo avatar Mar 23 '19 07:03 lubbo

Try adding a 600ms wait after the press action. See my previous post

We do use a wait in the action for our java based tests using Appium and it works flawlessly.
I was posting the logs from Appium Desktop, which never works. I am hopeful that Appium Desktop's Swipe by Coordinates button can be fixed. I assume all that's needed is for a wait to be added in Appium Desktop.

ZGrauer avatar Mar 23 '19 12:03 ZGrauer

I added the 500ms wait between the initial press and moveTo and was able to successfully scroll the application using Appium Desktop. Created pull #1090

async _execute ({elementId, methodName, args, skipScreenshotAndSource}) {
    this._lastActiveMoment = +(new Date());
    let cachedEl;
    let res = {};
    if (!_.isArray(args)) {
      args = [args];
    }

    if (elementId) {
      // Give the cached element a variable name (el1, el2, el3,...) the first time it's used
      cachedEl = this.elementCache[elementId];
      if (!cachedEl.variableName && cachedEl.variableType === 'string') {
        cachedEl.variableName = `el${this.elVariableCounter++}`;
      }
      res = await cachedEl.el[methodName].apply(cachedEl.el, args);
    } else {
      // Specially handle the tap and swipe method
      if (methodName === SCREENSHOT_INTERACTION_MODE.TAP) {
        res = await (new wd.TouchAction(this.driver))
          .tap({x: args[0], y: args[1]})
          .perform();
      } else if (methodName === SCREENSHOT_INTERACTION_MODE.SWIPE) {
        const [startX, startY, endX, endY] = args;
        res = await (new wd.TouchAction(this.driver))
          .press({x: startX, y: startY})
          .wait(500)
          .moveTo({x: endX, y: endY})
          .release()
          .perform();
      } else if (methodName !== 'source' && methodName !== 'screenshot') {
        res = await this.driver[methodName].apply(this.driver, args);
      }
    }

ZGrauer avatar Sep 16 '19 15:09 ZGrauer