useNativeCachingStrategy #16186

rrawat0406 · 2021-12-01T06:53:34Z

rrawat0406
Dec 1, 2021

@KazuCocoa @mykola-mokhnach
While using appium:useNativeCachingStrategy with Test case and executing on simulators the stale element exception is not being handled. Could you please elaborate the impact of this capability as I tried setting this to true and false both but was unable to see the impact.

KazuCocoa · 2021-12-01T08:12:11Z

KazuCocoa
Dec 1, 2021
Maintainer

It is if caching an element in Appium/WDA or depending on XCTest every time.
https://github.com/appium/WebDriverAgent/blob/master/WebDriverAgentLib/Categories/XCUIElement+FBResolve.h#L25-L28 is an example description.

if your result was the same, I don't think this helps for your case.

1 reply

rrawat0406 Dec 1, 2021
Author

This is a sample test written for using useNativeCachingStrategy capability and it is throwing a stale element error.
I tried keeping the capability as TRUE and FALSE both. But unable to tackle the stale element exception produced by it.

Could you please let me know how we can check if this useNativeCachingStrategy capability is working or not?

Driver.launchApplication(data,"browserName","useNativeCachingStrategy=false");
Driver().get("https://ui.cogmento.com/");
WebElement element = Driver().findElement(By.name("email"));
element.sendKeys("abc@xyz.com");
Driver().get(Driver().getCurrentUrl());
element.sendKeys("xyz@abc.com");

KazuCocoa · 2021-12-01T08:45:47Z

KazuCocoa
Dec 1, 2021
Maintainer

Did you use appium 1.22?
Then, the capability should work https://github.com/appium/appium/blob/master/CHANGELOG.md#iosxcuitest-1

4 replies

rrawat0406 Dec 1, 2021
Author

Yes I'm trying with 1.22.0 but it isn't working

KazuCocoa Dec 1, 2021
Maintainer

Please attach the full appium server log.
We cannot say anything without any logs. If it was possible an issue, please create an issue by following the template in https://github.com/appium/appium/issues

rrawat0406 Dec 6, 2021
Author

@KazuCocoa Please find the appium logs below:

Could you please let me know now, that how this capability is working
Here useNativeCaching is true

