Skip to content

Instantly share code, notes, and snippets.

@presidenten

presidenten/appium server log

Created October 25, 2019 15:07
Show Gist options
  • Save presidenten/d38794895b64b6cf565e1833236b6845 to your computer and use it in GitHub Desktop.
Save presidenten/d38794895b64b6cf565e1833236b6845 to your computer and use it in GitHub Desktop.
Download ZIP
Testrun appium 1.16.0-beta.1 vs ios13
Raw
appium server log
~/selenium $ ./appium/beta/appium -a "<removed-from-log>" --nodeconfig ~/selenium/ios/iphone3.json --session-override --allow-cors --webkit-debug-proxy-port 5662 -p 5562 --relaxed-security
[Appium] Welcome to Appium v1.16.0-beta.1
[Appium] Non-default server args:
[Appium] allowCors: true
[Appium] address: <removed-from-log>
[Appium] port: 5562
[Appium] sessionOverride: true
[Appium] nodeconfig: <removed-from-log>/ios/iphone3.json
[Appium] webkitDebugProxyPort: 5662
[Appium] relaxedSecurityEnabled: true
[Appium] You have enabled CORS requests from any host. Be careful not to visit sites which could maliciously try to start Appium sessions on your machine
[debug] [Appium] Starting auto register thread for grid. Will try to register every 5000 ms.
[Appium] Appium REST http interface listener started on <removed-from-log>:5562
[HTTP] --> GET /wd/hub/status
[HTTP] {}
[debug] [GENERIC] Calling AppiumDriver.getStatus() with args: []
[debug] [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.16.0-beta.1"}}
[HTTP] <-- GET /wd/hub/status 200 16 ms - 75
[HTTP]
[debug] [Appium] Appium successfully registered with the grid on http://chat-selenium.upp.telia.se:4444
[HTTP] --> POST /wd/hub/session
[HTTP] {"desiredCapabilities":{"appium:deviceType":"phone","server:CONFIG_UUID":"6a9e8092-9be2-4feb-acc2-dc96096b8d90","appium:udid":"<removed-from-log>","appium:wdaLocalPort":5562,"browserVersion":"13.1-iphone3","browserName":"safari","appium:deviceName":"iphone","platformName":"ios","appium:platformVersion":"13.1"},"capabilities":{"firstMatch":[{"appium:deviceName":"iphone","appium:deviceType":"phone","appium:platformVersion":"13.1","appium:udid":"<removed-from-log>","appium:wdaLocalPort":5562,"browserName":"safari","browserVersion":"13.1-iphone3","platformName":"ios","server:CONFIG_UUID":"6a9e8092-9be2-4feb-acc2-dc96096b8d90"}]}}
[debug] [W3C] Calling AppiumDriver.createSession() with args: [{"appium:deviceType":"phone","server:CONFIG_UUID":"6a9e8092-9be2-4feb-acc2-dc96096b8d90","appium:udid":"<removed-from-log>","appium:wdaLocalPort":5562,"browserVersion":"13.1-iphone3","browserName":"safari","appium:deviceName":"iphone","platformName":"ios","appium:platformVersion":"13.1"},null,{"firstMatch":[{"appium:deviceName":"iphone","appium:deviceType":"phone","appium:platformVersion":"13.1","appium:udid":"<removed-from-log>","appium:wdaLocalPort":5562,"browserName":"safari","browserVersion":"13.1-iphone3","platformName":"ios","server:CONFIG_UUID":"6a9e8092-9be2-4feb-acc2-dc96096b8d90"}]}]
[debug] [BaseDriver] Event 'newSessionRequested' logged at 1572015675396 (17:01:15 GMT+0200 (Central European Summer Time))
[Appium] DeprecationWarning: 'automationName' capability was not provided. Future versions of Appium will require 'automationName' capability to be set for iOS sessions.
[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.
[Appium] Appium v1.16.0-beta.1 creating new XCUITestDriver (v3.3.0) session
[Appium] Applying relaxed security to 'XCUITestDriver' as per server command line argument. All insecure features will be enabled unless explicitly disabled by --deny-insecure
[debug] [BaseDriver] W3C capabilities and MJSONWP desired capabilities were provided
[debug] [BaseDriver] Creating session with W3C capabilities: {
[debug] [BaseDriver] "alwaysMatch": {
[debug] [BaseDriver] "browserName": "safari",
[debug] [BaseDriver] "browserVersion": "13.1-iphone3",
[debug] [BaseDriver] "platformName": "ios",
[debug] [BaseDriver] "server:CONFIG_UUID": "6a9e8092-9be2-4feb-acc2-dc96096b8d90",
[debug] [BaseDriver] "appium:deviceName": "iphone",
[debug] [BaseDriver] "appium:deviceType": "phone",
[debug] [BaseDriver] "appium:platformVersion": "13.1",
[debug] [BaseDriver] "appium:udid": "<removed-from-log>",
[debug] [BaseDriver] "appium:wdaLocalPort": 5562
[debug] [BaseDriver] },
[debug] [BaseDriver] "firstMatch": [
[debug] [BaseDriver] {}
[debug] [BaseDriver] ]
[debug] [BaseDriver] }
[BaseDriver] The following capabilities were provided, but are not recognized by Appium:
[BaseDriver] browserVersion
[BaseDriver] server:CONFIG_UUID
[BaseDriver] deviceType
[BaseDriver] Session created with session id: 3d3476a6-b768-4b42-93d6-afafbb7bd034
[debug] [XCUITest] Current user: 'callguide'
[debug] [XCUITest] Available devices: <removed-from-log>, <removed-from-log>, <removed-from-log>, <removed-from-log>, <removed-from-log>, <removed-from-log>
[debug] [XCUITest] Creating iDevice object with udid '<removed-from-log>'
[XCUITest] Determining device to run tests on: udid: '<removed-from-log>', real device: true
[debug] [BaseDriver] Event 'xcodeDetailsRetrieved' logged at 1572015675887 (17:01:15 GMT+0200 (Central European Summer Time))
[XCUITest] Safari test requested
[debug] [BaseDriver] Event 'appConfigured' logged at 1572015675888 (17:01:15 GMT+0200 (Central European Summer Time))
[debug] [BaseDriver] Event 'resetStarted' logged at 1572015675888 (17:01:15 GMT+0200 (Central European Summer Time))
[debug] [XCUITest] Reset: running ios real device reset flow
[debug] [BaseDriver] Event 'resetComplete' logged at 1572015675889 (17:01:15 GMT+0200 (Central European Summer Time))
[debug] [XCUITest] Crash reports root '/Users/callguide/Library/Logs/CrashReporter/MobileDevice/iPhone 8 #3' does not exist. Got nothing to gather.
[debug] [BaseDriver] Event 'logCaptureStarted' logged at 1572015676182 (17:01:16 GMT+0200 (Central European Summer Time))
[XCUITest] Setting up real device
[RemoteDebugger] Remote Debugger version 6.3.0
[debug] [RemoteDebugger] useNewSafari --> false
[debug] [RemoteDebugger] Checking which communication style to use (Safari on platform version '13.1')
[debug] [RemoteDebugger] Platform version equal or higher than '12.2': true
[RemoteDebugger] Setting communication protocol: using Target-based communication
[debug] [RemoteDebugger] Sending connection key request
[debug] [RemoteDebugger] Sending '_rpc_reportIdentifier:' message (id: 0)
[debug] [RemoteDebugger] Sending to Web Inspector took 8ms
[debug] [RemoteDebugger] Connected to application
[XCUITest] Using WDA path: '<removed-from-log>/appium/beta/node_modules/appium-webdriveragent'
[XCUITest] Using WDA agent: '<removed-from-log>/appium/beta/node_modules/appium-webdriveragent/WebDriverAgent.xcodeproj'
[debug] [RemoteDebugger] Received connected applications list: PID:6552, PID:6555, PID:67, PID:6554, PID:6550, PID:6572
[debug] [RemoteDebugger] Received connected driver list: {}
[debug] [XCUITest] No obsolete cached processes from previous WDA sessions listening on port 5562 have been found
[DevCon Factory] Requesting connection for device <removed-from-log> on local port 5562, device port 5562
[debug] [DevCon Factory] Cached connections count: 0
[DevCon Factory] Port #5562 is busy
[DevCon Factory] Successfully requested the connection for <removed-from-log>:5562
[debug] [XCUITest] Parsed BUILD_DIR configuration value: '/Users/callguide/Library/Developer/Xcode/DerivedData/WebDriverAgent-cwjhbxgpzbwudscgnebnnnojcxdq/Build/Products'
[debug] [XCUITest] Got derived data root: '/Users/callguide/Library/Developer/Xcode/DerivedData/WebDriverAgent-cwjhbxgpzbwudscgnebnnnojcxdq'
[debug] [XCUITest] Starting WebDriverAgent initialization with the synchronization key '/Users/callguide/Library/Developer/Xcode/DerivedData/WebDriverAgent-cwjhbxgpzbwudscgnebnnnojcxdq'
[debug] [WD Proxy] Matched '/status' to command name 'getStatus'
[debug] [WD Proxy] Proxying [GET /status] to [GET http://localhost:5562/status] with no body
[debug] [WD Proxy] Got response with status 200: {
[debug] [WD Proxy] "value" : {
[debug] [WD Proxy] "message" : "WebDriverAgent is ready to accept commands",
[debug] [WD Proxy] "state" : "success",
[debug] [WD Proxy] "os" : {
[debug] [WD Proxy] "name" : "iOS",
[debug] [WD Proxy] "version" : "13.1.2",
[debug] [WD Proxy] "sdkVersion" : "13.0"
[debug] [WD Proxy] },
[debug] [WD Proxy] "ios" : {
[debug] [WD Proxy] "simulatorVersion" : "13.1.2",
[debug] [WD Proxy] "ip" : "192.168.1.9"
[debug] [WD Proxy] },
[debug] [WD Proxy] "ready" : true,
[debug] [WD Proxy] "build" : {
[debug] [WD Proxy] "upgradedAt" : "1571994054419",
[debug] [WD Proxy] "time" : "Oct 25 2019 16:44:10",
[debug] [WD Proxy] "productBundleIdentifier" : "com.facebook.WebDriverAgentRunner"
[debug] [WD Proxy] }
[debug] [WD Proxy] },
[debug] [WD Proxy] "sessionId" : "D9015573-0AC8-41A3-8B8F-D2E31E80510A"
[debug] [WD Proxy] }
[debug] [XCUITest] Upgrade timestamp of the currently bundled WDA: 1571994054419
[debug] [XCUITest] Upgrade timestamp of the WDA on the device: 1571994054419
[XCUITest] Will reuse previously cached WDA instance at 'http://localhost:5562/' with 'com.facebook.WebDriverAgentRunner'. Set the wdaLocalPort capability to a value different from 5562 if this is an undesired behavior.
[debug] [XCUITest] Trying to start WebDriverAgent 1 times with 10000ms interval
[debug] [XCUITest] These values can be customized by changing wdaStartupRetries/wdaStartupRetryInterval capabilities
[debug] [BaseDriver] Event 'wdaStartAttempted' logged at 1572015678553 (17:01:18 GMT+0200 (Central European Summer Time))
[XCUITest] Using provided WebdriverAgent at 'http://localhost:5562/'
[debug] [WD Proxy] Matched '/status' to command name 'getStatus'
[debug] [WD Proxy] Proxying [GET /status] to [GET http://localhost:5562/status] with no body
[debug] [WD Proxy] Got response with status 200: {
[debug] [WD Proxy] "value" : {
[debug] [WD Proxy] "message" : "WebDriverAgent is ready to accept commands",
[debug] [WD Proxy] "state" : "success",
[debug] [WD Proxy] "os" : {
[debug] [WD Proxy] "name" : "iOS",
[debug] [WD Proxy] "version" : "13.1.2",
[debug] [WD Proxy] "sdkVersion" : "13.0"
[debug] [WD Proxy] },
[debug] [WD Proxy] "ios" : {
[debug] [WD Proxy] "simulatorVersion" : "13.1.2",
[debug] [WD Proxy] "ip" : "192.168.1.9"
[debug] [WD Proxy] },
[debug] [WD Proxy] "ready" : true,
[debug] [WD Proxy] "build" : {
[debug] [WD Proxy] "upgradedAt" : "1571994054419",
[debug] [WD Proxy] "time" : "Oct 25 2019 16:44:10",
[debug] [WD Proxy] "productBundleIdentifier" : "com.facebook.WebDriverAgentRunner"
[debug] [WD Proxy] }
[debug] [WD Proxy] },
[debug] [WD Proxy] "sessionId" : "D9015573-0AC8-41A3-8B8F-D2E31E80510A"
[debug] [WD Proxy] }
[debug] [BaseDriver] Event 'wdaSessionAttempted' logged at 1572015678574 (17:01:18 GMT+0200 (Central European Summer Time))
[debug] [XCUITest] Sending createSession command to WDA
[debug] [WD Proxy] Matched '/session' to command name 'createSession'
[debug] [WD Proxy] Proxying [POST /session] to [POST http://localhost:5562/session] with body: {"capabilities":{"firstMatch":[{"bundleId":"com.apple.mobilesafari","arguments":[],"environment":{},"eventloopIdleDelaySec":0,"shouldWaitForQuiescence":true,"shouldUseTestManagerForVisibilityDetection":false,"maxTypingFrequency":60,"shouldUseSingletonTestManager":true}],"alwaysMatch":{}}}
[debug] [RemoteDebugger] Application 'PID:6550' disconnected. Removing from app dictionary.
[debug] [RemoteDebugger] Current app is null
[HTTP] --> GET /wd/hub/status
[HTTP] {}
[debug] [GENERIC] Calling AppiumDriver.getStatus() with args: []
[debug] [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.16.0-beta.1"}}
[HTTP] <-- GET /wd/hub/status 200 2 ms - 75
[HTTP]
[debug] [RemoteDebugger] Notified that new application 'PID:6608' has connected
[debug] [RemoteDebugger] Found app id key 'PID:6608' for bundle 'com.apple.mobilesafari'
[debug] [RemoteDebugger] Notified that new application 'PID:6610' has connected
[debug] [RemoteDebugger] Page changed: {
[debug] [RemoteDebugger] "1": {
[debug] [RemoteDebugger] "WIRTitleKey": "",
[debug] [RemoteDebugger] "WIRTypeKey": "WIRTypeWeb",
[debug] [RemoteDebugger] "WIRURLKey": "about:blank",
[debug] [RemoteDebugger] "WIRPageIdentifierKey": 1
[debug] [RemoteDebugger] }
[debug] [RemoteDebugger] }
[debug] [XCUITest] Remote debugger notified us of a new page listing: {"appIdKey":"6608","pageArray":[{"id":1,"title":"","url":"about:blank","isKey":false}]}
[debug] [XCUITest] No key id found. Choosing first id from page array
[debug] [XCUITest] We do not appear to have window set yet, ignoring
[debug] [RemoteDebugger] Page changed: {
[debug] [RemoteDebugger] "1": {
[debug] [RemoteDebugger] "WIRTitleKey": "Google",
[debug] [RemoteDebugger] "WIRTypeKey": "WIRTypeWeb",
[debug] [RemoteDebugger] "WIRURLKey": "https://www.google.com/",
[debug] [RemoteDebugger] "WIRPageIdentifierKey": 1
[debug] [RemoteDebugger] }
[debug] [RemoteDebugger] }
[debug] [XCUITest] Remote debugger notified us of a new page listing: {"appIdKey":"6608","pageArray":[{"id":1,"title":"Google","url":"https://www.google.com/","isKey":false}]}
[debug] [XCUITest] No key id found. Choosing first id from page array
[debug] [XCUITest] We do not appear to have window set yet, ignoring
[debug] [RemoteDebugger] Notified that new application 'PID:6612' has connected
[debug] [WD Proxy] Got response with status 200: {"value":{"sessionId":"FBC7CF16-0819-4DA3-833C-77E69BC49A51","capabilities":{"device":"iphone","browserName":"Safari","sdkVersion":"13.1.2","CFBundleIdentifier":"com.apple.mobilesafari"}},"sessionId":"FBC7CF16-0819-4DA3-833C-77E69BC49A51"}
[WD Proxy] Determined the downstream protocol as 'W3C'
[debug] [BaseDriver] Event 'wdaSessionStarted' logged at 1572015681205 (17:01:21 GMT+0200 (Central European Summer Time))
[debug] [BaseDriver] Event 'wdaStarted' logged at 1572015681205 (17:01:21 GMT+0200 (Central European Summer Time))
[XCUITest] Skipping setting of the initial display orientation. Set the "orientation" capability to either "LANDSCAPE" or "PORTRAIT", if this is an undesired behavior.
[debug] [BaseDriver] Event 'orientationSet' logged at 1572015681205 (17:01:21 GMT+0200 (Central European Summer Time))
[debug] [XCUITest] Waiting for initial webview
[debug] [iOS] Navigating to most recently opened webview
[debug] [iOS] Retrieving contexts and views
[debug] [XCUITest] Selecting by url: false
[debug] [RemoteDebugger] Selecting application
[debug] [RemoteDebugger] Current applications available:
[debug] [RemoteDebugger] Application: 'PID:6552'
[debug] [RemoteDebugger] id: "PID:6552"
[debug] [RemoteDebugger] isProxy: true
[debug] [RemoteDebugger] name: "com.apple.WebKit.WebContent"
[debug] [RemoteDebugger] bundleId: "process-com.apple.WebKit.WebContent"
[debug] [RemoteDebugger] hostId: "PID:6550"
[debug] [RemoteDebugger] isActive: 0
[debug] [RemoteDebugger] isAutomationEnabled: false
[debug] [RemoteDebugger] Application: 'PID:6555'
[debug] [RemoteDebugger] id: "PID:6555"
[debug] [RemoteDebugger] isProxy: true
[debug] [RemoteDebugger] name: "com.apple.WebKit.WebContent"
[debug] [RemoteDebugger] bundleId: "process-com.apple.WebKit.WebContent"
[debug] [RemoteDebugger] hostId: "PID:6550"
[debug] [RemoteDebugger] isActive: 0
[debug] [RemoteDebugger] isAutomationEnabled: false
[debug] [RemoteDebugger] Application: 'PID:6554'
[debug] [RemoteDebugger] id: "PID:6554"
[debug] [RemoteDebugger] isProxy: true
[debug] [RemoteDebugger] name: "com.apple.WebKit.WebContent"
[debug] [RemoteDebugger] bundleId: "process-com.apple.WebKit.WebContent"
[debug] [RemoteDebugger] hostId: "PID:6550"
[debug] [RemoteDebugger] isActive: 0
[debug] [RemoteDebugger] isAutomationEnabled: false
[debug] [RemoteDebugger] Application: 'PID:6572'
[debug] [RemoteDebugger] id: "PID:6572"
[debug] [RemoteDebugger] isProxy: true
[debug] [RemoteDebugger] name: "com.apple.WebKit.WebContent"
[debug] [RemoteDebugger] bundleId: "process-com.apple.WebKit.WebContent"
[debug] [RemoteDebugger] hostId: "PID:6550"
[debug] [RemoteDebugger] isActive: 0
[debug] [RemoteDebugger] isAutomationEnabled: false
[debug] [RemoteDebugger] Application: 'PID:6608'
[debug] [RemoteDebugger] id: "PID:6608"
[debug] [RemoteDebugger] isProxy: false
[debug] [RemoteDebugger] name: "Safari"
[debug] [RemoteDebugger] bundleId: "com.apple.mobilesafari"
[debug] [RemoteDebugger] hostId: undefined
[debug] [RemoteDebugger] isActive: 2
[debug] [RemoteDebugger] isAutomationEnabled: false
[debug] [RemoteDebugger] pageArray:
[debug] [RemoteDebugger] - id: 1
[debug] [RemoteDebugger] title: "Google"
[debug] [RemoteDebugger] url: "https://www.google.com/"
[debug] [RemoteDebugger] isKey: false
[debug] [RemoteDebugger] Application: 'PID:6610'
[debug] [RemoteDebugger] id: "PID:6610"
[debug] [RemoteDebugger] isProxy: true
[debug] [RemoteDebugger] name: "com.apple.WebKit.WebContent"
[debug] [RemoteDebugger] bundleId: "process-com.apple.WebKit.WebContent"
[debug] [RemoteDebugger] hostId: "PID:6608"
[debug] [RemoteDebugger] isActive: 2
[debug] [RemoteDebugger] isAutomationEnabled: false
[debug] [RemoteDebugger] pageArray: 'Waiting for data'
[debug] [RemoteDebugger] Application: 'PID:6612'
[debug] [RemoteDebugger] id: "PID:6612"
[debug] [RemoteDebugger] isProxy: true
[debug] [RemoteDebugger] name: "com.apple.WebKit.WebContent"
[debug] [RemoteDebugger] bundleId: "process-com.apple.WebKit.WebContent"
[debug] [RemoteDebugger] hostId: "PID:6608"
[debug] [RemoteDebugger] isActive: 0
[debug] [RemoteDebugger] isAutomationEnabled: false
[debug] [RemoteDebugger] pageArray: 'Waiting for data'
[debug] [RemoteDebugger] Found app id key 'PID:6608' for bundle 'com.apple.mobilesafari'
[debug] [RemoteDebugger] Found separate bundleId 'process-com.apple.WebKit.WebContent' acting as proxy for 'com.apple.mobilesafari', with app id 'PID:6610'
[debug] [RemoteDebugger] Found separate bundleId 'process-com.apple.WebKit.WebContent' acting as proxy for 'com.apple.mobilesafari', with app id 'PID:6612'
[debug] [RemoteDebugger] Trying out the possible app ids: PID:6608, PID:6610, PID:6612 (try #1 of 20)
[debug] [RemoteDebugger] Attempting app 'PID:6608'
[debug] [RemoteDebugger] Sending '_rpc_forwardGetListing:' message to app 'PID:6608' (id: 2)
[debug] [RemoteDebugger] Received response from send (id: 2): '["PID:6608",{"1":{"WIRTitleKey":"Google","WIRTypeKey":"WIRTypeWeb","WIRURLKey":"https://www.google.com/","WIRPageIdentifierKey":1}}]'
[debug] [RemoteDebugger] Received page change notice for app 'PID:6608' but the listing has not changed. Ignoring.
[debug] [RemoteDebugger] Sending to Web Inspector took 6ms
[debug] [RemoteDebugger] Current applications available:
[debug] [RemoteDebugger] Application: 'PID:6552'
[debug] [RemoteDebugger] id: "PID:6552"
[debug] [RemoteDebugger] isProxy: true
[debug] [RemoteDebugger] name: "com.apple.WebKit.WebContent"
[debug] [RemoteDebugger] bundleId: "process-com.apple.WebKit.WebContent"
[debug] [RemoteDebugger] hostId: "PID:6550"
[debug] [RemoteDebugger] isActive: 0
[debug] [RemoteDebugger] isAutomationEnabled: false
[debug] [RemoteDebugger] Application: 'PID:6555'
[debug] [RemoteDebugger] id: "PID:6555"
[debug] [RemoteDebugger] isProxy: true
[debug] [RemoteDebugger] name: "com.apple.WebKit.WebContent"
[debug] [RemoteDebugger] bundleId: "process-com.apple.WebKit.WebContent"
[debug] [RemoteDebugger] hostId: "PID:6550"
[debug] [RemoteDebugger] isActive: 0
[debug] [RemoteDebugger] isAutomationEnabled: false
[debug] [RemoteDebugger] Application: 'PID:6554'
[debug] [RemoteDebugger] id: "PID:6554"
[debug] [RemoteDebugger] isProxy: true
[debug] [RemoteDebugger] name: "com.apple.WebKit.WebContent"
[debug] [RemoteDebugger] bundleId: "process-com.apple.WebKit.WebContent"
[debug] [RemoteDebugger] hostId: "PID:6550"
[debug] [RemoteDebugger] isActive: 0
[debug] [RemoteDebugger] isAutomationEnabled: false
[debug] [RemoteDebugger] Application: 'PID:6572'
[debug] [RemoteDebugger] id: "PID:6572"
[debug] [RemoteDebugger] isProxy: true
[debug] [RemoteDebugger] name: "com.apple.WebKit.WebContent"
[debug] [RemoteDebugger] bundleId: "process-com.apple.WebKit.WebContent"
[debug] [RemoteDebugger] hostId: "PID:6550"
[debug] [RemoteDebugger] isActive: 0
[debug] [RemoteDebugger] isAutomationEnabled: false
[debug] [RemoteDebugger] Application: 'PID:6608'
[debug] [RemoteDebugger] id: "PID:6608"
[debug] [RemoteDebugger] isProxy: false
[debug] [RemoteDebugger] name: "Safari"
[debug] [RemoteDebugger] bundleId: "com.apple.mobilesafari"
[debug] [RemoteDebugger] hostId: undefined
[debug] [RemoteDebugger] isActive: 2
[debug] [RemoteDebugger] isAutomationEnabled: false
[debug] [RemoteDebugger] pageArray:
[debug] [RemoteDebugger] - id: 1
[debug] [RemoteDebugger] title: "Google"
[debug] [RemoteDebugger] url: "https://www.google.com/"
[debug] [RemoteDebugger] isKey: false
[debug] [RemoteDebugger] Application: 'PID:6610'
[debug] [RemoteDebugger] id: "PID:6610"
[debug] [RemoteDebugger] isProxy: true
[debug] [RemoteDebugger] name: "com.apple.WebKit.WebContent"
[debug] [RemoteDebugger] bundleId: "process-com.apple.WebKit.WebContent"
[debug] [RemoteDebugger] hostId: "PID:6608"
[debug] [RemoteDebugger] isActive: 2
[debug] [RemoteDebugger] isAutomationEnabled: false
[debug] [RemoteDebugger] pageArray: 'Waiting for data'
[debug] [RemoteDebugger] Application: 'PID:6612'
[debug] [RemoteDebugger] id: "PID:6612"
[debug] [RemoteDebugger] isProxy: true
[debug] [RemoteDebugger] name: "com.apple.WebKit.WebContent"
[debug] [RemoteDebugger] bundleId: "process-com.apple.WebKit.WebContent"
[debug] [RemoteDebugger] hostId: "PID:6608"
[debug] [RemoteDebugger] isActive: 0
[debug] [RemoteDebugger] isAutomationEnabled: false
[debug] [RemoteDebugger] pageArray: 'Waiting for data'
[debug] [RemoteDebugger] Finally selecting app PID:6608: [{"id":1,"title":"Google","url":"https://www.google.com/","isKey":false}]
[debug] [RemoteDebugger] Selected app after 27ms
[debug] [iOS] Picking webview 'WEBVIEW_6608.1'
[debug] [XCUITest] Attempting to set context to 'WEBVIEW_6608.1' from 'NATIVE_APP'
[debug] [RemoteDebugger] Selecting page '1' on app 'PID:6608' and forwarding socket setup
[debug] [RemoteDebugger] Sending '_rpc_forwardSocketSetup:' message to app 'PID:6608', page '1' (id: 4)
[debug] [RemoteDebugger] Sending to Web Inspector took 3ms
[debug] [RemoteDebugger] Sender key set
[debug] [RemoteDebugger] Sending '_rpc_forwardSocketData:' message to app 'PID:6608', page '1' (id: 6)
[debug] [RemoteDebugger] Target created for app 'PID:6608' and page '1': {"targetId":"page-6","type":"page"}
[debug] [RemoteDebugger] Sending to Web Inspector took 9ms
[debug] [RemoteDebugger] Sending '_rpc_forwardSocketData:' message to app 'PID:6608', page '1', target 'page-6' (id: 8)
[debug] [RemoteDebugger] Received data response from send (id: 8): '{}'
[debug] [RemoteDebugger] Sending to Web Inspector took 15ms
[debug] [RemoteDebugger] Sending '_rpc_forwardSocketData:' message to app 'PID:6608', page '1', target 'page-6' (id: 10)
[debug] [RemoteDebugger] Received data response from send (id: 10): '{}'
[debug] [RemoteDebugger] Sending to Web Inspector took 20ms
[debug] [RemoteDebugger] Sending '_rpc_forwardSocketData:' message to app 'PID:6608', page '1', target 'page-6' (id: 12)
[debug] [RemoteDebugger] Received data response from send (id: 12): '{}'
[debug] [RemoteDebugger] Sending to Web Inspector took 8ms
[debug] [RemoteDebugger] Sending '_rpc_forwardSocketData:' message to app 'PID:6608', page '1', target 'page-6' (id: 14)
[debug] [RemoteDebugger] Received data response from send (id: 14): '{}'
[debug] [RemoteDebugger] Sending to Web Inspector took 6ms
[debug] [RemoteDebugger] Checking document readyState
[debug] [RemoteDebugger] Sending javascript command: '(function (){ return document.readyState; })()'
[debug] [RemoteDebugger] Sending '_rpc_forwardSocketData:' message to app 'PID:6608', page '1', target 'page-6' (id: 16)
[debug] [RemoteDebugger] Received data response from send (id: 16): 'complete'
[debug] [RemoteDebugger] Sending to Web Inspector took 15ms
[debug] [RemoteDebugger] Document readyState is 'complete'
[debug] [RemoteDebugger] Selected page after 82ms
[debug] [RemoteDebugger] Starting to listen for JavaScript console
[debug] [RemoteDebugger] Starting to listen for network events
[debug] [RemoteDebugger] Sending '_rpc_forwardSocketData:' message to app 'PID:6608', page '1', target 'page-6' (id: 18)
[debug] [RemoteDebugger] Received data response from send (id: 18): '{}'
[debug] [RemoteDebugger] Sending to Web Inspector took 10ms
[debug] [RemoteDebugger] Unregistering from page readiness notifications
[debug] [BaseDriver] Event 'initialWebviewNavigated' logged at 1572015681330 (17:01:21 GMT+0200 (Central European Summer Time))
[debug] [iOS] Attempting to set url 'http://appium.io'
[debug] [RemoteDebugger] Navigating to new URL: 'http://appium.io'
[debug] [RemoteDebugger] Sending '_rpc_forwardSocketData:' message to app 'PID:6608', page '1', target 'page-6' (id: 20)
[debug] [RemoteDebugger] Received data response from send (id: 20): '{}'
[debug] [RemoteDebugger] Sending to Web Inspector took 26ms
[debug] [RemoteDebugger] Target destroyed: {"targetId":"page-6"}
[RemoteDebugger] Received 'targetCreated' event with no pending request: {"targetId":"page-6","type":"page"}
[debug] [RemoteDebugger] Application 'PID:6555' disconnected. Removing from app dictionary.
[debug] [RemoteDebugger] Current app is PID:6608
[debug] [RemoteDebugger] Application 'PID:6554' disconnected. Removing from app dictionary.
[debug] [RemoteDebugger] Current app is PID:6608
[debug] [RemoteDebugger] Application 'PID:6552' disconnected. Removing from app dictionary.
[debug] [RemoteDebugger] Current app is PID:6608
[debug] [RemoteDebugger] Application 'PID:6572' disconnected. Removing from app dictionary.
[debug] [RemoteDebugger] Current app is PID:6608
[debug] [RemoteDebugger] Waiting for frame navigated message...
[debug] [RemoteDebugger] Frame navigated in 0.502 sec from source: timeout
[debug] [RemoteDebugger] Waiting for dom...
[debug] [RemoteDebugger] Page loaded, verifying whether ready
[debug] [RemoteDebugger] Checking document readyState
[debug] [RemoteDebugger] Sending javascript command: '(function (){ return document.readyState; })()'
[debug] [RemoteDebugger] Sending '_rpc_forwardSocketData:' message to app 'PID:6608', page '1', target 'page-6' (id: 22)
[debug] [RemoteDebugger] Received data response from send (id: 22): 'complete'
[debug] [RemoteDebugger] Sending to Web Inspector took 78ms
[debug] [RemoteDebugger] Document readyState is 'complete'
[debug] [RemoteDebugger] Page is ready
[Appium] New XCUITestDriver session created successfully, session 3d3476a6-b768-4b42-93d6-afafbb7bd034 added to master session list
[debug] [BaseDriver] Event 'newSessionStarted' logged at 1572015683461 (17:01:23 GMT+0200 (Central European Summer Time))
[debug] [W3C (3d3476a6)] Cached the protocol value 'W3C' for the new session 3d3476a6-b768-4b42-93d6-afafbb7bd034
[debug] [W3C (3d3476a6)] Responding to client with driver.createSession() result: {"capabilities":{"webStorageEnabled":false,"locationContextEnabled":false,"browserName":"safari","platform":"MAC","javascriptEnabled":true,"databaseEnabled":false,"takesScreenshot":true,"networkConnectionEnabled":false,"browserVersion":"13.1-iphone3","platformName":"ios","server:CONFIG_UUID":"6a9e8092-9be2-4feb-acc2-dc96096b8d90","deviceName":"iphone","deviceType":"phone","platformVersion":"13.1","udid":"<removed-from-log>","wdaLocalPort":5562}}
[HTTP] <-- POST /wd/hub/session 200 8068 ms - 532
[HTTP]
[debug] [RemoteDebugger] Notified that new application 'PID:6613' has connected
[HTTP] --> POST /wd/hub/session/3d3476a6-b768-4b42-93d6-afafbb7bd034/url
[HTTP] {"url":"http://www.seleniumeasy.com/test/"}
[debug] [W3C (3d3476a6)] Calling AppiumDriver.setUrl() with args: ["http://www.seleniumeasy.com/test/","3d3476a6-b768-4b42-93d6-afafbb7bd034"]
[debug] [XCUITest] Executing command 'setUrl'
[debug] [iOS] Attempting to set url 'http://www.seleniumeasy.com/test/'
[debug] [RemoteDebugger] Navigating to new URL: 'http://www.seleniumeasy.com/test/'
[debug] [RemoteDebugger] Sending '_rpc_forwardSocketData:' message to app 'PID:6608', page '1', target 'page-6' (id: 24)
[HTTP] --> GET /wd/hub/status
[HTTP] {}
[debug] [GENERIC] Calling AppiumDriver.getStatus() with args: []
[debug] [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.16.0-beta.1"}}
[HTTP] <-- GET /wd/hub/status 200 2 ms - 75
[HTTP]
[debug] [RemoteDebugger] Received data response from send (id: 24): '{}'
[debug] [RemoteDebugger] Sending to Web Inspector took 18ms
[debug] [RemoteDebugger] Target destroyed: {"targetId":"page-6"}
[RemoteDebugger] Received 'targetCreated' event with no pending request: {"targetId":"page-6","type":"page"}
[debug] [RemoteDebugger] Waiting for frame navigated message...
[debug] [RemoteDebugger] Notified that new application 'PID:6619' has connected
[debug] [RemoteDebugger] Frame navigated in 0.502 sec from source: timeout
[debug] [RemoteDebugger] Waiting for dom...
[debug] [RemoteDebugger] Page loaded, verifying whether ready
[debug] [RemoteDebugger] Checking document readyState
[debug] [RemoteDebugger] Sending javascript command: '(function (){ return document.readyState; })()'
[debug] [RemoteDebugger] Sending '_rpc_forwardSocketData:' message to app 'PID:6608', page '1', target 'page-6' (id: 26)
[debug] [RemoteDebugger] Received data response from send (id: 26): 'complete'
[debug] [RemoteDebugger] Sending to Web Inspector took 33ms
[debug] [RemoteDebugger] Document readyState is 'complete'
[debug] [RemoteDebugger] Page is ready
[debug] [W3C (3d3476a6)] Responding to client with driver.setUrl() result: null
[HTTP] <-- POST /wd/hub/session/3d3476a6-b768-4b42-93d6-afafbb7bd034/url 200 2075 ms - 14
[HTTP]
[HTTP] --> GET /wd/hub/session/3d3476a6-b768-4b42-93d6-afafbb7bd034/screenshot
[HTTP] {}
[debug] [W3C (3d3476a6)] Calling AppiumDriver.getScreenshot() with args: ["3d3476a6-b768-4b42-93d6-afafbb7bd034"]
[debug] [XCUITest] Executing command 'getScreenshot'
[debug] [XCUITest] Taking screenshot with WDA
[debug] [WD Proxy] Matched '/screenshot' to command name 'getScreenshot'
[debug] [WD Proxy] Proxying [GET /screenshot] to [GET http://localhost:5562/session/FBC7CF16-0819-4DA3-833C-77E69BC49A51/screenshot] with no body
[HTTP] --> POST /wd/hub/session/3d3476a6-b768-4b42-93d6-afafbb7bd034/element
[HTTP] {"using":"css selector","value":"a#basic_example"}
[debug] [W3C (3d3476a6)] Calling AppiumDriver.findElement() with args: ["css selector","a#basic_example","3d3476a6-b768-4b42-93d6-afafbb7bd034"]
[debug] [XCUITest] Executing command 'findElement'
[debug] [WD Proxy] Got response with status 200: {
[debug] [WD Proxy] "value" : "iVBORw0KGgoAAAANSUhEUgAAAu4AAAU2CAIAAABFtaRRAAAAAXNSR0IArs4c6QAA\r\nAERlWElmTU0AKgAAAAgAAYdpAAQAAAABAAAAGgAAAAAAA6ABAAMAAAABAAEAAKAC\r\nAAQAAAABAAAC7qADAAQAAAABAAAFNgAAAACw0axrAAAAHGlET1QAAAACAAAAAAAA\r\nApsAAAAoAAACmwAAApsAAzypXxr9JwAAQABJREFUeAHs3YebXrW1Nvz3n\/muc50T\r\nQkIJOfRebFynd9u424AxzZBegdDdp7jQEtJICD2E3kJ3AUMapPcKISFUf7\/nuWG\/\r\n87rhMuAZW7qM0F5aWlq6tSStLWk\/839aSygIFAQKAgWBgkBBYK9HYFJDZ3djZ0tb\r\na2O7f81N\/rW1trS2N7Z1NrV2tbT0tDX719Xa0jWxvXN8J2JPC3pre0tb84TOxrHd\r\nDaN7Gsd2NaN0NMvuamvpJKGpvbGjdVx3y5iJXWOaOsa1tk1QA\/kTO9oJaWzr6mjq\r\n6WnoqfE3dzW2t6MT2Nrq3\/aG\/7O9jIWvIFAQKAgUBAoCBYE9F4GeRv5HJ0ek7n\/U\r\nXBkuBc+mqQ2lvbWls62Fj9Le1sI74Yg0x5VBrFMaJ3Y2ju9qnNDJBam5Mh01p6eT\r\na4K5o7Whu2VCU8eElvYG6Y6WmlhZEzrqrkxzV3djV1wfblPdldkxlIsrs2N4Fe6C\r\nQEGgIFAQKAjskQhwPuyj1HdZWmv7Im2N+dfS1uhfa1tDGy+kdVxH25i29pNb20+2\r\nJWOHprupvaextbu5ue6g1HwU7g5XxhYLBvs33KCOltaepmZOjn\/dTbV\/eFTEm+G7\r\nqJQL5Z8Efv6NLB7S9oNcXJntx6pwFgQKAgWBgkBBYM9FoKXTPoo9lXoLN3Vl3vFm\r\nHA+1jWtrG8OhaWmt7dP0NLZPaqx5KvFmeC2dze3+1b0...
[debug] [BaseDriver] Valid locator strategies for this request: xpath, id, name, class name, -ios predicate string, -ios class chain, accessibility id
[debug] [BaseDriver] Waiting up to 0 ms for condition
[debug] [RemoteDebugger] Executing atom 'find_element'
[debug] [W3C (3d3476a6)] Responding to client with driver.getScreenshot() result: "iVBORw0KGgoAAAANSUhEUgAAAu4AAAU2CAIAAABFtaRRAAAAAXNSR0IArs4c6QAA\r\nAERlWElmTU0AKgAAAAgAAYdpAAQAAAABAAAAGgAAAAAAA6ABAAMAAAABAAEAAKAC\r\nAAQAAAABAAAC7qADAAQAAAABAAAFNgAAAACw0axrAAAAHGlET1QAAAACAAAAAAAA\r\nApsAAAAoAAACmwAAApsAAzypXxr9JwAAQABJREFUeAHs3YebXrW1Nvz3n/muc50T\r\nQkIJOfRebFynd9u424AxzZBegdDdp7jQEtJICD2E3kJ3AUMapPcKISFUf7/nuWG/\r\n87rhMuAZW7qM0F5aWlq6tSStLWk/839aSygIFAQKAgWBgkBBYK9HYFJDZ3djZ0tb\r\na2O7f81N/rW1trS2N7Z1NrV2tbT0tDX719Xa0jWxvXN8J2JPC3pre0tb84TOxrHd\r\nDaN7Gsd2NaN0NMvuamvpJKGpvbGjdVx3y5iJXWOaOsa1tk1QA/kTO9oJaWzr6mjq\r\n6WnoqfE3dzW2t6MT2Nrq3/aG/7O9jIWvIFAQKAgUBAoCBYE9F4GeRv5HJ0ek7n/U\r\nXBkuBc+mqQ2lvbWls62Fj9Le1sI74Yg0x5VBrFMaJ3Y2ju9qnNDJBam5Mh01p6eT\r\na4K5o7Whu2VCU8eElvYG6Y6WmlhZEzrqrkxzV3djV1wfblPdldkxlIsrs2N4Fe6C\r\nQEGgIFAQKAjskQhwPuyj1HdZWmv7Im2N+dfS1uhfa1tDGy+kdVxH25i29pNb20+2\r\nJWOHprupvaextbu5ue6g1HwU7g5XxhYLBvs33KCOltaepmZOjn/dTbV/eFTEm+G7\r\nqJQL5Z8Efv6NLB7S9oNcXJntx6pwFgQKAgWBgkBBYM9FoKXTPoo9lXoLN3Vl3vFm\r\nHA+1jWtrG8OhaWmt7dP0NLZPaqx5KvFmeC2dze3+1b0imy41V8YjNlsvPQ02YDp5\r\n...
[HTTP] <-- GET /wd/hub/session/3d3476a6-b768-4b42-93d6-afafbb7bd034/screenshot 200 167 ms - 790744
[HTTP]
[debug] [RemoteDebugger] Executing 'find_element' atom in default context
[debug] [RemoteDebugger] Sending javascript command: '(function(){return function(){var k=this;functi...'
[debug] [RemoteDebugger] Sending '_rpc_forwardSocketData:' message to app 'PID:6608', page '1', target 'page-6' (id: 28)
[debug] [RemoteDebugger] Received data response from send (id: 28): '{"status":0,"value":{"ELEMENT":":wdc:1572015686253"}}'
[debug] [RemoteDebugger] Sending to Web Inspector took 39ms
[debug] [RemoteDebugger] Received result for atom 'find_element' execution: {"ELEMENT":":wdc:1572015686253"}
[debug] [W3C (3d3476a6)] Responding to client with driver.findElement() result: {"element-6066-11e4-a52e-4f735466cecf":"5000","ELEMENT":"5000"}
[HTTP] <-- POST /wd/hub/session/3d3476a6-b768-4b42-93d6-afafbb7bd034/element 200 202 ms - 73
[HTTP]
[HTTP] --> POST /wd/hub/session/3d3476a6-b768-4b42-93d6-afafbb7bd034/element/5000/click
[HTTP] {}
[debug] [W3C (3d3476a6)] Calling AppiumDriver.click() with args: ["5000","3d3476a6-b768-4b42-93d6-afafbb7bd034"]
[debug] [XCUITest] Executing command 'click'
[debug] [RemoteDebugger] Executing atom 'click'
[debug] [RemoteDebugger] Executing 'click' atom in default context
[debug] [RemoteDebugger] Sending javascript command: '(function(){return function(){var h,aa=this;fun...'
[debug] [RemoteDebugger] Sending '_rpc_forwardSocketData:' message to app 'PID:6608', page '1', target 'page-6' (id: 30)
[debug] [RemoteDebugger] Received data response from send (id: 30): '{"status":0,"value":null}'
[debug] [RemoteDebugger] Sending to Web Inspector took 76ms
[debug] [RemoteDebugger] Received result for atom 'click' execution: null
[debug] [W3C (3d3476a6)] Responding to client with driver.click() result: null
[HTTP] <-- POST /wd/hub/session/3d3476a6-b768-4b42-93d6-afafbb7bd034/element/5000/click 200 80 ms - 14
[HTTP]
[HTTP] --> GET /wd/hub/session/3d3476a6-b768-4b42-93d6-afafbb7bd034/screenshot
[HTTP] {}
[debug] [W3C (3d3476a6)] Calling AppiumDriver.getScreenshot() with args: ["3d3476a6-b768-4b42-93d6-afafbb7bd034"]
[debug] [XCUITest] Executing command 'getScreenshot'
[debug] [XCUITest] Taking screenshot with WDA
[debug] [WD Proxy] Matched '/screenshot' to command name 'getScreenshot'
[debug] [WD Proxy] Proxying [GET /screenshot] to [GET http://localhost:5562/session/FBC7CF16-0819-4DA3-833C-77E69BC49A51/screenshot] with no body
[debug] [WD Proxy] Got response with status 200: {
[debug] [WD Proxy] "value" : "iVBORw0KGgoAAAANSUhEUgAAAu4AAAU2CAIAAABFtaRRAAAAAXNSR0IArs4c6QAA\r\nAERlWElmTU0AKgAAAAgAAYdpAAQAAAABAAAAGgAAAAAAA6ABAAMAAAABAAEAAKAC\r\nAAQAAAABAAAC7qADAAQAAAABAAAFNgAAAACw0axrAAAAHGlET1QAAAACAAAAAAAA\r\nApsAAAAoAAACmwAAApsAAXLVa9d21QAAQABJREFUeAHs3YefXlXVL\/D719z3vvdV\r\nFEREeodAEpJMe6amkQahhwRU7Ehv6TMpVLErLSBopAtSUkCwi+21NywoNff7PD84\r\n79xpmUmBSdj7EzbrrL3aXnvtvdfZ55xn\/lfbW1JaR112nzkxIfJHbc6YCXef\/UVy\r\n8UDxQPFA8UDxwO7zwMypnT1NneQ319qa21taa\/65am+udba2dbW19nQ093S0dNVa\r\nuppqnVM6OtXNtXY0KKd0Nk3qajmxp2VSV3tzW1edsrmnTtne0tQxrdZ+UmdtwtSu\r\nic0dk9tqU2ptTfBTO9pP7iSkTtwzraezuSG5vR2ewLY2\/0Zb\/tdoCXeObvQZwc7p\r\nGYm7pDIjeae0FQ8UDxQPFA+8sz0gj+mupzJylzdSmeQTrW3t\/rW1dna0tHe2tHe0\r\n1nOXeo7SSGVqrW21thapz9SOlpM726ZIRNokJfVUpq21a2pHW1NHU602ubtVHjOl\r\ntX1aR9u0jtZ6ktTU3t7Ih7qkR91NXfVUprWeHjVSmbGNREllRp9ljYpybO4v1MUD\r\nxQPFA8UDxQPjwwNSio6WeiqTg5a2WlP+tdaa\/Gur1bOQzrbJnW0TO2onOWhxWiO\/\r\nkdz0NLV1N7c1Upw2SY\/DGP+c4jieqScr7ZpaepqbOlva\/ENZJ26pp0eyGbmLDKaz\r\nuf4PMLW9ft5TF9LaPnqvlFRmVAnK6IlG7\/pCWTxQPF...
[debug] [W3C (3d3476a6)] Responding to client with driver.getScreenshot() result: "iVBORw0KGgoAAAANSUhEUgAAAu4AAAU2CAIAAABFtaRRAAAAAXNSR0IArs4c6QAA\r\nAERlWElmTU0AKgAAAAgAAYdpAAQAAAABAAAAGgAAAAAAA6ABAAMAAAABAAEAAKAC\r\nAAQAAAABAAAC7qADAAQAAAABAAAFNgAAAACw0axrAAAAHGlET1QAAAACAAAAAAAA\r\nApsAAAAoAAACmwAAApsAAXLVa9d21QAAQABJREFUeAHs3YefXlXVL/D719z3vvdV\r\nFEREeodAEpJMe6amkQahhwRU7Ehv6TMpVLErLSBopAtSUkCwi+21NywoNff7PD84\r\n79xpmUmBSdj7EzbrrL3aXnvtvdfZ55xn/lfbW1JaR112nzkxIfJHbc6YCXef/UVy\r\n8UDxQPFA8UDxwO7zwMypnT1NneQ319qa21taa/65am+udba2dbW19nQ093S0dNVa\r\nuppqnVM6OtXNtXY0KKd0Nk3qajmxp2VSV3tzW1edsrmnTtne0tQxrdZ+UmdtwtSu\r\nic0dk9tqU2ptTfBTO9pP7iSkTtwzraezuSG5vR2ewLY2/0Zb/tdoCXeObvQZwc7p\r\nGYm7pDIjeae0FQ8UDxQPFA+8sz0gj+mupzJylzdSmeQTrW3t/rW1dna0tHe2tHe0\r\n1nOXeo7SSGVqrW21thapz9SOlpM726ZIRNokJfVUpq21a2pHW1NHU602ubtVHjOl\r\ntX1aR9u0jtZ6ktTU3t7Ih7qkR91NXfVUprWeHjVSmbGNREllRp9ljYpybO4v1MUD\r\nxQPFA8UDxQPjwwNSio6WeiqTg5a2WlP+tdaa/Gur1bOQzrbJnW0TO2onOWhxWiO/\r\nkdz0NLV1N7c1Upw2SY/DGP+c4jieqScr7ZpaepqbOlva/ENZJ26pp0eyGbmLDKaz\r\nuf4PMLW9ft5TF9LaPnqvlFRmVAnK6IlG7/pCWTxQPFA8UDxQPDCOPNBaT02kMg2T\r\n...
[HTTP] <-- GET /wd/hub/session/3d3476a6-b768-4b42-93d6-afafbb7bd034/screenshot 200 103 ms - 230596
[HTTP]
[HTTP] --> POST /wd/hub/session/3d3476a6-b768-4b42-93d6-afafbb7bd034/element
[HTTP] {"using":"css selector","value":".list-group-item[href*=\"basic-first-form-demo\"]"}
[debug] [W3C (3d3476a6)] Calling AppiumDriver.findElement() with args: ["css selector",".list-group-item[href*=\"basic-first-form-demo\"]","3d3476a6-b768-4b42-93d6-afafbb7bd034"]
[debug] [XCUITest] Executing command 'findElement'
[debug] [BaseDriver] Valid locator strategies for this request: xpath, id, name, class name, -ios predicate string, -ios class chain, accessibility id
[debug] [BaseDriver] Waiting up to 0 ms for condition
[debug] [RemoteDebugger] Executing atom 'find_element'
[debug] [RemoteDebugger] Executing 'find_element' atom in default context
[debug] [RemoteDebugger] Sending javascript command: '(function(){return function(){var k=this;functi...'
[debug] [RemoteDebugger] Sending '_rpc_forwardSocketData:' message to app 'PID:6608', page '1', target 'page-6' (id: 32)
[HTTP] --> GET /wd/hub/status
[HTTP] {}
[debug] [GENERIC] Calling AppiumDriver.getStatus() with args: []
[debug] [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.16.0-beta.1"}}
[HTTP] <-- GET /wd/hub/status 200 1 ms - 75
[HTTP]
[HTTP] --> GET /wd/hub/status
[HTTP] {}
[debug] [GENERIC] Calling AppiumDriver.getStatus() with args: []
[debug] [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.16.0-beta.1"}}
[HTTP] <-- GET /wd/hub/status 200 3 ms - 75
[HTTP]
[HTTP] --> GET /wd/hub/status
[HTTP] {}
[debug] [GENERIC] Calling AppiumDriver.getStatus() with args: []
[debug] [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.16.0-beta.1"}}
[HTTP] <-- GET /wd/hub/status 200 6 ms - 75
[HTTP]
[HTTP] --> GET /wd/hub/status
[HTTP] {}
[debug] [GENERIC] Calling AppiumDriver.getStatus() with args: []
[debug] [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.16.0-beta.1"}}
[HTTP] <-- GET /wd/hub/status 200 4 ms - 75
[HTTP]
[HTTP] --> GET /wd/hub/status
[HTTP] {}
[debug] [GENERIC] Calling AppiumDriver.getStatus() with args: []
[debug] [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.16.0-beta.1"}}
[HTTP] <-- GET /wd/hub/status 200 2 ms - 75
[HTTP]
[HTTP] --> GET /wd/hub/status
[HTTP] {}
[debug] [GENERIC] Calling AppiumDriver.getStatus() with args: []
[debug] [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.16.0-beta.1"}}
[HTTP] <-- GET /wd/hub/status 200 3 ms - 75
[HTTP]
[HTTP] --> GET /wd/hub/status
[HTTP] {}
[debug] [GENERIC] Calling AppiumDriver.getStatus() with args: []
[debug] [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.16.0-beta.1"}}
[HTTP] <-- GET /wd/hub/status 200 2 ms - 75
[HTTP]
[HTTP] --> GET /wd/hub/status
[HTTP] {}
[debug] [GENERIC] Calling AppiumDriver.getStatus() with args: []
[debug] [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.16.0-beta.1"}}
[HTTP] <-- GET /wd/hub/status 200 2 ms - 75
[HTTP]
[HTTP] --> GET /wd/hub/status
[HTTP] {}
[debug] [GENERIC] Calling AppiumDriver.getStatus() with args: []
[debug] [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.16.0-beta.1"}}
[HTTP] <-- GET /wd/hub/status 200 1 ms - 75
[HTTP]
[HTTP] <-- POST /wd/hub/session/3d3476a6-b768-4b42-93d6-afafbb7bd034/element - - ms - -
[HTTP]
[HTTP] --> GET /wd/hub/status
[HTTP] {}
[debug] [GENERIC] Calling AppiumDriver.getStatus() with args: []
[debug] [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.16.0-beta.1"}}
[HTTP] <-- GET /wd/hub/status 200 1 ms - 75
[HTTP]
[HTTP] --> GET /wd/hub/status
[HTTP] {}
[debug] [GENERIC] Calling AppiumDriver.getStatus() with args: []
[debug] [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.16.0-beta.1"}}
[HTTP] <-- GET /wd/hub/status 200 2 ms - 75
[HTTP]
[HTTP] --> GET /wd/hub/status
[HTTP] {}
[debug] [GENERIC] Calling AppiumDriver.getStatus() with args: []
[debug] [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.16.0-beta.1"}}
[HTTP] <-- GET /wd/hub/status 200 3 ms - 75
[HTTP]
[HTTP] --> GET /wd/hub/status
[HTTP] {}
[debug] [GENERIC] Calling AppiumDriver.getStatus() with args: []
[debug] [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.16.0-beta.1"}}
[HTTP] <-- GET /wd/hub/status 200 2 ms - 75
[HTTP]
[HTTP] --> GET /wd/hub/status
[HTTP] {}
[debug] [GENERIC] Calling AppiumDriver.getStatus() with args: []
[debug] [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.16.0-beta.1"}}
[HTTP] <-- GET /wd/hub/status 200 1 ms - 75
[HTTP]
[HTTP] --> GET /wd/hub/status
[HTTP] {}
[debug] [GENERIC] Calling AppiumDriver.getStatus() with args: []
[debug] [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.16.0-beta.1"}}
[HTTP] <-- GET /wd/hub/status 200 2 ms - 75
[HTTP]
[HTTP] --> GET /wd/hub/status
[HTTP] {}
[debug] [GENERIC] Calling AppiumDriver.getStatus() with args: []
[debug] [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.16.0-beta.1"}}
[HTTP] <-- GET /wd/hub/status 200 2 ms - 75
[HTTP]
[HTTP] --> GET /wd/hub/status
[HTTP] {}
[debug] [GENERIC] Calling AppiumDriver.getStatus() with args: []
[debug] [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.16.0-beta.1"}}
[HTTP] <-- GET /wd/hub/status 200 1 ms - 75
[HTTP]
Raw
wdio-0-0.log
2019-10-25T15:01:15.045Z INFO @wdio/local-runner: Run worker command: run
2019-10-25T15:01:15.321Z DEBUG @wdio/local-runner:utils: init remote session
2019-10-25T15:01:15.357Z INFO webdriver: [POST] <removed-from-log>:4444/wd/hub/session
2019-10-25T15:01:15.357Z INFO webdriver: DATA {
capabilities: {
alwaysMatch: {
browserName: 'safari',
platformName: 'IOS',
browserVersion: '13.1-iphone3',
'appium:deviceName': 'iphone',
'appium:deviceType': 'phone',
'appium:platformVersion': '13.1',
'appium:udid': '<removed-from-log>',
'appium:wdaLocalPort': 5562
},
firstMatch: [ {} ]
},
desiredCapabilities: {
browserName: 'safari',
platformName: 'IOS',
browserVersion: '13.1-iphone3',
'appium:deviceName': 'iphone',
'appium:deviceType': 'phone',
'appium:platformVersion': '13.1',
'appium:udid': '<removed-from-log>',
'appium:wdaLocalPort': 5562
}
}
2019-10-25T15:01:23.956Z INFO webdriver: COMMAND navigateTo("http://www.seleniumeasy.com/test/")
2019-10-25T15:01:23.957Z INFO webdriver: [POST] <removed-from-log>:4444/wd/hub/session/3d3476a6-b768-4b42-93d6-afafbb7bd034/url
2019-10-25T15:01:23.957Z INFO webdriver: DATA { url: 'http://www.seleniumeasy.com/test/' }
2019-10-25T15:01:26.044Z INFO webdriver: COMMAND takeScreenshot()
2019-10-25T15:01:26.044Z INFO webdriver: [GET] <removed-from-log>:4444/wd/hub/session/3d3476a6-b768-4b42-93d6-afafbb7bd034/screenshot
2019-10-25T15:01:26.047Z INFO webdriver: COMMAND findElement("css selector", "a#basic_example")
2019-10-25T15:01:26.047Z INFO webdriver: [POST] <removed-from-log>:4444/wd/hub/session/3d3476a6-b768-4b42-93d6-afafbb7bd034/element
2019-10-25T15:01:26.047Z INFO webdriver: DATA { using: 'css selector', value: 'a#basic_example' }
2019-10-25T15:01:26.276Z INFO webdriver: RESULT { 'element-6066-11e4-a52e-4f735466cecf': '5000', ELEMENT: '5000' }
2019-10-25T15:01:26.306Z INFO webdriver: COMMAND elementClick("5000")
2019-10-25T15:01:26.307Z INFO webdriver: [POST] <removed-from-log>:4444/wd/hub/session/3d3476a6-b768-4b42-93d6-afafbb7bd034/element/5000/click
2019-10-25T15:01:26.398Z INFO webdriver: COMMAND takeScreenshot()
2019-10-25T15:01:26.399Z INFO webdriver: [GET] <removed-from-log>:4444/wd/hub/session/3d3476a6-b768-4b42-93d6-afafbb7bd034/screenshot
2019-10-25T15:01:26.465Z INFO webdriver: RESULT iVBORw0KGgoAAAANSUhEUgAAAu4AAAU2CAIAAABFtaRRAAAAAXNSR0IArs4c6...
2019-10-25T15:01:26.615Z INFO webdriver: RESULT iVBORw0KGgoAAAANSUhEUgAAAu4AAAU2CAIAAABFtaRRAAAAAXNSR0IArs4c6...
2019-10-25T15:01:26.700Z INFO webdriver: COMMAND findElement("css selector", ".list-group-item[href*="basic-first-form-demo"]")
2019-10-25T15:01:26.700Z INFO webdriver: [POST] <removed-from-log>:4444/wd/hub/session/3d3476a6-b768-4b42-93d6-afafbb7bd034/element
2019-10-25T15:01:26.701Z INFO webdriver: DATA {
using: 'css selector',
value: '.list-group-item[href*="basic-first-form-demo"]'
}
2019-10-25T15:02:11.713Z DEBUG webdriver: request failed due to missing body
2019-10-25T15:02:11.714Z WARN webdriver: Request failed due to Response has empty body
2019-10-25T15:02:11.714Z INFO webdriver: Retrying 1/3
2019-10-25T15:02:11.714Z INFO webdriver: [POST] <removed-from-log>:4444/wd/hub/session/3d3476a6-b768-4b42-93d6-afafbb7bd034/element
2019-10-25T15:02:11.714Z INFO webdriver: DATA {
using: 'css selector',
value: '.list-group-item[href*="basic-first-form-demo"]'
}
2019-10-25T15:02:11.720Z DEBUG webdriver: request failed due to status 13
2019-10-25T15:02:11.721Z WARN webdriver: Request failed due to Session [3d3476a6-b768-4b42-93d6-afafbb7bd034] was terminated due to SO_TIMEOUT
2019-10-25T15:02:11.721Z INFO webdriver: Retrying 2/3
2019-10-25T15:02:11.721Z INFO webdriver: [POST] <removed-from-log>:4444/wd/hub/session/3d3476a6-b768-4b42-93d6-afafbb7bd034/element
2019-10-25T15:02:11.721Z INFO webdriver: DATA {
using: 'css selector',
value: '.list-group-item[href*="basic-first-form-demo"]'
}
2019-10-25T15:02:11.727Z DEBUG webdriver: request failed due to status 13
2019-10-25T15:02:11.728Z WARN webdriver: Request failed due to Session [3d3476a6-b768-4b42-93d6-afafbb7bd034] was terminated due to SO_TIMEOUT
2019-10-25T15:02:11.728Z INFO webdriver: Retrying 3/3
2019-10-25T15:02:11.728Z INFO webdriver: [POST] <removed-from-log>:4444/wd/hub/session/3d3476a6-b768-4b42-93d6-afafbb7bd034/element
2019-10-25T15:02:11.728Z INFO webdriver: DATA {
using: 'css selector',
value: '.list-group-item[href*="basic-first-form-demo"]'
}
2019-10-25T15:02:11.734Z DEBUG webdriver: request failed due to status 13
2019-10-25T15:02:11.734Z ERROR webdriver: Request failed due to unknown error: Session [3d3476a6-b768-4b42-93d6-afafbb7bd034] was terminated due to SO_TIMEOUT
at getErrorFromResponseBody (<removed-from-log>/gui-test-template/node_modules/webdriver/build/utils.js:350:10)
at Request._callback (<removed-from-log>/gui-test-template/node_modules/webdriver/build/request.js:121:64)
at Request.self.callback (<removed-from-log>/gui-test-template/node_modules/request/request.js:185:22)
at Request.emit (events.js:209:13)
at Request.EventEmitter.emit (domain.js:476:20)
at Request.<anonymous> (<removed-from-log>/gui-test-template/node_modules/request/request.js:1161:10)
at Request.emit (events.js:209:13)
at Request.EventEmitter.emit (domain.js:476:20)
at IncomingMessage.<anonymous> (<removed-from-log>/gui-test-template/node_modules/request/request.js:1083:12)
at Object.onceWrapper (events.js:298:28)
2019-10-25T15:02:11.746Z INFO webdriver: COMMAND takeScreenshot()
2019-10-25T15:02:11.746Z INFO webdriver: [GET] <removed-from-log>:4444/wd/hub/session/3d3476a6-b768-4b42-93d6-afafbb7bd034/screenshot
2019-10-25T15:02:11.750Z INFO webdriver: COMMAND navigateTo("http://www.seleniumeasy.com/test/")
2019-10-25T15:02:11.750Z INFO webdriver: [POST] <removed-from-log>:4444/wd/hub/session/3d3476a6-b768-4b42-93d6-afafbb7bd034/url
2019-10-25T15:02:11.750Z INFO webdriver: DATA { url: 'http://www.seleniumeasy.com/test/' }
2019-10-25T15:02:11.755Z DEBUG webdriver: request failed due to status 13
2019-10-25T15:02:11.755Z WARN webdriver: Request failed due to Session [3d3476a6-b768-4b42-93d6-afafbb7bd034] was terminated due to SO_TIMEOUT
2019-10-25T15:02:11.755Z INFO webdriver: Retrying 1/3
2019-10-25T15:02:11.755Z INFO webdriver: [GET] <removed-from-log>:4444/wd/hub/session/3d3476a6-b768-4b42-93d6-afafbb7bd034/screenshot
2019-10-25T15:02:11.757Z DEBUG webdriver: request failed due to status 13
2019-10-25T15:02:11.757Z WARN webdriver: Request failed due to Session [3d3476a6-b768-4b42-93d6-afafbb7bd034] was terminated due to SO_TIMEOUT
2019-10-25T15:02:11.757Z INFO webdriver: Retrying 1/3
2019-10-25T15:02:11.757Z INFO webdriver: [POST] <removed-from-log>:4444/wd/hub/session/3d3476a6-b768-4b42-93d6-afafbb7bd034/url
2019-10-25T15:02:11.758Z INFO webdriver: DATA { url: 'http://www.seleniumeasy.com/test/' }
2019-10-25T15:02:11.762Z DEBUG webdriver: request failed due to status 13
2019-10-25T15:02:11.763Z WARN webdriver: Request failed due to Session [3d3476a6-b768-4b42-93d6-afafbb7bd034] was terminated due to SO_TIMEOUT
2019-10-25T15:02:11.763Z INFO webdriver: Retrying 2/3
2019-10-25T15:02:11.763Z INFO webdriver: [GET] <removed-from-log>:4444/wd/hub/session/3d3476a6-b768-4b42-93d6-afafbb7bd034/screenshot
2019-10-25T15:02:11.765Z DEBUG webdriver: request failed due to status 13
2019-10-25T15:02:11.765Z WARN webdriver: Request failed due to Session [3d3476a6-b768-4b42-93d6-afafbb7bd034] was terminated due to SO_TIMEOUT
2019-10-25T15:02:11.765Z INFO webdriver: Retrying 2/3
2019-10-25T15:02:11.765Z INFO webdriver: [POST] <removed-from-log>:4444/wd/hub/session/3d3476a6-b768-4b42-93d6-afafbb7bd034/url
2019-10-25T15:02:11.765Z INFO webdriver: DATA { url: 'http://www.seleniumeasy.com/test/' }
2019-10-25T15:02:11.772Z DEBUG webdriver: request failed due to status 13
2019-10-25T15:02:11.772Z WARN webdriver: Request failed due to Session [3d3476a6-b768-4b42-93d6-afafbb7bd034] was terminated due to SO_TIMEOUT
2019-10-25T15:02:11.772Z INFO webdriver: Retrying 3/3
2019-10-25T15:02:11.772Z INFO webdriver: [GET] <removed-from-log>:4444/wd/hub/session/3d3476a6-b768-4b42-93d6-afafbb7bd034/screenshot
2019-10-25T15:02:11.777Z DEBUG webdriver: request failed due to status 13
2019-10-25T15:02:11.777Z WARN webdriver: Request failed due to Session [3d3476a6-b768-4b42-93d6-afafbb7bd034] was terminated due to SO_TIMEOUT
2019-10-25T15:02:11.778Z INFO webdriver: Retrying 3/3
2019-10-25T15:02:11.778Z INFO webdriver: [POST] <removed-from-log>:4444/wd/hub/session/3d3476a6-b768-4b42-93d6-afafbb7bd034/url
2019-10-25T15:02:11.778Z INFO webdriver: DATA { url: 'http://www.seleniumeasy.com/test/' }
2019-10-25T15:02:11.779Z DEBUG webdriver: request failed due to status 13
2019-10-25T15:02:11.779Z ERROR webdriver: Request failed due to unknown error: Session [3d3476a6-b768-4b42-93d6-afafbb7bd034] was terminated due to SO_TIMEOUT
at getErrorFromResponseBody (<removed-from-log>/gui-test-template/node_modules/webdriver/build/utils.js:350:10)
at Request._callback (<removed-from-log>/gui-test-template/node_modules/webdriver/build/request.js:121:64)
at Request.self.callback (<removed-from-log>/gui-test-template/node_modules/request/request.js:185:22)
at Request.emit (events.js:209:13)
at Request.EventEmitter.emit (domain.js:476:20)
at Request.<anonymous> (<removed-from-log>/gui-test-template/node_modules/request/request.js:1161:10)
at Request.emit (events.js:209:13)
at Request.EventEmitter.emit (domain.js:476:20)
at IncomingMessage.<anonymous> (<removed-from-log>/gui-test-template/node_modules/request/request.js:1083:12)
at Object.onceWrapper (events.js:298:28)
2019-10-25T15:02:11.790Z DEBUG webdriver: request failed due to status 13
2019-10-25T15:02:11.790Z ERROR webdriver: Request failed due to unknown error: Session [3d3476a6-b768-4b42-93d6-afafbb7bd034] was terminated due to SO_TIMEOUT
at getErrorFromResponseBody (<removed-from-log>/gui-test-template/node_modules/webdriver/build/utils.js:350:10)
at Request._callback (<removed-from-log>/gui-test-template/node_modules/webdriver/build/request.js:121:64)
at Request.self.callback (<removed-from-log>/gui-test-template/node_modules/request/request.js:185:22)
at Request.emit (events.js:209:13)
at Request.EventEmitter.emit (domain.js:476:20)
at Request.<anonymous> (<removed-from-log>/gui-test-template/node_modules/request/request.js:1161:10)
at Request.emit (events.js:209:13)
at Request.EventEmitter.emit (domain.js:476:20)
at IncomingMessage.<anonymous> (<removed-from-log>/gui-test-template/node_modules/request/request.js:1083:12)
at Object.onceWrapper (events.js:298:28)
2019-10-25T15:02:11.792Z INFO webdriver: COMMAND findElement("css selector", "a#advanced_example")
2019-10-25T15:02:11.792Z INFO webdriver: [POST] <removed-from-log>:4444/wd/hub/session/3d3476a6-b768-4b42-93d6-afafbb7bd034/element
2019-10-25T15:02:11.792Z INFO webdriver: DATA { using: 'css selector', value: 'a#advanced_example' }
2019-10-25T15:02:11.799Z DEBUG webdriver: request failed due to status 13
2019-10-25T15:02:11.799Z WARN webdriver: Request failed due to Session [3d3476a6-b768-4b42-93d6-afafbb7bd034] was terminated due to SO_TIMEOUT
2019-10-25T15:02:11.799Z INFO webdriver: Retrying 1/3
2019-10-25T15:02:11.799Z INFO webdriver: [POST] <removed-from-log>:4444/wd/hub/session/3d3476a6-b768-4b42-93d6-afafbb7bd034/element
2019-10-25T15:02:11.799Z INFO webdriver: DATA { using: 'css selector', value: 'a#advanced_example' }
2019-10-25T15:02:11.804Z DEBUG webdriver: request failed due to status 13
2019-10-25T15:02:11.805Z WARN webdriver: Request failed due to Session [3d3476a6-b768-4b42-93d6-afafbb7bd034] was terminated due to SO_TIMEOUT
2019-10-25T15:02:11.806Z INFO webdriver: Retrying 2/3
2019-10-25T15:02:11.806Z INFO webdriver: [POST] <removed-from-log>:4444/wd/hub/session/3d3476a6-b768-4b42-93d6-afafbb7bd034/element
2019-10-25T15:02:11.806Z INFO webdriver: DATA { using: 'css selector', value: 'a#advanced_example' }
2019-10-25T15:02:11.812Z DEBUG webdriver: request failed due to status 13
2019-10-25T15:02:11.812Z WARN webdriver: Request failed due to Session [3d3476a6-b768-4b42-93d6-afafbb7bd034] was terminated due to SO_TIMEOUT
2019-10-25T15:02:11.812Z INFO webdriver: Retrying 3/3
2019-10-25T15:02:11.812Z INFO webdriver: [POST] <removed-from-log>:4444/wd/hub/session/3d3476a6-b768-4b42-93d6-afafbb7bd034/element
2019-10-25T15:02:11.812Z INFO webdriver: DATA { using: 'css selector', value: 'a#advanced_example' }
2019-10-25T15:02:11.820Z DEBUG webdriver: request failed due to status 13
2019-10-25T15:02:11.820Z ERROR webdriver: Request failed due to unknown error: Session [3d3476a6-b768-4b42-93d6-afafbb7bd034] was terminated due to SO_TIMEOUT
at getErrorFromResponseBody (<removed-from-log>/gui-test-template/node_modules/webdriver/build/utils.js:350:10)
at Request._callback (<removed-from-log>/gui-test-template/node_modules/webdriver/build/request.js:121:64)
at Request.self.callback (<removed-from-log>/gui-test-template/node_modules/request/request.js:185:22)
at Request.emit (events.js:209:13)
at Request.EventEmitter.emit (domain.js:476:20)
at Request.<anonymous> (<removed-from-log>/gui-test-template/node_modules/request/request.js:1161:10)
at Request.emit (events.js:209:13)
at Request.EventEmitter.emit (domain.js:476:20)
at IncomingMessage.<anonymous> (<removed-from-log>/gui-test-template/node_modules/request/request.js:1083:12)
at Object.onceWrapper (events.js:298:28)
2019-10-25T15:02:11.822Z INFO webdriver: COMMAND takeScreenshot()
2019-10-25T15:02:11.822Z INFO webdriver: [GET] <removed-from-log>:4444/wd/hub/session/3d3476a6-b768-4b42-93d6-afafbb7bd034/screenshot
2019-10-25T15:02:11.824Z INFO webdriver: COMMAND navigateTo("http://www.seleniumeasy.com/test/")
2019-10-25T15:02:11.824Z INFO webdriver: [POST] <removed-from-log>:4444/wd/hub/session/3d3476a6-b768-4b42-93d6-afafbb7bd034/url
2019-10-25T15:02:11.824Z INFO webdriver: DATA { url: 'http://www.seleniumeasy.com/test/' }
2019-10-25T15:02:11.833Z DEBUG webdriver: request failed due to status 13
2019-10-25T15:02:11.833Z WARN webdriver: Request failed due to Session [3d3476a6-b768-4b42-93d6-afafbb7bd034] was terminated due to SO_TIMEOUT
2019-10-25T15:02:11.833Z INFO webdriver: Retrying 1/3
2019-10-25T15:02:11.833Z INFO webdriver: [GET] <removed-from-log>:4444/wd/hub/session/3d3476a6-b768-4b42-93d6-afafbb7bd034/screenshot
2019-10-25T15:02:11.835Z DEBUG webdriver: request failed due to status 13
2019-10-25T15:02:11.835Z WARN webdriver: Request failed due to Session [3d3476a6-b768-4b42-93d6-afafbb7bd034] was terminated due to SO_TIMEOUT
2019-10-25T15:02:11.835Z INFO webdriver: Retrying 1/3
2019-10-25T15:02:11.835Z INFO webdriver: [POST] <removed-from-log>:4444/wd/hub/session/3d3476a6-b768-4b42-93d6-afafbb7bd034/url
2019-10-25T15:02:11.835Z INFO webdriver: DATA { url: 'http://www.seleniumeasy.com/test/' }
2019-10-25T15:02:11.842Z DEBUG webdriver: request failed due to status 13
2019-10-25T15:02:11.842Z WARN webdriver: Request failed due to Session [3d3476a6-b768-4b42-93d6-afafbb7bd034] was terminated due to SO_TIMEOUT
2019-10-25T15:02:11.842Z INFO webdriver: Retrying 2/3
2019-10-25T15:02:11.842Z INFO webdriver: [GET] <removed-from-log>:4444/wd/hub/session/3d3476a6-b768-4b42-93d6-afafbb7bd034/screenshot
2019-10-25T15:02:11.842Z DEBUG webdriver: request failed due to status 13
2019-10-25T15:02:11.842Z WARN webdriver: Request failed due to Session [3d3476a6-b768-4b42-93d6-afafbb7bd034] was terminated due to SO_TIMEOUT
2019-10-25T15:02:11.843Z INFO webdriver: Retrying 2/3
2019-10-25T15:02:11.843Z INFO webdriver: [POST] <removed-from-log>:4444/wd/hub/session/3d3476a6-b768-4b42-93d6-afafbb7bd034/url
2019-10-25T15:02:11.843Z INFO webdriver: DATA { url: 'http://www.seleniumeasy.com/test/' }
2019-10-25T15:02:11.847Z DEBUG webdriver: request failed due to status 13
2019-10-25T15:02:11.848Z WARN webdriver: Request failed due to Session [3d3476a6-b768-4b42-93d6-afafbb7bd034] was terminated due to SO_TIMEOUT
2019-10-25T15:02:11.848Z INFO webdriver: Retrying 3/3
2019-10-25T15:02:11.848Z INFO webdriver: [GET] <removed-from-log>:4444/wd/hub/session/3d3476a6-b768-4b42-93d6-afafbb7bd034/screenshot
2019-10-25T15:02:11.848Z DEBUG webdriver: request failed due to status 13
2019-10-25T15:02:11.849Z WARN webdriver: Request failed due to Session [3d3476a6-b768-4b42-93d6-afafbb7bd034] was terminated due to SO_TIMEOUT
2019-10-25T15:02:11.849Z INFO webdriver: Retrying 3/3
2019-10-25T15:02:11.849Z INFO webdriver: [POST] <removed-from-log>:4444/wd/hub/session/3d3476a6-b768-4b42-93d6-afafbb7bd034/url
2019-10-25T15:02:11.849Z INFO webdriver: DATA { url: 'http://www.seleniumeasy.com/test/' }
2019-10-25T15:02:11.853Z DEBUG webdriver: request failed due to status 13
2019-10-25T15:02:11.853Z ERROR webdriver: Request failed due to unknown error: Session [3d3476a6-b768-4b42-93d6-afafbb7bd034] was terminated due to SO_TIMEOUT
at getErrorFromResponseBody (<removed-from-log>/gui-test-template/node_modules/webdriver/build/utils.js:350:10)
at Request._callback (<removed-from-log>/gui-test-template/node_modules/webdriver/build/request.js:121:64)
at Request.self.callback (<removed-from-log>/gui-test-template/node_modules/request/request.js:185:22)
at Request.emit (events.js:209:13)
at Request.EventEmitter.emit (domain.js:476:20)
at Request.<anonymous> (<removed-from-log>/gui-test-template/node_modules/request/request.js:1161:10)
at Request.emit (events.js:209:13)
at Request.EventEmitter.emit (domain.js:476:20)
at IncomingMessage.<anonymous> (<removed-from-log>/gui-test-template/node_modules/request/request.js:1083:12)
at Object.onceWrapper (events.js:298:28)
2019-10-25T15:02:11.855Z DEBUG webdriver: request failed due to status 13
2019-10-25T15:02:11.855Z ERROR webdriver: Request failed due to unknown error: Session [3d3476a6-b768-4b42-93d6-afafbb7bd034] was terminated due to SO_TIMEOUT
at getErrorFromResponseBody (<removed-from-log>/gui-test-template/node_modules/webdriver/build/utils.js:350:10)
at Request._callback (<removed-from-log>/gui-test-template/node_modules/webdriver/build/request.js:121:64)
at Request.self.callback (<removed-from-log>/gui-test-template/node_modules/request/request.js:185:22)
at Request.emit (events.js:209:13)
at Request.EventEmitter.emit (domain.js:476:20)
at Request.<anonymous> (<removed-from-log>/gui-test-template/node_modules/request/request.js:1161:10)
at Request.emit (events.js:209:13)
at Request.EventEmitter.emit (domain.js:476:20)
at IncomingMessage.<anonymous> (<removed-from-log>/gui-test-template/node_modules/request/request.js:1083:12)
at Object.onceWrapper (events.js:298:28)
2019-10-25T15:02:11.856Z INFO webdriver: COMMAND findElement("css selector", "a#basic_example")
2019-10-25T15:02:11.857Z INFO webdriver: [POST] <removed-from-log>:4444/wd/hub/session/3d3476a6-b768-4b42-93d6-afafbb7bd034/element
2019-10-25T15:02:11.857Z INFO webdriver: DATA { using: 'css selector', value: 'a#basic_example' }
2019-10-25T15:02:11.863Z DEBUG webdriver: request failed due to status 13
2019-10-25T15:02:11.863Z WARN webdriver: Request failed due to Session [3d3476a6-b768-4b42-93d6-afafbb7bd034] was terminated due to SO_TIMEOUT
2019-10-25T15:02:11.863Z INFO webdriver: Retrying 1/3
2019-10-25T15:02:11.863Z INFO webdriver: [POST] <removed-from-log>:4444/wd/hub/session/3d3476a6-b768-4b42-93d6-afafbb7bd034/element
2019-10-25T15:02:11.863Z INFO webdriver: DATA { using: 'css selector', value: 'a#basic_example' }
2019-10-25T15:02:11.870Z DEBUG webdriver: request failed due to status 13
2019-10-25T15:02:11.870Z WARN webdriver: Request failed due to Session [3d3476a6-b768-4b42-93d6-afafbb7bd034] was terminated due to SO_TIMEOUT
2019-10-25T15:02:11.870Z INFO webdriver: Retrying 2/3
2019-10-25T15:02:11.870Z INFO webdriver: [POST] <removed-from-log>:4444/wd/hub/session/3d3476a6-b768-4b42-93d6-afafbb7bd034/element
2019-10-25T15:02:11.870Z INFO webdriver: DATA { using: 'css selector', value: 'a#basic_example' }
2019-10-25T15:02:11.877Z DEBUG webdriver: request failed due to status 13
2019-10-25T15:02:11.877Z WARN webdriver: Request failed due to Session [3d3476a6-b768-4b42-93d6-afafbb7bd034] was terminated due to SO_TIMEOUT
2019-10-25T15:02:11.878Z INFO webdriver: Retrying 3/3
2019-10-25T15:02:11.878Z INFO webdriver: [POST] <removed-from-log>:4444/wd/hub/session/3d3476a6-b768-4b42-93d6-afafbb7bd034/element
2019-10-25T15:02:11.878Z INFO webdriver: DATA { using: 'css selector', value: 'a#basic_example' }
2019-10-25T15:02:11.883Z DEBUG webdriver: request failed due to status 13
2019-10-25T15:02:11.883Z ERROR webdriver: Request failed due to unknown error: Session [3d3476a6-b768-4b42-93d6-afafbb7bd034] was terminated due to SO_TIMEOUT
at getErrorFromResponseBody (<removed-from-log>/gui-test-template/node_modules/webdriver/build/utils.js:350:10)
at Request._callback (<removed-from-log>/gui-test-template/node_modules/webdriver/build/request.js:121:64)
at Request.self.callback (<removed-from-log>/gui-test-template/node_modules/request/request.js:185:22)
at Request.emit (events.js:209:13)
at Request.EventEmitter.emit (domain.js:476:20)
at Request.<anonymous> (<removed-from-log>/gui-test-template/node_modules/request/request.js:1161:10)
at Request.emit (events.js:209:13)
at Request.EventEmitter.emit (domain.js:476:20)
at IncomingMessage.<anonymous> (<removed-from-log>/gui-test-template/node_modules/request/request.js:1083:12)
at Object.onceWrapper (events.js:298:28)
2019-10-25T15:02:11.886Z INFO webdriver: COMMAND takeScreenshot()
2019-10-25T15:02:11.887Z INFO webdriver: [GET] <removed-from-log>:4444/wd/hub/session/3d3476a6-b768-4b42-93d6-afafbb7bd034/screenshot
2019-10-25T15:02:11.895Z DEBUG webdriver: request failed due to status 13
2019-10-25T15:02:11.895Z WARN webdriver: Request failed due to Session [3d3476a6-b768-4b42-93d6-afafbb7bd034] was terminated due to SO_TIMEOUT
2019-10-25T15:02:11.895Z INFO webdriver: Retrying 1/3
2019-10-25T15:02:11.895Z INFO webdriver: [GET] <removed-from-log>:4444/wd/hub/session/3d3476a6-b768-4b42-93d6-afafbb7bd034/screenshot
2019-10-25T15:02:11.896Z INFO webdriver: COMMAND getLogTypes()
2019-10-25T15:02:11.897Z INFO webdriver: [GET] <removed-from-log>:4444/wd/hub/session/3d3476a6-b768-4b42-93d6-afafbb7bd034/log/types
2019-10-25T15:02:11.901Z DEBUG webdriver: request failed due to status 13
2019-10-25T15:02:11.901Z WARN webdriver: Request failed due to Session [3d3476a6-b768-4b42-93d6-afafbb7bd034] was terminated due to SO_TIMEOUT
2019-10-25T15:02:11.901Z INFO webdriver: Retrying 2/3
2019-10-25T15:02:11.901Z INFO webdriver: [GET] <removed-from-log>:4444/wd/hub/session/3d3476a6-b768-4b42-93d6-afafbb7bd034/screenshot
2019-10-25T15:02:11.905Z DEBUG webdriver: request failed due to status 13
2019-10-25T15:02:11.905Z WARN webdriver: Request failed due to Session [3d3476a6-b768-4b42-93d6-afafbb7bd034] was terminated due to SO_TIMEOUT
2019-10-25T15:02:11.905Z INFO webdriver: Retrying 1/3
2019-10-25T15:02:11.905Z INFO webdriver: [GET] <removed-from-log>:4444/wd/hub/session/3d3476a6-b768-4b42-93d6-afafbb7bd034/log/types
2019-10-25T15:02:11.910Z DEBUG webdriver: request failed due to status 13
2019-10-25T15:02:11.910Z WARN webdriver: Request failed due to Session [3d3476a6-b768-4b42-93d6-afafbb7bd034] was terminated due to SO_TIMEOUT
2019-10-25T15:02:11.910Z INFO webdriver: Retrying 3/3
2019-10-25T15:02:11.910Z INFO webdriver: [GET] <removed-from-log>:4444/wd/hub/session/3d3476a6-b768-4b42-93d6-afafbb7bd034/screenshot
2019-10-25T15:02:11.912Z DEBUG webdriver: request failed due to status 13
2019-10-25T15:02:11.912Z WARN webdriver: Request failed due to Session [3d3476a6-b768-4b42-93d6-afafbb7bd034] was terminated due to SO_TIMEOUT
2019-10-25T15:02:11.912Z INFO webdriver: Retrying 2/3
2019-10-25T15:02:11.912Z INFO webdriver: [GET] <removed-from-log>:4444/wd/hub/session/3d3476a6-b768-4b42-93d6-afafbb7bd034/log/types
2019-10-25T15:02:11.916Z DEBUG webdriver: request failed due to status 13
2019-10-25T15:02:11.916Z ERROR webdriver: Request failed due to unknown error: Session [3d3476a6-b768-4b42-93d6-afafbb7bd034] was terminated due to SO_TIMEOUT
at getErrorFromResponseBody (<removed-from-log>/gui-test-template/node_modules/webdriver/build/utils.js:350:10)
at Request._callback (<removed-from-log>/gui-test-template/node_modules/webdriver/build/request.js:121:64)
at Request.self.callback (<removed-from-log>/gui-test-template/node_modules/request/request.js:185:22)
at Request.emit (events.js:209:13)
at Request.EventEmitter.emit (domain.js:476:20)
at Request.<anonymous> (<removed-from-log>/gui-test-template/node_modules/request/request.js:1161:10)
at Request.emit (events.js:209:13)
at Request.EventEmitter.emit (domain.js:476:20)
at IncomingMessage.<anonymous> (<removed-from-log>/gui-test-template/node_modules/request/request.js:1083:12)
at Object.onceWrapper (events.js:298:28)
2019-10-25T15:02:11.918Z DEBUG webdriver: request failed due to status 13
2019-10-25T15:02:11.918Z WARN webdriver: Request failed due to Session [3d3476a6-b768-4b42-93d6-afafbb7bd034] was terminated due to SO_TIMEOUT
2019-10-25T15:02:11.919Z INFO webdriver: Retrying 3/3
2019-10-25T15:02:11.919Z INFO webdriver: [GET] <removed-from-log>:4444/wd/hub/session/3d3476a6-b768-4b42-93d6-afafbb7bd034/log/types
2019-10-25T15:02:11.924Z DEBUG webdriver: request failed due to status 13
2019-10-25T15:02:11.924Z ERROR webdriver: Request failed due to unknown error: Session [3d3476a6-b768-4b42-93d6-afafbb7bd034] was terminated due to SO_TIMEOUT
at getErrorFromResponseBody (<removed-from-log>/gui-test-template/node_modules/webdriver/build/utils.js:350:10)
at Request._callback (<removed-from-log>/gui-test-template/node_modules/webdriver/build/request.js:121:64)
at Request.self.callback (<removed-from-log>/gui-test-template/node_modules/request/request.js:185:22)
at Request.emit (events.js:209:13)
at Request.EventEmitter.emit (domain.js:476:20)
at Request.<anonymous> (<removed-from-log>/gui-test-template/node_modules/request/request.js:1161:10)
at Request.emit (events.js:209:13)
at Request.EventEmitter.emit (domain.js:476:20)
at IncomingMessage.<anonymous> (<removed-from-log>/gui-test-template/node_modules/request/request.js:1083:12)
at Object.onceWrapper (events.js:298:28)
2019-10-25T15:02:11.925Z INFO webdriver: COMMAND deleteSession()
2019-10-25T15:02:11.925Z INFO webdriver: [DELETE] <removed-from-log>:4444/wd/hub/session/3d3476a6-b768-4b42-93d6-afafbb7bd034
2019-10-25T15:02:11.931Z DEBUG webdriver: request failed due to status 13
2019-10-25T15:02:11.931Z WARN webdriver: Request failed due to Session [3d3476a6-b768-4b42-93d6-afafbb7bd034] was terminated due to SO_TIMEOUT
2019-10-25T15:02:11.931Z INFO webdriver: Retrying 1/3
2019-10-25T15:02:11.931Z INFO webdriver: [DELETE] <removed-from-log>:4444/wd/hub/session/3d3476a6-b768-4b42-93d6-afafbb7bd034
2019-10-25T15:02:11.937Z DEBUG webdriver: request failed due to status 13
2019-10-25T15:02:11.937Z WARN webdriver: Request failed due to Session [3d3476a6-b768-4b42-93d6-afafbb7bd034] was terminated due to SO_TIMEOUT
2019-10-25T15:02:11.937Z INFO webdriver: Retrying 2/3
2019-10-25T15:02:11.937Z INFO webdriver: [DELETE] <removed-from-log>:4444/wd/hub/session/3d3476a6-b768-4b42-93d6-afafbb7bd034
2019-10-25T15:02:11.943Z DEBUG webdriver: request failed due to status 13
2019-10-25T15:02:11.943Z WARN webdriver: Request failed due to Session [3d3476a6-b768-4b42-93d6-afafbb7bd034] was terminated due to SO_TIMEOUT
2019-10-25T15:02:11.943Z INFO webdriver: Retrying 3/3
2019-10-25T15:02:11.943Z INFO webdriver: [DELETE] <removed-from-log>:4444/wd/hub/session/3d3476a6-b768-4b42-93d6-afafbb7bd034
2019-10-25T15:02:11.949Z DEBUG webdriver: request failed due to status 13
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment