Skip to content

Instantly share code, notes, and snippets.

@dpgraham

dpgraham/execute-async-javascript-https

Created September 4, 2019 17:50
Show Gist options
  • Save dpgraham/9408966205a928600b4cced5b7505c3e to your computer and use it in GitHub Desktop.
Save dpgraham/9408966205a928600b4cced5b7505c3e to your computer and use it in GitHub Desktop.
Download ZIP
Raw
execute-async-javascript-https
dbug XCUITest Sending createSession command to WDA
dbug WD Proxy Matched '/session' to command name 'createSession'
dbug WD Proxy Proxying [POST /session] to [POST http://localhost:8100/session] with body: {"capabilities":{"firstMatch":[{"bundleId":"com.apple.mobilesafari","arguments":[],"environment":{},"eventloopIdleDelaySec":0,"shouldWaitForQuiescence":true,"shouldUseTestManagerForVisibilityDetection":false,"maxTypingFrequency":30,"shouldUseSingletonTestManager":true}],"alwaysMatch":{}}}
dbug WD Proxy Got response with status 200: {"value":{"sessionId":"583311BC-A082-4BFF-AF33-4428EC6CC91C","capabilities":{"device":"iphone","browserName":"Safari","sdkVersion":"13.0","CFBundleIdentifier":"com.apple.mobilesafari"}},"sessionId":"583311BC-A082-4BFF-AF33-4428EC6CC91C"}
info WD Proxy Determined the downstream protocol as 'W3C'
dbug BaseDriver Event 'wdaSessionStarted' logged at 1567618976053 (10:42:56 GMT-0700 (Pacific Daylight Time))
dbug XCUITest Parsed BUILD_DIR configuration value: '/Users/danielgraham/Library/Developer/Xcode/DerivedData/WebDriverAgent-gsarxbnghdckurdrxkxxwuwfvisl/Build/Products'
dbug XCUITest Got derived data root: '/Users/danielgraham/Library/Developer/Xcode/DerivedData/WebDriverAgent-gsarxbnghdckurdrxkxxwuwfvisl'
dbug BaseDriver Event 'wdaStarted' logged at 1567618982343 (10:43:02 GMT-0700 (Pacific Daylight Time))
info XCUITest Skipping setting of the initial display orientation. Set the "orientation" capability to either "LANDSCAPE" or "PORTRAIT", if this is an undesired behavior.
dbug BaseDriver Event 'orientationSet' logged at 1567618982343 (10:43:02 GMT-0700 (Pacific Daylight Time))
dbug XCUITest Waiting for initial webview
dbug iOS Navigating to most recently opened webview
dbug iOS Retrieving contexts and views
dbug XCUITest Selecting by url: true (expected url: 'http://localhost:4994/test/guinea-pig')
info HTTP --> GET /test/guinea-pig
info HTTP {}
dbug HTTP Sending guinea pig response with params: {"throwError":"","serverTime":1567618982,"userAgent":"Mozilla/5.0 (iPhone; CPU iPhone OS 13_0 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/13.0 Mobile/15E148 Safari/604.1","comment":"None"}
info HTTP <-- GET /test/guinea-pig 200 21 ms - 3566
info HTTP
info RemoteDebugger Remote Debugger version 5.3.0
dbug RemoteDebugger useNewSafari --> false
dbug RemoteDebugger Checking which communication style to use (Safari on platform version '13.0')
dbug RemoteDebugger Platform version equal or higher than '12.2': true
WARN RemoteDebugger Setting communication protocol: using Target-based communication
dbug RemoteDebugger Connecting to remote debugger through unix domain socket: '/private/tmp/com.apple.launchd.n7MnR8slCU/com.apple.webinspectord_sim.socket'
dbug RemoteDebugger Debugger socket connected
dbug RemoteDebugger Sending connection key request
dbug RemoteDebugger Sending '_rpc_reportIdentifier:' message (id: 0)
info HTTP <-- GET /js/jquery.min.js 200 2 ms - 92630
info HTTP
info HTTP <-- GET /favicon.ico 200 1 ms - 1150
info HTTP
dbug RemoteDebugger Received no apps from remote debugger. Unable to connect.
dbug RemoteDebugger Connected to application
dbug RemoteDebugger Selecting application
dbug RemoteDebugger No applications currently connected.
dbug XCUITest No web frames found.
WARN iOS Could not find any webviews yet, refreshing/retrying
dbug RemoteDebugger Notified that new application 'PID:42904' has connected
dbug RemoteDebugger Notified that new application 'PID:42886' has connected
dbug RemoteDebugger Found app id key 'PID:42886' for bundle 'com.apple.mobilesafari'
dbug RemoteDebugger Found separate bundleId 'process-com.apple.WebKit.WebContent' acting as proxy for 'com.apple.mobilesafari', with app id 'PID:42904'
dbug RemoteDebugger Using proxied app id 'PID:42904'
dbug RemoteDebugger Notified that new application 'PID:42889' has connected
dbug RemoteDebugger Notified that new application 'PID:42907' has connected
dbug RemoteDebugger Current applications available:
dbug RemoteDebugger Application: 'PID:42904'
dbug RemoteDebugger id: "PID:42904"
dbug RemoteDebugger isProxy: true
dbug RemoteDebugger name: "com.apple.WebKit.WebContent"
dbug RemoteDebugger bundleId: "process-com.apple.WebKit.WebContent"
dbug RemoteDebugger hostId: "PID:42886"
dbug RemoteDebugger isActive: 1
dbug RemoteDebugger isAutomationEnabled: false
dbug RemoteDebugger pageArray: 'Waiting for data'
dbug RemoteDebugger Application: 'PID:42886'
dbug RemoteDebugger id: "PID:42886"
dbug RemoteDebugger isProxy: false
dbug RemoteDebugger name: "Safari"
dbug RemoteDebugger bundleId: "com.apple.mobilesafari"
dbug RemoteDebugger hostId: undefined
dbug RemoteDebugger isActive: 2
dbug RemoteDebugger isAutomationEnabled: false
dbug RemoteDebugger pageArray:
dbug RemoteDebugger - id: 1
dbug RemoteDebugger title: "I am a page title"
dbug RemoteDebugger url: "http://localhost:4994/test/guinea-pig"
dbug RemoteDebugger isKey: false
dbug RemoteDebugger - id: 5
dbug RemoteDebugger title: "Yahoo"
dbug RemoteDebugger url: "https://ca.yahoo.com/?p=us"
dbug RemoteDebugger isKey: false
dbug RemoteDebugger Application: 'PID:42889'
dbug RemoteDebugger id: "PID:42889"
dbug RemoteDebugger isProxy: true
dbug RemoteDebugger name: "com.apple.WebKit.WebContent"
dbug RemoteDebugger bundleId: "process-com.apple.WebKit.WebContent"
dbug RemoteDebugger hostId: "PID:42886"
dbug RemoteDebugger isActive: 2
dbug RemoteDebugger isAutomationEnabled: false
dbug RemoteDebugger pageArray: 'Waiting for data'
dbug RemoteDebugger Application: 'PID:42907'
dbug RemoteDebugger id: "PID:42907"
dbug RemoteDebugger isProxy: true
dbug RemoteDebugger name: "com.apple.WebKit.WebContent"
dbug RemoteDebugger bundleId: "process-com.apple.WebKit.WebContent"
dbug RemoteDebugger hostId: "PID:42886"
dbug RemoteDebugger isActive: 0
dbug RemoteDebugger isAutomationEnabled: false
dbug RemoteDebugger pageArray: 'Waiting for data'
dbug RemoteDebugger Page changed: {
dbug RemoteDebugger "1": {
dbug RemoteDebugger "WIRTitleKey": "I am a page title",
dbug RemoteDebugger "WIRTypeKey": "WIRTypeWeb",
dbug RemoteDebugger "WIRURLKey": "http://localhost:4994/test/guinea-pig",
dbug RemoteDebugger "WIRPageIdentifierKey": 1
dbug RemoteDebugger },
dbug RemoteDebugger "5": {
dbug RemoteDebugger "WIRTitleKey": "Yahoo",
dbug RemoteDebugger "WIRTypeKey": "WIRTypeWeb",
dbug RemoteDebugger "WIRURLKey": "https://ca.yahoo.com/?p=us",
dbug RemoteDebugger "WIRPageIdentifierKey": 5
dbug RemoteDebugger }
dbug RemoteDebugger }
dbug RemoteDebugger Received page change notice for app 'PID:42886' but listening for 'PID:42904'. Ignoring.
dbug RemoteDebugger Application 'PID:42904' disconnected. Removing from app dictionary.
dbug RemoteDebugger Current app is PID:42904
dbug RemoteDebugger No longer have app id. Attempting to find new one.
dbug RemoteDebugger Found app id key 'PID:42886' for bundle 'com.apple.mobilesafari'
dbug RemoteDebugger Found separate bundleId 'process-com.apple.WebKit.WebContent' acting as proxy for 'com.apple.mobilesafari', with app id 'PID:42889'
dbug RemoteDebugger Found separate bundleId 'process-com.apple.WebKit.WebContent' acting as proxy for 'com.apple.mobilesafari', with app id 'PID:42907'
dbug RemoteDebugger Using proxied app id 'PID:42907'
dbug RemoteDebugger Application 'PID:42907' disconnected. Removing from app dictionary.
dbug RemoteDebugger Current app is PID:42907
dbug RemoteDebugger No longer have app id. Attempting to find new one.
dbug RemoteDebugger Found app id key 'PID:42886' for bundle 'com.apple.mobilesafari'
dbug RemoteDebugger Found separate bundleId 'process-com.apple.WebKit.WebContent' acting as proxy for 'com.apple.mobilesafari', with app id 'PID:42889'
dbug RemoteDebugger Using proxied app id 'PID:42889'
dbug RemoteDebugger Notified that new application 'PID:42928' has connected
dbug RemoteDebugger Current applications available:
dbug RemoteDebugger Application: 'PID:42886'
dbug RemoteDebugger id: "PID:42886"
dbug RemoteDebugger isProxy: false
dbug RemoteDebugger name: "Safari"
dbug RemoteDebugger bundleId: "com.apple.mobilesafari"
dbug RemoteDebugger hostId: undefined
dbug RemoteDebugger isActive: 2
dbug RemoteDebugger isAutomationEnabled: false
dbug RemoteDebugger pageArray:
dbug RemoteDebugger - id: 1
dbug RemoteDebugger title: "I am a page title"
dbug RemoteDebugger url: "http://localhost:4994/test/guinea-pig"
dbug RemoteDebugger isKey: false
dbug RemoteDebugger Application: 'PID:42889'
dbug RemoteDebugger id: "PID:42889"
dbug RemoteDebugger isProxy: true
dbug RemoteDebugger name: "com.apple.WebKit.WebContent"
dbug RemoteDebugger bundleId: "process-com.apple.WebKit.WebContent"
dbug RemoteDebugger hostId: "PID:42886"
dbug RemoteDebugger isActive: 2
dbug RemoteDebugger isAutomationEnabled: false
dbug RemoteDebugger pageArray: 'Waiting for data'
dbug RemoteDebugger Application: 'PID:42928'
dbug RemoteDebugger id: "PID:42928"
dbug RemoteDebugger isProxy: true
dbug RemoteDebugger name: "com.apple.WebKit.WebContent"
dbug RemoteDebugger bundleId: "process-com.apple.WebKit.WebContent"
dbug RemoteDebugger hostId: "PID:42886"
dbug RemoteDebugger isActive: 1
dbug RemoteDebugger isAutomationEnabled: false
dbug RemoteDebugger pageArray: 'Waiting for data'
dbug RemoteDebugger Page changed: {
dbug RemoteDebugger "1": {
dbug RemoteDebugger "WIRTitleKey": "I am a page title",
dbug RemoteDebugger "WIRTypeKey": "WIRTypeWeb",
dbug RemoteDebugger "WIRURLKey": "http://localhost:4994/test/guinea-pig",
dbug RemoteDebugger "WIRPageIdentifierKey": 1
dbug RemoteDebugger }
dbug RemoteDebugger }
dbug RemoteDebugger Received page change notice for app 'PID:42886' but listening for 'PID:42889'. Ignoring.
dbug iOS Retrieving contexts and views
dbug XCUITest Selecting by url: true (expected url: 'http://localhost:4994/test/guinea-pig')
dbug RemoteDebugger Selecting application
dbug RemoteDebugger Current applications available:
dbug RemoteDebugger Application: 'PID:42886'
dbug RemoteDebugger id: "PID:42886"
dbug RemoteDebugger isProxy: false
dbug RemoteDebugger name: "Safari"
dbug RemoteDebugger bundleId: "com.apple.mobilesafari"
dbug RemoteDebugger hostId: undefined
dbug RemoteDebugger isActive: 2
dbug RemoteDebugger isAutomationEnabled: false
dbug RemoteDebugger pageArray:
dbug RemoteDebugger - id: 1
dbug RemoteDebugger title: "I am a page title"
dbug RemoteDebugger url: "http://localhost:4994/test/guinea-pig"
dbug RemoteDebugger isKey: false
dbug RemoteDebugger Application: 'PID:42889'
dbug RemoteDebugger id: "PID:42889"
dbug RemoteDebugger isProxy: true
dbug RemoteDebugger name: "com.apple.WebKit.WebContent"
dbug RemoteDebugger bundleId: "process-com.apple.WebKit.WebContent"
dbug RemoteDebugger hostId: "PID:42886"
dbug RemoteDebugger isActive: 2
dbug RemoteDebugger isAutomationEnabled: false
dbug RemoteDebugger pageArray: 'Waiting for data'
dbug RemoteDebugger Application: 'PID:42928'
dbug RemoteDebugger id: "PID:42928"
dbug RemoteDebugger isProxy: true
dbug RemoteDebugger name: "com.apple.WebKit.WebContent"
dbug RemoteDebugger bundleId: "process-com.apple.WebKit.WebContent"
dbug RemoteDebugger hostId: "PID:42886"
dbug RemoteDebugger isActive: 1
dbug RemoteDebugger isAutomationEnabled: false
dbug RemoteDebugger pageArray: 'Waiting for data'
dbug RemoteDebugger Found app id key 'PID:42886' for bundle 'com.apple.mobilesafari'
dbug RemoteDebugger Found separate bundleId 'process-com.apple.WebKit.WebContent' acting as proxy for 'com.apple.mobilesafari', with app id 'PID:42889'
dbug RemoteDebugger Found separate bundleId 'process-com.apple.WebKit.WebContent' acting as proxy for 'com.apple.mobilesafari', with app id 'PID:42928'
dbug RemoteDebugger Trying out the possible app ids: PID:42886, PID:42889, PID:42928
dbug RemoteDebugger Selecting app PID:42886 (try #1 of 20)
dbug RemoteDebugger Sending '_rpc_forwardGetListing:' message to app 'PID:42886' (id: 1)
dbug RemoteDebugger Received response from send (id: 1): '["PID:42886",{"1":{"WIRTitleKey":"I am a page title","WIRTypeKey":"WIRTypeWeb","WIRURLKey":"http://localhost:4994/test/guinea-pig","WIRPageIdentifierKey":1}}]'
dbug RemoteDebugger Received page change notice for app 'PID:42886' but the listing has not changed. Ignoring.
dbug RemoteDebugger Sending to Web Inspector took 4ms
dbug RemoteDebugger Received altered app id, updating from 'PID:42889' to 'PID:42886'
dbug RemoteDebugger Current applications available:
dbug RemoteDebugger Application: 'PID:42886'
dbug RemoteDebugger id: "PID:42886"
dbug RemoteDebugger isProxy: false
dbug RemoteDebugger name: "Safari"
dbug RemoteDebugger bundleId: "com.apple.mobilesafari"
dbug RemoteDebugger hostId: undefined
dbug RemoteDebugger isActive: 2
dbug RemoteDebugger isAutomationEnabled: false
dbug RemoteDebugger pageArray:
dbug RemoteDebugger - id: 1
dbug RemoteDebugger title: "I am a page title"
dbug RemoteDebugger url: "http://localhost:4994/test/guinea-pig"
dbug RemoteDebugger isKey: false
dbug RemoteDebugger Application: 'PID:42889'
dbug RemoteDebugger id: "PID:42889"
dbug RemoteDebugger isProxy: true
dbug RemoteDebugger name: "com.apple.WebKit.WebContent"
dbug RemoteDebugger bundleId: "process-com.apple.WebKit.WebContent"
dbug RemoteDebugger hostId: "PID:42886"
dbug RemoteDebugger isActive: 2
dbug RemoteDebugger isAutomationEnabled: false
dbug RemoteDebugger pageArray: 'Waiting for data'
dbug RemoteDebugger Application: 'PID:42928'
dbug RemoteDebugger id: "PID:42928"
dbug RemoteDebugger isProxy: true
dbug RemoteDebugger name: "com.apple.WebKit.WebContent"
dbug RemoteDebugger bundleId: "process-com.apple.WebKit.WebContent"
dbug RemoteDebugger hostId: "PID:42886"
dbug RemoteDebugger isActive: 1
dbug RemoteDebugger isAutomationEnabled: false
dbug RemoteDebugger pageArray: 'Waiting for data'
dbug RemoteDebugger Finally selecting app PID:42886: [{"id":1,"title":"I am a page title","url":"http://localhost:4994/test/guinea-pig","isKey":false}]
dbug RemoteDebugger Selected app after 16ms
dbug iOS Picking webview 'WEBVIEW_42886.1'
dbug XCUITest Attempting to set context to 'WEBVIEW_42886.1'
dbug RemoteDebugger Selecting page '1' on app 'PID:42886' and forwarding socket setup
dbug RemoteDebugger Sending '_rpc_forwardSocketSetup:' message to app 'PID:42886', page '1' (id: 2)
dbug RemoteDebugger Sending to Web Inspector took 3ms
dbug RemoteDebugger Sender key set
dbug RemoteDebugger Target created for app 'PID:42886' and page '1': {"targetId":"page-6","type":"page"}
dbug RemoteDebugger Current applications available:
dbug RemoteDebugger Application: 'PID:42886'
dbug RemoteDebugger id: "PID:42886"
dbug RemoteDebugger isProxy: false
dbug RemoteDebugger name: "Safari"
dbug RemoteDebugger bundleId: "com.apple.mobilesafari"
dbug RemoteDebugger hostId: undefined
dbug RemoteDebugger isActive: 2
dbug RemoteDebugger isAutomationEnabled: false
dbug RemoteDebugger pageArray:
dbug RemoteDebugger - id: 1
dbug RemoteDebugger title: "I am a page title"
dbug RemoteDebugger url: "http://localhost:4994/test/guinea-pig"
dbug RemoteDebugger isKey: true
dbug RemoteDebugger Application: 'PID:42889'
dbug RemoteDebugger id: "PID:42889"
dbug RemoteDebugger isProxy: true
dbug RemoteDebugger name: "com.apple.WebKit.WebContent"
dbug RemoteDebugger bundleId: "process-com.apple.WebKit.WebContent"
dbug RemoteDebugger hostId: "PID:42886"
dbug RemoteDebugger isActive: 2
dbug RemoteDebugger isAutomationEnabled: false
dbug RemoteDebugger pageArray: 'Waiting for data'
dbug RemoteDebugger Application: 'PID:42928'
dbug RemoteDebugger id: "PID:42928"
dbug RemoteDebugger isProxy: true
dbug RemoteDebugger name: "com.apple.WebKit.WebContent"
dbug RemoteDebugger bundleId: "process-com.apple.WebKit.WebContent"
dbug RemoteDebugger hostId: "PID:42886"
dbug RemoteDebugger isActive: 0
dbug RemoteDebugger isAutomationEnabled: false
dbug RemoteDebugger pageArray: 'Waiting for data'
dbug RemoteDebugger Page changed: {
dbug RemoteDebugger "1": {
dbug RemoteDebugger "WIRTitleKey": "I am a page title",
dbug RemoteDebugger "WIRTypeKey": "WIRTypeWeb",
dbug RemoteDebugger "WIRURLKey": "http://localhost:4994/test/guinea-pig",
dbug RemoteDebugger "WIRPageIdentifierKey": 1,
dbug RemoteDebugger "WIRConnectionIdentifierKey": "06f6189c-d082-47b7-8428-32787682dfe0"
dbug RemoteDebugger }
dbug RemoteDebugger }
dbug iOS Remote debugger notified us of a new page listing: {"appIdKey":"42886","pageArray":[{"id":1,"title":"I am a page title","url":"http://localhost:4994/test/guinea-pig","isKey":true}]}
dbug iOS We do not appear to have window set yet, ignoring
dbug RemoteDebugger Sending '_rpc_forwardSocketData:' message to app 'PID:42886', page '1', target 'page-6' (id: 3)
dbug RemoteDebugger Received data response from send (id: 3): '{}'
dbug RemoteDebugger Sending to Web Inspector took 1017ms
dbug RemoteDebugger Enabled activity on page
dbug RemoteDebugger Checking document readyState
dbug RemoteDebugger Sending javascript command (function (){ return document.readyState; })()
dbug RemoteDebugger Sending '_rpc_forwardSocketData:' message to app 'PID:42886', page '1', target 'page-6' (id: 4)
dbug RemoteDebugger Received data response from send (id: 4): 'complete'
dbug RemoteDebugger Sending to Web Inspector took 12ms
dbug RemoteDebugger Document readyState is 'complete'
dbug RemoteDebugger Selected page after 1035ms
dbug RemoteDebugger Starting to listen for JavaScript console
dbug RemoteDebugger Sending '_rpc_forwardSocketData:' message to app 'PID:42886', page '1', target 'page-6' (id: 5)
dbug RemoteDebugger Received data response from send (id: 5): '{}'
dbug RemoteDebugger Sending to Web Inspector took 35ms
dbug RemoteDebugger Starting to listen for network events
dbug RemoteDebugger Sending '_rpc_forwardSocketData:' message to app 'PID:42886', page '1', target 'page-6' (id: 6)
dbug RemoteDebugger Received data response from send (id: 6): '{}'
dbug RemoteDebugger Sending to Web Inspector took 5ms
dbug RemoteDebugger Unregistering from page readiness notifications
dbug BaseDriver Event 'initialWebviewNavigated' logged at 1567618985100 (10:43:05 GMT-0700 (Pacific Daylight Time))
dbug BaseDriver Event 'newSessionStarted' logged at 1567618985101 (10:43:05 GMT-0700 (Pacific Daylight Time))
dbug MJSONWP (6d7890bb) Cached the protocol value 'MJSONWP' for the new session 6d7890bb-4e64-48f6-81cb-b52a2c8a7219
dbug MJSONWP (6d7890bb) Responding to client with driver.createSession() result: {"webStorageEnabled":false,"locationContextEnabled":false,"browserName":"Safari","platform":"MAC","javascriptEnabled":true,"databaseEnabled":false,"takesScreenshot":true,"networkConnectionEnabled":false,"safariInitialUrl":"http://localhost:4994/test/guinea-pig","nativeWebTap":true,"platformName":"iOS","platformVersion":"13.0","deviceName":"iPhone X","automationName":"XCUITest","noReset":true,"maxTypingFrequency":30,"clearSystemFiles":true,"wdaLaunchTimeout":240000,"wdaConnectionTimeout":480000,"useNewWDA":true,"udid":"F3532C29-FC61-485C-BECE-14AF8037E5C8"}
info HTTP <-- POST /wd/hub/session 200 80169 ms - 634
info HTTP
asynchronous
info HTTP --> POST /wd/hub/session/6d7890bb-4e64-48f6-81cb-b52a2c8a7219/timeouts/async_script
info HTTP {"ms":1000}
dbug MJSONWP (6d7890bb) Calling XCUITestDriver.asyncScriptTimeout() with args: [1000,"6d7890bb-4e64-48f6-81cb-b52a2c8a7219"]
dbug XCUITest Executing command 'asyncScriptTimeout'
dbug iOS Set async script timeout to 1000ms
dbug MJSONWP (6d7890bb) Responding to client with driver.asyncScriptTimeout() result: null
info HTTP <-- POST /wd/hub/session/6d7890bb-4e64-48f6-81cb-b52a2c8a7219/timeouts/async_script 200 41 ms - 76
info HTTP
info HTTP --> POST /wd/hub/session/6d7890bb-4e64-48f6-81cb-b52a2c8a7219/execute_async
info HTTP {"script":"arguments[arguments.length - 1](123);","args":[]}
dbug MJSONWP (6d7890bb) Calling XCUITestDriver.executeAsync() with args: ["arguments[arguments.length - 1](123);",[],"6d7890bb-4e64-48f6-81cb-b52a2c8a7219"]
dbug XCUITest Executing command 'executeAsync'
dbug RemoteDebugger Sending javascript command window.location.href
dbug RemoteDebugger Sending '_rpc_forwardSocketData:' message to app 'PID:42886', page '1', target 'page-6' (id: 7)
dbug RemoteDebugger Received data response from send (id: 7): 'http://localhost:4994/test/guinea-pig'
dbug RemoteDebugger Sending to Web Inspector took 24ms
dbug iOS Response url for executeAsync: http://localhost:4994/wd/hub/session/6d7890bb-4e64-48f6-81cb-b52a2c8a7219/receive_async_response
dbug RemoteDebugger Executing 'execute_async_script' atom in default context
dbug RemoteDebugger Sending javascript command (function(){return function(){var e=this;
dbug RemoteDebugger funct...
dbug RemoteDebugger Sending '_rpc_forwardSocketData:' message to app 'PID:42886', page '1', target 'page-6' (id: 8)
dbug RemoteDebugger Received data response from send (id: 8): '{"result":{"type":"undefined"}}'
dbug RemoteDebugger Sending to Web Inspector took 9ms
info HTTP --> POST /wd/hub/session/6d7890bb-4e64-48f6-81cb-b52a2c8a7219/receive_async_response
info HTTP {"status":0,"value":123}
dbug MJSONWP (6d7890bb) Calling XCUITestDriver.receiveAsyncResponse() with args: [0,123,"6d7890bb-4e64-48f6-81cb-b52a2c8a7219"]
dbug XCUITest Executing command 'receiveAsyncResponse'
dbug iOS Received async response: 123
dbug MJSONWP (6d7890bb) Responding to client with driver.receiveAsyncResponse() result: null
info HTTP <-- POST /wd/hub/session/6d7890bb-4e64-48f6-81cb-b52a2c8a7219/receive_async_response 200 1 ms - 76
info HTTP
dbug MJSONWP (6d7890bb) Responding to client with driver.executeAsync() result: 123
info HTTP <-- POST /wd/hub/session/6d7890bb-4e64-48f6-81cb-b52a2c8a7219/execute_async 200 41 ms - 75
info HTTP
✓ should execute async javascript (99ms)
info HTTP --> DELETE /wd/hub/session/6d7890bb-4e64-48f6-81cb-b52a2c8a7219
info HTTP {}
dbug MJSONWP (6d7890bb) Calling XCUITestDriver.deleteSession() with args: ["6d7890bb-4e64-48f6-81cb-b52a2c8a7219"]
dbug XCUITest Executing command 'deleteSession'
dbug BaseDriver Event 'quitSessionRequested' logged at 1567618985218 (10:43:05 GMT-0700 (Pacific Daylight Time))
dbug IDB Disconnecting idb service from 'F3532C29-FC61-485C-BECE-14AF8037E5C8'
dbug IDB Cleaning up 1 obsolete idb_companion process
dbug WD Proxy Matched '/session/6d7890bb-4e64-48f6-81cb-b52a2c8a7219' to command name 'deleteSession'
dbug WD Proxy Proxying [DELETE /session/6d7890bb-4e64-48f6-81cb-b52a2c8a7219] to [DELETE http://localhost:8100/session/583311BC-A082-4BFF-AF33-4428EC6CC91C] with no body
dbug RemoteDebugger Application 'PID:42886' disconnected. Removing from app dictionary.
dbug RemoteDebugger Current app is PID:42886
dbug RemoteDebugger No longer have app id. Attempting to find new one.
dbug RemoteDebugger Application 'PID:42889' disconnected. Removing from app dictionary.
dbug RemoteDebugger Current app is undefined
dbug RemoteDebugger Application 'PID:42928' disconnected. Removing from app dictionary.
dbug RemoteDebugger Current app is undefined
dbug WD Proxy Got response with status 200: {
dbug WD Proxy "value" : null,
dbug WD Proxy "sessionId" : "0D0134B6-056E-4DAD-BB00-D2766532A94B"
dbug WD Proxy }
info XCUITest Shutting down sub-processes
info XCUITest Shutting down xcodebuild process (pid 42854)
ERR! XCUITest xcodebuild exited with code 'null' and signal 'SIGTERM'
dbug XCUITest Started background XCTest logs cleanup: find -E /private/var/folders -regex '.*/Session-WebDriverAgentRunner.*\.log$|.*/StandardOutputAndStandardError\.txt$' -type f -exec sh -c 'echo "" > "{}"' \;
info XCUITest Cleaning test logs in '/Users/danielgraham/Library/Developer/Xcode/DerivedData/WebDriverAgent-gsarxbnghdckurdrxkxxwuwfvisl/Logs' folder
dbug iOS Clearing log files
dbug iOS Deleting '/Users/danielgraham/Library/Developer/Xcode/DerivedData/WebDriverAgent-gsarxbnghdckurdrxkxxwuwfvisl/Logs'. Freeing 132K.
dbug iOS Finished clearing log files
dbug XCUITest In a web session. Removing remote debugger
dbug RemoteDebugger Disconnecting from remote debugger
dbug RemoteDebugger Cleaning up listeners
dbug IOSSimulatorLog Stopping iOS log capture
dbug BaseDriver Event 'quitSessionFinished' logged at 1567618987115 (10:43:07 GMT-0700 (Pacific Daylight Time))
dbug MJSONWP (6d7890bb) Received response: null
dbug MJSONWP (6d7890bb) But deleting session, so not returning
dbug MJSONWP (6d7890bb) Responding to client with driver.deleteSession() result: null
info HTTP <-- DELETE /wd/hub/session/6d7890bb-4e64-48f6-81cb-b52a2c8a7219 200 1899 ms - 76
info HTTP
dbug XCUITest Killing running processes 'xcodebuild.*F3532C29-FC61-485C-BECE-14AF8037E5C8, F3532C29-FC61-485C-BECE-14AF8037E5C8.*XCTRunner' for the device F3532C29-FC61-485C-BECE-14AF8037E5C8...
dbug XCUITest 'pgrep -ifn xcodebuild.*F3532C29-FC61-485C-BECE-14AF8037E5C8' didn't detect any matching processes. Return code: 1
dbug XCUITest 'pgrep -ifn F3532C29-FC61-485C-BECE-14AF8037E5C8.*XCTRunner' didn't detect any matching processes. Return code: 1
dbug iOSSim Killing all iOS Simulators
dbug iOSSim Using fkill to kill processes: 42554
dbug iOSSim Using pkill to kill application: Simulator
https
dbug iOSSim Killing all iOS Simulators
dbug iOSSim Simulator is not running. Continuing...
info XCUITest XCUITestDriver server listening on http://localhost:4994
info HTTP --> POST /wd/hub/session
info HTTP {"desiredCapabilities":{"safariInitialUrl":"http://localhost:4994/test/guinea-pig","nativeWebTap":true,"enableAsyncExecuteFromHttps":true,"browserName":"Safari","platformName":"iOS","platformVersion":"13.0","deviceName":"iPhone X","automationName":"XCUITest","noReset":true,"maxTypingFrequency":30,"clearSystemFiles":true,"wdaLaunchTimeout":240000,"wdaConnectionTimeout":480000,"useNewWDA":true}}
dbug MJSONWP Calling XCUITestDriver.createSession() with args: [{"safariInitialUrl":"http://localhost:4994/test/guinea-pig","nativeWebTap":true,"enableAsyncExecuteFromHttps":true,"browserName":"Safari","platformName":"iOS","platformVersion":"13.0","deviceName":"iPhone X","automationName":"XCUITest","noReset":true,"maxTypingFrequency":30,"clearSystemFiles":true,"wdaLaunchTimeout":240000,"wdaConnectionTimeout":480000,"useNewWDA":true},null,null]
dbug XCUITest Executing command 'createSession'
dbug BaseDriver Event 'newSessionRequested' logged at 1567618994109 (10:43:14 GMT-0700 (Pacific Daylight Time))
dbug BaseDriver Creating session with MJSONWP desired capabilities: {
dbug BaseDriver "safariInitialUrl": "http://localhost:4994/test/guinea-pig",
dbug BaseDriver "nativeWebTap": true,
dbug BaseDriver "enableAsyncExecuteFromHttps": true,
dbug BaseDriver "browserName": "Safari",
dbug BaseDriver "platformName": "iOS",
dbug BaseDriver "platformVersion": "13.0",
dbug BaseDriver "deviceName": "iPhone X",
dbug BaseDriver "automationName": "XCUITest",
dbug BaseDriver "noReset": true,
dbug BaseDriver "maxTypingFrequency": 30,
dbug BaseDriver "clearSystemFiles": true,
dbug BaseDriver "wdaLaunchTimeout": 240000,
dbug BaseDriver "wdaConnectionTimeout": 480000,
dbug BaseDriver "useNewWDA": true
dbug BaseDriver }
info BaseDriver Session created with session id: e29c2474-bad5-4e23-991b-112a3ec555d4
dbug XCUITest Current user: 'Daniel-Graham-SL1298'
info XCUITest iOS SDK Version set to '13.0'
info XCUITest Simulator udid not provided
info XCUITest Using desired caps to create a new simulator
dbug simctl Creating simulator with name 'appiumTest-25945F9F-60FD-40F0-9E05-1C9E201EAB77-iPhone X', device type id 'iPhone X' and runtime id 'com.apple.CoreSimulator.SimRuntime.iOS-13-0'
info iOSSim Constructing iOS simulator for Xcode version 11.0 with udid '39375895-145D-494D-A067-D24B093DA6BC'
info XCUITest Created simulator with udid '39375895-145D-494D-A067-D24B093DA6BC'.
info XCUITest Determining device to run tests on: udid: '39375895-145D-494D-A067-D24B093DA6BC', real device: false
dbug BaseDriver Event 'xcodeDetailsRetrieved' logged at 1567618994711 (10:43:14 GMT-0700 (Pacific Daylight Time))
dbug XCUITest Killing running processes 'xcodebuild.*39375895-145D-494D-A067-D24B093DA6BC, 39375895-145D-494D-A067-D24B093DA6BC.*XCTRunner' for the device 39375895-145D-494D-A067-D24B093DA6BC...
dbug XCUITest 'pgrep -ifn xcodebuild.*39375895-145D-494D-A067-D24B093DA6BC' didn't detect any matching processes. Return code: 1
dbug XCUITest 'pgrep -ifn 39375895-145D-494D-A067-D24B093DA6BC.*XCTRunner' didn't detect any matching processes. Return code: 1
dbug iOS Starting https server for async responses
dbug iOSSim Installing certificate to /Users/danielgraham/Library/Developer/CoreSimulator/Devices/39375895-145D-494D-A067-D24B093DA6BC/data
dbug iOSSim Executing SQL query "CREATE TABLE IF NOT EXISTS tsettings (sha1 BLOB NOT NULL DEFAULT '', subj BLOB NOT NULL DEFAULT '', tset BLOB, data BLOB, PRIMARY KEY(sha1));" on '/Users/danielgraham/Library/Developer/CoreSimulator/Devices/39375895-145D-494D-A067-D24B093DA6BC/data/Library/Keychains/TrustStore.sqlite3'
dbug iOSSim Executing SQL query "CREATE INDEX isubj ON tsettings(subj);" on '/Users/danielgraham/Library/Developer/CoreSimulator/Devices/39375895-145D-494D-A067-D24B093DA6BC/data/Library/Keychains/TrustStore.sqlite3'
dbug iOSSim Executing SQL query "SELECT count(*) FROM tsettings WHERE subj = 'localhost
dbug iOSSim '" on '/Users/danielgraham/Library/Developer/CoreSimulator/Devices/39375895-145D-494D-A067-D24B093DA6BC/data/Library/Keychains/TrustStore.sqlite3'
dbug iOSSim Executing SQL query "INSERT INTO tsettings (sha1, subj, tset, data) VALUES (x'710ac56c20254329223e9be95b0ad9ad1e633736', 'localhost
dbug iOSSim ', '<?xml version="1.0" encoding="UTF-8"?>
dbug iOSSim
dbug iOSSim <!DOCTYPE plist PUBLIC "-//Apple//DTD PLIST 1.0//EN" "http://www.apple.com/DTDs/PropertyList-1.0.dtd">
dbug iOSSim <plist version="1.0">
dbug iOSSim <array/>
dbug iOSSim </plist>', x'308202a43082018c02090097b9a6c52de6a37c300d06092a864886f70d01010b050030143112301006035504030c096c6f63616c686f7374301e170d3139303930343137343331355a170d3139303930353137343331355a30143112301006035504030c096c6f63616c686f737430820122300d06092a864886f70d01010105000382010f003082010a0282010100b8045cf2840c492cda737c192b3fb9251db68984d88693eda1637b96cb4bffd7cc54d9b234eb1f05c08623958e586681e3f9e3edbdfe822817da78230ffe034382fe24129e7471184d27f22364def8acf1c9bdfc185462da2bf636fabd231c0e2f41497cf4f4fa40c19f26af1ef707c9f1760da41af7fc1a99236f72ffd76ac45d0e74837922910c5cc0c0aaf75736426c27a7c067a3d88c356fdb7eac31e3d62e8535a1ae1a8eb6186ac14fc6f2a260d314a340df7e64e502e78ea336a56fee9f6fb44eb0bb0200767f1878698797fcd68c149c031ef2a897664cdda17df1ffc04ff998f19c3312ab19c70bfa53d31cb82ffdff179261e190c63a899499ff410203010001300d06092a864886f70d01010b05000382010100713ee02ef5d1c916e50183a98f73c67ab3343638656f1783f2e1e9ae76aaf92810a45e453701c3227dd51621d15535e99aefa3c5a438c9f536c08347aa4df4b9cfac2e32b9ccdac6c9b19d0ff59f26b0465a5136d908bd02683b1a01f8dd9e6b541b7f420951a31c3b4878bd733aa6b34ce634c99b546a8651035b54b3e03a08e0dfe9567a4a7d360b944dee04dbaeecd2f8818103082762176b1512bb65230e7732c5bef0beaa820af9a4c4ddc8d98d575f6204330fb02d0b2b3cef47967d110f49a75b3058c8f58f35c9bfab405f088191f24e43a53ce174806c65778879967ded5635ee84bc9b0c9724cce092e56dd338f49f802f20c0a00bca997799c66d')" on '/Users/danielgraham/Library/Developer/CoreSimulator/Devices/39375895-145D-494D-A067-D24B093DA6BC/data/Library/Keychains/TrustStore.sqlite3'
info XCUITest Safari test requested
dbug BaseDriver Event 'appConfigured' logged at 1567618995171 (10:43:15 GMT-0700 (Pacific Daylight Time))
dbug BaseDriver Event 'resetStarted' logged at 1567618995171 (10:43:15 GMT-0700 (Pacific Daylight Time))
dbug XCUITest Reset: noReset is on. Leaving simulator as is
dbug BaseDriver Event 'resetComplete' logged at 1567618995171 (10:43:15 GMT-0700 (Pacific Daylight Time))
WARN XCUITest Continuing without capturing device logs: iOS Simulator with udid 39375895-145D-494D-A067-D24B093DA6BC is not running
info XCUITest Setting up simulator
dbug iOS No reason to set locale
dbug iOS No iOS / app preferences to set
dbug iOS Setting did not need to be updated
dbug iOSSim Setting preferences of 39375895-145D-494D-A067-D24B093DA6BC Simulator to {"ConnectHardwareKeyboard":false}
dbug iOSSim Setting common Simulator preferences to {"RotateWindowWhenSignaledByGuest":true,"ConnectHardwareKeyboard":false}
dbug iOSSim Updated 39375895-145D-494D-A067-D24B093DA6BC Simulator preferences at '/Users/danielgraham/Library/Preferences/com.apple.iphonesimulator.plist' with {"DevicePreferences":{"39375895-145D-494D-A067-D24B093DA6BC":{"ConnectHardwareKeyboard":false}},"RotateWindowWhenSignaledByGuest":true,"ConnectHardwareKeyboard":false}
info iOSSim Booting Simulator with UDID 39375895-145D-494D-A067-D24B093DA6BC...
info iOSSim Starting Simulator UI with command: open -Fn /Users/danielgraham/Xcode/Xcode-beta.app/Contents/Developer/Applications/Simulator.app --args -CurrentDeviceUDID 39375895-145D-494D-A067-D24B093DA6BC -ConnectHardwareKeyboard 0
info iOSSim Simulator with UDID 39375895-145D-494D-A067-D24B093DA6BC booted in 34 seconds
dbug IDB Connecting idb service to '39375895-145D-494D-A067-D24B093DA6BC'
dbug IDB Disconnecting idb service from '39375895-145D-494D-A067-D24B093DA6BC'
dbug IDB idb daemon started on port 9889
info IDB Successfully established the connection to idb service for '39375895-145D-494D-A067-D24B093DA6BC'
dbug BaseDriver Event 'simStarted' logged at 1567619036907 (10:43:56 GMT-0700 (Pacific Daylight Time))
dbug IOSSimulatorLog Starting log capture for iOS Simulator with udid '39375895-145D-494D-A067-D24B093DA6BC', using 'xcrun simctl spawn 39375895-145D-494D-A067-D24B093DA6BC log stream --style compact'
dbug BaseDriver Event 'logCaptureStarted' logged at 1567619037467 (10:43:57 GMT-0700 (Pacific Daylight Time))
info XCUITest Using WDA path: '/Users/danielgraham/appium-xcuitest-driver/node_modules/appium-webdriveragent'
info XCUITest Using WDA agent: '/Users/danielgraham/appium-xcuitest-driver/node_modules/appium-webdriveragent/WebDriverAgent.xcodeproj'
dbug XCUITest No obsolete cached processes from previous WDA sessions listening on port 8100 have been found
dbug XCUITest Starting WebDriverAgent initialization with the synchronization key 'XCUITestDriver'
dbug XCUITest Capability 'useNewWDA' set to true, so uninstalling WDA before proceeding
info XCUITest Shutting down sub-processes
dbug iOSSim Building bundle path map
dbug XCUITest No WDAs on the device.
dbug BaseDriver Event 'wdaUninstalled' logged at 1567619037725 (10:43:57 GMT-0700 (Pacific Daylight Time))
dbug XCUITest Trying to start WebDriverAgent 2 times with 10000ms interval
dbug XCUITest These values can be customized by changing wdaStartupRetries/wdaStartupRetryInterval capabilities
dbug BaseDriver Event 'wdaStartAttempted' logged at 1567619037725 (10:43:57 GMT-0700 (Pacific Daylight Time))
info XCUITest Launching WebDriverAgent on the device
info WebDriverAgent Fetching dependencies
info WebDriverAgent Dependencies up-to-date
dbug XCUITest Killing running processes 'xcodebuild.*39375895-145D-494D-A067-D24B093DA6BC, 39375895-145D-494D-A067-D24B093DA6BC.*XCTRunner' for the device 39375895-145D-494D-A067-D24B093DA6BC...
dbug XCUITest 'pgrep -ifn xcodebuild.*39375895-145D-494D-A067-D24B093DA6BC' didn't detect any matching processes. Return code: 1
dbug XCUITest 'pgrep -ifn 39375895-145D-494D-A067-D24B093DA6BC.*XCTRunner' didn't detect any matching processes. Return code: 1
dbug XCUITest Beginning test with command 'xcodebuild build-for-testing test-without-building -project /Users/danielgraham/appium-xcuitest-driver/node_modules/appium-webdriveragent/WebDriverAgent.xcodeproj -scheme WebDriverAgentRunner -destination id=39375895-145D-494D-A067-D24B093DA6BC IPHONEOS_DEPLOYMENT_TARGET=13.0 GCC_TREAT_WARNINGS_AS_ERRORS=0 COMPILER_INDEX_STORE_ENABLE=NO' in directory '/Users/danielgraham/appium-xcuitest-driver/node_modules/appium-webdriveragent'
dbug XCUITest Output from xcodebuild will only be logged if any errors are present there. To change this, use 'showXcodeLog' desired capability
dbug XCUITest Waiting up to 240000ms for WebDriverAgent to start
dbug WD Proxy Matched '/status' to command name 'getStatus'
dbug WD Proxy Proxying [GET /status] to [GET http://localhost:8100/status] with no body
info WD Proxy Got an unexpected response with status undefined: {"errno":"ECONNREFUSED","code":"ECONNREFUSED","syscall":"connect","address":"127.0.0.1","port":8100}
dbug WD Proxy Matched '/status' to command name 'getStatus'
dbug WD Proxy Proxying [GET /status] to [GET http://localhost:8100/status] with no body
info WD Proxy Got an unexpected response with status undefined: {"errno":"ECONNREFUSED","code":"ECONNREFUSED","syscall":"connect","address":"127.0.0.1","port":8100}
dbug WD Proxy Matched '/status' to command name 'getStatus'
dbug WD Proxy Proxying [GET /status] to [GET http://localhost:8100/status] with no body
info WD Proxy Got an unexpected response with status undefined: {"errno":"ECONNREFUSED","code":"ECONNREFUSED","syscall":"connect","address":"127.0.0.1","port":8100}
dbug XCUITest Log file for xcodebuild test: /Users/danielgraham/Library/Developer/Xcode/DerivedData/WebDriverAgent-gsarxbnghdckurdrxkxxwuwfvisl/Logs/Test/Test-WebDriverAgentRunner-2019.09.04_10-43-59--0700.xcresult/Staging/2_Test/Diagnostics/WebDriverAgentRunner-0A2EF48E-F20E-4156-9349-B93D6D921930/WebDriverAgentRunner-71841CB5-3C1F-41CA-9699-EBD87DD4034F/Session-WebDriverAgentRunner-2019-09-04_104401-YdN8Jm.log
dbug WD Proxy Matched '/status' to command name 'getStatus'
dbug WD Proxy Proxying [GET /status] to [GET http://localhost:8100/status] with no body
info WD Proxy Got an unexpected response with status undefined: {"errno":"ECONNREFUSED","code":"ECONNREFUSED","syscall":"connect","address":"127.0.0.1","port":8100}
dbug WD Proxy Matched '/status' to command name 'getStatus'
dbug WD Proxy Proxying [GET /status] to [GET http://localhost:8100/status] with no body
info WD Proxy Got an unexpected response with status undefined: {"errno":"ECONNREFUSED","code":"ECONNREFUSED","syscall":"connect","address":"127.0.0.1","port":8100}
dbug WD Proxy Matched '/status' to command name 'getStatus'
dbug WD Proxy Proxying [GET /status] to [GET http://localhost:8100/status] with no body
info WD Proxy Got an unexpected response with status undefined: {"errno":"ECONNREFUSED","code":"ECONNREFUSED","syscall":"connect","address":"127.0.0.1","port":8100}
dbug WD Proxy Matched '/status' to command name 'getStatus'
dbug WD Proxy Proxying [GET /status] to [GET http://localhost:8100/status] with no body
info WD Proxy Got an unexpected response with status undefined: {"errno":"ECONNREFUSED","code":"ECONNREFUSED","syscall":"connect","address":"127.0.0.1","port":8100}
dbug WD Proxy Matched '/status' to command name 'getStatus'
dbug WD Proxy Proxying [GET /status] to [GET http://localhost:8100/status] with no body
dbug WD Proxy Got response with status 200: {
dbug WD Proxy "value" : {
dbug WD Proxy "message" : "WebDriverAgent is ready to accept commands",
dbug WD Proxy "state" : "success",
dbug WD Proxy "os" : {
dbug WD Proxy "name" : "iOS",
dbug WD Proxy "version" : "13.0",
dbug WD Proxy "sdkVersion" : "13.0"
dbug WD Proxy },
dbug WD Proxy "ios" : {
dbug WD Proxy "simulatorVersion" : "13.0",
dbug WD Proxy "ip" : "10.30.0.76"
dbug WD Proxy },
dbug WD Proxy "ready" : true,
dbug WD Proxy "build" : {
dbug WD Proxy "upgradedAt" : "1567562906809",
dbug WD Proxy "time" : "Sep 3 2019 19:08:34",
dbug WD Proxy "productBundleIdentifier" : "com.facebook.WebDriverAgentRunner"
dbug WD Proxy }
dbug WD Proxy },
dbug WD Proxy "sessionId" : "8E5B35F8-6017-4895-A1CC-E31EBEF97C30"
dbug WD Proxy }
dbug XCUITest WebDriverAgent information:
dbug XCUITest {
dbug XCUITest "message": "WebDriverAgent is ready to accept commands",
dbug XCUITest "state": "success",
dbug XCUITest "os": {
dbug XCUITest "name": "iOS",
dbug XCUITest "version": "13.0",
dbug XCUITest "sdkVersion": "13.0"
dbug XCUITest },
dbug XCUITest "ios": {
dbug XCUITest "simulatorVersion": "13.0",
dbug XCUITest "ip": "10.30.0.76"
dbug XCUITest },
dbug XCUITest "ready": true,
dbug XCUITest "build": {
dbug XCUITest "upgradedAt": "1567562906809",
dbug XCUITest "time": "Sep 3 2019 19:08:34",
dbug XCUITest "productBundleIdentifier": "com.facebook.WebDriverAgentRunner"
dbug XCUITest }
dbug XCUITest }
dbug XCUITest WebDriverAgent successfully started after 7651ms
dbug BaseDriver Event 'wdaSessionAttempted' logged at 1567619045461 (10:44:05 GMT-0700 (Pacific Daylight Time))
dbug XCUITest Sending createSession command to WDA
dbug WD Proxy Matched '/session' to command name 'createSession'
dbug WD Proxy Proxying [POST /session] to [POST http://localhost:8100/session] with body: {"capabilities":{"firstMatch":[{"bundleId":"com.apple.mobilesafari","arguments":[],"environment":{},"eventloopIdleDelaySec":0,"shouldWaitForQuiescence":true,"shouldUseTestManagerForVisibilityDetection":false,"maxTypingFrequency":30,"shouldUseSingletonTestManager":true}],"alwaysMatch":{}}}
dbug WD Proxy Got response with status 200: {"value":{"sessionId":"0057A9DD-7B6B-4ACA-9D13-A2F79DC97654","capabilities":{"device":"iphone","browserName":"Safari","sdkVersion":"13.0","CFBundleIdentifier":"com.apple.mobilesafari"}},"sessionId":"0057A9DD-7B6B-4ACA-9D13-A2F79DC97654"}
info WD Proxy Determined the downstream protocol as 'W3C'
dbug BaseDriver Event 'wdaSessionStarted' logged at 1567619050155 (10:44:10 GMT-0700 (Pacific Daylight Time))
dbug XCUITest Parsed BUILD_DIR configuration value: '/Users/danielgraham/Library/Developer/Xcode/DerivedData/WebDriverAgent-gsarxbnghdckurdrxkxxwuwfvisl/Build/Products'
dbug XCUITest Got derived data root: '/Users/danielgraham/Library/Developer/Xcode/DerivedData/WebDriverAgent-gsarxbnghdckurdrxkxxwuwfvisl'
dbug BaseDriver Event 'wdaStarted' logged at 1567619055701 (10:44:15 GMT-0700 (Pacific Daylight Time))
info XCUITest Skipping setting of the initial display orientation. Set the "orientation" capability to either "LANDSCAPE" or "PORTRAIT", if this is an undesired behavior.
dbug BaseDriver Event 'orientationSet' logged at 1567619055702 (10:44:15 GMT-0700 (Pacific Daylight Time))
dbug XCUITest Waiting for initial webview
dbug iOS Navigating to most recently opened webview
dbug iOS Retrieving contexts and views
dbug XCUITest Selecting by url: true (expected url: 'http://localhost:4994/test/guinea-pig')
info RemoteDebugger Remote Debugger version 5.3.0
dbug RemoteDebugger useNewSafari --> false
dbug RemoteDebugger Checking which communication style to use (Safari on platform version '13.0')
dbug RemoteDebugger Platform version equal or higher than '12.2': true
WARN RemoteDebugger Setting communication protocol: using Target-based communication
dbug RemoteDebugger Connecting to remote debugger through unix domain socket: '/private/tmp/com.apple.launchd.7z3yWyBkUP/com.apple.webinspectord_sim.socket'
dbug RemoteDebugger Debugger socket connected
dbug RemoteDebugger Sending connection key request
dbug RemoteDebugger Sending '_rpc_reportIdentifier:' message (id: 0)
info HTTP --> GET /test/guinea-pig
info HTTP {}
dbug HTTP Sending guinea pig response with params: {"throwError":"","serverTime":1567619056,"userAgent":"Mozilla/5.0 (iPhone; CPU iPhone OS 13_0 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/13.0 Mobile/15E148 Safari/604.1","comment":"None"}
info HTTP <-- GET /test/guinea-pig 200 11 ms - 3566
info HTTP
info HTTP <-- GET /js/jquery.min.js 200 1 ms - 92630
info HTTP
info HTTP <-- GET /favicon.ico 200 1 ms - 1150
info HTTP
dbug RemoteDebugger Connected to application
dbug RemoteDebugger Selecting application
dbug RemoteDebugger Current applications available:
dbug RemoteDebugger Application: 'PID:43240'
dbug RemoteDebugger id: "PID:43240"
dbug RemoteDebugger isProxy: false
dbug RemoteDebugger name: "Safari"
dbug RemoteDebugger bundleId: "com.apple.mobilesafari"
dbug RemoteDebugger hostId: undefined
dbug RemoteDebugger isActive: 2
dbug RemoteDebugger isAutomationEnabled: false
dbug RemoteDebugger Found app id key 'PID:43240' for bundle 'com.apple.mobilesafari'
dbug RemoteDebugger Trying out the possible app ids: PID:43240
dbug RemoteDebugger Selecting app PID:43240 (try #1 of 20)
dbug RemoteDebugger Sending '_rpc_forwardGetListing:' message to app 'PID:43240' (id: 1)
dbug RemoteDebugger We were notified we might have connected to the wrong app. Using id PID:43244 instead of PID:43240
dbug RemoteDebugger Notified that new application 'PID:43244' has connected
dbug RemoteDebugger Found app id key 'PID:43240' for bundle 'com.apple.mobilesafari'
dbug RemoteDebugger Found separate bundleId 'process-com.apple.WebKit.WebContent' acting as proxy for 'com.apple.mobilesafari', with app id 'PID:43244'
dbug RemoteDebugger Using proxied app id 'PID:43244'
dbug RemoteDebugger Error checking application: 'New application has connected'. Retrying connection
dbug RemoteDebugger Received response from send (id: 1): '["PID:43240",{"1":{"WIRTitleKey":"I am a page title","WIRTypeKey":"WIRTypeWeb","WIRURLKey":"http://localhost:4994/test/guinea-pig","WIRPageIdentifierKey":1}}]'
dbug RemoteDebugger Current applications available:
dbug RemoteDebugger Application: 'PID:43240'
dbug RemoteDebugger id: "PID:43240"
dbug RemoteDebugger isProxy: false
dbug RemoteDebugger name: "Safari"
dbug RemoteDebugger bundleId: "com.apple.mobilesafari"
dbug RemoteDebugger hostId: undefined
dbug RemoteDebugger isActive: 2
dbug RemoteDebugger isAutomationEnabled: false
dbug RemoteDebugger pageArray:
dbug RemoteDebugger - id: 1
dbug RemoteDebugger title: "I am a page title"
dbug RemoteDebugger url: "http://localhost:4994/test/guinea-pig"
dbug RemoteDebugger isKey: false
dbug RemoteDebugger Application: 'PID:43244'
dbug RemoteDebugger id: "PID:43244"
dbug RemoteDebugger isProxy: true
dbug RemoteDebugger name: "com.apple.WebKit.WebContent"
dbug RemoteDebugger bundleId: "process-com.apple.WebKit.WebContent"
dbug RemoteDebugger hostId: "PID:43240"
dbug RemoteDebugger isActive: 2
dbug RemoteDebugger isAutomationEnabled: false
dbug RemoteDebugger pageArray: 'Waiting for data'
dbug RemoteDebugger Page changed: {
dbug RemoteDebugger "1": {
dbug RemoteDebugger "WIRTitleKey": "I am a page title",
dbug RemoteDebugger "WIRTypeKey": "WIRTypeWeb",
dbug RemoteDebugger "WIRURLKey": "http://localhost:4994/test/guinea-pig",
dbug RemoteDebugger "WIRPageIdentifierKey": 1
dbug RemoteDebugger }
dbug RemoteDebugger }
dbug RemoteDebugger Received page change notice for app 'PID:43240' but listening for 'PID:43244'. Ignoring.
dbug RemoteDebugger Sending to Web Inspector took 43ms
dbug RemoteDebugger Notified that new application 'PID:43283' has connected
dbug RemoteDebugger Current applications available:
dbug RemoteDebugger Application: 'PID:43240'
dbug RemoteDebugger id: "PID:43240"
dbug RemoteDebugger isProxy: false
dbug RemoteDebugger name: "Safari"
dbug RemoteDebugger bundleId: "com.apple.mobilesafari"
dbug RemoteDebugger hostId: undefined
dbug RemoteDebugger isActive: 2
dbug RemoteDebugger isAutomationEnabled: false
dbug RemoteDebugger pageArray:
dbug RemoteDebugger - id: 1
dbug RemoteDebugger title: "I am a page title"
dbug RemoteDebugger url: "http://localhost:4994/test/guinea-pig"
dbug RemoteDebugger isKey: false
dbug RemoteDebugger Application: 'PID:43244'
dbug RemoteDebugger id: "PID:43244"
dbug RemoteDebugger isProxy: true
dbug RemoteDebugger name: "com.apple.WebKit.WebContent"
dbug RemoteDebugger bundleId: "process-com.apple.WebKit.WebContent"
dbug RemoteDebugger hostId: "PID:43240"
dbug RemoteDebugger isActive: 2
dbug RemoteDebugger isAutomationEnabled: false
dbug RemoteDebugger pageArray: 'Waiting for data'
dbug RemoteDebugger Application: 'PID:43283'
dbug RemoteDebugger id: "PID:43283"
dbug RemoteDebugger isProxy: true
dbug RemoteDebugger name: "com.apple.WebKit.WebContent"
dbug RemoteDebugger bundleId: "process-com.apple.WebKit.WebContent"
dbug RemoteDebugger hostId: "PID:43240"
dbug RemoteDebugger isActive: 0
dbug RemoteDebugger isAutomationEnabled: false
dbug RemoteDebugger pageArray: 'Waiting for data'
dbug RemoteDebugger Found app id key 'PID:43240' for bundle 'com.apple.mobilesafari'
dbug RemoteDebugger Found separate bundleId 'process-com.apple.WebKit.WebContent' acting as proxy for 'com.apple.mobilesafari', with app id 'PID:43244'
dbug RemoteDebugger Found separate bundleId 'process-com.apple.WebKit.WebContent' acting as proxy for 'com.apple.mobilesafari', with app id 'PID:43283'
dbug RemoteDebugger Trying out the possible app ids: PID:43240, PID:43244, PID:43283
dbug RemoteDebugger Selecting app PID:43240 (try #1 of 20)
dbug RemoteDebugger Sending '_rpc_forwardGetListing:' message to app 'PID:43240' (id: 2)
dbug RemoteDebugger Received response from send (id: 2): '["PID:43240",{"1":{"WIRTitleKey":"I am a page title","WIRTypeKey":"WIRTypeWeb","WIRURLKey":"http://localhost:4994/test/guinea-pig","WIRPageIdentifierKey":1}}]'
dbug RemoteDebugger Received page change notice for app 'PID:43240' but the listing has not changed. Ignoring.
dbug RemoteDebugger Sending to Web Inspector took 2ms
dbug RemoteDebugger Received altered app id, updating from 'PID:43244' to 'PID:43240'
dbug RemoteDebugger Current applications available:
dbug RemoteDebugger Application: 'PID:43240'
dbug RemoteDebugger id: "PID:43240"
dbug RemoteDebugger isProxy: false
dbug RemoteDebugger name: "Safari"
dbug RemoteDebugger bundleId: "com.apple.mobilesafari"
dbug RemoteDebugger hostId: undefined
dbug RemoteDebugger isActive: 2
dbug RemoteDebugger isAutomationEnabled: false
dbug RemoteDebugger pageArray:
dbug RemoteDebugger - id: 1
dbug RemoteDebugger title: "I am a page title"
dbug RemoteDebugger url: "http://localhost:4994/test/guinea-pig"
dbug RemoteDebugger isKey: false
dbug RemoteDebugger Application: 'PID:43244'
dbug RemoteDebugger id: "PID:43244"
dbug RemoteDebugger isProxy: true
dbug RemoteDebugger name: "com.apple.WebKit.WebContent"
dbug RemoteDebugger bundleId: "process-com.apple.WebKit.WebContent"
dbug RemoteDebugger hostId: "PID:43240"
dbug RemoteDebugger isActive: 2
dbug RemoteDebugger isAutomationEnabled: false
dbug RemoteDebugger pageArray: 'Waiting for data'
dbug RemoteDebugger Application: 'PID:43283'
dbug RemoteDebugger id: "PID:43283"
dbug RemoteDebugger isProxy: true
dbug RemoteDebugger name: "com.apple.WebKit.WebContent"
dbug RemoteDebugger bundleId: "process-com.apple.WebKit.WebContent"
dbug RemoteDebugger hostId: "PID:43240"
dbug RemoteDebugger isActive: 0
dbug RemoteDebugger isAutomationEnabled: false
dbug RemoteDebugger pageArray: 'Waiting for data'
dbug RemoteDebugger Finally selecting app PID:43240: [{"id":1,"title":"I am a page title","url":"http://localhost:4994/test/guinea-pig","isKey":false}]
dbug RemoteDebugger Selected app after 540ms
dbug iOS Picking webview 'WEBVIEW_43240.1'
dbug XCUITest Attempting to set context to 'WEBVIEW_43240.1'
dbug RemoteDebugger Selecting page '1' on app 'PID:43240' and forwarding socket setup
dbug RemoteDebugger Sending '_rpc_forwardSocketSetup:' message to app 'PID:43240', page '1' (id: 3)
dbug RemoteDebugger Sending to Web Inspector took 3ms
dbug RemoteDebugger Sender key set
dbug RemoteDebugger Target created for app 'PID:43240' and page '1': {"targetId":"page-6","type":"page"}
dbug RemoteDebugger Current applications available:
dbug RemoteDebugger Application: 'PID:43240'
dbug RemoteDebugger id: "PID:43240"
dbug RemoteDebugger isProxy: false
dbug RemoteDebugger name: "Safari"
dbug RemoteDebugger bundleId: "com.apple.mobilesafari"
dbug RemoteDebugger hostId: undefined
dbug RemoteDebugger isActive: 2
dbug RemoteDebugger isAutomationEnabled: false
dbug RemoteDebugger pageArray:
dbug RemoteDebugger - id: 1
dbug RemoteDebugger title: "I am a page title"
dbug RemoteDebugger url: "http://localhost:4994/test/guinea-pig"
dbug RemoteDebugger isKey: true
dbug RemoteDebugger Application: 'PID:43244'
dbug RemoteDebugger id: "PID:43244"
dbug RemoteDebugger isProxy: true
dbug RemoteDebugger name: "com.apple.WebKit.WebContent"
dbug RemoteDebugger bundleId: "process-com.apple.WebKit.WebContent"
dbug RemoteDebugger hostId: "PID:43240"
dbug RemoteDebugger isActive: 2
dbug RemoteDebugger isAutomationEnabled: false
dbug RemoteDebugger pageArray: 'Waiting for data'
dbug RemoteDebugger Application: 'PID:43283'
dbug RemoteDebugger id: "PID:43283"
dbug RemoteDebugger isProxy: true
dbug RemoteDebugger name: "com.apple.WebKit.WebContent"
dbug RemoteDebugger bundleId: "process-com.apple.WebKit.WebContent"
dbug RemoteDebugger hostId: "PID:43240"
dbug RemoteDebugger isActive: 0
dbug RemoteDebugger isAutomationEnabled: false
dbug RemoteDebugger pageArray: 'Waiting for data'
dbug RemoteDebugger Page changed: {
dbug RemoteDebugger "1": {
dbug RemoteDebugger "WIRTitleKey": "I am a page title",
dbug RemoteDebugger "WIRTypeKey": "WIRTypeWeb",
dbug RemoteDebugger "WIRURLKey": "http://localhost:4994/test/guinea-pig",
dbug RemoteDebugger "WIRPageIdentifierKey": 1,
dbug RemoteDebugger "WIRConnectionIdentifierKey": "7f87d373-3bd3-4d28-a3e8-05f2e25f0717"
dbug RemoteDebugger }
dbug RemoteDebugger }
dbug iOS Remote debugger notified us of a new page listing: {"appIdKey":"43240","pageArray":[{"id":1,"title":"I am a page title","url":"http://localhost:4994/test/guinea-pig","isKey":true}]}
dbug iOS We do not appear to have window set yet, ignoring
dbug RemoteDebugger Sending '_rpc_forwardSocketData:' message to app 'PID:43240', page '1', target 'page-6' (id: 4)
dbug RemoteDebugger Received data response from send (id: 4): '{}'
dbug RemoteDebugger Sending to Web Inspector took 1012ms
dbug RemoteDebugger Enabled activity on page
dbug RemoteDebugger Checking document readyState
dbug RemoteDebugger Sending javascript command (function (){ return document.readyState; })()
dbug RemoteDebugger Sending '_rpc_forwardSocketData:' message to app 'PID:43240', page '1', target 'page-6' (id: 5)
dbug RemoteDebugger Received data response from send (id: 5): 'complete'
dbug RemoteDebugger Sending to Web Inspector took 8ms
dbug RemoteDebugger Document readyState is 'complete'
dbug RemoteDebugger Selected page after 1025ms
dbug RemoteDebugger Starting to listen for JavaScript console
dbug RemoteDebugger Sending '_rpc_forwardSocketData:' message to app 'PID:43240', page '1', target 'page-6' (id: 6)
dbug RemoteDebugger Received data response from send (id: 6): '{}'
dbug RemoteDebugger Sending to Web Inspector took 4ms
dbug RemoteDebugger Starting to listen for network events
dbug RemoteDebugger Sending '_rpc_forwardSocketData:' message to app 'PID:43240', page '1', target 'page-6' (id: 7)
dbug RemoteDebugger Received data response from send (id: 7): '{}'
dbug RemoteDebugger Sending to Web Inspector took 36ms
dbug RemoteDebugger Unregistering from page readiness notifications
dbug BaseDriver Event 'initialWebviewNavigated' logged at 1567619059296 (10:44:19 GMT-0700 (Pacific Daylight Time))
dbug BaseDriver Event 'newSessionStarted' logged at 1567619059296 (10:44:19 GMT-0700 (Pacific Daylight Time))
dbug MJSONWP (e29c2474) Cached the protocol value 'MJSONWP' for the new session e29c2474-bad5-4e23-991b-112a3ec555d4
dbug MJSONWP (e29c2474) Responding to client with driver.createSession() result: {"webStorageEnabled":false,"locationContextEnabled":false,"browserName":"Safari","platform":"MAC","javascriptEnabled":true,"databaseEnabled":false,"takesScreenshot":true,"networkConnectionEnabled":false,"safariInitialUrl":"http://localhost:4994/test/guinea-pig","nativeWebTap":true,"enableAsyncExecuteFromHttps":true,"platformName":"iOS","platformVersion":"13.0","deviceName":"iPhone X","automationName":"XCUITest","noReset":true,"maxTypingFrequency":30,"clearSystemFiles":true,"wdaLaunchTimeout":240000,"wdaConnectionTimeout":480000,"useNewWDA":true,"udid":"39375895-145D-494D-A067-D24B093DA6BC"}
info HTTP <-- POST /wd/hub/session 200 65186 ms - 669
info HTTP
info HTTP --> POST /wd/hub/session/e29c2474-bad5-4e23-991b-112a3ec555d4/url
info HTTP {"url":"https://google.com"}
dbug MJSONWP (e29c2474) Calling XCUITestDriver.setUrl() with args: ["https://google.com","e29c2474-bad5-4e23-991b-112a3ec555d4"]
dbug XCUITest Executing command 'setUrl'
dbug iOS Attempting to set url 'https://google.com'
dbug RemoteDebugger Navigating to new URL: 'https://google.com'
dbug RemoteDebugger Sending '_rpc_forwardSocketData:' message to app 'PID:43240', page '1', target 'page-6' (id: 8)
dbug RemoteDebugger Received data response from send (id: 8): '{}'
dbug RemoteDebugger Sending to Web Inspector took 15ms
dbug RemoteDebugger Received 'targetDestroyed' event with no target. Skipping
WARN RemoteDebugger Received 'targetCreated' event with no pending request: {"targetId":"page-6","type":"page"}
dbug RemoteDebugger Waiting for frame navigated message...
dbug RemoteDebugger Frame navigated in 0.501 sec from source: timeout
dbug RemoteDebugger Waiting for dom...
dbug RemoteDebugger Page loaded, verifying whether ready
dbug RemoteDebugger Notified that new application 'PID:43284' has connected
dbug RemoteDebugger Checking document readyState
dbug RemoteDebugger Sending javascript command (function (){ return document.readyState; })()
dbug RemoteDebugger Sending '_rpc_forwardSocketData:' message to app 'PID:43240', page '1', target 'page-6' (id: 9)
dbug RemoteDebugger Received data response from send (id: 9): 'complete'
dbug RemoteDebugger Sending to Web Inspector took 6ms
dbug RemoteDebugger Document readyState is 'complete'
dbug RemoteDebugger Page is ready
dbug MJSONWP (e29c2474) Responding to client with driver.setUrl() result: null
info HTTP <-- POST /wd/hub/session/e29c2474-bad5-4e23-991b-112a3ec555d4/url 200 2060 ms - 76
info HTTP
info HTTP --> GET /wd/hub/session/e29c2474-bad5-4e23-991b-112a3ec555d4/title
info HTTP {}
dbug MJSONWP (e29c2474) Calling XCUITestDriver.title() with args: ["e29c2474-bad5-4e23-991b-112a3ec555d4"]
dbug XCUITest Executing command 'title'
dbug RemoteDebugger Executing atom 'title'
dbug RemoteDebugger Executing 'title' atom in default context
dbug RemoteDebugger Sending javascript command (function(){return function(){var g=this;functi...
dbug RemoteDebugger Sending '_rpc_forwardSocketData:' message to app 'PID:43240', page '1', target 'page-6' (id: 10)
dbug RemoteDebugger Received data response from send (id: 10): '{"status":0,"value":"Google"}'
dbug RemoteDebugger Sending to Web Inspector took 9ms
dbug RemoteDebugger Received result for atom 'title' execution: "Google"
dbug MJSONWP (e29c2474) Responding to client with driver.title() result: "Google"
info HTTP <-- GET /wd/hub/session/e29c2474-bad5-4e23-991b-112a3ec555d4/title 200 24 ms - 80
info HTTP
asynchronous
info HTTP --> POST /wd/hub/session/e29c2474-bad5-4e23-991b-112a3ec555d4/timeouts/async_script
info HTTP {"ms":1000}
dbug MJSONWP (e29c2474) Calling XCUITestDriver.asyncScriptTimeout() with args: [1000,"e29c2474-bad5-4e23-991b-112a3ec555d4"]
dbug XCUITest Executing command 'asyncScriptTimeout'
dbug iOS Set async script timeout to 1000ms
dbug MJSONWP (e29c2474) Responding to client with driver.asyncScriptTimeout() result: null
info HTTP <-- POST /wd/hub/session/e29c2474-bad5-4e23-991b-112a3ec555d4/timeouts/async_script 200 2 ms - 76
info HTTP
info HTTP --> POST /wd/hub/session/e29c2474-bad5-4e23-991b-112a3ec555d4/execute_async
info HTTP {"script":"arguments[arguments.length - 1](123);","args":[]}
dbug MJSONWP (e29c2474) Calling XCUITestDriver.executeAsync() with args: ["arguments[arguments.length - 1](123);",[],"e29c2474-bad5-4e23-991b-112a3ec555d4"]
dbug XCUITest Executing command 'executeAsync'
dbug RemoteDebugger Sending javascript command window.location.href
dbug RemoteDebugger Sending '_rpc_forwardSocketData:' message to app 'PID:43240', page '1', target 'page-6' (id: 11)
dbug RemoteDebugger Received data response from send (id: 11): 'https://www.google.com/'
dbug RemoteDebugger Sending to Web Inspector took 4ms
dbug iOS Response url for executeAsync: https://localhost:8000/wd/hub/session/e29c2474-bad5-4e23-991b-112a3ec555d4/receive_async_response
dbug RemoteDebugger Executing 'execute_async_script' atom in default context
dbug RemoteDebugger Sending javascript command (function(){return function(){var e=this;
dbug RemoteDebugger funct...
dbug RemoteDebugger Sending '_rpc_forwardSocketData:' message to app 'PID:43240', page '1', target 'page-6' (id: 12)
dbug RemoteDebugger Received data response from send (id: 12): '{"result":{"type":"undefined"}}'
dbug RemoteDebugger Sending to Web Inspector took 6ms
dbug MJSONWP (e29c2474) Encountered internal error running command: Error: Did not get any response after 300s
dbug MJSONWP (e29c2474) at XCUITestDriver.waitForAtom (/Users/danielgraham/appium-xcuitest-driver/lib/commands/web.js:273:13)
info HTTP <-- POST /wd/hub/session/e29c2474-bad5-4e23-991b-112a3ec555d4/execute_async 500 300026 ms - 206
info HTTP
1) should execute async javascript
info HTTP --> DELETE /wd/hub/session/e29c2474-bad5-4e23-991b-112a3ec555d4
info HTTP {}
dbug MJSONWP (e29c2474) Calling XCUITestDriver.deleteSession() with args: ["e29c2474-bad5-4e23-991b-112a3ec555d4"]
dbug XCUITest Executing command 'deleteSession'
dbug BaseDriver Event 'quitSessionRequested' logged at 1567619361439 (10:49:21 GMT-0700 (Pacific Daylight Time))
dbug IDB Disconnecting idb service from '39375895-145D-494D-A067-D24B093DA6BC'
dbug IDB Cleaning up 1 obsolete idb_companion process
dbug WD Proxy Matched '/session/e29c2474-bad5-4e23-991b-112a3ec555d4' to command name 'deleteSession'
dbug WD Proxy Proxying [DELETE /session/e29c2474-bad5-4e23-991b-112a3ec555d4] to [DELETE http://localhost:8100/session/0057A9DD-7B6B-4ACA-9D13-A2F79DC97654] with no body
dbug RemoteDebugger Application 'PID:43240' disconnected. Removing from app dictionary.
dbug RemoteDebugger Current app is PID:43240
dbug RemoteDebugger No longer have app id. Attempting to find new one.
dbug RemoteDebugger Application 'PID:43283' disconnected. Removing from app dictionary.
dbug RemoteDebugger Current app is undefined
dbug RemoteDebugger Application 'PID:43244' disconnected. Removing from app dictionary.
dbug RemoteDebugger Current app is undefined
dbug RemoteDebugger Application 'PID:43284' disconnected. Removing from app dictionary.
dbug RemoteDebugger Current app is undefined
dbug WD Proxy Got response with status 200: {
dbug WD Proxy "value" : null,
dbug WD Proxy "sessionId" : "A5A0A1E2-F694-4784-8F76-4588E15ADCA0"
dbug WD Proxy }
info XCUITest Shutting down sub-processes
info XCUITest Shutting down xcodebuild process (pid 43209)
ERR! XCUITest xcodebuild exited with code 'null' and signal 'SIGTERM'
dbug XCUITest Started background XCTest logs cleanup: find -E /private/var/folders -regex '.*/Session-WebDriverAgentRunner.*\.log$|.*/StandardOutputAndStandardError\.txt$' -type f -exec sh -c 'echo "" > "{}"' \;
info XCUITest Cleaning test logs in '/Users/danielgraham/Library/Developer/Xcode/DerivedData/WebDriverAgent-gsarxbnghdckurdrxkxxwuwfvisl/Logs' folder
dbug iOS Clearing log files
dbug iOS Deleting '/Users/danielgraham/Library/Developer/Xcode/DerivedData/WebDriverAgent-gsarxbnghdckurdrxkxxwuwfvisl/Logs'. Freeing 16K.
dbug iOS Finished clearing log files
dbug XCUITest In a web session. Removing remote debugger
dbug RemoteDebugger Disconnecting from remote debugger
dbug RemoteDebugger Cleaning up listeners
dbug IOSSimulatorLog Stopping iOS log capture
dbug iOS Stopping https server for async responses
dbug iOSSim Executing SQL query "CREATE TABLE IF NOT EXISTS tsettings (sha1 BLOB NOT NULL DEFAULT '', subj BLOB NOT NULL DEFAULT '', tset BLOB, data BLOB, PRIMARY KEY(sha1));" on '/Users/danielgraham/Library/Developer/CoreSimulator/Devices/39375895-145D-494D-A067-D24B093DA6BC/data/Library/Keychains/TrustStore.sqlite3'
dbug iOSSim Executing SQL query "CREATE INDEX isubj ON tsettings(subj);" on '/Users/danielgraham/Library/Developer/CoreSimulator/Devices/39375895-145D-494D-A067-D24B093DA6BC/data/Library/Keychains/TrustStore.sqlite3'
dbug iOSSim Executing SQL query "DELETE FROM tsettings WHERE subj = 'localhost
dbug iOSSim '" on '/Users/danielgraham/Library/Developer/CoreSimulator/Devices/39375895-145D-494D-A067-D24B093DA6BC/data/Library/Keychains/TrustStore.sqlite3'
dbug BaseDriver Event 'quitSessionFinished' logged at 1567619363012 (10:49:23 GMT-0700 (Pacific Daylight Time))
dbug MJSONWP (e29c2474) Received response: null
dbug MJSONWP (e29c2474) But deleting session, so not returning
dbug MJSONWP (e29c2474) Responding to client with driver.deleteSession() result: null
info HTTP <-- DELETE /wd/hub/session/e29c2474-bad5-4e23-991b-112a3ec555d4 200 1575 ms - 76
info HTTP
dbug XCUITest Killing running processes 'xcodebuild.*39375895-145D-494D-A067-D24B093DA6BC, 39375895-145D-494D-A067-D24B093DA6BC.*XCTRunner' for the device 39375895-145D-494D-A067-D24B093DA6BC...
dbug XCUITest 'pgrep -ifn xcodebuild.*39375895-145D-494D-A067-D24B093DA6BC' didn't detect any matching processes. Return code: 1
dbug XCUITest 'pgrep -ifn 39375895-145D-494D-A067-D24B093DA6BC.*XCTRunner' didn't detect any matching processes. Return code: 1
dbug iOSSim Killing all iOS Simulators
dbug iOSSim Using fkill to kill processes: 43041
dbug iOSSim Using pkill to kill application: Simulator
1 passing (8m)
1 failing
1) safari - execute -
https
asynchronous
should execute async javascript:
Error: [executeAsync("arguments[arguments.length - 1](123);")] Error response status: 13, , UnknownError - An unknown server-side error occurred while processing the command. Selenium error: An unknown server-side error occurred while processing the command. Original error: Did not get any response after 300s
at exports.newError (node_modules/wd/lib/utils.js:152:13)
at /Users/danielgraham/appium-xcuitest-driver/node_modules/wd/lib/callbacks.js:94:19
at /Users/danielgraham/appium-xcuitest-driver/node_modules/wd/lib/webdriver.js:196:5
at Request._callback (node_modules/wd/lib/http-utils.js:89:7)
at Request.self.callback (node_modules/request/request.js:185:22)
at Request.<anonymous> (node_modules/request/request.js:1161:10)
at IncomingMessage.<anonymous> (node_modules/request/request.js:1083:12)
at endReadableNT (_stream_readable.js:1129:12)
at process._tickCallback (internal/process/next_tick.js:63:19)
npm ERR! code ELIFECYCLE
npm ERR! errno 1
npm ERR! appium-xcuitest-driver@2.128.2 mocha: `mocha "-t" "0" "-R" "spec" "build/test/functional/web" "--exit"`
npm ERR! Exit status 1
npm ERR!
npm ERR! Failed at the appium-xcuitest-driver@2.128.2 mocha script.
npm ERR! This is probably not a problem with npm. There is likely additional logging output above.
npm ERR! A complete log of this run can be found in:
npm ERR! /Users/danielgraham/.npm/_logs/2019-09-04T17_49_29_708Z-debug.log
npm ERR! code ELIFECYCLE
npm ERR! errno 1
npm ERR! appium-xcuitest-driver@2.128.2 e2e-test:web: `npm run build && _FORCE_LOGS=1 npm run mocha -- -t 0 -R spec build/test/functional/web --exit`
npm ERR! Exit status 1
npm ERR!
npm ERR! Failed at the appium-xcuitest-driver@2.128.2 e2e-test:web script.
npm ERR! This is probably not a problem with npm. There is likely additional logging output above.
npm ERR! A complete log of this run can be found in:
npm ERR! /Users/danielgraham/.npm/_logs/2019-09-04T17_49_29_732Z-debug.log
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment