Skip to content

Instantly share code, notes, and snippets.

@mare1012

mare1012/timeout Secret

Created May 29, 2017 11:41
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 mare1012/88439bc0ec88db35743898bda8cc2c83 to your computer and use it in GitHub Desktop.
Save mare1012/88439bc0ec88db35743898bda8cc2c83 to your computer and use it in GitHub Desktop.
iOS-webkit-debug-proxy disconnects
[HTTP] --> POST /wd/hub/session {"requiredCapabilities":{},"desiredCapabilities":{"deviceName":"iPhone 6s","xcodeOrgId":"XXXXXXXXXX","udid":"XXXXXXXXXXXXXXXXXXX","automationName":"XCUITest","autoWebView":true,"startIWDP":true,"clearSystemFiles":true,"browserName":"Safari","webkitResponseTimeout":50000,"newCommandTimeout":15000,"platformVersion":"10.3","safariInitialUrl":"https://web.de","platformName":"ios","xcodeSigningId":"iPhone Developer"}}
[debug] [MJSONWP] Calling AppiumDriver.createSession() with args: [{"deviceName":"iPhone 6s","xcodeOrgId":"XXXXXXXXXX","udid":"XXXXXXXXXXXXXXXXXXX","automationName":"XCUITest","autoWebView":true,"startIWDP":true,"clearSystemFiles":true,"browserName":"Safari","webkitResponseTimeout":50000,"newCommandTimeout":15000,"platformVersion":"10.3","safariInitialUrl":"https://web.de","platformName":"ios","xcodeSigningId":"iPhone Developer"},{},null]
[debug] [BaseDriver] Event 'newSessionRequested' logged at 1495454163234 (13:56:03 GMT+0200 (CEST))
[Appium] Creating new XCUITestDriver (v2.30.0) session
[Appium] Capabilities:
[Appium] deviceName: 'iPhone 6s'
[Appium] xcodeOrgId: 'XXXXXXXXXX'
[Appium] udid: 'XXXXXXXXXXXXXXXXXXX'
[Appium] automationName: 'XCUITest'
[Appium] autoWebView: true
[Appium] startIWDP: true
[Appium] clearSystemFiles: true
[Appium] browserName: 'Safari'
[Appium] webkitResponseTimeout: 50000
[Appium] newCommandTimeout: 15000
[Appium] platformVersion: '10.3'
[Appium] safariInitialUrl: 'https://web.de'
[Appium] platformName: 'ios'
[Appium] xcodeSigningId: 'iPhone Developer'
[debug] [XCUITest] XCUITestDriver version: 2.30.0
[BaseDriver] The following capabilities were provided, but are not recognized by appium: autoWebView.
[BaseDriver] Session created with session id: e5bae256-28ed-42fc-9f74-30abc8122e78
[debug] [XCUITest] Xcode version set to '8.3.2' (tools v8.3.2.0.1.1492020469)
[debug] [XCUITest] iOS SDK Version set to '10.3'
[debug] [BaseDriver] Event 'xcodeDetailsRetrieved' logged at 1495454163286 (13:56:03 GMT+0200 (CEST))
[debug] [XCUITest] Available devices: XXXXXXXXXXXXXXXXXXX
[debug] [XCUITest] Creating iDevice object with udid 'XXXXXXXXXXXXXXXXXXX'
[XCUITest] Determining device to run tests on: udid: 'XXXXXXXXXXXXXXXXXXX', real device: true
[XCUITest] Safari test requested
[debug] [BaseDriver] Event 'appConfigured' logged at 1495454163385 (13:56:03 GMT+0200 (CEST))
[debug] [BaseDriver] Event 'resetStarted' logged at 1495454163385 (13:56:03 GMT+0200 (CEST))
[debug] [XCUITest] Reset: running ios real device reset flow
[debug] [BaseDriver] Event 'resetComplete' logged at 1495454163387 (13:56:03 GMT+0200 (CEST))
[debug] [iOSLog] Attempting iOS device log capture via libimobiledevice idevicesyslog
[debug] [iOSLog] Found idevicesyslog: '/usr/local/bin/idevicesyslog'
[debug] [BaseDriver] Event 'logCaptureStarted' logged at 1495454163535 (13:56:03 GMT+0200 (CEST))
[XCUITest] Setting up real device
[debug] [BaseDriver] Event 'wdaStartAttempted' logged at 1495454163538 (13:56:03 GMT+0200 (CEST))
[XCUITest] Using WDA path: '/usr/local/lib/node_modules/appium/node_modules/appium-xcuitest-driver/WebDriverAgent'
[XCUITest] Using WDA agent: '/usr/local/lib/node_modules/appium/node_modules/appium-xcuitest-driver/WebDriverAgent/WebDriverAgent.xcodeproj'
[XCUITest] Launching WebDriverAgent on the device
[debug] [XCUITest] Carthage found: '/usr/local/bin/carthage'
[debug] [XCUITest] Killing hanging processes
[debug] [XCUITest] Generating xcode config file for orgId 'XXXXXXXXXX' and signingId 'iPhone Developer'
[debug] [XCUITest] Writing xcode config file to /var/folders/cm/mx5dgfln7k31q46xccnxsl4h0000gp/T/2017422-1605-10v09gl.veg60cc8fr/appium-temp.xcconfig
[debug] [XCUITest] Using Xcode configuration file: '/var/folders/cm/mx5dgfln7k31q46xccnxsl4h0000gp/T/2017422-1605-10v09gl.veg60cc8fr/appium-temp.xcconfig'
[debug] [XCUITest] Beginning test with command 'xcodebuild build-for-testing test-without-building -project /usr/local/lib/node_modules/appium/node_modules/appium-xcuitest-driver/WebDriverAgent/WebDriverAgent.xcodeproj -scheme WebDriverAgentRunner -destination id=XXXXXXXXXXXXXXXXXXX -configuration Debug IPHONEOS_DEPLOYMENT_TARGET=10.3 -xcconfig /var/folders/cm/mx5dgfln7k31q46xccnxsl4h0000gp/T/2017422-1605-10v09gl.veg60cc8fr/appium-temp.xcconfig' in directory '/usr/local/lib/node_modules/appium/node_modules/appium-xcuitest-driver/WebDriverAgent'
[debug] [XCUITest] Output from xcodebuild will not be logged
[debug] [XCUITest] Starting iproxy to forward traffic from local port 8100 to device port 8100 over USB
[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] Got response with status 200: "{\n \"value\" : {\n \"state\" : \"success\",\n \"os\" : {\n \"name\" : \"iOS\",\n \"version\" : \"10.3.1\"\n },\n \"ios\" : {\n \"simulatorVersion\" : \"10.3.1\",\n \"ip\" : \"10.15.224.46\"\n },\n \"build\" : {\n \"time\" : \"May 19 2017 13:26:31\"\n }\n },\n \"sessionId\" : \"41CA94A8-6A12-401E-9AF1-CFE1EBF886D0\",\n \"status\" : 0\n}"
[debug] [XCUITest] WebDriverAgent running on ip '10.15.224.46'
[debug] [XCUITest] WebDriverAgent successfully started after 586ms
[debug] [BaseDriver] Event 'wdaSessionAttempted' logged at 1495454169301 (13:56:09 GMT+0200 (CEST))
[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","https://web.de"],"shouldWaitForQuiescence":true,"shouldUseTestManagerForVisibilityDetection":false,"maxTypingFrequency":60,"shouldUseSingletonTestManager":true}}
[debug] [XCUITest] Log file for xcodebuild test: /Users/admin/Library/Developer/Xcode/DerivedData/WebDriverAgent-brdadhpuduowllgivnnvuygpwhzy/Logs/Test/6072740B-937D-450B-8EA2-C48EFB9E32E3/Session-WebDriverAgentRunner-2017-05-22_135610-lLnJ92.log
[debug] [XCUITest] Failed to create WDA session. Retrying...
[debug] [BaseDriver] Event 'wdaSessionAttempted' logged at 1495454172229 (13:56:12 GMT+0200 (CEST))
[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","https://web.de"],"shouldWaitForQuiescence":true,"shouldUseTestManagerForVisibilityDetection":false,"maxTypingFrequency":60,"shouldUseSingletonTestManager":true}}
[debug] [XCUITest] Failed to create WDA session. Retrying...
[debug] [BaseDriver] Event 'wdaSessionAttempted' logged at 1495454173243 (13:56:13 GMT+0200 (CEST))
[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","https://web.de"],"shouldWaitForQuiescence":true,"shouldUseTestManagerForVisibilityDetection":false,"maxTypingFrequency":60,"shouldUseSingletonTestManager":true}}
[debug] [XCUITest] Failed to create WDA session. Retrying...
[debug] [BaseDriver] Event 'wdaSessionAttempted' logged at 1495454174260 (13:56:14 GMT+0200 (CEST))
[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","https://web.de"],"shouldWaitForQuiescence":true,"shouldUseTestManagerForVisibilityDetection":false,"maxTypingFrequency":60,"shouldUseSingletonTestManager":true}}
[debug] [XCUITest] Failed to create WDA session. Retrying...
[debug] [BaseDriver] Event 'wdaSessionAttempted' logged at 1495454175272 (13:56:15 GMT+0200 (CEST))
[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","https://web.de"],"shouldWaitForQuiescence":true,"shouldUseTestManagerForVisibilityDetection":false,"maxTypingFrequency":60,"shouldUseSingletonTestManager":true}}
[debug] [XCUITest] Failed to create WDA session. Retrying...
[debug] [BaseDriver] Event 'wdaSessionAttempted' logged at 1495454176302 (13:56:16 GMT+0200 (CEST))
[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","https://web.de"],"shouldWaitForQuiescence":true,"shouldUseTestManagerForVisibilityDetection":false,"maxTypingFrequency":60,"shouldUseSingletonTestManager":true}}
[debug] [JSONWP Proxy] Got response with status 200: {"value":{"sessionId":"134D0DF0-7688-44F9-BDA5-EDB31783373A","capabilities":{"device":"iphone","browserName":"Safari","sdkVersion":"10.3.1","CFBundleIdentifier":"com.apple.mobilesafari"}},"sessionId":"134D0DF0-7688-44F9-BDA5-EDB31783373A","status":0}
[debug] [BaseDriver] Event 'wdaSessionStarted' logged at 1495454183415 (13:56:23 GMT+0200 (CEST))
[debug] [XCUITest] Found WDA derived data folder: '/Users/admin/Library/Developer/Xcode/DerivedData/WebDriverAgent-brdadhpuduowllgivnnvuygpwhzy'
[XCUITest] Setting '555' permissions to '/Users/admin/Library/Developer/Xcode/DerivedData/WebDriverAgent-brdadhpuduowllgivnnvuygpwhzy/Logs/Test/Attachments' folder
[debug] [BaseDriver] Event 'wdaPermsAdjusted' logged at 1495454183518 (13:56:23 GMT+0200 (CEST))
[debug] [BaseDriver] Event 'wdaStarted' logged at 1495454183519 (13:56:23 GMT+0200 (CEST))
[debug] [XCUITest] Setting initial orientation to 'PORTRAIT'
[debug] [JSONWP Proxy] Proxying [POST /orientation] to [POST http://localhost:8100/session/134D0DF0-7688-44F9-BDA5-EDB31783373A/orientation] with body: {"orientation":"PORTRAIT"}
[debug] [JSONWP Proxy] Got response with status 200: {"value":{},"sessionId":"134D0DF0-7688-44F9-BDA5-EDB31783373A","status":0}
[debug] [BaseDriver] Event 'orientationSet' logged at 1495454190138 (13:56:30 GMT+0200 (CEST))
[debug] [BaseDriver] Event 'iwdpStarting' logged at 1495454190139 (13:56:30 GMT+0200 (CEST))
[debug] [iOS] Starting ios_webkit_debug_proxy at port 27753 on device XXXXXXXXXXXXXXXXXXX
[debug] [BaseDriver] Event 'iwdpStarted' logged at 1495454190173 (13:56:30 GMT+0200 (CEST))
[debug] [XCUITest] Started ios_webkit_debug proxy server at: http://localhost:27753
[debug] [XCUITest] Waiting for initial webview
[debug] [iOS] Waiting for 3000 ms before navigating to view.
[IWDP] [ 'Unable to bind XXXXXXXXXXXXXXXXXXX on port 27753-27753' ]
[debug] [iOS] Navigating to most recently opened webview
[debug] [iOS] Retrieving contexts and views
[debug] [iOS] Selecting by url: true (expected url: 'https://web.de')
[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/47","faviconUrl":"","thumbnailUrl":"/thumb/about:blank","title":"","url":"about:blank","webSocketDebuggerUrl":"ws://localhost:27753/devtools/page/47","appId":"PID:893"},{"devtoolsFrontendUrl":"/devtools/devtools.html?ws=localhost:27753/devtools/page/48","faviconUrl":"","thumbnailUrl":"/thumb/https://web.de/","title":"E-Mail-Adresse kostenlos, FreeMail, De-Mail & Nachrichten","url":"https://web.de/","webSocketDebuggerUrl":"ws://localhost:27753/devtools/page/48","appId":"PID:891"}]
[debug] [iOS] Retrieving contexts and views
[debug] [iOS] Selecting by url: true (expected url: 'https://web.de')
[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/47","faviconUrl":"","thumbnailUrl":"/thumb/about:blank","title":"","url":"about:blank","webSocketDebuggerUrl":"ws://localhost:27753/devtools/page/47","appId":"PID:893"},{"devtoolsFrontendUrl":"/devtools/devtools.html?ws=localhost:27753/devtools/page/48","faviconUrl":"","thumbnailUrl":"/thumb/https://web.de/","title":"E-Mail-Adresse kostenlos, FreeMail, De-Mail & Nachrichten","url":"https://web.de/","webSocketDebuggerUrl":"ws://localhost:27753/devtools/page/48","appId":"PID:891"}]
[debug] [iOS] Picking webview 'WEBVIEW_47'
[debug] [iOS] Attempting to set context to 'WEBVIEW_47'
[debug] [RemoteDebugger] WebKit debugger web socket connected to url: ws://localhost:27753/devtools/page/47
[debug] [RemoteDebugger] Unregistering from page readiness notifications
[debug] [BaseDriver] Event 'initialWebviewNavigated' logged at 1495454193242 (13:56:33 GMT+0200 (CEST))
[Appium] New XCUITestDriver session created successfully, session e5bae256-28ed-42fc-9f74-30abc8122e78 added to master session list
[debug] [BaseDriver] Event 'newSessionStarted' logged at 1495454193245 (13:56:33 GMT+0200 (CEST))
[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":"iPhone 6s","xcodeOrgId":"XXXXXXXXXX","udid":"XXXXXXXXXXXXXXXXXXX","automationName":"XCUITest","autoWebView":true,"startIWDP":true,"clearSystemFiles":true,"webkitResponseTimeout":50000,"newCommandTimeout":15000,"platformVersion":"10.3","safariInitialUrl":"https://web.de","platformName":"ios","xcodeSigningId":"iPhone Developer"}
[HTTP] <-- POST /wd/hub/session 200 30035 ms - 638
[HTTP] --> POST /wd/hub/session/e5bae256-28ed-42fc-9f74-30abc8122e78/url {"url":"https://web.de","sessionId":"e5bae256-28ed-42fc-9f74-30abc8122e78"}
[debug] [MJSONWP] Calling AppiumDriver.setUrl() with args: ["https://web.de","e5bae256-28ed-42fc-9f74-30abc8122e78"]
[debug] [XCUITest] Executing command 'setUrl'
[debug] [iOS] Attempting to set url 'https://web.de'
[debug] [RemoteDebugger] Navigating to new URL: https://web.de
[debug] [RemoteDebugger] Sending WebKit data: {"method":"Page.navigate","params":{"objectGroup":"console","includeCommandLineAPI":true,"doNotPauseOnExceptionsAndMuteConsole":true,"url":"https://web.de"}}
[debug] [RemoteDebugger] Webkit response timeout: 50000
[debug] [RemoteDebugger] Receiving WebKit data: {"result":{},"id":1}
[debug] [RemoteDebugger] Found handler for message '1'
[debug] [RemoteDebugger] Received page navigated message: {"result":{},"id":1}
[debug] [RemoteDebugger] Waiting for dom...
[debug] [RemoteDebugger] Page loaded, verifying whether ready
[debug] [RemoteDebugger] Not connected to an application. Ignoring page load
[debug] [MJSONWP] Responding to client with driver.setUrl() result: null
[HTTP] <-- POST /wd/hub/session/e5bae256-28ed-42fc-9f74-30abc8122e78/url 200 1699 ms - 76
[HTTP] --> POST /wd/hub/session/e5bae256-28ed-42fc-9f74-30abc8122e78/element {"using":"xpath","sessionId":"e5bae256-28ed-42fc-9f74-30abc8122e78","value":"//button[@class='login-button']"}
[debug] [MJSONWP] Calling AppiumDriver.findElement() with args: ["xpath","//button[@class='login-button']","e5bae256-28ed-42fc-9f74-30abc8122e78"]
[debug] [XCUITest] Executing command 'findElement'
[debug] [BaseDriver] Valid locator strategies for this request: xpath, id, name, class name, -ios predicate string, -ios class chain, accessibility id
[debug] [BaseDriver] Waiting up to 0 ms for condition
[debug] [RemoteDebugger] Executing 'find_element' atom in default context
[debug] [RemoteDebugger] Sending javascript command (function(){return function(){var aa="function"...
[debug] [RemoteDebugger] Sending WebKit data: {"method":"Runtime.evaluate","params":{"objectGroup":"console","includeCommandLineAPI":true,"doNotPauseOnExceptionsAndMuteConsole":true,"expression":"(function(){return function(){var aa=\"function...
[debug] [RemoteDebugger] Webkit response timeout: 50000
[IWDP] [ 'Page 27753/0 not found' ]
[IWDP] [ 'Already sent close_frame' ]
[debug] [RemoteDebugger] WebKit remote debugger socket disconnected
[debug] [XCUITest] Connection to WDA timed out
[debug] [iProxy] recv failed: Operation not permitted
[MJSONWP] Encountered internal error running command: Error: Error while executing atom: operation timed out
at XCUITestDriver.callee$0$0$ (../../../lib/commands/web.js:79:11)
at tryCatch (/usr/local/lib/node_modules/appium/node_modules/babel-runtime/regenerator/runtime.js:67:40)
at GeneratorFunctionPrototype.invoke [as _invoke] (/usr/local/lib/node_modules/appium/node_modules/babel-runtime/regenerator/runtime.js:315:22)
at GeneratorFunctionPrototype.prototype.(anonymous function) [as throw] (/usr/local/lib/node_modules/appium/node_modules/babel-runtime/regenerator/runtime.js:100:21)
at GeneratorFunctionPrototype.invoke (/usr/local/lib/node_modules/appium/node_modules/babel-runtime/regenerator/runtime.js:136:37)
[HTTP] <-- POST /wd/hub/session/e5bae256-28ed-42fc-9f74-30abc8122e78/element 500 50022 ms - 218
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment