2022-05-10 11:24:23.922 INFO main i.t.a.Program AGENT MANAGER - START
2022-05-10 11:24:24.697 INFO init-entry i.t.a.h TestProject Agent 3.5.0 (cd294d338) on Windows 10
2022-05-10 11:24:24.736 INFO init-entry i.t.a.h Running under user yiann (yiann) of type Administrator with Guest privileges
2022-05-10 11:24:24.736 INFO init-entry i.t.a.h Using WINDOWS-ROOT certificates store
2022-05-10 11:24:24.742 INFO init-entry i.t.a.m.C Starting launcher socket on port 54431
2022-05-10 11:24:24.941 INFO init-entry i.t.a.h Working folder: C:\Program Files\TestProject Agent\app
2022-05-10 11:24:24.941 INFO init-entry i.t.a.h Data folder: C:\Users\yiann\AppData\Roaming\TestProject\Agent
2022-05-10 11:24:25.086 INFO init-entry i.t.a.h Agent IP addresses: 192.168.1.30, 10.254.252.144, 169.254.105.248
2022-05-10 11:24:25.279 INFO init-entry i.t.a.m.q Deleting unused dependency file chromedriver.exe
2022-05-10 11:24:25.280 INFO init-entry i.t.a.m.q Deleted dependency successfully? true
2022-05-10 11:24:25.281 INFO init-entry i.t.a.m.q Deleting unused dependency file chromedriver.exe
2022-05-10 11:24:25.282 INFO init-entry i.t.a.m.q Deleted dependency successfully? true
2022-05-10 11:24:25.592 INFO init-entry i.t.a.i.f C:\Users\yiann\AppData\Local\Temp\b16c676e-9053-4176-9cbc-d7961816ada43914044520517847898\Payload\WebDriverAgentRunner-Runner.app\Info.plist - Bundle ID: com.apple.test.WebDriverAgentRunner-Runner, Version: 3.3.1
2022-05-10 11:24:25.699 INFO init-entry i.t.a.m.c.f Checking if extensions installation blacklist is configured...
2022-05-10 11:24:25.720 WARN init-entry i.t.a.f.o Registry query failed - ERROR: The system was unable to find the specified registry key or value.
2022-05-10 11:24:25.742 WARN init-entry i.t.a.f.o Registry query failed - ERROR: The system was unable to find the specified registry key or value.
2022-05-10 11:24:25.742 INFO init-entry i.t.a.m.c.f There is no evidence that all extensions are blacklisted
2022-05-10 11:24:25.996 INFO pool-7-thread-1 i.t.a.i.l iOS Wrapper 3.5.0 starting on port 54437...
2022-05-10 11:24:25.996 INFO pool-7-thread-1 i.t.a.i.l Building server for port 54437
2022-05-10 11:24:26.463 INFO pool-7-thread-1 i.t.a.i.l Starting server...
2022-05-10 11:24:26.935 INFO pool-7-thread-1 i.t.a.i.l Server started, listening on 54437
2022-05-10 11:24:26.936 INFO pool-7-thread-1 i.t.a.i.l Starting services...
2022-05-10 11:24:26.970 INFO pool-7-thread-1 i.t.a.i.l iOS SDK Wrapper - Ready!
2022-05-10 11:24:27.585 INFO pool-7-thread-1 i.t.a.i.l Services Status: [c=DONE]
2022-05-10 11:24:27.604 INFO pool-7-thread-1 i.t.a.i.l Canceling isReadyTask returned true
2022-05-10 11:24:27.628 INFO pool-7-thread-1 i.t.a.i.l Starting heartbeat service...
2022-05-10 11:24:27.643 INFO pool-7-thread-1 i.t.a.i.l Received request to start monitoring
2022-05-10 11:24:27.693 INFO grpc-default-ex i.t.a.m.f.l Trying to obtain device 00008030-001954993633402E properties...
2022-05-10 11:24:28.490 INFO init-entry i.t.a.a.a.c.a Didn't detect any running ADB processes.
2022-05-10 11:24:28.493 INFO init-entry i.t.a.a.a.c.a Setting ADB port to 5037
2022-05-10 11:24:28.506 INFO init-entry i.t.a.a.a.c.a Creating new ADB bridge...
2022-05-10 11:24:28.689 INFO grpc-default-ex i.t.a.m.f.l DeviceName: iPhone SE (2nd generation), ModelNumber: MHGP3, ProductVersion: 15.3.1
2022-05-10 11:24:33.825 INFO init-entry i.t.a.a.a.c.a Successfully connected to ADB Bridge running on port 5037.
2022-05-10 11:24:34.015 INFO init-entry i.t.a.m.f.a Discovered Android Device: xiaomi-redmi_note_8_pro-9pfeca65det4n79p
2022-05-10 11:24:34.087 INFO init-entry i.t.a.i.l Waiting a bit for IOS Wrapper to come up...
2022-05-10 11:24:35.108 INFO init-entry i.t.a.m.f.l iOS Device iPhone SE (2nd generation) (00008030-001954993633402E) connected | 750x1334 - 2.0f
2022-05-10 11:24:35.223 INFO init-entry i.t.a.m.c.a Requesting ADB version from C:\Program Files\TestProject Agent\app\android-sdk\platform-tools\adb.exe
2022-05-10 11:24:35.247 INFO init-entry i.t.a.m.c.a Detected Java [13.0.1]
2022-05-10 11:24:35.248 INFO init-entry i.t.a.m.c.a Detected DotNet [6.0.1]
2022-05-10 11:24:35.248 INFO init-entry i.t.a.m.c.a Detected ADB [1.0.41]
2022-05-10 11:24:35.250 INFO init-entry i.t.a.m.c.f Searching for installed browsers...
2022-05-10 11:24:35.251 INFO init-entry i.t.a.m.c.f Checking installed browsers by default paths...
2022-05-10 11:24:35.256 INFO init-entry i.t.a.m.c.f Detected Edge browser [101.0.1210.39]
2022-05-10 11:24:35.257 INFO init-entry i.t.a.m.c.f Detected Chrome browser [101.0.4951.54]
2022-05-10 11:24:35.258 INFO init-entry i.t.a.m.c.f Detected Firefox browser [100.0.0.8153]
2022-05-10 11:24:35.262 INFO init-entry i.t.a.m.c.f Detected InternetExplorer browser [11.0.22000.120]
2022-05-10 11:24:35.262 INFO init-entry i.t.a.m.c.f Finished detecting installed browsers.
2022-05-10 11:24:35.268 INFO init-entry i.t.a.m.c.a Detected Chrome driver [100.0.4896.127] x64
2022-05-10 11:24:35.268 INFO init-entry i.t.a.m.c.a Detected Chrome driver [100.0.4896.75] x64
2022-05-10 11:24:35.268 INFO init-entry i.t.a.m.c.a Detected Chrome driver [101.0.4951.41] x64
2022-05-10 11:24:35.268 INFO init-entry i.t.a.m.c.a Detected InternetExplorer driver [11.914.17763.0] x64
2022-05-10 11:24:35.282 ERROR init-entry i.t.a.f.d Failed to get amount of available CPU cores
java.lang.IllegalStateException: Unmapped relationship: 7
at com.sun.jna.platform.win32.WinNT$SYSTEM_LOGICAL_PROCESSOR_INFORMATION_EX.fromPointer(WinNT.java:3033)
at com.sun.jna.platform.win32.Kernel32Util.getLogicalProcessorInformationEx(Kernel32Util.java:715)
at oshi.driver.windows.LogicalProcessorInformation.getLogicalProcessorInformationEx(LogicalProcessorInformation.java:61)
at oshi.hardware.platform.windows.WindowsCentralProcessor.initProcessorCounts(WindowsCentralProcessor.java:155)
at oshi.hardware.common.AbstractCentralProcessor.<init>(AbstractCentralProcessor.java:74)
at oshi.hardware.platform.windows.WindowsCentralProcessor.<init>(WindowsCentralProcessor.java:66)
at oshi.hardware.platform.windows.WindowsHardwareAbstractionLayer.createProcessor(WindowsHardwareAbstractionLayer.java:60)
at oshi.util.Memoizer$1.get(Memoizer.java:87)
at oshi.hardware.common.AbstractHardwareAbstractionLayer.getProcessor(AbstractHardwareAbstractionLayer.java:68)
at io.testproject.agent.f.d.b(TestProjectAgent:76)
at io.testproject.agent.managers.c.a.a(TestProjectAgent:173)
at io.testproject.agent.h.f(TestProjectAgent:351)
at io.testproject.agent.fsm.local.a.w.a(TestProjectAgent:66)
at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515)
at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
at io.testproject.agent.c.d.run(TestProjectAgent:33)
at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
at java.base/java.lang.Thread.run(Thread.java:830)
2022-05-10 11:24:35.311 INFO init-entry i.t.a.m.b Starting gRPC Server on port localhost:54590
2022-05-10 11:24:35.339 INFO init-entry i.t.a.m.O Runtime information saved
2022-05-10 11:24:35.340 INFO init-entry i.t.a.h Agent initialization is complete.
2022-05-10 11:24:35.344 INFO idle-entry i.t.a.w.b Starting Web Server...
2022-05-10 11:24:35.344 INFO idle-entry i.t.a.w.b Starting internal web server...
2022-05-10 11:24:35.348 INFO disconnect-entr i.t.a.m.K Checking Agent's connectivity with the outside world...
2022-05-10 11:24:35.679 WARN usb4java Device i.t.a.d.m Failed to get Xiaomi Android device serial number!
2022-05-10 11:24:36.053 INFO disconnect-entr i.t.a.m.K Connection established successfully.
2022-05-10 11:24:36.054 INFO disconnect-entr i.t.a.f.c.a.k Direct connection (no proxy) is possible.
2022-05-10 11:24:36.054 INFO disconnect-entr i.t.a.f.c.a.k External connectivity is possible, proceeding to Authentication...
2022-05-10 11:24:36.055 INFO auth-entry i.t.a.f.c.a.a Trying to load Agent remote identity...
2022-05-10 11:24:36.056 INFO auth-entry i.t.a.f.c.a.a Agent is registered as [Yiannis agent] (vjw2P8EsA0OrxEPLtBkL2g), proceeding to authentication...
2022-05-10 11:24:36.058 INFO auth-entry i.t.a.f.c.a.a Authenticating with TestProject...
2022-05-10 11:24:36.059 INFO auth-entry i.t.a.s.c Requesting authenticating token...
2022-05-10 11:24:36.197 INFO idle-entry i.t.a.w.b Web server started on http://localhost:8585
2022-05-10 11:24:36.199 INFO start-appium i.t.a.m.f.k Starting Appium server...
2022-05-10 11:24:36.465 INFO auth-entry i.t.a.s.c Authenticated successfully
2022-05-10 11:24:36.478 INFO state-machine i.t.a.m.f.l Reporting 2 connected devices/simulators/emulators...
2022-05-10 11:24:36.963 INFO state-machine i.t.a.b.a.d PUT https://api.testproject.io/api/v1/agent/devices - 200 (HFGL9EmP): No Message
2022-05-10 11:24:36.969 INFO state-machine i.t.a.f.c.a.h Starting Signal...
2022-05-10 11:24:36.969 INFO auth-entry i.t.a.m.f.v Checking if 9pfeca65det4n79p has UIAutomator Server installed...
2022-05-10 11:24:37.044 INFO start-appium i.t.a.m.q Validates if iOS image version 15.3.1 is ready...
2022-05-10 11:24:37.044 INFO qtp1974489483-8 i.t.a.s.IdentityManager Requesting remote identity...
2022-05-10 11:24:37.203 INFO qtp1974489483-8 i.t.a.b.a.d GET https://api.testproject.io/api/v1/agent - 200 (m3V9hGa2): No Message
2022-05-10 11:24:37.243 INFO qtp1974489483-8 i.t.a.s.IdentityManager Agent identity has been saved
2022-05-10 11:24:37.246 INFO qtp1974489483-8 i.t.a.m.w.b Configuring concurrent workers amount: 1
2022-05-10 11:24:37.275 INFO auth-entry i.t.a.m.f.v Device 9pfeca65det4n79p has UiAutomator Server 2.0.0 installed
2022-05-10 11:24:37.275 INFO auth-entry i.t.a.m.f.v Getting new port for UIAutomator...
2022-05-10 11:24:37.276 INFO auth-entry i.t.a.a.a.c.a Attempting to create port forward on device 9pfeca65det4n79p from 54622 to 6790...
2022-05-10 11:24:37.282 INFO auth-entry i.t.a.a.a.c.a Device adb forward finished successfully!
2022-05-10 11:24:37.407 INFO start-appium i.t.a.b.a.d GET https://api.testproject.io/dependencies/ios-images/15.3?fileHash=ec03860d7fe0a0738748a375a67f2e85 - 204 (j7LgCiXH): No Message
2022-05-10 11:24:37.407 INFO start-appium i.t.a.b.b.a Agent already has the latest dependency
2022-05-10 11:24:37.409 INFO start-appium i.t.a.m.q iOS 15.3 image has been validated successfully
2022-05-10 11:24:37.594 INFO EventThread i.t.a.b.c.a Signal connected, awaiting authentication result...
2022-05-10 11:24:37.596 INFO EventThread i.t.a.b.c.a Sent empty Session ID
2022-05-10 11:24:38.680 INFO EventThread i.t.a.b.c.a Signal authentication finished
2022-05-10 11:24:38.681 INFO EventThread i.t.a.b.c.a Connection Session ID: 9f4g9LVQVX
2022-05-10 11:24:38.681 INFO EventThread i.t.a.b.c.a Signal authorized
2022-05-10 11:24:38.827 INFO idle-entry i.t.a.b.a.d POST https://api.testproject.io/api/v1/agent/certificate - 200 (jt9rCmJu): No Message
2022-05-10 11:24:38.868 INFO idle-entry i.t.a.w.b Added SSL connector
2022-05-10 11:24:38.868 INFO idle-entry i.t.a.f.c.a.d Account plan: Free
2022-05-10 11:24:38.869 INFO idle-entry i.t.a.h Refreshing & reporting capabilities...
2022-05-10 11:24:38.892 INFO idle-entry i.t.a.m.c.a Requesting ADB version from C:\Program Files\TestProject Agent\app\android-sdk\platform-tools\adb.exe
2022-05-10 11:24:38.917 INFO idle-entry i.t.a.m.c.a Detected Java [13.0.1]
2022-05-10 11:24:38.917 INFO idle-entry i.t.a.m.c.a Detected DotNet [6.0.1]
2022-05-10 11:24:38.917 INFO idle-entry i.t.a.m.c.a Detected ADB [1.0.41]
2022-05-10 11:24:38.919 INFO idle-entry i.t.a.m.c.a Detected Chrome driver [100.0.4896.127] x64
2022-05-10 11:24:38.920 INFO idle-entry i.t.a.m.c.a Detected Chrome driver [100.0.4896.75] x64
2022-05-10 11:24:38.920 INFO idle-entry i.t.a.m.c.a Detected Chrome driver [101.0.4951.41] x64
2022-05-10 11:24:38.920 INFO idle-entry i.t.a.m.c.a Detected InternetExplorer driver [11.914.17763.0] x64
2022-05-10 11:24:38.923 ERROR idle-entry i.t.a.f.d Failed to get amount of available CPU cores
java.lang.IllegalStateException: Unmapped relationship: 7
at com.sun.jna.platform.win32.WinNT$SYSTEM_LOGICAL_PROCESSOR_INFORMATION_EX.fromPointer(WinNT.java:3033)
at com.sun.jna.platform.win32.Kernel32Util.getLogicalProcessorInformationEx(Kernel32Util.java:715)
at oshi.driver.windows.LogicalProcessorInformation.getLogicalProcessorInformationEx(LogicalProcessorInformation.java:61)
at oshi.hardware.platform.windows.WindowsCentralProcessor.initProcessorCounts(WindowsCentralProcessor.java:155)
at oshi.hardware.common.AbstractCentralProcessor.<init>(AbstractCentralProcessor.java:74)
at oshi.hardware.platform.windows.WindowsCentralProcessor.<init>(WindowsCentralProcessor.java:66)
at oshi.hardware.platform.windows.WindowsHardwareAbstractionLayer.createProcessor(WindowsHardwareAbstractionLayer.java:60)
at oshi.util.Memoizer$1.get(Memoizer.java:87)
at oshi.hardware.common.AbstractHardwareAbstractionLayer.getProcessor(AbstractHardwareAbstractionLayer.java:68)
at io.testproject.agent.f.d.b(TestProjectAgent:76)
at io.testproject.agent.managers.c.a.a(TestProjectAgent:173)
at io.testproject.agent.h.g(TestProjectAgent:477)
at io.testproject.agent.fsm.cloud.a.e.a(TestProjectAgent:114)
at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515)
at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
at io.testproject.agent.c.d.run(TestProjectAgent:33)
at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
at java.base/java.lang.Thread.run(Thread.java:830)
2022-05-10 11:24:39.050 INFO jersey-client-a i.t.a.b.a.d POST https://api.testproject.io/api/v1/agent/capabilities - 200 (0m8U2ohJ): Capabilities were received
2022-05-10 11:24:39.051 INFO idle-entry i.t.a.m.f.l Reporting 2 connected devices/simulators/emulators...
2022-05-10 11:24:39.380 INFO idle-entry i.t.a.b.a.d PUT https://api.testproject.io/api/v1/agent/devices - 200 (utrJdX8E): No Message
2022-05-10 11:24:39.381 INFO idle-entry i.t.a.b.b.a Retrieving list of provisioned iOS devices...
2022-05-10 11:24:39.381 INFO pool-3-thread-7 i.t.a.m.q Validates if iOS image version 15.3.1 is ready...
2022-05-10 11:24:39.409 WARN auth-entry i.t.a.b.b.j Failed communicating with UIAutomator on 9pfeca65det4n79p via port 54622
2022-05-10 11:24:39.409 INFO auth-entry i.t.a.m.f.v Checking if 9pfeca65det4n79p has UIAutomator Server installed...
2022-05-10 11:24:39.516 INFO idle-entry i.t.a.b.a.d GET https://api.testproject.io/api/v1/devices/provisioned - 200 (Qndir3ZO): No Message
2022-05-10 11:24:39.519 INFO pool-3-thread-8 i.t.a.b.b.a Reporting state: Idle
2022-05-10 11:24:39.575 INFO pool-3-thread-7 i.t.a.b.a.d GET https://api.testproject.io/dependencies/ios-images/15.3?fileHash=ec03860d7fe0a0738748a375a67f2e85 - 204 (iiOb3yr6): No Message
2022-05-10 11:24:39.575 INFO pool-3-thread-7 i.t.a.b.b.a Agent already has the latest dependency
2022-05-10 11:24:39.575 INFO pool-3-thread-7 i.t.a.m.q iOS 15.3 image has been validated successfully
2022-05-10 11:24:39.600 INFO auth-entry i.t.a.m.f.v Device 9pfeca65det4n79p has UiAutomator Server 2.0.0 installed
2022-05-10 11:24:39.600 INFO auth-entry i.t.a.m.f.v Re-using previous UIAutomator port 54622
2022-05-10 11:24:39.600 INFO auth-entry i.t.a.a.a.c.a Attempting to create port forward on device 9pfeca65det4n79p from 54622 to 6790...
2022-05-10 11:24:39.607 INFO auth-entry i.t.a.a.a.c.a Device adb forward finished successfully!
2022-05-10 11:24:40.335 INFO idle-entry i.t.a.m.f.v UIAutomator Server exited on 9pfeca65det4n79p
2022-05-10 11:24:41.821 INFO idle-entry i.t.a.m.A Reported Agent state and got response - [Agent Version: 3.5.0]
2022-05-10 11:24:41.822 INFO idle-entry i.t.a.m.d.p Report uploading manager started.
2022-05-10 11:24:42.841 INFO qtp1974489483-6 i.t.a.w.b.h RecorderSocket 41dea00b-9a3b-4c00-83dc-3a85871e6198 #1 connected
2022-05-10 11:24:43.368 INFO qtp1974489483-7 i.t.a.w.b.f MirroringSocket bffee5eb-83d9-4c22-91e8-a34aab14d53c #2 connected
2022-05-10 11:24:43.404 INFO pool-3-thread-8 i.t.a.b.a.d GET https://api.testproject.io/api/v1/agent/tokens?type=Recording&token=3WwsOZnYFUYuVgTCBAU2QYQbUV1bb1CBIJZ8B7XXe1s1 - 200 (0He8ZyBO): No Message
2022-05-10 11:24:43.404 INFO idle-entry i.t.a.b.a.d GET https://api.testproject.io/api/v1/agent/tokens?type=DeviceInteraction&token=0LmBnmspdadsAcyGuvP8TnkNiwI_Zt1WMgBmPQGajHY1 - 200 (vFEwjXHA): No Message
2022-05-10 11:24:43.405 INFO pool-3-thread-8 i.t.a.b.b.a Token Recording is valid
2022-05-10 11:24:43.405 INFO idle-entry i.t.a.b.b.a Token DeviceInteraction is valid
2022-05-10 11:24:43.406 INFO qtp1974489483-1 i.t.a.w.r.e.DeviceInteractionEndpoints Received request to set device orientation to PORTRAIT
2022-05-10 11:24:43.420 INFO qtp1974489483-8 i.t.a.w.r.e.RecordingEndpoints Received request for recording session on 00008030-001954993633402E
2022-05-10 11:24:43.421 INFO state-machine i.t.a.f.l.a.F Recorder is running and awaiting instructions.
2022-05-10 11:24:43.421 INFO pool-3-thread-8 i.t.a.b.b.a Reporting state: Recording
2022-05-10 11:24:43.510 INFO qtp1974489483-1 i.t.a.w.r.e.DeviceInteractionEndpoints Received a request for device 00008030-001954993633402E screen mirroring
2022-05-10 11:24:44.094 INFO qtp1974489483-1 i.t.a.i.n XCUITest is not running on 00008030-001954993633402E
2022-05-10 11:24:44.148 INFO qtp1974489483-1 i.t.a.b.b.a Requesting driver: Appium_iOS (existing: 91bb67c7cfb5af1a401529cad72ce4d1bc0a643093edba28d4e234dc882434a4)
2022-05-10 11:24:44.256 INFO qtp1974489483-1 i.t.a.b.a.d GET https://api.testproject.io/api/v1/agent/drivers?type=Appium_iOS&version=91bb67c7cfb5af1a401529cad72ce4d1bc0a643093edba28d4e234dc882434a4 - 204 (7dhFDds2): Already up to date
2022-05-10 11:24:44.257 INFO qtp1974489483-1 i.t.a.b.b.a Agent already has the latest Appium_iOS (91bb67c7cfb5af1a401529cad72ce4d1bc0a643093edba28d4e234dc882434a4) driver
2022-05-10 11:24:44.257 INFO qtp1974489483-1 i.t.a.m.q Validates if iOS image version 15.3.1 is ready...
2022-05-10 11:24:44.496 INFO qtp1974489483-1 i.t.a.b.a.d GET https://api.testproject.io/dependencies/ios-images/15.3?fileHash=ec03860d7fe0a0738748a375a67f2e85 - 204 (AtSwecho): No Message
2022-05-10 11:24:44.496 INFO qtp1974489483-1 i.t.a.b.b.a Agent already has the latest dependency
2022-05-10 11:24:44.496 INFO qtp1974489483-1 i.t.a.m.q iOS 15.3 image has been validated successfully
2022-05-10 11:24:45.140 INFO auth-entry i.t.a.i.n Executing XCUITest (WDA) on 00008030-001954993633402E
2022-05-10 11:24:45.141 INFO auth-entry i.t.a.i.n Setting mjpeg port to 54683 and control port to 54682
2022-05-10 11:24:45.347 ERROR idle-entry i.t.a.w.b.f Failed to obtain recorder manager
2022-05-10 11:24:45.776 INFO idle-entry i.t.a.m.A Reported Agent state and got response - [Agent Version: 3.5.0]
2022-05-10 11:24:45.776 INFO idle-entry i.t.a.f.l.a.I Creating recording manager for test TUnNvs8vyk-Jm259NOBMQQ on iOS platform
2022-05-10 11:24:45.780 INFO idle-entry i.t.a.m.q Skipping drivers update - Agent is offline.
2022-05-10 11:24:45.781 INFO idle-entry i.t.a.m.q Skipping drivers update - Agent is offline.
2022-05-10 11:24:45.781 INFO idle-entry i.t.a.m.f.k Waiting for Appium to be ready
2022-05-10 11:24:46.616 INFO auth-entry i.t.a.i.n Found WDA installed of the same version 3.3.1 on the device, skipping uninstall
2022-05-10 11:24:46.616 INFO auth-entry i.t.a.i.n WDA is already installed on device.
2022-05-10 11:24:46.845 INFO idle-entry i.t.a.m.r.d Searching for app using bundleId: com.eight.ap-stage
2022-05-10 11:24:47.693 INFO auth-entry i.t.a.i.n Trying to start XCUITest on 00008030-001954993633402E...
2022-05-10 11:24:49.003 INFO idle-entry i.t.a.m.f.s No session was found for device 00008030-001954993633402E
2022-05-10 11:24:49.004 INFO pool-3-thread-8 i.t.a.m.f.s Creating new Appium_iOS session for 00008030-001954993633402E
2022-05-10 11:24:49.005 INFO pool-3-thread-8 i.t.a.m.f.s Attempt #0 to initialize Appium_iOS session on 00008030-001954993633402E
2022-05-10 11:24:52.287 INFO auth-entry i.t.a.i.n WDA started on 00008030-001954993633402E
2022-05-10 11:24:52.289 INFO auth-entry i.t.a.i.h Starting control proxy on 00008030-001954993633402E
2022-05-10 11:24:52.294 INFO auth-entry i.t.a.i.h Starting data proxy on 00008030-001954993633402E
2022-05-10 11:24:52.297 INFO auth-entry i.t.a.i.h Device 00008030-001954993633402E proxy started successfully
2022-05-10 11:24:52.298 INFO auth-entry i.t.a.i.n Probing WDA...
2022-05-10 11:24:52.302 INFO auth-entry i.t.a.i.n XCUITest is running on 00008030-001954993633402E
2022-05-10 11:24:52.323 INFO auth-entry i.t.a.i.n WDA is not answering.
2022-05-10 11:24:53.324 INFO auth-entry i.t.a.i.n Probing WDA...
2022-05-10 11:24:53.328 INFO auth-entry i.t.a.i.n XCUITest is running on 00008030-001954993633402E
2022-05-10 11:24:53.395 INFO auth-entry i.t.a.i.n WDA is not answering.
2022-05-10 11:24:54.396 INFO auth-entry i.t.a.i.n Probing WDA...
2022-05-10 11:24:54.403 INFO auth-entry i.t.a.i.n XCUITest is running on 00008030-001954993633402E
2022-05-10 11:24:54.451 INFO auth-entry i.t.a.i.n WDA is running - Session ID: F3CE42FA-481D-48BD-864A-919C14928AD6
2022-05-10 11:24:54.451 INFO auth-entry i.t.a.i.n XCUITest (WDA) successfully started on 00008030-001954993633402E (SessionID: F3CE42FA-481D-48BD-864A-919C14928AD6)
2022-05-10 11:24:54.452 INFO qtp1974489483-1 i.t.a.m.f.t Starting mirroring session for iOS device iPhone SE (2nd generation)
2022-05-10 11:24:54.453 INFO qtp1974489483-1 i.t.a.m.f.p Connecting to 00008030-001954993633402E frames socket via http://localhost:54683
2022-05-10 11:24:54.455 INFO pool-3-thread-8 i.t.a.i.n XCUITest is running on 00008030-001954993633402E
2022-05-10 11:24:54.460 INFO qtp1974489483-1 i.t.a.i.n XCUITest is running on 00008030-001954993633402E
2022-05-10 11:24:54.536 INFO qtp1974489483-1 i.t.a.m.f.l iOS Device iPhone SE (2nd generation) (00008030-001954993633402E) connected | 750x1334 - 2.0f
2022-05-10 11:24:54.537 WARN qtp1974489483-1 i.t.a.m.f.l Device 00008030-001954993633402E is already in the list, replacing...
2022-05-10 11:24:54.607 INFO pool-3-thread-8 i.t.a.i.n Existing WDA sessionID: F3CE42FA-481D-48BD-864A-919C14928AD6
2022-05-10 11:24:54.607 INFO pool-3-thread-8 i.t.a.m.f.s WDA is running and answering on 00008030-001954993633402E
2022-05-10 11:24:54.619 INFO idle-entry i.t.a.m.d.e Initializing driver Appium_iOS...
2022-05-10 11:24:54.629 INFO idle-entry i.t.a.i.n XCUITest is running on 00008030-001954993633402E
2022-05-10 11:24:54.956 INFO qtp1974489483-1 i.t.a.b.a.d PUT https://api.testproject.io/api/v1/agent/devices/MDAwMDgwMzAtMDAxOTU0OTkzNjMzNDAyRQ== - 200 (5psjOwUX): No Message
2022-05-10 11:24:54.956 INFO qtp1974489483-1 i.t.a.b.b.a Retrieving list of provisioned iOS devices...
2022-05-10 11:24:55.113 INFO qtp1974489483-1 i.t.a.b.a.d GET https://api.testproject.io/api/v1/devices/provisioned - 200 (Cgk6gm93): No Message
2022-05-10 11:24:55.115 INFO pool-3-thread-9 i.t.a.m.q Validates if iOS image version 15.3.1 is ready...
2022-05-10 11:24:55.283 INFO pool-3-thread-9 i.t.a.b.a.d GET https://api.testproject.io/dependencies/ios-images/15.3?fileHash=ec03860d7fe0a0738748a375a67f2e85 - 204 (WO0zUsK1): No Message
2022-05-10 11:24:55.283 INFO pool-3-thread-9 i.t.a.b.b.a Agent already has the latest dependency
2022-05-10 11:24:55.284 INFO pool-3-thread-9 i.t.a.m.q iOS 15.3 image has been validated successfully
2022-05-10 11:24:55.475 INFO qtp1974489483-6 i.t.a.w.b.g MobileFramesSocket 817f80ce-192a-4916-8698-8bf72454cbbe #3 connected
2022-05-10 11:24:55.539 INFO qtp1974489483-6 i.t.a.w.b.g MirroringSocket bffee5eb-83d9-4c22-91e8-a34aab14d53c #3 disconnected: [1005] null
2022-05-10 11:24:57.178 INFO idle-entry i.t.a.m.d.e Appium_iOS Driver initialized.
2022-05-10 11:24:57.185 INFO pool-3-thread-8 i.t.a.i.n XCUITest is running on 00008030-001954993633402E
2022-05-10 11:24:57.189 INFO pool-3-thread-8 i.t.a.d.e mobile 00008030-001954993633402E session data: session Id :7c3bec9b-2fee-4d3f-9c79-0aead7603830,browser-name: ,bundle-Id:com.eight.ap-stage
2022-05-10 11:24:57.572 INFO idle-entry i.t.a.b.a.d GET https://api.testproject.io/api/v1/element-types?_embed=xPath - 200 (5KAOyEqf): No Message
2022-05-10 11:24:57.598 INFO idle-entry i.t.a.m.r.d Recording session started successfully.
2022-05-10 11:24:57.598 INFO pool-3-thread-8 i.t.a.b.b.a Reporting state: Recording
2022-05-10 11:24:57.887 INFO idle-entry i.t.a.m.A Reported Agent state and got response - [Agent Version: 3.5.0]
2022-05-10 11:25:04.302 ERROR rec-step-exec i.t.a.i.n Failed to retrieve cached source 404
2022-05-10 11:25:48.236 INFO qtp1974489483-8 i.t.a.w.b.g RecorderSocket 41dea00b-9a3b-4c00-83dc-3a85871e6198 #2 disconnected: [1001] null
2022-05-10 11:25:48.236 INFO qtp1974489483-1 i.t.a.w.b.g MobileFramesSocket 817f80ce-192a-4916-8698-8bf72454cbbe #1 disconnected: [1001] null
2022-05-10 11:25:48.236 INFO qtp1974489483-1 i.t.a.w.b.g Last device mirroring client disconnected
2022-05-10 11:25:48.505 INFO jersey-client-a i.t.a.b.a.d DELETE https://api.testproject.io/api/v1/agent/devices/MDAwMDgwMzAtMDAxOTU0OTkzNjMzNDAyRQ/interactions - 200 (qwYKq0bt): Device interaction session unlocked
2022-05-10 11:25:48.506 INFO qtp1974489483-1 i.t.a.m.f.t Stopping Screen Mirroring...
2022-05-10 11:25:48.513 INFO qtp1974489483-1 i.t.a.i.n XCUITest is running on 00008030-001954993633402E
2022-05-10 11:25:48.514 INFO qtp1974489483-1 i.t.a.m.f.p iOS device-mirror stopped
2022-05-10 11:26:05.242 ERROR state-machine i.t.a.i.n Failed to process 'Close App' request: java.net.SocketTimeoutException: Read timed out
2022-05-10 11:26:05.242 INFO state-machine i.t.a.i.n Stopping WDA on 00008030-001954993633402E
2022-05-10 11:26:20.244 ERROR state-machine i.t.a.i.n Failed to stop WDA on java.net.SocketTimeoutException: Read timed out
2022-05-10 11:26:20.244 INFO state-machine i.t.a.i.n Stopping iOS components on 00008030-001954993633402E...
2022-05-10 11:26:21.309 INFO auth-entry i.t.a.i.n All iOS components on 00008030-001954993633402E were stopped
2022-05-10 11:26:21.310 ERROR state-machine i.t.a.i.n Failed to close app
2022-05-10 11:26:21.310 INFO state-machine i.t.a.f.l.a.I Ending recording mode...
2022-05-10 11:26:21.317 INFO rec-step-exec i.t.a.b.b.a Reporting state: Idle
2022-05-10 11:26:22.640 INFO ForkJoinPool.co i.t.a.f.i Found 0 processes with requested filters [Name equals "chromedriver.exe" ]
2022-05-10 11:26:22.641 INFO ForkJoinPool.co i.t.a.f.i Done killing processes.
2022-05-10 11:26:24.011 INFO idle-entry i.t.a.m.A Reported Agent state and got response - [Agent Version: 3.5.0]
2022-05-10 11:28:42.952 INFO EventThread i.t.a.b.c.a User 773239 has connected
2022-05-10 11:28:43.315 INFO qtp1974489483-8 i.t.a.w.b.c EventsSocket 81d0dc79-931d-4569-9c5e-0e193e4bd8b9 #1 connected
2022-05-10 11:28:50.727 INFO qtp1974489483-1 i.t.a.w.b.h RecorderSocket aae7d998-0548-43e2-9c1c-023f369bbdc3 #2 connected
2022-05-10 11:28:51.083 INFO pool-3-thread-1 i.t.a.b.a.d GET https://api.testproject.io/api/v1/agent/tokens?type=Recording&token=y-elvK39oIKYgrWGdAGpKUpzOHTynMRqpJdRrY-8RYI1 - 200 (aLqtaUCk): No Message
2022-05-10 11:28:51.084 INFO pool-3-thread-1 i.t.a.b.b.a Token Recording is valid
2022-05-10 11:28:51.084 INFO qtp1974489483-8 i.t.a.w.r.e.RecordingEndpoints Received request for recording session on 00008030-001954993633402E
2022-05-10 11:28:51.085 INFO pool-3-thread-1 i.t.a.b.a.d GET https://api.testproject.io/api/v1/agent/tokens?type=DeviceInteraction&token=UtAEioxRDzj_9VyVEQQaU3MF-SwWVHC3qLH4tYfXhHI1 - 200 (YTbZbEYu): No Message
2022-05-10 11:28:51.085 INFO state-machine i.t.a.f.l.a.F Recorder is running and awaiting instructions.
2022-05-10 11:28:51.085 INFO pool-3-thread-1 i.t.a.b.b.a Token DeviceInteraction is valid
2022-05-10 11:28:51.086 INFO qtp1974489483-1 i.t.a.w.r.e.DeviceInteractionEndpoints Received request to set device orientation to PORTRAIT
2022-05-10 11:28:51.086 INFO pool-3-thread-1 i.t.a.b.b.a Reporting state: Recording
2022-05-10 11:28:51.115 INFO qtp1974489483-7 i.t.a.w.r.e.DeviceInteractionEndpoints Received a request for device 00008030-001954993633402E screen mirroring
2022-05-10 11:28:51.223 INFO qtp1974489483-6 i.t.a.w.b.f MirroringSocket aed3e107-0a83-479f-885c-4aaedbd3be5b #3 connected
2022-05-10 11:28:51.691 INFO qtp1974489483-7 i.t.a.i.n XCUITest is not running on 00008030-001954993633402E
2022-05-10 11:28:51.738 INFO qtp1974489483-7 i.t.a.b.b.a Requesting driver: Appium_iOS (existing: 91bb67c7cfb5af1a401529cad72ce4d1bc0a643093edba28d4e234dc882434a4)
2022-05-10 11:28:51.922 INFO qtp1974489483-7 i.t.a.b.a.d GET https://api.testproject.io/api/v1/agent/drivers?type=Appium_iOS&version=91bb67c7cfb5af1a401529cad72ce4d1bc0a643093edba28d4e234dc882434a4 - 204 (xF6K539N): Already up to date
2022-05-10 11:28:51.922 INFO qtp1974489483-7 i.t.a.b.b.a Agent already has the latest Appium_iOS (91bb67c7cfb5af1a401529cad72ce4d1bc0a643093edba28d4e234dc882434a4) driver
2022-05-10 11:28:51.923 INFO qtp1974489483-7 i.t.a.m.q Validates if iOS image version 15.3.1 is ready...
2022-05-10 11:28:52.168 INFO qtp1974489483-7 i.t.a.b.a.d GET https://api.testproject.io/dependencies/ios-images/15.3?fileHash=ec03860d7fe0a0738748a375a67f2e85 - 204 (9G0UZkOi): No Message
2022-05-10 11:28:52.169 INFO qtp1974489483-7 i.t.a.b.b.a Agent already has the latest dependency
2022-05-10 11:28:52.169 INFO qtp1974489483-7 i.t.a.m.q iOS 15.3 image has been validated successfully
2022-05-10 11:28:52.846 INFO pool-3-thread-1 i.t.a.i.n Executing XCUITest (WDA) on 00008030-001954993633402E
2022-05-10 11:28:52.847 INFO pool-3-thread-1 i.t.a.i.n Setting mjpeg port to 54921 and control port to 54920
2022-05-10 11:28:53.231 ERROR pool-3-thread-1 i.t.a.w.b.f Failed to obtain recorder manager
2022-05-10 11:28:53.384 INFO pool-3-thread-1 i.t.a.m.A Reported Agent state and got response - [Agent Version: 3.5.0]
2022-05-10 11:28:53.385 INFO pool-3-thread-1 i.t.a.f.l.a.I Creating recording manager for test TUnNvs8vyk-Jm259NOBMQQ on iOS platform
2022-05-10 11:28:53.385 INFO pool-3-thread-1 i.t.a.m.q Skipping drivers update - Agent is offline.
2022-05-10 11:28:53.386 INFO pool-3-thread-1 i.t.a.m.q Skipping drivers update - Agent is offline.
2022-05-10 11:28:53.386 INFO pool-3-thread-1 i.t.a.m.f.k Waiting for Appium to be ready
2022-05-10 11:28:54.471 INFO pool-3-thread-1 i.t.a.i.n Found WDA installed of the same version 3.3.1 on the device, skipping uninstall
2022-05-10 11:28:54.472 INFO pool-3-thread-1 i.t.a.i.n WDA is already installed on device.
2022-05-10 11:28:54.714 INFO pool-3-thread-1 i.t.a.m.r.d Searching for app using bundleId: com.eight.ap-stage
2022-05-10 11:28:55.621 INFO pool-3-thread-1 i.t.a.i.n Trying to start XCUITest on 00008030-001954993633402E...
2022-05-10 11:29:00.226 INFO pool-3-thread-1 i.t.a.i.n WDA started on 00008030-001954993633402E
2022-05-10 11:29:00.227 INFO pool-3-thread-1 i.t.a.i.h Starting control proxy on 00008030-001954993633402E
2022-05-10 11:29:00.230 INFO pool-3-thread-1 i.t.a.i.h Starting data proxy on 00008030-001954993633402E
2022-05-10 11:29:00.234 INFO pool-3-thread-1 i.t.a.i.h Device 00008030-001954993633402E proxy started successfully
2022-05-10 11:29:00.234 INFO pool-3-thread-1 i.t.a.i.n Probing WDA...
2022-05-10 11:29:00.238 INFO pool-3-thread-1 i.t.a.i.n XCUITest is running on 00008030-001954993633402E
2022-05-10 11:29:00.296 INFO pool-3-thread-1 i.t.a.i.n WDA is not answering.
2022-05-10 11:29:01.296 INFO pool-3-thread-1 i.t.a.i.n Probing WDA...
2022-05-10 11:29:01.300 INFO pool-3-thread-1 i.t.a.i.n XCUITest is running on 00008030-001954993633402E
2022-05-10 11:29:01.323 INFO pool-3-thread-1 i.t.a.i.n WDA is not answering.
2022-05-10 11:29:02.323 INFO pool-3-thread-1 i.t.a.i.n Probing WDA...
2022-05-10 11:29:02.330 INFO pool-3-thread-1 i.t.a.i.n XCUITest is running on 00008030-001954993633402E
2022-05-10 11:29:02.376 INFO pool-3-thread-1 i.t.a.i.n WDA is running - Session ID: E45DC546-9520-4615-AEBA-A650675103E5
2022-05-10 11:29:02.376 INFO pool-3-thread-1 i.t.a.i.n XCUITest (WDA) successfully started on 00008030-001954993633402E (SessionID: E45DC546-9520-4615-AEBA-A650675103E5)
2022-05-10 11:29:02.376 INFO qtp1974489483-7 i.t.a.m.f.t Starting mirroring session for iOS device iPhone SE (2nd generation)
2022-05-10 11:29:02.377 INFO qtp1974489483-7 i.t.a.m.f.p Connecting to 00008030-001954993633402E frames socket via http://localhost:54921
2022-05-10 11:29:02.379 INFO pool-3-thread-1 i.t.a.i.n XCUITest is running on 00008030-001954993633402E
2022-05-10 11:29:02.383 INFO qtp1974489483-7 i.t.a.i.n XCUITest is running on 00008030-001954993633402E
2022-05-10 11:29:02.410 ERROR pool-3-thread-1 i.t.a.m.f.s Failed to poke existing driver
2022-05-10 11:29:02.410 INFO pool-3-thread-1 i.t.a.m.f.s Removed mobile session for: 00008030-001954993633402E, closing driver...
2022-05-10 11:29:02.410 INFO pool-3-thread-1 i.t.a.d.e mobile 00008030-001954993633402E session data: session Id :7c3bec9b-2fee-4d3f-9c79-0aead7603830,browser-name: ,bundle-Id:com.eight.ap-stage
2022-05-10 11:29:02.410 INFO pool-3-thread-1 i.t.a.m.f.s Removed session data for mobile: 00008030-001954993633402E
2022-05-10 11:29:02.410 INFO pool-3-thread-1 i.t.a.m.f.s Closing driver...
2022-05-10 11:29:02.411 INFO pool-3-thread-1 i.t.a.m.f.s Closing driver session: 7c3bec9b-2fee-4d3f-9c79-0aead7603830
2022-05-10 11:29:02.413 INFO qtp1974489483-7 i.t.a.m.f.l iOS Device iPhone SE (2nd generation) (00008030-001954993633402E) connected | 750x1334 - 2.0f
2022-05-10 11:29:02.414 WARN qtp1974489483-7 i.t.a.m.f.l Device 00008030-001954993633402E is already in the list, replacing...
2022-05-10 11:29:02.419 INFO pool-3-thread-1 i.t.a.m.f.s Closed driver session: 7c3bec9b-2fee-4d3f-9c79-0aead7603830
2022-05-10 11:29:02.419 INFO pool-3-thread-1 i.t.a.m.f.s Creating new Appium_iOS session for 00008030-001954993633402E
2022-05-10 11:29:02.419 INFO pool-3-thread-1 i.t.a.m.f.s Attempt #0 to initialize Appium_iOS session on 00008030-001954993633402E
2022-05-10 11:29:02.424 INFO pool-3-thread-1 i.t.a.i.n XCUITest is running on 00008030-001954993633402E
2022-05-10 11:29:02.587 INFO pool-3-thread-1 i.t.a.i.n Existing WDA sessionID: E45DC546-9520-4615-AEBA-A650675103E5
2022-05-10 11:29:02.587 INFO pool-3-thread-1 i.t.a.m.f.s WDA is running and answering on 00008030-001954993633402E
2022-05-10 11:29:02.587 INFO pool-3-thread-1 i.t.a.m.d.e Initializing driver Appium_iOS...
2022-05-10 11:29:02.594 INFO pool-3-thread-1 i.t.a.i.n XCUITest is running on 00008030-001954993633402E
2022-05-10 11:29:02.601 INFO qtp1974489483-7 i.t.a.b.a.d PUT https://api.testproject.io/api/v1/agent/devices/MDAwMDgwMzAtMDAxOTU0OTkzNjMzNDAyRQ
- 200 (Hl3zqGqR): No Message
2022-05-10 11:29:02.602 INFO qtp1974489483-7 i.t.a.b.b.a Retrieving list of provisioned iOS devices...
2022-05-10 11:29:02.706 INFO qtp1974489483-7 i.t.a.b.a.d GET https://api.testproject.io/api/v1/devices/provisioned - 200 (t9VtAky4): No Message
2022-05-10 11:29:02.709 INFO pool-3-thread-1 i.t.a.m.q Validates if iOS image version 15.3.1 is ready...
2022-05-10 11:29:02.881 INFO pool-3-thread-1 i.t.a.b.a.d GET https://api.testproject.io/dependencies/ios-images/15.3?fileHash=ec03860d7fe0a0738748a375a67f2e85 - 204 (WLEZKoFd): No Message
2022-05-10 11:29:02.881 INFO pool-3-thread-1 i.t.a.b.b.a Agent already has the latest dependency
2022-05-10 11:29:02.881 INFO pool-3-thread-1 i.t.a.m.q iOS 15.3 image has been validated successfully
2022-05-10 11:29:03.066 INFO qtp1974489483-6 i.t.a.w.b.g MobileFramesSocket 290d8260-5a6a-469b-aa49-c964437d3902 #4 connected
2022-05-10 11:29:03.121 INFO qtp1974489483-7 i.t.a.w.b.g MirroringSocket aed3e107-0a83-479f-885c-4aaedbd3be5b #4 disconnected: [1005] null
2022-05-10 11:29:03.931 INFO pool-3-thread-1 i.t.a.m.d.e Appium_iOS Driver initialized.
2022-05-10 11:29:03.931 WARN qtp1974489483-1 i.t.a.i.n Failed retrieving orientation due to session change. Refreshing session id and retrying
2022-05-10 11:29:03.936 INFO pool-3-thread-1 i.t.a.i.n XCUITest is running on 00008030-001954993633402E
2022-05-10 11:29:03.944 INFO pool-3-thread-1 i.t.a.d.e mobile 00008030-001954993633402E session data: session Id :9ccb3750-e5b7-4fa5-b3b2-783c21450bbc,browser-name: ,bundle-Id:com.eight.ap-stage
2022-05-10 11:29:04.191 INFO pool-3-thread-1 i.t.a.b.a.d GET https://api.testproject.io/api/v1/element-types?_embed=xPath - 200 (y1jymHPg): No Message
2022-05-10 11:29:04.216 INFO pool-3-thread-1 i.t.a.m.r.d Recording session started successfully.
2022-05-10 11:29:04.216 INFO pool-3-thread-1 i.t.a.b.b.a Reporting state: Recording
2022-05-10 11:29:04.493 INFO pool-3-thread-1 i.t.a.m.A Reported Agent state and got response - [Agent Version: 3.5.0]
2022-05-10 11:29:08.276 ERROR rec-step-exec i.t.a.i.n Failed to retrieve cached source 404
2022-05-10 11:29:09.327 INFO qtp1974489483-8 i.t.a.w.r.e.RecordingEndpoints Received request to update test settings
2022-05-10 11:29:09.369 INFO qtp1974489483-1 i.t.a.w.r.e.RecordingEndpoints Received request to update recording session parameters
2022-05-10 11:29:09.373 INFO qtp1974489483-8 i.t.a.w.r.e.RecordingEndpoints Received request to update recording session parameters
2022-05-10 11:29:10.917 INFO pool-3-thread-1 i.t.a.m.d.r Started processing Step yI5O9OF9VU-x7tLSpJAgeA (1) in Test null
2022-05-10 11:29:10.919 INFO pool-3-thread-1 i.t.a.m.d.r Step Code Block Class Name: io.testproject.agent.codeblocks.driver.mobile.ResetApp
2022-05-10 11:29:10.927 INFO pool-3-thread-1 i.t.a.m.d.r Executing ActionStep: 1.1. Reset App
2022-05-10 11:29:10.928 INFO pool-3-thread-1 i.t.a.m.d.r Step designed to sleep 500ms (Before)
2022-05-10 11:29:11.429 INFO pool-3-thread-1 i.t.a.m.d.r Step yI5O9OF9VU-x7tLSpJAgeA defines a timeout of 15000ms
2022-05-10 11:29:12.685 INFO pool-3-thread-1 i.t.a.i.n XCUITest is running on 00008030-001954993633402E
2022-05-10 11:29:12.691 INFO pool-3-thread-1 i.t.a.m.d.r Removing driver implicit timeout
2022-05-10 11:29:12.695 INFO pool-3-thread-1 i.t.a.m.d.r Step yI5O9OF9VU-x7tLSpJAgeA (1.1) in Test yI5O9OF9VU-x7tLSpJAgeA execution Finished.
2022-05-10 11:29:12.696 INFO pool-3-thread-1 i.t.a.m.d.r Step yI5O9OF9VU-x7tLSpJAgeA (1.1) in Test null - Passed!
2022-05-10 11:29:12.697 INFO pool-3-thread-1 i.t.a.m.d.r Evaluating Step yI5O9OF9VU-x7tLSpJAgeA (1.1) conditions...
2022-05-10 11:29:12.697 INFO pool-3-thread-1 i.t.a.m.d.r Step conditions validation completed.
2022-05-10 11:29:12.697 INFO pool-3-thread-1 i.t.a.m.d.r Conditions validation passed successfully for step yI5O9OF9VU-x7tLSpJAgeA
2022-05-10 11:29:12.698 INFO pool-3-thread-1 i.t.a.m.d.r Finished processing Step yI5O9OF9VU-x7tLSpJAgeA (1) in Test null
2022-05-10 11:29:12.698 INFO pool-3-thread-1 i.t.a.m.d.r Finished executing ActionStep: 1.1. Reset App -Passed (null)
2022-05-10 11:29:13.215 INFO rec-step-exec i.t.a.b.b.a Downloading test UxB3Xz0Mv0uhLa8vSC3PHg in project s9HM6bx3MkSZvy9JIiD7jQ from server
2022-05-10 11:29:13.918 INFO rec-step-exec i.t.a.b.a.d GET https://api.testproject.io/api/v1/projects/s9HM6bx3MkSZvy9JIiD7jQ/tests/UxB3Xz0Mv0uhLa8vSC3PHg?_embed=app&_embed=settings&_embed=parameters&_embed=steps&_embed=steps.action&_embed=steps.action.fields&_embed=steps.codeBlock&_embed=steps.parameterMaps&_embed=steps.conditions&_embed=steps.recoveryTest&_embed=steps.context&_embed=steps.element&_embed=steps.element.type - 200 (SFKX2DFz): No Message
2022-05-10 11:29:13.919 INFO rec-step-exec i.t.a.b.b.a Downloaded test UxB3Xz0Mv0uhLa8vSC3PHg in project s9HM6bx3MkSZvy9JIiD7jQ
2022-05-10 11:29:13.949 INFO pool-3-thread-1 i.t.a.m.d.r Started processing Step DGJRRWTDekep84aQTheyWg (2) in Test null
2022-05-10 11:29:13.950 INFO pool-3-thread-1 i.t.a.m.d.r Step Code Block Class Name: null
2022-05-10 11:29:13.950 INFO pool-3-thread-1 i.t.a.m.d.r Executing SubTestStep: 1.2. Run Test {{Login}}
2022-05-10 11:29:13.951 INFO pool-3-thread-1 i.t.a.m.d.r Step designed to sleep 500ms (Before)
2022-05-10 11:29:14.452 INFO pool-3-thread-1 i.t.a.m.d.r Step DGJRRWTDekep84aQTheyWg defines a timeout of 15000ms
2022-05-10 11:29:14.457 INFO pool-3-thread-1 i.t.a.m.d.r Step DGJRRWTDekep84aQTheyWg (1.2) calls Test UxB3Xz0Mv0uhLa8vSC3PHg
2022-05-10 11:29:14.457 INFO pool-3-thread-1 i.t.a.m.d.r Sub Test UxB3Xz0Mv0uhLa8vSC3PHg parameters map is: [{"name":"Email","value":"[email protected]","secret":false,"isJsonCompliant":false},{"name":"Password","value":"Eight8","secret":false,"isJsonCompliant":false},{"name":"FullName","value":"","secret":false,"isJsonCompliant":false}]
2022-05-10 11:29:14.458 INFO pool-3-thread-1 i.t.a.m.d.o Evaluated target test parameter [Email] to: [[email protected]]
2022-05-10 11:29:14.458 INFO pool-3-thread-1 i.t.a.m.d.o Evaluated target test parameter [Email] new value: [[email protected]]
2022-05-10 11:29:14.458 INFO pool-3-thread-1 i.t.a.m.d.o Evaluated target test parameter [Password] to: [Eight8]
2022-05-10 11:29:14.458 INFO pool-3-thread-1 i.t.a.m.d.o Evaluated target test parameter [Password] new value: [Eight8]
2022-05-10 11:29:14.458 INFO pool-3-thread-1 i.t.a.m.d.o Evaluated target test parameter [FullName] to: []
2022-05-10 11:29:14.458 INFO pool-3-thread-1 i.t.a.m.d.o Evaluated target test parameter [FullName] new value: []
2022-05-10 11:29:14.459 INFO pool-3-thread-1 i.t.a.m.d.x Starting test Login (UxB3Xz0Mv0uhLa8vSC3PHg) execution...
2022-05-10 11:29:14.459 INFO pool-3-thread-1 i.t.a.m.d.x Test [Login] Settings: {"aggressiveHealing":true,"driverInstances":0,"stepSleepTime":500,"stepTimeout":15000,"stepSleepTiming":"Before","stepFailureBehaviorType":"Abort","stepTakeScreenshotConditionType":"Failure"}
2022-05-10 11:29:14.459 INFO pool-3-thread-1 i.t.a.m.d.x Starting Test 'Login' Steps execution...
2022-05-10 11:29:14.460 INFO pool-3-thread-1 i.t.a.m.d.r Started processing Step 8L0rxsw9o0C59mthEDkYpw (1) in Test null
2022-05-10 11:29:14.461 INFO pool-3-thread-1 i.t.a.m.d.r Step Code Block Class Name: io.testproject.agent.codeblocks.driver.element.wait.WaitForElementVisibility
2022-05-10 11:29:14.463 INFO pool-3-thread-1 i.t.a.m.d.r Executing ActionStep: 1.2.1. Is {{Log in2 <XCUIElementTypeStaticText>}} visible?
2022-05-10 11:29:14.463 INFO pool-3-thread-1 i.t.a.m.d.r Step designed to sleep 500ms (Before)
2022-05-10 11:29:14.965 INFO pool-3-thread-1 i.t.a.m.d.r Step 8L0rxsw9o0C59mthEDkYpw inherits timeout from Test of 15000ms
2022-05-10 11:29:14.970 INFO pool-3-thread-1 i.t.a.m.d.r Using locator [{"byType":"IOSPREDICATE","byValue":"type \u003d\u003d \u0027XCUIElementTypeStaticText\u0027 AND label \u003d\u003d \u0027Log in\u0027 AND visible \u003d\u003d 1","index":0}] (1/1) for element [Log in2] in step [1.2.0/0]
2022-05-10 11:29:23.329 INFO rec-step-exec i.t.a.m.d.x Step DGJRRWTDekep84aQTheyWg execution has been interrupted
2022-05-10 11:29:23.341 INFO rec-steps-exec i.t.a.m.r.d Execution has been aborted, interrupting steps execution loop.
2022-05-10 11:29:30.155 INFO pool-3-thread-1 i.t.a.m.d.r Updating driver with test default timeout 15000ms
2022-05-10 11:29:30.159 INFO pool-3-thread-1 i.t.a.m.d.r Step 8L0rxsw9o0C59mthEDkYpw (1.2.1) in Test 8L0rxsw9o0C59mthEDkYpw execution Finished.
2022-05-10 11:29:30.160 INFO pool-3-thread-1 i.t.a.m.d.r Step 8L0rxsw9o0C59mthEDkYpw (1.2.1) in Test null - Failed! (Action failed using following locators: 1. IOSPREDICATE=type == 'XCUIElementTypeStaticText' AND label == 'Log in' AND visible == 1TimeoutException: Timeout occurred while trying to perform the action. Element was not found). Test is configured to Abort.
2022-05-10 11:29:30.160 INFO pool-3-thread-1 i.t.a.m.d.r Evaluating Step 8L0rxsw9o0C59mthEDkYpw (1.2.1) conditions...
2022-05-10 11:29:30.161 INFO pool-3-thread-1 i.t.a.m.d.r Step conditions validation completed.
2022-05-10 11:29:30.161 INFO pool-3-thread-1 i.t.a.m.d.r Conditions validation passed successfully for step 8L0rxsw9o0C59mthEDkYpw
2022-05-10 11:29:30.161 INFO pool-3-thread-1 i.t.a.m.d.r Finished processing Step 8L0rxsw9o0C59mthEDkYpw (1) in Test null
2022-05-10 11:29:30.161 INFO pool-3-thread-1 i.t.a.m.d.r Finished executing ActionStep: 1.2.1. Is {{Log in2 <XCUIElementTypeStaticText>}} visible? -
Failed (Action failed using following locators:

  1. IOSPREDICATE=type == 'XCUIElementTypeStaticText' AND label == 'Log in' AND visible == 1
    TimeoutException: Timeout occurred while trying to perform the action. Element was not found

)
2022-05-10 11:29:30.162 INFO pool-3-thread-1 i.t.a.m.d.r Started processing Step 489gASlP8EqC72Shz2w5XQ (2) in Test null
2022-05-10 11:29:30.163 INFO pool-3-thread-1 i.t.a.m.d.r Step Code Block Class Name: io.testproject.agent.codeblocks.driver.element.ClickElement
2022-05-10 11:29:30.164 INFO pool-3-thread-1 i.t.a.m.d.r Started processing Step cwI3o9R1-kezIq6HR_feVQ (3) in Test null
2022-05-10 11:29:30.165 INFO pool-3-thread-1 i.t.a.m.d.r Step Code Block Class Name: io.testproject.agent.codeblocks.driver.element.wait.WaitForElementToContainText
2022-05-10 11:29:30.167 INFO pool-3-thread-1 i.t.a.m.d.r Started processing Step QJCAu3SveEOFVeXxByRG2Q (4) in Test null
2022-05-10 11:29:30.167 INFO pool-3-thread-1 i.t.a.m.d.r Step Code Block Class Name: io.testproject.agent.codeblocks.driver.element.SendKeys
2022-05-10 11:29:30.168 INFO pool-3-thread-1 i.t.a.m.d.r Started processing Step wX6lY9W7uEivSAdy5wg6WQ (5) in Test null
2022-05-10 11:29:30.168 INFO pool-3-thread-1 i.t.a.m.d.r Step Code Block Class Name: io.testproject.agent.codeblocks.driver.element.ClickElement
2022-05-10 11:29:30.169 INFO pool-3-thread-1 i.t.a.m.d.r Started processing Step ZxR0N-PyN0qhL3DjeLudzQ (6) in Test null
2022-05-10 11:29:30.169 INFO pool-3-thread-1 i.t.a.m.d.r Step Code Block Class Name: io.testproject.agent.codeblocks.driver.element.wait.WaitForElementToContainText
2022-05-10 11:29:30.170 INFO pool-3-thread-1 i.t.a.m.d.r Started processing Step yho19sRW0EGhxgpvGoYmHw (7) in Test null
2022-05-10 11:29:30.170 INFO pool-3-thread-1 i.t.a.m.d.r Step Code Block Class Name: io.testproject.agent.codeblocks.driver.element.SendKeys
2022-05-10 11:29:30.170 INFO pool-3-thread-1 i.t.a.m.d.r Started processing Step Nug5EDXaf0abxgO-uapGew (8) in Test null
2022-05-10 11:29:30.171 INFO pool-3-thread-1 i.t.a.m.d.r Step Code Block Class Name: io.testproject.agent.codeblocks.driver.element.ClickElement
2022-05-10 11:29:30.172 INFO pool-3-thread-1 i.t.a.m.d.x Finished executing Test 'Login': Failed
2022-05-10 11:29:30.173 INFO pool-3-thread-1 i.t.a.m.d.r Removing driver implicit timeout
2022-05-10 11:29:30.178 INFO pool-3-thread-1 i.t.a.m.d.r Step DGJRRWTDekep84aQTheyWg (1.2) in Test DGJRRWTDekep84aQTheyWg execution Finished.
2022-05-10 11:29:30.178 INFO pool-3-thread-1 i.t.a.m.d.r Step DGJRRWTDekep84aQTheyWg (1.2) in Test null - Failed! (Action failed using following locators: 1. IOSPREDICATE=type == 'XCUIElementTypeStaticText' AND label == 'Log in' AND visible == 1TimeoutException: Timeout occurred while trying to perform the action. Element was not found). Test is configured to Abort.
2022-05-10 11:29:30.178 INFO pool-3-thread-1 i.t.a.m.d.r Evaluating Step DGJRRWTDekep84aQTheyWg (1.2) conditions...
2022-05-10 11:29:30.178 INFO pool-3-thread-1 i.t.a.m.d.r Step conditions validation completed.
2022-05-10 11:29:30.178 INFO pool-3-thread-1 i.t.a.m.d.r Conditions validation passed successfully for step DGJRRWTDekep84aQTheyWg
2022-05-10 11:29:30.178 INFO pool-3-thread-1 i.t.a.m.d.r Finished processing Step DGJRRWTDekep84aQTheyWg (2) in Test null
2022-05-10 11:29:30.178 INFO pool-3-thread-1 i.t.a.m.d.r Finished executing SubTestStep: 1.2. Run Test {{Login}} --> Failed (Action failed using following locators:

  1. IOSPREDICATE=type == 'XCUIElementTypeStaticText' AND label == 'Log in' AND visible == 1
    TimeoutException: Timeout occurred while trying to perform the action. Element was not found

)
2022-05-10 11:29:31.932 ERROR rec-step-exec i.t.a.i.n Failed to retrieve cached source 404
2022-05-10 11:29:41.147 ERROR rec-step-exec i.t.a.i.n Failed to retrieve cached source 404
2022-05-10 11:31:05.768 INFO EventThread i.t.a.b.c.a User 773239 has connected
2022-05-10 11:31:06.142 INFO qtp1974489483-1 i.t.a.w.b.c EventsSocket 227163df-b75f-41ba-8307-8eca59707138 #4 connected
2022-05-10 11:31:19.488 ERROR qtp1974489483-7 i.t.a.m.E Failed to delete temporary logs archive C:\Users\yiann\AppData\Roaming\TestProject\Agent\logs-3c72ef5d-2f65-4fdb-b668-80e558335b83.zip
2022-05-10 11:31:33.066 INFO qtp1974489483-8 i.t.a.w.b.c EventsSocket 81d0dc79-931d-4569-9c5e-0e193e4bd8b9 #4 disconnected: [1001] null
2022-05-10 11:31:33.066 INFO qtp1974489483-6 i.t.a.w.b.c RecorderSocket aae7d998-0548-43e2-9c1c-023f369bbdc3 #3 disconnected: [1001] null
2022-05-10 11:31:33.067 INFO qtp1974489483-8 i.t.a.w.b.c MobileFramesSocket 290d8260-5a6a-469b-aa49-c964437d3902 #2 disconnected: [1001] null
2022-05-10 11:31:33.067 INFO qtp1974489483-8 i.t.a.w.b.c Last device mirroring client disconnected
2022-05-10 11:31:34.179 INFO qtp1974489483-7 i.t.a.w.b.c EventsSocket 227163df-b75f-41ba-8307-8eca59707138 #1 disconnected: [1001] null
2022-05-10 11:31:34.307 INFO EventThread i.t.a.b.c.a User 773239 has disconnected
2022-05-10 11:31:36.517 INFO jersey-client-a i.t.a.b.a.d DELETE https://api.testproject.io/api/v1/agent/devices/MDAwMDgwMzAtMDAxOTU0OTkzNjMzNDAyRQ==/interactions - 200 (iiAyGpMt): Device interaction session unlocked
2022-05-10 11:31:36.517 INFO qtp1974489483-8 i.t.a.m.f.t Stopping Screen Mirroring...
2022-05-10 11:31:36.525 INFO qtp1974489483-8 i.t.a.i.n XCUITest is running on 00008030-001954993633402E
2022-05-10 11:31:36.525 INFO qtp1974489483-8 i.t.a.m.f.p iOS device-mirror stopped
2022-05-10 11:31:50.069 ERROR state-machine i.t.a.i.n Failed to process 'Close App' request: java.net.SocketTimeoutException: Read timed out
2022-05-10 11:31:50.070 INFO state-machine i.t.a.i.n Stopping WDA on 00008030-001954993633402E
2022-05-10 11:31:59.176 INFO EventThread i.t.a.b.c.a Signal disconnected - transport error
2022-05-10 11:31:59.177 INFO disconnect-entr i.t.a.m.K Checking Agent's connectivity with the outside world...
2022-05-10 11:32:00.188 INFO disconnect-entr i.t.a.m.K Agent failed communicating with the outside world.
2022-05-10 11:32:00.188 INFO disconnect-entr i.t.a.f.c.a.k Direct connection is not possible, proxy is needed?
2022-05-10 11:32:00.188 INFO disconnect-entr i.t.a.m.H Trying to discover proxy...
2022-05-10 11:32:00.199 INFO disconnect-entr i.t.a.m.H Proxy was not discovered
2022-05-10 11:32:00.199 INFO disconnect-entr i.t.a.b.c.a Sent shutdown event to Signal server
2022-05-10 11:32:00.199 INFO disconnect-entr i.t.a.b.c.a Disconnecting Signal as part of the SignalClient stop sequence
2022-05-10 11:32:05.072 ERROR state-machine i.t.a.i.n Failed to stop WDA on java.net.SocketTimeoutException: Read timed out
2022-05-10 11:32:05.072 INFO state-machine i.t.a.i.n Stopping iOS components on 00008030-001954993633402E...
2022-05-10 11:32:06.265 INFO rec-step-exec i.t.a.i.n All iOS components on 00008030-001954993633402E were stopped
2022-05-10 11:32:06.266 ERROR state-machine i.t.a.i.n Failed to close app
2022-05-10 11:32:06.266 INFO state-machine i.t.a.f.l.a.I Ending recording mode...
2022-05-10 11:32:07.637 INFO ForkJoinPool.co i.t.a.f.i Found 0 processes with requested filters [Name equals "chromedriver.exe" ]
2022-05-10 11:32:07.637 INFO ForkJoinPool.co i.t.a.f.i Done killing processes.
2022-05-10 11:33:00.200 INFO disconnect-entr i.t.a.m.K Checking Agent's connectivity with the outside world...
2022-05-10 11:33:02.766 INFO disconnect-entr i.t.a.m.K Connection established successfully.
2022-05-10 11:33:02.766 INFO disconnect-entr i.t.a.f.c.a.k Direct connection (no proxy) is possible.
2022-05-10 11:33:02.766 INFO disconnect-entr i.t.a.f.c.a.k External connectivity is possible, proceeding to Authentication...
2022-05-10 11:33:02.767 INFO auth-entry i.t.a.f.c.a.a Trying to load Agent remote identity...
2022-05-10 11:33:02.768 INFO auth-entry i.t.a.f.c.a.a Agent is registered as [Yiannis agent] (vjw2P8EsA0OrxEPLtBkL2g), proceeding to authentication...
2022-05-10 11:33:02.768 INFO auth-entry i.t.a.f.c.a.a Authenticating with TestProject...
2022-05-10 11:33:02.768 INFO auth-entry i.t.a.s.c Requesting authenticating token...
2022-05-10 11:33:04.788 INFO auth-entry i.t.a.s.c Authenticated successfully
2022-05-10 11:33:04.793 INFO state-machine i.t.a.m.f.l Reporting 2 connected devices/simulators/emulators...
2022-05-10 11:33:06.963 INFO state-machine i.t.a.b.a.d PUT https://api.testproject.io/api/v1/agent/devices - 200 (Cm7Ylrzg): No Message
2022-05-10 11:33:06.964 INFO state-machine i.t.a.f.c.a.h Starting Signal...
2022-05-10 11:33:06.965 INFO pool-3-thread-1 i.t.a.m.q Validates if iOS image version 15.3.1 is ready...
2022-05-10 11:33:07.095 INFO auth-entry i.t.a.b.a.d GET http://localhost:54622/wd/hub/status - 200 (null): No Message
2022-05-10 11:33:07.095 INFO auth-entry i.t.a.b.b.j UIAutomator on xiaomi-redmi_note_8_pro-9pfeca65det4n79p is running...
2022-05-10 11:33:07.467 INFO pool-3-thread-1 i.t.a.b.a.d GET https://api.testproject.io/dependencies/ios-images/15.3?fileHash=ec03860d7fe0a0738748a375a67f2e85 - 204 (eQgL9DnH): No Message
2022-05-10 11:33:07.468 INFO pool-3-thread-1 i.t.a.b.b.a Agent already has the latest dependency
2022-05-10 11:33:07.468 INFO pool-3-thread-1 i.t.a.m.q iOS 15.3 image has been validated successfully
2022-05-10 11:33:09.728 INFO EventThread i.t.a.b.c.a Signal connected, awaiting authentication result...
2022-05-10 11:33:09.729 INFO EventThread i.t.a.b.c.a Sent empty Session ID
2022-05-10 11:33:11.070 INFO EventThread i.t.a.b.c.a Signal authentication finished
2022-05-10 11:33:11.070 INFO EventThread i.t.a.b.c.a Connection Session ID: gkr6GfuRFA
2022-05-10 11:33:11.070 INFO EventThread i.t.a.b.c.a Signal authorized

Edit
Pub: 10 May 2022 10:16 UTC
Views: 64