appium_1_7_1.txt

2017-10-27 14:14:24:501 - info: [Appium] Welcome to Appium v1.7.1
2017-10-27 14:14:24:505 - info: [Appium] Non-default server args:
2017-10-27 14:14:24:506 - info: [Appium]   sessionOverride: true
2017-10-27 14:14:24:506 - info: [Appium]   log: [...]
2017-10-27 14:14:24:506 - info: [Appium]   localTimezone: true
2017-10-27 14:14:24:558 - info: [Appium] Appium REST http interface listener started on 0.0.0.0:4723
2017-10-27 14:14:56:226 - info: [HTTP] --> POST /wd/hub/session {"capabilities":{"firstMatch":[{}],"alwaysMatch":{"platformName":"iOS"}},"desiredCapabilities":{"app":"[...]","platformName":"iOS","platformVersion":"10.3","deviceName":"iPad Air","noReset":true,"automationName":"XCUITest","newCommandTimeout":0,"preventWDAAttachments":true,"orientation":"LANDSCAPE"}}
2017-10-27 14:14:56:232 - info: [debug] [MJSONWP] Calling AppiumDriver.createSession() with args: [{"app":"[...]","platformName":"iOS","platformVersion":"10.3","deviceName":"iPad Air","noReset":true,"automationName":"XCUITest","newCommandTimeout":0,"preventWDAAttachments":true,"orientation":"LANDSCAPE"},null,{"firstMatch":[{}],"alwaysMatch":{"platformName":"iOS"}}]
2017-10-27 14:14:56:234 - info: [debug] [BaseDriver] Event 'newSessionRequested' logged at 1509106496234 (14:14:56 GMT+0200 (CEST))
2017-10-27 14:14:56:238 - info: [Appium] Creating new XCUITestDriver (v2.51.1) session
2017-10-27 14:14:56:238 - info: [Appium] Capabilities:
2017-10-27 14:14:56:243 - info: [Appium]   app: '[...]'
2017-10-27 14:14:56:248 - info: [Appium]   platformName: 'iOS'
2017-10-27 14:14:56:248 - info: [Appium]   platformVersion: '10.3'
2017-10-27 14:14:56:249 - info: [Appium]   deviceName: 'iPad Air'
2017-10-27 14:14:56:250 - info: [Appium]   noReset: true
2017-10-27 14:14:56:250 - info: [Appium]   automationName: 'XCUITest'
2017-10-27 14:14:56:251 - info: [Appium]   newCommandTimeout: 0
2017-10-27 14:14:56:252 - info: [Appium]   preventWDAAttachments: true
2017-10-27 14:14:56:253 - info: [Appium]   orientation: 'LANDSCAPE'
2017-10-27 14:14:56:406 - info: [BaseDriver] Session created with session id: d39e4f63-4a9e-4faa-8403-06710bd036dd
2017-10-27 14:14:56:516 - info: [debug] [XCUITest] Xcode version set to '8.3.3' (tools v9.0.1.0.1.1506734476)
2017-10-27 14:14:56:531 - info: [debug] [XCUITest] iOS SDK Version set to '10.3'
2017-10-27 14:14:56:532 - info: [debug] [BaseDriver] Event 'xcodeDetailsRetrieved' logged at 1509106496532 (14:14:56 GMT+0200 (CEST))
2017-10-27 14:14:56:750 - info: [iOSSim] Constructing iOS simulator for Xcode version 8.3.3 with udid '[...]'
2017-10-27 14:14:56:751 - info: [XCUITest] Determining device to run tests on: udid: '[...]', real device: false
2017-10-27 14:14:56:752 - info: [BaseDriver] Using local app '[...]'
2017-10-27 14:14:56:752 - info: [debug] [BaseDriver] Event 'appConfigured' logged at 1509106496752 (14:14:56 GMT+0200 (CEST))
2017-10-27 14:14:56:752 - info: [debug] [XCUITest] Checking whether app '[...]' is actually present on file system
2017-10-27 14:14:56:753 - info: [debug] [XCUITest] App is present
2017-10-27 14:14:56:756 - info: [debug] [iOS] Getting bundle ID from app '[...]': '[...]'
2017-10-27 14:14:56:757 - info: [debug] [BaseDriver] Event 'resetStarted' logged at 1509106496757 (14:14:56 GMT+0200 (CEST))
2017-10-27 14:14:56:757 - info: [debug] [XCUITest] Reset: noReset is on. Leaving simulator as is
2017-10-27 14:14:56:757 - info: [debug] [BaseDriver] Event 'resetComplete' logged at 1509106496757 (14:14:56 GMT+0200 (CEST))
2017-10-27 14:14:56:841 - info: [debug] [iOSLog] Starting iOS 10.3 simulator log capture
2017-10-27 14:14:56:841 - info: [debug] [iOSLog] System log path: [...]/Library/Logs/CoreSimulator/[...]/system.log
2017-10-27 14:14:56:881 - info: [debug] [BaseDriver] Event 'logCaptureStarted' logged at 1509106496881 (14:14:56 GMT+0200 (CEST))
2017-10-27 14:14:56:881 - info: [XCUITest] Setting up simulator
2017-10-27 14:14:56:882 - info: [debug] [iOS] No reason to set locale
2017-10-27 14:14:56:882 - info: [debug] [iOS] No iOS / app preferences to set
2017-10-27 14:14:57:328 - info: [debug] [iOSSim] The count of running Simulator UI client instances is 1
2017-10-27 14:14:57:328 - info: [iOSSim] Both Simulator with UDID [...] and the UI client are currently running
2017-10-27 14:14:57:329 - info: [debug] [BaseDriver] Event 'simStarted' logged at 1509106497328 (14:14:57 GMT+0200 (CEST))
2017-10-27 14:14:57:424 - info: [debug] [XCUITest] App '[...]' is already installed. No need to reinstall.
2017-10-27 14:14:57:424 - info: [debug] [BaseDriver] Event 'appInstalled' logged at 1509106497424 (14:14:57 GMT+0200 (CEST))
2017-10-27 14:14:57:426 - info: [XCUITest] Using WDA path: '/usr/local/lib/node_modules/appium/node_modules/appium-xcuitest-driver/WebDriverAgent'
2017-10-27 14:14:57:426 - info: [XCUITest] Using WDA agent: '/usr/local/lib/node_modules/appium/node_modules/appium-xcuitest-driver/WebDriverAgent/WebDriverAgent.xcodeproj'
2017-10-27 14:14:57:429 - info: [debug] [XCUITest] Capability 'useNewWDA' set to false, so trying to reuse currently running WDA instance at 'http://localhost:8100/'
2017-10-27 14:14:57:431 - info: [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8100/status] with no body
2017-10-27 14:14:57:462 - info: [debug] [XCUITest] WDA is not listening at 'http://localhost:8100/'. Rebuilding...
2017-10-27 14:14:57:464 - info: [debug] [BaseDriver] Event 'wdaStartAttempted' logged at 1509106497464 (14:14:57 GMT+0200 (CEST))
2017-10-27 14:14:57:464 - info: [XCUITest] Launching WebDriverAgent on the device
2017-10-27 14:14:57:466 - info: [debug] [XCUITest] Carthage found: '/usr/local/bin/carthage'
2017-10-27 14:14:57:468 - info: [debug] [XCUITest] Killing running processes 'xcodebuild.*[...], iproxy 8100, [...].*XCTRunner' for the device [...]...
2017-10-27 14:14:57:494 - info: [debug] [XCUITest] 'pgrep -nif xcodebuild.*[...]' didn't detect any matching processes. Return code: 1
2017-10-27 14:14:57:517 - info: [debug] [XCUITest] 'pgrep -nif iproxy 8100' didn't detect any matching processes. Return code: 1
2017-10-27 14:14:57:543 - info: [debug] [XCUITest] 'pgrep -nif [...].*XCTRunner' didn't detect any matching processes. Return code: 1
2017-10-27 14:14:57:545 - info: [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=[...] IPHONEOS_DEPLOYMENT_TARGET=10.3' in directory '/usr/local/lib/node_modules/appium/node_modules/appium-xcuitest-driver/WebDriverAgent'
2017-10-27 14:14:57:546 - info: [debug] [XCUITest] Output from xcodebuild will not be logged. To see xcode logging, use 'showXcodeLog' desired capability
2017-10-27 14:14:58:233 - info: [debug] [XCUITest] Waiting up to 60000ms for WebDriverAgent to start
2017-10-27 14:14:58:234 - info: [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8100/status] with no body
2017-10-27 14:14:58:746 - info: [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8100/status] with no body
2017-10-27 14:14:59:253 - info: [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8100/status] with no body
2017-10-27 14:14:59:760 - info: [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8100/status] with no body
2017-10-27 14:15:00:269 - info: [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8100/status] with no body
2017-10-27 14:15:00:773 - info: [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8100/status] with no body
2017-10-27 14:15:01:280 - info: [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8100/status] with no body
2017-10-27 14:15:01:790 - info: [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8100/status] with no body
2017-10-27 14:15:02:296 - info: [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8100/status] with no body
2017-10-27 14:15:02:803 - info: [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8100/status] with no body
2017-10-27 14:15:03:312 - info: [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8100/status] with no body
2017-10-27 14:15:03:818 - info: [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8100/status] with no body
2017-10-27 14:15:04:327 - info: [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8100/status] with no body
2017-10-27 14:15:04:834 - info: [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8100/status] with no body
2017-10-27 14:15:05:340 - info: [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8100/status] with no body
2017-10-27 14:15:05:736 - info: [debug] [XCUITest] Log file for xcodebuild test: [...]/Library/Developer/Xcode/DerivedData/WebDriverAgent-brdadhpuduowllgivnnvuygpwhzy/Logs/Test/9F9313E7-6555-4C9F-93FE-DEA0CF901FFE/Session-WebDriverAgentRunner-2017-10-27_141505-BP9BzI.log
2017-10-27 14:15:05:845 - info: [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8100/status] with no body
2017-10-27 14:15:06:354 - info: [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8100/status] with no body
2017-10-27 14:15:06:859 - info: [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8100/status] with no body
2017-10-27 14:15:07:369 - info: [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8100/status] with no body
2017-10-27 14:15:07:876 - info: [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8100/status] with no body
2017-10-27 14:15:08:382 - info: [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8100/status] with no body
2017-10-27 14:15:08:889 - info: [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8100/status] with no body
2017-10-27 14:15:09:397 - info: [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8100/status] with no body
2017-10-27 14:15:09:904 - info: [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8100/status] with no body
2017-10-27 14:15:10:410 - info: [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8100/status] with no body
2017-10-27 14:15:10:916 - info: [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8100/status] with no body
2017-10-27 14:15:11:425 - info: [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8100/status] with no body
2017-10-27 14:15:11:931 - info: [debug] [JSONWP Proxy] Proxying [GET /status] to [GET http://localhost:8100/status] with no body
2017-10-27 14:15:11:940 - info: [debug] [JSONWP Proxy] Got response with status 200: "{\n  \"value\" : {\n    \"state\" : \"success\",\n    \"os\" : {\n      \"name\" : \"iOS\",\n      \"version\" : \"10.3.1\"\n    },\n    \"ios\" : {\n      \"simulatorVersion\" : \"10.3.1\",\n      \"ip\" : \"[...]\"\n    },\n    \"build\" : {\n      \"time\" : \"Oct 27 2017 14:15:00\"\n    }\n  },\n  \"sessionId\" : \"2DDCFFEA-2982-425C-9A5F-412247905305\",\n  \"status\" : 0\n}"
2017-10-27 14:15:11:941 - info: [debug] [XCUITest] WebDriverAgent running on ip '[...]'
2017-10-27 14:15:11:941 - info: [debug] [XCUITest] WebDriverAgent successfully started after 14393ms
2017-10-27 14:15:11:943 - info: [debug] [BaseDriver] Event 'wdaSessionAttempted' logged at 1509106511943 (14:15:11 GMT+0200 (CEST))
2017-10-27 14:15:11:943 - info: [debug] [XCUITest] Sending createSession command to WDA
2017-10-27 14:15:11:946 - info: [debug] [JSONWP Proxy] Proxying [POST /session] to [POST http://localhost:8100/session] with body: {"desiredCapabilities":{"bundleId":"[...]","arguments":[],"environment":{},"shouldWaitForQuiescence":true,"shouldUseTestManagerForVisibilityDetection":false,"maxTypingFrequency":60,"shouldUseSingletonTestManager":true}}
2017-10-27 14:15:15:368 - info: [debug] [JSONWP Proxy] Got response with status 200: {"value":{"sessionId":"4E2AA453-BB1A-4C54-A015-3ECD07FF2814","capabilities":{"device":"ipad","browserName":"DEVELOP","sdkVersion":"10.3.1","CFBundleIdentifier":"[...]"}},"sessionId":"4E2AA453-BB1A-4C54-A015-3ECD07FF2814","status":0}
2017-10-27 14:15:15:369 - info: [debug] [BaseDriver] Event 'wdaSessionStarted' logged at 1509106515369 (14:15:15 GMT+0200 (CEST))
2017-10-27 14:15:15:420 - info: [XCUITest] Setting '555' permissions to '[...]/Library/Developer/Xcode/DerivedData/WebDriverAgent-brdadhpuduowllgivnnvuygpwhzy/Logs/Test/Attachments' folder
2017-10-27 14:15:15:422 - info: [debug] [BaseDriver] Event 'wdaPermsAdjusted' logged at 1509106515422 (14:15:15 GMT+0200 (CEST))
2017-10-27 14:15:15:422 - info: [debug] [BaseDriver] Event 'wdaStarted' logged at 1509106515422 (14:15:15 GMT+0200 (CEST))
2017-10-27 14:15:15:423 - info: [debug] [XCUITest] Setting initial orientation to 'LANDSCAPE'
2017-10-27 14:15:15:425 - info: [debug] [JSONWP Proxy] Proxying [POST /orientation] to [POST http://localhost:8100/session/4E2AA453-BB1A-4C54-A015-3ECD07FF2814/orientation] with body: {"orientation":"LANDSCAPE"}
2017-10-27 14:15:17:221 - info: [debug] [JSONWP Proxy] Got response with status 200: {"value":{},"sessionId":"4E2AA453-BB1A-4C54-A015-3ECD07FF2814","status":0}
2017-10-27 14:15:17:222 - info: [debug] [BaseDriver] Event 'orientationSet' logged at 1509106517221 (14:15:17 GMT+0200 (CEST))
2017-10-27 14:15:17:223 - info: [Appium] New XCUITestDriver session created successfully, session d39e4f63-4a9e-4faa-8403-06710bd036dd added to master session list
2017-10-27 14:15:17:223 - info: [debug] [BaseDriver] Event 'newSessionStarted' logged at 1509106517223 (14:15:17 GMT+0200 (CEST))
2017-10-27 14:15:17:224 - info: [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":"[...]","platformName":"iOS","platformVersion":"10.3","deviceName":"iPad Air","noReset":true,"automationName":"XCUITest","newCommandTimeout":0,"preventWDAAttachments":true,"orientation":"LANDSCAPE","udid":"[...]"}
2017-10-27 14:15:17:227 - info: [HTTP] <-- POST /wd/hub/session 200 21001 ms - 608 
2017-10-27 14:22:29:264 - info: [debug] [XCUITest] Log file for xcodebuild test: [...]/Library/Developer/Xcode/DerivedData/WebDriverAgent-brdadhpuduowllgivnnvuygpwhzy/Logs/Test/9F9313E7-6555-4C9F-93FE-DEA0CF901FFE/Session-WebDriverAgentRunner-2017-10-27_142229-EPOc5i.log
2017-10-27 14:22:36:088 - info: [XCUITest] xcodebuild exited with code '65' and signal 'null'