iOS devices are not available on STF after reboot linux

[dhreben]

WorkAround. ./zebrunner.sh restart

build@build:~/tools/mcloud-agent$ docker logs -f device-iPhone_12-000................1003A-connector
[27/09/2024 10:50:10] [INFO] 

        MCLOUD-IOS-CONNECTOR

[27/09/2024 10:50:10] [INFO] localhost 2222 port is free.
[27/09/2024 10:50:10] [INFO] Start containerized usbmuxd service/process.
[10:50:10.723][3] usbmuxd v1.1.1-58-gbc0b91c starting up
2024/09/27 10:50:10 socat[30] E connect(, AF=1 "/var/run/usbmuxd", 18): No such file or directory
[10:50:10.723][3] Using libusb 1.0.27
[27/09/2024 10:50:10] [ERROR] Can't connect to '/var/run/usbmuxd'. One more attempt...
libusb: error [get_usbfs_fd] File doesn't exist, wait 10 ms and try again
libusb: error [get_usbfs_fd] libusb couldn't open USB device /dev/bus/usb/003/009, errno=2
[10:50:10.736][2] Could not open device 3-9: LIBUSB_ERROR_NO_DEVICE
libusb: error [get_usbfs_fd] File doesn't exist, wait 10 ms and try again
libusb: error [get_usbfs_fd] libusb couldn't open USB device /dev/bus/usb/001/028, errno=2
[10:50:10.746][2] Could not open device 1-28: LIBUSB_ERROR_NO_DEVICE
libusb: error [get_usbfs_fd] File doesn't exist, wait 10 ms and try again
libusb: error [get_usbfs_fd] libusb couldn't open USB device /dev/bus/usb/011/025, errno=2
[10:50:10.756][2] Could not open device 11-25: LIBUSB_ERROR_NO_DEVICE
libusb: error [get_usbfs_fd] File doesn't exist, wait 10 ms and try again
libusb: error [get_usbfs_fd] libusb couldn't open USB device /dev/bus/usb/001/015, errno=2
[10:50:10.767][2] Could not open device 1-15: LIBUSB_ERROR_NO_DEVICE
libusb: error [get_usbfs_fd] File doesn't exist, wait 10 ms and try again
libusb: error [get_usbfs_fd] libusb couldn't open USB device /dev/bus/usb/001/030, errno=2
[10:50:10.777][2] Could not open device 1-30: LIBUSB_ERROR_NO_DEVICE
libusb: error [get_usbfs_fd] File doesn't exist, wait 10 ms and try again
libusb: error [get_usbfs_fd] libusb couldn't open USB device /dev/bus/usb/001/009, errno=2
[10:50:10.787][2] Could not open device 1-9: LIBUSB_ERROR_NO_DEVICE
[10:50:10.787][3] Initialization complete
[10:50:10.787][3] Found CDC-NCM and Apple USB Multiplexor in device 11-31 configuration 5
[10:50:10.787][2] Skipping switch device 11-31 mode from 3 to 3
[10:50:10.787][3] Found usbmux interface for device 11-31: 1
[10:50:10.789][3] Connecting to new device on location 0xb001f as ID 1
[10:50:10.792][3] Connected to v2.0 device 1 on location 0xb001f with serial number 000..............591A51003A
[27/09/2024 10:50:11] [INFO] Usbmuxd socket is available.
[27/09/2024 10:50:11] [INFO] Usbmuxd forwarding established.
[27/09/2024 10:50:11] [INFO] Device '00..............579591A51003A' is available.
[27/09/2024 10:50:11] [INFO] Device info:
{
  "level": "warning",
  "msg": "go-ios agent is not running. You might need to start it with 'ios tunnel start' for ios17+. Use ENABLE_GO_IOS_AGENT=yes for experimental daemon mode.",
  "time": "2024-09-27T10:50:11Z"
}
{
  "level": "warning",
  "msg": "failed to get tunnel info",
  "time": "2024-09-27T10:50:11Z",
  "udid": "00................A51003A"
}
{
  "ActivationState": "Activated",
  "BasebandActivationTicketVersion": "V2",
  "BasebandCertId": 3095201109,
  "BasebandChipID": 938209,
  "BasebandKeyHashInformation": {
    "AKeyStatus": 2,
    "SKeyHash": "AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA=",
    "SKeyStatus": 0
  },
  "BasebandMasterKeyHash": "1B41607650EBF11C6B39F41CB267DC64C121A9BCF44DBA5D28F55ACC86361BBA366554CD57B4C466055803E1EF81C870",
  "BasebandRegionSKU": "AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA==",
  "BasebandSerialNumber": "7BK/4A==",
  "BasebandStatus": "BBInfoAvailable",
  "BasebandVersion": "5.02.06",
  "BluetoothAddress": "44:f2:1b:30:7d:ba",
  "BoardId": 12,
  "BootSessionID": "FE000A26-8A2F-4AC9-A068-D342B5542917",
  "BrickState": false,
  "BuildVersion": "22A3354",
  "CPUArchitecture": "arm64e",
  "CarrierBundleInfoArray": [],
  "CertID": 3095201109,
  "ChipID": 33025,
  "ChipSerialNo": "7BK/4A==",
  "DeviceClass": "iPhone",
  "DeviceColor": "1",
  "DeviceName": "Qaprosoft’s iPhone (2)",
  "DieID": 1540798484119610,
  "EthernetAddress": "44:f2:1b:1e:f7:bd",
  "FirmwareVersion": "iBoot-11881.2.10",
  "FusingStatus": 3,
  "HardwareModel": "D53gAP",
  "HardwarePlatform": "t8101",
  "HasSiDP": true,
  "HostAttached": true,
  "HumanReadableProductVersionString": "18.0",
  "InternationalMobileEquipmentIdentity": "353055117904828",
  "InternationalMobileEquipmentIdentity2": "353055117660370",
  "MLBSerialNumber": "FG1044518FAPRVV5Y",
  "MobileEquipmentIdentifier": "35305511790482",
  "MobileSubscriberCountryCode": "",
  "MobileSubscriberNetworkCode": "",
  "ModelNumber": "MGJ53",
  "NonVolatileRAM": {
    "StartupMute": "MQ==",
    "SystemAudioVolumeSaved": "MQ==",
    "auto-boot": "dHJ1ZQ==",
    "backlight-level": "NTk4",
    "backlight-nits": "MHgwMDUwMDAwMA==",
    "boot-args": "",
    "fm-activation-locked": "Tk8=",
    "fm-spstatus": "WUVT",
    "usbcfwflasherResult": "Tm8gZXJyb3Jz"
  },
  "PairRecordProtectionClass": 4,
  "PartitionType": "GUID_partition_scheme",
  "PasswordProtected": false,
  "PkHash": "uH0FVUsJlKhEmD05YurBSpF46D+trNajs+X4F7a6CGTl4f/iRcwbiJB9YouFNk62",
  "ProductName": "iPhone OS",
  "ProductType": "iPhone13,2",
  "ProductVersion": "18.0",
  "ProductionSOC": true,
  "ProtocolVersion": "2",
  "ProximitySensorCalibration": "f3oABTIAHgBVvhtB5caMQgAAyEJTS7tAffuqQgAAFkNTS7tAffuqQgAAFkPbS2tC6omxwJ0EtUSwCbVE/Zy1RHpJtUTeV89Bc8vMQbTZ60F/ofNBAAAgQslgKkON0g1CAABIQgxBFkS51BVEccEIRnC4xkPEnp1fARsIEqr8nkUBGvBDvz2ARQIAAAAAAACK",
  "RegionInfo": "RM/A",
  "SIMStatus": "kCTSIMSupportSIMStatusNotInserted",
  "SIMTrayStatus": "kCTSIMSupportSIMTrayInsertedNoSIM",
  "SerialNumber": "FFVDMTXW0F0N",
  "SoftwareBehavior": "AQAAAAAAAAAAAAAAAAAAAA==",
  "SoftwareBundleVersion": "",
  "SupportedDeviceFamilies": [
    1
  ],
  "TelephonyCapability": true,
  "TimeIntervalSince1970": 1727434211.818939,
  "TimeZone": "Europe/Minsk",
  "TimeZoneOffsetFromUTC": 10800,
  "TrustedHostAttached": true,
  "UniqueChipID": 1540798484119610,
  "UniqueDeviceID": "00.............91A51003A",
  "UseRaptorCerts": true,
  "Uses24HourClock": false,
  "WiFiAddress": "44:f2:1b:1f:7c:04",
  "WirelessBoardSerialNumber": "0A504CE00224",
  "kCTPostponementInfoServiceProvisioningState": false,
  "kCTPostponementStatus": "kCTPostponementStatusActivated"
}
[27/09/2024 10:50:12] [INFO] Detected device os version: 18.0
[27/09/2024 10:50:12] [INFO] Major os version detected as '18'
[27/09/2024 10:50:12] [INFO] Starting go-ncm and its reporting on 3030 port.
2024/09/27 10:50:12 INFO usbmuxd version version=1.1.1-58-gbc0b91c
2024/09/27 10:50:12 INFO prometheus metrics up port=3030 endpoint=/metrics
[27/09/2024 10:50:12] [INFO] Ncm '/metrics' endpoint is not available.
[27/09/2024 10:50:12] [WARN] Waiting for 5 seconds.
2024/09/27 10:50:17 ERROR failed opening devices err="libusb: no device [code -4]"
2024/09/27 10:50:17 INFO assigning interface iface=iphone0 serial=00008101000579591A51003A
2024/09/27 10:50:17 INFO got device serial=00008101000579591A51003A
2024/09/27 10:50:17 INFO active config active=5 serial=00008101000579591A51003A
2024/09/27 10:50:17 INFO available configs configs="map[1:Configuration 1 2:Configuration 2 3:Configuration 3 4:Configuration 4 5:Configuration 5]" len=5 serial=00008101000579591A51003A
2024/09/27 10:50:17 INFO got config config="vid=05ac,pid=12a8,bus=11,addr=31,config=5" serial=00008101000579591A51003A
2024/09/27 10:50:17 INFO alt setting alt="Interface 3 alternate setting 1 (available endpoints: [0x05(5,OUT) 0x87(7,IN)])" class=10 subclass=0 protocol=1 serial=00008101000579591A51003A
2024/09/27 10:50:17 INFO alt setting alt="Interface 5 alternate setting 1 (available endpoints: [0x06(6,OUT) 0x88(8,IN)])" class=10 subclass=0 protocol=1 serial=00008101000579591A51003A
[27/09/2024 10:50:17] [INFO] Found 0 device connected with ncm.
2024/09/27 10:50:17 INFO 0x88
2024/09/27 10:50:17 INFO 0x06
2024/09/27 10:50:17 INFO claimed interfaces serial=00008101000579591A51003A
2024/09/27 10:50:17 INFO created streams serial=00008101000579591A51003A
2024/09/27 10:50:17 INFO creating TAP device device=iphone0 serial=00008101000579591A51003A
2024/09/27 10:50:17 INFO add IP address to device device=iphone0 serial=00008101000579591A51003A ip=FC00:0000:0000:0000:0000:0000:0000:00FB/64
2024/09/27 10:50:17 INFO ethernet device is up: device=iphone0 serial=00008101000579591A51003A
[27/09/2024 10:50:17] [WARN] Waiting for 5 seconds.
2024/09/27 10:50:22 INFO device already handled serial=00008101000579591A51003A
2024/09/27 10:50:22 INFO closing what="device vid=05ac,pid=12a8,bus=11,addr=31"
[27/09/2024 10:50:22] [INFO] Found 1 device connected with ncm.
[27/09/2024 10:50:22] [INFO] Starting tunnel for --udid=000................591A51003A
[27/09/2024 10:50:22] [INFO] Go-ios '/tunnels' endpoint is not available.
[27/09/2024 10:50:22] [WARN] Waiting for 5 seconds.
{"level":"warning","msg":"go-ios agent is not running. You might need to start it with 'ios tunnel start' for ios17+. Use ENABLE_GO_IOS_AGENT=yes for experimental daemon mode.","time":"2024-09-27T10:50:22Z"}
{"level":"info","msg":"Tunnel server started","time":"2024-09-27T10:50:22Z"}
{"level":"info","msg":"start tunnel","time":"2024-09-27T10:50:23Z","udid":"00.............91A51003A"}
{"level":"info","msg":"connect to lockdown tunnel endpoint on device","time":"2024-09-27T10:50:23Z"}
2024/09/27 10:50:27 INFO device already handled serial=00008101000579591A51003A
2024/09/27 10:50:27 INFO closing what="device vid=05ac,pid=12a8,bus=11,addr=31"
[27/09/2024 10:50:27] [INFO] Go-ios '/tunnels' endpoint is available:
[{"address":"fdcb:18a2:53de::1","rsdPort":50295,"udid":"0000..........591A51003A","userspaceTun":false,"userspaceTunPort":0}]
[27/09/2024 10:50:27] [INFO] Allow to download and mount DeveloperDiskImages automatically.
[27/09/2024 10:50:28] [INFO] Developer Image auto mount succeed:
{
  "level": "info",
  "msg": "device iOS version: 18.0.0, getting developer image: https://deviceboxhq.com/ddi-15F31d.zip",
  "time": "2024-09-27T10:50:27Z"
}
{
  "level": "info",
  "msg": "downloading 'https://deviceboxhq.com/ddi-15F31d.zip' to path '/tmp/DeveloperDiskImages/ddi-15F31d.zip'",
  "time": "2024-09-27T10:50:27Z"
}
{
  "basedir": "/tmp/DeveloperDiskImages",
  "level": "info",
  "msg": "success downloaded image",
  "time": "2024-09-27T10:50:28Z",
  "udid": "000081............51003A"
}
{
  "level": "warning",
  "msg": "there is already a developer image mounted, reboot the device if you want to remove it. aborting.",
  "time": "2024-09-27T10:50:28Z"
}
{
  "image": "/tmp/DeveloperDiskImages/ddi-15F31d/Restore",
  "level": "info",
  "msg": "success mounting image",
  "time": "2024-09-27T10:50:28Z",
  "udid": "0000............A51003A"
}
[27/09/2024 10:50:31] [INFO] 'com.facebook.WebDriverAgentRunner.xctrunner' app is already installed
[27/09/2024 10:50:31] [WARN] Running WDA not detected.
[27/09/2024 10:50:31] [INFO] Starting WebDriverAgent application on port '8100'.
[27/09/2024 10:50:31] [INFO] Resetting springboard process and waiting for 5 seconds.
2024/09/27 10:50:32 INFO device already handled serial=00008101000579591A51003A
2024/09/27 10:50:32 INFO closing what="device vid=05ac,pid=12a8,bus=11,addr=31"
{"level":"info","msg":"com.apple.springboard killed, Pid: 426","time":"2024-09-27T10:50:32Z"}
[27/09/2024 10:50:34] [INFO] Forwarding port: 8100
[10:50:35.494][2] Device 1: WARNING: handleConnectResult new session to port 8100 failed: 61
[27/09/2024 10:50:35] [INFO] Forwarding port: 8101
[10:50:35.721][2] Device 1: WARNING: handleConnectResult new session to port 8100 failed: 61
[27/09/2024 10:50:35] [WARN] Bad or no response from 'http://localhost:8100/status'. One more attempt...
[10:50:36.154][2] Device 1: WARNING: handleConnectResult new session to port 8100 failed: 61
2024/09/27 10:50:37 INFO device already handled serial=00008101000579591A51003A
2024/09/27 10:50:37 INFO closing what="device vid=05ac,pid=12a8,bus=11,addr=31"
[10:50:37.532][2] Device 1: WARNING: handleConnectResult new session to port 8100 failed: 61
[10:50:37.748][2] Device 1: WARNING: handleConnectResult new session to port 8100 failed: 61
[27/09/2024 10:50:37] [WARN] Bad or no response from 'http://localhost:8100/status'. One more attempt...
{"bundleid":"com.facebook.WebDriverAgentRunner.xctrunner","level":"info","msg":"Running wda","testbundleid":"com.facebook.WebDriverAgentRunner.xctrunner","time":"2024-09-27T10:50:37Z","xctestconfig":"WebDriverAgentRunner.xctest"}
{"level":"info","msg":"got capabilities","receivedCaps":{"CapabilitiesDictionary":{"authorize test session capability":1,"automation mode":1,"daemon container sandbox extension":1,"initiate daemon control session capability":4,"initiate test runner session capability":2,"record UI interaction capability":1,"report crashes capability":1,"request log archive capability":1,"request spindump capability":2,"request tailspin capability":1,"supports delayed attachment transfers":1}},"time":"2024-09-27T10:50:37Z"}
{"caps":{"CapabilitiesDictionary":{"authorize test session capability":1,"automation mode":1,"daemon container sandbox extension":1,"initiate daemon control session capability":4,"initiate test runner session capability":2,"record UI interaction capability":1,"report crashes capability":1,"request log archive capability":1,"request spindump capability":2,"request tailspin capability":1,"supports delayed attachment transfers":1}},"level":"info","msg":"got capabilities","time":"2024-09-27T10:50:38Z"}
{"authorized":true,"level":"info","msg":"authorized","time":"2024-09-27T10:50:38Z"}
2024-09-27 13:50:38.197814+0300 WebDriverAgentRunner-Runner[487:8354] [Default] Running tests...
13:50:38.213 WebDriverAgentRunner-Runner[487:8354] Creating future for 'requesting test configuration' with timeout 600.00
13:50:38.213 WebDriverAgentRunner-Runner[487:8354] Notifying test runner ready
13:50:38.213 WebDriverAgentRunner-Runner[487:8354] <XCTWaiter: 0x303f3f4e0> entering wait loop for 600.00s with expectations: `requesting test configuration`
13:50:38.213 WebDriverAgentRunner-Runner[487:8367] -[XCTRunnerDaemonSession(XCTMessagingRole_UIApplicationStateUpdating) _XCT_applicationDidUpdateState:] com.facebook.WebDriverAgentRunner.xctrunner@/private/var/containers/Bundle/Application/FD6C5F55-3AA2-4F84-A1B8-AA92025950FF/WebDriverAgentRunner-Runner.app pid: 487 state: running foreground
13:50:38.217 WebDriverAgentRunner-Runner[487:8367] Received test runner ready reply. Waiting for request to start running tests.
13:50:38.217 WebDriverAgentRunner-Runner[487:8354] Returning result XCTWaiterResultCompleted for waiter <XCTWaiter: 0x303f3f4e0>
13:50:38.217 WebDriverAgentRunner-Runner[487:8354] Wait <XCTWaiterWait: 0x30240c980> completed after 0.004s
13:50:38.217 WebDriverAgentRunner-Runner[487:8354] cleaning up requesting test configuration
13:50:38.217 WebDriverAgentRunner-Runner[487:8354] Loading test bundle at: file:///private/var/containers/Bundle/Application/FD6C5F55-3AA2-4F84-A1B8-AA92025950FF/WebDriverAgentRunner-Runner.app/PlugIns/WebDriverAgentRunner.xctest/
13:50:38.224 WebDriverAgentRunner-Runner[487:8365] -[XCTRunnerDaemonSession(XCTMessagingRole_UIApplicationStateUpdating) _XCT_applicationDidUpdateState:] com.facebook.WebDriverAgentRunner.xctrunner@/private/var/containers/Bundle/Application/FD6C5F55-3AA2-4F84-A1B8-AA92025950FF/WebDriverAgentRunner-Runner.app pid: 487 state: running foreground
13:50:38.248 WebDriverAgentRunner-Runner[487:8354] Finished loading test bundle
13:50:38.248 WebDriverAgentRunner-Runner[487:8354] Registering in-process symbolication service with image names: WebDriverAgentRunner
13:50:38.248 WebDriverAgentRunner-Runner[487:8354] Running tests with active test configuration: <XCTestConfiguration: 0x105d9c780>
                      testBundleURL:file://PlugIns/WebDriverAgentRunner.xctest
             testBundleRelativePath:(null)
                  productModuleName:WebDriverAgentRunner
                        testsToSkip:(null)
                         testsToRun:(null)
                     testTagsToSkip:(null)
                      testTagsToRun:(null)
                 reportResultsToIDE:YES
                   testsDrivenByIDE:NO
                  sessionIdentifier:8DC08F22-01AD-45A2-AEAB-2AB04C29776C
          disablePerformanceMetrics:NO
    treatMissingBaselinesAsFailures:NO
                    baselineFileURL:(null)
           baselineFileRelativePath:(null)
              targetApplicationPath:/private/var/containers/Bundle/Application/FD6C5F55-3AA2-4F84-A1B8-AA92025950FF/WebDriverAgentRunner-Runner.app
          targetApplicationBundleID:com.facebook.WebDriverAgentRunner.xctrunner
     testApplicationDependencies:(null)
     bundleIDsForCrashReportEmphasis:(null)
     testApplicationUserOverrides:(null)
         targetApplicationArguments:(null)
       targetApplicationEnvironment:(null)
                      reportActivities:YES
              testsMustRunOnMainThread:YES
                initializeForUITesting:YES
              initializeForMultiDevice:NO
        shouldExtractMultiDeviceRequirements:NO
         multiDevicePlatformVersionMap:null
       multiDeviceRequirementsFilePath:(null)
                traceCollectionEnabled:(null)
     enablePerformanceTestsDiagnostics:(null)
     aggregateStatisticsBeforeCrash:
{
    XCSuiteRecordsKey =     {
    };
}
            automationFrameworkPath:/Developer/Library/PrivateFrameworks/XCTAutomationSupport.framework
                         emitOSLogs:NO
           systemAttachmentLifetime:keepNever
             userAttachmentLifetime:keepAlways
       preferredScreenCaptureFormat:screenshots
       gatherLocalizableStringsData:NO
              testExecutionOrdering:alphabetical
        randomExecutionOrderingSeed:(null)
                   repetitionPolicy:<XCTRepetitionPolicy: 0x30313a8e0>
                mode:None
                maximumIterations:1
                repeatInNewRunnerProcess:NO

        runtimeIssueDetectionPolicy: <XCTRuntimeIssueDetectionPolicy: 0x30313aec0>
                detectionEnabled:NO
                severity:failure

                testTimeoutsEnabled:NO
                  leaksCheckingMode:off
     defaultTestExecutionTimeAllowance:600
     maximumTestExecutionTimeAllowance:0
                    IDECapabilities:<XCTCapabilities: 0x303325720>: {
    "XCTIssue capability" = 1;
    "daemon container sandbox extension" = 1;
    "delayed attachment transfer" = 1;
    "expected failure test capability" = 1;
    "request diagnostics for specific devices" = 1;
    "skipped test capability" = 1;
    "test case run configurations" = 1;
    "test iterations" = 1;
    "test timeout capability" = 1;
    "ubiquitous test identifiers" = 1;
}
             applicationBundleInfos:(null)

