appium logs

[debug] [HTTP] Request idempotency key: 9f6e30cb-f124-4b71-af66-6f00fbb2e8b1
[HTTP] --> POST /wd/hub/session
[HTTP] {"capabilities":{"firstMatch":[{"appium:automationName":"XCUITest","platformName":"iOS","appium:deviceName":"iPhone Xr","appium:platformVersion":"15.2","browserName":"Safari","appium:xcodeOrgId":"FCSM7534VM","appium:autoWebview":true,"appium:xcodeSigningId":"iPhone Developer","appium:udid":"00008020-001204CA3CD8003A","appium:safariInitialUrl":"https://www.github.com","appium:orientationSet":"PORTRAIT","appium:webviewConnectTimeout":90000}]},"desiredCapabilities":{"automationName":"XCUITest","platformName":"iOS","deviceName":"iPhone Xr","platformVersion":"15.2","browserName":"Safari","xcodeOrgId":"FCSM7534VM","autoWebview":true,"xcodeSigningId":"iPhone Developer","udid":"00008020-001204CA3CD8003A","safariInitialUrl":"https://www.github.com","orientationSet":"PORTRAIT","webviewConnectTimeout":90000}}
[debug] [W3C] Calling AppiumDriver.createSession() with args: [{"automationName":"XCUITest","platformName":"iOS","deviceName":"iPhone Xr","platformVersion":"15.2","browserName":"Safari","xcodeOrgId":"******","autoWebview":true,"xcodeSigningId":"iPhone Developer","udid":"00008020-001204CA3CD8003A","safariInitialUrl":"https://www.github.com","orientationSet":"PORTRAIT","webviewConnectTimeout":90000},null,{"firstMatch":[{"appium:automationName":"XCUITest","platformName":"iOS","appium:deviceName":"iPhone Xr","appium:platformVersion":"15.2","browserName":"Safari","appium:xcodeOrgId":"FCSM7534VM","appium:autoWebview":true,"appium:xcodeSigningId":"iPhone Developer","appium:udid":"auto","appium:safariInitialUrl":"https://www.github.com","appium:orientationSet":"PORTRAIT","appium:webviewConnectTimeout":90000}]}]
[debug] [BaseDriver] Event 'newSessionRequested' logged at 1643022971439 (13:16:11 GMT+0200 (Eastern European Standard Time))
[Appium] Appium v1.23.0-beta.0 creating new XCUITestDriver (v3.61.0) session
[debug] [BaseDriver] W3C capabilities and MJSONWP desired capabilities were provided
[debug] [BaseDriver] Creating session with W3C capabilities: {
[debug] [BaseDriver]   "alwaysMatch": {
[debug] [BaseDriver]     "platformName": "iOS",
[debug] [BaseDriver]     "browserName": "Safari",
[debug] [BaseDriver]     "appium:automationName": "XCUITest",
[debug] [BaseDriver]     "appium:deviceName": "iPhone Xr",
[debug] [BaseDriver]     "appium:platformVersion": "15.2",
[debug] [BaseDriver]     "appium:xcodeOrgId": "FCSM7534VM",
[debug] [BaseDriver]     "appium:autoWebview": true,
[debug] [BaseDriver]     "appium:xcodeSigningId": "iPhone Developer",
[debug] [BaseDriver]     "appium:udid": "00008020-001204CA3CD8003A",
[debug] [BaseDriver]     "appium:safariInitialUrl": "https://www.github.com",
[debug] [BaseDriver]     "appium:orientationSet": "PORTRAIT",
[debug] [BaseDriver]     "appium:webviewConnectTimeout": 90000
[debug] [BaseDriver]   },
[debug] [BaseDriver]   "firstMatch": [
[debug] [BaseDriver]     {}
[debug] [BaseDriver]   ]
[debug] [BaseDriver] }
[BaseDriver] The following capabilities were provided, but are not recognized by Appium:
[BaseDriver]   orientationSet
[BaseDriver] Session created with session id: 32c8730d-8fdc-4a32-a43a-75a2f49959ae
[debug] [XCUITest] Current user: 'yarik'
[debug] [XCUITest] Available devices: 00008020-001204CA3CD8003A
[debug] [XCUITest] Creating iDevice object with udid '00008020-001204CA3CD8003A'
[XCUITest] Determining device to run tests on: udid: '00008020-001204CA3CD8003A', real device: true
[debug] [BaseDriver] Event 'xcodeDetailsRetrieved' logged at 1643022971844 (13:16:11 GMT+0200 (Eastern European Standard Time))
[XCUITest] Safari test requested
[debug] [BaseDriver] Event 'appConfigured' logged at 1643022971845 (13:16:11 GMT+0200 (Eastern European Standard Time))
[debug] [BaseDriver] Event 'resetStarted' logged at 1643022971845 (13:16:11 GMT+0200 (Eastern European Standard Time))
[debug] [XCUITest] Reset: running ios real device reset flow
[debug] [BaseDriver] Event 'resetComplete' logged at 1643022971846 (13:16:11 GMT+0200 (Eastern European Standard Time))
[WebDriverAgent] Using WDA path: '/usr/local/lib/node_modules/appium/node_modules/appium-webdriveragent'
[WebDriverAgent] Using WDA agent: '/usr/local/lib/node_modules/appium/node_modules/appium-webdriveragent/WebDriverAgent.xcodeproj'
[XCUITest] Continuing without capturing device logs: Failed to receive any data within the timeout: 5000
[XCUITest] Setting up real device
[debug] [WebDriverAgent] No obsolete cached processes from previous WDA sessions listening on port 8100 have been found
[DevCon Factory] Requesting connection for device 00008020-001204CA3CD8003A on local port 8100, device port 8100
[debug] [DevCon Factory] Cached connections count: 0
[DevCon Factory] Successfully requested the connection for 00008020-001204CA3CD8003A:8100
[debug] [WebDriverAgent] Parsed BUILD_DIR configuration value: '/Users/yarik/Library/Developer/Xcode/DerivedData/WebDriverAgent-ciegwgvxzxdrqthilmrmczmqvrgu/Build/Products'
[debug] [WebDriverAgent] Got derived data root: '/Users/yarik/Library/Developer/Xcode/DerivedData/WebDriverAgent-ciegwgvxzxdrqthilmrmczmqvrgu'
[debug] [XCUITest] Starting WebDriverAgent initialization with the synchronization key '/Users/yarik/Library/Developer/Xcode/DerivedData/WebDriverAgent-ciegwgvxzxdrqthilmrmczmqvrgu'
[debug] [WD Proxy] Matched '/status' to command name 'getStatus'
[debug] [WD Proxy] Proxying [GET /status] to [GET http://127.0.0.1:8100/status] with no body
[debug] [WD Proxy] Got response with status 200: {"value":{"message":"WebDriverAgent is ready to accept commands","state":"success","os":{"testmanagerdVersion":28,"name":"iOS","sdkVersion":"15.2","version":"15.2"},"ios":{"ip":"169.254.121.182"},"ready":true,"build":{"upgradedAt":"1643020865097","time":"Jan 24 2022 13:01:24","productBundleIdentifier":"com.facebook.WebDriverAgentRunner"}},"sessionId":null}
[debug] [WebDriverAgent] Upgrade timestamp of the currently bundled WDA: 1643020865097
[debug] [WebDriverAgent] Upgrade timestamp of the WDA on the device: 1643020865097
[WebDriverAgent] Will reuse previously cached WDA instance at 'http://127.0.0.1:8100/' with 'com.facebook.WebDriverAgentRunner'. Set the wdaLocalPort capability to a value different from 8100 if this is an undesired behavior.
[debug] [XCUITest] Trying to start WebDriverAgent 1 times with 10000ms interval
[debug] [XCUITest] These values can be customized by changing wdaStartupRetries/wdaStartupRetryInterval capabilities
[debug] [BaseDriver] Event 'wdaStartAttempted' logged at 1643022978341 (13:16:18 GMT+0200 (Eastern European Standard Time))
[WebDriverAgent] Using provided WebdriverAgent at 'http://127.0.0.1:8100/'
[debug] [WD Proxy] Matched '/status' to command name 'getStatus'
[debug] [WD Proxy] Proxying [GET /status] to [GET http://127.0.0.1:8100/status] with no body
[debug] [WD Proxy] Got response with status 200: {"value":{"message":"WebDriverAgent is ready to accept commands","state":"success","os":{"testmanagerdVersion":28,"name":"iOS","sdkVersion":"15.2","version":"15.2"},"ios":{"ip":"169.254.121.182"},"ready":true,"build":{"upgradedAt":"1643020865097","time":"Jan 24 2022 13:01:24","productBundleIdentifier":"com.facebook.WebDriverAgentRunner"}},"sessionId":null}
[debug] [BaseDriver] Event 'wdaSessionAttempted' logged at 1643022978355 (13:16:18 GMT+0200 (Eastern European Standard Time))
[debug] [XCUITest] Sending createSession command to WDA
[debug] [WD Proxy] Matched '/session' to command name 'createSession'
[debug] [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":{}}}
[debug] [WD Proxy] Got response with status 200: {"value":{"sessionId":"A01E1C1D-AD33-4A22-8A5A-A64370AE223B","capabilities":{"device":"iphone","browserName":"Safari","sdkVersion":"15.2","CFBundleIdentifier":"com.apple.mobilesafari"}},"sessionId":"A01E1C1D-AD33-4A22-8A5A-A64370AE223B"}
[WD Proxy] Determined the downstream protocol as 'W3C'
[debug] [BaseDriver] Event 'wdaSessionStarted' logged at 1643022986128 (13:16:26 GMT+0200 (Eastern European Standard Time))
[debug] [BaseDriver] Event 'wdaStarted' logged at 1643022986128 (13:16:26 GMT+0200 (Eastern European Standard Time))
[XCUITest] Skipping setting of the initial display orientation. Set the "orientation" capability to either "LANDSCAPE" or "PORTRAIT", if this is an undesired behavior.
[debug] [BaseDriver] Event 'orientationSet' logged at 1643022986129 (13:16:26 GMT+0200 (Eastern European Standard Time))
[debug] [XCUITest] Waiting for initial webview
[debug] [XCUITest] Navigating to most recently opened webview
[debug] [XCUITest] Retrieving contexts and views
[debug] [XCUITest] Selecting by url: false
[RemoteDebugger] Remote Debugger version 8.13.2
[debug] [RemoteDebugger] useNewSafari --> false
[debug] [RemoteDebugger] Checking which communication style to use (Safari on platform version '15.2')
[debug] [RemoteDebugger] Platform version equal or higher than '12.2': true
[RemoteDebugger] Setting communication protocol: using Target-based communication
[XCUITest] {}
[debug] [WD Proxy] Matched '/session/32c8730d-8fdc-4a32-a43a-75a2f49959ae' to command name 'deleteSession'
[debug] [WD Proxy] Proxying [DELETE /session/32c8730d-8fdc-4a32-a43a-75a2f49959ae] to [DELETE http://127.0.0.1:8100/session/A01E1C1D-AD33-4A22-8A5A-A64370AE223B] with no body
[debug] [WD Proxy] Got response with status 200: {"value":null,"sessionId":null}
[DevCon Factory] Releasing connections for 00008020-001204CA3CD8003A device on any port number
[DevCon Factory] Found cached connections to release: ["00008020-001204CA3CD8003A:8100"]
[DevCon Factory] Releasing the listener for '00008020-001204CA3CD8003A:8100'
[debug] [iProxy@00008020:8100] Closing the connection
[debug] [DevCon Factory] Cached connections count: 0
[debug] [XCUITest] Not clearing log files. Use `clearSystemFiles` capability to turn on.
[debug] [XCUITest] Found a remote debugger session. Removing...
[debug] [RemoteDebugger] Cleaning up listeners
[debug] [BaseDriver] Event 'newSessionStarted' logged at 1643022992321 (13:16:32 GMT+0200 (Eastern European Standard Time))
[debug] [W3C] Encountered internal error running command: Error: Could not navigate to webview! Err: Failed to receive any data within the timeout: 5000
[debug] [W3C]     at spinHandles (/usr/local/lib/node_modules/appium/node_modules/appium-xcuitest-driver/lib/commands/context.js:144:23)
[debug] [W3C]     at XCUITestDriver.navToViewWithTitle (/usr/local/lib/node_modules/appium/node_modules/appium-xcuitest-driver/lib/commands/context.js:186:3)
[debug] [W3C]     at XCUITestDriver.navToInitialWebview (/usr/local/lib/node_modules/appium/node_modules/appium-xcuitest-driver/lib/commands/context.js:222:5)
[debug] [W3C]     at XCUITestDriver.start (/usr/local/lib/node_modules/appium/node_modules/appium-xcuitest-driver/lib/driver.js:524:7)
[debug] [W3C]     at XCUITestDriver.createSession (/usr/local/lib/node_modules/appium/node_modules/appium-xcuitest-driver/lib/driver.js:256:7)
[debug] [W3C]     at AppiumDriver.createSession (/usr/local/lib/node_modules/appium/lib/appium.js:387:35)
[debug] [W3C] Caused by: Error: Failed to receive any data within the timeout: 5000
[debug] [W3C]     at Timeout._onTimeout (/usr/local/lib/node_modules/appium/node_modules/appium-ios-device/lib/plist-service/index.js:67:16)
[debug] [W3C]     at listOnTimeout (node:internal/timers:568:17)
[debug] [W3C]     at processTimers (node:internal/timers:510:7)