Appium - Mac2Driver

Hi Team,
Currently doing Automation for Desktop application.

When clicking on some Elements in the application(e.g. Button to load the details of a customer), the newly loaded elements are not recognised by Appium.

To further debug this, after clicking that button, executed ‘driver.getPageSource()’ command to see the source tree. But Appium server is throwing the error ----
Error Domain=com.apple.dt.xctest.automation-support.error Code=5 "Timed out snapshotting io.appium.WebDriverAgentRunner.xctrunner, app is either unresponsive or taking too long to snapshot.

This Error occurs only when UI is loaded with many elements. In other cases, Elements can be identified and driver.getPageSource() works normally.

Is this a limitation of Appium server ? Is there any way to overcome this ?

Attaching the full server logs -

[info] e[35m[HTTP]e[39m Waiting until the server is closed
[info] e[35m[HTTP]e[39m Received server close event
[info] e[35m[Appium]e[39m Welcome to Appium v1.20.2
[info] e[35m[Appium]e[39m Non-default server args:
[info] e[35m[Appium]e[39m relaxedSecurityEnabled: true
[info] e[35m[Appium]e[39m allowInsecure: {
[info] e[35m[Appium]e[39m }
[info] e[35m[Appium]e[39m denyInsecure: {
[info] e[35m[Appium]e[39m }
[info] e[35m[Appium]e[39m Appium REST http interface listener started on 0.0.0.0:4723[debug] e[35m[HTTP]e[39m Request idempotency key: 56d42fd6-b52f-4d96-8b65-8e56e4aee081
[info] e[35m[HTTP]e[39m e[37m–>e[39m e[37mPOSTe[39m e[37m/wd/hub/sessione[39m
[info] e[35m[HTTP]e[39m e[90m{“desiredCapabilities”:{“automationName”:“mac2”,“appium:bundleId”:“com.ist.Company.Prime.qa”,“appium:showServerLogs”:“true”,“platformName”:“mac”},“capabilities”:{“firstMatch”:[{“appium:bundleId”:“com.ist.Company.Prime.qa”,“appium:showServerLogs”:“true”,“appium:automationName”:“mac2”,“platformName”:“mac”}]}}e[39m
[debug] e[35m[W3C]e[39m Calling AppiumDriver.createSession() with args: [{“automationName”:“mac2”,“appium:bundleId”:“com.ist.Company.Prime.qa”,“appium:showServerLogs”:“true”,“platformName”:“mac”},null,{“firstMatch”:[{“appium:bundleId”:“com.ist.Company.Prime.qa”,“appium:showServerLogs”:“true”,“appium:automationName”:“mac2”,“platformName”:“mac”}]}]
[debug] e[35m[BaseDriver]e[39m Event ‘newSessionRequested’ logged at 1622008441747 (11:24:01 GMT+0530 (India Standard Time))
[info] e[35m[Appium]e[39m Appium v1.20.2 creating new Mac2Driver (v0.8.1) session
[info] e[35m[Appium]e[39m Applying relaxed security to ‘Mac2Driver’ as per server command line argument. All insecure features will be enabled unless explicitly disabled by --deny-insecure
[debug] e[35m[BaseDriver]e[39m W3C capabilities and MJSONWP desired capabilities were provided
[debug] e[35m[BaseDriver]e[39m Creating session with W3C capabilities: {
[debug] e[35m[BaseDriver]e[39m “alwaysMatch”: {
[debug] e[35m[BaseDriver]e[39m “platformName”: “mac”,
[debug] e[35m[BaseDriver]e[39m “appium:bundleId”: “com.ist.Company.Prime.qa”,
[debug] e[35m[BaseDriver]e[39m “appium:showServerLogs”: “true”,
[debug] e[35m[BaseDriver]e[39m “appium:automationName”: “mac2”
[debug] e[35m[BaseDriver]e[39m },
[debug] e[35m[BaseDriver]e[39m “firstMatch”: [
[debug] e[35m[BaseDriver]e[39m {}
[debug] e[35m[BaseDriver]e[39m ]
[debug] e[35m[BaseDriver]e[39m }
[warn] e[35m[BaseDriver]e[39m Capability ‘showServerLogs’ changed from string to boolean. This may cause unexpected behavior
[info] e[35m[BaseDriver]e[39m Session created with session id: 8453dd53-4518-4643-869f-b3d15fe7a09c
[debug] e[35m[WD Proxy]e[39m Matched ‘/status’ to command name ‘getStatus’
[debug] e[35m[WD Proxy]e[39m Proxying [GET /status] to [GET http://127.0.0.1:10100/status] with no body
[debug] e[35m[WD Proxy]e[39m Got response with status 200: {“value”:{“message”:“WebDriverAgent is ready to accept commands”,“state”:“success”,“os”:{“version”:“Version 10.15.7 (Build 19H1030)”},“ready”:true,“build”:{“time”:“Apr 26 2021 09:35:52”}},“sessionId”:null}
[info] e[35m[WebDriverAgentMac]e[39m The host process has already been listening. Proceeding with session creation
[debug] e[35m[WD Proxy]e[39m Matched ‘/session’ to command name ‘createSession’
[debug] e[35m[WD Proxy]e[39m Proxying [POST /session] to [POST http://127.0.0.1:10100/session] with body: {“capabilities”:{“firstMatch”:[{}],“alwaysMatch”:{“platformName”:“mac”,“bundleId”:“com.ist.Company.Prime.qa”,“showServerLogs”:true,“automationName”:“mac2”}}}
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 90128.46s Open com.ist.Company.Prime.qa
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 90128.46s Launch com.ist.Company.Prime.qa
[debug] e[35m[WebDriverAgentMac]e[39m 2021-05-26 11:24:01.755850+0530 WebDriverAgentRunner-Runner[47566:973114] Launching macOS application via NSWorkspace while sandboxed. Launch arguments and environment variables may be dropped.[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 90129.22s Wait for accessibility to load[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 90129.70s Setting up automation session[debug] e[35m[WD Proxy]e[39m Got response with status 200: {“value”:{“sessionId”:“ED3D4463-95FC-452D-A287-65B42265F94A”,“capabilities”:{“CFBundleIdentifier”:“com.ist.Company.Prime.qa”}},“sessionId”:“ED3D4463-95FC-452D-A287-65B42265F94A”}
[info] e[35m[WD Proxy]e[39m Determined the downstream protocol as ‘W3C’
[info] e[35m[Appium]e[39m New Mac2Driver session created successfully, session 8453dd53-4518-4643-869f-b3d15fe7a09c added to master session list
[debug] e[35m[BaseDriver]e[39m Event ‘newSessionStarted’ logged at 1622008443040 (11:24:03 GMT+0530 (India Standard Time))
[debug] e[35m[W3C (8453dd53)]e[39m Cached the protocol value ‘W3C’ for the new session 8453dd53-4518-4643-869f-b3d15fe7a09c
[debug] e[35m[W3C (8453dd53)]e[39m Responding to client with driver.createSession() result: {“capabilities”:{“platformName”:“mac”,“bundleId”:“com.ist.Company.Prime.qa”,“showServerLogs”:true,“automationName”:“mac2”}}
[info] e[35m[HTTP]e[39m e[37m<-- POST /wd/hub/session e[39me[32m200e[39m e[90m1294 ms - 182e[39m
[info] e[35m[HTTP]e[39m e[90me[39m
[info] e[35m[HTTP]e[39m e[37m–>e[39m e[37mPOSTe[39m e[37m/wd/hub/session/8453dd53-4518-4643-869f-b3d15fe7a09c/appium/settingse[39m
[info] e[35m[HTTP]e[39m e[90m{“settings”:{“allowInvisibleElements”:true}}e[39m
[debug] e[35m[W3C (8453dd53)]e[39m Calling AppiumDriver.updateSettings() with args: [{“allowInvisibleElements”:true},“8453dd53-4518-4643-869f-b3d15fe7a09c”]
[debug] e[35m[WD Proxy]e[39m Matched ‘/appium/settings’ to command name ‘updateSettings’
[debug] e[35m[WD Proxy]e[39m Proxying [POST /appium/settings] to [POST http://127.0.0.1:10100/session/ED3D4463-95FC-452D-A287-65B42265F94A/appium/settings] with body: {“settings”:{“allowInvisibleElements”:true}}
[debug] e[35m[WD Proxy]e[39m Got response with status 200: {“value”:{“boundElementsByIndex”:false,“useDefaultUiInterruptionsHandling”:1},“sessionId”:“ED3D4463-95FC-452D-A287-65B42265F94A”}
[debug] e[35m[W3C (8453dd53)]e[39m Responding to client with driver.updateSettings() result: null
[info] e[35m[HTTP]e[39m e[37m<-- POST /wd/hub/session/8453dd53-4518-4643-869f-b3d15fe7a09c/appium/settings e[39me[32m200e[39m e[90m18 ms - 14e[39m
[info] e[35m[HTTP]e[39m e[90me[39m[info] e[35m[HTTP]e[39m e[37m–>e[39m e[37mPOSTe[39m e[37m/wd/hub/session/8453dd53-4518-4643-869f-b3d15fe7a09c/elemente[39m
[info] e[35m[HTTP]e[39m e[90m{“using”:“xpath”,“value”:"//XCUIElementTypeTable/XCUIElementTypeCell/XCUIElementTypeStaticText[@label=‘My Cases’]"}e[39m
[debug] e[35m[W3C (8453dd53)]e[39m Calling AppiumDriver.findElement() with args: [“xpath”,"//XCUIElementTypeTable/XCUIElementTypeCell/XCUIElementTypeStaticText[@label=‘My Cases’]",“8453dd53-4518-4643-869f-b3d15fe7a09c”]
[debug] e[35m[BaseDriver]e[39m Valid locator strategies for this request: id, name, accessibility id, xpath, class name, -ios predicate string, predicate string, -ios class chain, class chain
[debug] e[35m[WD Proxy]e[39m Matched ‘/element’ to command name ‘findElement’
[debug] e[35m[WD Proxy]e[39m Proxying [POST /element] to [POST http://127.0.0.1:10100/session/ED3D4463-95FC-452D-A287-65B42265F94A/element] with body: {“using”:“xpath”,“value”:"//XCUIElementTypeTable/XCUIElementTypeCell/XCUIElementTypeStaticText[@label=‘My Cases’]"}
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 90139.82s Find the Application ‘com.ist.Company.Prime.qa’[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 90140.95s Get all elements bound by accessibility element for: Elements matching predicate ‘BLOCKPREDICATE(0x6000018c1b90)’
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 90140.95s Requesting snapshot of accessibility hierarchy for app with pid 87292[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 90141.41s Find: Descendants matching type Any
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 90141.41s Find: Elements matching predicate ‘BLOCKPREDICATE(0x6000018c1b90)’
[debug] e[35m[WD Proxy]e[39m Got response with status 200: {“value”:{“ELEMENT”:“6F008213-726F-41D8-80CF-C1EE68F7446A”,“element-6066-11e4-a52e-4f735466cecf”:“6F008213-726F-41D8-80CF-C1EE68F7446A”},“sessionId”:“ED3D4463-95FC-452D-A287-65B42265F94A”}
[debug] e[35m[W3C (8453dd53)]e[39m Responding to client with driver.findElement() result: {“element-6066-11e4-a52e-4f735466cecf”:“6F008213-726F-41D8-80CF-C1EE68F7446A”,“ELEMENT”:“6F008213-726F-41D8-80CF-C1EE68F7446A”}
[info] e[35m[HTTP]e[39m e[37m<-- POST /wd/hub/session/8453dd53-4518-4643-869f-b3d15fe7a09c/element e[39me[32m200e[39m e[90m1598 ms - 137e[39m
[info] e[35m[HTTP]e[39m e[90me[39m
[info] e[35m[HTTP]e[39m e[37m–>e[39m e[37mPOSTe[39m e[37m/wd/hub/session/8453dd53-4518-4643-869f-b3d15fe7a09c/element/6F008213-726F-41D8-80CF-C1EE68F7446A/clicke[39m
[info] e[35m[HTTP]e[39m e[90m{“id”:“6F008213-726F-41D8-80CF-C1EE68F7446A”}e[39m
[info] e[35m[W3C (8453dd53)]e[39m Driver proxy active, passing request on via HTTP proxy
[debug] e[35m[WD Proxy]e[39m Matched ‘/wd/hub/session/8453dd53-4518-4643-869f-b3d15fe7a09c/element/6F008213-726F-41D8-80CF-C1EE68F7446A/click’ to command name ‘click’
[debug] e[35m[WD Proxy]e[39m Proxying [POST /wd/hub/session/8453dd53-4518-4643-869f-b3d15fe7a09c/element/6F008213-726F-41D8-80CF-C1EE68F7446A/click] to [POST http://127.0.0.1:10100/session/ED3D4463-95FC-452D-A287-65B42265F94A/element/6F008213-726F-41D8-80CF-C1EE68F7446A/click] with body: {“id”:“6F008213-726F-41D8-80CF-C1EE68F7446A”}
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 90141.44s Find the “My Cases” StaticText
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 90141.44s Requesting snapshot of accessibility hierarchy for app with pid 87292[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 90141.88s Find: Descendants matching type Any
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 90141.88s Find: Elements matching predicate ‘BLOCKPREDICATE(0x6000018c1b90)’
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 90141.89s Find: Identity Binding
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 90141.89s Click “My Cases” StaticText
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 90141.89s Wait for com.ist.Company.Prime.qa to idle
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 90141.90s Find the “My Cases” StaticText
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 90141.90s Requesting snapshot of accessibility hierarchy for app with pid 87292[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 90142.33s Find: Descendants matching type Any
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 90142.33s Find: Elements matching predicate ‘BLOCKPREDICATE(0x6000018c1b90)’
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 90142.33s Find: Identity Binding
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 90142.35s Synthesize event[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 90142.66s Wait for com.ist.Company.Prime.qa to idle
[debug] e[35m[WD Proxy]e[39m Got response with status 200: {“value”:null,“sessionId”:“ED3D4463-95FC-452D-A287-65B42265F94A”}
[info] e[35m[WD Proxy]e[39m Replacing sessionId ED3D4463-95FC-452D-A287-65B42265F94A with 8453dd53-4518-4643-869f-b3d15fe7a09c
[info] e[35m[HTTP]e[39m e[37m<-- POST /wd/hub/session/8453dd53-4518-4643-869f-b3d15fe7a09c/element/6F008213-726F-41D8-80CF-C1EE68F7446A/click e[39me[32m200e[39m e[90m1291 ms - 65e[39m
[info] e[35m[HTTP]e[39m e[90me[39m
[info] e[35m[HTTP]e[39m e[37m–>e[39m e[37mPOSTe[39m e[37m/wd/hub/session/8453dd53-4518-4643-869f-b3d15fe7a09c/elemente[39m
[info] e[35m[HTTP]e[39m e[90m{“using”:“xpath”,“value”:"//XCUIElementTypeTable/XCUIElementTypeCell/XCUIElementTypeStaticText[@label=‘Unassigned’]"}e[39m
[debug] e[35m[W3C (8453dd53)]e[39m Calling AppiumDriver.findElement() with args: [“xpath”,"//XCUIElementTypeTable/XCUIElementTypeCell/XCUIElementTypeStaticText[@label=‘Unassigned’]",“8453dd53-4518-4643-869f-b3d15fe7a09c”]
[debug] e[35m[BaseDriver]e[39m Valid locator strategies for this request: id, name, accessibility id, xpath, class name, -ios predicate string, predicate string, -ios class chain, class chain
[debug] e[35m[WD Proxy]e[39m Matched ‘/element’ to command name ‘findElement’
[debug] e[35m[WD Proxy]e[39m Proxying [POST /element] to [POST http://127.0.0.1:10100/session/ED3D4463-95FC-452D-A287-65B42265F94A/element] with body: {“using”:“xpath”,“value”:"//XCUIElementTypeTable/XCUIElementTypeCell/XCUIElementTypeStaticText[@label=‘Unassigned’]"}
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 90142.73s Find the Application ‘com.ist.Company.Prime.qa’[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 90143.35s Get all elements bound by accessibility element for: Elements matching predicate ‘BLOCKPREDICATE(0x60000183b960)’
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 90143.35s Requesting snapshot of accessibility hierarchy for app with pid 87292[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 90143.79s Find: Descendants matching type Any
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 90143.80s Find: Elements matching predicate ‘BLOCKPREDICATE(0x60000183b960)’
[debug] e[35m[WD Proxy]e[39m Got response with status 200: {“value”:{“ELEMENT”:“666B3D10-3517-438B-BDB5-273C6081EE33”,“element-6066-11e4-a52e-4f735466cecf”:“666B3D10-3517-438B-BDB5-273C6081EE33”},“sessionId”:“ED3D4463-95FC-452D-A287-65B42265F94A”}
[debug] e[35m[W3C (8453dd53)]e[39m Responding to client with driver.findElement() result: {“element-6066-11e4-a52e-4f735466cecf”:“666B3D10-3517-438B-BDB5-273C6081EE33”,“ELEMENT”:“666B3D10-3517-438B-BDB5-273C6081EE33”}
[info] e[35m[HTTP]e[39m e[37m<-- POST /wd/hub/session/8453dd53-4518-4643-869f-b3d15fe7a09c/element e[39me[32m200e[39m e[90m1077 ms - 137e[39m
[info] e[35m[HTTP]e[39m e[90me[39m
[info] e[35m[HTTP]e[39m e[37m–>e[39m e[37mPOSTe[39m e[37m/wd/hub/session/8453dd53-4518-4643-869f-b3d15fe7a09c/element/666B3D10-3517-438B-BDB5-273C6081EE33/clicke[39m
[info] e[35m[HTTP]e[39m e[90m{“id”:“666B3D10-3517-438B-BDB5-273C6081EE33”}e[39m
[info] e[35m[W3C (8453dd53)]e[39m Driver proxy active, passing request on via HTTP proxy
[debug] e[35m[WD Proxy]e[39m Matched ‘/wd/hub/session/8453dd53-4518-4643-869f-b3d15fe7a09c/element/666B3D10-3517-438B-BDB5-273C6081EE33/click’ to command name ‘click’
[debug] e[35m[WD Proxy]e[39m Proxying [POST /wd/hub/session/8453dd53-4518-4643-869f-b3d15fe7a09c/element/666B3D10-3517-438B-BDB5-273C6081EE33/click] to [POST http://127.0.0.1:10100/session/ED3D4463-95FC-452D-A287-65B42265F94A/element/666B3D10-3517-438B-BDB5-273C6081EE33/click] with body: {“id”:“666B3D10-3517-438B-BDB5-273C6081EE33”}
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 90143.81s Find the “Unassigned” StaticText
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 90143.81s Requesting snapshot of accessibility hierarchy for app with pid 87292[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 90144.28s Find: Descendants matching type Any
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 90144.28s Find: Elements matching predicate ‘BLOCKPREDICATE(0x60000183b960)’
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 90144.28s Find: Identity Binding
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 90144.29s Click “Unassigned” StaticText
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 90144.29s Wait for com.ist.Company.Prime.qa to idle
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 90144.29s Find the “Unassigned” StaticText
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 90144.29s Requesting snapshot of accessibility hierarchy for app with pid 87292[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 90144.74s Find: Descendants matching type Any
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 90144.74s Find: Elements matching predicate ‘BLOCKPREDICATE(0x60000183b960)’
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 90144.74s Find: Identity Binding
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 90144.75s Synthesize event[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 90145.06s Wait for com.ist.Company.Prime.qa to idle[debug] e[35m[WD Proxy]e[39m Got response with status 200: {“value”:null,“sessionId”:“ED3D4463-95FC-452D-A287-65B42265F94A”}
[info] e[35m[WD Proxy]e[39m Replacing sessionId ED3D4463-95FC-452D-A287-65B42265F94A with 8453dd53-4518-4643-869f-b3d15fe7a09c
[info] e[35m[HTTP]e[39m e[37m<-- POST /wd/hub/session/8453dd53-4518-4643-869f-b3d15fe7a09c/element/666B3D10-3517-438B-BDB5-273C6081EE33/click e[39me[32m200e[39m e[90m1308 ms - 65e[39m
[info] e[35m[HTTP]e[39m e[90me[39m[info] e[35m[HTTP]e[39m e[37m–>e[39m e[37mPOSTe[39m e[37m/wd/hub/session/8453dd53-4518-4643-869f-b3d15fe7a09c/elemente[39m
[info] e[35m[HTTP]e[39m e[90m{“using”:“xpath”,“value”:"//[@=‘Dhandeti chandrahas’]"}e[39m
[debug] e[35m[W3C (8453dd53)]e[39m Calling AppiumDriver.findElement() with args: [“xpath”,"//[@=‘Dhandeti chandrahas’]",“8453dd53-4518-4643-869f-b3d15fe7a09c”]
[debug] e[35m[BaseDriver]e[39m Valid locator strategies for this request: id, name, accessibility id, xpath, class name, -ios predicate string, predicate string, -ios class chain, class chain
[debug] e[35m[WD Proxy]e[39m Matched ‘/element’ to command name ‘findElement’
[debug] e[35m[WD Proxy]e[39m Proxying [POST /element] to [POST http://127.0.0.1:10100/session/ED3D4463-95FC-452D-A287-65B42265F94A/element] with body: {“using”:“xpath”,“value”:"//[@=‘Dhandeti chandrahas’]"}
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 90150.13s Find the Application ‘com.ist.Company.Prime.qa’[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 90151.77s Get all elements bound by accessibility element for: Elements matching predicate ‘BLOCKPREDICATE(0x6000018f8a20)’
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 90151.77s Requesting snapshot of accessibility hierarchy for app with pid 87292[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 90153.00s Find: Descendants matching type Any
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 90153.01s Find: Elements matching predicate ‘BLOCKPREDICATE(0x6000018f8a20)’
[debug] e[35m[WD Proxy]e[39m Got response with status 200: {“value”:{“ELEMENT”:“9D131165-63EA-4F47-9558-F8CCF8FB59F7”,“element-6066-11e4-a52e-4f735466cecf”:“9D131165-63EA-4F47-9558-F8CCF8FB59F7”},“sessionId”:“ED3D4463-95FC-452D-A287-65B42265F94A”}
[debug] e[35m[W3C (8453dd53)]e[39m Responding to client with driver.findElement() result: {“element-6066-11e4-a52e-4f735466cecf”:“9D131165-63EA-4F47-9558-F8CCF8FB59F7”,“ELEMENT”:“9D131165-63EA-4F47-9558-F8CCF8FB59F7”}
[info] e[35m[HTTP]e[39m e[37m<-- POST /wd/hub/session/8453dd53-4518-4643-869f-b3d15fe7a09c/element e[39me[32m200e[39m e[90m2908 ms - 137e[39m
[info] e[35m[HTTP]e[39m e[90me[39m
[info] e[35m[HTTP]e[39m e[37m–>e[39m e[37mPOSTe[39m e[37m/wd/hub/session/8453dd53-4518-4643-869f-b3d15fe7a09c/element/9D131165-63EA-4F47-9558-F8CCF8FB59F7/clicke[39m
[info] e[35m[HTTP]e[39m e[90m{“id”:“9D131165-63EA-4F47-9558-F8CCF8FB59F7”}e[39m
[info] e[35m[W3C (8453dd53)]e[39m Driver proxy active, passing request on via HTTP proxy
[debug] e[35m[WD Proxy]e[39m Matched ‘/wd/hub/session/8453dd53-4518-4643-869f-b3d15fe7a09c/element/9D131165-63EA-4F47-9558-F8CCF8FB59F7/click’ to command name ‘click’
[debug] e[35m[WD Proxy]e[39m Proxying [POST /wd/hub/session/8453dd53-4518-4643-869f-b3d15fe7a09c/element/9D131165-63EA-4F47-9558-F8CCF8FB59F7/click] to [POST http://127.0.0.1:10100/session/ED3D4463-95FC-452D-A287-65B42265F94A/element/9D131165-63EA-4F47-9558-F8CCF8FB59F7/click] with body: {“id”:“9D131165-63EA-4F47-9558-F8CCF8FB59F7”}
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 90153.04s Find the “Dhandeti chandrahas” StaticText
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 90153.04s Requesting snapshot of accessibility hierarchy for app with pid 87292[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 90154.28s Find: Descendants matching type Any
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 90154.28s Find: Elements matching predicate ‘BLOCKPREDICATE(0x6000018f8a20)’
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 90154.29s Find: Identity Binding
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 90154.30s Click “Dhandeti chandrahas” StaticText
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 90154.30s Wait for com.ist.Company.Prime.qa to idle
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 90154.30s Find the “Dhandeti chandrahas” StaticText
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 90154.30s Requesting snapshot of accessibility hierarchy for app with pid 87292[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 90155.53s Find: Descendants matching type Any
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 90155.53s Find: Elements matching predicate ‘BLOCKPREDICATE(0x6000018f8a20)’
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 90155.53s Find: Identity Binding
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 90155.55s Synthesize event[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 90155.86s Wait for com.ist.Company.Prime.qa to idle[debug] e[35m[WD Proxy]e[39m Got response with status 200: {“value”:null,“sessionId”:“ED3D4463-95FC-452D-A287-65B42265F94A”}
[info] e[35m[WD Proxy]e[39m Replacing sessionId ED3D4463-95FC-452D-A287-65B42265F94A with 8453dd53-4518-4643-869f-b3d15fe7a09c
[info] e[35m[HTTP]e[39m e[37m<-- POST /wd/hub/session/8453dd53-4518-4643-869f-b3d15fe7a09c/element/9D131165-63EA-4F47-9558-F8CCF8FB59F7/click e[39me[32m200e[39m e[90m2948 ms - 65e[39m
[info] e[35m[HTTP]e[39m e[90me[39m[info] e[35m[HTTP]e[39m e[37m–>e[39m e[37mGETe[39m e[37m/wd/hub/session/8453dd53-4518-4643-869f-b3d15fe7a09c/sourcee[39m
[info] e[35m[HTTP]e[39m e[90m{}e[39m
[info] e[35m[W3C (8453dd53)]e[39m Driver proxy active, passing request on via HTTP proxy
[debug] e[35m[WD Proxy]e[39m Matched ‘/wd/hub/session/8453dd53-4518-4643-869f-b3d15fe7a09c/source’ to command name ‘getPageSource’
[debug] e[35m[WD Proxy]e[39m Proxying [GET /wd/hub/session/8453dd53-4518-4643-869f-b3d15fe7a09c/source] to [GET http://127.0.0.1:10100/session/ED3D4463-95FC-452D-A287-65B42265F94A/source] with no body
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 90160.99s Find the Application ‘com.ist.Company.Prime.qa’[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 90162.62s Find the Application ‘com.ist.Company.Prime.qa’ (retry 1)[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 90164.22s Find the Application ‘com.ist.Company.Prime.qa’ (retry 2)[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] 2021-05-26 11:24:38.136262+0530 WebDriverAgentRunner-Runner[47566:973114] The snapshot of Application ‘com.ist.Company.Prime.qa’ cannot be taken. Original error: Error Domain=com.Company.dt.xctest.ui-testing.error Code=10018 “Failed to resolve remote element AX element pid: 87308, token: DFUBAPxUAQBvY29j/v///////38AAAAAAAAAAAxVAQAAABw+elqUAg8nAAAAAAAA” UserInfo={NSLocalizedDescription=Failed to resolve remote element AX element pid: 87308, token: DFUBAPxUAQBvY29j/v///////38AAAAAAAAAAAxVAQAAABw+elqUAg8nAAAAAAAA, NSUnderlyingError=0x6000019c31b0 {Error Domain=com.Company.dt.xctest.automation-support.error Code=5 “Timed out snapshotting io.appium.WebDriverAgentRunner.xctrunner, app is either unresponsive or taking too long to snapshot.” UserInfo={accessibility-error=-25204, NSLocalizedDescription=Timed out snapshotting io.appium.WebDriverAgentRunner.xctrunner, app is either unresponsive or taking too long to snapshot.}}}
[info] e[35m[WD Proxy]e[39m Got response with status 500: {“value”:{“error”:“unknown error”,“message”:“Cannot get ‘xml’ source of the current application”,“traceback”:""},“sessionId”:“ED3D4463-95FC-452D-A287-65B42265F94A”}
[debug] e[35m[W3C]e[39m Matched W3C error code ‘unknown error’ to UnknownError
[info] e[35m[HTTP]e[39m e[37m<-- GET /wd/hub/session/8453dd53-4518-4643-869f-b3d15fe7a09c/source e[39me[31m500e[39m e[90m3850 ms - 835e[39m
[info] e[35m[HTTP]e[39m e[90me[39m
[info] e[35m[HTTP]e[39m e[37m–>e[39m e[37mDELETEe[39m e[37m/wd/hub/session/8453dd53-4518-4643-869f-b3d15fe7a09ce[39m
[info] e[35m[HTTP]e[39m e[90m{}e[39m
[debug] e[35m[W3C (8453dd53)]e[39m Calling AppiumDriver.deleteSession() with args: [“8453dd53-4518-4643-869f-b3d15fe7a09c”]
[debug] e[35m[BaseDriver]e[39m Event ‘quitSessionRequested’ logged at 1622008478162 (11:24:38 GMT+0530 (India Standard Time))
[info] e[35m[Appium]e[39m Removing session 8453dd53-4518-4643-869f-b3d15fe7a09c from our master session list
[debug] e[35m[WD Proxy]e[39m Matched ‘/session/ED3D4463-95FC-452D-A287-65B42265F94A’ to command name ‘deleteSession’
[debug] e[35m[WD Proxy]e[39m Proxying [DELETE /session/ED3D4463-95FC-452D-A287-65B42265F94A] to [DELETE http://127.0.0.1:10100/session/ED3D4463-95FC-452D-A287-65B42265F94A] with no body
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 90164.87s Terminate com.ist.Company.Prime.qa:87292[debug] e[35m[WD Proxy]e[39m Got response with status 200: {“value”:null,“sessionId”:null}
[debug] e[35m[BaseDriver]e[39m Event ‘quitSessionFinished’ logged at 1622008479194 (11:24:39 GMT+0530 (India Standard Time))
[debug] e[35m[W3C (8453dd53)]e[39m Received response: null
[debug] e[35m[W3C (8453dd53)]e[39m But deleting session, so not returning
[debug] e[35m[W3C (8453dd53)]e[39m Responding to client with driver.deleteSession() result: null
[info] e[35m[HTTP]e[39m e[37m<-- DELETE /wd/hub/session/8453dd53-4518-4643-869f-b3d15fe7a09c e[39me[32m200e[39m e[90m1033 ms - 14e[39m
[info] e[35m[HTTP]e[39m e[90me[39m

This seems to be a limitation of XCTest framework. Only Apple is able to change that behaviour

Thanks for the reply.
So in that case when I am contacting the Dev team, what should issue should I describe, so that they can give me a better build ?

Apple itself does not seem to be very responsive to such kind of issues: XCode Version: 10.2.1: Timed out s… | Apple Developer Forums

I would try to make sure the app is not hogging the UI thread and that it does not have too many elements in a single visible view

Thanks for the reply.

In my case, observed that the elements gets identified sometimes, but most of the times it will be showing the error -
" An unknown server-side error occurred while processing the command. Original error: Cannot evaluate results for XPath"

Not sure whether this is due the above mentioned issue as pointed.

Sorry, I cannot provide more details there without detail XCTest and Appium server logs

Attaching the complete Appium Logs for the case where the Element is not getting identified.


[info] e[35m[HTTP]e[39m Waiting until the server is closed
[info] e[35m[HTTP]e[39m Received server close event
[info] e[35m[Appium]e[39m Welcome to Appium v1.20.2
[info] e[35m[Appium]e[39m Non-default server args:
[info] e[35m[Appium]e[39m relaxedSecurityEnabled: true
[info] e[35m[Appium]e[39m allowInsecure: {
[info] e[35m[Appium]e[39m }
[info] e[35m[Appium]e[39m denyInsecure: {
[info] e[35m[Appium]e[39m }
[info] e[35m[Appium]e[39m Appium REST http interface listener started on 0.0.0.0:4723[debug] e[35m[HTTP]e[39m Request idempotency key: d8d89d63-adb4-4ff0-8628-74bd549859ce
[info] e[35m[HTTP]e[39m e[37m–>e[39m e[37mPOSTe[39m e[37m/wd/hub/sessione[39m
[info] e[35m[HTTP]e[39m e[90m{“desiredCapabilities”:{“automationName”:“mac2”,“appium:bundleId”:“com.ist.application.Prime.qa”,“appium:showServerLogs”:“true”,“platformName”:“mac”},“capabilities”:{“firstMatch”:[{“appium:bundleId”:“com.ist.application.Prime.qa”,“appium:showServerLogs”:“true”,“appium:automationName”:“mac2”,“platformName”:“mac”}]}}e[39m
[debug] e[35m[W3C]e[39m Calling AppiumDriver.createSession() with args: [{“automationName”:“mac2”,“appium:bundleId”:“com.ist.application.Prime.qa”,“appium:showServerLogs”:“true”,“platformName”:“mac”},null,{“firstMatch”:[{“appium:bundleId”:“com.ist.application.Prime.qa”,“appium:showServerLogs”:“true”,“appium:automationName”:“mac2”,“platformName”:“mac”}]}]
[debug] e[35m[BaseDriver]e[39m Event ‘newSessionRequested’ logged at 1622036239681 (19:07:19 GMT+0530 (India Standard Time))
[info] e[35m[Appium]e[39m Appium v1.20.2 creating new Mac2Driver (v0.8.1) session
[info] e[35m[Appium]e[39m Applying relaxed security to ‘Mac2Driver’ as per server command line argument. All insecure features will be enabled unless explicitly disabled by --deny-insecure
[debug] e[35m[BaseDriver]e[39m W3C capabilities and MJSONWP desired capabilities were provided
[debug] e[35m[BaseDriver]e[39m Creating session with W3C capabilities: {
[debug] e[35m[BaseDriver]e[39m “alwaysMatch”: {
[debug] e[35m[BaseDriver]e[39m “platformName”: “mac”,
[debug] e[35m[BaseDriver]e[39m “appium:bundleId”: “com.ist.application.Prime.qa”,
[debug] e[35m[BaseDriver]e[39m “appium:showServerLogs”: “true”,
[debug] e[35m[BaseDriver]e[39m “appium:automationName”: “mac2”
[debug] e[35m[BaseDriver]e[39m },
[debug] e[35m[BaseDriver]e[39m “firstMatch”: [
[debug] e[35m[BaseDriver]e[39m {}
[debug] e[35m[BaseDriver]e[39m ]
[debug] e[35m[BaseDriver]e[39m }
[warn] e[35m[BaseDriver]e[39m Capability ‘showServerLogs’ changed from string to boolean. This may cause unexpected behavior
[info] e[35m[BaseDriver]e[39m Session created with session id: e4be27d1-a4dc-49b9-9043-8bf2e93af067
[debug] e[35m[WD Proxy]e[39m Matched ‘/status’ to command name ‘getStatus’
[debug] e[35m[WD Proxy]e[39m Proxying [GET /status] to [GET http://127.0.0.1:10100/status] with no body
[debug] e[35m[WD Proxy]e[39m Got response with status 200: {“value”:{“message”:“WebDriverAgent is ready to accept commands”,“state”:“success”,“os”:{“version”:“Version 10.15.7 (Build 19H1030)”},“ready”:true,“build”:{“time”:“Apr 26 2021 09:35:52”}},“sessionId”:“2DC54EAF-516A-48DA-B057-31DEC18A4212”}
[info] e[35m[WebDriverAgentMac]e[39m The host process has already been listening. Proceeding with session creation
[debug] e[35m[WD Proxy]e[39m Matched ‘/session’ to command name ‘createSession’
[debug] e[35m[WD Proxy]e[39m Proxying [POST /session] to [POST http://127.0.0.1:10100/session] with body: {“capabilities”:{“firstMatch”:[{}],“alwaysMatch”:{“platformName”:“mac”,“bundleId”:“com.ist.application.Prime.qa”,“showServerLogs”:true,“automationName”:“mac2”}}}
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 277.16s Terminate com.ist.application.Prime.qa:13747[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 278.16s Open com.ist.application.Prime.qa
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 278.17s Launch com.ist.application.Prime.qa
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] 2021-05-26 19:07:20.699351+0530 WebDriverAgentRunner-Runner[13697:2089447] Launching macOS application via NSWorkspace while sandboxed. Launch arguments and environment variables may be dropped.[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 278.65s Wait for accessibility to load[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 279.08s Setting up automation session[debug] e[35m[WD Proxy]e[39m Got response with status 200: {“value”:{“sessionId”:“F23E0D08-A6BC-47C9-99A5-182D049E39EA”,“capabilities”:{“CFBundleIdentifier”:“com.ist.application.Prime.qa”}},“sessionId”:“F23E0D08-A6BC-47C9-99A5-182D049E39EA”}
[info] e[35m[WD Proxy]e[39m Determined the downstream protocol as ‘W3C’
[info] e[35m[Appium]e[39m New Mac2Driver session created successfully, session e4be27d1-a4dc-49b9-9043-8bf2e93af067 added to master session list
[debug] e[35m[BaseDriver]e[39m Event ‘newSessionStarted’ logged at 1622036241632 (19:07:21 GMT+0530 (India Standard Time))
[debug] e[35m[W3C (e4be27d1)]e[39m Cached the protocol value ‘W3C’ for the new session e4be27d1-a4dc-49b9-9043-8bf2e93af067
[debug] e[35m[W3C (e4be27d1)]e[39m Responding to client with driver.createSession() result: {“capabilities”:{“platformName”:“mac”,“bundleId”:“com.ist.application.Prime.qa”,“showServerLogs”:true,“automationName”:“mac2”}}
[info] e[35m[HTTP]e[39m e[37m<-- POST /wd/hub/session e[39me[32m200e[39m e[90m1952 ms - 182e[39m
[info] e[35m[HTTP]e[39m e[90me[39m
[info] e[35m[HTTP]e[39m e[37m–>e[39m e[37mPOSTe[39m e[37m/wd/hub/session/e4be27d1-a4dc-49b9-9043-8bf2e93af067/timeoutse[39m
[info] e[35m[HTTP]e[39m e[90m{“pageLoad”:30000}e[39m
[info] e[35m[W3C (e4be27d1)]e[39m Driver proxy active, passing request on via HTTP proxy
[debug] e[35m[WD Proxy]e[39m Matched ‘/wd/hub/session/e4be27d1-a4dc-49b9-9043-8bf2e93af067/timeouts’ to command name ‘timeouts’
[debug] e[35m[Protocol Converter]e[39m Will send the following request bodies to /timeouts: [{“pageLoad”:30000}]
[debug] e[35m[WD Proxy]e[39m Proxying [POST /wd/hub/session/e4be27d1-a4dc-49b9-9043-8bf2e93af067/timeouts] to [POST http://127.0.0.1:10100/session/F23E0D08-A6BC-47C9-99A5-182D049E39EA/timeouts] with body: {“pageLoad”:30000}
[debug] e[35m[WD Proxy]e[39m Got response with status 200: {“value”:null,“sessionId”:“F23E0D08-A6BC-47C9-99A5-182D049E39EA”}
[info] e[35m[WD Proxy]e[39m Replacing sessionId F23E0D08-A6BC-47C9-99A5-182D049E39EA with e4be27d1-a4dc-49b9-9043-8bf2e93af067
[info] e[35m[HTTP]e[39m e[37m<-- POST /wd/hub/session/e4be27d1-a4dc-49b9-9043-8bf2e93af067/timeouts e[39me[32m200e[39m e[90m3 ms - 65e[39m
[info] e[35m[HTTP]e[39m e[90me[39m
[info] e[35m[HTTP]e[39m e[37m–>e[39m e[37mPOSTe[39m e[37m/wd/hub/session/e4be27d1-a4dc-49b9-9043-8bf2e93af067/timeoutse[39m
[info] e[35m[HTTP]e[39m e[90m{“script”:30000}e[39m
[info] e[35m[W3C (e4be27d1)]e[39m Driver proxy active, passing request on via HTTP proxy
[debug] e[35m[WD Proxy]e[39m Matched ‘/wd/hub/session/e4be27d1-a4dc-49b9-9043-8bf2e93af067/timeouts’ to command name ‘timeouts’
[debug] e[35m[Protocol Converter]e[39m Will send the following request bodies to /timeouts: [{“script”:30000}]
[debug] e[35m[WD Proxy]e[39m Proxying [POST /wd/hub/session/e4be27d1-a4dc-49b9-9043-8bf2e93af067/timeouts] to [POST http://127.0.0.1:10100/session/F23E0D08-A6BC-47C9-99A5-182D049E39EA/timeouts] with body: {“script”:30000}
[debug] e[35m[WD Proxy]e[39m Got response with status 200: {“value”:null,“sessionId”:“F23E0D08-A6BC-47C9-99A5-182D049E39EA”}
[info] e[35m[WD Proxy]e[39m Replacing sessionId F23E0D08-A6BC-47C9-99A5-182D049E39EA with e4be27d1-a4dc-49b9-9043-8bf2e93af067
[info] e[35m[HTTP]e[39m e[37m<-- POST /wd/hub/session/e4be27d1-a4dc-49b9-9043-8bf2e93af067/timeouts e[39me[32m200e[39m e[90m3 ms - 65e[39m
[info] e[35m[HTTP]e[39m e[90me[39m
[info] e[35m[HTTP]e[39m e[37m–>e[39m e[37mPOSTe[39m e[37m/wd/hub/session/e4be27d1-a4dc-49b9-9043-8bf2e93af067/appium/settingse[39m
[info] e[35m[HTTP]e[39m e[90m{“settings”:{“customSnapshotTimeout”:10000}}e[39m
[debug] e[35m[W3C (e4be27d1)]e[39m Calling AppiumDriver.updateSettings() with args: [{“customSnapshotTimeout”:10000},“e4be27d1-a4dc-49b9-9043-8bf2e93af067”]
[debug] e[35m[WD Proxy]e[39m Matched ‘/appium/settings’ to command name ‘updateSettings’
[debug] e[35m[WD Proxy]e[39m Proxying [POST /appium/settings] to [POST http://127.0.0.1:10100/session/F23E0D08-A6BC-47C9-99A5-182D049E39EA/appium/settings] with body: {“settings”:{“customSnapshotTimeout”:10000}}
[debug] e[35m[WD Proxy]e[39m Got response with status 200: {“value”:{“boundElementsByIndex”:false,“useDefaultUiInterruptionsHandling”:1},“sessionId”:“F23E0D08-A6BC-47C9-99A5-182D049E39EA”}
[debug] e[35m[W3C (e4be27d1)]e[39m Responding to client with driver.updateSettings() result: null
[info] e[35m[HTTP]e[39m e[37m<-- POST /wd/hub/session/e4be27d1-a4dc-49b9-9043-8bf2e93af067/appium/settings e[39me[32m200e[39m e[90m12 ms - 14e[39m
[info] e[35m[HTTP]e[39m e[90me[39m
[info] e[35m[HTTP]e[39m e[37m–>e[39m e[37mPOSTe[39m e[37m/wd/hub/session/e4be27d1-a4dc-49b9-9043-8bf2e93af067/appium/settingse[39m
[info] e[35m[HTTP]e[39m e[90m{“settings”:{“snapshotMaxDepth”:100}}e[39m
[debug] e[35m[W3C (e4be27d1)]e[39m Calling AppiumDriver.updateSettings() with args: [{“snapshotMaxDepth”:100},“e4be27d1-a4dc-49b9-9043-8bf2e93af067”]
[debug] e[35m[WD Proxy]e[39m Matched ‘/appium/settings’ to command name ‘updateSettings’
[debug] e[35m[WD Proxy]e[39m Proxying [POST /appium/settings] to [POST http://127.0.0.1:10100/session/F23E0D08-A6BC-47C9-99A5-182D049E39EA/appium/settings] with body: {“settings”:{“snapshotMaxDepth”:100}}
[debug] e[35m[WD Proxy]e[39m Got response with status 200: {“value”:{“boundElementsByIndex”:false,“useDefaultUiInterruptionsHandling”:1},“sessionId”:“F23E0D08-A6BC-47C9-99A5-182D049E39EA”}
[debug] e[35m[W3C (e4be27d1)]e[39m Responding to client with driver.updateSettings() result: null
[info] e[35m[HTTP]e[39m e[37m<-- POST /wd/hub/session/e4be27d1-a4dc-49b9-9043-8bf2e93af067/appium/settings e[39me[32m200e[39m e[90m4 ms - 14e[39m
[info] e[35m[HTTP]e[39m e[90me[39m
[info] e[35m[HTTP]e[39m e[37m–>e[39m e[37mGETe[39m e[37m/session/e4be27d1-a4dc-49b9-9043-8bf2e93af067/log/typese[39m
[info] e[35m[HTTP]e[39m e[90m{}e[39m
[debug] e[35m[HTTP]e[39m No route found for /session/e4be27d1-a4dc-49b9-9043-8bf2e93af067/log/types
[info] e[35m[HTTP]e[39m e[37m<-- GET /session/e4be27d1-a4dc-49b9-9043-8bf2e93af067/log/types e[39me[33m404e[39m e[90m2 ms - 262e[39m
[info] e[35m[HTTP]e[39m e[90me[39m[info] e[35m[HTTP]e[39m e[37m–>e[39m e[37mPOSTe[39m e[37m/wd/hub/session/e4be27d1-a4dc-49b9-9043-8bf2e93af067/elemente[39m
[info] e[35m[HTTP]e[39m e[90m{“using”:“xpath”,“value”:"//XCUIElementTypeButton[@identifier=’_XCUI:FullScreenWindow’]"}e[39m
[debug] e[35m[W3C (e4be27d1)]e[39m Calling AppiumDriver.findElement() with args: [“xpath”,"//XCUIElementTypeButton[@identifier=’_XCUI:FullScreenWindow’]",“e4be27d1-a4dc-49b9-9043-8bf2e93af067”]
[debug] e[35m[BaseDriver]e[39m Valid locator strategies for this request: id, name, accessibility id, xpath, class name, -ios predicate string, predicate string, -ios class chain, class chain
[debug] e[35m[WD Proxy]e[39m Matched ‘/element’ to command name ‘findElement’
[debug] e[35m[WD Proxy]e[39m Proxying [POST /element] to [POST http://127.0.0.1:10100/session/F23E0D08-A6BC-47C9-99A5-182D049E39EA/element] with body: {“using”:“xpath”,“value”:"//XCUIElementTypeButton[@identifier=’_XCUI:FullScreenWindow’]"}
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 289.19s Find the Application ‘com.ist.application.Prime.qa’[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 290.12s Get all elements bound by accessibility element for: Elements matching predicate ‘BLOCKPREDICATE(0x600003912b20)’
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 290.12s Requesting snapshot of accessibility hierarchy for app with pid 13766[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 290.53s Find: Descendants matching type Any
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 290.54s Find: Elements matching predicate ‘BLOCKPREDICATE(0x600003912b20)’
[debug] e[35m[WD Proxy]e[39m Got response with status 200: {“value”:{“ELEMENT”:“9C6A68B1-6BBB-4EC8-B45A-C5ACAF7A053C”,“element-6066-11e4-a52e-4f735466cecf”:“9C6A68B1-6BBB-4EC8-B45A-C5ACAF7A053C”},“sessionId”:“F23E0D08-A6BC-47C9-99A5-182D049E39EA”}
[debug] e[35m[W3C (e4be27d1)]e[39m Responding to client with driver.findElement() result: {“element-6066-11e4-a52e-4f735466cecf”:“9C6A68B1-6BBB-4EC8-B45A-C5ACAF7A053C”,“ELEMENT”:“9C6A68B1-6BBB-4EC8-B45A-C5ACAF7A053C”}
[info] e[35m[HTTP]e[39m e[37m<-- POST /wd/hub/session/e4be27d1-a4dc-49b9-9043-8bf2e93af067/element e[39me[32m200e[39m e[90m1360 ms - 137e[39m
[info] e[35m[HTTP]e[39m e[90me[39m[info] e[35m[HTTP]e[39m e[37m–>e[39m e[37mPOSTe[39m e[37m/wd/hub/session/e4be27d1-a4dc-49b9-9043-8bf2e93af067/elemente[39m
[info] e[35m[HTTP]e[39m e[90m{“using”:“xpath”,“value”:"//XCUIElementTypeTable/XCUIElementTypeCell/XCUIElementTypeStaticText[@label=‘My Cases’]"}e[39m
[debug] e[35m[W3C (e4be27d1)]e[39m Calling AppiumDriver.findElement() with args: [“xpath”,"//XCUIElementTypeTable/XCUIElementTypeCell/XCUIElementTypeStaticText[@label=‘My Cases’]",“e4be27d1-a4dc-49b9-9043-8bf2e93af067”]
[debug] e[35m[BaseDriver]e[39m Valid locator strategies for this request: id, name, accessibility id, xpath, class name, -ios predicate string, predicate string, -ios class chain, class chain
[debug] e[35m[WD Proxy]e[39m Matched ‘/element’ to command name ‘findElement’
[debug] e[35m[WD Proxy]e[39m Proxying [POST /element] to [POST http://127.0.0.1:10100/session/F23E0D08-A6BC-47C9-99A5-182D049E39EA/element] with body: {“using”:“xpath”,“value”:"//XCUIElementTypeTable/XCUIElementTypeCell/XCUIElementTypeStaticText[@label=‘My Cases’]"}
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 295.58s Find the Application ‘com.ist.application.Prime.qa’[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 296.11s Get all elements bound by accessibility element for: Elements matching predicate ‘BLOCKPREDICATE(0x600003920ed0)’
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 296.11s Requesting snapshot of accessibility hierarchy for app with pid 13766[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 296.53s Find: Descendants matching type Any
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 296.53s Find: Elements matching predicate ‘BLOCKPREDICATE(0x600003920ed0)’
[debug] e[35m[WD Proxy]e[39m Got response with status 200: {“value”:{“ELEMENT”:“47D766CD-A3EF-475F-8656-632ABC59B740”,“element-6066-11e4-a52e-4f735466cecf”:“47D766CD-A3EF-475F-8656-632ABC59B740”},“sessionId”:“F23E0D08-A6BC-47C9-99A5-182D049E39EA”}
[debug] e[35m[W3C (e4be27d1)]e[39m Responding to client with driver.findElement() result: {“element-6066-11e4-a52e-4f735466cecf”:“47D766CD-A3EF-475F-8656-632ABC59B740”,“ELEMENT”:“47D766CD-A3EF-475F-8656-632ABC59B740”}
[info] e[35m[HTTP]e[39m e[37m<-- POST /wd/hub/session/e4be27d1-a4dc-49b9-9043-8bf2e93af067/element e[39me[32m200e[39m e[90m970 ms - 137e[39m
[info] e[35m[HTTP]e[39m e[90me[39m
[info] e[35m[HTTP]e[39m e[37m–>e[39m e[37mPOSTe[39m e[37m/wd/hub/session/e4be27d1-a4dc-49b9-9043-8bf2e93af067/element/47D766CD-A3EF-475F-8656-632ABC59B740/clicke[39m
[info] e[35m[HTTP]e[39m e[90m{“id”:“47D766CD-A3EF-475F-8656-632ABC59B740”}e[39m
[info] e[35m[W3C (e4be27d1)]e[39m Driver proxy active, passing request on via HTTP proxy
[debug] e[35m[WD Proxy]e[39m Matched ‘/wd/hub/session/e4be27d1-a4dc-49b9-9043-8bf2e93af067/element/47D766CD-A3EF-475F-8656-632ABC59B740/click’ to command name ‘click’
[debug] e[35m[WD Proxy]e[39m Proxying [POST /wd/hub/session/e4be27d1-a4dc-49b9-9043-8bf2e93af067/element/47D766CD-A3EF-475F-8656-632ABC59B740/click] to [POST http://127.0.0.1:10100/session/F23E0D08-A6BC-47C9-99A5-182D049E39EA/element/47D766CD-A3EF-475F-8656-632ABC59B740/click] with body: {“id”:“47D766CD-A3EF-475F-8656-632ABC59B740”}
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 296.55s Find the “My Cases” StaticText
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 296.55s Requesting snapshot of accessibility hierarchy for app with pid 13766[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 296.97s Find: Descendants matching type Any
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 296.97s Find: Elements matching predicate ‘BLOCKPREDICATE(0x600003920ed0)’
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 296.98s Find: Identity Binding
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 296.98s Click “My Cases” StaticText
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 296.98s Wait for com.ist.application.Prime.qa to idle
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 296.98s Find the “My Cases” StaticText
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 296.98s Requesting snapshot of accessibility hierarchy for app with pid 13766[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 297.40s Find: Descendants matching type Any
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 297.40s Find: Elements matching predicate ‘BLOCKPREDICATE(0x600003920ed0)’
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 297.41s Find: Identity Binding
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 297.42s Found 1 interrupting element:
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 297.42s Window at {{3.0, 217.0}, {650.0, 600.0}} from Application ‘io.appium.desktop’
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 297.42s Invoking UI interruption monitors for Window at {{3.0, 217.0}, {650.0, 600.0}} from Application ‘io.appium.desktop’
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 297.42s Checking for user permission dialogs…
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 297.42s Checking existence of StaticText
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 297.46s Checking for Bluetooth Setup Assistant…
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 297.46s Find the Window at {{3.0, 217.0}, {650.0, 600.0}}
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 297.49s No monitors handled UI interruption, checking to see if element still exists…
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 297.49s Checking existence of "Appium" Window
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 297.53s Did not handle the interruption, but will attempt to continue…
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 297.53s Open com.ist.application.Prime.qa
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 297.53s Activate com.ist.application.Prime.qa
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 297.53s Wait for com.ist.application.Prime.qa to idle[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 297.56s Synthesize event[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 297.88s Wait for com.ist.application.Prime.qa to idle
[debug] e[35m[WD Proxy]e[39m Got response with status 200: {“value”:null,“sessionId”:“F23E0D08-A6BC-47C9-99A5-182D049E39EA”}
[info] e[35m[WD Proxy]e[39m Replacing sessionId F23E0D08-A6BC-47C9-99A5-182D049E39EA with e4be27d1-a4dc-49b9-9043-8bf2e93af067
[info] e[35m[HTTP]e[39m e[37m<-- POST /wd/hub/session/e4be27d1-a4dc-49b9-9043-8bf2e93af067/element/47D766CD-A3EF-475F-8656-632ABC59B740/click e[39me[32m200e[39m e[90m1388 ms - 65e[39m
[info] e[35m[HTTP]e[39m e[90me[39m
[info] e[35m[HTTP]e[39m e[37m–>e[39m e[37mPOSTe[39m e[37m/wd/hub/session/e4be27d1-a4dc-49b9-9043-8bf2e93af067/elemente[39m
[info] e[35m[HTTP]e[39m e[90m{“using”:“xpath”,“value”:"//XCUIElementTypeTable/XCUIElementTypeCell/XCUIElementTypeStaticText[@label=‘Unassigned’]"}e[39m
[debug] e[35m[W3C (e4be27d1)]e[39m Calling AppiumDriver.findElement() with args: [“xpath”,"//XCUIElementTypeTable/XCUIElementTypeCell/XCUIElementTypeStaticText[@label=‘Unassigned’]",“e4be27d1-a4dc-49b9-9043-8bf2e93af067”]
[debug] e[35m[BaseDriver]e[39m Valid locator strategies for this request: id, name, accessibility id, xpath, class name, -ios predicate string, predicate string, -ios class chain, class chain
[debug] e[35m[WD Proxy]e[39m Matched ‘/element’ to command name ‘findElement’
[debug] e[35m[WD Proxy]e[39m Proxying [POST /element] to [POST http://127.0.0.1:10100/session/F23E0D08-A6BC-47C9-99A5-182D049E39EA/element] with body: {“using”:“xpath”,“value”:"//XCUIElementTypeTable/XCUIElementTypeCell/XCUIElementTypeStaticText[@label=‘Unassigned’]"}
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 297.94s Find the Application ‘com.ist.application.Prime.qa’[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 298.53s Get all elements bound by accessibility element for: Elements matching predicate ‘BLOCKPREDICATE(0x6000039554a0)’
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 298.53s Requesting snapshot of accessibility hierarchy for app with pid 13766[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 298.96s Find: Descendants matching type Any
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 298.97s Find: Elements matching predicate ‘BLOCKPREDICATE(0x6000039554a0)’
[debug] e[35m[WD Proxy]e[39m Got response with status 200: {“value”:{“ELEMENT”:“0F786787-A3BA-416D-88CB-E7E332DBBFA3”,“element-6066-11e4-a52e-4f735466cecf”:“0F786787-A3BA-416D-88CB-E7E332DBBFA3”},“sessionId”:“F23E0D08-A6BC-47C9-99A5-182D049E39EA”}
[debug] e[35m[W3C (e4be27d1)]e[39m Responding to client with driver.findElement() result: {“element-6066-11e4-a52e-4f735466cecf”:“0F786787-A3BA-416D-88CB-E7E332DBBFA3”,“ELEMENT”:“0F786787-A3BA-416D-88CB-E7E332DBBFA3”}
[info] e[35m[HTTP]e[39m e[37m<-- POST /wd/hub/session/e4be27d1-a4dc-49b9-9043-8bf2e93af067/element e[39me[32m200e[39m e[90m1038 ms - 137e[39m
[info] e[35m[HTTP]e[39m e[90me[39m
[info] e[35m[HTTP]e[39m e[37m–>e[39m e[37mPOSTe[39m e[37m/wd/hub/session/e4be27d1-a4dc-49b9-9043-8bf2e93af067/element/0F786787-A3BA-416D-88CB-E7E332DBBFA3/clicke[39m
[info] e[35m[HTTP]e[39m e[90m{“id”:“0F786787-A3BA-416D-88CB-E7E332DBBFA3”}e[39m
[info] e[35m[W3C (e4be27d1)]e[39m Driver proxy active, passing request on via HTTP proxy
[debug] e[35m[WD Proxy]e[39m Matched ‘/wd/hub/session/e4be27d1-a4dc-49b9-9043-8bf2e93af067/element/0F786787-A3BA-416D-88CB-E7E332DBBFA3/click’ to command name ‘click’
[debug] e[35m[WD Proxy]e[39m Proxying [POST /wd/hub/session/e4be27d1-a4dc-49b9-9043-8bf2e93af067/element/0F786787-A3BA-416D-88CB-E7E332DBBFA3/click] to [POST http://127.0.0.1:10100/session/F23E0D08-A6BC-47C9-99A5-182D049E39EA/element/0F786787-A3BA-416D-88CB-E7E332DBBFA3/click] with body: {“id”:“0F786787-A3BA-416D-88CB-E7E332DBBFA3”}
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 298.98s Find the “Unassigned” StaticText
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 298.98s Requesting snapshot of accessibility hierarchy for app with pid 13766[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 299.42s Find: Descendants matching type Any
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 299.42s Find: Elements matching predicate ‘BLOCKPREDICATE(0x6000039554a0)’
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 299.43s Find: Identity Binding
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 299.43s Click “Unassigned” StaticText
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 299.43s Wait for com.ist.application.Prime.qa to idle
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 299.44s Find the “Unassigned” StaticText
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 299.44s Requesting snapshot of accessibility hierarchy for app with pid 13766[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 299.87s Find: Descendants matching type Any
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 299.87s Find: Elements matching predicate ‘BLOCKPREDICATE(0x6000039554a0)’
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 299.87s Find: Identity Binding
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 299.88s Synthesize event[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 300.19s Wait for com.ist.application.Prime.qa to idle
[debug] e[35m[WD Proxy]e[39m Got response with status 200: {“value”:null,“sessionId”:“F23E0D08-A6BC-47C9-99A5-182D049E39EA”}
[info] e[35m[WD Proxy]e[39m Replacing sessionId F23E0D08-A6BC-47C9-99A5-182D049E39EA with e4be27d1-a4dc-49b9-9043-8bf2e93af067
[info] e[35m[HTTP]e[39m e[37m<-- POST /wd/hub/session/e4be27d1-a4dc-49b9-9043-8bf2e93af067/element/0F786787-A3BA-416D-88CB-E7E332DBBFA3/click e[39me[32m200e[39m e[90m1271 ms - 65e[39m
[info] e[35m[HTTP]e[39m e[90me[39m[info] e[35m[HTTP]e[39m e[37m–>e[39m e[37mPOSTe[39m e[37m/wd/hub/session/e4be27d1-a4dc-49b9-9043-8bf2e93af067/elemente[39m
[info] e[35m[HTTP]e[39m e[90m{“using”:“xpath”,“value”:"//[@=‘Dhandeti chandrahas’]"}e[39m
[debug] e[35m[W3C (e4be27d1)]e[39m Calling AppiumDriver.findElement() with args: [“xpath”,"//[@=‘Dhandeti chandrahas’]",“e4be27d1-a4dc-49b9-9043-8bf2e93af067”]
[debug] e[35m[BaseDriver]e[39m Valid locator strategies for this request: id, name, accessibility id, xpath, class name, -ios predicate string, predicate string, -ios class chain, class chain
[debug] e[35m[WD Proxy]e[39m Matched ‘/element’ to command name ‘findElement’
[debug] e[35m[WD Proxy]e[39m Proxying [POST /element] to [POST http://127.0.0.1:10100/session/F23E0D08-A6BC-47C9-99A5-182D049E39EA/element] with body: {“using”:“xpath”,“value”:"//[@=‘Dhandeti chandrahas’]"}
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 305.26s Find the Application ‘com.ist.application.Prime.qa’[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 306.79s Get all elements bound by accessibility element for: Elements matching predicate ‘BLOCKPREDICATE(0x600003af0f60)’
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 306.79s Requesting snapshot of accessibility hierarchy for app with pid 13766[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 308.00s Find: Descendants matching type Any
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 308.00s Find: Elements matching predicate ‘BLOCKPREDICATE(0x600003af0f60)’
[debug] e[35m[WD Proxy]e[39m Got response with status 200: {“value”:{“ELEMENT”:“AAB7DBDB-B3B0-413C-80C7-5F8FBCA88A2F”,“element-6066-11e4-a52e-4f735466cecf”:“AAB7DBDB-B3B0-413C-80C7-5F8FBCA88A2F”},“sessionId”:“F23E0D08-A6BC-47C9-99A5-182D049E39EA”}
[debug] e[35m[W3C (e4be27d1)]e[39m Responding to client with driver.findElement() result: {“element-6066-11e4-a52e-4f735466cecf”:“AAB7DBDB-B3B0-413C-80C7-5F8FBCA88A2F”,“ELEMENT”:“AAB7DBDB-B3B0-413C-80C7-5F8FBCA88A2F”}
[info] e[35m[HTTP]e[39m e[37m<-- POST /wd/hub/session/e4be27d1-a4dc-49b9-9043-8bf2e93af067/element e[39me[32m200e[39m e[90m2770 ms - 137e[39m
[info] e[35m[HTTP]e[39m e[90me[39m
[info] e[35m[HTTP]e[39m e[37m–>e[39m e[37mPOSTe[39m e[37m/wd/hub/session/e4be27d1-a4dc-49b9-9043-8bf2e93af067/element/AAB7DBDB-B3B0-413C-80C7-5F8FBCA88A2F/clicke[39m
[info] e[35m[HTTP]e[39m e[90m{“id”:“AAB7DBDB-B3B0-413C-80C7-5F8FBCA88A2F”}e[39m
[info] e[35m[W3C (e4be27d1)]e[39m Driver proxy active, passing request on via HTTP proxy
[debug] e[35m[WD Proxy]e[39m Matched ‘/wd/hub/session/e4be27d1-a4dc-49b9-9043-8bf2e93af067/element/AAB7DBDB-B3B0-413C-80C7-5F8FBCA88A2F/click’ to command name ‘click’
[debug] e[35m[WD Proxy]e[39m Proxying [POST /wd/hub/session/e4be27d1-a4dc-49b9-9043-8bf2e93af067/element/AAB7DBDB-B3B0-413C-80C7-5F8FBCA88A2F/click] to [POST http://127.0.0.1:10100/session/F23E0D08-A6BC-47C9-99A5-182D049E39EA/element/AAB7DBDB-B3B0-413C-80C7-5F8FBCA88A2F/click] with body: {“id”:“AAB7DBDB-B3B0-413C-80C7-5F8FBCA88A2F”}
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 308.03s Find the “Dhandeti chandrahas” StaticText
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 308.03s Requesting snapshot of accessibility hierarchy for app with pid 13766[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 309.24s Find: Descendants matching type Any
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 309.25s Find: Elements matching predicate ‘BLOCKPREDICATE(0x600003af0f60)’
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 309.25s Find: Identity Binding
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 309.26s Click “Dhandeti chandrahas” StaticText
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 309.26s Wait for com.ist.application.Prime.qa to idle
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 309.26s Find the “Dhandeti chandrahas” StaticText
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 309.26s Requesting snapshot of accessibility hierarchy for app with pid 13766[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 310.47s Find: Descendants matching type Any
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 310.47s Find: Elements matching predicate ‘BLOCKPREDICATE(0x600003af0f60)’
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 310.47s Find: Identity Binding
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 310.49s Synthesize event[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 310.80s Wait for com.ist.application.Prime.qa to idle
[debug] e[35m[WD Proxy]e[39m Got response with status 200: {“value”:null,“sessionId”:“F23E0D08-A6BC-47C9-99A5-182D049E39EA”}
[info] e[35m[WD Proxy]e[39m Replacing sessionId F23E0D08-A6BC-47C9-99A5-182D049E39EA with e4be27d1-a4dc-49b9-9043-8bf2e93af067
[info] e[35m[HTTP]e[39m e[37m<-- POST /wd/hub/session/e4be27d1-a4dc-49b9-9043-8bf2e93af067/element/AAB7DBDB-B3B0-413C-80C7-5F8FBCA88A2F/click e[39me[32m200e[39m e[90m2872 ms - 65e[39m
[info] e[35m[HTTP]e[39m e[90me[39m
[info] e[35m[HTTP]e[39m e[37m–>e[39m e[37mPOSTe[39m e[37m/wd/hub/session/e4be27d1-a4dc-49b9-9043-8bf2e93af067/elemente[39m
[info] e[35m[HTTP]e[39m e[90m{“using”:“xpath”,“value”:"//XCUIElementTypeTable/XCUIElementTypeCell/XCUIElementTypeStaticText[@label=‘My Cases’]"}e[39m
[debug] e[35m[W3C (e4be27d1)]e[39m Calling AppiumDriver.findElement() with args: [“xpath”,"//XCUIElementTypeTable/XCUIElementTypeCell/XCUIElementTypeStaticText[@label=‘My Cases’]",“e4be27d1-a4dc-49b9-9043-8bf2e93af067”]
[debug] e[35m[BaseDriver]e[39m Valid locator strategies for this request: id, name, accessibility id, xpath, class name, -ios predicate string, predicate string, -ios class chain, class chain
[debug] e[35m[WD Proxy]e[39m Matched ‘/element’ to command name ‘findElement’
[debug] e[35m[WD Proxy]e[39m Proxying [POST /element] to [POST http://127.0.0.1:10100/session/F23E0D08-A6BC-47C9-99A5-182D049E39EA/element] with body: {“using”:“xpath”,“value”:"//XCUIElementTypeTable/XCUIElementTypeCell/XCUIElementTypeStaticText[@label=‘My Cases’]"}
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 310.91s Find the Application ‘com.ist.application.Prime.qa’[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 312.76s Get all elements bound by accessibility element for: Elements matching predicate ‘BLOCKPREDICATE(0x600003a67540)’
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 312.76s Requesting snapshot of accessibility hierarchy for app with pid 13766[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 314.32s Find: Descendants matching type Any
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 314.32s Find: Elements matching predicate ‘BLOCKPREDICATE(0x600003a67540)’
[debug] e[35m[WD Proxy]e[39m Got response with status 200: {“value”:{“ELEMENT”:“96AA5D9F-232D-4695-BA6D-4700D40CE891”,“element-6066-11e4-a52e-4f735466cecf”:“96AA5D9F-232D-4695-BA6D-4700D40CE891”},“sessionId”:“F23E0D08-A6BC-47C9-99A5-182D049E39EA”}
[debug] e[35m[W3C (e4be27d1)]e[39m Responding to client with driver.findElement() result: {“element-6066-11e4-a52e-4f735466cecf”:“96AA5D9F-232D-4695-BA6D-4700D40CE891”,“ELEMENT”:“96AA5D9F-232D-4695-BA6D-4700D40CE891”}
[info] e[35m[HTTP]e[39m e[37m<-- POST /wd/hub/session/e4be27d1-a4dc-49b9-9043-8bf2e93af067/element e[39me[32m200e[39m e[90m3439 ms - 137e[39m
[info] e[35m[HTTP]e[39m e[90me[39m[info] e[35m[HTTP]e[39m e[37m–>e[39m e[37mPOSTe[39m e[37m/wd/hub/session/e4be27d1-a4dc-49b9-9043-8bf2e93af067/elemente[39m
[info] e[35m[HTTP]e[39m e[90m{“using”:“xpath”,“value”:"//XCUIElementTypeStaticText[@label=‘Case ID:’]/following-sibling::XCUIElementTypeStaticText"}e[39m
[debug] e[35m[W3C (e4be27d1)]e[39m Calling AppiumDriver.findElement() with args: [“xpath”,"//XCUIElementTypeStaticText[@label=‘Case ID:’]/following-sibling::XCUIElementTypeStaticText",“e4be27d1-a4dc-49b9-9043-8bf2e93af067”]
[debug] e[35m[BaseDriver]e[39m Valid locator strategies for this request: id, name, accessibility id, xpath, class name, -ios predicate string, predicate string, -ios class chain, class chain
[debug] e[35m[WD Proxy]e[39m Matched ‘/element’ to command name ‘findElement’
[debug] e[35m[WD Proxy]e[39m Proxying [POST /element] to [POST http://127.0.0.1:10100/session/F23E0D08-A6BC-47C9-99A5-182D049E39EA/element] with body: {“using”:“xpath”,“value”:"//XCUIElementTypeStaticText[@label=‘Case ID:’]/following-sibling::XCUIElementTypeStaticText"}
[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 323.37s Find the Application ‘com.ist.application.Prime.qa’[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 324.95s Find the Application ‘com.ist.application.Prime.qa’ (retry 1)[debug] e[35m[WebDriverAgentMac]e[39m [xcodebuild] t = 326.54s Find the Application ‘com.ist.application.Prime.qa’ (retry 2)[info] e[35m[WD Proxy]e[39m Got response with status 500: {“value”:{“error”:“unknown error”,“message”:“Cannot evaluate results for XPath expression “//XCUIElementTypeStaticText[@label=‘Case ID:’]/following-sibling::XCUIElementTypeStaticText”. Original error: Error Domain=com.application.dt.xctest.ui-testing.error Code=10018 “Failed to resolve remote element AX element pid: 13775, token: zzUAAMY1AABvY29j/v///////38AAAAAAAAAAM81AAAAAAAAAE+qGw8nAAAAAAAA” UserInfo={NSLocalizedDescription=Failed to resolve remote element AX element pid: 13775, token: zzUAAMY1AABvY29j/v///////38AAAAAAAAAAM81AAAAAAAAAE+qGw8nAAAAAAAA, NSUnderlyingError=0x600003be8240 {Error Domain=com.application.dt.xctest.automation-support.error Code=5 “Timed out snapshotting io.appium.WebDriverAgentRunner.xctrunner, app is either unresponsive or taking too long to snapshot.” UserInfo={accessibility-error=-25204, NSLocalizedDescription=Timed out snapshotting io.appium.WebDriverAgentRunner.xctrunner, app is either unresponsive or taking too long to snapshot.}}}”,“traceback”:"(\n\t0 CoreFoundation …
[debug] e[35m[W3C]e[39m Matched W3C error code ‘unknown error’ to UnknownError
[debug] e[35m[W3C (e4be27d1)]e[39m Encountered internal error running command: UnknownError: An unknown server-side error occurred while processing the command. Original error: Cannot evaluate results for XPath expression “//XCUIElementTypeStaticText[@label=‘Case ID:’]/following-sibling::XCUIElementTypeStaticText”. Original error: Error Domain=com.application.dt.xctest.ui-testing.error Code=10018 “Failed to resolve remote element AX element pid: 13775, token: zzUAAMY1AABvY29j/v///////38AAAAAAAAAAM81AAAAAAAAAE+qGw8nAAAAAAAA” UserInfo={NSLocalizedDescription=Failed to resolve remote element AX element pid: 13775, token: zzUAAMY1AABvY29j/v///////38AAAAAAAAAAM81AAAAAAAAAE+qGw8nAAAAAAAA, NSUnderlyingError=0x600003be8240 {Error Domain=com.application.dt.xctest.automation-support.error Code=5 “Timed out snapshotting io.appium.WebDriverAgentRunner.xctrunner, app is either unresponsive or taking too long to snapshot.” UserInfo={accessibility-error=-25204, NSLocalizedDescription=Timed out snapshotting io.appium.WebDriverAgentRunner.xctrunner, app is either unresponsive or taking too long to snapshot.}}}
[debug] e[35m[W3C (e4be27d1)]e[39m at errorFromW3CJsonCode (/Applications/Appium.app/Contents/Resources/app/node_modules/appium/node_modules/appium-base-driver/lib/protocol/errors.js:780:25)
[debug] e[35m[W3C (e4be27d1)]e[39m at ProxyRequestError.getActualError (/Applications/Appium.app/Contents/Resources/app/node_modules/appium/node_modules/appium-base-driver/lib/protocol/errors.js:663:14)
[debug] e[35m[W3C (e4be27d1)]e[39m at WDAMacProxy.command (/Applications/Appium.app/Contents/Resources/app/node_modules/appium/node_modules/appium-base-driver/lib/jsonwp-proxy/proxy.js:273:19)
[debug] e[35m[W3C (e4be27d1)]e[39m at processTicksAndRejections (internal/process/task_queues.js:85:5)
[info] e[35m[HTTP]e[39m e[37m<-- POST /wd/hub/session/e4be27d1-a4dc-49b9-9043-8bf2e93af067/element e[39me[31m500e[39m e[90m3732 ms - 2670e[39m
[info] e[35m[HTTP]e[39m e[90me[39m

In the above logs, the mentioned Element (quoted above) is identified sometimes and remaining times the error shown in the log attached is thrown.

Not sure why this inconsistent behaviour.

It’s the same error as above. It is necessary to take a snapshot of the accessibility hierarchy to be able to find by xpath. I don’t know why the behaviour is inconsistent though, it’s something on XCTest side.

Ok Thanks for the reply. Will check with the dev team for the same.

I got a new build from the dev team. This build seems to be better that the earlier one.
One thing I observed is, when I am starting the Appium server via UI, elements are getting identified.

But when I am starting Appium server via terminal, with the command -
/usr/local/bin/node /Applications/Appium.app/Contents/Resources/app/node_modules/appium/build/lib/main.js --address 127.0.0.1 --port 4723

for some element’s identification, it is throwing -
[W3C (10bd34d4)] Driver proxy active, passing request on via HTTP proxy
[debug] [WD Proxy] Matched ‘/wd/hub/session/10bd34d4-2c4e-4012-a45b-cc65cef2f7bd/element/E2D5DA6A-51CD-413B-8755-79281AC897E6/text’ to command name ‘getText’
[debug] [WD Proxy] Proxying [GET /wd/hub/session/10bd34d4-2c4e-4012-a45b-cc65cef2f7bd/element/E2D5DA6A-51CD-413B-8755-79281AC897E6/text] to [GET http://127.0.0.1:10100/session/93093083-FD24-44C5-A739-A576B3232930/element/E2D5DA6A-51CD-413B-8755-79281AC897E6/text] with no body
[debug] [WebDriverAgentMac] [xcodebuild] t = 153.12s Find the “Case ID:” StaticText
[debug] [WebDriverAgentMac] [xcodebuild] t = 154.23s Find the “Case ID:” StaticText
[WD Proxy] Error: timeout of 240000ms exceeded
[WD Proxy] at createError (/Applications/Appium.app/Contents/Resources/app/node_modules/appium/node_modules/axios/lib/core/createError.js:16:15)
[WD Proxy] at RedirectableRequest.handleRequestTimeout (/Applications/Appium.app/Contents/Resources/app/node_modules/appium/node_modules/axios/lib/adapters/http.js:280:16)
[WD Proxy] at Object.onceWrapper (events.js:422:26)
[WD Proxy] at RedirectableRequest.emit (events.js:315:20)
[WD Proxy] at ClientRequest.eventHandlers. (/Applications/Appium.app/Contents/Resources/app/node_modules/appium/node_modules/follow-redirects/index.js:13:24)
[WD Proxy] at ClientRequest.emit (events.js:315:20)
[WD Proxy] at Socket.emitRequestTimeout (_http_client.js:784:9)
[WD Proxy] at Object.onceWrapper (events.js:421:28)
[WD Proxy] at Socket.emit (events.js:327:22)
[WD Proxy] at Socket._onTimeout (net.js:483:8)
[WD Proxy] at listOnTimeout (internal/timers.js:554:17)
[WD Proxy] at processTimers (internal/timers.js:497:7)
[debug] [W3C (10bd34d4)] Encountered internal error running command: Error: Could not proxy. Proxy error: Could not proxy command to the remote server. Original error: timeout of 240000ms exceeded
[debug] [W3C (10bd34d4)] at doJwpProxy (/Applications/Appium.app/Contents/Resources/app/node_modules/appium/node_modules/appium-base-driver/lib/protocol/protocol.js:481:13)
[debug] [W3C (10bd34d4)] at asyncHandler (/Applications/Appium.app/Contents/Resources/app/node_modules/appium/node_modules/appium-base-driver/lib/protocol/protocol.js:253:9)
[HTTP] <-- GET /wd/hub/session/10bd34d4-2c4e-4012-a45b-cc65cef2f7bd/element/E2D5DA6A-51CD-413B-8755-79281AC897E6/text 500 60068 ms - 803
[HTTP]
[HTTP] --> DELETE /wd/hub/session/10bd34d4-2c4e-4012-a45b-cc65cef2f7bd
[HTTP] {}
[debug] [W3C (10bd34d4)] Calling AppiumDriver.deleteSession() with args: [“10bd34d4-2c4e-4012-a45b-cc65cef2f7bd”]
[debug] [BaseDriver] Event ‘quitSessionRequested’ logged at 1622116964789 (17:32:44 GMT+0530 (India Standard Time))
[Appium] Removing session 10bd34d4-2c4e-4012-a45b-cc65cef2f7bd from our master session list
[debug] [WD Proxy] Matched ‘/session/93093083-FD24-44C5-A739-A576B3232930’ to command name ‘deleteSession’
[debug] [WD Proxy] Proxying [DELETE /session/93093083-FD24-44C5-A739-A576B3232930] to [DELETE http://127.0.0.1:10100/session/93093083-FD24-44C5-A739-A576B3232930] with no body
[debug] [WebDriverAgentMac] [xcodebuild] t = 216.31s Find the “Case ID:” StaticText (retry 1)
[debug] [WebDriverAgentMac] [xcodebuild] t = 217.26s Find the “Case ID:” StaticText
[debug] [WebDriverAgentMac] [xcodebuild] t = 218.23s Terminate com.org.ist.ApplicationPrime.uat:63898
[debug] [WD Proxy] Got response with status 200: {“value”:null,“sessionId”:null}
[debug] [BaseDriver] Event ‘quitSessionFinished’ logged at 1622116970853 (17:32:50 GMT+0530 (India Standard Time))
[debug] [W3C (10bd34d4)] Received response: null
[debug] [W3C (10bd34d4)] But deleting session, so not returning
[debug] [W3C (10bd34d4)] Responding to client with driver.deleteSession() result: null
[HTTP] <-- DELETE /wd/hub/session/10bd34d4-2c4e-4012-a45b-cc65cef2f7bd 200 6065 ms - 14

I think I am missing something in my Appium server Manual start command.
Will you be able to help me here

it could be that your sessions management on the client side is broken. Make sure a previous session gets closed before starting a new one

When we are starting the Appium server via UI, is there any server flags set ?
I can see --relaxed-security flag enabled from the server logs.

OR is it the problem from the WD proxy.
When I am starting the Appium server via command, for a particular element getting response like -
[WD Proxy] Error: timeout of 240000ms exceeded
[WD Proxy] at createError (/Applications/Appium.app/Contents/Resources/app/node_modules/appium/node_modules/axios/lib/core/createError.js:16:15)
[WD Proxy] at RedirectableRequest.handleRequestTimeout (/Applications/Appium.app/Contents/Resources/app/node_modules/appium/node_modules/axios/lib/adapters/http.js:280:16)
[WD Proxy] at Object.onceWrapper (events.js:422:26)
[WD Proxy] at RedirectableRequest.emit (events.js:315:20)
[WD Proxy] at ClientRequest.eventHandlers. (/Applications/Appium.app/Contents/Resources/app/node_modules/appium/node_modules/follow-redirects/index.js:13:24)
[WD Proxy] at ClientRequest.emit (events.js:315:20)
[WD Proxy] at Socket.emitRequestTimeout (_http_client.js:784:9)
[WD Proxy] at Object.onceWrapper (events.js:421:28)
[WD Proxy] at Socket.emit (events.js:327:22)
[WD Proxy] at Socket._onTimeout (net.js:483:8)
[WD Proxy] at listOnTimeout (internal/timers.js:554:17)
[WD Proxy] at processTimers (internal/timers.js:497:7)

But when I am starting the Appium server via UI, For the same element getting response -

[WD Proxy] Got response with status 200: {“value”:“Case ID:”,“sessionId”:“7D2F0F41-E4D5-4393-A03B-C580DE2B25AF”}
[WD Proxy] Replacing sessionId 7D2F0F41-E4D5-4393-A03B-C580DE2B25AF with 6840ec2d-f9cc-4411-9eea-c7011346c132
[HTTP] <-- GET /wd/hub/session/6840ec2d-f9cc-4411-9eea-c7011346c132/element/E8E67204-6556-4C6C-918C-2F1F8ACEAB49/text 200 63006 ms - 71
[HTTP]
[HTTP] --> POST /wd/hub/session/6840ec2d-f9cc-4411-9eea-c7011346c132/element
[HTTP] {“using”:“xpath”,“value”:"//XCUIElementTypeStaticText[@=‘Originator:’]/following-sibling::XCUIElementTypeStaticText"}
[W3C (6840ec2d)] Calling AppiumDriver.findElement() with args: [“xpath”,"//XCUIElementTypeStaticText[@
=‘Originator:’]/following-sibling::XCUIElementTypeStaticText",“6840ec2d-f9cc-4411-9eea-c7011346c132”]
[BaseDriver] Valid locator strategies for this request: id, name, accessibility id, xpath, class name, -ios predicate string, predicate string, -ios class chain, class chain
[WD Proxy] Matched ‘/element’ to command name ‘findElement’
[WD Proxy] Proxying [POST /element] to [POST http://127.0.0.1:10100/session/7D2F0F41-E4D5-4393-A03B-C580DE2B25AF/element] with body: {“using”:“xpath”,“value”:"//XCUIElementTypeStaticText[@*=‘Originator:’]/following-sibling::XCUIElementTypeStaticText"}
[WebDriverAgentMac] [xcodebuild] t = 101.24s Find the Application ‘com.apple.ist.SonarPrime.uat’
[WebDriverAgentMac] [xcodebuild] t = 103.73s Get all elements bound by accessibility element for: Elements matching predicate ‘BLOCKPREDICATE(0x6000010552c0)’
[WebDriverAgentMac] [xcodebuild] t = 103.73s Requesting snapshot of accessibility hierarchy for app with pid 75241
[WebDriverAgentMac] [xcodebuild] t = 105.69s Find: Descendants matching type Any
[WebDriverAgentMac] [xcodebuild] t = 105.69s Find: Elements matching predicate ‘BLOCKPREDICATE(0x6000010552c0)’
[WD Proxy] Got response with status 200: {“value”:{“ELEMENT”:“86EC8839-94B0-458E-8D43-7112742D6658”,“element-6066-11e4-a52e-4f735466cecf”:“86EC8839-94B0-458E-8D43-7112742D6658”},“sessionId”:“7D2F0F41-E4D5-4393-A03B-C580DE2B25AF”}
[W3C (6840ec2d)] Responding to client with driver.findElement() result: {“element-6066-11e4-a52e-4f735466cecf”:“86EC8839-94B0-458E-8D43-7112742D6658”,“ELEMENT”:“86EC8839-94B0-458E-8D43-7112742D6658”}
[HTTP] <-- POST /wd/hub/session/6840ec2d-f9cc-4411-9eea-c7011346c132/element 200 4484 ms - 137

Can you help me to understand why this difference in behaviour ?

Fixed the issue when installed Appium via npm and started using the corresponding Main.js file