13:50:38.249 WebDriverAgentRunner-Runner[487:8354] <XCTWaiter: 0x303f08150> entering wait loop for 600.00s with expectations: `requesting ready for testing`
13:50:38.258 WebDriverAgentRunner-Runner[487:8367] _IDE_startExecutingTestPlanWithProtocolVersion:36
13:50:38.258 WebDriverAgentRunner-Runner[487:8354] Returning result XCTWaiterResultCompleted for waiter <XCTWaiter: 0x303f08150>
13:50:38.259 WebDriverAgentRunner-Runner[487:8354] Wait <XCTWaiterWait: 0x302437e00> completed after 0.010s
13:50:38.259 WebDriverAgentRunner-Runner[487:8354] cleaning up requesting ready for testing
13:50:38.259 WebDriverAgentRunner-Runner[487:8354] Wait <XCTWaiterWait: 0x30240e240> completed after 0.000s
13:50:38.259 WebDriverAgentRunner-Runner[487:8354] cleaning up IDE proxy
13:50:38.259 WebDriverAgentRunner-Runner[487:8354] Initializing for UI testing.
13:50:38.262 WebDriverAgentRunner-Runner[487:8354] Linked XCTAutomationSupport.framework from /System/Developer/Library/PrivateFrameworks/XCTAutomationSupport.framework/XCTAutomationSupport, built with Xcode 1540(15F18), modified on Wednesday, 17 April 2024 at 06:26:59 Moscow Standard Time, bundle version: 22720
13:50:38.263 WebDriverAgentRunner-Runner[487:8354] Wait <XCTWaiterWait: 0x302420d00> completed after 0.000s
13:50:38.263 WebDriverAgentRunner-Runner[487:8354] cleaning up initiating daemon session
13:50:38.264 WebDriverAgentRunner-Runner[487:8354] Creating future for 'enabling automation mode' with timeout 60.00
13:50:38.264 WebDriverAgentRunner-Runner[487:8354] <XCTWaiter: 0x303f08780> entering wait loop for 60.00s with expectations: `enabling automation mode`
13:50:38.269 WebDriverAgentRunner-Runner[487:8354] Returning result XCTWaiterResultCompleted for waiter <XCTWaiter: 0x303f08780>
13:50:38.269 WebDriverAgentRunner-Runner[487:8354] Wait <XCTWaiterWait: 0x302421e00> completed after 0.006s
13:50:38.270 WebDriverAgentRunner-Runner[487:8354] cleaning up enabling automation mode
13:50:38.270 WebDriverAgentRunner-Runner[487:8354] Creating future for 'loading Accessibility' with timeout 70.00
13:50:38.270 WebDriverAgentRunner-Runner[487:8354] <XCTWaiter: 0x303f08930> entering wait loop for 70.00s with expectations: `loading Accessibility`
[10:50:39.547][2] Device 1: WARNING: handleConnectResult new session to port 8100 failed: 61
13:50:38.813 WebDriverAgentRunner-Runner[487:8354] Returning result XCTWaiterResultCompleted for waiter <XCTWaiter: 0x303f08930>
13:50:38.813 WebDriverAgentRunner-Runner[487:8354] Wait <XCTWaiterWait: 0x302421c40> completed after 0.544s
13:50:38.813 WebDriverAgentRunner-Runner[487:8354] cleaning up loading Accessibility
13:50:38.813 WebDriverAgentRunner-Runner[487:8354] Creating future for 'enabling localized string gathering' with timeout 70.00
13:50:38.813 WebDriverAgentRunner-Runner[487:8354] <XCTWaiter: 0x303fc99e0> entering wait loop for 70.00s with expectations: `enabling localized string gathering`
13:50:38.814 WebDriverAgentRunner-Runner[487:8354] Returning result XCTWaiterResultCompleted for waiter <XCTWaiter: 0x303fc99e0>
13:50:38.814 WebDriverAgentRunner-Runner[487:8354] Wait <XCTWaiterWait: 0x3024bf080> completed after 0.001s
13:50:38.814 WebDriverAgentRunner-Runner[487:8354] cleaning up enabling localized string gathering
13:50:38.815 WebDriverAgentRunner-Runner[487:8354] Creating future for 'setting AX timeout to 60.00s' with timeout 65.00
13:50:38.815 WebDriverAgentRunner-Runner[487:8354] <XCTWaiter: 0x303f063d0> entering wait loop for 65.00s with expectations: `setting AX timeout to 60.00s`
13:50:38.815 WebDriverAgentRunner-Runner[487:8354] Returning result XCTWaiterResultCompleted for waiter <XCTWaiter: 0x303f063d0>
13:50:38.815 WebDriverAgentRunner-Runner[487:8354] Wait <XCTWaiterWait: 0x3024010c0> completed after 0.000s
13:50:38.815 WebDriverAgentRunner-Runner[487:8354] cleaning up setting AX timeout to 60.00s
13:50:38.815 WebDriverAgentRunner-Runner[487:8354] Creating future for 'registering for kAXUserTestingNotification' with timeout 65.00
13:50:38.815 WebDriverAgentRunner-Runner[487:8354] <XCTWaiter: 0x303f130f0> entering wait loop for 65.00s with expectations: `registering for kAXUserTestingNotification`
13:50:38.815 WebDriverAgentRunner-Runner[487:8354] Returning result XCTWaiterResultCompleted for waiter <XCTWaiter: 0x303f130f0>
13:50:38.815 WebDriverAgentRunner-Runner[487:8354] Wait <XCTWaiterWait: 0x302418740> completed after 0.000s
13:50:38.815 WebDriverAgentRunner-Runner[487:8354] cleaning up registering for kAXUserTestingNotification
13:50:38.816 WebDriverAgentRunner-Runner[487:8354] Creating future for 'registering for kAXAlertNotification' with timeout 65.00
13:50:38.816 WebDriverAgentRunner-Runner[487:8354] <XCTWaiter: 0x303f12b80> entering wait loop for 65.00s with expectations: `registering for kAXAlertNotification`
13:50:38.816 WebDriverAgentRunner-Runner[487:8354] Returning result XCTWaiterResultCompleted for waiter <XCTWaiter: 0x303f12b80>
13:50:38.816 WebDriverAgentRunner-Runner[487:8354] Wait <XCTWaiterWait: 0x3024183c0> completed after 0.000s
13:50:38.816 WebDriverAgentRunner-Runner[487:8354] cleaning up registering for kAXAlertNotification
13:50:38.816 WebDriverAgentRunner-Runner[487:8354] Creating future for 'registering for kAXPidStatusChangedNotification' with timeout 65.00
13:50:38.816 WebDriverAgentRunner-Runner[487:8354] <XCTWaiter: 0x303f12460> entering wait loop for 65.00s with expectations: `registering for kAXPidStatusChangedNotification`
13:50:38.816 WebDriverAgentRunner-Runner[487:8354] Returning result XCTWaiterResultCompleted for waiter <XCTWaiter: 0x303f12460>
13:50:38.816 WebDriverAgentRunner-Runner[487:8354] Wait <XCTWaiterWait: 0x302404a00> completed after 0.000s
13:50:38.816 WebDriverAgentRunner-Runner[487:8354] cleaning up registering for kAXPidStatusChangedNotification
13:50:38.816 WebDriverAgentRunner-Runner[487:8354] Creating future for 'requesting background assertion for test process' with timeout 30.00
13:50:38.816 WebDriverAgentRunner-Runner[487:8354] <XCTWaiter: 0x303fc9530> entering wait loop for 30.00s with expectations: `requesting background assertion for test process`
13:50:38.817 WebDriverAgentRunner-Runner[487:8365] Got reply for background assertion for 487, acquired: YES.
13:50:38.817 WebDriverAgentRunner-Runner[487:8354] Returning result XCTWaiterResultCompleted for waiter <XCTWaiter: 0x303fc9530>
13:50:38.817 WebDriverAgentRunner-Runner[487:8354] Wait <XCTWaiterWait: 0x3024be9c0> completed after 0.001s
13:50:38.817 WebDriverAgentRunner-Runner[487:8354] cleaning up requesting background assertion for test process
13:50:38.818 WebDriverAgentRunner-Runner[487:8354] Registering for UIApplicationDidEnterBackgroundNotification
13:50:38.818 WebDriverAgentRunner-Runner[487:8354] Hitting home button to enter background.
13:50:38.818 WebDriverAgentRunner-Runner[487:8354] Creating future for 'synthesizing device event' with timeout 30.00
13:50:38.818 WebDriverAgentRunner-Runner[487:8354] <XCTWaiter: 0x303fc8ea0> entering wait loop for 30.00s with expectations: `synthesizing device event`
13:50:38.841 WebDriverAgentRunner-Runner[487:8371] Handling kAXUserTestingNotification
13:50:38.841 WebDriverAgentRunner-Runner[487:8371] Received kAXUserTestingNotification from AX element pid: 477, elementOrHash.elementID: 0.1: {
    event = ButtonEventsCompleted;
}
2024-09-27 13:50:39.100038+0300 WebDriverAgentRunner-Runner[487:8354] [Default] Continuing to run tests in the background with task ID 2
13:50:39.087 WebDriverAgentRunner-Runner[487:8371] Handling kAXUserTestingNotification
13:50:39.088 WebDriverAgentRunner-Runner[487:8371] Received kAXUserTestingNotification from AX element pid: 477, elementOrHash.elementID: 0.1: {
    event = ButtonEventsCompleted;
}
13:50:39.103 WebDriverAgentRunner-Runner[487:8370] Handling kAXPidStatusChangedNotification
13:50:39.103 WebDriverAgentRunner-Runner[487:8370] Got kAXPidStatusChangedNotification for invalid pid 0, full payload {
    pid = 0;
    pipPid = 1;
}
13:50:39.107 WebDriverAgentRunner-Runner[487:8371] Handling kAXPidStatusChangedNotification
13:50:39.107 WebDriverAgentRunner-Runner[487:8371] Got kAXPidStatusChangedNotification for pid 487: active
13:50:39.120 WebDriverAgentRunner-Runner[487:8354] No application process found matching pid 487, caching
13:50:39.129 WebDriverAgentRunner-Runner[487:8366] Handling kAXUserTestingNotification
13:50:39.129 WebDriverAgentRunner-Runner[487:8366] Received kAXUserTestingNotification from AX element pid: 481, elementOrHash.elementID: 0.1: {
    controllerClass = "WidgetRenderer.WidgetSceneContentViewController";
    event = ViewDidAppear;
}
13:50:39.130 WebDriverAgentRunner-Runner[487:8366] Handling kAXUserTestingNotification
13:50:39.130 WebDriverAgentRunner-Runner[487:8366] Received kAXUserTestingNotification from AX element pid: 481, elementOrHash.elementID: 0.1: {
    controllerClass = "ChronoKit.SecureHostingController";
    event = ViewDidAppear;
}
13:50:39.139 WebDriverAgentRunner-Runner[487:8366] Handling kAXUserTestingNotification
13:50:39.140 WebDriverAgentRunner-Runner[487:8366] Received kAXUserTestingNotification from AX element pid: 481, elementOrHash.elementID: 0.1: {
    controllerClass = "WidgetRenderer.WidgetSceneContentViewController";
    event = ViewDidAppear;
}
13:50:39.142 WebDriverAgentRunner-Runner[487:8371] Handling kAXUserTestingNotification
13:50:39.143 WebDriverAgentRunner-Runner[487:8371] Received kAXUserTestingNotification from AX element pid: 481, elementOrHash.elementID: 0.1: {
    controllerClass = "ChronoKit.SecureHostingController";
    event = ViewDidAppear;
}
13:50:39.168 WebDriverAgentRunner-Runner[487:8371] Handling kAXUserTestingNotification
13:50:39.168 WebDriverAgentRunner-Runner[487:8371] Received kAXUserTestingNotification from AX element pid: 477, elementOrHash.elementID: 0.1: {
    controllerClass = SBHWidgetViewController;
    event = ViewDidAppear;
}
13:50:39.168 WebDriverAgentRunner-Runner[487:8371] Handling kAXUserTestingNotification
13:50:39.168 WebDriverAgentRunner-Runner[487:8371] Received kAXUserTestingNotification from AX element pid: 477, elementOrHash.elementID: 0.1: {
    controllerClass = SBHWidgetContainerViewController;
    event = ViewDidAppear;
}
13:50:39.169 WebDriverAgentRunner-Runner[487:8371] Handling kAXUserTestingNotification
13:50:39.169 WebDriverAgentRunner-Runner[487:8371] Received kAXUserTestingNotification from AX element pid: 477, elementOrHash.elementID: 0.1: {
    controllerClass = SBHMultiplexingViewController;
    event = ViewDidAppear;
}
13:50:39.171 WebDriverAgentRunner-Runner[487:8371] Handling kAXUserTestingNotification
13:50:39.171 WebDriverAgentRunner-Runner[487:8371] Received kAXUserTestingNotification from AX element pid: 477, elementOrHash.elementID: 0.1: {
    controllerClass = SBHWidgetViewController;
    event = ViewDidAppear;
}
13:50:39.174 WebDriverAgentRunner-Runner[487:8373] Handling kAXUserTestingNotification
13:50:39.175 WebDriverAgentRunner-Runner[487:8373] Received kAXUserTestingNotification from AX element pid: 477, elementOrHash.elementID: 0.1: {
    controllerClass = SBHWidgetContainerViewController;
    event = ViewDidAppear;
}
13:50:39.175 WebDriverAgentRunner-Runner[487:8373] Handling kAXUserTestingNotification
13:50:39.175 WebDriverAgentRunner-Runner[487:8373] Received kAXUserTestingNotification from AX element pid: 477, elementOrHash.elementID: 0.1: {
    controllerClass = SBHMultiplexingViewController;
    event = ViewDidAppear;
}
13:50:39.175 WebDriverAgentRunner-Runner[487:8373] Handling kAXUserTestingNotification
13:50:39.175 WebDriverAgentRunner-Runner[487:8373] Received kAXUserTestingNotification from AX element pid: 477, elementOrHash.elementID: 0.1: {
    controllerClass = SBRootFolderController;
    event = ViewDidAppear;
}
13:50:39.176 WebDriverAgentRunner-Runner[487:8373] Handling kAXUserTestingNotification
13:50:39.176 WebDriverAgentRunner-Runner[487:8373] Received kAXUserTestingNotification from AX element pid: 477, elementOrHash.elementID: 0.1: {
    controllerClass = SBHWidgetStackViewController;
    event = ViewDidAppear;
}
13:50:39.176 WebDriverAgentRunner-Runner[487:8373] Handling kAXUserTestingNotification
13:50:39.176 WebDriverAgentRunner-Runner[487:8373] Received kAXUserTestingNotification from AX element pid: 477, elementOrHash.elementID: 0.1: {
    controllerClass = SBHWidgetStackViewController;
    event = ViewDidAppear;
}
13:50:39.276 WebDriverAgentRunner-Runner[487:8354] Returning result XCTWaiterResultCompleted for waiter <XCTWaiter: 0x303fc8ea0>
13:50:39.276 WebDriverAgentRunner-Runner[487:8354] Wait <XCTWaiterWait: 0x3024be640> completed after 0.459s
13:50:39.277 WebDriverAgentRunner-Runner[487:8354] cleaning up synthesizing device event
13:50:39.277 WebDriverAgentRunner-Runner[487:8354] <XCTWaiter: 0x303f6e7c0> entering wait loop for 30.00s with expectations: `Expect notification 'UIApplicationDidEnterBackgroundNotification' from any object`
13:50:39.535 WebDriverAgentRunner-Runner[487:8366] Handling kAXUserTestingNotification
13:50:39.535 WebDriverAgentRunner-Runner[487:8366] Received kAXUserTestingNotification from AX element pid: 477, elementOrHash.elementID: 0.1: {
    controllerClass = SBSwitcherViewController;
    event = ViewDidDisappear;
}
13:50:39.536 WebDriverAgentRunner-Runner[487:8366] Handling kAXUserTestingNotification
13:50:39.536 WebDriverAgentRunner-Runner[487:8366] Received kAXUserTestingNotification from AX element pid: 477, elementOrHash.elementID: 0.1: {
    controllerClass = SBDeckSwitcherViewController;
    event = ViewDidDisappear;
}
13:50:39.536 WebDriverAgentRunner-Runner[487:8366] Handling kAXUserTestingNotification
13:50:39.537 WebDriverAgentRunner-Runner[487:8366] Received kAXUserTestingNotification from AX element pid: 477, elementOrHash.elementID: 0.1: {
    controllerClass = SBDeviceApplicationSceneViewController;
    event = ViewDidDisappear;
}
2024-09-27 13:50:39.542719+0300 WebDriverAgentRunner-Runner[487:8373] [Common] Snapshot request 0x303f05140 complete with error: <NSError: 0x303f6e6d0; domain: FBSSceneSnapshotErrorDomain; code: 4; "an unrelated condition or state was not satisfied"> {
    NSLocalizedDescription = an error occurred during a scene snapshotting operation;
}
13:50:39.538 WebDriverAgentRunner-Runner[487:8371] Handling kAXPidStatusChangedNotification
13:50:39.539 WebDriverAgentRunner-Runner[487:8371] Got kAXPidStatusChangedNotification for invalid pid 0, full payload {
    pid = 0;
    pipPid = 1;
}
13:50:39.544 WebDriverAgentRunner-Runner[487:8354] Returning result XCTWaiterResultCompleted for waiter <XCTWaiter: 0x303f6e7c0>
13:50:39.544 WebDriverAgentRunner-Runner[487:8354] Wait <XCTWaiterWait: 0x302475d80> completed after 0.267s
13:50:39.544 WebDriverAgentRunner-Runner[487:8354] cleaning up Expect notification 'UIApplicationDidEnterBackgroundNotification' from any object
13:50:39.545 WebDriverAgentRunner-Runner[487:8354] Done initializing for UI testing.
13:50:39.545 WebDriverAgentRunner-Runner[487:8354] Loading test suite...
13:50:39.623 WebDriverAgentRunner-Runner[487:8354] Finished loading test suite, took 0.078s
13:50:39.623 WebDriverAgentRunner-Runner[487:8354] Wait <XCTWaiterWait: 0x302425b40> completed after 0.000s
13:50:39.623 WebDriverAgentRunner-Runner[487:8354] cleaning up preparing execution worker
13:50:39.624 WebDriverAgentRunner-Runner[487:8354] Entering test execution event loop
13:50:39.624 WebDriverAgentRunner-Runner[487:8354] -[XCTRunnerIDESession(_XCTestObservationInternal) testBundleWillStart:]
Test Suite 'All tests' started at 2024-09-27 13:50:39.624.
XCTestOutputBarrier13:50:39.624 WebDriverAgentRunner-Runner[487:8354] Running suite...
13:50:39.624 WebDriverAgentRunner-Runner[487:8354] -[XCTRunnerIDESession(_XCTestObservationInternal) testSuiteWillStart:]
13:50:39.625 WebDriverAgentRunner-Runner[487:8354] -[XCTRunnerIDESession(_XCTestObservationInternal) testSuiteWillStart:]
13:50:39.625 WebDriverAgentRunner-Runner[487:8354] -[XCTRunnerIDESession(_XCTestObservationInternal) testSuiteWillStart:]
13:50:39.626 WebDriverAgentRunner-Runner[487:8354] <XCTContext: 0x301222cb0> started activity <XCActivityRecord: 0x301222d00> 2024-09-27 10:50:39 +0000: Suite Set Up
Test Suite 'WebDriverAgentRunner.xctest' started at 2024-09-27 13:50:39.625.
XCTestOutputBarrierTest Suite 'UITestingUITests' started at 2024-09-27 13:50:39.625.
XCTestOutputBarrier    t =      nans Suite Set Up
2024-09-27 13:50:39.642910+0300 WebDriverAgentRunner-Runner[487:8354] [User Defaults] Couldn't write values for keys (
    KeyboardAutocorrection
) in CFPrefsPlistSource<0x300331a70> (Domain: com.apple.keyboard.preferences, User: kCFPreferencesCurrentUser, ByHost: No, Container: (null), Contents Need Refresh: No): setting preferences outside an application's container requires user-preference-write or file-write-data sandbox access
2024-09-27 13:50:39.643201+0300 WebDriverAgentRunner-Runner[487:8354] [User Defaults] Couldn't write values for keys (
    "KeyboardAutocorrection_changedAt"
) in CFPrefsPlistSource<0x300331a70> (Domain: com.apple.keyboard.preferences, User: kCFPreferencesCurrentUser, ByHost: No, Container: (null), Contents Need Refresh: No): setting preferences outside an application's container requires user-preference-write or file-write-data sandbox access
2024-09-27 13:50:39.643442+0300 WebDriverAgentRunner-Runner[487:8354] [User Defaults] Couldn't write values for keys (
    "KeyboardAutocorrection_buildAtChange"
) in CFPrefsPlistSource<0x300331a70> (Domain: com.apple.keyboard.preferences, User: kCFPreferencesCurrentUser, ByHost: No, Container: (null), Contents Need Refresh: No): setting preferences outside an application's container requires user-preference-write or file-write-data sandbox access
2024-09-27 13:50:39.643670+0300 WebDriverAgentRunner-Runner[487:8354] [User Defaults] Couldn't write values for keys (
    "KeyboardAutocorrection_previousValue"
) in CFPrefsPlistSource<0x300331a70> (Domain: com.apple.keyboard.preferences, User: kCFPreferencesCurrentUser, ByHost: No, Container: (null), Contents Need Refresh: No): setting preferences outside an application's container requires user-preference-write or file-write-data sandbox access
2024-09-27 13:50:39.643890+0300 WebDriverAgentRunner-Runner[487:8354] [User Defaults] Couldn't write values for keys (
    "KeyboardAutocorrection_analyzedAt"
) in CFPrefsPlistSource<0x300331a70> (Domain: com.apple.keyboard.preferences, User: kCFPreferencesCurrentUser, ByHost: No, Container: (null), Contents Need Refresh: No): setting preferences outside an application's container requires user-preference-write or file-write-data sandbox access
2024-09-27 13:50:39.655671+0300 WebDriverAgentRunner-Runner[487:8354] [User Defaults] Couldn't write values for keys (
    KeyboardPrediction
) in CFPrefsPlistSource<0x300331a70> (Domain: com.apple.keyboard.preferences, User: kCFPreferencesCurrentUser, ByHost: No, Container: (null), Contents Need Refresh: No): setting preferences outside an application's container requires user-preference-write or file-write-data sandbox access
2024-09-27 13:50:39.655935+0300 WebDriverAgentRunner-Runner[487:8354] [User Defaults] Couldn't write values for keys (
    KeyboardShowPredictionBar
) in CFPrefsPlistSource<0x300331a70> (Domain: com.apple.keyboard.preferences, User: kCFPreferencesCurrentUser, ByHost: No, Container: (null), Contents Need Refresh: No): setting preferences outside an application's container requires user-preference-write or file-write-data sandbox access
2024-09-27 13:50:39.656170+0300 WebDriverAgentRunner-Runner[487:8354] [User Defaults] Couldn't write values for keys (
    DidShowGestureKeyboardIntroduction
) in CFPrefsPlistSource<0x300331a70> (Domain: com.apple.keyboard.preferences, User: kCFPreferencesCurrentUser, ByHost: No, Container: (null), Contents Need Refresh: No): setting preferences outside an application's container requires user-preference-write or file-write-data sandbox access
[27/09/2024 10:50:39] [INFO] Wda status is OK!
13:50:39.662 WebDriverAgentRunner-Runner[487:8354] <XCTContext: 0x301222cb0> finished activity <XCActivityRecord: 0x301222d00> 2024-09-27 10:50:39 +0000: Suite Set Up (0.0364649s)
Test Case '-[UITestingUITests testRunner]' started.
13:50:39.663 WebDriverAgentRunner-Runner[487:8354] -[XCTRunnerIDESession(_XCTestObservationInternal) testCaseWillStart:] UITestingUITests testRunner
13:50:39.663 WebDriverAgentRunner-Runner[487:8354] <XCTContext: 0x30122fd40> started activity <XCActivityRecord: 0x30122fca0> 2024-09-27 10:50:39 +0000: Start Test at 2024-09-27 13:50:39.663
13:50:39.663 WebDriverAgentRunner-Runner[487:8354] Creating future for 'getting screen identifiers' with timeout 30.00
XCTestOutputBarrier    t =     0.00s Start Test at 2024-09-27 13:50:39.663
13:50:39.664 WebDriverAgentRunner-Runner[487:8354] Wait <XCTWaiterWait: 0x302427800> completed after 0.000s
13:50:39.664 WebDriverAgentRunner-Runner[487:8354] cleaning up getting screen identifiers
13:50:39.664 WebDriverAgentRunner-Runner[487:8354] Failed to start diagnostic screen recording - configuration prefers screenshots.
13:50:39.664 WebDriverAgentRunner-Runner[487:8354] Creating future for 'getting screen identifiers' with timeout 30.00
13:50:39.664 WebDriverAgentRunner-Runner[487:8354] Wait <XCTWaiterWait: 0x302427a00> completed after 0.000s
13:50:39.664 WebDriverAgentRunner-Runner[487:8354] cleaning up getting screen identifiers
13:50:39.665 WebDriverAgentRunner-Runner[487:8354] <XCTContext: 0x30122fd40> finished activity <XCActivityRecord: 0x30122fca0> 2024-09-27 10:50:39 +0000: Start Test at 2024-09-27 13:50:39.663 (0.00165701s)
13:50:39.665 WebDriverAgentRunner-Runner[487:8354] <XCTContext: 0x30122fd40> started activity <XCActivityRecord: 0x301221900> 2024-09-27 10:50:39 +0000: Set Up
13:50:39.665 WebDriverAgentRunner-Runner[487:8354] Wait <XCTWaiterWait: 0x302427a00> completed after 0.000s
13:50:39.665 WebDriverAgentRunner-Runner[487:8354] cleaning up failable invocation block completion
13:50:39.665 WebDriverAgentRunner-Runner[487:8354] <XCTContext: 0x30122fd40> finished activity <XCActivityRecord: 0x301221900> 2024-09-27 10:50:39 +0000: Set Up (0.000555038s)
13:50:39.665 WebDriverAgentRunner-Runner[487:8354] _assertObjectsInvalidAfterScope
    t =     0.00s Set Up
