"Error while executing atom: operation timed out" shows in iOS Real device(iPod)
144 views
Skip to first unread message
Karthick Balasubramanian
Jul 19, 2017, 9:05:31 PM7/19/17
to webdriver
"Error while executing atom: operation timed out" exception throws after
launching the app, unable to click any element further. How to solve this
issue?
- Appium version : 1.6.5
- Real device : iPod touchbe (verison 9.3.5)
**Capabilities**
File appdir = new File ( "/Users/Gokul/Desktop" );
File app = new File ( appdir, "FlexBook.ipa" );
DesiredCapabilities capabilities = new DesiredCapabilities ( );
capabilities.setCapability( MobileCapabilityType.PLATFORM_NAME, MobilePlatform.IOS );
capabilities.setCapability( MobileCapabilityType.PLATFORM_VERSION, "9.3.5" );
capabilities.setCapability( MobileCapabilityType.DEVICE_NAME, "iPod touchbe");
capabilities.setCapability( MobileCapabilityType.AUTOMATION_NAME, "XCUITest" );
capabilities.setCapability( "safariAllowPopups", true );
capabilities.setCapability( "autoWebview", true );
capabilities.setCapability("newCommandTimeout", "60000");
capabilities.setCapability( "safariOpenLinksInBackground", true );
capabilities.setCapability( "recreateChromeDriverSessions", true );
capabilities.setCapability("nativeWebTap", true);
capabilities.setCapability ("showXcodeLog", true);
capabilities.setCapability("startIWDP", "true");
capabilities.setCapability ("webkitResponseTimeout", "70000");
capabilities.setCapability(MobileCapabilityType.UDID, "b781aba1bd650fa8a8f336d98aaa6edd1209ee2b");
capabilities.setCapability( "app", app.getAbsolutePath() );
driver = new IOSDriver( new URL( "http://127.0.0.1:4729/wd/hub" ), capabilities );
driver.manage().timeouts().implicitlyWait( 90, TimeUnit.SECONDS );
**Appium Log**
[Appium] Welcome to Appium v1.6.5
[Appium] Non-default server args:
[Appium] address: 127.0.0.1
[Appium] port: 4729
[Appium] sessionOverride: true
[Appium] nodeconfig: /Users/Gokul/Documents/selenium/iOS.json
[Appium] chromedriverExecutable: /Users/Gokul/Desktop/Chromedriver/27
[Appium] webkitDebugProxyPort: 9221
[debug] [Appium] Starting auto register thread for grid. Will try to
register every 10000 ms.
[Appium] Appium REST http interface listener started on 127.0.0.1:4729
[HTTP] --> GET /wd/hub/status {}
[debug] [MJSONWP] Calling AppiumDriver.getStatus() with args: []
[debug] [MJSONWP] Responding to client with driver.getStatus() result:
{"build":{"version":"1.6.5","revision":null}}
[HTTP] <-- GET /wd/hub/status 200 20 ms - 83
[HTTP] --> GET /wd/hub/status {}
[debug] [MJSONWP] Calling AppiumDriver.getStatus() with args: []
[debug] [MJSONWP] Responding to client with driver.getStatus() result:
{"build":{"version":"1.6.5","revision":null}}
[HTTP] <-- GET /wd/hub/status 200 13 ms - 83
[HTTP] --> GET /wd/hub/status {}
[debug] [MJSONWP] Calling AppiumDriver.getStatus() with args: []
[debug] [MJSONWP] Responding to client with driver.getStatus() result:
{"build":{"version":"1.6.5","revision":null}}
[HTTP] <-- GET /wd/hub/status 200 11 ms - 83
[HTTP] --> GET /wd/hub/status {}
[debug] [MJSONWP] Calling AppiumDriver.getStatus() with args: []
[debug] [MJSONWP] Responding to client with driver.getStatus() result:
{"build":{"version":"1.6.5","revision":null}}
[HTTP] <-- GET /wd/hub/status 200 10 ms - 83
[HTTP] --> GET /wd/hub/status {}
[debug] [MJSONWP] Calling AppiumDriver.getStatus() with args: []
[debug] [MJSONWP] Responding to client with driver.getStatus() result:
{"build":{"version":"1.6.5","revision":null}}
[HTTP] <-- GET /wd/hub/status 200 11 ms - 83
[debug] [Appium] Appium successfully registered with the grid on
127.0.0.1:4444
[HTTP] --> GET /wd/hub/status {}
[debug] [MJSONWP] Calling AppiumDriver.getStatus() with args: []
[debug] [MJSONWP] Responding to client with driver.getStatus() result:
{"build":{"version":"1.6.5","revision":null}}
[HTTP] <-- GET /wd/hub/status 200 181 ms - 83
[HTTP] --> GET /wd/hub/status {}
[debug] [MJSONWP] Calling AppiumDriver.getStatus() with args: []
[debug] [MJSONWP] Responding to client with driver.getStatus() result:
{"build":{"version":"1.6.5","revision":null}}
[HTTP] <-- GET /wd/hub/status 200 12 ms - 83
[HTTP] --> GET /wd/hub/status {}
[debug] [MJSONWP] Calling AppiumDriver.getStatus() with args: []
[debug] [MJSONWP] Responding to client with driver.getStatus() result:
{"build":{"version":"1.6.5","revision":null}}
[HTTP] <-- GET /wd/hub/status 200 30 ms - 83
[HTTP] --> GET /wd/hub/status {}
[debug] [MJSONWP] Calling AppiumDriver.getStatus() with args: []
[debug] [MJSONWP] Responding to client with driver.getStatus() result:
{"build":{"version":"1.6.5","revision":null}}
[HTTP] <-- GET /wd/hub/status 200 14 ms - 83
[HTTP] --> GET /wd/hub/status {}
[debug] [MJSONWP] Calling AppiumDriver.getStatus() with args: []
[debug] [MJSONWP] Responding to client with driver.getStatus() result:
{"build":{"version":"1.6.5","revision":null}}
[HTTP] <-- GET /wd/hub/status 200 18 ms - 83
[HTTP] --> GET /wd/hub/status {}
[debug] [MJSONWP] Calling AppiumDriver.getStatus() with args: []
[debug] [MJSONWP] Responding to client with driver.getStatus() result:
{"build":{"version":"1.6.5","revision":null}}
[HTTP] <-- GET /wd/hub/status 200 28 ms - 83
[HTTP] --> POST /wd/hub/session
{"desiredCapabilities":{"app":"/Users/Gokul/Desktop/FlexBook.ipa","recreateChromeDriverSessions":true,"webkitResponseTimeout":"70000","safariOpenLinksInBackground":true,"startIWDP":"true","deviceName":"iPod
touchbe","nativeWebTap":true,"showXcodeLog":true,"newCommandTimeout":"60000","platformVersion":"9.3.5","safariAllowPopups":true,"automationName":"XCUITest","autoWebview":true,"platformName":"iOS","udid":"b781aba1bd650fa8a8f336d98aaa6edd1209ee2b"}}
[debug] [MJSONWP] Calling AppiumDriver.createSession() with args:
[{"app":"/Users/Gokul/Desktop/FlexBook.ipa","recreateChromeDriverSessions":true,"webkitResponseTimeout":"70000","safariOpenLinksInBackground":true,"startIWDP":"true","deviceName":"iPod
touchbe","nativeWebTap":true,"showXcodeLog":true,"newCommandTimeout":"60000","platformVersion":"9.3.5","safariAllowPopups":true,"automationName":"XCUITest","autoWebview":true,"platformName":"iOS","udid":"b781aba1bd650fa8a8f336d98aaa6edd1209ee2b"},null,null]
[debug] [BaseDriver] Event 'newSessionRequested' logged at 1499952103663
(18:51:43 GMT+0530 (IST))
[Appium] Creating new XCUITestDriver (v2.30.0) session
[Appium] Capabilities:
[Appium] app: '/Users/Gokul/Desktop/FlexBook.ipa'
[Appium] recreateChromeDriverSessions: true
[Appium] webkitResponseTimeout: '70000'
[Appium] safariOpenLinksInBackground: true
[Appium] startIWDP: 'true'
[Appium] deviceName: 'iPod touchbe'
[Appium] nativeWebTap: true
[Appium] showXcodeLog: true
[Appium] newCommandTimeout: '60000'
[Appium] platformVersion: '9.3.5'
[Appium] safariAllowPopups: true
[Appium] automationName: 'XCUITest'
[Appium] autoWebview: true
[Appium] platformName: 'iOS'
[Appium] udid: 'b781aba1bd650fa8a8f336d98aaa6edd1209ee2b'
[debug] [XCUITest] XCUITestDriver version: 2.30.0
[BaseDriver] Capability 'startIWDP' changed from string to boolean. This
may cause unexpected behavior
[BaseDriver] Capability 'newCommandTimeout' changed from string ('60000')
to integer (60000). This may cause unexpected behavior
[BaseDriver] Capability 'webkitResponseTimeout' changed from string
('70000') to integer (70000). This may cause unexpected behavior
[BaseDriver] The following capabilities were provided, but are not
recognized by appium: recreateChromeDriverSessions.
[BaseDriver] Session created with session id:
78ae2181-3ac5-4080-aecf-4f2f0138fbf6
[HTTP] --> GET /wd/hub/status {}
[debug] [MJSONWP] Calling AppiumDriver.getStatus() with args: []
[debug] [XCUITest] Xcode version set to '8.3.3'
[debug] [XCUITest] iOS SDK Version set to '10.3'
[debug] [BaseDriver] Event 'xcodeDetailsRetrieved' logged at 1499952106124
(18:51:46 GMT+0530 (IST))
[debug] [XCUITest] Available devices:
b781aba1bd650fa8a8f336d98aaa6edd1209ee2b
[debug] [XCUITest] Creating iDevice object with udid
'b781aba1bd650fa8a8f336d98aaa6edd1209ee2b'
[XCUITest] Determining device to run tests on: udid:
'b781aba1bd650fa8a8f336d98aaa6edd1209ee2b', real device: true
[BaseDriver] Using local app '/Users/Gokul/Desktop/FlexBook.ipa'
[debug] [BaseDriver] Copying local zip to tmp dir
[debug] [BaseDriver] Unzipping
/var/folders/wc/cd4666f15z310d0sx6kgsbfc0000gn/T/2017613-45970-2rv0ql.7zhg833di/appium-app.zip
[debug] [BaseDriver] Testing zip archive:
/var/folders/wc/cd4666f15z310d0sx6kgsbfc0000gn/T/2017613-45970-2rv0ql.7zhg833di/appium-app.zip
[BaseDriver] Unzipped local app to
'/var/folders/wc/cd4666f15z310d0sx6kgsbfc0000gn/T/2017613-45970-2rv0ql.7zhg833di/Payload/FlexBook.app'
[debug] [BaseDriver] Event 'appConfigured' logged at 1499952108067
(18:51:48 GMT+0530 (IST))
[debug] [XCUITest] Checking whether app
'/var/folders/wc/cd4666f15z310d0sx6kgsbfc0000gn/T/2017613-45970-2rv0ql.7zhg833di/Payload/FlexBook.app'
is actually present on file system
[debug] [XCUITest] App is present
[debug] [iOS] Getting bundle ID from app
'/var/folders/wc/cd4666f15z310d0sx6kgsbfc0000gn/T/2017613-45970-2rv0ql.7zhg833di/Payload/FlexBook.app':
'org.ck12.reader'
[debug] [BaseDriver] Event 'resetStarted' logged at 1499952108078 (18:51:48
GMT+0530 (IST))
[debug] [XCUITest] Reset: running ios real device reset flow
[debug] [BaseDriver] Event 'resetComplete' logged at 1499952108080
(18:51:48 GMT+0530 (IST))
[debug] [iOSLog] Attempting iOS device log capture via libimobiledevice
idevicesyslog
[debug] [iOSLog] Found idevicesyslog: '/usr/local/bin/idevicesyslog'
[debug] [BaseDriver] Event 'logCaptureStarted' logged at 1499952108470
(18:51:48 GMT+0530 (IST))
[XCUITest] Setting up real device
[debug] [XCUITest] Reset requested. Removing app with id 'org.ck12.reader'
from the device
[debug] [XCUITest] Installing
'/var/folders/wc/cd4666f15z310d0sx6kgsbfc0000gn/T/2017613-45970-2rv0ql.7zhg833di/Payload/FlexBook.app'
on device with UUID 'b781aba1bd650fa8a8f336d98aaa6edd1209ee2b'...
[debug] [XCUITest] The app has been installed successfully.
[debug] [BaseDriver] Event 'appInstalled' logged at 1499952121168 (18:52:01
GMT+0530 (IST))
[debug] [BaseDriver] Event 'wdaStartAttempted' logged at 1499952121172
(18:52:01 GMT+0530 (IST))
[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] 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=b781aba1bd650fa8a8f336d98aaa6edd1209ee2b -configuration Debug
IPHONEOS_DEPLOYMENT_TARGET=9.3' in directory
'/usr/local/lib/node_modules/appium/node_modules/appium-xcuitest-driver/WebDriverAgent'
[debug] [XCUITest] Output from xcodebuild will be logged
[debug] [XCUITest] Starting iproxy to forward traffic from local port 8100
to device port 8100 over USB
[Xcode] Build settings from command line:
[Xcode]
[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
[Xcode] IPHONEOS_DEPLOYMENT_TARGET = 9.3
[Xcode]
[Xcode]
[debug] [JSONWP Proxy] Proxying [GET /status] to [GET
http://localhost:8100/status] with no body
[debug] [JSONWP Proxy] Proxying [GET /status] to [GET
http://localhost:8100/status] with no body
[debug] [JSONWP Proxy] Proxying [GET /status] to [GET
http://localhost:8100/status] with no body
[debug] [JSONWP Proxy] Proxying [GET /status] to [GET
http://localhost:8100/status] with no body
[debug] [JSONWP Proxy] Proxying [GET /status] to [GET
http://localhost:8100/status] with no body
[debug] [JSONWP Proxy] Proxying [GET /status] to [GET
http://localhost:8100/status] with no body
[debug] [JSONWP Proxy] Proxying [GET /status] to [GET
http://localhost:8100/status] with no body
[Xcode] === BUILD TARGET IntegrationApp OF PROJECT WebDriverAgent WITH
CONFIGURATION Debug ===
[Xcode]
[Xcode]
[Xcode] Check dependencies
[Xcode]
[debug] [JSONWP Proxy] Proxying [GET /status] to [GET
http://localhost:8100/status] with no body
[Xcode]
[Xcode]
[Xcode] Validate
/Users/Gokul/Library/Developer/Xcode/DerivedData/WebDriverAgent-brdadhpuduowllgivnnvuygpwhzy/Build/Products/Debug-iphoneos/IntegrationApp.app
[Xcode] cd
/usr/local/lib/node_modules/appium/node_modules/appium-xcuitest-driver/WebDriverAgent
[Xcode] export
PATH="/Applications/Xcode.app/Contents/Developer/Platforms/iPhoneOS.platform/Developer/usr/bin:/Applications/Xcode.app/Contents/Developer/usr/bin:/Users/Gokul/.rvm/gems/ruby-2.3.0/bin:/Users/Gokul/.rvm/gems/ruby-2.3.0@global/bin:/Users/Gokul/.rvm/rubies/ruby-2.3.0/bin:/usr/local/bin:/usr/bin:/bin:/usr/sbin:/sbin:/Library/android-sdk-macosx/platforms:/Library/android-sdk-macosx/tools:/Library/android-sdk-macosx/platform-tools:/Users/Gokul/.rvm/bin"
[Xcode] export PRODUCT_TYPE=com.apple.product-type.application
[Xcode] builtin-validationUtility
/Users/Gokul/Library/Developer/Xcode/DerivedData/WebDriverAgent-brdadhpuduowllgivnnvuygpwhzy/Build/Products/Debug-iphoneos/IntegrationApp.app
[Xcode]
[debug] [JSONWP Proxy] Proxying [GET /status] to [GET
http://localhost:8100/status] with no body
[Xcode]
[Xcode]
[Xcode] === BUILD TARGET WebDriverAgentLib OF PROJECT WebDriverAgent WITH
CONFIGURATION Debug ===
[Xcode]
[Xcode] Check dependencies
[Xcode]
[Xcode]
[Xcode] === BUILD TARGET WebDriverAgentRunner OF PROJECT WebDriverAgent
WITH CONFIGURATION Debug ===
[Xcode]
[Xcode]
[Xcode] Check dependencies
[Xcode]
[Xcode]
[Xcode]
[Xcode] ** TEST BUILD SUCCEEDED **
[Xcode]
[Xcode]
[debug] [XCUITest] Log file for xcodebuild test:
/Users/Gokul/Library/Developer/Xcode/DerivedData/WebDriverAgent-brdadhpuduowllgivnnvuygpwhzy/Logs/Test/092466DB-D74F-4997-A76F-2360E6D6C556/Session-WebDriverAgentRunner-2017-07-13_185217-ltr3lz.log
[Xcode] 2017-07-13 18:52:17.851 xcodebuild[46031:285747]
IDETestOperationsObserverDebug: Writing diagnostic log for test session to:
[Xcode]
/Users/Gokul/Library/Developer/Xcode/DerivedData/WebDriverAgent-brdadhpuduowllgivnnvuygpwhzy/Logs/Test/092466DB-D74F-4997-A76F-2360E6D6C556/Session-WebDriverAgentRunner-2017-07-13_185217-ltr3lz.log
[Xcode]
[Xcode] 2017-07-13 18:52:17.852 xcodebuild[46031:285742] [MT]
IDETestOperationsObserverDebug: (BF79A7C2-7A40-4A48-9D41-6F94CF16F1B6)
Beginning test session
WebDriverAgentRunner-BF79A7C2-7A40-4A48-9D41-6F94CF16F1B6 at 2017-07-13
18:52:17.851 with Xcode 8E3004b on target <DVTiOSDevice: 0x7f8678a38be0> {
[Xcode] deviceSerialNumber: F91LT023F4K4
[Xcode] identifier: b781aba1bd650fa8a8f336d98aaa6edd1209ee2b
[Xcode] deviceClass: iPod
[Xcode] deviceName: iPod touchbe
[Xcode] deviceIdentifier: b781aba1bd650fa8a8f336d98aaa6edd1209ee2b
[Xcode] productVersion: 9.3.5
[Xcode] buildVersion: 13G36
[Xcode] deviceSoftwareVersion: 9.3.5 (13G36)
[Xcode] deviceArchitecture: armv7f
[Xcode] deviceTotalCapacity: 29189857280
[Xcode] deviceAvailableCapacity: 22678036480
[Xcode] deviceIsTransient: NO
[Xcode] ignored: NO
[Xcode] deviceIsBusy: NO
[Xcode] deviceIsActivated: YES
[Xcode] deviceActivationState: Activated
[Xcode] isPasscodeLocked: NO
[Xcode] deviceType: <DVTDeviceType:0x7f8678634060 Xcode.DeviceType.iPhone>
[Xcode] supportedDeviceFamilies: (
[Xcode] 1
[Xcode] )
[Xcode] applications: (null)
[Xcode] provisioningProfiles: (null)
[Xcode] activityProgress: -2
[Xcode] activityTitle:
[Xcode] hasInternalSupport: NO
[Xcode] isSupportedOS: YES
[Xcode] developerDiskMountError: (null)
[Xcode] (null)
[Xcode] bootArgs: <unavailable>
[Xcode] } (9.3.5 (13G36))
[Xcode]
[debug] [JSONWP Proxy] Proxying [GET /status] to [GET
http://localhost:8100/status] with no body
[debug] [JSONWP Proxy] Proxying [GET /status] to [GET
http://localhost:8100/status] with no body
[debug] [JSONWP Proxy] Proxying [GET /status] to [GET
http://localhost:8100/status] with no body
[debug] [JSONWP Proxy] Proxying [GET /status] to [GET
http://localhost:8100/status] with no body
[Xcode] MDMCreateDeltaDirectory:1920 calling MDMDirectoryDiff with:
[Xcode] state->old_bundle:
/var/folders/wc/cd4666f15z310d0sx6kgsbfc0000gn/C/com.apple.DeveloperTools/All/Xcode/EmbeddedAppDeltas/e6afe0df9f536818603d9a334ba56815/b781aba1bd650fa8a8f336d98aaa6edd1209ee2b/WebDriverAgentRunner-Runner.app
[Xcode] state->new_bundle:
/Users/Gokul/Library/Developer/Xcode/DerivedData/WebDriverAgent-brdadhpuduowllgivnnvuygpwhzy/Build/Products/Debug-iphoneos/WebDriverAgentRunner-Runner.app
[Xcode] state->dst_bundle:
/var/folders/wc/cd4666f15z310d0sx6kgsbfc0000gn/C/com.apple.DeveloperTools/All/Xcode/EmbeddedAppDeltas/WebDriverAgentRunner-Runner.app.bk9jNT/WebDriverAgentRunner-Runner.app_sparse.ipa/Payload//WebDriverAgentRunner-Runner.app,
binaryDiff flag: FALSE
[Xcode] dst_ipa:
/var/folders/wc/cd4666f15z310d0sx6kgsbfc0000gn/C/com.apple.DeveloperTools/All/Xcode/EmbeddedAppDeltas/WebDriverAgentRunner-Runner.app.bk9jNT/WebDriverAgentRunner-Runner.app_sparse.ipa
[Xcode]
[Xcode] __MDMDirectoryDiff_block_invoke.37:1473 calling writeDictToFile
with:
/var/folders/wc/cd4666f15z310d0sx6kgsbfc0000gn/C/com.apple.DeveloperTools/All/Xcode/EmbeddedAppDeltas/WebDriverAgentRunner-Runner.app.bk9jNT/WebDriverAgentRunner-Runner.app_sparse.ipa/ManifestCache.plist
[Xcode]
[Xcode] writeDictToFile:1278 ==== Successfully wrote Manifest cache to
/var/folders/wc/cd4666f15z310d0sx6kgsbfc0000gn/C/com.apple.DeveloperTools/All/Xcode/EmbeddedAppDeltas/WebDriverAgentRunner-Runner.app.bk9jNT/WebDriverAgentRunner-Runner.app_sparse.ipa/ManifestCache.plist
[Xcode]
[debug] [JSONWP Proxy] Proxying [GET /status] to [GET
http://localhost:8100/status] with no body
[debug] [JSONWP Proxy] Proxying [GET /status] to [GET
http://localhost:8100/status] with no body
[debug] [JSONWP Proxy] Proxying [GET /status] to [GET
http://localhost:8100/status] with no body
[debug] [JSONWP Proxy] Proxying [GET /status] to [GET
http://localhost:8100/status] with no body
[debug] [JSONWP Proxy] Proxying [GET /status] to [GET
http://localhost:8100/status] with no body
[debug] [JSONWP Proxy] Proxying [GET /status] to [GET
http://localhost:8100/status] with no body
[debug] [JSONWP Proxy] Proxying [GET /status] to [GET
http://localhost:8100/status] with no body
[debug] [JSONWP Proxy] Proxying [GET /status] to [GET
http://localhost:8100/status] with no body
[debug] [JSONWP Proxy] Proxying [GET /status] to [GET
http://localhost:8100/status] with no body
[debug] [JSONWP Proxy] Proxying [GET /status] to [GET
http://localhost:8100/status] with no body
[debug] [JSONWP Proxy] Proxying [GET /status] to [GET
http://localhost:8100/status] with no body
[debug] [JSONWP Proxy] Proxying [GET /status] to [GET
http://localhost:8100/status] with no body
[debug] [JSONWP Proxy] Proxying [GET /status] to [GET
http://localhost:8100/status] with no body
[debug] [JSONWP Proxy] Proxying [GET /status] to [GET
http://localhost:8100/status] with no body
[debug] [JSONWP Proxy] Proxying [GET /status] to [GET
http://localhost:8100/status] with no body
[debug] [JSONWP Proxy] Proxying [GET /status] to [GET
http://localhost:8100/status] with no body
[debug] [JSONWP Proxy] Proxying [GET /status] to [GET
http://localhost:8100/status] with no body
[debug] [JSONWP Proxy] Proxying [GET /status] to [GET
http://localhost:8100/status] with no body
[debug] [JSONWP Proxy] Proxying [GET /status] to [GET
http://localhost:8100/status] with no body
[debug] [JSONWP Proxy] Proxying [GET /status] to [GET
http://localhost:8100/status] with no body
[debug] [JSONWP Proxy] Proxying [GET /status] to [GET
http://localhost:8100/status] with no body
[debug] [JSONWP Proxy] Proxying [GET /status] to [GET
http://localhost:8100/status] with no body
[debug] [JSONWP Proxy] Proxying [GET /status] to [GET
http://localhost:8100/status] with no body
[debug] [JSONWP Proxy] Proxying [GET /status] to [GET
http://localhost:8100/status] with no body
[debug] [JSONWP Proxy] Proxying [GET /status] to [GET
http://localhost:8100/status] with no body
[debug] [JSONWP Proxy] Got response with status 200: "{\n \"sessionId\" :
\"B91F72C4-AE11-407B-803C-2DA95CA69438\",\n \"value\" : {\n \"state\" :
\"success\",\n \"os\" : {\n \"name\" : \"iPhone OS\",\n \"version\" :
\"9.3.5\"\n },\n \"ios\" : {\n \"ip\" : \"192.168.1.34\",\n
\"simulatorVersion\" : \"9.3.5\"\n },\n \"build\" : {\n \"time\" : \"Jul 13
2017 12:25:20\"\n }\n },\n \"status\" : 0\n}"
[debug] [XCUITest] WebDriverAgent running on ip '192.168.1.34'
[debug] [XCUITest] WebDriverAgent successfully started after 25796ms
[debug] [BaseDriver] Event 'wdaSessionAttempted' logged at 1499952152209
(18:52:32 GMT+0530 (IST))
[debug] [XCUITest] Sending createSession command to WDA
[XCUITest] Forcing shouldUseSingletonTestManager capability value to true,
because of known XCTest issues under 9.3 platform version
[debug] [JSONWP Proxy] Proxying [POST /session] to [POST
http://localhost:8100/session] with body:
{"desiredCapabilities":{"bundleId":"org.ck12.reader","arguments":[],"environment":{},"shouldWaitForQuiescence":true,"shouldUseTestManagerForVisibilityDetection":true,"maxTypingFrequency":60,"shouldUseSingletonTestManager":true}}
[debug] [JSONWP Proxy] Got response with status 200:
{"sessionId":"7117BB40-3D45-497A-8014-37FF8EEF8010","value":{"sessionId":"7117BB40-3D45-497A-8014-37FF8EEF8010","capabilities":{"browserName":"FlexBook","device":"iphone","CFBundleIdentifier":"org.ck12.reader","sdkVersion":"9.3.5"}},"status":0}
[debug] [BaseDriver] Event 'wdaSessionStarted' logged at 1499952157849
(18:52:37 GMT+0530 (IST))
[debug] [XCUITest] Found WDA derived data folder:
'/Users/Gokul/Library/Developer/Xcode/DerivedData/WebDriverAgent-brdadhpuduowllgivnnvuygpwhzy'
[XCUITest] Setting '555' permissions to
'/Users/Gokul/Library/Developer/Xcode/DerivedData/WebDriverAgent-brdadhpuduowllgivnnvuygpwhzy/Logs/Test/Attachments'
folder
[debug] [BaseDriver] Event 'wdaPermsAdjusted' logged at 1499952157853
(18:52:37 GMT+0530 (IST))
[debug] [BaseDriver] Event 'wdaStarted' logged at 1499952157854 (18:52:37
GMT+0530 (IST))
[debug] [XCUITest] Setting initial orientation to 'PORTRAIT'
[debug] [JSONWP Proxy] Proxying [POST /orientation] to [POST
http://localhost:8100/session/7117BB40-3D45-497A-8014-37FF8EEF8010/orientation]
with body: {"orientation":"PORTRAIT"}
[debug] [JSONWP Proxy] Got response with status 200:
{"sessionId":"7117BB40-3D45-497A-8014-37FF8EEF8010","value":{},"status":0}
[debug] [BaseDriver] Event 'orientationSet' logged at 1499952160761
(18:52:40 GMT+0530 (IST))
[debug] [BaseDriver] Event 'iwdpStarting' logged at 1499952160762 (18:52:40
GMT+0530 (IST))
[debug] [iOS] Starting ios_webkit_debug_proxy at port 9221 on device
b781aba1bd650fa8a8f336d98aaa6edd1209ee2b
[debug] [BaseDriver] Event 'iwdpStarted' logged at 1499952160809 (18:52:40
GMT+0530 (IST))
[debug] [XCUITest] Started ios_webkit_debug proxy server at:
http://localhost:9221
[debug] [XCUITest] Waiting for initial webview
[debug] [iOS] Waiting for 3000 ms before navigating to view.
[IWDP] [ 'Unable to bind b781aba1bd650fa8a8f336d98aaa6edd1209ee2b on port
9221-9221' ]
[debug] [iOS] Navigating to most recently opened webview
[debug] [iOS] Retrieving contexts and views
[debug] [iOS] Selecting by url: false
[debug] [RemoteDebugger] Getting WebKitRemoteDebugger pageArray: localhost,
9221
[debug] [RemoteDebugger] Sending request to: http://localhost:9221/json
[debug] [RemoteDebugger] Page element JSON:
[{"devtoolsFrontendUrl":"/devtools/devtools.html?ws=localhost:9221/devtools/page/11","faviconUrl":"","thumbnailUrl":"/thumb/https://app.link/_strong_match?os=iOS&hardware_id=97E65C29-B701-4E03-AEA2-A2A6A3E16450&app_version=1.0.4&branch_key=key_live_mnvimkXTJyWAQGC2AmH8pdfhwAhhtlBh&sdk=ios0.12.3","title":"","url":"https://app.link/_strong_match?os=iOS&hardware_id=97E65C29-B701-4E03-AEA2-A2A6A3E16450&app_version=1.0.4&branch_key=key_live_mnvimkXTJyWAQGC2AmH8pdfhwAhhtlBh&sdk=ios0.12.3","webSocketDebuggerUrl":"ws://localhost:9221/devtools/page/11","appId":"PID:550"}]
[debug] [iOS] Picking webview 'WEBVIEW_11'
[debug] [iOS] Attempting to set context to 'WEBVIEW_11'
[debug] [RemoteDebugger] WebKit debugger web socket connected to url:
ws://localhost:9221/devtools/page/11
[debug] [RemoteDebugger] Unregistering from page readiness notifications
[debug] [BaseDriver] Event 'initialWebviewNavigated' logged at
1499952163847 (18:52:43 GMT+0530 (IST))
[Appium] New XCUITestDriver session created successfully, session
78ae2181-3ac5-4080-aecf-4f2f0138fbf6 added to master session list
[debug] [BaseDriver] Event 'newSessionStarted' logged at 1499952163849
(18:52:43 GMT+0530 (IST))
[debug] [MJSONWP] Responding to client with driver.createSession() result:
{"webStorageEnabled":false,"locationContextEnabled":false,"browserName":"","platform":"MAC","javascriptEnabled":true,"databaseEnabled":false,"takesScreenshot":true,"networkConnectionEnabled":false,"app":"/Users/Gokul/Desktop/FlexBook.ipa","recreateChromeDriverSessions":true,"webkitResponseTimeout":"70000","safariOpenLinksInBackground":true,"startIWDP":"true","deviceName":"iPod
touchbe","nativeWebTap":true,"showXcodeLog":true,"newCommandTimeout":"60000","platformVersion":"9.3.5","safariAllowPopups":true,"automationName":"XCUITest","autoWebview":true,"platformName":"iOS","udid":"b781aba1bd650fa8a8f336d98aaa6edd1209ee2b"}
[HTTP] <-- POST /wd/hub/session 200 60190 ms - 698
[HTTP] --> POST
/wd/hub/session/78ae2181-3ac5-4080-aecf-4f2f0138fbf6/timeouts
{"type":"implicit","ms":90000}
[debug] [MJSONWP] Calling AppiumDriver.timeouts() with args:
["implicit",90000,"78ae2181-3ac5-4080-aecf-4f2f0138fbf6"]
[debug] [XCUITest] Executing command 'timeouts'
[debug] [BaseDriver] Set implicit wait to 90000ms
[debug] [MJSONWP] Responding to client with driver.timeouts() result: null
[HTTP] <-- POST
/wd/hub/session/78ae2181-3ac5-4080-aecf-4f2f0138fbf6/timeouts 200 155 ms -
76
[debug] [MJSONWP] Responding to client with driver.getStatus() result:
{"build":{"version":"1.6.5","revision":null}}
[HTTP] <-- GET /wd/hub/status 200 60179 ms - 83
[HTTP] --> GET
/wd/hub/session/78ae2181-3ac5-4080-aecf-4f2f0138fbf6/contexts {}
[debug] [MJSONWP] Calling AppiumDriver.getContexts() with args:
["78ae2181-3ac5-4080-aecf-4f2f0138fbf6"]
[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,
9221
[debug] [RemoteDebugger] Sending request to: http://localhost:9221/json
[debug] [RemoteDebugger] Page element JSON:
[{"devtoolsFrontendUrl":"","faviconUrl":"","thumbnailUrl":"/thumb/https://app.link/_strong_match?os=iOS&hardware_id=97E65C29-B701-4E03-AEA2-A2A6A3E16450&app_version=1.0.4&branch_key=key_live_mnvimkXTJyWAQGC2AmH8pdfhwAhhtlBh&sdk=ios0.12.3","title":"","url":"https://app.link/_strong_match?os=iOS&hardware_id=97E65C29-B701-4E03-AEA2-A2A6A3E16450&app_version=1.0.4&branch_key=key_live_mnvimkXTJyWAQGC2AmH8pdfhwAhhtlBh&sdk=ios0.12.3","webSocketDebuggerUrl":"ws://localhost:9221/devtools/page/11","appId":"PID:550"}]
[debug] [MJSONWP] Responding to client with driver.getContexts() result:
["NATIVE_APP","WEBVIEW_11"]
[HTTP] <-- GET
/wd/hub/session/78ae2181-3ac5-4080-aecf-4f2f0138fbf6/contexts 200 57 ms -
99
[HTTP] --> POST
/wd/hub/session/78ae2181-3ac5-4080-aecf-4f2f0138fbf6/context
{"name":"WEBVIEW_11"}
[debug] [MJSONWP] Calling AppiumDriver.setContext() with args:
["WEBVIEW_11","78ae2181-3ac5-4080-aecf-4f2f0138fbf6"]
[debug] [XCUITest] Executing command 'setContext'
[debug] [iOS] Attempting to set context to 'WEBVIEW_11'
[debug] [RemoteDebugger] Disconnecting from WebKit remote debugger
[debug] [RemoteDebugger] WebKit remote debugger socket disconnected
[debug] [RemoteDebugger] WebKit debugger web socket connected to url:
ws://localhost:9221/devtools/page/11
[debug] [MJSONWP] Responding to client with driver.setContext() result: null
[HTTP] <-- POST
/wd/hub/session/78ae2181-3ac5-4080-aecf-4f2f0138fbf6/context 200 23 ms - 76
[HTTP] --> GET
/wd/hub/session/78ae2181-3ac5-4080-aecf-4f2f0138fbf6/contexts {}
[debug] [MJSONWP] Calling AppiumDriver.getContexts() with args:
["78ae2181-3ac5-4080-aecf-4f2f0138fbf6"]
[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,
9221
[debug] [RemoteDebugger] Sending request to: http://localhost:9221/json
[debug] [RemoteDebugger] Page element JSON:
[{"devtoolsFrontendUrl":"","faviconUrl":"","thumbnailUrl":"/thumb/https://app.link/_strong_match?os=iOS&hardware_id=97E65C29-B701-4E03-AEA2-A2A6A3E16450&app_version=1.0.4&branch_key=key_live_mnvimkXTJyWAQGC2AmH8pdfhwAhhtlBh&sdk=ios0.12.3","title":"","url":"https://app.link/_strong_match?os=iOS&hardware_id=97E65C29-B701-4E03-AEA2-A2A6A3E16450&app_version=1.0.4&branch_key=key_live_mnvimkXTJyWAQGC2AmH8pdfhwAhhtlBh&sdk=ios0.12.3","webSocketDebuggerUrl":"ws://localhost:9221/devtools/page/11","appId":"PID:550"}]
[debug] [MJSONWP] Responding to client with driver.getContexts() result:
["NATIVE_APP","WEBVIEW_11"]
[HTTP] <-- GET
/wd/hub/session/78ae2181-3ac5-4080-aecf-4f2f0138fbf6/contexts 200 11 ms -
99
[HTTP] --> POST
/wd/hub/session/78ae2181-3ac5-4080-aecf-4f2f0138fbf6/context
{"name":"WEBVIEW_11"}
[debug] [MJSONWP] Calling AppiumDriver.setContext() with args:
["WEBVIEW_11","78ae2181-3ac5-4080-aecf-4f2f0138fbf6"]
[debug] [XCUITest] Executing command 'setContext'
[debug] [iOS] Attempting to set context to 'WEBVIEW_11'
[debug] [RemoteDebugger] Disconnecting from WebKit remote debugger
[debug] [RemoteDebugger] WebKit remote debugger socket disconnected
[debug] [RemoteDebugger] WebKit debugger web socket connected to url:
ws://localhost:9221/devtools/page/11
[debug] [MJSONWP] Responding to client with driver.setContext() result: null
[HTTP] <-- POST
/wd/hub/session/78ae2181-3ac5-4080-aecf-4f2f0138fbf6/context 200 9 ms - 76
[HTTP] --> GET
/wd/hub/session/78ae2181-3ac5-4080-aecf-4f2f0138fbf6/contexts {}
[debug] [MJSONWP] Calling AppiumDriver.getContexts() with args:
["78ae2181-3ac5-4080-aecf-4f2f0138fbf6"]
[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,
9221
[debug] [RemoteDebugger] Sending request to: http://localhost:9221/json
[debug] [RemoteDebugger] Page element JSON:
[{"devtoolsFrontendUrl":"","faviconUrl":"","thumbnailUrl":"/thumb/https://app.link/_strong_match?os=iOS&hardware_id=97E65C29-B701-4E03-AEA2-A2A6A3E16450&app_version=1.0.4&branch_key=key_live_mnvimkXTJyWAQGC2AmH8pdfhwAhhtlBh&sdk=ios0.12.3","title":"","url":"https://app.link/_strong_match?os=iOS&hardware_id=97E65C29-B701-4E03-AEA2-A2A6A3E16450&app_version=1.0.4&branch_key=key_live_mnvimkXTJyWAQGC2AmH8pdfhwAhhtlBh&sdk=ios0.12.3","webSocketDebuggerUrl":"ws://localhost:9221/devtools/page/11","appId":"PID:550"}]
[debug] [MJSONWP] Responding to client with driver.getContexts() result:
["NATIVE_APP","WEBVIEW_11"]
[HTTP] <-- GET
/wd/hub/session/78ae2181-3ac5-4080-aecf-4f2f0138fbf6/contexts 200 17 ms -
99
[HTTP] --> POST
/wd/hub/session/78ae2181-3ac5-4080-aecf-4f2f0138fbf6/context
{"name":"WEBVIEW_11"}
[debug] [MJSONWP] Calling AppiumDriver.setContext() with args:
["WEBVIEW_11","78ae2181-3ac5-4080-aecf-4f2f0138fbf6"]
[debug] [XCUITest] Executing command 'setContext'
[debug] [iOS] Attempting to set context to 'WEBVIEW_11'
[debug] [RemoteDebugger] Disconnecting from WebKit remote debugger
[debug] [RemoteDebugger] WebKit remote debugger socket disconnected
[debug] [RemoteDebugger] WebKit debugger web socket connected to url:
ws://localhost:9221/devtools/page/11
[debug] [MJSONWP] Responding to client with driver.setContext() result: null
[HTTP] <-- POST
/wd/hub/session/78ae2181-3ac5-4080-aecf-4f2f0138fbf6/context 200 9 ms - 76
[HTTP] --> GET
/wd/hub/session/78ae2181-3ac5-4080-aecf-4f2f0138fbf6/contexts {}
[debug] [MJSONWP] Calling AppiumDriver.getContexts() with args:
["78ae2181-3ac5-4080-aecf-4f2f0138fbf6"]
[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,
9221
[debug] [RemoteDebugger] Sending request to: http://localhost:9221/json
[debug] [RemoteDebugger] Page element JSON:
[{"devtoolsFrontendUrl":"","faviconUrl":"","thumbnailUrl":"/thumb/https://app.link/_strong_match?os=iOS&hardware_id=97E65C29-B701-4E03-AEA2-A2A6A3E16450&app_version=1.0.4&branch_key=key_live_mnvimkXTJyWAQGC2AmH8pdfhwAhhtlBh&sdk=ios0.12.3","title":"","url":"https://app.link/_strong_match?os=iOS&hardware_id=97E65C29-B701-4E03-AEA2-A2A6A3E16450&app_version=1.0.4&branch_key=key_live_mnvimkXTJyWAQGC2AmH8pdfhwAhhtlBh&sdk=ios0.12.3","webSocketDebuggerUrl":"ws://localhost:9221/devtools/page/11","appId":"PID:550"}]
[debug] [MJSONWP] Responding to client with driver.getContexts() result:
["NATIVE_APP","WEBVIEW_11"]
[HTTP] <-- GET
/wd/hub/session/78ae2181-3ac5-4080-aecf-4f2f0138fbf6/contexts 200 15 ms -
99
[HTTP] --> POST
/wd/hub/session/78ae2181-3ac5-4080-aecf-4f2f0138fbf6/context
{"name":"WEBVIEW_11"}
[debug] [MJSONWP] Calling AppiumDriver.setContext() with args:
["WEBVIEW_11","78ae2181-3ac5-4080-aecf-4f2f0138fbf6"]
[debug] [XCUITest] Executing command 'setContext'
[debug] [iOS] Attempting to set context to 'WEBVIEW_11'
[debug] [RemoteDebugger] Disconnecting from WebKit remote debugger
[debug] [RemoteDebugger] WebKit remote debugger socket disconnected
[debug] [RemoteDebugger] WebKit debugger web socket connected to url:
ws://localhost:9221/devtools/page/11
[debug] [MJSONWP] Responding to client with driver.setContext() result: null
[HTTP] <-- POST
/wd/hub/session/78ae2181-3ac5-4080-aecf-4f2f0138fbf6/context 200 9 ms - 76
[HTTP] --> GET
/wd/hub/session/78ae2181-3ac5-4080-aecf-4f2f0138fbf6/contexts {}
[debug] [MJSONWP] Calling AppiumDriver.getContexts() with args:
["78ae2181-3ac5-4080-aecf-4f2f0138fbf6"]
[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,
9221
[debug] [RemoteDebugger] Sending request to: http://localhost:9221/json
[debug] [RemoteDebugger] Page element JSON:
[{"devtoolsFrontendUrl":"","faviconUrl":"","thumbnailUrl":"/thumb/https://app.link/_strong_match?os=iOS&hardware_id=97E65C29-B701-4E03-AEA2-A2A6A3E16450&app_version=1.0.4&branch_key=key_live_mnvimkXTJyWAQGC2AmH8pdfhwAhhtlBh&sdk=ios0.12.3","title":"","url":"https://app.link/_strong_match?os=iOS&hardware_id=97E65C29-B701-4E03-AEA2-A2A6A3E16450&app_version=1.0.4&branch_key=key_live_mnvimkXTJyWAQGC2AmH8pdfhwAhhtlBh&sdk=ios0.12.3","webSocketDebuggerUrl":"ws://localhost:9221/devtools/page/11","appId":"PID:550"}]
[debug] [MJSONWP] Responding to client with driver.getContexts() result:
["NATIVE_APP","WEBVIEW_11"]
[HTTP] <-- GET
/wd/hub/session/78ae2181-3ac5-4080-aecf-4f2f0138fbf6/contexts 200 10 ms -
99
[HTTP] --> POST
/wd/hub/session/78ae2181-3ac5-4080-aecf-4f2f0138fbf6/context
{"name":"WEBVIEW_11"}
[debug] [MJSONWP] Calling AppiumDriver.setContext() with args:
["WEBVIEW_11","78ae2181-3ac5-4080-aecf-4f2f0138fbf6"]
[debug] [XCUITest] Executing command 'setContext'
[debug] [iOS] Attempting to set context to 'WEBVIEW_11'
[debug] [RemoteDebugger] Disconnecting from WebKit remote debugger
[debug] [RemoteDebugger] WebKit remote debugger socket disconnected
[debug] [RemoteDebugger] WebKit debugger web socket connected to url:
ws://localhost:9221/devtools/page/11
[debug] [MJSONWP] Responding to client with driver.setContext() result: null
[HTTP] <-- POST
/wd/hub/session/78ae2181-3ac5-4080-aecf-4f2f0138fbf6/context 200 10 ms - 76
[HTTP] --> GET
/wd/hub/session/78ae2181-3ac5-4080-aecf-4f2f0138fbf6/contexts {}
[debug] [MJSONWP] Calling AppiumDriver.getContexts() with args:
["78ae2181-3ac5-4080-aecf-4f2f0138fbf6"]
[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,
9221
[debug] [RemoteDebugger] Sending request to: http://localhost:9221/json
[debug] [RemoteDebugger] Page element JSON:
[{"devtoolsFrontendUrl":"","faviconUrl":"","thumbnailUrl":"/thumb/https://app.link/_strong_match?os=iOS&hardware_id=97E65C29-B701-4E03-AEA2-A2A6A3E16450&app_version=1.0.4&branch_key=key_live_mnvimkXTJyWAQGC2AmH8pdfhwAhhtlBh&sdk=ios0.12.3","title":"","url":"https://app.link/_strong_match?os=iOS&hardware_id=97E65C29-B701-4E03-AEA2-A2A6A3E16450&app_version=1.0.4&branch_key=key_live_mnvimkXTJyWAQGC2AmH8pdfhwAhhtlBh&sdk=ios0.12.3","webSocketDebuggerUrl":"ws://localhost:9221/devtools/page/11","appId":"PID:550"}]
[debug] [MJSONWP] Responding to client with driver.getContexts() result:
["NATIVE_APP","WEBVIEW_11"]
[HTTP] <-- GET
/wd/hub/session/78ae2181-3ac5-4080-aecf-4f2f0138fbf6/contexts 200 13 ms -
99
[HTTP] --> POST
/wd/hub/session/78ae2181-3ac5-4080-aecf-4f2f0138fbf6/context
{"name":"WEBVIEW_11"}
[debug] [MJSONWP] Calling AppiumDriver.setContext() with args:
["WEBVIEW_11","78ae2181-3ac5-4080-aecf-4f2f0138fbf6"]
[debug] [XCUITest] Executing command 'setContext'
[debug] [iOS] Attempting to set context to 'WEBVIEW_11'
[debug] [RemoteDebugger] Disconnecting from WebKit remote debugger
[debug] [RemoteDebugger] WebKit remote debugger socket disconnected
[debug] [RemoteDebugger] WebKit debugger web socket connected to url:
ws://localhost:9221/devtools/page/11
[debug] [MJSONWP] Responding to client with driver.setContext() result: null
[HTTP] <-- POST
/wd/hub/session/78ae2181-3ac5-4080-aecf-4f2f0138fbf6/context 200 7 ms - 76
[HTTP] --> GET
/wd/hub/session/78ae2181-3ac5-4080-aecf-4f2f0138fbf6/contexts {}
[debug] [MJSONWP] Calling AppiumDriver.getContexts() with args:
["78ae2181-3ac5-4080-aecf-4f2f0138fbf6"]
[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,
9221
[debug] [RemoteDebugger] Sending request to: http://localhost:9221/json
[debug] [RemoteDebugger] Page element JSON:
[{"devtoolsFrontendUrl":"","faviconUrl":"","thumbnailUrl":"/thumb/https://app.link/_strong_match?os=iOS&hardware_id=97E65C29-B701-4E03-AEA2-A2A6A3E16450&app_version=1.0.4&branch_key=key_live_mnvimkXTJyWAQGC2AmH8pdfhwAhhtlBh&sdk=ios0.12.3","title":"","url":"https://app.link/_strong_match?os=iOS&hardware_id=97E65C29-B701-4E03-AEA2-A2A6A3E16450&app_version=1.0.4&branch_key=key_live_mnvimkXTJyWAQGC2AmH8pdfhwAhhtlBh&sdk=ios0.12.3","webSocketDebuggerUrl":"ws://localhost:9221/devtools/page/11","appId":"PID:550"}]
[debug] [MJSONWP] Responding to client with driver.getContexts() result:
["NATIVE_APP","WEBVIEW_11"]
[HTTP] <-- GET
/wd/hub/session/78ae2181-3ac5-4080-aecf-4f2f0138fbf6/contexts 200 15 ms -
99
[HTTP] --> POST
/wd/hub/session/78ae2181-3ac5-4080-aecf-4f2f0138fbf6/context
{"name":"WEBVIEW_11"}
[debug] [MJSONWP] Calling AppiumDriver.setContext() with args:
["WEBVIEW_11","78ae2181-3ac5-4080-aecf-4f2f0138fbf6"]
[debug] [XCUITest] Executing command 'setContext'
[debug] [iOS] Attempting to set context to 'WEBVIEW_11'
[debug] [RemoteDebugger] Disconnecting from WebKit remote debugger
[debug] [RemoteDebugger] WebKit remote debugger socket disconnected
[debug] [RemoteDebugger] WebKit debugger web socket connected to url:
ws://localhost:9221/devtools/page/11
[debug] [MJSONWP] Responding to client with driver.setContext() result: null
[HTTP] <-- POST
/wd/hub/session/78ae2181-3ac5-4080-aecf-4f2f0138fbf6/context 200 6 ms - 76
[HTTP] --> GET
/wd/hub/session/78ae2181-3ac5-4080-aecf-4f2f0138fbf6/contexts {}
[debug] [MJSONWP] Calling AppiumDriver.getContexts() with args:
["78ae2181-3ac5-4080-aecf-4f2f0138fbf6"]
[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,
9221
[debug] [RemoteDebugger] Sending request to: http://localhost:9221/json
[debug] [RemoteDebugger] Page element JSON:
[{"devtoolsFrontendUrl":"","faviconUrl":"","thumbnailUrl":"/thumb/https://app.link/_strong_match?os=iOS&hardware_id=97E65C29-B701-4E03-AEA2-A2A6A3E16450&app_version=1.0.4&branch_key=key_live_mnvimkXTJyWAQGC2AmH8pdfhwAhhtlBh&sdk=ios0.12.3","title":"","url":"https://app.link/_strong_match?os=iOS&hardware_id=97E65C29-B701-4E03-AEA2-A2A6A3E16450&app_version=1.0.4&branch_key=key_live_mnvimkXTJyWAQGC2AmH8pdfhwAhhtlBh&sdk=ios0.12.3","webSocketDebuggerUrl":"ws://localhost:9221/devtools/page/11","appId":"PID:550"}]
[debug] [MJSONWP] Responding to client with driver.getContexts() result:
["NATIVE_APP","WEBVIEW_11"]
[HTTP] <-- GET
/wd/hub/session/78ae2181-3ac5-4080-aecf-4f2f0138fbf6/contexts 200 9 ms - 99
[HTTP] --> POST
/wd/hub/session/78ae2181-3ac5-4080-aecf-4f2f0138fbf6/context
{"name":"WEBVIEW_11"}
[debug] [MJSONWP] Calling AppiumDriver.setContext() with args:
["WEBVIEW_11","78ae2181-3ac5-4080-aecf-4f2f0138fbf6"]
[debug] [XCUITest] Executing command 'setContext'
[debug] [iOS] Attempting to set context to 'WEBVIEW_11'
[debug] [RemoteDebugger] Disconnecting from WebKit remote debugger
[debug] [RemoteDebugger] WebKit remote debugger socket disconnected
[debug] [RemoteDebugger] WebKit debugger web socket connected to url:
ws://localhost:9221/devtools/page/11
[debug] [MJSONWP] Responding to client with driver.setContext() result: null
[HTTP] <-- POST
/wd/hub/session/78ae2181-3ac5-4080-aecf-4f2f0138fbf6/context 200 8 ms - 76
[HTTP] --> GET
/wd/hub/session/78ae2181-3ac5-4080-aecf-4f2f0138fbf6/contexts {}
[debug] [MJSONWP] Calling AppiumDriver.getContexts() with args:
["78ae2181-3ac5-4080-aecf-4f2f0138fbf6"]
[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,
9221
[debug] [RemoteDebugger] Sending request to: http://localhost:9221/json
[debug] [RemoteDebugger] Page element JSON:
[{"devtoolsFrontendUrl":"","faviconUrl":"","thumbnailUrl":"/thumb/https://app.link/_strong_match?os=iOS&hardware_id=97E65C29-B701-4E03-AEA2-A2A6A3E16450&app_version=1.0.4&branch_key=key_live_mnvimkXTJyWAQGC2AmH8pdfhwAhhtlBh&sdk=ios0.12.3","title":"","url":"https://app.link/_strong_match?os=iOS&hardware_id=97E65C29-B701-4E03-AEA2-A2A6A3E16450&app_version=1.0.4&branch_key=key_live_mnvimkXTJyWAQGC2AmH8pdfhwAhhtlBh&sdk=ios0.12.3","webSocketDebuggerUrl":"ws://localhost:9221/devtools/page/11","appId":"PID:550"}]
[debug] [MJSONWP] Responding to client with driver.getContexts() result:
["NATIVE_APP","WEBVIEW_11"]
[HTTP] <-- GET
/wd/hub/session/78ae2181-3ac5-4080-aecf-4f2f0138fbf6/contexts 200 13 ms -
99
[HTTP] --> POST
/wd/hub/session/78ae2181-3ac5-4080-aecf-4f2f0138fbf6/context
{"name":"WEBVIEW_11"}
[debug] [MJSONWP] Calling AppiumDriver.setContext() with args:
["WEBVIEW_11","78ae2181-3ac5-4080-aecf-4f2f0138fbf6"]
[debug] [XCUITest] Executing command 'setContext'
[debug] [iOS] Attempting to set context to 'WEBVIEW_11'
[debug] [RemoteDebugger] Disconnecting from WebKit remote debugger
[debug] [RemoteDebugger] WebKit remote debugger socket disconnected
[debug] [RemoteDebugger] WebKit debugger web socket connected to url:
ws://localhost:9221/devtools/page/11
[debug] [MJSONWP] Responding to client with driver.setContext() result: null
[HTTP] <-- POST
/wd/hub/session/78ae2181-3ac5-4080-aecf-4f2f0138fbf6/context 200 7 ms - 76
[HTTP] --> GET
/wd/hub/session/78ae2181-3ac5-4080-aecf-4f2f0138fbf6/contexts {}
[debug] [MJSONWP] Calling AppiumDriver.getContexts() with args:
["78ae2181-3ac5-4080-aecf-4f2f0138fbf6"]
[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,
9221
[debug] [RemoteDebugger] Sending request to: http://localhost:9221/json
[debug] [RemoteDebugger] Page element JSON:
[{"devtoolsFrontendUrl":"","faviconUrl":"","thumbnailUrl":"/thumb/https://app.link/_strong_match?os=iOS&hardware_id=97E65C29-B701-4E03-AEA2-A2A6A3E16450&app_version=1.0.4&branch_key=key_live_mnvimkXTJyWAQGC2AmH8pdfhwAhhtlBh&sdk=ios0.12.3","title":"","url":"https://app.link/_strong_match?os=iOS&hardware_id=97E65C29-B701-4E03-AEA2-A2A6A3E16450&app_version=1.0.4&branch_key=key_live_mnvimkXTJyWAQGC2AmH8pdfhwAhhtlBh&sdk=ios0.12.3","webSocketDebuggerUrl":"ws://localhost:9221/devtools/page/11","appId":"PID:550"}]
[debug] [MJSONWP] Responding to client with driver.getContexts() result:
["NATIVE_APP","WEBVIEW_11"]
[HTTP] <-- GET
/wd/hub/session/78ae2181-3ac5-4080-aecf-4f2f0138fbf6/contexts 200 12 ms -
99
[HTTP] --> POST
/wd/hub/session/78ae2181-3ac5-4080-aecf-4f2f0138fbf6/context
{"name":"WEBVIEW_11"}
[debug] [MJSONWP] Calling AppiumDriver.setContext() with args:
["WEBVIEW_11","78ae2181-3ac5-4080-aecf-4f2f0138fbf6"]
[debug] [XCUITest] Executing command 'setContext'
[debug] [iOS] Attempting to set context to 'WEBVIEW_11'
[debug] [RemoteDebugger] Disconnecting from WebKit remote debugger
[debug] [RemoteDebugger] WebKit remote debugger socket disconnected
[debug] [RemoteDebugger] WebKit debugger web socket connected to url:
ws://localhost:9221/devtools/page/11
[debug] [MJSONWP] Responding to client with driver.setContext() result: null
[HTTP] <-- POST
/wd/hub/session/78ae2181-3ac5-4080-aecf-4f2f0138fbf6/context 200 12 ms - 76
[HTTP] --> GET
/wd/hub/session/78ae2181-3ac5-4080-aecf-4f2f0138fbf6/contexts {}
[debug] [MJSONWP] Calling AppiumDriver.getContexts() with args:
["78ae2181-3ac5-4080-aecf-4f2f0138fbf6"]
[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,
9221
[debug] [RemoteDebugger] Sending request to: http://localhost:9221/json
[debug] [RemoteDebugger] Page element JSON:
[{"devtoolsFrontendUrl":"","faviconUrl":"","thumbnailUrl":"/thumb/https://app.link/_strong_match?os=iOS&hardware_id=97E65C29-B701-4E03-AEA2-A2A6A3E16450&app_version=1.0.4&branch_key=key_live_mnvimkXTJyWAQGC2AmH8pdfhwAhhtlBh&sdk=ios0.12.3","title":"","url":"https://app.link/_strong_match?os=iOS&hardware_id=97E65C29-B701-4E03-AEA2-A2A6A3E16450&app_version=1.0.4&branch_key=key_live_mnvimkXTJyWAQGC2AmH8pdfhwAhhtlBh&sdk=ios0.12.3","webSocketDebuggerUrl":"ws://localhost:9221/devtools/page/11","appId":"PID:550"}]
[debug] [MJSONWP] Responding to client with driver.getContexts() result:
["NATIVE_APP","WEBVIEW_11"]
[HTTP] <-- GET
/wd/hub/session/78ae2181-3ac5-4080-aecf-4f2f0138fbf6/contexts 200 19 ms -
99
[HTTP] --> POST
/wd/hub/session/78ae2181-3ac5-4080-aecf-4f2f0138fbf6/context
{"name":"WEBVIEW_11"}
[debug] [MJSONWP] Calling AppiumDriver.setContext() with args:
["WEBVIEW_11","78ae2181-3ac5-4080-aecf-4f2f0138fbf6"]
[debug] [XCUITest] Executing command 'setContext'
[debug] [iOS] Attempting to set context to 'WEBVIEW_11'
[debug] [RemoteDebugger] Disconnecting from WebKit remote debugger
[debug] [RemoteDebugger] WebKit remote debugger socket disconnected
[debug] [RemoteDebugger] WebKit debugger web socket connected to url:
ws://localhost:9221/devtools/page/11
[debug] [MJSONWP] Responding to client with driver.setContext() result: null
[HTTP] <-- POST
/wd/hub/session/78ae2181-3ac5-4080-aecf-4f2f0138fbf6/context 200 14 ms - 76
[HTTP] --> GET /wd/hub/session/78ae2181-3ac5-4080-aecf-4f2f0138fbf6/context
{}
[debug] [MJSONWP] Calling AppiumDriver.getCurrentContext() with args:
["78ae2181-3ac5-4080-aecf-4f2f0138fbf6"]
[debug] [XCUITest] Executing command 'getCurrentContext'
[debug] [MJSONWP] Responding to client with driver.getCurrentContext()
result: "WEBVIEW_11"
[HTTP] <-- GET /wd/hub/session/78ae2181-3ac5-4080-aecf-4f2f0138fbf6/context
200 7 ms - 84
[HTTP] --> GET
/wd/hub/session/78ae2181-3ac5-4080-aecf-4f2f0138fbf6/contexts {}
[debug] [MJSONWP] Calling AppiumDriver.getContexts() with args:
["78ae2181-3ac5-4080-aecf-4f2f0138fbf6"]
[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,
9221
[debug] [RemoteDebugger] Sending request to: http://localhost:9221/json
[debug] [RemoteDebugger] Page element JSON:
[{"devtoolsFrontendUrl":"","faviconUrl":"","thumbnailUrl":"/thumb/https://app.link/_strong_match?os=iOS&hardware_id=97E65C29-B701-4E03-AEA2-A2A6A3E16450&app_version=1.0.4&branch_key=key_live_mnvimkXTJyWAQGC2AmH8pdfhwAhhtlBh&sdk=ios0.12.3","title":"","url":"https://app.link/_strong_match?os=iOS&hardware_id=97E65C29-B701-4E03-AEA2-A2A6A3E16450&app_version=1.0.4&branch_key=key_live_mnvimkXTJyWAQGC2AmH8pdfhwAhhtlBh&sdk=ios0.12.3","webSocketDebuggerUrl":"ws://localhost:9221/devtools/page/11","appId":"PID:550"}]
[debug] [MJSONWP] Responding to client with driver.getContexts() result:
["NATIVE_APP","WEBVIEW_11"]
[HTTP] <-- GET
/wd/hub/session/78ae2181-3ac5-4080-aecf-4f2f0138fbf6/contexts 200 21 ms -
99
[HTTP] --> POST
/wd/hub/session/78ae2181-3ac5-4080-aecf-4f2f0138fbf6/context
{"name":"WEBVIEW_11"}
[debug] [MJSONWP] Calling AppiumDriver.setContext() with args:
["WEBVIEW_11","78ae2181-3ac5-4080-aecf-4f2f0138fbf6"]
[debug] [XCUITest] Executing command 'setContext'
[debug] [iOS] Attempting to set context to 'WEBVIEW_11'
[debug] [RemoteDebugger] Disconnecting from WebKit remote debugger
[debug] [RemoteDebugger] WebKit remote debugger socket disconnected
[debug] [RemoteDebugger] WebKit debugger web socket connected to url:
ws://localhost:9221/devtools/page/11
[debug] [MJSONWP] Responding to client with driver.setContext() result: null
[HTTP] <-- POST
/wd/hub/session/78ae2181-3ac5-4080-aecf-4f2f0138fbf6/context 200 26 ms - 76
[HTTP] --> GET /wd/hub/session/78ae2181-3ac5-4080-aecf-4f2f0138fbf6/context
{}
[debug] [MJSONWP] Calling AppiumDriver.getCurrentContext() with args:
["78ae2181-3ac5-4080-aecf-4f2f0138fbf6"]
[debug] [XCUITest] Executing command 'getCurrentContext'
[debug] [MJSONWP] Responding to client with driver.getCurrentContext()
result: "WEBVIEW_11"
[HTTP] <-- GET /wd/hub/session/78ae2181-3ac5-4080-aecf-4f2f0138fbf6/context
200 14 ms - 84
[HTTP] --> GET /wd/hub/status {}
[debug] [MJSONWP] Calling AppiumDriver.getStatus() with args: []
[debug] [MJSONWP] Responding to client with driver.getStatus() result:
{"build":{"version":"1.6.5","revision":null}}
[HTTP] <-- GET /wd/hub/status 200 13 ms - 83
[HTTP] --> GET /wd/hub/status {}
[debug] [MJSONWP] Calling AppiumDriver.getStatus() with args: []
[debug] [MJSONWP] Responding to client with driver.getStatus() result:
{"build":{"version":"1.6.5","revision":null}}
[HTTP] <-- GET /wd/hub/status 200 11 ms - 83
[HTTP] --> GET /wd/hub/status {}
[debug] [MJSONWP] Calling AppiumDriver.getStatus() with args: []
[debug] [MJSONWP] Responding to client with driver.getStatus() result:
{"build":{"version":"1.6.5","revision":null}}
[HTTP] <-- GET /wd/hub/status 200 88 ms - 83
[HTTP] --> GET /wd/hub/status {}
[debug] [MJSONWP] Calling AppiumDriver.getStatus() with args: []
[debug] [MJSONWP] Responding to client with driver.getStatus() result:
{"build":{"version":"1.6.5","revision":null}}
[HTTP] <-- GET /wd/hub/status 200 20 ms - 83
[HTTP] --> GET /wd/hub/status {}
[debug] [MJSONWP] Calling AppiumDriver.getStatus() with args: []
[debug] [MJSONWP] Responding to client with driver.getStatus() result:
{"build":{"version":"1.6.5","revision":null}}
[HTTP] <-- GET /wd/hub/status 200 10 ms - 83
[HTTP] --> GET /wd/hub/status {}
[debug] [MJSONWP] Calling AppiumDriver.getStatus() with args: []
[debug] [MJSONWP] Responding to client with driver.getStatus() result:
{"build":{"version":"1.6.5","revision":null}}
[HTTP] <-- GET /wd/hub/status 200 9 ms - 83
[HTTP] --> GET /wd/hub/status {}
[debug] [MJSONWP] Calling AppiumDriver.getStatus() with args: []
[debug] [MJSONWP] Responding to client with driver.getStatus() result:
{"build":{"version":"1.6.5","revision":null}}
[HTTP] <-- GET /wd/hub/status 200 9 ms - 83
[HTTP] --> GET /wd/hub/status {}
[debug] [MJSONWP] Calling AppiumDriver.getStatus() with args: []
[debug] [MJSONWP] Responding to client with driver.getStatus() result:
{"build":{"version":"1.6.5","revision":null}}
[HTTP] <-- GET /wd/hub/status 200 9 ms - 83
[HTTP] --> GET /wd/hub/status {}
[debug] [MJSONWP] Calling AppiumDriver.getStatus() with args: []
[debug] [MJSONWP] Responding to client with driver.getStatus() result:
{"build":{"version":"1.6.5","revision":null}}
[HTTP] <-- GET /wd/hub/status 200 85 ms - 83
[HTTP] --> GET /wd/hub/status {}
[debug] [MJSONWP] Calling AppiumDriver.getStatus() with args: []
[debug] [MJSONWP] Responding to client with driver.getStatus() result:
{"build":{"version":"1.6.5","revision":null}}
[HTTP] <-- GET /wd/hub/status 200 11 ms - 83
[HTTP] --> GET /wd/hub/status {}
[debug] [MJSONWP] Calling AppiumDriver.getStatus() with args: []
[debug] [MJSONWP] Responding to client with driver.getStatus() result:
{"build":{"version":"1.6.5","revision":null}}
[HTTP] <-- GET /wd/hub/status 200 12 ms - 83
[HTTP] --> GET /wd/hub/status {}
[debug] [MJSONWP] Calling AppiumDriver.getStatus() with args: []
[debug] [MJSONWP] Responding to client with driver.getStatus() result:
{"build":{"version":"1.6.5","revision":null}}
[HTTP] <-- GET /wd/hub/status 200 14 ms - 83
[HTTP] --> GET /wd/hub/status {}
[debug] [MJSONWP] Calling AppiumDriver.getStatus() with args: []
[debug] [MJSONWP] Responding to client with driver.getStatus() result:
{"build":{"version":"1.6.5","revision":null}}
[HTTP] <-- GET /wd/hub/status 200 14 ms - 83
[debug] [XCUITest] Connection to WDA timed out
[debug] [iProxy] recv failed: Operation not permitted
[HTTP] --> POST
/wd/hub/session/78ae2181-3ac5-4080-aecf-4f2f0138fbf6/element {"using":"css
selector","value":".firstPage-msg"}
[debug] [MJSONWP] Calling AppiumDriver.findElement() with args: ["css
selector",".firstPage-msg","78ae2181-3ac5-4080-aecf-4f2f0138fbf6"]
[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 90000 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: 70000
[debug] [RemoteDebugger] WebKit remote debugger socket disconnected
[HTTP] --> GET /wd/hub/status {}
[debug] [MJSONWP] Calling AppiumDriver.getStatus() with args: []
[debug] [MJSONWP] Responding to client with driver.getStatus() result:
{"build":{"version":"1.6.5","revision":null}}
[HTTP] <-- GET /wd/hub/status 200 14 ms - 83
[HTTP] --> GET /wd/hub/status {}
[debug] [MJSONWP] Calling AppiumDriver.getStatus() with args: []
[debug] [MJSONWP] Responding to client with driver.getStatus() result:
{"build":{"version":"1.6.5","revision":null}}
[HTTP] <-- GET /wd/hub/status 200 14 ms - 83
[HTTP] --> GET /wd/hub/status {}
[debug] [MJSONWP] Calling AppiumDriver.getStatus() with args: []
[debug] [MJSONWP] Responding to client with driver.getStatus() result:
{"build":{"version":"1.6.5","revision":null}}
[HTTP] <-- GET /wd/hub/status 200 11 ms - 83
[HTTP] --> GET /wd/hub/status {}
[debug] [MJSONWP] Calling AppiumDriver.getStatus() with args: []
[debug] [MJSONWP] Responding to client with driver.getStatus() result:
{"build":{"version":"1.6.5","revision":null}}
[HTTP] <-- GET /wd/hub/status 200 65 ms - 83
[HTTP] --> GET /wd/hub/status {}
[debug] [MJSONWP] Calling AppiumDriver.getStatus() with args: []
[debug] [MJSONWP] Responding to client with driver.getStatus() result:
{"build":{"version":"1.6.5","revision":null}}
[HTTP] <-- GET /wd/hub/status 200 18 ms - 83
[HTTP] --> GET /wd/hub/status {}
[debug] [MJSONWP] Calling AppiumDriver.getStatus() with args: []
[debug] [MJSONWP] Responding to client with driver.getStatus() result:
{"build":{"version":"1.6.5","revision":null}}
[HTTP] <-- GET /wd/hub/status 200 12 ms - 83
[HTTP] --> GET /wd/hub/status {}
[debug] [MJSONWP] Calling AppiumDriver.getStatus() with args: []
[debug] [MJSONWP] Responding to client with driver.getStatus() result:
{"build":{"version":"1.6.5","revision":null}}
[HTTP] <-- GET /wd/hub/status 200 12 ms - 83
[HTTP] --> GET /wd/hub/status {}
[debug] [MJSONWP] Calling AppiumDriver.getStatus() with args: []
[debug] [MJSONWP] Responding to client with driver.getStatus() result:
{"build":{"version":"1.6.5","revision":null}}
[HTTP] <-- GET /wd/hub/status 200 18 ms - 83
[HTTP] --> GET /wd/hub/status {}
[debug] [MJSONWP] Calling AppiumDriver.getStatus() with args: []
[debug] [MJSONWP] Responding to client with driver.getStatus() result:
{"build":{"version":"1.6.5","revision":null}}
[HTTP] <-- GET /wd/hub/status 200 13 ms - 83
[HTTP] --> GET /wd/hub/status {}
[debug] [MJSONWP] Calling AppiumDriver.getStatus() with args: []
[debug] [MJSONWP] Responding to client with driver.getStatus() result:
{"build":{"version":"1.6.5","revision":null}}
[HTTP] <-- GET /wd/hub/status 200 12 ms - 83
[HTTP] --> GET /wd/hub/status {}
[debug] [MJSONWP] Calling AppiumDriver.getStatus() with args: []
[debug] [MJSONWP] Responding to client with driver.getStatus() result:
{"build":{"version":"1.6.5","revision":null}}
[HTTP] <-- GET /wd/hub/status 200 12 ms - 83
[HTTP] --> GET /wd/hub/status {}
[debug] [MJSONWP] Calling AppiumDriver.getStatus() with args: []
[debug] [MJSONWP] Responding to client with driver.getStatus() result:
{"build":{"version":"1.6.5","revision":null}}
[HTTP] <-- GET /wd/hub/status 200 13 ms - 83
[HTTP] --> GET /wd/hub/status {}
[debug] [MJSONWP] Calling AppiumDriver.getStatus() with args: []
[debug] [MJSONWP] Responding to client with driver.getStatus() result:
{"build":{"version":"1.6.5","revision":null}}
[HTTP] <-- GET /wd/hub/status 200 16 ms - 83
[HTTP] --> GET /wd/hub/status {}
[debug] [MJSONWP] Calling AppiumDriver.getStatus() with args: []
[debug] [MJSONWP] Responding to client with driver.getStatus() result:
{"build":{"version":"1.6.5","revision":null}}
[HTTP] <-- GET /wd/hub/status 200 14 ms - 83
[HTTP] --> GET /wd/hub/status {}
[debug] [MJSONWP] Calling AppiumDriver.getStatus() with args: []
[debug] [MJSONWP] Responding to client with driver.getStatus() result:
{"build":{"version":"1.6.5","revision":null}}
[HTTP] <-- GET /wd/hub/status 200 12 ms - 83
[HTTP] --> GET /wd/hub/status {}
[debug] [MJSONWP] Calling AppiumDriver.getStatus() with args: []
[debug] [MJSONWP] Responding to client with driver.getStatus() result:
{"build":{"version":"1.6.5","revision":null}}
[HTTP] <-- GET /wd/hub/status 200 9 ms - 83
[HTTP] --> GET /wd/hub/status {}
[debug] [MJSONWP] Calling AppiumDriver.getStatus() with args: []
[debug] [MJSONWP] Responding to client with driver.getStatus() result:
{"build":{"version":"1.6.5","revision":null}}
[HTTP] <-- GET /wd/hub/status 200 9 ms - 83
[HTTP] --> GET /wd/hub/status {}
[debug] [MJSONWP] Calling AppiumDriver.getStatus() with args: []
[debug] [MJSONWP] Responding to client with driver.getStatus() result:
{"build":{"version":"1.6.5","revision":null}}
[HTTP] <-- GET /wd/hub/status 200 9 ms - 83
[HTTP] --> GET /wd/hub/status {}
[debug] [MJSONWP] Calling AppiumDriver.getStatus() with args: []
[debug] [MJSONWP] Responding to client with driver.getStatus() result:
{"build":{"version":"1.6.5","revision":null}}
[HTTP] <-- GET /wd/hub/status 200 11 ms - 83
[HTTP] --> GET /wd/hub/status {}
[debug] [MJSONWP] Calling AppiumDriver.getStatus() with args: []
[debug] [MJSONWP] Responding to client with driver.getStatus() result:
{"build":{"version":"1.6.5","revision":null}}
[HTTP] <-- GET /wd/hub/status 200 10 ms - 83
[HTTP] --> GET /wd/hub/status {}
[debug] [MJSONWP] Calling AppiumDriver.getStatus() with args: []
[debug] [MJSONWP] Responding to client with driver.getStatus() result:
{"build":{"version":"1.6.5","revision":null}}
[HTTP] <-- GET /wd/hub/status 200 10 ms - 83
[HTTP] --> GET /wd/hub/status {}
[debug] [MJSONWP] Calling AppiumDriver.getStatus() with args: []
[debug] [MJSONWP] Responding to client with driver.getStatus() result:
{"build":{"version":"1.6.5","revision":null}}
[HTTP] <-- GET /wd/hub/status 200 9 ms - 83
[HTTP] --> GET /wd/hub/status {}
[debug] [MJSONWP] Calling AppiumDriver.getStatus() with args: []
[debug] [MJSONWP] Responding to client with driver.getStatus() result:
{"build":{"version":"1.6.5","revision":null}}
[HTTP] <-- GET /wd/hub/status 200 11 ms - 83
[HTTP] --> GET /wd/hub/status {}
[debug] [MJSONWP] Calling AppiumDriver.getStatus() with args: []
[debug] [MJSONWP] Responding to client with driver.getStatus() result:
{"build":{"version":"1.6.5","revision":null}}
[HTTP] <-- GET /wd/hub/status 200 8 ms - 83
[HTTP] --> GET /wd/hub/status {}
[debug] [MJSONWP] Calling AppiumDriver.getStatus() with args: []
[debug] [MJSONWP] Responding to client with driver.getStatus() result:
{"build":{"version":"1.6.5","revision":null}}
[HTTP] <-- GET /wd/hub/status 200 8 ms - 83
[HTTP] --> GET /wd/hub/status {}
[debug] [MJSONWP] Calling AppiumDriver.getStatus() with args: []
[debug] [MJSONWP] Responding to client with driver.getStatus() result:
{"build":{"version":"1.6.5","revision":null}}
[HTTP] <-- GET /wd/hub/status 200 9 ms - 83
[HTTP] --> GET /wd/hub/status {}
[debug] [MJSONWP] Calling AppiumDriver.getStatus() with args: []
[debug] [MJSONWP] Responding to client with driver.getStatus() result:
{"build":{"version":"1.6.5","revision":null}}
[HTTP] <-- GET /wd/hub/status 200 9 ms - 83
[HTTP] --> GET /wd/hub/status {}
[debug] [MJSONWP] Calling AppiumDriver.getStatus() with args: []
[debug] [MJSONWP] Responding to client with driver.getStatus() result:
{"build":{"version":"1.6.5","revision":null}}
[HTTP] <-- GET /wd/hub/status 200 9 ms - 83
[HTTP] --> GET /wd/hub/status {}
[debug] [MJSONWP] Calling AppiumDriver.getStatus() with args: []
[debug] [MJSONWP] Responding to client with driver.getStatus() result:
{"build":{"version":"1.6.5","revision":null}}
[HTTP] <-- GET /wd/hub/status 200 47 ms - 83
[HTTP] --> GET /wd/hub/status {}
[debug] [MJSONWP] Calling AppiumDriver.getStatus() with args: []
[debug] [MJSONWP] Responding to client with driver.getStatus() result:
{"build":{"version":"1.6.5","revision":null}}
[HTTP] <-- GET /wd/hub/status 200 10 ms - 83
[HTTP] --> GET /wd/hub/status {}
[debug] [MJSONWP] Calling AppiumDriver.getStatus() with args: []
[debug] [MJSONWP] Responding to client with driver.getStatus() result:
{"build":{"version":"1.6.5","revision":null}}
[HTTP] <-- GET /wd/hub/status 200 9 ms - 83
[HTTP] --> GET /wd/hub/status {}
[debug] [MJSONWP] Calling AppiumDriver.getStatus() with args: []
[debug] [MJSONWP] Responding to client with driver.getStatus() result:
{"build":{"version":"1.6.5","revision":null}}
[HTTP] <-- GET /wd/hub/status 200 11 ms - 83
[HTTP] --> GET /wd/hub/status {}
[debug] [MJSONWP] Calling AppiumDriver.getStatus() with args: []
[debug] [MJSONWP] Responding to client with driver.getStatus() result:
{"build":{"version":"1.6.5","revision":null}}
[HTTP] <-- GET /wd/hub/status 200 9 ms - 83
[HTTP] --> GET /wd/hub/status {}
[debug] [MJSONWP] Calling AppiumDriver.getStatus() with args: []
[debug] [MJSONWP] Responding to client with driver.getStatus() result:
{"build":{"version":"1.6.5","revision":null}}
[HTTP] <-- GET /wd/hub/status 200 9 ms - 83
[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/78ae2181-3ac5-4080-aecf-4f2f0138fbf6/element 500 70110 ms -
218
[HTTP] --> GET /wd/hub/status {}
[debug] [MJSONWP] Calling AppiumDriver.getStatus() with args: []
[debug] [MJSONWP] Responding to client with driver.getStatus() result:
{"build":{"version":"1.6.5","revision":null}}
[HTTP] <-- GET /wd/hub/status 200 10 ms - 83
[HTTP] --> GET /wd/hub/status {}
[debug] [MJSONWP] Calling AppiumDriver.getStatus() with args: []
[debug] [MJSONWP] Responding to client with driver.getStatus() result:
{"build":{"version":"1.6.5","revision":null}}
[HTTP] <-- GET /wd/hub/status 200 9 ms - 83
^C[Appium] Received SIGINT - shutting down
[Xcode] ** BUILD INTERRUPTED **
[Xcode]
[debug] [XCUITest] iproxy exited with code 'null'
[debug] [iOS] Starting ios_webkit_debug_proxy at port 9221 on device
b781aba1bd650fa8a8f336d98aaa6edd1209ee2b
[XCUITest] xcodebuild exited with code 'null' and signal 'SIGINT'
[Xcode] Contents of xcodebuild log file
'/Users/Gokul/Library/Developer/Xcode/DerivedData/WebDriverAgent-brdadhpuduowllgivnnvuygpwhzy/Logs/Test/092466DB-D74F-4997-A76F-2360E6D6C556/Session-WebDriverAgentRunner-2017-07-13_185217-ltr3lz.log':
[IWDP] [ 'Unable to bind b781aba1bd650fa8a8f336d98aaa6edd1209ee2b on port
9221-9221' ]
[Xcode] 18:52:17.852 xcodebuild[46031:285742] Beginning test session
WebDriverAgentRunner-BF79A7C2-7A40-4A48-9D41-6F94CF16F1B6 at 2017-07-13
18:52:17.851 with Xcode 8E3004b on target <DVTiOSDevice: 0x7f8678a38be0> {
[Xcode] deviceSerialNumber: F91LT023F4K4
[Xcode] identifier: b781aba1bd650fa8a8f336d98aaa6edd1209ee2b
[Xcode] deviceClass: iPod
[Xcode] deviceName: iPod touchbe
[Xcode] deviceIdentifier: b781aba1bd650fa8a8f336d98aaa6edd1209ee2b
[Xcode] productVersion: 9.3.5
[Xcode] buildVersion: 13G36
[Xcode] deviceSoftwareVersion: 9.3.5 (13G36)
[Xcode] deviceArchitecture: armv7f
[Xcode] deviceTotalCapacity: 29189857280
[Xcode] deviceAvailableCapacity: 22678036480
[Xcode] deviceIsTransient: NO
[Xcode] ignored: NO
[Xcode] deviceIsBusy: NO
[Xcode] deviceIsActivated: YES
[Xcode] deviceActivationState: Activated
[Xcode] isPasscodeLocked: NO
[Xcode] deviceType: <DVTDeviceType:0x7f8678634060 Xcode.DeviceType.iPhone>
[Xcode] supportedDeviceFamilies: (
[Xcode] 1
[Xcode] )
[Xcode] applications: (null)
[Xcode] provisioningProfiles: (null)
[Xcode] activityProgress: -2
[Xcode] activityTitle:
[Xcode] hasInternalSupport: NO
[Xcode] isSupportedOS: YES
[Xcode] developerDiskMountError: (null)
[Xcode] (null)
[Xcode] bootArgs: <unavailable>
[Xcode] } (9.3.5 (13G36))
[Xcode] 18:52:17.853 xcodebuild[46031:285742]
/Applications/Xcode.app/Contents/Developer/usr/bin/xcodebuild
[Xcode] build-for-testing
[Xcode] test-without-building
[Xcode] -project
[Xcode]
/usr/local/lib/node_modules/appium/node_modules/appium-xcuitest-driver/WebDriverAgent/WebDriverAgent.xcodeproj
[Xcode] -scheme
[Xcode] WebDriverAgentRunner
[Xcode] -destination
[Xcode] id=b781aba1bd650fa8a8f336d98aaa6edd1209ee2b
[Xcode] -configuration
[Xcode] Debug
[Xcode] IPHONEOS_DEPLOYMENT_TARGET=9.3
[Xcode] 18:52:17.853 xcodebuild[46031:285742] Launching with
Xcode.IDEFoundation.Launcher.PosixSpawn
[Xcode] 18:52:17.864 xcodebuild[46031:285812] Connecting to mobile device:
b781aba1bd650fa8a8f336d98aaa6edd1209ee2b
[Xcode] 18:52:17.869 xcodebuild[46031:285812] Starting session with mobile
device
[Xcode] 18:52:17.917 xcodebuild[46031:285742] Launch session started.
[Xcode] 18:52:18.202 xcodebuild[46031:285812] Starting test manager service
on mobile device.
[Xcode] 18:52:18.230 xcodebuild[46031:285865] Received connection for test
runner session
[Xcode] 18:52:18.230 xcodebuild[46031:285865] Listening for proxy
connection request from the test bundle
[Xcode] 18:52:18.230 xcodebuild[46031:285742] Test runner session acquired
connection.
[Xcode] 18:52:18.231 xcodebuild[46031:285865] Initiating session with
identifier: BF79A7C2-7A40-4A48-9D41-6F94CF16F1B6
[Xcode] 18:52:18.231 xcodebuild[46031:285742] Waiting for test process to
launch.
[Xcode] 18:52:18.385 xcodebuild[46031:285865] Got reply to session
initiation request with identifier BF79A7C2-7A40-4A48-9D41-6F94CF16F1B6
(result:error): 16: (null)
[Xcode] 18:52:19.029 xcodebuild[46031:285866] Installing
com.apple.test.WebDriverAgentRunner-Runner using MobileInstall
[Xcode] 18:52:19.419 xcodebuild[46031:285745] app uninstall set - {(
[Xcode] )}
[Xcode] 18:52:19.419 xcodebuild[46031:285745] app install set - {(
[Xcode]
<DVTFilePath:0x7f8675edc450:'/Users/Gokul/Library/Developer/Xcode/DerivedData/WebDriverAgent-brdadhpuduowllgivnnvuygpwhzy/Build/Products/Debug-iphoneos/WebDriverAgentRunner-Runner.app'>
[Xcode] )}
[Xcode] 18:52:25.599 xcodebuild[46031:285866] remoteExecutable
setInstalledPath:/private/var/containers/Bundle/Application/602F6F66-C9E9-4B6D-8576-0BF7965FC517/WebDriverAgentRunner-Runner.app
[Xcode]
[Xcode] 18:52:25.599 xcodebuild[46031:285866] Configuring for testing on
device...
[Xcode] 18:52:25.600 xcodebuild[46031:285866] Updating test configuration
with device paths.
[Xcode] 18:52:25.772 xcodebuild[46031:285866] Updated test configuration at
/var/folders/wc/cd4666f15z310d0sx6kgsbfc0000gn/T/com.apple.dt.XCTest/BF79A7C2-7A40-4A48-9D41-6F94CF16F1B6/remote-container/tmp/WebDriverAgentRunner-BF79A7C2-7A40-4A48-9D41-6F94CF16F1B6.xctestconfiguration
with device paths: <XCTestConfiguration: 0x7f86789faf90>
[Xcode]
testBundleURL:file:///private/var/containers/Bundle/Application/602F6F66-C9E9-4B6D-8576-0BF7965FC517/WebDriverAgentRunner-Runner.app/PlugIns/WebDriverAgentRunner.xctest
[Xcode] testBundleRelativePath:(null)
[Xcode] productModuleName:WebDriverAgentRunner
[Xcode] testsToSkip:(null)
[Xcode] testsToRun:(null)
[Xcode] reportResultsToIDE:YES
[Xcode] sessionIdentifier:BF79A7C2-7A40-4A48-9D41-6F94CF16F1B6
[Xcode] pathToXcodeReportingSocket:(null)
[Xcode] disablePerformanceMetrics:no
[Xcode] treatMissingBaselinesAsFailures:no
[Xcode] baselineFileURL:(null)
[Xcode] baselineFileRelativePath:(null)
[Xcode]
targetApplicationPath:/Users/Gokul/Library/Developer/Xcode/DerivedData/WebDriverAgent-brdadhpuduowllgivnnvuygpwhzy/Build/Products/Debug-iphoneos/IntegrationApp.app
[Xcode] targetApplicationBundleID:org.ck12.reader
[Xcode] targetApplicationArguments:(null)
[Xcode] targetApplicationEnvironment:
[Xcode] (null)
[Xcode] reportActivities:YES
[Xcode] testsMustRunOnMainThread:YES
[Xcode] initializeForUITesting:YES
[Xcode] aggregateStatisticsBeforeCrash:{
[Xcode] XCSuiteRecordsKey = {
[Xcode] };
[Xcode] }
[Xcode]
automationFrameworkPath:/Developer/Library/PrivateFrameworks/XCTAutomationSupport.framework
[Xcode] emitOSLogs:no
[Xcode]
[Xcode] 18:52:25.772 xcodebuild[46031:285866] Uploading test configuration
to app container.
[Xcode] 18:52:26.813 xcodebuild[46031:285866] Updating test process
environment variables.
[Xcode] 18:52:26.814 xcodebuild[46031:285866] Updated testing environment
variables {
[Xcode] "DYLD_FRAMEWORK_PATH" =
"/private/var/containers/Bundle/Application/602F6F66-C9E9-4B6D-8576-0BF7965FC517/WebDriverAgentRunner-Runner.app/Frameworks:/Users/Gokul/Library/Developer/Xcode/DerivedData/WebDriverAgent-brdadhpuduowllgivnnvuygpwhzy/Build/Products/Debug-iphoneos:__PLATFORMS__/iPhoneOS.platform/Developer/Library/Frameworks:/Developer/Library/PrivateFrameworks";
[Xcode] "DYLD_LIBRARY_PATH" =
"/private/var/containers/Bundle/Application/602F6F66-C9E9-4B6D-8576-0BF7965FC517/WebDriverAgentRunner-Runner.app/Frameworks:/Users/Gokul/Library/Developer/Xcode/DerivedData/WebDriverAgent-brdadhpuduowllgivnnvuygpwhzy/Build/Products/Debug-iphoneos:__PLATFORMS__/iPhoneOS.platform/Developer/Library/Frameworks:/Developer/Library/PrivateFrameworks";
[Xcode] "XCODE_DBG_XPC_EXCLUSIONS" = "com.apple.dt.xctestSymbolicator";
[Xcode] XCTestConfigurationFilePath =
"/private/var/mobile/Containers/Data/Application/0C5423EF-8AC6-43B9-877E-60B3B6E19828/tmp/WebDriverAgentRunner-BF79A7C2-7A40-4A48-9D41-6F94CF16F1B6.xctestconfiguration";
[Xcode] }
[Xcode] 18:52:27.461 xcodebuild[46031:285742] Adding console adaptor
<IDEConsoleAdaptor: 0x7f8678fb86b0 'target'> for test session
BF79A7C2-7A40-4A48-9D41-6F94CF16F1B6.
[Xcode] 18:52:27.698 xcodebuild[46031:285742] Test process runnable PID is
548.
[Xcode] 18:52:28.878 xcodebuild[46031:285864] Handling proxy channel
request from test runner
[Xcode] 18:52:28.878 xcodebuild[46031:285864] Test runner is ready, running
protocol 22, requires at least version 8. Control framework is running 22
and requires at least 8
[Xcode] 18:52:28.878 xcodebuild[46031:285742] Test runner session
successfully initiated.
[Xcode] 18:52:28.878 xcodebuild[46031:285742] Creating the daemon control
session.
[Xcode] 18:52:28.879 xcodebuild[46031:285742] Whitelisting test process ID
548
[Xcode] 18:52:28.879 xcodebuild[46031:285742] Got authorization request for
pid 548
[Xcode] 18:52:29.220 xcodebuild[46031:285747] Requesting authorization for
pid 548
[Xcode] 18:52:18.183 testmanagerd[546:72682] Launched testmanagerd from
/Developer/usr/libexec/testmanagerd, built at Saturday, 20 February 2016 at
6:20:51 AM India Standard Time
[Xcode] 18:52:18.186 testmanagerd[546:72682] Setting availability state.
[Xcode] 18:52:18.188 testmanagerd[546:72682] Posting availability to test
bundles...
[Xcode] 18:52:18.193 testmanagerd[546:72703] IDE connected via lockdown
over socket 5 (__54-[XCTestManagerIDEServer
initWithLockdownServiceName:]_block_invoke)
[Xcode] 18:52:18.193 testmanagerd[546:72703] Created session with socket 5
[Xcode] 18:52:18.212 testmanagerd[546:72703] Handled a proxy request from
the IDE with session <XCIDESession: 0x1660ab20> proxy <_DTXProxy:
0x1660dfb0>
[Xcode] 18:52:18.214 testmanagerd[546:72682] __84-[XCIDESession
_IDE_initiateSessionWithIdentifier:forClient:atPath:protocolVersion:]_block_invoke
<__NSConcreteUUID 0x1660f2d0> BF79A7C2-7A40-4A48-9D41-6F94CF16F1B6 1 5 from
client 080B3BD7-1036-4AA3-8BBD-B8C4E825B3F0-46031-00001AF43E65652A
(/Applications/Xcode.app/Contents/Developer/usr/bin/xcodebuild)
[Xcode] 18:52:18.215 testmanagerd[546:72703] __45-[XCTestManager
setIDESession:forIdentifier:]_block_invoke <XCIDESession: 0x1660ab20> ->
<__NSConcreteUUID 0x1660f2d0> BF79A7C2-7A40-4A48-9D41-6F94CF16F1B6
[Xcode] 18:52:18.215 testmanagerd[546:72703] -[XCTestManager
_handleRequestForSessionWithIdentifier:]
[Xcode] 18:52:23.194 testmanagerd[546:72682] Considering exiting, 0 test
sessions, 1 sessions with identifiers, 0 unidentified sessions...
[Xcode] 18:52:28.519 testmanagerd[546:72938] Received new test session
connection (-[XCTestManager listener:shouldAcceptNewConnection:])
[Xcode] 18:52:28.531 testmanagerd[546:72682] Initial orientation 1
[Xcode] 18:52:28.585 testmanagerd[546:72940] Test session <__NSConcreteUUID
0x16615340> BF79A7C2-7A40-4A48-9D41-6F94CF16F1B6 requested socket
(-[XCTestSession _XCT_requestSocketForSessionIdentifier:reply:])
[Xcode] 18:52:28.586 testmanagerd[546:72940] Test session <__NSConcreteUUID
0x16615340> BF79A7C2-7A40-4A48-9D41-6F94CF16F1B6 requested socket
(__57-[XCTestManager requestSocketForSessionIdentifier:reply:]_block_invoke)
[Xcode] 18:52:28.587 testmanagerd[546:72940] -[XCTestManager
_handleRequestForSessionWithIdentifier:]
[Xcode] 18:52:28.587 testmanagerd[546:72940] Closing IDE connection before
sending socket 6 to test host.
[Xcode] 18:52:28.587 testmanagerd[546:72940] -[XCIDESession
closeIDEConnection:] sending barrier
[Xcode] 18:52:28.588 testmanagerd[546:72682] barrier flushed, closing
DTXConnection to IDE, socket will not be closed
[Xcode] 18:52:28.593 testmanagerd[546:72698] Socket disconnected
[Xcode] 18:52:28.594 testmanagerd[546:72698] Disconnecting lockdown socket
[Xcode] 18:52:28.594 testmanagerd[546:72941] __33-[XCTestManager
closeIDESession:]_block_invoke <XCIDESession: 0x1660ab20>
[Xcode] 18:52:28.595 testmanagerd[546:72941] Considering exiting, 1 test
sessions, 0 sessions with identifiers, 0 unidentified sessions...
[Xcode] 18:52:28.600 testmanagerd[546:72682] BKSAccelerometer reported
orientation update: 5
[Xcode] 18:52:28.695 testmanagerd[546:72682] Sending socket 6 to test host
[Xcode] 18:52:28.711 testmanagerd[546:72941] __33-[XCTestManager
closeIDESession:]_block_invoke <XCIDESession: 0x1660ab20>
[Xcode] 18:52:28.711 testmanagerd[546:72941] Considering exiting, 1 test
sessions, 0 sessions with identifiers, 0 unidentified sessions...
[Xcode] 18:52:29.048 testmanagerd[546:72941] IDE connected via lockdown
over socket 7 (__54-[XCTestManagerIDEServer
initWithLockdownServiceName:]_block_invoke)
[Xcode] 18:52:29.048 testmanagerd[546:72941] Created session with socket 7
[Xcode] 18:52:29.058 testmanagerd[546:72941] Handled a proxy request from
the IDE with session <XCIDESession: 0x165241e0> proxy <_DTXProxy:
0x16516ee0>
[Xcode] 18:52:29.058 testmanagerd[546:72941] -[XCIDESession
_IDE_initiateControlSessionForTestProcessID:protocolVersion:] 548 22
[Xcode] 18:52:29.059 testmanagerd[546:72682] Set control session
<XCIDESession: 0x165241e0> with proto 22 and proxy <_DTXProxy: 0x16516ee0>
[Xcode] 18:52:29.274 xcodebuild[46031:285747] Got reply to authorization
request for pid 548 (result:error): 16: (null)
[Xcode] 18:52:29.275 xcodebuild[46031:285742] Got whitelisting response
from test daemon. Success: 1
[Xcode] 18:52:28.499 XCTRunner[548:72894] Linked XCTest.framework from
/var/containers/Bundle/Application/602F6F66-C9E9-4B6D-8576-0BF7965FC517/WebDriverAgentRunner-Runner.app/Frameworks/XCTest.framework/XCTest,
built with Xcode 0833(8E3002), modified on Thursday, 13 July 2017 at
2:08:52 PM India Standard Time
[Xcode] 18:52:28.501 XCTRunner[548:72894] XCTest.framework bundle version:
12124
[Xcode] 18:52:28.504 XCTRunner[548:72894] System uptime: 6:34:21
[Xcode] 18:52:28.505 XCTRunner[548:72894] Process arguments: (
[Xcode]
"/var/containers/Bundle/Application/602F6F66-C9E9-4B6D-8576-0BF7965FC517/WebDriverAgentRunner-Runner.app/XCTRunner",
[Xcode] "-NSTreatUnknownArgumentsAsOpen",
[Xcode] NO,
[Xcode] "-ApplePersistenceIgnoreState",
[Xcode] YES
[Xcode] )
[Xcode] 18:52:28.505 XCTRunner[548:72894] Process environment: {
[Xcode] "CFFIXED_USER_HOME" =
"/private/var/mobile/Containers/Data/Application/0C5423EF-8AC6-43B9-877E-60B3B6E19828";
[Xcode] "CFLOG_FORCE_STDERR" = YES;
[Xcode] CLASSIC = 1;
[Xcode] "DYLD_FRAMEWORK_PATH" =
"/private/var/containers/Bundle/Application/602F6F66-C9E9-4B6D-8576-0BF7965FC517/WebDriverAgentRunner-Runner.app/Frameworks:/Users/Gokul/Library/Developer/Xcode/DerivedData/WebDriverAgent-brdadhpuduowllgivnnvuygpwhzy/Build/Products/Debug-iphoneos:__PLATFORMS__/iPhoneOS.platform/Developer/Library/Frameworks:/Developer/Library/PrivateFrameworks";
[Xcode] "DYLD_LIBRARY_PATH" =
"/private/var/containers/Bundle/Application/602F6F66-C9E9-4B6D-8576-0BF7965FC517/WebDriverAgentRunner-Runner.app/Frameworks:/Users/Gokul/Library/Developer/Xcode/DerivedData/WebDriverAgent-brdadhpuduowllgivnnvuygpwhzy/Build/Products/Debug-iphoneos:__PLATFORMS__/iPhoneOS.platform/Developer/Library/Frameworks:/Developer/Library/PrivateFrameworks";
[Xcode] FBSClientLogging = 0;
[Xcode] HOME =
"/private/var/mobile/Containers/Data/Application/0C5423EF-8AC6-43B9-877E-60B3B6E19828";
[Xcode] LOGNAME = mobile;
[Xcode] NSUnbufferedIO = YES;
[Xcode] "OS_ACTIVITY_DT_MODE" = YES;
[Xcode] PATH = "/usr/bin:/bin:/usr/sbin:/sbin";
[Xcode] SHELL = "/bin/sh";
[Xcode] TMPDIR =
"/private/var/mobile/Containers/Data/Application/0C5423EF-8AC6-43B9-877E-60B3B6E19828/tmp";
[Xcode] USER = mobile;
[Xcode] "XCODE_DBG_XPC_EXCLUSIONS" = "com.apple.dt.xctestSymbolicator";
[Xcode] XCTestConfigurationFilePath =
"/private/var/mobile/Containers/Data/Application/0C5423EF-8AC6-43B9-877E-60B3B6E19828/tmp/WebDriverAgentRunner-BF79A7C2-7A40-4A48-9D41-6F94CF16F1B6.xctestconfiguration";
[Xcode] "XPC_FLAGS" = 0x0;
[Xcode] "XPC_SERVICE_NAME" =
"UIKitApplication:com.apple.test.WebDriverAgentRunner-Runner[0x5055]";
[Xcode] "__CF_USER_TEXT_ENCODING" = "0x1F5:0:0";
[Xcode] }
[Xcode] 18:52:28.519 XCTRunner[548:72894] <XCTWaiter: 0x14eabc90> entering
wait loop for 5.00s
[Xcode] 18:52:28.552 XCTRunner[548:72922] Got daemon protocol version 3
[Xcode] 18:52:28.553 XCTRunner[548:72920] <XCTWaiter: 0x14eabc90>:
cancelWaiting
[Xcode] 18:52:28.580 XCTRunner[548:72894] <XCTWaiter: 0x14eabc90> done
waiting
[Xcode] 18:52:28.580 XCTRunner[548:72894] <XCTWaiter: 0x14eabc90> cleaning
up Exchange protocol versions with daemon
[Xcode] 18:52:28.583 XCTRunner[548:72894] Requesting socket
[Xcode] 18:52:28.586 XCTRunner[548:72894] <XCTWaiter: 0x14dab710> entering
wait loop for 30.00s
[Xcode] 18:52:28.697 XCTRunner[548:72922] <XCTWaiter: 0x14dab710>:
cancelWaiting
[Xcode] 18:52:28.698 XCTRunner[548:72894] <XCTWaiter: 0x14dab710> done
waiting
[Xcode] 18:52:28.698 XCTRunner[548:72894] <XCTWaiter: 0x14dab710> cleaning
up Socket for IDE session acquired
[Xcode] 18:52:28.699 XCTRunner[548:72894] Creating transport with socket 5
[Xcode] 18:52:28.700 XCTRunner[548:72894] Creating connection with
transport <DTXSocketTransport: 0x14da1010>
[Xcode] 18:52:28.703 XCTRunner[548:72894] Creating the proxy channel with
connection <DTXConnection 0x14dd3250 : x1>
[Xcode] 18:52:28.707 XCTRunner[548:72894] Messaging IDE proxy -
_XCT_testBundleReadyWithProtocolVersion:22 minimumVersion:8
[Xcode] 18:52:28.710 XCTRunner[548:72894] <XCTWaiter: 0x14eb3b60> entering
wait loop for 30.00s
[Xcode] 18:52:28.712 XCTRunner[548:72910]
_XCT_testBundleReadyWithProtocolVersion:minimumVersion: reply received
[Xcode] 18:52:29.113 XCTRunner[548:72923]
_IDE_startExecutingTestPlanWithProtocolVersion:22
[Xcode] 18:52:29.114 XCTRunner[548:72910] <XCTWaiter: 0x14eb3b60>:
cancelWaiting
[Xcode] 18:52:29.115 XCTRunner[548:72894] <XCTWaiter: 0x14eb3b60> done
waiting
[Xcode] 18:52:29.115 XCTRunner[548:72894] <XCTWaiter: 0x14eb3b60> cleaning
up IDE session ready for test plan
[Xcode] 18:52:29.323 xcodebuild[46031:285742] Starting test plan, clearing
initialization timeout timer.
[Xcode] 18:52:29.155 XCTRunner[548:72894] Initializing for UI testing.
[Xcode] 18:52:29.156 XCTRunner[548:72894] Daemon protocol version does not
support explicit load AX call, daemon will fall back to implicit load on
demand.
[Xcode] 18:52:29.158 XCTRunner[548:72894] <XCTWaiter: 0x14dd5270> entering
wait loop for 20.00s
[Xcode] 18:52:19.062 profiled[123] (Note ) MC: Provisioning profiles changed
[Xcode] 18:52:19.068 profiled[123] (Note ) MC: Updating MIS trust...
[Xcode] 18:52:20.648 installd[302] 0x1f115000 -[MIClientConnection
_doBackgroundInstallationForPath:withOptions:completion:]: Install of
"/var/mobile/Media/PublicStaging/WebDriverAgentRunner-Runner.app_sparse.ipa"
type Developer (LSInstallType = (null)) requested by streaming_zip_conduit
(pid 213)
[Xcode] 18:52:20.745 installd[302] 0x1f115000 -[MIInstaller
performInstallationWithError:]: Installing <MIInstallableBundlePatch
ID=com.apple.test.WebDriverAgentRunner-Runner; Version=1, ShortVersion=1.0>
[Xcode] 18:52:20.752 installd[302] 0x1f115000 -[MIInstallableBundlePatch
applyPatchWithError:]: Attempting patch update of
com.apple.test.WebDriverAgentRunner-Runner from 1 (1.0) to 1 (1.0)
[Xcode] 18:52:21.269 profiled[123] (Note ) MC: Provisioning profiles changed
[Xcode] 18:52:21.275 profiled[123] (Note ) MC: Updating MIS trust...
[Xcode] 18:52:21.285 securityd[85] secTaskDiagnoseEntitlements MISSING
keychain entitlements: no stored taskRef found
[Xcode] 18:52:21.285 securityd[85] secTaskDiagnoseEntitlements MISSING
keychain entitlements: no stored taskRef found
[Xcode] 18:52:21.294 installd[302] SecTrustEvaluate [leaf IssuerCommonName
SubjectCommonName]
[Xcode] 18:52:21.464 securityd[85] secTaskDiagnoseEntitlements MISSING
keychain entitlements: no stored taskRef found
[Xcode] 18:52:21.464 securityd[85] secTaskDiagnoseEntitlements MISSING
keychain entitlements: no stored taskRef found
[Xcode] 18:52:21.471 installd[302] SecTrustEvaluate [leaf IssuerCommonName
SubjectCommonName]
[Xcode] 18:52:21.734 securityd[85] secTaskDiagnoseEntitlements MISSING
keychain entitlements: no stored taskRef found
[Xcode] 18:52:21.734 securityd[85] secTaskDiagnoseEntitlements MISSING
keychain entitlements: no stored taskRef found
[Xcode] 18:52:21.742 installd[302] SecTrustEvaluate [leaf IssuerCommonName
SubjectCommonName]
[Xcode] 18:52:21.811 installd[302] 0x1f115000 -[MIInstallableBundle
_refreshUUIDForContainer:withError:]: Data container for
com.apple.test.WebDriverAgentRunner-Runner is now at
/private/var/mobile/Containers/Data/Application/0C5423EF-8AC6-43B9-877E-60B3B6E19828
[Xcode] 18:52:21.919 installd[302] 0x1f115000 -[MIContainer
makeContainerLiveReplacingContainer:reason:withError:]: Made container live
for com.apple.test.WebDriverAgentRunner-Runner at
/private/var/containers/Bundle/Application/602F6F66-C9E9-4B6D-8576-0BF7965FC517
[Xcode] 18:52:22.114 installd[302] 0x1f115000 -[MIInstaller
performInstallationWithError:]: Install Successful; Staging: 0.00s;
Waiting: 0.00s; Preflight/Patch: 0.31s, Verifying: 0.71s; Overall: 1.47s
[Xcode] 18:52:22.118 pkd[144] releasing plug-in hold
046B9979-6F49-4A4E-B266-43E9598FE51F at client's request
[Xcode] 18:52:22.309 online-auth-agent[326] SecTrustEvaluate [leaf
IssuerCommonName SubjectCommonName]
[Xcode] 18:52:23.106 SpringBoard[48] Installed apps did change.
[Xcode] Added: {(
[Xcode] )}
[Xcode] Removed: {(
[Xcode] )}
[Xcode] Modified: {(
[Xcode] "com.apple.test.WebDriverAgentRunner-Runner"
[Xcode] )}
[Xcode] 18:52:24.746 lockdownd[32] CFStringGetCString error in locklog
[Xcode] 18:52:25.739 lockdownd[32] CFStringGetCString error in locklog
[Xcode] 18:52:26.000 kernel[0] AppleKeyStore:Sending lock change 0 for
handle 0
[Xcode] 18:52:26.178 UserEventAgent[23] LockStateNotifier
aksNotificationCallback posting notification:
com.apple.mobile.keybagd.lock_status
[Xcode] 18:52:26.181 UserEventAgent[23] (Note ) PIH: Lock status changed.
[Xcode] 18:52:26.189 MobileMail[156] Ignoring lock state change from
disabled to disabled
[Xcode] 18:52:26.000 kernel[0] AppleKeyStore:Sending lock change 0 for
handle 0
[Xcode] 18:52:26.425 UserEventAgent[23] LockStateNotifier
aksNotificationCallback posting notification:
com.apple.mobile.keybagd.lock_status
[Xcode] 18:52:26.428 UserEventAgent[23] (Note ) PIH: Lock status changed.
[Xcode] 18:52:26.433 MobileMail[156] Ignoring lock state change from
disabled to disabled
[Xcode] 18:52:27.337 SpringBoard[48] SecTrustEvaluate [leaf
IssuerCommonName SubjectCommonName]
[Xcode] 18:52:27.367 SpringBoard[48] SecTrustEvaluate [leaf
IssuerCommonName SubjectCommonName]
[Xcode] 18:52:27.416 securityd[85] secTaskDiagnoseEntitlements MISSING
keychain entitlements: no stored taskRef found
[Xcode] 18:52:27.417 securityd[85] secTaskDiagnoseEntitlements MISSING
keychain entitlements: no stored taskRef found
[Xcode] 18:52:27.423 amfid[219] SecTrustEvaluate [leaf IssuerCommonName
SubjectCommonName]
[Xcode] 18:52:27.436 securityd[85] secTaskDiagnoseEntitlements MISSING
keychain entitlements: no stored taskRef found
[Xcode] 18:52:27.437 securityd[85] secTaskDiagnoseEntitlements MISSING
keychain entitlements: no stored taskRef found
[Xcode] 18:52:27.442 amfid[219] SecTrustEvaluate [leaf IssuerCommonName
SubjectCommonName]
[Xcode] 18:52:27.000 kernel[0] xpcproxy[548] Container:
/private/var/mobile/Containers/Data/Application/0C5423EF-8AC6-43B9-877E-60B3B6E19828
(sandbox)
[Xcode] 18:52:27.573 securityd[85] secTaskDiagnoseEntitlements MISSING
keychain entitlements: no stored taskRef found
[Xcode] 18:52:27.575 SpringBoard[48] LICreateIconForImage passed NULL
CGImageRef image
[Xcode] 18:52:27.584 securityd[85] secTaskDiagnoseEntitlements MISSING
keychain entitlements: no stored taskRef found
[Xcode] 18:52:27.597 amfid[219] SecTrustEvaluate [leaf IssuerCommonName
SubjectCommonName]
[Xcode] 18:52:27.733 com.apple.xpc.launchd[1] assertion failed: 13G36:
launchd + 85529 [083E000D-4C31-3B98-A2C4-6FADB4D1940F]: 0x3
[Xcode] 18:52:28.247 XCTRunner[548] Running tests...
[Xcode] 18:52:29.261 XCTRunner[548:72920] <XCTWaiter: 0x14dd5270>:
cancelWaiting
[Xcode] 18:52:29.262 XCTRunner[548:72894] <XCTWaiter: 0x14dd5270> done
waiting
[Xcode] 18:52:28.288 securityd[85] secTaskDiagnoseEntitlements MISSING
keychain entitlements: no stored taskRef found
[Xcode] 18:52:29.262 XCTRunner[548:72894] <XCTWaiter: 0x14dd5270> cleaning
up Expect predicate `BLOCKPREDICATE(0x14dd54c0)` for object
BLOCKPREDICATE(0x14dd54c0)
[Xcode] 18:52:29.264 XCTRunner[548:72894] Ignoring request to set AX
messaging timeout - daemon protocol version does not support this
functionality.
[Xcode] 18:52:29.268 XCTRunner[548:72894] <XCTWaiter: 0x14dd73f0> entering
wait loop for 15.00s
[Xcode] 18:52:28.290 securityd[85] secTaskDiagnoseEntitlements MISSING
keychain entitlements: no stored taskRef found
[Xcode] 18:52:28.297 amfid[219] SecTrustEvaluate [leaf IssuerCommonName
SubjectCommonName]
[Xcode] 18:52:28.332 securityd[85] secTaskDiagnoseEntitlements MISSING
keychain entitlements: no stored taskRef found
[Xcode] 18:52:28.333 securityd[85] secTaskDiagnoseEntitlements MISSING
keychain entitlements: no stored taskRef found
[Xcode] 18:52:28.339 amfid[219] SecTrustEvaluate [leaf IssuerCommonName
SubjectCommonName]
[Xcode] 18:52:28.381 securityd[85] secTaskDiagnoseEntitlements MISSING
keychain entitlements: no stored taskRef found
[Xcode] 18:52:28.382 securityd[85] secTaskDiagnoseEntitlements MISSING
keychain entitlements: no stored taskRef found
[Xcode] 18:52:28.389 amfid[219] SecTrustEvaluate [leaf IssuerCommonName
SubjectCommonName]
[Xcode] 18:52:28.595 testmanagerd[546] BUG in libdispatch client:
kevent[EVFILT_READ] delete: "Bad file descriptor" - 0x9
[Xcode] 18:52:29.370 XCTRunner[548:72923] <XCTWaiter: 0x14dd73f0>:
cancelWaiting
[Xcode] 18:52:29.374 testmanagerd[546:72940] -[XCTestManager
allowUITestControlForPID:] 548 vs 548
[Xcode] 18:52:29.371 XCTRunner[548:72894] <XCTWaiter: 0x14dd73f0> done
waiting
[Xcode] 18:52:29.371 XCTRunner[548:72894] <XCTWaiter: 0x14dd73f0> cleaning
up Expect predicate `BLOCKPREDICATE(0x14ebb890)` for object
BLOCKPREDICATE(0x14ebb890)
[Xcode] 18:52:29.375 XCTRunner[548:72894] <XCTWaiter: 0x14ebb980> entering
wait loop for 15.00s
[Xcode] 18:52:29.390 testmanagerd[546:72940]
_AXSApplicationAccessibilityEnabled() returned false
[Xcode] 18:52:29.450 testmanagerd[546:72940] Waiting for AX to finish
loading...
[Xcode] 18:52:29.477 testmanagerd[546:72682] AX is loaded
[Xcode] 18:52:29.490 testmanagerd[546:72682] AX is loaded
[Xcode] 18:52:29.505 testmanagerd[546:72682] AX is loaded
[Xcode] 18:52:29.582 testmanagerd[546:72682] AX is loaded
[Xcode] 18:52:29.584 testmanagerd[546:72682] AX is loaded
[Xcode] 18:52:29.587 testmanagerd[546:72682] AX is loaded
[Xcode] 18:52:29.596 testmanagerd[546:72940]
AXDisableAccessibilityOnTermination() returned 0
[Xcode] 18:52:29.597 testmanagerd[546:72940] Created system wide AX element
<AXUIElement 0x165293a0> {pid=0}
[Xcode] 18:52:29.604 testmanagerd[546:72940] Added 1021 notification to
<AXObserver 0x16529960> {pid=0} for <AXUIElement 0x165293a0> {pid=0}: 0
[Xcode] 18:52:29.606 testmanagerd[546:72940] Added 1000 notification to
<AXObserver 0x16529960> {pid=0} for <AXUIElement 0x165293a0> {pid=0}: 0
[Xcode] 18:52:29.608 testmanagerd[546:72940] Added 1005 notification to
<AXObserver 0x16529960> {pid=0} for <AXUIElement 0x165293a0> {pid=0}: 0
[Xcode] 18:52:29.610 testmanagerd[546:72940] Added 1001 notification to
<AXObserver 0x16529960> {pid=0} for <AXUIElement 0x165293a0> {pid=0}: 0
[Xcode] 18:52:29.613 testmanagerd[546:72940] Added 1011 notification to
<AXObserver 0x16529960> {pid=0} for <AXUIElement 0x165293a0> {pid=0}: 0
[Xcode] 18:52:29.615 testmanagerd[546:72940] Added 4002 notification to
<AXObserver 0x16529960> {pid=0} for <AXUIElement 0x165293a0> {pid=0}: 0
[Xcode] 18:52:29.617 testmanagerd[546:72940] Added 4000 notification to
<AXObserver 0x16529960> {pid=0} for <AXUIElement 0x165293a0> {pid=0}: 0
[Xcode] 18:52:29.622 testmanagerd[546:72940] Added 4001 notification to
<AXObserver 0x16529960> {pid=0} for <AXUIElement 0x165293a0> {pid=0}: 0
[Xcode] 18:52:29.625 testmanagerd[546:72940] Added 5000 notification to
<AXObserver 0x16529960> {pid=0} for <AXUIElement 0x165293a0> {pid=0}: 0
[Xcode] 18:52:29.627 testmanagerd[546:72940] Registered for AX notification
4002
[Xcode] 18:52:29.655 testmanagerd[546:72682] AX is loaded
[Xcode] 18:52:29.657 testmanagerd[546:72682] AX is loaded
[Xcode] 18:52:29.659 testmanagerd[546:72682] AX is loaded
[Xcode] 18:52:29.677 testmanagerd[546:72682] AX is loaded
[Xcode] 18:52:29.690 testmanagerd[546:72682] AX is loaded
[Xcode] 18:52:29.704 XCTRunner[548:72949] <XCTWaiter: 0x14ebb980>:
cancelWaiting
[Xcode] 18:52:29.712 testmanagerd[546:72682] AX is loaded
[Xcode] 18:52:29.744 testmanagerd[546:72682] AX is loaded
[Xcode] 18:52:29.744 testmanagerd[546:72682] AX is loaded
[Xcode] 18:52:29.745 testmanagerd[546:72682] AX is loaded
[Xcode] 18:52:29.746 testmanagerd[546:72682] AX is loaded
[Xcode] 18:52:29.747 testmanagerd[546:72682] AX is loaded
[Xcode] 18:52:29.748 testmanagerd[546:72682] AX is loaded
[Xcode] 18:52:29.749 testmanagerd[546:72682] AX is loaded
[Xcode] 18:52:29.881 XCTRunner[548:72894] <XCTWaiter: 0x14ebb980> done
waiting
[Xcode] 18:52:29.886 testmanagerd[546:72940] -[XCTestManager
allowUITestControlForPID:] 548 vs 548
[Xcode] 18:52:29.881 XCTRunner[548:72894] <XCTWaiter: 0x14ebb980> cleaning
up Expect predicate `BLOCKPREDICATE(0x14dd7ff0)` for object
BLOCKPREDICATE(0x14dd7ff0)
[Xcode] 18:52:29.887 XCTRunner[548:72894] <XCTWaiter: 0x14d6ef10> entering
wait loop for 15.00s
[Xcode] 18:52:29.894 testmanagerd[546:72940] Registered for AX notification
1006
[Xcode] 18:52:29.898 XCTRunner[548:72924] <XCTWaiter: 0x14d6ef10>:
cancelWaiting
[Xcode] 18:52:29.899 XCTRunner[548:72894] <XCTWaiter: 0x14d6ef10> done
waiting
[Xcode] 18:52:29.899 XCTRunner[548:72894] <XCTWaiter: 0x14d6ef10> cleaning
up Expect predicate `BLOCKPREDICATE(0x14ec0ac0)` for object
BLOCKPREDICATE(0x14ec0ac0)
[Xcode] 18:52:29.901 testmanagerd[546:72944] -[XCTestManager
allowUITestControlForPID:] 548 vs 548
[Xcode] 18:52:29.902 XCTRunner[548:72894] <XCTWaiter: 0x14d6e160> entering
wait loop for 15.00s
[Xcode] 18:52:29.908 testmanagerd[546:72944] Registered for AX notification
1021
[Xcode] 18:52:29.911 XCTRunner[548:72924] <XCTWaiter: 0x14d6e160>:
cancelWaiting
[Xcode] 18:52:29.911 XCTRunner[548:72894] <XCTWaiter: 0x14d6e160> done
waiting
[Xcode] 18:52:29.912 XCTRunner[548:72894] <XCTWaiter: 0x14d6e160> cleaning
up Expect predicate `BLOCKPREDICATE(0x14ee5ce0)` for object
BLOCKPREDICATE(0x14ee5ce0)
[Xcode] 18:52:29.916 XCTRunner[548:72894] <XCTWaiter: 0x14d742a0> entering
wait loop for 30.00s
[Xcode] 18:52:29.914 testmanagerd[546:72940] -[XCTestManager
allowUITestControlForPID:] 548 vs 548
[Xcode] 18:52:29.916 testmanagerd[546:72940] Got request for process
assertion for pid 548.
[Xcode] 18:52:29.919 testmanagerd[546:72940] Created process assertion for
pid 548: <BKSProcessAssertion: 0x16528250>, waiting for response from
assertiond.
[Xcode] 18:52:29.926 testmanagerd[546:72944] Got reply for process
assertion request, success: YES
[Xcode] 18:52:29.928 XCTRunner[548:72920] Got reply for background
assertion.
[Xcode] 18:52:29.929 XCTRunner[548:72920] <XCTWaiter: 0x14d742a0>:
cancelWaiting
[Xcode] 18:52:29.930 XCTRunner[548:72894] <XCTWaiter: 0x14d742a0> done
waiting
[Xcode] 18:52:29.930 XCTRunner[548:72894] <XCTWaiter: 0x14d742a0> cleaning
up Expect predicate `BLOCKPREDICATE(0x14d70200)` for object
BLOCKPREDICATE(0x14d70200)
[Xcode] 18:52:29.931 XCTRunner[548:72894] Registering for
UIApplicationDidEnterBackgroundNotification
[Xcode] 18:52:29.932 XCTRunner[548:72894] Hitting home button to enter
background.
[Xcode] 18:52:29.938 testmanagerd[546:72698] -[XCTestManager
allowUITestControlForPID:] 548 vs 548
[Xcode] 18:52:29.940 testmanagerd[546:72698] Performing
<XCDeviceEvent:0x16523900 page 12 usage 64 duration 0.01s
[Xcode] 18:52:29.944 XCTRunner[548:72894] <XCTWaiter: 0x14d5f0f0> entering
wait loop for 30.00s
[Xcode] 18:52:29.951 testmanagerd[546:72698] Dispatching device event
synthesis to background queue
[Xcode] 18:52:29.953 testmanagerd[546:72944] Sleeping for 0.005000s before
sending up event
[Xcode] 18:52:29.964 testmanagerd[546:72944] Device event sent.
[Xcode] 18:52:29.966 testmanagerd[546:72682] AX Notification 4002
[Xcode] 18:52:29.970 XCTRunner[548:72948] Got AX notification 4002
[Xcode] 18:52:29.969 testmanagerd[546:72682] Got user testing notification
with payload {
[Xcode] event = ButtonEventsCompleted;
[Xcode] }
[Xcode] 18:52:29.971 testmanagerd[546:72682] Device event complete,
replying to client.
[Xcode] 18:52:29.976 XCTRunner[548:72924] <XCTWaiter: 0x14d5f0f0>:
cancelWaiting
[Xcode] 18:52:29.976 XCTRunner[548:72894] <XCTWaiter: 0x14d5f0f0> done
waiting
[Xcode] 18:52:29.977 XCTRunner[548:72894] <XCTWaiter: 0x14d5f0f0> cleaning
up Expect predicate `BLOCKPREDICATE(0x14dfb1a0)` for object
BLOCKPREDICATE(0x14dfb1a0)
[Xcode] 18:52:29.982 XCTRunner[548:72894] <XCTWaiter: 0x14ec7330> entering
wait loop for 30.00s
[Xcode] 18:52:30.317 testmanagerd[546:72682] AX Notification 1021
[Xcode] 18:52:30.324 XCTRunner[548:72923] Got AX notification 1021
[Xcode] 18:52:30.327 XCTRunner[548:72923] Got
kAXPidStatusChangedNotification for 548: active
[Xcode] 18:52:30.385 XCTRunner[548:72894] No application process found
matching pid 548, caching
[Xcode] 18:52:30.402 testmanagerd[546:72682] AX Notification 4002
[Xcode] 18:52:30.413 XCTRunner[548:72948] Got AX notification 4002
[Xcode] 18:52:30.420 testmanagerd[546:72682] Got user testing notification
with payload {
[Xcode] controllerClass = SBIconController;
[Xcode] event = ViewDidAppear;
[Xcode] }
[Xcode] 18:52:30.424 testmanagerd[546:72682] AX Notification 4002
[Xcode] 18:52:30.430 testmanagerd[546:72682] Got user testing notification
with payload {
[Xcode] controllerClass = SBSearchViewController;
[Xcode] event = ViewDidAppear;
[Xcode] }
[Xcode] 18:52:30.430 XCTRunner[548:72948] Got AX notification 4002
[Xcode] 18:52:30.433 testmanagerd[546:72682] AX Notification 4002
[Xcode] 18:52:30.437 XCTRunner[548:72923] Got AX notification 4002
[Xcode] 18:52:30.436 testmanagerd[546:72682] Got user testing notification
with payload {
[Xcode] controllerClass = SPUINavigationController;
[Xcode] event = ViewDidAppear;
[Xcode] }
[Xcode] 18:52:30.445 testmanagerd[546:72682] AX Notification 4002
[Xcode] 18:52:30.448 XCTRunner[548:72948] Got AX notification 4002
[Xcode] 18:52:30.448 testmanagerd[546:72682] Got user testing notification
with payload {
[Xcode] controllerClass = SPUISearchViewController;
[Xcode] event = ViewDidAppear;
[Xcode] }
[Xcode] 18:52:30.279 XCTRunner[548] Continuing to run tests in the
background with task ID 1
[Xcode] 18:52:30.355 SpringBoard[48] LICreateIconForImage passed NULL
CGImageRef image
[Xcode] 18:52:30.800 testmanagerd[546:72682] AX Notification 1011
[Xcode] 18:52:30.990 testmanagerd[546:72682] AX Notification 4002
[Xcode] 18:52:30.992 testmanagerd[546:72682] Got user testing notification
with payload {
[Xcode] controllerClass = SBAppContainerViewController;
[Xcode] event = ViewDidDisappear;
[Xcode] }
[Xcode] 18:52:30.994 testmanagerd[546:72682] AX Notification 1021
[Xcode] 18:52:31.001 XCTRunner[548:72894] Got
UIApplicationDidEnterBackgroundNotification
[Xcode] 18:52:31.001 XCTRunner[548:72923] Got AX notification 4002
[Xcode] 18:52:31.005 XCTRunner[548:72923] Got AX notification 1021
[Xcode] 18:52:31.012 XCTRunner[548:72923] Got
kAXPidStatusChangedNotification for 548: suspended
[Xcode] 18:52:31.012 XCTRunner[548:72894] No application process found
matching pid 548, caching
[Xcode] 18:52:31.013 XCTRunner[548:72948] <XCTWaiter: 0x14ec7330>:
cancelWaiting
[Xcode] 18:52:31.194 XCTRunner[548:72894] <XCTWaiter: 0x14ec7330> done
waiting
[Xcode] 18:52:31.194 XCTRunner[548:72894] <XCTWaiter: 0x14ec7330> cleaning
up Expect predicate `BLOCKPREDICATE(0x14ed4330)` for object
BLOCKPREDICATE(0x14ed4330)
[Xcode] 18:52:31.194 XCTRunner[548:72894] Done initializing for UI testing.
[Xcode] 18:52:31.194 XCTRunner[548:72894] Loading test suite...
[Xcode] 18:52:31.307 XCTRunner[548:72894] Finished loading test suite, took
0.112s
[Xcode] 18:52:31.307 XCTRunner[548:72894] -[XCTRunnerIDESession
testBundleWillStart:]
[Xcode] 18:52:31.308 XCTRunner[548:72894] Running suite...
[Xcode] 18:52:31.313 XCTRunner[548:72894] -[XCTRunnerIDESession
testSuiteWillStart:]
[Xcode] 18:52:31.487 xcodebuild[46031:285742] _XCT_testSuite:All tests
didStartAt:2017-07-13 13:22:31 +0000
[Xcode] 18:52:35.407 xcodebuild[46031:285866] (LOG ARBITER) Rotating
message buffer with 201 messages.
[Xcode] 18:52:38.462 xcodebuild[46031:285747] (LOG ARBITER) Rotating
message buffer with 319 messages.
[Xcode] 18:52:41.398 xcodebuild[46031:285868] (LOG ARBITER) Rotating
message buffer with 365 messages.
[Xcode] 18:52:44.472 xcodebuild[46031:285866] (LOG ARBITER) Rotating
message buffer with 4 messages.
[Xcode] 18:52:47.477 xcodebuild[46031:285866] (LOG ARBITER) Rotating
message buffer with 34 messages.
[Xcode] 18:52:50.407 xcodebuild[46031:285747] (LOG ARBITER) Rotating
message buffer with 5 messages.
[Xcode] 18:52:53.435 xcodebuild[46031:285866] (LOG ARBITER) Rotating
message buffer with 11 messages.
[Xcode] 18:52:55.487 xcodebuild[46031:285747] (LOG ARBITER) Rotating
message buffer with 15 messages.
[Xcode] 18:52:59.456 xcodebuild[46031:285747] (LOG ARBITER) Rotating
message buffer with 13 messages.
[Xcode] 18:53:02.474 xcodebuild[46031:286113] (LOG ARBITER) Rotating
message buffer with 5 messages.
[Xcode] 18:53:05.425 xcodebuild[46031:286113] (LOG ARBITER) Rotating
message buffer with 5 messages.
[Xcode] 18:53:14.304 xcodebuild[46031:285868] (LOG ARBITER) Rotating
message buffer with 1 messages.
[Xcode] 18:53:17.460 xcodebuild[46031:286113] (LOG ARBITER) Rotating
message buffer with 2 messages.
[Xcode] 18:53:41.485 xcodebuild[46031:286388] (LOG ARBITER) Rotating
message buffer with 14 messages.
[Xcode] 18:53:59.427 xcodebuild[46031:286441] (LOG ARBITER) Rotating
message buffer with 1 messages.
[Xcode] 18:54:19.484 xcodebuild[46031:286441] (LOG ARBITER) Rotating
message buffer with 2 messages.
[Xcode] 18:54:23.473 xcodebuild[46031:286637] (LOG ARBITER) Rotating
message buffer with 10 messages.
[Xcode]
Reply all
Reply to author
Forward
0 new messages