Skip to content

Instantly share code, notes, and snippets.

@richlanc

richlanc/gist:ce445d56d855e38208d7cb6f691a582b

Created October 10, 2017 10:53
Show Gist options
  • Star 0 You must be signed in to star a gist
  • Fork 0 You must be signed in to fork a gist
  • Save richlanc/ce445d56d855e38208d7cb6f691a582b to your computer and use it in GitHub Desktop.
Save richlanc/ce445d56d855e38208d7cb6f691a582b to your computer and use it in GitHub Desktop.
Download ZIP
Raw
gistfile1.txt
pinhead:~ scivisum$ /sv/ios/appium/node_modules/.bin/appium -p 4723
[Appium] Welcome to Appium v1.7.1
[Appium] Appium REST http interface listener started on 0.0.0.0:4723
[HTTP] --> POST /wd/hub/session {"requiredCapabilities":{},"desiredCapabilities":{"deviceName":"iPhone2017-B","automationName":"XCUITest","browserName":"Safari","newCommandTimeout":0,"platformVersion":"11.0","platformName":"iOS"}}
[debug] [MJSONWP] Calling AppiumDriver.createSession() with args: [{"deviceName":"iPhone2017-B","automationName":"XCUITest","browserName":"Safari","newCommandTimeout":0,"platformVersion":"11.0","platformName":"iOS"},{},null]
[debug] [BaseDriver] Event 'newSessionRequested' logged at 1507631253117 (11:27:33 GMT+0100 (BST))
[Appium] Creating new XCUITestDriver (v2.51.1) session
[Appium] Capabilities:
[Appium] deviceName: 'iPhone2017-B'
[Appium] automationName: 'XCUITest'
[Appium] browserName: 'Safari'
[Appium] newCommandTimeout: 0
[Appium] platformVersion: '11.0'
[Appium] platformName: 'iOS'
[BaseDriver] Session created with session id: 068f6558-61d4-49fc-a9d1-c649d1045d13
[debug] [XCUITest] Xcode version set to '9.0' (tools v9.0.0.0.1.1504363082)
[debug] [XCUITest] iOS SDK Version set to '11.0'
[debug] [BaseDriver] Event 'xcodeDetailsRetrieved' logged at 1507631253261 (11:27:33 GMT+0100 (BST))
[XCUITest] Simulator udid not provided, using desired caps to create a new simulator
[debug] [simctl] Creating simulator with name 'appiumTest-068f6558-61d4-49fc-a9d1-c649d1045d13', device type id 'iPhone2017-B' and runtime id '11.0'
[iOSSim] Constructing iOS simulator for Xcode version 9.0 with udid 'E294AB12-F4F0-4309-AEBE-5C1A810E6768'
[XCUITest] Created simulator with udid 'E294AB12-F4F0-4309-AEBE-5C1A810E6768'.
[XCUITest] Determining device to run tests on: udid: 'E294AB12-F4F0-4309-AEBE-5C1A810E6768', real device: false
[XCUITest] Safari test requested
[debug] [BaseDriver] Event 'appConfigured' logged at 1507631254254 (11:27:34 GMT+0100 (BST))
[debug] [BaseDriver] Event 'resetStarted' logged at 1507631254255 (11:27:34 GMT+0100 (BST))
[simctl] Error: simctl error running 'terminate': An error was encountered processing the command (domain=com.apple.CoreSimulator.SimError, code=164):
Unable to lookup in current state: Shutdown
at Object.wrappedLogger.errorAndThrow (../../lib/logging.js:63:13)
at simCommand$ (../../lib/simctl.js:50:11)
at tryCatch (/sv/ios/appium/node_modules/appium/node_modules/babel-runtime/regenerator/runtime.js:67:40)
at GeneratorFunctionPrototype.invoke [as _invoke] (/sv/ios/appium/node_modules/appium/node_modules/babel-runtime/regenerator/runtime.js:315:22)
at GeneratorFunctionPrototype.prototype.(anonymous function) [as throw] (/sv/ios/appium/node_modules/appium/node_modules/babel-runtime/regenerator/runtime.js:100:21)
at GeneratorFunctionPrototype.invoke (/sv/ios/appium/node_modules/appium/node_modules/babel-runtime/regenerator/runtime.js:136:37)
at <anonymous>
at process._tickCallback (internal/process/next_tick.js:188:7)
Error: simctl error running 'terminate': An error was encountered processing the command (domain=com.apple.CoreSimulator.SimError, code=164):
Unable to lookup in current state: Shutdown
at Object.wrappedLogger.errorAndThrow (../../lib/logging.js:63:13)
at simCommand$ (../../lib/simctl.js:50:11)
at tryCatch (/sv/ios/appium/node_modules/appium/node_modules/babel-runtime/regenerator/runtime.js:67:40)
at GeneratorFunctionPrototype.invoke [as _invoke] (/sv/ios/appium/node_modules/appium/node_modules/babel-runtime/regenerator/runtime.js:315:22)
at GeneratorFunctionPrototype.prototype.(anonymous function) [as throw] (/sv/ios/appium/node_modules/appium/node_modules/babel-runtime/regenerator/runtime.js:100:21)
at GeneratorFunctionPrototype.invoke (/sv/ios/appium/node_modules/appium/node_modules/babel-runtime/regenerator/runtime.js:136:37)
at <anonymous>
at process._tickCallback (internal/process/next_tick.js:188:7)
[debug] [iOSSim] Cleaning mobile safari data files
[debug] [iOSSim] Checking whether simulator has been run before
[debug] [iOSSim] Simulator has not been run before
[iOSSim] Could not find Safari support directories to clean out old data. Probably there is nothing to clean out
[debug] [BaseDriver] Event 'resetComplete' logged at 1507631254682 (11:27:34 GMT+0100 (BST))
[debug] [iOSLog] Starting iOS 11.0 simulator log capture
[debug] [iOSLog] System log path: /Users/scivisum/Library/Logs/CoreSimulator/E294AB12-F4F0-4309-AEBE-5C1A810E6768/system.log
[debug] [BaseDriver] Event 'logCaptureStarted' logged at 1507631254704 (11:27:34 GMT+0100 (BST))
[XCUITest] Setting up simulator
[debug] [iOS] No reason to set locale
[debug] [iOS] No iOS / app preferences to set
[debug] [iOSSim] Setting common Simulator preferences to {"ConnectHardwareKeyboard":false}
[debug] [iOSSim] Updated shared Simulator preferences at '/Users/scivisum/Library/Preferences/com.apple.iphonesimulator.plist' with {"ConnectHardwareKeyboard":false}
[debug] [iOSSim] The count of running Simulator UI client instances is 1
[iOSSim] Booting Simulator with UDID E294AB12-F4F0-4309-AEBE-5C1A810E6768...
[iOSSim] Simulator with UDID E294AB12-F4F0-4309-AEBE-5C1A810E6768 booted in 43 seconds
[debug] [BaseDriver] Event 'simStarted' logged at 1507631297846 (11:28:17 GMT+0100 (BST))
[XCUITest] Using WDA path: '/sv/ios/appium/node_modules/appium/node_modules/appium-xcuitest-driver/WebDriverAgent'
[XCUITest] Using WDA agent: '/sv/ios/appium/node_modules/appium/node_modules/appium-xcuitest-driver/WebDriverAgent/WebDriverAgent.xcodeproj'
[debug] [XCUITest] Capability 'useNewWDA' set to false, so trying to reuse currently running WDA instance at 'http://localhost:8100/'
[debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8100/status] with no body
[debug] [XCUITest] WDA is not listening at 'http://localhost:8100/'. Rebuilding...
[debug] [BaseDriver] Event 'wdaStartAttempted' logged at 1507631297987 (11:28:17 GMT+0100 (BST))
[XCUITest] Launching WebDriverAgent on the device
[debug] [XCUITest] Carthage found: '/usr/local/bin/carthage'
[debug] [XCUITest] Killing running processes 'xcodebuild.*E294AB12-F4F0-4309-AEBE-5C1A810E6768, iproxy 8100, E294AB12-F4F0-4309-AEBE-5C1A810E6768.*XCTRunner' for the device E294AB12-F4F0-4309-AEBE-5C1A810E6768...
[debug] [XCUITest] 'pgrep -nif xcodebuild.*E294AB12-F4F0-4309-AEBE-5C1A810E6768' didn't detect any matching processes. Return code: 1
[debug] [XCUITest] 'pgrep -nif iproxy 8100' didn't detect any matching processes. Return code: 1
[debug] [XCUITest] 'pgrep -nif E294AB12-F4F0-4309-AEBE-5C1A810E6768.*XCTRunner' didn't detect any matching processes. Return code: 1
[debug] [XCUITest] Using Xcode 9.0, so fixing WDA codebase
[debug] [XCUITest] Beginning test with command 'xcodebuild build-for-testing test-without-building -project /sv/ios/appium/node_modules/appium/node_modules/appium-xcuitest-driver/WebDriverAgent/WebDriverAgent.xcodeproj -scheme WebDriverAgentRunner -destination id=E294AB12-F4F0-4309-AEBE-5C1A810E6768 IPHONEOS_DEPLOYMENT_TARGET=11.0' in directory '/sv/ios/appium/node_modules/appium/node_modules/appium-xcuitest-driver/WebDriverAgent'
[debug] [XCUITest] Output from xcodebuild will not be logged. To see xcode logging, use 'showXcodeLog' desired capability
[debug] [XCUITest] Waiting up to 60000ms for WebDriverAgent to start
[debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8100/status] with no body
[debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8100/status] with no body
[debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8100/status] with no body
[debug] [XCUITest] Log file for xcodebuild test: /var/folders/0g/by2z5wcx07n82jqt6sqtk9t80000gp/T/com.apple.dt.XCTest/IDETestRunSession-F214592B-4C1F-452E-B457-8CC7E827F3BE/WebDriverAgentRunner-5AD71DD4-CD81-4AA1-92E7-BA53A5E15033/Session-WebDriverAgentRunner-2017-10-10_112820-rX09gY.log
[debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8100/status] with no body
[debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8100/status] with no body
[debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8100/status] with no body
[debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8100/status] with no body
[debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8100/status] with no body
[debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8100/status] with no body
[debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8100/status] with no body
[debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8100/status] with no body
[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\" : \"11.0\"\n },\n \"ios\" : {\n \"simulatorVersion\" : \"11.0\",\n \"ip\" : \"10.0.0.33\"\n },\n \"build\" : {\n \"time\" : \"Oct 5 2017 10:01:20\"\n }\n },\n \"sessionId\" : \"7EC6F258-CB9D-4447-A295-320C52198FC8\",\n \"status\" : 0\n}"
[debug] [XCUITest] WebDriverAgent running on ip '10.0.0.33'
[debug] [XCUITest] WebDriverAgent successfully started after 7657ms
[debug] [BaseDriver] Event 'wdaSessionAttempted' logged at 1507631305907 (11:28:25 GMT+0100 (BST))
[debug] [XCUITest] Sending createSession command to WDA
[debug] [JSONWP Proxy] Proxying [POST /session] to [POST http://localhost:8100/session] with body: {"desiredCapabilities":{"bundleId":"com.apple.mobilesafari","arguments":["-u","http://0.0.0.0:4723/welcome"],"shouldWaitForQuiescence":true,"shouldUseTestManagerForVisibilityDetection":false,"maxTypingFrequency":60,"shouldUseSingletonTestManager":true}}
[HTTP] --> GET /welcome {}
[debug] [HTTP] Sending welcome response with params: {"message":"Let's browse!"}
[HTTP] <-- GET /welcome 200 9 ms - 124
[debug] [JSONWP Proxy] Got response with status 200: {"value":{"sessionId":"3EEA3CBB-5718-4AED-86C8-B789ABE5CFF1","capabilities":{"device":"iphone","browserName":"Safari","sdkVersion":"11.0","CFBundleIdentifier":"com.apple.mobilesafari"}},"sessionId":"3EEA3CBB-5718-4AED-86C8-B789ABE5CFF1","status":0}
[debug] [BaseDriver] Event 'wdaSessionStarted' logged at 1507631308504 (11:28:28 GMT+0100 (BST))
[debug] [XCUITest] Cannot find a match for DerivedData folder path from lsof. Trying to access logs
[XCUITest] Setting '555' permissions to '/Users/scivisum/Library/Developer/Xcode/DerivedData/WebDriverAgent-efdizqcnzokteebakslcvzkdyadn/Logs/Test/Attachments' folder
[debug] [BaseDriver] Event 'wdaPermsAdjusted' logged at 1507631308858 (11:28:28 GMT+0100 (BST))
[debug] [BaseDriver] Event 'wdaStarted' logged at 1507631308858 (11:28:28 GMT+0100 (BST))
[debug] [XCUITest] Setting initial orientation to 'PORTRAIT'
[debug] [JSONWP Proxy] Proxying [POST /orientation] to [POST http://localhost:8100/session/3EEA3CBB-5718-4AED-86C8-B789ABE5CFF1/orientation] with body: {"orientation":"PORTRAIT"}
[debug] [JSONWP Proxy] Got response with status 200: {"value":{},"sessionId":"3EEA3CBB-5718-4AED-86C8-B789ABE5CFF1","status":0}
[debug] [BaseDriver] Event 'orientationSet' logged at 1507631309950 (11:28:29 GMT+0100 (BST))
[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://0.0.0.0:4723/welcome')
[debug] [RemoteDebugger] useNewSafari --> false
[debug] [RemoteDebugger] Debugger socket connected to ::1:27753
[debug] [RemoteDebugger] Sending connection key request
[debug] [RemoteDebugger] Sending '_rpc_reportIdentifier:' message to remote debugger
[debug] [RemoteDebugger] Receiving data from remote debugger: '{"__argument":{"WIRSimulatorProductVersionKey":"11.0","WIRSimulatorDeviceIdentifierKey":"E294AB12-F4F0-4309-AEBE-5C1A810E6768","WIRSimulatorBuildKey":"15A372","WIRSimulatorNameKey":"iPhone"},"__selector":"_rpc_reportSetup:"}'
[debug] [RemoteDebugger] Received response from socket send: '["iPhone","15A372","11.0"]'
[debug] [RemoteDebugger] Sim name: iPhone
[debug] [RemoteDebugger] Sim build: 15A372
[debug] [RemoteDebugger] Sim platform version: 11.0
[debug] [RemoteDebugger] Receiving data from remote debugger: '{"__argument":{"WIRApplicationDictionaryKey":{}},"__selector":"_rpc_reportConnectedApplicationList:"}'
[debug] [RemoteDebugger] Received no apps from remote debugger. Unable to connect.
[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] Selecting application
[debug] [RemoteDebugger] No applications currently connected.
[debug] [iOS] No web frames found.
[debug] [iOS] Retrieving contexts and views
[debug] [iOS] Selecting by url: true (expected url: 'http://0.0.0.0:4723/welcome')
[debug] [RemoteDebugger] useNewSafari --> false
[debug] [RemoteDebugger] Receiving data from remote debugger: '{"__argument":{"WIRRemoteAutomationEnabledKey":false,"WIRIsApplicationActiveKey":2,"WIRIsApplicationReadyKey":false,"WIRApplicationIdentifierKey":"PID:10761","WIRIsApplicationProxyKey":false,"WIRApplicationNameKey":"Safari","WIRApplicationBundleIdentifierKey":"com.apple.mobilesafari"},"__selector":"_rpc_applicationConnected:"}'
[debug] [RemoteDebugger] Notified that new application 'PID:10761' has connected
[debug] [RemoteDebugger] Current applications available:
[debug] [RemoteDebugger] Application: 'PID:10761'
[debug] [RemoteDebugger] id: "PID:10761"
[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] pageDict: "Waiting for data"
[debug] [RemoteDebugger] Found app id key 'PID:10761' for bundle 'com.apple.mobilesafari'
[debug] [RemoteDebugger] Receiving data from remote debugger: '{"__argument":{"WIRRemoteAutomationEnabledKey":false,"WIRIsApplicationActiveKey":1,"WIRIsApplicationReadyKey":false,"WIRApplicationIdentifierKey":"PID:10770","WIRIsApplicationProxyKey":true,"WIRApplicationNameKey":"com.apple.WebKit.WebContent","WIRApplicationBundleIdentifierKey":"com.apple.WebKit.WebContent","WIRHostApplicationIdentifierKey":"PID:10761"},"__selector":"_rpc_applicationConnected:"}'
[debug] [RemoteDebugger] Notified that new application 'PID:10770' has connected
[debug] [RemoteDebugger] Current applications available:
[debug] [RemoteDebugger] Application: 'PID:10761'
[debug] [RemoteDebugger] id: "PID:10761"
[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] pageDict: "Waiting for data"
[debug] [RemoteDebugger] Application: 'PID:10770'
[debug] [RemoteDebugger] id: "PID:10770"
[debug] [RemoteDebugger] isProxy: true
[debug] [RemoteDebugger] name: "com.apple.WebKit.WebContent"
[debug] [RemoteDebugger] bundleId: "com.apple.WebKit.WebContent"
[debug] [RemoteDebugger] hostId: "PID:10761"
[debug] [RemoteDebugger] isActive: 1
[debug] [RemoteDebugger] isAutomationEnabled: false
[debug] [RemoteDebugger] pageDict: "Waiting for data"
[debug] [RemoteDebugger] Receiving data from remote debugger: '{"__argument":{"WIRRemoteAutomationEnabledKey":false,"WIRIsApplicationActiveKey":1,"WIRIsApplicationReadyKey":false,"WIRApplicationIdentifierKey":"PID:10768","WIRIsApplicationProxyKey":true,"WIRApplicationNameKey":"com.apple.WebKit.WebContent","WIRApplicationBundleIdentifierKey":"com.apple.WebKit.WebContent","WIRHostApplicationIdentifierKey":"PID:10761"},"__selector":"_rpc_applicationConnected:"}'
[debug] [RemoteDebugger] Notified that new application 'PID:10768' has connected
[debug] [RemoteDebugger] Current applications available:
[debug] [RemoteDebugger] Application: 'PID:10761'
[debug] [RemoteDebugger] id: "PID:10761"
[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] pageDict: "Waiting for data"
[debug] [RemoteDebugger] Application: 'PID:10770'
[debug] [RemoteDebugger] id: "PID:10770"
[debug] [RemoteDebugger] isProxy: true
[debug] [RemoteDebugger] name: "com.apple.WebKit.WebContent"
[debug] [RemoteDebugger] bundleId: "com.apple.WebKit.WebContent"
[debug] [RemoteDebugger] hostId: "PID:10761"
[debug] [RemoteDebugger] isActive: 1
[debug] [RemoteDebugger] isAutomationEnabled: false
[debug] [RemoteDebugger] pageDict: "Waiting for data"
[debug] [RemoteDebugger] Application: 'PID:10768'
[debug] [RemoteDebugger] id: "PID:10768"
[debug] [RemoteDebugger] isProxy: true
[debug] [RemoteDebugger] name: "com.apple.WebKit.WebContent"
[debug] [RemoteDebugger] bundleId: "com.apple.WebKit.WebContent"
[debug] [RemoteDebugger] hostId: "PID:10761"
[debug] [RemoteDebugger] isActive: 1
[debug] [RemoteDebugger] isAutomationEnabled: false
[debug] [RemoteDebugger] pageDict: "Waiting for data"
[debug] [RemoteDebugger] Debugger socket connected to ::1:27753
[debug] [RemoteDebugger] Sending connection key request
[debug] [RemoteDebugger] Sending '_rpc_reportIdentifier:' message to remote debugger
[debug] [RemoteDebugger] Receiving data from remote debugger: '{"__argument":{"WIRSimulatorProductVersionKey":"11.0","WIRSimulatorDeviceIdentifierKey":"E294AB12-F4F0-4309-AEBE-5C1A810E6768","WIRSimulatorBuildKey":"15A372","WIRSimulatorNameKey":"iPhone"},"__selector":"_rpc_reportSetup:"}'
[debug] [RemoteDebugger] Received response from socket send: '["iPhone","15A372","11.0"]'
[debug] [RemoteDebugger] Receiving data from remote debugger: '{"__argument":{"WIRApplicationDictionaryKey":{"PID:10770":{"WIRRemoteAutomationEnabledKey":false,"WIRIsApplicationActiveKey":1,"WIRIsApplicationReadyKey":false,"WIRApplicationIdentifierKey":"PID:10770","WIRIsApplicationProxyKey":true,"WIRApplicationNameKey":"com.apple.WebKit.WebContent","WIRApplicationBundleIdentifierKey":"com.apple.WebKit.WebContent","WIRHostApplicationIdentifierKey":"PID:10761"},"PID:10768":{"WIRRemoteAutomationEnabledKey":false,"WIRIsApplicationActiveKey":1,"WIRIsApplicationReadyKey":false,"WIRApplicationIdentifierKey":"PID:10768","WIRIsApplicationProxyKey":true,"WIRApplicationNameKey":"com.apple.WebKit.WebContent","WIRApplicationBundleIdentifierKey":"com.apple.WebKit.WebContent","WIRHostApplicationIdentifierKey":"PID:10761"},"PID:10761":{"WIRRemoteAutomationEnabledKey":false,"WIRIsApplicationActiveKey":2,"WIRIsApplicationReadyKey":false,"WIRApplicationIdentifierKey":"PID:10761","WIRIsApplicationProxyKey":false,"WIRApplicationNameKey":"Safari","WIRApplicationBundleIdentifierKey":"com.apple.mobilesafari"}}},"__selector":"_rpc_reportConnectedApplicationList:"}'
[debug] [RemoteDebugger] Connected to application
[debug] [RemoteDebugger] Sim name: iPhone
[debug] [RemoteDebugger] Sim build: 15A372
[debug] [RemoteDebugger] Sim platform version: 11.0
[debug] [RemoteDebugger] Selecting application
[debug] [RemoteDebugger] Found app id key 'PID:10761' 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:10770'
[debug] [RemoteDebugger] Found separate bundleId 'com.apple.WebKit.WebContent' acting as proxy for 'com.apple.mobilesafari', with app id 'PID:10768'
[debug] [RemoteDebugger] Trying out the possible app ids: PID:10770, PID:10768
[debug] [RemoteDebugger] Selecting app PID:10770 (try #1 of 20)
[debug] [RemoteDebugger] Sending '_rpc_forwardGetListing:' message to remote debugger
[debug] [RemoteDebugger] Receiving data from remote debugger: '{"__argument":{"WIRDriverDictionaryKey":{}},"__selector":"_rpc_reportConnectedDriverList:"}'
[debug] [RemoteDebugger] Notified of connected drivers: {}.
[debug] [RemoteDebugger] Receiving data from remote debugger: '{"__argument":{"WIRApplicationIdentifierKey":"PID:10770","WIRListingKey":{"6":{"WIRTitleKey":"Yahoo","WIRTypeKey":"WIRTypeWeb","WIRURLKey":"https://uk.yahoo.com/searchset?p=us","WIRPageIdentifierKey":6}}},"__selector":"_rpc_applicationSentListing:"}'
[debug] [RemoteDebugger] Received response from socket send: '["PID:10770",{"6":{"WIRTitleKey":"Yahoo","WIRTy...'
[debug] [RemoteDebugger] Received page change notice for app 'PID:10770' but listening for 'null'. Ignoring.
[debug] [RemoteDebugger] Receiving data from remote debugger: '{"__argument":{"WIRApplicationIdentifierKey":"PID:10770","WIRListingKey":{"6":{"WIRTitleKey":"Yahoo","WIRTypeKey":"WIRTypeWeb","WIRURLKey":"https://uk.yahoo.com/searchset?p=us","WIRPageIdentifierKey":6}}},"__selector":"_rpc_applicationSentListing:"}'
[debug] [RemoteDebugger] Received page change notice for app 'PID:10770' but listening for 'PID:10761'. Ignoring.
[debug] [RemoteDebugger] Receiving data from remote debugger: '{"__argument":{"WIRRemoteAutomationEnabledKey":false,"WIRIsApplicationActiveKey":1,"WIRIsApplicationReadyKey":true,"WIRApplicationIdentifierKey":"PID:10770","WIRIsApplicationProxyKey":true,"WIRApplicationNameKey":"com.apple.WebKit.WebContent","WIRApplicationBundleIdentifierKey":"com.apple.WebKit.WebContent","WIRHostApplicationIdentifierKey":"PID:10761"},"__selector":"_rpc_applicationUpdated:"}'
[debug] [RemoteDebugger] Notified that application 'PID:10770' has been updated.
[debug] [RemoteDebugger] Current applications available:
[debug] [RemoteDebugger] Application: 'PID:10761'
[debug] [RemoteDebugger] id: "PID:10761"
[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] pageDict: "Waiting for data"
[debug] [RemoteDebugger] Application: 'PID:10770'
[debug] [RemoteDebugger] id: "PID:10770"
[debug] [RemoteDebugger] isProxy: true
[debug] [RemoteDebugger] name: "com.apple.WebKit.WebContent"
[debug] [RemoteDebugger] bundleId: "com.apple.WebKit.WebContent"
[debug] [RemoteDebugger] hostId: "PID:10761"
[debug] [RemoteDebugger] isActive: 1
[debug] [RemoteDebugger] isAutomationEnabled: false
[debug] [RemoteDebugger] pageDict: [{"id":6,"title":"Yahoo","url":"https://uk.yahoo.com/searchset?p=us","isKey":false}]
[debug] [RemoteDebugger] Application: 'PID:10768'
[debug] [RemoteDebugger] id: "PID:10768"
[debug] [RemoteDebugger] isProxy: true
[debug] [RemoteDebugger] name: "com.apple.WebKit.WebContent"
[debug] [RemoteDebugger] bundleId: "com.apple.WebKit.WebContent"
[debug] [RemoteDebugger] hostId: "PID:10761"
[debug] [RemoteDebugger] isActive: 1
[debug] [RemoteDebugger] isAutomationEnabled: false
[debug] [RemoteDebugger] pageDict: "Waiting for data"
[debug] [RemoteDebugger] Receiving data from remote debugger: '{"__argument":{"WIRRemoteAutomationEnabledKey":false,"WIRIsApplicationActiveKey":1,"WIRIsApplicationReadyKey":true,"WIRApplicationIdentifierKey":"PID:10770","WIRIsApplicationProxyKey":true,"WIRApplicationNameKey":"com.apple.WebKit.WebContent","WIRApplicationBundleIdentifierKey":"com.apple.WebKit.WebContent","WIRHostApplicationIdentifierKey":"PID:10761"},"__selector":"_rpc_applicationUpdated:"}'
[debug] [RemoteDebugger] Notified that application 'PID:10770' has been updated.
[debug] [RemoteDebugger] Current applications available:
[debug] [RemoteDebugger] Application: 'PID:10770'
[debug] [RemoteDebugger] id: "PID:10770"
[debug] [RemoteDebugger] isProxy: true
[debug] [RemoteDebugger] name: "com.apple.WebKit.WebContent"
[debug] [RemoteDebugger] bundleId: "com.apple.WebKit.WebContent"
[debug] [RemoteDebugger] hostId: "PID:10761"
[debug] [RemoteDebugger] isActive: 1
[debug] [RemoteDebugger] isAutomationEnabled: false
[debug] [RemoteDebugger] pageDict: [{"id":6,"title":"Yahoo","url":"https://uk.yahoo.com/searchset?p=us","isKey":false}]
[debug] [RemoteDebugger] Application: 'PID:10768'
[debug] [RemoteDebugger] id: "PID:10768"
[debug] [RemoteDebugger] isProxy: true
[debug] [RemoteDebugger] name: "com.apple.WebKit.WebContent"
[debug] [RemoteDebugger] bundleId: "com.apple.WebKit.WebContent"
[debug] [RemoteDebugger] hostId: "PID:10761"
[debug] [RemoteDebugger] isActive: 1
[debug] [RemoteDebugger] isAutomationEnabled: false
[debug] [RemoteDebugger] Application: 'PID:10761'
[debug] [RemoteDebugger] id: "PID:10761"
[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] Found app id key 'PID:10761' 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:10770'
[debug] [RemoteDebugger] Found separate bundleId 'com.apple.WebKit.WebContent' acting as proxy for 'com.apple.mobilesafari', with app id 'PID:10768'
[debug] [RemoteDebugger] Using proxied app id 'PID:10768'
[debug] [RemoteDebugger] Received app, but expected url ('http://0.0.0.0:4723/welcome') was not found. Trying again.
[debug] [RemoteDebugger] Selecting app PID:10768 (try #1 of 20)
[debug] [RemoteDebugger] Sending '_rpc_forwardGetListing:' message to remote debugger
[debug] [RemoteDebugger] Receiving data from remote debugger: '{"__argument":{"WIRApplicationIdentifierKey":"PID:10768","WIRListingKey":{"1":{"WIRTitleKey":"Appium/welcome","WIRTypeKey":"WIRTypeWeb","WIRURLKey":"http://0.0.0.0:4723/welcome","WIRPageIdentifierKey":1}}},"__selector":"_rpc_applicationSentListing:"}'
[debug] [RemoteDebugger] Received page change notice for app 'PID:10768' but listening for 'PID:10761'. Ignoring.
[debug] [RemoteDebugger] Receiving data from remote debugger: '{"__argument":{"WIRApplicationIdentifierKey":"PID:10768","WIRListingKey":{"1":{"WIRTitleKey":"Appium/welcome","WIRTypeKey":"WIRTypeWeb","WIRURLKey":"http://0.0.0.0:4723/welcome","WIRPageIdentifierKey":1}}},"__selector":"_rpc_applicationSentListing:"}'
[debug] [RemoteDebugger] Received response from socket send: '["PID:10768",{"1":{"WIRTitleKey":"Appium/welcom...'
[debug] [RemoteDebugger] Page changed: {"1":{"WIRTitleKey":"Appium/welcome","WIRTypeKey":"WIRTypeWeb","WIRURLKey":"http://0.0.0.0:4723/welcome","WIRPageIdentifierKey":1}}
[debug] [RemoteDebugger] Receiving data from remote debugger: '{"__argument":{"WIRRemoteAutomationEnabledKey":false,"WIRIsApplicationActiveKey":1,"WIRIsApplicationReadyKey":true,"WIRApplicationIdentifierKey":"PID:10768","WIRIsApplicationProxyKey":true,"WIRApplicationNameKey":"com.apple.WebKit.WebContent","WIRApplicationBundleIdentifierKey":"com.apple.WebKit.WebContent","WIRHostApplicationIdentifierKey":"PID:10761"},"__selector":"_rpc_applicationUpdated:"}'
[debug] [RemoteDebugger] Notified that application 'PID:10768' has been updated.
[debug] [RemoteDebugger] Current applications available:
[debug] [RemoteDebugger] Application: 'PID:10770'
[debug] [RemoteDebugger] id: "PID:10770"
[debug] [RemoteDebugger] isProxy: true
[debug] [RemoteDebugger] name: "com.apple.WebKit.WebContent"
[debug] [RemoteDebugger] bundleId: "com.apple.WebKit.WebContent"
[debug] [RemoteDebugger] hostId: "PID:10761"
[debug] [RemoteDebugger] isActive: 1
[debug] [RemoteDebugger] isAutomationEnabled: false
[debug] [RemoteDebugger] pageDict: [{"id":6,"title":"Yahoo","url":"https://uk.yahoo.com/searchset?p=us","isKey":false}]
[debug] [RemoteDebugger] Application: 'PID:10768'
[debug] [RemoteDebugger] id: "PID:10768"
[debug] [RemoteDebugger] isProxy: true
[debug] [RemoteDebugger] name: "com.apple.WebKit.WebContent"
[debug] [RemoteDebugger] bundleId: "com.apple.WebKit.WebContent"
[debug] [RemoteDebugger] hostId: "PID:10761"
[debug] [RemoteDebugger] isActive: 1
[debug] [RemoteDebugger] isAutomationEnabled: false
[debug] [RemoteDebugger] pageDict: [{"id":1,"title":"Appium/welcome","url":"http://0.0.0.0:4723/welcome","isKey":false}]
[debug] [RemoteDebugger] Application: 'PID:10761'
[debug] [RemoteDebugger] id: "PID:10761"
[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] Receiving data from remote debugger: '{"__argument":{"WIRRemoteAutomationEnabledKey":false,"WIRIsApplicationActiveKey":1,"WIRIsApplicationReadyKey":true,"WIRApplicationIdentifierKey":"PID:10768","WIRIsApplicationProxyKey":true,"WIRApplicationNameKey":"com.apple.WebKit.WebContent","WIRApplicationBundleIdentifierKey":"com.apple.WebKit.WebContent","WIRHostApplicationIdentifierKey":"PID:10761"},"__selector":"_rpc_applicationUpdated:"}'
[debug] [RemoteDebugger] Notified that application 'PID:10768' has been updated.
[debug] [RemoteDebugger] Current applications available:
[debug] [RemoteDebugger] Application: 'PID:10761'
[debug] [RemoteDebugger] id: "PID:10761"
[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] pageDict: "Waiting for data"
[debug] [RemoteDebugger] Application: 'PID:10770'
[debug] [RemoteDebugger] id: "PID:10770"
[debug] [RemoteDebugger] isProxy: true
[debug] [RemoteDebugger] name: "com.apple.WebKit.WebContent"
[debug] [RemoteDebugger] bundleId: "com.apple.WebKit.WebContent"
[debug] [RemoteDebugger] hostId: "PID:10761"
[debug] [RemoteDebugger] isActive: 1
[debug] [RemoteDebugger] isAutomationEnabled: false
[debug] [RemoteDebugger] pageDict: [{"id":6,"title":"Yahoo","url":"https://uk.yahoo.com/searchset?p=us","isKey":false}]
[debug] [RemoteDebugger] Application: 'PID:10768'
[debug] [RemoteDebugger] id: "PID:10768"
[debug] [RemoteDebugger] isProxy: true
[debug] [RemoteDebugger] name: "com.apple.WebKit.WebContent"
[debug] [RemoteDebugger] bundleId: "com.apple.WebKit.WebContent"
[debug] [RemoteDebugger] hostId: "PID:10761"
[debug] [RemoteDebugger] isActive: 1
[debug] [RemoteDebugger] isAutomationEnabled: false
[debug] [RemoteDebugger] pageDict: [{"id":1,"title":"Appium/welcome","url":"http://0.0.0.0:4723/welcome","isKey":false}]
[debug] [RemoteDebugger] Finally selecting app PID:10768: [{"id":1,"title":"Appium/welcome","url":"http://0.0.0.0:4723/welcome","isKey":false}]
[debug] [iOS] Picking webview 'WEBVIEW_10768.1'
[debug] [iOS] Attempting to set context to 'WEBVIEW_10768.1'
[debug] [RemoteDebugger] Selecting page '1' on app 'PID:10768' 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 1507631310110 (11:28:30 GMT+0100 (BST))
[Appium] New XCUITestDriver session created successfully, session 068f6558-61d4-49fc-a9d1-c649d1045d13 added to master session list
[debug] [BaseDriver] Event 'newSessionStarted' logged at 1507631310111 (11:28:30 GMT+0100 (BST))
[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":"iPhone2017-B","automationName":"XCUITest","newCommandTimeout":0,"platformVersion":"11.0","platformName":"iOS","udid":"E294AB12-F4F0-4309-AEBE-5C1A810E6768"}
[HTTP] <-- POST /wd/hub/session 200 57002 ms - 445
[HTTP] --> POST /wd/hub/session/068f6558-61d4-49fc-a9d1-c649d1045d13/url {"url":"http://127.0.0.1:6543/","sessionId":"068f6558-61d4-49fc-a9d1-c649d1045d13"}
[debug] [MJSONWP] Calling AppiumDriver.setUrl() with args: ["http://127.0.0.1:6543/","068f6558-61d4-49fc-a9d1-c649d1045d13"]
[debug] [XCUITest] Executing command 'setUrl'
[debug] [iOS] Attempting to set url 'http://127.0.0.1:6543/'
[debug] [RemoteDebugger] Navigating to new URL: http://127.0.0.1:6543/
[debug] [RemoteDebugger] Sending '_rpc_forwardSocketData:' message to remote debugger
[debug] [RemoteDebugger] Received applicationSentData response
[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] Frame navigated, unloading page
[debug] [RemoteDebugger] No frame navigation callback set.
[debug] [RemoteDebugger] Received applicationSentData response
[debug] [RemoteDebugger] Page loaded, verifying whether ready
[debug] [RemoteDebugger] Received applicationSentData response
[debug] [RemoteDebugger] Received applicationSentData response
[debug] [RemoteDebugger] Receiving data from remote debugger: '{"__argument":{"WIRApplicationIdentifierKey":"PID:10761","WIRListingKey":{}},"__selector":"_rpc_applicationSentListing:"}'
[debug] [RemoteDebugger] Page changed: {}
[debug] [iOS] Remote debugger notified us of a new page listing: {"appIdKey":"10761","pageArray":[]}
[debug] [iOS] No key id found. Choosing first id from page array
[debug] [iOS] Page change not referring to currently selected app, ignoring.
[debug] [RemoteDebugger] Receiving data from remote debugger: '{"__argument":{"WIRRemoteAutomationEnabledKey":false,"WIRIsApplicationActiveKey":2,"WIRIsApplicationReadyKey":true,"WIRApplicationIdentifierKey":"PID:10761","WIRIsApplicationProxyKey":false,"WIRApplicationNameKey":"Safari","WIRApplicationBundleIdentifierKey":"com.apple.mobilesafari"},"__selector":"_rpc_applicationUpdated:"}'
[debug] [RemoteDebugger] Notified that application 'PID:10761' has been updated.
[debug] [RemoteDebugger] Current applications available:
[debug] [RemoteDebugger] Application: 'PID:10761'
[debug] [RemoteDebugger] id: "PID:10761"
[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] pageDict: []
[debug] [RemoteDebugger] Application: 'PID:10770'
[debug] [RemoteDebugger] id: "PID:10770"
[debug] [RemoteDebugger] isProxy: true
[debug] [RemoteDebugger] name: "com.apple.WebKit.WebContent"
[debug] [RemoteDebugger] bundleId: "com.apple.WebKit.WebContent"
[debug] [RemoteDebugger] hostId: "PID:10761"
[debug] [RemoteDebugger] isActive: 1
[debug] [RemoteDebugger] isAutomationEnabled: false
[debug] [RemoteDebugger] pageDict: [{"id":6,"title":"Yahoo","url":"https://uk.yahoo.com/searchset?p=us","isKey":false}]
[debug] [RemoteDebugger] Application: 'PID:10768'
[debug] [RemoteDebugger] id: "PID:10768"
[debug] [RemoteDebugger] isProxy: true
[debug] [RemoteDebugger] name: "com.apple.WebKit.WebContent"
[debug] [RemoteDebugger] bundleId: "com.apple.WebKit.WebContent"
[debug] [RemoteDebugger] hostId: "PID:10761"
[debug] [RemoteDebugger] isActive: 1
[debug] [RemoteDebugger] isAutomationEnabled: false
[debug] [RemoteDebugger] pageDict: [{"id":1,"title":"Appium/welcome","url":"http://0.0.0.0:4723/welcome","isKey":false}]
[debug] [RemoteDebugger] Receiving data from remote debugger: '{"__argument":{"WIRApplicationIdentifierKey":"PID:10761","WIRListingKey":{}},"__selector":"_rpc_applicationSentListing:"}'
[debug] [RemoteDebugger] Received page change notice for app 'PID:10761' but listening for 'PID:10768'. Ignoring.
[debug] [RemoteDebugger] Receiving data from remote debugger: '{"__argument":{"WIRRemoteAutomationEnabledKey":false,"WIRIsApplicationActiveKey":2,"WIRIsApplicationReadyKey":true,"WIRApplicationIdentifierKey":"PID:10761","WIRIsApplicationProxyKey":false,"WIRApplicationNameKey":"Safari","WIRApplicationBundleIdentifierKey":"com.apple.mobilesafari"},"__selector":"_rpc_applicationUpdated:"}'
[debug] [RemoteDebugger] Notified that application 'PID:10761' has been updated.
[debug] [RemoteDebugger] Current applications available:
[debug] [RemoteDebugger] Application: 'PID:10770'
[debug] [RemoteDebugger] id: "PID:10770"
[debug] [RemoteDebugger] isProxy: true
[debug] [RemoteDebugger] name: "com.apple.WebKit.WebContent"
[debug] [RemoteDebugger] bundleId: "com.apple.WebKit.WebContent"
[debug] [RemoteDebugger] hostId: "PID:10761"
[debug] [RemoteDebugger] isActive: 1
[debug] [RemoteDebugger] isAutomationEnabled: false
[debug] [RemoteDebugger] pageDict: [{"id":6,"title":"Yahoo","url":"https://uk.yahoo.com/searchset?p=us","isKey":false}]
[debug] [RemoteDebugger] Application: 'PID:10768'
[debug] [RemoteDebugger] id: "PID:10768"
[debug] [RemoteDebugger] isProxy: true
[debug] [RemoteDebugger] name: "com.apple.WebKit.WebContent"
[debug] [RemoteDebugger] bundleId: "com.apple.WebKit.WebContent"
[debug] [RemoteDebugger] hostId: "PID:10761"
[debug] [RemoteDebugger] isActive: 1
[debug] [RemoteDebugger] isAutomationEnabled: false
[debug] [RemoteDebugger] pageDict: [{"id":1,"title":"Appium/welcome","url":"http://0.0.0.0:4723/welcome","isKey":false}]
[debug] [RemoteDebugger] Application: 'PID:10761'
[debug] [RemoteDebugger] id: "PID:10761"
[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] pageDict: []
[debug] [RemoteDebugger] Receiving data from remote debugger: '{"__argument":{"WIRApplicationIdentifierKey":"PID:10770","WIRListingKey":{"6":{"WIRTitleKey":"Yahoo","WIRTypeKey":"WIRTypeWeb","WIRURLKey":"https://uk.yahoo.com/searchset?p=us","WIRPageIdentifierKey":6}}},"__selector":"_rpc_applicationSentListing:"}'
[debug] [RemoteDebugger] Received page change notice for app 'PID:10770' but listening for 'PID:10768'. Ignoring.
[debug] [RemoteDebugger] Receiving data from remote debugger: '{"__argument":{"WIRApplicationIdentifierKey":"PID:10768","WIRListingKey":{"1":{"WIRTitleKey":"","WIRTypeKey":"WIRTypeWeb","WIRURLKey":"http://127.0.0.1:6543/","WIRPageIdentifierKey":1,"WIRConnectionIdentifierKey":"dfd90044-34d5-44ed-9824-653847dab2cc"}}},"__selector":"_rpc_applicationSentListing:"}'
[debug] [RemoteDebugger] Page changed: {"1":{"WIRTitleKey":"","WIRTypeKey":"WIRTypeWeb","WIRURLKey":"http://127.0.0.1:6543/","WIRPageIdentifierKey":1,"WIRConnectionIdentifierKey":"dfd90044-34d5-44ed-9824-653847dab2cc"}}
[debug] [iOS] Remote debugger notified us of a new page listing: {"appIdKey":"10768","pageArray":[{"id":1,"title":"","url":"http://127.0.0.1:6543/","isKey":true}]}
[debug] [iOS] Checking if page needs to load
[debug] [iOS] Page load needed. Loading.
[debug] [RemoteDebugger] Page loaded, verifying whether ready
[debug] [RemoteDebugger] Receiving data from remote debugger: '{"__argument":{"WIRRemoteAutomationEnabledKey":false,"WIRIsApplicationActiveKey":1,"WIRIsApplicationReadyKey":true,"WIRApplicationIdentifierKey":"PID:10768","WIRIsApplicationProxyKey":true,"WIRApplicationNameKey":"com.apple.WebKit.WebContent","WIRApplicationBundleIdentifierKey":"com.apple.WebKit.WebContent","WIRHostApplicationIdentifierKey":"PID:10761"},"__selector":"_rpc_applicationUpdated:"}'
[debug] [RemoteDebugger] Notified that application 'PID:10768' has been updated.
[debug] [RemoteDebugger] Current applications available:
[debug] [RemoteDebugger] Application: 'PID:10770'
[debug] [RemoteDebugger] id: "PID:10770"
[debug] [RemoteDebugger] isProxy: true
[debug] [RemoteDebugger] name: "com.apple.WebKit.WebContent"
[debug] [RemoteDebugger] bundleId: "com.apple.WebKit.WebContent"
[debug] [RemoteDebugger] hostId: "PID:10761"
[debug] [RemoteDebugger] isActive: 1
[debug] [RemoteDebugger] isAutomationEnabled: false
[debug] [RemoteDebugger] pageDict: [{"id":6,"title":"Yahoo","url":"https://uk.yahoo.com/searchset?p=us","isKey":false}]
[debug] [RemoteDebugger] Application: 'PID:10768'
[debug] [RemoteDebugger] id: "PID:10768"
[debug] [RemoteDebugger] isProxy: true
[debug] [RemoteDebugger] name: "com.apple.WebKit.WebContent"
[debug] [RemoteDebugger] bundleId: "com.apple.WebKit.WebContent"
[debug] [RemoteDebugger] hostId: "PID:10761"
[debug] [RemoteDebugger] isActive: 1
[debug] [RemoteDebugger] isAutomationEnabled: false
[debug] [RemoteDebugger] pageDict: [{"id":1,"title":"","url":"http://127.0.0.1:6543/","isKey":true}]
[debug] [RemoteDebugger] Application: 'PID:10761'
[debug] [RemoteDebugger] id: "PID:10761"
[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] pageDict: []
[debug] [RemoteDebugger] Receiving data from remote debugger: '{"__argument":{"WIRApplicationIdentifierKey":"PID:10770","WIRListingKey":{"6":{"WIRTitleKey":"Yahoo","WIRTypeKey":"WIRTypeWeb","WIRURLKey":"https://uk.yahoo.com/searchset?p=us","WIRPageIdentifierKey":6}}},"__selector":"_rpc_applicationSentListing:"}'
[debug] [RemoteDebugger] Received page change notice for app 'PID:10770' but listening for 'PID:10761'. Ignoring.
[debug] [RemoteDebugger] Receiving data from remote debugger: '{"__argument":{"WIRApplicationIdentifierKey":"PID:10768","WIRListingKey":{"1":{"WIRTitleKey":"","WIRTypeKey":"WIRTypeWeb","WIRURLKey":"http://127.0.0.1:6543/","WIRPageIdentifierKey":1,"WIRConnectionIdentifierKey":"dfd90044-34d5-44ed-9824-653847dab2cc"}}},"__selector":"_rpc_applicationSentListing:"}'
[debug] [RemoteDebugger] Received page change notice for app 'PID:10768' but listening for 'PID:10761'. Ignoring.
[debug] [RemoteDebugger] Receiving data from remote debugger: '{"__argument":{"WIRRemoteAutomationEnabledKey":false,"WIRIsApplicationActiveKey":1,"WIRIsApplicationReadyKey":true,"WIRApplicationIdentifierKey":"PID:10768","WIRIsApplicationProxyKey":true,"WIRApplicationNameKey":"com.apple.WebKit.WebContent","WIRApplicationBundleIdentifierKey":"com.apple.WebKit.WebContent","WIRHostApplicationIdentifierKey":"PID:10761"},"__selector":"_rpc_applicationUpdated:"}'
[debug] [RemoteDebugger] Notified that application 'PID:10768' has been updated.
[debug] [RemoteDebugger] Current applications available:
[debug] [RemoteDebugger] Application: 'PID:10761'
[debug] [RemoteDebugger] id: "PID:10761"
[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] pageDict: []
[debug] [RemoteDebugger] Application: 'PID:10770'
[debug] [RemoteDebugger] id: "PID:10770"
[debug] [RemoteDebugger] isProxy: true
[debug] [RemoteDebugger] name: "com.apple.WebKit.WebContent"
[debug] [RemoteDebugger] bundleId: "com.apple.WebKit.WebContent"
[debug] [RemoteDebugger] hostId: "PID:10761"
[debug] [RemoteDebugger] isActive: 1
[debug] [RemoteDebugger] isAutomationEnabled: false
[debug] [RemoteDebugger] pageDict: [{"id":6,"title":"Yahoo","url":"https://uk.yahoo.com/searchset?p=us","isKey":false}]
[debug] [RemoteDebugger] Application: 'PID:10768'
[debug] [RemoteDebugger] id: "PID:10768"
[debug] [RemoteDebugger] isProxy: true
[debug] [RemoteDebugger] name: "com.apple.WebKit.WebContent"
[debug] [RemoteDebugger] bundleId: "com.apple.WebKit.WebContent"
[debug] [RemoteDebugger] hostId: "PID:10761"
[debug] [RemoteDebugger] isActive: 1
[debug] [RemoteDebugger] isAutomationEnabled: false
[debug] [RemoteDebugger] pageDict: [{"id":1,"title":"","url":"http://127.0.0.1:6543/","isKey":true}]
[debug] [RemoteDebugger] Receiving data from remote debugger: '{"__argument":{"WIRRemoteAutomationEnabledKey":false,"WIRIsApplicationActiveKey":1,"WIRIsApplicationReadyKey":true,"WIRApplicationIdentifierKey":"PID:10770","WIRIsApplicationProxyKey":true,"WIRApplicationNameKey":"com.apple.WebKit.WebContent","WIRApplicationBundleIdentifierKey":"com.apple.WebKit.WebContent","WIRHostApplicationIdentifierKey":"PID:10761"},"__selector":"_rpc_applicationDisconnected:"}'
[debug] [RemoteDebugger] Application 'PID:10770' disconnected. Removing from app dictionary.
[debug] [RemoteDebugger] Current app is PID:10761
[debug] [RemoteDebugger] Receiving data from remote debugger: '{"__argument":{"WIRRemoteAutomationEnabledKey":false,"WIRIsApplicationActiveKey":1,"WIRIsApplicationReadyKey":true,"WIRApplicationIdentifierKey":"PID:10770","WIRIsApplicationProxyKey":true,"WIRApplicationNameKey":"com.apple.WebKit.WebContent","WIRApplicationBundleIdentifierKey":"com.apple.WebKit.WebContent","WIRHostApplicationIdentifierKey":"PID:10761"},"__selector":"_rpc_applicationDisconnected:"}'
[debug] [RemoteDebugger] Application 'PID:10770' disconnected. Removing from app dictionary.
[debug] [RemoteDebugger] Current app is PID:10768
[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] 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] [iOS] New page listing is same as old, doing nothing
[debug] [RemoteDebugger] Waiting for frame navigated message...
[debug] [RemoteDebugger] Frame navigated in 0.5 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] [MJSONWP] Responding to client with driver.setUrl() result: null
[HTTP] <-- POST /wd/hub/session/068f6558-61d4-49fc-a9d1-c649d1045d13/url 200 2025 ms - 76
[HTTP] --> POST /wd/hub/session/068f6558-61d4-49fc-a9d1-c649d1045d13/orientation {"sessionId":"068f6558-61d4-49fc-a9d1-c649d1045d13","orientation":"LANDSCAPE"}
[MJSONWP] Driver proxy active, passing request on via HTTP proxy
[debug] [XCUITest] Executing command 'proxyReqRes'
[debug] [JSONWP Proxy] Proxying [POST /wd/hub/session/068f6558-61d4-49fc-a9d1-c649d1045d13/orientation] to [POST http://localhost:8100/session/3EEA3CBB-5718-4AED-86C8-B789ABE5CFF1/orientation] with body: {"sessionId":"068f6558-61d4-49fc-a9d1-c649d1045d13","orientation":"LANDSCAPE"}
[debug] [JSONWP Proxy] Got response with status 200: {"value":{},"sessionId":"3EEA3CBB-5718-4AED-86C8-B789ABE5CFF1","status":0}
[JSONWP Proxy] Replacing sessionId 3EEA3CBB-5718-4AED-86C8-B789ABE5CFF1 with 068f6558-61d4-49fc-a9d1-c649d1045d13
[HTTP] <-- POST /wd/hub/session/068f6558-61d4-49fc-a9d1-c649d1045d13/orientation 200 1384 ms - 74
[HTTP] --> POST /wd/hub/session/068f6558-61d4-49fc-a9d1-c649d1045d13/execute {"sessionId":"068f6558-61d4-49fc-a9d1-c649d1045d13","args":[{"y":200,"x":200}],"script":"mobile: tap"}
[debug] [MJSONWP] Calling AppiumDriver.execute() with args: ["mobile: tap",[{"y":200,"x":200}],"068f6558-61d4-49fc-a9d1-c649d1045d13"]
[debug] [XCUITest] Executing command 'execute'
[debug] [JSONWP Proxy] Proxying [POST /wda/tap/0] to [POST http://localhost:8100/session/3EEA3CBB-5718-4AED-86C8-B789ABE5CFF1/wda/tap/0] with body: {"x":200,"y":200}
[debug] [JSONWP Proxy] Got response with status 200: {"value":{},"sessionId":"3EEA3CBB-5718-4AED-86C8-B789ABE5CFF1","status":0}
[debug] [MJSONWP] Responding to client with driver.execute() result: {}
[HTTP] <-- POST /wd/hub/session/068f6558-61d4-49fc-a9d1-c649d1045d13/execute 200 265 ms - 74
[debug] [RemoteDebugger] Receiving data from remote debugger: '{"__argument":{"WIRApplicationIdentifierKey":"PID:10768","WIRListingKey":{"1":{"WIRTitleKey":"","WIRTypeKey":"WIRTypeWeb","WIRURLKey":"http://127.0.0.1:6543/","WIRPageIdentifierKey":1,"WIRConnectionIdentifierKey":"dfd90044-34d5-44ed-9824-653847dab2cc"}}},"__selector":"_rpc_applicationSentListing:"}'
[debug] [RemoteDebugger] Page changed: {"1":{"WIRTitleKey":"","WIRTypeKey":"WIRTypeWeb","WIRURLKey":"http://127.0.0.1:6543/","WIRPageIdentifierKey":1,"WIRConnectionIdentifierKey":"dfd90044-34d5-44ed-9824-653847dab2cc"}}
[debug] [iOS] Remote debugger notified us of a new page listing: {"appIdKey":"10768","pageArray":[{"id":1,"title":"","url":"http://127.0.0.1:6543/","isKey":true}]}
[debug] [iOS] Checking if page needs to load
[debug] [iOS] Page load needed. Loading.
[debug] [RemoteDebugger] Page loaded, verifying whether ready
[debug] [RemoteDebugger] Receiving data from remote debugger: '{"__argument":{"WIRApplicationIdentifierKey":"PID:10768","WIRListingKey":{"1":{"WIRTitleKey":"","WIRTypeKey":"WIRTypeWeb","WIRURLKey":"http://127.0.0.1:6543/","WIRPageIdentifierKey":1,"WIRConnectionIdentifierKey":"dfd90044-34d5-44ed-9824-653847dab2cc"}}},"__selector":"_rpc_applicationSentListing:"}'
[debug] [RemoteDebugger] Received page change notice for app 'PID:10768' but listening for 'PID:10761'. Ignoring.
[HTTP] --> POST /wd/hub/session/068f6558-61d4-49fc-a9d1-c649d1045d13/appium/device/hide_keyboard {"sessionId":"068f6558-61d4-49fc-a9d1-c649d1045d13","strategy":"tapOutside"}
[debug] [MJSONWP] Calling AppiumDriver.hideKeyboard() with args: ["tapOutside",null,null,null,"068f6558-61d4-49fc-a9d1-c649d1045d13"]
[debug] [XCUITest] Executing command 'hideKeyboard'
[debug] [BaseDriver] Waiting up to 0 ms for condition
[debug] [JSONWP Proxy] Proxying [POST /element] to [POST http://localhost:8100/session/3EEA3CBB-5718-4AED-86C8-B789ABE5CFF1/element] with body: {"using":"class name","value":"XCUIElementTypeKeyboard"}
[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] [iOS] New page listing is same as old, doing nothing
[debug] [JSONWP Proxy] Got response with status 200: {"value":{"ELEMENT":"DCF9A493-7864-48D9-BD21-62F45F6D2837"},"sessionId":"3EEA3CBB-5718-4AED-86C8-B789ABE5CFF1","status":0}
[debug] [XCUITest] Finding keyboard and clicking final button to close
[debug] [BaseDriver] Waiting up to 0 ms for condition
[debug] [JSONWP Proxy] Proxying [POST /element/DCF9A493-7864-48D9-BD21-62F45F6D2837/elements] to [POST http://localhost:8100/session/3EEA3CBB-5718-4AED-86C8-B789ABE5CFF1/element/DCF9A493-7864-48D9-BD21-62F45F6D2837/elements] with body: {"using":"class name","value":"XCUIElementTypeButton"}
[debug] [JSONWP Proxy] Got response with status 200: {"value":[{"ELEMENT":"C1679652-FF86-4747-9867-6650426EF18F"},{"ELEMENT":"3E0879BC-0B0C-4674-891C-7D1F556D3D3E"},{"ELEMENT":"DFD27FD7-D12B-485B-80A3-6119F1BF5F8F"},{"ELEMENT":"4D8E87E6-5F43-4AC1-8A93-B2C8ACE95A00"}],"sessionId":"3EEA3CBB-5718-4AED-86C8-B789ABE5CFF1","status":0}
[debug] [JSONWP Proxy] Proxying [POST /element/4D8E87E6-5F43-4AC1-8A93-B2C8ACE95A00/click] to [POST http://localhost:8100/session/3EEA3CBB-5718-4AED-86C8-B789ABE5CFF1/element/4D8E87E6-5F43-4AC1-8A93-B2C8ACE95A00/click] with body: {}
[debug] [JSONWP Proxy] Got response with status 200: {"status":0,"id":"4D8E87E6-5F43-4AC1-8A93-B2C8ACE95A00","value":"","sessionId":"3EEA3CBB-5718-4AED-86C8-B789ABE5CFF1"}
[debug] [MJSONWP] Responding to client with driver.hideKeyboard() result: null
[HTTP] <-- POST /wd/hub/session/068f6558-61d4-49fc-a9d1-c649d1045d13/appium/device/hide_keyboard 200 167
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment