log2.txt

2022-01-11 00:39:41:514 [Appium] Welcome to Appium v1.22.2
2022-01-11 00:39:41:516 [Appium] Non-default server args:
2022-01-11 00:39:41:517 [Appium]   shell: true
2022-01-11 00:39:41:517 [Appium]   address: 127.0.0.1
2022-01-11 00:39:41:518 [Appium]   port: 4727
2022-01-11 00:39:41:529 [Appium]   sessionOverride: true
2022-01-11 00:39:41:530 [Appium]   logFile: /Users/gitlabrunner/Documents/logs/iPhone_07/AppiumServerLogs
2022-01-11 00:39:41:530 [Appium]   logTimestamp: true
2022-01-11 00:39:41:531 [Appium]   robotPort: 5127
2022-01-11 00:39:41:531 [Appium]   tmpDir: /Users/gitlabrunner/Documents/temp/iPhone_07/
2022-01-11 00:39:41:532 [Appium]   debugLogSpacing: true
2022-01-11 00:39:41:532 [Appium]   longStacktrace: true
2022-01-11 00:39:41:532 [Appium]   defaultCapabilities: {
2022-01-11 00:39:41:533 [Appium]     platformName: iOS
2022-01-11 00:39:41:533 [Appium]     platformVersion: 15.2
2022-01-11 00:39:41:533 [Appium]     deviceName: iPhone_07
2022-01-11 00:39:41:534 [Appium]     app: /Users/gitlabrunner/builds/Uc7axEVr/0/Fedor/idlegame/appname.ipa
2022-01-11 00:39:41:534 [Appium]     bundleId: com.companyname.appname
2022-01-11 00:39:41:534 [Appium]     udid: 62adb18587ca78a723cc68b82805547833e3b8b1
2022-01-11 00:39:41:535 [Appium]     newCommandTimeout: 0
2022-01-11 00:39:41:535 [Appium]     xcodeOrgId: redacted
2022-01-11 00:39:41:535 [Appium]     xcodeSigningId: iPhone Developer
2022-01-11 00:39:41:535 [Appium]     wdaLocalPort: 4927
2022-01-11 00:39:41:536 [Appium]     webkitDebugProxyPort: 5027
2022-01-11 00:39:41:536 [Appium]     mjpegServerPort: 4827
2022-01-11 00:39:41:536 [Appium]     usePrebuiltWDA: true
2022-01-11 00:39:41:536 [Appium]     derivedDataPath: /usr/local/lib/node_modules/appium/node_modules/appium-webdriveragent/derived_data/iPhone_07
2022-01-11 00:39:41:536 [Appium]     noReset: true
2022-01-11 00:39:41:536 [Appium]     autoAcceptAlerts: true
2022-01-11 00:39:41:537 [Appium]     showXcodeLog: true
2022-01-11 00:39:41:537 [Appium]   }
2022-01-11 00:39:41:537 [Appium]   relaxedSecurityEnabled: true
2022-01-11 00:39:41:538 [Appium] Deprecated server args:
2022-01-11 00:39:41:538 [Appium]   --async-trace => --default-capabilities
2022-01-11 00:39:41:538 [Appium] Default capabilities, which will be added to each request unless overridden by desired capabilities:
2022-01-11 00:39:41:539 [Appium]   platformName: iOS
2022-01-11 00:39:41:539 [Appium]   platformVersion: 15.2
2022-01-11 00:39:41:539 [Appium]   deviceName: iPhone_07
2022-01-11 00:39:41:549 [Appium]   app: /Users/gitlabrunner/builds/Uc7axEVr/0/Fedor/idlegame/appname.ipa
2022-01-11 00:39:41:549 [Appium]   bundleId: com.companyname.appname
2022-01-11 00:39:41:549 [Appium]   udid: 62adb18587ca78a723cc68b82805547833e3b8b1
2022-01-11 00:39:41:550 [Appium]   newCommandTimeout: 0
2022-01-11 00:39:41:550 [Appium]   xcodeOrgId: redacted
2022-01-11 00:39:41:550 [Appium]   xcodeSigningId: iPhone Developer
2022-01-11 00:39:41:550 [Appium]   wdaLocalPort: 4927
2022-01-11 00:39:41:550 [Appium]   webkitDebugProxyPort: 5027
2022-01-11 00:39:41:551 [Appium]   mjpegServerPort: 4827
2022-01-11 00:39:41:551 [Appium]   usePrebuiltWDA: true
2022-01-11 00:39:41:551 [Appium]   derivedDataPath: /usr/local/lib/node_modules/appium/node_modules/appium-webdriveragent/derived_data/iPhone_07
2022-01-11 00:39:41:551 [Appium]   noReset: true
2022-01-11 00:39:41:551 [Appium]   autoAcceptAlerts: true
2022-01-11 00:39:41:552 [Appium]   showXcodeLog: true
2022-01-11 00:39:41:576 [Appium] Appium REST http interface listener started on 127.0.0.1:4727
2022-01-11 00:39:42:166 [HTTP] --> HEAD /wd/hub/status
2022-01-11 00:39:42:166 [HTTP] {}
2022-01-11 00:39:42:167 [GENERIC] Calling AppiumDriver.getStatus() with args: []
2022-01-11 00:39:42:168 [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.22.2"}}
2022-01-11 00:39:42:176 [HTTP] <-- HEAD /wd/hub/status 200 6 ms - 68
2022-01-11 00:39:42:176 [HTTP] 
2022-01-11 00:39:42:193 [HTTP] Request idempotency key: bfa6f13d-b4d4-42a7-9f88-7c2cc07d6876
2022-01-11 00:39:42:210 [HTTP] --> POST /wd/hub/session
2022-01-11 00:39:42:210 [HTTP] {"capabilities":{"alwaysMatch":{"platformName":"iOS","appium:platformVersion":"15.2","appium:deviceName":"iPhone_07","appium:app":"/Users/gitlabrunner/builds/Uc7axEVr/0/Fedor/idlegame/appname.ipa","appium:bundleId":"com.companyname.appname","appium:udid":"62adb18587ca78a723cc68b82805547833e3b8b1","appium:newCommandTimeout":0,"appium:xcodeOrgId":"redacted","appium:xcodeSigningId":"iPhone Developer","appium:wdaLocalPort":4927,"appium:webkitDebugProxyPort":5027,"appium:mjpegServerPort":4827,"appium:usePrebuiltWDA":true,"appium:derivedDataPath":"/usr/local/lib/node_modules/appium/node_modules/appium-webdriveragent/derived_data/iPhone_07","appium:noReset":true,"appium:autoAcceptAlerts":true,"appium:showXcodeLog":true},"firstMatch":[{}]},"desiredCapabilities":{"platformName":"iOS","platformVersion":"15.2","deviceName":"iPhone_07","app":"/Users/gitlabrunner/builds/Uc7axEVr/0/Fedor/idlegame/appname.ipa","bundleId":"com.companyname.appname","udid":"62adb18587ca78a723cc68b82...
2022-01-11 00:39:42:211 [W3C] Calling AppiumDriver.createSession() with args: [{"platformName":"iOS","platformVersion":"15.2","deviceName":"iPhone_07","app":"/Users/gitlabrunner/builds/Uc7axEVr/0/Fedor/idlegame/appname.ipa","bundleId":"com.companyname.appname","udid":"62adb18587ca78a723cc68b82805547833e3b8b1","newCommandTimeout":0,"xcodeOrgId":"redacted","xcodeSigningId":"iPhone Developer","wdaLocalPort":4927,"webkitDebugProxyPort":5027,"mjpegServerPort":4827,"usePrebuiltWDA":true,"derivedDataPath":"/usr/local/lib/node_modules/appium/node_modules/appium-webdriveragent/derived_data/iPhone_07","noReset":true,"autoAcceptAlerts":true,"showXcodeLog":true},null,{"alwaysMatch":{"platformName":"iOS","appium:platformVersion":"15.2","appium:deviceName":"iPhone_07","appium:app":"/Users/gitlabrunner/builds/Uc7axEVr/0/Fedor/idlegame/appname.ipa","appium:bundleId":"com.companyname.appname","appium:udid":"62adb18587ca78a723cc68b82805547833e3b8b1","appium:newCommandTimeout":0,"appium:xcodeOrgId":"redacted","appium:xcodeSigningId":"iPhone Developer","appiu...
2022-01-11 00:39:42:213 [BaseDriver] Event 'newSessionRequested' logged at 1641861582211 (01:39:42 GMT+0100 (Central European Standard Time))
2022-01-11 00:39:42:220 [Appium] DeprecationWarning: 'automationName' capability was not provided. Future versions of Appium will require 'automationName' capability to be set for iOS sessions.
2022-01-11 00:39:42:221 [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 00:39:43:351 [Appium] Appium v1.22.2 creating new XCUITestDriver (v3.59.0) session
2022-01-11 00:39:43:351 [Appium] There are no active sessions for cleanup
2022-01-11 00:39:43:353 [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 00:39:43:354 [BaseDriver] W3C capabilities and MJSONWP desired capabilities were provided
2022-01-11 00:39:43:354 [BaseDriver] Creating session with W3C capabilities: {
2022-01-11 00:39:43:355 [BaseDriver]   "alwaysMatch": {
2022-01-11 00:39:43:355 [BaseDriver]     "platformName": "iOS",
2022-01-11 00:39:43:355 [BaseDriver]     "appium:platformVersion": "15.2",
2022-01-11 00:39:43:355 [BaseDriver]     "appium:deviceName": "iPhone_07",
2022-01-11 00:39:43:355 [BaseDriver]     "appium:app": "/Users/gitlabrunner/builds/Uc7axEVr/0/Fedor/idlegame/appname.ipa",
2022-01-11 00:39:43:355 [BaseDriver]     "appium:bundleId": "com.companyname.appname",
2022-01-11 00:39:43:356 [BaseDriver]     "appium:udid": "62adb18587ca78a723cc68b82805547833e3b8b1",
2022-01-11 00:39:43:356 [BaseDriver]     "appium:newCommandTimeout": 0,
2022-01-11 00:39:43:356 [BaseDriver]     "appium:xcodeOrgId": "redacted",
2022-01-11 00:39:43:356 [BaseDriver]     "appium:xcodeSigningId": "iPhone Developer",
2022-01-11 00:39:43:356 [BaseDriver]     "appium:wdaLocalPort": 4927,
2022-01-11 00:39:43:357 [BaseDriver]     "appium:webkitDebugProxyPort": 5027,
2022-01-11 00:39:43:357 [BaseDriver]     "appium:mjpegServerPort": 4827,
2022-01-11 00:39:43:357 [BaseDriver]     "appium:usePrebuiltWDA": true,
2022-01-11 00:39:43:357 [BaseDriver]     "appium:derivedDataPath": "/usr/local/lib/node_modules/appium/node_modules/appium-webdriveragent/derived_data/iPhone_07",
2022-01-11 00:39:43:357 [BaseDriver]     "appium:noReset": true,
2022-01-11 00:39:43:357 [BaseDriver]     "appium:autoAcceptAlerts": true,
2022-01-11 00:39:43:358 [BaseDriver]     "appium:showXcodeLog": true
2022-01-11 00:39:43:358 [BaseDriver]   },
2022-01-11 00:39:43:358 [BaseDriver]   "firstMatch": [
2022-01-11 00:39:43:358 [BaseDriver]     {}
2022-01-11 00:39:43:359 [BaseDriver]   ]
2022-01-11 00:39:43:359 [BaseDriver] }
2022-01-11 00:39:43:390 [BaseDriver] Session created with session id: 4e1ec175-a2d4-4f6d-9e50-e94ce9646002
2022-01-11 00:39:43:459 [XCUITest] Current user: 'gitlabrunner'
2022-01-11 00:39:43:499 [XCUITest] Available devices: 2d7ed2ecd07a267c883226f7cc14bbf0f9688fc1, bc0949990263122a45436ddc7e411df11a3792cb, 6af8d529764e9e220b98293cdabcfbfb5073ef4c, 8ad453447c097432a333122150ca802f7e924bae, 3daeb456020150a38ebd63a9a6b0393ed8ccb52e, 5ebd43abee62d503f97815254bdb5009fd24e0df, 62adb18587ca78a723cc68b82805547833e3b8b1, d0b210c69c14eea08499c6cfd342706b264e0e0f
2022-01-11 00:39:43:499 [XCUITest] Creating iDevice object with udid '62adb18587ca78a723cc68b82805547833e3b8b1'
2022-01-11 00:39:43:500 [XCUITest] Determining device to run tests on: udid: '62adb18587ca78a723cc68b82805547833e3b8b1', real device: true
2022-01-11 00:39:43:632 [BaseDriver] Event 'xcodeDetailsRetrieved' logged at 1641861583627 (01:39:43 GMT+0100 (Central European Standard Time))
2022-01-11 00:39:43:652 [BaseDriver] Using local app '/Users/gitlabrunner/builds/Uc7axEVr/0/Fedor/idlegame/appname.ipa'
2022-01-11 00:39:45:516 [BaseDriver] Unzipping '/Users/gitlabrunner/builds/Uc7axEVr/0/Fedor/idlegame/appname.ipa'
2022-01-11 00:39:45:517 [BaseDriver] Enforcing UTF-8 encoding on the extracted file names for 'appname.ipa'
2022-01-11 00:39:58:763 [BaseDriver] Extracted 1 bundle item from '/Users/gitlabrunner/builds/Uc7axEVr/0/Fedor/idlegame/appname.ipa' in 13236ms: Payload/appname.app
2022-01-11 00:39:58:764 [BaseDriver] Assuming 'Payload/appname.app' is the correct bundle
2022-01-11 00:39:58:773 [BaseDriver] Unzipped local app to '/Users/gitlabrunner/Documents/temp/iPhone_07/2022011-73636-28mnir.wl8e5/appname.app'
2022-01-11 00:39:58:774 [BaseDriver] Event 'appConfigured' logged at 1641861598774 (01:39:58 GMT+0100 (Central European Standard Time))
2022-01-11 00:39:58:774 [XCUITest] Checking whether app '/Users/gitlabrunner/Documents/temp/iPhone_07/2022011-73636-28mnir.wl8e5/appname.app' is actually present on file system
2022-01-11 00:39:58:775 [XCUITest] App is present
2022-01-11 00:39:58:775 [BaseDriver] Event 'resetStarted' logged at 1641861598775 (01:39:58 GMT+0100 (Central European Standard Time))
2022-01-11 00:39:58:776 [XCUITest] Reset: fullReset not set. Leaving as is
2022-01-11 00:39:58:776 [BaseDriver] Event 'resetComplete' logged at 1641861598776 (01:39:58 GMT+0100 (Central European Standard Time))
2022-01-11 00:39:58:777 [WebDriverAgent] Using WDA path: '/usr/local/lib/node_modules/appium/node_modules/appium-webdriveragent'
2022-01-11 00:39:58:777 [WebDriverAgent] Using WDA agent: '/usr/local/lib/node_modules/appium/node_modules/appium-webdriveragent/WebDriverAgent.xcodeproj'
2022-01-11 00:39:59:041 [XCUITest] Crash reports root '/Users/gitlabrunner/Library/Logs/CrashReporter/MobileDevice/iPhone_07' does not exist. Got nothing to gather.
2022-01-11 00:39:59:042 [BaseDriver] Event 'logCaptureStarted' logged at 1641861599041 (01:39:59 GMT+0100 (Central European Standard Time))
2022-01-11 00:39:59:042 [XCUITest] Setting up real device
2022-01-11 00:39:59:042 [XCUITest] Verifying application platform
2022-01-11 00:39:59:051 [XCUITest] CFBundleSupportedPlatforms: ["iPhoneOS"]
2022-01-11 00:39:59:329 [XCUITest] App 'com.companyname.appname' is already installed. No need to reinstall.
2022-01-11 00:39:59:329 [BaseDriver] Event 'appInstalled' logged at 1641861599329 (01:39:59 GMT+0100 (Central European Standard Time))
2022-01-11 00:39:59:440 [WebDriverAgent] No obsolete cached processes from previous WDA sessions listening on port 4927 have been found
2022-01-11 00:39:59:440 [DevCon Factory] Requesting connection for device 62adb18587ca78a723cc68b82805547833e3b8b1 on local port 4927, device port 4927
2022-01-11 00:39:59:441 [DevCon Factory] Cached connections count: 0
2022-01-11 00:39:59:444 [DevCon Factory] Successfully requested the connection for 62adb18587ca78a723cc68b82805547833e3b8b1:4927
2022-01-11 00:39:59:447 [XCUITest] Starting WebDriverAgent initialization with the synchronization key 'XCUITestDriver'
2022-01-11 00:39:59:449 [WD Proxy] Matched '/status' to command name 'getStatus'
2022-01-11 00:39:59:450 [WD Proxy] Proxying [GET /status] to [GET http://127.0.0.1:4927/status] with no body
2022-01-11 00:39:59:474 [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":"15.2"},"ios":{"ip":"169.254.23.68"},"ready":true,"build":{"upgradedAt":"1641488548805","time":"Jan 10 2022 02:49:55","productBundleIdentifier":"com.facebook.WebDriverAgentRunner"}},"sessionId":"DBB4BFBA-E28F-46F5-A550-D6E2615ED9AC"}
2022-01-11 00:39:59:475 [WebDriverAgent] Upgrade timestamp of the currently bundled WDA: 1641488548805
2022-01-11 00:39:59:475 [WebDriverAgent] Upgrade timestamp of the WDA on the device: 1641488548805
2022-01-11 00:39:59:476 [WebDriverAgent] Will reuse previously cached WDA instance at 'http://127.0.0.1:4927/' with 'com.facebook.WebDriverAgentRunner'. Set the wdaLocalPort capability to a value different from 4927 if this is an undesired behavior.
2022-01-11 00:39:59:476 [XCUITest] Trying to start WebDriverAgent 1 times with 10000ms interval
2022-01-11 00:39:59:476 [XCUITest] These values can be customized by changing wdaStartupRetries/wdaStartupRetryInterval capabilities
2022-01-11 00:39:59:477 [BaseDriver] Event 'wdaStartAttempted' logged at 1641861599476 (01:39:59 GMT+0100 (Central European Standard Time))
2022-01-11 00:39:59:477 [WebDriverAgent] Using provided WebdriverAgent at 'http://127.0.0.1:4927/'
2022-01-11 00:39:59:478 [WD Proxy] Matched '/status' to command name 'getStatus'
2022-01-11 00:39:59:478 [WD Proxy] Proxying [GET /status] to [GET http://127.0.0.1:4927/status] with no body
2022-01-11 00:39:59:488 [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":"15.2"},"ios":{"ip":"169.254.23.68"},"ready":true,"build":{"upgradedAt":"1641488548805","time":"Jan 10 2022 02:49:55","productBundleIdentifier":"com.facebook.WebDriverAgentRunner"}},"sessionId":"DBB4BFBA-E28F-46F5-A550-D6E2615ED9AC"}
2022-01-11 00:39:59:489 [BaseDriver] Event 'wdaSessionAttempted' logged at 1641861599489 (01:39:59 GMT+0100 (Central European Standard Time))
2022-01-11 00:39:59:489 [XCUITest] Sending createSession command to WDA
2022-01-11 00:39:59:490 [WD Proxy] Matched '/session' to command name 'createSession'
2022-01-11 00:39:59:491 [WD Proxy] Proxying [POST /session] to [POST http://127.0.0.1:4927/session] with body: {"capabilities":{"firstMatch":[{"bundleId":"com.companyname.appname","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 00:39:59:581 [WD Proxy] Got response with status 200: {"value":{"sessionId":"EB03B938-5367-4E68-99FC-9C77EE453905","capabilities":{"device":"iphone","browserName":"","sdkVersion":"15.2","CFBundleIdentifier":"com.companyname.appname"}},"sessionId":"EB03B938-5367-4E68-99FC-9C77EE453905"}
2022-01-11 00:39:59:581 [WD Proxy] Determined the downstream protocol as 'W3C'
2022-01-11 00:39:59:582 [BaseDriver] Event 'wdaSessionStarted' logged at 1641861599581 (01:39:59 GMT+0100 (Central European Standard Time))
2022-01-11 00:39:59:582 [BaseDriver] Event 'wdaStarted' logged at 1641861599582 (01:39:59 GMT+0100 (Central European Standard Time))
2022-01-11 00:39:59:583 [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 00:39:59:583 [BaseDriver] Event 'orientationSet' logged at 1641861599583 (01:39:59 GMT+0100 (Central European Standard Time))
2022-01-11 00:39:59:583 [BaseDriver] The value of 'elementResponseAttributes' setting did not change. Skipping the update for it
2022-01-11 00:39:59:583 [BaseDriver] The value of 'shouldUseCompactResponses' setting did not change. Skipping the update for it
2022-01-11 00:39:59:584 [Appium] New XCUITestDriver session created successfully, session 4e1ec175-a2d4-4f6d-9e50-e94ce9646002 added to master session list
2022-01-11 00:39:59:585 [BaseDriver] Event 'newSessionStarted' logged at 1641861599585 (01:39:59 GMT+0100 (Central European Standard Time))
2022-01-11 00:39:59:585 [W3C (4e1ec175)] Cached the protocol value 'W3C' for the new session 4e1ec175-a2d4-4f6d-9e50-e94ce9646002
2022-01-11 00:39:59:586 [W3C (4e1ec175)] 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":"15.2","deviceName":"iPhone_07","app":"/Users/gitlabrunner/builds/Uc7axEVr/0/Fedor/idlegame/appname.ipa","bundleId":"com.companyname.appname","udid":"62adb18587ca78a723cc68b82805547833e3b8b1","newCommandTimeout":0,"xcodeOrgId":"redacted","xcodeSigningId":"iPhone Developer","wdaLocalPort":4927,"webkitDebugProxyPort":5027,"mjpegServerPort":4827,"usePrebuiltWDA":true,"derivedDataPath":"/usr/local/lib/node_modules/appium/node_modules/appium-webdriveragent/derived_data/iPhone_07","noReset":true,"autoAcceptAlerts":true,"showXcodeLog":true}}
2022-01-11 00:39:59:588 [HTTP] <-- POST /wd/hub/session 200 17378 ms - 875
2022-01-11 00:39:59:588 [HTTP] 
2022-01-11 00:40:06:114 [HTTP] --> POST /wd/hub/session/4e1ec175-a2d4-4f6d-9e50-e94ce9646002/appium/start_recording_screen
2022-01-11 00:40:06:114 [HTTP] {"options":{"forceRestart":true,"timeLimit":1800,"videoType":"hevc","videoQuality":"low","videoFilters":"transpose=2","videoFps":30}}
2022-01-11 00:40:06:120 [W3C (4e1ec175)] Calling AppiumDriver.startRecordingScreen() with args: [{"forceRestart":true,"timeLimit":1800,"videoType":"hevc","videoQuality":"low","videoFilters":"transpose=2","videoFps":30},"4e1ec175-a2d4-4f6d-9e50-e94ce9646002"]
2022-01-11 00:40:06:121 [XCUITest] Executing command 'startRecordingScreen'
2022-01-11 00:40:06:122 [DevCon Factory] Releasing connections for 62adb18587ca78a723cc68b82805547833e3b8b1 device on 4827 port number
2022-01-11 00:40:06:123 [DevCon Factory] No cached connections have been found
2022-01-11 00:40:06:132 [WD Proxy] Matched '/appium/settings' to command name 'getSettings'
2022-01-11 00:40:06:133 [WD Proxy] Proxying [GET /appium/settings] to [GET http://127.0.0.1:4927/session/EB03B938-5367-4E68-99FC-9C77EE453905/appium/settings] with no body
2022-01-11 00:40:06:146 [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":"EB03B938-5367-4E68-99FC-9C77EE453905"}
2022-01-11 00:40:06:148 [DevCon Factory] Requesting connection for device 62adb18587ca78a723cc68b82805547833e3b8b1 on local port 4827, device port 4827
2022-01-11 00:40:06:148 [DevCon Factory] Cached connections count: 1
2022-01-11 00:40:06:150 [DevCon Factory] Successfully requested the connection for 62adb18587ca78a723cc68b82805547833e3b8b1:4827
2022-01-11 00:40:06:396 [ffmpeg] ffmpeg version 2.8.18 Copyright (c) 2000-2021 the FFmpeg developers
2022-01-11 00:40:06:396 [ffmpeg]   built with Apple clang version 13.0.0 (clang-1300.0.29.3)
2022-01-11 00:40:06:397 [ffmpeg]   configuration: --prefix='/usr/local/Cellar/ffmpeg@2.8/2.8.18' --enable-shared --enable-pthreads --enable-gpl --enable-version3 --enable-hardcoded-tables --enable-avresample --cc=clang --host-cflags= --host-ldflags= --enable-ffplay --enable-libmp3lame --enable-libopus --enable-libsnappy --enable-libtheora --enable-libvo-aacenc --enable-libvorbis --enable-libvpx --enable-libx264 --enable-libx265 --enable-libxvid --enable-libfontconfig --enable-libfreetype --enable-frei0r --enable-libass --enable-libopencore-amrnb --enable-libopencore-amrwb --enable-librtmp --enable-libspeex --enable-opencl --disable-indev=jack --disable-libxcb --disable-xlib --enable-vda
2022-01-11 00:40:06:397 [ffmpeg] 
2022-01-11 00:40:06:398 [ffmpeg]   libavutil      54. 31.100 / 54. 31.100
2022-01-11 00:40:06:399 [ffmpeg]   libavcodec     56. 60.100 / 56. 60.100
2022-01-11 00:40:06:399 [ffmpeg]   libavformat    56. 40.101 / 56. 40.101
2022-01-11 00:40:06:399 [ffmpeg]   libavdevice    56.  4.100 / 56.  4.100
2022-01-11 00:40:06:399 [ffmpeg]   libavfilter     5. 40.101 /  5. 40.101
2022-01-11 00:40:06:400 [ffmpeg]   libavresample   2.  1.  0 /  2.  1.  0
2022-01-11 00:40:06:400 [ffmpeg]   libswscale      3.  1.101 /  3.  1.101
2022-01-11 00:40:06:400 [ffmpeg]   libswresample   1.  2.101 /  1.  2.101
2022-01-11 00:40:06:400 [ffmpeg]   libpostproc    53.  3.100 / 53.  3.100
2022-01-11 00:40:06:400 [ffmpeg] 
2022-01-11 00:40:06:507 [ffmpeg] [mjpeg @ 0x7fbe08010200] Changeing bps to 8
2022-01-11 00:40:06:507 [ffmpeg] 
2022-01-11 00:40:06:511 [ffmpeg] Input #0, mjpeg, from 'http://127.0.0.1:4827':
2022-01-11 00:40:06:511 [ffmpeg]   Duration: N/A, bitrate: N/A
2022-01-11 00:40:06:511 [ffmpeg]     Stream #0:0: Video: mjpeg, yuvj420p(pc, bt470bg/unknown/unknown), 750x1334 [SAR 72:72 DAR 375:667], 25 tbr, 1200k tbn, 25 tbc
2022-01-11 00:40:06:512 [ffmpeg] 
2022-01-11 00:40:06:517 [ffmpeg] [swscaler @ 0x7fbe074a9000] deprecated pixel format used, make sure you did set range correctly
2022-01-11 00:40:06:517 [ffmpeg] 
2022-01-11 00:40:06:518 [ffmpeg] x265 [info]: HEVC encoder version 3.4+31-6722fce1f
2022-01-11 00:40:06:518 [ffmpeg] x265 [info]: build info [Mac OS X][clang 13.0.0][64 bit] 8bit+10bit+12bit
2022-01-11 00:40:06:518 [ffmpeg] 
2022-01-11 00:40:06:521 [ffmpeg] x265 [info]: using cpu capabilities: MMX2 SSE2Fast LZCNT SSSE3 SSE4.2 AVX FMA3 BMI2 AVX2
2022-01-11 00:40:06:521 [ffmpeg] x265 [info]: Main profile, Level-4 (Main tier)
2022-01-11 00:40:06:521 [ffmpeg] x265 [info]: Thread pool created using 4 threads
2022-01-11 00:40:06:521 [ffmpeg] x265 [info]: Slices                              : 1
2022-01-11 00:40:06:522 [ffmpeg] x265 [info]: frame threads / pool features       : 2 / wpp(12 rows)
2022-01-11 00:40:06:522 [ffmpeg] 
2022-01-11 00:40:06:525 [ffmpeg] x265 [info]: Coding QT: max CU size, min CU size : 64 / 8
2022-01-11 00:40:06:525 [ffmpeg] x265 [info]: Residual QT: max TU size, max depth : 32 / 1 inter / 1 intra
2022-01-11 00:40:06:525 [ffmpeg] x265 [info]: ME / range / subpel / merge         : hex / 57 / 2 / 3
2022-01-11 00:40:06:526 [ffmpeg] x265 [info]: Keyframe min / max / scenecut / bias  : 25 / 250 / 40 / 5.00 
2022-01-11 00:40:06:526 [ffmpeg] x265 [info]: Lookahead / bframes / badapt        : 20 / 4 / 2
2022-01-11 00:40:06:526 [ffmpeg] x265 [info]: b-pyramid / weightp / weightb       : 1 / 1 / 0
2022-01-11 00:40:06:526 [ffmpeg] x265 [info]: References / ref-limit  cu / depth  : 3 / off / on
2022-01-11 00:40:06:526 [ffmpeg] x265 [info]: AQ: mode / str / qg-size / cu-tree  : 2 / 1.0 / 32 / 1
2022-01-11 00:40:06:527 [ffmpeg] x265 [info]: Rate Control / qCompress            : CRF-28.0 / 0.60
2022-01-11 00:40:06:527 [ffmpeg] x265 [info]: tools: rd=3 psy-rd=2.00 early-skip rskip mode=1 signhide tmvp
2022-01-11 00:40:06:527 [ffmpeg] x265 [info]: tools: b-intra strong-intra-smoothing lslices=4 deblock sao
2022-01-11 00:40:06:527 [ffmpeg] 
2022-01-11 00:40:06:528 [ffmpeg] Output #0, mp4, to '/Users/gitlabrunner/Documents/temp/iPhone_07/2022011-73636-1e1ae1t.wztz/appium_63610e.mp4':
2022-01-11 00:40:06:528 [ffmpeg]   Metadata:
2022-01-11 00:40:06:529 [ffmpeg]     encoder         : Lavf56.40.101
2022-01-11 00:40:06:529 [ffmpeg]     Stream #0:0: Video: hevc (libx265) ([35][0][0][0] / 0x0023), yuv420p, 1334x750 [SAR 1:1 DAR 667:375], q=2-31, 25 fps, 12800 tbn, 25 tbc
2022-01-11 00:40:06:530 [ffmpeg]     Metadata:
2022-01-11 00:40:06:530 [ffmpeg]       encoder         : Lavc56.60.100 libx265
2022-01-11 00:40:06:530 [ffmpeg] Stream mapping:
2022-01-11 00:40:06:530 [ffmpeg]   Stream #0:0 -> #0:0 (mjpeg (native) -> hevc (libx265))
2022-01-11 00:40:06:530 [ffmpeg] Press [q] to stop, [?] for help
2022-01-11 00:40:06:531 [ffmpeg] 
2022-01-11 00:40:07:368 [XCUITest] Starting screen capture on the device '62adb18587ca78a723cc68b82805547833e3b8b1' with command: 'ffmpeg -f mjpeg -i http://127.0.0.1:4827 -vf transpose=2 -vcodec hevc -y /Users/gitlabrunner/Documents/temp/iPhone_07/2022011-73636-1e1ae1t.wztz/appium_63610e.mp4'. Will timeout in 1800000ms
2022-01-11 00:40:07:369 [W3C (4e1ec175)] Responding to client with driver.startRecordingScreen() result: ""
2022-01-11 00:40:07:370 [HTTP] <-- POST /wd/hub/session/4e1ec175-a2d4-4f6d-9e50-e94ce9646002/appium/start_recording_screen 200 1256 ms - 12
2022-01-11 00:40:07:371 [HTTP] 
2022-01-11 00:50:07:506 [HTTP] --> POST /wd/hub/session/4e1ec175-a2d4-4f6d-9e50-e94ce9646002/appium/stop_recording_screen
2022-01-11 00:50:07:507 [HTTP] {"options":{"output":"Airtest/main/tests/cos/temp/temp_test/log/recording_0.mp4"}}
2022-01-11 00:50:07:518 [W3C (4e1ec175)] Calling AppiumDriver.stopRecordingScreen() with args: [{"output":"Airtest/main/tests/cos/temp/temp_test/log/recording_0.mp4"},"4e1ec175-a2d4-4f6d-9e50-e94ce9646002"]
2022-01-11 00:50:07:519 [XCUITest] Executing command 'stopRecordingScreen'
2022-01-11 00:50:08:894 [iProxy@62adb185:4827] write EPIPE
2022-01-11 00:50:08:895 [ffmpeg] Exiting normally, received signal 2.
2022-01-11 00:50:08:896 [ffmpeg] 
2022-01-11 00:50:08:904 [DevCon Factory] Releasing connections for 62adb18587ca78a723cc68b82805547833e3b8b1 device on 4827 port number
2022-01-11 00:50:08:907 [DevCon Factory] Found cached connections to release: ["62adb18587ca78a723cc68b82805547833e3b8b1:4827"]
2022-01-11 00:50:08:911 [DevCon Factory] Releasing the listener for '62adb18587ca78a723cc68b82805547833e3b8b1:4827'
2022-01-11 00:50:08:912 [iProxy@62adb185:4827] Closing the connection
2022-01-11 00:50:08:912 [DevCon Factory] Cached connections count: 1
2022-01-11 00:50:08:913 [iProxy@62adb185:4827] The connection has been closed
2022-01-11 00:50:08:915 [XCUITest] The size of the file is 3.70 MB
2022-01-11 00:50:09:086 [DevCon Factory] Releasing connections for 62adb18587ca78a723cc68b82805547833e3b8b1 device on 4827 port number
2022-01-11 00:50:09:087 [DevCon Factory] No cached connections have been found
2022-01-11 00:50:09:244 [W3C (4e1ec175)] Responding to client with driver.stopRecordingScreen() result: "AAAAHGZ0eXBpc29tAAACAGlzb21pc28ybXA0MQAAAAhmcmVlADnYnG1kYXQAAMAwKAGvHRg1na1Peyzb5NuwvnhYGP8tykuM98/IaNknX5DqNLrKkpQZmEad5xDr4A5rbFI55xDxIWEueKSexogpZW0b2cEaMn/YD47VToBZQQ80lzV8RBTXLG3WN02zJvjzeXKKBkNfLcRPONji9VAyTP+yDn+KjU5Gr/8xBX1mB8ZK9u8WxifCuw/iwjUUYqMARWZ2r8XflWvW7beaT46sQCiwPVRTrVGVcRLRTUhNV8GaKDOjOPUQuINiQdnFuj/HS9o8kDJGrXe3eqodSJdpmL1d8ILy6V4mMMZNVbRvDtNNmGsPiR2d6/Ef71as6Fe2pl3nhe0n/0vGfh9cvHxlNRK3WDBt6l3xH2YBmWImJU8IIjJbS7QNJqSa9WNqsgmqyY9Zmu6gKk27N/14lw4vfI/9KiAaxjYTeupknN2w8aOVR0Nwf+syOsfnXMQQspMVatAEhoZWVsmg5D/C4DBmZpPsNvlsYXnt7XW4lr/LGnzxRqcTeqYEEW4bVShXv53X2hEEGscJldBhBgk5QMSHfQ9BoYFsCw7oDrnoOP6Ei8AnFVbmOGWE3+2NBkn824VZCJ5a1XFXKF7F8NyDVsm4bQvITt0N5Z3qOk4y0K+rKq3iIWqJmW5aZ+vNjQndk8UIqJhZj9z+38Y3ebszpnqvBSFrl/NL1Bfnl7YumlzsGe4TMSPC4r7rg2Zq3vdFIzCqnbW972zKku84M4Or0G+uWs7I/383N7mWDBbFE0LP8Sa57eflKa0UBFHC4EOKXVIylkzDeWlhBK9D+57Kyc1vES8yOf8Q11MLuAcq0y97V9BH0MqiWMRmRRsCEvBfJRkC63U+6FIH+j9xXgRDbtuB+GE4JiPNbs1peogc1zMvCsw59gmIm0KBJF9UPRnDxujOYu0YExRnTXXrBPTheTSmhDPFi/gHd+oZzutyBnZPEEygbJ0igujw0RoJ9qH/...
2022-01-11 00:50:09:323 [HTTP] <-- POST /wd/hub/session/4e1ec175-a2d4-4f6d-9e50-e94ce9646002/appium/stop_recording_screen 200 1813 ms - 5167548
2022-01-11 00:50:09:324 [HTTP] 
2022-01-11 00:50:09:370 [HTTP] --> POST /wd/hub/session/4e1ec175-a2d4-4f6d-9e50-e94ce9646002/log
2022-01-11 00:50:09:370 [HTTP] {"type":"syslog"}
2022-01-11 00:50:09:370 [W3C (4e1ec175)] Calling AppiumDriver.getLog() with args: ["syslog","4e1ec175-a2d4-4f6d-9e50-e94ce9646002"]
2022-01-11 00:50:09:371 [XCUITest] Executing command 'getLog'
2022-01-11 00:50:09:371 [BaseDriver] Retrieving 'syslog' logs
2022-01-11 00:50:09:372 [BaseDriver] Retrieving supported log types
2022-01-11 00:50:09:510 [W3C (4e1ec175)] Responding to client with driver.getLog() result: [{"timestamp":1641862174300,"level":"ALL","message":"Jan 11 01:49:34 iPhone-07 nsurlsessiond[707] <Notice>: NDSession <14E5FA58-7EE7-4C5B-B2B1-79DF3669CB3D> Task <7A3EC578-3C2E-4EB9-B649-D4104F73D20B>.<1> enqueueing"},{"timestamp":1641862174300,"level":"ALL","message":"Jan 11 01:49:34 iPhone-07 cloudd(C2)[735] <Notice>: [<C2Session: 0x102e95ca0> addTask:<C2SessionTask: 0x102f56b80> withDescription:CKDFetchDatabaseChangesURLRequest requestUUID:89F2FD7C-C2ED-4DD5-98BF-4C58F07D3D98 request:<NSMutableURLRequest: 0x102e85b90> { URL: https://gateway.icloud.com:443/ckdatabase/api/client/zone/sync }]"},{"timestamp":1641862174300,"level":"ALL","message":"Jan 11 01:49:34 iPhone-07 cloudd(C2)[735] <Notice>: [<C2SessionTask: 0x102f56b80> setTask:BackgroundDataTask <7A3EC578-3C2E-4EB9-B649-D4104F73D20B>.<1>]"},{"timestamp":1641862174301,"level":"ALL","message":"Jan 11 01:49:34 iPhone-07 cloudd(C2)[735] <Notice>: created task (CKDFetchDatabaseChangesURLRequest requestUUID:89F2FD7C-C2ED-4DD5-98BF-4C58F07D3D98). nsurlsess...
2022-01-11 00:50:09:557 [HTTP] <-- POST /wd/hub/session/4e1ec175-a2d4-4f6d-9e50-e94ce9646002/log 200 186 ms - 2256421
2022-01-11 00:50:09:557 [HTTP] 
2022-01-11 00:50:09:630 [HTTP] --> POST /wd/hub/session/4e1ec175-a2d4-4f6d-9e50-e94ce9646002/execute/sync
2022-01-11 00:50:09:631 [HTTP] {"script":"mobile: pressButton","args":[{"name":"Home"}]}
2022-01-11 00:50:09:634 [W3C (4e1ec175)] Calling AppiumDriver.execute() with args: ["mobile: pressButton",[{"name":"Home"}],"4e1ec175-a2d4-4f6d-9e50-e94ce9646002"]
2022-01-11 00:50:09:634 [XCUITest] Executing command 'execute'
2022-01-11 00:50:09:644 [XCUITest] Proxying to WDA with an unknown route: POST /wda/pressButton
2022-01-11 00:50:09:646 [WD Proxy] Proxying [POST /wda/pressButton] to [POST http://127.0.0.1:4927/session/EB03B938-5367-4E68-99FC-9C77EE453905/wda/pressButton] with body: {"name":"Home"}
2022-01-11 00:50:09:849 [WD Proxy] Got response with status 200: {"value":null,"sessionId":"EB03B938-5367-4E68-99FC-9C77EE453905"}
2022-01-11 00:50:09:855 [W3C (4e1ec175)] Responding to client with driver.execute() result: null
2022-01-11 00:50:09:857 [HTTP] <-- POST /wd/hub/session/4e1ec175-a2d4-4f6d-9e50-e94ce9646002/execute/sync 200 225 ms - 14
2022-01-11 00:50:09:857 [HTTP] 
2022-01-11 00:50:09:864 [HTTP] --> POST /wd/hub/session/4e1ec175-a2d4-4f6d-9e50-e94ce9646002/appium/device/lock
2022-01-11 00:50:09:865 [HTTP] {}
2022-01-11 00:50:09:865 [W3C (4e1ec175)] Calling AppiumDriver.lock() with args: [null,"4e1ec175-a2d4-4f6d-9e50-e94ce9646002"]
2022-01-11 00:50:09:866 [XCUITest] Executing command 'lock'
2022-01-11 00:50:09:867 [WD Proxy] Proxying [POST /wda/lock] to [POST http://127.0.0.1:4927/session/EB03B938-5367-4E68-99FC-9C77EE453905/wda/lock] with no body
2022-01-11 00:50:13:710 [WD Proxy] Got response with status 200: {"value":null,"sessionId":"EB03B938-5367-4E68-99FC-9C77EE453905"}
2022-01-11 00:50:13:711 [W3C (4e1ec175)] Responding to client with driver.lock() result: null
2022-01-11 00:50:13:713 [HTTP] <-- POST /wd/hub/session/4e1ec175-a2d4-4f6d-9e50-e94ce9646002/appium/device/lock 200 3848 ms - 14
2022-01-11 00:50:13:714 [HTTP] 
2022-01-11 00:50:13:715 [HTTP] --> DELETE /wd/hub/session/4e1ec175-a2d4-4f6d-9e50-e94ce9646002
2022-01-11 00:50:13:715 [HTTP] {}
2022-01-11 00:50:13:715 [W3C (4e1ec175)] Calling AppiumDriver.deleteSession() with args: ["4e1ec175-a2d4-4f6d-9e50-e94ce9646002"]
2022-01-11 00:50:13:715 [BaseDriver] Event 'quitSessionRequested' logged at 1641862213715 (01:50:13 GMT+0100 (Central European Standard Time))
2022-01-11 00:50:13:717 [Appium] Removing session 4e1ec175-a2d4-4f6d-9e50-e94ce9646002 from our master session list
2022-01-11 00:50:13:750 [WD Proxy] Matched '/session/4e1ec175-a2d4-4f6d-9e50-e94ce9646002' to command name 'deleteSession'
2022-01-11 00:50:13:751 [WD Proxy] Proxying [DELETE /session/4e1ec175-a2d4-4f6d-9e50-e94ce9646002] to [DELETE http://127.0.0.1:4927/session/EB03B938-5367-4E68-99FC-9C77EE453905] with no body
2022-01-11 00:50:13:864 [WD Proxy] Got response with status 200: {"value":null,"sessionId":null}
2022-01-11 00:50:13:864 [DevCon Factory] Releasing connections for 62adb18587ca78a723cc68b82805547833e3b8b1 device on any port number
2022-01-11 00:50:13:865 [DevCon Factory] Found cached connections to release: ["62adb18587ca78a723cc68b82805547833e3b8b1:4927"]
2022-01-11 00:50:13:865 [DevCon Factory] Releasing the listener for '62adb18587ca78a723cc68b82805547833e3b8b1:4927'
2022-01-11 00:50:13:865 [iProxy@62adb185:4927] Closing the connection
2022-01-11 00:50:13:866 [DevCon Factory] Cached connections count: 0
2022-01-11 00:50:13:867 [XCUITest] Not clearing log files. Use `clearSystemFiles` capability to turn on.
2022-01-11 00:50:13:868 [BaseDriver] Event 'quitSessionFinished' logged at 1641862213868 (01:50:13 GMT+0100 (Central European Standard Time))
2022-01-11 00:50:13:869 [W3C (4e1ec175)] Received response: null
2022-01-11 00:50:13:869 [W3C (4e1ec175)] But deleting session, so not returning
2022-01-11 00:50:13:869 [W3C (4e1ec175)] Responding to client with driver.deleteSession() result: null
2022-01-11 00:50:13:873 [HTTP] <-- DELETE /wd/hub/session/4e1ec175-a2d4-4f6d-9e50-e94ce9646002 200 156 ms - 14
2022-01-11 00:50:13:873 [HTTP] 
2022-01-11 00:50:28:979 [Appium] Received SIGTERM - shutting down