2021-12-03 10:26:34:732 [Appium] Welcome to Appium v1.22.0
2021-12-03 10:26:34:733 [Appium] Non-default server args:
2021-12-03 10:26:34:734 [Appium] logFile: /Users/user0/perfecto/executions/3a1975d4-2d03-4c77-a372-0a8ca956cc38/artifacts/appiumServer.log
2021-12-03 10:26:34:734 [Appium] logTimestamp: true
2021-12-03 10:26:34:735 [Appium] debugLogSpacing: true
2021-12-03 10:26:34:735 [Appium] defaultCapabilities: {
2021-12-03 10:26:34:735 [Appium] }
2021-12-03 10:26:34:736 [Appium] relaxedSecurityEnabled: true
2021-12-03 10:26:34:736 [Appium] allowInsecure: {
2021-12-03 10:26:34:736 [Appium] 0: execute_driver_script
2021-12-03 10:26:34:736 [Appium] }
2021-12-03 10:26:34:761 [Appium] Appium REST http interface listener started on 0.0.0.0:4723
2021-12-03 10:26:34:840 [HTTP] --> GET /wd/hub/status?requestedBy=semHost
2021-12-03 10:26:34:841 [HTTP] {}
2021-12-03 10:26:34:842 [GENERIC] Calling AppiumDriver.getStatus() with args: []
2021-12-03 10:26:34:843 [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.22.0"}}
2021-12-03 10:26:34:849 [HTTP] <-- GET /wd/hub/status?requestedBy=semHost 200 6 ms - 68
2021-12-03 10:26:34:849 [HTTP]
2021-12-03 10:26:35:508 [HTTP] --> POST /wd/hub/session
2021-12-03 10:26:35:508 [HTTP] {"desiredCapabilities":{"appiumVersion":"1.22.0","bundleId":"","version":"","platform":"ANY","platformVersion":"13.5","useNativeCachingStrategy":"true","browserName":"safari","platformName":"iOS","newCommandTimeout":0,"deviceName":"iPhone 6s Plus","udid":"C73AD1B7-31CE-4BC0-9730-D309BA884C1C","usePrebuiltWDA":true,"derivedDataPath":"/Users/user0/perfecto/WebDriverAgents/1.22.0/13.5","mjpegServerPort":"9412","appium:deviceName":"iPhone 6s Plus","appium:platformVersion":"13.5","appium:newCommandTimeout":0,"appium:udid":"C73AD1B7-31CE-4BC0-9730-D309BA884C1C","appium:usePrebuiltWDA":true},"capabilities":{"firstMatch":[{"appiumVersion":"1.22.0","browserName":"safari","appium:bundleId":"","platformName":"iOS","appium:platformVersion":"13.5","useNativeCachingStrategy":"true","version":"","platform":"ANY","newCommandTimeout":0,"deviceName":"iPhone 6s Plus","udid":"C73AD1B7-31CE-4BC0-9730-D309BA884C1C","usePrebuiltWDA":true,"derivedDataPath":"/Users/user0/perfecto/WebDriverAgents/1.22.0/13.5","mjpegServerPort":"941...
2021-12-03 10:26:35:509 [W3C] Calling AppiumDriver.createSession() with args: [{"appiumVersion":"1.22.0","bundleId":"","version":"","platform":"ANY","platformVersion":"13.5","useNativeCachingStrategy":"true","browserName":"safari","platformName":"iOS","newCommandTimeout":0,"deviceName":"iPhone 6s Plus","udid":"C73AD1B7-31CE-4BC0-9730-D309BA884C1C","usePrebuiltWDA":true,"derivedDataPath":"/Users/user0/perfecto/WebDriverAgents/1.22.0/13.5","mjpegServerPort":"9412","appium:deviceName":"iPhone 6s Plus","appium:platformVersion":"13.5","appium:newCommandTimeout":0,"appium:udid":"C73AD1B7-31CE-4BC0-9730-D309BA884C1C","appium:usePrebuiltWDA":true},null,{"firstMatch":[{"appiumVersion":"1.22.0","browserName":"safari","appium:bundleId":"","platformName":"iOS","appium:platformVersion":"13.5","useNativeCachingStrategy":"true","version":"","platform":"ANY","newCommandTimeout":0,"deviceName":"iPhone 6s Plus","udid":"C73AD1B7-31CE-4BC0-9730-D309BA884C1C","usePrebuiltWDA":true,"derivedDataPath":"/Users/user0/perfecto/WebDriverAgents/1.22.0/13.5","mjpegServerPort":"9412","appium:deviceName":"iPhone 6...
2021-12-03 10:26:35:509 [BaseDriver] Event 'newSessionRequested' logged at 1638527195509 (05:26:35 GMT-0500 (Eastern Standard Time))
2021-12-03 10:26:35:512 [BaseDriver] The following capabilities are not standard capabilities and should have an extension prefix:
2021-12-03 10:26:35:512 [BaseDriver] appiumVersion
2021-12-03 10:26:35:512 [BaseDriver] useNativeCachingStrategy
2021-12-03 10:26:35:512 [BaseDriver] version
2021-12-03 10:26:35:512 [BaseDriver] platform
2021-12-03 10:26:35:512 [BaseDriver] newCommandTimeout
2021-12-03 10:26:35:513 [BaseDriver] deviceName
2021-12-03 10:26:35:513 [BaseDriver] udid
2021-12-03 10:26:35:513 [BaseDriver] usePrebuiltWDA
2021-12-03 10:26:35:513 [BaseDriver] derivedDataPath
2021-12-03 10:26:35:513 [BaseDriver] mjpegServerPort
2021-12-03 10:26:35:516 [Appium] DeprecationWarning: 'automationName' capability was not provided. Future versions of Appium will require 'automationName' capability to be set for iOS sessions.
2021-12-03 10:26:35:517 [Appium] Requested iOS support with version >= 10, using 'XCUITest' driver instead of UIAutomation-based driver, since the latter is unsupported on iOS 10 and up.
2021-12-03 10:26:36:000 [Appium] Appium v1.22.0 creating new XCUITestDriver (v3.53.1) session
2021-12-03 10:26:36:002 [Appium] Applying relaxed security to 'XCUITestDriver' as per server command line argument. All insecure features will be enabled unless explicitly disabled by --deny-insecure
2021-12-03 10:26:36:002 [Appium] Explicitly enabling use of insecure features:
2021-12-03 10:26:36:002 [Appium] execute_driver_script
2021-12-03 10:26:36:004 [BaseDriver] W3C capabilities and MJSONWP desired capabilities were provided
2021-12-03 10:26:36:004 [BaseDriver] Creating session with W3C capabilities: {
2021-12-03 10:26:36:004 [BaseDriver] "alwaysMatch": {
2021-12-03 10:26:36:005 [BaseDriver] "appium:appiumVersion": "1.22.0",
2021-12-03 10:26:36:005 [BaseDriver] "browserName": "safari",
2021-12-03 10:26:36:005 [BaseDriver] "platformName": "iOS",
2021-12-03 10:26:36:005 [BaseDriver] "appium:useNativeCachingStrategy": "true",
2021-12-03 10:26:36:005 [BaseDriver] "appium:version": "",
2021-12-03 10:26:36:005 [BaseDriver] "appium:platform": "ANY",
2021-12-03 10:26:36:005 [BaseDriver] "appium:newCommandTimeout": 0,
2021-12-03 10:26:36:005 [BaseDriver] "appium:deviceName": "iPhone 6s Plus",
2021-12-03 10:26:36:007 [BaseDriver] "appium:udid": "C73AD1B7-31CE-4BC0-9730-D309BA884C1C",
2021-12-03 10:26:36:007 [BaseDriver] "appium:usePrebuiltWDA": true,
2021-12-03 10:26:36:007 [BaseDriver] "appium:derivedDataPath": "/Users/user0/perfecto/WebDriverAgents/1.22.0/13.5",
2021-12-03 10:26:36:007 [BaseDriver] "appium:mjpegServerPort": "9412",
2021-12-03 10:26:36:007 [BaseDriver] "appium:bundleId": "",
2021-12-03 10:26:36:007 [BaseDriver] "appium:platformVersion": "13.5"
2021-12-03 10:26:36:007 [BaseDriver] },
2021-12-03 10:26:36:007 [BaseDriver] "firstMatch": [
2021-12-03 10:26:36:007 [BaseDriver] {}
2021-12-03 10:26:36:007 [BaseDriver] ]
2021-12-03 10:26:36:007 [BaseDriver] }
2021-12-03 10:26:36:010 [BaseDriver] Number capability passed in as string. Functionality may be compromised.
2021-12-03 10:26:36:012 [BaseDriver] Capability 'useNativeCachingStrategy' changed from string to boolean. This may cause unexpected behavior
2021-12-03 10:26:36:012 [BaseDriver] Capability 'mjpegServerPort' changed from string ('9412') to integer (9412). This may cause unexpected behavior
2021-12-03 10:26:36:015 [BaseDriver] The following capabilities were provided, but are not recognized by Appium:
2021-12-03 10:26:36:016 [BaseDriver] appiumVersion
2021-12-03 10:26:36:016 [BaseDriver] version
2021-12-03 10:26:36:016 [BaseDriver] platform
2021-12-03 10:26:36:017 [BaseDriver] Session created with session id: b3e8dbe5-f93a-451e-8021-9d2fa7d361d6
2021-12-03 10:26:36:034 [XCUITest] Current user: 'user0'
2021-12-03 10:26:36:047 [XCUITest] Available devices:
2021-12-03 10:26:36:047 [XCUITest] No real device with udid 'C73AD1B7-31CE-4BC0-9730-D309BA884C1C'. Looking for simulator
2021-12-03 10:26:36:261 [iOSSim] Constructing iOS simulator for Xcode version 12.4 with udid 'C73AD1B7-31CE-4BC0-9730-D309BA884C1C'
2021-12-03 10:26:36:261 [XCUITest] Determining device to run tests on: udid: 'C73AD1B7-31CE-4BC0-9730-D309BA884C1C', real device: false
2021-12-03 10:26:36:262 [BaseDriver] Event 'xcodeDetailsRetrieved' logged at 1638527196262 (05:26:36 GMT-0500 (Eastern Standard Time))
2021-12-03 10:26:36:262 [XCUITest] Safari test requested
2021-12-03 10:26:36:263 [BaseDriver] Event 'appConfigured' logged at 1638527196262 (05:26:36 GMT-0500 (Eastern Standard Time))
2021-12-03 10:26:36:263 [BaseDriver] Event 'resetStarted' logged at 1638527196263 (05:26:36 GMT-0500 (Eastern Standard Time))
2021-12-03 10:26:36:939 [iOSSim] Cleaning mobile safari data files
2021-12-03 10:26:37:082 [iOSSim] Checking whether simulator has been run before: no
2021-12-03 10:26:37:082 [iOSSim] Could not find Safari support directories to clean out old data. Probably there is nothing to clean out
2021-12-03 10:26:37:082 [BaseDriver] Event 'resetComplete' logged at 1638527197082 (05:26:37 GMT-0500 (Eastern Standard Time))
2021-12-03 10:26:37:083 [WebDriverAgent] Using WDA path: '/usr/local/avm/versions/1.22.0/node_modules/appium/node_modules/appium-webdriveragent'
2021-12-03 10:26:37:083 [WebDriverAgent] Using WDA agent: '/usr/local/avm/versions/1.22.0/node_modules/appium/node_modules/appium-webdriveragent/WebDriverAgent.xcodeproj'
2021-12-03 10:26:37:231 [IOSSimulatorLog] Starting log capture for iOS Simulator with udid 'C73AD1B7-31CE-4BC0-9730-D309BA884C1C' using simctl
2021-12-03 10:26:37:577 [BaseDriver] Event 'logCaptureStarted' logged at 1638527197576 (05:26:37 GMT-0500 (Eastern Standard Time))
2021-12-03 10:26:37:577 [XCUITest] Setting up simulator
2021-12-03 10:26:37:577 [XCUITest] No reason to set locale
2021-12-03 10:26:37:578 [XCUITest] No iOS / app preferences to set
2021-12-03 10:26:37:578 [XCUITest] Setting did not need to be updated
2021-12-03 10:26:37:579 [iOSSim] Setting preferences of C73AD1B7-31CE-4BC0-9730-D309BA884C1C Simulator to {"ConnectHardwareKeyboard":false}
2021-12-03 10:26:37:579 [iOSSim] Setting common Simulator preferences to {"RotateWindowWhenSignaledByGuest":true,"ConnectHardwareKeyboard":false,"PasteboardAutomaticSync":false}
2021-12-03 10:26:38:123 [iOSSim] Updated C73AD1B7-31CE-4BC0-9730-D309BA884C1C Simulator preferences at '/Users/user0/Library/Preferences/com.apple.iphonesimulator.plist' with {"RotateWindowWhenSignaledByGuest":true,"ConnectHardwareKeyboard":false,"PasteboardAutomaticSync":false,"DevicePreferences":{"C73AD1B7-31CE-4BC0-9730-D309BA884C1C":{"SimulatorExternalDisplay":2114,"ConnectHardwareKeyboard":false}}}
2021-12-03 10:26:38:320 [iOSSim] Got Simulator UI client PID: 347
2021-12-03 10:26:38:320 [iOSSim] Both Simulator with UDID 'C73AD1B7-31CE-4BC0-9730-D309BA884C1C' and the UI client are currently running
2021-12-03 10:26:38:320 [BaseDriver] Event 'simStarted' logged at 1638527198320 (05:26:38 GMT-0500 (Eastern Standard Time))
2021-12-03 10:26:38:406 [WebDriverAgent] No obsolete cached processes from previous WDA sessions listening on port 8100 have been found
2021-12-03 10:26:38:407 [DevCon Factory] Requesting connection for device C73AD1B7-31CE-4BC0-9730-D309BA884C1C on local port 8100
2021-12-03 10:26:38:407 [DevCon Factory] Cached connections count: 0
2021-12-03 10:26:38:407 [DevCon Factory] Successfully requested the connection for C73AD1B7-31CE-4BC0-9730-D309BA884C1C:8100
2021-12-03 10:26:38:408 [XCUITest] Starting WebDriverAgent initialization with the synchronization key 'XCUITestDriver'
2021-12-03 10:26:38:410 [WD Proxy] Matched '/status' to command name 'getStatus'
2021-12-03 10:26:38:411 [WD Proxy] Proxying [GET /status] to [GET http://127.0.0.1:8100/status] with no body
2021-12-03 10:26:38:422 [WD Proxy] connect ECONNREFUSED 127.0.0.1:8100
2021-12-03 10:26:38:423 [WebDriverAgent] WDA is not listening at 'http://127.0.0.1:8100/'
2021-12-03 10:26:38:423 [WebDriverAgent] WDA is currently not running. There is nothing to cache
2021-12-03 10:26:38:423 [XCUITest] Trying to start WebDriverAgent 2 times with 10000ms interval
2021-12-03 10:26:38:423 [XCUITest] These values can be customized by changing wdaStartupRetries/wdaStartupRetryInterval capabilities
2021-12-03 10:26:38:423 [BaseDriver] Event 'wdaStartAttempted' logged at 1638527198423 (05:26:38 GMT-0500 (Eastern Standard Time))
2021-12-03 10:26:38:424 [WebDriverAgent] Launching WebDriverAgent on the device
2021-12-03 10:26:38:425 [WebDriverAgent] Skipped WDA project cleanup according to the provided capabilities
2021-12-03 10:26:38:425 [WebDriverAgent] Killing running processes 'xcodebuild.*C73AD1B7-31CE-4BC0-9730-D309BA884C1C, C73AD1B7-31CE-4BC0-9730-D309BA884C1C.*XCTRunner, xctest.*C73AD1B7-31CE-4BC0-9730-D309BA884C1C' for the device C73AD1B7-31CE-4BC0-9730-D309BA884C1C...
2021-12-03 10:26:38:470 [WebDriverAgent] 'pgrep -if xcodebuild.*C73AD1B7-31CE-4BC0-9730-D309BA884C1C' didn't detect any matching processes. Return code: 1
2021-12-03 10:26:38:471 [WebDriverAgent] 'pgrep -if C73AD1B7-31CE-4BC0-9730-D309BA884C1C.*XCTRunner' didn't detect any matching processes. Return code: 1
2021-12-03 10:26:38:499 [WebDriverAgent] 'pgrep -if xctest.*C73AD1B7-31CE-4BC0-9730-D309BA884C1C' didn't detect any matching processes. Return code: 1
2021-12-03 10:26:38:500 [WebDriverAgent] Beginning test with command 'xcodebuild test-without-building -project /usr/local/avm/versions/1.22.0/node_modules/appium/node_modules/appium-webdriveragent/WebDriverAgent.xcodeproj -scheme WebDriverAgentRunner -derivedDataPath /Users/user0/perfecto/WebDriverAgents/1.22.0/13.5 -destination id=C73AD1B7-31CE-4BC0-9730-D309BA884C1C IPHONEOS_DEPLOYMENT_TARGET=13.5 GCC_TREAT_WARNINGS_AS_ERRORS=0 COMPILER_INDEX_STORE_ENABLE=NO' in directory '/usr/local/avm/versions/1.22.0/node_modules/appium/node_modules/appium-webdriveragent'
2021-12-03 10:26:38:501 [WebDriverAgent] Output from xcodebuild will only be logged if any errors are present there. To change this, use 'showXcodeLog' desired capability
2021-12-03 10:26:41:415 [WebDriverAgent] Waiting up to 60000ms for WebDriverAgent to start
2021-12-03 10:26:41:416 [WD Proxy] Matched '/status' to command name 'getStatus'
2021-12-03 10:26:41:416 [WD Proxy] Proxying [GET /status] to [GET http://127.0.0.1:8100/status] with no body
2021-12-03 10:26:41:418 [WD Proxy] connect ECONNREFUSED 127.0.0.1:8100
2021-12-03 10:26:42:421 [WD Proxy] Matched '/status' to command name 'getStatus'
2021-12-03 10:26:42:421 [WD Proxy] Proxying [GET /status] to [GET http://127.0.0.1:8100/status] with no body
2021-12-03 10:26:42:425 [WD Proxy] connect ECONNREFUSED 127.0.0.1:8100
2021-12-03 10:26:42:998 [WebDriverAgent] Log file for xcodebuild test: /Users/user0/perfecto/WebDriverAgents/1.22.0/13.5/Logs/Test/Test-WebDriverAgentRunner-2021.12.03_05-26-42--0500.xcresult/Staging/1_Test/Diagnostics/WebDriverAgentRunner-38046501-8AE2-4AB7-BBEC-129E310D8BC2/WebDriverAgentRunner-D0FBB3C3-0A69-4F68-BA46-0842851D042D/Session-WebDriverAgentRunner-2021-12-03_052642-K43sgt.log
2021-12-03 10:26:43:444 [WD Proxy] Matched '/status' to command name 'getStatus'
2021-12-03 10:26:43:444 [WD Proxy] Proxying [GET /status] to [GET http://127.0.0.1:8100/status] with no body
2021-12-03 10:26:43:445 [WD Proxy] connect ECONNREFUSED 127.0.0.1:8100
2021-12-03 10:26:44:446 [WD Proxy] Matched '/status' to command name 'getStatus'
2021-12-03 10:26:44:446 [WD Proxy] Proxying [GET /status] to [GET http://127.0.0.1:8100/status] with no body
2021-12-03 10:26:44:448 [WD Proxy] connect ECONNREFUSED 127.0.0.1:8100
2021-12-03 10:26:45:448 [WD Proxy] Matched '/status' to command name 'getStatus'
2021-12-03 10:26:45:448 [WD Proxy] Proxying [GET /status] to [GET http://127.0.0.1:8100/status] with no body
2021-12-03 10:26:45:449 [WD Proxy] connect ECONNREFUSED 127.0.0.1:8100
2021-12-03 10:26:46:450 [WD Proxy] Matched '/status' to command name 'getStatus'
2021-12-03 10:26:46:450 [WD Proxy] Proxying [GET /status] to [GET http://127.0.0.1:8100/status] with no body
2021-12-03 10:26:46:451 [WD Proxy] connect ECONNREFUSED 127.0.0.1:8100
2021-12-03 10:26:47:452 [WD Proxy] Matched '/status' to command name 'getStatus'
2021-12-03 10:26:47:452 [WD Proxy] Proxying [GET /status] to [GET http://127.0.0.1:8100/status] with no body
2021-12-03 10:26:47:453 [WD Proxy] connect ECONNREFUSED 127.0.0.1:8100
2021-12-03 10:26:48:453 [WD Proxy] Matched '/status' to command name 'getStatus'
2021-12-03 10:26:48:454 [WD Proxy] Proxying [GET /status] to [GET http://127.0.0.1:8100/status] with no body
2021-12-03 10:26:48:454 [WD Proxy] connect ECONNREFUSED 127.0.0.1:8100
2021-12-03 10:26:49:456 [WD Proxy] Matched '/status' to command name 'getStatus'
2021-12-03 10:26:49:456 [WD Proxy] Proxying [GET /status] to [GET http://127.0.0.1:8100/status] with no body
2021-12-03 10:26:49:457 [WD Proxy] connect ECONNREFUSED 127.0.0.1:8100
2021-12-03 10:26:50:458 [WD Proxy] Matched '/status' to command name 'getStatus'
2021-12-03 10:26:50:459 [WD Proxy] Proxying [GET /status] to [GET http://127.0.0.1:8100/status] with no body
2021-12-03 10:26:50:459 [WD Proxy] connect ECONNREFUSED 127.0.0.1:8100
2021-12-03 10:26:51:460 [WD Proxy] Matched '/status' to command name 'getStatus'
2021-12-03 10:26:51:460 [WD Proxy] Proxying [GET /status] to [GET http://127.0.0.1:8100/status] with no body
2021-12-03 10:26:51:465 [WD Proxy] connect ECONNREFUSED 127.0.0.1:8100
2021-12-03 10:26:52:467 [WD Proxy] Matched '/status' to command name 'getStatus'
2021-12-03 10:26:52:467 [WD Proxy] Proxying [GET /status] to [GET http://127.0.0.1:8100/status] with no body
2021-12-03 10:26:52:472 [WD Proxy] connect ECONNREFUSED 127.0.0.1:8100
2021-12-03 10:26:53:477 [WD Proxy] Matched '/status' to command name 'getStatus'
2021-12-03 10:26:53:477 [WD Proxy] Proxying [GET /status] to [GET http://127.0.0.1:8100/status] with no body
2021-12-03 10:26:53:478 [WD Proxy] connect ECONNREFUSED 127.0.0.1:8100
2021-12-03 10:26:54:478 [WD Proxy] Matched '/status' to command name 'getStatus'
2021-12-03 10:26:54:479 [WD Proxy] Proxying [GET /status] to [GET http://127.0.0.1:8100/status] with no body
2021-12-03 10:26:54:480 [WD Proxy] connect ECONNREFUSED 127.0.0.1:8100
2021-12-03 10:26:55:481 [WD Proxy] Matched '/status' to command name 'getStatus'
2021-12-03 10:26:55:481 [WD Proxy] Proxying [GET /status] to [GET http://127.0.0.1:8100/status] with no body
2021-12-03 10:26:55:482 [WD Proxy] connect ECONNREFUSED 127.0.0.1:8100
2021-12-03 10:26:56:483 [WD Proxy] Matched '/status' to command name 'getStatus'
2021-12-03 10:26:56:483 [WD Proxy] Proxying [GET /status] to [GET http://127.0.0.1:8100/status] with no body
2021-12-03 10:26:56:484 [WD Proxy] connect ECONNREFUSED 127.0.0.1:8100
2021-12-03 10:26:57:485 [WD Proxy] Matched '/status' to command name 'getStatus'
2021-12-03 10:26:57:485 [WD Proxy] Proxying [GET /status] to [GET http://127.0.0.1:8100/status] with no body
2021-12-03 10:26:57:486 [WD Proxy] connect ECONNREFUSED 127.0.0.1:8100
2021-12-03 10:26:58:486 [WD Proxy] Matched '/status' to command name 'getStatus'
2021-12-03 10:26:58:486 [WD Proxy] Proxying [GET /status] to [GET http://127.0.0.1:8100/status] with no body
2021-12-03 10:26:58:487 [WD Proxy] connect ECONNREFUSED 127.0.0.1:8100
2021-12-03 10:26:59:487 [WD Proxy] Matched '/status' to command name 'getStatus'
2021-12-03 10:26:59:487 [WD Proxy] Proxying [GET /status] to [GET http://127.0.0.1:8100/status] with no body
2021-12-03 10:26:59:489 [WD Proxy] connect ECONNREFUSED 127.0.0.1:8100
2021-12-03 10:27:00:490 [WD Proxy] Matched '/status' to command name 'getStatus'
2021-12-03 10:27:00:491 [WD Proxy] Proxying [GET /status] to [GET http://127.0.0.1:8100/status] with no body
2021-12-03 10:27:00:492 [WD Proxy] connect ECONNREFUSED 127.0.0.1:8100
2021-12-03 10:27:01:491 [WD Proxy] Matched '/status' to command name 'getStatus'
2021-12-03 10:27:01:492 [WD Proxy] Proxying [GET /status] to [GET http://127.0.0.1:8100/status] with no body
2021-12-03 10:27:01:493 [WD Proxy] connect ECONNREFUSED 127.0.0.1:8100
2021-12-03 10:27:02:493 [WD Proxy] Matched '/status' to command name 'getStatus'
2021-12-03 10:27:02:493 [WD Proxy] Proxying [GET /status] to [GET http://127.0.0.1:8100/status] with no body
2021-12-03 10:27:02:500 [WD Proxy] Got response with status 200: {"value":{"message":"WebDriverAgent is ready to accept commands","state":"success","os":{"testmanagerdVersion":28,"name":"iOS","sdkVersion":"14.2","version":"13.5"},"ios":{"simulatorVersion":"13.5","ip":"100.88.4.128"},"ready":true,"build":{"upgradedAt":"1634016285723","time":"Oct 20 2021 22:50:57","productBundleIdentifier":"com.facebook.WebDriverAgentRunner"}},"sessionId":null}
2021-12-03 10:27:02:500 [WebDriverAgent] WebDriverAgent information:
2021-12-03 10:27:02:500 [WebDriverAgent] {
2021-12-03 10:27:02:501 [WebDriverAgent] "message": "WebDriverAgent is ready to accept commands",
2021-12-03 10:27:02:501 [WebDriverAgent] "state": "success",
2021-12-03 10:27:02:501 [WebDriverAgent] "os": {
2021-12-03 10:27:02:501 [WebDriverAgent] "testmanagerdVersion": 28,
2021-12-03 10:27:02:501 [WebDriverAgent] "name": "iOS",
2021-12-03 10:27:02:501 [WebDriverAgent] "sdkVersion": "14.2",
2021-12-03 10:27:02:501 [WebDriverAgent] "version": "13.5"
2021-12-03 10:27:02:501 [WebDriverAgent] },
2021-12-03 10:27:02:501 [WebDriverAgent] "ios": {
2021-12-03 10:27:02:501 [WebDriverAgent] "simulatorVersion": "13.5",
2021-12-03 10:27:02:501 [WebDriverAgent] "ip": "100.88.4.128"
2021-12-03 10:27:02:501 [WebDriverAgent] },
2021-12-03 10:27:02:502 [WebDriverAgent] "ready": true,
2021-12-03 10:27:02:502 [WebDriverAgent] "build": {
2021-12-03 10:27:02:502 [WebDriverAgent] "upgradedAt": "1634016285723",
2021-12-03 10:27:02:502 [WebDriverAgent] "time": "Oct 20 2021 22:50:57",
2021-12-03 10:27:02:502 [WebDriverAgent] "productBundleIdentifier": "com.facebook.WebDriverAgentRunner"
2021-12-03 10:27:02:502 [WebDriverAgent] }
2021-12-03 10:27:02:502 [WebDriverAgent] }
2021-12-03 10:27:02:502 [WebDriverAgent] WebDriverAgent successfully started after 24001ms
2021-12-03 10:27:02:503 [BaseDriver] Event 'wdaSessionAttempted' logged at 1638527222503 (05:27:02 GMT-0500 (Eastern Standard Time))
2021-12-03 10:27:02:504 [XCUITest] Sending createSession command to WDA
2021-12-03 10:27:02:504 [WD Proxy] Matched '/session' to command name 'createSession'
2021-12-03 10:27:02:505 [WD Proxy] Proxying [POST /session] to [POST http://127.0.0.1:8100/session] with body: {"capabilities":{"firstMatch":[{"bundleId":"com.apple.mobilesafari","arguments":[],"environment":{},"eventloopIdleDelaySec":0,"shouldWaitForQuiescence":true,"shouldUseTestManagerForVisibilityDetection":false,"maxTypingFrequency":60,"shouldUseSingletonTestManager":true,"shouldTerminateApp":true,"forceAppLaunch":true,"useNativeCachingStrategy":true,"forceSimulatorSoftwareKeyboardPresence":true}],"alwaysMatch":{}}}
2021-12-03 10:27:03:881 [Xcode] 2021-12-03 05:27:03.877790-0500 WebDriverAgentRunner-Runner[20480:128572009] Error Domain=com.facebook.WebDriverAgent Code=1 "Cannot take a screenshot within 20000 ms timeout" UserInfo={NSLocalizedDescription=Cannot take a screenshot within 20000 ms timeout}
2021-12-03 10:27:03:882 [Xcode]
2021-12-03 10:27:05:241 [Xcode] 2021-12-03 05:27:05.241137-0500 WebDriverAgentRunner-Runner[20480:128571915] Getting the most recent active application (out of 1 total items)
2021-12-03 10:27:05:242 [Xcode]
2021-12-03 10:27:05:242 [Xcode] t = 3.65s Find the Application 'com.apple.mobilesafari'
2021-12-03 10:27:05:243 [Xcode]
2021-12-03 10:27:05:244 [Xcode] t = 3.65s Requesting snapshot of accessibility hierarchy for app with pid 20486
2021-12-03 10:27:05:245 [Xcode]
2021-12-03 10:27:05:404 [WD Proxy] Got response with status 200: {"value":{"sessionId":"A322C6D4-6C03-4227-82C0-5B319386B6F7","capabilities":{"device":"iphone","browserName":"Safari","sdkVersion":"13.5","CFBundleIdentifier":"com.apple.mobilesafari"}},"sessionId":"A322C6D4-6C03-4227-82C0-5B319386B6F7"}
2021-12-03 10:27:05:405 [WD Proxy] Determined the downstream protocol as 'W3C'
2021-12-03 10:27:05:405 [BaseDriver] Event 'wdaSessionStarted' logged at 1638527225405 (05:27:05 GMT-0500 (Eastern Standard Time))
2021-12-03 10:27:05:405 [BaseDriver] Event 'wdaStarted' logged at 1638527225405 (05:27:05 GMT-0500 (Eastern Standard Time))
2021-12-03 10:27:05:405 [XCUITest] Skipping setting of the initial display orientation. Set the "orientation" capability to either "LANDSCAPE" or "PORTRAIT", if this is an undesired behavior.
2021-12-03 10:27:05:405 [BaseDriver] Event 'orientationSet' logged at 1638527225405 (05:27:05 GMT-0500 (Eastern Standard Time))
2021-12-03 10:27:06:031 [HTTP] --> GET /welcome
2021-12-03 10:27:06:032 [HTTP] {}
2021-12-03 10:27:06:207 [HTTP] Sending welcome response with params: {"message":"Let's browse!"}
2021-12-03 10:27:06:232 [HTTP] <-- GET /welcome 200 197 ms - 195
2021-12-03 10:27:06:232 [HTTP]
2021-12-03 10:27:06:753 [HTTP] <-- GET /favicon.ico 200 1 ms - 1150
2021-12-03 10:27:06:754 [HTTP]
2021-12-03 10:27:06:791 [Xcode] 2021-12-03 05:27:06.758713-0500 WebDriverAgentRunner-Runner[20480:128572004] Error Domain=com.facebook.WebDriverAgent Code=1 "Cannot take a screenshot within 20000 ms timeout" UserInfo={NSLocalizedDescription=Cannot take a screenshot within 20000 ms timeout}
2021-12-03 10:27:06:791 [Xcode]
2021-12-03 10:27:08:343 [iOSSim] Safari successfully opened 'http://0.0.0.0:4723/welcome' in 2.649s
2021-12-03 10:27:08:343 [XCUITest] Waiting for initial webview
2021-12-03 10:27:08:344 [XCUITest] Navigating to most recently opened webview
2021-12-03 10:27:08:345 [XCUITest] Retrieving contexts and views
2021-12-03 10:27:08:345 [XCUITest] Selecting by url: true (expected url: 'http://0.0.0.0:4723/welcome')
2021-12-03 10:27:08:382 [RemoteDebugger] Remote Debugger version 8.13.2
2021-12-03 10:27:08:382 [RemoteDebugger] useNewSafari --> false
2021-12-03 10:27:08:383 [RemoteDebugger] Checking which communication style to use (Safari on platform version '13.5')
2021-12-03 10:27:08:383 [RemoteDebugger] Platform version equal or higher than '12.2': true
2021-12-03 10:27:08:384 [RemoteDebugger] Setting communication protocol: using Target-based communication
2021-12-03 10:27:08:384 [RemoteDebugger] Connecting to remote debugger through unix domain socket: '/private/tmp/com.apple.launchd.ejeAjgsMP1/com.apple.webinspectord_sim.socket'
2021-12-03 10:27:08:418 [RemoteDebugger] Debugger socket connected
2021-12-03 10:27:08:418 [RemoteDebugger] Sending connection key request
2021-12-03 10:27:08:419 [RemoteDebugger] Sending '_rpc_reportIdentifier:' message (id: 0): 'setConnectionKey'
2021-12-03 10:27:08:434 [RemoteDebugger] Sending to Web Inspector took 15ms
2021-12-03 10:27:08:434 [RemoteDebugger] Sending connection key request
2021-12-03 10:27:08:434 [RemoteDebugger] Sending '_rpc_reportIdentifier:' message (id: 2): 'setConnectionKey'
2021-12-03 10:27:08:435 [RemoteDebugger] Sending to Web Inspector took 1ms
2021-12-03 10:27:08:435 [RemoteDebugger] Selecting application
2021-12-03 10:27:08:435 [RemoteDebugger] No applications currently connected.
2021-12-03 10:27:08:436 [XCUITest] No web frames found.
2021-12-03 10:27:08:436 [XCUITest] Could not find any webviews yet, refreshing/retrying
2021-12-03 10:27:08:936 [XCUITest] Retrieving contexts and views
2021-12-03 10:27:08:936 [XCUITest] Selecting by url: true (expected url: 'http://0.0.0.0:4723/welcome')
2021-12-03 10:27:08:937 [RemoteDebugger] Sending connection key request
2021-12-03 10:27:08:937 [RemoteDebugger] Sending '_rpc_reportIdentifier:' message (id: 4): 'setConnectionKey'
2021-12-03 10:27:08:938 [RemoteDebugger] Sending to Web Inspector took 1ms
2021-12-03 10:27:08:938 [RemoteDebugger] Selecting application
2021-12-03 10:27:08:938 [RemoteDebugger] No applications currently connected.
2021-12-03 10:27:08:938 [XCUITest] No web frames found.
2021-12-03 10:27:08:939 [XCUITest] Could not find any webviews yet, refreshing/retrying
2021-12-03 10:27:09:439 [XCUITest] Retrieving contexts and views
2021-12-03 10:27:09:439 [XCUITest] Selecting by url: true (expected url: 'http://0.0.0.0:4723/welcome')
2021-12-03 10:27:09:439 [RemoteDebugger] Sending connection key request
2021-12-03 10:27:09:439 [RemoteDebugger] Sending '_rpc_reportIdentifier:' message (id: 6): 'setConnectionKey'
2021-12-03 10:27:09:440 [RemoteDebugger] Sending to Web Inspector took 1ms
2021-12-03 10:27:09:440 [RemoteDebugger] Selecting application
2021-12-03 10:27:09:440 [RemoteDebugger] No applications currently connected.
2021-12-03 10:27:09:440 [XCUITest] No web frames found.
2021-12-03 10:27:09:440 [XCUITest] Could not find any webviews yet, refreshing/retrying
2021-12-03 10:27:09:622 [Xcode] 2021-12-03 05:27:09.621561-0500 WebDriverAgentRunner-Runner[20480:128572003] Disconnected a client from screenshots broadcast
2021-12-03 10:27:09:622 [Xcode]
2021-12-03 10:27:09:637 [Xcode] 2021-12-03 05:27:09.636848-0500 WebDriverAgentRunner-Runner[20480:128572003] Got screenshots broadcast client connection at 100.88.7.254:52271
2021-12-03 10:27:09:637 [Xcode]
2021-12-03 10:27:09:637 [Xcode] 2021-12-03 05:27:09.636990-0500 WebDriverAgentRunner-Runner[20480:128572003] Starting screenshots broadcast for the client at 100.88.7.254:52271
2021-12-03 10:27:09:638 [Xcode]
2021-12-03 10:27:09:942 [XCUITest] Retrieving contexts and views
2021-12-03 10:27:09:942 [XCUITest] Selecting by url: true (expected url: 'http://0.0.0.0:4723/welcome')
2021-12-03 10:27:09:942 [RemoteDebugger] Sending connection key request
2021-12-03 10:27:09:943 [RemoteDebugger] Sending '_rpc_reportIdentifier:' message (id: 8): 'setConnectionKey'
2021-12-03 10:27:09:944 [RemoteDebugger] Sending to Web Inspector took 1ms
2021-12-03 10:27:09:944 [RemoteDebugger] Selecting application
2021-12-03 10:27:09:944 [RemoteDebugger] No applications currently connected.
2021-12-03 10:27:09:944 [XCUITest] No web frames found.
2021-12-03 10:27:09:944 [XCUITest] Could not find any webviews yet, refreshing/retrying
2021-12-03 10:27:10:298 [RemoteDebugger] Received connected applications list: PID:20486, PID:20490
2021-12-03 10:27:10:300 [RemoteDebugger] Received connected driver list: {}
2021-12-03 10:27:10:444 [XCUITest] Retrieving contexts and views
2021-12-03 10:27:10:444 [XCUITest] Selecting by url: true (expected url: 'http://0.0.0.0:4723/welcome')
2021-12-03 10:27:10:444 [RemoteDebugger] Sending connection key request
2021-12-03 10:27:10:445 [RemoteDebugger] Sending '_rpc_reportIdentifier:' message (id: 10): 'setConnectionKey'
2021-12-03 10:27:10:446 [RemoteDebugger] Sending to Web Inspector took 1ms
2021-12-03 10:27:10:446 [RemoteDebugger] Selecting application
2021-12-03 10:27:10:446 [RemoteDebugger] Current applications available:
2021-12-03 10:27:10:446 [RemoteDebugger] Application: "PID:20486"
2021-12-03 10:27:10:447 [RemoteDebugger] id: "PID:20486"
2021-12-03 10:27:10:447 [RemoteDebugger] isProxy: false
2021-12-03 10:27:10:447 [RemoteDebugger] name: "Safari"
2021-12-03 10:27:10:447 [RemoteDebugger] bundleId: "com.apple.mobilesafari"
2021-12-03 10:27:10:447 [RemoteDebugger] hostId: undefined
2021-12-03 10:27:10:447 [RemoteDebugger] isActive: true
2021-12-03 10:27:10:447 [RemoteDebugger] isAutomationEnabled: "Unknown"
2021-12-03 10:27:10:447 [RemoteDebugger] Application: "PID:20490"
2021-12-03 10:27:10:447 [RemoteDebugger] id: "PID:20490"
2021-12-03 10:27:10:447 [RemoteDebugger] isProxy: false
2021-12-03 10:27:10:448 [RemoteDebugger] name: "com.apple.WebKit.WebContent"
2021-12-03 10:27:10:448 [RemoteDebugger] bundleId: "process-com.apple.WebKit.WebContent"
2021-12-03 10:27:10:448 [RemoteDebugger] hostId: undefined
2021-12-03 10:27:10:448 [RemoteDebugger] isActive: true
2021-12-03 10:27:10:448 [RemoteDebugger] isAutomationEnabled: "Unknown"
2021-12-03 10:27:10:448 [RemoteDebugger] Checking for bundle identifiers: com.apple.WebKit.WebContent, process-com.apple.WebKit.WebContent, process-SafariViewService, com.apple.SafariViewService, , com.apple.mobilesafari
2021-12-03 10:27:10:448 [RemoteDebugger] Found app id key 'PID:20490' for bundle 'com.apple.WebKit.WebContent'
2021-12-03 10:27:10:449 [RemoteDebugger] Found app id key 'PID:20490' for bundle 'process-com.apple.WebKit.WebContent'
2021-12-03 10:27:10:449 [RemoteDebugger] Found app id key 'PID:20490' for bundle 'process-SafariViewService'
2021-12-03 10:27:10:449 [RemoteDebugger] Found app id key 'PID:20490' for bundle 'com.apple.SafariViewService'
2021-12-03 10:27:10:449 [RemoteDebugger] Found app id key 'PID:20490' for bundle ''
2021-12-03 10:27:10:449 [RemoteDebugger] Found app id key 'PID:20486' for bundle 'com.apple.mobilesafari'
2021-12-03 10:27:10:449 [RemoteDebugger] Trying out the possible app ids: PID:20490, PID:20486 (try #1 of 20)
2021-12-03 10:27:10:449 [RemoteDebugger] Attempting app 'PID:20490'
2021-12-03 10:27:10:450 [RemoteDebugger] Sending '_rpc_forwardGetListing:' message to app 'PID:20490' (id: 12): 'connectToApp'
2021-12-03 10:27:10:452 [RemoteDebugger] Received response from send (id: 12): '["PID:20490",{}]'
2021-12-03 10:27:10:453 [RemoteDebugger] Found app id key 'PID:20486' for bundle 'com.apple.mobilesafari'
2021-12-03 10:27:10:453 [RemoteDebugger] Sending to Web Inspector took 3ms
2021-12-03 10:27:10:453 [RemoteDebugger] Empty page dictionary received
2021-12-03 10:27:10:479 [RemoteDebugger] Error checking application: 'Empty page dictionary received'. Retrying connection
2021-12-03 10:27:10:479 [RemoteDebugger] Attempting app 'PID:20486'
2021-12-03 10:27:10:479 [RemoteDebugger] Sending '_rpc_forwardGetListing:' message to app 'PID:20486' (id: 14): 'connectToApp'
2021-12-03 10:27:10:480 [RemoteDebugger] Received response from send (id: 14): '["PID:20486",{"1":{"WIRTitleKey":"Appium/welcome","WIRTypeKey":"WIRTypeWebPage","WIRURLKey":"http://0.0.0.0:4723/welcome","WIRPageIdentifierKey":1}}]'
2021-12-03 10:27:10:481 [RemoteDebugger] Page changed: {
2021-12-03 10:27:10:481 [RemoteDebugger] "1": {
2021-12-03 10:27:10:481 [RemoteDebugger] "WIRTitleKey": "Appium/welcome",
2021-12-03 10:27:10:481 [RemoteDebugger] "WIRTypeKey": "WIRTypeWebPage",
2021-12-03 10:27:10:481 [RemoteDebugger] "WIRURLKey": "http://0.0.0.0:4723/welcome",
2021-12-03 10:27:10:482 [RemoteDebugger] "WIRPageIdentifierKey": 1
2021-12-03 10:27:10:482 [RemoteDebugger] }
2021-12-03 10:27:10:482 [RemoteDebugger] }
2021-12-03 10:27:10:482 [XCUITest] Remote debugger notified us of a new page listing: {"appIdKey":"20486","pageArray":[{"id":1,"title":"Appium/welcome","url":"http://0.0.0.0:4723/welcome","isKey":false}]}
2021-12-03 10:27:10:482 [XCUITest] No key id found. Choosing first id from page array
2021-12-03 10:27:10:482 [XCUITest] We do not appear to have window set yet, ignoring
2021-12-03 10:27:10:483 [RemoteDebugger] Sending to Web Inspector took 3ms
2021-12-03 10:27:10:483 [RemoteDebugger] Current applications available:
2021-12-03 10:27:10:483 [RemoteDebugger] Application: "PID:20486"
2021-12-03 10:27:10:484 [RemoteDebugger] id: "PID:20486"
2021-12-03 10:27:10:484 [RemoteDebugger] isProxy: false
2021-12-03 10:27:10:484 [RemoteDebugger] name: "Safari"
2021-12-03 10:27:10:484 [RemoteDebugger] bundleId: "com.apple.mobilesafari"
2021-12-03 10:27:10:484 [RemoteDebugger] hostId: undefined
2021-12-03 10:27:10:484 [RemoteDebugger] isActive: true
2021-12-03 10:27:10:484 [RemoteDebugger] isAutomationEnabled: true
2021-12-03 10:27:10:484 [RemoteDebugger] pageArray:
2021-12-03 10:27:10:484 [RemoteDebugger] - id: 1
2021-12-03 10:27:10:484 [RemoteDebugger] title: "Appium/welcome"
2021-12-03 10:27:10:484 [RemoteDebugger] url: "http://0.0.0.0:4723/welcome"
2021-12-03 10:27:10:484 [RemoteDebugger] isKey: false
2021-12-03 10:27:10:485 [RemoteDebugger] Application: "PID:20490"
2021-12-03 10:27:10:485 [RemoteDebugger] id: "PID:20490"
2021-12-03 10:27:10:485 [RemoteDebugger] isProxy: false
2021-12-03 10:27:10:485 [RemoteDebugger] name: "com.apple.WebKit.WebContent"
2021-12-03 10:27:10:485 [RemoteDebugger] bundleId: "process-com.apple.WebKit.WebContent"
2021-12-03 10:27:10:485 [RemoteDebugger] hostId: undefined
2021-12-03 10:27:10:485 [RemoteDebugger] isActive: true
2021-12-03 10:27:10:485 [RemoteDebugger] isAutomationEnabled: "Unknown"
2021-12-03 10:27:10:485 [RemoteDebugger] pageArray: "Waiting for data"
2021-12-03 10:27:10:486 [RemoteDebugger] Finally selecting app PID:20486: [{"id":1,"title":"Appium/welcome","url":"http://0.0.0.0:4723/welcome","isKey":false}]
2021-12-03 10:27:10:486 [RemoteDebugger] Selected app after 40ms
2021-12-03 10:27:10:486 [XCUITest] Picking webview 'WEBVIEW_20486.1'
2021-12-03 10:27:10:486 [XCUITest] Attempting to set context to 'WEBVIEW_20486.1' from 'NATIVE_APP'
2021-12-03 10:27:10:487 [RemoteDebugger] Selecting page '1' on app 'PID:20486' and forwarding socket setup
2021-12-03 10:27:10:487 [RemoteDebugger] Sending '_rpc_forwardIndicateWebView:' message to app 'PID:20486', page '1' (id: 16): 'indicateWebView'
2021-12-03 10:27:10:488 [RemoteDebugger] Sending to Web Inspector took 1ms
2021-12-03 10:27:10:488 [RemoteDebugger] Sending '_rpc_forwardIndicateWebView:' message to app 'PID:20486', page '1' (id: 18): 'indicateWebView'
2021-12-03 10:27:10:489 [RemoteDebugger] Sending to Web Inspector took 1ms
2021-12-03 10:27:10:490 [RemoteDebugger] Sending '_rpc_forwardSocketSetup:' message to app 'PID:20486', page '1' (id: 20): 'setSenderKey'
2021-12-03 10:27:10:491 [RemoteDebugger] Sending to Web Inspector took 1ms
2021-12-03 10:27:10:491 [RemoteDebugger] Sender key set
2021-12-03 10:27:10:497 [RemoteDebugger] Sending '_rpc_forwardSocketData:' message to app 'PID:20486', page '1' (id: 22): 'Inspector.enable'
2021-12-03 10:27:10:504 [RemoteDebugger] Target created for app 'PID:20486' and page '1': {"targetId":"page-7","type":"page"}
2021-12-03 10:27:10:506 [RemoteDebugger] Page changed: {
2021-12-03 10:27:10:506 [RemoteDebugger] "1": {
2021-12-03 10:27:10:506 [RemoteDebugger] "WIRTitleKey": "Appium/welcome",
2021-12-03 10:27:10:506 [RemoteDebugger] "WIRTypeKey": "WIRTypeWebPage",
2021-12-03 10:27:10:506 [RemoteDebugger] "WIRURLKey": "http://0.0.0.0:4723/welcome",
2021-12-03 10:27:10:506 [RemoteDebugger] "WIRPageIdentifierKey": 1,
2021-12-03 10:27:10:506 [RemoteDebugger] "WIRConnectionIdentifierKey": "821dd89f-de0c-4751-881d-fd88fc415c4d"
2021-12-03 10:27:10:506 [RemoteDebugger] }
2021-12-03 10:27:10:506 [RemoteDebugger] }
2021-12-03 10:27:10:507 [XCUITest] Remote debugger notified us of a new page listing: {"appIdKey":"20486","pageArray":[{"id":1,"title":"Appium/welcome","url":"http://0.0.0.0:4723/welcome","isKey":true}]}
2021-12-03 10:27:10:507 [XCUITest] We are in the middle of selecting a page, ignoring
2021-12-03 10:27:10:507 [RemoteDebugger] Handling message (id: '23')
2021-12-03 10:27:10:508 [RemoteDebugger] Sending to Web Inspector took 15ms
2021-12-03 10:27:10:508 [RemoteDebugger] Sending '_rpc_forwardSocketData:' message to app 'PID:20486', page '1', target 'page-7' (id: 24): 'Page.enable'
2021-12-03 10:27:10:510 [RemoteDebugger] Sending to Web Inspector took 2ms
2021-12-03 10:27:10:510 [RemoteDebugger] Sending '_rpc_forwardSocketData:' message to app 'PID:20486', page '1', target 'page-7' (id: 26): 'Network.enable'
2021-12-03 10:27:10:511 [RemoteDebugger] Sending to Web Inspector took 1ms
2021-12-03 10:27:10:512 [RemoteDebugger] Sending '_rpc_forwardSocketData:' message to app 'PID:20486', page '1', target 'page-7' (id: 28): 'Runtime.enable'
2021-12-03 10:27:10:513 [RemoteDebugger] Sending to Web Inspector took 1ms
2021-12-03 10:27:10:513 [RemoteDebugger] Sending '_rpc_forwardSocketData:' message to app 'PID:20486', page '1', target 'page-7' (id: 30): 'Heap.enable'
2021-12-03 10:27:10:515 [RemoteDebugger] Sending to Web Inspector took 1ms
2021-12-03 10:27:10:515 [RemoteDebugger] Sending '_rpc_forwardSocketData:' message to app 'PID:20486', page '1', target 'page-7' (id: 32): 'Debugger.enable'
2021-12-03 10:27:10:517 [RemoteDebugger] Sending to Web Inspector took 2ms
2021-12-03 10:27:10:517 [RemoteDebugger] Sending '_rpc_forwardSocketData:' message to app 'PID:20486', page '1', target 'page-7' (id: 34): 'Console.enable'
2021-12-03 10:27:10:519 [RemoteDebugger] Sending to Web Inspector took 1ms
2021-12-03 10:27:10:519 [RemoteDebugger] Sending '_rpc_forwardSocketData:' message to app 'PID:20486', page '1', target 'page-7' (id: 36): 'Inspector.initialized'
2021-12-03 10:27:10:520 [RemoteDebugger] Sending to Web Inspector took 1ms
2021-12-03 10:27:10:520 [RemoteDebugger] Checking document readyState
2021-12-03 10:27:10:521 [RemoteDebugger] Sending javascript command: 'document.readyState;'
2021-12-03 10:27:10:521 [RemoteDebugger] Sending '_rpc_forwardSocketData:' message to app 'PID:20486', page '1', target 'page-7' (id: 38): 'Runtime.evaluate'
2021-12-03 10:27:10:537 [RemoteDebugger] Received data response from send (id: 38): '"complete"'
2021-12-03 10:27:10:537 [RemoteDebugger] Sending to Web Inspector took 16ms
2021-12-03 10:27:10:538 [RemoteDebugger] Document readyState is 'complete'
2021-12-03 10:27:10:538 [RemoteDebugger] Selected page after 51ms
2021-12-03 10:27:10:538 [RemoteDebugger] Starting to listen for JavaScript console
2021-12-03 10:27:10:538 [RemoteDebugger] Starting to listen for network events
2021-12-03 10:27:10:539 [RemoteDebugger] Unregistering from page readiness notifications
2021-12-03 10:27:10:539 [BaseDriver] Event 'initialWebviewNavigated' logged at 1638527230539 (05:27:10 GMT-0500 (Eastern Standard Time))
2021-12-03 10:27:10:539 [BaseDriver] The value of 'elementResponseAttributes' setting did not change. Skipping the update for it
2021-12-03 10:27:10:539 [BaseDriver] The value of 'shouldUseCompactResponses' setting did not change. Skipping the update for it
2021-12-03 10:27:10:540 [Appium] New XCUITestDriver session created successfully, session b3e8dbe5-f93a-451e-8021-9d2fa7d361d6 added to master session list
2021-12-03 10:27:10:540 [BaseDriver] Event 'newSessionStarted' logged at 1638527230540 (05:27:10 GMT-0500 (Eastern Standard Time))
2021-12-03 10:27:10:541 [W3C (b3e8dbe5)] Cached the protocol value 'W3C' for the new session b3e8dbe5-f93a-451e-8021-9d2fa7d361d6
2021-12-03 10:27:10:541 [W3C (b3e8dbe5)] Responding to client with driver.createSession() result: {"capabilities":{"webStorageEnabled":false,"locationContextEnabled":false,"browserName":"safari","platform":"ANY","javascriptEnabled":true,"databaseEnabled":false,"takesScreenshot":true,"networkConnectionEnabled":false,"platformName":"iOS","appiumVersion":"1.22.0","useNativeCachingStrategy":true,"version":"","newCommandTimeout":0,"deviceName":"iPhone 6s Plus","udid":"C73AD1B7-31CE-4BC0-9730-D309BA884C1C","usePrebuiltWDA":true,"derivedDataPath":"/Users/user0/perfecto/WebDriverAgents/1.22.0/13.5","mjpegServerPort":9412,"bundleId":"","platformVersion":"13.5"}}
2021-12-03 10:27:10:542 [HTTP] <-- POST /wd/hub/session 200 35034 ms - 624
2021-12-03 10:27:10:542 [HTTP]
2021-12-03 10:27:10:954 [HTTP] --> POST /wd/hub/session/b3e8dbe5-f93a-451e-8021-9d2fa7d361d6/timeouts
2021-12-03 10:27:10:954 [HTTP] {"implicit":20000}
2021-12-03 10:27:10:955 [W3C (b3e8dbe5)] Calling AppiumDriver.timeouts() with args: [null,null,null,null,20000,"b3e8dbe5-f93a-451e-8021-9d2fa7d361d6"]
2021-12-03 10:27:10:956 [XCUITest] Executing command 'timeouts'
2021-12-03 10:27:10:956 [BaseDriver] W3C timeout argument: {"implicit":20000}}
2021-12-03 10:27:10:956 [BaseDriver] Set implicit wait to 20000ms
2021-12-03 10:27:10:957 [W3C (b3e8dbe5)] Responding to client with driver.timeouts() result: null
2021-12-03 10:27:10:958 [HTTP] <-- POST /wd/hub/session/b3e8dbe5-f93a-451e-8021-9d2fa7d361d6/timeouts 200 4 ms - 14
2021-12-03 10:27:10:958 [HTTP]
2021-12-03 10:27:11:477 [RemoteDebugger] Web Inspector garbage collected
2021-12-03 10:27:11:511 [HTTP] --> POST /wd/hub/session/b3e8dbe5-f93a-451e-8021-9d2fa7d361d6/url
2021-12-03 10:27:11:511 [HTTP] {"url":"https://ui.cogmento.com/"}
2021-12-03 10:27:11:512 [W3C (b3e8dbe5)] Calling AppiumDriver.setUrl() with args: ["https://ui.cogmento.com/","b3e8dbe5-f93a-451e-8021-9d2fa7d361d6"]
2021-12-03 10:27:11:512 [XCUITest] Executing command 'setUrl'
2021-12-03 10:27:11:512 [XCUITest] Attempting to set url 'https://ui.cogmento.com/'
2021-12-03 10:27:11:512 [RemoteDebugger] Navigating to new URL: 'https://ui.cogmento.com/'
2021-12-03 10:27:11:513 [RemoteDebugger] Waiting for frame navigated message...
2021-12-03 10:27:11:513 [RemoteDebugger] Sending '_rpc_forwardSocketData:' message to app 'PID:20486', page '1', target 'page-7' (id: 40): 'Page.navigate'
2021-12-03 10:27:11:582 [RemoteDebugger] Notified that new application 'PID:20508' has connected
2021-12-03 10:27:11:583 [RemoteDebugger] Received data response from send (id: 40): '{}'
2021-12-03 10:27:11:586 [RemoteDebugger] Provisional target created for app 'PID:20486', 'page-47'. Ignoring until target update event
2021-12-03 10:27:11:586 [RemoteDebugger] Sending to Web Inspector took 73ms
2021-12-03 10:27:12:015 [RemoteDebugger] Frame navigated in 502ms from: 500ms timeout
2021-12-03 10:27:12:046 [RemoteDebugger] Target updated for app 'PID:20486'. Old target: 'page-7', new target: 'page-47'
2021-12-03 10:27:12:102 [RemoteDebugger] Target destroyed for app 'PID:20486': page-7
2021-12-03 10:27:12:103 [RemoteDebugger] Found provisional target for app 'PID:20486'. Old target: 'page-7', new target: 'page-47'. Updating
2021-12-03 10:27:12:587 [RemoteDebugger] Waiting for dom...
2021-12-03 10:27:12:587 [RemoteDebugger] Page loaded, verifying whether ready
2021-12-03 10:27:13:088 [RemoteDebugger] Checking document readyState
2021-12-03 10:27:13:089 [RemoteDebugger] Sending javascript command: 'document.readyState;'
2021-12-03 10:27:13:089 [RemoteDebugger] Sending '_rpc_forwardSocketData:' message to app 'PID:20486', page '1', target 'page-47' (id: 42): 'Runtime.evaluate'
2021-12-03 10:27:14:558 [RemoteDebugger] Received data response from send (id: 42): '"interactive"'
2021-12-03 10:27:14:558 [RemoteDebugger] Sending to Web Inspector took 1469ms
2021-12-03 10:27:14:559 [RemoteDebugger] Document readyState is 'interactive'
2021-12-03 10:27:14:559 [RemoteDebugger] Page was not ready, retrying
2021-12-03 10:27:15:059 [RemoteDebugger] Checking document readyState
2021-12-03 10:27:15:060 [RemoteDebugger] Sending javascript command: 'document.readyState;'
2021-12-03 10:27:15:060 [RemoteDebugger] Sending '_rpc_forwardSocketData:' message to app 'PID:20486', page '1', target 'page-47' (id: 44): 'Runtime.evaluate'
2021-12-03 10:27:15:061 [RemoteDebugger] Received data response from send (id: 44): '"complete"'
2021-12-03 10:27:15:061 [RemoteDebugger] Sending to Web Inspector took 2ms
2021-12-03 10:27:15:062 [RemoteDebugger] Document readyState is 'complete'
2021-12-03 10:27:15:062 [RemoteDebugger] Page is ready
2021-12-03 10:27:15:062 [RemoteDebugger] Page load completed in 2475ms
2021-12-03 10:27:15:062 [RemoteDebugger] Sending '_rpc_forwardSocketData:' message to app 'PID:20486', page '1', target 'page-47' (id: 46): 'Console.enable'
2021-12-03 10:27:15:064 [RemoteDebugger] Received data response from send (id: 46): '{}'
2021-12-03 10:27:15:065 [RemoteDebugger] Sending to Web Inspector took 3ms
2021-12-03 10:27:15:065 [W3C (b3e8dbe5)] Responding to client with driver.setUrl() result: null
2021-12-03 10:27:15:066 [HTTP] <-- POST /wd/hub/session/b3e8dbe5-f93a-451e-8021-9d2fa7d361d6/url 200 3555 ms - 14
2021-12-03 10:27:15:066 [HTTP]
2021-12-03 10:27:15:350 [HTTP] --> POST /wd/hub/session/b3e8dbe5-f93a-451e-8021-9d2fa7d361d6/element
2021-12-03 10:27:15:350 [HTTP] {"using":"name","value":"email"}
2021-12-03 10:27:15:351 [W3C (b3e8dbe5)] Calling AppiumDriver.findElement() with args: ["name","email","b3e8dbe5-f93a-451e-8021-9d2fa7d361d6"]
2021-12-03 10:27:15:352 [XCUITest] Executing command 'findElement'
2021-12-03 10:27:15:352 [BaseDriver] Valid locator strategies for this request: xpath, id, name, class name, -ios predicate string, -ios class chain, accessibility id, css selector
2021-12-03 10:27:15:353 [BaseDriver] Waiting up to 20000 ms for condition
2021-12-03 10:27:15:353 [RemoteDebugger] Executing atom 'find_element' with 'args=["name","email",null]; frames='
2021-12-03 10:27:15:356 [RemoteDebugger] Executing 'find_element' atom in default context
2021-12-03 10:27:15:357 [RemoteDebugger] Sending javascript command: '(function(){return function(){var k=this;functi...'
2021-12-03 10:27:15:358 [RemoteDebugger] Sending '_rpc_forwardSocketData:' message to app 'PID:20486', page '1', target 'page-47' (id: 48): 'Runtime.evaluate'
2021-12-03 10:27:15:366 [RemoteDebugger] Received data response from send (id: 48): '"{"status":0,"value":{"ELEMENT":":wdc:1638527235365"}}"'
2021-12-03 10:27:15:366 [RemoteDebugger] Sending to Web Inspector took 9ms
2021-12-03 10:27:15:366 [RemoteDebugger] Received result for atom 'find_element' execution: {"ELEMENT":":wdc:1638527235365"}
2021-12-03 10:27:15:368 [W3C (b3e8dbe5)] Responding to client with driver.findElement() result: {"element-6066-11e4-a52e-4f735466cecf":":wdc:1638527235365","ELEMENT":":wdc:1638527235365"}
2021-12-03 10:27:15:368 [HTTP] <-- POST /wd/hub/session/b3e8dbe5-f93a-451e-8021-9d2fa7d361d6/element 200 18 ms - 101
2021-12-03 10:27:15:369 [HTTP]
2021-12-03 10:27:15:384 [HTTP] --> GET /wd/hub/session/b3e8dbe5-f93a-451e-8021-9d2fa7d361d6/screenshot
2021-12-03 10:27:15:384 [HTTP] {}
2021-12-03 10:27:15:384 [W3C (b3e8dbe5)] Calling AppiumDriver.getScreenshot() with args: ["b3e8dbe5-f93a-451e-8021-9d2fa7d361d6"]
2021-12-03 10:27:15:384 [XCUITest] Executing command 'getScreenshot'
2021-12-03 10:27:15:385 [XCUITest] Taking screenshot with WDA
2021-12-03 10:27:15:386 [WD Proxy] Matched '/screenshot' to command name 'getScreenshot'
2021-12-03 10:27:15:387 [WD Proxy] Proxying [GET /screenshot] to [GET http://127.0.0.1:8100/session/A322C6D4-6C03-4227-82C0-5B319386B6F7/screenshot] with no body
2021-12-03 10:27:15:510 [WD Proxy] Got response with status 200: {"value":"iVBORw0KGgoAAAANSUhEUgAABNoAAAigCAYAAADpkk+ZAAAAAXNSR0IArs4c6QAA\r\nAERlWElmTU0AKgAAAAgAAYdpAAQAAAABAAAAGgAAAAAAA6ABAAMAAAABAAEAAKAC\r\nAAQAAAABAAAE2qADAAQAAAABAAAIoAAAAAAWj13IAAAAHGlET1QAAAACAAAAAAAA\r\nBFAAAAAoAAAEUAAABFAAAn6OGrHeegAAQABJREFUeAHs3Qe4bkdVPnCsdEIAIQga\r\nKSEUQ5EiwdAREQuCIBixg4hUEVGBPwEk0qSLEkkBgUQwoURKUEPoEEA0IB0TwBAE\r\nEkICCBb4/ve39f0y9+OUW86559x73v08883smTVr1qx598z61p6998X+53/+Z9ZQ\r\nHRQDxUAxUAwUA8VAMVAMFAPFQDFQDBQDxUAxUAwUA7uHgYtVgbunwOqv+isGioFi\r\noBgoBoqBYqAYKAaKgWKgGCgGioFioBiAgTrauqOvOxqLgWKgGCgGioFioBgoBoqB\r\nYqAYKAaKgWKgGCgG1gADdbStgRLrta7XuhgoBoqBYqAYKAaKgWKgGCgGioFioBgo\r\nBoqBYqCOtjra6rEuBoqBYqAYKAaKgWKgGCgGioFioBgoBoqBYqAYWAMM1NG2Bkqs\r\nx7oe62KgGCgGioFioBgoBoqBYqAYKAaKgWKgGCgGioE62upoq8e6GCgGioFioBgo\r\nBoqBYqAYKAaKgWKgGCgGioFiYA0wUEfbGiixHut6rIuBYqAYKAaKgWKgGCgGioFi\r\noBgoBoqBYqAYKAbqaKujbV091v/93/89+9a3vjX75je/OYX/+q//mgnyxMozEYUu\r\nNOooQzPSjedoRno8wg/d2JZ05EA38kydxp0Ui4FioBgoBoqBYqAYKAaKgWKgGCgG\r\n9kUMjP+l9c95jvw/Hmny/zn/zTe7TiJn+pI+Ok8Zv4D8+ATS3+SvRR/raKu...
2021-12-03 10:27:15:512 [W3C (b3e8dbe5)] Responding to client with driver.getScreenshot() result: "iVBORw0KGgoAAAANSUhEUgAABNoAAAigCAYAAADpkk+ZAAAAAXNSR0IArs4c6QAA\r\nAERlWElmTU0AKgAAAAgAAYdpAAQAAAABAAAAGgAAAAAAA6ABAAMAAAABAAEAAKAC\r\nAAQAAAABAAAE2qADAAQAAAABAAAIoAAAAAAWj13IAAAAHGlET1QAAAACAAAAAAAA\r\nBFAAAAAoAAAEUAAABFAAAn6OGrHeegAAQABJREFUeAHs3Qe4bkdVPnCsdEIAIQga\r\nKSEUQ5EiwdAREQuCIBixg4hUEVGBPwEk0qSLEkkBgUQwoURKUEPoEEA0IB0TwBAE\r\nEkICCBb4/ve39f0y9+OUW86559x73v08883smTVr1qx598z61p6998X+53/+Z9ZQ\r\nHRQDxUAxUAwUA8VAMVAMFAPFQDFQDBQDxUAxUAwUA7uHgYtVgbunwOqv+isGioFi\r\noBgoBoqBYqAYKAaKgWKgGCgGioFioBiAgTrauqOvOxqLgWKgGCgGioFioBgoBoqB\r\nYqAYKAaKgWKgGCgG1gADdbStgRLrta7XuhgoBoqBYqAYKAaKgWKgGCgGioFioBgo\r\nBoqBYqCOtjra6rEuBoqBYqAYKAaKgWKgGCgGioFioBgoBoqBYqAYWAMM1NG2Bkqs\r\nx7oe62KgGCgGioFioBgoBoqBYqAYKAaKgWKgGCgGioE62upoq8e6GCgGioFioBgo\r\nBoqBYqAYKAaKgWKgGCgGioFiYA0wUEfbGiixHut6rIuBYqAYKAaKgWKgGCgGioFi\r\noBgoBoqBYqAYKAbqaKujbV091v/93/89+9a3vjX75je/OYX/+q//mgnyxMozEYUu\r\nNOooQzPSjedoRno8wg/d2JZ05EA38kydxp0Ui4FioBgoBoqBYqAYKAaKgWKgGCgG\r\n9kUMjP+l9c95jvw/Hmny/zn/zTe7TiJn+pI+Ok8Zv4D8+ATS3+SvRR/raKujbe6Y\r\n...
2021-12-03 10:27:15:576 [HTTP] <-- GET /wd/hub/session/b3e8dbe5-f93a-451e-8021-9d2fa7d361d6/screenshot 200 133 ms - 508236
2021-12-03 10:27:15:576 [HTTP]
2021-12-03 10:27:16:061 [HTTP] --> POST /wd/hub/session/b3e8dbe5-f93a-451e-8021-9d2fa7d361d6/element/:wdc:1638527235365/value
2021-12-03 10:27:16:062 [HTTP] {"id":":wdc:1638527235365","text":"abc@xyz.com","value":["a","b","c","@","x","y","z",".","c","o","m"]}
2021-12-03 10:27:16:062 [W3C (b3e8dbe5)] Calling AppiumDriver.setValue() with args: [["a","b","c","@","x","y","z",".","c","o","m"],":wdc:1638527235365","b3e8dbe5-f93a-451e-8021-9d2fa7d361d6"]
2021-12-03 10:27:16:063 [XCUITest] Executing command 'setValue'
2021-12-03 10:27:16:063 [RemoteDebugger] Executing atom 'click' with 'args=[{"ELEMENT":":wdc:1638527235365"}]; frames='
2021-12-03 10:27:16:064 [RemoteDebugger] Executing 'click' atom in default context
2021-12-03 10:27:16:064 [RemoteDebugger] Sending javascript command: '(function(){return function(){var h,aa=this;fun...'
2021-12-03 10:27:16:065 [RemoteDebugger] Sending '_rpc_forwardSocketData:' message to app 'PID:20486', page '1', target 'page-47' (id: 50): 'Runtime.evaluate'
2021-12-03 10:27:16:159 [RemoteDebugger] Received data response from send (id: 50): '"{"status":0,"value":null}"'
2021-12-03 10:27:16:159 [RemoteDebugger] Sending to Web Inspector took 95ms
2021-12-03 10:27:16:159 [RemoteDebugger] Received result for atom 'click' execution: null
2021-12-03 10:27:16:160 [RemoteDebugger] Executing atom 'type' with 'args=[{"ELEMENT":":wdc:1638527235365"},["a","b","c","@","x","y","z",".","c","o","m"]]; frames='
2021-12-03 10:27:16:161 [RemoteDebugger] Executing 'type' atom in default context
2021-12-03 10:27:16:161 [RemoteDebugger] Sending javascript command: '(function(){return function(){var h,aa=this;fun...'
2021-12-03 10:27:16:162 [RemoteDebugger] Sending '_rpc_forwardSocketData:' message to app 'PID:20486', page '1', target 'page-47' (id: 52): 'Runtime.evaluate'
2021-12-03 10:27:16:232 [RemoteDebugger] Received data response from send (id: 52): '"{"status":0,"value":null}"'
2021-12-03 10:27:16:232 [RemoteDebugger] Sending to Web Inspector took 71ms
2021-12-03 10:27:16:232 [RemoteDebugger] Received result for atom 'type' execution: null
2021-12-03 10:27:16:233 [W3C (b3e8dbe5)] Responding to client with driver.setValue() result: null
2021-12-03 10:27:16:233 [HTTP] <-- POST /wd/hub/session/b3e8dbe5-f93a-451e-8021-9d2fa7d361d6/element/:wdc:1638527235365/value 200 172 ms - 14
2021-12-03 10:27:16:234 [HTTP]
2021-12-03 10:27:16:511 [HTTP] --> POST /wd/hub/session/b3e8dbe5-f93a-451e-8021-9d2fa7d361d6/refresh
2021-12-03 10:27:16:511 [HTTP] {}
2021-12-03 10:27:16:511 [W3C (b3e8dbe5)] Calling AppiumDriver.refresh() with args: ["b3e8dbe5-f93a-451e-8021-9d2fa7d361d6"]
2021-12-03 10:27:16:511 [XCUITest] Executing command 'refresh'
2021-12-03 10:27:16:512 [RemoteDebugger] Executing atom 'refresh' with 'args=[]; frames='
2021-12-03 10:27:16:513 [RemoteDebugger] Executing 'refresh' atom in default context
2021-12-03 10:27:16:513 [RemoteDebugger] Sending javascript command: '(function(){return function(){var g=this;functi...'
2021-12-03 10:27:16:514 [RemoteDebugger] Sending '_rpc_forwardSocketData:' message to app 'PID:20486', page '1', target 'page-47' (id: 54): 'Runtime.evaluate'
2021-12-03 10:27:16:568 [RemoteDebugger] Received data response from send (id: 54): '"{"status":0}"'
2021-12-03 10:27:16:569 [RemoteDebugger] Sending to Web Inspector took 55ms
2021-12-03 10:27:16:569 [RemoteDebugger] Received result for atom 'refresh' execution: {"status":0}
2021-12-03 10:27:16:570 [W3C (b3e8dbe5)] Responding to client with driver.refresh() result: null
2021-12-03 10:27:16:570 [HTTP] <-- POST /wd/hub/session/b3e8dbe5-f93a-451e-8021-9d2fa7d361d6/refresh 200 59 ms - 14
2021-12-03 10:27:16:570 [HTTP]
2021-12-03 10:27:16:852 [HTTP] --> POST /wd/hub/session/b3e8dbe5-f93a-451e-8021-9d2fa7d361d6/element/:wdc:1638527235365/value
2021-12-03 10:27:16:852 [HTTP] {"id":":wdc:1638527235365","text":"abc@xyz.com","value":["a","b","c","@","x","y","z",".","c","o","m"]}
2021-12-03 10:27:16:855 [W3C (b3e8dbe5)] Calling AppiumDriver.setValue() with args: [["a","b","c","@","x","y","z",".","c","o","m"],":wdc:1638527235365","b3e8dbe5-f93a-451e-8021-9d2fa7d361d6"]
2021-12-03 10:27:16:856 [XCUITest] Executing command 'setValue'
2021-12-03 10:27:16:857 [RemoteDebugger] Executing atom 'click' with 'args=[{"ELEMENT":":wdc:1638527235365"}]; frames='
2021-12-03 10:27:16:858 [RemoteDebugger] Executing 'click' atom in default context
2021-12-03 10:27:16:858 [RemoteDebugger] Sending javascript command: '(function(){return function(){var h,aa=this;fun...'
2021-12-03 10:27:16:863 [RemoteDebugger] Sending '_rpc_forwardSocketData:' message to app 'PID:20486', page '1', target 'page-47' (id: 56): 'Runtime.evaluate'
2021-12-03 10:27:16:963 [RemoteDebugger] Received page change notice for app 'PID:20486' but the listing has not changed. Ignoring.
2021-12-03 10:27:16:963 [RemoteDebugger] Page changed: {
2021-12-03 10:27:16:963 [RemoteDebugger] "1": {
2021-12-03 10:27:16:963 [RemoteDebugger] "WIRTitleKey": "Cogmento CRM",
2021-12-03 10:27:16:963 [RemoteDebugger] "WIRTypeKey": "WIRTypeWebPage",
2021-12-03 10:27:16:963 [RemoteDebugger] "WIRURLKey": "https://ui.cogmento.com/",
2021-12-03 10:27:16:963 [RemoteDebugger] "WIRPageIdentifierKey": 1,
2021-12-03 10:27:16:963 [RemoteDebugger] "WIRConnectionIdentifierKey": "821dd89f-de0c-4751-881d-fd88fc415c4d"
2021-12-03 10:27:16:964 [RemoteDebugger] }
2021-12-03 10:27:16:964 [RemoteDebugger] }
2021-12-03 10:27:16:964 [XCUITest] Remote debugger notified us of a new page listing: {"appIdKey":"20486","pageArray":[{"id":1,"title":"Cogmento CRM","url":"https://ui.cogmento.com/","isKey":true}]}
2021-12-03 10:27:16:964 [XCUITest] Checking if page needs to load
2021-12-03 10:27:16:965 [XCUITest] New page listing is same as old, doing nothing
2021-12-03 10:27:17:005 [RemoteDebugger] Notified that new application 'PID:20524' has connected
2021-12-03 10:27:17:008 [RemoteDebugger] Received data response from send (id: 56): '"{"status":10,"value":{"message":"Element does not exist in cache"}}"'
2021-12-03 10:27:17:009 [RemoteDebugger] Sending to Web Inspector took 150ms
2021-12-03 10:27:17:009 [MJSONWP] Matched JSONWP error code 10 to StaleElementReferenceError
2021-12-03 10:27:17:858 [XCUITest] Error received while executing atom: Element does not exist in cache
2021-12-03 10:27:17:858 [W3C (b3e8dbe5)] Encountered internal error running command: StaleElementReferenceError: Element does not exist in cache
2021-12-03 10:27:17:858 [W3C (b3e8dbe5)] at errorFromMJSONWPStatusCode (/usr/local/avm/versions/1.22.0/node_modules/appium/node_modules/appium-base-driver/lib/protocol/errors.js:764:12)
2021-12-03 10:27:17:859 [W3C (b3e8dbe5)] at convertResult (/usr/local/avm/versions/1.22.0/node_modules/appium/node_modules/appium-remote-debugger/lib/utils.js:222:11)
2021-12-03 10:27:17:859 [W3C (b3e8dbe5)] at RemoteDebugger.execute (/usr/local/avm/versions/1.22.0/node_modules/appium/node_modules/appium-remote-debugger/lib/mixins/execute.js:135:10)
2021-12-03 10:27:17:859 [W3C (b3e8dbe5)] at RemoteDebugger.executeAtom (/usr/local/avm/versions/1.22.0/node_modules/appium/node_modules/appium-remote-debugger/lib/mixins/execute.js:27:17)
2021-12-03 10:27:17:860 [HTTP] <-- POST /wd/hub/session/b3e8dbe5-f93a-451e-8021-9d2fa7d361d6/element/:wdc:1638527235365/value 404 1008 ms - 746
2021-12-03 10:27:17:860 [HTTP]
2021-12-03 10:27:17:883 [HTTP] --> GET /wd/hub/session/b3e8dbe5-f93a-451e-8021-9d2fa7d361d6/screenshot
2021-12-03 10:27:17:883 [HTTP] {}
2021-12-03 10:27:17:884 [W3C (b3e8dbe5)] Calling AppiumDriver.getScreenshot() with args: ["b3e8dbe5-f93a-451e-8021-9d2fa7d361d6"]
2021-12-03 10:27:17:884 [XCUITest] Executing command 'getScreenshot'
2021-12-03 10:27:17:884 [XCUITest] Taking screenshot with WDA
2021-12-03 10:27:17:885 [WD Proxy] Matched '/screenshot' to command name 'getScreenshot'
2021-12-03 10:27:17:885 [WD Proxy] Proxying [GET /screenshot] to [GET http://127.0.0.1:8100/session/A322C6D4-6C03-4227-82C0-5B319386B6F7/screenshot] with no body
2021-12-03 10:27:18:043 [WD Proxy] Got response with status 200: {"value":"iVBORw0KGgoAAAANSUhEUgAABNoAAAigCAYAAADpkk+ZAAAAAXNSR0IArs4c6QAA\r\nAERlWElmTU0AKgAAAAgAAYdpAAQAAAABAAAAGgAAAAAAA6ABAAMAAAABAAEAAKAC\r\nAAQAAAABAAAE2qADAAQAAAABAAAIoAAAAAAWj13IAAAAHGlET1QAAAACAAAAAAAA\r\nBFAAAAAoAAAEUAAABFAAAc3JP6dJawAAQABJREFUeAHs3Qn8t9lYP3CtZBtblijZ\r\nibEUMhrGUqJSka1JpZBk61+y11AktKm0GSbRiGypGMTYs5RC2TOUJcwYM4gW7v/z\r\nvuvz7Txfv+15ft/f7/k9z/O5X6/zPec+5zrXuc51Pvc51/e6z33f5/nv//7vqaE6\r\nKAaKgWKgGCgGioFioBgoBoqBYqAYKAaKgWKgGCgGtoeB81SB21Ng9Vf9FQPFQDFQ\r\nDBQDxUAxUAwUA8VAMVAMFAPFQDFQDMBAHW3d0dcdjcVAMVAMFAPFQDFQDBQDxUAx\r\nUAwUA8VAMVAMFAMrwEAdbStQYr3W9VoXA8VAMVAMFAPFQDFQDBQDxUAxUAwUA8VA\r\nMVAM1NFWR1s91sVAMVAMFAPFQDFQDBQDxUAxUAwUA8VAMVAMFAMrwEAdbStQYj3W\r\n9VgXA8VAMVAMFAPFQDFQDBQDxUAxUAwUA8VAMVAM1NFWR1s91sVAMVAMFAPFQDFQ\r\nDBQDxUAxUAwUA8VAMVAMFAMrwEAdbStQYj3W9VgXA8VAMVAMFAPFQDFQDBQDxUAx\r\nUAwUA8VAMVAM1NFWR9uOeqz/67/+a/riF784feELX5jDf/7nf06CPLHyTEShC406\r\nytCMdOM5mpEej/BDN7YlHTnQjTxTZ6fi9C1tjnLuVJvl2wm+GCgGioFioBgoBoqB\r\nYqAYKAaKgWIgGBj/S8tzniP/VUea/H/Of/Pw2atx5Exf0kfnKeMXkB+fQPq...
2021-12-03 10:27:18:044 [W3C (b3e8dbe5)] Responding to client with driver.getScreenshot() result: "iVBORw0KGgoAAAANSUhEUgAABNoAAAigCAYAAADpkk+ZAAAAAXNSR0IArs4c6QAA\r\nAERlWElmTU0AKgAAAAgAAYdpAAQAAAABAAAAGgAAAAAAA6ABAAMAAAABAAEAAKAC\r\nAAQAAAABAAAE2qADAAQAAAABAAAIoAAAAAAWj13IAAAAHGlET1QAAAACAAAAAAAA\r\nBFAAAAAoAAAEUAAABFAAAc3JP6dJawAAQABJREFUeAHs3Qn8t9lYP3CtZBtblijZ\r\nibEUMhrGUqJSka1JpZBk61+y11AktKm0GSbRiGypGMTYs5RC2TOUJcwYM4gW7v/z\r\nvuvz7Txfv+15ft/f7/k9z/O5X6/zPec+5zrXuc51Pvc51/e6z33f5/nv//7vqaE6\r\nKAaKgWKgGCgGioFioBgoBoqBYqAYKAaKgWKgGCgGtoeB81SB21Ng9Vf9FQPFQDFQ\r\nDBQDxUAxUAwUA8VAMVAMFAPFQDFQDMBAHW3d0dcdjcVAMVAMFAPFQDFQDBQDxUAx\r\nUAwUA8VAMVAMFAMrwEAdbStQYr3W9VoXA8VAMVAMFAPFQDFQDBQDxUAxUAwUA8VA\r\nMVAM1NFWR1s91sVAMVAMFAPFQDFQDBQDxUAxUAwUA8VAMVAMFAMrwEAdbStQYj3W\r\n9VgXA8VAMVAMFAPFQDFQDBQDxUAxUAwUA8VAMVAM1NFWR1s91sVAMVAMFAPFQDFQ\r\nDBQDxUAxUAwUA8VAMVAMFAMrwEAdbStQYj3W9VgXA8VAMVAMFAPFQDFQDBQDxUAx\r\nUAwUA8VAMVAM1NFWR9uOeqz/67/+a/riF784feELX5jDf/7nf06CPLHyTEShC406\r\nytCMdOM5mpEej/BDN7YlHTnQjTxTZ6fi9C1tjnLuVJvl2wm+GCgGioFioBgoBoqB\r\nYqAYKAaKgWIgGBj/S8tzniP/VUea/H/Of/Pw2atx5Exf0kfnKeMXkB+fQPqb/FX0\r\n...
2021-12-03 10:27:18:110 [HTTP] <-- GET /wd/hub/session/b3e8dbe5-f93a-451e-8021-9d2fa7d361d6/screenshot 200 163 ms - 276852
2021-12-03 10:27:18:110 [HTTP]
2021-12-03 10:27:18:702 [HTTP] --> GET /wd/hub/session/b3e8dbe5-f93a-451e-8021-9d2fa7d361d6/screenshot
2021-12-03 10:27:18:703 [HTTP] {}
2021-12-03 10:27:18:703 [W3C (b3e8dbe5)] Calling AppiumDriver.getScreenshot() with args: ["b3e8dbe5-f93a-451e-8021-9d2fa7d361d6"]
2021-12-03 10:27:18:703 [XCUITest] Executing command 'getScreenshot'
2021-12-03 10:27:18:703 [XCUITest] Taking screenshot with WDA
2021-12-03 10:27:18:704 [WD Proxy] Matched '/screenshot' to command name 'getScreenshot'
2021-12-03 10:27:18:704 [WD Proxy] Proxying [GET /screenshot] to [GET http://127.0.0.1:8100/session/A322C6D4-6C03-4227-82C0-5B319386B6F7/screenshot] with no body
2021-12-03 10:27:18:809 [WD Proxy] Got response with status 200: {"value":"iVBORw0KGgoAAAANSUhEUgAABNoAAAigCAYAAADpkk+ZAAAAAXNSR0IArs4c6QAA\r\nAERlWElmTU0AKgAAAAgAAYdpAAQAAAABAAAAGgAAAAAAA6ABAAMAAAABAAEAAKAC\r\nAAQAAAABAAAE2qADAAQAAAABAAAIoAAAAAAWj13IAAAAHGlET1QAAAACAAAAAAAA\r\nBFAAAAAoAAAEUAAABFAAAn5vzbwMlAAAQABJREFUeAHs3Qe4bkdVPnCsdEIAIQga\r\nKSEUQ5EiwdAREQuCIBixg4hUEVGBPwEk0qSLEkkBgUQwoURKUEPoEEA0IB0TwBAE\r\nEkICCBb4/ve39f0y9+OUW86559x73v08883smTVr1qx598z61p6998X+53/+Z9ZQ\r\nHRQDxUAxUAwUA8VAMVAMFAPFQDFQDBQDxUAxUAwUA7uHgYtVgbunwOqv+isGioFi\r\noBgoBoqBYqAYKAaKgWKgGCgGioFioBiAgTrauqOvOxqLgWKgGCgGioFioBgoBoqB\r\nYqAYKAaKgWKgGCgG1gADdbStgRLrta7XuhgoBoqBYqAYKAaKgWKgGCgGioFioBgo\r\nBoqBYqCOtjra6rEuBoqBYqAYKAaKgWKgGCgGioFioBgoBoqBYqAYWAMM1NG2Bkqs\r\nx7oe62KgGCgGioFioBgoBoqBYqAYKAaKgWKgGCgGioE62upoq8e6GCgGioFioBgo\r\nBoqBYqAYKAaKgWKgGCgGioFiYA0wUEfbGiixHut6rIuBYqAYKAaKgWKgGCgGioFi\r\noBgoBoqBYqAYKAbqaKujbV091v/93/89+9a3vjX75je/OYX/+q//mgnyxMozEYUu\r\nNOooQzPSjedoRno8wg/d2JZ05EA38kydxp0Ui4FioBgoBoqBYqAYKAaKgWKgGCgG\r\n9kUMjP+l9c95jvw/Hmny/zn/zTe7TiJn+pI+Ok8Zv4D8+ATS3+SvRR/raKu...
2021-12-03 10:27:18:811 [W3C (b3e8dbe5)] Responding to client with driver.getScreenshot() result: "iVBORw0KGgoAAAANSUhEUgAABNoAAAigCAYAAADpkk+ZAAAAAXNSR0IArs4c6QAA\r\nAERlWElmTU0AKgAAAAgAAYdpAAQAAAABAAAAGgAAAAAAA6ABAAMAAAABAAEAAKAC\r\nAAQAAAABAAAE2qADAAQAAAABAAAIoAAAAAAWj13IAAAAHGlET1QAAAACAAAAAAAA\r\nBFAAAAAoAAAEUAAABFAAAn5vzbwMlAAAQABJREFUeAHs3Qe4bkdVPnCsdEIAIQga\r\nKSEUQ5EiwdAREQuCIBixg4hUEVGBPwEk0qSLEkkBgUQwoURKUEPoEEA0IB0TwBAE\r\nEkICCBb4/ve39f0y9+OUW86559x73v08883smTVr1qx598z61p6998X+53/+Z9ZQ\r\nHRQDxUAxUAwUA8VAMVAMFAPFQDFQDBQDxUAxUAwUA7uHgYtVgbunwOqv+isGioFi\r\noBgoBoqBYqAYKAaKgWKgGCgGioFioBiAgTrauqOvOxqLgWKgGCgGioFioBgoBoqB\r\nYqAYKAaKgWKgGCgG1gADdbStgRLrta7XuhgoBoqBYqAYKAaKgWKgGCgGioFioBgo\r\nBoqBYqCOtjra6rEuBoqBYqAYKAaKgWKgGCgGioFioBgoBoqBYqAYWAMM1NG2Bkqs\r\nx7oe62KgGCgGioFioBgoBoqBYqAYKAaKgWKgGCgGioE62upoq8e6GCgGioFioBgo\r\nBoqBYqAYKAaKgWKgGCgGioFiYA0wUEfbGiixHut6rIuBYqAYKAaKgWKgGCgGioFi\r\noBgoBoqBYqAYKAbqaKujbV091v/93/89+9a3vjX75je/OYX/+q//mgnyxMozEYUu\r\nNOooQzPSjedoRno8wg/d2JZ05EA38kydxp0Ui4FioBgoBoqBYqAYKAaKgWKgGCgG\r\n9kUMjP+l9c95jvw/Hmny/zn/zTe7TiJn+pI+Ok8Zv4D8+ATS3+SvRR/raKujbe6Y\r\n...
2021-12-03 10:27:18:815 [HTTP] <-- GET /wd/hub/session/b3e8dbe5-f93a-451e-8021-9d2fa7d361d6/screenshot 200 112 ms - 508196
2021-12-03 10:27:18:815 [HTTP]
2021-12-03 10:27:20:262 [HTTP] --> DELETE /wd/hub/session/b3e8dbe5-f93a-451e-8021-9d2fa7d361d6
2021-12-03 10:27:20:262 [HTTP] {}
2021-12-03 10:27:20:262 [W3C (b3e8dbe5)] Calling AppiumDriver.deleteSession() with args: ["b3e8dbe5-f93a-451e-8021-9d2fa7d361d6"]
2021-12-03 10:27:20:263 [BaseDriver] Event 'quitSessionRequested' logged at 1638527240263 (05:27:20 GMT-0500 (Eastern Standard Time))
2021-12-03 10:27:20:263 [Appium] Removing session b3e8dbe5-f93a-451e-8021-9d2fa7d361d6 from our master session list
2021-12-03 10:27:20:264 [WD Proxy] Matched '/session/b3e8dbe5-f93a-451e-8021-9d2fa7d361d6' to command name 'deleteSession'
2021-12-03 10:27:20:264 [WD Proxy] Proxying [DELETE /session/b3e8dbe5-f93a-451e-8021-9d2fa7d361d6] to [DELETE http://127.0.0.1:8100/session/A322C6D4-6C03-4227-82C0-5B319386B6F7] with no body
2021-12-03 10:27:20:279 [Xcode] t = 18.69s Terminate com.apple.mobilesafari:20486
2021-12-03 10:27:20:280 [Xcode]
2021-12-03 10:27:20:295 [RemoteDebugger] Application 'PID:20486' disconnected. Removing from app dictionary.
2021-12-03 10:27:20:295 [RemoteDebugger] Current app is 'PID:20486'
2021-12-03 10:27:20:295 [RemoteDebugger] No longer have app id. Attempting to find new one.
2021-12-03 10:27:20:295 [RemoteDebugger] Application 'PID:20508' disconnected. Removing from app dictionary.
2021-12-03 10:27:20:295 [RemoteDebugger] Current app is 'undefined'
2021-12-03 10:27:20:310 [RemoteDebugger] Application 'PID:20524' disconnected. Removing from app dictionary.
2021-12-03 10:27:20:310 [RemoteDebugger] Current app is 'undefined'
2021-12-03 10:27:20:314 [RemoteDebugger] Application 'PID:20490' disconnected. Removing from app dictionary.
2021-12-03 10:27:20:314 [RemoteDebugger] Current app is 'undefined'
2021-12-03 10:27:21:316 [Xcode] t = 19.72s Wait for com.apple.mobilesafari to become Not Running
2021-12-03 10:27:21:316 [Xcode]
2021-12-03 10:27:21:317 [WD Proxy] Got response with status 200: {"value":null,"sessionId":null}
2021-12-03 10:27:21:317 [DevCon Factory] Releasing connections for C73AD1B7-31CE-4BC0-9730-D309BA884C1C device on any port number
2021-12-03 10:27:21:317 [DevCon Factory] Found cached connections to release: ["C73AD1B7-31CE-4BC0-9730-D309BA884C1C:8100"]
2021-12-03 10:27:21:317 [DevCon Factory] Cached connections count: 0
2021-12-03 10:27:21:317 [XCUITest] Not clearing log files. Use clearSystemFiles capability to turn on.
2021-12-03 10:27:21:317 [XCUITest] Found a remote debugger session. Removing...
2021-12-03 10:27:21:318 [RemoteDebugger] Disconnecting from remote debugger
2021-12-03 10:27:21:318 [RemoteDebugger] Cleaning up listeners
2021-12-03 10:27:21:318 [IOSSimulatorLog] Stopping iOS log capture
2021-12-03 10:27:21:339 [BaseDriver] Event 'quitSessionFinished' logged at 1638527241339 (05:27:21 GMT-0500 (Eastern Standard Time))
2021-12-03 10:27:21:340 [W3C (b3e8dbe5)] Received response: null
2021-12-03 10:27:21:340 [W3C (b3e8dbe5)] But deleting session, so not returning
2021-12-03 10:27:21:340 [W3C (b3e8dbe5)] Responding to client with driver.deleteSession() result: null
2021-12-03 10:27:21:340 [HTTP] <-- DELETE /wd/hub/session/b3e8dbe5-f93a-451e-8021-9d2fa7d361d6 200 1078 ms - 14
2021-12-03 10:27:21:340 [HTTP]
2021-12-03 10:27:21:539 [Xcode] 2021-12-03 05:27:21.538208-0500 WebDriverAgentRunner-Runner[20480:128572007] Disconnected a client from screenshots broadcast
2021-12-03 10:27:21:539 [Xcode]
2021-12-03 10:27:31:929 [Xcode]
2021-12-03 10:27:31:931 [Xcode]
2021-12-03 10:27:31:931 [Xcode] *** If you believe this error represents a bug, please attach the result bundle at /Users/user0/perfecto/WebDriverAgents/1.22.0/13.5/Logs/Test/Test-WebDriverAgentRunner-2021.12.03_05-26-42--0500.xcresult
2021-12-03 10:27:31:931 [Xcode]
2021-12-03 10:27:31:931 [Xcode]

KazuCocoa Dec 14, 2021
Maintainer

2021-12-03 10:27:02:505 [WD Proxy] Proxying [POST /session] to [POST http://127.0.0.1:8100/session] with body: {"capabilities":{"firstMatch":[{"bundleId":"com.apple.mobilesafari","arguments":[],"environment":{},"eventloopIdleDelaySec":0,"shouldWaitForQuiescence":true,"shouldUseTestManagerForVisibilityDetection":false,"maxTypingFrequency":60,"shouldUseSingletonTestManager":true,"shouldTerminateApp":true,"forceAppLaunch":true,"useNativeCachingStrategy":true,"forceSimulatorSoftwareKeyboardPresence":true}],"alwaysMatch":{}}}

useNativeCachingStrategy looks like it passed to the WDA.

Provide feedback

Saved searches

Use saved searches to filter your results more quickly

useNativeCachingStrategy #16186

{{title}}

Replies: 2 comments 5 replies

{{title}}

{{title}}

{{title}}

{{title}}

{{title}}

{{title}}

{{title}}

Select a reply

useNativeCachingStrategy #16186

rrawat0406 Dec 1, 2021

Replies: 2 comments · 5 replies

KazuCocoa Dec 1, 2021 Maintainer

rrawat0406 Dec 1, 2021 Author

KazuCocoa Dec 1, 2021 Maintainer

rrawat0406 Dec 1, 2021 Author

KazuCocoa Dec 1, 2021 Maintainer

rrawat0406 Dec 6, 2021 Author

KazuCocoa Dec 14, 2021 Maintainer

rrawat0406
Dec 1, 2021

Replies: 2 comments 5 replies

KazuCocoa
Dec 1, 2021
Maintainer

rrawat0406 Dec 1, 2021
Author

KazuCocoa
Dec 1, 2021
Maintainer

rrawat0406 Dec 1, 2021
Author

KazuCocoa Dec 1, 2021
Maintainer

rrawat0406 Dec 6, 2021
Author

KazuCocoa Dec 14, 2021
Maintainer