gistfile1.txt

[HTTP] --> POST /device-farm/api/block {"udid":"00008101-000A0D5C34C3003A","host":"http://192.168.88.97:4723"}
[device-farm-main] Received non-webdriver request with url /device-farm/api/block. So, not proxying it to downstream.
[device-farm-main] Updated devices with filter: {"udid":{"$in":"00008101-000A0D5C34C3003A"}}
[HTTP] <-- POST /device-farm/api/block 200 3 ms - 16 
[HTTP] --> POST /device-farm/api/dashboard/installiOSWDA {"udid":"00008101-000A0D5C34C3003A"}
[device-farm-main] Received non-webdriver request with url /device-farm/api/dashboard/installiOSWDA. So, not proxying it to downstream.
[device-farm-main] Updated devices with filter: {"udid":{"$in":["00008101-000A0D5C34C3003A"]}}
[HTTP] --> GET /device-farm/api/device {}
[device-farm-main] Received non-webdriver request with url /device-farm/api/device. So, not proxying it to downstream.
[HTTP] <-- GET /device-farm/api/device 200 2 ms - 1127 
[HTTP] --> GET /device-farm/api/queue/length {}
[device-farm-main] Received non-webdriver request with url /device-farm/api/queue/length. So, not proxying it to downstream.
[HTTP] <-- GET /device-farm/api/queue/length 304 2 ms - - 
stdout: 
stderr: {"appPath":"/Users/qaauto/.cache/appium-device-farm/assets/wda-resign.ipa","device":"00008101-000A0D5C34C3003A","level":"info","msg":"installing","time":"2024-06-23T07:59:46+03:00"}
{"level":"info","msg":"installing","percentComplete":5,"status":"CreatingStagingDirectory","time":"2024-06-23T07:59:46+03:00"}
{"level":"info","msg":"installing","percentComplete":15,"status":"ExtractingPackage","time":"2024-06-23T07:59:46+03:00"}
{"level":"info","msg":"installing","percentComplete":20,"status":"InspectingPackage","time":"2024-06-23T07:59:46+03:00"}
{"level":"info","msg":"installing","percentComplete":30,"status":"PreflightingApplication","time":"2024-06-23T07:59:46+03:00"}
{"level":"info","msg":"installing","percentComplete":40,"status":"VerifyingApplication","time":"2024-06-23T07:59:47+03:00"}
{"level":"info","msg":"installing","percentComplete":50,"status":"CreatingContainer","time":"2024-06-23T07:59:47+03:00"}
{"level":"info","msg":"installing","percentComplete":60,"status":"InstallingApplication","time":"2024-06-23T07:59:47+03:00"}
{"level":"info","msg":"installing","percentComplete":70,"status":"PostflightingApplication","time":"2024-06-23T07:59:47+03:00"}
{"level":"info","msg":"installing","percentComplete":80,"status":"SandboxingApplication","time":"2024-06-23T07:59:47+03:00"}
{"level":"info","msg":"installing","percentComplete":90,"status":"GeneratingApplicationMap","time":"2024-06-23T07:59:47+03:00"}
{"level":"info","msg":"installing","percentComplete":100,"status":"InstallComplete","time":"2024-06-23T07:59:47+03:00"}
{"level":"info","msg":"installation successful","time":"2024-06-23T07:59:47+03:00"}

