Open mkiewicz opened 6 years ago
More details: Appium 1.8.0
Appium Logs
ac5026:~ xxm5k$ appium -a 10.96.19.66 --nodeconfig ./Selenium/Appium/iOS-10.3/ipad_config.json
(node:44320) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead.
[Appium] Welcome to Appium v1.8.0
[Appium] Non-default server args:
[Appium] address: 10.96.19.66
[Appium] nodeconfig: ./Selenium/Appium/iOS-10.3/ipad_config.json
[debug] [Appium] Starting auto register thread for grid. Will try to register every 5000 ms.
[Appium] Appium REST http interface listener started on 10.96.19.66:4723
[debug] [Appium] Appium successfully registered with the grid on http://10.96.16.252:4444
[debug] [Appium] Grid registration error: Cannot find proxy with ID =http://10.96.16.58:4724 in the registry.
[debug] [Appium] Appium successfully registered with the grid on http://10.96.16.252:4444
[HTTP] --> POST /wd/hub/session
[HTTP] {"desiredCapabilities":{"browserName":"Safari","deviceName":"iPad Simulator","platform":"MAC","platformName":"iOS","platformVersion":"10.3","version":""},"capabilities":{"desiredCapabilities":{"browserName":"Safari","deviceName":"iPad Simulator","platform":"MAC","platformName":"iOS","platformVersion":"10.3","version":""},"firstMatch":[{"browserName":"Safari","platformName":"ios"}]}}
[debug] [MJSONWP] Calling AppiumDriver.createSession() with args: [{"browserName":"Safari","deviceName":"iPad Simulator","platform":"MAC","platformName":"iOS","platformVersion":"10.3","version":""},null,{"desiredCapabilities":{"browserName":"Safari","deviceName":"iPad Simulator","platform":"MAC","platformName":"iOS","platformVersion":"10.3","version":""},"firstMatch":[{"browserName":"Safari","platformName":"ios"}]}]
[debug] [BaseDriver] Event 'newSessionRequested' logged at 1527178966690 (12:22:46 GMT-0400 (EDT))
[Appium] Could not parse W3C capabilities: 'deviceName' can't be blank. Falling back to JSONWP protocol.
[Appium] The following capabilities were provided in the JSONWP desired capabilities that are missing in W3C capabilities: ["browserName","deviceName","platform","platformName","platformVersion","version"]. Falling back to JSONWP protocol.
[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] Creating new XCUITestDriver (v2.76.2) session
[Appium] Capabilities:
[Appium] browserName: Safari
[Appium] deviceName: iPad Simulator
[Appium] platform: MAC
[Appium] platformName: iOS
[Appium] platformVersion: 10.3
[Appium] version:
[debug] [BaseDriver] Creating session with MJSONWP desired capabilities: {"browserName":"Safari","de...
[BaseDriver] The following capabilities were provided, but are not recognized by appium: platform, version.
[BaseDriver] Session created with session id: a65eb583-5461-443a-99d2-60a466a13478
[debug] [XCUITest] Current user: 'xxm5k'
[debug] [XCUITest] Xcode version set to '9.3.1' (tools v9.3.0.0.1.1521514116)
[debug] [XCUITest] iOS SDK Version set to '11.3'
[debug] [BaseDriver] Event 'xcodeDetailsRetrieved' logged at 1527178966893 (12:22:46 GMT-0400 (EDT))
[debug] [XCUITest] Changing deviceName from 'iPad Simulator' to 'iPad Air'
[iOSSim] Constructing iOS simulator for Xcode version 9.3.1 with udid '0235B038-0BC6-4C57-AF16-4E06C716FCC5'
[XCUITest] Determining device to run tests on: udid: '0235B038-0BC6-4C57-AF16-4E06C716FCC5', real device: false
[XCUITest] Safari test requested
[debug] [BaseDriver] Event 'appConfigured' logged at 1527178967182 (12:22:47 GMT-0400 (EDT))
[debug] [BaseDriver] Event 'resetStarted' logged at 1527178967182 (12:22:47 GMT-0400 (EDT))
[debug] [iOSSim] Cleaning mobile safari data files
[debug] [iOSSim] Checking whether simulator has been run before
[debug] [iOSSim] Simulator has been run before
[debug] [iOSSim] Checking whether simulator has been run before
[debug] [iOSSim] Simulator has been run before
[debug] [iOSSim] Building bundle path map
[debug] [BaseDriver] Event 'resetComplete' logged at 1527178967742 (12:22:47 GMT-0400 (EDT))
[debug] [XCUITest] Starting log capture for iOS Simulator with udid '0235B038-0BC6-4C57-AF16-4E06C716FCC5', using 'xcrun simctl spawn 0235B038-0BC6-4C57-AF16-4E06C716FCC5 log stream --style compact'
[debug] [BaseDriver] Event 'logCaptureStarted' logged at 1527178968019 (12:22:48 GMT-0400 (EDT))
[XCUITest] Setting up simulator
[debug] [iOS] No reason to set locale
[debug] [iOS] No iOS / app preferences to set
[debug] [iOSSim] Matched 1 Simulator cache item for cleanup: /Users/xxm5k/Library/Developer/CoreSimulator/Devices/0235B038-0BC6-4C57-AF16-4E06C716FCC5/data/Library/Caches/com.apple.mobile.installd.staging
[debug] [iOSSim] Setting common Simulator preferences to {"ConnectHardwareKeyboard":false}
[debug] [iOSSim] Updated 0235B038-0BC6-4C57-AF16-4E06C716FCC5 Simulator preferences at '/Users/xxm5k/Library/Preferences/com.apple.iphonesimulator.plist' with {"ConnectHardwareKeyboard":false}
[debug] [iOSSim] The count of running Simulator UI client instances is 1
[iOSSim] Both Simulator with UDID 0235B038-0BC6-4C57-AF16-4E06C716FCC5 and the UI client are currently running
[debug] [BaseDriver] Event 'simStarted' logged at 1527178968804 (12:22:48 GMT-0400 (EDT))
[XCUITest] Using WDA path: '/usr/local/lib/node_modules/appium/node_modules/appium-xcuitest-driver/WebDriverAgent'
[XCUITest] Using WDA agent: '/usr/local/lib/node_modules/appium/node_modules/appium-xcuitest-driver/WebDriverAgent/WebDriverAgent.xcodeproj'
[debug] [XCUITest] No obsolete cached processes from previous WDA sessions listening on port 8100 have been found
[debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8100/status] with no body
[debug] [JSONWP Proxy] Got response with status 200: "{\n \"value\" : {\n \"state\" : \"success\",\n \"os\" : {\n \"name\" : \"iOS\",\n \"version\" : \"10.3.1\",\n \"sdkVersion\" : \"11.3\"\n },\n \"ios\" : {\n \"simulatorVersion\" : \"10.3.1\",\n \"ip\" : \"10.96.19.10\"\n },\n \"build\" : {\n \"time\" : \"May 24 2018 11:26:08\"\n }\n },\n \"sessionId\" : \"D49415C2-ECB6-48A8-8D0A-3F0D513CAD29\",\n \"status\" : 0\n}"
[XCUITest] Will reuse previously cached WDA instance at 'http://localhost:8100/'. Set the wdaLocalPort capability to a value different from 8100 if this is an undesired behavior.
[debug] [BaseDriver] Event 'wdaStartAttempted' logged at 1527178968912 (12:22:48 GMT-0400 (EDT))
[XCUITest] Using provided WebdriverAgent at 'http://localhost:8100/'
[debug] [BaseDriver] Event 'wdaSessionAttempted' logged at 1527178968913 (12:22:48 GMT-0400 (EDT))
[debug] [XCUITest] Sending createSession command to WDA
[debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8100/status] with no body
[debug] [JSONWP Proxy] Got response with status 200: "{\n \"value\" : {\n \"state\" : \"success\",\n \"os\" : {\n \"name\" : \"iOS\",\n \"version\" : \"10.3.1\",\n \"sdkVersion\" : \"11.3\"\n },\n \"ios\" : {\n \"simulatorVersion\" : \"10.3.1\",\n \"ip\" : \"10.96.19.10\"\n },\n \"build\" : {\n \"time\" : \"May 24 2018 11:26:08\"\n }\n },\n \"sessionId\" : \"D49415C2-ECB6-48A8-8D0A-3F0D513CAD29\",\n \"status\" : 0\n}"
[debug] [JSONWP Proxy] Proxying [POST /session] to [POST http://localhost:8100/session] with body: {"desiredCapabilities":{"bundleId":"com.apple.mobilesafari","arguments":["-u","http://10.96.19.66:4723/welcome"],"environment":{},"shouldWaitForQuiescence":true,"shouldUseTestManagerForVisibilityDetection":false,"maxTypingFrequency":60,"shouldUseSingletonTestManager":true}}
[HTTP] --> GET /welcome
[HTTP] {}
[debug] [HTTP] Sending welcome response with params: {"message":"Let's browse!"}
[HTTP] <-- GET /welcome 200 10 ms - 124
[HTTP]
[debug] [JSONWP Proxy] Got response with status 200: {"value":{"sessionId":"28E4B918-B62B-4F00-952D-2DD2505850C8","capabilities":{"device":"ipad","browserName":"Safari","sdkVersion":"10.3.1","CFBundleIdentifier":"com.apple.mobilesafari"}},"sessionId":"28E4B918-B62B-4F00-952D-2DD2505850C8","status":0}
[debug] [BaseDriver] Event 'wdaSessionStarted' logged at 1527178971932 (12:22:51 GMT-0400 (EDT))
[debug] [BaseDriver] Event 'wdaStarted' logged at 1527178971933 (12:22:51 GMT-0400 (EDT))
[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 1527178971933 (12:22:51 GMT-0400 (EDT))
[debug] [XCUITest] Waiting for initial webview
[debug] [iOS] Navigating to most recently opened webview
[debug] [iOS] Retrieving contexts and views
[debug] [iOS] Selecting by url: true (expected url: 'http://10.96.19.66:4723/welcome')
[debug] [RemoteDebugger] useNewSafari --> false
[debug] [RemoteDebugger] Connecting to remote debugger through TCP: ::1:27753
[debug] [RemoteDebugger] Debugger socket connected
[debug] [RemoteDebugger] Sending connection key request
[debug] [RemoteDebugger] Sending '_rpc_reportIdentifier:' message to remote debugger
[debug] [RemoteDebugger] Receiving data from remote debugger: '{"argument":{"WIRSimulatorBuildKey":"14E8301","WIRSimulatorProductVersionKey":"10.3.1","WIRSimulatorNameKey":"iPad"},"selector":"_rpc_reportSetup:"}'
[debug] [RemoteDebugger] Received response from socket send: '["iPad","14E8301","10.3.1"]'
[debug] [RemoteDebugger] Receiving data from remote debugger: '{"argument":{"WIRApplicationDictionaryKey":{"PID:44352":{"WIRRemoteAutomationEnabledKey":false,"WIRIsApplicationActiveKey":1,"WIRIsApplicationReadyKey":true,"WIRApplicationIdentifierKey":"PID:44352","WIRIsApplicationProxyKey":true,"WIRApplicationNameKey":"com.apple.WebKit.WebContent","WIRApplicationBundleIdentifierKey":"com.apple.WebKit.WebContent","WIRHostApplicationIdentifierKey":"PID:44350"},"PID:44350":{"WIRRemoteAutomationEnabledKey":false,"WIRIsApplicationActiveKey":1,"WIRIsApplicationReadyKey":true,"WIRApplicationIdentifierKey":"PID:44350","WIRIsApplicationProxyKey":false,"WIRApplicationNameKey":"Safari","WIRApplicationBundleIdentifierKey":"com.apple.mobilesafari"}}},"selector":"_rpc_reportConnectedApplicationList:"}'
[debug] [RemoteDebugger] Receiving data from remote debugger: '{"argument":{"WIRDriverDictionaryKey":{}},"selector":"_rpc_reportConnectedDriverList:"}'
[debug] [RemoteDebugger] Notified of connected drivers: {}.
[debug] [RemoteDebugger] Connected to application
[debug] [RemoteDebugger] Sim name: iPad
[debug] [RemoteDebugger] Sim build: 14E8301
[debug] [RemoteDebugger] Sim platform version: 10.3.1
[debug] [RemoteDebugger] Selecting application
[debug] [RemoteDebugger] Current applications available:
[debug] [RemoteDebugger] Application: 'PID:44352'
[debug] [RemoteDebugger] id: "PID:44352"
[debug] [RemoteDebugger] isProxy: true
[debug] [RemoteDebugger] name: "com.apple.WebKit.WebContent"
[debug] [RemoteDebugger] bundleId: "com.apple.WebKit.WebContent"
[debug] [RemoteDebugger] hostId: "PID:44350"
[debug] [RemoteDebugger] isActive: 1
[debug] [RemoteDebugger] isAutomationEnabled: false
[debug] [RemoteDebugger] Application: 'PID:44350'
[debug] [RemoteDebugger] id: "PID:44350"
[debug] [RemoteDebugger] isProxy: false
[debug] [RemoteDebugger] name: "Safari"
[debug] [RemoteDebugger] bundleId: "com.apple.mobilesafari"
[debug] [RemoteDebugger] hostId: undefined
[debug] [RemoteDebugger] isActive: 1
[debug] [RemoteDebugger] isAutomationEnabled: false
[debug] [RemoteDebugger] Found app id key 'PID:44350' for bundle 'com.apple.mobilesafari'
[debug] [RemoteDebugger] Found separate bundleId 'com.apple.WebKit.WebContent' acting as proxy for 'com.apple.mobilesafari', with app id 'PID:44352'
[debug] [RemoteDebugger] Trying out the possible app ids: PID:44352
[debug] [RemoteDebugger] Selecting app PID:44352 (try #1 of 20)
[debug] [RemoteDebugger] Sending '_rpc_forwardGetListing:' message to remote debugger
[debug] [RemoteDebugger] Receiving data from remote debugger: '{"argument":{"WIRApplicationIdentifierKey":"PID:44352","WIRListingKey":{"1":{"WIRTitleKey":"Appium/welcome","WIRTypeKey":"WIRTypeWeb","WIRURLKey":"http://10.96.19.66:4723/welcome","WIRPageIdentifierKey":1}}},"selector":"_rpc_applicationSentListing:"}'
[debug] [RemoteDebugger] Received response from socket send: '["PID:44352",{"1":{"WIRTitleKey":"Appium/welcom...'
[debug] [RemoteDebugger] Received page change notice for app 'PID:44352' but listening for 'null'. Ignoring.
[debug] [RemoteDebugger] Received altered app id, updating from 'null' to 'PID:44352'
[debug] [RemoteDebugger] Finally selecting app PID:44352: [{"id":1,"title":"Appium/welcome","url":"http://10.96.19.66:4723/welcome","isKey":false}]
[debug] [iOS] Picking webview 'WEBVIEW_44352.1'
[debug] [iOS] Attempting to set context to 'WEBVIEW_44352.1'
[debug] [RemoteDebugger] Selecting page '1' on app 'PID:44352' and forwarding socket setup
[debug] [RemoteDebugger] Sending '_rpc_forwardSocketSetup:' message to remote debugger
[debug] [RemoteDebugger] Sender key set
[debug] [RemoteDebugger] Sending '_rpc_forwardSocketData:' message to remote debugger
[debug] [RemoteDebugger] Received applicationSentData response
[debug] [RemoteDebugger] Found data handler for response
[debug] [RemoteDebugger] Received data response from socket send: '{}'
[debug] [RemoteDebugger] Original command: enablePage
[debug] [RemoteDebugger] Enabled activity on page
[debug] [RemoteDebugger] Checking document readyState
[debug] [RemoteDebugger] Sending javascript command (function (){ return document.readyState; })()
[debug] [RemoteDebugger] Sending '_rpc_forwardSocketData:' message to remote debugger
[debug] [RemoteDebugger] Received applicationSentData response
[debug] [RemoteDebugger] Found data handler for response
[debug] [RemoteDebugger] Received data response from socket send: 'complete'
[debug] [RemoteDebugger] Original command: sendJSCommand
[debug] [RemoteDebugger] readyState was "complete"
[debug] [RemoteDebugger] Unregistering from page readiness notifications
[debug] [BaseDriver] Event 'initialWebviewNavigated' logged at 1527178972023 (12:22:52 GMT-0400 (EDT))
[Appium] New XCUITestDriver session created successfully, session a65eb583-5461-443a-99d2-60a466a13478 added to master session list
[debug] [BaseDriver] Event 'newSessionStarted' logged at 1527178972026 (12:22:52 GMT-0400 (EDT))
[debug] [MJSONWP] Responding to client with driver.createSession() result: {"webStorageEnabled":false,"locationContextEnabled":false,"browserName":"Safari","platform":"MAC","javascriptEnabled":true,"databaseEnabled":false,"takesScreenshot":true,"networkConnectionEnabled":false,"deviceName":"iPad Simulator","platformName":"iOS","platformVersion":"10.3","version":"","udid":"0235B038-0BC6-4C57-AF16-4E06C716FCC5"}
[HTTP] <-- POST /wd/hub/session 200 5343 ms - 410
[HTTP]
[HTTP] --> POST /wd/hub/session/a65eb583-5461-443a-99d2-60a466a13478/window/current/maximize
[HTTP] {"windowHandle":"current","handle":"current"}
[debug] [W3C] Calling AppiumDriver.maximizeWindow() with args: ["current","a65eb583-5461-443a-99d2-60a466a13478"]
[debug] [XCUITest] Executing command 'maximizeWindow'
[HTTP] <-- POST /wd/hub/session/a65eb583-5461-443a-99d2-60a466a13478/window/current/maximize 404 11 ms - 3839
[HTTP]
[HTTP] --> POST /wd/hub/session/a65eb583-5461-443a-99d2-60a466a13478/timeouts
[HTTP] {"type":"implicit","ms":10000}
[debug] [W3C] Bad parameters: BadParametersError: Parameters were incorrect. We wanted "W3C protocol expects any of script, pageLoad or implicit to be set" and you sent {"type":"implicit","ms":10000}
[HTTP] <-- POST /wd/hub/session/a65eb583-5461-443a-99d2-60a466a13478/timeouts 400 24 ms - 4196
[HTTP]
[HTTP] --> POST /wd/hub/session/a65eb583-5461-443a-99d2-60a466a13478/url
[HTTP] {"url":"https://soa-t.ameritas.com/"}
[debug] [W3C] Calling AppiumDriver.setUrl() with args: ["https://soa-t.ameritas.com/","a65eb583-5461-443a-99d2-60a466a13478"]
[debug] [XCUITest] Executing command 'setUrl'
[debug] [iOS] Attempting to set url 'https://soa-t.ameritas.com/'
[debug] [RemoteDebugger] Navigating to new URL: https://soa-t.ameritas.com/
[debug] [RemoteDebugger] Sending '_rpc_forwardSocketData:' message to remote debugger
[debug] [RemoteDebugger] Received applicationSentData response
[debug] [RemoteDebugger] Found data handler for response
[debug] [RemoteDebugger] Received data response from socket send: '{}'
[debug] [RemoteDebugger] Original command: setUrl
[debug] [RemoteDebugger] Received applicationSentData response
[debug] [RemoteDebugger] Receiving data from remote debugger: '{"argument":{"WIRApplicationIdentifierKey":"PID:44352","WIRListingKey":{"1":{"WIRTitleKey":"Appium/welcome","WIRTypeKey":"WIRTypeWeb","WIRURLKey":"http://10.96.19.66:4723/welcome","WIRPageIdentifierKey":1}}},"selector":"_rpc_applicationSentListing:"}'
[debug] [RemoteDebugger] Page changed: {"1":{"WIRTitleKey":"Appium/welcome","WIRTypeKey":"WIRTypeWeb","WIRURLKey":"http://10.96.19.66:4723/welcome","WIRPageIdentifierKey":1}}
[debug] [iOS] Remote debugger notified us of a new page listing: {"appIdKey":"44352","pageArray":[{"id":1,"title":"Appium/welcome","url":"http://10.96.19.66:4723/welcome","isKey":false}]}
[debug] [iOS] No key id found. Choosing first id from page array
[debug] [iOS] Checking if page needs to load
[debug] [iOS] New page listing is same as old, doing nothing
[debug] [iOS] Redirected from 'https://soa-t.ameritas.com/' to 'http://10.96.19.66:4723/welcome'
[debug] [RemoteDebugger] Received applicationSentData response
[debug] [RemoteDebugger] Received applicationSentData response
[debug] [RemoteDebugger] Received applicationSentData response
[debug] [RemoteDebugger] Frame navigated, unloading page
[debug] [RemoteDebugger] No frame navigation callback set.
[debug] [RemoteDebugger] Receiving data from remote debugger: '{"argument":{"WIRApplicationIdentifierKey":"PID:44352","WIRListingKey":{"1":{"WIRTitleKey":"Ameritas","WIRTypeKey":"WIRTypeWeb","WIRURLKey":"https://soa-t.ameritas.com/","WIRPageIdentifierKey":1,"WIRConnectionIdentifierKey":"42a38b5a-27a6-4b09-9972-910adf3b147a"}}},"selector":"_rpc_applicationSentListing:"}'
[debug] [RemoteDebugger] Page changed: {"1":{"WIRTitleKey":"Ameritas","WIRTypeKey":"WIRTypeWeb","WIRURLKey":"https://soa-t.ameritas.com/","WIRPageIdentifierKey":1,"WIRConnectionIdentifierKey":"42a38b5a-27a6-4b09-9972-910adf3b147a"}}
[debug] [iOS] Remote debugger notified us of a new page listing: {"appIdKey":"44352","pageArray":[{"id":1,"title":"Ameritas","url":"https://soa-t.ameritas.com/","isKey":true}]}
[debug] [iOS] Checking if page needs to load
[debug] [iOS] New page listing is same as old, doing nothing
[debug] [iOS] Redirected from 'http://10.96.19.66:4723/welcome' to 'https://soa-t.ameritas.com/'
[debug] [RemoteDebugger] Receiving data from remote debugger: '{"argument":{"WIRRemoteAutomationEnabledKey":false,"WIRIsApplicationActiveKey":1,"WIRIsApplicationReadyKey":true,"WIRApplicationIdentifierKey":"PID:44352","WIRIsApplicationProxyKey":true,"WIRApplicationNameKey":"com.apple.WebKit.WebContent","WIRApplicationBundleIdentifierKey":"com.apple.WebKit.WebContent","WIRHostApplicationIdentifierKey":"PID:44350"},"selector":"_rpc_applicationUpdated:"}'
[debug] [RemoteDebugger] Notified that application 'PID:44352' has been updated.
[debug] [RemoteDebugger] Received applicationSentData response
[debug] [RemoteDebugger] Received applicationSentData response
[debug] [RemoteDebugger] Received applicationSentData response
[debug] [RemoteDebugger] Page loaded, verifying whether ready
[debug] [RemoteDebugger] Receiving data from remote debugger: '{"argument":{"WIRApplicationIdentifierKey":"PID:44352","WIRListingKey":{"1":{"WIRTitleKey":"Ameritas","WIRTypeKey":"WIRTypeWeb","WIRURLKey":"https://soa-t.ameritas.com/","WIRPageIdentifierKey":1,"WIRConnectionIdentifierKey":"42a38b5a-27a6-4b09-9972-910adf3b147a"}}},"selector":"_rpc_applicationSentListing:"}'
[debug] [RemoteDebugger] Page changed: {"1":{"WIRTitleKey":"Ameritas","WIRTypeKey":"WIRTypeWeb","WIRURLKey":"https://soa-t.ameritas.com/","WIRPageIdentifierKey":1,"WIRConnectionIdentifierKey":"42a38b5a-27a6-4b09-9972-910adf3b147a"}}
[debug] [iOS] Remote debugger notified us of a new page listing: {"appIdKey":"44352","pageArray":[{"id":1,"title":"Ameritas","url":"https://soa-t.ameritas.com/","isKey":true}]}
[debug] [iOS] Checking if page needs to load
[debug] [iOS] New page listing is same as old, doing nothing
[debug] [RemoteDebugger] Waiting for frame navigated message...
[debug] [RemoteDebugger] Checking document readyState
[debug] [RemoteDebugger] Sending javascript command (function (){ return document.readyState; })()
[debug] [RemoteDebugger] Sending '_rpc_forwardSocketData:' message to remote debugger
[debug] [RemoteDebugger] Received applicationSentData response
[debug] [RemoteDebugger] Found data handler for response
[debug] [RemoteDebugger] Received data response from socket send: 'complete'
[debug] [RemoteDebugger] Original command: sendJSCommand
[debug] [RemoteDebugger] readyState was "complete"
[debug] [RemoteDebugger] Page is ready
[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 remote debugger
[debug] [RemoteDebugger] Received applicationSentData response
[debug] [RemoteDebugger] Found data handler for response
[debug] [RemoteDebugger] Received data response from socket send: 'complete'
[debug] [RemoteDebugger] Original command: sendJSCommand
[debug] [RemoteDebugger] readyState was "complete"
[debug] [RemoteDebugger] Page is ready
[debug] [W3C] Responding to client with driver.setUrl() result: null
[HTTP] <-- POST /wd/hub/session/a65eb583-5461-443a-99d2-60a466a13478/url 200 2041 ms - 76
[HTTP]
[HTTP] --> POST /wd/hub/session/a65eb583-5461-443a-99d2-60a466a13478/timeouts
[HTTP] {"type":"implicit","ms":30000}
[debug] [W3C] Bad parameters: BadParametersError: Parameters were incorrect. We wanted "W3C protocol expects any of script, pageLoad or implicit to be set" and you sent {"type":"implicit","ms":30000}
[HTTP] <-- POST /wd/hub/session/a65eb583-5461-443a-99d2-60a466a13478/timeouts 400 4 ms - 4196
[HTTP]
[HTTP] --> POST /wd/hub/session/a65eb583-5461-443a-99d2-60a466a13478/element
[HTTP] {"using":"css selector","value":"div.login--buttonContainer button[class='loginButton']"}
[debug] [W3C] Calling AppiumDriver.findElement() with args: ["css selector","div.login--buttonContainer button[class='loginButton']","a65eb583-5461-443a-99d2-60a466a13478"]
[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 '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 remote debugger
[debug] [RemoteDebugger] Received applicationSentData response
[debug] [RemoteDebugger] Found data handler for response
[debug] [RemoteDebugger] Received data response from socket send: '{"status":0,"value":null}'
[debug] [RemoteDebugger] Original command: sendJSCommand
[debug] [RemoteDebugger] Received result for atom 'find_element' execution: null
[HTTP] <-- POST /wd/hub/session/a65eb583-5461-443a-99d2-60a466a13478/element 404 37 ms - 938
[HTTP]
[HTTP] --> DELETE /wd/hub/session/a65eb583-5461-443a-99d2-60a466a13478
[HTTP] {}
[debug] [W3C] Calling AppiumDriver.deleteSession() with args: ["a65eb583-5461-443a-99d2-60a466a13478"]
[debug] [BaseDriver] Event 'quitSessionRequested' logged at 1527178974330 (12:22:54 GMT-0400 (EDT))
[Appium] Removing session a65eb583-5461-443a-99d2-60a466a13478 from our master session list
[debug] [JSONWP Proxy] Proxying [DELETE /session/a65eb583-5461-443a-99d2-60a466a13478] to [DELETE http://localhost:8100/session/28E4B918-B62B-4F00-952D-2DD2505850C8] with no body
[debug] [RemoteDebugger] Receiving data from remote debugger: '{"argument":{"WIRRemoteAutomationEnabledKey":false,"WIRIsApplicationActiveKey":1,"WIRIsApplicationReadyKey":true,"WIRApplicationIdentifierKey":"PID:44350","WIRIsApplicationProxyKey":false,"WIRApplicationNameKey":"Safari","WIRApplicationBundleIdentifierKey":"com.apple.mobilesafari"},"selector":"_rpc_applicationDisconnected:"}'
[debug] [RemoteDebugger] Application 'PID:44350' disconnected. Removing from app dictionary.
[debug] [RemoteDebugger] Current app is PID:44352
[debug] [RemoteDebugger] Receiving data from remote debugger: '{"argument":{"WIRRemoteAutomationEnabledKey":false,"WIRIsApplicationActiveKey":1,"WIRIsApplicationReadyKey":true,"WIRApplicationIdentifierKey":"PID:44352","WIRIsApplicationProxyKey":true,"WIRApplicationNameKey":"com.apple.WebKit.WebContent","WIRApplicationBundleIdentifierKey":"com.apple.WebKit.WebContent","WIRHostApplicationIdentifierKey":"PID:44350"},"selector":"_rpc_applicationDisconnected:"}'
[debug] [RemoteDebugger] Application 'PID:44352' disconnected. Removing from app dictionary.
[debug] [RemoteDebugger] Current app is PID:44352
[debug] [RemoteDebugger] No longer have app id. Attempting to find new one.
[debug] [JSONWP Proxy] Got response with status 200: "{\n \"value\" : {\n\n },\n \"sessionId\" : \"06E50C2B-E286-4BCB-827B-54BB5F5DBB21\",\n \"status\" : 0\n}"
[debug] [XCUITest] Not clearing log files. Use clearSystemFiles
capability to turn on.
[debug] [XCUITest] In a web session. Removing remote debugger
[debug] [RemoteDebugger] Disconnecting from remote debugger
[debug] [RemoteDebugger] Cleaning up listeners
[debug] [BaseDriver] Event 'quitSessionFinished' logged at 1527178974587 (12:22:54 GMT-0400 (EDT))
[debug] [W3C] Received response: null
[debug] [W3C] But deleting session, so not returning
[debug] [W3C] Responding to client with driver.deleteSession() result: null
[HTTP] <-- DELETE /wd/hub/session/a65eb583-5461-443a-99d2-60a466a13478 200 260 ms - 76
[HTTP]
^C[Appium] Received SIGINT - shutting down
^C
ac5026:~ xxm5k$
First off, apologies in advance if I don't have things set up right as this is the first time I am trying to run Safari Mobile on a Mac device simulator, so I'm kind of in uncharted waters. I have the WebDriverAgent installed on all of the simulators, but now I read that WebDriverAgent has been possibly superseded by XCTWebDriverAgent? Unfortunately, the documentation on how to get that and install it is pretty sparse. As I stand now I am able to kick off Safari Mobile browsers on all of the simulators, but the problem seems to be when trying to enter text into a text field using Selenium
Here are the code snippets that I have used to enter the text with inObject representing the text field page object being sent to the have text entered into it, and where used, js is the JavaScriptExecutor. The goal is to clear the field of any text and then enter a username, enter a password, which make a login button available:
Using javescript to enter text
Safari Mobile enters text into field, but upon entering text into the next (Password) field the text that was entered. Safari pulls up the right click menu
Using straight Selenium to enter text
Same result as the javascript snippet noted above
Using Actions to enter text
Safari Mobile does nothing. No input into either field, but it doesn't throw an exception either. Safari works as it should
The full scripts that I am running work in all other non-mobile browsers (including regular Safari) and also in Android. iOS Mobile Safari is the only problem child. It's almost like there isn't an update event that is firing when the text gets entered. I have seen this before with other browsers, but sending a key press to either hit the Enter button or tabbing of the field with sendKeys has always worked in the past to get past this
I am running Selenium Grid with Windows 10 on the hub. MacBook Air with High Sierra on the node, Selenium 3.12, Safari 11.1 on the MacBook Air, Safari 10.3 and Safari 11.3 on the iOS simulators
I really need to this to work, and again, apologies for my lack of setup. I look forward to hearing back
Thanks Mike Kiewicz