2024-09-27 13:50:39.665561+0300 WebDriverAgentRunner-Runner[487:8354] Built at Aug 20 2024 17:48:21
2024-09-27 13:50:39.682331+0300 WebDriverAgentRunner-Runner[487:8354] ServerURLHere->http://192.168.1.30:8100<-ServerURLHere
13:50:39.682 WebDriverAgentRunner-Runner[487:8354] Creating future for 'getting screen identifiers' with timeout 30.00
13:50:39.683 WebDriverAgentRunner-Runner[487:8354] Wait <XCTWaiterWait: 0x3024ac800> completed after 0.000s
13:50:39.683 WebDriverAgentRunner-Runner[487:8354] cleaning up getting screen identifiers
2024-09-27 13:50:39.732637+0300 WebDriverAgentRunner-Runner[487:8354] Using singleton test manager
13:50:39.793 WebDriverAgentRunner-Runner[487:8370] -[XCTRunnerDaemonSession(XCTMessagingRole_UIApplicationStateUpdating) _XCT_applicationDidUpdateState:] com.facebook.WebDriverAgentRunner.xctrunner@/private/var/containers/Bundle/Application/FD6C5F55-3AA2-4F84-A1B8-AA92025950FF/WebDriverAgentRunner-Runner.app pid: 487 state: running background
13:50:39.793 WebDriverAgentRunner-Runner[487:8354] [AM:0] Processing state snapshot com.facebook.WebDriverAgentRunner.xctrunner@/private/var/containers/Bundle/Application/FD6C5F55-3AA2-4F84-A1B8-AA92025950FF/WebDriverAgentRunner-Runner.app pid: 487 state: running background
2024/09/27 10:50:42 INFO device already handled serial=00008101000579591A51003A
2024/09/27 10:50:42 INFO closing what="device vid=05ac,pid=12a8,bus=11,addr=31"
13:50:42.690 WebDriverAgentRunner-Runner[487:8370] Handling kAXPidStatusChangedNotification
13:50:42.690 WebDriverAgentRunner-Runner[487:8370] Got kAXPidStatusChangedNotification for pid 486: active
13:50:43.188 WebDriverAgentRunner-Runner[487:8354] No application process found matching pid 486, caching
13:50:43.510 WebDriverAgentRunner-Runner[487:8369] Handling kAXPidStatusChangedNotification
13:50:43.511 WebDriverAgentRunner-Runner[487:8369] Got kAXPidStatusChangedNotification for pid 484: active
13:50:43.688 WebDriverAgentRunner-Runner[487:8354] No application process found matching pid 484, caching
2024/09/27 10:50:47 INFO device already handled serial=00008101000579591A51003A
2024/09/27 10:50:47 INFO closing what="device vid=05ac,pid=12a8,bus=11,addr=31"
2024/09/27 10:50:52 INFO device already handled serial=00008101000579591A51003A
2024/09/27 10:50:52 INFO closing what="device vid=05ac,pid=12a8,bus=11,addr=31"
2024-09-27 13:50:56.645306+0300 WebDriverAgentRunner-Runner[487:8371] Got screenshots broadcast client connection at 127.0.0.1:49385
2024/09/27 10:50:57 INFO device already handled serial=00008101000579591A51003A
2024/09/27 10:50:57 INFO closing what="device vid=05ac,pid=12a8,bus=11,addr=31"
2024/09/27 10:51:02 INFO device already handled serial=00008101000579591A51003A
2024/09/27 10:51:02 INFO closing what="device vid=05ac,pid=12a8,bus=11,addr=31"
2024/09/27 10:51:07 INFO device already handled serial=00008101000579591A51003A
2024/09/27 10:51:07 INFO closing what="device vid=05ac,pid=12a8,bus=11,addr=31"
2024/09/27 10:51:12 INFO device already handled serial=00008101000579591A51003A
2024/09/27 10:51:12 INFO closing what="device vid=05ac,pid=12a8,bus=11,addr=31"
2024-09-27 13:51:14.101121+0300 WebDriverAgentRunner-Runner[487:8354] [BackgroundTask] Background Task 2 ("Called by UIKitCore, from <redacted>"), was created over 30 seconds ago. In applications running in the background, this creates a risk of termination. Remember to call UIApplication.endBackgroundTask(_:) for your task in a timely manner to avoid this.
2024/09/27 10:51:17 INFO device already handled serial=00008101000579591A51003A
2024/09/27 10:51:17 INFO closing what="device vid=05ac,pid=12a8,bus=11,addr=31"
13:51:18.260 WebDriverAgentRunner-Runner[487:8371] Handling kAXPidStatusChangedNotification
13:51:18.260 WebDriverAgentRunner-Runner[487:8371] Got kAXPidStatusChangedNotification for pid 497: active
13:51:18.336 WebDriverAgentRunner-Runner[487:8371] Handling kAXPidStatusChangedNotification
13:51:18.336 WebDriverAgentRunner-Runner[487:8371] Got kAXPidStatusChangedNotification for pid 492: active
13:51:18.688 WebDriverAgentRunner-Runner[487:8354] No application process found matching pid 497, caching
13:51:18.688 WebDriverAgentRunner-Runner[487:8354] No application process found matching pid 492, caching
[27/09/2024 10:52:21] [INFO] 

[azarouski]

If docker containers were running when the server was shut down some of containers store previous usb device address and can't start automatically. Ideally we need to schedule zebrunner-farm restart after docker is fully loaded after system reboot.