search

appium / appium-xcuitest-driver

Appium iOS driver, backed by Apple XCTest
https://appium.github.io/appium-xcuitest-driver/
Apache License 2.0
744 stars 417 forks source link

Screen Recorder process is not starting #1363

Closed n00bstr closed 2 years ago

n00bstr commented 2 years ago

The screen recording does generate the mp4 in the temp folders. It is also complaining that the WebDriverAgent cannot take a screenshot with 20000 ms.

Installed Appium 1.22.2 XCUITEST Driver - 3.59.0 FFMPEG - 4.4.1 OS - Mac OS Monterey XCode - 13.2.1

Logs: 2021-12-24 16:29:44:795 - [HTTP] --> POST /wd/hub/session/0b11dcf3-d40d-4ec7-90e7-618c6d0ea0d8/appium/start_recording_screen 2021-12-24 16:29:44:795 - [HTTP] {} 2021-12-24 16:29:44:796 - [debug] [W3C (0b11dcf3)] Calling AppiumDriver.startRecordingScreen() with args: [null,"0b11dcf3-d40d-4ec7-90e7-618c6d0ea0d8"] 2021-12-24 16:29:44:796 - [debug] [XCUITest] Executing command 'startRecordingScreen' 2021-12-24 16:29:44:796 - [XCUITest] Checking if there is/was a previous screen recording. Set 'forceRestart' option to 'true' if you'd like to skip this step. 2021-12-24 16:29:44:797 - [XCUITest] Screen recording is not running. There is nothing to stop. 2021-12-24 16:29:44:808 - [DevCon Factory] Releasing connections for 01B145DA-9B98-48BA-81CA-738D0478B658 device on 9100 port number 2021-12-24 16:29:44:808 - [DevCon Factory] No cached connections have been found 2021-12-24 16:29:44:812 - [debug] [WD Proxy] Matched '/appium/settings' to command name 'getSettings' 2021-12-24 16:29:44:812 - [debug] [WD Proxy] Proxying [GET /appium/settings] to [GET http://127.0.0.1:8190/session/A4250843-A600-4C2A-BA7A-A5386622FDB1/appium/settings] with no body 2021-12-24 16:29:44:814 - [debug] [WD Proxy] Got response with status 200: {"value":{"boundElementsByIndex":false,"mjpegServerFramerate":10,"screenshotOrientation":"auto","reduceMotion":false,"elementResponseAttributes":"type,label","screenshotQuality":1,"mjpegScalingFactor":100,"keyboardPrediction":0,"defaultActiveApplication":"auto","mjpegServerScreenshotQuality":25,"defaultAlertAction":"","keyboardAutocorrection":0,"useFirstMatch":false,"shouldUseCompactResponses":true,"customSnapshotTimeout":15,"dismissAlertButtonSelector":"","activeAppDetectionPoint":"64.00,64.00","snapshotMaxDepth":50,"waitForIdleTimeout":10,"includeNonModalElements":false,"acceptAlertButtonSelector":"","animationCoolOffTimeout":2},"sessionId":"A4250843-A600-4C2A-BA7A-A5386622FDB1"} 2021-12-24 16:29:44:825 - [DevCon Factory] Requesting connection for device 01B145DA-9B98-48BA-81CA-738D0478B658 on local port 9100, device port 9100 2021-12-24 16:29:44:825 - [debug] [DevCon Factory] Cached connections count: 1 2021-12-24 16:29:44:825 - [DevCon Factory] Successfully requested the connection for 01B145DA-9B98-48BA-81CA-738D0478B658:9100 2021-12-24 16:29:49:853 - [XCUITest] Screen capture process did not start within 5000ms. Continuing anyway 2021-12-24 16:29:49:854 - [XCUITest] Starting screen capture on the device '01B145DA-9B98-48BA-81CA-738D0478B658' with command: 'ffmpeg -f mjpeg -i http://127.0.0.1:9100 -vcodec mjpeg -y /var/folders/7r/cp9601gd0h3604ghk65vvyp00000gn/T/20211124-97705-1s0bxa7.hupm/appium_3c8447.mp4'. Will timeout in 180000ms 2021-12-24 16:29:49:856 - [debug] [W3C (0b11dcf3)] Responding to client with driver.startRecordingScreen() result: "" 2021-12-24 16:29:49:863 - [HTTP] <-- POST /wd/hub/session/0b11dcf3-d40d-4ec7-90e7-618c6d0ea0d8/appium/start_recording_screen 200 5063 ms - 12 2021-12-24 16:29:49:863 - [HTTP] Using sim/device/emulator with udid: 01B145DA-9B98-48BA-81CA-738D0478B658 2021-12-24 16:29:49:903 - [HTTP] --> GET /wd/hub/session/0b11dcf3-d40d-4ec7-90e7-618c6d0ea0d8/context 2021-12-24 16:29:49:903 - [HTTP] {} 2021-12-24 16:29:49:903 - [debug] [W3C (0b11dcf3)] Calling AppiumDriver.getCurrentContext() with args: ["0b11dcf3-d40d-4ec7-90e7-618c6d0ea0d8"] 2021-12-24 16:29:49:904 - [debug] [XCUITest] Executing command 'getCurrentContext' 2021-12-24 16:29:49:904 - [debug] [W3C (0b11dcf3)] Responding to client with driver.getCurrentContext() result: "NATIVE_APP" 2021-12-24 16:29:49:905 - [HTTP] <-- GET /wd/hub/session/0b11dcf3-d40d-4ec7-90e7-618c6d0ea0d8/context 200 2 ms - 22 2021-12-24 16:29:49:905 - [HTTP] 2021-12-24 16:29:49:910 - [HTTP] --> POST /wd/hub/session/0b11dcf3-d40d-4ec7-90e7-618c6d0ea0d8/element 2021-12-24 16:29:49:911 - [HTTP] {"using":"xpath","value":"/hierarchy//android.widget.Button[@text='Sign up with MyPost']|//XCUIElementTypeButton[@label='Sign up with MyPost']|//XCUIElementTypeButton[@label='Sign up for free']"} 2021-12-24 16:29:49:911 - [debug] [W3C (0b11dcf3)] Calling AppiumDriver.findElement() with args: ["xpath","/hierarchy//android.widget.Button[@text='Sign up with MyPost']|//XCUIElementTypeButton[@label='Sign up with MyPost']|//XCUIElementTypeButton[@label='Sign up for free']","0b11dcf3-d40d-4ec7-90e7-618c6d0ea0d8"] 2021-12-24 16:29:49:911 - [debug] [XCUITest] Executing command 'findElement' 2021-12-24 16:29:49:911 - [debug] [BaseDriver] Valid locator strategies for this request: xpath, id, name, class name, -ios predicate string, -ios class chain, accessibility id, css selector 2021-12-24 16:29:49:912 - [debug] [BaseDriver] Waiting up to 5000 ms for condition 2021-12-24 16:29:49:912 - [debug] [WD Proxy] Matched '/element' to command name 'findElement' 2021-12-24 16:29:49:913 - [debug] [WD Proxy] Proxying [POST /element] to [POST http://127.0.0.1:8190/session/A4250843-A600-4C2A-BA7A-A5386622FDB1/element] with body: {"using":"xpath","value":"/hierarchy//android.widget.Button[@text='Sign up with MyPost']|//XCUIElementTypeButton[@label='Sign up with MyPost']|//XCUIElementTypeButton[@label='Sign up for free']"} 2021-12-24 16:29:49:926 - [Xcode] 2021-12-24 16:29:49.924377+1100 WebDriverAgentRunner-Runner[97991:4962725] Getting the most recent active application (out of 1 total items) 2021-12-24 16:29:49:927 - [Xcode] 2021-12-24 16:29:49:933 - [Xcode] 2021-12-24 16:29:49.927724+1100 WebDriverAgentRunner-Runner[97991:4962725] The following attributes were requested to be included into the XML: {( 2021-12-24 16:29:49:933 - [Xcode] FBLabelAttribute 2021-12-24 16:29:49:933 - [Xcode] )} 2021-12-24 16:29:49:933 - [Xcode] 2021-12-24 16:29:49:933 - [Xcode] t = 19.49s Requesting snapshot of accessibility hierarchy for app with pid 98010 2021-12-24 16:29:49:933 - [Xcode] 2021-12-24 16:29:49:995 - [Xcode] t = 19.56s Get all elements bound by accessibility element for: Elements matching predicate 'wdUID IN {"0E000000-0000-0000-DA7E-010000000000"}' 2021-12-24 16:29:49:995 - [Xcode] 2021-12-24 16:29:49:995 - [Xcode] t = 19.56s Requesting snapshot of accessibility hierarchy for app with pid 98010 2021-12-24 16:29:49:996 - [Xcode] 2021-12-24 16:29:50:001 - [Xcode] t = 19.56s Find: Descendants matching type Button 2021-12-24 16:29:50:001 - [Xcode] 2021-12-24 16:29:50:001 - [Xcode] t = 19.56s Find: Elements matching predicate 'wdUID IN {"0E000000-0000-0000-DA7E-010000000000"}' 2021-12-24 16:29:50:001 - [Xcode] 2021-12-24 16:29:50:006 - [Xcode] t = 19.57s Checking existence of "Sign up with MyPost" Button 2021-12-24 16:29:50:006 - [Xcode] 2021-12-24 16:29:50:006 - [Xcode] t = 19.57s Requesting snapshot of accessibility hierarchy for app with pid 98010 2021-12-24 16:29:50:006 - [Xcode] 2021-12-24 16:29:50:012 - [Xcode] t = 19.57s Find: Descendants matching type Button 2021-12-24 16:29:50:012 - [Xcode] 2021-12-24 16:29:50:012 - [Xcode] t = 19.57s Find: Elements matching predicate 'wdUID IN {"0E000000-0000-0000-DA7E-010000000000"}' 2021-12-24 16:29:50:012 - [Xcode] 2021-12-24 16:29:50:012 - [Xcode] t = 19.58s Find: Identity Binding 2021-12-24 16:29:50:012 - [Xcode] 2021-12-24 16:29:50:014 - [debug] [WD Proxy] Got response with status 200: {"value":{"ELEMENT":"0E000000-0000-0000-DA7E-010000000000","element-6066-11e4-a52e-4f735466cecf":"0E000000-0000-0000-DA7E-010000000000"},"sessionId":"A4250843-A600-4C2A-BA7A-A5386622FDB1"} 2021-12-24 16:29:50:014 - [debug] [W3C (0b11dcf3)] Responding to client with driver.findElement() result: {"element-6066-11e4-a52e-4f735466cecf":"0E000000-0000-0000-DA7E-010000000000","ELEMENT":"0E000000-0000-0000-DA7E-010000000000"} 2021-12-24 16:29:50:015 - [HTTP] <-- POST /wd/hub/session/0b11dcf3-d40d-4ec7-90e7-618c6d0ea0d8/element 200 104 ms - 137 2021-12-24 16:29:50:015 - [HTTP] 2021-12-24 16:29:50:016 - [HTTP] --> GET /wd/hub/session/0b11dcf3-d40d-4ec7-90e7-618c6d0ea0d8/element/0E000000-0000-0000-DA7E-010000000000/displayed 2021-12-24 16:29:50:016 - [HTTP] {} 2021-12-24 16:29:50:017 - [W3C (0b11dcf3)] Driver proxy active, passing request on via HTTP proxy 2021-12-24 16:29:50:017 - [debug] [XCUITest] Executing command 'proxyReqRes' 2021-12-24 16:29:50:017 - [debug] [WD Proxy] Matched '/wd/hub/session/0b11dcf3-d40d-4ec7-90e7-618c6d0ea0d8/element/0E000000-0000-0000-DA7E-010000000000/displayed' to command name 'elementDisplayed' 2021-12-24 16:29:50:017 - [debug] [WD Proxy] Proxying [GET /wd/hub/session/0b11dcf3-d40d-4ec7-90e7-618c6d0ea0d8/element/0E000000-0000-0000-DA7E-010000000000/displayed] to [GET http://127.0.0.1:8190/session/A4250843-A600-4C2A-BA7A-A5386622FDB1/element/0E000000-0000-0000-DA7E-010000000000/displayed] with no body 2021-12-24 16:29:50:019 - [Xcode] t = 19.58s Requesting snapshot of accessibility hierarchy for app with pid 98010 2021-12-24 16:29:50:019 - [Xcode] 2021-12-24 16:29:50:024 - [Xcode] t = 19.59s Find: Descendants matching type Button 2021-12-24 16:29:50:024 - [Xcode] 2021-12-24 16:29:50:024 - [Xcode] t = 19.59s Find: Elements matching predicate 'wdUID IN {"0E000000-0000-0000-DA7E-010000000000"}' 2021-12-24 16:29:50:024 - [Xcode] 2021-12-24 16:29:50:024 - [Xcode] t = 19.59s Find: Identity Binding 2021-12-24 16:29:50:024 - [Xcode] 2021-12-24 16:29:50:130 - [debug] [WD Proxy] Got response with status 200: {"value":true,"sessionId":"A4250843-A600-4C2A-BA7A-A5386622FDB1"} 2021-12-24 16:29:50:130 - [WD Proxy] Replacing sessionId A4250843-A600-4C2A-BA7A-A5386622FDB1 with 0b11dcf3-d40d-4ec7-90e7-618c6d0ea0d8 2021-12-24 16:29:50:130 - [HTTP] <-- GET /wd/hub/session/0b11dcf3-d40d-4ec7-90e7-618c6d0ea0d8/element/0E000000-0000-0000-DA7E-010000000000/displayed 200 114 ms - 65 2021-12-24 16:29:50:130 - [HTTP] 2021-12-24 16:29:50:131 - [HTTP] --> GET /wd/hub/session/0b11dcf3-d40d-4ec7-90e7-618c6d0ea0d8/context 2021-12-24 16:29:50:131 - [HTTP] {} 2021-12-24 16:29:50:131 - [debug] [W3C (0b11dcf3)] Calling AppiumDriver.getCurrentContext() with args: ["0b11dcf3-d40d-4ec7-90e7-618c6d0ea0d8"] 2021-12-24 16:29:50:131 - [debug] [XCUITest] Executing command 'getCurrentContext' 2021-12-24 16:29:50:132 - [debug] [W3C (0b11dcf3)] Responding to client with driver.getCurrentContext() result: "NATIVE_APP" 2021-12-24 16:29:50:132 - [HTTP] <-- GET /wd/hub/session/0b11dcf3-d40d-4ec7-90e7-618c6d0ea0d8/context 200 0 ms - 22 2021-12-24 16:29:50:132 - [HTTP] 2021-12-24 16:29:50:135 - [HTTP] --> POST /wd/hub/session/0b11dcf3-d40d-4ec7-90e7-618c6d0ea0d8/element 2021-12-24 16:29:50:135 - [HTTP] {"using":"xpath","value":"/hierarchy//android.widget.Button[@text='Sign up with MyPost']|//XCUIElementTypeButton[@label='Sign up with MyPost']|//XCUIElementTypeButton[@label='Sign up for free']"} 2021-12-24 16:29:50:135 - [debug] [W3C (0b11dcf3)] Calling AppiumDriver.findElement() with args: ["xpath","/hierarchy//android.widget.Button[@text='Sign up with MyPost']|//XCUIElementTypeButton[@label='Sign up with MyPost']|//XCUIElementTypeButton[@label='Sign up for free']","0b11dcf3-d40d-4ec7-90e7-618c6d0ea0d8"] 2021-12-24 16:29:50:135 - [debug] [XCUITest] Executing command 'findElement' 2021-12-24 16:29:50:135 - [debug] [BaseDriver] Valid locator strategies for this request: xpath, id, name, class name, -ios predicate string, -ios class chain, accessibility id, css selector 2021-12-24 16:29:50:135 - [debug] [BaseDriver] Waiting up to 5000 ms for condition 2021-12-24 16:29:50:135 - [debug] [WD Proxy] Matched '/element' to command name 'findElement' 2021-12-24 16:29:50:135 - [debug] [WD Proxy] Proxying [POST /element] to [POST http://127.0.0.1:8190/session/A4250843-A600-4C2A-BA7A-A5386622FDB1/element] with body: {"using":"xpath","value":"/hierarchy//android.widget.Button[@text='Sign up with MyPost']|//XCUIElementTypeButton[@label='Sign up with MyPost']|//XCUIElementTypeButton[@label='Sign up for free']"} 2021-12-24 16:29:50:137 - [Xcode] 2021-12-24 16:29:50.137680+1100 WebDriverAgentRunner-Runner[97991:4962725] Getting the most recent active application (out of 1 total items) 2021-12-24 16:29:50:137 - [Xcode] 2021-12-24 16:29:50:140 - [Xcode] 2021-12-24 16:29:50.140486+1100 WebDriverAgentRunner-Runner[97991:4962725] The following attributes were requested to be included into the XML: {( 2021-12-24 16:29:50:140 - [Xcode] FBLabelAttribute 2021-12-24 16:29:50:140 - [Xcode] )} 2021-12-24 16:29:50:140 - [Xcode] 2021-12-24 16:29:50:141 - [Xcode] t = 19.70s Requesting snapshot of accessibility hierarchy for app with pid 98010 2021-12-24 16:29:50:141 - [Xcode] 2021-12-24 16:29:50:179 - [Xcode] t = 19.74s Get all elements bound by accessibility element for: Elements matching predicate 'wdUID IN {"0E000000-0000-0000-DA7E-010000000000"}' 2021-12-24 16:29:50:179 - [Xcode] 2021-12-24 16:29:50:180 - [Xcode] t = 19.74s Requesting snapshot of accessibility hierarchy for app with pid 98010 2021-12-24 16:29:50:181 - [Xcode] 2021-12-24 16:29:50:186 - [Xcode] t = 19.75s Find: Descendants matching type Button 2021-12-24 16:29:50:186 - [Xcode] 2021-12-24 16:29:50:186 - [Xcode] t = 19.75s Find: Elements matching predicate 'wdUID IN {"0E000000-0000-0000-DA7E-010000000000"}' 2021-12-24 16:29:50:186 - [Xcode] 2021-12-24 16:29:50:190 - [Xcode] t = 19.75s Checking existence of "Sign up with MyPost" Button 2021-12-24 16:29:50:190 - [Xcode] 2021-12-24 16:29:50:190 - [Xcode] t = 19.75s Requesting snapshot of accessibility hierarchy for app with pid 98010 2021-12-24 16:29:50:190 - [Xcode] 2021-12-24 16:29:50:199 - [Xcode] t = 19.76s Find: Descendants matching type Button 2021-12-24 16:29:50:199 - [Xcode] t = 19.76s Find: Elements matching predicate 'wdUID IN {"0E000000-0000-0000-DA7E-010000000000"}' 2021-12-24 16:29:50:199 - [Xcode] t = 19.76s Find: Identity Binding 2021-12-24 16:29:50:199 - [Xcode] 2021-12-24 16:29:50:200 - [debug] [WD Proxy] Got response with status 200: {"value":{"ELEMENT":"0E000000-0000-0000-DA7E-010000000000","element-6066-11e4-a52e-4f735466cecf":"0E000000-0000-0000-DA7E-010000000000"},"sessionId":"A4250843-A600-4C2A-BA7A-A5386622FDB1"} 2021-12-24 16:29:50:201 - [debug] [W3C (0b11dcf3)] Responding to client with driver.findElement() result: {"element-6066-11e4-a52e-4f735466cecf":"0E000000-0000-0000-DA7E-010000000000","ELEMENT":"0E000000-0000-0000-DA7E-010000000000"} 2021-12-24 16:29:50:201 - [HTTP] <-- POST /wd/hub/session/0b11dcf3-d40d-4ec7-90e7-618c6d0ea0d8/element 200 66 ms - 137 2021-12-24 16:29:50:201 - [HTTP] 2021-12-24 16:29:50:204 - [HTTP] --> POST /wd/hub/session/0b11dcf3-d40d-4ec7-90e7-618c6d0ea0d8/element/0E000000-0000-0000-DA7E-010000000000/click 2021-12-24 16:29:50:204 - [HTTP] {"id":"0E000000-0000-0000-DA7E-010000000000"} 2021-12-24 16:29:50:204 - [W3C (0b11dcf3)] Driver proxy active, passing request on via HTTP proxy 2021-12-24 16:29:50:204 - [debug] [XCUITest] Executing command 'proxyReqRes' 2021-12-24 16:29:50:204 - [debug] [WD Proxy] Matched '/wd/hub/session/0b11dcf3-d40d-4ec7-90e7-618c6d0ea0d8/element/0E000000-0000-0000-DA7E-010000000000/click' to command name 'click' 2021-12-24 16:29:50:204 - [debug] [WD Proxy] Proxying [POST /wd/hub/session/0b11dcf3-d40d-4ec7-90e7-618c6d0ea0d8/element/0E000000-0000-0000-DA7E-010000000000/click] to [POST http://127.0.0.1:8190/session/A4250843-A600-4C2A-BA7A-A5386622FDB1/element/0E000000-0000-0000-DA7E-010000000000/click] with body: {"id":"0E000000-0000-0000-DA7E-010000000000"} 2021-12-24 16:29:50:206 - [Xcode] t = 19.77s Requesting snapshot of accessibility hierarchy for app with pid 98010 2021-12-24 16:29:50:206 - [Xcode] 2021-12-24 16:29:50:212 - [Xcode] t = 19.77s Find: Descendants matching type Button 2021-12-24 16:29:50:212 - [Xcode] 2021-12-24 16:29:50:213 - [Xcode] t = 19.78s Find: Elements matching predicate 'wdUID IN {"0E000000-0000-0000-DA7E-010000000000"}' 2021-12-24 16:29:50:213 - [Xcode] 2021-12-24 16:29:50:213 - [Xcode] t = 19.78s Find: Identity Binding 2021-12-24 16:29:50:213 - [Xcode] 2021-12-24 16:29:50:222 - [Xcode] t = 19.78s Tap "Sign up with MyPost" Button 2021-12-24 16:29:50:222 - [Xcode] 2021-12-24 16:29:50.219764+1100 WebDriverAgentRunner-Runner[97991:4962725] Waiting up to 10s until com.auspost.mobile.develop is in idle state (including animations) 2021-12-24 16:29:50:222 - [Xcode] t = 19.78s Wait for com.auspost.mobile.develop to idle 2021-12-24 16:29:50:222 - [Xcode] 2021-12-24 16:29:50:223 - [Xcode] t = 19.78s Find the "Sign up with MyPost" Button 2021-12-24 16:29:50:223 - [Xcode] t = 19.79s Requesting snapshot of accessibility hierarchy for app with pid 98010 2021-12-24 16:29:50:224 - [Xcode] 2021-12-24 16:29:50:234 - [Xcode] t = 19.79s Find: Descendants matching type Button 2021-12-24 16:29:50:234 - [Xcode] t = 19.79s Find: Elements matching predicate 'wdUID IN {"0E000000-0000-0000-DA7E-010000000000"}' 2021-12-24 16:29:50:234 - [Xcode] 2021-12-24 16:29:50:234 - [Xcode] t = 19.79s Find: Identity Binding 2021-12-24 16:29:50:234 - [Xcode] 2021-12-24 16:29:50:235 - [Xcode] t = 19.79s Application is configured to skip UI interruption handling 2021-12-24 16:29:50:235 - [Xcode] t = 19.80s Synthesize event 2021-12-24 16:29:50:235 - [Xcode] 2021-12-24 16:29:50:312 - [Xcode] 2021-12-24 16:29:50.311872+1100 WebDriverAgentRunner-Runner[97991:4962725] Waiting up to 10s until com.auspost.mobile.develop is in idle state (including animations) 2021-12-24 16:29:50:312 - [Xcode] 2021-12-24 16:29:50:312 - [Xcode] t = 19.87s Wait for com.auspost.mobile.develop to idle 2021-12-24 16:29:50:312 - [Xcode] 2021-12-24 16:29:50:534 - [ffmpeg] ffmpeg version 4.4.1 Copyright (c) 2000-2021 the FFmpeg developers 2021-12-24 16:29:50:534 - [ffmpeg] built with Apple clang version 13.0.0 (clang-1300.0.29.3) 2021-12-24 16:29:50:534 - [ffmpeg] configuration: --prefix=/opt/homebrew/Cellar/ffmpeg/4.4.1_3 --enable-shared --enable-pthreads --enable-version3 --cc=clang --host-cflags= --host-ldflags= --enable-ffplay --enable-gnutls --enable-gpl --enable-libaom --enable-libbluray --enable-libdav1d --enable-libmp3lame --enable-libopus --enable-librav1e --enable-librist --enable-librubberband --enable-libsnappy --enable-libsrt --enable-libtesseract --enable-libtheora --enable-libvidstab --enable-libvmaf --enable-libvorbis --enable-libvpx --enable-libwebp --enable-libx264 --enable-libx265 --enable-libxml2 --enable-libxvid --enable-lzma --enable-libfontconfig --enable-libfreetype --enable-frei0r --enable-libass --enable-libopencore-amrnb --enable-libopencore-amrwb --enable-libopenjpeg --enable-libspeex --enable-libsoxr --enable-libzmq --enable-libzimg --disable-libjack --disable-indev=jack --enable-avresample --enable-videotoolbox 2021-12-24 16:29:50:534 - [ffmpeg] 2021-12-24 16:29:50:535 - [ffmpeg] libavutil 56. 70.100 / 56. 70.100 2021-12-24 16:29:50:535 - [ffmpeg] libavcodec 58.134.100 / 58.134.100 2021-12-24 16:29:50:535 - [ffmpeg] libavformat 58. 76.100 / 58. 76.100 2021-12-24 16:29:50:535 - [ffmpeg] libavdevice 58. 13.100 / 58. 13.100 2021-12-24 16:29:50:535 - [ffmpeg] libavfilter 7.110.100 / 7.110.100 2021-12-24 16:29:50:535 - [ffmpeg] libavresample 4. 0. 0 / 4. 0. 0 2021-12-24 16:29:50:535 - [ffmpeg] libswscale 5. 9.100 / 5. 9.100 2021-12-24 16:29:50:535 - [ffmpeg] libswresample 3. 9.100 / 3. 9.100 2021-12-24 16:29:50:535 - [ffmpeg] libpostproc 55. 9.100 / 55. 9.100 2021-12-24 16:29:50:535 - [ffmpeg] 2021-12-24 16:29:50:559 - [Xcode] 2021-12-24 16:29:50.559587+1100 WebDriverAgentRunner-Runner[97991:4963568] Got screenshots broadcast client connection at 127.0.0.1:60156 2021-12-24 16:29:50:559 - [Xcode] 2021-12-24 16:29:50:559 - [Xcode] 2021-12-24 16:29:50.559660+1100 WebDriverAgentRunner-Runner[97991:4962746] Starting screenshots broadcast for the client at 127.0.0.1:60156 2021-12-24 16:29:50:560 - [Xcode] 2021-12-24 16:29:51:362 - [debug] [WD Proxy] Got response with status 200: {"value":null,"sessionId":"A4250843-A600-4C2A-BA7A-A5386622FDB1"} 2021-12-24 16:29:51:364 - [WD Proxy] Replacing sessionId A4250843-A600-4C2A-BA7A-A5386622FDB1 with 0b11dcf3-d40d-4ec7-90e7-618c6d0ea0d8 2021-12-24 16:29:51:366 - [HTTP] <-- POST /wd/hub/session/0b11dcf3-d40d-4ec7-90e7-618c6d0ea0d8/element/0E000000-0000-0000-DA7E-010000000000/click 200 1161 ms - 65 2021-12-24 16:29:51:366 - [HTTP] 2021-12-24 16:29:51:373 - [HTTP] --> GET /wd/hub/session/0b11dcf3-d40d-4ec7-90e7-618c6d0ea0d8/context 2021-12-24 16:29:51:373 - [HTTP] {} 2021-12-24 16:29:51:374 - [debug] [W3C (0b11dcf3)] Calling AppiumDriver.getCurrentContext() with args: ["0b11dcf3-d40d-4ec7-90e7-618c6d0ea0d8"] 2021-12-24 16:29:51:378 - [debug] [XCUITest] Executing command 'getCurrentContext' 2021-12-24 16:29:51:379 - [debug] [W3C (0b11dcf3)] Responding to client with driver.getCurrentContext() result: "NATIVE_APP" 2021-12-24 16:29:51:381 - [HTTP] <-- GET /wd/hub/session/0b11dcf3-d40d-4ec7-90e7-618c6d0ea0d8/context 200 8 ms - 22 2021-12-24 16:29:51:381 - [HTTP] 2021-12-24 16:29:51:389 - [HTTP] --> POST /wd/hub/session/0b11dcf3-d40d-4ec7-90e7-618c6d0ea0d8/element 2021-12-24 16:29:51:389 - [HTTP] {"using":"xpath","value":"/hierarchy//android.widget.Button[@text='Sign up with MyPost']|//XCUIElementTypeStaticText[@name='Title For Email Step']"} 2021-12-24 16:29:51:391 - [debug] [W3C (0b11dcf3)] Calling AppiumDriver.findElement() with args: ["xpath","/hierarchy//android.widget.Button[@text='Sign up with MyPost']|//XCUIElementTypeStaticText[@name='Title For Email Step']","0b11dcf3-d40d-4ec7-90e7-618c6d0ea0d8"] 2021-12-24 16:29:51:392 - [debug] [XCUITest] Executing command 'findElement' 2021-12-24 16:29:51:392 - [debug] [BaseDriver] Valid locator strategies for this request: xpath, id, name, class name, -ios predicate string, -ios class chain, accessibility id, css selector 2021-12-24 16:29:51:392 - [debug] [BaseDriver] Waiting up to 5000 ms for condition 2021-12-24 16:29:51:393 - [debug] [WD Proxy] Matched '/element' to command name 'findElement' 2021-12-24 16:29:51:393 - [debug] [WD Proxy] Proxying [POST /element] to [POST http://127.0.0.1:8190/session/A4250843-A600-4C2A-BA7A-A5386622FDB1/element] with body: {"using":"xpath","value":"/hierarchy//android.widget.Button[@text='Sign up with MyPost']|//XCUIElementTypeStaticText[@name='Title For Email Step']"} 2021-12-24 16:29:51:604 - [Xcode] 2021-12-24 16:29:51.603484+1100 WebDriverAgentRunner-Runner[97991:4962746] Error Domain=com.facebook.WebDriverAgent Code=1 "Cannot take a screenshot within 20000 ms timeout" UserInfo={NSLocalizedDescription=Cannot take a screenshot within 20000 ms timeout} 2021-12-24 16:29:51:604 - [Xcode] 2021-12-24 16:29:52:263 - [Xcode] 2021-12-24 16:29:52.259113+1100 WebDriverAgentRunner-Runner[97991:4962725] Getting the most recent active application (out of 1 total items) 2021-12-24 16:29:52:264 - [Xcode] 2021-12-24 16:29:52:268 - [Xcode] 2021-12-24 16:29:52.267615+1100 WebDriverAgentRunner-Runner[97991:4962725] The following attributes were requested to be included into the XML: {( 2021-12-24 16:29:52:268 - [Xcode] FBNameAttribute 2021-12-24 16:29:52:268 - [Xcode] )} 2021-12-24 16:29:52:268 - [Xcode] 2021-12-24 16:29:52:268 - [Xcode] t = 21.83s Requesting snapshot of accessibility hierarchy for app with pid 98010 2021-12-24 16:29:52:268 - [Xcode] 2021-12-24 16:29:52:611 - [Xcode] t = 22.17s Get all elements bound by accessibility element for: Elements matching predicate 'wdUID IN {"57000000-0000-0000-DA7E-010000000000"}' 2021-12-24 16:29:52:611 - [Xcode] 2021-12-24 16:29:52:612 - [Xcode] t = 22.17s Requesting snapshot of accessibility hierarchy for app with pid 98010 2021-12-24 16:29:52:612 - [Xcode] 2021-12-24 16:29:52:633 - [Xcode] t = 22.20s Find: Descendants matching type StaticText 2021-12-24 16:29:52:634 - [Xcode] 2021-12-24 16:29:52:635 - [Xcode] t = 22.20s Find: Elements matching predicate 'wdUID IN {"57000000-0000-0000-DA7E-010000000000"}' 2021-12-24 16:29:52:636 - [Xcode] 2021-12-24 16:29:52:646 - [Xcode] t = 22.21s Checking existence of "Title For Email Step" StaticText 2021-12-24 16:29:52:646 - [Xcode] 2021-12-24 16:29:52:647 - [Xcode] t = 22.21s Requesting snapshot of accessibility hierarchy for app with pid 98010 2021-12-24 16:29:52:647 - [Xcode] 2021-12-24 16:29:52:671 - [Xcode] t = 22.23s Find: Descendants matching type StaticText 2021-12-24 16:29:52:672 - [Xcode] 2021-12-24 16:29:52:672 - [Xcode] t = 22.23s Find: Elements matching predicate 'wdUID IN {"57000000-0000-0000-DA7E-010000000000"}' 2021-12-24 16:29:52:672 - [Xcode] 2021-12-24 16:29:52:673 - [Xcode] t = 22.23s Find: Identity Binding 2021-12-24 16:29:52:674 - [Xcode] 2021-12-24 16:29:52:677 - [Xcode] 2021-12-24 16:29:52.676093+1100 WebDriverAgentRunner-Runner[97991:4962746] Error Domain=com.facebook.WebDriverAgent Code=1 "Cannot take a screenshot within 20000 ms timeout" UserInfo={NSLocalizedDescription=Cannot take a screenshot within 20000 ms timeout}

mykola-mokhnach commented 2 years ago

Only Apple can fix that. The screenshots retrieval is controlled by XCTest.

n00bstr commented 2 years ago

Just an update to this issue, I was using a simulator with iOS 14.0 under XCode 13. After switching to iOS 15.0, this issue is no longer appearing. Putting it here to help some people.

Closing this off.