Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

iOS devices are not available on STF after reboot linux #367

Open
dhreben opened this issue Sep 27, 2024 · 1 comment
Open

iOS devices are not available on STF after reboot linux #367

dhreben opened this issue Sep 27, 2024 · 1 comment
Labels
bug Something isn't working

Comments

@dhreben
Copy link
Contributor

dhreben commented Sep 27, 2024 •
edited
Loading

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]
@dhreben dhreben added the bug Something isn't working label Sep 27, 2024
@azarouski azarouski transferred this issue from zebrunner/mcloud-ios-connector Sep 27, 2024
@azarouski
Copy link
Contributor

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.

@azarouski azarouski linked a pull request Oct 4, 2024 that will close this issue
added support for device naming on MacOS with spaces #370
Merged
@azarouski azarouski removed a link to a pull request Oct 4, 2024
added support for device naming on MacOS with spaces #370
Merged
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
bug Something isn't working
Projects
Device Farm (MCloud)
Status: To do
Milestone
No milestone
Development

No branches or pull requests
2 participants
@dhreben @azarouski