[HTTP] <-- POST /device-farm/api/dashboard/installiOSWDA 200 936 ms - 91 
[HTTP] --> POST /device-farm/api/dashboard/appiumSession {"udid":"00008101-000A0D5C34C3003A","origin":"http://192.168.88.97:4723"}
[device-farm-main] Received non-webdriver request with url /device-farm/api/dashboard/appiumSession. So, not proxying it to downstream.
[device-farm-main] Updated devices with filter: {"udid":{"$in":["00008101-000A0D5C34C3003A"]}}
[HTTP] --> POST /wd/hub/session {"capabilities":{"alwaysMatch":{"platformName":"iOS","appium:automationName":"XCUITest","appium:newCommandTimeout":120,"appium:udid":"00008101-000A0D5C34C3003A","df:skipReport":true,"appium:mjpegServerPort":63001,"appium:waitForQuiescence":false,"appium:wdaEventloopIdleDelay":0,"appium:eventLoopIdleDelaySec":0,"appium:waitForIdleTimeout":0,"appium:settings":{"mjpegServerFramerate":34,"mjpegServerScreenshotQuality":75,"mjpegScalingFactor":100,"animationCoolOffTimeout":0}},"firstMatch":[{}]},"desiredCapabilities":{"platformName":"iOS","appium:automationName":"XCUITest","appium:newCommandTimeout":120,"appium:udid":"00008101-000A0D5C34C3003A","df:skipReport":true,"appium:mjpegServerPort":63001,"appium:waitForQuiescence":false,"appium:wdaEventloopIdleDelay":0,"appium:eventLoopIdleDelaySec":0,"appium:waitForIdleTimeout":0,"appium:settings":{"mjpegServerFramerate":34,"mjpegServerScreenshotQuality":75,"mjpegScalingFactor":100,"animationCoolOffTimeout":0},"appium:usePreinstalledWDA":true,"appium:updatedWDABundleId"...
[AppiumDriver@8950] Calling AppiumDriver.createSession() with args: [{"platformName":"iOS","appium:automationName":"XCUITest","appium:newCommandTimeout":120,"appium:udid":"00008101-000A0D5C34C3003A","df:skipReport":true,"appium:mjpegServerPort":63001,"appium:waitForQuiescence":false,"appium:wdaEventloopIdleDelay":0,"appium:eventLoopIdleDelaySec":0,"appium:waitForIdleTimeout":0,"appium:settings":{"mjpegServerFramerate":34,"mjpegServerScreenshotQuality":75,"mjpegScalingFactor":100,"animationCoolOffTimeout":0},"appium:usePreinstalledWDA":true,"appium:updatedWDABundleId":"ru.headhqa.ios.appium.WebDriverAgentRunner","appium:updatedWDABundleIdSuffix":"","df:portForward":true},null,{"alwaysMatch":{"platformName":"iOS","appium:automationName":"XCUITest","appium:newCommandTimeout":120,"appium:udid":"00008101-000A0D5C34C3003A","df:skipReport":true,"appium:mjpegServerPort":63001,"appium:waitForQuiescence":false,"appium:wdaEventloopIdleDelay":0,"appium:eventLoopIdleDelaySec":0,"appium:waitForIdleTimeout":0,"appium:settings":{"mjpegServerFramerate":34,"mjpegServerScreenshotQuality":75,...
[device-farm-main] 📱 Plugin Args: {"platform":"both","iosDeviceType":"real","androidDeviceType":"both"}
[AppiumDriver@8950] Plugins which can handle cmd 'createSession': device-farm
[AppiumDriver@8950] Plugin device-farm is now handling cmd 'createSession'
[device-farm-main] 📱 pluginArgs: {"platform":"both","iosDeviceType":"real","androidDeviceType":"both","skipChromeDownload":true,"maxSessions":8,"adbRemote":[],"emulators":[],"simulators":[],"deviceAvailabilityTimeoutMs":300000,"deviceAvailabilityQueryIntervalMs":10000,"sendNodeDevicesToHubIntervalMs":30000,"checkStaleDevicesIntervalMs":30000,"checkBlockedDevicesIntervalMs":30000,"newCommandTimeoutSec":60,"bindHostOrIp":"192.168.88.97","enableDashboard":false,"removeDevicesFromDatabaseBeforeRunningThePlugin":false,"remoteConnectionTimeout":60000,"liveStreaming":false,"wdaBundleId":"","preBuildWDAPath":""}
[device-farm-main] Receiving session request at host: 192.168.88.97
[device-farm-main] 📱 Creating temporary session capability_id: d19bd707-5312-4850-a18f-8a8a7707e6cb
[device-farm-main] Max session count: 8, Busy device count: 0
[device-farm-main] Waiting for free device. Filter: {"platform":"ios","udid":"00008101-000A0D5C34C3003A","busy":false,"userBlocked":false}}
[device-farm-main] Updated devices with filter: {"platform":"ios","udid":{"$in":"00008101-000A0D5C34C3003A"}}
[device-farm-main] Updated devices with filter: {"platform":"ios","udid":{"$in":"00008101-000A0D5C34C3003A"}}
[device-farm-main] 📱 Blocking device 00008101-000A0D5C34C3003A at host http://192.168.88.97:4723 for new session
[device-farm-main] Updating allocated device: "{"userBlocked":true,"offline":false,"wdaLocalPort":63000,"mjpegServerPort":63001,"udid":"00008101-000A0D5C34C3003A","sdk":"17.4.1","name":"iPhone-12 AQA Black","busy":false,"realDevice":true,"deviceType":"real","platform":"ios","host":"http://192.168.88.97:4723","wdaBundleId":"","productModel":"iPhone13,2","totalUtilizationTimeMilliSec":345217,"sessionStartTime":0,"width":"390","height":"844","tags":[],"nodeId":"6338ecbb-9ac4-4605-bbef-81c7156162e4","meta":{"revision":24,"created":1719118151201,"version":0,"updated":1719118786191},"$loki":2,"proxyUrl":"http://127.0.0.1:63000","proxySessionId":"E22886BA-342F-451B-988D-7B182B0B434A"}"
[device-farm-main] Updated allocated device: "[{"userBlocked":true,"offline":false,"wdaLocalPort":63000,"mjpegServerPort":63001,"udid":"00008101-000A0D5C34C3003A","sdk":"17.4.1","name":"iPhone-12 AQA Black","busy":true,"realDevice":true,"deviceType":"real","platform":"ios","host":"http://192.168.88.97:4723","wdaBundleId":"","productModel":"iPhone13,2","totalUtilizationTimeMilliSec":345217,"sessionStartTime":0,"width":"390","height":"844","tags":[],"nodeId":"6338ecbb-9ac4-4605-bbef-81c7156162e4","meta":{"revision":26,"created":1719118151201,"version":0,"updated":1719118787210},"$loki":2,"proxyUrl":"http://127.0.0.1:63000","proxySessionId":"E22886BA-342F-451B-988D-7B182B0B434A","newCommandTimeout":120}]"
[device-farm-main] device.host: http://192.168.88.97:4723 and pluginArgs.bindHostOrIp: 192.168.88.97
[device-farm-main] 📱 Creating session on the same node
[AppiumDriver@8950] Executing default handling behavior for command 'createSession'
[AppiumDriver@8950] Event 'newSessionRequested' logged at 1719118787211 (07:59:47 GMT+0300 (Moscow Standard Time))
[Appium] Attempting to find matching driver for automationName 'XCUITest' and platformName 'iOS'
[Appium] The 'xcuitest' driver was installed and matched caps.
[Appium] Will require it at /Users/qaauto/.appium/node_modules/appium-xcuitest-driver
[Appium] Requiring driver at /Users/qaauto/.appium/node_modules/appium-xcuitest-driver/build/index.js
[AppiumDriver@8950] Appium v2.10.3 creating new XCUITestDriver (v7.17.5) session
[AppiumDriver@8950] Checking BaseDriver versions for Appium and XCUITestDriver
[AppiumDriver@8950] Appium's BaseDriver version is 9.10.3
[AppiumDriver@8950] XCUITestDriver's BaseDriver version is 9.6.0
[XCUITestDriver@c799] Creating session with W3C capabilities: {
[XCUITestDriver@c799]   "alwaysMatch": {
[XCUITestDriver@c799]     "platformName": "iOS",
[XCUITestDriver@c799]     "df:skipReport": true,
[XCUITestDriver@c799]     "appium:automationName": "XCUITest",
[XCUITestDriver@c799]     "appium:newCommandTimeout": 120,
[XCUITestDriver@c799]     "appium:waitForQuiescence": false,
[XCUITestDriver@c799]     "appium:wdaEventloopIdleDelay": 0,
[XCUITestDriver@c799]     "appium:eventLoopIdleDelaySec": 0,
[XCUITestDriver@c799]     "appium:waitForIdleTimeout": 0,
[XCUITestDriver@c799]     "appium:udid": "00008101-000A0D5C34C3003A",
[XCUITestDriver@c799]     "appium:deviceName": "iPhone-12 AQA Black",
[XCUITestDriver@c799]     "appium:platformVersion": "17.4.1",
[XCUITestDriver@c799]     "appium:wdaLocalPort": 63000,
[XCUITestDriver@c799]     "appium:usePreinstalledWDA": true,
[XCUITestDriver@c799]     "appium:updatedWDABundleId": "ru.headhqa.ios.appium.WebDriverAgentRunner",
[XCUITestDriver@c799]     "appium:updatedWDABundleIdSuffix": ""
[XCUITestDriver@c799]   },
[XCUITestDriver@c799]   "firstMatch": [
[XCUITestDriver@c799]     {}
[XCUITestDriver@c799]   ]
[XCUITestDriver@c799] }
[BaseDriver] The 'waitForQuiescence' capability has been deprecated and must not be used anymore. Please check the driver documentation for possible alternatives.
[BaseDriver] The 'waitForQuiescence' capability has been deprecated and must not be used anymore. Please check the driver documentation for possible alternatives.
[XCUITestDriver@c799] The following provided capabilities were not recognized by this driver:
[XCUITestDriver@c799]   df:skipReport
[XCUITestDriver@c799]   eventLoopIdleDelaySec
[XCUITestDriver@c799] The desired capabilities include neither an app nor a bundleId. WebDriverAgent will be started without the default app
[XCUITestDriver@c799 (8b48c32d)] Session created with session id: 8b48c32d-e09e-4f7f-b1ad-d82ca4b93e7e
[XCUITest] Current user: 'qaauto'
[XCUITestDriver@c799 (8b48c32d)] Available real devices: 00008101-000A0D5C34C3003A
[XCUITestDriver@c799 (8b48c32d)] Creating iDevice object with udid '00008101-000A0D5C34C3003A'
[XCUITestDriver@c799 (8b48c32d)] Determining device to run tests on: udid: '00008101-000A0D5C34C3003A', real device: true
[XCUITestDriver@c799 (8b48c32d)] Normalized platformVersion capability value '17.4.1' to '17.4'
[XCUITestDriver@c799 (8b48c32d)] Event 'xcodeDetailsRetrieved' logged at 1719118787228 (07:59:47 GMT+0300 (Moscow Standard Time))
[XCUITestDriver@c799 (8b48c32d)] Event 'appConfigured' logged at 1719118787228 (07:59:47 GMT+0300 (Moscow Standard Time))
[XCUITestDriver@c799 (8b48c32d)] Event 'resetStarted' logged at 1719118787228 (07:59:47 GMT+0300 (Moscow Standard Time))
[XCUITestDriver@c799 (8b48c32d)] Reset: running ios real device reset flow
[XCUITestDriver@c799 (8b48c32d)] Event 'resetComplete' logged at 1719118787228 (07:59:47 GMT+0300 (Moscow Standard Time))
[XCUITestDriver@c799 (8b48c32d)] Using WDA path: '/Users/qaauto/.appium/node_modules/appium-xcuitest-driver/node_modules/appium-webdriveragent'
[XCUITestDriver@c799 (8b48c32d)] Using WDA agent: '/Users/qaauto/.appium/node_modules/appium-xcuitest-driver/node_modules/appium-webdriveragent/WebDriverAgent.xcodeproj'
[XCUITest] Crash reports root '/Users/qaauto/Library/Logs/CrashReporter/MobileDevice/iPhone-12 AQA Black' does not exist. Got nothing to gather.
[XCUITestDriver@c799 (8b48c32d)] Event 'logCaptureStarted' logged at 1719118787344 (07:59:47 GMT+0300 (Moscow Standard Time))
[XCUITestDriver@c799 (8b48c32d)] Setting up real device
[XCUITestDriver@c799 (8b48c32d)] No obsolete cached processes from previous WDA sessions listening on port 63000 have been found
[DevCon Factory] Requesting connection for device 00008101-000A0D5C34C3003A on local port 63000, device port 63000
[DevCon Factory] Cached connections count: 0
[DevCon Factory] Successfully requested the connection for 00008101-000A0D5C34C3003A:63000
[XCUITestDriver@c799 (8b48c32d)] Starting WebDriverAgent initialization with the synchronization key 'XCUITestDriver'
[XCUITestDriver@c799 (8b48c32d)] Trying to start WebDriverAgent once since at least one of webDriverAgentUrl,usePreinstalledWDA capabilities is provided
[XCUITestDriver@c799 (8b48c32d)] Event 'wdaStartAttempted' logged at 1719118787404 (07:59:47 GMT+0300 (Moscow Standard Time))
[XCUITestDriver@c799 (8b48c32d)] The executable name for the bundle id 'ru.headhqa.ios.appium.WebDriverAgentRunner' was 'WebDriverAgentRunner-Runner'
[XCUITestDriver@c799 (8b48c32d)] Calling devicectl to kill the process
[XCUITestDriver@c799 (8b48c32d)] Executing xcrun devicectl device info processes --device 00008101-000A0D5C34C3003A --quiet --json-output -
[XCUITestDriver@c799 (8b48c32d)] The process of the bundle id 'ru.headhqa.ios.appium.WebDriverAgentRunner' was not running
[XCUITestDriver@c799 (8b48c32d)] Launching WebDriverAgent on the device without xcodebuild
[XCUITestDriver@c799 (8b48c32d)] Executing xcrun devicectl device process launch --device 00008101-000A0D5C34C3003A --terminate-existing --environment-variables '{"USE_PORT":"63000","WDA_PRODUCT_BUNDLE_IDENTIFIER":"ru.headhqa.ios.appium.WebDriverAgentRunner"}' ru.headhqa.ios.appium.WebDriverAgentRunner
[WD Proxy] Matched '/status' to command name 'getStatus'
[WD Proxy] Proxying [GET /status] to [GET http://127.0.0.1:63000/status] with no body
[iProxy@00008101:63000] Connection was refused to port 63000
[WD Proxy] socket hang up
[WD Proxy] Matched '/status' to command name 'getStatus'
[WD Proxy] Proxying [GET /status] to [GET http://127.0.0.1:63000/status] with no body
[iProxy@00008101:63000] Connection was refused to port 63000
[WD Proxy] socket hang up
[WD Proxy] Matched '/status' to command name 'getStatus'
[WD Proxy] Proxying [GET /status] to [GET http://127.0.0.1:63000/status] with no body
[iProxy@00008101:63000] Connection was refused to port 63000
[WD Proxy] socket hang up
[WD Proxy] Matched '/status' to command name 'getStatus'
[WD Proxy] Proxying [GET /status] to [GET http://127.0.0.1:63000/status] with no body
[iProxy@00008101:63000] Connection was refused to port 63000
[WD Proxy] socket hang up
[WD Proxy] Matched '/status' to command name 'getStatus'
[WD Proxy] Proxying [GET /status] to [GET http://127.0.0.1:63000/status] with no body
[iProxy@00008101:63000] Connection was refused to port 63000
[WD Proxy] socket hang up
[WD Proxy] Matched '/status' to command name 'getStatus'
[WD Proxy] Proxying [GET /status] to [GET http://127.0.0.1:63000/status] with no body
[WD Proxy] Got response with status 200: {"value":{"build":{"version":"8.5.2","time":"Apr 17 2024 08:22:41","productBundleIdentifier":"ru.headhqa.ios.appium.WebDriverAgentRunner"},"os":{"testmanagerdVersion":65535,"name":"iOS","sdkVersion":"17.0","version":"17.4.1"},"device":"iphone","ios":{"ip":"192.168.88.96"},"message":"WebDriverAgent is ready to accept commands","state":"success","ready":true},"sessionId":null}
[XCUITestDriver@c799 (8b48c32d)] Event 'wdaSessionAttempted' logged at 1719118791811 (07:59:51 GMT+0300 (Moscow Standard Time))
[XCUITestDriver@c799 (8b48c32d)] Sending createSession command to WDA
[XCUITestDriver@c799 (8b48c32d)] Matched '/session' to command name 'createSession'
[XCUITestDriver@c799 (8b48c32d)] Proxying [POST /session] to [POST http://127.0.0.1:63000/session] with body: {"capabilities":{"firstMatch":[{"arguments":[],"environment":{},"eventloopIdleDelaySec":0,"shouldWaitForQuiescence":false,"shouldUseTestManagerForVisibilityDetection":false,"maxTypingFrequency":60,"shouldUseSingletonTestManager":true,"waitForIdleTimeout":0,"shouldTerminateApp":true,"forceAppLaunch":true,"useNativeCachingStrategy":true,"forceSimulatorSoftwareKeyboardPresence":true}],"alwaysMatch":{}}}
[XCUITestDriver@c799 (8b48c32d)] Got response with status 200: {"value":{"sessionId":"5F6C7885-CD37-4A17-B618-1987FFB7CF33","capabilities":{"sdkVersion":"17.4.1","device":"iphone"}},"sessionId":"5F6C7885-CD37-4A17-B618-1987FFB7CF33"}
[XCUITestDriver@c799 (8b48c32d)] Determined the downstream protocol as 'W3C'
[XCUITestDriver@c799 (8b48c32d)] WDA session startup took 8ms
[XCUITestDriver@c799 (8b48c32d)] Event 'wdaSessionStarted' logged at 1719118791820 (07:59:51 GMT+0300 (Moscow Standard Time))
[XCUITestDriver@c799 (8b48c32d)] Event 'wdaStarted' logged at 1719118791820 (07:59:51 GMT+0300 (Moscow Standard Time))
[BaseDriver] The value of 'elementResponseAttributes' setting did not change. Skipping the update for it
[BaseDriver] The value of 'shouldUseCompactResponses' setting did not change. Skipping the update for it
[AppiumDriver@8950] New XCUITestDriver session created successfully, session 8b48c32d-e09e-4f7f-b1ad-d82ca4b93e7e added to master session list
[AppiumDriver@8950] Applying the initial values to Appium settings parsed from W3C caps: {"mjpegServerFramerate":34,"mjpegServerScreenshotQuality":75,"mjpegScalingFactor":100,"animationCoolOffTimeout":0}
[XCUITestDriver@c799 (8b48c32d)] Matched '/appium/settings' to command name 'updateSettings'
[XCUITestDriver@c799 (8b48c32d)] Proxying [POST /appium/settings] to [POST http://127.0.0.1:63000/session/5F6C7885-CD37-4A17-B618-1987FFB7CF33/appium/settings] with body: {"settings":{"mjpegServerFramerate":34}}
[XCUITestDriver@c799 (8b48c32d)] Got response with status 200: {"value":{"mjpegFixOrientation":false,"boundElementsByIndex":false,"mjpegServerFramerate":34,"screenshotOrientation":"auto","reduceMotion":false,"elementResponseAttributes":"type,label","screenshotQuality":3,"mjpegScalingFactor":100,"keyboardPrediction":0,"defaultActiveApplication":"auto","mjpegServerScreenshotQuality":25,"defaultAlertAction":"","keyboardAutocorrection":0,"useFirstMatch":false,"shouldUseCompactResponses":true,"customSnapshotTimeout":15,"dismissAlertButtonSelector":"","activeAppDetectionPoint":"64.00,64.00","snapshotMaxDepth":50,"waitForIdleTimeout":0,"includeNonModalElements":false,"acceptAlertButtonSelector":"","animationCoolOffTimeout":2},"sessionId":"5F6C7885-CD37-4A17-B618-1987FFB7CF33"}
[XCUITestDriver@c799 (8b48c32d)] Matched '/appium/settings' to command name 'updateSettings'
[XCUITestDriver@c799 (8b48c32d)] Proxying [POST /appium/settings] to [POST http://127.0.0.1:63000/session/5F6C7885-CD37-4A17-B618-1987FFB7CF33/appium/settings] with body: {"settings":{"mjpegServerScreenshotQuality":75}}
[XCUITestDriver@c799 (8b48c32d)] Got response with status 200: {"value":{"mjpegFixOrientation":false,"boundElementsByIndex":false,"mjpegServerFramerate":34,"screenshotOrientation":"auto","reduceMotion":false,"elementResponseAttributes":"type,label","screenshotQuality":3,"mjpegScalingFactor":100,"keyboardPrediction":0,"defaultActiveApplication":"auto","mjpegServerScreenshotQuality":75,"defaultAlertAction":"","keyboardAutocorrection":0,"useFirstMatch":false,"shouldUseCompactResponses":true,"customSnapshotTimeout":15,"dismissAlertButtonSelector":"","activeAppDetectionPoint":"64.00,64.00","snapshotMaxDepth":50,"waitForIdleTimeout":0,"includeNonModalElements":false,"acceptAlertButtonSelector":"","animationCoolOffTimeout":2},"sessionId":"5F6C7885-CD37-4A17-B618-1987FFB7CF33"}
[BaseDriver] The value of 'mjpegScalingFactor' setting did not change. Skipping the update for it
[XCUITestDriver@c799 (8b48c32d)] Matched '/appium/settings' to command name 'updateSettings'
[XCUITestDriver@c799 (8b48c32d)] Proxying [POST /appium/settings] to [POST http://127.0.0.1:63000/session/5F6C7885-CD37-4A17-B618-1987FFB7CF33/appium/settings] with body: {"settings":{"animationCoolOffTimeout":0}}
[XCUITestDriver@c799 (8b48c32d)] Got response with status 200: {"value":{"mjpegFixOrientation":false,"boundElementsByIndex":false,"mjpegServerFramerate":34,"screenshotOrientation":"auto","reduceMotion":false,"elementResponseAttributes":"type,label","screenshotQuality":3,"mjpegScalingFactor":100,"keyboardPrediction":0,"defaultActiveApplication":"auto","mjpegServerScreenshotQuality":75,"defaultAlertAction":"","keyboardAutocorrection":0,"useFirstMatch":false,"shouldUseCompactResponses":true,"customSnapshotTimeout":15,"dismissAlertButtonSelector":"","activeAppDetectionPoint":"64.00,64.00","snapshotMaxDepth":50,"waitForIdleTimeout":0,"includeNonModalElements":false,"acceptAlertButtonSelector":"","animationCoolOffTimeout":0},"sessionId":"5F6C7885-CD37-4A17-B618-1987FFB7CF33"}
[AppiumDriver@8950] Event 'newSessionStarted' logged at 1719118791837 (07:59:51 GMT+0300 (Moscow Standard Time))
[device-farm-main] 📱 Forwarding ios port to real device 00008101-000A0D5C34C3003A for manual interaction
[DevCon Factory Device Farm] Requesting connection for device 00008101-000A0D5C34C3003A on local port 63001, device port 63001
[DevCon Factory Device Farm] Cached connections count: 1
[DevCon Factory Device Farm] Found cached connections on port #63001: ["00008101-000A0D5C34C3003A:63001"]
[DevCon Factory Device Farm] Port #63001 is busy. Did you quit the previous driver session(s) properly?
[DevCon Factory Device Farm] Trying to release the port
[DevCon Factory Device Farm] Releasing the listener for '00008101-000A0D5C34C3003A:63001'
[iProxy@00008101:63001] Closing the connection
[iProxy@00008101:63001] The connection has been closed
[DevCon Factory Device Farm] Port #63001 has been successfully released after 2ms
[DevCon Factory Device Farm] Successfully requested the connection for 00008101-000A0D5C34C3003A:63001
[device-farm-main] 📱 d19bd707-5312-4850-a18f-8a8a7707e6cb Session response:  {"protocol":"W3C","value":["8b48c32d-e09e-4f7f-b1ad-d82ca4b93e7e",{"webStorageEnabled":false,"locationContextEnabled":false,"browserName":"","platform":"MAC","javascriptEnabled":true,"databaseEnabled":false,"takesScreenshot":true,"networkConnectionEnabled":false,"platformName":"iOS","df:skipReport":true,"automationName":"XCUITest","newCommandTimeout":120,"waitForQuiescence":false,"wdaEventloopIdleDelay":0,"eventLoopIdleDelaySec":0,"waitForIdleTimeout":0,"udid":"00008101-000A0D5C34C3003A","deviceName":"iPhone-12 AQA Black","platformVersion":"17.4","wdaLocalPort":63000,"usePreinstalledWDA":true,"updatedWDABundleId":"ru.headhqa.ios.appium.WebDriverAgentRunner","updatedWDABundleIdSuffix":""},"W3C"]}
[device-farm-main] 📱 Removing pending session with capability_id: d19bd707-5312-4850-a18f-8a8a7707e6cb
[device-farm-main] d19bd707-5312-4850-a18f-8a8a7707e6cb 📱 Session response is CreateSessionResponseInternal
[device-farm-main] 📱 d19bd707-5312-4850-a18f-8a8a7707e6cb ----- Device UDID 00008101-000A0D5C34C3003A blocked for session 8b48c32d-e09e-4f7f-b1ad-d82ca4b93e7e
[device-farm-main] Updating allocated device: "{"userBlocked":true,"offline":false,"wdaLocalPort":63000,"mjpegServerPort":63001,"udid":"00008101-000A0D5C34C3003A","sdk":"17.4.1","name":"iPhone-12 AQA Black","busy":false,"realDevice":true,"deviceType":"real","platform":"ios","host":"http://192.168.88.97:4723","wdaBundleId":"","productModel":"iPhone13,2","totalUtilizationTimeMilliSec":345217,"sessionStartTime":0,"width":"390","height":"844","tags":[],"nodeId":"6338ecbb-9ac4-4605-bbef-81c7156162e4","meta":{"revision":24,"created":1719118151201,"version":0,"updated":1719118786191},"$loki":2,"proxyUrl":"http://127.0.0.1:63000","proxySessionId":"E22886BA-342F-451B-988D-7B182B0B434A"}"
[device-farm-main] Updated allocated device: "[{"userBlocked":true,"offline":false,"wdaLocalPort":63000,"mjpegServerPort":63001,"udid":"00008101-000A0D5C34C3003A","sdk":"17.4.1","name":"iPhone-12 AQA Black","busy":true,"realDevice":true,"deviceType":"real","platform":"ios","host":"http://192.168.88.97:4723","wdaBundleId":"","productModel":"iPhone13,2","totalUtilizationTimeMilliSec":345217,"sessionStartTime":1719118791843,"width":"390","height":"844","tags":[],"nodeId":"6338ecbb-9ac4-4605-bbef-81c7156162e4","meta":{"revision":27,"created":1719118151201,"version":0,"updated":1719118791844},"$loki":2,"proxyUrl":"http://127.0.0.1:63000","proxySessionId":"E22886BA-342F-451B-988D-7B182B0B434A","newCommandTimeout":120,"session_id":"8b48c32d-e09e-4f7f-b1ad-d82ca4b93e7e","lastCmdExecutedAt":1719118791843}]"
[device-farm-main] d19bd707-5312-4850-a18f-8a8a7707e6cb 📱 Updating Device 00008101-000A0D5C34C3003A with session ID 8b48c32d-e09e-4f7f-b1ad-d82ca4b93e7e
[device-farm-main] Updating allocated device: "{"userBlocked":true,"offline":false,"wdaLocalPort":63000,"mjpegServerPort":63001,"udid":"00008101-000A0D5C34C3003A","sdk":"17.4.1","name":"iPhone-12 AQA Black","busy":false,"realDevice":true,"deviceType":"real","platform":"ios","host":"http://192.168.88.97:4723","wdaBundleId":"","productModel":"iPhone13,2","totalUtilizationTimeMilliSec":345217,"sessionStartTime":0,"width":"390","height":"844","tags":[],"nodeId":"6338ecbb-9ac4-4605-bbef-81c7156162e4","meta":{"revision":24,"created":1719118151201,"version":0,"updated":1719118786191},"$loki":2,"proxyUrl":"http://127.0.0.1:63000","proxySessionId":"E22886BA-342F-451B-988D-7B182B0B434A"}"
[device-farm-main] Updated allocated device: "[{"userBlocked":true,"offline":false,"wdaLocalPort":63000,"mjpegServerPort":63001,"udid":"00008101-000A0D5C34C3003A","sdk":"17.4.1","name":"iPhone-12 AQA Black","busy":true,"realDevice":true,"deviceType":"real","platform":"ios","host":"http://192.168.88.97:4723","wdaBundleId":"","productModel":"iPhone13,2","totalUtilizationTimeMilliSec":345217,"sessionStartTime":1719118791843,"width":"390","height":"844","tags":[],"nodeId":"6338ecbb-9ac4-4605-bbef-81c7156162e4","meta":{"revision":28,"created":1719118151201,"version":0,"updated":1719118791844},"$loki":2,"proxyUrl":"http://127.0.0.1:63000","proxySessionId":"5F6C7885-CD37-4A17-B618-1987FFB7CF33","newCommandTimeout":120,"session_id":"8b48c32d-e09e-4f7f-b1ad-d82ca4b93e7e","lastCmdExecutedAt":1719118791843}]"
[AppiumDriver@8950] Promoting 1 sessionless plugins to be attached to session ID 8b48c32d-e09e-4f7f-b1ad-d82ca4b93e7e
[XCUITestDriver@c799 (8b48c32d)] Cached the protocol value 'W3C' for the new session 8b48c32d-e09e-4f7f-b1ad-d82ca4b93e7e
[XCUITestDriver@c799 (8b48c32d)] Responding to client with driver.createSession() result: {"capabilities":{"webStorageEnabled":false,"locationContextEnabled":false,"browserName":"","platform":"MAC","javascriptEnabled":true,"databaseEnabled":false,"takesScreenshot":true,"networkConnectionEnabled":false,"platformName":"iOS","df:skipReport":true,"automationName":"XCUITest","newCommandTimeout":120,"waitForQuiescence":false,"wdaEventloopIdleDelay":0,"eventLoopIdleDelaySec":0,"waitForIdleTimeout":0,"udid":"00008101-000A0D5C34C3003A","deviceName":"iPhone-12 AQA Black","platformVersion":"17.4","wdaLocalPort":63000,"usePreinstalledWDA":true,"updatedWDABundleId":"ru.headhqa.ios.appium.WebDriverAgentRunner","updatedWDABundleIdSuffix":""}}
[HTTP] <-- POST /wd/hub/session 200 4639 ms - 708 
[device-farm-main] Response from browser create session {"value":{"capabilities":{"webStorageEnabled":false,"locationContextEnabled":false,"browserName":"","platform":"MAC","javascriptEnabled":true,"databaseEnabled":false,"takesScreenshot":true,"networkConnectionEnabled":false,"platformName":"iOS","df:skipReport":true,"automationName":"XCUITest","newCommandTimeout":120,"waitForQuiescence":false,"wdaEventloopIdleDelay":0,"eventLoopIdleDelaySec":0,"waitForIdleTimeout":0,"udid":"00008101-000A0D5C34C3003A","deviceName":"iPhone-12 AQA Black","platformVersion":"17.4","wdaLocalPort":63000,"usePreinstalledWDA":true,"updatedWDABundleId":"ru.headhqa.ios.appium.WebDriverAgentRunner","updatedWDABundleIdSuffix":""},"sessionId":"8b48c32d-e09e-4f7f-b1ad-d82ca4b93e7e"}}
[device-farm-main] Updated devices with filter: {"udid":{"$in":["00008101-000A0D5C34C3003A"]}}
[HTTP] <-- POST /device-farm/api/dashboard/appiumSession 200 4650 ms - 835 
[iProxy@00008101:63001] Connection was refused to port 63001
[HTTP] --> GET /device-farm/ui-assets/device-loading-BbhTw1Mo.gif {}
[device-farm-main] Received non-webdriver request with url /device-farm/ui-assets/device-loading-BbhTw1Mo.gif. So, not proxying it to downstream.
[HTTP] <-- GET /device-farm/ui-assets/device-loading-BbhTw1Mo.gif 304 2 ms - - 
[device-farm-main] New streaming request recieved with url: /ios-stream/00008101-000A0D5C34C3003A
[device-farm-main] Device id 00008101-000A0D5C34C3003A matched for streaming url : /ios-stream/00008101-000A0D5C34C3003A
[device-farm-main] Updated devices with filter: {"udid":{"$in":["00008101-000A0D5C34C3003A"]}}
[device-farm-main] Found 0 device candidates to be released
[device-farm-main] Cleaning pending sessions...
[device-farm-main] No pending sessions to clean
[device-farm-main] Found 0 device candidates to be released
[device-farm-main] Cleaning pending sessions...
[device-farm-main] No pending sessions to clean