Skip to content

Instantly share code, notes, and snippets.

@maesiva
Created December 5, 2016 01:18
Show Gist options
  • Save maesiva/db03136e414517d2810e3432e7e370da to your computer and use it in GitHub Desktop.
Save maesiva/db03136e414517d2810e3432e7e370da to your computer and use it in GitHub Desktop.
Appium 1.6.2 logs
myMac:~ myUser$ appium --address 127.0.0.1
[Appium] Welcome to Appium v1.6.0
[Appium] Non-default server args:
[Appium] address: '127.0.0.1'
[Appium] Appium REST http interface listener started on 127.0.0.1:4723
[HTTP] --> POST /wd/hub/session {"capabilities":{"desiredCapabilities":{"app":"/Users/myUser/Development/mobile_app/myApp.app","platformVersion":"10.0.2","automationName":"XCUITest","autoWebView":"true","udid":"<myUDID>","platformName":"iOS","device":"iPad","deviceName":"iPad Air 2","fullReset":"false"},"requiredCapabilities":{}},"desiredCapabilities":{"app":"/Users/myUser/Development/mobile_app/myApp.app","platformVersion":"10.0.2","automationName":"XCUITest","autoWebView":"true","udid":"<myUDID>","platformName":"iOS","device":"iPad","deviceName":"iPad Air 2","fullReset":"false"},"requiredCapabilities":{}}
[debug] [MJSONWP] Bad parameters: BadParametersError: Parameters were incorrect. We wanted {"required":["desiredCapabilities"],"optional":["requiredCapabilities","sessionId","id"]} and you sent ["capabilities","desiredCapabilities","requiredCapabilities"]
[HTTP] <-- POST /wd/hub/session 400 6 ms - 201
[HTTP] --> POST /wd/hub/session {"desiredCapabilities":{"app":"/Users/myUser/Development/mobile_app/myApp.app","platformVersion":"10.0.2","automationName":"XCUITest","autoWebView":"true","udid":"<myUDID>","platformName":"iOS","device":"iPad","deviceName":"iPad Air 2","fullReset":"false"},"requiredCapabilities":{}}
[MJSONWP] Calling AppiumDriver.createSession() with args: [{"app":"/Users/myUser/Dev...
[Appium] Creating new XCUITestDriver session
[Appium] Capabilities:
[Appium] app: '/Users/myUser/Development/mobile_app/myApp.app'
[Appium] platformVersion: '10.0.2'
[Appium] automationName: 'XCUITest'
[Appium] autoWebView: 'true'
[Appium] udid: '<myUDID>'
[Appium] platformName: 'iOS'
[Appium] device: 'iPad'
[Appium] deviceName: 'iPad Air 2'
[Appium] fullReset: 'false'
[debug] [XCUITest] XCUITestDriver version: 2.0.26
[BaseDriver] Capability 'fullReset' changed from string to boolean. This may cause unexpected behavior
[BaseDriver] The following capabilities were provided, but are not recognized by appium: autoWebView, device.
[BaseDriver] Session created with session id: 4720f7e6-a0ec-4266-9c94-5d584efa0179
[debug] [XCUITest] Xcode version set to '8.0'
[debug] [XCUITest] iOS SDK Version set to '10.0'
[debug] [XCUITest] Available devices: <myUDID>
[debug] [XCUITest] Creating iDevice object with udid <myUDID>
[XCUITest] Determining device to run tests on: udid: '<myUDID>', real device: true
[BaseDriver] Using local app '/Users/myUser/Development/mobile_app/myApp.app'
[debug] [XCUITest] Checking whether app is actually present
[debug] [ios-app-utils] Getting bundle ID from app
[debug] [iOSLog] Attempting iOS device log capture via libimobiledevice idevicesyslog
[debug] [iOSLog] Found idevicesyslog: '/usr/local/bin/idevicesyslog'
[XCUITest] Setting up real device
[debug] [XCUITest] Stdout from app isInstalled check: [....] Waiting for iOS device to be connected
[....] Using J96AP 'iPad' (<myUDID>).
true
[debug] [XCUITest] App is installed.
[debug] [XCUITest] Full reset not requested. No need to install.
[XCUITest] Using default agent: /Users/myUser/local/lib/node_modules/appium/node_modules/appium-xcuitest-driver/WebDriverAgent/WebDriverAgent.xcodeproj
[XCUITest] Using default bootstrap: /Users/myUser/local/lib/node_modules/appium/node_modules/appium-xcuitest-driver/WebDriverAgent
[XCUITest] Launching WebDriverAgent on the device
[debug] [XCUITest] Carthage found: /usr/local/bin/carthage
[debug] [XCUITest] Beginning test with command '/Users/myUser/local/lib/node_modules/appium/node_modules/appium-xcuitest-driver/bin/run-xcodebuild.sh --project /Users/myUser/local/lib/node_modules/appium/node_modules/appium-xcuitest-driver/WebDriverAgent/WebDriverAgent.xcodeproj --scheme WebDriverAgentRunner --destination id=<myUDID>' in directory '/Users/myUser/local/lib/node_modules/appium/node_modules/appium-xcuitest-driver/WebDriverAgent'
[XCUITest] Waiting for WebDriverAgent to start on device
[debug] [XCUITest] Log file for xcodebuild test: /Users/myUser/Library/Developer/Xcode/DerivedData/WebDriverAgent-evuunhxarlywewebxgibzsvlgfoi/Logs/Test/B75F432C-165B-4585-87EA-E1BD733A2683/Session-WebDriverAgentRunner-2016-12-04_170647-g9Bf5t.log
[debug] [XCUITest] Waiting for WebDriverAgent server to finish loading...
[debug] [XCUITest] Waiting for WebDriverAgent server to finish loading...
[XCUITest] Detected that WebDriverAgent is running at url 'http://192.168.0.21:8100'
[XCUITest] WebDriverAgent started at url 'http://192.168.0.21:8100'
[debug] [XCUITest] Starting iproxy to forward traffic from local port 8100 to device port 8100 over USB
[JSONWP Proxy] Proxying [POST /session] to [POST http://localhost:8100/session] with body: {"desiredCapabilities":{"bu...
[debug] [XCUITest] Waiting for WebDriverAgent server to finish loading...
[JSONWP Proxy] Got response with status 200: {"value":{"sessionId":"F7B0...
[Appium] New XCUITestDriver session created successfully, session 4720f7e6-a0ec-4266-9c94-5d584efa0179 added to master session list
[MJSONWP] Responding to client with driver.createSession() result: {"webStorageEnabled":false,...
[HTTP] <-- POST /wd/hub/session 200 39630 ms - 535
[HTTP] --> GET /wd/hub/session/4720f7e6-a0ec-4266-9c94-5d584efa0179/contexts {}
[MJSONWP] Calling AppiumDriver.getContexts() with args: ["4720f7e6-a0ec-4266-9c94-5...
[debug] [XCUITest] Executing command 'getContexts'
[debug] [iOS] Getting list of available contexts
[debug] [iOS] Retrieving contexts and views
[debug] [iOS] Selecting by url: false
[debug] [RemoteDebugger] Getting WebKitRemoteDebugger pageArray: localhost, 27753
[debug] [RemoteDebugger] Sending request to: http://localhost:27753/json
[debug] [RemoteDebugger] Page element JSON: [{"devtoolsFrontendUrl":"/devtools/devtools.html?ws=localhost:27753/devtools/page/1","faviconUrl":"","thumbnailUrl":"/thumb/file:///var/containers/Bundle/Application/2ADF6222-8958-44DF-88E4-FAA813457C8B/myApp.app/www/index.html","title":"mobile","url":"file:///var/containers/Bundle/Application/2ADF6222-8958-44DF-88E4-FAA813457C8B/myApp.app/www/index.html","webSocketDebuggerUrl":"ws://localhost:27753/devtools/page/1","appId":"PID:401"}]
[MJSONWP] Responding to client with driver.getContexts() result: ["NATIVE_APP","WEBVIEW_1"]
[HTTP] <-- GET /wd/hub/session/4720f7e6-a0ec-4266-9c94-5d584efa0179/contexts 200 16 ms - 98
[HTTP] --> POST /wd/hub/session/4720f7e6-a0ec-4266-9c94-5d584efa0179/context {"name":"WEBVIEW_1"}
[MJSONWP] Calling AppiumDriver.setContext() with args: ["WEBVIEW_1","4720f7e6-a0ec...
[debug] [XCUITest] Executing command 'setContext'
[debug] [iOS] Attempting to set context to 'WEBVIEW_1'
[debug] [RemoteDebugger] WebKit debugger web socket connected to url: ws://localhost:27753/devtools/page/1
[MJSONWP] Responding to client with driver.setContext() result: null
[HTTP] <-- POST /wd/hub/session/4720f7e6-a0ec-4266-9c94-5d584efa0179/context 200 11 ms - 76
[HTTP] --> POST /wd/hub/session/4720f7e6-a0ec-4266-9c94-5d584efa0179/element {"using":"id","value":"loginid"}
[MJSONWP] Calling AppiumDriver.findElement() with args: ["id","loginid","4720f7e6-a...
[debug] [XCUITest] Executing command 'findElement'
[debug] [BaseDriver] Valid locator strategies for this request: xpath, id, name, class name, -ios predicate string, accessibility id
[debug] [BaseDriver] Waiting up to 0 ms for condition
[debug] [RemoteDebugger] Executing 'find_element' atom in default context
[debug] [RemoteDebugger] Sending javascript command (function(){return function...
[debug] [RemoteDebugger] Sending WebKit data: {"method":"Runtime.evaluate...
[debug] [RemoteDebugger] Receiving WebKit data: {"result":{"result":{"type"...
[debug] [RemoteDebugger] Found handler for message '1'
[debug] [RemoteDebugger] Received result for atom 'find_element' execution: {"ELEMENT":":wdc:1480899925833"}
[MJSONWP] Responding to client with driver.findElement() result: {"ELEMENT":"5000"}
[HTTP] <-- POST /wd/hub/session/4720f7e6-a0ec-4266-9c94-5d584efa0179/element 200 527 ms - 90
[HTTP] --> POST /wd/hub/session/4720f7e6-a0ec-4266-9c94-5d584efa0179/element/5000/value {"id":"5000","value":["User12"]}
[MJSONWP] Calling AppiumDriver.setValue() with args: [["User12"],"5000","4720...
[debug] [XCUITest] Executing command 'setValue'
[debug] [RemoteDebugger] Executing 'click' atom in default context
[debug] [RemoteDebugger] Sending javascript command (function(){return function...
[debug] [RemoteDebugger] Sending WebKit data: {"method":"Runtime.evaluate...
[debug] [RemoteDebugger] Receiving WebKit data: {"result":{"result":{"type"...
[debug] [RemoteDebugger] Found handler for message '2'
[debug] [RemoteDebugger] Received result for atom 'click' execution: null
[debug] [RemoteDebugger] Executing 'type' atom in default context
[debug] [RemoteDebugger] Sending javascript command (function(){return function...
[debug] [RemoteDebugger] Sending WebKit data: {"method":"Runtime.evaluate...
[debug] [RemoteDebugger] Receiving WebKit data: {"result":{"result":{"type"...
[debug] [RemoteDebugger] Found handler for message '3'
[debug] [RemoteDebugger] Received result for atom 'type' execution: null
[MJSONWP] Responding to client with driver.setValue() result: null
[HTTP] <-- POST /wd/hub/session/4720f7e6-a0ec-4266-9c94-5d584efa0179/element/5000/value 200 1023 ms - 76
[HTTP] --> POST /wd/hub/session/4720f7e6-a0ec-4266-9c94-5d584efa0179/element {"using":"id","value":"password"}
[MJSONWP] Calling AppiumDriver.findElement() with args: ["id","password","4720f7e6-...
[debug] [XCUITest] Executing command 'findElement'
[debug] [BaseDriver] Valid locator strategies for this request: xpath, id, name, class name, -ios predicate string, accessibility id
[debug] [BaseDriver] Waiting up to 0 ms for condition
[debug] [RemoteDebugger] Executing 'find_element' atom in default context
[debug] [RemoteDebugger] Sending javascript command (function(){return function...
[debug] [RemoteDebugger] Sending WebKit data: {"method":"Runtime.evaluate...
[debug] [RemoteDebugger] Receiving WebKit data: {"result":{"result":{"type"...
[debug] [RemoteDebugger] Found handler for message '4'
[debug] [RemoteDebugger] Received result for atom 'find_element' execution: {"ELEMENT":":wdc:1480899925834"}
[MJSONWP] Responding to client with driver.findElement() result: {"ELEMENT":"5001"}
[HTTP] <-- POST /wd/hub/session/4720f7e6-a0ec-4266-9c94-5d584efa0179/element 200 509 ms - 90
[HTTP] --> POST /wd/hub/session/4720f7e6-a0ec-4266-9c94-5d584efa0179/element/5001/value {"id":"5001","value":["Test!2345"]}
[MJSONWP] Calling AppiumDriver.setValue() with args: [["Test!2345"],"5001","4720...
[debug] [XCUITest] Executing command 'setValue'
[debug] [RemoteDebugger] Executing 'click' atom in default context
[debug] [RemoteDebugger] Sending javascript command (function(){return function...
[debug] [RemoteDebugger] Sending WebKit data: {"method":"Runtime.evaluate...
[debug] [RemoteDebugger] Receiving WebKit data: {"result":{"result":{"type"...
[debug] [RemoteDebugger] Found handler for message '5'
[debug] [RemoteDebugger] Received result for atom 'click' execution: null
[debug] [RemoteDebugger] Executing 'type' atom in default context
[debug] [RemoteDebugger] Sending javascript command (function(){return function...
[debug] [RemoteDebugger] Sending WebKit data: {"method":"Runtime.evaluate...
[debug] [RemoteDebugger] Receiving WebKit data: {"result":{"result":{"type"...
[debug] [RemoteDebugger] Found handler for message '6'
[debug] [RemoteDebugger] Received result for atom 'type' execution: null
[MJSONWP] Responding to client with driver.setValue() result: null
[HTTP] <-- POST /wd/hub/session/4720f7e6-a0ec-4266-9c94-5d584efa0179/element/5001/value 200 1011 ms - 76
[HTTP] --> POST /wd/hub/session/4720f7e6-a0ec-4266-9c94-5d584efa0179/element {"using":"id","value":"btnlogin"}
[MJSONWP] Calling AppiumDriver.findElement() with args: ["id","btnlogin","4720f7e6-...
[debug] [XCUITest] Executing command 'findElement'
[debug] [BaseDriver] Valid locator strategies for this request: xpath, id, name, class name, -ios predicate string, accessibility id
[debug] [BaseDriver] Waiting up to 0 ms for condition
[debug] [RemoteDebugger] Executing 'find_element' atom in default context
[debug] [RemoteDebugger] Sending javascript command (function(){return function...
[debug] [RemoteDebugger] Sending WebKit data: {"method":"Runtime.evaluate...
[debug] [RemoteDebugger] Receiving WebKit data: {"result":{"result":{"type"...
[debug] [RemoteDebugger] Found handler for message '7'
[debug] [RemoteDebugger] Received result for atom 'find_element' execution: {"ELEMENT":":wdc:1480899925835"}
[MJSONWP] Responding to client with driver.findElement() result: {"ELEMENT":"5002"}
[HTTP] <-- POST /wd/hub/session/4720f7e6-a0ec-4266-9c94-5d584efa0179/element 200 510 ms - 90
[HTTP] --> POST /wd/hub/session/4720f7e6-a0ec-4266-9c94-5d584efa0179/element/5002/click {"id":"5002"}
[MJSONWP] Calling AppiumDriver.click() with args: ["5002","4720f7e6-a0ec-4266...
[debug] [XCUITest] Executing command 'click'
[debug] [RemoteDebugger] Executing 'click' atom in default context
[debug] [RemoteDebugger] Sending javascript command (function(){return function...
[debug] [RemoteDebugger] Sending WebKit data: {"method":"Runtime.evaluate...
[debug] [RemoteDebugger] Receiving WebKit data: {"result":{"result":{"type"...
[debug] [RemoteDebugger] Found handler for message '8'
[debug] [RemoteDebugger] Received result for atom 'click' execution: null
[MJSONWP] Responding to client with driver.click() result: null
[HTTP] <-- POST /wd/hub/session/4720f7e6-a0ec-4266-9c94-5d584efa0179/element/5002/click 200 509 ms - 76
[debug] [XCUITest] Waiting for WebDriverAgent server to finish loading...
[debug] [iProxy] recv failed: Resource temporarily unavailable
[debug] [iProxy] recv failed: Operation not permitted
[debug] [XCUITest] Waiting for WebDriverAgent server to finish loading...
[BaseDriver] Shutting down because we waited 60 seconds for a command
[JSONWP Proxy] Proxying [DELETE /session/4720f7e6-a0ec-4266-9c94-5d584efa0179] to [DELETE http://localhost:8100/session/F7B067D9-9BCC-4278-80AB-1ABDF7788349] with no body
[Appium] Closing session, cause was 'New Command Timeout of 60 seconds expired. Try customizing the timeout using the 'newCommandTimeout' desired capability'
[Appium] Removing session 4720f7e6-a0ec-4266-9c94-5d584efa0179 from our master session list
[JSONWP Proxy] Got response with status 200: "{\n \"value\" : {\n\n },...
[XCUITest] Shutting down WebDriverAgent
[XCUITest] xcodebuild exited with code 'null' and signal 'SIGTERM'
[XCUITest] System log exited with code '0'
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment