serverLog.txt

2022-01-11 16:14:40:862 [Appium] Welcome to Appium v1.23.0-beta.0
2022-01-11 16:14:40:863 [Appium] Non-default server args:
2022-01-11 16:14:40:863 [Appium]   shell: true
2022-01-11 16:14:40:864 [Appium]   address: 127.0.0.1
2022-01-11 16:14:40:864 [Appium]   port: 4720
2022-01-11 16:14:40:864 [Appium]   sessionOverride: true
2022-01-11 16:14:40:865 [Appium]   logFile: /Users/gitlabrunner/Documents/logs/iPhone_10/AppiumServerLogs
2022-01-11 16:14:40:865 [Appium]   logTimestamp: true
2022-01-11 16:14:40:866 [Appium]   robotPort: 5120
2022-01-11 16:14:40:866 [Appium]   tmpDir: /Users/gitlabrunner/Documents/temp/iPhone_10/
2022-01-11 16:14:40:867 [Appium]   debugLogSpacing: true
2022-01-11 16:14:40:867 [Appium]   longStacktrace: true
2022-01-11 16:14:40:868 [Appium]   defaultCapabilities: {
2022-01-11 16:14:40:882 [Appium]     platformName: iOS
2022-01-11 16:14:40:882 [Appium]     platformVersion: 13.4
2022-01-11 16:14:40:882 [Appium]     deviceName: iPhone_10
2022-01-11 16:14:40:883 [Appium]     app: /Users/gitlabrunner/builds/QSH2MAjg/0/Fedor/idlegame/appnames.ipa
2022-01-11 16:14:40:883 [Appium]     udid: 3daeb456020150a38ebd63a9a6b0393ed8ccb52e
2022-01-11 16:14:40:883 [Appium]     newCommandTimeout: 0
2022-01-11 16:14:40:884 [Appium]     xcodeOrgId: redacted
2022-01-11 16:14:40:884 [Appium]     xcodeSigningId: iPhone Developer
2022-01-11 16:14:40:884 [Appium]     wdaLocalPort: 4920
2022-01-11 16:14:40:885 [Appium]     webkitDebugProxyPort: 5020
2022-01-11 16:14:40:885 [Appium]     mjpegServerPort: 4820
2022-01-11 16:14:40:885 [Appium]     usePrebuiltWDA: true
2022-01-11 16:14:40:885 [Appium]     derivedDataPath: /usr/local/lib/node_modules/appium/node_modules/appium-webdriveragent/derived_data/iPhone_10
2022-01-11 16:14:40:886 [Appium]     noReset: true
2022-01-11 16:14:40:886 [Appium]     autoAcceptAlerts: true
2022-01-11 16:14:40:886 [Appium]     showXcodeLog: true
2022-01-11 16:14:40:887 [Appium]     launchWithIDB: true
2022-01-11 16:14:40:887 [Appium]   }
2022-01-11 16:14:40:887 [Appium]   relaxedSecurityEnabled: true
2022-01-11 16:14:40:888 [Appium] Deprecated server args:
2022-01-11 16:14:40:888 [Appium]   --async-trace => --default-capabilities
2022-01-11 16:14:40:889 [Appium] Default capabilities, which will be added to each request unless overridden by desired capabilities:
2022-01-11 16:14:40:889 [Appium]   platformName: iOS
2022-01-11 16:14:40:889 [Appium]   platformVersion: 13.4
2022-01-11 16:14:40:890 [Appium]   deviceName: iPhone_10
2022-01-11 16:14:40:890 [Appium]   app: /Users/gitlabrunner/builds/QSH2MAjg/0/Fedor/idlegame/appnames.ipa
2022-01-11 16:14:40:890 [Appium]   udid: 3daeb456020150a38ebd63a9a6b0393ed8ccb52e
2022-01-11 16:14:40:890 [Appium]   newCommandTimeout: 0
2022-01-11 16:14:40:891 [Appium]   xcodeOrgId: redacted
2022-01-11 16:14:40:891 [Appium]   xcodeSigningId: iPhone Developer
2022-01-11 16:14:40:891 [Appium]   wdaLocalPort: 4920
2022-01-11 16:14:40:898 [Appium]   webkitDebugProxyPort: 5020
2022-01-11 16:14:40:899 [Appium]   mjpegServerPort: 4820
2022-01-11 16:14:40:899 [Appium]   usePrebuiltWDA: true
2022-01-11 16:14:40:899 [Appium]   derivedDataPath: /usr/local/lib/node_modules/appium/node_modules/appium-webdriveragent/derived_data/iPhone_10
2022-01-11 16:14:40:900 [Appium]   noReset: true
2022-01-11 16:14:40:900 [Appium]   autoAcceptAlerts: true
2022-01-11 16:14:40:900 [Appium]   showXcodeLog: true
2022-01-11 16:14:40:900 [Appium]   launchWithIDB: true
2022-01-11 16:14:40:935 [Appium] Appium REST http interface listener started on 127.0.0.1:4720
2022-01-11 16:14:41:164 [HTTP] --> HEAD /wd/hub/status
2022-01-11 16:14:41:164 [HTTP] {}
2022-01-11 16:14:41:166 [GENERIC] Calling AppiumDriver.getStatus() with args: []
2022-01-11 16:14:41:166 [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.23.0-beta.0"}}
2022-01-11 16:14:41:172 [HTTP] <-- HEAD /wd/hub/status 200 6 ms - 75
2022-01-11 16:14:41:173 [HTTP] 
2022-01-11 16:14:41:213 [HTTP] Request idempotency key: 42478adf-86a5-43d4-bc37-798838d83fca
2022-01-11 16:14:41:227 [HTTP] --> POST /wd/hub/session
2022-01-11 16:14:41:228 [HTTP] {"capabilities":{"alwaysMatch":{"platformName":"iOS","appium:platformVersion":"13.4","appium:deviceName":"iPhone_10","appium:app":"/Users/gitlabrunner/builds/QSH2MAjg/0/Fedor/idlegame/appnames.ipa","appium:udid":"3daeb456020150a38ebd63a9a6b0393ed8ccb52e","appium:newCommandTimeout":0,"appium:xcodeOrgId":"redacted","appium:xcodeSigningId":"iPhone Developer","appium:wdaLocalPort":4920,"appium:webkitDebugProxyPort":5020,"appium:mjpegServerPort":4820,"appium:usePrebuiltWDA":true,"appium:derivedDataPath":"/usr/local/lib/node_modules/appium/node_modules/appium-webdriveragent/derived_data/iPhone_10","appium:noReset":true,"appium:autoAcceptAlerts":true,"appium:showXcodeLog":true,"appium:launchWithIDB":true},"firstMatch":[{}]},"desiredCapabilities":{"platformName":"iOS","platformVersion":"13.4","deviceName":"iPhone_10","app":"/Users/gitlabrunner/builds/QSH2MAjg/0/Fedor/idlegame/appnames.ipa","udid":"3daeb456020150a38ebd63a9a6b0393ed8ccb52e","newCommandTimeout":0,"xcodeOrgId":"redacted","xcodeSign...
2022-01-11 16:14:41:229 [W3C] Calling AppiumDriver.createSession() with args: [{"platformName":"iOS","platformVersion":"13.4","deviceName":"iPhone_10","app":"/Users/gitlabrunner/builds/QSH2MAjg/0/Fedor/idlegame/appnames.ipa","udid":"3daeb456020150a38ebd63a9a6b0393ed8ccb52e","newCommandTimeout":0,"xcodeOrgId":"redacted","xcodeSigningId":"iPhone Developer","wdaLocalPort":4920,"webkitDebugProxyPort":5020,"mjpegServerPort":4820,"usePrebuiltWDA":true,"derivedDataPath":"/usr/local/lib/node_modules/appium/node_modules/appium-webdriveragent/derived_data/iPhone_10","noReset":true,"autoAcceptAlerts":true,"showXcodeLog":true,"launchWithIDB":true},null,{"alwaysMatch":{"platformName":"iOS","appium:platformVersion":"13.4","appium:deviceName":"iPhone_10","appium:app":"/Users/gitlabrunner/builds/QSH2MAjg/0/Fedor/idlegame/appnames.ipa","appium:udid":"3daeb456020150a38ebd63a9a6b0393ed8ccb52e","appium:newCommandTimeout":0,"appium:xcodeOrgId":"redacted","appium:xcodeSigningId":"iPhone Developer","appium:wdaLocalPort":4920,"appium:webkitDebugProxyPort":5020,"appium:mjpegServerPort":4...
2022-01-11 16:14:41:231 [BaseDriver] Event 'newSessionRequested' logged at 1641917681229 (17:14:41 GMT+0100 (Central European Standard Time))
2022-01-11 16:14:41:239 [Appium] DeprecationWarning: 'automationName' capability was not provided. Future versions of Appium will require 'automationName' capability to be set for iOS sessions.
2022-01-11 16:14:41:240 [Appium] Requested iOS support with version >= 10, using 'XCUITest' driver instead of UIAutomation-based driver, since the latter is unsupported on iOS 10 and up.
2022-01-11 16:14:41:864 [Appium] Appium v1.23.0-beta.0 creating new XCUITestDriver (v3.59.1) session
2022-01-11 16:14:41:865 [Appium] There are no active sessions for cleanup
2022-01-11 16:14:41:866 [Appium] Applying relaxed security to 'XCUITestDriver' as per server command line argument. All insecure features will be enabled unless explicitly disabled by --deny-insecure
2022-01-11 16:14:41:867 [BaseDriver] W3C capabilities and MJSONWP desired capabilities were provided
2022-01-11 16:14:41:868 [BaseDriver] Creating session with W3C capabilities: {
2022-01-11 16:14:41:868 [BaseDriver]   "alwaysMatch": {
2022-01-11 16:14:41:868 [BaseDriver]     "platformName": "iOS",
2022-01-11 16:14:41:868 [BaseDriver]     "appium:platformVersion": "13.4",
2022-01-11 16:14:41:869 [BaseDriver]     "appium:deviceName": "iPhone_10",
2022-01-11 16:14:41:869 [BaseDriver]     "appium:app": "/Users/gitlabrunner/builds/QSH2MAjg/0/Fedor/idlegame/appnames.ipa",
2022-01-11 16:14:41:869 [BaseDriver]     "appium:udid": "3daeb456020150a38ebd63a9a6b0393ed8ccb52e",
2022-01-11 16:14:41:869 [BaseDriver]     "appium:newCommandTimeout": 0,
2022-01-11 16:14:41:870 [BaseDriver]     "appium:xcodeOrgId": "redacted",
2022-01-11 16:14:41:870 [BaseDriver]     "appium:xcodeSigningId": "iPhone Developer",
2022-01-11 16:14:41:870 [BaseDriver]     "appium:wdaLocalPort": 4920,
2022-01-11 16:14:41:870 [BaseDriver]     "appium:webkitDebugProxyPort": 5020,
2022-01-11 16:14:41:870 [BaseDriver]     "appium:mjpegServerPort": 4820,
2022-01-11 16:14:41:871 [BaseDriver]     "appium:usePrebuiltWDA": true,
2022-01-11 16:14:41:871 [BaseDriver]     "appium:derivedDataPath": "/usr/local/lib/node_modules/appium/node_modules/appium-webdriveragent/derived_data/iPhone_10",
2022-01-11 16:14:41:871 [BaseDriver]     "appium:noReset": true,
2022-01-11 16:14:41:871 [BaseDriver]     "appium:autoAcceptAlerts": true,
2022-01-11 16:14:41:872 [BaseDriver]     "appium:showXcodeLog": true,
2022-01-11 16:14:41:886 [BaseDriver]     "appium:launchWithIDB": true
2022-01-11 16:14:41:886 [BaseDriver]   },
2022-01-11 16:14:41:886 [BaseDriver]   "firstMatch": [
2022-01-11 16:14:41:886 [BaseDriver]     {}
2022-01-11 16:14:41:887 [BaseDriver]   ]
2022-01-11 16:14:41:887 [BaseDriver] }
2022-01-11 16:14:41:893 [BaseDriver] Session created with session id: 44ef589b-2e73-4d53-a39f-77dc8506182f
2022-01-11 16:14:41:938 [XCUITest] Current user: 'gitlabrunner'
2022-01-11 16:14:41:995 [XCUITest] Available devices: 2d7ed2ecd07a267c883226f7cc14bbf0f9688fc1, 8ad453447c097432a333122150ca802f7e924bae, bc0949990263122a45436ddc7e411df11a3792cb, d0b210c69c14eea08499c6cfd342706b264e0e0f, 62adb18587ca78a723cc68b82805547833e3b8b1, 6af8d529764e9e220b98293cdabcfbfb5073ef4c, 3daeb456020150a38ebd63a9a6b0393ed8ccb52e, 5ebd43abee62d503f97815254bdb5009fd24e0df
2022-01-11 16:14:41:995 [XCUITest] Creating iDevice object with udid '3daeb456020150a38ebd63a9a6b0393ed8ccb52e'
2022-01-11 16:14:41:996 [XCUITest] Determining device to run tests on: udid: '3daeb456020150a38ebd63a9a6b0393ed8ccb52e', real device: true
2022-01-11 16:14:42:088 [BaseDriver] Event 'xcodeDetailsRetrieved' logged at 1641917682087 (17:14:42 GMT+0100 (Central European Standard Time))
2022-01-11 16:14:42:090 [BaseDriver] Using local app '/Users/gitlabrunner/builds/QSH2MAjg/0/Fedor/idlegame/appnames.ipa'
2022-01-11 16:14:44:003 [BaseDriver] Unzipping '/Users/gitlabrunner/builds/QSH2MAjg/0/Fedor/idlegame/appnames.ipa'
2022-01-11 16:14:44:004 [BaseDriver] Enforcing UTF-8 encoding on the extracted file names for 'appnames.ipa'
2022-01-11 16:15:00:382 [BaseDriver] Extracted 1 bundle item from '/Users/gitlabrunner/builds/QSH2MAjg/0/Fedor/idlegame/appnames.ipa' in 16378ms: Payload/appnames.app
2022-01-11 16:15:00:383 [BaseDriver] Assuming 'Payload/appnames.app' is the correct bundle
2022-01-11 16:15:00:395 [BaseDriver] Unzipped local app to '/Users/gitlabrunner/Documents/temp/iPhone_10/2022011-25383-13tvec1.whyj/appnames.app'
2022-01-11 16:15:00:721 [BaseDriver] Event 'appConfigured' logged at 1641917700721 (17:15:00 GMT+0100 (Central European Standard Time))
2022-01-11 16:15:00:721 [XCUITest] Checking whether app '/Users/gitlabrunner/Documents/temp/iPhone_10/2022011-25383-13tvec1.whyj/appnames.app' is actually present on file system
2022-01-11 16:15:00:727 [XCUITest] App is present
2022-01-11 16:15:00:762 [XCUITest] Getting bundle ID from app '/Users/gitlabrunner/Documents/temp/iPhone_10/2022011-25383-13tvec1.whyj/appnames.app': 'com.companyname.appnames'
2022-01-11 16:15:00:762 [BaseDriver] Event 'resetStarted' logged at 1641917700762 (17:15:00 GMT+0100 (Central European Standard Time))
2022-01-11 16:15:00:763 [XCUITest] Reset: fullReset not set. Leaving as is
2022-01-11 16:15:00:763 [BaseDriver] Event 'resetComplete' logged at 1641917700763 (17:15:00 GMT+0100 (Central European Standard Time))
2022-01-11 16:15:00:765 [WebDriverAgent] Using WDA path: '/usr/local/lib/node_modules/appium/node_modules/appium-webdriveragent'
2022-01-11 16:15:00:765 [WebDriverAgent] Using WDA agent: '/usr/local/lib/node_modules/appium/node_modules/appium-webdriveragent/WebDriverAgent.xcodeproj'
2022-01-11 16:15:00:995 [XCUITest] Crash reports root '/Users/gitlabrunner/Library/Logs/CrashReporter/MobileDevice/iPhone_10' does not exist. Got nothing to gather.
2022-01-11 16:15:00:995 [BaseDriver] Event 'logCaptureStarted' logged at 1641917700995 (17:15:00 GMT+0100 (Central European Standard Time))
2022-01-11 16:15:00:996 [XCUITest] Setting up real device
2022-01-11 16:15:00:996 [XCUITest] Verifying application platform
2022-01-11 16:15:01:004 [XCUITest] CFBundleSupportedPlatforms: ["iPhoneOS"]
2022-01-11 16:15:01:302 [XCUITest] App 'com.companyname.appnames' is already installed. No need to reinstall.
2022-01-11 16:15:01:302 [BaseDriver] Event 'appInstalled' logged at 1641917701302 (17:15:01 GMT+0100 (Central European Standard Time))
2022-01-11 16:15:01:400 [WebDriverAgent] No obsolete cached processes from previous WDA sessions listening on port 4920 have been found
2022-01-11 16:15:01:401 [DevCon Factory] Requesting connection for device 3daeb456020150a38ebd63a9a6b0393ed8ccb52e on local port 4920, device port 4920
2022-01-11 16:15:01:402 [DevCon Factory] Cached connections count: 0
2022-01-11 16:15:01:407 [DevCon Factory] Successfully requested the connection for 3daeb456020150a38ebd63a9a6b0393ed8ccb52e:4920
2022-01-11 16:15:01:409 [XCUITest] Starting WebDriverAgent initialization with the synchronization key 'XCUITestDriver'
2022-01-11 16:15:01:415 [WD Proxy] Matched '/status' to command name 'getStatus'
2022-01-11 16:15:01:416 [WD Proxy] Proxying [GET /status] to [GET http://127.0.0.1:4920/status] with no body
2022-01-11 16:15:01:598 [WD Proxy] Got response with status 200: {"value":{"message":"WebDriverAgent is ready to accept commands","state":"success","os":{"testmanagerdVersion":28,"name":"iOS","sdkVersion":"15.2","version":"13.4"},"ios":{"ip":"192.168.3.144"},"ready":true,"build":{"upgradedAt":"1641866244882","time":"Jan 11 2022 03:01:44","productBundleIdentifier":"com.facebook.WebDriverAgentRunner"}},"sessionId":null}
2022-01-11 16:15:01:599 [WebDriverAgent] Upgrade timestamp of the currently bundled WDA: 1641866244882
2022-01-11 16:15:01:600 [WebDriverAgent] Upgrade timestamp of the WDA on the device: 1641866244882
2022-01-11 16:15:01:600 [WebDriverAgent] Will reuse previously cached WDA instance at 'http://127.0.0.1:4920/' with 'com.facebook.WebDriverAgentRunner'. Set the wdaLocalPort capability to a value different from 4920 if this is an undesired behavior.
2022-01-11 16:15:01:601 [XCUITest] Trying to start WebDriverAgent 1 times with 10000ms interval
2022-01-11 16:15:01:601 [XCUITest] These values can be customized by changing wdaStartupRetries/wdaStartupRetryInterval capabilities
2022-01-11 16:15:01:601 [BaseDriver] Event 'wdaStartAttempted' logged at 1641917701601 (17:15:01 GMT+0100 (Central European Standard Time))
2022-01-11 16:15:01:602 [WebDriverAgent] Using provided WebdriverAgent at 'http://127.0.0.1:4920/'
2022-01-11 16:15:01:603 [WD Proxy] Matched '/status' to command name 'getStatus'
2022-01-11 16:15:01:605 [WD Proxy] Proxying [GET /status] to [GET http://127.0.0.1:4920/status] with no body
2022-01-11 16:15:01:622 [WD Proxy] Got response with status 200: {"value":{"message":"WebDriverAgent is ready to accept commands","state":"success","os":{"testmanagerdVersion":28,"name":"iOS","sdkVersion":"15.2","version":"13.4"},"ios":{"ip":"192.168.3.144"},"ready":true,"build":{"upgradedAt":"1641866244882","time":"Jan 11 2022 03:01:44","productBundleIdentifier":"com.facebook.WebDriverAgentRunner"}},"sessionId":null}
2022-01-11 16:15:01:622 [BaseDriver] Event 'wdaSessionAttempted' logged at 1641917701622 (17:15:01 GMT+0100 (Central European Standard Time))
2022-01-11 16:15:01:623 [XCUITest] Sending createSession command to WDA
2022-01-11 16:15:01:624 [WD Proxy] Matched '/session' to command name 'createSession'
2022-01-11 16:15:01:624 [WD Proxy] Proxying [POST /session] to [POST http://127.0.0.1:4920/session] with body: {"capabilities":{"firstMatch":[{"bundleId":"com.companyname.appnames","arguments":[],"environment":{},"eventloopIdleDelaySec":0,"shouldWaitForQuiescence":true,"shouldUseTestManagerForVisibilityDetection":false,"maxTypingFrequency":60,"shouldUseSingletonTestManager":true,"shouldTerminateApp":false,"forceAppLaunch":false,"useNativeCachingStrategy":true,"forceSimulatorSoftwareKeyboardPresence":true,"defaultAlertAction":"accept"}],"alwaysMatch":{}}}
2022-01-11 16:15:01:849 [WD Proxy] Got response with status 200: {"value":{"sessionId":"035505F0-DE56-46B1-B7FE-BC92B98AE4FB","capabilities":{"device":"iphone","browserName":"Clash of Streamers","sdkVersion":"13.4","CFBundleIdentifier":"com.companyname.appnames"}},"sessionId":"035505F0-DE56-46B1-B7FE-BC92B98AE4FB"}
2022-01-11 16:15:01:850 [WD Proxy] Determined the downstream protocol as 'W3C'
2022-01-11 16:15:01:850 [BaseDriver] Event 'wdaSessionStarted' logged at 1641917701850 (17:15:01 GMT+0100 (Central European Standard Time))
2022-01-11 16:15:01:851 [BaseDriver] Event 'wdaStarted' logged at 1641917701850 (17:15:01 GMT+0100 (Central European Standard Time))
2022-01-11 16:15:01:851 [XCUITest] Skipping setting of the initial display orientation. Set the "orientation" capability to either "LANDSCAPE" or "PORTRAIT", if this is an undesired behavior.
2022-01-11 16:15:01:852 [BaseDriver] Event 'orientationSet' logged at 1641917701852 (17:15:01 GMT+0100 (Central European Standard Time))
2022-01-11 16:15:01:852 [BaseDriver] The value of 'elementResponseAttributes' setting did not change. Skipping the update for it
2022-01-11 16:15:01:853 [BaseDriver] The value of 'shouldUseCompactResponses' setting did not change. Skipping the update for it
2022-01-11 16:15:01:853 [Appium] New XCUITestDriver session created successfully, session 44ef589b-2e73-4d53-a39f-77dc8506182f added to master session list
2022-01-11 16:15:01:854 [BaseDriver] Event 'newSessionStarted' logged at 1641917701854 (17:15:01 GMT+0100 (Central European Standard Time))
2022-01-11 16:15:01:855 [W3C (44ef589b)] Cached the protocol value 'W3C' for the new session 44ef589b-2e73-4d53-a39f-77dc8506182f
2022-01-11 16:15:01:855 [W3C (44ef589b)] Responding to client with driver.createSession() result: {"capabilities":{"webStorageEnabled":false,"locationContextEnabled":false,"browserName":"","platform":"MAC","javascriptEnabled":true,"databaseEnabled":false,"takesScreenshot":true,"networkConnectionEnabled":false,"platformName":"iOS","platformVersion":"13.4","deviceName":"iPhone_10","app":"/Users/gitlabrunner/builds/QSH2MAjg/0/Fedor/idlegame/appnames.ipa","udid":"3daeb456020150a38ebd63a9a6b0393ed8ccb52e","newCommandTimeout":0,"xcodeOrgId":"redacted","xcodeSigningId":"iPhone Developer","wdaLocalPort":4920,"webkitDebugProxyPort":5020,"mjpegServerPort":4820,"usePrebuiltWDA":true,"derivedDataPath":"/usr/local/lib/node_modules/appium/node_modules/appium-webdriveragent/derived_data/iPhone_10","noReset":true,"autoAcceptAlerts":true,"showXcodeLog":true,"launchWithIDB":true}}
2022-01-11 16:15:01:857 [HTTP] <-- POST /wd/hub/session 200 20630 ms - 848
2022-01-11 16:15:01:862 [HTTP] 
2022-01-11 16:15:10:515 [HTTP] --> POST /wd/hub/session/44ef589b-2e73-4d53-a39f-77dc8506182f/appium/start_recording_screen
2022-01-11 16:15:10:516 [HTTP] {"options":{"timeLimit":600,"videoType":"h264","videoFps":24,"videoScale":"320:-2"}}
2022-01-11 16:15:10:522 [W3C (44ef589b)] Calling AppiumDriver.startRecordingScreen() with args: [{"timeLimit":600,"videoType":"h264","videoFps":24,"videoScale":"320:-2"},"44ef589b-2e73-4d53-a39f-77dc8506182f"]
2022-01-11 16:15:10:522 [XCUITest] Executing command 'startRecordingScreen'
2022-01-11 16:15:10:523 [XCUITest] Checking if there is/was a previous screen recording. Set 'forceRestart' option to 'true' if you'd like to skip this step.
2022-01-11 16:15:10:524 [XCUITest] Screen recording is not running. There is nothing to stop.
2022-01-11 16:15:10:528 [DevCon Factory] Releasing connections for 3daeb456020150a38ebd63a9a6b0393ed8ccb52e device on 4820 port number
2022-01-11 16:15:10:529 [DevCon Factory] No cached connections have been found
2022-01-11 16:15:10:541 [WD Proxy] Matched '/appium/settings' to command name 'getSettings'
2022-01-11 16:15:10:542 [WD Proxy] Proxying [GET /appium/settings] to [GET http://127.0.0.1:4920/session/035505F0-DE56-46B1-B7FE-BC92B98AE4FB/appium/settings] with no body
2022-01-11 16:15:10:549 [WD Proxy] Got response with status 200: {"value":{"boundElementsByIndex":false,"mjpegServerFramerate":30,"screenshotOrientation":"auto","reduceMotion":false,"elementResponseAttributes":"type,label","screenshotQuality":1,"mjpegScalingFactor":100,"keyboardPrediction":0,"defaultActiveApplication":"auto","mjpegServerScreenshotQuality":10,"defaultAlertAction":"accept","keyboardAutocorrection":0,"useFirstMatch":false,"shouldUseCompactResponses":true,"customSnapshotTimeout":15,"dismissAlertButtonSelector":"","activeAppDetectionPoint":"64.00,64.00","snapshotMaxDepth":50,"waitForIdleTimeout":10,"includeNonModalElements":false,"acceptAlertButtonSelector":"","animationCoolOffTimeout":2},"sessionId":"035505F0-DE56-46B1-B7FE-BC92B98AE4FB"}
2022-01-11 16:15:10:551 [WD Proxy] Matched '/appium/settings' to command name 'updateSettings'
2022-01-11 16:15:10:552 [WD Proxy] Proxying [POST /appium/settings] to [POST http://127.0.0.1:4920/session/035505F0-DE56-46B1-B7FE-BC92B98AE4FB/appium/settings] with body: {"settings":{"mjpegServerScreenshotQuality":25,"mjpegServerFramerate":24}}
2022-01-11 16:15:10:559 [WD Proxy] Got response with status 200: {"value":{"boundElementsByIndex":false,"mjpegServerFramerate":24,"screenshotOrientation":"auto","reduceMotion":false,"elementResponseAttributes":"type,label","screenshotQuality":1,"mjpegScalingFactor":100,"keyboardPrediction":0,"defaultActiveApplication":"auto","mjpegServerScreenshotQuality":25,"defaultAlertAction":"accept","keyboardAutocorrection":0,"useFirstMatch":false,"shouldUseCompactResponses":true,"customSnapshotTimeout":15,"dismissAlertButtonSelector":"","activeAppDetectionPoint":"64.00,64.00","snapshotMaxDepth":50,"waitForIdleTimeout":10,"includeNonModalElements":false,"acceptAlertButtonSelector":"","animationCoolOffTimeout":2},"sessionId":"035505F0-DE56-46B1-B7FE-BC92B98AE4FB"}
2022-01-11 16:15:10:564 [DevCon Factory] Requesting connection for device 3daeb456020150a38ebd63a9a6b0393ed8ccb52e on local port 4820, device port 4820
2022-01-11 16:15:10:565 [DevCon Factory] Cached connections count: 1
2022-01-11 16:15:10:567 [DevCon Factory] Successfully requested the connection for 3daeb456020150a38ebd63a9a6b0393ed8ccb52e:4820
2022-01-11 16:15:11:185 [ffmpeg] ffmpeg version 4.4.1 Copyright (c) 2000-2021 the FFmpeg developers
2022-01-11 16:15:11:185 [ffmpeg]   built with Apple clang version 13.0.0 (clang-1300.0.29.3)
2022-01-11 16:15:11:186 [ffmpeg]   configuration: --prefix=/usr/local/Cellar/ffmpeg/4.4.1_5 --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
2022-01-11 16:15:11:186 [ffmpeg] 
2022-01-11 16:15:11:190 [ffmpeg]   libavutil      56. 70.100 / 56. 70.100
2022-01-11 16:15:11:191 [ffmpeg]   libavcodec     58.134.100 / 58.134.100
2022-01-11 16:15:11:191 [ffmpeg]   libavformat    58. 76.100 / 58. 76.100
2022-01-11 16:15:11:191 [ffmpeg]   libavdevice    58. 13.100 / 58. 13.100
2022-01-11 16:15:11:192 [ffmpeg]   libavfilter     7.110.100 /  7.110.100
2022-01-11 16:15:11:192 [ffmpeg]   libavresample   4.  0.  0 /  4.  0.  0
2022-01-11 16:15:11:192 [ffmpeg]   libswscale      5.  9.100 /  5.  9.100
2022-01-11 16:15:11:193 [ffmpeg]   libswresample   3.  9.100 /  3.  9.100
2022-01-11 16:15:11:193 [ffmpeg]   libpostproc    55.  9.100 / 55.  9.100
2022-01-11 16:15:11:193 [ffmpeg] 
2022-01-11 16:15:11:322 [ffmpeg] Input #0, mjpeg, from 'http://127.0.0.1:4820':
2022-01-11 16:15:11:323 [ffmpeg]   Duration: N/A, bitrate: N/A
2022-01-11 16:15:11:323 [ffmpeg] 
2022-01-11 16:15:11:324 [ffmpeg]   Stream #0:0: Video: mjpeg (Baseline), yuvj420p(pc, bt470bg/unknown/unknown), 750x1334 [SAR 144:144 DAR 375:667], 25 tbr, 1200k tbn, 25 tbc
2022-01-11 16:15:11:324 [ffmpeg] Stream mapping:
2022-01-11 16:15:11:324 [ffmpeg]   Stream #0:0 -> #0:0 (mjpeg (native) -> h264 (libx264))
2022-01-11 16:15:11:325 [ffmpeg] Press [q] to stop, [?] for help
2022-01-11 16:15:11:325 [ffmpeg] 
2022-01-11 16:15:11:328 [ffmpeg] [swscaler @ 0x7f95488cb000] deprecated pixel format used, make sure you did set range correctly
2022-01-11 16:15:11:328 [ffmpeg] 
2022-01-11 16:15:11:338 [ffmpeg] [libx264 @ 0x7f954980e200] using SAR=1379/1377
2022-01-11 16:15:11:338 [ffmpeg] 
2022-01-11 16:15:11:339 [ffmpeg] [libx264 @ 0x7f954980e200] using cpu capabilities: MMX2 SSE2Fast SSSE3 SSE4.2 AVX FMA3 BMI2 AVX2
2022-01-11 16:15:11:340 [ffmpeg] 
2022-01-11 16:15:11:342 [ffmpeg] [libx264 @ 0x7f954980e200] profile High, level 2.1, 4:2:0, 8-bit
2022-01-11 16:15:11:343 [ffmpeg] 
2022-01-11 16:15:11:345 [ffmpeg] [libx264 @ 0x7f954980e200] 264 - core 163 r3060 5db6aa6 - H.264/MPEG-4 AVC codec - Copyleft 2003-2021 - http://www.videolan.org/x264.html - options: cabac=1 ref=3 deblock=1:0:0 analyse=0x3:0x113 me=hex subme=7 psy=1 psy_rd=1.00:0.00 mixed_ref=1 me_range=16 chroma_me=1 trellis=1 8x8dct=1 cqm=0 deadzone=21,11 fast_pskip=1 chroma_qp_offset=-2 threads=6 lookahead_threads=1 sliced_threads=0 nr=0 decimate=1 interlaced=0 bluray_compat=0 constrained_intra=0 bframes=3 b_pyramid=2 b_adapt=1 b_bias=0 direct=1 weightb=1 open_gop=0 weightp=2 keyint=250 keyint_min=25 scenecut=40 intra_refresh=0 rc_lookahead=40 rc=crf mbtree=1 crf=23.0 qcomp=0.60 qpmin=0 qpmax=69 qpstep=4 ip_ratio=1.40 aq=1:1.00
2022-01-11 16:15:11:345 [ffmpeg] 
2022-01-11 16:15:11:346 [ffmpeg] Output #0, mp4, to '/Users/gitlabrunner/Documents/temp/iPhone_10/2022011-25383-cphzwb.m0s6u/appium_2df5b0.mp4':
2022-01-11 16:15:11:346 [ffmpeg]   Metadata:
2022-01-11 16:15:11:346 [ffmpeg]     encoder         : Lavf58.76.100
2022-01-11 16:15:11:347 [ffmpeg]   Stream #0:0: Video: h264 (avc1 / 0x31637661), yuvj420p(pc, bt470bg/unknown/unknown, progressive), 320x570 [SAR 21375:21344 DAR 375:667], q=2-31, 25 fps, 12800 tbn
2022-01-11 16:15:11:347 [ffmpeg]     Metadata:
2022-01-11 16:15:11:347 [ffmpeg]       encoder         : Lavc58.134.100 libx264
2022-01-11 16:15:11:347 [ffmpeg]     Side data:
2022-01-11 16:15:11:348 [ffmpeg]       cpb: bitrate max/min/avg: 0/0/0 buffer size: 0 vbv_delay: N/A
2022-01-11 16:15:11:348 [ffmpeg] 
2022-01-11 16:15:11:501 [XCUITest] Starting screen capture on the device '3daeb456020150a38ebd63a9a6b0393ed8ccb52e' with command: 'ffmpeg -f mjpeg -reconnect 1 -reconnect_at_eof 1 -reconnect_streamed 1 -reconnect_delay_max 601 -i http://127.0.0.1:4820 -vf scale=320:-2 -vcodec h264 -y /Users/gitlabrunner/Documents/temp/iPhone_10/2022011-25383-cphzwb.m0s6u/appium_2df5b0.mp4'. Will timeout in 600000ms
2022-01-11 16:15:11:502 [W3C (44ef589b)] Responding to client with driver.startRecordingScreen() result: ""
2022-01-11 16:15:11:503 [HTTP] <-- POST /wd/hub/session/44ef589b-2e73-4d53-a39f-77dc8506182f/appium/start_recording_screen 200 987 ms - 12
2022-01-11 16:15:11:504 [HTTP] 
2022-01-11 16:15:11:513 [HTTP] --> 2022-01-11 16:25:10:833 [HTTP] 
2022-01-11 16:25:11:639 [HTTP] --> POST /wd/hub/session/44ef589b-2e73-4d53-a39f-77dc8506182f/appium/stop_recording_screen
2022-01-11 16:25:11:640 [HTTP] {"options":{"output":"Airtest/main/tests/cos/temp/temp_test/log/recording_0.mp4"}}
2022-01-11 16:25:11:640 [W3C (44ef589b)] Calling AppiumDriver.stopRecordingScreen() with args: [{"output":"Airtest/main/tests/cos/temp/temp_test/log/recording_0.mp4"},"44ef589b-2e73-4d53-a39f-77dc8506182f"]
2022-01-11 16:25:11:641 [XCUITest] Executing command 'stopRecordingScreen'
2022-01-11 16:25:11:655 [DevCon Factory] Releasing connections for 3daeb456020150a38ebd63a9a6b0393ed8ccb52e device on 4820 port number
2022-01-11 16:25:11:656 [DevCon Factory] Found cached connections to release: ["3daeb456020150a38ebd63a9a6b0393ed8ccb52e:4820"]
2022-01-11 16:25:11:656 [DevCon Factory] Releasing the listener for '3daeb456020150a38ebd63a9a6b0393ed8ccb52e:4820'
2022-01-11 16:25:11:657 [iProxy@3daeb456:4820] Closing the connection
2022-01-11 16:25:11:658 [DevCon Factory] Cached connections count: 1
2022-01-11 16:25:11:660 [XCUITest] The size of the file is 3.25 MB
2022-01-11 16:25:11:743 [DevCon Factory] Releasing connections for 3daeb456020150a38ebd63a9a6b0393ed8ccb52e device on 4820 port number
2022-01-11 16:25:11:744 [DevCon Factory] No cached connections have been found
2022-01-11 16:25:12:050 [W3C (44ef589b)] Responding to client with driver.stopRecordingScreen() result: "AAAAIGZ0eXBpc29tAAACAGlzb21pc28yYXZjMW1wNDEAAAAIZnJlZQAAAABtZGF0AAACrgYF//+q3EXpvebZSLeWLNgg2SPu73gyNjQgLSBjb3JlIDE2MyByMzA2MCA1ZGI2YWE2IC0gSC4yNjQvTVBFRy00IEFWQyBjb2RlYyAtIENvcHlsZWZ0IDIwMDMtMjAyMSAtIGh0dHA6Ly93d3cudmlkZW9sYW4ub3JnL3gyNjQuaHRtbCAtIG9wdGlvbnM6IGNhYmFjPTEgcmVmPTMgZGVibG9jaz0xOjA6MCBhbmFseXNlPTB4MzoweDExMyBtZT1oZXggc3VibWU9NyBwc3k9MSBwc3lfcmQ9MS4wMDowLjAwIG1peGVkX3JlZj0xIG1lX3JhbmdlPTE2IGNocm9tYV9tZT0xIHRyZWxsaXM9MSA4eDhkY3Q9MSBjcW09MCBkZWFkem9uZT0yMSwxMSBmYXN0X3Bza2lwPTEgY2hyb21hX3FwX29mZnNldD0tMiB0aHJlYWRzPTYgbG9va2FoZWFkX3RocmVhZHM9MSBzbGljZWRfdGhyZWFkcz0wIG5yPTAgZGVjaW1hdGU9MSBpbnRlcmxhY2VkPTAgYmx1cmF5X2NvbXBhdD0wIGNvbnN0cmFpbmVkX2ludHJhPTAgYmZyYW1lcz0zIGJfcHlyYW1pZD0yIGJfYWRhcHQ9MSBiX2JpYXM9MCBkaXJlY3Q9MSB3ZWlnaHRiPTEgb3Blbl9nb3A9MCB3ZWlnaHRwPTIga2V5aW50PTI1MCBrZXlpbnRfbWluPTI1IHNjZW5lY3V0PTQwIGludHJhX3JlZnJlc2g9MCByY19sb29rYWhlYWQ9NDAgcmM9Y3JmIG1idHJlZT0xIGNyZj0yMy4wIHFjb21wPTAuNjAgcXBtaW49MCBxcG1heD02OSBxcHN0ZXA9NCBpcF9yYXRpbz0xLjQwIGFxPTE6MS4wMACAAABk0GWIhABfqNhszkZPmOxvDg0bNSjp9XNv...
2022-01-11 16:25:12:514 [iProxy@3daeb456:4820] write EPIPE
2022-01-11 16:25:12:517 [iProxy@3daeb456:4820] The connection has been closed
2022-01-11 16:25:12:531 [HTTP] <-- POST /wd/hub/session/44ef589b-2e73-4d53-a39f-77dc8506182f/appium/stop_recording_screen 200 680 ms - 4543908
2022-01-11 16:25:12:531 [HTTP] 
2022-01-11 16:25:12:532 [DevCon Factory] Releasing connections for 3daeb456020150a38ebd63a9a6b0393ed8ccb52e device on 4820 port number
2022-01-11 16:25:12:533 [DevCon Factory] No cached connections have been found
2022-01-11 16:25:12:583 [HTTP] --> POST /wd/hub/session/44ef589b-2e73-4d53-a39f-77dc8506182f/log
2022-01-11 16:25:12:584 [HTTP] {"type":"syslog"}
2022-01-11 16:25:12:584 [W3C (44ef589b)] Calling AppiumDriver.getLog() with args: ["syslog","44ef589b-2e73-4d53-a39f-77dc8506182f"]
2022-01-11 16:25:12:585 [XCUITest] Executing command 'getLog'
2022-01-11 16:25:12:585 [BaseDriver] Retrieving 'syslog' logs
2022-01-11 16:25:12:586 [BaseDriver] Retrieving supported log types
2022-01-11 16:25:12:845 [W3C (44ef589b)] Responding to client with driver.getLog() result: [{"timestamp":1641918246697,"level":"ALL","message":"Jan 11 17:24:06 iPhone-10 testmanagerd[359] <Notice>: Preparing screenshot for <private>"},{"timestamp":1641918246700,"level":"ALL","message":"Jan 11 17:24:06 iPhone-10 testmanagerd[359] <Notice>: Got screenshot, rotating to orientation 2"},{"timestamp":1641918246713,"level":"ALL","message":"Jan 11 17:24:06 iPhone-10 DTServiceHub(DVTInstrumentsFoundation)[222] <Notice>: Heartbeat"},{"timestamp":1641918246715,"level":"ALL","message":"Jan 11 17:24:06 iPhone-10 testmanagerd[359] <Notice>: Replying with data 0x11911ccc0 (50453 bytes)"},{"timestamp":1641918246743,"level":"ALL","message":"Jan 11 17:24:06 iPhone-10 testmanagerd[359] <Notice>: Got system UI orientation (<private>[kAXApplicationOrientationAttribute]) 3"},{"timestamp":1641918246743,"level":"ALL","message":"Jan 11 17:24:06 iPhone-10 testmanagerd[359] <Notice>: Test session <XCTestSession: 0x11920ba90> requested screenshot with parameters Screenshot request for screenID 1, rect {{inf, inf}, {0.0, 0....
2022-01-11 16:25:12:878 [HTTP] <-- POST /wd/hub/session/44ef589b-2e73-4d53-a39f-77dc8506182f/log 200 291 ms - 1908121
2022-01-11 16:25:12:892 [HTTP] 
2022-01-11 16:25:13:008 [HTTP] --> DELETE /wd/hub/session/44ef589b-2e73-4d53-a39f-77dc8506182f
2022-01-11 16:25:13:009 [HTTP] {}
2022-01-11 16:25:13:009 [W3C (44ef589b)] Calling AppiumDriver.deleteSession() with args: ["44ef589b-2e73-4d53-a39f-77dc8506182f"]
2022-01-11 16:25:13:010 [BaseDriver] Event 'quitSessionRequested' logged at 1641918313010 (17:25:13 GMT+0100 (Central European Standard Time))
2022-01-11 16:25:13:011 [Appium] Removing session 44ef589b-2e73-4d53-a39f-77dc8506182f from our master session list
2022-01-11 16:25:13:012 [WD Proxy] Matched '/session/44ef589b-2e73-4d53-a39f-77dc8506182f' to command name 'deleteSession'
2022-01-11 16:25:13:013 [WD Proxy] Proxying [DELETE /session/44ef589b-2e73-4d53-a39f-77dc8506182f] to [DELETE http://127.0.0.1:4920/session/035505F0-DE56-46B1-B7FE-BC92B98AE4FB] with no body
2022-01-11 16:25:13:025 [WD Proxy] Got response with status 200: {"value":null,"sessionId":null}
2022-01-11 16:25:13:026 [DevCon Factory] Releasing connections for 3daeb456020150a38ebd63a9a6b0393ed8ccb52e device on any port number
2022-01-11 16:25:13:026 [DevCon Factory] Found cached connections to release: ["3daeb456020150a38ebd63a9a6b0393ed8ccb52e:4920"]
2022-01-11 16:25:13:026 [DevCon Factory] Releasing the listener for '3daeb456020150a38ebd63a9a6b0393ed8ccb52e:4920'
2022-01-11 16:25:13:027 [iProxy@3daeb456:4920] Closing the connection
2022-01-11 16:25:13:028 [DevCon Factory] Cached connections count: 0
2022-01-11 16:25:13:028 [XCUITest] Not clearing log files. Use `clearSystemFiles` capability to turn on.
2022-01-11 16:25:13:029 [BaseDriver] Event 'quitSessionFinished' logged at 1641918313029 (17:25:13 GMT+0100 (Central European Standard Time))
2022-01-11 16:25:13:030 [W3C (44ef589b)] Received response: null
2022-01-11 16:25:13:030 [W3C (44ef589b)] But deleting session, so not returning
2022-01-11 16:25:13:030 [W3C (44ef589b)] Responding to client with driver.deleteSession() result: null
2022-01-11 16:25:13:032 [HTTP] <-- DELETE /wd/hub/session/44ef589b-2e73-4d53-a39f-77dc8506182f 200 23 ms - 14
2022-01-11 16:25:13:033 [HTTP] 
2022-01-11 16:25:28:177 [Appium] Received SIGTERM - shutting down